http://ofbiz.116.s1.nabble.com/jira-Created-OFBIZ-114-Error-Receive-Inventory-Quick-Receive-Shipment-tp170127p170147.html
Hi. Your log file got cut off in the comments section. Generally it's better to attach the log file as an uploaded file rather than just paste because the logs can be a bit long.
Debit and Credit Totals must be equal to post to GL., Debit and Credit Totals must be equal to post to GL.
473265 (http-0.0.0.0-8443-Processor4) [ TransactionUtil.java:280:INFO ] [TransactionUtil.setRollbackOnly] transaction rollback only not set, rollback only is already set.
But I tested it again with HSQL and opentaps 0.9.1 with your exact order and everything worked without problems. You've also seen that it runs on our demo server with PostgreSQL fine, so I'm not sure what the problem is.
How about try to install it on MySQL or PostgreSQL first (whichever one you plan on using long term) and then trying it again? While I can't say that's the problem, I'm not sure it's worth it tracking down the bug if it turns out to be some HSQL-related thing.
> Error Receive Inventory / Quick Receive Shipment
> ------------------------------------------------
>
> Key: OFBIZ-114
> URL:
http://issues.apache.org/jira/browse/OFBIZ-114> Project: OFBiz (The Open for Business Project)
> Issue Type: Bug
> Components: order
> Environment: Ubuntu Breezy Badger
> Reporter: Carl Johansson
>
> When I try to receive inventory after purchasing a product I get an error message. Generally I can't complete anything.
> I currently run Opentaps 0.9 with the HSQL on my Ubuntu box but have have also tried on Fedora Core 5 and XP with pretty much the same problems.
> When I install Opentaps I just unzip it and run "sh startofbiz.sh" and tail the console.log file.
> Si thinks that it isn't db related.Personally I think that there is something wrong with what I do. Here is the description.
> 1 Check to see that I have a supplier for the Tiny Gizmo.
> 2 Enter an order for Comapny from BigSupplier.
> 3 Select currency and agreements, "1000 - Agreement with BigSupplier".
> 4 Add 501 pcs of Tiny Gizmo.
> 5 Continue without changing the terms.
> 6 Continue with the selected shipping address.
> 7 Continue with "Ship all at once".
> 8 Continue without any additional parties.
> 9 Create order.
> 10 Confirm order.
> 11 Approve order.
> 12 New shipment.
> 13 Change shipment status to "Receive (Received)".
> 14 Reveive inventory, error
> And that is where it malfunctions. However it works very well on the Opentaps Online Demo. There I can also Quick Receive the order just fine.
>
http://www.carl-johansson.com/ofbiz/carlj1>
http://www.carl-johansson.com/ofbiz/carlj2>
http://www.carl-johansson.com/ofbiz/carlj4>
http://www.carl-johansson.com/ofbiz/carlj5>
http://www.carl-johansson.com/ofbiz/carlj6>
http://www.carl-johansson.com/ofbiz/carlj7>
http://www.carl-johansson.com/ofbiz/carlj8>
http://www.carl-johansson.com/ofbiz/carlj9>
http://www.carl-johansson.com/ofbiz/carlj10>
http://www.carl-johansson.com/ofbiz/carlj11>
http://www.carl-johansson.com/ofbiz/carlj12>
http://www.carl-johansson.com/ofbiz/carlj13>
http://www.carl-johansson.com/ofbiz/carlj14a>
http://www.carl-johansson.com/ofbiz/carlj14b> ------------------
> console.log
> ------------------
> Set OFBIZ_HOME to - /usr/opentaps
> Admin socket configured on - /127.0.0.1:10523
> 0 (main) [ ContainerLoader.java:57 :INFO ] [Startup] Loading containers...
> 1082 (main) [ UtilXml.java:242:DEBUG] XML Read 1.031s: file:/usr/opentaps/base/config/ofbiz-containers.xml
> 1135 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0070s: file:/usr/opentaps/base/config/component-load.xml
> 1143 (main) [ ComponentContainer.java:155:INFO ] Auto-Loading component directory : [/usr/opentaps/framework]
> 1154 (main) [ UtilXml.java:242:DEBUG] XML Read 0.011s: file:/usr/opentaps/framework/component-load.xml
> 1180 (main) [ UtilXml.java:242:DEBUG] XML Read 0.021s: file:/usr/opentaps/framework/minerva/ofbiz-component.xml
> 1182 (main) [ ComponentContainer.java:217:INFO ] Loading component : [minerva]
> 1208 (main) [ UtilXml.java:242:DEBUG] XML Read 0.025s: file:/usr/opentaps/framework/jotm/ofbiz-component.xml
> 1209 (main) [ ComponentContainer.java:217:INFO ] Loading component : [jotm]
> 1229 (main) [ UtilXml.java:242:DEBUG] XML Read 0.019s: file:/usr/opentaps/framework/entity/ofbiz-component.xml
> 1233 (main) [ ComponentContainer.java:217:INFO ] Loading component : [entity]
> 1254 (main) [ UtilXml.java:242:DEBUG] XML Read 0.018s: file:/usr/opentaps/framework/catalina/ofbiz-component.xml
> 1255 (main) [ ComponentContainer.java:217:INFO ] Loading component : [catalina]
> 1279 (main) [ UtilXml.java:242:DEBUG] XML Read 0.021s: file:/usr/opentaps/framework/security/ofbiz-component.xml
> 1280 (main) [ ComponentContainer.java:217:INFO ] Loading component : [security]
> 1297 (main) [ UtilXml.java:242:DEBUG] XML Read 0.016s: file:/usr/opentaps/framework/datafile/ofbiz-component.xml
> 1297 (main) [ ComponentContainer.java:217:INFO ] Loading component : [datafile]
> 1324 (main) [ UtilXml.java:242:DEBUG] XML Read 0.025s: file:/usr/opentaps/framework/minilang/ofbiz-component.xml
> 1325 (main) [ ComponentContainer.java:217:INFO ] Loading component : [minilang]
> 1347 (main) [ UtilXml.java:242:DEBUG] XML Read 0.022s: file:/usr/opentaps/framework/common/ofbiz-component.xml
> 1351 (main) [ ComponentContainer.java:217:INFO ] Loading component : [common]
> 1371 (main) [ UtilXml.java:242:DEBUG] XML Read 0.018s: file:/usr/opentaps/framework/service/ofbiz-component.xml
> 1373 (main) [ ComponentContainer.java:217:INFO ] Loading component : [service]
> 1392 (main) [ UtilXml.java:242:DEBUG] XML Read 0.018s: file:/usr/opentaps/framework/entityext/ofbiz-component.xml
> 1393 (main) [ ComponentContainer.java:217:INFO ] Loading component : [entityext]
> 1407 (main) [ UtilXml.java:242:DEBUG] XML Read 0.014s: file:/usr/opentaps/framework/webapp/ofbiz-component.xml
> 1408 (main) [ ComponentContainer.java:217:INFO ] Loading component : [webapp]
> 1420 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0090s: file:/usr/opentaps/framework/guiapp/ofbiz-component.xml
> 1421 (main) [ ComponentContainer.java:217:INFO ] Loading component : [guiapp]
> 1430 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0070s: file:/usr/opentaps/framework/widget/ofbiz-component.xml
> 1431 (main) [ ComponentContainer.java:217:INFO ] Loading component : [widget]
> 1438 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0060s: file:/usr/opentaps/framework/appserver/ofbiz-component.xml
> 1439 (main) [ ComponentContainer.java:217:INFO ] Loading component : [appservers]
> 1474 (main) [ UtilXml.java:242:DEBUG] XML Read 0.034s: file:/usr/opentaps/framework/workflow/ofbiz-component.xml
> 1476 (main) [ ComponentContainer.java:217:INFO ] Loading component : [workflow]
> 1484 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0070s: file:/usr/opentaps/framework/shark/ofbiz-component.xml
> 1490 (main) [ ComponentContainer.java:217:INFO ] Loading component : [shark]
> 1494 (main) [ ComponentContainer.java:258:WARN ] Location '/usr/opentaps/framework/shark/build/lib' does not exist
> 1507 (main) [ UtilXml.java:242:DEBUG] XML Read 0.012s: file:/usr/opentaps/framework/webtools/ofbiz-component.xml
> 1508 (main) [ ComponentContainer.java:217:INFO ] Loading component : [webtools]
> 1518 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0080s: file:/usr/opentaps/framework/images/ofbiz-component.xml
> 1519 (main) [ ComponentContainer.java:217:INFO ] Loading component : [images]
> 1531 (main) [ UtilXml.java:242:DEBUG] XML Read 0.012s: file:/usr/opentaps/framework/example/ofbiz-component.xml
> 1538 (main) [ ComponentContainer.java:217:INFO ] Loading component : [example]
> 1539 (main) [ ComponentContainer.java:258:WARN ] Location '/usr/opentaps/framework/example/build/lib' does not exist
> 1539 (main) [ ComponentContainer.java:155:INFO ] Auto-Loading component directory : [/usr/opentaps/applications]
> 1545 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0060s: file:/usr/opentaps/applications/component-load.xml
> 1553 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0070s: file:/usr/opentaps/applications/party/ofbiz-component.xml
> 1554 (main) [ ComponentContainer.java:217:INFO ] Loading component : [party]
> 1565 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0090s: file:/usr/opentaps/applications/securityext/ofbiz-component.xml
> 1566 (main) [ ComponentContainer.java:217:INFO ] Loading component : [securityext]
> 1576 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0090s: file:/usr/opentaps/applications/content/ofbiz-component.xml
> 1577 (main) [ ComponentContainer.java:217:INFO ] Loading component : [content]
> 1590 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0080s: file:/usr/opentaps/applications/workeffort/ofbiz-component.xml
> 1592 (main) [ ComponentContainer.java:217:INFO ] Loading component : [workeffort]
> 1601 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0070s: file:/usr/opentaps/applications/humanres/ofbiz-component.xml
> 1606 (main) [ ComponentContainer.java:217:INFO ] Loading component : [humanres]
> 1610 (main) [ ComponentContainer.java:258:WARN ] Location '/usr/opentaps/applications/humanres/build/lib' does not exist
> 1630 (main) [ UtilXml.java:242:DEBUG] XML Read 0.016s: file:/usr/opentaps/applications/product/ofbiz-component.xml
> 1635 (main) [ ComponentContainer.java:217:INFO ] Loading component : [product]
> 1654 (main) [ UtilXml.java:242:DEBUG] XML Read 0.019s: file:/usr/opentaps/applications/manufacturing/ofbiz-component.xml
> 1655 (main) [ ComponentContainer.java:217:INFO ] Loading component : [manufacturing]
> 1665 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0090s: file:/usr/opentaps/applications/accounting/ofbiz-component.xml
> 1668 (main) [ ComponentContainer.java:217:INFO ] Loading component : [accounting]
> 1678 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0090s: file:/usr/opentaps/applications/order/ofbiz-component.xml
> 1679 (main) [ ComponentContainer.java:217:INFO ] Loading component : [order]
> 1689 (main) [ UtilXml.java:242:DEBUG] XML Read 0.01s: file:/usr/opentaps/applications/marketing/ofbiz-component.xml
> 1690 (main) [ ComponentContainer.java:217:INFO ] Loading component : [marketing]
> 1706 (main) [ UtilXml.java:242:DEBUG] XML Read 0.014s: file:/usr/opentaps/applications/ecommerce/ofbiz-component.xml
> 1714 (main) [ ComponentContainer.java:217:INFO ] Loading component : [ecommerce]
> 1730 (main) [ UtilXml.java:242:DEBUG] XML Read 0.014s: file:/usr/opentaps/applications/pos/ofbiz-component.xml
> 1738 (main) [ ComponentContainer.java:217:INFO ] Loading component : [pos]
> 1739 (main) [ ComponentContainer.java:155:INFO ] Auto-Loading component directory : [/usr/opentaps/specialized]
> 1747 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0080s: file:/usr/opentaps/specialized/component-load.xml
> 1747 (main) [ ComponentContainer.java:155:INFO ] Auto-Loading component directory : [/usr/opentaps/hot-deploy]
> 1792 (main) [ UtilXml.java:242:DEBUG] XML Read 0.044s: file:/usr/opentaps/hot-deploy/crmsfa/ofbiz-component.xml
> 1794 (main) [ ComponentContainer.java:217:INFO ] Loading component : [crmsfa]
> 1809 (main) [ UtilXml.java:242:DEBUG] XML Read 0.014s: file:/usr/opentaps/hot-deploy/financials/ofbiz-component.xml
> 1810 (main) [ ComponentContainer.java:217:INFO ] Loading component : [financials]
> 1840 (main) [ UtilXml.java:242:DEBUG] XML Read 0.029s: file:/usr/opentaps/resources/ofbiz-component.xml
> 1840 (main) [ ComponentContainer.java:217:INFO ] Loading component : [resources]
> 1843 (main) [ ComponentContainer.java:130:INFO ] All components loaded
> 1875 (main) [ClassLoaderContainer.java:59 :INFO ] CachedClassLoader created
> 4058 (main) [ Jotm.java:118:INFO ] JOTM started with a local transaction factory which is not bound.
> 4058 (main) [ Jotm.java:129:INFO ] CAROL initialization
> 4188 (main) [ Current.java:171:INFO ] Jotm version = 2.0.9
> 4263 (main) [ JotmContainer.java:115:INFO ] JOTM is bound to JNDI - java:comp/UserTransaction
> 4276 (main) [ CacheLineTable.java:71 :INFO ] Creating file system cache store for cache with name: printer.applet.settings
> 4405 (main) [ GenericDelegator.java:124:INFO ] Creating new delegator [default] (main)
> 4457 (main) [ UtilXml.java:242:DEBUG] XML Read 0.037s: file:/usr/opentaps/framework/entity/config/entityengine.xml
> 4489 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0080s: /usr/opentaps/framework/entity/entitydef/entitymodel.xml
> 4514 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0090s: /usr/opentaps/framework/entity/entitydef/entitymodel_test.xml
> 4553 (main) [ UtilXml.java:242:DEBUG] XML Read 0.024s: /usr/opentaps/framework/catalina/entitydef/entitymodel.xml
> 4565 (main) [ UtilXml.java:242:DEBUG] XML Read 0.011s: /usr/opentaps/framework/security/entitydef/entitymodel.xml
> 4593 (main) [ UtilXml.java:242:DEBUG] XML Read 0.019s: /usr/opentaps/framework/common/entitydef/entitymodel.xml
> 4619 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0090s: /usr/opentaps/framework/service/entitydef/entitymodel.xml
> 4636 (main) [ UtilXml.java:242:DEBUG] XML Read 0.01s: /usr/opentaps/framework/entityext/entitydef/entitymodel.xml
> 4687 (main) [ UtilXml.java:242:DEBUG] XML Read 0.047s: /usr/opentaps/framework/webapp/entitydef/entitymodel.xml
> 4721 (main) [ UtilXml.java:242:DEBUG] XML Read 0.017s: /usr/opentaps/framework/workflow/entitydef/entitymodel.xml
> 4766 (main) [ UtilXml.java:242:DEBUG] XML Read 0.028s: /usr/opentaps/framework/shark/entitydef/entitymodel.xml
> 4787 (main) [ UtilXml.java:242:DEBUG] XML Read 0.015s: /usr/opentaps/framework/example/entitydef/entitymodel.xml
> 4814 (main) [ UtilXml.java:242:DEBUG] XML Read 0.025s: /usr/opentaps/applications/party/entitydef/entitymodel.xml
> 4855 (main) [ UtilXml.java:242:DEBUG] XML Read 0.02s: /usr/opentaps/applications/content/entitydef/entitymodel.xml
> 4928 (main) [ UtilXml.java:242:DEBUG] XML Read 0.061s: /usr/opentaps/applications/workeffort/entitydef/entitymodel.xml
> 4977 (main) [ UtilXml.java:242:DEBUG] XML Read 0.012s: /usr/opentaps/applications/workeffort/entitydef/entitymodel_view.xml
> 5003 (main) [ UtilXml.java:242:DEBUG] XML Read 0.022s: /usr/opentaps/applications/humanres/entitydef/entitymodel.xml
> 5043 (main) [ UtilXml.java:242:DEBUG] XML Read 0.035s: /usr/opentaps/applications/product/entitydef/entitymodel.xml
> 5080 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0080s: /usr/opentaps/applications/product/entitydef/entitymodel_odbc.xml
> 5122 (main) [ UtilXml.java:242:DEBUG] XML Read 0.039s: /usr/opentaps/applications/product/entitydef/entitymodel_shipment.xml
> 5145 (main) [ UtilXml.java:242:DEBUG] XML Read 0.013s: /usr/opentaps/applications/manufacturing/entitydef/entitymodel.xml
> 5172 (main) [ UtilXml.java:242:DEBUG] XML Read 0.026s: /usr/opentaps/applications/accounting/entitydef/entitymodel.xml
> 5201 (main) [ UtilXml.java:242:DEBUG] XML Read 0.01s: /usr/opentaps/applications/accounting/entitydef/entitymodel_old.xml
> 5229 (main) [ UtilXml.java:242:DEBUG] XML Read 0.027s: /usr/opentaps/applications/order/entitydef/entitymodel.xml
> 5276 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0070s: /usr/opentaps/applications/order/entitydef/entitymodel_old.xml
> 5299 (main) [ UtilXml.java:242:DEBUG] XML Read 0.022s: /usr/opentaps/applications/order/entitydef/entitymodel_view.xml
> 5322 (main) [ UtilXml.java:242:DEBUG] XML Read 0.011s: /usr/opentaps/applications/marketing/entitydef/entitymodel.xml
> 5332 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0060s: /usr/opentaps/applications/pos/entitydef/entitymodel.xml
> 5340 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0070s: /usr/opentaps/hot-deploy/crmsfa/entitydef/entitymodel.xml
> 5348 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0060s: /usr/opentaps/hot-deploy/financials/entitydef/entitymodel.xml
> 5620 (main) [ ModelReader.java:382:INFO ] FINISHED LOADING ENTITIES - ALL FILES; #Entities=735 #ViewEntities=144 #Fields=7412 #Relationships=2401 #AutoRelationships=1766
> 5626 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0030s: /usr/opentaps/framework/entity/entitydef/entitygroup.xml
> 5629 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0030s: /usr/opentaps/framework/entity/entitydef/entitygroup_test.xml
> 5632 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0020s: /usr/opentaps/framework/catalina/entitydef/entitygroup.xml
> 5635 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0030s: /usr/opentaps/framework/security/entitydef/entitygroup.xml
> 5638 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0020s: /usr/opentaps/framework/common/entitydef/entitygroup.xml
> 5641 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0020s: /usr/opentaps/framework/service/entitydef/entitygroup.xml
> 5646 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0040s: /usr/opentaps/framework/entityext/entitydef/entitygroup.xml
> 5663 (main) [ UtilXml.java:242:DEBUG] XML Read 0.016s: /usr/opentaps/framework/webapp/entitydef/entitygroup.xml
> 5666 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0030s: /usr/opentaps/framework/workflow/entitydef/entitygroup.xml
> 5669 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0030s: /usr/opentaps/framework/shark/entitydef/entitygroup.xml
> 5672 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0020s: /usr/opentaps/framework/example/entitydef/entitygroup.xml
> 5676 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0030s: /usr/opentaps/applications/party/entitydef/entitygroup.xml
> 5680 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0030s: /usr/opentaps/applications/content/entitydef/entitygroup.xml
> 5685 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0040s: /usr/opentaps/applications/workeffort/entitydef/entitygroup.xml
> 5690 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0050s: /usr/opentaps/applications/humanres/entitydef/entitygroup.xml
> 5696 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0050s: /usr/opentaps/applications/product/entitydef/entitygroup.xml
> 5702 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0050s: /usr/opentaps/applications/manufacturing/entitydef/entitygroup.xml
> 5706 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0040s: /usr/opentaps/applications/accounting/entitydef/entitygroup.xml
> 5714 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0040s: /usr/opentaps/applications/order/entitydef/entitygroup.xml
> 5721 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0050s: /usr/opentaps/applications/marketing/entitydef/entitygroup.xml
> 5724 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0030s: /usr/opentaps/applications/pos/entitydef/entitygroup.xml
> 5727 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0020s: /usr/opentaps/hot-deploy/crmsfa/entitydef/entitygroup.xml
> 5730 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0020s: /usr/opentaps/hot-deploy/financials/entitydef/entitygroup.xml
> 5740 (main) [ GenericDelegator.java:159:INFO ] Doing entity definition check...
> 5749 (main) [ ModelEntityChecker.java:514:INFO ] [initReservedWords] array length=1023
> 5769 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0030s: entityengine.xml
> 5832 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0030s: entityengine.xml
> 5912 (main) [ GenericDelegator.java:176:INFO ] Delegator "default" initializing helper "localhsql" for entity group "org.ofbiz".
> 5918 (main) [ GenericDelegator.java:193:INFO ] Doing database check as requested in entityengine.xml with addMissing=true
> 5988 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0040s: file:/usr/opentaps/base/config/jndiservers.xml
> 8525 (main) [ DatabaseUtil.java:765:INFO ] Database Product Name is HSQL Database Engine
> 8526 (main) [ DatabaseUtil.java:766:INFO ] Database Product Version is 1.7.2
> 8526 (main) [ DatabaseUtil.java:774:INFO ] Database Driver Name is HSQL Database Engine Driver
> 8526 (main) [ DatabaseUtil.java:775:INFO ] Database Driver Version is 1.7.2
> 8527 (main) [ DatabaseUtil.java:776:INFO ] Database Driver JDBC Version is 3.0
> 8527 (main) [ DatabaseUtil.java:786:INFO ] Database Setting/Support Information (those with a * should be true):
> 8527 (main) [ DatabaseUtil.java:787:INFO ] - supports transactions [true]*
> 8528 (main) [ DatabaseUtil.java:788:INFO ] - isolation None [false]
> 8528 (main) [ DatabaseUtil.java:789:INFO ] - isolation ReadCommitted [false]
> 8528 (main) [ DatabaseUtil.java:790:INFO ] - isolation ReadUncommitted[true]
> 8528 (main) [ DatabaseUtil.java:791:INFO ] - isolation RepeatableRead [false]
> 8529 (main) [ DatabaseUtil.java:792:INFO ] - isolation Serializable [false]
> 8531 (main) [ DatabaseUtil.java:793:INFO ] - default fetchsize [0]
> 8532 (main) [ DatabaseUtil.java:794:INFO ] - forward only type [true]
> 8532 (main) [ DatabaseUtil.java:795:INFO ] - scroll sensitive type [false]
> 8532 (main) [ DatabaseUtil.java:796:INFO ] - scroll insensitive type [true]
> 8532 (main) [ DatabaseUtil.java:797:INFO ] - is case sensitive [false]
> 8533 (main) [ DatabaseUtil.java:798:INFO ] - stores LowerCase [false]
> 8533 (main) [ DatabaseUtil.java:799:INFO ] - stores MixedCase [false]
> 8533 (main) [ DatabaseUtil.java:800:INFO ] - stores UpperCase [true]
> 8533 (main) [ DatabaseUtil.java:801:INFO ] - max table name length [0]
> 8534 (main) [ DatabaseUtil.java:802:INFO ] - max column name length [0]
> 8534 (main) [ DatabaseUtil.java:803:INFO ] - max schema name length [0]
> 8534 (main) [ DatabaseUtil.java:804:INFO ] - concurrent connections [0]
> 8535 (main) [ DatabaseUtil.java:805:INFO ] - concurrent statements [0]
> 8536 (main) [ DatabaseUtil.java:806:INFO ] - ANSI SQL92 Entry [false]
> 8536 (main) [ DatabaseUtil.java:807:INFO ] - ANSI SQL92 Itermediate [false]
> 8536 (main) [ DatabaseUtil.java:808:INFO ] - ANSI SQL92 Full [false]
> 8536 (main) [ DatabaseUtil.java:809:INFO ] - ODBC SQL Grammar Core [true]
> 8537 (main) [ DatabaseUtil.java:810:INFO ] - ODBC SQL Grammar Extended[false]
> 8537 (main) [ DatabaseUtil.java:811:INFO ] - ODBC SQL Grammar Minimum [false]
> 8537 (main) [ DatabaseUtil.java:812:INFO ] - outer joins [true]*
> 8537 (main) [ DatabaseUtil.java:813:INFO ] - limited outer joins [true]
> 8538 (main) [ DatabaseUtil.java:814:INFO ] - full outer joins [false]
> 8538 (main) [ DatabaseUtil.java:815:INFO ] - group by [true]*
> 8538 (main) [ DatabaseUtil.java:816:INFO ] - group by not in select [true]
> 8539 (main) [ DatabaseUtil.java:817:INFO ] - column aliasing [true]
> 8539 (main) [ DatabaseUtil.java:818:INFO ] - order by not in select [true]
> 8539 (main) [ DatabaseUtil.java:820:INFO ] - alter table add column [true]*
> 8539 (main) [ DatabaseUtil.java:821:INFO ] - non-nullable column [true]*
> 8540 (main) [ DatabaseUtil.java:858:INFO ] Getting Table Info From Database
> 8642 (main) [ DatabaseUtil.java:993:INFO ] Getting Column Info From Database
> 8863 (main) [ GenericDelegator.java:176:INFO ] Delegator "default" initializing helper "localderbyodbc" for entity group "org.ofbiz.odbc".
> 8864 (main) [ GenericDelegator.java:193:INFO ] Doing database check as requested in entityengine.xml with addMissing=true
> 10177 (main) [ DatabaseUtil.java:765:INFO ] Database Product Name is Apache Derby
> 10178 (main) [ DatabaseUtil.java:766:INFO ] Database Product Version is 10.1.2.1
> 10178 (main) [ DatabaseUtil.java:774:INFO ] Database Driver Name is Apache Derby Embedded JDBC Driver
> 10179 (main) [ DatabaseUtil.java:775:INFO ] Database Driver Version is 10.1.2.1
> 10179 (main) [ DatabaseUtil.java:776:INFO ] Database Driver JDBC Version is 3.0
> 10179 (main) [ DatabaseUtil.java:786:INFO ] Database Setting/Support Information (those with a * should be true):
> 10179 (main) [ DatabaseUtil.java:787:INFO ] - supports transactions [true]*
> 10180 (main) [ DatabaseUtil.java:788:INFO ] - isolation None [false]
> 10180 (main) [ DatabaseUtil.java:789:INFO ] - isolation ReadCommitted [true]
> 10180 (main) [ DatabaseUtil.java:790:INFO ] - isolation ReadUncommitted[true]
> 10180 (main) [ DatabaseUtil.java:791:INFO ] - isolation RepeatableRead [true]
> 10181 (main) [ DatabaseUtil.java:792:INFO ] - isolation Serializable [true]
> 10185 (main) [ DatabaseUtil.java:793:INFO ] - default fetchsize [1]
> 10185 (main) [ DatabaseUtil.java:794:INFO ] - forward only type [true]
> 10185 (main) [ DatabaseUtil.java:795:INFO ] - scroll sensitive type [false]
> 10185 (main) [ DatabaseUtil.java:796:INFO ] - scroll insensitive type [true]
> 10186 (main) [ DatabaseUtil.java:797:INFO ] - is case sensitive [false]
> 10186 (main) [ DatabaseUtil.java:798:INFO ] - stores LowerCase [false]
> 10186 (main) [ DatabaseUtil.java:799:INFO ] - stores MixedCase [false]
> 10186 (main) [ DatabaseUtil.java:800:INFO ] - stores UpperCase [true]
> 10187 (main) [ DatabaseUtil.java:801:INFO ] - max table name length [128]
> 10187 (main) [ DatabaseUtil.java:802:INFO ] - max column name length [128]
> 10187 (main) [ DatabaseUtil.java:803:INFO ] - max schema name length [128]
> 10187 (main) [ DatabaseUtil.java:804:INFO ] - concurrent connections [0]
> 10188 (main) [ DatabaseUtil.java:805:INFO ] - concurrent statements [0]
> 10188 (main) [ DatabaseUtil.java:806:INFO ] - ANSI SQL92 Entry [false]
> 10188 (main) [ DatabaseUtil.java:807:INFO ] - ANSI SQL92 Itermediate [false]
> 10188 (main) [ DatabaseUtil.java:808:INFO ] - ANSI SQL92 Full [false]
> 10189 (main) [ DatabaseUtil.java:809:INFO ] - ODBC SQL Grammar Core [false]
> 10189 (main) [ DatabaseUtil.java:810:INFO ] - ODBC SQL Grammar Extended[false]
> 10189 (main) [ DatabaseUtil.java:811:INFO ] - ODBC SQL Grammar Minimum [true]
> 10189 (main) [ DatabaseUtil.java:812:INFO ] - outer joins [true]*
> 10190 (main) [ DatabaseUtil.java:813:INFO ] - limited outer joins [true]
> 10190 (main) [ DatabaseUtil.java:814:INFO ] - full outer joins [false]
> 10190 (main) [ DatabaseUtil.java:815:INFO ] - group by [true]*
> 10190 (main) [ DatabaseUtil.java:816:INFO ] - group by not in select [true]
> 10191 (main) [ DatabaseUtil.java:817:INFO ] - column aliasing [true]
> 10191 (main) [ DatabaseUtil.java:818:INFO ] - order by not in select [false]
> 10191 (main) [ DatabaseUtil.java:820:INFO ] - alter table add column [true]*
> 10191 (main) [ DatabaseUtil.java:821:INFO ] - non-nullable column [true]*
> 10192 (main) [ DatabaseUtil.java:858:INFO ] Getting Table Info From Database
> 10919 (main) [ DatabaseUtil.java:993:INFO ] Getting Column Info From Database
> 11233 (main) [ GenericDelegator.java:234:INFO ] Distributed Cache Clear System disabled for delegator [default]
> 11264 (main) [ ServiceDispatcher.java:89 :INFO ] [ServiceDispatcher] : Creating new instance.
> 11276 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0050s: file:/usr/opentaps/framework/service/config/serviceengine.xml
> 11280 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0030s: /usr/opentaps/framework/common/servicedef/groups_test.xml
> 11285 (main) [ ServiceGroupReader.java:105:INFO ] Loaded 1 Group definitions from file:/usr/opentaps/framework/common/servicedef/groups_test.xml
> 11288 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0030s: /usr/opentaps/framework/entityext/servicedef/groups.xml
> 11290 (main) [ ServiceGroupReader.java:105:INFO ] Loaded 4 Group definitions from file:/usr/opentaps/framework/entityext/servicedef/groups.xml
> 11293 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0030s: /usr/opentaps/applications/workeffort/servicedef/service_groups.xml
> 11294 (main) [ ServiceGroupReader.java:105:INFO ] Loaded 3 Group definitions from file:/usr/opentaps/applications/workeffort/servicedef/service_groups.xml
> 11297 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0030s: /usr/opentaps/applications/product/servicedef/groups_shipment.xml
> 11298 (main) [ ServiceGroupReader.java:105:INFO ] Loaded 1 Group definitions from file:/usr/opentaps/applications/product/servicedef/groups_shipment.xml
> 11301 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0020s: /usr/opentaps/applications/accounting/servicedef/groups.xml
> 11302 (main) [ ServiceGroupReader.java:105:INFO ] Loaded 4 Group definitions from file:/usr/opentaps/applications/accounting/servicedef/groups.xml
> 11309 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0040s: /usr/opentaps/framework/common/servicedef/secas_test.xml
> 11317 (main) [ ServiceEcaUtil.java:135:INFO ] Loaded 4 Service ECA definitions from file:/usr/opentaps/framework/common/servicedef/secas_test.xml
> 11331 (main) [ UtilXml.java:242:DEBUG] XML Read 0.012s: /usr/opentaps/applications/party/servicedef/secas.xml
> 11332 (main) [ ServiceEcaUtil.java:135:INFO ] Loaded 15 Service ECA definitions from file:/usr/opentaps/applications/party/servicedef/secas.xml
> 11335 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0030s: /usr/opentaps/applications/content/servicedef/secas.xml
> 11336 (main) [ ServiceEcaUtil.java:135:INFO ] Loaded 3 Service ECA definitions from file:/usr/opentaps/applications/content/servicedef/secas.xml
> 11341 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0050s: /usr/opentaps/applications/workeffort/servicedef/secas.xml
> 11342 (main) [ ServiceEcaUtil.java:135:INFO ] Loaded 11 Service ECA definitions from file:/usr/opentaps/applications/workeffort/servicedef/secas.xml
> 11347 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0040s: /usr/opentaps/applications/product/servicedef/secas.xml
> 11348 (main) [ ServiceEcaUtil.java:135:INFO ] Loaded 15 Service ECA definitions from file:/usr/opentaps/applications/product/servicedef/secas.xml
> 11352 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0040s: /usr/opentaps/applications/product/servicedef/secas_shipment.xml
> 11353 (main) [ ServiceEcaUtil.java:135:INFO ] Loaded 15 Service ECA definitions from file:/usr/opentaps/applications/product/servicedef/secas_shipment.xml
> 11358 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0040s: /usr/opentaps/applications/manufacturing/servicedef/secas.xml
> 11359 (main) [ ServiceEcaUtil.java:135:INFO ] Loaded 4 Service ECA definitions from file:/usr/opentaps/applications/manufacturing/servicedef/secas.xml
> 11363 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0040s: /usr/opentaps/applications/accounting/servicedef/secas.xml
> 11364 (main) [ ServiceEcaUtil.java:135:INFO ] Loaded 14 Service ECA definitions from file:/usr/opentaps/applications/accounting/servicedef/secas.xml
> 11368 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0040s: /usr/opentaps/applications/accounting/servicedef/secas_payment.xml
> 11368 (main) [ ServiceEcaUtil.java:135:INFO ] Loaded 2 Service ECA definitions from file:/usr/opentaps/applications/accounting/servicedef/secas_payment.xml
> 11374 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0050s: /usr/opentaps/applications/order/servicedef/secas.xml
> 11376 (main) [ ServiceEcaUtil.java:135:INFO ] Loaded 44 Service ECA definitions from file:/usr/opentaps/applications/order/servicedef/secas.xml
> 11380 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0040s: /usr/opentaps/applications/marketing/servicedef/secas.xml
> 11381 (main) [ ServiceEcaUtil.java:135:INFO ] Loaded 1 Service ECA definitions from file:/usr/opentaps/applications/marketing/servicedef/secas.xml
> 11388 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0070s: /usr/opentaps/hot-deploy/crmsfa/servicedef/secas_contacts.xml
> 11389 (main) [ ServiceEcaUtil.java:135:INFO ] Loaded 1 Service ECA definitions from file:/usr/opentaps/hot-deploy/crmsfa/servicedef/secas_contacts.xml
> 11392 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0030s: /usr/opentaps/hot-deploy/crmsfa/servicedef/secas_activities.xml
> 11393 (main) [ ServiceEcaUtil.java:135:INFO ] Loaded 1 Service ECA definitions from file:/usr/opentaps/hot-deploy/crmsfa/servicedef/secas_activities.xml
> 11397 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0040s: /usr/opentaps/hot-deploy/financials/servicedef/secas_ledger.xml
> 11399 (main) [ ServiceEcaUtil.java:135:INFO ] Loaded 12 Service ECA definitions from file:/usr/opentaps/hot-deploy/financials/servicedef/secas_ledger.xml
> 11412 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0090s: file:/usr/opentaps/framework/security/config/security.xml
> 11446 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0040s: /usr/opentaps/applications/product/entitydef/eecas.xml
> 11455 (main) [ EntityEcaUtil.java:143:INFO ] Loaded 7 Entity ECA definitions from entitydef/eecas.xml in loader main
> 11462 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0060s: /usr/opentaps/applications/order/entitydef/eecas.xml
> 11463 (main) [ EntityEcaUtil.java:143:INFO ] Loaded 4 Entity ECA definitions from entitydef/eecas.xml in loader main
> 11467 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0040s: /usr/opentaps/hot-deploy/financials/entitydef/eecas.xml
> 11467 (main) [ EntityEcaUtil.java:143:INFO ] Loaded 2 Entity ECA definitions from entitydef/eecas.xml in loader main
> 11483 (main) [ JobManager.java:257:INFO ] No crashed jobs to re-schedule
> 11746 (org.ofbiz.service.job.JobPoller@1b104d7) [ JobPoller.java:87 :INFO ] JobPoller: (org.ofbiz.service.job.JobPoller@1b104d7) Thread Running...
> 11748 (main) [ ServiceDispatcher.java:172:INFO ] Registered dispatcher: entity-default
> 11761 (org.ofbiz.service.jms.JmsListenerFactory@1fc468e) [ JmsListenerFactory.java:72 :INFO ] Starting JMS Listener Factory Thread
> 11762 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0080s: /usr/opentaps/framework/common/servicedef/services.xml
> 11791 (main) [ ModelServiceReader.java:244:INFO ] Loaded 30 Service definitions from file:/usr/opentaps/framework/common/servicedef/services.xml
> 11799 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0080s: /usr/opentaps/framework/common/servicedef/services_test.xml
> 11801 (main) [ ModelServiceReader.java:244:INFO ] Loaded 26 Service definitions from file:/usr/opentaps/framework/common/servicedef/services_test.xml
> 11809 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0080s: /usr/opentaps/framework/service/servicedef/services.xml
> 11810 (main) [ ModelServiceReader.java:244:INFO ] Loaded 6 Service definitions from file:/usr/opentaps/framework/service/servicedef/services.xml
> 11818 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0070s: /usr/opentaps/framework/entityext/servicedef/services.xml
> 11821 (main) [ ModelServiceReader.java:244:INFO ] Loaded 36 Service definitions from file:/usr/opentaps/framework/entityext/servicedef/services.xml
> 11827 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0050s: /usr/opentaps/framework/workflow/servicedef/services.xml
> 11830 (main) [ ModelServiceReader.java:244:INFO ] Loaded 19 Service definitions from file:/usr/opentaps/framework/workflow/servicedef/services.xml
> 11845 (main) [ UtilXml.java:242:DEBUG] XML Read 0.015s: /usr/opentaps/framework/shark/servicedef/services_test.xml
> 11845 (main) [ ModelServiceReader.java:244:INFO ] Loaded 1 Service definitions from file:/usr/opentaps/framework/shark/servicedef/services_test.xml
> 11857 (main) [ UtilXml.java:242:DEBUG] XML Read 0.011s: /usr/opentaps/framework/webtools/servicedef/services.xml
> 11858 (main) [ ModelServiceReader.java:244:INFO ] Loaded 4 Service definitions from file:/usr/opentaps/framework/webtools/servicedef/services.xml
> 11865 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0060s: /usr/opentaps/framework/example/servicedef/services.xml
> 11867 (main) [ ModelServiceReader.java:244:INFO ] Loaded 13 Service definitions from file:/usr/opentaps/framework/example/servicedef/services.xml
> 11888 (main) [ UtilXml.java:242:DEBUG] XML Read 0.021s: /usr/opentaps/applications/party/servicedef/services.xml
> 11905 (main) [ ModelServiceReader.java:244:INFO ] Loaded 81 Service definitions from file:/usr/opentaps/applications/party/servicedef/services.xml
> 11911 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0060s: /usr/opentaps/applications/party/servicedef/services_view.xml
> 11913 (main) [ ModelServiceReader.java:244:INFO ] Loaded 12 Service definitions from file:/usr/opentaps/applications/party/servicedef/services_view.xml
> 11919 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0060s: /usr/opentaps/applications/securityext/servicedef/services.xml
> 11920 (main) [ ModelServiceReader.java:244:INFO ] Loaded 14 Service definitions from file:/usr/opentaps/applications/securityext/servicedef/services.xml
> 11940 (main) [ UtilXml.java:242:DEBUG] XML Read 0.019s: /usr/opentaps/applications/content/servicedef/services.xml
> 11960 (main) [ ModelServiceReader.java:244:INFO ] Loaded 139 Service definitions from file:/usr/opentaps/applications/content/servicedef/services.xml
> 11966 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0060s: /usr/opentaps/applications/content/servicedef/services_contenttypes.xml
> 11969 (main) [ ModelServiceReader.java:244:INFO ] Loaded 32 Service definitions from file:/usr/opentaps/applications/content/servicedef/services_contenttypes.xml
> 11975 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0050s: /usr/opentaps/applications/content/servicedef/services_email.xml
> 11977 (main) [ ModelServiceReader.java:244:INFO ] Loaded 11 Service definitions from file:/usr/opentaps/applications/content/servicedef/services_email.xml
> 12000 (main) [ UtilXml.java:242:DEBUG] XML Read 0.022s: /usr/opentaps/applications/content/servicedef/services_survey.xml
> 12006 (main) [ ModelServiceReader.java:244:INFO ] Loaded 42 Service definitions from file:/usr/opentaps/applications/content/servicedef/services_survey.xml
> 12011 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0050s: /usr/opentaps/applications/content/servicedef/services_commevent.xml
> 12012 (main) [ ModelServiceReader.java:244:INFO ] Loaded 5 Service definitions from file:/usr/opentaps/applications/content/servicedef/services_commevent.xml
> 12024 (main) [ UtilXml.java:242:DEBUG] XML Read 0.011s: /usr/opentaps/applications/workeffort/servicedef/services.xml
> 12035 (main) [ ModelServiceReader.java:244:INFO ] Loaded 46 Service definitions from file:/usr/opentaps/applications/workeffort/servicedef/services.xml
> 12041 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0060s: /usr/opentaps/applications/workeffort/servicedef/services_timesheet.xml
> 12044 (main) [ ModelServiceReader.java:244:INFO ] Loaded 21 Service definitions from file:/usr/opentaps/applications/workeffort/servicedef/services_timesheet.xml
> 12061 (main) [ UtilXml.java:242:DEBUG] XML Read 0.017s: /usr/opentaps/applications/humanres/servicedef/services.xml
> 12061 (main) [ ModelServiceReader.java:244:INFO ] Loaded 1 Service definitions from file:/usr/opentaps/applications/humanres/servicedef/services.xml
> 12075 (main) [ UtilXml.java:242:DEBUG] XML Read 0.013s: /usr/opentaps/applications/product/servicedef/services.xml
> 12086 (main) [ ModelServiceReader.java:244:INFO ] Loaded 111 Service definitions from file:/usr/opentaps/applications/product/servicedef/services.xml
> 12104 (main) [ UtilXml.java:242:DEBUG] XML Read 0.011s: /usr/opentaps/applications/product/servicedef/services_facility.xml
> 12112 (main) [ ModelServiceReader.java:244:INFO ] Loaded 74 Service definitions from file:/usr/opentaps/applications/product/servicedef/services_facility.xml
> 12135 (main) [ UtilXml.java:242:DEBUG] XML Read 0.022s: /usr/opentaps/applications/product/servicedef/services_feature.xml
> 12138 (main) [ ModelServiceReader.java:244:INFO ] Loaded 22 Service definitions from file:/usr/opentaps/applications/product/servicedef/services_feature.xml
> 12144 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0050s: /usr/opentaps/applications/product/servicedef/services_maint.xml
> 12147 (main) [ ModelServiceReader.java:244:INFO ] Loaded 16 Service definitions from file:/usr/opentaps/applications/product/servicedef/services_maint.xml
> 12153 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0060s: /usr/opentaps/applications/product/servicedef/services_picklist.xml
> 12155 (main) [ ModelServiceReader.java:244:INFO ] Loaded 17 Service definitions from file:/usr/opentaps/applications/product/servicedef/services_picklist.xml
> 12162 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0070s: /usr/opentaps/applications/product/servicedef/services_pricepromo.xml
> 12166 (main) [ ModelServiceReader.java:244:INFO ] Loaded 37 Service definitions from file:/usr/opentaps/applications/product/servicedef/services_pricepromo.xml
> 12171 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0040s: /usr/opentaps/applications/product/servicedef/services_shipment_dhl.xml
> 12172 (main) [ ModelServiceReader.java:244:INFO ] Loaded 3 Service definitions from file:/usr/opentaps/applications/product/servicedef/services_shipment_dhl.xml
> 12177 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0040s: /usr/opentaps/applications/product/servicedef/services_shipment_ups.xml
> 12178 (main) [ ModelServiceReader.java:244:INFO ] Loaded 5 Service definitions from file:/usr/opentaps/applications/product/servicedef/services_shipment_ups.xml
> 12183 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0040s: /usr/opentaps/applications/product/servicedef/services_shipment_usps.xml
> 12185 (main) [ ModelServiceReader.java:244:INFO ] Loaded 9 Service definitions from file:/usr/opentaps/applications/product/servicedef/services_shipment_usps.xml
> 12196 (main) [ UtilXml.java:242:DEBUG] XML Read 0.011s: /usr/opentaps/applications/product/servicedef/services_shipment.xml
> 12201 (main) [ ModelServiceReader.java:244:INFO ] Loaded 66 Service definitions from file:/usr/opentaps/applications/product/servicedef/services_shipment.xml
> 12212 (main) [ UtilXml.java:242:DEBUG] XML Read 0.01s: /usr/opentaps/applications/product/servicedef/services_store.xml
> 12215 (main) [ ModelServiceReader.java:244:INFO ] Loaded 28 Service definitions from file:/usr/opentaps/applications/product/servicedef/services_store.xml
> 12235 (main) [ UtilXml.java:242:DEBUG] XML Read 0.02s: /usr/opentaps/applications/product/servicedef/services_view.xml
> 12236 (main) [ ModelServiceReader.java:244:INFO ] Loaded 12 Service definitions from file:/usr/opentaps/applications/product/servicedef/services_view.xml
> 12252 (main) [ UtilXml.java:242:DEBUG] XML Read 0.015s: /usr/opentaps/applications/product/servicedef/services_cost.xml
> 12253 (main) [ ModelServiceReader.java:244:INFO ] Loaded 9 Service definitions from file:/usr/opentaps/applications/product/servicedef/services_cost.xml
> 12258 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0050s: /usr/opentaps/applications/manufacturing/servicedef/services.xml
> 12258 (main) [ ModelServiceReader.java:244:INFO ] Loaded 2 Service definitions from file:/usr/opentaps/applications/manufacturing/servicedef/services.xml
> 12267 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0080s: /usr/opentaps/applications/manufacturing/servicedef/services_calendar.xml
> 12269 (main) [ ModelServiceReader.java:244:INFO ] Loaded 12 Service definitions from file:/usr/opentaps/applications/manufacturing/servicedef/services_calendar.xml
> 12274 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0050s: /usr/opentaps/applications/manufacturing/servicedef/services_routing.xml
> 12275 (main) [ ModelServiceReader.java:244:INFO ] Loaded 4 Service definitions from file:/usr/opentaps/applications/manufacturing/servicedef/services_routing.xml
> 12283 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0080s: /usr/opentaps/applications/manufacturing/servicedef/services_production_run.xml
> 12285 (main) [ ModelServiceReader.java:244:INFO ] Loaded 26 Service definitions from file:/usr/opentaps/applications/manufacturing/servicedef/services_production_run.xml
> 12291 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0050s: /usr/opentaps/applications/manufacturing/servicedef/services_bom.xml
> 12293 (main) [ ModelServiceReader.java:244:INFO ] Loaded 13 Service definitions from file:/usr/opentaps/applications/manufacturing/servicedef/services_bom.xml
> 12312 (main) [ UtilXml.java:242:DEBUG] XML Read 0.019s: /usr/opentaps/applications/manufacturing/servicedef/services_mrp.xml
> 12313 (main) [ ModelServiceReader.java:244:INFO ] Loaded 5 Service definitions from file:/usr/opentaps/applications/manufacturing/servicedef/services_mrp.xml
> 12318 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0040s: /usr/opentaps/applications/manufacturing/servicedef/services_formula.xml
> 12319 (main) [ ModelServiceReader.java:244:INFO ] Loaded 5 Service definitions from file:/usr/opentaps/applications/manufacturing/servicedef/services_formula.xml
> 12325 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0050s: /usr/opentaps/applications/accounting/servicedef/services_agreement.xml
> 12326 (main) [ ModelServiceReader.java:244:INFO ] Loaded 12 Service definitions from file:/usr/opentaps/applications/accounting/servicedef/services_agreement.xml
> 12333 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0050s: /usr/opentaps/applications/accounting/servicedef/services_admin.xml
> 12335 (main) [ ModelServiceReader.java:244:INFO ] Loaded 8 Service definitions from file:/usr/opentaps/applications/accounting/servicedef/services_admin.xml
> 12365 (main) [ UtilXml.java:242:DEBUG] XML Read 0.03s: /usr/opentaps/applications/accounting/servicedef/services_billing.xml
> 12367 (main) [ ModelServiceReader.java:244:INFO ] Loaded 9 Service definitions from file:/usr/opentaps/applications/accounting/servicedef/services_billing.xml
> 12373 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0060s: /usr/opentaps/applications/accounting/servicedef/services_finaccount.xml
> 12375 (main) [ ModelServiceReader.java:244:INFO ] Loaded 22 Service definitions from file:/usr/opentaps/applications/accounting/servicedef/services_finaccount.xml
> 12382 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0060s: /usr/opentaps/applications/accounting/servicedef/services_fixedasset.xml
> 12385 (main) [ ModelServiceReader.java:244:INFO ] Loaded 22 Service definitions from file:/usr/opentaps/applications/accounting/servicedef/services_fixedasset.xml
> 12392 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0050s: /usr/opentaps/applications/accounting/servicedef/services_invoice.xml
> 12394 (main) [ ModelServiceReader.java:244:INFO ] Loaded 21 Service definitions from file:/usr/opentaps/applications/accounting/servicedef/services_invoice.xml
> 12401 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0060s: /usr/opentaps/applications/accounting/servicedef/services_ledger.xml
> 12404 (main) [ ModelServiceReader.java:244:INFO ] Loaded 31 Service definitions from file:/usr/opentaps/applications/accounting/servicedef/services_ledger.xml
> 12411 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0060s: /usr/opentaps/applications/accounting/servicedef/services_payment.xml
> 12412 (main) [ ModelServiceReader.java:244:INFO ] Loaded 6 Service definitions from file:/usr/opentaps/applications/accounting/servicedef/services_payment.xml
> 12430 (main) [ UtilXml.java:242:DEBUG] XML Read 0.018s: /usr/opentaps/applications/accounting/servicedef/services_paymentmethod.xml
> 12434 (main) [ ModelServiceReader.java:244:INFO ] Loaded 52 Service definitions from file:/usr/opentaps/applications/accounting/servicedef/services_paymentmethod.xml
> 12441 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0060s: /usr/opentaps/applications/accounting/servicedef/services_tax.xml
> 12443 (main) [ ModelServiceReader.java:244:INFO ] Loaded 27 Service definitions from file:/usr/opentaps/applications/accounting/servicedef/services_tax.xml
> 12448 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0040s: /usr/opentaps/applications/accounting/servicedef/services_cost.xml
> 12449 (main) [ ModelServiceReader.java:244:INFO ] Loaded 5 Service definitions from file:/usr/opentaps/applications/accounting/servicedef/services_cost.xml
> 12464 (main) [ UtilXml.java:242:DEBUG] XML Read 0.015s: /usr/opentaps/applications/accounting/servicedef/services_clearcommerce.xml
> 12465 (main) [ ModelServiceReader.java:244:INFO ] Loaded 5 Service definitions from file:/usr/opentaps/applications/accounting/servicedef/services_clearcommerce.xml
> 12469 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0040s: /usr/opentaps/applications/accounting/servicedef/services_cybersource.xml
> 12470 (main) [ ModelServiceReader.java:244:INFO ] Loaded 5 Service definitions from file:/usr/opentaps/applications/accounting/servicedef/services_cybersource.xml
> 12475 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0040s: /usr/opentaps/applications/accounting/servicedef/services_pccharge.xml
> 12475 (main) [ ModelServiceReader.java:244:INFO ] Loaded 4 Service definitions from file:/usr/opentaps/applications/accounting/servicedef/services_pccharge.xml
> 12482 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0060s: /usr/opentaps/applications/accounting/servicedef/services_rita.xml
> 12483 (main) [ ModelServiceReader.java:244:INFO ] Loaded 10 Service definitions from file:/usr/opentaps/applications/accounting/servicedef/services_rita.xml
> 12491 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0080s: /usr/opentaps/applications/accounting/servicedef/services_valuelink.xml
> 12493 (main) [ ModelServiceReader.java:244:INFO ] Loaded 21 Service definitions from file:/usr/opentaps/applications/accounting/servicedef/services_valuelink.xml
> 12498 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0040s: /usr/opentaps/applications/accounting/servicedef/services_verisign.xml
> 12499 (main) [ ModelServiceReader.java:244:INFO ] Loaded 4 Service definitions from file:/usr/opentaps/applications/accounting/servicedef/services_verisign.xml
> 12508 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0090s: /usr/opentaps/applications/order/servicedef/services.xml
> 12516 (main) [ ModelServiceReader.java:244:INFO ] Loaded 55 Service definitions from file:/usr/opentaps/applications/order/servicedef/services.xml
> 12523 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0070s: /usr/opentaps/applications/order/servicedef/services_cart.xml
> 12527 (main) [ ModelServiceReader.java:244:INFO ] Loaded 8 Service definitions from file:/usr/opentaps/applications/order/servicedef/services_cart.xml
> 12533 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0060s: /usr/opentaps/applications/order/servicedef/services_shoppinglist.xml
> 12536 (main) [ ModelServiceReader.java:244:INFO ] Loaded 13 Service definitions from file:/usr/opentaps/applications/order/servicedef/services_shoppinglist.xml
> 12542 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0060s: /usr/opentaps/applications/order/servicedef/services_request.xml
> 12544 (main) [ ModelServiceReader.java:244:INFO ] Loaded 15 Service definitions from file:/usr/opentaps/applications/order/servicedef/services_request.xml
> 12550 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0060s: /usr/opentaps/applications/order/servicedef/services_quote.xml
> 12564 (main) [ ModelServiceReader.java:244:INFO ] Loaded 29 Service definitions from file:/usr/opentaps/applications/order/servicedef/services_quote.xml
> 12570 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0050s: /usr/opentaps/applications/order/servicedef/services_requirement.xml
> 12571 (main) [ ModelServiceReader.java:244:INFO ] Loaded 11 Service definitions from file:/usr/opentaps/applications/order/servicedef/services_requirement.xml
> 12577 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0050s: /usr/opentaps/applications/order/servicedef/services_return.xml
> 12580 (main) [ ModelServiceReader.java:244:INFO ] Loaded 31 Service definitions from file:/usr/opentaps/applications/order/servicedef/services_return.xml
> 12586 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0050s: /usr/opentaps/applications/order/servicedef/services_opportunity.xml
> 12587 (main) [ ModelServiceReader.java:244:INFO ] Loaded 2 Service definitions from file:/usr/opentaps/applications/order/servicedef/services_opportunity.xml
> 12603 (main) [ UtilXml.java:242:DEBUG] XML Read 0.015s: /usr/opentaps/applications/marketing/servicedef/services.xml
> 12607 (main) [ ModelServiceReader.java:244:INFO ] Loaded 41 Service definitions from file:/usr/opentaps/applications/marketing/servicedef/services.xml
> 12612 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0040s: /usr/opentaps/hot-deploy/crmsfa/servicedef/services_accounts.xml
> 12613 (main) [ ModelServiceReader.java:244:INFO ] Loaded 5 Service definitions from file:/usr/opentaps/hot-deploy/crmsfa/servicedef/services_accounts.xml
> 12618 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0050s: /usr/opentaps/hot-deploy/crmsfa/servicedef/services_contacts.xml
> 12619 (main) [ ModelServiceReader.java:244:INFO ] Loaded 6 Service definitions from file:/usr/opentaps/hot-deploy/crmsfa/servicedef/services_contacts.xml
> 12626 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0060s: /usr/opentaps/hot-deploy/crmsfa/servicedef/services_leads.xml
> 12632 (main) [ ModelServiceReader.java:244:INFO ] Loaded 6 Service definitions from file:/usr/opentaps/hot-deploy/crmsfa/servicedef/services_leads.xml
> 12640 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0080s: /usr/opentaps/hot-deploy/crmsfa/servicedef/services_opportunities.xml
> 12642 (main) [ ModelServiceReader.java:244:INFO ] Loaded 8 Service definitions from file:/usr/opentaps/hot-deploy/crmsfa/servicedef/services_opportunities.xml
> 12648 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0060s: /usr/opentaps/hot-deploy/crmsfa/servicedef/services_cases.xml
> 12648 (main) [ ModelServiceReader.java:244:INFO ] Loaded 3 Service definitions from file:/usr/opentaps/hot-deploy/crmsfa/servicedef/services_cases.xml
> 12656 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0070s: /usr/opentaps/hot-deploy/crmsfa/servicedef/services_activities.xml
> 12658 (main) [ ModelServiceReader.java:244:INFO ] Loaded 14 Service definitions from file:/usr/opentaps/hot-deploy/crmsfa/servicedef/services_activities.xml
> 12673 (main) [ UtilXml.java:242:DEBUG] XML Read 0.015s: /usr/opentaps/hot-deploy/crmsfa/servicedef/services_forecasts.xml
> 12674 (main) [ ModelServiceReader.java:244:INFO ] Loaded 4 Service definitions from file:/usr/opentaps/hot-deploy/crmsfa/servicedef/services_forecasts.xml
> 12678 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0040s: /usr/opentaps/hot-deploy/crmsfa/servicedef/services_teams.xml
> 12679 (main) [ ModelServiceReader.java:244:INFO ] Loaded 4 Service definitions from file:/usr/opentaps/hot-deploy/crmsfa/servicedef/services_teams.xml
> 12684 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0050s: /usr/opentaps/hot-deploy/crmsfa/servicedef/services_party.xml
> 12685 (main) [ ModelServiceReader.java:244:INFO ] Loaded 6 Service definitions from file:/usr/opentaps/hot-deploy/crmsfa/servicedef/services_party.xml
> 12689 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0040s: /usr/opentaps/hot-deploy/crmsfa/servicedef/services_notes.xml
> 12690 (main) [ ModelServiceReader.java:244:INFO ] Loaded 4 Service definitions from file:/usr/opentaps/hot-deploy/crmsfa/servicedef/services_notes.xml
> 12694 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0040s: /usr/opentaps/hot-deploy/crmsfa/servicedef/services_datasources.xml
> 12695 (main) [ ModelServiceReader.java:244:INFO ] Loaded 4 Service definitions from file:/usr/opentaps/hot-deploy/crmsfa/servicedef/services_datasources.xml
> 12701 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0050s: /usr/opentaps/hot-deploy/financials/servicedef/services_ledger_ext.xml
> 12702 (main) [ ModelServiceReader.java:244:INFO ] Loaded 15 Service definitions from file:/usr/opentaps/hot-deploy/financials/servicedef/services_ledger_ext.xml
> 12708 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0050s: /usr/opentaps/hot-deploy/financials/servicedef/services_other.xml
> 12709 (main) [ ModelServiceReader.java:244:INFO ] Loaded 1 Service definitions from file:/usr/opentaps/hot-deploy/financials/servicedef/services_other.xml
> 12713 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0040s: /usr/opentaps/hot-deploy/financials/servicedef/services_cogs.xml
> 12714 (main) [ ModelServiceReader.java:244:INFO ] Loaded 5 Service definitions from file:/usr/opentaps/hot-deploy/financials/servicedef/services_cogs.xml
> 12720 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0060s: /usr/opentaps/hot-deploy/financials/servicedef/services_financials.xml
> 12723 (main) [ ModelServiceReader.java:244:INFO ] Loaded 12 Service definitions from file:/usr/opentaps/hot-deploy/financials/servicedef/services_financials.xml
> 12731 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0070s: /usr/opentaps/hot-deploy/financials/servicedef/services_manufacturing.xml
> 12732 (main) [ ModelServiceReader.java:244:INFO ] Loaded 1 Service definitions from file:/usr/opentaps/hot-deploy/financials/servicedef/services_manufacturing.xml
> 12738 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0060s: /usr/opentaps/hot-deploy/financials/servicedef/services_configuration.xml
> 12740 (main) [ ModelServiceReader.java:244:INFO ] Loaded 5 Service definitions from file:/usr/opentaps/hot-deploy/financials/servicedef/services_configuration.xml
> 12741 (main) [ GenericDispatcher.java:85 :INFO ] [LocalDispatcher] : Created Dispatcher for: entity-default
> 13050 (main) [ UtilXml.java:242:DEBUG] XML Read 0.013s: file:/usr/opentaps/framework/catalina/config/mime-type.xml
> 13735 (main) [ ContainerLoader.java:83 :INFO ] [Startup] Starting containers...
> 13739 (main) [ ServiceDispatcher.java:172:INFO ] Registered dispatcher: RMIDispatcher
> 13741 (main) [ GenericDispatcher.java:85 :INFO ] [LocalDispatcher] : Created Dispatcher for: RMIDispatcher
> 13853 (main) [ ServiceDispatcher.java:172:INFO ] Registered dispatcher: RMIFopPrintServer
> 13882 (main) [ GenericDispatcher.java:85 :INFO ] [LocalDispatcher] : Created Dispatcher for: RMIFopPrintServer
> 13907 (main) [ ServiceDispatcher.java:172:INFO ] Registered dispatcher: JavaMailDispatcher
> 13909 (main) [ GenericDispatcher.java:85 :INFO ] [LocalDispatcher] : Created Dispatcher for: JavaMailDispatcher
> 13935 (main) [ UtilXml.java:242:DEBUG] XML Read 0.014s: /usr/opentaps/framework/common/servicedef/smcas_test.xml
> 13942 (main) [ ServiceMcaUtil.java:116:INFO ] Loaded 1 Service MCA definitions from file:/usr/opentaps/framework/common/servicedef/smcas_test.xml
> 13946 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0030s: /usr/opentaps/hot-deploy/crmsfa/servicedef/smcas.xml
> 13946 (main) [ ServiceMcaUtil.java:116:INFO ] Loaded 1 Service MCA definitions from file:/usr/opentaps/hot-deploy/crmsfa/servicedef/smcas.xml
> DEBUG: not loading system providers in <java.home>/lib
> DEBUG: not loading optional custom providers file: /META-INF/javamail.providers
> DEBUG: successfully loaded default providers
> DEBUG: Tables of loaded providers
> DEBUG: Providers Listed By Class Name: {com.sun.mail.smtp.SMTPTransport=javax.mail.Provider[TRANSPORT,smtp,com.sun.mail.smtp.SMTPTransport,Sun Microsystems, Inc], com.sun.mail.imap.IMAPStore=javax.mail.Provider[STORE,imap,com.sun.mail.imap.IMAPStore,Sun Microsystems, Inc], com.sun.mail.pop3.POP3Store=javax.mail.Provider[STORE,pop3,com.sun.mail.pop3.POP3Store,Sun Microsystems, Inc]}
> DEBUG: Providers Listed By Protocol: {imap=javax.mail.Provider[STORE,imap,com.sun.mail.imap.IMAPStore,Sun Microsystems, Inc], pop3=javax.mail.Provider[STORE,pop3,com.sun.mail.pop3.POP3Store,Sun Microsystems, Inc], smtp=javax.mail.Provider[TRANSPORT,smtp,com.sun.mail.smtp.SMTPTransport,Sun Microsystems, Inc]}
> DEBUG: not loading optional address map file: /META-INF/javamail.address.map
> DEBUG: getProvider() returning javax.mail.Provider[STORE,imap,com.sun.mail.imap.IMAPStore,Sun Microsystems, Inc]
> 13975 (main) [ JavaMailContainer.java:235:INFO ] Update URL - imap://[user]@[host]:-1![pass];null
> 13977 (main) [ JavaMailContainer.java:183:INFO ] URLName - imap://%5Buser%5D:%5Bpass%5D@[host]
> DEBUG: getProvider() returning javax.mail.Provider[STORE,imap,com.sun.mail.imap.IMAPStore,Sun Microsystems, Inc]
> 13987 (main) [ JavaMailContainer.java:196:ERROR] Unable to connect to mail store : imap://%5Buser%5D@[host]
> 13988 (main) [ JavaMailContainer.java:138:WARN ] No JavaMail Store(s) configured; poller disabled.
> 14550 (main) [ ContextFilter.java:356:INFO ] Adding web.xml context-param to application attribute with name [entityDelegatorName] and value [default]
> 14551 (main) [ ContextFilter.java:356:INFO ] Adding web.xml context-param to application attribute with name [localDispatcherName] and value [example]
> 14551 (main) [ Conte