Solved

slow GroupWise E-Mail Sync with BES 4.1.6 over SOAP

Posted on 2009-04-08
14
3,029 Views
Last Modified: 2013-11-18
Hello,

I've downloaded and installed the BES trial version 4.1.6 for GroupWise und the MR3 update.
Now I've the problem, that new E-Mails where replicated between 5 and 15 minutes later, than they recieved in the online Mailbox.
I configured the BES to communicate over the SOAP Port with the GroupWise 703 Agents.

The BES can contact the POA over SOAP Port 7191 and the POA can contact the BES over Port 9000

The BES has W2K3 SP2, BES 4.1.6 MR3, SQL2005 Express, GW Client 7.0.3 HP2
The GW Server has SLES 9 SP3, GW POA 7.0.3 without HP.

Has anybody an idear?

Martin
0
Comment
Question by:chief-MH
  • 7
  • 7
14 Comments
 
LVL 26

Expert Comment

by:Gary Cutri
Comment Utility
Hi, the most common causes of this issue are:

1. Low system resources on the BES
2. A large number of email messages in the user's Trash folder.
3. The users have a large number of contacts (over 1000)
4. You have to many events queued by the Novell GroupWise SOAP connector

First try restarting the BES and then check the GroupWise SOAP connector log.
0
 

Author Comment

by:chief-MH
Comment Utility
Hello garycutri,

thanks for your answer.
1. There are enough system resources (i checked it first)
2. In the Trash folder are 24 messages
3. In the synced Adressbook are 134 contacts
4. The Groupwise SOAP queu is empty
5. the GW SOAP connector log can you read in the code colum
6. When I trace the connection, I see the POA talking to the Listenerport 9000, but after that there wouldn't be a transaction from the SOAP port. Some minutes later the BES gets the Mails from the POA over SOAP

I hope this helps you
0
 
LVL 26

Assisted Solution

by:Gary Cutri
Gary Cutri earned 50 total points
Comment Utility
Can you please check the BlackBerry Controller log for errors? Start by searching for the following terms: terminate, exceeded (and then search for any other errors of importance).  The logs are located in c:\Program Files\Research In Motion\BlackBerry Enterprise Server\Logs.
0
 
LVL 26

Expert Comment

by:Gary Cutri
Comment Utility
Also what size is the users mailbox?
0
 
LVL 26

Assisted Solution

by:Gary Cutri
Gary Cutri earned 50 total points
Comment Utility
Sorry another question, is the BES server in the local network of the GroupWise Post Office that holds the users mailbox?
0
 

Author Comment

by:chief-MH
Comment Utility
Here are the Logdetails:
GWCO_01 Log:
 

[30000] (04/08 00:00:05):{0x1784} Current Date: 2009/04/08

[34157] (04/08 00:00:06):{0x960} {GWCO} {GWConnector} Novell GroupWiseObject Library: C:\Novell\GroupWise\\gwcma1.dll, Version: 7.0.3

[34493] (04/08 00:00:06):{0x960} {GWCO} {GWConnector} GWConnector Version: 4.1.6.29

[34444] (04/08 00:00:06):{0x960} {0} {Hamhaber, Martin} User Details: UserConfigID=5, PIN=20760C66, Status=Active, RedirectionEnabled=True

[34058] (04/08 00:00:06):{0x960} {0} {Hamhaber, Martin} User PIM Status: Contacts=Active, Memos=Active, Tasks=Active

[34444] (04/08 00:00:06):{0x960} {1} {Geller, Steffen} User Details: UserConfigID=2, PIN=25062856, Status=Active, RedirectionEnabled=True

[34058] (04/08 00:00:06):{0x960} {1} {Geller, Steffen} User PIM Status: Contacts=Active, Memos=Active, Tasks=Active

[34113] (04/08 00:28:55):{0xE00} {1} {Geller, Steffen} Folders: Start (1/47)

[34112] (04/08 00:28:55):{0xE00} {1} {Geller, Steffen} Folders: Interrupt (8/47)

[34113] (04/08 00:28:57):{0xE00} {1} {Geller, Steffen} Folders: Resume (9/47)

[34112] (04/08 00:28:57):{0xE00} {1} {Geller, Steffen} Folders: Interrupt (25/47)

[34113] (04/08 00:28:59):{0xE00} {1} {Geller, Steffen} Folders: Resume (26/47)

[34112] (04/08 00:28:59):{0xE00} {1} {Geller, Steffen} Folders: Interrupt (35/47)

[34113] (04/08 00:29:01):{0xE00} {1} {Geller, Steffen} Folders: Resume (36/47)

[34111] (04/08 00:29:01):{0xE00} {1} {Geller, Steffen} Folders: End (47/47), Deleted=0/29, Total=29, Subscribed=2

[34113] (04/08 03:31:02):{0xDFC} {0} {Hamhaber, Martin} Folders: Start (1/51)

[44000] (04/08 03:31:02):{0xDFC} {Exception} {Database} A match could not be found for folder '44AA4B31.GEK.HV.100.1666178.1.A606.1'

[44000] (04/08 03:31:02):{0xDFC} {0} {Hamhaber, Martin} An error has occured in getting the folder '44AA4B31.GEK.HV.100.1666178.1.A606.1' from the database.

[34112] (04/08 03:31:02):{0xDFC} {0} {Hamhaber, Martin} Folders: Interrupt (34/51)

[34113] (04/08 03:31:04):{0xDFC} {0} {Hamhaber, Martin} Folders: Resume (35/51)

[34111] (04/08 03:31:04):{0xDFC} {0} {Hamhaber, Martin} Folders: End (51/51), Deleted=0/24, Total=24, Subscribed=2

[34121] (04/08 04:03:39):{0xE24} {GWCO} {GWConnector} MbMailSync Cleanup: Start

[44004] (04/08 04:03:39):{0xE24} {GWCO} {GWConnector} MbMailSync Cleanup: Nothing to prune

[34122] (04/08 04:03:39):{0xE24} {GWCO} {GWConnector} MbMailSync Cleanup: Successful

[34113] (04/08 04:12:13):{0xE00} {1} {Geller, Steffen} Folders: Start (1/47)

[34112] (04/08 04:12:14):{0xE00} {1} {Geller, Steffen} Folders: Interrupt (29/47)

[34113] (04/08 04:12:15):{0xE00} {1} {Geller, Steffen} Folders: Resume (30/47)

[34111] (04/08 04:12:16):{0xE00} {1} {Geller, Steffen} Folders: End (47/47), Deleted=0/29, Total=29, Subscribed=2

[34113] (04/08 07:48:44):{0xDFC} {0} {Hamhaber, Martin} Folders: Start (1/51)

[44000] (04/08 07:48:44):{0xDFC} {Exception} {Database} A match could not be found for folder '44AA4B31.GEK.HV.100.1666178.1.A606.1'

[44000] (04/08 07:48:44):{0xDFC} {0} {Hamhaber, Martin} An error has occured in getting the folder '44AA4B31.GEK.HV.100.1666178.1.A606.1' from the database.

[34112] (04/08 07:48:44):{0xDFC} {0} {Hamhaber, Martin} Folders: Interrupt (28/51)

[34113] (04/08 07:48:45):{0xDFC} {0} {Hamhaber, Martin} Folders: Resume (29/51)

[34112] (04/08 07:48:46):{0xDFC} {0} {Hamhaber, Martin} Folders: Interrupt (45/51)

[34113] (04/08 07:48:47):{0xDFC} {0} {Hamhaber, Martin} Folders: Resume (46/51)

[34111] (04/08 07:48:48):{0xDFC} {0} {Hamhaber, Martin} Folders: End (51/51), Deleted=0/24, Total=24, Subscribed=2

[34113] (04/08 08:29:55):{0xE00} {1} {Geller, Steffen} Folders: Start (1/47)

[34112] (04/08 08:29:56):{0xE00} {1} {Geller, Steffen} Folders: Interrupt (26/47)

[34113] (04/08 08:29:57):{0xE00} {1} {Geller, Steffen} Folders: Resume (27/47)

[34112] (04/08 08:29:58):{0xE00} {1} {Geller, Steffen} Folders: Interrupt (44/47)

[34113] (04/08 08:29:59):{0xE00} {1} {Geller, Steffen} Folders: Resume (45/47)

[34111] (04/08 08:29:59):{0xE00} {1} {Geller, Steffen} Folders: End (47/47), Deleted=0/29, Total=29, Subscribed=2

[34118] (04/08 09:37:40):{0xE24} {0} {Hamhaber, Martin} Received MFH APPOINTMENT, EntryID=13

[34119] (04/08 09:37:41):{0xDFC} {0} {Hamhaber, Martin} MFH: Begin processing APPOINTMENT job, EntryID=13

[34021] (04/08 09:37:42):{0xDFC} {0} {Hamhaber, Martin} MTH: Update Meeting, RefId=-503996416, ICEId=49DC6C7C.GEK.HV.100.1666178.1.4A162.1, MbCalendarSyncId=608, ObjectDefnId=2000, Successful=True

[34174] (04/08 09:37:42):{0xDFC} {0} {Hamhaber, Martin} MFH: MEETING RESPONSE, RefId=-503996416, ICEId=49DC6C7C.GEK.HV.100.1666178.1.4A162.1, WorkQueueId=13, MbCalendarSyncId=608, ObjectDefnId=2000, Successful=True

[34118] (04/08 09:38:13):{0xE24} {0} {Hamhaber, Martin} Received MFH APPOINTMENT, EntryID=14

[34118] (04/08 09:38:13):{0xE24} {0} {Hamhaber, Martin} Received MFH APPOINTMENT, EntryID=15

[34118] (04/08 09:38:14):{0xE24} {0} {Hamhaber, Martin} Received MFH APPOINTMENT, EntryID=16

[34118] (04/08 09:38:14):{0xE24} {0} {Hamhaber, Martin} Received MFH APPOINTMENT, EntryID=17

[34119] (04/08 09:38:15):{0xDFC} {0} {Hamhaber, Martin} MFH: Begin processing APPOINTMENT job, EntryID=14

[34021] (04/08 09:38:15):{0xDFC} {0} {Hamhaber, Martin} MTH: Update Meeting, RefId=-1212258815, ICEId=49DC6C96.GEK.HV.100.1666178.1.4A164.1, MbCalendarSyncId=609, ObjectDefnId=2000, Successful=True

[34174] (04/08 09:38:15):{0xDFC} {0} {Hamhaber, Martin} MFH: MEETING RESPONSE, RefId=-1212258815, ICEId=49DC6C96.GEK.HV.100.1666178.1.4A164.1, WorkQueueId=14, MbCalendarSyncId=609, ObjectDefnId=2000, Successful=True

[34119] (04/08 09:38:15):{0xDFC} {0} {Hamhaber, Martin} MFH: Begin processing APPOINTMENT job, EntryID=15

[34021] (04/08 09:38:15):{0xDFC} {0} {Hamhaber, Martin} MTH: Update Meeting, RefId=-324836862, ICEId=49DC6CE4.GEK.HV.100.1666178.1.4A166.1, MbCalendarSyncId=610, ObjectDefnId=2000, Successful=True

[34174] (04/08 09:38:16):{0xDFC} {0} {Hamhaber, Martin} MFH: MEETING RESPONSE, RefId=-324836862, ICEId=49DC6CE4.GEK.HV.100.1666178.1.4A166.1, WorkQueueId=15, MbCalendarSyncId=610, ObjectDefnId=2000, Successful=True

[34119] (04/08 09:38:17):{0xDFC} {0} {Hamhaber, Martin} MFH: Begin processing APPOINTMENT job, EntryID=16

[34021] (04/08 09:38:17):{0xDFC} {0} {Hamhaber, Martin} MTH: Update Meeting, RefId=-593316477, ICEId=49DC6D0D.GEK.HV.100.1666178.1.4A168.1, MbCalendarSyncId=611, ObjectDefnId=2000, Successful=True

[34174] (04/08 09:38:17):{0xDFC} {0} {Hamhaber, Martin} MFH: MEETING RESPONSE, RefId=-593316477, ICEId=49DC6D0D.GEK.HV.100.1666178.1.4A168.1, WorkQueueId=16, MbCalendarSyncId=611, ObjectDefnId=2000, Successful=True

[34119] (04/08 09:38:17):{0xDFC} {0} {Hamhaber, Martin} MFH: Begin processing APPOINTMENT job, EntryID=17

[34021] (04/08 09:38:18):{0xDFC} {0} {Hamhaber, Martin} MTH: Update Meeting, RefId=-1491313788, ICEId=49DC6D36.GEK.HV.100.1666178.1.4A16A.1, MbCalendarSyncId=612, ObjectDefnId=2000, Successful=True

[34174] (04/08 09:38:18):{0xDFC} {0} {Hamhaber, Martin} MFH: MEETING RESPONSE, RefId=-1491313788, ICEId=49DC6D36.GEK.HV.100.1666178.1.4A16A.1, WorkQueueId=17, MbCalendarSyncId=612, ObjectDefnId=2000, Successful=True

[34118] (04/08 09:38:21):{0xE24} {0} {Hamhaber, Martin} Received MFH APPOINTMENT, EntryID=18

[34119] (04/08 09:38:22):{0xDFC} {0} {Hamhaber, Martin} MFH: Begin processing APPOINTMENT job, EntryID=18

[34021] (04/08 09:38:22):{0xDFC} {0} {Hamhaber, Martin} MTH: Update Meeting, RefId=-1515279483, ICEId=49DC6D5C.GEK.HV.100.1666178.1.4A16C.1, MbCalendarSyncId=613, ObjectDefnId=2000, Successful=True

