CalDAV Redirect 302 with empty header

[oudeis]

Hi,
I'm using DAVdroid (0.7.2) on Android 5.1 and since upgrading to Zimbra 8.6 (version 8.6.0_GA_1153.FOSS) the synchronisation to a shared calendar (belonging to another user) doesn't work anymore.
The relevant Zimbra logs:
2015-04-08 19:05:37,634 INFO  [qtp509886383-597016:https://aaa.aaa.aaa.aaa:443/dav/otherus ... a06876.ics] [aname=otheruser@xxxxxxx.xx;ip=212.233.58.49;ua=DAVdroid/0.7.2;] FileUploadServlet - saveUpload(): received Upload: { accountId=888cc41c-44ba-4417-91cd-610c0f9fb45d, time=Wed Apr 08 19:05:37 CEST 2015, size=4006, uploadId=7cc1b01e-b163-4851-a32d-59d09e8a9819:1d4f0474-0e0f-4fe5-946d-2b43dac9c891, name=null, path=null }
2015-04-08 19:05:37,675 INFO  [qtp509886383-597018:https://127.0.1.1:443/dav/mainuser@xxxx ... a06876.ics] [aname=otheruser@xxxxxxx.xx;ip=127.0.0.1;ua=Jakarta Commons-HttpClient/3.1;] FileUploadServlet - saveUpload(): received Upload: { accountId=888cc41c-44ba-4417-91cd-610c0f9fb45d, time=Wed Apr 08 19:05:37 CEST 2015, size=4006, uploadId=7cc1b01e-b163-4851-a32d-59d09e8a9819:52cb5052-3299-43b3-8755-fdc1f8bb5944, name=null, path=null }
2015-04-08 19:05:37,677 INFO  [qtp509886383-597018:https://127.0.1.1:443/dav/mainuser@xxxx ... a06876.ics] [aname=otheruser@xxxxxxx.xx;ip=127.0.0.1;ua=Jakarta Commons-HttpClient/3.1;] dav - sending redirect
2015-04-08 19:05:37,677 INFO  [qtp509886383-597018:https://127.0.1.1:443/dav/mainuser@xxxx ... a06876.ics] [aname=otheruser@xxxxxxx.xx;ip=127.0.0.1;ua=Jakarta Commons-HttpClient/3.1;] dav - sending http error 302 because: wrong url - redirecting to:
https://mother.xxxxxx.xx:443/dav/mainus ... a06876.ics


But on the  DAVdroid side, the redirect reply received from Zimbra has an 0-sized header:
D/Wire    (10941): http-outgoing-0 << "HTTP/1.1 302 Found[
][
]"
D/Wire    (10941): http-outgoing-0 << "Date: Wed, 08 Apr 2015 17:01:19 GMT[
][
]"
D/Wire    (10941): http-outgoing-0 << "Content-Length: 0[
][
]"
D/Wire    (10941): http-outgoing-0 << "[
][
]"
E/davdroid.DavRedirectStrategy(10941): Received redirection without Location header, ignoring


There seems to be a problem on the Zimbra side...
Thanks a lot!
Carlos.

[zstephen]

I think have a similar issue, I am runnning DAVdroid 0.8.0 and Zimbra 8.6.0_GA_1162 (build 20150225174718)
DAV troubleshooting is new to me, so I'm not sure where to start, I assumed that my problem event is the 20150604T120505Z-3443_518a4a2985156.ics file, but I haven't been able to find a way to locate that file on the server(I have access to do so) to look inside it.
It looks like same server behaviour is causing the same reaction on DAVdroid, but by a corrupted calendar item?
Here is a debug snippet I got from the phone:
06-04 22:05:05.029    3443-3602/? I/davdroid.SyncManager﹕ Removing non-dirty resources that are not present remotely anymore
06-04 22:05:05.040    3443-3602/? D/davdroid.Collection﹕ Committing 2 operations
06-04 22:05:05.143    3443-3602/? I/davdroid.SyncManager﹕ Sync complete, fetching new CTag
06-04 22:05:05.163    3443-3602/? I/davdroid.SyncManager﹕ Remotely removing 0 deleted resource(s) (if not changed)
06-04 22:05:05.196    3443-3602/? I/davdroid.SyncManager﹕ Uploading 1 new resource(s) (if not existing)
06-04 22:05:05.237    3443-3602/? V/davdroid.URIUtils﹕ Normalized URL 20150604T120505Z-3443_518a4a2985156.ics -> 20150604T120505Z-3443_518a4a2985156.ics
06-04 22:05:05.677    3443-3602/? E/davdroid.DavRedirectStrategy﹕ Received redirection without Location header, ignoring
06-04 22:05:05.679    3443-3602/? W/davdroid.DavSyncAdapter﹕ Soft HTTP error 302 (Android will try again later)
    at.bitfire.davdroid.webdav.HttpException: 302 Found
            at at.bitfire.davdroid.webdav.WebDavResource.checkResponse(WebDavResource.java:431)
            at at.bitfire.davdroid.webdav.WebDavResource.checkResponse(WebDavResource.java:405)
            at at.bitfire.davdroid.webdav.WebDavResource.put(WebDavResource.java:382)
            at at.bitfire.davdroid.resource.RemoteCollection.add(RemoteCollection.java:180)
            at at.bitfire.davdroid.syncadapter.SyncManager.pushNew(SyncManager.java:143)
            at at.bitfire.davdroid.syncadapter.SyncManager.synchronize(SyncManager.java:49)
            at at.bitfire.davdroid.syncadapter.DavSyncAdapter.onPerformSync(DavSyncAdapter.java:146)
            at android.content.AbstractThreadedSyncAdapter$SyncThread.run(AbstractThreadedSyncAdapter.java:259)
