"Funambol Data Synchronization Server never started"
[2011-05-05 12:49:00,047] [funambol.server.updatediscovery] [INFO] [] [] [] [] [] Checking for updates on http://www.funambol.com/opensource/download/updates
[2011-05-05 12:49:00,049] [funambol.server.plugin] [INFO] [] [] [] [] [] No plugin to start
[2011-05-05 12:49:00,099] [funambol.transport.http] [INFO] [] [] [] [] [] ================================================================================


[2011-05-05 12:49:00,099] [funambol.transport.http] [INFO] [] [] [] [] [] Funambol Data Synchronization Server v. 8.7.0 engine started.
Configuration object found.


[2011-05-05 12:49:00,105] [funambol.transport.http] [INFO] [] [] [] [] [] {java.vendor=Sun Microsystems Inc., sun.java.launcher=SUN_STANDARD, catalina.base=/opt/Funambol/tools/tomcat, sun.management.compiler=HotSpot Client Compiler, catalina.useNaming=true, os.name=Linux, sun.boot.class.path=/opt/Funambol/tools/jre-1.6.0/jre/lib/resources.jar:/opt/Funambol/tools/jre-1.6.0/jre/lib/rt.jar:/opt/Funambol/tools/jre-1.6.0/jre/lib/sunrsasign.jar:/opt/Funambol/tools/jre-1.6.0/jre/lib/jsse.jar:/opt/Funambol/tools/jre-1.6.0/jre/lib/jce.jar:/opt/Funambol/tools/jre-1.6.0/jre/lib/charsets.jar:/opt/Funambol/tools/jre-1.6.0/jre/classes, java.util.logging.config.file=/opt/Funambol/tools/tomcat/conf/logging.properties, funambol.home=/opt/Funambol, com.sun.management.jmxremote=, java.vm.specification.vendor=Sun Microsystems Inc., java.runtime.version=1.6.0_17-b04, user.name=root, shared.loader=, tomcat.util.buf.StringCache.byte.enabled=true, java.naming.factory.initial=org.apache.naming.java.javaURLContextFactory, user.language=en, sun.boot.library.path=/opt/Funambol/tools/jre-1.6.0/jre/lib/i386, com.sun.management.jmxremote.port=8101, java.version=1.6.0_17, java.util.logging.manager=org.apache.juli.ClassLoaderLogManager, user.timezone=America/Montreal, java.net.preferIPv4Stack=true, sun.arch.data.model=32, java.endorsed.dirs=/opt/Funambol/tools/tomcat/endorsed, java.rmi.server.randomIDs=true, sun.cpu.isalist=, sun.jnu.encoding=UTF-8, file.encoding.pkg=sun.io, package.access=sun.,org.apache.catalina.,org.apache.coyote.,org.apache.tomcat.,org.apache.jasper.,sun.beans., file.separator=/, java.specification.name=Java Platform API Specification, java.class.version=50.0, user.country=US, java.home=/opt/Funambol/tools/jre-1.6.0/jre, java.vm.info=mixed mode, os.version=2.6.32-31-generic-pae, funambol.debug=false, com.sun.management.jmxremote.ssl=false, path.separator=:, java.vm.version=14.3-b01, java.awt.printerjob=sun.print.PSPrinterJob, sun.io.unicode.encoding=UnicodeLittle, com.sun.management.jmxremote.authenticate=false, package.definition=sun.,java.,org.apache.catalina.,org.apache.coyote.,org.apache.tomcat.,org.apache.jasper., java.naming.factory.url.pkgs=org.apache.naming, user.home=/root, java.specification.vendor=Sun Microsystems Inc., java.library.path=/opt/Funambol/ds-server/lib/linux, java.vendor.url=http://java.sun.com/, java.vm.vendor=Sun Microsystems Inc., common.loader=${catalina.home}/lib,${catalina.home}/lib/*.jar, java.runtime.name=Java(TM) SE Runtime Environment, java.class.path=:/opt/Funambol/tools/tomcat/bin/bootstrap.jar, java.vm.specification.name=Java Virtual Machine Specification, java.vm.specification.version=1.0, catalina.home=/opt/Funambol/tools/tomcat, sun.cpu.endian=little, sun.os.patch.level=unknown, java.awt.headless=true, java.io.tmpdir=/opt/Funambol/tools/tomcat/temp, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, server.loader=, os.arch=i386, java.awt.graphicsenv=sun.awt.X11GraphicsEnvironment, java.ext.dirs=/opt/Funambol/tools/jre-1.6.0/jre/lib/ext:/usr/java/packages/lib/ext, user.dir=/opt/Funambol/tools/tomcat/bin, line.separator=
, java.vm.name=Java HotSpot(TM) Client VM, file.encoding=UTF-8, java.specification.version=1.6, javax.net.ssl.trustStore=/opt/Funambol/ds-server/lib/security/cacerts}


