Results 1 to 4 of 4

Thread: Errors in ZBC Logs

  1. 03-28-2008, 12:29 PM #1
    Chewie71
    Chewie71 is online now Trained Alumni
    Send a message via Yahoo to Chewie71
    Join Date
    Sep 2006
    Location
    Illinois
    Posts
    368
    Rep Power
    7

    Default Errors in ZBC Logs

    How do I know if my users are syncing to BES through ZBC?

    I'm getting this in my ZBC errors...
    Code:
    28-03-2008 14:21:03.987 [3548]: TRACING => Zimbra::Rpc::UserSession::GetInstance

28-03-2008 14:21:03.987 [3548]: DEBUG: Zimbra::Rpc::UserSession::GetInstance - get a user session (0x2857450)

28-03-2008 14:21:03.987 [3548]: *99* Enter Zimbra::Rpc::Connection::SendRequest ...

28-03-2008 14:21:03.987 [3548]: *99* <<<<-------- HTTP stream Start (Request) ----------------------------------------------->>>>

28-03-2008 14:22:04.509 [3548]: *99* ERROR: Zimbra::Rpc::Connection::SendRequest - failed on WinHttpReceiveResponse(...), Error Code: 12002

28-03-2008 14:22:04.509 [3548]: Zimbra::Store::CWaitRequest::WaitRequestThread. SendRequest failed with Error 0x2ee2. Desc The request has timed out.
    The BES MAGT Log has entries like this..
    Code:
    [30053] (03/28 14:24:48.306):{0xE28} Retry start for user: my-email@my.domain

[30041] (03/28 14:24:48.306):{0xE28} Starting handheld for MYNAME

[30463] (03/28 14:24:48.306):{0xE28} User MYNAME can not be started, ServerDN not defined, user may restart after ScanGAL.

[20154] (03/28 14:24:48.306):{0xE28} User MYNAME not started

[50079] (03/28 14:24:48.306):{0xE28} 8 user(s) failed to initialize
    Thanks,
    Matt
    Reply With Quote Reply With Quote
  2. 03-28-2008, 12:55 PM #2
    alee
    alee is offline Former Zimbran
    Join Date
    Apr 2007
    Location
    San Jose
    Posts
    406
    Rep Power
    7

    Default

    Quote Originally Posted by Chewie71 View Post
    How do I know if my users are syncing to BES through ZBC?

    I'm getting this in my ZBC errors...
    Code:
    28-03-2008 14:21:03.987 [3548]: TRACING => Zimbra::Rpc::UserSession::GetInstance

28-03-2008 14:21:03.987 [3548]: DEBUG: Zimbra::Rpc::UserSession::GetInstance - get a user session (0x2857450)

28-03-2008 14:21:03.987 [3548]: *99* Enter Zimbra::Rpc::Connection::SendRequest ...

28-03-2008 14:21:03.987 [3548]: *99* <<<<-------- HTTP stream Start (Request) ----------------------------------------------->>>>

28-03-2008 14:22:04.509 [3548]: *99* ERROR: Zimbra::Rpc::Connection::SendRequest - failed on WinHttpReceiveResponse(...), Error Code: 12002

28-03-2008 14:22:04.509 [3548]: Zimbra::Store::CWaitRequest::WaitRequestThread. SendRequest failed with Error 0x2ee2. Desc The request has timed out.
    The BES MAGT Log has entries like this..
    Code:
    [30053] (03/28 14:24:48.306):{0xE28} Retry start for user: my-email@my.domain

[30041] (03/28 14:24:48.306):{0xE28} Starting handheld for MYNAME

[30463] (03/28 14:24:48.306):{0xE28} User MYNAME can not be started, ServerDN not defined, user may restart after ScanGAL.

[20154] (03/28 14:24:48.306):{0xE28} User MYNAME not started