[34174] (04/08 09:38:22):{0xDFC} {0} {Hamhaber, Martin} MFH: MEETING RESPONSE, RefId=-1515279483, ICEId=49DC6D5C.GEK.HV.100.1666178.1.4A16C.1, WorkQueueId=18, MbCalendarSyncId=613, ObjectDefnId=2000, Successful=True

[34118] (04/08 09:48:03):{0xE24} {0} {Hamhaber, Martin} Received MFH APPOINTMENT, EntryID=19

[34119] (04/08 09:48:03):{0xDFC} {0} {Hamhaber, Martin} MFH: Begin processing APPOINTMENT job, EntryID=19

[34021] (04/08 09:48:03):{0xDFC} {0} {Hamhaber, Martin} MTH: Update Meeting, RefId=-131379696, ICEId=49DC7204.GEK.HV.100.1666178.1.4A19C.1, MbCalendarSyncId=624, ObjectDefnId=2000, Successful=True

[34174] (04/08 09:48:03):{0xDFC} {0} {Hamhaber, Martin} MFH: MEETING RESPONSE, RefId=-131379696, ICEId=49DC7204.GEK.HV.100.1666178.1.4A19C.1, WorkQueueId=19, MbCalendarSyncId=624, ObjectDefnId=2000, Successful=True

[34118] (04/08 09:49:39):{0xE24} {0} {Hamhaber, Martin} Received MFH TASK, EntryID=20

[34118] (04/08 09:49:39):{0xE24} {0} {Hamhaber, Martin} Received MFH MEMO, EntryID=21

[34119] (04/08 09:49:40):{0xDFC} {0} {Hamhaber, Martin} MFH: Begin processing TASK job, EntryID=20

[34182] (04/08 09:49:41):{0xDFC} {0} {Hamhaber, Martin} MFH: UPDATE TASK, Sync CmdID=-51, ICEId=49DC708D.GEK.HV.100.1666178.1.4A17C.1, WorkQueueId=20, MbPIMSyncId=609, ObjectDefnId=5000, Successful=True

[34119] (04/08 09:49:42):{0xDFC} {0} {Hamhaber, Martin} MFH: Begin processing MEMO job, EntryID=21

[34184] (04/08 09:49:43):{0xDFC} {0} {Hamhaber, Martin} MFH: NEW MEMO, Sync CmdID=-53, ICEId=49DC7335.GEK.HV.100.1666178.1.4A19E.1, WorkQueueId=21, MbPIMSyncId=611, ObjectDefnId=6000, Successful=True

[34113] (04/08 11:09:14):{0xDFC} {0} {Hamhaber, Martin} Folders: Start (1/51)

[34112] (04/08 11:09:14):{0xDFC} {0} {Hamhaber, Martin} Folders: Interrupt (1/51)

[34113] (04/08 11:09:16):{0xDFC} {0} {Hamhaber, Martin} Folders: Resume (2/51)

[34112] (04/08 11:09:16):{0xDFC} {0} {Hamhaber, Martin} Folders: Interrupt (14/51)

[34113] (04/08 11:09:18):{0xDFC} {0} {Hamhaber, Martin} Folders: Resume (15/51)

[44000] (04/08 11:09:19):{0xDFC} {Exception} {Database} A match could not be found for folder '44AA4B31.GEK.HV.100.1666178.1.A606.1'

[44000] (04/08 11:09:19):{0xDFC} {0} {Hamhaber, Martin} An error has occured in getting the folder '44AA4B31.GEK.HV.100.1666178.1.A606.1' from the database.

[34112] (04/08 11:09:19):{0xDFC} {0} {Hamhaber, Martin} Folders: Interrupt (21/51)

[34113] (04/08 11:09:20):{0xDFC} {0} {Hamhaber, Martin} Folders: Resume (22/51)

[34112] (04/08 11:09:21):{0xDFC} {0} {Hamhaber, Martin} Folders: Interrupt (43/51)

[34113] (04/08 11:09:22):{0xDFC} {0} {Hamhaber, Martin} Folders: Resume (44/51)

[34111] (04/08 11:09:22):{0xDFC} {0} {Hamhaber, Martin} Folders: End (51/51), Deleted=0/24, Total=24, Subscribed=2

[34113] (04/08 11:50:24):{0xE00} {1} {Geller, Steffen} Folders: Start (1/47)

[34112] (04/08 11:50:25):{0xE00} {1} {Geller, Steffen} Folders: Interrupt (1/47)

[34113] (04/08 11:50:26):{0xE00} {1} {Geller, Steffen} Folders: Resume (2/47)

[34112] (04/08 11:50:27):{0xE00} {1} {Geller, Steffen} Folders: Interrupt (2/47)

[34113] (04/08 11:50:28):{0xE00} {1} {Geller, Steffen} Folders: Resume (3/47)

[34112] (04/08 11:50:29):{0xE00} {1} {Geller, Steffen} Folders: Interrupt (23/47)

[34113] (04/08 11:50:30):{0xE00} {1} {Geller, Steffen} Folders: Resume (24/47)

[34112] (04/08 11:50:31):{0xE00} {1} {Geller, Steffen} Folders: Interrupt (25/47)

[34113] (04/08 11:50:32):{0xE00} {1} {Geller, Steffen} Folders: Resume (26/47)

[34111] (04/08 11:50:33):{0xE00} {1} {Geller, Steffen} Folders: End (47/47), Deleted=0/29, Total=29, Subscribed=2

[14144] (04/08 14:46:23):{0xE24} {Exception} {Database} Error executing DB call:  CallingFunction=PollWorkQueue SQL= SELECT top 5 ID, UserConfigID, DataText, ObjectDefnId, WorkDefnId, ObjectId FROM WorkQueue WITH (NOLOCK)  WHERE ServerConfigId = 1 AND ID > 21 AND ( ((WorkDefnId = 101) AND (Status = 0)) OR  ((WorkDefnId = 100) AND (Status <> 4)) ) ORDER BY ID

[24014] (04/08 14:46:23):{0xE24} {Exception} {Database} General network error.  Check your network documentation. - System.Data.SqlClient.SqlException: General network error.  Check your network documentation.

   at System.Data.SqlClient.SqlCommand.ExecuteReader(CommandBehavior cmdBehavior, RunBehavior runBehavior, Boolean returnStream)

   at System.Data.SqlClient.SqlCommand.ExecuteReader(CommandBehavior behavior)

   at DBLibrary.SyncDatabase.ExecuteReader(String sSQL, SqlDataReader& oDR, SqlConnection& oConn)

[14129] (04/08 14:46:41):{0xE24} {Exception} {Database} DBTest Failed, Error(17)=SQL Server does not exist or access denied.

[14129] (04/08 14:46:43):{0xE24} {Exception} {Database} DBTest Failed, Error(17)=SQL Server does not exist or access denied.

[30000] (04/08 14:47:05):{0xCA4} Current Date: 2009/04/08

[54001] (04/08 14:47:05):{0xC2C} {GWCO} {GWConnector} Starting GWConnector

[34192] (04/08 14:47:06):{0xC2C} {GWCO} {GWConnector} Database Edition=EXPRESS

[34481] (04/08 14:47:06):{0xC2C} {GWCO} {GWConnector} GW API Mode=GW_SOAP

[34154] (04/08 14:47:06):{0xC2C} {GWCO} {GWConnector} Mailstore test start, test level=1

[34116] (04/08 14:47:06):{0xC2C} {GWCO} {rimadmin} Login: Start

[34117] (04/08 14:47:07):{0xC2C} {GWCO} {rimadmin} Login Successful: DOMAIN=GEK, POA=HV, FID=7e1 (1316537)

[34443] (04/08 14:47:07):{0xC2C} {GWCO} {rimadmin} POA Test: Successful

[34108] (04/08 14:47:07):{0xC2C} {GWCO} {rimadmin} Folders: Start

[34109] (04/08 14:47:07):{0xC2C} {GWCO} {rimadmin} Folders: Test successful

[34127] (04/08 14:47:07):{0xC2C} {GWCO} {rimadmin} Messages: Start

[34128] (04/08 14:47:08):{0xC2C} {GWCO} {rimadmin} Messages: Test successful

[34134] (04/08 14:47:08):{0xC2C} {GWCO} {rimadmin} QuickMessages: Check New test start

[34135] (04/08 14:47:08):{0xC2C} {GWCO} {rimadmin} QuickMessages: Check New test successful

[34137] (04/08 14:47:08):{0xC2C} {GWCO} {rimadmin} QuickMessages: Check Changes test start

[34138] (04/08 14:47:08):{0xC2C} {GWCO} {rimadmin} QuickMessages: Check Changes test successful

[34129] (04/08 14:47:08):{0xC2C} {GWCO} {rimadmin} Appointments: Start

[34130] (04/08 14:47:08):{0xC2C} {GWCO} {rimadmin} Appointments: Test successful

[34140] (04/08 14:47:08):{0xC2C} {GWCO} {rimadmin} CalendarQuery: Start

[34141] (04/08 14:47:08):{0xC2C} {GWCO} {rimadmin} CalendarQuery: Successful, items=1

[34131] (04/08 14:47:08):{0xC2C} {GWCO} {rimadmin} Messages: Deleted test message.

[34132] (04/08 14:47:08):{0xC2C} {GWCO} {rimadmin} Messages: Deleted test appointment.

[34142] (04/08 14:47:08):{0xC2C} {GWCO} {rimadmin} Trash: Start

[34143] (04/08 14:47:08):{0xC2C} {GWCO} {rimadmin} Trash: query successful, entries=2

[34133] (04/08 14:47:08):{0xC2C} {GWCO} {rimadmin} Cleanup: Deleting test items from trash

[34144] (04/08 14:47:09):{0xC2C} {GWCO} {rimadmin} AddressBooks: Start, Book=Frequent Contacts

[34145] (04/08 14:47:10):{0xC2C} {GWCO} {rimadmin} AddressBooks: Test successful

[34045] (04/08 14:47:10):{0xC2C} {GWCO} {rimadmin} Logout admin user

[34155] (04/08 14:47:10):{0xC2C} {GWCO} {GWConnector} Mailstore test successful.

[34059] (04/08 14:47:10):{0xC2C} {GWCO} {Geller, Steffen} User Details: UserConfigID=2, PIN=25062856, Status=Active, RedirectionEnabled=True

[34058] (04/08 14:47:10):{0xC2C} {GWCO} {Geller, Steffen} User PIM Status: Contacts=Active, Memos=Active, Tasks=Active

[34059] (04/08 14:47:10):{0xC2C} {GWCO} {Hamhaber, Martin} User Details: UserConfigID=5, PIN=20760C66, Status=Active, RedirectionEnabled=True

[34058] (04/08 14:47:10):{0xC2C} {GWCO} {Hamhaber, Martin} User PIM Status: Contacts=Active, Memos=Active, Tasks=Active

[34046] (04/08 14:47:10):{0xC2C} {0} {Hamhaber, Martin} User Assigned to Thread

[34046] (04/08 14:47:10):{0xC2C} {1} {Geller, Steffen} User Assigned to Thread

[34117] (04/08 14:47:10):{0xDAC} {0} {Hamhaber, Martin} Login Successful: DOMAIN=GEK, POA=HV, FID=xaf (1666178)

[34063] (04/08 14:47:10):{0xDAC} {0} {Hamhaber, Martin} Folders: Total=24 Subscribed=2

[34117] (04/08 14:47:10):{0xDB0} {1} {Geller, Steffen} Login Successful: DOMAIN=GEK, POA=HV, FID=jzg (1677A6A)

[34063] (04/08 14:47:10):{0xDB0} {1} {Geller, Steffen} Folders: Total=29 Subscribed=2

[14144] (04/08 14:57:05):{0xDD4} {Exception} {Database} Error executing DB call:  CallingFunction=PollWorkQueue SQL= SELECT top 5 ID, UserConfigID, DataText, ObjectDefnId, WorkDefnId, ObjectId FROM WorkQueue WITH (NOLOCK)  WHERE ServerConfigId = 1 AND ID > 0 AND ( ((WorkDefnId = 101) AND (Status = 0)) OR  ((WorkDefnId = 100) AND (Status <> 4)) ) ORDER BY ID

[24014] (04/08 14:57:05):{0xDD4} {Exception} {Database} General network error.  Check your network documentation. - System.Data.SqlClient.SqlException: General network error.  Check your network documentation.

   at System.Data.SqlClient.SqlCommand.ExecuteReader(CommandBehavior cmdBehavior, RunBehavior runBehavior, Boolean returnStream)

   at System.Data.SqlClient.SqlCommand.ExecuteReader(CommandBehavior behavior)

   at DBLibrary.SyncDatabase.ExecuteReader(String sSQL, SqlDataReader& oDR, SqlConnection& oConn)

[14129] (04/08 14:57:23):{0xDD4} {Exception} {Database} DBTest Failed, Error(17)=SQL Server does not exist or access denied.

[14129] (04/08 14:57:25):{0xDD4} {Exception} {Database} DBTest Failed, Error(17)=SQL Server does not exist or access denied.

[30000] (04/08 14:57:43):{0x9B4} Current Date: 2009/04/08

[54001] (04/08 14:57:44):{0x970} {GWCO} {GWConnector} Starting GWConnector

[34192] (04/08 14:57:44):{0x970} {GWCO} {GWConnector} Database Edition=EXPRESS

[34481] (04/08 14:57:44):{0x970} {GWCO} {GWConnector} GW API Mode=GW_SOAP

[34154] (04/08 14:57:44):{0x970} {GWCO} {GWConnector} Mailstore test start, test level=1

[34116] (04/08 14:57:47):{0x970} {GWCO} {rimadmin} Login: Start

