Cant activate a new user

Mikevacs · 04-06-2010

So I upgraded from BPS to BES express.

It wasnt the smoothest move. I started the install and found out later that I wasnt able to migrate users over from BPS to BES.

So I installed BES, and removed BPS. I was just going to reactivate all my users phones, but once I added the users into BES, their phones just started talking.

When I installed BES, I also used the old SRP and SRP key from BPS.

Now I am having issues trying to activate a new user. I cannot activate a user either over USB or Wireless.

I never had issues before and always used wireless activation.

Firewall has no email spam filter on it.

When I try to activate a user over wireless the phone sits at just activating xxxx@xxxx.com

After a while im sure it times out because I get an error

"The server is not responding. Please contact your system administrator."

In BAS I can see the activation hung at

"Waiting for recognition of valid user activation response before continuing with processing."

I have tried two different accounts, one which even had BES working. (I removed the user and then tried to reactivate)

When I did install BES Express, i used the existing bpsadmin account, instead of creating a new BES admin account.

Please help!

I used my one free support case with blackberry, and have gotten no where!

knottyrope · 06-25-2008

I also used the old SRP and SRP key from BPS.

that might be why

What do your MAGT or MDAT logs show when you activate?

default location :\Program Files\Research In Motion\BlackBerryEnterprise Server\Logs

Also look in event veiwer to seef if any errors or warnings pertain to your issue

Please post back the log info minus personal info

to isolate an affected user on a static messaging agent , then look for the MAGT log for that agent (while trying to send/receive messages). The format you'll be looking for is:

BESName_MAGT_##_yyyymmdd_####.txt

Where the first pair of digits are the Agent ID you set.

Click "Accept as Solution" if your problem is solved. To give thanks, click thumbs up
Click to search the Knowledge Base at BTSC and click to Read The Fabulous Manuals
BESAdmin's, please make a signature with your BES environment info. BlackBerry Unlocking FAQ

Mikevacs · 04-06-2010

Hi Knotty, I dont know how you are so knowledgeable with BES, but its a blessing!

The IEMSTEST.exe is failing at configuring user as well.

I dont see any errors in event logs under system or application.

Also I was talking to you in another post about my BES looking like it has two databases?

BES_MAGT_210_0001.txt

