Video Screencast Help
Scheduled Maintenance: Symantec Connect is scheduled to be down Saturday, April 19 from 10am to 2pm Pacific Standard Time (GMT: 5pm to 9pm) for server migration and upgrade.
Please accept our apologies in advance for any inconvenience this might cause.

Enterprise Vault: no mailboxes found

Created: 08 Nov 2012 • Updated: 14 Nov 2012 | 12 comments
This issue has been solved. See solution.

Version 10.0.1.1171

i'd Install and configure Enterprise Vault, but problems is:

no mailbox found and no mailbox archived for the target Exchange 2010.

target for provisioning group is: domain group with one test user in this group(example: "URL\Vault Archive Group").

Provisioning task running succesfully.

Dtrace log:

 

DTrace trace log for server: lessom.ural.xxx.bb
[Opened: 2012/11/08 19:38:12]
 
VAC trace entry info
  Trace category:
    Mailbox provisioning issues (Exchange)
 
  Title:
    provision
 
  Case description:
    
 
  Start time:
    08/11/2012 19:38:12
 
  Running time:
    10 Minutes
 
  Maximum log size:
    100 MB
-----
 
 
Windows version: 6.1.7600
 
Time zone: Ekaterinburg Standard Time
Time difference to UTC: +5:00
 
-----
 
Enabled Trace options
 
Id    Name                             Detail     Include Events
21  - EvExchangePolicySyncTask         Verbose    Yes         
 
-----
 
 
Display Filter:
  Include strings:
    EV-
    EV:
    EV~
 
  Exclude strings:
    CMMStream
    DBFramework
    DirectoryConnection
-----
 
Current File Rollover Settings: 100Mb
 
-----
 
 
Running in Terminal Services session...
  Monitoring debug output from processes bbnning in the console session
  Monitoring debug output from processes bbnning in the current Terminal Services session
 
