You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@uima.apache.org by Jörn Kottmann <ko...@gmail.com> on 2009/11/20 16:41:52 UTC

UIMA AS extended tests just hang

Hi,

I tried to add a test to the extended tests, but when
I execute them they just hang.

Jörn

Console output:

...

UIMA AS Client - Shared JMS Connection Not Closed. Current Client 
Instance Count1
UIMA AS Client Undeployed All Containers
Nov 20, 2009 4:29:42 PM 
org.apache.uima.adapter.jms.activemq.JmsOutputChannel stop
INFO: Controller: NoOpAnnotatorQueue Output Channel Shutdown Completed
Nov 20, 2009 4:29:42 PM 
org.apache.uima.aae.controller.AnalysisEngineInstancePoolWithThreadAffinity 
abort
INFO: NoOpAnnotator Calling Destroy On AE instance # 0
Nov 20, 2009 4:29:42 PM 
org.apache.uima.adapter.jms.client.BaseUIMAAsynchronousEngineCommon_impl$SharedConnection 
destroy
INFO: UIMA AS Client Terminated. Shared Connection Still Open. Current 
Client Count:1
getMeta Timeout on delegate:NoOpAnnotatorQueue
runTest: Initialization Received Reply Containing Exception:
Stopping Uima AS Client API. Service Not Responding To a Ping.
Nov 20, 2009 4:29:57 PM org.apache.uima.aae.delegate.Delegate$1 
Delegate.TimerTask.run
INFO: Timeout While Waiting For Reply From Delegate:NoOpAnnotatorQueue 
GetMeta Request Timed Out. Configured Reply Window Of 60,000.
Nov 20, 2009 4:29:57 PM 
org.apache.uima.adapter.jms.client.BaseUIMAAsynchronousEngineCommon_impl 
notifyOnTimout
INFO: Request For Metadata Has Timed-out. Service Queue:NoOpAnnotatorQueue
org.apache.uima.aae.error.UimaASMetaRequestTimeout
    at 
org.apache.uima.adapter.jms.client.BaseUIMAAsynchronousEngineCommon_impl.notifyOnTimout(BaseUIMAAsynchronousEngineCommon_impl.java:1872)
    at 
org.apache.uima.adapter.jms.client.ClientServiceDelegate.handleError(ClientServiceDelegate.java:152)
    at org.apache.uima.aae.delegate.Delegate$1.run(Delegate.java:624)
    at java.util.TimerThread.mainLoop(Timer.java:512)
    at java.util.TimerThread.run(Timer.java:462)