[34117] (04/08 14:57:47):{0x970} {GWCO} {rimadmin} Login Successful: DOMAIN=GEK, POA=HV, FID=7e1 (1316537)

[34443] (04/08 14:57:47):{0x970} {GWCO} {rimadmin} POA Test: Successful

[34108] (04/08 14:57:47):{0x970} {GWCO} {rimadmin} Folders: Start

[34109] (04/08 14:57:48):{0x970} {GWCO} {rimadmin} Folders: Test successful

[34127] (04/08 14:57:48):{0x970} {GWCO} {rimadmin} Messages: Start

[34128] (04/08 14:57:49):{0x970} {GWCO} {rimadmin} Messages: Test successful

[34134] (04/08 14:57:49):{0x970} {GWCO} {rimadmin} QuickMessages: Check New test start

[34135] (04/08 14:57:49):{0x970} {GWCO} {rimadmin} QuickMessages: Check New test successful

[34137] (04/08 14:57:49):{0x970} {GWCO} {rimadmin} QuickMessages: Check Changes test start

[34138] (04/08 14:57:49):{0x970} {GWCO} {rimadmin} QuickMessages: Check Changes test successful

[34129] (04/08 14:57:49):{0x970} {GWCO} {rimadmin} Appointments: Start

[34130] (04/08 14:57:49):{0x970} {GWCO} {rimadmin} Appointments: Test successful

[34140] (04/08 14:57:49):{0x970} {GWCO} {rimadmin} CalendarQuery: Start

[34141] (04/08 14:57:49):{0x970} {GWCO} {rimadmin} CalendarQuery: Successful, items=1

[34131] (04/08 14:57:49):{0x970} {GWCO} {rimadmin} Messages: Deleted test message.

[34132] (04/08 14:57:50):{0x970} {GWCO} {rimadmin} Messages: Deleted test appointment.

[34142] (04/08 14:57:50):{0x970} {GWCO} {rimadmin} Trash: Start

[34143] (04/08 14:57:50):{0x970} {GWCO} {rimadmin} Trash: query successful, entries=2

[34133] (04/08 14:57:50):{0x970} {GWCO} {rimadmin} Cleanup: Deleting test items from trash

[34144] (04/08 14:57:50):{0x970} {GWCO} {rimadmin} AddressBooks: Start, Book=Frequent Contacts

[34145] (04/08 14:57:50):{0x970} {GWCO} {rimadmin} AddressBooks: Test successful

[34045] (04/08 14:57:50):{0x970} {GWCO} {rimadmin} Logout admin user

[34155] (04/08 14:57:50):{0x970} {GWCO} {GWConnector} Mailstore test successful.

[34059] (04/08 14:57:50):{0x970} {GWCO} {Geller, Steffen} User Details: UserConfigID=2, PIN=25062856, Status=Active, RedirectionEnabled=True

[34058] (04/08 14:57:50):{0x970} {GWCO} {Geller, Steffen} User PIM Status: Contacts=Active, Memos=Active, Tasks=Active

[34059] (04/08 14:57:50):{0x970} {GWCO} {Hamhaber, Martin} User Details: UserConfigID=5, PIN=20760C66, Status=Active, RedirectionEnabled=True

[34058] (04/08 14:57:50):{0x970} {GWCO} {Hamhaber, Martin} User PIM Status: Contacts=Active, Memos=Active, Tasks=Active

[34046] (04/08 14:57:50):{0x970} {0} {Hamhaber, Martin} User Assigned to Thread

[34046] (04/08 14:57:50):{0x970} {1} {Geller, Steffen} User Assigned to Thread

[34117] (04/08 14:57:50):{0xE14} {0} {Hamhaber, Martin} Login Successful: DOMAIN=GEK, POA=HV, FID=xaf (1666178)

[34063] (04/08 14:57:50):{0xE14} {0} {Hamhaber, Martin} Folders: Total=24 Subscribed=2

[34117] (04/08 14:57:51):{0xE18} {1} {Geller, Steffen} Login Successful: DOMAIN=GEK, POA=HV, FID=jzg (1677A6A)

[34063] (04/08 14:57:51):{0xE18} {1} {Geller, Steffen} Folders: Total=29 Subscribed=2
 

GWSV_01 Log:
 

[30000] (04/08 00:00:30):{0x17C4} Current Date: 2009/04/08

[34157] (04/08 00:00:30):{0x968} {GWSV} {GWService} Novell GroupWiseObject Library: C:\Novell\GroupWise\\gwcma1.dll, Version: 7.0.3

[34493] (04/08 00:00:30):{0x968} {GWSV} {GWService} GWService Version: 4.1.6.29

[34480] (04/08 00:00:48):{0x968} {0} {GWSV} GW API Mode=GW_SOAP

[34442] (04/08 00:00:48):{0x968} {0} {GWSV} Monitoring GWSoapConnector (2380)

[34442] (04/08 00:00:48):{0x968} {0} {GWSV} Monitoring GWCheckNew (0)

[34442] (04/08 00:00:48):{0x968} {0} {GWSV} Monitoring GWCheckPA (0)

[34442] (04/08 00:00:48):{0x968} {0} {GWSV} Monitoring GWConnector (2396)

[34436] (04/08 00:00:48):{0x968} {0} {GWSV} Process Health Check (Monitor NR=08.04.2009 00:01:00)

[34436] (04/08 00:01:49):{0x968} {0} {GWSV} Process Health Check (Monitor NR=08.04.2009 00:02:02)

[34436] (04/08 00:02:50):{0x968} {0} {GWSV} Process Health Check (Monitor NR=08.04.2009 00:03:04)

[34436] (04/08 00:03:51):{0x968} {0} {GWSV} Process Health Check (Monitor NR=08.04.2009 00:04:06)

[...]

[34436] (04/08 14:44:46):{0x968} {0} {GWSV} Process Health Check (Monitor NR=08.04.2009 14:44:59)

[34436] (04/08 14:45:46):{0x968} {0} {GWSV} Process Health Check (Monitor NR=08.04.2009 14:46:00)

[30000] (04/08 14:46:56):{0x694} Current Date: 2009/04/08

[14144] (04/08 14:46:58):{0x5C0} {Exception} {Database} Error executing DB call:  CallingFunction=GetDBEdition SQL= Select SERVERPROPERTY('EngineEdition')

[24014] (04/08 14:46:58):{0x5C0} {Exception} {Database} Cannot open database "BESMgmt" requested by the login. The login failed.  Login failed for user 'NTDMZ02\dmzadmin'. - System.Data.SqlClient.SqlException: Cannot open database "BESMgmt" requested by the login. The login failed.

Login failed for user 'NTDMZ02\dmzadmin'.

   at System.Data.SqlClient.ConnectionPool.CreateConnection()

   at System.Data.SqlClient.ConnectionPool.UserCreateRequest()

   at System.Data.SqlClient.ConnectionPool.GetConnection(Boolean& isInTransaction)

   at System.Data.SqlClient.SqlConnectionPoolManager.GetPooledConnection(SqlConnectionString options, Boolean& isInTransaction)

   at System.Data.SqlClient.SqlConnection.Open()

   at DBLibrary.SyncDatabase.ExecuteReader(String sSQL, SqlDataReader& oDR, SqlConnection& oConn)

[14129] (04/08 14:47:00):{0x5C0} {Exception} {Database} DBTest Failed, Error(4060)=Cannot open database "BESMgmt" requested by the login. The login failed.  Login failed for user 'NTDMZ02\dmzadmin'.

[14129] (04/08 14:47:02):{0x5C0} {Exception} {Database} DBTest Failed, Error(4060)=Cannot open database "BESMgmt" requested by the login. The login failed.  Login failed for user 'NTDMZ02\dmzadmin'.

[14129] (04/08 14:47:04):{0x5C0} {Exception} {Database} DBTest Failed, Error(4060)=Cannot open database "BESMgmt" requested by the login. The login failed.  Login failed for user 'NTDMZ02\dmzadmin'.

[24014] (04/08 14:47:04):{0x5C0} {Exception} {Database} GetDBEdition - System.NullReferenceException: Object reference not set to an instance of an object.

   at DBLibrary.SyncDatabase.GetDBEdition()

[14107] (04/08 14:47:04):{0x5C0} {Exception} {Database} Error detecting database edition

[54003] (04/08 14:47:04):{0x5C0} {0} {GWSV} - - - - - Starting GW Controlling Service - - - - -

[34429] (04/08 14:47:04):{0x5C0} {0} {GWSV} Path=c:\Program Files\Research In Motion\BlackBerry Enterprise Server\

[34430] (04/08 14:47:04):{0x5C0} {0} {GWSV} Timeout=900

[34431] (04/08 14:47:04):{0x5C0} {0} {GWSV} Health Interval=30

[34432] (04/08 14:47:04):{0x5C0} {0} {GWSV} Kill outstanding processes

[34433] (04/08 14:47:04):{0x5C0} {0} {GWSV} Start GW processes

[34480] (04/08 14:47:04):{0x5C0} {0} {GWSV} GW API Mode=GW_SOAP

[34427] (04/08 14:47:04):{0x5C0} {0} {GWSV} Started Process GWSoapConnector (3100)

[34427] (04/08 14:47:04):{0x5C0} {0} {GWSV} Started Process GWConnector (3112)

[34436] (04/08 14:47:04):{0xC30} {0} {GWSV} Starting Process Monitor

[34436] (04/08 14:57:04):{0xC30} {0} {GWSV} Process Health Check (Monitor NR=08.04.2009 14:57:22)

[30000] (04/08 14:57:39):{0x4E8} Current Date: 2009/04/08

[54003] (04/08 14:57:42):{0x4CC} {0} {GWSV} - - - - - Starting GW Controlling Service - - - - -

[34429] (04/08 14:57:43):{0x4CC} {0} {GWSV} Path=c:\Program Files\Research In Motion\BlackBerry Enterprise Server\

[34430] (04/08 14:57:43):{0x4CC} {0} {GWSV} Timeout=900

[34431] (04/08 14:57:43):{0x4CC} {0} {GWSV} Health Interval=30

[34432] (04/08 14:57:43):{0x4CC} {0} {GWSV} Kill outstanding processes

[34433] (04/08 14:57:43):{0x4CC} {0} {GWSV} Start GW processes

[34480] (04/08 14:57:43):{0x4CC} {0} {GWSV} GW API Mode=GW_SOAP

[34427] (04/08 14:57:43):{0x4CC} {0} {GWSV} Started Process GWSoapConnector (2400)

[34427] (04/08 14:57:43):{0x4CC} {0} {GWSV} Started Process GWConnector (2412)

[34436] (04/08 14:57:43):{0x974} {0} {GWSV} Starting Process Monitor

[34436] (04/08 15:07:43):{0x974} {0} {GWSV} Process Health Check (Monitor NR=08.04.2009 15:08:01)

[34436] (04/08 15:08:43):{0x974} {0} {GWSV} Process Health Check (Monitor NR=08.04.2009 15:09:01)

[34436] (04/08 15:09:43):{0x974} {0} {GWSV} Process Health Check (Monitor NR=08.04.2009 15:10:02)

[...]
 

GWSC_01 Log:
 

[34060] (04/08 14:36:09):{0xD3C} {Listener} {Listener} Listening for events at IP=192.168.15.21, Port=9000

[14144] (04/08 14:46:26):{0xD40} {Exception} {Database} Error executing DB call:  CallingFunction=GetServerConfigHistory SQL= SELECT ID, UserConfigID, operation, details, ObjectDefnId, ServerConfigId FROM ServerConfigHistory WITH (NOLOCK) WHERE (( (ObjectDefnId = 200) AND ( (operation = 'I') OR (operation = 'D') OR (operation = 'U')) ) OR ( (ObjectDefnId = 100) AND (operation = 'U') ) )  And ID > 2068 order by id

[24014] (04/08 14:46:26):{0xD40} {Exception} {Database} General network error.  Check your network documentation. - System.Data.SqlClient.SqlException: General network error.  Check your network documentation.

   at System.Data.SqlClient.SqlCommand.ExecuteReader(CommandBehavior cmdBehavior, RunBehavior runBehavior, Boolean returnStream)

   at System.Data.SqlClient.SqlCommand.ExecuteReader(CommandBehavior behavior)

   at DBLibrary.SyncDatabase.ExecuteReader(String sSQL, SqlDataReader& oDR, SqlConnection& oConn)

[30000] (04/08 14:47:05):{0xC84} Current Date: 2009/04/08

[54001] (04/08 14:47:05):{0xC20} {GWSC} {GWSoapConnector} Starting GWSoapConnector

[34192] (04/08 14:47:05):{0xC20} {GWSC} {GWSoapConnector} Database Edition=EXPRESS

[34121] (04/08 14:47:05):{0xC20} {GWSC} {GWSoapConnector} User Event Flush Threshold = 10 events

[34122] (04/08 14:47:05):{0xC20} {GWSC} {GWSoapConnector} User Event Flush Timeout = 900 sec

[34483] (04/08 14:47:05):{0xD14} {BESMgmt} {GWSoapConnector} Begin BESMgmt Thread

[34480] (04/08 14:47:05):{0xC20} {GWSC} {GWSoapConnector} GW API Mode=GW_SOAP

[34480] (04/08 14:47:05):{0xC20} {GWSC} {GWSoapConnector} SSL=OPTIONAL

[34154] (04/08 14:47:05):{0xC20} {GWSC} {GWSoapConnector} Mailstore test start, test level=1

[34116] (04/08 14:47:05):{0xC20} {GWSC} {rimadmin} Login: Start

[34117] (04/08 14:47:08):{0xC20} {GWSC} {rimadmin} Login Successful, POA=10.252.1.237:7191, Ver=7.0.3, Build=560, SSL=False, Seconds ahead of BES=0

