Re: [caldav4j] Problem executing put method Etag was not matched and some other problems

170 views
Skip to first unread message

Roberto Polli

unread,
Jul 9, 2012, 10:04:51 AM7/9/12
to cald...@googlegroups.com
Hi Hans

2012/7/9 Hans Will <hansw...@googlemail.com>:
> org.osaf.caldav4j.exceptions.CalDAV4JException: Problem executing put method
> Caused by: org.osaf.caldav4j.exceptions.ResourceOutOfDateException: Etag was
> not matched: "20120709T122331Z-1067"
> at
> org.osaf.caldav4j.CalDAVCalendarCollectionBase.put(CalDAVCalendarCollectionBase.java:209)
This means that you're sending a mismatching ETAG to bedework, aka the
event on server was changed between your first get and your put.


> here is the server log:
Too long! Please send the Http.Wire log of caldav4j so that you could
check if the transaction is a valid caldav one.

Particularly you have to check that each time you want to modify an
event, the right etag is pushed back.
See more on etags here http://en.wikipedia.org/wiki/HTTP_ETag

> When i run the Unit tests i became this errors:

> Failed tests:
> testGetEventResources(org.osaf.caldav4j.CalDAVCalendarCollectionWithCacheTest):
> expected:<0> but was:<1>
it should be 1, going to patch it.

> testGetEventResources(org.osaf.caldav4j.CalDAVCollectionTest):
> expected:<3> but was:<0>
I'm watching it... for this and the following errors check your
http.wire trace and send it to ml.



> testGetEventResourcesFloatingIssues(org.osaf.caldav4j.CalDAVCollectionTest)
> queryPartialCalendar(org.osaf.caldav4j.CalDAVCollectionTest): bad number
> of responses: expected:<3> but was:<1>
>
> testAddRemoveCalendarResource(org.osaf.caldav4j.google.methods.GooglePutGetTest):
> Response status expected:<201> but was:<405>


Thx + Peace,
R.

Hans Will

unread,
Jul 10, 2012, 2:48:08 AM7/10/12
to cald...@googlegroups.com
for the ETAG Problem:

2012-07-10 08:36:07,221 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-10) ReportMethod: doMethod
2012-07-10 08:36:07,221 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-10) [452] <?xml version="1.0"?>
<C:calendar-query xmlns:C="urn:ietf:params:xml:ns:caldav">
    <D:prop xmlns:D="DAV:">
        <D:getetag/>
        <C:calendar-data/>
    </D:prop>
    <C:filter>
        <C:comp-filter name="VCALENDAR">
            <C:comp-filter name="VEVENT">
                <C:prop-filter name="UID">
                    <C:text-match collation="i;ascii-casemap">20120710T083606-77caf433-2c7d-4e68-9383-2d8077c52c31-vbede</C:text-match>
                </C:prop-filter>
            </C:comp-filter>
        </C:comp-filter>
    </C:filter>
</C:calendar-query>

2012-07-10 08:36:07,221 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-10) ReportMethod: depth=1
2012-07-10 08:36:07,221 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-10) [452] prop: {DAV:}getetag
2012-07-10 08:36:07,221 DEBUG [org.bedework.caldav.server.calquery.CalData] (http-0.0.0.0-8080-10)   <calendar-data  content-type="text/calendar">
2012-07-10 08:36:07,221 DEBUG [org.bedework.caldav.server.calquery.CalData] (http-0.0.0.0-8080-10)   </calendar-data>
2012-07-10 08:36:07,221 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-10) [452] prop: {urn:ietf:params:xml:ns:caldav}calendar-data
2012-07-10 08:36:07,252 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-10) REPORT: query
2012-07-10 08:36:07,252 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-10) <filter>
2012-07-10 08:36:07,252 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-10)   <comp-filter name="VCALENDAR">
2012-07-10 08:36:07,252 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-10)     <comp-filter name="VEVENT">
2012-07-10 08:36:07,252 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-10)       <prop-filter name="UID">

2012-07-10 08:36:07,252 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-10)         <text-match collation=i;ascii-casemap negate-condition=no>
2012-07-10 08:36:07,252 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-10) 20120710T083606-77caf433-2c7d-4e68-9383-2d8077c52c31-vbede
2012-07-10 08:36:07,252 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-10)         </text-match>

2012-07-10 08:36:07,252 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-10)       </prop-filter>
2012-07-10 08:36:07,252 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-10)     </comp-filter>
2012-07-10 08:36:07,252 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-10)   </comp-filter>
2012-07-10 08:36:07,252 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-10) </filter>
2012-07-10 08:36:07,252 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-10) setStatus(207)
2012-07-10 08:36:07,252 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-10) getWriter called
2012-07-10 08:36:07,252 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-10) resourceUri: /user/vbede/calendar/
2012-07-10 08:36:07,252 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-10) [452] About to get node for /user/vbede/calendar/
2012-07-10 08:36:07,252 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-10) [452] Normalized uri=/user/vbede/calendar
2012-07-10 08:36:07,252 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-10) [452] search for collection uri "/user/vbede/calendar"
2012-07-10 08:36:07,252 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-10) [452] create collection uri - cal="/user/vbede/calendar"
2012-07-10 08:36:07,252 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-10) REPORT: preq not null
2012-07-10 08:36:07,252 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-10) doNodeAndChildren: curDepth=0 maxDepth=1 uri=/user/vbede/calendar
2012-07-10 08:36:07,252 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-10) getNodes: /user/vbede/calendar
2012-07-10 08:36:07,252 DEBUG [org.bedework.calcore.hibernate.CoreEvents] (http-0.0.0.0-8080-10) getEvents for start=null end=null
2012-07-10 08:36:07,252 DEBUG [org.bedework.calcore.hibernate.CoreEvents] (http-0.0.0.0-8080-10) select distinct ev from org.bedework.calfacade.BwEventObj ev where ev.tombstoned=false  and (((ev.entityType=:fq__0) and (lower(ev.uid) like :fq__1) and (ev.colPath=:fq__2)))
2012-07-10 08:36:07,252 DEBUG [org.bedework.calcore.hibernate.CoreEvents] (http-0.0.0.0-8080-10) Getting (0) Found 1 entries
2012-07-10 08:36:07,252 DEBUG [org.bedework.calcore.hibernate.CoreEvents] (http-0.0.0.0-8080-10) select distinct ev from org.bedework.calfacade.BwEventAnnotation ev where ev.tombstoned=false  and  (ev.override = false)
2012-07-10 08:36:07,252 DEBUG [org.bedework.calcore.hibernate.CoreEvents] (http-0.0.0.0-8080-10) Getting (1) Found 0 entries
2012-07-10 08:36:07,252 DEBUG [org.bedework.calcore.hibernate.CoreEvents] (http-0.0.0.0-8080-10) select distinct ev from org.bedework.calfacade.BwEventObj ev, org.bedework.calfacade.BwRecurrenceInstance inst where (inst.master = ev)  and (((ev.entityType=:fq__0) and (lower(ev.uid) like :fq__1) and (ev.colPath=:fq__2)))
2012-07-10 08:36:07,252 DEBUG [org.bedework.calcore.hibernate.CoreEvents] (http-0.0.0.0-8080-10) Getting (4) Found 0 entries
2012-07-10 08:36:07,252 DEBUG [org.bedework.calcore.hibernate.CoreEvents] (http-0.0.0.0-8080-10) select distinct ev from org.bedework.calfacade.BwEventAnnotation ev where ev.tombstoned=false  and  (ev.override = true)
2012-07-10 08:36:07,252 DEBUG [org.bedework.calcore.hibernate.CoreEvents] (http-0.0.0.0-8080-10) Getting (2) Found 0 entries
2012-07-10 08:36:07,252 DEBUG [org.bedework.caldav.server.filter.FilterHandler] (http-0.0.0.0-8080-10) Query returned 1
2012-07-10 08:36:07,252 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-10) [452] About to get node for /user/vbede/calendar/20120710T083606-77caf433-2c7d-4e68-9383-2d8077c52c31-vbede.ics
2012-07-10 08:36:07,252 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-10) [452] Normalized uri=/user/vbede/calendar/20120710T083606-77caf433-2c7d-4e68-9383-2d8077c52c31-vbede.ics
2012-07-10 08:36:07,252 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-10) [452] do CalendarData for /user/vbede/calendar/20120710T083606-77caf433-2c7d-4e68-9383-2d8077c52c31-vbede.ics
2012-07-10 08:36:07,252 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-10) End transaction for 2012-07-10 08:36:07.206
2012-07-10 08:36:07,252 DEBUG [org.bedework.indexer.MessageProcessor] (org.bedework:service=Indexer) Event CALDAV_IN
2012-07-10 08:36:07,252 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-10) Close for 2012-07-10 08:36:07.206
2012-07-10 08:36:07,252 DEBUG [org.bedework.indexer.MessageProcessor] (org.bedework:service=Indexer) Event ENTITY_FETCHED
2012-07-10 08:36:07,252 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-10) ------------------------ Dump of response -------------------
2012-07-10 08:36:07,252 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-10) <?xml version="1.0" encoding="UTF-8" ?>

<DAV:multistatus xmlns:DAV="DAV:"
              xmlns="urn:ietf:params:xml:ns:caldav"
              xmlns:ical="http://www.w3.org/2002/12/cal/ical#">
  <DAV:response>
    <DAV:href>/ucaldav/user/vbede/calendar/20120710T083606-77caf433-2c7d-4e68-9383-2d8077c52c31-vbede.ics</DAV:href>
    <DAV:propstat>
      <DAV:prop>
        <DAV:getetag>"20120710T063606Z-0333"</DAV:getetag>
        <calendar-data><![CDATA[BEGIN:VCALENDAR
PRODID://Bedework.org//BedeWork V3.8//EN
VERSION:2.0
BEGIN:VEVENT
CREATED:20120710T063606Z
DURATION:PT1H
DTSTAMP:20120710T063606Z
DTSTART:20120710T010000Z
LAST-MODIFIED:20120710T063606Z
SUMMARY:Ein neuer Termin
UID:20120710T083606-77caf433-2c7d-4e68-9383-2d8077c52c31-vbede
END:VEVENT
END:VCALENDAR
]]></calendar-data>
      </DAV:prop>
      <DAV:status>HTTP/1.1 200 ok</DAV:status>
    </DAV:propstat>
  </DAV:response>
</DAV:multistatus>

