ShadowSongTTL
commented
11 years ago OK here is the logfile, i copy & paste for you
19/06/2013 19:36:22 [ 1428] [DEBUG] [cn=sync,dc=tran-ldap] -------- Start 19/06/2013 19:36:22 [ 1428] [ INFO] [cn=sync,dc=tran-ldap] Version='2.0.7-1690' method='OPTIONS' from='192.168.1.1' cmd='' getUser='cn=sync,dc=tran-ldap' devId='IMEI356795024156858' devType='IMEI' 19/06/2013 19:36:22 [ 1428] [DEBUG] [cn=sync,dc=tran-ldap] NoPostRequestException: Options request - code: 1 19/06/2013 19:36:22 [ 1428] [DEBUG] [cn=sync,dc=tran-ldap] Including backend file: '/mnt/DatenHD/Web-Seiten/PHPPush2/backend/ldap.php' 19/06/2013 19:36:22 [ 1428] [DEBUG] [cn=sync,dc=tran-ldap] ZPush::GetSupportedProtocolVersions(): 1.0,2.0,2.1,2.5 19/06/2013 19:36:22 [ 1428] [DEBUG] [cn=sync,dc=tran-ldap] ZPush::GetSupportedCommands(): Sync,SendMail,SmartForward,SmartReply,GetAttachment,GetHierarchy,CreateCollection,DeleteCollection,MoveCollection,FolderSync,FolderCreate,FolderDelete,FolderUpdate,MoveItems,GetItemEstimate,MeetingResponse,ResolveRecipients,ValidateCert,Provision,Search,Ping,Notify 19/06/2013 19:36:22 [ 1428] [ INFO] [cn=sync,dc=tran-ldap] Options request 19/06/2013 19:36:22 [ 1428] [DEBUG] [cn=sync,dc=tran-ldap] -------- End 19/06/2013 19:36:22 [ 1428] [DEBUG] [cn=sync,dc=tran-ldap] -------- Start 19/06/2013 19:36:22 [ 1428] [ INFO] [cn=sync,dc=tran-ldap] Version='2.0.7-1690' method='POST' from='192.168.1.1' cmd='FolderSync' getUser='cn=sync,dc=tran-ldap' devId='IMEI356795024156858' devType='IMEI' 19/06/2013 19:36:22 [ 1428] [DEBUG] [cn=sync,dc=tran-ldap] Used timezone 'Europe/Berlin' 19/06/2013 19:36:22 [ 1428] [DEBUG] [cn=sync,dc=tran-ldap] Including backend file: '/mnt/DatenHD/Web-Seiten/PHPPush2/backend/ldap.php' 19/06/2013 19:36:22 [ 1428] [DEBUG] [cn=sync,dc=tran-ldap] Request::ProcessHeaders() ASVersion: 2.5 19/06/2013 19:36:22 [ 1428] [DEBUG] [cn=sync,dc=tran-ldap] Request::isClientCertAuth() triggered. 19/06/2013 19:36:22 [ 1428] [DEBUG] [cn=sync,dc=tran-ldap] Request::isClientCertAuth() SYNC_REQUIRE_CRT_USERNAME_IN disabled or not set. 19/06/2013 19:36:22 [ 1428] [DEBUG] [cn=sync,dc=tran-ldap] ZPush::CommandNeedsProvisioning(9): true 19/06/2013 19:36:22 [ 1428] [ INFO] [cn=sync,dc=tran-ldap] StateNotFoundException: FileStateMachine->GetStateHash(): Could not locate state '/mnt/DatenHD/Web-Seiten/PHPPush2/var/lib/z-push/8/5/IMEI356795024156858-devicedata' - code: 0 19/06/2013 19:36:22 [ 1428] [DEBUG] [cn=sync,dc=tran-ldap] TopCollector(): Initialized mutexid Resource id #108 and memid Resource id #109. 19/06/2013 19:36:22 [ 1428] [DEBUG] [cn=sync,dc=tran-ldap] LoopDetection(): Initialized mutexid Resource id #111 and memid Resource id #112. 19/06/2013 19:36:22 [ 1428] [DEBUG] [cn=sync,dc=tran-ldap] ZPush::HierarchyCommand(9): true 19/06/2013 19:36:22 [ 1428] [ INFO] [cn=sync,dc=tran-ldap] StateNotFoundException: FileStateMachine->GetStateHash(): Could not locate state '/mnt/DatenHD/Web-Seiten/PHPPush2/var/lib/z-push/8/5/IMEI356795024156858-devicedata' - code: 0 19/06/2013 19:36:22 [ 1428] [DEBUG] [cn=sync,dc=tran-ldap] DeviceManager->ProvisioningRequired('985540186') saved device key '-1': true 19/06/2013 19:36:22 [ 1428] [ INFO] [cn=sync,dc=tran-ldap] ProvisioningRequiredException: Retry after sending a PROVISION command - code: 0 19/06/2013 19:36:22 [ 1428] [ INFO] [cn=sync,dc=tran-ldap] User-agent: 'NokiaE51/3.00(50)MailforExchange' 19/06/2013 19:36:22 [ 1428] [DEBUG] [cn=sync,dc=tran-ldap] DeviceManager->Save(): Device data changed 19/06/2013 19:36:22 [ 1428] [DEBUG] [cn=sync,dc=tran-ldap] LoopDetection->ProcessLoopDetectionTerminate() 19/06/2013 19:36:22 [ 1428] [DEBUG] [cn=sync,dc=tran-ldap] -------- End 19/06/2013 19:36:22 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] -------- Start 19/06/2013 19:36:22 [ 1365] [ INFO] [cn=sync,dc=tran-ldap] Version='2.0.7-1690' method='POST' from='192.168.1.1' cmd='Provision' getUser='cn=sync,dc=tran-ldap' devId='IMEI356795024156858' devType='IMEI' 19/06/2013 19:36:22 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] Used timezone 'Europe/Berlin' 19/06/2013 19:36:22 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] Including backend file: '/mnt/DatenHD/Web-Seiten/PHPPush2/backend/ldap.php' 19/06/2013 19:36:22 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] Request::ProcessHeaders() ASVersion: 2.5 19/06/2013 19:36:22 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] Request::isClientCertAuth() triggered. 19/06/2013 19:36:22 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] Request::isClientCertAuth() SYNC_REQUIRE_CRT_USERNAME_IN disabled or not set. 19/06/2013 19:36:22 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] ZPush::CommandNeedsProvisioning(20): false 19/06/2013 19:36:22 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] ZPush::CommandNeedsAuthentication(20): false 19/06/2013 19:36:22 [ 1365] [ INFO] [cn=sync,dc=tran-ldap] StateNotFoundException: FileStateMachine->GetStateHash(): Could not locate state '/mnt/DatenHD/Web-Seiten/PHPPush2/var/lib/z-push/8/5/IMEI356795024156858-devicedata' - code: 0 19/06/2013 19:36:22 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] TopCollector(): Initialized mutexid Resource id #109 and memid Resource id #110. 19/06/2013 19:36:22 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] LoopDetection(): Initialized mutexid Resource id #112 and memid Resource id #113. 19/06/2013 19:36:22 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] ZPush::HierarchyCommand(20): false 19/06/2013 19:36:22 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] ZPush::GetSupportedProtocolVersions(): 1.0,2.0,2.1,2.5 19/06/2013 19:36:22 [ 1365] [ INFO] [cn=sync,dc=tran-ldap] Announcing latest AS version to device: 1.0,2.0,2.1,2.5 19/06/2013 19:36:22 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] ZPush::CommandNeedsPlainInput(20): false 19/06/2013 19:36:22 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] RequestProcessor::HandleProvision(): Forcing delayed Authentication 19/06/2013 19:36:23 [ 1365] [ INFO] [cn=sync,dc=tran-ldap] BackendLDAP->Logon(): User 'cn=Sync,dc=Tran-LDAP' is authenticated on LDAP 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] WBXMLEncoder->startWBXML() type: vnd.ms-sync.wbxml 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] WBXMLEncoder->endTag() WBXML output completed 19/06/2013 19:36:23 [ 1365] [ INFO] [cn=sync,dc=tran-ldap] BackendLDAP->Logoff(): Disconnection successfull. 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] DeviceManager->Save(): Device data changed 19/06/2013 19:36:23 [ 1365] [ INFO] [cn=sync,dc=tran-ldap] Linking device ID 'IMEI356795024156858' to user 'cn=sync,dc=tran-ldap' 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] SimpleMutex(): Initialized mutexid Resource id #129 and memid Resource id #130. 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] FileStateMachine->LinkUserDevice(): wrote 71 bytes to users file 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] FileStateMachine->SetState() written 498 bytes on file: '/mnt/DatenHD/Web-Seiten/PHPPush2/var/lib/z-push/8/5/IMEI356795024156858-devicedata' 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] DeviceManager->Save(): Device data saved 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] LoopDetection->ProcessLoopDetectionTerminate() 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] -------- End 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] -------- Start 19/06/2013 19:36:23 [ 1365] [ INFO] [cn=sync,dc=tran-ldap] Version='2.0.7-1690' method='POST' from='192.168.1.1' cmd='Provision' getUser='cn=sync,dc=tran-ldap' devId='IMEI356795024156858' devType='IMEI' 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] Used timezone 'Europe/Berlin' 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] Including backend file: '/mnt/DatenHD/Web-Seiten/PHPPush2/backend/ldap.php' 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] Request::ProcessHeaders() ASVersion: 2.5 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] Request::isClientCertAuth() triggered. 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] Request::isClientCertAuth() SYNC_REQUIRE_CRT_USERNAME_IN disabled or not set. 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] ZPush::CommandNeedsProvisioning(20): false 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] ZPush::CommandNeedsAuthentication(20): false 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] FileStateMachine->GetState() on file: '/mnt/DatenHD/Web-Seiten/PHPPush2/var/lib/z-push/8/5/IMEI356795024156858-devicedata' 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] ASDevice data loaded for user: 'cn=sync,dc=tran-ldap' 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] TopCollector(): Initialized mutexid Resource id #111 and memid Resource id #112. 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] LoopDetection(): Initialized mutexid Resource id #114 and memid Resource id #115. 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] ZPush::HierarchyCommand(20): false 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] ZPush::CommandNeedsPlainInput(20): false 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] RequestProcessor::HandleProvision(): Forcing delayed Authentication 19/06/2013 19:36:23 [ 1365] [ INFO] [cn=sync,dc=tran-ldap] BackendLDAP->Logon(): User 'cn=Sync,dc=Tran-LDAP' is authenticated on LDAP 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] WBXMLEncoder->startWBXML() type: vnd.ms-sync.wbxml 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] DeviceManager->SetPolicyKey('502847838') 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] WBXMLEncoder->endTag() WBXML output completed 19/06/2013 19:36:23 [ 1365] [ INFO] [cn=sync,dc=tran-ldap] BackendLDAP->Logoff(): Disconnection successfull. 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] DeviceManager->Save(): Device data changed 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] FileStateMachine->SetState() written 549 bytes on file: '/mnt/DatenHD/Web-Seiten/PHPPush2/var/lib/z-push/8/5/IMEI356795024156858-devicedata' 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] DeviceManager->Save(): Device data saved 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] LoopDetection->ProcessLoopDetectionTerminate() 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] -------- End 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] -------- Start 19/06/2013 19:36:23 [ 1365] [ INFO] [cn=sync,dc=tran-ldap] Version='2.0.7-1690' method='POST' from='192.168.1.1' cmd='FolderSync' getUser='cn=sync,dc=tran-ldap' devId='IMEI356795024156858' devType='IMEI' 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] Used timezone 'Europe/Berlin' 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] Including backend file: '/mnt/DatenHD/Web-Seiten/PHPPush2/backend/ldap.php' 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] Request::ProcessHeaders() ASVersion: 2.5 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] Request::isClientCertAuth() triggered. 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] Request::isClientCertAuth() SYNC_REQUIRE_CRT_USERNAME_IN disabled or not set. 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] ZPush::CommandNeedsProvisioning(9): true 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] FileStateMachine->GetState() on file: '/mnt/DatenHD/Web-Seiten/PHPPush2/var/lib/z-push/8/5/IMEI356795024156858-devicedata' 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] ASDevice data loaded for user: 'cn=sync,dc=tran-ldap' 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] TopCollector(): Initialized mutexid Resource id #110 and memid Resource id #111. 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] LoopDetection(): Initialized mutexid Resource id #113 and memid Resource id #114. 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] ZPush::HierarchyCommand(9): true 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] DeviceManager->ProvisioningRequired('502847838') saved device key '502847838': false 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] ZPush::CommandNeedsAuthentication(9): true 19/06/2013 19:36:23 [ 1365] [ INFO] [cn=sync,dc=tran-ldap] BackendLDAP->Logon(): User 'cn=Sync,dc=Tran-LDAP' is authenticated on LDAP 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] ZPush::CommandNeedsPlainInput(9): false 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] ZPush::GetSupportedProtocolVersions(): 1.0,2.0,2.1,2.5 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] ZPush::GetSupportedCommands(): Sync,SendMail,SmartForward,SmartReply,GetAttachment,GetHierarchy,CreateCollection,DeleteCollection,MoveCollection,FolderSync,FolderCreate,FolderDelete,FolderUpdate,MoveItems,GetItemEstimate,MeetingResponse,ResolveRecipients,ValidateCert,Provision,Search,Ping,Notify 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] ASDevice->GetHierarchyCache(): HierarchyCache is up - Cached objects: 0 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] WBXMLEncoder->startWBXML() type: vnd.ms-sync.wbxml 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] Initializing folder diff engine 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] ExportChangesDiff->InitializeExporter(): Initializing folder diff engine 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] BackendLDAP->GetFolderList(): Getting all folders. 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] BackendLDAP->StatFolder('ou=Adressen,dc=Tran-LDAP') 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] BackendLDAP->GetFolder('ou=Adressen,dc=Tran-LDAP') 19/06/2013 19:36:23 [ 1365] [ INFO] [cn=sync,dc=tran-ldap] ExportChangesDiff->InitializeExporter(): Found '1' changes 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] BackendLDAP->GetFolder('ou=Adressen,dc=Tran-LDAP') 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] BackendLDAP->StatFolder('ou=Adressen,dc=Tran-LDAP') 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] BackendLDAP->GetFolder('ou=Adressen,dc=Tran-LDAP') 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] HierarchyCache: AddFolder() serverid: ou=Adressen,dc=Tran-LDAP displayname: ou=Adressen,dc=Tran-LDAP 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] StateManager::linkState(#ASDevice, '3d8c7f02-85d4-4205-b58f-63a1ee991ccf','HierarchyCache'): linked to uuid '3d8c7f02-85d4-4205-b58f-63a1ee991ccf'. 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] ASDevice->GetHierarchyCache(): HierarchyCache is up - Cached objects: 1 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] FileStateMachine->SetState() written 286 bytes on file: '/mnt/DatenHD/Web-Seiten/PHPPush2/var/lib/z-push/8/5/IMEI356795024156858-3d8c7f02-85d4-4205-b58f-63a1ee991ccf-hc-1' 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] FileStateMachine->SetState() written 120 bytes on file: '/mnt/DatenHD/Web-Seiten/PHPPush2/var/lib/z-push/8/5/IMEI356795024156858-3d8c7f02-85d4-4205-b58f-63a1ee991ccf-1' 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] WBXMLEncoder->endTag() WBXML output completed 19/06/2013 19:36:23 [ 1365] [ INFO] [cn=sync,dc=tran-ldap] BackendLDAP->Logoff(): Disconnection successfull. 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] DeviceManager->Save(): Device data changed 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] FileStateMachine->SetState() written 714 bytes on file: '/mnt/DatenHD/Web-Seiten/PHPPush2/var/lib/z-push/8/5/IMEI356795024156858-devicedata' 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] DeviceManager->Save(): Device data saved 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] LoopDetection->ProcessLoopDetectionTerminate() 19/06/2013 19:36:23 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] -------- End 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] -------- Start 19/06/2013 19:36:24 [ 1365] [ INFO] [cn=sync,dc=tran-ldap] Version='2.0.7-1690' method='POST' from='192.168.1.1' cmd='Sync' getUser='cn=sync,dc=tran-ldap' devId='IMEI356795024156858' devType='IMEI' 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] Used timezone 'Europe/Berlin' 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] Including backend file: '/mnt/DatenHD/Web-Seiten/PHPPush2/backend/ldap.php' 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] Request::ProcessHeaders() ASVersion: 2.5 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] Request::isClientCertAuth() triggered. 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] Request::isClientCertAuth() SYNC_REQUIRE_CRT_USERNAME_IN disabled or not set. 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] ZPush::CommandNeedsProvisioning(0): true 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] FileStateMachine->GetState() on file: '/mnt/DatenHD/Web-Seiten/PHPPush2/var/lib/z-push/8/5/IMEI356795024156858-devicedata' 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] ASDevice data loaded for user: 'cn=sync,dc=tran-ldap' 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] TopCollector(): Initialized mutexid Resource id #110 and memid Resource id #111. 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] LoopDetection(): Initialized mutexid Resource id #113 and memid Resource id #114. 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] ZPush::HierarchyCommand(0): false 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] DeviceManager->ProvisioningRequired('502847838') saved device key '502847838': false 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] ZPush::CommandNeedsAuthentication(0): true 19/06/2013 19:36:24 [ 1365] [ INFO] [cn=sync,dc=tran-ldap] BackendLDAP->Logon(): User 'cn=Sync,dc=Tran-LDAP' is authenticated on LDAP 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] ZPush::CommandNeedsPlainInput(0): false 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] Sync folder: 'Contacts' 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] SyncParameters->RemoveSyncKey(): saved sync key removed 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] HandleSync(): Start Output 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] WBXMLEncoder->startWBXML() type: vnd.ms-sync.wbxml 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] Sync->loadStates(): loading states for folder 'ou=Adressen,dc=Tran-LDAP' 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] ZPush::GetAdditionalSyncFolderStore('ou=Adressen,dc=Tran-LDAP'): 'false' 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] ExportChangesDiff->InitializeExporter(): Initializing message diff engine. '0' messages in state 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] BackendLDAP->GetMessageList('ou=Adressen,dc=Tran-LDAP','0') 19/06/2013 19:36:24 [ 1365] [ WARN] [cn=sync,dc=tran-ldap] /mnt/DatenHD/Web-Seiten/PHPPush2/backend/ldap.php:130 Undefined offset: 1 (8) 19/06/2013 19:36:24 [ 1365] [ WARN] [cn=sync,dc=tran-ldap] /mnt/DatenHD/Web-Seiten/PHPPush2/backend/ldap.php:131 ldap_list(): Search: No such object (2) 19/06/2013 19:36:24 [ 1365] [ WARN] [cn=sync,dc=tran-ldap] /mnt/DatenHD/Web-Seiten/PHPPush2/backend/ldap.php:132 ldap_count_entries() expects parameter 2 to be resource, boolean given (2) 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] BackendLDAP->GetMessageList(): Got contacts in base_dn ''. 19/06/2013 19:36:24 [ 1365] [ WARN] [cn=sync,dc=tran-ldap] /mnt/DatenHD/Web-Seiten/PHPPush2/backend/ldap.php:133 ldap_get_entries() expects parameter 2 to be resource, boolean given (2) 19/06/2013 19:36:24 [ 1365] [ INFO] [cn=sync,dc=tran-ldap] ExportChangesDiff->InitializeExporter(): Found '0' changes 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] Folder type: Contacts 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] StateManager::linkState(#ASDevice, 'bcae2108-0b78-40d5-bf3c-d3803dc5e0e8','ou=Adressen,dc=Tran-LDAP'): linked to uuid 'bcae2108-0b78-40d5-bf3c-d3803dc5e0e8'. 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] FileStateMachine->SetState() written 6 bytes on file: '/mnt/DatenHD/Web-Seiten/PHPPush2/var/lib/z-push/8/5/IMEI356795024156858-bcae2108-0b78-40d5-bf3c-d3803dc5e0e8-1' 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] SyncCollections->SaveCollection(): Data of folder 'ou=Adressen,dc=Tran-LDAP' changed 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] FileStateMachine->SetState() written 574 bytes on file: '/mnt/DatenHD/Web-Seiten/PHPPush2/var/lib/z-push/8/5/IMEI356795024156858-bcae2108-0b78-40d5-bf3c-d3803dc5e0e8-fd' 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] WBXMLEncoder->endTag() WBXML output completed 19/06/2013 19:36:24 [ 1365] [ INFO] [cn=sync,dc=tran-ldap] BackendLDAP->Logoff(): Disconnection successfull. 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] DeviceManager->Save(): Device data changed 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] FileStateMachine->SetState() written 762 bytes on file: '/mnt/DatenHD/Web-Seiten/PHPPush2/var/lib/z-push/8/5/IMEI356795024156858-devicedata' 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] DeviceManager->Save(): Device data saved 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] LoopDetection->ProcessLoopDetectionTerminate() 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] -------- End 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] -------- Start 19/06/2013 19:36:24 [ 1365] [ INFO] [cn=sync,dc=tran-ldap] Version='2.0.7-1690' method='POST' from='192.168.1.1' cmd='Sync' getUser='cn=sync,dc=tran-ldap' devId='IMEI356795024156858' devType='IMEI' 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] Used timezone 'Europe/Berlin' 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] Including backend file: '/mnt/DatenHD/Web-Seiten/PHPPush2/backend/ldap.php' 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] Request::ProcessHeaders() ASVersion: 2.5 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] Request::isClientCertAuth() triggered. 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] Request::isClientCertAuth() SYNC_REQUIRE_CRT_USERNAME_IN disabled or not set. 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] ZPush::CommandNeedsProvisioning(0): true 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] FileStateMachine->GetState() on file: '/mnt/DatenHD/Web-Seiten/PHPPush2/var/lib/z-push/8/5/IMEI356795024156858-devicedata' 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] ASDevice data loaded for user: 'cn=sync,dc=tran-ldap' 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] TopCollector(): Initialized mutexid Resource id #110 and memid Resource id #111. 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] LoopDetection(): Initialized mutexid Resource id #113 and memid Resource id #114. 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] ZPush::HierarchyCommand(0): false 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] DeviceManager->ProvisioningRequired('502847838') saved device key '502847838': false 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] ZPush::CommandNeedsAuthentication(0): true 19/06/2013 19:36:24 [ 1365] [ INFO] [cn=sync,dc=tran-ldap] BackendLDAP->Logon(): User 'cn=Sync,dc=Tran-LDAP' is authenticated on LDAP 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] ZPush::CommandNeedsPlainInput(0): false 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] Sync folder: 'Contacts' 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] FileStateMachine->GetState() on file: '/mnt/DatenHD/Web-Seiten/PHPPush2/var/lib/z-push/8/5/IMEI356795024156858-bcae2108-0b78-40d5-bf3c-d3803dc5e0e8-fd' 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] SyncParameters->UseCPO('DEFAULT') 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] SyncParameters->UseCPO('DEFAULT') 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] HandleSync(): Start Output 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] WBXMLEncoder->startWBXML() type: vnd.ms-sync.wbxml 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] Sync->loadStates(): loading states for folder 'ou=Adressen,dc=Tran-LDAP' 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] FileStateMachine->GetState() on file: '/mnt/DatenHD/Web-Seiten/PHPPush2/var/lib/z-push/8/5/IMEI356795024156858-bcae2108-0b78-40d5-bf3c-d3803dc5e0e8-1' 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] ZPush::GetAdditionalSyncFolderStore('ou=Adressen,dc=Tran-LDAP'): 'false' 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] ExportChangesDiff->InitializeExporter(): Initializing message diff engine. '0' messages in state 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] BackendLDAP->GetMessageList('ou=Adressen,dc=Tran-LDAP','0') 19/06/2013 19:36:24 [ 1365] [ WARN] [cn=sync,dc=tran-ldap] /mnt/DatenHD/Web-Seiten/PHPPush2/backend/ldap.php:130 Undefined offset: 1 (8) 19/06/2013 19:36:24 [ 1365] [ WARN] [cn=sync,dc=tran-ldap] /mnt/DatenHD/Web-Seiten/PHPPush2/backend/ldap.php:131 ldap_list(): Search: No such object (2) 19/06/2013 19:36:24 [ 1365] [ WARN] [cn=sync,dc=tran-ldap] /mnt/DatenHD/Web-Seiten/PHPPush2/backend/ldap.php:132 ldap_count_entries() expects parameter 2 to be resource, boolean given (2) 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] BackendLDAP->GetMessageList(): Got contacts in base_dn ''. 19/06/2013 19:36:24 [ 1365] [ WARN] [cn=sync,dc=tran-ldap] /mnt/DatenHD/Web-Seiten/PHPPush2/backend/ldap.php:133 ldap_get_entries() expects parameter 2 to be resource, boolean given (2) 19/06/2013 19:36:24 [ 1365] [ INFO] [cn=sync,dc=tran-ldap] ExportChangesDiff->InitializeExporter(): Found '0' changes 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] Folder type: Contacts 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] SyncCollections->SaveCollection(): Data of folder 'ou=Adressen,dc=Tran-LDAP' changed 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] FileStateMachine->SetState() written 636 bytes on file: '/mnt/DatenHD/Web-Seiten/PHPPush2/var/lib/z-push/8/5/IMEI356795024156858-bcae2108-0b78-40d5-bf3c-d3803dc5e0e8-fd' 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] WBXMLEncoder->endTag() WBXML output completed 19/06/2013 19:36:24 [ 1365] [ INFO] [cn=sync,dc=tran-ldap] BackendLDAP->Logoff(): Disconnection successfull. 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] LoopDetection->ProcessLoopDetectionTerminate() 19/06/2013 19:36:24 [ 1365] [DEBUG] [cn=sync,dc=tran-ldap] -------- End
Hello, i get my mobile phone (Nokia E51) syncing. But i got problems with the LDAP_BASE_DNS variable. Since i use a ou hierarchy in my OpenLDAP server, i cannot get sync with full path. For example: ou=Adressen,ou=Buch,dc=tran-ldap then i got warning in logfile and no contacts are synced. If i put Contacts to toplevel ou=Adressen (without ou subunits) then i from time to time i get sync. But even here warning in the logfile. how can i submit logfile ? can you help me please ? thank you