Project

General

Profile

Actions

Bug #38

closed

"No attributes" error with Fanvil X3U

Added by Jörg Ebeling almost 3 years ago. Updated over 2 years ago.

Status:
Closed
Priority:
High
Assignee:
Target version:
Start date:
07/20/2021
Due date:
07/21/2021
% Done:

100%

Estimated time:
4:00 h

Description

Fanvil X3U (FW: 2.4.6) does not request specific attributes during an (objectclass=*) request.
L2CPBG respond with a "No attributes (possibliy after cleanup of unconfigured ldap attribute mappings)!" error (see Fanvil Unterstuetzung/Support).

This L2CPBG response is wrong as per LDAP definition an search request without specific listed attributes, has to return all available user- attributes.


Files

contact.vcf (2.23 KB) contact.vcf CardDAV server address book entries Markus Jessl, 07/22/2021 06:52 AM
PhoneDetail.jpg (517 KB) PhoneDetail.jpg Markus Jessl, 07/22/2021 07:41 AM
Phone.jpg (492 KB) Phone.jpg Markus Jessl, 07/22/2021 07:41 AM
l2cpbg_0.9.2beta4_windows-32bit.zip (8.87 MB) l2cpbg_0.9.2beta4_windows-32bit.zip Jörg Ebeling, 07/27/2021 11:23 PM
l2cpbg_0.9.2beta4_windows-64bit.zip (9.06 MB) l2cpbg_0.9.2beta4_windows-64bit.zip Jörg Ebeling, 07/27/2021 11:23 PM

Related issues 1 (0 open1 closed)

Copied to Support #39: Unidentified LDAP request of Fanvil X3UClosedJörg Ebeling07/28/2021

Actions
Actions #1

Updated by Jörg Ebeling almost 3 years ago

  • Status changed from New to In Progress
Actions #2

Updated by Jörg Ebeling almost 3 years ago

  • Subject changed from "No attributes" error @ Fanvil X3U to "No attributes" error with Fanvil X3U
Actions #3

Updated by Jörg Ebeling almost 3 years ago

  • File l2cpbg_0.9.2beta2_windows-32bit.zip added
  • File l2cpbg_0.9.2beta2_windows-64bit.zip added
  • Status changed from In Progress to Feedback
  • % Done changed from 0 to 50

Good morning Markus,

you phone's debug output was really helpful, but it's becoming complicated ;-)

Your phone tried an:

ldapsearch -P 3 -h 192.168.xxx.xxx -p 1389 -b "dc=xxx, dc=local" -s one -l 60 -LLL -N -D "cn=phone" -w xxx > /tmp/2751005888.ldif

which is a very simple "give me ALL entries from 'dc=xxx, dc=local' (single entries, without diving into subtrees)".
Even if it's a very simple LDAP search request, it might result in a very large response (imagine some thousand contacts, with a lot of data like contact-photos, addresses and all the stuff, a phone is normally not interested in).
So I can't really imagine why it's doing such expensive search request (mostly expensive for the phone, not for my gateway).
The only reason I can imagine at the moment, is that it's only simply trying if the LDAP server handles search requests to the configured LDAP-Base 'dc=xxx, dc=local' (and doesn't really use the result).

Unfortunately I don't have a Fanvil phone like yours.

So I need some more debugging help from you.

I prepared a new beta2 of the upcoming 0.9.2 version where this special LDAP search request doesn't produce an error anymore. Instead of, it returns all (internal) uid's of the contacts, but not all user-attributes.
Please be so kind and check what happen with this version.
You phone's debug log should also not fail with

D/vcore.contact.ldapparse | ldapFileReadLdif: get ldif file size[0]
E/vcore.contact.ldapparse | ldapFileParse: ldapFileReadLdif error.

anymore after the "ldapsearch".

With this new beta I hope for a second (or more) "ldapsearch" command(s) from your phone.

If not, please add in the phone's "LDAP Settings", to the fields "Name Attr" as well as "Display name" the ldap attribute "uid" and do another search.

BTW: If you check the Readme of this new beta, you might recognize the new "Service Manager". You don't need to try it, it's not yet working for Windows.

Actions #4

Updated by Jörg Ebeling almost 3 years ago

  • File l2cpbg_0.9.2beta3_windows-32bit.zip added
  • File l2cpbg_0.9.2beta3_windows-64bit.zip added
  • % Done changed from 50 to 60

Regarding forum response RE: Fanvil Unterstuetzung/Support

Beta2 had a silly typo mistake why it doesn't responsed with the expected result.
Please see/test version 0.9.2-beta-3.

It shall display now the VCARD uid's within the phones display (when uid get added to "Name Attr" as well as "Display name").
If not, please drop me the relevant log parts.

If possible, please followup here in the ticket area via the "Bearbeiten" or "Edit" link at the bottom.

Actions #5

Updated by Jörg Ebeling almost 3 years ago

  • File deleted (l2cpbg_0.9.2beta2_windows-64bit.zip)
Actions #6

Updated by Jörg Ebeling almost 3 years ago

  • File deleted (l2cpbg_0.9.2beta2_windows-32bit.zip)
Actions #7

Updated by Markus Jessl almost 3 years ago

Thx a million for the new beta-3 version.

I had a quick look and it seems to do the trick. I can get the phone book. :)

Find below the logs and a small bug.

Actions #8

Updated by Markus Jessl almost 3 years ago

Used address book entries on the CardDAV server:

See attached "contact.vcf:

L2CPBG startup log:

l2cpbg.exe 12:54:54 [INF] LDAP-2-CardDAV (1 way read) Phone Book Gateway version 0.9.2beta3 12:54:54 [INF] Loaded config './l2cpbg.conf' 12:54:54 [TRC] [config] buildLdapMap() 12:54:54 [DBG] [config] ldapAttributes []string{"c", "cn", "company", "department", "facsimileTelephoneNumber", "givenName", "homePhone", "l", "mail", "mobile", "postalAddress", "postalCode", "sn", "street", "telephoneNumber"} 12:54:54 [TRC] [config] Preparing LdapMap for 'ldap.map.c' entries... 12:54:54 [TRC] [config] Preparing LdapMap for 'ldap.map.cn' entries... 12:54:54 [TRC] [config] Preparing LdapMap for 'ldap.map.company' entries... 12:54:54 [TRC] [config] Preparing LdapMap for 'ldap.map.department' entries... 12:54:54 [TRC] [config] Preparing LdapMap for 'ldap.map.facsimileTelephoneNumber' entries... 12:54:54 [TRC] [config] Preparing LdapMap for 'ldap.map.givenName' entries... 12:54:54 [TRC] [config] Preparing LdapMap for 'ldap.map.homePhone' entries... 12:54:54 [TRC] [config] Preparing LdapMap for 'ldap.map.l' entries... 12:54:54 [TRC] [config] Preparing LdapMap for 'ldap.map.mail' entries... 12:54:54 [TRC] [config] Preparing LdapMap for 'ldap.map.mobile' entries... 12:54:54 [TRC] [config] Preparing LdapMap for 'ldap.map.postalAddress' entries... 12:54:54 [TRC] [config] Preparing LdapMap for 'ldap.map.postalCode' entries... 12:54:54 [TRC] [config] Preparing LdapMap for 'ldap.map.sn' entries... 12:54:54 [TRC] [config] Preparing LdapMap for 'ldap.map.street' entries... 12:54:54 [TRC] [config] Preparing LdapMap for 'ldap.map.telephoneNumber' entries... 12:54:54 [TRC] [intern] ReadLicense() 12:54:54 [INF] [intern] * Unregistered free version with approx. 12 requests per hour * 12:54:54 [TRC] [ db ] Open() 12:54:54 [INF] [ db ] DB directory './l2cpbg.db' 12:54:54 [INF] [badger] All 0 tables opened in 0s 12:54:54 [TRC] [ db ] checkDbVersion() 12:54:54 [INF] [ db ] Found no 'cdbver' key. Assume new database. Creating a new one 12:54:54 [TRC] [server] Listen() 12:54:54 [INF] [client] Syncing with CardDAV server 'https://192.168.xxx.xxx:xxx/carddav/pbx01'... 12:54:54 [TRC] [server] checkLdapServerConfigEqualsDb() 12:54:54 [TRC] [client] checkDavServerConfigEqualsDb() 12:54:54 [DBG] [server] ldapConfigCompString "dc=xxx, dc=local\n49\n36961\n0\n7\ntrue\nDE\n3" 12:54:54 [TRC] [ db ] valueOfKeyEquals() 12:54:54 [INF] [ db ] Found no 'cdavsrv' key. Assume new database. Creating a new one 12:54:54 [TRC] [ db ] valueOfKeyEquals() 12:54:54 [INF] [ db ] Found no 'cldapStructure' key. Assume new database. Creating a new one 12:54:54 [INF] [client] New Database or CardDav- Server or User has changed. Dropping old records... 12:54:54 [TRC] [ db ] deletePrefix('s') 12:54:54 [INF] [server] LDAP or Location settings have changed... 12:54:54 [TRC] [ card ] ReindexCards() 12:54:54 [TRC] [ db ] deletePrefix('C') 12:54:54 [TRC] [ db ] deletePrefix('L') 12:54:54 [TRC] [ db ] deletePrefix('L') 12:54:54 [TRC] [ db ] deletePrefix('p') 12:54:54 [TRC] [ db ] deletePrefix('l') 12:54:54 [TRC] [ db ] deletePrefix('l') 12:54:54 [TRC] [ db ] deletePrefix('e') 12:54:54 [TRC] [ db ] deletePrefix('e') 12:54:54 [TRC] [ db ] Set('cdavsrv', ...) 12:54:54 [TRC] [ db ] deletePrefix('p') 12:54:54 [TRC] [ db ] Set('cdavusr', ...) 12:54:54 [TRC] [ db ] Set('cldapStructure', ...) 12:54:54 [TRC] [ db ] Set('cdavbooks', ...) 12:54:54 [INF] [server] Start LDAP server on 192.168.xxx.xxx:1389 12:54:54 [TRC] [client] new() 12:54:55 [DBG] [client] Principal of user 'pbx01' = '/carddav/pbx01/' 12:54:56 [DBG] [client] Address book home = '/carddav/pbx01/' 12:54:56 [TRC] [client] getMatchingAddressBooks() 12:54:56 [INF] [client] Found matching AddressBook 'testaddressbook' 12:54:56 [WRN] [client] Skipped AddressBook 'xxx@xxx.de' because it does not match config option dav.addressbooks 'testaddressbook' 12:54:56 [TRC] [ db ] Get('stestaddressbook') 12:54:56 [DBG] [client] Got Sync-Token '' for AddressBook 'testaddressbook' from db 12:54:56 [INF] [client] Found no Sync-Token for AddressBook 'testaddressbook'. Assume new AddressBook, initiate full load... 12:54:56 [INF] [client] Loading AddressBook 'testaddressbook'... 12:54:56 [TRC] [client] loadAddressesByChunks('testaddressbook'...) 12:54:57 [INF] [client] Received contact 1/12 (chunk 1/1) '/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/d242ab6c-c76f-45f3-802c-7bf8e6d5b3d5.vcf' 12:54:57 [DBG] [ vcf ] New VCF UID 'd242ab6c-c76f-45f3-802c-7bf8e6d5b3d5' 12:54:57 [TRC] [ vcf ] Save() 12:54:57 [INF] [ vcf ] Saving VCF '/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/d242ab6c-c76f-45f3-802c-7bf8e6d5b3d5.vcf' 12:54:57 [DBG] [ card ] New vCard UID 'd242ab6c-c76f-45f3-802c-7bf8e6d5b3d5' 12:54:57 [TRC] [ card ] Save() 12:54:57 [INF] [ card ] Saving vCard 'd242ab6c-c76f-45f3-802c-7bf8e6d5b3d5' 12:54:57 [TRC] [ card ] Delete('d242ab6c-c76f-45f3-802c-7bf8e6d5b3d5') 12:54:57 [DBG] [ card ] Deleted key=Cd242ab6c-c76f-45f3-802c-7bf8e6d5b3d5 12:54:57 [DBG] [ card ] Deleted key=Ld242ab6c-c76f-45f3-802c-7bf8e6d5b3d5 12:54:57 [TRC] [ db ] Set('Cd242ab6c-c76f-45f3-802c-7bf8e6d5b3d5', ...) 12:54:57 [DBG] [ card ] Saved vcard UID 'd242ab6c-c76f-45f3-802c-7bf8e6d5b3d5' 12:54:57 [TRC] [ card ] UpdateIndexKeys() 12:54:57 [TRC] [ card ] mergeAppleAddressBookExtensionToType() 12:54:57 [TRC] [ db ] Set('e+491511111112.d242ab6c-c76f-45f3-802c-7bf8e6d5b3d5', ...) 12:54:57 [DBG] [ card ] Saved key 'e+491511111112.d242ab6c-c76f-45f3-802c-7bf8e6d5b3d5' = 'd242ab6c-c76f-45f3-802c-7bf8e6d5b3d5' 12:54:57 [TRC] [ db ] Set('lsn.lastname2.d242ab6c-c76f-45f3-802c-7bf8e6d5b3d5', ...) 12:54:57 [DBG] [ card ] Saved key 'lsn.lastname2.d242ab6c-c76f-45f3-802c-7bf8e6d5b3d5' = 'd242ab6c-c76f-45f3-802c-7bf8e6d5b3d5' 12:54:57 [TRC] [ db ] Set('lcn.firstname2 lastname2.d242ab6c-c76f-45f3-802c-7bf8e6d5b3d5', ...) 12:54:57 [DBG] [ card ] Saved key 'lcn.firstname2 lastname2.d242ab6c-c76f-45f3-802c-7bf8e6d5b3d5' = 'd242ab6c-c76f-45f3-802c-7bf8e6d5b3d5' 12:54:57 [TRC] [ db ] Set('e+493011111112.d242ab6c-c76f-45f3-802c-7bf8e6d5b3d5', ...) 12:54:57 [DBG] [ card ] Saved key 'e+493011111112.d242ab6c-c76f-45f3-802c-7bf8e6d5b3d5' = 'd242ab6c-c76f-45f3-802c-7bf8e6d5b3d5' 12:54:57 [TRC] [ db ] Set('lgivenName.firstname2.d242ab6c-c76f-45f3-802c-7bf8e6d5b3d5', ...) 12:54:57 [DBG] [ card ] Saved key 'lgivenName.firstname2.d242ab6c-c76f-45f3-802c-7bf8e6d5b3d5' = 'd242ab6c-c76f-45f3-802c-7bf8e6d5b3d5' 12:54:57 [TRC] [ db ] Set('Ld242ab6c-c76f-45f3-802c-7bf8e6d5b3d5', ...) 12:54:57 [DBG] [ vcf ] Saved UID 'd242ab6c-c76f-45f3-802c-7bf8e6d5b3d5' 12:54:57 [TRC] [ db ] Set('p/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/d242ab6c-c76f-45f3-802c-7bf8e6d5b3d5.vcf', ...) 12:54:57 [DBG] [ vcf ] Saved UID 'd242ab6c-c76f-45f3-802c-7bf8e6d5b3d5' by path '/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/d242ab6c-c76f-45f3-802c-7bf8e6d5b3d5.vcf 12:54:57 [INF] [client] Received contact 2/12 (chunk 1/1) '/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/d61239b9-a214-4572-b409-f83e3573a6bf.vcf' 12:54:57 [DBG] [ vcf ] New VCF UID 'd61239b9-a214-4572-b409-f83e3573a6bf' 12:54:57 [TRC] [ vcf ] Save() 12:54:57 [INF] [ vcf ] Saving VCF '/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/d61239b9-a214-4572-b409-f83e3573a6bf.vcf' 12:54:57 [DBG] [ card ] New vCard UID 'd61239b9-a214-4572-b409-f83e3573a6bf' 12:54:57 [TRC] [ card ] Save() 12:54:57 [INF] [ card ] Saving vCard 'd61239b9-a214-4572-b409-f83e3573a6bf' 12:54:57 [TRC] [ card ] Delete('d61239b9-a214-4572-b409-f83e3573a6bf') 12:54:57 [DBG] [ card ] Deleted key=Cd61239b9-a214-4572-b409-f83e3573a6bf 12:54:57 [DBG] [ card ] Deleted key=Ld61239b9-a214-4572-b409-f83e3573a6bf 12:54:57 [TRC] [ db ] Set('Cd61239b9-a214-4572-b409-f83e3573a6bf', ...) 12:54:57 [DBG] [ card ] Saved vcard UID 'd61239b9-a214-4572-b409-f83e3573a6bf' 12:54:57 [TRC] [ card ] UpdateIndexKeys() 12:54:57 [TRC] [ card ] mergeAppleAddressBookExtensionToType() 12:54:57 [TRC] [ db ] Set('e+491511111117.d61239b9-a214-4572-b409-f83e3573a6bf', ...) 12:54:57 [DBG] [ card ] Saved key 'e+491511111117.d61239b9-a214-4572-b409-f83e3573a6bf' = 'd61239b9-a214-4572-b409-f83e3573a6bf' 12:54:57 [TRC] [ db ] Set('lsn.lastname7.d61239b9-a214-4572-b409-f83e3573a6bf', ...) 12:54:57 [DBG] [ card ] Saved key 'lsn.lastname7.d61239b9-a214-4572-b409-f83e3573a6bf' = 'd61239b9-a214-4572-b409-f83e3573a6bf' 12:54:57 [TRC] [ db ] Set('lcn.firstname7 lastname7.d61239b9-a214-4572-b409-f83e3573a6bf', ...) 12:54:57 [DBG] [ card ] Saved key 'lcn.firstname7 lastname7.d61239b9-a214-4572-b409-f83e3573a6bf' = 'd61239b9-a214-4572-b409-f83e3573a6bf' 12:54:57 [TRC] [ db ] Set('lgivenName.firstname7.d61239b9-a214-4572-b409-f83e3573a6bf', ...) 12:54:57 [DBG] [ card ] Saved key 'lgivenName.firstname7.d61239b9-a214-4572-b409-f83e3573a6bf' = 'd61239b9-a214-4572-b409-f83e3573a6bf' 12:54:57 [TRC] [ db ] Set('e+493011111117.d61239b9-a214-4572-b409-f83e3573a6bf', ...) 12:54:57 [DBG] [ card ] Saved key 'e+493011111117.d61239b9-a214-4572-b409-f83e3573a6bf' = 'd61239b9-a214-4572-b409-f83e3573a6bf' 12:54:57 [TRC] [ db ] Set('Ld61239b9-a214-4572-b409-f83e3573a6bf', ...) 12:54:57 [DBG] [ vcf ] Saved UID 'd61239b9-a214-4572-b409-f83e3573a6bf' 12:54:57 [TRC] [ db ] Set('p/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/d61239b9-a214-4572-b409-f83e3573a6bf.vcf', ...) 12:54:57 [DBG] [ vcf ] Saved UID 'd61239b9-a214-4572-b409-f83e3573a6bf' by path '/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/d61239b9-a214-4572-b409-f83e3573a6bf.vcf 12:54:57 [INF] [client] Received contact 3/12 (chunk 1/1) '/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/d63e99d2-837c-40b4-83fd-25fd3a649a00.vcf' 12:54:57 [DBG] [ vcf ] New VCF UID 'd63e99d2-837c-40b4-83fd-25fd3a649a00' 12:54:57 [TRC] [ vcf ] Save() 12:54:57 [INF] [ vcf ] Saving VCF '/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/d63e99d2-837c-40b4-83fd-25fd3a649a00.vcf' 12:54:57 [DBG] [ card ] New vCard UID 'd63e99d2-837c-40b4-83fd-25fd3a649a00' 12:54:57 [TRC] [ card ] Save() 12:54:57 [INF] [ card ] Saving vCard 'd63e99d2-837c-40b4-83fd-25fd3a649a00' 12:54:57 [TRC] [ card ] Delete('d63e99d2-837c-40b4-83fd-25fd3a649a00') 12:54:57 [DBG] [ card ] Deleted key=Cd63e99d2-837c-40b4-83fd-25fd3a649a00 12:54:57 [DBG] [ card ] Deleted key=Ld63e99d2-837c-40b4-83fd-25fd3a649a00 12:54:57 [TRC] [ db ] Set('Cd63e99d2-837c-40b4-83fd-25fd3a649a00', ...) 12:54:57 [DBG] [ card ] Saved vcard UID 'd63e99d2-837c-40b4-83fd-25fd3a649a00' 12:54:57 [TRC] [ card ] UpdateIndexKeys() 12:54:57 [TRC] [ card ] mergeAppleAddressBookExtensionToType() 12:54:57 [TRC] [ db ] Set('lsn.lastname11.d63e99d2-837c-40b4-83fd-25fd3a649a00', ...) 12:54:57 [DBG] [ card ] Saved key 'lsn.lastname11.d63e99d2-837c-40b4-83fd-25fd3a649a00' = 'd63e99d2-837c-40b4-83fd-25fd3a649a00' 12:54:57 [TRC] [ db ] Set('e+4915111111111.d63e99d2-837c-40b4-83fd-25fd3a649a00', ...) 12:54:57 [DBG] [ card ] Saved key 'e+4915111111111.d63e99d2-837c-40b4-83fd-25fd3a649a00' = 'd63e99d2-837c-40b4-83fd-25fd3a649a00' 12:54:57 [TRC] [ db ] Set('lcn.firstname11 lastname11.d63e99d2-837c-40b4-83fd-25fd3a649a00', ...) 12:54:57 [DBG] [ card ] Saved key 'lcn.firstname11 lastname11.d63e99d2-837c-40b4-83fd-25fd3a649a00' = 'd63e99d2-837c-40b4-83fd-25fd3a649a00' 12:54:57 [TRC] [ db ] Set('lgivenName.firstname11.d63e99d2-837c-40b4-83fd-25fd3a649a00', ...) 12:54:57 [DBG] [ card ] Saved key 'lgivenName.firstname11.d63e99d2-837c-40b4-83fd-25fd3a649a00' = 'd63e99d2-837c-40b4-83fd-25fd3a649a00' 12:54:57 [TRC] [ db ] Set('e+4930111111111.d63e99d2-837c-40b4-83fd-25fd3a649a00', ...) 12:54:57 [DBG] [ card ] Saved key 'e+4930111111111.d63e99d2-837c-40b4-83fd-25fd3a649a00' = 'd63e99d2-837c-40b4-83fd-25fd3a649a00' 12:54:57 [TRC] [ db ] Set('Ld63e99d2-837c-40b4-83fd-25fd3a649a00', ...) 12:54:57 [DBG] [ vcf ] Saved UID 'd63e99d2-837c-40b4-83fd-25fd3a649a00' 12:54:57 [TRC] [ db ] Set('p/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/d63e99d2-837c-40b4-83fd-25fd3a649a00.vcf', ...) 12:54:57 [DBG] [ vcf ] Saved UID 'd63e99d2-837c-40b4-83fd-25fd3a649a00' by path '/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/d63e99d2-837c-40b4-83fd-25fd3a649a00.vcf 12:54:57 [INF] [client] Received contact 4/12 (chunk 1/1) '/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/52af4c99-9483-4021-a1cb-815b35e62275.vcf' 12:54:57 [DBG] [ vcf ] New VCF UID '52af4c99-9483-4021-a1cb-815b35e62275' 12:54:57 [TRC] [ vcf ] Save() 12:54:57 [INF] [ vcf ] Saving VCF '/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/52af4c99-9483-4021-a1cb-815b35e62275.vcf' 12:54:57 [DBG] [ card ] New vCard UID '52af4c99-9483-4021-a1cb-815b35e62275' 12:54:57 [TRC] [ card ] Save() 12:54:57 [INF] [ card ] Saving vCard '52af4c99-9483-4021-a1cb-815b35e62275' 12:54:57 [TRC] [ card ] Delete('52af4c99-9483-4021-a1cb-815b35e62275') 12:54:57 [DBG] [ card ] Deleted key=C52af4c99-9483-4021-a1cb-815b35e62275 12:54:57 [DBG] [ card ] Deleted key=L52af4c99-9483-4021-a1cb-815b35e62275 12:54:57 [TRC] [ db ] Set('C52af4c99-9483-4021-a1cb-815b35e62275', ...) 12:54:57 [DBG] [ card ] Saved vcard UID '52af4c99-9483-4021-a1cb-815b35e62275' 12:54:57 [TRC] [ card ] UpdateIndexKeys() 12:54:57 [TRC] [ card ] mergeAppleAddressBookExtensionToType() 12:54:57 [TRC] [ db ] Set('lcn.firstname8 lastname8.52af4c99-9483-4021-a1cb-815b35e62275', ...) 12:54:57 [DBG] [ card ] Saved key 'lcn.firstname8 lastname8.52af4c99-9483-4021-a1cb-815b35e62275' = '52af4c99-9483-4021-a1cb-815b35e62275' 12:54:57 [TRC] [ db ] Set('lgivenName.firstname8.52af4c99-9483-4021-a1cb-815b35e62275', ...) 12:54:57 [DBG] [ card ] Saved key 'lgivenName.firstname8.52af4c99-9483-4021-a1cb-815b35e62275' = '52af4c99-9483-4021-a1cb-815b35e62275' 12:54:57 [TRC] [ db ] Set('e+493011111118.52af4c99-9483-4021-a1cb-815b35e62275', ...) 12:54:57 [DBG] [ card ] Saved key 'e+493011111118.52af4c99-9483-4021-a1cb-815b35e62275' = '52af4c99-9483-4021-a1cb-815b35e62275' 12:54:57 [TRC] [ db ] Set('lsn.lastname8.52af4c99-9483-4021-a1cb-815b35e62275', ...) 12:54:57 [DBG] [ card ] Saved key 'lsn.lastname8.52af4c99-9483-4021-a1cb-815b35e62275' = '52af4c99-9483-4021-a1cb-815b35e62275' 12:54:57 [TRC] [ db ] Set('e+491511111118.52af4c99-9483-4021-a1cb-815b35e62275', ...) 12:54:57 [DBG] [ card ] Saved key 'e+491511111118.52af4c99-9483-4021-a1cb-815b35e62275' = '52af4c99-9483-4021-a1cb-815b35e62275' 12:54:57 [TRC] [ db ] Set('L52af4c99-9483-4021-a1cb-815b35e62275', ...) 12:54:57 [DBG] [ vcf ] Saved UID '52af4c99-9483-4021-a1cb-815b35e62275' 12:54:57 [TRC] [ db ] Set('p/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/52af4c99-9483-4021-a1cb-815b35e62275.vcf', ...) 12:54:57 [DBG] [ vcf ] Saved UID '52af4c99-9483-4021-a1cb-815b35e62275' by path '/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/52af4c99-9483-4021-a1cb-815b35e62275.vcf 12:54:57 [INF] [client] Received contact 5/12 (chunk 1/1) '/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/5f464ecb-9331-4018-86c1-a313c5b1ce1d.vcf' 12:54:57 [DBG] [ vcf ] New VCF UID '5f464ecb-9331-4018-86c1-a313c5b1ce1d' 12:54:57 [TRC] [ vcf ] Save() 12:54:57 [INF] [ vcf ] Saving VCF '/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/5f464ecb-9331-4018-86c1-a313c5b1ce1d.vcf' 12:54:57 [DBG] [ card ] New vCard UID '5f464ecb-9331-4018-86c1-a313c5b1ce1d' 12:54:57 [TRC] [ card ] Save() 12:54:57 [INF] [ card ] Saving vCard '5f464ecb-9331-4018-86c1-a313c5b1ce1d' 12:54:57 [TRC] [ card ] Delete('5f464ecb-9331-4018-86c1-a313c5b1ce1d') 12:54:57 [DBG] [ card ] Deleted key=C5f464ecb-9331-4018-86c1-a313c5b1ce1d 12:54:57 [DBG] [ card ] Deleted key=L5f464ecb-9331-4018-86c1-a313c5b1ce1d 12:54:57 [TRC] [ db ] Set('C5f464ecb-9331-4018-86c1-a313c5b1ce1d', ...) 12:54:57 [DBG] [ card ] Saved vcard UID '5f464ecb-9331-4018-86c1-a313c5b1ce1d' 12:54:57 [TRC] [ card ] UpdateIndexKeys() 12:54:57 [TRC] [ card ] mergeAppleAddressBookExtensionToType() 12:54:57 [TRC] [ db ] Set('e+491511111119.5f464ecb-9331-4018-86c1-a313c5b1ce1d', ...) 12:54:57 [DBG] [ card ] Saved key 'e+491511111119.5f464ecb-9331-4018-86c1-a313c5b1ce1d' = '5f464ecb-9331-4018-86c1-a313c5b1ce1d' 12:54:57 [TRC] [ db ] Set('lsn.lastname9.5f464ecb-9331-4018-86c1-a313c5b1ce1d', ...) 12:54:57 [DBG] [ card ] Saved key 'lsn.lastname9.5f464ecb-9331-4018-86c1-a313c5b1ce1d' = '5f464ecb-9331-4018-86c1-a313c5b1ce1d' 12:54:57 [TRC] [ db ] Set('lcn.firstname9 lastname9.5f464ecb-9331-4018-86c1-a313c5b1ce1d', ...) 12:54:57 [DBG] [ card ] Saved key 'lcn.firstname9 lastname9.5f464ecb-9331-4018-86c1-a313c5b1ce1d' = '5f464ecb-9331-4018-86c1-a313c5b1ce1d' 12:54:57 [TRC] [ db ] Set('lgivenName.firstname9.5f464ecb-9331-4018-86c1-a313c5b1ce1d', ...) 12:54:57 [DBG] [ card ] Saved key 'lgivenName.firstname9.5f464ecb-9331-4018-86c1-a313c5b1ce1d' = '5f464ecb-9331-4018-86c1-a313c5b1ce1d' 12:54:57 [TRC] [ db ] Set('e+493011111119.5f464ecb-9331-4018-86c1-a313c5b1ce1d', ...) 12:54:57 [DBG] [ card ] Saved key 'e+493011111119.5f464ecb-9331-4018-86c1-a313c5b1ce1d' = '5f464ecb-9331-4018-86c1-a313c5b1ce1d' 12:54:57 [TRC] [ db ] Set('L5f464ecb-9331-4018-86c1-a313c5b1ce1d', ...) 12:54:57 [DBG] [ vcf ] Saved UID '5f464ecb-9331-4018-86c1-a313c5b1ce1d' 12:54:57 [TRC] [ db ] Set('p/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/5f464ecb-9331-4018-86c1-a313c5b1ce1d.vcf', ...) 12:54:57 [DBG] [ vcf ] Saved UID '5f464ecb-9331-4018-86c1-a313c5b1ce1d' by path '/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/5f464ecb-9331-4018-86c1-a313c5b1ce1d.vcf 12:54:57 [INF] [client] Received contact 6/12 (chunk 1/1) '/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/93dbbb06-34e4-4041-a0c6-cf5910071913.vcf' 12:54:57 [DBG] [ vcf ] New VCF UID '93dbbb06-34e4-4041-a0c6-cf5910071913' 12:54:57 [TRC] [ vcf ] Save() 12:54:57 [INF] [ vcf ] Saving VCF '/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/93dbbb06-34e4-4041-a0c6-cf5910071913.vcf' 12:54:57 [DBG] [ card ] New vCard UID '93dbbb06-34e4-4041-a0c6-cf5910071913' 12:54:57 [TRC] [ card ] Save() 12:54:57 [INF] [ card ] Saving vCard '93dbbb06-34e4-4041-a0c6-cf5910071913' 12:54:57 [TRC] [ card ] Delete('93dbbb06-34e4-4041-a0c6-cf5910071913') 12:54:57 [DBG] [ card ] Deleted key=C93dbbb06-34e4-4041-a0c6-cf5910071913 12:54:57 [DBG] [ card ] Deleted key=L93dbbb06-34e4-4041-a0c6-cf5910071913 12:54:57 [TRC] [ db ] Set('C93dbbb06-34e4-4041-a0c6-cf5910071913', ...) 12:54:57 [DBG] [ card ] Saved vcard UID '93dbbb06-34e4-4041-a0c6-cf5910071913' 12:54:57 [TRC] [ card ] UpdateIndexKeys() 12:54:57 [TRC] [ card ] mergeAppleAddressBookExtensionToType() 12:54:57 [TRC] [ db ] Set('lsn.lastname3.93dbbb06-34e4-4041-a0c6-cf5910071913', ...) 12:54:57 [DBG] [ card ] Saved key 'lsn.lastname3.93dbbb06-34e4-4041-a0c6-cf5910071913' = '93dbbb06-34e4-4041-a0c6-cf5910071913' 12:54:57 [TRC] [ db ] Set('e+491511111113.93dbbb06-34e4-4041-a0c6-cf5910071913', ...) 12:54:57 [DBG] [ card ] Saved key 'e+491511111113.93dbbb06-34e4-4041-a0c6-cf5910071913' = '93dbbb06-34e4-4041-a0c6-cf5910071913' 12:54:57 [TRC] [ db ] Set('lcn.firstname3 lastname3.93dbbb06-34e4-4041-a0c6-cf5910071913', ...) 12:54:57 [DBG] [ card ] Saved key 'lcn.firstname3 lastname3.93dbbb06-34e4-4041-a0c6-cf5910071913' = '93dbbb06-34e4-4041-a0c6-cf5910071913' 12:54:57 [TRC] [ db ] Set('lgivenName.firstname3.93dbbb06-34e4-4041-a0c6-cf5910071913', ...) 12:54:57 [DBG] [ card ] Saved key 'lgivenName.firstname3.93dbbb06-34e4-4041-a0c6-cf5910071913' = '93dbbb06-34e4-4041-a0c6-cf5910071913' 12:54:57 [TRC] [ db ] Set('e+493011111113.93dbbb06-34e4-4041-a0c6-cf5910071913', ...) 12:54:57 [DBG] [ card ] Saved key 'e+493011111113.93dbbb06-34e4-4041-a0c6-cf5910071913' = '93dbbb06-34e4-4041-a0c6-cf5910071913' 12:54:57 [TRC] [ db ] Set('L93dbbb06-34e4-4041-a0c6-cf5910071913', ...) 12:54:57 [DBG] [ vcf ] Saved UID '93dbbb06-34e4-4041-a0c6-cf5910071913' 12:54:57 [TRC] [ db ] Set('p/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/93dbbb06-34e4-4041-a0c6-cf5910071913.vcf', ...) 12:54:57 [DBG] [ vcf ] Saved UID '93dbbb06-34e4-4041-a0c6-cf5910071913' by path '/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/93dbbb06-34e4-4041-a0c6-cf5910071913.vcf 12:54:57 [INF] [client] Received contact 7/12 (chunk 1/1) '/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/5fdd288f-fb31-4f76-aea0-41d85ba2e1f0.vcf' 12:54:57 [DBG] [ vcf ] New VCF UID '5fdd288f-fb31-4f76-aea0-41d85ba2e1f0' 12:54:57 [TRC] [ vcf ] Save() 12:54:57 [INF] [ vcf ] Saving VCF '/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/5fdd288f-fb31-4f76-aea0-41d85ba2e1f0.vcf' 12:54:57 [DBG] [ card ] New vCard UID '5fdd288f-fb31-4f76-aea0-41d85ba2e1f0' 12:54:57 [TRC] [ card ] Save() 12:54:57 [INF] [ card ] Saving vCard '5fdd288f-fb31-4f76-aea0-41d85ba2e1f0' 12:54:57 [TRC] [ card ] Delete('5fdd288f-fb31-4f76-aea0-41d85ba2e1f0') 12:54:57 [DBG] [ card ] Deleted key=C5fdd288f-fb31-4f76-aea0-41d85ba2e1f0 12:54:57 [DBG] [ card ] Deleted key=L5fdd288f-fb31-4f76-aea0-41d85ba2e1f0 12:54:57 [TRC] [ db ] Set('C5fdd288f-fb31-4f76-aea0-41d85ba2e1f0', ...) 12:54:57 [DBG] [ card ] Saved vcard UID '5fdd288f-fb31-4f76-aea0-41d85ba2e1f0' 12:54:57 [TRC] [ card ] UpdateIndexKeys() 12:54:57 [TRC] [ card ] mergeAppleAddressBookExtensionToType() 12:54:57 [TRC] [ db ] Set('lcn.firstname5 lastname5.5fdd288f-fb31-4f76-aea0-41d85ba2e1f0', ...) 12:54:57 [DBG] [ card ] Saved key 'lcn.firstname5 lastname5.5fdd288f-fb31-4f76-aea0-41d85ba2e1f0' = '5fdd288f-fb31-4f76-aea0-41d85ba2e1f0' 12:54:57 [TRC] [ db ] Set('lgivenName.firstname5.5fdd288f-fb31-4f76-aea0-41d85ba2e1f0', ...) 12:54:57 [DBG] [ card ] Saved key 'lgivenName.firstname5.5fdd288f-fb31-4f76-aea0-41d85ba2e1f0' = '5fdd288f-fb31-4f76-aea0-41d85ba2e1f0' 12:54:57 [TRC] [ db ] Set('e+493011111115.5fdd288f-fb31-4f76-aea0-41d85ba2e1f0', ...) 12:54:57 [DBG] [ card ] Saved key 'e+493011111115.5fdd288f-fb31-4f76-aea0-41d85ba2e1f0' = '5fdd288f-fb31-4f76-aea0-41d85ba2e1f0' 12:54:57 [TRC] [ db ] Set('lsn.lastname5.5fdd288f-fb31-4f76-aea0-41d85ba2e1f0', ...) 12:54:57 [DBG] [ card ] Saved key 'lsn.lastname5.5fdd288f-fb31-4f76-aea0-41d85ba2e1f0' = '5fdd288f-fb31-4f76-aea0-41d85ba2e1f0' 12:54:57 [TRC] [ db ] Set('e+491511111115.5fdd288f-fb31-4f76-aea0-41d85ba2e1f0', ...) 12:54:57 [DBG] [ card ] Saved key 'e+491511111115.5fdd288f-fb31-4f76-aea0-41d85ba2e1f0' = '5fdd288f-fb31-4f76-aea0-41d85ba2e1f0' 12:54:57 [TRC] [ db ] Set('L5fdd288f-fb31-4f76-aea0-41d85ba2e1f0', ...) 12:54:57 [DBG] [ vcf ] Saved UID '5fdd288f-fb31-4f76-aea0-41d85ba2e1f0' 12:54:57 [TRC] [ db ] Set('p/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/5fdd288f-fb31-4f76-aea0-41d85ba2e1f0.vcf', ...) 12:54:57 [DBG] [ vcf ] Saved UID '5fdd288f-fb31-4f76-aea0-41d85ba2e1f0' by path '/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/5fdd288f-fb31-4f76-aea0-41d85ba2e1f0.vcf 12:54:57 [INF] [client] Received contact 8/12 (chunk 1/1) '/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52.vcf' 12:54:57 [DBG] [ vcf ] New VCF UID '81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52' 12:54:57 [TRC] [ vcf ] Save() 12:54:57 [INF] [ vcf ] Saving VCF '/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52.vcf' 12:54:57 [DBG] [ card ] New vCard UID '81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52' 12:54:57 [TRC] [ card ] Save() 12:54:57 [INF] [ card ] Saving vCard '81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52' 12:54:57 [TRC] [ card ] Delete('81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52') 12:54:57 [DBG] [ card ] Deleted key=C81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52 12:54:57 [DBG] [ card ] Deleted key=L81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52 12:54:57 [TRC] [ db ] Set('C81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52', ...) 12:54:57 [DBG] [ card ] Saved vcard UID '81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52' 12:54:57 [TRC] [ card ] UpdateIndexKeys() 12:54:57 [TRC] [ card ] mergeAppleAddressBookExtensionToType() 12:54:57 [TRC] [ db ] Set('lgivenName.firstname12.81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52', ...) 12:54:57 [DBG] [ card ] Saved key 'lgivenName.firstname12.81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52' = '81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52' 12:54:57 [TRC] [ db ] Set('e+4930111111112.81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52', ...) 12:54:57 [DBG] [ card ] Saved key 'e+4930111111112.81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52' = '81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52' 12:54:57 [TRC] [ db ] Set('e+4915111111112.81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52', ...) 12:54:57 [DBG] [ card ] Saved key 'e+4915111111112.81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52' = '81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52' 12:54:57 [TRC] [ db ] Set('lsn.lastname12.81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52', ...) 12:54:57 [DBG] [ card ] Saved key 'lsn.lastname12.81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52' = '81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52' 12:54:57 [TRC] [ db ] Set('lcn.firstname12 lastname12.81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52', ...) 12:54:57 [DBG] [ card ] Saved key 'lcn.firstname12 lastname12.81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52' = '81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52' 12:54:57 [TRC] [ db ] Set('L81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52', ...) 12:54:57 [DBG] [ vcf ] Saved UID '81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52' 12:54:57 [TRC] [ db ] Set('p/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52.vcf', ...) 12:54:57 [DBG] [ vcf ] Saved UID '81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52' by path '/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52.vcf 12:54:57 [INF] [client] Received contact 9/12 (chunk 1/1) '/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/5d4d5e71-121a-4b3a-8f93-9edd9c676dc5.vcf' 12:54:57 [DBG] [ vcf ] New VCF UID '5d4d5e71-121a-4b3a-8f93-9edd9c676dc5' 12:54:57 [TRC] [ vcf ] Save() 12:54:57 [INF] [ vcf ] Saving VCF '/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/5d4d5e71-121a-4b3a-8f93-9edd9c676dc5.vcf' 12:54:57 [DBG] [ card ] New vCard UID '5d4d5e71-121a-4b3a-8f93-9edd9c676dc5' 12:54:57 [TRC] [ card ] Save() 12:54:57 [INF] [ card ] Saving vCard '5d4d5e71-121a-4b3a-8f93-9edd9c676dc5' 12:54:57 [TRC] [ card ] Delete('5d4d5e71-121a-4b3a-8f93-9edd9c676dc5') 12:54:57 [DBG] [ card ] Deleted key=C5d4d5e71-121a-4b3a-8f93-9edd9c676dc5 12:54:57 [DBG] [ card ] Deleted key=L5d4d5e71-121a-4b3a-8f93-9edd9c676dc5 12:54:57 [TRC] [ db ] Set('C5d4d5e71-121a-4b3a-8f93-9edd9c676dc5', ...) 12:54:57 [DBG] [ card ] Saved vcard UID '5d4d5e71-121a-4b3a-8f93-9edd9c676dc5' 12:54:57 [TRC] [ card ] UpdateIndexKeys() 12:54:57 [TRC] [ card ] mergeAppleAddressBookExtensionToType() 12:54:57 [TRC] [ db ] Set('e+491511111114.5d4d5e71-121a-4b3a-8f93-9edd9c676dc5', ...) 12:54:57 [DBG] [ card ] Saved key 'e+491511111114.5d4d5e71-121a-4b3a-8f93-9edd9c676dc5' = '5d4d5e71-121a-4b3a-8f93-9edd9c676dc5' 12:54:57 [TRC] [ db ] Set('lsn.lastname4.5d4d5e71-121a-4b3a-8f93-9edd9c676dc5', ...) 12:54:57 [DBG] [ card ] Saved key 'lsn.lastname4.5d4d5e71-121a-4b3a-8f93-9edd9c676dc5' = '5d4d5e71-121a-4b3a-8f93-9edd9c676dc5' 12:54:57 [TRC] [ db ] Set('lcn.firstname4 lastname4.5d4d5e71-121a-4b3a-8f93-9edd9c676dc5', ...) 12:54:57 [DBG] [ card ] Saved key 'lcn.firstname4 lastname4.5d4d5e71-121a-4b3a-8f93-9edd9c676dc5' = '5d4d5e71-121a-4b3a-8f93-9edd9c676dc5' 12:54:57 [TRC] [ db ] Set('lgivenName.firstname4.5d4d5e71-121a-4b3a-8f93-9edd9c676dc5', ...) 12:54:57 [DBG] [ card ] Saved key 'lgivenName.firstname4.5d4d5e71-121a-4b3a-8f93-9edd9c676dc5' = '5d4d5e71-121a-4b3a-8f93-9edd9c676dc5' 12:54:57 [TRC] [ db ] Set('e+493011111114.5d4d5e71-121a-4b3a-8f93-9edd9c676dc5', ...) 12:54:57 [DBG] [ card ] Saved key 'e+493011111114.5d4d5e71-121a-4b3a-8f93-9edd9c676dc5' = '5d4d5e71-121a-4b3a-8f93-9edd9c676dc5' 12:54:57 [TRC] [ db ] Set('L5d4d5e71-121a-4b3a-8f93-9edd9c676dc5', ...) 12:54:57 [DBG] [ vcf ] Saved UID '5d4d5e71-121a-4b3a-8f93-9edd9c676dc5' 12:54:57 [TRC] [ db ] Set('p/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/5d4d5e71-121a-4b3a-8f93-9edd9c676dc5.vcf', ...) 12:54:57 [DBG] [ vcf ] Saved UID '5d4d5e71-121a-4b3a-8f93-9edd9c676dc5' by path '/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/5d4d5e71-121a-4b3a-8f93-9edd9c676dc5.vcf 12:54:57 [INF] [client] Received contact 10/12 (chunk 1/1) '/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/af084bb1-5336-4a85-86ab-f16050e91651.vcf' 12:54:57 [DBG] [ vcf ] New VCF UID 'af084bb1-5336-4a85-86ab-f16050e91651' 12:54:57 [TRC] [ vcf ] Save() 12:54:57 [INF] [ vcf ] Saving VCF '/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/af084bb1-5336-4a85-86ab-f16050e91651.vcf' 12:54:57 [DBG] [ card ] New vCard UID 'af084bb1-5336-4a85-86ab-f16050e91651' 12:54:57 [TRC] [ card ] Save() 12:54:57 [INF] [ card ] Saving vCard 'af084bb1-5336-4a85-86ab-f16050e91651' 12:54:57 [TRC] [ card ] Delete('af084bb1-5336-4a85-86ab-f16050e91651') 12:54:57 [DBG] [ card ] Deleted key=Caf084bb1-5336-4a85-86ab-f16050e91651 12:54:57 [DBG] [ card ] Deleted key=Laf084bb1-5336-4a85-86ab-f16050e91651 12:54:57 [TRC] [ db ] Set('Caf084bb1-5336-4a85-86ab-f16050e91651', ...) 12:54:57 [DBG] [ card ] Saved vcard UID 'af084bb1-5336-4a85-86ab-f16050e91651' 12:54:57 [TRC] [ card ] UpdateIndexKeys() 12:54:57 [TRC] [ card ] mergeAppleAddressBookExtensionToType() 12:54:57 [TRC] [ db ] Set('lcn.firstname1 lastname1.af084bb1-5336-4a85-86ab-f16050e91651', ...) 12:54:57 [DBG] [ card ] Saved key 'lcn.firstname1 lastname1.af084bb1-5336-4a85-86ab-f16050e91651' = 'af084bb1-5336-4a85-86ab-f16050e91651' 12:54:57 [TRC] [ db ] Set('lgivenName.firstname1.af084bb1-5336-4a85-86ab-f16050e91651', ...) 12:54:57 [DBG] [ card ] Saved key 'lgivenName.firstname1.af084bb1-5336-4a85-86ab-f16050e91651' = 'af084bb1-5336-4a85-86ab-f16050e91651' 12:54:57 [TRC] [ db ] Set('e+493011111111.af084bb1-5336-4a85-86ab-f16050e91651', ...) 12:54:57 [DBG] [ card ] Saved key 'e+493011111111.af084bb1-5336-4a85-86ab-f16050e91651' = 'af084bb1-5336-4a85-86ab-f16050e91651' 12:54:57 [TRC] [ db ] Set('e+491511111111.af084bb1-5336-4a85-86ab-f16050e91651', ...) 12:54:57 [DBG] [ card ] Saved key 'e+491511111111.af084bb1-5336-4a85-86ab-f16050e91651' = 'af084bb1-5336-4a85-86ab-f16050e91651' 12:54:57 [TRC] [ db ] Set('lsn.lastname1.af084bb1-5336-4a85-86ab-f16050e91651', ...) 12:54:57 [DBG] [ card ] Saved key 'lsn.lastname1.af084bb1-5336-4a85-86ab-f16050e91651' = 'af084bb1-5336-4a85-86ab-f16050e91651' 12:54:57 [TRC] [ db ] Set('Laf084bb1-5336-4a85-86ab-f16050e91651', ...) 12:54:57 [DBG] [ vcf ] Saved UID 'af084bb1-5336-4a85-86ab-f16050e91651' 12:54:57 [TRC] [ db ] Set('p/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/af084bb1-5336-4a85-86ab-f16050e91651.vcf', ...) 12:54:57 [DBG] [ vcf ] Saved UID 'af084bb1-5336-4a85-86ab-f16050e91651' by path '/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/af084bb1-5336-4a85-86ab-f16050e91651.vcf 12:54:57 [INF] [client] Received contact 11/12 (chunk 1/1) '/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/00777273-e49b-477f-b689-1f258ce1519f.vcf' 12:54:57 [DBG] [ vcf ] New VCF UID '00777273-e49b-477f-b689-1f258ce1519f' 12:54:57 [TRC] [ vcf ] Save() 12:54:57 [INF] [ vcf ] Saving VCF '/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/00777273-e49b-477f-b689-1f258ce1519f.vcf' 12:54:57 [DBG] [ card ] New vCard UID '00777273-e49b-477f-b689-1f258ce1519f' 12:54:57 [TRC] [ card ] Save() 12:54:57 [INF] [ card ] Saving vCard '00777273-e49b-477f-b689-1f258ce1519f' 12:54:57 [TRC] [ card ] Delete('00777273-e49b-477f-b689-1f258ce1519f') 12:54:57 [DBG] [ card ] Deleted key=C00777273-e49b-477f-b689-1f258ce1519f 12:54:57 [DBG] [ card ] Deleted key=L00777273-e49b-477f-b689-1f258ce1519f 12:54:57 [TRC] [ db ] Set('C00777273-e49b-477f-b689-1f258ce1519f', ...) 12:54:57 [DBG] [ card ] Saved vcard UID '00777273-e49b-477f-b689-1f258ce1519f' 12:54:57 [TRC] [ card ] UpdateIndexKeys() 12:54:57 [TRC] [ card ] mergeAppleAddressBookExtensionToType() 12:54:57 [TRC] [ db ] Set('e+4915111111110.00777273-e49b-477f-b689-1f258ce1519f', ...) 12:54:57 [DBG] [ card ] Saved key 'e+4915111111110.00777273-e49b-477f-b689-1f258ce1519f' = '00777273-e49b-477f-b689-1f258ce1519f' 12:54:57 [TRC] [ db ] Set('lsn.lastname10.00777273-e49b-477f-b689-1f258ce1519f', ...) 12:54:57 [DBG] [ card ] Saved key 'lsn.lastname10.00777273-e49b-477f-b689-1f258ce1519f' = '00777273-e49b-477f-b689-1f258ce1519f' 12:54:57 [TRC] [ db ] Set('lcn.firstname10 lastname10.00777273-e49b-477f-b689-1f258ce1519f', ...) 12:54:57 [DBG] [ card ] Saved key 'lcn.firstname10 lastname10.00777273-e49b-477f-b689-1f258ce1519f' = '00777273-e49b-477f-b689-1f258ce1519f' 12:54:57 [TRC] [ db ] Set('lgivenName.firstname10.00777273-e49b-477f-b689-1f258ce1519f', ...) 12:54:57 [DBG] [ card ] Saved key 'lgivenName.firstname10.00777273-e49b-477f-b689-1f258ce1519f' = '00777273-e49b-477f-b689-1f258ce1519f' 12:54:57 [TRC] [ db ] Set('e+4930111111110.00777273-e49b-477f-b689-1f258ce1519f', ...) 12:54:57 [DBG] [ card ] Saved key 'e+4930111111110.00777273-e49b-477f-b689-1f258ce1519f' = '00777273-e49b-477f-b689-1f258ce1519f' 12:54:57 [TRC] [ db ] Set('L00777273-e49b-477f-b689-1f258ce1519f', ...) 12:54:57 [DBG] [ vcf ] Saved UID '00777273-e49b-477f-b689-1f258ce1519f' 12:54:57 [TRC] [ db ] Set('p/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/00777273-e49b-477f-b689-1f258ce1519f.vcf', ...) 12:54:57 [DBG] [ vcf ] Saved UID '00777273-e49b-477f-b689-1f258ce1519f' by path '/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/00777273-e49b-477f-b689-1f258ce1519f.vcf 12:54:57 [INF] [client] Received contact 12/12 (chunk 1/1) '/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/144d5240-c759-4491-97bc-6c1b8421432c.vcf' 12:54:57 [DBG] [ vcf ] New VCF UID '144d5240-c759-4491-97bc-6c1b8421432c' 12:54:57 [TRC] [ vcf ] Save() 12:54:57 [INF] [ vcf ] Saving VCF '/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/144d5240-c759-4491-97bc-6c1b8421432c.vcf' 12:54:57 [DBG] [ card ] New vCard UID '144d5240-c759-4491-97bc-6c1b8421432c' 12:54:57 [TRC] [ card ] Save() 12:54:57 [INF] [ card ] Saving vCard '144d5240-c759-4491-97bc-6c1b8421432c' 12:54:57 [TRC] [ card ] Delete('144d5240-c759-4491-97bc-6c1b8421432c') 12:54:57 [DBG] [ card ] Deleted key=C144d5240-c759-4491-97bc-6c1b8421432c 12:54:57 [DBG] [ card ] Deleted key=L144d5240-c759-4491-97bc-6c1b8421432c 12:54:57 [TRC] [ db ] Set('C144d5240-c759-4491-97bc-6c1b8421432c', ...) 12:54:57 [DBG] [ card ] Saved vcard UID '144d5240-c759-4491-97bc-6c1b8421432c' 12:54:57 [TRC] [ card ] UpdateIndexKeys() 12:54:57 [TRC] [ card ] mergeAppleAddressBookExtensionToType() 12:54:57 [TRC] [ db ] Set('lgivenName.firstname6.144d5240-c759-4491-97bc-6c1b8421432c', ...) 12:54:57 [DBG] [ card ] Saved key 'lgivenName.firstname6.144d5240-c759-4491-97bc-6c1b8421432c' = '144d5240-c759-4491-97bc-6c1b8421432c' 12:54:57 [TRC] [ db ] Set('e+493011111116.144d5240-c759-4491-97bc-6c1b8421432c', ...) 12:54:57 [DBG] [ card ] Saved key 'e+493011111116.144d5240-c759-4491-97bc-6c1b8421432c' = '144d5240-c759-4491-97bc-6c1b8421432c' 12:54:57 [TRC] [ db ] Set('e+491511111116.144d5240-c759-4491-97bc-6c1b8421432c', ...) 12:54:57 [DBG] [ card ] Saved key 'e+491511111116.144d5240-c759-4491-97bc-6c1b8421432c' = '144d5240-c759-4491-97bc-6c1b8421432c' 12:54:57 [TRC] [ db ] Set('lsn.lastname6.144d5240-c759-4491-97bc-6c1b8421432c', ...) 12:54:57 [DBG] [ card ] Saved key 'lsn.lastname6.144d5240-c759-4491-97bc-6c1b8421432c' = '144d5240-c759-4491-97bc-6c1b8421432c' 12:54:57 [TRC] [ db ] Set('lcn.firstname6 lastname6.144d5240-c759-4491-97bc-6c1b8421432c', ...) 12:54:57 [DBG] [ card ] Saved key 'lcn.firstname6 lastname6.144d5240-c759-4491-97bc-6c1b8421432c' = '144d5240-c759-4491-97bc-6c1b8421432c' 12:54:57 [TRC] [ db ] Set('L144d5240-c759-4491-97bc-6c1b8421432c', ...) 12:54:57 [DBG] [ vcf ] Saved UID '144d5240-c759-4491-97bc-6c1b8421432c' 12:54:57 [TRC] [ db ] Set('p/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/144d5240-c759-4491-97bc-6c1b8421432c.vcf', ...) 12:54:57 [DBG] [ vcf ] Saved UID '144d5240-c759-4491-97bc-6c1b8421432c' by path '/carddav/pbx01/a21e9323-9bd4-41bb-aa87-0c6cb9dcd9dd/144d5240-c759-4491-97bc-6c1b8421432c.vcf 12:54:57 [INF] [client] Successful loaded 12 of 12 vCards from AddressBook 'testaddressbook' 12:54:57 [DBG] [client] loadAddressBook('testaddressbook') return with syncToken: 'http://radicale.org/ns/sync/19_756' 12:54:57 [TRC] [ db ] Set('stestaddressbook', ...) 12:54:57 [DBG] [client] Saving Sync-Token 'http://radicale.org/ns/sync/19_756' for AddressBook 'testaddressbook' 12:54:57 [TRC] [ db ] GarbageCollect()

