View Issue Details

Jump to NotesJump to History
IDProjectCategoryView StatusDate SubmittedLast Update
0000172Lightning (Inverse Edition)public2009-10-14 09:372013-01-18 17:49
Reporterklaasd Assigned To 
PrioritynormalSeveritymajorReproducibilityalways
Status closedResolutionsuspended 
Product VersionMonotone / nightly 
Summary0000172: READ ONLY calendars after starting Thunderbird
Description

Hi,

always when I start Thunderbird, the external CalDAV calendars are set to read-only mode. After manually reloading the external calendars from the server (or when the automatic reload is activated after 30 minutes), their status is changed back so that I can now add and delete items from my external calendars. This really appears always when I start Thunderbird. It only appears in the Inverse Edition of Lightning but not with the regular 0.9 release of Lightning. Furthermore, it seems not connected to bug 0000135
http://www.scalableogo.org/bugs/view.php?id=135 where the READ ONLY seems to be related to the Sogo Connector add-on. Deactivating Sogo Connector didn't fix the problem.

I have installed the following calendar related plugins: Sogo Connector 0.97pre2 (downloaded from monotone), Ligthning (Inverse Edition) 0.9.7pre2 (also downloaded from monotone). I use Ubuntu 9.04 in a 32bit version. The external calendars are stored on a server which runs the latest trunk version of SOGo.

The following error is shown in the error console of Thunderbird which seems to be related to this problem:

