uima-user mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Hansen Candrawinata <hansen.candrawin...@gmail.com>
Subject Re: Exception thrown when deploying CAS Processors in managed mode
Date Fri, 04 Dec 2009 03:04:58 GMT
Okay, I did some debugging, traced through the UIMA source code, and found the
likely cause, that is, the VinciAnalysisEngineService_impl failed to be 
started.  This is most likely due to misconfiguration in my classpath[*], so I
started the service manually from the command line, and it was looking good for
a second, but then it suddenly stopped due to a shutdown request.  Here are the
log messages printed out in the console:

VinciAnalysisEngineService_impl: Starting Server with Socket Timeout:300000
[12/4/09 1:26 PM] Service JodAndTika starting on port 10001
[12/4/09 1:26 PM] Pooledcount: 1 busyCount: 0
[12/4/09 1:26 PM](VinciServer.shutdown()) Accepted shutdown request: Shutting
Down the Vinci Analysis Engine Service
04/12/2009 1:26:12 PM
org.apache.uima.adapter.vinci.VinciAnalysisEngineService_impl stop(315)
INFO: Shutting Down the Vinci Analysis Engine Service
[12/4/09 1:26 PM](VinciServer.shutdown()) Accepted shutdown request: Shutting
Down the Vinci Analysis Engine Service

[*] An exception should have been thrown in this case in my opinion, but instead
UIMA seems to silently ignore this.  See
org.apache.uima.collection.impl.cpm.container.deployer.vinci.VinciCasProcessorDe
ployer
line 551, where the Process object returned should have been used to check the
status of the spawned process.

--

The CPE seemed to indicate that there was an error when converting CAS container
to CAS data, as shown below:

