All pastes #434658 Raw Edit

Stuff

public text v1 · immutable
·published 2007-04-11 14:51 UTC
rendered paste body
[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:
<?xml version="1.0" encoding="UTF-8"?>
<SyncML>
<SyncHdr><VerDTD>1.1</VerDTD>
<VerProto>SyncML/1.1</VerProto>
<SessionID>1176301957</SessionID>
<MsgID>1</MsgID>
<Target><LocURI>http://myserverhostname.com:8080/funambol/ds</LocURI>
</Target>
<Source><LocURI>fol-QUNFUjpraW5n</LocURI>
</Source>
<Cred><Meta><Format xmlns="syncml:metinf">b64</Format>
<Type xmlns="syncml:metinf">syncml:auth-basic</Type>
</Meta>
<Data>c3Rpam46dXJkcXYyanQ=</Data>
</Cred>
<Meta><MaxMsgSize xmlns="syncml:metinf">250000</MaxMsgSize>
</Meta>
</SyncHdr>
<SyncBody><Alert><CmdID>1</CmdID>
<Data>200</Data>
<Item><Target><LocURI>scal</LocURI>
</Target>
<Source><LocURI>appointment</LocURI>
</Source>
<Meta><Anchor xmlns="syncml:metinf"><Last>0</Last>
<Next>1176301958</Next>
</Anchor>
</Meta>
</Item>
</Alert>
<Put><CmdID>2</CmdID>
<Meta><Type xmlns="syncml:metinf">application/vnd.syncml-devinf+xml</Type>
</Meta>
<Item><Source><LocURI>./devinf11</LocURI>
</Source>
<Data><DevInf xmlns="syncml:devinf"><VerDTD>1.1</VerDTD>
<Man>Funambol</Man>
<Mod>Funambol Outlook Plug-in</Mod>
<SwV>6.0.7</SwV>
<DevID>fol-QUNFUjpraW5n</DevID>
<DevTyp>workstation</DevTyp>
<UTC/><SupportLargeObjs/><SupportNumberOfChanges/><DataStore><SourceRef>appointment</SourceRef>
<Rx-Pref><CTType>text/x-s4j-sife</CTType>
<VerCT></VerCT>
</Rx-Pref>
<Rx><CTType>text/x-s4j-sife</CTType>
<VerCT></VerCT>
</Rx>
<Rx><CTType>text/x-vcalendar</CTType>
<VerCT>1.0</VerCT>
</Rx>
<Tx-Pref><CTType>text/x-s4j-sife</CTType>
<VerCT></VerCT>
</Tx-Pref>
<Tx><CTType>text/x-s4j-sife</CTType>
<VerCT></VerCT>
</Tx>
<Tx><CTType>text/x-vcalendar</CTType>
<VerCT>1.0</VerCT>
</Tx>
<SyncCap><SyncType>2</SyncType>
<SyncType>1</SyncType>
<SyncType>5</SyncType>
<SyncType>3</SyncType>
<SyncType>6</SyncType>
<SyncType>4</SyncType>
</SyncCap>
</DataStore>
</DevInf>
</Data>
</Item>
</Put>
<Final/></SyncBody>
</SyncML>
[Apr 11, 2007 11:36:43 AM][funambol.server] FINEST: Starting input canonization
[Apr 11, 2007 11:36:43 AM][funambol.server] FINEST: prolog: <?xml version="1.0" encoding="UTF-8"?>
[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: <?xml version="1.0" encoding="UTF-8"?>
<SyncML>
<SyncHdr>
<VerDTD>1.1</VerDTD>
<VerProto>SyncML/1.1</VerProto>
<SessionID>1176301957</SessionID>
<MsgID>1</MsgID>
<Target>
<LocURI>http://myserverhostname.com:8080/funambol/ds</LocURI>
</Target>
<Source>
<LocURI>fol-QUNFUjpraW5n</LocURI>
</Source>
<Cred>
<Meta>
<Format>b64</Format>
<Type>syncml:auth-basic</Type>
</Meta>
<Data>c3Rpam46dXJkcXYyanQ=</Data>
</Cred>
<Meta>
<MaxMsgSize>250000</MaxMsgSize>
</Meta>
</SyncHdr>
<SyncBody>
<Alert>
<CmdID>1</CmdID>
<Data>200</Data>
<Item>
<Target>
<LocURI>scal</LocURI>
</Target>
<Source>
<LocURI>appointment</LocURI>
</Source>
<Meta>
<Anchor xmlns="syncml:metinf">
<Last>0</Last>
<Next>1176301958</Next>
</Anchor>
</Meta>
</Item>
</Alert>
<Put>
<CmdID>2</CmdID>
<Meta>
<Type>application/vnd.syncml-devinf+xml</Type>
</Meta>
<Item>
<Source>
<LocURI>./devinf11</LocURI>
</Source>
<Data>
<DevInf xmlns="syncml:devinf">
<VerDTD>1.1</VerDTD>
<Man>Funambol</Man>
<Mod>Funambol Outlook Plug-in</Mod>
<SwV>6.0.7</SwV>
<DevID>fol-QUNFUjpraW5n</DevID>
<DevTyp>workstation</DevTyp>
<UTC></UTC>
<SupportLargeObjs></SupportLargeObjs>
<SupportNumberOfChanges></SupportNumberOfChanges>
<DataStore>
<SourceRef>appointment</SourceRef>
<Rx-Pref>
<CTType>text/x-s4j-sife</CTType>
<VerCT></VerCT>
</Rx-Pref>
<Rx>
<CTType>text/x-s4j-sife</CTType>
<VerCT></VerCT>
</Rx>
<Rx>
<CTType>text/x-vcalendar</CTType>
<VerCT>1.0</VerCT>
</Rx>
<Tx-Pref>
<CTType>text/x-s4j-sife</CTType>
<VerCT></VerCT>
</Tx-Pref>
<Tx>
<CTType>text/x-s4j-sife</CTType>
<VerCT></VerCT>
</Tx>
<Tx>
<CTType>text/x-vcalendar</CTType>
<VerCT>1.0</VerCT>
</Tx>
<SyncCap>
<SyncType>2</SyncType>
<SyncType>1</SyncType>
<SyncType>5</SyncType>
<SyncType>3</SyncType>
<SyncType>6</SyncType>
<SyncType>4</SyncType>
</SyncCap>
</DataStore>
</DevInf>
</Data>
</Item>
</Put>
<Final></Final>
</SyncBody>
</SyncML>

[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=<null>,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=<null>,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: <?xml version="1.0" encoding="UTF-8"?>
<SyncML>
<SyncHdr>
<VerDTD>1.1</VerDTD>
<VerProto>SyncML/1.1</VerProto>
<SessionID>1176301957</SessionID>
<MsgID>1</MsgID>
<Target>
<LocURI>fol-QUNFUjpraW5n</LocURI>
</Target>
<Source>
<LocURI>http://myserverhostname.com:8080/funambol/ds</LocURI>
</Source>
<RespURI>myserverhostname.com;jsessionid=A34E8A206BEFD252DA8A8CAC1237D2FE</RespURI>
</SyncHdr>
<SyncBody>
<Status>
<CmdID>1</CmdID>
<MsgRef>1</MsgRef>
<CmdRef>0</CmdRef>
<Cmd>SyncHdr</Cmd>
<TargetRef>http://myserverhostname.com:8080/funambol/ds</TargetRef>
<SourceRef>fol-QUNFUjpraW5n</SourceRef>
<Data>212</Data>
</Status>
<Status>
<CmdID>2</CmdID>
<MsgRef>1</MsgRef>
<CmdRef>1</CmdRef>
<Cmd>Alert</Cmd>
<TargetRef>scal</TargetRef>
<SourceRef>appointment</SourceRef>
<Data>508</Data>
<Item>
<Data>
<Anchor xmlns="syncml:metinf">
<Next>1176301958</Next>
</Anchor>
</Data>
</Item>
</Status>
<Status>
<CmdID>3</CmdID>
<MsgRef>1</MsgRef>
<CmdRef>2</CmdRef>
<Cmd>Put</Cmd>
<SourceRef>./devinf11</SourceRef>
<Data>200</Data>
</Status>
<Alert>
<CmdID>4</CmdID>
<Data>201</Data>
<Item>
<Target>
<LocURI>appointment</LocURI>
</Target>
<Source>
<LocURI>scal</LocURI>
</Source>
<Meta>
<Anchor xmlns="syncml:metinf">
<Last>1176305803883</Last>
<Next>1176305803883</Next>
</Anchor>
</Meta>
</Item>
</Alert>
<Final></Final>
</SyncBody>
</SyncML>

[Apr 11, 2007 11:36:44 AM][funambol.server] FINEST: Outgoing message: <?xml version="1.0" encoding="UTF-8"?>
<SyncML>
<SyncHdr>
<VerDTD>1.1</VerDTD>
<VerProto>SyncML/1.1</VerProto>
<SessionID>1176301957</SessionID>
<MsgID>1</MsgID>
<Target>
<LocURI>fol-QUNFUjpraW5n</LocURI>
</Target>
<Source>
<LocURI>http://myserverhostname.com:8080/funambol/ds</LocURI>
</Source>
<RespURI>myserverhostname.com;jsessionid=A34E8A206BEFD252DA8A8CAC1237D2FE</RespURI>
</SyncHdr>
<SyncBody>
<Status>
<CmdID>1</CmdID>
<MsgRef>1</MsgRef>
<CmdRef>0</CmdRef>
<Cmd>SyncHdr</Cmd>
<TargetRef>http://myserverhostname.com:8080/funambol/ds</TargetRef>
<SourceRef>fol-QUNFUjpraW5n</SourceRef>
<Data>212</Data>
</Status>
<Status>
<CmdID>2</CmdID>
<MsgRef>1</MsgRef>
<CmdRef>1</CmdRef>
<Cmd>Alert</Cmd>
<TargetRef>scal</TargetRef>
<SourceRef>appointment</SourceRef>
<Data>508</Data>
<Item>
<Data>
<Anchor xmlns="syncml:metinf">
<Next>1176301958</Next>
</Anchor>
</Data>
</Item>
</Status>
<Status>
<CmdID>3</CmdID>
<MsgRef>1</MsgRef>
<CmdRef>2</CmdRef>
<Cmd>Put</Cmd>
<SourceRef>./devinf11</SourceRef>
<Data>200</Data>
</Status>
<Alert>
<CmdID>4</CmdID>
<Data>201</Data>
<Item>
<Target>
<LocURI>appointment</LocURI>
</Target>
<Source>
<LocURI>scal</LocURI>
</Source>
<Meta>
<Anchor xmlns="syncml:metinf">
<Last>1176305803883</Last>
<Next>1176305803883</Next>
</Anchor>
</Meta>
</Item>
</Alert>
<Final></Final>
</SyncBody>
</SyncML>

[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:
<?xml version="1.0" encoding="UTF-8"?>
<SyncML>
<SyncHdr><VerDTD>1.1</VerDTD>
<VerProto>SyncML/1.1</VerProto>
<SessionID>1176301957</SessionID>
<MsgID>2</MsgID>
<Target><LocURI>http://myserverhostname.com:8080/funambol/ds</LocURI>
</Target>
<Source><LocURI>fol-QUNFUjpraW5n</LocURI>
</Source>
</SyncHdr>
<SyncBody><Status><CmdID>1</CmdID>
<MsgRef>1</MsgRef>
<CmdRef>0</CmdRef>
<Cmd>SyncHdr</Cmd>
<TargetRef>http://myserverhostname.com:8080/funambol/ds</TargetRef>
<SourceRef>fol-QUNFUjpraW5n</SourceRef>
<Data>200</Data>
</Status>
<Status><CmdID>2</CmdID>
<MsgRef>1</MsgRef>
<CmdRef>4</CmdRef>
<Cmd>Alert</Cmd>
<TargetRef>scal</TargetRef>
<SourceRef>appointment</SourceRef>
<Data>200</Data>
<Item><Data><Anchor xmlns="syncml:metinf"><Next>1176305803883</Next>
</Anchor>
</Data>
</Item>
</Status>
<Sync><CmdID>3</CmdID>
<Target><LocURI>scal</LocURI>
</Target>
<Source><LocURI>appointment</LocURI>
</Source>
</Sync>
<Final/></SyncBody>
</SyncML>
[Apr 11, 2007 11:36:52 AM][funambol.server] FINEST: Starting input canonization
[Apr 11, 2007 11:36:52 AM][funambol.server] FINEST: prolog: <?xml version="1.0" encoding="UTF-8"?>
[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: <?xml version="1.0" encoding="UTF-8"?>
<SyncML>
<SyncHdr>
<VerDTD>1.1</VerDTD>
<VerProto>SyncML/1.1</VerProto>
<SessionID>1176301957</SessionID>
<MsgID>2</MsgID>
<Target>
<LocURI>http://myserverhostname.com:8080/funambol/ds</LocURI>
</Target>
<Source>
<LocURI>fol-QUNFUjpraW5n</LocURI>
</Source>
</SyncHdr>
<SyncBody>
<Status>
<CmdID>1</CmdID>
<MsgRef>1</MsgRef>
<CmdRef>0</CmdRef>
<Cmd>SyncHdr</Cmd>
<TargetRef>http://myserverhostname.com:8080/funambol/ds</TargetRef>
<SourceRef>fol-QUNFUjpraW5n</SourceRef>
<Data>200</Data>
</Status>
<Status>
<CmdID>2</CmdID>
<MsgRef>1</MsgRef>
<CmdRef>4</CmdRef>
<Cmd>Alert</Cmd>
<TargetRef>scal</TargetRef>
<SourceRef>appointment</SourceRef>
<Data>200</Data>
<Item>
<Data>
<Anchor xmlns="syncml:metinf">
<Next>1176305803883</Next>
</Anchor>
</Data>
</Item>
</Status>
<Sync>
<CmdID>3</CmdID>
<Target>
<LocURI>scal</LocURI>
</Target>
<Source>
<LocURI>appointment</LocURI>
</Source>
</Sync>
<Final></Final>
</SyncBody>
</SyncML>

[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: <?xml version="1.0" encoding="UTF-8"?>
<SyncML>
<SyncHdr>
<VerDTD>1.1</VerDTD>
<VerProto>SyncML/1.1</VerProto>
<SessionID>1176301957</SessionID>
<MsgID>2</MsgID>
<Target>
<LocURI>fol-QUNFUjpraW5n</LocURI>
</Target>
<Source>
<LocURI>myserverhostname.com</LocURI>
</Source>
<RespURI>myserverhostname.com;jsessionid=379E1C6F0252807B01F695A6F4C267F6</RespURI>
</SyncHdr>
<SyncBody>
<Status>
<CmdID>1</CmdID>
<MsgRef>2</MsgRef>
<CmdRef>0</CmdRef>
<Cmd>SyncHdr</Cmd>
<TargetRef>http://myserverhostname.com:8080/funambol/ds</TargetRef>
<SourceRef>fol-QUNFUjpraW5n</SourceRef>
<Data>511</Data>
</Status>
<Final></Final>
</SyncBody>
</SyncML>

[Apr 11, 2007 11:36:52 AM][funambol.server] FINEST: Outgoing message: <?xml version="1.0" encoding="UTF-8"?>
<SyncML>
<SyncHdr>
<VerDTD>1.1</VerDTD>
<VerProto>SyncML/1.1</VerProto>
<SessionID>1176301957</SessionID>
<MsgID>2</MsgID>
<Target>
<LocURI>fol-QUNFUjpraW5n</LocURI>
</Target>
<Source>
<LocURI>myserverhostname.com</LocURI>
</Source>
<RespURI>myserverhostname.com;jsessionid=379E1C6F0252807B01F695A6F4C267F6</RespURI>
</SyncHdr>
<SyncBody>
<Status>
<CmdID>1</CmdID>
<MsgRef>2</MsgRef>
<CmdRef>0</CmdRef>
<Cmd>SyncHdr</Cmd>
<TargetRef>http://myserverhostname.com:8080/funambol/ds</TargetRef>
<SourceRef>fol-QUNFUjpraW5n</SourceRef>
<Data>511</Data>
</Status>
<Final></Final>
</SyncBody>
</SyncML>

[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