Error: Assert failed: replay action failed: null
1: [file:///home/klaasd/.mozilla-thunderbird/2c1jibf4.default/extensions/%7Be2fda1a4-762b-4020-b5ad-a41df1933103%7D/components/calItemModule.js -> file:///home/klaasd/.mozilla-thunderbird/2c1jibf4.default/extensions/%7Be2fda1a4-762b-4020-b5ad-a41df1933103%7D/js/calUtils.js:1004] ASSERT
2: [file:///home/klaasd/.mozilla-thunderbird/2c1jibf4.default/extensions/%7Be2fda1a4-762b-4020-b5ad-a41df1933103%7D/components/calItemModule.js -> file:///home/klaasd/.mozilla-thunderbird/2c1jibf4.default/extensions/%7Be2fda1a4-762b-4020-b5ad-a41df1933103%7D/js/calCachedCalendar.js:268] anonymous
3: [null:0] null
4: [file:///home/klaasd/.mozilla-thunderbird/2c1jibf4.default/extensions/%7Be2fda1a4-762b-4020-b5ad-a41df1933103%7D/components/calDavCalendarModule.js -> file:///home/klaasd/.mozilla-thunderbird/2c1jibf4.default/extensions/%7Be2fda1a4-762b-4020-b5ad-a41df1933103%7D/js/calDavCalendar.js:2493] caldav_completeCheckServerInfo
5: [file:///home/klaasd/.mozilla-thunderbird/2c1jibf4.default/extensions/%7Be2fda1a4-762b-4020-b5ad-a41df1933103%7D/components/calDavCalendarModule.js -> file:///home/klaasd/.mozilla-thunderbird/2c1jibf4.default/extensions/%7Be2fda1a4-762b-4020-b5ad-a41df1933103%7D/js/calDavCalendar.js:1989] checkDavResourceType_oSC

Sourcefile: file:///home/klaasd/.mozilla-thunderbird/2c1jibf4.default/extensions/%7Be2fda1a4-762b-4020-b5ad-a41df1933103%7D/components/calItemModule.js -> file:///home/klaasd/.mozilla-thunderbird/2c1jibf4.default/extensions/%7Be2fda1a4-762b-4020-b5ad-a41df1933103%7D/js/calUtils.js
Line: 1009

TagsNo tags attached.

Activities

ludovic

ludovic

2009-10-17 19:29

administrator   ~0000197

show us the full sogod log output when you start tb please

2009-10-20 08:08

 

initialrequest.log (3,359 bytes)    
localhost - - [20/Oct/2009:10:10:52 GMT] "PROPFIND /SOGo/dav/user0/Calendar/user1_personal/ HTTP/1.1" 401 0/190 0.011 - - 0
localhost - - [20/Oct/2009:10:10:52 GMT] "PROPFIND /SOGo/dav/user0/Calendar/personal/ HTTP/1.1" 401 0/190 0.003 - - 0
localhost - - [20/Oct/2009:10:10:52 GMT] "PROPFIND /SOGo/dav/user0/Calendar//user2_personal/ HTTP/1.1" 401 0/190 0.032 - - 0
localhost - - [20/Oct/2009:10:10:52 GMT] "OPTIONS /SOGo/dav/user0/Calendar/personal/ HTTP/1.1" 401 0/0 0.003 - - 0
localhost - - [20/Oct/2009:10:10:52 GMT] "OPTIONS /SOGo/dav/user0/Calendar//user2_personal/ HTTP/1.1" 401 0/0 0.004 - - 0
localhost - - [20/Oct/2009:10:10:52 GMT] "OPTIONS /SOGo/dav/user0/Calendar/user1_personal/ HTTP/1.1" 401 0/0 0.003 - - 0
localhost - - [20/Oct/2009:10:10:53 GMT] "PROPFIND /SOGo/dav/user0/Calendar/user1_personal/ HTTP/1.1" 207 590/190 0.306 6729 91% 52K
localhost - - [20/Oct/2009:10:10:53 GMT] "PROPFIND /SOGo/dav/user0/Calendar/personal/ HTTP/1.1" 207 767/190 0.025 7901 90% 12K
localhost - - [20/Oct/2009:10:10:53 GMT] "PROPFIND /SOGo/dav/user0/Calendar//user2_personal/ HTTP/1.1" 207 587/190 0.061 4109 85% 0
localhost - - [20/Oct/2009:10:10:53 GMT] "OPTIONS /SOGo/dav/user0/Calendar/personal/ HTTP/1.1" 200 0/0 0.009 - - 0
localhost - - [20/Oct/2009:10:10:53 GMT] "OPTIONS /SOGo/dav/user0/Calendar//user2_personal/ HTTP/1.1" 200 0/0 0.051 - - 0
localhost - - [20/Oct/2009:10:10:53 GMT] "OPTIONS /SOGo/dav/user0/Calendar/user1_personal/ HTTP/1.1" 200 0/0 0.051 - - 0
localhost - - [20/Oct/2009:10:10:53 GMT] "PROPFIND /SOGo/dav/user0/Calendar/personal/ HTTP/1.1" 207 479/166 0.012 1814 73% 0
localhost - - [20/Oct/2009:10:10:53 GMT] "PROPFIND /SOGo/dav/user0/Calendar//user2_personal/ HTTP/1.1" 207 762/166 0.052 - - 0
localhost - - [20/Oct/2009:10:10:53 GMT] "PROPFIND /SOGo/dav/user0/Calendar/user1_personal/ HTTP/1.1" 207 768/166 0.053 - - 0
localhost - - [20/Oct/2009:10:10:53 GMT] "PROPFIND /SOGo/dav/user0/ HTTP/1.1" 401 0/182 0.004 - - 0
localhost - - [20/Oct/2009:10:10:53 GMT] "REPORT /SOGo/dav/user0/ HTTP/1.1" 401 0/102 0.004 - - 0
localhost - - [20/Oct/2009:10:10:53 GMT] "PROPFIND /SOGo/dav/user2/ HTTP/1.1" 401 0/182 0.004 - - 0
localhost - - [20/Oct/2009:10:10:53 GMT] "REPORT /SOGo/dav/user2/ HTTP/1.1" 401 0/102 0.027 - - 0
localhost - - [20/Oct/2009:10:10:54 GMT] "PROPFIND /SOGo/dav/user1/ HTTP/1.1" 401 0/182 0.034 - - 0
localhost - - [20/Oct/2009:10:10:54 GMT] "REPORT /SOGo/dav/user1/ HTTP/1.1" 401 0/102 0.004 - - 0
localhost - - [20/Oct/2009:10:10:54 GMT] "PROPFIND /SOGo/dav/user0/ HTTP/1.1" 207 513/182 0.039 - - 0
localhost - - [20/Oct/2009:10:10:54 GMT] "REPORT /SOGo/dav/user0/ HTTP/1.1" 207 217/102 0.006 - - 0
localhost - - [20/Oct/2009:10:10:54 GMT] "PROPFIND /SOGo/dav/user2/ HTTP/1.1" 207 529/182 0.044 - - 0
localhost - - [20/Oct/2009:10:10:54 GMT] "REPORT /SOGo/dav/user2/ HTTP/1.1" 207 217/102 0.007 - - 0
localhost - - [20/Oct/2009:10:10:54 GMT] "PROPFIND /SOGo/dav/user1/ HTTP/1.1" 207 531/182 0.042 - - 0
localhost - - [20/Oct/2009:10:10:54 GMT] "REPORT /SOGo/dav/user1/ HTTP/1.1" 207 217/102 0.006 - - 0
localhost - - [20/Oct/2009:10:10:54 GMT] "PROPFIND /SOGo/dav/user0/ HTTP/1.1" 207 513/182 0.047 - - 0
localhost - - [20/Oct/2009:10:10:54 GMT] "PROPFIND /SOGo/dav/user0/ HTTP/1.1" 207 513/182 0.008 - - 0
localhost - - [20/Oct/2009:10:10:54 GMT] "PROPFIND /SOGo/dav/user0/ HTTP/1.1" 207 513/182 0.040 - - 0
initialrequest.log (3,359 bytes)   

2009-10-20 08:08

 

reloadrequest.log (1,105 bytes)    
localhost - - [20/Oct/2009:10:11:26 GMT] "PROPFIND /SOGo/dav/user0/Calendar/personal/ HTTP/1.1" 207 488/190 0.015 1207 59% 0
localhost - - [20/Oct/2009:10:11:26 GMT] "OPTIONS /SOGo/dav/user0/Calendar/personal/ HTTP/1.1" 200 0/0 0.009 - - 0
localhost - - [20/Oct/2009:10:11:26 GMT] "OPTIONS /SOGo/dav/user0/Calendar/ HTTP/1.1" 200 0/0 0.051 - - 0
localhost - - [20/Oct/2009:10:11:26 GMT] "PROPFIND /SOGo/dav/user0/Calendar/personal/ HTTP/1.1" 207 479/166 0.020 1814 73% 0
localhost - - [20/Oct/2009:10:11:26 GMT] "PROPFIND /SOGo/dav/user0/Calendar/ HTTP/1.1" 207 409/98 0.008 - - 0
localhost - - [20/Oct/2009:10:11:26 GMT] "PROPFIND /SOGo/dav/user0/ HTTP/1.1" 207 513/182 0.037 - - 0
localhost - - [20/Oct/2009:10:11:26 GMT] "REPORT /SOGo/dav/user0/ HTTP/1.1" 207 217/102 0.006 - - 0
localhost - - [20/Oct/2009:10:11:26 GMT] "REPORT /SOGo/dav/user0/ HTTP/1.1" 207 658/407 0.032 - - 0
localhost - - [20/Oct/2009:10:11:26 GMT] "PROPFIND /SOGo/dav/user0/ HTTP/1.1" 207 513/182 0.042 - - 0
localhost - - [20/Oct/2009:10:11:26 GMT] "PROPFIND /SOGo/dav/user0/Calendar/personal/ HTTP/1.1" 207 349/123 0.014 - - 0
reloadrequest.log (1,105 bytes)   
klaasd

klaasd

2009-10-20 08:13

reporter   ~0000220

I've uploaded two files:

1) initialrequest.log contains the part of the log file which corresponds to the request of TB directly after startup. TB should authenticate as user0. TB imports the calendars of two further users but which have been deactivated during startup (i.e. the box isn't checked and thus the calendar not shown).

2) reloadrequest.log contains the part of the log file which corresponds to the reload request in TB that is initiated by me. I right-click on the calendar of user0 and choose "Reload external calendar".
klaasd

klaasd

2009-10-23 15:24

reporter   ~0000246

Further information that is available on the standard output console of Thunderbird when the calendar is initially retrieved:

CalDAV: Error without status on initial PROPFIND for calendar xxxidentities for calendar: https://xxx/SOGo/dav/klaasd/Calendar/personal/
type: owner
address: mailto:klaasd@uni-koblenz.de
identities for calendar: https://xxx/SOGo/dav/klaasd/Calendar/personal/
type: user
address: mailto:klaasd@uni-koblenz.de
ludovic

ludovic

2009-10-23 16:14

administrator   ~0000249

Could you please sniff the trafic using tcpflow and send us the full trace when you fire up Thunderbird?

2009-10-23 17:38

 

tcpflow.tgz (3,830 bytes)
klaasd

klaasd

2009-10-23 17:40

reporter   ~0000250

I attached the sniffed tcpflow for a connection to the server on localhost. I removed all files where the ports 20000 or 5432 were involved (i.e. sogod/apache internal communication and communication to Postgresql).
ludovic

ludovic

2009-10-23 17:43

administrator   ~0000251

Sadly we can't do much with it. SOGo returns gzipped out put.

Use WODontZipResponse = YES; in your prefs.

Also, just sniff what's going on on the sogod port - so 20000 or something. You should modify your apache conf temporarily to use only 1 sogod worker.

Sniff using:

tcpflow -i ethX -c tcp and port 20000 > /tmp/sniffsniff.log

2009-10-23 19:57

 

sniffsniff4.log (3,469 bytes)    
127.000.000.001.50772-127.000.000.001.20000: PROPFIND /SOGo/dav/klaasd/Calendar/personal/ HTTP/1.1
Host: 127.0.0.1:20000
User-Agent: Mozilla/5.0 (X11; U; Linux i686; en-US; rv:1.8.1.23) Gecko/20090817 Lightning/0.9.7-Inverse Thunderbird/2.0.0.23
Accept: text/xml,application/xml,application/xhtml+xml,text/html;q=0.9,text/plain;q=0.8,image/png,*/*;q=0.5
Accept-Language: de-de,de;q=0.8,en-us;q=0.5,en;q=0.3
Accept-Encoding: gzip,deflate
Accept-Charset: ISO-8859-1,utf-8;q=0.7,*;q=0.7
Content-Type: text/xml; charset=utf-8
Authorization: Basic a2xhYXNkOnRlc3Q=
Pragma: no-cache
Cache-Control: no-cache
X-Forwarded-For: 127.0.0.1
X-Forwarded-Host: localhost
X-Forwarded-Server: 127.0.0.1
Connection: close
Content-Length: 190

<D:propfind xmlns:D="DAV:" xmlns:CS="http://calendarserver.org/ns/">
  <D:prop>
    <D:resourcetype/>
    <D:owner/>
    <D:supported-report-set/>
    <CS:getctag/>
  </D:prop>
</D:propfind>
127.000.000.001.20000-127.000.000.001.50772: HTTP/1.0 207 Multi-Status

127.000.000.001.20000-127.000.000.001.50772: pragma: no-cache
cache-control: no-cache
ms-author-via: DAV
content-length: 1949
connection: close
content-type: text/xml; charset="utf-8"
date: Fr, 23 Okt 2009 19:51:32 GMT
x-dav-error: 200 No error
server: SOPE 4.9.37/WebDAV

127.000.000.001.20000-127.000.000.001.50772: 

127.000.000.001.20000-127.000.000.001.50772: <?xml version="1.0" encoding="utf-8"?>
<D:multistatus xmlns:a="http://calendarserver.org/ns/" xmlns:D="DAV:"><D:response><D:href>http://localhost/SOGo/dav/klaasd/Calendar/personal/e31aaf78-593a-4900-b261-cd6bc6c92644.ics</D:href><D:propstat><D:status>HTTP/1.1 200 OK</D:status><D:prop><D:resourcetype/><D:owner xmlns:D="DAV:"><D:href>http://localhost/SOGo/dav/klaasd/</D:href></D:owner><D:supported-report-set xmlns:n1="urn:inverse:params:xml:ns:inverse-dav" xmlns:D="DAV:"><D:report><n1:acl-query/></D:report><D
127.000.000.001.20000-127.000.000.001.50772: :report><D:principal-match/></D:report></D:supported-report-set></D:prop></D:propstat><D:propstat><D:status>HTTP/1.1 404 Resource Not Found</D:status><D:prop><a:getctag/></D:prop></D:propstat></D:response><D:response><D:href>http://localhost/SOGo/dav/klaasd/Calendar/personal/bc9d989f-0fab-48d4-a35d-bf2e1e2e3056.ics</D:href><D:propstat><D:status>HTTP/1.1 200 OK</D:status><D:prop><D:resourcetype/><D:owner xmlns:D="DAV:"><D:href>http://localhost/SOGo/dav/klaasd/</D:href></D:owner><D:supported-report-set xmlns:
127.000.000.001.20000-127.000.000.001.50772: n1="urn:inverse:params:xml:ns:inverse-dav" xmlns:D="DAV:"><D:report><n1:acl-query/></D:report><D:report><D:principal-match/></D:report></D:supported-report-set></D:prop></D:propstat><D:propstat><D:status>HTTP/1.1 404 Resource Not Found</D:status><D:prop><a:getctag/></D:prop></D:propstat></D:response><D:response><D:href>http://localhost/SOGo/dav/klaasd/Calendar/personal/bbbf8326-49e6-416e-ac4b-c43c3c44028b.ics</D:href><D:propstat><D:status>HTTP/1.1 200 OK</D:status><D:prop><D:resourcetype/><D:owner xmlns:D="
127.000.000.001.20000-127.000.000.001.50772: DAV:"><D:href>http://localhost/SOGo/dav/klaasd/</D:href></D:owner><D:supported-report-set xmlns:n1="urn:inverse:params:xml:ns:inverse-dav" xmlns:D="DAV:"><D:report><n1:acl-query/></D:report><D:report><D:principal-match/></D:report></D:supported-report-set></D:prop></D:propstat><D:propstat><D:status>HTTP/1.1 404 Resource Not Found</D:status><D:prop><a:getctag/></D:prop></D:propstat></D:response></D:multistatus>
sniffsniff4.log (3,469 bytes)   
klaasd

klaasd

2009-10-23 20:02

reporter   ~0000253

I've uploaded the output of tcpflow. This file is taken for the same Thunderbird but another, freshly compiled and installed server on localhost (I can not make all the necessary changes and tests with the productive server). For this configuration, I was not yet able to import a remote calendar into Thunderbird. It always sticks to READ ONLY mode and nothing is downloaded from the server.
ludovic

ludovic

2009-10-30 01:29

administrator   ~0000265

Could you try with SOGo Connector / Integrator 0.97 and Lightning 0.9.7?
klaasd

klaasd

2009-11-03 11:21

reporter   ~0000270

The problem still exists with Ligthning 0.9.7 and Connector 0.97. The Integrator is not installed on my site.
ludovic

ludovic

2013-01-18 17:49

administrator   ~0005235

This bug report is so old that it is probably now irrelevant - especially since we no longer modify Lightning.

Issue History

Date Modified Username Field Change
2009-10-14 09:37 klaasd New Issue
2009-10-17 19:29 ludovic Note Added: 0000197
2009-10-20 08:08 klaasd File Added: initialrequest.log
2009-10-20 08:08 klaasd File Added: reloadrequest.log
2009-10-20 08:13 klaasd Note Added: 0000220
2009-10-23 15:24 klaasd Note Added: 0000246
2009-10-23 16:14 ludovic Note Added: 0000249
2009-10-23 17:38 klaasd File Added: tcpflow.tgz
2009-10-23 17:40 klaasd Note Added: 0000250
2009-10-23 17:43 ludovic Note Added: 0000251
2009-10-23 19:57 klaasd File Added: sniffsniff4.log
2009-10-23 20:02 klaasd Note Added: 0000253
2009-10-30 01:29 ludovic Note Added: 0000265
2009-11-03 11:21 klaasd Note Added: 0000270
2013-01-18 17:49 ludovic Note Added: 0005235
2013-01-18 17:49 ludovic Status new => closed
2013-01-18 17:49 ludovic Resolution open => suspended