Z-Push-2 Zimbra

[liverpoolfcfan]

I am trying to port over the zimbra backend to version 2, and have gotten an error after the initial folder sync. Can you please help me to understand what might be causing this ...

What is the GetAdditionalSyncStore call for ?

Why does it end up calling Setup a second time with nothing passed over [ 23/09/2011 12:55:52 [23423] [myUser] [DEBUG] START Setup { store = ; checkACLonly = ; folderid = } ] ?

Any guidance would be greatly appreciated.

Thanks in advance.

Vincent

In the folder /usr/lib/z-push/f/a it has created the following 3 files

07E905741E50123456789B64323134AF-a1f8c6f2-736f-4784-b481-12c25a25547b-1
07E905741E50123456789B64323134AF-a1f8c6f2-736f-4784-b481-12c25a25547b-hc-1
07E905741E50123456789B64323134AF-devicedata

then it tries to do a sync of the contents of the Contacts folder 'f7' but fails and bombs out



23/09/2011 12:55:51 [23423] [myUser] [DEBUG] PHP version: 5.2.10 - z-push Zimbra Backend version:
23/09/2011 12:55:51 [23423] [myUser] [DEBUG] START Logon { username = myUser; domain = myDomain; password = <hidden> }
23/09/2011 12:55:52 [23423] [myUser] [DEBUG] END Logon { connected = true; uid = myUser }
23/09/2011 12:55:52 [23423] [myUser] [DEBUG] START Setup { store = myUser; checkACLonly = 1; folderid = }
23/09/2011 12:55:52 [23423] [myUser] [DEBUG] Store [myUser]
23/09/2011 12:55:52 [23423] [myUser] [ WARN] /var/www/html/z-push-2/backend/zimbra/zimbra.php:755 Undefined index: useragent (8)
23/09/2011 12:55:52 [23423] [myUser] [DEBUG] Smart Folders ENABLED - User Profile files will be ignored
23/09/2011 12:55:52 [23423] [myUser] [DEBUG] Found *SyncConfig* folder with [1] Directives
23/09/2011 12:55:52 [23423] [myUser] [DEBUG] END Setup { 89 Folders Loaded }
23/09/2011 12:55:52 [23423] [myUser] [DEBUG] ZimbraBackend->Setup(): Checking for admin ACLs on store 'myUser': 'true'
23/09/2011 12:55:52 [23423] [myUser] [DEBUG] ASDevice initialized for DeviceID '07E905741E50123456789B64323134AF'
23/09/2011 12:55:52 [23423] [myUser] [DEBUG] FileStateMachine->GetState() on file: '/var/lib/z-push/f/a/07E905741E50123456789B64323134AF-devicedata'
23/09/2011 12:55:52 [23423] [myUser] [DEBUG] ASDevice data loaded for user: 'myUser'
23/09/2011 12:55:52 [23423] [myUser] [DEBUG] ZPush::HierarchyCommand('Sync'): false
23/09/2011 12:55:52 [23423] [myUser] [DEBUG] ZPush::CommandNeedsPlainInput('Sync'): false
23/09/2011 12:55:52 [23423] [myUser] [DEBUG] Sync folder: 'Contacts'
23/09/2011 12:55:52 [23423] [myUser] [DEBUG] ZPush::GetAdditionalSyncFolderStore('f7'): 'false'
23/09/2011 12:55:52 [23423] [myUser] [DEBUG] START Setup { store = ; checkACLonly = ; folderid = }
23/09/2011 12:55:52 [23423] [myUser] [DEBUG] END Setup { 89 Folders Loaded }
23/09/2011 12:55:52 [23423] [myUser] [DEBUG] Diffstate->Config: State [] - flags [0]
23/09/2011 12:55:52 [23423] [myUser] [DEBUG] ImportChangesDiff->InitializeExporter(): Initializing message diff engine. '0' messages in state
23/09/2011 12:55:52 [23423] [myUser] [DEBUG] START GetMessageList { folderid = f7; cutoffdate = 0; virtual = 0; offset = 0 }
23/09/2011 12:55:54 [23423] [myUser] [DEBUG] END GetMessageList CONTACTS { count = 458 }
23/09/2011 12:55:54 [23423] [myUser] [ INFO] ImportChangesDiff->InitializeExporter(): Found '458' changes
23/09/2011 12:55:54 [23423] [myUser] [ WARN] StatusException: DiffState->GetState(): Error, state not available - code: 12
23/09/2011 12:55:54 [23423] [myUser] [ INFO] User-agent: 'MSFT-SPhone/5.2.5309'
23/09/2011 12:55:54 [23423] [myUser] [DEBUG] ZPush::PrintZPushLegal()
23/09/2011 12:55:54 [23423] [myUser] [DEBUG] -------- End


