cancel
Showing results for 
Search instead for 
Did you mean: 

Event ID 3310

Nicola_Rositani
Level 5
Hi all,
In my Enteprise Vault Server I can see sometime this error event log:
Event ID: 3310
Category: Archive Task

There was a problem accessing a network service or resource. The dispenser will re-queue the current item and sleep for 10 minute(s).
Task: Exchange Mailbox Archiving Task for MYEXCHANGESERVER

Can I reduce the time to retry tha task? 3-5 minutes for example..

Thanks all in advance
Regards


1 ACCEPTED SOLUTION

Accepted Solutions

Wayne_Humphrey
Level 6
Partner Accredited Certified
Nicola,

Try setting the Closest GC, this is part of my standard "Defaults.reg"

THE SID needs to be that of the VSA, and needs to be on each Enterprise Vault Server wehere the Agent is on.
Windows Registry Editor Version 5.00
[HKEY_USERS\S-1-5-21-1242909238-215958010-794563710-203157\Software\Microsoft\Exchange\Exchange Provider]
"Closest GC"=dword:00000001

--wayne

View solution in original post

24 REPLIES 24

Paul_Grimshaw
Level 6
Employee Accredited Certified

3310 Error is a little blighter of an error as it can cover so many different issues and is a very generic error as you can see from the error description. If you are constantly seeing this error every 10 minutes then we need to investigate and what I would need to see is the output of a DTRACE of the archivetask process whilst the error is generated.

Usually 3310 is an indication of a loss of MAPI resource and can be solved sometimes simply by restarting the archiving task but this is not always the case. If a restart does not work then follow the MAPI troubleshooting guide that you can find in the articles section of this website. If after restarting, MAPI troubleshooting you still get the error every 10 minutes then post the DTRACE,

If you only see this error every now and again then you can safely ignore it

Nicola_Rositani
Level 5
Sometime I get this error 3-4 times in a day, sometime I can't see this error for a week....

Paul_Grimshaw
Level 6
Employee Accredited Certified

OK so if you woud like to futher troubleshoot then I would recommend setting up a dtrace of the archivetask process and putting in a trigger on 3310. There are technotes out there explaining how to do this if you do not already. Then you can post the log data and we will see where we can go from there.

AndrewB
Moderator
Moderator
Partner    VIP    Accredited
Just wanted to chime in and let you know my experience with evil event ID 3310. After many frustrating hours of online research, following all related Symantec articles, troubleshooting, etc, it turned out to be caused by a corrupt message in the A2 queue.
Had to manually restart the mailbox archiving task over and over to get the queue down to about a dozen and then purged it. I took a screenshot of the queue and then manually archived the individual messages by hand. Out of the 12, I was able to find 10 of them. Not sure about the missing two but I bet one of them was the culprit.

Paul_Grimshaw
Level 6
Employee Accredited Certified
Yep that is one of the many :)

Nicola_Rositani
Level 5
Thanks AndrewB,
but in my case I can't see any pending items in message queueing!
I put dtrace log as soon as possible

Nicola_Rositani
Level 5
Using dtrace do I enable only 4-ArchiveTask Detail?

Paul_Grimshaw
Level 6
Employee Accredited Certified
Correct yes and you may want to intriduce a trigger as shown in the following technote otherwise the log will grow rather large:-

http://support.veritas.com/docs/284344


Try setting a trigger on 3310

Nicola_Rositani
Level 5
Thanks Paul, I've apply trigger correctly

Nicola_Rositani
Level 5
I've just capture watch log:

2395409 12:23:01.994 [4060] (ArchiveTask) <5956> EV:M CSavesetPersist::Save Stream: 0xa4a5620 ClearDirty: False
2395410 12:23:01.994 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg2::IsSavesetUnicode (Entry)
2395411 12:23:01.994 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg2::IsSavesetUnicode (Exit). hr=Success [0]
2395412 12:23:01.994 [4060] (ArchiveTask) <5956> EV:M CSaveset2::get_NumSharers_Threshold (Entry)
2395413 12:23:01.994 [4060] (ArchiveTask) <5956> EV:M CSaveset2::get_NumSharers_Threshold (Exit). hr=Success [0]
2395414 12:23:01.994 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg::get_ContentHandlingName (Entry)
2395415 12:23:01.994 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg::ReadStreamedPropertyBin (Entry)
2395416 12:23:01.994 [4060] (ArchiveTask) <5956> EV:H CSavesetOnIStg::ReadStreamedPropertyBin _com_error exception reading streamed property.|Property Name: CHGuid|hr=%1 could not be found. [0x80030002]
2395417 12:23:01.994 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg::ReadStreamedPropertyBin (Exit). hr=%1 could not be found. [0x80030002]
2395418 12:23:01.994 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg::get_ContentHandlingName (Exit). hr=False [0x1]
2395419 12:23:01.994 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg::get_IndexableItemSize (Entry)
2395420 12:23:01.994 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg::OpenIndexableItem (Entry)
2395421 12:23:01.994 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg::OpenSubStg (Exit). hr=%1 could not be found. [0x80030002]
2395422 12:23:01.994 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg::OpenIndexableItem (Exit). hr=%1 could not be found. [0x80030002]
2395423 12:23:01.994 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg::get_IndexableItemSize (Exit). hr=False [0x1]
2395424 12:23:01.994 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg::get_SISPartMap (Entry)
2395425 12:23:01.994 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg::ReadStreamedPropertyAsStream (Entry)
2395426 12:23:01.994 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg::ReadStreamedPropertyToStream (Entry)
2395427 12:23:01.994 [4060] (ArchiveTask) <5956> EV:H CSavesetOnIStg::ReadStreamedPropertyToStream _com_error exception reading streamed property.|Property Name: SISPartMap|hr=%1 could not be found. [0x80030002]
2395428 12:23:01.994 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg::ReadStreamedPropertyToStream (Exit). hr=%1 could not be found. [0x80030002]
2395429 12:23:01.994 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg::ReadStreamedPropertyAsStream (Exit). hr=Success [0]
2395430 12:23:01.994 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg::get_SISPartMap (Exit). hr=Success [0]
2395431 12:23:01.994 [4060] (ArchiveTask) <5956> EV:L CSavesetPersist::PackSaveset Saveset is Unicode: True, Content Handling Name: (null) IndexableItem present: False
2395432 12:23:01.994 [4060] (ArchiveTask) <5956> EV:L [class CIStreamSequentialStream] SetPosition: 0 hr:0x0
2395433 12:23:01.994 [4060] (ArchiveTask) <5956> EV:L [class CIStreamSequentialStream] Position: 0 hr:0x0
2395434 12:23:01.994 [4060] (ArchiveTask) <5956> EV:L [class CIStreamSequentialStream] Position: 0 hr:0x0
2395435 12:23:01.994 [4060] (ArchiveTask) <5956> EV:L [class CIStreamSequentialStream] Position: 17 hr:0x0
2395436 12:23:01.994 [4060] (ArchiveTask) <5956> EV:L [class CILockBytesSequentialStream] Position: 0 hr:0x0
2395437 12:23:01.994 [4060] (ArchiveTask) <5956> EV:L [class CIStreamSequentialStream] Position: 17 hr:0x0
2395438 12:23:01.994 [4060] (ArchiveTask) <5956> EV:L [class CILockBytesSequentialStream] Length:38912 hr:0x0
2395439 12:23:01.994 [4060] (ArchiveTask) <5956> EV:L [class CIStreamSequentialStream] Length:17 hr:0x0
2395440 12:23:01.994 [4060] (ArchiveTask) <5956> EV:L [class CIStreamSequentialStream] SetCapacity:11301 hr:0x0
2395441 12:23:01.994 [4060] (ArchiveTask) <5956> EV:L [class CIStreamSequentialStream] Position: 17 hr:0x0
2395442 12:23:02.009 [4060] (ArchiveTask) <5880> EV:H :CArchivingAgent::PostProcessArchivedItem() |Return the MAPI session to the session pool |
2395443 12:23:02.009 [4060] (ArchiveTask) <5880> EV:M CArchivingAgent::IncrementOpsLeadingToRecycle - Current Count [0x00000E10] Recycle at [0x0000C350]|
2395444 12:23:02.009 [4060] (ArchiveTask) <5956> EV:L [class CIStreamSequentialStream] Position: 9483 hr:0x0
2395445 12:23:02.009 [4060] (ArchiveTask) <5880> EV:H :CArchivingAgent::PostProcessArchivedItem() |Exiting routine |
2395446 12:23:02.009 [4060] (ArchiveTask) <5956> EV:L [class CIStreamSequentialStream] SetLength:9483 hr:0x0
2395447 12:23:02.009 [4060] (ArchiveTask) <5880> EV:H CArchivingAgent::PostProcessArchivedItem Time Taken to Post Process Item - 200907220000000~200807071521190000~Z~614C46BE9176ABE860B3AB4637A8D061: 0.062444 secs
2395448 12:23:02.009 [4060] (ArchiveTask) <5956> EV:L [class CIStreamSequentialStream] Position: 9483 hr:0x0
2395449 12:23:02.009 [4060] (ArchiveTask) <5956> EV:L [class CIStreamSequentialStream] SetPosition: 17 hr:0x0
2395450 12:23:02.009 [4060] (ArchiveTask) <5956> EV:L [class CIStreamSequentialStream] SetPosition: 9483 hr:0x0
2395451 12:23:02.009 [4060] (ArchiveTask) <5956> EV:L [class CIStreamSequentialStream] Flush hr:0x0
2395452 12:23:02.009 [4060] (ArchiveTask) <5956> EV:M [CCompressor] Compress|Type:class CZlibCompressor|Header: Y|Fat: 38912|Thin: 9454|Thin guess good: 11301)|CRC: 3268035671
2395453 12:23:02.009 [4060] (ArchiveTask) <5880> EV:H :AgentMessageDispenser::ActivateObject() |Exiting routine at point A |
2395454 12:23:02.009 [4060] (ArchiveTask) <5956> EV:M CSavesetPersist::PackSavesetBytes Completed compression step. hr=Success [0]|Uncompressed size:38912 Compressed size:9483 Elapsed:0.02 (secs)
2395455 12:23:02.009 [4060] (ArchiveTask) <5956> EV:L [class CIStreamSequentialStream] SetPosition: 0 hr:0x0
2395456 12:23:02.009 [4060] (ArchiveTask) <5956> EV:L [class CIStreamSequentialStream] Position: 0 hr:0x0
2395457 12:23:02.009 [4060] (ArchiveTask) <5956> EV:L [class CIStreamSequentialStream] Position: 17 hr:0x0
2395458 12:23:02.009 [4060] (ArchiveTask) <5956> EV:L [class CNullSequentialStream] SetCapacity:38912 hr:0x0
2395459 12:23:02.009 [4060] (ArchiveTask) <5880> EV:M :AgentMessageDispenser::ProcessNextMessage() |ActivateObject has returned successfully |
2395460 12:23:02.009 [4060] (ArchiveTask) <5956> EV:L [class CIStreamSequentialStream] Position: 29 hr:0x0
2395461 12:23:02.009 [4060] (ArchiveTask) <5956> EV:L [class CIStreamSequentialStream] SetPosition: 9483 hr:0x0
2395462 12:23:02.009 [4060] (ArchiveTask) <5956> EV:L [class CNullSequentialStream] Position: 0 hr:0x0
2395463 12:23:02.009 [4060] (ArchiveTask) <5956> EV:L [class CNullSequentialStream] SetLength:0 hr:0x0
2395464 12:23:02.009 [4060] (ArchiveTask) <5956> EV:M Compression header:Ok [Header:9458:38912 Decompression:9458:38912]
2395465 12:23:02.009 [4060] (ArchiveTask) <5956> EV:L [class CNullSequentialStream] Flush hr:0x0
2395466 12:23:02.009 [4060] (ArchiveTask) <5956> EV:M [CCompressor] Decompress|Type: class CZlibCompressor|Header: Y|Thin: 9454|Fat: 38912|Fat guess good: 38912)|CRC: 3268035671
2395467 12:23:02.009 [4060] (ArchiveTask) <5956> EV:L CSavesetPersist::PackSavesetBytes Completed verification decompression step. hr=Success [0]|Compressed size:9483 Uncompressed size:38912 Elapsed:0.00 (secs)
2395468 12:23:02.009 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg::Initialize (Entry)
2395469 12:23:02.009 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg2::SelectSharer (Entry)
2395470 12:23:02.009 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg::OpenUserArchivableItem (Entry)
2395471 12:23:02.009 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg::OpenUserArchivableItem (Exit). hr=Success [0]
2395472 12:23:02.009 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg::OnSwitchUserInfo (Entry)
2395473 12:23:02.009 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg::OnSwitchUserInfo (Exit). hr=Success [0]
2395474 12:23:02.009 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg2::SelectSharer (Exit). hr=Success [0]
2395475 12:23:02.009 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg::Initialize (Exit). hr=Success [0]
2395476 12:23:02.009 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg2::OpenSharableContent (Entry)
2395477 12:23:02.009 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg2::OpenSharableContent (Exit). hr=Success [0]
2395478 12:23:02.009 [4060] (ArchiveTask) <5956> EV:M CSaveset2::Save (Exit). hr=Success [0]
2395479 12:23:02.009 [4060] (ArchiveTask) <5956> EV:L CSavesetMessage::Send(Stream) (Entry) |
2395480 12:23:02.009 [4060] (ArchiveTask) <5956> EV:L [class CMemorySequentialStream] Position: 0 hr:0x0
2395481 12:23:02.009 [4060] (ArchiveTask) <5956> EV:L CSavesetMessage::SendCommon (Entry) |
2395482 12:23:02.009 [4060] (ArchiveTask) <5956> EV:H CSavesetMessage::SendCommon|MsgHeader Contents|CompressedSavesetSize.HighPart = 0|CompressedSavesetSize.LowPart = 9458|UncompressedSavesetSize.HighPart = 0|UncompressedSavesetSize.LowPart = 38912|GuidUniquifier = {E01B3C50-512D-46B1-B437-52DDF21ACC15}|Part 0 of 1|ParamsMemorySize = 301|Version = 1.0
2395483 12:23:02.056 [4060] (ArchiveTask) <5956> EV:M CSavesetMessage::SendCommon (Exit) |One or more of the properties passed resulted in a warning, but the function completed. [0x400e0001] |
2395484 12:23:02.056 [4060] (ArchiveTask) <5956> EV:M AuditInterface::SUCCESS_AUDIT (with Savset data)
2395485 12:23:02.056 [4060] (ArchiveTask) <5956> EV:M CEVAudit::WillPerformAudit Determine whether to audit or not.
2395486 12:23:02.056 [4060] (ArchiveTask) <5956> EV:M CSavesetMessage::Send(Stream) (Exit) |One or more of the properties passed resulted in a warning, but the function completed. [0x400e0001] |
2395487 12:23:02.056 [4060] (ArchiveTask) <5956> EV:M CStorageArchive::Store (Exit) |One or more of the properties passed resulted in a warning, but the function completed. [0x400e0001] |
2395488 12:23:02.056 [4060] (ArchiveTask) <5956> EV:M CArchivingAgent::IncrementOpsLeadingToRecycle - Current Count [0x00000E11] Recycle at [0x0000C350]|
2395489 12:23:02.056 [4060] (ArchiveTask) <5956> EV:L :CArchivingAgent::ProcessItem() |Exiting routine |
2395490 12:23:02.056 [4060] (ArchiveTask) <5956> EV:H CArchivingAgent::ProcessItemInternal Time Taken to Process Item - 614C4632CC1BE7D5550CEBD26D0804C1: 0.141857 secs
2395491 12:23:02.056 [4060] (ArchiveTask) <5956> EV:L CSavesetPersist::ResetStorage LB: 0x15fd8ef8 Stg(LB): 0xa455c38 Stg(doc): 0 File: (null)
2395492 12:23:02.072 [4060] (ArchiveTask) <5956> EV:H :CArchivingAgent::SetupAndCallQueueMsg() |Exiting routine. |
2395493 12:23:02.072 [4060] (ArchiveTask) <5956> EV:H :CArchivingAgent::IsEligibleForArchive() |Entering routine |
2395494 12:23:02.072 [4060] (ArchiveTask) <5956> EV:L :CArchivingAgent::IsEligibleForArchive(/O=VIMAR/OU=Marostica/cn=Recipients/cn=VolpatoA2) |The message titled: has the retention category of its parent folder its value is 14A79626E628BC348A870E525961F5F401b10000eva.vimar.net |
2395495 12:23:02.072 [4060] (ArchiveTask) <5956> EV:L :CArchivingAgent::IsEligibleForArchive(/O=VIMAR/OU=Marostica/cn=Recipients/cn=VolpatoA2) |The message titled: is eligible for archive |
2395496 12:23:02.072 [4060] (ArchiveTask) <5956> EV:H :CArchivingAgent::IsEligibleForArchive() |Exiting routine |
2395497 12:23:02.072 [4060] (ArchiveTask) <5956> EV:L CArchivingAgent::QueueEligibleItem - Eligible Item Strategy [Age]
2395498 12:23:02.072 [4060] (ArchiveTask) <5956> EV:H :CArchivingAgent::PopulateTableRowItem() |Entering routine. |
2395499 12:23:02.072 [4060] (ArchiveTask) <5956> EV:H :CArchivingAgent::PopulateTableRowItem() |Exiting routine. |
2395500 12:23:02.072 [4060] (ArchiveTask) <5956> EV:H :CArchivingAgent::SetupAndCallQueueMsg() |Entering routine. |
2395501 12:23:02.072 [4060] (ArchiveTask) <5956> EV:L CStorageArchive::GetVaultStatus (Entry) |
2395502 12:23:02.072 [4060] (ArchiveTask) <5956> EV:L CStorageArchive::GetVaultStatusData (Entry) |
2395503 12:23:02.072 [4060] (ArchiveTask) <5956> EV:M CStorageArchive::GetVaultStatusData|Vault ID: 17D7301909507334D9C1CB1395F5315391110000eva.vimar.net|Status: 1|Limit Status: 0|Storage Svc: 1555499E420F5464BAE1ED5C335442F581e10000eva.vimar.net|Rollover Vault :|Vault Store Status: 0
2395504 12:23:02.072 [4060] (ArchiveTask) <5956> EV:M CStorageArchive::GetVaultStatusData (Exit) |Success [0] |
2395505 12:23:02.072 [4060] (ArchiveTask) <5956> EV:L CStorageArchive::GetVaultThrottleStatus (Entry) |
2395506 12:23:02.072 [4060] (ArchiveTask) <5956> EV:M CStorageArchive::GetVaultThrottleStatus() - StorageServiceId: 1555499E420F5464BAE1ED5C335442F581e10000eva.vimar.net | ThrottleStatus: 1
2395507 12:23:02.072 [4060] (ArchiveTask) <5956> EV:M CStorageArchive::GetVaultThrottleStatus (Exit) |Success [0] |
2395508 12:23:02.072 [4060] (ArchiveTask) <5956> EV:M CStorageArchive::GetVaultStatus|Modifying Vault status with throttled status|ArchiveID: 17D7301909507334D9C1CB1395F5315391110000eva.vimar.net|Directory Status: 1|Throttle Status: 1|
2395509 12:23:02.072 [4060] (ArchiveTask) <5956> EV:L CStorageArchive::GetStorageInfoData (Entry) |
2395510 12:23:02.072 [4060] (ArchiveTask) <5956> EV:M CStorageArchive::GetStorageInfoData|Vault ID: 17D7301909507334D9C1CB1395F5315391110000eva.vimar.net|Storage Info Status: 1
2395511 12:23:02.072 [4060] (ArchiveTask) <5956> EV:M CStorageArchive::GetStorageInfoData (Exit) |Success [0] |
2395512 12:23:02.072 [4060] (ArchiveTask) <5956> EV:M CStorageArchive::GetVaultStatus|Modifying Vault status with StorageInfo status|ArchiveID: 17D7301909507334D9C1CB1395F5315391110000eva.vimar.net|Directory Status: 1|StorageInfo Status: 1|
2395513 12:23:02.072 [4060] (ArchiveTask) <5956> EV:M CStorageArchive::GetVaultStatus (Exit) |Success [0] |
2395514 12:23:02.103 [4060] (ArchiveTask) <5956> EV:M CArchivingAgent::MakeItemPendingAndProcessMsg - ArchiveId = 17D7301909507334D9C1CB1395F5315391110000eva.vimar.net | VaultId = 13A905599E3729F4B8FDB3CFEF3A62E011110000eva.vimar.net
2395515 12:23:02.103 [4060] (ArchiveTask) <5956> EV:M CArchivingAgent::ProcessItemInternal ArchiveId: 17D7301909507334D9C1CB1395F5315391110000eva.vimar.net | VaultId: 13A905599E3729F4B8FDB3CFEF3A62E011110000eva.vimar.net
2395516 12:23:02.103 [4060] (ArchiveTask) <5956> EV:M CArchivingAgent::ProcessItemInternal [queued:F][PropertySet:(null)]
2395517 12:23:02.103 [4060] (ArchiveTask) <5956> EV:L :CArchivingAgent::ProcessItem() |Building the Exchange Archivable Item object using the Exchange Location object |
2395518 12:23:02.103 [4060] (ArchiveTask) <5956> EV:M CExchangeArchivableItem::BuildFromMAPIMessageEx - Entered Routine|
2395519 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M (BlacklistedDLs) 'BlacklistedDLs' registry key not found. No DLs will be blacklisted.
2395520 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CArchivingAgent::PI_BuildObjectsFromMessage - GetMessageClass() returned error 0x00000000
2395521 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CArchivingAgent::PI_CreateSaveset - entering function
2395522 12:23:02.119 [4060] (ArchiveTask) <5956> EV:L CStorageArchive::Create (Entry) |
2395523 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSaveset2::get_Saveset (Entry)
2395524 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSaveset2::get_Saveset (Exit). hr=Success [0]
2395525 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSaveset2::CreateNew (Entry)
2395526 12:23:02.119 [4060] (ArchiveTask) <5956> EV:L CSavesetPersist::ResetStorage LB: 0 Stg(LB): 0 Stg(doc): 0 File: (null)
2395527 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg::Initialize (Entry)
2395528 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg2::OpenSharableContent (Entry)
2395529 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg2::OpenSharableContent (Exit). hr=Success [0]
2395530 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg2::AddSharer (Entry)
2395531 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg::OnSwitchUserInfo (Entry)
2395532 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg::OnSwitchUserInfo (Exit). hr=Success [0]
2395533 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg2::AddSharer (Exit). hr=Success [0]
2395534 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg::Initialize (Exit). hr=Success [0]
2395535 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg2::OpenSharableContent (Entry)
2395536 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg2::OpenSharableContent (Exit). hr=Success [0]
2395537 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSaveset2::CreateNew (Exit). hr=Success [0]
2395538 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CStorageArchive::Create|Creating Saveset2 object.|Requested in memory: TRUE|Creating in memory: TRUE
2395539 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CStorageArchive::Create (Exit) |Success [0] |
2395540 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSaveset2::put_TransactionID (Entry)
2395541 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSaveset2::put_TransactionID (Exit). hr=Success [0]
2395542 12:23:02.119 [4060] (ArchiveTask) <5956> EV:L :CArchivingAgent::ProcessItem() |Writing the Archive ID of the message to the saveset |
2395543 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSaveset2::put_VaultId (Entry)
2395544 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg::put_VaultId (Entry)
2395545 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg::put_VaultId (Exit). hr=Success [0]
2395546 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSaveset2::put_VaultId (Exit). hr=Success [0]
2395547 12:23:02.119 [4060] (ArchiveTask) <5956> EV:L :CArchivingAgent::ProcessItem() |Writing the retention category of the message to the saveset |
2395548 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSaveset2::put_RetentionCategory (Entry)
2395549 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg::put_RetentionCategory (Entry)
2395550 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg::put_RetentionCategory (Exit). hr=Success [0]
2395551 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSaveset2::put_RetentionCategory (Exit). hr=Success [0]
2395552 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CArchivingAgent::PI_BuildSaveset - write the message to the saveset
2395553 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CMAPISaveset2::_Insert (Entry)
2395554 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CMAPISaveset::_Insert (Entry)
2395555 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg::put_OriginalFormat (Entry)
2395556 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg::put_OriginalFormat (Exit). hr=Success [0]
2395557 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CMAPISaveset::__Insert (Entry)
2395558 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CMAPISaveset::SaveSharableMessage (Entry)
2395559 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M Original Message Class found: IPM.Note
2395560 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CMAPISaveset::SaveSharableMessage Save Sharable Message as MAPI.
2395561 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CMAPISaveset::OpenSharedMessage (Entry)
2395562 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg2::OpenArchivableItem (Entry)
2395563 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg2::OpenSharableContent (Entry)
2395564 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg2::OpenSharableContent (Exit). hr=Success [0]
2395565 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg::OpenArchivableItem (Entry)
2395566 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg::OpenArchivableItem (Exit). hr=Success [0]
2395567 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg2::OpenArchivableItem (Exit). hr=Success [0]
2395568 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CMAPISaveset::CheckForPSTStream (Entry)
2395569 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CMAPISaveset::CheckForPSTStream (Exit). hr=Success [0]
2395570 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CMAPISaveset::OpenSharedMessageOnIMsg (Entry)
2395571 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CMAPISaveset::get__SavesetUnicode (Entry)
2395572 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg2::IsSavesetUnicode (Entry)
2395573 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg2::IsSavesetUnicode (Exit). hr=Success [0]
2395574 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CMAPISaveset::get__SavesetUnicode (Exit). hr=Success [0]
2395575 12:23:02.134 [4060] (ArchiveTask) <5956> EV:M CMAPISaveset::OpenSharedMessageOnIMsg (Exit). hr=Success [0]
2395576 12:23:02.134 [4060] (ArchiveTask) <5956> EV:M CMAPISaveset::OpenSharedMessage (Exit). hr=Success [0]
2395577 12:23:02.134 [4060] (ArchiveTask) <5956> EV:M CMAPISaveset::get__SavesetUnicode (Entry)
2395578 12:23:02.134 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg2::IsSavesetUnicode (Entry)
2395579 12:23:02.134 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg2::IsSavesetUnicode (Exit). hr=Success [0]
2395580 12:23:02.134 [4060] (ArchiveTask) <5956> EV:M CMAPISaveset::get__SavesetUnicode (Exit). hr=Success [0]
2395581 12:23:02.134 [4060] (ArchiveTask) <5956> EV:M CMAPISaveset::CopySharableProps (Entry)
2395582 12:23:02.134 [4060] (ArchiveTask) <5956> EV:L CMAPIMessageProps::Init - UnicodeMessage: true, Requested Props: 2
2395583 12:23:02.134 [4060] (ArchiveTask) <4564> EV:M :AgentMessageDispenser::ProcessNextMessage() |Retrieved a message successfully from the queue |
2395584 12:23:02.134 [4060] (ArchiveTask) <4564> EV:M :AgentMessageDispenser::ProcessNextMessage() |Read new message or a message that is within its retry limit (). |About to process the message body |
2395585 12:23:02.134 [4060] (ArchiveTask) <4564> EV:H :AgentMessageDispenser::ActivateObject() |Entering routine |
2395586 12:23:02.134 [4060] (ArchiveTask) <4564> EV:M :AgentMessageDispenser::ActivateObject() |Message type indicator = MsgID_RemoveBody |
2395587 12:23:02.134 [4060] (ArchiveTask) <4564> EV:L :AgentMessageDispenser::ActivateObject() |Called server side object, with arguments: |m_pIArchivingAgentUpdate->PostProcessArchivedItem(OriginatingAgent = "17F96B9E3DB5EF740BC5D3D92BAEC0F6E1011z00eva.vimar.net",| m_pIShortcutAccessor,| m_pILocation,| SavesetID = "200907220000000~200902031008120000~Z~614C4632CC1BE7D5550CEBD26D0804C1",| VaultID = "13A905599E3729F4B8FDB3CFEF3A62E011110000eva.vimar.net",| nRetryCount = 0,| NULL);| |
2395588 12:23:02.134 [4060] (ArchiveTask) <4564> EV:M EPC::GDP - Returning Default Policy : [Default Exchange Mailbox Policy][1238B09CDBE699E40AEF495AC7C2003F61012700eva.vimar.net]
2395589 12:23:02.134 [4060] (ArchiveTask) <4564> EV:H :CArchivingAgent::PostProcessArchivedItem() |Entering routine |
2395590 12:23:02.134 [4060] (ArchiveTask) <4564> EV:H :CArchivingAgent::PostProcessArchivedItem() |Getting ExchangeShortcutAccessor interface |
2395591 12:23:02.134 [4060] (ArchiveTask) <4564> EV:H :CArchivingAgent::PostProcessArchivedItem() |Getting ShortcutAccessor2 interface |
2395592 12:23:02.134 [4060] (ArchiveTask) <4564> EV:H :CArchivingAgent::PostProcessArchivedItem() |Getting ExchangeLocation interface |
2395593 12:23:02.134 [4060] (ArchiveTask) <4564> EV:H :CArchivingAgent::PostProcessArchivedItem() |Getting a MAPI session from the session pool |
2395594 12:23:02.134 [4060] (ArchiveTask) <4564> EV:M CMAPISession::GetMapiSessionFromPool(AdditionalFlags = 0)
2395595 12:23:02.134 [4060] (ArchiveTask) <4564> EV:M CMAPISession::GetMapiSessionFromPool(): Exit status: 0x0
2395596 12:23:02.134 [4060] (ArchiveTask) <4564> EV:H :CArchivingAgent::PostProcessArchivedItem() |Setting the context for the shortcut accessor |
2395597 12:23:02.134 [4060] (ArchiveTask) <4564> EV:M CArchivingAgent::PostProcessArchivedItem - BuildFromSessionAndLocation succeeded.
2395598 12:23:02.134 [4060] (ArchiveTask) <4564> EV:H :CArchivingAgent::PostProcessArchivedItem() |Getting the value of the createShortcut flag from the Shortcut message |
2395599 12:23:02.134 [4060] (ArchiveTask) <4564> EV:M CArchivingAgent::PostProcessArchivedItem - Setting item to shortcut.
2395600 12:23:02.150 [4060] (ArchiveTask) <4564> EV:M AuditInterface::SUCCESS_AUDIT (with IShortcutAccessor)
2395601 12:23:02.150 [4060] (ArchiveTask) <4564> EV:M CEVAudit::WillPerformAudit Determine whether to audit or not.
2395602 12:23:02.150 [4060] (ArchiveTask) <4564> EV:M CAA::PPAI() - Getting Policy Information
2395603 12:23:02.150 [4060] (ArchiveTask) <4564> EV:M CAA::SUP() - bsStoreEntryId = 0000000038A1BB1005E5101AA1BB08002B2A56C20000454D534D44422E444C4C00000000000000001B55FA20AA6611CD9BC800AA002FC45A0C00000053455843564956563032002F4F3D56494D41522F4F553D4D61726F73746963612F636E3D526563697069656E74732F636E3D566F6C7061746F413200D83521F3A600000001000000140000007A0000002F6F3D56494D41522F6F753D45786368616E67652041646D696E6973747261746976652047726F7570202846594449424F484632335350444C54292F636E3D436F6E66696775726174696F6E2F636E3D536572766572732F636E3D53455843564956563032005300450058004300
2395604 12:23:02.150 [4060] (ArchiveTask) <4564> EV:M CPolicyTargetGroupCache::GetUsersPolicyTargetGroup - Found entry for /O=VIMAR/OU=Marostica/cn=Recipients/cn=VolpatoA2 in cache
2395605 12:23:02.150 [4060] (ArchiveTask) <4564> EV:M CPolicyTargetGroupCache::GetUsersPolicyTargetGroup - User [/O=VIMAR/OU=Marostica/cn=Recipients/cn=VolpatoA2] maps to Policy


