Dtrace log entries for the example custom filter

This section gives examples of the lines in the Dtrace log. The lines that are included show the archiving task loading the custom filter, evaluating the appointment item, and applying the rule actions.

In the Dtrace log, lines similar to the following show that the example custom filter has loaded successfully.

1167927	06:23:38.027	 [6860]	(ArchiveTask)	<17472> 
EV~I Event ID: 45329 External Filter 'EnterpriseVault.CustomFilter' 
initialising... |
1167950	06:23:38.308	 [6860]	(ArchiveTask)	<17472> EV-M
{CustomPropertiesDefinition} Loading Custom Properties from file:
\C:\PROGRAM FILES\ENTERPRISE VAULT\Custom Filter Rules\
Custom Properties.xml
1167951	06:23:38.308	 [6860]	(ArchiveTask)	<17472> EV-L
{CustomPropertiesDefinition} Loading Custom Property definitions...
1167952	06:23:38.324	 [6860]	(ArchiveTask)	<17472> EV-L
{CustomPropertiesDefinition} Adding property MSGCLASS [namespace=]
1167953	06:23:38.324	 [6860]	(ArchiveTask)	<17472> EV-L
{CustomPropertiesDefinition} Adding content categories...
1167954	06:23:38.324	 [6860]	(ArchiveTask)	<17472> EV-L
{CustomPropertiesDefinition} Adding category MsgClassTest
1167955	06:23:38.324	 [6860]	(ArchiveTask)	<17472> EV-L
{CustomPropertiesDefinition} Default Category = MsgClassTest
1167956	06:23:38.339	 [6860]	(ArchiveTask)	<17472> EV-L
{CustomPropertiesDefinition} Adding presentation applications...
1167957	06:23:38.339	 [6860]	(ArchiveTask)	<17472> EV-L
{CustomPropertiesDefinition} Adding application search.asp 
(Locale='1033')
1167958	06:23:38.339	 [6860]	(ArchiveTask)	<17472> EV:M
[CustomXMLFilter] Setting DEFAULT Content Category to [MsgClassTest]
1167959	06:23:38.339	 [6860]	(ArchiveTask)	<17472> EV:M
Adding External Filter 'EnterpriseVault.CustomFilter' to the list 
for processing|
1167960	06:23:38.339	 [6860]	(ArchiveTask)	<17472> EV:M
Successfully added External Filter 'EnterpriseVault.CustomFilter'|
Calling Initialize
1167961	06:23:38.339	 [6860]	(ArchiveTask)	<17472> EV:M
[CustomXMLFilter] Custom Filter initialized on thread.
1167962	06:23:38.339	 [6860]	(ArchiveTask)	<17472> EV:M
CEVFilterController::CreateFilterObject() (Exit) |Success  [0] |
1167963	06:23:38.339	 [6860]	(ArchiveTask)	<17472> EV:M
CEVFilterController::InitializeFiltersFromRegistry - MoveOnFilterFailure 
RegKey: [0x00000000]
1167964	06:23:38.339	 [6860]	(ArchiveTask)	<17472> EV:M
CEVFilterController::InitializeFiltersFromRegistry() (Exit) |Success  [0] |
1167965	06:23:38.339	 [6860]	(ArchiveTask)	<17472> EV:M
Successfully enabled external filtering

Lines similar to the following show the appointment is evaluated using the example filter rule, and matches:

1171158	06:23:49.996	 [6860]	(ArchiveTask)	<17472> EV:H
[CustomXMLFilter] Custom Filter processing message 'test appointment'
1171159	06:23:49.996	 [6860]	(ArchiveTask)	<17472> EV:L
...
1171161	06:23:49.996	 [6860]	(ArchiveTask)	<17472> EV:H
[CustomRules][CRuleSet] Getting rule data...
...
1171164	06:23:50.058	 [6860]	(ArchiveTask)	<17472> EV:H
[CustomXMLFilter] New RuleDataXML is now '<?xml version="1.0" 
encoding="UTF-16"?> <RULE_DATA><DATATYPE NAME="NAMEDPROPERTIES">
<DATA NAME="TAG"><VALUE>MSGCLASS</VALUE> </DATA></DATATYPE>
</RULE_DATA>'
1171165	06:23:50.058	 [6860]	(ArchiveTask)	<17472> EV:L
[CustomXMLFilter] GetMessageNamedProperties - XML RULE Data ='
<?xml version="1.0" encoding="UTF-16"?><RULE_DATA><DATATYPE NAME="NAMEDPROPERTIES"><DATA NAME="TAG"><VALUE>MSGCLASS</VALUE></DATA>
</DATATYPE></RULE_DATA>'
...
1171167	06:23:50.058	 [6860]	(ArchiveTask)	<17472> EV:L
[CustomXMLFilter] GetMessageNamedProperties - Getting Tag ='MSGCLASS' from custom properties
...
1171169	06:23:50.058	 [6860]	(ArchiveTask)	<17472> EV:M
CEVFilterController::get_MessageClass - Returning 
'Original Message Class' = IPm.Appointment
1171170	06:23:50.058	 [6860]	(ArchiveTask)	<17472> EV:L
[CustomXMLFilter] Custom tag 'MSGCLASS' and name 
'0x001A', set to IPm.Appointment
1171171	06:23:50.058	 [6860]	(ArchiveTask)	<17472> EV:L
[CustomXMLFilter] Adding property 'PR_MESSAGE_CLASS (0x001a)' 
to Items XML. [tag='MSGCLASS', value='IPm.Appointment']
1171172	06:23:50.058	 [6860]	(ArchiveTask)	<17472> EV:L
[CustomRules][CRule] Evaluating item against MBX DIFF_RET_CAT rule...
1171173	06:23:50.058	 [6860]	(ArchiveTask)	<17472> EV:L
[CustomRules][CNamedPropClause] testing against ANY of 1 NamedProps
1171174	06:23:50.058	 [6860]	(ArchiveTask)	<17472> EV:L
[CustomRules][CNamedPropClause] : ipm.appointment  MATCHED  
ipm.appointment
1171175	06:23:50.058	 [6860]	(ArchiveTask)	<17472> EV:L
[CustomRules][CNamedPropClause] match with test ''ipm.appointment''
1171176	06:23:50.058	 [6860]	(ArchiveTask)	<17472> EV:L
[CustomRules][CNamedPropClause] Named prop clause: MSGCLASS  MATCHED 
ANY PROP Values
1171177	06:23:50.058	 [6860]	(ArchiveTask)	<17472> EV:L
[CustomRules][CRule] Finished evaluating item against MBX DIFF_RET_CAT 
rule; matches

