I can confirm that this is not caused by low memory. I have been monitoring the memory and there are not such signs. I believe it is a combination of the code and the database server I am running with. Does any body use this combination (jdk1.5/1.6 + psql (PostgreSQL) 7.4.19 + redhat dedora 2.6.9-42.0.3.EL)? I will try to change or upgrade the database. Thanks.
On Sun, Feb 22, 2009 at 1:03 PM, BJ Freeman <[email protected]> wrote: > -----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----- >
