[Apr 11, 2007 11:36:23 AM] INFO: ================================================================================ [Apr 11, 2007 11:36:23 AM] INFO: Funambol Data Synchronization Server v. 5.0 engine started. Configuration object found. [Apr 11, 2007 11:36:23 AM] INFO: {java.runtime.name=Java(TM) 2 Runtime Environment, Standard Edition, sun.boot.library.path=/var/lib/Funambol/tools/jre-1.5.0/jre/lib/i386, java.vm.version=1.5.0_06-b05, shared.loader=${catalina.base}/shared/classes,${catalina.base}/shared/lib/*.jar, java.vm.vendor=Sun Microsystems Inc., java.vendor.url=http://java.sun.com/, path.separator=:, java.vm.name=Java HotSpot(TM) Client VM, file.encoding.pkg=sun.io, user.country=US, sun.os.patch.level=unknown, java.vm.specification.name=Java Virtual Machine Specification, user.dir=/var/lib/Funambol/tools/tomcat/bin, funambol.ds.home=/var/lib/Funambol/ds-server, java.runtime.version=1.5.0_06-b05, java.awt.graphicsenv=sun.awt.X11GraphicsEnvironment, java.endorsed.dirs=/var/lib/Funambol/tools/tomcat/common/endorsed, os.arch=i386, java.io.tmpdir=/var/lib/Funambol/tools/tomcat/temp, line.separator= , java.vm.specification.vendor=Sun Microsystems Inc., java.naming.factory.url.pkgs=org.apache.naming, os.name=Linux, sun.jnu.encoding=UTF-8, java.library.path=/var/lib/Funambol/tools/jre-1.5.0/jre/lib/i386/client:/var/lib/Funambol/tools/jre-1.5.0/jre/lib/i386:/var/lib/Funambol/tools/jre-1.5.0/jre/../lib/i386:, java.specification.name=Java Platform API Specification, java.class.version=49.0, sun.management.compiler=HotSpot Client Compiler, os.version=2.6.17-1.2142_FC4smp, user.home=/root, catalina.useNaming=true, user.timezone=America/New_York, java.awt.printerjob=sun.print.PSPrinterJob, file.encoding=UTF-8, java.specification.version=1.5, catalina.home=/var/lib/Funambol/tools/tomcat, java.class.path=/var/lib/Funambol/tools/jre-1.5.0/jre/lib/tools.jar:/var/lib/Funambol/tools/tomcat/bin/bootstrap.jar:/var/lib/Funambol/tools/tomcat/bin/commons-logging-api.jar, user.name=root, java.naming.factory.initial=org.apache.naming.java.javaURLContextFactory, package.definition=sun.,java.,org.apache.catalina.,org.apache.coyote.,org.apache.tomcat.,org.apache.jasper., java.vm.specification.version=1.0, java.home=/var/lib/Funambol/tools/jre-1.5.0/jre, sun.arch.data.model=32, user.language=en, java.specification.vendor=Sun Microsystems Inc., java.vm.info=mixed mode, java.version=1.5.0_06, java.ext.dirs=/var/lib/Funambol/tools/jre-1.5.0/jre/lib/ext, sun.boot.class.path=/var/lib/Funambol/tools/tomcat/common/endorsed/xml-apis.jar:/var/lib/Funambol/tools/tomcat/common/endorsed/xercesImpl.jar:/var/lib/Funambol/tools/jre-1.5.0/jre/lib/rt.jar:/var/lib/Funambol/tools/jre-1.5.0/jre/lib/i18n.jar:/var/lib/Funambol/tools/jre-1.5.0/jre/lib/sunrsasign.jar:/var/lib/Funambol/tools/jre-1.5.0/jre/lib/jsse.jar:/var/lib/Funambol/tools/jre-1.5.0/jre/lib/jce.jar:/var/lib/Funambol/tools/jre-1.5.0/jre/lib/charsets.jar:/var/lib/Funambol/tools/jre-1.5.0/jre/classes, server.loader=${catalina.home}/server/classes,${catalina.home}/server/lib/*.jar, java.vendor=Sun Microsystems Inc., catalina.base=/var/lib/Funambol/tools/tomcat, file.separator=/, java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi, common.loader=${catalina.home}/common/classes,${catalina.home}/common/endorsed/*.jar,${catalina.home}/common/lib/*.jar, sun.io.unicode.encoding=UnicodeLittle, sun.cpu.endian=little, package.access=sun.,org.apache.catalina.,org.apache.coyote.,org.apache.tomcat.,org.apache.jasper.,sun.beans., sun.cpu.isalist=} [Apr 11, 2007 11:36:23 AM] INFO: ================================================================================ [Apr 11, 2007 11:36:24 AM] INFO: Engine configuration: [Apr 11, 2007 11:36:24 AM] INFO: - store: com/funambol/server/store/PersistentStoreManager.xml (com.funambol.framework.server.store.PersistentStoreManager - {jndiDataSourceName: jdbc/fnblds; stores: com/funambol/server/store/ClientMappingPersistentStore.xml,com/funambol/server/store/LastTimestampPersistentStore.xml,com/funambol/server/store/SyncSourcePersistentStore.xml,com/funambol/server/store/PrincipalPersistentStore.xml,com/funambol/server/store/ModulesPersistentStore.xml,com/funambol/server/store/IDPersistentStore.xml) [Apr 11, 2007 11:36:24 AM] INFO: - officer: com/funambol/server/security/UserProvisioningOfficer.xml (com.funambol.server.security.UserProvisioningOfficer@1d49247) [Apr 11, 2007 11:36:24 AM] INFO: - strategy: com/funambol/server/engine/Strategy.xml (com.funambol.server.engine.Sync4jStrategy@cf829d) [Apr 11, 2007 11:36:24 AM] INFO: Default encoding: UTF8 [Apr 11, 2007 11:36:24 AM] INFO: Server URI set: server2.tonal.org [Apr 11, 2007 11:36:42 AM][funambol.transport.http] FINEST: Http header: [Apr 11, 2007 11:36:42 AM][funambol.transport.http] FINEST: accept: */* [Apr 11, 2007 11:36:42 AM][funambol.transport.http] FINEST: content-type: application/vnd.syncml+xml [Apr 11, 2007 11:36:42 AM][funambol.transport.http] FINEST: content-length: 1840 [Apr 11, 2007 11:36:42 AM][funambol.transport.http] FINEST: accept-encoding: deflate [Apr 11, 2007 11:36:42 AM][funambol.transport.http] FINEST: user-agent: Funambol Outlook Plug-in v. 6.0.7 [Apr 11, 2007 11:36:42 AM][funambol.transport.http] FINEST: host: server2.tonal.org:8080 [Apr 11, 2007 11:36:42 AM][funambol.transport.http] FINEST: connection: Keep-Alive [Apr 11, 2007 11:36:42 AM][funambol.transport.http] FINEST: cache-control: no-cache [Apr 11, 2007 11:36:42 AM] INFO: Handling incoming request: http://myserverhostname.com:8080/funambol/ds [Apr 11, 2007 11:36:42 AM] INFO: Requested sessionId: null [Apr 11, 2007 11:36:42 AM][funambol.transport.http] FINEST: contentType: application/vnd.syncml+xml [Apr 11, 2007 11:36:42 AM][funambol.transport.http] FINEST: contentLength: 1840 [Apr 11, 2007 11:36:42 AM][funambol.transport.http] FINEST: cache: {} [Apr 11, 2007 11:36:42 AM][funambol.transport.http] FINEST: Searching holder with sessionId: A34E8A206BEFD252DA8A8CAC1237D2FE [Apr 11, 2007 11:36:42 AM][funambol.transport.http] FINEST: Holder not found. A new holder will be created [Apr 11, 2007 11:36:43 AM][funambol.transport.http] FINEST: Cleaning procedure... [Apr 11, 2007 11:36:43 AM][funambol.transport.http] FINEST: now: 1176305803126 [Apr 11, 2007 11:36:43 AM][funambol.transport.http] FINEST: CLEANING_PERIOD: 300000 [Apr 11, 2007 11:36:43 AM][funambol.transport.http] FINEST: lastCleaningTimestamp: 1176305783356 [Apr 11, 2007 11:36:43 AM][funambol.transport.http] FINEST: holderTimeToLive: 1200000 [Apr 11, 2007 11:36:43 AM][funambol.transport.http] FINEST: No purging required [Apr 11, 2007 11:36:43 AM][funambol.transport.http] FINEST: Caching A34E8A206BEFD252DA8A8CAC1237D2FE(com.funambol.transport.http.server.LocalSyncHolder@c0a9f9) [Apr 11, 2007 11:36:43 AM][funambol.server] FINEST: Message has content type: application/vnd.syncml+xml [Apr 11, 2007 11:36:43 AM][funambol.server] FINEST: Charset (default): UTF-8 [Apr 11, 2007 11:36:43 AM][funambol.server] FINEST: Message to translate into the SyncML object: 1.1 SyncML/1.1 1176301957 1 http://myserverhostname.com:8080/funambol/ds fol-QUNFUjpraW5n b64 syncml:auth-basic c3Rpam46dXJkcXYyanQ= 250000 1 200 scal appointment 0 1176301958 2 application/vnd.syncml-devinf+xml ./devinf11 1.1 Funambol Funambol Outlook Plug-in 6.0.7 fol-QUNFUjpraW5n workstation appointment text/x-s4j-sife text/x-s4j-sife text/x-vcalendar 1.0 text/x-s4j-sife text/x-s4j-sife text/x-vcalendar 1.0 2 1 5 3 6 4 [Apr 11, 2007 11:36:43 AM][funambol.server] FINEST: Starting input canonization [Apr 11, 2007 11:36:43 AM][funambol.server] FINEST: prolog: [Apr 11, 2007 11:36:43 AM][funambol.server] FINEST: Calling input pipeline [Apr 11, 2007 11:36:43 AM][funambol.engine.pipeline] FINEST: Starting preprocessing [Apr 11, 2007 11:36:43 AM][funambol.engine.pipeline] FINEST: Preprocessed message: 1.1 SyncML/1.1 1176301957 1 http://myserverhostname.com:8080/funambol/ds fol-QUNFUjpraW5n b64 syncml:auth-basic c3Rpam46dXJkcXYyanQ= 250000 1 200 scal appointment 0 1176301958 2 application/vnd.syncml-devinf+xml ./devinf11 1.1 Funambol Funambol Outlook Plug-in 6.0.7 fol-QUNFUjpraW5n workstation appointment text/x-s4j-sife text/x-s4j-sife text/x-vcalendar 1.0 text/x-s4j-sife text/x-s4j-sife text/x-vcalendar 1.0 2 1 5 3 6 4 [Apr 11, 2007 11:36:43 AM][funambol.handler] FINEST: current state: STATE_START [Apr 11, 2007 11:36:43 AM][funambol] FINEST: User to check: stijn [Apr 11, 2007 11:36:43 AM][funambol] FINEST: User 'stijn' found [Apr 11, 2007 11:36:43 AM][funambol] FINEST: User authenticate [Apr 11, 2007 11:36:43 AM][funambol] FINEST: Principal 'stijn/fol-QUNFUjpraW5n' found [Apr 11, 2007 11:36:43 AM] INFO: stijn/fol-QUNFUjpraW5n logged in. [Apr 11, 2007 11:36:43 AM][funambol.handler] FINEST: moving to state STATE_PKG1_RECEIVING [Apr 11, 2007 11:36:43 AM][funambol.handler] FINEST: Processing the initialization commands [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: Store capabilities with id '2' [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: Capabilities with id '2' found in db. Merge will be done [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: Read capabilities from DB by ID '2' [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: Capabilities id '2' found in DB. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: Rx list and RxPref read from DB and set in datastore. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: Tx list and TxPref read from DB and set in datastore. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: FilterRx list read from DB and set in datastore. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: FilterCap list read from DB and set in datastore. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: DsMem read from DB and set in datastore. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: Datastore 'contact' read from DB. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: Rx list and RxPref read from DB and set in datastore. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: Tx list and TxPref read from DB and set in datastore. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: FilterRx list read from DB and set in datastore. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: FilterCap list read from DB and set in datastore. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: Datastore 'appointment' read from DB. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: Rx list and RxPref read from DB and set in datastore. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: Tx list and TxPref read from DB and set in datastore. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: FilterRx list read from DB and set in datastore. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: FilterCap list read from DB and set in datastore. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: DsMem read from DB and set in datastore. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: Datastore 'task' read from DB. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: Rx list and RxPref read from DB and set in datastore. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: Tx list and TxPref read from DB and set in datastore. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: FilterRx list read from DB and set in datastore. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: FilterCap list read from DB and set in datastore. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: DsMem read from DB and set in datastore. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: Datastore 'note' read from DB. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: Ext list read from DB. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: Capabilities with id '2' successfuly read from DB. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: Capabilities with id '2' deleted from db. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: Add datastore 'contact' in db. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: RxPref of datastore 'contact' saved in db. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: Rx of datastore 'contact' saved in db. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: TxPref of datastore 'contact' saved in db. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: Tx of datastore 'contact' saved in db. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: RxFilter of datastore 'contact' saved in db. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: CapFilter of datastore 'contact' saved in db. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: DsMem of datastore 'contact' saved in db. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: Datastore 'contact' saved in db. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: Add datastore 'appointment' in db. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: RxPref of datastore 'appointment' saved in db. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: Rx of datastore 'appointment' saved in db. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: TxPref of datastore 'appointment' saved in db. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: Tx of datastore 'appointment' saved in db. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: RxFilter of datastore 'appointment' saved in db. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: CapFilter of datastore 'appointment' saved in db. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: DsMem of datastore 'appointment' saved in db. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: Datastore 'appointment' saved in db. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: Add datastore 'task' in db. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: RxPref of datastore 'task' saved in db. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: Rx of datastore 'task' saved in db. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: TxPref of datastore 'task' saved in db. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: Tx of datastore 'task' saved in db. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: RxFilter of datastore 'task' saved in db. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: CapFilter of datastore 'task' saved in db. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: DsMem of datastore 'task' saved in db. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: Datastore 'task' saved in db. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: Add datastore 'note' in db. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: RxPref of datastore 'note' saved in db. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: Rx of datastore 'note' saved in db. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: TxPref of datastore 'note' saved in db. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: Tx of datastore 'note' saved in db. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: RxFilter of datastore 'note' saved in db. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: CapFilter of datastore 'note' saved in db. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: DsMem of datastore 'note' saved in db. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: Datastore 'note' saved in db. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: Ext of capabilities with id '2' saved in db. [Apr 11, 2007 11:36:43 AM][funambol.server.store.capabilities] FINEST: Capabilities with id '2' saved in db. [Apr 11, 2007 11:36:43 AM][funambol.engine] FINEST: Checking if the database com.funambol.framework.database.Database@9fe84e[name=scal,type=,statusCode=200,target=com.funambol.framework.core.Target@2006a0,source=com.funambol.framework.core.Source@9300cc,anchor=com.funambol.framework.core.Anchor@1198ff2,principal=fol-QUNFUjpraW5n/stijn] is in the server database list. [Apr 11, 2007 11:36:43 AM] INFO: fol-QUNFUjpraW5n/stijn: database 'scal' found [Apr 11, 2007 11:36:43 AM][funambol.handler] FINEST: Requested databases: [com.funambol.framework.database.Database@9fe84e[name=scal,type=,statusCode=508,target=com.funambol.framework.core.Target@2006a0,source=com.funambol.framework.core.Source@9300cc,anchor=com.funambol.framework.core.Anchor@1198ff2,principal=fol-QUNFUjpraW5n/stijn]] [Apr 11, 2007 11:36:43 AM][funambol.engine] FINEST: adding com.funambol.framework.engine.source.MemorySyncSource@91f005 - {name: scal, type: unknown, uri: scal} [Apr 11, 2007 11:36:44 AM][funambol.handler] FINEST: Checking if MaxMsgSize is larger than the minimum size supported by the server (2500) [Apr 11, 2007 11:36:44 AM][funambol.handler] FINEST: moving to state STATE_PKG3_RECEIVING [Apr 11, 2007 11:36:44 AM][funambol.server] FINEST: Using the configured server uri to create the RespURI [Apr 11, 2007 11:36:44 AM][funambol.server] FINEST: Calling output pipeline [Apr 11, 2007 11:36:44 AM][funambol.engine.pipeline] FINEST: Starting postprocessing [Apr 11, 2007 11:36:44 AM][funambol.engine.pipeline] FINEST: Returning message to process: 1.1 SyncML/1.1 1176301957 1 fol-QUNFUjpraW5n http://myserverhostname.com:8080/funambol/ds myserverhostname.com;jsessionid=A34E8A206BEFD252DA8A8CAC1237D2FE 1 1 0 SyncHdr http://myserverhostname.com:8080/funambol/ds fol-QUNFUjpraW5n 212 2 1 1 Alert scal appointment 508 1176301958 3 1 2 Put ./devinf11 200 4 201 appointment scal 1176305803883 1176305803883 [Apr 11, 2007 11:36:44 AM][funambol.server] FINEST: Outgoing message: 1.1 SyncML/1.1 1176301957 1 fol-QUNFUjpraW5n http://myserverhostname.com:8080/funambol/ds myserverhostname.com;jsessionid=A34E8A206BEFD252DA8A8CAC1237D2FE 1 1 0 SyncHdr http://myserverhostname.com:8080/funambol/ds fol-QUNFUjpraW5n 212 2 1 1 Alert scal appointment 508 1176301958 3 1 2 Put ./devinf11 200 4 201 appointment scal 1176305803883 1176305803883 [Apr 11, 2007 11:36:44 AM][funambol.server] FINEST: Creating response with charset: UTF-8 [Apr 11, 2007 11:36:44 AM][funambol.server] FINEST: Starting output canonization [Apr 11, 2007 11:36:44 AM][funambol.transport.http] FINEST: Outgoing message length: 1320 [Apr 11, 2007 11:36:44 AM][funambol.transport.http] FINEST: Finally [Apr 11, 2007 11:36:52 AM][funambol.transport.http] FINEST: Http header: [Apr 11, 2007 11:36:52 AM][funambol.transport.http] FINEST: accept: */* [Apr 11, 2007 11:36:52 AM][funambol.transport.http] FINEST: content-type: application/vnd.syncml+xml [Apr 11, 2007 11:36:52 AM][funambol.transport.http] FINEST: content-length: 937 [Apr 11, 2007 11:36:52 AM][funambol.transport.http] FINEST: accept-encoding: deflate [Apr 11, 2007 11:36:52 AM][funambol.transport.http] FINEST: user-agent: Funambol Outlook Plug-in v. 6.0.7 [Apr 11, 2007 11:36:52 AM][funambol.transport.http] FINEST: host: myserverhostname.com:8080 [Apr 11, 2007 11:36:52 AM][funambol.transport.http] FINEST: connection: Keep-Alive [Apr 11, 2007 11:36:52 AM][funambol.transport.http] FINEST: cache-control: no-cache [Apr 11, 2007 11:36:52 AM] INFO: Handling incoming request: http://myserverhostname.com:8080/funambol/ds [Apr 11, 2007 11:36:52 AM] INFO: Requested sessionId: null [Apr 11, 2007 11:36:52 AM][funambol.transport.http] FINEST: contentType: application/vnd.syncml+xml [Apr 11, 2007 11:36:52 AM][funambol.transport.http] FINEST: contentLength: 937 [Apr 11, 2007 11:36:52 AM][funambol.transport.http] FINEST: cache: {A34E8A206BEFD252DA8A8CAC1237D2FE=com.funambol.transport.http.server.LocalSyncHolder@c0a9f9} [Apr 11, 2007 11:36:52 AM][funambol.transport.http] FINEST: Searching holder with sessionId: 379E1C6F0252807B01F695A6F4C267F6 [Apr 11, 2007 11:36:52 AM][funambol.transport.http] FINEST: Holder not found. A new holder will be created [Apr 11, 2007 11:36:52 AM][funambol.transport.http] FINEST: Cleaning procedure... [Apr 11, 2007 11:36:52 AM][funambol.transport.http] FINEST: now: 1176305812677 [Apr 11, 2007 11:36:52 AM][funambol.transport.http] FINEST: CLEANING_PERIOD: 300000 [Apr 11, 2007 11:36:52 AM][funambol.transport.http] FINEST: lastCleaningTimestamp: 1176305783356 [Apr 11, 2007 11:36:52 AM][funambol.transport.http] FINEST: holderTimeToLive: 1200000 [Apr 11, 2007 11:36:52 AM][funambol.transport.http] FINEST: No purging required [Apr 11, 2007 11:36:52 AM][funambol.transport.http] FINEST: Caching 379E1C6F0252807B01F695A6F4C267F6(com.funambol.transport.http.server.LocalSyncHolder@68cd79) [Apr 11, 2007 11:36:52 AM][funambol.server] FINEST: Message has content type: application/vnd.syncml+xml [Apr 11, 2007 11:36:52 AM][funambol.server] FINEST: Charset (default): UTF-8 [Apr 11, 2007 11:36:52 AM][funambol.server] FINEST: Message to translate into the SyncML object: 1.1 SyncML/1.1 1176301957 2 http://myserverhostname.com:8080/funambol/ds fol-QUNFUjpraW5n 1 1 0 SyncHdr http://myserverhostname.com:8080/funambol/ds fol-QUNFUjpraW5n 200 2 1 4 Alert scal appointment 200 1176305803883 3 scal appointment [Apr 11, 2007 11:36:52 AM][funambol.server] FINEST: Starting input canonization [Apr 11, 2007 11:36:52 AM][funambol.server] FINEST: prolog: [Apr 11, 2007 11:36:52 AM][funambol.server] FINEST: Calling input pipeline [Apr 11, 2007 11:36:52 AM][funambol.engine.pipeline] FINEST: Starting preprocessing [Apr 11, 2007 11:36:52 AM][funambol.engine.pipeline] FINEST: Preprocessed message: 1.1 SyncML/1.1 1176301957 2 http://myserverhostname.com:8080/funambol/ds fol-QUNFUjpraW5n 1 1 0 SyncHdr http://myserverhostname.com:8080/funambol/ds fol-QUNFUjpraW5n 200 2 1 4 Alert scal appointment 200 1176305803883 3 scal appointment [Apr 11, 2007 11:36:52 AM][funambol.handler] FINEST: current state: STATE_START [Apr 11, 2007 11:36:52 AM][funambol.handler] FINEST: moving to state STATE_PKG1_RECEIVING [Apr 11, 2007 11:36:52 AM][funambol.handler] FINEST: Server auhenticated (code 200) [Apr 11, 2007 11:36:52 AM][funambol.handler] FINER: THROW java.lang.NullPointerException at com.funambol.server.session.SyncSessionHandler.processInitSyncMapMessage(SyncSessionHandler.java:850) at com.funambol.server.session.SyncSessionHandler.processMessage(SyncSessionHandler.java:440) at com.funambol.server.engine.SyncAdapter.processInputMessage(SyncAdapter.java:459) at com.funambol.server.engine.SyncAdapter.processXMLMessage(SyncAdapter.java:215) at com.funambol.transport.http.server.LocalSyncHolder.processXMLMessage(LocalSyncHolder.java:80) at com.funambol.transport.http.server.Sync4jServlet.doPost(Sync4jServlet.java:297) at javax.servlet.http.HttpServlet.service(HttpServlet.java:709) at javax.servlet.http.HttpServlet.service(HttpServlet.java:802) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:252) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:173) at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:214) at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:104) at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:520) at org.apache.catalina.core.StandardContextValve.invokeInternal(StandardContextValve.java:198) at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:152) at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:104) at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:520) at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:137) at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:104) at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:118) at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:102) at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:520) at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109) at org.apache.catalina.core.StandardValveContext.invokeNext(StandardValveContext.java:104) at org.apache.catalina.core.StandardPipeline.invoke(StandardPipeline.java:520) at org.apache.catalina.core.ContainerBase.invoke(ContainerBase.java:929) at org.apache.coyote.tomcat5.CoyoteAdapter.service(CoyoteAdapter.java:160) at org.apache.coyote.http11.Http11Processor.process(Http11Processor.java:799) at org.apache.coyote.http11.Http11Protocol$Http11ConnectionHandler.processConnection(Http11Protocol.java:705) at org.apache.tomcat.util.net.TcpWorkerThread.runIt(PoolTcpEndpoint.java:577) at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:684) at java.lang.Thread.run(Unknown Source) [Apr 11, 2007 11:36:52 AM][funambol.handler] FINEST: moving to state STATE_ERROR [Apr 11, 2007 11:36:52 AM][funambol.handler] FINEST: moving to state STATE_ERROR [Apr 11, 2007 11:36:52 AM][funambol.server] FINEST: Using the configured server uri to create the RespURI [Apr 11, 2007 11:36:52 AM][funambol.server] FINEST: Calling output pipeline [Apr 11, 2007 11:36:52 AM][funambol.engine.pipeline] FINEST: Starting postprocessing [Apr 11, 2007 11:36:52 AM][funambol.engine.pipeline] FINEST: Returning message to process: 1.1 SyncML/1.1 1176301957 2 fol-QUNFUjpraW5n myserverhostname.com myserverhostname.com;jsessionid=379E1C6F0252807B01F695A6F4C267F6 1 2 0 SyncHdr http://myserverhostname.com:8080/funambol/ds fol-QUNFUjpraW5n 511 [Apr 11, 2007 11:36:52 AM][funambol.server] FINEST: Outgoing message: 1.1 SyncML/1.1 1176301957 2 fol-QUNFUjpraW5n myserverhostname.com myserverhostname.com;jsessionid=379E1C6F0252807B01F695A6F4C267F6 1 2 0 SyncHdr http://myserverhostname.com:8080/funambol/ds fol-QUNFUjpraW5n 511 [Apr 11, 2007 11:36:52 AM][funambol.server] FINEST: Creating response with charset: UTF-8 [Apr 11, 2007 11:36:52 AM][funambol.server] FINEST: Starting output canonization [Apr 11, 2007 11:36:52 AM][funambol.transport.http] FINEST: Outgoing message length: 625 [Apr 11, 2007 11:36:52 AM][funambol.transport.http] FINEST: Finally [Apr 11, 2007 11:36:52 AM][funambol.engine] FINEST: null: synchronization ending [Apr 11, 2007 11:36:52 AM][funambol.engine.strategy] FINEST: SyncStrategy synchronization completed. [Apr 11, 2007 11:36:52 AM] INFO: null: synchronization completed [Apr 11, 2007 11:36:52 AM][funambol.handler] FINEST: moving to state STATE_ERROR [Apr 11, 2007 11:36:52 AM][funambol.handler] FINEST: moving to state STATE_ENDED [Apr 11, 2007 11:36:52 AM][funambol.transport.http] FINEST: Removing holder for 379E1C6F0252807B01F695A6F4C267F6