VAC trace entry info
Trace category:
Mailbox provisioning issues (Exchange)
Title:
Provision
Case description:
Start time:
27/03/2009 09:51:29
Running time:
5 Minutes
Maximum log size:
100 MB
-----
Enabled Trace options
Id Name Detail Include Events
20 - EvExchangePolicySyncTask Verbose Yes
-----
Display Filter:
Include strings:
EV-
EV:
EV~
Exclude strings:
CMMStream
DBFramework
DirectoryConnection
-----
Running in Terminal Services session...
Monitoring debug output from processes running in the console session
Monitoring debug output from processes running in the current Terminal Services session
1 09:51:46.334 [3648] (EvExchangePolicySyncTask) <3572> EV-H {ExchangePolicySyncTaskProcessor.Main} Entering...
2 09:51:46.349 [3648] (EvExchangePolicySyncTask) <3572> EV-H {ExchangePolicySyncTaskProcessor..ctor} Entering...
3 09:51:46.365 [3648] (EvExchangePolicySyncTask) <3572> EV-H {ExchangePolicySynchronization..ctor} Entering
4 09:51:46.381 [3648] (EvExchangePolicySyncTask) <3572> EV-H {ExchangePolicySynchronization..ctor} Leaving
5 09:51:46.381 [3648] (EvExchangePolicySyncTask) <3572> EV-H {ExchangePolicySyncTaskProcessor..ctor} leaving...
6 09:51:46.381 [3648] (EvExchangePolicySyncTask) <3572> EV-M {ManagedObject.KVS.EnterpriseVault.Management.IAdminControl.RegisterStateChangedHandler} Title:ExchangePolicySyncTask
7 09:51:46.381 [3648] (EvExchangePolicySyncTask) <3572> EV-M {ManagedObjectState.RegisterStateChangedHandler} Owner:ExchangePolicySyncTask
8 09:51:46.381 [3648] (EvExchangePolicySyncTask) <3572> EV-H {ManagedProcess.Run} Process starting - "C:\Program Files\Enterprise Vault\EvExchangePolicySyncTask.exe" -URL:tcp://10.248.193.21:1307/8b31dc80_0912_488b_ae9c_824479679474/KVS.EnterpriseVault.ProcessManager50157736 -CUSTOM:1ADDB46DE7C24B5449E081E7C8178A32E1012u00EVSite01|False||
9 09:51:46.381 [3648] (EvExchangePolicySyncTask) <3572> EV-L {ManagedProcess.LoadConfigurationSettings} Config Settings - debugWaitSeconds=0
10 09:51:46.412 [3648] (EvExchangePolicySyncTask) <3572> EV-M {ManagedProcess.RemoteThis} URL[0]: tcp://10.248.193.21:3360/ce1da180_8305_4f3c_a8b5_44fb5feb8485/EVProcessManager51b762e0_defd_4c77_9f43_806edbf3442d
11 09:51:46.427 [3648] (EvExchangePolicySyncTask) <3572> EV-M {ManagedProcess.NotifyAllPresentAndCorrect} Using Process manager URL:tcp://10.248.193.21:1307/8b31dc80_0912_488b_ae9c_824479679474/KVS.EnterpriseVault.ProcessManager50157736 Title:1ADDB46DE7C24B5449E081E7C8178A32E1012u00EVSite01
12 09:51:46.443 [3648] (EvExchangePolicySyncTask) <3572> EV-M {ManagedObjectState.RegisterStateChangedHandler} Owner:EvExchangePolicySyncTask
13 09:51:46.490 [3648] (EvExchangePolicySyncTask) <6032> EV-M {ManagedProcess.KVS.EnterpriseVault.Management.IAdminControl.Start} Current State:Stopped
14 09:51:46.490 [3648] (EvExchangePolicySyncTask) <6032> EV-M {ManagedObject.KVS.EnterpriseVault.Management.IAdminControl.Start} Name:ExchangePolicySyncTask Current State:Stopped
15 09:51:46.490 [3648] (EvExchangePolicySyncTask) <6032> EV-H {ManagedObject.KVS.EnterpriseVault.Management.IAdminControl.Start} ManagedTask.OnStart()
16 09:51:46.490 [3648] (EvExchangePolicySyncTask) <6032> EV-M {ManagedProcess.ObjectStateChanged} ExchangePolicySyncTask New:Running Old:Starting My:Starting
17 09:51:46.490 [3648] (EvExchangePolicySyncTask) <6032> EV-M {ManagedProcess.CheckProcessState} New State:Running Quorum:True
18 09:51:46.490 [3648] (EvExchangePolicySyncTask) <6032> EV-M {ManagedProcess.NotifyPMStateChanged} EvExchangePolicySyncTask New State:Running Old:Starting My:Running
19 09:51:46.506 [3648] (EvExchangePolicySyncTask) <3572> EV-L {ManagedProcess.Run} Loading... State:Running Countdown:0
20 09:51:46.506 [3648] (EvExchangePolicySyncTask) <6032> EV-M {ManagedProcess.CheckProcessState} New State:Running Quorum:True
21 09:51:46.584 [3648] (EvExchangePolicySyncTask) <3328> EV:M CoCreateInstanceHelper::IsDirectoryAvailable()
22 09:51:46.584 [3648] (EvExchangePolicySyncTask) <3328> EV:M CoCreateInstanceHelper::IsDirectoryAvailable - Trying to connect to a local Directory Service
23 09:51:46.615 [3648] (EvExchangePolicySyncTask) <3328> EV:M CoCreateInstanceHelper::IsDirectoryAvailable - Directory Service is available
24 09:51:46.615 [3648] (EvExchangePolicySyncTask) <3328> EV-H {ManagedTask.MainThread} ManagedTask.MainThread() - Custom args:1ADDB46DE7C24B5449E081E7C8178A32E1012u00EVSite01|False||
25 09:51:46.615 [3648] (EvExchangePolicySyncTask) <3328> EV-H {ExchangePolicySyncTaskProcessor.TaskMain} Entering...
26 09:51:46.615 [3648] (EvExchangePolicySyncTask) <3328> EV-H {ExchangePolicySyncTaskProcessor.WaitForDebugger} Entering...
27 09:51:46.615 [3648] (EvExchangePolicySyncTask) <3328> EV-H {ExchangePolicySyncTaskProcessor.WaitForDebugger} leaving...
34 09:51:46.662 [3648] (EvExchangePolicySyncTask) <3328> EV:M CBaseDirectoryServiceWrapper::GetInterface - RegKey UseLocalDirectory [False]
36 09:51:46.662 [3648] (EvExchangePolicySyncTask) <3328> EV:M CBaseDirectoryServiceWrapper::CreateDirectoryService() - Entry [m_nNumTries = 40]
37 09:51:46.677 [3648] (EvExchangePolicySyncTask) <3328> EV:L CBaseDirectoryServiceWrapper::CreateDirectoryService() - Successfully communicated with an EV Directory Service on the local machine
38 09:51:46.677 [3648] (EvExchangePolicySyncTask) <3328> EV:M DirectoryVault: Initialization: DLL_PROCESS_ATTACH received by the DirectoryVault DLL. |
39 09:51:46.677 [3648] (EvExchangePolicySyncTask) <3328> EV:H DirectoryVault: Initialization: DirectoryVault DLL version is: v1.2.| |
40 09:51:46.677 [3648] (EvExchangePolicySyncTask) <3328> EV:L CDirectoryVaultObject::GetAttributeListFromType Unknown type - Task
42 09:51:46.693 [3648] (EvExchangePolicySyncTask) <3328> EV:M CBaseDirectoryServiceWrapper::CreateDirectoryService() - Entry [m_nNumTries = 40]
43 09:51:46.693 [3648] (EvExchangePolicySyncTask) <3328> EV:L CBaseDirectoryServiceWrapper::CreateDirectoryService() - Successfully communicated with an EV Directory Service on the local machine
44 09:51:46.693 [3648] (EvExchangePolicySyncTask) <3328> EV:L CDirectoryVaultObject::GetAttributeListFromType Unknown type - ExchangePolicySyncTaskView
45 09:51:46.709 [3648] (EvExchangePolicySyncTask) <3328> EV-H {ExchangePolicySyncTaskProcessor.RunTask} Entering...
46 09:51:46.724 [3648] (EvExchangePolicySyncTask) <3328> EV-H {ExchangePolicySynchronization.RunSynchronization} Synchronization started..
47 09:51:46.740 [3648] (EvExchangePolicySyncTask) <3328> EV-H {ExchangePolicySynchronization.Initialize} Initializing Task [Exchange Provisioning Task for ad.msnet.atf.gov] for organization [1A5C1211251ECC24680FE5014AD8D9EDF1011e00EVSite01]
49 09:51:46.740 [3648] (EvExchangePolicySyncTask) <3328> EV:M CBaseDirectoryServiceWrapper::CreateDirectoryService() - Entry [m_nNumTries = 40]
50 09:51:46.756 [3648] (EvExchangePolicySyncTask) <3328> EV:L CBaseDirectoryServiceWrapper::CreateDirectoryService() - Successfully communicated with an EV Directory Service on the local machine
51 09:51:46.865 [3648] (EvExchangePolicySyncTask) <3328> EV-M {DirectoryCache.ReadEntry} Sel Type: KVS.EnterpriseVault.Directory.DirectoryDS+OrganizationRow |From cache? False
53 09:51:47.006 [3648] (EvExchangePolicySyncTask) <3328> EV:M CBaseDirectoryServiceWrapper::CreateDirectoryService() - Entry [m_nNumTries = 40]
54 09:51:47.021 [3648] (EvExchangePolicySyncTask) <3328> EV:L CBaseDirectoryServiceWrapper::CreateDirectoryService() - Successfully communicated with an EV Directory Service on the local machine
55 09:51:47.021 [3648] (EvExchangePolicySyncTask) <3328> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: EVSite01 ==> SQL server: hqsql\atfsql01 Cached? False
56 09:51:47.099 [3648] (EvExchangePolicySyncTask) <3328> EV-M {DirectoryDataset.GetEntryInfo} EntryId: 1A5C1211251ECC24680FE5014AD8D9EDF1011e00EVSite01 Name: ad.msnet.atf.gov
57 09:51:47.099 [3648] (EvExchangePolicySyncTask) <3328> EV-M {DirectoryCache.Prune} Cache size: 0 Removed:0
58 09:51:47.099 [3648] (EvExchangePolicySyncTask) <3328> EV-L {DirectoryCache.InsertEntry} EntryId:1A5C1211251ECC24680FE5014AD8D9EDF1011e00EVSite01|Replaced? False
59 09:51:47.099 [3648] (EvExchangePolicySyncTask) <3328> EV:M DirectoryHelper::GetDcName() - Domain = [ad.msnet.atf.gov]
60 09:51:47.099 [3648] (EvExchangePolicySyncTask) <3328> EV:M DirectoryHelper::GetDcName() - Unable to contact GC; trying nearest DC
61 09:51:47.099 [3648] (EvExchangePolicySyncTask) <3328> EV:M DirectoryHelper::GetDcName() - Returning GC server [\\hqpad03.ad.msnet.atf.gov]
62 09:51:47.099 [3648] (EvExchangePolicySyncTask) <3328> EV-H {ExchangePolicySynchronization.TestDCConnection} Entering
63 09:51:47.099 [3648] (EvExchangePolicySyncTask) <3328> EV-M {ExchangePolicySynchronization.InitDirEntry} Binding to object [GC://hqpad03.ad.msnet.atf.gov] (serverBind = True)
64 09:51:47.115 [3648] (EvExchangePolicySyncTask) <3328> EV-H {ExchangePolicySynchronization.Initialize} Processing domain [ad.msnet.atf.gov] using DC [GC://hqpad03.ad.msnet.atf.gov]
65 09:51:47.115 [3648] (EvExchangePolicySyncTask) <3328> EV-M {ExchangePolicySynchronization.CalculateDomainFQDN} Calculating FQDN for domain [ad.msnet.atf.gov]
66 09:51:47.115 [3648] (EvExchangePolicySyncTask) <3328> EV-M {ExchangePolicySynchronization.CalculateDomainFQDN} Domain FQDN = [DC=ad,DC=msnet,DC=atf,DC=gov]
67 09:51:47.115 [3648] (EvExchangePolicySyncTask) <3328> EV-H {ExchangePolicySynchronization.InitializeDomainControllerCache} Entering
68 09:51:47.131 [3648] (EvExchangePolicySyncTask) <3328> EV:M CDirectoryHelper::GetDomainsInForest() - Called for domain [ad.msnet.atf.gov]
69 09:51:47.131 [3648] (EvExchangePolicySyncTask) <3328> EV:M CDirectoryHelper::GetDomainsInForest() - Got initial DC [\\hqpad03.ad.msnet.atf.gov]
70 09:51:47.131 [3648] (EvExchangePolicySyncTask) <3328> EV:M CDirectoryHelper::GetDomainsInForest() - Successfully enumerated domains in the forest
71 09:51:47.131 [3648] (EvExchangePolicySyncTask) <3328> EV:M CDirectoryHelper::NameToFQDN() - Calling CrackDsName() with NT4 to FQDN
72 09:51:47.131 [3648] (EvExchangePolicySyncTask) <3328> EV:M DirectoryHelper::CrackDsName() - DC = [\\hqpmsnet1.msnet.atf.gov], NameToCrack = [MSNET\]
73 09:51:47.131 [3648] (EvExchangePolicySyncTask) <3328> EV:M DirectoryHelper::CrackDsName() - Calling DsBind()
74 09:51:47.131 [3648] (EvExchangePolicySyncTask) <3328> EV:M DirectoryHelper::CrackDsName() - Calling DsCrackNames()
75 09:51:47.131 [3648] (EvExchangePolicySyncTask) <3328> EV:M DirectoryHelper::CrackDsName() - Returning cracked name: [DC=msnet,DC=atf,DC=gov]
76 09:51:47.146 [3648] (EvExchangePolicySyncTask) <3328> EV:M CDirectoryHelper::GetDomainsInForest() - Found domain [DC=msnet,DC=atf,DC=gov] with DC [\\hqpmsnet1.msnet.atf.gov]
77 09:51:47.146 [3648] (EvExchangePolicySyncTask) <3328> EV:M CDirectoryHelper::NameToFQDN() - Calling CrackDsName() with NT4 to FQDN
78 09:51:47.146 [3648] (EvExchangePolicySyncTask) <3328> EV:M DirectoryHelper::CrackDsName() - DC = [\\ntcpextad01.external.ad.msnet.atf.gov], NameToCrack = [ATF_EXT\]
79 09:51:47.146 [3648] (EvExchangePolicySyncTask) <3328> EV:M DirectoryHelper::CrackDsName() - Calling DsBind()
80 09:51:47.521 [3648] (EvExchangePolicySyncTask) <3328> EV:M DirectoryHelper::CrackDsName() - Calling DsCrackNames()
81 09:51:47.615 [3648] (EvExchangePolicySyncTask) <3328> EV:M DirectoryHelper::CrackDsName() - Returning cracked name: [DC=external,DC=ad,DC=msnet,DC=atf,DC=gov]
82 09:51:47.615 [3648] (EvExchangePolicySyncTask) <3328> EV:M CDirectoryHelper::GetDomainsInForest() - Found domain [DC=external,DC=ad,DC=msnet,DC=atf,DC=gov] with DC [\\ntcpextad01.external.ad.msnet.atf.gov]
83 09:51:47.615 [3648] (EvExchangePolicySyncTask) <3328> EV:M CDirectoryHelper::NameToFQDN() - Calling CrackDsName() with NT4 to FQDN
84 09:51:47.615 [3648] (EvExchangePolicySyncTask) <3328> EV:M DirectoryHelper::CrackDsName() - DC = [\\hqpad03.ad.msnet.atf.gov], NameToCrack = [ATF_HQ\]
85 09:51:47.615 [3648] (EvExchangePolicySyncTask) <3328> EV:M DirectoryHelper::CrackDsName() - Calling DsBind()
86 09:51:47.615 [3648] (EvExchangePolicySyncTask) <3328> EV:M DirectoryHelper::CrackDsName() - Calling DsCrackNames()
87 09:51:47.615 [3648] (EvExchangePolicySyncTask) <3328> EV:M DirectoryHelper::CrackDsName() - Returning cracked name: [DC=ad,DC=msnet,DC=atf,DC=gov]
88 09:51:47.615 [3648] (EvExchangePolicySyncTask) <3328> EV:M CDirectoryHelper::GetDomainsInForest() - Found domain [DC=ad,DC=msnet,DC=atf,DC=gov] with DC [\\hqpad03.ad.msnet.atf.gov]
89 09:51:47.615 [3648] (EvExchangePolicySyncTask) <3328> EV-M {ExchangePolicySynchronization.InitializeDomainControllerCache} Adding domain controller [hqpmsnet1.msnet.atf.gov] for domain [DC=msnet,DC=atf,DC=gov]
90 09:51:47.615 [3648] (EvExchangePolicySyncTask) <3328> EV-M {ExchangePolicySynchronization.InitializeDomainControllerCache} Adding domain controller [ntcpextad01.external.ad.msnet.atf.gov] for domain [DC=external,DC=ad,DC=msnet,DC=atf,DC=gov]
91 09:51:47.615 [3648] (EvExchangePolicySyncTask) <3328> EV-M {ExchangePolicySynchronization.InitializeDomainControllerCache} Adding domain controller [hqpad03.ad.msnet.atf.gov] for domain [DC=ad,DC=msnet,DC=atf,DC=gov]
92 09:51:47.615 [3648] (EvExchangePolicySyncTask) <3328> EV-H {ExchangePolicySynchronization.InitializeDomainControllerCache} Leaving
93 09:51:47.615 [3648] (EvExchangePolicySyncTask) <3328> EV-L {ThreadSafeQueue.SetQueueType} Set QueueType = [Sequential]
94 09:51:47.615 [3648] (EvExchangePolicySyncTask) <3328> EV-H {ExchangePolicySynchronization.InitalizeAuditing} Entering
95 09:51:47.631 [3648] (EvExchangePolicySyncTask) <3328> EV:M AuditInterface::AuditingEnabled() called
96 09:51:47.631 [3648] (EvExchangePolicySyncTask) <3328> EV:M CEVAudit::WillPerformAudit Determine whether to audit or not.
97 09:51:47.631 [3648] (EvExchangePolicySyncTask) <3328> EV:M CEVAudit::CEVAudit Memory mapped area found existing
98 09:51:47.631 [3648] (EvExchangePolicySyncTask) <3328> EV:M CEVAudit::WillPerformAudit Auditing is off, current status '1'
99 09:51:47.631 [3648] (EvExchangePolicySyncTask) <3328> EV-H {ExchangePolicySynchronization.InitalizeAuditing} Leaving
100 09:51:47.631 [3648] (EvExchangePolicySyncTask) <3328> EV-M {ExchangePolicySynchronization.Initialize} Read 'SynchInMigrationMode' registry value [0]
101 09:51:47.631 [3648] (EvExchangePolicySyncTask) <3328> EV-M {ExchangePolicySynchronization.Initialize} Number of worker threads = 5
102 09:51:47.662 [3648] (EvExchangePolicySyncTask) <3328> EV-M {DirectoryCache.ReadEntry} Sel Type: KVS.EnterpriseVault.Directory.DirectoryDS+ArchivingRetrievalTaskViewRow |From cache? False
103 09:51:47.662 [3648] (EvExchangePolicySyncTask) <3328> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: EVSite01 ==> SQL server: hqsql\atfsql01 Cached? True
104 09:51:47.662 [3648] (EvExchangePolicySyncTask) <3328> EV-M {DirectoryDataset.GetEntryInfo} EntryId: 19282EEACF292A342AC7560DA2BF1DA221011z00EVSite01 Name: Exchange Mailbox Archiving Task for EXCHMBB
105 09:51:47.662 [3648] (EvExchangePolicySyncTask) <3328> EV-M {DirectoryCache.Prune} Cache size: 1 Removed:0
106 09:51:47.662 [3648] (EvExchangePolicySyncTask) <3328> EV-L {DirectoryCache.InsertEntry} EntryId:19282EEACF292A342AC7560DA2BF1DA221011z00EVSite01|Replaced? False
107 09:51:47.662 [3648] (EvExchangePolicySyncTask) <3328> EV-M {DirectoryDataset.GetEntryInfo} EntryId: 198E5B7666686A54C9F22B23620CCB8CD1011z00EVSite01 Name: Exchange Mailbox Archiving Task for EXCHMBA
108 09:51:47.662 [3648] (EvExchangePolicySyncTask) <3328> EV-M {DirectoryCache.Prune} Cache size: 2 Removed:0
109 09:51:47.662 [3648] (EvExchangePolicySyncTask) <3328> EV-L {DirectoryCache.InsertEntry} EntryId:198E5B7666686A54C9F22B23620CCB8CD1011z00EVSite01|Replaced? False
110 09:51:47.662 [3648] (EvExchangePolicySyncTask) <3328> EV-M {DirectoryCache.ReadEntry} Sel Type: KVS.EnterpriseVault.Directory.DirectoryDS+ComputerEntryRow |From cache? False
111 09:51:47.662 [3648] (EvExchangePolicySyncTask) <3328> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: EVSite01 ==> SQL server: hqsql\atfsql01 Cached? True
112 09:51:47.662 [3648] (EvExchangePolicySyncTask) <3328> EV-M {DirectoryDataset.GetEntryInfo} EntryId: 1501290328AB1CC4C9DC959CA40995EE31410000EVSite01 Name: EVAULTVS02.ad.msnet.atf.gov
113 09:51:47.662 [3648] (EvExchangePolicySyncTask) <3328> EV-M {DirectoryCache.Prune} Cache size: 3 Removed:0
114 09:51:47.662 [3648] (EvExchangePolicySyncTask) <3328> EV-L {DirectoryCache.InsertEntry} EntryId:1501290328AB1CC4C9DC959CA40995EE31410000EVSite01|Replaced? False
115 09:51:47.693 [3648] (EvExchangePolicySyncTask) <3328> EV-M {AgentSyncManager.Initialize} Added sync helper for exchange server [EXCHMBB] against task [Exchange Mailbox Archiving Task for EXCHMBB] on EV computer [EVAULTVS02.ad.msnet.atf.gov]
116 09:51:47.709 [3648] (EvExchangePolicySyncTask) <3328> EV-M {DirectoryCache.ReadEntry} Sel Type: KVS.EnterpriseVault.Directory.DirectoryDS+ComputerEntryRow |From cache? False
117 09:51:47.709 [3648] (EvExchangePolicySyncTask) <3328> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: EVSite01 ==> SQL server: hqsql\atfsql01 Cached? True
118 09:51:47.709 [3648] (EvExchangePolicySyncTask) <3328> EV-M {DirectoryDataset.GetEntryInfo} EntryId: 1501290328AB1CC4C9DC959CA40995EE31410000EVSite01 Name: EVAULTVS02.ad.msnet.atf.gov
119 09:51:47.709 [3648] (EvExchangePolicySyncTask) <3328> EV-M {DirectoryCache.Prune} Cache size: 4 Removed:0
120 09:51:47.709 [3648] (EvExchangePolicySyncTask) <3328> EV-L {DirectoryCache.InsertEntry} EntryId:1501290328AB1CC4C9DC959CA40995EE31410000EVSite01|Replaced? True
121 09:51:47.724 [3648] (EvExchangePolicySyncTask) <3328> EV-M {AgentSyncManager.Initialize} Added sync helper for exchange server [EXCHMBA] against task [Exchange Mailbox Archiving Task for EXCHMBA] on EV computer [EVAULTVS02.ad.msnet.atf.gov]
122 09:51:47.724 [3648] (EvExchangePolicySyncTask) <3328> EV-M {ExchangePolicySynchronization.Initialize} Mark mailboxes as deleted after 14 days.
123 09:51:47.724 [3648] (EvExchangePolicySyncTask) <3328> EV-M {DirectoryCache.ReadEntry} Sel Type: KVS.EnterpriseVault.Directory.DirectoryDS+ExchangeServerEntryRow |From cache? False
124 09:51:47.724 [3648] (EvExchangePolicySyncTask) <3328> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: EVSite01 ==> SQL server: hqsql\atfsql01 Cached? True
125 09:51:47.740 [3648] (EvExchangePolicySyncTask) <3328> EV-M {DirectoryDataset.GetEntryInfo} EntryId: 15306387EDC129A42907291B5F448FBFF1o10000EVSite01 Name: EXCHMBA
126 09:51:47.740 [3648] (EvExchangePolicySyncTask) <3328> EV-M {DirectoryCache.Prune} Cache size: 4 Removed:0
127 09:51:47.740 [3648] (EvExchangePolicySyncTask) <3328> EV-L {DirectoryCache.InsertEntry} EntryId:15306387EDC129A42907291B5F448FBFF1o10000EVSite01|Replaced? False
128 09:51:47.740 [3648] (EvExchangePolicySyncTask) <3328> EV-M {DirectoryDataset.GetEntryInfo} EntryId: 14EAF4D8314BA7B4ABD96E1539CF32C431o10000EVSite01 Name: EXCHMBB
129 09:51:47.740 [3648] (EvExchangePolicySyncTask) <3328> EV-M {DirectoryCache.Prune} Cache size: 5 Removed:0
130 09:51:47.740 [3648] (EvExchangePolicySyncTask) <3328> EV-L {DirectoryCache.InsertEntry} EntryId:14EAF4D8314BA7B4ABD96E1539CF32C431o10000EVSite01|Replaced? False
131 09:51:47.740 [3648] (EvExchangePolicySyncTask) <3328> EV-M {ExchangePolicySynchronization.Initialize} Read Exchange Server info:|Name = [EXCHMBA]|LegacyDN = [/O=Bur of ATF/OU=ATF_HQ/cn=Configuration/cn=Servers/cn=EXCHMBA]|ID = [1]
132 09:51:47.740 [3648] (EvExchangePolicySyncTask) <3328> EV-M {ExchangePolicySynchronization.Initialize} Added Exchange server [EXCHMBA] to cache
133 09:51:47.740 [3648] (EvExchangePolicySyncTask) <3328> EV-M {ExchangePolicySynchronization.Initialize} Read Exchange Server info:|Name = [EXCHMBB]|LegacyDN = [/O=Bur of ATF/OU=ATF_HQ/cn=Configuration/cn=Servers/cn=EXCHMBB]|ID = [2]
134 09:51:47.740 [3648] (EvExchangePolicySyncTask) <3328> EV-M {ExchangePolicySynchronization.Initialize} Added Exchange server [EXCHMBB] to cache
135 09:51:47.756 [3648] (EvExchangePolicySyncTask) <3328> EV~I |Event ID: 41102 The Exchange mailbox provisioning task has started.|Task: Exchange Provisioning Task for ad.msnet.atf.gov|Domain: ad.msnet.atf.gov
136 09:51:47.756 [3648] (EvExchangePolicySyncTask) <3328> EV-H {ExchangePolicySynchronization.Initialize} Leaving
137 09:51:47.756 [3648] (EvExchangePolicySyncTask) <3328> EV-H {EVManagedFolders.UpdateManagedFolderRecords} Updating Managed Folder Records [hqpad03.ad.msnet.atf.gov]
138 09:51:47.756 [3648] (EvExchangePolicySyncTask) <3328> EV-M {ADHelper.InitDirEntry} Binding to object [LDAP://hqpad03.ad.msnet.atf.gov/rootDSE] (serverBind = True)
139 09:51:47.771 [3648] (EvExchangePolicySyncTask) <3328> EV-H {EVManagedFolders.GetExchangeOrganizations} searchRoot [LDAP://hqpad03.ad.msnet.atf.gov/CN=Microsoft Exchange,CN=Services,CN=Configuration,DC=msnet,DC=atf,DC=gov]
140 09:51:47.771 [3648] (EvExchangePolicySyncTask) <3328> EV-M {ADHelper.InitDirEntry} Binding to object [LDAP://hqpad03.ad.msnet.atf.gov/CN=Microsoft Exchange,CN=Services,CN=Configuration,DC=msnet,DC=atf,DC=gov] (serverBind = True)
141 09:51:47.818 [3648] (EvExchangePolicySyncTask) <3328> EV-L {EVManagedFolders.GetExchangeOrganizations} Found Exchange Organization [ATF]
142 09:51:47.818 [3648] (EvExchangePolicySyncTask) <3328> EV-L {EVManagedFolders.UpdateManagedFolderRecords} searchRoot [LDAP://hqpad03.ad.msnet.atf.gov/CN=ELC Folders Container,CN=ATF,CN=Microsoft Exchange,CN=Services,CN=Configuration,DC=msnet,DC=atf,DC=gov]
143 09:51:47.818 [3648] (EvExchangePolicySyncTask) <3328> EV-M {ADHelper.InitDirEntry} Binding to object [LDAP://hqpad03.ad.msnet.atf.gov/CN=ELC Folders Container,CN=ATF,CN=Microsoft Exchange,CN=Services,CN=Configuration,DC=msnet,DC=atf,DC=gov] (serverBind = True)
144 09:51:47.818 [3648] (EvExchangePolicySyncTask) <3328> EV-H {EVManagedFolders.UpdateManagedFolderRecords} Active Directory search for Managed Folder objects failed|Reason: There is no such object on the server.|SearchRoot: LDAP://hqpad03.ad.msnet.atf.gov/CN=ELC Folders Container,CN=ATF,CN=Microsoft Exchange,CN=Services,CN=Configuration,DC=msnet,DC=atf,DC=gov
145 09:51:47.818 [3648] (EvExchangePolicySyncTask) <3328> EV-L {EVManagedFolders.IsExchange2007Enviroment} searchRoot [LDAP://hqpad03.ad.msnet.atf.gov/CN=ATF,CN=Microsoft Exchange,CN=Services,CN=Configuration,DC=msnet,DC=atf,DC=gov]
146 09:51:47.818 [3648] (EvExchangePolicySyncTask) <3328> EV-M {ADHelper.InitDirEntry} Binding to object [LDAP://hqpad03.ad.msnet.atf.gov/CN=ATF,CN=Microsoft Exchange,CN=Services,CN=Configuration,DC=msnet,DC=atf,DC=gov] (serverBind = True)
147 09:51:47.818 [3648] (EvExchangePolicySyncTask) <3328> EV-H {EVManagedFolders.UpdateManagedFolderRecords} No Managed Folder Content Settings found [ATF]
148 09:51:47.818 [3648] (EvExchangePolicySyncTask) <3328> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: EVSite01 ==> SQL server: hqsql\atfsql01 Cached? True
149 09:51:47.818 [3648] (EvExchangePolicySyncTask) <3328> EV-M {DirectoryCache.ReadEntry} Sel Type: KVS.EnterpriseVault.Directory.DirectoryDS+ArchivingRetrievalTaskViewRow |From cache? False
150 09:51:47.818 [3648] (EvExchangePolicySyncTask) <3328> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: EVSite01 ==> SQL server: hqsql\atfsql01 Cached? True
151 09:51:47.834 [3648] (EvExchangePolicySyncTask) <3328> EV-M {DirectoryDataset.GetEntryInfo} EntryId: 19282EEACF292A342AC7560DA2BF1DA221011z00EVSite01 Name: Exchange Mailbox Archiving Task for EXCHMBB
152 09:51:47.834 [3648] (EvExchangePolicySyncTask) <3328> EV-M {DirectoryCache.Prune} Cache size: 6 Removed:0
153 09:51:47.834 [3648] (EvExchangePolicySyncTask) <3328> EV-L {DirectoryCache.InsertEntry} EntryId:19282EEACF292A342AC7560DA2BF1DA221011z00EVSite01|Replaced? True
154 09:51:47.834 [3648] (EvExchangePolicySyncTask) <3328> EV-M {DirectoryDataset.GetEntryInfo} EntryId: 198E5B7666686A54C9F22B23620CCB8CD1011z00EVSite01 Name: Exchange Mailbox Archiving Task for EXCHMBA
155 09:51:47.834 [3648] (EvExchangePolicySyncTask) <3328> EV-M {DirectoryCache.Prune} Cache size: 6 Removed:0
156 09:51:47.834 [3648] (EvExchangePolicySyncTask) <3328> EV-L {DirectoryCache.InsertEntry} EntryId:198E5B7666686A54C9F22B23620CCB8CD1011z00EVSite01|Replaced? True
157 09:51:47.834 [3648] (EvExchangePolicySyncTask) <3328> EV-M {EVManagedFolders.RaiseRefreshEvent} Retention mapper cache refresh firing for task [Exchange Mailbox Archiving Task for EXCHMBB]
158 09:51:47.834 [3648] (EvExchangePolicySyncTask) <3328> EV-M {DirectoryCache.ReadEntry} Sel Type: KVS.EnterpriseVault.Directory.DirectoryDS+ComputerEntryRow |From cache? False
159 09:51:47.834 [3648] (EvExchangePolicySyncTask) <3328> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: EVSite01 ==> SQL server: hqsql\atfsql01 Cached? True
160 09:51:47.834 [3648] (EvExchangePolicySyncTask) <3328> EV-M {DirectoryDataset.GetEntryInfo} EntryId: 1501290328AB1CC4C9DC959CA40995EE31410000EVSite01 Name: EVAULTVS02.ad.msnet.atf.gov
161 09:51:47.834 [3648] (EvExchangePolicySyncTask) <3328> EV-M {DirectoryCache.Prune} Cache size: 6 Removed:0
162 09:51:47.834 [3648] (EvExchangePolicySyncTask) <3328> EV-L {DirectoryCache.InsertEntry} EntryId:1501290328AB1CC4C9DC959CA40995EE31410000EVSite01|Replaced? True
163 09:51:47.849 [3648] (EvExchangePolicySyncTask) <3328> EV-M {EVManagedFolders.RaiseRefreshEvent} Retention mapper cache refresh firing for task [Exchange Mailbox Archiving Task for EXCHMBA]
164 09:51:47.849 [3648] (EvExchangePolicySyncTask) <3328> EV-M {DirectoryCache.ReadEntry} Sel Type: KVS.EnterpriseVault.Directory.DirectoryDS+ComputerEntryRow |From cache? False
165 09:51:47.849 [3648] (EvExchangePolicySyncTask) <3328> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: EVSite01 ==> SQL server: hqsql\atfsql01 Cached? True
166 09:51:47.849 [3648] (EvExchangePolicySyncTask) <3328> EV-M {DirectoryDataset.GetEntryInfo} EntryId: 1501290328AB1CC4C9DC959CA40995EE31410000EVSite01 Name: EVAULTVS02.ad.msnet.atf.gov
167 09:51:47.849 [3648] (EvExchangePolicySyncTask) <3328> EV-M {DirectoryCache.Prune} Cache size: 6 Removed:0
168 09:51:47.849 [3648] (EvExchangePolicySyncTask) <3328> EV-L {DirectoryCache.InsertEntry} EntryId:1501290328AB1CC4C9DC959CA40995EE31410000EVSite01|Replaced? True
169 09:51:47.880 [3648] (EvExchangePolicySyncTask) <3328> EV-H {ExchangePolicySynchronization.ReadSortedTargetsFromDB} Entering
170 09:51:47.880 [3648] (EvExchangePolicySyncTask) <3328> EV-M {DirectoryCache.ReadEntry} Sel Type: KVS.EnterpriseVault.Directory.DirectoryDS+ExchangePolicyTargetGroupAndMemberViewRow |From cache? False
171 09:51:47.880 [3648] (EvExchangePolicySyncTask) <3328> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: EVSite01 ==> SQL server: hqsql\atfsql01 Cached? True
172 09:51:47.896 [3648] (EvExchangePolicySyncTask) <3328> EV-H {ExchangePolicySynchronization.RunSynchronization} No provisioning group members to process for domain [ad.msnet.atf.gov]
173 09:51:47.896 [3648] (EvExchangePolicySyncTask) <3328> EV~W |Event ID: 41130 No provisioning groups are defined. Define some provisioning groups and run the provisioning task again.|Task: Exchange Provisioning Task for ad.msnet.atf.gov|Domain: ad.msnet.atf.gov
174 09:51:47.896 [3648] (EvExchangePolicySyncTask) <3328> EV-M {ExchangePolicySynchronization.WaitForWorkerThreads} Waiting for worker threads to finish processing
175 09:51:47.896 [3648] (EvExchangePolicySyncTask) <3328> EV-M {ExchangePolicySynchronization.WaitForWorkerThreads} Queue is empty, setting WorkerStopEvent
176 09:51:47.896 [3648] (EvExchangePolicySyncTask) <3328> EV-M {ExchangePolicySynchronization.WaitForWorkerThreads} All worker threads finished processing
177 09:51:47.896 [3648] (EvExchangePolicySyncTask) <3328> EV~I |Event ID: 41104 The Exchange mailbox provisioning task has completed.|Task: Exchange Provisioning Task for ad.msnet.atf.gov|Domain: ad.msnet.atf.gov
178 09:51:47.912 [3648] (EvExchangePolicySyncTask) <3328> EV-H {ExchangePolicySyncTaskProcessor.RunTask} leaving...
179 09:51:47.912 [3648] (EvExchangePolicySyncTask) <3328> EV-H {ExchangePolicySyncTaskProcessor.TaskMain} Leaving..
180 09:51:47.912 [3648] (EvExchangePolicySyncTask) <6032> EV-M {ManagedProcess.KVS.EnterpriseVault.Management.IAdminControl.Stop} Current State:Running
181 09:51:47.912 [3648] (EvExchangePolicySyncTask) <6032> EV-M {ManagedObject.KVS.EnterpriseVault.Management.IAdminControl.Stop} Title:ExchangePolicySyncTask Current State:Running
182 09:51:47.912 [3648] (EvExchangePolicySyncTask) <6032> EV-H {ManagedObject.KVS.EnterpriseVault.Management.IAdminControl.Stop} ManagedTask.OnStop()
183 09:51:47.912 [3648] (EvExchangePolicySyncTask) <6032> EV-M {DirectorySync.Abandon} Is waiting? False
184 09:51:47.912 [3648] (EvExchangePolicySyncTask) <6032> EV-M {ManagedProcess.ObjectStateChanged} ExchangePolicySyncTask New:Stopped Old:Stopping My:Stopping
185 09:51:47.912 [3648] (EvExchangePolicySyncTask) <6032> EV-M {ManagedProcess.CheckProcessState} New State:Stopped Quorum:True
186 09:51:47.912 [3648] (EvExchangePolicySyncTask) <6032> EV-M {ManagedProcess.NotifyPMStateChanged} EvExchangePolicySyncTask New State:Stopped Old:Stopping My:Stopped
187 09:51:47.912 [3648] (EvExchangePolicySyncTask) <6032> EV-M {ManagedProcess.CheckProcessState} New State:Stopped Quorum:True
188 09:51:52.911 [3648] (EvExchangePolicySyncTask) <3572> EV-H {ManagedTask.Run} Process exiting
189 09:51:52.911 [3648] (EvExchangePolicySyncTask) <3572> EV-H {ExchangePolicySyncTaskProcessor.Main} leaving...
190 09:51:52.911 [3648] (EvExchangePolicySyncTask) <3572> EV:M CEVAudit::Close Closing down the audit database.
191 09:51:52.911 [3648] (EvExchangePolicySyncTask) <3572> EV:M CEVAudit::CloseAuditDatabase Releasing memory map of audit settings
192 09:51:52.927 [3648] (EvExchangePolicySyncTask) <3572> EV:H DirectoryVault: Termination: DLL_PROCESS_DETACH received by the DirectoryVault DLL. |
**** DTrace exit - log terminated