Nov 20, 2009 4:29:57 PM 
org.apache.uima.adapter.jms.client.ClientServiceDelegate handleError
INFO: Request For Metadata Has Timed-out. Service Queue:NoOpAnnotatorQueue
Nov 20, 2009 4:29:57 PM 
org.apache.uima.adapter.jms.client.BaseUIMAAsynchronousEngineCommon_impl 
stop
INFO: Stopping Asynchronous Client.
Nov 20, 2009 4:29:57 PM 
org.apache.uima.adapter.jms.client.BaseUIMAAsynchronousEngineCommon_impl 
stop
INFO: Asynchronous Client Has Stopped.
Nov 20, 2009 4:29:57 PM 
org.apache.uima.aae.controller.PrimitiveAnalysisEngineController_impl 
terminate
INFO: Controller: NoOpAnnotator Received Terminate Event
Nov 20, 2009 4:29:57 PM 
org.apache.uima.adapter.jms.activemq.JmsInputChannel stop
INFO: Stopping Service JMS Transport. Service: NoOpAnnotatorQueue
Service:NoOpAnnotator Message Channel:queue://NoOpAnnotatorQueue 
Selector:Command=2000 OR Command=2002 Stopped
Service:NoOpAnnotator Message Channel:queue://NoOpAnnotatorQueue 
Selector:Command=2001 Stopped
Nov 20, 2009 4:29:57 PM 
org.apache.uima.adapter.jms.activemq.JmsInputChannel stop
INFO: Stopping Service JMS Transport. Service: NoOpAnnotatorQueue
Nov 20, 2009 4:29:57 PM 
org.apache.uima.aae.controller.PrimitiveAnalysisEngineController_impl stop
INFO: Stopping Controller: NoOpAnnotator
Nov 20, 2009 4:29:57 PM 
org.apache.uima.adapter.jms.activemq.JmsOutputChannel stop
INFO: Controller: NoOpAnnotatorQueue Aborted Connection To Endpoint: 
ID:karkand-54505-1258730845595-3:3664:4 and Broker Uri: tcp://localhost:8200
Nov 20, 2009 4:29:57 PM 
org.apache.uima.adapter.jms.activemq.JmsOutputChannel stop
INFO: Controller: NoOpAnnotatorQueue Aborted Connection To Endpoint: 
ID:karkand-54505-1258730845595-3:3664:1 and Broker Uri: tcp://localhost:8200
Nov 20, 2009 4:29:57 PM 
org.apache.uima.adapter.jms.activemq.JmsOutputChannel stop
INFO: Controller: NoOpAnnotatorQueue Aborted Connection To Endpoint: 
ID:karkand-54505-1258730845595-3:3664:2 and Broker Uri: tcp://localhost:8200
Nov 20, 2009 4:29:57 PM 
org.apache.uima.adapter.jms.client.BaseUIMAAsynchronousEngine_impl 
initialize
WARNING: Aborting Asynchronous Client Due to: Metadata Timeout
Nov 20, 2009 4:29:57 PM 
org.apache.uima.adapter.jms.client.BaseUIMAAsynchronousEngine_impl 
notifyOnInitializationFailure
WARNING: Top Level Controller Initialization Exception. Cause: 
org.apache.uima.resource.ResourceInitializationException
org.apache.uima.resource.ResourceInitializationException
    at 
org.apache.uima.adapter.jms.client.BaseUIMAAsynchronousEngine_impl.initialize(BaseUIMAAsynchronousEngine_impl.java:668)
    at 
org.apache.uima.ee.test.utils.BaseTestSupport.initialize(BaseTestSupport.java:169)
    at 
org.apache.uima.ee.test.TestUimaASExtended.access$3(TestUimaASExtended.java:1)
    at 
org.apache.uima.ee.test.TestUimaASExtended$1.run(TestUimaASExtended.java:438)
Caused by: org.apache.uima.aae.error.UimaASMetaRequestTimeout
    ... 4 more
JMS Connection to Broker: tcp://localhost:8200 Closed
Service:NoOpAnnotator Stopped
UIMA AS Client - Shared JMS Connection Closed
UIMA AS Client Undeployed All Containers
Nov 20, 2009 4:29:57 PM 
org.apache.uima.adapter.jms.activemq.JmsOutputChannel stop
INFO: Controller: NoOpAnnotatorQueue Output Channel Shutdown Completed
Nov 20, 2009 4:29:57 PM 
org.apache.uima.aae.controller.AnalysisEngineInstancePoolWithThreadAffinity 
abort
INFO: NoOpAnnotator Calling Destroy On AE instance # 0
Nov 20, 2009 4:29:57 PM 
org.apache.uima.adapter.jms.client.BaseUIMAAsynchronousEngineCommon_impl$SharedConnection 
destroy
INFO: UIMA AS Client Shared Connection Has Been Closed

Re: UIMA AS extended tests just hang

