[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} {User1@company.com} Receiving packet from device, size=182, TransactionId=-435992664, Tag=1832, content type=CMIME, cmd=0x3
[30112] (04/28 16:12:24.116):{0x11AC} {User1@company.com} Receiving message from device, RefId=1134923011, Tag=1832, TransactionId=-435992664
[45036] (04/28 16:12:24.116):{0x11AC} {User1@company.com} Pending attachment upload, RefId=1134923011, Tag=1832
[45037] (04/28 16:12:24.491):{0x11AC} {User1@company.com} Starting attachment upload, RefId=1134923011, Tag=1832
[40583] (04/28 16:12:24.491):{0x11AC} {User1@company.com} Sending packet to device, Size=53, Tag=4532, TransactionId=-906532730
[40279] (04/28 16:12:24.491):{0x11AC} {User1@company.com} SubmitToRelaySendQ, Tag=4532
[45044] (04/28 16:12:24.491):{0x11AC} {User1@company.com} Sending request for PartId=0, RefId=1134923011
[40279] (04/28 16:12:24.491):{0x11AC} {User1@company.com} 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} {User1@company.com} FindDeviceSendTransaction: transaction not found in current device send list, Tag=4532
[40692] (04/28 16:12:30.272):{0xFEC} {User2@company.com} Starting message moved rescan
[40693] (04/28 16:12:30.272):{0xFEC} {User2@company.com} 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} {User1@company.com} Receiving packet from device, size=15425, TransactionId=-435992663, Tag=1833, content type=CMIME, cmd=0x3
[45038] (04/28 16:12:34.319):{0xFF0} {User1@company.com} 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} {User1@company.com} Upload in progress, pending more chunks. Tag=1833, TransactionId=-435992663, RefId=1134923011, PartId=0
[40279] (04/28 16:12:34.335):{0xFF0} {User1@company.com} 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} {User2@company.com} Interactive request, Tag=1834
[40700] (04/28 16:12:34.335):{0x11C8} {User2@company.com} Receiving packet from device, size=57, TransactionId=640463457, Tag=1834, content type=CMIME, cmd=0x3
[40296] (04/28 16:12:34.335):{0x11C8} {User2@company.com} 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} {User2@company.com} Found original message in More cache, RefId=-915119496, Tag=1834
[40439] (04/28 16:12:34.335):{0x11C8} {User2@company.com} Actual MORE data available, size=6004, Tag=1834, TransactionId=640463457
[40298] (04/28 16:12:34.335):{0x11C8} {User2@company.com} Sending MORE to device, RefId=-915119496, PartId=-2, Offset=1596, Length=3000
[40583] (04/28 16:12:34.335):{0x11C8} {User2@company.com} Sending packet to device, Size=3059, Tag=4533, TransactionId=-906533059
[40279] (04/28 16:12:34.335):{0x11C8} {User2@company.com} SubmitToRelaySendQ, Tag=4533
[40279] (04/28 16:12:34.335):{0x11C8} {User2@company.com} 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} {User2@company.com} 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} {User1@company.com} Receiving packet from device, size=15425, TransactionId=-435992662, Tag=1835, content type=CMIME, cmd=0x3
[45038] (04/28 16:12:37.460):{0x11DC} {User1@company.com} 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} {User1@company.com} Upload in progress, pending more chunks. Tag=1835, TransactionId=-435992662, RefId=1134923011, PartId=0
[40279] (04/28 16:12:37.476):{0x11DC} {User1@company.com} 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} {User1@company.com} Receiving packet from device, size=15425, TransactionId=-435992661, Tag=1836, content type=CMIME, cmd=0x3
[45038] (04/28 16:12:40.210):{0x11E4} {User1@company.com} 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} {User1@company.com} Upload in progress, pending more chunks. Tag=1836, TransactionId=-435992661, RefId=1134923011, PartId=0
[40279] (04/28 16:12:40.225):{0x11E4} {User1@company.com} 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} {User1@company.com} Receiving packet from device, size=15425, TransactionId=-435992660, Tag=1837, content type=CMIME, cmd=0x3
[45038] (04/28 16:12:42.819):{0x11EC} {User1@company.com} 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} {User1@company.com} Upload in progress, pending more chunks. Tag=1837, TransactionId=-435992660, RefId=1134923011, PartId=0
[40279] (04/28 16:12:42.835):{0x11EC} {User1@company.com} 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} {User1@company.com} Receiving packet from device, size=15425, TransactionId=-435992659, Tag=1838, content type=CMIME, cmd=0x3
[45038] (04/28 16:12:45.413):{0x11F8} {User1@company.com} 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} {User1@company.com} Upload in progress, pending more chunks. Tag=1838, TransactionId=-435992659, RefId=1134923011, PartId=0
[40279] (04/28 16:12:45.429):{0x11F8} {User1@company.com} 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} {User2@company.com} Receiving packet from device, size=49, TransactionId=640463458, Tag=1839, content type=CMIME, cmd=0x3
[40698] (04/28 16:12:53.928):{0x11AC} {User2@company.com} Receiving OTAFM request from device, Tag=1839, TransactionId=640463458
[40572] (04/28 16:12:53.928):{0x11AC} {User2@company.com} Receiving MESSAGE_STATUS_UPDATE request from device, Tag=1839, TransactionId=640463458, RefId=-915119496, MsgStatus=1
[40262] (04/28 16:12:53.928):{0x11AC} {User2@company.com} StateDb - Found RefId=-915119496
[40699] (04/28 16:12:54.132):{0x11AC} {User2@company.com} OTAFM request from device processed
[40279] (04/28 16:12:54.132):{0x11AC} {User2@company.com} 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} {User2@company.com} Interactive request, Tag=1840
[40700] (04/28 16:13:20.662):{0x11EC} {User2@company.com} Receiving packet from device, size=57, TransactionId=640463459, Tag=1840, content type=CMIME, cmd=0x3
[40296] (04/28 16:13:20.662):{0x11EC} {User2@company.com} 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} {User2@company.com} Found original message in More cache, RefId=-915119496, Tag=1840
[40439] (04/28 16:13:20.662):{0x11EC} {User2@company.com} Actual MORE data available, size=6004, Tag=1840, TransactionId=640463459
[40298] (04/28 16:13:20.662):{0x11EC} {User2@company.com} Sending MORE to device, RefId=-915119496, PartId=-2, Offset=4596, Length=1408
[40583] (04/28 16:13:20.662):{0x11EC} {User2@company.com} Sending packet to device, Size=1467, Tag=4535, TransactionId=-906533058
[40279] (04/28 16:13:20.662):{0x11EC} {User2@company.com} SubmitToRelaySendQ, Tag=4535
[40279] (04/28 16:13:20.662):{0x11EC} {User2@company.com} 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} {User2@company.com} FindDeviceSendTransaction: transaction not found in current device send list, Tag=4535
[25025] (04/28 16:13:24.490):{0x11F8} {User1@company.com} Upload transaction expired - terminating, RefId=1936703700
[40277] (04/28 16:13:24.599):{0x11F8} {User1@company.com} Sending message error to device for message 1936703700
[40583] (04/28 16:13:24.599):{0x11F8} {User1@company.com} Sending packet to device, Size=44, Tag=4536, TransactionId=-906532729
[40279] (04/28 16:13:24.599):{0x11F8} {User1@company.com} 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} {User1@company.com} 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=jsimmons@outwardbound.org, 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.