[2011-05-05 12:49:00,105] [funambol.transport.http] [INFO] [] [] [] [] [] ================================================================================
[2011-05-05 12:49:00,639] [funambol.transport.http] [INFO] [] [] [] [] [] Initializing Funambol servlet with:
> sync-holder-class:  com.funambol.transport.http.server.LocalSyncHolder
> session-timeout:    900 s
> log-messages:       false
> dirlog-messages:    not specified
> enable-compression: true
> preferred-encoding: gzip
> supported-encoding: gzip,deflate
> compression-level:  -1
[2011-05-05 12:49:00,639] [funambol.transport.http] [INFO] [] [] [] [] [] Engine configuration:
> store:            com.funambol.server.store.PersistentStoreManager - {stores: com.funambol.server.store.SyncSourcePersistentStore@1bf68a9,com.funambol.server.store.IDPersistentStore@1706eb7,com.funambol.server.store.ModulesPersistentStore@74db2c,com.funambol.server.store.ClientMappingPersistentStore@1e16483,com.funambol.server.store.PrincipalPersistentStore@8ddb93,com.funambol.server.store.LastTimestampPersistentStore@14ab51b
> officer:          com.funambol.server.security.UserProvisioningOfficer@1b82d69 (com/funambol/server/security/UserProvisioningOfficer.xml)
> strategy:         com.funambol.server.engine.Sync4jStrategy@ad6513 (com/funambol/server/engine/Strategy.xml)
> default encoding: UTF8
> server URI:       not specified
[2011-05-05 12:49:00,651] [funambol.framework.encryption] [INFO] [] [] [] [] [] Initializing encryption key
[2011-05-05 12:49:00,652] [funambol.framework.encryption] [INFO] [] [] [] [] [] EncryptionKeyProvider not found, using default key
[2011-05-05 12:49:01,535] [funambol.server.updatediscovery] [INFO] [] [] [] [] [] Detected new DS Server version: com.funambol.server.update.Component@1e3bbd7[name=ds-server,version=9.0.0,releaseDate=2011-01-19,shortDescription=,longDescription=,url=]
[2011-05-05 12:49:21,654] [funambol.configuration] [INFO] [] [] [] [] [] Stopping push manager
[2011-05-05 12:49:21,674] [funambol.configuration] [INFO] [] [] [] [] [] Stopping configuration monitor [funambol-directory-monitor-19e11a1]
[2011-05-05 12:49:21,674] [funambol.configuration] [INFO] [] [] [] [] [] Stopping update discovery thread [funambol-update-discovery-thread-87e704]
[2011-05-05 12:49:21,910] [funambol.configuration] [INFO] [] [] [] [] [] Stopping plugins
[2011-05-05 12:49:21,910] [funambol.server.plugin] [INFO] [] [] [] [] [] No plugin to stop
[2011-05-05 12:49:23,479] [funambol.configuration] [INFO] [] [] [] [] [] Stopping configuration monitor [funambol-directory-monitor-1c13d0b]
[2011-05-05 12:51:50,003] [funambol.server.plugin] [INFO] [] [] [] [] [] No plugin to start
[2011-05-05 12:51:50,004] [funambol.server.updatediscovery] [INFO] [] [] [] [] [] Checking for updates on http://www.funambol.com/opensource/download/updates
[2011-05-05 12:51:50,086] [funambol.transport.http] [INFO] [] [] [] [] [] ================================================================================


[2011-05-05 12:51:50,087] [funambol.transport.http] [INFO] [] [] [] [] [] Funambol Data Synchronization Server v. 8.7.0 engine started.
Configuration object found.


[2011-05-05 12:51:50,087] [funambol.transport.http] [INFO] [] [] [] [] [] {java.vendor=Sun Microsystems Inc., sun.java.launcher=SUN_STANDARD, catalina.base=/opt/Funambol/tools/tomcat, sun.management.compiler=HotSpot Client Compiler, catalina.useNaming=true, os.name=Linux, sun.boot.class.path=/opt/Funambol/tools/jre-1.6.0/jre/lib/resources.jar:/opt/Funambol/tools/jre-1.6.0/jre/lib/rt.jar:/opt/Funambol/tools/jre-1.6.0/jre/lib/sunrsasign.jar:/opt/Funambol/tools/jre-1.6.0/jre/lib/jsse.jar:/opt/Funambol/tools/jre-1.6.0/jre/lib/jce.jar:/opt/Funambol/tools/jre-1.6.0/jre/lib/charsets.jar:/opt/Funambol/tools/jre-1.6.0/jre/classes, java.util.logging.config.file=/opt/Funambol/tools/tomcat/conf/logging.properties, funambol.home=/opt/Funambol, com.sun.management.jmxremote=, java.vm.specification.vendor=Sun Microsystems Inc., java.runtime.version=1.6.0_17-b04, user.name=root, shared.loader=, tomcat.util.buf.StringCache.byte.enabled=true, java.naming.factory.initial=org.apache.naming.java.javaURLContextFactory, user.language=en, sun.boot.library.path=/opt/Funambol/tools/jre-1.6.0/jre/lib/i386, com.sun.management.jmxremote.port=8101, java.version=1.6.0_17, java.util.logging.manager=org.apache.juli.ClassLoaderLogManager, user.timezone=America/Montreal, java.net.preferIPv4Stack=true, sun.arch.data.model=32, java.endorsed.dirs=/opt/Funambol/tools/tomcat/endorsed, java.rmi.server.randomIDs=true, sun.cpu.isalist=, sun.jnu.encoding=UTF-8, file.encoding.pkg=sun.io, package.access=sun.,org.apache.catalina.,org.apache.coyote.,org.apache.tomcat.,org.apache.jasper.,sun.beans., file.separator=/, java.specification.name=Java Platform API Specification, java.class.version=50.0, user.country=US, java.home=/opt/Funambol/tools/jre-1.6.0/jre, java.vm.info=mixed mode, os.version=2.6.32-31-generic-pae, funambol.debug=false, com.sun.management.jmxremote.ssl=false, path.separator=:, java.vm.version=14.3-b01, java.awt.printerjob=sun.print.PSPrinterJob, sun.io.unicode.encoding=UnicodeLittle, com.sun.management.jmxremote.authenticate=false, package.definition=sun.,java.,org.apache.catalina.,org.apache.coyote.,org.apache.tomcat.,org.apache.jasper., java.naming.factory.url.pkgs=org.apache.naming, user.home=/root, java.specification.vendor=Sun Microsystems Inc., java.library.path=/opt/Funambol/ds-server/lib/linux, java.vendor.url=http://java.sun.com/, java.vm.vendor=Sun Microsystems Inc., common.loader=${catalina.home}/lib,${catalina.home}/lib/*.jar, java.runtime.name=Java(TM) SE Runtime Environment, java.class.path=:/opt/Funambol/tools/tomcat/bin/bootstrap.jar, java.vm.specification.name=Java Virtual Machine Specification, java.vm.specification.version=1.0, catalina.home=/opt/Funambol/tools/tomcat, sun.cpu.endian=little, sun.os.patch.level=unknown, java.awt.headless=true, java.io.tmpdir=/opt/Funambol/tools/tomcat/temp, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, server.loader=, os.arch=i386, java.awt.graphicsenv=sun.awt.X11GraphicsEnvironment, java.ext.dirs=/opt/Funambol/tools/jre-1.6.0/jre/lib/ext:/usr/java/packages/lib/ext, user.dir=/opt/Funambol/tools/tomcat/bin, line.separator=
, java.vm.name=Java HotSpot(TM) Client VM, file.encoding=UTF-8, java.specification.version=1.6, javax.net.ssl.trustStore=/opt/Funambol/ds-server/lib/security/cacerts}


[2011-05-05 12:51:50,091] [funambol.transport.http] [INFO] [] [] [] [] [] ================================================================================
[2011-05-05 12:51:50,154] [funambol.transport.http] [INFO] [] [] [] [] [] Initializing Funambol servlet with:
> sync-holder-class:  com.funambol.transport.http.server.LocalSyncHolder
> session-timeout:    900 s
> log-messages:       false
> dirlog-messages:    not specified
> enable-compression: true
> preferred-encoding: gzip
> supported-encoding: gzip,deflate
> compression-level:  -1
[2011-05-05 12:51:50,155] [funambol.transport.http] [INFO] [] [] [] [] [] Engine configuration:
> store:            com.funambol.server.store.PersistentStoreManager - {stores: com.funambol.server.store.SyncSourcePersistentStore@88b1e6,com.funambol.server.store.IDPersistentStore@578dfb,com.funambol.server.store.ModulesPersistentStore@2a987d,com.funambol.server.store.ClientMappingPersistentStore@813bc1,com.funambol.server.store.PrincipalPersistentStore@7a36a2,com.funambol.server.store.LastTimestampPersistentStore@198c6f3
> officer:          ca.inverse.sogo.security.SOGoOfficer@12d8ecd (ca/inverse/sogo/security/SOGoOfficer.xml)
> strategy:         com.funambol.server.engine.Sync4jStrategy@1fa5e5e (com/funambol/server/engine/Strategy.xml)
> default encoding: UTF8
> server URI:       not specified
[2011-05-05 12:51:50,238] [funambol.framework.encryption] [INFO] [] [] [] [] [] Initializing encryption key
[2011-05-05 12:51:50,239] [funambol.framework.encryption] [INFO] [] [] [] [] [] EncryptionKeyProvider not found, using default key
[2011-05-05 12:51:50,749] [funambol.server.updatediscovery] [INFO] [] [] [] [] [] Detected new DS Server version: com.funambol.server.update.Component@16d3536[name=ds-server,version=9.0.0,releaseDate=2011-01-19,shortDescription=,longDescription=,url=]
[2011-05-05 12:52:19,740] [funambol.configuration] [INFO] [] [] [] [] [] Stopping push manager
[2011-05-05 12:52:19,763] [funambol.configuration] [INFO] [] [] [] [] [] Stopping configuration monitor [funambol-directory-monitor-1ce1bea]
[2011-05-05 12:52:19,763] [funambol.configuration] [INFO] [] [] [] [] [] Stopping update discovery thread [funambol-update-discovery-thread-1024994]
[2011-05-05 12:52:19,857] [funambol.configuration] [INFO] [] [] [] [] [] Stopping plugins
[2011-05-05 12:52:19,857] [funambol.server.plugin] [INFO] [] [] [] [] [] No plugin to stop
[2011-05-05 12:52:20,603] [funambol.configuration] [INFO] [] [] [] [] [] Stopping configuration monitor [funambol-directory-monitor-c6e1ec]
[2011-05-05 12:53:39,904] [funambol.server.plugin] [INFO] [] [] [] [] [] No plugin to start
[2011-05-05 12:53:39,955] [funambol.transport.http] [INFO] [] [] [] [] [] ================================================================================


[2011-05-05 12:53:39,955] [funambol.transport.http] [INFO] [] [] [] [] [] Funambol Data Synchronization Server v. 8.7.0 engine started.
Configuration object found.


[2011-05-05 12:53:39,955] [funambol.transport.http] [INFO] [] [] [] [] [] {java.vendor=Sun Microsystems Inc., sun.java.launcher=SUN_STANDARD, catalina.base=/opt/Funambol/tools/tomcat, sun.management.compiler=HotSpot Client Compiler, catalina.useNaming=true, os.name=Linux, sun.boot.class.path=/opt/Funambol/tools/jre-1.6.0/jre/lib/resources.jar:/opt/Funambol/tools/jre-1.6.0/jre/lib/rt.jar:/opt/Funambol/tools/jre-1.6.0/jre/lib/sunrsasign.jar:/opt/Funambol/tools/jre-1.6.0/jre/lib/jsse.jar:/opt/Funambol/tools/jre-1.6.0/jre/lib/jce.jar:/opt/Funambol/tools/jre-1.6.0/jre/lib/charsets.jar:/opt/Funambol/tools/jre-1.6.0/jre/classes, java.util.logging.config.file=/opt/Funambol/tools/tomcat/conf/logging.properties, funambol.home=/opt/Funambol, com.sun.management.jmxremote=, java.vm.specification.vendor=Sun Microsystems Inc., java.runtime.version=1.6.0_17-b04, user.name=root, shared.loader=, tomcat.util.buf.StringCache.byte.enabled=true, java.naming.factory.initial=org.apache.naming.java.javaURLContextFactory, user.language=en, sun.boot.library.path=/opt/Funambol/tools/jre-1.6.0/jre/lib/i386, com.sun.management.jmxremote.port=8101, java.version=1.6.0_17, java.util.logging.manager=org.apache.juli.ClassLoaderLogManager, user.timezone=America/Montreal, java.net.preferIPv4Stack=true, sun.arch.data.model=32, java.endorsed.dirs=/opt/Funambol/tools/tomcat/endorsed, java.rmi.server.randomIDs=true, sun.cpu.isalist=, sun.jnu.encoding=UTF-8, file.encoding.pkg=sun.io, package.access=sun.,org.apache.catalina.,org.apache.coyote.,org.apache.tomcat.,org.apache.jasper.,sun.beans., file.separator=/, java.specification.name=Java Platform API Specification, java.class.version=50.0, user.country=US, java.home=/opt/Funambol/tools/jre-1.6.0/jre, java.vm.info=mixed mode, os.version=2.6.32-31-generic-pae, funambol.debug=false, com.sun.management.jmxremote.ssl=false, path.separator=:, java.vm.version=14.3-b01, java.awt.printerjob=sun.print.PSPrinterJob, sun.io.unicode.encoding=UnicodeLittle, com.sun.management.jmxremote.authenticate=false, package.definition=sun.,java.,org.apache.catalina.,org.apache.coyote.,org.apache.tomcat.,org.apache.jasper., java.naming.factory.url.pkgs=org.apache.naming, user.home=/root, java.specification.vendor=Sun Microsystems Inc., java.library.path=/opt/Funambol/ds-server/lib/linux, java.vendor.url=http://java.sun.com/, java.vm.vendor=Sun Microsystems Inc., common.loader=${catalina.home}/lib,${catalina.home}/lib/*.jar, java.runtime.name=Java(TM) SE Runtime Environment, java.class.path=:/opt/Funambol/tools/tomcat/bin/bootstrap.jar, java.vm.specification.name=Java Virtual Machine Specification, java.vm.specification.version=1.0, catalina.home=/opt/Funambol/tools/tomcat, sun.cpu.endian=little, sun.os.patch.level=unknown, java.awt.headless=true, java.io.tmpdir=/opt/Funambol/tools/tomcat/temp, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, server.loader=, os.arch=i386, java.awt.graphicsenv=sun.awt.X11GraphicsEnvironment, java.ext.dirs=/opt/Funambol/tools/jre-1.6.0/jre/lib/ext:/usr/java/packages/lib/ext, user.dir=/opt/Funambol/tools/tomcat/bin, line.separator=
, java.vm.name=Java HotSpot(TM) Client VM, file.encoding=UTF-8, java.specification.version=1.6, javax.net.ssl.trustStore=/opt/Funambol/ds-server/lib/security/cacerts}


[2011-05-05 12:53:39,955] [funambol.transport.http] [INFO] [] [] [] [] [] ================================================================================
[2011-05-05 12:53:39,991] [funambol.transport.http] [INFO] [] [] [] [] [] Initializing Funambol servlet with:
> sync-holder-class:  com.funambol.transport.http.server.LocalSyncHolder
> session-timeout:    900 s
> log-messages:       false
> dirlog-messages:    not specified
> enable-compression: true
> preferred-encoding: gzip
> supported-encoding: gzip,deflate
> compression-level:  -1
[2011-05-05 12:53:39,991] [funambol.transport.http] [INFO] [] [] [] [] [] Engine configuration:
> store:            com.funambol.server.store.PersistentStoreManager - {stores: com.funambol.server.store.SyncSourcePersistentStore@a68fd8,com.funambol.server.store.IDPersistentStore@12dd538,com.funambol.server.store.ModulesPersistentStore@97617,com.funambol.server.store.ClientMappingPersistentStore@127f79d,com.funambol.server.store.PrincipalPersistentStore@f19d6e,com.funambol.server.store.LastTimestampPersistentStore@96b5c2
> officer:          ca.inverse.sogo.security.SOGoOfficer@76db09 (ca/inverse/sogo/security/SOGoOfficer.xml)
> strategy:         com.funambol.server.engine.Sync4jStrategy@1265109 (com/funambol/server/engine/Strategy.xml)
> default encoding: UTF8
> server URI:       not specified
[2011-05-05 12:53:39,994] [funambol.framework.encryption] [INFO] [] [] [] [] [] Initializing encryption key
[2011-05-05 12:53:39,995] [funambol.framework.encryption] [INFO] [] [] [] [] [] EncryptionKeyProvider not found, using default key
[2011-05-05 13:43:04,542] [funambol.configuration] [INFO] [] [] [] [] [] Stopping push manager
[2011-05-05 13:43:04,547] [funambol.configuration] [INFO] [] [] [] [] [] Stopping configuration monitor [funambol-directory-monitor-df83e5]
[2011-05-05 13:43:04,547] [funambol.configuration] [INFO] [] [] [] [] [] Stopping update discovery thread [funambol-update-discovery-thread-cb42cf]
[2011-05-05 13:43:05,235] [funambol.configuration] [INFO] [] [] [] [] [] Stopping plugins
[2011-05-05 13:43:05,238] [funambol.server.plugin] [INFO] [] [] [] [] [] No plugin to stop
[2011-05-05 13:43:05,535] [funambol.configuration] [INFO] [] [] [] [] [] Stopping configuration monitor [funambol-directory-monitor-dc9065]
[2011-05-05 14:00:11,135] [funambol.server.plugin] [INFO] [] [] [] [] [] No plugin to start
[2011-05-05 14:00:11,174] [funambol.transport.http] [INFO] [] [] [] [] [] ================================================================================


[2011-05-05 14:00:11,174] [funambol.transport.http] [INFO] [] [] [] [] [] Funambol Data Synchronization Server v. 8.7.0 engine started.
Configuration object found.


[2011-05-05 14:00:11,176] [funambol.transport.http] [INFO] [] [] [] [] [] {java.vendor=Sun Microsystems Inc., sun.java.launcher=SUN_STANDARD, catalina.base=/opt/Funambol/tools/tomcat, sun.management.compiler=HotSpot Client Compiler, catalina.useNaming=true, os.name=Linux, sun.boot.class.path=/opt/Funambol/tools/jre-1.6.0/jre/lib/resources.jar:/opt/Funambol/tools/jre-1.6.0/jre/lib/rt.jar:/opt/Funambol/tools/jre-1.6.0/jre/lib/sunrsasign.jar:/opt/Funambol/tools/jre-1.6.0/jre/lib/jsse.jar:/opt/Funambol/tools/jre-1.6.0/jre/lib/jce.jar:/opt/Funambol/tools/jre-1.6.0/jre/lib/charsets.jar:/opt/Funambol/tools/jre-1.6.0/jre/classes, java.util.logging.config.file=/opt/Funambol/tools/tomcat/conf/logging.properties, funambol.home=/opt/Funambol, com.sun.management.jmxremote=, java.vm.specification.vendor=Sun Microsystems Inc., java.runtime.version=1.6.0_17-b04, user.name=root, shared.loader=, tomcat.util.buf.StringCache.byte.enabled=true, java.naming.factory.initial=org.apache.naming.java.javaURLContextFactory, user.language=en, sun.boot.library.path=/opt/Funambol/tools/jre-1.6.0/jre/lib/i386, com.sun.management.jmxremote.port=8101, java.version=1.6.0_17, java.util.logging.manager=org.apache.juli.ClassLoaderLogManager, user.timezone=America/Montreal, java.net.preferIPv4Stack=true, sun.arch.data.model=32, java.endorsed.dirs=/opt/Funambol/tools/tomcat/endorsed, java.rmi.server.randomIDs=true, sun.cpu.isalist=, sun.jnu.encoding=UTF-8, file.encoding.pkg=sun.io, package.access=sun.,org.apache.catalina.,org.apache.coyote.,org.apache.tomcat.,org.apache.jasper.,sun.beans., file.separator=/, java.specification.name=Java Platform API Specification, java.class.version=50.0, user.country=US, java.home=/opt/Funambol/tools/jre-1.6.0/jre, java.vm.info=mixed mode, os.version=2.6.32-31-generic-pae, funambol.debug=false, com.sun.management.jmxremote.ssl=false, path.separator=:, java.vm.version=14.3-b01, java.awt.printerjob=sun.print.PSPrinterJob, sun.io.unicode.encoding=UnicodeLittle, com.sun.management.jmxremote.authenticate=false, package.definition=sun.,java.,org.apache.catalina.,org.apache.coyote.,org.apache.tomcat.,org.apache.jasper., java.naming.factory.url.pkgs=org.apache.naming, user.home=/root, java.specification.vendor=Sun Microsystems Inc., java.library.path=/opt/Funambol/ds-server/lib/linux, java.vendor.url=http://java.sun.com/, java.vm.vendor=Sun Microsystems Inc., common.loader=${catalina.home}/lib,${catalina.home}/lib/*.jar, java.runtime.name=Java(TM) SE Runtime Environment, java.class.path=:/opt/Funambol/tools/tomcat/bin/bootstrap.jar, java.vm.specification.name=Java Virtual Machine Specification, java.vm.specification.version=1.0, catalina.home=/opt/Funambol/tools/tomcat, sun.cpu.endian=little, sun.os.patch.level=unknown, java.awt.headless=true, java.io.tmpdir=/opt/Funambol/tools/tomcat/temp, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, server.loader=, os.arch=i386, java.awt.graphicsenv=sun.awt.X11GraphicsEnvironment, java.ext.dirs=/opt/Funambol/tools/jre-1.6.0/jre/lib/ext:/usr/java/packages/lib/ext, user.dir=/opt/Funambol/tools/tomcat/bin, line.separator=
, java.vm.name=Java HotSpot(TM) Client VM, file.encoding=UTF-8, java.specification.version=1.6, javax.net.ssl.trustStore=/opt/Funambol/ds-server/lib/security/cacerts}


[2011-05-05 14:00:11,176] [funambol.transport.http] [INFO] [] [] [] [] [] ================================================================================
[2011-05-05 14:00:11,263] [funambol.transport.http] [INFO] [] [] [] [] [] Initializing Funambol servlet with:
> sync-holder-class:  com.funambol.transport.http.server.LocalSyncHolder
> session-timeout:    900 s
> log-messages:       false
> dirlog-messages:    not specified
> enable-compression: true
> preferred-encoding: gzip
> supported-encoding: gzip,deflate
> compression-level:  -1
[2011-05-05 14:00:11,264] [funambol.transport.http] [INFO] [] [] [] [] [] Engine configuration:
> store:            com.funambol.server.store.PersistentStoreManager - {stores: com.funambol.server.store.SyncSourcePersistentStore@1e91a4d,com.funambol.server.store.IDPersistentStore@bdc9b3,com.funambol.server.store.ModulesPersistentStore@523df,com.funambol.server.store.ClientMappingPersistentStore@1762027,com.funambol.server.store.PrincipalPersistentStore@38fff7,com.funambol.server.store.LastTimestampPersistentStore@2cbc86
> officer:          ca.inverse.sogo.security.SOGoOfficer@10d3f0d (ca/inverse/sogo/security/SOGoOfficer.xml)
> strategy:         com.funambol.server.engine.Sync4jStrategy@a68fd8 (com/funambol/server/engine/Strategy.xml)
> default encoding: UTF8
> server URI:       not specified
[2011-05-05 14:00:11,274] [funambol.framework.encryption] [INFO] [] [] [] [] [] Initializing encryption key
[2011-05-05 14:00:11,278] [funambol.framework.encryption] [INFO] [] [] [] [] [] EncryptionKeyProvider not found, using default key
[2011-05-05 14:05:00,227] [funambol.configuration] [INFO] [] [] [] [] [] Stopping push manager
[2011-05-05 14:05:00,227] [funambol.configuration] [INFO] [] [] [] [] [] Stopping configuration monitor [funambol-directory-monitor-15b0e2c]
[2011-05-05 14:05:00,227] [funambol.configuration] [INFO] [] [] [] [] [] Stopping update discovery thread [funambol-update-discovery-thread-b9b8d0]
[2011-05-05 14:05:00,356] [funambol.configuration] [INFO] [] [] [] [] [] Stopping plugins
[2011-05-05 14:05:00,356] [funambol.server.plugin] [INFO] [] [] [] [] [] No plugin to stop
[2011-05-05 14:05:00,496] [funambol.configuration] [INFO] [] [] [] [] [] Stopping configuration monitor [funambol-directory-monitor-1362a63]
[2011-05-05 14:05:54,765] [funambol.server.plugin] [INFO] [] [] [] [] [] No plugin to start
[2011-05-05 14:05:54,863] [funambol.transport.http] [INFO] [] [] [] [] [] ================================================================================


[2011-05-05 14:05:54,864] [funambol.transport.http] [INFO] [] [] [] [] [] Funambol Data Synchronization Server v. 8.7.0 engine started.
Configuration object found.


[2011-05-05 14:05:54,864] [funambol.transport.http] [INFO] [] [] [] [] [] {java.vendor=Sun Microsystems Inc., sun.java.launcher=SUN_STANDARD, catalina.base=/opt/Funambol/tools/tomcat, sun.management.compiler=HotSpot Client Compiler, catalina.useNaming=true, os.name=Linux, sun.boot.class.path=/opt/Funambol/tools/jre-1.6.0/jre/lib/resources.jar:/opt/Funambol/tools/jre-1.6.0/jre/lib/rt.jar:/opt/Funambol/tools/jre-1.6.0/jre/lib/sunrsasign.jar:/opt/Funambol/tools/jre-1.6.0/jre/lib/jsse.jar:/opt/Funambol/tools/jre-1.6.0/jre/lib/jce.jar:/opt/Funambol/tools/jre-1.6.0/jre/lib/charsets.jar:/opt/Funambol/tools/jre-1.6.0/jre/classes, java.util.logging.config.file=/opt/Funambol/tools/tomcat/conf/logging.properties, funambol.home=/opt/Funambol, com.sun.management.jmxremote=, java.vm.specification.vendor=Sun Microsystems Inc., java.runtime.version=1.6.0_17-b04, user.name=root, shared.loader=, tomcat.util.buf.StringCache.byte.enabled=true, java.naming.factory.initial=org.apache.naming.java.javaURLContextFactory, user.language=en, sun.boot.library.path=/opt/Funambol/tools/jre-1.6.0/jre/lib/i386, com.sun.management.jmxremote.port=8101, java.version=1.6.0_17, java.util.logging.manager=org.apache.juli.ClassLoaderLogManager, user.timezone=America/Montreal, java.net.preferIPv4Stack=true, sun.arch.data.model=32, java.endorsed.dirs=/opt/Funambol/tools/tomcat/endorsed, java.rmi.server.randomIDs=true, sun.cpu.isalist=, sun.jnu.encoding=UTF-8, file.encoding.pkg=sun.io, package.access=sun.,org.apache.catalina.,org.apache.coyote.,org.apache.tomcat.,org.apache.jasper.,sun.beans., file.separator=/, java.specification.name=Java Platform API Specification, java.class.version=50.0, user.country=US, java.home=/opt/Funambol/tools/jre-1.6.0/jre, java.vm.info=mixed mode, os.version=2.6.32-31-generic-pae, funambol.debug=false, com.sun.management.jmxremote.ssl=false, path.separator=:, java.vm.version=14.3-b01, java.awt.printerjob=sun.print.PSPrinterJob, sun.io.unicode.encoding=UnicodeLittle, com.sun.management.jmxremote.authenticate=false, package.definition=sun.,java.,org.apache.catalina.,org.apache.coyote.,org.apache.tomcat.,org.apache.jasper., java.naming.factory.url.pkgs=org.apache.naming, user.home=/root, java.specification.vendor=Sun Microsystems Inc., java.library.path=/opt/Funambol/ds-server/lib/linux, java.vendor.url=http://java.sun.com/, java.vm.vendor=Sun Microsystems Inc., common.loader=${catalina.home}/lib,${catalina.home}/lib/*.jar, java.runtime.name=Java(TM) SE Runtime Environment, java.class.path=:/opt/Funambol/tools/tomcat/bin/bootstrap.jar, java.vm.specification.name=Java Virtual Machine Specification, java.vm.specification.version=1.0, catalina.home=/opt/Funambol/tools/tomcat, sun.cpu.endian=little, sun.os.patch.level=unknown, java.awt.headless=true, java.io.tmpdir=/opt/Funambol/tools/tomcat/temp, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, server.loader=, os.arch=i386, java.awt.graphicsenv=sun.awt.X11GraphicsEnvironment, java.ext.dirs=/opt/Funambol/tools/jre-1.6.0/jre/lib/ext:/usr/java/packages/lib/ext, user.dir=/opt/Funambol/tools/tomcat/bin, line.separator=
, java.vm.name=Java HotSpot(TM) Client VM, file.encoding=UTF-8, java.specification.version=1.6, javax.net.ssl.trustStore=/opt/Funambol/ds-server/lib/security/cacerts}


[2011-05-05 14:05:54,865] [funambol.transport.http] [INFO] [] [] [] [] [] ================================================================================
[2011-05-05 14:05:54,892] [funambol.transport.http] [INFO] [] [] [] [] [] Initializing Funambol servlet with:
> sync-holder-class:  com.funambol.transport.http.server.LocalSyncHolder
> session-timeout:    900 s
> log-messages:       false
> dirlog-messages:    not specified
> enable-compression: true
> preferred-encoding: gzip
> supported-encoding: gzip,deflate
> compression-level:  -1
[2011-05-05 14:05:54,892] [funambol.transport.http] [INFO] [] [] [] [] [] Engine configuration:
> store:            com.funambol.server.store.PersistentStoreManager - {stores: com.funambol.server.store.SyncSourcePersistentStore@1762027,com.funambol.server.store.IDPersistentStore@38fff7,com.funambol.server.store.ModulesPersistentStore@2cbc86,com.funambol.server.store.ClientMappingPersistentStore@10d3f0d,com.funambol.server.store.PrincipalPersistentStore@a68fd8,com.funambol.server.store.LastTimestampPersistentStore@12dd538
> officer:          ca.inverse.sogo.security.SOGoOfficer@97617 (ca/inverse/sogo/security/SOGoOfficer.xml)
> strategy:         com.funambol.server.engine.Sync4jStrategy@127f79d (com/funambol/server/engine/Strategy.xml)
> default encoding: UTF8
> server URI:       not specified
[2011-05-05 14:05:54,894] [funambol.framework.encryption] [INFO] [] [] [] [] [] Initializing encryption key
[2011-05-05 14:05:54,895] [funambol.framework.encryption] [INFO] [] [] [] [] [] EncryptionKeyProvider not found, using default key
[2011-05-05 14:08:50,448] [funambol.configuration] [INFO] [] [] [] [] [] Stopping push manager
[2011-05-05 14:08:50,449] [funambol.configuration] [INFO] [] [] [] [] [] Stopping configuration monitor [funambol-directory-monitor-ff9053]
[2011-05-05 14:08:50,450] [funambol.configuration] [INFO] [] [] [] [] [] Stopping update discovery thread [funambol-update-discovery-thread-bfed5a]
[2011-05-05 14:08:50,565] [funambol.configuration] [INFO] [] [] [] [] [] Stopping plugins
[2011-05-05 14:08:50,566] [funambol.server.plugin] [INFO] [] [] [] [] [] No plugin to stop
[2011-05-05 14:08:50,752] [funambol.configuration] [INFO] [] [] [] [] [] Stopping configuration monitor [funambol-directory-monitor-f42160]
[2011-07-15 15:24:49,978] [funambol.server.plugin] [INFO] [] [] [] [] [] No plugin to start
[2011-07-15 15:24:49,980] [funambol.server.updatediscovery] [INFO] [] [] [] [] [] Checking for updates on http://www.funambol.com/opensource/download/updates
[2011-07-15 15:24:50,065] [funambol.transport.http] [INFO] [] [] [] [] [] ================================================================================


[2011-07-15 15:24:50,065] [funambol.transport.http] [INFO] [] [] [] [] [] Funambol Data Synchronization Server v. 8.7.0 engine started.
Configuration object found.


[2011-07-15 15:24:50,066] [funambol.transport.http] [INFO] [] [] [] [] [] {java.vendor=Sun Microsystems Inc., sun.java.launcher=SUN_STANDARD, catalina.base=/opt/Funambol/tools/tomcat, sun.management.compiler=HotSpot Client Compiler, catalina.useNaming=true, os.name=Linux, sun.boot.class.path=/opt/Funambol/tools/jre-1.6.0/jre/lib/resources.jar:/opt/Funambol/tools/jre-1.6.0/jre/lib/rt.jar:/opt/Funambol/tools/jre-1.6.0/jre/lib/sunrsasign.jar:/opt/Funambol/tools/jre-1.6.0/jre/lib/jsse.jar:/opt/Funambol/tools/jre-1.6.0/jre/lib/jce.jar:/opt/Funambol/tools/jre-1.6.0/jre/lib/charsets.jar:/opt/Funambol/tools/jre-1.6.0/jre/classes, java.util.logging.config.file=/opt/Funambol/tools/tomcat/conf/logging.properties, funambol.home=/opt/Funambol, com.sun.management.jmxremote=, java.vm.specification.vendor=Sun Microsystems Inc., java.runtime.version=1.6.0_17-b04, user.name=root, shared.loader=, tomcat.util.buf.StringCache.byte.enabled=true, java.naming.factory.initial=org.apache.naming.java.javaURLContextFactory, user.language=en, sun.boot.library.path=/opt/Funambol/tools/jre-1.6.0/jre/lib/i386, com.sun.management.jmxremote.port=8101, java.version=1.6.0_17, java.util.logging.manager=org.apache.juli.ClassLoaderLogManager, user.timezone=America/Montreal, java.net.preferIPv4Stack=true, sun.arch.data.model=32, java.endorsed.dirs=/opt/Funambol/tools/tomcat/endorsed, java.rmi.server.randomIDs=true, sun.cpu.isalist=, sun.jnu.encoding=UTF-8, file.encoding.pkg=sun.io, package.access=sun.,org.apache.catalina.,org.apache.coyote.,org.apache.tomcat.,org.apache.jasper.,sun.beans., file.separator=/, java.specification.name=Java Platform API Specification, java.class.version=50.0, user.country=US, java.home=/opt/Funambol/tools/jre-1.6.0/jre, java.vm.info=mixed mode, os.version=2.6.32-31-generic-pae, funambol.debug=false, com.sun.management.jmxremote.ssl=false, path.separator=:, java.vm.version=14.3-b01, java.awt.printerjob=sun.print.PSPrinterJob, sun.io.unicode.encoding=UnicodeLittle, com.sun.management.jmxremote.authenticate=false, package.definition=sun.,java.,org.apache.catalina.,org.apache.coyote.,org.apache.tomcat.,org.apache.jasper., java.naming.factory.url.pkgs=org.apache.naming, user.home=/root, java.specification.vendor=Sun Microsystems Inc., java.library.path=/opt/Funambol/ds-server/lib/linux, java.vendor.url=http://java.sun.com/, java.vm.vendor=Sun Microsystems Inc., common.loader=${catalina.home}/lib,${catalina.home}/lib/*.jar, java.runtime.name=Java(TM) SE Runtime Environment, java.class.path=:/opt/Funambol/tools/tomcat/bin/bootstrap.jar, java.vm.specification.name=Java Virtual Machine Specification, java.vm.specification.version=1.0, catalina.home=/opt/Funambol/tools/tomcat, sun.cpu.endian=little, sun.os.patch.level=unknown, java.awt.headless=true, java.io.tmpdir=/opt/Funambol/tools/tomcat/temp, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, server.loader=, os.arch=i386, java.awt.graphicsenv=sun.awt.X11GraphicsEnvironment, java.ext.dirs=/opt/Funambol/tools/jre-1.6.0/jre/lib/ext:/usr/java/packages/lib/ext, user.dir=/opt/Funambol/tools/tomcat/bin, line.separator=
, java.vm.name=Java HotSpot(TM) Client VM, file.encoding=UTF-8, java.specification.version=1.6, javax.net.ssl.trustStore=/opt/Funambol/ds-server/lib/security/cacerts}


[2011-07-15 15:24:50,066] [funambol.transport.http] [INFO] [] [] [] [] [] ================================================================================
[2011-07-15 15:24:50,192] [funambol.transport.http] [INFO] [] [] [] [] [] Initializing Funambol servlet with:
> sync-holder-class:  com.funambol.transport.http.server.LocalSyncHolder
> session-timeout:    900 s
> log-messages:       false
> dirlog-messages:    not specified
> enable-compression: true
> preferred-encoding: gzip
> supported-encoding: gzip,deflate
> compression-level:  -1
[2011-07-15 15:24:50,192] [funambol.transport.http] [INFO] [] [] [] [] [] Engine configuration:
> store:            com.funambol.server.store.PersistentStoreManager - {stores: com.funambol.server.store.SyncSourcePersistentStore@1e91a4d,com.funambol.server.store.IDPersistentStore@bdc9b3,com.funambol.server.store.ModulesPersistentStore@523df,com.funambol.server.store.ClientMappingPersistentStore@1762027,com.funambol.server.store.PrincipalPersistentStore@38fff7,com.funambol.server.store.LastTimestampPersistentStore@2cbc86
> officer:          ca.inverse.sogo.security.SOGoOfficer@10d3f0d (ca/inverse/sogo/security/SOGoOfficer.xml)
> strategy:         com.funambol.server.engine.Sync4jStrategy@a68fd8 (com/funambol/server/engine/Strategy.xml)
> default encoding: UTF8
> server URI:       not specified
[2011-07-15 15:24:50,204] [funambol.framework.encryption] [INFO] [] [] [] [] [] Initializing encryption key
[2011-07-15 15:24:50,205] [funambol.framework.encryption] [INFO] [] [] [] [] [] EncryptionKeyProvider not found, using default key
[2011-07-15 15:24:50,860] [funambol.server.updatediscovery] [INFO] [] [] [] [] [] Detected new DS Server version: com.funambol.server.update.Component@ef4504[name=ds-server,version=10.0.2,releaseDate=2011-06-06,shortDescription=,longDescription=,url=]
[2011-07-15 15:54:22,792] [funambol.configuration] [INFO] [] [] [] [] [] Stopping push manager
[2011-07-15 15:54:22,792] [funambol.configuration] [INFO] [] [] [] [] [] Stopping configuration monitor [funambol-directory-monitor-106daba]
[2011-07-15 15:54:22,792] [funambol.configuration] [INFO] [] [] [] [] [] Stopping update discovery thread [funambol-update-discovery-thread-20807c]
[2011-07-15 15:54:22,900] [funambol.configuration] [INFO] [] [] [] [] [] Stopping plugins
[2011-07-15 15:54:22,901] [funambol.server.plugin] [INFO] [] [] [] [] [] No plugin to stop
[2011-07-15 15:54:23,165] [funambol.configuration] [INFO] [] [] [] [] [] Stopping configuration monitor [funambol-directory-monitor-1b7b407]
[2011-08-12 18:47:50,437] [funambol.server.plugin] [INFO] [] [] [] [] [] No plugin to start
[2011-08-12 18:47:50,437] [funambol.server.updatediscovery] [INFO] [] [] [] [] [] Checking for updates on http://www.funambol.com/opensource/download/updates
[2011-08-12 18:47:50,471] [funambol.transport.http] [INFO] [] [] [] [] [] ================================================================================


[2011-08-12 18:47:50,476] [funambol.transport.http] [INFO] [] [] [] [] [] Funambol Data Synchronization Server v. 8.7.0 engine started.
Configuration object found.


[2011-08-12 18:47:50,476] [funambol.transport.http] [INFO] [] [] [] [] [] {java.vendor=Sun Microsystems Inc., sun.java.launcher=SUN_STANDARD, catalina.base=/opt/Funambol/tools/tomcat, sun.management.compiler=HotSpot Client Compiler, catalina.useNaming=true, os.name=Linux, sun.boot.class.path=/opt/Funambol/tools/jre-1.6.0/jre/lib/resources.jar:/opt/Funambol/tools/jre-1.6.0/jre/lib/rt.jar:/opt/Funambol/tools/jre-1.6.0/jre/lib/sunrsasign.jar:/opt/Funambol/tools/jre-1.6.0/jre/lib/jsse.jar:/opt/Funambol/tools/jre-1.6.0/jre/lib/jce.jar:/opt/Funambol/tools/jre-1.6.0/jre/lib/charsets.jar:/opt/Funambol/tools/jre-1.6.0/jre/classes, java.util.logging.config.file=/opt/Funambol/tools/tomcat/conf/logging.properties, funambol.home=/opt/Funambol, com.sun.management.jmxremote=, java.vm.specification.vendor=Sun Microsystems Inc., java.runtime.version=1.6.0_17-b04, user.name=root, shared.loader=, tomcat.util.buf.StringCache.byte.enabled=true, java.naming.factory.initial=org.apache.naming.java.javaURLContextFactory, user.language=en, sun.boot.library.path=/opt/Funambol/tools/jre-1.6.0/jre/lib/i386, com.sun.management.jmxremote.port=8101, java.version=1.6.0_17, java.util.logging.manager=org.apache.juli.ClassLoaderLogManager, user.timezone=America/Montreal, java.net.preferIPv4Stack=true, sun.arch.data.model=32, java.endorsed.dirs=/opt/Funambol/tools/tomcat/endorsed, java.rmi.server.randomIDs=true, sun.cpu.isalist=, sun.jnu.encoding=UTF-8, file.encoding.pkg=sun.io, package.access=sun.,org.apache.catalina.,org.apache.coyote.,org.apache.tomcat.,org.apache.jasper.,sun.beans., file.separator=/, java.specification.name=Java Platform API Specification, java.class.version=50.0, user.country=US, java.home=/opt/Funambol/tools/jre-1.6.0/jre, java.vm.info=mixed mode, os.version=2.6.32-33-generic-pae, funambol.debug=false, com.sun.management.jmxremote.ssl=false, path.separator=:, java.vm.version=14.3-b01, java.awt.printerjob=sun.print.PSPrinterJob, sun.io.unicode.encoding=UnicodeLittle, com.sun.management.jmxremote.authenticate=false, package.definition=sun.,java.,org.apache.catalina.,org.apache.coyote.,org.apache.tomcat.,org.apache.jasper., java.naming.factory.url.pkgs=org.apache.naming, user.home=/root, java.specification.vendor=Sun Microsystems Inc., java.library.path=/opt/Funambol/ds-server/lib/linux, java.vendor.url=http://java.sun.com/, java.vm.vendor=Sun Microsystems Inc., common.loader=${catalina.home}/lib,${catalina.home}/lib/*.jar, java.runtime.name=Java(TM) SE Runtime Environment, java.class.path=:/opt/Funambol/tools/tomcat/bin/bootstrap.jar, java.vm.specification.name=Java Virtual Machine Specification, java.vm.specification.version=1.0, catalina.home=/opt/Funambol/tools/tomcat, sun.cpu.endian=little, sun.os.patch.level=unknown, java.awt.headless=true, java.io.tmpdir=/opt/Funambol/tools/tomcat/temp, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, server.loader=, os.arch=i386, java.awt.graphicsenv=sun.awt.X11GraphicsEnvironment, java.ext.dirs=/opt/Funambol/tools/jre-1.6.0/jre/lib/ext:/usr/java/packages/lib/ext, user.dir=/opt/Funambol/tools/tomcat/bin, line.separator=
, java.vm.name=Java HotSpot(TM) Client VM, file.encoding=UTF-8, java.specification.version=1.6, javax.net.ssl.trustStore=/opt/Funambol/ds-server/lib/security/cacerts}


[2011-08-12 18:47:50,476] [funambol.transport.http] [INFO] [] [] [] [] [] ================================================================================
[2011-08-12 18:47:50,535] [funambol.transport.http] [INFO] [] [] [] [] [] Initializing Funambol servlet with:
> sync-holder-class:  com.funambol.transport.http.server.LocalSyncHolder
> session-timeout:    900 s
> log-messages:       false
> dirlog-messages:    not specified
> enable-compression: true
> preferred-encoding: gzip
> supported-encoding: gzip,deflate
> compression-level:  -1
[2011-08-12 18:47:50,535] [funambol.transport.http] [INFO] [] [] [] [] [] Engine configuration:
> store:            com.funambol.server.store.PersistentStoreManager - {stores: com.funambol.server.store.SyncSourcePersistentStore@180b22e,com.funambol.server.store.IDPersistentStore@31fb31,com.funambol.server.store.ModulesPersistentStore@162522b,com.funambol.server.store.ClientMappingPersistentStore@89dd,com.funambol.server.store.PrincipalPersistentStore@15b1773,com.funambol.server.store.LastTimestampPersistentStore@160bf50
> officer:          ca.inverse.sogo.security.SOGoOfficer@b05236 (ca/inverse/sogo/security/SOGoOfficer.xml)
> strategy:         com.funambol.server.engine.Sync4jStrategy@1e55794 (com/funambol/server/engine/Strategy.xml)
> default encoding: UTF8
> server URI:       not specified
[2011-08-12 18:47:50,582] [funambol.framework.encryption] [INFO] [] [] [] [] [] Initializing encryption key
[2011-08-12 18:47:50,582] [funambol.framework.encryption] [INFO] [] [] [] [] [] EncryptionKeyProvider not found, using default key
[2011-08-12 18:47:50,909] [funambol.server.updatediscovery] [INFO] [] [] [] [] [] Detected new DS Server version: com.funambol.server.update.Component@114b17[name=ds-server,version=10.0.2,releaseDate=2011-06-06,shortDescription=,longDescription=,url=]
[2011-08-12 19:46:53,063] [funambol.transport.http] [INFO] [D0C2774B21682527C461A9F68044146F] [127.0.0.1] [] [] [] Closing session D0C2774B21682527C461A9F68044146F
[2011-08-12 19:50:56,837] [funambol.transport.http] [INFO] [23CF2F1CF09106A233D1EA3FAD2344C0] [127.0.0.1] [] [] [] Handling incoming request
[2011-08-12 19:50:56,838] [funambol.transport.http] [INFO] [23CF2F1CF09106A233D1EA3FAD2344C0] [127.0.0.1] [] [] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 19:50:56,838] [funambol.transport.http] [INFO] [23CF2F1CF09106A233D1EA3FAD2344C0] [127.0.0.1] [] [] [] Requested sessionId: null
[2011-08-12 19:50:57,378] [funambol.handler] [INFO] [23CF2F1CF09106A233D1EA3FAD2344C0] [127.0.0.1] [fac-012571000822136] [sogo1] [] sogo1/fac-012571000822136 logged in.
[2011-08-12 19:50:57,380] [funambol.handler] [INFO] [23CF2F1CF09106A233D1EA3FAD2344C0] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG1_RECEIVING
[2011-08-12 19:50:57,510] [funambol.sogo] [INFO] [23CF2F1CF09106A233D1EA3FAD2344C0] [127.0.0.1] [fac-012571000822136] [sogo1] [] Source URI: sogo-cal
[2011-08-12 19:50:57,510] [funambol.sogo] [INFO] [23CF2F1CF09106A233D1EA3FAD2344C0] [127.0.0.1] [fac-012571000822136] [sogo1] [] Done! Internal item type: 2 (text/x-vevent)
[2011-08-12 19:50:57,512] [funambol.sogo] [INFO] [23CF2F1CF09106A233D1EA3FAD2344C0] [127.0.0.1] [fac-012571000822136] [sogo1] [] Source URI: sogo-card
[2011-08-12 19:50:57,512] [funambol.sogo] [INFO] [23CF2F1CF09106A233D1EA3FAD2344C0] [127.0.0.1] [fac-012571000822136] [sogo1] [] Done! Internal item type: 1 (text/x-vcard)
[2011-08-12 19:50:57,513] [funambol.sogo] [INFO] [23CF2F1CF09106A233D1EA3FAD2344C0] [127.0.0.1] [fac-012571000822136] [sogo1] [] Source URI: sogo-todo
[2011-08-12 19:50:57,513] [funambol.sogo] [INFO] [23CF2F1CF09106A233D1EA3FAD2344C0] [127.0.0.1] [fac-012571000822136] [sogo1] [] Done! Internal item type: 3 (text/x-vtodo)
[2011-08-12 19:50:57,523] [funambol.handler] [INFO] [23CF2F1CF09106A233D1EA3FAD2344C0] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG3_RECEIVING
[2011-08-12 19:50:57,547] [funambol.transport.http] [INFO] [23CF2F1CF09106A233D1EA3FAD2344C0] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request processed.
[2011-08-12 19:52:21,925] [funambol.transport.http] [INFO] [ED7B9B9C8D41D33C4EFE379CB59A8D7C] [127.0.0.1] [] [] [] Handling incoming request
[2011-08-12 19:52:21,926] [funambol.transport.http] [INFO] [ED7B9B9C8D41D33C4EFE379CB59A8D7C] [127.0.0.1] [] [] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 19:52:21,926] [funambol.transport.http] [INFO] [ED7B9B9C8D41D33C4EFE379CB59A8D7C] [127.0.0.1] [] [] [] Requested sessionId: null
[2011-08-12 19:52:22,068] [funambol.handler] [INFO] [ED7B9B9C8D41D33C4EFE379CB59A8D7C] [127.0.0.1] [fac-012571000822136] [sogo1] [] sogo1/fac-012571000822136 logged in.
[2011-08-12 19:52:22,072] [funambol.handler] [INFO] [ED7B9B9C8D41D33C4EFE379CB59A8D7C] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG1_RECEIVING
[2011-08-12 19:52:22,138] [funambol.handler] [INFO] [ED7B9B9C8D41D33C4EFE379CB59A8D7C] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG3_RECEIVING
[2011-08-12 19:52:22,147] [funambol.transport.http] [INFO] [ED7B9B9C8D41D33C4EFE379CB59A8D7C] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request processed.
[2011-08-12 19:52:23,221] [funambol.transport.http] [INFO] [ED7B9B9C8D41D33C4EFE379CB59A8D7C] [127.0.0.1] [fac-012571000822136] [sogo1] [] Handling incoming request
[2011-08-12 19:52:23,221] [funambol.transport.http] [INFO] [ED7B9B9C8D41D33C4EFE379CB59A8D7C] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 19:52:23,221] [funambol.transport.http] [INFO] [ED7B9B9C8D41D33C4EFE379CB59A8D7C] [127.0.0.1] [fac-012571000822136] [sogo1] [] Requested sessionId: ED7B9B9C8D41D33C4EFE379CB59A8D7C
[2011-08-12 19:52:23,247] [funambol.engine] [INFO] [ED7B9B9C8D41D33C4EFE379CB59A8D7C] [127.0.0.1] [fac-012571000822136] [sogo1] [] Starting synchronization ...
[2011-08-12 19:52:23,274] [funambol.engine.strategy] [INFO] [ED7B9B9C8D41D33C4EFE379CB59A8D7C] [127.0.0.1] [fac-012571000822136] [sogo1] [card] Preparing slow synchronization
[2011-08-12 19:52:23,276] [funambol.engine.strategy] [INFO] [ED7B9B9C8D41D33C4EFE379CB59A8D7C] [127.0.0.1] [fac-012571000822136] [sogo1] [card] Preparation completed (last message in the package)
[2011-08-12 19:52:23,276] [funambol.engine.strategy] [INFO] [ED7B9B9C8D41D33C4EFE379CB59A8D7C] [127.0.0.1] [fac-012571000822136] [sogo1] [card] Synchronizing...
[2011-08-12 19:52:23,277] [funambol.handler] [INFO] [ED7B9B9C8D41D33C4EFE379CB59A8D7C] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG3_RECEIVED
[2011-08-12 19:52:23,279] [funambol.transport.http] [INFO] [ED7B9B9C8D41D33C4EFE379CB59A8D7C] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request processed.
[2011-08-12 19:52:23,937] [funambol.transport.http] [INFO] [ED7B9B9C8D41D33C4EFE379CB59A8D7C] [127.0.0.1] [fac-012571000822136] [sogo1] [] Handling incoming request
[2011-08-12 19:52:23,937] [funambol.transport.http] [INFO] [ED7B9B9C8D41D33C4EFE379CB59A8D7C] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 19:52:23,937] [funambol.transport.http] [INFO] [ED7B9B9C8D41D33C4EFE379CB59A8D7C] [127.0.0.1] [fac-012571000822136] [sogo1] [] Requested sessionId: ED7B9B9C8D41D33C4EFE379CB59A8D7C
[2011-08-12 19:52:23,941] [funambol.handler] [INFO] [ED7B9B9C8D41D33C4EFE379CB59A8D7C] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG1_RECEIVING
[2011-08-12 19:52:23,948] [funambol.transport.http] [INFO] [ED7B9B9C8D41D33C4EFE379CB59A8D7C] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request processed.
[2011-08-12 19:52:24,651] [funambol.transport.http] [INFO] [ED7B9B9C8D41D33C4EFE379CB59A8D7C] [127.0.0.1] [fac-012571000822136] [sogo1] [] Handling incoming request
[2011-08-12 19:52:24,651] [funambol.transport.http] [INFO] [ED7B9B9C8D41D33C4EFE379CB59A8D7C] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 19:52:24,651] [funambol.transport.http] [INFO] [ED7B9B9C8D41D33C4EFE379CB59A8D7C] [127.0.0.1] [fac-012571000822136] [sogo1] [] Requested sessionId: ED7B9B9C8D41D33C4EFE379CB59A8D7C
[2011-08-12 19:52:24,659] [funambol.handler] [INFO] [ED7B9B9C8D41D33C4EFE379CB59A8D7C] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG3_RECEIVING
[2011-08-12 19:52:24,659] [funambol.handler] [INFO] [ED7B9B9C8D41D33C4EFE379CB59A8D7C] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_END
[2011-08-12 19:52:24,665] [funambol.handler] [INFO] [ED7B9B9C8D41D33C4EFE379CB59A8D7C] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_ENDED
[2011-08-12 19:52:24,665] [funambol.handler] [INFO] [ED7B9B9C8D41D33C4EFE379CB59A8D7C] [127.0.0.1] [fac-012571000822136] [sogo1] [] Synchronization completed
[2011-08-12 19:52:24,668] [funambol.transport.http] [INFO] [ED7B9B9C8D41D33C4EFE379CB59A8D7C] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request processed.
[2011-08-12 19:52:24,668] [funambol.transport.http] [INFO] [ED7B9B9C8D41D33C4EFE379CB59A8D7C] [127.0.0.1] [fac-012571000822136] [sogo1] [] Closing session ED7B9B9C8D41D33C4EFE379CB59A8D7C
[2011-08-12 19:52:37,966] [funambol.transport.http] [INFO] [119FD4922B03385BB45779525D682652] [127.0.0.1] [] [] [] Handling incoming request
[2011-08-12 19:52:37,967] [funambol.transport.http] [INFO] [119FD4922B03385BB45779525D682652] [127.0.0.1] [] [] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 19:52:37,967] [funambol.transport.http] [INFO] [119FD4922B03385BB45779525D682652] [127.0.0.1] [] [] [] Requested sessionId: null
[2011-08-12 19:52:38,007] [funambol.handler] [INFO] [119FD4922B03385BB45779525D682652] [127.0.0.1] [fac-012571000822136] [sogo1] [] sogo1/fac-012571000822136 logged in.
[2011-08-12 19:52:38,029] [funambol.handler] [INFO] [119FD4922B03385BB45779525D682652] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG1_RECEIVING
[2011-08-12 19:52:38,092] [funambol.handler] [INFO] [119FD4922B03385BB45779525D682652] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG3_RECEIVING
[2011-08-12 19:52:38,095] [funambol.transport.http] [INFO] [119FD4922B03385BB45779525D682652] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request processed.
[2011-08-12 19:52:38,668] [funambol.transport.http] [INFO] [119FD4922B03385BB45779525D682652] [127.0.0.1] [fac-012571000822136] [sogo1] [] Handling incoming request
[2011-08-12 19:52:38,669] [funambol.transport.http] [INFO] [119FD4922B03385BB45779525D682652] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 19:52:38,669] [funambol.transport.http] [INFO] [119FD4922B03385BB45779525D682652] [127.0.0.1] [fac-012571000822136] [sogo1] [] Requested sessionId: 119FD4922B03385BB45779525D682652
[2011-08-12 19:52:38,677] [funambol.engine] [INFO] [119FD4922B03385BB45779525D682652] [127.0.0.1] [fac-012571000822136] [sogo1] [] Starting synchronization ...
[2011-08-12 19:52:38,689] [funambol.engine.strategy] [INFO] [119FD4922B03385BB45779525D682652] [127.0.0.1] [fac-012571000822136] [sogo1] [event] Preparing slow synchronization
[2011-08-12 19:52:38,691] [funambol.engine.strategy] [INFO] [119FD4922B03385BB45779525D682652] [127.0.0.1] [fac-012571000822136] [sogo1] [event] Preparation completed (last message in the package)
[2011-08-12 19:52:38,691] [funambol.engine.strategy] [INFO] [119FD4922B03385BB45779525D682652] [127.0.0.1] [fac-012571000822136] [sogo1] [event] Synchronizing...
[2011-08-12 19:52:38,692] [funambol.handler] [INFO] [119FD4922B03385BB45779525D682652] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG3_RECEIVED
[2011-08-12 19:52:38,695] [funambol.transport.http] [INFO] [119FD4922B03385BB45779525D682652] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request processed.
[2011-08-12 19:52:39,041] [funambol.transport.http] [INFO] [119FD4922B03385BB45779525D682652] [127.0.0.1] [fac-012571000822136] [sogo1] [] Handling incoming request
[2011-08-12 19:52:39,042] [funambol.transport.http] [INFO] [119FD4922B03385BB45779525D682652] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 19:52:39,042] [funambol.transport.http] [INFO] [119FD4922B03385BB45779525D682652] [127.0.0.1] [fac-012571000822136] [sogo1] [] Requested sessionId: 119FD4922B03385BB45779525D682652
[2011-08-12 19:52:39,049] [funambol.handler] [INFO] [119FD4922B03385BB45779525D682652] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG1_RECEIVING
[2011-08-12 19:52:39,056] [funambol.transport.http] [INFO] [119FD4922B03385BB45779525D682652] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request processed.
[2011-08-12 19:52:39,731] [funambol.transport.http] [INFO] [119FD4922B03385BB45779525D682652] [127.0.0.1] [fac-012571000822136] [sogo1] [] Handling incoming request
[2011-08-12 19:52:39,732] [funambol.transport.http] [INFO] [119FD4922B03385BB45779525D682652] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 19:52:39,733] [funambol.transport.http] [INFO] [119FD4922B03385BB45779525D682652] [127.0.0.1] [fac-012571000822136] [sogo1] [] Requested sessionId: 119FD4922B03385BB45779525D682652
[2011-08-12 19:52:39,739] [funambol.handler] [INFO] [119FD4922B03385BB45779525D682652] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG3_RECEIVING
[2011-08-12 19:52:39,739] [funambol.handler] [INFO] [119FD4922B03385BB45779525D682652] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_END
[2011-08-12 19:52:39,745] [funambol.handler] [INFO] [119FD4922B03385BB45779525D682652] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_ENDED
[2011-08-12 19:52:39,745] [funambol.handler] [INFO] [119FD4922B03385BB45779525D682652] [127.0.0.1] [fac-012571000822136] [sogo1] [] Synchronization completed
[2011-08-12 19:52:39,747] [funambol.transport.http] [INFO] [119FD4922B03385BB45779525D682652] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request processed.
[2011-08-12 19:52:39,747] [funambol.transport.http] [INFO] [119FD4922B03385BB45779525D682652] [127.0.0.1] [fac-012571000822136] [sogo1] [] Closing session 119FD4922B03385BB45779525D682652
[2011-08-12 19:59:45,250] [funambol.transport.http] [INFO] [B49CE57C57ECF1181CD762BD8960E7F0] [127.0.0.1] [] [] [] Handling incoming request
[2011-08-12 19:59:45,251] [funambol.transport.http] [INFO] [B49CE57C57ECF1181CD762BD8960E7F0] [127.0.0.1] [] [] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 19:59:45,251] [funambol.transport.http] [INFO] [B49CE57C57ECF1181CD762BD8960E7F0] [127.0.0.1] [] [] [] Requested sessionId: null
[2011-08-12 19:59:45,402] [funambol.handler] [INFO] [B49CE57C57ECF1181CD762BD8960E7F0] [127.0.0.1] [fac-012571000822136] [sogo1] [] sogo1/fac-012571000822136 logged in.
[2011-08-12 19:59:45,426] [funambol.handler] [INFO] [B49CE57C57ECF1181CD762BD8960E7F0] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG1_RECEIVING
[2011-08-12 19:59:45,430] [funambol.handler] [INFO] [B49CE57C57ECF1181CD762BD8960E7F0] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG3_RECEIVING
[2011-08-12 19:59:45,433] [funambol.transport.http] [INFO] [B49CE57C57ECF1181CD762BD8960E7F0] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request processed.
[2011-08-12 19:59:45,869] [funambol.transport.http] [INFO] [B49CE57C57ECF1181CD762BD8960E7F0] [127.0.0.1] [fac-012571000822136] [sogo1] [] Handling incoming request
[2011-08-12 19:59:45,869] [funambol.transport.http] [INFO] [B49CE57C57ECF1181CD762BD8960E7F0] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 19:59:45,869] [funambol.transport.http] [INFO] [B49CE57C57ECF1181CD762BD8960E7F0] [127.0.0.1] [fac-012571000822136] [sogo1] [] Requested sessionId: B49CE57C57ECF1181CD762BD8960E7F0
[2011-08-12 19:59:45,873] [funambol.engine] [INFO] [B49CE57C57ECF1181CD762BD8960E7F0] [127.0.0.1] [fac-012571000822136] [sogo1] [] Starting synchronization ...
[2011-08-12 19:59:45,874] [funambol.engine.strategy] [INFO] [B49CE57C57ECF1181CD762BD8960E7F0] [127.0.0.1] [fac-012571000822136] [sogo1] [card] Preparing fast synchronization since 2011-08-12 19:52:22.045
[2011-08-12 19:59:45,881] [funambol.engine.strategy] [INFO] [B49CE57C57ECF1181CD762BD8960E7F0] [127.0.0.1] [fac-012571000822136] [sogo1] [card] Preparation completed (last message in the package)
[2011-08-12 19:59:45,881] [funambol.engine.strategy] [INFO] [B49CE57C57ECF1181CD762BD8960E7F0] [127.0.0.1] [fac-012571000822136] [sogo1] [card] Synchronizing...
[2011-08-12 19:59:45,882] [funambol.handler] [INFO] [B49CE57C57ECF1181CD762BD8960E7F0] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG3_RECEIVED
[2011-08-12 19:59:45,883] [funambol.transport.http] [INFO] [B49CE57C57ECF1181CD762BD8960E7F0] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request processed.
[2011-08-12 19:59:46,310] [funambol.transport.http] [INFO] [B49CE57C57ECF1181CD762BD8960E7F0] [127.0.0.1] [fac-012571000822136] [sogo1] [] Handling incoming request
[2011-08-12 19:59:46,310] [funambol.transport.http] [INFO] [B49CE57C57ECF1181CD762BD8960E7F0] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 19:59:46,310] [funambol.transport.http] [INFO] [B49CE57C57ECF1181CD762BD8960E7F0] [127.0.0.1] [fac-012571000822136] [sogo1] [] Requested sessionId: B49CE57C57ECF1181CD762BD8960E7F0
[2011-08-12 19:59:46,313] [funambol.handler] [INFO] [B49CE57C57ECF1181CD762BD8960E7F0] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG1_RECEIVING
[2011-08-12 19:59:46,315] [funambol.transport.http] [INFO] [B49CE57C57ECF1181CD762BD8960E7F0] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request processed.
[2011-08-12 19:59:46,744] [funambol.transport.http] [INFO] [B49CE57C57ECF1181CD762BD8960E7F0] [127.0.0.1] [fac-012571000822136] [sogo1] [] Handling incoming request
[2011-08-12 19:59:46,744] [funambol.transport.http] [INFO] [B49CE57C57ECF1181CD762BD8960E7F0] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 19:59:46,745] [funambol.transport.http] [INFO] [B49CE57C57ECF1181CD762BD8960E7F0] [127.0.0.1] [fac-012571000822136] [sogo1] [] Requested sessionId: B49CE57C57ECF1181CD762BD8960E7F0
[2011-08-12 19:59:46,747] [funambol.handler] [INFO] [B49CE57C57ECF1181CD762BD8960E7F0] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG3_RECEIVING
[2011-08-12 19:59:46,747] [funambol.handler] [INFO] [B49CE57C57ECF1181CD762BD8960E7F0] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_END
[2011-08-12 19:59:46,749] [funambol.handler] [INFO] [B49CE57C57ECF1181CD762BD8960E7F0] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_ENDED
[2011-08-12 19:59:46,749] [funambol.handler] [INFO] [B49CE57C57ECF1181CD762BD8960E7F0] [127.0.0.1] [fac-012571000822136] [sogo1] [] Synchronization completed
[2011-08-12 19:59:46,753] [funambol.transport.http] [INFO] [B49CE57C57ECF1181CD762BD8960E7F0] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request processed.
[2011-08-12 19:59:46,754] [funambol.transport.http] [INFO] [B49CE57C57ECF1181CD762BD8960E7F0] [127.0.0.1] [fac-012571000822136] [sogo1] [] Closing session B49CE57C57ECF1181CD762BD8960E7F0
[2011-08-12 19:59:48,184] [funambol.transport.http] [INFO] [7606AC42824438AF56C80BA36CB1AF9E] [127.0.0.1] [] [] [] Handling incoming request
[2011-08-12 19:59:48,184] [funambol.transport.http] [INFO] [7606AC42824438AF56C80BA36CB1AF9E] [127.0.0.1] [] [] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 19:59:48,184] [funambol.transport.http] [INFO] [7606AC42824438AF56C80BA36CB1AF9E] [127.0.0.1] [] [] [] Requested sessionId: null
[2011-08-12 19:59:48,217] [funambol.handler] [INFO] [7606AC42824438AF56C80BA36CB1AF9E] [127.0.0.1] [fac-012571000822136] [sogo1] [] sogo1/fac-012571000822136 logged in.
[2011-08-12 19:59:48,232] [funambol.handler] [INFO] [7606AC42824438AF56C80BA36CB1AF9E] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG1_RECEIVING
[2011-08-12 19:59:48,237] [funambol.handler] [INFO] [7606AC42824438AF56C80BA36CB1AF9E] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG3_RECEIVING
[2011-08-12 19:59:48,239] [funambol.transport.http] [INFO] [7606AC42824438AF56C80BA36CB1AF9E] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request processed.
[2011-08-12 19:59:49,762] [funambol.transport.http] [INFO] [7606AC42824438AF56C80BA36CB1AF9E] [127.0.0.1] [fac-012571000822136] [sogo1] [] Handling incoming request
[2011-08-12 19:59:49,762] [funambol.transport.http] [INFO] [7606AC42824438AF56C80BA36CB1AF9E] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 19:59:49,762] [funambol.transport.http] [INFO] [7606AC42824438AF56C80BA36CB1AF9E] [127.0.0.1] [fac-012571000822136] [sogo1] [] Requested sessionId: 7606AC42824438AF56C80BA36CB1AF9E
[2011-08-12 19:59:49,770] [funambol.engine] [INFO] [7606AC42824438AF56C80BA36CB1AF9E] [127.0.0.1] [fac-012571000822136] [sogo1] [] Starting synchronization ...
[2011-08-12 19:59:49,770] [funambol.engine.strategy] [INFO] [7606AC42824438AF56C80BA36CB1AF9E] [127.0.0.1] [fac-012571000822136] [sogo1] [event] Preparing fast synchronization since 2011-08-12 19:52:37.977
[2011-08-12 19:59:49,959] [funambol.engine.strategy] [INFO] [7606AC42824438AF56C80BA36CB1AF9E] [127.0.0.1] [fac-012571000822136] [sogo1] [event] Preparation completed (last message in the package)
[2011-08-12 19:59:49,959] [funambol.engine.strategy] [INFO] [7606AC42824438AF56C80BA36CB1AF9E] [127.0.0.1] [fac-012571000822136] [sogo1] [event] Synchronizing...
[2011-08-12 19:59:49,999] [funambol.handler] [INFO] [7606AC42824438AF56C80BA36CB1AF9E] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG3_RECEIVED
[2011-08-12 19:59:50,001] [funambol.transport.http] [INFO] [7606AC42824438AF56C80BA36CB1AF9E] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request processed.
[2011-08-12 19:59:51,746] [funambol.transport.http] [INFO] [7606AC42824438AF56C80BA36CB1AF9E] [127.0.0.1] [fac-012571000822136] [sogo1] [] Handling incoming request
[2011-08-12 19:59:51,748] [funambol.transport.http] [INFO] [7606AC42824438AF56C80BA36CB1AF9E] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 19:59:51,748] [funambol.transport.http] [INFO] [7606AC42824438AF56C80BA36CB1AF9E] [127.0.0.1] [fac-012571000822136] [sogo1] [] Requested sessionId: 7606AC42824438AF56C80BA36CB1AF9E
[2011-08-12 19:59:51,764] [funambol.handler] [INFO] [7606AC42824438AF56C80BA36CB1AF9E] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG1_RECEIVING
[2011-08-12 19:59:51,767] [funambol.transport.http] [INFO] [7606AC42824438AF56C80BA36CB1AF9E] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request processed.
[2011-08-12 19:59:52,295] [funambol.transport.http] [INFO] [7606AC42824438AF56C80BA36CB1AF9E] [127.0.0.1] [fac-012571000822136] [sogo1] [] Handling incoming request
[2011-08-12 19:59:52,295] [funambol.transport.http] [INFO] [7606AC42824438AF56C80BA36CB1AF9E] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 19:59:52,296] [funambol.transport.http] [INFO] [7606AC42824438AF56C80BA36CB1AF9E] [127.0.0.1] [fac-012571000822136] [sogo1] [] Requested sessionId: 7606AC42824438AF56C80BA36CB1AF9E
[2011-08-12 19:59:52,299] [funambol.handler] [INFO] [7606AC42824438AF56C80BA36CB1AF9E] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG3_RECEIVING
[2011-08-12 19:59:52,299] [funambol.handler] [INFO] [7606AC42824438AF56C80BA36CB1AF9E] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_END
[2011-08-12 19:59:52,300] [funambol.handler] [INFO] [7606AC42824438AF56C80BA36CB1AF9E] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_ENDED
[2011-08-12 19:59:52,300] [funambol.handler] [INFO] [7606AC42824438AF56C80BA36CB1AF9E] [127.0.0.1] [fac-012571000822136] [sogo1] [] Synchronization completed
[2011-08-12 19:59:52,303] [funambol.transport.http] [INFO] [7606AC42824438AF56C80BA36CB1AF9E] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request processed.
[2011-08-12 19:59:52,303] [funambol.transport.http] [INFO] [7606AC42824438AF56C80BA36CB1AF9E] [127.0.0.1] [fac-012571000822136] [sogo1] [] Closing session 7606AC42824438AF56C80BA36CB1AF9E
[2011-08-12 20:06:53,188] [funambol.transport.http] [INFO] [23CF2F1CF09106A233D1EA3FAD2344C0] [127.0.0.1] [fac-012571000822136] [sogo1] [] Closing session 23CF2F1CF09106A233D1EA3FAD2344C0
[2011-08-12 20:06:53,195] [funambol.handler] [INFO] [23CF2F1CF09106A233D1EA3FAD2344C0] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_ERROR [Session aborted with status code: SESSION_EXPIRED]
[2011-08-12 20:06:53,195] [funambol.handler] [INFO] [23CF2F1CF09106A233D1EA3FAD2344C0] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_ENDED
[2011-08-12 20:06:53,195] [funambol.handler] [INFO] [23CF2F1CF09106A233D1EA3FAD2344C0] [127.0.0.1] [fac-012571000822136] [sogo1] [] Synchronization completed with error(s)
[2011-08-12 20:07:13,581] [funambol.transport.http] [INFO] [B00742AA7F413834EAFFF25E4CC07524] [127.0.0.1] [] [] [] Handling incoming request
[2011-08-12 20:07:13,581] [funambol.transport.http] [INFO] [B00742AA7F413834EAFFF25E4CC07524] [127.0.0.1] [] [] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 20:07:13,581] [funambol.transport.http] [INFO] [B00742AA7F413834EAFFF25E4CC07524] [127.0.0.1] [] [] [] Requested sessionId: null
[2011-08-12 20:07:13,624] [funambol.handler] [INFO] [B00742AA7F413834EAFFF25E4CC07524] [127.0.0.1] [fac-012571000822136] [sogo1] [] sogo1/fac-012571000822136 logged in.
[2011-08-12 20:07:13,641] [funambol.handler] [INFO] [B00742AA7F413834EAFFF25E4CC07524] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG1_RECEIVING
[2011-08-12 20:07:13,644] [funambol.handler] [INFO] [B00742AA7F413834EAFFF25E4CC07524] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG3_RECEIVING
[2011-08-12 20:07:13,647] [funambol.transport.http] [INFO] [B00742AA7F413834EAFFF25E4CC07524] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request processed.
[2011-08-12 20:07:14,176] [funambol.transport.http] [INFO] [B00742AA7F413834EAFFF25E4CC07524] [127.0.0.1] [fac-012571000822136] [sogo1] [] Handling incoming request
[2011-08-12 20:07:14,176] [funambol.transport.http] [INFO] [B00742AA7F413834EAFFF25E4CC07524] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 20:07:14,176] [funambol.transport.http] [INFO] [B00742AA7F413834EAFFF25E4CC07524] [127.0.0.1] [fac-012571000822136] [sogo1] [] Requested sessionId: B00742AA7F413834EAFFF25E4CC07524
[2011-08-12 20:07:14,185] [funambol.engine] [INFO] [B00742AA7F413834EAFFF25E4CC07524] [127.0.0.1] [fac-012571000822136] [sogo1] [] Starting synchronization ...
[2011-08-12 20:07:14,185] [funambol.engine.strategy] [INFO] [B00742AA7F413834EAFFF25E4CC07524] [127.0.0.1] [fac-012571000822136] [sogo1] [card] Preparing fast synchronization since 2011-08-12 19:59:45.257
[2011-08-12 20:07:14,186] [funambol.engine.strategy] [INFO] [B00742AA7F413834EAFFF25E4CC07524] [127.0.0.1] [fac-012571000822136] [sogo1] [card] Preparation completed (last message in the package)
[2011-08-12 20:07:14,186] [funambol.engine.strategy] [INFO] [B00742AA7F413834EAFFF25E4CC07524] [127.0.0.1] [fac-012571000822136] [sogo1] [card] Synchronizing...
[2011-08-12 20:07:14,187] [funambol.handler] [INFO] [B00742AA7F413834EAFFF25E4CC07524] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG3_RECEIVED
[2011-08-12 20:07:14,188] [funambol.transport.http] [INFO] [B00742AA7F413834EAFFF25E4CC07524] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request processed.
[2011-08-12 20:07:14,644] [funambol.transport.http] [INFO] [B00742AA7F413834EAFFF25E4CC07524] [127.0.0.1] [fac-012571000822136] [sogo1] [] Handling incoming request
[2011-08-12 20:07:14,644] [funambol.transport.http] [INFO] [B00742AA7F413834EAFFF25E4CC07524] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 20:07:14,644] [funambol.transport.http] [INFO] [B00742AA7F413834EAFFF25E4CC07524] [127.0.0.1] [fac-012571000822136] [sogo1] [] Requested sessionId: B00742AA7F413834EAFFF25E4CC07524
[2011-08-12 20:07:14,647] [funambol.handler] [INFO] [B00742AA7F413834EAFFF25E4CC07524] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG1_RECEIVING
[2011-08-12 20:07:14,650] [funambol.transport.http] [INFO] [B00742AA7F413834EAFFF25E4CC07524] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request processed.
[2011-08-12 20:07:15,277] [funambol.transport.http] [INFO] [B00742AA7F413834EAFFF25E4CC07524] [127.0.0.1] [fac-012571000822136] [sogo1] [] Handling incoming request
[2011-08-12 20:07:15,277] [funambol.transport.http] [INFO] [B00742AA7F413834EAFFF25E4CC07524] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 20:07:15,277] [funambol.transport.http] [INFO] [B00742AA7F413834EAFFF25E4CC07524] [127.0.0.1] [fac-012571000822136] [sogo1] [] Requested sessionId: B00742AA7F413834EAFFF25E4CC07524
[2011-08-12 20:07:15,280] [funambol.handler] [INFO] [B00742AA7F413834EAFFF25E4CC07524] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG3_RECEIVING
[2011-08-12 20:07:15,280] [funambol.handler] [INFO] [B00742AA7F413834EAFFF25E4CC07524] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_END
[2011-08-12 20:07:15,282] [funambol.handler] [INFO] [B00742AA7F413834EAFFF25E4CC07524] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_ENDED
[2011-08-12 20:07:15,282] [funambol.handler] [INFO] [B00742AA7F413834EAFFF25E4CC07524] [127.0.0.1] [fac-012571000822136] [sogo1] [] Synchronization completed
[2011-08-12 20:07:15,284] [funambol.transport.http] [INFO] [B00742AA7F413834EAFFF25E4CC07524] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request processed.
[2011-08-12 20:07:15,284] [funambol.transport.http] [INFO] [B00742AA7F413834EAFFF25E4CC07524] [127.0.0.1] [fac-012571000822136] [sogo1] [] Closing session B00742AA7F413834EAFFF25E4CC07524
[2011-08-12 20:08:39,729] [funambol.transport.http] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [] [] [] Handling incoming request
[2011-08-12 20:08:39,730] [funambol.transport.http] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [] [] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 20:08:39,730] [funambol.transport.http] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [] [] [] Requested sessionId: null
[2011-08-12 20:08:39,809] [funambol.handler] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [] sogo1/fac-012571000822136 logged in.
[2011-08-12 20:08:39,822] [funambol.handler] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG1_RECEIVING
[2011-08-12 20:08:39,824] [funambol.sogo] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [] Source URI: sogo-cal
[2011-08-12 20:08:39,825] [funambol.sogo] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [] Done! Internal item type: 2 (text/x-vevent)
[2011-08-12 20:08:39,826] [funambol.handler] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG3_RECEIVING
[2011-08-12 20:08:39,830] [funambol.transport.http] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request processed.
[2011-08-12 20:08:40,777] [funambol.transport.http] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [] Handling incoming request
[2011-08-12 20:08:40,778] [funambol.transport.http] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 20:08:40,778] [funambol.transport.http] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [] Requested sessionId: 1E0D37D9DE91DBD10056410C88F7A5DA
[2011-08-12 20:08:40,783] [funambol.engine] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [] Starting synchronization ...
[2011-08-12 20:08:40,789] [funambol.sogo] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] In beginSync()...
[2011-08-12 20:08:40,794] [funambol.sogo] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Loading the JDBC driver for URL: jdbc:postgresql://localhost/sogo
[2011-08-12 20:08:40,796] [funambol.sogo] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Context's user ID: sogo1
[2011-08-12 20:08:40,797] [funambol.sogo] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Context sync mode: 201
[2011-08-12 20:08:40,797] [funambol.sogo] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Context conflict resolution: 1
[2011-08-12 20:08:40,797] [funambol.sogo] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Context query: null
[2011-08-12 20:08:40,809] [funambol.sogo] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Device's charset: UTF-8
[2011-08-12 20:08:40,809] [funambol.sogo] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Device's timezone: null
[2011-08-12 20:08:40,809] [funambol.engine.strategy] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Preparing slow synchronization
[2011-08-12 20:08:40,809] [funambol.sogo] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] getAllSyncItemKeys()
[2011-08-12 20:08:40,812] [funambol.sogo] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] in aAlarmCleanup(), s on input is:BEGIN:VCALENDAR
VERSION:1.0
TZ:-0700
DAYLIGHT:FALSE
BEGIN:VEVENT
SUMMARY:simple Test
DESCRIPTION:
LOCATION:
CLASS:PUBLIC
DTSTART:20110815T235543Z
DTEND:20110816T005543Z
AALARM:20110815T234543Z;;0;
X-FUNAMBOL-ALLDAY:0
END:VEVENT
END:VCALENDAR

