Hello,
I have experienced for some time now the following behavior :
whenever I click on a message in thunderbird, the status bar
displays "Loading message...", the message pane goes blank,
and the message effectively appears roughly 2 to 3 seconds
after my click. Everything else is working as intended.
Sogod-side, I have the attached log file. The highlights are:
- the client contacts the server for resolving some person
from the address book, the server "grants" the search, and does it
- the server also tries to resolve using an ldap query,
the log does not show which one it uses, and the log from the ldap
server show that no query is actually made, then sogo reports it took
many seconds to complete (which is the delay I am experiencing..).
- this scenario is run twice per message clicked.
I have stripped the "so-security" lines since they always returns
"allow" without delay. I have the following debug messages activated :
GCSFolderDebugEnabled YES
GCSFolderStoreDebugEnabled YES
LDAPDebugEnabled YES
OCSFolderManagerSQLDebugEnabled YES
PGDebugEnabled YES
SoDebugObjectTraversal YES
SOGoDebugRequests YES
SOGoUIxDebugEnabled YES
SoSecurityManagerDebugEnabled YES
WODebugZipResponse NO
I am using thunderbird 3.1 with the 3.104 extensions, and
I tested sogo 1.3.4 and the following nightlies. As far as
I can remember, it did not occur using 1.3.3, and I also can't
tell if this is client or server side.
I also do not know how or where I can get some debugging output
from the thunderbird extensions, if they are relevant, please
tell how to get it for you, and if I should create a bug report
for this.
Thanks,
--
Damien MASCRÉ
Centre de Ressources Informatiques et Technologiques
IUT de Villetaneuse, Université Paris 13
01 49 40 37 44
--
users@sogo.nu
https://inverse.ca/sogo/lists
Jan 14 10:02:31 sogod [17348]: |SOGo| starting method 'REPORT' on uri
'/SOGo/dav/mascre/Contacts/public/'
Jan 14 10:02:31 sogod [17348]: |SOGo| traverse: mascre => Contacts => public
Jan 14 10:02:31 sogod [17348]: |SOGo| do traverse name: 'mascre'
Jan 14 10:02:31 sogod [17348]: |SOGo| do traverse name: 'Contacts'
Jan 14 10:02:31 sogod [17348]: <<0x1BB1A780[SOGoUserFolder]:mascre>>D traverse
key 'Contacts' (acquire=no) ..
Jan 14 10:02:31 sogod [17348]: <<0x1BB1A780[SOGoUserFolder]:mascre>>D key
'Contacts' resolved: <0x1BA85340[SOGoContactFolders]: name=Contacts
container=0x1BB1A780/mascre>
Jan 14 10:02:31 sogod [17348]: |SOGo| do traverse name: 'public'
Jan 14 10:02:31 sogod [17348]: <<0x1BA85340[SOGoContactFolders]:Contacts>>D
traverse key 'public' (acquire=no) ..
2011-01-14 10:02:31.015 sogod[17348] PG0x0x1ba88840 SQL: SELECT c_path4 FROM
sogo_folder_info WHERE c_path2 = 'mascre' AND c_folder_type = 'Contact'
Jan 14 10:02:31 sogod [17348]: <<0x1BA85340[SOGoContactFolders]:Contacts>>D
key 'public' resolved: <0x1BA739B0[SOGoContactSourceFolder]: name=public
container=0x1BA85340/Contacts>
Jan 14 10:02:31 sogod [17348]: |SOGo| set clientObject:
<0x1BA739B0[SOGoContactSourceFolder]: name=public container=0x1BA85340/Contacts>
2011-01-14 10:02:31.018 sogod[17348] -[NGLdapConnection
_searchAtBaseDN:qualifier:attributes:scope:]: search with at base filter for
attrs
Jan 14 10:02:32 sogod [17348]: |SOGo| request took 1.762824 seconds to execute
localhost.localdomain - - [14/Jan/2011:10:02:32 GMT] "REPORT
/SOGo/dav/mascre/Contacts/public/ HTTP/1.1" 207 127/357 1.764 - - 0
Jan 14 10:02:32 sogod [17348]: |SOGo| starting method 'REPORT' on uri
'/SOGo/dav/mascre/Contacts/public/'
Jan 14 10:02:32 sogod [17348]: |SOGo| traverse: mascre => Contacts => public
Jan 14 10:02:32 sogod [17348]: |SOGo| do traverse name: 'mascre'
Jan 14 10:02:32 sogod [17348]: |SOGo| do traverse name: 'Contacts'
Jan 14 10:02:32 sogod [17348]: <<0x1BA850F0[SOGoUserFolder]:mascre>>D traverse
key 'Contacts' (acquire=no) ..
Jan 14 10:02:32 sogod [17348]: <<0x1BA850F0[SOGoUserFolder]:mascre>>D key
'Contacts' resolved: <0x1BA85370[SOGoContactFolders]: name=Contacts
container=0x1BA850F0/mascre>
Jan 14 10:02:32 sogod [17348]: |SOGo| do traverse name: 'public'
Jan 14 10:02:32 sogod [17348]: <<0x1BA85370[SOGoContactFolders]:Contacts>>D
traverse key 'public' (acquire=no) ..
2011-01-14 10:02:32.818 sogod[17348] PG0x0x1ba88840 SQL: SELECT c_path4 FROM
sogo_folder_info WHERE c_path2 = 'mascre' AND c_folder_type = 'Contact'
Jan 14 10:02:32 sogod [17348]: <<0x1BA85370[SOGoContactFolders]:Contacts>>D
key 'public' resolved: <0x1BA739B0[SOGoContactSourceFolder]: name=public
container=0x1BA85370/Contacts>
Jan 14 10:02:32 sogod [17348]: |SOGo| set clientObject:
<0x1BA739B0[SOGoContactSourceFolder]: name=public container=0x1BA85370/Contacts>
2011-01-14 10:02:32.821 sogod[17348] -[NGLdapConnection
_searchAtBaseDN:qualifier:attributes:scope:]: search with at base filter for
attrs
Jan 14 10:02:34 sogod [17348]: |SOGo| request took 1.324832 seconds to execute
localhost.localdomain - - [14/Jan/2011:10:02:34 GMT] "REPORT
/SOGo/dav/mascre/Contacts/public/ HTTP/1.1" 207 941/361 1.326 - - 0
Jan 14 10:02:34 sogod [17348]: |SOGo| starting method 'REPORT' on uri
'/SOGo/dav/mascre/Contacts/public/'
Jan 14 10:02:34 sogod [17348]: |SOGo| traverse: mascre => Contacts => public
Jan 14 10:02:34 sogod [17348]: |SOGo| do traverse name: 'mascre'
Jan 14 10:02:34 sogod [17348]: |SOGo| do traverse name: 'Contacts'
Jan 14 10:02:34 sogod [17348]: <<0x1BAB7880[SOGoUserFolder]:mascre>>D traverse
key 'Contacts' (acquire=no) ..
Jan 14 10:02:34 sogod [17348]: <<0x1BAB7880[SOGoUserFolder]:mascre>>D key
'Contacts' resolved: <0x1BA85340[SOGoContactFolders]: name=Contacts
container=0x1BAB7880/mascre>
Jan 14 10:02:34 sogod [17348]: |SOGo| do traverse name: 'public'
Jan 14 10:02:34 sogod [17348]: <<0x1BA85340[SOGoContactFolders]:Contacts>>D
traverse key 'public' (acquire=no) ..
2011-01-14 10:02:34.150 sogod[17348] PG0x0x1ba88840 SQL: SELECT c_path4 FROM
sogo_folder_info WHERE c_path2 = 'mascre' AND c_folder_type = 'Contact'
Jan 14 10:02:34 sogod [17348]: <<0x1BA85340[SOGoContactFolders]:Contacts>>D
key 'public' resolved: <0x1BA9CB00[SOGoContactSourceFolder]: name=public
container=0x1BA85340/Contacts>
Jan 14 10:02:34 sogod [17348]: |SOGo| set clientObject:
<0x1BA9CB00[SOGoContactSourceFolder]: name=public container=0x1BA85340/Contacts>
2011-01-14 10:02:34.153 sogod[17348] -[NGLdapConnection
_searchAtBaseDN:qualifier:attributes:scope:]: search with at base filter for
attrs
Jan 14 10:02:34 sogod [17348]: |SOGo| request took 0.284724 seconds to execute
localhost.localdomain - - [14/Jan/2011:10:02:34 GMT] "REPORT
/SOGo/dav/mascre/Contacts/public/ HTTP/1.1" 207 127/357 0.285 - - 0