Posted by Jaroslaw Cwiklik <ui...@gmail.com>.
Hmm, not sure why your testcase doesnt work. Can you provide more
information about the test. Which of the testcases seem to hang? Is it the
one that you've added? In any case, try to run it debug and see where it
gets stuck. Let me know if you find a bug. I quickly tested client timeout
using this:

  public void testClientProcessTimeout() throws Exception {
    System.out
            .println("-------------- testClientProcessTimeout
-------------");
    // Instantiate Uima AS Client
    BaseUIMAAsynchronousEngine_impl uimaAsEngine = new
BaseUIMAAsynchronousEngine_impl();
    // Deploy Uima AS Primitive Service
    deployService(uimaAsEngine, relativePath +
"/Deploy_NoOpAnnotatorWithLongDelay.xml");
    Map<String, Object> appCtx =
buildContext(String.valueOf(broker.getMasterConnectorURI()),
            "NoOpAnnotatorQueueLongDelay");
    appCtx.put(UimaAsynchronousEngine.Timeout, 1100);
    initialize(uimaAsEngine, appCtx);
    waitUntilInitialized();

    for (int i = 0; i < 1; i++) {
      CAS cas = uimaAsEngine.getCAS();
      cas.setDocumentText("Some Text");
      System.out.println("UIMA AS Client Sending CAS#" + (i + 1) + " Request
to a Service");
      uimaAsEngine.sendCAS(cas);
    }

    uimaAsEngine.collectionProcessingComplete();
    uimaAsEngine.stop();

  }

The above seems to work fine.It deploys a service that waits for a long time
and causes a client timeout after 1100 ms.

JC

On Fri, Nov 20, 2009 at 10:41 AM, Jörn Kottmann <ko...@gmail.com> wrote:

> Hi,
>
> I tried to add a test to the extended tests, but when
> I execute them they just hang.
>
> Jörn
>
> Console output:
>
> ...
>
> UIMA AS Client - Shared JMS Connection Not Closed. Current Client Instance
> Count1
> UIMA AS Client Undeployed All Containers
> Nov 20, 2009 4:29:42 PM
> org.apache.uima.adapter.jms.activemq.JmsOutputChannel stop
> INFO: Controller: NoOpAnnotatorQueue Output Channel Shutdown Completed
> Nov 20, 2009 4:29:42 PM
> org.apache.uima.aae.controller.AnalysisEngineInstancePoolWithThreadAffinity
> abort
> INFO: NoOpAnnotator Calling Destroy On AE instance # 0
> Nov 20, 2009 4:29:42 PM
> org.apache.uima.adapter.jms.client.BaseUIMAAsynchronousEngineCommon_impl$SharedConnection
> destroy
> INFO: UIMA AS Client Terminated. Shared Connection Still Open. Current
> Client Count:1
> getMeta Timeout on delegate:NoOpAnnotatorQueue
> runTest: Initialization Received Reply Containing Exception:
> Stopping Uima AS Client API. Service Not Responding To a Ping.
> Nov 20, 2009 4:29:57 PM org.apache.uima.aae.delegate.Delegate$1
> Delegate.TimerTask.run
> INFO: Timeout While Waiting For Reply From Delegate:NoOpAnnotatorQueue
> GetMeta Request Timed Out. Configured Reply Window Of 60,000.
> Nov 20, 2009 4:29:57 PM
> org.apache.uima.adapter.jms.client.BaseUIMAAsynchronousEngineCommon_impl
> notifyOnTimout
> INFO: Request For Metadata Has Timed-out. Service Queue:NoOpAnnotatorQueue
> org.apache.uima.aae.error.UimaASMetaRequestTimeout
>   at
> org.apache.uima.adapter.jms.client.BaseUIMAAsynchronousEngineCommon_impl.notifyOnTimout(BaseUIMAAsynchronousEngineCommon_impl.java:1872)
>   at
> org.apache.uima.adapter.jms.client.ClientServiceDelegate.handleError(ClientServiceDelegate.java:152)
>   at org.apache.uima.aae.delegate.Delegate$1.run(Delegate.java:624)
>   at java.util.TimerThread.mainLoop(Timer.java:512)
>   at java.util.TimerThread.run(Timer.java:462)
> Nov 20, 2009 4:29:57 PM
> org.apache.uima.adapter.jms.client.ClientServiceDelegate handleError
> INFO: Request For Metadata Has Timed-out. Service Queue:NoOpAnnotatorQueue
> Nov 20, 2009 4:29:57 PM
> org.apache.uima.adapter.jms.client.BaseUIMAAsynchronousEngineCommon_impl
> stop
> INFO: Stopping Asynchronous Client.
> Nov 20, 2009 4:29:57 PM
> org.apache.uima.adapter.jms.client.BaseUIMAAsynchronousEngineCommon_impl
> stop
> INFO: Asynchronous Client Has Stopped.
> Nov 20, 2009 4:29:57 PM
> org.apache.uima.aae.controller.PrimitiveAnalysisEngineController_impl
> terminate
> INFO: Controller: NoOpAnnotator Received Terminate Event
> Nov 20, 2009 4:29:57 PM
> org.apache.uima.adapter.jms.activemq.JmsInputChannel stop
> INFO: Stopping Service JMS Transport. Service: NoOpAnnotatorQueue
> Service:NoOpAnnotator Message Channel:queue://NoOpAnnotatorQueue
> Selector:Command=2000 OR Command=2002 Stopped
> Service:NoOpAnnotator Message Channel:queue://NoOpAnnotatorQueue
> Selector:Command=2001 Stopped
> Nov 20, 2009 4:29:57 PM
> org.apache.uima.adapter.jms.activemq.JmsInputChannel stop
> INFO: Stopping Service JMS Transport. Service: NoOpAnnotatorQueue
> Nov 20, 2009 4:29:57 PM
> org.apache.uima.aae.controller.PrimitiveAnalysisEngineController_impl stop
> INFO: Stopping Controller: NoOpAnnotator
> Nov 20, 2009 4:29:57 PM
> org.apache.uima.adapter.jms.activemq.JmsOutputChannel stop
> INFO: Controller: NoOpAnnotatorQueue Aborted Connection To Endpoint:
> ID:karkand-54505-1258730845595-3:3664:4 and Broker Uri: tcp://localhost:8200
> Nov 20, 2009 4:29:57 PM
> org.apache.uima.adapter.jms.activemq.JmsOutputChannel stop
> INFO: Controller: NoOpAnnotatorQueue Aborted Connection To Endpoint:
> ID:karkand-54505-1258730845595-3:3664:1 and Broker Uri: tcp://localhost:8200
> Nov 20, 2009 4:29:57 PM
> org.apache.uima.adapter.jms.activemq.JmsOutputChannel stop
> INFO: Controller: NoOpAnnotatorQueue Aborted Connection To Endpoint:
> ID:karkand-54505-1258730845595-3:3664:2 and Broker Uri: tcp://localhost:8200
> Nov 20, 2009 4:29:57 PM
> org.apache.uima.adapter.jms.client.BaseUIMAAsynchronousEngine_impl
> initialize
> WARNING: Aborting Asynchronous Client Due to: Metadata Timeout
> Nov 20, 2009 4:29:57 PM
> org.apache.uima.adapter.jms.client.BaseUIMAAsynchronousEngine_impl
> notifyOnInitializationFailure
> WARNING: Top Level Controller Initialization Exception. Cause:
> org.apache.uima.resource.ResourceInitializationException
> org.apache.uima.resource.ResourceInitializationException
>   at
> org.apache.uima.adapter.jms.client.BaseUIMAAsynchronousEngine_impl.initialize(BaseUIMAAsynchronousEngine_impl.java:668)
>   at
> org.apache.uima.ee.test.utils.BaseTestSupport.initialize(BaseTestSupport.java:169)
>   at
> org.apache.uima.ee.test.TestUimaASExtended.access$3(TestUimaASExtended.java:1)
>   at
> org.apache.uima.ee.test.TestUimaASExtended$1.run(TestUimaASExtended.java:438)
> Caused by: org.apache.uima.aae.error.UimaASMetaRequestTimeout
>   ... 4 more
> JMS Connection to Broker: tcp://localhost:8200 Closed
> Service:NoOpAnnotator Stopped
> UIMA AS Client - Shared JMS Connection Closed
> UIMA AS Client Undeployed All Containers
> Nov 20, 2009 4:29:57 PM
> org.apache.uima.adapter.jms.activemq.JmsOutputChannel stop
> INFO: Controller: NoOpAnnotatorQueue Output Channel Shutdown Completed
> Nov 20, 2009 4:29:57 PM
> org.apache.uima.aae.controller.AnalysisEngineInstancePoolWithThreadAffinity
> abort
> INFO: NoOpAnnotator Calling Destroy On AE instance # 0
> Nov 20, 2009 4:29:57 PM
> org.apache.uima.adapter.jms.client.BaseUIMAAsynchronousEngineCommon_impl$SharedConnection
> destroy
> INFO: UIMA AS Client Shared Connection Has Been Closed
>