See <https://builds.apache.org/job/Tajo-master-jdk8-nightly/211/changes>
Changes:
[jihoonson] TAJO-1904: Extend GraphVisitor to accept user-defined context.
[hyunsik] TAJO-1955: Add a feature to strip quotes from CSV file.
------------------------------------------
[...truncated 183799 lines...]
Optimized master plan
-------------------------------------------------------------------------------
Execution Block Graph (TERMINAL - eb_1446522253707_0543_000003)
-------------------------------------------------------------------------------
|-eb_1446522253707_0543_000003
|-eb_1446522253707_0543_000002
|-eb_1446522253707_0543_000001
-------------------------------------------------------------------------------
Order of Execution
-------------------------------------------------------------------------------
1: eb_1446522253707_0543_000001
2: eb_1446522253707_0543_000002
3: eb_1446522253707_0543_000003
-------------------------------------------------------------------------------
=======================================================
Block Id: eb_1446522253707_0543_000001 [LEAF]
=======================================================
[Outgoing]
[q_1446522253707_0543] 1 => 2 (type=RANGE_SHUFFLE, key=?cast (TEXT), ?cast_1
(TEXT), num=32)
SORT(6)
=> Sort Keys: ?cast (TEXT) (asc),?cast_1 (TEXT) (asc)
SCAN(0) on default.lineitem
=> target list: CAST (default.lineitem.l_orderkey (INT4) AS TEXT) as
?cast, CAST (default.lineitem.l_partkey (INT4) AS TEXT) as ?cast_1,
default.lineitem.l_shipdate (TEXT), default.lineitem.l_returnflag (TEXT), CAST
(default.lineitem.l_suppkey (INT4) AS TEXT) as ?cast_2
=> out schema: {(5) ?cast (TEXT), ?cast_1 (TEXT),
default.lineitem.l_shipdate (TEXT), default.lineitem.l_returnflag (TEXT),
?cast_2 (TEXT)}
=> in schema: {(16) default.lineitem.l_orderkey (INT4),
default.lineitem.l_partkey (INT4), default.lineitem.l_suppkey (INT4),
default.lineitem.l_linenumber (INT4), default.lineitem.l_quantity (FLOAT8),
default.lineitem.l_extendedprice (FLOAT8), default.lineitem.l_discount
(FLOAT8), default.lineitem.l_tax (FLOAT8), default.lineitem.l_returnflag
(TEXT), default.lineitem.l_linestatus (TEXT), default.lineitem.l_shipdate
(TEXT), default.lineitem.l_commitdate (TEXT), default.lineitem.l_receiptdate
(TEXT), default.lineitem.l_shipinstruct (TEXT), default.lineitem.l_shipmode
(TEXT), default.lineitem.l_comment (TEXT)}
=======================================================
Block Id: eb_1446522253707_0543_000002 [ROOT]
=======================================================
[Incoming]
[q_1446522253707_0543] 1 => 2 (type=RANGE_SHUFFLE, key=?cast (TEXT), ?cast_1
(TEXT), num=32)
[Enforcers]
0: sorted input=eb_1446522253707_0543_000001
INSERT(2) INTO TestHBaseTable.hbase_mapped_table
=> {(5) TestHBaseTable.hbase_mapped_table.rk1 (TEXT),
TestHBaseTable.hbase_mapped_table.rk2 (TEXT),
TestHBaseTable.hbase_mapped_table.col1 (TEXT),
TestHBaseTable.hbase_mapped_table.col2 (TEXT),
TestHBaseTable.hbase_mapped_table.col3 (TEXT)}
SORT(4)
=> Sort Keys: ?cast (TEXT) (asc),?cast_1 (TEXT) (asc)
SCAN(7) on eb_1446522253707_0543_000001
=> out schema: {(5) ?cast (TEXT), ?cast_1 (TEXT),
default.lineitem.l_shipdate (TEXT), default.lineitem.l_returnflag (TEXT),
?cast_2 (TEXT)}
=> in schema: {(5) ?cast (TEXT), ?cast_1 (TEXT),
default.lineitem.l_shipdate (TEXT), default.lineitem.l_returnflag (TEXT),
?cast_2 (TEXT)}
=======================================================
Block Id: eb_1446522253707_0543_000003 [TERMINAL]
=======================================================
2015-11-03 03:53:50,415 INFO: org.apache.tajo.querymaster.Query (<init>(234)) -
=======================================================
The order of execution:
1: eb_1446522253707_0543_000001
2: eb_1446522253707_0543_000002
3: eb_1446522253707_0543_000003
=======================================================
2015-11-03 03:53:50,416 INFO: org.apache.tajo.querymaster.Query (handle(868)) -
Processing q_1446522253707_0543 of type START
2015-11-03 03:53:50,416 INFO:
org.apache.tajo.engine.planner.global.ParallelExecutionQueue (first(84)) -
Initial executable blocks [eb_1446522253707_0543_000001]
2015-11-03 03:53:50,416 INFO: org.apache.tajo.querymaster.Query (handle(886)) -
q_1446522253707_0543 Query Transitioned from QUERY_NEW to QUERY_RUNNING
2015-11-03 03:53:50,416 INFO: org.apache.tajo.querymaster.Stage
(calculateShuffleOutputNum(1026)) - ============>>>>> Unexpected Case!
<<<<<================
2015-11-03 03:53:50,417 INFO: org.apache.tajo.querymaster.Stage
(calculateShuffleOutputNum(1030)) - eb_1446522253707_0543_000001, Table's
volume is approximately 1 MB
2015-11-03 03:53:50,417 INFO: org.apache.tajo.querymaster.Stage
(calculateShuffleOutputNum(1033)) - eb_1446522253707_0543_000001, The
determined number of partitions is 1
2015-11-03 03:53:50,437 INFO: org.apache.tajo.querymaster.Stage
(initTaskScheduler(901)) - org.apache.tajo.querymaster.DefaultTaskScheduler is
chosen for the task scheduling for eb_1446522253707_0543_000001
2015-11-03 03:53:50,439 INFO: org.apache.tajo.storage.FileTablespace
(listStatus(363)) - Total input paths to process : 1
2015-11-03 03:53:50,441 INFO: org.apache.tajo.storage.FileTablespace
(getSplits(564)) - Total # of splits: 1
2015-11-03 03:53:50,441 INFO: org.apache.tajo.querymaster.Stage (run(853)) - 1
objects are scheduled
2015-11-03 03:53:50,441 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(start(134)) - Start TaskScheduler
2015-11-03 03:53:50,442 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(increaseConcurrency(525)) - Assigned host : 127.0.0.1, Unknown Volume : -1,
Concurrency : 1
2015-11-03 03:53:50,444 INFO: org.apache.tajo.worker.TaskManager
(createExecutionBlock(114)) - QueryMaster Address:asf906.gq1.ygridcore.net:24448
2015-11-03 03:53:50,444 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(assignToLeafTasks(922)) - Assigned Local/Rack/Total: (0/1/1), Attempted
Cancel/Assign/Total: (0/1/1), Locality: 0.00%, Rack host:
asf906.gq1.ygridcore.net
2015-11-03 03:53:50,446 INFO: org.apache.tajo.worker.ExecutionBlockContext
(init(119)) - Tajo Root Dir: hdfs://localhost:52738/tajo
2015-11-03 03:53:50,446 INFO: org.apache.tajo.worker.ExecutionBlockContext
(init(120)) - Worker Local Dir:
file://<https://builds.apache.org/job/Tajo-master-jdk8-nightly/ws/tajo-core-tests/target/test-data/1ca48b08-80d9-4cde-8aa7-d5ad12171ddc/tajo-localdir>
2015-11-03 03:53:50,448 INFO: org.apache.tajo.worker.TaskManager (handle(184))
- Running ExecutionBlocks: 1, running tasks:0, availableResource: (Memory:1000,
Disks:2, vCores:2)
2015-11-03 03:53:50,448 INFO: org.apache.tajo.worker.TaskImpl (<init>(116)) -
Task basedir is created
(<https://builds.apache.org/job/Tajo-master-jdk8-nightly/ws/tajo-core-tests/target/test-data/1ca48b08-80d9-4cde-8aa7-d5ad12171ddc/tajo-localdir/q_1446522253707_0543/output/1)>
2015-11-03 03:53:50,449 INFO: org.apache.tajo.worker.TaskImpl (init(197)) -
Initializing: ta_1446522253707_0543_000001_000000_00
2015-11-03 03:53:50,449 INFO: org.apache.tajo.worker.TaskImpl (initPlan(152)) -
==================================
2015-11-03 03:53:50,449 INFO: org.apache.tajo.worker.TaskImpl (initPlan(153)) -
* Stage ta_1446522253707_0543_000001_000000_00 is initialized
2015-11-03 03:53:50,449 INFO: org.apache.tajo.worker.TaskImpl (initPlan(154)) -
* InterQuery: true, Use RANGE_SHUFFLE shuffle, Fragments (num: 1), Fetches
(total:0) :
2015-11-03 03:53:50,449 INFO: org.apache.tajo.worker.TaskImpl (initPlan(164)) -
* Local task dir:
<https://builds.apache.org/job/Tajo-master-jdk8-nightly/ws/tajo-core-tests/target/test-data/1ca48b08-80d9-4cde-8aa7-d5ad12171ddc/tajo-localdir/q_1446522253707_0543/output/1/0_0>
2015-11-03 03:53:50,449 INFO: org.apache.tajo.worker.TaskImpl (initPlan(169)) -
==================================
2015-11-03 03:53:50,450 INFO:
org.apache.tajo.engine.planner.physical.RangeShuffleFileWriteExec (init(80)) -
Output data directory:
<https://builds.apache.org/job/Tajo-master-jdk8-nightly/ws/tajo-core-tests/target/test-data/1ca48b08-80d9-4cde-8aa7-d5ad12171ddc/tajo-localdir/q_1446522253707_0543/output/1/0_0/output>
2015-11-03 03:53:50,476 INFO:
org.apache.tajo.engine.planner.physical.ExternalSortExec (info(70)) -
[ta_1446522253707_0543_000001_000000_00] Chunks creation time: 1 msec
2015-11-03 03:53:50,482 INFO: org.apache.tajo.worker.TaskImpl (run(462)) -
ta_1446522253707_0543_000001_000000_00 completed. Worker's task counter -
total:1, succeeded: 1, killed: 0, failed: 0
2015-11-03 03:53:50,483 INFO: org.apache.tajo.querymaster.Stage
(transition(1349)) - Stage - eb_1446522253707_0543_000001 finalize
RANGE_SHUFFLE (total=1, success=1, killed=0)
2015-11-03 03:53:50,483 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(stop(163)) - Task Scheduler stopped
2015-11-03 03:53:50,483 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(run(126)) - TaskScheduler schedulingThread stopped
2015-11-03 03:53:50,483 INFO: org.apache.tajo.querymaster.Stage
(transition(1407)) - Stage completed - eb_1446522253707_0543_000001 (total=1,
success=1, killed=0)
2015-11-03 03:53:50,483 INFO: org.apache.tajo.querymaster.Query (handle(868)) -
Processing q_1446522253707_0543 of type STAGE_COMPLETED
2015-11-03 03:53:50,483 INFO:
org.apache.tajo.engine.planner.global.ParallelExecutionQueue (next(95)) - Next
executable block eb_1446522253707_0543_000002
2015-11-03 03:53:50,484 INFO: org.apache.tajo.querymaster.Query
(executeNextBlock(770)) - Scheduling Stage:eb_1446522253707_0543_000002
2015-11-03 03:53:50,484 INFO: org.apache.tajo.worker.TaskManager
(stopExecutionBlock(163)) - Stopped execution block:eb_1446522253707_0543_000001
2015-11-03 03:53:50,501 INFO: org.apache.tajo.querymaster.Stage
(initTaskScheduler(901)) - org.apache.tajo.querymaster.DefaultTaskScheduler is
chosen for the task scheduling for eb_1446522253707_0543_000002
2015-11-03 03:53:50,501 INFO: org.apache.tajo.querymaster.Stage
(getNonLeafTaskNum(1095)) - eb_1446522253707_0543_000002, Table's volume is
approximately 1 MB
2015-11-03 03:53:50,501 INFO: org.apache.tajo.querymaster.Stage
(getNonLeafTaskNum(1100)) - eb_1446522253707_0543_000002, The determined number
of non-leaf tasks is 1
2015-11-03 03:53:50,510 INFO: org.apache.tajo.querymaster.Stage (run(853)) - 1
objects are scheduled
2015-11-03 03:53:50,510 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(start(134)) - Start TaskScheduler
2015-11-03 03:53:50,513 INFO: org.apache.tajo.worker.TaskManager
(createExecutionBlock(114)) - QueryMaster Address:asf906.gq1.ygridcore.net:24448
2015-11-03 03:53:50,515 INFO: org.apache.tajo.worker.ExecutionBlockContext
(init(119)) - Tajo Root Dir: hdfs://localhost:52738/tajo
2015-11-03 03:53:50,515 INFO: org.apache.tajo.worker.ExecutionBlockContext
(init(120)) - Worker Local Dir:
file://<https://builds.apache.org/job/Tajo-master-jdk8-nightly/ws/tajo-core-tests/target/test-data/1ca48b08-80d9-4cde-8aa7-d5ad12171ddc/tajo-localdir>
2015-11-03 03:53:50,516 INFO: org.apache.tajo.worker.TaskManager (handle(184))
- Running ExecutionBlocks: 1, running tasks:0, availableResource: (Memory:1000,
Disks:3, vCores:2)
2015-11-03 03:53:50,516 INFO: org.apache.tajo.worker.TaskImpl (<init>(116)) -
Task basedir is created
(<https://builds.apache.org/job/Tajo-master-jdk8-nightly/ws/tajo-core-tests/target/test-data/1ca48b08-80d9-4cde-8aa7-d5ad12171ddc/tajo-localdir/q_1446522253707_0543/output/2)>
2015-11-03 03:53:50,517 INFO: org.apache.tajo.worker.TaskImpl (init(197)) -
Initializing: ta_1446522253707_0543_000002_000000_00
2015-11-03 03:53:50,517 INFO: org.apache.tajo.storage.FileTablespace
(getAppenderFilePath(289)) - Output File Path:
hdfs://localhost:52738/tmp/tajo-jenkins/staging/q_1446522253707_0543/RESULT/part-02-000000-000
2015-11-03 03:53:50,517 INFO: org.apache.tajo.worker.TaskImpl (initPlan(147)) -
Output File Path:
hdfs://localhost:52738/tmp/tajo-jenkins/staging/q_1446522253707_0543/RESULT/part-02-000000-000
2015-11-03 03:53:50,517 INFO: org.apache.tajo.worker.TaskImpl (initPlan(152)) -
==================================
2015-11-03 03:53:50,517 INFO: org.apache.tajo.worker.TaskImpl (initPlan(153)) -
* Stage ta_1446522253707_0543_000002_000000_00 is initialized
2015-11-03 03:53:50,517 INFO: org.apache.tajo.worker.TaskImpl (initPlan(154)) -
* InterQuery: false, Fragments (num: 1), Fetches (total:1) :
2015-11-03 03:53:50,517 INFO: org.apache.tajo.worker.TaskImpl (initPlan(164)) -
* Local task dir:
<https://builds.apache.org/job/Tajo-master-jdk8-nightly/ws/tajo-core-tests/target/test-data/1ca48b08-80d9-4cde-8aa7-d5ad12171ddc/tajo-localdir/q_1446522253707_0543/output/2/0_0>
2015-11-03 03:53:50,517 INFO: org.apache.tajo.worker.TaskImpl (initPlan(169)) -
==================================
2015-11-03 03:53:50,518 INFO: org.apache.tajo.worker.TaskImpl (init(216)) - the
directory is created
<https://builds.apache.org/job/Tajo-master-jdk8-nightly/ws/tajo-core-tests/target/test-data/1ca48b08-80d9-4cde-8aa7-d5ad12171ddc/tajo-localdir/q_1446522253707_0543/in/eb_1446522253707_0543_000002/0/0/eb_1446522253707_0543_000001>
2015-11-03 03:53:50,537 INFO: org.apache.tajo.pullserver.TajoPullServerService
(getFileChunks(688)) - GET Request for
<https://builds.apache.org/job/Tajo-master-jdk8-nightly/ws/tajo-core-tests/target/test-data/1ca48b08-80d9-4cde-8aa7-d5ad12171ddc/tajo-localdir/q_1446522253707_0543/output/1/0_0/output/output>
(start=(1,1), end=(3,3), last=true)
2015-11-03 03:53:50,537 INFO: org.apache.tajo.worker.TaskImpl
(getFetchRunners(725)) - Create shuffle Fetchers local:1, remote:0
2015-11-03 03:53:50,537 INFO: org.apache.tajo.worker.TaskImpl (run(617)) - Add
a new FileChunk to local chunk list
2015-11-03 03:53:50,537 INFO: org.apache.tajo.worker.TaskImpl
(waitForFetch(364)) - ta_1446522253707_0543_000002_000000_00 All fetches are
done!
2015-11-03 03:53:50,554 INFO: org.apache.tajo.storage.hbase.HFileAppender
(init(84)) - Created hbase file writer:
hdfs://localhost:52738/tmp/tajo-jenkins/staging/q_1446522253707_0543/RESULT/_temporary/0/_temporary/attempt_q_1446522253707_0543_0002_m_000000_0
2015-11-03 03:53:50,554 INFO:
org.apache.tajo.engine.planner.physical.ExternalSortExec (info(70)) -
[ta_1446522253707_0543_000002_000000_00] Total merge time: 0 msec
2015-11-03 03:53:50,602 INFO:
org.apache.tajo.engine.planner.physical.ExternalSortExec (close(791)) - Delete
file: "fragment": {"id": "eb_1446522253707_0543_000001", "path":
<https://builds.apache.org/job/Tajo-master-jdk8-nightly/ws/tajo-core-tests/target/test-data/1ca48b08-80d9-4cde-8aa7-d5ad12171ddc/tajo-localdir/q_1446522253707_0543/output/1/0_0/output/output";,>
"start": 0,"length": 145}
2015-11-03 03:53:50,607 INFO: BlockStateChange (logAddStoredBlock(2624)) -
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:42123 is added to
blk_1073743765_2941{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-977e3b7d-37ac-43c8-80ce-bfc4f7d8107b:NORMAL:127.0.0.1:42123|RBW]]}
size 0
2015-11-03 03:53:50,610 INFO: BlockStateChange (logAddStoredBlock(2624)) -
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:42123 is added to
blk_1073743766_2942{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-a37d40ca-ac73-43dc-9f3a-b8b59e2e01b5:NORMAL:127.0.0.1:42123|RBW]]}
size 0
2015-11-03 03:53:50,615 INFO: BlockStateChange (logAddStoredBlock(2624)) -
BLOCK* addStoredBlock: blockMap updated: 127.0.0.1:42123 is added to
blk_1073743767_2943{UCState=UNDER_CONSTRUCTION, truncateBlock=null,
primaryNodeIndex=-1,
replicas=[ReplicaUC[[DISK]DS-977e3b7d-37ac-43c8-80ce-bfc4f7d8107b:NORMAL:127.0.0.1:42123|FINALIZED]]}
size 0
2015-11-03 03:53:50,618 INFO: org.apache.tajo.worker.TaskImpl (run(462)) -
ta_1446522253707_0543_000002_000000_00 completed. Worker's task counter -
total:1, succeeded: 1, killed: 0, failed: 0
2015-11-03 03:53:50,619 INFO: org.apache.tajo.querymaster.Stage
(transition(1349)) - Stage - eb_1446522253707_0543_000002 finalize NONE_SHUFFLE
(total=1, success=1, killed=0)
2015-11-03 03:53:50,619 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(stop(163)) - Task Scheduler stopped
2015-11-03 03:53:50,619 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler
(run(126)) - TaskScheduler schedulingThread stopped
2015-11-03 03:53:50,619 INFO: org.apache.tajo.querymaster.Stage
(transition(1407)) - Stage completed - eb_1446522253707_0543_000002 (total=1,
success=1, killed=0)
2015-11-03 03:53:50,619 INFO: org.apache.tajo.querymaster.Query (handle(868)) -
Processing q_1446522253707_0543 of type STAGE_COMPLETED
2015-11-03 03:53:50,619 INFO:
org.apache.tajo.engine.planner.global.ParallelExecutionQueue (next(95)) - Next
executable block eb_1446522253707_0543_000003
2015-11-03 03:53:50,619 INFO: org.apache.tajo.querymaster.Query
(transition(815)) - Complete Stage[eb_1446522253707_0543_000002], State:
SUCCEEDED, 2/2.
2015-11-03 03:53:50,619 INFO: org.apache.tajo.querymaster.Query (handle(868)) -
Processing q_1446522253707_0543 of type QUERY_COMPLETED
2015-11-03 03:53:50,619 INFO: org.apache.tajo.worker.TaskManager
(stopExecutionBlock(163)) - Stopped execution block:eb_1446522253707_0543_000002
2015-11-03 03:53:50,675 WARN:
org.apache.hadoop.hbase.mapreduce.LoadIncrementalHFiles (doBulkLoad(300)) -
managed connection cannot be used for bulkload. Creating unmanaged connection.
2015-11-03 03:53:50,675 INFO: org.apache.zookeeper.ZooKeeper (<init>(438)) -
Initiating client connection, connectString=asf906.gq1.ygridcore.net:63630
sessionTimeout=90000 watcher=hconnection-0x17f4de0a0x0,
quorum=asf906.gq1.ygridcore.net:63630, baseZNode=/hbase
2015-11-03 03:53:50,678 INFO: org.apache.zookeeper.ClientCnxn
(logStartConnect(975)) - Opening socket connection to server
asf906.gq1.ygridcore.net/67.195.81.150:63630. Will not attempt to authenticate
using SASL (unknown error)
2015-11-03 03:53:50,679 INFO: org.apache.zookeeper.server.NIOServerCnxnFactory
(run(197)) - Accepted socket connection from /67.195.81.150:48317
2015-11-03 03:53:50,680 INFO: org.apache.zookeeper.ClientCnxn
(primeConnection(852)) - Socket connection established to
asf906.gq1.ygridcore.net/67.195.81.150:63630, initiating session
2015-11-03 03:53:50,680 INFO: org.apache.zookeeper.server.ZooKeeperServer
(processConnectRequest(868)) - Client attempting to establish new session at
/67.195.81.150:48317
2015-11-03 03:53:50,681 INFO: org.apache.zookeeper.server.ZooKeeperServer
(finishSessionInit(617)) - Established session 0x150cb782cfc0023 with
negotiated timeout 40000 for client /67.195.81.150:48317
2015-11-03 03:53:50,681 INFO: org.apache.zookeeper.ClientCnxn
(onConnected(1235)) - Session establishment complete on server
asf906.gq1.ygridcore.net/67.195.81.150:63630, sessionid = 0x150cb782cfc0023,
negotiated timeout = 40000
2015-11-03 03:53:50,686 INFO: SecurityLogger.org.apache.hadoop.hbase.Server
(processConnectionHeader(1677)) - Connection from 67.195.81.150 port: 40378
with version info: version: "1.1.1" url:
"git://hw11397.local/Volumes/hbase-1.1.1RC0/hbase" revision:
"d0a115a7267f54e01c72c603ec53e91ec418292f" user: "ndimiduk" date: "Tue Jun 23
14:56:34 PDT 2015" src_checksum: "6e2d8cecbd28738ad86daacb25dc467e"
2015-11-03 03:53:50,693 WARN:
org.apache.hadoop.hbase.mapreduce.LoadIncrementalHFiles (visitBulkHFiles(188))
- Skipping non-directory
hdfs://localhost:52738/tmp/tajo-jenkins/staging/q_1446522253707_0543/RESULT/_SUCCESS
2015-11-03 03:53:50,704 INFO: SecurityLogger.org.apache.hadoop.hbase.Server
(processConnectionHeader(1677)) - Connection from 67.195.81.150 port: 47585
with version info: version: "1.1.1" url:
"git://hw11397.local/Volumes/hbase-1.1.1RC0/hbase" revision:
"d0a115a7267f54e01c72c603ec53e91ec418292f" user: "ndimiduk" date: "Tue Jun 23
14:56:34 PDT 2015" src_checksum: "6e2d8cecbd28738ad86daacb25dc467e"
2015-11-03 03:53:50,750 INFO: org.apache.zookeeper.server.PrepRequestProcessor
(pRequest2Txn(494)) - Processed session termination for sessionid:
0x150cb782cfc0023
2015-11-03 03:53:50,751 INFO: org.apache.zookeeper.ZooKeeper (close(684)) -
Session: 0x150cb782cfc0023 closed
2015-11-03 03:53:50,751 INFO: org.apache.zookeeper.server.NIOServerCnxn
(closeSock(1007)) - Closed socket connection for client /67.195.81.150:48317
which had sessionid 0x150cb782cfc0023
2015-11-03 03:53:50,751 INFO: org.apache.zookeeper.ClientCnxn (run(512)) -
EventThread shut down
2015-11-03 03:53:50,858 INFO: org.apache.tajo.querymaster.Query (handle(886)) -
q_1446522253707_0543 Query Transitioned from QUERY_RUNNING to QUERY_SUCCEEDED
2015-11-03 03:53:50,858 INFO: org.apache.tajo.querymaster.QueryMasterTask
(handle(296)) - Query completion notified from q_1446522253707_0543 final
state: QUERY_SUCCEEDED
2015-11-03 03:53:50,859 INFO: org.apache.tajo.master.QueryInProgress
(heartbeat(259)) - Received QueryMaster
heartbeat:q_1446522253707_0543,state=QUERY_SUCCEEDED,progress=1.0,
queryMaster=asf906.gq1.ygridcore.net
2015-11-03 03:53:50,859 INFO: org.apache.tajo.master.QueryManager
(stopQuery(279)) - Stop QueryInProgress:q_1446522253707_0543
2015-11-03 03:53:50,859 INFO: org.apache.tajo.querymaster.QueryMasterTask
(serviceStop(172)) - Stopping QueryMasterTask:q_1446522253707_0543
2015-11-03 03:53:50,859 INFO: org.apache.tajo.master.QueryInProgress
(stopProgress(124)) - =========================================================
2015-11-03 03:53:50,859 INFO: org.apache.tajo.master.QueryInProgress
(stopProgress(125)) - Stop query:q_1446522253707_0543
2015-11-03 03:53:50,859 INFO: org.apache.tajo.querymaster.QueryMasterTask
(cleanupQuery(473)) - Cleanup resources of all workers. Query:
q_1446522253707_0543, workers: 1
2015-11-03 03:53:50,859 INFO: org.apache.tajo.querymaster.QueryMasterTask
(serviceStop(188)) - Stopped QueryMasterTask:q_1446522253707_0543
2015-11-03 03:53:51,456 INFO: org.apache.tajo.master.GlobalEngine
(executeQuery(176)) - Query: DROP TABLE hbase_mapped_table PURGE
2015-11-03 03:53:51,457 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(280)) - Non Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
2015-11-03 03:53:51,457 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(282)) - =============================================
2015-11-03 03:53:51,457 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(283)) - Optimized Query:
-----------------------------
Query Block Graph
-----------------------------
|-#ROOT
-----------------------------
Optimization Log:
-----------------------------
2015-11-03 03:53:51,457 INFO: org.apache.tajo.master.GlobalEngine
(createLogicalPlan(284)) - =============================================
2015-11-03 03:53:51,461 INFO: org.apache.tajo.catalog.CatalogServer
(dropTable(721)) - relation "TestHBaseTable.hbase_mapped_table" is deleted from
the catalog (127.0.0.1:24442)
Killed
Results :
Tests run: 559, Failures: 0, Errors: 0, Skipped: 0
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] Tajo Main ......................................... SUCCESS [ 1.639 s]
[INFO] Tajo Project POM .................................. SUCCESS [ 2.149 s]
[INFO] Tajo Maven Plugins ................................ SUCCESS [ 3.102 s]
[INFO] Tajo Common ....................................... SUCCESS [ 28.360 s]
[INFO] Tajo Algebra ...................................... SUCCESS [ 3.357 s]
[INFO] Tajo Catalog Common ............................... SUCCESS [ 4.674 s]
[INFO] Tajo Plan ......................................... SUCCESS [ 6.950 s]
[INFO] Tajo Rpc Common ................................... SUCCESS [ 1.305 s]
[INFO] Tajo Protocol Buffer Rpc .......................... SUCCESS [ 50.599 s]
[INFO] Tajo Catalog Client ............................... SUCCESS [ 1.426 s]
[INFO] Tajo Catalog Server ............................... SUCCESS [ 11.044 s]
[INFO] Tajo Storage Common ............................... SUCCESS [ 2.828 s]
[INFO] Tajo HDFS Storage ................................. SUCCESS [ 49.033 s]
[INFO] Tajo PullServer ................................... SUCCESS [ 0.916 s]
[INFO] Tajo Client ....................................... SUCCESS [ 2.753 s]
[INFO] Tajo CLI tools .................................... SUCCESS [ 1.815 s]
[INFO] Tajo SQL Parser ................................... SUCCESS [ 3.911 s]
[INFO] ASM (thirdparty) .................................. SUCCESS [ 2.018 s]
[INFO] Tajo RESTful Container ............................ SUCCESS [ 3.712 s]
[INFO] Tajo Metrics ...................................... SUCCESS [ 1.526 s]
[INFO] Tajo Core ......................................... SUCCESS [ 7.907 s]
[INFO] Tajo RPC .......................................... SUCCESS [ 0.922 s]
[INFO] Tajo Catalog Drivers Hive ......................... SUCCESS [ 9.218 s]
[INFO] Tajo Catalog Drivers .............................. SUCCESS [ 0.057 s]
[INFO] Tajo Catalog ...................................... SUCCESS [ 0.963 s]
[INFO] Tajo Client Example ............................... SUCCESS [ 1.060 s]
[INFO] Tajo HBase Storage ................................ SUCCESS [ 3.567 s]
[INFO] Tajo Cluster Tests ................................ SUCCESS [ 2.298 s]
[INFO] Tajo JDBC Driver .................................. SUCCESS [ 32.540 s]
[INFO] Tajo JDBC storage common .......................... SUCCESS [ 0.919 s]
[INFO] Tajo PostgreSQL JDBC storage ...................... SUCCESS [ 0.958 s]
[INFO] Tajo Storage ...................................... SUCCESS [ 0.989 s]
[INFO] Tajo Distribution ................................. SUCCESS [ 5.867 s]
[INFO] Tajo Core Tests ................................... FAILURE [09:49 min]
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 14:00 min
[INFO] Finished at: 2015-11-03T03:53:51+00:00
[INFO] Final Memory: 167M/2179M
[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-jdk8-nightly/ws/tajo-core-tests> &&
/home/jenkins/jenkins-slave/tools/hudson.model.JDK/latest1.8/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-jdk8-nightly/ws/tajo-core-tests/target/surefire/surefirebooter7195260034492952879.jar>
<https://builds.apache.org/job/Tajo-master-jdk8-nightly/ws/tajo-core-tests/target/surefire/surefire6749953114813329683tmp>
<https://builds.apache.org/job/Tajo-master-jdk8-nightly/ws/tajo-core-tests/target/surefire/surefire_146865026928014808669tmp>
[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
Archiving artifacts
Recording test results
