drill-issues mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Khurram Faraaz (JIRA)" <j...@apache.org>
Subject [jira] [Comment Edited] (DRILL-5077) Memory Leak -
Date Mon, 28 Nov 2016 19:29:59 GMT

    [ https://issues.apache.org/jira/browse/DRILL-5077?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=15702863#comment-15702863
] 

Khurram Faraaz edited comment on DRILL-5077 at 11/28/16 7:29 PM:
-----------------------------------------------------------------

To repro the problem.

Run the below query

{noformat}
apache drill 1.9.0
"a little sql for your nosql"
0: jdbc:drill:schema=dfs.tmp> select count(*) from `twoKeyJsn.json`;
Error: CONNECTION ERROR: Connection /10.10.100.201:45748 <--> /10.10.100.201:31010 (user
client) closed unexpectedly. Drillbit down?


[Error Id: 5b7d036e-3e05-463b-a661-8718a8a85e25 ] (state=,code=0)
{noformat}

and then stop the foreman Drillbit while above query is running, like this
{noformat}
[root@centos-01 bin]# pwd
/opt/mapr/drill/drill-1.9.0/bin
[root@centos-01 bin]# ./drillbit.sh stop
Stopping drillbit
.......
[root@centos-01 bin]# grep "Memory" /opt/mapr/drill/drill-1.9.0/logs/drillbit.log
Caused by: java.lang.IllegalStateException: Memory was leaked by query. Memory leaked: (1048576)
{noformat}


was (Author: khfaraaz):
To repro the problem.

Run the below query

{noformat}
apache drill 1.9.0
"a little sql for your nosql"
0: jdbc:drill:schema=dfs.tmp> select count(*) from `twoKeyJsn.json`;
Error: CONNECTION ERROR: Connection /10.10.100.201:45748 <--> /10.10.100.201:31010 (user
client) closed unexpectedly. Drillbit down?


[Error Id: 5b7d036e-3e05-463b-a661-8718a8a85e25 ] (state=,code=0)
{noformat}

and then stop the foreman Drillbit, like this
{noformat}
[root@centos-01 bin]# pwd
/opt/mapr/drill/drill-1.9.0/bin
[root@centos-01 bin]# ./drillbit.sh stop
Stopping drillbit
.......
[root@centos-01 bin]# grep "Memory" /opt/mapr/drill/drill-1.9.0/logs/drillbit.log
Caused by: java.lang.IllegalStateException: Memory was leaked by query. Memory leaked: (1048576)
{noformat}

