Skip to content

Instantly share code, notes, and snippets.

@drewwills
Created June 27, 2012 14:30
Show Gist options
  • Select an option

  • Save drewwills/3004422 to your computer and use it in GitHub Desktop.

Select an option

Save drewwills/3004422 to your computer and use it in GitHub Desktop.
exchange ws SoapFaultClientException
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:16,913 controller.AjaxCalendarController.[] - AjaxCalendarController took 317 ms to produce JSON model
DEBUG [uPortal-PortletExecutor-7-RENDER-[exchange-calendar]] Jun/27 06:57:21,851 controller.CalendarController.[] - startDate from session is: 2012-06-27T00:00:00.000-05:00
DEBUG [uPortal-PortletExecutor-7-RENDER-[exchange-calendar]] Jun/27 06:57:21,955 dao.HibernateCalendarStore.[] - fetching calendar configurations for unicon
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,462 dao.HibernateCalendarStore.[] - fetching calendar configurations for unicon
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,487 controller.AjaxCalendarController.[] - new interval: 2012-06-27T00:00:00.000/2012-07-04T00:00:00.000
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,553 adapter.ExchangeCalendarAdapter.[] - Retreiving exchange events for account unicon@stcloudstate.edu
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,553 adapter.ExchangeCalendarAdapter.[] - Retrieving exchange events for period: 2012-06-27T00:00:00.000/2012-07-04T00:00:00.000
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,554 core.WebServiceTemplate.[] - Opening [org.springframework.ws.transport.http.HttpComponentsConnection@7f9fb036] to [https://mail.stcloudstate.edu/EWS/exchange.asmx]
TRACE [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,556 MessageTracing.sent.[] - Sent request [<SOAP-ENV:Envelope xmlns:SOAP-ENV="http://schemas.xmlsoap.org/soap/envelope/"><SOAP-ENV:Header/><SOAP-ENV:Body><ns2:GetUserAvailabilityRequest xmlns:ns2="http://schemas.microsoft.com/exchange/services/2006/messages" xmlns:ns3="http://schemas.microsoft.com/exchange/services/2006/types"><ns3:TimeZone><ns3:Bias>0</ns3:Bias><ns3:StandardTime><ns3:Bias>0</ns3:Bias><ns3:Time>02:00:00</ns3:Time><ns3:DayOrder>1</ns3:DayOrder><ns3:Month>11</ns3:Month><ns3:DayOfWeek>Sunday</ns3:DayOfWeek></ns3:StandardTime><ns3:DaylightTime><ns3:Bias>0</ns3:Bias><ns3:Time>02:00:00</ns3:Time><ns3:DayOrder>1</ns3:DayOrder><ns3:Month>3</ns3:Month><ns3:DayOfWeek>Sunday</ns3:DayOfWeek></ns3:DaylightTime></ns3:TimeZone><ns2:MailboxDataArray><ns3:MailboxData><ns3:Email><ns3:Name/><ns3:Address>unicon@stcloudstate.edu</ns3:Address></ns3:Email><ns3:AttendeeType>Required</ns3:AttendeeType><ns3:ExcludeConflicts>false</ns3:ExcludeConflicts></ns3:MailboxData></ns2:MailboxDataArray><ns3:FreeBusyViewOptions><ns3:TimeWindow><ns3:StartTime>2012-06-27T00:00:00.000</ns3:StartTime><ns3:EndTime>2012-07-04T00:00:00.000</ns3:EndTime></ns3:TimeWindow><ns3:MergedFreeBusyIntervalInMinutes>60</ns3:MergedFreeBusyIntervalInMinutes><ns3:RequestedView>DetailedMerged</ns3:RequestedView></ns3:FreeBusyViewOptions></ns2:GetUserAvailabilityRequest></SOAP-ENV:Body></SOAP-ENV:Envelope>]
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,556 tsccm.ThreadSafeClientConnManager.[] - Get connection: HttpRoute[{s}->https://mail.stcloudstate.edu], timeout = 0
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,557 tsccm.ConnPoolByRoute.[] - [HttpRoute[{s}->https://mail.stcloudstate.edu]] total kept alive: 2, total issued: 0, total allocated: 2 out of 200
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,557 conn.DefaultClientConnection.[] - Connection closed
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,557 tsccm.ConnPoolByRoute.[] - Getting free connection [HttpRoute[{s}->https://mail.stcloudstate.edu]][null]
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,557 conn.DefaultClientConnectionOperator.[] - Connecting to mail.stcloudstate.edu/199.17.15.16:443
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,628 protocol.RequestAddCookies.[] - CookieSpec selected: best-match
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,629 protocol.RequestAddCookies.[] - Cookie [version: 0][name: exchangecookie][value: 99b0717eb0f0413fa970c5ae7eafed43][domain: mail.stcloudstate.edu][path: /][expiry: Thu Jun 27 06:56:17 MST 2013] match [(secure)mail.stcloudstate.edu:443/EWS/exchange.asmx]
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,629 protocol.RequestAuthCache.[] - Auth cache not set in the context
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,629 exchange.NtlmAuthHttpClient.[] - Attempt 1 to execute request
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,630 conn.DefaultClientConnection.[] - Sending request: POST /EWS/exchange.asmx HTTP/1.1
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,630 http.wire.[] - >> "POST /EWS/exchange.asmx HTTP/1.1[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,630 http.wire.[] - >> "Accept-Encoding: gzip[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,630 http.wire.[] - >> "Accept: text/xml, text/html, image/gif, image/jpeg, *; q=.2, */*; q=.2[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,630 http.wire.[] - >> "SOAPAction: "http://schemas.microsoft.com/exchange/services/2006/messages/GetUserAvailability"[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,631 http.wire.[] - >> "Content-Type: text/xml; charset=utf-8[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,631 http.wire.[] - >> "Content-Length: 1342[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,631 http.wire.[] - >> "Host: mail.stcloudstate.edu[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,631 http.wire.[] - >> "Connection: Keep-Alive[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,631 http.wire.[] - >> "User-Agent: Apache-HttpClient/4.1.1 (java 1.5)[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,631 http.wire.[] - >> "Cookie: exchangecookie=99b0717eb0f0413fa970c5ae7eafed43[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,632 http.wire.[] - >> "Cookie2: $Version=1[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,632 http.wire.[] - >> "[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,632 http.headers.[] - >> POST /EWS/exchange.asmx HTTP/1.1
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,632 http.headers.[] - >> Accept-Encoding: gzip
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,632 http.headers.[] - >> Accept: text/xml, text/html, image/gif, image/jpeg, *; q=.2, */*; q=.2
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,632 http.headers.[] - >> SOAPAction: "http://schemas.microsoft.com/exchange/services/2006/messages/GetUserAvailability"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,632 http.headers.[] - >> Content-Type: text/xml; charset=utf-8
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,633 http.headers.[] - >> Content-Length: 1342
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,633 http.headers.[] - >> Host: mail.stcloudstate.edu
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,633 http.headers.[] - >> Connection: Keep-Alive
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,633 http.headers.[] - >> User-Agent: Apache-HttpClient/4.1.1 (java 1.5)
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,633 http.headers.[] - >> Cookie: exchangecookie=99b0717eb0f0413fa970c5ae7eafed43
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,633 http.headers.[] - >> Cookie2: $Version=1
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,634 http.wire.[] - >> "<SOAP-ENV:Envelope xmlns:SOAP-ENV="http://schemas.xmlsoap.org/soap/envelope/"><SOAP-ENV:Header/><SOAP-ENV:Body><ns2:GetUserAvailabilityRequest xmlns:ns2="http://schemas.microsoft.com/exchange/services/2006/messages" xmlns:ns3="http://schemas.microsoft.com/exchange/services/2006/types"><ns3:TimeZone><ns3:Bias>0</ns3:Bias><ns3:StandardTime><ns3:Bias>0</ns3:Bias><ns3:Time>02:00:00</ns3:Time><ns3:DayOrder>1</ns3:DayOrder><ns3:Month>11</ns3:Month><ns3:DayOfWeek>Sunday</ns3:DayOfWeek></ns3:StandardTime><ns3:DaylightTime><ns3:Bias>0</ns3:Bias><ns3:Time>02:00:00</ns3:Time><ns3:DayOrder>1</ns3:DayOrder><ns3:Month>3</ns3:Month><ns3:DayOfWeek>Sunday</ns3:DayOfWeek></ns3:DaylightTime></ns3:TimeZone><ns2:MailboxDataArray><ns3:MailboxData><ns3:Email><ns3:Name/><ns3:Address>unicon@stcloudstate.edu</ns3:Address></ns3:Email><ns3:AttendeeType>Required</ns3:AttendeeType><ns3:ExcludeConflicts>false</ns3:ExcludeConflicts></ns3:MailboxData></ns2:MailboxDataArray><ns3:FreeBusyViewOptions><ns3:TimeWindow><ns3:StartTime>2012-06-27T00:00:00.000</ns3:StartTime><ns3:EndTime>2012-07-04T00:00:00.000</ns3:EndTime></ns3:TimeWindow><ns3:MergedFreeBusyIntervalInMinutes>60</ns3:MergedFreeBusyIntervalInMinutes><ns3:RequestedView>DetailedMerged</ns3:RequestedView></ns3:FreeBusyViewOptions></ns2:GetUserAvailabilityRequest></SOAP-ENV:Body></SOAP-ENV:Envelope>"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,653 http.wire.[] - << "HTTP/1.1 401 Unauthorized[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,654 http.wire.[] - << "Server: Microsoft-IIS/7.5[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,654 http.wire.[] - << "WWW-Authenticate: Negotiate[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,654 http.wire.[] - << "WWW-Authenticate: NTLM[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,654 http.wire.[] - << "WWW-Authenticate: Basic realm="mail.stcloudstate.edu"[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,654 http.wire.[] - << "X-Powered-By: ASP.NET[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,655 http.wire.[] - << "Date: Wed, 27 Jun 2012 13:57:21 GMT[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,655 http.wire.[] - << "Content-Length: 0[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,655 http.wire.[] - << "[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,655 conn.DefaultClientConnection.[] - Receiving response: HTTP/1.1 401 Unauthorized
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,655 http.headers.[] - << HTTP/1.1 401 Unauthorized
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,655 http.headers.[] - << Server: Microsoft-IIS/7.5
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,656 http.headers.[] - << WWW-Authenticate: Negotiate
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,656 http.headers.[] - << WWW-Authenticate: NTLM
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,656 http.headers.[] - << WWW-Authenticate: Basic realm="mail.stcloudstate.edu"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,656 http.headers.[] - << X-Powered-By: ASP.NET
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,656 http.headers.[] - << Date: Wed, 27 Jun 2012 13:57:21 GMT
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,656 http.headers.[] - << Content-Length: 0
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,656 exchange.NtlmAuthHttpClient.[] - Connection can be kept alive indefinitely
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,657 exchange.NtlmAuthHttpClient.[] - Target requested authentication
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,657 client.DefaultTargetAuthenticationHandler.[] - Authentication schemes in the order of preference: [negotiate, NTLM, Digest, Basic]
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,657 client.DefaultTargetAuthenticationHandler.[] - negotiate authentication scheme selected
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,657 exchange.NtlmAuthHttpClient.[] - Authorization challenge processed
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,657 exchange.NtlmAuthHttpClient.[] - Authentication scope: NTLM <any realm>@mail.stcloudstate.edu:443
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,657 exchange.NtlmAuthHttpClient.[] - Found credentials
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,658 protocol.RequestAddCookies.[] - CookieSpec selected: best-match
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,658 protocol.RequestAddCookies.[] - Cookie [version: 0][name: exchangecookie][value: 99b0717eb0f0413fa970c5ae7eafed43][domain: mail.stcloudstate.edu][path: /][expiry: Thu Jun 27 06:56:17 MST 2013] match [(secure)mail.stcloudstate.edu:443/EWS/exchange.asmx]
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,658 protocol.RequestAuthCache.[] - Auth cache not set in the context
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,659 exchange.NtlmAuthHttpClient.[] - Attempt 2 to execute request
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,659 conn.DefaultClientConnection.[] - Sending request: POST /EWS/exchange.asmx HTTP/1.1
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,659 http.wire.[] - >> "POST /EWS/exchange.asmx HTTP/1.1[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,659 http.wire.[] - >> "Accept-Encoding: gzip[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,659 http.wire.[] - >> "Accept: text/xml, text/html, image/gif, image/jpeg, *; q=.2, */*; q=.2[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,660 http.wire.[] - >> "SOAPAction: "http://schemas.microsoft.com/exchange/services/2006/messages/GetUserAvailability"[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,660 http.wire.[] - >> "Content-Type: text/xml; charset=utf-8[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,660 http.wire.[] - >> "Content-Length: 1342[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,660 http.wire.[] - >> "Host: mail.stcloudstate.edu[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,660 http.wire.[] - >> "Connection: Keep-Alive[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,660 http.wire.[] - >> "User-Agent: Apache-HttpClient/4.1.1 (java 1.5)[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,660 http.wire.[] - >> "Cookie: exchangecookie=99b0717eb0f0413fa970c5ae7eafed43[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,660 http.wire.[] - >> "Cookie2: $Version=1[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,661 http.wire.[] - >> "Authorization: NTLM TlRMTVNTUAABAAAABbIIoAwADAAgAAAAGAAYACwAAABTVENMT1VEU1RBVEVQQVJBTURPRVNOT1RTRUVNVE9NQVRURVI=[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,661 http.wire.[] - >> "[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,661 http.headers.[] - >> POST /EWS/exchange.asmx HTTP/1.1
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,661 http.headers.[] - >> Accept-Encoding: gzip
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,661 http.headers.[] - >> Accept: text/xml, text/html, image/gif, image/jpeg, *; q=.2, */*; q=.2
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,661 http.headers.[] - >> SOAPAction: "http://schemas.microsoft.com/exchange/services/2006/messages/GetUserAvailability"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,661 http.headers.[] - >> Content-Type: text/xml; charset=utf-8
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,661 http.headers.[] - >> Content-Length: 1342
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,661 http.headers.[] - >> Host: mail.stcloudstate.edu
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,661 http.headers.[] - >> Connection: Keep-Alive
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,661 http.headers.[] - >> User-Agent: Apache-HttpClient/4.1.1 (java 1.5)
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,662 http.headers.[] - >> Cookie: exchangecookie=99b0717eb0f0413fa970c5ae7eafed43
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,662 http.headers.[] - >> Cookie2: $Version=1
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,662 http.headers.[] - >> Authorization: NTLM TlRMTVNTUAABAAAABbIIoAwADAAgAAAAGAAYACwAAABTVENMT1VEU1RBVEVQQVJBTURPRVNOT1RTRUVNVE9NQVRURVI=
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,662 http.wire.[] - >> "<SOAP-ENV:Envelope xmlns:SOAP-ENV="http://schemas.xmlsoap.org/soap/envelope/"><SOAP-ENV:Header/><SOAP-ENV:Body><ns2:GetUserAvailabilityRequest xmlns:ns2="http://schemas.microsoft.com/exchange/services/2006/messages" xmlns:ns3="http://schemas.microsoft.com/exchange/services/2006/types"><ns3:TimeZone><ns3:Bias>0</ns3:Bias><ns3:StandardTime><ns3:Bias>0</ns3:Bias><ns3:Time>02:00:00</ns3:Time><ns3:DayOrder>1</ns3:DayOrder><ns3:Month>11</ns3:Month><ns3:DayOfWeek>Sunday</ns3:DayOfWeek></ns3:StandardTime><ns3:DaylightTime><ns3:Bias>0</ns3:Bias><ns3:Time>02:00:00</ns3:Time><ns3:DayOrder>1</ns3:DayOrder><ns3:Month>3</ns3:Month><ns3:DayOfWeek>Sunday</ns3:DayOfWeek></ns3:DaylightTime></ns3:TimeZone><ns2:MailboxDataArray><ns3:MailboxData><ns3:Email><ns3:Name/><ns3:Address>unicon@stcloudstate.edu</ns3:Address></ns3:Email><ns3:AttendeeType>Required</ns3:AttendeeType><ns3:ExcludeConflicts>false</ns3:ExcludeConflicts></ns3:MailboxData></ns2:MailboxDataArray><ns3:FreeBusyViewOptions><ns3:TimeWindow><ns3:StartTime>2012-06-27T00:00:00.000</ns3:StartTime><ns3:EndTime>2012-07-04T00:00:00.000</ns3:EndTime></ns3:TimeWindow><ns3:MergedFreeBusyIntervalInMinutes>60</ns3:MergedFreeBusyIntervalInMinutes><ns3:RequestedView>DetailedMerged</ns3:RequestedView></ns3:FreeBusyViewOptions></ns2:GetUserAvailabilityRequest></SOAP-ENV:Body></SOAP-ENV:Envelope>"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,685 http.wire.[] - << "HTTP/1.1 401 Unauthorized[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,685 http.wire.[] - << "Server: Microsoft-IIS/7.5[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,685 http.wire.[] - << "WWW-Authenticate: NTLM TlRMTVNTUAACAAAAGAAYADgAAAAFgomic09AHFJHYSkAAAAAAAAAAOQA5ABQAAAABgGxHQAAAA9TAFQAQwBMAE8AVQBEAFMAVABBAFQARQACABgAUwBUAEMATABPAFUARABTAFQAQQBUAEUAAQAOAFMAQwBTAFUAMQA0AEMABAAuAGMAYQBtAHAAdQBzAC4AcwB0AGMAbABvAHUAZABzAHQAYQB0AGUALgBlAGQAdQADAD4AcwBjAHMAdQAxADQAYwAuAGMAYQBtAHAAdQBzAC4AcwB0AGMAbABvAHUAZABzAHQAYQB0AGUALgBlAGQAdQAFAC4AYwBhAG0AcAB1AHMALgBzAHQAYwBsAG8AdQBkAHMAdABhAHQAZQAuAGUAZAB1AAcACAAyNvXFbFTNAQAAAAA=[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,685 http.wire.[] - << "WWW-Authenticate: Negotiate[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,685 http.wire.[] - << "WWW-Authenticate: Basic realm="mail.stcloudstate.edu"[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,686 http.wire.[] - << "X-Powered-By: ASP.NET[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,686 http.wire.[] - << "Date: Wed, 27 Jun 2012 13:57:21 GMT[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,686 http.wire.[] - << "Content-Length: 0[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,686 http.wire.[] - << "[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,686 conn.DefaultClientConnection.[] - Receiving response: HTTP/1.1 401 Unauthorized
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,686 http.headers.[] - << HTTP/1.1 401 Unauthorized
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,686 http.headers.[] - << Server: Microsoft-IIS/7.5
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,686 http.headers.[] - << WWW-Authenticate: NTLM TlRMTVNTUAACAAAAGAAYADgAAAAFgomic09AHFJHYSkAAAAAAAAAAOQA5ABQAAAABgGxHQAAAA9TAFQAQwBMAE8AVQBEAFMAVABBAFQARQACABgAUwBUAEMATABPAFUARABTAFQAQQBUAEUAAQAOAFMAQwBTAFUAMQA0AEMABAAuAGMAYQBtAHAAdQBzAC4AcwB0AGMAbABvAHUAZABzAHQAYQB0AGUALgBlAGQAdQADAD4AcwBjAHMAdQAxADQAYwAuAGMAYQBtAHAAdQBzAC4AcwB0AGMAbABvAHUAZABzAHQAYQB0AGUALgBlAGQAdQAFAC4AYwBhAG0AcAB1AHMALgBzAHQAYwBsAG8AdQBkAHMAdABhAHQAZQAuAGUAZAB1AAcACAAyNvXFbFTNAQAAAAA=
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,686 http.headers.[] - << WWW-Authenticate: Negotiate
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,686 http.headers.[] - << WWW-Authenticate: Basic realm="mail.stcloudstate.edu"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,687 http.headers.[] - << X-Powered-By: ASP.NET
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,687 http.headers.[] - << Date: Wed, 27 Jun 2012 13:57:21 GMT
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,687 http.headers.[] - << Content-Length: 0
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,687 exchange.NtlmAuthHttpClient.[] - Connection can be kept alive indefinitely
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,687 exchange.NtlmAuthHttpClient.[] - Target requested authentication
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,687 exchange.NtlmAuthHttpClient.[] - Authorization challenge processed
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,687 exchange.NtlmAuthHttpClient.[] - Authentication scope: NTLM <any realm>@mail.stcloudstate.edu:443
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,688 protocol.RequestAddCookies.[] - CookieSpec selected: best-match
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,688 protocol.RequestAddCookies.[] - Cookie [version: 0][name: exchangecookie][value: 99b0717eb0f0413fa970c5ae7eafed43][domain: mail.stcloudstate.edu][path: /][expiry: Thu Jun 27 06:56:17 MST 2013] match [(secure)mail.stcloudstate.edu:443/EWS/exchange.asmx]
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,688 protocol.RequestAuthCache.[] - Auth cache not set in the context
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,689 exchange.NtlmAuthHttpClient.[] - Attempt 3 to execute request
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,689 conn.DefaultClientConnection.[] - Sending request: POST /EWS/exchange.asmx HTTP/1.1
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,689 http.wire.[] - >> "POST /EWS/exchange.asmx HTTP/1.1[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,689 http.wire.[] - >> "Accept-Encoding: gzip[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,690 http.wire.[] - >> "Accept: text/xml, text/html, image/gif, image/jpeg, *; q=.2, */*; q=.2[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,690 http.wire.[] - >> "SOAPAction: "http://schemas.microsoft.com/exchange/services/2006/messages/GetUserAvailability"[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,690 http.wire.[] - >> "Content-Type: text/xml; charset=utf-8[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,690 http.wire.[] - >> "Content-Length: 1342[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,690 http.wire.[] - >> "Host: mail.stcloudstate.edu[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,690 http.wire.[] - >> "Connection: Keep-Alive[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,690 http.wire.[] - >> "User-Agent: Apache-HttpClient/4.1.1 (java 1.5)[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,690 http.wire.[] - >> "Cookie: exchangecookie=99b0717eb0f0413fa970c5ae7eafed43[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,690 http.wire.[] - >> "Cookie2: $Version=1[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,690 http.wire.[] - >> "Authorization: NTLM TlRMTVNTUAADAAAAGAAYAEAAAAAUARQBWAAAABgAGABsAQAADAAMAIQBAAAwADAAkAEAAAAAAAAAAAAABYKIouK/6TvnjxGynQamJDYnUSMVFw461cI50sb32kSIZLvrIPfQofO+NDIBAQAAAAAAABDvfMZsVM0Ba2IrBIspp3IAAAAAAgAYAFMAVABDAEwATwBVAEQAUwBUAEEAVABFAAEADgBTAEMAUwBVADEANABDAAQALgBjAGEAbQBwAHUAcwAuAHMAdABjAGwAbwB1AGQAcwB0AGEAdABlAC4AZQBkAHUAAwA+AHMAYwBzAHUAMQA0AGMALgBjAGEAbQBwAHUAcwAuAHMAdABjAGwAbwB1AGQAcwB0AGEAdABlAC4AZQBkAHUABQAuAGMAYQBtAHAAdQBzAC4AcwB0AGMAbABvAHUAZABzAHQAYQB0AGUALgBlAGQAdQAHAAgAMjb1xWxUzQEAAAAAAAAAAFMAVABDAEwATwBVAEQAUwBUAEEAVABFAHUAbgBpAGMAbwBuAFAAQQBSAEEATQBEAE8ARQBTAE4ATwBUAFMARQBFAE0AVABPAE0AQQBUAFQARQBSAA==[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,690 http.wire.[] - >> "[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,690 http.headers.[] - >> POST /EWS/exchange.asmx HTTP/1.1
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,690 http.headers.[] - >> Accept-Encoding: gzip
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,690 http.headers.[] - >> Accept: text/xml, text/html, image/gif, image/jpeg, *; q=.2, */*; q=.2
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,690 http.headers.[] - >> SOAPAction: "http://schemas.microsoft.com/exchange/services/2006/messages/GetUserAvailability"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,690 http.headers.[] - >> Content-Type: text/xml; charset=utf-8
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,690 http.headers.[] - >> Content-Length: 1342
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,690 http.headers.[] - >> Host: mail.stcloudstate.edu
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,690 http.headers.[] - >> Connection: Keep-Alive
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,690 http.headers.[] - >> User-Agent: Apache-HttpClient/4.1.1 (java 1.5)
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,691 http.headers.[] - >> Cookie: exchangecookie=99b0717eb0f0413fa970c5ae7eafed43
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,691 http.headers.[] - >> Cookie2: $Version=1
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,691 http.headers.[] - >> Authorization: NTLM TlRMTVNTUAADAAAAGAAYAEAAAAAUARQBWAAAABgAGABsAQAADAAMAIQBAAAwADAAkAEAAAAAAAAAAAAABYKIouK/6TvnjxGynQamJDYnUSMVFw461cI50sb32kSIZLvrIPfQofO+NDIBAQAAAAAAABDvfMZsVM0Ba2IrBIspp3IAAAAAAgAYAFMAVABDAEwATwBVAEQAUwBUAEEAVABFAAEADgBTAEMAUwBVADEANABDAAQALgBjAGEAbQBwAHUAcwAuAHMAdABjAGwAbwB1AGQAcwB0AGEAdABlAC4AZQBkAHUAAwA+AHMAYwBzAHUAMQA0AGMALgBjAGEAbQBwAHUAcwAuAHMAdABjAGwAbwB1AGQAcwB0AGEAdABlAC4AZQBkAHUABQAuAGMAYQBtAHAAdQBzAC4AcwB0AGMAbABvAHUAZABzAHQAYQB0AGUALgBlAGQAdQAHAAgAMjb1xWxUzQEAAAAAAAAAAFMAVABDAEwATwBVAEQAUwBUAEEAVABFAHUAbgBpAGMAbwBuAFAAQQBSAEEATQBEAE8ARQBTAE4ATwBUAFMARQBFAE0AVABPAE0AQQBUAFQARQBSAA==
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,691 http.wire.[] - >> "<SOAP-ENV:Envelope xmlns:SOAP-ENV="http://schemas.xmlsoap.org/soap/envelope/"><SOAP-ENV:Header/><SOAP-ENV:Body><ns2:GetUserAvailabilityRequest xmlns:ns2="http://schemas.microsoft.com/exchange/services/2006/messages" xmlns:ns3="http://schemas.microsoft.com/exchange/services/2006/types"><ns3:TimeZone><ns3:Bias>0</ns3:Bias><ns3:StandardTime><ns3:Bias>0</ns3:Bias><ns3:Time>02:00:00</ns3:Time><ns3:DayOrder>1</ns3:DayOrder><ns3:Month>11</ns3:Month><ns3:DayOfWeek>Sunday</ns3:DayOfWeek></ns3:StandardTime><ns3:DaylightTime><ns3:Bias>0</ns3:Bias><ns3:Time>02:00:00</ns3:Time><ns3:DayOrder>1</ns3:DayOrder><ns3:Month>3</ns3:Month><ns3:DayOfWeek>Sunday</ns3:DayOfWeek></ns3:DaylightTime></ns3:TimeZone><ns2:MailboxDataArray><ns3:MailboxData><ns3:Email><ns3:Name/><ns3:Address>unicon@stcloudstate.edu</ns3:Address></ns3:Email><ns3:AttendeeType>Required</ns3:AttendeeType><ns3:ExcludeConflicts>false</ns3:ExcludeConflicts></ns3:MailboxData></ns2:MailboxDataArray><ns3:FreeBusyViewOptions><ns3:TimeWindow><ns3:StartTime>2012-06-27T00:00:00.000</ns3:StartTime><ns3:EndTime>2012-07-04T00:00:00.000</ns3:EndTime></ns3:TimeWindow><ns3:MergedFreeBusyIntervalInMinutes>60</ns3:MergedFreeBusyIntervalInMinutes><ns3:RequestedView>DetailedMerged</ns3:RequestedView></ns3:FreeBusyViewOptions></ns2:GetUserAvailabilityRequest></SOAP-ENV:Body></SOAP-ENV:Envelope>"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,731 http.wire.[] - << "HTTP/1.1 500 Internal Server Error[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,731 http.wire.[] - << "Cache-Control: private[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,731 http.wire.[] - << "Transfer-Encoding: chunked[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,731 http.wire.[] - << "Content-Type: text/xml; charset=utf-8[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,731 http.wire.[] - << "Server: Microsoft-IIS/7.5[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,731 http.wire.[] - << "X-AspNet-Version: 2.0.50727[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,731 http.wire.[] - << "Persistent-Auth: true[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,731 http.wire.[] - << "X-Powered-By: ASP.NET[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,731 http.wire.[] - << "Date: Wed, 27 Jun 2012 13:57:21 GMT[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,731 http.wire.[] - << "[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,731 conn.DefaultClientConnection.[] - Receiving response: HTTP/1.1 500 Internal Server Error
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,731 http.headers.[] - << HTTP/1.1 500 Internal Server Error
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,731 http.headers.[] - << Cache-Control: private
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,731 http.headers.[] - << Transfer-Encoding: chunked
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,731 http.headers.[] - << Content-Type: text/xml; charset=utf-8
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,731 http.headers.[] - << Server: Microsoft-IIS/7.5
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,731 http.headers.[] - << X-AspNet-Version: 2.0.50727
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,731 http.headers.[] - << Persistent-Auth: true
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,731 http.headers.[] - << X-Powered-By: ASP.NET
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,731 http.headers.[] - << Date: Wed, 27 Jun 2012 13:57:21 GMT
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,731 exchange.NtlmAuthHttpClient.[] - Connection can be kept alive indefinitely
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,731 http.wire.[] - << "247[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,731 http.wire.[] - << "<?xml version="1.0" encoding="utf-8"?><s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/"><s:Body><s:Fault><faultcode xmlns:a="http://schemas.microsoft.com/exchange/services/2006/types">a:ErrorInvalidRequest</faultcode><faultstring xml:lang="en-US">The request is invalid.</faultstring><detail><e:ResponseCode xmlns:e="http://schemas.microsoft.com/exchange/services/2006/errors">ErrorInvalidRequest</e:ResponseCode><e:Message xmlns:e="http://schemas.microsoft.com/exchange/services/2006/errors">The request is invalid.</e:Message></detail></s:Fault></s:Body></s:Envelope>"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,731 http.wire.[] - << "[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,731 http.wire.[] - << "0[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,731 http.wire.[] - << "[\r][\n]"
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,731 tsccm.ThreadSafeClientConnManager.[] - Released connection is reusable.
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,731 tsccm.ConnPoolByRoute.[] - Releasing connection [HttpRoute[{s}->https://mail.stcloudstate.edu]][STCLOUDSTATE/unicon]
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,731 tsccm.ConnPoolByRoute.[] - Pooling connection [HttpRoute[{s}->https://mail.stcloudstate.edu]][STCLOUDSTATE/unicon]; keep alive indefinitely
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,731 tsccm.ConnPoolByRoute.[] - Notifying no-one, there are no waiting threads
TRACE [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,746 MessageTracing.received.[] - Received response [<?xml version="1.0" encoding="utf-8"?><s:Envelope xmlns:s="http://schemas.xmlsoap.org/soap/envelope/"><s:Body><s:Fault><faultcode xmlns:a="http://schemas.microsoft.com/exchange/services/2006/types">a:ErrorInvalidRequest</faultcode><faultstring xml:lang="en-US">The request is invalid.</faultstring><detail><e:ResponseCode xmlns:e="http://schemas.microsoft.com/exchange/services/2006/errors">ErrorInvalidRequest</e:ResponseCode><e:Message xmlns:e="http://schemas.microsoft.com/exchange/services/2006/errors">The request is invalid.</e:Message></detail></s:Fault></s:Body></s:Envelope>] for request [<SOAP-ENV:Envelope xmlns:SOAP-ENV="http://schemas.xmlsoap.org/soap/envelope/"><SOAP-ENV:Header/><SOAP-ENV:Body><ns2:GetUserAvailabilityRequest xmlns:ns2="http://schemas.microsoft.com/exchange/services/2006/messages" xmlns:ns3="http://schemas.microsoft.com/exchange/services/2006/types"><ns3:TimeZone><ns3:Bias>0</ns3:Bias><ns3:StandardTime><ns3:Bias>0</ns3:Bias><ns3:Time>02:00:00</ns3:Time><ns3:DayOrder>1</ns3:DayOrder><ns3:Month>11</ns3:Month><ns3:DayOfWeek>Sunday</ns3:DayOfWeek></ns3:StandardTime><ns3:DaylightTime><ns3:Bias>0</ns3:Bias><ns3:Time>02:00:00</ns3:Time><ns3:DayOrder>1</ns3:DayOrder><ns3:Month>3</ns3:Month><ns3:DayOfWeek>Sunday</ns3:DayOfWeek></ns3:DaylightTime></ns3:TimeZone><ns2:MailboxDataArray><ns3:MailboxData><ns3:Email><ns3:Name/><ns3:Address>unicon@stcloudstate.edu</ns3:Address></ns3:Email><ns3:AttendeeType>Required</ns3:AttendeeType><ns3:ExcludeConflicts>false</ns3:ExcludeConflicts></ns3:MailboxData></ns2:MailboxDataArray><ns3:FreeBusyViewOptions><ns3:TimeWindow><ns3:StartTime>2012-06-27T00:00:00.000</ns3:StartTime><ns3:EndTime>2012-07-04T00:00:00.000</ns3:EndTime></ns3:TimeWindow><ns3:MergedFreeBusyIntervalInMinutes>60</ns3:MergedFreeBusyIntervalInMinutes><ns3:RequestedView>DetailedMerged</ns3:RequestedView></ns3:FreeBusyViewOptions></ns2:GetUserAvailabilityRequest></SOAP-ENV:Body></SOAP-ENV:Envelope>]
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,746 core.WebServiceTemplate.[] - Received Fault message for request [SaajSoapMessage {http://schemas.microsoft.com/exchange/services/2006/messages}GetUserAvailabilityRequest]
WARN [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,746 controller.AjaxCalendarController.[] - Unknown Error
org.springframework.ws.soap.client.SoapFaultClientException: The request is invalid.
at org.springframework.ws.soap.client.core.SoapFaultMessageResolver.resolveFault(SoapFaultMessageResolver.java:37)
at org.springframework.ws.client.core.WebServiceTemplate.handleFault(WebServiceTemplate.java:774)
at org.springframework.ws.client.core.WebServiceTemplate.doSendAndReceive(WebServiceTemplate.java:600)
at org.springframework.ws.client.core.WebServiceTemplate.sendAndReceive(WebServiceTemplate.java:537)
at org.springframework.ws.client.core.WebServiceTemplate.marshalSendAndReceive(WebServiceTemplate.java:384)
at org.springframework.ws.client.core.WebServiceTemplate.marshalSendAndReceive(WebServiceTemplate.java:378)
at org.jasig.portlet.calendar.adapter.ExchangeCalendarAdapter.retrieveExchangeEvents(ExchangeCalendarAdapter.java:171)
at org.jasig.portlet.calendar.adapter.ExchangeCalendarAdapter.getEvents(ExchangeCalendarAdapter.java:132)
at org.jasig.portlet.calendar.adapter.CalendarEventsDao.getEvents(CalendarEventsDao.java:74)
at org.jasig.portlet.calendar.mvc.controller.AjaxCalendarController.getEventList(AjaxCalendarController.java:127)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at org.springframework.web.bind.annotation.support.HandlerMethodInvoker.invokeHandlerMethod(HandlerMethodInvoker.java:176)
at org.springframework.web.portlet.mvc.annotation.AnnotationMethodHandlerAdapter.invokeHandlerMethod(AnnotationMethodHandlerAdapter.java:358)
at org.springframework.web.portlet.mvc.annotation.AnnotationMethodHandlerAdapter.doHandle(AnnotationMethodHandlerAdapter.java:345)
at org.springframework.web.portlet.mvc.annotation.AnnotationMethodHandlerAdapter.handleResource(AnnotationMethodHandlerAdapter.java:293)
at org.springframework.web.portlet.DispatcherPortlet.doResourceService(DispatcherPortlet.java:825)
at org.springframework.web.portlet.FrameworkPortlet.processRequest(FrameworkPortlet.java:525)
at org.springframework.web.portlet.FrameworkPortlet.serveResource(FrameworkPortlet.java:477)
at org.jasig.portal.portlet.container.FilterChainImpl.doFilter(FilterChainImpl.java:212)
at org.jasig.portal.portlet.container.FilterChainImpl.processFilter(FilterChainImpl.java:84)
at org.jasig.portal.portlet.container.FilterManagerImpl.processFilter(FilterManagerImpl.java:103)
at org.apache.pluto.container.driver.PortletServlet.dispatch(PortletServlet.java:350)
at org.apache.pluto.container.driver.PortletServlet.doGet(PortletServlet.java:261)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:690)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:803)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
at org.apache.catalina.core.ApplicationDispatcher.invoke(ApplicationDispatcher.java:630)
at org.apache.catalina.core.ApplicationDispatcher.processRequest(ApplicationDispatcher.java:436)
at org.apache.catalina.core.ApplicationDispatcher.doForward(ApplicationDispatcher.java:374)
at org.apache.catalina.core.ApplicationDispatcher.forward(ApplicationDispatcher.java:302)
at org.apache.pluto.driver.container.DefaultPortletInvokerService.invoke(DefaultPortletInvokerService.java:229)
at org.apache.pluto.driver.container.DefaultPortletInvokerService.serveResource(DefaultPortletInvokerService.java:149)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:318)
at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
at org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:90)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at org.springframework.aop.aspectj.MethodInvocationProceedingJoinPoint.proceed(MethodInvocationProceedingJoinPoint.java:80)
at org.jasig.portal.portlet.dao.jpa.ThreadContextClassLoaderAspect.doThreadContextClassLoaderUpdate(ThreadContextClassLoaderAspect.java:63)
at sun.reflect.GeneratedMethodAccessor100.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at org.springframework.aop.aspectj.AbstractAspectJAdvice.invokeAdviceMethodWithGivenArgs(AbstractAspectJAdvice.java:621)
at org.springframework.aop.aspectj.AbstractAspectJAdvice.invokeAdviceMethod(AbstractAspectJAdvice.java:610)
at org.springframework.aop.aspectj.AspectJAroundAdvice.invoke(AspectJAroundAdvice.java:65)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:202)
at $Proxy210.serveResource(Unknown Source)
at org.apache.pluto.container.impl.PortletContainerImpl.doServeResource(PortletContainerImpl.java:203)
at org.jasig.portal.portlet.rendering.PortletRendererImpl.doServeResource(PortletRendererImpl.java:598)
at org.jasig.portal.portlet.rendering.worker.PortletResourceExecutionWorker.callInternal(PortletResourceExecutionWorker.java:54)
at org.jasig.portal.portlet.rendering.worker.PortletResourceExecutionWorker.callInternal(PortletResourceExecutionWorker.java:32)
at org.jasig.portal.portlet.rendering.worker.PortletExecutionWorker$1.call(PortletExecutionWorker.java:165)
at org.jasig.portal.portlet.rendering.worker.PortletExecutionCallable.call(PortletExecutionCallable.java:119)
at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:303)
at java.util.concurrent.FutureTask.run(FutureTask.java:138)
at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:662)
DEBUG [uPortal-PortletExecutor-7-RESOURCE-[exchange-calendar]] Jun/27 06:57:22,746 controller.AjaxCalendarController.[] - AjaxCalendarController took 388 ms to produce JSON model
DEBUG [Thread-90] Jun/27 07:26:28,672 tsccm.ThreadSafeClientConnManager.[] - Shutting down
DEBUG [Thread-90] Jun/27 07:26:28,673 tsccm.ConnPoolByRoute.[] - Closing connection [HttpRoute[{s}->https://mail.stcloudstate.edu]][STCLOUDSTATE/unicon]
DEBUG [Thread-90] Jun/27 07:26:28,673 conn.DefaultClientConnection.[] - Connection closed
DEBUG [Thread-90] Jun/27 07:26:28,673 tsccm.ConnPoolByRoute.[] - Closing connection [HttpRoute[{s}->https://mail.stcloudstate.edu]][STCLOUDSTATE/unicon]
DEBUG [Thread-90] Jun/27 07:26:28,673 conn.DefaultClientConnection.[] - Connection closed
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment