View Issue Details

Jump to NotesJump to History
IDProjectCategoryView StatusDate SubmittedLast Update
0000849Funambol SOGo Connectorpublic2010-09-21 09:422010-09-27 16:08
Reporterskasch Assigned To 
PrioritynormalSeveritycrashReproducibilityalways
Status closedResolutionno change required 
Product Version1.0.8 
Summary0000849: SOGo crashes on calendar sync with Funambol from Outlook 2007
Description

Hi,

already asked on the mailling list but didn't get an answer. so i file a bug report, because the problem is reproducable
on two different systems with sogo-nightly and stable and funambol 8.5 and 8.7.

System is Ubuntu 8.10 x86. SOGo is 1.3.1 stable release, Funambol 8.7 (8.5.2 produces the same error) and Funambol Outlook Client 8.7
Funambol was configured as stated in the docs and synchronisation of contacts work, calendar works when syncing from sogo to outlook.
but when i add an appointment in outlook (simple meeting from 13-17pm, alarm 15 min) and sync with funambol, sogo crashes at latest
when i refresh the calender in the webfrontend. before this happens funambol and sogo are logging what one can see here.

because of a likewise error found in bug "0000266: TimeZone bug" i reference it here.
line 207 "No timezone defined in SOGo for user: skasch.domain.com".

GNUstepDefaults:

{
NSGlobalDomain = {
};
gdnc = {
};
sogod = {
NGUseUTF8AsURLEncoding = YES;
OCSFolderInfoURL = "mysql://sogo:sogo@localhost:3306/sogo/sogo_folder_info";
SOGoAppointmentSendEMailNotifications = YES;
SOGoDraftsFolderName = INBOX/Drafts;
SOGoFirstDayOfWeek = 1;
SOGoFoldersSendEMailNotifications = NO;
SOGoForceIMAPLoginWithEmail = NO;
SOGoMailAuxiliaryUserAccountsEnabled = YES;
SOGoLDAPUserManagerCleanupInterval = 1;
SOGoForwardEnabled = YES;
SOGoIMAPAclConformsToIMAPExt = YES;
SOGoIMAPServer = mailgate.domain.com;
SOGoLanguage = German;
SOGoLoginModule = Calendar;
SOGoMailDomain = domain.com;
SOGoMailingMechanism = smtp;
SOGoProfileURL = "mysql://sogo:sogo@localhost:3306/sogo/sogo_user_profile";
SOGoSMTPServer = mailgate.domain.com;
SOGoSieveScriptsEnabled = YES;
SOGoSentFolderName = INBOX/Sent;
SOGoSuperUsernames = (
sogo
);
SOGoTimeZone = Europe/Berlin;
SOGoTrashFolderName = INBOX/Trash;
SOGoUserSources = (
{
CNFieldName = cn;
IDFieldName = sAMAccountName;
UIDFieldName = description;
baseDN = "ou=userbase,dc=domain,dc=com";
bindDN = "sogo@domain.com";
bindFields = (
description
);
bindPassword = xxxxxx;
canAuthenticate = YES;
hostname = 172.20.4.2;
id = directory;
isAddressBook = NO;
port = 389;
},
{
baseDN = "ou=addresses,dc=domain,dc=com";
bindDN = "sogo@domain.com";
bindPassword = xxxxxxx;
canAuthenticate = NO;
displayName = "abc Adressen (read only)";
hostname = 172.20.4.2;
id = addresses;
isAddressBook = YES;
port = 389;
}
);
SOGoVacationEnabled = YES;
SxVMemLimit = 768;
WOMessageUseUTF8 = YES;
WOParsersUseUTF8 = YES;
WOPort = 127.0.0.1:20000;
WOUseRelativeURLs = YES;
};
}

sogo sogo.log:

2010-09-21 11:03:05.612 sogod[5971] Scanner initialised with nil string
Sep 21 11:03:05 sogod: SOGo watchdog [5971]: [WARN] <0x0xa6df980[SOGoWebDAVAclManager]> entry '{DAV:}write' already exists in DAV permissions table
Sep 21 11:03:05 sogod: SOGo watchdog [5971]: [WARN] <0x0xa6df980[SOGoWebDAVAclManager]> entry '{DAV:}write-properties' already exists in DAV permissions table
Sep 21 11:03:05 sogod: SOGo watchdog [5971]: [WARN] <0x0xa6df980[SOGoWebDAVAclManager]> entry '{DAV:}write-content' already exists in DAV permissions table
localhost - - [21/Sep/2010:11:03:05 GMT] "GET /SOGo/dav/skasch.domain.com/freebusy.ifb HTTP/1.1" 200 233/0 0.085 - - 552K

funambol ds-server.log:

[2010-09-21 11:03:05,574] [funambol.transport.http] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [] [] [] Handling incoming request
[2010-09-21 11:03:05,574] [funambol.transport.http] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [] [] [] Request URL: http://team.domain.com:8080/funambol/ds
[2010-09-21 11:03:05,574] [funambol.transport.http] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [] [] [] Requested sessionId: null
[2010-09-21 11:03:05,700] [funambol.handler] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [] skasch.domain.com/fol-U1VQUE9SVC03OnNrYXNjaA== logged in.
[2010-09-21 11:03:05,732] [funambol.handler] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [] moving to state: STATE_PKG1_RECEIVING
[2010-09-21 11:03:05,734] [funambol.sogo] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [] Source URI: sogo-cal
[2010-09-21 11:03:05,735] [funambol.sogo] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [] Done! Internal item type: 2 (text/x-vevent)
[2010-09-21 11:03:05,735] [funambol.handler] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [] moving to state: STATE_PKG3_RECEIVING
[2010-09-21 11:03:05,737] [funambol.transport.http] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [] Request processed.
[2010-09-21 11:03:06,146] [funambol.transport.http] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [] Handling incoming request
[2010-09-21 11:03:06,146] [funambol.transport.http] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [] Request URL: http://team.domain.com:8080/funambol/ds
[2010-09-21 11:03:06,146] [funambol.transport.http] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [] Requested sessionId: 6BB414AF9C8B88E75D7212EAF4FAAA18
[2010-09-21 11:03:06,149] [funambol.engine] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [] Starting synchronization ...
[2010-09-21 11:03:06,149] [funambol.sogo] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [sogo-cal] In beginSync()...
[2010-09-21 11:03:06,149] [funambol.sogo] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [sogo-cal] Loading the JDBC driver for URL: jdbc:mysql://localhost/sogo
[2010-09-21 11:03:06,153] [funambol.sogo] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [sogo-cal] Context's user ID: skasch.domain.com
[2010-09-21 11:03:06,153] [funambol.sogo] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [sogo-cal] Context sync mode: 200
[2010-09-21 11:03:06,153] [funambol.sogo] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [sogo-cal] Context conflict resolution: 1
[2010-09-21 11:03:06,154] [funambol.sogo] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [sogo-cal] Context query: null
[2010-09-21 11:03:06,154] [funambol.sogo] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [sogo-cal] Device's charset: UTF-8
[2010-09-21 11:03:06,154] [funambol.sogo] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [sogo-cal] Device's timezone: Europe/Berlin
[2010-09-21 11:03:06,154] [funambol.engine.strategy] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [sogo-cal] Preparing fast synchronization since 2010-09-21 10:59:00.738
[2010-09-21 11:03:06,154] [funambol.sogo] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [sogo-cal] getNewItemKeys(2010-09-21 10:59:00.738(1285059540), 2010-09-21 11:03:05.579(1285059785))
[2010-09-21 11:03:06,155] [funambol.sogo] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [sogo-cal] getUpdatedSyncItemKeys(2010-09-21 10:59:00.738(1285059540), 2010-09-21 11:03:05.579(1285059785))
[2010-09-21 11:03:06,155] [funambol.sogo] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [sogo-cal] getDeletedItemKeys(2010-09-21 10:59:00.738(1285059540), 2010-09-21 11:03:05.579(1285059785))
[2010-09-21 11:03:06,155] [funambol.sogo] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [sogo-cal] in aAlarmCleanup(), s on input is:BEGIN:VCALENDAR
VERSION:1.0
BEGIN:VEVENT
X-FUNAMBOL-FOLDER:DEFAULT_FOLDER
X-FUNAMBOL-ALLDAY:0
DTSTART:20100921T110000Z
DTEND:20100921T150000Z
X-MICROSOFT-CDO-BUSYSTATUS:2
CATEGORIES:
DESCRIPTION;ENCODING=QUOTED-PRINTABLE;CHARSET=UTF-8:Won=E2=80=99t get into WebFrontend
LOCATION:
PRIORITY:2
STATUS:0
X-MICROSOFT-CDO-REPLYTIME:
SUMMARY:From Outlook to SOGo via Funambol 8.7
CLASS:PUBLIC
AALARM:20100921T104500Z;;0;
RRULE:
X-FUNAMBOL-BILLINGINFO:
X-FUNAMBOL-COMPANIES:
X-FUNAMBOL-MILEAGE:
X-FUNAMBOL-NOAGING:0
END:VEVENT
END:VCALENDAR

[2010-09-21 11:03:06,156] [funambol.sogo] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [sogo-cal] found part[1]=20100921T104500Z
[2010-09-21 11:03:06,156] [funambol.sogo] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [sogo-cal] found an alarmdate:20100921T104500Z
[2010-09-21 11:03:06,156] [funambol.sogo] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [sogo-cal] after aAlarmCleanup(), ret value is:BEGIN:VCALENDAR
VERSION:1.0
BEGIN:VEVENT
X-FUNAMBOL-FOLDER:DEFAULT_FOLDER
X-FUNAMBOL-ALLDAY:0
DTSTART:20100921T110000Z
DTEND:20100921T150000Z
X-MICROSOFT-CDO-BUSYSTATUS:2
CATEGORIES:
DESCRIPTION;ENCODING=QUOTED-PRINTABLE;CHARSET=UTF-8:Won=E2=80=99t get into WebFrontend
LOCATION:
PRIORITY:2
STATUS:0
X-MICROSOFT-CDO-REPLYTIME:
SUMMARY:From Outlook to SOGo via Funambol 8.7
CLASS:PUBLIC
AALARM:20100921T104500Z
RRULE:
X-FUNAMBOL-BILLINGINFO:
X-FUNAMBOL-COMPANIES:
X-FUNAMBOL-MILEAGE:
X-FUNAMBOL-NOAGING:0
END:VEVENT
END:VCALENDAR

[2010-09-21 11:03:06,158] [funambol.sogo] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [sogo-cal] DATE 2: 1285066800 tz: sun.util.calendar.ZoneInfo[id="GMT",offset=0,dstSavings=0,useDaylight=false,transitions=0,lastRule=null]
[2010-09-21 11:03:06,159] [funambol.engine.strategy] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [sogo-cal] Preparation completed (last message in the package)
[2010-09-21 11:03:06,159] [funambol.engine.strategy] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [sogo-cal] Synchronizing...
[2010-09-21 11:03:06,159] [funambol.sogo] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [sogo-cal] removeSyncItem( { keyValue: 000000002DA3FF377A868749B91BE9D2D5AD5AE3C4192000 } , 2010-09-21 11:03:05.579 , soft? false)
[2010-09-21 11:03:06,159] [funambol.sogo] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [sogo-cal] Tags: [ ] count: 1
[2010-09-21 11:03:06,159] [funambol.sogo] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [sogo-cal] Target tag: location: sogoskaschal00179f6f7a2 i:1
[2010-09-21 11:03:06,160] [funambol.sogo] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [sogo-cal] addSyncItem(000000002DA3FF377A868749B91BE9D2D5AD5AE3E4192000)
[2010-09-21 11:03:06,160] [funambol.sogo] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [sogo-cal] addSyncItem - type: text/x-vcalendar
[2010-09-21 11:03:06,160] [funambol.sogo] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [sogo-cal] getSyncItemFromId( { keyValue: 000000002DA3FF377A868749B91BE9D2D5AD5AE3E4192000 } )
[2010-09-21 11:03:06,160] [funambol.sogo] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [sogo-cal] addSyncItem - content: BEGIN:VCALENDAR
VERSION:1.0
BEGIN:VEVENT
X-FUNAMBOL-FOLDER:DEFAULT_FOLDER
X-FUNAMBOL-ALLDAY:0
DTSTART:20100921T110000Z
DTEND:20100921T150000Z
X-MICROSOFT-CDO-BUSYSTATUS:BUSY
CATEGORIES:
DESCRIPTION;ENCODING=QUOTED-PRINTABLE;CHARSET=UTF-8:Won=E2=80=99t get into WebFrontend
LOCATION:
PRIORITY:2
STATUS:0
X-MICROSOFT-CDO-REPLYTIME:
SUMMARY:From Outlook to SOGo via Funambol 8.7
CLASS:PUBLIC
AALARM:20100921T104500Z;;0;
RRULE:
X-FUNAMBOL-BILLINGINFO:
X-FUNAMBOL-COMPANIES:
X-FUNAMBOL-MILEAGE:
X-FUNAMBOL-NOAGING:0
END:VEVENT
END:VCALENDAR

[2010-09-21 11:03:06,160] [funambol.sogo] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [sogo-cal] Preferred item type: 1
[2010-09-21 11:03:06,160] [funambol.sogo] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [sogo-cal] No timezone defined in SOGo for user: skasch.domain.com
[2010-09-21 11:03:06,160] [funambol.sogo] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [sogo-cal] in aAlarmCleanup(), s on input is:BEGIN:VCALENDAR
VERSION:1.0
BEGIN:VEVENT
X-FUNAMBOL-FOLDER:DEFAULT_FOLDER
X-FUNAMBOL-ALLDAY:0
DTSTART:20100921T110000Z
DTEND:20100921T150000Z
X-MICROSOFT-CDO-BUSYSTATUS:2
CATEGORIES:
DESCRIPTION;ENCODING=QUOTED-PRINTABLE;CHARSET=UTF-8:Won=E2=80=99t get into WebFrontend
LOCATION:
PRIORITY:2
STATUS:0
X-MICROSOFT-CDO-REPLYTIME:
SUMMARY:From Outlook to SOGo via Funambol 8.7
CLASS:PUBLIC
AALARM:20100921T104500Z;;0;
RRULE:
X-FUNAMBOL-BILLINGINFO:
X-FUNAMBOL-COMPANIES:
X-FUNAMBOL-MILEAGE:
X-FUNAMBOL-NOAGING:0
END:VEVENT
END:VCALENDAR
[2010-09-21 11:03:06,160] [funambol.sogo] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [sogo-cal] found part[1]=20100921T104500Z
[2010-09-21 11:03:06,160] [funambol.sogo] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [sogo-cal] found an alarmdate:20100921T104500Z
[2010-09-21 11:03:06,160] [funambol.sogo] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [sogo-cal] after aAlarmCleanup(), ret value is:BEGIN:VCALENDAR
VERSION:1.0
BEGIN:VEVENT
X-FUNAMBOL-FOLDER:DEFAULT_FOLDER
X-FUNAMBOL-ALLDAY:0
DTSTART:20100921T110000Z
DTEND:20100921T150000Z
X-MICROSOFT-CDO-BUSYSTATUS:2
CATEGORIES:
DESCRIPTION;ENCODING=QUOTED-PRINTABLE;CHARSET=UTF-8:Won=E2=80=99t get into WebFrontend
LOCATION:
PRIORITY:2
STATUS:0
X-MICROSOFT-CDO-REPLYTIME:
SUMMARY:From Outlook to SOGo via Funambol 8.7
CLASS:PUBLIC
AALARM:20100921T104500Z
RRULE:
X-FUNAMBOL-BILLINGINFO:
X-FUNAMBOL-COMPANIES:
X-FUNAMBOL-MILEAGE:
X-FUNAMBOL-NOAGING:0
END:VEVENT
END:VCALENDAR

