http://ofbiz.116.s1.nabble.com/jira-Created-OFBIZ-114-Error-Receive-Inventory-Quick-Receive-Shipment-tp170127p170139.html
Aha, if you don't have the financials module plugged into the ofbiz svn then it won't try to post to ledger, so you won't see the log message.
> 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
> Attachments: console.log
>
>
> 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) [ ContextFilter.java:321:INFO ] [ContextFilter.init] Getting Entity Engine Delegator with delegator name default
> 14553 (main) [ ServiceDispatcher.java:172:INFO ] Registered dispatcher: example
> 14575 (main) [ GenericDispatcher.java:85 :INFO ] [LocalDispatcher] : Created Dispatcher for: example
> 14581 (main) [ ControlServlet.java:74 :INFO ] [ControlServlet.init] Loading Control Servlet mounted on path /usr/opentaps/framework/example/webapp/example/
> 14595 (main) [ RequestHandler.java:92 :INFO ] [RequestHandler Loading...]
> 14614 (main) [ UtilXml.java:242:DEBUG] XML Read 0.0070s: jndi:/0.0.0.0/example/WEB-INF/controller.xml
> 14615 (main) [ ConfigXMLReader.java:552:INFO ] ConfigMap Created: (4) records in 0.0s
> 14616 (ma