This is only a preview of the output.
Log file is very large.
Do you need other info?

Paul_Grimshaw
Level 6
Employee Accredited Certified
That snippet of log is not useful. Need the section of the log where the 3310 is generated

Nicola_Rositani
Level 5
2445396 13:48:22.047 [4060] (ArchiveTask) <5044> EV:H :AgentMessageDispenser::ActivateObject() |Exiting routine at point A |
2445397 13:48:22.047 [4060] (ArchiveTask) <5044> EV:M :AgentMessageDispenser::ProcessNextMessage() |ActivateObject has returned failure |
2445398 13:48:22.047 [4060] (ArchiveTask) <5044> EV:M AgentMessageDispenser::ProcessNextMessage - AGENTS_E_DISPABORTITEM, agent will sleep and abort the the current MSMQ Transation
2445399 13:48:22.047 [4060] (ArchiveTask) <1824> EV:H :CArchivingAgent::ArchiveItem() |Exiting routine |
2445400 13:48:22.062 [4060] (ArchiveTask) <1824> EV:M CArchivingAgent::ArchiveItemV35 (Exit) |<0x80040115> |
2445401 13:48:22.062 [4060] (ArchiveTask) <1824> EV:H :AgentMessageDispenser::ActivateObject() |Exiting routine at point A |
2445402 13:48:22.062 [4060] (ArchiveTask) <1824> EV:M :AgentMessageDispenser::ProcessNextMessage() |ActivateObject has returned failure |
2445403 13:48:22.062 [4060] (ArchiveTask) <1824> EV:M AgentMessageDispenser::ProcessNextMessage - AGENTS_E_DISPPROBLEMSLEEPABORTITEM, agent will sleep and abort the the current MSMQ Transation
2445404 13:48:22.062 [4060] (ArchiveTask) <5880> EV:M AgentMessageDispenser: 1 of 10 Agent threads is pausing|
2445405 13:48:22.062 [4060] (ArchiveTask) <5880> EV:M AgentMessageDispenser::LogPauseReason - First Dispenser Thread to Sleep