33 19:38:24.659 [4960] (EvExchangePolicySyncTask) <952> EV-H {ExchangePolicySyncTaskProcessor.Main} Entering...
34 19:38:24.675 [4960] (EvExchangePolicySyncTask) <952> EV-M {ExchangePolicySyncTaskProcessor} Creating new managed object 'ExchangePolicySyncTask'.
35 19:38:24.675 [4960] (EvExchangePolicySyncTask) <952> EV-H {ExchangePolicySyncTaskProcessor..ctor} Entering...
36 19:38:24.677 [4960] (EvExchangePolicySyncTask) <952> EV-H {ExchangePolicySynchronization..ctor} Entering
37 19:38:24.728 [4960] (EvExchangePolicySyncTask) <952> EV-H {ExchangePolicySynchronization..ctor} Start time = 2012-11-08 14:38:24.000
38 19:38:24.728 [4960] (EvExchangePolicySyncTask) <952> EV-H {ExchangePolicySynchronization..ctor} Leaving
39 19:38:24.728 [4960] (EvExchangePolicySyncTask) <952> EV-H {ExchangePolicySyncTaskProcessor..ctor} leaving...
40 19:38:24.732 [4960] (EvExchangePolicySyncTask) <952> EV-M {ManagedObject.KVS.EnterpriseVault.ProcessManagement.IAdminControl.RegisterStateChangedHandler} Title:ExchangePolicySyncTask
41 19:38:24.732 [4960] (EvExchangePolicySyncTask) <952> EV-M {ManagedObjectState.RegisterStateChangedHandler} Owner:ExchangePolicySyncTask
42 19:38:24.734 [4960] (EvExchangePolicySyncTask) <952> EV-H {ManagedProcess.Run} Process starting - "C:\Program Files (x86)\Enterprise Vault\EvExchangePolicySyncTask.exe" -URL:tcp://192.168.224.95:1140/11290671_c4c8_442d_894d_60e3c9bddfcf/KVS.EnterpriseVault.ProcessManager62107587 -CUSTOM:1A05B765098B2774A9AAAF1B7FEF27DB61012u00vlt|False||
43 19:38:24.747 [4960] (EvExchangePolicySyncTask) <952> EV-L {ManagedProcess.LoadConfigurationSettings} Config Settings - debugWaitSeconds=0
44 19:38:24.796 [4960] (EvExchangePolicySyncTask) <952> EV-M {ManagedProcess.RemoteThis} URL[0]: tcp://192.168.224.95:1611/4704594f_6310_4152_9105_cd4f127ea839/EVProcessManager2f5cbf50_bd27_4909_a14b_da1dfad3165d
45 19:38:24.806 [4960] (EvExchangePolicySyncTask) <952> EV-M {ManagedProcess.NotifyAllPresentAndCorrect} Using Process manager URL:tcp://192.168.224.95:1140/11290671_c4c8_442d_894d_60e3c9bddfcf/KVS.EnterpriseVault.ProcessManager62107587 Title:1A05B765098B2774A9AAAF1B7FEF27DB61012u00vlt
46 19:38:24.814 [4960] (EvExchangePolicySyncTask) <952> EV-M {ManagedObjectState.RegisterStateChangedHandler} Owner:EvExchangePolicySyncTask
47 19:38:24.843 [4960] (EvExchangePolicySyncTask) <4648> EV-M {ManagedProcess.KVS.EnterpriseVault.ProcessManagement.IAdminControl.Start} Current State:Stopped
48 19:38:24.844 [4960] (EvExchangePolicySyncTask) <4648> EV-M {ExchangePolicySyncTaskProcessor} Name:ExchangePolicySyncTask Current State:Stopped startOptions: ''.
49 19:38:24.845 [4960] (EvExchangePolicySyncTask) <4648> EV-H {ManagedTask.OnStart} ManagedTask.OnStart()
50 19:38:24.849 [4960] (EvExchangePolicySyncTask) <4648> EV-M {ManagedProcess.ObjectStateChanged} ExchangePolicySyncTask New:Running Old:Starting My:Starting
51 19:38:24.849 [4960] (EvExchangePolicySyncTask) <4648> EV-M {ManagedProcess.CheckProcessState} New State:Running Quobbm:Tbbe
52 19:38:24.850 [4960] (EvExchangePolicySyncTask) <4648> EV-M {ManagedProcess.NotifyPMStateChanged} EvExchangePolicySyncTask New State:Running Old:Starting My:Running
53 19:38:24.857 [4960] (EvExchangePolicySyncTask) <4648> EV-M {ManagedProcess.CheckProcessState} New State:Running Quobbm:Tbbe
54 19:38:24.857 [4960] (EvExchangePolicySyncTask) <952> EV-L {ManagedProcess.Run} Loading... State:Running Countdown:0
55 19:38:24.899 [4960] (EvExchangePolicySyncTask) <5984> EV-L {DirectorySync.IsDirectoryAvailable} IsDirectoryAvailable()
56 19:38:24.899 [4960] (EvExchangePolicySyncTask) <5984> EV-L {DirectorySync.IsDirectoryAvailable} Try to create Directory Service locally first.
57 19:38:24.908 [4960] (EvExchangePolicySyncTask) <5984> EV-L {DirectorySync.IsDirectoryAvailable} Local creation for DirectoryService succeeded.
58 19:38:24.908 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectorySync.IsDirectoryAvailable} IsDirectoryAvailable() - Check if config wizard has being bbn
59 19:38:24.933 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectorySync.IsDirectoryAvailable} IsDirectoryAvailable() - Releasing Directory Public Service interface
60 19:38:24.933 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectorySync.IsDirectoryAvailable} IsDirectoryAvailable() - Directory Service is available
61 19:38:24.934 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ManagedTask.MainThread} ManagedTask.MainThread() - Custom args:1A05B765098B2774A9AAAF1B7FEF27DB61012u00vlt|False||
62 19:38:25.116 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ExchangePolicySyncTaskProcessor.TaskMain} Entering...
63 19:38:25.116 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ExchangePolicySyncTaskProcessor.WaitForDebugger} Entering...
64 19:38:25.116 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ExchangePolicySyncTaskProcessor.WaitForDebugger} leaving...
65 19:38:25.226 [4960] (EvExchangePolicySyncTask) <5984> EV-L {VaultStoreSync..ctor} Setting VaultStoreSync cache refresh interval to 5 minutes
71 19:38:25.242 [4960] (EvExchangePolicySyncTask) <5984> EV:L CBaseDirectoryServiceWrapper::CreateDirectoryService() - Entry [m_nNumTries = 40]
72 19:38:25.244 [4960] (EvExchangePolicySyncTask) <5984> EV:L CBaseDirectoryServiceWrapper::CreateDirectoryService() - Successfully communicated with an EV Directory Service on the local machine
73 19:38:25.261 [4960] (EvExchangePolicySyncTask) <5984> EV:M DirectoryVault: Initialization: DLL_PROCESS_ATTACH received by the DirectoryVault DLL. |
74 19:38:25.261 [4960] (EvExchangePolicySyncTask) <5984> EV:H DirectoryVault: Initialization: DirectoryVault DLL version is: v1.2.| |
75 19:38:25.262 [4960] (EvExchangePolicySyncTask) <5984> EV:L CDirectoryVaultObject::GetAttributeListFromType Unknown type - Task
77 19:38:25.265 [4960] (EvExchangePolicySyncTask) <5984> EV:L CBaseDirectoryServiceWrapper::CreateDirectoryService() - Entry [m_nNumTries = 40]
78 19:38:25.267 [4960] (EvExchangePolicySyncTask) <5984> EV:L CBaseDirectoryServiceWrapper::CreateDirectoryService() - Successfully communicated with an EV Directory Service on the local machine
79 19:38:25.283 [4960] (EvExchangePolicySyncTask) <5984> EV:L CDirectoryVaultObject::GetAttributeListFromType Unknown type - ExchangePolicySyncTaskView
80 19:38:25.288 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ExchangePolicySyncTaskProcessor.RunTask} Entering...
81 19:38:25.298 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ExchangePolicySynchronization.RunSynchronization} Synchronization started..
82 19:38:25.305 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ExchangePolicySynchronization.Initialize} Initializing Task [Exchange Provisioning Task for URAL.XXX.RU] for organization [1913D207F115C5B488B46001D97DD383C1011e00vlt]
84 19:38:25.307 [4960] (EvExchangePolicySyncTask) <5984> EV:L CBaseDirectoryServiceWrapper::CreateDirectoryService() - Entry [m_nNumTries = 40]
85 19:38:25.310 [4960] (EvExchangePolicySyncTask) <5984> EV:L CBaseDirectoryServiceWrapper::CreateDirectoryService() - Successfully communicated with an EV Directory Service on the local machine
86 19:38:25.483 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryCache.ReadEntry} Sel Type: KVS.EnterpriseVault.Directory.DirectoryDS+OrganizationRow |From cache? False
88 19:38:25.655 [4960] (EvExchangePolicySyncTask) <5984> EV:L CBaseDirectoryServiceWrapper::CreateDirectoryService() - Entry [m_nNumTries = 40]
89 19:38:25.659 [4960] (EvExchangePolicySyncTask) <5984> EV:L CBaseDirectoryServiceWrapper::CreateDirectoryService() - Successfully communicated with an EV Directory Service on the local machine
90 19:38:25.661 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: vlt ==> SQL server: LESSOM\IDI Cached? False
91 19:38:25.732 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDataset.GetEntryInfo} EntryId: 1913D207F115C5B488B46001D97DD383C1011e00vlt Name: URAL.XXX.RU
92 19:38:25.734 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryCache.Pbbne} Cache size: 0 Removed:0
93 19:38:25.735 [4960] (EvExchangePolicySyncTask) <5984> EV-L {DirectoryCache.InsertEntry} EntryId:1913D207F115C5B488B46001D97DD383C1011e00vlt|Replaced? False
94 19:38:25.738 [4960] (EvExchangePolicySyncTask) <5984> EV:M DirectoryHelper::GetDcName() - Domain = [URAL.XXX.RU]
95 19:38:25.740 [4960] (EvExchangePolicySyncTask) <5984> EV:M DirectoryHelper::GetDcName() - Unable to contact GC; trying nearest DC
96 19:38:25.741 [4960] (EvExchangePolicySyncTask) <5984> EV:M DirectoryHelper::GetDcName() - Returning GC server [\\palladium.ural.xxx.bb]
97 19:38:25.874 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ExchangePolicySynchronization.TestDCConnection} Entering
98 19:38:25.875 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.InitDirEntry} Binding to object [GC://palladium.ural.xxx.bb] (serverBind = Tbbe)
99 19:38:25.910 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ExchangePolicySynchronization.Initialize} Processing domain [URAL.XXX.RU] using DC [GC://palladium.ural.xxx.bb]
100 19:38:25.911 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.CalculateDomainFQDN} Calculating FQDN for domain [URAL.XXX.RU]
101 19:38:25.911 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.CalculateDomainFQDN} Domain FQDN = [DC=URAL,DC=XXX,DC=RU]
102 19:38:25.913 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ExchangePolicySynchronization.InitializeDomainControllerCache} Entering
103 19:38:25.920 [4960] (EvExchangePolicySyncTask) <5984> EV:M CDirectoryHelper::GetDomainsInForest() - Called for domain [URAL.XXX.RU]
104 19:38:25.923 [4960] (EvExchangePolicySyncTask) <5984> EV:M CDirectoryHelper::GetDomainsInForest() - Got initial DC [\\palladium.ural.xxx.bb]
105 19:38:25.942 [4960] (EvExchangePolicySyncTask) <5984> EV:M CDirectoryHelper::GetDomainsInForest() - Successfully enumerated domains in the forest
106 19:38:25.944 [4960] (EvExchangePolicySyncTask) <5984> EV:M CDirectoryHelper::NameToFQDN() - Calling CrackDsName() with NT4 to FQDN
107 19:38:25.945 [4960] (EvExchangePolicySyncTask) <5984> EV:M DirectoryHelper::CrackDsName() - DC = [\\Hercules-V.xxx.bb], NameToCrack = [AJAX\]
108 19:38:25.945 [4960] (EvExchangePolicySyncTask) <5984> EV:M DirectoryHelper::CrackDsName() - Calling DsBind()
109 19:38:26.292 [4960] (EvExchangePolicySyncTask) <5984> EV:M DirectoryHelper::CrackDsName() - Calling DsCrackNames()
110 19:38:26.373 [4960] (EvExchangePolicySyncTask) <5984> EV:M DirectoryHelper::CrackDsName() - Returning cracked name: [DC=xxx,DC=bb]
111 19:38:26.373 [4960] (EvExchangePolicySyncTask) <5984> EV:M CDirectoryHelper::GetDomainsInForest() - Found domain [DC=xxx,DC=bb] with DC [\\Hercules-V.xxx.bb]
112 19:38:26.554 [4960] (EvExchangePolicySyncTask) <5984> EV:M CDirectoryHelper::NameToFQDN() - Calling CrackDsName() with NT4 to FQDN
113 19:38:26.554 [4960] (EvExchangePolicySyncTask) <5984> EV:M DirectoryHelper::CrackDsName() - DC = [\\r59vdc2.kama.xxx.bb], NameToCrack = [KAMA\]
114 19:38:26.554 [4960] (EvExchangePolicySyncTask) <5984> EV:M DirectoryHelper::CrackDsName() - Calling DsBind()
115 19:38:27.301 [4960] (EvExchangePolicySyncTask) <5984> EV:M DirectoryHelper::CrackDsName() - Calling DsCrackNames()
116 19:38:27.451 [4960] (EvExchangePolicySyncTask) <5984> EV:M DirectoryHelper::CrackDsName() - Returning cracked name: [DC=kama,DC=xxx,DC=bb]
117 19:38:27.451 [4960] (EvExchangePolicySyncTask) <5984> EV:M CDirectoryHelper::GetDomainsInForest() - Found domain [DC=kama,DC=xxx,DC=bb] with DC [\\r59vdc2.kama.xxx.bb]
118 19:38:27.632 [4960] (EvExchangePolicySyncTask) <5984> EV:M CDirectoryHelper::NameToFQDN() - Calling CrackDsName() with NT4 to FQDN
119 19:38:27.632 [4960] (EvExchangePolicySyncTask) <5984> EV:M DirectoryHelper::CrackDsName() - DC = [\\F09DC02.kras.xxx.bb], NameToCrack = [KRAS\]
120 19:38:27.633 [4960] (EvExchangePolicySyncTask) <5984> EV:M DirectoryHelper::CrackDsName() - Calling DsBind()
129 19:38:48.663 [4960] (EvExchangePolicySyncTask) <5984> EV:M DirectoryHelper::CrackDsName() - Method failed with error [1722]
130 19:38:48.663 [4960] (EvExchangePolicySyncTask) <5984> EV:M CDirectoryHelper::GetDomainsInForest() - Failed to translate domain [KRAS\] to FQDN with DC [\\F09DC02.kras.xxx.bb], err [1722]
131 19:38:48.820 [4960] (EvExchangePolicySyncTask) <5984> EV:M CDirectoryHelper::NameToFQDN() - Calling CrackDsName() with NT4 to FQDN
132 19:38:48.821 [4960] (EvExchangePolicySyncTask) <5984> EV:M DirectoryHelper::CrackDsName() - DC = [\\ATRIUM.nnov.xxx.bb], NameToCrack = [NNV\]
133 19:38:48.821 [4960] (EvExchangePolicySyncTask) <5984> EV:M DirectoryHelper::CrackDsName() - Calling DsBind()
138 19:38:49.433 [4960] (EvExchangePolicySyncTask) <5984> EV:M DirectoryHelper::CrackDsName() - Calling DsCrackNames()
139 19:38:49.547 [4960] (EvExchangePolicySyncTask) <5984> EV:M DirectoryHelper::CrackDsName() - Returning cracked name: [DC=nnov,DC=xxx,DC=bb]
140 19:38:49.547 [4960] (EvExchangePolicySyncTask) <5984> EV:M CDirectoryHelper::GetDomainsInForest() - Found domain [DC=nnov,DC=xxx,DC=bb] with DC [\\ATRIUM.nnov.xxx.bb]
141 19:38:49.680 [4960] (EvExchangePolicySyncTask) <5984> EV:M CDirectoryHelper::NameToFQDN() - Calling CrackDsName() with NT4 to FQDN
142 19:38:49.680 [4960] (EvExchangePolicySyncTask) <5984> EV:M DirectoryHelper::CrackDsName() - DC = [\\NSKDC01.nsk.xxx.bb], NameToCrack = [NSK\]
143 19:38:49.680 [4960] (EvExchangePolicySyncTask) <5984> EV:M DirectoryHelper::CrackDsName() - Calling DsBind()
146 19:38:50.002 [4960] (EvExchangePolicySyncTask) <5984> EV:M DirectoryHelper::CrackDsName() - Calling DsCrackNames()
147 19:38:50.055 [4960] (EvExchangePolicySyncTask) <5984> EV:M DirectoryHelper::CrackDsName() - Returning cracked name: [DC=nsk,DC=xxx,DC=bb]
148 19:38:50.055 [4960] (EvExchangePolicySyncTask) <5984> EV:M CDirectoryHelper::GetDomainsInForest() - Found domain [DC=nsk,DC=xxx,DC=bb] with DC [\\NSKDC01.nsk.xxx.bb]
149 19:38:50.186 [4960] (EvExchangePolicySyncTask) <5984> EV:M CDirectoryHelper::NameToFQDN() - Calling CrackDsName() with NT4 to FQDN
150 19:38:50.186 [4960] (EvExchangePolicySyncTask) <5984> EV:M DirectoryHelper::CrackDsName() - DC = [\\SMRDC2.samara.xxx.bb], NameToCrack = [SAMARA\]
151 19:38:50.186 [4960] (EvExchangePolicySyncTask) <5984> EV:M DirectoryHelper::CrackDsName() - Calling DsBind()
152 19:38:50.480 [4960] (EvExchangePolicySyncTask) <5984> EV:M DirectoryHelper::CrackDsName() - Calling DsCrackNames()
153 19:38:50.525 [4960] (EvExchangePolicySyncTask) <5984> EV:M DirectoryHelper::CrackDsName() - Returning cracked name: [DC=samara,DC=xxx,DC=bb]
154 19:38:50.525 [4960] (EvExchangePolicySyncTask) <5984> EV:M CDirectoryHelper::GetDomainsInForest() - Found domain [DC=samara,DC=xxx,DC=bb] with DC [\\SMRDC2.samara.xxx.bb]
155 19:38:50.731 [4960] (EvExchangePolicySyncTask) <5984> EV:M CDirectoryHelper::NameToFQDN() - Calling CrackDsName() with NT4 to FQDN
156 19:38:50.731 [4960] (EvExchangePolicySyncTask) <5984> EV:M DirectoryHelper::CrackDsName() - DC = [\\neptun.south.xxx.bb], NameToCrack = [SOUTH\]
157 19:38:50.731 [4960] (EvExchangePolicySyncTask) <5984> EV:M DirectoryHelper::CrackDsName() - Calling DsBind()
162 19:38:51.622 [4960] (EvExchangePolicySyncTask) <5984> EV:M DirectoryHelper::CrackDsName() - Calling DsCrackNames()
163 19:38:51.720 [4960] (EvExchangePolicySyncTask) <5984> EV:M DirectoryHelper::CrackDsName() - Returning cracked name: [DC=south,DC=xxx,DC=bb]
164 19:38:51.720 [4960] (EvExchangePolicySyncTask) <5984> EV:M CDirectoryHelper::GetDomainsInForest() - Found domain [DC=south,DC=xxx,DC=bb] with DC [\\neptun.south.xxx.bb]
165 19:38:51.721 [4960] (EvExchangePolicySyncTask) <5984> EV:M CDirectoryHelper::NameToFQDN() - Calling CrackDsName() with NT4 to FQDN
166 19:38:51.722 [4960] (EvExchangePolicySyncTask) <5984> EV:M DirectoryHelper::CrackDsName() - DC = [\\palladium.ural.xxx.bb], NameToCrack = [URAL\]
167 19:38:51.722 [4960] (EvExchangePolicySyncTask) <5984> EV:M DirectoryHelper::CrackDsName() - Calling DsBind()
168 19:38:51.730 [4960] (EvExchangePolicySyncTask) <5984> EV:M DirectoryHelper::CrackDsName() - Calling DsCrackNames()
169 19:38:51.733 [4960] (EvExchangePolicySyncTask) <5984> EV:M DirectoryHelper::CrackDsName() - Returning cracked name: [DC=ural,DC=xxx,DC=bb]
170 19:38:51.733 [4960] (EvExchangePolicySyncTask) <5984> EV:M CDirectoryHelper::GetDomainsInForest() - Found domain [DC=ural,DC=xxx,DC=bb] with DC [\\palladium.ural.xxx.bb]
171 19:38:51.733 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.InitializeDomainControllerCache} Adding domain controller [Hercules-V.xxx.bb] for domain [DC=xxx,DC=bb]
172 19:38:51.733 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.InitializeDomainControllerCache} Adding domain controller [r59vdc2.kama.xxx.bb] for domain [DC=kama,DC=xxx,DC=bb]
173 19:38:51.733 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.InitializeDomainControllerCache} Adding domain controller [ATRIUM.nnov.xxx.bb] for domain [DC=nnov,DC=xxx,DC=bb]
174 19:38:51.733 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.InitializeDomainControllerCache} Adding domain controller [NSKDC01.nsk.xxx.bb] for domain [DC=nsk,DC=xxx,DC=bb]
175 19:38:51.733 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.InitializeDomainControllerCache} Adding domain controller [SMRDC2.samara.xxx.bb] for domain [DC=samara,DC=xxx,DC=bb]
176 19:38:51.752 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.InitializeDomainControllerCache} Adding domain controller [neptun.south.xxx.bb] for domain [DC=south,DC=xxx,DC=bb]
177 19:38:51.752 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.InitializeDomainControllerCache} Adding domain controller [palladium.ural.xxx.bb] for domain [DC=ural,DC=xxx,DC=bb]
178 19:38:51.811 [4960] (EvExchangePolicySyncTask) <5984> EV~W |Event ID: 41143 The Exchange mailbox provisioning task could not connect to a domain controller for a domain in the forest. The task cannot process any Active Directory objects from this domain.|Task: Exchange Provisioning Task for URAL.XXX.RU|Domain: URAL.XXX.RU|Failed domain: KRAS\|Error code: DomainNameToFQDN() [\\F09DC02.kras.xxx.bb] [1722]
179 19:38:51.812 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ExchangePolicySynchronization.InitializeDomainControllerCache} Leaving
180 19:38:51.813 [4960] (EvExchangePolicySyncTask) <5984> EV-L {ThreadSafeQueue.SetQueueType} Set QueueType = [Sequential]
181 19:38:51.814 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ExchangePolicySynchronization.InitalizeAuditing} Entering
182 19:38:51.831 [4960] (EvExchangePolicySyncTask) <5984> EV:L AuditInterface::AuditingEnabled() called
183 19:38:51.831 [4960] (EvExchangePolicySyncTask) <5984> EV:L CEVAudit::WillPerformAudit Determine whether to audit or not.
184 19:38:51.831 [4960] (EvExchangePolicySyncTask) <5984> EV:L CEVAudit::Initialise Memory mapped area found existing
185 19:38:51.832 [4960] (EvExchangePolicySyncTask) <5984> EV:L CEVAudit::WillPerformAudit Auditing is off, current status '1'
186 19:38:51.832 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ExchangePolicySynchronization.InitalizeAuditing} Leaving
187 19:38:51.833 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.Initialize} Read 'SynchInMigrationMode' registry value [0]
188 19:38:51.833 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.Initialize} Number of worker threads = 5
189 19:38:51.878 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryCache.ReadEntry} Sel Type: KVS.EnterpriseVault.Directory.DirectoryDS+ArchivingRetrievalTaskViewRow |From cache? False
190 19:38:51.878 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: vlt ==> SQL server: LESSOM\IDI Cached? Tbbe
191 19:38:51.897 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDataset.GetEntryInfo} EntryId: 115CB3C3C30ACAE4AB97B9336B5B2F8031011z00vlt Name: Exchange Mailbox Archiving Task for SELENIUM
192 19:38:51.898 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryCache.Pbbne} Cache size: 1 Removed:0
193 19:38:51.898 [4960] (EvExchangePolicySyncTask) <5984> EV-L {DirectoryCache.InsertEntry} EntryId:115CB3C3C30ACAE4AB97B9336B5B2F8031011z00vlt|Replaced? False
194 19:38:51.910 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryCache.ReadEntry} Sel Type: KVS.EnterpriseVault.Directory.DirectoryDS+ComputerEntryRow |From cache? False
195 19:38:51.911 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: vlt ==> SQL server: LESSOM\IDI Cached? Tbbe
196 19:38:51.919 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDataset.GetEntryInfo} EntryId: 1009A7A1C41B947418F110387915A89361410000vlt Name: vlt.ural.xxx.bb
197 19:38:51.920 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryCache.Pbbne} Cache size: 2 Removed:0
198 19:38:51.920 [4960] (EvExchangePolicySyncTask) <5984> EV-L {DirectoryCache.InsertEntry} EntryId:1009A7A1C41B947418F110387915A89361410000vlt|Replaced? False
199 19:38:51.958 [4960] (EvExchangePolicySyncTask) <5984> EV:L VaultCoCreateInstanceEx: IsLocalMachineExtendedCheck returned tbbe for server [vlt.ural.xxx.bb] as the IP Address matched that of the local machine
200 19:38:51.976 [4960] (EvExchangePolicySyncTask) <5984> EV-M {AgentSyncManager.Initialize} Added sync helper for exchange server [SELENIUM] against task [Exchange Mailbox Archiving Task for SELENIUM] on EV computer [vlt.ural.xxx.bb]
201 19:38:51.976 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.Initialize} Mark mailboxes as deleted after 14 days.
202 19:38:51.981 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryCache.ReadEntry} Sel Type: KVS.EnterpriseVault.Directory.DirectoryDS+ExchangeServerEntryRow |From cache? False
203 19:38:51.981 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: vlt ==> SQL server: LESSOM\IDI Cached? Tbbe
204 19:38:51.991 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDataset.GetEntryInfo} EntryId: 18AE0383B2791B84887ACF50E254621641o10000vlt Name: SELENIUM
205 19:38:51.991 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryCache.Pbbne} Cache size: 3 Removed:0
206 19:38:51.991 [4960] (EvExchangePolicySyncTask) <5984> EV-L {DirectoryCache.InsertEntry} EntryId:18AE0383B2791B84887ACF50E254621641o10000vlt|Replaced? False
207 19:38:51.993 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.Initialize} Read Exchange Server info:|Name = [SELENIUM]|LegacyDN = [/o=AJAX_CORP/ou=Exchange Administrative Group (FYDIBOHF23SPDLT)/cn=Configuration/cn=Servers/cn=SELENIUM]|ID = [1]
208 19:38:51.993 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.Initialize} Added Exchange server [SELENIUM] to cache
209 19:38:52.002 [4960] (EvExchangePolicySyncTask) <5984> EV~I |Event ID: 41102 The Exchange mailbox provisioning task has started.|Task: Exchange Provisioning Task for URAL.XXX.RU|Domain: URAL.XXX.RU
210 19:38:52.003 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ExchangePolicySynchronization.Initialize} Leaving
211 19:38:52.017 [4960] (EvExchangePolicySyncTask) <5984> EV-H {EVManagedFolders.UpdateManagedFolderRecords} Updating Managed Folder Records [palladium.ural.xxx.bb]
212 19:38:52.017 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ADHelper.InitDirEntry} Binding to object [LDAP://palladium.ural.xxx.bb/rootDSE] (serverBind = Tbbe)
213 19:38:52.032 [4960] (EvExchangePolicySyncTask) <5984> EV-H {EVManagedFolders.GetExchangeOrganizations} searchRoot [LDAP://palladium.ural.xxx.bb/CN=Microsoft Exchange,CN=Services,CN=Configuration,DC=xxx,DC=bb]
214 19:38:52.032 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ADHelper.InitDirEntry} Binding to object [LDAP://palladium.ural.xxx.bb/CN=Microsoft Exchange,CN=Services,CN=Configuration,DC=xxx,DC=bb] (serverBind = Tbbe)
215 19:38:52.529 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.GetExchangeOrganizations} Found Exchange Organization [Холдинг]
216 19:38:52.529 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.UpdateManagedFolderRecords} searchRoot [LDAP://palladium.ural.xxx.bb/CN=ELC Folders Container,CN=Холдинг,CN=Microsoft Exchange,CN=Services,CN=Configuration,DC=xxx,DC=bb]
217 19:38:52.529 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ADHelper.InitDirEntry} Binding to object [LDAP://palladium.ural.xxx.bb/CN=ELC Folders Container,CN=Холдинг,CN=Microsoft Exchange,CN=Services,CN=Configuration,DC=xxx,DC=bb] (serverBind = Tbbe)
218 19:38:52.548 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [name]: [Inbox 3 days] [System.String]
219 19:38:52.549 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [whenChanged]: [5/16/2012 4:26:36 PM] [System.DateTime]
220 19:38:52.552 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [name]: [Inbox 3 days] [System.String]
221 19:38:52.552 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [msExchELCFlags]: [0] [System.Int32]
222 19:38:52.552 [4960] (EvExchangePolicySyncTask) <5984> EV-H {EVManagedFolders.UpdateManagedFolderRecords} Processing Managed Content Setting [Inbox 3 days]
223 19:38:52.557 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: vlt ==> SQL server: LESSOM\IDI Cached? Tbbe
224 19:38:52.563 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [name]: [Junk 3 days] [System.String]
225 19:38:52.564 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [whenChanged]: [5/16/2012 4:26:51 PM] [System.DateTime]
226 19:38:52.566 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [name]: [Junk E-mail 3 days] [System.String]
227 19:38:52.567 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [msExchELCFlags]: [0] [System.Int32]
228 19:38:52.567 [4960] (EvExchangePolicySyncTask) <5984> EV-H {EVManagedFolders.UpdateManagedFolderRecords} Processing Managed Content Setting [Junk 3 days]
229 19:38:52.567 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: vlt ==> SQL server: LESSOM\IDI Cached? Tbbe
230 19:38:52.572 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [name]: [Sent 730 days] [System.String]
231 19:38:52.572 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [whenChanged]: [5/16/2012 4:26:50 PM] [System.DateTime]
232 19:38:52.575 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [name]: [Sent Items 730 days] [System.String]
233 19:38:52.575 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [msExchELCFlags]: [0] [System.Int32]
234 19:38:52.575 [4960] (EvExchangePolicySyncTask) <5984> EV-H {EVManagedFolders.UpdateManagedFolderRecords} Processing Managed Content Setting [Sent 730 days]
235 19:38:52.575 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: vlt ==> SQL server: LESSOM\IDI Cached? Tbbe
236 19:38:52.579 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [name]: [VoiceMail-MissedCalls] [System.String]
237 19:38:52.579 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [whenChanged]: [8/13/2012 8:28:04 AM] [System.DateTime]
238 19:38:52.582 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [name]: [VoiceMail] [System.String]
239 19:38:52.582 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [msExchELCFlags]: [0] [System.Int32]
240 19:38:52.583 [4960] (EvExchangePolicySyncTask) <5984> EV-H {EVManagedFolders.UpdateManagedFolderRecords} Processing Managed Content Setting [VoiceMail-MissedCalls]
241 19:38:52.583 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: vlt ==> SQL server: LESSOM\IDI Cached? Tbbe
242 19:38:52.587 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [name]: [VoiceMail-VM] [System.String]
243 19:38:52.587 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [whenChanged]: [8/13/2012 8:28:04 AM] [System.DateTime]
244 19:38:52.590 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [name]: [VoiceMail] [System.String]
245 19:38:52.590 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [msExchELCFlags]: [0] [System.Int32]
246 19:38:52.590 [4960] (EvExchangePolicySyncTask) <5984> EV-H {EVManagedFolders.UpdateManagedFolderRecords} Processing Managed Content Setting [VoiceMail-VM]
247 19:38:52.590 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: vlt ==> SQL server: LESSOM\IDI Cached? Tbbe
248 19:38:52.593 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [name]: [Inbox 180 days] [System.String]
249 19:38:52.593 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [whenChanged]: [5/16/2012 4:26:39 PM] [System.DateTime]
250 19:38:52.595 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [name]: [Inbox 180 days] [System.String]
251 19:38:52.595 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [msExchELCFlags]: [0] [System.Int32]
252 19:38:52.596 [4960] (EvExchangePolicySyncTask) <5984> EV-H {EVManagedFolders.UpdateManagedFolderRecords} Processing Managed Content Setting [Inbox 180 days]
253 19:38:52.596 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: vlt ==> SQL server: LESSOM\IDI Cached? Tbbe
254 19:38:52.599 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [name]: [Entire Mailbox 180 days] [System.String]
255 19:38:52.599 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [whenChanged]: [5/16/2012 4:26:39 PM] [System.DateTime]
256 19:38:52.602 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [name]: [Entire Mailbox 180 days] [System.String]
257 19:38:52.602 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [msExchELCFlags]: [0] [System.Int32]
258 19:38:52.602 [4960] (EvExchangePolicySyncTask) <5984> EV-H {EVManagedFolders.UpdateManagedFolderRecords} Processing Managed Content Setting [Entire Mailbox 180 days]
259 19:38:52.602 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: vlt ==> SQL server: LESSOM\IDI Cached? Tbbe
260 19:38:52.606 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [name]: [Sent items 180 days] [System.String]
261 19:38:52.606 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [whenChanged]: [5/16/2012 4:26:39 PM] [System.DateTime]
262 19:38:52.609 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [name]: [Sent Items 180 days] [System.String]
263 19:38:52.609 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [msExchELCFlags]: [0] [System.Int32]
264 19:38:52.609 [4960] (EvExchangePolicySyncTask) <5984> EV-H {EVManagedFolders.UpdateManagedFolderRecords} Processing Managed Content Setting [Sent items 180 days]
265 19:38:52.609 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: vlt ==> SQL server: LESSOM\IDI Cached? Tbbe
266 19:38:52.612 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [name]: [Inbox 30 days] [System.String]
267 19:38:52.613 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [whenChanged]: [5/16/2012 4:26:39 PM] [System.DateTime]
268 19:38:52.615 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [name]: [Inbox 30 days] [System.String]
269 19:38:52.616 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [msExchELCFlags]: [0] [System.Int32]
270 19:38:52.616 [4960] (EvExchangePolicySyncTask) <5984> EV-H {EVManagedFolders.UpdateManagedFolderRecords} Processing Managed Content Setting [Inbox 30 days]
271 19:38:52.616 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: vlt ==> SQL server: LESSOM\IDI Cached? Tbbe
272 19:38:52.619 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [name]: [Sent Items 30 days] [System.String]
273 19:38:52.620 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [whenChanged]: [5/16/2012 4:26:39 PM] [System.DateTime]
274 19:38:52.622 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [name]: [Sent Items 30 days] [System.String]
275 19:38:52.623 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [msExchELCFlags]: [0] [System.Int32]
276 19:38:52.623 [4960] (EvExchangePolicySyncTask) <5984> EV-H {EVManagedFolders.UpdateManagedFolderRecords} Processing Managed Content Setting [Sent Items 30 days]
277 19:38:52.623 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: vlt ==> SQL server: LESSOM\IDI Cached? Tbbe
278 19:38:52.627 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [name]: [Deleted 30 days] [System.String]
279 19:38:52.627 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [whenChanged]: [5/16/2012 4:26:39 PM] [System.DateTime]
280 19:38:52.630 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [name]: [Deleted Items 30 days] [System.String]
281 19:38:52.630 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [msExchELCFlags]: [0] [System.Int32]
282 19:38:52.630 [4960] (EvExchangePolicySyncTask) <5984> EV-H {EVManagedFolders.UpdateManagedFolderRecords} Processing Managed Content Setting [Deleted 30 days]
283 19:38:52.630 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: vlt ==> SQL server: LESSOM\IDI Cached? Tbbe
284 19:38:52.634 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [name]: [Entire Mailbox 30 days] [System.String]
285 19:38:52.634 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [whenChanged]: [5/16/2012 4:26:39 PM] [System.DateTime]
286 19:38:52.637 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [name]: [Entire Mailbox 30 days] [System.String]
287 19:38:52.637 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [msExchELCFlags]: [0] [System.Int32]
288 19:38:52.637 [4960] (EvExchangePolicySyncTask) <5984> EV-H {EVManagedFolders.UpdateManagedFolderRecords} Processing Managed Content Setting [Entire Mailbox 30 days]
289 19:38:52.637 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: vlt ==> SQL server: LESSOM\IDI Cached? Tbbe
290 19:38:52.640 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [name]: [Junk 30 days] [System.String]
291 19:38:52.640 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [whenChanged]: [5/16/2012 4:26:39 PM] [System.DateTime]
292 19:38:52.643 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [name]: [Junk E-mail 30 days] [System.String]
293 19:38:52.643 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [msExchELCFlags]: [0] [System.Int32]
294 19:38:52.643 [4960] (EvExchangePolicySyncTask) <5984> EV-H {EVManagedFolders.UpdateManagedFolderRecords} Processing Managed Content Setting [Junk 30 days]
295 19:38:52.643 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: vlt ==> SQL server: LESSOM\IDI Cached? Tbbe
296 19:38:52.647 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [name]: [Deleted 90 days] [System.String]
297 19:38:52.647 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [whenChanged]: [5/16/2012 4:26:36 PM] [System.DateTime]
298 19:38:52.650 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [name]: [Deleted Items 90 days] [System.String]
299 19:38:52.650 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [msExchELCFlags]: [0] [System.Int32]
300 19:38:52.650 [4960] (EvExchangePolicySyncTask) <5984> EV-H {EVManagedFolders.UpdateManagedFolderRecords} Processing Managed Content Setting [Deleted 90 days]
301 19:38:52.650 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: vlt ==> SQL server: LESSOM\IDI Cached? Tbbe
302 19:38:52.654 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [name]: [Deleted 7 days] [System.String]
303 19:38:52.654 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [whenChanged]: [5/16/2012 4:26:31 PM] [System.DateTime]
304 19:38:52.657 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [name]: [Deleted Items 7 days] [System.String]
305 19:38:52.657 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [msExchELCFlags]: [0] [System.Int32]
306 19:38:52.657 [4960] (EvExchangePolicySyncTask) <5984> EV-H {EVManagedFolders.UpdateManagedFolderRecords} Processing Managed Content Setting [Deleted 7 days]
307 19:38:52.658 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: vlt ==> SQL server: LESSOM\IDI Cached? Tbbe
308 19:38:52.661 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [name]: [Sent 365 days] [System.String]
309 19:38:52.662 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [whenChanged]: [5/16/2012 4:26:39 PM] [System.DateTime]
310 19:38:52.664 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [name]: [Sent Items 365 days] [System.String]
311 19:38:52.664 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [msExchELCFlags]: [0] [System.Int32]
312 19:38:52.665 [4960] (EvExchangePolicySyncTask) <5984> EV-H {EVManagedFolders.UpdateManagedFolderRecords} Processing Managed Content Setting [Sent 365 days]
313 19:38:52.665 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: vlt ==> SQL server: LESSOM\IDI Cached? Tbbe
314 19:38:52.668 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [name]: [Junk 14 days] [System.String]
315 19:38:52.668 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [whenChanged]: [5/16/2012 4:26:36 PM] [System.DateTime]
316 19:38:52.671 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [name]: [Junk E-mail 14 days] [System.String]
317 19:38:52.671 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [msExchELCFlags]: [0] [System.Int32]
318 19:38:52.672 [4960] (EvExchangePolicySyncTask) <5984> EV-H {EVManagedFolders.UpdateManagedFolderRecords} Processing Managed Content Setting [Junk 14 days]
319 19:38:52.672 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: vlt ==> SQL server: LESSOM\IDI Cached? Tbbe
320 19:38:52.675 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [name]: [Junk 7 days] [System.String]
321 19:38:52.675 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [whenChanged]: [5/16/2012 4:26:31 PM] [System.DateTime]
322 19:38:52.678 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [name]: [Junk E-mail 7 days] [System.String]
323 19:38:52.679 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [msExchELCFlags]: [0] [System.Int32]
324 19:38:52.679 [4960] (EvExchangePolicySyncTask) <5984> EV-H {EVManagedFolders.UpdateManagedFolderRecords} Processing Managed Content Setting [Junk 7 days]
325 19:38:52.679 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: vlt ==> SQL server: LESSOM\IDI Cached? Tbbe
326 19:38:52.682 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [name]: [Sent 912 days] [System.String]
327 19:38:52.683 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [whenChanged]: [5/16/2012 4:26:35 PM] [System.DateTime]
328 19:38:52.686 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [name]: [Sent Items 912 days] [System.String]
329 19:38:52.686 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [msExchELCFlags]: [0] [System.Int32]
330 19:38:52.686 [4960] (EvExchangePolicySyncTask) <5984> EV-H {EVManagedFolders.UpdateManagedFolderRecords} Processing Managed Content Setting [Sent 912 days]
331 19:38:52.686 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: vlt ==> SQL server: LESSOM\IDI Cached? Tbbe
332 19:38:52.690 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [name]: [Deleted 180 days] [System.String]
333 19:38:52.691 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [whenChanged]: [5/16/2012 4:26:36 PM] [System.DateTime]
334 19:38:52.694 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [name]: [Deleted Items 180 days] [System.String]
335 19:38:52.694 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [msExchELCFlags]: [0] [System.Int32]
336 19:38:52.694 [4960] (EvExchangePolicySyncTask) <5984> EV-H {EVManagedFolders.UpdateManagedFolderRecords} Processing Managed Content Setting [Deleted 180 days]
337 19:38:52.694 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: vlt ==> SQL server: LESSOM\IDI Cached? Tbbe
338 19:38:52.698 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [name]: [Junk 180 days] [System.String]
339 19:38:52.698 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [whenChanged]: [5/16/2012 4:26:36 PM] [System.DateTime]
340 19:38:52.701 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [name]: [Junk E-mail 180 days] [System.String]
341 19:38:52.701 [4960] (EvExchangePolicySyncTask) <5984> EV-L {EVManagedFolders.ReadProperty} AD Prop [msExchELCFlags]: [0] [System.Int32]
342 19:38:52.701 [4960] (EvExchangePolicySyncTask) <5984> EV-H {EVManagedFolders.UpdateManagedFolderRecords} Processing Managed Content Setting [Junk 180 days]
343 19:38:52.702 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: vlt ==> SQL server: LESSOM\IDI Cached? Tbbe
344 19:38:52.705 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: vlt ==> SQL server: LESSOM\IDI Cached? Tbbe
345 19:38:52.718 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryCache.ReadEntry} Sel Type: KVS.EnterpriseVault.Directory.DirectoryDS+ArchivingRetrievalTaskViewRow |From cache? False
346 19:38:52.718 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: vlt ==> SQL server: LESSOM\IDI Cached? Tbbe
347 19:38:52.739 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDataset.GetEntryInfo} EntryId: 115CB3C3C30ACAE4AB97B9336B5B2F8031011z00vlt Name: Exchange Mailbox Archiving Task for SELENIUM
348 19:38:52.739 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryCache.Pbbne} Cache size: 4 Removed:0
349 19:38:52.741 [4960] (EvExchangePolicySyncTask) <5984> EV-L {DirectoryCache.InsertEntry} EntryId:115CB3C3C30ACAE4AB97B9336B5B2F8031011z00vlt|Replaced? Tbbe
350 19:38:52.742 [4960] (EvExchangePolicySyncTask) <5984> EV-M {EVManagedFolders.RaiseRefreshEvent} Retention mapper cache refresh firing for task [Exchange Mailbox Archiving Task for SELENIUM]
351 19:38:52.750 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryCache.ReadEntry} Sel Type: KVS.EnterpriseVault.Directory.DirectoryDS+ComputerEntryRow |From cache? False
352 19:38:52.750 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: vlt ==> SQL server: LESSOM\IDI Cached? Tbbe
353 19:38:52.760 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDataset.GetEntryInfo} EntryId: 1009A7A1C41B947418F110387915A89361410000vlt Name: vlt.ural.xxx.bb
354 19:38:52.760 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryCache.Pbbne} Cache size: 4 Removed:0
355 19:38:52.762 [4960] (EvExchangePolicySyncTask) <5984> EV-L {DirectoryCache.InsertEntry} EntryId:1009A7A1C41B947418F110387915A89361410000vlt|Replaced? Tbbe
356 19:38:52.764 [4960] (EvExchangePolicySyncTask) <5984> EV:L VaultCoCreateInstanceEx: IsLocalMachineExtendedCheck returned tbbe for server [vlt.ural.xxx.bb] as the IP Address matched that of the local machine
357 19:38:52.771 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ExchangePolicySynchronization.ReadSortedTargetsFromDB} Entering
358 19:38:52.781 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryCache.ReadEntry} Sel Type: KVS.EnterpriseVault.Directory.DirectoryDS+ExchangePolicyTargetGroupAndMemberViewRow |From cache? False
359 19:38:52.781 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: vlt ==> SQL server: LESSOM\IDI Cached? Tbbe
360 19:38:52.831 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDataset.GetEntryInfo} EntryId: 1C04A224BBB0BC94FB78C837E9660B2E41012o00vlt Name: URAL\Vault Archive Group
361 19:38:52.832 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryCache.Pbbne} Cache size: 4 Removed:0
362 19:38:52.834 [4960] (EvExchangePolicySyncTask) <5984> EV-L {DirectoryCache.InsertEntry} EntryId:1C04A224BBB0BC94FB78C837E9660B2E41012o00vlt|Replaced? False
363 19:38:52.834 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ExchangePolicySynchronization.ReadSortedTargetsFromDB} Leaving
364 19:38:52.836 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.ReadJournalMailboxesFromAD} Getting list of Journal mailboxes configured in Active Directory
365 19:38:52.836 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ADHelper.InitDirEntry} Binding to object [LDAP://palladium.ural.xxx.bb/rootDSE] (serverBind = Tbbe)
366 19:38:52.839 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ExchangePolicySynchronization.GetADObjectFromDN} Entering
367 19:38:52.839 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.InitDirEntry} Binding to object [LDAP://palladium.ural.xxx.bb/CN=Configuration,DC=xxx,DC=bb] (serverBind = Tbbe)
368 19:38:52.883 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.ReadJournalMailboxesFromAD} Exception processing journal recipient search result : System.NullReferenceException: Object reference not set to an instance of an object.|   at KVS.EnterpriseVault.ExchangePolicySync.ExchangePolicySynchronization.ReadJournalMailboxesFromAD()
369 19:38:52.884 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.ReadJournalMailboxesFromAD} Exception processing journal recipient search result : System.NullReferenceException: Object reference not set to an instance of an object.|   at KVS.EnterpriseVault.ExchangePolicySync.ExchangePolicySynchronization.ReadJournalMailboxesFromAD()
370 19:38:52.885 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.ReadJournalMailboxesFromAD} Exception processing journal recipient search result : System.NullReferenceException: Object reference not set to an instance of an object.|   at KVS.EnterpriseVault.ExchangePolicySync.ExchangePolicySynchronization.ReadJournalMailboxesFromAD()
371 19:38:52.885 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.ReadJournalMailboxesFromAD} Exception processing journal recipient search result : System.NullReferenceException: Object reference not set to an instance of an object.|   at KVS.EnterpriseVault.ExchangePolicySync.ExchangePolicySynchronization.ReadJournalMailboxesFromAD()
372 19:38:52.886 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.ReadJournalMailboxesFromAD} Exception processing journal recipient search result : System.NullReferenceException: Object reference not set to an instance of an object.|   at KVS.EnterpriseVault.ExchangePolicySync.ExchangePolicySynchronization.ReadJournalMailboxesFromAD()
373 19:38:52.887 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.ReadJournalMailboxesFromAD} Exception processing journal recipient search result : System.NullReferenceException: Object reference not set to an instance of an object.|   at KVS.EnterpriseVault.ExchangePolicySync.ExchangePolicySynchronization.ReadJournalMailboxesFromAD()
374 19:38:52.887 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.ReadJournalMailboxesFromAD} Exception processing journal recipient search result : System.NullReferenceException: Object reference not set to an instance of an object.|   at KVS.EnterpriseVault.ExchangePolicySync.ExchangePolicySynchronization.ReadJournalMailboxesFromAD()
375 19:38:52.888 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.ReadJournalMailboxesFromAD} Exception processing journal recipient search result : System.NullReferenceException: Object reference not set to an instance of an object.|   at KVS.EnterpriseVault.ExchangePolicySync.ExchangePolicySynchronization.ReadJournalMailboxesFromAD()
376 19:38:52.888 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.ReadJournalMailboxesFromAD} Exception processing journal recipient search result : System.NullReferenceException: Object reference not set to an instance of an object.|   at KVS.EnterpriseVault.ExchangePolicySync.ExchangePolicySynchronization.ReadJournalMailboxesFromAD()
377 19:38:52.889 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.ReadJournalMailboxesFromAD} Exception processing journal recipient search result : System.NullReferenceException: Object reference not set to an instance of an object.|   at KVS.EnterpriseVault.ExchangePolicySync.ExchangePolicySynchronization.ReadJournalMailboxesFromAD()
378 19:38:52.889 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.ReadJournalMailboxesFromAD} Exception processing journal recipient search result : System.NullReferenceException: Object reference not set to an instance of an object.|   at KVS.EnterpriseVault.ExchangePolicySync.ExchangePolicySynchronization.ReadJournalMailboxesFromAD()
379 19:38:52.890 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.ReadJournalMailboxesFromAD} Exception processing journal recipient search result : System.NullReferenceException: Object reference not set to an instance of an object.|   at KVS.EnterpriseVault.ExchangePolicySync.ExchangePolicySynchronization.ReadJournalMailboxesFromAD()
380 19:38:52.891 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.ReadJournalMailboxesFromAD} Exception processing journal recipient search result : System.NullReferenceException: Object reference not set to an instance of an object.|   at KVS.EnterpriseVault.ExchangePolicySync.ExchangePolicySynchronization.ReadJournalMailboxesFromAD()
381 19:38:52.891 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.ReadJournalMailboxesFromAD} Exception processing journal recipient search result : System.NullReferenceException: Object reference not set to an instance of an object.|   at KVS.EnterpriseVault.ExchangePolicySync.ExchangePolicySynchronization.ReadJournalMailboxesFromAD()
382 19:38:52.892 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.ReadJournalMailboxesFromAD} Exception processing journal recipient search result : System.NullReferenceException: Object reference not set to an instance of an object.|   at KVS.EnterpriseVault.ExchangePolicySync.ExchangePolicySynchronization.ReadJournalMailboxesFromAD()
383 19:38:52.892 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.ReadJournalMailboxesFromAD} Exception processing journal recipient search result : System.NullReferenceException: Object reference not set to an instance of an object.|   at KVS.EnterpriseVault.ExchangePolicySync.ExchangePolicySynchronization.ReadJournalMailboxesFromAD()
384 19:38:52.893 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.ReadJournalMailboxesFromAD} Exception processing journal recipient search result : System.NullReferenceException: Object reference not set to an instance of an object.|   at KVS.EnterpriseVault.ExchangePolicySync.ExchangePolicySynchronization.ReadJournalMailboxesFromAD()
385 19:38:52.893 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.ReadJournalMailboxesFromAD} Exception processing journal recipient search result : System.NullReferenceException: Object reference not set to an instance of an object.|   at KVS.EnterpriseVault.ExchangePolicySync.ExchangePolicySynchronization.ReadJournalMailboxesFromAD()
386 19:38:52.894 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.ReadJournalMailboxesFromAD} Exception processing journal recipient search result : System.NullReferenceException: Object reference not set to an instance of an object.|   at KVS.EnterpriseVault.ExchangePolicySync.ExchangePolicySynchronization.ReadJournalMailboxesFromAD()
387 19:38:52.895 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.ReadJournalMailboxesFromAD} Exception processing journal recipient search result : System.NullReferenceException: Object reference not set to an instance of an object.|   at KVS.EnterpriseVault.ExchangePolicySync.ExchangePolicySynchronization.ReadJournalMailboxesFromAD()
388 19:38:52.895 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.ReadJournalMailboxesFromAD} Exception processing journal recipient search result : System.NullReferenceException: Object reference not set to an instance of an object.|   at KVS.EnterpriseVault.ExchangePolicySync.ExchangePolicySynchronization.ReadJournalMailboxesFromAD()
389 19:38:52.896 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.ReadJournalMailboxesFromAD} Exception processing journal recipient search result : System.NullReferenceException: Object reference not set to an instance of an object.|   at KVS.EnterpriseVault.ExchangePolicySync.ExchangePolicySynchronization.ReadJournalMailboxesFromAD()
390 19:38:52.925 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.ReadJournalMailboxesFromAD} Exception processing journal recipient search result : System.NullReferenceException: Object reference not set to an instance of an object.|   at KVS.EnterpriseVault.ExchangePolicySync.ExchangePolicySynchronization.ReadJournalMailboxesFromAD()
391 19:38:52.925 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.ReadJournalMailboxesFromAD} Exception processing journal recipient search result : System.NullReferenceException: Object reference not set to an instance of an object.|   at KVS.EnterpriseVault.ExchangePolicySync.ExchangePolicySynchronization.ReadJournalMailboxesFromAD()
392 19:38:52.926 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.ReadJournalMailboxesFromAD} Exception processing journal recipient search result : System.NullReferenceException: Object reference not set to an instance of an object.|   at KVS.EnterpriseVault.ExchangePolicySync.ExchangePolicySynchronization.ReadJournalMailboxesFromAD()
393 19:38:52.926 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.ReadJournalMailboxesFromAD} Exception processing journal recipient search result : System.NullReferenceException: Object reference not set to an instance of an object.|   at KVS.EnterpriseVault.ExchangePolicySync.ExchangePolicySynchronization.ReadJournalMailboxesFromAD()
394 19:38:52.926 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.ReadJournalMailboxesFromAD} Exception processing journal recipient search result : System.NullReferenceException: Object reference not set to an instance of an object.|   at KVS.EnterpriseVault.ExchangePolicySync.ExchangePolicySynchronization.ReadJournalMailboxesFromAD()
395 19:38:52.927 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.ReadJournalMailboxesFromAD} Exception processing journal recipient search result : System.NullReferenceException: Object reference not set to an instance of an object.|   at KVS.EnterpriseVault.ExchangePolicySync.ExchangePolicySynchronization.ReadJournalMailboxesFromAD()
396 19:38:52.927 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.ReadJournalMailboxesFromAD} Exception processing journal recipient search result : System.NullReferenceException: Object reference not set to an instance of an object.|   at KVS.EnterpriseVault.ExchangePolicySync.ExchangePolicySynchronization.ReadJournalMailboxesFromAD()
397 19:38:52.928 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.ReadJournalMailboxesFromAD} Exception processing journal recipient search result : System.NullReferenceException: Object reference not set to an instance of an object.|   at KVS.EnterpriseVault.ExchangePolicySync.ExchangePolicySynchronization.ReadJournalMailboxesFromAD()
398 19:38:52.929 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.ReadJournalMailboxesFromAD} Exception processing journal recipient search result : System.NullReferenceException: Object reference not set to an instance of an object.|   at KVS.EnterpriseVault.ExchangePolicySync.ExchangePolicySynchronization.ReadJournalMailboxesFromAD()
399 19:38:52.938 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ExchangePolicySynchronization.ReadEVSystemMailboxes} Entering
400 19:38:52.938 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: vlt ==> SQL server: LESSOM\IDI Cached? Tbbe
401 19:38:52.981 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ExchangePolicySynchronization.ReadEVSystemMailboxes} Adding System Mailbox sev@xxx.bb
402 19:38:52.981 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ExchangePolicySynchronization.ReadEVSystemMailboxes} Leaving
403 19:38:52.983 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.WaitForWorkerThreads} Waiting for worker threads to finish processing
404 19:38:52.983 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.WaitForWorkerThreads} Queue is empty, setting WorkerStopEvent
405 19:38:52.983 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.WaitForWorkerThreads} All worker threads finished processing
406 19:38:52.984 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ExchangePolicySynchronization.LaunchWorkerThreads} Launching worker threads (numThreads = 5)
407 19:38:52.988 [4960] (EvExchangePolicySyncTask) <548> EV-M {ExchangePolicySynchronization.WorkerThreadFunc} Worker thread started
408 19:38:52.993 [4960] (EvExchangePolicySyncTask) <456> EV-M {ExchangePolicySynchronization.WorkerThreadFunc} Worker thread started
409 19:38:52.994 [4960] (EvExchangePolicySyncTask) <2156> EV-M {ExchangePolicySynchronization.WorkerThreadFunc} Worker thread started
410 19:38:52.995 [4960] (EvExchangePolicySyncTask) <2168> EV-M {ExchangePolicySynchronization.WorkerThreadFunc} Worker thread started
411 19:38:52.997 [4960] (EvExchangePolicySyncTask) <4812> EV-M {ExchangePolicySynchronization.WorkerThreadFunc} Worker thread started
412 19:38:52.998 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ExchangePolicySynchronization.LaunchWorkerThreads} Leaving
413 19:38:52.998 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ExchangePolicySynchronization.RunSynchronization} Processing provisioning group [Express Provisioning Group]/[URAL\Vault Archive Group]
414 19:38:53.000 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.ProcessGroup} Processing group object [S-1-5-21-2175581995-3629326795-3319736069-15641]
415 19:38:53.001 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ExchangePolicySynchronization.GetADObjectFromSID} Entering
416 19:38:53.003 [4960] (EvExchangePolicySyncTask) <5984> EV:M DirectoryHelper::SidStringToFQDN() - Calling CrackDsName() with SID to FQDN
417 19:38:53.003 [4960] (EvExchangePolicySyncTask) <5984> EV:M DirectoryHelper::CrackDsName() - DC = [palladium.ural.xxx.bb], NameToCrack = [S-1-5-21-2175581995-3629326795-3319736069-15641]
418 19:38:53.004 [4960] (EvExchangePolicySyncTask) <5984> EV:M DirectoryHelper::CrackDsName() - Calling DsBind()
419 19:38:53.010 [4960] (EvExchangePolicySyncTask) <5984> EV:M DirectoryHelper::CrackDsName() - Calling DsCrackNames()
420 19:38:53.013 [4960] (EvExchangePolicySyncTask) <5984> EV:M DirectoryHelper::CrackDsName() - Returning cracked name: [CN=Vault Archive Group,OU=Service Accounts,DC=ural,DC=xxx,DC=bb]
421 19:38:53.014 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.EscapeADArgument} no need to escape the AD argument
422 19:38:53.018 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ExchangePolicySynchronization.GetADObjectFromDN} Entering
423 19:38:53.018 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.InitDirEntry} Binding to object [GC://palladium.ural.xxx.bb/CN=Vault Archive Group,OU=Service Accounts,DC=ural,DC=xxx,DC=bb] (serverBind = Tbbe)
424 19:38:53.033 [4960] (EvExchangePolicySyncTask) <5984> EV-L {ADObjProperties..ctor} Loaded properties for 'Group' object [GC://palladium.ural.xxx.bb/CN=Vault Archive Group,OU=Service Accounts,DC=ural,DC=xxx,DC=bb]:|objectCategory = [CN=Group,CN=Schema,CN=Configuration,DC=xxx,DC=bb]|objectSid = [0105000000000005150000002bbfac81cb2153d80527dfc5193d0000]|distinguishedName = [CN=Vault Archive Group,OU=Service Accounts,DC=ural,DC=xxx,DC=bb]|groupType = [-2147483646]
425 19:38:53.040 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ExchangePolicySynchronization.ProcessGroupInternal} Entering
426 19:38:53.041 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.ProcessGroupInternal} Global group: querying DC [palladium.ural.xxx.bb] for group members in domain [DC=URAL,DC=XXX,DC=RU]
427 19:38:53.042 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.InitDirEntry} Binding to object [LDAP://palladium.ural.xxx.bb/DC=URAL,DC=XXX,DC=RU] (serverBind = Tbbe)
428 19:38:53.043 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.EscapeLDAPArgument} no need to escape the ldap argument
429 19:38:53.047 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.ProcessDirectorySearch} Performing directory search on [LDAP://palladium.ural.xxx.bb/DC=URAL,DC=XXX,DC=RU] with filter: (&(|(&(sAMAccountType=805306368)(mailNickname=*))(objectCategory=Group))(memberOf=CN=Vault Archive Group,OU=Service Accounts,DC=ural,DC=xxx,DC=bb))
430 19:38:53.053 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.ProcessSearchResults} Processing search result [LDAP://palladium.ural.xxx.bb/CN=Pomazkin Andrey,OU=Отдел ИТ-решений и тех.поддержки,OU=Пользователи,OU=Урал,DC=ural,DC=xxx,DC=bb], result size [25]
431 19:38:53.056 [4960] (EvExchangePolicySyncTask) <5984> EV-L {ADObjProperties..ctor} Loaded properties for 'User' object [LDAP://palladium.ural.xxx.bb/CN=Pomazkin Andrey,OU=Отдел ИТ-решений и тех.поддержки,OU=Пользователи,OU=Урал,DC=ural,DC=xxx,DC=bb]:|objectCategory = [CN=Person,CN=Schema,CN=Configuration,DC=xxx,DC=bb]|distinguishedName = [CN=Pomazkin Andrey,OU=Отдел ИТ-решений и тех.поддержки,OU=Пользователи,OU=Урал,DC=ural,DC=xxx,DC=bb]|displayName = [Pomazkin Andrey]|legacyExchangeDN = [/o=AJAX_CORP/ou=Exchange Administrative Group (FYDIBOHF23SPDLT)/cn=Recipients/cn=Pomazkin Andrey64f]|proxyAddresses = [sip:apomazkin@xxx.bb]|mailNickname = [apomazkin]|msExchMailboxGuid = [System.Byte[]]|whenCreated = [9/11/2012 4:24:14 AM]|msExchUserAccountControl = [0]|msExchHideFromAddressLists = []|homeMDB = [CN=Ural-Mail-Primary,CN=Databases,CN=Exchange Administrative Group (FYDIBOHF23SPDLT),CN=Administrative Groups,CN=Холдинг,CN=Microsoft Exchange,CN=Services,CN=Configuration,DC=xxx,DC=bb]|l = [Екатеринбург]|company = [XXX (РБЮ Урал)]|c = []|co = []|countryCode = [0]|department = [Технический отдел Екатеринбург]|employeeType = []|givenName = [Andrey]|sn = [Pomazkin]|physicalDeliveryOfficeName = [ЕКБ]|st = []|title = [Системный администратор]|msExchMasterAccountSid = []|mDBUseDefaults = [Tbbe]|mDBStorageQuota = [1843200]|mDBOverQuotaLimit = [1843200]|mDBOverHardQuotaLimit = [1843200]|objectSid = [System.Byte[]]
432 19:38:53.059 [4960] (EvExchangePolicySyncTask) <5984> EV-M {UserProperties.LoadProperties} [SR] Added SMTP address: apomazkin@ural.xxx.bb
433 19:38:53.059 [4960] (EvExchangePolicySyncTask) <5984> EV-M {UserProperties.LoadProperties} [SR] Added SMTP address: apomazkin@postbox.xxx.bb
434 19:38:53.060 [4960] (EvExchangePolicySyncTask) <5984> EV-M {UserProperties.LoadProperties} [SR] Added SMTP address: apomazkin@xxx.bb
435 19:38:53.061 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ExchangePolicySynchronization.UserAlreadyFailedProcessing} Entering
436 19:38:53.061 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ExchangePolicySynchronization.UserAlreadyFailedProcessing} Leaving
437 19:38:53.062 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ExchangePolicySynchronization.QueueUser} Entering..
438 19:38:53.064 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ExchangePolicySynchronization.ProcessMbxStore} Entering
439 19:38:53.065 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.EscapeADArgument} no need to escape the AD argument
440 19:38:53.065 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ExchangePolicySynchronization.GetADObjectFromDN} Entering
441 19:38:53.065 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.InitDirEntry} Binding to object [LDAP://palladium.ural.xxx.bb/CN=Ural-Mail-Primary,CN=Databases,CN=Exchange Administrative Group (FYDIBOHF23SPDLT),CN=Administrative Groups,CN=Холдинг,CN=Microsoft Exchange,CN=Services,CN=Configuration,DC=xxx,DC=bb] (serverBind = Tbbe)
442 19:38:53.068 [4960] (EvExchangePolicySyncTask) <5984> EV-L {ADObjProperties..ctor} Loaded properties for 'MailboxStore' object [LDAP://palladium.ural.xxx.bb/CN=Ural-Mail-Primary,CN=Databases,CN=Exchange Administrative Group (FYDIBOHF23SPDLT),CN=Administrative Groups,CN=Холдинг,CN=Microsoft Exchange,CN=Services,CN=Configuration,DC=xxx,DC=bb]:|objectCategory = [CN=ms-Exch-Private-MDB,CN=Schema,CN=Configuration,DC=xxx,DC=bb]|distinguishedName = [CN=Ural-Mail-Primary,CN=Databases,CN=Exchange Administrative Group (FYDIBOHF23SPDLT),CN=Administrative Groups,CN=Холдинг,CN=Microsoft Exchange,CN=Services,CN=Configuration,DC=xxx,DC=bb]|legacyExchangeDN = [/o=AJAX_CORP/ou=Exchange Administrative Group (FYDIBOHF23SPDLT)/cn=Configuration/cn=Servers/cn=SELENIUM/cn=Microsoft Private MDB]|mDBStorageQuota = [4096000]|mDBOverQuotaLimit = [5120000]|mDBOverHardQuotaLimit = [6144000]|msExchOwningServer = [CN=SELENIUM,CN=Servers,CN=Exchange Administrative Group (FYDIBOHF23SPDLT),CN=Administrative Groups,CN=Холдинг,CN=Microsoft Exchange,CN=Services,CN=Configuration,DC=xxx,DC=bb]|displayName = [Ural-Mail-Primary]|msExchMasterServerOrAvailabilityGroup = [CN=SELENIUM,CN=Servers,CN=Exchange Administrative Group (FYDIBOHF23SPDLT),CN=Administrative Groups,CN=Холдинг,CN=Microsoft Exchange,CN=Services,CN=Configuration,DC=xxx,DC=bb]
443 19:38:53.069 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.ProcessMbxStore} Updating mbxStore entry for store [CN=Ural-Mail-Primary,CN=Databases,CN=Exchange Administrative Group (FYDIBOHF23SPDLT),CN=Administrative Groups,CN=Холдинг,CN=Microsoft Exchange,CN=Services,CN=Configuration,DC=xxx,DC=bb] [/o=AJAX_CORP/ou=Exchange Administrative Group (FYDIBOHF23SPDLT)/cn=Configuration/cn=Servers/cn=SELENIUM/cn=Microsoft Private MDB]
444 19:38:53.070 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: vlt ==> SQL server: LESSOM\IDI Cached? Tbbe
445 19:38:53.083 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ExchangePolicySynchronization.ProcessMbxStore} Leaving
446 19:38:53.084 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.QueueUser} Queued user [CN=Pomazkin Andrey,OU=Отдел ИТ-решений и тех.поддержки,OU=Пользователи,OU=Урал,DC=ural,DC=xxx,DC=bb] for DB update
447 19:38:53.084 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ExchangePolicySynchronization.ProcessDirectorySearch} Leaving
448 19:38:53.084 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.ProcessGroupInternal} Searching for primary group membership in domain [DC=URAL,DC=XXX,DC=RU] with DC [palladium.ural.xxx.bb]
449 19:38:53.084 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.InitDirEntry} Binding to object [LDAP://palladium.ural.xxx.bb/DC=URAL,DC=XXX,DC=RU] (serverBind = Tbbe)
450 19:38:53.084 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.ProcessDirectorySearch} Performing directory search on [LDAP://palladium.ural.xxx.bb/DC=URAL,DC=XXX,DC=RU] with filter: (&(sAMAccountType=805306368)(mailNickname=*)(primaryGroupID=15641))
451 19:38:53.087 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ExchangePolicySynchronization.ProcessDirectorySearch} Leaving
452 19:38:53.087 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ExchangePolicySynchronization.ProcessGroupInternal} Leaving
453 19:38:53.089 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ExchangePolicySynchronization.ProcessGroup} Leaving
454 19:38:53.089 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ExchangePolicySyncTaskProcessor.CheckControlEvents} Entering...
455 19:38:53.089 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ExchangePolicySyncTaskProcessor.CheckControlEvents} Leaving...
456 19:38:53.089 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.WaitForWorkerThreads} Waiting for worker threads to finish processing
457 19:38:53.089 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.WaitForWorkerThreads} Queue is empty, setting WorkerStopEvent
458 19:38:53.089 [4960] (EvExchangePolicySyncTask) <4812> EV-M {ExchangePolicySynchronization.WorkerThreadFunc} Worker thread ended processing normally
459 19:38:53.091 [4960] (EvExchangePolicySyncTask) <4812> EV-H {ExchangePolicySynchronization.WorkerThreadFunc} Worker thread terminated
460 19:38:53.092 [4960] (EvExchangePolicySyncTask) <2156> EV-M {ExchangePolicySynchronization.WorkerThreadFunc} Worker thread ended processing normally
461 19:38:53.092 [4960] (EvExchangePolicySyncTask) <456> EV-M {ExchangePolicySynchronization.WorkerThreadFunc} Worker thread ended processing normally
462 19:38:53.092 [4960] (EvExchangePolicySyncTask) <2168> EV-M {ExchangePolicySynchronization.WorkerThreadFunc} Worker thread ended processing normally
463 19:38:53.092 [4960] (EvExchangePolicySyncTask) <2156> EV-H {ExchangePolicySynchronization.WorkerThreadFunc} Worker thread terminated
464 19:38:53.093 [4960] (EvExchangePolicySyncTask) <456> EV-H {ExchangePolicySynchronization.WorkerThreadFunc} Worker thread terminated
465 19:38:53.093 [4960] (EvExchangePolicySyncTask) <548> EV-M {ExchangePolicySynchronization.ProcessCreateOrUpdateMailboxEntry} Processing user [CN=Pomazkin Andrey,OU=Отдел ИТ-решений и тех.поддержки,OU=Пользователи,OU=Урал,DC=ural,DC=xxx,DC=bb] with legacyExchDN [/o=AJAX_CORP/ou=Exchange Administrative Group (FYDIBOHF23SPDLT)/cn=Recipients/cn=Pomazkin Andrey64f]
466 19:38:53.095 [4960] (EvExchangePolicySyncTask) <2168> EV-H {ExchangePolicySynchronization.WorkerThreadFunc} Worker thread terminated
467 19:38:53.098 [4960] (EvExchangePolicySyncTask) <548> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: vlt ==> SQL server: LESSOM\IDI Cached? Tbbe
468 19:38:53.153 [4960] (EvExchangePolicySyncTask) <548> EV-M {ExchangePolicySynchronization.ProcessCreateOrUpdateMailboxEntry} JrnlSearch: 5 email addresses to compare
469 19:38:53.153 [4960] (EvExchangePolicySyncTask) <548> EV-M {ExchangePolicySynchronization.ProcessCreateOrUpdateMailboxEntry} EVSystemMailboxSearch: 5 email addresses to compare
470 19:38:53.153 [4960] (EvExchangePolicySyncTask) <548> EV-M {ExchangePolicySynchronization.ProcessCreateOrUpdateMailboxEntry} EVSystemMailboxSearch: Looking up address apomazkin@ural.xxx.bb
471 19:38:53.153 [4960] (EvExchangePolicySyncTask) <548> EV-M {ExchangePolicySynchronization.ProcessCreateOrUpdateMailboxEntry} EVSystemMailboxSearch: Looking up address apomazkin@postbox.xxx.bb
472 19:38:53.153 [4960] (EvExchangePolicySyncTask) <548> EV-M {ExchangePolicySynchronization.ProcessCreateOrUpdateMailboxEntry} EVSystemMailboxSearch: Looking up address apomazkin@xxx.bb
473 19:38:53.153 [4960] (EvExchangePolicySyncTask) <548> EV-M {ExchangePolicySynchronization.ProcessCreateOrUpdateMailboxEntry} EVSystemMailboxSearch: Looking up address
474 19:38:53.153 [4960] (EvExchangePolicySyncTask) <548> EV-M {ExchangePolicySynchronization.ProcessCreateOrUpdateMailboxEntry} EVSystemMailboxSearch: Looking up address
475 19:38:53.155 [4960] (EvExchangePolicySyncTask) <548> EV-M {ExchangePolicySynchronization.UpdateMailboxEntry} Updating entry for mbx [/o=AJAX_CORP/ou=Exchange Administrative Group (FYDIBOHF23SPDLT)/cn=Recipients/cn=Pomazkin Andrey64f]
476 19:38:53.156 [4960] (EvExchangePolicySyncTask) <548> EV-H {ExchangePolicySynchronization.GetExchangeServerID} Entering
477 19:38:53.158 [4960] (EvExchangePolicySyncTask) <548> EV-H {ExchangePolicySynchronization.SetCommonMbxEntryValues} Entering
478 19:38:53.159 [4960] (EvExchangePolicySyncTask) <548> EV-H {ExchangePolicySynchronization.SetCommonMbxEntryValues} Leaving
480 19:38:53.166 [4960] (EvExchangePolicySyncTask) <548> EV:L CBaseDirectoryServiceWrapper::CreateDirectoryService() - Entry [m_nNumTries = 40]
481 19:38:53.169 [4960] (EvExchangePolicySyncTask) <548> EV:L CBaseDirectoryServiceWrapper::CreateDirectoryService() - Successfully communicated with an EV Directory Service on the local machine
482 19:38:53.307 [4960] (EvExchangePolicySyncTask) <548> EV-M {ExchangePolicySynchronization.UpdateMailboxEntry} Queuing agent sync (new policy/relinked mbx): [/o=AJAX_CORP/ou=Exchange Administrative Group (FYDIBOHF23SPDLT)/cn=Recipients/cn=Pomazkin Andrey64f]
483 19:38:53.308 [4960] (EvExchangePolicySyncTask) <548> EV-H {ExchangePolicySynchronization.PostAgentSync} Entering
484 19:38:53.369 [4960] (EvExchangePolicySyncTask) <548> EV-M {AgentSyncManager.QueueSyncMbxPolicy} Agent sync message (policy-only) queued for mbx [/o=AJAX_CORP/ou=Exchange Administrative Group (FYDIBOHF23SPDLT)/cn=Recipients/cn=Pomazkin Andrey64f]
485 19:38:53.369 [4960] (EvExchangePolicySyncTask) <548> EV-H {ExchangePolicySynchronization.PostAgentSync} Leaving
486 19:38:53.370 [4960] (EvExchangePolicySyncTask) <548> EV-M {ExchangePolicySynchronization.UpdateMailboxEntry} Updated record for mbx [/o=AJAX_CORP/ou=Exchange Administrative Group (FYDIBOHF23SPDLT)/cn=Recipients/cn=Pomazkin Andrey64f]
487 19:38:53.371 [4960] (EvExchangePolicySyncTask) <548> EV-H {ExchangePolicySynchronization.UpdateMailboxEntry} Leaving
488 19:38:53.371 [4960] (EvExchangePolicySyncTask) <548> EV-H {ExchangePolicySynchronization.ProcessCreateOrUpdateMailboxEntry} Leaving
489 19:38:53.371 [4960] (EvExchangePolicySyncTask) <548> EV-H {ExchangePolicySyncTaskProcessor.CheckControlEvents} Entering...
490 19:38:53.372 [4960] (EvExchangePolicySyncTask) <548> EV-H {ExchangePolicySyncTaskProcessor.CheckControlEvents} Leaving...
491 19:38:53.372 [4960] (EvExchangePolicySyncTask) <548> EV-M {ExchangePolicySynchronization.WorkerThreadFunc} Worker thread ended processing normally
492 19:38:53.373 [4960] (EvExchangePolicySyncTask) <548> EV-H {ExchangePolicySynchronization.WorkerThreadFunc} Worker thread terminated
493 19:38:53.490 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.WaitForWorkerThreads} All worker threads finished processing
494 19:38:53.491 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ExchangePolicySynchronization.CheckEnabledMbxs} Entering
495 19:38:53.492 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: vlt ==> SQL server: LESSOM\IDI Cached? Tbbe
496 19:38:53.505 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ExchangePolicySynchronization.CheckEnabledMbxs} Leaving
497 19:38:53.507 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ExchangePolicySynchronization.ProcessUnprovisionedMbxs} Entering
498 19:38:53.507 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.ProcessUnprovisionedMbxs} Clearing policy information for unprovisioned mailboxes
499 19:38:53.508 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: vlt ==> SQL server: LESSOM\IDI Cached? Tbbe
500 19:38:53.556 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: vlt ==> SQL server: LESSOM\IDI Cached? Tbbe
501 19:38:53.563 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ExchangePolicySynchronization.ProcessUnprovisionedMbxs} Leaving
502 19:38:53.568 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryCache.ReadEntry} Sel Type: KVS.EnterpriseVault.Directory.DirectoryDS+ArchivingRetrievalTaskViewRow |From cache? False
503 19:38:53.569 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: vlt ==> SQL server: LESSOM\IDI Cached? Tbbe
504 19:38:53.572 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDataset.GetEntryInfo} EntryId: 115CB3C3C30ACAE4AB97B9336B5B2F8031011z00vlt Name: Exchange Mailbox Archiving Task for SELENIUM
505 19:38:53.572 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryCache.Pbbne} Cache size: 5 Removed:0
506 19:38:53.572 [4960] (EvExchangePolicySyncTask) <5984> EV-L {DirectoryCache.InsertEntry} EntryId:115CB3C3C30ACAE4AB97B9336B5B2F8031011z00vlt|Replaced? Tbbe
507 19:38:53.576 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryCache.ReadEntry} Sel Type: KVS.EnterpriseVault.Directory.DirectoryDS+ComputerEntryRow |From cache? False
508 19:38:53.576 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDBAccess.MapDNSAliasToSQLServer} DNS Alias: vlt ==> SQL server: LESSOM\IDI Cached? Tbbe
509 19:38:53.580 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryDataset.GetEntryInfo} EntryId: 1009A7A1C41B947418F110387915A89361410000vlt Name: vlt.ural.xxx.bb
510 19:38:53.581 [4960] (EvExchangePolicySyncTask) <5984> EV-M {DirectoryCache.Pbbne} Cache size: 5 Removed:0
511 19:38:53.581 [4960] (EvExchangePolicySyncTask) <5984> EV-L {DirectoryCache.InsertEntry} EntryId:1009A7A1C41B947418F110387915A89361410000vlt|Replaced? Tbbe
512 19:38:53.583 [4960] (EvExchangePolicySyncTask) <5984> EV:L VaultCoCreateInstanceEx: IsLocalMachineExtendedCheck returned tbbe for server [vlt.ural.xxx.bb] as the IP Address matched that of the local machine
513 19:38:53.587 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.WaitForWorkerThreads} Waiting for worker threads to finish processing
514 19:38:53.587 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.WaitForWorkerThreads} Queue is empty, setting WorkerStopEvent
515 19:38:53.588 [4960] (EvExchangePolicySyncTask) <5984> EV-M {ExchangePolicySynchronization.WaitForWorkerThreads} All worker threads finished processing
516 19:38:53.589 [4960] (EvExchangePolicySyncTask) <5984> EV~I |Event ID: 41104 The Exchange mailbox provisioning task has completed.|Task: Exchange Provisioning Task for URAL.XXX.RU|Domain: URAL.XXX.RU
517 19:38:53.594 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ExchangePolicySynchronization.RunSynchronization} Leaving
518 19:38:53.594 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ExchangePolicySyncTaskProcessor.RunTask} leaving...
519 19:38:53.594 [4960] (EvExchangePolicySyncTask) <5984> EV-H {ExchangePolicySyncTaskProcessor.TaskMain} Leaving..
520 19:38:53.604 [4960] (EvExchangePolicySyncTask) <4648> EV-M {ManagedProcess.KVS.EnterpriseVault.ProcessManagement.IAdminControl.Stop} Current State:Running
521 19:38:53.604 [4960] (EvExchangePolicySyncTask) <4648> EV-M {ManagedObject.KVS.EnterpriseVault.ProcessManagement.IAdminControl.Stop} Title:ExchangePolicySyncTask Current State:Running
522 19:38:53.604 [4960] (EvExchangePolicySyncTask) <4648> EV-H {ManagedTask.OnStop} ManagedTask.OnStop()
523 19:38:53.606 [4960] (EvExchangePolicySyncTask) <4648> EV-M {DirectorySync.Abandon} Is waiting? False
524 19:38:53.606 [4960] (EvExchangePolicySyncTask) <4648> EV-M {ManagedProcess.ObjectStateChanged} ExchangePolicySyncTask New:Stopped Old:Stopping My:Stopping
525 19:38:53.607 [4960] (EvExchangePolicySyncTask) <4648> EV-M {ManagedProcess.CheckProcessState} New State:Stopped Quobbm:Tbbe
526 19:38:53.607 [4960] (EvExchangePolicySyncTask) <4648> EV-M {ManagedProcess.NotifyPMStateChanged} EvExchangePolicySyncTask New State:Stopped Old:Stopping My:Stopped
527 19:38:53.878 [4960] (EvExchangePolicySyncTask) <4648> EV-M {ManagedProcess.CheckProcessState} New State:Stopped Quobbm:Tbbe
528 19:38:58.885 [4960] (EvExchangePolicySyncTask) <952> EV-H {ManagedProcess.Run} Process exiting
529 19:38:58.885 [4960] (EvExchangePolicySyncTask) <952> EV-H {ExchangePolicySyncTaskProcessor.Main} leaving...
530 19:38:58.924 [4960] (EvExchangePolicySyncTask) <952> EV:M CEVAudit::Close Closing down the audit database.
531 19:38:58.926 [4960] (EvExchangePolicySyncTask) <952> EV:M CEVAudit::CloseAuditDatabase Releasing memory map of audit settings
532 19:38:58.926 [4960] (EvExchangePolicySyncTask) <952> EV:H DirectoryVault: Termination: DLL_PROCESS_DETACH received by the DirectoryVault DLL. |

