On Wednesday, March 10, 2004, at 10:09 AM, Alan Cabrera wrote:
They worked on my windows machines. It's odd that the other network tests worked fine. I think that these may be the ones that shovel one meg packets down the stack; there may be threading issues.
I don't have access to either a linux or mac osx box. Does anyone know where I can investigate this?
If the answer isn't obvious soon I can let you into my linux box, although it may not be much help as there are no tools. I don't know how well remote debugging over dsl is likely to work...
Do you have any output I can look at?
working backwards:
Testsuite: org.apache.geronimo.security.network.protocol.SubjectCarryingProtocolTest Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 11.52 sec ------------- Standard Output --------------- Created MBeanServer with ID: eda64e:fb3601b6f4:-8000:mac.local.:1 ------------- ---------------- --------------- ------------- Standard Error ----------------- Mar 10, 2004 10:21:12 AM org.apache.geronimo.kernel.Kernel boot INFO: Starting boot Mar 10, 2004 10:21:15 AM org.apache.geronimo.kernel.Kernel boot INFO: Booted Mar 10, 2004 10:21:20 AM org.apache.geronimo.security.jaas.LoginService doStart INFO: Login server has been started Mar 10, 2004 10:21:20 AM org.apache.geronimo.remoting.transport.async.bio.BlockingServer bind INFO: Remoting 'async' protocol available at: /0.0.0.0:4242 Mar 10, 2004 10:21:20 AM org.apache.geronimo.remoting.transport.async.bio.BlockingServer bind INFO: Remoting 'async' protocol clients will connect to: async://mac.local.:4242 Mar 10, 2004 10:21:21 AM org.apache.geronimo.system.ThreadPool doStart INFO: Thread pool Server TP started Mar 10, 2004 10:21:21 AM org.apache.geronimo.system.ThreadPool doStart INFO: Thread pool Client TP started Mar 10, 2004 10:21:21 AM org.apache.geronimo.system.ClockPool doStart INFO: Clock pool Client CP started Mar 10, 2004 10:21:21 AM org.apache.geronimo.system.ClockPool doStart INFO: Clock pool Server CP started Mar 10, 2004 10:21:21 AM org.apache.geronimo.security.jaas.LoginService doStop INFO: Login server has been stopped Mar 10, 2004 10:21:21 AM org.apache.geronimo.network.protocol.ServerSocketAcceptor doStart INFO: Remoting 'async' protocol available at: /127.0.0.1:8081 Mar 10, 2004 10:21:21 AM org.apache.geronimo.network.protocol.ServerSocketAcceptor doStart INFO: Remoting 'async' protocol clients will connect to: async://mac.local.:8081 Mar 10, 2004 10:21:21 AM org.apache.geronimo.kernel.Kernel shutdown INFO: Starting kernel shutdown Mar 10, 2004 10:21:21 AM org.apache.geronimo.kernel.Kernel shutdown INFO: Kernel shutdown complete ------------- ---------------- ---------------
Testcase: test took 9.388 sec
Caused an ERROR
null
java.security.PrivilegedActionException:
org.apache.geronimo.network.protocol.ProtocolException:
java.net.ConnectException: Connection refused
at java.security.AccessController.doPrivileged(Native Method)
at javax.security.auth.Subject.doAs(Subject.java:378)
at
org.apache.geronimo.security.network.protocol.SubjectCarryingProtocolTest.test(SubjectCarryingProtocolTest.java:88)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
Caused by: org.apache.geronimo.network.protocol.ProtocolException:
java.net.ConnectException: Connection refused
at
org.apache.geronimo.network.protocol.SocketProtocol.doStart(SocketProtocol.java:159)
at
org.apache.geronimo.network.protocol.ProtocolStack.doStart(ProtocolStack.java:105)
at
org.apache.geronimo.network.protocol.control.ControlClientProtocolStack.doStart(ControlClientProtocolStack.java:104)
at
org.apache.geronimo.security.network.protocol.SubjectCarryingProtocolTest$ClientAction.run(SubjectCarryingProtocolTest.java:129)
... 17 more
Caused by: java.net.ConnectException: Connection refused
at sun.nio.ch.Net.connect(Native Method)
at sun.nio.ch.SocketChannelImpl.connect(SocketChannelImpl.java:457)
at
org.apache.geronimo.network.protocol.SocketProtocol.doStart(SocketProtocol.java:156)
... 20 more
Testcase: testTestsuite: org.apache.geronimo.network.protocol.SocketProtocolTest
Tests run: 1, Failures: 0, Errors: 1, Time elapsed: 10.917 sec
------------- Standard Error -----------------
Mar 10, 2004 10:28:08 AM org.apache.geronimo.system.ThreadPool doStart
INFO: Thread pool TP started
Mar 10, 2004 10:28:09 AM org.apache.geronimo.system.ClockPool doStart
INFO: Clock pool CP started
Mar 10, 2004 10:28:09 AM
org.apache.geronimo.network.protocol.ServerSocketAcceptor doStart
INFO: Remoting 'async' protocol available at: /127.0.0.1:8081
Mar 10, 2004 10:28:09 AM
org.apache.geronimo.network.protocol.ServerSocketAcceptor doStart
INFO: Remoting 'async' protocol clients will connect to: async://mac.local.:8081
------------- ---------------- ---------------
Testcase: test took 10.754 sec
Caused an ERROR
Send timeout.
org.apache.geronimo.network.protocol.ProtocolException: Send timeout.
at
org.apache.geronimo.network.protocol.SocketProtocol.sendDown(SocketProtocol.java:202)
at
org.apache.geronimo.network.protocol.SocketProtocolTest.test(SocketProtocolTest.java:162)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
Testcase: testTestsuite: org.apache.geronimo.network.protocol.DatagramProtocolTest
Tests run: 2, Failures: 0, Errors: 1, Time elapsed: 5.958 sec
------------- Standard Error -----------------
Mar 10, 2004 10:30:58 AM org.apache.geronimo.system.ThreadPool doStart
INFO: Thread pool TP started
Mar 10, 2004 10:31:03 AM org.apache.geronimo.system.ThreadPool doStop
INFO: Thread pool TP stopped
Mar 10, 2004 10:31:03 AM org.apache.geronimo.system.ThreadPool doStart
INFO: Thread pool TP started
------------- ---------------- ---------------
Testcase: testClone took 5.729 sec
Testcase: test took 0.051 sec
Caused an ERROR
java.net.SocketException: Address already in use
org.apache.geronimo.network.protocol.ProtocolException:
java.net.SocketException: Address already in use
at
org.apache.geronimo.network.protocol.DatagramProtocol.doStart(DatagramProtocol.java:127)
at
org.apache.geronimo.network.protocol.DatagramProtocolTest.test(DatagramProtocolTest.java:97)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
Caused by: java.net.SocketException: Address already in use
at sun.nio.ch.Net.bind(Native Method)
at sun.nio.ch.DatagramChannelImpl.bind(DatagramChannelImpl.java:471)
at sun.nio.ch.DatagramSocketAdaptor.bind(DatagramSocketAdaptor.java:82)
at
org.apache.geronimo.network.protocol.DatagramProtocol.doStart(DatagramProtocol.java:114)
... 15 more
Testcase: testthread dump from hung ProtocolStackTest:
[junit] Running org.apache.geronimo.network.protocol.ProtocolStackTest
^\ [junit] Full thread dump Java HotSpot(TM) Client VM (1.4.1_01-24 mixed mode):
[junit]
[junit] "Signal Dispatcher" daemon prio=10 tid=0x00096530 nid=0x5c0e0 waiting on condition [0..0]
[junit]
[junit] "Finalizer" daemon prio=8 tid=0x000928e0 nid=0x531c0 in Object.wait() [f0284000..f0284b70]
[junit] at java.lang.Object.wait(Native Method)
[junit] - waiting on <0x677254b0> (a java.lang.ref.ReferenceQueue$Lock)
[junit] at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:111)
[junit] - locked <0x677254b0> (a java.lang.ref.ReferenceQueue$Lock)
[junit] at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:127)
[junit] at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:159)
[junit]
[junit] "Reference Handler" daemon prio=10 tid=0x00091d50 nid=0x52630 in Object.wait() [f0203000..f0203b70]
[junit] at java.lang.Object.wait(Native Method)
[junit] - waiting on <0x67725518> (a java.lang.ref.Reference$Lock)
[junit] at java.lang.Object.wait(Object.java:426)
[junit] at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:113)
[junit] - locked <0x67725518> (a java.lang.ref.Reference$Lock)
[junit]
[junit] "main" prio=5 tid=0x00089550 nid=0x49330 in Object.wait() [f007f000..f00803b8]
[junit] at java.lang.Object.wait(Native Method)
[junit] - waiting on <0x670452f8> (a EDU.oswego.cs.dl.util.concurrent.Mutex)
[junit] at EDU.oswego.cs.dl.util.concurrent.Mutex.attempt(Mutex.java:148)
[junit] - locked <0x670452f8> (a EDU.oswego.cs.dl.util.concurrent.Mutex)
[junit] at org.apache.geronimo.network.protocol.SocketProtocol.sendDown(SocketProto col.java:202)
[junit] at org.apache.geronimo.network.protocol.ProtocolStackTest.test(ProtocolStac kTest.java:134)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.jav a:39)
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessor Impl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:324)
[junit] at junit.framework.TestCase.runTest(TestCase.java:154)
[junit] at junit.framework.TestCase.runBare(TestCase.java:127)
[junit] at junit.framework.TestResult$1.protect(TestResult.java:106)
[junit] at junit.framework.TestResult.runProtected(TestResult.java:124)
[junit] at junit.framework.TestResult.run(TestResult.java:109)
[junit] at junit.framework.TestCase.run(TestCase.java:118)
[junit] at junit.framework.TestSuite.runTest(TestSuite.java:208)
[junit] at junit.framework.TestSuite.run(TestSuite.java:203)
[junit] at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.run(JUnitTe stRunner.java:325)
[junit] at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.main(JUnitT estRunner.java:536)
[junit]
[junit] "VM Thread" prio=5 tid=0x00090c50 nid=0x523d0 runnable
[junit]
[junit] "VM Periodic Task Thread" prio=10 tid=0x00095c30 nid=0x5be80 waiting on condition
[junit] "Exception Catcher Thread" prio=10 tid=0x0008a030 nid=0x4a810 runnable
--------------------- thread dump from hung ControlProtocolTest:
[junit] Running org.apache.geronimo.network.protocol.control.ControlProtocolTest
^\ [junit] Full thread dump Java HotSpot(TM) Client VM (1.4.1_01-24 mixed mode):
[junit]
[junit] "Signal Dispatcher" daemon prio=10 tid=0x00096550 nid=0x5c100 waiting on condition [0..0]
[junit]
[junit] "Finalizer" daemon prio=8 tid=0x00092900 nid=0x531e0 in Object.wait() [f0284000..f0284b70]
[junit] at java.lang.Object.wait(Native Method)
[junit] - waiting on <0x677254b0> (a java.lang.ref.ReferenceQueue$Lock)
[junit] at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:111)
[junit] - locked <0x677254b0> (a java.lang.ref.ReferenceQueue$Lock)
[junit] at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:127)
[junit] at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:159)
[junit]
[junit] "Reference Handler" daemon prio=10 tid=0x00091d70 nid=0x52650 in Object.wait() [f0203000..f0203b70]
[junit] at java.lang.Object.wait(Native Method)
[junit] - waiting on <0x67725518> (a java.lang.ref.Reference$Lock)
[junit] at java.lang.Object.wait(Object.java:426)
[junit] at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:113)
[junit] - locked <0x67725518> (a java.lang.ref.Reference$Lock)
[junit]
[junit] "main" prio=5 tid=0x00089570 nid=0x49330 in Object.wait() [f007f000..f00803b8]
[junit] at java.lang.Object.wait(Native Method)
[junit] - waiting on <0x677a29c0> (a EDU.oswego.cs.dl.util.concurrent.Mutex)
[junit] at EDU.oswego.cs.dl.util.concurrent.Mutex.attempt(Mutex.java:148)
[junit] - locked <0x677a29c0> (a EDU.oswego.cs.dl.util.concurrent.Mutex)
[junit] at org.apache.geronimo.network.protocol.control.ControlClientProtocolKitche n.sendDown(ControlClientProtocolKitchen.java:117)
[junit] at org.apache.geronimo.network.protocol.ProtocolStack.sendDown(ProtocolStac k.java:122)
[junit] at org.apache.geronimo.network.protocol.control.ControlProtocolTest.test(Co ntrolProtocolTest.java:136)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.jav a:39)
[junit] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessor Impl.java:25)
[junit] at java.lang.reflect.Method.invoke(Method.java:324)
[junit] at junit.framework.TestCase.runTest(TestCase.java:154)
[junit] at junit.framework.TestCase.runBare(TestCase.java:127)
[junit] at junit.framework.TestResult$1.protect(TestResult.java:106)
[junit] at junit.framework.TestResult.runProtected(TestResult.java:124)
[junit] at junit.framework.TestResult.run(TestResult.java:109)
[junit] at junit.framework.TestCase.run(TestCase.java:118)
[junit] at junit.framework.TestSuite.runTest(TestSuite.java:208)
[junit] at junit.framework.TestSuite.run(TestSuite.java:203)
[junit] at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.run(JUnitTe stRunner.java:325)
[junit] at org.apache.tools.ant.taskdefs.optional.junit.JUnitTestRunner.main(JUnitT estRunner.java:536)
[junit]
[junit] "VM Thread" prio=5 tid=0x00090c70 nid=0x523f0 runnable
[junit]
[junit] "VM Periodic Task Thread" prio=10 tid=0x00095c50 nid=0x5bea0 waiting on condition
[junit] "Exception Catcher Thread" prio=10 tid=0x0008a050 nid=0x4a810 runnable
thanks david jencks
Regards, Alan
-----Original Message----- From: David Jencks [mailto:[EMAIL PROTECTED] Sent: Wednesday, March 10, 2004 1:04 PM To: [EMAIL PROTECTED] Subject: Test failures in network module
I'm seeing hanging and/or test failures in many of the network module tests on both linux and mac osx. Does anyone else see this? What systems do the tests pass on?
Test classes with problems:
ControlProtocolTest DatagramProtocolTest ProtocolStackTest SocketProtocolTest
thanks david jencks
----------------------------------------------------------------- Visit our Internet site at http://www.reuters.com
Get closer to the financial markets with Reuters Messaging - for more information and to register, visit http://www.reuters.com/messaging
Any views expressed in this message are those of the individual sender, except where the sender specifically states them to be the views of Reuters Ltd.
