[Simplicity Trevor]

A client of mine is running Blackberry Professional Software for Microsoft Exchange 4.1.4 (Bundle 183) with 20 users. Users are unable to send attachments from their phones (e.g. a picture taken on the phone). Forwarding attachments works fine. One user reports that he also recieves MDS errors occasionally.

I've checked the permissions for the BESAdmin account on; SQL(running on a different server), Local Machine Admin and Security Policy, Exchange View Only Admin, and the User Objects. All permissions are correct.

CDO.dll and MAPI32.dll are Version: 6.5.7654.12 on Exchange and BES Server.

As far as I can tell, the envoriment is set up just like RIM wants.

The messages on the handheld have a red X. Most say "Message Status: Error" or "Message Status: Unknown Error". There have also been a few "Message Status: Transaction error - failure at service"

These handhelds have been wiped and reactivated a couple of times. Service books look to contain all the correct information. Everything else seems to be working properly. Email, contacts, calendar, memos, and tasks are sync.



"User1" is who I've been primarily working with. "User2" just had good timing for the logging.

This is from the MAGT Log:

[40700] (04/28 16:12:24.116):{0x11AC} {[email protected]} Receiving packet from device, size=182, TransactionId=-435992664, Tag=1832, content type=CMIME, cmd=0x3
[30112] (04/28 16:12:24.116):{0x11AC} {[email protected]} Receiving message from device, RefId=1134923011, Tag=1832, TransactionId=-435992664
[45036] (04/28 16:12:24.116):{0x11AC} {[email protected]} Pending attachment upload, RefId=1134923011, Tag=1832
[45037] (04/28 16:12:24.491):{0x11AC} {[email protected]} Starting attachment upload, RefId=1134923011, Tag=1832
[40583] (04/28 16:12:24.491):{0x11AC} {[email protected]} Sending packet to device, Size=53, Tag=4532, TransactionId=-906532730
[40279] (04/28 16:12:24.491):{0x11AC} {[email protected]} SubmitToRelaySendQ, Tag=4532
[45044] (04/28 16:12:24.491):{0x11AC} {[email protected]} Sending request for PartId=0, RefId=1134923011
[40279] (04/28 16:12:24.491):{0x11AC} {[email protected]} SubmitToRelaySendQ, Tag=1832
[40000] (04/28 16:12:24.491):{0xFB8} [BIPP] Send data, Tag=4532
[40000] (04/28 16:12:24.491):{0xFB8} [BIPP] Send status DATA_ACCEPTED, Tag=1832
[40000] (04/28 16:12:24.960):{0xFB4} [BIPP] Received status DELIVERED, Tag=4532
[40000] (04/28 16:12:24.960):{0xFE4} {[email protected]} FindDeviceSendTransaction: transaction not found in current device send list, Tag=4532
[40692] (04/28 16:12:30.272):{0xFEC} {[email protected]} Starting message moved rescan
[40693] (04/28 16:12:30.272):{0xFEC} {[email protected]} Message moved rescan completed
[40000] (04/28 16:12:34.319):{0xFB4} [BIPP] Received datagram, Tag=1833
[40700] (04/28 16:12:34.319):{0xFF0} {[email protected]} Receiving packet from device, size=15425, TransactionId=-435992663, Tag=1833, content type=CMIME, cmd=0x3
[45038] (04/28 16:12:34.319):{0xFF0} {[email protected]} Receiving MORE message from device, Tag=1833, TransactionId=-435992663, RefId=1134923011, PartId=0, Offset=0, Length=15360
[45039] (04/28 16:12:34.335):{0xFF0} {[email protected]} Upload in progress, pending more chunks. Tag=1833, TransactionId=-435992663, RefId=1134923011, PartId=0
[40279] (04/28 16:12:34.335):{0xFF0} {[email protected]} SubmitToRelaySendQ, Tag=1833
[40000] (04/28 16:12:34.335):{0xFB8} [BIPP] Send status DATA_ACCEPTED, Tag=1833
[40000] (04/28 16:12:34.335):{0xFB4} [BIPP] Received datagram, Tag=1834
[40664] (04/28 16:12:34.335):{0x11C8} {[email protected]} Interactive request, Tag=1834
[40700] (04/28 16:12:34.335):{0x11C8} {[email protected]} Receiving packet from device, size=57, TransactionId=640463457, Tag=1834, content type=CMIME, cmd=0x3
[40296] (04/28 16:12:34.335):{0x11C8} {[email protected]} Receiving MORE request from device, Tag=1834, TransactionId=640463457, RefId=-915119496, PartId=-2, Offset=1596, Length=3000
[40898] (04/28 16:12:34.335):{0x11C8} {[email protected]} Found original message in More cache, RefId=-915119496, Tag=1834
[40439] (04/28 16:12:34.335):{0x11C8} {[email protected]} Actual MORE data available, size=6004, Tag=1834, TransactionId=640463457
[40298] (04/28 16:12:34.335):{0x11C8} {[email protected]} Sending MORE to device, RefId=-915119496, PartId=-2, Offset=1596, Length=3000
[40583] (04/28 16:12:34.335):{0x11C8} {[email protected]} Sending packet to device, Size=3059, Tag=4533, TransactionId=-906533059
[40279] (04/28 16:12:34.335):{0x11C8} {[email protected]} SubmitToRelaySendQ, Tag=4533
[40279] (04/28 16:12:34.335):{0x11C8} {[email protected]} SubmitToRelaySendQ, Tag=1834
[40000] (04/28 16:12:34.335):{0xFB8} [BIPP] Send data, Tag=4533
[40000] (04/28 16:12:34.335):{0xFB8} [BIPP] Send status DATA_ACCEPTED, Tag=1834
[40000] (04/28 16:12:34.976):{0xFB4} [BIPP] Received status DELIVERED, Tag=4533
[40000] (04/28 16:12:34.976):{0x11D4} {[email protected]} FindDeviceSendTransaction: transaction not found in current device send list, Tag=4533
[40000] (04/28 16:12:37.460):{0xFB4} [BIPP] Received datagram, Tag=1835
[40700] (04/28 16:12:37.460):{0x11DC} {[email protected]} Receiving packet from device, size=15425, TransactionId=-435992662, Tag=1835, content type=CMIME, cmd=0x3
[45038] (04/28 16:12:37.460):{0x11DC} {[email protected]} Receiving MORE message from device, Tag=1835, TransactionId=-435992662, RefId=1134923011, PartId=0, Offset=15360, Length=15360
[45039] (04/28 16:12:37.476):{0x11DC} {[email protected]} Upload in progress, pending more chunks. Tag=1835, TransactionId=-435992662, RefId=1134923011, PartId=0
[40279] (04/28 16:12:37.476):{0x11DC} {[email protected]} SubmitToRelaySendQ, Tag=1835
[40000] (04/28 16:12:37.476):{0xFB8} [BIPP] Send status DATA_ACCEPTED, Tag=1835
[40000] (04/28 16:12:40.210):{0xFB4} [BIPP] Received datagram, Tag=1836
[40700] (04/28 16:12:40.210):{0x11E4} {[email protected]} Receiving packet from device, size=15425, TransactionId=-435992661, Tag=1836, content type=CMIME, cmd=0x3
[45038] (04/28 16:12:40.210):{0x11E4} {[email protected]} Receiving MORE message from device, Tag=1836, TransactionId=-435992661, RefId=1134923011, PartId=0, Offset=30720, Length=15360
[45039] (04/28 16:12:40.225):{0x11E4} {[email protected]} Upload in progress, pending more chunks. Tag=1836, TransactionId=-435992661, RefId=1134923011, PartId=0
[40279] (04/28 16:12:40.225):{0x11E4} {[email protected]} SubmitToRelaySendQ, Tag=1836
[40000] (04/28 16:12:40.225):{0xFB8} [BIPP] Send status DATA_ACCEPTED, Tag=1836
[40000] (04/28 16:12:42.819):{0xFB4} [BIPP] Received datagram, Tag=1837
[40700] (04/28 16:12:42.819):{0x11EC} {[email protected]} Receiving packet from device, size=15425, TransactionId=-435992660, Tag=1837, content type=CMIME, cmd=0x3
[45038] (04/28 16:12:42.819):{0x11EC} {[email protected]} Receiving MORE message from device, Tag=1837, TransactionId=-435992660, RefId=1134923011, PartId=0, Offset=46080, Length=15360
[45039] (04/28 16:12:42.835):{0x11EC} {[email protected]} Upload in progress, pending more chunks. Tag=1837, TransactionId=-435992660, RefId=1134923011, PartId=0
[40279] (04/28 16:12:42.835):{0x11EC} {[email protected]} SubmitToRelaySendQ, Tag=1837
[40000] (04/28 16:12:42.835):{0xFB8} [BIPP] Send status DATA_ACCEPTED, Tag=1837
[40000] (04/28 16:12:45.413):{0xFB4} [BIPP] Received datagram, Tag=1838
[40700] (04/28 16:12:45.413):{0x11F8} {[email protected]} Receiving packet from device, size=15425, TransactionId=-435992659, Tag=1838, content type=CMIME, cmd=0x3
[45038] (04/28 16:12:45.413):{0x11F8} {[email protected]} Receiving MORE message from device, Tag=1838, TransactionId=-435992659, RefId=1134923011, PartId=0, Offset=61440, Length=15360
[45039] (04/28 16:12:45.429):{0x11F8} {[email protected]} Upload in progress, pending more chunks. Tag=1838, TransactionId=-435992659, RefId=1134923011, PartId=0
[40279] (04/28 16:12:45.429):{0x11F8} {[email protected]} SubmitToRelaySendQ, Tag=1838
[40000] (04/28 16:12:45.429):{0xFB8} [BIPP] Send status DATA_ACCEPTED, Tag=1838
[40000] (04/28 16:12:53.928):{0xFB4} [BIPP] Received datagram, Tag=1839
[40700] (04/28 16:12:53.928):{0x11AC} {[email protected]} Receiving packet from device, size=49, TransactionId=640463458, Tag=1839, content type=CMIME, cmd=0x3
[40698] (04/28 16:12:53.928):{0x11AC} {[email protected]} Receiving OTAFM request from device, Tag=1839, TransactionId=640463458
[40572] (04/28 16:12:53.928):{0x11AC} {[email protected]} Receiving MESSAGE_STATUS_UPDATE request from device, Tag=1839, TransactionId=640463458, RefId=-915119496, MsgStatus=1
[40262] (04/28 16:12:53.928):{0x11AC} {[email protected]} StateDb - Found RefId=-915119496
[40699] (04/28 16:12:54.132):{0x11AC} {[email protected]} OTAFM request from device processed
[40279] (04/28 16:12:54.132):{0x11AC} {[email protected]} SubmitToRelaySendQ, Tag=1839
[40000] (04/28 16:12:54.132):{0xFB8} [BIPP] Send status DATA_ACCEPTED, Tag=1839
...
[40000] (04/28 16:13:20.662):{0xFB4} [BIPP] Received datagram, Tag=1840
[40664] (04/28 16:13:20.662):{0x11EC} {[email protected]} Interactive request, Tag=1840
[40700] (04/28 16:13:20.662):{0x11EC} {[email protected]} Receiving packet from device, size=57, TransactionId=640463459, Tag=1840, content type=CMIME, cmd=0x3
[40296] (04/28 16:13:20.662):{0x11EC} {[email protected]} Receiving MORE request from device, Tag=1840, TransactionId=640463459, RefId=-915119496, PartId=-2, Offset=4596, Length=3000
[40898] (04/28 16:13:20.662):{0x11EC} {[email protected]} Found original message in More cache, RefId=-915119496, Tag=1840
[40439] (04/28 16:13:20.662):{0x11EC} {[email protected]} Actual MORE data available, size=6004, Tag=1840, TransactionId=640463459
[40298] (04/28 16:13:20.662):{0x11EC} {[email protected]} Sending MORE to device, RefId=-915119496, PartId=-2, Offset=4596, Length=1408
[40583] (04/28 16:13:20.662):{0x11EC} {[email protected]} Sending packet to device, Size=1467, Tag=4535, TransactionId=-906533058
[40279] (04/28 16:13:20.662):{0x11EC} {[email protected]} SubmitToRelaySendQ, Tag=4535
[40279] (04/28 16:13:20.662):{0x11EC} {[email protected]} SubmitToRelaySendQ, Tag=1840
[40000] (04/28 16:13:20.662):{0xFB8} [BIPP] Send data, Tag=4535
[40000] (04/28 16:13:20.662):{0xFB8} [BIPP] Send status DATA_ACCEPTED, Tag=1840
[40000] (04/28 16:13:21.194):{0xFB4} [BIPP] Received status DELIVERED, Tag=4535
[40000] (04/28 16:13:21.194):{0x11F0} {[email protected]} FindDeviceSendTransaction: transaction not found in current device send list, Tag=4535
[25025] (04/28 16:13:24.490):{0x11F8} {[email protected]} Upload transaction expired - terminating, RefId=1936703700
[40277] (04/28 16:13:24.599):{0x11F8} {[email protected]} Sending message error to device for message 1936703700
[40583] (04/28 16:13:24.599):{0x11F8} {[email protected]} Sending packet to device, Size=44, Tag=4536, TransactionId=-906532729
[40279] (04/28 16:13:24.599):{0x11F8} {[email protected]} SubmitToRelaySendQ, Tag=4536
[40000] (04/28 16:13:24.599):{0xFB8} [BIPP] Send data, Tag=4536
[40000] (04/28 16:13:29.785):{0xFB4} [BIPP] Received status DELIVERED, Tag=4536
[40000] (04/28 16:13:29.785):{0x11FC} {[email protected]} FindDeviceSendTransaction: transaction not found in current device send list, Tag=4536