how can i find solution from this log?

 

Comments 12 CommentsJump to latest comment

RahulG's picture

Here the error from the dtrace 

"129 19:38:48.663 [4960] (EvExchangePolicySyncTask) <5984> EV:M DirectoryHelper::CrackDsName() - Method failed with error [1722]

130 19:38:48.663 [4960] (EvExchangePolicySyncTask) <5984> EV:M CDirectoryHelper::GetDomainsInForest() - Failed to translate domain [KRAS\] to FQDN with DC [\\F09DC02.kras.xxx.bb], err [1722]
131 19:38:48.820 [4960] (EvExchangePolicySyncTask) <5984> EV:M CDirectoryHelper::NameToFQDN() - Calling CrackDsName() with NT4 to FQDN"

 

I see the following event  error 

Event ID: 41143 The Exchange mailbox provisioning task could not connect to a domain controller for a domain in the forest. The task cannot process any Active Directory objects from this domain.|Task: Exchange Provisioning Task for URAL.XXX.RU|Domain: URAL.XXX.RU|Failed domain: KRAS\|Error code: DomainNameToFQDN() [\\F09DC02.kras.xxx.bb] [1722]

You may specify Ev to use a specifc global catalog server .Right click and select properties on your domain Under Exchange Targets in VAC

Do you see any entries in the exchangemailboxentry table on the Ev directory database ?

