You are viewing a plain text version of this content. The canonical link for it is here.
Posted to issues@tajo.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2015/08/10 07:33:21 UTC

Build failed in Jenkins: Tajo-master-build #794

See <https://builds.apache.org/job/Tajo-master-build/794/changes>

Changes:

[jhkim] TAJO-1743: Improve calculation of intermediate table statistics.

------------------------------------------
[...truncated 877 lines...]
	at org.apache.tajo.rpc.NettyClientBase.connect(NettyClientBase.java:180)
	at org.apache.tajo.rpc.TestBlockingRpc.testUnresolvedAddress(TestBlockingRpc.java:376)

Running org.apache.tajo.rpc.TestAsyncRpc
Aug 10, 2015 5:32:45 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-15 ServerSocketChannelFactory. Worker:3
Aug 10, 2015 5:32:45 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:10188
Aug 10, 2015 5:32:45 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:10188) shutdown
Aug 10, 2015 5:32:45 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-16 ServerSocketChannelFactory. Worker:3
Aug 10, 2015 5:32:45 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:10189
Aug 10, 2015 5:32:46 AM org.apache.tajo.rpc.MonitorClientHandler userEventTriggered
INFO: Server has not respond [id: 0x0efd6ae4, /127.0.0.1:42392 => /127.0.0.1:10189]
Aug 10, 2015 5:32:46 AM org.apache.tajo.rpc.NettyClientBase$NettyChannelInboundHandler exceptionCaught
SEVERE: Exception [org.apache.tajo.rpc.test.DummyProtocol(/127.0.0.1:10189)]: 
Aug 10, 2015 5:32:46 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:10189) shutdown
Aug 10, 2015 5:32:46 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-17 ServerSocketChannelFactory. Worker:3
Aug 10, 2015 5:32:46 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:10190
Aug 10, 2015 5:32:46 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:10190) shutdown
Aug 10, 2015 5:32:46 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-18 ServerSocketChannelFactory. Worker:3
Aug 10, 2015 5:32:46 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:10191
Aug 10, 2015 5:32:46 AM org.apache.tajo.rpc.test.impl.DummyProtocolAsyncImpl getNull
INFO: noCallback is called
Aug 10, 2015 5:32:46 AM org.apache.tajo.rpc.TestAsyncRpc$4 run
INFO: testGetNull retrieved
Aug 10, 2015 5:32:46 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:10191) shutdown
Aug 10, 2015 5:32:46 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-19 ServerSocketChannelFactory. Worker:3
Aug 10, 2015 5:32:46 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:10192
Aug 10, 2015 5:32:47 AM org.apache.tajo.rpc.NettyClientBase$NettyChannelInboundHandler userEventTriggered
INFO: Idle connection closed successfully :[id: 0xffa7196e, /127.0.0.1:40379 :> /127.0.0.1:10192]
Aug 10, 2015 5:32:47 AM org.apache.tajo.rpc.NettyClientBase$NettyChannelInboundHandler userEventTriggered
INFO: Idle connection closed successfully :[id: 0xccaae2d9, /127.0.0.1:40390 :> /127.0.0.1:10192]
Aug 10, 2015 5:32:47 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:10192) shutdown
Aug 10, 2015 5:32:47 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-20 ServerSocketChannelFactory. Worker:3
Aug 10, 2015 5:32:47 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:10193
Aug 10, 2015 5:32:47 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:10193) shutdown
Aug 10, 2015 5:32:47 AM org.apache.tajo.rpc.NettyClientBase$1 operationComplete
WARNING: java.nio.channels.ClosedChannelException Try to reconnect :localhost/127.0.0.1:10193
Aug 10, 2015 5:32:48 AM org.apache.tajo.rpc.NettyClientBase$NettyChannelInboundHandler exceptionCaught
SEVERE: Exception [org.apache.tajo.rpc.test.DummyProtocol(localhost/127.0.0.1:10193)]: ClosedChannelException: 
java.nio.channels.ClosedChannelException

