Guys,I started to use File Management Software (Enterprise Vault) to decrease number of files on Vault Partition, now all saveset files (DVS) are combined in larger collection files (CAB). It was used for long period of time without any problem. After new year I backuped first partition and closed it. All archiving is done now to new partition. EV version is 5.0 SP3 on Windows 2000.Yesterday one of the user complained on strange error. He opens archived item, but if he closes it and try to re-open ot he receives error - "there was an error opening the file. Some functionality may be unavailable."At the same time I notice errors in event log. Here they are:Event Type:ErrorEvent Source:Enterprise VaultEvent Category:Storage Online Event ID:6835Date:2/3/2006Time:2:22:03 PMUser:N/AComputer:KZATKVS01Description:Failed to extract CAB file. Reason: FDI Error. User aborted CAB File: \\kzatnas01\arc-Exc$\Ptn1\2004\10\05\Collection17902.CAB Event Type:ErrorEvent Source:Enterprise VaultEvent Category:Storage Online Event ID:6838Date:2/3/2006Time:2:22:03 PMUser:N/AComputer:KZATKVS01Description:Failed to recall a Saveset from its Collection. Reason: Failed to extract CAB file. Saveset: \\kzatnas01\arc-Exc$\Ptn1\2004\10\05\14\173000000000000~200410051433500000~0.ARCHDVS Collection: \\kzatnas01\arc-Exc$\Ptn1\2004\10\05\Collection17902.CAB Event Type:ErrorEvent Source:Enterprise VaultEvent Category:Storage Online Event ID:6811Date:2/3/2006Time:2:22:03 PMUser:N/AComputer:KZATKVS01Description:Failed to recall a Saveset from the HSM Software Component with ProgID EnterpriseVault.Collector Method : CVaultStoreDB::GetSavesetFilePath Data:0000: 46 61 69 6c 65 64 20 74 Failed t0008: 6f 20 65 78 74 72 61 63 o extrac0010: 74 20 43 41 42 20 66 69 t CAB fi0018: 6c 65 2e 20 20 5b 30 78 le. Some of users have such problem, some users have not. CAB files can not be an issue because DVS files are extracted when users open archived message. Any ideas?

Hi AskarThere is a hotfix for StorageOnlineOPNS on V5 SP3 that should resolve this issue. You will need to contact support to obtain this though. Alternatively you can look at upgrading to SP6.Cheers

When you browse to the network path from the EV server you see the archdvs files?Saveset: \\kzatnas01\arc-Exc$\Ptn1\2004\10\05\14\173000000000000~200410051433500000~0.ARCHDVS Can the user having the problem ever open an item in that date range?

Hi,That file might not exist. I'd check if you can open the .CAB file.

Guys,I already told that user can open the message when he tries to do it first time, but right after closing file and opening it again he receives an error and it is logged in event log.File exist, I can open CAB file and Extract this message.

Sorry Askar,Missed that part. :)Is there plenty of space on the closed partition?

Hi,I don't think this makes sense because after you view the first item then there is a temporary copy of the item itself on disk (ie not in CAB file) so it doesn't make sense that the 2nd viewing of the item is pulled from a CAB file and then goes on to cause an error.So I think what you'd need is dtrace of storageonlineopns when items are viewed by users so we can see exactly what's happening.

Failed to extract CAB file.

12 Replies

TonySterling
Moderator
19 years ago
When you browse to the network path from the EV server you see the archdvs files?

Saveset: \\kzatnas01\arc-Exc$\Ptn1\2004\10\05\14\173000000000000~200410051433500000~0.ARCHDVS

Can the user having the problem ever open an item in that date range?
Michael_Bilsbor
Level 6
19 years ago
Hi,

That file might not exist.
I'd check if you can open the .CAB file.
Askar_Kopbayev
Level 4
19 years ago
Guys,

I already told that user can open the message when he tries to do it first time, but right after closing file and opening it again he receives an error and it is logged in event log.

File exist, I can open CAB file and Extract this message.
TonySterling
Moderator
19 years ago
Sorry Askar,
Missed that part. :)

Is there plenty of space on the closed partition?
Michael_Bilsbor
Level 6
19 years ago
Hi,

I don't think this makes sense because after you view the first item then there is a temporary copy of the item itself on disk (ie not in CAB file) so it doesn't make sense that the 2nd viewing of the item is pulled from a CAB file and then goes on to cause an error.