2445406 13:48:22.062 [4060] (ArchiveTask) <5880> EV~E Event ID: 3310 There was a problem accessing a network service or resource. The dispenser will re-queue the current item and sleep for 10 minute(s). |Task: Exchange Mailbox Archiving Task for SEXCVIVV02 |

2445407 13:48:22.062 [4060] (ArchiveTask) <7040> EV:M AgentMessageDispenser: 3 of 10 Agent threads is pausing|
2445408 13:48:22.062 [4060] (ArchiveTask) <5292> EV:M AgentMessageDispenser: 2 of 10 Agent threads is pausing|
2445409 13:48:22.125 [4060] (ArchiveTask) <5044> EV:M AgentMessageDispenser: 4 of 10 Agent threads is pausing|
2445410 13:48:22.125 [4060] (ArchiveTask) <3028> EV:M AgentMessageDispenser: 5 of 10 Agent threads is pausing|
2445411 13:48:22.125 [4060] (ArchiveTask) <4564> EV:M AgentMessageDispenser: 6 of 10 Agent threads is pausing|
2445412 13:48:22.140 [4060] (ArchiveTask) <1824> EV:M AgentMessageDispenser: 7 of 10 Agent threads is pausing|
2445413 13:48:22.281 [4060] (ArchiveTask) <7552> EV:H :CArchivingAgent::ArchiveItem() |Exiting routine |
2445414 13:48:22.281 [4060] (ArchiveTask) <7552> EV:M CArchivingAgent::ArchiveItemV35 (Exit) |<0x80040115> |
2445415 13:48:22.281 [4060] (ArchiveTask) <7552> EV:H :AgentMessageDispenser::ActivateObject() |Exiting routine at point A |
2445416 13:48:22.281 [4060] (ArchiveTask) <7552> EV:M :AgentMessageDispenser::ProcessNextMessage() |ActivateObject has returned failure |
2445417 13:48:22.281 [4060] (ArchiveTask) <7552> EV:M AgentMessageDispenser::ProcessNextMessage - AGENTS_E_DISPABORTITEM, agent will sleep and abort the the current MSMQ Transation
2445418 13:48:22.343 [4060] (ArchiveTask) <7552> EV:M AgentMessageDispenser: 8 of 10 Agent threads is pausing|
2445419 13:48:23.031 [4060] (ArchiveTask) <5956> EV:H :CArchivingAgent::ArchiveItem() |Exiting routine |
2445420 13:48:23.047 [4060] (ArchiveTask) <5956> EV:M CArchivingAgent::ArchiveItemV35 (Exit) |<0x80040115> |
2445421 13:48:23.047 [4060] (ArchiveTask) <5956> EV:H :AgentMessageDispenser::ActivateObject() |Exiting routine at point A |
2445422 13:48:23.047 [4060] (ArchiveTask) <5956> EV:M :AgentMessageDispenser::ProcessNextMessage() |ActivateObject has returned failure |
2445423 13:48:23.047 [4060] (ArchiveTask) <5956> EV:M AgentMessageDispenser::ProcessNextMessage - AGENTS_E_DISPABORTITEM, agent will sleep and abort the the current MSMQ Transation
2445424 13:48:23.109 [4060] (ArchiveTask) <5956> EV:M AgentMessageDispenser: 9 of 10 Agent threads is pausing|