06-04 22:05:05.679    3443-3602/? I/davdroid.DavSyncAdapter﹕ Sync complete for com.android.calendar
06-04 22:05:05.692    3443-3443/? D/davdroid.DavSyncAdapter﹕ Closing httpClient
06-04 22:05:05.695      709-728/? D/SyncManager﹕ failed sync operation user@xxxx.com.au u0 (bitfire.at.davdroid), com.android.calendar, LOCAL, currentRunTime 27866, reason: 10002, SyncResult: stats [ numIoExceptions: 1 numUpdates: 35 numEntries: 35]
06-04 22:05:15.783     709-1829/? I/ActivityManager﹕ Killing 3443:at.bitfire.davdroid:sync/u0a92 (adj 15): empty #17

If this is a separate issue from Carlos', let me know and I'll file it accordingly.

Thanks,
Stephen

[zstephen]

Hi I did some further investigating at the zimbra server end. The .ics file being referred to does not exist at that location, the first block of text in orange, is a date/time stamp, which I accounted for, but even the last string in the file isn't found. I checked this by using a dav browser and downloading all the .ics files from the server at the location being quoted in the error.

I used tail -f /opt/zimbra/log/mailbox.log | grep dav to get these lines of output, and to trigger their occurrence I turned off then back on the calendar sync on my DAVdroid profile.


2015-06-05 22:57:46,171 INFO  [qtp509886383-17591:https://10.0.12.9:8443/dav/user1@domain ... 985156.ics] [aname=user1@domain.com.au;ip=10.0.12.9;ua=DAVdroid/0.8.0;] FileUploadServlet - saveUpload(): received Upload: { accountId=db67985d-2ae3-411b-a79c-9e8a674dcc89, time=Fri Jun 05 22:57:46 EST 2015, size=3165, uploadId=dbc3185c-e8ac-4e27-8740-29ef8144a571:a577dde6-0cbd-47c6-ac00-14cf6e3d8d80, name=null, path=null }
2015-06-05 22:57:46,209 INFO  [qtp509886383-17588:https://10.0.12.9:8443/dav/user2@domain ... 985156.ics] [aname=user1@domain.com.au;ip=10.0.12.9;ua=Jakarta Commons-HttpClient/3.1;] FileUploadServlet - saveUpload(): received Upload: { accountId=db67985d-2ae3-411b-a79c-9e8a674dcc89, time=Fri Jun 05 22:57:46 EST 2015, size=3165, uploadId=dbc3185c-e8ac-4e27-8740-29ef8144a571:0dd27575-f24a-40b9-9610-65eef7fcd6eb, name=null, path=null }
2015-06-05 22:57:46,211 INFO  [qtp509886383-17588:https://10.0.12.9:8443/dav/user2@domain ... 985156.ics] [aname=user1@domain.com.au;ip=10.0.12.9;ua=Jakarta Commons-HttpClient/3.1;] dav - sending redirect
2015-06-05 22:57:46,211 INFO  [qtp509886383-17588:https://10.0.12.9:8443/dav/user2@domain ... 985156.ics] [aname=user1@domain.com.au;ip=10.0.12.9;ua=Jakarta Commons-HttpClient/3.1;] dav - sending http error 302 because: wrong url - redirecting to:
https://mail.domain.com.au/dav/user2@do ... 985156.ics
2015-06-05 22:57:46,211 INFO  [qtp509886383-17588:https://10.0.12.9:8443/dav/user2@domain ... 985156.ics] [aname=user1@domain.com.au;ip=10.0.12.9;ua=Jakarta Commons-HttpClient/3.1;] dav - DavServlet operation PUT to /home/user2@domain.com.au/Calendar/20150605T125745Z-12265_518a4a2985156.ics (depth: zero) finished in 33ms
2015-06-05 22:57:46,211 INFO  [qtp509886383-17591:https://10.0.12.9:8443/dav/user1@domain ... 985156.ics] [aname=user1@domain.com.au;ip=10.0.12.9;ua=DAVdroid/0.8.0;] dav - DavServlet operation PUT to /home/user1@domain.com.au/user2's Calendar/20150605T125745Z-12265_518a4a2985156.ics (depth: zero) finished in 234ms

[Gren Elliot]

Thank you for finding and reporting this issue.  I have raised https://bugzilla.zimbra.com/show_bug.cgi?id=100479 to track this issue.  (Please feel free to submit bugs directly into Bugzilla - that is probably the best way to ensure issues are properly tracked - can always post here to with a reference to the bug if you want the community perspective)
Regards,
Gren.