-----BEGIN PGP SIGNED MESSAGE----- Hash: SHA1 from the logs you don't have a DB deadlock problem but the EEAS is failing. so the question is why is not the data being passed. the only other thing different is permissions. possibly not enough memory.
Hansen Wang sent the following on 2/22/2009 11:24 AM: > The same configuration and data work fine in windows. Only difference is > that I have a newer version of postgreSQL (8.4) in windows. > > I tried to run it with 1.6 with jdbc4, still no difference that 1.5 with > jdbc3. > > I tried to make sendOrderCompleteNotification (invoked by eca of > changeOrderStatus to COMPLETED) run as syn. see some improvement that it > gets to the point of sending the email and creating communication event. > > I tried to remove sendOrderCompleteNotification from the above eca. it > works. > > So ther problem is between sendOrderCompletedNotification (after inserting > communication event) and the rest. Given my time constraints, I do not think > that I will be able to find and resolve the deadlock problem in java level > given the code is relative stable and many projects are using it. What I > would like to do is just want to get around it by either changing the server > env or re-arranging the executinon sequnce. Any advise is appreciated. > > > > On Sun, Feb 22, 2009 at 5:12 AM, BJ Freeman <[email protected]> wrote: > > not sure ofbiz 4.0 will run on 1.5. > would check the proper driver for PostgreSQL 7.4.19 are being used in ofbiz > looks like a loop after the first warning > [ServiceEcaCondition.java:127:WARN ] From Field (communicationEventId) is >>>> not found in context for createWorkEffort, defaulting to null. > > Hansen Wang sent the following on 2/22/2009 3:57 AM: >>>> I encountered the following deadlock when I tried to quick ship an entire >>>> order. >>>> >>>> The server environment are as bellows: >>>> >>>> psql (PostgreSQL) 7.4.19 >>>> 2.6.9-42.0.3.EL #1 Mon Sep 25 17:14:19 EDT 2006 i686 athlon i386 > GNU/Linux >>>> java version "1.5.0_06" >>>> ofbiz4.0 >>>> >>>> Please note that the order is the only order in the system. >>>> >>>> Attached bellow are db locks and ofbiz log. There may not be enough >>>> information to pinpoint the cause of the >>>> problem by examining the following logs. But please let me know if you > have >>>> seen similar problem like this >>>> before. >>>> >>>> >>>> 1. object locks sort by name. nothing suspecious from here. >>>> >>>> relname | relation | database | transaction | pid >>>> | mode | granted >>>> > -------------------------------+----------+----------+-------------+------+------------------+--------- >>>> contact_mech | 2644120 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> content | 2644156 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> data_resource | 2644335 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> entity_sync_remove | 2644501 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> entity_sync_remove | 2644501 | 2643821 | | 9483 > | >>>> RowExclusiveLock | t >>>> facility | 2644547 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> facility | 2644547 | 2643821 | | 9483 > | >>>> RowShareLock | t >>>> facility_contact_mech_purpose | 2644563 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> inventory_item | 2644829 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> inventory_item | 2644829 | 2643821 | | 9483 > | >>>> RowShareLock | t >>>> item_issuance | 2644925 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> item_issuance | 2644925 | 2643821 | | 9483 > | >>>> RowShareLock | t >>>> item_issuance | 2644925 | 2643821 | | 9483 > | >>>> RowExclusiveLock | t >>>> item_issuance_role | 2644929 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> item_issuance_role | 2644929 | 2643821 | | 9483 > | >>>> RowExclusiveLock | t >>>> job_sandbox | 2644933 | 2643821 | | 9535 > | >>>> AccessShareLock | t >>>> job_sandbox | 2644933 | 2643821 | | 9535 > | >>>> RowExclusiveLock | t >>>> note_data | 2644988 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> order_adjustment | 2645019 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> order_contact_mech | 2645051 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> order_header | 2645067 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> order_header | 2645067 | 2643821 | | 9483 > | >>>> RowShareLock | t >>>> order_header | 2645067 | 2643821 | | 9483 > | >>>> RowExclusiveLock | t >>>> order_item | 2645079 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> order_item | 2645079 | 2643821 | | 9483 > | >>>> RowShareLock | t >>>> order_item | 2645079 | 2643821 | | 9483 > | >>>> RowExclusiveLock | t >>>> order_item_ship_group | 2645115 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> order_item_ship_group_assoc | 2645119 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> order_item_ship_grp_inv_res | 2645123 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> order_item_ship_grp_inv_res | 2645123 | 2643821 | | 9483 > | >>>> RowExclusiveLock | t >>>> order_role | 2645147 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> order_shipment | 2645151 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> order_status | 2645155 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> order_status | 2645155 | 2643821 | | 9483 > | >>>> RowExclusiveLock | t >>>> party | 2645186 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> party | 2645186 | 2643821 | | 9483 > | >>>> RowShareLock | t >>>> party_contact_mech | 2645217 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> party_role | 2645293 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> party_role | 2645293 | 2643821 | | 9483 > | >>>> RowShareLock | t >>>> party_role | 2645293 | 2643821 | | 9483 > | >>>> RowExclusiveLock | t >>>> pg_class | 1259 | 2643821 | | 9660 > | >>>> AccessShareLock | t >>>> pg_locks | 16759 | 2643821 | | 9660 > | >>>> AccessShareLock | t >>>> pk_user_login | 2646560 | 2643821 | | 9535 > | >>>> AccessShareLock | t >>>> postal_address | 2645443 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> postal_address | 2645443 | 2643821 | | 9483 > | >>>> RowShareLock | t >>>> product | 2645483 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> product | 2645483 | 2643821 | | 9483 > | >>>> RowShareLock | t >>>> product_store | 2645789 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> return_item | 2646009 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> role_type | 2646041 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> role_type | 2646041 | 2643821 | | 9483 > | >>>> RowShareLock | t >>>> rtn_itm_byorditm | 2656311 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> runtime_data | 2646049 | 2643821 | | 9535 > | >>>> AccessShareLock | t >>>> runtime_data | 2646049 | 2643821 | | 9535 > | >>>> RowShareLock | t >>>> runtime_data | 2646049 | 2643821 | | 9535 > | >>>> RowExclusiveLock | t >>>> shipment | 2646157 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> shipment | 2646157 | 2643821 | | 9483 > | >>>> RowShareLock | t >>>> shipment | 2646157 | 2643821 | | 9483 > | >>>> RowExclusiveLock | t >>>> shipment_item | 2646181 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> shipment_item | 2646181 | 2643821 | | 9483 > | >>>> RowShareLock | t >>>> shipment_item | 2646181 | 2643821 | | 9483 > | >>>> RowExclusiveLock | t >>>> shipment_method_type | 2646193 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> shipment_method_type | 2646193 | 2643821 | | 9483 > | >>>> RowShareLock | t >>>> shipment_package | 2646197 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> shipment_route_segment | 2646220 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> shipment_route_segment | 2646220 | 2643821 | | 9483 > | >>>> RowExclusiveLock | t >>>> shipment_status | 2646227 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> shipment_status | 2646227 | 2643821 | | 9483 > | >>>> RowExclusiveLock | t >>>> shipment_type | 2646231 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> shipment_type | 2646231 | 2643821 | | 9483 > | >>>> RowShareLock | t >>>> status_item | 2646279 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> status_item | 2646279 | 2643821 | | 9483 > | >>>> RowShareLock | t >>>> status_valid_change | 2646287 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> telecom_number | 2646444 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> telecom_number | 2646444 | 2643821 | | 9483 > | >>>> RowShareLock | t >>>> user_login | 2646558 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> user_login | 2646558 | 2643821 | | 9483 > | >>>> RowShareLock | t >>>> user_login | 2646558 | 2643821 | | 9535 > | >>>> AccessShareLock | t >>>> user_login | 2646558 | 2643821 | | 9535 > | >>>> RowShareLock | t >>>> user_login_security_group | 2646566 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> work_effort | 2646661 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> work_effort | 2646661 | 2643821 | | 9483 > | >>>> RowShareLock | t >>>> work_effort | 2646661 | 2643821 | | 9483 > | >>>> RowExclusiveLock | t >>>> work_effort_attribute | 2646688 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> work_effort_content | 2646700 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> work_effort_keyword | 2646740 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> work_effort_keyword | 2646740 | 2643821 | | 9483 > | >>>> RowExclusiveLock | t >>>> work_effort_note | 2646744 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> work_effort_party_assignment | 2646748 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> work_effort_party_assignment | 2646748 | 2643821 | | 9483 > | >>>> RowExclusiveLock | t >>>> work_effort_status | 2646775 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> work_effort_status | 2646775 | 2643821 | | 9483 > | >>>> RowExclusiveLock | t >>>> work_effort_type | 2646783 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> work_effort_type | 2646783 | 2643821 | | 9483 > | >>>> RowShareLock | t >>>> >>>> >>>> 2. object locks sort pid. nothing suspecious from here. >>>> >>>> relname | relation | database | transaction | pid >>>> | mode | granted >>>> > -------------------------------+----------+----------+-------------+------+------------------+--------- >>>> contact_mech | 2644120 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> content | 2644156 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> data_resource | 2644335 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> entity_sync_remove | 2644501 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> entity_sync_remove | 2644501 | 2643821 | | 9483 > | >>>> RowExclusiveLock | t >>>> facility | 2644547 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> facility | 2644547 | 2643821 | | 9483 > | >>>> RowShareLock | t >>>> facility_contact_mech_purpose | 2644563 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> inventory_item | 2644829 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> inventory_item | 2644829 | 2643821 | | 9483 > | >>>> RowShareLock | t >>>> item_issuance | 2644925 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> item_issuance | 2644925 | 2643821 | | 9483 > | >>>> RowShareLock | t >>>> item_issuance | 2644925 | 2643821 | | 9483 > | >>>> RowExclusiveLock | t >>>> item_issuance_role | 2644929 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> item_issuance_role | 2644929 | 2643821 | | 9483 > | >>>> RowExclusiveLock | t >>>> note_data | 2644988 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> order_adjustment | 2645019 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> order_contact_mech | 2645051 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> order_header | 2645067 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> order_header | 2645067 | 2643821 | | 9483 > | >>>> RowShareLock | t >>>> order_header | 2645067 | 2643821 | | 9483 > | >>>> RowExclusiveLock | t >>>> order_item | 2645079 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> order_item | 2645079 | 2643821 | | 9483 > | >>>> RowShareLock | t >>>> order_item | 2645079 | 2643821 | | 9483 > | >>>> RowExclusiveLock | t >>>> order_item_ship_group | 2645115 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> order_item_ship_group_assoc | 2645119 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> order_item_ship_grp_inv_res | 2645123 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> order_item_ship_grp_inv_res | 2645123 | 2643821 | | 9483 > | >>>> RowExclusiveLock | t >>>> order_role | 2645147 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> order_shipment | 2645151 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> order_status | 2645155 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> order_status | 2645155 | 2643821 | | 9483 > | >>>> RowExclusiveLock | t >>>> party | 2645186 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> party | 2645186 | 2643821 | | 9483 > | >>>> RowShareLock | t >>>> party_contact_mech | 2645217 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> party_role | 2645293 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> party_role | 2645293 | 2643821 | | 9483 > | >>>> RowShareLock | t >>>> party_role | 2645293 | 2643821 | | 9483 > | >>>> RowExclusiveLock | t >>>> postal_address | 2645443 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> postal_address | 2645443 | 2643821 | | 9483 > | >>>> RowShareLock | t >>>> product | 2645483 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> product | 2645483 | 2643821 | | 9483 > | >>>> RowShareLock | t >>>> product_store | 2645789 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> return_item | 2646009 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> role_type | 2646041 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> role_type | 2646041 | 2643821 | | 9483 > | >>>> RowShareLock | t >>>> rtn_itm_byorditm | 2656311 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> shipment | 2646157 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> shipment | 2646157 | 2643821 | | 9483 > | >>>> RowShareLock | t >>>> shipment | 2646157 | 2643821 | | 9483 > | >>>> RowExclusiveLock | t >>>> shipment_item | 2646181 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> shipment_item | 2646181 | 2643821 | | 9483 > | >>>> RowShareLock | t >>>> shipment_item | 2646181 | 2643821 | | 9483 > | >>>> RowExclusiveLock | t >>>> shipment_method_type | 2646193 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> shipment_method_type | 2646193 | 2643821 | | 9483 > | >>>> RowShareLock | t >>>> shipment_package | 2646197 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> shipment_route_segment | 2646220 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> shipment_route_segment | 2646220 | 2643821 | | 9483 > | >>>> RowExclusiveLock | t >>>> shipment_status | 2646227 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> shipment_status | 2646227 | 2643821 | | 9483 > | >>>> RowExclusiveLock | t >>>> shipment_type | 2646231 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> shipment_type | 2646231 | 2643821 | | 9483 > | >>>> RowShareLock | t >>>> status_item | 2646279 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> status_item | 2646279 | 2643821 | | 9483 > | >>>> RowShareLock | t >>>> status_valid_change | 2646287 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> telecom_number | 2646444 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> telecom_number | 2646444 | 2643821 | | 9483 > | >>>> RowShareLock | t >>>> user_login | 2646558 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> user_login | 2646558 | 2643821 | | 9483 > | >>>> RowShareLock | t >>>> user_login_security_group | 2646566 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> work_effort | 2646661 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> work_effort | 2646661 | 2643821 | | 9483 > | >>>> RowShareLock | t >>>> work_effort | 2646661 | 2643821 | | 9483 > | >>>> RowExclusiveLock | t >>>> work_effort_attribute | 2646688 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> work_effort_content | 2646700 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> work_effort_keyword | 2646740 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> work_effort_keyword | 2646740 | 2643821 | | 9483 > | >>>> RowExclusiveLock | t >>>> work_effort_note | 2646744 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> work_effort_party_assignment | 2646748 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> work_effort_party_assignment | 2646748 | 2643821 | | 9483 > | >>>> RowExclusiveLock | t >>>> work_effort_status | 2646775 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> work_effort_status | 2646775 | 2643821 | | 9483 > | >>>> RowExclusiveLock | t >>>> work_effort_type | 2646783 | 2643821 | | 9483 > | >>>> AccessShareLock | t >>>> work_effort_type | 2646783 | 2643821 | | 9483 > | >>>> RowShareLock | t >>>> job_sandbox | 2644933 | 2643821 | | 9535 > | >>>> AccessShareLock | t >>>> job_sandbox | 2644933 | 2643821 | | 9535 > | >>>> RowExclusiveLock | t >>>> pk_user_login | 2646560 | 2643821 | | 9535 > | >>>> AccessShareLock | t >>>> runtime_data | 2646049 | 2643821 | | 9535 > | >>>> AccessShareLock | t >>>> runtime_data | 2646049 | 2643821 | | 9535 > | >>>> RowShareLock | t >>>> runtime_data | 2646049 | 2643821 | | 9535 > | >>>> RowExclusiveLock | t >>>> user_login | 2646558 | 2643821 | | 9535 > | >>>> AccessShareLock | t >>>> user_login | 2646558 | 2643821 | | 9535 > | >>>> RowShareLock | t >>>> pg_class | 1259 | 2643821 | | 9660 > | >>>> AccessShareLock | t >>>> pg_locks | 16759 | 2643821 | | 9660 > | >>>> AccessShareLock | t >>>> >>>> >>>> 3. transaction locks. transaxtion 923120 has two postgres process 9483 > and >>>> 9535. how did this happen? >>>> it seems caused by runAsyn using the same transaxtion context? >>>> >>>> relation | database | transaction | pid | mode | granted >>>> ----------+----------+-------------+------+---------------+--------- >>>> | | 923120 | 9483 | ExclusiveLock | t >>>> | | 923120 | 9535 | ShareLock | f >>>> | | 923136 | 9535 | ExclusiveLock | t >>>> | | 923210 | 9660 | ExclusiveLock | t >>>> >>>> 4. ofbiz logs. >>>> >>>> 2009-02-22 04:13:18,628 (http-0.0.0.0-8443-Processor4) [ >>>> 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] >>>> 2009-02-22 04:13:18,629 (http-0.0.0.0-8443-Processor4) [ >>>> RequestHandler.java:236:INFO ] [Processing Request]: quickShipOrder >>>> sessionId=2C128ED9735B132A42285B1D4025C7B8.jvm1 >>>> 2009-02-22 04:13:18,776 (http-0.0.0.0-8443-Processor4) [ >>>> SequenceUtil.java:254:INFO ] Got bank of sequenced IDs for [Shipment]; >>>> curSeqId=10040, maxSeqId=10050, bankSize=10 >>>> 2009-02-22 04:13:18,869 (http-0.0.0.0-8443-Processor4) [ >>>> SequenceUtil.java:254:INFO ] Got bank of sequenced IDs for [WorkEffort]; >>>> curSeqId=10040, maxSeqId=10050, bankSize=10 >>>> 2009-02-22 04:13:18,881 (http-0.0.0.0-8443-Processor4) [ >>>> EntityEcaRule.java:111:INFO ] Running Entity ECA Service: >>>> indexWorkEffortKeywords, triggered by rule on Entity: WorkEffort >>>> 2009-02-22 04:13:19,387 (http-0.0.0.0-8443-Processor4) [ >>>> ServiceEcaRule.java:137:INFO ] Running Service ECA Service: >>>> quickAssignPartyToWorkEffort, triggered by rule on Service: > createWorkEffort >>>> 2009-02-22 04:13:19,399 (http-0.0.0.0-8443-Processor4) >>>> [ServiceEcaCondition.java:127:WARN ] From Field (communicationEventId) is >>>> not found in context for createWorkEffort, defaulting to null. >>>> 2009-02-22 04:13:19,400 (http-0.0.0.0-8443-Processor4) [ >>>> ServiceEcaRule.java:112:INFO ] Got false for condition: >>>> [null][][communicationEventId][is-not-empty][null][][true][String][] >>>> 2009-02-22 04:13:19,423 (http-0.0.0.0-8443-Processor4) [ >>>> EntityEcaRule.java:111:INFO ] Running Entity ECA Service: >>>> indexWorkEffortKeywords, triggered by rule on Entity: WorkEffort >>>> 2009-02-22 04:13:19,454 (http-0.0.0.0-8443-Processor4) [ >>>> ServiceEcaRule.java:137:INFO ] Running Service ECA Service: >>>> quickAssignPartyToWorkEffort, triggered by rule on Service: > createWorkEffort >>>> 2009-02-22 04:13:19,458 (http-0.0.0.0-8443-Processor4) >>>> [ServiceEcaCondition.java:127:WARN ] From Field (communicationEventId) is >>>> not found in context for createWorkEffort, defaulting to null. >>>> 2009-02-22 04:13:19,459 (http-0.0.0.0-8443-Processor4) [ >>>> ServiceEcaRule.java:112:INFO ] Got false for condition: >>>> [null][][communicationEventId][is-not-empty][null][][true][String][] >>>> 2009-02-22 04:13:19,468 (http-0.0.0.0-8443-Processor4) [ >>>> ServiceEcaRule.java:112:INFO ] Got false for condition: >>>> [null][][statusId][equals][null][SHIPMENT_SCHEDULED][true][String][] >>>> 2009-02-22 04:13:19,469 (http-0.0.0.0-8443-Processor4) [ >>>> ServiceEcaRule.java:137:INFO ] Running Service ECA Service: >>>> setShipmentSettingsFromFacilities, triggered by rule on Service: >>>> createShipment >>>> 2009-02-22 04:13:19,479 (http-0.0.0.0-8443-Processor4) [ >>>> FieldToResult.java:66 :WARN ] Field value not found with name >>>> lookedUpValue.shipmentTypeId in Map with name >>>> 2009-02-22 04:13:19,480 (http-0.0.0.0-8443-Processor4) [ >>>> FieldToResult.java:66 :WARN ] Field value not found with name >>>> lookedUpValue.destinationFacilityId in Map with name >>>> 2009-02-22 04:13:19,486 (http-0.0.0.0-8443-Processor4) [ >>>> ServiceEcaRule.java:112:INFO ] Got false for condition: >>>> [null][][statusId][equals][null][SHIPMENT_PACKED][true][String][] >>>> 2009-02-22 04:13:19,487 (http-0.0.0.0-8443-Processor4) [ >>>> ServiceEcaRule.java:112:INFO ] Got false for condition: >>>> [null][][statusId][equals][null][PURCH_SHIP_RECEIVED][true][String][] >>>> 2009-02-22 04:13:19,488 (http-0.0.0.0-8443-Processor4) [ >>>> ServiceEcaRule.java:112:INFO ] Got false for condition: >>>> [null][][statusId][equals][null][PURCH_SHIP_SHIPPED][true][String][] >>>> 2009-02-22 04:13:19,489 (http-0.0.0.0-8443-Processor4) [ >>>> ServiceEcaRule.java:112:INFO ] Got false for condition: >>>> [null][][statusId][equals][null][PURCH_SHIP_RECEIVED][true][String][] >>>> 2009-02-22 04:13:19,489 (http-0.0.0.0-8443-Processor4) [ >>>> ServiceEcaRule.java:112:INFO ] Got false for condition: >>>> [null][][statusId][equals][null][PURCH_SHIP_RECEIVED][true][String][] >>>> 2009-02-22 04:13:19,490 (http-0.0.0.0-8443-Processor4) [ >>>> ServiceEcaRule.java:112:INFO ] Got false for condition: >>>> [null][][statusId][equals][null][SHIPMENT_SCHEDULED][true][String][] >>>> 2009-02-22 04:13:19,491 (http-0.0.0.0-8443-Processor4) [ >>>> ServiceEcaRule.java:112:INFO ] Got false for condition: >>>> > [null][][originFacilityId][not-equals][][oldOriginFacilityId][false][String][] >>>> 2009-02-22 04:13:19,492 (http-0.0.0.0-8443-Processor4) [ >>>> ServiceEcaRule.java:112:INFO ] Got false for condition: >>>> [null][][destinationFacilityId][is-not-empty][null][][true][String][] >>>> 2009-02-22 04:13:19,492 (http-0.0.0.0-8443-Processor4) [ >>>> ServiceEcaRule.java:112:INFO ] Got false for condition: >>>> > [null][][primaryOrderId][not-equals][][oldPrimaryOrderId][false][String][] >>>> 2009-02-22 04:13:19,493 (http-0.0.0.0-8443-Processor4) >>>> [ServiceEcaCondition.java:127:WARN ] From Field (destinationFacilityId) > is >>>> not found in context for createShipment, defaulting to null. >>>> 2009-02-22 04:13:19,494 (http-0.0.0.0-8443-Processor4) [ >>>> ServiceEcaRule.java:112:INFO ] Got false for condition: >>>> [null][][destinationFacilityId][is-not-empty][null][][true][String][] >>>> 2009-02-22 04:13:19,495 (http-0.0.0.0-8443-Processor4) [ >>>> ServiceEcaRule.java:137:INFO ] Running Service ECA Service: >>>> setShipmentSettingsFromPrimaryOrder, triggered by rule on Service: >>>> createShipment >>>> 2009-02-22 04:13:19,611 (http-0.0.0.0-8443-Processor4) [ >>>> FieldToResult.java:66 :WARN ] Field value not found with name >>>> lookedUpValue.shipmentTypeId in Map with name >>>> 2009-02-22 04:13:19,624 (http-0.0.0.0-8443-Processor4) [ >>>> FieldToResult.java:66 :WARN ] Field value not found with name >>>> lookedUpValue.destinationFacilityId in Map with name >>>> 2009-02-22 04:13:19,629 (http-0.0.0.0-8443-Processor4) [ >>>> ServiceEcaRule.java:112:INFO ] Got false for condition: >>>> [null][][statusId][equals][null][SHIPMENT_PACKED][true][String][] >>>> 2009-02-22 04:13:19,630 (http-0.0.0.0-8443-Processor4) [ >>>> ServiceEcaRule.java:112:INFO ] Got false for condition: >>>> [null][][statusId][equals][null][PURCH_SHIP_RECEIVED][true][String][] >>>> 2009-02-22 04:13:19,631 (http-0.0.0.0-8443-Processor4) [ >>>> ServiceEcaRule.java:112:INFO ] Got false for condition: >>>> [null][][statusId][equals][null][PURCH_SHIP_SHIPPED][true][String][] >>>> 2009-02-22 04:13:19,632 (http-0.0.0.0-8443-Processor4) [ >>>> ServiceEcaRule.java:112:INFO ] Got false for condition: >>>> [null][][statusId][equals][null][PURCH_SHIP_RECEIVED][true][String][] >>>> 2009-02-22 04:13:19,632 (http-0.0.0.0-8443-Processor4) [ >>>> ServiceEcaRule.java:112:INFO ] Got false for condition: >>>> [null][][statusId][equals][null][PURCH_SHIP_RECEIVED][true][String][] >>>> 2009-02-22 04:13:19,633 (http-0.0.0.0-8443-Processor4) [ >>>> ServiceEcaRule.java:112:INFO ] Got false for condition: >>>> [null][][statusId][equals][null][SHIPMENT_SCHEDULED][true][String][] >>>> 2009-02-22 04:13:19,634 (http-0.0.0.0-8443-Processor4) [ >>>> ServiceEcaRule.java:112:INFO ] Got false for condition: >>>> > [null][][originFacilityId][not-equals][][oldOriginFacilityId][false][String][] >>>> 2009-02-22 04:13:19,635 (http-0.0.0.0-8443-Processor4) [ >>>> ServiceEcaRule.java:112:INFO ] Got false for condition: >>>> [null][][destinationFacilityId][is-not-empty][null][][true][String][] >>>> 2009-02-22 04:13:19,635 (http-0.0.0.0-8443-Processor4) [ >>>> ServiceEcaRule.java:112:INFO ] Got false for condition: >>>> > [null][][primaryOrderId][not-equals][][oldPrimaryOrderId][false][String][] >>>> 2009-02-22 04:13:19,692 (http-0.0.0.0-8443-Processor4) [ >>>> Log.java:94 :INFO ] order item ship grp inv res info: >>>> [GenericEntity:OrderItemShipGrpInvRes][createdDatetime,2009-02-22 >>>> 00:50:40.581(java.sql.Timestamp)][createdStamp,2009-02-22 >>>> 00:50:40.581(java.sql.Timestamp)][createdTxStamp,2009-02-22 >>>> > 00:50:38.29(java.sql.Timestamp)][currentPromisedDate,null()][inventoryItemId,87976(java.lang.String)][lastUpdatedStamp,2009-02-22 >>>> 00:50:40.581(java.sql.Timestamp)][lastUpdatedTxStamp,2009-02-22 >>>> > 00:50:38.29(java.sql.Timestamp)][oldPickStartDate,null()][orderId,GM10000(java.lang.String)][orderItemSeqId,00001(java.lang.String)][promisedDatetime,2009-03-24 > 00:50:38.35(java.sql.Timestamp)][quantity,1.0(java.lang.Double)][quantityNotAvailable,null()][reserveOrderEnumId,INVRO_FIFO_REC(java.lang.String)][reservedDatetime,2009-02-22 >>>> 00:50:40.581(java.sql.Timestamp)][shipGroupSeqId,00001(java.lang.String)] >>>> 2009-02-22 04:13:19,705 (http-0.0.0.0-8443-Processor4) [ >>>> Calculate.java:342:INFO ] Field not found with field-name >>>> orderShipment.quantity, and map-name using a default of 0 >>>> 2009-02-22 04:13:19,720 (http-0.0.0.0-8443-Processor4) [ >>>> ServiceEcaRule.java:137:INFO ] Running Service ECA Service: >>>> checkCreateStockRequirementQoh, triggered by rule on Service: >>>> createItemIssuance >>>> 2009-02-22 04:13:19,812 (http-0.0.0.0-8443-Processor4) [ >>>> SequenceUtil.java:254:INFO ] Got bank of sequenced IDs for > [ItemIssuance]; >>>> curSeqId=10040, maxSeqId=10050, bankSize=10 >>>> 2009-02-22 04:13:19,910 (http-0.0.0.0-8443-Processor4) [ >>>> SequenceUtil.java:254:INFO ] Got bank of sequenced IDs for >>>> [EntitySyncRemove]; curSeqId=10050, maxSeqId=10060, bankSize=10 >>>> 2009-02-22 04:13:19,958 (http-0.0.0.0-8443-Processor4) [ >>>> SequenceUtil.java:254:INFO ] Got bank of sequenced IDs for [OrderStatus]; >>>> curSeqId=10040, maxSeqId=10050, bankSize=10 >>>> 2009-02-22 04:13:19,978 (http-0.0.0.0-8443-Processor4) [ >>>> ServiceEcaRule.java:112:INFO ] Got false for condition: >>>> [null][][statusId][equals][null][ITEM_CANCELLED][true][String][] >>>> 2009-02-22 04:13:19,979 (http-0.0.0.0-8443-Processor4) [ >>>> ServiceEcaRule.java:137:INFO ] Running Service ECA Service: >>>> checkOrderItemStatus, triggered by rule on Service: changeOrderItemStatus >>>> 2009-02-22 04:13:19,994 (http-0.0.0.0-8443-Processor4) [ >>>> ServiceEcaRule.java:112:INFO ] Got false for condition: >>>> [null][][statusId][equals][null][ORDER_CANCELLED][true][String][] >>>> 2009-02-22 04:13:19,995 (http-0.0.0.0-8443-Processor4) [ >>>> ServiceEcaRule.java:137:INFO ] Running Service ECA Service: > resetGrandTotal, >>>> triggered by rule on Service: changeOrderStatus >>>> 2009-02-22 04:13:20,004 (http-0.0.0.0-8443-Processor4) [ >>>> ServiceEcaRule.java:137:INFO ] Running Service ECA Service: >>>> sendOrderCompleteNotification, triggered by rule on Service: >>>> changeOrderStatus >>>> 2009-02-22 04:13:20,007 (http-0.0.0.0-8443-Processor4) [ >>>> SequenceUtil.java:254:INFO ] Got bank of sequenced IDs for [RuntimeData]; >>>> curSeqId=10040, maxSeqId=10050, bankSize=10 >>>> 2009-02-22 04:13:20,054 (http-0.0.0.0-8443-Processor4) [ >>>> SequenceUtil.java:254:INFO ] Got bank of sequenced IDs for [JobSandbox]; >>>> curSeqId=10060, maxSeqId=10070, bankSize=10 >>>> >> -----BEGIN PGP SIGNATURE----- Version: GnuPG v1.4.6 (MingW32) Comment: Using GnuPG with Mozilla - http://enigmail.mozdev.org iD8DBQFJoa+mrP3NbaWWqE4RAtMtAJ9hB4MO5hzF6n1h41rqDrn2r098hgCgneDL oedZgdu4/dbAlcVlNIqLcOY= =ewJz -----END PGP SIGNATURE-----