[50079] (03/28 14:24:48.306):{0xE28} 8 user(s) failed to initialize
    Thanks,
    Matt
    the snippet from the ZCB logs is not an error. the snipped from the agent logs is very bad. the server DN should be returned as a valid string.
    please post the ZCB logs from the same timeframe where that error in the agent logs occurred. ZCB are responsible for giving BES the server DN.
    Reply With Quote Reply With Quote
  3. 03-28-2008, 02:34 PM #3
    Chewie71
    Chewie71 is online now Trained Alumni
    Send a message via Yahoo to Chewie71
    Join Date
    Sep 2006
    Location
    Illinois
    Posts
    368
    Rep Power
    7

    Default

    OK...I rebooted my BES server and watched the logs again.

    Right around 16:09:15 I got the "ServerDN not defined" error in the BES MAGT log.

    This is what is occurring in the ZCB logs at about that time.

    Code:
    28-03-2008 16:09:02.912 [3448]: Zimbra::Sync::Synchronizer::TrySync. ++++++++++++SYNC FINISHED++++++++++++

28-03-2008 16:09:02.912 [3448]: Zimbra::Sync::Synchronizer::TrySync. done syncing primary store (Zimbra - BES Admin)

28-03-2008 16:09:02.912 [3448]: Zimbra::Sync::Synchronizer::TrySync. +++++++++++++++++++++++++++++++++++++++++

28-03-2008 16:09:02.912 [3448]:

28-03-2008 16:09:02.912 [3448]: Zimbra::Store::StoreContext::InitiateSync. After sync on store

28-03-2008 16:09:02.912 [3448]: Zimbra::Store::StoreContextManager::ProcessSyncRequest. About to sync other stores

28-03-2008 16:09:03.82 [2384]: In Hook_AddrBookOpenEntry

28-03-2008 16:09:03.82 [2384]: ObjectManager::WrapObject

28-03-2008 16:09:03.82 [2384]: Object Type: (6) MAPI_MAILUSER

28-03-2008 16:09:03.82 [2384]: ctor this(02865898) inner(014150C8) thiscount(0l)

28-03-2008 16:09:03.82 [2384]: Zimbra::Store::ZimbraMailUser::ZimbraMailUser

28-03-2008 16:09:03.82 [2384]: Zimbra::Store::ZimbraMailUser::Initialize

28-03-2008 16:09:03.82 [2384]: Zimbra::Store::ZimbraMailUser::GetProps.

28-03-2008 16:09:03.82 [2384]: Zimbra::Store::ZimbraMailUser::GetProps. (00000000812b1fa4bea310199d6e00dd010f5402000000804200450053002000410064006d0069006e0000004c0053004d0053000000620065007300610064006d0069006e0040007700690075002e006500640075000000) ++++++++++++++++++++++++++++++++

28-03-2008 16:09:03.82 [2384]: Zimbra::Store::ZimbraMailUser::GetProps. (00000000812b1fa4bea310199d6e00dd010f5402000000804200450053002000410064006d0069006e0000004c0053004d0053000000620065007300610064006d0069006e0040007700690075002e006500640075000000) interface address: 14150c8

28-03-2008 16:09:03.82 [2384]: Zimbra::Store::ZimbraMailUser::GetProps. (00000000812b1fa4bea310199d6e00dd010f5402000000804200450053002000410064006d0069006e0000004c0053004d0053000000620065007300610064006d0069006e0040007700690075002e006500640075000000) PR_DISPLAY_NAME: BES Admin

28-03-2008 16:09:03.82 [2384]: Zimbra::Store::ZimbraMailUser::GetProps. (00000000812b1fa4bea310199d6e00dd010f5402000000804200450053002000410064006d0069006e0000004c0053004d0053000000620065007300610064006d0069006e0040007700690075002e006500640075000000) cannot dump the rest of the folder info due to lack of store pointer

28-03-2008 16:09:03.82 [2384]: Zimbra::Store::ZimbraMailUser::GetProps. (00000000812b1fa4bea310199d6e00dd010f5402000000804200450053002000410064006d0069006e0000004c0053004d0053000000620065007300610064006d0069006e0040007700690075002e006500640075000000) ++++++++++++++++++++++++++++++++

28-03-2008 16:09:03.82 [2384]: Zimbra::Store::ZimbraMailUser::GetProps. dumping prop tags requested for this user:

28-03-2008 16:09:03.82 [2384]: Tracing properties...

28-03-2008 16:09:03.142 [2384]: Id(Named Property (8007)) Type(PT_STRING8)

28-03-2008 16:09:03.142 [2384]: DEBUG: Zimbra::Rpc::UserSession::GetSupportObject - m_pMapiSup = 0x013d2bc8

28-03-2008 16:09:03.142 [2384]: DEBUG: Zimbra::Mapi::GetProfileParamProps - # of properties retrieved from profile: 19

28-03-2008 16:09:03.142 [2384]: DEBUG: Zimbra::Mapi::GetProfileParamProps #0 property tag: 0x6640001f

28-03-2008 16:09:03.142 [2384]: DEBUG: Zimbra::Mapi::GetProfileParamProps #1 property tag: 0x66430003

28-03-2008 16:09:03.142 [2384]: DEBUG: Zimbra::Mapi::GetProfileParamProps #2 property tag: 0x6641001f

28-03-2008 16:09:03.142 [2384]: DEBUG: Zimbra::Mapi::GetProfileParamProps #3 property tag: 0x66420102

28-03-2008 16:09:03.142 [2384]: DEBUG: Zimbra::Mapi::GetProfileParamProps #4 property tag: 0x6644000b

28-03-2008 16:09:03.142 [2384]: DEBUG: Zimbra::Mapi::GetProfileParamProps #5 property tag: 0x66550003

28-03-2008 16:09:03.142 [2384]: DEBUG: Zimbra::Mapi::GetProfileParamProps #5 property value:      0x1

28-03-2008 16:09:03.142 [2384]: DEBUG: Zimbra::Mapi::GetProfileParamProps #6 property tag: 0x6656000a

28-03-2008 16:09:03.142 [2384]: DEBUG: Zimbra::Mapi::GetProfileParamProps #7 property tag: 0x6657000a

28-03-2008 16:09:03.142 [2384]: DEBUG: Zimbra::Mapi::GetProfileParamProps #8 property tag: 0x6645001f

28-03-2008 16:09:03.142 [2384]: DEBUG: Zimbra::Mapi::GetProfileParamProps #9 property tag: 0x6646001f

28-03-2008 16:09:03.142 [2384]: DEBUG: Zimbra::Mapi::GetProfileParamProps #10 property tag: 0x66470102

28-03-2008 16:09:03.142 [2384]: DEBUG: Zimbra::Mapi::GetProfileParamProps #11 property tag: 0x6648000b

28-03-2008 16:09:03.142 [2384]: DEBUG: Zimbra::Mapi::GetProfileParamProps #12 property tag: 0x6649000a

28-03-2008 16:09:03.142 [2384]: DEBUG: Zimbra::Mapi::GetProfileParamProps #13 property tag: 0x6650000a

28-03-2008 16:09:03.142 [2384]: DEBUG: Zimbra::Mapi::GetProfileParamProps #14 property tag: 0x6652000a

28-03-2008 16:09:03.142 [2384]: DEBUG: Zimbra::Mapi::GetProfileParamProps #15 property tag: 0x6651000a

28-03-2008 16:09:03.142 [2384]: DEBUG: Zimbra::Mapi::GetProfileParamProps #16 property tag: 0x3d0c000a

28-03-2008 16:09:03.142 [2384]: DEBUG: Zimbra::Mapi::GetProfileParamProps #17 property tag: 0x3d12001f

28-03-2008 16:09:03.142 [2384]: DEBUG: Zimbra::Mapi::GetProfileParamProps #18 property tag: 0x66580003

28-03-2008 16:09:03.142 [2384]: Zimbra::Store::ZimbraMailUser::GetProps. preprocessing prop tags found PR_EMS_AB_HOME_MTA at index 0

28-03-2008 16:09:03.142 [2384]: Zimbra::Store::ZimbraMailUser::GetProps. the mail server is zimbra.xxxx.edu

28-03-2008 16:09:03.142 [2384]: Zimbra::Store::ZimbraMailUser::GetProps. MDB string is /o=zimbra.xxxx.edu/ou=First Administrative Group/cn=Configuration/cn=Servers/cn=zimbra.xxxx.edu/cn=Microsoft Private MDB

28-03-2008 16:09:03.142 [2384]: Zimbra::Store::ZimbraMailUser::GetProps. MTA string is /o=zimbra.xxxx.edu/ou=First Administrative Group/cn=Configuration/cn=Servers/cn=zimbra.xxxx.edu/cn=Microsoft MTA

28-03-2008 16:09:03.142 [2384]: Zimbra::Store::ZimbraMailUser::GetProps. populating PR_EMS_AB_HOME_MTA with ansii at index 0