[34117] (04/08 14:47:08):{0xC20} {GWSC} {rimadmin} Logout Successful

[34045] (04/08 14:47:08):{0xC20} {GWSC} {rimadmin} Logout admin user

[34155] (04/08 14:47:08):{0xC20} {GWSC} {GWSoapConnector} Mailstore test successful.

[34497] (04/08 14:47:08):{0xC20} {GWSC} {GWSoapConnector} ThreadPool Size=10

[34496] (04/08 14:47:08):{0xD9C} {ThreadPool} {ThreadPool} Begin ThreadPool

[34059] (04/08 14:47:08):{0xC20} {GWSC} {Geller, Steffen} User Details: UserConfigID=2, PIN=25062856, Status=Active, RedirectionEnabled=True

[34058] (04/08 14:47:08):{0xC20} {GWSC} {Geller, Steffen} User PIM Status: Contacts=Active, Memos=Active, Tasks=Active

[34059] (04/08 14:47:08):{0xC20} {GWSC} {Hamhaber, Martin} User Details: UserConfigID=5, PIN=20760C66, Status=Active, RedirectionEnabled=True

[34058] (04/08 14:47:08):{0xC20} {GWSC} {Hamhaber, Martin} User PIM Status: Contacts=Active, Memos=Active, Tasks=Active

[34482] (04/08 14:47:08):{0xDA0} {Listener} {Listener} Begin Listener

[34060] (04/08 14:47:08):{0xDA0} {Listener} {Listener} Listening for events at IP=192.168.15.21, Port=9001

[34123] (04/08 14:47:08):{0xDA4} {GWSC} {GWSoapConnector} LogFile Cleanup

[34057] (04/08 14:47:08):{0xDA4} {GWSC} {Hamhaber, Martin} Subscribed Folders: Mailbox, Ausgangsnachrichten

[34057] (04/08 14:47:08):{0xDA4} {GWSC} {Geller, Steffen} Subscribed Folders: Mailbox, Ausgangsnachrichten

[34117] (04/08 14:47:09):{0xD74} {0} {Hamhaber, Martin} Login Successful, POA=10.252.1.237:7191, Ver=7.0.3, Build=560, SSL=False, Seconds ahead of BES=-1

[34495] (04/08 14:47:09):{0xD7C} {2} {Hamhaber, Martin} SC_USER_START

[34117] (04/08 14:47:09):{0xD78} {1} {Geller, Steffen} Login Successful, POA=10.252.1.237:7191, Ver=7.0.3, Build=560, SSL=False, Seconds ahead of BES=-1

[34495] (04/08 14:47:09):{0xD74} {0} {Geller, Steffen} SC_USER_START

[34113] (04/08 14:47:10):{0xD74} {0} {Geller, Steffen} Folders: Start (0/31)

[34113] (04/08 14:47:10):{0xD74} {0} {Geller, Steffen} Folders: End (31/31), Total ICE Folders=48, Deleted=0

[34203] (04/08 14:47:10):{0xD74} {0} {Geller, Steffen} System Folders: Calendar=A.GEK.HV.100.0.1.0.1@19, Trash=9.GEK.HV.100.0.1.0.1@18

[34105] (04/08 14:47:10):{0xD74} {0} {Geller, Steffen} Address Book: Detected valid address book (Adressbuch 6.3, externe Kontakte (Clemens Weller))

[34113] (04/08 14:47:10):{0xD7C} {2} {Hamhaber, Martin} Folders: Start (0/26)

[34113] (04/08 14:47:10):{0xD7C} {2} {Hamhaber, Martin} Folders: End (26/26), Total ICE Folders=52, Deleted=0

[34203] (04/08 14:47:10):{0xD7C} {2} {Hamhaber, Martin} System Folders: Calendar=A.GEK.HV.100.0.1.0.1@19, Trash=9.GEK.HV.100.0.1.0.1@18

[34489] (04/08 14:47:10):{0xD74} {0} {Geller, Steffen} SC_RIM_BES_P1 Events: Queued=15, Preprocessed=10

[34105] (04/08 14:47:10):{0xD7C} {2} {Hamhaber, Martin} Address Book: Detected valid address book (Martin Hamhaber)

[34489] (04/08 14:47:10):{0xD7C} {2} {Hamhaber, Martin} SC_RIM_BES_P1 Events: Queued=7, Preprocessed=0

[34455] (04/08 14:47:11):{0xD74} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCB30A.GEK.HV.100.1677A6A.1.FAED.1

[44006] (04/08 14:47:11):{0xD74} {0} {Geller, Steffen} ProcessMail Result=FAILED, ICE_ID=49DCB30A.GEK.HV.100.1677A6A.1.FAED.1

[34030] (04/08 14:47:11):{0xD74} {0} {Geller, Steffen} ProcessNewUpdateDelete failed/ignored processing item MAIL

[34455] (04/08 14:47:11):{0xD74} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCB309.GEK.HV.100.1677A6A.1.FAEA.1

[44006] (04/08 14:47:12):{0xD78} {1} {Hamhaber, Martin} ProcessMail Result=IGNORED, ICE_ID=49DCB2CB.GEK.HV.100.1666178.1.4A1FB.1

[34455] (04/08 14:47:12):{0xD74} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCB658.GEK.HV.100.1677A6A.1.FAF1.1

[44006] (04/08 14:47:12):{0xD74} {0} {Geller, Steffen} ProcessMail Result=FAILED, ICE_ID=49DCB658.GEK.HV.100.1677A6A.1.FAF1.1

[34030] (04/08 14:47:12):{0xD74} {0} {Geller, Steffen} ProcessNewUpdateDelete failed/ignored processing item MAIL

[34455] (04/08 14:47:13):{0xD74} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCB655.GEK.HV.100.1677A6A.1.FAEE.1

[44012] (04/08 14:47:13):{0xD78} {1} {Hamhaber, Martin} ProcessMailNonMemo - Processing of mail with ID=49DCB385.GEK.HV.100.1666178.1.4A1FD.1 returned True

[44014] (04/08 14:47:13):{0xD78} {1} {Hamhaber, Martin} ProcessMailNonMemo - Triggering OTAFM Scan

[34006] (04/08 14:47:13):{0xD78} {1} {Hamhaber, Martin} MTH: Update Mail, RefId=-1228418273, ICEId=49DCB385.GEK.HV.100.1666178.1.4A1FD.1, MbMailSyncId=407, ObjectDefnId=1000, Successful=True

[34455] (04/08 14:47:14):{0xD78} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCB665.GEK.HV.100.1677A6A.1.FAF5.1

[44006] (04/08 14:47:14):{0xD78} {1} {Geller, Steffen} ProcessMail Result=FAILED, ICE_ID=49DCB665.GEK.HV.100.1677A6A.1.FAF5.1

[34030] (04/08 14:47:14):{0xD78} {1} {Geller, Steffen} ProcessNewUpdateDelete failed/ignored processing item MAIL

[34455] (04/08 14:47:14):{0xD78} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCB665.GEK.HV.100.1677A6A.1.FAF2.1

[44012] (04/08 14:47:14):{0xD74} {0} {Hamhaber, Martin} ProcessMailNonMemo - Processing of mail with ID=49DCB3E0.GEK.HV.100.1666178.1.4A1FF.1 returned True

[44014] (04/08 14:47:14):{0xD74} {0} {Hamhaber, Martin} ProcessMailNonMemo - Triggering OTAFM Scan

[34006] (04/08 14:47:14):{0xD74} {0} {Hamhaber, Martin} MTH: Update Mail, RefId=-937855481, ICEId=49DCB3E0.GEK.HV.100.1666178.1.4A1FF.1, MbMailSyncId=408, ObjectDefnId=1000, Successful=True

[44012] (04/08 14:47:15):{0xD74} {0} {Hamhaber, Martin} ProcessMailNonMemo - Processing of mail with ID=49DCAB92.GEK.HV.100.1666178.1.4A1F0.1 returned True

[44014] (04/08 14:47:15):{0xD74} {0} {Hamhaber, Martin} ProcessMailNonMemo - Triggering OTAFM Scan

[34006] (04/08 14:47:15):{0xD74} {0} {Hamhaber, Martin} MTH: Update Mail, RefId=-1809543857, ICEId=49DCAB92.GEK.HV.100.1666178.1.4A1F0.1, MbMailSyncId=404, ObjectDefnId=1000, Successful=True

[14144] (04/08 14:57:09):{0xDA4} {Exception} {Database} Error executing DB call:  CallingFunction=GetParmUserStatus SQL= Select UserConfigID, UserStatus From MbParm WITH (NOLOCK)  WHERE UserConfigID IN  (Select ID from UserConfig WITH (NOLOCK) WHERE ServerConfigID = 1)

[24014] (04/08 14:57:09):{0xDA4} {Exception} {Database} General network error.  Check your network documentation. - System.Data.SqlClient.SqlException: General network error.  Check your network documentation.

   at System.Data.SqlClient.SqlCommand.ExecuteReader(CommandBehavior cmdBehavior, RunBehavior runBehavior, Boolean returnStream)

   at System.Data.SqlClient.SqlCommand.ExecuteReader(CommandBehavior behavior)

   at DBLibrary.SyncDatabase.ExecuteReader(String sSQL, SqlDataReader& oDR, SqlConnection& oConn)

[30000] (04/08 14:57:43):{0x9C4} Current Date: 2009/04/08

[54001] (04/08 14:57:44):{0x964} {GWSC} {GWSoapConnector} Starting GWSoapConnector

[34192] (04/08 14:57:46):{0x964} {GWSC} {GWSoapConnector} Database Edition=EXPRESS

[34121] (04/08 14:57:46):{0x964} {GWSC} {GWSoapConnector} User Event Flush Threshold = 10 events

[34122] (04/08 14:57:46):{0x964} {GWSC} {GWSoapConnector} User Event Flush Timeout = 900 sec

[34483] (04/08 14:57:46):{0xB74} {BESMgmt} {GWSoapConnector} Begin BESMgmt Thread

[34480] (04/08 14:57:46):{0x964} {GWSC} {GWSoapConnector} GW API Mode=GW_SOAP

[34480] (04/08 14:57:46):{0x964} {GWSC} {GWSoapConnector} SSL=OPTIONAL

[34154] (04/08 14:57:46):{0x964} {GWSC} {GWSoapConnector} Mailstore test start, test level=1

[34116] (04/08 14:57:46):{0x964} {GWSC} {rimadmin} Login: Start

[34117] (04/08 14:57:49):{0x964} {GWSC} {rimadmin} Login Successful, POA=10.252.1.237:7191, Ver=7.0.3, Build=560, SSL=False, Seconds ahead of BES=0

[34117] (04/08 14:57:50):{0x964} {GWSC} {rimadmin} Logout Successful

[34045] (04/08 14:57:50):{0x964} {GWSC} {rimadmin} Logout admin user

[34155] (04/08 14:57:50):{0x964} {GWSC} {GWSoapConnector} Mailstore test successful.

[34497] (04/08 14:57:50):{0x964} {GWSC} {GWSoapConnector} ThreadPool Size=10

[34496] (04/08 14:57:50):{0xE00} {ThreadPool} {ThreadPool} Begin ThreadPool

[34059] (04/08 14:57:50):{0x964} {GWSC} {Geller, Steffen} User Details: UserConfigID=2, PIN=25062856, Status=Active, RedirectionEnabled=True

[34058] (04/08 14:57:50):{0x964} {GWSC} {Geller, Steffen} User PIM Status: Contacts=Active, Memos=Active, Tasks=Active

[34059] (04/08 14:57:50):{0x964} {GWSC} {Hamhaber, Martin} User Details: UserConfigID=5, PIN=20760C66, Status=Active, RedirectionEnabled=True

[34058] (04/08 14:57:50):{0x964} {GWSC} {Hamhaber, Martin} User PIM Status: Contacts=Active, Memos=Active, Tasks=Active

[34482] (04/08 14:57:50):{0xE04} {Listener} {Listener} Begin Listener

[34060] (04/08 14:57:50):{0xE04} {Listener} {Listener} Listening for events at IP=192.168.15.21, Port=9000

[34123] (04/08 14:57:50):{0xE08} {GWSC} {GWSoapConnector} LogFile Cleanup

[34057] (04/08 14:57:50):{0xE08} {GWSC} {Hamhaber, Martin} Subscribed Folders: Mailbox, Ausgangsnachrichten

[34057] (04/08 14:57:50):{0xE08} {GWSC} {Geller, Steffen} Subscribed Folders: Mailbox, Ausgangsnachrichten

[34117] (04/08 14:57:50):{0xDD8} {0} {Hamhaber, Martin} Login Successful, POA=10.252.1.237:7191, Ver=7.0.3, Build=560, SSL=False, Seconds ahead of BES=0

[34117] (04/08 14:57:50):{0xDDC} {1} {Geller, Steffen} Login Successful, POA=10.252.1.237:7191, Ver=7.0.3, Build=560, SSL=False, Seconds ahead of BES=0

[34495] (04/08 14:57:50):{0xDD8} {0} {Hamhaber, Martin} SC_USER_START

[34495] (04/08 14:57:50):{0xDDC} {1} {Geller, Steffen} SC_USER_START

[34113] (04/08 14:57:51):{0xDDC} {1} {Geller, Steffen} Folders: Start (0/31)

[34113] (04/08 14:57:51):{0xDDC} {1} {Geller, Steffen} Folders: End (31/31), Total ICE Folders=48, Deleted=0

[34203] (04/08 14:57:51):{0xDDC} {1} {Geller, Steffen} System Folders: Calendar=A.GEK.HV.100.0.1.0.1@19, Trash=9.GEK.HV.100.0.1.0.1@18

