Deleted archives stuck in "Marked for deletion" state

[yarg]

Hi all,

I have an EV environment (v9.0.2.1061) which was set up some years ago to handle Exchange mailbox archiving as well as journalling.  The environment is no longer being used to archive new content but still handles the journalling.  Users are still able to access their old archives (archiving of new items was stopped around a year ago).

I'm now in the process of trying to clean up all of our environments and have realised that when users have left the organisation, their archives were never deleted.  Management has given me the go-ahead to delete a few hundred archives which are shown as "Account unkown" state in the console; I imagine this is because these accounts and mailboxes were deleted from AD but the archive remains.

I have tried deleting some archives to test how it works and they remain in "Marked for deletion" state for a number of days now.  I've searched on the forums/internet and can confirm that none of the suggested fixes applies to us, eg we don't have any retention categories preventing deletion etc.  I have also followed the technote which suggest to change the state back to "1" in the DB, restart the storage service, try again and yet nothing works.  I've run DTRACE against the storage process, and although I see lots of line items with "Success" at the end, I'm not experienced enough to read this log and determine where the root cause for this is.  Due to it's length, I guess I can't post the entire log here, so below is an extract (please let me know if you need more).

Any pointers would be much appreciated!
Thanks in advance,
G

-----------------------------------------------------

Windows version: 6.1.7601

Time zone: W. Europe Daylight Time

Time difference to UTC: +2:00

-----

Enabled Trace options

Id    Name                             Detail     Include Events

83  - StorageDelete                    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 running in the console session

  Monitoring debug output from processes running in the current Terminal Services session

1 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CNTFSStorageDeviceDeleter::Delete} (Exit) Status: [False (0x1)]

2 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CNTFSStorageDeviceDeleter::DeleteSaveset} (Exit) Status: [False (0x1)]

3 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CSavesetSISManager::DeleteSaveset} (Exit) Status: [False (0x1)]

4 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CVault::DeleteSaveset} (Exit) Status: [False (0x1)]

5 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CStoreAccessor::DeleteItem} (Exit) Status: [Success]

6 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CStoreAccessor::DeleteItem} (Entry)

7 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CStoreAccessor::CheckContainerStatii} (Entry)

8 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CStoreAccessor::CheckVaultStoreStatus} (Entry)

9 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CVaultStorePartitionCache::ReadEntry:#135} Successfully read partition info for Vault Store [10BE7FC86F12BCC47AB9CF4CB3F3AC84A1210000Archive]

10 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CStoreAccessor::CheckVaultStoreStatus} (Exit) Status: [Success]

11 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CVaultParameters::GetVaultName} (Entry)

12 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CVaultParameters::GetVaultParams} (Entry)

13 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CVaultCache::ReadEntry:#104} Successfully read Vault [15BECD51FE9197F4EA464706769A6EC9C1110000Archive] information from cache.

14 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CVaultParameters::GetVaultParams} (Exit) Status: [Success]

15 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CVaultParameters::GetVaultName} (Exit) Status: [Success]

16 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CVaultParameters::GetVaultStatus} (Entry)

17 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CVaultParameters::GetVaultParams} (Entry)

18 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CVaultCache::ReadEntry:#104} Successfully read Vault [15BECD51FE9197F4EA464706769A6EC9C1110000Archive] information from cache.

19 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CVaultParameters::GetVaultParams} (Exit) Status: [Success]

20 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CVaultParameters::GetVaultStatus} (Exit) Status: [Success]

21 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CVaultParameters::GetArchiveId} (Entry)

22 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CVaultCache::ReadEntry:#104} Successfully read Vault [15BECD51FE9197F4EA464706769A6EC9C1110000Archive] information from cache.

23 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CVaultParameters::GetArchiveId} (Exit) Status: [Success]

24 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CStoreAccessor::CheckContainerStatii} (Exit) Status: [Success]

25 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CVaultParameters::GetVaultType} (Entry)