[2011-08-12 20:08:40,812] [funambol.sogo] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] found part[1]=20110815T234543Z
[2011-08-12 20:08:40,813] [funambol.sogo] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] found an alarmdate:20110815T234543Z
[2011-08-12 20:08:40,813] [funambol.sogo] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] after aAlarmCleanup(), ret value is:BEGIN:VCALENDAR
VERSION:1.0
TZ:-0700
DAYLIGHT:FALSE
BEGIN:VEVENT
SUMMARY:simple Test
DESCRIPTION:
LOCATION:
CLASS:PUBLIC
DTSTART:20110815T235543Z
DTEND:20110816T005543Z
AALARM:20110815T234543Z
X-FUNAMBOL-ALLDAY:0
END:VEVENT
END:VCALENDAR

[2011-08-12 20:08:40,834] [funambol.sogo] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] DATE 2: 1313452543 tz: sun.util.calendar.ZoneInfo[id="GMT",offset=0,dstSavings=0,useDaylight=false,transitions=0,lastRule=null]
[2011-08-12 20:08:40,835] [funambol.sogo] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] in aAlarmCleanup(), s on input is:BEGIN:VCALENDAR
VERSION:1.0
TZ:-0700
DAYLIGHT:FALSE
BEGIN:VEVENT
SUMMARY:Repeat event
DESCRIPTION:
LOCATION:
CLASS:PUBLIC
DTSTART:20110816T230000Z
DTEND:20110817T000000Z
AALARM:20110816T225000Z;;0;
RRULE:W1 TU #0
X-FUNAMBOL-ALLDAY:0
END:VEVENT
END:VCALENDAR

