[jira] Created: (OFBIZ-114) Error Receive Inventory / Quick Receive Shipment

[Nicolas Malin (Jira)]

     [ http://issues.apache.org/jira/browse/OFBIZ-114?page=all ]

David E. Jones closed OFBIZ-114.
--------------------------------

    Fix Version/s: SVN trunk
       Resolution: Fixed
         Assignee: David E. Jones

The fix is now verified.

> 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
>         Assigned To: David E. Jones
>             Fix For: SVN trunk
>
>         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 (main) [    ConfigXMLReader