[34105] (04/08 14:57:51):{0xDDC} {1} {Geller, Steffen} Address Book: Detected valid address book (Adressbuch 6.3, externe Kontakte (Clemens Weller))

[34489] (04/08 14:57:51):{0xDDC} {1} {Geller, Steffen} SC_RIM_BES_P1 Events: Queued=5, Preprocessed=0

[34113] (04/08 14:57:52):{0xDD8} {0} {Hamhaber, Martin} Folders: Start (0/26)

[34113] (04/08 14:57:52):{0xDD8} {0} {Hamhaber, Martin} Folders: End (26/26), Total ICE Folders=52, Deleted=0

[34203] (04/08 14:57:52):{0xDD8} {0} {Hamhaber, Martin} System Folders: Calendar=A.GEK.HV.100.0.1.0.1@19, Trash=9.GEK.HV.100.0.1.0.1@18

[34105] (04/08 14:57:52):{0xDD8} {0} {Hamhaber, Martin} Address Book: Detected valid address book (Martin Hamhaber)

[34489] (04/08 14:57:52):{0xDD8} {0} {Hamhaber, Martin} SC_RIM_BES_P1 Events: Queued=11, Preprocessed=0

[34455] (04/08 14:57:52):{0xDDC} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCB665.GEK.HV.100.1677A6A.1.FAF5.1

[44006] (04/08 14:57:52):{0xDDC} {1} {Geller, Steffen} ProcessMail Result=FAILED, ICE_ID=49DCB665.GEK.HV.100.1677A6A.1.FAF5.1

[34030] (04/08 14:57:52):{0xDDC} {1} {Geller, Steffen} ProcessNewUpdateDelete failed/ignored processing item MAIL

[34455] (04/08 14:57:52):{0xDDC} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCB665.GEK.HV.100.1677A6A.1.FAF2.1

[34006] (04/08 14:57:53):{0xDD8} {0} {Hamhaber, Martin} MTH: New Message, RefId=0, ICEId=49DCB99F.GEK.HV.100.1666178.1.4A202.1, MbMailSyncId=410, ObjectDefnId=1000, Successful=True

[44006] (04/08 14:57:54):{0xDD8} {0} {Hamhaber, Martin} ProcessMail Result=IGNORED, ICE_ID=49DCB2CB.GEK.HV.100.1666178.1.4A1FB.1

[44006] (04/08 14:57:55):{0xDD8} {0} {Hamhaber, Martin} ProcessMail Result=IGNORED, ICE_ID=49DCB385.GEK.HV.100.1666178.1.4A1FD.1

[44006] (04/08 14:57:55):{0xDD8} {0} {Hamhaber, Martin} ProcessMail Result=IGNORED, ICE_ID=49DCB3E0.GEK.HV.100.1666178.1.4A1FF.1

[44006] (04/08 14:57:56):{0xDD8} {0} {Hamhaber, Martin} ProcessMail Result=IGNORED, ICE_ID=49DCAB92.GEK.HV.100.1666178.1.4A1F0.1

[34455] (04/08 14:57:57):{0xDD8} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 5  MessageID: 49DCB956.GEK.HV.100.1666178.1.4A201.1

[34060] (04/08 14:58:33):{0xE04} {Listener} {Listener} Listening for events at IP=192.168.15.21, Port=9000

[34060] (04/08 15:00:35):{0xE04} {Listener} {Listener} Listening for events at IP=192.168.15.21, Port=9000

[34484] (04/08 15:12:50):{0xE08} {BESMgmt} {BESMgmt} Updating most recent event times in db

[34489] (04/08 15:12:59):{0xDDC} {1} {Geller, Steffen} SC_RIM_BES_P1 Events: Queued=24, Preprocessed=19, SlapTimeout=True

[34489] (04/08 15:12:59):{0xDD8} {0} {Hamhaber, Martin} SC_RIM_BES_P1 Events: Queued=10, Preprocessed=10, SlapTimeout=True

[34006] (04/08 15:12:59):{0xDD8} {0} {Hamhaber, Martin} MTH: New Message, RefId=0, ICEId=49DCBBF8.GEK.HV.100.1666178.1.4A204.1, MbMailSyncId=411, ObjectDefnId=1000, Successful=True

[34006] (04/08 15:13:00):{0xDD8} {0} {Hamhaber, Martin} MTH: New Message, RefId=0, ICEId=49DCBC83.GEK.HV.100.1666178.1.4A206.1, MbMailSyncId=412, ObjectDefnId=1000, Successful=True

[34006] (04/08 15:13:01):{0xDD8} {0} {Hamhaber, Martin} MTH: New Message, RefId=0, ICEId=49DCBE66.GEK.HV.100.1666178.1.4A20C.1, MbMailSyncId=413, ObjectDefnId=1000, Successful=True

[34455] (04/08 15:13:02):{0xDD8} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCBC12.GEK.HV.100.1677A6A.1.FAF9.1

[44006] (04/08 15:13:02):{0xDD8} {0} {Geller, Steffen} ProcessMail Result=FAILED, ICE_ID=49DCBC12.GEK.HV.100.1677A6A.1.FAF9.1

[34030] (04/08 15:13:02):{0xDD8} {0} {Geller, Steffen} ProcessNewUpdateDelete failed/ignored processing item MAIL

[34455] (04/08 15:13:02):{0xDD8} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCBC11.GEK.HV.100.1677A6A.1.FAF6.1

[34455] (04/08 15:13:02):{0xDD8} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCBC12.GEK.HV.100.1677A6A.1.FAF9.1

[44006] (04/08 15:13:02):{0xDD8} {0} {Geller, Steffen} ProcessMail Result=FAILED, ICE_ID=49DCBC12.GEK.HV.100.1677A6A.1.FAF9.1

[34030] (04/08 15:13:02):{0xDD8} {0} {Geller, Steffen} ProcessNewUpdateDelete failed/ignored processing item MAIL

[34455] (04/08 15:13:03):{0xDD8} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCBC31.GEK.HV.100.1677A6A.1.FAFA.1

[34006] (04/08 15:13:03):{0xDD8} {0} {Geller, Steffen} MTH: New Message, RefId=0, ICEId=49DCBC5B.GEK.HV.100.1677A6A.1.FAFB.1, MbMailSyncId=414, ObjectDefnId=1000, Successful=True

[34455] (04/08 15:13:04):{0xDD8} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCBCD2.GEK.HV.100.1677A6A.1.FB00.1

[44006] (04/08 15:13:04):{0xDD8} {0} {Geller, Steffen} ProcessMail Result=FAILED, ICE_ID=49DCBCD2.GEK.HV.100.1677A6A.1.FB00.1

[34030] (04/08 15:13:04):{0xDD8} {0} {Geller, Steffen} ProcessNewUpdateDelete failed/ignored processing item MAIL

[34455] (04/08 15:13:04):{0xDD8} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCBCD1.GEK.HV.100.1677A6A.1.FAFD.1

[34455] (04/08 15:13:05):{0xDD8} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCBDA2.GEK.HV.100.1677A6A.1.FB04.1

[44006] (04/08 15:13:05):{0xDD8} {0} {Geller, Steffen} ProcessMail Result=FAILED, ICE_ID=49DCBDA2.GEK.HV.100.1677A6A.1.FB04.1

[34030] (04/08 15:13:05):{0xDD8} {0} {Geller, Steffen} ProcessNewUpdateDelete failed/ignored processing item MAIL

[44006] (04/08 15:13:05):{0xDDC} {1} {Hamhaber, Martin} ProcessMail Result=IGNORED, ICE_ID=49DCB99F.GEK.HV.100.1666178.1.4A202.1

[34455] (04/08 15:13:05):{0xDD8} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCBDA2.GEK.HV.100.1677A6A.1.FB01.1

[34455] (04/08 15:13:06):{0xDD8} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 5  MessageID: 49DCBE44.GEK.HV.100.1666178.1.4A20B.1

[44006] (04/08 15:13:06):{0xDD8} {0} {Hamhaber, Martin} ProcessMail Result=FAILED, ICE_ID=49DCBE44.GEK.HV.100.1666178.1.4A20B.1

[34030] (04/08 15:13:06):{0xDD8} {0} {Hamhaber, Martin} ProcessNewUpdateDelete failed/ignored processing item MAIL

[34455] (04/08 15:13:06):{0xDD8} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 5  MessageID: 49DCBE43.GEK.HV.100.1666178.1.4A208.1

[34484] (04/08 15:27:50):{0xE08} {BESMgmt} {BESMgmt} Updating most recent event times in db

[34489] (04/08 15:28:09):{0xDD8} {0} {Hamhaber, Martin} SC_RIM_BES_P1 Events: Queued=3, Preprocessed=0, SlapTimeout=True

[34006] (04/08 15:28:09):{0xDDC} {1} {Hamhaber, Martin} MTH: New Message, RefId=0, ICEId=49DCBF42.GEK.HV.100.1666178.1.4A20E.1, MbMailSyncId=415, ObjectDefnId=1000, Successful=True

[34489] (04/08 15:28:09):{0xDD8} {0} {Geller, Steffen} SC_RIM_BES_P1 Events: Queued=32, Preprocessed=29, SlapTimeout=True

[34006] (04/08 15:28:10):{0xDD8} {0} {Hamhaber, Martin} MTH: New Message, RefId=0, ICEId=49DCBFEA.GEK.HV.100.1666178.1.4A210.1, MbMailSyncId=416, ObjectDefnId=1000, Successful=True

[34006] (04/08 15:28:10):{0xDDC} {1} {Hamhaber, Martin} MTH: New Message, RefId=0, ICEId=49DCC117.GEK.HV.100.1666178.1.4A212.1, MbMailSyncId=417, ObjectDefnId=1000, Successful=True

[44013] (04/08 15:28:12):{0xDD8} {0} {Geller, Steffen} ProcessMailNonMemo - Ignored item in unsubscribed folder Object=MAIL , ICE_ID= 49DCBFAE.GEK.HV.100.1677A6A.1.FB05.1

[44006] (04/08 15:28:12):{0xDD8} {0} {Geller, Steffen} ProcessMail Result=IGNORED, ICE_ID=49DCBFAE.GEK.HV.100.1677A6A.1.FB05.1

[34455] (04/08 15:28:13):{0xDD8} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCC002.GEK.HV.100.1677A6A.1.FB0A.1

[44006] (04/08 15:28:13):{0xDD8} {0} {Geller, Steffen} ProcessMail Result=FAILED, ICE_ID=49DCC002.GEK.HV.100.1677A6A.1.FB0A.1

[34030] (04/08 15:28:13):{0xDD8} {0} {Geller, Steffen} ProcessNewUpdateDelete failed/ignored processing item MAIL

[34455] (04/08 15:28:13):{0xDD8} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCC001.GEK.HV.100.1677A6A.1.FB07.1

[34455] (04/08 15:28:14):{0xDD8} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCC014.GEK.HV.100.1677A6A.1.FB0E.1

[44006] (04/08 15:28:14):{0xDD8} {0} {Geller, Steffen} ProcessMail Result=FAILED, ICE_ID=49DCC014.GEK.HV.100.1677A6A.1.FB0E.1

[34030] (04/08 15:28:14):{0xDD8} {0} {Geller, Steffen} ProcessNewUpdateDelete failed/ignored processing item MAIL

[34455] (04/08 15:28:14):{0xDD8} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCC014.GEK.HV.100.1677A6A.1.FB0B.1

[34455] (04/08 15:28:15):{0xDD8} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCC185.GEK.HV.100.1677A6A.1.FB12.1

[44006] (04/08 15:28:15):{0xDD8} {0} {Geller, Steffen} ProcessMail Result=FAILED, ICE_ID=49DCC185.GEK.HV.100.1677A6A.1.FB12.1

[34030] (04/08 15:28:15):{0xDD8} {0} {Geller, Steffen} ProcessNewUpdateDelete failed/ignored processing item MAIL

[34455] (04/08 15:28:15):{0xDD8} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCC185.GEK.HV.100.1677A6A.1.FB0F.1

[34455] (04/08 15:28:16):{0xDD8} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCC1D5.GEK.HV.100.1677A6A.1.FB16.1

[44006] (04/08 15:28:16):{0xDD8} {0} {Geller, Steffen} ProcessMail Result=FAILED, ICE_ID=49DCC1D5.GEK.HV.100.1677A6A.1.FB16.1

[34030] (04/08 15:28:16):{0xDD8} {0} {Geller, Steffen} ProcessNewUpdateDelete failed/ignored processing item MAIL

[34455] (04/08 15:28:16):{0xDD8} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCC1D4.GEK.HV.100.1677A6A.1.FB13.1

[34455] (04/08 15:28:17):{0xDD8} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCC23E.GEK.HV.100.1677A6A.1.FB1A.1

[44006] (04/08 15:28:17):{0xDD8} {0} {Geller, Steffen} ProcessMail Result=FAILED, ICE_ID=49DCC23E.GEK.HV.100.1677A6A.1.FB1A.1

[34030] (04/08 15:28:17):{0xDD8} {0} {Geller, Steffen} ProcessNewUpdateDelete failed/ignored processing item MAIL

[34455] (04/08 15:28:18):{0xDD8} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCC23E.GEK.HV.100.1677A6A.1.FB17.1

[34484] (04/08 15:42:50):{0xE08} {BESMgmt} {BESMgmt} Updating most recent event times in db

[34489] (04/08 15:43:19):{0xDD8} {0} {Hamhaber, Martin} SC_RIM_BES_P1 Events: Queued=4, Preprocessed=0, SlapTimeout=True