2012-07-10 08:36:07,252 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-10) ---------------------- End dump of response -----------------
2012-07-10 08:36:07,252 DEBUG [org.bedework.indexer.MessageProcessor] (org.bedework:service=Indexer) Event CALDAV_OUT
2012-07-10 08:36:07,252 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-10) contentLength=922
2012-07-10 08:36:07,252 INFO  [edu.rpi.sss.util.jsp.SessionListener] (http-0.0.0.0-8080-10) SESSION-END:073B2F0E4A64A753AA6E365CEB56B381:?:0:452:132M:599M
2012-07-10 08:36:07,252 INFO  [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost].[/ucaldav]] (http-0.0.0.0-8080-10) ========= Session destroyed(?): 0 active. vm(used, max)=(132M, 599M)
2012-07-10 08:36:07,284 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-10) entry: PUT
2012-07-10 08:36:07,284 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-10) Request headers
2012-07-10 08:36:07,284 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-10)   authorization = "Basic **********"
2012-07-10 08:36:07,284 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-10)   if-match = ""20120710T063606Z-0333""
2012-07-10 08:36:07,284 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-10)   user-agent = "Jakarta Commons-HttpClient/3.1"
2012-07-10 08:36:07,284 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-10)   host = "localhost:8080"
2012-07-10 08:36:07,284 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-10)   cookie = "$Version=0; JSESSIONID=073B2F0E4A64A753AA6E365CEB56B381; $Path=/ucaldav"
2012-07-10 08:36:07,284 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-10)   content-length = "354"
2012-07-10 08:36:07,284 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-10)   content-type = "text/calendar; charset=UTF-8"
2012-07-10 08:36:07,284 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-10) Request parameters - global info and uris
2012-07-10 08:36:07,284 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-10) getRemoteAddr = 127.0.0.1
2012-07-10 08:36:07,284 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-10) getRequestURI = /ucaldav/user/vbede/calendar/20120710T083606-77caf433-2c7d-4e68-9383-2d8077c52c31-vbede.ics
2012-07-10 08:36:07,284 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-10) getRemoteUser = vbede
2012-07-10 08:36:07,284 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-10) getRequestedSessionId = 073B2F0E4A64A753AA6E365CEB56B381
2012-07-10 08:36:07,284 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-10) HttpUtils.getRequestURL(req) = http://localhost:8080/ucaldav/user/vbede/calendar/20120710T083606-77caf433-2c7d-4e68-9383-2d8077c52c31-vbede.ics
2012-07-10 08:36:07,284 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-10) contextPath=/ucaldav
2012-07-10 08:36:07,284 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-10) query=null
2012-07-10 08:36:07,284 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-10) contentlen=354
2012-07-10 08:36:07,284 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-10) request=org.apache.catalina.connector.RequestFacade@5c0c2339
2012-07-10 08:36:07,284 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-10) parameters:
2012-07-10 08:36:07,284 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-10) Request parameters
2012-07-10 08:36:07,284 INFO  [edu.rpi.sss.util.jsp.SessionListener] (http-0.0.0.0-8080-10) SESSION-START:C6AE78682F28DBF227166E1BAEB16B50:?:1:453:131M:599M
2012-07-10 08:36:07,284 INFO  [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost].[/ucaldav]] (http-0.0.0.0-8080-10) ========= New session(?): 1 active, 453 total. vm(used, max)=(131M, 599M)
2012-07-10 08:36:07,284 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-10) New hibernate session for null
2012-07-10 08:36:07,284 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-10) Open session for null
2012-07-10 08:36:07,284 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-10) Begin transaction for null
2012-07-10 08:36:07,284 DEBUG [org.bedework.calsvc.CalSvc] (http-0.0.0.0-8080-10) Authenticated user vbede logged on
2012-07-10 08:36:07,284 DEBUG [org.bedework.calsvc.directory.UserGroupsLdapImpl] (http-0.0.0.0-8080-10) Logged into LDAP server, javax.naming.ldap.InitialLdapContext@74467cd8
2012-07-10 08:36:07,299 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-10) End transaction for 2012-07-10 08:36:07.284
2012-07-10 08:36:07,299 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-10) Close for 2012-07-10 08:36:07.284
2012-07-10 08:36:07,299 DEBUG [org.bedework.indexer.MessageProcessor] (org.bedework:service=Indexer) Event USER_LOGIN
2012-07-10 08:36:07,299 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-10) New hibernate session for 2012-07-10 08:36:07.284
2012-07-10 08:36:07,299 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-10) Open session for 2012-07-10 08:36:07.284
2012-07-10 08:36:07,299 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-10) Begin transaction for 2012-07-10 08:36:07.284
2012-07-10 08:36:07,299 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-10) End transaction for 2012-07-10 08:36:07.284
2012-07-10 08:36:07,299 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-10) Close for 2012-07-10 08:36:07.284
2012-07-10 08:36:07,299 DEBUG [org.bedework.indexer.MessageProcessor] (org.bedework:service=Indexer) Event USER_SVCINIT
2012-07-10 08:36:07,299 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-10) New hibernate session for 2012-07-10 08:36:07.284
2012-07-10 08:36:07,299 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-10) Open session for 2012-07-10 08:36:07.284
2012-07-10 08:36:07,299 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-10) Begin transaction for 2012-07-10 08:36:07.284
2012-07-10 08:36:07,299 DEBUG [edu.rpi.cct.webdav.servlet.common.PutMethod] (http-0.0.0.0-8080-10) PutMethod: doMethod
2012-07-10 08:36:07,299 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-10) [453] About to get node for /user/vbede/calendar/20120710T083606-77caf433-2c7d-4e68-9383-2d8077c52c31-vbede.ics
2012-07-10 08:36:07,299 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-10) [453] Normalized uri=/user/vbede/calendar/20120710T083606-77caf433-2c7d-4e68-9383-2d8077c52c31-vbede.ics
2012-07-10 08:36:07,299 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-10) [453] find event(s) - cal="/user/vbede/calendar" name="20120710T083606-77caf433-2c7d-4e68-9383-2d8077c52c31-vbede.ics"
2012-07-10 08:36:07,299 DEBUG [org.bedework.caldav.server.SysIntfReader] (http-0.0.0.0-8080-10) [146] BEGIN:VCALENDAR
2012-07-10 08:36:07,299 DEBUG [org.bedework.caldav.server.SysIntfReader] (http-0.0.0.0-8080-10) [146] PRODID://Bedework.org//BedeWork V3.8//EN
2012-07-10 08:36:07,299 DEBUG [org.bedework.caldav.server.SysIntfReader] (http-0.0.0.0-8080-10) [146] VERSION:2.0
2012-07-10 08:36:07,299 DEBUG [org.bedework.caldav.server.SysIntfReader] (http-0.0.0.0-8080-10) [146] BEGIN:VEVENT
2012-07-10 08:36:07,299 DEBUG [org.bedework.caldav.server.SysIntfReader] (http-0.0.0.0-8080-10) [146] DTSTART:20120710T010000Z
2012-07-10 08:36:07,299 DEBUG [org.bedework.caldav.server.SysIntfReader] (http-0.0.0.0-8080-10) [146] DURATION:PT1H
2012-07-10 08:36:07,299 DEBUG [org.bedework.caldav.server.SysIntfReader] (http-0.0.0.0-8080-10) [146] UID:20120710T083606-77caf433-2c7d-4e68-9383-2d8077c52c31-vbede
2012-07-10 08:36:07,299 DEBUG [org.bedework.caldav.server.SysIntfReader] (http-0.0.0.0-8080-10) [146] SUMMARY:I changed the summary!
2012-07-10 08:36:07,299 DEBUG [org.bedework.caldav.server.SysIntfReader] (http-0.0.0.0-8080-10) [146] ATTENDEE;PARTSTAT=ACCEPTED:mailto:te...@test.de
2012-07-10 08:36:07,299 DEBUG [org.bedework.caldav.server.SysIntfReader] (http-0.0.0.0-8080-10) [146] DESCRIPTION:Termin geaendert
2012-07-10 08:36:07,299 DEBUG [org.bedework.caldav.server.SysIntfReader] (http-0.0.0.0-8080-10) [146] DTSTAMP:20120710T063607Z
2012-07-10 08:36:07,299 DEBUG [org.bedework.caldav.server.SysIntfReader] (http-0.0.0.0-8080-10) [146] END:VEVENT
2012-07-10 08:36:07,299 DEBUG [org.bedework.caldav.server.SysIntfReader] (http-0.0.0.0-8080-10) [146] END:VCALENDAR
2012-07-10 08:36:07,299 DEBUG [org.bedework.icalendar.IcalUtil] (http-0.0.0.0-8080-10) TRANS-TO_EVENT: try to fetch event with guid=20120710T083606-77caf433-2c7d-4e68-9383-2d8077c52c31-vbede
2012-07-10 08:36:07,299 DEBUG [org.bedework.icalendar.IcalUtil] (http-0.0.0.0-8080-10) TRANS-TO_EVENT: fetched event with guid
2012-07-10 08:36:07,299 WARN  [org.bedework.calfacade.util.ChangeTable] (http-0.0.0.0-8080-10) No entry for index UNKNOWN_PROPERTY name null
2012-07-10 08:36:07,299 WARN  [org.bedework.calfacade.util.ChangeTable] (http-0.0.0.0-8080-10) No entry for index BUSYTYPE name BUSYTYPE
2012-07-10 08:36:07,299 WARN  [org.bedework.calfacade.util.ChangeTable] (http-0.0.0.0-8080-10) No entry for index COLLECTION name COLLECTION
2012-07-10 08:36:07,299 WARN  [org.bedework.calfacade.util.ChangeTable] (http-0.0.0.0-8080-10) No entry for index LANG name LANGUAGE
2012-07-10 08:36:07,299 WARN  [org.bedework.calfacade.util.ChangeTable] (http-0.0.0.0-8080-10) No entry for index XBEDEWORK_COST name X-BEDEWORK-COST
2012-07-10 08:36:07,299 WARN  [org.bedework.calfacade.util.ChangeTable] (http-0.0.0.0-8080-10) No entry for index CALSCALE name CALSCALE
2012-07-10 08:36:07,299 WARN  [org.bedework.calfacade.util.ChangeTable] (http-0.0.0.0-8080-10) No entry for index METHOD name METHOD
2012-07-10 08:36:07,299 WARN  [org.bedework.calfacade.util.ChangeTable] (http-0.0.0.0-8080-10) No entry for index PRODID name PRODID
2012-07-10 08:36:07,299 WARN  [org.bedework.calfacade.util.ChangeTable] (http-0.0.0.0-8080-10) No entry for index VERSION name VERSION
2012-07-10 08:36:07,299 DEBUG [org.bedework.icalendar.IcalUtil] (http-0.0.0.0-8080-10) ChangeTable{
SUMMARY: changed
DESCRIPTION: changed
DTSTAMP
UID
ATTENDEE: changed
DTSTART
DURATION}
2012-07-10 08:36:07,299 DEBUG [org.bedework.icalendar.IcalUtil] (http-0.0.0.0-8080-10) BwEvent{id=3783, owner=/principals/users/vbede, publick=false,
 creator=/principals/users/vbede, access=null, collection=/user/vbede/calendar,
   entityType=0, deleted=false,
   dtstamp=20120710T063607Z,
   dtstart=BwDateTime{DATETIME, dtval=20120710T010000Z, UTC},
   dtend=BwDateTime{DATETIME, dtval=20120710T020000Z, UTC},
 status=null,
 lastmod=20120710T063606Z, created=20120710T063606Z, stag=null,
   priority=null, classification=null,
 uid=20120710T083606-77caf433-2c7d-4e68-9383-2d8077c52c31-vbede,
   name=20120710T083606-77caf433-2c7d-4e68-9383-2d8077c52c31-vbede.ics,
   ctoken=20120710T063606Z-0333
, getRecurring=false,
 organizer=null,
   summary={BwString{id=-1, lang=null, value=I changed the summary!},
   description={BwLongString{id=-1, lang=null, value=Termin geaendert},
   attendee=BwAttendee{id=-1, cn=null, cuType=null, delegatedFrom=null, delegatedTo=null,
      dir=null, language=null, member=null, rsvp=false,
      role=null, partstat=ACCEPTED, sentBy=null, attendeeUri=mailto:te...@test.de,
      sequence=0, dtstamp=null,
      scheduleAgent=0, scheduleStatus=null},
   sequence=0, scheduleMethod=0,
   originator=null, scheduleState=0}
2012-07-10 08:36:07,299 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-10) [453] putContent: intf has event with name 20120710T083606-77caf433-2c7d-4e68-9383-2d8077c52c31-vbede.ics and summary I changed the summary! new event = false
2012-07-10 08:36:07,299 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-10) [453] putContent: etag mismatch if="20120710T063606Z-0333"prev="20120710T063606Z-0"
2012-07-10 08:36:07,299 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-10) Enter rollback
2012-07-10 08:36:07,299 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-10) About to rollback
2012-07-10 08:36:07,299 ERROR [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-10) org.bedework.caldav.server.CaldavBWIntf@6376f152
edu.rpi.cct.webdav.servlet.shared.WebdavException
    at org.bedework.caldav.server.CaldavBWIntf.putEvent(CaldavBWIntf.java:896)
    at org.bedework.caldav.server.CaldavBWIntf.putContent(CaldavBWIntf.java:760)
    at edu.rpi.cct.webdav.servlet.shared.WebdavNsIntf.putContent(WebdavNsIntf.java:680)
    at edu.rpi.cct.webdav.servlet.common.PutMethod.doMethod(PutMethod.java:57)
    at edu.rpi.cct.webdav.servlet.common.WebdavServlet.service(WebdavServlet.java:149)
    at javax.servlet.http.HttpServlet.service(HttpServlet.java:717)
    at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290)
    at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
    at org.jboss.web.tomcat.filters.ReplyHeaderFilter.doFilter(ReplyHeaderFilter.java:96)
    at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235)
    at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
    at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:235)
    at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:191)
    at org.jboss.web.tomcat.security.SecurityAssociationValve.invoke(SecurityAssociationValve.java:190)
    at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:525)
    at org.jboss.web.tomcat.security.JaccContextValve.invoke(JaccContextValve.java:92)
    at org.jboss.web.tomcat.security.SecurityContextEstablishmentValve.process(SecurityContextEstablishmentValve.java:126)
    at org.jboss.web.tomcat.security.SecurityContextEstablishmentValve.invoke(SecurityContextEstablishmentValve.java:70)
    at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127)
    at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
    at org.jboss.web.tomcat.service.jca.CachedConnectionValve.invoke(CachedConnectionValve.java:158)
    at org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:567)
    at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
    at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:330)
    at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:829)
    at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:598)
    at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:447)
    at java.lang.Thread.run(Thread.java:662)
2012-07-10 08:36:07,299 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-10) Enter rollback
2012-07-10 08:36:07,299 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-10) setStatus(412) message=null
2012-07-10 08:36:07,299 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-10) End transaction for 2012-07-10 08:36:07.284

