hc-httpclient-users mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Alex Oscherov <aosche...@salesforce.com>
Subject Re: Http asyc client doesn't handle correctly large requests in presence of authentication challenge
Date Mon, 05 Aug 2013 20:16:54 GMT
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>abhijat@s2x.uncrap.local</t:EmailAddress></t:Mailbox></t:DistinguishedFolderId></m:SavedItemFolderId><m:Items><t:Contact><t:GivenName>Alex</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">alex.bla@foo.com</t:Entry></t:EmailAddresses><t:Surname>Oscherov</t:Surname></t:Contact><t:Contact><t:GivenName>Alexander</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">al.brum@bum.com</t:Entry></t:EmailAddresses><t:Surname>Osherov</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName0LastName0@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName0</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName1LastName1@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName1</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName2LastName2@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName2</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName3LastName3@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName3</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName4LastName4@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName4</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName5LastName5@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName5</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName6LastName6@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName6</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName7LastName7@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName7</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName8LastName8@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName8</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName9LastName9@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName9</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName10LastName10@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName10</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName11LastName11@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName11</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName12LastName12@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName12</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName13LastName13@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName13</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName14LastName14@test.com</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 >> "
stName15@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName15</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName16LastName16@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName16</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName17LastName17@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName17</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName18LastName18@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName18</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName19LastName19@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName19</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName20LastName20@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName20</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName21LastName21@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName21</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName22LastName22@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName22</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName23LastName23@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName23</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName24LastName24@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName24</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName25LastName25@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName25</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName26LastName26@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName26</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName27LastName27@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName27</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName28LastName28@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName28</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName29LastName29@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName29</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName30LastName30@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName30</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName31LastName31@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName31</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName32LastName32@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName32</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName33LastName33@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName33</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName34LastName34@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName34</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName35LastName35@test.com
</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">
FirstName36LastName36@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName36</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName37LastName37@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName37</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName38LastName38@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName38</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName39LastName39@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName39</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName40LastName40@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName40</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName41LastName41@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName41</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName42LastName42@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName42</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName43LastName43@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName43</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName44LastName44@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName44</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName45LastName45@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName45</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName46LastName46@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName46</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName47LastName47@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName47</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName48LastName48@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName48</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName49LastName49@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName49</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName50LastName50@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName50</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName51LastName51@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName51</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName52LastName52@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName52</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName53LastName53@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName53</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName54LastName54@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName54</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName55LastName55@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName55</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName56LastName56@test.com
</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">FirstName57LastName57@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName57</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName58LastName58@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName58</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName59LastName59@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName59</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName60LastName60@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName60</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName61LastName61@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName61</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName62LastName62@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName62</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName63LastName63@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName63</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName64LastName64@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName64</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName65LastName65@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName65</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName66LastName66@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName66</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName67LastName67@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName67</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName68LastName68@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName68</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName69LastName69@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName69</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName70LastName70@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName70</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName71LastName71@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName71</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName72LastName72@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName72</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName73LastName73@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName73</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName74LastName74@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName74</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName75LastName75@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName75</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName76LastName76@test.com</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">FirstName78LastName78@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName78</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName79LastName79@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName79</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName80LastName80@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName80</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName81LastName81@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName81</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName82LastName82@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName82</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName83LastName83@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName83</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName84LastName84@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName84</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName85LastName85@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName85</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName86LastName86@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName86</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName87LastName87@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName87</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName88LastName88@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName88</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName89LastName89@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName89</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName90LastName90@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName90</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName91LastName91@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName91</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName92LastName92@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName92</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName93LastName93@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName93</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName94LastName94@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName94</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName95LastName95@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName95</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName96LastName96@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName96</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName97LastName97@test.com
</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">FirstName98LastName98@test.com</t:Entry></t:EmailAddresses><t:Surname>LastName98</t:Surname></t:Contact><t:Contact><t:GivenName>FirstName</t:GivenName><t:EmailAddresses><t:Entry
Key="EmailAddress1">FirstName99LastName99@test.com
</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 <olegk@apache.org> 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 <olegk@apache.org>
> > 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:
> >         httpclient-users-unsubscribe@hc.apache.org
> >         For additional commands, e-mail:
> >         httpclient-users-help@hc.apache.org
> >
> >
> >
> > ---------------------------------------------------------------------
> > To unsubscribe, e-mail: httpclient-users-unsubscribe@hc.apache.org
> > For additional commands, e-mail: httpclient-users-help@hc.apache.org
>
>
>
> ---------------------------------------------------------------------
> To unsubscribe, e-mail: httpclient-users-unsubscribe@hc.apache.org
> For additional commands, e-mail: httpclient-users-help@hc.apache.org
>
>

Mime
  • Unnamed multipart/alternative (inline, None, 0 bytes)
View raw message