You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@ambari.apache.org by Andrew Onischuk <ao...@hortonworks.com> on 2016/03/03 12:16:12 UTC
Review Request 44330: Accumulo Service Check fails due to timeout
after deployment
-----------------------------------------------------------
This is an automatically generated e-mail. To reply, visit:
https://reviews.apache.org/r/44330/
-----------------------------------------------------------
Review request for Ambari and Dmitro Lisnichenko.
Bugs: AMBARI-15282
https://issues.apache.org/jira/browse/AMBARI-15282
Repository: ambari
Description
-------
After deployment accumulo service check is failing with the below error.
{
"href" : "https://172.22.118.198:8443/api/v1/clusters/cl1/requests/5/tasks/188",
"Tasks" : {
"attempt_cnt" : 1,
"cluster_name" : "cl1",
"command" : "SERVICE_CHECK",
"command_detail" : "SERVICE_CHECK ACCUMULO",
"end_time" : 1456880285591,
"error_log" : "/var/lib/ambari-agent/data/errors-188.txt",
"exit_code" : 1,
"host_name" : "os-r6-ucmvau-ambari-us-21nf-3-5.novalocal",
"id" : 188,
"output_log" : "/var/lib/ambari-agent/data/output-188.txt",
"request_id" : 5,
"role" : "ACCUMULO_SERVICE_CHECK",
"stage_id" : 5,
"start_time" : 1456880242827,
"status" : "FAILED",
"stderr" : "Traceback (most recent call last):\n File \"/var/lib/ambari-agent/cache/common-services/ACCUMULO/1.6.1.2.2.0/package/scripts/service_check.py\", line 64, in <module>\n AccumuloServiceCheck().execute()\n File \"/usr/lib/python2.6/site-packages/resource_management/libraries/script/script.py\", line 219, in execute\n method(env)\n File \"/var/lib/ambari-agent/cache/common-services/ACCUMULO/1.6.1.2.2.0/package/scripts/service_check.py\", line 53, in service_check\n logoutput=True)\n File \"/usr/lib/python2.6/site-packages/resource_management/core/base.py\", line 154, in __init__\n self.env.run()\n File \"/usr/lib/python2.6/site-packages/resource_management/core/environment.py\", line 158, in run\n self.run_action(resource, action)\n File \"/usr/lib/python2.6/site-packages/resource_management/core/environment.py\", line 121, in run_action\n provider_action()\n File \"/usr/lib/python2.6/site-packages/resource_management/core/providers/system.py\
", line 238, in action_run\n tries=self.resource.tries, try_sleep=self.resource.try_sleep)\n File \"/usr/lib/python2.6/site-packages/resource_management/core/shell.py\", line 70, in inner\n result = function(command, **kwargs)\n File \"/usr/lib/python2.6/site-packages/resource_management/core/shell.py\", line 92, in checked_call\n tries=tries, try_sleep=try_sleep)\n File \"/usr/lib/python2.6/site-packages/resource_management/core/shell.py\", line 140, in _call_wrapper\n result = _call(command, **kwargs_copy)\n File \"/usr/lib/python2.6/site-packages/resource_management/core/shell.py\", line 285, in _call\n raise ExecuteTimeoutException(err_msg)\nresource_management.core.exceptions.ExecuteTimeoutException: Execution of 'ambari-sudo.sh su ambari-qa -l -s /bin/bash -c 'export PATH='\"'\"'/usr/sbin:/sbin:/usr/lib/ambari-server/*:/usr/lib64/qt-3.3/bin:/usr/local/bin:/bin:/usr/bin:/usr/local/sbin:/usr/sbin:/sbin:/home/agentslava/bin:/var/lib/ambari-agent'\"'\"' ; /usr/
hdp/current/accumulo-client/bin/accumulo shell -u ambari-qa -p smoke -f /var/lib/ambari-agent/tmp/cmds'' was killed due timeout after 30 seconds",
"stdout" : "2016-03-02 00:57:33,504 - Using hadoop conf dir: /usr/hdp/current/hadoop-client/conf\n2016-03-02 00:57:33,512 - File['/var/lib/ambari-agent/tmp/cmds'] {'content': InlineTemplate(...), 'owner': 'ambari-qa', 'group': 'hadoop', 'mode': 0600}\n2016-03-02 00:57:33,578 - Writing File['/var/lib/ambari-agent/tmp/cmds'] because it doesn't exist\n2016-03-02 00:57:33,627 - Changing owner for /var/lib/ambari-agent/tmp/cmds from 0 to ambari-qa\n2016-03-02 00:57:33,628 - Changing group for /var/lib/ambari-agent/tmp/cmds from 0 to hadoop\n2016-03-02 00:57:33,667 - Changing permission for /var/lib/ambari-agent/tmp/cmds from 640 to 600\n2016-03-02 00:57:33,689 - Execute['/usr/hdp/current/accumulo-client/bin/accumulo shell -u ambari-qa -p smoke -f /var/lib/ambari-agent/tmp/cmds'] {'logoutput': True, 'user': 'ambari-qa', 'timeout': 30}\n######## Hortonworks #############\nThis is MOTD message, added for testing in qe infra\n2016-03-02 00:57:36,175 [trace.DistributedTrace] INFO : Spa
nReceiver org.apache.accumulo.tracer.ZooTraceClient was loaded successfully.\nrow cf:cq [] val\n2016-03-02 00:57:38,606 [shell.Shell] INFO : Flush of table testtable completed.\nrow cf:cq [] val",
"structured_out" : { }
}
This issue is intermittent and is not reproduced in all the runs
Noticed the below in accumulo logs
2016-03-02 01:07:51,101 [tserver.TabletServer] INFO : Waiting for tablet server lock
2016-03-02 01:07:52,654 [tserver.TabletServer] WARN : Got getScans message from master before lock acquired, ignoring...
2016-03-02 01:07:52,659 [tserver.TabletServer$ThriftClientHandler] ERROR: Lock not acquired
java.lang.RuntimeException: Lock not acquired
at org.apache.accumulo.tserver.TabletServer$ThriftClientHandler.checkPermission(TabletServer.java:1410)
at org.apache.accumulo.tserver.TabletServer$ThriftClientHandler.getActiveScans(TabletServer.java:1632)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:497)
at org.apache.accumulo.core.trace.wrappers.RpcServerInvocationHandler.invoke(RpcServerInvocationHandler.java:39)
at org.apache.accumulo.server.rpc.RpcWrapper$1.invoke(RpcWrapper.java:73)
at com.sun.proxy.$Proxy21.getActiveScans(Unknown Source)
at org.apache.accumulo.core.tabletserver.thrift.TabletClientService$Processor$getActiveScans.getResult(TabletClientService.java:2864)
at org.apache.accumulo.core.tabletserver.thrift.TabletClientService$Processor$getActiveScans.getResult(TabletClientService.java:2848)
at org.apache.thrift.ProcessFunction.process(ProcessFunction.java:39)
at org.apache.thrift.TBaseProcessor.process(TBaseProcessor.java:39)
at org.apache.accumulo.server.rpc.TimedProcessor.process(TimedProcessor.java:63)
at org.apache.thrift.server.AbstractNonblockingServer$FrameBuffer.invoke(AbstractNonblockingServer.java:516)
at org.apache.accumulo.server.rpc.CustomNonBlockingServer$1.run(CustomNonBlockingServer.java:78)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at org.apache.accumulo.fate.util.LoggingRunnable.run(LoggingRunnable.java:35)
at java.lang.Thread.run(Thread.java:745)
2016-03-02 01:07:52,668 [thrift.ProcessFunction] ERROR: Internal error processing getActiveScans
org.apache.thrift.TException: Lock not acquired
at org.apache.accumulo.server.rpc.RpcWrapper$1.invoke(RpcWrapper.java:80)
at com.sun.proxy.$Proxy21.getActiveScans(Unknown Source)
at org.apache.accumulo.core.tabletserver.thrift.TabletClientService$Processor$getActiveScans.getResult(TabletClientService.java:2864)
at org.apache.accumulo.core.tabletserver.thrift.TabletClientService$Processor$getActiveScans.getResult(TabletClientService.java:2848)
at org.apache.thrift.ProcessFunction.process(ProcessFunction.java:39)
at org.apache.thrift.TBaseProcessor.process(TBaseProcessor.java:39)
at org.apache.accumulo.server.rpc.TimedProcessor.process(TimedProcessor.java:63)
at org.apache.thrift.server.AbstractNonblockingServer$FrameBuffer.invoke(AbstractNonblockingServer.java:516)
at org.apache.accumulo.server.rpc.CustomNonBlockingServer$1.run(CustomNonBlockingServer.java:78)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at org.apache.accumulo.fate.util.LoggingRunnable.run(LoggingRunnable.java:35)
at java.lang.Thread.run(Thread.java:745)
2016-03-02 01:07:56,118 [tserver.TabletServer] INFO : Waiting for tablet server lock
2016-03-02 01:07:57,706 [tserver.TabletServer] WARN : Got getScans message from master before lock acquired, ignoring...
2016-03-02 01:07:57,707 [tserver.TabletServer$ThriftClientHandler] ERROR: Lock not acquired
java.lang.RuntimeException: Lock not acquired
at org.apache.accumulo.tserver.TabletServer$ThriftClientHandler.checkPermission(TabletServer.java:1410)
at org.apache.accumulo.tserver.TabletServer$ThriftClientHandler.getActiveScans(TabletServer.java:1632)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:497)
at org.apache.accumulo.core.trace.wrappers.RpcServerInvocationHandler.invoke(RpcServerInvocationHandler.java:39)
at org.apache.accumulo.server.rpc.RpcWrapper$1.invoke(RpcWrapper.java:73)
at com.sun.proxy.$Proxy21.getActiveScans(Unknown Source)
at org.apache.accumulo.core.tabletserver.thrift.TabletClientService$Processor$getActiveScans.getResult(TabletClientService.java:2864)
at org.apache.accumulo.core.tabletserver.thrift.TabletClientService$Processor$getActiveScans.getResult(TabletClientService.java:2848)
at org.apache.thrift.ProcessFunction.process(ProcessFunction.java:39)
at org.apache.thrift.TBaseProcessor.process(TBaseProcessor.java:39)
at org.apache.accumulo.server.rpc.TimedProcessor.process(TimedProcessor.java:63)
at org.apache.thrift.server.AbstractNonblockingServer$FrameBuffer.invoke(AbstractNonblockingServer.java:516)
at org.apache.accumulo.server.rpc.CustomNonBlockingServer$1.run(CustomNonBlockingServer.java:78)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at org.apache.accumulo.fate.util.LoggingRunnable.run(LoggingRunnable.java:35)
at java.lang.Thread.run(Thread.java:745)
2016-03-02 01:07:57,710 [thrift.ProcessFunction] ERROR: Internal error processing getActiveScans
org.apache.thrift.TException: Lock not acquired
at org.apache.accumulo.server.rpc.RpcWrapper$1.invoke(RpcWrapper.java:80)
at com.sun.proxy.$Proxy21.getActiveScans(Unknown Source)
at org.apache.accumulo.core.tabletserver.thrift.TabletClientService$Processor$getActiveScans.getResult(TabletClientService.java:2864)
at org.apache.accumulo.core.tabletserver.thrift.TabletClientService$Processor$getActiveScans.getResult(TabletClientService.java:2848)
at org.apache.thrift.ProcessFunction.process(ProcessFunction.java:39)
at org.apache.thrift.TBaseProcessor.process(TBaseProcessor.java:39)
at org.apache.accumulo.server.rpc.TimedProcessor.process(TimedProcessor.java:63)
at org.apache.thrift.server.AbstractNonblockingServer$FrameBuffer.invoke(AbstractNonblockingServer.java:516)
at org.apache.accumulo.server.rpc.CustomNonBlockingServer$1.run(CustomNonBlockingServer.java:78)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at org.apache.accumulo.fate.util.LoggingRunnable.run(LoggingRunnable.java:35)
at java.lang.Thread.run(Thread.java:745)
2016-03-02 01:08:01,137 [tserver.TabletServer] INFO : Waiting for tablet server lock
2016-03-02 01:08:02,730 [tserver.TabletServer] WARN : Got getScans message from master before lock acquired, ignoring...
2016-03-02 01:08:02,731 [tserver.TabletServer$ThriftClientHandler] ERROR: Lock not acquired
java.lang.RuntimeException: Lock not acquired
at org.apache.accumulo.tserver.TabletServer$ThriftClientHandler.checkPermission(TabletServer.java:1410)
at org.apache.accumulo.tserver.TabletServer$ThriftClientHandler.getActiveScans(TabletServer.java:1632)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:497)
at org.apache.accumulo.core.trace.wrappers.RpcServerInvocationHandler.invoke(RpcServerInvocationHandler.java:39)
at org.apache.accumulo.server.rpc.RpcWrapper$1.invoke(RpcWrapper.java:73)
at com.sun.proxy.$Proxy21.getActiveScans(Unknown Source)
at org.apache.accumulo.core.tabletserver.thrift.TabletClientService$Processor$getActiveScans.getResult(TabletClientService.java:2864)
at org.apache.accumulo.core.tabletserver.thrift.TabletClientService$Processor$getActiveScans.getResult(TabletClientService.java:2848)
at org.apache.thrift.ProcessFunction.process(ProcessFunction.java:39)
at org.apache.thrift.TBaseProcessor.process(TBaseProcessor.java:39)
at org.apache.accumulo.server.rpc.TimedProcessor.process(TimedProcessor.java:63)
at org.apache.thrift.server.AbstractNonblockingServer$FrameBuffer.invoke(AbstractNonblockingServer.java:516)
at org.apache.accumulo.server.rpc.CustomNonBlockingServer$1.run(CustomNonBlockingServer.java:78)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at org.apache.accumulo.fate.util.LoggingRunnable.run(LoggingRunnable.java:35)
at java.lang.Thread.run(Thread.java:745)
2016-03-02 01:08:02,732 [thrift.ProcessFunction] ERROR: Internal error processing getActiveScans
org.apache.thrift.TException: Lock not acquired
at org.apache.accumulo.server.rpc.RpcWrapper$1.invoke(RpcWrapper.java:80)
at com.sun.proxy.$Proxy21.getActiveScans(Unknown Source)
at org.apache.accumulo.core.tabletserver.thrift.TabletClientService$Processor$getActiveScans.getResult(TabletClientService.java:2864)
at org.apache.accumulo.core.tabletserver.thrift.TabletClientService$Processor$getActiveScans.getResult(TabletClientService.java:2848)
at org.apache.thrift.ProcessFunction.process(ProcessFunction.java:39)
at org.apache.thrift.TBaseProcessor.process(TBaseProcessor.java:39)
at org.apache.accumulo.server.rpc.TimedProcessor.process(TimedProcessor.java:63)
at org.apache.thrift.server.AbstractNonblockingServer$FrameBuffer.invoke(AbstractNonblockingServer.java:516)
at org.apache.accumulo.server.rpc.CustomNonBlockingServer$1.run(CustomNonBlockingServer.java:78)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at org.apache.accumulo.fate.util.LoggingRunnable.run(LoggingRunnable.java:35)
at java.lang.Thread.run(Thread.java:745)
2016-03-02 01:08:06,162 [tserver.TabletServer] INFO : Waiting for tablet server lock
2016-03-02 01:08:07,754 [tserver.TabletServer] WARN : Got getScans message from master before lock acquired, ignoring...
2016-03-02 01:08:07,755 [tserver.TabletServer$ThriftClientHandler] ERROR: Lock not acquired
java.lang.RuntimeException: Lock not acquired
at org.apache.accumulo.tserver.TabletServer$ThriftClientHandler.checkPermission(TabletServer.java:1410)
at org.apache.accumulo.tserver.TabletServer$ThriftClientHandler.getActiveScans(TabletServer.java:1632)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:497)
at org.apache.accumulo.core.trace.wrappers.RpcServerInvocationHandler.invoke(RpcServerInvocationHandler.java:39)
at org.apache.accumulo.server.rpc.RpcWrapper$1.invoke(RpcWrapper.java:73)
at com.sun.proxy.$Proxy21.getActiveScans(Unknown Source)
at org.apache.accumulo.core.tabletserver.thrift.TabletClientService$Processor$getActiveScans.getResult(TabletClientService.java:2864)
at org.apache.accumulo.core.tabletserver.thrift.TabletClientService$Processor$getActiveScans.getResult(TabletClientService.java:2848)
at org.apache.thrift.ProcessFunction.process(ProcessFunction.java:39)
at org.apache.thrift.TBaseProcessor.process(TBaseProcessor.java:39)
at org.apache.accumulo.server.rpc.TimedProcessor.process(TimedProcessor.java:63)
at org.apache.thrift.server.AbstractNonblockingServer$FrameBuffer.invoke(AbstractNonblockingServer.java:516)
at org.apache.accumulo.server.rpc.CustomNonBlockingServer$1.run(CustomNonBlockingServer.java:78)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at org.apache.accumulo.fate.util.LoggingRunnable.run(LoggingRunnable.java:35)
at java.lang.Thread.run(Thread.java:745)
2016-03-02 01:08:07,759 [thrift.ProcessFunction] ERROR: Internal error processing getActiveScans
org.apache.thrift.TException: Lock not acquired
at org.apache.accumulo.server.rpc.RpcWrapper$1.invoke(RpcWrapper.java:80)
at com.sun.proxy.$Proxy21.getActiveScans(Unknown Source)
at org.apache.accumulo.core.tabletserver.thrift.TabletClientService$Processor$getActiveScans.getResult(TabletClientService.java:2864)
at org.apache.accumulo.core.tabletserver.thrift.TabletClientService$Processor$getActiveScans.getResult(TabletClientService.java:2848)
at org.apache.thrift.ProcessFunction.process(ProcessFunction.java:39)
at org.apache.thrift.TBaseProcessor.process(TBaseProcessor.java:39)
at org.apache.accumulo.server.rpc.TimedProcessor.process(TimedProcessor.java:63)
at org.apache.thrift.server.AbstractNonblockingServer$FrameBuffer.invoke(AbstractNonblockingServer.java:516)
at org.apache.accumulo.server.rpc.CustomNonBlockingServer$1.run(CustomNonBlockingServer.java:78)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
at org.apache.accumulo.fate.util.LoggingRunnable.run(LoggingRunnable.java:35)
at java.lang.Thread.run(Thread.java:745)
2016-03-02 01:08:11,247 [tserver.TabletServer] INFO : Started replication service on os-r6-ucmvau-ambari-us-21nf-3-5.novalocal:10002
2016-03-02 01:17:49,532 [server.Accumulo] WARN : System swappiness setting is greater than ten (60) which can cause time-sensitive operations to be delayed. Accumulo is time sensitive because it needs to maintain distributed lock agreement.
Please help take a look so that we can avoid this intermittent issues as well.
Diffs
-----
ambari-server/src/main/resources/common-services/ACCUMULO/1.6.1.2.2.0/package/scripts/service_check.py 91b2fcd
Diff: https://reviews.apache.org/r/44330/diff/
Testing
-------
mvn clean test
Thanks,
Andrew Onischuk
Re: Review Request 44330: Accumulo Service Check fails due to timeout
after deployment
Posted by Dmitro Lisnichenko <dl...@hortonworks.com>.
-----------------------------------------------------------
This is an automatically generated e-mail. To reply, visit:
https://reviews.apache.org/r/44330/#review121828
-----------------------------------------------------------
Ship it!
Ship It!
- Dmitro Lisnichenko
On March 3, 2016, 1:16 p.m., Andrew Onischuk wrote:
>
> -----------------------------------------------------------
> This is an automatically generated e-mail. To reply, visit:
> https://reviews.apache.org/r/44330/
> -----------------------------------------------------------
>
> (Updated March 3, 2016, 1:16 p.m.)
>
>
> Review request for Ambari and Dmitro Lisnichenko.
>
>
> Bugs: AMBARI-15282
> https://issues.apache.org/jira/browse/AMBARI-15282
>
>
> Repository: ambari
>
>
> Description
> -------
>
> After deployment accumulo service check is failing with the below error.
>
>
>
>
> {
> "href" : "https://172.22.118.198:8443/api/v1/clusters/cl1/requests/5/tasks/188",
> "Tasks" : {
> "attempt_cnt" : 1,
> "cluster_name" : "cl1",
> "command" : "SERVICE_CHECK",
> "command_detail" : "SERVICE_CHECK ACCUMULO",
> "end_time" : 1456880285591,
> "error_log" : "/var/lib/ambari-agent/data/errors-188.txt",
> "exit_code" : 1,
> "host_name" : "os-r6-ucmvau-ambari-us-21nf-3-5.novalocal",
> "id" : 188,
> "output_log" : "/var/lib/ambari-agent/data/output-188.txt",
> "request_id" : 5,
> "role" : "ACCUMULO_SERVICE_CHECK",
> "stage_id" : 5,
> "start_time" : 1456880242827,
> "status" : "FAILED",
> "stderr" : "Traceback (most recent call last):\n File \"/var/lib/ambari-agent/cache/common-services/ACCUMULO/1.6.1.2.2.0/package/scripts/service_check.py\", line 64, in <module>\n AccumuloServiceCheck().execute()\n File \"/usr/lib/python2.6/site-packages/resource_management/libraries/script/script.py\", line 219, in execute\n method(env)\n File \"/var/lib/ambari-agent/cache/common-services/ACCUMULO/1.6.1.2.2.0/package/scripts/service_check.py\", line 53, in service_check\n logoutput=True)\n File \"/usr/lib/python2.6/site-packages/resource_management/core/base.py\", line 154, in __init__\n self.env.run()\n File \"/usr/lib/python2.6/site-packages/resource_management/core/environment.py\", line 158, in run\n self.run_action(resource, action)\n File \"/usr/lib/python2.6/site-packages/resource_management/core/environment.py\", line 121, in run_action\n provider_action()\n File \"/usr/lib/python2.6/site-packages/resource_management/core/providers/system.p
y\", line 238, in action_run\n tries=self.resource.tries, try_sleep=self.resource.try_sleep)\n File \"/usr/lib/python2.6/site-packages/resource_management/core/shell.py\", line 70, in inner\n result = function(command, **kwargs)\n File \"/usr/lib/python2.6/site-packages/resource_management/core/shell.py\", line 92, in checked_call\n tries=tries, try_sleep=try_sleep)\n File \"/usr/lib/python2.6/site-packages/resource_management/core/shell.py\", line 140, in _call_wrapper\n result = _call(command, **kwargs_copy)\n File \"/usr/lib/python2.6/site-packages/resource_management/core/shell.py\", line 285, in _call\n raise ExecuteTimeoutException(err_msg)\nresource_management.core.exceptions.ExecuteTimeoutException: Execution of 'ambari-sudo.sh su ambari-qa -l -s /bin/bash -c 'export PATH='\"'\"'/usr/sbin:/sbin:/usr/lib/ambari-server/*:/usr/lib64/qt-3.3/bin:/usr/local/bin:/bin:/usr/bin:/usr/local/sbin:/usr/sbin:/sbin:/home/agentslava/bin:/var/lib/ambari-agent'\"'\"' ; /us
r/hdp/current/accumulo-client/bin/accumulo shell -u ambari-qa -p smoke -f /var/lib/ambari-agent/tmp/cmds'' was killed due timeout after 30 seconds",
> "stdout" : "2016-03-02 00:57:33,504 - Using hadoop conf dir: /usr/hdp/current/hadoop-client/conf\n2016-03-02 00:57:33,512 - File['/var/lib/ambari-agent/tmp/cmds'] {'content': InlineTemplate(...), 'owner': 'ambari-qa', 'group': 'hadoop', 'mode': 0600}\n2016-03-02 00:57:33,578 - Writing File['/var/lib/ambari-agent/tmp/cmds'] because it doesn't exist\n2016-03-02 00:57:33,627 - Changing owner for /var/lib/ambari-agent/tmp/cmds from 0 to ambari-qa\n2016-03-02 00:57:33,628 - Changing group for /var/lib/ambari-agent/tmp/cmds from 0 to hadoop\n2016-03-02 00:57:33,667 - Changing permission for /var/lib/ambari-agent/tmp/cmds from 640 to 600\n2016-03-02 00:57:33,689 - Execute['/usr/hdp/current/accumulo-client/bin/accumulo shell -u ambari-qa -p smoke -f /var/lib/ambari-agent/tmp/cmds'] {'logoutput': True, 'user': 'ambari-qa', 'timeout': 30}\n######## Hortonworks #############\nThis is MOTD message, added for testing in qe infra\n2016-03-02 00:57:36,175 [trace.DistributedTrace] INFO : S
panReceiver org.apache.accumulo.tracer.ZooTraceClient was loaded successfully.\nrow cf:cq [] val\n2016-03-02 00:57:38,606 [shell.Shell] INFO : Flush of table testtable completed.\nrow cf:cq [] val",
> "structured_out" : { }
> }
>
>
> This issue is intermittent and is not reproduced in all the runs
> Noticed the below in accumulo logs
>
>
>
>
> 2016-03-02 01:07:51,101 [tserver.TabletServer] INFO : Waiting for tablet server lock
> 2016-03-02 01:07:52,654 [tserver.TabletServer] WARN : Got getScans message from master before lock acquired, ignoring...
> 2016-03-02 01:07:52,659 [tserver.TabletServer$ThriftClientHandler] ERROR: Lock not acquired
> java.lang.RuntimeException: Lock not acquired
> at org.apache.accumulo.tserver.TabletServer$ThriftClientHandler.checkPermission(TabletServer.java:1410)
> at org.apache.accumulo.tserver.TabletServer$ThriftClientHandler.getActiveScans(TabletServer.java:1632)
> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> at java.lang.reflect.Method.invoke(Method.java:497)
> at org.apache.accumulo.core.trace.wrappers.RpcServerInvocationHandler.invoke(RpcServerInvocationHandler.java:39)
> at org.apache.accumulo.server.rpc.RpcWrapper$1.invoke(RpcWrapper.java:73)
> at com.sun.proxy.$Proxy21.getActiveScans(Unknown Source)
> at org.apache.accumulo.core.tabletserver.thrift.TabletClientService$Processor$getActiveScans.getResult(TabletClientService.java:2864)
> at org.apache.accumulo.core.tabletserver.thrift.TabletClientService$Processor$getActiveScans.getResult(TabletClientService.java:2848)
> at org.apache.thrift.ProcessFunction.process(ProcessFunction.java:39)
> at org.apache.thrift.TBaseProcessor.process(TBaseProcessor.java:39)
> at org.apache.accumulo.server.rpc.TimedProcessor.process(TimedProcessor.java:63)
> at org.apache.thrift.server.AbstractNonblockingServer$FrameBuffer.invoke(AbstractNonblockingServer.java:516)
> at org.apache.accumulo.server.rpc.CustomNonBlockingServer$1.run(CustomNonBlockingServer.java:78)
> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
> at org.apache.accumulo.fate.util.LoggingRunnable.run(LoggingRunnable.java:35)
> at java.lang.Thread.run(Thread.java:745)
> 2016-03-02 01:07:52,668 [thrift.ProcessFunction] ERROR: Internal error processing getActiveScans
> org.apache.thrift.TException: Lock not acquired
> at org.apache.accumulo.server.rpc.RpcWrapper$1.invoke(RpcWrapper.java:80)
> at com.sun.proxy.$Proxy21.getActiveScans(Unknown Source)
> at org.apache.accumulo.core.tabletserver.thrift.TabletClientService$Processor$getActiveScans.getResult(TabletClientService.java:2864)
> at org.apache.accumulo.core.tabletserver.thrift.TabletClientService$Processor$getActiveScans.getResult(TabletClientService.java:2848)
> at org.apache.thrift.ProcessFunction.process(ProcessFunction.java:39)
> at org.apache.thrift.TBaseProcessor.process(TBaseProcessor.java:39)
> at org.apache.accumulo.server.rpc.TimedProcessor.process(TimedProcessor.java:63)
> at org.apache.thrift.server.AbstractNonblockingServer$FrameBuffer.invoke(AbstractNonblockingServer.java:516)
> at org.apache.accumulo.server.rpc.CustomNonBlockingServer$1.run(CustomNonBlockingServer.java:78)
> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
> at org.apache.accumulo.fate.util.LoggingRunnable.run(LoggingRunnable.java:35)
> at java.lang.Thread.run(Thread.java:745)
> 2016-03-02 01:07:56,118 [tserver.TabletServer] INFO : Waiting for tablet server lock
> 2016-03-02 01:07:57,706 [tserver.TabletServer] WARN : Got getScans message from master before lock acquired, ignoring...
> 2016-03-02 01:07:57,707 [tserver.TabletServer$ThriftClientHandler] ERROR: Lock not acquired
> java.lang.RuntimeException: Lock not acquired
> at org.apache.accumulo.tserver.TabletServer$ThriftClientHandler.checkPermission(TabletServer.java:1410)
> at org.apache.accumulo.tserver.TabletServer$ThriftClientHandler.getActiveScans(TabletServer.java:1632)
> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> at java.lang.reflect.Method.invoke(Method.java:497)
> at org.apache.accumulo.core.trace.wrappers.RpcServerInvocationHandler.invoke(RpcServerInvocationHandler.java:39)
> at org.apache.accumulo.server.rpc.RpcWrapper$1.invoke(RpcWrapper.java:73)
> at com.sun.proxy.$Proxy21.getActiveScans(Unknown Source)
> at org.apache.accumulo.core.tabletserver.thrift.TabletClientService$Processor$getActiveScans.getResult(TabletClientService.java:2864)
> at org.apache.accumulo.core.tabletserver.thrift.TabletClientService$Processor$getActiveScans.getResult(TabletClientService.java:2848)
> at org.apache.thrift.ProcessFunction.process(ProcessFunction.java:39)
> at org.apache.thrift.TBaseProcessor.process(TBaseProcessor.java:39)
> at org.apache.accumulo.server.rpc.TimedProcessor.process(TimedProcessor.java:63)
> at org.apache.thrift.server.AbstractNonblockingServer$FrameBuffer.invoke(AbstractNonblockingServer.java:516)
> at org.apache.accumulo.server.rpc.CustomNonBlockingServer$1.run(CustomNonBlockingServer.java:78)
> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
> at org.apache.accumulo.fate.util.LoggingRunnable.run(LoggingRunnable.java:35)
> at java.lang.Thread.run(Thread.java:745)
> 2016-03-02 01:07:57,710 [thrift.ProcessFunction] ERROR: Internal error processing getActiveScans
> org.apache.thrift.TException: Lock not acquired
> at org.apache.accumulo.server.rpc.RpcWrapper$1.invoke(RpcWrapper.java:80)
> at com.sun.proxy.$Proxy21.getActiveScans(Unknown Source)
> at org.apache.accumulo.core.tabletserver.thrift.TabletClientService$Processor$getActiveScans.getResult(TabletClientService.java:2864)
> at org.apache.accumulo.core.tabletserver.thrift.TabletClientService$Processor$getActiveScans.getResult(TabletClientService.java:2848)
> at org.apache.thrift.ProcessFunction.process(ProcessFunction.java:39)
> at org.apache.thrift.TBaseProcessor.process(TBaseProcessor.java:39)
> at org.apache.accumulo.server.rpc.TimedProcessor.process(TimedProcessor.java:63)
> at org.apache.thrift.server.AbstractNonblockingServer$FrameBuffer.invoke(AbstractNonblockingServer.java:516)
> at org.apache.accumulo.server.rpc.CustomNonBlockingServer$1.run(CustomNonBlockingServer.java:78)
> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
> at org.apache.accumulo.fate.util.LoggingRunnable.run(LoggingRunnable.java:35)
> at java.lang.Thread.run(Thread.java:745)
> 2016-03-02 01:08:01,137 [tserver.TabletServer] INFO : Waiting for tablet server lock
> 2016-03-02 01:08:02,730 [tserver.TabletServer] WARN : Got getScans message from master before lock acquired, ignoring...
> 2016-03-02 01:08:02,731 [tserver.TabletServer$ThriftClientHandler] ERROR: Lock not acquired
> java.lang.RuntimeException: Lock not acquired
> at org.apache.accumulo.tserver.TabletServer$ThriftClientHandler.checkPermission(TabletServer.java:1410)
> at org.apache.accumulo.tserver.TabletServer$ThriftClientHandler.getActiveScans(TabletServer.java:1632)
> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> at java.lang.reflect.Method.invoke(Method.java:497)
> at org.apache.accumulo.core.trace.wrappers.RpcServerInvocationHandler.invoke(RpcServerInvocationHandler.java:39)
> at org.apache.accumulo.server.rpc.RpcWrapper$1.invoke(RpcWrapper.java:73)
> at com.sun.proxy.$Proxy21.getActiveScans(Unknown Source)
> at org.apache.accumulo.core.tabletserver.thrift.TabletClientService$Processor$getActiveScans.getResult(TabletClientService.java:2864)
> at org.apache.accumulo.core.tabletserver.thrift.TabletClientService$Processor$getActiveScans.getResult(TabletClientService.java:2848)
> at org.apache.thrift.ProcessFunction.process(ProcessFunction.java:39)
> at org.apache.thrift.TBaseProcessor.process(TBaseProcessor.java:39)
> at org.apache.accumulo.server.rpc.TimedProcessor.process(TimedProcessor.java:63)
> at org.apache.thrift.server.AbstractNonblockingServer$FrameBuffer.invoke(AbstractNonblockingServer.java:516)
> at org.apache.accumulo.server.rpc.CustomNonBlockingServer$1.run(CustomNonBlockingServer.java:78)
> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
> at org.apache.accumulo.fate.util.LoggingRunnable.run(LoggingRunnable.java:35)
> at java.lang.Thread.run(Thread.java:745)
> 2016-03-02 01:08:02,732 [thrift.ProcessFunction] ERROR: Internal error processing getActiveScans
> org.apache.thrift.TException: Lock not acquired
> at org.apache.accumulo.server.rpc.RpcWrapper$1.invoke(RpcWrapper.java:80)
> at com.sun.proxy.$Proxy21.getActiveScans(Unknown Source)
> at org.apache.accumulo.core.tabletserver.thrift.TabletClientService$Processor$getActiveScans.getResult(TabletClientService.java:2864)
> at org.apache.accumulo.core.tabletserver.thrift.TabletClientService$Processor$getActiveScans.getResult(TabletClientService.java:2848)
> at org.apache.thrift.ProcessFunction.process(ProcessFunction.java:39)
> at org.apache.thrift.TBaseProcessor.process(TBaseProcessor.java:39)
> at org.apache.accumulo.server.rpc.TimedProcessor.process(TimedProcessor.java:63)
> at org.apache.thrift.server.AbstractNonblockingServer$FrameBuffer.invoke(AbstractNonblockingServer.java:516)
> at org.apache.accumulo.server.rpc.CustomNonBlockingServer$1.run(CustomNonBlockingServer.java:78)
> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
> at org.apache.accumulo.fate.util.LoggingRunnable.run(LoggingRunnable.java:35)
> at java.lang.Thread.run(Thread.java:745)
> 2016-03-02 01:08:06,162 [tserver.TabletServer] INFO : Waiting for tablet server lock
> 2016-03-02 01:08:07,754 [tserver.TabletServer] WARN : Got getScans message from master before lock acquired, ignoring...
> 2016-03-02 01:08:07,755 [tserver.TabletServer$ThriftClientHandler] ERROR: Lock not acquired
> java.lang.RuntimeException: Lock not acquired
> at org.apache.accumulo.tserver.TabletServer$ThriftClientHandler.checkPermission(TabletServer.java:1410)
> at org.apache.accumulo.tserver.TabletServer$ThriftClientHandler.getActiveScans(TabletServer.java:1632)
> at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
> at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> at java.lang.reflect.Method.invoke(Method.java:497)
> at org.apache.accumulo.core.trace.wrappers.RpcServerInvocationHandler.invoke(RpcServerInvocationHandler.java:39)
> at org.apache.accumulo.server.rpc.RpcWrapper$1.invoke(RpcWrapper.java:73)
> at com.sun.proxy.$Proxy21.getActiveScans(Unknown Source)
> at org.apache.accumulo.core.tabletserver.thrift.TabletClientService$Processor$getActiveScans.getResult(TabletClientService.java:2864)
> at org.apache.accumulo.core.tabletserver.thrift.TabletClientService$Processor$getActiveScans.getResult(TabletClientService.java:2848)
> at org.apache.thrift.ProcessFunction.process(ProcessFunction.java:39)
> at org.apache.thrift.TBaseProcessor.process(TBaseProcessor.java:39)
> at org.apache.accumulo.server.rpc.TimedProcessor.process(TimedProcessor.java:63)
> at org.apache.thrift.server.AbstractNonblockingServer$FrameBuffer.invoke(AbstractNonblockingServer.java:516)
> at org.apache.accumulo.server.rpc.CustomNonBlockingServer$1.run(CustomNonBlockingServer.java:78)
> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
> at org.apache.accumulo.fate.util.LoggingRunnable.run(LoggingRunnable.java:35)
> at java.lang.Thread.run(Thread.java:745)
> 2016-03-02 01:08:07,759 [thrift.ProcessFunction] ERROR: Internal error processing getActiveScans
> org.apache.thrift.TException: Lock not acquired
> at org.apache.accumulo.server.rpc.RpcWrapper$1.invoke(RpcWrapper.java:80)
> at com.sun.proxy.$Proxy21.getActiveScans(Unknown Source)
> at org.apache.accumulo.core.tabletserver.thrift.TabletClientService$Processor$getActiveScans.getResult(TabletClientService.java:2864)
> at org.apache.accumulo.core.tabletserver.thrift.TabletClientService$Processor$getActiveScans.getResult(TabletClientService.java:2848)
> at org.apache.thrift.ProcessFunction.process(ProcessFunction.java:39)
> at org.apache.thrift.TBaseProcessor.process(TBaseProcessor.java:39)
> at org.apache.accumulo.server.rpc.TimedProcessor.process(TimedProcessor.java:63)
> at org.apache.thrift.server.AbstractNonblockingServer$FrameBuffer.invoke(AbstractNonblockingServer.java:516)
> at org.apache.accumulo.server.rpc.CustomNonBlockingServer$1.run(CustomNonBlockingServer.java:78)
> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
> at org.apache.accumulo.fate.util.LoggingRunnable.run(LoggingRunnable.java:35)
> at java.lang.Thread.run(Thread.java:745)
> 2016-03-02 01:08:11,247 [tserver.TabletServer] INFO : Started replication service on os-r6-ucmvau-ambari-us-21nf-3-5.novalocal:10002
> 2016-03-02 01:17:49,532 [server.Accumulo] WARN : System swappiness setting is greater than ten (60) which can cause time-sensitive operations to be delayed. Accumulo is time sensitive because it needs to maintain distributed lock agreement.
>
>
> Please help take a look so that we can avoid this intermittent issues as well.
>
>
> Diffs
> -----
>
> ambari-server/src/main/resources/common-services/ACCUMULO/1.6.1.2.2.0/package/scripts/service_check.py 91b2fcd
>
> Diff: https://reviews.apache.org/r/44330/diff/
>
>
> Testing
> -------
>
> mvn clean test
>
>
> Thanks,
>
> Andrew Onischuk
>
>