[2011-08-12 20:08:40,835] [funambol.sogo] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] found part[1]=20110816T225000Z
[2011-08-12 20:08:40,835] [funambol.sogo] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] found an alarmdate:20110816T225000Z
[2011-08-12 20:08:40,835] [funambol.sogo] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] after aAlarmCleanup(), ret value is:BEGIN:VCALENDAR
VERSION:1.0
TZ:-0700
DAYLIGHT:FALSE
BEGIN:VEVENT
SUMMARY:Repeat event
DESCRIPTION:
LOCATION:
CLASS:PUBLIC
DTSTART:20110816T230000Z
DTEND:20110817T000000Z
AALARM:20110816T225000Z
RRULE:W1 TU #0
X-FUNAMBOL-ALLDAY:0
END:VEVENT
END:VCALENDAR

[2011-08-12 20:08:40,840] [funambol.sogo] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] DATE 2: 1313535600 tz: sun.util.calendar.ZoneInfo[id="GMT",offset=0,dstSavings=0,useDaylight=false,transitions=0,lastRule=null]
[2011-08-12 20:08:40,840] [funambol.sogo] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] in aAlarmCleanup(), s on input is:BEGIN:VCALENDAR
VERSION:1.0
TZ:-0700
DAYLIGHT:FALSE
BEGIN:VEVENT
SUMMARY:Long Event
DESCRIPTION:
LOCATION:
CLASS:PUBLIC
DTSTART:20110818T010000Z
DTEND:20110818T230000Z
AALARM:20110818T005000Z;;0;
X-FUNAMBOL-ALLDAY:0
END:VEVENT
END:VCALENDAR

[2011-08-12 20:08:40,840] [funambol.sogo] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] found part[1]=20110818T005000Z
[2011-08-12 20:08:40,840] [funambol.sogo] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] found an alarmdate:20110818T005000Z
[2011-08-12 20:08:40,840] [funambol.sogo] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] after aAlarmCleanup(), ret value is:BEGIN:VCALENDAR
VERSION:1.0
TZ:-0700
DAYLIGHT:FALSE
BEGIN:VEVENT
SUMMARY:Long Event
DESCRIPTION:
LOCATION:
CLASS:PUBLIC
DTSTART:20110818T010000Z
DTEND:20110818T230000Z
AALARM:20110818T005000Z
X-FUNAMBOL-ALLDAY:0
END:VEVENT
END:VCALENDAR

[2011-08-12 20:08:40,847] [funambol.sogo] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] DATE 2: 1313629200 tz: sun.util.calendar.ZoneInfo[id="GMT",offset=0,dstSavings=0,useDaylight=false,transitions=0,lastRule=null]
[2011-08-12 20:08:40,847] [funambol.engine.strategy] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Preparation completed (last message in the package)
[2011-08-12 20:08:40,847] [funambol.engine.strategy] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Synchronizing...
[2011-08-12 20:08:40,847] [funambol.sogo] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] addSyncItem(44)
[2011-08-12 20:08:40,847] [funambol.sogo] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] addSyncItem - type: text/x-vcalendar
[2011-08-12 20:08:40,848] [funambol.sogo] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] getSyncItemFromId( { keyValue: 44 } )
[2011-08-12 20:08:40,849] [funambol.sogo] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] addSyncItem - content: BEGIN:VCALENDAR
VERSION:1.0
TZ:-0700
DAYLIGHT:FALSE
BEGIN:VEVENT
SUMMARY:simple Test
DESCRIPTION:
LOCATION:
CLASS:PUBLIC
DTSTART:20110815T235543Z
DTEND:20110816T005543Z
AALARM:20110815T234543Z;;0;
X-FUNAMBOL-ALLDAY:0
END:VEVENT
END:VCALENDAR

[2011-08-12 20:08:40,849] [funambol.sogo] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Preferred item type: 1
[2011-08-12 20:08:40,851] [funambol.sogo] [WARN] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Exception occured in getUserTimeZone() - trying to read from JSON string: java.lang.NullPointerException
java.lang.NullPointerException
	at java.io.StringReader.<init>(Unknown Source)
	at org.json.simple.JSONValue.parse(JSONValue.java:33)
	at ca.inverse.sogo.engine.source.SOGoUtilities.getUserTimeZone(SOGoUtilities.java:654)
	at ca.inverse.sogo.engine.source.SOGoEventUtilities.addvEventSyncItem(SOGoEventUtilities.java:65)
	at ca.inverse.sogo.engine.source.SOGoSyncSource.addSyncItem(SOGoSyncSource.java:1000)
	at com.funambol.server.engine.Sync4jStrategy.execNewOperation(Sync4jStrategy.java:2634)
	at com.funambol.server.engine.Sync4jStrategy.execSyncOperation(Sync4jStrategy.java:1456)
	at com.funambol.server.engine.Sync4jStrategy.sync(Sync4jStrategy.java:990)
	at com.funambol.server.engine.Sync4jEngine.sync(Sync4jEngine.java:692)
	at com.funambol.server.session.SyncSessionHandler.processModifications(SyncSessionHandler.java:1655)
	at com.funambol.server.session.SyncSessionHandler.processSyncMessage(SyncSessionHandler.java:1499)
	at com.funambol.server.session.SyncSessionHandler.processInitSyncMapMessage(SyncSessionHandler.java:960)
	at com.funambol.server.session.SyncSessionHandler.processMessage(SyncSessionHandler.java:527)
	at com.funambol.server.engine.SyncAdapter.processInputMessage(SyncAdapter.java:533)
	at com.funambol.server.engine.SyncAdapter.processWBXMLMessage(SyncAdapter.java:369)
	at com.funambol.transport.http.server.LocalSyncHolder.processWBXMLMessage(LocalSyncHolder.java:118)
	at com.funambol.transport.http.server.Sync4jServlet.doPost(Sync4jServlet.java:398)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:710)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:803)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
	at com.funambol.transport.http.server.LogContextFilter.doFilter(LogContextFilter.java:137)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
	at com.funambol.transport.http.server.SyncResponseTimeFilter.doFilter(SyncResponseTimeFilter.java:153)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:233)
	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:175)
	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:128)
	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:286)
	at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:844)
	at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:583)
	at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:447)
	at java.lang.Thread.run(Unknown Source)
[2011-08-12 20:08:40,853] [funambol.handler] [ERROR] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [] Error processing message
java.lang.NoClassDefFoundError: org/apache/commons/configuration/plist/PropertyListConfiguration
	at ca.inverse.sogo.engine.source.SOGoUtilities.getUserTimeZone(SOGoUtilities.java:668)
	at ca.inverse.sogo.engine.source.SOGoEventUtilities.addvEventSyncItem(SOGoEventUtilities.java:65)
	at ca.inverse.sogo.engine.source.SOGoSyncSource.addSyncItem(SOGoSyncSource.java:1000)
	at com.funambol.server.engine.Sync4jStrategy.execNewOperation(Sync4jStrategy.java:2634)
	at com.funambol.server.engine.Sync4jStrategy.execSyncOperation(Sync4jStrategy.java:1456)
	at com.funambol.server.engine.Sync4jStrategy.sync(Sync4jStrategy.java:990)
	at com.funambol.server.engine.Sync4jEngine.sync(Sync4jEngine.java:692)
	at com.funambol.server.session.SyncSessionHandler.processModifications(SyncSessionHandler.java:1655)
	at com.funambol.server.session.SyncSessionHandler.processSyncMessage(SyncSessionHandler.java:1499)
	at com.funambol.server.session.SyncSessionHandler.processInitSyncMapMessage(SyncSessionHandler.java:960)
	at com.funambol.server.session.SyncSessionHandler.processMessage(SyncSessionHandler.java:527)
	at com.funambol.server.engine.SyncAdapter.processInputMessage(SyncAdapter.java:533)
	at com.funambol.server.engine.SyncAdapter.processWBXMLMessage(SyncAdapter.java:369)
	at com.funambol.transport.http.server.LocalSyncHolder.processWBXMLMessage(LocalSyncHolder.java:118)
	at com.funambol.transport.http.server.Sync4jServlet.doPost(Sync4jServlet.java:398)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:710)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:803)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
	at com.funambol.transport.http.server.LogContextFilter.doFilter(LogContextFilter.java:137)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
	at com.funambol.transport.http.server.SyncResponseTimeFilter.doFilter(SyncResponseTimeFilter.java:153)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:233)
	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:175)
	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:128)
	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:286)
	at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:844)
	at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:583)
	at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:447)
	at java.lang.Thread.run(Unknown Source)