Thanks in advance Paul

Paul_Grimshaw
Level 6
Employee Accredited Certified
0X80040115 = MAPI_E_NETWORK_ERROR

It could be telling us a few things

Looking at ther time of the DTRACE am I right in assuming that this particular error did not occur within the scheduled archiving run?

If so then it would appear that manual archiving is causing this so do you see any messages on the A2 MSMQ hen you see this error?

Nicola_Rositani
Level 5
I'm sure that I've the error when users manually archive items (not ALWAYS!).
Usually in A2 queue I don't see pending items.

How can I do?

Woodhead
Level 3
Had a similar situation where messages would get 'caught' in the A2 queue and either stopping and starting teh task service or the archiving tasks would take care of it.

Happened infrequenlty.

Turns out the underlying problem was someone moved the system mailbox from one exchange server to another.

Vault1 task, using system mailbox 1 on exchange server 1.

Someone moved system mailbox 1 to exchange server 2.

Putting the system mailbox back corrected the problem.

Nicola_Rositani
Level 5
Thanks Woodhead,
in my case I've only an exchange server 2007 with mailbox role.

Regards

Paul_Grimshaw
Level 6
Employee Accredited Certified

We could be getting into the realms of putting on a network sniffer.
Is the exchange server SEXCVIVV02 sat close to the ev server from a network perspective.
We rely on the exchange server as a default referring us to a good gc. WHat you can do is implement a key called closest gc which will change the gc referral to use outlook installed on the ev server. It maybe that you have intermittent GC problems from you exchange server.

As a first stop implement this key on the ev server (just google it for info) and restart your tasks. Monitor for the 3310 error. If you still get it then we can move onto the next step.

AndrewB
Moderator
Moderator
Partner    VIP    Accredited
I don't know if it's the same thing but you can specify the GC via the console. Navigate to directory->site->targets->exchange->domain then right click and properties. Check the box and type in the name of the GC.

mike50
Level 3
The tech article Paul is referring to is http://support.microsoft.com/kb/319206, if you are still getting 3310 , you could try specific GC instead of the closest GC.