2009-11-15 15:23:35,203 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 34] Services from file:/C:/ofbiz-trunk/applications/manufacturing/servicedef/services_production_run.xml 2009-11-15 15:23:35,250 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 13] Services from file:/C:/ofbiz-trunk/applications/manufacturing/servicedef/services_bom.xml 2009-11-15 15:23:35,265 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 5] Services from file:/C:/ofbiz-trunk/applications/manufacturing/servicedef/services_mrp.xml 2009-11-15 15:23:35,281 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 5] Services from file:/C:/ofbiz-trunk/applications/manufacturing/servicedef/services_formula.xml 2009-11-15 15:23:35,375 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 30] Services from file:/C:/ofbiz-trunk/applications/accounting/servicedef/services_agreement.xml 2009-11-15 15:23:35,406 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 14] Services from file:/C:/ofbiz-trunk/applications/accounting/servicedef/services_admin.xml 2009-11-15 15:23:35,421 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 10] Services from file:/C:/ofbiz-trunk/applications/accounting/servicedef/services_billing.xml 2009-11-15 15:23:35,546 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 57] Services from file:/C:/ofbiz-trunk/applications/accounting/servicedef/services_finaccount.xml 2009-11-15 15:23:35,609 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 31] Services from file:/C:/ofbiz-trunk/applications/accounting/servicedef/services_fixedasset.xml 2009-11-15 15:23:35,671 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 40] Services from file:/C:/ofbiz-trunk/applications/accounting/servicedef/services_invoice.xml 2009-11-15 15:23:35,796 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 81] Services from file:/C:/ofbiz-trunk/applications/accounting/servicedef/services_ledger.xml 2009-11-15 15:23:35,843 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 23] Services from file:/C:/ofbiz-trunk/applications/accounting/servicedef/services_payment.xml 2009-11-15 15:23:35,984 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 91] Services from file:/C:/ofbiz-trunk/applications/accounting/servicedef/services_paymentmethod.xml 2009-11-15 15:23:36,000 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 8] Services from file:/C:/ofbiz-trunk/applications/accounting/servicedef/services_paymentgateway.xml 2009-11-15 15:23:36,046 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 25] Services from file:/C:/ofbiz-trunk/applications/accounting/servicedef/services_tax.xml 2009-11-15 15:23:36,062 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 5] Services from file:/C:/ofbiz-trunk/applications/accounting/servicedef/services_rate.xml 2009-11-15 15:23:36,093 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 11] Services from file:/C:/ofbiz-trunk/applications/accounting/servicedef/services_cost.xml 2009-11-15 15:23:36,093 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 2] Services from file:/C:/ofbiz-trunk/applications/accounting/servicedef/services_olap.xml 2009-11-15 15:23:36,109 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 6] Services from file:/C:/ofbiz-trunk/applications/accounting/servicedef/services_authorizedotnet.xml 2009-11-15 15:23:36,140 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 6] Services from file:/C:/ofbiz-trunk/applications/accounting/servicedef/services_clearcommerce.xml 2009-11-15 15:23:36,156 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 5] Services from file:/C:/ofbiz-trunk/applications/accounting/servicedef/services_cybersource.xml 2009-11-15 15:23:36,171 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 8] Services from file:/C:/ofbiz-trunk/applications/accounting/servicedef/services_paypal.xml 2009-11-15 15:23:36,187 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 4] Services from file:/C:/ofbiz-trunk/applications/accounting/servicedef/services_pccharge.xml 2009-11-15 15:23:36,218 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 10] Services from file:/C:/ofbiz-trunk/applications/accounting/servicedef/services_rita.xml 2009-11-15 15:23:36,250 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 21] Services from file:/C:/ofbiz-trunk/applications/accounting/servicedef/services_valuelink.xml 2009-11-15 15:23:36,265 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 11] Services from file:/C:/ofbiz-trunk/applications/accounting/servicedef/services_verisign.xml 2009-11-15 15:23:36,296 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 5] Services from file:/C:/ofbiz-trunk/applications/accounting/servicedef/services_orbital.xml 2009-11-15 15:23:36,296 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 3] Services from file:/C:/ofbiz-trunk/applications/accounting/servicedef/services_upgrade.xml 2009-11-15 15:23:36,500 (main) [ ModelServiceReader.java:215:INFO ] Loaded [109] Services from file:/C:/ofbiz-trunk/applications/humanres/servicedef/services.xml 2009-11-15 15:23:36,718 (main) [ ModelServiceReader.java:215:INFO ] Loaded [111] Services from file:/C:/ofbiz-trunk/applications/order/servicedef/services.xml 2009-11-15 15:23:36,750 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 12] Services from file:/C:/ofbiz-trunk/applications/order/servicedef/services_cart.xml 2009-11-15 15:23:36,796 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 15] Services from file:/C:/ofbiz-trunk/applications/order/servicedef/services_shoppinglist.xml 2009-11-15 15:23:36,828 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 21] Services from file:/C:/ofbiz-trunk/applications/order/servicedef/services_request.xml 2009-11-15 15:23:36,906 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 30] Services from file:/C:/ofbiz-trunk/applications/order/servicedef/services_quote.xml 2009-11-15 15:23:36,953 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 21] Services from file:/C:/ofbiz-trunk/applications/order/servicedef/services_requirement.xml 2009-11-15 15:23:37,046 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 49] Services from file:/C:/ofbiz-trunk/applications/order/servicedef/services_return.xml 2009-11-15 15:23:37,062 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 2] Services from file:/C:/ofbiz-trunk/applications/order/servicedef/services_upgrade.xml 2009-11-15 15:23:37,156 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 60] Services from file:/C:/ofbiz-trunk/applications/marketing/servicedef/services.xml 2009-11-15 15:23:37,203 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 11] Services from file:/C:/ofbiz-trunk/applications/marketing/servicedef/services_opportunity.xml 2009-11-15 15:23:37,234 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 5] Services from file:/C:/ofbiz-trunk/applications/commonext/servicedef/services.xml 2009-11-15 15:23:37,578 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 5] Services from file:/C:/ofbiz-trunk/specialpurpose/hhfacility/servicedef/services_hhfacility.xml 2009-11-15 15:23:37,609 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 2] Services from file:/C:/ofbiz-trunk/specialpurpose/assetmaint/servicedef/services.xml 2009-11-15 15:23:37,656 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 21] Services from file:/C:/ofbiz-trunk/specialpurpose/projectmgr/servicedef/services.xml 2009-11-15 15:23:37,718 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 18] Services from file:/C:/ofbiz-trunk/specialpurpose/oagis/servicedef/services.xml 2009-11-15 15:23:37,765 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 5] Services from file:/C:/ofbiz-trunk/specialpurpose/googlebase/servicedef/services.xml 2009-11-15 15:23:37,796 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 11] Services from file:/C:/ofbiz-trunk/specialpurpose/googlecheckout/servicedef/services_request.xml 2009-11-15 15:23:37,812 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 10] Services from file:/C:/ofbiz-trunk/specialpurpose/googlecheckout/servicedef/services.xml 2009-11-15 15:23:37,859 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 13] Services from file:/C:/ofbiz-trunk/specialpurpose/ebay/servicedef/services.xml 2009-11-15 15:23:37,890 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 2] Services from file:/C:/ofbiz-trunk/specialpurpose/myportal/servicedef/services.xml 2009-11-15 15:23:37,921 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 4] Services from file:/C:/ofbiz-trunk/specialpurpose/webpos/servicedef/services.xml 2009-11-15 15:23:37,937 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 6] Services from file:/C:/ofbiz-trunk/specialpurpose/webpos/servicedef/services_manager.xml 2009-11-15 15:23:37,968 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 8] Services from file:/C:/ofbiz-trunk/specialpurpose/webpos/servicedef/services_payment.xml 2009-11-15 15:23:37,984 (main) [ ModelServiceReader.java:215:INFO ] Loaded [ 1] Services from file:/C:/ofbiz-trunk/specialpurpose/webpos/servicedef/services_promo.xml 2009-11-15 15:23:37,984 (main) [ ServiceEcaUtil.java:117:INFO ] Loaded [ 4] Service ECA Rules from file:/C:/ofbiz-trunk/framework/common/servicedef/secas_test.xml 2009-11-15 15:23:38,000 (main) [ ServiceEcaUtil.java:117:INFO ] Loaded [ 0] Service ECA Rules from file:/C:/ofbiz-trunk/framework/common/servicedef/secas_cdyne.xml 2009-11-15 15:23:38,000 (main) [ ServiceEcaUtil.java:117:INFO ] Loaded [ 2] Service ECA Rules from file:/C:/ofbiz-trunk/framework/service/servicedef/secas_test_se.xml 2009-11-15 15:23:38,015 (main) [ ServiceEcaUtil.java:117:INFO ] Loaded [ 2] Service ECA Rules from file:/C:/ofbiz-trunk/framework/example/servicedef/secas.xml 2009-11-15 15:23:38,015 (main) [ ServiceEcaUtil.java:117:INFO ] Loaded [16] Service ECA Rules from file:/C:/ofbiz-trunk/applications/party/servicedef/secas.xml 2009-11-15 15:23:38,031 (main) [ ServiceEcaUtil.java:117:INFO ] Loaded [27] Service ECA Rules from file:/C:/ofbiz-trunk/applications/content/servicedef/secas.xml 2009-11-15 15:23:38,046 (main) [ ServiceEcaUtil.java:117:INFO ] Loaded [10] Service ECA Rules from file:/C:/ofbiz-trunk/applications/workeffort/servicedef/secas.xml 2009-11-15 15:23:38,046 (main) [ ServiceEcaUtil.java:117:INFO ] Loaded [21] Service ECA Rules from file:/C:/ofbiz-trunk/applications/product/servicedef/secas.xml 2009-11-15 15:23:38,062 (main) [ ServiceEcaUtil.java:117:INFO ] Loaded [23] Service ECA Rules from file:/C:/ofbiz-trunk/applications/product/servicedef/secas_shipment.xml 2009-11-15 15:23:38,062 (main) [ ServiceEcaUtil.java:117:INFO ] Loaded [ 2] Service ECA Rules from file:/C:/ofbiz-trunk/applications/product/servicedef/secas_olap.xml 2009-11-15 15:23:38,078 (main) [ ServiceEcaUtil.java:117:INFO ] Loaded [ 4] Service ECA Rules from file:/C:/ofbiz-trunk/applications/manufacturing/servicedef/secas.xml 2009-11-15 15:23:38,093 (main) [ ServiceEcaUtil.java:117:INFO ] Loaded [22] Service ECA Rules from file:/C:/ofbiz-trunk/applications/accounting/servicedef/secas.xml 2009-11-15 15:23:38,093 (main) [ ServiceEcaUtil.java:117:INFO ] Loaded [ 6] Service ECA Rules from file:/C:/ofbiz-trunk/applications/accounting/servicedef/secas_payment.xml 2009-11-15 15:23:38,109 (main) [ ServiceEcaUtil.java:117:INFO ] Loaded [20] Service ECA Rules from file:/C:/ofbiz-trunk/applications/accounting/servicedef/secas_ledger.xml 2009-11-15 15:23:38,109 (main) [ ServiceEcaUtil.java:117:INFO ] Loaded [ 1] Service ECA Rules from file:/C:/ofbiz-trunk/applications/accounting/servicedef/secas_olap.xml 2009-11-15 15:23:38,109 (main) [ ServiceEcaUtil.java:117:INFO ] Loaded [ 2] Service ECA Rules from file:/C:/ofbiz-trunk/applications/accounting/servicedef/secas_invoice.xml 2009-11-15 15:23:38,140 (main) [ ServiceEcaUtil.java:117:INFO ] Loaded [69] Service ECA Rules from file:/C:/ofbiz-trunk/applications/order/servicedef/secas.xml 2009-11-15 15:23:38,140 (main) [ ServiceEcaUtil.java:117:INFO ] Loaded [ 6] Service ECA Rules from file:/C:/ofbiz-trunk/applications/marketing/servicedef/secas.xml 2009-11-15 15:23:38,140 (main) [ ServiceEcaUtil.java:117:INFO ] Loaded [ 3] Service ECA Rules from file:/C:/ofbiz-trunk/applications/commonext/servicedef/secas.xml 2009-11-15 15:23:38,156 (main) [ ServiceEcaUtil.java:117:INFO ] Loaded [ 5] Service ECA Rules from file:/C:/ofbiz-trunk/specialpurpose/assetmaint/servicedef/secas.xml 2009-11-15 15:23:38,171 (main) [ ServiceEcaUtil.java:117:INFO ] Loaded [ 0] Service ECA Rules from file:/C:/ofbiz-trunk/specialpurpose/projectmgr/servicedef/secas.xml 2009-11-15 15:23:38,171 (main) [ ServiceEcaUtil.java:117:INFO ] Loaded [10] Service ECA Rules from file:/C:/ofbiz-trunk/specialpurpose/googlecheckout/servicedef/secas.xml 2009-11-15 15:23:38,171 (main) [ ServiceEcaUtil.java:117:INFO ] Loaded [ 1] Service ECA Rules from file:/C:/ofbiz-trunk/specialpurpose/ebay/servicedef/secas.xml 2009-11-15 15:23:41,468 (main) [ JkMain.java:251:INFO ] Can't find home, jk2.properties not loaded 2009-11-15 15:23:41,531 (main) [ Http11Protocol.java:182:INFO ] Initializing Coyote HTTP/1.1 on http-0.0.0.0-8080 2009-11-15 15:23:42,140 (main) [ SSLImpl.java:49 :INFO ] SSLImpl loaded; using custom ServerSocketFactory 2009-11-15 15:23:43,718 (main) [ Http11Protocol.java:182:INFO ] Initializing Coyote HTTP/1.1 on http-0.0.0.0-8443 2009-11-15 15:23:43,734 (main) [ ContainerLoader.java:95 :INFO ] [Startup] Starting containers... 2009-11-15 15:23:43,906 (main) [ VfsLog.java:122:INFO ] Using "C:\DOCUME~1\ADMINI~1\LOCALS~1\Temp\vfs_cache" as temporary files store. 2009-11-15 15:23:44,984 (main) [ GenericDispatcher.java:61 :INFO ] Creating new dispatcher [RMIDispatcher] (main) 2009-11-15 15:23:49,750 (main) [ GenericDispatcher.java:61 :INFO ] Creating new dispatcher [oagismgr] (main) 2009-11-15 15:23:49,968 (main) [ ControlServlet.java:69 :INFO ] LOADING WEBAPP [oagis] Open Oagis - Oagis Implementation, located at C:\ofbiz-trunk\specialpurpose\oagis\webapp\oagis\ 2009-11-15 15:23:50,140 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.015s, 18 requests, 14 views in file:/C:/ofbiz-trunk/framework/common/webcommon/WEB-INF/common-controller.xml 2009-11-15 15:23:50,140 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.078s, 32 requests, 18 views in jndi:/0.0.0.0/oagis/WEB-INF/controller.xml 2009-11-15 15:23:50,718 (main) [ FreeMarkerWorker.java:104:INFO ] loading properties: file:/C:/ofbiz-trunk/framework/webapp/config/freemarkerTransforms.properties 2009-11-15 15:23:50,828 (main) [ FreeMarkerWorker.java:104:INFO ] loading properties: file:/C:/ofbiz-trunk/framework/widget/config/freemarkerTransforms.properties 2009-11-15 15:23:50,859 (main) [ FreeMarkerWorker.java:104:INFO ] loading properties: file:/C:/ofbiz-trunk/applications/content/config/freemarkerTransforms.properties 2009-11-15 15:23:51,515 (org.ofbiz.service.jms.JmsListenerFactory@1efad77) [ JmsListenerFactory.java:75 :INFO ] JMS Listener Factory Thread Finished; All listeners connected. 2009-11-15 15:23:51,765 (main) [ GenericDispatcher.java:61 :INFO ] Creating new dispatcher [xmlrpc-dispatcher] (main) 2009-11-15 15:23:51,875 (main) [ GenericDispatcher.java:61 :INFO ] Creating new dispatcher [sstream-dispatcher] (main) 2009-11-15 15:23:52,765 (main) [ GenericDispatcher.java:61 :INFO ] Creating new dispatcher [googlecheckout] (main) 2009-11-15 15:23:52,765 (main) [ ControlServlet.java:69 :INFO ] LOADING WEBAPP [googlecheckout] Open For Business - Google Checkout, located at C:\ofbiz-trunk\specialpurpose\googlecheckout\webapp\googlecheckout\ 2009-11-15 15:23:52,781 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.0s, 28 requests, 17 views in jndi:/0.0.0.0/googlecheckout/WEB-INF/controller.xml 2009-11-15 15:23:53,609 (main) [ GenericDispatcher.java:61 :INFO ] Creating new dispatcher [facility] (main) 2009-11-15 15:23:53,625 (main) [ ControlServlet.java:69 :INFO ] LOADING WEBAPP [facility] Open For Business - Facility Manager, located at C:\ofbiz-trunk\applications\product\webapp\facility\ 2009-11-15 15:23:53,718 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.0s, 5 requests, 2 views in file:/C:/ofbiz-trunk/applications/commonext/webapp/WEB-INF/controller.xml 2009-11-15 15:23:53,718 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.047s, 246 requests, 98 views in jndi:/0.0.0.0/facility/WEB-INF/controller.xml 2009-11-15 15:23:54,484 (main) [ GenericDispatcher.java:61 :INFO ] Creating new dispatcher [accounting] (main) 2009-11-15 15:23:54,500 (main) [ ControlServlet.java:69 :INFO ] LOADING WEBAPP [accounting] Open For Business - Accounting Manager, located at C:\ofbiz-trunk\applications\accounting\webapp\accounting\ 2009-11-15 15:23:54,656 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.031s, 474 requests, 228 views in jndi:/0.0.0.0/accounting/WEB-INF/controller.xml 2009-11-15 15:23:55,281 (main) [ GenericDispatcher.java:61 :INFO ] Creating new dispatcher [assetmaint] (main) 2009-11-15 15:23:55,281 (main) [ ControlServlet.java:69 :INFO ] LOADING WEBAPP [assetmaint] Open For Business - Asset Maintenance, located at C:\ofbiz-trunk\specialpurpose\assetmaint\webapp\assetmaint\ 2009-11-15 15:23:55,390 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.0s, 33 requests, 21 views in file:/C:/ofbiz-trunk/applications/workeffort/webapp/workeffort/WEB-INF/icalendar-controller.xml 2009-11-15 15:23:55,406 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.031s, 175 requests, 86 views in file:/C:/ofbiz-trunk/applications/workeffort/webapp/workeffort/WEB-INF/controller.xml 2009-11-15 15:23:55,531 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.031s, 466 requests, 151 views in file:/C:/ofbiz-trunk/applications/product/webapp/catalog/WEB-INF/controller.xml 2009-11-15 15:23:55,578 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.0s, 246 requests, 98 views in file:/C:/ofbiz-trunk/applications/product/webapp/facility/WEB-INF/controller.xml 2009-11-15 15:23:55,671 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.015s, 474 requests, 228 views in file:/C:/ofbiz-trunk/applications/accounting/webapp/accounting/WEB-INF/controller.xml 2009-11-15 15:23:55,687 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.391s, 1247 requests, 483 views in jndi:/0.0.0.0/assetmaint/WEB-INF/controller.xml 2009-11-15 15:23:57,031 (main) [ GenericDispatcher.java:61 :INFO ] Creating new dispatcher [OfbizSite] (main) 2009-11-15 15:23:57,031 (main) [ ControlServlet.java:69 :INFO ] LOADING WEBAPP [ofbiz] Open For Business - CMS Site, located at C:\ofbiz-trunk\specialpurpose\cmssite\webapp\ofbizsite\ 2009-11-15 15:23:57,046 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.0s, 19 requests, 14 views in jndi:/0.0.0.0/ofbiz/WEB-INF/controller.xml 2009-11-15 15:23:57,671 (main) [ GenericDispatcher.java:61 :INFO ] Creating new dispatcher [setup] (main) 2009-11-15 15:23:57,671 (main) [ ControlServlet.java:69 :INFO ] LOADING WEBAPP [ofbizsetup] Open For Business - Setup Manager, located at C:\ofbiz-trunk\applications\commonext\webapp\ofbizsetup\ 2009-11-15 15:23:57,906 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.016s, 377 requests, 162 views in file:/C:/ofbiz-trunk/applications/content/webapp/content/WEB-INF/controller.xml 2009-11-15 15:23:57,921 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.14s, 623 requests, 254 views in file:/C:/ofbiz-trunk/applications/party/webapp/partymgr/WEB-INF/controller.xml 2009-11-15 15:23:57,921 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.234s, 647 requests, 266 views in jndi:/0.0.0.0/ofbizsetup/WEB-INF/controller.xml 2009-11-15 15:23:58,531 (main) [ GenericDispatcher.java:61 :INFO ] Creating new dispatcher [hhfacility] (main) 2009-11-15 15:23:58,531 (main) [ ControlServlet.java:69 :INFO ] LOADING WEBAPP [hhfacility] Facility Handheld Application, located at C:\ofbiz-trunk\specialpurpose\hhfacility\webapp\hhfacility\ 2009-11-15 15:23:58,546 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.0s, 34 requests, 26 views in jndi:/0.0.0.0/hhfacility/WEB-INF/controller.xml 2009-11-15 15:23:59,218 (main) [ GenericDispatcher.java:61 :INFO ] Creating new dispatcher [manufacturing] (main) 2009-11-15 15:23:59,234 (main) [ ControlServlet.java:69 :INFO ] LOADING WEBAPP [manufacturing] Open For Business - Manufacturing Manager, located at C:\ofbiz-trunk\applications\manufacturing\webapp\manufacturing\ 2009-11-15 15:23:59,281 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.0s, 157 requests, 74 views in jndi:/0.0.0.0/manufacturing/WEB-INF/controller.xml 2009-11-15 15:24:00,203 (main) [ GenericDispatcher.java:61 :INFO ] Creating new dispatcher [bi] (main) 2009-11-15 15:24:00,218 (main) [ ControlServlet.java:69 :INFO ] LOADING WEBAPP [bi] Open For Business - Business Intelligence, located at C:\ofbiz-trunk\framework\bi\webapp\bi\ 2009-11-15 15:24:00,265 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.015s, 18 requests, 14 views in file:/C:/ofbiz-trunk/framework/common/webcommon/WEB-INF/common-controller.xml 2009-11-15 15:24:00,265 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.015s, 29 requests, 19 views in jndi:/0.0.0.0/bi/WEB-INF/controller.xml 2009-11-15 15:24:01,875 (Dispatcher-Thread-1) [ ?:? :INFO ] Discarded 2 messages due to full event buffer including: controller loaded: 0.0s, 175 requests, 86 views in jndi:/0.0.0.0/workeffort/WEB-INF/controller.xml 2009-11-15 15:24:01,875 (Dispatcher-Thread-1) [ ?:? :INFO ] Discarded 2 messages due to full event buffer including: Creating new dispatcher [workeffort] (main) 2009-11-15 15:24:01,875 (Dispatcher-Thread-1) [ ?:? :INFO ] Discarded 2 messages due to full event buffer including: LOADING WEBAPP [workeffort] Open For Business - Work Manager, located at C:\ofbiz-trunk\applications\workeffort\webapp\workeffort\ 2009-11-15 15:24:02,921 (main) [ GenericDispatcher.java:61 :INFO ] Creating new dispatcher [ecommerce] (main) 2009-11-15 15:24:02,921 (main) [ ControlServlet.java:69 :INFO ] LOADING WEBAPP [ecomclone] Open For Business - demostore, located at C:\ofbiz-trunk\specialpurpose\ecommerce\webapp\ecomclone\ 2009-11-15 15:24:03,031 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.016s, 275 requests, 91 views in file:/C:/ofbiz-trunk/specialpurpose/ecommerce/webapp/ecommerce/WEB-INF/controller.xml 2009-11-15 15:24:03,031 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.094s, 275 requests, 91 views in jndi:/0.0.0.0/ecomclone/WEB-INF/controller.xml 2009-11-15 15:24:03,171 (org.ofbiz.service.job.JobPoller@1b583cf) [ GenericDispatcher.java:61 :INFO ] Creating new dispatcher [JobDispatcher] (org.ofbiz.service.job.JobPoller@1b583cf) 2009-11-15 15:24:04,046 (default-invoker-Thread-8) [ JobInvoker.java:232:INFO ] Invoker [default-invoker-Thread-8] received job [Create Also Bought Product Associations] from poller [org.ofbiz.service.job.JobPoller@1b583cf] 2009-11-15 15:24:04,062 (default-invoker-Thread-7) [ JobInvoker.java:232:INFO ] Invoker [default-invoker-Thread-7] received job [Purge Old Store Auto-Entered Promos] from poller [org.ofbiz.service.job.JobPoller@1b583cf] 2009-11-15 15:24:04,062 (default-invoker-Thread-10) [ JobInvoker.java:232:INFO ] Invoker [default-invoker-Thread-10] received job [Set Last Inventory Count on ProductFacility] from poller [org.ofbiz.service.job.JobPoller@1b583cf] 2009-11-15 15:24:04,046 (default-invoker-Thread-9) [ JobInvoker.java:232:INFO ] Invoker [default-invoker-Thread-9] received job [Replacement Held Order Auto-Cancel] from poller [org.ofbiz.service.job.JobPoller@1b583cf] 2009-11-15 15:24:04,046 (default-invoker-Thread-11) [ JobInvoker.java:232:INFO ] Invoker [default-invoker-Thread-11] received job [BackOrder Notification] from poller [org.ofbiz.service.job.JobPoller@1b583cf] 2009-11-15 15:24:05,218 (Dispatcher-Thread-1) [ ?:? :INFO ] Discarded 2 messages due to full event buffer including: controller loaded: 0.0s, 5 requests, 2 views in file:/C:/ofbiz-trunk/applications/commonext/webapp/WEB-INF/controller.xml 2009-11-15 15:24:05,218 (Dispatcher-Thread-1) [ ?:? :INFO ] Discarded 1 messages due to full event buffer including: Creating new dispatcher [catalog] (main) 2009-11-15 15:24:05,218 (Dispatcher-Thread-1) [ ?:? :INFO ] Discarded 1 messages due to full event buffer including: LOADING WEBAPP [catalog] Open For Business - Catalog, located at C:\ofbiz-trunk\applications\product\webapp\catalog\ 2009-11-15 15:24:06,046 (main) [ GenericDispatcher.java:61 :INFO ] Creating new dispatcher [sfa] (main) 2009-11-15 15:24:06,062 (main) [ ControlServlet.java:69 :INFO ] LOADING WEBAPP [sfa] Open For Business - SFA Manager, located at C:\ofbiz-trunk\applications\marketing\webapp\sfa\ 2009-11-15 15:24:06,109 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.0s, 667 requests, 279 views in jndi:/0.0.0.0/sfa/WEB-INF/controller.xml 2009-11-15 15:24:06,625 (default-invoker-Thread-10) [PersistedServiceJob.java:135:WARN ] Persisted Job [8801] references a RecurrenceInfo, recommend using TemporalExpression instead 2009-11-15 15:24:07,765 (main) [ GenericDispatcher.java:61 :INFO ] Creating new dispatcher [ar] (main) 2009-11-15 15:24:07,781 (main) [ ControlServlet.java:69 :INFO ] LOADING WEBAPP [ar] Open For Business - Account Receivable, located at C:\ofbiz-trunk\applications\accounting\webapp\ar\ 2009-11-15 15:24:07,921 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.062s, 474 requests, 228 views in file:/C:/ofbiz-trunk/applications/accounting/webapp/accounting/WEB-INF/controller.xml 2009-11-15 15:24:07,921 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.125s, 479 requests, 235 views in jndi:/0.0.0.0/ar/WEB-INF/controller.xml 2009-11-15 15:24:08,125 (default-invoker-Thread-8) [ SequenceUtil.java:236:WARN ] [SequenceUtil.SequenceBank.fillBank] first select failed: will try to add new row, result set was empty for sequence [JobSandbox] Used SQL: SELECT SEQ_ID FROM OFBIZ.SEQUENCE_VALUE_ITEM WHERE SEQ_NAME='JobSandbox' Thread Name is: default-invoker-Thread-8:Thread[default-invoker-Thread-8,5,main] 2009-11-15 15:24:08,265 (default-invoker-Thread-8) [ SequenceUtil.java:341:INFO ] Got bank of sequenced IDs for [JobSandbox]; curSeqId=10000, maxSeqId=10100, bankSize=100 2009-11-15 15:24:08,765 (main) [ GenericDispatcher.java:61 :INFO ] Creating new dispatcher [example] (main) 2009-11-15 15:24:08,765 (main) [ ControlServlet.java:69 :INFO ] LOADING WEBAPP [example] Open For Business - Example Application, located at C:\ofbiz-trunk\framework\example\webapp\example\ 2009-11-15 15:24:08,812 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.0s, 18 requests, 5 views in file:/C:/ofbiz-trunk/framework/common/webcommon/WEB-INF/portal-controller.xml 2009-11-15 15:24:08,812 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.016s, 68 requests, 35 views in jndi:/0.0.0.0/example/WEB-INF/controller.xml 2009-11-15 15:24:09,109 (default-invoker-Thread-11) [PersistedServiceJob.java:178:INFO ] org.ofbiz.service.job.PersistedServiceJob@1afbe84[8000] -- Next runtime: Mon Nov 16 00:00:00 EET 2009 2009-11-15 15:24:09,171 (default-invoker-Thread-8) [PersistedServiceJob.java:178:INFO ] org.ofbiz.service.job.PersistedServiceJob@bd2661[8008] -- Next runtime: Mon Nov 16 00:00:00 EET 2009 2009-11-15 15:24:09,171 (default-invoker-Thread-9) [PersistedServiceJob.java:178:INFO ] org.ofbiz.service.job.PersistedServiceJob@afacf1[8007] -- Next runtime: Mon Nov 16 00:00:00 EET 2009 2009-11-15 15:24:09,171 (default-invoker-Thread-10) [PersistedServiceJob.java:178:INFO ] org.ofbiz.service.job.PersistedServiceJob@16e411f[8801] -- Next runtime: Sun Nov 15 16:00:00 EET 2009 2009-11-15 15:24:09,171 (default-invoker-Thread-7) [PersistedServiceJob.java:178:INFO ] org.ofbiz.service.job.PersistedServiceJob@1759b95[8100] -- Next runtime: Mon Nov 16 00:00:00 EET 2009 2009-11-15 15:24:09,390 (default-invoker-Thread-11) [ AbstractEngine.java:73 :INFO ] Loaded Service Locations : [main-rmi=rmi://localhost:1099/RMIDispatcher, main-http=http://localhost:8080/webtools/control/httpService, entity-sync-rmi=rmi://localhost:1099/RMIDispatcher, entity-sync-http=http://localhost:8080/webtools/control/httpService, rita-rmi=rmi://localhost:1099/RMIDispatcher, eedcc-test=http://localhost:8080/webtools/control/httpService] 2009-11-15 15:24:10,453 (main) [ GenericDispatcher.java:61 :INFO ] Creating new dispatcher [marketing] (main) 2009-11-15 15:24:10,453 (main) [ ControlServlet.java:69 :INFO ] LOADING WEBAPP [marketing] Open For Business - Marketing Manager, located at C:\ofbiz-trunk\applications\marketing\webapp\marketing\ 2009-11-15 15:24:10,531 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.0s, 18 requests, 14 views in file:/C:/ofbiz-trunk/framework/common/webcommon/WEB-INF/common-controller.xml 2009-11-15 15:24:10,531 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.016s, 111 requests, 61 views in jndi:/0.0.0.0/marketing/WEB-INF/controller.xml 2009-11-15 15:24:12,921 (Dispatcher-Thread-1) [ ?:? :INFO ] Discarded 2 messages due to full event buffer including: controller loaded: 0.016s, 377 requests, 162 views in file:/C:/ofbiz-trunk/applications/content/webapp/content/WEB-INF/controller.xml 2009-11-15 15:24:12,921 (Dispatcher-Thread-1) [ ?:? :INFO ] Discarded 1 messages due to full event buffer including: Creating new dispatcher [partymgr] (main) 2009-11-15 15:24:12,921 (Dispatcher-Thread-1) [ ?:? :INFO ] Discarded 1 messages due to full event buffer including: LOADING WEBAPP [partymgr] Open For Business - Party Manager, located at C:\ofbiz-trunk\applications\party\webapp\partymgr\ 2009-11-15 15:24:14,578 (default-invoker-Thread-11) [ InventoryServices.java:380:INFO ] OOS Inventory Items: 0 2009-11-15 15:24:14,578 (default-invoker-Thread-11) [ ServiceDispatcher.java:588:INFO ] Sync service [JobDispatcher/checkInventoryAvailability] finished in [5203] milliseconds 2009-11-15 15:24:14,687 (main) [ GenericDispatcher.java:61 :INFO ] Creating new dispatcher [Humanres] (main) 2009-11-15 15:24:14,687 (main) [ ControlServlet.java:69 :INFO ] LOADING WEBAPP [humanres] Open For Business - HumanRes Manager, located at C:\ofbiz-trunk\applications\humanres\webapp\humanres\ 2009-11-15 15:24:14,765 (default-invoker-Thread-7) [ ServiceDispatcher.java:588:INFO ] Sync service [JobDispatcher/purgeOldStoreAutoPromos] finished in [5375] milliseconds 2009-11-15 15:24:15,156 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.0s, 5 requests, 2 views in file:/C:/ofbiz-trunk/applications/commonext/webapp/WEB-INF/controller.xml 2009-11-15 15:24:15,171 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.062s, 623 requests, 254 views in file:/C:/ofbiz-trunk/applications/party/webapp/partymgr/WEB-INF/controller.xml 2009-11-15 15:24:15,187 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.141s, 826 requests, 339 views in jndi:/0.0.0.0/humanres/WEB-INF/controller.xml 2009-11-15 15:24:15,187 (default-invoker-Thread-11) [ JobInvoker.java:232:INFO ] Invoker [default-invoker-Thread-11] received job [Send Email] from poller [org.ofbiz.service.job.JobPoller@1b583cf] 2009-11-15 15:24:15,328 (default-invoker-Thread-7) [ JobInvoker.java:232:INFO ] Invoker [default-invoker-Thread-7] received job [Clear EntitySyncRemove Info] from poller [org.ofbiz.service.job.JobPoller@1b583cf] 2009-11-15 15:24:15,437 (default-invoker-Thread-9) [ ServiceDispatcher.java:588:INFO ] Sync service [JobDispatcher/autoCancelReplacementOrders] finished in [6031] milliseconds 2009-11-15 15:24:15,609 (default-invoker-Thread-9) [ JobInvoker.java:232:INFO ] Invoker [default-invoker-Thread-9] received job [Purge Old Jobs] from poller [org.ofbiz.service.job.JobPoller@1b583cf] 2009-11-15 15:24:16,656 (default-invoker-Thread-11) [PersistedServiceJob.java:178:INFO ] org.ofbiz.service.job.PersistedServiceJob@5eb17e[SENDEMAIL] -- Next runtime: Sun Nov 15 15:39:15 EET 2009 2009-11-15 15:24:16,687 (default-invoker-Thread-7) [PersistedServiceJob.java:178:INFO ] org.ofbiz.service.job.PersistedServiceJob@ac00b6[8200] -- Next runtime: Mon Nov 16 00:00:00 EET 2009 2009-11-15 15:24:16,703 (default-invoker-Thread-9) [PersistedServiceJob.java:178:INFO ] org.ofbiz.service.job.PersistedServiceJob@8ebd2e[PURGE_OLD_JOBS] -- Next runtime: Mon Nov 16 00:00:00 EET 2009 2009-11-15 15:24:17,062 (default-invoker-Thread-7) [ EntitySyncServices.java:601:INFO ] Running cleanSyncRemoveInfo 2009-11-15 15:24:17,375 (default-invoker-Thread-9) [ TransactionUtil.java:406:WARN ] No transaction in place, so not suspending. 2009-11-15 15:24:17,656 (default-invoker-Thread-7) [ EntitySyncServices.java:628:INFO ] In cleanSyncRemoveInfo removed [0] values with TX timestamp before [2009-11-15 15:00:17.359] 2009-11-15 15:24:17,656 (default-invoker-Thread-7) [ ServiceDispatcher.java:588:INFO ] Sync service [JobDispatcher/cleanSyncRemoveInfo] finished in [953] milliseconds 2009-11-15 15:24:17,671 (default-invoker-Thread-7) [ JobInvoker.java:232:INFO ] Invoker [default-invoker-Thread-7] received job [Auto-create Fixed Asset Maintenances] from poller [org.ofbiz.service.job.JobPoller@1b583cf] 2009-11-15 15:24:17,734 (default-invoker-Thread-7) [PersistedServiceJob.java:178:INFO ] org.ofbiz.service.job.PersistedServiceJob@176f752[FAM_AUTO_CREATE] -- Next runtime: Sun Nov 15 16:24:17 EET 2009 2009-11-15 15:24:19,125 (main) [ GenericDispatcher.java:61 :INFO ] Creating new dispatcher [content] (main) 2009-11-15 15:24:19,125 (main) [ ControlServlet.java:69 :INFO ] LOADING WEBAPP [content] Open For Business - Content Manager, located at C:\ofbiz-trunk\applications\content\webapp\content\ 2009-11-15 15:24:19,250 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.0s, 377 requests, 162 views in jndi:/0.0.0.0/content/WEB-INF/controller.xml 2009-11-15 15:24:20,234 (main) [ GenericDispatcher.java:61 :INFO ] Creating new dispatcher [ebay] (main) 2009-11-15 15:24:20,265 (main) [ ControlServlet.java:69 :INFO ] LOADING WEBAPP [ebay] Open For Business - Ebay, located at C:\ofbiz-trunk\specialpurpose\ebay\webapp\ebay\ 2009-11-15 15:24:20,843 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.0s, 18 requests, 14 views in file:/C:/ofbiz-trunk/framework/common/webcommon/WEB-INF/common-controller.xml 2009-11-15 15:24:20,859 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.5s, 466 requests, 151 views in file:/C:/ofbiz-trunk/applications/product/webapp/catalog/WEB-INF/controller.xml 2009-11-15 15:24:20,859 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.563s, 484 requests, 156 views in jndi:/0.0.0.0/ebay/WEB-INF/controller.xml 2009-11-15 15:24:22,265 (default-invoker-Thread-8) [ ServiceDispatcher.java:588:INFO ] Sync service [JobDispatcher/createAlsoBoughtProductAssocsForOrder] finished in [5625] milliseconds 2009-11-15 15:24:22,281 (default-invoker-Thread-8) [ ServiceDispatcher.java:588:INFO ] Sync service [JobDispatcher/createAlsoBoughtProductAssocs] finished in [12891] milliseconds 2009-11-15 15:24:22,296 (default-invoker-Thread-8) [ JobInvoker.java:232:INFO ] Invoker [default-invoker-Thread-8] received job [Re-Try Failed Auths NSF] from poller [org.ofbiz.service.job.JobPoller@1b583cf] 2009-11-15 15:24:22,312 (default-invoker-Thread-9) [ TransactionUtil.java:406:WARN ] No transaction in place, so not suspending. 2009-11-15 15:24:22,390 (default-invoker-Thread-8) [PersistedServiceJob.java:178:INFO ] org.ofbiz.service.job.PersistedServiceJob@196e934[8004] -- Next runtime: Mon Nov 16 00:00:00 EET 2009 2009-11-15 15:24:27,125 (main) [ GenericDispatcher.java:61 :INFO ] Creating new dispatcher [ap] (main) 2009-11-15 15:24:27,171 (main) [ ControlServlet.java:69 :INFO ] LOADING WEBAPP [ap] Open For Business - Account Payable, located at C:\ofbiz-trunk\applications\accounting\webapp\ap\ 2009-11-15 15:24:27,921 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.0s, 5 requests, 2 views in file:/C:/ofbiz-trunk/applications/commonext/webapp/WEB-INF/controller.xml 2009-11-15 15:24:27,937 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.078s, 474 requests, 228 views in file:/C:/ofbiz-trunk/applications/accounting/webapp/accounting/WEB-INF/controller.xml 2009-11-15 15:24:27,937 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.703s, 490 requests, 239 views in jndi:/0.0.0.0/ap/WEB-INF/controller.xml 2009-11-15 15:24:29,437 (org.ofbiz.service.job.JobPoller@1b583cf) [ GenericDAO.java:758:INFO ] Ran query in 266 milli-seconds: SELECT JOB_ID, JOB_NAME, RUN_TIME, POOL_ID, STATUS_ID, PARENT_JOB_ID, PREVIOUS_JOB_ID, SERVICE_NAME, LOADER_NAME, MAX_RETRY, AUTH_USER_LOGIN_ID, RUN_AS_USER, RUNTIME_DATA_ID, RECURRENCE_INFO_ID, TEMP_EXPR_ID, CURRENT_RECURRENCE_COUNT, MAX_RECURRENCE_COUNT, RUN_BY_INSTANCE_ID, START_DATE_TIME, FINISH_DATE_TIME, CANCEL_DATE_TIME, LAST_UPDATED_STAMP, LAST_UPDATED_TX_STAMP, CREATED_STAMP, CREATED_TX_STAMP FROM OFBIZ.JOB_SANDBOX WHERE (RUN_BY_INSTANCE_ID = ? AND STATUS_ID = ?) ORDER BY RUN_TIME ASC 2009-11-15 15:24:29,593 (default-invoker-Thread-8) [PaymentGatewayServices.java:2660:INFO ] Processing failed order re-auth(s) 2009-11-15 15:24:29,593 (default-invoker-Thread-8) [ ServiceDispatcher.java:588:INFO ] Sync service [JobDispatcher/retryFailedAuthNsfs] finished in [6609] milliseconds 2009-11-15 15:24:29,640 (default-invoker-Thread-8) [ JobInvoker.java:232:INFO ] Invoker [default-invoker-Thread-8] received job [Re-Try Failed Auths] from poller [org.ofbiz.service.job.JobPoller@1b583cf] 2009-11-15 15:24:29,718 (default-invoker-Thread-8) [PersistedServiceJob.java:178:INFO ] org.ofbiz.service.job.PersistedServiceJob@194d29e[8001] -- Next runtime: Mon Nov 16 00:00:00 EET 2009 2009-11-15 15:24:30,531 (default-invoker-Thread-8) [PaymentGatewayServices.java:2611:INFO ] Processing failed order re-auth(s) 2009-11-15 15:24:30,531 (default-invoker-Thread-8) [ ServiceDispatcher.java:588:INFO ] Sync service [JobDispatcher/retryFailedAuths] finished in [266] milliseconds 2009-11-15 15:24:30,531 (default-invoker-Thread-8) [ JobInvoker.java:232:INFO ] Invoker [default-invoker-Thread-8] received job [Extend expired Subscriptions] from poller [org.ofbiz.service.job.JobPoller@1b583cf] 2009-11-15 15:24:31,171 (default-invoker-Thread-8) [PersistedServiceJob.java:178:INFO ] org.ofbiz.service.job.PersistedServiceJob@17a406f[8005] -- Next runtime: Mon Nov 16 00:00:00 EET 2009 2009-11-15 15:24:33,281 (default-invoker-Thread-10) [ ListFinder.java:149:WARN ] In find entity by condition cannot have use-cache set to true condition select use-iterator for the output type. Using cache and ignoring use-iterator setting. 2009-11-15 15:24:33,296 (default-invoker-Thread-7) [ Log.java:111:INFO ] [CommonPermissionServices.xml#genericBasePermissionCheck] Checking for primary permission ACCOUNTING_CREATE 2009-11-15 15:24:33,546 (default-invoker-Thread-7) [ ServiceEcaRule.java:134:INFO ] For Service ECA [fixedAssetPermissionCheck] on [return] got false for condition: [hasPermission][equals][false][true][Boolean] 2009-11-15 15:24:33,546 (default-invoker-Thread-7) [ ServiceDispatcher.java:588:INFO ] Sync service [JobDispatcher/fixedAssetPermissionCheck] finished in [15796] milliseconds 2009-11-15 15:24:40,953 (default-invoker-Thread-8) [ ServiceDispatcher.java:588:INFO ] Sync service [JobDispatcher/runSubscriptionAutoReorders] finished in [9766] milliseconds 2009-11-15 15:24:44,937 (default-invoker-Thread-8) [ JobInvoker.java:232:INFO ] Invoker [default-invoker-Thread-8] received job [Run Auto-Reorders] from poller [org.ofbiz.service.job.JobPoller@1b583cf] 2009-11-15 15:24:45,515 (default-invoker-Thread-8) [PersistedServiceJob.java:178:INFO ] org.ofbiz.service.job.PersistedServiceJob@64ab6c[8003] -- Next runtime: Mon Nov 16 00:00:00 EET 2009 2009-11-15 15:24:47,437 (main) [ ControlServlet.java:69 :INFO ] LOADING WEBAPP [ecommerce] Open For Business - demostore, located at C:\ofbiz-trunk\specialpurpose\ecommerce\webapp\ecommerce\ 2009-11-15 15:24:47,968 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.484s, 275 requests, 91 views in jndi:/0.0.0.0/ecommerce/WEB-INF/controller.xml 2009-11-15 15:24:49,046 (default-invoker-Thread-11) [ ServiceDispatcher.java:588:INFO ] Sync service [JobDispatcher/sendEmailDated] finished in [32390] milliseconds 2009-11-15 15:24:49,078 (default-invoker-Thread-11) [ JobInvoker.java:232:INFO ] Invoker [default-invoker-Thread-11] received job [Generate Invoice template] from poller [org.ofbiz.service.job.JobPoller@1b583cf] 2009-11-15 15:24:52,656 (default-invoker-Thread-11) [PersistedServiceJob.java:135:WARN ] Persisted Job [8500] references a RecurrenceInfo, recommend using TemporalExpression instead 2009-11-15 15:24:52,656 (default-invoker-Thread-7) [ ServiceDispatcher.java:588:INFO ] Sync service [JobDispatcher/createMaintsFromTimeInterval] finished in [34922] milliseconds 2009-11-15 15:24:54,765 (default-invoker-Thread-11) [PersistedServiceJob.java:178:INFO ] org.ofbiz.service.job.PersistedServiceJob@12c81d9[8500] -- Next runtime: Mon Dec 14 22:00:00 EET 2009 2009-11-15 15:25:01,781 (main) [ GenericDispatcher.java:61 :INFO ] Creating new dispatcher [projectmgr] (main) 2009-11-15 15:25:01,906 (default-invoker-Thread-10) [ ServiceDispatcher.java:588:INFO ] Sync service [JobDispatcher/getProductInventoryAvailable] finished in [18906] milliseconds 2009-11-15 15:25:01,921 (main) [ ControlServlet.java:69 :INFO ] LOADING WEBAPP [projectmgr] Open For Business - Project Management, located at C:\ofbiz-trunk\specialpurpose\projectmgr\webapp\projectmgr\ 2009-11-15 15:25:04,203 (default-invoker-Thread-10) [ UtilProperties.java:917:INFO ] ResourceBundle DefaultMessages (en) created in 1.047s with 11 properties 2009-11-15 15:25:05,390 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.0s, 18 requests, 14 views in file:/C:/ofbiz-trunk/framework/common/webcommon/WEB-INF/common-controller.xml 2009-11-15 15:25:05,406 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.0s, 5 requests, 2 views in file:/C:/ofbiz-trunk/applications/commonext/webapp/WEB-INF/controller.xml 2009-11-15 15:25:06,015 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 2.875s, 466 requests, 151 views in file:/C:/ofbiz-trunk/applications/product/webapp/catalog/WEB-INF/controller.xml 2009-11-15 15:25:08,390 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.5s, 377 requests, 162 views in file:/C:/ofbiz-trunk/applications/content/webapp/content/WEB-INF/controller.xml 2009-11-15 15:25:08,890 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 2.187s, 699 requests, 290 views in file:/C:/ofbiz-trunk/applications/order/webapp/ordermgr/WEB-INF/controller.xml 2009-11-15 15:25:13,734 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.547s, 474 requests, 228 views in file:/C:/ofbiz-trunk/applications/accounting/webapp/accounting/WEB-INF/controller.xml 2009-11-15 15:25:17,625 (default-invoker-Thread-10) [ ServiceDispatcher.java:588:INFO ] Sync service [JobDispatcher/checkProductFacilityRelatedPermission] finished in [12204] milliseconds 2009-11-15 15:25:18,296 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.0s, 18 requests, 14 views in file:/C:/ofbiz-trunk/framework/common/webcommon/WEB-INF/common-controller.xml 2009-11-15 15:25:18,312 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.0s, 5 requests, 2 views in file:/C:/ofbiz-trunk/applications/commonext/webapp/WEB-INF/controller.xml 2009-11-15 15:25:18,828 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.0s, 33 requests, 21 views in file:/C:/ofbiz-trunk/applications/workeffort/webapp/workeffort/WEB-INF/icalendar-controller.xml 2009-11-15 15:25:18,828 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.563s, 175 requests, 86 views in file:/C:/ofbiz-trunk/applications/workeffort/webapp/workeffort/WEB-INF/controller.xml 2009-11-15 15:25:21,625 (default-invoker-Thread-8) [ GenericDAO.java:758:INFO ] Ran query in 13735 milli-seconds: SELECT SHOPPING_LIST_ID, SHOPPING_LIST_TYPE_ID, PARENT_SHOPPING_LIST_ID, PRODUCT_STORE_ID, VISITOR_ID, PARTY_ID, LIST_NAME, DESCRIPTION, IS_PUBLIC, IS_ACTIVE, CURRENCY_UOM, SHIPMENT_METHOD_TYPE_ID, CARRIER_PARTY_ID, CARRIER_ROLE_TYPE_ID, CONTACT_MECH_ID, PAYMENT_METHOD_ID, RECURRENCE_INFO_ID, LAST_ORDERED_DATE, LAST_ADMIN_MODIFIED, PRODUCT_PROMO_CODE_ID, LAST_UPDATED_STAMP, LAST_UPDATED_TX_STAMP, CREATED_STAMP, CREATED_TX_STAMP FROM OFBIZ.SHOPPING_LIST WHERE (SHOPPING_LIST_TYPE_ID = ? AND IS_ACTIVE = ?) ORDER BY LAST_ORDERED_DATE DESC 2009-11-15 15:25:21,625 (default-invoker-Thread-8) [ ServiceDispatcher.java:588:INFO ] Sync service [JobDispatcher/runShoppingListAutoReorder] finished in [36110] milliseconds 2009-11-15 15:25:22,140 (org.ofbiz.service.job.JobPoller@1b583cf) [ GenericDAO.java:758:INFO ] Ran query in 9453 milli-seconds: SELECT JOB_ID, JOB_NAME, RUN_TIME, POOL_ID, STATUS_ID, PARENT_JOB_ID, PREVIOUS_JOB_ID, SERVICE_NAME, LOADER_NAME, MAX_RETRY, AUTH_USER_LOGIN_ID, RUN_AS_USER, RUNTIME_DATA_ID, RECURRENCE_INFO_ID, TEMP_EXPR_ID, CURRENT_RECURRENCE_COUNT, MAX_RECURRENCE_COUNT, RUN_BY_INSTANCE_ID, START_DATE_TIME, FINISH_DATE_TIME, CANCEL_DATE_TIME, LAST_UPDATED_STAMP, LAST_UPDATED_TX_STAMP, CREATED_STAMP, CREATED_TX_STAMP FROM OFBIZ.JOB_SANDBOX WHERE (RUN_BY_INSTANCE_ID = ? AND STATUS_ID = ?) ORDER BY RUN_TIME ASC 2009-11-15 15:25:28,093 (default-invoker-Thread-9) [ TransactionUtil.java:797:WARN ] ---- exception report ---------------------------------------------------------- WARNING: In getSetRollbackOnlyCause no stack placeholder was in place, here is the current location: Exception: java.lang.Exception Message: Current Stack Trace ---- stack trace --------------------------------------------------------------- java.lang.Exception: Current Stack Trace org.ofbiz.entity.transaction.TransactionUtil.getSetRollbackOnlyCause(TransactionUtil.java:796) org.ofbiz.entity.transaction.TransactionUtil.commit(TransactionUtil.java:271) org.ofbiz.entity.transaction.TransactionUtil.commit(TransactionUtil.java:244) org.ofbiz.service.semaphore.ServiceSemaphore.dbWrite(ServiceSemaphore.java:179) org.ofbiz.service.semaphore.ServiceSemaphore.release(ServiceSemaphore.java:79) org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:566) org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:223) org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:159) org.ofbiz.service.job.GenericServiceJob.exec(GenericServiceJob.java:70) org.ofbiz.service.job.JobInvoker.run(JobInvoker.java:242) java.lang.Thread.run(Unknown Source) -------------------------------------------------------------------------------- 2009-11-15 15:25:28,093 (default-invoker-Thread-9) [ ServiceSemaphore.java:181:ERROR] ---- exception report ---------------------------------------------------------- Exception: org.ofbiz.entity.transaction.GenericTransactionException Message: Roll back error (with no rollbackOnly cause found), could not commit transaction, was rolled back instead: javax.transaction.RollbackException: Transaction timeout (Transaction timeout) ---- cause --------------------------------------------------------------------- Exception: javax.transaction.RollbackException Message: Transaction timeout ---- stack trace --------------------------------------------------------------- javax.transaction.RollbackException: Transaction timeout org.apache.geronimo.transaction.manager.TransactionImpl.commit(TransactionImpl.java:269) org.apache.geronimo.transaction.manager.TransactionManagerImpl.commit(TransactionManagerImpl.java:245) org.ofbiz.entity.transaction.TransactionUtil.commit(TransactionUtil.java:258) org.ofbiz.entity.transaction.TransactionUtil.commit(TransactionUtil.java:244) org.ofbiz.service.semaphore.ServiceSemaphore.dbWrite(ServiceSemaphore.java:179) org.ofbiz.service.semaphore.ServiceSemaphore.release(ServiceSemaphore.java:79) org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:566) org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:223) org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:159) org.ofbiz.service.job.GenericServiceJob.exec(GenericServiceJob.java:70) org.ofbiz.service.job.JobInvoker.run(JobInvoker.java:242) java.lang.Thread.run(Unknown Source) -------------------------------------------------------------------------------- 2009-11-15 15:25:28,765 (default-invoker-Thread-9) [ GenericServiceJob.java:117:ERROR] ---- error report -------------------------------------------------------------- Async-Service failed. Exception: java.lang.OutOfMemoryError Message: Java heap space ---- stack trace --------------------------------------------------------------- java.lang.OutOfMemoryError: Java heap space java.util.jar.Manifest$FastInputStream.(Unknown Source) java.util.jar.Manifest$FastInputStream.(Unknown Source) java.util.jar.Manifest.read(Unknown Source) java.util.jar.Manifest.(Unknown Source) java.util.jar.JarFile.getManifestFromReference(Unknown Source) java.util.jar.JarFile.getManifest(Unknown Source) sun.misc.URLClassPath$JarLoader$2.getManifest(Unknown Source) java.net.URLClassLoader.defineClass(Unknown Source) java.net.URLClassLoader.access$000(Unknown Source) java.net.URLClassLoader$1.run(Unknown Source) java.security.AccessController.doPrivileged(Native Method) java.net.URLClassLoader.findClass(Unknown Source) java.lang.ClassLoader.loadClass(Unknown Source) java.lang.ClassLoader.loadClass(Unknown Source) java.lang.ClassLoader.loadClassInternal(Unknown Source) org.apache.xml.dtm.SecuritySupport12.getSystemProperty(SecuritySupport12.java:88) org.apache.xml.dtm.ObjectFactory.lookUpFactoryClassName(ObjectFactory.java:265) org.apache.xml.dtm.ObjectFactory.lookUpFactoryClass(ObjectFactory.java:208) org.apache.xml.dtm.ObjectFactory.createObject(ObjectFactory.java:133) org.apache.xml.dtm.ObjectFactory.createObject(ObjectFactory.java:103) org.apache.xml.dtm.DTMManager.newInstance(DTMManager.java:137) org.apache.xpath.XPathContext.(XPathContext.java:102) org.apache.xpath.XPathContext.(XPathContext.java:349) org.apache.xpath.XPathContext.(XPathContext.java:337) org.apache.xalan.transformer.TransformerImpl.(TransformerImpl.java:397) org.apache.xalan.templates.StylesheetRoot.newTransformer(StylesheetRoot.java:200) org.apache.xalan.processor.TransformerFactoryImpl.newTransformer(TransformerFactoryImpl.java:784) org.ofbiz.base.util.UtilXml.createOutputTransformer(UtilXml.java:194) org.ofbiz.base.util.UtilXml.writeXmlDocument(UtilXml.java:225) org.ofbiz.base.util.UtilXml.writeXmlDocument(UtilXml.java:270) org.ofbiz.base.util.UtilXml.writeXmlDocument(UtilXml.java:237) org.ofbiz.entity.serialize.XmlSerializer.serialize(XmlSerializer.java:74) -------------------------------------------------------------------------------- 2009-11-15 15:25:28,765 (default-invoker-Thread-9) [ TransactionUtil.java:702:WARN ] ---- exception report ---------------------------------------------------------- WARNING: In setTransactionBeginStack a stack placeholder was already in place, here is where the transaction began: Exception: java.lang.Exception Message: Tx Stack Placeholder ---- stack trace --------------------------------------------------------------- java.lang.Exception: Tx Stack Placeholder org.ofbiz.entity.transaction.TransactionUtil.setTransactionBeginStack(TransactionUtil.java:696) org.ofbiz.entity.transaction.TransactionUtil.begin(TransactionUtil.java:184) org.ofbiz.entity.transaction.TransactionUtil.begin(TransactionUtil.java:130) org.ofbiz.service.semaphore.ServiceSemaphore.dbWrite(ServiceSemaphore.java:151) org.ofbiz.service.semaphore.ServiceSemaphore.release(ServiceSemaphore.java:79) org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:566) org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:223) org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:159) org.ofbiz.service.job.GenericServiceJob.exec(GenericServiceJob.java:70) org.ofbiz.service.job.JobInvoker.run(JobInvoker.java:242) java.lang.Thread.run(Unknown Source) -------------------------------------------------------------------------------- 2009-11-15 15:25:29,390 (default-invoker-Thread-9) [ TransactionUtil.java:704:WARN ] ---- exception report ---------------------------------------------------------- WARNING: In setTransactionBeginStack a stack placeholder was already in place, here is the current location: Exception: java.lang.Exception Message: Current Stack Trace ---- stack trace --------------------------------------------------------------- java.lang.Exception: Current Stack Trace org.ofbiz.entity.transaction.TransactionUtil.setTransactionBeginStack(TransactionUtil.java:703) org.ofbiz.entity.transaction.TransactionUtil.setTransactionBeginStack(TransactionUtil.java:697) org.ofbiz.entity.transaction.TransactionUtil.begin(TransactionUtil.java:184) org.ofbiz.entity.transaction.TransactionUtil.begin(TransactionUtil.java:130) org.ofbiz.entity.GenericDelegator.findOne(GenericDelegator.java:1498) org.ofbiz.entity.GenericDelegator.findOne(GenericDelegator.java:1478) org.ofbiz.service.job.PersistedServiceJob.getJob(PersistedServiceJob.java:316) org.ofbiz.service.job.PersistedServiceJob.failed(PersistedServiceJob.java:231) org.ofbiz.service.job.GenericServiceJob.exec(GenericServiceJob.java:90) org.ofbiz.service.job.JobInvoker.run(JobInvoker.java:242) java.lang.Thread.run(Unknown Source) -------------------------------------------------------------------------------- 2009-11-15 15:25:29,515 (main) [ ApplicationContext.java:660:ERROR] StandardWrapper.Throwable java.lang.OutOfMemoryError: Java heap space at org.apache.xerces.dom.DeferredDocumentImpl.createChunk(Unknown Source) at org.apache.xerces.dom.DeferredDocumentImpl.ensureCapacity(Unknown Source) at org.apache.xerces.dom.DeferredDocumentImpl.createNode(Unknown Source) at org.apache.xerces.dom.DeferredDocumentImpl.createDeferredAttribute(Unknown Source) at org.apache.xerces.dom.DeferredDocumentImpl.setDeferredAttribute(Unknown Source) at org.apache.xerces.parsers.AbstractDOMParser.startElement(Unknown Source) at org.apache.xerces.parsers.AbstractDOMParser.emptyElement(Unknown Source) at org.apache.xerces.impl.xs.XMLSchemaValidator.emptyElement(Unknown Source) at org.apache.xerces.impl.XMLNSDocumentScannerImpl.scanStartElement(Unknown Source) at org.apache.xerces.impl.XMLDocumentFragmentScannerImpl$FragmentContentDispatcher.dispatch(Unknown Source) at org.apache.xerces.impl.XMLDocumentFragmentScannerImpl.scanDocument(Unknown Source) at org.apache.xerces.parsers.XML11Configuration.parse(Unknown Source) at org.apache.xerces.parsers.XML11Configuration.parse(Unknown Source) at org.apache.xerces.parsers.XMLParser.parse(Unknown Source) at org.apache.xerces.parsers.DOMParser.parse(Unknown Source) at org.apache.xerces.jaxp.DocumentBuilderImpl.parse(Unknown Source) at javax.xml.parsers.DocumentBuilder.parse(Unknown Source) at org.ofbiz.base.util.UtilXml.readXmlDocument(UtilXml.java:416) at org.ofbiz.base.util.UtilXml.readXmlDocument(UtilXml.java:335) at org.ofbiz.webapp.control.ConfigXMLReader.loadDocument(ConfigXMLReader.java:346) at org.ofbiz.webapp.control.ConfigXMLReader$ControllerConfig.(ConfigXMLReader.java:105) at org.ofbiz.webapp.control.ConfigXMLReader.getControllerConfig(ConfigXMLReader.java:73) at org.ofbiz.webapp.control.ConfigXMLReader$ControllerConfig.loadIncludes(ConfigXMLReader.java:150) at org.ofbiz.webapp.control.ConfigXMLReader$ControllerConfig.(ConfigXMLReader.java:109) at org.ofbiz.webapp.control.ConfigXMLReader.getControllerConfig(ConfigXMLReader.java:73) at org.ofbiz.webapp.control.ConfigXMLReader$ControllerConfig.loadIncludes(ConfigXMLReader.java:150) at org.ofbiz.webapp.control.ConfigXMLReader$ControllerConfig.(ConfigXMLReader.java:109) at org.ofbiz.webapp.control.ConfigXMLReader.getControllerConfig(ConfigXMLReader.java:73) at org.ofbiz.webapp.control.ConfigXMLReader$ControllerConfig.loadIncludes(ConfigXMLReader.java:150) at org.ofbiz.webapp.control.ConfigXMLReader$ControllerConfig.(ConfigXMLReader.java:109) at org.ofbiz.webapp.control.ConfigXMLReader.getControllerConfig(ConfigXMLReader.java:73) at org.ofbiz.webapp.control.RequestHandler.init(RequestHandler.java:90) 2009-11-15 15:25:29,515 (main) [ StandardContext.java:4060:ERROR] Servlet /projectmgr threw load() exception java.lang.OutOfMemoryError: Java heap space at org.apache.xerces.dom.DeferredDocumentImpl.createChunk(Unknown Source) at org.apache.xerces.dom.DeferredDocumentImpl.ensureCapacity(Unknown Source) at org.apache.xerces.dom.DeferredDocumentImpl.createNode(Unknown Source) at org.apache.xerces.dom.DeferredDocumentImpl.createDeferredAttribute(Unknown Source) at org.apache.xerces.dom.DeferredDocumentImpl.setDeferredAttribute(Unknown Source) at org.apache.xerces.parsers.AbstractDOMParser.startElement(Unknown Source) at org.apache.xerces.parsers.AbstractDOMParser.emptyElement(Unknown Source) at org.apache.xerces.impl.xs.XMLSchemaValidator.emptyElement(Unknown Source) at org.apache.xerces.impl.XMLNSDocumentScannerImpl.scanStartElement(Unknown Source) at org.apache.xerces.impl.XMLDocumentFragmentScannerImpl$FragmentContentDispatcher.dispatch(Unknown Source) at org.apache.xerces.impl.XMLDocumentFragmentScannerImpl.scanDocument(Unknown Source) at org.apache.xerces.parsers.XML11Configuration.parse(Unknown Source) at org.apache.xerces.parsers.XML11Configuration.parse(Unknown Source) at org.apache.xerces.parsers.XMLParser.parse(Unknown Source) at org.apache.xerces.parsers.DOMParser.parse(Unknown Source) at org.apache.xerces.jaxp.DocumentBuilderImpl.parse(Unknown Source) at javax.xml.parsers.DocumentBuilder.parse(Unknown Source) at org.ofbiz.base.util.UtilXml.readXmlDocument(UtilXml.java:416) at org.ofbiz.base.util.UtilXml.readXmlDocument(UtilXml.java:335) at org.ofbiz.webapp.control.ConfigXMLReader.loadDocument(ConfigXMLReader.java:346) at org.ofbiz.webapp.control.ConfigXMLReader$ControllerConfig.(ConfigXMLReader.java:105) at org.ofbiz.webapp.control.ConfigXMLReader.getControllerConfig(ConfigXMLReader.java:73) at org.ofbiz.webapp.control.ConfigXMLReader$ControllerConfig.loadIncludes(ConfigXMLReader.java:150) at org.ofbiz.webapp.control.ConfigXMLReader$ControllerConfig.(ConfigXMLReader.java:109) at org.ofbiz.webapp.control.ConfigXMLReader.getControllerConfig(ConfigXMLReader.java:73) at org.ofbiz.webapp.control.ConfigXMLReader$ControllerConfig.loadIncludes(ConfigXMLReader.java:150) at org.ofbiz.webapp.control.ConfigXMLReader$ControllerConfig.(ConfigXMLReader.java:109) at org.ofbiz.webapp.control.ConfigXMLReader.getControllerConfig(ConfigXMLReader.java:73) at org.ofbiz.webapp.control.ConfigXMLReader$ControllerConfig.loadIncludes(ConfigXMLReader.java:150) at org.ofbiz.webapp.control.ConfigXMLReader$ControllerConfig.(ConfigXMLReader.java:109) at org.ofbiz.webapp.control.ConfigXMLReader.getControllerConfig(ConfigXMLReader.java:73) at org.ofbiz.webapp.control.RequestHandler.init(RequestHandler.java:90) 2009-11-15 15:25:35,875 (default-invoker-Thread-11) [ ServiceDispatcher.java:588:INFO ] Sync service [JobDispatcher/autoGenerateInvoiceFromExistingInvoice] finished in [39157] milliseconds 2009-11-15 15:25:43,546 (main) [ GenericDispatcher.java:61 :INFO ] Creating new dispatcher [exampleext] (main) 2009-11-15 15:25:43,546 (main) [ ControlServlet.java:69 :INFO ] LOADING WEBAPP [exampleext] Open For Business - Extended Example Application, located at C:\ofbiz-trunk\framework\exampleext\webapp\exampleext\ 2009-11-15 15:25:43,640 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.0s, 18 requests, 14 views in file:/C:/ofbiz-trunk/framework/common/webcommon/WEB-INF/common-controller.xml 2009-11-15 15:25:44,156 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.0s, 18 requests, 5 views in file:/C:/ofbiz-trunk/framework/common/webcommon/WEB-INF/portal-controller.xml 2009-11-15 15:25:44,156 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.531s, 68 requests, 35 views in file:/C:/ofbiz-trunk/framework/example/webapp/example/WEB-INF/controller.xml 2009-11-15 15:25:44,156 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.547s, 68 requests, 35 views in jndi:/0.0.0.0/exampleext/WEB-INF/controller.xml 2009-11-15 15:25:44,203 (default-invoker-Thread-10) [ ServiceDispatcher.java:588:INFO ] Sync service [JobDispatcher/updateProductFacility] finished in [38782] milliseconds 2009-11-15 15:25:46,875 (default-invoker-Thread-10) [ ServiceDispatcher.java:588:INFO ] Sync service [JobDispatcher/getProductInventoryAvailable] finished in [2672] milliseconds 2009-11-15 15:25:50,015 (default-invoker-Thread-10) [ ServiceDispatcher.java:588:INFO ] Sync service [JobDispatcher/checkProductFacilityRelatedPermission] finished in [3140] milliseconds 2009-11-15 15:25:50,015 (default-invoker-Thread-10) [ ServiceDispatcher.java:588:INFO ] Sync service [JobDispatcher/updateProductFacility] finished in [3140] milliseconds 2009-11-15 15:25:50,562 (default-invoker-Thread-10) [ ServiceDispatcher.java:588:INFO ] Sync service [JobDispatcher/getProductInventoryAvailable] finished in [547] milliseconds 2009-11-15 15:25:51,140 (default-invoker-Thread-10) [ ServiceDispatcher.java:588:INFO ] Sync service [JobDispatcher/checkProductFacilityRelatedPermission] finished in [578] milliseconds 2009-11-15 15:25:51,140 (default-invoker-Thread-10) [ ServiceDispatcher.java:588:INFO ] Sync service [JobDispatcher/updateProductFacility] finished in [578] milliseconds 2009-11-15 15:25:51,203 (default-invoker-Thread-10) [ ServiceDispatcher.java:588:INFO ] Sync service [JobDispatcher/updateProductFacility] finished in [63] milliseconds 2009-11-15 15:25:51,765 (default-invoker-Thread-10) [ ServiceDispatcher.java:588:INFO ] Sync service [JobDispatcher/checkProductFacilityRelatedPermission] finished in [562] milliseconds 2009-11-15 15:25:51,765 (default-invoker-Thread-10) [ ServiceDispatcher.java:588:INFO ] Sync service [JobDispatcher/updateProductFacility] finished in [562] milliseconds 2009-11-15 15:25:52,359 (default-invoker-Thread-10) [ ServiceDispatcher.java:588:INFO ] Sync service [JobDispatcher/checkProductFacilityRelatedPermission] finished in [547] milliseconds 2009-11-15 15:25:52,359 (default-invoker-Thread-10) [ ServiceDispatcher.java:588:INFO ] Sync service [JobDispatcher/updateProductFacility] finished in [547] milliseconds 2009-11-15 15:25:54,968 (default-invoker-Thread-10) [ ServiceDispatcher.java:588:INFO ] Sync service [JobDispatcher/checkProductFacilityRelatedPermission] finished in [2078] milliseconds 2009-11-15 15:25:54,968 (default-invoker-Thread-10) [ ServiceDispatcher.java:588:INFO ] Sync service [JobDispatcher/updateProductFacility] finished in [2078] milliseconds 2009-11-15 15:25:56,000 (default-invoker-Thread-10) [ ServiceDispatcher.java:588:INFO ] Sync service [JobDispatcher/getProductInventoryAvailable] finished in [1032] milliseconds 2009-11-15 15:25:56,531 (default-invoker-Thread-10) [ ServiceDispatcher.java:588:INFO ] Sync service [JobDispatcher/checkProductFacilityRelatedPermission] finished in [531] milliseconds 2009-11-15 15:25:56,562 (default-invoker-Thread-10) [ ServiceDispatcher.java:588:INFO ] Sync service [JobDispatcher/updateProductFacility] finished in [562] milliseconds 2009-11-15 15:25:58,125 (default-invoker-Thread-10) [ ServiceDispatcher.java:588:INFO ] Sync service [JobDispatcher/checkProductFacilityRelatedPermission] finished in [1563] milliseconds 2009-11-15 15:26:02,890 (org.ofbiz.service.job.JobPoller@1b583cf) [ GenericDAO.java:758:INFO ] Ran query in 2656 milli-seconds: SELECT JOB_ID, JOB_NAME, RUN_TIME, POOL_ID, STATUS_ID, PARENT_JOB_ID, PREVIOUS_JOB_ID, SERVICE_NAME, LOADER_NAME, MAX_RETRY, AUTH_USER_LOGIN_ID, RUN_AS_USER, RUNTIME_DATA_ID, RECURRENCE_INFO_ID, TEMP_EXPR_ID, CURRENT_RECURRENCE_COUNT, MAX_RECURRENCE_COUNT, RUN_BY_INSTANCE_ID, START_DATE_TIME, FINISH_DATE_TIME, CANCEL_DATE_TIME, LAST_UPDATED_STAMP, LAST_UPDATED_TX_STAMP, CREATED_STAMP, CREATED_TX_STAMP FROM OFBIZ.JOB_SANDBOX WHERE (RUN_BY_INSTANCE_ID = ? AND STATUS_ID = ?) ORDER BY RUN_TIME ASC 2009-11-15 15:26:04,984 (default-invoker-Thread-10) [ ServiceDispatcher.java:588:INFO ] Sync service [JobDispatcher/updateProductFacility] finished in [8422] milliseconds 2009-11-15 15:26:05,562 (default-invoker-Thread-10) [ ServiceDispatcher.java:588:INFO ] Sync service [JobDispatcher/getProductInventoryAvailable] finished in [578] milliseconds 2009-11-15 15:26:08,265 (default-invoker-Thread-10) [ ServiceDispatcher.java:588:INFO ] Sync service [JobDispatcher/checkProductFacilityRelatedPermission] finished in [2703] milliseconds 2009-11-15 15:26:08,796 (default-invoker-Thread-10) [ ServiceDispatcher.java:588:INFO ] Sync service [JobDispatcher/updateProductFacility] finished in [3234] milliseconds 2009-11-15 15:26:10,921 (default-invoker-Thread-10) [ TransactionUtil.java:797:WARN ] ---- exception report ---------------------------------------------------------- WARNING: In getSetRollbackOnlyCause no stack placeholder was in place, here is the current location: Exception: java.lang.Exception Message: Current Stack Trace ---- stack trace --------------------------------------------------------------- java.lang.Exception: Current Stack Trace org.ofbiz.entity.transaction.TransactionUtil.getSetRollbackOnlyCause(TransactionUtil.java:796) org.ofbiz.entity.transaction.TransactionUtil.commit(TransactionUtil.java:271) org.ofbiz.entity.transaction.TransactionUtil.commit(TransactionUtil.java:244) org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:543) org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:223) org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:159) org.ofbiz.service.job.GenericServiceJob.exec(GenericServiceJob.java:70) org.ofbiz.service.job.JobInvoker.run(JobInvoker.java:242) java.lang.Thread.run(Unknown Source) -------------------------------------------------------------------------------- 2009-11-15 15:26:10,921 (default-invoker-Thread-10) [ ServiceDispatcher.java:546:ERROR] ---- exception report ---------------------------------------------------------- Could not commit transaction for service [setLastInventoryCount] call Exception: org.ofbiz.entity.transaction.GenericTransactionException Message: Roll back error (with no rollbackOnly cause found), could not commit transaction, was rolled back instead: javax.transaction.RollbackException: Transaction timeout (Transaction timeout) ---- cause --------------------------------------------------------------------- Exception: javax.transaction.RollbackException Message: Transaction timeout ---- stack trace --------------------------------------------------------------- javax.transaction.RollbackException: Transaction timeout org.apache.geronimo.transaction.manager.TransactionImpl.commit(TransactionImpl.java:269) org.apache.geronimo.transaction.manager.TransactionManagerImpl.commit(TransactionManagerImpl.java:245) org.ofbiz.entity.transaction.TransactionUtil.commit(TransactionUtil.java:258) org.ofbiz.entity.transaction.TransactionUtil.commit(TransactionUtil.java:244) org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:543) org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:223) org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:159) org.ofbiz.service.job.GenericServiceJob.exec(GenericServiceJob.java:70) org.ofbiz.service.job.JobInvoker.run(JobInvoker.java:242) java.lang.Thread.run(Unknown Source) -------------------------------------------------------------------------------- 2009-11-15 15:26:10,921 (default-invoker-Thread-10) [ GenericServiceJob.java:117:ERROR] ---- exception report ---------------------------------------------------------- Async-Service failed. Exception: org.ofbiz.service.GenericServiceException Message: Could not commit transaction for service [setLastInventoryCount] call: Roll back error (with no rollbackOnly cause found), could not commit transaction, was rolled back instead: javax.transaction.RollbackException: Transaction timeout (Transaction timeout) ---- stack trace --------------------------------------------------------------- org.ofbiz.service.GenericServiceException: Could not commit transaction for service [setLastInventoryCount] call: Roll back error (with no rollbackOnly cause found), could not commit transaction, was rolled back instead: javax.transaction.RollbackException: Transaction timeout (Transaction timeout) org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:550) org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:223) org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:159) org.ofbiz.service.job.GenericServiceJob.exec(GenericServiceJob.java:70) org.ofbiz.service.job.JobInvoker.run(JobInvoker.java:242) java.lang.Thread.run(Unknown Source) -------------------------------------------------------------------------------- 2009-11-15 15:26:10,921 (default-invoker-Thread-10) [ TransactionUtil.java:702:WARN ] ---- exception report ---------------------------------------------------------- WARNING: In setTransactionBeginStack a stack placeholder was already in place, here is where the transaction began: Exception: java.lang.Exception Message: Tx Stack Placeholder ---- stack trace --------------------------------------------------------------- java.lang.Exception: Tx Stack Placeholder org.ofbiz.entity.transaction.TransactionUtil.setTransactionBeginStack(TransactionUtil.java:696) org.ofbiz.entity.transaction.TransactionUtil.begin(TransactionUtil.java:184) org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:312) org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:223) org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:159) org.ofbiz.service.job.GenericServiceJob.exec(GenericServiceJob.java:70) org.ofbiz.service.job.JobInvoker.run(JobInvoker.java:242) java.lang.Thread.run(Unknown Source) -------------------------------------------------------------------------------- 2009-11-15 15:26:10,921 (default-invoker-Thread-10) [ TransactionUtil.java:704:WARN ] ---- exception report ---------------------------------------------------------- WARNING: In setTransactionBeginStack a stack placeholder was already in place, here is the current location: Exception: java.lang.Exception Message: Current Stack Trace ---- stack trace --------------------------------------------------------------- java.lang.Exception: Current Stack Trace org.ofbiz.entity.transaction.TransactionUtil.setTransactionBeginStack(TransactionUtil.java:703) org.ofbiz.entity.transaction.TransactionUtil.setTransactionBeginStack(TransactionUtil.java:697) org.ofbiz.entity.transaction.TransactionUtil.begin(TransactionUtil.java:184) org.ofbiz.entity.transaction.TransactionUtil.begin(TransactionUtil.java:130) org.ofbiz.entity.GenericDelegator.findOne(GenericDelegator.java:1498) org.ofbiz.entity.GenericDelegator.findOne(GenericDelegator.java:1478) org.ofbiz.service.job.PersistedServiceJob.getJob(PersistedServiceJob.java:316) org.ofbiz.service.job.PersistedServiceJob.failed(PersistedServiceJob.java:231) org.ofbiz.service.job.GenericServiceJob.exec(GenericServiceJob.java:90) org.ofbiz.service.job.JobInvoker.run(JobInvoker.java:242) java.lang.Thread.run(Unknown Source) -------------------------------------------------------------------------------- class org.webslinger.commons.vfs.flat.FlatFileProvider 2009-11-15 15:26:48,031 (main) [ GenericDispatcher.java:61 :INFO ] Creating new dispatcher [ismgr] (main) 2009-11-15 15:26:48,031 (main) [ ControlServlet.java:69 :INFO ] LOADING WEBAPP [ismgr] Open For Business - Information Systems, located at C:\ofbiz-trunk\specialpurpose\assetmaint\webapp\ismgr\ 2009-11-15 15:26:49,640 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.0s, 18 requests, 14 views in file:/C:/ofbiz-trunk/framework/common/webcommon/WEB-INF/common-controller.xml 2009-11-15 15:26:49,656 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.0s, 5 requests, 2 views in file:/C:/ofbiz-trunk/applications/commonext/webapp/WEB-INF/controller.xml 2009-11-15 15:26:50,171 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.0s, 33 requests, 21 views in file:/C:/ofbiz-trunk/applications/workeffort/webapp/workeffort/WEB-INF/icalendar-controller.xml 2009-11-15 15:26:50,171 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 1.046s, 175 requests, 86 views in file:/C:/ofbiz-trunk/applications/workeffort/webapp/workeffort/WEB-INF/controller.xml 2009-11-15 15:26:53,203 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 2.469s, 466 requests, 151 views in file:/C:/ofbiz-trunk/applications/product/webapp/catalog/WEB-INF/controller.xml 2009-11-15 15:26:55,703 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.5s, 246 requests, 98 views in file:/C:/ofbiz-trunk/applications/product/webapp/facility/WEB-INF/controller.xml 2009-11-15 15:27:01,828 (main) [ ApplicationContext.java:660:ERROR] StandardWrapper.Throwable java.lang.OutOfMemoryError: Java heap space at org.apache.xerces.dom.DeferredDocumentImpl.getNodeObject(Unknown Source) at org.apache.xerces.dom.DeferredDocumentImpl.synchronizeChildren(Unknown Source) at org.apache.xerces.dom.DeferredElementNSImpl.synchronizeChildren(Unknown Source) at org.apache.xerces.dom.ParentNode.getFirstChild(Unknown Source) at org.ofbiz.base.util.UtilXml.firstChildElement(UtilXml.java:770) at org.ofbiz.webapp.control.ConfigXMLReader$RequestMap.(ConfigXMLReader.java:383) at org.ofbiz.webapp.control.ConfigXMLReader$ControllerConfig.loadRequestMap(ConfigXMLReader.java:243) at org.ofbiz.webapp.control.ConfigXMLReader$ControllerConfig.(ConfigXMLReader.java:112) at org.ofbiz.webapp.control.ConfigXMLReader.getControllerConfig(ConfigXMLReader.java:73) at org.ofbiz.webapp.control.ConfigXMLReader$ControllerConfig.loadIncludes(ConfigXMLReader.java:150) at org.ofbiz.webapp.control.ConfigXMLReader$ControllerConfig.(ConfigXMLReader.java:109) at org.ofbiz.webapp.control.ConfigXMLReader.getControllerConfig(ConfigXMLReader.java:73) at org.ofbiz.webapp.control.RequestHandler.init(RequestHandler.java:90) at org.ofbiz.webapp.control.RequestHandler.getRequestHandler(RequestHandler.java:74) at org.ofbiz.webapp.control.ControlServlet.getRequestHandler(ControlServlet.java:330) at org.ofbiz.webapp.control.ControlServlet.init(ControlServlet.java:74) at org.apache.catalina.core.StandardWrapper.loadServlet(StandardWrapper.java:1161) at org.apache.catalina.core.StandardWrapper.load(StandardWrapper.java:981) at org.apache.catalina.core.StandardContext.loadOnStartup(StandardContext.java:4058) at org.apache.catalina.core.StandardContext.start(StandardContext.java:4364) at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1045) at org.apache.catalina.core.StandardHost.start(StandardHost.java:719) at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1045) at org.apache.catalina.core.StandardEngine.start(StandardEngine.java:443) at org.apache.catalina.startup.Embedded.start(Embedded.java:825) at org.ofbiz.catalina.container.CatalinaContainer.start(CatalinaContainer.java:225) at org.ofbiz.base.container.ContainerLoader.start(ContainerLoader.java:100) at org.ofbiz.base.start.Start.startStartLoaders(Start.java:271) at org.ofbiz.base.start.Start.startServer(Start.java:321) at org.ofbiz.base.start.Start.start(Start.java:325) at org.ofbiz.base.start.Start.main(Start.java:410) 2009-11-15 15:27:01,843 (main) [ StandardContext.java:4060:ERROR] Servlet /ismgr threw load() exception java.lang.OutOfMemoryError: Java heap space at org.apache.xerces.dom.DeferredDocumentImpl.getNodeObject(Unknown Source) at org.apache.xerces.dom.DeferredDocumentImpl.synchronizeChildren(Unknown Source) at org.apache.xerces.dom.DeferredElementNSImpl.synchronizeChildren(Unknown Source) at org.apache.xerces.dom.ParentNode.getFirstChild(Unknown Source) at org.ofbiz.base.util.UtilXml.firstChildElement(UtilXml.java:770) at org.ofbiz.webapp.control.ConfigXMLReader$RequestMap.(ConfigXMLReader.java:383) at org.ofbiz.webapp.control.ConfigXMLReader$ControllerConfig.loadRequestMap(ConfigXMLReader.java:243) at org.ofbiz.webapp.control.ConfigXMLReader$ControllerConfig.(ConfigXMLReader.java:112) at org.ofbiz.webapp.control.ConfigXMLReader.getControllerConfig(ConfigXMLReader.java:73) at org.ofbiz.webapp.control.ConfigXMLReader$ControllerConfig.loadIncludes(ConfigXMLReader.java:150) at org.ofbiz.webapp.control.ConfigXMLReader$ControllerConfig.(ConfigXMLReader.java:109) at org.ofbiz.webapp.control.ConfigXMLReader.getControllerConfig(ConfigXMLReader.java:73) at org.ofbiz.webapp.control.RequestHandler.init(RequestHandler.java:90) at org.ofbiz.webapp.control.RequestHandler.getRequestHandler(RequestHandler.java:74) at org.ofbiz.webapp.control.ControlServlet.getRequestHandler(ControlServlet.java:330) at org.ofbiz.webapp.control.ControlServlet.init(ControlServlet.java:74) at org.apache.catalina.core.StandardWrapper.loadServlet(StandardWrapper.java:1161) at org.apache.catalina.core.StandardWrapper.load(StandardWrapper.java:981) at org.apache.catalina.core.StandardContext.loadOnStartup(StandardContext.java:4058) at org.apache.catalina.core.StandardContext.start(StandardContext.java:4364) at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1045) at org.apache.catalina.core.StandardHost.start(StandardHost.java:719) at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1045) at org.apache.catalina.core.StandardEngine.start(StandardEngine.java:443) at org.apache.catalina.startup.Embedded.start(Embedded.java:825) at org.ofbiz.catalina.container.CatalinaContainer.start(CatalinaContainer.java:225) at org.ofbiz.base.container.ContainerLoader.start(ContainerLoader.java:100) at org.ofbiz.base.start.Start.startStartLoaders(Start.java:271) at org.ofbiz.base.start.Start.startServer(Start.java:321) at org.ofbiz.base.start.Start.start(Start.java:325) at org.ofbiz.base.start.Start.main(Start.java:410) 2009-11-15 15:28:10,687 (main) [ GenericDispatcher.java:61 :INFO ] Creating new dispatcher [webtools] (main) 2009-11-15 15:28:10,703 (main) [ ControlServlet.java:69 :INFO ] LOADING WEBAPP [webtools] Open For Business - Web Tools, located at C:\ofbiz-trunk\framework\webtools\webapp\webtools\ 2009-11-15 15:28:11,406 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.0s, 18 requests, 14 views in file:/C:/ofbiz-trunk/framework/common/webcommon/WEB-INF/common-controller.xml 2009-11-15 15:28:11,421 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.0s, 5 requests, 2 views in file:/C:/ofbiz-trunk/applications/commonext/webapp/WEB-INF/controller.xml 2009-11-15 15:28:11,984 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.0s, 5 requests, 0 views in file:/C:/ofbiz-trunk/framework/common/webcommon/WEB-INF/tempexpr-controller.xml 2009-11-15 15:28:12,078 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.0s, 26 requests, 17 views in file:/C:/ofbiz-trunk/framework/testtools/webapp/testtools/WEB-INF/controller.xml 2009-11-15 15:28:12,078 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.688s, 127 requests, 78 views in jndi:/0.0.0.0/webtools/WEB-INF/controller.xml 2009-11-15 15:28:31,843 (main) [ GenericDispatcher.java:61 :INFO ] Creating new dispatcher [ical] (main) 2009-11-15 15:28:55,390 (main) [ GenericDispatcher.java:61 :INFO ] Creating new dispatcher [CmsSite] (main) 2009-11-15 15:28:55,390 (main) [ ControlServlet.java:69 :INFO ] LOADING WEBAPP [cmssite] Open For Business - CMS Site, located at C:\ofbiz-trunk\specialpurpose\cmssite\webapp\cmssite\ 2009-11-15 15:28:55,937 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.0s, 18 requests, 14 views in file:/C:/ofbiz-trunk/framework/common/webcommon/WEB-INF/common-controller.xml 2009-11-15 15:28:55,937 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.531s, 19 requests, 14 views in jndi:/0.0.0.0/cmssite/WEB-INF/controller.xml 2009-11-15 15:29:21,531 (main) [ GenericDispatcher.java:61 :INFO ] Creating new dispatcher [googlebase] (main) 2009-11-15 15:29:22,062 (main) [ ControlServlet.java:69 :INFO ] LOADING WEBAPP [googlebase] Open For Business - Google Base, located at C:\ofbiz-trunk\specialpurpose\googlebase\webapp\googlebase\ 2009-11-15 15:29:24,203 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.0s, 18 requests, 14 views in file:/C:/ofbiz-trunk/framework/common/webcommon/WEB-INF/common-controller.xml 2009-11-15 15:29:25,687 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.484s, 5 requests, 2 views in file:/C:/ofbiz-trunk/applications/commonext/webapp/WEB-INF/controller.xml 2009-11-15 15:29:35,593 (main) [ ApplicationContext.java:660:ERROR] StandardWrapper.Throwable java.lang.OutOfMemoryError: Java heap space at org.apache.xerces.dom.DeferredDocumentImpl.getNodeObject(Unknown Source) at org.apache.xerces.dom.DeferredDocumentImpl.synchronizeChildren(Unknown Source) at org.apache.xerces.dom.DeferredElementNSImpl.synchronizeChildren(Unknown Source) at org.apache.xerces.dom.ParentNode.getFirstChild(Unknown Source) at org.ofbiz.base.util.UtilXml.firstChildElement(UtilXml.java:770) at org.ofbiz.webapp.control.ConfigXMLReader$RequestMap.(ConfigXMLReader.java:383) at org.ofbiz.webapp.control.ConfigXMLReader$ControllerConfig.loadRequestMap(ConfigXMLReader.java:243) at org.ofbiz.webapp.control.ConfigXMLReader$ControllerConfig.(ConfigXMLReader.java:112) at org.ofbiz.webapp.control.ConfigXMLReader.getControllerConfig(ConfigXMLReader.java:73) at org.ofbiz.webapp.control.ConfigXMLReader$ControllerConfig.loadIncludes(ConfigXMLReader.java:150) at org.ofbiz.webapp.control.ConfigXMLReader$ControllerConfig.(ConfigXMLReader.java:109) at org.ofbiz.webapp.control.ConfigXMLReader.getControllerConfig(ConfigXMLReader.java:73) at org.ofbiz.webapp.control.RequestHandler.init(RequestHandler.java:90) at org.ofbiz.webapp.control.RequestHandler.getRequestHandler(RequestHandler.java:74) at org.ofbiz.webapp.control.ControlServlet.getRequestHandler(ControlServlet.java:330) at org.ofbiz.webapp.control.ControlServlet.init(ControlServlet.java:74) at org.apache.catalina.core.StandardWrapper.loadServlet(StandardWrapper.java:1161) at org.apache.catalina.core.StandardWrapper.load(StandardWrapper.java:981) at org.apache.catalina.core.StandardContext.loadOnStartup(StandardContext.java:4058) at org.apache.catalina.core.StandardContext.start(StandardContext.java:4364) at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1045) at org.apache.catalina.core.StandardHost.start(StandardHost.java:719) at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1045) at org.apache.catalina.core.StandardEngine.start(StandardEngine.java:443) at org.apache.catalina.startup.Embedded.start(Embedded.java:825) at org.ofbiz.catalina.container.CatalinaContainer.start(CatalinaContainer.java:225) at org.ofbiz.base.container.ContainerLoader.start(ContainerLoader.java:100) at org.ofbiz.base.start.Start.startStartLoaders(Start.java:271) at org.ofbiz.base.start.Start.startServer(Start.java:321) at org.ofbiz.base.start.Start.start(Start.java:325) at org.ofbiz.base.start.Start.main(Start.java:410) 2009-11-15 15:29:35,609 (main) [ StandardContext.java:4060:ERROR] Servlet /googlebase threw load() exception java.lang.OutOfMemoryError: Java heap space at org.apache.xerces.dom.DeferredDocumentImpl.getNodeObject(Unknown Source) at org.apache.xerces.dom.DeferredDocumentImpl.synchronizeChildren(Unknown Source) at org.apache.xerces.dom.DeferredElementNSImpl.synchronizeChildren(Unknown Source) at org.apache.xerces.dom.ParentNode.getFirstChild(Unknown Source) at org.ofbiz.base.util.UtilXml.firstChildElement(UtilXml.java:770) at org.ofbiz.webapp.control.ConfigXMLReader$RequestMap.(ConfigXMLReader.java:383) at org.ofbiz.webapp.control.ConfigXMLReader$ControllerConfig.loadRequestMap(ConfigXMLReader.java:243) at org.ofbiz.webapp.control.ConfigXMLReader$ControllerConfig.(ConfigXMLReader.java:112) at org.ofbiz.webapp.control.ConfigXMLReader.getControllerConfig(ConfigXMLReader.java:73) at org.ofbiz.webapp.control.ConfigXMLReader$ControllerConfig.loadIncludes(ConfigXMLReader.java:150) at org.ofbiz.webapp.control.ConfigXMLReader$ControllerConfig.(ConfigXMLReader.java:109) at org.ofbiz.webapp.control.ConfigXMLReader.getControllerConfig(ConfigXMLReader.java:73) at org.ofbiz.webapp.control.RequestHandler.init(RequestHandler.java:90) at org.ofbiz.webapp.control.RequestHandler.getRequestHandler(RequestHandler.java:74) at org.ofbiz.webapp.control.ControlServlet.getRequestHandler(ControlServlet.java:330) at org.ofbiz.webapp.control.ControlServlet.init(ControlServlet.java:74) at org.apache.catalina.core.StandardWrapper.loadServlet(StandardWrapper.java:1161) at org.apache.catalina.core.StandardWrapper.load(StandardWrapper.java:981) at org.apache.catalina.core.StandardContext.loadOnStartup(StandardContext.java:4058) at org.apache.catalina.core.StandardContext.start(StandardContext.java:4364) at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1045) at org.apache.catalina.core.StandardHost.start(StandardHost.java:719) at org.apache.catalina.core.ContainerBase.start(ContainerBase.java:1045) at org.apache.catalina.core.StandardEngine.start(StandardEngine.java:443) at org.apache.catalina.startup.Embedded.start(Embedded.java:825) at org.ofbiz.catalina.container.CatalinaContainer.start(CatalinaContainer.java:225) at org.ofbiz.base.container.ContainerLoader.start(ContainerLoader.java:100) at org.ofbiz.base.start.Start.startStartLoaders(Start.java:271) at org.ofbiz.base.start.Start.startServer(Start.java:321) at org.ofbiz.base.start.Start.start(Start.java:325) at org.ofbiz.base.start.Start.main(Start.java:410) 2009-11-15 15:29:50,718 (main) [ GenericDispatcher.java:61 :INFO ] Creating new dispatcher [myportal] (main) 2009-11-15 15:29:51,250 (main) [ ControlServlet.java:69 :INFO ] LOADING WEBAPP [myportal] Open For Business - My Page, located at C:\ofbiz-trunk\specialpurpose\myportal\webapp\myportal\ 2009-11-15 15:29:51,312 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.0s, 18 requests, 14 views in file:/C:/ofbiz-trunk/framework/common/webcommon/WEB-INF/common-controller.xml 2009-11-15 15:29:51,812 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.0s, 18 requests, 5 views in file:/C:/ofbiz-trunk/framework/common/webcommon/WEB-INF/portal-controller.xml 2009-11-15 15:29:51,828 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.0s, 5 requests, 2 views in file:/C:/ofbiz-trunk/applications/commonext/webapp/WEB-INF/controller.xml 2009-11-15 15:30:02,156 (main) [ ApplicationContext.java:660:ERROR] StandardWrapper.Throwable java.lang.OutOfMemoryError: Java heap space 2009-11-15 15:30:02,171 (main) [ StandardContext.java:4060:ERROR] Servlet /myportal threw load() exception java.lang.OutOfMemoryError: Java heap space 2009-11-15 15:30:23,937 (main) [ GenericDispatcher.java:61 :INFO ] Creating new dispatcher [order] (main) 2009-11-15 15:30:23,953 (main) [ ControlServlet.java:69 :INFO ] LOADING WEBAPP [ordermgr] Open For Business - Order Manager, located at C:\ofbiz-trunk\applications\order\webapp\ordermgr\ 2009-11-15 15:30:25,546 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.0s, 18 requests, 14 views in file:/C:/ofbiz-trunk/framework/common/webcommon/WEB-INF/common-controller.xml 2009-11-15 15:30:26,078 (main) [ ConfigXMLReader.java:118:INFO ] controller loaded: 0.0s, 5 requests, 2 views in file:/C:/ofbiz-trunk/applications/commonext/webapp/WEB-INF/controller.xml 2009-11-15 15:30:29,500 (main) [ ApplicationContext.java:660:ERROR] StandardWrapper.Throwable java.lang.OutOfMemoryError: Java heap space 2009-11-15 15:30:30,000 (main) [ StandardContext.java:4060:ERROR] Servlet /ordermgr threw load() exception java.lang.OutOfMemoryError: Java heap space 2009-11-15 15:32:45,265 (main) [ ChannelSocket.java:387:INFO ] JK: ajp13 listening on /0.0.0.0:8009 2009-11-15 15:32:47,218 (main) [ JkMain.java:344:INFO ] Jk running ID=0 time=15/3843 config=null 2009-11-15 15:32:59,000 (main) [ Http11Protocol.java:209:INFO ] Starting Coyote HTTP/1.1 on http-0.0.0.0-8080 2009-11-15 15:33:05,328 (main) [ Http11Protocol.java:209:INFO ] Starting Coyote HTTP/1.1 on http-0.0.0.0-8443 2009-11-15 15:33:11,546 (main) [ CatalinaContainer.java:237:INFO ] Connector AJP/1.3 @ 8009 - not-secure [org.apache.jk.server.JkCoyoteHandler] started. 2009-11-15 15:33:11,546 (main) [ CatalinaContainer.java:234:INFO ] Connector null @ 8080 - not-secure [org.apache.coyote.http11.Http11Protocol] started. 2009-11-15 15:33:11,546 (main) [ CatalinaContainer.java:234:INFO ] Connector null @ 8443 - secure [org.apache.coyote.http11.Http11Protocol] started. 2009-11-15 15:33:11,546 (main) [ CatalinaContainer.java:241:INFO ] Started Apache Tomcat/6.0.16 Httpd started on port: 9989 Sessiond started on port: 9990 2009-11-15 15:33:14,125 (main) [ BeanShellContainer.java:100:INFO ] Started BeanShell telnet service on 9989, 9990 2009-11-15 15:33:14,125 (main) [ BeanShellContainer.java:101:INFO ] NOTICE: BeanShell service ports are not secure. Please protect the ports