Video Screencast Help
Symantec to Separate Into Two Focused, Industry-Leading Technology Companies. Learn more.

EV user cannot see all Cached emails

Created: 30 Jul 2012 • Updated: 08 Dec 2012 | 14 comments
TRP's picture
This issue has been solved. See solution.

Hi all :)

I have a user who runs a laptop and has EV vault cache enabled.

He can do a complete sync but there are still some archived email folders that do not work when he is out of the office and not connected to the office network.

What could be causing this to happen even when a full sync is done?

thanks

Tracy 

Discussion Filed Under:

Comments 14 CommentsJump to latest comment

TonySterling's picture

What version of EV, Client, and Outlook?

Also, how large is the users archive and what is your Vault Cache size limit set to?

For the items that don't work, are they older items compared to other items in the archive?

 

TRP's picture

EV8, Outlook 2010.

His archive is 24gb, max cache size is 35gb.

I'll check with him about age

TRP's picture

Regards age it is a mixture. Also, it is whole folders and not individual emails.

JesusWept3's picture

How much has already been downloaded?
Typically archives that size will take a longer time to download, and take multiple restarts of outlook after batches have completed downloading

So for instance you open outlook and hit sync, it downloads like 10,000 items then stops, and then you restart and it does another bunch efc

SOLUTION
TRP's picture

WHere is the vault cache kept so I can check its size?

ta

Rob.Wilcox's picture

You can only check 'on disk'...  and what specific version of EV 8 (on the client).  There were bugs in some versions whereby offline users didn't retrieve items from their cache (even though the item was there)

TRP's picture

I am using the version 9 http only Outlook add in

Rob.Wilcox's picture

Okay can you get two client traces?  One showing a retrieval which works, and one that doesn't?

I suspect with an archive that big that actually the OVStoreSize is being hit, and some data is not actually local any more.

TRP's picture

that link just takes me to the main sym page

TRP's picture

this is the log file after doing a syncronisation:

 