26 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CVaultCache::ReadEntry:#104} Successfully read Vault [15BECD51FE9197F4EA464706769A6EC9C1110000Archive] information from cache.

27 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CVaultParameters::GetVaultType} (Exit) Status: [Success]

28 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CVault::DeleteSaveset} (Entry)

29 16:04:09.976 [7656] (StorageDelete) <6180> EV:H {CVault::DeleteSaveset}|Selector: F5CF50B11DD24F888E61E5A27F795FE0

30 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CVault::GetItemProperties} (Entry)

31 16:04:09.976 [7656] (StorageDelete) <6180> EV:H {CVault::GetItemProperties}|Selector: F5CF50B11DD24F888E61E5A27F795FE0

32 16:04:09.976 [7656] (StorageDelete) <6180> EV:H CSavesetIdentifier::ExtractTransactionId Invalid saveset identifier: F5CF50B11DD24F888E61E5A27F795FE0

33 16:04:09.976 [7656] (StorageDelete) <6180> EV:L CVaultStoreDB::GetSavesetProperties Information: No SISParts found for saveset having TID:'F5CF50B1-1DD2-4F88-8E61-E5A27F795FE0'.

34 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CVault::ReadPartitionEntry} (Entry)

35 16:04:09.976 [7656] (StorageDelete) <6180> EV:M {CVault::ReadPartitionEntry}|Id: 3

36 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CVaultStorePartitionCache::ReadEntry:#135} Successfully read partition info for Vault Store [10BE7FC86F12BCC47AB9CF4CB3F3AC84A1210000Archive]

37 16:04:09.976 [7656] (StorageDelete) <6180> EV:M {CVault::ReadPartitionEntry}|Partition Id:3VaultStore:10BE7FC86F12BCC47AB9CF4CB3F3AC84A1210000Archive HR=Success  (0)

38 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CVault::ReadPartitionEntry} (Exit) Status: [Success]

39 16:04:09.976 [7656] (StorageDelete) <6180> EV:M CSSASCache::ReadEntry Id:17B394300772C5C4883D06994CE03292D1e10000Archive hr:0

40 16:04:09.976 [7656] (StorageDelete) <6180> EV:M CRetentionCategoryIdentityCache::ReadEntry Id:2 SiteId:1D6F5829F0A8FAB49ACABFB34F3CC17451d10000Archive hr:0

41 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CVault::SetComplianceDevice} (Entry)

42 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CVault::SetComplianceDevice} (Exit) Status: [Success]

43 16:04:09.976 [7656] (StorageDelete) <6180> EV:M {CVault::GetItemProperties}|Sel:F5CF50B11DD24F888E61E5A27F795FE0 Seq No:52956 Partition ID:3 Store Id:2009\01-18\F\Collection225863.CAB CollectionId:True

44 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CVault::GetItemProperties} (Exit) Status: [Success]

45 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CVault::IsDeletePermitted} (Entry)

46 16:04:09.976 [7656] (StorageDelete) <6180> EV:M CRetentionCategoryCache::ReadEntry Id:1724E3988881344409873222A36879E0B1b10000Archive hr:0

47 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CVault::IsDeletePermitted}|Retention Category: GF Retention Policy|ONHOLD: No

48 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CVault::IsDeletePermitted} (Exit)

49 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CVault::ReadPartitionEntry} (Entry)

50 16:04:09.976 [7656] (StorageDelete) <6180> EV:M {CVault::ReadPartitionEntry}|Id: 3

51 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CVaultStorePartitionCache::ReadEntry:#135} Successfully read partition info for Vault Store [10BE7FC86F12BCC47AB9CF4CB3F3AC84A1210000Archive]

52 16:04:09.976 [7656] (StorageDelete) <6180> EV:M {CVault::ReadPartitionEntry}|Partition Id:3VaultStore:10BE7FC86F12BCC47AB9CF4CB3F3AC84A1210000Archive HR=Success  (0)

53 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CVault::ReadPartitionEntry} (Exit) Status: [Success]

