2009-02-06 14:08:48 GMT +0:00 - # Mozilla PIM Plugin Log

14:08:48 GMT +0:00 [DEBUG] - Reading configuration settings...
14:08:48 GMT +0:00 [DEBUG] - Reading configuration settings from the management tree
14:08:48 GMT +0:00 [DEBUG] - Creating SyncSources...
14:08:48 GMT +0:00 [DEBUG] - Checking for contacts sync...
14:08:48 GMT +0:00 [INFO] - ----------> Prepare Address book Synchronization <----------
14:08:48 GMT +0:00 [DEBUG] - Checking for calendar sync...
14:08:48 GMT +0:00 [DEBUG] - The calendar SyncSource is not set to be synced
14:08:48 GMT +0:00 [DEBUG] - Set listeners
14:08:48 GMT +0:00 [INFO] - ----------> Begin SyncML Synchronization <----------
14:08:48 GMT +0:00 [DEBUG] - Synchronization URL: http://stage.contactclean.com/horde/rpc.php
14:08:48 GMT +0:00 [INFO] - Preparing synchronization of contact...
14:08:48 GMT +0:00 [DEBUG] - PropertyFile: it is not possible to read the file: 'C:\Documents and Settings\Ian\My Documents/contact'
14:08:48 GMT +0:00 [DEBUG] - PropertyFile: try to see if a journal exists...
14:08:48 GMT +0:00 [DEBUG] - PropertyFile: there is no journal file: 'C:\Documents and Settings\Ian\My Documents/contact.jour'
14:08:48 GMT +0:00 [DEBUG] - Checking devinfo...
14:08:48 GMT +0:00 [DEBUG] - devinfo hash: ACHQsM9DsJ2sRZiWkCECow==
14:08:48 GMT +0:00 [DEBUG] - devinfo unchanged, no need to send
14:08:48 GMT +0:00 [DEBUG] - Initialization message:
14:08:48 GMT +0:00 [DEBUG] - User Agent = Funambol Mozilla Plugin v0.7
14:08:48 GMT +0:00 [DEBUG] - <?xml version="1.0" encoding="UTF-8"?>
<SyncML>
<SyncHdr><VerDTD>1.1</VerDTD>
<VerProto>SyncML/1.1</VerProto>
<SessionID>1233929328</SessionID>
<MsgID>1</MsgID>
<Target><LocURI>http://stage.contactclean.com/horde/rpc.php</LocURI>
</Target>
<Source><LocURI>fmz-f2yglKNzjYWuhs95DKz18g==</LocURI>
</Source>
<Cred><Meta><Format xmlns="syncml:metinf">b64</Format>
<Type xmlns="syncml:metinf">syncml:auth-basic</Type>
</Meta>
<Data>--password removed--</Data>
</Cred>
<Meta><MaxMsgSize xmlns="syncml:metinf">250000</MaxMsgSize>
</Meta>
</SyncHdr>
<SyncBody><Alert><CmdID>1</CmdID>
<Data>200</Data>
<Item><Target><LocURI>card</LocURI>
</Target>
<Source><LocURI>contact</LocURI>
</Source>
<Meta><Anchor xmlns="syncml:metinf"><Last>1233929058</Last>
<Next>1233929328</Next>
</Anchor>
</Meta>
</Item>
</Alert>
<Final/></SyncBody>
</SyncML>
14:08:48 GMT +0:00 [DEBUG] - Connecting to stage.contactclean.com:80
14:08:48 GMT +0:00 [DEBUG] - Requesting resource /horde/rpc.php
14:08:50 GMT +0:00 [DEBUG] - Message sent
14:08:50 GMT +0:00 [DEBUG] - Data sent succesfully to server. Server responds OK
14:08:50 GMT +0:00 [DEBUG] - Reading response...
14:08:50 GMT +0:00 [DEBUG] - Content-length: 1533
14:08:50 GMT +0:00 [DEBUG] - Response read:
<?xml version="1.0" encoding="UTF-8"?><SyncML xmlns="syncml:syncml1.1"><SyncHdr><VerDTD>1.1</VerDTD><VerProto>SyncML/1.1</VerProto><SessionID>1233929328</SessionID><MsgID>1</MsgID><Target><LocURI>fmz-f2yglKNzjYWuhs95DKz18g==</LocURI><LocName>ian</LocName></Target><Source><LocURI>http://stage.contactclean.com/horde/rpc.php</LocURI></Source><RespURI>http://stage.contactclean.com/horde/rpc.php</RespURI><Meta><MaxMsgSize xmlns="syncml:metinf">1000000000</MaxMsgSize><MaxObjSize xmlns="syncml:metinf">1000000000</MaxObjSize></Meta></SyncHdr><SyncBody><Status><CmdID>1</CmdID><MsgRef>1</MsgRef><CmdRef>0</CmdRef><Cmd>SyncHdr</Cmd><TargetRef>http://stage.contactclean.com/horde/rpc.php</TargetRef><SourceRef>fmz-f2yglKNzjYWuhs95DKz18g==</SourceRef><Data>212</Data></Status><Status><CmdID>2</CmdID><MsgRef>1</MsgRef><CmdRef>1</CmdRef><Cmd>Alert</Cmd><TargetRef>card</TargetRef><SourceRef>contact</SourceRef><Data>200</Data><Item><Data><Anchor xmlns="syncml:metinf"><Last>1233929058</Last><Next>1233929328</Next></Anchor></Data></Item></Status><Alert><CmdID>3</CmdID><Data>200</Data><Item><Target><LocURI>contact</LocURI></Target><Source><LocURI>card</LocURI></Source><Meta><Anchor xmlns="syncml:metinf"><Last>1233928940</Last><Next>1233929210</Next></Anchor><MaxObjSize xmlns="syncml:metinf">1000000000</MaxObjSize></Meta></Item></Alert><Get><CmdID>4</CmdID><Meta><Type xmlns="syncml:metinf">application/vnd.syncml-devinf+xml</Type></Meta><Item><Target><LocURI>./devinf11</LocURI></Target></Item></Get><Final></Final></SyncBody></SyncML>
14:08:50 GMT +0:00 [DEBUG] - [Contacts] CardItemsCollector(): Start
14:08:50 GMT +0:00 [DEBUG] - [Contacts] CardItemsCollector(): Getting AddressBook filename...
14:08:50 GMT +0:00 [DEBUG] - Reading configuration settings from the management tree
14:08:50 GMT +0:00 [DEBUG] - Reading configuration settings from the management tree
14:08:50 GMT +0:00 [DEBUG] - Reading configuration settings from the management tree
14:08:50 GMT +0:00 [DEBUG] - [Contacts] CardItemsCollector(): AddressBook filename: "abook-1.mab"
14:08:50 GMT +0:00 [DEBUG] - [Contacts] CardItemsCollector(): AddressBook found: "test"
14:08:50 GMT +0:00 [DEBUG] - [Contacts] CardItemsCollector(): End
14:08:50 GMT +0:00 [DEBUG] - [Contacts] GetNewItemsList(): Getting new items list
14:08:50 GMT +0:00 [DEBUG] - [Contacts] CollectModifiedItems(): Start
14:08:50 GMT +0:00 [INFO] - [Contacts] Initializing modified items...
14:08:50 GMT +0:00 [DEBUG] - Reading configuration settings from the management tree
14:08:50 GMT +0:00 [DEBUG] - Reading configuration settings from the management tree
14:08:50 GMT +0:00 [DEBUG] - Reading configuration settings from the management tree
14:08:50 GMT +0:00 [INFO] - [Contacts] Initialization done
14:08:50 GMT +0:00 [INFO] - [Contacts] Detected 0 new items, 0 updated items, 0 deleted items
14:08:50 GMT +0:00 [DEBUG] - [Contacts] CollectModifiedItems(): End
14:08:50 GMT +0:00 [DEBUG] - [Contacts] GetUpdatedItemsList(): Getting updated items list
14:08:50 GMT +0:00 [DEBUG] - [Contacts] GetDeletedItemsList(): Getting deleted items list
14:08:50 GMT +0:00 [DEBUG] - Modification message: estimated size 0, allowed size 250000, real size 2059 / estimated size 0 = 100%
14:08:50 GMT +0:00 [DEBUG] - <?xml version="1.0" encoding="UTF-8"?>
<SyncML>
<SyncHdr><VerDTD>1.1</VerDTD>
<VerProto>SyncML/1.1</VerProto>
<SessionID>1233929328</SessionID>
<MsgID>2</MsgID>
<Target><LocURI>http://stage.contactclean.com/horde/rpc.php</LocURI>
</Target>
<Source><LocURI>fmz-f2yglKNzjYWuhs95DKz18g==</LocURI>
</Source>
</SyncHdr>
<SyncBody><Status><CmdID>1</CmdID>
<MsgRef>1</MsgRef>
<CmdRef>0</CmdRef>
<Cmd>SyncHdr</Cmd>
<TargetRef>http://stage.contactclean.com/horde/rpc.php</TargetRef>
<SourceRef>fmz-f2yglKNzjYWuhs95DKz18g==</SourceRef>
<Data>200</Data>
</Status>
<Status><CmdID>2</CmdID>
<MsgRef>1</MsgRef>
<CmdRef>3</CmdRef>
<Cmd>Alert</Cmd>
<TargetRef>card</TargetRef>
<SourceRef>contact</SourceRef>
<Data>200</Data>
<Item><Data><Anchor xmlns="syncml:metinf"><Next>1233929210</Next>
</Anchor>
</Data>
</Item>
</Status>
<Status><CmdID>4</CmdID>
<MsgRef>1</MsgRef>
<CmdRef>4</CmdRef>
<Cmd>Get</Cmd>
<Data>200</Data>
</Status>
<Results><CmdID>3</CmdID>
<MsgRef>1</MsgRef>
<CmdRef>4</CmdRef>
<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>
<SwV>0.7</SwV>
<DevID>fmz-f2yglKNzjYWuhs95DKz18g==</DevID>
<DevTyp>workstation</DevTyp>
<UTC/><DataStore><SourceRef>calendar</SourceRef>
<Rx-Pref><CTType>text/calendar</CTType>
<VerCT>2.0</VerCT>
</Rx-Pref>
<Tx-Pref><CTType>text/calendar</CTType>
<VerCT>2.0</VerCT>
</Tx-Pref>
<SyncCap><SyncType>2</SyncType>
<SyncType>1</SyncType>
<SyncType>6</SyncType>
<SyncType>4</SyncType>
</SyncCap>
</DataStore>
<DataStore><SourceRef>contact</SourceRef>
<Rx-Pref><CTType>text/x-vcard</CTType>
<VerCT>2.1</VerCT>
</Rx-Pref>
<Tx-Pref><CTType>text/x-vcard</CTType>
<VerCT>2.1</VerCT>
</Tx-Pref>
<SyncCap><SyncType>2</SyncType>
<SyncType>1</SyncType>
<SyncType>6</SyncType>
<SyncType>4</SyncType>
</SyncCap>
</DataStore>
</DevInf>
</Data>
</Item>
</Results>
<Sync><CmdID>5</CmdID>
<Target><LocURI>card</LocURI>
</Target>
<Source><LocURI>contact</LocURI>
</Source>
</Sync>
<Final/></SyncBody>
</SyncML>
14:08:50 GMT +0:00 [DEBUG] - Connecting to stage.contactclean.com:80
14:08:50 GMT +0:00 [DEBUG] - Requesting resource /horde/rpc.php
14:08:52 GMT +0:00 [DEBUG] - Message sent
14:08:52 GMT +0:00 [DEBUG] - Data sent succesfully to server. Server responds OK
14:08:52 GMT +0:00 [DEBUG] - Reading response...
14:08:52 GMT +0:00 [DEBUG] - Content-length: 1625
14:08:52 GMT +0:00 [DEBUG] - Response read:
<?xml version="1.0" encoding="UTF-8"?><SyncML xmlns="syncml:syncml1.1"><SyncHdr><VerDTD>1.1</VerDTD><VerProto>SyncML/1.1</VerProto><SessionID>1233929328</SessionID><MsgID>2</MsgID><Target><LocURI>fmz-f2yglKNzjYWuhs95DKz18g==</LocURI><LocName>ian</LocName></Target><Source><LocURI>http://stage.contactclean.com/horde/rpc.php</LocURI></Source><RespURI>http://stage.contactclean.com/horde/rpc.php</RespURI><Meta><MaxMsgSize xmlns="syncml:metinf">1000000000</MaxMsgSize><MaxObjSize xmlns="syncml:metinf">1000000000</MaxObjSize></Meta></SyncHdr><SyncBody><Status><CmdID>1</CmdID><MsgRef>2</MsgRef><CmdRef>0</CmdRef><Cmd>SyncHdr</Cmd><TargetRef>http://stage.contactclean.com/horde/rpc.php</TargetRef><SourceRef>fmz-f2yglKNzjYWuhs95DKz18g==</SourceRef><Data>200</Data></Status><Status><CmdID>2</CmdID><MsgRef>2</MsgRef><CmdRef>3</CmdRef><Cmd>Results</Cmd><SourceRef>./devinf11</SourceRef><Data>200</Data></Status><Status><CmdID>3</CmdID><MsgRef>2</MsgRef><CmdRef>5</CmdRef><Cmd>Sync</Cmd><TargetRef>card</TargetRef><SourceRef>contact</SourceRef><Data>200</Data></Status><Get><CmdID>4</CmdID><Meta><Type xmlns="syncml:metinf">application/vnd.syncml-devinf+xml</Type></Meta><Item><Target><LocURI>./devinf11</LocURI></Target></Item></Get><Sync><CmdID>5</CmdID><Target><LocURI>contact</LocURI></Target><Source><LocURI>card</LocURI></Source><Add><CmdID>6</CmdID><Meta><Type xmlns="syncml:metinf">text/x-vcard</Type></Meta><Item><Source><LocURI>1233929152</LocURI></Source><Data><![CDATA[BEGIN:VCARD