08/08/2012 01:55:05.514[8480]: Log file created (level 1).
08/08/2012 01:55:05.515[8480]: Module path: C:\Program Files (x86)\Enterprise Vault\EVClient\Valkyrie.dll
08/08/2012 01:55:05.516[8480]: Found 0 Desktop resource DLLs
08/08/2012 01:55:05.516[8480]: No resource module was loaded and the default English resources in Valkyrie.dll will be used.
08/08/2012 01:55:05.517[8480]: RPC over HTTP is enabled (flags = 35)
08/08/2012 01:55:05.648[8480]: Update environment path to: C:\Program Files (x86)\Microsoft Office\Office14\;C:\Program Files\Common Files\Microsoft Shared\Windows Live;C:\Program Files (x86)\Common Files\Microsoft Shared\Windows Live;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Windows\System32\WindowsPowerShell\v1.0\;C:\Program Files (x86)\ATI Technologies\ATI.ACE\Core-Static;C:\Program Files\WIDCOMM\Bluetooth Software\;C:\Program Files\WIDCOMM\Bluetooth Software\syswow64;C:\Program Files (x86)\IBM\Client Access\Emulator;C:\Program Files (x86)\IBM\Client Access\Shared;C:\Program Files (x86)\IBM\Client Access\;C:\Program Files (x86)\Windows Live\Shared;C:\Program Files (x86)\jZip;C:\Program Files (x86)\Enterprise Vault\EVClient\;c:\program files (x86)\enterprise vault\evclient\
08/08/2012 01:55:05.651[8480]: 
08/08/2012 01:55:05.651[8480]: Environment:
08/08/2012 01:55:05.651[8480]:     Enterprise Vault Client 9.0.2.1061.0 (HTTP)
08/08/2012 01:55:05.651[8480]:     Microsoft Windows 7  (Build 7600)
08/08/2012 01:55:05.651[8480]:     Intel(R) Core(TM) i7-2720QM CPU @ 2.20GHz Intel64 Family 6 Model 42 Stepping 7;
08/08/2012 01:55:05.652[8480]:     Microsoft Outlook 2010
08/08/2012 01:55:05.652[8480]:         [RPC over HTTP enabled (flags = 35)]
08/08/2012 01:55:05.652[8480]:     Microsoft Internet Explorer 9.0.8112.16421
08/08/2012 01:55:05.652[8480]:     Computer name: ENGDM.masport.co.nz
08/08/2012 01:55:05.652[8480]:     User name: DavidM@masport.co.nz
08/08/2012 01:55:05.653[8480]: 
08/08/2012 01:55:05.653[8480]: Contents of Desktop Settings:
08/08/2012 01:55:05.653[8480]:     ;SERVER; = SERVER2
08/08/2012 01:55:05.653[8480]:     ALLOWSCRIPTPUBLICFOLDERS = 1
08/08/2012 01:55:05.653[8480]:     ALLOWSCRIPTSHAREDFOLDERS = 1
08/08/2012 01:55:05.653[8480]:     ARCHIVEDELITEMSFOLDER = 0
08/08/2012 01:55:05.653[8480]:     ARCHIVEEXPLORERBTNVISIBLE = 1
08/08/2012 01:55:05.653[8480]:     ARCHIVEMANAGEDFOLDERS = 0
08/08/2012 01:55:05.654[8480]:     ARCHIVEONLYWITHATTACH = 0
08/08/2012 01:55:05.654[8480]:     ARCHIVESETTINGSLOCKED = 1
08/08/2012 01:55:05.654[8480]:     DEFAULTARCHIVEID = 1F35A5F13534F8645902876C1ED7CA2E71110000MSPEVAULT
08/08/2012 01:55:05.654[8480]:     DEFAULTDOUBLECLICKVIEW = 0
08/08/2012 01:55:05.654[8480]:     DEFAULTRETENTIONCATEGORYID = 157FEFE87AAD0A143BBC2BD318FA0CB6D1b10000MSPEVAULT
08/08/2012 01:55:05.654[8480]:     DEFAULTWEBAPPURL = http://MSPEVAULT.MASPORT.CO.NZ/EnterpriseVault
08/08/2012 01:55:05.654[8480]:     DEPLOYFORMSLOCALLY = 2
08/08/2012 01:55:05.655[8480]:     EVSERVERS = MSPEVAULT.MASPORT.CO.NZ;EVAULTSVR;
08/08/2012 01:55:05.655[8480]:     FIXOUTLOOKPREVIEWPANE = 1
08/08/2012 01:55:05.655[8480]:     INACTIVITYPERIOD = 3
08/08/2012 01:55:05.655[8480]:     INACTIVITYPERIODUNITS = 2
08/08/2012 01:55:05.655[8480]:     LARGEITEMSSIZEKB = 10240
08/08/2012 01:55:05.655[8480]:     LOCALISEDRESOURCES = C:\Program Files (x86)\Enterprise Vault\EVClient\Valkyrie.dll
08/08/2012 01:55:05.655[8480]:     MAILBOXDN = /o=Masport/ou=First Administrative Group/cn=Recipients/cn=DavidM
08/08/2012 01:55:05.656[8480]:     MAILBOXSTATE = 1
08/08/2012 01:55:05.656[8480]:     MAILBOXTYPE = PrimaryUser
08/08/2012 01:55:05.656[8480]:     MANAGEDFOLDERHANDLING = 2
08/08/2012 01:55:05.656[8480]:     MARKPSTS = 1
08/08/2012 01:55:05.656[8480]:     MAXAUTOREENABLES = 5
08/08/2012 01:55:05.656[8480]:     MINAGEPERIOD = 2
08/08/2012 01:55:05.656[8480]:     MINAGEPERIODUNITS = 1
08/08/2012 01:55:05.656[8480]:     OVENABLED = 2
08/08/2012 01:55:05.656[8480]:     OVMESSAGECLASSINCLUDE = IPM.Post*;IPM.Note*;IPM.Document*;
08/08/2012 01:55:05.656[8480]:     OVSTARTDOWNLOADBTNVISIBLE = 1
08/08/2012 01:55:05.657[8480]:     OVSTORESIZEPERCENT = 25
08/08/2012 01:55:05.657[8480]:     OWA2003DELETEMODE = 0
08/08/2012 01:55:05.657[8480]:     OWAWEBAPPURL = http://MSPEVAULT.MASPORT.CO.NZ/EVAnon
08/08/2012 01:55:05.657[8480]:     PFINACTIVITYPERIOD = 4
08/08/2012 01:55:05.657[8480]:     PFINACTIVITYPERIODUNITS = 2
08/08/2012 01:55:05.657[8480]:     PFLARGEITEMSSIZEKB = 10240
08/08/2012 01:55:05.657[8480]:     PFMINAGEPERIOD = 4
08/08/2012 01:55:05.657[8480]:     PFMINAGEPERIODUNITS = 2
08/08/2012 01:55:05.658[8480]:     PFUSELARGEITEMSSIZE = 0
08/08/2012 01:55:05.658[8480]:     POLICYENTRYID = 12EC9BDC4AEB915438A412C4BAA7DAB321012700MSPEVAULT
08/08/2012 01:55:05.658[8480]:     PSTIMPORTENABLED = 1
08/08/2012 01:55:05.658[8480]:     PSTPOLICYENTRYID = 1038D61C60401E34FBDC9A9EB8CF21D1B1012700MSPEVAULT
08/08/2012 01:55:05.658[8480]:     RPCOVERHTTPPROXYURL = https://webmail.masport.co.nz
08/08/2012 01:55:05.658[8480]:     RPCOVERHTTPRESTRICTIONS = 0
08/08/2012 01:55:05.658[8480]:     RPCOVERHTTPUSEDIRECTCONNECTION = 1
08/08/2012 01:55:05.659[8480]:     SERVERVERSION = 8.04
08/08/2012 01:55:05.659[8480]:     SITEENTRYID = 12D9C69DB34030E4CA16A09DBE27ABBEA1d10000MSPEVAULT
08/08/2012 01:55:05.659[8480]:     SYSTEMMAILBOX = SMTP:entvault@masport.co.nz
08/08/2012 01:55:05.659[8480]:     USEINACTIVITYPERIOD = 1
08/08/2012 01:55:05.659[8480]:     USELARGEITEMSSIZE = 0
08/08/2012 01:55:05.659[8480]:     USEQUOTALIMIT = 0
08/08/2012 01:55:05.659[8480]:     USERSCANDELETEITEMS = 1
08/08/2012 01:55:05.659[8480]:     USESELFINSTALLFUNCT = 0
08/08/2012 01:55:05.659[8480]:     VVENABLED = 1
08/08/2012 01:55:05.660[8480]: 
08/08/2012 01:55:05.660[8480]: 
08/08/2012 01:55:05.667[6792]: PSTMIG: 
08/08/2012 01:55:05.667[6792]: PSTMIG: PST Importer started.
08/08/2012 01:55:05.667[6792]: PSTMIG: 
08/08/2012 01:55:05.667[8480]: Installed Outlook preview pane registry keys fix for Outlook V14
08/08/2012 01:55:05.668[8480]: Installed Outlook ForceFormReload registry key fix for Outlook under 14.0
08/08/2012 01:55:05.668[8480]: Set SharedFolderScript = 1
08/08/2012 01:55:05.668[8480]: Set PublicFolderScript = 1
08/08/2012 01:55:05.671[8480]: [Offline Config] Enabled: true
08/08/2012 01:55:05.671[8480]: [Offline Config] Root Directory: C:\Users\davidm\AppData\Local\KVS\Enterprise Vault\
08/08/2012 01:55:05.788[8480]: [Offline Config] Working Directory: C:\Users\davidm\AppData\Local\KVS\Enterprise Vault\2AE5F1E04235BF48BD1FD8BD8CD61118\
08/08/2012 01:55:05.789[8480]: Adding folder (Deleted Items) to the list of folders the trawler will ignore
08/08/2012 01:55:05.790[8480]: Adding folder (Conflicts) to the list of folders the trawler will ignore
08/08/2012 01:55:05.790[8480]: Adding folder (Sync Issues) to the list of folders the trawler will ignore
08/08/2012 01:55:05.790[8480]: Adding folder (Local Failures) to the list of folders the trawler will ignore
08/08/2012 01:55:05.799[3252]: HDR:SCHED: LastGoodSyncTime:07/08/2012 20:25:17.000 (129888447170000000)
08/08/2012 01:55:05.799[3252]: HDR:SCHED: LastSyncTime:07/08/2012 20:24:54.000 (129888446940000000)
08/08/2012 01:55:05.799[3252]: HDR:SCHED: NextSyncTime:08/08/2012 20:24:22.000 (129889310620000000)
08/08/2012 01:55:05.803[8876]: DTCC: Successfully lowered the priority of this thread
08/08/2012 01:55:05.803[8876]: HDR:SCHED: Starting MDC Scheduler Thread
08/08/2012 01:55:05.804[8664]: DTCC: Successfully lowered the priority of this thread
08/08/2012 01:55:05.807[8876]: HDR:SCHED: MDC Scheduler starting
08/08/2012 01:55:05.807[8876]: HDR:SCHED: Attempting to add MDC to scheduler for archive: 1F35A5F13534F8645902876C1ED7CA2E71110000MSPEVAULT
08/08/2012 01:55:06.082[8876]: HDR: Opening MDC for archive: 1F35A5F13534F8645902876C1ED7CA2E71110000MSPEVAULT
08/08/2012 01:55:06.083[8876]: HDR: ArchiveInfo has changed for '1F35A5F13534F8645902876C1ED7CA2E71110000MSPEVAULT' - updating
08/08/2012 01:55:06.085[8876]: HDR: Successfully opened cache for archive:1F35A5F13534F8645902876C1ED7CA2E71110000MSPEVAULT
08/08/2012 01:55:06.087[8876]: CONTENT:BUILD: GetWebAppURL Returned:http://MSPEVAULT.MASPORT.CO.NZ/EnterpriseVault
08/08/2012 01:55:06.087[8876]: CONTENT:BUILD: ActualWebAppURL Is:http://MSPEVAULT.MASPORT.CO.NZ/EnterpriseVault
08/08/2012 01:55:06.088[8876]: CONTENT:BUILD: VEID:1F35A5F13534F8645902876C1ED7CA2E71110000MSPEVAULT, WebAppURL:http://MSPEVAULT.MASPORT.CO.NZ/EnterpriseVault
08/08/2012 01:55:06.088[8876]: HDR:SYNC:ARC: Performing Cleanup on archive: 1F35A5F13534F8645902876C1ED7CA2E71110000MSPEVAULT
08/08/2012 01:55:06.224[9300]: EVMSP: <EVMSP-20120808-015505-235> [EVMSP::MSProviderInit] mapiVer: 0x00010010
08/08/2012 01:55:06.295[8876]: HDR:SYNC:ARC: Cleanup not required: Already clean
08/08/2012 01:55:06.296[8876]: HDR:PST: CacheState set to: 1
08/08/2012 01:55:06.296[8876]: HDR:SYNC:ARC: Finished Cleanup on archive: 1F35A5F13534F8645902876C1ED7CA2E71110000MSPEVAULT
08/08/2012 01:55:06.296[8876]: HDR:SCHED: LastSyncTime:07/08/2012 20:24:54.000 (129888446940000000)
08/08/2012 01:55:06.296[8876]: HDR:SCHED: NextSyncTime:08/08/2012 20:24:22.000 (129889310620000000)
08/08/2012 01:55:06.297[8876]: HDR:SCHED: Seconds to next sync:66556
08/08/2012 01:55:06.784[9764]: EVMSP: <EVMSP-20120808-015505-235> [EVMSP::MSProviderInit] mapiVer: 0x00010010
08/08/2012 01:55:07.883[8480]: EVMSP: <EVMSP-20120808-015505-235> [EVMSP::MSProviderInit] mapiVer: 0x00010010
08/08/2012 01:55:11.582[4884]: EVMSP: <EVMSP-20120808-015505-235> [EVMSP::MSProviderInit] mapiVer: 0x00010010
08/08/2012 01:55:15.455[8480]: User initiated 'Synchronize Vault Cache'.
08/08/2012 01:55:15.457[8876]: HDR:SYNC: Pre-processing.  Type:MANUALLY INITIATED
08/08/2012 01:55:15.458[8876]: HDR: Sync status:19
08/08/2012 01:55:15.459[8876]: HDR:SYNC: Updating MDC map
08/08/2012 01:55:15.459[8876]: HDR:SYNC: Getting accessible archives
08/08/2012 01:55:15.459[8876]: HDR: Requesting page: ListArchives.aspx
08/08/2012 01:55:15.488[3252]: CONTENT:STORE: [Offline Config] Maximum Store Size (MB): 13312
08/08/2012 01:55:15.849[8876]: HDR:SYNC:ARC: Considering '1F35A5F13534F8645902876C1ED7CA2E71110000MSPEVAULT' for synchronization. Accessible:yes, Enabled:yes, Status:19, File:0, Last sync. time:2012-08-07T20:24:55
08/08/2012 01:55:15.849[8876]: HDR:SYNC:ARC: Is eligible
08/08/2012 01:55:15.850[8876]: HDR:SYNC:ARC: Begin '1F35A5F13534F8645902876C1ED7CA2E71110000MSPEVAULT' ('David McKay')
08/08/2012 01:55:15.850[8876]: HDR: Sync status:30
08/08/2012 01:55:15.850[8876]: HDR:SYNC:ARC: PerformSync
08/08/2012 01:55:15.851[8876]: HDR: Requesting page: Slot.aspx?ArchiveID=1F35A5F13534F8645902876C1ED7CA2E71110000MSPEVAULT&TimeOut=0
08/08/2012 01:55:15.889[8876]: HDR:SYNC:ARC: GetSyncSlotResponse SyncSlot: 4b0c57c7-30c5-4999-8a7c-b3e01ede97c3
08/08/2012 01:55:15.889[8876]: HDR:SYNC:ARC: GetSyncSlotResponse TimeOut: 300
08/08/2012 01:55:15.889[8876]: HDR:SYNC:ARC: Margin:25%, reaffirm interval:225
08/08/2012 01:55:15.889[8876]: HDR: Sync status:20
08/08/2012 01:55:15.890[8876]: HDR:SYNC:ARC: Performing Sync on archive: 1F35A5F13534F8645902876C1ED7CA2E71110000MSPEVAULT
08/08/2012 01:55:16.139[8876]: HDR:SYNC:ARC: Ensuring that the EVMSP operation searches are running & complete
08/08/2012 01:55:16.202[8876]: HDR:SYNC:ARC: Waiting until::08/08/2012 01:59:00.850 (129888647408500000)
08/08/2012 01:55:16.626[8480]: MDC::PSTCacheAdviseSink<128>::OnNotify - Event advised: 128
08/08/2012 01:55:16.960[8480]: MDC::PSTCacheAdviseSink<128>::OnNotify - Event advised: 128
08/08/2012 01:55:17.283[8480]: MDC::PSTCacheAdviseSink<128>::OnNotify - Event advised: 128
08/08/2012 01:55:17.284[8876]: HDR:SYNC:ARC: MDC EVMSP operation search complete
08/08/2012 01:55:17.284[8876]: HDR: Requesting page: SyncPoint.aspx?Slot=4b0c57c7-30c5-4999-8a7c-b3e01ede97c3&ArchiveID=1F35A5F13534F8645902876C1ED7CA2E71110000MSPEVAULT&SyncPoint=131394&SiteID=12D9C69DB34030E4CA16A09DBE27ABBEA1d10000MSPEVAULT
08/08/2012 01:55:17.362[8876]: HDR:SYNC:ARC: GetSyncPointResponse SyncType: NONE
08/08/2012 01:55:17.362[8876]: HDR:SYNC:ARC: GetSyncPointResponse SyncPoint: 131394
08/08/2012 01:55:17.363[8876]: HDR: Requesting page: GetArchiveFolderHierarchy.aspx?archiveId=1F35A5F13534F8645902876C1ED7CA2E71110000MSPEVAULT
08/08/2012 01:55:17.607[8876]: HDR:SYNC:ARC: Folder Only Sync on archive: 1F35A5F13534F8645902876C1ED7CA2E71110000MSPEVAULT
08/08/2012 01:55:17.607[8876]: HDR:SYNC:ARC: Finding new folders created in the archive and creating them in the MDC
08/08/2012 01:55:17.607[8876]: HDR:PST: CacheState set to: 12
08/08/2012 01:55:17.611[8876]: HDR:SYNC:ARC: Issuing item delete requests to the EV server
08/08/2012 01:55:17.612[8876]: HDR:PST: CacheState set to: 16
08/08/2012 01:55:17.613[8876]: HDR:SYNC:ARC: Processing hierarchy changes
08/08/2012 01:55:17.614[8876]: HDR:PST: CacheState set to: 13
08/08/2012 01:55:17.614[8876]: HDR:SYNC:ARC: Applying locally created and changed folder operations to the resultant hierarchy
08/08/2012 01:55:18.591[8876]: HDR:SYNC:ARC: Applying local delete folder operations to the resultant hierarchy
08/08/2012 01:55:18.592[8876]: HDR:SYNC:ARC: Applying folder operations that have been performed in the archive to the MDC
08/08/2012 01:55:18.926[8876]: HDR:SYNC:ARC: Finding folders deleted in the archive and deleting them in the MDC
08/08/2012 01:55:18.927[8876]: HDR:SYNC:ARC: Issuing folder create and update requests to the EV server
08/08/2012 01:55:18.928[8876]: HDR:PST: CacheState set to: 25
08/08/2012 01:55:19.215[8876]: HDR:SYNC:ARC: Issuing item move requests to the EV server
08/08/2012 01:55:19.216[8876]: HDR:PST: CacheState set to: 19
08/08/2012 01:55:19.469[8876]: HDR:SYNC:ARC: Issuing folder delete requests to the EV server
08/08/2012 01:55:19.470[8876]: HDR:PST: CacheState set to: 28
08/08/2012 01:55:19.470[8876]: HDR:SYNC:ARC: Issuing item create requests to the EV server
08/08/2012 01:55:19.470[8876]: HDR:PST: CacheState set to: 22
08/08/2012 01:55:19.473[8664]: CONTENT:BUILD: 
08/08/2012 01:55:19.473[8876]: HDR:PST: CacheState set to: 1
08/08/2012 01:55:19.473[8664]: CONTENT:BUILD: Content cache entire download started.
08/08/2012 01:55:19.473[8664]: CONTENT:BUILD: 
08/08/2012 01:55:19.473[8876]: HDR:PST: CurrentSyncPoint set to: 131394
08/08/2012 01:55:19.474[8876]: HDR:PST: CurrentFullSyncPointTarget set to: 0
08/08/2012 01:55:19.475[8876]: HDR:SYNC:ARC: Finished Sync on archive: 1F35A5F13534F8645902876C1ED7CA2E71110000MSPEVAULT
08/08/2012 01:55:19.475[8876]: HDR:SYNC:ARC: Sync Report - Hierarchy only sync for archive: 1F35A5F13534F8645902876C1ED7CA2E71110000MSPEVAULT
08/08/2012 01:55:19.475[8876]: HDR: Sync status:0
08/08/2012 01:55:19.476[8876]: HDR: Requesting page: Slot.aspx?ArchiveID=1F35A5F13534F8645902876C1ED7CA2E71110000MSPEVAULT&Slot=4b0c57c7-30c5-4999-8a7c-b3e01ede97c3&release=true&MSt=0&CSt=3&Ls=2012-08-08T01:55:15&Ci=40695&Td=0
08/08/2012 01:55:19.495[8876]: HDR:SYNC:ARC: End '1F35A5F13534F8645902876C1ED7CA2E71110000MSPEVAULT' ('David McKay'):SUCCEEDED
08/08/2012 01:55:19.497[8876]: HDR:SYNC:ARC: Cache '1F35A5F13534F8645902876C1ED7CA2E71110000MSPEVAULT', status:0
08/08/2012 01:55:19.497[8876]: HDR:SCHED: LastSyncTime:08/08/2012 01:55:15.457 (129888645154570000)
08/08/2012 01:55:19.498[8876]: HDR:SCHED: NextSyncTime:08/08/2012 20:24:22.000 (129889310620000000)
08/08/2012 01:55:19.498[8876]: HDR:SCHED: Seconds to next sync:66542
08/08/2012 01:55:19.498[8876]: HDR:SYNC: Successful
08/08/2012 01:55:26.038[5756]: 
08/08/2012 01:55:36.611[7268]: EVMSP: <EVMSP-20120808-015505-235> [EVMSP::MSProviderInit] mapiVer: 0x00010010
08/08/2012 01:55:36.639[8480]: User initiated 'Synchronize Vault Cache'.
08/08/2012 01:55:36.642[8876]: HDR:SYNC: Pre-processing.  Type:MANUALLY INITIATED
08/08/2012 01:55:36.643[8876]: HDR: Sync status:19
08/08/2012 01:55:36.644[8876]: HDR:SYNC: Updating MDC map
08/08/2012 01:55:36.645[8876]: HDR:SYNC: Getting accessible archives
08/08/2012 01:55:36.645[8876]: HDR: Requesting page: ListArchives.aspx
08/08/2012 01:55:36.681[8876]: HDR:SYNC:ARC: Considering '1F35A5F13534F8645902876C1ED7CA2E71110000MSPEVAULT' for synchronization. Accessible:yes, Enabled:yes, Status:19, File:0, Last sync. time:2012-08-08T01:55:15
08/08/2012 01:55:36.682[8876]: HDR:SYNC:ARC: Is eligible
08/08/2012 01:55:36.682[8876]: HDR:SYNC:ARC: Begin '1F35A5F13534F8645902876C1ED7CA2E71110000MSPEVAULT' ('David McKay')
08/08/2012 01:55:36.682[8876]: HDR: Sync status:30
08/08/2012 01:55:36.682[8876]: HDR:SYNC:ARC: PerformSync
08/08/2012 01:55:36.683[8876]: HDR: Requesting page: Slot.aspx?ArchiveID=1F35A5F13534F8645902876C1ED7CA2E71110000MSPEVAULT&TimeOut=0
08/08/2012 01:55:36.701[8876]: HDR:SYNC:ARC: GetSyncSlotResponse SyncSlot: 40e96bce-a05a-456f-8038-0f48de6f0d99
08/08/2012 01:55:36.701[8876]: HDR:SYNC:ARC: GetSyncSlotResponse TimeOut: 300
08/08/2012 01:55:36.701[8876]: HDR:SYNC:ARC: Margin:25%, reaffirm interval:225
08/08/2012 01:55:36.702[8876]: HDR: Sync status:20
08/08/2012 01:55:36.702[8876]: HDR:SYNC:ARC: Performing Sync on archive: 1F35A5F13534F8645902876C1ED7CA2E71110000MSPEVAULT
08/08/2012 01:55:36.951[8876]: HDR:SYNC:ARC: Ensuring that the EVMSP operation searches are running & complete
08/08/2012 01:55:37.018[8876]: HDR:SYNC:ARC: Waiting until::08/08/2012 01:59:21.683 (129888647616830000)
08/08/2012 01:55:37.408[8480]: MDC::PSTCacheAdviseSink<128>::OnNotify - Event advised: 128
08/08/2012 01:55:37.715[8480]: MDC::PSTCacheAdviseSink<128>::OnNotify - Event advised: 128
08/08/2012 01:55:38.020[8480]: MDC::PSTCacheAdviseSink<128>::OnNotify - Event advised: 128
08/08/2012 01:55:38.021[8876]: HDR:SYNC:ARC: MDC EVMSP operation search complete
08/08/2012 01:55:38.022[8876]: HDR: Requesting page: SyncPoint.aspx?Slot=40e96bce-a05a-456f-8038-0f48de6f0d99&ArchiveID=1F35A5F13534F8645902876C1ED7CA2E71110000MSPEVAULT&SyncPoint=131394&SiteID=12D9C69DB34030E4CA16A09DBE27ABBEA1d10000MSPEVAULT
08/08/2012 01:55:38.051[8876]: HDR:SYNC:ARC: GetSyncPointResponse SyncType: NONE
08/08/2012 01:55:38.051[8876]: HDR:SYNC:ARC: GetSyncPointResponse SyncPoint: 131394
08/08/2012 01:55:38.052[8876]: HDR: Requesting page: GetArchiveFolderHierarchy.aspx?archiveId=1F35A5F13534F8645902876C1ED7CA2E71110000MSPEVAULT
08/08/2012 01:55:38.275[8876]: HDR:SYNC:ARC: Folder Only Sync on archive: 1F35A5F13534F8645902876C1ED7CA2E71110000MSPEVAULT
08/08/2012 01:55:38.275[8876]: HDR:SYNC:ARC: Finding new folders created in the archive and creating them in the MDC
08/08/2012 01:55:38.276[8876]: HDR:PST: CacheState set to: 12
08/08/2012 01:55:38.281[8876]: HDR:SYNC:ARC: Issuing item delete requests to the EV server
08/08/2012 01:55:38.281[8876]: HDR:PST: CacheState set to: 16
08/08/2012 01:55:38.282[8876]: HDR:SYNC:ARC: Processing hierarchy changes
08/08/2012 01:55:38.283[8876]: HDR:PST: CacheState set to: 13
08/08/2012 01:55:38.283[8876]: HDR:SYNC:ARC: Applying locally created and changed folder operations to the resultant hierarchy
08/08/2012 01:55:39.234[8876]: HDR:SYNC:ARC: Applying local delete folder operations to the resultant hierarchy
08/08/2012 01:55:39.234[8876]: HDR:SYNC:ARC: Applying folder operations that have been performed in the archive to the MDC
08/08/2012 01:55:39.536[8876]: HDR:SYNC:ARC: Finding folders deleted in the archive and deleting them in the MDC
08/08/2012 01:55:39.537[8876]: HDR:SYNC:ARC: Issuing folder create and update requests to the EV server
08/08/2012 01:55:39.538[8876]: HDR:PST: CacheState set to: 25
08/08/2012 01:55:39.810[8876]: HDR:SYNC:ARC: Issuing item move requests to the EV server
08/08/2012 01:55:39.810[8876]: HDR:PST: CacheState set to: 19
08/08/2012 01:55:40.067[8876]: HDR:SYNC:ARC: Issuing folder delete requests to the EV server
08/08/2012 01:55:40.068[8876]: HDR:PST: CacheState set to: 28
08/08/2012 01:55:40.068[8876]: HDR:SYNC:ARC: Issuing item create requests to the EV server
08/08/2012 01:55:40.068[8876]: HDR:PST: CacheState set to: 22
08/08/2012 01:55:40.071[8664]: CONTENT:BUILD: 
08/08/2012 01:55:40.071[8876]: HDR:PST: CacheState set to: 1
08/08/2012 01:55:40.071[8664]: CONTENT:BUILD: Content cache entire download started.
08/08/2012 01:55:40.072[8664]: CONTENT:BUILD: 
08/08/2012 01:55:40.071[8876]: HDR:PST: CurrentSyncPoint set to: 131394
08/08/2012 01:55:40.072[8876]: HDR:PST: CurrentFullSyncPointTarget set to: 0
08/08/2012 01:55:40.073[8876]: HDR:SYNC:ARC: Finished Sync on archive: 1F35A5F13534F8645902876C1ED7CA2E71110000MSPEVAULT
08/08/2012 01:55:40.073[8876]: HDR:SYNC:ARC: Sync Report - Hierarchy only sync for archive: 1F35A5F13534F8645902876C1ED7CA2E71110000MSPEVAULT
08/08/2012 01:55:40.073[8876]: HDR: Sync status:0
08/08/2012 01:55:40.074[8876]: HDR: Requesting page: Slot.aspx?ArchiveID=1F35A5F13534F8645902876C1ED7CA2E71110000MSPEVAULT&Slot=40e96bce-a05a-456f-8038-0f48de6f0d99&release=true&MSt=0&CSt=3&Ls=2012-08-08T01:55:36&Ci=40695&Td=0
08/08/2012 01:55:40.093[8876]: HDR:SYNC:ARC: End '1F35A5F13534F8645902876C1ED7CA2E71110000MSPEVAULT' ('David McKay'):SUCCEEDED
08/08/2012 01:55:40.094[8876]: HDR:SYNC:ARC: Cache '1F35A5F13534F8645902876C1ED7CA2E71110000MSPEVAULT', status:0
08/08/2012 01:55:40.094[8876]: HDR:SCHED: LastSyncTime:08/08/2012 01:55:36.643 (129888645366430000)
08/08/2012 01:55:40.095[8876]: HDR:SCHED: NextSyncTime:08/08/2012 20:24:22.000 (129889310620000000)
08/08/2012 01:55:40.095[8876]: HDR:SCHED: Seconds to next sync:66521
08/08/2012 01:55:40.095[8876]: HDR:SYNC: Successful
08/08/2012 01:56:05.853[6792]: PSTMIG: PSTIncludeDeletedItems = 0 [policy]
08/08/2012 01:56:05.854[6792]: PSTMIG: PSTExpandOfflineVault = 0 [policy]
08/08/2012 01:56:05.854[6792]: PSTMIG: PSTSearchRestricted = 0 [policy]
08/08/2012 01:56:05.855[6792]: PSTMIG: PSTIncludeShortcuts = 0 [policy]
08/08/2012 01:56:05.855[6792]: PSTMIG: PSTMaxPSTChunkSize = 10000 [policy]
08/08/2012 01:56:05.856[6792]: PSTMIG: MaxPSTPasswordRetries = 3
08/08/2012 01:56:15.000[8480]: EVMSP: <EVMSP-20120808-015505-235> [EVMSP::MSProviderInit] mapiVer: 0x00010010
TypoProne's picture

I may be mistaken because I alway shave a challenge reading logs posted like that..... but I do not see the item being retrieved . 

 

This also is not the two logs that were requested. Can you pelase collect the two logs requested and post them up in txt format as an attachemtn?

 

Many thanks.