28-03-2008 16:09:03.142 [2384]: Zimbra::Store::ZimbraMailUser::GetProps. dumping prop values returning for this user after mapping.

28-03-2008 16:09:03.142 [2384]: Named Property (8007) PT_STRING8 /o=zimbra.xxxx.edu/ou=First Administrative Group/cn=Configuration/cn=Servers/cn=zimbra.xxxx.edu/cn=Microsoft MTA

28-03-2008 16:09:03.142 [2384]: Zimbra::Store::ZimbraMailUser::GetProps. exitting early

28-03-2008 16:09:03.142 [2384]: Zimbra::Store::ZimbraMailUser::~ZimbraMailUser

28-03-2008 16:09:03.142 [2384]: dtor this(02865898) inner(014150C8) thiscount(0l) innercount(0l)

28-03-2008 16:09:07.118 [3456]: TRACING => ProgressTask::~ProgressTask this(0x27a6b60)

28-03-2008 16:09:58.591 [3504]: *5* ERROR: Zimbra::Rpc::Connection::SendRequest - failed on WinHttpReceiveResponse(...), Error Code: 12002

28-03-2008 16:09:58.961 [3504]: Zimbra::Store::CWaitRequest::WaitRequestThread. SendRequest failed with Error 0x2ee2. Desc The request has timed out.
    Reply With Quote Reply With Quote
  4. 03-28-2008, 02:46 PM #4
    alee
    alee is offline Former Zimbran
    Join Date
    Apr 2007
    Location
    San Jose
    Posts
    406
    Rep Power
    7

    Default

    Quote Originally Posted by Chewie71 View Post
    OK...I rebooted my BES server and watched the logs again.

    Right around 16:09:15 I got the "ServerDN not defined" error in the BES MAGT log.

    This is what is occurring in the ZCB logs at about that time.

    Code:
    28-03-2008 16:09:02.912 [3448]: Zimbra::Sync::Synchronizer::TrySync. ++++++++++++SYNC FINISHED++++++++++++

28-03-2008 16:09:02.912 [3448]: Zimbra::Sync::Synchronizer::TrySync. done syncing primary store (Zimbra - BES Admin)

28-03-2008 16:09:02.912 [3448]: Zimbra::Sync::Synchronizer::TrySync. +++++++++++++++++++++++++++++++++++++++++

28-03-2008 16:09:02.912 [3448]:

28-03-2008 16:09:02.912 [3448]: Zimbra::Store::StoreContext::InitiateSync. After sync on store

28-03-2008 16:09:02.912 [3448]: Zimbra::Store::StoreContextManager::ProcessSyncRequest. About to sync other stores

28-03-2008 16:09:03.82 [2384]: In Hook_AddrBookOpenEntry

28-03-2008 16:09:03.82 [2384]: ObjectManager::WrapObject

28-03-2008 16:09:03.82 [2384]: Object Type: (6) MAPI_MAILUSER

28-03-2008 16:09:03.82 [2384]: ctor this(02865898) inner(014150C8) thiscount(0l)

28-03-2008 16:09:03.82 [2384]: Zimbra::Store::ZimbraMailUser::ZimbraMailUser

28-03-2008 16:09:03.82 [2384]: Zimbra::Store::ZimbraMailUser::Initialize

28-03-2008 16:09:03.82 [2384]: Zimbra::Store::ZimbraMailUser::GetProps.

28-03-2008 16:09:03.82 [2384]: Zimbra::Store::ZimbraMailUser::GetProps. (00000000812b1fa4bea310199d6e00dd010f5402000000804200450053002000410064006d0069006e0000004c0053004d0053000000620065007300610064006d0069006e0040007700690075002e006500640075000000) ++++++++++++++++++++++++++++++++

28-03-2008 16:09:03.82 [2384]: Zimbra::Store::ZimbraMailUser::GetProps. (00000000812b1fa4bea310199d6e00dd010f5402000000804200450053002000410064006d0069006e0000004c0053004d0053000000620065007300610064006d0069006e0040007700690075002e006500640075000000) interface address: 14150c8

28-03-2008 16:09:03.82 [2384]: Zimbra::Store::ZimbraMailUser::GetProps. (00000000812b1fa4bea310199d6e00dd010f5402000000804200450053002000410064006d0069006e0000004c0053004d0053000000620065007300610064006d0069006e0040007700690075002e006500640075000000) PR_DISPLAY_NAME: BES Admin