Aug 10, 2015 5:32:48 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-21 ServerSocketChannelFactory. Worker:3
Aug 10, 2015 5:32:48 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:10194
Aug 10, 2015 5:32:48 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:10194) shutdown
Aug 10, 2015 5:32:48 AM org.apache.tajo.rpc.NettyClientBase$1 operationComplete
WARNING: java.nio.channels.ClosedChannelException Try to reconnect :localhost/127.0.0.1:10194
Aug 10, 2015 5:32:49 AM org.apache.tajo.rpc.NettyClientBase$NettyChannelInboundHandler exceptionCaught
SEVERE: Exception [org.apache.tajo.rpc.test.DummyProtocol(localhost/127.0.0.1:10194)]: ClosedChannelException: 
java.nio.channels.ClosedChannelException

Aug 10, 2015 5:32:49 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-22 ServerSocketChannelFactory. Worker:3
Aug 10, 2015 5:32:49 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:10195
Aug 10, 2015 5:32:50 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:10195) shutdown
Aug 10, 2015 5:32:50 AM org.apache.tajo.rpc.NettyClientBase doReconnect
WARNING: Exception [org.apache.tajo.rpc.test.DummyProtocol(/127.0.0.1:52610)]: ConnectException: Connection refused: /127.0.0.1:52610 Try to reconnect : /127.0.0.1:52610
Aug 10, 2015 5:32:51 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-23 ServerSocketChannelFactory. Worker:2
Aug 10, 2015 5:32:51 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:52610
Aug 10, 2015 5:32:51 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:52610) shutdown
Aug 10, 2015 5:32:51 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-24 ServerSocketChannelFactory. Worker:3
Aug 10, 2015 5:32:51 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:10196
Aug 10, 2015 5:32:54 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:10196) shutdown
Aug 10, 2015 5:32:54 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-25 ServerSocketChannelFactory. Worker:3
Aug 10, 2015 5:32:54 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:10197
Aug 10, 2015 5:32:55 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:10197) shutdown
Aug 10, 2015 5:32:55 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-26 ServerSocketChannelFactory. Worker:3
Aug 10, 2015 5:32:55 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:10198
Aug 10, 2015 5:32:55 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:10198) shutdown
Aug 10, 2015 5:32:55 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-27 ServerSocketChannelFactory. Worker:3
Aug 10, 2015 5:32:55 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:10199
Aug 10, 2015 5:32:55 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:10199) shutdown
Aug 10, 2015 5:32:55 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-28 ServerSocketChannelFactory. Worker:3
Aug 10, 2015 5:32:55 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:10200
Aug 10, 2015 5:32:55 AM org.apache.tajo.rpc.NettyClientBase$1 operationComplete
WARNING: java.nio.channels.ClosedChannelException Try to reconnect :/127.0.0.1:10200
Aug 10, 2015 5:32:56 AM org.apache.tajo.rpc.NettyClientBase$1 operationComplete
WARNING: java.nio.channels.ClosedChannelException Try to reconnect :/127.0.0.1:10200
Aug 10, 2015 5:32:57 AM org.apache.tajo.rpc.NettyClientBase$1 operationComplete
WARNING: java.nio.channels.ClosedChannelException Try to reconnect :/127.0.0.1:10200
Aug 10, 2015 5:32:58 AM org.apache.tajo.rpc.NettyClientBase$1 operationComplete
WARNING: java.nio.channels.ClosedChannelException Try to reconnect :/127.0.0.1:10200
Aug 10, 2015 5:32:59 AM org.apache.tajo.rpc.NettyClientBase$1 operationComplete
WARNING: java.nio.channels.ClosedChannelException Try to reconnect :/127.0.0.1:10200
Aug 10, 2015 5:33:00 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:10200) shutdown
Aug 10, 2015 5:33:00 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-29 ServerSocketChannelFactory. Worker:3
Aug 10, 2015 5:33:00 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:10201
Aug 10, 2015 5:33:03 AM org.apache.tajo.rpc.NettyClientBase$NettyChannelInboundHandler userEventTriggered
INFO: Idle connection closed successfully :[id: 0xb8d1e00c, /127.0.0.1:57025 :> /127.0.0.1:10201]
Aug 10, 2015 5:33:03 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:10201) shutdown
Aug 10, 2015 5:33:03 AM org.apache.tajo.rpc.NettyClientBase doReconnect
WARNING: Exception [org.apache.tajo.rpc.test.DummyProtocol(/127.0.0.1:50673)]: ConnectException: Connection refused: /127.0.0.1:50673 Try to reconnect : /127.0.0.1:50673
Aug 10, 2015 5:33:04 AM org.apache.tajo.rpc.NettyClientBase$1 operationComplete
WARNING: java.nio.channels.ClosedChannelException Try to reconnect :/127.0.0.1:50673
Aug 10, 2015 5:33:05 AM org.apache.tajo.rpc.NettyClientBase$1 operationComplete
WARNING: java.nio.channels.ClosedChannelException Try to reconnect :/127.0.0.1:50673
Aug 10, 2015 5:33:06 AM org.apache.tajo.rpc.NettyClientBase$NettyChannelInboundHandler exceptionCaught
SEVERE: Exception [org.apache.tajo.rpc.test.DummyProtocol(/127.0.0.1:50673)]: ClosedChannelException: 
java.nio.channels.ClosedChannelException

