Birdiesync won't write events to mobile from caldav

Erk · Post by **Erk** » Tue May 15, 2007 3:05 pm

Hi there

I'm currently testing birdiesync with a HTC 4350, Thunderbird/Lightning. I
think it works ok with local calender, but not with remote caldav.

It seems that events are read from the mobile phone and written ok to Lightning calendar. The other way wont work. The sync process seems to loop infinitly trying to read the events from Lightning and writing them in the phone. I tested with two events and you could see ActiveSync trying to sync these two events. When i removed the events from the remote calendar, the sync process ended in 'success'.

My setup:
Server (CalDAV):
- Ubuntu server 7.04 (Feisty Fawn)
- Apache 2
- PHP 5
- Postgresql 8.2
- RSCDS (Really Simple CalDAV Store)

Client
- Windows XP SP2
- Thunderbird 2.0.0.0, Lightning 0.3.1
- Birdiesync 1.5.3.1
- Patch (calDavCalendar.js)

Mobile phone:
- HTC p4350
- Windows Mobile 5

Kind regards
/Erik

Post by **Birdy** » Wed May 16, 2007 8:49 pm

Hello,

As mentioned in the mail I sent you, I will need some logs to investigate on the problem. Could you send me them as described in this thread ?

Taliesin · Post by **Taliesin** » Mon Oct 08, 2007 6:07 am

I have same problem tride to apply patch for caldav but its for lightning 0.3.1 , new birdiesync is no longer compatibile with this version with 0.5 its not working with remote caldav calendar. It is going to loop syncing. Without lightning caldav patch there is info "It is impossible to list events of calendar".

DesktopProvider.log

Code: Select all

Local 08/10/2007 07:42:42::0687 BirdieSync: version 1.6.1.1
Local 08/10/2007 07:42:42::0687 Operating system: id 2, version 5.1, SP 2.0
Local 08/10/2007 07:42:47::0109 Card synchronization: False
Local 08/10/2007 07:42:47::0109 Event synchronization: True
Local 08/10/2007 07:42:47::0109 Task synchronization: False
Local 08/10/2007 07:42:47::0109 Mail synchronization: False
Local 08/10/2007 07:42:51::0671 ERROR: Bad result returned by GetObjectRequest, id = 3030a477-08e5-c743-a78c-063e37e2316e (GetLastError = 0)
Local 08/10/2007 07:42:51::0984 ERROR: Bad result returned by GetObjectRequest, id = 27f4b21a-5aa2-446d-9561-7fa3f4922f85 (GetLastError = 0)
Local 08/10/2007 07:42:52::0296 ERROR: Bad result returned by GetObjectRequest, id = aaf26a14-c03c-47f3-ae4f-69727a4aec24 (GetLastError = 0)
Local 08/10/2007 07:42:52::0609 ERROR: Bad result returned by GetObjectRequest, id = b66362e7-0b4e-194e-a727-2847b13c4ddd (GetLastError = 0)
Local 08/10/2007 07:42:52::0921 ERROR: Bad result returned by GetObjectRequest, id = a6295992-af0a-41b5-acb8-5a8ca6cd8ae9 (GetLastError = 0)
Local 08/10/2007 07:42:55::0187 ERROR: Bad result returned by GetObjectRequest, id = 3030a477-08e5-c743-a78c-063e37e2316e (GetLastError = 0)
Local 08/10/2007 07:42:55::0500 ERROR: Bad result returned by GetObjectRequest, id = 27f4b21a-5aa2-446d-9561-7fa3f4922f85 (GetLastError = 0)
Local 08/10/2007 07:42:55::0812 ERROR: Bad result returned by GetObjectRequest, id = aaf26a14-c03c-47f3-ae4f-69727a4aec24 (GetLastError = 0)
Local 08/10/2007 07:42:56::0125 ERROR: Bad result returned by GetObjectRequest, id = b66362e7-0b4e-194e-a727-2847b13c4ddd (GetLastError = 0)
Local 08/10/2007 07:42:56::0437 ERROR: Bad result returned by GetObjectRequest, id = a6295992-af0a-41b5-acb8-5a8ca6cd8ae9 (GetLastError = 0)

ThunderbirdService.log

Code: Select all

