If you look at the aware log, you will see the service request fails.
However i am using the same service in another BSV, with success.
Can aware support please have a look at this.
This seems to be a bug.
Aware Log:
2008-01-19 18:18:18,388 Incorporex#jane -16 Finished executing process CompleteOrder
2008-01-19 18:18:18,388 Incorporex#jane -17
2008-01-19 18:18:18,388 Incorporex#jane -17
2008-01-19 18:18:21,325 Incorporex#jane -17
2008-01-19 18:18:21,325 Incorporex#jane -17
2008-01-19 18:18:21,325 Incorporex#jane -30 Updating business object PurchaseOrder 934
2008-01-19 18:18:21,325 Incorporex#jane -17
2008-01-19 18:18:21,325 Incorporex#jane -17
2008-01-19 18:18:21,325 Incorporex#jane -30 Starting execution of rules when updating business object PurchaseOrder 934
2008-01-19 18:18:21,341 Incorporex#jane -16 Finished execution of rules when updating business object PurchaseOrder 934
2008-01-19 18:18:21,341 Incorporex#jane -17
2008-01-19 18:18:21,341 Incorporex#jane -17
2008-01-19 18:18:21,341 ERROR Incorporex#jane -Execution Exception after starting system process message Access to business object PurchaseOrder is denied for Customer
2008-01-19 18:18:21,341 Incorporex#jane -16 Finished updating business object PurchaseOrder 934
2008-01-19 18:18:21,341 Incorporex#jane -17
2008-01-19 18:18:21,341 Incorporex#jane -17
2008-01-19 18:18:24,341 Incorporex#jane -17
2008-01-19 18:18:24,341 Incorporex#jane -17
2008-01-19 18:18:24,341 Incorporex#jane -20 Executing process CreatePaymentParameters
2008-01-19 18:18:24,372 Incorporex#jane -21 Process CreatePaymentParameters is suspended. Its id is 48
2008-01-19 18:18:24,372 Incorporex#jane -17
2008-01-19 18:18:24,372 Incorporex#jane -17
2008-01-19 18:18:27,263 Reply#48 -17
2008-01-19 18:18:27,263 Reply#48 -17
2008-01-19 18:18:27,263 Reply#48 -19 Resuming process 48
2008-01-19 18:18:27,263 Reply#48 -17
2008-01-19 18:18:27,263 Reply#48 -17
2008-01-19 18:18:27,263 Reply#48 -23 Starting execution of rules when creating business object MyVirtualParameters 936
2008-01-19 18:18:27,279 Reply#48 -16 Finished execution of rules when creating business object MyVirtualParameters 936
2008-01-19 18:18:27,279 Reply#48 -17
2008-01-19 18:18:27,279 Reply#48 -17
2008-01-19 18:18:27,279 Reply#48 -17
2008-01-19 18:18:27,279 Reply#48 -17
2008-01-19 18:18:27,279 Reply#48 -20 Executing process MakePayment
2008-01-19 18:18:27,279 Reply#48 -18 Process MakePayment has failed
2008-01-19 18:18:27,279 Reply#48 -17
2008-01-19 18:18:27,279 Reply#48 -17
2008-01-19 18:18:27,279 Reply#48 -Exception while executing process MakePayment. Exception class=com.bas.basserver.executionengine.ExecutionException message Operation error. Invalid service provider in the following rule:
REQUEST SERVICE MakePaymentService OF MyGate USING MyVirtualParameters
2008-01-19 18:18:27,279 ERROR Reply#48 -Execution Exception after resuming a process message Operation error. Invalid service provider in the following rule:
REQUEST SERVICE MakePaymentService OF MyGate USING MyVirtualParameters
2008-01-19 18:18:27,279 ERROR Reply#48 -18 Resumed process CreatePaymentParameters has failed. Message is Operation error. Invalid service provider in the following rule:
REQUEST SERVICE MakePaymentService OF MyGate USING MyVirtualParameters Sending failure reply
INFO: OutputAdaptor1.Controller has commited 100 transactions
2008-01-19 18:18:27,310 Reply#48 -16 Finished resuming process 48
2008-01-19 18:18:27,310 Reply#48 -17
2008-01-19 18:18:27,325 Reply#48 -17
Tomcat Log:
Next page: popped from stack /updateEntity.do?actionMethod=displayForm&formInfo=934%40%40PurchaseOrder%40%40Update%40%40%2FIncorporex_om_PurchaseOrder_131_Customer+new+order_Customer_Customer.jsp%40%40-1%40%40null%40%40-1%40%40Main%40%40true%40%401%40%40Customer+new+order%40%400%40%40n&UIContextId=null&statusString=status.operationSuccess&processFrame=null
Stack is now null
Next page: transferring to page /updateEntity.do?actionMethod=displayForm&formInfo=934%40%40PurchaseOrder%40%40Update%40%40%2FIncorporex_om_PurchaseOrder_131_Customer+new+order_Customer_Customer.jsp%40%40-1%40%40null%40%40-1%40%40Main%40%40true%40%401%40%40Customer+new+order%40%400%40%40n&UIContextId=null&statusString=status.operationSuccess&processFrame=null
Evaluating condition PurchaseOrder.State='New' AND PurchaseOrder.TotalItems>0
Condition evaluated to false:Completed=New AND 1>0
Evaluating condition PurchaseOrder.State='Completed'
Condition evaluated to true:Completed=Completed
Calling BAS server
ActionErrorException thrown with message Access to business object PurchaseOrder is denied for Customer
com.bas.webapp.actions.e: Access to business object PurchaseOrder is denied for Customer
at com.bas.webapp.actions.b.a(Unknown Source)
at com.bas.webapp.actions.UpdateEntityAction.else(Unknown Source)
at com.bas.webapp.actions.UpdateEntityAction.save_onClick(Unknown Source)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
at java.lang.reflect.Method.invoke(Unknown Source)
at com.cc.framework.adapter.struts.ActionUtil.handleFormAction(Unknown Source)
at com.cc.framework.adapter.struts.FWAction.handleFormAction(Unknown Source)
at com.cc.framework.adapter.struts.ActionUtil.execute(Unknown Source)
at com.cc.framework.adapter.struts.FWAction.execute(Unknown Source)
at com.cc.framework.adapter.struts.FWAction.execute(Unknown Source)
at com.cc.framework.adapter.struts.RequestProcessorUtil.processAction(Unknown Source)
at com.cc.framework.adapter.struts.FWRequestProcessor.processActionPerform(Unknown Source)
at org.apache.struts.action.RequestProcessor.process(RequestProcessor.java:226)
at org.apache.struts.action.ActionServlet.process(ActionServlet.java:1164)
at org.apache.struts.action.ActionServlet.doPost(ActionServlet.java:415)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:709)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:802)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:237)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:157)
at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:214)
at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:104)
at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:520)
at org.apache.catalina.core.StandardContextValve.invokeInternal(StandardContextValve.java:198-)
at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:152)
at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:104)
at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:520)
at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:137)
at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:104)
at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:118-)
at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:102)
at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:520)
at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:104)
at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:520)
at org.apache.catalina.core.ContainerBase.invoke(ContainerBase.java:929)
at org.apache.coyote.tomcat5.CoyoteAdapter.service(CoyoteAdapter.java:160)
at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:799)
at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.processConnection(Http11Protocol.java:705)
at org.apache.tomcat.util.net.TcpWorkerThread.runIt(PoolTcpEndpoint.java:577)
at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:683)
at java.lang.Thread.run(Unknown Source)
Evaluating condition PurchaseOrder.State='New' AND PurchaseOrder.TotalItems>0
Condition evaluated to false:Completed=New AND 1>0
Evaluating condition PurchaseOrder.State='Completed'
Condition evaluated to true:Completed=Completed
Pushing on stack: /updateEntity.do?actionMethod=displayForm&formInfo=934%40%40PurchaseOrder%40%40Update%40%40%2FIncorporex_om_PurchaseOrder_131_Customer+new+order_Customer_Customer.jsp%40%40-1%40%40null%40%40-1%40%40Main%40%40true%40%401%40%40Customer+new+order%40%400%40%40n&UIContextId=null&statusString=status.operationSuccess&processFrame=null
Stack is now /updateEntity.do?actionMethod=displayForm^formInfo=934%40%40PurchaseOrder%40%40Update%40%40%2FIncorporex_om_PurchaseOrder_131_Customer+new+order_Customer_Customer.jsp%40%40-1%40%40null%40%40-1%40%40Main%40%40true%40%401%40%40Customer+new+order%40%400%40%40n^UIContextId=null^statusString=status.operationSuccess^processFrame=null
Calling BAS server
Server replied to the original request that some UI operation is required
Pushing on stack: /inputEntity.do?actionMethod=handleServerRequest&UIContextId=null
Stack is now /updateEntity.do?actionMethod=displayForm^formInfo=934%40%40PurchaseOrder%40%40Update%40%40%2FIncorporex_om_PurchaseOrder_131_Customer+new+order_Customer_Customer.jsp%40%40-1%40%40null%40%40-1%40%40Main%40%40true%40%401%40%40Customer+new+order%40%400%40%40n^UIContextId=null^statusString=status.operationSuccess^processFrame=null~~/inputEntity.do?actionMethod=handleServerRequest^UIContextId=null
Starting Next page: Base context: 30899614 Stack: /updateEntity.do?actionMethod=displayForm^formInfo=934%40%40PurchaseOrder%40%40Update%40%40%2FIncorporex_om_PurchaseOrder_131_Customer+new+order_Customer_Customer.jsp%40%40-1%40%40null%40%40-1%40%40Main%40%40true%40%401%40%40Customer+new+order%40%400%40%40n^UIContextId=null^statusString=status.operationSuccess^processFrame=null~~/inputEntity.do?actionMethod=handleServerRequest^UIContextId=null
Next page: popped from stack /inputEntity.do?actionMethod=handleServerRequest&UIContextId=null
Stack is now /updateEntity.do?actionMethod=displayForm^formInfo=934%40%40PurchaseOrder%40%40Update%40%40%2FIncorporex_om_PurchaseOrder_131_Customer+new+order_Customer_Customer.jsp%40%40-1%40%40null%40%40-1%40%40Main%40%40true%40%401%40%40Customer+new+order%40%400%40%40n^UIContextId=null^statusString=status.operationSuccess^processFrame=null
Next page: transferring to page /inputEntity.do?actionMethod=handleServerRequest&UIContextId=null
Sending notification message to the server for process 48
Server replied back
Original request failed
Pushing on stack: /procFailed.do?actionMethod=displayForm&UIContextId=null&failureReason=Operation error. Invalid service provider in the following rule:
REQUEST SERVICE MakePaymentService OF MyGate USING MyVirtualParameters
Stack is now /procFailed.do?actionMethod=displayForm^UIContextId=null^failureReason=Operation error. Invalid service provider in the following rule:
REQUEST SERVICE MakePaymentService OF MyGate USING MyVirtualParameters
Starting Next page: Base context: 30899614 Stack: /procFailed.do?actionMethod=displayForm^UIContextId=null^failureReason=Operation error. Invalid service provider in the following rule:
REQUEST SERVICE MakePaymentService OF MyGate USING MyVirtualParameters
Next page: popped from stack /procFailed.do?actionMethod=displayForm&UIContextId=null&failureReason=Operation error. Invalid service provider in the following rule:
REQUEST SERVICE MakePaymentService OF MyGate USING MyVirtualParameters
Stack is now null
Next page: transferring to page /procFailed.do?actionMethod=displayForm&UIContextId=null&failureReason=Operation error. Invalid service provider in the following rule:
REQUEST SERVICE MakePaymentService OF MyGate USING MyVirtualParameters
INFO: UIOutputAdaptor.Controller has commited 100 transactions