Where can i see the http.wire trace?
Thanks for your work and help!
Regards Hans


Am Montag, 9. Juli 2012 15:10:16 UTC+2 schrieb Hans Will:
i running my owen local bedework server and use caldav4j in eclipse, when i use the Examples in caldav4j i run in this error:

Creating Caldav Client..
Opening a collection..
root:/ucaldav/user/vbede/calendar/
create a dummy event..
modify the event..
Retrieve the event..
original        BEGIN:VEVENT
CREATED:20120709T122331Z
DURATION:PT1H
DTSTAMP:20120709T122331Z
DTSTART:20120710T010000Z
LAST-MODIFIED:20120709T122331Z
SUMMARY:Ein neuer Termin
UID:20120709T142331-4ebff3ea-b5c6-4479-9a7c-0a7a270f6052-vbede
END:VEVENT


org.osaf.caldav4j.exceptions.CalDAV4JException: Problem executing put method
    at org.osaf.caldav4j.CalDAVCalendarCollectionBase.put(CalDAVCalendarCollectionBase.java:214)
    at org.osaf.caldav4j.CalDAVCalendarCollection.updateMasterEvent(CalDAVCalendarCollection.java:404)
    at example.CalDavCollectionManager.editEvent(CalDavCollectionManager.java:245)
    at example.CalDavCollectionManager.main(CalDavCollectionManager.java:400)

Caused by: org.osaf.caldav4j.exceptions.ResourceOutOfDateException: Etag was not matched: "20120709T122331Z-1067"
    at org.osaf.caldav4j.CalDAVCalendarCollectionBase.put(CalDAVCalendarCollectionBase.java:209)
    ... 3 more
dump the event..
BEGIN:VCALENDAR
PRODID://Bedework.org//BedeWork V3.8//EN
VERSION:2.0
BEGIN:VEVENT
CREATED:20120709T122331Z
DURATION:PT1H
DTSTAMP:20120709T122331Z
DTSTART:20120710T010000Z
LAST-MODIFIED:20120709T122331Z
SUMMARY:Ein neuer Termin
UID:20120709T142331-4ebff3ea-b5c6-4479-9a7c-0a7a270f6052-vbede
END:VEVENT
END:VCALENDAR


here is the server log:

2012-07-09 14:23:31,894 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) entry: PUT
2012-07-09 14:23:31,894 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) Request headers
2012-07-09 14:23:31,894 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8)   authorization = "Basic **********"
2012-07-09 14:23:31,894 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8)   if-none-match = "*"
2012-07-09 14:23:31,894 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8)   user-agent = "Jakarta Commons-HttpClient/3.1"
2012-07-09 14:23:31,894 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8)   host = "localhost:8080"
2012-07-09 14:23:31,894 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8)   content-length = "257"
2012-07-09 14:23:31,894 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8)   content-type = "text/calendar; charset=UTF-8"
2012-07-09 14:23:31,894 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) Request parameters - global info and uris
2012-07-09 14:23:31,894 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) getRemoteAddr = 127.0.0.1
2012-07-09 14:23:31,894 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) getRequestURI = /ucaldav/user/vbede/calendar/20120709T142331-4ebff3ea-b5c6-4479-9a7c-0a7a270f6052-vbede.ics
2012-07-09 14:23:31,894 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) getRemoteUser = vbede
2012-07-09 14:23:31,894 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) getRequestedSessionId = null
2012-07-09 14:23:31,894 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) HttpUtils.getRequestURL(req) = http://localhost:8080/ucaldav/user/vbede/calendar/20120709T142331-4ebff3ea-b5c6-4479-9a7c-0a7a270f6052-vbede.ics
2012-07-09 14:23:31,894 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) contextPath=/ucaldav
2012-07-09 14:23:31,894 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) query=null
2012-07-09 14:23:31,894 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) contentlen=257
2012-07-09 14:23:31,894 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) request=org.apache.catalina.connector.RequestFacade@4526d044
2012-07-09 14:23:31,894 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) parameters:
2012-07-09 14:23:31,895 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) Request parameters
2012-07-09 14:23:31,895 INFO  [edu.rpi.sss.util.jsp.SessionListener] (http-0.0.0.0-8080-8) SESSION-START:F4823B2A8A91B0845058EE1458405376:?:1:2366:251M:598M
2012-07-09 14:23:31,895 INFO  [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost].[/ucaldav]] (http-0.0.0.0-8080-8) ========= New session(?): 1 active, 2366 total. vm(used, max)=(251M, 598M)
2012-07-09 14:23:31,895 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) New hibernate session for null
2012-07-09 14:23:31,895 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Open session for null
2012-07-09 14:23:31,895 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Begin transaction for null
2012-07-09 14:23:31,896 DEBUG [org.bedework.calsvc.CalSvc] (http-0.0.0.0-8080-8) Authenticated user vbede logged on
2012-07-09 14:23:31,897 DEBUG [org.bedework.calsvc.directory.UserGroupsLdapImpl] (http-0.0.0.0-8080-8) Logged into LDAP server, javax.naming.ldap.InitialLdapContext@4441f63f
2012-07-09 14:23:31,899 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) End transaction for 2012-07-09 14:23:31.898
2012-07-09 14:23:31,900 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Close for 2012-07-09 14:23:31.898
2012-07-09 14:23:31,900 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) New hibernate session for 2012-07-09 14:23:31.898
2012-07-09 14:23:31,900 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Open session for 2012-07-09 14:23:31.898
2012-07-09 14:23:31,901 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Begin transaction for 2012-07-09 14:23:31.898
2012-07-09 14:23:31,901 DEBUG [org.bedework.indexer.MessageProcessor] (org.bedework:service=Indexer) Event USER_LOGIN
2012-07-09 14:23:31,904 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) End transaction for 2012-07-09 14:23:31.898
2012-07-09 14:23:31,905 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Close for 2012-07-09 14:23:31.898
2012-07-09 14:23:31,905 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) New hibernate session for 2012-07-09 14:23:31.898
2012-07-09 14:23:31,905 DEBUG [org.bedework.indexer.MessageProcessor] (org.bedework:service=Indexer) Event USER_SVCINIT
2012-07-09 14:23:31,905 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Open session for 2012-07-09 14:23:31.898
2012-07-09 14:23:31,905 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Begin transaction for 2012-07-09 14:23:31.898
2012-07-09 14:23:31,906 DEBUG [edu.rpi.cct.webdav.servlet.common.PutMethod] (http-0.0.0.0-8080-8) PutMethod: doMethod
2012-07-09 14:23:31,906 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-8) [2366] About to get node for /user/vbede/calendar/20120709T142331-4ebff3ea-b5c6-4479-9a7c-0a7a270f6052-vbede.ics
2012-07-09 14:23:31,906 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-8) [2366] Normalized uri=/user/vbede/calendar/20120709T142331-4ebff3ea-b5c6-4479-9a7c-0a7a270f6052-vbede.ics
2012-07-09 14:23:31,907 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-8) [2366] find event(s) - cal="/user/vbede/calendar" name="20120709T142331-4ebff3ea-b5c6-4479-9a7c-0a7a270f6052-vbede.ics"
2012-07-09 14:23:31,908 DEBUG [org.bedework.caldav.server.SysIntfReader] (http-0.0.0.0-8080-8) [714] BEGIN:VCALENDAR
2012-07-09 14:23:31,908 DEBUG [org.bedework.caldav.server.SysIntfReader] (http-0.0.0.0-8080-8) [714] PRODID:
2012-07-09 14:23:31,908 DEBUG [org.bedework.caldav.server.SysIntfReader] (http-0.0.0.0-8080-8) [714] VERSION:2.0
2012-07-09 14:23:31,908 DEBUG [org.bedework.caldav.server.SysIntfReader] (http-0.0.0.0-8080-8) [714] CALSCALE:GREGORIAN
2012-07-09 14:23:31,908 DEBUG [org.bedework.caldav.server.SysIntfReader] (http-0.0.0.0-8080-8) [714] BEGIN:VEVENT
2012-07-09 14:23:31,908 DEBUG [org.bedework.caldav.server.SysIntfReader] (http-0.0.0.0-8080-8) [714] DTSTAMP:20120709T122331Z
2012-07-09 14:23:31,909 DEBUG [org.bedework.caldav.server.SysIntfReader] (http-0.0.0.0-8080-8) [714] DTSTART:20120710T010000Z
2012-07-09 14:23:31,909 DEBUG [org.bedework.caldav.server.SysIntfReader] (http-0.0.0.0-8080-8) [714] DURATION:PT1H
2012-07-09 14:23:31,909 DEBUG [org.bedework.caldav.server.SysIntfReader] (http-0.0.0.0-8080-8) [714] SUMMARY:Ein neuer Termin
2012-07-09 14:23:31,909 DEBUG [org.bedework.caldav.server.SysIntfReader] (http-0.0.0.0-8080-8) [714] UID:20120709T142331-4ebff3ea-b5c6-4479-9a7c-0a7a270f6052-vbede
2012-07-09 14:23:31,909 DEBUG [org.bedework.caldav.server.SysIntfReader] (http-0.0.0.0-8080-8) [714] END:VEVENT
2012-07-09 14:23:31,909 DEBUG [org.bedework.caldav.server.SysIntfReader] (http-0.0.0.0-8080-8) [714] END:VCALENDAR
2012-07-09 14:23:31,909 DEBUG [org.bedework.icalendar.IcalUtil] (http-0.0.0.0-8080-8) TRANS-TO_EVENT: try to fetch event with guid=20120709T142331-4ebff3ea-b5c6-4479-9a7c-0a7a270f6052-vbede
2012-07-09 14:23:31,911 DEBUG [org.bedework.icalendar.IcalUtil] (http-0.0.0.0-8080-8) TRANS-TO_EVENT: did not find event with guid
2012-07-09 14:23:31,911 WARN  [org.bedework.calfacade.util.ChangeTable] (http-0.0.0.0-8080-8) No entry for index UNKNOWN_PROPERTY name null
2012-07-09 14:23:31,911 WARN  [org.bedework.calfacade.util.ChangeTable] (http-0.0.0.0-8080-8) No entry for index BUSYTYPE name BUSYTYPE
2012-07-09 14:23:31,911 WARN  [org.bedework.calfacade.util.ChangeTable] (http-0.0.0.0-8080-8) No entry for index COLLECTION name COLLECTION
2012-07-09 14:23:31,911 WARN  [org.bedework.calfacade.util.ChangeTable] (http-0.0.0.0-8080-8) No entry for index LANG name LANGUAGE
2012-07-09 14:23:31,911 WARN  [org.bedework.calfacade.util.ChangeTable] (http-0.0.0.0-8080-8) No entry for index XBEDEWORK_COST name X-BEDEWORK-COST
2012-07-09 14:23:31,911 WARN  [org.bedework.calfacade.util.ChangeTable] (http-0.0.0.0-8080-8) No entry for index CALSCALE name CALSCALE
2012-07-09 14:23:31,911 WARN  [org.bedework.calfacade.util.ChangeTable] (http-0.0.0.0-8080-8) No entry for index METHOD name METHOD
2012-07-09 14:23:31,911 WARN  [org.bedework.calfacade.util.ChangeTable] (http-0.0.0.0-8080-8) No entry for index PRODID name PRODID
2012-07-09 14:23:31,911 WARN  [org.bedework.calfacade.util.ChangeTable] (http-0.0.0.0-8080-8) No entry for index VERSION name VERSION
2012-07-09 14:23:31,911 DEBUG [org.bedework.icalendar.IcalUtil] (http-0.0.0.0-8080-8) ChangeTable{
SUMMARY: changed
DTSTAMP
UID: changed
DTSTART: changed
DURATION: changed}
2012-07-09 14:23:31,911 DEBUG [org.bedework.icalendar.IcalUtil] (http-0.0.0.0-8080-8) BwEvent{id=-1, owner=/principals/users/vbede, publick=null,
 creator=/principals/users/vbede, access=null, collection=/user/vbede/calendar,
   entityType=0, deleted=false,
   dtstamp=20120709T122331Z,
   dtstart=BwDateTime{DATETIME, dtval=20120710T010000Z, UTC},
   dtend=BwDateTime{DATETIME, dtval=20120710T020000Z, UTC},
 status=null,
 lastmod=20120709T122331Z, created=20120709T122331Z, stag=null,
   priority=null, classification=null,
 uid=20120709T142331-4ebff3ea-b5c6-4479-9a7c-0a7a270f6052-vbede,
   name=null,
   ctoken=20120709T122331Z-1066
, getRecurring=false,
 organizer=null,
   summary={BwString{id=-1, lang=null, value=Ein neuer Termin},
   sequence=0, scheduleMethod=0,
   originator=null, scheduleState=0}
2012-07-09 14:23:31,911 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-8) [2366] putContent: intf has event with name 20120709T142331-4ebff3ea-b5c6-4479-9a7c-0a7a270f6052-vbede.ics and summary Ein neuer Termin new event = true
2012-07-09 14:23:31,915 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) setStatus(201)
2012-07-09 14:23:31,915 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) End transaction for 2012-07-09 14:23:31.898
2012-07-09 14:23:31,916 DEBUG [org.bedework.indexer.MessageProcessor] (org.bedework:service=Indexer) Event CALDAV_IN
2012-07-09 14:23:31,919 DEBUG [org.bedework.indexer.MessageProcessor] (org.bedework:service=Indexer) Event ENTITY_FETCHED
2012-07-09 14:23:31,920 DEBUG [org.bedework.indexer.MessageProcessor] (org.bedework:service=Indexer) Event ENTITY_FETCHED
2012-07-09 14:23:31,921 DEBUG [org.bedework.indexer.MessageProcessor] (org.bedework:service=Indexer) Event STATS
2012-07-09 14:23:31,924 DEBUG [org.bedework.indexer.MessageProcessor] (org.bedework:service=Indexer) Event STATS
2012-07-09 14:23:31,924 DEBUG [org.bedework.indexer.MessageProcessor] (org.bedework:service=Indexer) Event STATS
2012-07-09 14:23:31,925 DEBUG [org.bedework.indexer.MessageProcessor] (org.bedework:service=Indexer) Event STATS
2012-07-09 14:23:31,926 DEBUG [org.bedework.indexer.MessageProcessor] (org.bedework:service=Indexer) Event ENTITY_ADDED
2012-07-09 14:23:31,926 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (org.bedework:service=Indexer) New hibernate session for 2012-07-09 12:14:01.756
2012-07-09 14:23:31,926 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (org.bedework:service=Indexer) Open session for 2012-07-09 12:14:01.756
2012-07-09 14:23:31,926 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (org.bedework:service=Indexer) Begin transaction for 2012-07-09 12:14:01.756
2012-07-09 14:23:31,928 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Close for 2012-07-09 14:23:31.898
2012-07-09 14:23:31,931 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) ------------------------ Dump of response -------------------
2012-07-09 14:23:31,932 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8)
2012-07-09 14:23:31,932 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) ---------------------- End dump of response -----------------
2012-07-09 14:23:31,932 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) contentLength=0
2012-07-09 14:23:31,932 INFO  [edu.rpi.sss.util.jsp.SessionListener] (http-0.0.0.0-8080-8) SESSION-END:F4823B2A8A91B0845058EE1458405376:?:0:2366:246M:598M
2012-07-09 14:23:31,932 INFO  [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost].[/ucaldav]] (http-0.0.0.0-8080-8) ========= Session destroyed(?): 0 active. vm(used, max)=(246M, 598M)
2012-07-09 14:23:31,933 DEBUG [org.bedework.calsvc.indexing.BwIndexLuceneImpl] (org.bedework:service=Indexer) removed 0 entries for event:020 /user/vbede/calendar058 20120709T142331-4ebff3ea-b5c6-4479-9a7c-0a7a270f6052-vbedeN
2012-07-09 14:23:31,984 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (org.bedework:service=Indexer) End transaction for 2012-07-09 12:14:01.756
2012-07-09 14:23:31,988 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (org.bedework:service=Indexer) Close for 2012-07-09 12:14:01.756
2012-07-09 14:23:31,989 DEBUG [org.bedework.indexer.MessageProcessor] (org.bedework:service=Indexer) Event STATS
2012-07-09 14:23:31,989 DEBUG [org.bedework.indexer.MessageProcessor] (org.bedework:service=Indexer) Event CALDAV_OUT
2012-07-09 14:23:31,989 DEBUG [org.bedework.indexer.MessageProcessor] (org.bedework:service=Indexer) Event ENTITY_FETCHED
2012-07-09 14:23:32,105 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) entry: REPORT
2012-07-09 14:23:32,105 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) Request headers
2012-07-09 14:23:32,106 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8)   authorization = "Basic **********"
2012-07-09 14:23:32,106 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8)   user-agent = "Jakarta Commons-HttpClient/3.1"
2012-07-09 14:23:32,106 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8)   host = "localhost:8080"
2012-07-09 14:23:32,106 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8)   cookie = "$Version=0; JSESSIONID=F4823B2A8A91B0845058EE1458405376; $Path=/ucaldav"
2012-07-09 14:23:32,106 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8)   content-length = "571"
2012-07-09 14:23:32,106 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8)   depth = "1"
2012-07-09 14:23:32,106 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8)   content-type = "text/xml"
2012-07-09 14:23:32,106 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) Request parameters - global info and uris
2012-07-09 14:23:32,106 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) getRemoteAddr = 127.0.0.1
2012-07-09 14:23:32,106 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) getRequestURI = /ucaldav/user/vbede/calendar/
2012-07-09 14:23:32,106 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) getRemoteUser = vbede
2012-07-09 14:23:32,106 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) getRequestedSessionId = F4823B2A8A91B0845058EE1458405376
2012-07-09 14:23:32,106 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) HttpUtils.getRequestURL(req) = http://localhost:8080/ucaldav/user/vbede/calendar/
2012-07-09 14:23:32,106 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) contextPath=/ucaldav
2012-07-09 14:23:32,106 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) query=null
2012-07-09 14:23:32,106 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) contentlen=571
2012-07-09 14:23:32,106 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) request=org.apache.catalina.connector.RequestFacade@4526d044
2012-07-09 14:23:32,107 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) parameters:
2012-07-09 14:23:32,107 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) Request parameters
2012-07-09 14:23:32,107 INFO  [edu.rpi.sss.util.jsp.SessionListener] (http-0.0.0.0-8080-8) SESSION-START:3753CE921FA34612419841C754C9D785:?:1:2367:244M:598M
2012-07-09 14:23:32,107 INFO  [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost].[/ucaldav]] (http-0.0.0.0-8080-8) ========= New session(?): 1 active, 2367 total. vm(used, max)=(244M, 598M)
2012-07-09 14:23:32,107 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) New hibernate session for null
2012-07-09 14:23:32,107 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Open session for null
2012-07-09 14:23:32,107 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Begin transaction for null
2012-07-09 14:23:32,108 DEBUG [org.bedework.calsvc.CalSvc] (http-0.0.0.0-8080-8) Authenticated user vbede logged on
2012-07-09 14:23:32,109 DEBUG [org.bedework.calsvc.directory.UserGroupsLdapImpl] (http-0.0.0.0-8080-8) Logged into LDAP server, javax.naming.ldap.InitialLdapContext@486bae92
2012-07-09 14:23:32,110 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) End transaction for 2012-07-09 14:23:32.11
2012-07-09 14:23:32,112 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Close for 2012-07-09 14:23:32.11
2012-07-09 14:23:32,112 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) New hibernate session for 2012-07-09 14:23:32.11
2012-07-09 14:23:32,112 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Open session for 2012-07-09 14:23:32.11
2012-07-09 14:23:32,112 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Begin transaction for 2012-07-09 14:23:32.11
2012-07-09 14:23:32,112 DEBUG [org.bedework.indexer.MessageProcessor] (org.bedework:service=Indexer) Event USER_LOGIN
2012-07-09 14:23:32,115 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) End transaction for 2012-07-09 14:23:32.11
2012-07-09 14:23:32,116 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Close for 2012-07-09 14:23:32.11
2012-07-09 14:23:32,116 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) New hibernate session for 2012-07-09 14:23:32.11
2012-07-09 14:23:32,117 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Open session for 2012-07-09 14:23:32.11
2012-07-09 14:23:32,117 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Begin transaction for 2012-07-09 14:23:32.11
2012-07-09 14:23:32,116 DEBUG [org.bedework.indexer.MessageProcessor] (org.bedework:service=Indexer) Event USER_SVCINIT
2012-07-09 14:23:32,117 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) No charset specified in request; forced to UTF-8
2012-07-09 14:23:32,117 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8) ReportMethod: doMethod
2012-07-09 14:23:32,118 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-8) [2367] <?xml version="1.0"?>
<C:calendar-query xmlns:C="urn:ietf:params:xml:ns:caldav">
    <D:prop xmlns:D="DAV:">
        <D:getetag/>
        <C:calendar-data/>
    </D:prop>
    <C:filter>
        <C:comp-filter name="VCALENDAR">
            <C:comp-filter name="VEVENT">
                <C:prop-filter name="UID">
                    <C:text-match collation="i;ascii-casemap">20120709T142331-4ebff3ea-b5c6-4479-9a7c-0a7a270f6052-vbede</C:text-match>
                </C:prop-filter>
            </C:comp-filter>
        </C:comp-filter>
    </C:filter>
