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/11/03 04:54:04 UTC
Build failed in Jenkins: Tajo-master-jdk8-nightly #211
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