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