> Memory Leak - 
> --------------
>
>                 Key: DRILL-5077
>                 URL: https://issues.apache.org/jira/browse/DRILL-5077
>             Project: Apache Drill
>          Issue Type: Bug
>          Components: Execution - Flow
>    Affects Versions: 1.9.0
>         Environment: 4 node cluster CentOS
>            Reporter: Khurram Faraaz
>            Priority: Blocker
>
> terminating foreman drillbit while a query is running results in memory leak
> Drill 1.9.0 git commit id: 4312d65b
> Stack trace from drillbit.log
> {noformat}
> 2016-11-28 06:12:45,338 [27c43522-79ca-989f-3659-d7ccbc77e2e7:foreman] INFO  o.a.drill.exec.work.foreman.Foreman
- Query text for query id 27c43522-79ca-989f-3659-d7ccbc77e2e7: select count(*) from `twoKeyJsn.json`
> 2016-11-28 06:12:45,602 [27c43522-79ca-989f-3659-d7ccbc77e2e7:foreman] INFO  o.a.d.exec.store.dfs.FileSelection
- FileSelection.getStatuses() took 0 ms, numFiles: 1
> 2016-11-28 06:12:45,602 [27c43522-79ca-989f-3659-d7ccbc77e2e7:foreman] INFO  o.a.d.exec.store.dfs.FileSelection
- FileSelection.getStatuses() took 0 ms, numFiles: 1
> 2016-11-28 06:12:45,603 [27c43522-79ca-989f-3659-d7ccbc77e2e7:foreman] INFO  o.a.d.exec.store.dfs.FileSelection
- FileSelection.getStatuses() took 0 ms, numFiles: 1
> 2016-11-28 06:12:45,603 [27c43522-79ca-989f-3659-d7ccbc77e2e7:foreman] INFO  o.a.d.exec.store.dfs.FileSelection
- FileSelection.getStatuses() took 0 ms, numFiles: 1
> 2016-11-28 06:12:45,603 [27c43522-79ca-989f-3659-d7ccbc77e2e7:foreman] INFO  o.a.d.exec.store.dfs.FileSelection
- FileSelection.getStatuses() took 0 ms, numFiles: 1
> 2016-11-28 06:12:45,633 [27c43522-79ca-989f-3659-d7ccbc77e2e7:foreman] INFO  o.a.d.exec.store.dfs.FileSelection
- FileSelection.getStatuses() took 0 ms, numFiles: 1
> 2016-11-28 06:12:45,669 [27c43522-79ca-989f-3659-d7ccbc77e2e7:foreman] INFO  o.a.d.e.s.schedule.BlockMapBuilder
- Get block maps: Executed 1 out of 1 using 1 threads. Time: 33ms total, 33.494123ms avg,
33ms max.
> 2016-11-28 06:12:45,669 [27c43522-79ca-989f-3659-d7ccbc77e2e7:foreman] INFO  o.a.d.e.s.schedule.BlockMapBuilder
- Get block maps: Executed 1 out of 1 using 1 threads. Earliest start: 9.540000 μs, Latest
start: 9.540000 μs, Average start: 9.540000 μs .
> 2016-11-28 06:12:45,913 [27c43522-79ca-989f-3659-d7ccbc77e2e7:frag:0:0] INFO  o.a.d.e.w.fragment.FragmentExecutor
- 27c43522-79ca-989f-3659-d7ccbc77e2e7:0:0: State change requested AWAITING_ALLOCATION -->
RUNNING
> 2016-11-28 06:12:45,913 [27c43522-79ca-989f-3659-d7ccbc77e2e7:frag:0:0] INFO  o.a.d.e.w.f.FragmentStatusReporter
- 27c43522-79ca-989f-3659-d7ccbc77e2e7:0:0: State to report: RUNNING
> Mon Nov 28 06:12:48 UTC 2016 Terminating drillbit pid 28004
> 2016-11-28 06:12:48,697 [Drillbit-ShutdownHook#0] INFO  o.apache.drill.exec.server.Drillbit
- Received shutdown request.
> 2016-11-28 06:12:55,749 [pool-6-thread-2] INFO  o.a.drill.exec.rpc.data.DataServer -
closed eventLoopGroup io.netty.channel.nio.NioEventLoopGroup@15bcfacd in 1017 ms
> 2016-11-28 06:12:55,750 [pool-6-thread-2] INFO  o.a.drill.exec.service.ServiceEngine
- closed dataPool in 1018 ms
> 2016-11-28 06:12:57,749 [Drillbit-ShutdownHook#0] WARN  o.apache.drill.exec.work.WorkManager
- Closing WorkManager but there are 1 running fragments.
> 2016-11-28 06:12:57,751 [27c43522-79ca-989f-3659-d7ccbc77e2e7:frag:0:0] INFO  o.a.d.e.w.fragment.FragmentExecutor
- 27c43522-79ca-989f-3659-d7ccbc77e2e7:0:0: State change requested RUNNING --> FAILED
> 2016-11-28 06:12:57,751 [27c43522-79ca-989f-3659-d7ccbc77e2e7:frag:0:0] INFO  o.a.d.e.w.fragment.FragmentExecutor
- 27c43522-79ca-989f-3659-d7ccbc77e2e7:0:0: State change requested FAILED --> FINISHED
> 2016-11-28 06:12:57,756 [27c43522-79ca-989f-3659-d7ccbc77e2e7:frag:0:0] ERROR o.a.d.e.w.fragment.FragmentExecutor
- SYSTEM ERROR: NullPointerException
> Fragment 0:0
> [Error Id: 2df2f9a1-a7bf-4454-a31b-717ab4ebd815 on centos-01.qa.lab:31010]
> org.apache.drill.common.exceptions.UserException: SYSTEM ERROR: NullPointerException
> Fragment 0:0
> [Error Id: 2df2f9a1-a7bf-4454-a31b-717ab4ebd815 on centos-01.qa.lab:31010]
>         at org.apache.drill.common.exceptions.UserException$Builder.build(UserException.java:543)
~[drill-common-1.9.0.jar:1.9.0]
>         at org.apache.drill.exec.work.fragment.FragmentExecutor.sendFinalState(FragmentExecutor.java:293)
[drill-java-exec-1.9.0.jar:1.9.0]
>         at org.apache.drill.exec.work.fragment.FragmentExecutor.cleanup(FragmentExecutor.java:160)
[drill-java-exec-1.9.0.jar:1.9.0]
>         at org.apache.drill.exec.work.fragment.FragmentExecutor.run(FragmentExecutor.java:262)
[drill-java-exec-1.9.0.jar:1.9.0]
>         at org.apache.drill.common.SelfCleaningRunnable.run(SelfCleaningRunnable.java:38)
[drill-common-1.9.0.jar:1.9.0]
>         at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
[na:1.7.0_101]
>         at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
[na:1.7.0_101]
>         at java.lang.Thread.run(Thread.java:745) [na:1.7.0_101]
> Caused by: java.lang.NullPointerException: null
>         at com.mapr.fs.MapRFsInStream.read(MapRFsInStream.java:276) ~[maprfs-5.2.0-mapr.jar:5.2.0-mapr]
>         at java.io.DataInputStream.read(DataInputStream.java:149) ~[na:1.7.0_101]
>         at org.apache.drill.exec.store.dfs.DrillFSDataInputStream$WrappedInputStream.read(DrillFSDataInputStream.java:216)
~[drill-java-exec-1.9.0.jar:1.9.0]
>         at java.io.DataInputStream.read(DataInputStream.java:149) ~[na:1.7.0_101]
>         at com.fasterxml.jackson.core.json.UTF8StreamJsonParser.loadMore(UTF8StreamJsonParser.java:207)
~[jackson-core-2.7.1.jar:2.7.1]
>         at com.fasterxml.jackson.core.json.UTF8StreamJsonParser.parseEscapedName(UTF8StreamJsonParser.java:1983)
~[jackson-core-2.7.1.jar:2.7.1]
>         at com.fasterxml.jackson.core.json.UTF8StreamJsonParser.slowParseName(UTF8StreamJsonParser.java:1885)
~[jackson-core-2.7.1.jar:2.7.1]
>         at com.fasterxml.jackson.core.json.UTF8StreamJsonParser._parseName(UTF8StreamJsonParser.java:1669)
~[jackson-core-2.7.1.jar:2.7.1]
>         at com.fasterxml.jackson.core.json.UTF8StreamJsonParser.nextToken(UTF8StreamJsonParser.java:752)
~[jackson-core-2.7.1.jar:2.7.1]
>         at com.fasterxml.jackson.core.base.ParserMinimalBase.skipChildren(ParserMinimalBase.java:147)
~[jackson-core-2.7.1.jar:2.7.1]
>         at org.apache.drill.exec.store.easy.json.reader.CountingJsonReader.write(CountingJsonReader.java:58)
~[drill-java-exec-1.9.0.jar:1.9.0]
>         at org.apache.drill.exec.store.easy.json.JSONRecordReader.next(JSONRecordReader.java:206)
~[drill-java-exec-1.9.0.jar:1.9.0]
>         at org.apache.drill.exec.physical.impl.ScanBatch.next(ScanBatch.java:178) ~[drill-java-exec-1.9.0.jar:1.9.0]
>         at org.apache.drill.exec.record.AbstractRecordBatch.next(AbstractRecordBatch.java:119)
~[drill-java-exec-1.9.0.jar:1.9.0]
>         at org.apache.drill.exec.record.AbstractRecordBatch.next(AbstractRecordBatch.java:109)
~[drill-java-exec-1.9.0.jar:1.9.0]
>         at org.apache.drill.exec.record.AbstractSingleRecordBatch.innerNext(AbstractSingleRecordBatch.java:51)
~[drill-java-exec-1.9.0.jar:1.9.0]
>         at org.apache.drill.exec.physical.impl.project.ProjectRecordBatch.innerNext(ProjectRecordBatch.java:135)
~[drill-java-exec-1.9.0.jar:1.9.0]
>         at org.apache.drill.exec.record.AbstractRecordBatch.next(AbstractRecordBatch.java:162)
~[drill-java-exec-1.9.0.jar:1.9.0]
>         at org.apache.drill.exec.record.AbstractRecordBatch.next(AbstractRecordBatch.java:119)
~[drill-java-exec-1.9.0.jar:1.9.0]
>         at org.apache.drill.exec.test.generated.StreamingAggregatorGen2.doWork(StreamingAggTemplate.java:173)
~[na:na]
>         at org.apache.drill.exec.physical.impl.aggregate.StreamingAggBatch.innerNext(StreamingAggBatch.java:167)
~[drill-java-exec-1.9.0.jar:1.9.0]
>         at org.apache.drill.exec.record.AbstractRecordBatch.next(AbstractRecordBatch.java:162)
~[drill-java-exec-1.9.0.jar:1.9.0]
>         at org.apache.drill.exec.record.AbstractRecordBatch.next(AbstractRecordBatch.java:119)
~[drill-java-exec-1.9.0.jar:1.9.0]
>         at org.apache.drill.exec.record.AbstractRecordBatch.next(AbstractRecordBatch.java:109)
~[drill-java-exec-1.9.0.jar:1.9.0]
>         at org.apache.drill.exec.physical.impl.aggregate.StreamingAggBatch.innerNext(StreamingAggBatch.java:137)
~[drill-java-exec-1.9.0.jar:1.9.0]
>         at org.apache.drill.exec.record.AbstractRecordBatch.next(AbstractRecordBatch.java:162)
~[drill-java-exec-1.9.0.jar:1.9.0]
>         at org.apache.drill.exec.record.AbstractRecordBatch.next(AbstractRecordBatch.java:119)
~[drill-java-exec-1.9.0.jar:1.9.0]
>         at org.apache.drill.exec.record.AbstractRecordBatch.next(AbstractRecordBatch.java:109)
~[drill-java-exec-1.9.0.jar:1.9.0]
>         at org.apache.drill.exec.record.AbstractSingleRecordBatch.innerNext(AbstractSingleRecordBatch.java:51)
~[drill-java-exec-1.9.0.jar:1.9.0]
>         at org.apache.drill.exec.physical.impl.project.ProjectRecordBatch.innerNext(ProjectRecordBatch.java:135)
~[drill-java-exec-1.9.0.jar:1.9.0]
>         at org.apache.drill.exec.record.AbstractRecordBatch.next(AbstractRecordBatch.java:162)
~[drill-java-exec-1.9.0.jar:1.9.0]
>         at org.apache.drill.exec.physical.impl.BaseRootExec.next(BaseRootExec.java:104)
~[drill-java-exec-1.9.0.jar:1.9.0]
>         at org.apache.drill.exec.physical.impl.ScreenCreator$ScreenRoot.innerNext(ScreenCreator.java:81)
~[drill-java-exec-1.9.0.jar:1.9.0]
>         at org.apache.drill.exec.physical.impl.BaseRootExec.next(BaseRootExec.java:94)
~[drill-java-exec-1.9.0.jar:1.9.0]
>         at org.apache.drill.exec.work.fragment.FragmentExecutor$1.run(FragmentExecutor.java:232)
~[drill-java-exec-1.9.0.jar:1.9.0]
>         at org.apache.drill.exec.work.fragment.FragmentExecutor$1.run(FragmentExecutor.java:226)
~[drill-java-exec-1.9.0.jar:1.9.0]
>         at java.security.AccessController.doPrivileged(Native Method) ~[na:1.7.0_101]
>         at javax.security.auth.Subject.doAs(Subject.java:415) ~[na:1.7.0_101]
>         at org.apache.hadoop.security.UserGroupInformation.doAs(UserGroupInformation.java:1595)
~[hadoop-common-2.7.0-mapr-1607.jar:na]
>         at org.apache.drill.exec.work.fragment.FragmentExecutor.run(FragmentExecutor.java:226)
[drill-java-exec-1.9.0.jar:1.9.0]
>         ... 4 common frames omitted
> 2016-11-28 06:12:59,758 [Drillbit-ShutdownHook#0] ERROR o.a.d.exec.server.BootStrapContext
- Pool did not terminate
> 2016-11-28 06:12:59,760 [Drillbit-ShutdownHook#0] WARN  o.apache.drill.exec.server.Drillbit
- Failure on close()
> java.lang.RuntimeException: Exception while closing
>         at org.apache.drill.common.DrillAutoCloseables.closeNoChecked(DrillAutoCloseables.java:46)
~[drill-common-1.9.0.jar:1.9.0]
>         at org.apache.drill.exec.server.BootStrapContext.close(BootStrapContext.java:153)
~[drill-java-exec-1.9.0.jar:1.9.0]
>         at org.apache.drill.common.AutoCloseables.close(AutoCloseables.java:76) ~[drill-common-1.9.0.jar:1.9.0]
>         at org.apache.drill.common.AutoCloseables.close(AutoCloseables.java:64) ~[drill-common-1.9.0.jar:1.9.0]
>         at org.apache.drill.exec.server.Drillbit.close(Drillbit.java:160) ~[drill-java-exec-1.9.0.jar:1.9.0]
>         at org.apache.drill.exec.server.Drillbit$ShutdownThread.run(Drillbit.java:254)
[drill-java-exec-1.9.0.jar:1.9.0]
> Caused by: java.lang.IllegalStateException: Memory was leaked by query. Memory leaked:
(1048576)
> Allocator(ROOT) 0/1048576/12686144/8589934592 (res/actual/peak/limit)
>         at org.apache.drill.exec.memory.BaseAllocator.close(BaseAllocator.java:492) ~[drill-memory-base-1.9.0.jar:1.9.0]
>         at org.apache.drill.common.DrillAutoCloseables.closeNoChecked(DrillAutoCloseables.java:44)
~[drill-common-1.9.0.jar:1.9.0]
>         ... 5 common frames omitted
> 2016-11-28 06:12:59,760 [Drillbit-ShutdownHook#0] INFO  o.apache.drill.exec.server.Drillbit
- Shutdown completed (11063 ms).
> Mon Nov 28 06:13:46 UTC 2016 Starting drillbit on centos-01.qa.lab
> core file size          (blocks, -c) 0
> data seg size           (kbytes, -d) unlimited
> scheduling priority             (-e) 0
> file size               (blocks, -f) unlimited
> pending signals                 (-i) 192931
> max locked memory       (kbytes, -l) 64
> max memory size         (kbytes, -m) unlimited
> open files                      (-n) 1024
> pipe size            (512 bytes, -p) 8
> POSIX message queues     (bytes, -q) 819200
> real-time priority              (-r) 0
> stack size              (kbytes, -s) 10240
> cpu time               (seconds, -t) unlimited
> max user processes              (-u) 192931
> virtual memory          (kbytes, -v) unlimited
> file locks                      (-x) unlimited
> {noformat}



--
This message was sent by Atlassian JIRA
(v6.3.4#6332)

Mime
View raw message