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/10/23 06:56:45 UTC
Build failed in Jenkins: Tajo-master-nightly #897
See <https://builds.apache.org/job/Tajo-master-nightly/897/changes>
Changes:
[hyunsik] TAJO-1643: SQLParse can't parse table alias in in brackets.
------------------------------------------
[...truncated 54705 lines...]
SORT(9)
=> Sort Keys: key_alias (FLOAT8) (desc)
GROUP_BY(1)(key_alias)
=> exprs: (count(?count (INT8)))
=> target list: key_alias (FLOAT8), cnt (INT8)
=> out schema:{(2) key_alias (FLOAT8), cnt (INT8)}
=> in schema:{(2) key_alias (FLOAT8), ?count (INT8)}
SCAN(8) on eb_1445576046880_0169_000001
=> out schema: {(2) key_alias (FLOAT8), ?count (INT8)}
=> in schema: {(2) key_alias (FLOAT8), ?count (INT8)}
=======================================================
Block Id: eb_1445576046880_0169_000003 [ROOT]
=======================================================
[Incoming]
[q_1445576046880_0169] 2 => 3 (type=RANGE_SHUFFLE, key=key_alias (FLOAT8), num=32)
[Enforcers]
0: sorted input=eb_1445576046880_0169_000002
SORT(2)
=> Sort Keys: key_alias (FLOAT8) (desc)
SCAN(10) on eb_1445576046880_0169_000002
=> out schema: {(2) key_alias (FLOAT8), cnt (INT8)}
=> in schema: {(2) key_alias (FLOAT8), cnt (INT8)}
=======================================================
Block Id: eb_1445576046880_0169_000004 [TERMINAL]
=======================================================
2015-10-23 04:57:22,697 INFO: org.apache.tajo.engine.planner.global.GlobalPlanner (build(160)) -
Optimized master plan
-------------------------------------------------------------------------------
Execution Block Graph (TERMINAL - eb_1445576046880_0169_000004)
-------------------------------------------------------------------------------
|-eb_1445576046880_0169_000004
|-eb_1445576046880_0169_000003
|-eb_1445576046880_0169_000002
|-eb_1445576046880_0169_000001
-------------------------------------------------------------------------------
Order of Execution
-------------------------------------------------------------------------------
1: eb_1445576046880_0169_000001
2: eb_1445576046880_0169_000002
3: eb_1445576046880_0169_000003
4: eb_1445576046880_0169_000004
-------------------------------------------------------------------------------
=======================================================
Block Id: eb_1445576046880_0169_000001 [LEAF]
=======================================================
[Outgoing]
[q_1445576046880_0169] 1 => 2 (type=HASH_SHUFFLE, key=key_alias (FLOAT8), num=32)
GROUP_BY(7)(key_alias)
=> exprs: (count())
=> target list: key_alias (FLOAT8), ?count (INT8)
=> out schema:{(2) key_alias (FLOAT8), ?count (INT8)}
=> in schema:{(1) key_alias (FLOAT8)}
PARTITIONS_SCAN(5) on default.testquerycasesoncolumnpartitionedtable
=> target list: default.testquerycasesoncolumnpartitionedtable.key (FLOAT8) as key_alias
=> num of filtered paths: 5
=> out schema: {(1) key_alias (FLOAT8)}
=> in schema: {(3) default.testquerycasesoncolumnpartitionedtable.col1 (INT4), default.testquerycasesoncolumnpartitionedtable.col2 (INT4), default.testquerycasesoncolumnpartitionedtable.null_col (INT4)}
=> 0: hdfs://localhost:33358/tajo/warehouse/default/testquerycasesoncolumnpartitionedtable/key=17.0
=> 1: hdfs://localhost:33358/tajo/warehouse/default/testquerycasesoncolumnpartitionedtable/key=36.0
=> 2: hdfs://localhost:33358/tajo/warehouse/default/testquerycasesoncolumnpartitionedtable/key=38.0
=> 3: hdfs://localhost:33358/tajo/warehouse/default/testquerycasesoncolumnpartitionedtable/key=45.0
=> 4: hdfs://localhost:33358/tajo/warehouse/default/testquerycasesoncolumnpartitionedtable/key=49.0
=======================================================
Block Id: eb_1445576046880_0169_000002 [INTERMEDIATE]
=======================================================
[Incoming]
[q_1445576046880_0169] 1 => 2 (type=HASH_SHUFFLE, key=key_alias (FLOAT8), num=32)
[Outgoing]
[q_1445576046880_0169] 2 => 3 (type=RANGE_SHUFFLE, key=key_alias (FLOAT8), num=32)
SORT(9)
=> Sort Keys: key_alias (FLOAT8) (desc)
GROUP_BY(1)(key_alias)
=> exprs: (count(?count (INT8)))
=> target list: key_alias (FLOAT8), cnt (INT8)
=> out schema:{(2) key_alias (FLOAT8), cnt (INT8)}
=> in schema:{(2) key_alias (FLOAT8), ?count (INT8)}
SCAN(8) on eb_1445576046880_0169_000001
=> out schema: {(2) key_alias (FLOAT8), ?count (INT8)}
=> in schema: {(2) key_alias (FLOAT8), ?count (INT8)}
=======================================================
Block Id: eb_1445576046880_0169_000003 [ROOT]
=======================================================
[Incoming]
[q_1445576046880_0169] 2 => 3 (type=RANGE_SHUFFLE, key=key_alias (FLOAT8), num=32)
[Enforcers]
0: sorted input=eb_1445576046880_0169_000002
SORT(2)
=> Sort Keys: key_alias (FLOAT8) (desc)
SCAN(10) on eb_1445576046880_0169_000002
=> out schema: {(2) key_alias (FLOAT8), cnt (INT8)}
=> in schema: {(2) key_alias (FLOAT8), cnt (INT8)}
=======================================================
Block Id: eb_1445576046880_0169_000004 [TERMINAL]
=======================================================
2015-10-23 04:57:22,698 INFO: org.apache.tajo.querymaster.Query (<init>(234)) -
=======================================================
The order of execution:
1: eb_1445576046880_0169_000001
2: eb_1445576046880_0169_000002
3: eb_1445576046880_0169_000003
4: eb_1445576046880_0169_000004
=======================================================
2015-10-23 04:57:22,698 INFO: org.apache.tajo.querymaster.Query (handle(868)) - Processing q_1445576046880_0169 of type START
2015-10-23 04:57:22,699 INFO: org.apache.tajo.engine.planner.global.ParallelExecutionQueue (first(84)) - Initial executable blocks [eb_1445576046880_0169_000001]
2015-10-23 04:57:22,699 INFO: org.apache.tajo.querymaster.Query (handle(886)) - q_1445576046880_0169 Query Transitioned from QUERY_NEW to QUERY_RUNNING
2015-10-23 04:57:22,699 INFO: org.apache.tajo.querymaster.Stage (calculateShuffleOutputNum(1018)) - eb_1445576046880_0169_000001, Table's volume is approximately 1 MB
2015-10-23 04:57:22,699 INFO: org.apache.tajo.querymaster.Stage (calculateShuffleOutputNum(1022)) - eb_1445576046880_0169_000001, The determined number of aggregation partitions is 1
2015-10-23 04:57:22,717 INFO: org.apache.tajo.querymaster.Stage (initTaskScheduler(901)) - org.apache.tajo.querymaster.DefaultTaskScheduler is chosen for the task scheduling for eb_1445576046880_0169_000001
2015-10-23 04:57:22,719 INFO: org.apache.tajo.storage.FileTablespace (listStatus(363)) - Total input paths to process : 1
2015-10-23 04:57:22,722 INFO: org.apache.tajo.storage.FileTablespace (listStatus(363)) - Total input paths to process : 1
2015-10-23 04:57:22,724 INFO: org.apache.tajo.storage.FileTablespace (listStatus(363)) - Total input paths to process : 1
2015-10-23 04:57:22,726 INFO: org.apache.tajo.storage.FileTablespace (listStatus(363)) - Total input paths to process : 1
2015-10-23 04:57:22,729 INFO: org.apache.tajo.storage.FileTablespace (listStatus(363)) - Total input paths to process : 1
2015-10-23 04:57:22,730 INFO: org.apache.tajo.storage.FileTablespace (getSplits(564)) - Total # of splits: 5
2015-10-23 04:57:22,730 INFO: org.apache.tajo.querymaster.Stage (run(853)) - 5 objects are scheduled
2015-10-23 04:57:22,730 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (start(134)) - Start TaskScheduler
2015-10-23 04:57:22,732 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (increaseConcurrency(524)) - Assigned host : 127.0.0.1, Unknown Volume : -1, Concurrency : 1
2015-10-23 04:57:22,733 INFO: org.apache.tajo.worker.TaskManager (createExecutionBlock(114)) - QueryMaster Address:asf903.gq1.ygridcore.net:12379
2015-10-23 04:57:22,733 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (assignToLeafTasks(921)) - Assigned Local/Rack/Total: (0/1/1), Attempted Cancel/Assign/Total: (0/1/1), Locality: 0.00%, Rack host: asf903.gq1.ygridcore.net
2015-10-23 04:57:22,734 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (increaseConcurrency(524)) - Assigned host : 127.0.0.1, Unknown Volume : -1, Concurrency : 2
2015-10-23 04:57:22,735 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (assignToLeafTasks(921)) - Assigned Local/Rack/Total: (0/2/2), Attempted Cancel/Assign/Total: (0/2/2), Locality: 0.00%, Rack host: asf903.gq1.ygridcore.net
2015-10-23 04:57:22,735 INFO: org.apache.tajo.worker.ExecutionBlockContext (init(119)) - Tajo Root Dir: hdfs://localhost:33358/tajo
2015-10-23 04:57:22,735 INFO: org.apache.tajo.worker.ExecutionBlockContext (init(120)) - Worker Local Dir: file://<https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core-tests/target/test-data/17848b7b-17ed-4e31-8d5b-9941fb3c08fc/tajo-localdir>
2015-10-23 04:57:22,736 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (increaseConcurrency(524)) - Assigned host : 127.0.0.1, Unknown Volume : -1, Concurrency : 3
2015-10-23 04:57:22,736 INFO: org.apache.tajo.worker.TaskManager (handle(184)) - Running ExecutionBlocks: 1, running tasks:0, availableResource: (Memory:500, Disks:1, vCores:1)
2015-10-23 04:57:22,737 INFO: org.apache.tajo.worker.TaskImpl (<init>(116)) - Task basedir is created (<https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core-tests/target/test-data/17848b7b-17ed-4e31-8d5b-9941fb3c08fc/tajo-localdir/q_1445576046880_0169/output/1)>
2015-10-23 04:57:22,737 INFO: org.apache.tajo.querymaster.DefaultTaskScheduler (assignToLeafTasks(921)) - Assigned Local/Rack/Total: (0/3/3), Attempted Cancel/Assign/Total: (0/3/3), Locality: 0.00%, Rack host: asf903.gq1.ygridcore.net
2015-10-23 04:57:22,737 INFO: org.apache.tajo.worker.TaskImpl (init(197)) - Initializing: ta_1445576046880_0169_000001_000000_00
2015-10-23 04:57:22,737 INFO: org.apache.tajo.worker.TaskImpl (<init>(116)) - Task basedir is created (<https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core-tests/target/test-data/17848b7b-17ed-4e31-8d5b-9941fb3c08fc/tajo-localdir/q_1445576046880_0169/output/1)>
2015-10-23 04:57:22,737 INFO: org.apache.tajo.worker.TaskImpl (initPlan(152)) - ==================================
2015-10-23 04:57:22,737 INFO: org.apache.tajo.worker.TaskImpl (initPlan(153)) - * Stage ta_1445576046880_0169_000001_000000_00 is initialized
2015-10-23 04:57:22,738 INFO: org.apache.tajo.worker.TaskImpl (initPlan(154)) - * InterQuery: true, Use HASH_SHUFFLE shuffle, Fragments (num: 1), Fetches (total:0) :
2015-10-23 04:57:22,738 INFO: org.apache.tajo.worker.TaskImpl (initPlan(164)) - * Local task dir: <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core-tests/target/test-data/17848b7b-17ed-4e31-8d5b-9941fb3c08fc/tajo-localdir/q_1445576046880_0169/output/1/0_0>
2015-10-23 04:57:22,738 INFO: org.apache.tajo.worker.TaskImpl (initPlan(169)) - ==================================
2015-10-23 04:57:22,737 INFO: org.apache.tajo.worker.TaskImpl (<init>(116)) - Task basedir is created (<https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core-tests/target/test-data/17848b7b-17ed-4e31-8d5b-9941fb3c08fc/tajo-localdir/q_1445576046880_0169/output/1)>
2015-10-23 04:57:22,737 INFO: org.apache.tajo.worker.TaskImpl (init(197)) - Initializing: ta_1445576046880_0169_000001_000001_00
2015-10-23 04:57:22,738 INFO: org.apache.tajo.worker.TaskImpl (init(197)) - Initializing: ta_1445576046880_0169_000001_000002_00
2015-10-23 04:57:22,738 INFO: org.apache.tajo.engine.planner.PhysicalPlannerImpl (createBestAggregationPlan(1030)) - Aggregation:estimatedSize=5, threshold=67108864
2015-10-23 04:57:22,738 INFO: org.apache.tajo.engine.planner.PhysicalPlannerImpl (createBestAggregationPlan(1032)) - The planner chooses [Hash Aggregation]
2015-10-23 04:57:22,738 INFO: org.apache.tajo.engine.planner.PhysicalPlannerImpl (createInMemoryHashAggregation(974)) - The planner chooses [Hash Aggregation]
2015-10-23 04:57:22,738 INFO: org.apache.tajo.worker.TaskImpl (initPlan(152)) - ==================================
2015-10-23 04:57:22,739 INFO: org.apache.tajo.worker.TaskImpl (initPlan(153)) - * Stage ta_1445576046880_0169_000001_000001_00 is initialized
2015-10-23 04:57:22,739 INFO: org.apache.tajo.worker.TaskImpl (initPlan(154)) - * InterQuery: true, Use HASH_SHUFFLE shuffle, Fragments (num: 1), Fetches (total:0) :
2015-10-23 04:57:22,739 INFO: org.apache.tajo.worker.TaskImpl (initPlan(164)) - * Local task dir: <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core-tests/target/test-data/17848b7b-17ed-4e31-8d5b-9941fb3c08fc/tajo-localdir/q_1445576046880_0169/output/1/1_0>
2015-10-23 04:57:22,739 INFO: org.apache.tajo.worker.TaskImpl (initPlan(169)) - ==================================
2015-10-23 04:57:22,738 INFO: org.apache.tajo.worker.TaskImpl (initPlan(152)) - ==================================
2015-10-23 04:57:22,739 INFO: org.apache.tajo.worker.TaskImpl (initPlan(153)) - * Stage ta_1445576046880_0169_000001_000002_00 is initialized
2015-10-23 04:57:22,739 INFO: org.apache.tajo.worker.TaskImpl (initPlan(154)) - * InterQuery: true, Use HASH_SHUFFLE shuffle, Fragments (num: 1), Fetches (total:0) :
2015-10-23 04:57:22,739 INFO: org.apache.tajo.worker.TaskImpl (initPlan(164)) - * Local task dir: <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core-tests/target/test-data/17848b7b-17ed-4e31-8d5b-9941fb3c08fc/tajo-localdir/q_1445576046880_0169/output/1/2_0>
2015-10-23 04:57:22,739 INFO: org.apache.tajo.worker.TaskImpl (initPlan(169)) - ==================================
2015-10-23 04:57:22,739 INFO: org.apache.tajo.engine.planner.PhysicalPlannerImpl (createBestAggregationPlan(1030)) - Aggregation:estimatedSize=5, threshold=67108864
2015-10-23 04:57:22,740 INFO: org.apache.tajo.engine.planner.PhysicalPlannerImpl (createBestAggregationPlan(1032)) - The planner chooses [Hash Aggregation]
2015-10-23 04:57:22,740 INFO: org.apache.tajo.engine.planner.PhysicalPlannerImpl (createInMemoryHashAggregation(974)) - The planner chooses [Hash Aggregation]
2015-10-23 04:57:22,740 INFO: org.apache.tajo.engine.planner.PhysicalPlannerImpl (createBestAggregationPlan(1030)) - Aggregation:estimatedSize=5, threshold=67108864
2015-10-23 04:57:22,740 INFO: org.apache.tajo.engine.planner.PhysicalPlannerImpl (createBestAggregationPlan(1032)) - The planner chooses [Hash Aggregation]
2015-10-23 04:57:22,740 INFO: org.apache.tajo.engine.planner.PhysicalPlannerImpl (createInMemoryHashAggregation(974)) - The planner chooses [Hash Aggregation]
2015-10-23 04:57:22,744 INFO: org.apache.tajo.worker.TaskImpl (run(462)) - ta_1445576046880_0169_000001_000000_00 completed. Worker's task counter - total:1, succeeded: 1, killed: 0, failed: 0
2015-10-23 04:57:22,744 INFO: org.apache.tajo.worker.TaskImpl (run(462)) - ta_1445576046880_0169_000001_000001_00 completed. Worker's task counter - total:2, succeeded: 2, killed: 0, failed: 0
2015-10-23 04:57:22,744 INFO: org.apache.tajo.querymaster.Stage (transition(1247)) - [eb_1445576046880_0169_000001] Task Completion Event (Total: 5, Success: 1, Killed: 0, Failed: 0)
2015-10-23 04:57:22,744 INFO: org.apache.tajo.worker.TaskImpl (run(462)) - ta_1445576046880_0169_000001_000002_00 completed. Worker's task counter - total:3, succeeded: 3, killed: 0, failed: 0
2015-10-23 04:57:22,745 INFO: org.apache.tajo.querymaster.Stage (transition(1247)) - [eb_1445576046880_0169_000001] Task Completion Event (Total: 5, Success: 2, Killed: 0, Failed: 0)
2015-10-23 04:57:22,745 INFO: org.apache.tajo.querymaster.Stage (transition(1247)) - [eb_1445576046880_0169_000001] Task Completion Event (Total: 5, Success: 3, Killed: 0, Failed: 0)
Killed
Results :
Tests run: 214, Failures: 0, Errors: 0, Skipped: 0
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO]
[INFO] Tajo Main ......................................... SUCCESS [ 2.764 s]
[INFO] Tajo Project POM .................................. SUCCESS [ 2.085 s]
[INFO] Tajo Maven Plugins ................................ SUCCESS [ 4.698 s]
[INFO] Tajo Common ....................................... SUCCESS [ 32.073 s]
[INFO] Tajo Algebra ...................................... SUCCESS [ 2.662 s]
[INFO] Tajo Catalog Common ............................... SUCCESS [ 5.306 s]
[INFO] Tajo Plan ......................................... SUCCESS [ 8.377 s]
[INFO] Tajo Rpc Common ................................... SUCCESS [ 1.416 s]
[INFO] Tajo Protocol Buffer Rpc .......................... SUCCESS [ 50.165 s]
[INFO] Tajo Catalog Client ............................... SUCCESS [ 1.561 s]
[INFO] Tajo Catalog Server ............................... SUCCESS [ 11.571 s]
[INFO] Tajo Storage Common ............................... SUCCESS [ 2.795 s]
[INFO] Tajo HDFS Storage ................................. SUCCESS [ 54.942 s]
[INFO] Tajo PullServer ................................... SUCCESS [ 1.084 s]
[INFO] Tajo Client ....................................... SUCCESS [ 2.803 s]
[INFO] Tajo CLI tools .................................... SUCCESS [ 2.538 s]
[INFO] Tajo SQL Parser ................................... SUCCESS [ 4.359 s]
[INFO] ASM (thirdparty) .................................. SUCCESS [ 2.513 s]
[INFO] Tajo RESTful Container ............................ SUCCESS [ 5.698 s]
[INFO] Tajo Metrics ...................................... SUCCESS [ 1.430 s]
[INFO] Tajo Core ......................................... SUCCESS [ 8.245 s]
[INFO] Tajo RPC .......................................... SUCCESS [ 0.966 s]
[INFO] Tajo Catalog Drivers Hive ......................... SUCCESS [ 11.525 s]
[INFO] Tajo Catalog Drivers .............................. SUCCESS [ 0.071 s]
[INFO] Tajo Catalog ...................................... SUCCESS [ 0.987 s]
[INFO] Tajo Client Example ............................... SUCCESS [ 1.082 s]
[INFO] Tajo HBase Storage ................................ SUCCESS [ 4.403 s]
[INFO] Tajo Cluster Tests ................................ SUCCESS [ 2.634 s]
[INFO] Tajo JDBC Driver .................................. SUCCESS [ 38.519 s]
[INFO] Tajo JDBC storage common .......................... SUCCESS [ 1.023 s]
[INFO] Tajo PostgreSQL JDBC storage ...................... SUCCESS [ 2.169 s]
[INFO] Tajo Storage ...................................... SUCCESS [ 1.039 s]
[INFO] Tajo Distribution ................................. SUCCESS [ 6.259 s]
[INFO] Tajo Core Tests ................................... FAILURE [03:32 min]
[INFO] ------------------------------------------------------------------------
[INFO] BUILD FAILURE
[INFO] ------------------------------------------------------------------------
[INFO] Total time: 08:13 min
[INFO] Finished at: 2015-10-23T04:57:22+00:00
[INFO] Final Memory: 163M/2133M
[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-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-nightly/ws/tajo-core-tests/target/surefire/surefirebooter5470001664331843564.jar> <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core-tests/target/surefire/surefire7279851573266758239tmp> <https://builds.apache.org/job/Tajo-master-nightly/ws/tajo-core-tests/target/surefire/surefire_145553915673001570458tmp>
[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
Jenkins build is back to normal : Tajo-master-nightly #898
Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/Tajo-master-nightly/898/changes>