[AMBARI-10160] Hive Metastore alert check "show databases" results in a Tez session causing RM to be unresponsive

• Type: Bug
• Status: Resolved
• Priority: Critical
• Resolution: Fixed
• Affects Version/s: 2.0.0
• Fix Version/s: 2.0.0
• Component/s: ambari-server
• Assignee: Jonathan Hurley
• Reporter: Jonathan Hurley
• Created: 20/Mar/15 16:44
• Updated: 30/Jun/15 14:03
• Resolved: 20/Mar/15 17:13

Description

The HS2 check does this:

export HIVE_CONF_DIR='/etc/hive/conf.server/' ; hive --hiveconf hive.metastore.uris=thrift://revo3.hortonworks.local:9083 -e 'show databases;'

Looks like this does this to hive:

[3/19/15, 7:47:37 PM] Paul Codding: 2015-03-19 18:44:22,959 INFO  [main]: parse.ParseDriver (ParseDriver.java:parse(185)) - Parsing command: show databases
2015-03-19 18:44:23,801 INFO  [main]: parse.ParseDriver (ParseDriver.java:parse(206)) - Parse Completed
2015-03-19 18:44:23,809 INFO  [main]: log.PerfLogger (PerfLogger.java:PerfLogEnd(135)) - </PERFLOG method=parse start=1426808662932 end=1426808663809 duration=877 from=org.apache.hadoop.hive.ql.Driver>
2015-03-19 18:44:23,818 INFO  [main]: log.PerfLogger (PerfLogger.java:PerfLogBegin(108)) - <PERFLOG method=semanticAnalyze from=org.apache.hadoop.hive.ql.Driver>
2015-03-19 18:44:24,002 INFO  [main]: ql.Driver (Driver.java:compile(427)) - Semantic Analysis Completed
2015-03-19 18:44:24,003 INFO  [main]: log.PerfLogger (PerfLogger.java:PerfLogEnd(135)) - </PERFLOG method=semanticAnalyze start=1426808663818 end=1426808664003 duration=185 from=org.apache.hadoop.hive.ql.Driver>
2015-03-19 18:44:24,386 INFO  [main]: exec.ListSinkOperator (Operator.java:initialize(346)) - Initializing Self OP[0]
2015-03-19 18:44:24,394 INFO  [main]: exec.ListSinkOperator (Operator.java:initializeChildren(419)) - Operator 0 OP initialized
2015-03-19 18:44:24,395 INFO  [main]: exec.ListSinkOperator (Operator.java:initialize(394)) - Initialization Done 0 OP
2015-03-19 18:44:24,419 INFO  [main]: ql.Driver (Driver.java:getSchema(235)) - Returning Hive schema: Schema(fieldSchemas:[FieldSchema(name:database_name, type:string, comment:from deserializer)], properties:null)
2015-03-19 18:44:24,420 INFO  [main]: log.PerfLogger (PerfLogger.java:PerfLogEnd(135)) - </PERFLOG method=compile start=1426808662818 end=1426808664420 duration=1602 from=org.apache.hadoop.hive.ql.Driver>
2015-03-19 18:44:24,420 INFO  [main]: log.PerfLogger (PerfLogger.java:PerfLogBegin(108)) - <PERFLOG method=Driver.execute from=org.apache.hadoop.hive.ql.Driver>
2015-03-19 18:44:24,420 INFO  [main]: ql.Driver (Driver.java:execute(1285)) - Starting command: show databases
2015-03-19 18:44:25,090 INFO  [main]: impl.TimelineClientImpl (TimelineClientImpl.java:serviceInit(285)) - Timeline service address: http://revo2.hortonworks.local:8188/ws/v1/timeline/
2015-03-19 18:44:25,093 INFO  [main]: hooks.ATSHook (ATSHook.java:<init>(87)) - Created ATS Hook
2015-03-19 18:44:25,093 INFO  [main]: log.PerfLogger (PerfLogger.java:PerfLogBegin(108)) - <PERFLOG method=PreHook.org.apache.hadoop.hive.ql.hooks.ATSHook from=org.apache.hadoop.hive.ql.Driver>
2015-03-19 18:44:25,098 INFO  [main]: log.PerfLogger (PerfLogger.java:PerfLogEnd(135)) - </PERFLOG method=PreHook.org.apache.hadoop.hive.ql.hooks.ATSHook start=1426808665093 end=1426808665098 duration=5 from=org.apache.hadoop.hive.ql.Driver>
2015-03-19 18:44:25,103 INFO  [main]: log.PerfLogger (PerfLogger.java:PerfLogEnd(135)) - </PERFLOG method=TimeToSubmit start=1426808662818 end=1426808665103 duration=2285 from=org.apache.hadoop.hive.ql.Driver>
2015-03-19 18:44:25,103 INFO  [main]: log.PerfLogger (PerfLogger.java:PerfLogBegin(108)) - <PERFLOG method=runTasks from=org.apache.hadoop.hive.ql.Driver>
2015-03-19 18:44:25,103 INFO  [main]: log.PerfLogger (PerfLogger.java:PerfLogBegin(108)) - <PERFLOG method=task.DDL.Stage-0 from=org.apache.hadoop.hive.ql.Driver>
2015-03-19 18:44:25,114 INFO  [main]: ql.Driver (Driver.java:launchTask(1602)) - Starting task [Stage-0:DDL] in serial mode
2015-03-19 18:44:25,309 INFO  [main]: exec.DDLTask (DDLTask.java:showDatabases(2173)) - results : 1
2015-03-19 18:44:25,317 INFO  [main]: log.PerfLogger (PerfLogger.java:PerfLogEnd(135)) - </PERFLOG method=runTasks start=1426808665103 end=1426808665317 duration=214 from=org.apache.hadoop.hive.ql.Driver>
2015-03-19 18:44:25,319 INFO  [main]: hooks.ATSHook (ATSHook.java:<init>(87)) - Created ATS Hook
2015-03-19 18:44:25,319 INFO  [main]: log.PerfLogger (PerfLogger.java:PerfLogBegin(108)) - <PERFLOG method=PostHook.org.apache.hadoop.hive.ql.hooks.ATSHook from=org.apache.hadoop.hive.ql.Driver>
2015-03-19 18:44:25,320 INFO  [main]: log.PerfLogger (PerfLogger.java:PerfLogEnd(135)) - </PERFLOG method=PostHook.org.apache.hadoop.hive.ql.hooks.ATSHook start=1426808665319 end=1426808665320 duration=1 from=org.apache.hadoop.hive.ql.Driver>
2015-03-19 18:44:25,321 INFO  [main]: log.PerfLogger (PerfLogger.java:PerfLogEnd(135)) - </PERFLOG method=Driver.execute start=1426808664420 end=1426808665321 duration=901 from=org.apache.hadoop.hive.ql.Driver>
2015-03-19 18:44:25,330 INFO  [main]: ql.Driver (SessionState.java:printInfo(824)) - OK
2015-03-19 18:44:25,331 INFO  [main]: log.PerfLogger (PerfLogger.java:PerfLogBegin(108)) - <PERFLOG method=releaseLocks from=org.apache.hadoop.hive.ql.Driver>
2015-03-19 18:44:25,331 INFO  [main]: log.PerfLogger (PerfLogger.java:PerfLogEnd(135)) - </PERFLOG method=releaseLocks start=1426808665331 end=1426808665331 duration=0 from=org.apache.hadoop.hive.ql.Driver>
2015-03-19 18:44:25,332 INFO  [main]: log.PerfLogger (PerfLogger.java:PerfLogEnd(135)) - </PERFLOG method=Driver.run start=1426808662817 end=1426808665332 duration=2515 from=org.apache.hadoop.hive.ql.Driver>
2015-03-19 18:44:25,353 INFO  [main]: Configuration.deprecation (Configuration.java:warnOnceIfDeprecated(1051)) - mapred.input.dir is deprecated. Instead, use mapreduce.input.fileinputformat.inputdir
2015-03-19 18:44:25,536 INFO  [main]: mapred.FileInputFormat (FileInputFormat.java:listStatus(247)) - Total input paths to process : 1
2015-03-19 18:44:25,635 INFO  [main]: exec.ListSinkOperator (Operator.java:close(595)) - 0 finished. closing... 
2015-03-19 18:44:25,635 INFO  [main]: exec.ListSinkOperator (Operator.java:close(613)) - 0 Close done
2015-03-19 18:44:25,660 INFO  [main]: CliDriver (SessionState.java:printInfo(824)) - Time taken: 2.529 seconds, Fetched: 1 row(s)
2015-03-19 18:44:25,660 INFO  [main]: log.PerfLogger (PerfLogger.java:PerfLogBegin(108)) - <PERFLOG method=releaseLocks from=org.apache.hadoop.hive.ql.Driver>
2015-03-19 18:44:25,662 INFO  [main]: log.PerfLogger (PerfLogger.java:PerfLogEnd(135)) - </PERFLOG method=releaseLocks start=1426808665660 end=1426808665662 duration=2 from=org.apache.hadoop.hive.ql.Driver>
2015-03-19 18:44:25,708 INFO  [main]: tez.TezSessionPoolManager (TezSessionPoolManager.java:close(174)) - Closing tez session default? false
2015-03-19 18:44:25,709 INFO  [main]: tez.TezSessionState (TezSessionState.java:close(263)) - Closing Tez Session
2015-03-19 18:44:25,710 INFO  [main]: client.TezClient (TezClient.java:stop(434)) - Shutting down Tez Session, sessionName=HIVE-f96da6a9-ad75-4949-b8ba-a9e3b65278d3, applicationId=application_1426808089446_0009
[3/19/15, 7:47:50 PM] Paul Codding: salient
[3/19/15, 7:47:50 PM] Paul Codding: 2015-03-19 18:44:22,959 INFO  [main]: parse.ParseDriver (ParseDriver.java:parse(185)) - Parsing command: show databases
2015-03-19 18:44:23,801 INFO  [main]: parse.ParseDriver (ParseDriver.java:parse(206)) - Parse Completed

Changing this check to use the MR enginer instead of TEZ corrects the problem:

export HIVE_CONF_DIR='/etc/hive/conf.server/' ; hive --hiveconf hive.metastore.uris=thrift://revo3.hortonworks.local:9083 --hiveconf hive.execution.engine=mr -e 'show databases;'

Issue Links

• links to: Reviewboard