Now access the LDAP address book in the Fanvil phone (maximum load entries set to 1000 in the phones config):

L2CPBG log:

13:14:22 [TRC] [server] handler->Bind(bindDN='cn=phone', bindSimplePw='xxx') 13:14:22 [TRC] [server] handler->Search(boundDN='cn=phone', searchReq ldap.SearchRequest{BaseDN:"dc=xxx, dc=local", Scope:1, DerefAliases:0, SizeLimit:0, TimeLimit:60, TypesOnly:false, Filter:"(objectclass=*)", Attributes:[]string{}, Controls:[]ldap.Control{}}, ...) 13:14:22 [DBG] [server] Correlation Text '192.168.xxx.xxx:47660|2021-07-22 13:14:22.9432191 +0800 +08 m=+1168.898851501|1' = 0xdfa20d26 = dfa20d26 13:14:22 [INF] [server] Search request #1: (objectclass=*) from 192.168.xxx.xxx:47660 {dfa20d26} 13:14:22 [DBG] [server] Stats &ldap.Stats{Conns:1, Binds:1, Unbinds:0, Searches:1, statsMutex:sync.Mutex{state:0, sema:0x0}} 13:14:22 [TRC] [server] Search(searchReq ldap.SearchRequest{BaseDN:"dc=xxx, dc=local", Scope:1, DerefAliases:0, SizeLimit:0, TimeLimit:60, TypesOnly:false, Filter:"(objectclass=*)", Attributes:[]string{}, Controls:[]ldap.Control{}}), ... 13:14:22 [TRC] [server] Search request without attributes. Preparing to returning all configured attributes = []string{"l", "mail", "c", "facsimileTelephoneNumber", "mobile", "postalCode", "sn", "telephoneNumber", "cn", "department", "postalAddress", "company", "givenName", "homePhone", "street"} 13:14:22 [DBG] [server] s.request.Filter '(objectclass=*)' 13:14:22 [TRC] [server] uidsByFilter(Description 'Present' = 'objectclass', Scope 1 (Single Level), 0 childs) 13:14:22 [TRC] [server] filterPresent(Description 'Present' = 'objectclass', uid = '', Scope 1 (Single Level), 0 childs) 13:14:22 [DBG] [server] filter &ber.Packet{ClassType:0x80, TagType:0x0, Tag:0x7, Value:interface {}(nil), ByteValue:[]uint8(nil), Data:(*bytes.Buffer)(0xc00018f440), Children:[]*ber.Packet{}, Description:"Present"} 13:14:22 [TRC] [server] isFilterAttributeDefined('objectclass') 13:14:22 [DBG] [server] Prefix matching key 'L00777273-e49b-477f-b689-1f258ce1519f' 13:14:22 [DBG] [server] Prefix matching key 'L144d5240-c759-4491-97bc-6c1b8421432c' 13:14:22 [DBG] [server] Prefix matching key 'L52af4c99-9483-4021-a1cb-815b35e62275' 13:14:22 [DBG] [server] Prefix matching key 'L5d4d5e71-121a-4b3a-8f93-9edd9c676dc5' 13:14:22 [DBG] [server] Prefix matching key 'L5f464ecb-9331-4018-86c1-a313c5b1ce1d' 13:14:22 [DBG] [server] Prefix matching key 'L5fdd288f-fb31-4f76-aea0-41d85ba2e1f0' 13:14:22 [DBG] [server] Prefix matching key 'L81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52' 13:14:22 [DBG] [server] Prefix matching key 'L93dbbb06-34e4-4041-a0c6-cf5910071913' 13:14:22 [DBG] [server] Prefix matching key 'Laf084bb1-5336-4a85-86ab-f16050e91651' 13:14:22 [DBG] [server] Prefix matching key 'Ld242ab6c-c76f-45f3-802c-7bf8e6d5b3d5' 13:14:22 [DBG] [server] Prefix matching key 'Ld61239b9-a214-4572-b409-f83e3573a6bf' 13:14:22 [DBG] [server] Prefix matching key 'Ld63e99d2-837c-40b4-83fd-25fd3a649a00' 13:14:22 [TRC] [server] filterPresent return with matchingUids = map[string]bool{"00777273-e49b-477f-b689-1f258ce1519f":true, "144d5240-c759-4491-97bc-6c1b8421432c":true, "52af4c99-9483-4021-a1cb-815b35e62275":true, "5d4d5e71-121a-4b3a-8f93-9edd9c676dc5":true, "5f464ecb-9331-4018-86c1-a313c5b1ce1d":true, "5fdd288f-fb31-4f76-aea0-41d85ba2e1f0":true, "81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52":true, "93dbbb06-34e4-4041-a0c6-cf5910071913":true, "af084bb1-5336-4a85-86ab-f16050e91651":true, "d242ab6c-c76f-45f3-802c-7bf8e6d5b3d5":true, "d61239b9-a214-4572-b409-f83e3573a6bf":true, "d63e99d2-837c-40b4-83fd-25fd3a649a00":true} 13:14:22 [DBG] [server] Collected UIDs map[string]bool{"00777273-e49b-477f-b689-1f258ce1519f":true, "144d5240-c759-4491-97bc-6c1b8421432c":true, "52af4c99-9483-4021-a1cb-815b35e62275":true, "5d4d5e71-121a-4b3a-8f93-9edd9c676dc5":true, "5f464ecb-9331-4018-86c1-a313c5b1ce1d":true, "5fdd288f-fb31-4f76-aea0-41d85ba2e1f0":true, "81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52":true, "93dbbb06-34e4-4041-a0c6-cf5910071913":true, "af084bb1-5336-4a85-86ab-f16050e91651":true, "d242ab6c-c76f-45f3-802c-7bf8e6d5b3d5":true, "d61239b9-a214-4572-b409-f83e3573a6bf":true, "d63e99d2-837c-40b4-83fd-25fd3a649a00":true} 13:14:22 [TRC] [intern] CountFlux() 13:14:22 [TRC] [server] loadLdapResults() 13:14:22 [TRC] [ db ] Get('Laf084bb1-5336-4a85-86ab-f16050e91651') 13:14:22 [DBG] [server] DB value of uid 'af084bb1-5336-4a85-86ab-f16050e91651': ldap.LdapRecord{uid:"af084bb1-5336-4a85-86ab-f16050e91651", attributes:map[string]string{"cn":"Firstname1 Lastname1", "givenName":"Firstname1", "homePhone":"030 11111111", "mobile":"1511111111", "sn":"Lastname1"}} 13:14:22 [TRC] [ db ] Get('Ld242ab6c-c76f-45f3-802c-7bf8e6d5b3d5') 13:14:22 [DBG] [server] DB value of uid 'd242ab6c-c76f-45f3-802c-7bf8e6d5b3d5': ldap.LdapRecord{uid:"d242ab6c-c76f-45f3-802c-7bf8e6d5b3d5", attributes:map[string]string{"cn":"Firstname2 Lastname2", "givenName":"Firstname2", "homePhone":"030 11111112", "mobile":"1511111112", "sn":"Lastname2"}} 13:14:23 [TRC] [ db ] Get('L00777273-e49b-477f-b689-1f258ce1519f') 13:14:23 [DBG] [server] DB value of uid '00777273-e49b-477f-b689-1f258ce1519f': ldap.LdapRecord{uid:"00777273-e49b-477f-b689-1f258ce1519f", attributes:map[string]string{"cn":"Firstname10 Lastname10", "givenName":"Firstname10", "homePhone":"030 111111110", "mobile":"01511 1111110", "sn":"Lastname10"}} 13:14:23 [TRC] [ db ] Get('L52af4c99-9483-4021-a1cb-815b35e62275') 13:14:23 [DBG] [server] DB value of uid '52af4c99-9483-4021-a1cb-815b35e62275': ldap.LdapRecord{uid:"52af4c99-9483-4021-a1cb-815b35e62275", attributes:map[string]string{"cn":"Firstname8 Lastname8", "givenName":"Firstname8", "homePhone":"030 11111118", "mobile":"1511111118", "sn":"Lastname8"}} 13:14:23 [TRC] [ db ] Get('L5d4d5e71-121a-4b3a-8f93-9edd9c676dc5') 13:14:23 [DBG] [server] DB value of uid '5d4d5e71-121a-4b3a-8f93-9edd9c676dc5': ldap.LdapRecord{uid:"5d4d5e71-121a-4b3a-8f93-9edd9c676dc5", attributes:map[string]string{"cn":"Firstname4 Lastname4", "givenName":"Firstname4", "homePhone":"030 11111114", "mobile":"1511111114", "sn":"Lastname4"}} 13:14:23 [TRC] [ db ] Get('L93dbbb06-34e4-4041-a0c6-cf5910071913') 13:14:23 [DBG] [server] DB value of uid '93dbbb06-34e4-4041-a0c6-cf5910071913': ldap.LdapRecord{uid:"93dbbb06-34e4-4041-a0c6-cf5910071913", attributes:map[string]string{"cn":"Firstname3 Lastname3", "givenName":"Firstname3", "homePhone":"030 11111113", "mobile":"1511111113", "sn":"Lastname3"}} 13:14:23 [TRC] [ db ] Get('Ld61239b9-a214-4572-b409-f83e3573a6bf') 13:14:23 [DBG] [server] DB value of uid 'd61239b9-a214-4572-b409-f83e3573a6bf': ldap.LdapRecord{uid:"d61239b9-a214-4572-b409-f83e3573a6bf", attributes:map[string]string{"cn":"Firstname7 Lastname7", "givenName":"Firstname7", "homePhone":"030 11111117", "mobile":"1511111117", "sn":"Lastname7"}} 13:14:23 [TRC] [ db ] Get('Ld63e99d2-837c-40b4-83fd-25fd3a649a00') 13:14:23 [DBG] [server] DB value of uid 'd63e99d2-837c-40b4-83fd-25fd3a649a00': ldap.LdapRecord{uid:"d63e99d2-837c-40b4-83fd-25fd3a649a00", attributes:map[string]string{"cn":"Firstname11 Lastname11", "givenName":"Firstname11", "homePhone":"030 111111111", "mobile":"01511 1111111", "sn":"Lastname11"}} 13:14:23 [TRC] [ db ] Get('L144d5240-c759-4491-97bc-6c1b8421432c') 13:14:23 [DBG] [server] DB value of uid '144d5240-c759-4491-97bc-6c1b8421432c': ldap.LdapRecord{uid:"144d5240-c759-4491-97bc-6c1b8421432c", attributes:map[string]string{"cn":"Firstname6 Lastname6", "givenName":"Firstname6", "homePhone":"030 11111116", "mobile":"1511111116", "sn":"Lastname6"}} 13:14:23 [TRC] [ db ] Get('L5f464ecb-9331-4018-86c1-a313c5b1ce1d') 13:14:23 [DBG] [server] DB value of uid '5f464ecb-9331-4018-86c1-a313c5b1ce1d': ldap.LdapRecord{uid:"5f464ecb-9331-4018-86c1-a313c5b1ce1d", attributes:map[string]string{"cn":"Firstname9 Lastname9", "givenName":"Firstname9", "homePhone":"030 11111119", "mobile":"1511111119", "sn":"Lastname9"}} 13:14:23 [TRC] [ db ] Get('L5fdd288f-fb31-4f76-aea0-41d85ba2e1f0') 13:14:23 [DBG] [server] DB value of uid '5fdd288f-fb31-4f76-aea0-41d85ba2e1f0': ldap.LdapRecord{uid:"5fdd288f-fb31-4f76-aea0-41d85ba2e1f0", attributes:map[string]string{"cn":"Firstname5 Lastname5", "givenName":"Firstname5", "homePhone":"030 11111115", "mobile":"1511111115", "sn":"Lastname5"}} 13:14:23 [TRC] [ db ] Get('L81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52') 13:14:23 [DBG] [server] DB value of uid '81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52': ldap.LdapRecord{uid:"81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52", attributes:map[string]string{"cn":"Firstname12 Lastname12", "givenName":"Firstname12", "homePhone":"030 111111112", "mobile":"01511 1111112", "sn":"Lastname12"}} 13:14:23 [DBG] [server] Loaded LDAP records []ldap.LdapRecord{ldap.LdapRecord{uid:"af084bb1-5336-4a85-86ab-f16050e91651", attributes:map[string]string{"cn":"Firstname1 Lastname1", "givenName":"Firstname1", "homePhone":"030 11111111", "mobile":"1511111111", "sn":"Lastname1"}}, ldap.LdapRecord{uid:"d242ab6c-c76f-45f3-802c-7bf8e6d5b3d5", attributes:map[string]string{"cn":"Firstname2 Lastname2", "givenName":"Firstname2", "homePhone":"030 11111112", "mobile":"1511111112", "sn":"Lastname2"}}, ldap.LdapRecord{uid:"00777273-e49b-477f-b689-1f258ce1519f", attributes:map[string]string{"cn":"Firstname10 Lastname10", "givenName":"Firstname10", "homePhone":"030 111111110", "mobile":"01511 1111110", "sn":"Lastname10"}}, ldap.LdapRecord{uid:"52af4c99-9483-4021-a1cb-815b35e62275", attributes:map[string]string{"cn":"Firstname8 Lastname8", "givenName":"Firstname8", "homePhone":"030 11111118", "mobile":"1511111118", "sn":"Lastname8"}}, ldap.LdapRecord{uid:"5d4d5e71-121a-4b3a-8f93-9edd9c676dc5", attributes:map[string]string{"cn":"Firstname4 Lastname4", "givenName":"Firstname4", "homePhone":"030 11111114", "mobile":"1511111114", "sn":"Lastname4"}}, ldap.LdapRecord{uid:"93dbbb06-34e4-4041-a0c6-cf5910071913", attributes:map[string]string{"cn":"Firstname3 Lastname3", "givenName":"Firstname3", "homePhone":"030 11111113", "mobile":"1511111113", "sn":"Lastname3"}}, ldap.LdapRecord{uid:"d61239b9-a214-4572-b409-f83e3573a6bf", attributes:map[string]string{"cn":"Firstname7 Lastname7", "givenName":"Firstname7", "homePhone":"030 11111117", "mobile":"1511111117", "sn":"Lastname7"}}, ldap.LdapRecord{uid:"d63e99d2-837c-40b4-83fd-25fd3a649a00", attributes:map[string]string{"cn":"Firstname11 Lastname11", "givenName":"Firstname11", "homePhone":"030 111111111", "mobile":"01511 1111111", "sn":"Lastname11"}}, ldap.LdapRecord{uid:"144d5240-c759-4491-97bc-6c1b8421432c", attributes:map[string]string{"cn":"Firstname6 Lastname6", "givenName":"Firstname6", "homePhone":"030 11111116", "mobile":"1511111116", "sn":"Lastname6"}}, ldap.LdapRecord{uid:"5f464ecb-9331-4018-86c1-a313c5b1ce1d", attributes:map[string]string{"cn":"Firstname9 Lastname9", "givenName":"Firstname9", "homePhone":"030 11111119", "mobile":"1511111119", "sn":"Lastname9"}}, ldap.LdapRecord{uid:"5fdd288f-fb31-4f76-aea0-41d85ba2e1f0", attributes:map[string]string{"cn":"Firstname5 Lastname5", "givenName":"Firstname5", "homePhone":"030 11111115", "mobile":"1511111115", "sn":"Lastname5"}}, ldap.LdapRecord{uid:"81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52", attributes:map[string]string{"cn":"Firstname12 Lastname12", "givenName":"Firstname12", "homePhone":"030 111111112", "mobile":"01511 1111112", "sn":"Lastname12"}}} 13:14:23 [TRC] [server] sortLdapResults() 13:14:23 [TRC] [server] insertLicenseInfo() 13:14:23 [TRC] [server] ldapResultEntry(ldapRec '{af084bb1-5336-4a85-86ab-f16050e91651 map[cn:Firstname1 Lastname1 givenName:Firstname1 homePhone:030 11111111 mobile:1511111111 sn:Lastname1]}') 13:14:23 [TRC] [server] ldapResultEntry(ldapRec '{d242ab6c-c76f-45f3-802c-7bf8e6d5b3d5 map[cn:Firstname2 Lastname2 givenName:Firstname2 homePhone:030 11111112 mobile:1511111112 sn:Lastname2]}') 13:14:23 [TRC] [server] ldapResultEntry(ldapRec '{00777273-e49b-477f-b689-1f258ce1519f map[cn:Firstname10 Lastname10 givenName:Firstname10 homePhone:030 111111110 mobile:01511 1111110 sn:Lastname10]}') 13:14:23 [TRC] [server] ldapResultEntry(ldapRec '{52af4c99-9483-4021-a1cb-815b35e62275 map[cn:Firstname8 Lastname8 givenName:Firstname8 homePhone:030 11111118 mobile:1511111118 sn:Lastname8]}') 13:14:23 [TRC] [server] ldapResultEntry(ldapRec '{5d4d5e71-121a-4b3a-8f93-9edd9c676dc5 map[cn:Firstname4 Lastname4 givenName:Firstname4 homePhone:030 11111114 mobile:1511111114 sn:Lastname4]}') 13:14:23 [TRC] [server] ldapResultEntry(ldapRec '{93dbbb06-34e4-4041-a0c6-cf5910071913 map[cn:Firstname3 Lastname3 givenName:Firstname3 homePhone:030 11111113 mobile:1511111113 sn:Lastname3]}') 13:14:23 [TRC] [server] ldapResultEntry(ldapRec '{d61239b9-a214-4572-b409-f83e3573a6bf map[cn:Firstname7 Lastname7 givenName:Firstname7 homePhone:030 11111117 mobile:1511111117 sn:Lastname7]}') 13:14:23 [TRC] [server] ldapResultEntry(ldapRec '{d63e99d2-837c-40b4-83fd-25fd3a649a00 map[cn:Firstname11 Lastname11 givenName:Firstname11 homePhone:030 111111111 mobile:01511 1111111 sn:Lastname11]}') 13:14:23 [TRC] [server] ldapResultEntry(ldapRec '{144d5240-c759-4491-97bc-6c1b8421432c map[cn:Firstname6 Lastname6 givenName:Firstname6 homePhone:030 11111116 mobile:1511111116 sn:Lastname6]}') 13:14:23 [TRC] [server] ldapResultEntry(ldapRec '{5f464ecb-9331-4018-86c1-a313c5b1ce1d map[cn:Firstname9 Lastname9 givenName:Firstname9 homePhone:030 11111119 mobile:1511111119 sn:Lastname9]}') 13:14:23 [TRC] [server] ldapResultEntry(ldapRec '{5fdd288f-fb31-4f76-aea0-41d85ba2e1f0 map[cn:Firstname5 Lastname5 givenName:Firstname5 homePhone:030 11111115 mobile:1511111115 sn:Lastname5]}') 13:14:23 [TRC] [server] ldapResultEntry(ldapRec '{81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52 map[cn:Firstname12 Lastname12 givenName:Firstname12 homePhone:030 111111112 mobile:01511 1111112 sn:Lastname12]}') 13:14:23 [INF] [server] 12 records returned for search request #1: (objectclass=*) from 192.168.xxx.xxx:47660 {dfa20d26} 13:14:23 [TRC] [server] handler->Bind(bindDN='cn=phone', bindSimplePw='xxx') 13:14:23 [TRC] [server] handler->Search(boundDN='cn=phone', searchReq ldap.SearchRequest{BaseDN:"dc=xxx, dc=local", Scope:2, DerefAliases:0, SizeLimit:1000, TimeLimit:60, TypesOnly:false, Filter:"(|(|(cn=*)(sn=*)(givenName=*)(company=*))(|(homePhone=*)(telephoneNumber=*)(mobile=*)))", Attributes:[]string{"cn", "company", "uid", "telephoneNumber", "mobile", "homePhone", "mail"}, Controls:[]ldap.Control{}}, ...) 13:14:23 [DBG] [server] Correlation Text '192.168.xxx.xxx:48237|2021-07-22 13:14:23.9947885 +0800 +08 m=+1169.950420901|2' = 0x6beddcb1 = 6beddcb1 13:14:23 [INF] [server] Search request #2: (|(|(cn=*)(sn=*)(givenName=*)(company=*))(|(homePhone=*)(telephoneNumber=*)(mobile=*))) from 192.168.3.251:48237 {6beddcb1} 13:14:23 [DBG] [server] Stats &ldap.Stats{Conns:2, Binds:2, Unbinds:1, Searches:2, statsMutex:sync.Mutex{state:0, sema:0x0}} 13:14:24 [TRC] [server] Search(searchReq ldap.SearchRequest{BaseDN:"dc=xxx, dc=local", Scope:2, DerefAliases:0, SizeLimit:1000, TimeLimit:60, TypesOnly:false, Filter:"(|(|(cn=*)(sn=*)(givenName=*)(company=*))(|(homePhone=*)(telephoneNumber=*)(mobile=*)))", Attributes:[]string{"cn", "company", "uid", "telephoneNumber", "mobile", "homePhone", "mail"}, Controls:[]ldap.Control{}}), ... 13:14:24 [TRC] [server] checkAttributes() 13:14:24 [TRC] [server] isAttributeDefined('cn') 13:14:24 [TRC] [server] isAttributeDefined('company') 13:14:24 [TRC] [server] isAttributeDefined('uid') 13:14:24 [WRN] [server] Unconfigured LDAP attribute 'uid' requested. Add a [ldap.map.uid...] mapping to the config! 13:14:24 [TRC] [server] isAttributeDefined('telephoneNumber') 13:14:24 [TRC] [server] isAttributeDefined('mobile') 13:14:24 [TRC] [server] isAttributeDefined('homePhone') 13:14:24 [TRC] [server] isAttributeDefined('mail') 13:14:24 [DBG] [server] s.request.Filter '(|(|(cn=*)(sn=*)(givenName=*)(company=*))(|(homePhone=*)(telephoneNumber=*)(mobile=*)))' 13:14:24 [TRC] [server] uidsByFilter(Description 'Or' = '�∟�☻cn�☻sn� givenName�company�$� homePhone�☼telephoneNumber�♠mobile', Scope 2 (Whole Subtree), 2 childs) 13:14:24 [DBG] [server] OR filter child 1 of 2 = '�☻cn�☻sn� givenName�company' 13:14:24 [TRC] [server] uidsByFilter(Description 'Or' = '�☻cn�☻sn� givenName�company', Scope 2 (Whole Subtree), 4 childs) 13:14:24 [DBG] [server] OR filter child 1 of 4 = 'cn' 13:14:24 [TRC] [server] uidsByFilter(Description 'Present' = 'cn', Scope 2 (Whole Subtree), 0 childs) 13:14:24 [TRC] [server] filterPresent(Description 'Present' = 'cn', uid = '', Scope 2 (Whole Subtree), 0 childs) 13:14:24 [DBG] [server] filter &ber.Packet{ClassType:0x80, TagType:0x0, Tag:0x7, Value:interface {}(nil), ByteValue:[]uint8(nil), Data:(*bytes.Buffer)(0xc0004db5c0), Children:[]*ber.Packet{}, Description:"Present"} 13:14:24 [TRC] [server] isFilterAttributeDefined('cn') 13:14:24 [TRC] [server] isAttributeDefined('cn') 13:14:24 [TRC] [server] getDbValues(prefix 'lcn.', regexp '(?i)^lcn\.') 13:14:24 [DBG] [server] Regexp matching key 'lcn.firstname1 lastname1.af084bb1-5336-4a85-86ab-f16050e91651' 13:14:24 [DBG] [server] Regexp matching key 'lcn.firstname10 lastname10.00777273-e49b-477f-b689-1f258ce1519f' 13:14:24 [DBG] [server] Regexp matching key 'lcn.firstname11 lastname11.d63e99d2-837c-40b4-83fd-25fd3a649a00' 13:14:24 [DBG] [server] Regexp matching key 'lcn.firstname12 lastname12.81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52' 13:14:24 [DBG] [server] Regexp matching key 'lcn.firstname2 lastname2.d242ab6c-c76f-45f3-802c-7bf8e6d5b3d5' 13:14:24 [DBG] [server] Regexp matching key 'lcn.firstname3 lastname3.93dbbb06-34e4-4041-a0c6-cf5910071913' 13:14:24 [DBG] [server] Regexp matching key 'lcn.firstname4 lastname4.5d4d5e71-121a-4b3a-8f93-9edd9c676dc5' 13:14:24 [DBG] [server] Regexp matching key 'lcn.firstname5 lastname5.5fdd288f-fb31-4f76-aea0-41d85ba2e1f0' 13:14:24 [DBG] [server] Regexp matching key 'lcn.firstname6 lastname6.144d5240-c759-4491-97bc-6c1b8421432c' 13:14:24 [DBG] [server] Regexp matching key 'lcn.firstname7 lastname7.d61239b9-a214-4572-b409-f83e3573a6bf' 13:14:24 [DBG] [server] Regexp matching key 'lcn.firstname8 lastname8.52af4c99-9483-4021-a1cb-815b35e62275' 13:14:24 [DBG] [server] Regexp matching key 'lcn.firstname9 lastname9.5f464ecb-9331-4018-86c1-a313c5b1ce1d' 13:14:24 [TRC] [server] filterPresent return with matchingUids = map[string]bool{"00777273-e49b-477f-b689-1f258ce1519f":true, "144d5240-c759-4491-97bc-6c1b8421432c":true, "52af4c99-9483-4021-a1cb-815b35e62275":true, "5d4d5e71-121a-4b3a-8f93-9edd9c676dc5":true, "5f464ecb-9331-4018-86c1-a313c5b1ce1d":true, "5fdd288f-fb31-4f76-aea0-41d85ba2e1f0":true, "81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52":true, "93dbbb06-34e4-4041-a0c6-cf5910071913":true, "af084bb1-5336-4a85-86ab-f16050e91651":true, "d242ab6c-c76f-45f3-802c-7bf8e6d5b3d5":true, "d61239b9-a214-4572-b409-f83e3573a6bf":true, "d63e99d2-837c-40b4-83fd-25fd3a649a00":true} 13:14:24 [DBG] [server] OR filter child 2 of 4 = 'sn' 13:14:24 [TRC] [server] uidsByFilter(Description 'Present' = 'sn', Scope 2 (Whole Subtree), 0 childs) 13:14:24 [TRC] [server] filterPresent(Description 'Present' = 'sn', uid = '', Scope 2 (Whole Subtree), 0 childs) 13:14:24 [DBG] [server] filter &ber.Packet{ClassType:0x80, TagType:0x0, Tag:0x7, Value:interface {}(nil), ByteValue:[]uint8(nil), Data:(*bytes.Buffer)(0xc0004db5f0), Children:[]*ber.Packet{}, Description:"Present"} 13:14:24 [TRC] [server] isFilterAttributeDefined('sn') 13:14:24 [TRC] [server] isAttributeDefined('sn') 13:14:24 [TRC] [server] getDbValues(prefix 'lsn.', regexp '(?i)^lsn\.') 13:14:24 [DBG] [server] Regexp matching key 'lsn.lastname1.af084bb1-5336-4a85-86ab-f16050e91651' 13:14:24 [DBG] [server] Regexp matching key 'lsn.lastname10.00777273-e49b-477f-b689-1f258ce1519f' 13:14:24 [DBG] [server] Regexp matching key 'lsn.lastname11.d63e99d2-837c-40b4-83fd-25fd3a649a00' 13:14:24 [DBG] [server] Regexp matching key 'lsn.lastname12.81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52' 13:14:24 [DBG] [server] Regexp matching key 'lsn.lastname2.d242ab6c-c76f-45f3-802c-7bf8e6d5b3d5' 13:14:24 [DBG] [server] Regexp matching key 'lsn.lastname3.93dbbb06-34e4-4041-a0c6-cf5910071913' 13:14:24 [DBG] [server] Regexp matching key 'lsn.lastname4.5d4d5e71-121a-4b3a-8f93-9edd9c676dc5' 13:14:24 [DBG] [server] Regexp matching key 'lsn.lastname5.5fdd288f-fb31-4f76-aea0-41d85ba2e1f0' 13:14:24 [DBG] [server] Regexp matching key 'lsn.lastname6.144d5240-c759-4491-97bc-6c1b8421432c' 13:14:24 [DBG] [server] Regexp matching key 'lsn.lastname7.d61239b9-a214-4572-b409-f83e3573a6bf' 13:14:24 [DBG] [server] Regexp matching key 'lsn.lastname8.52af4c99-9483-4021-a1cb-815b35e62275' 13:14:24 [DBG] [server] Regexp matching key 'lsn.lastname9.5f464ecb-9331-4018-86c1-a313c5b1ce1d' 13:14:24 [TRC] [server] filterPresent return with matchingUids = map[string]bool{"00777273-e49b-477f-b689-1f258ce1519f":true, "144d5240-c759-4491-97bc-6c1b8421432c":true, "52af4c99-9483-4021-a1cb-815b35e62275":true, "5d4d5e71-121a-4b3a-8f93-9edd9c676dc5":true, "5f464ecb-9331-4018-86c1-a313c5b1ce1d":true, "5fdd288f-fb31-4f76-aea0-41d85ba2e1f0":true, "81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52":true, "93dbbb06-34e4-4041-a0c6-cf5910071913":true, "af084bb1-5336-4a85-86ab-f16050e91651":true, "d242ab6c-c76f-45f3-802c-7bf8e6d5b3d5":true, "d61239b9-a214-4572-b409-f83e3573a6bf":true, "d63e99d2-837c-40b4-83fd-25fd3a649a00":true} 13:14:24 [DBG] [server] OR filter child 3 of 4 = 'givenName' 13:14:24 [TRC] [server] uidsByFilter(Description 'Present' = 'givenName', Scope 2 (Whole Subtree), 0 childs) 13:14:24 [TRC] [server] filterPresent(Description 'Present' = 'givenName', uid = '', Scope 2 (Whole Subtree), 0 childs) 13:14:24 [DBG] [server] filter &ber.Packet{ClassType:0x80, TagType:0x0, Tag:0x7, Value:interface {}(nil), ByteValue:[]uint8(nil), Data:(*bytes.Buffer)(0xc0004db620), Children:[]*ber.Packet{}, Description:"Present"} 13:14:24 [TRC] [server] isFilterAttributeDefined('givenName') 13:14:24 [TRC] [server] isAttributeDefined('givenName') 13:14:24 [TRC] [server] getDbValues(prefix 'lgivenName.', regexp '(?i)^lgivenName\.') 13:14:24 [DBG] [server] Regexp matching key 'lgivenName.firstname1.af084bb1-5336-4a85-86ab-f16050e91651' 13:14:24 [DBG] [server] Regexp matching key 'lgivenName.firstname10.00777273-e49b-477f-b689-1f258ce1519f' 13:14:24 [DBG] [server] Regexp matching key 'lgivenName.firstname11.d63e99d2-837c-40b4-83fd-25fd3a649a00' 13:14:24 [DBG] [server] Regexp matching key 'lgivenName.firstname12.81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52' 13:14:24 [DBG] [server] Regexp matching key 'lgivenName.firstname2.d242ab6c-c76f-45f3-802c-7bf8e6d5b3d5' 13:14:24 [DBG] [server] Regexp matching key 'lgivenName.firstname3.93dbbb06-34e4-4041-a0c6-cf5910071913' 13:14:24 [DBG] [server] Regexp matching key 'lgivenName.firstname4.5d4d5e71-121a-4b3a-8f93-9edd9c676dc5' 13:14:24 [DBG] [server] Regexp matching key 'lgivenName.firstname5.5fdd288f-fb31-4f76-aea0-41d85ba2e1f0' 13:14:24 [DBG] [server] Regexp matching key 'lgivenName.firstname6.144d5240-c759-4491-97bc-6c1b8421432c' 13:14:24 [DBG] [server] Regexp matching key 'lgivenName.firstname7.d61239b9-a214-4572-b409-f83e3573a6bf' 13:14:24 [DBG] [server] Regexp matching key 'lgivenName.firstname8.52af4c99-9483-4021-a1cb-815b35e62275' 13:14:24 [DBG] [server] Regexp matching key 'lgivenName.firstname9.5f464ecb-9331-4018-86c1-a313c5b1ce1d' 13:14:24 [TRC] [server] filterPresent return with matchingUids = map[string]bool{"00777273-e49b-477f-b689-1f258ce1519f":true, "144d5240-c759-4491-97bc-6c1b8421432c":true, "52af4c99-9483-4021-a1cb-815b35e62275":true, "5d4d5e71-121a-4b3a-8f93-9edd9c676dc5":true, "5f464ecb-9331-4018-86c1-a313c5b1ce1d":true, "5fdd288f-fb31-4f76-aea0-41d85ba2e1f0":true, "81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52":true, "93dbbb06-34e4-4041-a0c6-cf5910071913":true, "af084bb1-5336-4a85-86ab-f16050e91651":true, "d242ab6c-c76f-45f3-802c-7bf8e6d5b3d5":true, "d61239b9-a214-4572-b409-f83e3573a6bf":true, "d63e99d2-837c-40b4-83fd-25fd3a649a00":true} 13:14:24 [DBG] [server] OR filter child 4 of 4 = 'company' 13:14:24 [TRC] [server] uidsByFilter(Description 'Present' = 'company', Scope 2 (Whole Subtree), 0 childs) 13:14:24 [TRC] [server] filterPresent(Description 'Present' = 'company', uid = '', Scope 2 (Whole Subtree), 0 childs) 13:14:24 [DBG] [server] filter &ber.Packet{ClassType:0x80, TagType:0x0, Tag:0x7, Value:interface {}(nil), ByteValue:[]uint8(nil), Data:(*bytes.Buffer)(0xc0004db650), Children:[]*ber.Packet{}, Description:"Present"} 13:14:24 [TRC] [server] isFilterAttributeDefined('company') 13:14:24 [TRC] [server] isAttributeDefined('company') 13:14:24 [TRC] [server] getDbValues(prefix 'lcompany.', regexp '(?i)^lcompany\.') 13:14:24 [TRC] [server] filterPresent return with matchingUids = map[string]bool{} 13:14:24 [DBG] [server] OR filter child 2 of 2 = '� homePhone�☼telephoneNumber�♠mobile' 13:14:24 [TRC] [server] uidsByFilter(Description 'Or' = '� homePhone�☼telephoneNumber�♠mobile', Scope 2 (Whole Subtree), 3 childs) 13:14:24 [DBG] [server] OR filter child 1 of 3 = 'homePhone' 13:14:24 [TRC] [server] uidsByFilter(Description 'Present' = 'homePhone', Scope 2 (Whole Subtree), 0 childs) 13:14:24 [TRC] [server] filterPresent(Description 'Present' = 'homePhone', uid = '', Scope 2 (Whole Subtree), 0 childs) 13:14:24 [DBG] [server] filter &ber.Packet{ClassType:0x80, TagType:0x0, Tag:0x7, Value:interface {}(nil), ByteValue:[]uint8(nil), Data:(*bytes.Buffer)(0xc0004db6b0), Children:[]*ber.Packet{}, Description:"Present"} 13:14:24 [TRC] [server] isFilterAttributeDefined('homePhone') 13:14:24 [TRC] [server] isAttributeDefined('homePhone') 13:14:24 [TRC] [server] getDbValues(prefix 'e', regexp '(?i)^e') 13:14:24 [DBG] [server] Regexp matching key 'e+491511111111.af084bb1-5336-4a85-86ab-f16050e91651' 13:14:24 [DBG] [server] Regexp matching key 'e+4915111111110.00777273-e49b-477f-b689-1f258ce1519f' 13:14:24 [DBG] [server] Regexp matching key 'e+4915111111111.d63e99d2-837c-40b4-83fd-25fd3a649a00' 13:14:24 [DBG] [server] Regexp matching key 'e+4915111111112.81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52' 13:14:24 [DBG] [server] Regexp matching key 'e+491511111112.d242ab6c-c76f-45f3-802c-7bf8e6d5b3d5' 13:14:24 [DBG] [server] Regexp matching key 'e+491511111113.93dbbb06-34e4-4041-a0c6-cf5910071913' 13:14:24 [DBG] [server] Regexp matching key 'e+491511111114.5d4d5e71-121a-4b3a-8f93-9edd9c676dc5' 13:14:24 [DBG] [server] Regexp matching key 'e+491511111115.5fdd288f-fb31-4f76-aea0-41d85ba2e1f0' 13:14:24 [DBG] [server] Regexp matching key 'e+491511111116.144d5240-c759-4491-97bc-6c1b8421432c' 13:14:24 [DBG] [server] Regexp matching key 'e+491511111117.d61239b9-a214-4572-b409-f83e3573a6bf' 13:14:24 [DBG] [server] Regexp matching key 'e+491511111118.52af4c99-9483-4021-a1cb-815b35e62275' 13:14:24 [DBG] [server] Regexp matching key 'e+491511111119.5f464ecb-9331-4018-86c1-a313c5b1ce1d' 13:14:24 [DBG] [server] Regexp matching key 'e+493011111111.af084bb1-5336-4a85-86ab-f16050e91651' 13:14:24 [DBG] [server] Regexp matching key 'e+4930111111110.00777273-e49b-477f-b689-1f258ce1519f' 13:14:24 [DBG] [server] Regexp matching key 'e+4930111111111.d63e99d2-837c-40b4-83fd-25fd3a649a00' 13:14:24 [DBG] [server] Regexp matching key 'e+4930111111112.81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52' 13:14:24 [DBG] [server] Regexp matching key 'e+493011111112.d242ab6c-c76f-45f3-802c-7bf8e6d5b3d5' 13:14:24 [DBG] [server] Regexp matching key 'e+493011111113.93dbbb06-34e4-4041-a0c6-cf5910071913' 13:14:24 [DBG] [server] Regexp matching key 'e+493011111114.5d4d5e71-121a-4b3a-8f93-9edd9c676dc5' 13:14:24 [DBG] [server] Regexp matching key 'e+493011111115.5fdd288f-fb31-4f76-aea0-41d85ba2e1f0' 13:14:24 [DBG] [server] Regexp matching key 'e+493011111116.144d5240-c759-4491-97bc-6c1b8421432c' 13:14:24 [DBG] [server] Regexp matching key 'e+493011111117.d61239b9-a214-4572-b409-f83e3573a6bf' 13:14:24 [DBG] [server] Regexp matching key 'e+493011111118.52af4c99-9483-4021-a1cb-815b35e62275' 13:14:24 [DBG] [server] Regexp matching key 'e+493011111119.5f464ecb-9331-4018-86c1-a313c5b1ce1d' 13:14:24 [TRC] [server] filterPresent return with matchingUids = map[string]bool{"00777273-e49b-477f-b689-1f258ce1519f":true, "144d5240-c759-4491-97bc-6c1b8421432c":true, "52af4c99-9483-4021-a1cb-815b35e62275":true, "5d4d5e71-121a-4b3a-8f93-9edd9c676dc5":true, "5f464ecb-9331-4018-86c1-a313c5b1ce1d":true, "5fdd288f-fb31-4f76-aea0-41d85ba2e1f0":true, "81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52":true, "93dbbb06-34e4-4041-a0c6-cf5910071913":true, "af084bb1-5336-4a85-86ab-f16050e91651":true, "d242ab6c-c76f-45f3-802c-7bf8e6d5b3d5":true, "d61239b9-a214-4572-b409-f83e3573a6bf":true, "d63e99d2-837c-40b4-83fd-25fd3a649a00":true} 13:14:24 [DBG] [server] OR filter child 2 of 3 = 'telephoneNumber' 13:14:24 [TRC] [server] uidsByFilter(Description 'Present' = 'telephoneNumber', Scope 2 (Whole Subtree), 0 childs) 13:14:24 [TRC] [server] filterPresent(Description 'Present' = 'telephoneNumber', uid = '', Scope 2 (Whole Subtree), 0 childs) 13:14:24 [DBG] [server] filter &ber.Packet{ClassType:0x80, TagType:0x0, Tag:0x7, Value:interface {}(nil), ByteValue:[]uint8(nil), Data:(*bytes.Buffer)(0xc0004db6e0), Children:[]*ber.Packet{}, Description:"Present"} 13:14:24 [TRC] [server] isFilterAttributeDefined('telephoneNumber') 13:14:24 [TRC] [server] isAttributeDefined('telephoneNumber') 13:14:24 [TRC] [server] getDbValues(prefix 'e', regexp '(?i)^e') 13:14:24 [DBG] [server] Regexp matching key 'e+491511111111.af084bb1-5336-4a85-86ab-f16050e91651' 13:14:24 [DBG] [server] Regexp matching key 'e+4915111111110.00777273-e49b-477f-b689-1f258ce1519f' 13:14:24 [DBG] [server] Regexp matching key 'e+4915111111111.d63e99d2-837c-40b4-83fd-25fd3a649a00' 13:14:24 [DBG] [server] Regexp matching key 'e+4915111111112.81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52' 13:14:24 [DBG] [server] Regexp matching key 'e+491511111112.d242ab6c-c76f-45f3-802c-7bf8e6d5b3d5' 13:14:24 [DBG] [server] Regexp matching key 'e+491511111113.93dbbb06-34e4-4041-a0c6-cf5910071913' 13:14:24 [DBG] [server] Regexp matching key 'e+491511111114.5d4d5e71-121a-4b3a-8f93-9edd9c676dc5' 13:14:24 [DBG] [server] Regexp matching key 'e+491511111115.5fdd288f-fb31-4f76-aea0-41d85ba2e1f0' 13:14:24 [DBG] [server] Regexp matching key 'e+491511111116.144d5240-c759-4491-97bc-6c1b8421432c' 13:14:24 [DBG] [server] Regexp matching key 'e+491511111117.d61239b9-a214-4572-b409-f83e3573a6bf' 13:14:24 [DBG] [server] Regexp matching key 'e+491511111118.52af4c99-9483-4021-a1cb-815b35e62275' 13:14:24 [DBG] [server] Regexp matching key 'e+491511111119.5f464ecb-9331-4018-86c1-a313c5b1ce1d' 13:14:24 [DBG] [server] Regexp matching key 'e+493011111111.af084bb1-5336-4a85-86ab-f16050e91651' 13:14:24 [DBG] [server] Regexp matching key 'e+4930111111110.00777273-e49b-477f-b689-1f258ce1519f' 13:14:24 [DBG] [server] Regexp matching key 'e+4930111111111.d63e99d2-837c-40b4-83fd-25fd3a649a00' 13:14:24 [DBG] [server] Regexp matching key 'e+4930111111112.81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52' 13:14:24 [DBG] [server] Regexp matching key 'e+493011111112.d242ab6c-c76f-45f3-802c-7bf8e6d5b3d5' 13:14:24 [DBG] [server] Regexp matching key 'e+493011111113.93dbbb06-34e4-4041-a0c6-cf5910071913' 13:14:24 [DBG] [server] Regexp matching key 'e+493011111114.5d4d5e71-121a-4b3a-8f93-9edd9c676dc5' 13:14:24 [DBG] [server] Regexp matching key 'e+493011111115.5fdd288f-fb31-4f76-aea0-41d85ba2e1f0' 13:14:24 [DBG] [server] Regexp matching key 'e+493011111116.144d5240-c759-4491-97bc-6c1b8421432c' 13:14:24 [DBG] [server] Regexp matching key 'e+493011111117.d61239b9-a214-4572-b409-f83e3573a6bf' 13:14:24 [DBG] [server] Regexp matching key 'e+493011111118.52af4c99-9483-4021-a1cb-815b35e62275' 13:14:24 [DBG] [server] Regexp matching key 'e+493011111119.5f464ecb-9331-4018-86c1-a313c5b1ce1d' 13:14:24 [TRC] [server] filterPresent return with matchingUids = map[string]bool{"00777273-e49b-477f-b689-1f258ce1519f":true, "144d5240-c759-4491-97bc-6c1b8421432c":true, "52af4c99-9483-4021-a1cb-815b35e62275":true, "5d4d5e71-121a-4b3a-8f93-9edd9c676dc5":true, "5f464ecb-9331-4018-86c1-a313c5b1ce1d":true, "5fdd288f-fb31-4f76-aea0-41d85ba2e1f0":true, "81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52":true, "93dbbb06-34e4-4041-a0c6-cf5910071913":true, "af084bb1-5336-4a85-86ab-f16050e91651":true, "d242ab6c-c76f-45f3-802c-7bf8e6d5b3d5":true, "d61239b9-a214-4572-b409-f83e3573a6bf":true, "d63e99d2-837c-40b4-83fd-25fd3a649a00":true} 13:14:24 [DBG] [server] OR filter child 3 of 3 = 'mobile' 13:14:24 [TRC] [server] uidsByFilter(Description 'Present' = 'mobile', Scope 2 (Whole Subtree), 0 childs) 13:14:24 [TRC] [server] filterPresent(Description 'Present' = 'mobile', uid = '', Scope 2 (Whole Subtree), 0 childs) 13:14:24 [DBG] [server] filter &ber.Packet{ClassType:0x80, TagType:0x0, Tag:0x7, Value:interface {}(nil), ByteValue:[]uint8(nil), Data:(*bytes.Buffer)(0xc0004db710), Children:[]*ber.Packet{}, Description:"Present"} 13:14:24 [TRC] [server] isFilterAttributeDefined('mobile') 13:14:24 [TRC] [server] isAttributeDefined('mobile') 13:14:24 [TRC] [server] getDbValues(prefix 'e', regexp '(?i)^e') 13:14:24 [DBG] [server] Regexp matching key 'e+491511111111.af084bb1-5336-4a85-86ab-f16050e91651' 13:14:24 [DBG] [server] Regexp matching key 'e+4915111111110.00777273-e49b-477f-b689-1f258ce1519f' 13:14:24 [DBG] [server] Regexp matching key 'e+4915111111111.d63e99d2-837c-40b4-83fd-25fd3a649a00' 13:14:24 [DBG] [server] Regexp matching key 'e+4915111111112.81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52' 13:14:24 [DBG] [server] Regexp matching key 'e+491511111112.d242ab6c-c76f-45f3-802c-7bf8e6d5b3d5' 13:14:24 [DBG] [server] Regexp matching key 'e+491511111113.93dbbb06-34e4-4041-a0c6-cf5910071913' 13:14:24 [DBG] [server] Regexp matching key 'e+491511111114.5d4d5e71-121a-4b3a-8f93-9edd9c676dc5' 13:14:24 [DBG] [server] Regexp matching key 'e+491511111115.5fdd288f-fb31-4f76-aea0-41d85ba2e1f0' 13:14:24 [DBG] [server] Regexp matching key 'e+491511111116.144d5240-c759-4491-97bc-6c1b8421432c' 13:14:24 [DBG] [server] Regexp matching key 'e+491511111117.d61239b9-a214-4572-b409-f83e3573a6bf' 13:14:24 [DBG] [server] Regexp matching key 'e+491511111118.52af4c99-9483-4021-a1cb-815b35e62275' 13:14:24 [DBG] [server] Regexp matching key 'e+491511111119.5f464ecb-9331-4018-86c1-a313c5b1ce1d' 13:14:24 [DBG] [server] Regexp matching key 'e+493011111111.af084bb1-5336-4a85-86ab-f16050e91651' 13:14:24 [DBG] [server] Regexp matching key 'e+4930111111110.00777273-e49b-477f-b689-1f258ce1519f' 13:14:24 [DBG] [server] Regexp matching key 'e+4930111111111.d63e99d2-837c-40b4-83fd-25fd3a649a00' 13:14:24 [DBG] [server] Regexp matching key 'e+4930111111112.81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52' 13:14:24 [DBG] [server] Regexp matching key 'e+493011111112.d242ab6c-c76f-45f3-802c-7bf8e6d5b3d5' 13:14:24 [DBG] [server] Regexp matching key 'e+493011111113.93dbbb06-34e4-4041-a0c6-cf5910071913' 13:14:24 [DBG] [server] Regexp matching key 'e+493011111114.5d4d5e71-121a-4b3a-8f93-9edd9c676dc5' 13:14:24 [DBG] [server] Regexp matching key 'e+493011111115.5fdd288f-fb31-4f76-aea0-41d85ba2e1f0' 13:14:24 [DBG] [server] Regexp matching key 'e+493011111116.144d5240-c759-4491-97bc-6c1b8421432c' 13:14:24 [DBG] [server] Regexp matching key 'e+493011111117.d61239b9-a214-4572-b409-f83e3573a6bf' 13:14:24 [DBG] [server] Regexp matching key 'e+493011111118.52af4c99-9483-4021-a1cb-815b35e62275' 13:14:24 [DBG] [server] Regexp matching key 'e+493011111119.5f464ecb-9331-4018-86c1-a313c5b1ce1d' 13:14:24 [TRC] [server] filterPresent return with matchingUids = map[string]bool{"00777273-e49b-477f-b689-1f258ce1519f":true, "144d5240-c759-4491-97bc-6c1b8421432c":true, "52af4c99-9483-4021-a1cb-815b35e62275":true, "5d4d5e71-121a-4b3a-8f93-9edd9c676dc5":true, "5f464ecb-9331-4018-86c1-a313c5b1ce1d":true, "5fdd288f-fb31-4f76-aea0-41d85ba2e1f0":true, "81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52":true, "93dbbb06-34e4-4041-a0c6-cf5910071913":true, "af084bb1-5336-4a85-86ab-f16050e91651":true, "d242ab6c-c76f-45f3-802c-7bf8e6d5b3d5":true, "d61239b9-a214-4572-b409-f83e3573a6bf":true, "d63e99d2-837c-40b4-83fd-25fd3a649a00":true} 13:14:24 [DBG] [server] Collected UIDs map[string]bool{"00777273-e49b-477f-b689-1f258ce1519f":true, "144d5240-c759-4491-97bc-6c1b8421432c":true, "52af4c99-9483-4021-a1cb-815b35e62275":true, "5d4d5e71-121a-4b3a-8f93-9edd9c676dc5":true, "5f464ecb-9331-4018-86c1-a313c5b1ce1d":true, "5fdd288f-fb31-4f76-aea0-41d85ba2e1f0":true, "81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52":true, "93dbbb06-34e4-4041-a0c6-cf5910071913":true, "af084bb1-5336-4a85-86ab-f16050e91651":true, "d242ab6c-c76f-45f3-802c-7bf8e6d5b3d5":true, "d61239b9-a214-4572-b409-f83e3573a6bf":true, "d63e99d2-837c-40b4-83fd-25fd3a649a00":true} 13:14:24 [TRC] [intern] CountFlux() 13:14:24 [TRC] [server] loadLdapResults() 13:14:24 [TRC] [ db ] Get('L52af4c99-9483-4021-a1cb-815b35e62275') 13:14:24 [DBG] [server] DB value of uid '52af4c99-9483-4021-a1cb-815b35e62275': ldap.LdapRecord{uid:"52af4c99-9483-4021-a1cb-815b35e62275", attributes:map[string]string{"cn":"Firstname8 Lastname8", "givenName":"Firstname8", "homePhone":"030 11111118", "mobile":"1511111118", "sn":"Lastname8"}} 13:14:24 [TRC] [ db ] Get('Laf084bb1-5336-4a85-86ab-f16050e91651') 13:14:24 [DBG] [server] DB value of uid 'af084bb1-5336-4a85-86ab-f16050e91651': ldap.LdapRecord{uid:"af084bb1-5336-4a85-86ab-f16050e91651", attributes:map[string]string{"cn":"Firstname1 Lastname1", "givenName":"Firstname1", "homePhone":"030 11111111", "mobile":"1511111111", "sn":"Lastname1"}} 13:14:24 [TRC] [ db ] Get('Ld63e99d2-837c-40b4-83fd-25fd3a649a00') 13:14:24 [DBG] [server] DB value of uid 'd63e99d2-837c-40b4-83fd-25fd3a649a00': ldap.LdapRecord{uid:"d63e99d2-837c-40b4-83fd-25fd3a649a00", attributes:map[string]string{"cn":"Firstname11 Lastname11", "givenName":"Firstname11", "homePhone":"030 111111111", "mobile":"01511 1111111", "sn":"Lastname11"}} 13:14:24 [TRC] [ db ] Get('L93dbbb06-34e4-4041-a0c6-cf5910071913') 13:14:24 [DBG] [server] DB value of uid '93dbbb06-34e4-4041-a0c6-cf5910071913': ldap.LdapRecord{uid:"93dbbb06-34e4-4041-a0c6-cf5910071913", attributes:map[string]string{"cn":"Firstname3 Lastname3", "givenName":"Firstname3", "homePhone":"030 11111113", "mobile":"1511111113", "sn":"Lastname3"}} 13:14:24 [TRC] [ db ] Get('L00777273-e49b-477f-b689-1f258ce1519f') 13:14:24 [DBG] [server] DB value of uid '00777273-e49b-477f-b689-1f258ce1519f': ldap.LdapRecord{uid:"00777273-e49b-477f-b689-1f258ce1519f", attributes:map[string]string{"cn":"Firstname10 Lastname10", "givenName":"Firstname10", "homePhone":"030 111111110", "mobile":"01511 1111110", "sn":"Lastname10"}} 13:14:24 [TRC] [ db ] Get('L144d5240-c759-4491-97bc-6c1b8421432c') 13:14:24 [DBG] [server] DB value of uid '144d5240-c759-4491-97bc-6c1b8421432c': ldap.LdapRecord{uid:"144d5240-c759-4491-97bc-6c1b8421432c", attributes:map[string]string{"cn":"Firstname6 Lastname6", "givenName":"Firstname6", "homePhone":"030 11111116", "mobile":"1511111116", "sn":"Lastname6"}} 13:14:24 [TRC] [ db ] Get('L81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52') 13:14:24 [DBG] [server] DB value of uid '81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52': ldap.LdapRecord{uid:"81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52", attributes:map[string]string{"cn":"Firstname12 Lastname12", "givenName":"Firstname12", "homePhone":"030 111111112", "mobile":"01511 1111112", "sn":"Lastname12"}} 13:14:24 [TRC] [ db ] Get('Ld61239b9-a214-4572-b409-f83e3573a6bf') 13:14:24 [DBG] [server] DB value of uid 'd61239b9-a214-4572-b409-f83e3573a6bf': ldap.LdapRecord{uid:"d61239b9-a214-4572-b409-f83e3573a6bf", attributes:map[string]string{"cn":"Firstname7 Lastname7", "givenName":"Firstname7", "homePhone":"030 11111117", "mobile":"1511111117", "sn":"Lastname7"}} 13:14:24 [TRC] [ db ] Get('Ld242ab6c-c76f-45f3-802c-7bf8e6d5b3d5') 13:14:24 [DBG] [server] DB value of uid 'd242ab6c-c76f-45f3-802c-7bf8e6d5b3d5': ldap.LdapRecord{uid:"d242ab6c-c76f-45f3-802c-7bf8e6d5b3d5", attributes:map[string]string{"cn":"Firstname2 Lastname2", "givenName":"Firstname2", "homePhone":"030 11111112", "mobile":"1511111112", "sn":"Lastname2"}} 13:14:24 [TRC] [ db ] Get('L5d4d5e71-121a-4b3a-8f93-9edd9c676dc5') 13:14:24 [DBG] [server] DB value of uid '5d4d5e71-121a-4b3a-8f93-9edd9c676dc5': ldap.LdapRecord{uid:"5d4d5e71-121a-4b3a-8f93-9edd9c676dc5", attributes:map[string]string{"cn":"Firstname4 Lastname4", "givenName":"Firstname4", "homePhone":"030 11111114", "mobile":"1511111114", "sn":"Lastname4"}} 13:14:24 [TRC] [ db ] Get('L5fdd288f-fb31-4f76-aea0-41d85ba2e1f0') 13:14:24 [DBG] [server] DB value of uid '5fdd288f-fb31-4f76-aea0-41d85ba2e1f0': ldap.LdapRecord{uid:"5fdd288f-fb31-4f76-aea0-41d85ba2e1f0", attributes:map[string]string{"cn":"Firstname5 Lastname5", "givenName":"Firstname5", "homePhone":"030 11111115", "mobile":"1511111115", "sn":"Lastname5"}} 13:14:24 [TRC] [ db ] Get('L5f464ecb-9331-4018-86c1-a313c5b1ce1d') 13:14:24 [DBG] [server] DB value of uid '5f464ecb-9331-4018-86c1-a313c5b1ce1d': ldap.LdapRecord{uid:"5f464ecb-9331-4018-86c1-a313c5b1ce1d", attributes:map[string]string{"cn":"Firstname9 Lastname9", "givenName":"Firstname9", "homePhone":"030 11111119", "mobile":"1511111119", "sn":"Lastname9"}} 13:14:24 [DBG] [server] Loaded LDAP records []ldap.LdapRecord{ldap.LdapRecord{uid:"52af4c99-9483-4021-a1cb-815b35e62275", attributes:map[string]string{"cn":"Firstname8 Lastname8", "givenName":"Firstname8", "homePhone":"030 11111118", "mobile":"1511111118", "sn":"Lastname8"}}, ldap.LdapRecord{uid:"af084bb1-5336-4a85-86ab-f16050e91651", attributes:map[string]string{"cn":"Firstname1 Lastname1", "givenName":"Firstname1", "homePhone":"030 11111111", "mobile":"1511111111", "sn":"Lastname1"}}, ldap.LdapRecord{uid:"d63e99d2-837c-40b4-83fd-25fd3a649a00", attributes:map[string]string{"cn":"Firstname11 Lastname11", "givenName":"Firstname11", "homePhone":"030 111111111", "mobile":"01511 1111111", "sn":"Lastname11"}}, ldap.LdapRecord{uid:"93dbbb06-34e4-4041-a0c6-cf5910071913", attributes:map[string]string{"cn":"Firstname3 Lastname3", "givenName":"Firstname3", "homePhone":"030 11111113", "mobile":"1511111113", "sn":"Lastname3"}}, ldap.LdapRecord{uid:"00777273-e49b-477f-b689-1f258ce1519f", attributes:map[string]string{"cn":"Firstname10 Lastname10", "givenName":"Firstname10", "homePhone":"030 111111110", "mobile":"01511 1111110", "sn":"Lastname10"}}, ldap.LdapRecord{uid:"144d5240-c759-4491-97bc-6c1b8421432c", attributes:map[string]string{"cn":"Firstname6 Lastname6", "givenName":"Firstname6", "homePhone":"030 11111116", "mobile":"1511111116", "sn":"Lastname6"}}, ldap.LdapRecord{uid:"81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52", attributes:map[string]string{"cn":"Firstname12 Lastname12", "givenName":"Firstname12", "homePhone":"030 111111112", "mobile":"01511 1111112", "sn":"Lastname12"}}, ldap.LdapRecord{uid:"d61239b9-a214-4572-b409-f83e3573a6bf", attributes:map[string]string{"cn":"Firstname7 Lastname7", "givenName":"Firstname7", "homePhone":"030 11111117", "mobile":"1511111117", "sn":"Lastname7"}}, ldap.LdapRecord{uid:"d242ab6c-c76f-45f3-802c-7bf8e6d5b3d5", attributes:map[string]string{"cn":"Firstname2 Lastname2", "givenName":"Firstname2", "homePhone":"030 11111112", "mobile":"1511111112", "sn":"Lastname2"}}, ldap.LdapRecord{uid:"5d4d5e71-121a-4b3a-8f93-9edd9c676dc5", attributes:map[string]string{"cn":"Firstname4 Lastname4", "givenName":"Firstname4", "homePhone":"030 11111114", "mobile":"1511111114", "sn":"Lastname4"}}, ldap.LdapRecord{uid:"5fdd288f-fb31-4f76-aea0-41d85ba2e1f0", attributes:map[string]string{"cn":"Firstname5 Lastname5", "givenName":"Firstname5", "homePhone":"030 11111115", "mobile":"1511111115", "sn":"Lastname5"}}, ldap.LdapRecord{uid:"5f464ecb-9331-4018-86c1-a313c5b1ce1d", attributes:map[string]string{"cn":"Firstname9 Lastname9", "givenName":"Firstname9", "homePhone":"030 11111119", "mobile":"1511111119", "sn":"Lastname9"}}} 13:14:24 [TRC] [server] sortLdapResults() 13:14:24 [TRC] [server] insertLicenseInfo() 13:14:24 [DBG] [server] Inserted result: []ldap.LdapRecord{ldap.LdapRecord{uid:"LICENSE-INFO-0000", attributes:map[string]string{"cn":"* Unregistered free version with approx. 12 requests per hour *", "company":"* Unregistered free version with approx. 12 requests per hour *", "homePhone":"!", "mail":"* Unregistered free version with approx. 12 requests per hour *", "mobile":"!", "telephoneNumber":"!"}}, ldap.LdapRecord{uid:"af084bb1-5336-4a85-86ab-f16050e91651", attributes:map[string]string{"cn":"Firstname1 Lastname1", "givenName":"Firstname1", "homePhone":"030 11111111", "mobile":"1511111111", "sn":"Lastname1"}}, ldap.LdapRecord{uid:"00777273-e49b-477f-b689-1f258ce1519f", attributes:map[string]string{"cn":"Firstname10 Lastname10", "givenName":"Firstname10", "homePhone":"030 111111110", "mobile":"01511 1111110", "sn":"Lastname10"}}, ldap.LdapRecord{uid:"d63e99d2-837c-40b4-83fd-25fd3a649a00", attributes:map[string]string{"cn":"Firstname11 Lastname11", "givenName":"Firstname11", "homePhone":"030 111111111", "mobile":"01511 1111111", "sn":"Lastname11"}}, ldap.LdapRecord{uid:"81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52", attributes:map[string]string{"cn":"Firstname12 Lastname12", "givenName":"Firstname12", "homePhone":"030 111111112", "mobile":"01511 1111112", "sn":"Lastname12"}}, ldap.LdapRecord{uid:"d242ab6c-c76f-45f3-802c-7bf8e6d5b3d5", attributes:map[string]string{"cn":"Firstname2 Lastname2", "givenName":"Firstname2", "homePhone":"030 11111112", "mobile":"1511111112", "sn":"Lastname2"}}, ldap.LdapRecord{uid:"93dbbb06-34e4-4041-a0c6-cf5910071913", attributes:map[string]string{"cn":"Firstname3 Lastname3", "givenName":"Firstname3", "homePhone":"030 11111113", "mobile":"1511111113", "sn":"Lastname3"}}, ldap.LdapRecord{uid:"5d4d5e71-121a-4b3a-8f93-9edd9c676dc5", attributes:map[string]string{"cn":"Firstname4 Lastname4", "givenName":"Firstname4", "homePhone":"030 11111114", "mobile":"1511111114", "sn":"Lastname4"}}, ldap.LdapRecord{uid:"5fdd288f-fb31-4f76-aea0-41d85ba2e1f0", attributes:map[string]string{"cn":"Firstname5 Lastname5", "givenName":"Firstname5", "homePhone":"030 11111115", "mobile":"1511111115", "sn":"Lastname5"}}, ldap.LdapRecord{uid:"144d5240-c759-4491-97bc-6c1b8421432c", attributes:map[string]string{"cn":"Firstname6 Lastname6", "givenName":"Firstname6", "homePhone":"030 11111116", "mobile":"1511111116", "sn":"Lastname6"}}, ldap.LdapRecord{uid:"d61239b9-a214-4572-b409-f83e3573a6bf", attributes:map[string]string{"cn":"Firstname7 Lastname7", "givenName":"Firstname7", "homePhone":"030 11111117", "mobile":"1511111117", "sn":"Lastname7"}}, ldap.LdapRecord{uid:"52af4c99-9483-4021-a1cb-815b35e62275", attributes:map[string]string{"cn":"Firstname8 Lastname8", "givenName":"Firstname8", "homePhone":"030 11111118", "mobile":"1511111118", "sn":"Lastname8"}}, ldap.LdapRecord{uid:"5f464ecb-9331-4018-86c1-a313c5b1ce1d", attributes:map[string]string{"cn":"Firstname9 Lastname9", "givenName":"Firstname9", "homePhone":"030 11111119", "mobile":"1511111119", "sn":"Lastname9"}}} 13:14:24 [TRC] [server] ldapResultEntry(ldapRec '{LICENSE-INFO-0000 map[cn:* Unregistered free version with approx. 12 requests per hour * company:* Unregistered free version with approx. 12 requests per hour * homePhone:! mail:* Unregistered free version with approx. 12 requests per hour * mobile:! telephoneNumber:!]}') 13:14:24 [TRC] [server] ldapResultEntry(ldapRec '{af084bb1-5336-4a85-86ab-f16050e91651 map[cn:Firstname1 Lastname1 givenName:Firstname1 homePhone:030 11111111 mobile:1511111111 sn:Lastname1]}') 13:14:24 [TRC] [intern] FluxCompensate('Firstname1 Lastname1') 13:14:24 [TRC] [intern] fluxReached() 13:14:24 [TRC] [server] ldapResultEntry(ldapRec '{00777273-e49b-477f-b689-1f258ce1519f map[cn:Firstname10 Lastname10 givenName:Firstname10 homePhone:030 111111110 mobile:01511 1111110 sn:Lastname10]}') 13:14:24 [TRC] [intern] FluxCompensate('Firstname10 Lastname10') 13:14:24 [TRC] [intern] fluxReached() 13:14:24 [TRC] [server] ldapResultEntry(ldapRec '{d63e99d2-837c-40b4-83fd-25fd3a649a00 map[cn:Firstname11 Lastname11 givenName:Firstname11 homePhone:030 111111111 mobile:01511 1111111 sn:Lastname11]}') 13:14:24 [TRC] [intern] FluxCompensate('Firstname11 Lastname11') 13:14:24 [TRC] [intern] fluxReached() 13:14:24 [TRC] [server] ldapResultEntry(ldapRec '{81e9aa36-ad48-44d8-b9e1-0a2ddda5aa52 map[cn:Firstname12 Lastname12 givenName:Firstname12 homePhone:030 111111112 mobile:01511 1111112 sn:Lastname12]}') 13:14:24 [TRC] [intern] FluxCompensate('Firstname12 Lastname12') 13:14:24 [TRC] [intern] fluxReached() 13:14:24 [TRC] [server] ldapResultEntry(ldapRec '{d242ab6c-c76f-45f3-802c-7bf8e6d5b3d5 map[cn:Firstname2 Lastname2 givenName:Firstname2 homePhone:030 11111112 mobile:1511111112 sn:Lastname2]}') 13:14:24 [TRC] [intern] FluxCompensate('Firstname2 Lastname2') 13:14:24 [TRC] [intern] fluxReached() 13:14:24 [TRC] [server] ldapResultEntry(ldapRec '{93dbbb06-34e4-4041-a0c6-cf5910071913 map[cn:Firstname3 Lastname3 givenName:Firstname3 homePhone:030 11111113 mobile:1511111113 sn:Lastname3]}') 13:14:24 [TRC] [intern] FluxCompensate('Firstname3 Lastname3') 13:14:24 [TRC] [intern] fluxReached() 13:14:24 [TRC] [server] ldapResultEntry(ldapRec '{5d4d5e71-121a-4b3a-8f93-9edd9c676dc5 map[cn:Firstname4 Lastname4 givenName:Firstname4 homePhone:030 11111114 mobile:1511111114 sn:Lastname4]}') 13:14:24 [TRC] [intern] FluxCompensate('Firstname4 Lastname4') 13:14:24 [TRC] [intern] fluxReached() 13:14:24 [TRC] [server] ldapResultEntry(ldapRec '{5fdd288f-fb31-4f76-aea0-41d85ba2e1f0 map[cn:Firstname5 Lastname5 givenName:Firstname5 homePhone:030 11111115 mobile:1511111115 sn:Lastname5]}') 13:14:24 [TRC] [intern] FluxCompensate('Firstname5 Lastname5') 13:14:24 [TRC] [intern] fluxReached() 13:14:24 [TRC] [server] ldapResultEntry(ldapRec '{144d5240-c759-4491-97bc-6c1b8421432c map[cn:Firstname6 Lastname6 givenName:Firstname6 homePhone:030 11111116 mobile:1511111116 sn:Lastname6]}') 13:14:24 [TRC] [intern] FluxCompensate('Firstname6 Lastname6') 13:14:24 [TRC] [intern] fluxReached() 13:14:24 [TRC] [server] ldapResultEntry(ldapRec '{d61239b9-a214-4572-b409-f83e3573a6bf map[cn:Firstname7 Lastname7 givenName:Firstname7 homePhone:030 11111117 mobile:1511111117 sn:Lastname7]}') 13:14:24 [TRC] [intern] FluxCompensate('Firstname7 Lastname7') 13:14:24 [TRC] [intern] fluxReached() 13:14:24 [TRC] [server] ldapResultEntry(ldapRec '{52af4c99-9483-4021-a1cb-815b35e62275 map[cn:Firstname8 Lastname8 givenName:Firstname8 homePhone:030 11111118 mobile:1511111118 sn:Lastname8]}') 13:14:24 [TRC] [intern] FluxCompensate('Firstname8 Lastname8') 13:14:24 [TRC] [intern] fluxReached() 13:14:24 [TRC] [server] ldapResultEntry(ldapRec '{5f464ecb-9331-4018-86c1-a313c5b1ce1d map[cn:Firstname9 Lastname9 givenName:Firstname9 homePhone:030 11111119 mobile:1511111119 sn:Lastname9]}') 13:14:24 [TRC] [intern] FluxCompensate('Firstname9 Lastname9') 13:14:24 [TRC] [intern] fluxReached() 13:14:24 [INF] [server] 13 records returned for search request #2: (|(|(cn=*)(sn=*)(givenName=*)(company=*))(|(homePhone=*)(telephoneNumber=*)(mobile=*))) from 192.168.3.251:48237 {6beddcb1}