Tests run: 17, Failures: 0, Errors: 1, Skipped: 0, Time elapsed: 81.387 sec <<< FAILURE! - in org.apache.tajo.rpc.TestAsyncRpc
testUnresolvedAddress(org.apache.tajo.rpc.TestAsyncRpc)  Time elapsed: 60.003 sec  <<< ERROR!
java.lang.Exception: test timed out after 60000 milliseconds
	at java.lang.Object.wait(Native Method)
	at java.lang.Object.wait(Object.java:503)
	at io.netty.util.concurrent.DefaultPromise.awaitUninterruptibly(DefaultPromise.java:286)
	at io.netty.channel.DefaultChannelPromise.awaitUninterruptibly(DefaultChannelPromise.java:135)
	at io.netty.channel.DefaultChannelPromise.awaitUninterruptibly(DefaultChannelPromise.java:28)
	at org.apache.tajo.rpc.NettyClientBase.connect(NettyClientBase.java:180)
	at org.apache.tajo.rpc.TestAsyncRpc.testUnresolvedAddress(TestAsyncRpc.java:415)

Running org.apache.tajo.rpc.TestRpcClientManager
Aug 10, 2015 5:34:06 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-30 ServerSocketChannelFactory. Worker:3
Aug 10, 2015 5:34:06 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:10202
Aug 10, 2015 5:34:06 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:10202) shutdown
Aug 10, 2015 5:34:06 AM org.apache.tajo.rpc.RpcClientManager close
INFO: Closing RPC client manager
Aug 10, 2015 5:34:06 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-31 ServerSocketChannelFactory. Worker:50
Aug 10, 2015 5:34:06 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:10203
Aug 10, 2015 5:34:06 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:10203) shutdown
Aug 10, 2015 5:34:06 AM org.apache.tajo.rpc.RpcClientManager close
INFO: Closing RPC client manager
Aug 10, 2015 5:34:06 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-32 ServerSocketChannelFactory. Worker:3
Aug 10, 2015 5:34:06 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:10204
Aug 10, 2015 5:34:06 AM org.apache.tajo.rpc.RpcClientManager close
INFO: Closing RPC client manager
Aug 10, 2015 5:34:06 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:10204) shutdown
Aug 10, 2015 5:34:06 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-33 ServerSocketChannelFactory. Worker:3
Aug 10, 2015 5:34:06 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:10205
Aug 10, 2015 5:34:06 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:10205) shutdown
Aug 10, 2015 5:34:06 AM org.apache.tajo.rpc.RpcClientManager close
INFO: Closing RPC client manager
Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.092 sec - in org.apache.tajo.rpc.TestRpcClientManager

Results :

Tests in error: 
  TestBlockingRpc.testUnresolvedAddress:376->Object.wait:503->Object.wait:-2 »  ...
  TestAsyncRpc.testUnresolvedAddress:415->Object.wait:503->Object.wait:-2 »  tes...