VERSION:2.1

NICKNAME:via Horde

N:Bee;Eric;;;

FN:Eric Bee

END:VCARD

]]></Data></Item></Add></Sync><Final></Final></SyncBody></SyncML>
14:08:52 GMT +0:00 [DEBUG] - Adding item:
14:08:52 GMT +0:00 [DEBUG] - key: 1
14:08:52 GMT +0:00 [DEBUG] - card: BEGIN:VCARD

VERSION:2.1

NICKNAME:via Horde

N:Bee;Eric;;;

FN:Eric Bee

END:VCARD


14:08:52 GMT +0:00 [DEBUG] - [Contacts] AddCard(): Start
14:08:52 GMT +0:00 [DEBUG] - [Contacts] getAbCardFromVCard(): Start
14:08:52 GMT +0:00 [DEBUG] - [Contacts] getAbCardFromVCard(): End
14:08:52 GMT +0:00 [DEBUG] - Reading configuration settings from the management tree
14:08:52 GMT +0:00 [DEBUG] - Reading configuration settings from the management tree
14:08:52 GMT +0:00 [DEBUG] - [Contacts] AddCard(): Card added key: '47'
14:08:52 GMT +0:00 [DEBUG] - [Contacts] AddCard(): End
14:08:52 GMT +0:00 [DEBUG] - Mapping
14:08:52 GMT +0:00 [DEBUG] - <?xml version="1.0" encoding="UTF-8"?>
<SyncML>
<SyncHdr><VerDTD>1.1</VerDTD>
<VerProto>SyncML/1.1</VerProto>
<SessionID>1233929328</SessionID>
<MsgID>3</MsgID>
<Target><LocURI>http://stage.contactclean.com/horde/rpc.php</LocURI>
</Target>
<Source><LocURI>fmz-f2yglKNzjYWuhs95DKz18g==</LocURI>
</Source>
</SyncHdr>
<SyncBody><Status><CmdID>3</CmdID>
<MsgRef>2</MsgRef>
<CmdRef>0</CmdRef>
<Cmd>SyncHdr</Cmd>
<TargetRef>http://stage.contactclean.com/horde/rpc.php</TargetRef>
<SourceRef>fmz-f2yglKNzjYWuhs95DKz18g==</SourceRef>
<Data>200</Data>
</Status>
<Status><CmdID>1</CmdID>
<MsgRef>2</MsgRef>
<CmdRef>5</CmdRef>
<Cmd>Sync</Cmd>
<TargetRef>card</TargetRef>
<SourceRef>contact</SourceRef>
<Data>200</Data>
</Status>
<Status><CmdID>2</CmdID>
<MsgRef>2</MsgRef>
<CmdRef>6</CmdRef>
<Cmd>Add</Cmd>
<Data>200</Data>
<Item><Source><LocURI>1233929152</LocURI>
</Source>
</Item>
</Status>
<Final/></SyncBody>
</SyncML>
14:08:52 GMT +0:00 [DEBUG] - Connecting to stage.contactclean.com:80
14:08:52 GMT +0:00 [DEBUG] - Requesting resource /horde/rpc.php
14:08:53 GMT +0:00 [DEBUG] - Message sent
14:08:53 GMT +0:00 [DEBUG] - Data sent succesfully to server. Server responds OK
14:08:53 GMT +0:00 [DEBUG] - Reading response...
14:08:53 GMT +0:00 [DEBUG] - Content-length: 970
14:08:53 GMT +0:00 [DEBUG] - Response read:
<?xml version="1.0" encoding="UTF-8"?><SyncML xmlns="syncml:syncml1.1"><SyncHdr><VerDTD>1.1</VerDTD><VerProto>SyncML/1.1</VerProto><SessionID>1233929328</SessionID><MsgID>3</MsgID><Target><LocURI>fmz-f2yglKNzjYWuhs95DKz18g==</LocURI><LocName>ian</LocName></Target><Source><LocURI>http://stage.contactclean.com/horde/rpc.php</LocURI></Source><RespURI>http://stage.contactclean.com/horde/rpc.php</RespURI><Meta><MaxMsgSize xmlns="syncml:metinf">1000000000</MaxMsgSize><MaxObjSize xmlns="syncml:metinf">1000000000</MaxObjSize></Meta></SyncHdr><SyncBody><Status><CmdID>1</CmdID><MsgRef>3</MsgRef><CmdRef>0</CmdRef><Cmd>SyncHdr</Cmd><TargetRef>http://stage.contactclean.com/horde/rpc.php</TargetRef><SourceRef>fmz-f2yglKNzjYWuhs95DKz18g==</SourceRef><Data>200</Data></Status><Get><CmdID>2</CmdID><Meta><Type xmlns="syncml:metinf">application/vnd.syncml-devinf+xml</Type></Meta><Item><Target><LocURI>./devinf11</LocURI></Target></Item></Get><Final></Final></SyncBody></SyncML>
14:08:53 GMT +0:00 [INFO] - Server returned 1 new items, 0 updated items, 0 deleted items
14:08:53 GMT +0:00 [DEBUG] - Reading configuration settings from the management tree
14:08:53 GMT +0:00 [DEBUG] - Reading configuration settings from the management tree
14:08:53 GMT +0:00 [DEBUG] - Reading configuration settings from the management tree
14:08:53 GMT +0:00 [INFO] - Resetting contacts database file
14:08:53 GMT +0:00 [DEBUG] - Committing source contact (next: 1233929328)
14:08:53 GMT +0:00 [DEBUG] - ret: 0, lastErrorCode: 0, lastErrorMessage: 
14:08:53 GMT +0:00 [INFO] - Sync successfully completed.
14:08:53 GMT +0:00 [DEBUG] - ===========================================================
================   SYNCHRONIZATION REPORT   ===============
===========================================================
Last error code = 0
Last error msg  = 