Local 08/10/2007 07:41:51::0359 BirdieSync: version 1.6.1.1
Local 08/10/2007 07:41:51::0359 Operating system: id 2, version 5.1, SP 2.0
Local 08/10/2007 07:41:51::0359 Thunderbird: version 2.0.0.6
Local 08/10/2007 07:41:51::0375 Lightning extension: version 0.5
Local 08/10/2007 07:41:51::0375 Lightning extension is enabled
Local 08/10/2007 07:42:51::0671 ERROR: An error occured returned in on complete, error = 0x80004005: XXX something bad happened (GetLastError = 0)
Local 08/10/2007 07:42:51::0671 ERROR: Error while getting Event object (GetLastError = 0)
Local 08/10/2007 07:42:51::0828 ERROR: An error occured returned in on complete, error = 0x80004005: XXX something bad happened (GetLastError = 0)
Local 08/10/2007 07:42:51::0984 ERROR: An error occured returned in on complete, error = 0x80004005: XXX something bad happened (GetLastError = 0)
Local 08/10/2007 07:42:51::0984 ERROR: Error while getting Event object (GetLastError = 0)
Local 08/10/2007 07:42:52::0140 ERROR: An error occured returned in on complete, error = 0x80004005: XXX something bad happened (GetLastError = 0)
Local 08/10/2007 07:42:52::0296 ERROR: An error occured returned in on complete, error = 0x80004005: XXX something bad happened (GetLastError = 0)
Local 08/10/2007 07:42:52::0296 ERROR: Error while getting Event object (GetLastError = 0)
Local 08/10/2007 07:42:52::0453 ERROR: An error occured returned in on complete, error = 0x80004005: XXX something bad happened (GetLastError = 0)
Local 08/10/2007 07:42:52::0609 ERROR: An error occured returned in on complete, error = 0x80004005: XXX something bad happened (GetLastError = 0)
Local 08/10/2007 07:42:52::0609 ERROR: Error while getting Event object (GetLastError = 0)
Local 08/10/2007 07:42:52::0765 ERROR: An error occured returned in on complete, error = 0x80004005: XXX something bad happened (GetLastError = 0)
Local 08/10/2007 07:42:52::0921 ERROR: An error occured returned in on complete, error = 0x80004005: XXX something bad happened (GetLastError = 0)
Local 08/10/2007 07:42:52::0921 ERROR: Error while getting Event object (GetLastError = 0)

TransferLog.log

Code: Select all

Local 08/10/2007 07:42:36::0656 Connected with device
Local 08/10/2007 07:42:38::0546 Transfer completed

Post by **Birdy** » Mon Oct 08, 2007 3:37 pm

Hello,

Do you know which calDAV server you use to store your calendar ?

Taliesin · Post by **Taliesin** » Mon Oct 08, 2007 4:04 pm

The one mentioned above RSCDS http://rscds.sourceforge.net/ .

Taliesin · Post by **Taliesin** » Tue Oct 09, 2007 5:37 pm

Do you need more logs or something ?
Do you plan to write new "caldav" patch for lightning ?
I buy you soft for sure when it will be done

Post by **Birdy** » Tue Oct 09, 2007 5:47 pm

Do you have access to the logs of RSCDS ?
If yes, did you see 422 errors regarding received requests ?
I would have been interested in getting details about it...
Maybe a request sent by Lightning which is not understood by RSCDS ?
You can send these logs to support(at)birdiesync.com.

Taliesin · Post by **Taliesin** » Tue Oct 09, 2007 6:13 pm

Yes i'm admin of this caldav server

Here are erorrs from caldav server with caldav patch

Code: Select all

[Tue Oct 09 20:00:39 2007] [error] [client xxx] rscds: LOG: :==========> method =REPORT= =http://xxx/caldav.php= =/mantykora/home/
= == =/usr/share/rscds=
[Tue Oct 09 20:00:39 2007] [error] [client xxx] rscds: ***: ERROR: REPORT: Support for URN:IETF:PARAMS:XML:NS:CALDAV:PROP-FILTER properties is not im
plemented yet
[Tue Oct 09 20:00:39 2007] [error] [clien txx] rscds: ***: ERROR: REPORT: Support for URN:IETF:PARAMS:XML:NS:CALDAV:TEXT-MATCH properties is not imp
lemented yet

and

Code: Select all

xxx - mantykora [09/Oct/2007:20:00:07 +0200] "REPORT /caldav.php/mantykora/home/ HTTP/1.1" 207 1450 "-" "Mozilla/5.0 (Windows; U; Windows NT 5.1; pl;
 rv:1.8.1.6) Gecko/20070728 Thunderbird/2.0.0.6"
XXX- mantykora [09/Oct/2007:20:00:09 +0200] "REPORT /caldav.php/mantykora/home/ HTTP/1.1" 207 1450 "-" "Mozilla/5.0 (Windows; U; Windows NT 5.1; pl;
 rv:1.8.1.6) Gecko/20070728 Thunderbird/2.0.0.6"
xxx - mantykora [09/Oct/2007:20:00:09 +0200] "REPORT /caldav.php/mantykora/home/ HTTP/1.1" 422 200 "-" "Mozilla/5.0 (Windows; U; Windows NT 5.1; pl;
rv:1.8.1.6) Gecko/20070728 Thunderbird/2.0.0.6"
xxx - mantykora [09/Oct/2007:20:00:09 +0200] "REPORT /caldav.php/mantykora/home/ HTTP/1.1" 422 200 "-" "Mozilla/5.0 (Windows; U; Windows NT 5.1; pl;
rv:1.8.1.6) Gecko/20070728 Thunderbird/2.0.0.6"

Taliesin · Post by **Taliesin** » Tue Oct 09, 2007 6:27 pm

And this is without caldav patch

