Order status

Previous Topic Next Topic
 
classic Classic list List threaded Threaded
41 messages Options
123
Reply | Threaded
Open this post in threaded view
|

Re: Order status

Scott Gray-2
You're welcome, but like I said in that other email it would be a good  
idea to update past 731858.

Regards
Scott

On 8/05/2009, at 8:09 PM, madppiper wrote:

>
> WOOOOO!!!!
>
>
> That was exactly the right comment! Turns out that my  
> InvoiceServices.java
> file still contained one line where the BigDecimal was back-
> transformed into
> a Double :)
>
> I already checked, perhaps this has been fixed on a later revision,  
> because
> the most recent one has it already fixed...
>
> THANK YOU SO MUCH!
>
>
>
>
> Scott Gray-2 wrote:
>>
>> The type error should be easy enough to fix, according to the log:
>>> org
>>> .ofbiz
>>> .accounting
>>> .invoice.InvoiceServices.createInvoiceForOrder(InvoiceServices.java:
>>> 789)
>> is calling the createPaymentApplication service but incorrectly
>> passing in an amountApplied of type double rather than BigDecimal.
>>
>> Regards
>> Scott
>>
>> On 8/05/2009, at 7:16 PM, madppiper wrote:
>>
>>>
>>> Lol,
>>>
>>> well at least I got a good chuckle out of this ;)
>>>
>>> But anyway. Perhaps I should describe the problem in full once again
>>> (this
>>> could be rather long):
>>>
>>> For our own webstore, I integrated Moneybookers as our payment
>>> partner as
>>> well as a logistics company for the warehousing. Most of our
>>> components are
>>> from the major Revision on January 6th. The integration of
>>> moneybookers
>>> works as the following:
>>>
>>> 1) After the order has been created, I am preparing a transaction
>>> session
>>> internally (you do so by sending data to moneybookers) and using
>>> that very
>>> same session as a hyperlink within an iframe.
>>>
>>> 2) As the user clicks through the moneybookers terminal within the
>>> iframe
>>> and completes the payment, moneybookers will send status messages to
>>> a given
>>> url (this is much like the IPN Process with Paypal). So I used the
>>> PayPal
>>> java class as a reference and build the following:
>>>
>>>
>>>  Locale locale = UtilHttp.getLocale(request);
>>>       GenericDelegator delegator = (GenericDelegator)
>>> request.getAttribute("delegator");
>>>       LocalDispatcher dispatcher = (LocalDispatcher)
>>> request.getAttribute("dispatcher");
>>>
>>>
>>>       /*Special*/
>>>       String orderId = (String)
>>> request.getParameter("transaction_id");
>>>       Debug.logWarning("Setting orderId: " + orderId, module);
>>>
>>>    // get the order header
>>>       GenericValue orderHeader = null;
>>>       try {
>>>           orderHeader = delegator.findByPrimaryKey("OrderHeader",
>>> UtilMisc.toMap("orderId", orderId));
>>>       } catch (GenericEntityException e) {
>>>           Debug.logError(e, "Cannot get the order header for order:
>>> " +
>>> orderId, module);
>>>           request.setAttribute("_ERROR_MESSAGE_",
>>> UtilProperties.getMessage(resourceErr,
>>> "payPalEvents.problemsGettingOrderHeader", locale));
>>>           return "error";
>>>       }
>>>       Debug.logWarning("orderHeader:
>>> "+orderHeader.toString(),module);
>>>
>>>       // get the product store
>>>       GenericValue productStore =
>>> ProductStoreWorker.getProductStore(request);
>>>       if (productStore == null) {
>>>           Debug.logError("ProductStore is null", module);
>>>           request.setAttribute("_ERROR_MESSAGE_",
>>> UtilProperties.getMessage(resourceErr,
>>> "moneybookersEvents.problemsGettingMerchantConfiguration", locale));
>>>           return "error";
>>>       }
>>>
>>>       // get the order total
>>>      // String orderTotal =
>>> orderHeader.getBigDecimal("grandTotal").toPlainString();
>>>
>>>       // get the payment properties file
>>>       GenericValue paymentConfig =
>>> ProductStoreWorker.getProductStorePaymentSetting(delegator,
>>> productStore.getString("productStoreId"), "EXT_MONEYBOOKERS", null,
>>> true);
>>>       String configString = null;
>>>       if (paymentConfig != null) {
>>>           configString =
>>> paymentConfig.getString("paymentPropertiesPath");
>>>       }
>>>
>>>       if (configString == null) {
>>>           configString = "payment.properties";
>>>       }
>>>
>>>
>>>
>>>       // get the user
>>>       GenericValue userLogin = null;
>>>
>>>
>>>       String userLoginId = null;//request.getParameter("custom1");
>>>
>>>       if (userLoginId == null)
>>>           userLoginId = "admin";
>>>       try {
>>>           userLogin = delegator.findByPrimaryKey("UserLogin",
>>> UtilMisc.toMap("userLoginId", userLoginId));
>>>       } catch (GenericEntityException e) {
>>>           Debug.logError(e, "Cannot get UserLogin for: " +
>>> userLoginId +
>>> "; cannot continue", module);
>>>           request.setAttribute("_ERROR_MESSAGE_",
>>> UtilProperties.getMessage(resourceErr,
>>> "moneybookersEvents.problemsGettingAuthenticationUser", locale));
>>>           return "error";
>>>       }
>>>
>>>
>>>
>>>       /*  get payment data
>>>       String paymentCurrency = request.getParameter("mc_currency");
>>>       String paymentAmount = request.getParameter("mc_gross");
>>>       String paymentFee = request.getParameter("mc_fee");
>>>       String transactionId = request.getParameter("txn_id");
>>>       */
>>>
>>>       // get the transaction status
>>>       String paymentStatus =
>>> request.getParameter("status").toString();
>>>
>>>       // attempt to start a transaction
>>>       boolean okay = true;
>>>       boolean beganTransaction = false;
>>>       try {
>>>           beganTransaction = TransactionUtil.begin();
>>>
>>>           if (paymentStatus.equals("2")) {
>>>               Debug.logInfo("Order Approved...", module);
>>>               okay = OrderChangeHelper.approveOrder(dispatcher,
>>> userLogin,
>>> orderId);
>>>
>>>           } else if (paymentStatus.equals("-1") ||
>>> paymentStatus.equals("-2")) {
>>>               Debug.logInfo("Order Cancelled...", module);
>>>               okay = OrderChangeHelper.cancelOrder(dispatcher,
>>> userLogin,
>>> orderId);
>>>           }
>>>
>>>           if (okay) {
>>>               // set the payment preference
>>>               okay = setPaymentPreferences(delegator, dispatcher,
>>> userLogin, orderId, request);
>>>           }
>>>       } catch (Exception e) {
>>>           String errMsg = "Error handling Moneybookers  
>>> notification";
>>>           Debug.logError(e, errMsg, module);
>>>           try {
>>>               TransactionUtil.rollback(beganTransaction, errMsg, e);
>>>           } catch (GenericTransactionException gte2) {
>>>               Debug.logError(gte2, "Unable to rollback transaction",
>>> module);
>>>           }
>>>       } finally {
>>>           if (!okay) {
>>>               try {
>>>                   TransactionUtil.rollback(beganTransaction,
>>> "Failure in
>>> processing Moneybookers callback", null);
>>>               } catch (GenericTransactionException gte) {
>>>                   Debug.logError(gte, "Unable to rollback
>>> transaction",
>>> module);
>>>               }
>>>           } else {
>>>               try {
>>>                   TransactionUtil.commit(beganTransaction);
>>>               } catch (GenericTransactionException gte) {
>>>                   Debug.logError(gte, "Unable to commit  
>>> transaction",
>>> module);
>>>               }
>>>           }
>>>       }
>>>
>>>
>>>       if (okay) {
>>>           // attempt to release the offline hold on the order
>>> (workflow)
>>>           OrderChangeHelper.releaseInitialOrderHold(dispatcher,
>>> orderId);
>>>
>>>           // call the email confirm service
>>>           Map <String, String> emailContext =
>>> UtilMisc.toMap("orderId",
>>> orderId);
>>>           try {
>>>               dispatcher.runSync("sendOrderConfirmation",
>>> emailContext);
>>>           } catch (GenericServiceException e) {
>>>               Debug.logError(e, "Problems sending email
>>> confirmation",
>>> module);
>>>           }
>>>       }
>>>
>>>
>>> 3) Within Ofbiz, the order is now approved and the
>>> setPaymentPreferences
>>> procedure is called (again: just like in Paypal with first
>>>
>>> private static boolean setPaymentPreferences(GenericDelegator
>>> delegator,
>>> LocalDispatcher dispatcher, GenericValue userLogin, String orderId,
>>> HttpServletRequest request)
>>>
>>> being called and
>>>
>>>   private static boolean setPaymentPreference(LocalDispatcher
>>> dispatcher,
>>> GenericValue userLogin, GenericValue paymentPreference,
>>> HttpServletRequest
>>> request)
>>>
>>> thereafter)
>>>
>>> 4)
>>>
>>> The second setPaymentPreference then checks the payment and updates
>>> the
>>> Payment Status:
>>>
>>>
>>> Locale locale = UtilHttp.getLocale(request);
>>>
>>>       String paymentType = request.getParameter("payment_type");
>>>       String paymentStatus = request.getParameter("status");
>>>       String paymentAmount = request.getParameter("mb_amount");
>>>       if(paymentType == "null" || paymentType == null) paymentType
>>> ="WLT";
>>>       String transactionId =
>>> request.getParameter("mb_transaction_id");
>>>
>>>      // request.getSession().setAttribute("MONEYBOOKERS_ORDER",
>>> transactionId);
>>>
>>>       List <GenericValue> toStore = new LinkedList <GenericValue>  
>>> ();
>>>
>>>       // moneybookers returns the timestamp in the format 'hh:mm:ss
>>> Jan 1,
>>> 2000 PST'
>>>       /* Parse this into a valid Timestamp Object*/
>>>
>>>
>>>       java.sql.Timestamp authDate = UtilDateTime.nowTimestamp();
>>>
>>>       paymentPreference.set("maxAmount", new
>>> BigDecimal(paymentAmount));
>>>       if (paymentStatus.equals("2")) {
>>>           paymentPreference.set("statusId", "PAYMENT_RECEIVED");
>>>       } else if (paymentStatus.equals("0")) {
>>>           paymentPreference.set("statusId", "PAYMENT_NOT_RECEIVED");
>>>       } else {
>>>           paymentPreference.set("statusId", "PAYMENT_CANCELLED");
>>>       }
>>>       toStore.add(paymentPreference);
>>>
>>>       GenericDelegator delegator = paymentPreference.getDelegator();
>>>
>>>       // create the PaymentGatewayResponse
>>>       String responseId =
>>> delegator.getNextSeqId("PaymentGatewayResponse");
>>>       Debug.logInfo("PaymentGateWayResponse: "+responseId, module);
>>>       GenericValue response =
>>> delegator.makeValue("PaymentGatewayResponse");
>>>       response.set("paymentGatewayResponseId", responseId);
>>>       response.set("paymentServiceTypeEnumId", "PRDS_PAY_EXTERNAL");
>>>       response.set("orderPaymentPreferenceId",
>>> paymentPreference.get("orderPaymentPreferenceId"));
>>>       response.set("paymentMethodTypeId",
>>> paymentPreference.get("paymentMethodTypeId"));
>>>       response.set("paymentMethodId",
>>> paymentPreference.get("paymentMethodId"));
>>>
>>>       // set the auth info
>>>       response.set("amount", new BigDecimal(paymentAmount));
>>>       response.set("referenceNum", transactionId);
>>>       response.set("gatewayCode", paymentStatus);
>>>       response.set("gatewayFlag", paymentStatus);
>>>       response.set("gatewayMessage", paymentType);
>>>       response.set("transactionDate", authDate);
>>>
>>>       toStore.add(response);
>>>
>>>       try {
>>>           delegator.storeAll(toStore);
>>>       } catch (GenericEntityException e) {
>>>           Debug.logError(e, "Cannot set payment preference/payment
>>> info",
>>> module);
>>>           return false;
>>>       }
>>>
>>>       // create a payment record too
>>>       Map <String, Object> results = null;
>>>       Debug.logWarning("Processing In Parameters..", module);
>>>
>>>       try {
>>>           String comment = UtilProperties.getMessage(resource,
>>> "AccountingPaymentReceiveViaMoneybookers", locale);
>>>           results =  
>>> dispatcher.runSync("createPaymentFromPreference",
>>> UtilMisc.toMap("userLogin", userLogin,
>>>                   "orderPaymentPreferenceId",
>>> paymentPreference.get("orderPaymentPreferenceId"), "comments",
>>> comment));
>>>       } catch (GenericServiceException e) {
>>>           Debug.logError(e, "Failed to execute service
>>> createPaymentFromPreference", module);
>>>           request.setAttribute("_ERROR_MESSAGE_",
>>> UtilProperties.getMessage(resourceErr,
>>> "moneybookersEvents
>>> .failedToExecuteServiceCreatePaymentFromPreference",
>>> locale));
>>>           return false;
>>>       }
>>>
>>>       if ((results == null) ||
>>> (results
>>> .get
>>> (ModelService.RESPONSE_MESSAGE).equals(ModelService.RESPOND_ERROR)))
>>> {
>>>           Debug.logError((String)
>>> results.get(ModelService.ERROR_MESSAGE),
>>> module);
>>>           request.setAttribute("_ERROR_MESSAGE_", (String)
>>> results.get(ModelService.ERROR_MESSAGE));
>>>           return false;
>>>       }
>>>
>>>
>>>
>>> 5) The Order is updated in full and is now set to Approved with a
>>> Payment
>>> (Payment Created, Payment_Received) Shipping information and
>>> everything else
>>> available just as with any other order. (Yes, I checked the entities
>>> for the
>>> data, everything is right how it should be)
>>>
>>> 6) I am now trying to call the quickShipEntireOrder Service (this
>>> one works
>>> fine with a Cash on Delivery Payment, but I do not know how it
>>> reacts to a
>>> paypal payment). It is there where I receive several errors. I tried
>>> to
>>> trace it all back and it seems that right after the invoice is
>>> created, the
>>> following happens:
>>>
>>>
>>> Error: Error trying to begin transaction, could not process method:
>>> The
>>> current transaction is marked for rollback, not beginning a new
>>> transaction
>>> and aborting current operation; the rollbackOnly was caused by:
>>> Service
>>> [createPaymentApplication] threw an unexpected
>>> exception/errororg.ofbiz.service.ServiceValidationException: Type
>>> check
>>> failed for field [createPaymentApplication.amountApplied]; expected
>>> type is
>>> [BigDecimal]; actual type is [java.lang.Double] (Type check failed
>>> for field
>>> [createPaymentApplication.amountApplied]; expected type is
>>> [BigDecimal];
>>> actual type is [java.lang.Double])   calling service  
>>> updateShipment in
>>> createShipmentForFacilityAndShipGroup
>>>
>>>
>>> And the error in a full dump:
>>>
>>> 009-05-08 09:10:51,604 (http-0.0.0.0-8443-2) [ ControlServlet.java:
>>> 131:INFO
>>> ] [[[quickShipOrder] Servlet Starting, doing setup- total:0.0,since
>>> last(Begin):0.0]]
>>> 2009-05-08 09:10:51,654 (http-0.0.0.0-8443-2) [ ControlServlet.java:
>>> 196:INFO
>>> ] [[[quickShipOrder] Setup done, doing Event(s) and View(s)-
>>> total:0.05,since last([quickShipOrder] ...):0.05]]
>>> 2009-05-08 09:10:51,681 (http-0.0.0.0-8443-2) [ UtilXml.java:
>>> 246:INFO ] XML
>>> Read 0.027s: jndi:/0.0.0.0/ordermgr/WEB-INF/controller.xml
>>> 2009-05-08 09:10:51,693 (http-0.0.0.0-8443-2) [ UtilXml.java:
>>> 246:INFO ] XML
>>> Read 0.0090s:
>>> file:/opt/ofbiz/framework/common/webcommon/WEB-INF/common-
>>> controller.xml
>>> 2009-05-08 09:10:51,694 (http-0.0.0.0-8443-2) [
>>> ConfigXMLReader.java:631:INFO ] ConfigMap Created: (4) records in
>>> 0.01s
>>> 2009-05-08 09:10:51,695 (http-0.0.0.0-8443-2) [
>>> ConfigXMLReader.java:631:INFO ] ConfigMap Created: (4) records in
>>> 0.013s
>>> 2009-05-08 09:10:51,704 (http-0.0.0.0-8443-2) [ UtilXml.java:
>>> 246:INFO ] XML
>>> Read 0.0080s:
>>> file:/opt/ofbiz/framework/common/webcommon/WEB-INF/common-
>>> controller.xml
>>> 2009-05-08 09:10:51,713 (http-0.0.0.0-8443-2) [
>>> ConfigXMLReader.java:774:INFO ] HandlerMap Created: (7) view
>>> handlers and
>>> (11) request/event handlers in 0.017s
>>> 2009-05-08 09:10:51,721 (http-0.0.0.0-8443-2) [
>>> ConfigXMLReader.java:774:INFO ] HandlerMap Created: (7) view
>>> handlers and
>>> (13) request/event handlers in 0.026s
>>> 2009-05-08 09:10:51,730 (http-0.0.0.0-8443-2) [ UtilXml.java:
>>> 246:INFO ] XML
>>> Read 0.0090s:
>>> file:/opt/ofbiz/framework/common/webcommon/WEB-INF/common-
>>> controller.xml
>>> 2009-05-08 09:10:51,739 (Dispatcher-Thread-1) [ ?:? :INFO ]
>>> Discarded 14
>>> messages due to full event buffer including: RequestMap Created:  
>>> (14)
>>> records in 0.016s
>>> 2009-05-08 09:10:51,897 (http-0.0.0.0-8443-2) [
>>> ConfigXMLReader.java:379:INFO ] RequestMap Created: (312) records in
>>> 0.176s
>>> 2009-05-08 09:10:51,907 (http-0.0.0.0-8443-2) [ UtilXml.java:
>>> 246:INFO ] XML
>>> Read 0.0090s:
>>> file:/opt/ofbiz/framework/common/webcommon/WEB-INF/common-
>>> controller.xml
>>> 2009-05-08 09:10:51,913 (http-0.0.0.0-8443-2) [
>>> ConfigXMLReader.java:467:INFO ] ViewMap Created: (12) records in
>>> 0.015s
>>> 2009-05-08 09:10:51,973 (http-0.0.0.0-8443-2) [
>>> ConfigXMLReader.java:467:INFO ] ViewMap Created: (138) records in
>>> 0.075s
>>> 2009-05-08 09:10:51,985 (Dispatcher-Thread-1) [ ?:? :INFO ]
>>> Discarded 2
>>> messages due to full event buffer including: XML Read 0.0090s:
>>> file:/opt/ofbiz/framework/common/webcommon/WEB-INF/common-
>>> controller.xml
>>> 2009-05-08 09:10:52,004 (http-0.0.0.0-8443-2) [ RequestHandler.java:
>>> 256:INFO
>>> ] [Processing Request]: quickShipOrder
>>> sessionId=B6AF9B84C66AC116E0A250074D2D1045.jvm1
>>> 2009-05-08 09:10:52,078 (http-0.0.0.0-8443-2) [ UtilXml.java:
>>> 246:INFO ] XML
>>> Read 0.061s:
>>> file:/opt/ofbiz/applications/product/script/org/ofbiz/shipment/
>>> shipment/ShipmentServices.xml
>>> 2009-05-08 09:10:52,347 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [createShipment] on [commit] got false for
>>> condition:
>>> [statusId][equals][SHIPMENT_SCHEDULED][true][String]
>>> 2009-05-08 09:10:52,350 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 150:INFO
>>> ] Running Service ECA Service: setShipmentSettingsFromFacilities,
>>> triggered
>>> by rule on Service: createShipment
>>> 2009-05-08 09:10:52,740 (http-0.0.0.0-8443-2) [ FieldToResult.java:
>>> 76 :WARN
>>> ] Field value not found with name lookedUpValue.shipmentTypeId in
>>> Map with
>>> name
>>> 2009-05-08 09:10:52,756 (http-0.0.0.0-8443-2) [ FieldToResult.java:
>>> 76 :WARN
>>> ] Field value not found with name
>>> lookedUpValue.destinationFacilityId in Map
>>> with name
>>> 2009-05-08 09:10:52,782 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [updateShipment] on [commit] got false for
>>> condition:
>>> [shipmentTypeId][equals][SALES_SHIPMENT][true][String]
>>> 2009-05-08 09:10:52,786 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [updateShipment] on [commit] got false for
>>> condition:
>>> [statusId][equals][SHIPMENT_PACKED][true][String]
>>> 2009-05-08 09:10:52,789 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [updateShipment] on [commit] got false for
>>> condition:
>>> [statusId][equals][SHIPMENT_SHIPPED][true][String]
>>> 2009-05-08 09:10:52,793 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [updateShipment] on [commit] got false for
>>> condition:
>>> [statusId][equals][PURCH_SHIP_RECEIVED][true][String]
>>> 2009-05-08 09:10:52,797 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [updateShipment] on [commit] got false for
>>> condition:
>>> [statusId][equals][PURCH_SHIP_SHIPPED][true][String]
>>> 2009-05-08 09:10:52,800 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [updateShipment] on [commit] got false for
>>> condition:
>>> [statusId][equals][PURCH_SHIP_RECEIVED][true][String]
>>> 2009-05-08 09:10:52,804 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [updateShipment] on [commit] got false for
>>> condition:
>>> [statusId][equals][PURCH_SHIP_RECEIVED][true][String]
>>> 2009-05-08 09:10:52,807 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [updateShipment] on [commit] got false for
>>> condition:
>>> [statusId][equals][SHIPMENT_SHIPPED][true][String]
>>> 2009-05-08 09:10:52,810 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [updateShipment] on [commit] got false for
>>> condition:
>>> [statusId][equals][SHIPMENT_SCHEDULED][true][String]
>>> 2009-05-08 09:10:52,817 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [updateShipment] on [commit] got false for
>>> condition:
>>> [originFacilityId][not-equals][oldOriginFacilityId][false][String]
>>> 2009-05-08 09:10:52,820 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [updateShipment] on [commit] got false for
>>> condition:
>>> [destinationFacilityId][is-not-empty][true][String]
>>> 2009-05-08 09:10:52,835 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [updateShipment] on [commit] got false for
>>> condition:
>>> [primaryOrderId][not-equals][oldPrimaryOrderId][false][String]
>>> 2009-05-08 09:10:52,836 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service [order/
>>> updateShipment]
>>> finished in [210] milliseconds
>>> 2009-05-08 09:10:52,844 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/setShipmentSettingsFromFacilities] finished in [486]
>>> milliseconds
>>> 2009-05-08 09:10:52,846 (http-0.0.0.0-8443-2)
>>> [ServiceEcaCondition.java:155:WARN ] From Field
>>> (destinationFacilityId) is
>>> not found in context for createShipment, defaulting to null.
>>> 2009-05-08 09:10:52,847 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [createShipment] on [commit] got false for
>>> condition:
>>> [destinationFacilityId][is-not-empty][true][String]
>>> 2009-05-08 09:10:52,850 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 150:INFO
>>> ] Running Service ECA Service: setShipmentSettingsFromPrimaryOrder,
>>> triggered by rule on Service: createShipment
>>> 2009-05-08 09:10:53,417 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/createShipmentRouteSegment] finished in [213] milliseconds
>>> 2009-05-08 09:10:53,542 (http-0.0.0.0-8443-2) [ FieldToResult.java:
>>> 76 :WARN
>>> ] Field value not found with name lookedUpValue.shipmentTypeId in
>>> Map with
>>> name
>>> 2009-05-08 09:10:53,549 (http-0.0.0.0-8443-2) [ FieldToResult.java:
>>> 76 :WARN
>>> ] Field value not found with name
>>> lookedUpValue.destinationFacilityId in Map
>>> with name
>>> 2009-05-08 09:10:53,588 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [updateShipment] on [commit] got false for
>>> condition:
>>> [statusId][equals][SHIPMENT_CANCELLED][true][String]
>>> 2009-05-08 09:10:53,593 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [updateShipment] on [commit] got false for
>>> condition:
>>> [statusId][equals][SHIPMENT_PACKED][true][String]
>>> 2009-05-08 09:10:53,596 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [updateShipment] on [commit] got false for
>>> condition:
>>> [statusId][equals][SHIPMENT_SHIPPED][true][String]
>>> 2009-05-08 09:10:53,599 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [updateShipment] on [commit] got false for
>>> condition:
>>> [statusId][equals][PURCH_SHIP_RECEIVED][true][String]
>>> 2009-05-08 09:10:53,603 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [updateShipment] on [commit] got false for
>>> condition:
>>> [statusId][equals][PURCH_SHIP_SHIPPED][true][String]
>>> 2009-05-08 09:10:53,606 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [updateShipment] on [commit] got false for
>>> condition:
>>> [statusId][equals][PURCH_SHIP_RECEIVED][true][String]
>>> 2009-05-08 09:10:53,610 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [updateShipment] on [commit] got false for
>>> condition:
>>> [statusId][equals][PURCH_SHIP_RECEIVED][true][String]
>>> 2009-05-08 09:10:53,614 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [updateShipment] on [commit] got false for
>>> condition:
>>> [statusId][equals][SHIPMENT_SHIPPED][true][String]
>>> 2009-05-08 09:10:53,617 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [updateShipment] on [commit] got false for
>>> condition:
>>> [statusId][equals][SHIPMENT_SCHEDULED][true][String]
>>> 2009-05-08 09:10:53,624 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [updateShipment] on [commit] got false for
>>> condition:
>>> [originFacilityId][not-equals][oldOriginFacilityId][false][String]
>>> 2009-05-08 09:10:53,626 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [updateShipment] on [commit] got false for
>>> condition:
>>> [destinationFacilityId][is-not-empty][true][String]
>>> 2009-05-08 09:10:53,633 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [updateShipment] on [commit] got false for
>>> condition:
>>> [primaryOrderId][not-equals][oldPrimaryOrderId][false][String]
>>> 2009-05-08 09:10:53,633 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service [order/
>>> updateShipment]
>>> finished in [207] milliseconds
>>> 2009-05-08 09:10:53,647 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/setShipmentSettingsFromPrimaryOrder] finished in [789]
>>> milliseconds
>>> 2009-05-08 09:10:53,648 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service [order/
>>> createShipment]
>>> finished in [1388] milliseconds
>>> 2009-05-08 09:10:53,746 (http-0.0.0.0-8443-2) [ UtilXml.java:
>>> 246:INFO ] XML
>>> Read 0.049s:
>>> file:/opt/ofbiz/applications/product/script/org/ofbiz/shipment/
>>> issuance/IssuanceServices.xml
>>> 2009-05-08 09:10:53,851 (http-0.0.0.0-8443-2) [ Log.java:110:INFO ]
>>> [IssuanceServices.xml#issueOrderItemShipGrpInvResToShipment] order
>>> item ship
>>> grp inv res info:
>>> [GenericEntity:OrderItemShipGrpInvRes][createdDatetime,2009-05-07
>>> 21:31:38.0(java.sql.Timestamp)][createdStamp,2009-05-07
>>> 21:31:38.0(java.sql.Timestamp)][createdTxStamp,2009-05-07
>>> 21:31:38.0(java.sql.Timestamp)][currentPromisedDate,null()]
>>> [inventoryItemId,9000(java.lang.String)][lastUpdatedStamp,2009-05-07
>>> 21:31:38.0(java.sql.Timestamp)][lastUpdatedTxStamp,2009-05-07
>>> 21:31:38.0(java.sql.Timestamp)][oldPickStartDate,null()]
>>> [orderId,WSCO10462(java.lang.String)][orderItemSeqId,
>>> 00001(java.lang.String)][promisedDatetime,2009-05-22
>>> 21:31:38.0(java.sql.Timestamp)][quantity,
>>> 1.000000(java.math.BigDecimal)][quantityNotAvailable,null()]
>>> [reserveOrderEnumId,INVRO_FIFO_REC(java.lang.String)]
>>> [reservedDatetime,2009-05-07
>>> 21:31:38.0(java.sql.Timestamp)][sequenceId,null()][shipGroupSeqId,
>>> 00001(java.lang.String)]
>>> 2009-05-08 09:10:53,918 (http-0.0.0.0-8443-2) [ Calculate.java:
>>> 351:INFO ]
>>> Field not found with field-name orderShipment.quantity, and map-name
>>> using a
>>> default of 0
>>> 2009-05-08 09:10:54,129 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service [order/
>>> createShipmentItem]
>>> finished in [159] milliseconds
>>> 2009-05-08 09:10:54,202 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 150:INFO
>>> ] Running Service ECA Service: checkCreateStockRequirementQoh,
>>> triggered by
>>> rule on Service: createItemIssuance
>>> 2009-05-08 09:10:54,259 (http-0.0.0.0-8443-2) [ UtilXml.java:
>>> 246:INFO ] XML
>>> Read 0.051s:
>>> file:/opt/ofbiz/applications/order/script/org/ofbiz/order/order/
>>> OrderServices.xml
>>> 2009-05-08 09:10:54,410 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/checkCreateStockRequirementQoh] finished in [207]  
>>> milliseconds
>>> 2009-05-08 09:10:54,570 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 150:INFO
>>> ] Running Service ECA Service:
>>> createAcctgTransForSalesShipmentIssuance,
>>> triggered by rule on Service: createItemIssuance
>>> 2009-05-08 09:10:54,642 (http-0.0.0.0-8443-2) [ UtilXml.java:
>>> 246:INFO ] XML
>>> Read 0.058s:
>>> file:/opt/ofbiz/applications/accounting/script/org/ofbiz/accounting/
>>> ledger/GeneralLedgerServices.xml
>>> 2009-05-08 09:10:54,696 (http-0.0.0.0-8443-2) [ Log.java:110:INFO ]
>>> [GeneralLedgerServices.xml#createAcctgTransForSalesShipmentIssuance]
>>> Using
>>> ledger decimals [4] and rounding [HalfUp]
>>> 2009-05-08 09:10:54,852 (http-0.0.0.0-8443-2) [ UtilXml.java:
>>> 246:INFO ] XML
>>> Read 0.035s:
>>> file:/opt/ofbiz/applications/accounting/script/org/ofbiz/accounting/
>>> permissions/PermissionServices.xml
>>> 2009-05-08 09:10:54,920 (http-0.0.0.0-8443-2) [ UtilXml.java:
>>> 246:INFO ] XML
>>> Read 0.033s:
>>> file:/opt/ofbiz/framework/common/script/org/ofbiz/common/permission/
>>> CommonPermissionServices.xml
>>> 2009-05-08 09:10:54,947 (http-0.0.0.0-8443-2) [ Log.java:110:INFO ]
>>> [CommonPermissionServices.xml#genericBasePermissionCheck] Checking  
>>> for
>>> primary permission ACCTG_ATX_CREATE
>>> 2009-05-08 09:10:54,996 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/acctgTransactionPermissionCheck] finished in [187]  
>>> milliseconds
>>> 2009-05-08 09:10:55,020 (http-0.0.0.0-8443-2) [ Log.java:110:INFO ]
>>> [GeneralLedgerServices.xml#createAcctgTransAndEntries] Using ledger
>>> decimals
>>> [4] and rounding [HalfUp]
>>> 2009-05-08 09:10:55,106 (http-0.0.0.0-8443-2) [ Log.java:110:INFO ]
>>> [CommonPermissionServices.xml#genericBasePermissionCheck] Checking  
>>> for
>>> primary permission ACCTG_PREF_VIEW
>>> 2009-05-08 09:10:55,159 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/acctgPrefPermissionCheck] finished in [111] milliseconds
>>> 2009-05-08 09:10:55,199 (http-0.0.0.0-8443-2) [ UtilXml.java:
>>> 246:INFO ] XML
>>> Read 0.035s:
>>> file:/opt/ofbiz/applications/accounting/script/org/ofbiz/accounting/
>>> admin/AcctgAdminServices.xml
>>> 2009-05-08 09:10:55,540 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/getPartyAccountingPreferences] finished in [493] milliseconds
>>> 2009-05-08 09:10:56,127 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/getGlAccountFromAccountType] finished in [545] milliseconds
>>> 2009-05-08 09:10:56,233 (http-0.0.0.0-8443-2) [ Log.java:110:INFO ]
>>> [CommonPermissionServices.xml#genericBasePermissionCheck] Checking  
>>> for
>>> primary permission ACCTG_PREF_VIEW
>>> 2009-05-08 09:10:56,272 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/acctgPrefPermissionCheck] finished in [108] milliseconds
>>> 2009-05-08 09:10:56,586 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/getPartyAccountingPreferences] finished in [423] milliseconds
>>> 2009-05-08 09:10:57,182 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/getGlAccountFromAccountType] finished in [547] milliseconds
>>> 2009-05-08 09:10:57,274 (http-0.0.0.0-8443-2) [ Log.java:110:INFO ]
>>> [CommonPermissionServices.xml#genericBasePermissionCheck] Checking  
>>> for
>>> primary permission ACCTG_ATX_CREATE
>>> 2009-05-08 09:10:57,324 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/acctgTransactionPermissionCheck] finished in [108]  
>>> milliseconds
>>> 2009-05-08 09:10:57,366 (http-0.0.0.0-8443-2) [ UtilXml.java:
>>> 246:INFO ] XML
>>> Read 0.038s:
>>> file:/opt/ofbiz/applications/accounting/script/org/ofbiz/accounting/
>>> ledger/AcctgTransServices.xml
>>> 2009-05-08 09:10:57,450 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service [order/
>>> createAcctgTrans]
>>> finished in [242] milliseconds
>>> 2009-05-08 09:10:57,554 (http-0.0.0.0-8443-2) [ Log.java:110:INFO ]
>>> [CommonPermissionServices.xml#genericBasePermissionCheck] Checking  
>>> for
>>> primary permission ACCTG_ATX_CREATE
>>> 2009-05-08 09:10:57,594 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/acctgTransactionPermissionCheck] finished in [113]  
>>> milliseconds
>>> 2009-05-08 09:10:57,745 (http-0.0.0.0-8443-2) [ Log.java:110:INFO ]
>>> [CommonPermissionServices.xml#genericBasePermissionCheck] Checking  
>>> for
>>> primary permission ACCTG_PREF_VIEW
>>> 2009-05-08 09:10:57,805 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/acctgPrefPermissionCheck] finished in [135] milliseconds
>>> 2009-05-08 09:10:58,141 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/getPartyAccountingPreferences] finished in [472] milliseconds
>>> 2009-05-08 09:10:58,197 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service [order/
>>> createAcctgTransEntry]
>>> finished in [717] milliseconds
>>> 2009-05-08 09:10:58,288 (http-0.0.0.0-8443-2) [ Log.java:110:INFO ]
>>> [CommonPermissionServices.xml#genericBasePermissionCheck] Checking  
>>> for
>>> primary permission ACCTG_ATX_CREATE
>>> 2009-05-08 09:10:58,336 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/acctgTransactionPermissionCheck] finished in [118]  
>>> milliseconds
>>> 2009-05-08 09:10:58,488 (http-0.0.0.0-8443-2) [ Log.java:110:INFO ]
>>> [CommonPermissionServices.xml#genericBasePermissionCheck] Checking  
>>> for
>>> primary permission ACCTG_PREF_VIEW
>>> 2009-05-08 09:10:58,530 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/acctgPrefPermissionCheck] finished in [116] milliseconds
>>> 2009-05-08 09:10:58,883 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/getPartyAccountingPreferences] finished in [470] milliseconds
>>> 2009-05-08 09:10:58,929 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service [order/
>>> createAcctgTransEntry]
>>> finished in [712] milliseconds
>>> 2009-05-08 09:10:58,953 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 150:INFO
>>> ] Running Service ECA Service: postAcctgTrans, triggered by rule on
>>> Service:
>>> createAcctgTransAndEntries
>>> 2009-05-08 09:10:59,031 (http-0.0.0.0-8443-2) [ Log.java:110:INFO ]
>>> [CommonPermissionServices.xml#genericBasePermissionCheck] Checking  
>>> for
>>> primary permission ACCTG_ATX_CREATE
>>> 2009-05-08 09:10:59,067 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/acctgTransactionPermissionCheck] finished in [101]  
>>> milliseconds
>>> 2009-05-08 09:10:59,189 (http-0.0.0.0-8443-2) [ Log.java:110:INFO ]
>>> [CommonPermissionServices.xml#genericBasePermissionCheck] Checking  
>>> for
>>> primary permission ACCTG_ATX_CREATE
>>> 2009-05-08 09:10:59,230 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/acctgTransactionPermissionCheck] finished in [110]  
>>> milliseconds
>>> 2009-05-08 09:10:59,265 (http-0.0.0.0-8443-2) [ Log.java:110:INFO ]
>>> [AcctgTransServices.xml#calculateAcctgTransTrialBalance] Using  
>>> ledger
>>> decimals [4] and rounding [HalfUp]
>>> 2009-05-08 09:10:59,358 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/calculateAcctgTransTrialBalance] finished in [239]  
>>> milliseconds
>>> 2009-05-08 09:10:59,486 (http-0.0.0.0-8443-2) [ UtilXml.java:
>>> 246:INFO ] XML
>>> Read 0.034s:
>>> file:/opt/ofbiz/framework/common/script/org/ofbiz/common/period/
>>> PeriodServices.xml
>>> 2009-05-08 09:10:59,575 (http-0.0.0.0-8443-2) [ UtilXml.java:
>>> 246:INFO ] XML
>>> Read 0.045s:
>>> file:/opt/ofbiz/applications/party/script/org/ofbiz/party/party/
>>> PartyServices.xml
>>> 2009-05-08 09:11:00,105 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/getParentOrganizations] finished in [588] milliseconds
>>> 2009-05-08 09:11:00,159 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service [order/
>>> findCustomTimePeriods]
>>> finished in [712] milliseconds
>>> 2009-05-08 09:11:00,984 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/getParentOrganizations] finished in [744] milliseconds
>>> 2009-05-08 09:11:01,047 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service [order/
>>> findCustomTimePeriods]
>>> finished in [841] milliseconds
>>> 2009-05-08 09:11:01,147 (http-0.0.0.0-8443-2) [ Log.java:110:INFO ]
>>> [CommonPermissionServices.xml#genericBasePermissionCheck] Checking  
>>> for
>>> primary permission ACCTG_PREF_VIEW
>>> 2009-05-08 09:11:01,201 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/acctgPrefPermissionCheck] finished in [126] milliseconds
>>> 2009-05-08 09:11:01,566 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/getPartyAccountingPreferences] finished in [493] milliseconds
>>> 2009-05-08 09:11:01,619 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service [order/
>>> postAcctgTrans]
>>> finished in [2654] milliseconds
>>> 2009-05-08 09:11:01,620 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/createAcctgTransAndEntries] finished in [6812] milliseconds
>>> 2009-05-08 09:11:01,633 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/createAcctgTransForSalesShipmentIssuance] finished in [7053]
>>> milliseconds
>>> 2009-05-08 09:11:01,635 (http-0.0.0.0-8443-2)
>>> [ServiceEcaCondition.java:155:WARN ] From Field (fixedAssetId) is
>>> not found
>>> in context for createItemIssuance, defaulting to null.
>>> 2009-05-08 09:11:01,637 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [createItemIssuance] on [commit] got false for
>>> condition:
>>> [fixedAssetId][is-not-empty][true][String]
>>> 2009-05-08 09:11:01,644 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 150:INFO
>>> ] Running Service ECA Service: changeOwnerUponIssuance, triggered by
>>> rule on
>>> Service: createItemIssuance
>>> 2009-05-08 09:11:01,718 (http-0.0.0.0-8443-2) [ UtilXml.java:
>>> 246:INFO ] XML
>>> Read 0.059s:
>>> file:/opt/ofbiz/applications/product/script/org/ofbiz/product/
>>> inventory/InventoryServices.xml
>>> 2009-05-08 09:11:01,796 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/changeOwnerUponIssuance] finished in [143] milliseconds
>>> 2009-05-08 09:11:01,797 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service [order/
>>> createItemIssuance]
>>> finished in [7603] milliseconds
>>> 2009-05-08 09:11:01,988 (http-0.0.0.0-8443-2) [
>>> PrimaryKeyFinder.java:141:INFO ] Returning null because found
>>> incomplete
>>> primary key in find: [GenericEntity:Shipment]
>>> 2009-05-08 09:11:02,174 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/createItemIssuanceRole] finished in [326] milliseconds
>>> 2009-05-08 09:11:02,613 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [changeOrderItemStatus] on [commit] got false for
>>> condition: [statusId][equals][ITEM_CANCELLED][true][String]
>>> 2009-05-08 09:11:02,622 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 150:INFO
>>> ] Running Service ECA Service: checkOrderItemStatus, triggered by
>>> rule on
>>> Service: changeOrderItemStatus
>>> 2009-05-08 09:11:02,788 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [changeOrderStatus] on [global-commit] got false  
>>> for
>>> condition: [statusId][equals][ORDER_APPROVED][true][String]
>>> 2009-05-08 09:11:03,035 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [changeOrderStatus] on [commit] got false for
>>> condition:
>>> [statusId][equals][ORDER_CANCELLED][true][String]
>>> 2009-05-08 09:11:03,084 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 150:INFO
>>> ] Running Service ECA Service: resetGrandTotal, triggered by rule on
>>> Service: changeOrderStatus
>>> 2009-05-08 09:11:03,397 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service [order/
>>> resetGrandTotal]
>>> finished in [177] milliseconds
>>> 2009-05-08 09:11:03,398 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 150:INFO
>>> ] Running Service ECA Service: sendOrderCompleteNotification,
>>> triggered by
>>> rule on Service: changeOrderStatus
>>> 2009-05-08 09:11:03,438 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:599:INFO ] [[ASync service started...-
>>> total:0.0,since last(Begin):0.0]] - 'order /
>>> sendOrderCompleteNotification'
>>> 2009-05-08 09:11:03,537 (http-0.0.0.0-8443-2) [
>>> GenericAsyncEngine.java:137:INFO ] Persisted job queued :
>>> 1241766663510
>>> 2009-05-08 09:11:03,538 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:696:INFO ] [[ASync service finished...-
>>> total:0.098,since last(ASync service sta...):0.098]] - 'order /
>>> sendOrderCompleteNotification'
>>> 2009-05-08 09:11:03,904 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [changeOrderStatus] on [commit] got false for
>>> condition:
>>> [statusId][equals][ORDER_APPROVED][true][String]
>>> 2009-05-08 09:11:03,908 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [changeOrderStatus] on [commit] got false for
>>> condition:
>>> [oldStatusId][equals][ORDER_CREATED][true][String]
>>> 2009-05-08 09:11:03,922 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 150:INFO
>>> ] Running Service ECA Service: setEbayOrderToComplete, triggered by
>>> rule on
>>> Service: changeOrderStatus
>>> 2009-05-08 09:11:04,030 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/setEbayOrderToComplete] finished in [87] milliseconds
>>> 2009-05-08 09:11:04,031 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service [order/
>>> changeOrderStatus]
>>> finished in [1268] milliseconds
>>> 2009-05-08 09:11:04,034 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service [order/
>>> checkOrderItemStatus]
>>> finished in [1392] milliseconds
>>> 2009-05-08 09:11:04,039 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [changeOrderItemStatus] on [commit] got false for
>>> condition: [statusId][equals][ITEM_APPROVED][true][String]
>>> 2009-05-08 09:11:04,044 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [changeOrderItemStatus] on [commit] got false for
>>> condition: [statusId][equals][ITEM_APPROVED][true][String]
>>> 2009-05-08 09:11:04,054 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [changeOrderItemStatus] on [commit] got false for
>>> condition: [statusId][equals][ITEM_APPROVED][true][String]
>>> 2009-05-08 09:11:04,060 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service [order/
>>> changeOrderItemStatus]
>>> finished in [1734] milliseconds
>>> 2009-05-08 09:11:04,282 (http-0.0.0.0-8443-2) [ EntityEcaRule.java:
>>> 117:INFO
>>> ] Running Entity ECA Service: updateInventoryItemFromDetail,
>>> triggered by
>>> rule on Entity: InventoryItemDetail
>>> 2009-05-08 09:11:04,372 (http-0.0.0.0-8443-2) [ EntityEcaRule.java:
>>> 117:INFO
>>> ] Running Entity ECA Service: updateSerializedInventoryTotals,
>>> triggered by
>>> rule on Entity: InventoryItem
>>> 2009-05-08 09:11:04,430 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [entity-default/updateSerializedInventoryTotals] finished in [57]
>>> milliseconds
>>> 2009-05-08 09:11:04,440 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [entity-default/updateInventoryItemFromDetail] finished in [157]
>>> milliseconds
>>> 2009-05-08 09:11:04,450 (http-0.0.0.0-8443-2)
>>> [ServiceEcaCondition.java:155:WARN ] From Field (unitCost) is not
>>> found in
>>> context for createInventoryItemDetail, defaulting to null.
>>> 2009-05-08 09:11:04,452 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [createInventoryItemDetail] on [commit] got false
>>> for
>>> condition: [unitCost][is-not-empty][true][String]
>>> 2009-05-08 09:11:04,452 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/createInventoryItemDetail] finished in [354] milliseconds
>>> 2009-05-08 09:11:04,461 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/issueOrderItemShipGrpInvResToShipment] finished in [10767]
>>> milliseconds
>>> 2009-05-08 09:11:04,567 (http-0.0.0.0-8443-2) [ UtilXml.java:
>>> 246:INFO ] XML
>>> Read 0.056s:
>>> file:/opt/ofbiz/applications/product/script/org/ofbiz/shipment/
>>> shipment/ShipmentServices.xml
>>> 2009-05-08 09:11:04,697 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 150:INFO
>>> ] Running Service ECA Service: createShipmentPackage, triggered by
>>> rule on
>>> Service: createShipmentPackageContent
>>> 2009-05-08 09:11:05,054 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/createShipmentPackageRouteSeg] finished in [95] milliseconds
>>> 2009-05-08 09:11:05,074 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service [order/
>>> createShipmentPackage]
>>> finished in [367] milliseconds
>>> 2009-05-08 09:11:05,217 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/createShipmentPackageContent] finished in [525] milliseconds
>>> 2009-05-08 09:11:05,233 (http-0.0.0.0-8443-2) [ Log.java:110:INFO ]
>>> [ShipmentServices.xml#addShipmentContentToPackage] Shipment package:
>>> [GenericEntity:ShipmentPackageContent][shipmentId,
>>> 10202(java.lang.String)][shipmentItemSeqId,00001(java.lang.String)]
>>> [shipmentPackageSeqId,00001(java.lang.String)]
>>> 2009-05-08 09:11:05,240 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/addShipmentContentToPackage] finished in [732] milliseconds
>>> 2009-05-08 09:11:05,419 (http-0.0.0.0-8443-2) [ FieldToResult.java:
>>> 76 :WARN
>>> ] Field value not found with name
>>> lookedUpValue.destinationFacilityId in Map
>>> with name
>>> 2009-05-08 09:11:05,457 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [updateShipment] on [commit] got false for
>>> condition:
>>> [statusId][equals][SHIPMENT_CANCELLED][true][String]
>>> 2009-05-08 09:11:05,467 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 150:INFO
>>> ] Running Service ECA Service: createInvoicesFromShipment, triggered
>>> by rule
>>> on Service: updateShipment
>>> 2009-05-08 09:11:05,566 (http-0.0.0.0-8443-2) [
>>> InvoiceServices.java:1538:INFO ] prorateShipping is not N for
>>> productStoreId
>>> 9000: Additional shipping charges for shipments are ignored
>>> 2009-05-08 09:11:05,837 (http-0.0.0.0-8443-2) [ UtilXml.java:
>>> 246:INFO ] XML
>>> Read 0.048s:
>>> file:/opt/ofbiz/applications/accounting/script/org/ofbiz/accounting/
>>> permissions/PermissionServices.xml
>>> 2009-05-08 09:11:05,935 (http-0.0.0.0-8443-2) [ UtilXml.java:
>>> 246:INFO ] XML
>>> Read 0.052s:
>>> file:/opt/ofbiz/framework/common/script/org/ofbiz/common/permission/
>>> CommonPermissionServices.xml
>>> 2009-05-08 09:11:05,969 (http-0.0.0.0-8443-2) [ Log.java:110:INFO ]
>>> [CommonPermissionServices.xml#genericBasePermissionCheck] Checking  
>>> for
>>> primary permission ACCOUNTING_CREATE
>>> 2009-05-08 09:11:05,969 (http-0.0.0.0-8443-2) [ Log.java:110:INFO ]
>>> [CommonPermissionServices.xml#genericBasePermissionCheck] Checking  
>>> for
>>> alternate permission ACCOUNTING_ROLE_CREATE
>>> 2009-05-08 09:11:06,026 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/acctgInvoicePermissionCheck] finished in [245] milliseconds
>>> 2009-05-08 09:11:06,077 (http-0.0.0.0-8443-2) [ UtilXml.java:
>>> 665:ERROR]
>>> XmlFileLoader: File
>>> file:/opt/ofbiz/applications/accounting/script/org/ofbiz/accounting/
>>> invoice/InvoiceServices.xml
>>> process error. Line: 546. Error message: cvc-complex-type.3.2.2:
>>> Attribute
>>> 'map-name' is not allowed to appear in element 'field-to-result'.
>>> 2009-05-08 09:11:06,079 (http-0.0.0.0-8443-2) [ UtilXml.java:
>>> 246:INFO ] XML
>>> Read 0.048s:
>>> file:/opt/ofbiz/applications/accounting/script/org/ofbiz/accounting/
>>> invoice/InvoiceServices.xml
>>> 2009-05-08 09:11:06,174 (http-0.0.0.0-8443-2) [ Log.java:110:INFO ]
>>> [InvoiceServices.xml#getNextInvoiceId] In getNextInvoiceId partyId  
>>> is
>>> [Company], partyAcctgPreference:
>>> [GenericEntity:PartyAcctgPreference]
>>> [baseCurrencyUomId,EUR(java.lang.String)]
>>> [cogsMethodId,COGS_AVG_COST(java.lang.String)][createdStamp,
>>> 2009-05-05
>>> 14:33:04.0(java.sql.Timestamp)][createdTxStamp,2009-05-05
>>> 14:33:04.0(java.sql.Timestamp)]
>>> [errorGlJournalId,ERROR_JOURNAL(java.lang.String)]
>>> [fiscalYearStartDay,null()][fiscalYearStartMonth,null()]
>>> [invoiceIdPrefix,CI(java.lang.String)]
>>> [invoiceSequenceEnumId,INVSQ_ENF_SEQ(java.lang.String)]
>>> [lastInvoiceNumber,8(java.lang.Long)][lastInvoiceRestartDate,null()]
>>> [lastOrderNumber,null()][lastQuoteNumber,null()][lastUpdatedStamp,
>>> 2009-05-07
>>> 20:02:41.0(java.sql.Timestamp)][lastUpdatedTxStamp,2009-05-07
>>> 20:02:40.0(java.sql.Timestamp)][orderIdPrefix,CO(java.lang.String)]
>>> [orderSequenceEnumId,INVSQ_ENF_SEQ(java.lang.String)]
>>> [partyId,Company(java.lang.String)]
>>> [quoteIdPrefix,CQ(java.lang.String)]
>>> [quoteSequenceEnumId,INVSQ_ENF_SEQ(java.lang.String)]
>>> [refundPaymentMethodId,null()][taxFormId,null()]
>>> [useInvoiceIdForReturns,null()]
>>> 2009-05-08 09:11:06,178 (http-0.0.0.0-8443-2) [ Log.java:110:INFO ]
>>> [InvoiceServices.xml#getNextInvoiceId] In createInvoice sequence  
>>> enum
>>> INVSQ_ENF_SEQ
>>> 2009-05-08 09:11:06,239 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service [order/
>>> getNextInvoiceId]
>>> finished in [116] milliseconds
>>> 2009-05-08 09:11:06,336 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service [order/createInvoice]
>>> finished in [556] milliseconds
>>> 2009-05-08 09:11:06,457 (http-0.0.0.0-8443-2) [ Log.java:110:INFO ]
>>> [CommonPermissionServices.xml#genericBasePermissionCheck] Checking  
>>> for
>>> primary permission ACCOUNTING_UPDATE
>>> 2009-05-08 09:11:06,458 (http-0.0.0.0-8443-2) [ Log.java:110:INFO ]
>>> [CommonPermissionServices.xml#genericBasePermissionCheck] Checking  
>>> for
>>> alternate permission ACCOUNTING_ROLE_UPDATE
>>> 2009-05-08 09:11:06,515 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/acctgInvoicePermissionCheck] finished in [162] milliseconds
>>> 2009-05-08 09:11:06,636 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service [order/
>>> createInvoiceRole]
>>> finished in [284] milliseconds
>>> 2009-05-08 09:11:06,724 (http-0.0.0.0-8443-2) [ Log.java:110:INFO ]
>>> [CommonPermissionServices.xml#genericBasePermissionCheck] Checking  
>>> for
>>> primary permission ACCOUNTING_UPDATE
>>> 2009-05-08 09:11:06,725 (http-0.0.0.0-8443-2) [ Log.java:110:INFO ]
>>> [CommonPermissionServices.xml#genericBasePermissionCheck] Checking  
>>> for
>>> alternate permission ACCOUNTING_ROLE_UPDATE
>>> 2009-05-08 09:11:06,781 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/acctgInvoicePermissionCheck] finished in [143] milliseconds
>>> 2009-05-08 09:11:06,889 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service [order/
>>> createInvoiceRole]
>>> finished in [252] milliseconds
>>> 2009-05-08 09:11:06,998 (http-0.0.0.0-8443-2) [ Log.java:110:INFO ]
>>> [CommonPermissionServices.xml#genericBasePermissionCheck] Checking  
>>> for
>>> primary permission ACCOUNTING_UPDATE
>>> 2009-05-08 09:11:06,999 (http-0.0.0.0-8443-2) [ Log.java:110:INFO ]
>>> [CommonPermissionServices.xml#genericBasePermissionCheck] Checking  
>>> for
>>> alternate permission ACCOUNTING_ROLE_UPDATE
>>> 2009-05-08 09:11:07,051 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/acctgInvoicePermissionCheck] finished in [160] milliseconds
>>> 2009-05-08 09:11:07,174 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service [order/
>>> createInvoiceRole]
>>> finished in [284] milliseconds
>>> 2009-05-08 09:11:07,264 (http-0.0.0.0-8443-2) [ Log.java:110:INFO ]
>>> [CommonPermissionServices.xml#genericBasePermissionCheck] Checking  
>>> for
>>> primary permission ACCOUNTING_UPDATE
>>> 2009-05-08 09:11:07,265 (http-0.0.0.0-8443-2) [ Log.java:110:INFO ]
>>> [CommonPermissionServices.xml#genericBasePermissionCheck] Checking  
>>> for
>>> alternate permission ACCOUNTING_ROLE_UPDATE
>>> 2009-05-08 09:11:07,320 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/acctgInvoicePermissionCheck] finished in [144] milliseconds
>>> 2009-05-08 09:11:07,440 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service [order/
>>> createInvoiceRole]
>>> finished in [265] milliseconds
>>> 2009-05-08 09:11:07,550 (http-0.0.0.0-8443-2) [ Log.java:110:INFO ]
>>> [CommonPermissionServices.xml#genericBasePermissionCheck] Checking  
>>> for
>>> primary permission ACCOUNTING_UPDATE
>>> 2009-05-08 09:11:07,555 (http-0.0.0.0-8443-2) [ Log.java:110:INFO ]
>>> [CommonPermissionServices.xml#genericBasePermissionCheck] Checking  
>>> for
>>> alternate permission ACCOUNTING_ROLE_UPDATE
>>> 2009-05-08 09:11:07,612 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/acctgInvoicePermissionCheck] finished in [170] milliseconds
>>> 2009-05-08 09:11:07,747 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service [order/
>>> createInvoiceRole]
>>> finished in [306] milliseconds
>>> 2009-05-08 09:11:07,791 (http-0.0.0.0-8443-2) [
>>> InvoiceServices.java:339:WARN ] No billing locations found for order
>>> [WSCO10462] and none were created for Invoice [CI9]
>>> 2009-05-08 09:11:07,935 (http-0.0.0.0-8443-2) [ Log.java:110:INFO ]
>>> [CommonPermissionServices.xml#genericBasePermissionCheck] Checking  
>>> for
>>> primary permission ACCOUNTING_CREATE
>>> 2009-05-08 09:11:07,936 (http-0.0.0.0-8443-2) [ Log.java:110:INFO ]
>>> [CommonPermissionServices.xml#genericBasePermissionCheck] Checking  
>>> for
>>> alternate permission ACCOUNTING_ROLE_CREATE
>>> 2009-05-08 09:11:07,989 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/acctgInvoicePermissionCheck] finished in [159] milliseconds
>>> 2009-05-08 09:11:08,080 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/createInvoiceContactMech] finished in [251] milliseconds
>>> 2009-05-08 09:11:08,256 (http-0.0.0.0-8443-2) [ Log.java:110:INFO ]
>>> [CommonPermissionServices.xml#genericBasePermissionCheck] Checking  
>>> for
>>> primary permission ACCOUNTING_UPDATE
>>> 2009-05-08 09:11:08,257 (http-0.0.0.0-8443-2) [ Log.java:110:INFO ]
>>> [CommonPermissionServices.xml#genericBasePermissionCheck] Checking  
>>> for
>>> alternate permission ACCOUNTING_ROLE_UPDATE
>>> 2009-05-08 09:11:08,304 (default-invoker-Thread-1950) [
>>> JobInvoker.java:230:INFO ] Invoker [default-invoker-Thread-1950]
>>> received
>>> job [1241766663510] from poller
>>> [org.ofbiz.service.job.JobPoller@1acfa31]
>>> 2009-05-08 09:11:08,318 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/acctgInvoicePermissionCheck] finished in [186] milliseconds
>>> 2009-05-08 09:11:08,379 (default-invoker-Thread-1950)
>>> [PersistedServiceJob.java:176:INFO ]
>>> org.ofbiz.service.job.PersistedServiceJob@d6b80d[15202] -- Next
>>> runtime: Thu
>>> Jan 01 00:59:59 CET 1970
>>> 2009-05-08 09:11:08,422 (default-invoker-Thread-1950) [
>>> UtilXml.java:246:INFO ] XML Read 0.0030s: Internal Content
>>> 2009-05-08 09:11:08,520 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service [order/
>>> createInvoiceItem]
>>> finished in [391] milliseconds
>>> 2009-05-08 09:11:08,642 (http-0.0.0.0-8443-2) [ UtilXml.java:
>>> 246:INFO ] XML
>>> Read 0.111s:
>>> file:/opt/ofbiz/applications/order/script/org/ofbiz/order/order/
>>> OrderSimpleMethods.xml
>>> 2009-05-08 09:11:08,726 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/createOrderItemBilling] finished in [205] milliseconds
>>> 2009-05-08 09:11:08,756 (default-invoker-Thread-1950) [
>>> UtilXml.java:246:INFO ] XML Read 0.065s:
>>> file:/opt/ofbiz/applications/ecommerce/widget/EmailOrderScreens.xml
>>> 2009-05-08 09:11:08,830 (default-invoker-Thread-1950) [
>>> ScreenFactory.java:129:INFO ] Got 7 screens in 0.139s from:
>>> file:/opt/ofbiz/applications/ecommerce/widget/EmailOrderScreens.xml
>>> 2009-05-08 09:11:08,853 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/calculateInvoicedAdjustmentTotal] finished in [87]  
>>> milliseconds
>>> 2009-05-08 09:11:09,128 (http-0.0.0.0-8443-2) [ Log.java:110:INFO ]
>>> [CommonPermissionServices.xml#genericBasePermissionCheck] Checking  
>>> for
>>> primary permission ACCOUNTING_UPDATE
>>> 2009-05-08 09:11:09,129 (http-0.0.0.0-8443-2) [ Log.java:110:INFO ]
>>> [CommonPermissionServices.xml#genericBasePermissionCheck] Checking  
>>> for
>>> alternate permission ACCOUNTING_ROLE_UPDATE
>>> 2009-05-08 09:11:09,195 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/acctgInvoicePermissionCheck] finished in [336] milliseconds
>>> 2009-05-08 09:11:09,397 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service [order/
>>> createInvoiceItem]
>>> finished in [543] milliseconds
>>> 2009-05-08 09:11:09,505 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/createOrderAdjustmentBilling] finished in [107] milliseconds
>>> 2009-05-08 09:11:09,506 (http-0.0.0.0-8443-2) [
>>> InvoiceServices.java:2164:INFO ] adjAmount: 3.70, divisor: 17.99,
>>> multiplier: 17.99, invoiceTypeId: SALES_INVOICE, invoiceId: CI9,
>>> itemSeqId:
>>> 00002, decimals: 2, rounding: 4, adj:
>>> [GenericEntity:OrderAdjustment][amount,3.700(java.math.BigDecimal)]
>>> [comments,null()][correspondingProductId,null()]
>>> [createdByUserLogin,DemoCustomer(java.lang.String)][createdDate,
>>> 2009-05-07
>>> 21:31:38.0(java.sql.Timestamp)][createdStamp,2009-05-07
>>> 21:31:38.0(java.sql.Timestamp)][createdTxStamp,2009-05-07
>>> 21:31:38.0(java.sql.Timestamp)][customerReferenceId,null()]
>>> [description,null()][exemptAmount,null()][includeInShipping,null()]
>>> [includeInTax,null()][lastUpdatedStamp,2009-05-07
>>> 21:31:38.0(java.sql.Timestamp)][lastUpdatedTxStamp,2009-05-07
>>> 21:31:38.0(java.sql.Timestamp)][oldAmountPerQuantity,null()]
>>> [oldPercentage,null()][orderAdjustmentId,10472(java.lang.String)]
>>> [orderAdjustmentTypeId,SHIPPING_CHARGES(java.lang.String)]
>>> [orderId,WSCO10462(java.lang.String)]
>>> [orderItemSeqId,_NA_(java.lang.String)][originalAdjustmentId,null()]
>>> [overrideGlAccountId,null()][primaryGeoId,null()]
>>> [productFeatureId,null()][productPromoActionSeqId,null()]
>>> [productPromoId,null()][productPromoRuleId,null()]
>>> [recurringAmount,null()][secondaryGeoId,null()][shipGroupSeqId,
>>> 00001(java.lang.String)][sourcePercentage,null()]
>>> [sourceReferenceId,null()][taxAuthGeoId,null()]
>>> [taxAuthPartyId,null()][taxAuthorityRateSeqId,null()]
>>> 2009-05-08 09:11:09,612 (http-0.0.0.0-8443-2) [ ModelService.java:
>>> 545:ERROR]
>>> [ModelService.validate] : {createPaymentApplication} : (IN) Required
>>> test
>>> error: org.ofbiz.service.ServiceValidationException: Type check
>>> failed for
>>> field [createPaymentApplication.amountApplied]; expected type is
>>> [BigDecimal]; actual type is [java.lang.Double]
>>> 2009-05-08 09:11:09,614 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:368:ERROR] ---- exception report
>>> ---------------------------------------------------------- Incoming
>>> context
>>> (in runSync : createPaymentApplication) does not match expected
>>> requirements
>>> Exception: org.ofbiz.service.ServiceValidationException Message:
>>> Type check
>>> failed for field [createPaymentApplication.amountApplied]; expected
>>> type is
>>> [BigDecimal]; actual type is [java.lang.Double] ---- stack trace
>>> ---------------------------------------------------------------
>>> org.ofbiz.service.ServiceValidationException: Type check failed for
>>> field
>>> [createPaymentApplication.amountApplied]; expected type is
>>> [BigDecimal];
>>> actual type is [java.lang.Double]
>>> org.ofbiz.service.ModelService.validate(ModelService.java:657)
>>> org.ofbiz.service.ModelService.validate(ModelService.java:542)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 366)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 213)
>>> org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:
>>> 149)
>>> org
>>> .ofbiz
>>> .accounting
>>> .invoice.InvoiceServices.createInvoiceForOrder(InvoiceServices.java:
>>> 789)
>>> sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
>>> sun
>>> .reflect
>>> .NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
>>> sun
>>> .reflect
>>> .DelegatingMethodAccessorImpl
>>> .invoke(DelegatingMethodAccessorImpl.java:25)
>>> java.lang.reflect.Method.invoke(Method.java:597)
>>> org
>>> .ofbiz
>>> .service
>>> .engine.StandardJavaEngine.serviceInvoker(StandardJavaEngine.java:
>>> 96)
>>> org
>>> .ofbiz
>>> .service.engine.StandardJavaEngine.runSync(StandardJavaEngine.java:
>>> 54)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 384)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 213)
>>> org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:
>>> 149)
>>> org
>>> .ofbiz
>>> .accounting
>>> .invoice
>>> .InvoiceServices.createInvoicesFromShipments(InvoiceServices.java:
>>> 1544)
>>> sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
>>> sun
>>> .reflect
>>> .NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
>>> sun
>>> .reflect
>>> .DelegatingMethodAccessorImpl
>>> .invoke(DelegatingMethodAccessorImpl.java:25)
>>> java.lang.reflect.Method.invoke(Method.java:597)
>>> org
>>> .ofbiz
>>> .service
>>> .engine.StandardJavaEngine.serviceInvoker(StandardJavaEngine.java:
>>> 96)
>>> org
>>> .ofbiz
>>> .service.engine.StandardJavaEngine.runSync(StandardJavaEngine.java:
>>> 54)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 384)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 213)
>>> org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:
>>> 149)
>>> org
>>> .ofbiz
>>> .accounting
>>> .invoice
>>> .InvoiceServices.createInvoicesFromShipment(InvoiceServices.java:
>>> 1044)
>>> sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
>>> sun
>>> .reflect
>>> .NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
>>> sun
>>> .reflect
>>> .DelegatingMethodAccessorImpl
>>> .invoke(DelegatingMethodAccessorImpl.java:25)
>>> java.lang.reflect.Method.invoke(Method.java:597)
>>> org
>>> .ofbiz
>>> .service
>>> .engine.StandardJavaEngine.serviceInvoker(StandardJavaEngine.java:
>>> 96)
>>> org
>>> .ofbiz
>>> .service.engine.StandardJavaEngine.runSync(StandardJavaEngine.java:
>>> 54)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 384)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 213)
>>> org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:
>>> 149)
>>> org
>>> .ofbiz.service.eca.ServiceEcaAction.runAction(ServiceEcaAction.java:
>>> 135)
>>> org.ofbiz.service.eca.ServiceEcaRule.eval(ServiceEcaRule.java:151)
>>> org.ofbiz.service.eca.ServiceEcaUtil.evalRules(ServiceEcaUtil.java:
>>> 155)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 482)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 213)
>>> org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:
>>> 163)
>>> org.ofbiz.minilang.method.callops.CallService.exec(CallService.java:
>>> 244)
>>> org.ofbiz.minilang.SimpleMethod.runSubOps(SimpleMethod.java:921)
>>> org.ofbiz.minilang.method.conditional.MasterIf.exec(MasterIf.java:
>>> 85)
>>> org.ofbiz.minilang.SimpleMethod.runSubOps(SimpleMethod.java:921)
>>> org.ofbiz.minilang.method.ifops.IfEmpty.exec(IfEmpty.java:98)
>>> org.ofbiz.minilang.SimpleMethod.runSubOps(SimpleMethod.java:921)
>>> org.ofbiz.minilang.method.envops.Iterate.exec(Iterate.java:119)
>>> org.ofbiz.minilang.SimpleMethod.runSubOps(SimpleMethod.java:921)
>>> org.ofbiz.minilang.SimpleMethod.exec(SimpleMethod.java:749)
>>> org
>>> .ofbiz
>>> .minilang
>>> .method.callops.CallSimpleMethod.exec(CallSimpleMethod.java:
>>> 84)
>>> org.ofbiz.minilang.SimpleMethod.runSubOps(SimpleMethod.java:921)
>>> org.ofbiz.minilang.method.envops.Iterate.exec(Iterate.java:119)
>>> org.ofbiz.minilang.SimpleMethod.runSubOps(SimpleMethod.java:921)
>>> org.ofbiz.minilang.SimpleMethod.exec(SimpleMethod.java:749)
>>> org.ofbiz.minilang.SimpleMethod.runSimpleMethod(SimpleMethod.java:
>>> 149)
>>> org.ofbiz.minilang.SimpleMethod.runSimpleService(SimpleMethod.java:
>>> 131)
>>> org
>>> .ofbiz
>>> .minilang
>>> .SimpleServiceEngine.serviceInvoker(SimpleServiceEngine.java:76)
>>> org
>>> .ofbiz
>>> .minilang.SimpleServiceEngine.runSync(SimpleServiceEngine.java:
>>> 51)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 384)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 213)
>>> org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:
>>> 149)
>>> org
>>> .ofbiz
>>> .webapp.event.ServiceEventHandler.invoke(ServiceEventHandler.java:
>>> 332)
>>> org
>>> .ofbiz.webapp.control.RequestHandler.runEvent(RequestHandler.java:
>>> 461)
>>> org
>>> .ofbiz.webapp.control.RequestHandler.doRequest(RequestHandler.java:
>>> 293)
>>> org.ofbiz.webapp.control.ControlServlet.doGet(ControlServlet.java:
>>> 204)
>>> javax.servlet.http.HttpServlet.service(HttpServlet.java:690)
>>> javax.servlet.http.HttpServlet.service(HttpServlet.java:803)
>>> org
>>> .apache
>>> .catalina
>>> .core
>>> .ApplicationFilterChain
>>> .internalDoFilter(ApplicationFilterChain.java:
>>> 290)
>>> org
>>> .apache
>>> .catalina
>>> .core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:
>>> 206)
>>> org.ofbiz.webapp.control.ContextFilter.doFilter(ContextFilter.java:
>>> 259)
>>> org
>>> .apache
>>> .catalina
>>> .core
>>> .ApplicationFilterChain
>>> .internalDoFilter(ApplicationFilterChain.java:
>>> 235)
>>> org
>>> .apache
>>> .catalina
>>> .core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:
>>> 206)
>>> org
>>> .apache
>>> .catalina
>>> .core.StandardWrapperValve.invoke(StandardWrapperValve.java:
>>> 233)
>>> org
>>> .apache
>>> .catalina
>>> .core.StandardContextValve.invoke(StandardContextValve.java:
>>> 175)
>>> org
>>> .apache
>>> .catalina.core.StandardHostValve.invoke(StandardHostValve.java:128)
>>> org
>>> .apache
>>> .catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
>>> org
>>> .apache
>>> .catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:
>>> 109)
>>> org
>>> .apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:
>>> 568)
>>> org
>>> .ofbiz
>>> .catalina
>>> .container
>>> .CrossSubdomainSessionValve.invoke(CrossSubdomainSessionValve.java:
>>> 42)
>>> org
>>> .apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:
>>> 286)
>>> org
>>> .apache.coyote.http11.Http11Processor.process(Http11Processor.java:
>>> 844)
>>> org.apache.coyote.http11.Http11Protocol
>>> $Http11ConnectionHandler.process(Http11Protocol.java:583)
>>> org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:
>>> 447)
>>> java.lang.Thread.run(Thread.java:619)
>>> --------------------------------------------------------------------------------
>>> 2009-05-08 09:11:09,615 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:497:INFO ] [[Sync service failed...- total:
>>> 0.0,since
>>> last(Begin):0.0]] - 'order / createPaymentApplication'
>>> 2009-05-08 09:11:09,617 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:500:ERROR] ---- exception report
>>> ---------------------------------------------------------- Service
>>> [createPaymentApplication] threw an unexpected exception/error
>>> Exception:
>>> org.ofbiz.service.ServiceValidationException Message: Type check
>>> failed for
>>> field [createPaymentApplication.amountApplied]; expected type is
>>> [BigDecimal]; actual type is [java.lang.Double] ---- stack trace
>>> ---------------------------------------------------------------
>>> org.ofbiz.service.ServiceValidationException: Type check failed for
>>> field
>>> [createPaymentApplication.amountApplied]; expected type is
>>> [BigDecimal];
>>> actual type is [java.lang.Double]
>>> org.ofbiz.service.ModelService.validate(ModelService.java:657)
>>> org.ofbiz.service.ModelService.validate(ModelService.java:542)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 366)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 213)
>>> org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:
>>> 149)
>>> org
>>> .ofbiz
>>> .accounting
>>> .invoice.InvoiceServices.createInvoiceForOrder(InvoiceServices.java:
>>> 789)
>>> sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
>>> sun
>>> .reflect
>>> .NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
>>> sun
>>> .reflect
>>> .DelegatingMethodAccessorImpl
>>> .invoke(DelegatingMethodAccessorImpl.java:25)
>>> java.lang.reflect.Method.invoke(Method.java:597)
>>> org
>>> .ofbiz
>>> .service
>>> .engine.StandardJavaEngine.serviceInvoker(StandardJavaEngine.java:
>>> 96)
>>> org
>>> .ofbiz
>>> .service.engine.StandardJavaEngine.runSync(StandardJavaEngine.java:
>>> 54)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 384)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 213)
>>> org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:
>>> 149)
>>> org
>>> .ofbiz
>>> .accounting
>>> .invoice
>>> .InvoiceServices.createInvoicesFromShipments(InvoiceServices.java:
>>> 1544)
>>> sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
>>> sun
>>> .reflect
>>> .NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
>>> sun
>>> .reflect
>>> .DelegatingMethodAccessorImpl
>>> .invoke(DelegatingMethodAccessorImpl.java:25)
>>> java.lang.reflect.Method.invoke(Method.java:597)
>>> org
>>> .ofbiz
>>> .service
>>> .engine.StandardJavaEngine.serviceInvoker(StandardJavaEngine.java:
>>> 96)
>>> org
>>> .ofbiz
>>> .service.engine.StandardJavaEngine.runSync(StandardJavaEngine.java:
>>> 54)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 384)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 213)
>>> org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:
>>> 149)
>>> org
>>> .ofbiz
>>> .accounting
>>> .invoice
>>> .InvoiceServices.createInvoicesFromShipment(InvoiceServices.java:
>>> 1044)
>>> sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
>>> sun
>>> .reflect
>>> .NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
>>> sun
>>> .reflect
>>> .DelegatingMethodAccessorImpl
>>> .invoke(DelegatingMethodAccessorImpl.java:25)
>>> java.lang.reflect.Method.invoke(Method.java:597)
>>> org
>>> .ofbiz
>>> .service
>>> .engine.StandardJavaEngine.serviceInvoker(StandardJavaEngine.java:
>>> 96)
>>> org
>>> .ofbiz
>>> .service.engine.StandardJavaEngine.runSync(StandardJavaEngine.java:
>>> 54)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 384)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 213)
>>> org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:
>>> 149)
>>> org
>>> .ofbiz.service.eca.ServiceEcaAction.runAction(ServiceEcaAction.java:
>>> 135)
>>> org.ofbiz.service.eca.ServiceEcaRule.eval(ServiceEcaRule.java:151)
>>> org.ofbiz.service.eca.ServiceEcaUtil.evalRules(ServiceEcaUtil.java:
>>> 155)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 482)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 213)
>>> org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:
>>> 163)
>>> org.ofbiz.minilang.method.callops.CallService.exec(CallService.java:
>>> 244)
>>> org.ofbiz.minilang.SimpleMethod.runSubOps(SimpleMethod.java:921)
>>> org.ofbiz.minilang.method.conditional.MasterIf.exec(MasterIf.java:
>>> 85)
>>> org.ofbiz.minilang.SimpleMethod.runSubOps(SimpleMethod.java:921)
>>> org.ofbiz.minilang.method.ifops.IfEmpty.exec(IfEmpty.java:98)
>>> org.ofbiz.minilang.SimpleMethod.runSubOps(SimpleMethod.java:921)
>>> org.ofbiz.minilang.method.envops.Iterate.exec(Iterate.java:119)
>>> org.ofbiz.minilang.SimpleMethod.runSubOps(SimpleMethod.java:921)
>>> org.ofbiz.minilang.SimpleMethod.exec(SimpleMethod.java:749)
>>> org
>>> .ofbiz
>>> .minilang
>>> .method.callops.CallSimpleMethod.exec(CallSimpleMethod.java:
>>> 84)
>>> org.ofbiz.minilang.SimpleMethod.runSubOps(SimpleMethod.java:921)
>>> org.ofbiz.minilang.method.envops.Iterate.exec(Iterate.java:119)
>>> org.ofbiz.minilang.SimpleMethod.runSubOps(SimpleMethod.java:921)
>>> org.ofbiz.minilang.SimpleMethod.exec(SimpleMethod.java:749)
>>> org.ofbiz.minilang.SimpleMethod.runSimpleMethod(SimpleMethod.java:
>>> 149)
>>> org.ofbiz.minilang.SimpleMethod.runSimpleService(SimpleMethod.java:
>>> 131)
>>> org
>>> .ofbiz
>>> .minilang
>>> .SimpleServiceEngine.serviceInvoker(SimpleServiceEngine.java:76)
>>> org
>>> .ofbiz
>>> .minilang.SimpleServiceEngine.runSync(SimpleServiceEngine.java:
>>> 51)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 384)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 213)
>>> org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:
>>> 149)
>>> org
>>> .ofbiz
>>> .webapp.event.ServiceEventHandler.invoke(ServiceEventHandler.java:
>>> 332)
>>> org
>>> .ofbiz.webapp.control.RequestHandler.runEvent(RequestHandler.java:
>>> 461)
>>> org
>>> .ofbiz.webapp.control.RequestHandler.doRequest(RequestHandler.java:
>>> 293)
>>> org.ofbiz.webapp.control.ControlServlet.doGet(ControlServlet.java:
>>> 204)
>>> javax.servlet.http.HttpServlet.service(HttpServlet.java:690)
>>> javax.servlet.http.HttpServlet.service(HttpServlet.java:803)
>>> org
>>> .apache
>>> .catalina
>>> .core
>>> .ApplicationFilterChain
>>> .internalDoFilter(ApplicationFilterChain.java:
>>> 290)
>>> org
>>> .apache
>>> .catalina
>>> .core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:
>>> 206)
>>> org.ofbiz.webapp.control.ContextFilter.doFilter(ContextFilter.java:
>>> 259)
>>> org
>>> .apache
>>> .catalina
>>> .core
>>> .ApplicationFilterChain
>>> .internalDoFilter(ApplicationFilterChain.java:
>>> 235)
>>> org
>>> .apache
>>> .catalina
>>> .core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:
>>> 206)
>>> org
>>> .apache
>>> .catalina
>>> .core.StandardWrapperValve.invoke(StandardWrapperValve.java:
>>> 233)
>>> org
>>> .apache
>>> .catalina
>>> .core.StandardContextValve.invoke(StandardContextValve.java:
>>> 175)
>>> org
>>> .apache
>>> .catalina.core.StandardHostValve.invoke(StandardHostValve.java:128)
>>> org
>>> .apache
>>> .catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
>>> org
>>> .apache
>>> .catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:
>>> 109)
>>> org
>>> .apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:
>>> 568)
>>> org
>>> .ofbiz
>>> .catalina
>>> .container
>>> .CrossSubdomainSessionValve.invoke(CrossSubdomainSessionValve.java:
>>> 42)
>>> org
>>> .apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:
>>> 286)
>>> org
>>> .apache.coyote.http11.Http11Processor.process(Http11Processor.java:
>>> 844)
>>> org.apache.coyote.http11.Http11Protocol
>>> $Http11ConnectionHandler.process(Http11Protocol.java:583)
>>> org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:
>>> 447)
>>> java.lang.Thread.run(Thread.java:619)
>>> --------------------------------------------------------------------------------
>>> 2009-05-08 09:11:09,621 (http-0.0.0.0-8443-2) [
>>> TransactionUtil.java:311:WARN ] ---- exception report
>>> ----------------------------------------------------------
>>> [TransactionUtil.setRollbackOnly] Calling transaction
>>> setRollbackOnly; this
>>> stack trace shows where this is happening: Exception:
>>> java.lang.Exception
>>> Message: Service [createPaymentApplication] threw an unexpected
>>> exception/error ---- stack trace
>>> ---------------------------------------------------------------
>>> java.lang.Exception: Service [createPaymentApplication] threw an
>>> unexpected
>>> exception/error
>>> org
>>> .ofbiz
>>> .entity
>>> .transaction.TransactionUtil.setRollbackOnly(TransactionUtil.java:
>>> 311)
>>> org
>>> .ofbiz
>>> .entity.transaction.TransactionUtil.rollback(TransactionUtil.java:
>>> 258)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 503)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 213)
>>> org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:
>>> 149)
>>> org
>>> .ofbiz
>>> .accounting
>>> .invoice.InvoiceServices.createInvoiceForOrder(InvoiceServices.java:
>>> 789)
>>> sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
>>> sun
>>> .reflect
>>> .NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
>>> sun
>>> .reflect
>>> .DelegatingMethodAccessorImpl
>>> .invoke(DelegatingMethodAccessorImpl.java:25)
>>> java.lang.reflect.Method.invoke(Method.java:597)
>>> org
>>> .ofbiz
>>> .service
>>> .engine.StandardJavaEngine.serviceInvoker(StandardJavaEngine.java:
>>> 96)
>>> org
>>> .ofbiz
>>> .service.engine.StandardJavaEngine.runSync(StandardJavaEngine.java:
>>> 54)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 384)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 213)
>>> org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:
>>> 149)
>>> org
>>> .ofbiz
>>> .accounting
>>> .invoice
>>> .InvoiceServices.createInvoicesFromShipments(InvoiceServices.java:
>>> 1544)
>>> sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
>>> sun
>>> .reflect
>>> .NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
>>> sun
>>> .reflect
>>> .DelegatingMethodAccessorImpl
>>> .invoke(DelegatingMethodAccessorImpl.java:25)
>>> java.lang.reflect.Method.invoke(Method.java:597)
>>> org
>>> .ofbiz
>>> .service
>>> .engine.StandardJavaEngine.serviceInvoker(StandardJavaEngine.java:
>>> 96)
>>> org
>>> .ofbiz
>>> .service.engine.StandardJavaEngine.runSync(StandardJavaEngine.java:
>>> 54)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 384)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 213)
>>> org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:
>>> 149)
>>> org
>>> .ofbiz
>>> .accounting
>>> .invoice
>>> .InvoiceServices.createInvoicesFromShipment(InvoiceServices.java:
>>> 1044)
>>> sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
>>> sun
>>> .reflect
>>> .NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
>>> sun
>>> .reflect
>>> .DelegatingMethodAccessorImpl
>>> .invoke(DelegatingMethodAccessorImpl.java:25)
>>> java.lang.reflect.Method.invoke(Method.java:597)
>>> org
>>> .ofbiz
>>> .service
>>> .engine.StandardJavaEngine.serviceInvoker(StandardJavaEngine.java:
>>> 96)
>>> org
>>> .ofbiz
>>> .service.engine.StandardJavaEngine.runSync(StandardJavaEngine.java:
>>> 54)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 384)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 213)
>>> org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:
>>> 149)
>>> org
>>> .ofbiz.service.eca.ServiceEcaAction.runAction(ServiceEcaAction.java:
>>> 135)
>>> org.ofbiz.service.eca.ServiceEcaRule.eval(ServiceEcaRule.java:151)
>>> org.ofbiz.service.eca.ServiceEcaUtil.evalRules(ServiceEcaUtil.java:
>>> 155)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 482)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 213)
>>> org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:
>>> 163)
>>> org.ofbiz.minilang.method.callops.CallService.exec(CallService.java:
>>> 244)
>>> org.ofbiz.minilang.SimpleMethod.runSubOps(SimpleMethod.java:921)
>>> org.ofbiz.minilang.method.conditional.MasterIf.exec(MasterIf.java:
>>> 85)
>>> org.ofbiz.minilang.SimpleMethod.runSubOps(SimpleMethod.java:921)
>>> org.ofbiz.minilang.method.ifops.IfEmpty.exec(IfEmpty.java:98)
>>> org.ofbiz.minilang.SimpleMethod.runSubOps(SimpleMethod.java:921)
>>> org.ofbiz.minilang.method.envops.Iterate.exec(Iterate.java:119)
>>> org.ofbiz.minilang.SimpleMethod.runSubOps(SimpleMethod.java:921)
>>> org.ofbiz.minilang.SimpleMethod.exec(SimpleMethod.java:749)
>>> org
>>> .ofbiz
>>> .minilang
>>> .method.callops.CallSimpleMethod.exec(CallSimpleMethod.java:
>>> 84)
>>> org.ofbiz.minilang.SimpleMethod.runSubOps(SimpleMethod.java:921)
>>> org.ofbiz.minilang.method.envops.Iterate.exec(Iterate.java:119)
>>> org.ofbiz.minilang.SimpleMethod.runSubOps(SimpleMethod.java:921)
>>> org.ofbiz.minilang.SimpleMethod.exec(SimpleMethod.java:749)
>>> org.ofbiz.minilang.SimpleMethod.runSimpleMethod(SimpleMethod.java:
>>> 149)
>>> org.ofbiz.minilang.SimpleMethod.runSimpleService(SimpleMethod.java:
>>> 131)
>>> org
>>> .ofbiz
>>> .minilang
>>> .SimpleServiceEngine.serviceInvoker(SimpleServiceEngine.java:76)
>>> org
>>> .ofbiz
>>> .minilang.SimpleServiceEngine.runSync(SimpleServiceEngine.java:
>>> 51)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 384)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 213)
>>> org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:
>>> 149)
>>> org
>>> .ofbiz
>>> .webapp.event.ServiceEventHandler.invoke(ServiceEventHandler.java:
>>> 332)
>>> org
>>> .ofbiz.webapp.control.RequestHandler.runEvent(RequestHandler.java:
>>> 461)
>>> org
>>> .ofbiz.webapp.control.RequestHandler.doRequest(RequestHandler.java:
>>> 293)
>>> org.ofbiz.webapp.control.ControlServlet.doGet(ControlServlet.java:
>>> 204)
>>> javax.servlet.http.HttpServlet.service(HttpServlet.java:690)
>>> javax.servlet.http.HttpServlet.service(HttpServlet.java:803)
>>> org
>>> .apache
>>> .catalina
>>> .core
>>> .ApplicationFilterChain
>>> .internalDoFilter(ApplicationFilterChain.java:
>>> 290)
>>> org
>>> .apache
>>> .catalina
>>> .core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:
>>> 206)
>>> org.ofbiz.webapp.control.ContextFilter.doFilter(ContextFilter.java:
>>> 259)
>>> org
>>> .apache
>>> .catalina
>>> .core
>>> .ApplicationFilterChain
>>> .internalDoFilter(ApplicationFilterChain.java:
>>> 235)
>>> org
>>> .apache
>>> .catalina
>>> .core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:
>>> 206)
>>> org
>>> .apache
>>> .catalina
>>> .core.StandardWrapperValve.invoke(StandardWrapperValve.java:
>>> 233)
>>> org
>>> .apache
>>> .catalina
>>> .core.StandardContextValve.invoke(StandardContextValve.java:
>>> 175)
>>> org
>>> .apache
>>> .catalina.core.StandardHostValve.invoke(StandardHostValve.java:128)
>>> org
>>> .apache
>>> .catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
>>> org
>>> .apache
>>> .catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:
>>> 109)
>>> org
>>> .apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:
>>> 568)
>>> org
>>> .ofbiz
>>> .catalina
>>> .container
>>> .CrossSubdomainSessionValve.invoke(CrossSubdomainSessionValve.java:
>>> 42)
>>> org
>>> .apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:
>>> 286)
>>> org
>>> .apache.coyote.http11.Http11Processor.process(Http11Processor.java:
>>> 844)
>>> org.apache.coyote.http11.Http11Protocol
>>> $Http11ConnectionHandler.process(Http11Protocol.java:583)
>>> org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:
>>> 447)
>>> java.lang.Thread.run(Thread.java:619)
>>> --------------------------------------------------------------------------------
>>> 2009-05-08 09:11:09,627 (http-0.0.0.0-8443-2) [
>>> InvoiceServices.java:827:ERROR] ---- exception report
>>> ---------------------------------------------------------- Service/
>>> other
>>> problem creating invoice from order items:
>>> org.ofbiz.service.ServiceValidationException: Type check failed for
>>> field
>>> [createPaymentApplication.amountApplied]; expected type is
>>> [BigDecimal];
>>> actual type is [java.lang.Double] Exception:
>>> org.ofbiz.service.ServiceValidationException Message: Type check
>>> failed for
>>> field [createPaymentApplication.amountApplied]; expected type is
>>> [BigDecimal]; actual type is [java.lang.Double] ---- stack trace
>>> ---------------------------------------------------------------
>>> org.ofbiz.service.ServiceValidationException: Type check failed for
>>> field
>>> [createPaymentApplication.amountApplied]; expected type is
>>> [BigDecimal];
>>> actual type is [java.lang.Double]
>>> org.ofbiz.service.ModelService.validate(ModelService.java:657)
>>> org.ofbiz.service.ModelService.validate(ModelService.java:542)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 366)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 213)
>>> org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:
>>> 149)
>>> org
>>> .ofbiz
>>> .accounting
>>> .invoice.InvoiceServices.createInvoiceForOrder(InvoiceServices.java:
>>> 789)
>>> sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
>>> sun
>>> .reflect
>>> .NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
>>> sun
>>> .reflect
>>> .DelegatingMethodAccessorImpl
>>> .invoke(DelegatingMethodAccessorImpl.java:25)
>>> java.lang.reflect.Method.invoke(Method.java:597)
>>> org
>>> .ofbiz
>>> .service
>>> .engine.StandardJavaEngine.serviceInvoker(StandardJavaEngine.java:
>>> 96)
>>> org
>>> .ofbiz
>>> .service.engine.StandardJavaEngine.runSync(StandardJavaEngine.java:
>>> 54)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 384)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 213)
>>> org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:
>>> 149)
>>> org
>>> .ofbiz
>>> .accounting
>>> .invoice
>>> .InvoiceServices.createInvoicesFromShipments(InvoiceServices.java:
>>> 1544)
>>> sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
>>> sun
>>> .reflect
>>> .NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
>>> sun
>>> .reflect
>>> .DelegatingMethodAccessorImpl
>>> .invoke(DelegatingMethodAccessorImpl.java:25)
>>> java.lang.reflect.Method.invoke(Method.java:597)
>>> org
>>> .ofbiz
>>> .service
>>> .engine.StandardJavaEngine.serviceInvoker(StandardJavaEngine.java:
>>> 96)
>>> org
>>> .ofbiz
>>> .service.engine.StandardJavaEngine.runSync(StandardJavaEngine.java:
>>> 54)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 384)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 213)
>>> org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:
>>> 149)
>>> org
>>> .ofbiz
>>> .accounting
>>> .invoice
>>> .InvoiceServices.createInvoicesFromShipment(InvoiceServices.java:
>>> 1044)
>>> sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
>>> sun
>>> .reflect
>>> .NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
>>> sun
>>> .reflect
>>> .DelegatingMethodAccessorImpl
>>> .invoke(DelegatingMethodAccessorImpl.java:25)
>>> java.lang.reflect.Method.invoke(Method.java:597)
>>> org
>>> .ofbiz
>>> .service
>>> .engine.StandardJavaEngine.serviceInvoker(StandardJavaEngine.java:
>>> 96)
>>> org
>>> .ofbiz
>>> .service.engine.StandardJavaEngine.runSync(StandardJavaEngine.java:
>>> 54)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 384)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 213)
>>> org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:
>>> 149)
>>> org
>>> .ofbiz.service.eca.ServiceEcaAction.runAction(ServiceEcaAction.java:
>>> 135)
>>> org.ofbiz.service.eca.ServiceEcaRule.eval(ServiceEcaRule.java:151)
>>> org.ofbiz.service.eca.ServiceEcaUtil.evalRules(ServiceEcaUtil.java:
>>> 155)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 482)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 213)
>>> org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:
>>> 163)
>>> org.ofbiz.minilang.method.callops.CallService.exec(CallService.java:
>>> 244)
>>> org.ofbiz.minilang.SimpleMethod.runSubOps(SimpleMethod.java:921)
>>> org.ofbiz.minilang.method.conditional.MasterIf.exec(MasterIf.java:
>>> 85)
>>> org.ofbiz.minilang.SimpleMethod.runSubOps(SimpleMethod.java:921)
>>> org.ofbiz.minilang.method.ifops.IfEmpty.exec(IfEmpty.java:98)
>>> org.ofbiz.minilang.SimpleMethod.runSubOps(SimpleMethod.java:921)
>>> org.ofbiz.minilang.method.envops.Iterate.exec(Iterate.java:119)
>>> org.ofbiz.minilang.SimpleMethod.runSubOps(SimpleMethod.java:921)
>>> org.ofbiz.minilang.SimpleMethod.exec(SimpleMethod.java:749)
>>> org
>>> .ofbiz
>>> .minilang
>>> .method.callops.CallSimpleMethod.exec(CallSimpleMethod.java:
>>> 84)
>>> org.ofbiz.minilang.SimpleMethod.runSubOps(SimpleMethod.java:921)
>>> org.ofbiz.minilang.method.envops.Iterate.exec(Iterate.java:119)
>>> org.ofbiz.minilang.SimpleMethod.runSubOps(SimpleMethod.java:921)
>>> org.ofbiz.minilang.SimpleMethod.exec(SimpleMethod.java:749)
>>> org.ofbiz.minilang.SimpleMethod.runSimpleMethod(SimpleMethod.java:
>>> 149)
>>> org.ofbiz.minilang.SimpleMethod.runSimpleService(SimpleMethod.java:
>>> 131)
>>> org
>>> .ofbiz
>>> .minilang
>>> .SimpleServiceEngine.serviceInvoker(SimpleServiceEngine.java:76)
>>> org
>>> .ofbiz
>>> .minilang.SimpleServiceEngine.runSync(SimpleServiceEngine.java:
>>> 51)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 384)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 213)
>>> org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:
>>> 149)
>>> org
>>> .ofbiz
>>> .webapp.event.ServiceEventHandler.invoke(ServiceEventHandler.java:
>>> 332)
>>> org
>>> .ofbiz.webapp.control.RequestHandler.runEvent(RequestHandler.java:
>>> 461)
>>> org
>>> .ofbiz.webapp.control.RequestHandler.doRequest(RequestHandler.java:
>>> 293)
>>> org.ofbiz.webapp.control.ControlServlet.doGet(ControlServlet.java:
>>> 204)
>>> javax.servlet.http.HttpServlet.service(HttpServlet.java:690)
>>> javax.servlet.http.HttpServlet.service(HttpServlet.java:803)
>>> org
>>> .apache
>>> .catalina
>>> .core
>>> .ApplicationFilterChain
>>> .internalDoFilter(ApplicationFilterChain.java:
>>> 290)
>>> org
>>> .apache
>>> .catalina
>>> .core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:
>>> 206)
>>> org.ofbiz.webapp.control.ContextFilter.doFilter(ContextFilter.java:
>>> 259)
>>> org
>>> .apache
>>> .catalina
>>> .core
>>> .ApplicationFilterChain
>>> .internalDoFilter(ApplicationFilterChain.java:
>>> 235)
>>> org
>>> .apache
>>> .catalina
>>> .core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:
>>> 206)
>>> org
>>> .apache
>>> .catalina
>>> .core.StandardWrapperValve.invoke(StandardWrapperValve.java:
>>> 233)
>>> org
>>> .apache
>>> .catalina
>>> .core.StandardContextValve.invoke(StandardContextValve.java:
>>> 175)
>>> org
>>> .apache
>>> .catalina.core.StandardHostValve.invoke(StandardHostValve.java:128)
>>> org
>>> .apache
>>> .catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
>>> org
>>> .apache
>>> .catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:
>>> 109)
>>> org
>>> .apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:
>>> 568)
>>> org
>>> .ofbiz
>>> .catalina
>>> .container
>>> .CrossSubdomainSessionValve.invoke(CrossSubdomainSessionValve.java:
>>> 42)
>>> org
>>> .apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:
>>> 286)
>>> org
>>> .apache.coyote.http11.Http11Processor.process(Http11Processor.java:
>>> 844)
>>> org.apache.coyote.http11.Http11Protocol
>>> $Http11ConnectionHandler.process(Http11Protocol.java:583)
>>> org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:
>>> 447)
>>> java.lang.Thread.run(Thread.java:619)
>>> --------------------------------------------------------------------------------
>>> 2009-05-08 09:11:09,630 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:522:ERROR] Error in Service
>>> [createInvoiceForOrder]:
>>> Service/other problem creating invoice from order items:
>>> org.ofbiz.service.ServiceValidationException: Type check failed for
>>> field
>>> [createPaymentApplication.amountApplied]; expected type is
>>> [BigDecimal];
>>> actual type is [java.lang.Double]
>>> 2009-05-08 09:11:09,633 (http-0.0.0.0-8443-2) [
>>> TransactionUtil.java:315:INFO ] [TransactionUtil.setRollbackOnly]
>>> transaction rollback only not set, rollback only is already set.
>>> 2009-05-08 09:11:09,634 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service [order/
>>> createInvoiceForOrder]
>>> finished in [4068] milliseconds
>>> 2009-05-08 09:11:09,638 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/createInvoicesFromShipments] finished in [4166] milliseconds
>>> 2009-05-08 09:11:09,641 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [order/createInvoicesFromShipment] finished in [4173] milliseconds
>>> 2009-05-08 09:11:09,654 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [updateShipment] on [commit] got false for
>>> condition:
>>> [statusId][equals][SHIPMENT_SHIPPED][true][String]
>>> 2009-05-08 09:11:09,657 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [updateShipment] on [commit] got false for
>>> condition:
>>> [statusId][equals][PURCH_SHIP_RECEIVED][true][String]
>>> 2009-05-08 09:11:09,661 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [updateShipment] on [commit] got false for
>>> condition:
>>> [statusId][equals][PURCH_SHIP_SHIPPED][true][String]
>>> 2009-05-08 09:11:09,665 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [updateShipment] on [commit] got false for
>>> condition:
>>> [statusId][equals][PURCH_SHIP_RECEIVED][true][String]
>>> 2009-05-08 09:11:09,668 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [updateShipment] on [commit] got false for
>>> condition:
>>> [statusId][equals][PURCH_SHIP_RECEIVED][true][String]
>>> 2009-05-08 09:11:09,670 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [updateShipment] on [commit] got false for
>>> condition:
>>> [statusId][equals][SHIPMENT_SHIPPED][true][String]
>>> 2009-05-08 09:11:09,681 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [updateShipment] on [commit] got false for
>>> condition:
>>> [statusId][equals][SHIPMENT_SCHEDULED][true][String]
>>> 2009-05-08 09:11:09,682 (http-0.0.0.0-8443-2)
>>> [ServiceEcaCondition.java:155:WARN ] From Field (originFacilityId)
>>> is not
>>> found in context for updateShipment, defaulting to null.
>>> 2009-05-08 09:11:09,684 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [updateShipment] on [commit] got false for
>>> condition:
>>> [originFacilityId][is-not-empty][true][String]
>>> 2009-05-08 09:11:09,685 (http-0.0.0.0-8443-2)
>>> [ServiceEcaCondition.java:155:WARN ] From Field
>>> (destinationFacilityId) is
>>> not found in context for updateShipment, defaulting to null.
>>> 2009-05-08 09:11:09,686 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [updateShipment] on [commit] got false for
>>> condition:
>>> [destinationFacilityId][is-not-empty][true][String]
>>> 2009-05-08 09:11:09,696 (http-0.0.0.0-8443-2)
>>> [ServiceEcaCondition.java:155:WARN ] From Field (primaryOrderId) is
>>> not
>>> found in context for updateShipment, defaulting to null.
>>> 2009-05-08 09:11:09,697 (http-0.0.0.0-8443-2) [ ServiceEcaRule.java:
>>> 133:INFO
>>> ] For Service ECA [updateShipment] on [commit] got false for
>>> condition:
>>> [primaryOrderId][is-not-empty][true][String]
>>> 2009-05-08 09:11:09,698 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service [order/
>>> updateShipment]
>>> finished in [4445] milliseconds
>>> 2009-05-08 09:11:09,770 (http-0.0.0.0-8443-2)  
>>> [ TransactionUtil.java:
>>> 91
>>> :WARN ] ---- exception report
>>> ----------------------------------------------------------
>>> [TransactionUtil.begin] active transaction marked for rollback in
>>> place, so
>>> no transaction begun; this stack trace shows when the exception  
>>> began:
>>> Exception: java.lang.Exception Message: Tx Stack Placeholder ----
>>> stack
>>> trace  
>>> ---------------------------------------------------------------
>>> java.lang.Exception: Tx Stack Placeholder
>>> org
>>> .ofbiz
>>> .entity
>>> .transaction
>>> .TransactionUtil.setTransactionBeginStack(TransactionUtil.java:636)
>>> org
>>> .ofbiz
>>> .entity.transaction.TransactionUtil.begin(TransactionUtil.java:
>>> 125)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 302)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 213)
>>> org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:
>>> 149)
>>> org
>>> .ofbiz
>>> .webapp.event.ServiceEventHandler.invoke(ServiceEventHandler.java:
>>> 332)
>>> org
>>> .ofbiz.webapp.control.RequestHandler.runEvent(RequestHandler.java:
>>> 461)
>>> org
>>> .ofbiz.webapp.control.RequestHandler.doRequest(RequestHandler.java:
>>> 293)
>>> org.ofbiz.webapp.control.ControlServlet.doGet(ControlServlet.java:
>>> 204)
>>> javax.servlet.http.HttpServlet.service(HttpServlet.java:690)
>>> javax.servlet.http.HttpServlet.service(HttpServlet.java:803)
>>> org
>>> .apache
>>> .catalina
>>> .core
>>> .ApplicationFilterChain
>>> .internalDoFilter(ApplicationFilterChain.java:
>>> 290)
>>> org
>>> .apache
>>> .catalina
>>> .core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:
>>> 206)
>>> org.ofbiz.webapp.control.ContextFilter.doFilter(ContextFilter.java:
>>> 259)
>>> org
>>> .apache
>>> .catalina
>>> .core
>>> .ApplicationFilterChain
>>> .internalDoFilter(ApplicationFilterChain.java:
>>> 235)
>>> org
>>> .apache
>>> .catalina
>>> .core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:
>>> 206)
>>> org
>>> .apache
>>> .catalina
>>> .core.StandardWrapperValve.invoke(StandardWrapperValve.java:
>>> 233)
>>> org
>>> .apache
>>> .catalina
>>> .core.StandardContextValve.invoke(StandardContextValve.java:
>>> 175)
>>> org
>>> .apache
>>> .catalina.core.StandardHostValve.invoke(StandardHostValve.java:128)
>>> org
>>> .apache
>>> .catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
>>> org
>>> .apache
>>> .catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:
>>> 109)
>>> org
>>> .apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:
>>> 568)
>>> org
>>> .ofbiz
>>> .catalina
>>> .container
>>> .CrossSubdomainSessionValve.invoke(CrossSubdomainSessionValve.java:
>>> 42)
>>> org
>>> .apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:
>>> 286)
>>> org
>>> .apache.coyote.http11.Http11Processor.process(Http11Processor.java:
>>> 844)
>>> org.apache.coyote.http11.Http11Protocol
>>> $Http11ConnectionHandler.process(Http11Protocol.java:583)
>>> org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:
>>> 447)
>>> java.lang.Thread.run(Thread.java:619)
>>> --------------------------------------------------------------------------------
>>> 2009-05-08 09:11:09,770 (http-0.0.0.0-8443-2) [ SimpleMethod.java:
>>> 731:WARN ]
>>> Error trying to begin transaction, could not process method: The
>>> current
>>> transaction is marked for rollback, not beginning a new transaction
>>> and
>>> aborting current operation; the rollbackOnly was caused by: Service
>>> [createPaymentApplication] threw an unexpected
>>> exception/errororg.ofbiz.service.ServiceValidationException: Type
>>> check
>>> failed for field [createPaymentApplication.amountApplied]; expected
>>> type is
>>> [BigDecimal]; actual type is [java.lang.Double] (Type check failed
>>> for field
>>> [createPaymentApplication.amountApplied]; expected type is
>>> [BigDecimal];
>>> actual type is [java.lang.Double])
>>> 2009-05-08 09:11:09,775 (http-0.0.0.0-8443-2) [ SimpleMethod.java:
>>> 732:WARN ]
>>> ---- exception report
>>> ----------------------------------------------------------  
>>> Exception:
>>> org.ofbiz.entity.transaction.GenericTransactionException Message:  
>>> The
>>> current transaction is marked for rollback, not beginning a new
>>> transaction
>>> and aborting current operation; the rollbackOnly was caused by:
>>> Service
>>> [createPaymentApplication] threw an unexpected
>>> exception/errororg.ofbiz.service.ServiceValidationException: Type
>>> check
>>> failed for field [createPaymentApplication.amountApplied]; expected
>>> type is
>>> [BigDecimal]; actual type is [java.lang.Double] (Type check failed
>>> for field
>>> [createPaymentApplication.amountApplied]; expected type is
>>> [BigDecimal];
>>> actual type is [java.lang.Double]) ---- cause
>>> ---------------------------------------------------------------------
>>> Exception: org.ofbiz.service.ServiceValidationException Message:
>>> Type check
>>> failed for field [createPaymentApplication.amountApplied]; expected
>>> type is
>>> [BigDecimal]; actual type is [java.lang.Double] ---- stack trace
>>> ---------------------------------------------------------------
>>> org.ofbiz.service.ServiceValidationException: Type check failed for
>>> field
>>> [createPaymentApplication.amountApplied]; expected type is
>>> [BigDecimal];
>>> actual type is [java.lang.Double]
>>> org.ofbiz.service.ModelService.validate(ModelService.java:657)
>>> org.ofbiz.service.ModelService.validate(ModelService.java:542)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 366)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 213)
>>> org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:
>>> 149)
>>> org
>>> .ofbiz
>>> .accounting
>>> .invoice.InvoiceServices.createInvoiceForOrder(InvoiceServices.java:
>>> 789)
>>> sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
>>> sun
>>> .reflect
>>> .NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
>>> sun
>>> .reflect
>>> .DelegatingMethodAccessorImpl
>>> .invoke(DelegatingMethodAccessorImpl.java:25)
>>> java.lang.reflect.Method.invoke(Method.java:597)
>>> org
>>> .ofbiz
>>> .service
>>> .engine.StandardJavaEngine.serviceInvoker(StandardJavaEngine.java:
>>> 96)
>>> org
>>> .ofbiz
>>> .service.engine.StandardJavaEngine.runSync(StandardJavaEngine.java:
>>> 54)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 384)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 213)
>>> org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:
>>> 149)
>>> org
>>> .ofbiz
>>> .accounting
>>> .invoice
>>> .InvoiceServices.createInvoicesFromShipments(InvoiceServices.java:
>>> 1544)
>>> sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
>>> sun
>>> .reflect
>>> .NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
>>> sun
>>> .reflect
>>> .DelegatingMethodAccessorImpl
>>> .invoke(DelegatingMethodAccessorImpl.java:25)
>>> java.lang.reflect.Method.invoke(Method.java:597)
>>> org
>>> .ofbiz
>>> .service
>>> .engine.StandardJavaEngine.serviceInvoker(StandardJavaEngine.java:
>>> 96)
>>> org
>>> .ofbiz
>>> .service.engine.StandardJavaEngine.runSync(StandardJavaEngine.java:
>>> 54)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 384)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 213)
>>> org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:
>>> 149)
>>> org
>>> .ofbiz
>>> .accounting
>>> .invoice
>>> .InvoiceServices.createInvoicesFromShipment(InvoiceServices.java:
>>> 1044)
>>> sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
>>> sun
>>> .reflect
>>> .NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
>>> sun
>>> .reflect
>>> .DelegatingMethodAccessorImpl
>>> .invoke(DelegatingMethodAccessorImpl.java:25)
>>> java.lang.reflect.Method.invoke(Method.java:597)
>>> org
>>> .ofbiz
>>> .service
>>> .engine.StandardJavaEngine.serviceInvoker(StandardJavaEngine.java:
>>> 96)
>>> org
>>> .ofbiz
>>> .service.engine.StandardJavaEngine.runSync(StandardJavaEngine.java:
>>> 54)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 384)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 213)
>>> org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:
>>> 149)
>>> org
>>> .ofbiz.service.eca.ServiceEcaAction.runAction(ServiceEcaAction.java:
>>> 135)
>>> org.ofbiz.service.eca.ServiceEcaRule.eval(ServiceEcaRule.java:151)
>>> org.ofbiz.service.eca.ServiceEcaUtil.evalRules(ServiceEcaUtil.java:
>>> 155)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 482)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 213)
>>> org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:
>>> 163)
>>> org.ofbiz.minilang.method.callops.CallService.exec(CallService.java:
>>> 244)
>>> org.ofbiz.minilang.SimpleMethod.runSubOps(SimpleMethod.java:921)
>>> org.ofbiz.minilang.method.conditional.MasterIf.exec(MasterIf.java:
>>> 85)
>>> org.ofbiz.minilang.SimpleMethod.runSubOps(SimpleMethod.java:921)
>>> org.ofbiz.minilang.method.ifops.IfEmpty.exec(IfEmpty.java:98)
>>> org.ofbiz.minilang.SimpleMethod.runSubOps(SimpleMethod.java:921)
>>> org.ofbiz.minilang.method.envops.Iterate.exec(Iterate.java:119)
>>> org.ofbiz.minilang.SimpleMethod.runSubOps(SimpleMethod.java:921)
>>> org.ofbiz.minilang.SimpleMethod.exec(SimpleMethod.java:749)
>>> org
>>> .ofbiz
>>> .minilang
>>> .method.callops.CallSimpleMethod.exec(CallSimpleMethod.java:
>>> 84)
>>> org.ofbiz.minilang.SimpleMethod.runSubOps(SimpleMethod.java:921)
>>> org.ofbiz.minilang.method.envops.Iterate.exec(Iterate.java:119)
>>> org.ofbiz.minilang.SimpleMethod.runSubOps(SimpleMethod.java:921)
>>> org.ofbiz.minilang.SimpleMethod.exec(SimpleMethod.java:749)
>>> org.ofbiz.minilang.SimpleMethod.runSimpleMethod(SimpleMethod.java:
>>> 149)
>>> org.ofbiz.minilang.SimpleMethod.runSimpleService(SimpleMethod.java:
>>> 131)
>>> org
>>> .ofbiz
>>> .minilang
>>> .SimpleServiceEngine.serviceInvoker(SimpleServiceEngine.java:76)
>>> org
>>> .ofbiz
>>> .minilang.SimpleServiceEngine.runSync(SimpleServiceEngine.java:
>>> 51)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 384)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 213)
>>> org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:
>>> 149)
>>> org
>>> .ofbiz
>>> .webapp.event.ServiceEventHandler.invoke(ServiceEventHandler.java:
>>> 332)
>>> org
>>> .ofbiz.webapp.control.RequestHandler.runEvent(RequestHandler.java:
>>> 461)
>>> org
>>> .ofbiz.webapp.control.RequestHandler.doRequest(RequestHandler.java:
>>> 293)
>>> org.ofbiz.webapp.control.ControlServlet.doGet(ControlServlet.java:
>>> 204)
>>> javax.servlet.http.HttpServlet.service(HttpServlet.java:690)
>>> javax.servlet.http.HttpServlet.service(HttpServlet.java:803)
>>> org
>>> .apache
>>> .catalina
>>> .core
>>> .ApplicationFilterChain
>>> .internalDoFilter(ApplicationFilterChain.java:
>>> 290)
>>> org
>>> .apache
>>> .catalina
>>> .core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:
>>> 206)
>>> org.ofbiz.webapp.control.ContextFilter.doFilter(ContextFilter.java:
>>> 259)
>>> org
>>> .apache
>>> .catalina
>>> .core
>>> .ApplicationFilterChain
>>> .internalDoFilter(ApplicationFilterChain.java:
>>> 235)
>>> org
>>> .apache
>>> .catalina
>>> .core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:
>>> 206)
>>> org
>>> .apache
>>> .catalina
>>> .core.StandardWrapperValve.invoke(StandardWrapperValve.java:
>>> 233)
>>> org
>>> .apache
>>> .catalina
>>> .core.StandardContextValve.invoke(StandardContextValve.java:
>>> 175)
>>> org
>>> .apache
>>> .catalina.core.StandardHostValve.invoke(StandardHostValve.java:128)
>>> org
>>> .apache
>>> .catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
>>> org
>>> .apache
>>> .catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:
>>> 109)
>>> org
>>> .apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:
>>> 568)
>>> org
>>> .ofbiz
>>> .catalina
>>> .container
>>> .CrossSubdomainSessionValve.invoke(CrossSubdomainSessionValve.java:
>>> 42)
>>> org
>>> .apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:
>>> 286)
>>> org
>>> .apache.coyote.http11.Http11Processor.process(Http11Processor.java:
>>> 844)
>>> org.apache.coyote.http11.Http11Protocol
>>> $Http11ConnectionHandler.process(Http11Protocol.java:583)
>>> org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:
>>> 447)
>>> java.lang.Thread.run(Thread.java:619)
>>> --------------------------------------------------------------------------------
>>> 2009-05-08 09:11:09,778 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:522:ERROR] Error in Service [updateShipment]:
>>> Error
>>> trying to begin transaction, could not process method: The current
>>> transaction is marked for rollback, not beginning a new transaction
>>> and
>>> aborting current operation; the rollbackOnly was caused by: Service
>>> [createPaymentApplication] threw an unexpected
>>> exception/errororg.ofbiz.service.ServiceValidationException: Type
>>> check
>>> failed for field [createPaymentApplication.amountApplied]; expected
>>> type is
>>> [BigDecimal]; actual type is [java.lang.Double] (Type check failed
>>> for field
>>> [createPaymentApplication.amountApplied]; expected type is
>>> [BigDecimal];
>>> actual type is [java.lang.Double])
>>> 2009-05-08 09:11:09,783 (http-0.0.0.0-8443-2) [
>>> TransactionUtil.java:315:INFO ] [TransactionUtil.setRollbackOnly]
>>> transaction rollback only not set, rollback only is already set.
>>> 2009-05-08 09:11:09,784 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service [order/
>>> updateShipment]
>>> finished in [64] milliseconds
>>> 2009-05-08 09:11:09,792 (http-0.0.0.0-8443-2) [
>>> TransactionUtil.java:315:INFO ] [TransactionUtil.setRollbackOnly]
>>> transaction rollback only not set, rollback only is already set.
>>> 2009-05-08 09:11:09,795 (http-0.0.0.0-8443-2) [
>>> CallSimpleMethod.java:107:WARN ] Got error [error] calling inline
>>> simple-method named [createShipmentForFacilityAndShipGroup] in
>>> resource [],
>>> message is Error:?Error trying to begin transaction, could not  
>>> process
>>> method: The current transaction is marked for rollback, not
>>> beginning a new
>>> transaction and aborting current operation; the rollbackOnly was
>>> caused by:
>>> Service [createPaymentApplication] threw an unexpected
>>> exception/errororg.ofbiz.service.ServiceValidationException: Type
>>> check
>>> failed for field [createPaymentApplication.amountApplied]; expected
>>> type is
>>> [BigDecimal]; actual type is [java.lang.Double] (Type check failed
>>> for field
>>> [createPaymentApplication.amountApplied]; expected type is
>>> [BigDecimal];
>>> actual type is [java.lang.Double])?? calling service  
>>> updateShipment in
>>> createShipmentForFacilityAndShipGroup, and the error message list
>>> is: null
>>> 2009-05-08 09:11:09,801 (http-0.0.0.0-8443-2) [
>>> TransactionUtil.java:315:INFO ] [TransactionUtil.setRollbackOnly]
>>> transaction rollback only not set, rollback only is already set.
>>> 2009-05-08 09:11:09,802 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:522:ERROR] Error in Service
>>> [quickShipEntireOrder]:
>>> Error:?Error trying to begin transaction, could not process method:
>>> The
>>> current transaction is marked for rollback, not beginning a new
>>> transaction
>>> and aborting current operation; the rollbackOnly was caused by:
>>> Service
>>> [createPaymentApplication] threw an unexpected
>>> exception/errororg.ofbiz.service.ServiceValidationException: Type
>>> check
>>> failed for field [createPaymentApplication.amountApplied]; expected
>>> type is
>>> [BigDecimal]; actual type is [java.lang.Double] (Type check failed
>>> for field
>>> [createPaymentApplication.amountApplied]; expected type is
>>> [BigDecimal];
>>> actual type is [java.lang.Double])?? calling service  
>>> updateShipment in
>>> createShipmentForFacilityAndShipGroup
>>> 2009-05-08 09:11:09,831 (http-0.0.0.0-8443-2) [
>>> TransactionUtil.java:275:ERROR] ---- exception report
>>> ----------------------------------------------------------
>>> [TransactionUtil.rollback] Exception: java.lang.Exception Message:
>>> Stack
>>> Trace ---- stack trace
>>> ---------------------------------------------------------------
>>> java.lang.Exception: Stack Trace
>>> org
>>> .ofbiz
>>> .entity.transaction.TransactionUtil.rollback(TransactionUtil.java:
>>> 274)
>>> org
>>> .ofbiz
>>> .entity.transaction.TransactionUtil.rollback(TransactionUtil.java:
>>> 256)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 526)
>>> org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:
>>> 213)
>>> org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:
>>> 149)
>>> org
>>> .ofbiz
>>> .webapp.event.ServiceEventHandler.invoke(ServiceEventHandler.java:
>>> 332)
>>> org
>>> .ofbiz.webapp.control.RequestHandler.runEvent(RequestHandler.java:
>>> 461)
>>> org
>>> .ofbiz.webapp.control.RequestHandler.doRequest(RequestHandler.java:
>>> 293)
>>> org.ofbiz.webapp.control.ControlServlet.doGet(ControlServlet.java:
>>> 204)
>>> javax.servlet.http.HttpServlet.service(HttpServlet.java:690)
>>> javax.servlet.http.HttpServlet.service(HttpServlet.java:803)
>>> org
>>> .apache
>>> .catalina
>>> .core
>>> .ApplicationFilterChain
>>> .internalDoFilter(ApplicationFilterChain.java:
>>> 290)
>>> org
>>> .apache
>>> .catalina
>>> .core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:
>>> 206)
>>> org.ofbiz.webapp.control.ContextFilter.doFilter(ContextFilter.java:
>>> 259)
>>> org
>>> .apache
>>> .catalina
>>> .core
>>> .ApplicationFilterChain
>>> .internalDoFilter(ApplicationFilterChain.java:
>>> 235)
>>> org
>>> .apache
>>> .catalina
>>> .core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:
>>> 206)
>>> org
>>> .apache
>>> .catalina
>>> .core.StandardWrapperValve.invoke(StandardWrapperValve.java:
>>> 233)
>>> org
>>> .apache
>>> .catalina
>>> .core.StandardContextValve.invoke(StandardContextValve.java:
>>> 175)
>>> org
>>> .apache
>>> .catalina.core.StandardHostValve.invoke(StandardHostValve.java:128)
>>> org
>>> .apache
>>> .catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
>>> org
>>> .apache
>>> .catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:
>>> 109)
>>> org
>>> .apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:
>>> 568)
>>> org
>>> .ofbiz
>>> .catalina
>>> .container
>>> .CrossSubdomainSessionValve.invoke(CrossSubdomainSessionValve.java:
>>> 42)
>>> org
>>> .apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:
>>> 286)
>>> org
>>> .apache.coyote.http11.Http11Processor.process(Http11Processor.java:
>>> 844)
>>> org.apache.coyote.http11.Http11Protocol
>>> $Http11ConnectionHandler.process(Http11Protocol.java:583)
>>> org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:
>>> 447)
>>> java.lang.Thread.run(Thread.java:619)
>>> --------------------------------------------------------------------------------
>>> 2009-05-08 09:11:09,931 (http-0.0.0.0-8443-2) [
>>> TransactionUtil.java:285:INFO ] [TransactionUtil.rollback]  
>>> transaction
>>> rolled back
>>> 2009-05-08 09:11:09,932 (http-0.0.0.0-8443-2) [
>>> ServiceDispatcher.java:578:INFO ] Sync service [order/
>>> quickShipEntireOrder]
>>> finished in [17919] milliseconds
>>> 2009-05-08 09:11:09,940 (default-invoker-Thread-1950) [
>>> UtilXml.java:246:INFO ] XML Read 0.026s:
>>> file:/opt/ofbiz/hot-deploy/brandsparadise/widget/OrderScreens.xml
>>> 2009-05-08 09:11:09,959 (http-0.0.0.0-8443-2) [ UtilXml.java:
>>> 246:INFO ] XML
>>> Read 0.026s: jndi:/0.0.0.0/ordermgr/WEB-INF/controller.xml
>>> 2009-05-08 09:11:10,254 (default-invoker-Thread-1950) [
>>> ScreenFactory.java:129:INFO ] Got 37 screens in 0.34s from:
>>> file:/opt/ofbiz/hot-deploy/brandsparadise/widget/OrderScreens.xml
>>> 2009-05-08 09:11:10,277 (http-0.0.0.0-8443-2) [ UtilXml.java:
>>> 246:INFO ] XML
>>> Read 0.235s:
>>> file:/opt/ofbiz/framework/common/webcommon/WEB-INF/common-
>>> controller.xml
>>> 2009-05-08 09:11:10,278 (http-0.0.0.0-8443-2) [
>>> ConfigXMLReader.java:631:INFO ] ConfigMap Created: (4) records in
>>> 0.236s
>>> 2009-05-08 09:11:10,283 (http-0.0.0.0-8443-2) [
>>> ConfigXMLReader.java:631:INFO ] ConfigMap Created: (4) records in
>>> 0.243s
>>> 2009-05-08 09:11:10,312 (http-0.0.0.0-8443-2) [ UtilXml.java:
>>> 246:INFO ] XML
>>> Read 0.028s:
>>> file:/opt/ofbiz/framework/common/webcommon/WEB-INF/common-
>>> controller.xml
>>> 2009-05-08 09:11:10,327 (http-0.0.0.0-8443-2) [
>>> ConfigXMLReader.java:774:INFO ] HandlerMap Created: (7) view
>>> handlers and
>>> (11) request/event handlers in 0.043s
>>> 2009-05-08 09:11:10,351 (http-0.0.0.0-8443-2) [
>>> ConfigXMLReader.java:774:INFO ] HandlerMap Created: (7) view
>>> handlers and
>>> (13) request/event handlers in 0.067s
>>> 2009-05-08 09:11:10,361 (http-0.0.0.0-8443-2) [ UtilXml.java:
>>> 246:INFO ] XML
>>> Read 0.0090s:
>>> file:/opt/ofbiz/framework/common/webcommon/WEB-INF/common-
>>> controller.xml
>>> 2009-05-08 09:11:10,472 (http-0.0.0.0-8443-2) [
>>> ConfigXMLReader.java:379:INFO ] RequestMap Created: (14) records in
>>> 0.12s
>>> 2009-05-08 09:11:10,705 (http-0.0.0.0-8443-2) [
>>> ConfigXMLReader.java:379:INFO ] RequestMap Created: (312) records in
>>> 0.353s
>>> 2009-05-08 09:11:10,747 (http-0.0.0.0-8443-2) [ UtilXml.java:
>>> 246:INFO ] XML
>>> Read 0.042s:
>>> file:/opt/ofbiz/framework/common/webcommon/WEB-INF/common-
>>> controller.xml
>>> 2009-05-08 09:11:10,757 (http-0.0.0.0-8443-2) [
>>> ConfigXMLReader.java:467:INFO ] ViewMap Created: (12) records in
>>> 0.052s
>>> 2009-05-08 09:11:10,866 (http-0.0.0.0-8443-2) [
>>> ConfigXMLReader.java:467:INFO ] ViewMap Created: (138) records in
>>> 0.161s
>>> 2009-05-08 09:11:10,823 (default-invoker-Thread-1950) [
>>> UtilXml.java:246:INFO ] XML Read 0.252s:
>>> file:/opt/ofbiz/applications/party/script/org/ofbiz/party/party/
>>> PartyServices.xml
>>> 2009-05-08 09:11:10,918 (http-0.0.0.0-8443-2) [ UtilXml.java:
>>> 246:INFO ] XML
>>> Read 0.051s:
>>> file:/opt/ofbiz/framework/common/webcommon/WEB-INF/common-
>>> controller.xml
>>> 2009-05-08 09:11:10,930 (http-0.0.0.0-8443-2) [
>>> RequestHandler.java:326:ERROR] Request quickShipOrder caused an
>>> error with
>>> the following message: Error:?Error trying to begin transaction,
>>> could not
>>> process method: The current transaction is marked for rollback, not
>>> beginning a new transaction and aborting current operation; the
>>> rollbackOnly
>>> was caused by: Service [createPaymentApplication] threw an  
>>> unexpected
>>> exception/errororg.ofbiz.service.ServiceValidationException: Type
>>> check
>>> failed for field [createPaymentApplication.amountApplied]; expected
>>> type is
>>> [BigDecimal]; actual type is [java.lang.Double] (Type check failed
>>> for field
>>> [createPaymentApplication.amountApplied]; expected type is
>>> [BigDecimal];
>>> actual type is [java.lang.Double])?? calling service  
>>> updateShipment in
>>> createShipmentForFacilityAndShipGroup
>>> 2009-05-08 09:11:10,933 (http-0.0.0.0-8443-2) [ RequestHandler.java:
>>> 430:INFO
>>> ] [RequestHandler.doRequest]: Response is a Request redirect.
>>> sessionId=B6AF9B84C66AC116E0A250074D2D1045.jvm1
>>> 2009-05-08 09:11:10,934 (http-0.0.0.0-8443-2) [ RequestHandler.java:
>>> 546:INFO
>>> ] [Sending redirect]: /ordermgr/control/orderview?orderId=WSCO10462
>>> sessionId=B6AF9B84C66AC116E0A250074D2D1045.jvm1
>>> 2009-05-08 09:11:11,023 (default-invoker-Thread-1950) [
>>> ServiceDispatcher.java:578:INFO ] Sync service
>>> [JobDispatcher/getPartyNameForDate] finished in [482] milliseconds
>>> 2009-05-08 09:11:11,221 (http-0.0.0.0-8443-2) [ ControlServlet.java:
>>> 302:INFO
>>> ] [[[quickShipOrder] Done rendering page, Servlet Finished-
>>> total:19.617,since last([quickShipOrder] ...):19.567]]
>>> 2009-05-08 09:11:11,252 (http-0.0.0.0-8443-3) [ ControlServlet.java:
>>> 93 :INFO
>>> ] The character encoding of the request is: [null]. The character
>>> encoding
>>> we will use for the request and response is: [UTF-8]
>>> 2009-05-08 09:11:11,253 (http-0.0.0.0-8443-3) [ ControlServlet.java:
>>> 131:INFO
>>> ] [[[orderview] Servlet Starting, doing setup- total:0.0,since
>>> last(Begin):0.0]]
>>> 2009-05-08 09:11:11,291 (http-0.0.0.0-8443-3) [ ControlServlet.java:
>>> 196:INFO
>>> ] [[[orderview] Setup done, doing Event(s) and View(s)- total:
>>> 0.037,since
>>> last([orderview] Servl...):0.037]]
>>> 2009-05-08 09:11:11,306 (http-0.0.0.0-8443-3) [ RequestHandler.java:
>>> 256:INFO
>>> ] [Processing Request]: orderview
>>> sessionId=B6AF9B84C66AC116E0A250074D2D1045.jvm1
>>> 2009-05-08 09:11:11,310 (http-0.0.0.0-8443-3) [ RequestHandler.java:
>>> 440:INFO
>>> ] [RequestHandler.doRequest]: Response is a view.
>>> sessionId=B6AF9B84C66AC116E0A250074D2D1045.jvm1
>>> 2009-05-08 09:11:11,310 (http-0.0.0.0-8443-3) [ RequestHandler.java:
>>> 591:INFO
>>> ] servletName=control, view=orderview
>>> sessionId=B6AF9B84C66AC116E0A250074D2D1045.jvm1
>>> 2009-05-08 09:11:11,323 (default-invoker-Thread-1950)
>>> [ServiceEcaCondition.java:155:WARN ] From Field
>>> (communicationEventId) is
>>> not found in context for sendMail, defaulting to null.
>>> 2009-05-08 09:11:11,325 (default-invoker-Thread-1950) [
>>> ServiceEcaRule.java:150:INFO ] Running Service ECA Service:
>>> storeEmailAsCommunication, triggered by rule on Service: sendMail
>>>
>>>
>>> Somehow I get the feeling that I am missing 1 Parameter in the
>>> Moneybookers
>>> java file or that the paypal component might be a little buggy (has
>>> this
>>> been tested under live conditions recently?)
>>>
>>> So do you have an idea on this one?
>>>
>>> Cheers and thanks,
>>> Paul
>>>
>>>
>>> P.S:: Sorry about the uproar, being the sole programmer here, life
>>> gets
>>> frustrating sometimes ;)
>>>
>>>
>>>
>>>
>>>
>>>
>>>
>>>
>>>
>>>
>>>
>>>
>>> David E Jones-3 wrote:
>>>>
>>>>
>>>> Whatever the case, if you make it as easy as possible for people to
>>>> understand your question and recommend an answer someone will be  
>>>> more
>>>> likely to do so. If you don't put much effort into describing  
>>>> context
>>>> and your specific problem, and if applicable things like steps to
>>>> reproduce and such... then chances are people will read it and  
>>>> think
>>>> "I have NO idea what they are talking about... <delete>".
>>>>
>>>> -David
>>>>
>>>>
>>>> On May 7, 2009, at 3:02 PM, Jacques Le Roux wrote:
>>>>
>>>>> So true most of the time! Note that Paul uses Nabble. So it's  
>>>>> easier
>>>>> than usual (when people cut threads) to follow. But yes you have  
>>>>> to
>>>>> use the link. Sometimes though it's easier than reading messages
>>>>> with a lot of  >>>>>>> ;o)
>>>>>
>>>>> Jacques
>>>>>
>>>>> From: "BJ Freeman" <[hidden email]>
>>>>>> or your not posting the whole thread and not one wants to trace
>>>>>> back to
>>>>>> all the messages.
>>>>>>
>>>>>> madppiper sent the following on 5/7/2009 12:52 PM:
>>>>>>> I'm beginning to think that I should just stop posting... Isn't
>>>>>>> there anybody
>>>>>>> familiar with the payment process?
>>>>>>
>>>>>> --
>>>>>> BJ Freeman
>>>>>> http://www.businessesnetwork.com/automation
>>>>>> http://bjfreeman.elance.com
>>>>>> http://www.linkedin.com/profile?viewProfile=&key=1237480&locale=en_US&trk=tab_pro
>>>>>> Systems Integrator.
>>>>>>
>>>>>
>>>>>
>>>>
>>>>
>>>>
>>>
>>> --
>>> View this message in context:
>>> http://www.nabble.com/Order-status-tp18605511p23441236.html
>>> Sent from the OFBiz - User mailing list archive at Nabble.com.
>>>
>>
>>
>>
>>
>
> --
> View this message in context: http://www.nabble.com/Order-status-tp18605511p23441861.html
> Sent from the OFBiz - User mailing list archive at Nabble.com.
>


smime.p7s (3K) Download Attachment
123