----------|--------CLIENT---------|--------SERVER---------|
  Source  |  NEW  |  MOD  |  DEL  |  NEW  |  MOD  |  DEL  |
----------|-----------------------------------------------|
  calendar|  0/  0|  0/  0|  0/  0|  0/  0|  0/  0|  0/  0|
----------|-----------------------------------------------|
   contact|  1/  1|  0/  0|  0/  0|  0/  0|  0/  0|  0/  0|
----------|-----------------------------------------------|

calendar:
----------
   Source State    = 0
   Last error code = 0
   Last error msg  = (null)
contact:
----------
   Source State    = 0
   Last error code = 0
   Last error msg  = (null)
   Client Add: id '47' status 200 


14:08:53 GMT +0:00 [INFO] - ----------> End SyncML Synchronization <----------
14:08:53 GMT +0:00 [DEBUG] - Saving configuration settings...
14:08:53 GMT +0:00 [DEBUG] - Writing configuration settings to the management tree
14:08:54 GMT +0:00 [DEBUG] - Unset listeners
14:08:54 GMT +0:00 [DEBUG] - [Contacts] ~CardItemsCollector(): Start
14:08:54 GMT +0:00 [DEBUG] - [Contacts] ~CardItemsCollector(): End
14:08:56 GMT +0:00 [DEBUG] - Reading configuration settings from the management tree
14:08:56 GMT +0:00 [DEBUG] - Reading configuration settings from the management tree
14:08:57 GMT +0:00 [DEBUG] - Reading configuration settings from the management tree
14:08:59 GMT +0:00 [DEBUG] - Reading configuration settings from the management tree
14:08:59 GMT +0:00 [DEBUG] - Reading configuration settings from the management tree
14:08:59 GMT +0:00 [DEBUG] - Writing configuration settings to the management tree
14:09:02 GMT +0:00 [DEBUG] - Reading configuration settings from the management tree