Tests run: 37, Failures: 0, Errors: 2, Skipped: 0

[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO] 
[INFO] Tajo Main ......................................... SUCCESS [  3.169 s]
[INFO] Tajo Project POM .................................. SUCCESS [  2.674 s]
[INFO] Tajo Maven Plugins ................................ SUCCESS [  5.066 s]
[INFO] Tajo Common ....................................... SUCCESS [ 41.642 s]
[INFO] Tajo Algebra ...................................... SUCCESS [  2.926 s]
[INFO] Tajo Catalog Common ............................... SUCCESS [  7.707 s]
[INFO] Tajo Plan ......................................... SUCCESS [  8.075 s]
[INFO] Tajo Rpc Common ................................... SUCCESS [  0.792 s]
[INFO] Tajo Protocol Buffer Rpc .......................... FAILURE [02:49 min]
[INFO] Tajo Catalog Client ............................... SKIPPED
[INFO] Tajo Catalog Server ............................... SKIPPED
[INFO] Tajo Storage Common ............................... SKIPPED
[INFO] Tajo HDFS Storage ................................. SKIPPED
[INFO] Tajo HBase Storage ................................ SKIPPED
[INFO] Tajo PullServer ................................... SKIPPED
[INFO] Tajo Client ....................................... SKIPPED
[INFO] Tajo CLI tools .................................... SKIPPED
[INFO] Tajo JDBC Driver .................................. SKIPPED
[INFO] ASM (thirdparty) .................................. SKIPPED
[INFO] Tajo RESTful Container ............................ SKIPPED
[INFO] Tajo Metrics ...................................... SKIPPED
[INFO] Tajo Core ......................................... SKIPPED
[INFO] Tajo RPC .......................................... SKIPPED
[INFO] Tajo Catalog Drivers Hive ......................... SKIPPED
[INFO] Tajo Catalog Drivers .............................. SKIPPED
[INFO] Tajo Catalog ...................................... SKIPPED
[INFO] Tajo Storage ...................................... SKIPPED
[INFO] Tajo Distribution ................................. SKIPPED
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 04:03 min
[INFO] Finished at: 2015-08-10T05:34:07+00:00
[INFO] Final Memory: 54M/452M
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.17:test (default-test) on project tajo-rpc-protobuf: There are test failures.
[ERROR] 
[ERROR] Please refer to <https://builds.apache.org/job/Tajo-master-build/ws/tajo-rpc/tajo-rpc-protobuf/target/surefire-reports> for the individual test results.
[ERROR] -> [Help 1]
[ERROR] 
[ERROR] To see the full stack trace of the errors, re-run Maven with the -e switch.
[ERROR] Re-run Maven using the -X switch to enable full debug logging.
[ERROR] 
[ERROR] For more information about the errors and possible solutions, please read the following articles:
[ERROR] [Help 1] http://cwiki.apache.org/confluence/display/MAVEN/MojoFailureException
[ERROR] 
[ERROR] After correcting the problems, you can resume the build with the command
[ERROR]   mvn <goals> -rf :tajo-rpc-protobuf
Build step 'Execute shell' marked build as failure
Updating TAJO-1743

Jenkins build is back to normal : Tajo-master-build #796

Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Tajo-master-build/796/changes>


Build failed in Jenkins: Tajo-master-build #795

Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Tajo-master-build/795/changes>

Changes:

[jihoonson] TAJO-1732: During filter push down phase, join conditions are not set properly.