UTF-8 encoded
[30000] (02/07 13:29:45.140):{0xA190} Current Date: 2012/02/07
[30000] (02/07 13:29:45.140):{0xA190} [DIAG] EVENT=Thread_report, THREADID=0xA190, THREADNAME="DebugLogger"
[30000] (02/07 13:29:45.140):{0xA190} [ENV] Current Process: D:\Program Files\Research In Motion\BlackBerry Enterprise Server\BlackBerryAgent.exe
[30000] (02/07 13:29:45.140):{0xA190} [ENV] Current Process logged on as: SOUTHBROOK\bpsadmin, Start Time: Feb 07 2012 13:29:45, Uptime (seconds): 0
[30000] (02/07 13:29:45.140):{0xA190} [ENV] Current Process id: 44264
[30000] (02/07 13:29:45.140):{0xA190} [ENV] Computer Host Name: SBW-SBS, OS Version: 5.2, Build number 3790, ServicePack Major 2, ServicePack Minor 0
[30000] (02/07 13:29:45.140):{0xA190} [ENV] Processor Identifier: x86 Family 6 Model 15 Stepping 7
[30000] (02/07 13:29:45.140):{0xA190} [ENV] Processor Vendor Identifier: GenuineIntel
[30000] (02/07 13:29:45.140):{0xA190} [ENV] Processor Name String: Intel(R) Xeon(R) CPU E5335 @ 2.00GHz
[30000] (02/07 13:29:45.140):{0xA190} [ENV] Number of Processors: 8
[30000] (02/07 13:29:45.156):{0xA190} [ENV] 93 percent of memory in use.
[30000] (02/07 13:29:45.156):{0xA190} [ENV] Total size of physical memory: 4193264 KB
[30000] (02/07 13:29:45.156):{0xA190} [ENV] Size of physical memory available: 280740 KB
[30000] (02/07 13:29:45.156):{0xA190} [ENV] Size of the committed memory limit: 10310320 KB
[30000] (02/07 13:29:45.156):{0xA190} [ENV] Size of available memory to commit: 4735340 KB
[30000] (02/07 13:29:45.156):{0xA190} [ENV] Total size of the user mode portion of the virtual address space of the BES process: 2097024 KB
[30000] (02/07 13:29:45.156):{0xA190} [ENV] Size of unreserved and uncommitted memory in the user mode portion of the virtual address space of the BES process: 2056444 KB
[30000] (02/07 13:29:45.156):{0xA190} [ENV] Size of private memory of the BES process: 3540 KB
[30000] (02/07 13:29:45.156):{0xA190} [ENV] Total HD bytes: 363318808576, Total free bytes = 191095476224
[30000] (02/07 13:29:45.171):{0xA190} [ENV] System up time: 53 days 19 hrs 19 mins 29 secs
[30000] (02/07 13:29:45.171):{0xA190} [ENV] Local Time Zone: Eastern Daylight Time (GMT-05:00)
[30000] (02/07 13:29:45.171):{0xA190} [ENV] TCP/IP provider (Winsock) File Version: 5.2.3790.0 (C:\WINDOWS\System32\wsock32.dll)
[30000] (02/07 13:29:45.171):{0xA190} [ENV] Local machine IP Address = 192.168.0.1
[30000] (02/07 13:29:45.171):{0xA190} [ENV] Winsock2 file: Tcpip, Version: 5.2.3790.4318 (C:\WINDOWS\System32\mswsock.dll)
[30000] (02/07 13:29:45.171):{0xA190} [ENV] Winsock2 file: NTDS, Version: 5.2.3790.3959 (C:\WINDOWS\System32\winrnr.dll)
[30000] (02/07 13:29:45.171):{0xA190} [ENV] Winsock2 file: Network Location Awareness (NLA) Namespace, Version: 5.2.3790.4318 (C:\WINDOWS\System32\mswsock.dll)
[30000] (02/07 13:29:45.187):{0xA190} [ENV] Microsoft XML Parser 6.0: C:\WINDOWS\system32\msxml6.dll, Version: 6.20.2003.0
[30000] (02/07 13:29:45.625):{0xA190} [ENV] MSSQL OLEDB provider is available: [SQLOLEDB]
[30000] (02/07 13:29:45.625):{0xA190} [ENV] SQLCLI provider is available: [SQLNCLI]
[30000] (02/07 13:29:45.625):{0xA190} [ENV] SQLCLI provider is available: [SQLNCLI10]
[30000] (02/07 13:29:45.640):{0xA190} [ENV] MDAC version: 2.82.3959.0
[30000] (02/07 13:29:45.640):{0xA190} [ENV] MSADO15.DLL: C:\Program Files\Common Files\System\ado\msado15.dll, Version: 2.82.4795.0
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: System, ID: 4
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: smss.exe, ID: 428
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: csrss.exe, ID: 476
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: winlogon.exe, ID: 508
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: services.exe, ID: 556
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: lsass.exe, ID: 568
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: svchost.exe, ID: 784
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: svchost.exe, ID: 900
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: svchost.exe, ID: 996
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: svchost.exe, ID: 1032
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: svchost.exe, ID: 1060
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: svchost.exe, ID: 1328
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: spoolsv.exe, ID: 1568
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: msdtc.exe, ID: 1596
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: avgagent.exe, ID: 1708
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: dsm_sa_eventmgr32.exe, ID: 1972
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: dsm_sa_datamgr32.exe, ID: 1992
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: dfssvc.exe, ID: 2016
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: dns.exe, ID: 2060
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: ekrn.exe, ID: 2112
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: era.exe, ID: 2176
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: svchost.exe, ID: 2192
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: inetinfo.exe, ID: 2296
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: jqs.exe, ID: 2320
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: AgentMon.exe, ID: 2352
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: llssrv.exe, ID: 2460
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: LMIGuardianSvc.exe, ID: 2492
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: LTSvcMon.exe, ID: 2748
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: mr2kserv.exe, ID: 3088
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: sqlservr.exe, ID: 3592
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: sqlservr.exe, ID: 3604
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: sqlservr.exe, ID: 3628
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: svchost.exe, ID: 3656
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: ntfrs.exe, ID: 3684
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: dsm_om_shrsvc32.exe, ID: 236
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: svchost.exe, ID: 4536
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: pg_ctl.exe, ID: 4588
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: svchost.exe, ID: 4620
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: sbscrexe.exe, ID: 4652
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 4712
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: dsm_om_connsvc32.exe, ID: 4736
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: ShadowProtectSvc.exe, ID: 4808
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: ShadowProtectSvc.exe, ID: 4836
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 4932
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 5088
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 5100
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: OWSTIMER.EXE, ID: 5872
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: sqlagent.EXE, ID: 5896
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: sqlbrowser.exe, ID: 6004
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: sqlwriter.exe, ID: 6036
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: lserver.exe, ID: 2612
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: wins.exe, ID: 3804
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: WinVNC4.exe, ID: 3588
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: wlcollector.exe, ID: 4168
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: Apache.exe, ID: 4328
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: Apache.exe, ID: 4404
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: Apache.exe, ID: 4632
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 6540
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: Apache.exe, ID: 6752
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: tcpsvcs.exe, ID: 6772
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: exmgmt.exe, ID: 6808
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: Apache.exe, ID: 6984
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: Apache.exe, ID: 7160
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: mad.exe, ID: 7332
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: mssearch.exe, ID: 8068
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: vsnapvss.exe, ID: 6336
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: svchost.exe, ID: 6368
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: store.exe, ID: 7768
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: wmiprvse.exe, ID: 9112
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 7924
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 9564
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 12356
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 12620
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: svchost.exe, ID: 14096
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: svchost.exe, ID: 14752
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: w3wp.exe, ID: 18360
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 20472
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: wscript.exe, ID: 17756
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: wscript.exe, ID: 3532
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 1252
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 25152
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 14460
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 13740
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 21924
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 18528
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 21428
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 22900
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 25272
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 23544
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 21536
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 24632
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 14664
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 4672
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 22876
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 22380
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 24056
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 17388
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 24860
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 24988
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 21124
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 18800
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: LTSVC.exe, ID: 21844
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 16120
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 2572
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 22880
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 22252
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 27644
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 27272
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 27780
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 27600
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 22764
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 23328
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 27104
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 22100
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 24596
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 28292
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 26104
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 30188
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 29568
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 29108
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 29560
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 24148
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 22024
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 29140
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 29404
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 31888
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 28036
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: wscript.exe, ID: 33020
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: wscript.exe, ID: 34352
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: logon.scr, ID: 33568
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: csrss.exe, ID: 30936
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: winlogon.exe, ID: 38516
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: rdpclip.exe, ID: 40060
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: explorer.exe, ID: 39552
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: BacsTray.exe, ID: 33376
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: egui.exe, ID: 38012
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: ctfmon.exe, ID: 36488
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: LTTray.exe, ID: 36900
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: sqlmangr.exe, ID: 36568
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: wsc.exe, ID: 36160
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: sqlservr.exe, ID: 41692
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: BAS-NCC.exe, ID: 3048
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: BAS-AS.exe, ID: 3268
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: BESAlert.exe, ID: 42908
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: BBAttachServer.exe, ID: 16104
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: BBAttachMonitor.exe, ID: 16184
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: BlackBerryController.exe, ID: 37660
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: BlackBerryExtension.exe, ID: 15540
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: BlackBerryDispatcher.exe, ID: 38576
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: BlackBerrySyncServer.exe, ID: 41404
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: BlackBerryMailStoreSrvr.exe, ID: 35212
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: BlackBerryPolicyServer.exe, ID: 10448
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: BlackBerryAgent.exe, ID: 18024
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: bmds.exe, ID: 16468
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: BlackberryRouter.exe, ID: 11468
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: CalHelperWS.exe, ID: 18704
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: wmiprvse.exe, ID: 39784
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 3372
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: w3wp.exe, ID: 42540
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: AppMngr.exe, ID: 43888
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: postgres.exe, ID: 45144
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: BBConvert.exe, ID: 17604
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: BBConvert.exe, ID: 43188
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: BBConvert.exe, ID: 44888
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: BBConvert.exe, ID: 16072
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: mmc.exe, ID: 44140
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: iexplore.exe, ID: 44660
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: iexplore.exe, ID: 40192
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: RIMDeviceManager.exe, ID: 18744
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: BbDevMgr.exe, ID: 42672
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: CalHelper.exe, ID: 46988
[30000] (02/07 13:29:45.671):{0xA190} [ENV] Running Process: BlackBerryAgent.exe, ID: 44264
[40000] (02/07 13:29:45.687):{0xA190} [DIAG] EVENT=Syslog_connected, EVENTMSG="Connected to 127.0.0.1"
[50076] (02/07 13:29:45.750):{0xA458} Starting BlackBerry Mailbox Agent 210 for Server SBW-SBS - Version 5.0.3.21
[30000] (02/07 13:29:45.750):{0xAA8C} [DIAG] EVENT=Register_thread, THREADID=0xAA8C, THREADNAME="ExtUDPLogThread"
[50049] (02/07 13:29:45.750):{0xAA8C} Waiting for external UDP log messages on port 4086
[40000] (02/07 13:29:45.750):{0xA458} {DbConfig::setConfigValues} No Encoded SQL Auth keys found
[40000] (02/07 13:29:45.750):{0xA458} {DbConfig::setConfigValues} No SQL Auth keys found
[35060] (02/07 13:29:45.890):{0xAA8C} Controller: This BES Agent is under control of BlackBerry Controller
[35056] (02/07 13:29:45.890):{0xAA8C} Controller: Extension Process Configuration id 1
[30366] (02/07 13:29:45.890):{0xAA8C} Proxy specified for unnamed extension with id 1
[35058] (02/07 13:29:45.890):{0xAA8C} Controller: End of Extension Configuration
[30438] (02/07 13:29:46.984):{0xA458} Waiting for Controller to provide Extension configuration information
[30221] (02/07 13:29:47.109):{0xA458} BES Cryptographic Kernel module is initialized and working properly
[45009] (02/07 13:29:47.109):{0xA458} More cache hit rate: 0.0%, requests: 0, adds: 0, size: 0.0/30.0 Mb
[45014] (02/07 13:29:47.109):{0xA458} ExtMore cache hit rate: 0.0%, requests: 0, adds: 0, size: 0/20000
[45079] (02/07 13:29:47.109):{0xA458} Attachment cache hit rate: 0.0%, requests: 0, adds: 0, size: 0.0/30.0 Mb
[40212] (02/07 13:29:47.109):{0xA458} Address cache hit rate: 0.0%, requests: 0, adds: 0, size: 0
[40328] (02/07 13:29:47.109):{0xA458} Failed cache hit rate: 0.0%, requests: 0, adds: 0, size: 2
[30058] (02/07 13:29:47.156):{0xA458} Starting Database
[30000] (02/07 13:29:47.156):{0xA458} ADO ConnectionTimeout value = 30s
[30000] (02/07 13:29:47.156):{0xA458} ADO ConnectionTimeout for re-initalizing the pool = 45s
[45059] (02/07 13:29:47.234):{0xA458} {ConnectionPool:ostConnectWork()}: Using connection pool with limit of 100 connections
[30000] (02/07 13:29:47.250):{0xA458} [ENV] [DB] Connection String = Server=SBW-SBS\BLACKBERRY;Database=SBWBesexpress;Network=dbmssocn;Trusted_Connection=Yes
[30000] (02/07 13:29:47.250):{0xA458} [ENV] [DB] DBMS Name = Microsoft SQL Server; DBMS Version = 09.00.4035; Provider Name = sqlncli.dll; Provider Friendly Name = Microsoft SQL Native Client; OLE DB Version = 02.80; Provider Version = 9.00.4035.00; Failover Partner =
[35019] (02/07 13:29:47.250):{0xA458} [ENV] [DB] Database Schema Version: 5.0.3 <5.0.2011.02.11>
[30000] (02/07 13:29:47.265):{0xA458} ADO ConnectionTimeout value = 30s
[30000] (02/07 13:29:47.265):{0xA458} ADO ConnectionTimeout for re-initalizing the pool = 45s
[40682] (02/07 13:29:47.281):{0xA458} AddPagerToList(VACS) - UserId=41
[30231] (02/07 13:29:47.296):{0xA458} [CFG] 3DES encryption keys are ENABLED on this server
[30231] (02/07 13:29:47.296):{0xA458} [CFG] AES256 encryption keys are ENABLED on this server
[40000] (02/07 13:29:47.343):{0xA458} [CFG] Trait Settings: EnableLegacyProfileConfig is true
[30054] (02/07 13:29:47.343):{0xA458} Starting Message System
[30055] (02/07 13:29:47.343):{0xA458} Using MAPI profile BlackBerryServer
[40206] (02/07 13:29:47.343):{0xA458} MailboxManager:ubsystemInitialize - Using MAPI profile 'BlackBerryServer'
[30032] (02/07 13:29:47.359):{0xA458} MailboxManager:etProfileProp - HrGetOneProp(BlackBerryServer_Agent210,0x66190003) (0x8004010f)
[30000] (02/07 13:29:47.375):{0xAEB4} [DIAG] EVENT=Register_thread, THREADID=0xAEB4, THREADNAME="PFContactMonitor"
[40000] (02/07 13:29:47.375):{0xA458} Current directory: D:\Program Files\Research In Motion\BlackBerry Enterprise Server
[30000] (02/07 13:29:47.375):{0xA458} [ENV] MAPI32.DLL: C:\WINDOWS\system32\MAPI32.dll, Version: 6.5.7654.12
[30000] (02/07 13:29:47.390):{0xA458} [ENV] CDO.DLL: D:\Program Files\Exchsrvr\bin\cdo.dll, Version: 6.5.7654.12
[30000] (02/07 13:29:47.390):{0xA458} [ENV] Microsoft Management Console (MMC): C:\WINDOWS\system32\mmc.exe, Version: 5.2.3790.3959
[30000] (02/07 13:29:47.390):{0xA458} [ENV] Global Catalog Server (GCS) from the BlackBerry MAPI profile: SBW-SBS.Southbrook.local
[30000] (02/07 13:29:47.390):{0xA458} [ENV] BlackBerry Mailbox Agent 210 - BESX Version 5.0.3.21
[30000] (02/07 13:29:47.390):{0xA458} [ENV] BES Service Name: BlackBerry Mailbox Agent
[30000] (02/07 13:29:47.390):{0xA458} [ENV] BlackBerry Dispatcher: D:\Program Files\Research In Motion\BlackBerry Enterprise Server\BlackBerryDispatcher.exe, Version: 5.0.3.13
[30000] (02/07 13:29:47.390):{0xA458} [ENV] BlackBerry User Administration Service: NOT FOUND
[30000] (02/07 13:29:47.406):{0xA458} [ENV] BlackBerry MDS Connection Service: D:\Program Files\Research In Motion\BlackBerry Enterprise Server\MDS\bin\bmds.exe, Version: 5.0.3.26
[30000] (02/07 13:29:47.406):{0xA458} [ENV] BlackBerry Database Consistency Service: NOT FOUND
[30000] (02/07 13:29:47.406):{0xA458} [ENV] BlackBerry Policy Service: D:\Program Files\Research In Motion\BlackBerry Enterprise Server\BlackBerryPolicyServer.exe, Version: 5.0.3.13
[30000] (02/07 13:29:47.421):{0xA458} [ENV] BlackBerry Synchronization Service: D:\Program Files\Research In Motion\BlackBerry Enterprise Server\SyncServer\BlackBerrySyncServer.exe, Version: 5.0.3.14
[30000] (02/07 13:29:47.437):{0xA458} [ENV] BlackBerry Router: D:\Program Files\Research In Motion\BlackBerry Enterprise Server\BypassRouter\BlackberryRouter.exe, Version: 5.0.3.13
[30000] (02/07 13:29:47.437):{0xA458} [ENV] BlackBerry Controller: D:\Program Files\Research In Motion\BlackBerry Enterprise Server\BlackBerryController.exe, Version: 5.0.3.21
[30000] (02/07 13:29:47.437):{0xA458} [ENV] BlackBerry Alert: D:\Program Files\Research In Motion\BlackBerry Enterprise Server\BESAlert.exe, Version: 5.0.3.14
[30000] (02/07 13:29:47.453):{0xA458} [ENV] BlackBerry Attachment Service is installed remotely on
[35015] (02/07 13:29:47.453):{0xA458} [ENV] Total number of requests for connections: 21
[35014] (02/07 13:29:47.453):{0xA458} [ENV] Number of waits for connections since service was started: 0
[35019] (02/07 13:29:47.453):{0xA458} [ENV] [DB] Database Schema Version: 5.0.3 <5.0.2011.02.11>
[30000] (02/07 13:29:47.453):{0xA458} [ENV] [DB] Connection String = Server=SBW-SBS\BLACKBERRY;Database=SBWBesexpress;Network=dbmssocn;Trusted_Connection=Yes
[30000] (02/07 13:29:47.453):{0xA458} [ENV] [DB] DBMS Name = Microsoft SQL Server; DBMS Version = 09.00.4035; Provider Name = sqlncli.dll; Provider Friendly Name = Microsoft SQL Native Client; OLE DB Version = 02.80; Provider Version = 9.00.4035.00; Failover Partner =
[30480] (02/07 13:29:47.453):{0xA458} [CFG] BES failover mode is ACTIVE
[41120] (02/07 13:29:47.453):{0xA458} [CFG] Address Lookup is enabled
[41122] (02/07 13:29:47.453):{0xA458} [CFG] LDAP search is disabled, LDAP PIM search is disabled, LDAP ALP search is disabled
[41123] (02/07 13:29:47.453):{0xA458} [CFG] Using up to 10 external CDO Helpers in keep-alive mode
[41124] (02/07 13:29:47.453):{0xA458} [CFG] AddOwnerApptID is disabled
[41125] (02/07 13:29:47.453):{0xA458} [CFG] SetLocaleIDs is disabled
[30302] (02/07 13:29:47.453):{0xA458} [CFG] OTAFM hard-delete server support is disabled, rescans are 0x00
[40532] (02/07 13:29:47.453):{0xA458} [CFG] vCard conversion support has been enabled
[40892] (02/07 13:29:47.453):{0xA458} [CFG] GlobalRescan config: messaging, calendar, pim
[41126] (02/07 13:29:47.453):{0xA458} [CFG] EnableExceptionStackTrace is disabled
[40000] (02/07 13:29:47.453):{0xA458} [CFG] MAPI Encoding is 1
[30057] (02/07 13:29:47.453):{0xA458} Using BPSAdmin@xxx.com on Exchange server SBW-SBS
[30183] (02/07 13:29:47.453):{0xA458} ****** The SharedMemMaxSize registry key in SOFTWARE\Microsoft\Windows Messaging Subsystem = 8388608 ******
[30183] (02/07 13:29:47.453):{0xA458} ****** The SharedMemMaxSize registry key in SOFTWARE\Microsoft\Windows Messaging Subsystem\Applications\BESUserAdminService = 8388608 ******
[30183] (02/07 13:29:47.453):{0xA458} ****** The SharedMemMaxSize registry key in SOFTWARE\Microsoft\Windows Messaging Subsystem\Applications\BlackBerryAgent = 8388608 ******
[30183] (02/07 13:29:47.453):{0xA458} ****** The SharedMemMaxSize registry key in SOFTWARE\Microsoft\Windows Messaging Subsystem\Applications\BlackBerryMailStoreSrvr = 8388608 ******
[30183] (02/07 13:29:47.453):{0xA458} ****** The SharedMemMaxSize registry key in SOFTWARE\Microsoft\Windows Messaging Subsystem\Applications\exmgmt = 8388608 ******
[30183] (02/07 13:29:47.453):{0xA458} ****** The SharedMemMaxSize registry key in SOFTWARE\Microsoft\Windows Messaging Subsystem\Applications\Setup = 8388608 ******
[30000] (02/07 13:29:47.468):{0x2F74} [DIAG] EVENT=Register_thread, THREADID=0x2F74, THREADNAME="DBNS Receiver Thread"
[45012] (02/07 13:29:47.468):{0x2F74} Waiting for external UDP log messages on port 4191
[20000] (02/07 13:29:47.484):{0xA458} {NotifyDatabase} : DBNS component is not installed.
[30302] (02/07 13:29:47.546):{0xA458} [CFG] OTAFM hard-delete server support is disabled, rescans are 0x00
[30283] (02/07 13:29:47.546):{0xA458} Wireless Email Reconciliation is ENABLED. Server Support bitmask: 223
[30287] (02/07 13:29:47.546):{0xA458} S/MIME is ENABLED, state bitmask=0x1
[30059] (02/07 13:29:47.546):{0xA458} Loading Global Stats
[30000] (02/07 13:29:47.546):{0x9918} [DIAG] EVENT=Register_thread, THREADID=0x9918, THREADNAME="GlobalStatsThread"
[40722] (02/07 13:29:47.546):{0x9918} Global stats timer set to 15 seconds
[40723] (02/07 13:29:47.546):{0x9918} User stats timer set to 60 seconds
[30000] (02/07 13:29:47.546):{0xABCC} [DIAG] EVENT=Register_thread, THREADID=0xABCC, THREADNAME="SCSWorkerThread"
[40420] (02/07 13:29:47.625):{0xA458} Loading message processing DLLs
[30422] (02/07 13:29:47.859):{0xA458} D:\Program Files\Research In Motion\BlackBerry Enterprise Server\BBAttachBESExtension.dll is loaded by BlackBerryExtension
[40000] (02/07 13:29:47.968):{0xA458} [EXTS] BlackBerry Extension(1) connected.
[30423] (02/07 13:29:47.968):{0xA458} LoadDLL2Ex is called into BESExtension.dll
[30367] (02/07 13:29:47.968):{0xA458} Successfully loaded proxy DLL for 'BBAttachBESExtension' extension 1, Events=000D, Extensions=amr|bmp|doc|docx|dot|dotx|gif|htm|html|jpeg|jpg|mp3|odp|ods|odt|ott|pdf|png|pps|ppsx|ppt|pptx|rtf|tif|tiff|txt|wav|wma|wmf|wpd|xls|xlsx|zip
[30000] (02/07 13:29:47.968):{0xA458} [BIPP] Starting client 'BES [BlackBerryAgent Version 5.0.3.21] - SBW-SBS [MAGT] - Exchange' with UID S28599639_210
[35001] (02/07 13:29:47.968):{0xA458} [BIPP] Using AES256 encryption for BIPP Client
[30000] (02/07 13:29:47.968):{0xA458} ADO ConnectionTimeout value = 30s
[30000] (02/07 13:29:47.968):{0xA458} ADO ConnectionTimeout for re-initalizing the pool = 45s
[40748] (02/07 13:29:47.968):{0xA458} License total: 10000
[45179] (02/07 13:29:48.359):{0xA458} SNMP monitoring started
[40000] (02/07 13:29:48.359):{0xA458} Set shared memory Global\WMIBESAgentCounterPerformanceProvider 261 access: 6
[40000] (02/07 13:29:48.359):{0xA458} Shared memory: [Global\WMIBESAgentCounterPerformanceProvider] for WMI performance counters is reserved.
[40000] (02/07 13:29:48.359):{0xA458} Set shared memory Global\WMIBESAgentCounterPerformanceProvider 261 access: 6
[40000] (02/07 13:29:48.359):{0xA458} Shared memory: [Global\WMIBESAgentCounterPerformanceProvider] for WMI performance counters is reserved.
[40344] (02/07 13:29:48.359):{0xA458} Service is ready to accept SNMP Requests
[40000] (02/07 13:29:48.359):{0xA458} Reserve shared memory: [SBW-SBS-210] for performance counters. Index:: 209
[30060] (02/07 13:29:48.359):{0xA458} Msgs Forwarded 33429
[30061] (02/07 13:29:48.359):{0xA458} Msgs Sent 4383
[30062] (02/07 13:29:48.359):{0xA458} Msgs Expired 0
[30063] (02/07 13:29:48.359):{0xA458} Msgs Filtered 63
[30000] (02/07 13:29:48.359):{0xB66C} [DIAG] EVENT=Register_thread, THREADID=0xB66C, THREADNAME="TimerThread"
[40000] (02/07 13:29:48.359):{0xB66C} [DEBUG] EVENTMSG="TimerThread thread started"
[40216] (02/07 13:29:48.359):{0xA458} Using SRP Transport
[30000] (02/07 13:29:48.359):{0xA458} ADO ConnectionTimeout value = 30s
[30000] (02/07 13:29:48.359):{0xA458} ADO ConnectionTimeout for re-initalizing the pool = 45s
[30000] (02/07 13:29:48.359):{0xB3E8} [DIAG] EVENT=Register_thread, THREADID=0xB3E8, THREADNAME="BIPPa_ClientSession"
[40000] (02/07 13:29:48.359):{0xB3E8} BIPPa_ClientSession thread Id=0xB3E8, Handle=0x724
[30000] (02/07 13:29:48.359):{0xA708} [DIAG] EVENT=Register_thread, THREADID=0xA708, THREADNAME="BIPPa_ClientSessionReceiver"
[45001] (02/07 13:29:48.359):{0xB3E8} [BIPP] Connecting to "localhost"
[40000] (02/07 13:29:48.359):{0xA708} BIPPa_ClientSessionReceiver thread Id=0xA708, Handle=0x728
[40000] (02/07 13:29:48.359):{0xB3E8} BIPPaClient::Connect: Found 1 addresses for host "localhost"
[40000] (02/07 13:29:48.359):{0xB3E8} BIPPaClient::Connect: Host "localhost" @ [127.0.0.1]
[40000] (02/07 13:29:48.359):{0xB3E8} BIPPaClient::Connect: Trying "localhost" @ [127.0.0.1]
[30540] (02/07 13:29:48.375):{0xA458} PIM Rescan Throttling: MaxDomainPIMRescansPerMin = 100
[30540] (02/07 13:29:48.375):{0xA458} PIM Rescan Throttling: MaxAgentPIMRescansPerMin = 50
[30540] (02/07 13:29:48.375):{0xA458} PIM Rescan Throttling: MaxAgentPIMRescansInProcess = 25
[30540] (02/07 13:29:48.375):{0xA458} PIM Rescan Throttling: PIMRescanPollCycleInterval = 2
[30064] (02/07 13:29:48.375):{0xA458} BlackBerry Mailbox Agent 210 for Server SBW-SBS started
[30000] (02/07 13:29:48.375):{0x9C04} [DIAG] EVENT=Register_thread, THREADID=0x9C04, THREADNAME="SCS"
[30000] (02/07 13:29:48.375):{0xA458} [ENV] Loaded MAPI32.DLL: C:\WINDOWS\system32\MAPI32.dll, Version: 6.5.7654.12
[30047] (02/07 13:29:48.375):{0x9C04} Calendar synchronization has been enabled
[30049] (02/07 13:29:48.375):{0x9C04} Starting handhelds
[30000] (02/07 13:29:48.375):{0xA458} [ENV] Loaded EMSMDB32.DLL: C:\WINDOWS\system32\EMSMDB32.DLL, Version: 6.5.7654.12
[30000] (02/07 13:29:48.375):{0xA458} [ENV] Loaded EMSABP32.DLL: C:\WINDOWS\system32\EMSABP32.DLL, Version: 6.5.7638.1
[30000] (02/07 13:29:48.375):{0xA458} [ENV] Server DN: /o=SOUTHBROOK/ou=first administrative group/cn=Configuration/cn=Servers/cn=SBW-SBS
[30000] (02/07 13:29:48.375):{0xA458} [ENV] SRP Routing Info: S28599639
[30000] (02/07 13:29:48.375):{0xAB14} [DIAG] EVENT=Register_thread, THREADID=0xAB14, THREADNAME="StarterThread"
[30000] (02/07 13:29:48.375):{0xA884} [DIAG] EVENT=Register_thread, THREADID=0xA884, THREADNAME="StarterThread"
[30000] (02/07 13:29:48.375):{0x1404} [DIAG] EVENT=Register_thread, THREADID=0x1404, THREADNAME="StarterThread"
[30000] (02/07 13:29:48.375):{0xB264} [DIAG] EVENT=Register_thread, THREADID=0xB264, THREADNAME="StarterThread"
[30000] (02/07 13:29:48.375):{0x1EB4} [DIAG] EVENT=Register_thread, THREADID=0x1EB4, THREADNAME="StarterThread"
[30041] (02/07 13:29:48.390):{0xAB14} Starting handheld for VACS
[40704] (02/07 13:29:48.390):{0xAB14} MAPIMailbox::MAPIMailbox ServerDN=/o=SOUTHBROOK/ou=first administrative group/cn=Configuration/cn=Servers/cn=SBW-SBS/cn=Microsoft Private MDB, MailboxDN=/o=SOUTHBROOK/ou=first administrative group/cn=Recipients/cn=VACS
[40204] (02/07 13:29:48.390):{0xAB14} MailboxManager::GetMailboxSession - Using MAPI profile 'BlackBerryServer_Agent210'
[40000] (02/07 13:29:48.390):{0xB3E8} BIPPaClient::Authenticate: Authentication successful
[40000] (02/07 13:29:48.390):{0xB3E8} [BIPP] Send info, Features=0x00000001
[40000] (02/07 13:29:48.390):{0xB3E8} [SRP] Send info, ComplianceLevel=0x05
[40000] (02/07 13:29:48.390):{0xB3E8} [BIPP] Send info, Reason=2
[30294] (02/07 13:29:48.390):{0xB3E8} SRP connection established
[30294] (02/07 13:29:48.390):{0xB3E8} [BIPPa] Connection established
[30000] (02/07 13:29:48.390):{0xB728} [DIAG] EVENT=Register_thread, THREADID=0xB728, THREADNAME="BIPPa_ClientReceiver"
[40000] (02/07 13:29:48.390):{0xB728} BIPPa_ClientReceiver thread Id=0xB728, Handle=0x83c
[40000] (02/07 13:29:48.390):{0xA708} [BIPP] CONFIG received
[40000] (02/07 13:29:48.390):{0xA708} [BIPP] Received datagram, Tag=55889
[35005] (02/07 13:29:48.390):{0xA708} GetConfigFromDispatcher: ESPolicy Server Support is DISABLED
[45072] (02/07 13:29:48.390):{0xA708} Processed config stream from Dispatcher, Tag=55889
[40000] (02/07 13:29:48.390):{0xA708} [BIPP] Received datagram, Tag=55890
[35016] (02/07 13:29:48.390):{0xA708} GetConfigFromDispatcher: Failover mode is ACTIVE
[45072] (02/07 13:29:48.390):{0xA708} Processed config stream from Dispatcher, Tag=55890
[30520] (02/07 13:29:48.390):{0xA708} Sending REPORT_HEALTH_SCORE command to Dispatcher, Health=0x000000000200150F, Mask=0x000000000200150F, Users=0, Servers=0
[40000] (02/07 13:29:48.390):{0xA708} [BIPP] Received datagram, Tag=55891
[45072] (02/07 13:29:48.390):{0xA708} Processed config stream from Dispatcher, Tag=55891
[40430] (02/07 13:29:48.390):{0xAB14} {VACS@xxxx.com} MAPIMailbox::MAPIMailbox - Mailbox opened on session 0x03CA2838 (SBW-SBS)
[30554] (02/07 13:29:48.390):{0xAB14} {VACS@xxxx.com} Exchange version: 06.00.7654.012
[40193] (02/07 13:29:48.406):{0xAB14} {VACS@xxxx.com} MAPIMailbox:etRefIdSearchCriteria - creating search folder
[40992] (02/07 13:29:48.406):{0xAB14} {VACS@xxxx.com} MAPIMailbox:etMsgOnDeviceSearchCriteria - creating search folder
[40153] (02/07 13:29:48.406):{0xAB14} PMDatabase:penNamedFolder: opening existing folder 'BlackBerryHandheldInfo'
[40155] (02/07 13:29:48.406):{0xAB14} PMDatabase:penNamedFolder: opened an existing folder 'BlackBerryHandheldInfo'
[41079] (02/07 13:29:48.437):{0xAB14} {VACS@xxxx.com} PagerImp:aveChanges: changed items = 1, 2, 3, 4, 5, 6, 9, 27, 31, 32, 33, 43, 54, 83, 90, 92, 93, 94, 98 Size = 100
[40418] (02/07 13:29:48.437):{0xAB14} Starting thread pool for session 0x03CA2838 (SBW-SBS) with 1 worker thread
[30068] (02/07 13:29:48.437):{0xAB14} User VACS@xxxx.com starting up...
[40637] (02/07 13:29:48.437):{0xAB14} {VACS@xxxx.com} Mailbox Advise, Connection=2
[30069] (02/07 13:29:48.453):{0xAB14} {VACS@xxxx.com} Mailbox name is VACS
[40221] (02/07 13:29:48.453):{0xAB14} {VACS@xxxx.com} In Cradle timer is 854000
[30000] (02/07 13:29:48.453):{0xA944} [DIAG] EVENT=Register_thread, THREADID=0xA944, THREADNAME="UserWorkerThread<SBW-SBS>"
[30362] (02/07 13:29:48.875):{0x9C04} This agent will handle 1 user(s)
[30000] (02/07 13:29:48.875):{0x9C04} Optimize ThreadPools, total number of started users 1
[40413] (02/07 13:29:48.875):{0x9C04} Before optimization: total number of worker threads 1, where 1 non-removable
[40416] (02/07 13:29:48.875):{0x9C04} Thread pool for session 0x03CA2838 (SBW-SBS) has 1 threads to serve 1 handhelds
[40417] (02/07 13:29:48.875):{0x9C04} After optimization: total number of worker threads 1, where 1 non-removable
[40748] (02/07 13:29:48.890):{0x9C04} License total: 10000
[30050] (02/07 13:29:48.890):{0x9C04} All handhelds started
[30000] (02/07 13:29:48.890):{0xAAD8} [DIAG] EVENT=Register_thread, THREADID=0xAAD8, THREADNAME="TimerHealth"
[30000] (02/07 13:29:48.890):{0xAA70} [DIAG] EVENT=Register_thread, THREADID=0xAA70, THREADNAME="RegistryMonitor"
[30520] (02/07 13:29:48.890):{0x9C04} Sending REPORT_HEALTH_SCORE command to Dispatcher, Health=0x000000000200150F, Mask=0x000000000200150F, Users=0, Servers=0
[30000] (02/07 13:29:48.890):{0xAB90} [DIAG] EVENT=Register_thread, THREADID=0xAB90, THREADNAME="Unnamed"
[40981] (02/07 13:29:48.890):{0xAB90} PIM Rescan Throttling: Updating table. 1 users remaining to be rescaned.
[40683] (02/07 13:29:48.906):{0xA944} {VACS@xxxx.com} Updating the RefId generation number
[40684] (02/07 13:29:48.906):{0xA944} {VACS@xxxx.com} Updating DataSourceId 'd01c23'
[40861] (02/07 13:29:48.921):{0xA944} {VACS@xxxx.com} New user added to the server - setting pending fields to this server's settings
[50094] (02/07 13:29:48.921):{0xA944} [AUDIT] VACS@xxxx.com - User added to the BES
[40239] (02/07 13:29:48.921):{0xA944} {VACS@xxxx.com} Still handled by desktop
[40000] (02/07 13:29:48.937):{0xA944} {VACS@xxxx.com} UserControl::UpdateMailboxITPolicy - Updating ITPolicy Information in the user's mailbox.
[30160] (02/07 13:29:48.937):{0xA944} {VACS@xxxx.com} GetDeviceId() did not return a PIN, PIN currently is not set for this user
[30179] (02/07 13:29:48.937):{0xA944} {VACS@xxxx.com} OpenStateDB: calendar sync state not found (0x8004010f) creating new state
[40188] (02/07 13:29:48.953):{0xA944} {VACS@xxxx.com} Conflict resolution set to DESKTOP WINS
[41079] (02/07 13:29:48.953):{0xA944} {VACS@xxxx.com} PagerImp:aveChanges: changed items = 4, 23, 24, 42, 44, 45, 51, 52, 53, 59, 65, 75, 76, 77, 84, 86, 87, 88, 89, 95 Size = 2974
[40000] (02/07 13:29:48.984):{0xA944} {VACS@xxxx.com} Queued a SWAP_DATASOURCE_ID for the Policy Server
[40442] (02/07 13:29:48.984):{0xA944} User settings: email=VACS@xxxx.com, routing=VACS@xxxx.com, service=, device=, calendar=1, MDS=1, userOTAFM=0, incradle=0, SMIME=0, sentItems=0, dir=VACS, server=SBW-SBS (session : 0x03ca2838), pending=0
[40367] (02/07 13:29:48.984):{0xA944} {VACS@xxxx.com} User Stats - Msgs: Latency 0s, Sent/Min 0, Received/Min 0
[40702] (02/07 13:29:48.984):{0xA944} {VACS@xxxx.com} Starting message rescan due to user start
[40703] (02/07 13:29:48.984):{0xA944} {VACS@xxxx.com} Message rescan completed
[30000] (02/07 13:29:48.984):{0xA944} {VACS@xxxx.com} Sending data to Dispatcher, size=90, Tag=63330
[40000] (02/07 13:29:48.984):{0xA944} {VACS@xxxx.com} SendToDispatcher, Tag=63330
[40000] (02/07 13:29:48.984):{0xB3E8} [BIPP] Send data, Tag=63330
[40000] (02/07 13:29:48.984):{0xA708} [BIPP] Received status DELIVERED, Tag=63330
[40000] (02/07 13:29:48.984):{0xA708} [BIPP] Received datagram, Tag=55892
[40239] (02/07 13:29:49.000):{0xA944} {VACS@xxxx.com} Still handled by desktop
[30160] (02/07 13:29:49.000):{0xA944} {VACS@xxxx.com} GetDeviceId() did not return a PIN, PIN currently is not set for this user
[41079] (02/07 13:29:49.000):{0xA944} {VACS@xxxx.com} PagerImp:aveChanges: changed items = 96, 97, 100, 101 Size = 0
[40442] (02/07 13:29:49.000):{0xA944} User settings: email=VACS@xxxx.com, routing=VACS@xxxx.com, service=, device=, calendar=1, MDS=1, userOTAFM=0, incradle=0, SMIME=0, sentItems=0, dir=VACS, server=SBW-SBS (session : 0x03ca2838), pending=0
[40239] (02/07 13:29:49.000):{0xA944} {VACS@xxxx.com} Still handled by desktop
[30160] (02/07 13:29:49.000):{0xA944} {VACS@xxxx.com} GetDeviceId() did not return a PIN, PIN currently is not set for this user
[40442] (02/07 13:29:49.000):{0xA944} User settings: email=VACS@xxxx.com, routing=VACS@xxxx.com, service=, device=, calendar=1, MDS=1, userOTAFM=0, incradle=0, SMIME=0, sentItems=0, dir=VACS, server=SBW-SBS (session : 0x03ca2838), pending=0
[30315] (02/07 13:29:49.000):{0xA944} {VACS@xxxx.com} Sending data to PIM Connector, size=321
[40279] (02/07 13:29:49.000):{0xA944} {VACS@xxxx.com} SubmitToRelaySendQ, Tag=63331
[40000] (02/07 13:29:49.000):{0xB3E8} [BIPP] Send data, Tag=63331
[40793] (02/07 13:29:49.000):{0xA944} {VACS@xxxx.com} GetConfig request sent to the PIM Connector. Target=GetConfig
[40000] (02/07 13:29:49.000):{0xA708} [BIPP] Received status DELIVERED, Tag=63331
[30384] (02/07 13:29:49.000):{0xA944} {VACS@xxxx.com} Packet has been delivered to PIM Connector, Tag=63331
[40000] (02/07 13:30:15.171):{0xA708} [BIPP] Received datagram, Tag=55893
[40845] (02/07 13:30:15.187):{0xA944} {VACS@xxxx.com} SetPIMState - Registering for PIM synchronization (bState=1, bRet=1)
[30416] (02/07 13:30:15.187):{0xA944} {VACS@xxxx.com} Established session with PIM Connector
[40799] (02/07 13:30:15.187):{0xA944} {VACS@xxxx.com} Processed request from PIM Connector: cmd=0x5, Tag=55893
[40279] (02/07 13:30:15.187):{0xA944} {VACS@xxxx.com} SubmitToRelaySendQ, Tag=55893
[40000] (02/07 13:30:15.187):{0xB3E8} [BIPP] Send status DATA_ACCEPTED, Tag=55893
[40933] (02/07 13:30:47.375):{0xAEB4} Completed check for changes in public folders. Elapsed time 0 milliseconds
[40239] (02/07 13:30:48.921):{0xA944} {VACS@xxxx.com} Still handled by desktop
[30160] (02/07 13:30:48.921):{0xA944} {VACS@xxxx.com} GetDeviceId() did not return a PIN, PIN currently is not set for this user
[40442] (02/07 13:30:48.921):{0xA944} User settings: email=VACS@xxxx.com, routing=VACS@xxxx.com, service=, device=, calendar=1, MDS=1, userOTAFM=0, incradle=0, SMIME=0, sentItems=0, dir=VACS, server=SBW-SBS (session : 0x03ca2838), pending=0
[40000] (02/07 13:31:15.171):{0xB3E8} [BIPP] Ping 0 sent
[40000] (02/07 13:31:15.171):{0xA708} [BIPP] Ping Response 0 received
[40982] (02/07 13:31:48.375):{0xAB90} PIM Rescan Throttling: Scheduling rescans for 1 users.
[40984] (02/07 13:31:48.375):{0xAB90} PIM Rescan Throttling: Rescan scheduled for userID 41.
[40981] (02/07 13:31:48.375):{0xAB90} PIM Rescan Throttling: Updating table. 1 users remaining to be rescaned.
[40239] (02/07 13:31:48.390):{0xA944} {VACS@xxxx.com} Still handled by desktop
[30160] (02/07 13:31:48.390):{0xA944} {VACS@xxxx.com} GetDeviceId() did not return a PIN, PIN currently is not set for this user
[40442] (02/07 13:31:48.390):{0xA944} User settings: email=VACS@xxxx.com, routing=VACS@xxxx.com, service=, device=, calendar=1, MDS=1, userOTAFM=0, incradle=0, SMIME=0, sentItems=0, dir=VACS, server=SBW-SBS (session : 0x03ca2838), pending=0
[40941] (02/07 13:31:48.390):{0xA944} {VACS@xxxx.com} Triggering a rescan of 0 public folders
[30421] (02/07 13:31:48.390):{0xA944} {VACS@xxxx.com} Unable to get folders from database for PIM PF Rescan (GetListOfUserFolders)
[40000] (02/07 13:32:15.171):{0xB3E8} [BIPP] Ping 1 sent
[40000] (02/07 13:32:15.171):{0xA708} [BIPP] Ping Response 1 received
[40000] (02/07 13:33:15.171):{0xB3E8} [BIPP] Ping 2 sent
[40000] (02/07 13:33:15.171):{0xA708} [BIPP] Ping Response 2 received
[40983] (02/07 13:33:48.406):{0xAB90} PIM Rescan Throttling: All rescans have been scheduled, removing row from database.
[40000] (02/07 13:34:15.171):{0xB3E8} [BIPP] Ping 3 sent
[40000] (02/07 13:34:15.171):{0xA708} [BIPP] Ping Response 3 received
[40000] (02/07 13:35:15.171):{0xB3E8} [BIPP] Ping 4 sent
[40000] (02/07 13:35:15.171):{0xA708} [BIPP] Ping Response 4 received
[40000] (02/07 13:36:15.171):{0xB3E8} [BIPP] Ping 5 sent
[40000] (02/07 13:36:15.171):{0xA708} [BIPP] Ping Response 5 received
[40000] (02/07 13:37:15.171):{0xB3E8} [BIPP] Ping 6 sent
[40000] (02/07 13:37:15.171):{0xA708} [BIPP] Ping Response 6 received
[40000] (02/07 13:38:15.171):{0xB3E8} [BIPP] Ping 7 sent
[40000] (02/07 13:38:15.171):{0xA708} [BIPP] Ping Response 7 received
[40000] (02/07 13:39:15.171):{0xB3E8} [BIPP] Ping 8 sent
[40000] (02/07 13:39:15.171):{0xA708} [BIPP] Ping Response 8 received
[30181] (02/07 13:39:48.375):{0xB66C} Performing system health check (BlackBerry Mailbox Agent 210 - BESX Version 5.0.3.21)
[30509] (02/07 13:39:48.375):{0xB66C} Current health score, Health=0x000000000200150F, Mask=0x000000000200150F
[40360] (02/07 13:39:48.375):{0xB66C} [BIPPa] Health: QSize=0, intMap=0
[40000] (02/07 13:39:52.796):{0xA944} Starting new CDO helper 0110C0E8
[40574] (02/07 13:39:52.984):{0xAA8C} CDO helper 0110C0E8 started, PID 45816
[40000] (02/07 13:39:53.031):{0xA944} {VACS@xxxx.com} StartCalHelper - CDO profile created BES_CDO_44264_41
[40000] (02/07 13:40:15.171):{0xB3E8} [BIPP] Ping 9 sent
[40000] (02/07 13:40:15.171):{0xA708} [BIPP] Ping Response 9 received
[30315] (02/07 13:40:15.187):{0xA944} {VACS@xxxx.com} Sending data to PIM Connector, size=321
[40279] (02/07 13:40:15.203):{0xA944} {VACS@xxxx.com} SubmitToRelaySendQ, Tag=63332
[40000] (02/07 13:40:15.203):{0xB3E8} [BIPP] Send data, Tag=63332
[40793] (02/07 13:40:15.203):{0xA944} {VACS@xxxx.com} GetConfig request sent to the PIM Connector. Target=GetConfig
[40000] (02/07 13:40:15.203):{0xA708} [BIPP] Received status DELIVERED, Tag=63332
[30384] (02/07 13:40:15.203):{0xA944} {VACS@xxxx.com} Packet has been delivered to PIM Connector, Tag=63332
[40000] (02/07 13:40:15.203):{0xA708} [BIPP] Received datagram, Tag=55898
[30418] (02/07 13:40:15.203):{0xA944} {VACS@xxxx.com} Updated PIM config
[40799] (02/07 13:40:15.203):{0xA944} {VACS@xxxx.com} Processed request from PIM Connector: cmd=0x5, Tag=55898
[40279] (02/07 13:40:15.203):{0xA944} {VACS@xxxx.com} SubmitToRelaySendQ, Tag=55898
[40000] (02/07 13:40:15.203):{0xB3E8} [BIPP] Send status DATA_ACCEPTED, Tag=55898