</C:calendar-query>

2012-07-09 14:23:32,118 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8) ReportMethod: depth=1
2012-07-09 14:23:32,119 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-8) [2367] prop: {DAV:}getetag
2012-07-09 14:23:32,119 DEBUG [org.bedework.caldav.server.calquery.CalData] (http-0.0.0.0-8080-8)   <calendar-data  content-type="text/calendar">
2012-07-09 14:23:32,119 DEBUG [org.bedework.caldav.server.calquery.CalData] (http-0.0.0.0-8080-8)   </calendar-data>
2012-07-09 14:23:32,119 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-8) [2367] prop: {urn:ietf:params:xml:ns:caldav}calendar-data
2012-07-09 14:23:32,151 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8) REPORT: query
2012-07-09 14:23:32,152 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8) <filter>
2012-07-09 14:23:32,152 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8)   <comp-filter name="VCALENDAR">
2012-07-09 14:23:32,152 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8)     <comp-filter name="VEVENT">
2012-07-09 14:23:32,152 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8)       <prop-filter name="UID">

2012-07-09 14:23:32,152 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8)         <text-match collation=i;ascii-casemap negate-condition=no>
2012-07-09 14:23:32,152 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8) 20120709T142331-4ebff3ea-b5c6-4479-9a7c-0a7a270f6052-vbede
2012-07-09 14:23:32,152 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8)         </text-match>

2012-07-09 14:23:32,152 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8)       </prop-filter>
2012-07-09 14:23:32,152 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8)     </comp-filter>
2012-07-09 14:23:32,152 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8)   </comp-filter>
2012-07-09 14:23:32,152 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8) </filter>
2012-07-09 14:23:32,152 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) setStatus(207)
2012-07-09 14:23:32,152 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) getWriter called
2012-07-09 14:23:32,152 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8) resourceUri: /user/vbede/calendar/
2012-07-09 14:23:32,152 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-8) [2367] About to get node for /user/vbede/calendar/
2012-07-09 14:23:32,152 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-8) [2367] Normalized uri=/user/vbede/calendar
2012-07-09 14:23:32,152 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-8) [2367] search for collection uri "/user/vbede/calendar"
2012-07-09 14:23:32,154 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-8) [2367] create collection uri - cal="/user/vbede/calendar"
2012-07-09 14:23:32,154 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8) REPORT: preq not null
2012-07-09 14:23:32,154 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8) doNodeAndChildren: curDepth=0 maxDepth=1 uri=/user/vbede/calendar
2012-07-09 14:23:32,154 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8) getNodes: /user/vbede/calendar
2012-07-09 14:23:32,154 DEBUG [org.bedework.calcore.hibernate.CoreEvents] (http-0.0.0.0-8080-8) getEvents for start=null end=null
2012-07-09 14:23:32,154 DEBUG [org.bedework.calcore.hibernate.CoreEvents] (http-0.0.0.0-8080-8) select distinct ev from org.bedework.calfacade.BwEventObj ev where ev.tombstoned=false  and (((ev.entityType=:fq__0) and (lower(ev.uid) like :fq__1) and (ev.colPath=:fq__2)))
2012-07-09 14:23:32,155 DEBUG [org.bedework.calcore.hibernate.CoreEvents] (http-0.0.0.0-8080-8) Getting (0) Found 1 entries
2012-07-09 14:23:32,155 DEBUG [org.bedework.calcore.hibernate.CoreEvents] (http-0.0.0.0-8080-8) select distinct ev from org.bedework.calfacade.BwEventAnnotation ev where ev.tombstoned=false  and  (ev.override = false)
2012-07-09 14:23:32,156 DEBUG [org.bedework.calcore.hibernate.CoreEvents] (http-0.0.0.0-8080-8) Getting (1) Found 0 entries
2012-07-09 14:23:32,156 DEBUG [org.bedework.calcore.hibernate.CoreEvents] (http-0.0.0.0-8080-8) select distinct ev from org.bedework.calfacade.BwEventObj ev, org.bedework.calfacade.BwRecurrenceInstance inst where (inst.master = ev)  and (((ev.entityType=:fq__0) and (lower(ev.uid) like :fq__1) and (ev.colPath=:fq__2)))
2012-07-09 14:23:32,157 DEBUG [org.bedework.calcore.hibernate.CoreEvents] (http-0.0.0.0-8080-8) Getting (4) Found 0 entries
2012-07-09 14:23:32,157 DEBUG [org.bedework.calcore.hibernate.CoreEvents] (http-0.0.0.0-8080-8) select distinct ev from org.bedework.calfacade.BwEventAnnotation ev where ev.tombstoned=false  and  (ev.override = true)
2012-07-09 14:23:32,158 DEBUG [org.bedework.calcore.hibernate.CoreEvents] (http-0.0.0.0-8080-8) Getting (2) Found 0 entries
2012-07-09 14:23:32,158 DEBUG [org.bedework.caldav.server.filter.FilterHandler] (http-0.0.0.0-8080-8) Query returned 1
2012-07-09 14:23:32,158 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-8) [2367] About to get node for /user/vbede/calendar/20120709T142331-4ebff3ea-b5c6-4479-9a7c-0a7a270f6052-vbede.ics
2012-07-09 14:23:32,158 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-8) [2367] Normalized uri=/user/vbede/calendar/20120709T142331-4ebff3ea-b5c6-4479-9a7c-0a7a270f6052-vbede.ics
2012-07-09 14:23:32,159 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-8) [2367] do CalendarData for /user/vbede/calendar/20120709T142331-4ebff3ea-b5c6-4479-9a7c-0a7a270f6052-vbede.ics
2012-07-09 14:23:32,161 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) End transaction for 2012-07-09 14:23:32.11
2012-07-09 14:23:32,162 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Close for 2012-07-09 14:23:32.11
2012-07-09 14:23:32,163 DEBUG [org.bedework.indexer.MessageProcessor] (org.bedework:service=Indexer) Event CALDAV_IN
2012-07-09 14:23:32,163 DEBUG [org.bedework.indexer.MessageProcessor] (org.bedework:service=Indexer) Event ENTITY_FETCHED
2012-07-09 14:23:32,164 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) ------------------------ Dump of response -------------------
2012-07-09 14:23:32,164 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) <?xml version="1.0" encoding="UTF-8" ?>