------------------------------------------
[...truncated 986 lines...]
Aug 11, 2015 6:58:41 AM org.apache.tajo.rpc.MonitorClientHandler userEventTriggered
INFO: Server has not respond [id: 0xdd2bedd3, /127.0.0.1:56545 => /127.0.0.1:23048]
Aug 11, 2015 6:58:41 AM org.apache.tajo.rpc.NettyClientBase$NettyChannelInboundHandler exceptionCaught
SEVERE: Exception [org.apache.tajo.rpc.test.DummyProtocol(/127.0.0.1:23048)]: 
Aug 11, 2015 6:58:41 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:23048) shutdown
Aug 11, 2015 6:58:41 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-7 ServerSocketChannelFactory. Worker:3
Aug 11, 2015 6:58:41 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:23049
Aug 11, 2015 6:58:41 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:23049) shutdown
Aug 11, 2015 6:58:41 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-8 ServerSocketChannelFactory. Worker:3
Aug 11, 2015 6:58:41 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:23050
Aug 11, 2015 6:58:41 AM org.apache.tajo.rpc.test.impl.DummyProtocolAsyncImpl getNull
INFO: noCallback is called
Aug 11, 2015 6:58:41 AM org.apache.tajo.rpc.TestAsyncRpc$4 run
INFO: testGetNull retrieved
Aug 11, 2015 6:58:41 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:23050) shutdown
Aug 11, 2015 6:58:41 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-9 ServerSocketChannelFactory. Worker:3
Aug 11, 2015 6:58:41 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:23051
Aug 11, 2015 6:58:41 AM org.apache.tajo.rpc.NettyClientBase$NettyChannelInboundHandler userEventTriggered
INFO: Idle connection closed successfully :[id: 0x24632d48, /127.0.0.1:51250 :> /127.0.0.1:23051]
Aug 11, 2015 6:58:42 AM org.apache.tajo.rpc.NettyClientBase$NettyChannelInboundHandler userEventTriggered
INFO: Idle connection closed successfully :[id: 0x5d92d2f6, /127.0.0.1:51253 :> /127.0.0.1:23051]
Aug 11, 2015 6:58:42 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:23051) shutdown
Aug 11, 2015 6:58:42 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-10 ServerSocketChannelFactory. Worker:3
Aug 11, 2015 6:58:42 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:23052
Aug 11, 2015 6:58:42 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:23052) shutdown
Aug 11, 2015 6:58:42 AM org.apache.tajo.rpc.NettyClientBase$1 operationComplete
WARNING: java.nio.channels.ClosedChannelException Try to reconnect :localhost/127.0.0.1:23052
Aug 11, 2015 6:58:43 AM org.apache.tajo.rpc.NettyClientBase$NettyChannelInboundHandler exceptionCaught
SEVERE: Exception [org.apache.tajo.rpc.test.DummyProtocol(localhost/127.0.0.1:23052)]: ClosedChannelException: 
java.nio.channels.ClosedChannelException

Aug 11, 2015 6:58:43 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-11 ServerSocketChannelFactory. Worker:3
Aug 11, 2015 6:58:43 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:23053
Aug 11, 2015 6:58:43 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:23053) shutdown
Aug 11, 2015 6:58:43 AM org.apache.tajo.rpc.NettyClientBase$1 operationComplete
WARNING: java.nio.channels.ClosedChannelException Try to reconnect :localhost/127.0.0.1:23053
Aug 11, 2015 6:58:44 AM org.apache.tajo.rpc.NettyClientBase$NettyChannelInboundHandler exceptionCaught
SEVERE: Exception [org.apache.tajo.rpc.test.DummyProtocol(localhost/127.0.0.1:23053)]: ClosedChannelException: 
java.nio.channels.ClosedChannelException