28-03-2008 16:09:03.82 [2384]: Zimbra::Store::ZimbraMailUser::GetProps. (00000000812b1fa4bea310199d6e00dd010f5402000000804200450053002000410064006d0069006e0000004c0053004d0053000000620065007300610064006d0069006e0040007700690075002e006500640075000000) cannot dump the rest of the folder info due to lack of store pointer

28-03-2008 16:09:03.82 [2384]: Zimbra::Store::ZimbraMailUser::GetProps. (00000000812b1fa4bea310199d6e00dd010f5402000000804200450053002000410064006d0069006e0000004c0053004d0053000000620065007300610064006d0069006e0040007700690075002e006500640075000000) ++++++++++++++++++++++++++++++++

28-03-2008 16:09:03.82 [2384]: Zimbra::Store::ZimbraMailUser::GetProps. dumping prop tags requested for this user:

28-03-2008 16:09:03.82 [2384]: Tracing properties...

28-03-2008 16:09:03.142 [2384]: Id(Named Property (8007)) Type(PT_STRING8)

28-03-2008 16:09:03.142 [2384]: DEBUG: Zimbra::Rpc::UserSession::GetSupportObject - m_pMapiSup = 0x013d2bc8

28-03-2008 16:09:03.142 [2384]: DEBUG: Zimbra::Mapi::GetProfileParamProps - # of properties retrieved from profile: 19

28-03-2008 16:09:03.142 [2384]: DEBUG: Zimbra::Mapi::GetProfileParamProps #0 property tag: 0x6640001f

28-03-2008 16:09:03.142 [2384]: DEBUG: Zimbra::Mapi::GetProfileParamProps #1 property tag: 0x66430003

28-03-2008 16:09:03.142 [2384]: DEBUG: Zimbra::Mapi::GetProfileParamProps #2 property tag: 0x6641001f

28-03-2008 16:09:03.142 [2384]: DEBUG: Zimbra::Mapi::GetProfileParamProps #3 property tag: 0x66420102

28-03-2008 16:09:03.142 [2384]: DEBUG: Zimbra::Mapi::GetProfileParamProps #4 property tag: 0x6644000b

28-03-2008 16:09:03.142 [2384]: DEBUG: Zimbra::Mapi::GetProfileParamProps #5 property tag: 0x66550003

28-03-2008 16:09:03.142 [2384]: DEBUG: Zimbra::Mapi::GetProfileParamProps #5 property value:      0x1

28-03-2008 16:09:03.142 [2384]: DEBUG: Zimbra::Mapi::GetProfileParamProps #6 property tag: 0x6656000a

28-03-2008 16:09:03.142 [2384]: DEBUG: Zimbra::Mapi::GetProfileParamProps #7 property tag: 0x6657000a

28-03-2008 16:09:03.142 [2384]: DEBUG: Zimbra::Mapi::GetProfileParamProps #8 property tag: 0x6645001f

28-03-2008 16:09:03.142 [2384]: DEBUG: Zimbra::Mapi::GetProfileParamProps #9 property tag: 0x6646001f

28-03-2008 16:09:03.142 [2384]: DEBUG: Zimbra::Mapi::GetProfileParamProps #10 property tag: 0x66470102

28-03-2008 16:09:03.142 [2384]: DEBUG: Zimbra::Mapi::GetProfileParamProps #11 property tag: 0x6648000b

28-03-2008 16:09:03.142 [2384]: DEBUG: Zimbra::Mapi::GetProfileParamProps #12 property tag: 0x6649000a

28-03-2008 16:09:03.142 [2384]: DEBUG: Zimbra::Mapi::GetProfileParamProps #13 property tag: 0x6650000a

28-03-2008 16:09:03.142 [2384]: DEBUG: Zimbra::Mapi::GetProfileParamProps #14 property tag: 0x6652000a

28-03-2008 16:09:03.142 [2384]: DEBUG: Zimbra::Mapi::GetProfileParamProps #15 property tag: 0x6651000a

28-03-2008 16:09:03.142 [2384]: DEBUG: Zimbra::Mapi::GetProfileParamProps #16 property tag: 0x3d0c000a

