Accept invitation in pattir Inbox ======================================================================= 14:34:05,368 DEBUG [BwSvciFilter] in Obtained BwCallback object 14:34:05,370 DEBUG [CalintfImpl] Begin transaction for 2010-03-16 14:14:14.615 14:34:05,372 DEBUG [RenderAction] Event duration=PT60M 14:34:05,372 DEBUG [RenderAction] Event duration=PT60M 14:34:05,374 DEBUG [RenderAction] Event duration=PT60M 14:34:05,375 DEBUG [RenderAction] Event duration=PT60M 14:34:05,377 INFO [AttendeeRespond] REQUEST:AFEF029B2FBE97751351B2158C18AD93:DemoUserCal:67.224.78.52:http://leeloo.cynicbytrade.com:8080/ucal/schedule/attendeeRespond.do?b=de 14:34:05,377 INFO [AttendeeRespond] REFERRER:AFEF029B2FBE97751351B2158C18AD93:DemoUserCal:http://leeloo.cynicbytrade.com:8080/ucal/schedule/showAttendeeRespond.rdo 14:34:05,377 DEBUG [AttendeeRespond] entry 14:34:05,377 DEBUG [AttendeeRespond] ================================ 14:34:05,377 DEBUG [AttendeeRespond] isPortlet=false 14:34:05,377 DEBUG [AttendeeRespond] ================================ 14:34:05,377 DEBUG [AttendeeRespond] Request parameters - global info and uris 14:34:05,377 DEBUG [AttendeeRespond] getRequestURI = /ucal/schedule/attendeeRespond.do 14:34:05,377 DEBUG [AttendeeRespond] getRemoteUser = pattir 14:34:05,377 DEBUG [AttendeeRespond] getRequestedSessionId = AFEF029B2FBE97751351B2158C18AD93 14:34:05,377 DEBUG [AttendeeRespond] HttpUtils.getRequestURL(req) = http://leeloo.cynicbytrade.com:8080/ucal/schedule/attendeeRespond.do 14:34:05,378 DEBUG [AttendeeRespond] query=b=de 14:34:05,378 DEBUG [AttendeeRespond] contentlen=524 14:34:05,378 DEBUG [AttendeeRespond] request=org.apache.catalina.connector.RequestFacade@1181630 14:34:05,378 DEBUG [AttendeeRespond] parameters: 14:34:05,378 DEBUG [AttendeeRespond] Request parameters 14:34:05,378 DEBUG [AttendeeRespond] b = "de" 14:34:05,378 DEBUG [AttendeeRespond] updateEvent = "true" 14:34:05,378 DEBUG [AttendeeRespond] endType = "date" 14:34:05,378 DEBUG [AttendeeRespond] method = "REPLY" 14:34:05,378 DEBUG [AttendeeRespond] partstat = "ACCEPTED" 14:34:05,378 DEBUG [AttendeeRespond] delegate = "" 14:34:05,378 DEBUG [AttendeeRespond] eventStartDate.dateOnly = "off" 14:34:05,378 DEBUG [AttendeeRespond] eventEndDate.dateOnly = "off" 14:34:05,378 DEBUG [AttendeeRespond] eventStartDate.month = "3" 14:34:05,378 DEBUG [AttendeeRespond] eventStartDate.day = "18" 14:34:05,378 DEBUG [AttendeeRespond] eventStartDate.year = "2010" 14:34:05,378 DEBUG [AttendeeRespond] eventStartDate.hour = "10" 14:34:05,378 DEBUG [AttendeeRespond] eventStartDate.minute = "0" 14:34:05,378 DEBUG [AttendeeRespond] eventEndDate.month = "3" 14:34:05,378 DEBUG [AttendeeRespond] eventEndDate.day = "18" 14:34:05,378 DEBUG [AttendeeRespond] eventEndDate.year = "2010" 14:34:05,378 DEBUG [AttendeeRespond] eventEndDate.hour = "11" 14:34:05,378 DEBUG [AttendeeRespond] eventEndDate.minute = "0" 14:34:05,378 DEBUG [AttendeeRespond] eventEndType = "D" 14:34:05,378 DEBUG [AttendeeRespond] eventDuration.type = "daytime" 14:34:05,378 DEBUG [AttendeeRespond] eventDuration.daysStr = "0" 14:34:05,378 DEBUG [AttendeeRespond] eventDuration.hoursStr = "0" 14:34:05,378 DEBUG [AttendeeRespond] eventDuration.minutesStr = "60" 14:34:05,378 DEBUG [AttendeeRespond] eventLocationUid = "-1" 14:34:05,378 DEBUG [AttendeeRespond] locationAddress.value = "" 14:34:05,378 DEBUG [AttendeeRespond] submit = "Submit" 14:34:05,379 DEBUG [AttendeeRespond] Set presentation state 14:34:05,379 DEBUG [AttendeeRespond] ------------- Presentation state: action ------- 14:34:05,379 DEBUG [AttendeeRespond] AppRoot: http://localhost:8080/ucalrsrc 14:34:05,379 DEBUG [AttendeeRespond] BrowserResourceRoot: http://leeloo.cynicbytrade.com:8080/ucalrsrc 14:34:05,379 DEBUG [AttendeeRespond] BrowserType: null 14:34:05,379 DEBUG [AttendeeRespond] ContentType: null 14:34:05,379 DEBUG [AttendeeRespond] ContentName: null 14:34:05,379 DEBUG [AttendeeRespond] NoXSLT: false 14:34:05,379 DEBUG [AttendeeRespond] SkinName: null 14:34:05,379 DEBUG [AttendeeRespond] ForceXSLTRefresh: false 14:34:05,379 DEBUG [AttendeeRespond] ForceXSLTRefreshAlways: false 14:34:05,380 DEBUG [AttendeeRespond] ---------------------------------------- 14:34:05,380 DEBUG [AttendeeRespond] getState-- obtainedfrom session 14:34:05,380 DEBUG [AttendeeRespond] getState-- timeout interval = 1800 14:34:05,380 DEBUG [AttendeeRespond] CalSvcI-- Obtained from session for user BwUser{id=425984, account=pattir, created=null, logon=2010-03-16 14:14:14.615, lastAccess=2010-03-16 14:14:14.615, lastModify=null, kind=1, instanceOwner=false} 14:34:05,380 DEBUG [Preferences] trace: Setting locale to en_US 14:34:05,411 DEBUG [AttendeeRespond] Get event by guid found 1 14:34:05,440 DEBUG [AttendeeRespond] Fetch event found BwEvent{id=1048589, owner=/principals/users/profplump, publick=false, creator=/principals/users/profplump, access=null, collection=/user/profplump/calendar, entityType=0, deleted=false, dtstamp=20100316T193254Z, lastmod=20100316T193254Z, created=20100316T193254Z, stag=20100316T193254Z-001b, priority=null, classification=null, dtstart=BwDateTime{DATETIME, tzid=America/Chicago, dtval=20100318T100000, UTC=20100318T150000Z}, dtend=BwDateTime{DATETIME, tzid=America/Chicago, dtval=20100318T110000, UTC=20100318T160000Z}, uid=CAL-ff808081-27680b92-0127-6876e2c8-00000061demobedework@mysite.edu, name=CAL-ff808081-27680b92-0127-6876e2c8-00000061.ics, getRecurring=false, latestDate=null, organizer=BwOrganizer(cn=null, dir=null, language=null, sentBy=null, organizerUri=mailto:profplump@cynicbytrade.com, dtstamp=null, scheduleStatus=null}, recipient=mailto:pattir@cynicbytrade.com, recipient=mailto:profplump@cynicbytrade.com, summary={BwString{id=1081357, lang=null, value=Attempt 6, attendee=BwAttendee{id=1114135, cn=null, cuType=null, delegatedFrom=null, delegatedTo=null, dir=null, language=null, member=null, rsvp=false, role=REQ-PARTICIPANT, partstat=NEEDS-ACTION, sentBy=null, attendeeUri=mailto:pattir@cynicbytrade.com, sequence=0, dtstamp=20100316T193254Z, scheduleAgent=0, scheduleStatus=1.0}, attendee=BwAttendee{id=1114136, cn=null, cuType=null, delegatedFrom=null, delegatedTo=null, dir=null, language=null, member=null, rsvp=false, role=CHAIR, partstat=ACCEPTED, sentBy=null, attendeeUri=mailto:profplump@cynicbytrade.com, sequence=0, dtstamp=20100316T193254Z, scheduleAgent=0, scheduleStatus=1.0}, sequence=0, scheduleMethod=2, originator=mailto:profplump@cynicbytrade.com, scheduleState=0} 14:34:05,475 DEBUG [Scheduling] trace: UpdateResult {adding = false, 14:34:05,476 DEBUG [Scheduling] trace: updatedInstances 14:34:05,476 DEBUG [Scheduling] trace: deletedInstances 14:34:05,476 DEBUG [Scheduling] trace: addedInstances 14:34:05,476 DEBUG [Scheduling] trace: addedAttendees 14:34:05,476 DEBUG [Scheduling] trace: deletedAttendees 14:34:05,479 DEBUG [MessageProcessor] Event ENTITY_UPDATED 14:34:05,582 DEBUG [CalintfImpl] New hibernate session for null 14:34:05,583 DEBUG [CalintfImpl] Open session for null 14:34:05,583 DEBUG [CalintfImpl] Begin transaction for null 14:34:05,586 DEBUG [CalSvc] Authenticated user profplump logged on 14:34:05,611 DEBUG [Scheduling] trace: Add event with name In-ff808081-27680b92-0127-6877f9d6-00000065-20100316T193405Z.ics and summary Attempt 6 14:34:05,616 DEBUG [Scheduling] trace: added recipient mailto:profplump@cynicbytrade.com status = 0 14:34:05,616 DEBUG [AttendeeRespond] Emitted: property=org.bedework.client.message.schedule.sent ptype=object val(s)=mailto:profplump@cynicbytrade.com 14:34:05,616 DEBUG [AttendeeRespond] No errors emitted 14:34:05,617 DEBUG [AttendeeRespond] 1 messages emitted 14:34:05,617 DEBUG [AttendeeRespond] exit to success 14:34:05,617 DEBUG [BwSvciFilter] out Obtained BwCallback object 14:34:05,620 DEBUG [CalintfImpl] flush for 2010-03-16 14:14:14.615 14:34:05,621 DEBUG [CalintfImpl] About to flush 14:34:05,626 DEBUG [CalintfImpl] End transaction for 2010-03-16 14:14:14.615 14:34:05,658 INFO [InoutSched] Received messageEntityQueuedEvent{sysCode=SCHEDULE_QUEUED, dtstamp=20100316T193405Z, sequence=1, indexable=false, name=In-ff808081-27680b92-0127-6877f9d6-00000065-20100316T193405Z.ics, ownerHref=/principals/users/profplump, inBox=true} 14:34:05,659 DEBUG [InScheduler] trace: InSchedule inbox entry for for principal /principals/users/profplump 14:34:05,659 DEBUG [CalintfImpl] New hibernate session for null 14:34:05,659 DEBUG [CalintfImpl] Open session for null 14:34:05,659 DEBUG [CalintfImpl] Begin transaction for null 14:34:05,663 DEBUG [CalSvc] Authenticated user /principals/users/profplump logged on 14:34:05,665 DEBUG [BwSvciFilter] close Obtained BwCallback object 14:34:05,665 DEBUG [CalintfImpl] Close for 2010-03-16 14:14:14.615 14:34:05,669 DEBUG [UserGroupsLdapImpl] Logged into LDAP server, javax.naming.ldap.InitialLdapContext@df2b19 14:34:05,678 DEBUG [CalintfImpl] End transaction for 2010-03-16 14:34:05.672 14:34:05,708 DEBUG [CalintfImpl] Close for 2010-03-16 14:34:05.672 14:34:05,709 DEBUG [CalintfImpl] New hibernate session for 2010-03-16 14:34:05.672 14:34:05,709 DEBUG [CalintfImpl] Open session for 2010-03-16 14:34:05.672 14:34:05,709 DEBUG [CalintfImpl] Begin transaction for 2010-03-16 14:34:05.672 14:34:05,762 DEBUG [BwSvciFilter] in Obtained BwCallback object 14:34:05,767 DEBUG [CalintfImpl] New hibernate session for 2010-03-16 14:14:14.615 14:34:05,767 DEBUG [CalintfImpl] Open session for 2010-03-16 14:14:14.615 14:34:05,767 DEBUG [CalintfImpl] Begin transaction for 2010-03-16 14:14:14.615 14:34:05,768 DEBUG [ConfiguredXSLTFilter] XSLTFilter: Accessing filter for http://leeloo.cynicbytrade.com:8080/ucal/main/showMain.rdo GET response class: org.apache.catalina.connector.ResponseFacade 14:34:05,768 DEBUG [ConfiguredXSLTFilter] XSLTFilter: response: org.apache.catalina.connector.ResponseFacade@9e0fb0 14:34:05,768 INFO [RenderAction] REQUEST:AFEF029B2FBE97751351B2158C18AD93:DemoUserCal:67.224.78.52:http://leeloo.cynicbytrade.com:8080/ucal/main/showMain.rdo 14:34:05,768 INFO [RenderAction] REFERRER:AFEF029B2FBE97751351B2158C18AD93:DemoUserCal:http://leeloo.cynicbytrade.com:8080/ucal/schedule/showAttendeeRespond.rdo 14:34:05,768 DEBUG [RenderAction] entry 14:34:05,768 DEBUG [RenderAction] ================================ 14:34:05,768 DEBUG [RenderAction] isPortlet=false 14:34:05,768 DEBUG [RenderAction] ================================ 14:34:05,768 DEBUG [RenderAction] Request parameters - global info and uris 14:34:05,768 DEBUG [RenderAction] getRequestURI = /ucal/main/showMain.rdo 14:34:05,768 DEBUG [RenderAction] getRemoteUser = pattir 14:34:05,768 DEBUG [RenderAction] getRequestedSessionId = AFEF029B2FBE97751351B2158C18AD93 14:34:05,769 DEBUG [RenderAction] HttpUtils.getRequestURL(req) = http://leeloo.cynicbytrade.com:8080/ucal/main/showMain.rdo 14:34:05,769 DEBUG [RenderAction] query=null 14:34:05,769 DEBUG [RenderAction] contentlen=-1 14:34:05,769 DEBUG [RenderAction] request=org.apache.catalina.connector.RequestFacade@1181630 14:34:05,769 DEBUG [RenderAction] parameters: 14:34:05,769 DEBUG [RenderAction] Request parameters 14:34:05,769 DEBUG [ConfiguredXSLTFilter] setHeader("Pragma", "No-cache") 14:34:05,769 DEBUG [ConfiguredXSLTFilter] setHeader("Cache-Control", "no-cache") 14:34:05,769 DEBUG [RenderAction] Set presentation state 14:34:05,769 DEBUG [RenderAction] ------------- Presentation state: action ------- 14:34:05,769 DEBUG [RenderAction] AppRoot: http://localhost:8080/ucalrsrc 14:34:05,769 DEBUG [RenderAction] BrowserResourceRoot: http://leeloo.cynicbytrade.com:8080/ucalrsrc 14:34:05,769 DEBUG [RenderAction] BrowserType: null 14:34:05,769 DEBUG [RenderAction] ContentType: null 14:34:05,769 DEBUG [RenderAction] ContentName: null 14:34:05,769 DEBUG [RenderAction] NoXSLT: false 14:34:05,769 DEBUG [RenderAction] SkinName: null 14:34:05,769 DEBUG [RenderAction] ForceXSLTRefresh: false 14:34:05,769 DEBUG [RenderAction] ForceXSLTRefreshAlways: false 14:34:05,769 DEBUG [RenderAction] ---------------------------------------- 14:34:05,769 DEBUG [ConfiguredXSLTFilter] XSLTFilter: Converting xml to html 14:34:05,769 DEBUG [RenderAction] getState-- obtainedfrom session 14:34:05,769 DEBUG [RenderAction] getState-- timeout interval = 1800 14:34:05,769 DEBUG [RenderAction] CalSvcI-- Obtained from session for user BwUser{id=425984, account=pattir, created=null, logon=2010-03-16 14:14:14.615, lastAccess=2010-03-16 14:14:14.615, lastModify=null, kind=1, instanceOwner=false} 14:34:05,782 DEBUG [Preferences] trace: Setting locale to en_US 14:34:05,783 DEBUG [ConfiguredXSLTFilter] setHeader("Refresh", "300; URL=http://leeloo.cynicbytrade.com:8080/ucal/setup.do") 14:34:05,791 DEBUG [RenderAction] No errors emitted 14:34:05,792 DEBUG [RenderAction] No messages emitted 14:34:05,792 DEBUG [RenderAction] exit to success 14:34:05,794 DEBUG [ConfiguredXSLTFilter] XSLTFilter: Converting xml to html 14:34:05,799 DEBUG [ConfiguredXSLTFilter] getWriter called 14:34:05,805 DEBUG [CoreEvents] getEvents for start=null end=null 14:34:05,836 DEBUG [CoreEvents] calendar:/user/pattir/Inbox 14:34:05,836 DEBUG [CoreEvents] select distinct ev from org.bedework.calfacade.BwEventObj ev where ((ev.colPath in (:colPaths))) order by ev.dtstart.date 14:34:05,836 DEBUG [CoreEvents] select distinct ev from org.bedework.calfacade.BwEventObj ev where ((ev.colPath in (:colPaths))) order by ev.dtstart.date 14:34:05,842 DEBUG [CoreEvents] Getting (0) Found 0 entries 14:34:05,843 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:34:05,843 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:34:05,850 DEBUG [CoreEvents] Getting (1) Found 0 entries 14:34:05,856 DEBUG [CoreEvents] getEvents for start=null end=null 14:34:05,856 DEBUG [CoreEvents] calendar:/user/pattir/Inbox 14:34:05,856 DEBUG [CoreEvents] select distinct ev from org.bedework.calfacade.BwEventObj ev where ((ev.colPath in (:colPaths))) order by ev.dtstart.date 14:34:05,856 DEBUG [CoreEvents] select distinct ev from org.bedework.calfacade.BwEventObj ev where ((ev.colPath in (:colPaths))) order by ev.dtstart.date 14:34:05,862 DEBUG [CoreEvents] Getting (0) Found 0 entries 14:34:05,862 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:34:05,862 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:34:05,870 DEBUG [CoreEvents] Getting (1) Found 0 entries 14:34:05,885 DEBUG [CalintfImpl] End transaction for 2010-03-16 14:34:05.672 14:34:05,889 DEBUG [CalintfImpl] Close for 2010-03-16 14:34:05.672 14:34:05,891 DEBUG [CalintfImpl] New hibernate session for 2010-03-16 14:34:05.672 14:34:05,894 DEBUG [CalintfImpl] Open session for 2010-03-16 14:34:05.672 14:34:05,894 DEBUG [CalintfImpl] Begin transaction for 2010-03-16 14:34:05.672 14:34:05,921 DEBUG [UserGroupsLdapImpl] Logged into LDAP server, javax.naming.ldap.InitialLdapContext@15b1d99 14:34:05,930 DEBUG [CalintfImpl] End transaction for 2010-03-16 14:34:05.924 14:34:05,948 ERROR [AbstractFlushingEventListener] Could not synchronize database state with session org.hibernate.StaleObjectStateException: Row was updated or deleted by another transaction (or unsaved-value mapping was incorrect): [org.bedework.calfacade.BwUser#425985] at org.hibernate.persister.entity.AbstractEntityPersister.check(AbstractEntityPersister.java:1792) at org.hibernate.persister.entity.AbstractEntityPersister.update(AbstractEntityPersister.java:2435) at org.hibernate.persister.entity.AbstractEntityPersister.updateOrInsert(AbstractEntityPersister.java:2335) at org.hibernate.persister.entity.AbstractEntityPersister.update(AbstractEntityPersister.java:2635) at org.hibernate.action.EntityUpdateAction.execute(EntityUpdateAction.java:115) at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:279) at org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:263) at org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:168) at org.hibernate.event.def.AbstractFlushingEventListener.performExecutions(AbstractFlushingEventListener.java:321) at org.hibernate.event.def.DefaultFlushEventListener.onFlush(DefaultFlushEventListener.java:50) at org.hibernate.impl.SessionImpl.flush(SessionImpl.java:1027) at org.hibernate.impl.SessionImpl.managedFlush(SessionImpl.java:365) at org.hibernate.transaction.JDBCTransaction.commit(JDBCTransaction.java:137) at org.bedework.calcore.hibernate.HibSessionImpl.commit(HibSessionImpl.java:259) at org.bedework.calcore.hibernate.CalintfImpl.endTransaction(CalintfImpl.java:411) at org.bedework.calsvc.CalSvc.getCal(CalSvc.java:1182) at org.bedework.calsvc.CalSvc.open(CalSvc.java:508) at org.bedework.calsvc.CalSvc.init(CalSvc.java:337) at org.bedework.calsvci.CalSvcFactoryDefault.getSvc(CalSvcFactoryDefault.java:96) at org.bedework.inoutsched.InScheduler.getSvci(InScheduler.java:416) at org.bedework.inoutsched.InScheduler.processMessage(InScheduler.java:83) at org.bedework.inoutsched.InoutSched.action(InoutSched.java:125) at org.bedework.sysevents.listeners.JmsSysEventListener.onMessage(JmsSysEventListener.java:116) at org.bedework.sysevents.listeners.JmsSysEventListener.process(JmsSysEventListener.java:103) at org.bedework.inoutsched.InoutSched.run(InoutSched.java:105) at org.bedework.inoutsched.BwInoutSched$ProcessorThread.run(BwInoutSched.java:50) 14:34:05,955 ERROR [STDERR] org.bedework.calfacade.exc.CalFacadeStaleStateException: org.hibernate.StaleObjectStateException: Row was updated or deleted by another transaction (or unsaved-value mapping was incorrect): [org.bedework.calfacade.BwUser#425985] 14:34:05,955 ERROR [STDERR] at org.bedework.calcore.hibernate.HibSessionImpl.commit(HibSessionImpl.java:267) 14:34:05,955 ERROR [STDERR] at org.bedework.calcore.hibernate.CalintfImpl.endTransaction(CalintfImpl.java:411) 14:34:05,956 ERROR [STDERR] at org.bedework.calsvc.CalSvc.getCal(CalSvc.java:1182) 14:34:05,956 ERROR [STDERR] at org.bedework.calsvc.CalSvc.open(CalSvc.java:508) 14:34:05,956 ERROR [STDERR] at org.bedework.calsvc.CalSvc.init(CalSvc.java:337) 14:34:05,956 ERROR [STDERR] at org.bedework.calsvci.CalSvcFactoryDefault.getSvc(CalSvcFactoryDefault.java:96) 14:34:05,956 ERROR [STDERR] at org.bedework.inoutsched.InScheduler.getSvci(InScheduler.java:416) 14:34:05,956 ERROR [STDERR] at org.bedework.inoutsched.InScheduler.processMessage(InScheduler.java:83) 14:34:05,956 ERROR [STDERR] at org.bedework.inoutsched.InoutSched.action(InoutSched.java:125) 14:34:05,956 ERROR [STDERR] at org.bedework.sysevents.listeners.JmsSysEventListener.onMessage(JmsSysEventListener.java:116) 14:34:05,956 ERROR [STDERR] at org.bedework.sysevents.listeners.JmsSysEventListener.process(JmsSysEventListener.java:103) 14:34:05,956 ERROR [STDERR] at org.bedework.inoutsched.InoutSched.run(InoutSched.java:105) 14:34:05,956 ERROR [STDERR] at org.bedework.inoutsched.BwInoutSched$ProcessorThread.run(BwInoutSched.java:50) 14:34:05,956 ERROR [STDERR] Caused by: org.hibernate.StaleObjectStateException: Row was updated or deleted by another transaction (or unsaved-value mapping was incorrect): [org.bedework.calfacade.BwUser#425985] 14:34:05,957 ERROR [STDERR] at org.hibernate.persister.entity.AbstractEntityPersister.check(AbstractEntityPersister.java:1792) 14:34:05,957 ERROR [STDERR] at org.hibernate.persister.entity.AbstractEntityPersister.update(AbstractEntityPersister.java:2435) 14:34:05,957 ERROR [STDERR] at org.hibernate.persister.entity.AbstractEntityPersister.updateOrInsert(AbstractEntityPersister.java:2335) 14:34:05,957 ERROR [STDERR] at org.hibernate.persister.entity.AbstractEntityPersister.update(AbstractEntityPersister.java:2635) 14:34:05,957 ERROR [STDERR] at org.hibernate.action.EntityUpdateAction.execute(EntityUpdateAction.java:115) 14:34:05,957 ERROR [STDERR] at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:279) 14:34:05,957 ERROR [STDERR] at org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:263) 14:34:05,957 ERROR [STDERR] at org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:168) 14:34:05,957 ERROR [STDERR] at org.hibernate.event.def.AbstractFlushingEventListener.performExecutions(AbstractFlushingEventListener.java:321) 14:34:05,958 ERROR [STDERR] at org.hibernate.event.def.DefaultFlushEventListener.onFlush(DefaultFlushEventListener.java:50) 14:34:05,958 ERROR [STDERR] at org.hibernate.impl.SessionImpl.flush(SessionImpl.java:1027) 14:34:05,958 ERROR [STDERR] at org.hibernate.impl.SessionImpl.managedFlush(SessionImpl.java:365) 14:34:05,958 ERROR [STDERR] at org.hibernate.transaction.JDBCTransaction.commit(JDBCTransaction.java:137) 14:34:05,958 ERROR [STDERR] at org.bedework.calcore.hibernate.HibSessionImpl.commit(HibSessionImpl.java:259) 14:34:05,958 ERROR [STDERR] ... 12 more 14:34:05,958 DEBUG [CalintfImpl] End transaction for 2010-03-16 14:34:05.924 14:34:05,958 DEBUG [CalintfImpl] Close for 2010-03-16 14:34:05.924 14:34:05,958 DEBUG [CalintfImpl] Enter rollback 14:34:05,959 DEBUG [CalintfImpl] About to rollback 14:34:05,964 DEBUG [WeekView] getFirstDayOfWeek() = 1 14:34:05,966 DEBUG [WeekView] gtpi.first.getFirstDayOfWeek() = 1 14:34:05,967 DEBUG [WeekView] dayOfWeek=1 dayNum = 1 14:34:05,965 ERROR [AbstractFlushingEventListener] Could not synchronize database state with session org.hibernate.StaleObjectStateException: Row was updated or deleted by another transaction (or unsaved-value mapping was incorrect): [org.bedework.calfacade.BwUser#425985] at org.hibernate.persister.entity.AbstractEntityPersister.check(AbstractEntityPersister.java:1792) at org.hibernate.persister.entity.AbstractEntityPersister.update(AbstractEntityPersister.java:2435) at org.hibernate.persister.entity.AbstractEntityPersister.updateOrInsert(AbstractEntityPersister.java:2335) at org.hibernate.persister.entity.AbstractEntityPersister.update(AbstractEntityPersister.java:2635) at org.hibernate.action.EntityUpdateAction.execute(EntityUpdateAction.java:115) at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:279) at org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:263) at org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:168) at org.hibernate.event.def.AbstractFlushingEventListener.performExecutions(AbstractFlushingEventListener.java:321) at org.hibernate.event.def.DefaultFlushEventListener.onFlush(DefaultFlushEventListener.java:50) at org.hibernate.impl.SessionImpl.flush(SessionImpl.java:1027) at org.bedework.calcore.hibernate.HibSessionImpl.close(HibSessionImpl.java:1002) at org.bedework.calcore.hibernate.CalintfImpl.close(CalintfImpl.java:374) at org.bedework.calsvc.CalSvc.close(CalSvc.java:523) at org.bedework.calsvc.CalSvc.init(CalSvc.java:407) at org.bedework.calsvci.CalSvcFactoryDefault.getSvc(CalSvcFactoryDefault.java:96) at org.bedework.inoutsched.InScheduler.getSvci(InScheduler.java:416) at org.bedework.inoutsched.InScheduler.processMessage(InScheduler.java:83) at org.bedework.inoutsched.InoutSched.action(InoutSched.java:125) at org.bedework.sysevents.listeners.JmsSysEventListener.onMessage(JmsSysEventListener.java:116) at org.bedework.sysevents.listeners.JmsSysEventListener.process(JmsSysEventListener.java:103) at org.bedework.inoutsched.InoutSched.run(InoutSched.java:105) at org.bedework.inoutsched.BwInoutSched$ProcessorThread.run(BwInoutSched.java:50) 14:34:05,971 ERROR [STDERR] org.bedework.calfacade.exc.CalFacadeStaleStateException: org.hibernate.StaleObjectStateException: Row was updated or deleted by another transaction (or unsaved-value mapping was incorrect): [org.bedework.calfacade.BwUser#425985] 14:34:05,971 ERROR [STDERR] at org.bedework.calcore.hibernate.HibSessionImpl.commit(HibSessionImpl.java:267) 14:34:05,971 ERROR [STDERR] at org.bedework.calcore.hibernate.CalintfImpl.endTransaction(CalintfImpl.java:411) 14:34:05,971 ERROR [STDERR] at org.bedework.calsvc.CalSvc.getCal(CalSvc.java:1182) 14:34:05,971 ERROR [STDERR] at org.bedework.calsvc.CalSvc.open(CalSvc.java:508) 14:34:05,971 ERROR [STDERR] at org.bedework.calsvc.CalSvc.init(CalSvc.java:337) 14:34:05,971 ERROR [STDERR] at org.bedework.calsvci.CalSvcFactoryDefault.getSvc(CalSvcFactoryDefault.java:96) 14:34:05,971 ERROR [STDERR] at org.bedework.inoutsched.InScheduler.getSvci(InScheduler.java:416) 14:34:05,971 ERROR [STDERR] at org.bedework.inoutsched.InScheduler.processMessage(InScheduler.java:83) 14:34:05,971 ERROR [STDERR] at org.bedework.inoutsched.InoutSched.action(InoutSched.java:125) 14:34:05,971 ERROR [STDERR] at org.bedework.sysevents.listeners.JmsSysEventListener.onMessage(JmsSysEventListener.java:116) 14:34:05,972 ERROR [STDERR] at org.bedework.sysevents.listeners.JmsSysEventListener.process(JmsSysEventListener.java:103) 14:34:05,973 ERROR [STDERR] at org.bedework.inoutsched.InoutSched.run(InoutSched.java:105) 14:34:05,973 ERROR [STDERR] at org.bedework.inoutsched.BwInoutSched$ProcessorThread.run(BwInoutSched.java:50) 14:34:05,974 ERROR [STDERR] Caused by: org.hibernate.StaleObjectStateException: Row was updated or deleted by another transaction (or unsaved-value mapping was incorrect): [org.bedework.calfacade.BwUser#425985] 14:34:05,974 ERROR [STDERR] at org.hibernate.persister.entity.AbstractEntityPersister.check(AbstractEntityPersister.java:1792) 14:34:05,974 ERROR [STDERR] at org.hibernate.persister.entity.AbstractEntityPersister.update(AbstractEntityPersister.java:2435) 14:34:05,974 ERROR [STDERR] at org.hibernate.persister.entity.AbstractEntityPersister.updateOrInsert(AbstractEntityPersister.java:2335) 14:34:05,976 ERROR [STDERR] at org.hibernate.persister.entity.AbstractEntityPersister.update(AbstractEntityPersister.java:2635) 14:34:05,977 ERROR [STDERR] at org.hibernate.action.EntityUpdateAction.execute(EntityUpdateAction.java:115) 14:34:05,977 ERROR [STDERR] at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:279) 14:34:05,977 ERROR [STDERR] at org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:263) 14:34:05,977 ERROR [STDERR] at org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:168) 14:34:05,977 ERROR [STDERR] at org.hibernate.event.def.AbstractFlushingEventListener.performExecutions(AbstractFlushingEventListener.java:321) 14:34:05,977 ERROR [STDERR] at org.hibernate.event.def.DefaultFlushEventListener.onFlush(DefaultFlushEventListener.java:50) 14:34:05,977 ERROR [STDERR] at org.hibernate.impl.SessionImpl.flush(SessionImpl.java:1027) 14:34:05,977 ERROR [STDERR] at org.hibernate.impl.SessionImpl.managedFlush(SessionImpl.java:365) 14:34:05,977 ERROR [STDERR] at org.hibernate.transaction.JDBCTransaction.commit(JDBCTransaction.java:137) 14:34:06,009 ERROR [STDERR] at org.bedework.calcore.hibernate.HibSessionImpl.commit(HibSessionImpl.java:259) 14:34:06,010 ERROR [STDERR] ... 12 more 14:34:06,010 ERROR [InScheduler] org.bedework.inoutsched.InScheduler@d8dc9f org.bedework.calfacade.exc.CalFacadeStaleStateException: org.hibernate.StaleObjectStateException: Row was updated or deleted by another transaction (or unsaved-value mapping was incorrect): [org.bedework.calfacade.BwUser#425985] at org.bedework.calcore.hibernate.HibSessionImpl.commit(HibSessionImpl.java:267) at org.bedework.calcore.hibernate.CalintfImpl.endTransaction(CalintfImpl.java:411) at org.bedework.calsvc.CalSvc.getCal(CalSvc.java:1182) at org.bedework.calsvc.CalSvc.open(CalSvc.java:508) at org.bedework.calsvc.CalSvc.init(CalSvc.java:337) at org.bedework.calsvci.CalSvcFactoryDefault.getSvc(CalSvcFactoryDefault.java:96) at org.bedework.inoutsched.InScheduler.getSvci(InScheduler.java:416) at org.bedework.inoutsched.InScheduler.processMessage(InScheduler.java:83) at org.bedework.inoutsched.InoutSched.action(InoutSched.java:125) at org.bedework.sysevents.listeners.JmsSysEventListener.onMessage(JmsSysEventListener.java:116) at org.bedework.sysevents.listeners.JmsSysEventListener.process(JmsSysEventListener.java:103) at org.bedework.inoutsched.InoutSched.run(InoutSched.java:105) at org.bedework.inoutsched.BwInoutSched$ProcessorThread.run(BwInoutSched.java:50) Caused by: org.hibernate.StaleObjectStateException: Row was updated or deleted by another transaction (or unsaved-value mapping was incorrect): [org.bedework.calfacade.BwUser#425985] at org.hibernate.persister.entity.AbstractEntityPersister.check(AbstractEntityPersister.java:1792) at org.hibernate.persister.entity.AbstractEntityPersister.update(AbstractEntityPersister.java:2435) at org.hibernate.persister.entity.AbstractEntityPersister.updateOrInsert(AbstractEntityPersister.java:2335) at org.hibernate.persister.entity.AbstractEntityPersister.update(AbstractEntityPersister.java:2635) at org.hibernate.action.EntityUpdateAction.execute(EntityUpdateAction.java:115) at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:279) at org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:263) at org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:168) at org.hibernate.event.def.AbstractFlushingEventListener.performExecutions(AbstractFlushingEventListener.java:321) at org.hibernate.event.def.DefaultFlushEventListener.onFlush(DefaultFlushEventListener.java:50) at org.hibernate.impl.SessionImpl.flush(SessionImpl.java:1027) at org.hibernate.impl.SessionImpl.managedFlush(SessionImpl.java:365) at org.hibernate.transaction.JDBCTransaction.commit(JDBCTransaction.java:137) at org.bedework.calcore.hibernate.HibSessionImpl.commit(HibSessionImpl.java:259) ... 12 more 14:34:06,015 DEBUG [CoreEvents] getEvents for start=BwDateTime{DATE, tzid=America/Chicago, dtval=20100314, UTC=20100314T060000Z} end=BwDateTime{DATE, tzid=America/Chicago, dtval=20100321, UTC=20100321T050000Z} 14:34:06,019 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:34:06,020 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:34:06,054 DEBUG [BwIndexLuceneImpl] removed 1 entries for event:024 /user/profplump/calendar067 CAL-ff808081-27680b92-0127-6876e2c8-00000061demobedework@mysite.eduN 14:34:06,056 DEBUG [CoreEvents] Getting (0) Found 2 entries 14:34:06,138 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:34:06,138 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:34:06,142 DEBUG [CoreEvents] Getting (1) Found 0 entries 14:34:06,142 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:34:06,142 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:34:06,147 DEBUG [CoreEvents] Getting (4) Found 0 entries 14:34:06,162 DEBUG [WeekView] Event passed range 20100316T050000Z-20100317T050000Z with dates 20100316T150000Z-20100316T160000Z: Attempt 4 14:34:06,133 DEBUG [CalintfImpl] End transaction for 2010-03-16 14:34:05.672 14:34:06,179 DEBUG [CalintfImpl] Close for 2010-03-16 14:34:05.672 14:34:06,181 DEBUG [MessageProcessor] Event ENTITY_ADDED 14:34:06,182 DEBUG [CalintfImpl] New hibernate session for 2010-03-16 14:34:05.672 14:34:06,182 DEBUG [CalintfImpl] Open session for 2010-03-16 14:34:05.672 14:34:06,182 DEBUG [CalintfImpl] Begin transaction for 2010-03-16 14:34:05.672 14:34:06,174 DEBUG [WeekView] Event passed range 20100318T050000Z-20100319T050000Z with dates 20100318T120000Z-20100318T130000Z: Attempt 2 (w/specific user "All" granted) 14:34:06,222 DEBUG [ConfiguredXSLTFilter] getWriter called 14:34:06,229 INFO [ConfiguredXSLTFilter] PRETRANSFORM:AFEF029B2FBE97751351B2158C18AD93:461 14:34:06,230 DEBUG [ConfiguredXSLTFilter] ------------- Presentation state: ConfiguredXSLTFilter ------- 14:34:06,230 DEBUG [ConfiguredXSLTFilter] AppRoot: http://localhost:8080/ucalrsrc 14:34:06,230 DEBUG [ConfiguredXSLTFilter] BrowserResourceRoot: http://leeloo.cynicbytrade.com:8080/ucalrsrc 14:34:06,230 DEBUG [ConfiguredXSLTFilter] BrowserType: null 14:34:06,230 DEBUG [ConfiguredXSLTFilter] ContentType: null 14:34:06,230 DEBUG [ConfiguredXSLTFilter] ContentName: null 14:34:06,230 DEBUG [ConfiguredXSLTFilter] NoXSLT: false 14:34:06,230 DEBUG [ConfiguredXSLTFilter] SkinName: null 14:34:06,230 DEBUG [ConfiguredXSLTFilter] ForceXSLTRefresh: false 14:34:06,231 DEBUG [ConfiguredXSLTFilter] ForceXSLTRefreshAlways: false 14:34:06,231 DEBUG [ConfiguredXSLTFilter] ---------------------------------------- 14:34:06,231 DEBUG [ConfiguredXSLTFilter] About to try with forceDefaultBrowserType=false, forceDefaultSkinName=false, contentType=null 14:34:06,231 DEBUG [ConfiguredXSLTFilter] getXmlTransformer: ideal = http://localhost:8080/ucalrsrc/en_US/default/default.xsl actual = http://localhost:8080/ucalrsrc/default/default/default.xsl 14:34:06,231 DEBUG [ConfiguredXSLTFilter] Got Transformer OK 14:34:06,231 DEBUG [ConfiguredXSLTFilter] +*+*+*+*+*+*+*+*+*+*+* about to transform: len=38474 14:34:06,231 DEBUG [ConfiguredXSLTFilter] getXmlTransformer: ideal = http://localhost:8080/ucalrsrc/en_US/default/default.xsl actual = http://localhost:8080/ucalrsrc/default/default/default.xsl 14:34:06,241 DEBUG [BwIndexLuceneImpl] removed 0 entries for event:021 /user/profplump/Inbox067 CAL-ff808081-27680b92-0127-6876e2c8-00000061demobedework@mysite.eduN 14:34:06,271 DEBUG [ConfiguredXSLTFilter] --------- xslt-output property method=xml 14:34:06,271 DEBUG [ConfiguredXSLTFilter] --------- xslt-output property {http://xml.apache.org/xalan}content-handler=org.apache.xml.serializer.ToXMLStream 14:34:06,274 DEBUG [ConfiguredXSLTFilter] --------- xslt-output property doctype-public=-//W3C//DTD XHTML 1.0 Transitional//EN 14:34:06,274 DEBUG [ConfiguredXSLTFilter] --------- xslt-output property encoding=UTF-8 14:34:06,274 DEBUG [ConfiguredXSLTFilter] --------- xslt-output property omit-xml-declaration=yes 14:34:06,274 DEBUG [ConfiguredXSLTFilter] --------- xslt-output property indent=no 14:34:06,274 DEBUG [ConfiguredXSLTFilter] --------- xslt-output property standalone=yes 14:34:06,274 DEBUG [ConfiguredXSLTFilter] --------- xslt-output property {http://xml.apache.org/xalan}entities=org/apache/xml/serializer/XMLEntities 14:34:06,274 DEBUG [ConfiguredXSLTFilter] --------- xslt-output property version=1.0 14:34:06,275 DEBUG [ConfiguredXSLTFilter] --------- xslt-output property {http://xml.apache.org/xalan}indent-amount=0 14:34:06,275 DEBUG [ConfiguredXSLTFilter] --------- xslt-output property media-type=text/html 14:34:06,275 DEBUG [ConfiguredXSLTFilter] --------- xslt-output property doctype-system=http://www.w3.org/TR/xhtml1/DTD/xhtml1-transitional.dtd 14:34:06,275 DEBUG [ConfiguredXSLTFilter] Stylesheet set media-type to text/html 14:34:06,274 DEBUG [CalintfImpl] End transaction for 2010-03-16 14:34:05.672 14:34:06,277 DEBUG [CalintfImpl] Close for 2010-03-16 14:34:05.672 14:34:06,436 DEBUG [ConfiguredXSLTFilter] XML -> HTML conversion completed 14:34:06,436 INFO [ConfiguredXSLTFilter] POSTTRANSFORM:AFEF029B2FBE97751351B2158C18AD93:668 14:34:06,436 DEBUG [BwSvciFilter] out Obtained BwCallback object 14:34:06,441 DEBUG [CalintfImpl] flush for 2010-03-16 14:14:14.615 14:34:06,441 DEBUG [CalintfImpl] About to flush 14:34:06,442 DEBUG [CalintfImpl] End transaction for 2010-03-16 14:14:14.615 14:34:06,443 DEBUG [BwSvciFilter] close Obtained BwCallback object 14:34:06,443 DEBUG [CalintfImpl] Close for 2010-03-16 14:14:14.615