So I think what you'd need is dtrace of storageonlineopns when items are viewed by users so we can see exactly what's happening.
Askar_Kopbayev
Level 4
19 years ago
2 The Dodo

What is dtrace?
Jason_Szeto
Level 6
19 years ago
Dtrace is a debugger utility for EV. It's dtrace.exe, and located in the Enterprise Vault folder. It's a cmd line utility. I believe once you run it, you can type help to list the commands in it. But here's what you should do,

Run dtrace.exe in a cmd windows.
type view
find the number corresponding to storageonlineopns
type set v
log d:\storageonlineopns.log

Once you type those commands, it will then monitor and log storageonlineopns so when you try to view an item, everything will be captured and you should see the error in the log.
Askar_Kopbayev
Level 4
19 years ago
I am sorry to put all this here, but I need your help.

I did dtrace at medium level and this is logged information for that time when user generated some errors openinig archived message for second, third and forth time.

It seems to me that on first attempt DVS is extracted from CAB file. But when user closes message the extracted DVS file is not deleted and is still open. That's why on the second attempt the extraction is failed. Am I right?

SELECT ArchivePointIdentity,ArchivedItems,ArchivedItemsSize,CreatedDate,MeanRecallTime,ModifiedDate,Recalls,Restores,VaultID,VaultIdentity FROM Vault where VaultIdentity = 10617
5985906:29:56.193 (StorageOnlineOpns)<5804>EV:MCVaultCache::ReadEntry Id:100C4BA702EB801479260B53401FB2FF01110000KZATKVS t/o:120 hr:0
5986006:29:56.193 (StorageOnlineOpns)<5804>EV:MGetPartitionRelatedInfo: PartitionID = 0
5986106:29:56.209 (StorageOnlineOpns)<5804>EV:MEVCollectorPublic : xmlFiles = 2005\03\24\12\870300000000000~200503241253540000~0.DVS|
5986206:29:56.224 (StorageOnlineOpns)<5804>EV:MEVCabinet (FDI.cpp) : Failed to set the LastAccessTime for file \\kzatnas01\arc-Exc$\Ptn1\2005\03\24\12\870300000000000~200503241253540000~0.ARCHDVS. hr = 80070020. GetLastError returned 32
5986306:29:56.240 (StorageOnlineOpns)<5804>EV:MEVCabinet (FDI.cpp) : Failed to create file \\kzatnas01\arc-Exc$\Ptn1\2005\03\24\12\870300000000000~200503241253540000~0.ARCHDVS during extract operation
5986406:29:56.240 (StorageOnlineOpns)<5804>EV:MEVCabinet (FDI.cpp) : SKIP_ABORT_IF_FAILED : hr = 80070006 : Number of files requested = 1
5986506:29:57.240 (StorageOnlineOpns)<5804>EV:MEVCabinet (FDI.cpp) : 2nd attempt
5986606:29:57.240 (StorageOnlineOpns)<5804>EV:MEVCabinet (FDI.cpp) : Failed to set the LastAccessTime for file \\kzatnas01\arc-Exc$\Ptn1\2005\03\24\12\870300000000000~200503241253540000~0.ARCHDVS. hr = 80070020. GetLastError returned 32
5986706:29:57.240 (StorageOnlineOpns)<5804>EV:MEVCabinet (FDI.cpp) : Failed to create file \\kzatnas01\arc-Exc$\Ptn1\2005\03\24\12\870300000000000~200503241253540000~0.ARCHDVS during extract operation
5986806:29:57.240 (StorageOnlineOpns)<5804>EV:MEVCabinet (FDI.cpp) : SKIP_ABORT_IF_FAILED : hr = 80070006 : Number of files requested = 1
5986906:29:57.240 (StorageOnlineOpns)<5804>EV:MEVCabinet (FDI.cpp) : FDICopy failed. hr = 80070006. User aborted
5987006:29:57.256 (StorageOnlineOpns)<5804>EV:MCStorageOnline::GetOnlineSaveset (Exit) |Exception occurred.
5987106:29:57.256 (StorageOnlineOpns)<5804>EV:MCQueue::~CQueue (Exit) |Exception occurred.
5987206:29:57.256 (StorageOnlineOpns)<5804>EV:MCQueue::~CQueue (Exit) |Exception occurred.
5987306:29:57.256 (StorageOnlineOpns)<5804>EV:MSingletonRegistry: US '0X18CE290TransactionContext'
5987406:29:57.256 (StorageOnlineOpns)<5804>EV:MSingletonRegistry: US '0X18CE2901'
5987506:29:57.256 (StorageOnlineOpns)<5804>EV:MSingletonRegistry: US '0X18CE290'
5987606:29:57.256 (StorageOnlineOpns)<5804>EV:MCVaultStore::ReleaseResources (Exit)
5987706:29:57.256 (StorageOnlineOpns)<5804>EV:MCVaultStoreSunSAMFS::Disconnect (Exit) |The operation completed successfully.
5987806:29:57.256 (StorageOnlineOpns)<5804>EV:MCVaultStoreSunSAMFS::ClearConnection (Exit) |The operation completed successfully.
5987906:29:57.256 (StorageOnlineOpns)<5804>EV:MCVaultStoreEMCCentera::FreeFPLibrary (Exit) |The operation completed successfully.
5988006:29:57.256 (StorageOnlineOpns)<5804>EV:MCVaultStoreEMCCentera::ClearPool (Exit) |The operation completed successfully.
5988106:29:57.568 (StorageOnlineOpns)<5140>EV:MEVCommon : DeleteFile encountered a sharing violation. The thread will sleep and then retry
5988206:29:57.568 (StorageOnlineOpns)<5140>EV:MEVCommon : DeleteFile is going to sleep for 3000 milliseconds
5988306:30:00.568 (StorageOnlineOpns)<5140>EV:MEVCommon : DeleteFile encountered a sharing violation. The thread will sleep and then retry
5988406:30:00.568 (StorageOnlineOpns)<5140>EV:MEVCommon : DeleteFile is going to sleep for 3000 milliseconds
5988506:30:02.459 (StorageOnlineOpns)<6124>EV:HCStorageOnline::ReadRegistryData|CStorageOnline options|m_dontReplyAndDontDeleteFromIndex : 0|m_IndexRetryCount : 5|m_IndexRetryWait : 5000|m_FolderNoReadCheckOnlyArchiveCheck : 0|m_CheckVaultReadRights : 1|m_EnableVSDBLookup : 1
5988606:30:02.459 (StorageOnlineOpns)<6124>EV:MCStorageOnline::ReadRegistryData (Exit)
5988706:30:02.459 (StorageOnlineOpns)<6124>EV:HCStorageOnline::CStorageOnline|Object settings|NoReplyNoDeleteFromIndex? FALSE|IndexRetryCount: 5|IndexRetryWait (millisecs): 5000|FolderNoReadCheckOnlyArchiveCheck? FALSE
5988806:30:02.459 (StorageOnlineOpns)<6124>EV:MCStorageOnline::CStorageOnline (Exit)
5988906:30:02.459 (StorageOnlineOpns)<6124>EV:MCQueue::CQueue (Exit)
5989006:30:02.459 (StorageOnlineOpns)<6124>EV:MCQueue::CQueue (Exit)
5989106:30:02.459 (StorageOnlineOpns)<6124>EV:HCStorageOnline::GetOnlineSaveset|Get Online Saveset|SavesetID: 870300000000000~200503241253540000~0~19DC7AB0814049C2AFF693C85ED2945|Untrusted VaultID: 100C4BA702EB801479260B53401FB2FF01110000KZATKVS|QueueFormatName:
5989206:30:02.474 (StorageOnlineOpns)<6124>EV:MCVaultStore::SelectVaultStore|Vault Id: 100C4BA702EB801479260B53401FB2FF01110000KZATKVS|Cached VaultId:
5989306:30:02.474 (StorageOnlineOpns)<6124>EV:MCVaultCache::ReadEntry Id:100C4BA702EB801479260B53401FB2FF01110000KZATKVS hr:0
5989406:30:02.474 (StorageOnlineOpns)<6124>EV:MCVaultStorePartitionCache::ReadEntry Id:19E346B0F48342B4698A5EBD167B044B31210000KZATKVS hr:0
5989506:30:02.474 (StorageOnlineOpns)<6124>EV:MCSSASCache::ReadEntry Id:112D51C2DFAEF4C4D96B7208C27CE9D061e10000KZATKVS hr:0
5989606:30:02.474 (StorageOnlineOpns)<6124>EV:MCVaultParameters::GetSiteVaultParams (Exit) |The operation completed successfully.
5989706:30:02.474 (StorageOnlineOpns)<6124>EV:MCVaultParameters::GetVSVaultParams (Exit) |The operation completed successfully.
5989806:30:02.474 (StorageOnlineOpns)<6124>EV:MCVaultParameters::GetVaultParams (Exit) |The operation completed successfully.
5989906:30:02.474 (StorageOnlineOpns)<6124>EV:MCVaultStorePartitionCache::ReadEntry Id:19E346B0F48342B4698A5EBD167B044B31210000KZATKVS hr:0
5990006:30:02.474 (StorageOnlineOpns)<6124>EV:MCVaultStore::SetupVaultStoreDevice NTFS
5990106:30:02.474 (StorageOnlineOpns)<6124>EV:MCVaultStore::SelectVaultStore (Exit) |The operation completed successfully.
5990206:30:02.474 (StorageOnlineOpns)<6124>EV:MCDatabaseService::CheckDSN hr=0 DSN:EVMailboxVaultstore DbName:EVMailboxVaultstore Server:KZATSQL05
5990306:30:02.474 (StorageOnlineOpns)<6124>EV:MCVaultStore::GetVaultStoreDbInfo (Exit) |The operation completed successfully.
5990406:30:02.474 (StorageOnlineOpns)<6124>EV:MSingletonRegistry: RS '0X18CE290TransactionContext'
5990506:30:02.474 (StorageOnlineOpns)<6124>EV:MSingletonRegistry: RS '0X18CE2901'
5990606:30:02.474 (StorageOnlineOpns)<6124>EV:MSingletonRegistry: RS '0X18CE290'
5990706:30:02.474 (StorageOnlineOpns)<6124>EV:MCVaultStore::GetVaultStoreInfo (Exit)
5991006:30:02.474 (StorageOnlineOpns)<6124>EV:MSQLSvrDBBroker::BuildSQL : SELECT 'CollectionIdentity'=SS.CollectionIdentity,'IdPartition'=SS.IdPartition,ArchivePointIdentity,ArchivedDate,FileFormat,HSMType,IdChecksumHigh,IdChecksumLow,IdDateTime,IdTransaction,IdUniqueNo,IndexSeqNo,ItemSize,RefCount,RelativeFileName,RetentionCategoryIdentity,StoreIdentifier,TotalCount,VaultIdentity FROM Saveset SS LEFT OUTER JOIN SavesetStore SSS ON SS.SavesetIdentity = SSS.SavesetIdentity LEFT OUTER JOIN Collection COL ON SS.CollectionIdentity = COL.CollectionIdentity WHERE IdTransaction = '19DC7AB0-8140-49C2-AFF6-93C85ED29450'
5991106:30:02.490 (StorageOnlineOpns)<6124>EV:MDeviceTypeFromPartID: PartID = 0
5991206:30:02.490 (StorageOnlineOpns)<6124>EV:MDeviceTypeFromPartID: PartID 0 is NTFS
5991306:30:02.490 (StorageOnlineOpns)<6124>EV:MReadSavesetInformation : 870300000000000~200503241253540000~0~19DC7AB0814049C2AFF693C85ED2945 Collection : 17173
5991406:30:02.490 (StorageOnlineOpns)<6124>EV:MDeviceTypeFromPartID: PartID = 0
5991506:30:02.490 (StorageOnlineOpns)<6124>EV:MDeviceTypeFromPartID: PartID 0 is NTFS
5991606:30:02.490 (StorageOnlineOpns)<6124>EV:MGetSavesetFilename : 870300000000000~200503241253540000~0~19DC7AB0814049C2AFF693C85ED2945 collection : 17173
5991906:30:02.490 (StorageOnlineOpns)<6124>EV:MSQLSvrDBBroker::BuildSQL : SELECT ArchivePointIdentity,ArchivedItems,ArchivedItemsSize,CreatedDate,MeanRecallTime,ModifiedDate,Recalls,Restores,VaultID,VaultIdentity FROM Vault where VaultIdentity = 10617
5992006:30:02.490 (StorageOnlineOpns)<6124>EV:MCVaultCache::ReadEntry Id:100C4BA702EB801479260B53401FB2FF01110000KZATKVS t/o:120 hr:0
5992106:30:02.490 (StorageOnlineOpns)<6124>EV:MGetPartitionRelatedInfo: PartitionID = 0
5992206:30:02.490 (StorageOnlineOpns)<6124>EV:MEVCollectorPublic : xmlFiles = 2005\03\24\12\870300000000000~200503241253540000~0.DVS|
5992306:30:02.506 (StorageOnlineOpns)<6124>EV:MEVCabinet (FDI.cpp) : Failed to set the LastAccessTime for file \\kzatnas01\arc-Exc$\Ptn1\2005\03\24\12\870300000000000~200503241253540000~0.ARCHDVS. hr = 80070020. GetLastError returned 32
5992406:30:02.506 (StorageOnlineOpns)<6124>EV:MEVCabinet (FDI.cpp) : Failed to create file \\kzatnas01\arc-Exc$\Ptn1\2005\03\24\12\870300000000000~200503241253540000~0.ARCHDVS during extract operation
5992506:30:02.506 (StorageOnlineOpns)<6124>EV:MEVCabinet (FDI.cpp) : SKIP_ABORT_IF_FAILED : hr = 80070006 : Number of files requested = 1
5992606:30:03.506 (StorageOnlineOpns)<6124>EV:MEVCabinet (FDI.cpp) : 2nd attempt
5992706:30:03.506 (StorageOnlineOpns)<6124>EV:MEVCabinet (FDI.cpp) : Failed to set the LastAccessTime for file \\kzatnas01\arc-Exc$\Ptn1\2005\03\24\12\870300000000000~200503241253540000~0.ARCHDVS. hr = 80070020. GetLastError returned 32
5992806:30:03.506 (StorageOnlineOpns)<6124>EV:MEVCabinet (FDI.cpp) : Failed to create file \\kzatnas01\arc-Exc$\Ptn1\2005\03\24\12\870300000000000~200503241253540000~0.ARCHDVS during extract operation
5992906:30:03.506 (StorageOnlineOpns)<6124>EV:MEVCabinet (FDI.cpp) : SKIP_ABORT_IF_FAILED : hr = 80070006 : Number of files requested = 1
5993006:30:03.506 (StorageOnlineOpns)<6124>EV:MEVCabinet (FDI.cpp) : FDICopy failed. hr = 80070006. User aborted
5993106:30:03.521 (StorageOnlineOpns)<6124>EV:MCStorageOnline::GetOnlineSaveset (Exit) |Exception occurred.
5993206:30:03.521 (StorageOnlineOpns)<6124>EV:MCQueue::~CQueue (Exit) |Exception occurred.
5993306:30:03.521 (StorageOnlineOpns)<6124>EV:MCQueue::~CQueue (Exit) |Exception occurred.
5993406:30:03.521 (StorageOnlineOpns)<6124>EV:MSingletonRegistry: US '0X18CE290TransactionContext'
5993506:30:03.521 (StorageOnlineOpns)<6124>EV:MSingletonRegistry: US '0X18CE2901'
5993606:30:03.521 (StorageOnlineOpns)<6124>EV:MSingletonRegistry: US '0X18CE290'
5993706:30:03.521 (StorageOnlineOpns)<6124>EV:MCVaultStore::ReleaseResources (Exit)
5993806:30:03.521 (StorageOnlineOpns)<6124>EV:MCVaultStoreSunSAMFS::Disconnect (Exit) |The operation completed successfully.
5993906:30:03.521 (StorageOnlineOpns)<6124>EV:MCVaultStoreSunSAMFS::ClearConnection (Exit) |The operation completed successfully.
5994006:30:03.521 (StorageOnlineOpns)<6124>EV:MCVaultStoreEMCCentera::FreeFPLibrary (Exit) |The operation completed successfully.
5994106:30:03.521 (StorageOnlineOpns)<6124>EV:MCVaultStoreEMCCentera::ClearPool (Exit) |The operation completed successfully.
5994206:30:03.568 (StorageOnlineOpns)<5140>EV:MEVCommon : DeleteFile encountered a sharing violation. The thread will sleep and then retry
5994306:30:03.568 (StorageOnlineOpns)<5140>EV:MEVCommon : DeleteFile is going to sleep for 3000 milliseconds
5994406:30:06.568 (StorageOnlineOpns)<5140>EV:MEVCommon : DeleteFile encountered a sharing violation. The thread will sleep and then retry
5994506:30:06.568 (StorageOnlineOpns)<5140>EV:MEVCommon : DeleteFile is going to sleep for 3000 milliseconds
5994606:30:08.240 (StorageOnlineOpns)<5804>EV:HCStorageOnline::ReadRegistryData|CStorageOnline options|m_dontReplyAndDontDeleteFromIndex : 0|m_IndexRetryCount : 5|m_IndexRetryWait : 5000|m_FolderNoReadCheckOnlyArchiveCheck : 0|m_CheckVaultReadRights : 1|m_EnableVSDBLookup : 1
5994706:30:08.240 (StorageOnlineOpns)<5804>EV:MCStorageOnline::ReadRegistryData (Exit)
5994806:30:08.240 (StorageOnlineOpns)<5804>EV:HCStorageOnline::CStorageOnline|Object settings|NoReplyNoDeleteFromIndex? FALSE|IndexRetryCount: 5|IndexRetryWait (millisecs): 5000|FolderNoReadCheckOnlyArchiveCheck? FALSE
5994906:30:08.240 (StorageOnlineOpns)<5804>EV:MCStorageOnline::CStorageOnline (Exit)
5995006:30:08.256 (StorageOnlineOpns)<5804>EV:MCQueue::CQueue (Exit)
5995106:30:08.256 (StorageOnlineOpns)<5804>EV:MCQueue::CQueue (Exit)
5995206:30:08.256 (StorageOnlineOpns)<5804>EV:HCStorageOnline::GetOnlineSaveset|Get Online Saveset|SavesetID: 870300000000000~200503241253540000~0~19DC7AB0814049C2AFF693C85ED2945|Untrusted VaultID: 100C4BA702EB801479260B53401FB2FF01110000KZATKVS|QueueFormatName:
5995306:30:08.256 (StorageOnlineOpns)<5804>EV:MCVaultStore::SelectVaultStore|Vault Id: 100C4BA702EB801479260B53401FB2FF01110000KZATKVS|Cached VaultId:
5995406:30:08.256 (StorageOnlineOpns)<5804>EV:MCVaultCache::ReadEntry Id:100C4BA702EB801479260B53401FB2FF01110000KZATKVS hr:0
5995506:30:08.256 (StorageOnlineOpns)<5804>EV:MCVaultStorePartitionCache::ReadEntry Id:19E346B0F48342B4698A5EBD167B044B31210000KZATKVS hr:0
5995706:30:08.256 (StorageOnlineOpns)<5804>EV:MCBaseDirectoryServiceWrapper::CreateDirectoryService(): Using local directory connection
5997306:30:08.287 (StorageOnlineOpns)<5804>EV:MCSSASCache::LoadEntry Service EntryId:112D51C2DFAEF4C4D96B7208C27CE9D061e10000KZATKVS hr:0
5997506:30:08.287 (StorageOnlineOpns)<5804>EV:MCBaseDirectoryServiceWrapper::CreateDirectoryService(): Using local directory connection
5999306:30:08.318 (StorageOnlineOpns)<5804>EV:MCSSASCache::LoadEntry Site EntryId:16F4C2A0C32FEBD479B13359C1516D55F1d10000KZATKVS hr:0
5999406:30:08.318 (StorageOnlineOpns)<5804>EV:MCSSASCache::ReadEntry Id:112D51C2DFAEF4C4D96B7208C27CE9D061e10000KZATKVS hr:0
5999506:30:08.318 (StorageOnlineOpns)<5804>EV:MCVaultParameters::GetSiteVaultParams (Exit) |The operation completed successfully.
5999606:30:08.318 (StorageOnlineOpns)<5804>EV:MCVaultParameters::GetVSVaultParams (Exit) |The operation completed successfully.
5999706:30:08.318 (StorageOnlineOpns)<5804>EV:MCVaultParameters::GetVaultParams (Exit) |The operation completed successfully.
5999806:30:08.318 (StorageOnlineOpns)<5804>EV:MCVaultStorePartitionCache::ReadEntry Id:19E346B0F48342B4698A5EBD167B044B31210000KZATKVS hr:0
5999906:30:08.318 (StorageOnlineOpns)<5804>EV:MCVaultStore::SetupVaultStoreDevice NTFS
6000006:30:08.318 (StorageOnlineOpns)<5804>EV:MCVaultStore::SelectVaultStore (Exit) |The operation completed successfully.
6000106:30:08.318 (StorageOnlineOpns)<5804>EV:MCDatabaseService::CheckDSN hr=0 DSN:EVMailboxVaultstore DbName:EVMailboxVaultstore Server:KZATSQL05
6000206:30:08.318 (StorageOnlineOpns)<5804>EV:MCVaultStore::GetVaultStoreDbInfo (Exit) |The operation completed successfully.
6000306:30:08.318 (StorageOnlineOpns)<5804>EV:MSingletonRegistry: RS '0X18CE290TransactionContext'
6000406:30:08.318 (StorageOnlineOpns)<5804>EV:MSingletonRegistry: RS '0X18CE2901'
6000506:30:08.318 (StorageOnlineOpns)<5804>EV:MSingletonRegistry: RS '0X18CE290'
6000606:30:08.318 (StorageOnlineOpns)<5804>EV:MCVaultStore::GetVaultStoreInfo (Exit)
6000906:30:08.318 (StorageOnlineOpns)<5804>EV:MSQLSvrDBBroker::BuildSQL : SELECT 'CollectionIdentity'=SS.CollectionIdentity,'IdPartition'=SS.IdPartition,ArchivePointIdentity,ArchivedDate,FileFormat,HSMType,IdChecksumHigh,IdChecksumLow,IdDateTime,IdTransaction,IdUniqueNo,IndexSeqNo,ItemSize,RefCount,RelativeFileName,RetentionCategoryIdentity,StoreIdentifier,TotalCount,VaultIdentity FROM Saveset SS LEFT OUTER JOIN SavesetStore SSS ON SS.SavesetIdentity = SSS.SavesetIdentity LEFT OUTER JOIN Collection COL ON SS.CollectionIdentity = COL.CollectionIdentity WHERE IdTransaction = '19DC7AB0-8140-49C2-AFF6-93C85ED29450'
6001006:30:08.334 (StorageOnlineOpns)<5804>EV:MDeviceTypeFromPartID: PartID = 0
6001106:30:08.334 (StorageOnlineOpns)<5804>EV:MDeviceTypeFromPartID: PartID 0 is NTFS
6001206:30:08.334 (StorageOnlineOpns)<5804>EV:MReadSavesetInformation : 870300000000000~200503241253540000~0~19DC7AB0814049C2AFF693C85ED2945 Collection : 17173
6001306:30:08.334 (StorageOnlineOpns)<5804>EV:MDeviceTypeFromPartID: PartID = 0
6001406:30:08.334 (StorageOnlineOpns)<5804>EV:MDeviceTypeFromPartID: PartID 0 is NTFS
6001506:30:08.334 (StorageOnlineOpns)<5804>EV:MGetSavesetFilename : 870300000000000~200503241253540000~0~19DC7AB0814049C2AFF693C85ED2945 collection : 17173
6001806:30:08.334 (StorageOnlineOpns)<5804>EV:MSQLSvrDBBroker::BuildSQL : SELECT ArchivePointIdentity,ArchivedItems,ArchivedItemsSize,CreatedDate,MeanRecallTime,ModifiedDate,Recalls,Restores,VaultID,VaultIdentity FROM Vault where VaultIdentity = 10617
6001906:30:08.334 (StorageOnlineOpns)<5804>EV:MCVaultCache::ReadEntry Id:100C4BA702EB801479260B53401FB2FF01110000KZATKVS t/o:120 hr:0
6002006:30:08.334 (StorageOnlineOpns)<5804>EV:MGetPartitionRelatedInfo: PartitionID = 0
6002106:30:08.334 (StorageOnlineOpns)<5804>EV:MEVCollectorPublic : xmlFiles = 2005\03\24\12\870300000000000~200503241253540000~0.DVS|
6002206:30:08.349 (StorageOnlineOpns)<5804>EV:MEVCabinet (FDI.cpp) : Failed to set the LastAccessTime for file \\kzatnas01\arc-Exc$\Ptn1\2005\03\24\12\870300000000000~200503241253540000~0.ARCHDVS. hr = 80070020. GetLastError returned 32
6002306:30:08.349 (StorageOnlineOpns)<5804>EV:MEVCabinet (FDI.cpp) : Failed to create file \\kzatnas01\arc-Exc$\Ptn1\2005\03\24\12\870300000000000~200503241253540000~0.ARCHDVS during extract operation
6002406:30:08.349 (StorageOnlineOpns)<5804>EV:MEVCabinet (FDI.cpp) : SKIP_ABORT_IF_FAILED : hr = 80070006 : Number of files requested = 1
6002506:30:09.349 (StorageOnlineOpns)<5804>EV:MEVCabinet (FDI.cpp) : 2nd attempt
6002606:30:09.349 (StorageOnlineOpns)<5804>EV:MEVCabinet (FDI.cpp) : Failed to set the LastAccessTime for file \\kzatnas01\arc-Exc$\Ptn1\2005\03\24\12\870300000000000~200503241253540000~0.ARCHDVS. hr = 80070020. GetLastError returned 32
6002706:30:09.349 (StorageOnlineOpns)<5804>EV:MEVCabinet (FDI.cpp) : Failed to create file \\kzatnas01\arc-Exc$\Ptn1\2005\03\24\12\870300000000000~200503241253540000~0.ARCHDVS during extract operation
6002806:30:09.349 (StorageOnlineOpns)<5804>EV:MEVCabinet (FDI.cpp) : SKIP_ABORT_IF_FAILED : hr = 80070006 : Number of files requested = 1
6002906:30:09.349 (StorageOnlineOpns)<5804>EV:MEVCabinet (FDI.cpp) : FDICopy failed. hr = 80070006. User aborted
6003006:30:09.365 (StorageOnlineOpns)<5804>EV:MCStorageOnline::GetOnlineSaveset (Exit) |Exception occurred.
6003106:30:09.365 (StorageOnlineOpns)<5804>EV:MCQueue::~CQueue (Exit) |Exception occurred.
6003206:30:09.365 (StorageOnlineOpns)<5804>EV:MCQueue::~CQueue (Exit) |Exception occurred.
6003306:30:09.365 (StorageOnlineOpns)<5804>EV:MSingletonRegistry: US '0X18CE290TransactionContext'
6003406:30:09.381 (StorageOnlineOpns)<5804>EV:MSingletonRegistry: US '0X18CE2901'
6003506:30:09.381 (StorageOnlineOpns)<5804>EV:MSingletonRegistry: US '0X18CE290'
6003606:30:09.381 (StorageOnlineOpns)<5804>EV:MCVaultStore::ReleaseResources (Exit)
6003706:30:09.381 (StorageOnlineOpns)<5804>EV:MCVaultStoreSunSAMFS::Disconnect (Exit) |The operation completed successfully.
6003806:30:09.381 (StorageOnlineOpns)<5804>EV:MCVaultStoreSunSAMFS::ClearConnection (Exit) |The operation completed successfully.
6003906:30:09.381 (StorageOnlineOpns)<5804>EV:MCVaultStoreEMCCentera::FreeFPLibrary (Exit) |The operation completed successfully.
6004006:30:09.381 (StorageOnlineOpns)<5804>EV:MCVaultStoreEMCCentera::ClearPool (Exit) |The operation completed successfully.
6004106:30:09.568 (StorageOnlineOpns)<5140>EV:MEVCommon : DeleteFile encountered a sharing violation. The thread will sleep and then retry
6004206:30:09.568 (StorageOnlineOpns)<5140>EV:MEVCommon : DeleteFile is going to sleep for 3000 milliseconds
6004306:30:12.568 (StorageOnlineOpns)<5140>EV:MEVCommon : DeleteFile encountered a sharing violation. The thread will sleep and then retry
6004406:30:12.568 (StorageOnlineOpns)<5140>EV:MEVCommon : DeleteFile is going to sleep for 3000 milliseconds
Askar_Kopbayev
Level 4
19 years ago
C'mon, guys, I know it is not easy to read all of this, but it is a good challenge :)

I really need your advice.
Micah_Wyenn_2
Level 6
19 years ago
okay...disclaimer...I totally don't know what I'm doing when it comes to dtrace files. But as you suggested I did read through the beast. Here's my take...

The retrieval service is having permission problems accessing this volume: \\kzatnas01\arc-Exc$\Ptn1\

I'd check that there was enough space (at least 1gb) available, and the vault service account had the local admin privs for that partition. Those would be my first steps.

micah