[34489] (04/08 15:43:19):{0xDDC} {1} {Geller, Steffen} SC_RIM_BES_P1 Events: Queued=27, Preprocessed=19, SlapTimeout=True

[34006] (04/08 15:43:19):{0xDD8} {0} {Hamhaber, Martin} MTH: New Message, RefId=0, ICEId=49DCC30C.GEK.HV.100.1666178.1.4A214.1, MbMailSyncId=418, ObjectDefnId=1000, Successful=True

[34006] (04/08 15:43:20):{0xDDC} {1} {Hamhaber, Martin} MTH: New Message, RefId=0, ICEId=49DCC34C.GEK.HV.100.1666178.1.4A216.1, MbMailSyncId=419, ObjectDefnId=1000, Successful=True

[34006] (04/08 15:43:20):{0xDD8} {0} {Hamhaber, Martin} MTH: New Message, RefId=0, ICEId=49DCC374.GEK.HV.100.1666178.1.4A218.1, MbMailSyncId=420, ObjectDefnId=1000, Successful=True

[34006] (04/08 15:43:21):{0xDD8} {0} {Hamhaber, Martin} MTH: New Message, RefId=0, ICEId=49DCC57E.GEK.HV.100.1666178.1.4A21A.1, MbMailSyncId=421, ObjectDefnId=1000, Successful=True

[44004] (04/08 15:43:22):{0xDD8} {0} {Geller, Steffen} Get Contact in subscribed AddrBook=SUCCESS, GWID=43958E8E.GEK.HV.104.1677A6A.1.2C.1

[44009] (04/08 15:43:22):{0xDD8} {0} {Geller, Steffen} Contact in unsubscribed AddrBook=42F749AE.GEK.HV.104.1677A6A.1.3.1@53 Subscribed AddrBook=439EBB78.GEK.HV.104.1677A6A.1.30.1@53

[44029] (04/08 15:43:22):{0xDD8} {0} {Geller, Steffen} ProcessNewUpdDelete - Processed Contact Work_ID=21, GWID=43958E8E.GEK.HV.104.1677A6A.1.2C.1, Event=AddressBookItemModify, Result = IGNORED

[34030] (04/08 15:43:22):{0xDD8} {0} {Geller, Steffen} ProcessNewUpdateDelete failed/ignored processing item CONTACT

[34006] (04/08 15:43:22):{0xDD8} {0} {Geller, Steffen} MTH: New Message, RefId=0, ICEId=49DCC28A.GEK.HV.100.1677A6A.1.FB1B.1, MbMailSyncId=422, ObjectDefnId=1000, Successful=True

[34455] (04/08 15:43:23):{0xDD8} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCC2CF.GEK.HV.100.1677A6A.1.FB1F.1

[44006] (04/08 15:43:23):{0xDD8} {0} {Geller, Steffen} ProcessMail Result=FAILED, ICE_ID=49DCC2CF.GEK.HV.100.1677A6A.1.FB1F.1

[34030] (04/08 15:43:23):{0xDD8} {0} {Geller, Steffen} ProcessNewUpdateDelete failed/ignored processing item MAIL

[34455] (04/08 15:43:23):{0xDD8} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCC2CF.GEK.HV.100.1677A6A.1.FB1C.1

[34455] (04/08 15:43:24):{0xDD8} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCC405.GEK.HV.100.1677A6A.1.FB23.1

[44006] (04/08 15:43:24):{0xDD8} {0} {Geller, Steffen} ProcessMail Result=FAILED, ICE_ID=49DCC405.GEK.HV.100.1677A6A.1.FB23.1

[34030] (04/08 15:43:24):{0xDD8} {0} {Geller, Steffen} ProcessNewUpdateDelete failed/ignored processing item MAIL

[34455] (04/08 15:43:24):{0xDD8} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCC405.GEK.HV.100.1677A6A.1.FB20.1

[34455] (04/08 15:43:26):{0xDD8} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCC412.GEK.HV.100.1677A6A.1.FB27.1

[44006] (04/08 15:43:26):{0xDD8} {0} {Geller, Steffen} ProcessMail Result=FAILED, ICE_ID=49DCC412.GEK.HV.100.1677A6A.1.FB27.1

[34030] (04/08 15:43:26):{0xDD8} {0} {Geller, Steffen} ProcessNewUpdateDelete failed/ignored processing item MAIL

[34455] (04/08 15:43:26):{0xDD8} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCC412.GEK.HV.100.1677A6A.1.FB24.1

[34455] (04/08 15:43:27):{0xDD8} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCC5A1.GEK.HV.100.1677A6A.1.FB2B.1

[44006] (04/08 15:43:27):{0xDD8} {0} {Geller, Steffen} ProcessMail Result=FAILED, ICE_ID=49DCC5A1.GEK.HV.100.1677A6A.1.FB2B.1

[34030] (04/08 15:43:27):{0xDD8} {0} {Geller, Steffen} ProcessNewUpdateDelete failed/ignored processing item MAIL

[34455] (04/08 15:43:27):{0xDD8} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCC5A1.GEK.HV.100.1677A6A.1.FB28.1

[34484] (04/08 15:57:50):{0xE08} {BESMgmt} {BESMgmt} Updating most recent event times in db

[34057] (04/08 15:57:50):{0xE08} {GWSC} {Hamhaber, Martin} Subscribed Folders: Mailbox, Ausgangsnachrichten

[34057] (04/08 15:57:50):{0xE08} {GWSC} {Geller, Steffen} Subscribed Folders: Mailbox, Ausgangsnachrichten

[34489] (04/08 15:58:27):{0xDD8} {0} {Hamhaber, Martin} SC_RIM_BES_P1 Events: Queued=10, Preprocessed=10, SlapTimeout=True

[34489] (04/08 15:58:27):{0xDDC} {1} {Geller, Steffen} SC_RIM_BES_P1 Events: Queued=19, Preprocessed=17, SlapTimeout=True

[34006] (04/08 15:58:28):{0xDD8} {0} {Hamhaber, Martin} MTH: New Message, RefId=0, ICEId=49DCC7E5.GEK.HV.100.1666178.1.4A21C.1, MbMailSyncId=423, ObjectDefnId=1000, Successful=True

[34455] (04/08 15:58:29):{0xDD8} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 5  MessageID: 49DCC80E.GEK.HV.100.1666178.1.4A21E.1

[34455] (04/08 15:58:30):{0xDDC} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCC806.GEK.HV.100.1677A6A.1.FB2F.1

[44006] (04/08 15:58:30):{0xDDC} {1} {Geller, Steffen} ProcessMail Result=FAILED, ICE_ID=49DCC806.GEK.HV.100.1677A6A.1.FB2F.1

[34030] (04/08 15:58:30):{0xDDC} {1} {Geller, Steffen} ProcessNewUpdateDelete failed/ignored processing item MAIL

[34455] (04/08 15:58:30):{0xDDC} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCC806.GEK.HV.100.1677A6A.1.FB2C.1

[34455] (04/08 15:58:30):{0xDDC} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCC806.GEK.HV.100.1677A6A.1.FB2F.1

[44006] (04/08 15:58:30):{0xDDC} {1} {Geller, Steffen} ProcessMail Result=FAILED, ICE_ID=49DCC806.GEK.HV.100.1677A6A.1.FB2F.1

[34030] (04/08 15:58:30):{0xDDC} {1} {Geller, Steffen} ProcessNewUpdateDelete failed/ignored processing item MAIL

[34455] (04/08 15:58:31):{0xDDC} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCC8FA.GEK.HV.100.1677A6A.1.FB33.1

[44006] (04/08 15:58:31):{0xDDC} {1} {Geller, Steffen} ProcessMail Result=FAILED, ICE_ID=49DCC8FA.GEK.HV.100.1677A6A.1.FB33.1

[34030] (04/08 15:58:31):{0xDDC} {1} {Geller, Steffen} ProcessNewUpdateDelete failed/ignored processing item MAIL

[34455] (04/08 15:58:31):{0xDD8} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 5  MessageID: 49DCC92C.GEK.HV.100.1666178.1.4A222.1

[44006] (04/08 15:58:31):{0xDD8} {0} {Hamhaber, Martin} ProcessMail Result=FAILED, ICE_ID=49DCC92C.GEK.HV.100.1666178.1.4A222.1

[34030] (04/08 15:58:31):{0xDD8} {0} {Hamhaber, Martin} ProcessNewUpdateDelete failed/ignored processing item MAIL

[34455] (04/08 15:58:31):{0xDDC} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 5  MessageID: 49DCC92B.GEK.HV.100.1666178.1.4A21F.1

[34455] (04/08 15:58:31):{0xDD8} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCC8F9.GEK.HV.100.1677A6A.1.FB30.1

[34455] (04/08 15:58:32):{0xDD8} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCC8FA.GEK.HV.100.1677A6A.1.FB37.1

[44006] (04/08 15:58:32):{0xDD8} {0} {Geller, Steffen} ProcessMail Result=FAILED, ICE_ID=49DCC8FA.GEK.HV.100.1677A6A.1.FB37.1

[34030] (04/08 15:58:32):{0xDD8} {0} {Geller, Steffen} ProcessNewUpdateDelete failed/ignored processing item MAIL

[34455] (04/08 15:58:32):{0xDD8} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCC8FA.GEK.HV.100.1677A6A.1.FB34.1

[34484] (04/08 16:12:50):{0xE08} {BESMgmt} {BESMgmt} Updating most recent event times in db

[34489] (04/08 16:13:37):{0xDD8} {0} {Hamhaber, Martin} SC_RIM_BES_P1 Events: Queued=18, Preprocessed=10, SlapTimeout=True

[34489] (04/08 16:13:38):{0xDD8} {0} {Geller, Steffen} SC_RIM_BES_P1 Events: Queued=9, Preprocessed=3, SlapTimeout=True

[34003] (04/08 16:13:38):{0xDDC} {1} {Hamhaber, Martin} MTH: New Posted Appointment, RefId=0, ICEId=49DCCCCD.GEK.HV.100.1666178.1.4A228.1, MbCalendarSyncId=627, ObjectDefnId=2000, Successful=True

[44006] (04/08 16:13:39):{0xDD8} {0} {Hamhaber, Martin} ProcessMail Result=IGNORED, ICE_ID=49DCC7E5.GEK.HV.100.1666178.1.4A21C.1

[34455] (04/08 16:13:39):{0xDDC} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCCB36.GEK.HV.100.1677A6A.1.FB3B.1

[44006] (04/08 16:13:39):{0xDDC} {1} {Geller, Steffen} ProcessMail Result=FAILED, ICE_ID=49DCCB36.GEK.HV.100.1677A6A.1.FB3B.1

[34030] (04/08 16:13:39):{0xDDC} {1} {Geller, Steffen} ProcessNewUpdateDelete failed/ignored processing item MAIL

[34455] (04/08 16:13:39):{0xDDC} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCCB36.GEK.HV.100.1677A6A.1.FB38.1

[44012] (04/08 16:13:40):{0xDD8} {0} {Hamhaber, Martin} ProcessMailNonMemo - Processing of mail with ID=49DCBBF8.GEK.HV.100.1666178.1.4A204.1 returned True

[44014] (04/08 16:13:40):{0xDD8} {0} {Hamhaber, Martin} ProcessMailNonMemo - Triggering OTAFM Scan

[34006] (04/08 16:13:40):{0xDD8} {0} {Hamhaber, Martin} MTH: Update Mail, RefId=-821073593, ICEId=49DCBBF8.GEK.HV.100.1666178.1.4A204.1, MbMailSyncId=411, ObjectDefnId=1000, Successful=True

[34455] (04/08 16:13:40):{0xDDC} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCCBBA.GEK.HV.100.1677A6A.1.FB3C.1

[44012] (04/08 16:13:40):{0xDD8} {0} {Hamhaber, Martin} ProcessMailNonMemo - Processing of mail with ID=49DCBC83.GEK.HV.100.1666178.1.4A206.1 returned True

[44014] (04/08 16:13:40):{0xDD8} {0} {Hamhaber, Martin} ProcessMailNonMemo - Triggering OTAFM Scan

[34006] (04/08 16:13:40):{0xDD8} {0} {Hamhaber, Martin} MTH: Update Mail, RefId=-530510801, ICEId=49DCBC83.GEK.HV.100.1666178.1.4A206.1, MbMailSyncId=412, ObjectDefnId=1000, Successful=True

[44012] (04/08 16:13:41):{0xDD8} {0} {Hamhaber, Martin} ProcessMailNonMemo - Processing of mail with ID=49DCBE66.GEK.HV.100.1666178.1.4A20C.1 returned True

[44014] (04/08 16:13:41):{0xDD8} {0} {Hamhaber, Martin} ProcessMailNonMemo - Triggering OTAFM Scan

[34006] (04/08 16:13:41):{0xDD8} {0} {Hamhaber, Martin} MTH: Update Mail, RefId=-239948009, ICEId=49DCBE66.GEK.HV.100.1666178.1.4A20C.1, MbMailSyncId=413, ObjectDefnId=1000, Successful=True

[44012] (04/08 16:13:41):{0xDD8} {0} {Hamhaber, Martin} ProcessMailNonMemo - Processing of mail with ID=49DCBF42.GEK.HV.100.1666178.1.4A20E.1 returned True

[44014] (04/08 16:13:41):{0xDD8} {0} {Hamhaber, Martin} ProcessMailNonMemo - Triggering OTAFM Scan

[34006] (04/08 16:13:41):{0xDD8} {0} {Hamhaber, Martin} MTH: Update Mail, RefId=-2096868864, ICEId=49DCBF42.GEK.HV.100.1666178.1.4A20E.1, MbMailSyncId=415, ObjectDefnId=1000, Successful=True