[2010-09-21 11:03:06,166] [funambol.sogo] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [sogo-cal] Converting vCalendar to iCalendar
[2010-09-21 11:03:06,170] [funambol.sogo] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [sogo-cal] Adding work-around for VALARM
[2010-09-21 11:03:06,170] [funambol.sogo] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [sogo-cal] Removed REPEAT:0 from VALARM
[2010-09-21 11:03:06,170] [funambol.sogo] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [sogo-cal] DATE 2: 1285066800 tz: sun.util.calendar.ZoneInfo[id="GMT",offset=0,dstSavings=0,useDaylight=false,transitions=0,lastRule=null]
[2010-09-21 11:03:06,170] [funambol.sogo] [DEBUG] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [sogo-cal] Property value is null or empty
[2010-09-21 11:03:06,170] [funambol.sogo] [DEBUG] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [sogo-cal] Property value is null or empty
[2010-09-21 11:03:06,171] [funambol.sogo] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [sogo-cal] In commitSync()...
[2010-09-21 11:03:06,172] [funambol.handler] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [] moving to state: STATE_PKG3_RECEIVED
[2010-09-21 11:03:06,173] [funambol.transport.http] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [] Request processed.
[2010-09-21 11:03:06,184] [funambol.transport.http] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [] Handling incoming request
[2010-09-21 11:03:06,184] [funambol.transport.http] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [] Request URL: http://team.domain.com:8080/funambol/ds
[2010-09-21 11:03:06,184] [funambol.transport.http] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [] Requested sessionId: 6BB414AF9C8B88E75D7212EAF4FAAA18
[2010-09-21 11:03:06,186] [funambol.handler] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [] moving to state: STATE_PKG1_RECEIVING
[2010-09-21 11:03:06,187] [funambol.transport.http] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [] Request processed.
[2010-09-21 11:03:06,201] [funambol.transport.http] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [] Handling incoming request
[2010-09-21 11:03:06,201] [funambol.transport.http] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [] Request URL: http://team.domain.com:8080/funambol/ds
[2010-09-21 11:03:06,201] [funambol.transport.http] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [] Requested sessionId: 6BB414AF9C8B88E75D7212EAF4FAAA18
[2010-09-21 11:03:06,203] [funambol.handler] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [] moving to state: STATE_PKG3_RECEIVING
[2010-09-21 11:03:06,203] [funambol.handler] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [] moving to state: STATE_END
[2010-09-21 11:03:06,203] [funambol.engine.source] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [sogo-cal] sogo-cal: 0 new items added, 0 existing items updated, 0 items deleted.
[2010-09-21 11:03:06,203] [funambol.sogo] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [sogo-cal] In endSync()...
[2010-09-21 11:03:06,203] [funambol.sogo] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [sogo-cal] Closed the database connection.
[2010-09-21 11:03:06,204] [funambol.handler] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [] moving to state: STATE_ENDED
[2010-09-21 11:03:06,204] [funambol.handler] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [] Synchronization completed
[2010-09-21 11:03:06,205] [funambol.transport.http] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [] Request processed.
[2010-09-21 11:03:06,205] [funambol.transport.http] [INFO] [6BB414AF9C8B88E75D7212EAF4FAAA18] [172.20.4.103] [fol-U1VQUE9SVC03OnNrYXNjaA==] [skasch.domain.com] [] Closing session 6BB414AF9C8B88E75D7212EAF4FAAA18

TagsNo tags attached.

Activities

skasch

skasch

2010-09-21 10:29

reporter   ~0001500

Update:

The missing Calendar-entry from Outlook is now visible in Thunderbird... through lightning with address: https://team.domain.com/SOGo/dav/skasch.domain.com/Calendar/personal/

But the Webfrontend keeps on crashing.

regards,

sascha
skasch

skasch

2010-09-21 13:03

reporter   ~0001501

Hi,

another hour later the entry suddenly appears in the webfrontend... how come?
sogo is not crashing any longer, but why does it take so long to commit an appointment to sogo? and why does sogo log this as long as he won´t show an calendar-entry:

child 25000 exited
Sep 20 12:46:14 sogod: SOGo watchdog [24383]: <0x0x8c66490[WOWatchDogChild]> (terminated due to signal 11)

regards, sascha
skasch

skasch

2010-09-22 08:31

reporter   ~0001505

day later i can´t get sogo to show events from outlook. not even if i wait two hours as observed yesterday. i´m now back to funambol-bundle 8.5.1 and outlook client 8.5.4 but no dice. contacts sync, events sync in both ways and show up at least in thunderbird but not in the webfrontend. here again a more verbose log from sogo when refreshing after sync:

2010-09-22 10:31:01.510 sogod[20021] Note: Using UTF-8 as URL encoding in NGExtensions.
Sep 22 10:31:01 sogod: SOGo watchdog [20021]: <0x0x9802e00[SOGoCache]> Cache cleanup interval set every 300.000000 seconds
Sep 22 10:31:01 sogod: SOGo watchdog [20021]: <0x0x9802e00[SOGoCache]> Using host(s) 'localhost' as server(s)
2010-09-22 10:31:01.512 sogod[20021] Scanner initialised with nil string
2010-09-22 10:31:01.536 sogod[20021] Note(SoObject): SoDebugKeyLookup is enabled!
2010-09-22 10:31:01.536 sogod[20021] Note(SoObject): SoDebugBaseURL is enabled!
2010-09-22 10:31:01.536 sogod[20021] Note(SoObject): relative base URLs are enabled.
Sep 22 10:31:01 sogod: SOGo watchdog [20021]: [WARN] <0x0x993a2e8[SOGoWebDAVAclManager]> entry '{DAV:}write' already exists in DAV permissions table
Sep 22 10:31:01 sogod: SOGo watchdog [20021]: [WARN] <0x0x993a2e8[SOGoWebDAVAclManager]> entry '{DAV:}write-properties' already exists in DAV permissions table
Sep 22 10:31:01 sogod: SOGo watchdog [20021]: [WARN] <0x0x993a2e8[SOGoWebDAVAclManager]> entry '{DAV:}write-content' already exists in DAV permissions table
127.0.0.1 - - [22/Sep/2010:10:31:01 GMT] "POST /SOGo/so/skasch.albertbauer.com/Calendar/reloadWebCalendars HTTP/1.1" 204 0/0 0.049 - - 1M
127.0.0.1 - - [22/Sep/2010:10:31:01 GMT] "POST /SOGo/so/skasch.albertbauer.com/Calendar/eventslist?asc=true&sort=start&day=20100922&filterpopup=view_today HTTP/1.1" 200 254/0 0.019 - - 148K
127.0.0.1 - - [22/Sep/2010:10:31:01 GMT] "POST /SOGo/so/skasch.albertbauer.com/Calendar/taskslist?show-completed=NaN HTTP/1.1" 200 2/0 0.017 - - 4K
127.0.0.1 - - [22/Sep/2010:10:31:01 GMT] "POST /SOGo/so/skasch.albertbauer.com/Calendar/alarmslist?browserTime=1285144261 HTTP/1.1" 200 2/0 0.016 - - 0
2010-09-22 10:31:01.617 sogod[20021] ERROR(-[NGBundleManager bundleWithPath:]): could not create bundle for path: '/usr/share/GNUstep/Libraries/gnustep-base/Versions/1.16/Resources/SSL.bundle'
2010-09-22 10:31:01.625 sogod[20021] WOCompoundElement: pool embedding is on.
2010-09-22 10:31:01.625 sogod[20021] WOCompoundElement: id logging is on.
127.0.0.1 - - [22/Sep/2010:10:31:01 GMT] "POST /SOGo/so/skasch.albertbauer.com/Calendar/weekview?day=20100922 HTTP/1.1" 200 1957/0 0.034 22343 91% 1M
Sep 22 10:31:01 sogod: SOGo watchdog [19845]: <0x0x962bc90[WOWatchDogChild]> child 20021 exited
Sep 22 10:31:01 sogod: SOGo watchdog [19845]: <0x0x962bc90[WOWatchDogChild]> (terminated due to signal 11)
Sep 22 10:31:01 sogod: SOGo watchdog [19845]: <0x0x960fa88[WOWatchDog]> child spawned with pid 20119
Sep 22 10:31:01 sogod: SOGo watchdog [20119]: <0x0x976ba40[SOGoProductLoader]> SOGo products loaded from '/usr/lib/GNUstep/SOGo':
Sep 22 10:31:01 sogod: SOGo watchdog [20119]: <0x0x976ba40[SOGoProductLoader]> CommonUI.SOGo, MailerUI.SOGo, ContactsUI.SOGo, Contacts.SOGo, Mailer.SOGo, Appointments.SOGo, MainUI.SOGo, AdministrationUI.SOGo, PreferencesUI.SOGo, MailPartViewers.SOGo, SchedulerUI.SOGo
Sep 22 10:31:01 sogod: SOGo watchdog [20119]: |SOGo| version 1.3.2 (build @vizzini.inverse.ca 201009211534) -- starting
Sep 22 10:31:01 sogod: SOGo watchdog [20119]: |SOGo| vmem size check enabled: shutting down app when vmem > 2560 MB
skasch

skasch

2010-09-27 16:07

reporter   ~0001517

hi,

built from scratch on ubuntu 10.04 all problems were gone. apparently this bug
is no bug so it can be closed, thanks.

sascha

Issue History

Date Modified Username Field Change
2010-09-21 09:42 skasch New Issue
2010-09-21 10:29 skasch Note Added: 0001500
2010-09-21 13:03 skasch Note Added: 0001501
2010-09-22 08:31 skasch Note Added: 0001505
2010-09-27 16:07 skasch Note Added: 0001517
2010-09-27 16:08 ludovic Status new => closed
2010-09-27 16:08 ludovic Resolution open => no change required