[Termtools] thread dumps on starexec
Dieter Hofbauer
dieter_hofbauer at web.de
Fri Sep 2 17:38:15 CEST 2016
Dear StarExec users,
during tests of my tool for the termination competition, I repeatedly
encountered thread dumps caused by starexec, cf the example attached. These
see to be randomly distributed (not reproducible on starexec) and don't occur
on my machine at all.
I'm aware of the discussion
http://starexec.lefora.com/reply/169/strange-memouts#reply-169
and my tool is also Java and also calling an external solver quite often. In
my case, I don't see a connection to memory consumption, however.
Any hints? @Aprove: How do you handle this?
Best regards, Dieter
______________________________________
"On the second day in the month of September, the human race suddenly
encountered a deadly thread dump to its very existence." (based loosly on
"Little shop of horrors" by Ashman/Menken)
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.lri.fr/pipermail/termtools/attachments/20160902/821fcc9d/attachment.html>
-------------- next part --------------
1.09/2.06 2016-09-02 09:35:17
1.09/2.06 Full thread dump OpenJDK 64-Bit Server VM (25.77-b03 mixed mode):
1.09/2.06
1.09/2.06 "pool-1-thread-5" #14 prio=5 os_prio=0 tid=0x00002ab3e0285000 nid=0x8359 runnable [0x00002abe75d0b000]
1.09/2.06 java.lang.Thread.State: RUNNABLE
1.09/2.06 at java.lang.String.<init>(String.java:190)
1.09/2.06 at java.lang.String.substring(String.java:1933)
1.09/2.06 at de.dieter_hofbauer.RewriteStep.apply(RewriteStep.java:59)
1.09/2.06 at de.dieter_hofbauer.Derivation.<init>(Derivation.java:71)
1.09/2.06 at de.dieter_hofbauer.ForwardOptimizedExpander.expand(Expander.java:163)
1.09/2.06 at de.dieter_hofbauer.Derivations$1.next(Derivations.java:102)
1.09/2.06 at de.dieter_hofbauer.Derivations$1.next(Derivations.java:1)
1.09/2.06 at de.dieter_hofbauer.LoopFinder.call(LoopFinder.java:178)
1.09/2.06 at de.dieter_hofbauer.KnockedForLoops.searchLoopSequentially(KnockedForLoops.java:176)
1.09/2.06 at de.dieter_hofbauer.KnockedForLoops.call(KnockedForLoops.java:97)
1.09/2.06 at de.dieter_hofbauer.KnockedForLoops.call(KnockedForLoops.java:1)
1.09/2.06 at java.util.concurrent.FutureTask.run(FutureTask.java:266)
1.09/2.06 at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
1.09/2.06 at java.util.concurrent.FutureTask.run(FutureTask.java:266)
1.09/2.06 at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
1.09/2.06 at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
1.09/2.06 at java.lang.Thread.run(Thread.java:745)
1.09/2.06
1.09/2.06 "pool-1-thread-4" #13 prio=5 os_prio=0 tid=0x00002ab3e0283000 nid=0x8358 runnable [0x00002abe75c0a000]
1.09/2.06 java.lang.Thread.State: RUNNABLE
1.09/2.06 at java.util.Arrays.copyOfRange(Arrays.java:3661)
1.09/2.06 at java.lang.String.<init>(String.java:207)
1.09/2.06 at java.lang.StringBuilder.toString(StringBuilder.java:407)
1.09/2.06 at de.dieter_hofbauer.Derivation.<init>(Derivation.java:68)
1.09/2.06 at de.dieter_hofbauer.ForwardOptimizedExpander.expand(Expander.java:163)
1.09/2.06 at de.dieter_hofbauer.Derivations$1.next(Derivations.java:102)
1.09/2.06 at de.dieter_hofbauer.Derivations$1.next(Derivations.java:1)
1.09/2.06 at de.dieter_hofbauer.LoopFinder.call(LoopFinder.java:178)
1.09/2.06 at de.dieter_hofbauer.KnockedForLoops.searchLoopSequentially(KnockedForLoops.java:176)
1.09/2.06 at de.dieter_hofbauer.KnockedForLoops.call(KnockedForLoops.java:97)
1.09/2.06 at de.dieter_hofbauer.KnockedForLoops.call(KnockedForLoops.java:1)
1.09/2.06 at java.util.concurrent.FutureTask.run(FutureTask.java:266)
1.09/2.06 at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
1.09/2.06 at java.util.concurrent.FutureTask.run(FutureTask.java:266)
1.09/2.06 at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
1.09/2.06 at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
1.09/2.06 at java.lang.Thread.run(Thread.java:745)
1.09/2.06
1.09/2.06 "pool-1-thread-3" #12 prio=5 os_prio=0 tid=0x00002ab3e0281800 nid=0x8357 runnable [0x00002abe75b08000]
1.09/2.06 java.lang.Thread.State: RUNNABLE
1.09/2.06 at sun.misc.Unsafe.defineAnonymousClass(Native Method)
1.09/2.06 at java.lang.invoke.InvokerBytecodeGenerator.loadAndInitializeInvokerClass(InvokerBytecodeGenerator.java:284)
1.09/2.06 at java.lang.invoke.InvokerBytecodeGenerator.loadMethod(InvokerBytecodeGenerator.java:276)
1.09/2.06 at java.lang.invoke.InvokerBytecodeGenerator.generateCustomizedCode(InvokerBytecodeGenerator.java:618)
1.09/2.06 at java.lang.invoke.LambdaForm.compileToBytecode(LambdaForm.java:654)
1.09/2.06 at java.lang.invoke.Invokers.callSiteForm(Invokers.java:381)
1.09/2.06 at java.lang.invoke.Invokers.linkToTargetMethod(Invokers.java:347)
1.09/2.06 at java.lang.invoke.MethodHandleNatives.linkCallSiteImpl(MethodHandleNatives.java:314)
1.09/2.06 at java.lang.invoke.MethodHandleNatives.linkCallSite(MethodHandleNatives.java:297)
1.09/2.06 at java.lang.UNIXProcess.<init>(UNIXProcess.java:258)
1.09/2.06 at java.lang.ProcessImpl.start(ProcessImpl.java:134)
1.09/2.06 at java.lang.ProcessBuilder.start(ProcessBuilder.java:1029)
1.09/2.06 at java.lang.Runtime.exec(Runtime.java:620)
1.09/2.06 at java.lang.Runtime.exec(Runtime.java:450)
1.09/2.06 at java.lang.Runtime.exec(Runtime.java:347)
1.09/2.06 at de.dieter_hofbauer.GlpkSolver.solveModel(GlpkSolver.java:124)
1.09/2.06 at de.dieter_hofbauer.GlpkProofStep.apply(GlpkProofStep.java:65)
1.09/2.06 at de.dieter_hofbauer.Prover.go(Prover.java:82)
1.09/2.06 at de.dieter_hofbauer.MultumNonMultaDP.call(MultumNonMultaDP.java:69)
1.09/2.06 at de.dieter_hofbauer.MultumNonMultaDP.call(MultumNonMultaDP.java:1)
1.09/2.06 at java.util.concurrent.FutureTask.run(FutureTask.java:266)
1.09/2.06 at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
1.09/2.06 at java.util.concurrent.FutureTask.run(FutureTask.java:266)
1.09/2.06 at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
1.09/2.06 at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
1.09/2.06 at java.lang.Thread.run(Thread.java:745)
1.09/2.06
1.09/2.06 "pool-1-thread-2" #11 prio=5 os_prio=0 tid=0x00002ab3e027f800 nid=0x8356 runnable [0x00002abe75a07000]
1.09/2.06 java.lang.Thread.State: RUNNABLE
1.09/2.06 at sun.misc.Unsafe.defineAnonymousClass(Native Method)
1.09/2.06 at java.lang.invoke.InnerClassLambdaMetafactory.spinInnerClass(InnerClassLambdaMetafactory.java:326)
1.09/2.06 at java.lang.invoke.InnerClassLambdaMetafactory.buildCallSite(InnerClassLambdaMetafactory.java:194)
1.09/2.06 at java.lang.invoke.LambdaMetafactory.metafactory(LambdaMetafactory.java:304)
1.09/2.06 at java.lang.invoke.LambdaForm$DMH/1271480220.invokeStatic_L6_L(LambdaForm$DMH)
1.09/2.06 at java.lang.invoke.LambdaForm$BMH/704391963.reinvoke(LambdaForm$BMH)
1.09/2.06 at java.lang.invoke.LambdaForm$MH/1549830417.invoke_MT(LambdaForm$MH)
1.09/2.06 at java.lang.invoke.CallSite.makeSite(CallSite.java:302)
1.09/2.06 at java.lang.invoke.MethodHandleNatives.linkCallSiteImpl(MethodHandleNatives.java:307)
1.09/2.06 at java.lang.invoke.MethodHandleNatives.linkCallSite(MethodHandleNatives.java:297)
1.09/2.06 at java.lang.UNIXProcess.<init>(UNIXProcess.java:258)
1.09/2.06 at java.lang.ProcessImpl.start(ProcessImpl.java:134)
1.09/2.06 at java.lang.ProcessBuilder.start(ProcessBuilder.java:1029)
1.09/2.06 at java.lang.Runtime.exec(Runtime.java:620)
1.09/2.06 at java.lang.Runtime.exec(Runtime.java:450)
1.09/2.06 at java.lang.Runtime.exec(Runtime.java:347)
1.09/2.06 at de.dieter_hofbauer.GlpkSolver.solveModel(GlpkSolver.java:124)
1.09/2.06 at de.dieter_hofbauer.GlpkProofStep.apply(GlpkProofStep.java:65)
1.09/2.06 at de.dieter_hofbauer.Prover.go(Prover.java:82)
1.09/2.06 at de.dieter_hofbauer.MultumNonMultaDP.call(MultumNonMultaDP.java:69)
1.09/2.06 at de.dieter_hofbauer.MultumNonMultaDP.call(MultumNonMultaDP.java:1)
1.09/2.06 at java.util.concurrent.FutureTask.run(FutureTask.java:266)
1.09/2.06 at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
1.09/2.06 at java.util.concurrent.FutureTask.run(FutureTask.java:266)
1.09/2.06 at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
1.09/2.06 at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
1.09/2.06 at java.lang.Thread.run(Thread.java:745)
1.09/2.06
1.09/2.06 "pool-1-thread-1" #10 prio=5 os_prio=0 tid=0x00002ab3e027e000 nid=0x8355 runnable [0x00002abe75906000]
1.09/2.06 java.lang.Thread.State: RUNNABLE
1.09/2.06 at sun.misc.Unsafe.defineAnonymousClass(Native Method)
1.09/2.06 at java.lang.invoke.InnerClassLambdaMetafactory.spinInnerClass(InnerClassLambdaMetafactory.java:326)
1.09/2.06 at java.lang.invoke.InnerClassLambdaMetafactory.buildCallSite(InnerClassLambdaMetafactory.java:194)
1.09/2.06 at java.lang.invoke.LambdaMetafactory.metafactory(LambdaMetafactory.java:304)
1.09/2.06 at java.lang.invoke.LambdaForm$DMH/1271480220.invokeStatic_L6_L(LambdaForm$DMH)
1.09/2.06 at java.lang.invoke.LambdaForm$BMH/704391963.reinvoke(LambdaForm$BMH)
1.09/2.06 at java.lang.invoke.LambdaForm$MH/1549830417.invoke_MT(LambdaForm$MH)
1.09/2.06 at java.lang.invoke.CallSite.makeSite(CallSite.java:302)
1.09/2.06 at java.lang.invoke.MethodHandleNatives.linkCallSiteImpl(MethodHandleNatives.java:307)
1.09/2.06 at java.lang.invoke.MethodHandleNatives.linkCallSite(MethodHandleNatives.java:297)
1.09/2.06 at java.lang.UNIXProcess.<init>(UNIXProcess.java:258)
1.09/2.06 at java.lang.ProcessImpl.start(ProcessImpl.java:134)
1.09/2.06 at java.lang.ProcessBuilder.start(ProcessBuilder.java:1029)
1.09/2.06 at java.lang.Runtime.exec(Runtime.java:620)
1.09/2.06 at java.lang.Runtime.exec(Runtime.java:450)
1.09/2.06 at java.lang.Runtime.exec(Runtime.java:347)
1.09/2.06 at de.dieter_hofbauer.GlpkSolver.solveModel(GlpkSolver.java:124)
1.09/2.06 at de.dieter_hofbauer.GlpkProofStep.apply(GlpkProofStep.java:65)
1.09/2.06 at de.dieter_hofbauer.Prover.go(Prover.java:82)
1.09/2.06 at de.dieter_hofbauer.MultumNonMulta.call(MultumNonMulta.java:63)
1.09/2.06 at java.util.concurrent.FutureTask.run(FutureTask.java:266)
1.09/2.06 at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
1.09/2.06 at java.util.concurrent.FutureTask.run(FutureTask.java:266)
1.09/2.06 at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
1.09/2.06 at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
1.09/2.06 at java.lang.Thread.run(Thread.java:745)
1.09/2.06
1.09/2.06 "Service Thread" #9 daemon prio=9 os_prio=0 tid=0x00002ab3e0222800 nid=0x8353 runnable [0x0000000000000000]
1.09/2.06 java.lang.Thread.State: RUNNABLE
1.09/2.06
1.09/2.06 "C1 CompilerThread3" #8 daemon prio=9 os_prio=0 tid=0x00002ab3e0220000 nid=0x8352 runnable [0x0000000000000000]
1.09/2.06 java.lang.Thread.State: RUNNABLE
1.09/2.06
1.09/2.06 "C2 CompilerThread2" #7 daemon prio=9 os_prio=0 tid=0x00002ab3e021e000 nid=0x8351 runnable [0x0000000000000000]
1.09/2.06 java.lang.Thread.State: RUNNABLE
1.09/2.06
1.09/2.06 "C2 CompilerThread1" #6 daemon prio=9 os_prio=0 tid=0x00002ab3e021c000 nid=0x8350 runnable [0x0000000000000000]
1.09/2.06 java.lang.Thread.State: RUNNABLE
1.09/2.06
1.09/2.06 "C2 CompilerThread0" #5 daemon prio=9 os_prio=0 tid=0x00002ab3e020e800 nid=0x834f runnable [0x0000000000000000]
1.09/2.06 java.lang.Thread.State: RUNNABLE
1.09/2.06
1.09/2.06 "Signal Dispatcher" #4 daemon prio=9 os_prio=0 tid=0x00002ab3e020c800 nid=0x834e waiting on condition [0x0000000000000000]
1.09/2.06 java.lang.Thread.State: RUNNABLE
1.09/2.06
1.09/2.06 "Finalizer" #3 daemon prio=8 os_prio=0 tid=0x00002ab3e01e2000 nid=0x834d in Object.wait() [0x00002abe750ff000]
1.09/2.06 java.lang.Thread.State: WAITING (on object monitor)
1.09/2.06 at java.lang.Object.wait(Native Method)
1.09/2.06 - waiting on <0x00002aba9db0d350> (a java.lang.ref.ReferenceQueue$Lock)
1.09/2.06 at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:143)
1.09/2.06 - locked <0x00002aba9db0d350> (a java.lang.ref.ReferenceQueue$Lock)
1.09/2.06 at java.lang.ref.ReferenceQueue.remove(ReferenceQueue.java:164)
1.09/2.06 at java.lang.ref.Finalizer$FinalizerThread.run(Finalizer.java:209)
1.09/2.06
1.09/2.06 "Reference Handler" #2 daemon prio=10 os_prio=0 tid=0x00002ab3e01dd800 nid=0x834c in Object.wait() [0x00002abdfbf5f000]
1.09/2.06 java.lang.Thread.State: WAITING (on object monitor)
1.09/2.06 at java.lang.Object.wait(Native Method)
1.09/2.06 - waiting on <0x00002aba9db0a028> (a java.lang.ref.Reference$Lock)
1.09/2.06 at java.lang.Object.wait(Object.java:502)
1.09/2.06 at java.lang.ref.Reference.tryHandlePending(Reference.java:191)
1.09/2.06 - locked <0x00002aba9db0a028> (a java.lang.ref.Reference$Lock)
1.09/2.06 at java.lang.ref.Reference$ReferenceHandler.run(Reference.java:153)
1.09/2.06
1.09/2.06 "main" #1 prio=5 os_prio=0 tid=0x00002ab3e0009000 nid=0x8342 waiting on condition [0x00002ab3da522000]
1.09/2.06 java.lang.Thread.State: TIMED_WAITING (parking)
1.09/2.06 at sun.misc.Unsafe.park(Native Method)
1.09/2.06 - parking to wait for <0x00002aba9dc32a38> (a java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject)
1.09/2.06 at java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:215)
1.09/2.06 at java.util.concurrent.locks.AbstractQueuedSynchronizer$ConditionObject.awaitNanos(AbstractQueuedSynchronizer.java:2078)
1.09/2.06 at java.util.concurrent.LinkedBlockingQueue.poll(LinkedBlockingQueue.java:467)
1.09/2.06 at java.util.concurrent.ExecutorCompletionService.poll(ExecutorCompletionService.java:202)
1.09/2.06 at java.util.concurrent.AbstractExecutorService.doInvokeAny(AbstractExecutorService.java:182)
1.09/2.06 at java.util.concurrent.AbstractExecutorService.invokeAny(AbstractExecutorService.java:225)
1.09/2.06 at de.dieter_hofbauer.Main.go(Main.java:51)
1.09/2.06 at de.dieter_hofbauer.Main.main(Main.java:124)
1.09/2.06
1.09/2.06 "VM Thread" os_prio=0 tid=0x00002ab3e01d4000 nid=0x834b runnable
1.09/2.06
1.09/2.06 "GC task thread#0 (ParallelGC)" os_prio=0 tid=0x00002ab3e001e000 nid=0x8343 runnable
1.09/2.06
1.09/2.06 "GC task thread#1 (ParallelGC)" os_prio=0 tid=0x00002ab3e0020000 nid=0x8344 runnable
1.09/2.06
1.09/2.06 "GC task thread#2 (ParallelGC)" os_prio=0 tid=0x00002ab3e0021800 nid=0x8345 runnable
1.09/2.06
1.09/2.06 "GC task thread#3 (ParallelGC)" os_prio=0 tid=0x00002ab3e0023800 nid=0x8346 runnable
1.09/2.06
1.09/2.06 "GC task thread#4 (ParallelGC)" os_prio=0 tid=0x00002ab3e0025000 nid=0x8347 runnable
1.09/2.06
1.09/2.06 "GC task thread#5 (ParallelGC)" os_prio=0 tid=0x00002ab3e0027000 nid=0x8348 runnable
1.09/2.06
1.09/2.06 "GC task thread#6 (ParallelGC)" os_prio=0 tid=0x00002ab3e0029000 nid=0x8349 runnable
1.09/2.06
1.09/2.06 "GC task thread#7 (ParallelGC)" os_prio=0 tid=0x00002ab3e002a800 nid=0x834a runnable
1.09/2.06
1.09/2.06 "VM Periodic Task Thread" os_prio=0 tid=0x00002ab3e0225000 nid=0x8354 waiting on condition
1.09/2.06
1.09/2.09 JNI global references: 336
1.09/2.09
1.09/2.10 Heap
1.09/2.10 PSYoungGen total 611840K, used 293889K [0x00002aba9db00000, 0x00002abac8580000, 0x00002abdf3000000)
1.09/2.10 eden space 524800K, 56% used [0x00002aba9db00000,0x00002abaafa00648,0x00002ababdb80000)
1.09/2.10 from space 87040K, 0% used [0x00002abac3080000,0x00002abac3080000,0x00002abac8580000)
1.09/2.10 to space 87040K, 0% used [0x00002ababdb80000,0x00002ababdb80000,0x00002abac3080000)
1.09/2.10 ParOldGen total 1398272K, used 0K [0x00002ab3f3000000, 0x00002ab448580000, 0x00002aba9db00000)
1.09/2.10 object space 1398272K, 0% used [0x00002ab3f3000000,0x00002ab3f3000000,0x00002ab448580000)
1.09/2.10 Metaspace used 5849K, capacity 6178K, committed 6400K, reserved 8192K
1.09/2.10
3.96/4.01 EOF
More information about the Termtools
mailing list