[IMPALA-5235] Query throws a NullPointerException on starting impala cluster with logging_level=3

• Type: Bug
• Status: Resolved
• Priority: Critical
• Resolution: Fixed
• Affects Version/s: Impala 2.9.0
• Fix Version/s: Impala 2.9.0
• Component/s: Frontend
• Labels: regression supportability
• Epic Color: ghx-label-2
• Assignee: Anuj Phadke
• Reporter: Anuj Phadke
• Created: 20/Apr/17 19:57
• Updated: 04/May/17 20:28
• Resolved: 04/May/17 20:28

Description

Ran the same query with log_level=1 and log_level=3.
The same query runs fine with log_level=1 and throws a null pointer exception when log_level=3

Steps to recreate the issue -
1. With logging level set to 1

./bin/start-impala-cluster.py --log_level=1
[localhost:21000] > select straight_join t1.id, t2.id from functional.alltypes t1 inner join functional.alltypes t2 on t1.id = t2.id limit 5;
Query: select straight_join t1.id, t2.id from functional.alltypes t1 inner join functional.alltypes t2 on t1.id = t2.id limit 5
Query submitted at: 2017-04-20 12:38:04 (Coordinator: http://anuj-OptiPlex-9020:25000)
Query progress can be monitored at: http://anuj-OptiPlex-9020:25000/query_plan?query_id=1b4712c36f691117:cb538b9c00000000
--------+

id

id

--------+

592	592
597	597
604	604
606	606
610	610

--------+
Fetched 5 row(s) in 5.01s

2. log level set to 3
anuj@anuj-OptiPlex-9020:~/Impala$ ./bin/start-impala-cluster.py --log_level=3
[localhost:21000] > select straight_join t1.id, t2.id from functional.alltypes t1 inner join functional.alltypes t2 on t1.id = t2.id limit 5;
Query: select straight_join t1.id, t2.id from functional.alltypes t1 inner join functional.alltypes t2 on t1.id = t2.id limit 5
Query submitted at: 2017-04-20 12:38:37 (Coordinator: http://anuj-OptiPlex-9020:25000)
ERROR: NullPointerException: null

Stacktrace from the logs -

I0420 12:38:42.792183 3639 jni-util.cc:176] java.lang.NullPointerException
at org.apache.impala.planner.PlanNode.getExplainString(PlanNode.java:311)
at org.apache.impala.planner.PlanNode.getExplainString(PlanNode.java:243)
at org.apache.impala.planner.DistributedPlanner.createHashJoinFragment(DistributedPlanner.java:477)
at org.apache.impala.planner.DistributedPlanner.createPlanFragments(DistributedPlanner.java:121)
at org.apache.impala.planner.DistributedPlanner.createPlanFragments(DistributedPlanner.java:85)
at org.apache.impala.planner.Planner.createPlan(Planner.java:132)
at org.apache.impala.service.Frontend.createExecRequest(Frontend.java:1005)
at org.apache.impala.service.Frontend.createExecRequest(Frontend.java:1100)
at org.apache.impala.service.JniFrontend.createExecRequest(JniFrontend.java:155)
I0420 12:38:42.830754 3639 status.cc:119] NullPointerException: null
@ 0x1219ac5 impala::Status::Status()
@ 0x15e9c8e impala::JniUtil::GetJniExceptionMsg()
@ 0x14a64ec impala::JniUtil::CallJniMethod<>()
@ 0x14a29fd impala::Frontend::GetExecRequest()
@ 0x14bfd3b impala::ImpalaServer::ExecuteInternal()
@ 0x14bf8e2 impala::ImpalaServer::Execute()
@ 0x152b04e impala::ImpalaServer::query()
@ 0x19e8af6 beeswax::BeeswaxServiceProcessor::process_query()
@ 0x19e8844 beeswax::BeeswaxServiceProcessor::dispatchCall()
@ 0x19d124f impala::ImpalaServiceProcessor::dispatchCall()
@ 0x11c077c apache::thrift::TDispatchProcessor::process()
@ 0x2891b8b apache::thrift::server::TThreadPoolServer::Task::run()
@ 0x287a3f9 apache::thrift::concurrency::ThreadManager::Worker::run()
@ 0x137d943 impala::ThriftThread::RunRunnable()
@ 0x137f06f boost::_mfi::mf2<>::operator()()
@ 0x137ef05 boost::_bi::list3<>::operator()<>()
@ 0x137ec51 boost::_bi::bind_t<>::operator()()
@ 0x137eb64 boost::detail::function::void_function_obj_invoker0<>::invoke()
@ 0x138d248 boost::function0<>::operator()()
@ 0x164293f impala::Thread::SuperviseThread()
@ 0x164b2c2 boost::_bi::list4<>::operator()<>()
@ 0x164b205 boost::_bi::bind_t<>::operator()()
@ 0x164b1c8 boost::detail::thread_data<>::run()
@ 0x1b1058a thread_proxy
@ 0x7f185012d184 start_thread
@ 0x7f184fe5a37d clone