Aug 11, 2015 6:58:44 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-12 ServerSocketChannelFactory. Worker:3
Aug 11, 2015 6:58:44 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:23054
Aug 11, 2015 6:58:45 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:23054) shutdown
Aug 11, 2015 6:58:45 AM org.apache.tajo.rpc.NettyClientBase doReconnect
WARNING: Exception [org.apache.tajo.rpc.test.DummyProtocol(/127.0.0.1:37559)]: ConnectException: Connection refused: /127.0.0.1:37559 Try to reconnect : /127.0.0.1:37559
Aug 11, 2015 6:58:46 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-13 ServerSocketChannelFactory. Worker:2
Aug 11, 2015 6:58:46 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:37559
Aug 11, 2015 6:58:46 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:37559) shutdown
Aug 11, 2015 6:58:46 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-14 ServerSocketChannelFactory. Worker:3
Aug 11, 2015 6:58:46 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:23055
Aug 11, 2015 6:58:49 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:23055) shutdown
Aug 11, 2015 6:58:49 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-15 ServerSocketChannelFactory. Worker:3
Aug 11, 2015 6:58:49 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:23056
Aug 11, 2015 6:58:50 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:23056) shutdown
Aug 11, 2015 6:58:50 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-16 ServerSocketChannelFactory. Worker:3
Aug 11, 2015 6:58:50 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:23057
Aug 11, 2015 6:58:50 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:23057) shutdown
Aug 11, 2015 6:58:50 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-17 ServerSocketChannelFactory. Worker:3
Aug 11, 2015 6:58:50 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:23058
Aug 11, 2015 6:58:50 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:23058) shutdown
Aug 11, 2015 6:58:50 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-18 ServerSocketChannelFactory. Worker:3
Aug 11, 2015 6:58:50 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:23059
Aug 11, 2015 6:58:50 AM org.apache.tajo.rpc.NettyClientBase$1 operationComplete
WARNING: java.nio.channels.ClosedChannelException Try to reconnect :/127.0.0.1:23059
Aug 11, 2015 6:58:51 AM org.apache.tajo.rpc.NettyClientBase$1 operationComplete
WARNING: java.nio.channels.ClosedChannelException Try to reconnect :/127.0.0.1:23059
Aug 11, 2015 6:58:52 AM org.apache.tajo.rpc.NettyClientBase$1 operationComplete
WARNING: java.nio.channels.ClosedChannelException Try to reconnect :/127.0.0.1:23059
Aug 11, 2015 6:58:53 AM org.apache.tajo.rpc.NettyClientBase$1 operationComplete
WARNING: java.nio.channels.ClosedChannelException Try to reconnect :/127.0.0.1:23059
Aug 11, 2015 6:58:54 AM org.apache.tajo.rpc.NettyClientBase$1 operationComplete
WARNING: java.nio.channels.ClosedChannelException Try to reconnect :/127.0.0.1:23059
Aug 11, 2015 6:58:55 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:23059) shutdown
Aug 11, 2015 6:58:55 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-19 ServerSocketChannelFactory. Worker:3
Aug 11, 2015 6:58:55 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:23060
Aug 11, 2015 6:58:58 AM org.apache.tajo.rpc.NettyClientBase$NettyChannelInboundHandler userEventTriggered
INFO: Idle connection closed successfully :[id: 0xe3855141, /127.0.0.1:39999 :> /127.0.0.1:23060]
Aug 11, 2015 6:58:58 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:23060) shutdown
Aug 11, 2015 6:58:58 AM org.apache.tajo.rpc.NettyClientBase doReconnect
WARNING: Exception [org.apache.tajo.rpc.test.DummyProtocol(/127.0.0.1:52752)]: ConnectException: Connection refused: /127.0.0.1:52752 Try to reconnect : /127.0.0.1:52752
Aug 11, 2015 6:58:59 AM org.apache.tajo.rpc.NettyClientBase$1 operationComplete
WARNING: java.nio.channels.ClosedChannelException Try to reconnect :/127.0.0.1:52752
Aug 11, 2015 6:59:00 AM org.apache.tajo.rpc.NettyClientBase$1 operationComplete
WARNING: java.nio.channels.ClosedChannelException Try to reconnect :/127.0.0.1:52752
Aug 11, 2015 6:59:01 AM org.apache.tajo.rpc.NettyClientBase$NettyChannelInboundHandler exceptionCaught
SEVERE: Exception [org.apache.tajo.rpc.test.DummyProtocol(/127.0.0.1:52752)]: ClosedChannelException: 
java.nio.channels.ClosedChannelException

