OK Oleg, in case you didn't get my previous e-mail with log information. I put it into pastebin under name "HttpAsync Error".
Alex On Mon, Aug 5, 2013 at 1:16 PM, Alex Oscherov <[email protected]>wrote: > 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] >> >> >