MDAT LOG

<2012-02-07 13:14:37.750 EST>:[5683]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- Access Control data>
<2012-02-07 13:14:37.750 EST>:[5684]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh IT policy>
<2012-02-07 13:14:37.750 EST>:[5685]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, existing RSA option = 0 , new RSA option = 0>
<2012-02-07 13:14:37.750 EST>:[5686]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task Finished>
<2012-02-07 13:15:31.468 EST>:[5687]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = PING, TAG = 900534286, SIZE = 5>
<2012-02-07 13:15:31.468 EST>:[5688]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Receiving, VERSION = 1, COMMAND = PING_RESPONSE, TAG = 900534286, SIZE = 5>
<2012-02-07 13:15:31.468 EST>:[5689]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2012-02-07 13:15:31.468 EST>:[5690]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S28599639[SBW-SBS:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[58]>
<2012-02-07 13:16:31.468 EST>:[5691]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = PING, TAG = 900534287, SIZE = 5>
<2012-02-07 13:16:31.468 EST>:[5692]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Receiving, VERSION = 1, COMMAND = PING_RESPONSE, TAG = 900534287, SIZE = 5>
<2012-02-07 13:16:31.484 EST>:[5693]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2012-02-07 13:16:31.484 EST>:[5694]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S28599639[SBW-SBS:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[58]>
<2012-02-07 13:17:31.484 EST>:[5695]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2012-02-07 13:17:31.484 EST>:[5696]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S28599639[SBW-SBS:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[58]>
<2012-02-07 13:17:31.484 EST>:[5697]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = PING, TAG = 900534288, SIZE = 5>
<2012-02-07 13:17:31.484 EST>:[5698]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Receiving, VERSION = 1, COMMAND = PING_RESPONSE, TAG = 900534288, SIZE = 5>
<2012-02-07 13:18:31.484 EST>:[5699]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2012-02-07 13:18:31.484 EST>:[5700]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S28599639[SBW-SBS:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[58]>
<2012-02-07 13:19:31.484 EST>:[5701]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = PING, TAG = 900534289, SIZE = 5>
<2012-02-07 13:19:31.484 EST>:[5702]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Receiving, VERSION = 1, COMMAND = PING_RESPONSE, TAG = 900534289, SIZE = 5>
<2012-02-07 13:19:31.484 EST>:[5703]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2012-02-07 13:19:31.484 EST>:[5704]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S28599639[SBW-SBS:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[58]>
<2012-02-07 13:19:37.750 EST>:[5705]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task started;>
<2012-02-07 13:19:37.750 EST>:[5706]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, MDSConfiguration updated for :[S28599639[SBW-SBS:3200]]. Connection Id :[133]>
<2012-02-07 13:19:37.750 EST>:[5707]:<MDS-CS_SBW-SBS_MDS-CS_1>:<INFO >:<LAYER = SCM, Loaded network provider: 0/Windows/Native>
<2012-02-07 13:19:37.750 EST>:[5708]:<MDS-CS_SBW-SBS_MDS-CS_1>:<INFO >:<LAYER = SCM, Default network provider : Windows/Native>
<2012-02-07 13:19:37.750 EST>:[5709]:<MDS-CS_SBW-SBS_MDS-CS_1>:<INFO >:<LAYER = SCM, Initializing network connection cache connPerPrincipal=2 connTimeToLive=300000>
<2012-02-07 13:19:37.750 EST>:[5710]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, Initializing srpServers :[S28599639[SBW-SBS:3200]]>
<2012-02-07 13:19:37.750 EST>:[5711]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- Configuration data>
<2012-02-07 13:19:37.765 EST>:[5712]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- Retrieve all server hosts values>
<2012-02-07 13:19:37.765 EST>:[5713]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh group>
<2012-02-07 13:19:37.765 EST>:[5714]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- PIN-Email mapping rows changed: 0>
<2012-02-07 13:19:37.765 EST>:[5715]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh user group membership>
<2012-02-07 13:19:37.765 EST>:[5716]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- HTTP Proxy Mappings>
<2012-02-07 13:19:37.765 EST>:[5717]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh media management>
<2012-02-07 13:19:37.765 EST>:[5718]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- Access Control data>
<2012-02-07 13:19:37.765 EST>:[5719]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh IT policy>
<2012-02-07 13:19:37.765 EST>:[5720]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, existing RSA option = 0 , new RSA option = 0>
<2012-02-07 13:19:37.765 EST>:[5721]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task Finished>
<2012-02-07 13:20:31.484 EST>:[5722]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = PING, TAG = 900534290, SIZE = 5>
<2012-02-07 13:20:31.484 EST>:[5723]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Receiving, VERSION = 1, COMMAND = PING_RESPONSE, TAG = 900534290, SIZE = 5>
<2012-02-07 13:20:31.484 EST>:[5724]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2012-02-07 13:20:31.484 EST>:[5725]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S28599639[SBW-SBS:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[58]>
<2012-02-07 13:21:31.484 EST>:[5726]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = PING, TAG = 900534291, SIZE = 5>
<2012-02-07 13:21:31.484 EST>:[5727]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Receiving, VERSION = 1, COMMAND = PING_RESPONSE, TAG = 900534291, SIZE = 5>
<2012-02-07 13:21:31.500 EST>:[5728]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2012-02-07 13:21:31.500 EST>:[5729]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S28599639[SBW-SBS:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[58]>
<2012-02-07 13:22:31.500 EST>:[5730]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = PING, TAG = 900534292, SIZE = 5>
<2012-02-07 13:22:31.500 EST>:[5731]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2012-02-07 13:22:31.500 EST>:[5732]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S28599639[SBW-SBS:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[58]>
<2012-02-07 13:22:31.500 EST>:[5733]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Receiving, VERSION = 1, COMMAND = PING_RESPONSE, TAG = 900534292, SIZE = 5>
<2012-02-07 13:23:31.500 EST>:[5734]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = PING, TAG = 900534293, SIZE = 5>
<2012-02-07 13:23:31.500 EST>:[5735]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Receiving, VERSION = 1, COMMAND = PING_RESPONSE, TAG = 900534293, SIZE = 5>
<2012-02-07 13:23:31.500 EST>:[5736]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2012-02-07 13:23:31.500 EST>:[5737]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S28599639[SBW-SBS:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[58]>
<2012-02-07 13:24:31.500 EST>:[5738]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = PING, TAG = 900534294, SIZE = 5>
<2012-02-07 13:24:31.500 EST>:[5739]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Receiving, VERSION = 1, COMMAND = PING_RESPONSE, TAG = 900534294, SIZE = 5>
<2012-02-07 13:24:31.515 EST>:[5740]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2012-02-07 13:24:31.515 EST>:[5741]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S28599639[SBW-SBS:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[58]>
<2012-02-07 13:24:37.765 EST>:[5742]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task started;>
<2012-02-07 13:24:37.765 EST>:[5743]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, MDSConfiguration updated for :[S28599639[SBW-SBS:3200]]. Connection Id :[133]>
<2012-02-07 13:24:37.781 EST>:[5744]:<MDS-CS_SBW-SBS_MDS-CS_1>:<INFO >:<LAYER = SCM, Loaded network provider: 0/Windows/Native>
<2012-02-07 13:24:37.781 EST>:[5745]:<MDS-CS_SBW-SBS_MDS-CS_1>:<INFO >:<LAYER = SCM, Default network provider : Windows/Native>
<2012-02-07 13:24:37.781 EST>:[5746]:<MDS-CS_SBW-SBS_MDS-CS_1>:<INFO >:<LAYER = SCM, Initializing network connection cache connPerPrincipal=2 connTimeToLive=300000>
<2012-02-07 13:24:37.781 EST>:[5747]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, Initializing srpServers :[S28599639[SBW-SBS:3200]]>
<2012-02-07 13:24:37.781 EST>:[5748]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- Configuration data>
<2012-02-07 13:24:37.781 EST>:[5749]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- Retrieve all server hosts values>
<2012-02-07 13:24:37.781 EST>:[5750]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh group>
<2012-02-07 13:24:37.781 EST>:[5751]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- PIN-Email mapping rows changed: 0>
<2012-02-07 13:24:37.781 EST>:[5752]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh user group membership>
<2012-02-07 13:24:37.781 EST>:[5753]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- HTTP Proxy Mappings>
<2012-02-07 13:24:37.781 EST>:[5754]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh media management>
<2012-02-07 13:24:37.781 EST>:[5755]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- Access Control data>
<2012-02-07 13:24:37.796 EST>:[5756]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh IT policy>
<2012-02-07 13:24:37.796 EST>:[5757]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, existing RSA option = 0 , new RSA option = 0>
<2012-02-07 13:24:37.796 EST>:[5758]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task Finished>
<2012-02-07 13:25:31.515 EST>:[5759]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2012-02-07 13:25:31.515 EST>:[5760]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S28599639[SBW-SBS:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[58]>
<2012-02-07 13:25:31.515 EST>:[5761]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = PING, TAG = 900534295, SIZE = 5>
<2012-02-07 13:25:31.515 EST>:[5762]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Receiving, VERSION = 1, COMMAND = PING_RESPONSE, TAG = 900534295, SIZE = 5>
<2012-02-07 13:26:31.515 EST>:[5763]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2012-02-07 13:26:31.515 EST>:[5764]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S28599639[SBW-SBS:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[58]>
<2012-02-07 13:27:31.515 EST>:[5765]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = PING, TAG = 900534296, SIZE = 5>
<2012-02-07 13:27:31.515 EST>:[5766]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Receiving, VERSION = 1, COMMAND = PING_RESPONSE, TAG = 900534296, SIZE = 5>
<2012-02-07 13:27:31.515 EST>:[5767]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2012-02-07 13:27:31.515 EST>:[5768]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S28599639[SBW-SBS:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[58]>
<2012-02-07 13:28:31.515 EST>:[5769]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = PING, TAG = 900534297, SIZE = 5>
<2012-02-07 13:28:31.515 EST>:[5770]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Receiving, VERSION = 1, COMMAND = PING_RESPONSE, TAG = 900534297, SIZE = 5>
<2012-02-07 13:28:31.531 EST>:[5771]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2012-02-07 13:28:31.531 EST>:[5772]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S28599639[SBW-SBS:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[58]>
<2012-02-07 13:29:31.531 EST>:[5773]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2012-02-07 13:29:31.531 EST>:[5774]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S28599639[SBW-SBS:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[58]>
<2012-02-07 13:29:31.531 EST>:[5775]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = PING, TAG = 900534298, SIZE = 5>
<2012-02-07 13:29:31.531 EST>:[5776]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Receiving, VERSION = 1, COMMAND = PING_RESPONSE, TAG = 900534298, SIZE = 5>
<2012-02-07 13:29:37.796 EST>:[5777]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task started;>
<2012-02-07 13:29:37.796 EST>:[5778]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, MDSConfiguration updated for :[S28599639[SBW-SBS:3200]]. Connection Id :[133]>
<2012-02-07 13:29:37.796 EST>:[5779]:<MDS-CS_SBW-SBS_MDS-CS_1>:<INFO >:<LAYER = SCM, Loaded network provider: 0/Windows/Native>
<2012-02-07 13:29:37.812 EST>:[5780]:<MDS-CS_SBW-SBS_MDS-CS_1>:<INFO >:<LAYER = SCM, Default network provider : Windows/Native>
<2012-02-07 13:29:37.812 EST>:[5781]:<MDS-CS_SBW-SBS_MDS-CS_1>:<INFO >:<LAYER = SCM, Initializing network connection cache connPerPrincipal=2 connTimeToLive=300000>
<2012-02-07 13:29:37.812 EST>:[5782]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, Initializing srpServers :[S28599639[SBW-SBS:3200]]>
<2012-02-07 13:29:37.812 EST>:[5783]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- Configuration data>
<2012-02-07 13:29:37.812 EST>:[5784]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- Retrieve all server hosts values>
<2012-02-07 13:29:37.812 EST>:[5785]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh group>
<2012-02-07 13:29:37.812 EST>:[5786]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- PIN-Email mapping rows changed: 0>
<2012-02-07 13:29:37.812 EST>:[5787]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh user group membership>
<2012-02-07 13:29:37.812 EST>:[5788]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- HTTP Proxy Mappings>
<2012-02-07 13:29:37.812 EST>:[5789]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh media management>
<2012-02-07 13:29:37.812 EST>:[5790]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- Access Control data>
<2012-02-07 13:29:37.812 EST>:[5791]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh IT policy>
<2012-02-07 13:29:37.812 EST>:[5792]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, existing RSA option = 0 , new RSA option = 0>
<2012-02-07 13:29:37.812 EST>:[5793]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task Finished>
<2012-02-07 13:30:31.531 EST>:[5794]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2012-02-07 13:30:31.531 EST>:[5795]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S28599639[SBW-SBS:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[59]>
<2012-02-07 13:31:31.531 EST>:[5796]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2012-02-07 13:31:31.531 EST>:[5797]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S28599639[SBW-SBS:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[59]>
<2012-02-07 13:32:31.531 EST>:[5798]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = PING, TAG = 900534299, SIZE = 5>
<2012-02-07 13:32:31.531 EST>:[5799]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Receiving, VERSION = 1, COMMAND = PING_RESPONSE, TAG = 900534299, SIZE = 5>
<2012-02-07 13:32:31.531 EST>:[5800]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2012-02-07 13:32:31.531 EST>:[5801]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S28599639[SBW-SBS:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[59]>
<2012-02-07 13:33:31.531 EST>:[5802]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = PING, TAG = 900534300, SIZE = 5>
<2012-02-07 13:33:31.531 EST>:[5803]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Receiving, VERSION = 1, COMMAND = PING_RESPONSE, TAG = 900534300, SIZE = 5>
<2012-02-07 13:33:31.531 EST>:[5804]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2012-02-07 13:33:31.531 EST>:[5805]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S28599639[SBW-SBS:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[59]>
<2012-02-07 13:34:31.531 EST>:[5806]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = PING, TAG = 900534301, SIZE = 5>
<2012-02-07 13:34:31.531 EST>:[5807]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Receiving, VERSION = 1, COMMAND = PING_RESPONSE, TAG = 900534301, SIZE = 5>
<2012-02-07 13:34:31.546 EST>:[5808]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2012-02-07 13:34:31.546 EST>:[5809]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S28599639[SBW-SBS:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[59]>
<2012-02-07 13:34:37.812 EST>:[5810]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task started;>
<2012-02-07 13:34:37.828 EST>:[5811]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, MDSConfiguration updated for :[S28599639[SBW-SBS:3200]]. Connection Id :[133]>
<2012-02-07 13:34:37.828 EST>:[5812]:<MDS-CS_SBW-SBS_MDS-CS_1>:<INFO >:<LAYER = SCM, Loaded network provider: 0/Windows/Native>
<2012-02-07 13:34:37.828 EST>:[5813]:<MDS-CS_SBW-SBS_MDS-CS_1>:<INFO >:<LAYER = SCM, Default network provider : Windows/Native>
<2012-02-07 13:34:37.828 EST>:[5814]:<MDS-CS_SBW-SBS_MDS-CS_1>:<INFO >:<LAYER = SCM, Initializing network connection cache connPerPrincipal=2 connTimeToLive=300000>
<2012-02-07 13:34:37.828 EST>:[5815]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, Initializing srpServers :[S28599639[SBW-SBS:3200]]>
<2012-02-07 13:34:37.828 EST>:[5816]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- Configuration data>
<2012-02-07 13:34:37.828 EST>:[5817]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- Retrieve all server hosts values>
<2012-02-07 13:34:37.828 EST>:[5818]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh group>
<2012-02-07 13:34:37.828 EST>:[5819]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- PIN-Email mapping rows changed: 0>
<2012-02-07 13:34:37.828 EST>:[5820]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh user group membership>
<2012-02-07 13:34:37.828 EST>:[5821]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- HTTP Proxy Mappings>
<2012-02-07 13:34:37.843 EST>:[5822]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh media management>
<2012-02-07 13:34:37.843 EST>:[5823]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- Access Control data>
<2012-02-07 13:34:37.843 EST>:[5824]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh IT policy>
<2012-02-07 13:34:37.843 EST>:[5825]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, existing RSA option = 0 , new RSA option = 0>
<2012-02-07 13:34:37.843 EST>:[5826]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task Finished>
<2012-02-07 13:35:31.546 EST>:[5827]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2012-02-07 13:35:31.546 EST>:[5828]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S28599639[SBW-SBS:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[59]>
<2012-02-07 13:35:31.546 EST>:[5829]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = PING, TAG = 900534302, SIZE = 5>
<2012-02-07 13:35:31.546 EST>:[5830]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Receiving, VERSION = 1, COMMAND = PING_RESPONSE, TAG = 900534302, SIZE = 5>
<2012-02-07 13:36:31.546 EST>:[5831]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = PING, TAG = 900534303, SIZE = 5>
<2012-02-07 13:36:31.546 EST>:[5832]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2012-02-07 13:36:31.546 EST>:[5833]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S28599639[SBW-SBS:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[59]>
<2012-02-07 13:36:31.546 EST>:[5834]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Receiving, VERSION = 1, COMMAND = PING_RESPONSE, TAG = 900534303, SIZE = 5>
<2012-02-07 13:37:31.546 EST>:[5835]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = PING, TAG = 900534304, SIZE = 5>
<2012-02-07 13:37:31.546 EST>:[5836]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Receiving, VERSION = 1, COMMAND = PING_RESPONSE, TAG = 900534304, SIZE = 5>
<2012-02-07 13:37:31.546 EST>:[5837]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2012-02-07 13:37:31.546 EST>:[5838]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S28599639[SBW-SBS:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[59]>
<2012-02-07 13:38:31.546 EST>:[5839]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = PING, TAG = 900534305, SIZE = 5>
<2012-02-07 13:38:31.546 EST>:[5840]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Receiving, VERSION = 1, COMMAND = PING_RESPONSE, TAG = 900534305, SIZE = 5>
<2012-02-07 13:38:31.562 EST>:[5841]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2012-02-07 13:38:31.562 EST>:[5842]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S28599639[SBW-SBS:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[59]>
<2012-02-07 13:39:31.562 EST>:[5843]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2012-02-07 13:39:31.562 EST>:[5844]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S28599639[SBW-SBS:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[59]>
<2012-02-07 13:39:31.562 EST>:[5845]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = PING, TAG = 900534306, SIZE = 5>
<2012-02-07 13:39:31.562 EST>:[5846]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Receiving, VERSION = 1, COMMAND = PING_RESPONSE, TAG = 900534306, SIZE = 5>
<2012-02-07 13:39:37.843 EST>:[5847]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task started;>
<2012-02-07 13:39:37.843 EST>:[5848]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, MDSConfiguration updated for :[S28599639[SBW-SBS:3200]]. Connection Id :[133]>
<2012-02-07 13:39:37.843 EST>:[5849]:<MDS-CS_SBW-SBS_MDS-CS_1>:<INFO >:<LAYER = SCM, Loaded network provider: 0/Windows/Native>
<2012-02-07 13:39:37.843 EST>:[5850]:<MDS-CS_SBW-SBS_MDS-CS_1>:<INFO >:<LAYER = SCM, Default network provider : Windows/Native>
<2012-02-07 13:39:37.843 EST>:[5851]:<MDS-CS_SBW-SBS_MDS-CS_1>:<INFO >:<LAYER = SCM, Initializing network connection cache connPerPrincipal=2 connTimeToLive=300000>
<2012-02-07 13:39:37.843 EST>:[5852]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, Initializing srpServers :[S28599639[SBW-SBS:3200]]>
<2012-02-07 13:39:37.843 EST>:[5853]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- Configuration data>
<2012-02-07 13:39:37.859 EST>:[5854]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- Retrieve all server hosts values>
<2012-02-07 13:39:37.859 EST>:[5855]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh group>
<2012-02-07 13:39:37.859 EST>:[5856]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- PIN-Email mapping rows changed: 0>
<2012-02-07 13:39:37.859 EST>:[5857]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh user group membership>
<2012-02-07 13:39:37.859 EST>:[5858]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- HTTP Proxy Mappings>
<2012-02-07 13:39:37.859 EST>:[5859]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh media management>
<2012-02-07 13:39:37.859 EST>:[5860]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- Access Control data>
<2012-02-07 13:39:37.859 EST>:[5861]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task- refresh IT policy>
<2012-02-07 13:39:37.859 EST>:[5862]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, existing RSA option = 0 , new RSA option = 0>
<2012-02-07 13:39:37.859 EST>:[5863]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Admin. Task Finished>
<2012-02-07 13:40:31.562 EST>:[5864]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2012-02-07 13:40:31.562 EST>:[5865]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S28599639[SBW-SBS:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[59]>
<2012-02-07 13:41:31.562 EST>:[5866]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SRP, SRPID = S28599639[SBW-SBS:3200], EVENT = Sending, VERSION = 1, COMMAND = INFO, SIZE = 46>
<2012-02-07 13:41:31.562 EST>:[5867]:<MDS-CS_SBW-SBS_MDS-CS_1>:<DEBUG>:<LAYER = SCM, Sent health to S28599639[SBW-SBS:3200] Health=[0x 0000 0007 0000 0000],Mask=[0x 0000 0007 0000 0000],Load=[59]>

knottyrope · 06-25-2008

[40239] (02/07 13:30:48.921):{0xA944} {VACS@xxxx.com} Still handled by desktop
[30160] (02/07 13:30:48.921):{0xA944} {VACS@xxxx.com} GetDeviceId() did not return a PIN, PIN currently is not set for this user

not the correct dataplan or user has used desktop manager installed with BIS/Personal mode with device

It is a BESAdmin rite of passage to go through the gauntlet of correcting the right BES data plan with your carrier.

For BES dataplan testing.

Want to check if a device is provisioned correctly?
Sign up for free to the BlackBerry Expert Support Center.

https://www.blackberry.com/besc/dashboard/

Then use the Enterprise Activation Readiness tool.

All at absolutely no cost to you or your organization!

The BESC gives you a suite of valuable tools, and includes a Complimentary Support Incident should you need to contact BlackBerry Technical Support directly.

Click "Accept as Solution" if your problem is solved. To give thanks, click thumbs up
Click to search the Knowledge Base at BTSC and click to Read The Fabulous Manuals
BESAdmin's, please make a signature with your BES environment info. BlackBerry Unlocking FAQ

Mikevacs · 04-06-2010

Hi Knotty,

The vacs user is a brand new user I created today for testing.

Has no computer to use and hasnt had desktop manager installed or set up.

Would it make sense that when I wipe the phone I am able to wireless activate to an entirely different bes express server at another clients?

Wipe the phone again, and then try to activate vacs@xxxx.com it is giving that error about desktop manager being installed?

I also cant even activate over wired USB with BES Express @xxxx.com.

knottyrope · 06-25-2008

Mikevacs wrote:
Hi Knotty,

The vacs user is a brand new user I created today for testing.
Has no computer to use and hasnt had desktop manager installed or set up.

Would it make sense that when I wipe the phone I am able to wireless activate to an entirely different bes express server at another clients?

Wipe the phone again, and then try to activate vacs@xxxx.com it is giving that error about desktop manager being installed?

I also cant even activate over wired USB with BES Express @xxxx.com.

did you make a mailbox that was not hidden in the GAL for the test user?

Click "Accept as Solution" if your problem is solved. To give thanks, click thumbs up
Click to search the Knowledge Base at BTSC and click to Read The Fabulous Manuals
BESAdmin's, please make a signature with your BES environment info. BlackBerry Unlocking FAQ

Mikevacs · 04-06-2010

The user is not set to be hidden from the GAL.

Ive also tried another user that has never had desktop manager installed or set up, and I get the same error in the MGAT about still being handled by desktop.

knottyrope · 06-25-2008

what data plan is on the phone? i wonder if it is BIS and wont work with your SRP since it is a BPS one.

Click "Accept as Solution" if your problem is solved. To give thanks, click thumbs up
Click to search the Knowledge Base at BTSC and click to Read The Fabulous Manuals
BESAdmin's, please make a signature with your BES environment info. BlackBerry Unlocking FAQ

Mikevacs · 04-06-2010

Is there a way of changing the SRP? Or does that require a whole uninstall and reinstall?

knottyrope · 06-25-2008

before that, what dataplan is on it so we can rule that out

How to locate and change the SRP ID and SRP Authentication Key in BlackBerry Enterprise Server 4.0 t...

Click "Accept as Solution" if your problem is solved. To give thanks, click thumbs up
Click to search the Knowledge Base at BTSC and click to Read The Fabulous Manuals
BESAdmin's, please make a signature with your BES environment info. BlackBerry Unlocking FAQ

BlackBerry® Professional Software and BlackBerry® Enterprise Server Express

Cant activate a new user

Re: Cant activate a new user

Re: Cant activate a new user

Re: Cant activate a new user

Re: Cant activate a new user

Re: Cant activate a new user

Re: Cant activate a new user

Re: Cant activate a new user

Re: Cant activate a new user

Re: Cant activate a new user