<DAV:multistatus xmlns:DAV="DAV:"
              xmlns="urn:ietf:params:xml:ns:caldav"
              xmlns:ical="http://www.w3.org/2002/12/cal/ical#">
  <DAV:response>
    <DAV:href>/ucaldav/user/vbede/calendar/20120709T142331-4ebff3ea-b5c6-4479-9a7c-0a7a270f6052-vbede.ics</DAV:href>
    <DAV:propstat>
      <DAV:prop>
        <DAV:getetag>"20120709T122331Z-1067"</DAV:getetag>
        <calendar-data><![CDATA[BEGIN:VCALENDAR
PRODID://Bedework.org//BedeWork V3.8//EN
VERSION:2.0
BEGIN:VEVENT
CREATED:20120709T122331Z
DURATION:PT1H
DTSTAMP:20120709T122331Z
DTSTART:20120710T010000Z
LAST-MODIFIED:20120709T122331Z
SUMMARY:Ein neuer Termin
UID:20120709T142331-4ebff3ea-b5c6-4479-9a7c-0a7a270f6052-vbede
END:VEVENT
END:VCALENDAR
]]></calendar-data>
      </DAV:prop>
      <DAV:status>HTTP/1.1 200 ok</DAV:status>
    </DAV:propstat>
  </DAV:response>
</DAV:multistatus>

2012-07-09 14:23:32,164 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) ---------------------- End dump of response -----------------
2012-07-09 14:23:32,164 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) contentLength=922
2012-07-09 14:23:32,164 INFO  [edu.rpi.sss.util.jsp.SessionListener] (http-0.0.0.0-8080-8) SESSION-END:3753CE921FA34612419841C754C9D785:?:0:2367:237M:598M
2012-07-09 14:23:32,164 INFO  [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost].[/ucaldav]] (http-0.0.0.0-8080-8) ========= Session destroyed(?): 0 active. vm(used, max)=(237M, 598M)
2012-07-09 14:23:32,164 DEBUG [org.bedework.indexer.MessageProcessor] (org.bedework:service=Indexer) Event CALDAV_OUT
2012-07-09 14:23:32,212 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) entry: REPORT
2012-07-09 14:23:32,212 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) Request headers
2012-07-09 14:23:32,212 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8)   authorization = "Basic **********"
2012-07-09 14:23:32,212 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8)   user-agent = "Jakarta Commons-HttpClient/3.1"
2012-07-09 14:23:32,213 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8)   host = "localhost:8080"
2012-07-09 14:23:32,213 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8)   cookie = "$Version=0; JSESSIONID=3753CE921FA34612419841C754C9D785; $Path=/ucaldav"
2012-07-09 14:23:32,213 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8)   content-length = "571"
2012-07-09 14:23:32,213 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8)   depth = "1"
2012-07-09 14:23:32,213 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8)   content-type = "text/xml"
2012-07-09 14:23:32,213 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) Request parameters - global info and uris
2012-07-09 14:23:32,213 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) getRemoteAddr = 127.0.0.1
2012-07-09 14:23:32,213 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) getRequestURI = /ucaldav/user/vbede/calendar/
2012-07-09 14:23:32,213 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) getRemoteUser = vbede
2012-07-09 14:23:32,213 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) getRequestedSessionId = 3753CE921FA34612419841C754C9D785
2012-07-09 14:23:32,213 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) HttpUtils.getRequestURL(req) = http://localhost:8080/ucaldav/user/vbede/calendar/
2012-07-09 14:23:32,213 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) contextPath=/ucaldav
2012-07-09 14:23:32,213 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) query=null
2012-07-09 14:23:32,213 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) contentlen=571
2012-07-09 14:23:32,213 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) request=org.apache.catalina.connector.RequestFacade@4526d044
2012-07-09 14:23:32,213 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) parameters:
2012-07-09 14:23:32,213 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) Request parameters
2012-07-09 14:23:32,213 INFO  [edu.rpi.sss.util.jsp.SessionListener] (http-0.0.0.0-8080-8) SESSION-START:2ED3AB22CC319E19D1CDF6B7293A524F:?:1:2368:237M:598M
2012-07-09 14:23:32,213 INFO  [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost].[/ucaldav]] (http-0.0.0.0-8080-8) ========= New session(?): 1 active, 2368 total. vm(used, max)=(237M, 598M)
2012-07-09 14:23:32,214 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) New hibernate session for null
2012-07-09 14:23:32,214 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Open session for null
2012-07-09 14:23:32,214 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Begin transaction for null
2012-07-09 14:23:32,214 DEBUG [org.bedework.calsvc.CalSvc] (http-0.0.0.0-8080-8) Authenticated user vbede logged on
2012-07-09 14:23:32,215 DEBUG [org.bedework.calsvc.directory.UserGroupsLdapImpl] (http-0.0.0.0-8080-8) Logged into LDAP server, javax.naming.ldap.InitialLdapContext@54ad4aa
2012-07-09 14:23:32,217 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) End transaction for 2012-07-09 14:23:32.216
2012-07-09 14:23:32,218 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Close for 2012-07-09 14:23:32.216
2012-07-09 14:23:32,218 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) New hibernate session for 2012-07-09 14:23:32.216
2012-07-09 14:23:32,218 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Open session for 2012-07-09 14:23:32.216
2012-07-09 14:23:32,218 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Begin transaction for 2012-07-09 14:23:32.216
2012-07-09 14:23:32,218 DEBUG [org.bedework.indexer.MessageProcessor] (org.bedework:service=Indexer) Event USER_LOGIN
2012-07-09 14:23:32,221 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) End transaction for 2012-07-09 14:23:32.216
2012-07-09 14:23:32,222 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Close for 2012-07-09 14:23:32.216
2012-07-09 14:23:32,222 DEBUG [org.bedework.indexer.MessageProcessor] (org.bedework:service=Indexer) Event USER_SVCINIT
2012-07-09 14:23:32,222 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) New hibernate session for 2012-07-09 14:23:32.216
2012-07-09 14:23:32,222 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Open session for 2012-07-09 14:23:32.216
2012-07-09 14:23:32,222 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Begin transaction for 2012-07-09 14:23:32.216
2012-07-09 14:23:32,222 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) No charset specified in request; forced to UTF-8
2012-07-09 14:23:32,223 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8) ReportMethod: doMethod
2012-07-09 14:23:32,224 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-8) [2368] <?xml version="1.0"?>
<C:calendar-query xmlns:C="urn:ietf:params:xml:ns:caldav">
    <D:prop xmlns:D="DAV:">
        <D:getetag/>
        <C:calendar-data/>
    </D:prop>
    <C:filter>
        <C:comp-filter name="VCALENDAR">
            <C:comp-filter name="VEVENT">
                <C:prop-filter name="UID">
                    <C:text-match collation="i;ascii-casemap">20120709T142331-4ebff3ea-b5c6-4479-9a7c-0a7a270f6052-vbede</C:text-match>
                </C:prop-filter>
            </C:comp-filter>
        </C:comp-filter>
    </C:filter>
</C:calendar-query>

2012-07-09 14:23:32,224 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8) ReportMethod: depth=1
2012-07-09 14:23:32,224 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-8) [2368] prop: {DAV:}getetag
2012-07-09 14:23:32,224 DEBUG [org.bedework.caldav.server.calquery.CalData] (http-0.0.0.0-8080-8)   <calendar-data  content-type="text/calendar">
2012-07-09 14:23:32,224 DEBUG [org.bedework.caldav.server.calquery.CalData] (http-0.0.0.0-8080-8)   </calendar-data>
2012-07-09 14:23:32,224 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-8) [2368] prop: {urn:ietf:params:xml:ns:caldav}calendar-data
2012-07-09 14:23:32,252 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8) REPORT: query
2012-07-09 14:23:32,252 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8) <filter>
2012-07-09 14:23:32,252 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8)   <comp-filter name="VCALENDAR">
2012-07-09 14:23:32,252 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8)     <comp-filter name="VEVENT">
2012-07-09 14:23:32,252 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8)       <prop-filter name="UID">

2012-07-09 14:23:32,252 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8)         <text-match collation=i;ascii-casemap negate-condition=no>
2012-07-09 14:23:32,252 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8) 20120709T142331-4ebff3ea-b5c6-4479-9a7c-0a7a270f6052-vbede
2012-07-09 14:23:32,252 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8)         </text-match>

