You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@uima.apache.org by Thilo Goetz <tw...@gmx.de> on 2009/10/02 12:06:09 UTC
Re: CPM test case hang
Thilo Goetz wrote:
> Works fine with UIMA 2.2.2. I guess it's time to nuke the
> maven repo, do a clean extract from svn and see what happens.
> And do a reboot in the bargain :-)
Did the above except nuke the maven repo, and it still hangs.
Let me know if you want me to do anything to investigate.
--Thilo
>
> --Thilo
>
> Thilo Goetz wrote:
>> Jaroslaw Cwiklik wrote:
>>> I've applied a patch to VinciCasProcessorDeployer.java. Build with a version
>>> before the patch was applied and see if this problem goes away.
>> No luck. I went back as far as I could so it would still compile,
>> and still had the same problem. I guess the next thing to do would
>> be to try our last release on that machine with that jvm and see
>> if I have the same issue. I'll do that when I have time.
>>
>> --Thilo
>>
>>> JC
>>>
>>> On Fri, Sep 4, 2009 at 11:44 AM, Thilo Goetz <tw...@gmx.de> wrote:
>>>
>>>> Jaroslaw Cwiklik wrote:
>>>>> Ran on the command line multiple times with IBM 1.5 and Sun's 1.5 and no
>>>>> hang.
>>>> Yes, I can't reproduce it either on my laptop. Same code level,
>>>> same jvm, both dual core processors, same maven version even. Still
>>>> hangs reliably on my workstation, though. Some subtle synchronization
>>>> issue, undoubtedly. Let me know if you want me to go back to an
>>>> earlier code level and try there, or if there's anything else I can
>>>> do to help.
>>>>
>>>> --Thilo
>>>>
>>>>> JC
>>>>>
>>>>> On Fri, Sep 4, 2009 at 10:26 AM, Thilo Goetz <tw...@gmx.de> wrote:
>>>>>
>>>>>> Jaroslaw Cwiklik wrote:
>>>>>>> Thilo, I've tried IBM's 1.5.8 and Sun's 1.5 and I cant recreate the
>>>> hang.
>>>>>> I
>>>>>>> run the test cases in eclipse. Is anyone else having this problem?
>>>>>> Please try it on the command line, that's where I had the problem.
>>>>>>
>>>>>> --Thilo
>>>>>>
>>>>>>> Jerry
>>>>>>>
>>>>>>> On Thu, Sep 3, 2009 at 9:19 AM, Jaroslaw Cwiklik <ui...@gmail.com>
>>>>>> wrote:
>>>>>>>> Thilo, recently I applied a patch attached to JIRA UIMA-1304. I only
>>>>>> tested
>>>>>>>> this with JDK1.6. I will look into this today.
>>>>>>>>
>>>>>>>> Jerry
>>>>>>>>
>>>>>>>>
>>>>>>>> On Thu, Sep 3, 2009 at 5:00 AM, Thilo Goetz <tw...@gmx.de> wrote:
>>>>>>>>
>>>>>>>>> Hi all,
>>>>>>>>>
>>>>>>>>> has anything changed in the CPM recently? I get
>>>>>>>>> a reliable test case hang here (no cpu activity):
>>>>>>>>>
>>>>>>>>> -------------------------------------------------------
>>>>>>>>> T E S T S
>>>>>>>>> -------------------------------------------------------
>>>>>>>>> Running org.apache.uima.collection.impl.cpm.CpmProcessingTest
>>>>>>>>> Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.344
>>>>>> sec
>>>>>>>>> Running org.apache.uima.collection.impl.cpm.CasPoolTest
>>>>>>>>> Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.125
>>>>>> sec
>>>>>>>>> Running org.apache.uima.collection.impl.cpm.CpmInitTest
>>>>>>>>> Initialize was called: true
>>>>>>>>> Sep 3, 2009 10:50:48 AM
>>>> org.apache.uima.collection.impl.cpm.BaseCPMImpl
>>>>>>>>> process
>>>>>>>>> WARNING: Processing the request to stop the CPM. (Thread Name: main)
>>>>>>>>> Sep 3, 2009 10:50:48 AM
>>>>>>>>> org.apache.uima.collection.impl.cpm.engine.CPMEngine process
>>>>>>>>> INFO: The collection reader thread state is: 2007 (Thread Name: main)
>>>>>>>>>
>>>>>>>>> I have to kill the process, so I don't have more info than this.
>>>>>>>>> It only happens with this jvm (on windows):
>>>>>>>>>
>>>>>>>>> java version "1.5.0"
>>>>>>>>> Java(TM) 2 Runtime Environment, Standard Edition (build
>>>>>> pwi32dev-20090707
>>>>>>>>> (SR10 ))
>>>>>>>>> IBM J9 VM (build 2.3, J2RE 1.5.0 IBM J9 2.3 Windows XP x86-32
>>>>>>>>> j9vmwi3223-20090707 (JIT enabled)
>>>>>>>>> J9VM - 20090706_38445_lHdSMr
>>>>>>>>> JIT - 20090623_1334_r8
>>>>>>>>> GC - 200906_09)
>>>>>>>>> JCL - 20090705
>>>>>>>>>
>>>>>>>>> I tried 3 others, and it goes through with all of them:
>>>>>>>>>
>>>>>>>>> java version "1.6.0"
>>>>>>>>> Java(TM) SE Runtime Environment (build pwi3260sr5-20090529_04(SR5))
>>>>>>>>> IBM J9 VM (build 2.4, J2RE 1.6.0 IBM J9 2.4 Windows XP x86-32
>>>>>>>>> jvmwi3260sr5-20090519_35743 (JIT enabled, AOT enabled)
>>>>>>>>> J9VM - 20090519_035743_lHdSMr
>>>>>>>>> JIT - r9_20090518_2017
>>>>>>>>> GC - 20090417_AA)
>>>>>>>>> JCL - 20090529_01
>>>>>>>>>
>>>>>>>>> java version "1.5.0_11"
>>>>>>>>> Java(TM) 2 Runtime Environment, Standard Edition (build 1.5.0_11-b03)
>>>>>>>>> Java HotSpot(TM) Client VM (build 1.5.0_11-b03, mixed mode)
>>>>>>>>>
>>>>>>>>> java version "1.6.0_13"
>>>>>>>>> Java(TM) SE Runtime Environment (build 1.6.0_13-b03)
>>>>>>>>> Java HotSpot(TM) Client VM (build 11.3-b02, mixed mode)
>>>>>>>>>
>>>>>>>>> Let me know if you need more information.
>>>>>>>>>
>>>>>>>>> --Thilo
>>>>>>>>>
Re: CPM test case hang
Posted by Thilo Goetz <tw...@gmx.de>.
Marshall Schor wrote:
>
> Thilo Goetz wrote:
>> Marshall Schor wrote:
>>
>>> Thilo Goetz wrote:
>>>
>>>> Thilo Goetz wrote:
>>>>
>>>>
>>>>> Works fine with UIMA 2.2.2. I guess it's time to nuke the
>>>>> maven repo, do a clean extract from svn and see what happens.
>>>>> And do a reboot in the bargain :-)
>>>>>
>>>>>
>>>> Did the above except nuke the maven repo, and it still hangs.
>>>> Let me know if you want me to do anything to investigate.
>>>>
>>>>
>>> Please do the following when you get the hang to produce some
>>> diagnositic info:
>>>
>>> Press ctrl-break to generate a Java Dump
>>>
>>> Look in the Java Dump for the section labeled:
>>> LOCKS subcomponent dump routine
>>>
>>> See if you see anything like
>>> Deadlock detected !!!
>>> ---------------------
>>>
>> Well, no deadlock detected, I'm afraid. I include the locks
>> and threads section below. This is starting to look like a
>> maven problem to me. I see all these threads waiting, and
>> they all seem to be maven threads, not from any CPM code afaict.
>> The JSR 166 backport classes seem to come out of the maven
>> jar. Unfortunately I can't try a different maven version
>> as our build appears to require 2.2 now.
>>
>> Great hint about Ctrl-Break, I didn't know you could do
>> that.
>>
> Can you run the tests from Eclipse? Do they hang if you do that?
>
> Thanks... -Marshall
Works in Eclipse. I so we can conclude that it's a
maven issue.
>> --Thilo
>>
>> 0SECTION LOCKS subcomponent dump routine
>> NULL ===============================
>> NULL
>> 1LKPOOLINFO Monitor pool info:
>> 2LKPOOLTOTAL Current total number of monitors: 21
>> NULL
>> 1LKMONPOOLDUMP Monitor Pool Dump (flat & inflated object-monitors):
>> 2LKMONINUSE sys_mon_t:0x62AB2998 infl_mon_t: 0x62AB29D8:
>> 3LKMONOBJECT
>> hidden/edu/emory/mathcs/backport/java/util/concurrent/LinkedBlockingQueue$SerializableLock@00BE1DE8/00BE1DF4:
>> <unowned>
>> 3LKNOTIFYQ Waiting to be notified:
>> 3LKWAITNOTIFY "pool-1-thread-1" (0x62695900)
>> 3LKWAITNOTIFY "pool-1-thread-2" (0x62EC1500)
>> 3LKWAITNOTIFY "pool-1-thread-3" (0x62EC1900)
>> 3LKWAITNOTIFY "pool-1-thread-4" (0x62EC1D00)
>> 3LKWAITNOTIFY "pool-1-thread-5" (0x62C3FD00)
>> NULL
>>
>> 0SECTION THREADS subcomponent dump routine
>> NULL =================================
>> NULL
>> 1XMCURTHDINFO Current Thread Details
>> NULL ----------------------
>> NULL
>> 1XMTHDINFO All Thread Details
>> NULL ------------------
>> NULL
>> 2XMFULLTHDDUMP Full thread dump J9 VM (J2RE 5.0 IBM J9 2.3 Windows XP x86-32
>> build 20090706_38445_lHdSMr, native threads):
>> 3XMTHREADINFO "main" (TID:0x0009F300, sys_thread_t:0x00036E28, state:R,
>> native ID:0x0000084C) prio=5
>> 4XESTACKTRACE at java/lang/ProcessImpl.waitFor(Native Method)
>> 4XESTACKTRACE at
>> org/apache/maven/surefire/booter/shade/org/codehaus/plexus/util/cli/CommandLineUtils.executeCommandLine(CommandLineUtils.java:146)
>> 4XESTACKTRACE at
>> org/apache/maven/surefire/booter/shade/org/codehaus/plexus/util/cli/CommandLineUtils.executeCommandLine(CommandLineUtils.java:98)
>> 4XESTACKTRACE at
>> org/apache/maven/surefire/booter/SurefireBooter.fork(SurefireBooter.java:673)
>> 4XESTACKTRACE at
>> org/apache/maven/surefire/booter/SurefireBooter.forkSuites(SurefireBooter.java:479)
>> 4XESTACKTRACE at
>> org/apache/maven/surefire/booter/SurefireBooter.runSuitesForkOnce(SurefireBooter.java:379)
>> 4XESTACKTRACE at
>> org/apache/maven/surefire/booter/SurefireBooter.run(SurefireBooter.java:245)
>> 4XESTACKTRACE at
>> org/apache/maven/plugin/surefire/SurefirePlugin.execute(SurefirePlugin.java:537)
>> 4XESTACKTRACE at
>> org/apache/maven/plugin/DefaultPluginManager.executeMojo(DefaultPluginManager.java:490)
>> 4XESTACKTRACE at
>> org/apache/maven/lifecycle/DefaultLifecycleExecutor.executeGoals(DefaultLifecycleExecutor.java:694)
>> 4XESTACKTRACE at
>> org/apache/maven/lifecycle/DefaultLifecycleExecutor.executeGoalWithLifecycle(DefaultLifecycleExecutor.java:556)
>> 4XESTACKTRACE at
>> org/apache/maven/lifecycle/DefaultLifecycleExecutor.executeGoal(DefaultLifecycleExecutor.java:535)
>> 4XESTACKTRACE at
>> org/apache/maven/lifecycle/DefaultLifecycleExecutor.executeGoalAndHandleFailures(DefaultLifecycleExecutor.java:387)
>> 4XESTACKTRACE at
>> org/apache/maven/lifecycle/DefaultLifecycleExecutor.executeTaskSegments(DefaultLifecycleExecutor.java:348)
>> 4XESTACKTRACE at
>> org/apache/maven/lifecycle/DefaultLifecycleExecutor.execute(DefaultLifecycleExecutor.java:180)
>> 4XESTACKTRACE at
>> org/apache/maven/DefaultMaven.doExecute(DefaultMaven.java:328)
>> 4XESTACKTRACE at
>> org/apache/maven/DefaultMaven.execute(DefaultMaven.java:138)
>> 4XESTACKTRACE at
>> org/apache/maven/cli/MavenCli.main(MavenCli.java:362(Compiled Code))
>> 4XESTACKTRACE at
>> org/apache/maven/cli/compat/CompatibleMain.main(CompatibleMain.java:60)
>> 4XESTACKTRACE at sun/reflect/NativeMethodAccessorImpl.invoke0(Native
>> Method)
>> 4XESTACKTRACE at
>> sun/reflect/NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:79)
>> 4XESTACKTRACE at
>> sun/reflect/DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
>> 4XESTACKTRACE at java/lang/reflect/Method.invoke(Method.java:618)
>> 4XESTACKTRACE at
>> org/codehaus/classworlds/Launcher.launchEnhanced(Launcher.java:315)
>> 4XESTACKTRACE at
>> org/codehaus/classworlds/Launcher.launch(Launcher.java:255)
>> 4XESTACKTRACE at
>> org/codehaus/classworlds/Launcher.mainWithExitCode(Launcher.java:430(Compiled Code))
>> 4XESTACKTRACE at org/codehaus/classworlds/Launcher.main(Launcher.java:375)
>> 3XMTHREADINFO "JIT Compilation Thread" (TID:0x0009F700,
>> sys_thread_t:0x00037080, state:CW, native ID:0x00000A58) prio=11
>> 3XMTHREADINFO "Signal Dispatcher" (TID:0x0009FB00, sys_thread_t:0x00037530,
>> state:R, native ID:0x00000D00) prio=5
>> 4XESTACKTRACE at com/ibm/misc/SignalDispatcher.waitForSignal(Native Method)
>> 4XESTACKTRACE at
>> com/ibm/misc/SignalDispatcher.run(SignalDispatcher.java:84)
>> 3XMTHREADINFO "Gc Slave Thread" (TID:0x62695100, sys_thread_t:0x000379E0,
>> state:CW, native ID:0x0000007C) prio=5
>> 3XMTHREADINFO "Finalizer thread" (TID:0x62695500, sys_thread_t:0x62AB08E0,
>> state:CW, native ID:0x00000428) prio=5
>> 3XMTHREADINFO "pool-1-thread-1" (TID:0x62695900, sys_thread_t:0x62AB0B38,
>> state:CW, native ID:0x00000C54) prio=5
>> 4XESTACKTRACE at java/lang/Object.wait(Native Method)
>> 4XESTACKTRACE at java/lang/Object.wait(Object.java:199)
>> 4XESTACKTRACE at
>> hidden/edu/emory/mathcs/backport/java/util/concurrent/LinkedBlockingQueue.take(LinkedBlockingQueue.java:316)
>> 4XESTACKTRACE at
>> hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:994)
>> 4XESTACKTRACE at
>> hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1054)
>> 4XESTACKTRACE at
>> hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:575)
>> 4XESTACKTRACE at java/lang/Thread.run(Thread.java:811)
>> 3XMTHREADINFO "pool-1-thread-2" (TID:0x62EC1500, sys_thread_t:0x62AB0D90,
>> state:CW, native ID:0x00000F78) prio=5
>> 4XESTACKTRACE at java/lang/Object.wait(Native Method)
>> 4XESTACKTRACE at java/lang/Object.wait(Object.java:199)
>> 4XESTACKTRACE at
>> hidden/edu/emory/mathcs/backport/java/util/concurrent/LinkedBlockingQueue.take(LinkedBlockingQueue.java:316)
>> 4XESTACKTRACE at
>> hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:994)
>> 4XESTACKTRACE at
>> hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1054)
>> 4XESTACKTRACE at
>> hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:575)
>> 4XESTACKTRACE at java/lang/Thread.run(Thread.java:811)
>> 3XMTHREADINFO "pool-1-thread-3" (TID:0x62EC1900, sys_thread_t:0x62AB0FE8,
>> state:CW, native ID:0x00000E88) prio=5
>> 4XESTACKTRACE at java/lang/Object.wait(Native Method)
>> 4XESTACKTRACE at java/lang/Object.wait(Object.java:199)
>> 4XESTACKTRACE at
>> hidden/edu/emory/mathcs/backport/java/util/concurrent/LinkedBlockingQueue.take(LinkedBlockingQueue.java:316)
>> 4XESTACKTRACE at
>> hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:994)
>> 4XESTACKTRACE at
>> hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1054)
>> 4XESTACKTRACE at
>> hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:575)
>> 4XESTACKTRACE at java/lang/Thread.run(Thread.java:811)
>> 3XMTHREADINFO "pool-1-thread-4" (TID:0x62EC1D00, sys_thread_t:0x62AB1240,
>> state:CW, native ID:0x00000950) prio=5
>> 4XESTACKTRACE at java/lang/Object.wait(Native Method)
>> 4XESTACKTRACE at java/lang/Object.wait(Object.java:199)
>> 4XESTACKTRACE at
>> hidden/edu/emory/mathcs/backport/java/util/concurrent/LinkedBlockingQueue.take(LinkedBlockingQueue.java:316)
>> 4XESTACKTRACE at
>> hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:994)
>> 4XESTACKTRACE at
>> hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1054)
>> 4XESTACKTRACE at
>> hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:575)
>> 4XESTACKTRACE at java/lang/Thread.run(Thread.java:811)
>> 3XMTHREADINFO "pool-1-thread-5" (TID:0x62C3FD00, sys_thread_t:0x62AB1498,
>> state:CW, native ID:0x00000B20) prio=5
>> 4XESTACKTRACE at java/lang/Object.wait(Native Method)
>> 4XESTACKTRACE at java/lang/Object.wait(Object.java:199)
>> 4XESTACKTRACE at
>> hidden/edu/emory/mathcs/backport/java/util/concurrent/LinkedBlockingQueue.take(LinkedBlockingQueue.java:316)
>> 4XESTACKTRACE at
>> hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:994)
>> 4XESTACKTRACE at
>> hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1054)
>> 4XESTACKTRACE at
>> hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:575)
>> 4XESTACKTRACE at java/lang/Thread.run(Thread.java:811)
>> 3XMTHREADINFO "Thread-8" (TID:0x62C40100, sys_thread_t:0x62F10208, state:R,
>> native ID:0x00000888) prio=5
>> 4XESTACKTRACE at java/io/FileInputStream.readBytes(Native Method)
>> 4XESTACKTRACE at java/io/FileInputStream.read(FileInputStream.java:212)
>> 4XESTACKTRACE at
>> sun/nio/cs/StreamDecoder$ConverterSD.implRead(StreamDecoder.java:354(Compiled Code))
>> 4XESTACKTRACE at
>> sun/nio/cs/StreamDecoder.read(StreamDecoder.java:252(Compiled Code))
>> 4XESTACKTRACE at
>> java/io/InputStreamReader.read(InputStreamReader.java:212(Compiled Code))
>> 4XESTACKTRACE at
>> java/io/BufferedReader.fill(BufferedReader.java:157(Compiled Code))
>> 4XESTACKTRACE at
>> java/io/BufferedReader.readLine(BufferedReader.java:320(Compiled Code))
>> 4XESTACKTRACE at
>> java/io/BufferedReader.readLine(BufferedReader.java:383(Compiled Code))
>> 4XESTACKTRACE at
>> org/apache/maven/surefire/booter/shade/org/codehaus/plexus/util/cli/StreamPumper.run(StreamPumper.java:153)
>> 3XMTHREADINFO "Thread-7" (TID:0x62C40500, sys_thread_t:0x62F0FFB0, state:R,
>> native ID:0x00000E90) prio=5
>> 4XESTACKTRACE at java/io/FileInputStream.readBytes(Native Method)
>> 4XESTACKTRACE at
>> java/io/FileInputStream.read(FileInputStream.java:229(Compiled Code))
>> 4XESTACKTRACE at
>> java/io/BufferedInputStream.read1(BufferedInputStream.java:265(Compiled Code))
>> 4XESTACKTRACE at
>> java/io/BufferedInputStream.read(BufferedInputStream.java:324(Compiled Code))
>> 4XESTACKTRACE at
>> java/io/FilterInputStream.read(FilterInputStream.java:113(Compiled Code))
>> 4XESTACKTRACE at
>> sun/nio/cs/StreamDecoder$ConverterSD.implRead(StreamDecoder.java:354(Compiled Code))
>> 4XESTACKTRACE at
>> sun/nio/cs/StreamDecoder.read(StreamDecoder.java:252(Compiled Code))
>> 4XESTACKTRACE at
>> java/io/InputStreamReader.read(InputStreamReader.java:212(Compiled Code))
>> 4XESTACKTRACE at
>> java/io/BufferedReader.fill(BufferedReader.java:157(Compiled Code))
>> 4XESTACKTRACE at
>> java/io/BufferedReader.readLine(BufferedReader.java:320(Compiled Code))
>> 4XESTACKTRACE at
>> java/io/BufferedReader.readLine(BufferedReader.java:383(Compiled Code))
>> 4XESTACKTRACE at
>> org/apache/maven/surefire/booter/shade/org/codehaus/plexus/util/cli/StreamPumper.run(StreamPumper.java:153)
>> NULL
>> ------------------------------------------------------------------------
>>
>>
>>
>>
Re: CPM test case hang
Posted by Marshall Schor <ms...@schor.com>.
Thilo Goetz wrote:
> Marshall Schor wrote:
>
>> Thilo Goetz wrote:
>>
>>> Thilo Goetz wrote:
>>>
>>>
>>>> Works fine with UIMA 2.2.2. I guess it's time to nuke the
>>>> maven repo, do a clean extract from svn and see what happens.
>>>> And do a reboot in the bargain :-)
>>>>
>>>>
>>> Did the above except nuke the maven repo, and it still hangs.
>>> Let me know if you want me to do anything to investigate.
>>>
>>>
>> Please do the following when you get the hang to produce some
>> diagnositic info:
>>
>> Press ctrl-break to generate a Java Dump
>>
>> Look in the Java Dump for the section labeled:
>> LOCKS subcomponent dump routine
>>
>> See if you see anything like
>> Deadlock detected !!!
>> ---------------------
>>
>
> Well, no deadlock detected, I'm afraid. I include the locks
> and threads section below. This is starting to look like a
> maven problem to me. I see all these threads waiting, and
> they all seem to be maven threads, not from any CPM code afaict.
> The JSR 166 backport classes seem to come out of the maven
> jar. Unfortunately I can't try a different maven version
> as our build appears to require 2.2 now.
>
> Great hint about Ctrl-Break, I didn't know you could do
> that.
>
Can you run the tests from Eclipse? Do they hang if you do that?
Thanks... -Marshall
> --Thilo
>
> 0SECTION LOCKS subcomponent dump routine
> NULL ===============================
> NULL
> 1LKPOOLINFO Monitor pool info:
> 2LKPOOLTOTAL Current total number of monitors: 21
> NULL
> 1LKMONPOOLDUMP Monitor Pool Dump (flat & inflated object-monitors):
> 2LKMONINUSE sys_mon_t:0x62AB2998 infl_mon_t: 0x62AB29D8:
> 3LKMONOBJECT
> hidden/edu/emory/mathcs/backport/java/util/concurrent/LinkedBlockingQueue$SerializableLock@00BE1DE8/00BE1DF4:
> <unowned>
> 3LKNOTIFYQ Waiting to be notified:
> 3LKWAITNOTIFY "pool-1-thread-1" (0x62695900)
> 3LKWAITNOTIFY "pool-1-thread-2" (0x62EC1500)
> 3LKWAITNOTIFY "pool-1-thread-3" (0x62EC1900)
> 3LKWAITNOTIFY "pool-1-thread-4" (0x62EC1D00)
> 3LKWAITNOTIFY "pool-1-thread-5" (0x62C3FD00)
> NULL
>
> 0SECTION THREADS subcomponent dump routine
> NULL =================================
> NULL
> 1XMCURTHDINFO Current Thread Details
> NULL ----------------------
> NULL
> 1XMTHDINFO All Thread Details
> NULL ------------------
> NULL
> 2XMFULLTHDDUMP Full thread dump J9 VM (J2RE 5.0 IBM J9 2.3 Windows XP x86-32
> build 20090706_38445_lHdSMr, native threads):
> 3XMTHREADINFO "main" (TID:0x0009F300, sys_thread_t:0x00036E28, state:R,
> native ID:0x0000084C) prio=5
> 4XESTACKTRACE at java/lang/ProcessImpl.waitFor(Native Method)
> 4XESTACKTRACE at
> org/apache/maven/surefire/booter/shade/org/codehaus/plexus/util/cli/CommandLineUtils.executeCommandLine(CommandLineUtils.java:146)
> 4XESTACKTRACE at
> org/apache/maven/surefire/booter/shade/org/codehaus/plexus/util/cli/CommandLineUtils.executeCommandLine(CommandLineUtils.java:98)
> 4XESTACKTRACE at
> org/apache/maven/surefire/booter/SurefireBooter.fork(SurefireBooter.java:673)
> 4XESTACKTRACE at
> org/apache/maven/surefire/booter/SurefireBooter.forkSuites(SurefireBooter.java:479)
> 4XESTACKTRACE at
> org/apache/maven/surefire/booter/SurefireBooter.runSuitesForkOnce(SurefireBooter.java:379)
> 4XESTACKTRACE at
> org/apache/maven/surefire/booter/SurefireBooter.run(SurefireBooter.java:245)
> 4XESTACKTRACE at
> org/apache/maven/plugin/surefire/SurefirePlugin.execute(SurefirePlugin.java:537)
> 4XESTACKTRACE at
> org/apache/maven/plugin/DefaultPluginManager.executeMojo(DefaultPluginManager.java:490)
> 4XESTACKTRACE at
> org/apache/maven/lifecycle/DefaultLifecycleExecutor.executeGoals(DefaultLifecycleExecutor.java:694)
> 4XESTACKTRACE at
> org/apache/maven/lifecycle/DefaultLifecycleExecutor.executeGoalWithLifecycle(DefaultLifecycleExecutor.java:556)
> 4XESTACKTRACE at
> org/apache/maven/lifecycle/DefaultLifecycleExecutor.executeGoal(DefaultLifecycleExecutor.java:535)
> 4XESTACKTRACE at
> org/apache/maven/lifecycle/DefaultLifecycleExecutor.executeGoalAndHandleFailures(DefaultLifecycleExecutor.java:387)
> 4XESTACKTRACE at
> org/apache/maven/lifecycle/DefaultLifecycleExecutor.executeTaskSegments(DefaultLifecycleExecutor.java:348)
> 4XESTACKTRACE at
> org/apache/maven/lifecycle/DefaultLifecycleExecutor.execute(DefaultLifecycleExecutor.java:180)
> 4XESTACKTRACE at
> org/apache/maven/DefaultMaven.doExecute(DefaultMaven.java:328)
> 4XESTACKTRACE at
> org/apache/maven/DefaultMaven.execute(DefaultMaven.java:138)
> 4XESTACKTRACE at
> org/apache/maven/cli/MavenCli.main(MavenCli.java:362(Compiled Code))
> 4XESTACKTRACE at
> org/apache/maven/cli/compat/CompatibleMain.main(CompatibleMain.java:60)
> 4XESTACKTRACE at sun/reflect/NativeMethodAccessorImpl.invoke0(Native
> Method)
> 4XESTACKTRACE at
> sun/reflect/NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:79)
> 4XESTACKTRACE at
> sun/reflect/DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
> 4XESTACKTRACE at java/lang/reflect/Method.invoke(Method.java:618)
> 4XESTACKTRACE at
> org/codehaus/classworlds/Launcher.launchEnhanced(Launcher.java:315)
> 4XESTACKTRACE at
> org/codehaus/classworlds/Launcher.launch(Launcher.java:255)
> 4XESTACKTRACE at
> org/codehaus/classworlds/Launcher.mainWithExitCode(Launcher.java:430(Compiled Code))
> 4XESTACKTRACE at org/codehaus/classworlds/Launcher.main(Launcher.java:375)
> 3XMTHREADINFO "JIT Compilation Thread" (TID:0x0009F700,
> sys_thread_t:0x00037080, state:CW, native ID:0x00000A58) prio=11
> 3XMTHREADINFO "Signal Dispatcher" (TID:0x0009FB00, sys_thread_t:0x00037530,
> state:R, native ID:0x00000D00) prio=5
> 4XESTACKTRACE at com/ibm/misc/SignalDispatcher.waitForSignal(Native Method)
> 4XESTACKTRACE at
> com/ibm/misc/SignalDispatcher.run(SignalDispatcher.java:84)
> 3XMTHREADINFO "Gc Slave Thread" (TID:0x62695100, sys_thread_t:0x000379E0,
> state:CW, native ID:0x0000007C) prio=5
> 3XMTHREADINFO "Finalizer thread" (TID:0x62695500, sys_thread_t:0x62AB08E0,
> state:CW, native ID:0x00000428) prio=5
> 3XMTHREADINFO "pool-1-thread-1" (TID:0x62695900, sys_thread_t:0x62AB0B38,
> state:CW, native ID:0x00000C54) prio=5
> 4XESTACKTRACE at java/lang/Object.wait(Native Method)
> 4XESTACKTRACE at java/lang/Object.wait(Object.java:199)
> 4XESTACKTRACE at
> hidden/edu/emory/mathcs/backport/java/util/concurrent/LinkedBlockingQueue.take(LinkedBlockingQueue.java:316)
> 4XESTACKTRACE at
> hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:994)
> 4XESTACKTRACE at
> hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1054)
> 4XESTACKTRACE at
> hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:575)
> 4XESTACKTRACE at java/lang/Thread.run(Thread.java:811)
> 3XMTHREADINFO "pool-1-thread-2" (TID:0x62EC1500, sys_thread_t:0x62AB0D90,
> state:CW, native ID:0x00000F78) prio=5
> 4XESTACKTRACE at java/lang/Object.wait(Native Method)
> 4XESTACKTRACE at java/lang/Object.wait(Object.java:199)
> 4XESTACKTRACE at
> hidden/edu/emory/mathcs/backport/java/util/concurrent/LinkedBlockingQueue.take(LinkedBlockingQueue.java:316)
> 4XESTACKTRACE at
> hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:994)
> 4XESTACKTRACE at
> hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1054)
> 4XESTACKTRACE at
> hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:575)
> 4XESTACKTRACE at java/lang/Thread.run(Thread.java:811)
> 3XMTHREADINFO "pool-1-thread-3" (TID:0x62EC1900, sys_thread_t:0x62AB0FE8,
> state:CW, native ID:0x00000E88) prio=5
> 4XESTACKTRACE at java/lang/Object.wait(Native Method)
> 4XESTACKTRACE at java/lang/Object.wait(Object.java:199)
> 4XESTACKTRACE at
> hidden/edu/emory/mathcs/backport/java/util/concurrent/LinkedBlockingQueue.take(LinkedBlockingQueue.java:316)
> 4XESTACKTRACE at
> hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:994)
> 4XESTACKTRACE at
> hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1054)
> 4XESTACKTRACE at
> hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:575)
> 4XESTACKTRACE at java/lang/Thread.run(Thread.java:811)
> 3XMTHREADINFO "pool-1-thread-4" (TID:0x62EC1D00, sys_thread_t:0x62AB1240,
> state:CW, native ID:0x00000950) prio=5
> 4XESTACKTRACE at java/lang/Object.wait(Native Method)
> 4XESTACKTRACE at java/lang/Object.wait(Object.java:199)
> 4XESTACKTRACE at
> hidden/edu/emory/mathcs/backport/java/util/concurrent/LinkedBlockingQueue.take(LinkedBlockingQueue.java:316)
> 4XESTACKTRACE at
> hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:994)
> 4XESTACKTRACE at
> hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1054)
> 4XESTACKTRACE at
> hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:575)
> 4XESTACKTRACE at java/lang/Thread.run(Thread.java:811)
> 3XMTHREADINFO "pool-1-thread-5" (TID:0x62C3FD00, sys_thread_t:0x62AB1498,
> state:CW, native ID:0x00000B20) prio=5
> 4XESTACKTRACE at java/lang/Object.wait(Native Method)
> 4XESTACKTRACE at java/lang/Object.wait(Object.java:199)
> 4XESTACKTRACE at
> hidden/edu/emory/mathcs/backport/java/util/concurrent/LinkedBlockingQueue.take(LinkedBlockingQueue.java:316)
> 4XESTACKTRACE at
> hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:994)
> 4XESTACKTRACE at
> hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1054)
> 4XESTACKTRACE at
> hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:575)
> 4XESTACKTRACE at java/lang/Thread.run(Thread.java:811)
> 3XMTHREADINFO "Thread-8" (TID:0x62C40100, sys_thread_t:0x62F10208, state:R,
> native ID:0x00000888) prio=5
> 4XESTACKTRACE at java/io/FileInputStream.readBytes(Native Method)
> 4XESTACKTRACE at java/io/FileInputStream.read(FileInputStream.java:212)
> 4XESTACKTRACE at
> sun/nio/cs/StreamDecoder$ConverterSD.implRead(StreamDecoder.java:354(Compiled Code))
> 4XESTACKTRACE at
> sun/nio/cs/StreamDecoder.read(StreamDecoder.java:252(Compiled Code))
> 4XESTACKTRACE at
> java/io/InputStreamReader.read(InputStreamReader.java:212(Compiled Code))
> 4XESTACKTRACE at
> java/io/BufferedReader.fill(BufferedReader.java:157(Compiled Code))
> 4XESTACKTRACE at
> java/io/BufferedReader.readLine(BufferedReader.java:320(Compiled Code))
> 4XESTACKTRACE at
> java/io/BufferedReader.readLine(BufferedReader.java:383(Compiled Code))
> 4XESTACKTRACE at
> org/apache/maven/surefire/booter/shade/org/codehaus/plexus/util/cli/StreamPumper.run(StreamPumper.java:153)
> 3XMTHREADINFO "Thread-7" (TID:0x62C40500, sys_thread_t:0x62F0FFB0, state:R,
> native ID:0x00000E90) prio=5
> 4XESTACKTRACE at java/io/FileInputStream.readBytes(Native Method)
> 4XESTACKTRACE at
> java/io/FileInputStream.read(FileInputStream.java:229(Compiled Code))
> 4XESTACKTRACE at
> java/io/BufferedInputStream.read1(BufferedInputStream.java:265(Compiled Code))
> 4XESTACKTRACE at
> java/io/BufferedInputStream.read(BufferedInputStream.java:324(Compiled Code))
> 4XESTACKTRACE at
> java/io/FilterInputStream.read(FilterInputStream.java:113(Compiled Code))
> 4XESTACKTRACE at
> sun/nio/cs/StreamDecoder$ConverterSD.implRead(StreamDecoder.java:354(Compiled Code))
> 4XESTACKTRACE at
> sun/nio/cs/StreamDecoder.read(StreamDecoder.java:252(Compiled Code))
> 4XESTACKTRACE at
> java/io/InputStreamReader.read(InputStreamReader.java:212(Compiled Code))
> 4XESTACKTRACE at
> java/io/BufferedReader.fill(BufferedReader.java:157(Compiled Code))
> 4XESTACKTRACE at
> java/io/BufferedReader.readLine(BufferedReader.java:320(Compiled Code))
> 4XESTACKTRACE at
> java/io/BufferedReader.readLine(BufferedReader.java:383(Compiled Code))
> 4XESTACKTRACE at
> org/apache/maven/surefire/booter/shade/org/codehaus/plexus/util/cli/StreamPumper.run(StreamPumper.java:153)
> NULL
> ------------------------------------------------------------------------
>
>
>
>
Re: CPM test case hang
Posted by Thilo Goetz <tw...@gmx.de>.
Marshall Schor wrote:
> Thilo Goetz wrote:
>> Thilo Goetz wrote:
>>
>>> Works fine with UIMA 2.2.2. I guess it's time to nuke the
>>> maven repo, do a clean extract from svn and see what happens.
>>> And do a reboot in the bargain :-)
>>>
>> Did the above except nuke the maven repo, and it still hangs.
>> Let me know if you want me to do anything to investigate.
>>
> Please do the following when you get the hang to produce some
> diagnositic info:
>
> Press ctrl-break to generate a Java Dump
>
> Look in the Java Dump for the section labeled:
> LOCKS subcomponent dump routine
>
> See if you see anything like
> Deadlock detected !!!
> ---------------------
Well, no deadlock detected, I'm afraid. I include the locks
and threads section below. This is starting to look like a
maven problem to me. I see all these threads waiting, and
they all seem to be maven threads, not from any CPM code afaict.
The JSR 166 backport classes seem to come out of the maven
jar. Unfortunately I can't try a different maven version
as our build appears to require 2.2 now.
Great hint about Ctrl-Break, I didn't know you could do
that.
--Thilo
0SECTION LOCKS subcomponent dump routine
NULL ===============================
NULL
1LKPOOLINFO Monitor pool info:
2LKPOOLTOTAL Current total number of monitors: 21
NULL
1LKMONPOOLDUMP Monitor Pool Dump (flat & inflated object-monitors):
2LKMONINUSE sys_mon_t:0x62AB2998 infl_mon_t: 0x62AB29D8:
3LKMONOBJECT
hidden/edu/emory/mathcs/backport/java/util/concurrent/LinkedBlockingQueue$SerializableLock@00BE1DE8/00BE1DF4:
<unowned>
3LKNOTIFYQ Waiting to be notified:
3LKWAITNOTIFY "pool-1-thread-1" (0x62695900)
3LKWAITNOTIFY "pool-1-thread-2" (0x62EC1500)
3LKWAITNOTIFY "pool-1-thread-3" (0x62EC1900)
3LKWAITNOTIFY "pool-1-thread-4" (0x62EC1D00)
3LKWAITNOTIFY "pool-1-thread-5" (0x62C3FD00)
NULL
0SECTION THREADS subcomponent dump routine
NULL =================================
NULL
1XMCURTHDINFO Current Thread Details
NULL ----------------------
NULL
1XMTHDINFO All Thread Details
NULL ------------------
NULL
2XMFULLTHDDUMP Full thread dump J9 VM (J2RE 5.0 IBM J9 2.3 Windows XP x86-32
build 20090706_38445_lHdSMr, native threads):
3XMTHREADINFO "main" (TID:0x0009F300, sys_thread_t:0x00036E28, state:R,
native ID:0x0000084C) prio=5
4XESTACKTRACE at java/lang/ProcessImpl.waitFor(Native Method)
4XESTACKTRACE at
org/apache/maven/surefire/booter/shade/org/codehaus/plexus/util/cli/CommandLineUtils.executeCommandLine(CommandLineUtils.java:146)
4XESTACKTRACE at
org/apache/maven/surefire/booter/shade/org/codehaus/plexus/util/cli/CommandLineUtils.executeCommandLine(CommandLineUtils.java:98)
4XESTACKTRACE at
org/apache/maven/surefire/booter/SurefireBooter.fork(SurefireBooter.java:673)
4XESTACKTRACE at
org/apache/maven/surefire/booter/SurefireBooter.forkSuites(SurefireBooter.java:479)
4XESTACKTRACE at
org/apache/maven/surefire/booter/SurefireBooter.runSuitesForkOnce(SurefireBooter.java:379)
4XESTACKTRACE at
org/apache/maven/surefire/booter/SurefireBooter.run(SurefireBooter.java:245)
4XESTACKTRACE at
org/apache/maven/plugin/surefire/SurefirePlugin.execute(SurefirePlugin.java:537)
4XESTACKTRACE at
org/apache/maven/plugin/DefaultPluginManager.executeMojo(DefaultPluginManager.java:490)
4XESTACKTRACE at
org/apache/maven/lifecycle/DefaultLifecycleExecutor.executeGoals(DefaultLifecycleExecutor.java:694)
4XESTACKTRACE at
org/apache/maven/lifecycle/DefaultLifecycleExecutor.executeGoalWithLifecycle(DefaultLifecycleExecutor.java:556)
4XESTACKTRACE at
org/apache/maven/lifecycle/DefaultLifecycleExecutor.executeGoal(DefaultLifecycleExecutor.java:535)
4XESTACKTRACE at
org/apache/maven/lifecycle/DefaultLifecycleExecutor.executeGoalAndHandleFailures(DefaultLifecycleExecutor.java:387)
4XESTACKTRACE at
org/apache/maven/lifecycle/DefaultLifecycleExecutor.executeTaskSegments(DefaultLifecycleExecutor.java:348)
4XESTACKTRACE at
org/apache/maven/lifecycle/DefaultLifecycleExecutor.execute(DefaultLifecycleExecutor.java:180)
4XESTACKTRACE at
org/apache/maven/DefaultMaven.doExecute(DefaultMaven.java:328)
4XESTACKTRACE at
org/apache/maven/DefaultMaven.execute(DefaultMaven.java:138)
4XESTACKTRACE at
org/apache/maven/cli/MavenCli.main(MavenCli.java:362(Compiled Code))
4XESTACKTRACE at
org/apache/maven/cli/compat/CompatibleMain.main(CompatibleMain.java:60)
4XESTACKTRACE at sun/reflect/NativeMethodAccessorImpl.invoke0(Native
Method)
4XESTACKTRACE at
sun/reflect/NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:79)
4XESTACKTRACE at
sun/reflect/DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
4XESTACKTRACE at java/lang/reflect/Method.invoke(Method.java:618)
4XESTACKTRACE at
org/codehaus/classworlds/Launcher.launchEnhanced(Launcher.java:315)
4XESTACKTRACE at
org/codehaus/classworlds/Launcher.launch(Launcher.java:255)
4XESTACKTRACE at
org/codehaus/classworlds/Launcher.mainWithExitCode(Launcher.java:430(Compiled Code))
4XESTACKTRACE at org/codehaus/classworlds/Launcher.main(Launcher.java:375)
3XMTHREADINFO "JIT Compilation Thread" (TID:0x0009F700,
sys_thread_t:0x00037080, state:CW, native ID:0x00000A58) prio=11
3XMTHREADINFO "Signal Dispatcher" (TID:0x0009FB00, sys_thread_t:0x00037530,
state:R, native ID:0x00000D00) prio=5
4XESTACKTRACE at com/ibm/misc/SignalDispatcher.waitForSignal(Native Method)
4XESTACKTRACE at
com/ibm/misc/SignalDispatcher.run(SignalDispatcher.java:84)
3XMTHREADINFO "Gc Slave Thread" (TID:0x62695100, sys_thread_t:0x000379E0,
state:CW, native ID:0x0000007C) prio=5
3XMTHREADINFO "Finalizer thread" (TID:0x62695500, sys_thread_t:0x62AB08E0,
state:CW, native ID:0x00000428) prio=5
3XMTHREADINFO "pool-1-thread-1" (TID:0x62695900, sys_thread_t:0x62AB0B38,
state:CW, native ID:0x00000C54) prio=5
4XESTACKTRACE at java/lang/Object.wait(Native Method)
4XESTACKTRACE at java/lang/Object.wait(Object.java:199)
4XESTACKTRACE at
hidden/edu/emory/mathcs/backport/java/util/concurrent/LinkedBlockingQueue.take(LinkedBlockingQueue.java:316)
4XESTACKTRACE at
hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:994)
4XESTACKTRACE at
hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1054)
4XESTACKTRACE at
hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:575)
4XESTACKTRACE at java/lang/Thread.run(Thread.java:811)
3XMTHREADINFO "pool-1-thread-2" (TID:0x62EC1500, sys_thread_t:0x62AB0D90,
state:CW, native ID:0x00000F78) prio=5
4XESTACKTRACE at java/lang/Object.wait(Native Method)
4XESTACKTRACE at java/lang/Object.wait(Object.java:199)
4XESTACKTRACE at
hidden/edu/emory/mathcs/backport/java/util/concurrent/LinkedBlockingQueue.take(LinkedBlockingQueue.java:316)
4XESTACKTRACE at
hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:994)
4XESTACKTRACE at
hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1054)
4XESTACKTRACE at
hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:575)
4XESTACKTRACE at java/lang/Thread.run(Thread.java:811)
3XMTHREADINFO "pool-1-thread-3" (TID:0x62EC1900, sys_thread_t:0x62AB0FE8,
state:CW, native ID:0x00000E88) prio=5
4XESTACKTRACE at java/lang/Object.wait(Native Method)
4XESTACKTRACE at java/lang/Object.wait(Object.java:199)
4XESTACKTRACE at
hidden/edu/emory/mathcs/backport/java/util/concurrent/LinkedBlockingQueue.take(LinkedBlockingQueue.java:316)
4XESTACKTRACE at
hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:994)
4XESTACKTRACE at
hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1054)
4XESTACKTRACE at
hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:575)
4XESTACKTRACE at java/lang/Thread.run(Thread.java:811)
3XMTHREADINFO "pool-1-thread-4" (TID:0x62EC1D00, sys_thread_t:0x62AB1240,
state:CW, native ID:0x00000950) prio=5
4XESTACKTRACE at java/lang/Object.wait(Native Method)
4XESTACKTRACE at java/lang/Object.wait(Object.java:199)
4XESTACKTRACE at
hidden/edu/emory/mathcs/backport/java/util/concurrent/LinkedBlockingQueue.take(LinkedBlockingQueue.java:316)
4XESTACKTRACE at
hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:994)
4XESTACKTRACE at
hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1054)
4XESTACKTRACE at
hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:575)
4XESTACKTRACE at java/lang/Thread.run(Thread.java:811)
3XMTHREADINFO "pool-1-thread-5" (TID:0x62C3FD00, sys_thread_t:0x62AB1498,
state:CW, native ID:0x00000B20) prio=5
4XESTACKTRACE at java/lang/Object.wait(Native Method)
4XESTACKTRACE at java/lang/Object.wait(Object.java:199)
4XESTACKTRACE at
hidden/edu/emory/mathcs/backport/java/util/concurrent/LinkedBlockingQueue.take(LinkedBlockingQueue.java:316)
4XESTACKTRACE at
hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor.getTask(ThreadPoolExecutor.java:994)
4XESTACKTRACE at
hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1054)
4XESTACKTRACE at
hidden/edu/emory/mathcs/backport/java/util/concurrent/ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:575)
4XESTACKTRACE at java/lang/Thread.run(Thread.java:811)
3XMTHREADINFO "Thread-8" (TID:0x62C40100, sys_thread_t:0x62F10208, state:R,
native ID:0x00000888) prio=5
4XESTACKTRACE at java/io/FileInputStream.readBytes(Native Method)
4XESTACKTRACE at java/io/FileInputStream.read(FileInputStream.java:212)
4XESTACKTRACE at
sun/nio/cs/StreamDecoder$ConverterSD.implRead(StreamDecoder.java:354(Compiled Code))
4XESTACKTRACE at
sun/nio/cs/StreamDecoder.read(StreamDecoder.java:252(Compiled Code))
4XESTACKTRACE at
java/io/InputStreamReader.read(InputStreamReader.java:212(Compiled Code))
4XESTACKTRACE at
java/io/BufferedReader.fill(BufferedReader.java:157(Compiled Code))
4XESTACKTRACE at
java/io/BufferedReader.readLine(BufferedReader.java:320(Compiled Code))
4XESTACKTRACE at
java/io/BufferedReader.readLine(BufferedReader.java:383(Compiled Code))
4XESTACKTRACE at
org/apache/maven/surefire/booter/shade/org/codehaus/plexus/util/cli/StreamPumper.run(StreamPumper.java:153)
3XMTHREADINFO "Thread-7" (TID:0x62C40500, sys_thread_t:0x62F0FFB0, state:R,
native ID:0x00000E90) prio=5
4XESTACKTRACE at java/io/FileInputStream.readBytes(Native Method)
4XESTACKTRACE at
java/io/FileInputStream.read(FileInputStream.java:229(Compiled Code))
4XESTACKTRACE at
java/io/BufferedInputStream.read1(BufferedInputStream.java:265(Compiled Code))
4XESTACKTRACE at
java/io/BufferedInputStream.read(BufferedInputStream.java:324(Compiled Code))
4XESTACKTRACE at
java/io/FilterInputStream.read(FilterInputStream.java:113(Compiled Code))
4XESTACKTRACE at
sun/nio/cs/StreamDecoder$ConverterSD.implRead(StreamDecoder.java:354(Compiled Code))
4XESTACKTRACE at
sun/nio/cs/StreamDecoder.read(StreamDecoder.java:252(Compiled Code))
4XESTACKTRACE at
java/io/InputStreamReader.read(InputStreamReader.java:212(Compiled Code))
4XESTACKTRACE at
java/io/BufferedReader.fill(BufferedReader.java:157(Compiled Code))
4XESTACKTRACE at
java/io/BufferedReader.readLine(BufferedReader.java:320(Compiled Code))
4XESTACKTRACE at
java/io/BufferedReader.readLine(BufferedReader.java:383(Compiled Code))
4XESTACKTRACE at
org/apache/maven/surefire/booter/shade/org/codehaus/plexus/util/cli/StreamPumper.run(StreamPumper.java:153)
NULL
------------------------------------------------------------------------
Re: CPM test case hang
Posted by Marshall Schor <ms...@schor.com>.
Thilo Goetz wrote:
> Thilo Goetz wrote:
>
>> Works fine with UIMA 2.2.2. I guess it's time to nuke the
>> maven repo, do a clean extract from svn and see what happens.
>> And do a reboot in the bargain :-)
>>
>
> Did the above except nuke the maven repo, and it still hangs.
> Let me know if you want me to do anything to investigate.
>
Please do the following when you get the hang to produce some
diagnositic info:
Press ctrl-break to generate a Java Dump
Look in the Java Dump for the section labeled:
LOCKS subcomponent dump routine
See if you see anything like
Deadlock detected !!!
---------------------
If so, please post the relevant info :-) .
-Marshall
> --Thilo
>
>
>> --Thilo
>>
>> Thilo Goetz wrote:
>>
>>> Jaroslaw Cwiklik wrote:
>>>
>>>> I've applied a patch to VinciCasProcessorDeployer.java. Build with a version
>>>> before the patch was applied and see if this problem goes away.
>>>>
>>> No luck. I went back as far as I could so it would still compile,
>>> and still had the same problem. I guess the next thing to do would
>>> be to try our last release on that machine with that jvm and see
>>> if I have the same issue. I'll do that when I have time.
>>>
>>> --Thilo
>>>
>>>
>>>> JC
>>>>
>>>> On Fri, Sep 4, 2009 at 11:44 AM, Thilo Goetz <tw...@gmx.de> wrote:
>>>>
>>>>
>>>>> Jaroslaw Cwiklik wrote:
>>>>>
>>>>>> Ran on the command line multiple times with IBM 1.5 and Sun's 1.5 and no
>>>>>> hang.
>>>>>>
>>>>> Yes, I can't reproduce it either on my laptop. Same code level,
>>>>> same jvm, both dual core processors, same maven version even. Still
>>>>> hangs reliably on my workstation, though. Some subtle synchronization
>>>>> issue, undoubtedly. Let me know if you want me to go back to an
>>>>> earlier code level and try there, or if there's anything else I can
>>>>> do to help.
>>>>>
>>>>> --Thilo
>>>>>
>>>>>
>>>>>> JC
>>>>>>
>>>>>> On Fri, Sep 4, 2009 at 10:26 AM, Thilo Goetz <tw...@gmx.de> wrote:
>>>>>>
>>>>>>
>>>>>>> Jaroslaw Cwiklik wrote:
>>>>>>>
>>>>>>>> Thilo, I've tried IBM's 1.5.8 and Sun's 1.5 and I cant recreate the
>>>>>>>>
>>>>> hang.
>>>>>
>>>>>>> I
>>>>>>>
>>>>>>>> run the test cases in eclipse. Is anyone else having this problem?
>>>>>>>>
>>>>>>> Please try it on the command line, that's where I had the problem.
>>>>>>>
>>>>>>> --Thilo
>>>>>>>
>>>>>>>
>>>>>>>> Jerry
>>>>>>>>
>>>>>>>> On Thu, Sep 3, 2009 at 9:19 AM, Jaroslaw Cwiklik <ui...@gmail.com>
>>>>>>>>
>>>>>>> wrote:
>>>>>>>
>>>>>>>>> Thilo, recently I applied a patch attached to JIRA UIMA-1304. I only
>>>>>>>>>
>>>>>>> tested
>>>>>>>
>>>>>>>>> this with JDK1.6. I will look into this today.
>>>>>>>>>
>>>>>>>>> Jerry
>>>>>>>>>
>>>>>>>>>
>>>>>>>>> On Thu, Sep 3, 2009 at 5:00 AM, Thilo Goetz <tw...@gmx.de> wrote:
>>>>>>>>>
>>>>>>>>>
>>>>>>>>>> Hi all,
>>>>>>>>>>
>>>>>>>>>> has anything changed in the CPM recently? I get
>>>>>>>>>> a reliable test case hang here (no cpu activity):
>>>>>>>>>>
>>>>>>>>>> -------------------------------------------------------
>>>>>>>>>> T E S T S
>>>>>>>>>> -------------------------------------------------------
>>>>>>>>>> Running org.apache.uima.collection.impl.cpm.CpmProcessingTest
>>>>>>>>>> Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.344
>>>>>>>>>>
>>>>>>> sec
>>>>>>>
>>>>>>>>>> Running org.apache.uima.collection.impl.cpm.CasPoolTest
>>>>>>>>>> Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.125
>>>>>>>>>>
>>>>>>> sec
>>>>>>>
>>>>>>>>>> Running org.apache.uima.collection.impl.cpm.CpmInitTest
>>>>>>>>>> Initialize was called: true
>>>>>>>>>> Sep 3, 2009 10:50:48 AM
>>>>>>>>>>
>>>>> org.apache.uima.collection.impl.cpm.BaseCPMImpl
>>>>>
>>>>>>>>>> process
>>>>>>>>>> WARNING: Processing the request to stop the CPM. (Thread Name: main)
>>>>>>>>>> Sep 3, 2009 10:50:48 AM
>>>>>>>>>> org.apache.uima.collection.impl.cpm.engine.CPMEngine process
>>>>>>>>>> INFO: The collection reader thread state is: 2007 (Thread Name: main)
>>>>>>>>>>
>>>>>>>>>> I have to kill the process, so I don't have more info than this.
>>>>>>>>>> It only happens with this jvm (on windows):
>>>>>>>>>>
>>>>>>>>>> java version "1.5.0"
>>>>>>>>>> Java(TM) 2 Runtime Environment, Standard Edition (build
>>>>>>>>>>
>>>>>>> pwi32dev-20090707
>>>>>>>
>>>>>>>>>> (SR10 ))
>>>>>>>>>> IBM J9 VM (build 2.3, J2RE 1.5.0 IBM J9 2.3 Windows XP x86-32
>>>>>>>>>> j9vmwi3223-20090707 (JIT enabled)
>>>>>>>>>> J9VM - 20090706_38445_lHdSMr
>>>>>>>>>> JIT - 20090623_1334_r8
>>>>>>>>>> GC - 200906_09)
>>>>>>>>>> JCL - 20090705
>>>>>>>>>>
>>>>>>>>>> I tried 3 others, and it goes through with all of them:
>>>>>>>>>>
>>>>>>>>>> java version "1.6.0"
>>>>>>>>>> Java(TM) SE Runtime Environment (build pwi3260sr5-20090529_04(SR5))
>>>>>>>>>> IBM J9 VM (build 2.4, J2RE 1.6.0 IBM J9 2.4 Windows XP x86-32
>>>>>>>>>> jvmwi3260sr5-20090519_35743 (JIT enabled, AOT enabled)
>>>>>>>>>> J9VM - 20090519_035743_lHdSMr
>>>>>>>>>> JIT - r9_20090518_2017
>>>>>>>>>> GC - 20090417_AA)
>>>>>>>>>> JCL - 20090529_01
>>>>>>>>>>
>>>>>>>>>> java version "1.5.0_11"
>>>>>>>>>> Java(TM) 2 Runtime Environment, Standard Edition (build 1.5.0_11-b03)
>>>>>>>>>> Java HotSpot(TM) Client VM (build 1.5.0_11-b03, mixed mode)
>>>>>>>>>>
>>>>>>>>>> java version "1.6.0_13"
>>>>>>>>>> Java(TM) SE Runtime Environment (build 1.6.0_13-b03)
>>>>>>>>>> Java HotSpot(TM) Client VM (build 11.3-b02, mixed mode)
>>>>>>>>>>
>>>>>>>>>> Let me know if you need more information.
>>>>>>>>>>
>>>>>>>>>> --Thilo
>>>>>>>>>>
>>>>>>>>>>
>
>
>
>