Joe, Let me just say I love academic topics like this....:) Here is my setup. I created a form and added a 'wait' selection field to it. I added a filter that fires when that wait field is set to "Yes". It's only action is to do a DB wait action of 5 seconds. I created 5 records in the form and added an escalation that fires with no qual that sets the wait flag to Yes. The end result of this is I have 5 records that will each wait 5 seconds to process, thus causing the escalation to run for 25 seconds every 5 min's. I then turned on Escalation and Filter logs into the same file. Attached are the def of these 3 objects and the log of the events. What I see is a single thread on a single RPC ID performing updates to 5 records and that thread handles the filter actions of that modify in the same way. My direct SQL gets deferred to Phase 2, whereas in our original topic the delete would get done in Phase 3, but either way the Escalation thread waits for all phase of processing to finish before it continues to the next record. In this scenario I chose 5 seconds to give sufficient time for the escalation thread to 'move on' if it was going to....it didn't.
-----Original Message----- From: Action Request System discussion list(ARSList) [mailto:arsl...@arslist.org] On Behalf Of Joe D'Souza Sent: Thursday, January 21, 2010 10:44 AM To: arslist@ARSLIST.ORG Subject: Re: Delete entry That's actually not true.. Filters use a different thread than Escalations.. Let me explain further what I learnt in a performance tuning class years ago (version 4 days) and I do not think this has changed with the later versions.. When an escalation does what it has to do, it terminates its job and exits.. and it runs with a specific thread ID (I do not recall it by memory what that ID is but its something like 600630??). But that's besides the point.. The filter that actually does the delete does not use the same thread number as the escalation (try thread logging and you will see this). So when you have an escalation do what it has to do, namely the first phase operation of Set Fields, it does that and it doesn't really care if the Filter does or does not execute the operations defined to fire on Modify when the $USER$ is the escalation user.. Filters when triggered fire and utilize a different thread. So the deletes that would be triggered as a result of the Modify operation by the Escalation will be independent of that Escalation thread, and the escalation thread exits before all of the deleted occur, thus being available to any other escalations that may be designed to immediately follow.. Joe -----Original Message----- From: Action Request System discussion list(ARSList) [mailto:arsl...@arslist.org]on Behalf Of LJ Longwing Sent: Thursday, January 21, 2010 12:31 PM To: arslist@ARSLIST.ORG Subject: Re: Delete entry But Joe, yes the set field happens in phase 1, but the delete action happening on the record itself is still a phase 3 action. Either way the Escalation has to wait for each record to 'process' before it goes onto the next record, so delete from the escalation vs delete from a filter on the record will cause the escalation thread to still wait, so I'm not sure I agree with your statements. -----Original Message----- From: Action Request System discussion list(ARSList) [mailto:arsl...@arslist.org] On Behalf Of Joe D'Souza Sent: Thursday, January 21, 2010 10:25 AM To: arslist@ARSLIST.ORG Subject: Re: Delete entry Fred, You are right in that calculation-wise it will not really have any benefit. But doing a delete in an escalation is not the best way - Delete is a 2nd phase operation and is queued. You do not want to hold the escalation thread longer than it should for this queue to complete. So having a filter aid the escalation to actually do the delete and the escalation only to mark the record to be deleted is a better approach as set fields is a fast operation that happens on the first phase of the transaction.. Joe -----Original Message----- From: Action Request System discussion list(ARSList) [mailto:arsl...@arslist.org]on Behalf Of Grooms, Frederick W Sent: Thursday, January 21, 2010 12:16 PM To: arslist@ARSLIST.ORG Subject: Re: Delete entry Since it is on the right side of the equation and not using any fields from the form the Escalation should only do the calculation once. As for setting a field and having a Filter do the delete, that is my preferred method as well Fred -----Original Message----- From: Action Request System discussion list(ARSList) [mailto:arsl...@arslist.org] On Behalf Of Joe D'Souza Sent: Thursday, January 21, 2010 10:51 AM To: arslist@ARSLIST.ORG Subject: Re: Delete entry ** John, Should work but I'd make the process a little more data driven.. Instead of having the escalation calculate all this and chocking an escalation thread, I'd set that delete time to a temp field at the time of submitting the record. For e.g.. create a temp field called ztmpDeleteTime. Set the time $TIMESTAMP$ + 86400 to it using a filter.. Then have the Escalation check 'ztmpDeleteTime' < $TIMESTAMP$ and mark the record for deletion using another temp field that you have that escalation set say 'ztmpDelete' to Y.. Do not have the escalation delete it again for the purpose of not choking the escalation thread. Have a filter that performs that delete when the record is marked for deletion and the $USER$ is the escalation user. It would be more efficient especially over a large number of records.. Joe -----Original Message----- From: Action Request System discussion list(ARSList) [mailto:arsl...@arslist.org]on Behalf Of John Kelley Sent: Thursday, January 21, 2010 11:39 AM To: arslist@ARSLIST.ORG Subject: Delete entry Hi All Brain cramp today Can someone confirm that this statement is true! I am performing a delete action in an escalation for deleting all items in a form 24 hours and beyond. Let me rephrase I want to leave items in the form for 24 hours from the submit date. 'Submit Date' < ( $TIMESTAMP$ - 86400) Sys:Action ARS 7.1 Thanks JK ____________________________________________________________________________ ___ UNSUBSCRIBE or access ARSlist Archives at www.arslist.org Platinum Sponsor:rmisoluti...@verizon.net ARSlist: "Where the Answers Are" _______________________________________________________________________________ UNSUBSCRIBE or access ARSlist Archives at www.arslist.org Platinum Sponsor:rmisoluti...@verizon.net ARSlist: "Where the Answers Are"
char-set: windows-1252 # # File exported Thu Jan 21 14:01:22 2010 # begin schema name : !LJTest core-version : 2 upgrade-version: 0 num-fields : 10 timestamp : 1264099720 owner : longwing.lj last-changed : longwing.lj default-vui : Default Admin View num-vuis : 1 export-version : 10 schema-type : 1 next-field-id : 536870914 vui { id : 536870912 name : Default Admin View label : Default Admin View timestamp : 1264099446 vui-locale : vui-type : 1 owner : longwing.lj last-changed : longwing.lj display-prop : 1\20\4\18\Default Admin View\ } field { id : 1 name : Request ID datatype : 4 fieldtype : 1 create-mode : 2 option : 3 timestamp : 1264099448 owner : longwing.lj last-changed : longwing.lj maxlength : 15 menu-style : 2 qbe-match-op : 1 fulltext-optns : 0 permission : 0\1 permission : 3\1 permission : 4\1 display-instance : 536870912\16\20\4\10\Request ID\61\40\18\60\40\1\3\41\2\1012\2500\19799\4900\4\6\1\28\6\4\29\6\1\27\8\16\7\40\2147483640\231\6\0\143\40\4294967295\21\41\2\0\& display-instance : 400\6862\2400\151\41\2\7425\0\18787\2100\65\41\2\0\0\0\0\66\41\2\0\0\0\0\170\40\0\ } field { id : 2 name : Submitter datatype : 4 fieldtype : 1 create-mode : 1 option : 1 timestamp : 1264099449 owner : longwing.lj last-changed : longwing.lj maxlength : 254 menu-style : 2 qbe-match-op : 1 fulltext-optns : 0 permission : 3\1 permission : 4\1 display-instance : 536870912\16\20\4\9\Submitter\61\40\30\60\40\1\3\41\2\1012\5000\29024\7200\4\6\1\28\6\4\29\6\1\27\8\16\7\40\2147483641\231\6\0\143\40\4294967295\21\41\2\0\40& display-instance : 0\6075\2200\151\41\2\6637\0\25087\2100\65\41\2\0\0\0\0\66\41\2\25650\0\28012\2100\170\40\0\ } field { id : 3 name : Create Date datatype : 7 fieldtype : 1 create-mode : 2 option : 3 timestamp : 1264099448 owner : longwing.lj last-changed : longwing.lj permission : 3\1 permission : 4\1 display-instance : 536870912\16\20\4\11\Create Date\61\40\23\60\40\1\3\41\2\1012\7500\26212\9800\4\6\1\28\6\4\29\6\1\27\8\16\7\40\2147483642\231\6\0\143\40\4294967295\170\40\0\& display-instance : 21\41\2\0\400\7200\2300\151\41\2\7762\0\22162\2100\65\41\2\0\0\0\0\66\41\2\22837\0\25199\2100\ } field { id : 4 name : Assigned To datatype : 4 fieldtype : 1 create-mode : 2 option : 2 timestamp : 1264099448 owner : longwing.lj last-changed : longwing.lj maxlength : 254 menu-style : 2 qbe-match-op : 1 fulltext-optns : 0 permission : 4\1 display-instance : 536870912\16\20\4\11\Assigned To\61\40\30\60\40\1\3\41\2\1012\10000\29137\12100\4\6\1\28\6\4\29\6\1\27\8\16\7\40\2147483643\231\6\0\143\40\4294967295\21\41\2& display-instance : \0\400\6637\2100\151\41\2\7200\0\25200\2100\65\41\2\0\0\0\0\66\41\2\25762\0\28124\2100\170\40\0\ } field { id : 5 name : Last Modified By datatype : 4 fieldtype : 1 create-mode : 2 option : 3 timestamp : 1264099448 owner : longwing.lj last-changed : longwing.lj maxlength : 254 menu-style : 2 qbe-match-op : 1 fulltext-optns : 0 permission : 4\1 display-instance : 536870912\16\20\4\16\Last Modified By\61\40\33\60\40\1\3\41\2\1012\12500\34199\14800\4\6\1\28\6\4\29\6\1\27\8\16\7\40\2147483644\231\6\0\143\40\4294967295\21& display-instance : \41\2\0\400\9562\2300\151\41\2\10125\0\30150\2100\65\41\2\0\0\0\0\66\41\2\30825\0\33187\2100\170\40\0\ } field { id : 6 name : Modified Date datatype : 7 fieldtype : 1 create-mode : 2 option : 3 timestamp : 1264099448 owner : longwing.lj last-changed : longwing.lj permission : 4\1 display-instance : 536870912\16\20\4\13\Modified Date\61\40\23\60\40\1\3\41\2\1012\15000\26999\17300\4\6\1\28\6\4\29\6\1\27\8\16\7\40\2147483645\231\6\0\143\40\4294967295\170\4& display-instance : 0\0\21\41\2\0\400\8212\2300\151\41\2\8775\0\22950\2100\65\41\2\0\0\0\0\66\41\2\23625\0\25987\2100\ } field { id : 7 name : Status datatype : 6 fieldtype : 1 create-mode : 2 option : 1 timestamp : 1264099449 owner : longwing.lj last-changed : longwing.lj enum-value : New enum-value : Assigned enum-value : Fixed enum-value : Rejected enum-value : Closed default : 0 permission : 3\1 permission : 4\2 display-instance : 536870912\18\20\4\6\Status\64\6\1\61\40\0\60\40\1\3\41\2\1012\17500\35887\19400\4\6\1\28\6\4\29\6\1\27\8\16\7\40\2147483646\231\6\0\230\4\46\5\0\New\1\Assign& display-instance : ed\2\Fixed\3\Rejected\4\Closed\143\40\4294967295\21\41\2\0\400\4162\1900\151\41\2\4725\400\34875\1800\65\41\2\0\0\0\0\66\41\2\0\0\0\0\170\40\0\ } field { id : 8 name : Short Description datatype : 4 fieldtype : 1 create-mode : 1 option : 1 timestamp : 1264099449 owner : longwing.lj last-changed : longwing.lj maxlength : 254 menu-style : 2 qbe-match-op : 1 fulltext-optns : 0 permission : 3\1 permission : 4\1 display-instance : 536870912\16\20\4\17\Short Description\61\40\50\60\40\1\3\41\2\1012\20000\45224\22100\4\6\1\28\6\4\29\6\1\27\8\16\7\40\2147483647\231\6\0\143\40\4294967295\2& display-instance : 1\41\2\0\400\11137\2100\151\41\2\11700\0\41287\2100\65\41\2\0\0\0\0\66\41\2\41850\0\44212\2100\170\40\0\ } field { id : 15 name : Status History datatype : 4 fieldtype : 1 create-mode : 2 option : 3 timestamp : 1264099446 owner : longwing.lj last-changed : longwing.lj permission : 3\1 permission : 4\1 } field { id : 536870913 name : Wait datatype : 6 fieldtype : 1 create-mode : 2 option : 4 timestamp : 1264099449 owner : longwing.lj last-changed : longwing.lj enum-value : Yes display-instance : 536870912\20\14\40\1\170\40\0\230\4\7\1\0\Yes\20\4\4\Wait\3\41\2\58162\16200\77624\18300\4\6\1\5\6\2\64\6\0\60\40\1\61\40\25\27\8\16\28\6\4\29\6\1\7\40\21474& display-instance : 83648\231\6\0\143\40\4294967295\21\41\2\0\400\11025\2100\151\41\2\11587\0\19462\2100\65\41\2\0\0\0\0\66\41\2\0\0\0\0\ } end begin filter name : !LJTest:Wait timestamp : 1264099698 owner : longwing.lj last-changed : longwing.lj filter-op : 2 enable : 1 filter-order : 500 wk-conn-type : 1 schema-name : !LJTest export-version : 10 filter-query : 4\1\99\536870913\2\6\0\ action { direct-sql : 1...@\21\waitfor delay '0:0:5'\ } errhandler-opt : 0 end begin escalation name : !LJTest:Escalation timestamp : 1264099764 owner : longwing.lj last-changed : longwing.lj enable : 1 escl-tmType : 1 export-version : 10 escl-interval : 300 wk-conn-type : 1 schema-name : !LJTest action { set-field : 0\536870913\101\6\0\ } end _______________________________________________________________________________ UNSUBSCRIBE or access ARSlist Archives at www.arslist.org Platinum Sponsor:rmisoluti...@verizon.net ARSlist: "Where the Answers Are"
<ESCL> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > /* Thu Jan 21 2010 13:54:24.5150 */Checking !LJTest:Escalation (enabled) : ready to fire now on !LJTest <ESCL> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > --> Passed -- perform actions <ESCL> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > 000000000000001 <ESCL> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > 0: Set Fields <ESCL> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > Wait (536870913) = 0 <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > /* Thu Jan 21 2010 13:54:24.5300 */ Start filter processing (phase 1) -- Operation - SET on !LJTest - 000000000000001 <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > /* Thu Jan 21 2010 13:54:24.5300 */ Checking "!LJTest:Wait" (500) <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > --> Passed -- perform actions <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > 0: Direct SQL <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > <deferred to phase 2> <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > /* Thu Jan 21 2010 13:54:24.5300 */ End of filter processing (phase 1) -- Operation - SET on !LJTest - 000000000000001 <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > /* Thu Jan 21 2010 13:54:24.5300 */ Restart of filter processing (phase 2) -- Operation - SET on !LJTest - 000000000000001 <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > /* Thu Jan 21 2010 13:54:24.5300 */ Checking "!LJTest:Wait" <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > 0: Direct SQL <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > waitfor delay '0:0:5' <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > /* Thu Jan 21 2010 13:54:29.5300 */ End of filter processing (phase 2) -- Operation - SET on !LJTest - 000000000000001 <ESCL> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > 000000000000002 <ESCL> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > 0: Set Fields <ESCL> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > Wait (536870913) = 0 <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > /* Thu Jan 21 2010 13:54:29.5300 */ Start filter processing (phase 1) -- Operation - SET on !LJTest - 000000000000002 <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > /* Thu Jan 21 2010 13:54:29.5300 */ Checking "!LJTest:Wait" (500) <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > --> Passed -- perform actions <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > 0: Direct SQL <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > <deferred to phase 2> <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > /* Thu Jan 21 2010 13:54:29.5300 */ End of filter processing (phase 1) -- Operation - SET on !LJTest - 000000000000002 <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > /* Thu Jan 21 2010 13:54:29.5300 */ Restart of filter processing (phase 2) -- Operation - SET on !LJTest - 000000000000002 <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > /* Thu Jan 21 2010 13:54:29.5300 */ Checking "!LJTest:Wait" <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > 0: Direct SQL <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > waitfor delay '0:0:5' <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > /* Thu Jan 21 2010 13:54:34.5300 */ End of filter processing (phase 2) -- Operation - SET on !LJTest - 000000000000002 <ESCL> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > 000000000000003 <ESCL> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > 0: Set Fields <ESCL> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > Wait (536870913) = 0 <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > /* Thu Jan 21 2010 13:54:34.5460 */ Start filter processing (phase 1) -- Operation - SET on !LJTest - 000000000000003 <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > /* Thu Jan 21 2010 13:54:34.5460 */ Checking "!LJTest:Wait" (500) <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > --> Passed -- perform actions <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > 0: Direct SQL <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > <deferred to phase 2> <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > /* Thu Jan 21 2010 13:54:34.5460 */ End of filter processing (phase 1) -- Operation - SET on !LJTest - 000000000000003 <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > /* Thu Jan 21 2010 13:54:34.5460 */ Restart of filter processing (phase 2) -- Operation - SET on !LJTest - 000000000000003 <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > /* Thu Jan 21 2010 13:54:34.5460 */ Checking "!LJTest:Wait" <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > 0: Direct SQL <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > waitfor delay '0:0:5' <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > /* Thu Jan 21 2010 13:54:39.5310 */ End of filter processing (phase 2) -- Operation - SET on !LJTest - 000000000000003 <ESCL> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > 000000000000004 <ESCL> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > 0: Set Fields <ESCL> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > Wait (536870913) = 0 <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > /* Thu Jan 21 2010 13:54:39.5310 */ Start filter processing (phase 1) -- Operation - SET on !LJTest - 000000000000004 <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > /* Thu Jan 21 2010 13:54:39.5310 */ Checking "!LJTest:Wait" (500) <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > --> Passed -- perform actions <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > 0: Direct SQL <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > <deferred to phase 2> <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > /* Thu Jan 21 2010 13:54:39.5310 */ End of filter processing (phase 1) -- Operation - SET on !LJTest - 000000000000004 <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > /* Thu Jan 21 2010 13:54:39.5310 */ Restart of filter processing (phase 2) -- Operation - SET on !LJTest - 000000000000004 <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > /* Thu Jan 21 2010 13:54:39.5310 */ Checking "!LJTest:Wait" <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > 0: Direct SQL <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > waitfor delay '0:0:5' <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > /* Thu Jan 21 2010 13:54:44.5310 */ End of filter processing (phase 2) -- Operation - SET on !LJTest - 000000000000004 <ESCL> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > 000000000000005 <ESCL> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > 0: Set Fields <ESCL> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > Wait (536870913) = 0 <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > /* Thu Jan 21 2010 13:54:44.5310 */ Start filter processing (phase 1) -- Operation - SET on !LJTest - 000000000000005 <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > /* Thu Jan 21 2010 13:54:44.5310 */ Checking "!LJTest:Wait" (500) <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > --> Passed -- perform actions <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > 0: Direct SQL <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > <deferred to phase 2> <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > /* Thu Jan 21 2010 13:54:44.5310 */ End of filter processing (phase 1) -- Operation - SET on !LJTest - 000000000000005 <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > /* Thu Jan 21 2010 13:54:44.5310 */ Restart of filter processing (phase 2) -- Operation - SET on !LJTest - 000000000000005 <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > /* Thu Jan 21 2010 13:54:44.5460 */ Checking "!LJTest:Wait" <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > 0: Direct SQL <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > waitfor delay '0:0:5' <FLTR> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > /* Thu Jan 21 2010 13:54:49.5460 */ End of filter processing (phase 2) -- Operation - SET on !LJTest - 000000000000005 <ESCL> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > /* Thu Jan 21 2010 13:54:49.5460 */!LJTest:Escalation completed <ESCL> <TID: 0000013848> <RPC ID: 0000001656> <Queue: Escalation> <Client-RPC: 390603 > <USER: AR_ESCALATOR (Pool 1) > /* Thu Jan 21 2010 13:54:49.5460 */Stop escalation processing _______________________________________________________________________________ UNSUBSCRIBE or access ARSlist Archives at www.arslist.org Platinum Sponsor:rmisoluti...@verizon.net ARSlist: "Where the Answers Are"