2012-07-09 14:23:32,252 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8)       </prop-filter>
2012-07-09 14:23:32,252 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8)     </comp-filter>
2012-07-09 14:23:32,252 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8)   </comp-filter>
2012-07-09 14:23:32,252 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8) </filter>
2012-07-09 14:23:32,252 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) setStatus(207)
2012-07-09 14:23:32,253 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) getWriter called
2012-07-09 14:23:32,253 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8) resourceUri: /user/vbede/calendar/
2012-07-09 14:23:32,253 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-8) [2368] About to get node for /user/vbede/calendar/
2012-07-09 14:23:32,253 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-8) [2368] Normalized uri=/user/vbede/calendar
2012-07-09 14:23:32,253 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-8) [2368] search for collection uri "/user/vbede/calendar"
2012-07-09 14:23:32,254 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-8) [2368] create collection uri - cal="/user/vbede/calendar"
2012-07-09 14:23:32,254 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8) REPORT: preq not null
2012-07-09 14:23:32,254 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8) doNodeAndChildren: curDepth=0 maxDepth=1 uri=/user/vbede/calendar
2012-07-09 14:23:32,254 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8) getNodes: /user/vbede/calendar
2012-07-09 14:23:32,254 DEBUG [org.bedework.calcore.hibernate.CoreEvents] (http-0.0.0.0-8080-8) getEvents for start=null end=null
2012-07-09 14:23:32,254 DEBUG [org.bedework.calcore.hibernate.CoreEvents] (http-0.0.0.0-8080-8) select distinct ev from org.bedework.calfacade.BwEventObj ev where ev.tombstoned=false  and (((ev.entityType=:fq__0) and (lower(ev.uid) like :fq__1) and (ev.colPath=:fq__2)))
2012-07-09 14:23:32,255 DEBUG [org.bedework.calcore.hibernate.CoreEvents] (http-0.0.0.0-8080-8) Getting (0) Found 1 entries
2012-07-09 14:23:32,255 DEBUG [org.bedework.calcore.hibernate.CoreEvents] (http-0.0.0.0-8080-8) select distinct ev from org.bedework.calfacade.BwEventAnnotation ev where ev.tombstoned=false  and  (ev.override = false)
2012-07-09 14:23:32,255 DEBUG [org.bedework.calcore.hibernate.CoreEvents] (http-0.0.0.0-8080-8) Getting (1) Found 0 entries
2012-07-09 14:23:32,255 DEBUG [org.bedework.calcore.hibernate.CoreEvents] (http-0.0.0.0-8080-8) select distinct ev from org.bedework.calfacade.BwEventObj ev, org.bedework.calfacade.BwRecurrenceInstance inst where (inst.master = ev)  and (((ev.entityType=:fq__0) and (lower(ev.uid) like :fq__1) and (ev.colPath=:fq__2)))
2012-07-09 14:23:32,256 DEBUG [org.bedework.calcore.hibernate.CoreEvents] (http-0.0.0.0-8080-8) Getting (4) Found 0 entries
2012-07-09 14:23:32,256 DEBUG [org.bedework.calcore.hibernate.CoreEvents] (http-0.0.0.0-8080-8) select distinct ev from org.bedework.calfacade.BwEventAnnotation ev where ev.tombstoned=false  and  (ev.override = true)
2012-07-09 14:23:32,256 DEBUG [org.bedework.calcore.hibernate.CoreEvents] (http-0.0.0.0-8080-8) Getting (2) Found 0 entries
2012-07-09 14:23:32,256 DEBUG [org.bedework.caldav.server.filter.FilterHandler] (http-0.0.0.0-8080-8) Query returned 1
2012-07-09 14:23:32,256 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-8) [2368] About to get node for /user/vbede/calendar/20120709T142331-4ebff3ea-b5c6-4479-9a7c-0a7a270f6052-vbede.ics
2012-07-09 14:23:32,256 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-8) [2368] Normalized uri=/user/vbede/calendar/20120709T142331-4ebff3ea-b5c6-4479-9a7c-0a7a270f6052-vbede.ics
2012-07-09 14:23:32,257 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-8) [2368] do CalendarData for /user/vbede/calendar/20120709T142331-4ebff3ea-b5c6-4479-9a7c-0a7a270f6052-vbede.ics
2012-07-09 14:23:32,258 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) End transaction for 2012-07-09 14:23:32.216
2012-07-09 14:23:32,259 DEBUG [org.bedework.indexer.MessageProcessor] (org.bedework:service=Indexer) Event CALDAV_IN
2012-07-09 14:23:32,260 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Close for 2012-07-09 14:23:32.216
2012-07-09 14:23:32,260 DEBUG [org.bedework.indexer.MessageProcessor] (org.bedework:service=Indexer) Event ENTITY_FETCHED
2012-07-09 14:23:32,261 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) ------------------------ Dump of response -------------------
2012-07-09 14:23:32,261 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) <?xml version="1.0" encoding="UTF-8" ?>

<DAV:multistatus xmlns:DAV="DAV:"
              xmlns="urn:ietf:params:xml:ns:caldav"
              xmlns:ical="http://www.w3.org/2002/12/cal/ical#">
  <DAV:response>
    <DAV:href>/ucaldav/user/vbede/calendar/20120709T142331-4ebff3ea-b5c6-4479-9a7c-0a7a270f6052-vbede.ics</DAV:href>
    <DAV:propstat>
      <DAV:prop>
        <DAV:getetag>"20120709T122331Z-1067"</DAV:getetag>
        <calendar-data><![CDATA[BEGIN:VCALENDAR
PRODID://Bedework.org//BedeWork V3.8//EN
VERSION:2.0
BEGIN:VEVENT
CREATED:20120709T122331Z
DURATION:PT1H
DTSTAMP:20120709T122331Z
DTSTART:20120710T010000Z
LAST-MODIFIED:20120709T122331Z
SUMMARY:Ein neuer Termin
UID:20120709T142331-4ebff3ea-b5c6-4479-9a7c-0a7a270f6052-vbede
END:VEVENT
END:VCALENDAR
]]></calendar-data>
      </DAV:prop>
      <DAV:status>HTTP/1.1 200 ok</DAV:status>
    </DAV:propstat>
  </DAV:response>
</DAV:multistatus>

2012-07-09 14:23:32,261 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) ---------------------- End dump of response -----------------
2012-07-09 14:23:32,261 DEBUG [org.bedework.indexer.MessageProcessor] (org.bedework:service=Indexer) Event CALDAV_OUT
2012-07-09 14:23:32,261 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) contentLength=922
2012-07-09 14:23:32,261 INFO  [edu.rpi.sss.util.jsp.SessionListener] (http-0.0.0.0-8080-8) SESSION-END:2ED3AB22CC319E19D1CDF6B7293A524F:?:0:2368:231M:598M
2012-07-09 14:23:32,261 INFO  [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost].[/ucaldav]] (http-0.0.0.0-8080-8) ========= Session destroyed(?): 0 active. vm(used, max)=(231M, 598M)
2012-07-09 14:23:32,280 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) entry: PUT
2012-07-09 14:23:32,280 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) Request headers
2012-07-09 14:23:32,280 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8)   authorization = "Basic **********"
2012-07-09 14:23:32,280 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8)   if-match = ""20120709T122331Z-1067""
2012-07-09 14:23:32,280 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8)   user-agent = "Jakarta Commons-HttpClient/3.1"
2012-07-09 14:23:32,280 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8)   host = "localhost:8080"
2012-07-09 14:23:32,280 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8)   cookie = "$Version=0; JSESSIONID=2ED3AB22CC319E19D1CDF6B7293A524F; $Path=/ucaldav"
2012-07-09 14:23:32,280 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8)   content-length = "354"
2012-07-09 14:23:32,280 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8)   content-type = "text/calendar; charset=UTF-8"
2012-07-09 14:23:32,280 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) Request parameters - global info and uris
2012-07-09 14:23:32,281 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) getRemoteAddr = 127.0.0.1
2012-07-09 14:23:32,281 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) getRequestURI = /ucaldav/user/vbede/calendar/20120709T142331-4ebff3ea-b5c6-4479-9a7c-0a7a270f6052-vbede.ics
2012-07-09 14:23:32,281 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) getRemoteUser = vbede
2012-07-09 14:23:32,281 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) getRequestedSessionId = 2ED3AB22CC319E19D1CDF6B7293A524F
2012-07-09 14:23:32,281 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) HttpUtils.getRequestURL(req) = http://localhost:8080/ucaldav/user/vbede/calendar/20120709T142331-4ebff3ea-b5c6-4479-9a7c-0a7a270f6052-vbede.ics
2012-07-09 14:23:32,281 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) contextPath=/ucaldav
2012-07-09 14:23:32,281 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) query=null
2012-07-09 14:23:32,281 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) contentlen=354
2012-07-09 14:23:32,281 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) request=org.apache.catalina.connector.RequestFacade@4526d044
2012-07-09 14:23:32,281 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) parameters:
2012-07-09 14:23:32,281 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) Request parameters
2012-07-09 14:23:32,281 INFO  [edu.rpi.sss.util.jsp.SessionListener] (http-0.0.0.0-8080-8) SESSION-START:F1886758A33063ABD26A913FAA116CCF:?:1:2369:230M:598M
2012-07-09 14:23:32,281 INFO  [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost].[/ucaldav]] (http-0.0.0.0-8080-8) ========= New session(?): 1 active, 2369 total. vm(used, max)=(230M, 598M)
2012-07-09 14:23:32,282 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) New hibernate session for null
2012-07-09 14:23:32,282 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Open session for null
2012-07-09 14:23:32,282 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Begin transaction for null
2012-07-09 14:23:32,282 DEBUG [org.bedework.calsvc.CalSvc] (http-0.0.0.0-8080-8) Authenticated user vbede logged on
2012-07-09 14:23:32,283 DEBUG [org.bedework.calsvc.directory.UserGroupsLdapImpl] (http-0.0.0.0-8080-8) Logged into LDAP server, javax.naming.ldap.InitialLdapContext@27ba0fde
2012-07-09 14:23:32,285 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) End transaction for 2012-07-09 14:23:32.284
2012-07-09 14:23:32,286 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Close for 2012-07-09 14:23:32.284
2012-07-09 14:23:32,286 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) New hibernate session for 2012-07-09 14:23:32.284
2012-07-09 14:23:32,286 DEBUG [org.bedework.indexer.MessageProcessor] (org.bedework:service=Indexer) Event USER_LOGIN
2012-07-09 14:23:32,286 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Open session for 2012-07-09 14:23:32.284
2012-07-09 14:23:32,286 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Begin transaction for 2012-07-09 14:23:32.284
2012-07-09 14:23:32,289 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) End transaction for 2012-07-09 14:23:32.284
2012-07-09 14:23:32,290 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Close for 2012-07-09 14:23:32.284
2012-07-09 14:23:32,290 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) New hibernate session for 2012-07-09 14:23:32.284
2012-07-09 14:23:32,290 DEBUG [org.bedework.indexer.MessageProcessor] (org.bedework:service=Indexer) Event USER_SVCINIT
2012-07-09 14:23:32,290 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Open session for 2012-07-09 14:23:32.284
2012-07-09 14:23:32,291 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Begin transaction for 2012-07-09 14:23:32.284
2012-07-09 14:23:32,291 DEBUG [edu.rpi.cct.webdav.servlet.common.PutMethod] (http-0.0.0.0-8080-8) PutMethod: doMethod
2012-07-09 14:23:32,291 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-8) [2369] About to get node for /user/vbede/calendar/20120709T142331-4ebff3ea-b5c6-4479-9a7c-0a7a270f6052-vbede.ics
2012-07-09 14:23:32,291 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-8) [2369] Normalized uri=/user/vbede/calendar/20120709T142331-4ebff3ea-b5c6-4479-9a7c-0a7a270f6052-vbede.ics
2012-07-09 14:23:32,292 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-8) [2369] find event(s) - cal="/user/vbede/calendar" name="20120709T142331-4ebff3ea-b5c6-4479-9a7c-0a7a270f6052-vbede.ics"
2012-07-09 14:23:32,293 DEBUG [org.bedework.caldav.server.SysIntfReader] (http-0.0.0.0-8080-8) [715] BEGIN:VCALENDAR
2012-07-09 14:23:32,293 DEBUG [org.bedework.caldav.server.SysIntfReader] (http-0.0.0.0-8080-8) [715] PRODID://Bedework.org//BedeWork V3.8//EN
2012-07-09 14:23:32,293 DEBUG [org.bedework.caldav.server.SysIntfReader] (http-0.0.0.0-8080-8) [715] VERSION:2.0
2012-07-09 14:23:32,293 DEBUG [org.bedework.caldav.server.SysIntfReader] (http-0.0.0.0-8080-8) [715] BEGIN:VEVENT
2012-07-09 14:23:32,293 DEBUG [org.bedework.caldav.server.SysIntfReader] (http-0.0.0.0-8080-8) [715] DTSTART:20120710T010000Z
2012-07-09 14:23:32,293 DEBUG [org.bedework.caldav.server.SysIntfReader] (http-0.0.0.0-8080-8) [715] DURATION:PT1H
2012-07-09 14:23:32,293 DEBUG [org.bedework.caldav.server.SysIntfReader] (http-0.0.0.0-8080-8) [715] UID:20120709T142331-4ebff3ea-b5c6-4479-9a7c-0a7a270f6052-vbede
2012-07-09 14:23:32,293 DEBUG [org.bedework.caldav.server.SysIntfReader] (http-0.0.0.0-8080-8) [715] SUMMARY:I changed the summary!
2012-07-09 14:23:32,293 DEBUG [org.bedework.caldav.server.SysIntfReader] (http-0.0.0.0-8080-8) [715] ATTENDEE;PARTSTAT=ACCEPTED:mailto:te...@test.de
2012-07-09 14:23:32,294 DEBUG [org.bedework.caldav.server.SysIntfReader] (http-0.0.0.0-8080-8) [715] DESCRIPTION:Termin geaendert
2012-07-09 14:23:32,299 DEBUG [org.bedework.caldav.server.SysIntfReader] (http-0.0.0.0-8080-8) [715] DTSTAMP:20120709T122332Z
2012-07-09 14:23:32,299 DEBUG [org.bedework.caldav.server.SysIntfReader] (http-0.0.0.0-8080-8) [715] END:VEVENT
2012-07-09 14:23:32,299 DEBUG [org.bedework.caldav.server.SysIntfReader] (http-0.0.0.0-8080-8) [715] END:VCALENDAR
2012-07-09 14:23:32,299 DEBUG [org.bedework.icalendar.IcalUtil] (http-0.0.0.0-8080-8) TRANS-TO_EVENT: try to fetch event with guid=20120709T142331-4ebff3ea-b5c6-4479-9a7c-0a7a270f6052-vbede
2012-07-09 14:23:32,300 DEBUG [org.bedework.icalendar.IcalUtil] (http-0.0.0.0-8080-8) TRANS-TO_EVENT: fetched event with guid
2012-07-09 14:23:32,301 WARN  [org.bedework.calfacade.util.ChangeTable] (http-0.0.0.0-8080-8) No entry for index UNKNOWN_PROPERTY name null
2012-07-09 14:23:32,301 WARN  [org.bedework.calfacade.util.ChangeTable] (http-0.0.0.0-8080-8) No entry for index BUSYTYPE name BUSYTYPE
2012-07-09 14:23:32,301 WARN  [org.bedework.calfacade.util.ChangeTable] (http-0.0.0.0-8080-8) No entry for index COLLECTION name COLLECTION
2012-07-09 14:23:32,301 WARN  [org.bedework.calfacade.util.ChangeTable] (http-0.0.0.0-8080-8) No entry for index LANG name LANGUAGE
2012-07-09 14:23:32,301 WARN  [org.bedework.calfacade.util.ChangeTable] (http-0.0.0.0-8080-8) No entry for index XBEDEWORK_COST name X-BEDEWORK-COST
2012-07-09 14:23:32,301 WARN  [org.bedework.calfacade.util.ChangeTable] (http-0.0.0.0-8080-8) No entry for index CALSCALE name CALSCALE
2012-07-09 14:23:32,301 WARN  [org.bedework.calfacade.util.ChangeTable] (http-0.0.0.0-8080-8) No entry for index METHOD name METHOD
2012-07-09 14:23:32,301 WARN  [org.bedework.calfacade.util.ChangeTable] (http-0.0.0.0-8080-8) No entry for index PRODID name PRODID
2012-07-09 14:23:32,301 WARN  [org.bedework.calfacade.util.ChangeTable] (http-0.0.0.0-8080-8) No entry for index VERSION name VERSION
2012-07-09 14:23:32,302 DEBUG [org.bedework.icalendar.IcalUtil] (http-0.0.0.0-8080-8) ChangeTable{
SUMMARY: changed
DESCRIPTION: changed
DTSTAMP
UID
ATTENDEE: changed
DTSTART
DURATION}
2012-07-09 14:23:32,303 DEBUG [org.bedework.icalendar.IcalUtil] (http-0.0.0.0-8080-8) BwEvent{id=3505, owner=/principals/users/vbede, publick=false,
 creator=/principals/users/vbede, access=null, collection=/user/vbede/calendar,
   entityType=0, deleted=false,
   dtstamp=20120709T122332Z,
   dtstart=BwDateTime{DATETIME, dtval=20120710T010000Z, UTC},
   dtend=BwDateTime{DATETIME, dtval=20120710T020000Z, UTC},
 status=null,
 lastmod=20120709T122331Z, created=20120709T122331Z, stag=null,
   priority=null, classification=null,
 uid=20120709T142331-4ebff3ea-b5c6-4479-9a7c-0a7a270f6052-vbede,
   name=20120709T142331-4ebff3ea-b5c6-4479-9a7c-0a7a270f6052-vbede.ics,
   ctoken=20120709T122331Z-1067
, getRecurring=false,
 organizer=null,
   summary={BwString{id=-1, lang=null, value=I changed the summary!},
   description={BwLongString{id=-1, lang=null, value=Termin geaendert},
   attendee=BwAttendee{id=-1, cn=null, cuType=null, delegatedFrom=null, delegatedTo=null,
      dir=null, language=null, member=null, rsvp=false,
      role=null, partstat=ACCEPTED, sentBy=null, attendeeUri=mailto:test@test.de,
      sequence=0, dtstamp=null,
      scheduleAgent=0, scheduleStatus=null},
   sequence=0, scheduleMethod=0,
   originator=null, scheduleState=0}
2012-07-09 14:23:32,303 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-8) [2369] putContent: intf has event with name 20120709T142331-4ebff3ea-b5c6-4479-9a7c-0a7a270f6052-vbede.ics and summary I changed the summary! new event = false
2012-07-09 14:23:32,303 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-8) [2369] putContent: etag mismatch if="20120709T122331Z-1067"prev="20120709T122331Z-0"
2012-07-09 14:23:32,303 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Enter rollback
2012-07-09 14:23:32,303 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) About to rollback
2012-07-09 14:23:32,303 ERROR [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-8) org.bedework.caldav.server.CaldavBWIntf@9ada14
edu.rpi.cct.webdav.servlet.shared.WebdavException
    at org.bedework.caldav.server.CaldavBWIntf.putEvent(CaldavBWIntf.java:896)
    at org.bedework.caldav.server.CaldavBWIntf.putContent(CaldavBWIntf.java:760)
    at edu.rpi.cct.webdav.servlet.shared.WebdavNsIntf.putContent(WebdavNsIntf.java:680)
    at edu.rpi.cct.webdav.servlet.common.PutMethod.doMethod(PutMethod.java:57)
    at edu.rpi.cct.webdav.servlet.common.WebdavServlet.service(WebdavServlet.java:149)
    at javax.servlet.http.HttpServlet.service(HttpServlet.java:717)
    at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290)
    at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
    at org.jboss.web.tomcat.filters.ReplyHeaderFilter.doFilter(ReplyHeaderFilter.java:96)
    at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235)
    at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
    at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:235)
    at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:191)
    at org.jboss.web.tomcat.security.SecurityAssociationValve.invoke(SecurityAssociationValve.java:190)
    at org.apache.catalina.authenticator.AuthenticatorBase.invoke(AuthenticatorBase.java:525)
    at org.jboss.web.tomcat.security.JaccContextValve.invoke(JaccContextValve.java:92)
    at org.jboss.web.tomcat.security.SecurityContextEstablishmentValve.process(SecurityContextEstablishmentValve.java:126)
    at org.jboss.web.tomcat.security.SecurityContextEstablishmentValve.invoke(SecurityContextEstablishmentValve.java:70)
    at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127)
    at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
    at org.jboss.web.tomcat.service.jca.CachedConnectionValve.invoke(CachedConnectionValve.java:158)
    at org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:567)
    at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
    at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:330)
    at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:829)
    at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:598)
    at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:447)
    at java.lang.Thread.run(Thread.java:662)
