ahh.. errors about not being able to get eid of the user, and then some 500 internal server errors:
Code:
08-10-2009 16:06:07.257 [5804]: In Zimbra::Store::CZimbraExchangeManageStore::CreateStoreEntryID. Msg Store DN /o=pilot.testemail.org/ou=First Administrative Group/cn=Configuration/cn=Servers/cn=pilot.testemail.org/cn=Microsoft Private MDB, Mailbox DN markd@pilot.testemail.org, Flags 0x9
08-10-2009 16:06:07.257 [5804]: Zimbra::Store::CZimbraExchangeManageStore::CreateStoreEntryID. trying to get the sync lock...
08-10-2009 16:06:07.257 [5804]: Zimbra::Util::GlobalSyncLock::GetSyncLock. got the sync lock, we can sync
08-10-2009 16:06:07.257 [5804]: Zimbra::Store::CZimbraExchangeManageStore::CreateStoreEntryID. got the sync lock...
08-10-2009 16:06:07.257 [5804]: Zimbra::Store::CZimbraExchangeManageStore::CreateStoreEntryID. Waiting for GAL sync to complete
08-10-2009 16:06:07.257 [5804]: Zimbra::Store::CZimbraExchangeManageStore::CreateStoreEntryID. Dispatching message 0xc11a. HWND 0x80226
08-10-2009 16:06:07.257 [5804]: Zimbra::Store::CZimbraExchangeManageStore::CreateStoreEntryID. Dispatching message 0xc11a. HWND 0x80226
08-10-2009 16:06:07.272 [5804]: Zimbra::Store::CZimbraExchangeManageStore::CreateStoreEntryID. Dispatching message 0xc11a. HWND 0x80226
08-10-2009 16:06:07.272 [5804]: Zimbra::Store::CZimbraExchangeManageStore::CreateStoreEntryID. Dispatching message 0xc11a. HWND 0x80226
08-10-2009 16:06:07.272 [5804]: Zimbra::Store::CZimbraExchangeManageStore::CreateStoreEntryID. GAL sync is complete
08-10-2009 16:06:07.272 [5804]: Zimbra::Store::CZimbraExchangeManageStore::CreateStoreEntryID. trying to get eid for user: fisher@dwsd.org
08-10-2009 16:06:07.272 [5804]: In Zimbra::Store::StoreContextManager::GetMailboxEidForUser
08-10-2009 16:06:07.272 [5804]: Zimbra::Store::StoreContextManager::GetMailboxEidForUser. could not find the entry id in the map, looking in the profile for: markd@pilot.testemail.org
08-10-2009 16:06:07.272 [5804]: DEBUG: Zimbra::Rpc::UserSession::GetSupportObject - m_pMapiSup = 0x00f22c28
08-10-2009 16:06:07.272 [5804]: Zimbra::Mapi::GetProfileParamProps. trying to get PR_SERVICE_UID, PR_ZIMBRA_USER_ID from the profile section (1)
08-10-2009 16:06:07.272 [5804]: DEBUG: Zimbra::Mapi::GetProfileParamProps - # of properties retrieved from profile: 19
08-10-2009 16:06:07.272 [5804]: DEBUG: Zimbra::Mapi::GetProfileParamProps #0 property tag: 0x6640001f
08-10-2009 16:06:07.272 [5804]: DEBUG: Zimbra::Mapi::GetProfileParamProps #1 property tag: 0x66430003
08-10-2009 16:06:07.272 [5804]: DEBUG: Zimbra::Mapi::GetProfileParamProps #2 property tag: 0x6641001f
08-10-2009 16:06:07.272 [5804]: DEBUG: Zimbra::Mapi::GetProfileParamProps #3 property tag: 0x66420102
08-10-2009 16:06:07.272 [5804]: DEBUG: Zimbra::Mapi::GetProfileParamProps #4 property tag: 0x6644000b
08-10-2009 16:06:07.272 [5804]: DEBUG: Zimbra::Mapi::GetProfileParamProps #5 property tag: 0x66550003
08-10-2009 16:06:07.272 [5804]: DEBUG: Zimbra::Mapi::GetProfileParamProps #5 property value: 0x1
08-10-2009 16:06:07.272 [5804]: DEBUG: Zimbra::Mapi::GetProfileParamProps #6 property tag: 0x6656000a
08-10-2009 16:06:07.272 [5804]: DEBUG: Zimbra::Mapi::GetProfileParamProps #7 property tag: 0x6657000a
08-10-2009 16:06:07.272 [5804]: DEBUG: Zimbra::Mapi::GetProfileParamProps #8 property tag: 0x6645001f
08-10-2009 16:06:07.272 [5804]: DEBUG: Zimbra::Mapi::GetProfileParamProps #9 property tag: 0x6646001f
08-10-2009 16:06:07.272 [5804]: DEBUG: Zimbra::Mapi::GetProfileParamProps #10 property tag: 0x66470102
08-10-2009 16:06:07.272 [5804]: DEBUG: Zimbra::Mapi::GetProfileParamProps #11 property tag: 0x6648000b
08-10-2009 16:06:07.272 [5804]: DEBUG: Zimbra::Mapi::GetProfileParamProps #12 property tag: 0x6649000a
08-10-2009 16:06:07.272 [5804]: DEBUG: Zimbra::Mapi::GetProfileParamProps #13 property tag: 0x6650000a
08-10-2009 16:06:07.272 [5804]: DEBUG: Zimbra::Mapi::GetProfileParamProps #14 property tag: 0x6652000a
08-10-2009 16:06:07.272 [5804]: DEBUG: Zimbra::Mapi::GetProfileParamProps #15 property tag: 0x6651000a
08-10-2009 16:06:07.272 [5804]: DEBUG: Zimbra::Mapi::GetProfileParamProps #16 property tag: 0x3d0c000a
08-10-2009 16:06:07.272 [5804]: DEBUG: Zimbra::Mapi::GetProfileParamProps #17 property tag: 0x3d12001f
08-10-2009 16:06:07.272 [5804]: DEBUG: Zimbra::Mapi::GetProfileParamProps #18 property tag: 0x66580003
08-10-2009 16:06:07.272 [5804]: Zimbra::Store::StoreContextManager::GetMailboxEidForUserFromProfile. services table has 0 entries
08-10-2009 16:06:07.272 [5804]: Zimbra::Store::StoreContextManager::GetMailboxEidForUserFromProfile. no entries in the service table after the restriction
08-10-2009 16:06:07.272 [5804]: Zimbra::Store::StoreContextManager::GetMailboxEidForUser. +++++++++++++++++++++++++++++++++++++
08-10-2009 16:06:07.272 [5804]: Zimbra::Store::StoreContextManager::GetMailboxEidForUser. could not find the mailbox EID for markd@pilot.testemail.org...even in the profile!!!
08-10-2009 16:06:07.272 [5804]: Zimbra::Store::StoreContextManager::GetMailboxEidForUser. +++++++++++++++++++++++++++++++++++++
08-10-2009 16:06:07.272 [5804]: Zimbra::Store::CZimbraExchangeManageStore::CreateStoreEntryID. couldn't find the EID for the user [markd@pilot.testemail.org] in an existing store...
08-10-2009 16:06:07.272 [5804]: DEBUG: Zimbra::Rpc::UserSession::GetSupportObject - m_pMapiSup = 0x00f22c28
08-10-2009 16:06:07.616 [5804]: Zimbra::Store::CZimbraExchangeManageStore::CreateStoreEntryID. found the user in the GAL.
08-10-2009 16:06:07.616 [5804]: TRACING => Zimbra::Rpc::UserSession::GetInstance
08-10-2009 16:06:07.616 [5804]: DEBUG: Zimbra::Rpc::UserSession::GetInstance - get a user session (0x2437808)
08-10-2009 16:06:07.616 [5804]: Zimbra::Store::CZimbraExchangeManageStore::CreateStoreEntryID. getting the user's zimbra ID from the session.
08-10-2009 16:06:07.616 [5804]: *9* Enter Zimbra::Rpc::Connection::SendRequest ...
08-10-2009 16:06:07.616 [5804]: DEBUG: Zimbra::Rpc::Connection::Connect - thread 5804
08-10-2009 16:06:07.616 [5804]: *9* <<<<-------- HTTP stream Start (Request) ----------------------------------------------->>>>
08-10-2009 16:06:07.678 [5804]: *9* HTTP Headers:
08-10-2009 16:06:07.678 [5804]: POST /service/admin/soap/ HTTP/1.1
Content-Type: application/soap+xml; charset=utf-8
User-Agent: Zimbra-ZCB/5.0.2711.18 (5.2.3790 Service Pack 2; en-US)
Host: pilot.testemail.org:7071
Content-Length: 631
Connection: Keep-Alive
Cache-Control: no-cache
Pragma: no-cache
08-10-2009 16:06:07.678 [5804]: *9* HTTP body:
<soap:Envelope xmlns:soap="http://www.w3.org/2003/05/soap-envelope"><soap:Header><context xmlns="urn:zimbra"><userAgent name="ZimbraConnectorForBES" version="5.0.2711.18"/><nonotify/><noqualify/><authToken>0_0d379862ffa6a883255625b08b3f956e83d2ad95_69643d33363a66343661366435352d626132652d343538392d393766352d3432356563333539666266613b6578703d31333a313235353037353933313331393b61646d696e3d313a313b747970653d363a7a696d6272613b</authToken><nosession/></context></soap:Header><soap:Body><GetAccountInfoRequest xmlns="urn:zimbraAccount"><account by="name">markd@pilot.testemail.org</account></GetAccountInfoRequest></soap:Body></soap:Envelope>
08-10-2009 16:06:07.678 [5804]: *9* <<<<-------- HTTP stream End (Request) ----------------------------------------------->>>>
08-10-2009 16:06:07.678 [5804]: *9* <<<<-------- HTTP stream Start (Response) ----------------------------------------------->>>>
08-10-2009 16:06:07.678 [5804]: *9* DEBUG: in Zimbra::Rpc::Connection::ReadResponseFully - content-length in http response header: 518
08-10-2009 16:06:07.678 [5804]: *9* HTTP Headers:
08-10-2009 16:06:07.678 [5804]: HTTP/1.1 500 Internal Server Error
The 500 internal server errors continue in the log.
Not sure what might be causing those. Like I said, what's really strange is when attempting to do the IEMSTest on the Besadmin account itself, it works just fine. Just doesn't work when trying to do another user.