09-22-2012 07:29 PM
Hi,
I have some BES express issues. I'm running Exchange 2010 14.02.0309.002, BES Express 5.0.3 (bundle 12). Both are installed on seperate 2008 R2.
Some devices have had no email delivery for 4-5 days. Some receive a glut of emails sporadically, after a period of delay. The devices cannot lookup the Exchange GAL that are not receiving email messages to their devices.
In the even log are errors such as:
RIM_HrResolveProxysFromDN - ResolveNamesMAPI failed from -2147221233
--------------------------------------------------
{Firstname Surname} MAPIMailbox::~MAPIMailbox - Exception handled
--------------------------------------------------
Faulting application name: BAS-NCC.exe, version: 1.2.3.0, time stamp: 0x4c6c52b7
Faulting module name: BASWinDS.dll, version: 5.0.3.41, time stamp: 0x4daf4198
Exception code: 0xc000000d
Fault offset: 0x0007c5bc
Faulting process id: 0x1150
Faulting application start time: 0x01cd99070d0b3e2a
Faulting application path: C:\Program Files (x86)\Research In Motion\BlackBerry Enterprise Server\BAS\External\Bin\BAS-NCC.exe
Faulting module path: C:\Program Files (x86)\Research In Motion\BlackBerry Enterprise Server\BAS\dll\BASWinDS.dll
Report Id: 47113d1b-0502-11e2-8e6a-005056844d23
Re-occuring failure of above.
And also:
Fault bucket , type 0
Event Name: BEX
Response: Not available
Cab Id: 0
Problem signature:
P1: BAS-NCC.exe
P2: 1.2.3.0
P3: 4c6c52b7
P4: BASWinDS.dll
P5: 5.0.3.41
P6: 4daf4198
P7: 0007c5bc
P8: c000000d
P9: 00000000
P10:
Attached files:
These files may be available here:
C:\ProgramData\Microsoft\Windows\WER\ReportQueue\A
Analysis symbol:
Rechecking for solution: 0
Report Id: 47113d1b-0502-11e2-8e6a-005056844d23
Report Status: 4
The server has been rebooted but to no avail. Thanks in advance.
Solved! Go to Solution.
09-23-2012 02:36 PM
See attached logs including the MAGT log for further information. Thanks.
C:\Program Files\ExchangeMAPI\
CDO version 1.2.1 (6.5.8165)
BES server C:\Windows\System32\mapi32.dll CAS, HUB
File version 1.0.2536
Product version 6.1.7601.17514
CALH LOG
[30000] (09/23 00:31:08.711):{0x2070} {fname.surname@mydomain} Service::TestAccessToMailbox Soap Exception caught: The account does not have permission to impersonate the requested user.
[30000] (09/23 00:31:08.711):{0x2070} {fname.surname@mydomain} Diagnosis: The BES service account does not have impersonation rights for this user.
[30000] (09/23 00:31:08.711):{0x2070} {fname.surname@mydomain} Please consult the BES installation guide for Exchange Server configuration information.
CMNG LOG
[30017] (09/23 00:05:46.981):{0x14D0} {fname surname.398} Performing DeviceUserState check for records with no response.
[40089] (09/23 00:05:46.981):{0x14D0} {fname surname.398} CheckResponseReceived - no changes detected.
DISP LOG Output
[30222] (09/23 00:07:33.419):{0x99C} {fname surname} MTH: contentType=CICAL, sizeOTA=74, sizeOTW=18, TransactionId=-799243693, Tag=19517629
[30310] (09/23 00:07:33.419):{0x99C} {fname surname} Forwarding internal data to device, contentType=CICAL, routing=S80634750, device=, size=103, cmd=0x3, ack=0, TransactionId=-799243693, intTag=14452378, Tag=19517629, Submit=1
[30375] (09/23 00:07:33.450):{0x998} {fname surname} Packet returned as FAILED - could not be delivered to device, Tag=19517629
BBAS LOG EXTRACT
(09/23 02:10:05:292):{WorkManager(2)-7067} [com.rim.bes.bas.jobmanager.JobManagerBean] [DEBUG] [BBAS-200] {u=SystemUser, t=182421} Enter bean JobManagerBean.setIncompleteTaskAsFailedInNewTrans
(09/23 02:10:05:307):{WorkManager(2)-7067} [com.rim.bes.bas.jobmanager.JobManagerBean] [DEBUG] [BBAS-200] {u=SystemUser, t=182421} setIncompleteTaskAsFailedInNewTransactionLocal call delivery manager to cancel the task, taskId = 43550
(09/23 02:10:05:307):{WorkManager(2)-7067} [com.rim.bes.bas.jobmanager.JobManagerBean] [DEBUG] [BBAS-200] {u=SystemUser, t=182421} _updateJobThrottlingAvailableSystemSlotsCount update the Job Throttling System Data with following: objectTypePlugInId = 0, objectTypeId = 1, availableSystemSlotsChanges = 1
(09/23 02:10:05:307):{WorkManager(2)-7067} [com.rim.bes.bas.jobmanager.JobManagerBean] [DEBUG] [BBAS-200] {u=SystemUser, t=182421} _updateJobThrottlingAvailableSystemSlotsCount system slots available number after update is 1000
(09/23 02:10:05:307):{WorkManager(2)-7067} [com.rim.bes.bas.jobmanager.JobManagerBean] [DEBUG] [BBAS-200] {u=SystemUser, t=182433} Enter bean JobManagerBean.updateDependentTaskStateAsDependenc
(09/23 02:10:05:572):{WorkManager(2)-7067} [com.rim.bes.bas.jobmanager.JobManagerBean] [DEBUG] [BBAS-200] {u=SystemUser, t=182433} Exit bean JobManagerBean.updateDependentTaskStateAsDependenc
..
(09/23 10:19:44:146):{WorkManager(2)-8430} [com.rim.bes.bas.jobmanager.JobManagerBean] [DEBUG] [BBAS-200] {u=SystemUser, t=203658} _updateJobState update the job(25038) state to COMPLETED_WITH_NO_TASK_FAILURE
POLC LOG
[40000] (09/23 00:23:04.322):{0x163C} [BIPP] Ping 2222 sent
[40000] (09/23 00:23:04.322):{0x1638} [BIPP] Ping Response 2222 received
[40000] (09/23 00:24:02.338):{0x1668} SCS:
ollDBQueueNewRequests - Change Detection Poll: ProcessingRequests 0, LowerThreshold 30, ProcessingBESes 1, MaxDomainJobs 300, MaxJobsToSchedule 300, MaxBESJobs 100, MaxRequestsToQueue 100, Requests 0, QueueInterval (ms) 0.
[40000] (09/23 00:24:04.334):{0x163C} [BIPP] Ping 2223 sent
[40000] (09/23 00:24:04.334):{0x1638} [BIPP] Ping Response 2223 received
[40000] (09/23 00:24:33.256):{0x1648} {}RequestHandler:
oWork - Processing CHECK_FOR_USERCONFIG_CHANGES request
[20000] (09/23 00:24:33.506):{0x1648} POLC_UpdateBESUIDsInPolicy - Error parsing the Policy
[20000] (09/23 00:24:33.506):{0x1648} POLC_AddBESVersionToPolicy - Error parsing the Policy
[20000] (09/23 00:24:33.506):{0x1648} POLC_AddContentProtectionKeyToPolicy - Failed to parse the Policy.
[20000] (09/23 00:24:33.506):{0x1648} POLC_AddBESTypeFlagToPolicy - Error parsing the Policy
[20000] (09/23 00:24:33.506):{0x1648} POLC_AddWeakPolicyFlagToPolicy - Error parsing the Policy
[20000] (09/23 00:24:33.506):{0x1648} AddDigitalSignatureToPolicy - Error parsing the Policy
[40000] (09/23 00:24:33.506):{0x1648} {name@mydomain, PIN=0, UserId=285}SCS::CheckDBforInactiveUsers - Updating Policy State entry for new user
[40000] (09/23 00:24:33.553):{0x1648} {}RequestHandler:
oWork - Completed CHECK_FOR_USERCONFIG_CHANGES request
[40000] (09/23 00:25:02.303):{0x1668} SCS:
ollDBQueueNewRequests - Change Detection Poll: ProcessingRequests 0, LowerThreshold 30, ProcessingBESes 1, MaxDomainJobs 300, MaxJobsToSchedule 300, MaxBESJobs 100, MaxRequestsToQueue 100, Requests 0, QueueInterval (ms) 0.
..
MaxDomainJobs 300, MaxJobsToSchedule 300, MaxBESJobs 100, MaxRequestsToQueue 100, Requests 0, QueueInterval (ms) 0.
[40000] (09/23 03:29:11.403):{0x163C} [BIPP] Ping 2406 sent
[40000] (09/23 03:29:11.403):{0x1638} [BIPP] Ping Response 2406 received
[30181] (09/23 03:29:32.791):{0x1640} Performing system health check (BES [BlackBerryPolicyServer Version 5.0.3.13] - thebesserver [POLC] - Exchange)
[40000] (09/23 03:29:32.993):{0x1644} {}RequestHandler:
oWork - Processing CHECK_FOR_USERCONFIG_CHANGES request
[20000] (09/23 03:29:33.087):{0x1644} POLC_UpdateBESUIDsInPolicy - Error parsing the Policy
[20000] (09/23 03:29:33.087):{0x1644} POLC_AddBESVersionToPolicy - Error parsing the Policy
[20000] (09/23 03:29:33.087):{0x1644} POLC_AddContentProtectionKeyToPolicy - Failed to parse the Policy.
[20000] (09/23 03:29:33.087):{0x1644} POLC_AddBESTypeFlagToPolicy - Error parsing the Policy
[20000] (09/23 03:29:33.087):{0x1644} POLC_AddWeakPolicyFlagToPolicy - Error parsing the Policy
[20000] (09/23 03:29:33.087):{0x1644} AddDigitalSignatureToPolicy - Error parsing the Policy
[40000] (09/23 03:29:33.290):{0x1644} {}RequestHandler:
oWork - Completed CHECK_FOR_USERCONFIG_CHANGES request
[40000] (09/23 03:30:04.396):{0x1668} SCS:
ollDBQueueNewRequests - Change Detection Poll: ProcessingRequests 0, LowerThreshold 30, ProcessingBESes 1, MaxDomainJobs 300, MaxJobsToSchedule 300, MaxBESJobs 100, MaxRequestsToQueue 100, Requests 0, QueueInterval (ms) 0.
[40000] (09/23 03:30:11.416):{0x163C} [BIPP] Ping 2407 sent
SYNC LOG
Schofield:133]
[40000] (09/23 07:59:21.796):{0x155C} [BIPP] Received status FAILED, Tag=5159183
[40000] (09/23 07:59:21.796):{0x155C} [BIPP] Received status FAILED, Tag=5159184
[46066] (09/23 07:59:21.796):{0x133C} [SYNC-SSession] Received FAILED status from relay. [a name:75, SID=0, CLID=9, ECLID=83]
[46066] (09/23 07:59:21.796):{0x30C} [SYNC-SSession] Received FAILED status from relay. [firstname surname:72, SID=0, CLID=43, ECLID=2917]
[46051] (09/23 07:59:21.796):{0x30C} [SYNC-UserControl] BackoffServerSession is called, ServerSessionTimeout is changed to 80 minutes. [firstname surname:72]
[46051] (09/23 07:59:21.796):{0x133C} [SYNC-UserControl] BackoffServerSession is called, ServerSessionTimeout is changed to 80 minutes. firstname surname:75]
[40000] (09/23 07:59:21.827):{0x155C} [BIPP] Received status FAILED, Tag=5159185
[46066] (09/23 07:59:21.827):{0x12D0} [SYNC-SSession] Received FAILED status from relay. [firstname surname:95, SID=0, CLID=648, ECLID=117]
[46051] (09/23 07:59:21.827):{0x12D0} [SYNC-UserControl] BackoffServerSession is called, ServerSessionTimeout is changed to 80 minutes. [firstname surname:95]
[46008] (09/23 07:59:21.843):{0xF00} [SYNC-SSession] Send 1 packet(s) with 103 bytes to the device. [firstname surname:179, SID=164515487, CLID=38, ECLID=1574, TAG=5159192, ST=80]
[46052] (09/23 07:59:21.843):{0xF00} [SYNC-SSession] Contain 0 record(s) and 2 command(s) from Config. [firstname surname:179]
[40000] (09/23 07:59:21.843):{0x1560} [BIPP] Send data, Tag=5159192
[40000] (09/23 07:59:21.859):{0x155C} [BIPP] Received status FAILED, Tag=5159187
[40000] (09/23 07:59:21.859):{0x155C} [BIPP] Received status FAILED, Tag=5159189
[46066] (09/23 07:59:21.859):{0x13D4} [SYNC-SSession] Received FAILED status from relay. [firstname surname:107, SID=0, CLID=255, ECLID=7660]
[46066] (09/23 07:59:21.859):{0x1340} [SYNC-SSession] Received FAILED status from relay. [firstname surname:113, SID=0, CLID=233, ECLID=13030]
[46051] (09/23 07:59:21.859):{0x13D4} [SYNC-UserControl] BackoffServerSession is called, ServerSessionTimeout is changed to 80 minutes
MAGT Log entry
MAPIMailbox::HandleObjectModifiedNotification - OpenEntry (0x8004010f) failed
30089
{%s} Configuration incomplete: Message ignored EntryId=%d
The specified user account configuration is incomplete, so messages are not processed. A BlackBerry device might not be assigned to this user account.
[40720] (09/23 00:02:09.348):{0x2B0C} MAPI call failed. Error 'Network problems are preventing connection to the Microsoft Exchange Server computer.', LowLevelError 6, Component 'Microsoft Exchange Server Information Store', Context 1300
[40583] (09/23 00:00:39.696):{0x2554} {fname.surname@mydomain.co.uk} Sending packet to device, Size=54, Tag=14452247, TransactionId=-799243662
[40279] (09/23 00:00:39.696):{0x2554} {fname.surname@mydomain.co.uk} SubmitToRelaySendQ, Tag=14452247
[40000] (09/23 00:00:39.696):{0x1B78} [BIPP] Send data, Tag=14452247
[40000] (09/23 00:00:39.727):{0x1BDC} [BIPP] Received status FAILED, Tag=14452247
Delivered=23/09/2012 00:00:32
[30066] (09/23 00:00:52.036):{0x2BB8} Total Msgs Pending 11349
[40405] (09/23 00:00:52.067):{0x1BE4} {fname.surnae@mydomain} MAPIMailbox::HandleObjectModifiedNotification - OpenEntry (0x8004010f) failed
[40000] (09/23 00:00:52.161):{0xF68} RpcGetSubject is called. [CH=148254416, MH=477027440]
[40000] (09/23 00:00:52.161):{0xF68} RpcGetFirstAttachment is called. [CH=148254416, MH=477027440]
[30081] (09/23 00:00:52.161):{0x26A8} {fname.surnae@mydomain} Sending message to device, size=428, EntryId=3344566, RefId=-1812531975, TransactionId=-799243799, Tag=14452249
[40279] (09/23 00:00:52.161):{0x26A8} {fname.surnae@mydomain} SubmitToRelaySendQ, Tag=14452249
[40000] (09/23 00:00:52.161):{0x1B78} [BIPP] Send data, Tag=14452249
[20709] (09/23 00:01:35.637):{0x2390} {fname.surname@mydomain.co.uk} Failed to reach user's mailbox
[20265] (09/23 00:01:37.525):{0x25E8} {fname.surname@mydomain.co.uk} MAPIMailbox::RescanPIMItems - GetProps (0x80040115) failed
[20265] (09/23 00:01:37.525):{0x25E8} {fname.surname@mydomain.co.uk} MAPIMailbox::RescanPIMItems - GetProps (0x80040115) failed
[20535] (09/23 00:01:37.525):{0x25E8} {fname.surname@mydomain.co.uk} RescanPIMItems - Failed getting contents table (type=Address Book) (0x80040115)
[20539] (09/23 00:01:37.525):{0x25E8} {fname.surname@mydomain.co.uk} RescanPIMItems(1) failed: ERR_NETWORK_PROBLEM
[40690] (09/23 00:01:37.743):{0x1430} {fname.surname@mydomain.co.uk} Starting message status rescan
[40267] (09/23 00:01:38.149):{0x25E0} {fname.surname@mydomain.co.uk} Starting calendar rescan
[40856] (09/23 00:01:38.960):{0x26AC} {fname.surname@mydomain.co.uk} Starting Memos rescan
[20265] (09/23 00:01:39.069):{0x26AC} {fname.surname@mydomain.co.uk} MAPIMailbox::RescanPIMItems - GetProps (0x80040115) failed
[20535] (09/23 00:01:39.069):{0x26AC} {fname.surname@mydomain.co.uk} RescanPIMItems - Failed getting contents table (type=Memos) (0x80040115)
[20539] (09/23 00:01:39.069):{0x26AC} {fname.surname@mydomain.co.uk} RescanPIMItems(3) failed: ERR_NETWORK_PROBLEM
[20265] (09/23 00:01:43.484):{0x1430} {@mydomain.co.uk} MAPIMailbox::GetMessageStatusUpdates - GetContentsTable (0x80004005) failed
[20274] (09/23 00:01:43.484):{0x1430} {@mydomain.co.uk} GetMessageStatusUpdates() failed
[20709] (09/23 00:01:43.484):{0x2318} {mydomain.co.uk} Failed to reach user's mailbox
[20709] (09/23 00:01:43.484):{0x2B0C} {@mydomain.co.uk} Failed to reach user's mailbox
[20265] (09/23 00:01:43.499):{0x1A60} {mydomain.co.uk} MAPIMailbox::GetNewMovedMessages - GetContentsTable (0x80004005) failed
[20275] (09/23 00:01:43.499):{0x1A60} {@mydomain.co.uk} GetNewMovedMessages() failed
[20709] (09/23 00:01:43.499):{0x2514} {@mydomain.co.uk} Failed to reach user's mailbox
[40720] (09/23 00:01:43.499):{0x26EC} MAPI call failed. Error 'The client operation failed.', LowLevelError 1005, Component 'Microsoft Exchange Server Information Store', Context 1281
[20171] (09/23 00:01:43.499):{0x26EC} {@mydomain.co.uk} ReloadPagerConfig() failed
[20709] (09/23 00:01:43.515):{0x1BD8} {@mydomain.co.uk} Failed to reach user's mailbox
[20265] (09/23 00:01:43.531):{0x25F8} {@mydomain.co.uk} MAPIMailbox::CheckLastChangeTime - GetProps (0x80004005) failed
[20048] (09/23 00:01:43.593):{0x25E0} CalICSAgent:: - LoadSyncInfo Open Root Folder(0x80040115) failed for
[20043] (09/23 00:01:43.593):{0x25E0} CalICSAgent:
ynch: Could not reload synchronization state for
[30243] (09/23 00:01:43.593):{0x25E0} {@mydomain.co.uk} MAPIMailbox:
oICS ICS Synch failed
[20280] (09/23 00:01:43.593):{0x25E0} {@mydomain.co.uk} DoICS() failed: ERR_FAIL
[20265] (09/23 00:01:43.593):{0x277C} {@mydomain.co.uk} MAPIMailbox::GetNewMessages - GetProps (0x80040115) failed
[20001] (09/23 00:01:43.593):{0x25E0} {@mydomain.co.uk} CalSyncState::GetSyncStateTable - GetContentsTable(0x80040115) failed
[20265] (09/23 00:01:43.593):{0x277C} {k@mydomain.co.uk} MAPIMailbox::GetNewMessages - GetContentsTable (0x80040115) failed
[40720] (09/23 00:01:43.593):{0x25E0} MAPI call failed. Error 'Network problems are preventing connection to the Microsoft Exchange Server computer.', LowLevelError 1702, Component 'Microsoft Exchange Server Information Store', Context 1300
[20004] (09/23 00:01:43.593):{0x25E0} {@mydomain.co.uk} CalSyncState::QueryByRefId - GetSyncStateTable failed
[40720] (09/23 00:01:43.593):{0x277C} MAPI call failed. Error 'Network problems are preventing connection to the Microsoft Exchange Server computer.',
I am on AT&T. Please edit your Personal Profile with your DEVICE TYPE, DEVICE OS and Carrier
09-24-2012 10:03 AM
I would try this first
How to recreate the BlackBerry Enterprise Server MAPI profile
Click here to Backup the data on your BlackBerry Device! It's important, and FREE!
BESAdmin's, please make a signature with your BES environment info. SIM Free BlackBerry Unlocking FAQ
Follow me on Twitter @knottyrope
I am on AT&T. Please edit your Personal Profile with your DEVICE TYPE, DEVICE OS and Carrier
09-24-2012 10:04 AM
I would also upgrade CDO as well. 8244 is out
CDO 1.2.1 from here
http://www.microsoft.com/downloads/details.aspx?Fa
KB15779 How to install or upgrade CDO 1.2.1
Click here to Backup the data on your BlackBerry Device! It's important, and FREE!
BESAdmin's, please make a signature with your BES environment info. SIM Free BlackBerry Unlocking FAQ
Follow me on Twitter @knottyrope
09-24-2012 03:41 PM
I am on AT&T. Please edit your Personal Profile with your DEVICE TYPE, DEVICE OS and Carrier
09-24-2012 03:56 PM
glad to hear you are doing better
Click here to Backup the data on your BlackBerry Device! It's important, and FREE!
BESAdmin's, please make a signature with your BES environment info. SIM Free BlackBerry Unlocking FAQ
Follow me on Twitter @knottyrope