2012-07-09 14:23:32,304 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Enter rollback
2012-07-09 14:23:32,304 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) setStatus(412) message=null
2012-07-09 14:23:32,304 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) End transaction for 2012-07-09 14:23:32.284
2012-07-09 14:23:32,304 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Close for 2012-07-09 14:23:32.284
2012-07-09 14:23:32,304 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) ------------------------ Dump of response -------------------
2012-07-09 14:23:32,304 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8)
2012-07-09 14:23:32,304 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) ---------------------- End dump of response -----------------
2012-07-09 14:23:32,304 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) contentLength=0
2012-07-09 14:23:32,304 INFO  [edu.rpi.sss.util.jsp.SessionListener] (http-0.0.0.0-8080-8) SESSION-END:F1886758A33063ABD26A913FAA116CCF:?:0:2369:229M:598M
2012-07-09 14:23:32,304 INFO  [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost].[/ucaldav]] (http-0.0.0.0-8080-8) ========= Session destroyed(?): 0 active. vm(used, max)=(229M, 598M)
2012-07-09 14:23:32,327 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) entry: REPORT
2012-07-09 14:23:32,327 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) Request headers
2012-07-09 14:23:32,327 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8)   authorization = "Basic **********"
2012-07-09 14:23:32,327 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8)   user-agent = "Jakarta Commons-HttpClient/3.1"
2012-07-09 14:23:32,327 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8)   host = "localhost:8080"
2012-07-09 14:23:32,327 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8)   cookie = "$Version=0; JSESSIONID=F1886758A33063ABD26A913FAA116CCF; $Path=/ucaldav"
2012-07-09 14:23:32,327 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8)   content-length = "715"
2012-07-09 14:23:32,327 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8)   depth = "1"
2012-07-09 14:23:32,327 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8)   content-type = "text/xml"
2012-07-09 14:23:32,328 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) Request parameters - global info and uris
2012-07-09 14:23:32,328 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) getRemoteAddr = 127.0.0.1
2012-07-09 14:23:32,328 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) getRequestURI = /ucaldav/user/vbede/calendar/
2012-07-09 14:23:32,328 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) getRemoteUser = vbede
2012-07-09 14:23:32,328 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) getRequestedSessionId = F1886758A33063ABD26A913FAA116CCF
2012-07-09 14:23:32,328 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) HttpUtils.getRequestURL(req) = http://localhost:8080/ucaldav/user/vbede/calendar/
2012-07-09 14:23:32,328 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) contextPath=/ucaldav
2012-07-09 14:23:32,328 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) query=null
2012-07-09 14:23:32,328 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) contentlen=715
2012-07-09 14:23:32,328 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) request=org.apache.catalina.connector.RequestFacade@4526d044
2012-07-09 14:23:32,328 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) parameters:
2012-07-09 14:23:32,328 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) Request parameters
2012-07-09 14:23:32,328 INFO  [edu.rpi.sss.util.jsp.SessionListener] (http-0.0.0.0-8080-8) SESSION-START:A206117587CCC8AB66326AC439B7A194:?:1:2370:229M:598M
2012-07-09 14:23:32,328 INFO  [org.apache.catalina.core.ContainerBase.[jboss.web].[localhost].[/ucaldav]] (http-0.0.0.0-8080-8) ========= New session(?): 1 active, 2370 total. vm(used, max)=(229M, 598M)
2012-07-09 14:23:32,328 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) New hibernate session for null
2012-07-09 14:23:32,328 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Open session for null
2012-07-09 14:23:32,329 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Begin transaction for null
2012-07-09 14:23:32,329 DEBUG [org.bedework.calsvc.CalSvc] (http-0.0.0.0-8080-8) Authenticated user vbede logged on
2012-07-09 14:23:32,332 DEBUG [org.bedework.calsvc.directory.UserGroupsLdapImpl] (http-0.0.0.0-8080-8) Logged into LDAP server, javax.naming.ldap.InitialLdapContext@1b5ca52f
2012-07-09 14:23:32,334 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) End transaction for 2012-07-09 14:23:32.333
2012-07-09 14:23:32,335 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Close for 2012-07-09 14:23:32.333
2012-07-09 14:23:32,335 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) New hibernate session for 2012-07-09 14:23:32.333
2012-07-09 14:23:32,335 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Open session for 2012-07-09 14:23:32.333
2012-07-09 14:23:32,335 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Begin transaction for 2012-07-09 14:23:32.333
2012-07-09 14:23:32,335 DEBUG [org.bedework.indexer.MessageProcessor] (org.bedework:service=Indexer) Event USER_LOGIN
2012-07-09 14:23:32,338 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) End transaction for 2012-07-09 14:23:32.333
2012-07-09 14:23:32,339 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Close for 2012-07-09 14:23:32.333
2012-07-09 14:23:32,339 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) New hibernate session for 2012-07-09 14:23:32.333
2012-07-09 14:23:32,339 DEBUG [org.bedework.indexer.MessageProcessor] (org.bedework:service=Indexer) Event USER_SVCINIT
2012-07-09 14:23:32,339 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Open session for 2012-07-09 14:23:32.333
2012-07-09 14:23:32,339 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Begin transaction for 2012-07-09 14:23:32.333
2012-07-09 14:23:32,339 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) No charset specified in request; forced to UTF-8
2012-07-09 14:23:32,339 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8) ReportMethod: doMethod
2012-07-09 14:23:32,341 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-8) [2370] <?xml version="1.0"?>
<C:calendar-query xmlns:C="urn:ietf:params:xml:ns:caldav">
    <D:prop xmlns:D="DAV:">
        <D:getetag/>
        <C:calendar-data>
            <C:comp name="VCALENDAR">
                <C:comp name="VEVENT">
                    <C:prop name="UID"/>
                </C:comp>
            </C:comp>
        </C:calendar-data>
    </D:prop>
    <C:filter>
        <C:comp-filter name="VCALENDAR">
            <C:comp-filter name="VEVENT">
                <C:prop-filter name="DTSTAMP">
                    <C:time-range end="20120701T000000Z" start="20120710T010000Z"/>
                </C:prop-filter>
            </C:comp-filter>
        </C:comp-filter>
    </C:filter>
</C:calendar-query>

