Re: quick ship order cause deadlock

Sun, 22 Feb 2009 23:52:17 -0800 

Please try to use the lastest version of Postgres and the problem should 
dissapear. Also why are you using a so old Kernel version ?

Jacques

From: "Hansen Wang" <[email protected]>

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:


-----BEGIN PGP SIGNED MESSAGE-----
Hash: SHA1

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

iD8DBQFJoUFArP3NbaWWqE4RAlxeAJ9V8miVMgRI18dFPoRjveC5h4s6WACfX9zQ
etyJ9Tm3NxjfyejdCQX2/qU=
=Ctns
-----END PGP SIGNATURE-----

Reply via email to