Caused by: java.lang.ClassNotFoundException: org.apache.commons.configuration.plist.PropertyListConfiguration
	at org.apache.catalina.loader.WebappClassLoader.loadClass(WebappClassLoader.java:1360)
	at org.apache.catalina.loader.WebappClassLoader.loadClass(WebappClassLoader.java:1206)
	at java.lang.ClassLoader.loadClassInternal(Unknown Source)
	... 35 more
[2011-08-12 20:08:40,853] [funambol.handler] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_ERROR [Error processing message]
[2011-08-12 20:08:40,854] [funambol.handler] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_ERROR [com.funambol.framework.server.error.ServerFailureException: 511 SERVER FAILURE - Server error]
[2011-08-12 20:08:40,855] [funambol.transport.http] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request processed.
[2011-08-12 20:08:40,856] [funambol.transport.http] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [] Closing session 1E0D37D9DE91DBD10056410C88F7A5DA
[2011-08-12 20:08:40,856] [funambol.engine.source] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] sogo-cal: 0 new items added, 0 existing items updated, 0 items deleted.
[2011-08-12 20:08:40,856] [funambol.sogo] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] In endSync()...
[2011-08-12 20:08:40,857] [funambol.sogo] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Closed the database connection.
[2011-08-12 20:08:40,857] [funambol.handler] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_ERROR [Session aborted with status code: PROCESSING ERROR]
[2011-08-12 20:08:40,857] [funambol.handler] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_ENDED
[2011-08-12 20:08:40,857] [funambol.handler] [INFO] [1E0D37D9DE91DBD10056410C88F7A5DA] [127.0.0.1] [fac-012571000822136] [sogo1] [] Synchronization completed with error(s)
[2011-08-12 20:08:50,381] [funambol.transport.http] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [] [] [] Handling incoming request
[2011-08-12 20:08:50,382] [funambol.transport.http] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [] [] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 20:08:50,382] [funambol.transport.http] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [] [] [] Requested sessionId: null
[2011-08-12 20:08:50,412] [funambol.handler] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [] sogo1/fac-012571000822136 logged in.
[2011-08-12 20:08:50,428] [funambol.handler] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG1_RECEIVING
[2011-08-12 20:08:50,432] [funambol.sogo] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [] Source URI: sogo-cal
[2011-08-12 20:08:50,432] [funambol.sogo] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [] Done! Internal item type: 2 (text/x-vevent)
[2011-08-12 20:08:50,433] [funambol.handler] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG3_RECEIVING
[2011-08-12 20:08:50,434] [funambol.transport.http] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request processed.
[2011-08-12 20:08:51,145] [funambol.transport.http] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [] Handling incoming request
[2011-08-12 20:08:51,145] [funambol.transport.http] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 20:08:51,146] [funambol.transport.http] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [] Requested sessionId: 0664AF6D5526762CE4723EA4C54DBBF9
[2011-08-12 20:08:51,152] [funambol.engine] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [] Starting synchronization ...
[2011-08-12 20:08:51,152] [funambol.sogo] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] In beginSync()...
[2011-08-12 20:08:51,152] [funambol.sogo] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Loading the JDBC driver for URL: jdbc:postgresql://localhost/sogo
[2011-08-12 20:08:51,153] [funambol.sogo] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Context's user ID: sogo1
[2011-08-12 20:08:51,153] [funambol.sogo] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Context sync mode: 201
[2011-08-12 20:08:51,154] [funambol.sogo] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Context conflict resolution: 1
[2011-08-12 20:08:51,154] [funambol.sogo] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Context query: null
[2011-08-12 20:08:51,158] [funambol.sogo] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Device's charset: UTF-8
[2011-08-12 20:08:51,158] [funambol.sogo] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Device's timezone: null
[2011-08-12 20:08:51,158] [funambol.engine.strategy] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Preparing slow synchronization
[2011-08-12 20:08:51,158] [funambol.sogo] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] getAllSyncItemKeys()
[2011-08-12 20:08:51,159] [funambol.sogo] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] in aAlarmCleanup(), s on input is:BEGIN:VCALENDAR
VERSION:1.0
TZ:-0700
DAYLIGHT:FALSE
BEGIN:VEVENT
SUMMARY:simple Test
DESCRIPTION:
LOCATION:
CLASS:PUBLIC
DTSTART:20110815T235543Z
DTEND:20110816T005543Z
AALARM:20110815T234543Z;;0;
X-FUNAMBOL-ALLDAY:0
END:VEVENT
END:VCALENDAR

[2011-08-12 20:08:51,159] [funambol.sogo] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] found part[1]=20110815T234543Z
[2011-08-12 20:08:51,159] [funambol.sogo] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] found an alarmdate:20110815T234543Z
[2011-08-12 20:08:51,159] [funambol.sogo] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] after aAlarmCleanup(), ret value is:BEGIN:VCALENDAR
VERSION:1.0
TZ:-0700
DAYLIGHT:FALSE
BEGIN:VEVENT
SUMMARY:simple Test
DESCRIPTION:
LOCATION:
CLASS:PUBLIC
DTSTART:20110815T235543Z
DTEND:20110816T005543Z
AALARM:20110815T234543Z
X-FUNAMBOL-ALLDAY:0
END:VEVENT
END:VCALENDAR

[2011-08-12 20:08:51,162] [funambol.sogo] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] DATE 2: 1313452543 tz: sun.util.calendar.ZoneInfo[id="GMT",offset=0,dstSavings=0,useDaylight=false,transitions=0,lastRule=null]
[2011-08-12 20:08:51,162] [funambol.sogo] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] in aAlarmCleanup(), s on input is:BEGIN:VCALENDAR
VERSION:1.0
TZ:-0700
DAYLIGHT:FALSE
BEGIN:VEVENT
SUMMARY:Repeat event
DESCRIPTION:
LOCATION:
CLASS:PUBLIC
DTSTART:20110816T230000Z
DTEND:20110817T000000Z
AALARM:20110816T225000Z;;0;
RRULE:W1 TU #0
X-FUNAMBOL-ALLDAY:0
END:VEVENT
END:VCALENDAR

[2011-08-12 20:08:51,162] [funambol.sogo] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] found part[1]=20110816T225000Z
[2011-08-12 20:08:51,162] [funambol.sogo] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] found an alarmdate:20110816T225000Z
[2011-08-12 20:08:51,163] [funambol.sogo] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] after aAlarmCleanup(), ret value is:BEGIN:VCALENDAR
VERSION:1.0
TZ:-0700
DAYLIGHT:FALSE
BEGIN:VEVENT
SUMMARY:Repeat event
DESCRIPTION:
LOCATION:
CLASS:PUBLIC
DTSTART:20110816T230000Z
DTEND:20110817T000000Z
AALARM:20110816T225000Z
RRULE:W1 TU #0
X-FUNAMBOL-ALLDAY:0
END:VEVENT
END:VCALENDAR

[2011-08-12 20:08:51,172] [funambol.sogo] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] DATE 2: 1313535600 tz: sun.util.calendar.ZoneInfo[id="GMT",offset=0,dstSavings=0,useDaylight=false,transitions=0,lastRule=null]
[2011-08-12 20:08:51,172] [funambol.sogo] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] in aAlarmCleanup(), s on input is:BEGIN:VCALENDAR
VERSION:1.0
TZ:-0700
DAYLIGHT:FALSE
BEGIN:VEVENT
SUMMARY:Long Event
DESCRIPTION:
LOCATION:
CLASS:PUBLIC
DTSTART:20110818T010000Z
DTEND:20110818T230000Z
AALARM:20110818T005000Z;;0;
X-FUNAMBOL-ALLDAY:0
END:VEVENT
END:VCALENDAR

[2011-08-12 20:08:51,172] [funambol.sogo] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] found part[1]=20110818T005000Z
[2011-08-12 20:08:51,172] [funambol.sogo] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] found an alarmdate:20110818T005000Z
[2011-08-12 20:08:51,172] [funambol.sogo] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] after aAlarmCleanup(), ret value is:BEGIN:VCALENDAR
VERSION:1.0
TZ:-0700
DAYLIGHT:FALSE
BEGIN:VEVENT
SUMMARY:Long Event
DESCRIPTION:
LOCATION:
CLASS:PUBLIC
DTSTART:20110818T010000Z
DTEND:20110818T230000Z
AALARM:20110818T005000Z
X-FUNAMBOL-ALLDAY:0
END:VEVENT
END:VCALENDAR

[2011-08-12 20:08:51,175] [funambol.sogo] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] DATE 2: 1313629200 tz: sun.util.calendar.ZoneInfo[id="GMT",offset=0,dstSavings=0,useDaylight=false,transitions=0,lastRule=null]
[2011-08-12 20:08:51,177] [funambol.engine.strategy] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Preparation completed (last message in the package)
[2011-08-12 20:08:51,177] [funambol.engine.strategy] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Synchronizing...
[2011-08-12 20:08:51,177] [funambol.sogo] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] addSyncItem(44)
[2011-08-12 20:08:51,177] [funambol.sogo] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] addSyncItem - type: text/x-vcalendar
[2011-08-12 20:08:51,177] [funambol.sogo] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] getSyncItemFromId( { keyValue: 44 } )
[2011-08-12 20:08:51,178] [funambol.sogo] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] addSyncItem - content: BEGIN:VCALENDAR
VERSION:1.0
TZ:-0700
DAYLIGHT:FALSE
BEGIN:VEVENT
SUMMARY:simple Test
DESCRIPTION:
LOCATION:
CLASS:PUBLIC
DTSTART:20110815T235543Z
DTEND:20110816T005543Z
AALARM:20110815T234543Z;;0;
X-FUNAMBOL-ALLDAY:0
END:VEVENT
END:VCALENDAR

[2011-08-12 20:08:51,178] [funambol.sogo] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Preferred item type: 1
[2011-08-12 20:08:51,178] [funambol.sogo] [WARN] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Exception occured in getUserTimeZone() - trying to read from JSON string: java.lang.NullPointerException
java.lang.NullPointerException
	at java.io.StringReader.<init>(Unknown Source)
	at org.json.simple.JSONValue.parse(JSONValue.java:33)
	at ca.inverse.sogo.engine.source.SOGoUtilities.getUserTimeZone(SOGoUtilities.java:654)
	at ca.inverse.sogo.engine.source.SOGoEventUtilities.addvEventSyncItem(SOGoEventUtilities.java:65)
	at ca.inverse.sogo.engine.source.SOGoSyncSource.addSyncItem(SOGoSyncSource.java:1000)
	at com.funambol.server.engine.Sync4jStrategy.execNewOperation(Sync4jStrategy.java:2634)
	at com.funambol.server.engine.Sync4jStrategy.execSyncOperation(Sync4jStrategy.java:1456)
	at com.funambol.server.engine.Sync4jStrategy.sync(Sync4jStrategy.java:990)
	at com.funambol.server.engine.Sync4jEngine.sync(Sync4jEngine.java:692)
	at com.funambol.server.session.SyncSessionHandler.processModifications(SyncSessionHandler.java:1655)
	at com.funambol.server.session.SyncSessionHandler.processSyncMessage(SyncSessionHandler.java:1499)
	at com.funambol.server.session.SyncSessionHandler.processInitSyncMapMessage(SyncSessionHandler.java:960)
	at com.funambol.server.session.SyncSessionHandler.processMessage(SyncSessionHandler.java:527)
	at com.funambol.server.engine.SyncAdapter.processInputMessage(SyncAdapter.java:533)
	at com.funambol.server.engine.SyncAdapter.processWBXMLMessage(SyncAdapter.java:369)
	at com.funambol.transport.http.server.LocalSyncHolder.processWBXMLMessage(LocalSyncHolder.java:118)
	at com.funambol.transport.http.server.Sync4jServlet.doPost(Sync4jServlet.java:398)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:710)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:803)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
	at com.funambol.transport.http.server.LogContextFilter.doFilter(LogContextFilter.java:137)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
	at com.funambol.transport.http.server.SyncResponseTimeFilter.doFilter(SyncResponseTimeFilter.java:153)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:233)
	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:175)
	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:128)
	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:286)
	at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:844)
	at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:583)
	at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:447)
	at java.lang.Thread.run(Unknown Source)
[2011-08-12 20:08:51,179] [funambol.handler] [ERROR] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [] Error processing message
java.lang.NoClassDefFoundError: org/apache/commons/configuration/plist/PropertyListConfiguration
	at ca.inverse.sogo.engine.source.SOGoUtilities.getUserTimeZone(SOGoUtilities.java:668)
	at ca.inverse.sogo.engine.source.SOGoEventUtilities.addvEventSyncItem(SOGoEventUtilities.java:65)
	at ca.inverse.sogo.engine.source.SOGoSyncSource.addSyncItem(SOGoSyncSource.java:1000)
	at com.funambol.server.engine.Sync4jStrategy.execNewOperation(Sync4jStrategy.java:2634)
	at com.funambol.server.engine.Sync4jStrategy.execSyncOperation(Sync4jStrategy.java:1456)
	at com.funambol.server.engine.Sync4jStrategy.sync(Sync4jStrategy.java:990)
	at com.funambol.server.engine.Sync4jEngine.sync(Sync4jEngine.java:692)
	at com.funambol.server.session.SyncSessionHandler.processModifications(SyncSessionHandler.java:1655)
	at com.funambol.server.session.SyncSessionHandler.processSyncMessage(SyncSessionHandler.java:1499)
	at com.funambol.server.session.SyncSessionHandler.processInitSyncMapMessage(SyncSessionHandler.java:960)
	at com.funambol.server.session.SyncSessionHandler.processMessage(SyncSessionHandler.java:527)
	at com.funambol.server.engine.SyncAdapter.processInputMessage(SyncAdapter.java:533)
	at com.funambol.server.engine.SyncAdapter.processWBXMLMessage(SyncAdapter.java:369)
	at com.funambol.transport.http.server.LocalSyncHolder.processWBXMLMessage(LocalSyncHolder.java:118)
	at com.funambol.transport.http.server.Sync4jServlet.doPost(Sync4jServlet.java:398)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:710)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:803)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
	at com.funambol.transport.http.server.LogContextFilter.doFilter(LogContextFilter.java:137)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
	at com.funambol.transport.http.server.SyncResponseTimeFilter.doFilter(SyncResponseTimeFilter.java:153)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:233)
	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:175)
	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:128)
	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:286)
	at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:844)
	at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:583)
	at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:447)
	at java.lang.Thread.run(Unknown Source)
[2011-08-12 20:08:51,180] [funambol.handler] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_ERROR [Error processing message]
[2011-08-12 20:08:51,180] [funambol.handler] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_ERROR [com.funambol.framework.server.error.ServerFailureException: 511 SERVER FAILURE - Server error]
[2011-08-12 20:08:51,181] [funambol.transport.http] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request processed.
[2011-08-12 20:08:51,181] [funambol.transport.http] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [] Closing session 0664AF6D5526762CE4723EA4C54DBBF9
[2011-08-12 20:08:51,181] [funambol.engine.source] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] sogo-cal: 0 new items added, 0 existing items updated, 0 items deleted.
[2011-08-12 20:08:51,181] [funambol.sogo] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] In endSync()...
[2011-08-12 20:08:51,182] [funambol.sogo] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Closed the database connection.
[2011-08-12 20:08:51,182] [funambol.handler] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_ERROR [Session aborted with status code: PROCESSING ERROR]
[2011-08-12 20:08:51,182] [funambol.handler] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_ENDED
[2011-08-12 20:08:51,182] [funambol.handler] [INFO] [0664AF6D5526762CE4723EA4C54DBBF9] [127.0.0.1] [fac-012571000822136] [sogo1] [] Synchronization completed with error(s)
[2011-08-12 20:09:21,927] [funambol.transport.http] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [] [] [] Handling incoming request
[2011-08-12 20:09:21,927] [funambol.transport.http] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [] [] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 20:09:21,927] [funambol.transport.http] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [] [] [] Requested sessionId: null
[2011-08-12 20:09:21,962] [funambol.handler] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [] sogo1/fac-012571000822136 logged in.
[2011-08-12 20:09:21,976] [funambol.handler] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG1_RECEIVING
[2011-08-12 20:09:21,980] [funambol.sogo] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [] Source URI: sogo-cal
[2011-08-12 20:09:21,980] [funambol.sogo] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [] Done! Internal item type: 2 (text/x-vevent)
[2011-08-12 20:09:21,981] [funambol.handler] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG3_RECEIVING
[2011-08-12 20:09:21,983] [funambol.transport.http] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request processed.
[2011-08-12 20:09:23,242] [funambol.transport.http] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [] Handling incoming request
[2011-08-12 20:09:23,242] [funambol.transport.http] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 20:09:23,242] [funambol.transport.http] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [] Requested sessionId: 600E5FFC06E621317D5B34EF631FAC2F
[2011-08-12 20:09:23,248] [funambol.engine] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [] Starting synchronization ...
[2011-08-12 20:09:23,248] [funambol.sogo] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] In beginSync()...
[2011-08-12 20:09:23,248] [funambol.sogo] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Loading the JDBC driver for URL: jdbc:postgresql://localhost/sogo
[2011-08-12 20:09:23,250] [funambol.sogo] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Context's user ID: sogo1
[2011-08-12 20:09:23,250] [funambol.sogo] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Context sync mode: 203
[2011-08-12 20:09:23,250] [funambol.sogo] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Context conflict resolution: 1
[2011-08-12 20:09:23,250] [funambol.sogo] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Context query: null
[2011-08-12 20:09:23,254] [funambol.sogo] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Device's charset: UTF-8
[2011-08-12 20:09:23,254] [funambol.sogo] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Device's timezone: null
[2011-08-12 20:09:23,254] [funambol.engine.strategy] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Preparing slow synchronization
[2011-08-12 20:09:23,254] [funambol.sogo] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] getAllSyncItemKeys()
[2011-08-12 20:09:23,255] [funambol.sogo] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] in aAlarmCleanup(), s on input is:BEGIN:VCALENDAR
VERSION:1.0
TZ:-0700
DAYLIGHT:FALSE
BEGIN:VEVENT
SUMMARY:simple Test
DESCRIPTION:
LOCATION:
CLASS:PUBLIC
DTSTART:20110815T235543Z
DTEND:20110816T005543Z
AALARM:20110815T234543Z;;0;
X-FUNAMBOL-ALLDAY:0
END:VEVENT
END:VCALENDAR

[2011-08-12 20:09:23,255] [funambol.sogo] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] found part[1]=20110815T234543Z
[2011-08-12 20:09:23,255] [funambol.sogo] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] found an alarmdate:20110815T234543Z
[2011-08-12 20:09:23,255] [funambol.sogo] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] after aAlarmCleanup(), ret value is:BEGIN:VCALENDAR
VERSION:1.0
TZ:-0700
DAYLIGHT:FALSE
BEGIN:VEVENT
SUMMARY:simple Test
DESCRIPTION:
LOCATION:
CLASS:PUBLIC
DTSTART:20110815T235543Z
DTEND:20110816T005543Z
AALARM:20110815T234543Z
X-FUNAMBOL-ALLDAY:0
END:VEVENT
END:VCALENDAR

[2011-08-12 20:09:23,258] [funambol.sogo] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] DATE 2: 1313452543 tz: sun.util.calendar.ZoneInfo[id="GMT",offset=0,dstSavings=0,useDaylight=false,transitions=0,lastRule=null]
[2011-08-12 20:09:23,259] [funambol.sogo] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] in aAlarmCleanup(), s on input is:BEGIN:VCALENDAR
VERSION:1.0
TZ:-0700
DAYLIGHT:FALSE
BEGIN:VEVENT
SUMMARY:Repeat event
DESCRIPTION:
LOCATION:
CLASS:PUBLIC
DTSTART:20110816T230000Z
DTEND:20110817T000000Z
AALARM:20110816T225000Z;;0;
RRULE:W1 TU #0
X-FUNAMBOL-ALLDAY:0
END:VEVENT
END:VCALENDAR

[2011-08-12 20:09:23,259] [funambol.sogo] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] found part[1]=20110816T225000Z
[2011-08-12 20:09:23,259] [funambol.sogo] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] found an alarmdate:20110816T225000Z
[2011-08-12 20:09:23,259] [funambol.sogo] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] after aAlarmCleanup(), ret value is:BEGIN:VCALENDAR
VERSION:1.0
TZ:-0700
DAYLIGHT:FALSE
BEGIN:VEVENT
SUMMARY:Repeat event
DESCRIPTION:
LOCATION:
CLASS:PUBLIC
DTSTART:20110816T230000Z
DTEND:20110817T000000Z
AALARM:20110816T225000Z
RRULE:W1 TU #0
X-FUNAMBOL-ALLDAY:0
END:VEVENT
END:VCALENDAR

[2011-08-12 20:09:23,262] [funambol.sogo] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] DATE 2: 1313535600 tz: sun.util.calendar.ZoneInfo[id="GMT",offset=0,dstSavings=0,useDaylight=false,transitions=0,lastRule=null]
[2011-08-12 20:09:23,262] [funambol.sogo] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] in aAlarmCleanup(), s on input is:BEGIN:VCALENDAR
VERSION:1.0
TZ:-0700
DAYLIGHT:FALSE
BEGIN:VEVENT
SUMMARY:Long Event
DESCRIPTION:
LOCATION:
CLASS:PUBLIC
DTSTART:20110818T010000Z
DTEND:20110818T230000Z
AALARM:20110818T005000Z;;0;
X-FUNAMBOL-ALLDAY:0
END:VEVENT
END:VCALENDAR

[2011-08-12 20:09:23,262] [funambol.sogo] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] found part[1]=20110818T005000Z
[2011-08-12 20:09:23,262] [funambol.sogo] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] found an alarmdate:20110818T005000Z
[2011-08-12 20:09:23,262] [funambol.sogo] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] after aAlarmCleanup(), ret value is:BEGIN:VCALENDAR
VERSION:1.0
TZ:-0700
DAYLIGHT:FALSE
BEGIN:VEVENT
SUMMARY:Long Event
DESCRIPTION:
LOCATION:
CLASS:PUBLIC
DTSTART:20110818T010000Z
DTEND:20110818T230000Z
AALARM:20110818T005000Z
X-FUNAMBOL-ALLDAY:0
END:VEVENT
END:VCALENDAR

[2011-08-12 20:09:23,269] [funambol.sogo] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] DATE 2: 1313629200 tz: sun.util.calendar.ZoneInfo[id="GMT",offset=0,dstSavings=0,useDaylight=false,transitions=0,lastRule=null]
[2011-08-12 20:09:23,270] [funambol.engine.strategy] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Preparation completed (last message in the package)
[2011-08-12 20:09:23,270] [funambol.engine.strategy] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Synchronizing...
[2011-08-12 20:09:23,270] [funambol.sogo] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] addSyncItem(44)
[2011-08-12 20:09:23,270] [funambol.sogo] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] addSyncItem - type: text/x-vcalendar
[2011-08-12 20:09:23,270] [funambol.sogo] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] getSyncItemFromId( { keyValue: 44 } )
[2011-08-12 20:09:23,271] [funambol.sogo] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] addSyncItem - content: BEGIN:VCALENDAR
VERSION:1.0
TZ:-0700
DAYLIGHT:FALSE
BEGIN:VEVENT
SUMMARY:simple Test
DESCRIPTION:
LOCATION:
CLASS:PUBLIC
DTSTART:20110815T235543Z
DTEND:20110816T005543Z
AALARM:20110815T234543Z;;0;
X-FUNAMBOL-ALLDAY:0
END:VEVENT
END:VCALENDAR