Tests run: 17, Failures: 0, Errors: 1, Skipped: 0, Time elapsed: 81.5 sec <<< FAILURE! - in org.apache.tajo.rpc.TestAsyncRpc
testUnresolvedAddress(org.apache.tajo.rpc.TestAsyncRpc)  Time elapsed: 60.037 sec  <<< ERROR!
java.lang.Exception: test timed out after 60000 milliseconds
	at java.lang.Object.wait(Native Method)
	at java.lang.Object.wait(Object.java:503)
	at io.netty.util.concurrent.DefaultPromise.awaitUninterruptibly(DefaultPromise.java:286)
	at io.netty.channel.DefaultChannelPromise.awaitUninterruptibly(DefaultChannelPromise.java:135)
	at io.netty.channel.DefaultChannelPromise.awaitUninterruptibly(DefaultChannelPromise.java:28)
	at org.apache.tajo.rpc.NettyClientBase.connect(NettyClientBase.java:180)
	at org.apache.tajo.rpc.TestAsyncRpc.testUnresolvedAddress(TestAsyncRpc.java:415)

Running org.apache.tajo.rpc.TestBlockingRpc
Aug 11, 2015 7:00:01 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-20 ServerSocketChannelFactory. Worker:2
Aug 11, 2015 7:00:01 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:23061
Aug 11, 2015 7:00:01 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:23061) shutdown
Aug 11, 2015 7:00:01 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-21 ServerSocketChannelFactory. Worker:2
Aug 11, 2015 7:00:01 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:23062
Aug 11, 2015 7:00:02 AM org.apache.tajo.rpc.MonitorClientHandler userEventTriggered
INFO: Server has not respond [id: 0x6ff97001, /127.0.0.1:47206 => /127.0.0.1:23062]
Aug 11, 2015 7:00:02 AM org.apache.tajo.rpc.NettyClientBase$NettyChannelInboundHandler exceptionCaught
SEVERE: Exception [org.apache.tajo.rpc.test.DummyProtocol(/127.0.0.1:23062)]: 
Aug 11, 2015 7:00:02 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:23062) shutdown
Aug 11, 2015 7:00:02 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-22 ServerSocketChannelFactory. Worker:2
Aug 11, 2015 7:00:02 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:23063
Aug 11, 2015 7:00:02 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:23063) shutdown
Aug 11, 2015 7:00:02 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-23 ServerSocketChannelFactory. Worker:2
Aug 11, 2015 7:00:02 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:23064
Aug 11, 2015 7:00:02 AM org.apache.tajo.rpc.test.impl.DummyProtocolBlockingImpl getNull
INFO: noCallback is called
Aug 11, 2015 7:00:02 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:23064) shutdown
Aug 11, 2015 7:00:02 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-24 ServerSocketChannelFactory. Worker:2
Aug 11, 2015 7:00:02 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:23065
Aug 11, 2015 7:00:03 AM org.apache.tajo.rpc.NettyClientBase$NettyChannelInboundHandler userEventTriggered
INFO: Idle connection closed successfully :[id: 0xdcbf7c40, /127.0.0.1:55429 :> /127.0.0.1:23065]
Aug 11, 2015 7:00:03 AM org.apache.tajo.rpc.NettyClientBase$NettyChannelInboundHandler userEventTriggered
INFO: Idle connection closed successfully :[id: 0x6af16b28, /127.0.0.1:55430 :> /127.0.0.1:23065]
Aug 11, 2015 7:00:04 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:23065) shutdown
Aug 11, 2015 7:00:04 AM org.apache.tajo.rpc.RpcChannelFactory createServerChannelFactory
INFO: Create DummyProtocol-25 ServerSocketChannelFactory. Worker:2
Aug 11, 2015 7:00:04 AM org.apache.tajo.rpc.NettyServerBase start
INFO: Rpc (DummyProtocol) listens on /127.0.0.1:23066
Aug 11, 2015 7:00:04 AM org.apache.tajo.rpc.NettyServerBase shutdown
INFO: Rpc (DummyProtocol) listened on 127.0.0.1:23066) shutdown
Aug 11, 2015 7:00:04 AM org.apache.tajo.rpc.NettyClientBase$1 operationComplete
WARNING: java.nio.channels.ClosedChannelException Try to reconnect :localhost/127.0.0.1:23066
Killed

