Forum Discussion

Askar_Kopbayev's avatar
Askar_Kopbayev
Level 4
19 years ago

Failed to extract CAB file.

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:Error
Event Source:Enterprise Vault
Event Category:Storage Online
Event ID:6835
Date:2/3/2006
Time:2:22:03 PM
User:N/A
Computer:KZATKVS01
Description:
Failed to extract CAB file.

Reason: FDI Error. User aborted
CAB File: \\kzatnas01\arc-Exc$\Ptn1\2004\10\05\Collection17902.CAB


Event Type:Error
Event Source:Enterprise Vault
Event Category:Storage Online
Event ID:6838
Date:2/3/2006
Time:2:22:03 PM
User:N/A
Computer:KZATKVS01
Description:
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:Error
Event Source:Enterprise Vault
Event Category:Storage Online
Event ID:6811
Date:2/3/2006
Time:2:22:03 PM
User:N/A
Computer:KZATKVS01
Description:
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 t
0008: 6f 20 65 78 74 72 61 63 o extrac
0010: 74 20 43 41 42 20 66 69 t CAB fi
0018: 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?
Enterprise Vault
Information Governance
  • Tremaine's avatar
    Tremaine
    19 years ago
    Hi Askar

    There 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
  • TonySterling's avatar
    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?
  • Askar_Kopbayev's avatar
    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's avatar
    TonySterling
    Moderator
    19 years ago
    Sorry Askar,
    Missed that part. :)

    Is there plenty of space on the closed partition?
  • Michael_Bilsbor's avatar
    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.
  • Jason_Szeto's avatar
    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's avatar
    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's avatar
    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's avatar
    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