Proxyfing a LDAP with specific Schema returns no Entries

[sgadeau]

Hello,
I work for ASIP Santé (french gouvernment agency for digital health), and i am about to make an identity provider from several LDAPs.
i am trying to proxyfy the public ldap :
annuaire.asipsante.fr:389
when i try to browse o=GIP-CPS / ou=Sage-Femme, myVD returns no Entry
I know there are some entries, especialy where looking in the log file there is at least one entry:
dn : cn=810100896421+sn=LEBIGRE+givenName=COLINE,ou=Sage-Femme,o=GIP-CPS,c=fr
but it returns nothing.
below i give you my config and the log file (the schema is too long, but it can be fetch from the LDAP annuaire)
I think the problem is due to a specific schema from the source LDAP. it looks like the Entities are not well interpreted by myVd
Can you help me to fix my problem ? is this a bug ?
Thanks a lot
Stephane

############CONFIG#####################

#Listen on port 50389
server.listener.port=50389
server.globalChain=

#Configure global chains
server.globalChain=LogAllTransactions
server.globalChain.LogAllTransactions.className=net.sourceforge.myvd.inserts.DumpTransaction
server.globalChain.LogAllTransactions.config.logLevel=info
server.globalChain.LogAllTransactions.config.label=Global

#Configure namespaces
server.nameSpaces=Root,Schema,LDAPCPS

#Define RootDSE
server.Root.chain=RootDSE
server.Root.nameSpace=
server.Root.weight=0
server.Root.RootDSE.className=net.sourceforge.myvd.inserts.RootDSE
server.Root.RootDSE.config.namingContexts=c=fr
server.Root.RootDSE.config.subSchemaSubEntry=cn=schema

#Schema Declare
server.Schema.chain=schema
server.Schema.nameSpace=cn=schema
server.Schema.weight=50
server.Schema.schema.className=net.sourceforge.myvd.inserts.SchemaInsert
server.Schema.schema.config.schemaLDIF=cps.ldif

#Access to LDAP CPS
server.LDAPCPS.chain=RDN,LDAP2
server.LDAPCPS.nameSpace=c=fr
server.LDAPCPS.weight=100
server.LDAPCPS.RDN.className=net.sourceforge.myvd.inserts.setrdn.SetRDN
server.LDAPCPS.RDN.config.internalRDN=cn+sn+givenName
server.LDAPCPS.RDN.config.externalRDN=cn
server.LDAPCPS.RDN.config.objectClass=cpsPersonne
server.LDAPCPS.LDAP2.className=net.sourceforge.myvd.inserts.ldap.LDAPInterceptor
server.LDAPCPS.LDAP2.config.host=annuaire.asipsante.fr
server.LDAPCPS.LDAP2.config.port=389
server.LDAPCPS.LDAP2.config.remoteBase=c=fr

############END OF CONFIG#####################

############LOG FILE#####################

