Highlighted

Event ID 3310

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 Solution

Accepted Solutions
Highlighted
Accepted Solution!

Nicola, Try setting the

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
Highlighted

3310 Error is a little

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

Highlighted

Sometime I get this error 3-4

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

OK so if you woud like to

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.

Highlighted

Just wanted to chime in and

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.
Highlighted

Yep that is one of the many

Yep that is one of the many Smiley Happy
Highlighted

Thanks AndrewB, but in my

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

Using dtrace do I enable only

Using dtrace do I enable only 4-ArchiveTask Detail?
Highlighted

Correct yes and you may want

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

Highlighted

Thanks Paul, I've apply

Thanks Paul, I've apply trigger correctly
Highlighted

watch log

I've just capture watch log:

2395409 12:23:01.994 [4060] (ArchiveTask) <5956> EV:M CSavesetPersist:Smiley Frustratedave 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:Smiley SurprisedpenIndexableItem (Entry)
2395421 12:23:01.994 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg:Smiley SurprisedpenSubStg (Exit). hr=%1 could not be found. [0x80030002]
2395422 12:23:01.994 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg:Smiley SurprisedpenIndexableItem (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:Smiley TongueackSaveset 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:Smiley TongueostProcessArchivedItem() |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:Smiley TongueostProcessArchivedItem() |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:Smiley TongueostProcessArchivedItem 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:Smiley TongueackSavesetBytes 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:Smiley TonguerocessNextMessage() |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 headerSmiley Surprisedk [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:Smiley TongueackSavesetBytes 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:Smiley FrustratedelectSharer (Entry)
2395470 12:23:02.009 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg:Smiley SurprisedpenUserArchivableItem (Entry)
2395471 12:23:02.009 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg:Smiley SurprisedpenUserArchivableItem (Exit). hr=Success [0]
2395472 12:23:02.009 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg:Smiley SurprisednSwitchUserInfo (Entry)
2395473 12:23:02.009 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg:Smiley SurprisednSwitchUserInfo (Exit). hr=Success [0]
2395474 12:23:02.009 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg2:Smiley FrustratedelectSharer (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:Smiley SurprisedpenSharableContent (Entry)
2395477 12:23:02.009 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg2:Smiley SurprisedpenSharableContent (Exit). hr=Success [0]
2395478 12:23:02.009 [4060] (ArchiveTask) <5956> EV:M CSaveset2:Smiley Frustratedave (Exit). hr=Success [0]
2395479 12:23:02.009 [4060] (ArchiveTask) <5956> EV:L CSavesetMessage:Smiley Frustratedend(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:Smiley FrustratedendCommon (Entry) |
2395482 12:23:02.009 [4060] (ArchiveTask) <5956> EV:H CSavesetMessage:Smiley FrustratedendCommon|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:Smiley FrustratedendCommon (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:Smiley FrustratedUCCESS_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:Smiley Frustratedend(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:Smiley Frustratedtore (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:Smiley TonguerocessItem() |Exiting routine |
2395490 12:23:02.056 [4060] (ArchiveTask) <5956> EV:H CArchivingAgent:Smiley TonguerocessItemInternal 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:Smiley FrustratedetupAndCallQueueMsg() |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:Smiley TongueopulateTableRowItem() |Entering routine. |
2395499 12:23:02.072 [4060] (ArchiveTask) <5956> EV:H :CArchivingAgent:Smiley TongueopulateTableRowItem() |Exiting routine. |
2395500 12:23:02.072 [4060] (ArchiveTask) <5956> EV:H :CArchivingAgent:Smiley FrustratedetupAndCallQueueMsg() |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 Smiley IndifferentVault 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:Smiley TonguerocessItemInternal ArchiveId: 17D7301909507334D9C1CB1395F5315391110000eva.vimar.net | VaultId: 13A905599E3729F4B8FDB3CFEF3A62E011110000eva.vimar.net
2395516 12:23:02.103 [4060] (ArchiveTask) <5956> EV:M CArchivingAgent:Smiley TonguerocessItemInternal [queued:F][PropertySetSmiley Sadnull)]
2395517 12:23:02.103 [4060] (ArchiveTask) <5956> EV:L :CArchivingAgent:Smiley TonguerocessItem() |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:Smiley TongueI_BuildObjectsFromMessage - GetMessageClass() returned error 0x00000000
2395521 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CArchivingAgent:Smiley TongueI_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:Smiley SurprisedpenSharableContent (Entry)
2395529 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg2:Smiley SurprisedpenSharableContent (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:Smiley SurprisednSwitchUserInfo (Entry)
2395532 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg:Smiley SurprisednSwitchUserInfo (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:Smiley SurprisedpenSharableContent (Entry)
2395536 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg2:Smiley SurprisedpenSharableContent (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:Smiley Tongueut_TransactionID (Entry)
2395541 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSaveset2:Smiley Tongueut_TransactionID (Exit). hr=Success [0]
2395542 12:23:02.119 [4060] (ArchiveTask) <5956> EV:L :CArchivingAgent:Smiley TonguerocessItem() |Writing the Archive ID of the message to the saveset |
2395543 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSaveset2:Smiley Tongueut_VaultId (Entry)
2395544 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg:Smiley Tongueut_VaultId (Entry)
2395545 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg:Smiley Tongueut_VaultId (Exit). hr=Success [0]
2395546 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSaveset2:Smiley Tongueut_VaultId (Exit). hr=Success [0]
2395547 12:23:02.119 [4060] (ArchiveTask) <5956> EV:L :CArchivingAgent:Smiley TonguerocessItem() |Writing the retention category of the message to the saveset |
2395548 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSaveset2:Smiley Tongueut_RetentionCategory (Entry)
2395549 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg:Smiley Tongueut_RetentionCategory (Entry)
2395550 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg:Smiley Tongueut_RetentionCategory (Exit). hr=Success [0]
2395551 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSaveset2:Smiley Tongueut_RetentionCategory (Exit). hr=Success [0]
2395552 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CArchivingAgent:Smiley TongueI_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:Smiley Tongueut_OriginalFormat (Entry)
2395556 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg:Smiley Tongueut_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:Smiley FrustratedaveSharableMessage (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:Smiley FrustratedaveSharableMessage Save Sharable Message as MAPI.
2395561 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CMAPISaveset:Smiley SurprisedpenSharedMessage (Entry)
2395562 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg2:Smiley SurprisedpenArchivableItem (Entry)
2395563 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg2:Smiley SurprisedpenSharableContent (Entry)
2395564 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg2:Smiley SurprisedpenSharableContent (Exit). hr=Success [0]
2395565 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg:Smiley SurprisedpenArchivableItem (Entry)
2395566 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg:Smiley SurprisedpenArchivableItem (Exit). hr=Success [0]
2395567 12:23:02.119 [4060] (ArchiveTask) <5956> EV:M CSavesetOnIStg2:Smiley SurprisedpenArchivableItem (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:Smiley SurprisedpenSharedMessageOnIMsg (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:Smiley SurprisedpenSharedMessageOnIMsg (Exit). hr=Success [0]
2395576 12:23:02.134 [4060] (ArchiveTask) <5956> EV:M CMAPISaveset:Smiley SurprisedpenSharedMessage (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:Smiley TonguerocessNextMessage() |Retrieved a message successfully from the queue |
2395584 12:23:02.134 [4060] (ArchiveTask) <4564> EV:M :AgentMessageDispenser:Smiley TonguerocessNextMessage() |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:Smiley TongueostProcessArchivedItem() |Entering routine |
2395590 12:23:02.134 [4060] (ArchiveTask) <4564> EV:H :CArchivingAgent:Smiley TongueostProcessArchivedItem() |Getting ExchangeShortcutAccessor interface |
2395591 12:23:02.134 [4060] (ArchiveTask) <4564> EV:H :CArchivingAgent:Smiley TongueostProcessArchivedItem() |Getting ShortcutAccessor2 interface |
2395592 12:23:02.134 [4060] (ArchiveTask) <4564> EV:H :CArchivingAgent:Smiley TongueostProcessArchivedItem() |Getting ExchangeLocation interface |
2395593 12:23:02.134 [4060] (ArchiveTask) <4564> EV:H :CArchivingAgent:Smiley TongueostProcessArchivedItem() |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:Smiley TongueostProcessArchivedItem() |Setting the context for the shortcut accessor |
2395597 12:23:02.134 [4060] (ArchiveTask) <4564> EV:M CArchivingAgent:Smiley TongueostProcessArchivedItem - BuildFromSessionAndLocation succeeded.
2395598 12:23:02.134 [4060] (ArchiveTask) <4564> EV:H :CArchivingAgent:Smiley TongueostProcessArchivedItem() |Getting the value of the createShortcut flag from the Shortcut message |
2395599 12:23:02.134 [4060] (ArchiveTask) <4564> EV:M CArchivingAgent:Smiley TongueostProcessArchivedItem - Setting item to shortcut.
2395600 12:23:02.150 [4060] (ArchiveTask) <4564> EV:M AuditInterface:Smiley FrustratedUCCESS_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:Smiley TonguePAI() - Getting Policy Information
2395603 12:23:02.150 [4060] (ArchiveTask) <4564> EV:M CAA:Smiley FrustratedUP() - 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?

Highlighted

That snippet of log is not

That snippet of log is not useful. Need the section of the log where the 3310 is generated
Highlighted

2445396 13:48:22.047

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:Smiley TonguerocessNextMessage() |ActivateObject has returned failure |
2445398 13:48:22.047 [4060] (ArchiveTask) <5044> EV:M AgentMessageDispenser:Smiley TonguerocessNextMessage - 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:Smiley TonguerocessNextMessage() |ActivateObject has returned failure |
2445403 13:48:22.062 [4060] (ArchiveTask) <1824> EV:M AgentMessageDispenser:Smiley TonguerocessNextMessage - 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:Smiley TonguerocessNextMessage() |ActivateObject has returned failure |
2445417 13:48:22.281 [4060] (ArchiveTask) <7552> EV:M AgentMessageDispenser:Smiley TonguerocessNextMessage - 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:Smiley TonguerocessNextMessage() |ActivateObject has returned failure |
2445423 13:48:23.047 [4060] (ArchiveTask) <5956> EV:M AgentMessageDispenser:Smiley TonguerocessNextMessage - 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
Highlighted

0X80040115 =

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?
Highlighted

I'm sure that I've the error

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?
Highlighted

'Pumping' the A2 Queue

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.
Highlighted

Thanks Woodhead, in my case

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

Regards
Highlighted

We could be getting into the

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.

Highlighted

I don't know if it's the same

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.
Highlighted

The tech article Paul is

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.