[44012] (04/08 16:13:42):{0xDD8} {0} {Hamhaber, Martin} ProcessMailNonMemo - Processing of mail with ID=49DCBFEA.GEK.HV.100.1666178.1.4A210.1 returned True

[44014] (04/08 16:13:42):{0xDD8} {0} {Hamhaber, Martin} ProcessMailNonMemo - Triggering OTAFM Scan

[34006] (04/08 16:13:42):{0xDD8} {0} {Hamhaber, Martin} MTH: Update Mail, RefId=-1806306072, ICEId=49DCBFEA.GEK.HV.100.1666178.1.4A210.1, MbMailSyncId=416, ObjectDefnId=1000, Successful=True

[44012] (04/08 16:13:43):{0xDD8} {0} {Hamhaber, Martin} ProcessMailNonMemo - Processing of mail with ID=49DCC117.GEK.HV.100.1666178.1.4A212.1 returned True

[44014] (04/08 16:13:43):{0xDD8} {0} {Hamhaber, Martin} ProcessMailNonMemo - Triggering OTAFM Scan

[34006] (04/08 16:13:43):{0xDD8} {0} {Hamhaber, Martin} MTH: Update Mail, RefId=-1515743280, ICEId=49DCC117.GEK.HV.100.1666178.1.4A212.1, MbMailSyncId=417, ObjectDefnId=1000, Successful=True

[44012] (04/08 16:13:43):{0xDD8} {0} {Hamhaber, Martin} ProcessMailNonMemo - Processing of mail with ID=49DCC30C.GEK.HV.100.1666178.1.4A214.1 returned True

[44014] (04/08 16:13:43):{0xDD8} {0} {Hamhaber, Martin} ProcessMailNonMemo - Triggering OTAFM Scan

[34006] (04/08 16:13:43):{0xDD8} {0} {Hamhaber, Martin} MTH: Update Mail, RefId=-1225180488, ICEId=49DCC30C.GEK.HV.100.1666178.1.4A214.1, MbMailSyncId=418, ObjectDefnId=1000, Successful=True

[44012] (04/08 16:13:44):{0xDD8} {0} {Hamhaber, Martin} ProcessMailNonMemo - Processing of mail with ID=49DCC34C.GEK.HV.100.1666178.1.4A216.1 returned True

[44014] (04/08 16:13:44):{0xDD8} {0} {Hamhaber, Martin} ProcessMailNonMemo - Triggering OTAFM Scan

[34006] (04/08 16:13:44):{0xDD8} {0} {Hamhaber, Martin} MTH: Update Mail, RefId=-934617696, ICEId=49DCC34C.GEK.HV.100.1666178.1.4A216.1, MbMailSyncId=419, ObjectDefnId=1000, Successful=True

[44012] (04/08 16:13:45):{0xDD8} {0} {Hamhaber, Martin} ProcessMailNonMemo - Processing of mail with ID=49DCC374.GEK.HV.100.1666178.1.4A218.1 returned True

[44014] (04/08 16:13:45):{0xDD8} {0} {Hamhaber, Martin} ProcessMailNonMemo - Triggering OTAFM Scan

[34006] (04/08 16:13:45):{0xDD8} {0} {Hamhaber, Martin} MTH: Update Mail, RefId=-644054904, ICEId=49DCC374.GEK.HV.100.1666178.1.4A218.1, MbMailSyncId=420, ObjectDefnId=1000, Successful=True

[34455] (04/08 16:13:46):{0xDD8} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 5  MessageID: 49DCCCAE.GEK.HV.100.1666178.1.4A226.1

[44006] (04/08 16:13:46):{0xDD8} {0} {Hamhaber, Martin} ProcessMail Result=FAILED, ICE_ID=49DCCCAE.GEK.HV.100.1666178.1.4A226.1

[34030] (04/08 16:13:46):{0xDD8} {0} {Hamhaber, Martin} ProcessNewUpdateDelete failed/ignored processing item MAIL

[34455] (04/08 16:13:46):{0xDD8} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 5  MessageID: 49DCCCAC.GEK.HV.100.1666178.1.4A223.1

[44012] (04/08 16:13:47):{0xDD8} {0} {Hamhaber, Martin} ProcessMailNonMemo - Processing of mail with ID=49DCC57E.GEK.HV.100.1666178.1.4A21A.1 returned True

[44014] (04/08 16:13:47):{0xDD8} {0} {Hamhaber, Martin} ProcessMailNonMemo - Triggering OTAFM Scan

[34006] (04/08 16:13:47):{0xDD8} {0} {Hamhaber, Martin} MTH: Update Mail, RefId=-353492112, ICEId=49DCC57E.GEK.HV.100.1666178.1.4A21A.1, MbMailSyncId=421, ObjectDefnId=1000, Successful=True

Open in new window

0
 

Author Comment

by:chief-MH
Comment Utility
- the Mailboxsize is nearly 3 GB
- between the BES and the POA is a firewall. We checked the Logs from the firewall and there is nothing droped.
0
How to run any project with ease

Manage projects of all sizes how you want. Great for personal to-do lists, project milestones, team priorities and launch plans.
- Combine task lists, docs, spreadsheets, and chat in one
- View and edit from mobile/offline
- Cut down on emails

 
LVL 26

Expert Comment

by:Gary Cutri
Comment Utility
Ok, it appears you are having a large number of issues with your BlackBerry database (taking in to consideration the errors triggered by server restarts) which may be caused by SQL2005 Express or a database corruption.  You have a number of errors that indicate some contacts are missing or duplicated on the device.  First double click a user in BlackBerry Manager and then from PIM Sync scroll down to contacts and ensure the correct contact list is selected (e.g. the personal contact list Vs Frequent contacts), then from the device open Contact > Press menu > select Options > type RSET (this will delete all the device contacts and reload them from the server.)  Also please try the following to check the database for corruption:

http://www.blackberry.com/btsc/search.do?cmd=displayKC&docType=kc&externalId=KB13822&sliceId=1&docTypeID=DT_SUPPORTISSUE_1_1&dialogID=70524771&stateId=0 0 70665637
0
 

Author Comment

by:chief-MH
Comment Utility
Ok I have reset the Adressbook and made the DBCheck. The result was:

CHECKDB found 0 allocation errors and 0 consistency errors in database 'BESMgmt'.
DBCC execution completed. If DBCC printed error messages, contact your system administrator.

After that I restarted the BES Server and gets those messages:
GWCO Log:

[30000] (04/08 17:19:20):{0xB00} Current Date: 2009/04/08

[54001] (04/08 17:19:21):{0x984} {GWCO} {GWConnector} Starting GWConnector

[34192] (04/08 17:19:22):{0x984} {GWCO} {GWConnector} Database Edition=EXPRESS

[34481] (04/08 17:19:22):{0x984} {GWCO} {GWConnector} GW API Mode=GW_SOAP

[34154] (04/08 17:19:22):{0x984} {GWCO} {GWConnector} Mailstore test start, test level=1

[34116] (04/08 17:19:22):{0x984} {GWCO} {rimadmin} Login: Start

[34117] (04/08 17:19:23):{0x984} {GWCO} {rimadmin} Login Successful: DOMAIN=GEK, POA=HV, FID=7e1 (1316537)

[34443] (04/08 17:19:23):{0x984} {GWCO} {rimadmin} POA Test: Successful

[34108] (04/08 17:19:23):{0x984} {GWCO} {rimadmin} Folders: Start

[34109] (04/08 17:19:23):{0x984} {GWCO} {rimadmin} Folders: Test successful

[34127] (04/08 17:19:23):{0x984} {GWCO} {rimadmin} Messages: Start

[34128] (04/08 17:19:23):{0x984} {GWCO} {rimadmin} Messages: Test successful

[34134] (04/08 17:19:23):{0x984} {GWCO} {rimadmin} QuickMessages: Check New test start

[34135] (04/08 17:19:23):{0x984} {GWCO} {rimadmin} QuickMessages: Check New test successful

[34137] (04/08 17:19:23):{0x984} {GWCO} {rimadmin} QuickMessages: Check Changes test start

[34138] (04/08 17:19:23):{0x984} {GWCO} {rimadmin} QuickMessages: Check Changes test successful

[34129] (04/08 17:19:23):{0x984} {GWCO} {rimadmin} Appointments: Start

[34130] (04/08 17:19:23):{0x984} {GWCO} {rimadmin} Appointments: Test successful

[34140] (04/08 17:19:23):{0x984} {GWCO} {rimadmin} CalendarQuery: Start

[34141] (04/08 17:19:23):{0x984} {GWCO} {rimadmin} CalendarQuery: Successful, items=1

[34131] (04/08 17:19:24):{0x984} {GWCO} {rimadmin} Messages: Deleted test message.

[34132] (04/08 17:19:24):{0x984} {GWCO} {rimadmin} Messages: Deleted test appointment.

[34142] (04/08 17:19:24):{0x984} {GWCO} {rimadmin} Trash: Start

[34143] (04/08 17:19:24):{0x984} {GWCO} {rimadmin} Trash: query successful, entries=2

[34133] (04/08 17:19:24):{0x984} {GWCO} {rimadmin} Cleanup: Deleting test items from trash

[34144] (04/08 17:19:24):{0x984} {GWCO} {rimadmin} AddressBooks: Start, Book=Frequent Contacts

[34145] (04/08 17:19:24):{0x984} {GWCO} {rimadmin} AddressBooks: Test successful

[34045] (04/08 17:19:24):{0x984} {GWCO} {rimadmin} Logout admin user

[34155] (04/08 17:19:24):{0x984} {GWCO} {GWConnector} Mailstore test successful.

[34059] (04/08 17:19:24):{0x984} {GWCO} {Geller, Steffen} User Details: UserConfigID=2, PIN=25062856, Status=Active, RedirectionEnabled=True

[34058] (04/08 17:19:24):{0x984} {GWCO} {Geller, Steffen} User PIM Status: Contacts=Active, Memos=Active, Tasks=Active

[34059] (04/08 17:19:24):{0x984} {GWCO} {Hamhaber, Martin} User Details: UserConfigID=5, PIN=20760C66, Status=Active, RedirectionEnabled=True

[34058] (04/08 17:19:24):{0x984} {GWCO} {Hamhaber, Martin} User PIM Status: Contacts=Active, Memos=Active, Tasks=Active

[34046] (04/08 17:19:24):{0x984} {0} {Hamhaber, Martin} User Assigned to Thread

[34046] (04/08 17:19:24):{0x984} {1} {Geller, Steffen} User Assigned to Thread

[34117] (04/08 17:19:24):{0xDC8} {0} {Hamhaber, Martin} Login Successful: DOMAIN=GEK, POA=HV, FID=xaf (1666178)

[34117] (04/08 17:19:24):{0xDCC} {1} {Geller, Steffen} Login Successful: DOMAIN=GEK, POA=HV, FID=jzg (1677A6A)

[34063] (04/08 17:19:24):{0xDC8} {0} {Hamhaber, Martin} Folders: Total=24 Subscribed=2

[34063] (04/08 17:19:24):{0xDCC} {1} {Geller, Steffen} Folders: Total=29 Subscribed=2
 
 

GWSV Log:

[30000] (04/08 17:19:13):{0x4D4} Current Date: 2009/04/08

[54003] (04/08 17:19:18):{0x4B8} {0} {GWSV} - - - - - Starting GW Controlling Service - - - - -

[34429] (04/08 17:19:18):{0x4B8} {0} {GWSV} Path=c:\Program Files\Research In Motion\BlackBerry Enterprise Server\

[34430] (04/08 17:19:18):{0x4B8} {0} {GWSV} Timeout=900

[34431] (04/08 17:19:18):{0x4B8} {0} {GWSV} Health Interval=30

[34432] (04/08 17:19:18):{0x4B8} {0} {GWSV} Kill outstanding processes

[34433] (04/08 17:19:18):{0x4B8} {0} {GWSV} Start GW processes

[34480] (04/08 17:19:18):{0x4B8} {0} {GWSV} GW API Mode=GW_SOAP

[34427] (04/08 17:19:18):{0x4B8} {0} {GWSV} Started Process GWSoapConnector (2420)

[34427] (04/08 17:19:18):{0x4B8} {0} {GWSV} Started Process GWConnector (2432)

[34436] (04/08 17:19:18):{0x988} {0} {GWSV} Starting Process Monitor
 
 
 

GWSC Log
 

[30000] (04/08 17:19:20):{0xAF8} Current Date: 2009/04/08

[54001] (04/08 17:19:21):{0x978} {GWSC} {GWSoapConnector} Starting GWSoapConnector

[34192] (04/08 17:19:21):{0x978} {GWSC} {GWSoapConnector} Database Edition=EXPRESS

[34121] (04/08 17:19:22):{0x978} {GWSC} {GWSoapConnector} User Event Flush Threshold = 10 events

[34122] (04/08 17:19:22):{0x978} {GWSC} {GWSoapConnector} User Event Flush Timeout = 900 sec

[34483] (04/08 17:19:22):{0xC18} {BESMgmt} {GWSoapConnector} Begin BESMgmt Thread

[34480] (04/08 17:19:22):{0x978} {GWSC} {GWSoapConnector} GW API Mode=GW_SOAP

[34480] (04/08 17:19:22):{0x978} {GWSC} {GWSoapConnector} SSL=OPTIONAL

[34154] (04/08 17:19:22):{0x978} {GWSC} {GWSoapConnector} Mailstore test start, test level=1

[34116] (04/08 17:19:22):{0x978} {GWSC} {rimadmin} Login: Start