From the DISP log:

[30222] (04/28 16:12:24.116):{0xC98} {User 1} MFH: contentType=CMIME, sizeOTA=212, sizeOTW=145, TransactionId=-435992664, Tag=224016
[30308] (04/28 16:12:24.116):{0xC98} [BIPPa] {User 1} Forwarding data to BES Agent (SXXXXXXXX_001), size=182, intTag=1832, Tag=224016
[30222] (04/28 16:12:24.491):{0xC9C} {User 1} MTH: contentType=CMIME, sizeOTA=84, sizeOTW=19, TransactionId=-906532730, Tag=5893
[30310] (04/28 16:12:24.491):{0xC9C} {User 1} Forwarding internal data to device, contentType=CMIME, routing=SXXXXXXXX, device=31E81B6F, size=121, cmd=0x3, ack=0, TransactionId=-906532730, intTag=4532, Tag=5893, Submit=1
[30311] (04/28 16:12:24.491):{0xCA0} {User 1} Forwarding status to relay, intTag=1832, Tag=224016, Status=1
[30368] (04/28 16:12:24.960):{0xCA4} {User 1} Packet has been delivered to device, Tag=5893
[30388] (04/28 16:12:24.960):{0xCA4} [BIPPa] {User 1} Forwarding status to BES Agent (SXXXXXXXX_001), intTag=4532, extTag=5893
[30450] (04/28 16:12:31.647):{0xCB4} {Jessica Simmons} User unchanged (disp): id=32, [email protected], device=305F57E7, routing=SXXXXXXXX, agent=001, ext=1, wl=0, keys=(0:A:0)
[30222] (04/28 16:12:34.319):{0xCC4} {User 1} MFH: contentType=CMIME, sizeOTA=15460, sizeOTW=15388, TransactionId=-435992663, Tag=224017
[30308] (04/28 16:12:34.319):{0xCC4} [BIPPa] {User 1} Forwarding data to BES Agent (SXXXXXXXX_001), size=15425, intTag=1833, Tag=224017
[30222] (04/28 16:12:34.319):{0xCC8} {User 2} MFH: contentType=CMIME, sizeOTA=84, sizeOTW=21, TransactionId=640463457, Tag=224018
[30308] (04/28 16:12:34.319):{0xCC8} [BIPPa] {User 2} Forwarding data to BES Agent (SXXXXXXXX_001), size=57, intTag=1834, Tag=224018
[30311] (04/28 16:12:34.335):{0xCCC} {User 1} Forwarding status to relay, intTag=1833, Tag=224017, Status=1
[30222] (04/28 16:12:34.335):{0xC58} {User 2} MTH: contentType=CMIME, sizeOTA=1892, sizeOTW=3024, TransactionId=-906533059, Tag=5894
[30310] (04/28 16:12:34.335):{0xC58} {User 2} Forwarding internal data to device, contentType=CMIME, routing=SXXXXXXXX, device=306CB8BC, size=1930, cmd=0x3, ack=0, TransactionId=-906533059, intTag=4533, Tag=5894, Submit=1
[30311] (04/28 16:12:34.335):{0xC5C} {User 2} Forwarding status to relay, intTag=1834, Tag=224018, Status=1
[30368] (04/28 16:12:34.976):{0xC64} {User 2} Packet has been delivered to device, Tag=5894
[30388] (04/28 16:12:34.976):{0xC64} [BIPPa] {User 2} Forwarding status to BES Agent (SXXXXXXXX_001), intTag=4533, extTag=5894
[30222] (04/28 16:12:37.444):{0xC68} {User 1} MFH: contentType=CMIME, sizeOTA=15460, sizeOTW=15388, TransactionId=-435992662, Tag=224019
[30308] (04/28 16:12:37.444):{0xC68} [BIPPa] {User 1} Forwarding data to BES Agent (SXXXXXXXX_001), size=15425, intTag=1835, Tag=224019
[30311] (04/28 16:12:37.476):{0xC6C} {User 1} Forwarding status to relay, intTag=1835, Tag=224019, Status=1
[30222] (04/28 16:12:40.194):{0xC74} {User 1} MFH: contentType=CMIME, sizeOTA=15460, sizeOTW=15388, TransactionId=-435992661, Tag=224020
[30308] (04/28 16:12:40.194):{0xC74} [BIPPa] {User 1} Forwarding data to BES Agent (SXXXXXXXX_001), size=15425, intTag=1836, Tag=224020
[30311] (04/28 16:12:40.225):{0xC78} {User 1} Forwarding status to relay, intTag=1836, Tag=224020, Status=1
[30222] (04/28 16:12:42.819):{0xC80} {User 1} MFH: contentType=CMIME, sizeOTA=15460, sizeOTW=15388, TransactionId=-435992660, Tag=224021
[30308] (04/28 16:12:42.819):{0xC80} [BIPPa] {User 1} Forwarding data to BES Agent (SXXXXXXXX_001), size=15425, intTag=1837, Tag=224021
[30311] (04/28 16:12:42.835):{0xC84} {User 1} Forwarding status to relay, intTag=1837, Tag=224021, Status=1
[30222] (04/28 16:12:45.413):{0xC90} {User 1} MFH: contentType=CMIME, sizeOTA=15460, sizeOTW=15388, TransactionId=-435992659, Tag=224022
[30308] (04/28 16:12:45.413):{0xC90} [BIPPa] {User 1} Forwarding data to BES Agent (SXXXXXXXX_001), size=15425, intTag=1838, Tag=224022
[30311] (04/28 16:12:45.429):{0xC94} {User 1} Forwarding status to relay, intTag=1838, Tag=224022, Status=1
[30000] (04/28 16:12:46.147):{0x1B0C} SRPClient::ReceivePacket: No received bytes (0)
[50097] (04/28 16:12:46.147):{0x1B10} [SRP] Dispatcher\SRP Connection dropped, Error=0

Looking for some guidance.