2012-07-09 14:23:32,341 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8) ReportMethod: depth=1
2012-07-09 14:23:32,341 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-8) [2370] prop: {DAV:}getetag
2012-07-09 14:23:32,341 DEBUG [org.bedework.caldav.server.calquery.CalData] (http-0.0.0.0-8080-8) calendar-data node type: 1 name:C:comp
2012-07-09 14:23:32,341 DEBUG [org.bedework.caldav.server.calquery.CalData] (http-0.0.0.0-8080-8) comp node type: 1 name:C:comp
2012-07-09 14:23:32,341 DEBUG [org.bedework.caldav.server.calquery.CalData] (http-0.0.0.0-8080-8) comp node type: 1 name:C:prop
2012-07-09 14:23:32,341 DEBUG [org.bedework.caldav.server.calquery.CalData] (http-0.0.0.0-8080-8)   <calendar-data  content-type="text/calendar">
2012-07-09 14:23:32,341 DEBUG [org.bedework.caldav.server.calquery.CalData] (http-0.0.0.0-8080-8)     <comp name=VCALENDAR>
2012-07-09 14:23:32,341 DEBUG [org.bedework.caldav.server.calquery.CalData] (http-0.0.0.0-8080-8)       <comp name=VEVENT>
2012-07-09 14:23:32,341 DEBUG [org.bedework.caldav.server.calquery.CalData] (http-0.0.0.0-8080-8)         <calddav:prop name=UID novalue=no/>
2012-07-09 14:23:32,341 DEBUG [org.bedework.caldav.server.calquery.CalData] (http-0.0.0.0-8080-8)       </comp>
2012-07-09 14:23:32,341 DEBUG [org.bedework.caldav.server.calquery.CalData] (http-0.0.0.0-8080-8)     </comp>
2012-07-09 14:23:32,341 DEBUG [org.bedework.caldav.server.calquery.CalData] (http-0.0.0.0-8080-8)   </calendar-data>
2012-07-09 14:23:32,341 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-8) [2370] prop: {urn:ietf:params:xml:ns:caldav}calendar-data
2012-07-09 14:23:32,370 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8) REPORT: query
2012-07-09 14:23:32,370 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8) <filter>
2012-07-09 14:23:32,370 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8)   <comp-filter name="VCALENDAR">
2012-07-09 14:23:32,370 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8)     <comp-filter name="VEVENT">
2012-07-09 14:23:32,370 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8)       <prop-filter name="DTSTAMP">

2012-07-09 14:23:32,370 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8)         <time-range start=20120710T010000Z end=20120701T000000Z/>
2012-07-09 14:23:32,370 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8)       </prop-filter>
2012-07-09 14:23:32,370 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8)     </comp-filter>
2012-07-09 14:23:32,370 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8)   </comp-filter>
2012-07-09 14:23:32,370 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8) </filter>
2012-07-09 14:23:32,370 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) setStatus(207)
2012-07-09 14:23:32,370 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) getWriter called
2012-07-09 14:23:32,370 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8) resourceUri: /user/vbede/calendar/
2012-07-09 14:23:32,370 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-8) [2370] About to get node for /user/vbede/calendar/
2012-07-09 14:23:32,370 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-8) [2370] Normalized uri=/user/vbede/calendar
2012-07-09 14:23:32,370 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-8) [2370] search for collection uri "/user/vbede/calendar"
2012-07-09 14:23:32,372 DEBUG [org.bedework.caldav.server.CaldavBWIntf] (http-0.0.0.0-8080-8) [2370] create collection uri - cal="/user/vbede/calendar"
2012-07-09 14:23:32,373 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8) REPORT: preq not null
2012-07-09 14:23:32,373 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8) doNodeAndChildren: curDepth=0 maxDepth=1 uri=/user/vbede/calendar
2012-07-09 14:23:32,373 DEBUG [org.bedework.caldav.server.CaldavReportMethod] (http-0.0.0.0-8080-8) getNodes: /user/vbede/calendar
2012-07-09 14:23:32,373 DEBUG [org.bedework.calcore.hibernate.CoreEvents] (http-0.0.0.0-8080-8) getEvents for start=null end=null
2012-07-09 14:23:32,373 DEBUG [org.bedework.calcore.hibernate.CoreEvents] (http-0.0.0.0-8080-8) select distinct ev.lastmod, ev.uid, ev.access, ev.colPath, ev.created, ev.creatorHref, ev.dtend, ev.dtstamp, ev.dtstart, ev.duration, ev.endType, ev.entityType, ev.id, ev.name, ev.noStart, ev.ownerHref from org.bedework.calfacade.BwEventObj ev where ev.tombstoned=false  and (((ev.entityType=:fq__0) and ((ev.dtstamp>=:fq__1) and (ev.dtstamp<:fq__2)) and (ev.colPath=:fq__3)))
2012-07-09 14:23:32,386 DEBUG [org.bedework.calcore.hibernate.CoreEvents] (http-0.0.0.0-8080-8) Getting (0) Found 0 entries
2012-07-09 14:23:32,386 DEBUG [org.bedework.calcore.hibernate.CoreEvents] (http-0.0.0.0-8080-8) select distinct ev.lastmod, ev.uid, ev.access, ev.colPath, ev.created, ev.creatorHref, ev.dtend, ev.dtstamp, ev.dtstart, ev.duration, ev.endType, ev.entityType, ev.id, ev.name, ev.noStart, ev.ownerHref, ev.override, ev.target, ev.master from org.bedework.calfacade.BwEventAnnotation ev where ev.tombstoned=false  and  (ev.override = false)
2012-07-09 14:23:32,398 DEBUG [org.bedework.calcore.hibernate.CoreEvents] (http-0.0.0.0-8080-8) Getting (1) Found 0 entries
2012-07-09 14:23:32,398 DEBUG [org.bedework.calcore.hibernate.CoreEvents] (http-0.0.0.0-8080-8) select distinct ev.lastmod, ev.uid, ev.access, ev.colPath, ev.created, ev.creatorHref, ev.dtend, ev.dtstamp, ev.dtstart, ev.duration, ev.endType, ev.entityType, ev.id, ev.name, ev.noStart, ev.ownerHref from org.bedework.calfacade.BwEventObj ev, org.bedework.calfacade.BwRecurrenceInstance inst where (inst.master = ev)  and (((ev.entityType=:fq__0) and ((ev.dtstamp>=:fq__1) and (ev.dtstamp<:fq__2)) and (ev.colPath=:fq__3)))
2012-07-09 14:23:32,410 DEBUG [org.bedework.calcore.hibernate.CoreEvents] (http-0.0.0.0-8080-8) Getting (4) Found 0 entries
2012-07-09 14:23:32,411 DEBUG [org.bedework.calcore.hibernate.CoreEvents] (http-0.0.0.0-8080-8) select distinct ev from org.bedework.calfacade.BwEventAnnotation ev where ev.tombstoned=false  and  (ev.override = true)
2012-07-09 14:23:32,411 DEBUG [org.bedework.calcore.hibernate.CoreEvents] (http-0.0.0.0-8080-8) Getting (2) Found 0 entries
2012-07-09 14:23:32,411 DEBUG [org.bedework.caldav.server.filter.FilterHandler] (http-0.0.0.0-8080-8) Query returned 0
2012-07-09 14:23:32,411 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) End transaction for 2012-07-09 14:23:32.333
2012-07-09 14:23:32,413 DEBUG [org.bedework.indexer.MessageProcessor] (org.bedework:service=Indexer) Event CALDAV_IN
2012-07-09 14:23:32,413 DEBUG [org.bedework.calcore.hibernate.CalintfImpl] (http-0.0.0.0-8080-8) Close for 2012-07-09 14:23:32.333
2012-07-09 14:23:32,413 DEBUG [org.bedework.indexer.MessageProcessor] (org.bedework:service=Indexer) Event ENTITY_FETCHED
2012-07-09 14:23:32,414 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) ------------------------ Dump of response -------------------
2012-07-09 14:23:32,414 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) <?xml version="1.0" encoding="UTF-8" ?>

<DAV:multistatus xmlns:DAV="DAV:"
              xmlns="urn:ietf:params:xml:ns:caldav"
              xmlns:ical="http://www.w3.org/2002/12/cal/ical#">
</DAV:multistatus>

2012-07-09 14:23:32,414 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) ---------------------- End dump of response -----------------
2012-07-09 14:23:32,414 DEBUG [org.bedework.caldav.server.CaldavBWServlet] (http-0.0.0.0-8080-8) contentLength=210
2012-07-09 14:23:32,414 DEBUG [org.bedework.indexer.MessageProcessor] (org.bedework:service=Indexer) Event CALDAV_OUT


When i run the Unit tests i became this errors:

Results :

Failed tests:
  testGetCalendarByPath(org.osaf.caldav4j.CalDAVCalendarCollectionWithCacheTest)

  testGetEventResources(org.osaf.caldav4j.CalDAVCalendarCollectionWithCacheTest): expected:<0> but was:<1>
  testGetEventResources(org.osaf.caldav4j.CalDAVCollectionTest): expected:<3> but was:<0>
  testGetEventResourcesFloatingIssues(org.osaf.caldav4j.CalDAVCollectionTest)
  queryPartialCalendar(org.osaf.caldav4j.CalDAVCollectionTest): bad number of responses:  expected:<3> but was:<1>
  testAddRemoveCalendarResource(org.osaf.caldav4j.google.methods.GooglePutGetTest): Response status expected:<201> but was:<405>

Tests in error:
  testUpdateEvent(org.osaf.caldav4j.CalDAVCalendarCollectionTest): Problem executing put method
  testUpdateEvent(org.osaf.caldav4j.CalDAVCalendarCollectionWithCacheTest): Problem executing put method
  testGetOptions(org.osaf.caldav4j.CalDAVCollectionAceTest)
  testUpdateEvent(org.osaf.caldav4j.CalDAVCollectionTest): Problem executing put method



can anyone help me what is wrong?
Thanks Regards



Roberto Polli

unread,
Jul 10, 2012, 5:22:01 AM7/10/12
to cald...@googlegroups.com
Hi Hans,

the http.wire is in
./src/test/resources/log4j.xml

Set the following value to DEBUG. The log will show in caldav4j
(eclipse console)

<logger name="httpclient.wire">
<level value="DEBUG"/>
</logger>


Bedework logs are strange. Surely the wire will give us a simple way
to read it. Consider joining the chat:
irc.freenode.net#caldav

2012/7/10 Hans Will <hansw...@googlemail.com>:
> <DAV:href>/ucaldav/user/vbede/calendar/20120710T083606-77caf433-2c7d-4e68-9383-2d8077c52c31-vbede.ics</DAV:href>
> <DAV:getetag>"20120710T063606Z-0333"</DAV:getetag>


> (http-0.0.0.0-8080-10) [453] putContent: etag mismatch
> if="20120710T063606Z-0333" prev="20120710T063606Z-0"
Bedework says that the given event has etag: 20120710T063606Z-0
While you checked for: 20120710T063606Z-0333

Strange enough is that in your request the etag was "20120710T063606Z-0333"


Peace,
R.

Hans Will

unread,
Jul 10, 2012, 7:18:21 AM7/10/12
to cald...@googlegroups.com
ok i have setup the wire log.
I am in chat now
which part of the log you wont have?

yes the Etag Problem is curios, i dont unterstand this Etag problem

Thanks

Roberto Polli

unread,
Mar 26, 2013, 5:14:29 PM3/26/13
to cald...@googlegroups.com
2013/3/26 <gp9...@gmail.com>:
> Can either of (Hans, Roberto) say how this problem was resolved? I'm running
> Bedework/CalDAV4J and I'm getting the same error, "putContent: etag
> mismatch" on Bedework 3.8 but it works in 3.9.
If you pass the right etags to the server, then it's a bedework 3.8 bug.

Please paste *only* the request-response dialog (not the whole verbose
logs, just what's going on at HTTP level) and we'll see if where's the
issue.

If you're sure it's a bw 3.8 bug, then you should patch and rebuild..

Let us know + Peace,
R.

gp9...@gmail.com

unread,
Mar 26, 2013, 7:08:02 PM3/26/13
to cald...@googlegroups.com
Thanks for the response. I'm having trouble turning on the http.wire debugging. I set the value in the src/test/resources/log4j.xml, and rebuilt the library. It's not displaying on the Eclipse console. I also tried putting test/resources/log4j.xml in my src directory with no luck.
I have Eclipse building my client and am referencing the required libraries (including CalDAV4J). Can you tell me how to make it work?

Thanks!

Roberto Polli

unread,
Mar 27, 2013, 5:37:07 AM3/27/13
to cald...@googlegroups.com
2013/3/27 <gp9...@gmail.com>:
> Thanks for the response. I'm having trouble turning on the http.wire
> debugging. I set the value in the src/test/resources/log4j.xml, and rebuilt
> the library.
You can check your log4j.properties too - eventually removing
log4j.properties you have in your jars.

You don't need to rebuild after changing properties.

Let me know + Peace,
R.
Reply all
Reply to author
Forward
0 new messages