Getting multiple order confirmation mails after placing an order

[kr...@paxcel.net]

Hi All,

We are using Ofbiz V-9.04. We are facing an issue with order checkout, not 
exactly with checkout rather with emails. We can place an order and complete 
the checkout process. It is expected to get single mail with subject "OFBiz 
Demo - Order Confirmation #WSOD10290" but we are getting 4 similar mails due 
to re-scheduling. Following are the logs for email failure. Looking forward 
for your help to resolve this issue. Thanks in advance. :)

2012-04-17 17:57:16,832 (default-invoker-Thread-19) [ 
EmailServices.java:571:INFO ] Expanded email subject to: OFBiz Demo - Order 
Confirmation #WSOD10290
2012-04-17 17:57:16,833 (default-invoker-Thread-19) 
[ServiceEcaCondition.java:156:WARN ] From Field (communicationEventId) is 
not found in context for sendMailMultiPart, defaulting to null.
2012-04-17 17:57:16,833 (default-invoker-Thread-19) [ 
ServiceEcaRule.java:157:INFO ] Running Service ECA Service: 
createCommEventFromEmail, triggered by rule on Service: sendMailMultiPart
2012-04-17 17:57:16,996 (default-invoker-Thread-19) [ 
SequenceUtil.java:348:INFO ] Got bank of sequenced IDs for 
[CommunicationEvent]; curSeqId=10630, maxSeqId=10640, bankSize=10
2012-04-17 17:57:17,040 (default-invoker-Thread-19) [ 
Log.java:117:INFO ] [CommonPermissionServices.xml#genericBasePermissionCheck 
line 41] Checking for primary permission PARTYMGR_CREATE
2012-04-17 17:57:17,041 (default-invoker-Thread-19) [ 
Log.java:117:INFO ] [CommonPermissionServices.xml#genericBasePermissionCheck 
line 48] Checking for alternate permission PARTYMGR_ROLE_CREATE
2012-04-17 17:57:17,366 (default-invoker-Thread-19) [ 
ServiceDispatcher.java:604:INFO ] Sync service [default/createPartyRole] 
finished in [349] milliseconds with response [{responseMessage=success}]
2012-04-17 17:57:17,370 (default-invoker-Thread-19) [ 
ServiceDispatcher.java:604:INFO ] Sync service 
[default/createCommunicationEventRoleWithoutPermission] finished in [364] 
milliseconds with response [{responseMessage=success}]
2012-04-17 17:57:17,373 (default-invoker-Thread-19) [ 
ServiceEcaRule.java:137:INFO ] For Service ECA [createCommunicationEvent] on 
[commit] got false for condition: 
[statusId][equals][COM_ENTERED][true][String]
2012-04-17 17:57:17,373 (default-invoker-Thread-19) [ 
ServiceEcaRule.java:157:INFO ] Running Service ECA Service: linkToProduct, 
triggered by rule on Service: createCommunicationEvent
2012-04-17 17:57:17,614 (default-invoker-Thread-19) [ 
ServiceDispatcher.java:604:INFO ] Sync service [default/linkToProduct] 
finished in [240] milliseconds with response [{responseMessage=success}]
2012-04-17 17:57:17,615 (default-invoker-Thread-19) [ 
ServiceDispatcher.java:604:INFO ] Sync service 
[default/createCommunicationEvent] finished in [761] milliseconds with 
response [{communicationEventId=10630, responseMessage=success}]
2012-04-17 17:57:17,616 (default-invoker-Thread-19) [ 
ServiceDispatcher.java:604:INFO ] Sync service 
[default/createCommEventFromEmail] finished in [781] milliseconds with 
response [{responseMessage=success, communicationEventId=10630}]
2012-04-17 17:57:17,616 (default-invoker-Thread-19) [ 
EmailServices.java:105:INFO ] SendMail Running, for communicationEventId : 
10630
2012-04-17 17:57:17,635 (default-invoker-Thread-19) [ 
EmailServices.java:255:INFO ] 2 multiparts found
2012-04-17 17:57:17,635 (default-invoker-Thread-19) [ 
EmailServices.java:261:INFO ] part of type: text/html and size: 7230
2012-04-17 17:57:17,637 (default-invoker-Thread-19) [ 
EmailServices.java:265:INFO ] part of type: application/pdf and size: 5892


org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:226)
org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:163)
org.ofbiz.party.communication.CommunicationEventServices.createAttachmentContent(CommunicationEventServices.java:1023)
org.ofbiz.party.communication.CommunicationEventServices.updateCommEventAfterEmail(CommunicationEventServices.java:649)
sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
java.lang.reflect.Method.invoke(Unknown Source)
org.ofbiz.service.engine.StandardJavaEngine.serviceInvoker(StandardJavaEngine.java:100)
org.ofbiz.service.engine.StandardJavaEngine.runSync(StandardJavaEngine.java:57)
org.ofbiz.service.ModelServiceReader$GenericInvokerImpl.runSync(ModelServiceReader.java:761)
_$gen.file_58$.applications.party.servicedef.services_46$xml_35$updateCommEventAfterEmail.runSync(file:/applications/party/servicedef/services.xml#updateCommEventAfterEmail:900)
org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:399)
org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:226)
org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:163)
org.ofbiz.service.eca.ServiceEcaAction.runAction(ServiceEcaAction.java:138)
org.ofbiz.service.eca.ServiceEcaRule.eval(ServiceEcaRule.java:159)
org.ofbiz.service.eca.ServiceEcaUtil.evalRules(ServiceEcaUtil.java:188)
org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:500)
org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:226)
org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:163)
org.ofbiz.common.email.EmailServices.sendMailFromScreen(EmailServices.java:587)
sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
java.lang.reflect.Method.invoke(Unknown Source)
org.ofbiz.service.engine.StandardJavaEngine.serviceInvoker(StandardJavaEngine.java:100)
org.ofbiz.service.engine.StandardJavaEngine.runSync(StandardJavaEngine.java:57)
org.ofbiz.service.ModelServiceReader$GenericInvokerImpl.runSync(ModelServiceReader.java:761)
_$gen.file_58$..framework.common.servicedef.services_95$email_46$xml_35$sendMailFromScreen.runSync(file:/framework/common/servicedef/services_email.xml#sendMailFromScreen:83)
org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:399)
org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:226)
org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:163)
org.ofbiz.order.order.OrderServices.sendOrderNotificationScreen(OrderServices.java:2704)
org.ofbiz.order.order.OrderServices.sendOrderConfirmNotification(OrderServices.java:2551)
sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
sun.reflect.NativeMethodAccessorImpl.invoke(Unknown Source)
sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
java.lang.reflect.Method.invoke(Unknown Source)
org.ofbiz.service.engine.StandardJavaEngine.serviceInvoker(StandardJavaEngine.java:100)
org.ofbiz.service.engine.StandardJavaEngine.runSync(StandardJavaEngine.java:57)
org.ofbiz.service.ModelServiceReader$GenericInvokerImpl.runSync(ModelServiceReader.java:761)
_$gen.file_58$.applications.order.servicedef.services_46$xml_35$sendOrderConfirmation.runSync(file:/applications/order/servicedef/services.xml#sendOrderConfirmation:50)
org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:399)
org.ofbiz.service.ServiceDispatcher.runSync(ServiceDispatcher.java:226)
org.ofbiz.service.GenericDispatcher.runSync(GenericDispatcher.java:163)
org.ofbiz.service.job.GenericServiceJob.exec(GenericServiceJob.java:71)
org.ofbiz.service.job.JobInvoker.run(JobInvoker.java:242)
java.lang.Thread.run(Unknown Source)
--------------------------------------------------------------------------------

2012-04-17 17:57:28,914 (default-invoker-Thread-19) [ 
TransactionUtil.java:378:WARN ]
---- exception 
report ----------------------------------------------------------
[TransactionUtil.setRollbackOnly] Calling transaction setRollbackOnly; this 
stack trace shows where this is happening:
Exception: java.lang.Exception
Message: Failure in findOne operation for entity [PartyRole]: 
org.ofbiz.entity.GenericModelException: [GenericDelegator.findOne] Passed 
primary key is not a valid primary key: 
[GenericEntity:PartyRole][roleTypeId,OWNER(java.lang.String)]. Rolling back 
transaction.
---- stack 
trace ---------------------------------------------------------------
java.lang.Exception: Failure in findOne operation for entity [PartyRole]: 
org.ofbiz.entity.GenericModelException: [GenericDelegator.findOne] Passed 
primary key is not a valid primary key: 
[GenericEntity:PartyRole][roleTypeId,OWNER(java.lang.String)]. Rolling back 
transaction.
org.ofbiz.entity.transaction.TransactionUtil.setRollbackOnly(TransactionUtil.java:378)
.
.
.
.
.
.


Thanks,
Krishan Babbar
----------------------------------------------------------------------------------------------------------------------------
Disclaimer: This email and any files transmitted with it may contain 
privileged or confidential information. It is solely for use by the 
individual for whom it is intended even if addressed incorrectly. If you 
received this email in error please notify the sender, do not disclose, 
copy, distribute or take any action in reliance on the contents of this 
information; and delete it from your system. Any other use of this email is 
prohibited. Thank you for your compliance.