Fanvil log: (I hope i got the correct entries)

D/platform.sdev.fs | Can not find suffix in *, use default * D/platform.sdev.fs | sdevFsIoctl: fileName=[*], filePath=[/tmp], fullPath=[/tmp/*]! D/platform.sdev.fs | sdevFsIoctl: fileName=[*], filePath=[/tmp], fullPath=[/tmp/*]! D/platform.sdev.fs | get file [*] path success. filePath[/tmp] D/vcore.contact.ldapparse | ldapFileReadLdif:ldapCmdString[ldapsearch -P 3 -h 192.168.xxx.xxx -p 1389 -b "dc=xxx, dc=local" -s sub -l 60 -LLL -N -D "cn=phone" -w xxx -z 1000 "(|(|(cn=*)(sn=*)(givenName=*)(company=*))(|(homePhone=*)(telephoneNumber=*)(mobile=*)))" -S cn cn company uid telephoneNumber mobile homePhone mail > /tmp/2751677632.ldif] D/vcore.contact.ldapparse | ldapFileReadLdif: get ldif file size[1926] I/vcore.pfm.contact | contactSearchByPage: result row=[13], col=[1] D/xui.window | windowTaskCreate: ui receive EVT = EVT_DB_SEARCH event code is 0 D/xui.winHdl | winDefaultHdl: ui handle EVT = EVT_DB_SEARCH D/return_val_if_fail | getStrPosByUnicode:373 src && offset && glyphs_pos > 0 failed. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/return_val_if_fail | getStrPosByUnicode:373 src && offset && glyphs_pos > 0 failed. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/return_val_if_fail | getStrPosByUnicode:373 src && offset && glyphs_pos > 0 failed. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/return_val_if_fail | getStrPosByUnicode:373 src && offset && glyphs_pos > 0 failed. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/return_val_if_fail | getStrPosByUnicode:373 src && offset && glyphs_pos > 0 failed. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/return_val_if_fail | getStrPosByUnicode:373 src && offset && glyphs_pos > 0 failed. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/return_val_if_fail | getStrPosByUnicode:373 src && offset && glyphs_pos > 0 failed. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/return_val_if_fail | getStrPosByUnicode:373 src && offset && glyphs_pos > 0 failed. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/return_val_if_fail | getStrPosByUnicode:373 src && offset && glyphs_pos > 0 failed. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/return_val_if_fail | getStrPosByUnicode:373 src && offset && glyphs_pos > 0 failed. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/return_val_if_fail | getStrPosByUnicode:373 src && offset && glyphs_pos > 0 failed. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''. E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error. D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error. D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1] D/vcore.pfm.contact | getContactInfo: line=[-1] D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local]. W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error

Updated by Markus Jessl almost 3 years ago

Small bug

Some phone entries e.g. "Firstname1 Lastname1" mobile phone numbers are not correct.
The leading 0 in the phone number is missing.
See attached pictures for details.
(The names are banners in the phone, so they are rotating if selected. No issue there ;))

Actions #10

Updated by Jörg Ebeling almost 3 years ago

Hi Markus,

thanks a lot for the logs!

Quite cool that we get a big step into the right direction.

I just checked the logs in very detail.

L2CPBG start up log look fully okay.

Also L2CPBG request log look as expected.
I think you can remove the "uid" field from the two LDAP config fields of your phone, as well as switch back to "log = debug".
In addition, when in real usage, you should limit the "Max Hits" field to some value between 50-100.

Regarding the phones debug log:
Nice to see now ldapsearch requests which request the same LDAP attributes as configured in the phone (and not all).

What I'm curious now, are the blocks with

E/vcore.pfm.contact | getGroupIdByContactId: contactSearch CONTACT_SEARCH_CONTACT_ID_TO_GROUP_ID error.
D/vcore.pfm.contact | getContactInfo: getGroupIdByContactId error.
D/vcore.pfm.contact | getContactInfoByIndex: sdb is not local contact
D/sdevldapPhoneBook | sdevldapPhoneBookGetRow: current=[1]
D/vcore.pfm.contact | getContactInfo: line=[-1]
D/sdevldapPhoneBook | sdevldapPhoneBookSearch: url=[ldap://192.168.xxx.xxx:1389/dc=xxx, dc=local].
W/vcore.pfm.contact | pfmFreeSubSearchInfo: param error
E/vcore.pfm.contact | contactSearchByPage: sDbSearch error ''.

I guess they've something to do with the initial (attribute less) LDAP request which I still not understand.
Think it tries to load some kind of "organization unit" (ou) structure... probably to place/give them these "groupId" correlation.
However, I need to do some detailed research. Probably I find some Fanvil sample LDAP LDIF from which I'm able to identify the meaning.
I'll open a new ticket for this, to keep it on radar.

Regarding the big bug (yes, it is big because it would make that contact entry useless) with the missing 0 in the mobile phone, I'll load your contact.vcf (thanks a lot for it) for further debugging. Could you please drop me your L2CPBG configured [location] section so that I'm able to debug correctly?

Greetings

Jörg

Actions #11

Updated by Jörg Ebeling almost 3 years ago

  • Copied to Support #39: Unidentified LDAP request of Fanvil X3U added
Actions #12

Updated by Markus Jessl almost 3 years ago

Hi Joerg,

thx again.

Regarding the bug, I'm using the following config:

Specific settings to your location

[location]
country = "DE"
int = 49 # Your international dial code. 1 = US, 49 = Germany, ...
area = 36842 # Your local area code (without a leading 0)
areaPrefix = "0" # Dial prefix for national calls (has to be written as "string")
maxarealength = 7

Cheers,

Markus

Actions #13

Updated by Jörg Ebeling over 2 years ago

Hi Markus,

thanks for the location infos.

Today I found some time to debug the missing zeros in some of your test- contacts.

In principle they're okay because they're not valid phone numbers.
I use a port of Google's libphonenumber library for phone number validation, shortening and prettifying.
You may check this online parser and check i.e. "+491511111111" (invalid) or "+4915111111112" (valid) number.

But nevertheless there are some things I will/have to change:

  1. There's a bug which doesn't correctly show the number harmonizing & prettifying process (neither in debug nor in trace) log.
  2. I should output a warning message in the case of an invalid number
  3. Instead of prettifying an invalid number (which probably make the number useless) I should store the number as it was in the VCF.

Will take a couple of days.

Regarding your location settings:
Not critical, but when I'm right, you probably should change maxarealength from 7 to 5.
Maxarealength should be the max. length of a number within your local area. As far as I could see, they're mostly 4-5 numbers long.

Why having this int-, area- code and maxarealength stuff?
Most CardDAV VCF's get managed by normal humans in their Handy-, Thunderbird- or whatever address book. They don't care about E.164 or international format. If they store their local pizza dealer then they save "Happy Pizza = 565444"... next time, they store a number from their called list, in which case it's i.e 004930123456 or whatever. They even store "+49 (0)40 987 654".
What I want to say is: phone numbers in CardDAV address books are mostly total unorganized, or to be more precise totally chaotic, and I need to harmonize them in my Gateway process.

Nice greetings

Jörg

Updated by Jörg Ebeling over 2 years ago

Hi Makrus!

Got it ready so far.

Invalid phone numbers get logged now in log as well as stores as received from CardDAV.

Except the Fanvil specific group stuff, for which I opened a new ticket, this ticket should be closable in my opinion. Feel free to do so.

Greetings

Jörg

Actions #15

Updated by Jörg Ebeling over 2 years ago

  • File deleted (l2cpbg_0.9.2beta3_windows-32bit.zip)
Actions #16

Updated by Jörg Ebeling over 2 years ago

  • File deleted (l2cpbg_0.9.2beta3_windows-64bit.zip)
Actions #17

Updated by Markus Jessl over 2 years ago

Hello Joerg,

thx a lot.

The beta4 version works fine.

I'm most likely will use a Fanvil X7C and a X210 in Germany and can remotely test them hopefully next week.
Closing this bug now and let you know if I get any trouble on the other Fanvil phones.

Cheers,

Markus

Actions #18

Updated by Jörg Ebeling over 2 years ago

  • Status changed from Feedback to Closed
  • % Done changed from 90 to 100

Hi Markus,

thanks a lot for your test and feedback.

Don't hurry with the other phones. If you've any issues, simply create a new ticket.

Kind regards

Jörg

Actions

Also available in: Atom PDF

Go to top