Error - probably related to xindice

View: New views

1 Messages — Rating Filter: Alert me

Error - probably related to xindice

by Amy Parsons :: Rate this Message:

Reply to Author | View Threaded | Show Only this Message

Hi,

I have had Twister/Agila running for a project since December. The process is a very simple one, it consists of a recieve, an invoke to a webservice and then a reply. The xml messages are quite large. I had no problems at all and everything was running smoothly until last friday, when i got the following error in the log file (see below). Everytime i sent a message to the process engine which should have started a new process instance, it gave the same error and kept rolling back. I had to clear the databases and redeploy my bpel and then everything worked again. The xml remained unchanged from that which worked fine for several months.

I am a bit concerned that this error could happen again in another few months. Has anyone else had this problem before, or know what might be causing it? It looks like it might be related to the xindice xml database not being able to store the xml message, but i do not understand why, as its the same xml that is always sent. Or maybe this is a known problem that has been fixed?

Thanks for any help you can offer.

Kind Regards

Amy

INFO | jvm 1 | 2006/03/03 16:53:27 | [Twister] DEBUG [http-8080-Processor25] EngineWSA.sendToEngine(66) | The following message has been received : <?xml version="1.0" encoding="UTF-8"?>
INFO | jvm 1 | 2006/03/03 16:53:27 | <message><message><request><header><action>PRINTBOX</action><process>OFFNW</process><group>08004</group><version>001</version><pdf-filename>11414011973331.pdf</pdf-filename><pdf-encoding>Base64</pdf-encoding></header><body><product><contract><AL><AL_FUNCTIE>01</AL_FUNCTIE><AL_VIEWCOD>08004</AL_VIEWCOD><AL_VERSIEV>101</AL_VERSIEV><AL_APPLVS>001</AL_APPLVS><AL_APPLNM>BB</AL_APPLNM><AL_RECCRT>34503</AL_RECCRT></AL><PP><PP_EXTERN>000001111122222</PP_EXTERN><PP_MYAAND>D016</PP_MYAAND><DA><DA_VERZSOM>1000000</DA_VERZSOM><DA_CODE>6001</DA_CODE><DA_GSS>Z</DA_GSS><DA_MVZJAG>N</DA_MVZJAG></DA><PP_INGDAT>2006-03-20</PP_INGDAT><PP_CDUUMND>12</PP_CDUUMND><PP_BETTERM>12</PP_BETTERM><VP><VP_PCODE>7534ka</VP_PCODE><VP_LAND>NL</VP_LAND></VP></PP></contract></product></body></request></message></message>
INFO | jvm 1 | 2006/03/03 16:53:27 | [Twister] DEBUG [http-8080-Processor25] EngineWSA.sendToEngine(67) | Sending it to (WsBlackBoxServiceInitiator, pageProcessorPortType, http://www.smartcomps.org/twister, initiate)
INFO | jvm 1 | 2006/03/03 16:53:27 | [Twister] INFO [http-8080-Processor25] DBSessionManager.beginTransaction(64) | Beginning transaction with thread : Thread[http-8080-Processor25,5,main] and session net.sf.hibernate.impl.SessionImpl@ec42e0
INFO | jvm 1 | 2006/03/03 16:53:27 | [Twister] DEBUG [http-8080-Processor25] TwisterEngineImpl.acknowledge(55) | Finding up which activity instance the message is targeted at.
INFO | jvm 1 | 2006/03/03 16:53:58 | [Twister] DEBUG [http-8080-Processor25] MessageControllerImpl.acknowledge(307) | Step 5.
INFO | jvm 1 | 2006/03/03 16:53:58 | [Twister] DEBUG [http-8080-Processor25] MessageControllerImpl.acknowledge(324) | Step 5.1.
INFO | jvm 1 | 2006/03/03 16:53:58 | [Twister] DEBUG [http-8080-Processor25] TwisterEngineImpl.acknowledge(57) | An activity instance has been found.
INFO | jvm 1 | 2006/03/03 16:53:58 | [Twister] INFO [http-8080-Processor25] DBSessionManager.commitTransaction(85) | Commiting transaction with thread : Thread[http-8080-Processor25,5,main]
INFO | jvm 1 | 2006/03/03 16:53:58 | [Twister] DEBUG [http-8080-Processor25] TwisterEngineImpl.acknowledge(101) | Forwarding message to activity ExecutionContextImpl{id=655362,status=1,index=1} ::: ReceiveECImpl{waiting for "initiate" from partner "WsBlackBoxServiceInitiator". Asynchronous callback.}
INFO | jvm 1 | 2006/03/03 16:53:58 | [Twister] INFO [http-8080-Processor25] DBSessionManager.beginTransaction(64) | Beginning transaction with thread : Thread[http-8080-Processor25,5,main] and session net.sf.hibernate.impl.SessionImpl@9f6948
INFO | jvm 1 | 2006/03/03 16:54:18 | Mar 3, 2006 4:54:18 PM org.apache.xindice.xml.XMLCompressedInput readContentSize
INFO | jvm 1 | 2006/03/03 16:54:18 | WARNING: invalid node type : 5
INFO | jvm 1 | 2006/03/03 16:54:18 | Mar 3, 2006 4:54:18 PM org.apache.xindice.xml.XMLCompressedInput readContentSize
INFO | jvm 1 | 2006/03/03 16:54:18 | WARNING: invalid node type : 5
INFO | jvm 1 | 2006/03/03 16:54:18 | Mar 3, 2006 4:54:18 PM org.apache.xindice.xml.dom.ElementImpl checkLoaded
INFO | jvm 1 | 2006/03/03 16:54:18 | WARNING: ignored exception
INFO | jvm 1 | 2006/03/03 16:54:18 | java.lang.NullPointerException
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.xindice.xml.dom.ElementImpl.checkLoaded(ElementImpl.java:105)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.xindice.xml.dom.NodeImpl.getPrefix(NodeImpl.java:701)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.xindice.xml.dom.NodeImpl.getNamespaceURI(NodeImpl.java:664)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.dom4j.io.DOMReader.readElement(DOMReader.java:181)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.dom4j.io.DOMReader.readTree(DOMReader.java:106)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.dom4j.io.DOMReader.read(DOMReader.java:88)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.smcp.twister.engine.common.persistence.XMLDataAccess.getDocument(XMLDataAccess.java:81)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.smcp.twister.engine.priv.core.dynamic.impl.xao.VariableXAO.getVariable(VariableXAO.java:31)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.smcp.twister.engine.priv.core.dynamic.impl.InvokeECImpl.getInput(InvokeECImpl.java:83)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.smcp.twister.engine.priv.core.dynamic.impl.InvokeECImpl.execute(InvokeECImpl.java:47)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.smcp.twister.engine.priv.core.dynamic.impl.SequenceECImpl.executeContextAt(SequenceECImpl.java:66)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.smcp.twister.engine.priv.core.dynamic.impl.SequenceECImpl.notifyTermination(SequenceECImpl.java:52)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.smcp.twister.engine.priv.core.dynamic.impl.ExecutionContextImpl.notifyTerminationToContainer(ExecutionContextImpl.java:157)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.smcp.twister.engine.priv.core.dynamic.impl.ReceiveECImpl.acknowledgeMessage(ReceiveECImpl.java:57)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.smcp.twister.engine.priv.TwisterEngineImpl.syncAcknowledge(TwisterEngineImpl.java:233)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.smcp.twister.engine.priv.TwisterEngineImpl.acknowledge(TwisterEngineImpl.java:106)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.smcp.twister.wsa.EngineWSA.sendToEngine(EngineWSA.java:71)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.smcp.twister.wsa.EngineWSA.acknowledge(EngineWSA.java:232)
INFO | jvm 1 | 2006/03/03 16:54:18 | at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
INFO | jvm 1 | 2006/03/03 16:54:18 | at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
INFO | jvm 1 | 2006/03/03 16:54:18 | at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
INFO | jvm 1 | 2006/03/03 16:54:18 | at java.lang.reflect.Method.invoke(Method.java:324)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.axis.providers.java.MsgProvider.processMessage(MsgProvider.java:155)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.axis.providers.java.JavaProvider.invoke(JavaProvider.java:319)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.axis.strategies.InvocationStrategy.visit(InvocationStrategy.java:32)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.axis.SimpleChain.doVisiting(SimpleChain.java:118)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.axis.SimpleChain.invoke(SimpleChain.java:83)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.axis.handlers.soap.SOAPService.invoke(SOAPService.java:453)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.axis.server.AxisServer.invoke(AxisServer.java:281)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.axis.transport.http.AxisServlet.doPost(AxisServlet.java:697)
INFO | jvm 1 | 2006/03/03 16:54:18 | at javax.servlet.http.HttpServlet.service(HttpServlet.java:709)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.axis.transport.http.AxisServletBase.service(AxisServletBase.java:327)
INFO | jvm 1 | 2006/03/03 16:54:18 | at javax.servlet.http.HttpServlet.service(HttpServlet.java:802)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:237)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:157)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:214)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:104)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:520)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.catalina.core.StandardContextValve.invokeInternal(StandardContextValve.java:198)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:152)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:104)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:520)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:137)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:104)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:118)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:102)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:520)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:104)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:520)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.catalina.core.ContainerBase.invoke(ContainerBase.java:929)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.coyote.tomcat5.CoyoteAdapter.service(CoyoteAdapter.java:160)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:799)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.processConnection(Http11Protocol.java:705)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.tomcat.util.net.TcpWorkerThread.runIt(PoolTcpEndpoint.java:577)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:683)
INFO | jvm 1 | 2006/03/03 16:54:18 | at java.lang.Thread.run(Thread.java:534)
INFO | jvm 1 | 2006/03/03 16:54:18 | java.lang.NullPointerException
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.xindice.xml.dom.NodeImpl.getPrefix(NodeImpl.java:702)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.xindice.xml.dom.NodeImpl.getNamespaceURI(NodeImpl.java:664)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.dom4j.io.DOMReader.readElement(DOMReader.java:181)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.dom4j.io.DOMReader.readTree(DOMReader.java:106)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.dom4j.io.DOMReader.read(DOMReader.java:88)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.smcp.twister.engine.common.persistence.XMLDataAccess.getDocument(XMLDataAccess.java:81)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.smcp.twister.engine.priv.core.dynamic.impl.xao.VariableXAO.getVariable(VariableXAO.java:31)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.smcp.twister.engine.priv.core.dynamic.impl.InvokeECImpl.getInput(InvokeECImpl.java:83)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.smcp.twister.engine.priv.core.dynamic.impl.InvokeECImpl.execute(InvokeECImpl.java:47)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.smcp.twister.engine.priv.core.dynamic.impl.SequenceECImpl.executeContextAt(SequenceECImpl.java:66)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.smcp.twister.engine.priv.core.dynamic.impl.SequenceECImpl.notifyTermination(SequenceECImpl.java:52)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.smcp.twister.engine.priv.core.dynamic.impl.ExecutionContextImpl.notifyTerminationToContainer(ExecutionContextImpl.java:157)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.smcp.twister.engine.priv.core.dynamic.impl.ReceiveECImpl.acknowledgeMessage(ReceiveECImpl.java:57)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.smcp.twister.engine.priv.TwisterEngineImpl.syncAcknowledge(TwisterEngineImpl.java:233)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.smcp.twister.engine.priv.TwisterEngineImpl.acknowledge(TwisterEngineImpl.java:106)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.smcp.twister.wsa.EngineWSA.sendToEngine(EngineWSA.java:71)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.smcp.twister.wsa.EngineWSA.acknowledge(EngineWSA.java:232)
INFO | jvm 1 | 2006/03/03 16:54:18 | at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
INFO | jvm 1 | 2006/03/03 16:54:18 | at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
INFO | jvm 1 | 2006/03/03 16:54:18 | at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
INFO | jvm 1 | 2006/03/03 16:54:18 | at java.lang.reflect.Method.invoke(Method.java:324)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.axis.providers.java.MsgProvider.processMessage(MsgProvider.java:155)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.axis.providers.java.JavaProvider.invoke(JavaProvider.java:319)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.axis.strategies.InvocationStrategy.visit(InvocationStrategy.java:32)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.axis.SimpleChain.doVisiting(SimpleChain.java:118)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.axis.SimpleChain.invoke(SimpleChain.java:83)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.axis.handlers.soap.SOAPService.invoke(SOAPService.java:453)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.axis.server.AxisServer.invoke(AxisServer.java:281)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.axis.transport.http.AxisServlet.doPost(AxisServlet.java:697)
INFO | jvm 1 | 2006/03/03 16:54:18 | at javax.servlet.http.HttpServlet.service(HttpServlet.java:709)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.axis.transport.http.AxisServletBase.service(AxisServletBase.java:327)
INFO | jvm 1 | 2006/03/03 16:54:18 | at javax.servlet.http.HttpServlet.service(HttpServlet.java:802)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:237)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:157)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:214)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:104)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:520)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.catalina.core.StandardContextValve.invokeInternal(StandardContextValve.java:198)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:152)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:104)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:520)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:137)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:104)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:118)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:102)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:520)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:104)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:520)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.catalina.core.ContainerBase.invoke(ContainerBase.java:929)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.coyote.tomcat5.CoyoteAdapter.service(CoyoteAdapter.java:160)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:799)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.processConnection(Http11Protocol.java:705)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.tomcat.util.net.TcpWorkerThread.runIt(PoolTcpEndpoint.java:577)
INFO | jvm 1 | 2006/03/03 16:54:18 | at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:683)
INFO | jvm 1 | 2006/03/03 16:54:18 | at java.lang.Thread.run(Thread.java:534)
INFO | jvm 1 | 2006/03/03 16:54:18 | [Twister] INFO [http-8080-Processor25] DBSessionManager.rollbackTransaction(105) | Rollbacking transaction with thread : Thread[http-8080-Processor25,5,main]
INFO | jvm 1 | 2006/03/03 16:54:18 | [Twister] DEBUG [http-8080-Processor25] EngineWSA.sendToEngine(103) | Producing reply: <?xml version="1.0" encoding="UTF-8"?>
INFO | jvm 1 | 2006/03/03 16:54:18 | <message><return-code>1</return-code></message>

LightInTheBox - Buy quality products at wholesale price