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/09/11 03:42:12 UTC
Build failed in Jenkins: Tajo-master-build #858
See <https://builds.apache.org/job/Tajo-master-build/858/>
------------------------------------------
[...truncated 175794 lines...]
at io.netty.channel.ChannelInboundHandlerAdapter.channelRead(ChannelInboundHandlerAdapter.java:86)
at org.apache.tajo.rpc.MonitorServerHandler.channelRead(MonitorServerHandler.java:70)
at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:308)
at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:294)
at io.netty.channel.DefaultChannelPipeline.fireChannelRead(DefaultChannelPipeline.java:846)
at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:131)
at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:511)
at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:468)
at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:382)
at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:354)
at io.netty.util.concurrent.SingleThreadEventExecutor$2.run(SingleThreadEventExecutor.java:111)
at java.lang.Thread.run(Thread.java:724)
Caused by: java.lang.reflect.InvocationTargetException
at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:57)
at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
at java.lang.reflect.Constructor.newInstance(Constructor.java:526)
at org.apache.hadoop.hbase.client.ConnectionFactory.createConnection(ConnectionFactory.java:238)
... 36 more
Caused by: java.lang.NumberFormatException: For input string: "null"
at java.lang.NumberFormatException.forInputString(NumberFormatException.java:65)
at java.lang.Integer.parseInt(Integer.java:492)
at java.lang.Integer.parseInt(Integer.java:527)
at org.apache.hadoop.conf.Configuration.getInt(Configuration.java:1258)
at org.apache.hadoop.hbase.zookeeper.ZKConfig.getZKQuorumServersStringFromHbaseConfig(ZKConfig.java:303)
at org.apache.hadoop.hbase.zookeeper.ZKConfig.getZKQuorumServersString(ZKConfig.java:348)
at org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher.<init>(ZooKeeperWatcher.java:168)
at org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher.<init>(ZooKeeperWatcher.java:143)
at org.apache.hadoop.hbase.client.ZooKeeperKeepAliveConnection.<init>(ZooKeeperKeepAliveConnection.java:43)
at org.apache.hadoop.hbase.client.ConnectionManager$HConnectionImplementation.getKeepAliveZooKeeperWatcher(ConnectionManager.java:1656)
at org.apache.hadoop.hbase.client.ZooKeeperRegistry.getClusterId(ZooKeeperRegistry.java:104)
at org.apache.hadoop.hbase.client.ConnectionManager$HConnectionImplementation.retrieveClusterId(ConnectionManager.java:879)
at org.apache.hadoop.hbase.client.ConnectionManager$HConnectionImplementation.<init>(ConnectionManager.java:635)
... 41 more
2015-09-11 01:38:31,166 INFO: org.apache.tajo.master.GlobalEngine (updateQuery(239)) - SQL: CREATE DATABASE IF NOT EXISTS "TestHBaseTable"
2015-09-11 01:38:31,166 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(280)) - Non Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
CREATE_DATABASE(0) IF NOT EXISTS TestHBaseTable
2015-09-11 01:38:31,166 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(282)) - =============================================
2015-09-11 01:38:31,166 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(283)) - Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
CREATE_DATABASE(0) IF NOT EXISTS TestHBaseTable
2015-09-11 01:38:31,166 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(284)) - =============================================
2015-09-11 01:38:31,167 INFO: org.apache.tajo.master.exec.DDLExecutor (createDatabase(247)) - database "TestHBaseTable" is already exists.
Run: testIndexPredication Used memory: 645 MBytes, Active Threads:234
2015-09-11 01:38:31,168 INFO: org.apache.tajo.master.GlobalEngine (executeQuery(176)) - Query: CREATE TABLE hbase_mapped_table (rk text, col1 text, col2 text, col3 text) TABLESPACE cluster1 USING hbase WITH ('table'='hbase_table', 'columns'=':key,col1:a,col2:,col3:b', 'hbase.split.rowkeys'='010,040,060,080')
2015-09-11 01:38:31,169 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(280)) - Non Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
2015-09-11 01:38:31,169 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(282)) - =============================================
2015-09-11 01:38:31,170 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(283)) - Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
2015-09-11 01:38:31,170 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(284)) - =============================================
2015-09-11 01:38:31,172 ERROR: org.apache.tajo.master.GlobalEngine (printStackTrace(185)) -
Stack Trace:
java.io.IOException: java.lang.reflect.InvocationTargetException
at org.apache.hadoop.hbase.client.ConnectionFactory.createConnection(ConnectionFactory.java:240)
at org.apache.hadoop.hbase.client.ConnectionManager.createConnection(ConnectionManager.java:420)
at org.apache.hadoop.hbase.client.ConnectionManager.createConnection(ConnectionManager.java:413)
at org.apache.hadoop.hbase.client.ConnectionManager.getConnectionInternal(ConnectionManager.java:291)
at org.apache.hadoop.hbase.client.HBaseAdmin.<init>(HBaseAdmin.java:222)
at org.apache.tajo.storage.hbase.HBaseTablespace.createTable(HBaseTablespace.java:173)
at org.apache.tajo.storage.hbase.HBaseTablespace.createTable(HBaseTablespace.java:128)
at org.apache.tajo.master.exec.CreateTableExecutor.create(CreateTableExecutor.java:109)
at org.apache.tajo.master.exec.CreateTableExecutor.create(CreateTableExecutor.java:66)
at org.apache.tajo.master.exec.DDLExecutor.execute(DDLExecutor.java:99)
at org.apache.tajo.master.exec.QueryExecutor.execute(QueryExecutor.java:115)
at org.apache.tajo.master.GlobalEngine.executeQuery(GlobalEngine.java:191)
at org.apache.tajo.master.TajoMasterClientService$TajoMasterClientProtocolServiceHandler.submitQuery(TajoMasterClientService.java:305)
at org.apache.tajo.ipc.TajoMasterClientProtocol$TajoMasterClientProtocolService$2.callBlockingMethod(TajoMasterClientProtocol.java:659)
at org.apache.tajo.rpc.BlockingRpcServer$ServerHandler.channelRead0(BlockingRpcServer.java:100)
at org.apache.tajo.rpc.BlockingRpcServer$ServerHandler.channelRead0(BlockingRpcServer.java:61)
at io.netty.channel.SimpleChannelInboundHandler.channelRead(SimpleChannelInboundHandler.java:105)
at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:308)
at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:294)
at io.netty.handler.codec.MessageToMessageDecoder.channelRead(MessageToMessageDecoder.java:103)
at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:308)
at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:294)
at io.netty.handler.codec.ByteToMessageDecoder.channelRead(ByteToMessageDecoder.java:244)
at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:308)
at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:294)
at io.netty.channel.ChannelInboundHandlerAdapter.channelRead(ChannelInboundHandlerAdapter.java:86)
at org.apache.tajo.rpc.MonitorServerHandler.channelRead(MonitorServerHandler.java:70)
at io.netty.channel.AbstractChannelHandlerContext.invokeChannelRead(AbstractChannelHandlerContext.java:308)
at io.netty.channel.AbstractChannelHandlerContext.fireChannelRead(AbstractChannelHandlerContext.java:294)
at io.netty.channel.DefaultChannelPipeline.fireChannelRead(DefaultChannelPipeline.java:846)
at io.netty.channel.nio.AbstractNioByteChannel$NioByteUnsafe.read(AbstractNioByteChannel.java:131)
at io.netty.channel.nio.NioEventLoop.processSelectedKey(NioEventLoop.java:511)
at io.netty.channel.nio.NioEventLoop.processSelectedKeysOptimized(NioEventLoop.java:468)
at io.netty.channel.nio.NioEventLoop.processSelectedKeys(NioEventLoop.java:382)
at io.netty.channel.nio.NioEventLoop.run(NioEventLoop.java:354)
at io.netty.util.concurrent.SingleThreadEventExecutor$2.run(SingleThreadEventExecutor.java:111)
at java.lang.Thread.run(Thread.java:724)
Caused by: java.lang.reflect.InvocationTargetException
at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:57)
at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
at java.lang.reflect.Constructor.newInstance(Constructor.java:526)
at org.apache.hadoop.hbase.client.ConnectionFactory.createConnection(ConnectionFactory.java:238)
... 36 more
Caused by: java.lang.NumberFormatException: For input string: "null"
at java.lang.NumberFormatException.forInputString(NumberFormatException.java:65)
at java.lang.Integer.parseInt(Integer.java:492)
at java.lang.Integer.parseInt(Integer.java:527)
at org.apache.hadoop.conf.Configuration.getInt(Configuration.java:1258)
at org.apache.hadoop.hbase.zookeeper.ZKConfig.getZKQuorumServersStringFromHbaseConfig(ZKConfig.java:303)
at org.apache.hadoop.hbase.zookeeper.ZKConfig.getZKQuorumServersString(ZKConfig.java:348)
at org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher.<init>(ZooKeeperWatcher.java:168)
at org.apache.hadoop.hbase.zookeeper.ZooKeeperWatcher.<init>(ZooKeeperWatcher.java:143)
at org.apache.hadoop.hbase.client.ZooKeeperKeepAliveConnection.<init>(ZooKeeperKeepAliveConnection.java:43)
at org.apache.hadoop.hbase.client.ConnectionManager$HConnectionImplementation.getKeepAliveZooKeeperWatcher(ConnectionManager.java:1656)
at org.apache.hadoop.hbase.client.ZooKeeperRegistry.getClusterId(ZooKeeperRegistry.java:104)
at org.apache.hadoop.hbase.client.ConnectionManager$HConnectionImplementation.retrieveClusterId(ConnectionManager.java:879)
at org.apache.hadoop.hbase.client.ConnectionManager$HConnectionImplementation.<init>(ConnectionManager.java:635)
... 41 more
2015-09-11 01:38:31,174 INFO: org.apache.tajo.master.GlobalEngine (updateQuery(239)) - SQL: CREATE DATABASE IF NOT EXISTS "TestHBaseTable"
2015-09-11 01:38:31,174 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(280)) - Non Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
CREATE_DATABASE(0) IF NOT EXISTS TestHBaseTable
2015-09-11 01:38:31,174 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(282)) - =============================================
2015-09-11 01:38:31,175 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(283)) - Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
CREATE_DATABASE(0) IF NOT EXISTS TestHBaseTable
2015-09-11 01:38:31,175 INFO: org.apache.tajo.master.GlobalEngine (createLogicalPlan(284)) - =============================================
2015-09-11 01:38:31,175 INFO: org.apache.tajo.master.exec.DDLExecutor (createDatabase(247)) - database "TestHBaseTable" is already exists.
Run: testRowFieldSelectQuery Used memory: 646 MBytes, Active Threads:234
2015-09-11 01:38:31,178 INFO: org.apache.zookeeper.ZooKeeper (<init>(438)) - Initiating client connection, connectString=localhost:65273 sessionTimeout=180000 watcher=hconnection-0x17f54ac0x0, quorum=localhost:65273, baseZNode=/hbase
2015-09-11 01:38:31,179 INFO: org.apache.zookeeper.ClientCnxn (logStartConnect(975)) - Opening socket connection to server localhost/0:0:0:0:0:0:0:1:65273. Will not attempt to authenticate using SASL (unknown error)
2015-09-11 01:38:31,180 INFO: org.apache.zookeeper.server.NIOServerCnxnFactory (run(197)) - Accepted socket connection from /0:0:0:0:0:0:0:1:52973
2015-09-11 01:38:31,180 INFO: org.apache.zookeeper.ClientCnxn (primeConnection(852)) - Socket connection established to localhost/0:0:0:0:0:0:0:1:65273, initiating session
2015-09-11 01:38:31,181 INFO: org.apache.zookeeper.server.ZooKeeperServer (processConnectRequest(868)) - Client attempting to establish new session at /0:0:0:0:0:0:0:1:52973
2015-09-11 01:38:31,182 INFO: org.apache.zookeeper.server.ZooKeeperServer (finishSessionInit(617)) - Established session 0x14fba0ab4140005 with negotiated timeout 40000 for client /0:0:0:0:0:0:0:1:52973
2015-09-11 01:38:31,182 INFO: org.apache.zookeeper.ClientCnxn (onConnected(1235)) - Session establishment complete on server localhost/0:0:0:0:0:0:0:1:65273, sessionid = 0x14fba0ab4140005, negotiated timeout = 40000
Killed
Results :
Tests run: 497, Failures: 0, Errors: 0, Skipped: 0
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] Tajo Main ......................................... SUCCESS [ 2.279 s]
[INFO] Tajo Project POM .................................. SUCCESS [ 2.777 s]
[INFO] Tajo Maven Plugins ................................ SUCCESS [ 3.933 s]
[INFO] Tajo Common ....................................... SUCCESS [ 36.707 s]
[INFO] Tajo Algebra ...................................... SUCCESS [ 2.678 s]
[INFO] Tajo Catalog Common ............................... SUCCESS [ 4.996 s]
[INFO] Tajo Plan ......................................... SUCCESS [ 6.854 s]
[INFO] Tajo Rpc Common ................................... SUCCESS [ 1.452 s]
[INFO] Tajo Protocol Buffer Rpc .......................... SUCCESS [ 49.516 s]
[INFO] Tajo Catalog Client ............................... SUCCESS [ 1.413 s]
[INFO] Tajo Catalog Server ............................... SUCCESS [ 11.199 s]
[INFO] Tajo Storage Common ............................... SUCCESS [ 3.172 s]
[INFO] Tajo HDFS Storage ................................. SUCCESS [ 54.165 s]
[INFO] Tajo PullServer ................................... SUCCESS [ 1.265 s]
[INFO] Tajo Client ....................................... SUCCESS [ 2.365 s]
[INFO] Tajo CLI tools .................................... SUCCESS [ 2.374 s]
[INFO] Tajo SQL Parser ................................... SUCCESS [ 5.273 s]
[INFO] ASM (thirdparty) .................................. SUCCESS [ 2.284 s]
[INFO] Tajo RESTful Container ............................ SUCCESS [ 4.563 s]
[INFO] Tajo Metrics ...................................... SUCCESS [ 1.387 s]
[INFO] Tajo Core ......................................... SUCCESS [ 7.100 s]
[INFO] Tajo RPC .......................................... SUCCESS [ 1.013 s]
[INFO] Tajo Catalog Drivers Hive ......................... SUCCESS [ 12.621 s]
[INFO] Tajo Catalog Drivers .............................. SUCCESS [ 0.107 s]
[INFO] Tajo Catalog ...................................... SUCCESS [ 1.033 s]
[INFO] Tajo Client Example ............................... SUCCESS [ 1.067 s]
[INFO] Tajo HBase Storage ................................ SUCCESS [ 4.831 s]
[INFO] Tajo Cluster Tests ................................ SUCCESS [ 3.098 s]
[INFO] Tajo JDBC Driver .................................. SUCCESS [ 33.839 s]
[INFO] Tajo Storage ...................................... SUCCESS [ 1.006 s]
[INFO] Tajo Distribution ................................. SUCCESS [ 5.651 s]
[INFO] Tajo Core Tests ................................... FAILURE [11:27 min]
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 16:00 min
[INFO] Finished at: 2015-09-11T01:42:16+00:00
[INFO] Final Memory: 115M/481M
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.17:test (default-test) on project tajo-core-tests: 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-core-tests> && /home/jenkins/tools/java/jdk1.7.0_25-32/jre/bin/java -Xms512m -Xmx1024m -XX:MaxPermSize=152m -Dfile.encoding=UTF-8 -Dderby.storage.pageSize=1024 -Dderby.stream.error.file=/dev/null -jar <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/surefire/surefirebooter2979511047695008867.jar> <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/surefire/surefire3753457511870668901tmp> <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/surefire/surefire_145049190643662014tmp>
[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-core-tests
Build step 'Execute shell' marked build as failure
Jenkins build is back to normal : Tajo-master-build #860
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Tajo-master-build/860/changes>
Build failed in Jenkins: Tajo-master-build #859
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Tajo-master-build/859/changes>
Changes:
[blrunner] TAJO-1739: Add a statement for adding partition to TajoDump.
------------------------------------------
[...truncated 726883 lines...]
2015-09-11 04:23:51,395 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - STATS TABLE is created.
2015-09-11 04:23:51,402 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - IDX_STATS_TABLE_NAME INDEX is created.
2015-09-11 04:23:51,411 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - PARTITION_METHODS TABLE is created.
2015-09-11 04:23:51,417 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - IDX_PARTITION_METHODS_TABLE_ID INDEX is created.
2015-09-11 04:23:51,433 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - PARTITIONS TABLE is created.
2015-09-11 04:23:51,441 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - PARTITIONS_IDX INDEX is created.
2015-09-11 04:23:51,451 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - PARTITION_KEYS TABLE is created.
2015-09-11 04:23:51,458 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - PARTITION_KEYS_IDX INDEX is created.
2015-09-11 04:23:51,461 INFO: org.apache.tajo.catalog.store.DerbyStore (<init>(179)) - The base tables of CatalogServer are created.
2015-09-11 04:23:51,461 INFO: org.apache.tajo.rpc.RpcChannelFactory (createServerChannelFactory(141)) - Create CatalogProtocol-28 ServerSocketChannelFactory. Worker:2
2015-09-11 04:23:51,465 INFO: org.apache.tajo.rpc.NettyServerBase (start(130)) - Rpc (CatalogProtocol) listens on /127.0.0.1:13898
2015-09-11 04:23:51,465 INFO: org.apache.tajo.catalog.CatalogServer (serviceStart(176)) - Catalog Server startup (127.0.0.1:13898)
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.678 sec - in org.apache.tajo.engine.planner.TestLogicalPlan
Running org.apache.tajo.engine.planner.physical.TestHashJoinExec
2015-09-11 04:23:51,730 INFO: org.apache.tajo.TajoTestingCluster (startCatalogCluster(296)) - Apache Derby repository is set to jdbc:derby:memory:<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/aa3aa8db-e2b8-4e98-971e-37be22106acb/db;create=true>
2015-09-11 04:23:51,730 INFO: org.apache.tajo.catalog.CatalogServer (serviceInit(125)) - Catalog Store Class: org.apache.tajo.catalog.store.DerbyStore
2015-09-11 04:23:51,731 INFO: org.apache.tajo.catalog.store.DerbyStore (<init>(150)) - Loaded the Catalog driver (org.apache.derby.jdbc.EmbeddedDriver)
2015-09-11 04:23:51,731 INFO: org.apache.tajo.catalog.store.DerbyStore (<init>(156)) - Trying to connect database (jdbc:derby:memory:<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/aa3aa8db-e2b8-4e98-971e-37be22106acb/db;create=true)>
2015-09-11 04:23:51,827 INFO: org.apache.tajo.catalog.store.DerbyStore (<init>(158)) - Connected to database (jdbc:derby:memory:<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/aa3aa8db-e2b8-4e98-971e-37be22106acb/db;create=true)>
2015-09-11 04:23:51,871 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - META TABLE is created.
2015-09-11 04:23:51,883 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - TABLESPACES TABLE is created.
2015-09-11 04:23:51,895 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - DATABASES_ TABLE is created.
2015-09-11 04:23:51,956 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - IDX_DATABASE_DB_ID INDEX is created.
2015-09-11 04:23:51,968 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - TABLES TABLE is created.
2015-09-11 04:23:51,976 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - IDX_TABLES_TID INDEX is created.
2015-09-11 04:23:51,982 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - IDX_TABLES_NAME INDEX is created.
2015-09-11 04:23:51,993 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - COLUMNS TABLE is created.
2015-09-11 04:23:52,000 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - IDX_FK_COLUMNS_TABLE_NAME INDEX is created.
2015-09-11 04:23:52,010 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - OPTIONS TABLE is created.
2015-09-11 04:23:52,017 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - IDX_OPTIONS_KEY INDEX is created.
2015-09-11 04:23:52,051 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - INDEXES TABLE is created.
2015-09-11 04:23:52,061 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - IDX_INDEXES_PK INDEX is created.
2015-09-11 04:23:52,069 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - IDX_INDEXES_COLUMNS INDEX is created.
2015-09-11 04:23:52,079 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - STATS TABLE is created.
2015-09-11 04:23:52,085 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - IDX_STATS_TABLE_NAME INDEX is created.
2015-09-11 04:23:52,094 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - PARTITION_METHODS TABLE is created.
2015-09-11 04:23:52,100 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - IDX_PARTITION_METHODS_TABLE_ID INDEX is created.
2015-09-11 04:23:52,113 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - PARTITIONS TABLE is created.
2015-09-11 04:23:52,121 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - PARTITIONS_IDX INDEX is created.
2015-09-11 04:23:52,131 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - PARTITION_KEYS TABLE is created.
2015-09-11 04:23:52,138 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - PARTITION_KEYS_IDX INDEX is created.
2015-09-11 04:23:52,141 INFO: org.apache.tajo.catalog.store.DerbyStore (<init>(179)) - The base tables of CatalogServer are created.
2015-09-11 04:23:52,141 INFO: org.apache.tajo.rpc.RpcChannelFactory (createServerChannelFactory(141)) - Create CatalogProtocol-29 ServerSocketChannelFactory. Worker:2
2015-09-11 04:23:52,142 INFO: org.apache.tajo.rpc.NettyServerBase (start(130)) - Rpc (CatalogProtocol) listens on /127.0.0.1:13899
2015-09-11 04:23:52,143 INFO: org.apache.tajo.catalog.CatalogServer (serviceStart(176)) - Catalog Server startup (127.0.0.1:13899)
2015-09-11 04:23:52,190 INFO: org.apache.tajo.catalog.CatalogServer (createTablespace(215)) - tablespace "default" (<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/TestHashJoinExec)> is created
2015-09-11 04:23:52,199 INFO: org.apache.tajo.catalog.CatalogServer (createDatabase(406)) - database "default" is created
2015-09-11 04:23:52,223 INFO: org.apache.tajo.catalog.CatalogServer (createTable(671)) - relation "default.employee" is added to the catalog (127.0.0.1:13899)
2015-09-11 04:23:52,232 INFO: org.apache.tajo.catalog.CatalogServer (createTable(671)) - relation "default.people" is added to the catalog (127.0.0.1:13899)
2015-09-11 04:23:52,572 INFO: org.apache.tajo.engine.planner.PhysicalPlannerImpl (createInnerJoinPlan(366)) - Join (2) chooses [In-memory Hash Join]
2015-09-11 04:23:52,572 INFO: org.apache.tajo.engine.planner.PhysicalPlannerImpl (switchJoinSidesIfNecessary(412)) - [ta_0000000000000_0025_000001_000019_00] Right relations default.p (75 B) is smaller than Left relations default.e (140 B).
2015-09-11 04:23:52,574 INFO: org.apache.tajo.catalog.CatalogServer (serviceStop(182)) - Catalog Server (127.0.0.1:13899) shutdown
2015-09-11 04:23:52,574 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (CatalogProtocol) listened on 127.0.0.1:13899) shutdown
2015-09-11 04:23:52,575 INFO: org.apache.tajo.catalog.store.DerbyStore (close(2783)) - Close database (jdbc:derby:memory:<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/aa3aa8db-e2b8-4e98-971e-37be22106acb/db;create=true)>
2015-09-11 04:23:52,594 INFO: org.apache.tajo.TajoTestingCluster (startCatalogCluster(296)) - Apache Derby repository is set to jdbc:derby:memory:<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/7f06a5a9-2c4b-4801-9cdb-8e744c0e2e21/db;create=true>
2015-09-11 04:23:52,594 INFO: org.apache.tajo.catalog.CatalogServer (serviceInit(125)) - Catalog Store Class: org.apache.tajo.catalog.store.DerbyStore
2015-09-11 04:23:52,594 INFO: org.apache.tajo.catalog.store.DerbyStore (<init>(150)) - Loaded the Catalog driver (org.apache.derby.jdbc.EmbeddedDriver)
2015-09-11 04:23:52,595 INFO: org.apache.tajo.catalog.store.DerbyStore (<init>(156)) - Trying to connect database (jdbc:derby:memory:<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/7f06a5a9-2c4b-4801-9cdb-8e744c0e2e21/db;create=true)>
2015-09-11 04:23:52,684 INFO: org.apache.tajo.catalog.store.DerbyStore (<init>(158)) - Connected to database (jdbc:derby:memory:<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/7f06a5a9-2c4b-4801-9cdb-8e744c0e2e21/db;create=true)>
2015-09-11 04:23:52,727 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - META TABLE is created.
2015-09-11 04:23:52,739 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - TABLESPACES TABLE is created.
2015-09-11 04:23:52,749 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - DATABASES_ TABLE is created.
2015-09-11 04:23:52,800 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - IDX_DATABASE_DB_ID INDEX is created.
2015-09-11 04:23:52,812 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - TABLES TABLE is created.
2015-09-11 04:23:52,822 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - IDX_TABLES_TID INDEX is created.
2015-09-11 04:23:52,829 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - IDX_TABLES_NAME INDEX is created.
2015-09-11 04:23:52,840 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - COLUMNS TABLE is created.
2015-09-11 04:23:52,847 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - IDX_FK_COLUMNS_TABLE_NAME INDEX is created.
2015-09-11 04:23:52,858 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - OPTIONS TABLE is created.
2015-09-11 04:23:52,867 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - IDX_OPTIONS_KEY INDEX is created.
2015-09-11 04:23:52,895 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - INDEXES TABLE is created.
2015-09-11 04:23:52,904 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - IDX_INDEXES_PK INDEX is created.
2015-09-11 04:23:52,914 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - IDX_INDEXES_COLUMNS INDEX is created.
2015-09-11 04:23:52,923 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - STATS TABLE is created.
2015-09-11 04:23:52,930 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - IDX_STATS_TABLE_NAME INDEX is created.
2015-09-11 04:23:52,939 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - PARTITION_METHODS TABLE is created.
2015-09-11 04:23:52,945 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - IDX_PARTITION_METHODS_TABLE_ID INDEX is created.
2015-09-11 04:23:52,959 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - PARTITIONS TABLE is created.
2015-09-11 04:23:52,967 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - PARTITIONS_IDX INDEX is created.
2015-09-11 04:23:52,976 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - PARTITION_KEYS TABLE is created.
2015-09-11 04:23:52,983 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - PARTITION_KEYS_IDX INDEX is created.
2015-09-11 04:23:52,985 INFO: org.apache.tajo.catalog.store.DerbyStore (<init>(179)) - The base tables of CatalogServer are created.
2015-09-11 04:23:52,985 INFO: org.apache.tajo.rpc.RpcChannelFactory (createServerChannelFactory(141)) - Create CatalogProtocol-30 ServerSocketChannelFactory. Worker:2
2015-09-11 04:23:52,987 INFO: org.apache.tajo.rpc.NettyServerBase (start(130)) - Rpc (CatalogProtocol) listens on /127.0.0.1:13900
2015-09-11 04:23:52,987 INFO: org.apache.tajo.catalog.CatalogServer (serviceStart(176)) - Catalog Server startup (127.0.0.1:13900)
2015-09-11 04:23:53,035 INFO: org.apache.tajo.catalog.CatalogServer (createTablespace(215)) - tablespace "default" (<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/TestHashJoinExec)> is created
2015-09-11 04:23:53,045 INFO: org.apache.tajo.catalog.CatalogServer (createDatabase(406)) - database "default" is created
2015-09-11 04:23:53,073 INFO: org.apache.tajo.catalog.CatalogServer (createTable(671)) - relation "default.employee" is added to the catalog (127.0.0.1:13900)
2015-09-11 04:23:53,083 INFO: org.apache.tajo.catalog.CatalogServer (createTable(671)) - relation "default.people" is added to the catalog (127.0.0.1:13900)
2015-09-11 04:23:53,420 INFO: org.apache.tajo.engine.planner.PhysicalPlannerImpl (createInnerJoinPlan(366)) - Join (2) chooses [In-memory Hash Join]
2015-09-11 04:23:53,420 INFO: org.apache.tajo.engine.planner.PhysicalPlannerImpl (switchJoinSidesIfNecessary(412)) - [ta_0000000000000_0026_000001_000020_01] Right relations default.p (75 B) is smaller than Left relations default.e (140 B).
2015-09-11 04:23:53,421 INFO: org.apache.tajo.engine.planner.PhysicalPlannerImpl (switchJoinSidesIfNecessary(412)) - [ta_0000000000000_0026_000001_000020_01] Right relations default.p (75 B) is smaller than Left relations default.e (140 B).
2015-09-11 04:23:53,421 INFO: org.apache.tajo.engine.planner.PhysicalPlannerImpl (checkIfInMemoryInnerJoinIsPossible(287)) - [ta_0000000000000_0026_000001_000020_01] the volume of Left relations (default.e) is 5.0 MiB and is not fit to main maemory.
2015-09-11 04:23:53,421 INFO: org.apache.tajo.engine.planner.PhysicalPlannerImpl (checkIfInMemoryInnerJoinIsPossible(287)) - [ta_0000000000000_0026_000001_000020_01] the volume of Right relations (default.p) is 1.0 MiB and is fit to main maemory.
2015-09-11 04:23:53,421 INFO: org.apache.tajo.catalog.CatalogServer (serviceStop(182)) - Catalog Server (127.0.0.1:13900) shutdown
2015-09-11 04:23:53,422 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (CatalogProtocol) listened on 127.0.0.1:13900) shutdown
2015-09-11 04:23:53,422 INFO: org.apache.tajo.catalog.store.DerbyStore (close(2783)) - Close database (jdbc:derby:memory:<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/7f06a5a9-2c4b-4801-9cdb-8e744c0e2e21/db;create=true)>
Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.704 sec - in org.apache.tajo.engine.planner.physical.TestHashJoinExec
Running org.apache.tajo.engine.planner.physical.TestTupleSorter
2015-09-11 04:23:53,483 INFO: org.apache.tajo.engine.planner.physical.TestTupleSorter (testSortBench(121)) - Sort on keys [3, 5]: Vectorized 7 msec, Original 38 msec
2015-09-11 04:23:53,497 INFO: org.apache.tajo.engine.planner.physical.TestTupleSorter (testSortBench(121)) - Sort on keys [1, 3]: Vectorized 3 msec, Original 9 msec
2015-09-11 04:23:53,510 INFO: org.apache.tajo.engine.planner.physical.TestTupleSorter (testSortBench(121)) - Sort on keys [2, 4]: Vectorized 5 msec, Original 8 msec
2015-09-11 04:23:53,521 INFO: org.apache.tajo.engine.planner.physical.TestTupleSorter (testSortBench(121)) - Sort on keys [5]: Vectorized 4 msec, Original 7 msec
2015-09-11 04:23:53,528 INFO: org.apache.tajo.engine.planner.physical.TestTupleSorter (testSortBench(121)) - Sort on keys [0, 2, 5]: Vectorized 2 msec, Original 5 msec
2015-09-11 04:23:53,534 INFO: org.apache.tajo.engine.planner.physical.TestTupleSorter (testSortBench(121)) - Sort on keys [0, 2]: Vectorized 2 msec, Original 4 msec
2015-09-11 04:23:53,541 INFO: org.apache.tajo.engine.planner.physical.TestTupleSorter (testSortBench(121)) - Sort on keys [0, 2, 5]: Vectorized 3 msec, Original 4 msec
2015-09-11 04:23:53,547 INFO: org.apache.tajo.engine.planner.physical.TestTupleSorter (testSortBench(121)) - Sort on keys [1, 3, 5]: Vectorized 2 msec, Original 4 msec
2015-09-11 04:23:53,554 INFO: org.apache.tajo.engine.planner.physical.TestTupleSorter (testSortBench(121)) - Sort on keys [1, 2, 5]: Vectorized 3 msec, Original 4 msec
2015-09-11 04:23:53,560 INFO: org.apache.tajo.engine.planner.physical.TestTupleSorter (testSortBench(121)) - Sort on keys [1, 3]: Vectorized 2 msec, Original 4 msec
Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.136 sec - in org.apache.tajo.engine.planner.physical.TestTupleSorter
Running org.apache.tajo.engine.planner.physical.TestRightOuterHashJoinExec
2015-09-11 04:23:53,581 INFO: org.apache.tajo.TajoTestingCluster (startCatalogCluster(296)) - Apache Derby repository is set to jdbc:derby:memory:<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/0e0e6dbf-b1fe-4b02-af31-ea4f8d6d9f25/db;create=true>
2015-09-11 04:23:53,581 INFO: org.apache.tajo.catalog.CatalogServer (serviceInit(125)) - Catalog Store Class: org.apache.tajo.catalog.store.DerbyStore
2015-09-11 04:23:53,581 INFO: org.apache.tajo.catalog.store.DerbyStore (<init>(150)) - Loaded the Catalog driver (org.apache.derby.jdbc.EmbeddedDriver)
2015-09-11 04:23:53,581 INFO: org.apache.tajo.catalog.store.DerbyStore (<init>(156)) - Trying to connect database (jdbc:derby:memory:<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/0e0e6dbf-b1fe-4b02-af31-ea4f8d6d9f25/db;create=true)>
2015-09-11 04:23:53,668 INFO: org.apache.tajo.catalog.store.DerbyStore (<init>(158)) - Connected to database (jdbc:derby:memory:<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/0e0e6dbf-b1fe-4b02-af31-ea4f8d6d9f25/db;create=true)>
2015-09-11 04:23:53,710 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - META TABLE is created.
2015-09-11 04:23:53,720 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - TABLESPACES TABLE is created.
2015-09-11 04:23:53,730 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - DATABASES_ TABLE is created.
2015-09-11 04:23:53,777 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - IDX_DATABASE_DB_ID INDEX is created.
2015-09-11 04:23:53,788 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - TABLES TABLE is created.
2015-09-11 04:23:53,794 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - IDX_TABLES_TID INDEX is created.
2015-09-11 04:23:53,800 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - IDX_TABLES_NAME INDEX is created.
2015-09-11 04:23:53,811 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - COLUMNS TABLE is created.
2015-09-11 04:23:53,821 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - IDX_FK_COLUMNS_TABLE_NAME INDEX is created.
2015-09-11 04:23:53,833 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - OPTIONS TABLE is created.
2015-09-11 04:23:53,843 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - IDX_OPTIONS_KEY INDEX is created.
2015-09-11 04:23:53,872 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - INDEXES TABLE is created.
2015-09-11 04:23:53,884 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - IDX_INDEXES_PK INDEX is created.
2015-09-11 04:23:53,896 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - IDX_INDEXES_COLUMNS INDEX is created.
2015-09-11 04:23:53,908 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - STATS TABLE is created.
2015-09-11 04:23:53,916 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - IDX_STATS_TABLE_NAME INDEX is created.
2015-09-11 04:23:53,924 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - PARTITION_METHODS TABLE is created.
2015-09-11 04:23:53,930 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - IDX_PARTITION_METHODS_TABLE_ID INDEX is created.
2015-09-11 04:23:53,946 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - PARTITIONS TABLE is created.
2015-09-11 04:23:53,954 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - PARTITIONS_IDX INDEX is created.
2015-09-11 04:23:53,963 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - PARTITION_KEYS TABLE is created.
2015-09-11 04:23:53,969 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - PARTITION_KEYS_IDX INDEX is created.
2015-09-11 04:23:53,972 INFO: org.apache.tajo.catalog.store.DerbyStore (<init>(179)) - The base tables of CatalogServer are created.
2015-09-11 04:23:53,972 INFO: org.apache.tajo.rpc.RpcChannelFactory (createServerChannelFactory(141)) - Create CatalogProtocol-31 ServerSocketChannelFactory. Worker:2
2015-09-11 04:23:53,973 INFO: org.apache.tajo.rpc.NettyServerBase (start(130)) - Rpc (CatalogProtocol) listens on /127.0.0.1:13901
2015-09-11 04:23:53,974 INFO: org.apache.tajo.catalog.CatalogServer (serviceStart(176)) - Catalog Server startup (127.0.0.1:13901)
2015-09-11 04:23:54,017 INFO: org.apache.tajo.catalog.CatalogServer (createTablespace(215)) - tablespace "default" (<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/TestRightOuterHashJoinExec)> is created
2015-09-11 04:23:54,024 INFO: org.apache.tajo.catalog.CatalogServer (createDatabase(406)) - database "default" is created
2015-09-11 04:23:54,053 INFO: org.apache.tajo.catalog.CatalogServer (createTable(671)) - relation "default.dep3" is added to the catalog (127.0.0.1:13901)
2015-09-11 04:23:54,062 INFO: org.apache.tajo.catalog.CatalogServer (createTable(671)) - relation "default.job3" is added to the catalog (127.0.0.1:13901)
2015-09-11 04:23:54,072 INFO: org.apache.tajo.catalog.CatalogServer (createTable(671)) - relation "default.emp3" is added to the catalog (127.0.0.1:13901)
2015-09-11 04:23:54,478 INFO: org.apache.tajo.engine.planner.PhysicalPlannerImpl (createBestRightJoinPlan(529)) - Right Outer Join (2) chooses [Hash Join].
2015-09-11 04:23:54,480 INFO: org.apache.tajo.catalog.CatalogServer (serviceStop(182)) - Catalog Server (127.0.0.1:13901) shutdown
2015-09-11 04:23:54,481 INFO: org.apache.tajo.rpc.NettyServerBase (shutdown(173)) - Rpc (CatalogProtocol) listened on 127.0.0.1:13901) shutdown
2015-09-11 04:23:54,481 INFO: org.apache.tajo.catalog.store.DerbyStore (close(2783)) - Close database (jdbc:derby:memory:<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/0e0e6dbf-b1fe-4b02-af31-ea4f8d6d9f25/db;create=true)>
2015-09-11 04:23:54,508 INFO: org.apache.tajo.TajoTestingCluster (startCatalogCluster(296)) - Apache Derby repository is set to jdbc:derby:memory:<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/9cbfdd19-548e-4c43-8d7b-e159d490bfa6/db;create=true>
2015-09-11 04:23:54,508 INFO: org.apache.tajo.catalog.CatalogServer (serviceInit(125)) - Catalog Store Class: org.apache.tajo.catalog.store.DerbyStore
2015-09-11 04:23:54,508 INFO: org.apache.tajo.catalog.store.DerbyStore (<init>(150)) - Loaded the Catalog driver (org.apache.derby.jdbc.EmbeddedDriver)
2015-09-11 04:23:54,508 INFO: org.apache.tajo.catalog.store.DerbyStore (<init>(156)) - Trying to connect database (jdbc:derby:memory:<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/9cbfdd19-548e-4c43-8d7b-e159d490bfa6/db;create=true)>
2015-09-11 04:23:54,628 INFO: org.apache.tajo.catalog.store.DerbyStore (<init>(158)) - Connected to database (jdbc:derby:memory:<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/9cbfdd19-548e-4c43-8d7b-e159d490bfa6/db;create=true)>
2015-09-11 04:23:54,680 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - META TABLE is created.
2015-09-11 04:23:54,690 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - TABLESPACES TABLE is created.
2015-09-11 04:23:54,701 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - DATABASES_ TABLE is created.
2015-09-11 04:23:54,750 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - IDX_DATABASE_DB_ID INDEX is created.
2015-09-11 04:23:54,762 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - TABLES TABLE is created.
2015-09-11 04:23:54,769 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - IDX_TABLES_TID INDEX is created.
2015-09-11 04:23:54,776 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - IDX_TABLES_NAME INDEX is created.
2015-09-11 04:23:54,788 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - COLUMNS TABLE is created.
2015-09-11 04:23:54,794 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - IDX_FK_COLUMNS_TABLE_NAME INDEX is created.
2015-09-11 04:23:54,803 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - OPTIONS TABLE is created.
2015-09-11 04:23:54,809 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - IDX_OPTIONS_KEY INDEX is created.
2015-09-11 04:23:54,832 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - INDEXES TABLE is created.
2015-09-11 04:23:54,840 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - IDX_INDEXES_PK INDEX is created.
2015-09-11 04:23:54,848 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - IDX_INDEXES_COLUMNS INDEX is created.
2015-09-11 04:23:54,856 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - STATS TABLE is created.
2015-09-11 04:23:54,862 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - IDX_STATS_TABLE_NAME INDEX is created.
2015-09-11 04:23:54,870 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - PARTITION_METHODS TABLE is created.
2015-09-11 04:23:54,875 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - IDX_PARTITION_METHODS_TABLE_ID INDEX is created.
2015-09-11 04:23:54,889 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - PARTITIONS TABLE is created.
2015-09-11 04:23:54,896 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - PARTITIONS_IDX INDEX is created.
2015-09-11 04:23:54,904 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - PARTITION_KEYS TABLE is created.
2015-09-11 04:23:54,911 INFO: org.apache.tajo.catalog.store.XMLCatalogSchemaManager (createBaseSchema(292)) - PARTITION_KEYS_IDX INDEX is created.
2015-09-11 04:23:54,913 INFO: org.apache.tajo.catalog.store.DerbyStore (<init>(179)) - The base tables of CatalogServer are created.
2015-09-11 04:23:54,913 INFO: org.apache.tajo.rpc.RpcChannelFactory (createServerChannelFactory(141)) - Create CatalogProtocol-32 ServerSocketChannelFactory. Worker:2
2015-09-11 04:23:54,915 INFO: org.apache.tajo.rpc.NettyServerBase (start(130)) - Rpc (CatalogProtocol) listens on /127.0.0.1:13902
2015-09-11 04:23:54,915 INFO: org.apache.tajo.catalog.CatalogServer (serviceStart(176)) - Catalog Server startup (127.0.0.1:13902)
2015-09-11 04:23:54,960 INFO: org.apache.tajo.catalog.CatalogServer (createTablespace(215)) - tablespace "default" (<https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/test-data/TestRightOuterHashJoinExec)> is created
2015-09-11 04:23:54,969 INFO: org.apache.tajo.catalog.CatalogServer (createDatabase(406)) - database "default" is created
2015-09-11 04:23:54,996 INFO: org.apache.tajo.catalog.CatalogServer (createTable(671)) - relation "default.dep3" is added to the catalog (127.0.0.1:13902)
2015-09-11 04:23:55,005 INFO: org.apache.tajo.catalog.CatalogServer (createTable(671)) - relation "default.job3" is added to the catalog (127.0.0.1:13902)
2015-09-11 04:23:55,014 INFO: org.apache.tajo.catalog.CatalogServer (createTable(671)) - relation "default.emp3" is added to the catalog (127.0.0.1:13902)
Killed
Results :
Tests run: 1314, Failures: 0, Errors: 0, Skipped: 0
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] Tajo Main ......................................... SUCCESS [ 1.877 s]
[INFO] Tajo Project POM .................................. SUCCESS [ 1.638 s]
[INFO] Tajo Maven Plugins ................................ SUCCESS [ 3.195 s]
[INFO] Tajo Common ....................................... SUCCESS [ 40.041 s]
[INFO] Tajo Algebra ...................................... SUCCESS [ 2.878 s]
[INFO] Tajo Catalog Common ............................... SUCCESS [ 4.930 s]
[INFO] Tajo Plan ......................................... SUCCESS [ 7.156 s]
[INFO] Tajo Rpc Common ................................... SUCCESS [ 1.390 s]
[INFO] Tajo Protocol Buffer Rpc .......................... SUCCESS [ 48.321 s]
[INFO] Tajo Catalog Client ............................... SUCCESS [ 1.379 s]
[INFO] Tajo Catalog Server ............................... SUCCESS [ 11.062 s]
[INFO] Tajo Storage Common ............................... SUCCESS [ 3.286 s]
[INFO] Tajo HDFS Storage ................................. SUCCESS [ 54.329 s]
[INFO] Tajo PullServer ................................... SUCCESS [ 1.135 s]
[INFO] Tajo Client ....................................... SUCCESS [ 2.577 s]
[INFO] Tajo CLI tools .................................... SUCCESS [ 2.341 s]
[INFO] Tajo SQL Parser ................................... SUCCESS [ 5.236 s]
[INFO] ASM (thirdparty) .................................. SUCCESS [ 1.844 s]
[INFO] Tajo RESTful Container ............................ SUCCESS [ 4.769 s]
[INFO] Tajo Metrics ...................................... SUCCESS [ 1.377 s]
[INFO] Tajo Core ......................................... SUCCESS [ 6.356 s]
[INFO] Tajo RPC .......................................... SUCCESS [ 0.951 s]
[INFO] Tajo Catalog Drivers Hive ......................... SUCCESS [ 13.328 s]
[INFO] Tajo Catalog Drivers .............................. SUCCESS [ 0.083 s]
[INFO] Tajo Catalog ...................................... SUCCESS [ 0.984 s]
[INFO] Tajo Client Example ............................... SUCCESS [ 1.096 s]
[INFO] Tajo HBase Storage ................................ SUCCESS [ 4.865 s]
[INFO] Tajo Cluster Tests ................................ SUCCESS [ 2.915 s]
[INFO] Tajo JDBC Driver .................................. SUCCESS [ 33.651 s]
[INFO] Tajo Storage ...................................... SUCCESS [ 1.084 s]
[INFO] Tajo Distribution ................................. SUCCESS [ 5.495 s]
[INFO] Tajo Core Tests ................................... FAILURE [21:24 min]
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 25:57 min
[INFO] Finished at: 2015-09-11T04:23:55+00:00
[INFO] Final Memory: 78M/414M
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal org.apache.maven.plugins:maven-surefire-plugin:2.17:test (default-test) on project tajo-core-tests: 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-core-tests> && /home/jenkins/tools/java/jdk1.7.0_25-32/jre/bin/java -Xms512m -Xmx1024m -XX:MaxPermSize=152m -Dfile.encoding=UTF-8 -Dderby.storage.pageSize=1024 -Dderby.stream.error.file=/dev/null -jar <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/surefire/surefirebooter640452054730047474.jar> <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/surefire/surefire6857899286670000018tmp> <https://builds.apache.org/job/Tajo-master-build/ws/tajo-core-tests/target/surefire/surefire_148675436753608707036tmp>
[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-core-tests
Build step 'Execute shell' marked build as failure
Updating TAJO-1739