See <http://hudson.zones.apache.org/hudson/job/Tuscany-2x/540/changes>
Changes: [rfeng] Update geronimo connector/transaction versions ------------------------------------------ [...truncated 19284 lines...] [INFO] Installing <http://hudson.zones.apache.org/hudson/job/Tuscany-2x/ws/sca-2x/itest/nodes/two-nodes-test/target/itest-nodes-two-nodes-test-2.0-SNAPSHOT.jar> to /home/hudson/.m2/repository/org/apache/tuscany/sca/itest-nodes-two-nodes-test/2.0-SNAPSHOT/itest-nodes-two-nodes-test-2.0-SNAPSHOT.jar [HUDSON] Archiving <http://hudson.zones.apache.org/hudson/job/Tuscany-2x/ws/sca-2x/itest/nodes/two-nodes-test/pom.xml> to /export/home/hudson/hudson/jobs/Tuscany-2x/modules/org.apache.tuscany.sca$itest-nodes-two-nodes-test/builds/2010-03-14_23-32-09/archive/org.apache.tuscany.sca/itest-nodes-two-nodes-test/2.0-SNAPSHOT/pom.xml [HUDSON] Archiving <http://hudson.zones.apache.org/hudson/job/Tuscany-2x/ws/sca-2x/itest/nodes/two-nodes-test/target/itest-nodes-two-nodes-test-2.0-SNAPSHOT.jar> to /export/home/hudson/hudson/jobs/Tuscany-2x/modules/org.apache.tuscany.sca$itest-nodes-two-nodes-test/builds/2010-03-14_23-32-09/archive/org.apache.tuscany.sca/itest-nodes-two-nodes-test/2.0-SNAPSHOT/itest-nodes-two-nodes-test-2.0-SNAPSHOT.jar [INFO] ------------------------------------------------------------------------ [INFO] Building Apache Tuscany SCA iTest Nodes Two Nodes Two VMs Test [INFO] task-segment: [clean, install] [INFO] ------------------------------------------------------------------------ [INFO] [clean:clean {execution: default-clean}] [INFO] Deleting file set: <http://hudson.zones.apache.org/hudson/job/Tuscany-2x/ws/sca-2x/itest/nodes/two-nodes-two-vms-test/target> (included: [**], excluded: []) [INFO] [enforcer:enforce {execution: enforce-plugin-versions}] [INFO] [resources:resources {execution: default-resources}] [INFO] Using 'UTF-8' encoding to copy filtered resources. [INFO] skip non existing resourceDirectory <http://hudson.zones.apache.org/hudson/job/Tuscany-2x/ws/sca-2x/itest/nodes/two-nodes-two-vms-test/src/main/resources> [INFO] Copying 0 resource [INFO] [compiler:compile {execution: default-compile}] [INFO] No sources to compile [INFO] [resources:testResources {execution: default-testResources}] [INFO] Using 'UTF-8' encoding to copy filtered resources. [INFO] skip non existing resourceDirectory <http://hudson.zones.apache.org/hudson/job/Tuscany-2x/ws/sca-2x/itest/nodes/two-nodes-two-vms-test/src/test/resources> [INFO] [compiler:testCompile {execution: default-testCompile}] [INFO] Invoking Tuscany Eclipse JDT compiler [INFO] Compiling 2 to <http://hudson.zones.apache.org/hudson/job/Tuscany-2x/ws/sca-2x/itest/nodes/two-nodes-two-vms-test/target/test-classes> [INFO] [surefire:test {execution: default-test}] [INFO] Concurrency config is {parallel=classes, configurableParallelComputerPresent=false} [INFO] Surefire report directory: <http://hudson.zones.apache.org/hudson/job/Tuscany-2x/ws/sca-2x/itest/nodes/two-nodes-two-vms-test/target/surefire-reports> ------------------------------------------------------- T E S T S ------------------------------------------------------- There are no tests to run. Results : Tests run: 0, Failures: 0, Errors: 0, Skipped: 0 [HUDSON] Recording test results[INFO] [antrun:run {execution: run-samples}] [INFO] Executing tasks run: run: [java] Mar 15, 2010 12:51:38 AM org.apache.tuscany.sca.node.launcher.NodeLauncher main [java] INFO: SCA Node configuration: ./server-config.xml [java] Mar 15, 2010 12:51:38 AM org.apache.tuscany.sca.node.launcher.NodeLauncher main [java] INFO: Apache Tuscany SCA Node is starting... [java] Mar 15, 2010 12:51:38 AM org.apache.tuscany.sca.node.impl.NodeImpl start [java] INFO: Starting node: http://sample/nodes/TestNode2 domain: http://domain1 [java] Mar 15, 2010 12:51:39 AM org.apache.tuscany.sca.endpoint.tribes.AbstractReplicatedMap init [java] INFO: Initializing AbstractReplicatedMap with context name:http://domain1 [java] Mar 15, 2010 12:51:39 AM org.apache.catalina.tribes.transport.ReceiverBase bind [java] INFO: Receiver Server Socket bound to:/67.195.138.8:4000 [java] Mar 15, 2010 12:51:39 AM org.apache.catalina.tribes.membership.McastServiceImpl setupSocket [java] INFO: Attempting to bind the multicast socket to /228.0.0.100:50000 [java] Mar 15, 2010 12:51:39 AM org.apache.catalina.tribes.membership.McastServiceImpl setupSocket [java] INFO: Binding to multicast address, failed. Binding to port only. [java] Mar 15, 2010 12:51:39 AM org.apache.catalina.tribes.membership.McastServiceImpl setupSocket [java] INFO: Setting multihome multicast interface to:/fe80:0:0:0:221:5aff:fee0:c3a3%2 [java] Mar 15, 2010 12:51:39 AM org.apache.catalina.tribes.membership.McastServiceImpl setupSocket [java] INFO: Setting cluster mcast soTimeout to 500 [java] Mar 15, 2010 12:51:39 AM org.apache.catalina.tribes.membership.McastServiceImpl waitForMembers [java] INFO: Sleeping for 1000 milliseconds to establish cluster membership, start level:4 [java] Mar 15, 2010 12:51:40 AM org.apache.catalina.tribes.membership.McastServiceImpl waitForMembers [java] INFO: Done sleeping, membership established, start level:4 [java] Mar 15, 2010 12:51:40 AM org.apache.catalina.tribes.membership.McastServiceImpl waitForMembers [java] INFO: Sleeping for 1000 milliseconds to establish cluster membership, start level:8 [java] Mar 15, 2010 12:51:41 AM org.apache.catalina.tribes.membership.McastServiceImpl waitForMembers [java] INFO: Done sleeping, membership established, start level:8 [java] Mar 15, 2010 12:51:41 AM org.apache.tuscany.sca.node.impl.NodeFactoryImpl loadContributions [java] INFO: Loading contribution: <http://hudson.zones.apache.org/hudson/job/Tuscany-2x/ws/sca-2x/itest/nodes/helloworld-iface/target/classes/> [java] Mar 15, 2010 12:51:41 AM org.apache.tuscany.sca.node.impl.NodeFactoryImpl loadContributions [java] INFO: Loading contribution: <http://hudson.zones.apache.org/hudson/job/Tuscany-2x/ws/sca-2x/itest/nodes/helloworld-service/target/classes/> run: [java] Mar 15, 2010 12:51:48 AM org.apache.tuscany.sca.node.impl.NodeImpl start [java] INFO: Starting node: http://sample/nodes/TestNode1 domain: http://domain1 [java] Mar 15, 2010 12:51:49 AM org.apache.tuscany.sca.endpoint.tribes.AbstractReplicatedMap init [java] INFO: Initializing AbstractReplicatedMap with context name:http://domain1 [java] Mar 15, 2010 12:51:49 AM org.apache.catalina.tribes.transport.ReceiverBase bind [java] INFO: Receiver Server Socket bound to:/67.195.138.8:4001 [java] Mar 15, 2010 12:51:49 AM org.apache.catalina.tribes.membership.McastServiceImpl setupSocket [java] INFO: Attempting to bind the multicast socket to /228.0.0.100:50000 [java] Mar 15, 2010 12:51:49 AM org.apache.catalina.tribes.membership.McastServiceImpl setupSocket [java] INFO: Binding to multicast address, failed. Binding to port only. [java] Mar 15, 2010 12:51:49 AM org.apache.catalina.tribes.membership.McastServiceImpl setupSocket [java] INFO: Setting multihome multicast interface to:/fe80:0:0:0:221:5aff:fee0:c3a3%2 [java] Mar 15, 2010 12:51:49 AM org.apache.catalina.tribes.membership.McastServiceImpl setupSocket [java] INFO: Setting cluster mcast soTimeout to 500 [java] Mar 15, 2010 12:51:49 AM org.apache.catalina.tribes.membership.McastServiceImpl waitForMembers [java] INFO: Sleeping for 1000 milliseconds to establish cluster membership, start level:4 [java] Mar 15, 2010 12:51:50 AM org.apache.catalina.tribes.membership.McastServiceImpl waitForMembers [java] INFO: Done sleeping, membership established, start level:4 [java] Mar 15, 2010 12:51:50 AM org.apache.catalina.tribes.membership.McastServiceImpl waitForMembers [java] INFO: Sleeping for 1000 milliseconds to establish cluster membership, start level:8 [java] Mar 15, 2010 12:51:51 AM org.apache.catalina.tribes.io.BufferPool getBufferPool [java] INFO: Created a buffer pool with max size:104857600 bytes of type:org.apache.catalina.tribes.io.BufferPool15Impl [java] Mar 15, 2010 12:51:51 AM org.apache.catalina.tribes.membership.McastServiceImpl waitForMembers [java] INFO: Done sleeping, membership established, start level:8 [java] Mar 15, 2010 12:51:51 AM org.apache.tuscany.sca.node.impl.NodeFactoryImpl loadContributions [java] INFO: Loading contribution: <http://hudson.zones.apache.org/hudson/job/Tuscany-2x/ws/sca-2x/itest/nodes/helloworld-iface/target/itest-nodes-helloworld-iface-2.0-SNAPSHOT.jar> [java] Mar 15, 2010 12:51:51 AM org.apache.catalina.tribes.io.BufferPool getBufferPool [java] INFO: Created a buffer pool with max size:104857600 bytes of type:org.apache.catalina.tribes.io.BufferPool15Impl [java] Mar 15, 2010 12:51:51 AM org.apache.tuscany.sca.node.impl.NodeFactoryImpl loadContributions [java] INFO: Loading contribution: <http://hudson.zones.apache.org/hudson/job/Tuscany-2x/ws/sca-2x/itest/nodes/helloworld-client/target/itest-nodes-helloworld-client-2.0-SNAPSHOT.jar> [java] Mar 15, 2010 12:52:44 AM org.apache.tuscany.sca.node.launcher.NodeLauncher main [java] SEVERE: SCA Node could not be started [java] java.lang.reflect.InvocationTargetException [java] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [java] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) [java] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) [java] at java.lang.reflect.Method.invoke(Method.java:597) [java] at org.apache.tuscany.sca.node.launcher.NodeLauncher.main(NodeLauncher.java:202) [java] at org.apache.tuscany.sca.node.launcher.NodeMain.main(NodeMain.java:59) [java] Caused by: java.lang.IllegalStateException: org.apache.tuscany.sca.host.rmi.RMIHostRuntimeException: error during JRMP connection establishment; nested exception is: [java] java.net.SocketTimeoutException: Read timed out [java] at org.apache.tuscany.sca.node.impl.NodeImpl.start(NodeImpl.java:169) [java] ... 6 more [java] Caused by: org.apache.tuscany.sca.host.rmi.RMIHostRuntimeException: error during JRMP connection establishment; nested exception is: [java] java.net.SocketTimeoutException: Read timed out [java] at sun.rmi.transport.tcp.TCPChannel.createConnection(TCPChannel.java:286) [java] at sun.rmi.transport.tcp.TCPChannel.newConnection(TCPChannel.java:184) [java] at sun.rmi.server.UnicastRef.newCall(UnicastRef.java:322) [java] at sun.rmi.registry.RegistryImpl_Stub.bind(Unknown Source) [java] at org.apache.tuscany.sca.host.rmi.DefaultRMIHost.registerService(DefaultRMIHost.java:72) [java] at org.apache.tuscany.sca.host.rmi.ExtensibleRMIHost.registerService(ExtensibleRMIHost.java:42) [java] at org.apache.tuscany.sca.binding.rmi.provider.RMIServiceBindingProvider.start(RMIServiceBindingProvider.java:80) [java] at org.apache.tuscany.sca.binding.sca.provider.DelegatingSCAServiceBindingProvider.start(DelegatingSCAServiceBindingProvider.java:64) [java] at org.apache.tuscany.sca.binding.sca.provider.RuntimeSCAServiceBindingProvider.start(RuntimeSCAServiceBindingProvider.java:91) [java] at org.apache.tuscany.sca.core.assembly.impl.CompositeActivatorImpl$1.run(CompositeActivatorImpl.java:423) [java] at java.security.AccessController.doPrivileged(Native Method) [java] at org.apache.tuscany.sca.core.assembly.impl.CompositeActivatorImpl.startEndpoint(CompositeActivatorImpl.java:421) [java] at org.apache.tuscany.sca.core.assembly.impl.CompositeActivatorImpl.start(CompositeActivatorImpl.java:377) [java] at org.apache.tuscany.sca.core.assembly.impl.CompositeActivatorImpl.start(CompositeActivatorImpl.java:309) [java] at org.apache.tuscany.sca.node.impl.NodeImpl.start(NodeImpl.java:143) [java] ... 6 more [java] Mar 15, 2010 12:52:44 AM org.apache.tuscany.sca.node.impl.NodeImpl stop [java] INFO: Stopping node: http://sample/nodes/TestNode2 [java] Mar 15, 2010 12:52:44 AM org.apache.tuscany.sca.node.launcher.NodeLauncher stopNode [java] INFO: SCA Node is now stopped. [java] Exception in thread "main" java.lang.reflect.InvocationTargetException [java] at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) [java] at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) [java] at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) [java] at java.lang.reflect.Method.invoke(Method.java:597) [java] at org.apache.tuscany.sca.node.launcher.NodeLauncher.main(NodeLauncher.java:202) [java] at org.apache.tuscany.sca.node.launcher.NodeMain.main(NodeMain.java:59) [java] Caused by: java.lang.IllegalStateException: org.apache.tuscany.sca.host.rmi.RMIHostRuntimeException: error during JRMP connection establishment; nested exception is: [java] java.net.SocketTimeoutException: Read timed out [java] at org.apache.tuscany.sca.node.impl.NodeImpl.start(NodeImpl.java:169) [java] ... 6 more [java] Caused by: org.apache.tuscany.sca.host.rmi.RMIHostRuntimeException: error during JRMP connection establishment; nested exception is: [java] java.net.SocketTimeoutException: Read timed out [java] at sun.rmi.transport.tcp.TCPChannel.createConnection(TCPChannel.java:286) [java] at sun.rmi.transport.tcp.TCPChannel.newConnection(TCPChannel.java:184) [java] at sun.rmi.server.UnicastRef.newCall(UnicastRef.java:322) [java] at sun.rmi.registry.RegistryImpl_Stub.bind(Unknown Source) [java] at org.apache.tuscany.sca.host.rmi.DefaultRMIHost.registerService(DefaultRMIHost.java:72) [java] at org.apache.tuscany.sca.host.rmi.ExtensibleRMIHost.registerService(ExtensibleRMIHost.java:42) [java] at org.apache.tuscany.sca.binding.rmi.provider.RMIServiceBindingProvider.start(RMIServiceBindingProvider.java:80) [java] at org.apache.tuscany.sca.binding.sca.provider.DelegatingSCAServiceBindingProvider.start(DelegatingSCAServiceBindingProvider.java:64) [java] at org.apache.tuscany.sca.binding.sca.provider.RuntimeSCAServiceBindingProvider.start(RuntimeSCAServiceBindingProvider.java:91) [java] at org.apache.tuscany.sca.core.assembly.impl.CompositeActivatorImpl$1.run(CompositeActivatorImpl.java:423) [java] at java.security.AccessController.doPrivileged(Native Method) [java] at org.apache.tuscany.sca.core.assembly.impl.CompositeActivatorImpl.startEndpoint(CompositeActivatorImpl.java:421) [java] at org.apache.tuscany.sca.core.assembly.impl.CompositeActivatorImpl.start(CompositeActivatorImpl.java:377) [java] at org.apache.tuscany.sca.core.assembly.impl.CompositeActivatorImpl.start(CompositeActivatorImpl.java:309) [java] at org.apache.tuscany.sca.node.impl.NodeImpl.start(NodeImpl.java:143) [java] ... 6 more [java] java.lang.IllegalStateException: org.apache.tuscany.sca.host.rmi.RMIHostRuntimeException: error during JRMP connection establishment; nested exception is: [java] java.net.SocketTimeoutException: Read timed out [java] at org.apache.tuscany.sca.node.impl.NodeImpl.start(NodeImpl.java:169) [java] at itest.Client.setUpBeforeClass(Client.java:44) [java] at itest.Client.main(Client.java:66) [java] Caused by: org.apache.tuscany.sca.host.rmi.RMIHostRuntimeException: error during JRMP connection establishment; nested exception is: [java] java.net.SocketTimeoutException: Read timed out [java] at sun.rmi.transport.tcp.TCPChannel.createConnection(TCPChannel.java:286) [java] at sun.rmi.transport.tcp.TCPChannel.newConnection(TCPChannel.java:184) [java] at sun.rmi.server.UnicastRef.newCall(UnicastRef.java:322) [java] at sun.rmi.registry.RegistryImpl_Stub.bind(Unknown Source) [java] at org.apache.tuscany.sca.host.rmi.DefaultRMIHost.registerService(DefaultRMIHost.java:72) [java] at org.apache.tuscany.sca.host.rmi.ExtensibleRMIHost.registerService(ExtensibleRMIHost.java:42) [java] at org.apache.tuscany.sca.binding.rmi.provider.RMIServiceBindingProvider.start(RMIServiceBindingProvider.java:80) [java] at org.apache.tuscany.sca.binding.sca.provider.DelegatingSCAServiceBindingProvider.start(DelegatingSCAServiceBindingProvider.java:64) [java] at org.apache.tuscany.sca.binding.sca.provider.RuntimeSCAServiceBindingProvider.start(RuntimeSCAServiceBindingProvider.java:91) [java] at org.apache.tuscany.sca.core.assembly.impl.CompositeActivatorImpl$1.run(CompositeActivatorImpl.java:423) [java] at java.security.AccessController.doPrivileged(Native Method) [java] at org.apache.tuscany.sca.core.assembly.impl.CompositeActivatorImpl.startEndpoint(CompositeActivatorImpl.java:421) [java] at org.apache.tuscany.sca.core.assembly.impl.CompositeActivatorImpl.start(CompositeActivatorImpl.java:377) [java] at org.apache.tuscany.sca.core.assembly.impl.CompositeActivatorImpl.start(CompositeActivatorImpl.java:309) [java] at org.apache.tuscany.sca.node.impl.NodeImpl.start(NodeImpl.java:143) [java] ... 2 more [java] Exception in thread "main" java.lang.IllegalStateException: org.apache.tuscany.sca.host.rmi.RMIHostRuntimeException: error during JRMP connection establishment; nested exception is: [java] java.net.SocketTimeoutException: Read timed out [java] at org.apache.tuscany.sca.node.impl.NodeImpl.start(NodeImpl.java:169) [java] at itest.Client.setUpBeforeClass(Client.java:44) [java] at itest.Client.main(Client.java:66) [java] Caused by: org.apache.tuscany.sca.host.rmi.RMIHostRuntimeException: error during JRMP connection establishment; nested exception is: [java] java.net.SocketTimeoutException: Read timed out [java] at sun.rmi.transport.tcp.TCPChannel.createConnection(TCPChannel.java:286) [java] at sun.rmi.transport.tcp.TCPChannel.newConnection(TCPChannel.java:184) [java] at sun.rmi.server.UnicastRef.newCall(UnicastRef.java:322) [java] at sun.rmi.registry.RegistryImpl_Stub.bind(Unknown Source) [java] at org.apache.tuscany.sca.host.rmi.DefaultRMIHost.registerService(DefaultRMIHost.java:72) [java] at org.apache.tuscany.sca.host.rmi.ExtensibleRMIHost.registerService(ExtensibleRMIHost.java:42) [java] at org.apache.tuscany.sca.binding.rmi.provider.RMIServiceBindingProvider.start(RMIServiceBindingProvider.java:80) [java] at org.apache.tuscany.sca.binding.sca.provider.DelegatingSCAServiceBindingProvider.start(DelegatingSCAServiceBindingProvider.java:64) [java] at org.apache.tuscany.sca.binding.sca.provider.RuntimeSCAServiceBindingProvider.start(RuntimeSCAServiceBindingProvider.java:91) [java] at org.apache.tuscany.sca.core.assembly.impl.CompositeActivatorImpl$1.run(CompositeActivatorImpl.java:423) [java] at java.security.AccessController.doPrivileged(Native Method) [java] at org.apache.tuscany.sca.core.assembly.impl.CompositeActivatorImpl.startEndpoint(CompositeActivatorImpl.java:421) [java] at org.apache.tuscany.sca.core.assembly.impl.CompositeActivatorImpl.start(CompositeActivatorImpl.java:377) [java] at org.apache.tuscany.sca.core.assembly.impl.CompositeActivatorImpl.start(CompositeActivatorImpl.java:309) [java] at org.apache.tuscany.sca.node.impl.NodeImpl.start(NodeImpl.java:143) [java] ... 2 more [java] Java Result: 1 Build timed out. Aborting Sending e-mails to: [email protected] ERROR: Failed to parse POMs hudson.remoting.ChannelClosedException: channel is already closed at hudson.remoting.Channel.send(Channel.java:412) at hudson.remoting.ProxyOutputStream.write(ProxyOutputStream.java:112) at hudson.remoting.ProxyOutputStream.write(ProxyOutputStream.java:100) at hudson.remoting.RemoteOutputStream.write(RemoteOutputStream.java:110) at java.io.BufferedOutputStream.flushBuffer(BufferedOutputStream.java:65) at java.io.BufferedOutputStream.flush(BufferedOutputStream.java:123) at java.io.BufferedOutputStream.flush(BufferedOutputStream.java:124) at java.io.ObjectOutputStream$BlockDataOutputStream.flush(ObjectOutputStream.java:1784) at java.io.ObjectOutputStream.flush(ObjectOutputStream.java:691) at hudson.remoting.Channel.send(Channel.java:418) at hudson.remoting.Request.call(Request.java:149) at hudson.remoting.Channel.call(Channel.java:551) at hudson.maven.ProcessCache$MavenProcess.call(ProcessCache.java:156) at hudson.maven.MavenModuleSetBuild$RunnerImpl.doRun(MavenModuleSetBuild.java:480) at hudson.model.AbstractBuild$AbstractRunner.run(AbstractBuild.java:416) at hudson.model.Run.run(Run.java:1198) at hudson.maven.MavenModuleSetBuild.run(MavenModuleSetBuild.java:304) at hudson.model.ResourceController.execute(ResourceController.java:88) at hudson.model.Executor.run(Executor.java:122) FATAL: channel is already closed hudson.remoting.ChannelClosedException: channel is already closed at hudson.remoting.Channel.send(Channel.java:412) at hudson.remoting.Request.call(Request.java:105) at hudson.remoting.Channel.call(Channel.java:551) at hudson.Launcher$RemoteLauncher.kill(Launcher.java:735) at hudson.model.AbstractBuild$AbstractRunner.run(AbstractBuild.java:421) at hudson.model.Run.run(Run.java:1198) at hudson.maven.MavenModuleSetBuild.run(MavenModuleSetBuild.java:304) at hudson.model.ResourceController.execute(ResourceController.java:88) at hudson.model.Executor.run(Executor.java:122)
