paypal IPN error. IPN is not received and transaction is not recorded

Previous Topic Next Topic
 
classic Classic list List threaded Threaded
2 messages Options
Reply | Threaded
Open this post in threaded view
|

paypal IPN error. IPN is not received and transaction is not recorded

rohit
hi,

I am getting the error pasted below. the IPN from paypal is not recorded and no entry is made in the payment received. I am not sure what is the problem, hence is have pasted the log below. I am using the latest SVN 431010, 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 => payer@gmail.com
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 => business@paypal.com
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 => business@paypal.com
2006-08-15 17:49:25,382 (http-0.0.0.0-7070-Processor1) [       PayPalEvents.java:237:ERROR] ### Param: payer_email => payer@gmail.com
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]]
Reply | Threaded
Open this post in threaded view
|

Re: paypal IPN error. IPN is not received and transaction is not recorde

rohit
hi,

just wondering if anyone had a chance to look at the paypal IPN error. This is on the default setup of ofbiz, is it a bug?.

rohit

rohit2006 wrote
hi,

I am getting the error pasted below. the IPN from paypal is not recorded and no entry is made in the payment received. I am not sure what is the problem, hence is have pasted the log below. I am using the latest SVN 431010, 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 => payer@gmail.com
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 => business@paypal.com
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 => business@paypal.com
2006-08-15 17:49:25,382 (http-0.0.0.0-7070-Processor1) [       PayPalEvents.java:237:ERROR] ### Param: payer_email => payer@gmail.com
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]]