54 16:04:09.976 [7656] (StorageDelete) <6180> EV:M CSSASCache::ReadEntry Id:17B394300772C5C4883D06994CE03292D1e10000Archive hr:0

55 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CVaultParameters::GetIndexingData} (Entry)

56 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CVaultCache::ReadEntry:#104} Successfully read Vault [15BECD51FE9197F4EA464706769A6EC9C1110000Archive] information from cache.

57 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CVaultParameters::GetIndexingData} (Exit) Status: [Success]

58 16:04:09.976 [7656] (StorageDelete) <6180> EV:M {CVault::DeleteSaveset}|IndexItemsMode: 0

59 16:04:09.976 [7656] (StorageDelete) <6180> EV:L {CVaultCache::ReadEntry:#104} Successfully read Vault [1351508CFB7D0EF4EAB19A0DEC7086F771110000Archive] information from cache.

60 16:04:09.976 [7656] (StorageDelete) <6180> EV:L CVaultStoreDB::DeleteSaveset Information: Total 1 SISParts found for saveset having TID:'F5CF50B1-1DD2-4F88-8E61-E5A27F795FE0'.

61 16:04:09.976 [7656] (StorageDelete) <6180> EV:L CVaultStoreDB::DeleteSaveset Information: Total 1 SISParts added to output vector for saveset having TID:'F5CF50B1-1DD2-4F88-8E61-E5A27F795FE0'.

62 16:04:09.976 [7656] (StorageDelete) <6180> EV:L CVaultStoreDB::AggregateReportingData Information: Going to execute reporting aggregation SP.

63 16:04:09.992 [7656] (StorageDelete) <6180> EV:L CVaultStoreDB::AggregateReportingData Information: Executed reporting aggregation SP.

64 16:04:09.992 [7656] (StorageDelete) <6180> EV:M {CVault::DeleteSaveset}|SSID: 200901187992354~200811171625140000~Z~F5CF50B11DD24F888E61E5A27F795FE0|ItemSeqNo: 52956|IndexDeletesPending: 1|IndexingDisabled: 0|NotifyIndexer: 1|Ref Count: 0

65 16:04:09.992 [7656] (StorageDelete) <6180> EV:L {CVault::UpdateArchiveLimitsStatus} (Entry)

66 16:04:09.992 [7656] (StorageDelete) <6180> EV:L {CVaultParameters::GetVaultLimits} (Entry)

67 16:04:09.992 [7656] (StorageDelete) <6180> EV:L {CVaultParameters::GetVaultParams} (Entry)

68 16:04:09.992 [7656] (StorageDelete) <6180> EV:L {CVaultCache::ReadEntry:#104} Successfully read Vault [15BECD51FE9197F4EA464706769A6EC9C1110000Archive] information from cache.

69 16:04:09.992 [7656] (StorageDelete) <6180> EV:L {CVaultParameters::GetVSVaultParams} (Entry)

70 16:04:09.992 [7656] (StorageDelete) <6180> EV:L {CVaultStorePartitionCache::ReadEntry:#135} Successfully read partition info for Vault Store [10BE7FC86F12BCC47AB9CF4CB3F3AC84A1210000Archive]

71 16:04:09.992 [7656] (StorageDelete) <6180> EV:L {CVaultParameters::GetSiteVaultParams} (Entry)

72 16:04:09.992 [7656] (StorageDelete) <6180> EV:M CSSASCache::ReadEntry Id:17B394300772C5C4883D06994CE03292D1e10000Archive hr:0

73 16:04:09.992 [7656] (StorageDelete) <6180> EV:L {CVaultParameters::GetSiteVaultParams} (Exit) Status: [Success]

74 16:04:09.992 [7656] (StorageDelete) <6180> EV:L {CVaultParameters::GetVSVaultParams} (Exit) Status: [Success]

75 16:04:09.992 [7656] (StorageDelete) <6180> EV:L {CVaultParameters::GetVaultParams} (Exit) Status: [Success]

76 16:04:09.992 [7656] (StorageDelete) <6180> EV:L {CVaultParameters::GetVaultLimits} (Exit) Status: [Success]

77 16:04:09.992 [7656] (StorageDelete) <6180> EV:M {CVault::UpdateArchiveLimitsStatus}|Enabled: False

78 16:04:09.992 [7656] (StorageDelete) <6180> EV:L {CVault::UpdateArchiveLimitsStatus} (Exit) Status: [Success]

79 16:04:09.992 [7656] (StorageDelete) <6180> EV:M {CVault::DeleteSaveset}|Deleted item from VSDB, attempting to delete sis parts

80 16:04:09.992 [7656] (StorageDelete) <6180> EV:L {SavesetSISManager::DeleteSisParts} (Entry)

81 16:04:09.992 [7656] (StorageDelete) <6180> EV:L {CVaultStorePartitionCache::ReadEntry:#135} Successfully read partition info for Vault Store [10BE7FC86F12BCC47AB9CF4CB3F3AC84A1210000Archive]

82 16:04:09.992 [7656] (StorageDelete) <6180> EV:L Vault Store Partition Parameters:|VaultStoreGroup Entry ID: 115F92266DDBA4A479854D573051975DB1013300Archive|VaultStoreGroup Name: GF Vault Store Group|VaultStore Id: 10BE7FC86F12BCC47AB9CF4CB3F3AC84A1210000Archive|VaultStore Name: Archive|Sharing Enabled: Yes|Sharing Level: 1|SIS Part Size Threshold: 20 Kbytes|

83 16:04:09.992 [7656] (StorageDelete) <6180> EV:L {CSavesetSISManager::GetFPCatalogueAccessor} (Entry)

84 16:04:09.992 [7656] (StorageDelete) <6180> EV:L {CFingerprintCatalogueAccessorFactory::GetFPCatalogueAccessor} (Entry)

85 16:04:09.992 [7656] (StorageDelete) <6180> EV:L {CFingerprintCatalogueAccessorFactory::GetDataAccessClient} (Entry)

86 16:04:09.992 [7656] (StorageDelete) <6180> EV:L {CFingerprintCatalogueAccessorFactory::GetDataAccessClient:#103} FP Data Access Client already exists, returning existing from cache

87 16:04:09.992 [7656] (StorageDelete) <6180> EV:L {CFingerprintCatalogueAccessorFactory::GetDataAccessClient} (Exit) Status: [Success]

88 16:04:09.992 [7656] (StorageDelete) <6180> EV:L {CFingerprintCatalogueAccessorFactory::GetFPCatalogueAccessor} (Exit) Status: [Success]

89 16:04:09.992 [7656] (StorageDelete) <6180> EV:L {CSavesetSISManager::GetFPCatalogueAccessor} (Exit) Status: [Success]

90 16:04:09.992 [7656] (StorageDelete) <6180> EV:M {SavesetSISManager::DeleteSisParts}|Unsharing deleted sis parts

91 16:04:09.992 [7656] (StorageDelete) <6180> EV:L {CEVFingerprintCatalogueAccessor::UnshareAndBlacklistSISParts} (Entry) Total SISparts count = [1] ForceBlacklist = [not set]

92 16:04:09.992 [7656] (StorageDelete) <6180> EV:L {CFingerprintCatalogueInstance::UnshareSISParts} (Entry) SISPartsToUpdate = [1] ForceBlacklist = [not set]

93 16:04:09.992 [7656] (StorageDelete) <6180> EV:M CFingerprintCatalogueInstance::UnshareSISParts Info: About to unshare 1 SISParts for FPC Instance Id=1.

94 16:04:09.992 [7656] (StorageDelete) <6180> EV:L {CFingerprintCatalogueDatabase::UnshareSISParts} (Entry)

95 16:04:09.992 [7656] (StorageDelete) <6180> EV:L CADOContext::CreateConnection entry

96 16:04:09.992 [7656] (StorageDelete) <6180> EV:L CADOContext::CreateConnection exit. source:Provider=SQLOLEDB;Server=scaurora07\sql7;Database=EVVSGGFVaultStoreGroup_1_1;Integrated Security=SSPI hr=Success  (0)

97 16:04:09.992 [7656] (StorageDelete) <6180> EV:L CADODataAccess::CreateCommand entry

98 16:04:09.992 [7656] (StorageDelete) <6180> EV:L CADODataAccess::CreateCommand exit. hr=Success  (0)

99 16:04:09.992 [7656] (StorageDelete) <6180> EV:L CFingerprintCatalogueDatabase::UnshareSISParts Information: Trying to set unshare SISParts with first byte-0 and last byte-255 as byte range

100 16:04:10.039 [7656] (StorageDelete) <6180> EV:L uspu_UnshareBlacklist10 result: hrSPCall=0x00000000; retval=0x00000000; TxSuccessfulCount=2

101 16:04:10.039 [7656] (StorageDelete) <6180> EV:L {CFingerprintCatalogueDatabase::UnshareSISParts} (Entry)

102 16:04:10.039 [7656] (StorageDelete) <6180> EV:L {CFingerprintCatalogueDatabase::AggregateReportingData} (Entry)

103 16:04:10.039 [7656] (StorageDelete) <6180> EV:L CFingerprintCatalogueDatabase::AggregateReportingData Information: Reporting aggregation SP will not be executed based on random number generation.

104 16:04:10.039 [7656] (StorageDelete) <6180> EV:L {CFingerprintCatalogueDatabase::AggregateReportingData} (Exit) Status: [Success]

105 16:04:10.039 [7656] (StorageDelete) <6180> EV:L {CFingerprintCatalogueDatabase::UnshareSISParts} (Exit) Status: [Success]

106 16:04:10.039 [7656] (StorageDelete) <6180> EV:L {CFingerprintCatalogueDatabase::UnshareSISParts} (Exit) Status: [Success]

107 16:04:10.039 [7656] (StorageDelete) <6180> EV:M CFingerprintCatalogueInstance::UnshareSISParts Info: Unshared 1 SISParts for FPC database Id=1 in FPC Instance Id=1.

108 16:04:10.039 [7656] (StorageDelete) <6180> EV:M CFingerprintCatalogueInstance::UnshareSISParts Info:  Total SISParts updated = 1 and left to update = 0 for FPC database Id=1 in FPC Instance Id=1.

109 16:04:10.039 [7656] (StorageDelete) <6180> EV:L {CFingerprintCatalogueInstance::UnshareSISParts} (Exit) Status: [Success]

110 16:04:10.039 [7656] (StorageDelete) <6180> EV:L CEVFingerprintCatalogueAccessor::UnshareAndBlacklistSISParts Information: All SISParts to unshare were updated within primary instance

111 16:04:10.039 [7656] (StorageDelete) <6180> EV:L {CEVFingerprintCatalogueAccessor::UnshareAndBlacklistSISParts} (Exit) Status: [Success]

112 16:04:10.039 [7656] (StorageDelete) <6180> EV:L {CSavesetSISManager::VerifyUnreferencedSisParts} (Entry)

113 16:04:10.039 [7656] (StorageDelete) <6180> EV:L No unreferenced SisParts found

114 16:04:10.039 [7656] (StorageDelete) <6180> EV:L {CSavesetSISManager::VerifyUnreferencedSisParts} (Exit) Status: [Success]

115 16:04:10.039 [7656] (StorageDelete) <6180> EV:M {SavesetSISManager::DeleteSisParts}|Splitting 0 reffed items into those that are collected and those that aren't

116 16:04:10.039 [7656] (StorageDelete) <6180> EV:L {SavesetSISManager::GroupUnreferencedPartsByCollectedStatus} (Entry)

117 16:04:10.039 [7656] (StorageDelete) <6180> EV:L {SavesetSISManager::GroupUnreferencedPartsByCollectedStatus} (Exit)

118 16:04:10.039 [7656] (StorageDelete) <6180> EV:M {SavesetSISManager::DeleteSisParts}|dereffing collection counts of sis parts that have been deleted

119 16:04:10.039 [7656] (StorageDelete) <6180> EV:L {CSavesetSISManager::DereferenceCollectedSisParts} (Entry)

120 16:04:10.039 [7656] (StorageDelete) <6180> EV:L {CSavesetSISManager::DereferenceCollectedSisParts} (Exit) Status: [Success]

121 16:04:10.039 [7656] (StorageDelete) <6180> EV:M {SavesetSISManager::DeleteSisParts}|Deleting non collected sis parts

122 16:04:10.039 [7656] (StorageDelete) <6180> EV:L {SavesetSISManager::DeleteNonCollectedUnreferencedSisParts} (Entry)

123 16:04:10.039 [7656] (StorageDelete) <6180> EV:L {SavesetSISManager::DeleteNonCollectedUnreferencedSisParts} (Exit) Status: [Success]

124 16:04:10.039 [7656] (StorageDelete) <6180> EV:M {SavesetSISManager::DeleteSisParts}|Removing deleted items from the fingerprint catalogue

125 16:04:10.039 [7656] (StorageDelete) <6180> EV:L {CEVFingerprintCatalogueAccessor::DeleteDeletedSisPart} (Entry) Total SISparts to be deleted = [0]

126 16:04:10.039 [7656] (StorageDelete) <6180> EV:L {CFingerprintCatalogueInstance::DeleteDeletedSisPart} (Entry) SISPartsToDelete = [0]

127 16:04:10.039 [7656] (StorageDelete) <6180> EV:M CFingerprintCatalogueInstance::DeleteDeletedSisPart Info: About to delete 0 SISParts for FPC Instance Id=1.

128 16:04:10.039 [7656] (StorageDelete) <6180> EV:L {CFingerprintCatalogueDatabase::DeleteDeletedSisPart} (Entry)

129 16:04:10.039 [7656] (StorageDelete) <6180> EV:L CADOContext::CreateConnection entry

130 16:04:10.039 [7656] (StorageDelete) <6180> EV:L CADOContext::CreateConnection exit. source:Provider=SQLOLEDB;Server=scaurora07\sql7;Database=EVVSGGFVaultStoreGroup_1_1;Integrated Security=SSPI hr=Success  (0)

131 16:04:10.039 [7656] (StorageDelete) <6180> EV:L CADODataAccess::CreateCommand entry

132 16:04:10.039 [7656] (StorageDelete) <6180> EV:L CADODataAccess::CreateCommand exit. hr=Success  (0)

133 16:04:10.039 [7656] (StorageDelete) <6180> EV:L {CFingerprintCatalogueDatabase::DeleteDeletedSisPart} (Exit) Status: [Success]

134 16:04:10.039 [7656] (StorageDelete) <6180> EV:M CFingerprintCatalogueInstance::DeleteDeletedSisPart Info: Deleted 0 SISParts for FPC database Id=1 in FPC Instance Id=1.

135 16:04:10.039 [7656] (StorageDelete) <6180> EV:M CFingerprintCatalogueInstance::DeleteDeletedSisPart Info:  Total SISParts deleted = 0 and left to update = 0 for FPC database Id=1 in FPC Instance Id=1.

136 16:04:10.039 [7656] (StorageDelete) <6180> EV:L {CFingerprintCatalogueInstance::DeleteDeletedSisPart} (Exit) Status: [Success]

137 16:04:10.039 [7656] (StorageDelete) <6180> EV:L CEVFingerprintCatalogueAccessor::DeleteDeletedSisPart Information: All SISParts to delete were updated within primary instance

138 16:04:10.039 [7656] (StorageDelete) <6180> EV:L {CEVFingerprintCatalogueAccessor::DeleteDeletedSisPart} (Exit) Status: [Success]

139 16:04:10.039 [7656] (StorageDelete) <6180> EV:L {SavesetSISManager::DeleteSisParts} (Exit) Status: [Success]

140 16:04:10.039 [7656] (StorageDelete) <6180> EV:L {CVault::DeleteMigratedItem} (Entry)

141 16:04:10.039 [7656] (StorageDelete) <6180> EV:L {CVault::ReadPartitionEntry} (Entry)

142 16:04:10.039 [7656] (StorageDelete) <6180> EV:M {CVault::ReadPartitionEntry}|Id: 3

143 16:04:10.039 [7656] (StorageDelete) <6180> EV:L {CVaultStorePartitionCache::ReadEntry:#135} Successfully read partition info for Vault Store [10BE7FC86F12BCC47AB9CF4CB3F3AC84A1210000Archive]

144 16:04:10.039 [7656] (StorageDelete) <6180> EV:M {CVault::ReadPartitionEntry}|Partition Id:3VaultStore:10BE7FC86F12BCC47AB9CF4CB3F3AC84A1210000Archive HR=Success  (0)

145 16:04:10.039 [7656] (StorageDelete) <6180> EV:L {CVault::ReadPartitionEntry} (Exit) Status: [Success]

146 16:04:10.039 [7656] (StorageDelete) <6180> EV:L {CVault::AccessMigratedStorage} (Entry)

147 16:04:10.039 [7656] (StorageDelete) <6180> EV:L {CVault::AccessMigratedStorage} (Exit) Status: [Success]

148 16:04:10.039 [7656] (StorageDelete) <6180> EV:L {CVault::DeleteMigratedItem} (Exit) Status: [Success]

149 16:04:10.039 [7656] (StorageDelete) <6180> EV:L {CSavesetSISManager::DeleteSaveset} (Entry)

150 16:04:10.039 [7656] (StorageDelete) <6180> EV:L {CVaultStorePartitionCache::ReadEntry:#135} Successfully read partition info for Vault Store [10BE7FC86F12BCC47AB9CF4CB3F3AC84A1210000Archive]

151 16:04:10.039 [7656] (StorageDelete) <6180> EV:L Vault Store Partition Parameters:|VaultStoreGroup Entry ID: 115F92266DDBA4A479854D573051975DB1013300Archive|VaultStoreGroup Name: GF Vault Store Group|VaultStore Id: 10BE7FC86F12BCC47AB9CF4CB3F3AC84A1210000Archive|VaultStore Name: Archive|Sharing Enabled: Yes|Sharing Level: 1|SIS Part Size Threshold: 20 Kbytes|

152 16:04:10.039 [7656] (StorageDelete) <6180> EV:L {CSavesetSISManager::GetStorageDeviceDeleter} (Entry)

153 16:04:10.039 [7656] (StorageDelete) <6180> EV:L {CVaultStorePartitionCache::ReadEntry:#135} Successfully read partition info for Vault Store [10BE7FC86F12BCC47AB9CF4CB3F3AC84A1210000Archive]

154 16:04:10.039 [7656] (StorageDelete) <6180> EV:L {CStorageDeleterFactory::GetStorageDeviceDeleter} (Entry)

155 16:04:10.039 [7656] (StorageDelete) <6180> EV:M Sis part on vaultstoreIdentity 1, partition Id 3

156 16:04:10.039 [7656] (StorageDelete) <6180> EV:M CPartitionProperties::CPartitionProperties - vsId = [1], partitionId = [3]

157 16:04:10.039 [7656] (StorageDelete) <6180> EV:L {CStorageDeleterFactory::CreateNTFSStorageDeviceDeleter} (Entry)

158 16:04:10.039 [7656] (StorageDelete) <6180> EV:L {CVaultStorePartitionCache::ReadEntry:#135} Successfully read partition info for Vault Store [10BE7FC86F12BCC47AB9CF4CB3F3AC84A1210000Archive]

159 16:04:10.039 [7656] (StorageDelete) <6180> EV:M CPartitionProperties::CPartitionProperties - vsId = [1], partitionId = [3]

160 16:04:10.039 [7656] (StorageDelete) <6180> EV:L {CStorageDeleterFactory::CreateNTFSStorageDeviceDeleter} (Exit) Status: [Success]

161 16:04:10.039 [7656] (StorageDelete) <6180> EV:L {CStorageDeleterFactory::GetStorageDeviceDeleter} (Exit) Status: [Success]

162 16:04:10.039 [7656] (StorageDelete) <6180> EV:L {CSavesetSISManager::GetStorageDeviceDeleter} (Exit) Status: [Success]

163 16:04:10.039 [7656] (StorageDelete) <6180> EV:L {CNTFSStorageDeviceDeleter::DeleteSaveset} (Entry) VaultStoreId: 10BE7FC86F12BCC47AB9CF4CB3F3AC84A1210000Archive|VaultStoreName: Archive|SavesetId: 200901187992354~200811171625140000~Z~F5CF50B11DD24F888E61E5A27F795FE0|

164 16:04:10.039 [7656] (StorageDelete) <6180> EV:L CNTFSStorageDeviceDeleter::BuildExtensionlessSavesetFileName - SavesetIdentifier=[200901187992354~200811171625140000~Z~F5CF50B11DD24F888E61E5A27F795FE0], ExtensionlessFileName=[G:\ArchivePart04\2009\01-18\F\5CF\F5CF50B11DD24F888E61E5A27F795FE0]

165 16:04:10.039 [7656] (StorageDelete) <6180> EV:L {CNTFSStorageDeviceDeleter::Delete} (Entry) File name=[G:\ArchivePart04\2009\01-18\F\5CF\F5CF50B11DD24F888E61E5A27F795FE0.DVS]

166 16:04:10.039 [7656] (StorageDelete) <6180> EV:L {CWORMDeviceHelper::IsWORMDevice:#56} Device [is not] WORM.

167 16:04:10.039 [7656] (StorageDelete) <6180> EV:L EVCommon::DeleteFile : FileName = G:\ArchivePart04\2009\01-18\F\5CF\F5CF50B11DD24F888E61E5A27F795FE0.DVS

168 16:04:10.133 [7656] (StorageDelete) <6180> EV:L {CNTFSStorageDeviceDeleter::Delete} (Exit) Status: [False (0x1)]

169 16:04:10.133 [7656] (StorageDelete) <6180> EV:L {CNTFSStorageDeviceDeleter::DeleteSaveset} (Exit) Status: [False (0x1)]

170 16:04:10.133 [7656] (StorageDelete) <6180> EV:L {CSavesetSISManager::DeleteSaveset} (Exit) Status: [False (0x1)]

171 16:04:10.133 [7656] (StorageDelete) <6180> EV:L {CVault::DeleteSaveset} (Exit) Status: [False (0x1)]

172 16:04:10.133 [7656] (StorageDelete) <6180> EV:L {CStoreAccessor::DeleteItem} (Exit) Status: [Success]

[MichelZ · Accepted solution]

Hey

The log say that it's still deleting items. This can take some days depending on how much data you have. So just sit back and wait :)

Cheers
Michel

[yarg]

Hi,

Thanks for interpreting the log!  Patience is not a strong point for me, so I guess this is my chance to practise :)

Thanks again,
G

[GertjanA]

Be aware that deleting huge archives can take days... Look at this article: http://www.symantec.com/docs/TECH49804 It has some queries that show how many items are in the archive you are deleting. (and what happens in general). Running that query in the morning and evening will prove EV is deleting...

[yarg]

Thanks for your help guys; I was away for 4 days now and set 20 or so archives to delete before leaving.  They have now all gone, so I'm just going to do them in batches of 20 and wait.  The archives are big, with the smallest being about 5gb and the larger ones being 20gb+ 

Thanks again!
G