[2011-08-12 20:09:23,271] [funambol.sogo] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Preferred item type: 1
[2011-08-12 20:09:23,271] [funambol.sogo] [WARN] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Exception occured in getUserTimeZone() - trying to read from JSON string: java.lang.NullPointerException
java.lang.NullPointerException
	at java.io.StringReader.<init>(Unknown Source)
	at org.json.simple.JSONValue.parse(JSONValue.java:33)
	at ca.inverse.sogo.engine.source.SOGoUtilities.getUserTimeZone(SOGoUtilities.java:654)
	at ca.inverse.sogo.engine.source.SOGoEventUtilities.addvEventSyncItem(SOGoEventUtilities.java:65)
	at ca.inverse.sogo.engine.source.SOGoSyncSource.addSyncItem(SOGoSyncSource.java:1000)
	at com.funambol.server.engine.Sync4jStrategy.execNewOperation(Sync4jStrategy.java:2634)
	at com.funambol.server.engine.Sync4jStrategy.execSyncOperation(Sync4jStrategy.java:1456)
	at com.funambol.server.engine.Sync4jStrategy.sync(Sync4jStrategy.java:990)
	at com.funambol.server.engine.Sync4jEngine.sync(Sync4jEngine.java:692)
	at com.funambol.server.session.SyncSessionHandler.processModifications(SyncSessionHandler.java:1655)
	at com.funambol.server.session.SyncSessionHandler.processSyncMessage(SyncSessionHandler.java:1499)
	at com.funambol.server.session.SyncSessionHandler.processInitSyncMapMessage(SyncSessionHandler.java:960)
	at com.funambol.server.session.SyncSessionHandler.processMessage(SyncSessionHandler.java:527)
	at com.funambol.server.engine.SyncAdapter.processInputMessage(SyncAdapter.java:533)
	at com.funambol.server.engine.SyncAdapter.processWBXMLMessage(SyncAdapter.java:369)
	at com.funambol.transport.http.server.LocalSyncHolder.processWBXMLMessage(LocalSyncHolder.java:118)
	at com.funambol.transport.http.server.Sync4jServlet.doPost(Sync4jServlet.java:398)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:710)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:803)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
	at com.funambol.transport.http.server.LogContextFilter.doFilter(LogContextFilter.java:137)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
	at com.funambol.transport.http.server.SyncResponseTimeFilter.doFilter(SyncResponseTimeFilter.java:153)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:233)
	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:175)
	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:128)
	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:286)
	at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:844)
	at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:583)
	at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:447)
	at java.lang.Thread.run(Unknown Source)
[2011-08-12 20:09:23,272] [funambol.handler] [ERROR] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [] Error processing message
java.lang.NoClassDefFoundError: org/apache/commons/configuration/plist/PropertyListConfiguration
	at ca.inverse.sogo.engine.source.SOGoUtilities.getUserTimeZone(SOGoUtilities.java:668)
	at ca.inverse.sogo.engine.source.SOGoEventUtilities.addvEventSyncItem(SOGoEventUtilities.java:65)
	at ca.inverse.sogo.engine.source.SOGoSyncSource.addSyncItem(SOGoSyncSource.java:1000)
	at com.funambol.server.engine.Sync4jStrategy.execNewOperation(Sync4jStrategy.java:2634)
	at com.funambol.server.engine.Sync4jStrategy.execSyncOperation(Sync4jStrategy.java:1456)
	at com.funambol.server.engine.Sync4jStrategy.sync(Sync4jStrategy.java:990)
	at com.funambol.server.engine.Sync4jEngine.sync(Sync4jEngine.java:692)
	at com.funambol.server.session.SyncSessionHandler.processModifications(SyncSessionHandler.java:1655)
	at com.funambol.server.session.SyncSessionHandler.processSyncMessage(SyncSessionHandler.java:1499)
	at com.funambol.server.session.SyncSessionHandler.processInitSyncMapMessage(SyncSessionHandler.java:960)
	at com.funambol.server.session.SyncSessionHandler.processMessage(SyncSessionHandler.java:527)
	at com.funambol.server.engine.SyncAdapter.processInputMessage(SyncAdapter.java:533)
	at com.funambol.server.engine.SyncAdapter.processWBXMLMessage(SyncAdapter.java:369)
	at com.funambol.transport.http.server.LocalSyncHolder.processWBXMLMessage(LocalSyncHolder.java:118)
	at com.funambol.transport.http.server.Sync4jServlet.doPost(Sync4jServlet.java:398)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:710)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:803)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
	at com.funambol.transport.http.server.LogContextFilter.doFilter(LogContextFilter.java:137)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
	at com.funambol.transport.http.server.SyncResponseTimeFilter.doFilter(SyncResponseTimeFilter.java:153)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:233)
	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:175)
	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:128)
	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:286)
	at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:844)
	at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:583)
	at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:447)
	at java.lang.Thread.run(Unknown Source)
[2011-08-12 20:09:23,272] [funambol.handler] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_ERROR [Error processing message]
[2011-08-12 20:09:23,273] [funambol.handler] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_ERROR [com.funambol.framework.server.error.ServerFailureException: 511 SERVER FAILURE - Server error]
[2011-08-12 20:09:23,274] [funambol.transport.http] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request processed.
[2011-08-12 20:09:23,274] [funambol.transport.http] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [] Closing session 600E5FFC06E621317D5B34EF631FAC2F
[2011-08-12 20:09:23,274] [funambol.engine.source] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] sogo-cal: 0 new items added, 0 existing items updated, 0 items deleted.
[2011-08-12 20:09:23,274] [funambol.sogo] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] In endSync()...
[2011-08-12 20:09:23,275] [funambol.sogo] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Closed the database connection.
[2011-08-12 20:09:23,275] [funambol.handler] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_ERROR [Session aborted with status code: PROCESSING ERROR]
[2011-08-12 20:09:23,275] [funambol.handler] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_ENDED
[2011-08-12 20:09:23,275] [funambol.handler] [INFO] [600E5FFC06E621317D5B34EF631FAC2F] [127.0.0.1] [fac-012571000822136] [sogo1] [] Synchronization completed with error(s)
[2011-08-12 20:09:46,022] [funambol.transport.http] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [] [] [] Handling incoming request
[2011-08-12 20:09:46,022] [funambol.transport.http] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [] [] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 20:09:46,022] [funambol.transport.http] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [] [] [] Requested sessionId: null
[2011-08-12 20:09:46,057] [funambol.handler] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [fac-012571000822136] [sogo1] [] sogo1/fac-012571000822136 logged in.
[2011-08-12 20:09:46,071] [funambol.handler] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG1_RECEIVING
[2011-08-12 20:09:46,074] [funambol.sogo] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [fac-012571000822136] [sogo1] [] Source URI: sogo-card
[2011-08-12 20:09:46,074] [funambol.sogo] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [fac-012571000822136] [sogo1] [] Done! Internal item type: 1 (text/x-vcard)
[2011-08-12 20:09:46,075] [funambol.handler] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG3_RECEIVING
[2011-08-12 20:09:46,081] [funambol.transport.http] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request processed.
[2011-08-12 20:09:46,766] [funambol.transport.http] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [fac-012571000822136] [sogo1] [] Handling incoming request
[2011-08-12 20:09:46,766] [funambol.transport.http] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 20:09:46,766] [funambol.transport.http] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [fac-012571000822136] [sogo1] [] Requested sessionId: B3C4B476D3F5797AE6E3402A625AE1A2
[2011-08-12 20:09:46,771] [funambol.engine] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [fac-012571000822136] [sogo1] [] Starting synchronization ...
[2011-08-12 20:09:46,771] [funambol.sogo] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-card] In beginSync()...
[2011-08-12 20:09:46,771] [funambol.sogo] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-card] Loading the JDBC driver for URL: jdbc:postgresql://localhost/sogo
[2011-08-12 20:09:46,775] [funambol.sogo] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-card] Context's user ID: sogo1
[2011-08-12 20:09:46,775] [funambol.sogo] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-card] Context sync mode: 201
[2011-08-12 20:09:46,775] [funambol.sogo] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-card] Context conflict resolution: 1
[2011-08-12 20:09:46,775] [funambol.sogo] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-card] Context query: null
[2011-08-12 20:09:46,779] [funambol.sogo] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-card] Device's charset: UTF-8
[2011-08-12 20:09:46,779] [funambol.sogo] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-card] Device's timezone: null
[2011-08-12 20:09:46,779] [funambol.engine.strategy] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-card] Preparing slow synchronization
[2011-08-12 20:09:46,779] [funambol.sogo] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-card] getAllSyncItemKeys()
[2011-08-12 20:09:46,780] [funambol.engine.strategy] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-card] Preparation completed (last message in the package)
[2011-08-12 20:09:46,780] [funambol.engine.strategy] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-card] Synchronizing...
[2011-08-12 20:09:46,780] [funambol.sogo] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-card] In commitSync()...
[2011-08-12 20:09:46,780] [funambol.handler] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG3_RECEIVED
[2011-08-12 20:09:46,782] [funambol.transport.http] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request processed.
[2011-08-12 20:09:47,231] [funambol.transport.http] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [fac-012571000822136] [sogo1] [] Handling incoming request
[2011-08-12 20:09:47,231] [funambol.transport.http] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 20:09:47,231] [funambol.transport.http] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [fac-012571000822136] [sogo1] [] Requested sessionId: B3C4B476D3F5797AE6E3402A625AE1A2
[2011-08-12 20:09:47,233] [funambol.handler] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG1_RECEIVING
[2011-08-12 20:09:47,237] [funambol.transport.http] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request processed.
[2011-08-12 20:09:47,762] [funambol.transport.http] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [fac-012571000822136] [sogo1] [] Handling incoming request
[2011-08-12 20:09:47,762] [funambol.transport.http] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 20:09:47,762] [funambol.transport.http] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [fac-012571000822136] [sogo1] [] Requested sessionId: B3C4B476D3F5797AE6E3402A625AE1A2
[2011-08-12 20:09:47,765] [funambol.handler] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG3_RECEIVING
[2011-08-12 20:09:47,766] [funambol.handler] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_END
[2011-08-12 20:09:47,766] [funambol.engine.source] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-card] sogo-card: 0 new items added, 0 existing items updated, 0 items deleted.
[2011-08-12 20:09:47,766] [funambol.sogo] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-card] In endSync()...
[2011-08-12 20:09:47,766] [funambol.sogo] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-card] Closed the database connection.
[2011-08-12 20:09:47,768] [funambol.handler] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_ENDED
[2011-08-12 20:09:47,768] [funambol.handler] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [fac-012571000822136] [sogo1] [] Synchronization completed
[2011-08-12 20:09:47,772] [funambol.transport.http] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request processed.
[2011-08-12 20:09:47,772] [funambol.transport.http] [INFO] [B3C4B476D3F5797AE6E3402A625AE1A2] [127.0.0.1] [fac-012571000822136] [sogo1] [] Closing session B3C4B476D3F5797AE6E3402A625AE1A2
[2011-08-12 20:16:15,051] [funambol.transport.http] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [] [] [] Handling incoming request
[2011-08-12 20:16:15,051] [funambol.transport.http] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [] [] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 20:16:15,051] [funambol.transport.http] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [] [] [] Requested sessionId: null
[2011-08-12 20:16:15,083] [funambol.handler] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [] sogo1/fac-012571000822136 logged in.
[2011-08-12 20:16:15,100] [funambol.handler] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG1_RECEIVING
[2011-08-12 20:16:15,102] [funambol.sogo] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [] Source URI: sogo-cal
[2011-08-12 20:16:15,103] [funambol.sogo] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [] Done! Internal item type: 2 (text/x-vevent)
[2011-08-12 20:16:15,104] [funambol.handler] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG3_RECEIVING
[2011-08-12 20:16:15,105] [funambol.transport.http] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request processed.
[2011-08-12 20:16:16,288] [funambol.transport.http] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [] Handling incoming request
[2011-08-12 20:16:16,288] [funambol.transport.http] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 20:16:16,288] [funambol.transport.http] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [] Requested sessionId: 10AE34592EBE753BEA175FD98841AB55
[2011-08-12 20:16:16,293] [funambol.engine] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [] Starting synchronization ...
[2011-08-12 20:16:16,293] [funambol.sogo] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] In beginSync()...
[2011-08-12 20:16:16,293] [funambol.sogo] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Loading the JDBC driver for URL: jdbc:postgresql://localhost/sogo
[2011-08-12 20:16:16,296] [funambol.sogo] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Context's user ID: sogo1
[2011-08-12 20:16:16,296] [funambol.sogo] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Context sync mode: 203
[2011-08-12 20:16:16,296] [funambol.sogo] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Context conflict resolution: 1
[2011-08-12 20:16:16,297] [funambol.sogo] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Context query: null
[2011-08-12 20:16:16,299] [funambol.sogo] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Device's charset: UTF-8
[2011-08-12 20:16:16,299] [funambol.sogo] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Device's timezone: null
[2011-08-12 20:16:16,299] [funambol.engine.strategy] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Preparing slow synchronization
[2011-08-12 20:16:16,299] [funambol.sogo] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] getAllSyncItemKeys()
[2011-08-12 20:16:16,300] [funambol.sogo] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] in aAlarmCleanup(), s on input is:BEGIN:VCALENDAR
VERSION:1.0
TZ:-0700
DAYLIGHT:FALSE
BEGIN:VEVENT
SUMMARY:simple Test
DESCRIPTION:
LOCATION:
CLASS:PUBLIC
DTSTART:20110815T235543Z
DTEND:20110816T005543Z
AALARM:20110815T234543Z;;0;
X-FUNAMBOL-ALLDAY:0
END:VEVENT
END:VCALENDAR

[2011-08-12 20:16:16,300] [funambol.sogo] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] found part[1]=20110815T234543Z
[2011-08-12 20:16:16,300] [funambol.sogo] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] found an alarmdate:20110815T234543Z
[2011-08-12 20:16:16,300] [funambol.sogo] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] after aAlarmCleanup(), ret value is:BEGIN:VCALENDAR
VERSION:1.0
TZ:-0700
DAYLIGHT:FALSE
BEGIN:VEVENT
SUMMARY:simple Test
DESCRIPTION:
LOCATION:
CLASS:PUBLIC
DTSTART:20110815T235543Z
DTEND:20110816T005543Z
AALARM:20110815T234543Z
X-FUNAMBOL-ALLDAY:0
END:VEVENT
END:VCALENDAR

[2011-08-12 20:16:16,303] [funambol.sogo] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] DATE 2: 1313452543 tz: sun.util.calendar.ZoneInfo[id="GMT",offset=0,dstSavings=0,useDaylight=false,transitions=0,lastRule=null]
[2011-08-12 20:16:16,303] [funambol.sogo] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] in aAlarmCleanup(), s on input is:BEGIN:VCALENDAR
VERSION:1.0
TZ:-0700
DAYLIGHT:FALSE
BEGIN:VEVENT
SUMMARY:Repeat event
DESCRIPTION:
LOCATION:
CLASS:PUBLIC
DTSTART:20110816T230000Z
DTEND:20110817T000000Z
AALARM:20110816T225000Z;;0;
RRULE:W1 TU #0
X-FUNAMBOL-ALLDAY:0
END:VEVENT
END:VCALENDAR

[2011-08-12 20:16:16,303] [funambol.sogo] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] found part[1]=20110816T225000Z
[2011-08-12 20:16:16,303] [funambol.sogo] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] found an alarmdate:20110816T225000Z
[2011-08-12 20:16:16,303] [funambol.sogo] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] after aAlarmCleanup(), ret value is:BEGIN:VCALENDAR
VERSION:1.0
TZ:-0700
DAYLIGHT:FALSE
BEGIN:VEVENT
SUMMARY:Repeat event
DESCRIPTION:
LOCATION:
CLASS:PUBLIC
DTSTART:20110816T230000Z
DTEND:20110817T000000Z
AALARM:20110816T225000Z
RRULE:W1 TU #0
X-FUNAMBOL-ALLDAY:0
END:VEVENT
END:VCALENDAR

[2011-08-12 20:16:16,306] [funambol.sogo] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] DATE 2: 1313535600 tz: sun.util.calendar.ZoneInfo[id="GMT",offset=0,dstSavings=0,useDaylight=false,transitions=0,lastRule=null]
[2011-08-12 20:16:16,306] [funambol.sogo] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] in aAlarmCleanup(), s on input is:BEGIN:VCALENDAR
VERSION:1.0
TZ:-0700
DAYLIGHT:FALSE
BEGIN:VEVENT
SUMMARY:Long Event
DESCRIPTION:
LOCATION:
CLASS:PUBLIC
DTSTART:20110818T010000Z
DTEND:20110818T230000Z
AALARM:20110818T005000Z;;0;
X-FUNAMBOL-ALLDAY:0
END:VEVENT
END:VCALENDAR

[2011-08-12 20:16:16,306] [funambol.sogo] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] found part[1]=20110818T005000Z
[2011-08-12 20:16:16,306] [funambol.sogo] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] found an alarmdate:20110818T005000Z
[2011-08-12 20:16:16,306] [funambol.sogo] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] after aAlarmCleanup(), ret value is:BEGIN:VCALENDAR
VERSION:1.0
TZ:-0700
DAYLIGHT:FALSE
BEGIN:VEVENT
SUMMARY:Long Event
DESCRIPTION:
LOCATION:
CLASS:PUBLIC
DTSTART:20110818T010000Z
DTEND:20110818T230000Z
AALARM:20110818T005000Z
X-FUNAMBOL-ALLDAY:0
END:VEVENT
END:VCALENDAR

[2011-08-12 20:16:16,311] [funambol.sogo] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] DATE 2: 1313629200 tz: sun.util.calendar.ZoneInfo[id="GMT",offset=0,dstSavings=0,useDaylight=false,transitions=0,lastRule=null]
[2011-08-12 20:16:16,312] [funambol.engine.strategy] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Preparation completed (last message in the package)
[2011-08-12 20:16:16,312] [funambol.engine.strategy] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Synchronizing...
[2011-08-12 20:16:16,312] [funambol.sogo] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] addSyncItem(44)
[2011-08-12 20:16:16,312] [funambol.sogo] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] addSyncItem - type: text/x-vcalendar
[2011-08-12 20:16:16,312] [funambol.sogo] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] getSyncItemFromId( { keyValue: 44 } )
[2011-08-12 20:16:16,313] [funambol.sogo] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] addSyncItem - content: BEGIN:VCALENDAR
VERSION:1.0
TZ:-0700
DAYLIGHT:FALSE
BEGIN:VEVENT
SUMMARY:simple Test
DESCRIPTION:
LOCATION:
CLASS:PUBLIC
DTSTART:20110815T235543Z
DTEND:20110816T005543Z
AALARM:20110815T234543Z;;0;
X-FUNAMBOL-ALLDAY:0
END:VEVENT
END:VCALENDAR

[2011-08-12 20:16:16,313] [funambol.sogo] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Preferred item type: 1
[2011-08-12 20:16:16,313] [funambol.sogo] [WARN] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Exception occured in getUserTimeZone() - trying to read from JSON string: java.lang.NullPointerException
java.lang.NullPointerException
	at java.io.StringReader.<init>(Unknown Source)
	at org.json.simple.JSONValue.parse(JSONValue.java:33)
	at ca.inverse.sogo.engine.source.SOGoUtilities.getUserTimeZone(SOGoUtilities.java:654)
	at ca.inverse.sogo.engine.source.SOGoEventUtilities.addvEventSyncItem(SOGoEventUtilities.java:65)
	at ca.inverse.sogo.engine.source.SOGoSyncSource.addSyncItem(SOGoSyncSource.java:1000)
	at com.funambol.server.engine.Sync4jStrategy.execNewOperation(Sync4jStrategy.java:2634)
	at com.funambol.server.engine.Sync4jStrategy.execSyncOperation(Sync4jStrategy.java:1456)
	at com.funambol.server.engine.Sync4jStrategy.sync(Sync4jStrategy.java:990)
	at com.funambol.server.engine.Sync4jEngine.sync(Sync4jEngine.java:692)
	at com.funambol.server.session.SyncSessionHandler.processModifications(SyncSessionHandler.java:1655)
	at com.funambol.server.session.SyncSessionHandler.processSyncMessage(SyncSessionHandler.java:1499)
	at com.funambol.server.session.SyncSessionHandler.processInitSyncMapMessage(SyncSessionHandler.java:960)
	at com.funambol.server.session.SyncSessionHandler.processMessage(SyncSessionHandler.java:527)
	at com.funambol.server.engine.SyncAdapter.processInputMessage(SyncAdapter.java:533)
	at com.funambol.server.engine.SyncAdapter.processWBXMLMessage(SyncAdapter.java:369)
	at com.funambol.transport.http.server.LocalSyncHolder.processWBXMLMessage(LocalSyncHolder.java:118)
	at com.funambol.transport.http.server.Sync4jServlet.doPost(Sync4jServlet.java:398)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:710)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:803)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
	at com.funambol.transport.http.server.LogContextFilter.doFilter(LogContextFilter.java:137)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
	at com.funambol.transport.http.server.SyncResponseTimeFilter.doFilter(SyncResponseTimeFilter.java:153)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:233)
	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:175)
	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:128)
	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:286)
	at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:844)
	at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:583)
	at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:447)
	at java.lang.Thread.run(Unknown Source)
[2011-08-12 20:16:16,314] [funambol.handler] [ERROR] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [] Error processing message
java.lang.NoClassDefFoundError: org/apache/commons/configuration/plist/PropertyListConfiguration
	at ca.inverse.sogo.engine.source.SOGoUtilities.getUserTimeZone(SOGoUtilities.java:668)
	at ca.inverse.sogo.engine.source.SOGoEventUtilities.addvEventSyncItem(SOGoEventUtilities.java:65)
	at ca.inverse.sogo.engine.source.SOGoSyncSource.addSyncItem(SOGoSyncSource.java:1000)
	at com.funambol.server.engine.Sync4jStrategy.execNewOperation(Sync4jStrategy.java:2634)
	at com.funambol.server.engine.Sync4jStrategy.execSyncOperation(Sync4jStrategy.java:1456)
	at com.funambol.server.engine.Sync4jStrategy.sync(Sync4jStrategy.java:990)
	at com.funambol.server.engine.Sync4jEngine.sync(Sync4jEngine.java:692)
	at com.funambol.server.session.SyncSessionHandler.processModifications(SyncSessionHandler.java:1655)
	at com.funambol.server.session.SyncSessionHandler.processSyncMessage(SyncSessionHandler.java:1499)
	at com.funambol.server.session.SyncSessionHandler.processInitSyncMapMessage(SyncSessionHandler.java:960)
	at com.funambol.server.session.SyncSessionHandler.processMessage(SyncSessionHandler.java:527)
	at com.funambol.server.engine.SyncAdapter.processInputMessage(SyncAdapter.java:533)
	at com.funambol.server.engine.SyncAdapter.processWBXMLMessage(SyncAdapter.java:369)
	at com.funambol.transport.http.server.LocalSyncHolder.processWBXMLMessage(LocalSyncHolder.java:118)
	at com.funambol.transport.http.server.Sync4jServlet.doPost(Sync4jServlet.java:398)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:710)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:803)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
	at com.funambol.transport.http.server.LogContextFilter.doFilter(LogContextFilter.java:137)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
	at com.funambol.transport.http.server.SyncResponseTimeFilter.doFilter(SyncResponseTimeFilter.java:153)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:233)
	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:175)
	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:128)
	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:286)
	at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:844)
	at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:583)
	at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:447)
	at java.lang.Thread.run(Unknown Source)