28-03-2008 16:09:03.142 [2384]: DEBUG: Zimbra::Mapi::GetProfileParamProps #17 property tag: 0x3d12001f

28-03-2008 16:09:03.142 [2384]: DEBUG: Zimbra::Mapi::GetProfileParamProps #18 property tag: 0x66580003

28-03-2008 16:09:03.142 [2384]: Zimbra::Store::ZimbraMailUser::GetProps. preprocessing prop tags found PR_EMS_AB_HOME_MTA at index 0

28-03-2008 16:09:03.142 [2384]: Zimbra::Store::ZimbraMailUser::GetProps. the mail server is zimbra.xxxx.edu

28-03-2008 16:09:03.142 [2384]: Zimbra::Store::ZimbraMailUser::GetProps. MDB string is /o=zimbra.xxxx.edu/ou=First Administrative Group/cn=Configuration/cn=Servers/cn=zimbra.xxxx.edu/cn=Microsoft Private MDB

28-03-2008 16:09:03.142 [2384]: Zimbra::Store::ZimbraMailUser::GetProps. MTA string is /o=zimbra.xxxx.edu/ou=First Administrative Group/cn=Configuration/cn=Servers/cn=zimbra.xxxx.edu/cn=Microsoft MTA

28-03-2008 16:09:03.142 [2384]: Zimbra::Store::ZimbraMailUser::GetProps. populating PR_EMS_AB_HOME_MTA with ansii at index 0

28-03-2008 16:09:03.142 [2384]: Zimbra::Store::ZimbraMailUser::GetProps. dumping prop values returning for this user after mapping.

28-03-2008 16:09:03.142 [2384]: Named Property (8007) PT_STRING8 /o=zimbra.xxxx.edu/ou=First Administrative Group/cn=Configuration/cn=Servers/cn=zimbra.xxxx.edu/cn=Microsoft MTA

28-03-2008 16:09:03.142 [2384]: Zimbra::Store::ZimbraMailUser::GetProps. exitting early

28-03-2008 16:09:03.142 [2384]: Zimbra::Store::ZimbraMailUser::~ZimbraMailUser

28-03-2008 16:09:03.142 [2384]: dtor this(02865898) inner(014150C8) thiscount(0l) innercount(0l)

28-03-2008 16:09:07.118 [3456]: TRACING => ProgressTask::~ProgressTask this(0x27a6b60)

28-03-2008 16:09:58.591 [3504]: *5* ERROR: Zimbra::Rpc::Connection::SendRequest - failed on WinHttpReceiveResponse(...), Error Code: 12002

28-03-2008 16:09:58.961 [3504]: Zimbra::Store::CWaitRequest::WaitRequestThread. SendRequest failed with Error 0x2ee2. Desc The request has timed out.

    i'm assuming what is in zimbra.xxxx.edu is valid correct?
    seems like we are returning valid information that BES normally is happy with...
    at this point, i would take a look at the blackberry forums to see if their are known issues with BES and the server DN problem that you are seeing. this is the only call that returns server DN data and the response looks correct.
    have you tried to delete and reprovision the user to see if this helps the issue? are all users having this problem?
    Reply With Quote Reply With Quote
« Previous Thread | Next Thread »

Thread Information

Users Browsing this Thread

There are currently 1 users browsing this thread. (0 members and 1 guests)

Similar Threads

  1. Message Count Stats
    By multicast in forum Administrators
    Replies: 5
    Last Post: 02-22-2013, 06:31 PM
  2. zimbra daily reports problem - mta table is empty
    By sine in forum Administrators
    Replies: 4
    Last Post: 09-25-2008, 11:32 PM
  3. 5.0 RC1 statistics not updating
    By JamesA in forum Administrators
    Replies: 4
    Last Post: 11-19-2007, 11:45 PM
  4. [SOLVED] Zimbra 4.5.6 on Ubuntu 6.06 LTS - Logger problem
    By hogne in forum Installation
    Replies: 3
    Last Post: 09-05-2007, 06:25 AM
  5. Backup Issue - Redo Logs
    By torusgrp in forum Administrators
    Replies: 0
    Last Post: 06-17-2007, 09:44 AM

Posting Permissions

  • You may not post new threads
  • You may not post replies
  • You may not post attachments
  • You may not edit your posts
  •  

Forum Rules