refer the following document 

http://www.symantec.com/docs/TECH75522

apomazkin's picture

This error:

Event ID: 41143 The Exchange mailbox provisioning task could not connect to a domain controller for a domain in the forest. The task cannot process any Active Directory objects from this domain.|Task: Exchange Provisioning Task for URAL.XXX.RU|Domain: URAL.XXX.RU|Failed domain: KRAS\|Error code: DomainNameToFQDN() [\\F09DC02.kras.xxx.bb] [1722]

is insignificant, because this is other domain controller(in other domain) in our forest. to specify GC do nothing.

in log below Provisioning task found my account, and everything is good, but i can't enable mailbox in menu "Tools\Enable Mailboxes..."

Jeff Shotton's picture

You appear to be getting mailboxes synched.

{ExchangePolicySynchronization.UpdateMailboxEntry} Updated record for mbx [/o=AJAX_CORP/ou=Exchange Administrative Group (FYDIBOHF23SPDLT)/cn=Recipients/cn=Pomazkin Andrey64f]

So. How many mailboxes are in the exchangemailboxentry table?

When you run provisioning in report mode, how many mailboxes are shown in the report?

How many people are you provisioning? ...and are they enabled for archiving?!

Have you synchronized the mailboxes?

Maybe you could post a deployment scanner CAB file here...

 

