Oleg,
I just pasted log below. I hope you get it this way. If not I will think of
something else
Thanks a lot
Alex
2013/08/05 11:44:08:714 PDT [DEBUG] MainClientExec - [exchange: 1] start
execution
2013/08/05 11:44:08:722 PDT [DEBUG] RequestAddCookies - CookieSpec
selected: best-match
2013/08/05 11:44:08:733 PDT [DEBUG] RequestAuthCache - Auth cache not set
in the context
2013/08/05 11:44:08:734 PDT [DEBUG] InternalHttpAsyncClient - [exchange: 1]
Request connection for {s}->https://mc-ex4-1-sfm.s2x.uncrap.local:443
2013/08/05 11:44:08:735 PDT [DEBUG] PoolingNHttpClientConnectionManager -
Connection request: [route:
{s}->https://mc-ex4-1-sfm.s2x.uncrap.local:443][total
kept alive: 0; route allocated: 0 of 2; total allocated: 0 of 20]
2013/08/05 11:44:08:760 PDT [DEBUG] PoolingNHttpClientConnectionManager -
Connection leased: [id: http-outgoing-0][route:
{s}->https://mc-ex4-1-sfm.s2x.uncrap.local:443][total kept alive: 0; route
allocated: 1 of 2; total allocated: 1 of 20]
2013/08/05 11:44:08:766 PDT [DEBUG] InternalHttpAsyncClient - [exchange: 1]
Connection allocated: Managed connection
2013/08/05 11:44:08:768 PDT [DEBUG] ManagedNHttpClientConnectionImpl -
http-outgoing-0 10.0.115.37:47996<->10.224.203.221:443[ACTIVE][r:]: Set
attribute http.nio.exchange-handler
2013/08/05 11:44:08:768 PDT [DEBUG] ManagedNHttpClientConnectionImpl -
http-outgoing-0 10.0.115.37:47996<->10.224.203.221:443[ACTIVE][rw:]: Event
set [w]
2013/08/05 11:44:08:768 PDT [DEBUG] HttpAsyncRequestExecutor -
http-outgoing-0 [ACTIVE]: Connected
2013/08/05 11:44:08:769 PDT [DEBUG] ManagedNHttpClientConnectionImpl -
http-outgoing-0 10.0.115.37:47996<->10.224.203.221:443[ACTIVE][rw:]: Set
attribute http.nio.http-exchange-state
2013/08/05 11:44:08:769 PDT [DEBUG] HttpAsyncRequestExecutor -
http-outgoing-0 [ACTIVE] Request ready
2013/08/05 11:44:08:887 PDT [DEBUG] ManagedNHttpClientConnectionImpl -
http-outgoing-0 Upgrade session 10.0.115.37:47996<->10.224.203.221:443
[ACTIVE][rw:][ACTIVE][rw][NOT_HANDSHAKING][0][0][0][0]
2013/08/05 11:44:08:887 PDT [DEBUG] MainClientExec - [exchange: 1] Attempt
1 to execute request
2013/08/05 11:44:08:887 PDT [DEBUG] MainClientExec - Target auth state:
UNCHALLENGED
2013/08/05 11:44:08:888 PDT [DEBUG] MainClientExec - Proxy auth state:
UNCHALLENGED
2013/08/05 11:44:08:888 PDT [DEBUG] headers - http-outgoing-0 >> POST
/EWS/Exchange.asmx HTTP/1.1
2013/08/05 11:44:08:888 PDT [DEBUG] headers - http-outgoing-0 >>
Content-type: text/xml; charset=utf-8
2013/08/05 11:44:08:888 PDT [DEBUG] headers - http-outgoing-0 >>
Keep-Alive: 300
2013/08/05 11:44:08:888 PDT [DEBUG] headers - http-outgoing-0 >>
User-Agent: ExchangeServicesClient/0.0.0.0
2013/08/05 11:44:08:888 PDT [DEBUG] headers - http-outgoing-0 >> Accept:
text/xml
2013/08/05 11:44:08:888 PDT [DEBUG] headers - http-outgoing-0 >>
Transfer-Encoding: chunked
2013/08/05 11:44:08:888 PDT [DEBUG] headers - http-outgoing-0 >> Host:
mc-ex4-1-sfm.s2x.uncrap.local
2013/08/05 11:44:08:889 PDT [DEBUG] headers - http-outgoing-0 >>
Connection: Keep-Alive
2013/08/05 11:44:08:891 PDT [DEBUG] ManagedNHttpClientConnectionImpl -
http-outgoing-0
10.0.115.37:47996<->10.224.203.221:443[ACTIVE][rw:][ACTIVE][rw][NOT_HANDSHAKING][0][0][0][0]:
Event set [w]
2013/08/05 11:44:08:924 PDT [DEBUG] HttpAsyncRequestExecutor -
http-outgoing-0 [ACTIVE] Output ready
2013/08/05 11:44:08:925 PDT [DEBUG] MainClientExec - [exchange: 1] produce
content
2013/08/05 11:44:08:925 PDT [DEBUG] HttpAsyncRequestExecutor -
http-outgoing-0 [ACTIVE] [chunk-coded; completed: false]
2013/08/05 11:44:08:927 PDT [DEBUG] ManagedNHttpClientConnectionImpl -
http-outgoing-0
10.0.115.37:47996<->10.224.203.221:443[ACTIVE][rw:w][ACTIVE][rw][NOT_HANDSHAKING][0][0][4373][0]:
4347 bytes written
2013/08/05 11:44:08:927 PDT [DEBUG] wire - http-outgoing-0 >> "POST
/EWS/Exchange.asmx HTTP/1.1[\r][\n]"
2013/08/05 11:44:08:927 PDT [DEBUG] wire - http-outgoing-0 >>
"Content-type: text/xml; charset=utf-8[\r][\n]"
2013/08/05 11:44:08:927 PDT [DEBUG] wire - http-outgoing-0 >> "Keep-Alive:
300[\r][\n]"
2013/08/05 11:44:08:927 PDT [DEBUG] wire - http-outgoing-0 >> "User-Agent:
ExchangeServicesClient/0.0.0.0[\r][\n]"
2013/08/05 11:44:08:927 PDT [DEBUG] wire - http-outgoing-0 >> "Accept:
text/xml[\r][\n]"
2013/08/05 11:44:08:927 PDT [DEBUG] wire - http-outgoing-0 >>
"Transfer-Encoding: chunked[\r][\n]"
2013/08/05 11:44:08:927 PDT [DEBUG] wire - http-outgoing-0 >> "Host:
mc-ex4-1-sfm.s2x.uncrap.local[\r][\n]"
2013/08/05 11:44:08:927 PDT [DEBUG] wire - http-outgoing-0 >> "Connection:
Keep-Alive[\r][\n]"
2013/08/05 11:44:08:927 PDT [DEBUG] wire - http-outgoing-0 >> "[\r][\n]"
2013/08/05 11:44:08:927 PDT [DEBUG] wire - http-outgoing-0 >> "1000[\r][\n]"
2013/08/05 11:44:08:928 PDT [DEBUG] wire - http-outgoing-0 >> "<?xml
version="1.0" encoding="utf-8"?><soap:Envelope xmlns:soap="
http://schemas.xmlsoap.org/soap/envelope/"; xmlns:xsi="
http://www.w3.org/2001/XMLSchema-instance"; xmlns:m="
http://schemas.microsoft.com/exchange/services/2006/messages"; xmlns:t="
http://schemas.microsoft.com/exchange/services/2006/types";><soap:Header><t:RequestServerVersion
Version="Exchange2010_SP1"></t:RequestServerVersion></soap:Header><soap:Body><m:CreateItem
MessageDisposition="SaveOnly"
SendMeetingInvitations="SendToNone"><m:SavedItemFolderId><t:DistinguishedFolderId
Id="contacts"><t:Mailbox><t:EmailAddress>[email protected]</t:EmailAddress></t:Mailbox></t:DistinguishedFolderId></m:SavedItemFolderId><m:Items><t:Contact><t:GivenName>Alex</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>Oscherov</t:Surname></t:Contact><t:Contact><t:GivenName>Alexander</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>Osherov</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName0</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName1</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName2</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName3</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName4</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName5</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName6</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName7</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName8</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName9</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName10</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName11</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName12</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName13</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName14</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName15La[\r][\n]"
2013/08/05 11:44:08:928 PDT [DEBUG] HttpAsyncRequestExecutor -
http-outgoing-0 [ACTIVE] Output ready
2013/08/05 11:44:08:928 PDT [DEBUG] MainClientExec - [exchange: 1] produce
content
2013/08/05 11:44:08:928 PDT [DEBUG] HttpAsyncRequestExecutor -
http-outgoing-0 [ACTIVE] [chunk-coded; completed: false]
2013/08/05 11:44:08:930 PDT [DEBUG] ManagedNHttpClientConnectionImpl -
http-outgoing-0
10.0.115.37:47996<->10.224.203.221:443[ACTIVE][rw:w][ACTIVE][rw][NOT_HANDSHAKING][0][0][4170][0]:
4104 bytes written
2013/08/05 11:44:08:930 PDT [DEBUG] wire - http-outgoing-0 >> "1000[\r][\n]"
2013/08/05 11:44:08:930 PDT [DEBUG] wire - http-outgoing-0 >> "
[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName15</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName16</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName17</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName18</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName19</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName20</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName21</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName22</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName23</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName24</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName25</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName26</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName27</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName28</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName29</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName30</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName31</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName32</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName33</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName34</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]
</t:Entry></t:EmailAddresses><t:Surname>LastName35</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenNam[\r][\n]"
2013/08/05 11:44:08:931 PDT [DEBUG] HttpAsyncRequestExecutor -
http-outgoing-0 [ACTIVE] Output ready
2013/08/05 11:44:08:931 PDT [DEBUG] MainClientExec - [exchange: 1] produce
content
2013/08/05 11:44:08:931 PDT [DEBUG] HttpAsyncRequestExecutor -
http-outgoing-0 [ACTIVE] [chunk-coded; completed: false]
2013/08/05 11:44:08:932 PDT [DEBUG] ManagedNHttpClientConnectionImpl -
http-outgoing-0
10.0.115.37:47996<->10.224.203.221:443[ACTIVE][rw:w][ACTIVE][rw][NOT_HANDSHAKING][0][0][4170][0]:
4104 bytes written
2013/08/05 11:44:08:933 PDT [DEBUG] wire - http-outgoing-0 >> "1000[\r][\n]"
2013/08/05 11:44:08:933 PDT [DEBUG] wire - http-outgoing-0 >>
"e><t:EmailAddresses><t:Entry Key="EmailAddress1">
[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName36</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName37</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName38</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName39</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName40</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName41</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName42</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName43</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName44</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName45</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName46</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName47</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName48</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName49</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName50</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName51</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName52</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName53</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName54</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName55</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]
</t:Entry></t:EmailAddresses><t:Surname>LastName56</t:Sur[\r][\n]"
2013/08/05 11:44:08:933 PDT [DEBUG] HttpAsyncRequestExecutor -
http-outgoing-0 [ACTIVE] Output ready
2013/08/05 11:44:08:933 PDT [DEBUG] MainClientExec - [exchange: 1] produce
content
2013/08/05 11:44:08:933 PDT [DEBUG] HttpAsyncRequestExecutor -
http-outgoing-0 [ACTIVE] [chunk-coded; completed: false]
2013/08/05 11:44:08:935 PDT [DEBUG] ManagedNHttpClientConnectionImpl -
http-outgoing-0
10.0.115.37:47996<->10.224.203.221:443[ACTIVE][rw:w][ACTIVE][rw][NOT_HANDSHAKING][0][0][4170][0]:
4104 bytes written
2013/08/05 11:44:08:935 PDT [DEBUG] wire - http-outgoing-0 >> "1000[\r][\n]"
2013/08/05 11:44:08:935 PDT [DEBUG] wire - http-outgoing-0 >>
"name></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName57</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName58</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName59</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName60</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName61</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName62</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName63</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName64</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName65</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName66</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName67</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName68</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName69</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName70</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName71</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName72</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName73</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName74</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName75</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName76</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName77LastName77@tes[\r][\n]"
2013/08/05 11:44:08:936 PDT [DEBUG] ManagedNHttpClientConnectionImpl -
http-outgoing-0
10.0.115.37:47996<->10.224.203.221:443[ACTIVE][rw:rw][ACTIVE][rw][NOT_HANDSHAKING][0][0][0][0]:
369 bytes read
2013/08/05 11:44:08:936 PDT [DEBUG] wire - http-outgoing-0 << "HTTP/1.1 401
Unauthorized[\r][\n]"
2013/08/05 11:44:08:936 PDT [DEBUG] wire - http-outgoing-0 << "Server:
Microsoft-IIS/7.5[\r][\n]"
2013/08/05 11:44:08:937 PDT [DEBUG] wire - http-outgoing-0 << "Set-Cookie:
exchangecookie=e4816087401747ed9e2080d41eae52b3; expires=Tue, 05-Aug-2014
18:44:08 GMT; path=/; HttpOnly[\r][\n]"
2013/08/05 11:44:08:937 PDT [DEBUG] wire - http-outgoing-0 <<
"WWW-Authenticate: Negotiate[\r][\n]"
2013/08/05 11:44:08:937 PDT [DEBUG] wire - http-outgoing-0 <<
"WWW-Authenticate: NTLM[\r][\n]"
2013/08/05 11:44:08:937 PDT [DEBUG] wire - http-outgoing-0 <<
"WWW-Authenticate: Basic realm="mc-ex4-1-sfm.s2x.uncrap.local"[\r][\n]"
2013/08/05 11:44:08:937 PDT [DEBUG] wire - http-outgoing-0 <<
"X-Powered-By: ASP.NET[\r][\n]"
2013/08/05 11:44:08:937 PDT [DEBUG] wire - http-outgoing-0 << "Date: Mon,
05 Aug 2013 18:44:08 GMT[\r][\n]"
2013/08/05 11:44:08:937 PDT [DEBUG] wire - http-outgoing-0 <<
"Content-Length: 0[\r][\n]"
2013/08/05 11:44:08:937 PDT [DEBUG] wire - http-outgoing-0 << "[\r][\n]"
2013/08/05 11:44:08:943 PDT [DEBUG] headers - http-outgoing-0 << HTTP/1.1
401 Unauthorized
2013/08/05 11:44:08:943 PDT [DEBUG] headers - http-outgoing-0 << Server:
Microsoft-IIS/7.5
2013/08/05 11:44:08:943 PDT [DEBUG] headers - http-outgoing-0 <<
Set-Cookie: exchangecookie=e4816087401747ed9e2080d41eae52b3; expires=Tue,
05-Aug-2014 18:44:08 GMT; path=/; HttpOnly
2013/08/05 11:44:08:943 PDT [DEBUG] headers - http-outgoing-0 <<
WWW-Authenticate: Negotiate
2013/08/05 11:44:08:943 PDT [DEBUG] headers - http-outgoing-0 <<
WWW-Authenticate: NTLM
2013/08/05 11:44:08:943 PDT [DEBUG] headers - http-outgoing-0 <<
WWW-Authenticate: Basic realm="mc-ex4-1-sfm.s2x.uncrap.local"
2013/08/05 11:44:08:943 PDT [DEBUG] headers - http-outgoing-0 <<
X-Powered-By: ASP.NET
2013/08/05 11:44:08:943 PDT [DEBUG] headers - http-outgoing-0 << Date: Mon,
05 Aug 2013 18:44:08 GMT
2013/08/05 11:44:08:944 PDT [DEBUG] headers - http-outgoing-0 <<
Content-Length: 0
2013/08/05 11:44:08:944 PDT [DEBUG] HttpAsyncRequestExecutor -
http-outgoing-0 [ACTIVE] Response received
2013/08/05 11:44:08:944 PDT [DEBUG] ManagedNHttpClientConnectionImpl -
http-outgoing-0
10.0.115.37:47996<->10.224.203.221:443[ACTIVE][r:rw][ACTIVE][r][NOT_HANDSHAKING][0][0][0][0]:
Event cleared [w]
2013/08/05 11:44:08:944 PDT [DEBUG] MainClientExec - [exchange: 1] Response
received HTTP/1.1 401 Unauthorized
2013/08/05 11:44:08:951 PDT [DEBUG] ResponseProcessCookies - Cookie
accepted: "[version: 0][name: exchangecookie][value:
e4816087401747ed9e2080d41eae52b3][domain:
mc-ex4-1-sfm.s2x.uncrap.local][path: /][expiry: Tue Aug 05 11:44:08 PDT
2014]".
2013/08/05 11:44:08:951 PDT [DEBUG] MainClientExec - Authentication required
2013/08/05 11:44:08:952 PDT [DEBUG] MainClientExec -
mc-ex4-1-sfm.s2x.uncrap.local:443 requested authentication
2013/08/05 11:44:08:952 PDT [DEBUG] TargetAuthenticationStrategy -
Authentication schemes in the order of preference: [negotiate, Kerberos,
NTLM, Digest, Basic]
2013/08/05 11:44:08:952 PDT [WARN] TargetAuthenticationStrategy -
Authentication scheme negotiate not supported
2013/08/05 11:44:08:952 PDT [DEBUG] TargetAuthenticationStrategy -
Challenge for Kerberos authentication scheme not available
2013/08/05 11:44:08:952 PDT [WARN] TargetAuthenticationStrategy -
Authentication scheme NTLM not supported
2013/08/05 11:44:08:952 PDT [DEBUG] TargetAuthenticationStrategy -
Challenge for Digest authentication scheme not available
2013/08/05 11:44:08:960 PDT [DEBUG] MainClientExec - Selected
authentication options: [BASIC]
2013/08/05 11:44:08:960 PDT [DEBUG] HttpAsyncRequestExecutor -
http-outgoing-0 [ACTIVE] Input ready
2013/08/05 11:44:08:960 PDT [DEBUG] MainClientExec - [exchange: 1] Consume
content
2013/08/05 11:44:08:960 PDT [DEBUG] ManagedNHttpClientConnectionImpl -
http-outgoing-0
10.0.115.37:47996<->10.224.203.221:443[ACTIVE][r:rw][ACTIVE][r][NOT_HANDSHAKING][0][0][0][0]:
0 bytes read
2013/08/05 11:44:08:960 PDT [DEBUG] ManagedNHttpClientConnectionImpl -
http-outgoing-0
10.0.115.37:47996<->10.224.203.221:443[ACTIVE][r:rw][ACTIVE][r][NOT_HANDSHAKING][0][0][0][0]:
Close
2013/08/05 11:44:08:961 PDT [DEBUG] MainClientExec - [exchange: 1]
Connection can be kept alive indefinitely
2013/08/05 11:44:08:961 PDT [DEBUG] InternalHttpAsyncClient - [exchange: 1]
releasing connection
2013/08/05 11:44:08:961 PDT [DEBUG] ManagedNHttpClientConnectionImpl -
http-outgoing-0
10.0.115.37:47996<->10.224.203.221:443[ACTIVE][rw:rw][CLOSING][r][NEED_WRAP][0][0][0][0]:
Remove attribute http.nio.exchange-handler
2013/08/05 11:44:08:961 PDT [DEBUG] PoolingNHttpClientConnectionManager -
Connection released: [id: http-outgoing-0][route:
{s}->https://mc-ex4-1-sfm.s2x.uncrap.local:443][total kept alive: 0; route
allocated: 0 of 2; total allocated: 0 of 20]
2013/08/05 11:44:08:961 PDT [DEBUG] InternalHttpAsyncClient - [exchange: 1]
Request connection for {s}->https://mc-ex4-1-sfm.s2x.uncrap.local:443
2013/08/05 11:44:08:961 PDT [DEBUG] PoolingNHttpClientConnectionManager -
Connection request: [route:
{s}->https://mc-ex4-1-sfm.s2x.uncrap.local:443][total
kept alive: 0; route allocated: 0 of 2; total allocated: 0 of 20]
2013/08/05 11:44:08:961 PDT [DEBUG] ManagedNHttpClientConnectionImpl -
http-outgoing-0
10.0.115.37:47996<->10.224.203.221:443[ACTIVE][rw:rw][CLOSING][rw][NEED_WRAP][0][0][0][0]:
Event set [w]
2013/08/05 11:44:08:962 PDT [DEBUG] HttpAsyncRequestExecutor -
http-outgoing-0 [CLOSED] [content length: 0; pos: 0; completed: true]
2013/08/05 11:44:08:963 PDT [DEBUG] PoolingNHttpClientConnectionManager -
Connection leased: [id: http-outgoing-1][route:
{s}->https://mc-ex4-1-sfm.s2x.uncrap.local:443][total kept alive: 0; route
allocated: 1 of 2; total allocated: 1 of 20]
2013/08/05 11:44:08:963 PDT [DEBUG] InternalHttpAsyncClient - [exchange: 1]
Connection allocated: Managed connection
2013/08/05 11:44:08:963 PDT [DEBUG] ManagedNHttpClientConnectionImpl -
http-outgoing-1 10.0.115.37:47997<->10.224.203.221:443[ACTIVE][r:]: Set
attribute http.nio.exchange-handler
2013/08/05 11:44:08:963 PDT [DEBUG] ManagedNHttpClientConnectionImpl -
http-outgoing-1 10.0.115.37:47997<->10.224.203.221:443[ACTIVE][rw:]: Event
set [w]
2013/08/05 11:44:08:963 PDT [DEBUG] HttpAsyncRequestExecutor -
http-outgoing-1 [ACTIVE]: Connected
2013/08/05 11:44:08:963 PDT [DEBUG] ManagedNHttpClientConnectionImpl -
http-outgoing-1 10.0.115.37:47997<->10.224.203.221:443[ACTIVE][rw:]: Set
attribute http.nio.http-exchange-state
2013/08/05 11:44:08:963 PDT [DEBUG] HttpAsyncRequestExecutor -
http-outgoing-1 [ACTIVE] Request ready
2013/08/05 11:44:08:964 PDT [DEBUG] ManagedNHttpClientConnectionImpl -
http-outgoing-1 Upgrade session 10.0.115.37:47997<->10.224.203.221:443
[ACTIVE][rw:][ACTIVE][rw][NOT_HANDSHAKING][0][0][0][0]
2013/08/05 11:44:08:964 PDT [DEBUG] MainClientExec - [exchange: 1] Attempt
2 to execute request
2013/08/05 11:44:08:964 PDT [DEBUG] MainClientExec - Target auth state:
CHALLENGED
2013/08/05 11:44:08:964 PDT [DEBUG] MainClientExec - Generating response to
an authentication challenge using basic scheme
2013/08/05 11:44:08:965 PDT [DEBUG] MainClientExec - Proxy auth state:
UNCHALLENGED
2013/08/05 11:44:08:965 PDT [DEBUG] headers - http-outgoing-1 >> POST
/EWS/Exchange.asmx HTTP/1.1
2013/08/05 11:44:08:965 PDT [DEBUG] headers - http-outgoing-1 >>
Content-type: text/xml; charset=utf-8
2013/08/05 11:44:08:965 PDT [DEBUG] headers - http-outgoing-1 >>
Keep-Alive: 300
2013/08/05 11:44:08:965 PDT [DEBUG] headers - http-outgoing-1 >>
User-Agent: ExchangeServicesClient/0.0.0.0
2013/08/05 11:44:08:965 PDT [DEBUG] headers - http-outgoing-1 >> Accept:
text/xml
2013/08/05 11:44:08:966 PDT [DEBUG] headers - http-outgoing-1 >>
Transfer-Encoding: chunked
2013/08/05 11:44:08:966 PDT [DEBUG] headers - http-outgoing-1 >> Host:
mc-ex4-1-sfm.s2x.uncrap.local
2013/08/05 11:44:08:966 PDT [DEBUG] headers - http-outgoing-1 >>
Connection: Keep-Alive
2013/08/05 11:44:08:966 PDT [DEBUG] headers - http-outgoing-1 >>
Authorization: Basic UzJYLlVOQ1JBUC5MT0NBTC9zMnhhZG1pbjoxMjN0ZXN0IQ==
2013/08/05 11:44:08:966 PDT [DEBUG] ManagedNHttpClientConnectionImpl -
http-outgoing-1
10.0.115.37:47997<->10.224.203.221:443[ACTIVE][rw:][ACTIVE][rw][NOT_HANDSHAKING][0][0][0][0]:
Event set [w]
2013/08/05 11:44:08:972 PDT [DEBUG] HttpAsyncRequestExecutor -
http-outgoing-1 [ACTIVE] Output ready
2013/08/05 11:44:08:972 PDT [DEBUG] MainClientExec - [exchange: 1] produce
content
2013/08/05 11:44:08:972 PDT [DEBUG] HttpAsyncRequestExecutor -
http-outgoing-1 [ACTIVE] [chunk-coded; completed: false]
2013/08/05 11:44:08:972 PDT [DEBUG] ManagedNHttpClientConnectionImpl -
http-outgoing-1
10.0.115.37:47997<->10.224.203.221:443[ACTIVE][rw:w][ACTIVE][rw][NOT_HANDSHAKING][0][0][53][0]:
0 bytes written
2013/08/05 11:44:08:972 PDT [DEBUG] HttpAsyncRequestExecutor -
http-outgoing-1 [ACTIVE] Output ready
2013/08/05 11:44:08:972 PDT [DEBUG] MainClientExec - [exchange: 1] produce
content
2013/08/05 11:44:08:972 PDT [DEBUG] HttpAsyncRequestExecutor -
http-outgoing-1 [ACTIVE] [chunk-coded; completed: false]
2013/08/05 11:44:08:973 PDT [DEBUG] ManagedNHttpClientConnectionImpl -
http-outgoing-1
10.0.115.37:47997<->10.224.203.221:443[ACTIVE][rw:w][ACTIVE][rw][NOT_HANDSHAKING][0][0][4853][0]:
4817 bytes written
2013/08/05 11:44:08:973 PDT [DEBUG] wire - http-outgoing-1 >> "POST
/EWS/Exchange.asmx HTTP/1.1[\r][\n]"
2013/08/05 11:44:08:973 PDT [DEBUG] wire - http-outgoing-1 >>
"Content-type: text/xml; charset=utf-8[\r][\n]"
2013/08/05 11:44:08:973 PDT [DEBUG] wire - http-outgoing-1 >> "Keep-Alive:
300[\r][\n]"
2013/08/05 11:44:08:973 PDT [DEBUG] wire - http-outgoing-1 >> "User-Agent:
ExchangeServicesClient/0.0.0.0[\r][\n]"
2013/08/05 11:44:08:973 PDT [DEBUG] wire - http-outgoing-1 >> "Accept:
text/xml[\r][\n]"
2013/08/05 11:44:08:973 PDT [DEBUG] wire - http-outgoing-1 >>
"Transfer-Encoding: chunked[\r][\n]"
2013/08/05 11:44:08:973 PDT [DEBUG] wire - http-outgoing-1 >> "Host:
mc-ex4-1-sfm.s2x.uncrap.local[\r][\n]"
2013/08/05 11:44:08:973 PDT [DEBUG] wire - http-outgoing-1 >> "Connection:
Keep-Alive[\r][\n]"
2013/08/05 11:44:08:974 PDT [DEBUG] wire - http-outgoing-1 >>
"Authorization: Basic
UzJYLlVOQ1JBUC5MT0NBTC9zMnhhZG1pbjoxMjN0ZXN0IQ==[\r][\n]"
2013/08/05 11:44:08:974 PDT [DEBUG] wire - http-outgoing-1 >> "[\r][\n]"
2013/08/05 11:44:08:974 PDT [DEBUG] wire - http-outgoing-1 >> "1000[\r][\n]"
2013/08/05 11:44:08:974 PDT [DEBUG] wire - http-outgoing-1 >>
"t.com</t:Entry></t:EmailAddresses><t:Surname>LastName77</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName78</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName79</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName80</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName81</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName82</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName83</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName84</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName85</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName86</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName87</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName88</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName89</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName90</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName91</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName92</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName93</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName94</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName95</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName96</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]
</t:Entry></t:EmailAddresses><t:Surname>LastName97</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAd[\r][\n]"
2013/08/05 11:44:08:974 PDT [DEBUG] wire - http-outgoing-1 >> "188[\r][\n]"
2013/08/05 11:44:08:974 PDT [DEBUG] wire - http-outgoing-1 >>
"dresses><t:Entry
Key="EmailAddress1">[email protected]</t:Entry></t:EmailAddresses><t:Surname>LastName98</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">[email protected]
</t:Entry></t:EmailAddresses><t:Surname>LastName99</t:Surname></t:Contact></m:Items></m:CreateItem></soap:Body></soap:Envelope>[\r][\n]"
2013/08/05 11:44:08:974 PDT [DEBUG] HttpAsyncRequestExecutor -
http-outgoing-1 [ACTIVE] Output ready
2013/08/05 11:44:08:974 PDT [DEBUG] MainClientExec - [exchange: 1] produce
content
2013/08/05 11:44:08:974 PDT [DEBUG] MainClientExec - [exchange: 1] Request
completed
2013/08/05 11:44:08:974 PDT [DEBUG] HttpAsyncRequestExecutor -
http-outgoing-1 [ACTIVE] [chunk-coded; completed: true]
2013/08/05 11:44:08:974 PDT [DEBUG] ManagedNHttpClientConnectionImpl -
http-outgoing-1
10.0.115.37:47997<->10.224.203.221:443[ACTIVE][rw:w][ACTIVE][rw][NOT_HANDSHAKING][0][0][74][0]:
5 bytes written
2013/08/05 11:44:08:975 PDT [DEBUG] wire - http-outgoing-1 >> "0[\r][\n]"
2013/08/05 11:44:08:975 PDT [DEBUG] wire - http-outgoing-1 >> "[\r][\n]"
2013/08/05 11:44:08:975 PDT [DEBUG] ManagedNHttpClientConnectionImpl -
http-outgoing-1
10.0.115.37:47997<->10.224.203.221:443[ACTIVE][rw:w][ACTIVE][r][NOT_HANDSHAKING][0][0][74][0]:
Event cleared [w]
2013/08/05 11:44:08:981 PDT [DEBUG] ManagedNHttpClientConnectionImpl -
http-outgoing-1
10.0.115.37:47997<->10.224.203.221:443[ACTIVE][r:r][ACTIVE][r][NOT_HANDSHAKING][0][0][0][0]:
305 bytes read
2013/08/05 11:44:08:981 PDT [DEBUG] wire - http-outgoing-1 << "HTTP/1.1 400
Bad Request[\r][\n]"
2013/08/05 11:44:08:981 PDT [DEBUG] wire - http-outgoing-1 <<
"Cache-Control: private[\r][\n]"
2013/08/05 11:44:08:981 PDT [DEBUG] wire - http-outgoing-1 << "Server:
Microsoft-IIS/7.5[\r][\n]"
2013/08/05 11:44:08:981 PDT [DEBUG] wire - http-outgoing-1 << "Set-Cookie:
exchangecookie=bbe9d2eb198e4c17a014b4be12f59a48; expires=Tue, 05-Aug-2014
18:44:08 GMT; path=/; HttpOnly[\r][\n]"
2013/08/05 11:44:08:981 PDT [DEBUG] wire - http-outgoing-1 <<
"X-AspNet-Version: 2.0.50727[\r][\n]"
2013/08/05 11:44:08:981 PDT [DEBUG] wire - http-outgoing-1 <<
"X-Powered-By: ASP.NET[\r][\n]"
2013/08/05 11:44:08:982 PDT [DEBUG] wire - http-outgoing-1 << "Date: Mon,
05 Aug 2013 18:44:08 GMT[\r][\n]"
2013/08/05 11:44:08:982 PDT [DEBUG] wire - http-outgoing-1 <<
"Content-Length: 0[\r][\n]"
2013/08/05 11:44:08:982 PDT [DEBUG] wire - http-outgoing-1 << "[\r][\n]"
2013/08/05 11:44:08:982 PDT [DEBUG] headers - http-outgoing-1 << HTTP/1.1
400 Bad Request
2013/08/05 11:44:08:982 PDT [DEBUG] headers - http-outgoing-1 <<
Cache-Control: private
2013/08/05 11:44:08:982 PDT [DEBUG] headers - http-outgoing-1 << Server:
Microsoft-IIS/7.5
2013/08/05 11:44:08:982 PDT [DEBUG] headers - http-outgoing-1 <<
Set-Cookie: exchangecookie=bbe9d2eb198e4c17a014b4be12f59a48; expires=Tue,
05-Aug-2014 18:44:08 GMT; path=/; HttpOnly
2013/08/05 11:44:08:982 PDT [DEBUG] headers - http-outgoing-1 <<
X-AspNet-Version: 2.0.50727
2013/08/05 11:44:08:982 PDT [DEBUG] headers - http-outgoing-1 <<
X-Powered-By: ASP.NET
2013/08/05 11:44:08:982 PDT [DEBUG] headers - http-outgoing-1 << Date: Mon,
05 Aug 2013 18:44:08 GMT
2013/08/05 11:44:08:982 PDT [DEBUG] headers - http-outgoing-1 <<
Content-Length: 0
2013/08/05 11:44:08:982 PDT [DEBUG] HttpAsyncRequestExecutor -
http-outgoing-1 [ACTIVE] Response received
2013/08/05 11:44:08:982 PDT [DEBUG] MainClientExec - [exchange: 1] Response
received HTTP/1.1 400 Bad Request
2013/08/05 11:44:08:983 PDT [DEBUG] ResponseProcessCookies - Cookie
accepted: "[version: 0][name: exchangecookie][value:
bbe9d2eb198e4c17a014b4be12f59a48][domain:
mc-ex4-1-sfm.s2x.uncrap.local][path: /][expiry: Tue Aug 05 11:44:08 PDT
2014]".
2013/08/05 11:44:08:983 PDT [DEBUG] MainClientExec - Authentication
succeeded
2013/08/05 11:44:08:983 PDT [DEBUG] TargetAuthenticationStrategy - Caching
'basic' auth scheme for https://mc-ex4-1-sfm.s2x.uncrap.local:443
2013/08/05 11:44:08:985 PDT [DEBUG] HttpAsyncRequestExecutor -
http-outgoing-1 [ACTIVE] Input ready
2013/08/05 11:44:08:985 PDT [DEBUG] MainClientExec - [exchange: 1] Consume
content
2013/08/05 11:44:08:985 PDT [DEBUG] ManagedNHttpClientConnectionImpl -
http-outgoing-1
10.0.115.37:47997<->10.224.203.221:443[ACTIVE][r:r][ACTIVE][r][NOT_HANDSHAKING][0][0][0][0]:
0 bytes read
2013/08/05 11:44:08:985 PDT [DEBUG] MainClientExec - [exchange: 1]
Connection can be kept alive indefinitely
2013/08/05 11:44:08:986 PDT [DEBUG] MainClientExec - [exchange: 1] Response
processed
2013/08/05 11:44:08:986 PDT [DEBUG] InternalHttpAsyncClient - [exchange: 1]
releasing connection
2013/08/05 11:44:08:986 PDT [DEBUG] ManagedNHttpClientConnectionImpl -
http-outgoing-1
10.0.115.37:47997<->10.224.203.221:443[ACTIVE][r:r][ACTIVE][r][NOT_HANDSHAKING][0][0][0][0]:
Remove attribute http.nio.exchange-handler
2013/08/05 11:44:08:986 PDT [DEBUG] PoolingNHttpClientConnectionManager -
Connection [id: http-outgoing-1][route: {s}->
https://mc-ex4-1-sfm.s2x.uncrap.local:443] can be kept alive indefinitely
2013/08/05 11:44:08:986 PDT [DEBUG] PoolingNHttpClientConnectionManager -
Connection released: [id: http-outgoing-1][route:
{s}->https://mc-ex4-1-sfm.s2x.uncrap.local:443][total kept alive: 1; route
allocated: 1 of 2; total allocated: 1 of 20]
2013/08/05 11:44:11:358 PDT [DEBUG] HttpAsyncRequestExecutor -
http-outgoing-1 [ACTIVE] [content length: 0; pos: 0; completed: true]
javax.xml.stream.XMLStreamException: ParseError at [row,col]:[1,1]
Message: Premature end of file.
at
com.sun.org.apache.xerces.internal.impl.XMLStreamReaderImpl.next(XMLStreamReaderImpl.java:598)
at
com.sun.xml.internal.stream.XMLEventReaderImpl.nextEvent(XMLEventReaderImpl.java:83)
at
salesforce.microsoft.exchange.webservices.data.EwsXmlReader.read(EwsXmlReader.java:173)
at
salesforce.microsoft.exchange.webservices.data.EwsServiceXmlReader.read(EwsServiceXmlReader.java:22)
at
salesforce.microsoft.exchange.webservices.data.EwsXmlReader.read(EwsXmlReader.java:196)
at
salesforce.microsoft.exchange.webservices.data.EwsServiceXmlReader.read(EwsServiceXmlReader.java:22)
at
salesforce.microsoft.exchange.webservices.data.EwsXmlReader.internalReadElement(EwsXmlReader.java:111)
at
salesforce.microsoft.exchange.webservices.data.EwsXmlReader.readStartElement(EwsXmlReader.java:620)
at
salesforce.microsoft.exchange.webservices.data.EwsServiceXmlReader.readStartElement(EwsServiceXmlReader.java:22)
at
salesforce.microsoft.exchange.webservices.data.ServiceRequestBase.readResponse(ServiceRequestBase.java:523)
at sync.s2x.nio.commands.CommandFuture.get(CommandFuture.java:40)
at test.exchange.TestCreateItem.main(TestCreateItem.java:91)
javax.xml.stream.XMLStreamException: ParseError at [row,col]:[1,1]
Message: Premature end of file.
at
com.sun.org.apache.xerces.internal.impl.XMLStreamReaderImpl.next(XMLStreamReaderImpl.java:598)
at
com.sun.xml.internal.stream.XMLEventReaderImpl.nextEvent(XMLEventReaderImpl.java:83)
at
salesforce.microsoft.exchange.webservices.data.EwsXmlReader.read(EwsXmlReader.java:173)
at
salesforce.microsoft.exchange.webservices.data.EwsServiceXmlReader.read(EwsServiceXmlReader.java:22)
at
salesforce.microsoft.exchange.webservices.data.ServiceRequestBase.readSoapFault(ServiceRequestBase.java:744)
at sync.s2x.nio.commands.CommandFuture.get(CommandFuture.java:46)
at test.exchange.TestCreateItem.main(TestCreateItem.java:91)
Exception in thread "main" java.lang.NullPointerException
at sync.s2x.nio.commands.CommandFuture.get(CommandFuture.java:47)
at test.exchange.TestCreateItem.main(TestCreateItem.java:91)
On Mon, Aug 5, 2013 at 11:54 AM, Oleg Kalnichevski <[email protected]> wrote:
> On Mon, 2013-08-05 at 11:47 -0700, Alex Oscherov wrote:
> > Oleg,
> >
> > here is my complete log. My simple test program crashes since it can't
> > process unexpected message from the server but HTTP communications is
> > finished by then
> >
> >
> > Alex
> >
>
> Apparently the attachments got stripped away by the ASF mail server or
> some intermediate hops.
>
> Try using http://pastebin.com/ or a similar service.
>
> Oleg
>
> >
> >
> > On Mon, Aug 5, 2013 at 11:28 AM, Oleg Kalnichevski <[email protected]>
> > wrote:
> > On Mon, 2013-08-05 at 10:59 -0700, Alex Oscherov wrote:
> > > I have a problem making http async client working with
> > Exchange server
> > > through IIS 7.5. Everything works fine as long as requests
> > are small
> > > but as soon as size of requests increases communication
> > fails with the
> > > HTTP 400 response from the server.
> > >
> > >
> > > Analysing log (attached) I observe following behaviour from
> > the HTTP
> > > async client :
> > >
> > > 1. on line 2013/08/05 10:30:42:497 PDT [DEBUG] client starts
> > sending
> > > request to the server
> > >
> > > 2. on line 2013/08/05 10:30:42:515 PDT client while sending
> > request
> > > not finished receives authentication challenge from server
> > >
> > > 3. on line 2013/08/05 10:30:42:552 PDT client sends to the
> > server new
> > > authenticated request but instead of starting to send body
> > of the
> > > request from the beginning it just sends body of the request
> > from the
> > > point where it was interrupted first time.
> > >
> > >
> > > As a result server can't process request and sends back HTTP
> > 400.
> > >
> > > I am not an experienced http async client library user so it
> > is quite
> > > possible that I am doing something wrong.
> > >
> > >
> > > Any advice or recommendation will be highly appreciated and
> > any
> > > additional information if it is needed could be provided.
> > >
> > >
> >
> >
> > Alex
> >
> > Please please attach a complete log of the session.
> >
> > Oleg
> >
> >
> >
> >
> >
> ---------------------------------------------------------------------
> > To unsubscribe, e-mail:
> > [email protected]
> > For additional commands, e-mail:
> > [email protected]
> >
> >
> >
> > ---------------------------------------------------------------------
> > To unsubscribe, e-mail: [email protected]
> > For additional commands, e-mail: [email protected]
>
>
>
> ---------------------------------------------------------------------
> To unsubscribe, e-mail: [email protected]
> For additional commands, e-mail: [email protected]
>
>