[2011-08-12 20:16:16,316] [funambol.handler] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_ERROR [Error processing message]
[2011-08-12 20:16:16,316] [funambol.handler] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_ERROR [com.funambol.framework.server.error.ServerFailureException: 511 SERVER FAILURE - Server error]
[2011-08-12 20:16:16,317] [funambol.transport.http] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request processed.
[2011-08-12 20:16:16,317] [funambol.transport.http] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [] Closing session 10AE34592EBE753BEA175FD98841AB55
[2011-08-12 20:16:16,317] [funambol.engine.source] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] sogo-cal: 0 new items added, 0 existing items updated, 0 items deleted.
[2011-08-12 20:16:16,317] [funambol.sogo] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] In endSync()...
[2011-08-12 20:16:16,318] [funambol.sogo] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Closed the database connection.
[2011-08-12 20:16:16,318] [funambol.handler] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_ERROR [Session aborted with status code: PROCESSING ERROR]
[2011-08-12 20:16:16,318] [funambol.handler] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_ENDED
[2011-08-12 20:16:16,318] [funambol.handler] [INFO] [10AE34592EBE753BEA175FD98841AB55] [127.0.0.1] [fac-012571000822136] [sogo1] [] Synchronization completed with error(s)
[2011-08-12 20:17:53,277] [funambol.transport.http] [INFO] [E6FDEF24E79BDC67B3C148B788DCB2CE] [127.0.0.1] [] [] [] Closing session E6FDEF24E79BDC67B3C148B788DCB2CE
[2011-08-12 20:20:25,424] [funambol.transport.http] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [] [] [] Handling incoming request
[2011-08-12 20:20:25,425] [funambol.transport.http] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [] [] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 20:20:25,425] [funambol.transport.http] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [] [] [] Requested sessionId: null
[2011-08-12 20:20:25,518] [funambol.handler] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [fac-012571000822136] [sogo1] [] sogo1/fac-012571000822136 logged in.
[2011-08-12 20:20:25,533] [funambol.handler] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG1_RECEIVING
[2011-08-12 20:20:25,536] [funambol.sogo] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [fac-012571000822136] [sogo1] [] Source URI: sogo-cal
[2011-08-12 20:20:25,536] [funambol.sogo] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [fac-012571000822136] [sogo1] [] Done! Internal item type: 2 (text/x-vevent)
[2011-08-12 20:20:25,537] [funambol.handler] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG3_RECEIVING
[2011-08-12 20:20:25,539] [funambol.transport.http] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request processed.
[2011-08-12 20:20:26,317] [funambol.transport.http] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [fac-012571000822136] [sogo1] [] Handling incoming request
[2011-08-12 20:20:26,317] [funambol.transport.http] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 20:20:26,317] [funambol.transport.http] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [fac-012571000822136] [sogo1] [] Requested sessionId: 54DF5CD70D9DE27F708A8A66B3A6B75B
[2011-08-12 20:20:26,324] [funambol.engine] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [fac-012571000822136] [sogo1] [] Starting synchronization ...
[2011-08-12 20:20:26,324] [funambol.sogo] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] In beginSync()...
[2011-08-12 20:20:26,325] [funambol.sogo] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Loading the JDBC driver for URL: jdbc:postgresql://localhost/sogo
[2011-08-12 20:20:26,328] [funambol.sogo] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Context's user ID: sogo1
[2011-08-12 20:20:26,328] [funambol.sogo] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Context sync mode: 205
[2011-08-12 20:20:26,328] [funambol.sogo] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Context conflict resolution: 1
[2011-08-12 20:20:26,328] [funambol.sogo] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Context query: null
[2011-08-12 20:20:26,332] [funambol.sogo] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Device's charset: UTF-8
[2011-08-12 20:20:26,332] [funambol.sogo] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Device's timezone: null
[2011-08-12 20:20:26,332] [funambol.engine.strategy] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Preparing slow synchronization
[2011-08-12 20:20:26,332] [funambol.sogo] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] getAllSyncItemKeys()
[2011-08-12 20:20:26,333] [funambol.engine.strategy] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Preparation completed (last message in the package)
[2011-08-12 20:20:26,333] [funambol.engine.strategy] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Synchronizing...
[2011-08-12 20:20:26,333] [funambol.sogo] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] In commitSync()...
[2011-08-12 20:20:26,333] [funambol.handler] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG3_RECEIVED
[2011-08-12 20:20:26,336] [funambol.transport.http] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request processed.
[2011-08-12 20:20:26,699] [funambol.transport.http] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [fac-012571000822136] [sogo1] [] Handling incoming request
[2011-08-12 20:20:26,699] [funambol.transport.http] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 20:20:26,699] [funambol.transport.http] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [fac-012571000822136] [sogo1] [] Requested sessionId: 54DF5CD70D9DE27F708A8A66B3A6B75B
[2011-08-12 20:20:26,701] [funambol.handler] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG1_RECEIVING
[2011-08-12 20:20:26,704] [funambol.transport.http] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request processed.
[2011-08-12 20:20:27,171] [funambol.transport.http] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [fac-012571000822136] [sogo1] [] Handling incoming request
[2011-08-12 20:20:27,171] [funambol.transport.http] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 20:20:27,171] [funambol.transport.http] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [fac-012571000822136] [sogo1] [] Requested sessionId: 54DF5CD70D9DE27F708A8A66B3A6B75B
[2011-08-12 20:20:27,172] [funambol.handler] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG3_RECEIVING
[2011-08-12 20:20:27,172] [funambol.handler] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_END
[2011-08-12 20:20:27,172] [funambol.engine.source] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] sogo-cal: 0 new items added, 0 existing items updated, 0 items deleted.
[2011-08-12 20:20:27,172] [funambol.sogo] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] In endSync()...
[2011-08-12 20:20:27,173] [funambol.sogo] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Closed the database connection.
[2011-08-12 20:20:27,174] [funambol.handler] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_ENDED
[2011-08-12 20:20:27,174] [funambol.handler] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [fac-012571000822136] [sogo1] [] Synchronization completed
[2011-08-12 20:20:27,179] [funambol.transport.http] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request processed.
[2011-08-12 20:20:27,180] [funambol.transport.http] [INFO] [54DF5CD70D9DE27F708A8A66B3A6B75B] [127.0.0.1] [fac-012571000822136] [sogo1] [] Closing session 54DF5CD70D9DE27F708A8A66B3A6B75B
[2011-08-12 20:20:31,241] [funambol.transport.http] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [] [] [] Handling incoming request
[2011-08-12 20:20:31,241] [funambol.transport.http] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [] [] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 20:20:31,241] [funambol.transport.http] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [] [] [] Requested sessionId: null
[2011-08-12 20:20:31,275] [funambol.handler] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [fac-012571000822136] [sogo1] [] sogo1/fac-012571000822136 logged in.
[2011-08-12 20:20:31,292] [funambol.handler] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG1_RECEIVING
[2011-08-12 20:20:31,294] [funambol.sogo] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [fac-012571000822136] [sogo1] [] Source URI: sogo-cal
[2011-08-12 20:20:31,294] [funambol.sogo] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [fac-012571000822136] [sogo1] [] Done! Internal item type: 2 (text/x-vevent)
[2011-08-12 20:20:31,295] [funambol.handler] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG3_RECEIVING
[2011-08-12 20:20:31,296] [funambol.transport.http] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request processed.
[2011-08-12 20:20:31,931] [funambol.transport.http] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [fac-012571000822136] [sogo1] [] Handling incoming request
[2011-08-12 20:20:31,931] [funambol.transport.http] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 20:20:31,931] [funambol.transport.http] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [fac-012571000822136] [sogo1] [] Requested sessionId: 87AF246D678BE50393C6637151EEFEBC
[2011-08-12 20:20:31,939] [funambol.engine] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [fac-012571000822136] [sogo1] [] Starting synchronization ...
[2011-08-12 20:20:31,939] [funambol.sogo] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] In beginSync()...
[2011-08-12 20:20:31,939] [funambol.sogo] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Loading the JDBC driver for URL: jdbc:postgresql://localhost/sogo
[2011-08-12 20:20:31,941] [funambol.sogo] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Context's user ID: sogo1
[2011-08-12 20:20:31,941] [funambol.sogo] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Context sync mode: 200
[2011-08-12 20:20:31,942] [funambol.sogo] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Context conflict resolution: 1
[2011-08-12 20:20:31,942] [funambol.sogo] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Context query: null
[2011-08-12 20:20:31,948] [funambol.sogo] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Device's charset: UTF-8
[2011-08-12 20:20:31,948] [funambol.sogo] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Device's timezone: null
[2011-08-12 20:20:31,948] [funambol.engine.strategy] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Preparing fast synchronization since 2011-08-12 20:20:25.431
[2011-08-12 20:20:31,948] [funambol.sogo] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] getNewItemKeys(2011-08-12 20:20:25.431(1313194825), 2011-08-12 20:20:31.248(1313194831))
[2011-08-12 20:20:31,950] [funambol.sogo] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] getUpdatedSyncItemKeys(2011-08-12 20:20:25.431(1313194825), 2011-08-12 20:20:31.248(1313194831))
[2011-08-12 20:20:31,951] [funambol.sogo] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] getDeletedItemKeys(2011-08-12 20:20:25.431(1313194825), 2011-08-12 20:20:31.248(1313194831))
[2011-08-12 20:20:31,951] [funambol.engine.strategy] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Preparation completed (last message in the package)
[2011-08-12 20:20:31,951] [funambol.engine.strategy] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Synchronizing...
[2011-08-12 20:20:31,951] [funambol.sogo] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] In commitSync()...
[2011-08-12 20:20:31,952] [funambol.handler] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG3_RECEIVED
[2011-08-12 20:20:31,954] [funambol.transport.http] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request processed.
[2011-08-12 20:20:32,570] [funambol.transport.http] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [fac-012571000822136] [sogo1] [] Handling incoming request
[2011-08-12 20:20:32,570] [funambol.transport.http] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 20:20:32,570] [funambol.transport.http] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [fac-012571000822136] [sogo1] [] Requested sessionId: 87AF246D678BE50393C6637151EEFEBC
[2011-08-12 20:20:32,572] [funambol.handler] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG1_RECEIVING
[2011-08-12 20:20:32,574] [funambol.transport.http] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request processed.
[2011-08-12 20:20:32,932] [funambol.transport.http] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [fac-012571000822136] [sogo1] [] Handling incoming request
[2011-08-12 20:20:32,933] [funambol.transport.http] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 20:20:32,933] [funambol.transport.http] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [fac-012571000822136] [sogo1] [] Requested sessionId: 87AF246D678BE50393C6637151EEFEBC
[2011-08-12 20:20:32,937] [funambol.handler] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG3_RECEIVING
[2011-08-12 20:20:32,937] [funambol.handler] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_END
[2011-08-12 20:20:32,937] [funambol.engine.source] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] sogo-cal: 0 new items added, 0 existing items updated, 0 items deleted.
[2011-08-12 20:20:32,937] [funambol.sogo] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] In endSync()...
[2011-08-12 20:20:32,938] [funambol.sogo] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Closed the database connection.
[2011-08-12 20:20:32,939] [funambol.handler] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_ENDED
[2011-08-12 20:20:32,939] [funambol.handler] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [fac-012571000822136] [sogo1] [] Synchronization completed
[2011-08-12 20:20:32,941] [funambol.transport.http] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request processed.
[2011-08-12 20:20:32,941] [funambol.transport.http] [INFO] [87AF246D678BE50393C6637151EEFEBC] [127.0.0.1] [fac-012571000822136] [sogo1] [] Closing session 87AF246D678BE50393C6637151EEFEBC
[2011-08-12 20:21:00,447] [funambol.transport.http] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [] [] [] Handling incoming request
[2011-08-12 20:21:00,448] [funambol.transport.http] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [] [] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 20:21:00,448] [funambol.transport.http] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [] [] [] Requested sessionId: null
[2011-08-12 20:21:00,531] [funambol.handler] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [] sogo1/fac-012571000822136 logged in.
[2011-08-12 20:21:00,547] [funambol.handler] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG1_RECEIVING
[2011-08-12 20:21:00,552] [funambol.sogo] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [] Source URI: sogo-cal
[2011-08-12 20:21:00,552] [funambol.sogo] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [] Done! Internal item type: 2 (text/x-vevent)
[2011-08-12 20:21:00,552] [funambol.handler] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_PKG3_RECEIVING
[2011-08-12 20:21:00,554] [funambol.transport.http] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request processed.
[2011-08-12 20:21:01,061] [funambol.transport.http] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [] Handling incoming request
[2011-08-12 20:21:01,061] [funambol.transport.http] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 20:21:01,061] [funambol.transport.http] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [] Requested sessionId: EE9FD03BDFB25CF56695AA4AD210BA81
[2011-08-12 20:21:01,064] [funambol.engine] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [] Starting synchronization ...
[2011-08-12 20:21:01,065] [funambol.sogo] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] In beginSync()...
[2011-08-12 20:21:01,065] [funambol.sogo] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Loading the JDBC driver for URL: jdbc:postgresql://localhost/sogo
[2011-08-12 20:21:01,068] [funambol.sogo] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Context's user ID: sogo1
[2011-08-12 20:21:01,068] [funambol.sogo] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Context sync mode: 200
[2011-08-12 20:21:01,068] [funambol.sogo] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Context conflict resolution: 1
[2011-08-12 20:21:01,068] [funambol.sogo] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Context query: null
[2011-08-12 20:21:01,070] [funambol.sogo] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Device's charset: UTF-8
[2011-08-12 20:21:01,070] [funambol.sogo] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Device's timezone: null
[2011-08-12 20:21:01,070] [funambol.engine.strategy] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Preparing fast synchronization since 2011-08-12 20:20:31.248
[2011-08-12 20:21:01,070] [funambol.sogo] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] getNewItemKeys(2011-08-12 20:20:31.248(1313194831), 2011-08-12 20:21:00.454(1313194860))
[2011-08-12 20:21:01,072] [funambol.sogo] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] getUpdatedSyncItemKeys(2011-08-12 20:20:31.248(1313194831), 2011-08-12 20:21:00.454(1313194860))
[2011-08-12 20:21:01,073] [funambol.sogo] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] getDeletedItemKeys(2011-08-12 20:20:31.248(1313194831), 2011-08-12 20:21:00.454(1313194860))
[2011-08-12 20:21:01,074] [funambol.sogo] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] in aAlarmCleanup(), s on input is:BEGIN:VCALENDAR
VERSION:1.0
TZ:-0700
DAYLIGHT:FALSE
BEGIN:VEVENT
SUMMARY:Simple
DESCRIPTION:
LOCATION:
CLASS:PUBLIC
DTSTART:20110816T000000Z
DTEND:20110816T010000Z
AALARM:20110815T235000Z;;0;
X-FUNAMBOL-ALLDAY:0
END:VEVENT
END:VCALENDAR

[2011-08-12 20:21:01,074] [funambol.sogo] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] found part[1]=20110815T235000Z
[2011-08-12 20:21:01,074] [funambol.sogo] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] found an alarmdate:20110815T235000Z
[2011-08-12 20:21:01,074] [funambol.sogo] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] after aAlarmCleanup(), ret value is:BEGIN:VCALENDAR
VERSION:1.0
TZ:-0700
DAYLIGHT:FALSE
BEGIN:VEVENT
SUMMARY:Simple
DESCRIPTION:
LOCATION:
CLASS:PUBLIC
DTSTART:20110816T000000Z
DTEND:20110816T010000Z
AALARM:20110815T235000Z
X-FUNAMBOL-ALLDAY:0
END:VEVENT
END:VCALENDAR

[2011-08-12 20:21:01,076] [funambol.sogo] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] DATE 2: 1313452800 tz: sun.util.calendar.ZoneInfo[id="GMT",offset=0,dstSavings=0,useDaylight=false,transitions=0,lastRule=null]
[2011-08-12 20:21:01,076] [funambol.engine.strategy] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Preparation completed (last message in the package)
[2011-08-12 20:21:01,076] [funambol.engine.strategy] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Synchronizing...
[2011-08-12 20:21:01,076] [funambol.sogo] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] addSyncItem(47)
[2011-08-12 20:21:01,076] [funambol.sogo] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] addSyncItem - type: text/x-vcalendar
[2011-08-12 20:21:01,076] [funambol.sogo] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] getSyncItemFromId( { keyValue: 47 } )
[2011-08-12 20:21:01,077] [funambol.sogo] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] addSyncItem - content: BEGIN:VCALENDAR
VERSION:1.0
TZ:-0700
DAYLIGHT:FALSE
BEGIN:VEVENT
SUMMARY:Simple
DESCRIPTION:
LOCATION:
CLASS:PUBLIC
DTSTART:20110816T000000Z
DTEND:20110816T010000Z
AALARM:20110815T235000Z;;0;
X-FUNAMBOL-ALLDAY:0
END:VEVENT
END:VCALENDAR

[2011-08-12 20:21:01,077] [funambol.sogo] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Preferred item type: 1
[2011-08-12 20:21:01,077] [funambol.sogo] [WARN] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Exception occured in getUserTimeZone() - trying to read from JSON string: java.lang.NullPointerException
java.lang.NullPointerException
	at java.io.StringReader.<init>(Unknown Source)
	at org.json.simple.JSONValue.parse(JSONValue.java:33)
	at ca.inverse.sogo.engine.source.SOGoUtilities.getUserTimeZone(SOGoUtilities.java:654)
	at ca.inverse.sogo.engine.source.SOGoEventUtilities.addvEventSyncItem(SOGoEventUtilities.java:65)
	at ca.inverse.sogo.engine.source.SOGoSyncSource.addSyncItem(SOGoSyncSource.java:1000)
	at com.funambol.server.engine.Sync4jStrategy.execNewOperation(Sync4jStrategy.java:2634)
	at com.funambol.server.engine.Sync4jStrategy.execSyncOperation(Sync4jStrategy.java:1456)
	at com.funambol.server.engine.Sync4jStrategy.sync(Sync4jStrategy.java:990)
	at com.funambol.server.engine.Sync4jEngine.sync(Sync4jEngine.java:777)
	at com.funambol.server.session.SyncSessionHandler.processModifications(SyncSessionHandler.java:1655)
	at com.funambol.server.session.SyncSessionHandler.processSyncMessage(SyncSessionHandler.java:1499)
	at com.funambol.server.session.SyncSessionHandler.processInitSyncMapMessage(SyncSessionHandler.java:960)
	at com.funambol.server.session.SyncSessionHandler.processMessage(SyncSessionHandler.java:527)
	at com.funambol.server.engine.SyncAdapter.processInputMessage(SyncAdapter.java:533)
	at com.funambol.server.engine.SyncAdapter.processWBXMLMessage(SyncAdapter.java:369)
	at com.funambol.transport.http.server.LocalSyncHolder.processWBXMLMessage(LocalSyncHolder.java:118)
	at com.funambol.transport.http.server.Sync4jServlet.doPost(Sync4jServlet.java:398)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:710)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:803)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
	at com.funambol.transport.http.server.LogContextFilter.doFilter(LogContextFilter.java:137)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
	at com.funambol.transport.http.server.SyncResponseTimeFilter.doFilter(SyncResponseTimeFilter.java:153)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:233)
	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:175)
	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:128)
	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:286)
	at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:844)
	at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:583)
	at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:447)
	at java.lang.Thread.run(Unknown Source)
[2011-08-12 20:21:01,079] [funambol.handler] [ERROR] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [] Error processing message
java.lang.NoClassDefFoundError: org/apache/commons/configuration/plist/PropertyListConfiguration
	at ca.inverse.sogo.engine.source.SOGoUtilities.getUserTimeZone(SOGoUtilities.java:668)
	at ca.inverse.sogo.engine.source.SOGoEventUtilities.addvEventSyncItem(SOGoEventUtilities.java:65)
	at ca.inverse.sogo.engine.source.SOGoSyncSource.addSyncItem(SOGoSyncSource.java:1000)
	at com.funambol.server.engine.Sync4jStrategy.execNewOperation(Sync4jStrategy.java:2634)
	at com.funambol.server.engine.Sync4jStrategy.execSyncOperation(Sync4jStrategy.java:1456)
	at com.funambol.server.engine.Sync4jStrategy.sync(Sync4jStrategy.java:990)
	at com.funambol.server.engine.Sync4jEngine.sync(Sync4jEngine.java:777)
	at com.funambol.server.session.SyncSessionHandler.processModifications(SyncSessionHandler.java:1655)
	at com.funambol.server.session.SyncSessionHandler.processSyncMessage(SyncSessionHandler.java:1499)
	at com.funambol.server.session.SyncSessionHandler.processInitSyncMapMessage(SyncSessionHandler.java:960)
	at com.funambol.server.session.SyncSessionHandler.processMessage(SyncSessionHandler.java:527)
	at com.funambol.server.engine.SyncAdapter.processInputMessage(SyncAdapter.java:533)
	at com.funambol.server.engine.SyncAdapter.processWBXMLMessage(SyncAdapter.java:369)
	at com.funambol.transport.http.server.LocalSyncHolder.processWBXMLMessage(LocalSyncHolder.java:118)
	at com.funambol.transport.http.server.Sync4jServlet.doPost(Sync4jServlet.java:398)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:710)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:803)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
	at com.funambol.transport.http.server.LogContextFilter.doFilter(LogContextFilter.java:137)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
	at com.funambol.transport.http.server.SyncResponseTimeFilter.doFilter(SyncResponseTimeFilter.java:153)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:233)
	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:175)
	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:128)
	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:286)
	at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:844)
	at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:583)
	at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:447)
	at java.lang.Thread.run(Unknown Source)
