Paypal IPN is not properly received and transaction is not recorded.
-------------------------------------------------------------------- Key: OFBIZ-175 URL: http://issues.apache.org/jira/browse/OFBIZ-175 Project: OFBiz (The Open for Business Project) Issue Type: Bug Components: accounting Affects Versions: SVN trunk Environment: Red hat 9. JDK 1.4.2_11. Reporter: Rohit Sureka Paypal IPN is not recorded and no entry is made in the payments received. I am not sure what is the problem, hence is have pasted the log below. I am using the latest SVN, without any modifications. Rohit 2006-08-15 17:47:57,815 (http-0.0.0.0-7443-Processor1) [ RequestHandler.java:119:INFO ] [RequestHandler]: Chain in place: requestUri=checkExternalPayment nextView=null 2006-08-15 17:47:57,816 (http-0.0.0.0-7443-Processor1) [ RequestHandler.java:203:INFO ] [Processing Request]: checkExternalPayment 2006-08-15 17:47:57,833 (http-0.0.0.0-7443-Processor1) [ RequestHandler.java:341:INFO ] [RequestHandler.doRequest]: Response is a chained request. 2006-08-15 17:47:57,834 (http-0.0.0.0-7443-Processor1) [ RequestHandler.java:119:INFO ] [RequestHandler]: Chain in place: requestUri=callPayPal nextView=null 2006-08-15 17:47:57,836 (http-0.0.0.0-7443-Processor1) [ RequestHandler.java:203:INFO ] [Processing Request]: callPayPal 2006-08-15 17:47:57,929 (http-0.0.0.0-7443-Processor1) [ RequestHandler.java:391:INFO ] [RequestHandler.doRequest]: Response is handled by the event. 2006-08-15 17:47:58,664 (http-0.0.0.0-7443-Processor1) [ ControlServlet.java:250:DEBUG] [[[processorder] Done rendering page, Servlet Finished- total:12.513,since last([processorder] Se...):12.513]] 2006-08-15 17:49:24,588 (http-0.0.0.0-7070-Processor1) [ControlEventListener.java:63 :INFO ] Creating session: F9A55D2179EBC06C612F37CD5496143D.jvm1 2006-08-15 17:49:24,589 (http-0.0.0.0-7070-Processor1) [ ControlServlet.java:90 :INFO ] The character encoding of the request is: [null]. The character encoding we will use for the request and response is: [UTF-8] 2006-08-15 17:49:24,590 (http-0.0.0.0-7070-Processor1) [ ControlServlet.java:126:DEBUG] [[[payPalNotify] Servlet Starting, doing setup- total:0.0,since last(Begin):0.0]] 2006-08-15 17:49:24,591 (http-0.0.0.0-7070-Processor1) [ ControlServlet.java:175:DEBUG] [[[payPalNotify] Setup done, doing Event(s) and View(s)- total:0.0,since last([payPalNotify] Se...):0.0]] 2006-08-15 17:49:24,629 (http-0.0.0.0-7070-Processor1) [ UtilXml.java:255:DEBUG] XML Read 0.037s: jndi:/0.0.0.0/ecommerce/WEB-INF/controller.xml 2006-08-15 17:49:24,633 (http-0.0.0.0-7070-Processor1) [ ConfigXMLReader.java:544:INFO ] ConfigMap Created: (5) records in 0.0020s 2006-08-15 17:49:24,635 (http-0.0.0.0-7070-Processor1) [ ConfigXMLReader.java:614:INFO ] HandlerMap Created: (2) records in 0.0s 2006-08-15 17:49:24,652 (http-0.0.0.0-7070-Processor1) [ ConfigXMLReader.java:294:INFO ] RequestMap Created: (196) records in 0.015s 2006-08-15 17:49:24,654 (http-0.0.0.0-7070-Processor1) [ ConfigXMLReader.java:403:INFO ] ViewMap Created: (69) records in 0.0010s 2006-08-15 17:49:24,656 (http-0.0.0.0-7070-Processor1) [ RequestHandler.java:144:INFO ] This is the first request in this visit. 2006-08-15 17:49:24,924 (http-0.0.0.0-7070-Processor1) [ RequestHandler.java:203:INFO ] [Processing Request]: payPalNotify 2006-08-15 17:49:25,346 (http-0.0.0.0-7070-Processor1) [ PayPalEvents.java:222:ERROR] PayPal Verification Response: <!DOCTYPE HTML PUBLIC "-//W3C//DTD HTML 4.01 Transitional//EN" "http://www.w3.org/TR/html4/loose.dtd"> 2006-08-15 17:49:25,348 (http-0.0.0.0-7070-Processor1) [ PayPalEvents.java:231:ERROR] ###### PayPal did not verify this request, need investigation! 2006-08-15 17:49:25,350 (http-0.0.0.0-7070-Processor1) [ PayPalEvents.java:237:ERROR] ### Param: custom => [hidden email] 2006-08-15 17:49:25,351 (http-0.0.0.0-7070-Processor1) [ PayPalEvents.java:237:ERROR] ### Param: residence_country => US 2006-08-15 17:49:25,353 (http-0.0.0.0-7070-Processor1) [ PayPalEvents.java:237:ERROR] ### Param: payment_fee => 1.79 2006-08-15 17:49:25,354 (http-0.0.0.0-7070-Processor1) [ PayPalEvents.java:237:ERROR] ### Param: item_number => 0 2006-08-15 17:49:25,355 (http-0.0.0.0-7070-Processor1) [ PayPalEvents.java:237:ERROR] ### Param: first_name => name 2006-08-15 17:49:25,357 (http-0.0.0.0-7070-Processor1) [ PayPalEvents.java:237:ERROR] ### Param: charset => windows-1252 2006-08-15 17:49:25,358 (http-0.0.0.0-7070-Processor1) [ PayPalEvents.java:237:ERROR] ### Param: shipping => 0.00 2006-08-15 17:49:25,359 (http-0.0.0.0-7070-Processor1) [ PayPalEvents.java:237:ERROR] ### Param: invoice => xy123 2006-08-15 17:49:25,361 (http-0.0.0.0-7070-Processor1) [ PayPalEvents.java:237:ERROR] ### Param: mc_fee => 1.79 2006-08-15 17:49:25,362 (http-0.0.0.0-7070-Processor1) [ PayPalEvents.java:237:ERROR] ### Param: business => [hidden email] 2006-08-15 17:49:25,364 (http-0.0.0.0-7070-Processor1) [ PayPalEvents.java:237:ERROR] ### Param: mc_gross => 51.49 2006-08-15 17:49:25,365 (http-0.0.0.0-7070-Processor1) [ PayPalEvents.java:237:ERROR] ### Param: payment_type => instant 2006-08-15 17:49:25,366 (http-0.0.0.0-7070-Processor1) [ PayPalEvents.java:237:ERROR] ### Param: mc_currency => USD 2006-08-15 17:49:25,367 (http-0.0.0.0-7070-Processor1) [ PayPalEvents.java:237:ERROR] ### Param: item_name => Order #123 on site.com 2006-08-15 17:49:25,369 (http-0.0.0.0-7070-Processor1) [ PayPalEvents.java:237:ERROR] ### Param: quantity => 1 2006-08-15 17:49:25,370 (http-0.0.0.0-7070-Processor1) [ PayPalEvents.java:237:ERROR] ### Param: receiver_id => kjhuy78879 2006-08-15 17:49:25,371 (http-0.0.0.0-7070-Processor1) [ PayPalEvents.java:237:ERROR] ### Param: txn_type => web_accept 2006-08-15 17:49:25,373 (http-0.0.0.0-7070-Processor1) [ PayPalEvents.java:237:ERROR] ### Param: verify_sign => mhhjhjjg jfgfghfghfhgfghf ghf hgfhg 2006-08-15 17:49:25,374 (http-0.0.0.0-7070-Processor1) [ PayPalEvents.java:237:ERROR] ### Param: payer_id => kjiyiuyiuyiuyiuyui 2006-08-15 17:49:25,375 (http-0.0.0.0-7070-Processor1) [ PayPalEvents.java:237:ERROR] ### Param: payer_status => verified 2006-08-15 17:49:25,377 (http-0.0.0.0-7070-Processor1) [ PayPalEvents.java:237:ERROR] ### Param: notify_version => 2.1 2006-08-15 17:49:25,378 (http-0.0.0.0-7070-Processor1) [ PayPalEvents.java:237:ERROR] ### Param: payment_gross => 51.49 2006-08-15 17:49:25,379 (http-0.0.0.0-7070-Processor1) [ PayPalEvents.java:237:ERROR] ### Param: tax => 0.00 2006-08-15 17:49:25,381 (http-0.0.0.0-7070-Processor1) [ PayPalEvents.java:237:ERROR] ### Param: receiver_email => [hidden email] 2006-08-15 17:49:25,382 (http-0.0.0.0-7070-Processor1) [ PayPalEvents.java:237:ERROR] ### Param: payer_email => [hidden email] 2006-08-15 17:49:25,383 (http-0.0.0.0-7070-Processor1) [ PayPalEvents.java:237:ERROR] ### Param: payment_status => Completed 2006-08-15 17:49:25,385 (http-0.0.0.0-7070-Processor1) [ PayPalEvents.java:237:ERROR] ### Param: last_name => sdfsdf 2006-08-15 17:49:25,386 (http-0.0.0.0-7070-Processor1) [ PayPalEvents.java:237:ERROR] ### Param: txn_id => weruwerwekljl 2006-08-15 17:49:25,387 (http-0.0.0.0-7070-Processor1) [ PayPalEvents.java:237:ERROR] ### Param: payment_date => 14:49:23 Aug 15, 2006 PDT 2006-08-15 17:49:25,389 (http-0.0.0.0-7070-Processor1) [ PayPalEvents.java:237:ERROR] ### Param: cmd => null 2006-08-15 17:49:25,886 (http-0.0.0.0-7070-Processor1) [ ServiceEcaRule.java:105:INFO ] Got false for condition: [null][][statusId][equals][null][ORDER_CANCELLED][true][String][] 2006-08-15 17:49:25,888 (http-0.0.0.0-7070-Processor1) [ ServiceEcaRule.java:105:INFO ] Got false for condition: [null][][statusId][equals][null][ORDER_COMPLETED][true][String][] 2006-08-15 17:49:25,890 (http-0.0.0.0-7070-Processor1) [ ServiceEcaRule.java:121:INFO ] Running Service ECA Service: updateContentSubscriptionByOrder, triggered by rule on Service: changeOrderStatus 2006-08-15 17:49:25,953 (http-0.0.0.0-7070-Processor1) [ContentManagementServices.java:1573:INFO ] In updateContentSubscriptionByOrder service with orderId: 123 2006-08-15 17:49:25,960 (http-0.0.0.0-7070-Processor1) [ GenericDelegator.java:2307:WARN ] Tried to put a value of the OrderHeader entity in the BY PRIMARY KEY cache but this entity has never-cache set to true, not caching. 2006-08-15 17:49:25,969 (http-0.0.0.0-7070-Processor1) [ ServiceDispatcher.java:450:DEBUG] [[Sync service finished- total:0.077,since last(Begin):0.077]] - 'ecommerce / updateContentSubscriptionByOrder' 2006-08-15 17:49:25,971 (http-0.0.0.0-7070-Processor1) [ ServiceEcaRule.java:121:INFO ] Running Service ECA Service: processExtendSubscriptionByOrder, triggered by rule on Service: changeOrderStatus 2006-08-15 17:49:25,990 (http-0.0.0.0-7070-Processor1) [SubscriptionServices.java:217:INFO ] In processExtendSubscriptionByOrder service with orderId: 123 2006-08-15 17:49:25,998 (http-0.0.0.0-7070-Processor1) [ GenericDelegator.java:2307:WARN ] Tried to put a value of the OrderHeader entity in the BY PRIMARY KEY cache but this entity has never-cache set to true, not caching. 2006-08-15 17:49:26,060 (http-0.0.0.0-7070-Processor1) [ ServiceDispatcher.java:450:DEBUG] [[Sync service finished- total:0.088,since last(Begin):0.088]] - 'ecommerce / processExtendSubscriptionByOrder' 2006-08-15 17:49:26,062 (http-0.0.0.0-7070-Processor1) [ ServiceDispatcher.java:450:DEBUG] [[Sync service finished- total:0.638,since last(Begin):0.638]] - 'ecommerce / changeOrderStatus' 2006-08-15 17:49:26,373 (http-0.0.0.0-7070-Processor1) [ EntityEcaRule.java:114:INFO ] Running Entity ECA Service: checkCreateOrderRequirement, triggered by rule on Entity: OrderItem 2006-08-15 17:49:26,480 (http-0.0.0.0-7070-Processor1) [ UtilXml.java:255:DEBUG] XML Read 0.105s: file: /applications/order/script/org/ofbiz/order/order/OrderServices.xml 2006-08-15 17:49:26,497 (http-0.0.0.0-7070-Processor1) [ PrimaryKeyFinder.java:132:INFO ] Returning null because found incomplete primary key in find: [GenericEntity:Product] 2006-08-15 17:49:26,502 (http-0.0.0.0-7070-Processor1) [ ServiceDispatcher.java:450:DEBUG] [[Sync service finished- total:0.127,since last(Begin):0.127]] - 'entity-default / checkCreateOrderRequirement' 2006-08-15 17:49:27,074 (http-0.0.0.0-7070-Processor1) [ ServiceEcaRule.java:105:INFO ] Got false for condition: [null][][statusId][equals][null][ITEM_CANCELLED][true][String][] 2006-08-15 17:49:27,076 (http-0.0.0.0-7070-Processor1) [ ServiceEcaRule.java:105:INFO ] Got false for condition: [null][][statusId][equals][null][ITEM_COMPLETED][true][String][] 2006-08-15 17:49:27,078 (http-0.0.0.0-7070-Processor1) [ ServiceEcaRule.java:121:INFO ] Running Service ECA Service: checkOrderItemStatus, triggered by rule on Service: changeOrderItemStatus 2006-08-15 17:49:27,097 (http-0.0.0.0-7070-Processor1) [ OrderServices.java:1822:WARN ] Tried to setOrderStatus with the same statusId [ ORDER_APPROVED ] for order with ID [ 123 ] 2006-08-15 17:49:27,099 (http-0.0.0.0-7070-Processor1) [ ServiceEcaRule.java:105:INFO ] Got false for condition: [null][][statusId][equals][null][ORDER_CANCELLED][true][String][] 2006-08-15 17:49:27,101 (http-0.0.0.0-7070-Processor1) [ ServiceEcaRule.java:105:INFO ] Got false for condition: [null][][statusId][equals][null][ORDER_COMPLETED][true][String][] 2006-08-15 17:49:27,103 (http-0.0.0.0-7070-Processor1) [ServiceEcaCondition.java:128:WARN ] From Field (orderTypeId) is not found in context for changeOrderStatus, defaulting to null. 2006-08-15 17:49:27,105 (http-0.0.0.0-7070-Processor1) [ ServiceEcaRule.java:105:INFO ] Got false for condition: [null][][orderTypeId][equals][null][SALES_ORDER][true][String][] 2006-08-15 17:49:27,107 (http-0.0.0.0-7070-Processor1) [ ServiceDispatcher.java:450:DEBUG] [[Sync service finished- total:0.017,since last(Begin):0.017]] - 'ecommerce / changeOrderStatus' 2006-08-15 17:49:27,110 (http-0.0.0.0-7070-Processor1) [ ServiceDispatcher.java:450:DEBUG] [[Sync service finished- total:0.031,since last(Begin):0.031]] - 'ecommerce / checkOrderItemStatus' 2006-08-15 17:49:27,112 (http-0.0.0.0-7070-Processor1) [ ServiceEcaRule.java:121:INFO ] Running Service ECA Service: checkDigitalItemFulfillment, triggered by rule on Service: changeOrderItemStatus 2006-08-15 17:49:27,130 (http-0.0.0.0-7070-Processor1) [ ServiceDispatcher.java:450:DEBUG] [[Sync service finished- total:0.017,since last(Begin):0.017]] - 'ecommerce / checkDigitalItemFulfillment' 2006-08-15 17:49:27,132 (http-0.0.0.0-7070-Processor1) [ ServiceDispatcher.java:450:DEBUG] [[Sync service finished- total:1.069,since last(Begin):1.069]] - 'ecommerce / changeOrderItemStatus' 2006-08-15 17:49:28,304 (http-0.0.0.0-7070-Processor1) [ SequenceUtil.java:263:INFO ] Got bank of sequenced IDs for [PaymentGatewayResponse]; curSeqId=10020, maxSeqId=10030, bankSize=10 2006-08-15 17:49:29,155 (http-0.0.0.0-7070-Processor13) [ControlEventListener.java:63 :INFO ] Creating session: BB4327765346132C334931D518C0A0B8.jvm1 2006-08-15 17:49:29,156 (http-0.0.0.0-7070-Processor13) [ ControlServlet.java:90 :INFO ] The character encoding of the request is: [null]. The character encoding we will use for the request and response is: [UTF-8] 2006-08-15 17:49:29,157 (http-0.0.0.0-7070-Processor13) [ ControlServlet.java:126:DEBUG] [[[orderhistory] Servlet Starting, doing setup- total:0.0,since last(Begin):0.0]] 2006-08-15 17:49:29,158 (http-0.0.0.0-7070-Processor13) [ ControlServlet.java:175:DEBUG] [[[orderhistory] Setup done, doing Event(s) and View(s)- total:0.0,since last([orderhistory] Se...):0.0]] 2006-08-15 17:49:29,159 (http-0.0.0.0-7070-Processor13) [ RequestHandler.java:144:INFO ] This is the first request in this visit. 2006-08-15 17:49:30,324 (http-0.0.0.0-7070-Processor1) [ ServiceDispatcher.java:324:ERROR] ---- exception report ---------------------------------------------------------- Incoming context (in runSync : createPayment) does not match expected requirements Exception: org.ofbiz.service.ServiceValidationException Message: The following required parameter is missing: [createPayment.amount] ---- stack trace --------------------------------------------------------------- org.ofbiz.service.ServiceValidationException: The following required parameter is missing: [createPayment.amount] org.ofbiz.service.ModelService.validate(ModelService.java:363) org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:322) org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:212) org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:114) org.ofbiz.order.order.OrderServices.createPaymentFromPreference(OrderServices.java:3401) sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) java.lang.reflect.Method.invoke(Method.java:324) org.ofbiz.service.engine.StandardJavaEngine.serviceInvoker(StandardJavaEngine.java:92) org.ofbiz.service.engine.StandardJavaEngine.runSync(StandardJavaEngine.java:54) org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:338) org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:212) org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:114) org.ofbiz.accounting.thirdparty.paypal.PayPalEvents.setPaymentPreference(PayPalEvents.java:465) org.ofbiz.accounting.thirdparty.paypal.PayPalEvents.setPaymentPreferences(PayPalEvents.java:402) org.ofbiz.accounting.thirdparty.paypal.PayPalEvents.payPalIPN(PayPalEvents.java:302) sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) java.lang.reflect.Method.invoke(Method.java:324) org.ofbiz.webapp.event.JavaEventHandler.invoke(JavaEventHandler.java:90) org.ofbiz.webapp.event.JavaEventHandler.invoke(JavaEventHandler.java:76) org.ofbiz.webapp.control.RequestHandler.runEvent(RequestHandler.java:407) org.ofbiz.webapp.control.RequestHandler.doRequest(RequestHandler.java:243) org.ofbiz.webapp.control.ControlServlet.doGet(ControlServlet.java:183) org.ofbiz.webapp.control.ControlServlet.doPost(ControlServlet.java:79) javax.servlet.http.HttpServlet.service(HttpServlet.java:615) javax.servlet.http.HttpServlet.service(HttpServlet.java:688) org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:252) org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:173) org.ofbiz.webapp.control.ContextFilter.doFilter(ContextFilter.java:249) org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:202) org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:173) org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:213) org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:178) org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:126) org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:105) org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:107) org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:541) org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:148) org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:869) org.apache.coyote.http11.Http11BaseProtocol$Http11ConnectionHandler.processConnection(Http11BaseProtocol.java:664) org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(PoolTcpEndpoint.java:527) org.apache.tomcat.util.net.LeaderFollowerWorkerThread.runIt(LeaderFollowerWorkerThread.java:80) org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:684) java.lang.Thread.run(Thread.java:534) -------------------------------------------------------------------------------- 2006-08-15 17:49:30,327 (http-0.0.0.0-7070-Processor1) [ ServiceDispatcher.java:384:DEBUG] [[Sync service failed...- total:0.0,since last(Begin):0.0]] - 'ecommerce / createPayment' 2006-08-15 17:49:30,330 (http-0.0.0.0-7070-Processor1) [ ServiceDispatcher.java:387:ERROR] ---- exception report ---------------------------------------------------------- Service [createPayment] threw an unexpected exception/error Exception: org.ofbiz.service.ServiceValidationException Message: The following required parameter is missing: [createPayment.amount] ---- stack trace --------------------------------------------------------------- org.ofbiz.service.ServiceValidationException: The following required parameter is missing: [createPayment.amount] org.ofbiz.service.ModelService.validate(ModelService.java:363) org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:322) org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:212) org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:114) org.ofbiz.order.order.OrderServices.createPaymentFromPreference(OrderServices.java:3401) sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) java.lang.reflect.Method.invoke(Method.java:324) org.ofbiz.service.engine.StandardJavaEngine.serviceInvoker(StandardJavaEngine.java:92) org.ofbiz.service.engine.StandardJavaEngine.runSync(StandardJavaEngine.java:54) org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:338) org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:212) org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:114) org.ofbiz.accounting.thirdparty.paypal.PayPalEvents.setPaymentPreference(PayPalEvents.java:465) org.ofbiz.accounting.thirdparty.paypal.PayPalEvents.setPaymentPreferences(PayPalEvents.java:402) org.ofbiz.accounting.thirdparty.paypal.PayPalEvents.payPalIPN(PayPalEvents.java:302) sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) java.lang.reflect.Method.invoke(Method.java:324) org.ofbiz.webapp.event.JavaEventHandler.invoke(JavaEventHandler.java:90) org.ofbiz.webapp.event.JavaEventHandler.invoke(JavaEventHandler.java:76) org.ofbiz.webapp.control.RequestHandler.runEvent(RequestHandler.java:407) org.ofbiz.webapp.control.RequestHandler.doRequest(RequestHandler.java:243) org.ofbiz.webapp.control.ControlServlet.doGet(ControlServlet.java:183) org.ofbiz.webapp.control.ControlServlet.doPost(ControlServlet.java:79) javax.servlet.http.HttpServlet.service(HttpServlet.java:615) javax.servlet.http.HttpServlet.service(HttpServlet.java:688) org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:252) org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:173) org.ofbiz.webapp.control.ContextFilter.doFilter(ContextFilter.java:249) org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:202) org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:173) org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:213) org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:178) org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:126) org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:105) org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:107) org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:541) org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:148) org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:869) org.apache.coyote.http11.Http11BaseProtocol$Http11ConnectionHandler.processConnection(Http11BaseProtocol.java:664) org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(PoolTcpEndpoint.java:527) org.apache.tomcat.util.net.LeaderFollowerWorkerThread.runIt(LeaderFollowerWorkerThread.java:80) org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:684) java.lang.Thread.run(Thread.java:534) -------------------------------------------------------------------------------- 2006-08-15 17:49:30,334 (http-0.0.0.0-7070-Processor1) [ TransactionUtil.java:276:WARN ] ---- exception report ---------------------------------------------------------- [TransactionUtil.setRollbackOnly] Calling transaction setRollbackOnly; this stack trace shows where this is happening: Exception: java.lang.Exception Message: null ---- stack trace --------------------------------------------------------------- java.lang.Exception org.ofbiz.entity.transaction.TransactionUtil.setRollbackOnly(TransactionUtil.java:276) org.ofbiz.entity.transaction.TransactionUtil.rollback(TransactionUtil.java:226) org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:390) org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:212) org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:114) org.ofbiz.order.order.OrderServices.createPaymentFromPreference(OrderServices.java:3401) sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) java.lang.reflect.Method.invoke(Method.java:324) org.ofbiz.service.engine.StandardJavaEngine.serviceInvoker(StandardJavaEngine.java:92) org.ofbiz.service.engine.StandardJavaEngine.runSync(StandardJavaEngine.java:54) org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:338) org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:212) org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:114) org.ofbiz.accounting.thirdparty.paypal.PayPalEvents.setPaymentPreference(PayPalEvents.java:465) org.ofbiz.accounting.thirdparty.paypal.PayPalEvents.setPaymentPreferences(PayPalEvents.java:402) org.ofbiz.accounting.thirdparty.paypal.PayPalEvents.payPalIPN(PayPalEvents.java:302) sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) java.lang.reflect.Method.invoke(Method.java:324) org.ofbiz.webapp.event.JavaEventHandler.invoke(JavaEventHandler.java:90) org.ofbiz.webapp.event.JavaEventHandler.invoke(JavaEventHandler.java:76) org.ofbiz.webapp.control.RequestHandler.runEvent(RequestHandler.java:407) org.ofbiz.webapp.control.RequestHandler.doRequest(RequestHandler.java:243) org.ofbiz.webapp.control.ControlServlet.doGet(ControlServlet.java:183) org.ofbiz.webapp.control.ControlServlet.doPost(ControlServlet.java:79) javax.servlet.http.HttpServlet.service(HttpServlet.java:615) javax.servlet.http.HttpServlet.service(HttpServlet.java:688) org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:252) org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:173) org.ofbiz.webapp.control.ContextFilter.doFilter(ContextFilter.java:249) org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:202) org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:173) org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:213) org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:178) org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:126) org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:105) org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:107) org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:541) org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:148) org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:869) org.apache.coyote.http11.Http11BaseProtocol$Http11ConnectionHandler.processConnection(Http11BaseProtocol.java:664) org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(PoolTcpEndpoint.java:527) org.apache.tomcat.util.net.LeaderFollowerWorkerThread.runIt(LeaderFollowerWorkerThread.java:80) org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:684) java.lang.Thread.run(Thread.java:534) -------------------------------------------------------------------------------- 2006-08-15 17:49:30,338 (http-0.0.0.0-7070-Processor1) [ OrderServices.java:3407:ERROR] ---- exception report ---------------------------------------------------------- Unable to create payment using payment preference. Exception: org.ofbiz.service.ServiceValidationException Message: The following required parameter is missing: [createPayment.amount] ---- stack trace --------------------------------------------------------------- org.ofbiz.service.ServiceValidationException: The following required parameter is missing: [createPayment.amount] org.ofbiz.service.ModelService.validate(ModelService.java:363) org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:322) org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:212) org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:114) org.ofbiz.order.order.OrderServices.createPaymentFromPreference(OrderServices.java:3401) sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) java.lang.reflect.Method.invoke(Method.java:324) org.ofbiz.service.engine.StandardJavaEngine.serviceInvoker(StandardJavaEngine.java:92) org.ofbiz.service.engine.StandardJavaEngine.runSync(StandardJavaEngine.java:54) org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:338) org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:212) org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:114) org.ofbiz.accounting.thirdparty.paypal.PayPalEvents.setPaymentPreference(PayPalEvents.java:465) org.ofbiz.accounting.thirdparty.paypal.PayPalEvents.setPaymentPreferences(PayPalEvents.java:402) org.ofbiz.accounting.thirdparty.paypal.PayPalEvents.payPalIPN(PayPalEvents.java:302) sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) java.lang.reflect.Method.invoke(Method.java:324) org.ofbiz.webapp.event.JavaEventHandler.invoke(JavaEventHandler.java:90) org.ofbiz.webapp.event.JavaEventHandler.invoke(JavaEventHandler.java:76) org.ofbiz.webapp.control.RequestHandler.runEvent(RequestHandler.java:407) org.ofbiz.webapp.control.RequestHandler.doRequest(RequestHandler.java:243) org.ofbiz.webapp.control.ControlServlet.doGet(ControlServlet.java:183) org.ofbiz.webapp.control.ControlServlet.doPost(ControlServlet.java:79) javax.servlet.http.HttpServlet.service(HttpServlet.java:615) javax.servlet.http.HttpServlet.service(HttpServlet.java:688) org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:252) org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:173) org.ofbiz.webapp.control.ContextFilter.doFilter(ContextFilter.java:249) org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:202) org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:173) org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:213) org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:178) org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:126) org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:105) org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:107) org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:541) org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:148) org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:869) org.apache.coyote.http11.Http11BaseProtocol$Http11ConnectionHandler.processConnection(Http11BaseProtocol.java:664) org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(PoolTcpEndpoint.java:527) org.apache.tomcat.util.net.LeaderFollowerWorkerThread.runIt(LeaderFollowerWorkerThread.java:80) org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:684) java.lang.Thread.run(Thread.java:534) -------------------------------------------------------------------------------- 2006-08-15 17:49:30,340 (http-0.0.0.0-7070-Processor1) [ ServiceDispatcher.java:410:ERROR] Service Error [createPaymentFromPreference]: The following required parameter is missing: [createPayment.amount] 2006-08-15 17:49:30,342 (http-0.0.0.0-7070-Processor1) [ TransactionUtil.java:280:INFO ] [TransactionUtil.setRollbackOnly] transaction rollback only not set, rollback only is already set. 2006-08-15 17:49:30,343 (http-0.0.0.0-7070-Processor1) [ ServiceDispatcher.java:450:DEBUG] [[Sync service finished- total:0.394,since last(Begin):0.394]] - 'ecommerce / createPaymentFromPreference' 2006-08-15 17:49:30,345 (http-0.0.0.0-7070-Processor1) [ PayPalEvents.java:474:ERROR] The following required parameter is missing: [createPayment.amount] 2006-08-15 17:49:30,348 (http-0.0.0.0-7070-Processor1) [ TransactionUtil.java:243:ERROR] ---- exception report ---------------------------------------------------------- [TransactionUtil.rollback] Exception: java.lang.Exception Message: Stack Trace ---- stack trace --------------------------------------------------------------- java.lang.Exception: Stack Trace org.ofbiz.entity.transaction.TransactionUtil.rollback(TransactionUtil.java:242) org.ofbiz.entity.transaction.TransactionUtil.rollback(TransactionUtil.java:224) org.ofbiz.accounting.thirdparty.paypal.PayPalEvents.payPalIPN(PayPalEvents.java:315) sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39) sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25) java.lang.reflect.Method.invoke(Method.java:324) org.ofbiz.webapp.event.JavaEventHandler.invoke(JavaEventHandler.java:90) org.ofbiz.webapp.event.JavaEventHandler.invoke(JavaEventHandler.java:76) org.ofbiz.webapp.control.RequestHandler.runEvent(RequestHandler.java:407) org.ofbiz.webapp.control.RequestHandler.doRequest(RequestHandler.java:243) org.ofbiz.webapp.control.ControlServlet.doGet(ControlServlet.java:183) org.ofbiz.webapp.control.ControlServlet.doPost(ControlServlet.java:79) javax.servlet.http.HttpServlet.service(HttpServlet.java:615) javax.servlet.http.HttpServlet.service(HttpServlet.java:688) org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:252) org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:173) org.ofbiz.webapp.control.ContextFilter.doFilter(ContextFilter.java:249) org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:202) org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:173) org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:213) org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:178) org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:126) org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:105) org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:107) org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:541) org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:148) org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:869) org.apache.coyote.http11.Http11BaseProtocol$Http11ConnectionHandler.processConnection(Http11BaseProtocol.java:664) org.apache.tomcat.util.net.PoolTcpEndpoint.processSocket(PoolTcpEndpoint.java:527) org.apache.tomcat.util.net.LeaderFollowerWorkerThread.runIt(LeaderFollowerWorkerThread.java:80) org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:684) java.lang.Thread.run(Thread.java:534) -------------------------------------------------------------------------------- 2006-08-15 17:49:30,352 (http-0.0.0.0-7070-Processor1) [ TransactionUtil.java:253:INFO ] [TransactionUtil.rollback] transaction rolled back 2006-08-15 17:49:30,354 (http-0.0.0.0-7070-Processor1) [ RequestHandler.java:391:INFO ] [RequestHandler.doRequest]: Response is handled by the event. 2006-08-15 17:49:31,192 (http-0.0.0.0-7070-Processor1) [ ControlServlet.java:250:DEBUG] [[[payPalNotify] Done rendering page, Servlet Finished- total:6.6,since last([payPalNotify] Se...):6.6]] -- This message is automatically generated by JIRA. - If you think it was sent incorrectly contact one of the administrators: http://issues.apache.org/jira/secure/Administrators.jspa - For more information on JIRA, see: http://www.atlassian.com/software/jira |
Free forum by Nabble | Edit this page |