[34117] (04/08 17:19:25):{0x978} {GWSC} {rimadmin} Login Successful, POA=10.252.1.237:7191, Ver=7.0.3, Build=560, SSL=False, Seconds ahead of BES=-2

[34117] (04/08 17:19:25):{0x978} {GWSC} {rimadmin} Logout Successful

[34045] (04/08 17:19:25):{0x978} {GWSC} {rimadmin} Logout admin user

[34155] (04/08 17:19:25):{0x978} {GWSC} {GWSoapConnector} Mailstore test successful.

[34497] (04/08 17:19:25):{0x978} {GWSC} {GWSoapConnector} ThreadPool Size=10

[34059] (04/08 17:19:25):{0x978} {GWSC} {Geller, Steffen} User Details: UserConfigID=2, PIN=25062856, Status=Active, RedirectionEnabled=True

[34058] (04/08 17:19:25):{0x978} {GWSC} {Geller, Steffen} User PIM Status: Contacts=Active, Memos=Active, Tasks=Active

[34059] (04/08 17:19:25):{0x978} {GWSC} {Hamhaber, Martin} User Details: UserConfigID=5, PIN=20760C66, Status=Active, RedirectionEnabled=True

[34058] (04/08 17:19:25):{0x978} {GWSC} {Hamhaber, Martin} User PIM Status: Contacts=Active, Memos=Active, Tasks=Active

[34482] (04/08 17:19:25):{0xE38} {Listener} {Listener} Begin Listener

[34060] (04/08 17:19:25):{0xE38} {Listener} {Listener} Listening for events at IP=192.168.15.21, Port=9000

[34123] (04/08 17:19:25):{0xE3C} {GWSC} {GWSoapConnector} LogFile Cleanup

[34057] (04/08 17:19:25):{0xE3C} {GWSC} {Hamhaber, Martin} Subscribed Folders: Mailbox, Ausgangsnachrichten

[34057] (04/08 17:19:25):{0xE3C} {GWSC} {Geller, Steffen} Subscribed Folders: Mailbox, Ausgangsnachrichten

[34496] (04/08 17:19:25):{0xE34} {ThreadPool} {ThreadPool} Begin ThreadPool

[34117] (04/08 17:19:25):{0xE0C} {0} {Hamhaber, Martin} Login Successful, POA=10.252.1.237:7191, Ver=7.0.3, Build=560, SSL=False, Seconds ahead of BES=-1

[34117] (04/08 17:19:25):{0xE10} {1} {Geller, Steffen} Login Successful, POA=10.252.1.237:7191, Ver=7.0.3, Build=560, SSL=False, Seconds ahead of BES=-1

[34495] (04/08 17:19:25):{0xE0C} {0} {Hamhaber, Martin} SC_USER_START

[34495] (04/08 17:19:26):{0xE10} {1} {Geller, Steffen} SC_USER_START

[34113] (04/08 17:19:26):{0xE10} {1} {Geller, Steffen} Folders: Start (0/31)

[34113] (04/08 17:19:26):{0xE10} {1} {Geller, Steffen} Folders: End (31/31), Total ICE Folders=48, Deleted=0

[34203] (04/08 17:19:26):{0xE10} {1} {Geller, Steffen} System Folders: Calendar=A.GEK.HV.100.0.1.0.1@19, Trash=9.GEK.HV.100.0.1.0.1@18

[34105] (04/08 17:19:26):{0xE10} {1} {Geller, Steffen} Address Book: Detected valid address book (Adressbuch 6.3, externe Kontakte (Clemens Weller))

[34489] (04/08 17:19:26):{0xE10} {1} {Geller, Steffen} SC_RIM_BES_P1 Events: Queued=14, Preprocessed=8

[34113] (04/08 17:19:27):{0xE0C} {0} {Hamhaber, Martin} Folders: Start (0/26)

[34113] (04/08 17:19:27):{0xE0C} {0} {Hamhaber, Martin} Folders: End (26/26), Total ICE Folders=52, Deleted=0

[34203] (04/08 17:19:27):{0xE0C} {0} {Hamhaber, Martin} System Folders: Calendar=A.GEK.HV.100.0.1.0.1@19, Trash=9.GEK.HV.100.0.1.0.1@18

[34105] (04/08 17:19:27):{0xE0C} {0} {Hamhaber, Martin} Address Book: Detected valid address book (Martin Hamhaber)

[34489] (04/08 17:19:27):{0xE0C} {0} {Hamhaber, Martin} SC_RIM_BES_P1 Events: Queued=5, Preprocessed=0

[34006] (04/08 17:19:27):{0xE10} {1} {Geller, Steffen} MTH: New Message, RefId=0, ICEId=49DCDAD4.GEK.HV.100.1677A6A.1.FB59.1, MbMailSyncId=429, ObjectDefnId=1000, Successful=True

[34006] (04/08 17:19:27):{0xE0C} {0} {Hamhaber, Martin} MTH: New Message, RefId=0, ICEId=49DCD8F0.GEK.HV.100.1666178.1.4A24A.1, MbMailSyncId=430, ObjectDefnId=1000, Successful=True

[34006] (04/08 17:19:27):{0xE10} {1} {Geller, Steffen} MTH: New Message, RefId=0, ICEId=49DCDC8C.GEK.HV.100.1677A6A.1.FB5B.1, MbMailSyncId=431, ObjectDefnId=1000, Successful=True

[44038] (04/08 17:19:27):{0xE0C} {0} {Hamhaber, Martin} ProcessCalendarDelete - Calendar Item with ICEId=49DCCCCD.GEK.HV.100.1666178.1.4A228.1 retrieved from DB successfully

[44039] (04/08 17:19:27):{0xE0C} {0} {Hamhaber, Martin} ProcessCalendarDelete - Calendar Item retrieved with ICEId=49DCCCCD.GEK.HV.100.1666178.1.4A228.1DB_Id=-1

[44004] (04/08 17:19:28):{0xE10} {1} {Geller, Steffen} Get Contact in subscribed AddrBook=SUCCESS, GWID=49D4E703.GEK.HV.104.1677A6A.1.1E3.1

[44009] (04/08 17:19:28):{0xE10} {1} {Geller, Steffen} Contact in unsubscribed AddrBook=42F749AE.GEK.HV.104.1677A6A.1.3.1@53 Subscribed AddrBook=439EBB78.GEK.HV.104.1677A6A.1.30.1@53

[44029] (04/08 17:19:28):{0xE10} {1} {Geller, Steffen} ProcessNewUpdDelete - Processed Contact Work_ID=21, GWID=49D4E703.GEK.HV.104.1677A6A.1.1E3.1, Event=AddressBookItemModify, Result = IGNORED

[34030] (04/08 17:19:28):{0xE10} {1} {Geller, Steffen} ProcessNewUpdateDelete failed/ignored processing item CONTACT

[34455] (04/08 17:19:28):{0xE10} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCD642.GEK.HV.100.1677A6A.1.FB54.1

[44006] (04/08 17:19:28):{0xE10} {1} {Geller, Steffen} ProcessMail Result=FAILED, ICE_ID=49DCD642.GEK.HV.100.1677A6A.1.FB54.1

[34030] (04/08 17:19:28):{0xE10} {1} {Geller, Steffen} ProcessNewUpdateDelete failed/ignored processing item MAIL

[44041] (04/08 17:19:28):{0xE0C} {0} {Hamhaber, Martin} ProcessCalendarDelete - Succeeded retrieving item with ICEId=49DCCCCD.GEK.HV.100.1666178.1.4A228.1

[34003] (04/08 17:19:28):{0xE0C} {0} {Hamhaber, Martin} MTH: Delete Calendar, RefId=0, ICEId=49DCCCCD.GEK.HV.100.1666178.1.4A228.1, MbCalendarSyncId=-1, ObjectDefnId=2000, Successful=True

[44046] (04/08 17:19:28):{0xE0C} {0} {Hamhaber, Martin} ProcessCalendarDelete - Deletion of non-recurring calendar item with ICEId=49DCCCCD.GEK.HV.100.1666178.1.4A228.1 SUCCEEDED

[34455] (04/08 17:19:29):{0xE0C} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 5  MessageID: 49DCD6B5.GEK.HV.100.1666178.1.4A249.1

[34455] (04/08 17:19:29):{0xE10} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCDA7B.GEK.HV.100.1677A6A.1.FB58.1

[44006] (04/08 17:19:29):{0xE10} {1} {Geller, Steffen} ProcessMail Result=FAILED, ICE_ID=49DCDA7B.GEK.HV.100.1677A6A.1.FB58.1

[34030] (04/08 17:19:29):{0xE10} {1} {Geller, Steffen} ProcessNewUpdateDelete failed/ignored processing item MAIL

[34455] (04/08 17:19:29):{0xE10} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCDA7A.GEK.HV.100.1677A6A.1.FB55.1

[44022] (04/08 17:19:29):{0xE0C} {0} {Hamhaber, Martin} ProcessMailNonMemo - Ignoring item that is > 8days old

[44006] (04/08 17:19:29):{0xE0C} {0} {Hamhaber, Martin} ProcessMail Result=IGNORED, ICE_ID=49B510E6.GEK.HV.100.1666178.1.482B0.1

[44006] (04/08 17:19:29):{0xE10} {1} {Geller, Steffen} ProcessMail Result=IGNORED, ICE_ID=49DCCFE2.GEK.HV.100.1677A6A.1.FB3D.1

[34455] (04/08 17:19:30):{0xE0C} {Exception} {Database} GetPIMSyncByMessageID: MbPIMSync entry not found. UserID: 2  MessageID: 49DCDB3E.GEK.HV.100.1677A6A.1.FB5A.1

[34060] (04/08 17:22:03):{0xE38} {Listener} {Listener} Listening for events at IP=192.168.15.21, Port=9000

Open in new window

0
 
LVL 26

Expert Comment

by:Gary Cutri
Comment Utility
Ok, at this stage I would recommend the following assuming you already the BES running and then you upgraded to SP6 and MR3:

1. Wipe the users device - Options > Security Options > General Settings > Press menu > select Wipe Handheld
2. Delete the users account from the BES
3. If the users mailbox is over 2GB reduce the size as much as possible
4. Restart the BES
5. Add the user back onto the BES and reactivate the devices
6. Double click the users and select PIM Sync and ensure the correct contacts list is selected.
0
 

Author Comment

by:chief-MH
Comment Utility
Thanks for your answer.

At the moment, there are only two users on the BES Servers. And both has the same Problem.
But I'll test it tomorrow.
Is it posible to configer a Timeout for the BES to get Messages from the POA?

When I alanysed the Trace an E-Mail arrived in the Mailaccount.
After that the POA mad a syn and gets an ack back and pushs the Info of a new Mail. The BES send an ack und a termination.
After that happens nothing. When the next Mail arrive the POA didn't made a new connection.
Some minutes later the Interval fetches all Mails over SOAP und after that the POA made a new syn, gets an ack and pushs new Mailinfos.

So can I configer a Timeout?
0
 

Author Comment

by:chief-MH
Comment Utility
Hello,

today I deleted all Users from those BES Server and wiped those Handhelds.
Some minutes later, I added a nes User and activated it.
There is still the same error...
0
 
LVL 26

Expert Comment

by:Gary Cutri
Comment Utility
Apart from the possibility that there is an issue with the GroupWise Client on the BES (I have seen this twice before) we can try adding the /conntimeout-21 switch to the Novell GroupWise Messaging Agent start-up file (strtup.ma).  Past this point I would be inclined to uninstall BES, remove the GW client and perform a fresh install (this recommendation is only feasible due to the fact that you only have 2 users).
0
 

Accepted Solution

by:
chief-MH earned 0 total points
Comment Utility
Hello,

I found the problem:
At the networktrace on my BES I get after the PSH, ACK Packet a RST, ACK Packet. So the network interface drops those Stream and the BES didn't get those Packets and so there is no notification at the BES Logs.
After that I made a TCP Dump with "tcpdump -i eth0 -w dump.cap host 192.168.X.X" on my mailserver and checked this with Wireshark. This dumps shows a correct transmission with SYN - SYN,ACK - ACK - PSH,ACK - FIN,ACK - ACK That means, that the GroupWise Server sends the correct Packets which the the BES didn't get. After that I configured our eSafe Network Scanner to ignore all packets for the BES Server and now IT WORKS

So there isn't a problem with the BES and at the moment I didn't know what the eSafe found in that TCP - XML stream.

So thank you Sage for your help

Martin
0

Featured Post

Free Trending Threat Insights Every Day

Enhance your security with threat intelligence from the web. Get trending threat insights on hackers, exploits, and suspicious IP addresses delivered to your inbox with our free Cyber Daily.

Join & Write a Comment

Most of the sites are being standardized with W3C Web Standards. W3C provides lot of web standard services to the web. They have the web specification, process and documentation for all the web standards. You can apply HTML, CSS and Accessibility st…
I found this questions asking how to do this in many different forums, so I will describe here how to implement a solution using PHP and AJAX. The logical flow for the problem should be: Write an event handler for the first drop down box to get …
The viewer will receive an overview of the basics of CSS showing inline styles. In the head tags set up your style tags: (CODE) Reference the nav tag and set your properties.: (CODE) Set the reference for the UL element and styles for it to ensu…
The viewer will learn the basics of jQuery, including how to invoke it on a web page. Reference your jQuery libraries: (CODE) Include your new external js/jQuery file: (CODE) Write your first lines of code to setup your site for jQuery.: (CODE)

763 members asked questions and received personalized solutions in the past 7 days.

Join the community of 500,000 technology professionals and ask your questions.

Join & Ask a Question

Need Help in Real-Time?

Connect with top rated Experts

9 Experts available now in Live!

Get 1:1 Help Now