Lines similar to the following show the example filter rule actions are applied to the test message:

1171179	06:23:50.058	 [6860]	(ArchiveTask)	<17472> EV:M
[CustomXMLFilter] Reading MBX DIFF_RET_CAT rule properties...
...
1171181	06:23:50.074	 [6860]	(ArchiveTask)	<17472> EV:M
[CustomXMLFilter] Setting recognised ACTION to [1]
1171182	06:23:50.074	 [6860]	(ArchiveTask)	<17472> EV:M
[CustomXMLFilter] Setting message content category to [MsgClassTest]
...
1171184	06:23:50.074	 [6860]	(ArchiveTask)	<17472> EV:M
CEVFilterController::get_MessageClass - Returning 
'Original Message Class' = IPm.appointment
... 
1171187	06:23:50.074	 [6860]	(ArchiveTask)	<17472> EV:L
[CustomXMLFilter] Adding property 'PR_MESSAGE_CLASS (0x001a)' 
to index property set 'MsgClassTest' [tag='MSGCLASS', 
value='IPm.appointment']
1171188	06:23:50.074	 [6860]	(ArchiveTask)	<17472> EV:M
[CustomXMLFilter] Setting retention category to 
[167A06CB31E01744F8500E3D54FC80BEC1b10000evsite]
1171189	06:23:50.074	 [6860]	(ArchiveTask)	<17472> EV:M
Returning IndexedPropertiesSet = <?xml version="1.0" 
encoding="UTF-16"?>|<ARCHIVED_ITEM xmlns:o="urn:kvsplc-com:
archived_item" version="1.0"><MSG><PROPSETLIST><PROPSET 
NAME="MsgClassTest" SEARCH="y" RESULTS="y"><PROP NAME="MSGCLASS">
<VALUE>IPm.appointment</VALUE></PROP></PROPSET>
</PROPSETLIST></MSG></ARCHIVED_ITEM>|
1171190	06:23:50.074	 [6860]	(ArchiveTask)	<17472> EV:M
Returning Create Shortcut = TRUE
1171191	06:23:50.074	 [6860]	(ArchiveTask)	<17472> EV:M
Returning Delete Original = TRUE
1171192	06:23:50.074	 [6860]	(ArchiveTask)	<17472> EV:M
Returning Vault Id = 1E5850B2EA77101459FCD56CBC4D3A5871110000evsite
1171193	06:23:50.074	 [6860]	(ArchiveTask)	<17472> EV:M
Returning Retention Category = 167A06CB31E01744F8500E3D54FC80BEC
1b10000evsite
1171194	06:23:50.074	 [6860]	(ArchiveTask)	<17472> EV:M
Returning Action = 1
1171195	06:23:50.074	 [6860]	(ArchiveTask)	<17472> EV:L
CEVFilterController::FilteringCompleted() (Entry) |
1171196	06:23:50.074	 [6860]	(ArchiveTask)	<17472> EV:M
CEVFilterController::FilteringCompleted() (Exit) |Success  [0] |
...
1171200	06:23:50.089	 [6860]	(ArchiveTask)	<17472> EV:M
EF: Item will be archived|Mailbox: /o=EV Training/
ou=First Administrative Group/cn=Recipients/cn=VSA|Folder: Calendar|
Message: test appointment
1171201	06:23:50.089	 [6860]	(ArchiveTask)	<17472> EV:L
CArchivingAgent::ExternalFiltering() (Exit) |Success  [0] |
1171202	06:23:50.089	 [6860]	(ArchiveTask)	<17472> EV:M
CArchivingAgent::ProcessItemInternal - After call to ExternalFiltering. 
RetentionCategory[167A06CB31E01744F8500E3D54FC80BEC1b10000evsite] 
ArchiveId[1E5850B2EA77101459FCD56CBC4D3A5871110000evsite] 
ContainingArchiveId[1DF2DFF131A9AFB4EB0B493648330C02B1110000evsite] 
IndexedPropertiesSet[<?xml version="1.0" encoding="UTF-16"?>|
<ARCHIVED_ITEM xmlns:o="urn:kvsplc-com:archived_item" version="1.0">
<MSG><PROPSETLIST><PROPSET NAME="MsgClassTest" SEARCH="y" RESULTS="y">
<PROP NAME="MSGCLASS"><VALUE>IPm.appointment</VALUE>
</PROP></PROPSET></PROPSETLIST></MSG></ARCHIVED_ITEM>|] 
MessageModified[FALSE] RetryCount[0] [0x00000000]