[2011-08-12 20:21:01,079] [funambol.handler] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_ERROR [Error processing message]
[2011-08-12 20:21:01,080] [funambol.handler] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_ERROR [com.funambol.framework.server.error.ServerFailureException: 511 SERVER FAILURE - Server error]
[2011-08-12 20:21:01,081] [funambol.transport.http] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [] Request processed.
[2011-08-12 20:21:01,081] [funambol.transport.http] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [] Closing session EE9FD03BDFB25CF56695AA4AD210BA81
[2011-08-12 20:21:01,081] [funambol.engine.source] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] sogo-cal: 0 new items added, 0 existing items updated, 0 items deleted.
[2011-08-12 20:21:01,081] [funambol.sogo] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] In endSync()...
[2011-08-12 20:21:01,082] [funambol.sogo] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [sogo-cal] Closed the database connection.
[2011-08-12 20:21:01,082] [funambol.handler] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_ERROR [Session aborted with status code: PROCESSING ERROR]
[2011-08-12 20:21:01,082] [funambol.handler] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [] moving to state: STATE_ENDED
[2011-08-12 20:21:01,082] [funambol.handler] [INFO] [EE9FD03BDFB25CF56695AA4AD210BA81] [127.0.0.1] [fac-012571000822136] [sogo1] [] Synchronization completed with error(s)
[2011-08-12 20:28:22,655] [funambol.transport.http] [INFO] [D4BC107E67759515F30C76ED79EAC188] [127.0.0.1] [] [] [] Handling incoming request
[2011-08-12 20:28:22,655] [funambol.transport.http] [INFO] [D4BC107E67759515F30C76ED79EAC188] [127.0.0.1] [] [] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 20:28:22,655] [funambol.transport.http] [INFO] [D4BC107E67759515F30C76ED79EAC188] [127.0.0.1] [] [] [] Requested sessionId: null
[2011-08-12 20:28:22,897] [funambol.handler] [INFO] [D4BC107E67759515F30C76ED79EAC188] [127.0.0.1] [fac-012571000822136] [sogo2] [] sogo2/fac-012571000822136 logged in.
[2011-08-12 20:28:22,913] [funambol.handler] [INFO] [D4BC107E67759515F30C76ED79EAC188] [127.0.0.1] [fac-012571000822136] [sogo2] [] moving to state: STATE_PKG1_RECEIVING
[2011-08-12 20:28:22,993] [funambol.sogo] [INFO] [D4BC107E67759515F30C76ED79EAC188] [127.0.0.1] [fac-012571000822136] [sogo2] [] Source URI: sogo-cal
[2011-08-12 20:28:22,993] [funambol.sogo] [INFO] [D4BC107E67759515F30C76ED79EAC188] [127.0.0.1] [fac-012571000822136] [sogo2] [] Done! Internal item type: 2 (text/x-vevent)
[2011-08-12 20:28:22,995] [funambol.sogo] [INFO] [D4BC107E67759515F30C76ED79EAC188] [127.0.0.1] [fac-012571000822136] [sogo2] [] Source URI: sogo-card
[2011-08-12 20:28:22,995] [funambol.sogo] [INFO] [D4BC107E67759515F30C76ED79EAC188] [127.0.0.1] [fac-012571000822136] [sogo2] [] Done! Internal item type: 1 (text/x-vcard)
[2011-08-12 20:28:22,996] [funambol.sogo] [INFO] [D4BC107E67759515F30C76ED79EAC188] [127.0.0.1] [fac-012571000822136] [sogo2] [] Source URI: sogo-todo
[2011-08-12 20:28:22,996] [funambol.sogo] [INFO] [D4BC107E67759515F30C76ED79EAC188] [127.0.0.1] [fac-012571000822136] [sogo2] [] Done! Internal item type: 3 (text/x-vtodo)
[2011-08-12 20:28:23,002] [funambol.handler] [INFO] [D4BC107E67759515F30C76ED79EAC188] [127.0.0.1] [fac-012571000822136] [sogo2] [] moving to state: STATE_PKG3_RECEIVING
[2011-08-12 20:28:23,006] [funambol.transport.http] [INFO] [D4BC107E67759515F30C76ED79EAC188] [127.0.0.1] [fac-012571000822136] [sogo2] [] Request processed.
[2011-08-12 20:29:24,098] [funambol.transport.http] [INFO] [96E651C439A375A92EFAD1EEB014F5B3] [127.0.0.1] [] [] [] Handling incoming request
[2011-08-12 20:29:24,098] [funambol.transport.http] [INFO] [96E651C439A375A92EFAD1EEB014F5B3] [127.0.0.1] [] [] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 20:29:24,098] [funambol.transport.http] [INFO] [96E651C439A375A92EFAD1EEB014F5B3] [127.0.0.1] [] [] [] Requested sessionId: null
[2011-08-12 20:29:24,131] [funambol.handler] [INFO] [96E651C439A375A92EFAD1EEB014F5B3] [127.0.0.1] [fac-012571000822136] [sogo2] [] sogo2/fac-012571000822136 logged in.
[2011-08-12 20:29:24,145] [funambol.handler] [INFO] [96E651C439A375A92EFAD1EEB014F5B3] [127.0.0.1] [fac-012571000822136] [sogo2] [] moving to state: STATE_PKG1_RECEIVING
[2011-08-12 20:29:24,188] [funambol.sogo] [INFO] [96E651C439A375A92EFAD1EEB014F5B3] [127.0.0.1] [fac-012571000822136] [sogo2] [] Source URI: sogo-cal
[2011-08-12 20:29:24,188] [funambol.sogo] [INFO] [96E651C439A375A92EFAD1EEB014F5B3] [127.0.0.1] [fac-012571000822136] [sogo2] [] Done! Internal item type: 2 (text/x-vevent)
[2011-08-12 20:29:24,188] [funambol.handler] [INFO] [96E651C439A375A92EFAD1EEB014F5B3] [127.0.0.1] [fac-012571000822136] [sogo2] [] moving to state: STATE_PKG3_RECEIVING
[2011-08-12 20:29:24,190] [funambol.transport.http] [INFO] [96E651C439A375A92EFAD1EEB014F5B3] [127.0.0.1] [fac-012571000822136] [sogo2] [] Request processed.
[2011-08-12 20:29:36,917] [funambol.transport.http] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [] [] [] Handling incoming request
[2011-08-12 20:29:36,917] [funambol.transport.http] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [] [] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 20:29:36,918] [funambol.transport.http] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [] [] [] Requested sessionId: null
[2011-08-12 20:29:36,948] [funambol.handler] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [fac-012571000822136] [sogo2] [] sogo2/fac-012571000822136 logged in.
[2011-08-12 20:29:36,962] [funambol.handler] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [fac-012571000822136] [sogo2] [] moving to state: STATE_PKG1_RECEIVING
[2011-08-12 20:29:36,963] [funambol.sogo] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [fac-012571000822136] [sogo2] [] Source URI: sogo-cal
[2011-08-12 20:29:36,964] [funambol.sogo] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [fac-012571000822136] [sogo2] [] Done! Internal item type: 2 (text/x-vevent)
[2011-08-12 20:29:36,964] [funambol.handler] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [fac-012571000822136] [sogo2] [] moving to state: STATE_PKG3_RECEIVING
[2011-08-12 20:29:36,968] [funambol.transport.http] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [fac-012571000822136] [sogo2] [] Request processed.
[2011-08-12 20:29:37,485] [funambol.transport.http] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [fac-012571000822136] [sogo2] [] Handling incoming request
[2011-08-12 20:29:37,485] [funambol.transport.http] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [fac-012571000822136] [sogo2] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 20:29:37,485] [funambol.transport.http] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [fac-012571000822136] [sogo2] [] Requested sessionId: 352D3AC05ADD244B1C50AFB09F9A69CC
[2011-08-12 20:29:37,490] [funambol.engine] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [fac-012571000822136] [sogo2] [] Starting synchronization ...
[2011-08-12 20:29:37,490] [funambol.sogo] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [fac-012571000822136] [sogo2] [sogo-cal] In beginSync()...
[2011-08-12 20:29:37,490] [funambol.sogo] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [fac-012571000822136] [sogo2] [sogo-cal] Loading the JDBC driver for URL: jdbc:postgresql://localhost/sogo
[2011-08-12 20:29:37,496] [funambol.sogo] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [fac-012571000822136] [sogo2] [sogo-cal] Context's user ID: sogo2
[2011-08-12 20:29:37,496] [funambol.sogo] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [fac-012571000822136] [sogo2] [sogo-cal] Context sync mode: 201
[2011-08-12 20:29:37,496] [funambol.sogo] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [fac-012571000822136] [sogo2] [sogo-cal] Context conflict resolution: 1
[2011-08-12 20:29:37,496] [funambol.sogo] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [fac-012571000822136] [sogo2] [sogo-cal] Context query: null
[2011-08-12 20:29:37,499] [funambol.sogo] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [fac-012571000822136] [sogo2] [sogo-cal] Device's charset: UTF-8
[2011-08-12 20:29:37,499] [funambol.sogo] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [fac-012571000822136] [sogo2] [sogo-cal] Device's timezone: null
[2011-08-12 20:29:37,499] [funambol.engine.strategy] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [fac-012571000822136] [sogo2] [sogo-cal] Preparing slow synchronization
[2011-08-12 20:29:37,499] [funambol.sogo] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [fac-012571000822136] [sogo2] [sogo-cal] getAllSyncItemKeys()
[2011-08-12 20:29:37,499] [funambol.engine.strategy] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [fac-012571000822136] [sogo2] [sogo-cal] Preparation completed (last message in the package)
[2011-08-12 20:29:37,499] [funambol.engine.strategy] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [fac-012571000822136] [sogo2] [sogo-cal] Synchronizing...
[2011-08-12 20:29:37,499] [funambol.sogo] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [fac-012571000822136] [sogo2] [sogo-cal] In commitSync()...
[2011-08-12 20:29:37,499] [funambol.handler] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [fac-012571000822136] [sogo2] [] moving to state: STATE_PKG3_RECEIVED
[2011-08-12 20:29:37,501] [funambol.transport.http] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [fac-012571000822136] [sogo2] [] Request processed.
[2011-08-12 20:29:38,069] [funambol.transport.http] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [fac-012571000822136] [sogo2] [] Handling incoming request
[2011-08-12 20:29:38,069] [funambol.transport.http] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [fac-012571000822136] [sogo2] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 20:29:38,069] [funambol.transport.http] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [fac-012571000822136] [sogo2] [] Requested sessionId: 352D3AC05ADD244B1C50AFB09F9A69CC
[2011-08-12 20:29:38,071] [funambol.handler] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [fac-012571000822136] [sogo2] [] moving to state: STATE_PKG1_RECEIVING
[2011-08-12 20:29:38,073] [funambol.transport.http] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [fac-012571000822136] [sogo2] [] Request processed.
[2011-08-12 20:29:38,430] [funambol.transport.http] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [fac-012571000822136] [sogo2] [] Handling incoming request
[2011-08-12 20:29:38,430] [funambol.transport.http] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [fac-012571000822136] [sogo2] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 20:29:38,430] [funambol.transport.http] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [fac-012571000822136] [sogo2] [] Requested sessionId: 352D3AC05ADD244B1C50AFB09F9A69CC
[2011-08-12 20:29:38,434] [funambol.handler] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [fac-012571000822136] [sogo2] [] moving to state: STATE_PKG3_RECEIVING
[2011-08-12 20:29:38,434] [funambol.handler] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [fac-012571000822136] [sogo2] [] moving to state: STATE_END
[2011-08-12 20:29:38,434] [funambol.engine.source] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [fac-012571000822136] [sogo2] [sogo-cal] sogo-cal: 0 new items added, 0 existing items updated, 0 items deleted.
[2011-08-12 20:29:38,434] [funambol.sogo] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [fac-012571000822136] [sogo2] [sogo-cal] In endSync()...
[2011-08-12 20:29:38,435] [funambol.sogo] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [fac-012571000822136] [sogo2] [sogo-cal] Closed the database connection.
[2011-08-12 20:29:38,437] [funambol.handler] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [fac-012571000822136] [sogo2] [] moving to state: STATE_ENDED
[2011-08-12 20:29:38,437] [funambol.handler] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [fac-012571000822136] [sogo2] [] Synchronization completed
[2011-08-12 20:29:38,438] [funambol.transport.http] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [fac-012571000822136] [sogo2] [] Request processed.
[2011-08-12 20:29:38,438] [funambol.transport.http] [INFO] [352D3AC05ADD244B1C50AFB09F9A69CC] [127.0.0.1] [fac-012571000822136] [sogo2] [] Closing session 352D3AC05ADD244B1C50AFB09F9A69CC
[2011-08-12 20:30:35,188] [funambol.transport.http] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [] [] [] Handling incoming request
[2011-08-12 20:30:35,189] [funambol.transport.http] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [] [] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 20:30:35,189] [funambol.transport.http] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [] [] [] Requested sessionId: null
[2011-08-12 20:30:35,237] [funambol.handler] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [] sogo2/fac-012571000822136 logged in.
[2011-08-12 20:30:35,259] [funambol.handler] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [] moving to state: STATE_PKG1_RECEIVING
[2011-08-12 20:30:35,264] [funambol.sogo] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [] Source URI: sogo-cal
[2011-08-12 20:30:35,264] [funambol.sogo] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [] Done! Internal item type: 2 (text/x-vevent)
[2011-08-12 20:30:35,265] [funambol.handler] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [] moving to state: STATE_PKG3_RECEIVING
[2011-08-12 20:30:35,275] [funambol.transport.http] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [] Request processed.
[2011-08-12 20:30:35,943] [funambol.transport.http] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [] Handling incoming request
[2011-08-12 20:30:35,943] [funambol.transport.http] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 20:30:35,943] [funambol.transport.http] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [] Requested sessionId: F5A534EC4C8F1BF57008064B6CBAB136
[2011-08-12 20:30:35,946] [funambol.engine] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [] Starting synchronization ...
[2011-08-12 20:30:35,947] [funambol.sogo] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [sogo-cal] In beginSync()...
[2011-08-12 20:30:35,947] [funambol.sogo] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [sogo-cal] Loading the JDBC driver for URL: jdbc:postgresql://localhost/sogo
[2011-08-12 20:30:35,949] [funambol.sogo] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [sogo-cal] Context's user ID: sogo2
[2011-08-12 20:30:35,949] [funambol.sogo] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [sogo-cal] Context sync mode: 200
[2011-08-12 20:30:35,949] [funambol.sogo] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [sogo-cal] Context conflict resolution: 1
[2011-08-12 20:30:35,949] [funambol.sogo] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [sogo-cal] Context query: null
[2011-08-12 20:30:35,953] [funambol.sogo] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [sogo-cal] Device's charset: UTF-8
[2011-08-12 20:30:35,953] [funambol.sogo] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [sogo-cal] Device's timezone: null
[2011-08-12 20:30:35,953] [funambol.engine.strategy] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [sogo-cal] Preparing fast synchronization since 2011-08-12 20:29:36.925
[2011-08-12 20:30:35,953] [funambol.sogo] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [sogo-cal] getNewItemKeys(2011-08-12 20:29:36.925(1313195376), 2011-08-12 20:30:35.197(1313195435))
[2011-08-12 20:30:35,954] [funambol.sogo] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [sogo-cal] getNewSyncItemKeys():  { keyValue: 490-4E45C580-5-147ADF60.ics } (sogosogo2001636a3038)
[2011-08-12 20:30:35,955] [funambol.sogo] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [sogo-cal] getUpdatedSyncItemKeys(2011-08-12 20:29:36.925(1313195376), 2011-08-12 20:30:35.197(1313195435))
[2011-08-12 20:30:35,955] [funambol.sogo] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [sogo-cal] getDeletedItemKeys(2011-08-12 20:29:36.925(1313195376), 2011-08-12 20:30:35.197(1313195435))
[2011-08-12 20:30:35,956] [funambol.engine.strategy] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [sogo-cal] Preparation completed (last message in the package)
[2011-08-12 20:30:35,956] [funambol.engine.strategy] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [sogo-cal] Synchronizing...
[2011-08-12 20:30:35,956] [funambol.sogo] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [sogo-cal] In commitSync()...
[2011-08-12 20:30:35,962] [funambol.handler] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [] moving to state: STATE_PKG3_RECEIVED
[2011-08-12 20:30:35,964] [funambol.transport.http] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [] Request processed.
[2011-08-12 20:30:36,357] [funambol.transport.http] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [] Handling incoming request
[2011-08-12 20:30:36,357] [funambol.transport.http] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [] Request URL: http://www.angelsecurenetworks.com:8080/funambol/ds
[2011-08-12 20:30:36,357] [funambol.transport.http] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [] Requested sessionId: F5A534EC4C8F1BF57008064B6CBAB136
[2011-08-12 20:30:36,360] [funambol.sogo] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [sogo-cal] getSyncItemFromId( { keyValue: 490-4E45C580-5-147ADF60.ics } )
[2011-08-12 20:30:36,361] [funambol.sogo] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [sogo-cal] Preferred item type: 1
[2011-08-12 20:30:36,361] [funambol.sogo] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [sogo-cal] must secure? false
[2011-08-12 20:30:36,361] [funambol.sogo] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [sogo-cal] About to convert vCalendar (from v2 to v1): BEGIN:VCALENDAR
VERSION:2.0
PRODID:-//Inverse inc./SOGo 1.3.8//EN
BEGIN:VEVENT
UID:490-4E45C580-5-147ADF60
SUMMARY:Sogo Simple Event
CREATED:20110813T003010Z
DTSTAMP:20110813T003010Z
LAST-MODIFIED:20110813T003010Z
DTSTART:20110815T101000Z
DTEND:20110815T111000Z
TRANSP:OPAQUE
END:VEVENT
END:VCALENDAR
[2011-08-12 20:30:36,363] [funambol.sogo] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [sogo-cal] in aAlarmCleanup(), s on input is:BEGIN:VCALENDAR
VERSION:2.0
PRODID:-//Inverse inc./SOGo 1.3.8//EN
BEGIN:VEVENT
UID:490-4E45C580-5-147ADF60
SUMMARY:Sogo Simple Event
CREATED:20110813T003010Z
DTSTAMP:20110813T003010Z
LAST-MODIFIED:20110813T003010Z
DTSTART:20110815T101000Z
DTEND:20110815T111000Z
TRANSP:0
END:VEVENT
END:VCALENDAR
[2011-08-12 20:30:36,363] [funambol.sogo] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [sogo-cal] after aAlarmCleanup(), ret value is:BEGIN:VCALENDAR
VERSION:2.0
PRODID:-//Inverse inc./SOGo 1.3.8//EN
BEGIN:VEVENT
UID:490-4E45C580-5-147ADF60
SUMMARY:Sogo Simple Event
CREATED:20110813T003010Z
DTSTAMP:20110813T003010Z
LAST-MODIFIED:20110813T003010Z
DTSTART:20110815T101000Z
DTEND:20110815T111000Z
TRANSP:0
END:VEVENT
END:VCALENDAR

[2011-08-12 20:30:36,364] [funambol.sogo] [WARN] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [sogo-cal] Exception occured in getUserTimeZone() - trying to read from JSON string: java.lang.NullPointerException
java.lang.NullPointerException
	at java.io.StringReader.<init>(Unknown Source)
	at org.json.simple.JSONValue.parse(JSONValue.java:33)
	at ca.inverse.sogo.engine.source.SOGoUtilities.getUserTimeZone(SOGoUtilities.java:654)
	at ca.inverse.sogo.engine.source.SOGoUtilities.vCalendarV2toV1(SOGoUtilities.java:870)
	at ca.inverse.sogo.engine.source.SOGoSyncSource.getSyncItemFromId(SOGoSyncSource.java:639)
	at com.funambol.server.engine.Sync4jEngine.completeItemInfo(Sync4jEngine.java:1912)
	at com.funambol.server.session.SyncSessionHandler.splitSyncCommand(SyncSessionHandler.java:2674)
	at com.funambol.server.session.SyncSessionHandler.commandsToSend(SyncSessionHandler.java:2591)
	at com.funambol.server.session.SyncSessionHandler.processInitSyncMapMessage(SyncSessionHandler.java:1064)
	at com.funambol.server.session.SyncSessionHandler.processMessage(SyncSessionHandler.java:544)
	at com.funambol.server.engine.SyncAdapter.processInputMessage(SyncAdapter.java:533)
	at com.funambol.server.engine.SyncAdapter.processWBXMLMessage(SyncAdapter.java:369)
	at com.funambol.transport.http.server.LocalSyncHolder.processWBXMLMessage(LocalSyncHolder.java:118)
	at com.funambol.transport.http.server.Sync4jServlet.doPost(Sync4jServlet.java:398)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:710)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:803)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
	at com.funambol.transport.http.server.LogContextFilter.doFilter(LogContextFilter.java:137)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
	at com.funambol.transport.http.server.SyncResponseTimeFilter.doFilter(SyncResponseTimeFilter.java:153)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:233)
	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:175)
	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:128)
	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:286)
	at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:844)
	at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:583)
	at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:447)
	at java.lang.Thread.run(Unknown Source)
[2011-08-12 20:30:36,364] [funambol.handler] [ERROR] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [] Error processing message
java.lang.NoClassDefFoundError: org/apache/commons/configuration/plist/PropertyListConfiguration
	at ca.inverse.sogo.engine.source.SOGoUtilities.getUserTimeZone(SOGoUtilities.java:668)
	at ca.inverse.sogo.engine.source.SOGoUtilities.vCalendarV2toV1(SOGoUtilities.java:870)
	at ca.inverse.sogo.engine.source.SOGoSyncSource.getSyncItemFromId(SOGoSyncSource.java:639)
	at com.funambol.server.engine.Sync4jEngine.completeItemInfo(Sync4jEngine.java:1912)
	at com.funambol.server.session.SyncSessionHandler.splitSyncCommand(SyncSessionHandler.java:2674)
	at com.funambol.server.session.SyncSessionHandler.commandsToSend(SyncSessionHandler.java:2591)
	at com.funambol.server.session.SyncSessionHandler.processInitSyncMapMessage(SyncSessionHandler.java:1064)
	at com.funambol.server.session.SyncSessionHandler.processMessage(SyncSessionHandler.java:544)
	at com.funambol.server.engine.SyncAdapter.processInputMessage(SyncAdapter.java:533)
	at com.funambol.server.engine.SyncAdapter.processWBXMLMessage(SyncAdapter.java:369)
	at com.funambol.transport.http.server.LocalSyncHolder.processWBXMLMessage(LocalSyncHolder.java:118)
	at com.funambol.transport.http.server.Sync4jServlet.doPost(Sync4jServlet.java:398)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:710)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:803)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
	at com.funambol.transport.http.server.LogContextFilter.doFilter(LogContextFilter.java:137)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
	at com.funambol.transport.http.server.SyncResponseTimeFilter.doFilter(SyncResponseTimeFilter.java:153)
	at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235)
	at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
	at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:233)
	at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:175)
	at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:128)
	at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
	at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
	at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:286)
	at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:844)
	at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.process(Http11Protocol.java:583)
	at org.apache.tomcat.util.net.JIoEndpoint$Worker.run(JIoEndpoint.java:447)
	at java.lang.Thread.run(Unknown Source)
[2011-08-12 20:30:36,364] [funambol.handler] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [] moving to state: STATE_ERROR [Error processing message]
[2011-08-12 20:30:36,364] [funambol.handler] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [] moving to state: STATE_ERROR [com.funambol.framework.server.error.ServerFailureException: 511 SERVER FAILURE - Server error]
[2011-08-12 20:30:36,365] [funambol.transport.http] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [] Request processed.
[2011-08-12 20:30:36,366] [funambol.transport.http] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [] Closing session F5A534EC4C8F1BF57008064B6CBAB136
[2011-08-12 20:30:36,366] [funambol.engine.source] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [sogo-cal] sogo-cal: 0 new items added, 0 existing items updated, 0 items deleted.
[2011-08-12 20:30:36,366] [funambol.sogo] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [sogo-cal] In endSync()...
[2011-08-12 20:30:36,366] [funambol.sogo] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [sogo-cal] Closed the database connection.
[2011-08-12 20:30:36,366] [funambol.handler] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [] moving to state: STATE_ERROR [Session aborted with status code: PROCESSING ERROR]
[2011-08-12 20:30:36,367] [funambol.handler] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [] moving to state: STATE_ENDED
[2011-08-12 20:30:36,367] [funambol.handler] [INFO] [F5A534EC4C8F1BF57008064B6CBAB136] [127.0.0.1] [fac-012571000822136] [sogo2] [] Synchronization completed with error(s)
[2011-08-12 20:43:53,463] [funambol.transport.http] [INFO] [D4BC107E67759515F30C76ED79EAC188] [127.0.0.1] [fac-012571000822136] [sogo2] [] Closing session D4BC107E67759515F30C76ED79EAC188
[2011-08-12 20:43:53,463] [funambol.handler] [INFO] [D4BC107E67759515F30C76ED79EAC188] [127.0.0.1] [fac-012571000822136] [sogo2] [] moving to state: STATE_ERROR [Session aborted with status code: SESSION_EXPIRED]
[2011-08-12 20:43:53,463] [funambol.handler] [INFO] [D4BC107E67759515F30C76ED79EAC188] [127.0.0.1] [fac-012571000822136] [sogo2] [] moving to state: STATE_ENDED
[2011-08-12 20:43:53,463] [funambol.handler] [INFO] [D4BC107E67759515F30C76ED79EAC188] [127.0.0.1] [fac-012571000822136] [sogo2] [] Synchronization completed with error(s)
[2011-08-12 20:44:53,470] [funambol.transport.http] [INFO] [96E651C439A375A92EFAD1EEB014F5B3] [127.0.0.1] [fac-012571000822136] [sogo2] [] Closing session 96E651C439A375A92EFAD1EEB014F5B3
[2011-08-12 20:44:53,471] [funambol.handler] [INFO] [96E651C439A375A92EFAD1EEB014F5B3] [127.0.0.1] [fac-012571000822136] [sogo2] [] moving to state: STATE_ERROR [Session aborted with status code: SESSION_EXPIRED]
[2011-08-12 20:44:53,471] [funambol.handler] [INFO] [96E651C439A375A92EFAD1EEB014F5B3] [127.0.0.1] [fac-012571000822136] [sogo2] [] moving to state: STATE_ENDED
[2011-08-12 20:44:53,471] [funambol.handler] [INFO] [96E651C439A375A92EFAD1EEB014F5B3] [127.0.0.1] [fac-012571000822136] [sogo2] [] Synchronization completed with error(s)
[2011-08-12 20:45:53,479] [funambol.transport.http] [INFO] [F18390718B8A93EB48993436ED4B3F0E] [192.168.1.13] [] [] [] Closing session F18390718B8A93EB48993436ED4B3F0E