[CalendarServer-users] Problem creating multiple calendars for the same user

[Jane Atkinson]

-----BEGIN PGP SIGNED MESSAGE-----
Hash: SHA1

On 22/06/12 05:21, Glyph wrote:

> 
> What does the server's access log / error log look like around the
> time you create a new calendar in the client?  Is there any traffic
> at all?
> 

Here's what I've been able to find so far.

Note: dcs is running on 192.168.1.67  Thunderbird/Lightning and
Evolution are running on 192.168.1.65 The calendar is named
"dcsadmin2" and the location chosen was
"http://192.168.1.67:8008/calendars/users/admin/cal2"



access.log shows:

192.168.1.65 - - [22/Jun/2012:09:03:45 +1300] "PROPFIND
/calendars/users/admin/cal2/ HTTP/1.1" 404 229 "-" "Mozilla/5.0 (X11;
Linux i686; rv:13.0) Gecko/20120614 Thunderbird/13.0.1
Lightning/1.5.1" i=1 t=80.0 or=1


error.log shows:

2012-06-22 09:03:45+1200 [-] [caldav-1]
[PooledMemCacheProtocol,client] [twistedcaldav.method.propfind#error]
Resource not found: <Calendar Collection Resource
'38eae584-2b75-582b-a058-018391d1003f':'cal2' Non-existent>



I was running Thunderbird remotely via ssh X11 forwarding. I found
this message in the terminal window:

Warning: There has been an error reading data for calendar: dcsadmin2.
 However, this error is believed to be minor, so the program will
attempt to continue. Error code: DAV_NOT_DAV. Description: The
resource at http://192.168.1.67:8008/calendars/users/admin/cal2 is
either not a DAV collection or not available

- ---

Evolution shows almost identical messages.

access.log shows:

192.168.1.65 - - [22/Jun/2012:09:36:41 +1300] "OPTIONS
/calendars/users/admin/cal2/ HTTP/1.1" 401 141 "-" "Evolution/3.2.3"
i=1 t=17.0 or=1
192.168.1.65 - - [22/Jun/2012:09:36:50 +1300] "OPTIONS
/calendars/users/admin/cal2/ HTTP/1.1" 401 141 "-" "Evolution/3.2.3"
i=1 t=16.6 or=1
192.168.1.65 - admin [22/Jun/2012:09:36:50 +1300] "OPTIONS
/calendars/users/admin/cal2/ HTTP/1.1" 200 0 "-" "Evolution/3.2.3" i=0
t=16.5 or=1
192.168.1.65 - admin [22/Jun/2012:09:36:50 +1300] "PROPFIND
/calendars/users/admin/cal2/ HTTP/1.1" 404 229 "-" "Evolution/3.2.3"
i=0 t=17.9 or=1
192.168.1.65 - - [22/Jun/2012:09:36:50 +1300] "REPORT
/calendars/users/admin/cal2/ HTTP/1.1" 404 229 "-" "Evolution/3.2.3"
i=1 t=12.7 or=1
192.168.1.65 - - [22/Jun/2012:09:36:50 +1300] "REPORT
/calendars/users/admin/cal2/ HTTP/1.1" 404 229 "-" "Evolution/3.2.3"
i=0 t=12.2 or=1


error.log shows:

2012-06-22 09:36:50+1200 [-] [caldav-0]
[PooledMemCacheProtocol,client] [twistedcaldav.method.propfind#error]
Resource not found: <Calendar Collection Resource
'38eae584-2b75-582b-a058-018391d1003f':'cal2' Non-existent>
2012-06-22 09:36:50+1200 [-] [caldav-1]  [HTTPChannel,10,192.168.1.65]
[twistedcaldav.method.report#error] Resource not found: <Calendar
Collection Resource '38eae584-2b75-582b-a058-018391d1003f':'cal2'
Non-existent>
2012-06-22 09:36:50+1200 [-] [caldav-0]  [HTTPChannel,10,192.168.1.65]
[twistedcaldav.method.report#error] Resource not found: <Calendar
Collection Resource '38eae584-2b75-582b-a058-018391d1003f':'cal2'
Non-existent>


Trying to add an event to dcsadmin2 in Evolution causes this:

access.log

192.168.1.65 - - [22/Jun/2012:09:56:11 +1300] "OPTIONS
/calendars/users/admin/cal2/ HTTP/1.1" 401 141 "-" "Evolution/3.2.3"
i=1 t=37.0 or=1
192.168.1.65 - admin [22/Jun/2012:09:56:11 +1300] "OPTIONS
/calendars/users/admin/cal2/ HTTP/1.1" 200 0 "-" "Evolution/3.2.3" i=0
t=39.5 or=1
192.168.1.65 - admin [22/Jun/2012:09:56:11 +1300] "PROPFIND
/calendars/users/admin/cal2/ HTTP/1.1" 404 229 "-" "Evolution/3.2.3"
i=1 t=46.1 or=1
192.168.1.65 - - [22/Jun/2012:09:56:12 +1300] "REPORT
/calendars/users/admin/cal2/ HTTP/1.1" 404 229 "-" "Evolution/3.2.3"
i=0 t=33.8 or=1
192.168.1.65 - - [22/Jun/2012:09:56:12 +1300] "REPORT
/calendars/users/admin/cal2/ HTTP/1.1" 404 229 "-" "Evolution/3.2.3"
i=1 t=26.7 or=1

error.log

2012-06-22 09:56:11+1200 [-] [caldav-1]
[PooledMemCacheProtocol,client] [twistedcaldav.method.propfind#error]
Resource not found: <Calendar Collection Resource
'38eae584-2b75-582b-a058-018391d1003f':'cal2' Non-existent>
2012-06-22 09:56:12+1200 [-] [caldav-0]  [HTTPChannel,18,192.168.1.65]
[twistedcaldav.method.report#error] Resource not found: <Calendar
Collection Resource '38eae584-2b75-582b-a058-018391d1003f':'cal2'
Non-existent>
2012-06-22 09:56:12+1200 [-] [caldav-1]  [HTTPChannel,25,192.168.1.65]
[twistedcaldav.method.report#error] Resource not found: <Calendar
Collection Resource '38eae584-2b75-582b-a058-018391d1003f':'cal2'
Non-existent>


My guess is that a more detailed log is going to be needed. What is
the command for restarting calendarserver at a higher loglevel?

Jane Atkinson
-----BEGIN PGP SIGNATURE-----
Version: GnuPG v1.4.11 (GNU/Linux)
Comment: Using GnuPG with Mozilla - http://enigmail.mozdev.org/

iQEcBAEBAgAGBQJP47HeAAoJEERzSJEx033jiP8H/39P2NiHjckNR2SixwFGTAJg
ae30+ffSbxqeUl02fEiI65ULqxtbNBBgfxYHGpKbQagZMxzjAtKgIZGzrIvibfKs
HwCtWrYCy2+iED8GLBpqrLJuEjYItDK3FZTn/4nox+C3L2zW4EkyTmuoyAi10gyc
a2qpQmGYbwvzAJpRZxYvvyasLJPGNjrtf1Re4PiFAFaGDB7jH/MpHPTUZdaVFrEK
TvptKEoPZ1tNl1BT9qUr1xvvffyEKmgbDYn2c7V+u3XuSe+lJ8LnF6ysNbrXI18C
f7lQQXZhj0mV66+X9UAkQ3YCH2X3O8TM2/Ra4n/vfk8ZagkoaTRSGSvnwsPtD6g=
=YGEI
-----END PGP SIGNATURE-----