Send invitation from profplump to pattir ======================================================================= 14:32:53,931 DEBUG [BwSvciFilter] in Obtained BwCallback object 14:32:53,936 DEBUG [CalintfImpl] Begin transaction for 2010-03-16 14:15:41.688 14:32:54,050 DEBUG [RenderAction] Event duration=PT60M 14:32:54,050 DEBUG [RenderAction] Event duration=PT60M 14:32:54,051 DEBUG [RenderAction] Event duration=PT60M 14:32:54,052 DEBUG [RenderAction] Event duration=PT60M 14:32:54,054 INFO [UpdateEventAction] REQUEST:F0F6577875E64E23C273D11E3B0BA046:DemoUserCal:67.224.78.52:http://leeloo.cynicbytrade.com:8080/ucal/event/updateEvent.do?b=de 14:32:54,054 INFO [UpdateEventAction] REFERRER:F0F6577875E64E23C273D11E3B0BA046:DemoUserCal:http://leeloo.cynicbytrade.com:8080/ucal/event/showEditEvent.rdo 14:32:54,054 DEBUG [UpdateEventAction] entry 14:32:54,054 DEBUG [UpdateEventAction] ================================ 14:32:54,054 DEBUG [UpdateEventAction] isPortlet=false 14:32:54,054 DEBUG [UpdateEventAction] ================================ 14:32:54,054 DEBUG [UpdateEventAction] Request parameters - global info and uris 14:32:54,054 DEBUG [UpdateEventAction] getRequestURI = /ucal/event/updateEvent.do 14:32:54,054 DEBUG [UpdateEventAction] getRemoteUser = profplump 14:32:54,054 DEBUG [UpdateEventAction] getRequestedSessionId = F0F6577875E64E23C273D11E3B0BA046 14:32:54,054 DEBUG [UpdateEventAction] HttpUtils.getRequestURL(req) = http://leeloo.cynicbytrade.com:8080/ucal/event/updateEvent.do 14:32:54,054 DEBUG [UpdateEventAction] query=b=de 14:32:54,054 DEBUG [UpdateEventAction] contentlen=1466 14:32:54,054 DEBUG [UpdateEventAction] request=org.apache.catalina.connector.RequestFacade@12c10c5 14:32:54,054 DEBUG [UpdateEventAction] parameters: 14:32:54,054 DEBUG [UpdateEventAction] Request parameters 14:32:54,054 DEBUG [UpdateEventAction] b = "de" 14:32:54,054 DEBUG [UpdateEventAction] endType = "date" 14:32:54,054 DEBUG [UpdateEventAction] newCalPath = "/user/profplump/calendar" 14:32:54,054 DEBUG [UpdateEventAction] summary = "Attempt 6" 14:32:54,054 DEBUG [UpdateEventAction] eventStartDate.dateOnly = "false" 14:32:54,054 DEBUG [UpdateEventAction] eventEndDate.dateOnly = "false" 14:32:54,054 DEBUG [UpdateEventAction] eventStartDate.floating = "false" 14:32:54,054 DEBUG [UpdateEventAction] eventEndDate.floating = "false" 14:32:54,054 DEBUG [UpdateEventAction] eventStartDate.storeUTC = "false" 14:32:54,054 DEBUG [UpdateEventAction] eventEndDate.storeUTC = "false" 14:32:54,054 DEBUG [UpdateEventAction] bwEventWidgetStartDate = "2010-03-18" 14:32:54,054 DEBUG [UpdateEventAction] eventStartDate.year = "2010" 14:32:54,054 DEBUG [UpdateEventAction] eventStartDate.month = "3" 14:32:54,055 DEBUG [UpdateEventAction] eventStartDate.day = "18" 14:32:54,055 DEBUG [UpdateEventAction] eventStartDate.hour = "10" 14:32:54,055 DEBUG [UpdateEventAction] eventStartDate.minute = "0" 14:32:54,055 DEBUG [UpdateEventAction] eventStartDate.tzid = "America/Chicago" 14:32:54,055 DEBUG [UpdateEventAction] bwEventWidgetEndDate = "2010-03-18" 14:32:54,055 DEBUG [UpdateEventAction] eventEndDate.year = "2010" 14:32:54,055 DEBUG [UpdateEventAction] eventEndDate.month = "3" 14:32:54,055 DEBUG [UpdateEventAction] eventEndDate.day = "18" 14:32:54,055 DEBUG [UpdateEventAction] eventEndDate.hour = "11" 14:32:54,055 DEBUG [UpdateEventAction] eventEndDate.minute = "0" 14:32:54,055 DEBUG [UpdateEventAction] eventEndDate.tzid = "America/Chicago" 14:32:54,055 DEBUG [UpdateEventAction] eventEndType = "D" 14:32:54,055 DEBUG [UpdateEventAction] eventDuration.type = "daytime" 14:32:54,055 DEBUG [UpdateEventAction] eventDuration.daysStr = "0" 14:32:54,055 DEBUG [UpdateEventAction] eventDuration.hoursStr = "0" 14:32:54,055 DEBUG [UpdateEventAction] eventDuration.minutesStr = "60" 14:32:54,055 DEBUG [UpdateEventAction] locationUid = "" 14:32:54,055 DEBUG [UpdateEventAction] locationAddress.value = "" 14:32:54,055 DEBUG [UpdateEventAction] eventLink = "" 14:32:54,055 DEBUG [UpdateEventAction] description = " " 14:32:54,055 DEBUG [UpdateEventAction] eventStatus = "CONFIRMED" 14:32:54,055 DEBUG [UpdateEventAction] recurring = "false" 14:32:54,055 DEBUG [UpdateEventAction] interval = "" 14:32:54,055 DEBUG [UpdateEventAction] count = "" 14:32:54,056 DEBUG [UpdateEventAction] until = "" 14:32:54,056 DEBUG [UpdateEventAction] byday = "" 14:32:54,056 DEBUG [UpdateEventAction] bymonthday = "" 14:32:54,056 DEBUG [UpdateEventAction] bymonth = "" 14:32:54,056 DEBUG [UpdateEventAction] byweekno = "" 14:32:54,057 DEBUG [UpdateEventAction] byyearday = "" 14:32:54,057 DEBUG [UpdateEventAction] wkst = "" 14:32:54,057 DEBUG [UpdateEventAction] setpos = "" 14:32:54,057 DEBUG [UpdateEventAction] freq = "NONE" 14:32:54,057 DEBUG [UpdateEventAction] recurCountUntil = "forever" 14:32:54,057 DEBUG [UpdateEventAction] countHolder = "1" 14:32:54,057 DEBUG [UpdateEventAction] bwEventUntilDate = "" 14:32:54,057 DEBUG [UpdateEventAction] bwEventWidgetUntilDate = "2010-03-18" 14:32:54,057 DEBUG [UpdateEventAction] hourlyInterval = "1" 14:32:54,057 DEBUG [UpdateEventAction] dailyInterval = "1" 14:32:54,057 DEBUG [UpdateEventAction] weeklyInterval = "1" 14:32:54,057 DEBUG [UpdateEventAction] weekWkst = "SU" 14:32:54,057 DEBUG [UpdateEventAction] monthlyInterval = "1" 14:32:54,058 DEBUG [UpdateEventAction] yearlyInterval = "1" 14:32:54,058 DEBUG [UpdateEventAction] yearWkst = "SU" 14:32:54,058 DEBUG [UpdateEventAction] eventRdate.date = "2010-03-18" 14:32:54,058 DEBUG [UpdateEventAction] eventRdate.hour = "12" 14:32:54,058 DEBUG [UpdateEventAction] eventRdate.minute = "00" 14:32:54,058 DEBUG [UpdateEventAction] tzid = "America/Chicago" 14:32:54,058 DEBUG [UpdateEventAction] rdates = "" 14:32:54,058 DEBUG [UpdateEventAction] exdates = "" 14:32:54,058 DEBUG [UpdateEventAction] who = "" 14:32:54,058 DEBUG [UpdateEventAction] whoType = "user" 14:32:54,058 DEBUG [UpdateEventAction] setappvar = "accessRightsToggle(basic)" 14:32:54,058 DEBUG [UpdateEventAction] how = "" 14:32:54,059 DEBUG [UpdateEventAction] acl = "" 14:32:54,059 DEBUG [UpdateEventAction] howItem = "R" 14:32:54,059 DEBUG [UpdateEventAction] howItem = "D" 14:32:54,059 DEBUG [UpdateEventAction] howItem = "T" 14:32:54,059 DEBUG [UpdateEventAction] accessRead = "R" 14:32:54,059 DEBUG [UpdateEventAction] accessScheduleDeliver = "D" 14:32:54,059 DEBUG [UpdateEventAction] accessScheduleSend = "T" 14:32:54,059 DEBUG [UpdateEventAction] basicHowItem = "R" 14:32:54,059 DEBUG [UpdateEventAction] submitAndSend = "send" 14:32:54,059 DEBUG [UpdateEventAction] xproperty = "X-BEDEWORK-SUBMITTEDBY:profplump" 14:32:54,059 DEBUG [UpdateEventAction] Set presentation state 14:32:54,060 DEBUG [UpdateEventAction] ------------- Presentation state: action ------- 14:32:54,060 DEBUG [UpdateEventAction] AppRoot: http://localhost:8080/ucalrsrc 14:32:54,060 DEBUG [UpdateEventAction] BrowserResourceRoot: http://leeloo.cynicbytrade.com:8080/ucalrsrc 14:32:54,060 DEBUG [UpdateEventAction] BrowserType: null 14:32:54,060 DEBUG [UpdateEventAction] ContentType: null 14:32:54,060 DEBUG [UpdateEventAction] ContentName: null 14:32:54,060 DEBUG [UpdateEventAction] NoXSLT: false 14:32:54,060 DEBUG [UpdateEventAction] SkinName: null 14:32:54,060 DEBUG [UpdateEventAction] ForceXSLTRefresh: false 14:32:54,060 DEBUG [UpdateEventAction] ForceXSLTRefreshAlways: false 14:32:54,060 DEBUG [UpdateEventAction] ---------------------------------------- 14:32:54,060 DEBUG [UpdateEventAction] getState-- obtainedfrom session 14:32:54,061 DEBUG [UpdateEventAction] getState-- timeout interval = 1800 14:32:54,061 DEBUG [UpdateEventAction] CalSvcI-- Obtained from session for user BwUser{id=425985, account=profplump, created=null, logon=2010-03-16 14:15:41.688, lastAccess=2010-03-16 14:15:41.688, lastModify=null, kind=1, instanceOwner=false} 14:32:54,061 DEBUG [Preferences] trace: Setting locale to en_US 14:32:54,076 WARN [ChangeTable] No entry for index UNKNOWN_PROPERTY name null 14:32:54,076 WARN [ChangeTable] No entry for index CREATOR name CREATOR 14:32:54,076 WARN [ChangeTable] No entry for index OWNER name OWNER 14:32:54,076 WARN [ChangeTable] No entry for index COST name COST 14:32:54,077 WARN [ChangeTable] No entry for index CTAG name CTAG 14:32:54,077 WARN [ChangeTable] No entry for index ETAG name ETAG 14:32:54,077 WARN [ChangeTable] No entry for index COLLECTION name COLLECTION 14:32:54,077 WARN [ChangeTable] No entry for index ENTITY_TYPE name ENTITY_TYPE 14:32:54,077 WARN [ChangeTable] No entry for index LANG name LANGUAGE 14:32:54,077 DEBUG [IcalUtil] ChangeTable{ X-BEDEWORK-SUBMITTEDBY UID: changed XPROP: changed DTSTART: changed DURATION: changed DTEND: changed} 14:32:54,077 DEBUG [IcalUtil] BwEvent{id=-1, owner=/principals/users/profplump, publick=null, creator=/principals/users/profplump, access=null, collection=null, entityType=0, deleted=false, dtstamp=20100316T193254Z, lastmod=20100316T193254Z, created=20100316T193254Z, stag=20100316T193254Z-001a, priority=null, classification=null, dtstart=BwDateTime{DATETIME, dtval=20080212T000000, floating}, dtend=BwDateTime{DATETIME, dtval=20080212T000000, floating}, uid=123456, name=null, getRecurring=false, latestDate=null, organizer=null, sequence=0, scheduleMethod=0, originator=null, scheduleState=0} 14:32:54,179 DEBUG [MessageProcessor] Event ENTITY_ADDED 14:32:54,179 DEBUG [CalintfImpl] New hibernate session for 2010-03-16 14:32:20.44 14:32:54,179 DEBUG [CalintfImpl] Open session for 2010-03-16 14:32:20.44 14:32:54,179 DEBUG [CalintfImpl] Begin transaction for 2010-03-16 14:32:20.44 14:32:54,195 DEBUG [Scheduling] trace: UpdateResult {adding = true, 14:32:54,301 DEBUG [Scheduling] trace: Add event with name In-ff808081-27680b92-0127-6876e368-00000062-20100316T193254Z.ics and summary Attempt 6 14:32:54,307 DEBUG [Scheduling] trace: added recipient mailto:pattir@cynicbytrade.com status = 0 14:32:54,307 DEBUG [Scheduling] trace: added recipient mailto:profplump@cynicbytrade.com status = 0 14:32:54,307 DEBUG [UpdateEventAction] Emitted: property=org.bedework.client.message.schedule.sent ptype=object val(s)=mailto:pattir@cynicbytrade.com 14:32:54,307 DEBUG [UpdateEventAction] Emitted: property=org.bedework.client.message.schedule.sent ptype=object val(s)=mailto:profplump@cynicbytrade.com 14:32:54,311 DEBUG [UpdateEventAction] set new view to ViewTypeI=3 14:32:54,314 DEBUG [UpdateEventAction] Emitted: property=org.bedework.client.message.added.events ptype=int val(s)=1 14:32:54,315 INFO [InoutSched] Received messageEntityQueuedEvent{sysCode=SCHEDULE_QUEUED, dtstamp=20100316T193254Z, sequence=1, indexable=false, name=In-ff808081-27680b92-0127-6876e368-00000062-20100316T193254Z.ics, ownerHref=/principals/users/pattir, inBox=true} 14:32:54,315 DEBUG [InScheduler] trace: InSchedule inbox entry for for principal /principals/users/pattir 14:32:54,316 DEBUG [CalintfImpl] New hibernate session for null 14:32:54,316 DEBUG [CalintfImpl] Open session for null 14:32:54,316 DEBUG [CalintfImpl] Begin transaction for null 14:32:54,320 DEBUG [CalSvc] Authenticated user /principals/users/pattir logged on 14:32:54,322 DEBUG [UpdateEventAction] Emitted: property=org.bedework.client.message.added.events ptype=object val(s)=1 14:32:54,322 DEBUG [UpdateEventAction] set new view to ViewTypeI=3 14:32:54,322 DEBUG [UpdateEventAction] No errors emitted 14:32:54,322 DEBUG [UpdateEventAction] 3 messages emitted 14:32:54,322 DEBUG [UpdateEventAction] exit to success 14:32:54,323 DEBUG [BwSvciFilter] out Obtained BwCallback object 14:32:54,327 DEBUG [CalintfImpl] End transaction for 2010-03-16 14:15:41.688 14:32:54,372 DEBUG [BwIndexLuceneImpl] removed 0 entries for event:024 /user/profplump/calendar067 CAL-ff808081-27680b92-0127-6876e2c8-00000061demobedework@mysite.eduN 14:32:54,375 DEBUG [UserGroupsLdapImpl] Logged into LDAP server, javax.naming.ldap.InitialLdapContext@1596716 14:32:54,373 DEBUG [BwSvciFilter] close Obtained BwCallback object 14:32:54,389 DEBUG [CalintfImpl] End transaction for 2010-03-16 14:32:54.383 14:32:54,400 DEBUG [CalintfImpl] Close for 2010-03-16 14:32:54.383 14:32:54,402 DEBUG [CalintfImpl] New hibernate session for 2010-03-16 14:32:54.383 14:32:54,403 DEBUG [CalintfImpl] Open session for 2010-03-16 14:32:54.383 14:32:54,404 DEBUG [CalintfImpl] Begin transaction for 2010-03-16 14:32:54.383 14:32:54,435 DEBUG [CalintfImpl] End transaction for 2010-03-16 14:32:20.44 14:32:54,457 DEBUG [CalintfImpl] Close for 2010-03-16 14:32:20.44 14:32:54,463 DEBUG [MessageProcessor] Event ENTITY_ADDED 14:32:54,464 DEBUG [CalintfImpl] New hibernate session for null 14:32:54,464 DEBUG [CalintfImpl] Open session for null 14:32:54,471 DEBUG [BwSvciFilter] in Obtained BwCallback object 14:32:54,485 DEBUG [CalintfImpl] Begin transaction for 2010-03-16 14:15:41.688 14:32:54,485 DEBUG [ConfiguredXSLTFilter] XSLTFilter: Accessing filter for http://leeloo.cynicbytrade.com:8080/ucal/main/showMain.rdo GET response class: org.apache.catalina.connector.ResponseFacade 14:32:54,485 DEBUG [ConfiguredXSLTFilter] XSLTFilter: response: org.apache.catalina.connector.ResponseFacade@c60310 14:32:54,486 INFO [RenderAction] REQUEST:F0F6577875E64E23C273D11E3B0BA046:DemoUserCal:67.224.78.52:http://leeloo.cynicbytrade.com:8080/ucal/main/showMain.rdo 14:32:54,486 INFO [RenderAction] REFERRER:F0F6577875E64E23C273D11E3B0BA046:DemoUserCal:http://leeloo.cynicbytrade.com:8080/ucal/event/showEditEvent.rdo 14:32:54,486 DEBUG [RenderAction] entry 14:32:54,486 DEBUG [RenderAction] ================================ 14:32:54,486 DEBUG [RenderAction] isPortlet=false 14:32:54,486 DEBUG [RenderAction] ================================ 14:32:54,486 DEBUG [RenderAction] Request parameters - global info and uris 14:32:54,486 DEBUG [RenderAction] getRequestURI = /ucal/main/showMain.rdo 14:32:54,486 DEBUG [RenderAction] getRemoteUser = profplump 14:32:54,486 DEBUG [RenderAction] getRequestedSessionId = F0F6577875E64E23C273D11E3B0BA046 14:32:54,486 DEBUG [RenderAction] HttpUtils.getRequestURL(req) = http://leeloo.cynicbytrade.com:8080/ucal/main/showMain.rdo 14:32:54,486 DEBUG [RenderAction] query=null 14:32:54,486 DEBUG [RenderAction] contentlen=-1 14:32:54,486 DEBUG [RenderAction] request=org.apache.catalina.connector.RequestFacade@1f34112 14:32:54,486 DEBUG [RenderAction] parameters: 14:32:54,486 DEBUG [RenderAction] Request parameters 14:32:54,486 DEBUG [ConfiguredXSLTFilter] setHeader("Pragma", "No-cache") 14:32:54,486 DEBUG [ConfiguredXSLTFilter] setHeader("Cache-Control", "no-cache") 14:32:54,486 DEBUG [RenderAction] Set presentation state 14:32:54,486 DEBUG [RenderAction] ------------- Presentation state: action ------- 14:32:54,486 DEBUG [RenderAction] AppRoot: http://localhost:8080/ucalrsrc 14:32:54,486 DEBUG [RenderAction] BrowserResourceRoot: http://leeloo.cynicbytrade.com:8080/ucalrsrc 14:32:54,487 DEBUG [RenderAction] BrowserType: null 14:32:54,487 DEBUG [RenderAction] ContentType: null 14:32:54,487 DEBUG [RenderAction] ContentName: null 14:32:54,487 DEBUG [RenderAction] NoXSLT: false 14:32:54,487 DEBUG [RenderAction] SkinName: null 14:32:54,487 DEBUG [RenderAction] ForceXSLTRefresh: false 14:32:54,487 DEBUG [RenderAction] ForceXSLTRefreshAlways: false 14:32:54,487 DEBUG [RenderAction] ---------------------------------------- 14:32:54,487 DEBUG [ConfiguredXSLTFilter] XSLTFilter: Converting xml to html 14:32:54,487 DEBUG [RenderAction] getState-- obtainedfrom session 14:32:54,487 DEBUG [RenderAction] getState-- timeout interval = 1800 14:32:54,487 DEBUG [RenderAction] CalSvcI-- Obtained from session for user BwUser{id=425985, account=profplump, created=null, logon=2010-03-16 14:15:41.688, lastAccess=2010-03-16 14:15:41.688, lastModify=null, kind=1, instanceOwner=false} 14:32:54,487 DEBUG [Preferences] trace: Setting locale to en_US 14:32:54,487 DEBUG [ConfiguredXSLTFilter] setHeader("Refresh", "300; URL=http://leeloo.cynicbytrade.com:8080/ucal/setup.do") 14:32:54,505 DEBUG [RenderAction] No errors emitted 14:32:54,505 DEBUG [RenderAction] No messages emitted 14:32:54,505 DEBUG [RenderAction] exit to success 14:32:54,507 DEBUG [ConfiguredXSLTFilter] XSLTFilter: Converting xml to html 14:32:54,512 DEBUG [ConfiguredXSLTFilter] getWriter called 14:32:54,508 DEBUG [CalintfImpl] Begin transaction for null 14:32:54,521 DEBUG [CalSvc] Authenticated user pattir logged on 14:32:54,533 DEBUG [CoreEvents] getEvents for start=null end=null 14:32:54,537 DEBUG [CoreEvents] calendar:/user/profplump/Inbox 14:32:54,538 DEBUG [CoreEvents] select distinct ev from org.bedework.calfacade.BwEventObj ev where ((ev.colPath in (:colPaths))) order by ev.dtstart.date 14:32:54,538 DEBUG [CoreEvents] select distinct ev from org.bedework.calfacade.BwEventObj ev where ((ev.colPath in (:colPaths))) order by ev.dtstart.date 14:32:54,537 DEBUG [UserGroupsLdapImpl] Logged into LDAP server, javax.naming.ldap.InitialLdapContext@8c5baf 14:32:54,554 DEBUG [CalintfImpl] End transaction for 2010-03-16 14:32:54.548 14:32:54,564 DEBUG [CalintfImpl] End transaction for 2010-03-16 14:32:54.383 14:32:54,565 DEBUG [CalintfImpl] Close for 2010-03-16 14:32:54.548 14:32:54,567 DEBUG [CalintfImpl] New hibernate session for 2010-03-16 14:32:54.548 14:32:54,567 DEBUG [CalintfImpl] Open session for 2010-03-16 14:32:54.548 14:32:54,567 DEBUG [CalintfImpl] Begin transaction for 2010-03-16 14:32:54.548 14:32:54,573 DEBUG [CoreEvents] Getting (0) Found 0 entries 14:32:54,575 DEBUG [CoreEvents] select distinct ev from org.bedework.calfacade.BwEventAnnotation ev where ((ev.colPath in (:colPaths))) and (ev.override = false) order by ev.dtstart.date 14:32:54,576 DEBUG [CoreEvents] select distinct ev from org.bedework.calfacade.BwEventAnnotation ev where ((ev.colPath in (:colPaths))) and (ev.override = false) order by ev.dtstart.date 14:32:54,576 DEBUG [CalintfImpl] Close for 2010-03-16 14:32:54.383 14:32:54,579 DEBUG [CalintfImpl] New hibernate session for 2010-03-16 14:32:54.383 14:32:54,582 DEBUG [CalintfImpl] Open session for 2010-03-16 14:32:54.383 14:32:54,582 DEBUG [CalintfImpl] Begin transaction for 2010-03-16 14:32:54.383 14:32:54,606 DEBUG [CoreEvents] Getting (1) Found 0 entries 14:32:54,632 DEBUG [CoreEvents] getEvents for start=null end=null 14:32:54,633 DEBUG [CoreEvents] calendar:/user/profplump/Inbox 14:32:54,633 DEBUG [CoreEvents] select distinct ev from org.bedework.calfacade.BwEventObj ev where ((ev.colPath in (:colPaths))) order by ev.dtstart.date 14:32:54,634 DEBUG [CoreEvents] select distinct ev from org.bedework.calfacade.BwEventObj ev where ((ev.colPath in (:colPaths))) order by ev.dtstart.date 14:32:54,638 DEBUG [InScheduler] trace: InSchedule event In-ff808081-27680b92-0127-6876e368-00000062-20100316T193254Z.ics with method 2 14:32:54,661 DEBUG [CoreEvents] Getting (0) Found 0 entries 14:32:54,661 DEBUG [CoreEvents] select distinct ev from org.bedework.calfacade.BwEventAnnotation ev where ((ev.colPath in (:colPaths))) and (ev.override = false) order by ev.dtstart.date 14:32:54,661 DEBUG [CoreEvents] select distinct ev from org.bedework.calfacade.BwEventAnnotation ev where ((ev.colPath in (:colPaths))) and (ev.override = false) order by ev.dtstart.date 14:32:54,681 DEBUG [CoreEvents] Getting (1) Found 0 entries 14:32:54,721 DEBUG [CalintfImpl] End transaction for 2010-03-16 14:32:54.548 14:32:54,728 DEBUG [CalintfImpl] Close for 2010-03-16 14:32:54.548 14:32:54,731 DEBUG [CalintfImpl] New hibernate session for 2010-03-16 14:32:54.548 14:32:54,742 DEBUG [CalintfImpl] Open session for 2010-03-16 14:32:54.548 14:32:54,742 DEBUG [CalintfImpl] Begin transaction for 2010-03-16 14:32:54.548 14:32:54,842 DEBUG [BwIndexLuceneImpl] removed 0 entries for event:018 /user/pattir/Inbox067 CAL-ff808081-27680b92-0127-6876e2c8-00000061demobedework@mysite.eduN 14:32:54,943 DEBUG [CalintfImpl] End transaction for 2010-03-16 14:32:54.548 14:32:54,950 DEBUG [CalintfImpl] Close for 2010-03-16 14:32:54.548 14:32:54,957 DEBUG [MonthView] getFirstDayOfWeek() = 1 14:32:54,958 DEBUG [MonthView] gtpi.first.getFirstDayOfWeek() = 1 14:32:54,959 DEBUG [MonthView] dayOfWeek=2 dayNum = 1 14:32:54,966 DEBUG [MonthView] dayNum = 2 14:32:54,967 DEBUG [MonthView] dayOfWeek=1 dayNum = 1 14:32:54,972 DEBUG [MonthView] dayOfWeek=1 dayNum = 1 14:32:54,973 DEBUG [MonthView] dayOfWeek=1 dayNum = 1 14:32:54,977 DEBUG [MonthView] dayOfWeek=1 dayNum = 1 14:32:54,966 DEBUG [MessageProcessor] Event ENTITY_UPDATED 14:32:54,987 DEBUG [CalintfImpl] New hibernate session for null 14:32:54,987 DEBUG [CalintfImpl] Open session for null 14:32:54,987 DEBUG [CalintfImpl] Begin transaction for null 14:32:54,990 DEBUG [CalSvc] Authenticated user profplump logged on 14:32:55,006 DEBUG [UserGroupsLdapImpl] Logged into LDAP server, javax.naming.ldap.InitialLdapContext@1ccad6 14:32:55,009 DEBUG [CoreEvents] getEvents for start=BwDateTime{DATE, tzid=America/Chicago, dtval=20100301, UTC=20100301T060000Z} end=BwDateTime{DATE, tzid=America/Chicago, dtval=20100401, UTC=20100401T050000Z} 14:32:55,015 DEBUG [CoreEvents] select distinct ev from org.bedework.calfacade.BwEventObj ev where ((ev.dtstart.floatFlag=true and (ev.dtstart.date < :toFltDate) and ((ev.dtend.date > :fromFltDate) or ((ev.dtstart.date=ev.dtend.date) and (ev.dtend.date >= :fromFltDate)))) or (ev.dtstart.floatFlag is null and (ev.dtstart.date < :toDate) and ((ev.dtend.date > :fromDate) or ((ev.dtstart.date=ev.dtend.date) and (ev.dtend.date >= :fromDate))))) and ev.recurring = false and ((ev.colPath=:fq__0)) order by ev.dtstart.date 14:32:55,016 DEBUG [CoreEvents] select distinct ev from org.bedework.calfacade.BwEventObj ev where ((ev.dtstart.floatFlag=true and (ev.dtstart.date < :toFltDate) and ((ev.dtend.date > :fromFltDate) or ((ev.dtstart.date=ev.dtend.date) and (ev.dtend.date >= :fromFltDate)))) or (ev.dtstart.floatFlag is null and (ev.dtstart.date < :toDate) and ((ev.dtend.date > :fromDate) or ((ev.dtstart.date=ev.dtend.date) and (ev.dtend.date >= :fromDate))))) and ev.recurring = false and ((ev.colPath=:fq__0)) order by ev.dtstart.date 14:32:55,020 DEBUG [CalintfImpl] End transaction for 2010-03-16 14:32:55.01 14:32:55,031 DEBUG [CalintfImpl] Close for 2010-03-16 14:32:55.01 14:32:55,032 DEBUG [CalintfImpl] New hibernate session for 2010-03-16 14:32:55.01 14:32:55,032 DEBUG [CalintfImpl] Open session for 2010-03-16 14:32:55.01 14:32:55,035 DEBUG [CalintfImpl] Begin transaction for 2010-03-16 14:32:55.01 14:32:55,070 DEBUG [InScheduler] trace: InSchedule ScheduleResult{errorCode=null , ignored=false, reschedule=false, ignored=false} 14:32:55,072 DEBUG [CalintfImpl] End transaction for 2010-03-16 14:32:54.383 14:32:55,079 DEBUG [CoreEvents] Getting (0) Found 17 entries 14:32:55,082 DEBUG [CoreEvents] select distinct ev from org.bedework.calfacade.BwEventAnnotation ev where ((ev.dtstart.floatFlag=true and (ev.dtstart.date < :toFltDate) and ((ev.dtend.date > :fromFltDate) or ((ev.dtstart.date=ev.dtend.date) and (ev.dtend.date >= :fromFltDate)))) or (ev.dtstart.floatFlag is null and (ev.dtstart.date < :toDate) and ((ev.dtend.date > :fromDate) or ((ev.dtstart.date=ev.dtend.date) and (ev.dtend.date >= :fromDate))))) and ((ev.colPath=:fq__0)) order by ev.dtstart.date 14:32:55,082 DEBUG [CoreEvents] select distinct ev from org.bedework.calfacade.BwEventAnnotation ev where ((ev.dtstart.floatFlag=true and (ev.dtstart.date < :toFltDate) and ((ev.dtend.date > :fromFltDate) or ((ev.dtstart.date=ev.dtend.date) and (ev.dtend.date >= :fromFltDate)))) or (ev.dtstart.floatFlag is null and (ev.dtstart.date < :toDate) and ((ev.dtend.date > :fromDate) or ((ev.dtstart.date=ev.dtend.date) and (ev.dtend.date >= :fromDate))))) and ((ev.colPath=:fq__0)) order by ev.dtstart.date 14:32:55,089 DEBUG [CoreEvents] Getting (1) Found 0 entries 14:32:55,090 DEBUG [CoreEvents] select distinct ev.master from org.bedework.calfacade.BwRecurrenceInstance ev where ((ev.dtstart.floatFlag=true and (ev.dtstart.date < :toFltDate) and ((ev.dtend.date > :fromFltDate) or ((ev.dtstart.date=ev.dtend.date) and (ev.dtend.date >= :fromFltDate)))) or (ev.dtstart.floatFlag is null and (ev.dtstart.date < :toDate) and ((ev.dtend.date > :fromDate) or ((ev.dtstart.date=ev.dtend.date) and (ev.dtend.date >= :fromDate))))) and ((ev.master.colPath=:fq__0)) order by ev.master.dtstart.date 14:32:55,092 DEBUG [CoreEvents] select distinct ev.master from org.bedework.calfacade.BwRecurrenceInstance ev where ((ev.dtstart.floatFlag=true and (ev.dtstart.date < :toFltDate) and ((ev.dtend.date > :fromFltDate) or ((ev.dtstart.date=ev.dtend.date) and (ev.dtend.date >= :fromFltDate)))) or (ev.dtstart.floatFlag is null and (ev.dtstart.date < :toDate) and ((ev.dtend.date > :fromDate) or ((ev.dtstart.date=ev.dtend.date) and (ev.dtend.date >= :fromDate))))) and ((ev.master.colPath=:fq__0)) order by ev.master.dtstart.date 14:32:55,098 DEBUG [CalintfImpl] Close for 2010-03-16 14:32:54.383 14:32:55,112 DEBUG [CoreEvents] Getting (4) Found 0 entries 14:32:55,136 DEBUG [MonthView] Event passed range 20100309T060000Z-20100310T060000Z with dates 20100309T160000Z-20100309T170000Z: Direct Schedule 1 14:32:55,141 DEBUG [MonthView] Event passed range 20100309T060000Z-20100310T060000Z with dates 20100309T200000Z-20100309T210000Z: Indirect Schedule 1 14:32:55,169 DEBUG [CalintfImpl] End transaction for 2010-03-16 14:32:55.01 14:32:55,172 DEBUG [CalintfImpl] Close for 2010-03-16 14:32:55.01 14:32:55,175 DEBUG [CalintfImpl] New hibernate session for 2010-03-16 14:32:55.01 14:32:55,183 DEBUG [CalintfImpl] Open session for 2010-03-16 14:32:55.01 14:32:55,183 DEBUG [CalintfImpl] Begin transaction for 2010-03-16 14:32:55.01 14:32:55,204 DEBUG [MonthView] Event passed range 20100310T060000Z-20100311T060000Z with dates 20100310T144500Z-20100310T170000Z: Shared Meeting 1 14:32:55,207 DEBUG [MonthView] Event passed range 20100310T060000Z-20100311T060000Z with dates 20100310T173000Z-20100310T190000Z: Shared Meeting 2 14:32:55,238 DEBUG [BwIndexLuceneImpl] removed 1 entries for event:024 /user/profplump/calendar067 CAL-ff808081-27680b92-0127-6876e2c8-00000061demobedework@mysite.eduN 14:32:55,259 DEBUG [MonthView] Event passed range 20100311T060000Z-20100312T060000Z with dates 20100311T170000Z-20100311T180000Z: Shared Meeting 3 14:32:55,285 DEBUG [MonthView] Event passed range 20100312T060000Z-20100313T060000Z with dates 20100312T153000Z-20100312T163000Z: Shared Meeting 6 14:32:55,286 DEBUG [MonthView] Event passed range 20100312T060000Z-20100313T060000Z with dates 20100312T203000Z-20100312T214500Z: Sunbird Meeting 2 14:32:55,290 DEBUG [CalintfImpl] End transaction for 2010-03-16 14:32:55.01 14:32:55,297 DEBUG [CalintfImpl] Close for 2010-03-16 14:32:55.01 14:32:55,303 DEBUG [MessageProcessor] Event ENTITY_UPDATED 14:32:55,305 DEBUG [CalintfImpl] New hibernate session for null 14:32:55,306 DEBUG [CalintfImpl] Open session for null 14:32:55,308 DEBUG [CalintfImpl] Begin transaction for null 14:32:55,317 DEBUG [CalSvc] Authenticated user pattir logged on 14:32:55,323 DEBUG [MonthView] Event passed range 20100313T060000Z-20100314T060000Z with dates 20100313T171500Z-20100313T184500Z: Sunbird Meeting 3 14:32:55,324 DEBUG [MonthView] Event passed range 20100313T060000Z-20100314T060000Z with dates 20100313T200000Z-20100313T210000Z: External Test 1 14:32:55,327 DEBUG [MonthView] Event passed range 20100313T060000Z-20100314T060000Z with dates 20100313T220000Z-20100313T230000Z: Shared Meeting 7 14:32:55,328 DEBUG [UserGroupsLdapImpl] Logged into LDAP server, javax.naming.ldap.InitialLdapContext@1172a3b 14:32:55,343 DEBUG [CalintfImpl] End transaction for 2010-03-16 14:32:55.337 14:32:55,348 DEBUG [CalintfImpl] Close for 2010-03-16 14:32:55.337 14:32:55,349 DEBUG [CalintfImpl] New hibernate session for 2010-03-16 14:32:55.337 14:32:55,349 DEBUG [CalintfImpl] Open session for 2010-03-16 14:32:55.337 14:32:55,349 DEBUG [CalintfImpl] Begin transaction for 2010-03-16 14:32:55.337 14:32:55,411 DEBUG [MonthView] Event passed range 20100315T050000Z-20100316T050000Z with dates 20100315T110000Z-20100315T120000Z: External 3 14:32:55,418 DEBUG [MonthView] Event passed range 20100315T050000Z-20100316T050000Z with dates 20100315T130000Z-20100315T140000Z: Attempt 3 14:32:55,440 DEBUG [CalintfImpl] End transaction for 2010-03-16 14:32:55.337 14:32:55,448 DEBUG [CalintfImpl] Close for 2010-03-16 14:32:55.337 14:32:55,449 DEBUG [CalintfImpl] New hibernate session for 2010-03-16 14:32:55.337 14:32:55,450 DEBUG [CalintfImpl] Open session for 2010-03-16 14:32:55.337 14:32:55,450 DEBUG [CalintfImpl] Begin transaction for 2010-03-16 14:32:55.337 14:32:55,466 DEBUG [MonthView] Event passed range 20100316T050000Z-20100317T050000Z with dates 20100316T110000Z-20100316T120000Z: External 2 14:32:55,493 DEBUG [MonthView] Event passed range 20100317T050000Z-20100318T050000Z with dates 20100317T110000Z-20100317T120000Z: External 4 14:32:55,494 DEBUG [MonthView] Event passed range 20100317T050000Z-20100318T050000Z with dates 20100317T150000Z-20100317T160000Z: Attempt 5 14:32:55,494 DEBUG [BwIndexLuceneImpl] removed 1 entries for event:018 /user/pattir/Inbox067 CAL-ff808081-27680b92-0127-6876e2c8-00000061demobedework@mysite.eduN 14:32:55,534 DEBUG [CalintfImpl] End transaction for 2010-03-16 14:32:55.337 14:32:55,539 DEBUG [MonthView] Event passed range 20100318T050000Z-20100319T050000Z with dates 20100318T110000Z-20100318T120000Z: Attempt 1 (w/specific user "All" granted) 14:32:55,541 DEBUG [MonthView] Event passed range 20100318T050000Z-20100319T050000Z with dates 20100318T150000Z-20100318T160000Z: Attempt 6 14:32:55,547 DEBUG [CalintfImpl] Close for 2010-03-16 14:32:55.337 14:32:55,566 DEBUG [ConfiguredXSLTFilter] getWriter called 14:32:55,566 INFO [ConfiguredXSLTFilter] PRETRANSFORM:F0F6577875E64E23C273D11E3B0BA046:1081 14:32:55,566 DEBUG [ConfiguredXSLTFilter] ------------- Presentation state: ConfiguredXSLTFilter ------- 14:32:55,566 DEBUG [ConfiguredXSLTFilter] AppRoot: http://localhost:8080/ucalrsrc 14:32:55,567 DEBUG [ConfiguredXSLTFilter] BrowserResourceRoot: http://leeloo.cynicbytrade.com:8080/ucalrsrc 14:32:55,567 DEBUG [ConfiguredXSLTFilter] BrowserType: null 14:32:55,567 DEBUG [ConfiguredXSLTFilter] ContentType: null 14:32:55,567 DEBUG [ConfiguredXSLTFilter] ContentName: null 14:32:55,567 DEBUG [ConfiguredXSLTFilter] NoXSLT: false 14:32:55,567 DEBUG [ConfiguredXSLTFilter] SkinName: null 14:32:55,567 DEBUG [ConfiguredXSLTFilter] ForceXSLTRefresh: false 14:32:55,567 DEBUG [ConfiguredXSLTFilter] ForceXSLTRefreshAlways: false 14:32:55,567 DEBUG [ConfiguredXSLTFilter] ---------------------------------------- 14:32:55,567 DEBUG [ConfiguredXSLTFilter] About to try with forceDefaultBrowserType=false, forceDefaultSkinName=false, contentType=null 14:32:55,567 DEBUG [ConfiguredXSLTFilter] getXmlTransformer: ideal = http://localhost:8080/ucalrsrc/en_US/default/default.xsl actual = http://localhost:8080/ucalrsrc/default/default/default.xsl 14:32:55,568 DEBUG [ConfiguredXSLTFilter] Got Transformer OK 14:32:55,568 DEBUG [ConfiguredXSLTFilter] +*+*+*+*+*+*+*+*+*+*+* about to transform: len=124077 14:32:55,568 DEBUG [ConfiguredXSLTFilter] getXmlTransformer: ideal = http://localhost:8080/ucalrsrc/en_US/default/default.xsl actual = http://localhost:8080/ucalrsrc/default/default/default.xsl 14:32:55,611 DEBUG [ConfiguredXSLTFilter] --------- xslt-output property method=xml 14:32:55,611 DEBUG [ConfiguredXSLTFilter] --------- xslt-output property {http://xml.apache.org/xalan}content-handler=org.apache.xml.serializer.ToXMLStream 14:32:55,611 DEBUG [ConfiguredXSLTFilter] --------- xslt-output property doctype-public=-//W3C//DTD XHTML 1.0 Transitional//EN 14:32:55,612 DEBUG [ConfiguredXSLTFilter] --------- xslt-output property encoding=UTF-8 14:32:55,612 DEBUG [ConfiguredXSLTFilter] --------- xslt-output property omit-xml-declaration=yes 14:32:55,612 DEBUG [ConfiguredXSLTFilter] --------- xslt-output property indent=no 14:32:55,612 DEBUG [ConfiguredXSLTFilter] --------- xslt-output property standalone=yes 14:32:55,612 DEBUG [ConfiguredXSLTFilter] --------- xslt-output property {http://xml.apache.org/xalan}entities=org/apache/xml/serializer/XMLEntities 14:32:55,612 DEBUG [ConfiguredXSLTFilter] --------- xslt-output property version=1.0 14:32:55,612 DEBUG [ConfiguredXSLTFilter] --------- xslt-output property {http://xml.apache.org/xalan}indent-amount=0 14:32:55,612 DEBUG [ConfiguredXSLTFilter] --------- xslt-output property media-type=text/html 14:32:55,612 DEBUG [ConfiguredXSLTFilter] --------- xslt-output property doctype-system=http://www.w3.org/TR/xhtml1/DTD/xhtml1-transitional.dtd 14:32:55,612 DEBUG [ConfiguredXSLTFilter] Stylesheet set media-type to text/html 14:32:56,025 DEBUG [ConfiguredXSLTFilter] XML -> HTML conversion completed 14:32:56,025 INFO [ConfiguredXSLTFilter] POSTTRANSFORM:F0F6577875E64E23C273D11E3B0BA046:1540 14:32:56,025 DEBUG [BwSvciFilter] out Obtained BwCallback object 14:32:56,030 DEBUG [CalintfImpl] flush for 2010-03-16 14:15:41.688 14:32:56,030 DEBUG [CalintfImpl] About to flush 14:32:56,037 DEBUG [CalintfImpl] End transaction for 2010-03-16 14:15:41.688 14:32:56,041 DEBUG [BwSvciFilter] close Obtained BwCallback object 14:32:56,042 DEBUG [CalintfImpl] Close for 2010-03-16 14:15:41.688