I cleared out the /usr/lib/z-push folder, and removed the profile from the phone, and restarted with WBXML enabled for my account.

Here is the WBXML debug from the user debug file

23/09/2011 13:24:03 [22603] [myUser] [WBXML] I <Synchronize>
23/09/2011 13:24:03 [22603] [myUser] [WBXML] I <Folders>
23/09/2011 13:24:03 [22603] [myUser] [WBXML] I <Folder>
23/09/2011 13:24:03 [22603] [myUser] [WBXML] I <FolderType>
23/09/2011 13:24:03 [22603] [myUser] [WBXML] I Contacts
23/09/2011 13:24:03 [22603] [myUser] [DEBUG] Sync folder: 'Contacts'
23/09/2011 13:24:03 [22603] [myUser] [WBXML] I </FolderType>
23/09/2011 13:24:03 [22603] [myUser] [WBXML] I <SyncKey>
23/09/2011 13:24:03 [22603] [myUser] [WBXML] I 0
23/09/2011 13:24:03 [22603] [myUser] [WBXML] I </SyncKey>
23/09/2011 13:24:03 [22603] [myUser] [WBXML] I <FolderId>
23/09/2011 13:24:03 [22603] [myUser] [WBXML] I f7
23/09/2011 13:24:03 [22603] [myUser] [WBXML] I </FolderId>
23/09/2011 13:24:03 [22603] [myUser] [WBXML] I </Folder>
23/09/2011 13:24:03 [22603] [myUser] [DEBUG] ZPush::GetAdditionalSyncFolderStore('f7'): 'false'
23/09/2011 13:24:03 [22603] [myUser] [DEBUG] START Setup { store = ; checkACLonly = ; folderid = }
23/09/2011 13:24:03 [22603] [myUser] [DEBUG] END Setup { 89 Folders Loaded }
23/09/2011 13:24:03 [22603] [myUser] [WBXML] I </Folders>
23/09/2011 13:24:03 [22603] [myUser] [WBXML] I </Synchronize>
23/09/2011 13:24:03 [22603] [myUser] [DEBUG] Diffstate->Config: State [] - flags [0]
.....

23/09/2011 13:24:05 [22603] [myUser] [ INFO] ImportChangesDiff->InitializeExporter(): Found '458' changes
23/09/2011 13:24:05 [22603] [myUser] [WBXML] O <Synchronize>
23/09/2011 13:24:05 [22603] [myUser] [WBXML] O <Folders>
23/09/2011 13:24:05 [22603] [myUser] [WBXML] O <Folder>
23/09/2011 13:24:05 [22603] [myUser] [WBXML] O <FolderType>
23/09/2011 13:24:05 [22603] [myUser] [WBXML] O Contacts
23/09/2011 13:24:05 [22603] [myUser] [WBXML] O </FolderType>
23/09/2011 13:24:05 [22603] [myUser] [WBXML] O <SyncKey>
23/09/2011 13:24:05 [22603] [myUser] [WBXML] O {5d62d71d-7970-466f-b402-49526251b43c}1
23/09/2011 13:24:05 [22603] [myUser] [WBXML] O </SyncKey>
23/09/2011 13:24:05 [22603] [myUser] [WBXML] O <FolderId>
23/09/2011 13:24:05 [22603] [myUser] [WBXML] O f7
23/09/2011 13:24:05 [22603] [myUser] [WBXML] O </FolderId>
23/09/2011 13:24:05 [22603] [myUser] [WBXML] O <Status>
23/09/2011 13:24:05 [22603] [myUser] [WBXML] O 1
23/09/2011 13:24:05 [22603] [myUser] [WBXML] O </Status>
23/09/2011 13:24:05 [22603] [myUser] [WBXML] O </Folder>
23/09/2011 13:24:05 [22603] [myUser] [ WARN] StatusException: DiffState->GetState(): Error, state not available - code: 12
23/09/2011 13:24:05 [22603] [myUser] [ INFO] User-agent: 'MSFT-SPhone/5.2.5309'
23/09/2011 13:24:05 [22603] [myUser] [DEBUG] ZPush::PrintZPushLegal()
23/09/2011 13:24:05 [22603] [myUser] [DEBUG] -------- End

[liverpoolfcfan]

Answering my own post ...

I made a change to requestprocessor.php for the handling of first-time sync's, and it appears to have fixed the issue for me. See the linked post for details of the Bug report, and proposed fix.

http://z-push.sourceforge.net/phpbb/viewtopic.php?f=7&t=1600#p5954