xxx - - [09/Oct/2007:20:13:01 +0200] "PROPFIND /caldav.php/mantykora/home/ HTTP/1.1" 401 40 "-" "Mozilla/5.0 (Windows; U; Windows NT 5.1; pl; rv:1.8.
1.6) Gecko/20070728 Thunderbird/2.0.0.6"
xxx - mantykora [09/Oct/2007:20:13:01 +0200] "PROPFIND /caldav.php/mantykora/home/ HTTP/1.1" 207 347 "-" "Mozilla/5.0 (Windows; U; Windows NT 5.1; pl
; rv:1.8.1.6) Gecko/20070728 Thunderbird/2.0.0.6"
xxx - mantykora [09/Oct/2007:20:13:01 +0200] "REPORT /caldav.php/mantykora/home/ HTTP/1.1" 207 1508 "-" "Mozilla/5.0 (Windows; U; Windows NT 5.1; pl;
rv:1.8.1.6) Gecko/20070728 Thunderbird/2.0.0.6"
xxx - mantykora [09/Oct/2007:20:13:01 +0200] "REPORT /caldav.php/mantykora/home/ HTTP/1.1" 207 68 "-" "Mozilla/5.0 (Windows; U; Windows NT 5.1; pl; r
v:1.8.1.6) Gecko/20070728 Thunderbird/2.0.0.6"
xxx - mantykora [09/Oct/2007:20:13:02 +0200] "REPORT /caldav.php/mantykora/home/ HTTP/1.1" 207 2845 "-" "Mozilla/5.0 (Windows; U; Windows NT 5.1; pl;
rv:1.8.1.6) Gecko/20070728 Thunderbird/2.0.0.6"
xxx - mantykora [09/Oct/2007:20:13:02 +0200] "REPORT /caldav.php/mantykora/home/ HTTP/1.1" 207 2845 "-" "Mozilla/5.0 (Windows; U; Windows NT 5.1; pl;
rv:1.8.1.6) Gecko/20070728 Thunderbird/2.0.0.6"
xxx - mantykora [09/Oct/2007:20:13:02 +0200] "REPORT /caldav.php/mantykora/home/ HTTP/1.1" 207 1508 "-" "Mozilla/5.0 (Windows; U; Windows NT 5.1; pl;
rv:1.8.1.6) Gecko/20070728 Thunderbird/2.0.0.6"

[Tue Oct 09 20:13:01 2007] [error] [client xxx rscds: LOG: :==========> method =PROPFIND= =http://xxx/caldav.php= =/mantykora/hom
e/= == =/usr/share/rscds=
[Tue Oct 09 20:13:01 2007] [error] [client xxx] rscds: LOG: :==========> method =PROPFIND= =http://xxx/caldav.php= =/mantykora/hom
e/= == =/usr/share/rscds=
[Tue Oct 09 20:13:01 2007] [error] [client xxx] rscds: LOG: :==========> method =REPORT= =http://xxx/caldav.php= =/mantykora/home/
= == =/usr/share/rscds=
[Tue Oct 09 20:13:01 2007] [error] [client xxx] rscds: LOG: :==========> method =REPORT= =http://xxx/caldav.php= =/mantykora/home/
= == =/usr/share/rscds=
[Tue Oct 09 20:13:02 2007] [error] [client xxx] rscds: LOG: :==========> method =REPORT= =http://xxx/caldav.php= =/mantykora/home/
= == =/usr/share/rscds=
[Tue Oct 09 20:13:02 2007] [error] [client xxx] rscds: LOG: :==========> method =REPORT= =http://xxx/caldav.php= =/mantykora/home/
= == =/usr/share/rscds=
[Tue Oct 09 20:13:02 2007] [error] [client xxx] rscds: LOG: :==========> method =REPORT= =http://xxx/caldav.php= =/mantykora/home/
= == =/usr/share/rscds=

Post by **Birdy** » Tue Oct 09, 2007 6:35 pm

From what I see, it seems that RSCDS doesn't implement "PROP-FILTER" and "TEXT-MATCH" yet. Lightning uses these properties to retrieve a particular event from the calDAV server.

For now the solutions I see would be to contact the team of RSCDS to see if they can give an alternative query to retrieve a particular event (based on its UID) or to try to use another calDAV server.

Taliesin · Post by **Taliesin** » Tue Oct 09, 2007 6:46 pm

Do you know any solutions for calendar server (linux only) working with lightning ?

Taliesin · Post by **Taliesin** » Tue Oct 09, 2007 8:19 pm

It' works with todays svn build of rscds aka davical.

Post by **Birdy** » Tue Oct 09, 2007 9:42 pm

Do you mean that you succeeded in synchronizing with the very last version (SVN build) of davical ?
When it was not working, do you remember which version of RSCDS/davical you were using ?

Taliesin · Post by **Taliesin** » Tue Oct 09, 2007 10:04 pm

Yes sychro of events works like a charm. Before SVN build I was using latest package 0.8.0. I've read they are planing to relaese 0.8.1 in the near future.

SirSydom · Post by **SirSydom** » Tue Oct 16, 2007 4:58 pm

same for me with RSCDS 0.8.0

So the DAViCal Release is not released yet, did you just copy the SVN Head over you existing htdocs? DoI have to change database or something else?