2017-03-09 18:12:09,038 INFO  [HiveServer2-Handler-Pool: Thread-3835]: session.HiveSessionImpl (HiveSessionImpl.java:acquireAfterOpLock(332)) - We are setting the hadoop caller context to a4da896d-4863-4629-bd31-dfa5e4081316 for thread HiveServer2-Handler-Pool: Thread-3835
2017-03-09 18:12:09,039 INFO  [HiveServer2-Handler-Pool: Thread-3835]: metastore.HiveMetaStore (HiveMetaStore.java:logInfo(822)) - 102: get_databases: *
2017-03-09 18:12:09,039 INFO  [HiveServer2-Handler-Pool: Thread-3835]: HiveMetaStore.audit (HiveMetaStore.java:logAuditEvent(391)) - ugi=test_sh	ip=unknown-ip-addr	cmd=get_databases: *	
2017-03-09 18:12:09,080 INFO  [HiveServer2-Handler-Pool: Thread-3835]: session.HiveSessionImpl (HiveSessionImpl.java:releaseBeforeOpLock(356)) - We are resetting the hadoop caller context for thread HiveServer2-Handler-Pool: Thread-3835
2017-03-09 18:12:09,084 INFO  [HiveServer2-Handler-Pool: Thread-3835]: session.HiveSessionImpl (HiveSessionImpl.java:acquireAfterOpLock(332)) - We are setting the hadoop caller context to a4da896d-4863-4629-bd31-dfa5e4081316 for thread HiveServer2-Handler-Pool: Thread-3835
2017-03-09 18:12:09,085 INFO  [HiveServer2-Handler-Pool: Thread-3835]: session.HiveSessionImpl (HiveSessionImpl.java:releaseBeforeOpLock(356)) - We are resetting the hadoop caller context for thread HiveServer2-Handler-Pool: Thread-3835
2017-03-09 18:12:09,089 INFO  [HiveServer2-Handler-Pool: Thread-3835]: session.HiveSessionImpl (HiveSessionImpl.java:acquireAfterOpLock(332)) - We are setting the hadoop caller context to a4da896d-4863-4629-bd31-dfa5e4081316 for thread HiveServer2-Handler-Pool: Thread-3835
2017-03-09 18:12:09,089 INFO  [HiveServer2-Handler-Pool: Thread-3835]: session.HiveSessionImpl (HiveSessionImpl.java:releaseBeforeOpLock(356)) - We are resetting the hadoop caller context for thread HiveServer2-Handler-Pool: Thread-3835
2017-03-09 18:12:09,093 INFO  [HiveServer2-Handler-Pool: Thread-3835]: session.HiveSessionImpl (HiveSessionImpl.java:acquireAfterOpLock(332)) - We are setting the hadoop caller context to a4da896d-4863-4629-bd31-dfa5e4081316 for thread HiveServer2-Handler-Pool: Thread-3835
2017-03-09 18:12:09,093 INFO  [HiveServer2-Handler-Pool: Thread-3835]: session.HiveSessionImpl (HiveSessionImpl.java:releaseBeforeOpLock(356)) - We are resetting the hadoop caller context for thread HiveServer2-Handler-Pool: Thread-3835
2017-03-09 18:12:10,513 INFO  [HiveServer2-Handler-Pool: Thread-3835]: session.HiveSessionImpl (HiveSessionImpl.java:acquireAfterOpLock(332)) - We are setting the hadoop caller context to a4da896d-4863-4629-bd31-dfa5e4081316 for thread HiveServer2-Handler-Pool: Thread-3835
2017-03-09 18:12:10,515 INFO  [HiveServer2-Handler-Pool: Thread-3835]: log.PerfLogger (PerfLogger.java:PerfLogBegin(148)) - <PERFLOG method=Driver.run from=org.apache.hadoop.hive.ql.Driver>
2017-03-09 18:12:10,515 INFO  [HiveServer2-Handler-Pool: Thread-3835]: log.PerfLogger (PerfLogger.java:PerfLogBegin(148)) - <PERFLOG method=compile from=org.apache.hadoop.hive.ql.Driver>
2017-03-09 18:12:10,515 INFO  [HiveServer2-Handler-Pool: Thread-3835]: ql.Driver (Driver.java:compile(426)) - We are setting the hadoop caller context from HIVE_SSN_ID:a4da896d-4863-4629-bd31-dfa5e4081316 to hive_20170309181210_5d9929c8-352c-4360-b2bc-30bea3e14154
2017-03-09 18:12:10,516 INFO  [HiveServer2-Handler-Pool: Thread-3835]: log.PerfLogger (PerfLogger.java:PerfLogBegin(148)) - <PERFLOG method=parse from=org.apache.hadoop.hive.ql.Driver>
2017-03-09 18:12:10,516 INFO  [HiveServer2-Handler-Pool: Thread-3835]: parse.ParseDriver (ParseDriver.java:parse(185)) - Parsing command: SHOW TABLES IN default '*'
2017-03-09 18:12:10,516 INFO  [HiveServer2-Handler-Pool: Thread-3835]: parse.ParseDriver (ParseDriver.java:parse(209)) - Parse Completed
2017-03-09 18:12:10,516 INFO  [HiveServer2-Handler-Pool: Thread-3835]: log.PerfLogger (PerfLogger.java:PerfLogEnd(176)) - </PERFLOG method=parse start=1489054330516 end=1489054330516 duration=0 from=org.apache.hadoop.hive.ql.Driver>
2017-03-09 18:12:10,517 INFO  [HiveServer2-Handler-Pool: Thread-3835]: log.PerfLogger (PerfLogger.java:PerfLogBegin(148)) - <PERFLOG method=semanticAnalyze from=org.apache.hadoop.hive.ql.Driver>
2017-03-09 18:12:10,517 INFO  [HiveServer2-Handler-Pool: Thread-3835]: metastore.HiveMetaStore (HiveMetaStore.java:logInfo(822)) - 102: get_database: default
2017-03-09 18:12:10,518 INFO  [HiveServer2-Handler-Pool: Thread-3835]: HiveMetaStore.audit (HiveMetaStore.java:logAuditEvent(391)) - ugi=test_sh	ip=unknown-ip-addr	cmd=get_database: default	
2017-03-09 18:12:10,531 INFO  [HiveServer2-Handler-Pool: Thread-3835]: metastore.HiveMetaStore (HiveMetaStore.java:logInfo(822)) - 102: get_database: default
2017-03-09 18:12:10,532 INFO  [HiveServer2-Handler-Pool: Thread-3835]: HiveMetaStore.audit (HiveMetaStore.java:logAuditEvent(391)) - ugi=test_sh	ip=unknown-ip-addr	cmd=get_database: default	
2017-03-09 18:12:10,536 INFO  [HiveServer2-Handler-Pool: Thread-3835]: ql.Driver (Driver.java:compile(476)) - Semantic Analysis Completed
2017-03-09 18:12:10,536 INFO  [HiveServer2-Handler-Pool: Thread-3835]: log.PerfLogger (PerfLogger.java:PerfLogEnd(176)) - </PERFLOG method=semanticAnalyze start=1489054330517 end=1489054330536 duration=19 from=org.apache.hadoop.hive.ql.Driver>
2017-03-09 18:12:10,537 INFO  [HiveServer2-Handler-Pool: Thread-3835]: exec.ListSinkOperator (Operator.java:initialize(333)) - Initializing operator OP[206]
2017-03-09 18:12:10,538 INFO  [HiveServer2-Handler-Pool: Thread-3835]: ql.Driver (Driver.java:getSchema(252)) - Returning Hive schema: Schema(fieldSchemas:[FieldSchema(name:tab_name, type:string, comment:from deserializer)], properties:null)
2017-03-09 18:12:10,538 INFO  [HiveServer2-Handler-Pool: Thread-3835]: log.PerfLogger (PerfLogger.java:PerfLogEnd(176)) - </PERFLOG method=compile start=1489054330515 end=1489054330538 duration=23 from=org.apache.hadoop.hive.ql.Driver>
2017-03-09 18:12:10,538 INFO  [HiveServer2-Handler-Pool: Thread-3835]: ql.Driver (Driver.java:compile(558)) - We are resetting the hadoop caller context to HIVE_SSN_ID:a4da896d-4863-4629-bd31-dfa5e4081316
2017-03-09 18:12:10,539 INFO  [HiveServer2-Handler-Pool: Thread-3835]: session.HiveSessionImpl (HiveSessionImpl.java:releaseBeforeOpLock(356)) - We are resetting the hadoop caller context for thread HiveServer2-Handler-Pool: Thread-3835
2017-03-09 18:12:10,540 INFO  [HiveServer2-Background-Pool: Thread-3844]: ql.Driver (Driver.java:checkConcurrency(172)) - Concurrency mode is disabled, not creating a lock manager
2017-03-09 18:12:10,540 INFO  [HiveServer2-Background-Pool: Thread-3844]: log.PerfLogger (PerfLogger.java:PerfLogBegin(148)) - <PERFLOG method=Driver.execute from=org.apache.hadoop.hive.ql.Driver>
2017-03-09 18:12:10,541 INFO  [HiveServer2-Background-Pool: Thread-3844]: ql.Driver (Driver.java:execute(1406)) - Setting caller context to query id hive_20170309181210_5d9929c8-352c-4360-b2bc-30bea3e14154
2017-03-09 18:12:10,541 INFO  [HiveServer2-Background-Pool: Thread-3844]: ql.Driver (Driver.java:execute(1409)) - Starting command(queryId=hive_20170309181210_5d9929c8-352c-4360-b2bc-30bea3e14154): SHOW TABLES IN default '*'
2017-03-09 18:12:10,541 INFO  [HiveServer2-Background-Pool: Thread-3844]: hooks.ATSHook (ATSHook.java:<init>(90)) - Created ATS Hook
2017-03-09 18:12:10,541 INFO  [HiveServer2-Background-Pool: Thread-3844]: log.PerfLogger (PerfLogger.java:PerfLogBegin(148)) - <PERFLOG method=PreHook.org.apache.hadoop.hive.ql.hooks.ATSHook from=org.apache.hadoop.hive.ql.Driver>
2017-03-09 18:12:10,541 INFO  [HiveServer2-Background-Pool: Thread-3844]: log.PerfLogger (PerfLogger.java:PerfLogEnd(176)) - </PERFLOG method=PreHook.org.apache.hadoop.hive.ql.hooks.ATSHook start=1489054330541 end=1489054330541 duration=0 from=org.apache.hadoop.hive.ql.Driver>
2017-03-09 18:12:10,541 INFO  [HiveServer2-Background-Pool: Thread-3844]: log.PerfLogger (PerfLogger.java:PerfLogBen(148)) - <PERFLOG method=runTasks from=org.apache.hadoop.hive.ql.Driver>
2017-03-09 18:12:10,542 INFO  [HiveServer2-Background-Pool: Thread-3844]: ql.Driver (Driver.java:launchTask(1743)) - Starting task [Stage-0:DDL] in serial mode
2017-03-09 18:12:10,542 INFO  [HiveServer2-Background-Pool: Thread-3844]: metastore.HiveMetaStore (HiveMetaStore.java:logInfo(822)) - 104: get_database: default
2017-03-09 18:12:10,542 INFO  [HiveServer2-Background-Pool: Thread-3844]: HiveMetaStore.audit (HiveMetaStore.java:logAuditEvent(391)) - ugi=test_sh	ip=unknown-ip-addr	cmd=get_database: default	
2017-03-09 18:12:10,542 INFO  [HiveServer2-Background-Pool: Thread-3844]: metastore.HiveMetaStore (HiveMetaStore.java:newRawStore(665)) - 104: Opening raw store with implemenation class:org.apache.hadoop.hive.metastore.ObjectStore
2017-03-09 18:12:10,543 INFO  [HiveServer2-Background-Pool: Thread-3844]: metastore.ObjectStore (ObjectStore.java:initialize(299)) - ObjectStore, initialize called
2017-03-09 18:12:10,558 INFO  [HiveServer2-Background-Pool: Thread-3844]: metastore.MetaStoreDirectSql (MetaStoreDirectSql.java:<init>(139)) - Using direct SQL, underlying DB is MYSQL
2017-03-09 18:12:10,559 INFO  [HiveServer2-Background-Pool: Thread-3844]: metastore.ObjectStore (ObjectStore.java:setConf(282)) - Initialized ObjectStore
2017-03-09 18:12:10,562 INFO  [HiveServer2-Background-Pool: Thread-3844]: metadata.HiveUtils (HiveUtils.java:getMetaStoreAuthorizeProviderManagers(355)) - Adding metastore authorization provider: org.apache.hadoop.hive.ql.security.authorization.StorageBasedAuthorizationProvider
2017-03-09 18:12:10,564 INFO  [HiveServer2-Background-Pool: Thread-3844]: exec.DDLTask (DDLTask.java:showTables(2330)) - pattern: *
2017-03-09 18:12:10,564 INFO  [HiveServer2-Background-Pool: Thread-3844]: metastore.HiveMetaStore (HiveMetaStore.java:logInfo(822)) - 104: get_tables: db=default pat=*
2017-03-09 18:12:10,564 INFO  [HiveServer2-Background-Pool: Thread-3844]: HiveMetaStore.audit (HiveMetaStore.java:logAuditEvent(391)) - ugi=test_sh	ip=unknown-ip-addr	cmd=get_tables: db=default pat=*	
2017-03-09 18:12:10,567 INFO  [HiveServer2-Background-Pool: Thread-3844]: exec.DDLTask (DDLTask.java:showTables(2332)) - results : 0
2017-03-09 18:12:10,567 INFO  [HiveServer2-Background-Pool: Thread-3844]: log.PerfLogger (PerfLogger.java:PerfLogEnd(176)) - </PERFLOG method=runTasks start=1489054330541 end=1489054330567 duration=26 from=org.apache.hadoop.hive.ql.Driver>
2017-03-09 18:12:10,567 INFO  [HiveServer2-Background-Pool: Thread-3844]: hooks.ATSHook (ATSHook.java:<init>(90)) - Created ATS Hook
2017-03-09 18:12:10,567 INFO  [HiveServer2-Background-Pool: Thread-3844]: log.PerfLogger (PerfLogger.java:PerfLogBegin(148)) - <PERFLOG method=PostHook.org.apache.hadoop.hive.ql.hooks.ATSHook from=org.apache.hadoop.hive.ql.Driver>
2017-03-09 18:12:10,568 INFO  [HiveServer2-Background-Pool: Thread-3844]: log.PerfLogger (PerfLogger.java:PerfLogEnd(176)) - </PERFLOG method=PostHook.org.apache.hadoop.hive.ql.hooks.ATSHook start=1489054330567 end=1489054330568 duration=1 from=org.apache.hadoop.hive.ql.Driver>
2017-03-09 18:12:10,568 INFO  [HiveServer2-Background-Pool: Thread-3844]: ql.Driver (Driver.java:execute(1635)) - Resetting the caller context to HIVE_SSN_ID:a4da896d-4863-4629-bd31-dfa5e4081316
2017-03-09 18:12:10,568 INFO  [HiveServer2-Background-Pool: Thread-3844]: log.PerfLogger (PerfLogger.java:PerfLogEnd(176)) - </PERFLOG method=Driver.execute start=1489054330540 end=1489054330568 duration=28 from=org.apache.hadoop.hive.ql.Driver>
2017-03-09 18:12:10,568 INFO  [HiveServer2-Background-Pool: Thread-3844]: ql.Driver (SessionState.java:printInfo(939)) - OK
2017-03-09 18:12:10,568 INFO  [HiveServer2-Background-Pool: Thread-3844]: log.PerfLogger (PerfLogger.java:PerfLogBegin(148)) - <PERFLOG method=releaseLocks from=org.apache.hadoop.hive.ql.Driver>
2017-03-09 18:12:10,568 INFO  [HiveServer2-Background-Pool: Thread-3844]: log.PerfLogger (PerfLogger.java:PerfLogEnd(176)) - </PERFLOG method=releaseLocks start=1489054330568 end=1489054330568 duration=0 from=org.apache.hadoop.hive.ql.Driver>
2017-03-09 18:12:10,568 INFO  [HiveServer2-Background-Pool: Thread-3844]: log.PerfLogger (PerfLogger.java:PerfLogEnd(176)) - </PERFLOG method=Driver.run start=1489054330515 end=1489054330568 duration=53 from=org.apache.hadoop.hive.ql.Driver>
2017-03-09 18:12:10,572 INFO  [HiveServer2-Handler-Pool: Thread-3835]: session.HiveSessionImpl (HiveSessionImpl.java:acquireAfterOpLock(332)) - We are setting the hadoop caller context to a4da896d-4863-4629-bd31-dfa5e4081316 for thread HiveServer2-Handler-Pool: Thread-3835
2017-03-09 18:12:10,573 INFO  [HiveServer2-Handler-Pool: Thread-3835]: session.HiveSessionImpl (HiveSessionImpl.java:releaseBeforeOpLock(356)) - We are resetting the hadoop caller context for thread HiveServer2-Handler-Pool: Thread-3835
2017-03-09 18:12:10,576 INFO  [HiveServer2-Handler-Pool: Thread-3835]: session.HiveSessionImpl (HiveSessionImpl.java:acquireAfterOpLock(332)) - We are setting the hadoop caller context to a4da896d-4863-4629-bd31-dfa5e4081316 for thread HiveServer2-Handler-Pool: Thread-3835
2017-03-09 18:12:10,576 INFO  [HiveServer2-Handler-Pool: Thread-3835]: session.HiveSessionImpl (HiveSessionImpl.java:releaseBeforeOpLock(356)) - We are resetting the hadoop caller context for thread HiveServer2-Handler-Pool: Ted-3835
2017-03-09 18:12:10,584 INFO  [HiveServer2-Handler-Pool: Thread-3835]: session.HiveSessionImpl (HiveSessionImpl.java:acquireAfterOpLock(332)) - We are setting the hadoop caller context to a4da896d-4863-4629-bd31-dfa5e4081316 for thread HiveServer2-Handler-Pool: Thread-3835
2017-03-09 18:12:10,584 INFO  [HiveServer2-Handler-Pool: Thread-3835]: log.PerfLogger (PerfLogger.java:PerfLogBegin(148)) - <PERFLOG method=releaseLocks from=org.apache.hadoop.hive.ql.Driver>
2017-03-09 18:12:10,584 INFO  [HiveServer2-Handler-Pool: Thread-3835]: log.PerfLogger (PerfLogger.java:PerfLogEnd(176)) - </PERFLOG method=releaseLocks start=1489054330584 end=1489054330584 duration=0 from=org.apache.hadoop.hive.ql.Driver>
2017-03-09 18:12:10,585 INFO  [HiveServer2-Handler-Pool: Thread-3835]: session.HiveSessionImpl (HiveSessionImpl.java:releaseBeforeOpLock(356)) - We are resetting the hadoop caller context for thread HiveServer2-Handler-Pool: Thread-3835