04/12/2009 1:26:09 PM
org.apache.uima.collection.impl.cpm.container.deployer.vns.LocalVNS initialize
INFO: The CPM is about to test its VNS port 9005 for availability. (Thread 
Name: BaseCPMImpl-Thread) 
04/12/2009 1:26:09 PM
org.apache.uima.collection.impl.cpm.container.deployer.vns.LocalVNS initialize
INFO: The service port 9005 is available on localhost. (Thread Name: 
BaseCPMImpl-Thread) 
04/12/2009 1:26:09 PM
org.apache.uima.collection.impl.cpm.container.deployer.vns.LocalVNS initialize
INFO: The CPM VNS activity started on port 9005. (Thread Name: BaseCPMImpl-
Thread)
04/12/2009 1:26:09 PM
org.apache.uima.collection.impl.cpm.container.deployer.vns.LocalVNS process
INFO: The CPM VNS thread started. (Thread Name: VNS-Thread) VNS Port: 9005 
04/12/2009 1:26:09 PM
org.apache.uima.collection.impl.cpm.container.deployer.vinci.VinciCasProcessorDe
ployer initialize
INFO: The next port  was retrieved from the service port queue. (Thread Name: 
BaseCPMImpl-Thread)
04/12/2009 1:26:09 PM
org.apache.uima.collection.impl.cpm.container.deployer.vinci.VinciCasProcessorDe
ployer initialize
INFO: The service port is not available yet. There are 5000 retries left to
acquire the port. (Thread Name: BaseCPMImpl-Thread) 
<snipped>
04/12/2009 1:26:11 PM
org.apache.uima.collection.impl.cpm.container.deployer.vinci.VinciCasProcessorDe
ployer initialize
INFO: The service port is not available yet. There are 4984 retries left to 
acquire the port. (Thread Name: BaseCPMImpl-Thread) 
[12/4/09 1:26 PM] Pooledcount: 1 busyCount: 0
04/12/2009 1:26:11 PM
org.apache.uima.collection.impl.cpm.container.deployer.vns.LocalVNS initialize
INFO: The service port 10001 is available on localhost. (Thread Name: 
PooledThread#0) 
04/12/2009 1:26:11 PM 
org.apache.uima.collection.impl.cpm.container.deployer.vns.LocalVNS initialize
INFO: The service port 10001 is available on localhost. (Thread Name: 
PooledThread#0) 
04/12/2009 1:26:11 PM 
org.apache.uima.collection.impl.cpm.container.deployer.vinci.VinciCasProcessorDe
ployer initialize
INFO: The next port 10001 was retrieved from the service port queue. (Thread
Name: BaseCPMImpl-Thread)
04/12/2009 1:26:11 PM
org.apache.uima.collection.impl.cpm.container.deployer.vns.LocalVNS initialize
INFO: The service port 10002 is available on localhost. (Thread Name: 
BaseCPMImpl-Thread) 
04/12/2009 1:26:11 PM 
org.apache.uima.collection.impl.cpm.container.deployer.vns.LocalVNS initialize
INFO: The service port 10002 is available on localhost. (Thread Name: 
BaseCPMImpl-Thread) 
 ** initializationComplete
org.apache.uima.UIMARuntimeException
	at 
org.apache.uima.collection.impl.CasConverter.casContainerToCasData(CasConverter.
java:146)
	at 
org.apache.uima.collection.impl.cpm.engine.ProcessingUnit.processNext(Processing
Unit.java:795)
	at 
org.apache.uima.collection.impl.cpm.engine.ProcessingUnit.run(ProcessingUnit.jav
a:577)
Caused by: XCASParsingException: Error parsing XCAS from source <unknown> at 
line <unknown>, column <unknown>: expected text but found element: <i>.
	at 
org.apache.uima.cas_data.impl.XCasToCasDataSaxHandler.createException(XCasToCasD
ataSaxHandler.java:451)
	at 
org.apache.uima.cas_data.impl.XCasToCasDataSaxHandler.createException(XCasToCasD
ataSaxHandler.java:473)
	at 
org.apache.uima.cas_data.impl.XCasToCasDataSaxHandler.startElement(XCasToCasData
SaxHandler.java:161)
	at 
org.apache.uima.cas.impl.XCASSerializer$XCASDocSerializer.startElement(XCASSeria
lizer.java:327)
	at 
org.apache.uima.cas.impl.XCASSerializer$XCASDocSerializer.encodePrimitiveTypeArr
ayFS(XCASSerializer.java:542)
	at 
org.apache.uima.cas.impl.XCASSerializer$XCASDocSerializer.encodeFS(XCASSerialize
r.java:505)
	at 
org.apache.uima.cas.impl.XCASSerializer$XCASDocSerializer.encodeQueued(XCASSeria
lizer.java:407)
	at 
org.apache.uima.cas.impl.XCASSerializer$XCASDocSerializer.serialize(XCASSerializ
er.java:272)
	at 
org.apache.uima.cas.impl.XCASSerializer$XCASDocSerializer.access$500(XCASSeriali
zer.java:62)
	at 
org.apache.uima.cas.impl.XCASSerializer.serialize(XCASSerializer.java:919)
	at 
org.apache.uima.cas.impl.XCASSerializer.serialize(XCASSerializer.java:896)
	at 
org.apache.uima.cas.impl.XCASSerializer.serialize(XCASSerializer.java:878)
	at 
org.apache.uima.collection.impl.CasConverter.casContainerToCasData(CasConverter.
java:142)
	... 2 more
04/12/2009 1:26:12 PM org.apache.uima.collection.impl.cpm.engine.ProcessingUnit 
process
SEVERE: The container JodAndTika returned the following error message: null 
(Thread Name: [Procesing Pipeline#1 Thread]::) 
04/12/2009 1:26:12 PM org.apache.uima.collection.impl.cpm.engine.ProcessingUnit 
maybeLogSevereException(2499)
SEVERE: Thread: [Procesing Pipeline#1 Thread]::, message: null
org.apache.uima.UIMARuntimeException
	at 
org.apache.uima.collection.impl.CasConverter.casContainerToCasData(CasConverter.
java:146)
	at 
org.apache.uima.collection.impl.cpm.engine.ProcessingUnit.processNext(Processing
Unit.java:795)
	at 
org.apache.uima.collection.impl.cpm.engine.ProcessingUnit.run(ProcessingUnit.jav
a:577)
Caused by: XCASParsingException: Error parsing XCAS from source <unknown> at 
line <unknown>, column <unknown>: expected text but found element: <i>.
	at 
org.apache.uima.cas_data.impl.XCasToCasDataSaxHandler.createException(XCasToCasD
ataSaxHandler.java:451)
	at 
org.apache.uima.cas_data.impl.XCasToCasDataSaxHandler.createException(XCasToCasD
ataSaxHandler.java:473)
	at 
org.apache.uima.cas_data.impl.XCasToCasDataSaxHandler.startElement(XCasToCasData
SaxHandler.java:161)
	at 
org.apache.uima.cas.impl.XCASSerializer$XCASDocSerializer.startElement(XCASSeria
lizer.java:327)
	at 
org.apache.uima.cas.impl.XCASSerializer$XCASDocSerializer.encodePrimitiveTypeArr
ayFS(XCASSerializer.java:542)
	at 
org.apache.uima.cas.impl.XCASSerializer$XCASDocSerializer.encodeFS(XCASSerialize
r.java:505)
	at 
org.apache.uima.cas.impl.XCASSerializer$XCASDocSerializer.encodeQueued(XCASSeria
lizer.java:407)
	at 
org.apache.uima.cas.impl.XCASSerializer$XCASDocSerializer.serialize(XCASSerializ
er.java:272)
	at 
org.apache.uima.cas.impl.XCASSerializer$XCASDocSerializer.access$500(XCASSeriali
zer.java:62)
	at 
org.apache.uima.cas.impl.XCASSerializer.serialize(XCASSerializer.java:919)
	at 
org.apache.uima.cas.impl.XCASSerializer.serialize(XCASSerializer.java:896)
	at 
org.apache.uima.cas.impl.XCASSerializer.serialize(XCASSerializer.java:878)
	at 
org.apache.uima.collection.impl.CasConverter.casContainerToCasData(CasConverter.
java:142)
	... 2 more
 ** entityProcessComplete
04/12/2009 1:26:12 PM 
org.apache.uima.collection.impl.cpm.container.ProcessingContainer_Impl process
SEVERE: The CPM stopped because the configured error threshold 0 was exceeded. 
(Thread Name: [Procesing Pipeline#1 Thread]::) Component Name: JodAndTika 
04/12/2009 1:26:12 PM org.apache.uima.collection.impl.cpm.engine.ProcessingUnit 
process
SEVERE: The CPM is terminating. The current component is JodAndTika. (Thread 
Name: [Procesing Pipeline#1 Thread]::) 
04/12/2009 1:26:12 PM org.apache.uima.collection.impl.cpm.engine.ProcessingUnit 
process
WARNING: The CPM cannot be stopped by force. The current container is 
JodAndTika. (Thread Name: [Procesing Pipeline#1 Thread]::) Reason: The CAS 
processor JodAndTika is configured to stop the CPM when excessive errors are 
encountered.  (Thread Name: [Procesing Pipeline#1 Thread]::)
04/12/2009 1:26:12 PM org.apache.uima.collection.impl.cpm.engine.ProcessingUnit 
maybeLogSevereException(2499)
SEVERE: Thread: [Procesing Pipeline#1 Thread]::, message: 
org.apache.uima.collection.base_cpm.AbortCPMException: 
	at 
org.apache.uima.collection.impl.cpm.container.ProcessingContainer_Impl.increment
CasProcessorErrors(ProcessingContainer_Impl.java:802)
	at 
org.apache.uima.collection.impl.cpm.engine.ProcessingUnit.processNext(Processing
Unit.java:1038)
	at 
org.apache.uima.collection.impl.cpm.engine.ProcessingUnit.run(ProcessingUnit.jav
a:577)
04/12/2009 1:26:12 PM org.apache.uima.collection.impl.cpm.engine.CPMEngine 
process
INFO: The collection reader thread state is: 1004 (Thread Name: [Procesing 
Pipeline#1 Thread]::) 
04/12/2009 1:26:12 PM org.apache.uima.collection.impl.cpm.engine.CPMEngine 
process
INFO: The CPM processing unit is 0 and processing state 2003. (Thread Name:
[Procesing Pipeline#1 Thread]::)
04/12/2009 1:26:12 PM org.apache.uima.collection.impl.cpm.engine.CPMEngine 
process
INFO: The application stopped the CPM. (Thread Name: [Procesing Pipeline#1
Thread]::)
04/12/2009 1:26:12 PM org.apache.uima.collection.impl.cpm.engine.CPMEngine 
process
INFO: The CPM engine is stopping. An end-of-file token is added to the worker 
queue. (Thread Name: [Procesing Pipeline#1 Thread]::) Forced stop: true
04/12/2009 1:26:12 PM 
org.apache.uima.collection.impl.cpm.container.deployer.vns.LocalVNS process
INFO: The CPM VNS received a request to stop. (Thread Name: CPMEngine Thread) 
[12/4/09 1:26 PM] Interrupting pooled thread: 0
[12/4/09 1:26 PM] interrupted
[12/4/09 1:26 PM] pooled thread exit
04/12/2009 1:26:12 PM 
org.apache.uima.collection.impl.cpm.container.deployer.vns.LocalVNS process
INFO: The CPM VNS activity stopped. (Thread Name: CPMEngine Thread) 
04/12/2009 1:26:12 PM 
org.apache.uima.collection.impl.cpm.container.deployer.VinciTAP 
collectionProcessComplete(1199)
INFO:  Sending COLLECTION PROCESS COMPLETE TO Service
<vinci:FRAME xmlns:vinci="http://uima.apache.org/vinci/FrameSpec/">
   <vinci:COMMAND>CollectionProcessComplete</vinci:COMMAND>
</vinci:FRAME>

04/12/2009 1:26:12 PM 
org.apache.uima.collection.impl.cpm.container.deployer.VinciTAP process
INFO: The service application stopped. (Thread Name: CPMEngine Thread) Service 
Host: 127.0.0.1 Service Port: 10001 CMD: <vinci:FRAME 
xmlns:vinci="http://uima.apache.org/vinci/FrameSpec/">
   <vinci:COMMAND>Shutdown</vinci:COMMAND>
</vinci:FRAME>

04/12/2009 1:26:14 PM 
org.apache.uima.collection.impl.cpm.container.deployer.vns.LocalVNS process
INFO: The CPM VNS received a request to stop. (Thread Name: BaseCPMImpl-Thread) 
 ** aborted
04/12/2009 1:26:14 PM 
org.apache.uima.collection.impl.cpm.container.deployer.vns.LocalVNS process
INFO: The CPM VNS activity stopped. (Thread Name: BaseCPMImpl-Thread)

--

Thanks,
Hansen

PS. Is there anyway to bypass the 80 line characters limit?  It makes it really
hard to post stack traces of exceptions.


Mime
View raw message