Hi,
I am having a problem with a specific calendar on our sogo 2.1.1b server
that stopped to show any events in our BusyCal clients.
I opened a call with the BusyCal support and got the following answer
about missing updates in the CalDAV protocol.
I don't know what's wrong and how I can further help to debug this
problem. Below I also attached the portion of the sogo.log file.
Any ideas?
Thanks
Robert
-------- Original Message --------
Hi Robert,
Thanks for the logs. Here is what we see (and if you want to forward it
on to the Sogo folks, that would be great):
BusyCal tries to sync your Holidays calendar:
2014-02-14 10:24:00.822 -2- CalDAVSyncTask
(259FBD32-4A5E-4591-8809-FCAFADD1E92F): webdav sync for calendar
Holidays (MPIWG Calendar User <[email protected]>)
2014-02-14 10:24:00.822 -2- send request =>
REPORT
https://sogo.mpiwg-berlin.mpg.de/SOGo/dav/casties/Calendar/mpiwg-calendar_1132-4E54ED80-B-671B510/
User-Agent: BusyCal-2.5.4
Content-Type: text/xml; charset=UTF-8
Depth: 0
Cache-Control: no-cache
Authorization: <>
2014-02-14 10:24:00.868 -2- received response =>
HTTP 207
URL:
https://sogo.mpiwg-berlin.mpg.de/SOGo/dav/casties/Calendar/mpiwg-calendar_1132-4E54ED80-B-671B510/
Pragma: no-cache
Content-Type: text/xml; charset="utf-8"
Connection: Keep-Alive
Date: Fri, 14 Feb 2014 09:24:14 GMT
Content-Encoding: gzip
Content-Length: 1855
Cache-Control: no-cache
Keep-Alive: timeout=5, max=98
It gets a response from the server, but includes updated info for only
two events, so it asks for these:
2014-02-14 10:24:00.868 -2- CalDAVSyncTask
(259FBD32-4A5E-4591-8809-FCAFADD1E92F): received response
2014-02-14 10:24:00.887 -2- CalDAVSyncTask
(259FBD32-4A5E-4591-8809-FCAFADD1E92F): multiget events
2014-02-14 10:24:00.888 -2- send request =>
REPORT
https://sogo.mpiwg-berlin.mpg.de/SOGo/dav/casties/Calendar/mpiwg-calendar_1132-4E54ED80-B-671B510/
User-Agent: BusyCal-2.5.4
Content-Type: text/xml; charset=UTF-8
Authorization: <>
Cache-Control: no-cache
<?xml version="1.0" encoding="utf-8" standalone="no"?>
<b:calendar-multiget xmlns:d="DAV:"
xmlns:a="http://calendarserver.org/ns/";
xmlns:b="urn:ietf:params:xml:ns:caldav">
<d:prop>
<d:getetag></d:getetag>
<b:schedule-tag></b:schedule-tag>
<a:created-by></a:created-by>
<a:updated-by></a:updated-by>
<b:calendar-data></b:calendar-data>
</d:prop>
<d:href>/SOGo/dav/casties/Calendar/mpiwg-calendar_1132-4E54ED80-B-671B510/1DF1-4F6DD900-7-6CA3F70.ics</d:href>
<d:href>/SOGo/dav/casties/Calendar/mpiwg-calendar_1132-4E54ED80-B-671B510/1DF1-4F6DD880-1-6CA3F70.ics</d:href>
</b:calendar-multiget>
and it gets them:
2014-02-14 10:24:00.924 -1- CalDAV: pull change event Anfang Sommerzeit
(1DF1-4F6DD880-1-6CA3F70) from calendar Holidays (MPIWG Calendar User
<[email protected]>) by (null)
2014-02-14 10:24:00.927 -1- CalDAV: pull change event Ende Sommerzeit
(1DF1-4F6DD900-7-6CA3F70) from calendar Holidays (MPIWG Calendar User
<[email protected]>) by (null)
So, for some reason when BusyCal asks for the events from the server for
the Holiday's calendar, it is only getting 2 events back. It would be
great is someone could look at this request from the Sogo side and see
why that might be true.
Regards,
-Kirk
---------------
sogo.log
Feb 14 10:24:13 sogod [12571]: <0x0x7f3a1b411a30[NGLdapConnection]>
Using ldap_init (deprecated) for LDAP host:port
ldapreplik.mpiwg-berlin.mpg.de ldap.mpiwg-berlin.mpg.de:389
Feb 14 10:24:13 sogod [12571]: <0x0x7f3a1a3bcd80[LDAPSource]>
<NSException: 0x7f3a1b412240> NAME:LDAPException REASON:operation bind
failed: Invalid credentials (0x31) INFO:{login =
"cn=casties,ou=groups,dc=mpiwg-berlin,dc=mpg,dc=de"; }
Feb 14 10:24:13 sogod [12571]: <0x0x7f3a1b411a30[NGLdapConnection]>
Using ldap_init (deprecated) for LDAP host:port
ldapreplik.mpiwg-berlin.mpg.de ldap.mpiwg-berlin.mpg.de:389
Feb 14 10:24:13 sogod [12571]: <0x0x7f3a1a6264b0[NGLdapConnection]>
Using ldap_init (deprecated) for LDAP host:port
ldapreplik.mpiwg-berlin.mpg.de ldap.mpiwg-berlin.mpg.de:389
2014-02-14 10:24:13.794 sogod[12571] -[NGLdapConnection
_searchAtBaseDN:qualifier:attributes:scope:]: search at base
'ou=people,dc=mpiwg-berlin,dc=mpg,dc=de' filter
'(|(cn=casties)(uid=casties))' for attrs 'dn'
Feb 14 10:24:13 sogod [12571]: <0x0x7f3a1b8b38e0[NGLdapConnection]>
Using ldap_init (deprecated) for LDAP host:port
ldapreplik.mpiwg-berlin.mpg.de ldap.mpiwg-berlin.mpg.de:389
2014-02-14 10:24:13.805 sogod[12571] -[NGLdapConnection
_searchAtBaseDN:qualifier:attributes:scope:]: search at base
'ou=groups,dc=mpiwg-berlin,dc=mpg,dc=de' filter
'(|(cn=casties)(mail=casties))' for attrs '*'
Feb 14 10:24:13 sogod [12571]: <0x0x7f3a1a52f900[NGLdapConnection]>
Using ldap_init (deprecated) for LDAP host:port
ldapreplik.mpiwg-berlin.mpg.de ldap.mpiwg-berlin.mpg.de:389
2014-02-14 10:24:13.810 sogod[12571] -[NGLdapConnection
_searchAtBaseDN:qualifier:attributes:scope:]: search at base
'ou=people,dc=mpiwg-berlin,dc=mpg,dc=de' filter
'(|(uid=casties)(mail=casties)(cn=casties))' for attrs '*'
2014-02-14 10:24:13.815 sogod[12571] -[WEClientCapabilities
initWithRequest:]: Unknown WebClient: user-agent='BusyCal-2.5.4'
Feb 14 10:24:13 sogod [12571]: <0x0x7f3a1b6406d0[NGLdapConnection]>
Using ldap_init (deprecated) for LDAP host:port
ldapreplik.mpiwg-berlin.mpg.de ldap.mpiwg-berlin.mpg.de:389
2014-02-14 10:24:13.869 sogod[12571] -[NGLdapConnection
_searchAtBaseDN:qualifier:attributes:scope:]: search at base
'ou=groups,dc=mpiwg-berlin,dc=mpg,dc=de' filter '(cn=it)' for attrs '*'
141.14.237.82 - - [14/Feb/2014:10:24:14 GMT] "PROPFIND
/SOGo/dav/casties/ HTTP/1.1" 207 523/294 0.239 1793 70% 0
2014-02-14 10:24:14.030 sogod[12571] -[WEClientCapabilities
initWithRequest:]: Unknown WebClient: user-agent='BusyCal-2.5.4'
141.14.237.82 - - [14/Feb/2014:10:24:14 GMT] "PROPFIND
/SOGo/dav/casties/Calendar/ HTTP/1.1" 207 2874/770 0.130 51725 94% 0
141.14.238.18 - - [14/Feb/2014:10:24:14 GMT] "PROPFIND
/SOGo/dav/divarci/Calendar/ HTTP/1.1" 207 8001/862 0.054 - - 0
141.14.237.82 - - [14/Feb/2014:10:24:14 GMT] "REPORT
/SOGo/dav/casties/Calendar/mpiwg-calendar_1132-4E54ED80-B-671B510/
HTTP/1.1" 207 1855/182 0.043 54969 96% 4K
Feb 14 10:24:14 sogod [12571]: [ERROR]
<0x1B25DF30[SOGoAppointmentFolder]:mpiwg-calendar_1132-4E54ED80-B-671B510>
DAV property '{urn:ietf:params:xml:ns:caldav}schedule-tag' has no
matching SQL field, response could be incomplete
Feb 14 10:24:14 sogod [12571]: [ERROR]
<0x1B25DF30[SOGoAppointmentFolder]:mpiwg-calendar_1132-4E54ED80-B-671B510>
DAV property '{http://calendarserver.org/ns/}created-by' has no matching
SQL field, response could be incomplete
Feb 14 10:24:14 sogod [12571]: [ERROR]
<0x1B25DF30[SOGoAppointmentFolder]:mpiwg-calendar_1132-4E54ED80-B-671B510>
DAV property '{http://calendarserver.org/ns/}updated-by' has no matching
SQL field, response could be incomplete
141.14.237.82 - - [14/Feb/2014:10:24:14 GMT] "REPORT
/SOGo/dav/casties/Calendar/mpiwg-calendar_1132-4E54ED80-B-671B510/
HTTP/1.1" 207 530/506 0.029 1320 59% 0
--
[email protected]
https://inverse.ca/sogo/lists