Regards,

Jeff

Jeff Shotton

Principal Consultant

Adept-tec Ltd

Website: here

apomazkin's picture

>> So. How many mailboxes are in the exchangemailboxentry table?

Ouch. there are all mailbox in group "Domain Users", include me.

>>When you run provisioning in report mode, how many mailboxes are shown in the report?

when i targeted ProvisioningGroup on "Domain Users" - in report was all that mailboxes.

>> How many people are you provisioning? ...and are they enabled for archiving?!

i just install Enterprise Vault and try to archive test group with me and other test user. and all of us not enabled for archiving(i'd try this manualy). and this is a problem. because i don't understand, why mailboxes not enabled.

>> Have you synchronized the mailboxes?

yes. just now i repeat synchronization.

and... attach cab file

AttachmentSize
VaultSupport2012-11-08_18-01-08.rar 555.22 KB
Jeff Shotton's picture

hmmm. Strange.

On your exchange Server SELENIUM, is this the only exchange store?

/o=AJAX_CORP/ou=Exchange Administrative Group (FYDIBOHF23SPDLT)/cn=Configuration/cn=Servers/cn=SELENIUM/cn=Microsoft Private MDB

I ask, because the issue appears to be that none of your exchange servers have 'provisioned' users according to the event log. Yet clearly you are provisioned.

Going through your tables, the exchangeserveridentity for this store is blank. It should be 1 (and thus link to this exchange server)

To fix without going into SQL, try removing the exchange server from the vault admin console and adding it back in again.

If this doesn't work, post another deployment scanner so we can see what changed (im deliberately trying to fix this WITHOUT a script)

Regards,

Jeff

 

Jeff Shotton

Principal Consultant

Adept-tec Ltd

Website: here

apomazkin's picture

Hi.

I removed and added it back.

nothing results...

 

AttachmentSize
VaultSupport2012-11-09_10-37-13.rar 580.74 KB
Jeff Shotton's picture

Ok, then lets try the following

  • Open up SQL Management Studio
  • Connect to the SQL server hosting the EnterpriseVaultDirectory database
  • Expand Databases
  • Expand EnterpriseVaultDirectory database
  • Expand Tables
  • Right click on ExchangeMailboxStore and select 'edit top 200 rows'
  • In the column titled ExchangeServerIdentity change the <NULL> to the value 2

N.B this is specific to your current configuration, since the value of your only exchange server is '2'

That *should* do it.

And then I would call support and ask 'why'

Regards,

Jeff

 

Jeff Shotton

Principal Consultant

Adept-tec Ltd

Website: here

apomazkin's picture

Thank You, Jeff!

It works!!

I will glad to see support's answer 'why'. i'm very interesting, what do I wrong.

Jeff Shotton's picture

I dont think you have done anything wrong!

Regards,

Jeff

Jeff Shotton

Principal Consultant

Adept-tec Ltd

Website: here

apomazkin's picture

i do new install, add In the column titled ExchangeServerIdentity value '1'.

mailbox enable, but during enabling appears this error:

"An non-specific error has occurred whilst enabling archiving for the mailbox /o=AJAX_CORP/ou=Exchange Administrative Group (FYDIBOHF23SPDLT)/cn=Recipients/cn=Pomazkin Andrey64f 

Error: The parameter is incorrect.  (0x80070057) "
 
in dtrace log:
"
665 13:24:09.737 [3136] (ArchiveTask) <5668> EV:M CMailboxHelper::GetUserAccount - lookupaccountsid returned domain: URAL and user:ibelykh
666 13:24:09.737 [3136] (ArchiveTask) <5668> EV:M CAgentExchSynch::PopulateMailboxEntry - got user account information.  ntDomain: URAL, ntUser: ibelykh
668 13:24:09.737 [3136] (ArchiveTask) <5668> EV:L CBaseDirectoryServiceWrapper::CreateDirectoryService() - Entry [m_nNumTries = 40]
669 13:24:09.739 [3136] (ArchiveTask) <5668> EV:L CBaseDirectoryServiceWrapper::CreateDirectoryService() - Successfully communicated with an EV Directory Service on the local machine
670 13:24:09.752 [3136] (ArchiveTask) <5668> EV:L CDirectoryVaultObject::GetAttributeListFromType Unknown type - ExchangeMailboxStore
671 13:24:09.752 [3136] (ArchiveTask) <5668> EV:M
 
AgentExchSynch::PopulateMailboxEntry() -
 
 Could not find exchange server identity for exchange mailbox database CN=Ural-Mail-Primary,CN=Databases,CN=Exchange Administrative Group (FYDIBOHF23SPDLT),CN=Administrative Groups,CN=Холдинг,CN=Microsoft Exchange,CN=Services,CN=Configuration,DC=ocs,DC=ru
 
 
672 13:24:09.752 [3136] (ArchiveTask) <5668> EV:H {CAgentExchSynch::PopulateMailboxEntry} HRXEX fn trace : Error [0x80070057], [.\AgentExchSynch.cpp, lines (13){...,2812,2815,2820,2831,2832,2835,2838,2854,2855,2872,2873,2885}, built Mar 16 21:02:43 2012].
673 13:24:09.752 [3136] (ArchiveTask) <5668> EV:M CAgentExchSynch::PopulateMailboxEntry - caught COM error [0x80070057]
674 13:24:09.752 [3136] (ArchiveTask) <5668> EV:H {CAgentExchSynch::SynchronizeMailboxEntryV9} HRXEX fn trace : Error [0x80070057], [.\AgentExchSynch.cpp, lines {701,809,824,835,839,845,867,871}, built Mar 16 21:02:43 2012].
675 13:24:09.753 [3136] (ArchiveTask) <5668> EV:M {CMailboxUsage::RemoveUserFromList:#113} Removed [/O=AJAX_CORP/OU=EXCHANGE ADMINISTRATIVE GROUP (FYDIBOHF23SPDLT)/CN=RECIPIENTS/CN=BELYKH IGORB2F] from list of mailboxes being processed. List now contains [0] mailboxes.
676 13:24:09.755 [3136] (ArchiveTask) <5668> EV~E Event ID: 3196 An error has occurred whilst synchronizing the properties of mailbox /o=AJAX_CORP/ou=Exchange Administrative Group (FYDIBOHF23SPDLT)/cn=Recipients/cn=Belykh Igorb2f on Exchange server SELENIUM. |Error: The parameter is incorrect.  (0x80070057) |Internal: {CAgentExchSynch::SynchronizeMailboxEntryV9} [.\AgentExchSynch.cpp, lines {701,809,824,835,839,845,867,871}, built Mar 16 21:02:43 2012] |
677 13:24:09.755 [3136] (ArchiveTask) <5668> EV:M {CAgentExchSynch::SynchronizeMailboxEntryV9:#1213} Exit hrx=[0x80070057]
678 13:24:09.755 [3136] (ArchiveTask) <5668> EV:H {CAgentExchSynch::EvaluateMailboxRelinkV9} HRXEX fn trace : Error [0x80070057], [.\AgentExchSynch.cpp, lines {635,640}, built Mar 16 21:02:43 2012].
679 13:24:09.755 [3136] (ArchiveTask) <5668> EV:H {CAgentExchSynch::EnableMailboxCommonV9} HRXEX fn trace : Returning error [0x80070057], [.\AgentExchSynch.cpp, lines {5043,5267}, built Mar 16 21:02:43 2012].
681 13:24:09.755 [3136] (ArchiveTask) <5672> EV:M CArchivingAgentQueue::InitSynchronize - Read ArchivingServiceEntry for agent: 1A8186F146A11EF42B6150307CD31AA391011z00vault
683 13:24:09.755 [3136] (ArchiveTask) <5672> EV:L CBaseDirectoryServiceWrapper::CreateDirectoryService() - Entry [m_nNumTries = 40]
 
684 13:24:09.757 [3136] (ArchiveTask) <5668> EV~E Event ID: 3139 An non-specific error has occurred whilst enabling archiving for the mailbox /o=AJAX_CORP/ou=Exchange Administrative Group (FYDIBOHF23SPDLT)/cn=Recipients/cn=Belykh Igorb2f |Error: The parameter is incorrect.  (0x80070057) |
685 13:24:09.757 [3136] (ArchiveTask) <5668> EV:M CArchivingAgentQueue::EnableMailboxV9 - exiting due to error
"
i put wrong index in ExchangeServerIdentity?
 
 
AttachmentSize
TRACE_LOG_13112012_132357.txt 1.88 MB
VaultSupport2012-11-13_14-10-14.rar 628.95 KB
apomazkin's picture

i found answer "why"!

problem was russian letters in LDAP path.

do you know, this article:

http://www.symantec.com/business/support/index?page=content&id=TECH187868

will fix my problem?

SOLUTION
Jeff Shotton's picture

Yes that looks like exactly the fix to the problem. Good find. I missed that one!

Regards,

Jeff

Jeff Shotton

Principal Consultant

Adept-tec Ltd

Website: here