Results :

Tests in error: 
  TestAsyncRpc.testUnresolvedAddress:415->Object.wait:503->Object.wait:-2 »  tes...

Tests run: 21, Failures: 0, Errors: 1, Skipped: 0

[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO] 
[INFO] Tajo Main ......................................... SUCCESS [  1.860 s]
[INFO] Tajo Project POM .................................. SUCCESS [  1.648 s]
[INFO] Tajo Maven Plugins ................................ SUCCESS [  3.048 s]
[INFO] Tajo Common ....................................... SUCCESS [ 30.718 s]
[INFO] Tajo Algebra ...................................... SUCCESS [  2.778 s]
[INFO] Tajo Catalog Common ............................... SUCCESS [  4.786 s]
[INFO] Tajo Plan ......................................... SUCCESS [  6.012 s]
[INFO] Tajo Rpc Common ................................... SUCCESS [  1.452 s]
[INFO] Tajo Protocol Buffer Rpc .......................... FAILURE [01:26 min]
[INFO] Tajo Catalog Client ............................... SKIPPED
[INFO] Tajo Catalog Server ............................... SKIPPED
[INFO] Tajo Storage Common ............................... SKIPPED
[INFO] Tajo HDFS Storage ................................. SKIPPED
[INFO] Tajo HBase Storage ................................ SKIPPED
[INFO] Tajo PullServer ................................... SKIPPED
[INFO] Tajo Client ....................................... SKIPPED
[INFO] Tajo CLI tools .................................... SKIPPED
[INFO] Tajo JDBC Driver .................................. SKIPPED
[INFO] ASM (thirdparty) .................................. SKIPPED
[INFO] Tajo RESTful Container ............................ SKIPPED
[INFO] Tajo Metrics ...................................... SKIPPED
[INFO] Tajo Core ......................................... SKIPPED
[INFO] Tajo RPC .......................................... SKIPPED
[INFO] Tajo Catalog Drivers Hive ......................... SKIPPED
[INFO] Tajo Catalog Drivers .............................. SKIPPED
[INFO] Tajo Catalog ...................................... SKIPPED
[INFO] Tajo Storage ...................................... SKIPPED
[INFO] Tajo Distribution ................................. SKIPPED
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 02:19 min
[INFO] Finished at: 2015-08-11T07:00:04+00:00
[INFO] Final Memory: 54M/417M
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.17:test (default-test) on project tajo-rpc-protobuf: Execution default-test of goal org.apache.maven.plugins:maven-surefire-plugin:2.17:test failed: The forked VM terminated without properly saying goodbye. VM crash or System.exit called?
[ERROR] Command was /bin/sh -c cd <https://builds.apache.org/job/Tajo-master-build/ws/tajo-rpc/tajo-rpc-protobuf> && /home/jenkins/tools/java/jdk1.7.0_25-32/jre/bin/java -jar <https://builds.apache.org/job/Tajo-master-build/ws/tajo-rpc/tajo-rpc-protobuf/target/surefire/surefirebooter5824649887925705563.jar> <https://builds.apache.org/job/Tajo-master-build/ws/tajo-rpc/tajo-rpc-protobuf/target/surefire/surefire1808504733469701204tmp> <https://builds.apache.org/job/Tajo-master-build/ws/tajo-rpc/tajo-rpc-protobuf/target/surefire/surefire_42228009705234460076tmp>
[ERROR] -> [Help 1]
[ERROR] 
[ERROR] To see the full stack trace of the errors, re-run Maven with the -e switch.
[ERROR] Re-run Maven using the -X switch to enable full debug logging.
[ERROR] 
[ERROR] For more information about the errors and possible solutions, please read the following articles:
[ERROR] [Help 1] http://cwiki.apache.org/confluence/display/MAVEN/PluginExecutionException
[ERROR] 
[ERROR] After correcting the problems, you can resume the build with the command
[ERROR]   mvn <goals> -rf :tajo-rpc-protobuf
Build step 'Execute shell' marked build as failure
Updating TAJO-1732