[2019-11-22 14:56:49,350][NioProcessor-2] DEBUG ProtocolCodecFilter - Processing a MESSAGE_RECEIVED for session 1
[2019-11-22 14:56:49,351][NioProcessor-2] DEBUG CODEC_LOG - Decoding the PDU :
[2019-11-22 14:56:49,351][NioProcessor-2] DEBUG CODEC_LOG - 0x30 0x84 0x00 0x00 0x00 0x8A 0x02 0x01 0x47 0x63 0x84 0x00 0x00 0x00 0x4D 0x04 0x1C 0x6F 0x75 0x3D 0x53 0x61 0x67 0x65 0x2D 0x46 0x65 0x6D 0x6D 0x65 0x2C 0x6F 0x3D 0x47 0x49 0x50 0x2D 0x43 0x50 0x53 0x2C 0x63 0x3D 0x66 0x72 0x0A 0x01 0x01 0x0A 0x01 0x00 0x02 0x01 0x00 0x02 0x01 0x3C 0x01 0x01 0x00 0x87 0x0B 0x6F 0x62 0x6A 0x65 0x63 0x74 0x63 0x6C 0x61 0x73 0x73 0x30 0x84 0x00 0x00 0x00 0x0D 0x04 0x0B 0x6F 0x62 0x6A 0x65 0x63 0x74 0x63 0x6C 0x61 0x73 0x73 0xA0 0x84 0x00 0x00 0x00 0x2E 0x30 0x84 0x00 0x00 0x00 0x28 0x04 0x16 0x31 0x2E 0x32 0x2E 0x38 0x34 0x30 0x2E 0x31 0x31 0x33 0x35 0x35 0x36 0x2E 0x31 0x2E 0x34 0x2E 0x33 0x31 0x39 0x01 0x01 0xFF 0x04 0x0B 0x30 0x84 0x00 0x00 0x00 0x05 0x02 0x01 0x64 0x04 0x00
[2019-11-22 14:56:49,351][NioProcessor-2] DEBUG Asn1Decoder - >>>==========================================
[2019-11-22 14:56:49,351][NioProcessor-2] DEBUG Asn1Decoder - --> Decoding a PDU
[2019-11-22 14:56:49,351][NioProcessor-2] DEBUG Asn1Decoder - >>>------------------------------------------
[2019-11-22 14:56:49,351][NioProcessor-2] DEBUG Asn1Decoder - --- State = TAG_STATE_START ---
[2019-11-22 14:56:49,351][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x30
[2019-11-22 14:56:49,351][NioProcessor-2] DEBUG Asn1Decoder - Tag 0x30 has been decoded
[2019-11-22 14:56:49,351][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_START ---
[2019-11-22 14:56:49,351][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x84
[2019-11-22 14:56:49,351][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_PENDING ---
[2019-11-22 14:56:49,351][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x00
[2019-11-22 14:56:49,351][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x00
[2019-11-22 14:56:49,351][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x00
[2019-11-22 14:56:49,351][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x00
[2019-11-22 14:56:49,351][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x8A
[2019-11-22 14:56:49,351][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_END ---
[2019-11-22 14:56:49,351][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x02
[2019-11-22 14:56:49,351][NioProcessor-2] DEBUG Asn1Decoder - Parent length : TLV expected length stack : - null
[2019-11-22 14:56:49,351][NioProcessor-2] DEBUG Asn1Decoder - Root TLV[138]
[2019-11-22 14:56:49,351][NioProcessor-2] DEBUG Asn1Decoder - Length 138 has been decoded
[2019-11-22 14:56:49,351][NioProcessor-2] DEBUG Asn1Decoder - --- State = VALUE_STATE_START ---
[2019-11-22 14:56:49,351][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x02
[2019-11-22 14:56:49,352][NioProcessor-2] DEBUG Asn1Decoder - --- State = TLV_STATE_DONE ---
[2019-11-22 14:56:49,352][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x02
[2019-11-22 14:56:49,352][NioProcessor-2] DEBUG Asn1Decoder - TLV Tree : TLV0x30(138)
[2019-11-22 14:56:49,352][NioProcessor-2] DEBUG AbstractGrammar - Transition from state <START_STATE> to state <LDAP_MESSAGE_STATE>, tag <0x30>, action : LdapMessage initialization
[2019-11-22 14:56:49,352][NioProcessor-2] DEBUG Asn1Decoder - --- State = TAG_STATE_START ---
[2019-11-22 14:56:49,352][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x02
[2019-11-22 14:56:49,352][NioProcessor-2] DEBUG Asn1Decoder - Tag 0x02 has been decoded
[2019-11-22 14:56:49,352][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_START ---
[2019-11-22 14:56:49,352][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x01
[2019-11-22 14:56:49,352][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_END ---
[2019-11-22 14:56:49,352][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x47
[2019-11-22 14:56:49,352][NioProcessor-2] DEBUG Asn1Decoder - Parent length : TLV expected length stack : - 138 - null
[2019-11-22 14:56:49,352][NioProcessor-2] DEBUG Asn1Decoder - Length 1 has been decoded
[2019-11-22 14:56:49,352][NioProcessor-2] DEBUG Asn1Decoder - --- State = VALUE_STATE_START ---
[2019-11-22 14:56:49,352][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x47
[2019-11-22 14:56:49,352][NioProcessor-2] DEBUG Asn1Decoder - --- State = TLV_STATE_DONE ---
[2019-11-22 14:56:49,352][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x63
[2019-11-22 14:56:49,352][NioProcessor-2] DEBUG Asn1Decoder - TLV Tree : TLV0x02(1)-TLV0x30(135)
[2019-11-22 14:56:49,352][NioProcessor-2] DEBUG AbstractGrammar - Transition from state <LDAP_MESSAGE_STATE> to state <MESSAGE_ID_STATE>, tag <0x02>, action : Store MessageID
[2019-11-22 14:56:49,352][NioProcessor-2] DEBUG StoreMessageId - Ldap Message Id has been decoded : 71
[2019-11-22 14:56:49,352][NioProcessor-2] DEBUG Asn1Decoder - --- State = TAG_STATE_START ---
[2019-11-22 14:56:49,352][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x63
[2019-11-22 14:56:49,352][NioProcessor-2] DEBUG Asn1Decoder - Tag 0x63 has been decoded
[2019-11-22 14:56:49,352][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_START ---
[2019-11-22 14:56:49,352][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x84
[2019-11-22 14:56:49,352][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_PENDING ---
[2019-11-22 14:56:49,352][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x00
[2019-11-22 14:56:49,352][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x00
[2019-11-22 14:56:49,352][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x00
[2019-11-22 14:56:49,352][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x00
[2019-11-22 14:56:49,352][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x4D
[2019-11-22 14:56:49,352][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_END ---
[2019-11-22 14:56:49,352][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x04
[2019-11-22 14:56:49,352][NioProcessor-2] DEBUG Asn1Decoder - Parent length : TLV expected length stack : - 135 - null
[2019-11-22 14:56:49,352][NioProcessor-2] DEBUG Asn1Decoder - Length 77 has been decoded
[2019-11-22 14:56:49,352][NioProcessor-2] DEBUG Asn1Decoder - --- State = VALUE_STATE_START ---
[2019-11-22 14:56:49,352][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x04
[2019-11-22 14:56:49,352][NioProcessor-2] DEBUG Asn1Decoder - --- State = TLV_STATE_DONE ---
[2019-11-22 14:56:49,352][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x04
[2019-11-22 14:56:49,352][NioProcessor-2] DEBUG Asn1Decoder - TLV Tree : TLV0x63(77)-TLV0x30(52)
[2019-11-22 14:56:49,352][NioProcessor-2] DEBUG AbstractGrammar - Transition from state <MESSAGE_ID_STATE> to state <SEARCH_REQUEST_STATE>, tag <0x63>, action : Init SearchRequest
[2019-11-22 14:56:49,352][NioProcessor-2] DEBUG InitSearchRequest - Search Request
[2019-11-22 14:56:49,352][NioProcessor-2] DEBUG Asn1Decoder - --- State = TAG_STATE_START ---
[2019-11-22 14:56:49,353][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x04
[2019-11-22 14:56:49,353][NioProcessor-2] DEBUG Asn1Decoder - Tag 0x04 has been decoded
[2019-11-22 14:56:49,353][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_START ---
[2019-11-22 14:56:49,353][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x1C
[2019-11-22 14:56:49,353][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_END ---
[2019-11-22 14:56:49,353][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x6F
[2019-11-22 14:56:49,353][NioProcessor-2] DEBUG Asn1Decoder - Parent length : TLV expected length stack : - 77 - 52 - null
[2019-11-22 14:56:49,353][NioProcessor-2] DEBUG Asn1Decoder - Length 28 has been decoded
[2019-11-22 14:56:49,353][NioProcessor-2] DEBUG Asn1Decoder - --- State = VALUE_STATE_START ---
[2019-11-22 14:56:49,353][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x6F
[2019-11-22 14:56:49,353][NioProcessor-2] DEBUG Asn1Decoder - --- State = TLV_STATE_DONE ---
[2019-11-22 14:56:49,353][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x0A
[2019-11-22 14:56:49,353][NioProcessor-2] DEBUG Asn1Decoder - TLV Tree : TLV0x04(28)-TLV0x63(47)-TLV0x30(52)
[2019-11-22 14:56:49,353][NioProcessor-2] DEBUG AbstractGrammar - Transition from state <SEARCH_REQUEST_STATE> to state <BASE_OBJECT_STATE>, tag <0x04>, action : Store SearchRequest object Name
[2019-11-22 14:56:49,353][NioProcessor-2] DEBUG StoreSearchRequestBaseObject - Searching with root Dn : ou=Sage-Femme,o=GIP-CPS,c=fr
[2019-11-22 14:56:49,353][NioProcessor-2] DEBUG Asn1Decoder - --- State = TAG_STATE_START ---
[2019-11-22 14:56:49,353][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x0A
[2019-11-22 14:56:49,353][NioProcessor-2] DEBUG Asn1Decoder - Tag 0x0A has been decoded
[2019-11-22 14:56:49,353][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_START ---
[2019-11-22 14:56:49,353][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x01
[2019-11-22 14:56:49,353][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_END ---
[2019-11-22 14:56:49,353][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x01
[2019-11-22 14:56:49,353][NioProcessor-2] DEBUG Asn1Decoder - Parent length : TLV expected length stack : - 47 - 52 - null
[2019-11-22 14:56:49,353][NioProcessor-2] DEBUG Asn1Decoder - Length 1 has been decoded
[2019-11-22 14:56:49,353][NioProcessor-2] DEBUG Asn1Decoder - --- State = VALUE_STATE_START ---
[2019-11-22 14:56:49,353][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x01
[2019-11-22 14:56:49,353][NioProcessor-2] DEBUG Asn1Decoder - --- State = TLV_STATE_DONE ---
[2019-11-22 14:56:49,353][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x0A
[2019-11-22 14:56:49,353][NioProcessor-2] DEBUG Asn1Decoder - TLV Tree : TLV0x0A(1)-TLV0x63(44)-TLV0x30(52)
[2019-11-22 14:56:49,353][NioProcessor-2] DEBUG AbstractGrammar - Transition from state <BASE_OBJECT_STATE> to state <SCOPE_STATE>, tag <0x0A>, action : Store SearchRequest scope
[2019-11-22 14:56:49,353][NioProcessor-2] DEBUG StoreSearchRequestScope - Searching within SINGLE_LEVEL scope
[2019-11-22 14:56:49,353][NioProcessor-2] DEBUG Asn1Decoder - --- State = TAG_STATE_START ---
[2019-11-22 14:56:49,353][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x0A
[2019-11-22 14:56:49,354][NioProcessor-2] DEBUG Asn1Decoder - Tag 0x0A has been decoded
[2019-11-22 14:56:49,354][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_START ---
[2019-11-22 14:56:49,354][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x01
[2019-11-22 14:56:49,354][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_END ---
[2019-11-22 14:56:49,354][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x00
[2019-11-22 14:56:49,354][NioProcessor-2] DEBUG Asn1Decoder - Parent length : TLV expected length stack : - 44 - 52 - null
[2019-11-22 14:56:49,354][NioProcessor-2] DEBUG Asn1Decoder - Length 1 has been decoded
[2019-11-22 14:56:49,354][NioProcessor-2] DEBUG Asn1Decoder - --- State = VALUE_STATE_START ---
[2019-11-22 14:56:49,354][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x00
[2019-11-22 14:56:49,354][NioProcessor-2] DEBUG Asn1Decoder - --- State = TLV_STATE_DONE ---
[2019-11-22 14:56:49,354][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x02
[2019-11-22 14:56:49,354][NioProcessor-2] DEBUG Asn1Decoder - TLV Tree : TLV0x0A(1)-TLV0x63(41)-TLV0x30(52)
[2019-11-22 14:56:49,354][NioProcessor-2] DEBUG AbstractGrammar - Transition from state <SCOPE_STATE> to state <DEREF_ALIAS_STATE>, tag <0x0A>, action : Store SearchRequest derefAlias flag
[2019-11-22 14:56:49,354][NioProcessor-2] DEBUG StoreSearchRequestDerefAlias - Handling object strategy : NEVER_DEREF_ALIASES
[2019-11-22 14:56:49,354][NioProcessor-2] DEBUG Asn1Decoder - --- State = TAG_STATE_START ---
[2019-11-22 14:56:49,354][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x02
[2019-11-22 14:56:49,354][NioProcessor-2] DEBUG Asn1Decoder - Tag 0x02 has been decoded
[2019-11-22 14:56:49,354][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_START ---
[2019-11-22 14:56:49,354][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x01
[2019-11-22 14:56:49,354][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_END ---
[2019-11-22 14:56:49,354][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x00
[2019-11-22 14:56:49,354][NioProcessor-2] DEBUG Asn1Decoder - Parent length : TLV expected length stack : - 41 - 52 - null
[2019-11-22 14:56:49,354][NioProcessor-2] DEBUG Asn1Decoder - Length 1 has been decoded
[2019-11-22 14:56:49,354][NioProcessor-2] DEBUG Asn1Decoder - --- State = VALUE_STATE_START ---
[2019-11-22 14:56:49,354][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x00
[2019-11-22 14:56:49,354][NioProcessor-2] DEBUG Asn1Decoder - --- State = TLV_STATE_DONE ---
[2019-11-22 14:56:49,354][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x02
[2019-11-22 14:56:49,354][NioProcessor-2] DEBUG Asn1Decoder - TLV Tree : TLV0x02(1)-TLV0x63(38)-TLV0x30(52)
[2019-11-22 14:56:49,354][NioProcessor-2] DEBUG AbstractGrammar - Transition from state <DEREF_ALIAS_STATE> to state <SIZE_LIMIT_STATE>, tag <0x02>, action : Store SearchRequest sizeLimit
[2019-11-22 14:56:49,354][NioProcessor-2] DEBUG StoreSearchRequestSizeLimit - The sizeLimit value is set to 0 objects
[2019-11-22 14:56:49,354][NioProcessor-2] DEBUG Asn1Decoder - --- State = TAG_STATE_START ---
[2019-11-22 14:56:49,354][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x02
[2019-11-22 14:56:49,354][NioProcessor-2] DEBUG Asn1Decoder - Tag 0x02 has been decoded
[2019-11-22 14:56:49,354][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_START ---
[2019-11-22 14:56:49,354][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x01
[2019-11-22 14:56:49,354][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_END ---
[2019-11-22 14:56:49,354][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x3C
[2019-11-22 14:56:49,354][NioProcessor-2] DEBUG Asn1Decoder - Parent length : TLV expected length stack : - 38 - 52 - null
[2019-11-22 14:56:49,354][NioProcessor-2] DEBUG Asn1Decoder - Length 1 has been decoded
[2019-11-22 14:56:49,354][NioProcessor-2] DEBUG Asn1Decoder - --- State = VALUE_STATE_START ---
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x3C
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG Asn1Decoder - --- State = TLV_STATE_DONE ---
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x01
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG Asn1Decoder - TLV Tree : TLV0x02(1)-TLV0x63(35)-TLV0x30(52)
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG AbstractGrammar - Transition from state <SIZE_LIMIT_STATE> to state <TIME_LIMIT_STATE>, tag <0x02>, action : Store SearchRequest timeLimit
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG StoreSearchRequestTimeLimit - The timeLimit value is set to 60 seconds
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG Asn1Decoder - --- State = TAG_STATE_START ---
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x01
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG Asn1Decoder - Tag 0x01 has been decoded
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_START ---
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x01
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_END ---
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x00
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG Asn1Decoder - Parent length : TLV expected length stack : - 35 - 52 - null
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG Asn1Decoder - Length 1 has been decoded
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG Asn1Decoder - --- State = VALUE_STATE_START ---
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x00
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG Asn1Decoder - --- State = TLV_STATE_DONE ---
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x87
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG Asn1Decoder - TLV Tree : TLV0x01(1)-TLV0x63(32)-TLV0x30(52)
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG AbstractGrammar - Transition from state <TIME_LIMIT_STATE> to state <TYPES_ONLY_STATE>, tag <0x01>, action : Store SearchRequest typesOnly flag
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG StoreSearchRequestTypesOnly - The search will return attributes types and values
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG Asn1Decoder - --- State = TAG_STATE_START ---
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x87
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG Asn1Decoder - Tag 0x87 has been decoded
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_START ---
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x0B
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_END ---
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x6F
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG Asn1Decoder - Parent length : TLV expected length stack : - 32 - 52 - null
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG Asn1Decoder - Length 11 has been decoded
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG Asn1Decoder - --- State = VALUE_STATE_START ---
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x6F
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG Asn1Decoder - --- State = TLV_STATE_DONE ---
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x30
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG Asn1Decoder - TLV Tree : TLV0x87(11)-TLV0x63(19)-TLV0x30(52)
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG AbstractGrammar - Transition from state <TYPES_ONLY_STATE> to state <PRESENT_STATE>, tag <0x87>, action : Init present filter Value
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG InitPresentFilter - Initialize Present filter
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG Asn1Decoder - --- State = TAG_STATE_START ---
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x30
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG Asn1Decoder - Tag 0x30 has been decoded
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_START ---
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x84
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_PENDING ---
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x00
[2019-11-22 14:56:49,355][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x00
[2019-11-22 14:56:49,356][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x00
[2019-11-22 14:56:49,356][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x00
[2019-11-22 14:56:49,356][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x0D
[2019-11-22 14:56:49,356][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_END ---
[2019-11-22 14:56:49,356][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x04
[2019-11-22 14:56:49,356][NioProcessor-2] DEBUG Asn1Decoder - Parent length : TLV expected length stack : - 19 - 52 - null
[2019-11-22 14:56:49,356][NioProcessor-2] DEBUG Asn1Decoder - Length 13 has been decoded
[2019-11-22 14:56:49,356][NioProcessor-2] DEBUG Asn1Decoder - --- State = VALUE_STATE_START ---
[2019-11-22 14:56:49,356][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x04
[2019-11-22 14:56:49,356][NioProcessor-2] DEBUG Asn1Decoder - --- State = TLV_STATE_DONE ---
[2019-11-22 14:56:49,356][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x04
[2019-11-22 14:56:49,356][NioProcessor-2] DEBUG Asn1Decoder - TLV Tree : TLV0x30(13)-TLV0x63(0)-TLV0x30(52)
[2019-11-22 14:56:49,356][NioProcessor-2] DEBUG AbstractGrammar - Transition from state <PRESENT_STATE> to state <ATTRIBUTE_DESCRIPTION_LIST_STATE>, tag <0x30>, action : Initialize AttributeDesc list
[2019-11-22 14:56:49,356][NioProcessor-2] DEBUG InitSearchRequestAttributeDescList - Initialize AttributeDesc list
[2019-11-22 14:56:49,356][NioProcessor-2] DEBUG Asn1Decoder - --- State = TAG_STATE_START ---
[2019-11-22 14:56:49,356][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x04
[2019-11-22 14:56:49,356][NioProcessor-2] DEBUG Asn1Decoder - Tag 0x04 has been decoded
[2019-11-22 14:56:49,356][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_START ---
[2019-11-22 14:56:49,356][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x0B
[2019-11-22 14:56:49,356][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_END ---
[2019-11-22 14:56:49,356][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x6F
[2019-11-22 14:56:49,356][NioProcessor-2] DEBUG Asn1Decoder - Parent length : TLV expected length stack : - 13 - 0 - 52 - null
[2019-11-22 14:56:49,356][NioProcessor-2] DEBUG Asn1Decoder - Length 11 has been decoded
[2019-11-22 14:56:49,356][NioProcessor-2] DEBUG Asn1Decoder - --- State = VALUE_STATE_START ---
[2019-11-22 14:56:49,356][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x6F
[2019-11-22 14:56:49,356][NioProcessor-2] DEBUG Asn1Decoder - --- State = TLV_STATE_DONE ---
[2019-11-22 14:56:49,356][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0xA0
[2019-11-22 14:56:49,356][NioProcessor-2] DEBUG Asn1Decoder - TLV Tree : TLV0x04(11)-TLV0x30(0)-TLV0x63(0)-TLV0x30(52)
[2019-11-22 14:56:49,356][NioProcessor-2] DEBUG AbstractGrammar - Transition from state <ATTRIBUTE_DESCRIPTION_LIST_STATE> to state <ATTRIBUTE_DESCRIPTION_STATE>, tag <0x04>, action : Store attribute description
[2019-11-22 14:56:49,356][NioProcessor-2] DEBUG StoreSearchRequestAttributeDesc - Decoded Attribute Description : objectclass
[2019-11-22 14:56:49,356][NioProcessor-2] DEBUG Asn1Decoder - --- State = TAG_STATE_START ---
[2019-11-22 14:56:49,356][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0xA0
[2019-11-22 14:56:49,356][NioProcessor-2] DEBUG Asn1Decoder - Tag 0xA0 has been decoded
[2019-11-22 14:56:49,356][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_START ---
[2019-11-22 14:56:49,356][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x84
[2019-11-22 14:56:49,356][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_PENDING ---
[2019-11-22 14:56:49,356][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x00
[2019-11-22 14:56:49,356][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x00
[2019-11-22 14:56:49,356][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x00
[2019-11-22 14:56:49,356][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x00
[2019-11-22 14:56:49,356][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x2E
[2019-11-22 14:56:49,356][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_END ---
[2019-11-22 14:56:49,356][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x30
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG Asn1Decoder - Parent length : TLV expected length stack : - 52 - null
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG Asn1Decoder - Length 46 has been decoded
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG Asn1Decoder - --- State = VALUE_STATE_START ---
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x30
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG Asn1Decoder - --- State = TLV_STATE_DONE ---
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x30
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG Asn1Decoder - TLV Tree : TLV0xA0(46)-TLV0x30(0)
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG AbstractGrammar - Transition from state <ATTRIBUTE_DESCRIPTION_STATE> to state <CONTROLS_STATE>, tag <0xA0>, action : Initialize a control
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG InitControls - A new list of controls has been initialized
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG Asn1Decoder - --- State = TAG_STATE_START ---
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x30
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG Asn1Decoder - Tag 0x30 has been decoded
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_START ---
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x84
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_PENDING ---
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x00
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x00
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x00
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x00
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x28
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_END ---
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x04
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG Asn1Decoder - Parent length : TLV expected length stack : - 46 - 0 - null
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG Asn1Decoder - Length 40 has been decoded
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG Asn1Decoder - --- State = VALUE_STATE_START ---
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x04
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG Asn1Decoder - --- State = TLV_STATE_DONE ---
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x04
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG Asn1Decoder - TLV Tree : TLV0x30(40)-TLV0xA0(0)-TLV0x30(0)
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG AbstractGrammar - Transition from state <CONTROLS_STATE> to state <CONTROL_STATE>, tag <0x30>, action : Check that the length is not null
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG Asn1Decoder - --- State = TAG_STATE_START ---
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x04
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG Asn1Decoder - Tag 0x04 has been decoded
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_START ---
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x16
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_END ---
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x31
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG Asn1Decoder - Parent length : TLV expected length stack : - 40 - 0 - 0 - null
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG Asn1Decoder - Length 22 has been decoded
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG Asn1Decoder - --- State = VALUE_STATE_START ---
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x31
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG Asn1Decoder - --- State = TLV_STATE_DONE ---
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x01
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG Asn1Decoder - TLV Tree : TLV0x04(22)-TLV0x30(16)-TLV0xA0(0)-TLV0x30(0)
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG AbstractGrammar - Transition from state <CONTROL_STATE> to state <CONTROL_TYPE_STATE>, tag <0x04>, action : Add a new control
[2019-11-22 14:56:49,357][NioProcessor-2] DEBUG AddControl - Control OID : 1.2.840.113556.1.4.319
[2019-11-22 14:56:49,358][NioProcessor-2] DEBUG Asn1Decoder - --- State = TAG_STATE_START ---
[2019-11-22 14:56:49,358][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x01
[2019-11-22 14:56:49,358][NioProcessor-2] DEBUG Asn1Decoder - Tag 0x01 has been decoded
[2019-11-22 14:56:49,358][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_START ---
[2019-11-22 14:56:49,358][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x01
[2019-11-22 14:56:49,358][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_END ---
[2019-11-22 14:56:49,358][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0xFF
[2019-11-22 14:56:49,358][NioProcessor-2] DEBUG Asn1Decoder - Parent length : TLV expected length stack : - 16 - 0 - 0 - null
[2019-11-22 14:56:49,358][NioProcessor-2] DEBUG Asn1Decoder - Length 1 has been decoded
[2019-11-22 14:56:49,358][NioProcessor-2] DEBUG Asn1Decoder - --- State = VALUE_STATE_START ---
[2019-11-22 14:56:49,358][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0xFF
[2019-11-22 14:56:49,358][NioProcessor-2] DEBUG Asn1Decoder - --- State = TLV_STATE_DONE ---
[2019-11-22 14:56:49,358][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x04
[2019-11-22 14:56:49,358][NioProcessor-2] DEBUG Asn1Decoder - TLV Tree : TLV0x01(1)-TLV0x30(13)-TLV0xA0(0)-TLV0x30(0)
[2019-11-22 14:56:49,358][NioProcessor-2] DEBUG AbstractGrammar - Transition from state <CONTROL_TYPE_STATE> to state <CRITICALITY_STATE>, tag <0x04>, action : Store the control criticality
[2019-11-22 14:56:49,358][NioProcessor-2] DEBUG StoreControlCriticality - Control criticality : true
[2019-11-22 14:56:49,358][NioProcessor-2] DEBUG Asn1Decoder - --- State = TAG_STATE_START ---
[2019-11-22 14:56:49,358][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x04
[2019-11-22 14:56:49,358][NioProcessor-2] DEBUG Asn1Decoder - Tag 0x04 has been decoded
[2019-11-22 14:56:49,358][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_START ---
[2019-11-22 14:56:49,358][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x0B
[2019-11-22 14:56:49,358][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_END ---
[2019-11-22 14:56:49,358][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x30
[2019-11-22 14:56:49,358][NioProcessor-2] DEBUG Asn1Decoder - Parent length : TLV expected length stack : - 13 - 0 - 0 - null
[2019-11-22 14:56:49,358][NioProcessor-2] DEBUG Asn1Decoder - Length 11 has been decoded
[2019-11-22 14:56:49,358][NioProcessor-2] DEBUG Asn1Decoder - --- State = VALUE_STATE_START ---
[2019-11-22 14:56:49,358][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x30
[2019-11-22 14:56:49,358][NioProcessor-2] DEBUG Asn1Decoder - --- State = TLV_STATE_DONE ---
[2019-11-22 14:56:49,358][NioProcessor-2] DEBUG Asn1Decoder - no more byte to decode in the stream
[2019-11-22 14:56:49,358][NioProcessor-2] DEBUG Asn1Decoder - TLV Tree : TLV0x04(11)-TLV0x30(0)-TLV0xA0(0)-TLV0x30(0)
[2019-11-22 14:56:49,358][NioProcessor-2] DEBUG AbstractGrammar - Transition from state <CRITICALITY_STATE> to state <CONTROL_VALUE_STATE>, tag <0x04>, action : Store the control value
[2019-11-22 14:56:49,358][NioProcessor-2] DEBUG Asn1Decoder - >>>==========================================
[2019-11-22 14:56:49,358][NioProcessor-2] DEBUG Asn1Decoder - --> Decoding a PDU
[2019-11-22 14:56:49,359][NioProcessor-2] DEBUG Asn1Decoder - >>>------------------------------------------
[2019-11-22 14:56:49,359][NioProcessor-2] DEBUG Asn1Decoder - --- State = TAG_STATE_START ---
[2019-11-22 14:56:49,359][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x30
[2019-11-22 14:56:49,359][NioProcessor-2] DEBUG Asn1Decoder - Tag 0x30 has been decoded
[2019-11-22 14:56:49,359][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_START ---
[2019-11-22 14:56:49,359][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x84
[2019-11-22 14:56:49,359][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_PENDING ---
[2019-11-22 14:56:49,359][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x00
[2019-11-22 14:56:49,359][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x00
[2019-11-22 14:56:49,359][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x00
[2019-11-22 14:56:49,359][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x00
[2019-11-22 14:56:49,359][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x05
[2019-11-22 14:56:49,359][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_END ---
[2019-11-22 14:56:49,359][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x02
[2019-11-22 14:56:49,359][NioProcessor-2] DEBUG Asn1Decoder - Parent length : TLV expected length stack : - null
[2019-11-22 14:56:49,359][NioProcessor-2] DEBUG Asn1Decoder - Root TLV[5]
[2019-11-22 14:56:49,359][NioProcessor-2] DEBUG Asn1Decoder - Length 5 has been decoded
[2019-11-22 14:56:49,359][NioProcessor-2] DEBUG Asn1Decoder - --- State = VALUE_STATE_START ---
[2019-11-22 14:56:49,359][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x02
[2019-11-22 14:56:49,359][NioProcessor-2] DEBUG Asn1Decoder - --- State = TLV_STATE_DONE ---
[2019-11-22 14:56:49,359][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x02
[2019-11-22 14:56:49,359][NioProcessor-2] DEBUG Asn1Decoder - TLV Tree : TLV0x30(5)
[2019-11-22 14:56:49,359][NioProcessor-2] DEBUG AbstractGrammar - Transition from state <START_STATE> to state <PAGED_SEARCH_SEQUENCE_STATE>, tag <0x30>, action : no action
[2019-11-22 14:56:49,359][NioProcessor-2] DEBUG Asn1Decoder - --- State = TAG_STATE_START ---
[2019-11-22 14:56:49,359][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x02
[2019-11-22 14:56:49,359][NioProcessor-2] DEBUG Asn1Decoder - Tag 0x02 has been decoded
[2019-11-22 14:56:49,359][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_START ---
[2019-11-22 14:56:49,359][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x01
[2019-11-22 14:56:49,359][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_END ---
[2019-11-22 14:56:49,359][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x64
[2019-11-22 14:56:49,359][NioProcessor-2] DEBUG Asn1Decoder - Parent length : TLV expected length stack : - 5 - null
[2019-11-22 14:56:49,360][NioProcessor-2] DEBUG Asn1Decoder - Length 1 has been decoded
[2019-11-22 14:56:49,360][NioProcessor-2] DEBUG Asn1Decoder - --- State = VALUE_STATE_START ---
[2019-11-22 14:56:49,360][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x64
[2019-11-22 14:56:49,360][NioProcessor-2] DEBUG Asn1Decoder - --- State = TLV_STATE_DONE ---
[2019-11-22 14:56:49,360][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x04
[2019-11-22 14:56:49,360][NioProcessor-2] DEBUG Asn1Decoder - TLV Tree : TLV0x02(1)-TLV0x30(2)
[2019-11-22 14:56:49,360][NioProcessor-2] DEBUG AbstractGrammar - Transition from state <PAGED_SEARCH_SEQUENCE_STATE> to state <SIZE_STATE>, tag <0x02>, action : Set PagedSearchControl size
[2019-11-22 14:56:49,360][NioProcessor-2] DEBUG PagedResultsGrammar - size = 100
[2019-11-22 14:56:49,360][NioProcessor-2] DEBUG Asn1Decoder - --- State = TAG_STATE_START ---
[2019-11-22 14:56:49,360][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x04
[2019-11-22 14:56:49,360][NioProcessor-2] DEBUG Asn1Decoder - Tag 0x04 has been decoded
[2019-11-22 14:56:49,360][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_START ---
[2019-11-22 14:56:49,360][NioProcessor-2] DEBUG Asn1Decoder - current byte : 0x00
[2019-11-22 14:56:49,360][NioProcessor-2] DEBUG Asn1Decoder - --- State = LENGTH_STATE_END ---
[2019-11-22 14:56:49,360][NioProcessor-2] DEBUG Asn1Decoder - no more byte to decode in the stream
[2019-11-22 14:56:49,360][NioProcessor-2] DEBUG Asn1Decoder - Parent length : TLV expected length stack : - 2 - null
[2019-11-22 14:56:49,360][NioProcessor-2] DEBUG Asn1Decoder - Length 0 has been decoded
[2019-11-22 14:56:49,360][NioProcessor-2] DEBUG Asn1Decoder - --- State = TLV_STATE_DONE ---
[2019-11-22 14:56:49,360][NioProcessor-2] DEBUG Asn1Decoder - no more byte to decode in the stream
[2019-11-22 14:56:49,360][NioProcessor-2] DEBUG Asn1Decoder - TLV Tree : TLV0x04(0)-TLV0x30(0)
[2019-11-22 14:56:49,360][NioProcessor-2] DEBUG AbstractGrammar - Transition from state <SIZE_STATE> to state <COOKIE_STATE>, tag <0x04>, action : Set PagedSearchControl cookie
[2019-11-22 14:56:49,360][NioProcessor-2] DEBUG Asn1Decoder - <<<------------------------------------------
[2019-11-22 14:56:49,360][NioProcessor-2] DEBUG Asn1Decoder - <-- Stop decoding : TLV[ 0x04, 0, []]
[2019-11-22 14:56:49,360][NioProcessor-2] DEBUG Asn1Decoder - <<<==========================================
[2019-11-22 14:56:49,360][NioProcessor-2] DEBUG StoreControlValue - Control value : 0x30 0x84 0x00 0x00 0x00 0x05 0x02 0x01 0x64 0x04 0x00
[2019-11-22 14:56:49,360][NioProcessor-2] DEBUG Asn1Decoder - <<<------------------------------------------
[2019-11-22 14:56:49,360][NioProcessor-2] DEBUG Asn1Decoder - <-- Stop decoding : TLV[ 0x04, 11, DATA[0x30 0x84 0x00 0x00 0x00 0x05 0x02 0x01 0x64 0x04 0x00 ]]
[2019-11-22 14:56:49,360][NioProcessor-2] DEBUG Asn1Decoder - <<<==========================================
[2019-11-22 14:56:49,360][NioProcessor-2] DEBUG CODEC_LOG - Decoded LdapMessage : MessageType : SEARCH_REQUEST
Message ID : 71
SearchRequest
baseDn : 'ou=Sage-Femme,o=GIP-CPS,c=fr'
filter : '(objectclass=*)'
scope : single level
typesOnly : false
Size Limit : no limit
Time Limit : 60
Deref Aliases : never Deref Aliases
attributes : 'objectclass'
org.apache.directory.api.ldap.model.message.SearchRequestImpl@adaaadd5 Paged Search Control
oid : 1.2.840.113556.1.4.319
critical : true
size : '100'
cookie : ''

[2019-11-22 14:56:49,390][pool-4-thread-3] DEBUG IoFilterEvent - Firing a MESSAGE_RECEIVED event for session 1
[2019-11-22 14:56:49,391][pool-4-thread-3] DEBUG SearchRequestHandler - Handling single reply request: MessageType : SEARCH_REQUEST
Message ID : 71
SearchRequest
baseDn : 'ou=Sage-Femme,o=GIP-CPS,c=fr'
filter : '(objectclass=*)'
scope : single level
typesOnly : false
Size Limit : no limit
Time Limit : 60
Deref Aliases : never Deref Aliases
attributes : 'objectclass'
org.apache.directory.api.ldap.model.message.SearchRequestImpl@adaaadd5 Paged Search Control
oid : 1.2.840.113556.1.4.319
critical : true
size : '100'
cookie : ''

[2019-11-22 14:56:49,391][pool-4-thread-3] DEBUG SearchRequestHandler - ManageDsaITControl NOT detected.
[2019-11-22 14:56:49,391][pool-4-thread-3] DEBUG SearchRequestHandler - Entry ou=Sage-Femme,o=GIP-CPS,c=fr is NOT a referral.
[2019-11-22 14:56:49,391][pool-4-thread-3] DEBUG SearchRequestHandler - Message received: MessageType : SEARCH_REQUEST
Message ID : 71
SearchRequest
baseDn : 'ou=Sage-Femme,o=GIP-CPS,c=fr'
filter : '(objectclass=*)'
scope : single level
typesOnly : false
Size Limit : no limit
Time Limit : 60
Deref Aliases : never Deref Aliases
attributes : 'objectclass'
org.apache.directory.api.ldap.model.message.SearchRequestImpl@adaaadd5 Paged Search Control
oid : 1.2.840.113556.1.4.319
critical : true
size : '100'
cookie : ''

[2019-11-22 14:56:49,391][pool-4-thread-3] DEBUG DefaultSchemaObjectRegistry - Found attributetype ( 2.5.4.0 NAME 'objectClass'
DESC 'RFC2256: object classes of the entity'
EQUALITY objectIdentifierMatch
SYNTAX 1.3.6.1.4.1.1466.115.121.1.38
USAGE userApplications
) with oid: objectclass
[2019-11-22 14:56:49,391][pool-4-thread-3] DEBUG OPERATION_LOG - >> SearchOperation : SearchContext for Dn 'ou=Sage-Femme,o=GIP-CPS,c=fr', filter :'(objectclass=)'
[2019-11-22 14:56:49,391][pool-4-thread-3] DEBUG InterceptorChain - Chain Position : 0
[2019-11-22 14:56:49,391][pool-4-thread-3] DEBUG InterceptorChain - Insert : net.sourceforge.myvd.inserts.DumpTransaction@7e535455
[2019-11-22 14:56:49,391][pool-4-thread-3] INFO DumpTransaction - [Global] Begin Seach - Filter=(objectclass=);Base=ou=Sage-Femme,o=GIP-CPS,c=fr;Scope=1;Attributes=objectclass
[2019-11-22 14:56:49,391][pool-4-thread-3] DEBUG InterceptorChain - Chain Completed
[2019-11-22 14:56:49,391][pool-4-thread-3] DEBUG Router - Entering router search
[2019-11-22 14:56:49,391][pool-4-thread-3] DEBUG Router - Determining local levels
[2019-11-22 14:56:49,391][pool-4-thread-3] DEBUG Router - Is set namespace?
[2019-11-22 14:56:49,391][pool-4-thread-3] DEBUG Router - namespace set by router
[2019-11-22 14:56:49,391][pool-4-thread-3] DEBUG Router - namespace levels determined
[2019-11-22 14:56:49,391][pool-4-thread-3] DEBUG Router - Determined local levels
[2019-11-22 14:56:49,391][pool-4-thread-3] DEBUG Router - Iterate over levels
[2019-11-22 14:56:49,391][pool-4-thread-3] DEBUG Router - Determine scope
[2019-11-22 14:56:49,391][pool-4-thread-3] DEBUG Router - Base determined
[2019-11-22 14:56:49,391][pool-4-thread-3] DEBUG Router - create local chain
[2019-11-22 14:56:49,391][pool-4-thread-3] DEBUG Router - Begin Local Chain
[2019-11-22 14:56:49,391][pool-4-thread-3] DEBUG InterceptorChain - Chain Position : 0
[2019-11-22 14:56:49,391][pool-4-thread-3] DEBUG InterceptorChain - Insert : net.sourceforge.myvd.inserts.setrdn.SetRDN@346e3dc6
[2019-11-22 14:56:49,392][pool-4-thread-3] DEBUG InterceptorChain - Chain Position : 1
[2019-11-22 14:56:49,392][pool-4-thread-3] DEBUG InterceptorChain - Insert : net.sourceforge.myvd.inserts.ldap.LDAPInterceptor@3c332abd
[2019-11-22 14:56:49,392][pool-4-thread-3] DEBUG LDAPInterceptor - Bound inserts : []
[2019-11-22 14:56:49,393][pool-4-thread-3] DEBUG Router - chain complete
[2019-11-22 14:56:49,394][pool-4-thread-3] INFO DumpTransaction - [Global] Seach submitted
[2019-11-22 14:56:49,415][pool-4-thread-3] INFO DumpTransaction - [Global] Begin Post Search Entry - Filter=(objectclass=*);Base=ou=Sage-Femme,o=GIP-CPS,c=fr;Scope=1;Attributes=[LDAPAttribute: {type='objectclass'}, LDAPAttribute: {type='cn'}]
dn : cn=810100896421+sn=LEBIGRE+givenName=COLINE,ou=Sage-Femme,o=GIP-CPS,c=fr
cn : 810100896421
objectClass : top
objectClass : pkiUser
objectClass : cpsPersonne
objectClass : cpsCertificate
objectClass : cpsCarteCPS
myVdReturnEntry: true
[2019-11-22 14:56:49,415][pool-4-thread-3] DEBUG InterceptorChain - Chain Position : 0
[2019-11-22 14:56:49,415][pool-4-thread-3] DEBUG InterceptorChain - Insert : net.sourceforge.myvd.inserts.setrdn.SetRDN@346e3dc6
[2019-11-22 14:56:49,415][pool-4-thread-3] DEBUG InterceptorChain - Chain Position : 1
[2019-11-22 14:56:49,415][pool-4-thread-3] DEBUG InterceptorChain - Insert : net.sourceforge.myvd.inserts.ldap.LDAPInterceptor@3c332abd
[2019-11-22 14:56:49,415][pool-4-thread-3] INFO DumpTransaction - [Global] Post Seach Entry Complete
dn : cn=810100896421+sn=LEBIGRE+givenName=COLINE,ou=Sage-Femme,o=GIP-CPS,c=fr
cn : 810100896421
objectClass : top
objectClass : pkiUser
objectClass : cpsPersonne
objectClass : cpsCertificate
objectClass : cpsCarteCPS
myVdReturnEntry: true
[2019-11-22 14:56:49,415][pool-4-thread-3] DEBUG CURSOR_LOG - Creating MyVDBaseCursor BaseEntryFilteringCursor, no wrapped
No filter
No prefetched
[2019-11-22 14:56:49,415][pool-4-thread-3] DEBUG OPERATION_LOG - << SearchOperation successful
[2019-11-22 14:56:49,415][pool-4-thread-3] DEBUG OPERATION_TIME - Search operation took 24193624 ns
[2019-11-22 14:56:49,415][pool-4-thread-3] DEBUG SearchRequestHandler - using <9223372036854775807,100> for size limit
[2019-11-22 14:56:49,421][pool-4-thread-3] DEBUG CURSOR_LOG - Closing BaseEntryFilteringCursor BaseEntryFilteringCursor, wrapped :
No filter
No prefetched
[2019-11-22 14:56:49,421][pool-4-thread-3] DEBUG CODEC_LOG - Encoded message
MessageType : SEARCH_RESULT_DONE
Message ID : 71
Search Result Done
Ldap Result
Result code : (SUCCESS) success
Matched Dn : 'null'
Diagnostic message : 'null'

: 0x30 0x0C 0x02 0x01 0x47 0x65 0x07 0x0A 0x01 0x00 0x04 0x00 0x04 0x00
[2019-11-22 14:56:49,421][pool-4-thread-3] DEBUG SEARCH_TIME_LOG - Search with filter (objectclass=) took 30ms. Filter with assigned counts is (objectclass=)
[2019-11-22 14:56:49,421][pool-4-thread-3] DEBUG IoFilterEvent - Event MESSAGE_RECEIVED has been fired for session 1
[2019-11-22 14:56:49,422][NioProcessor-2] DEBUG LdapResponseHandler - Message Sent : MessageType : SEARCH_RESULT_DONE
Message ID : 71
Search Result Done
Ldap Result
Result code : (SUCCESS) success
Matched Dn : 'null'
Diagnostic message : 'null'

############END OF LOG FILE#####################

[mlbiam]

Got this working in my environment:

1. Remove everything from the lib directory
2. Download https://drive.google.com/file/d/1KEHEa4VBCbJ0nqVYsz5KT4Ur2mneQCHj/view?usp=sharing and unzip it in the lib directory
3. update your myvd.conf with the below (added an insert):

server.LDAPCPS.chain=clean,RDN,unescape,LDAP2
server.LDAPCPS.nameSpace=c=fr
server.LDAPCPS.weight=100
server.LDAPCPS.clean.className=net.sourceforge.myvd.inserts.mapping.AttributeCleaner
server.LDAPCPS.clean.clearAttributes=true
server.LDAPCPS.RDN.className=net.sourceforge.myvd.inserts.setrdn.SetRDN
server.LDAPCPS.RDN.config.internalRDN=cn+sn+givenName
server.LDAPCPS.RDN.config.externalRDN=cn
server.LDAPCPS.RDN.config.objectClass=cpsPersonne
server.LDAPCPS.unescape.className=net.sourceforge.myvd.inserts.mapping.ClearMultiValueRDNEscapesFilter
server.LDAPCPS.LDAP2.className=net.sourceforge.myvd.inserts.ldap.LDAPInterceptor
server.LDAPCPS.LDAP2.config.host=annuaire.asipsante.fr
server.LDAPCPS.LDAP2.config.port=389
server.LDAPCPS.LDAP2.config.remoteBase=c=fr

Restart MyVD. This isn't production quality yet but the build passed our integration tests so let me know how it goes.

[sgadeau]

Hello Marc, I have tested your solution this morning, it‘s perfect ! Thank’s a lot for your help. Now I am trying to join mariadb and another LDAP, the story continue … I’ll keep you informed of my progress Best regard Stéphane PS: I was wondering if the new insert you developed permits to handle multivalued DN in general ? Is it possible to use setRDN.insert to set multivalue RDN ? If the original DN is multivalued and we do nothing special, will myvd proxyfy the multivalued DN? De : Marc Boorshtein [mailto:notifications@github.com] Envoyé : mercredi 27 novembre 2019 19:48 À : TremoloSecurity/MyVirtualDirectory Cc : GADEAU Stéphane; Author Objet : Re: [TremoloSecurity/MyVirtualDirectory] Proxyfing a LDAP with specific Schema returns no Entries (#74) Got this working in my environment: 1. Remove everything from the lib directory 2. Download https://drive.google.com/file/d/1KEHEa4VBCbJ0nqVYsz5KT4Ur2mneQCHj/view?usp=sharing and unzip it in the lib directory 3. update your myvd.conf with the below (added an insert): server.LDAPCPS.chain=clean,RDN,unescape,LDAP2 server.LDAPCPS.nameSpace=c=fr server.LDAPCPS.weight=100 server.LDAPCPS.clean.className=net.sourceforge.myvd.inserts.mapping.AttributeCleaner server.LDAPCPS.clean.clearAttributes=true server.LDAPCPS.RDN.className=net.sourceforge.myvd.inserts.setrdn.SetRDN server.LDAPCPS.RDN.config.internalRDN=cn+sn+givenName server.LDAPCPS.RDN.config.externalRDN=cn server.LDAPCPS.RDN.config.objectClass=cpsPersonne server.LDAPCPS.unescape.className=net.sourceforge.myvd.inserts.mapping.ClearMultiValueRDNEscapesFilter server.LDAPCPS.LDAP2.className=net.sourceforge.myvd.inserts.ldap.LDAPInterceptor server.LDAPCPS.LDAP2.config.host=annuaire.asipsante.fr server.LDAPCPS.LDAP2.config.port=389 server.LDAPCPS.LDAP2.config.remoteBase=c=fr Restart MyVD. This isn't production quality yet but the build passed our integration tests so let me know how it goes. — You are receiving this because you authored the thread. Reply to this email directly, view it on GitHub<#74?email_source=notifications&email_token=AN2EJBYW2VQ7PGBZ4EJKHCTQV26HLA5CNFSM4JQROCF2YY3PNVWWK3TUL52HS4DFVREXG43VMVBW63LNMVXHJKTDN5WW2ZLOORPWSZGOEFKNYDY#issuecomment-559209487>, or unsubscribe<https://github.com/notifications/unsubscribe-auth/AN2EJB7E2B6JKB2HFBUQHZ3QV26HLANCNFSM4JQROCFQ>.