OpenIDM sync password to OpenDJ performance issue

This topic has 3 replies, 2 voices, and was last updated 5 years, 6 months ago by Bill Nelson.

  • Author
    Posts
  • #15045
     timmychan
    Participant

    Dear all,

    I set up a synchronization from OpenIDM to OpenDJ with the below connector:

    {
        "name" : "ldap",
        "connectorRef" : {
            "bundleName" : "org.forgerock.openicf.connectors.ldap-connector",
            "bundleVersion" : "1.4.1.0",
            "connectorName" : "org.identityconnectors.ldap.LdapConnector"
        },
        "configurationProperties" : {
            "host" : "10.77.15.85",
            "port" : "5636",
            "ssl" : "true",
            "principal" : "cn=Directory Manager",
            "credentials" : {
                "$crypto" : {
                    "type" : "x-simple-encryption",
                    "value" : {
                        "cipher" : "AES/CBC/PKCS5Padding",
                        "data" : "vudV9MU7qh5ztK1HWNnOvg==",
                        "iv" : "24bbMEuURfcL6Gu16sz7HA==",
                        "key" : "openidm-sym-default"
                    }
                }
            },
            "baseContexts" : [
                "ou=people,ou=users,o=example,dc=com,dc=hk"
            ],
            "baseContextsToSynchronize" : [
                "ou=people,ou=users,o=example,dc=com,dc=hk"
            ],
            "accountSearchFilter" : null,
            "accountSynchronizationFilter" : "(&(objectClass=top)(objectClass=person)(objectClass=inetOrgPerson)(objectClass=organizationalPerson)(objectClass=inetuser)(objectClass=dpPerson))",
            "groupSearchFilter" : null,
            "groupSynchronizationFilter" : null,
            "passwordAttributeToSynchronize" : null,
            "synchronizePasswords" : false,
            "removeLogEntryObjectClassFromFilter" : true,
            "modifiersNamesToFilterOut" : [ ],
            "passwordDecryptionKey" : null,
            "changeLogBlockSize" : 100,
            "attributesToSynchronize" : [ ],
            "changeNumberAttribute" : "changeNumber",
            "passwordDecryptionInitializationVector" : null,
            "filterWithOrInsteadOfAnd" : false,
            "objectClassesToSynchronize" : [
                "inetOrgPerson"
            ],
            "vlvSortAttribute" : "uid",
            "passwordAttribute" : "userPassword",
            "useBlocks" : false,
            "maintainPosixGroupMembership" : false,
            "failover" : [ ],
            "readSchema" : false,
            "accountObjectClasses" : [
                "top",
                "person",
                "organizationalPerson",
                "inetOrgPerson",
                "inetuser",
                "dpPerson"
            ],
            "accountUserNameAttributes" : [
                "uid"
            ],
            "groupMemberAttribute" : "uniqueMember",
            "passwordHashAlgorithm" : null,
            "usePagedResultControl" : true,
            "blockSize" : 100,
            "uidAttribute" : "dn",
            "maintainLdapGroupMembership" : false,
            "respectResourcePasswordPolicyChangeAfterReset" : false
        },
        "resultsHandlerConfig" : {
            "enableNormalizingResultsHandler" : true,
            "enableFilteredResultsHandler" : false,
            "enableCaseInsensitiveFilter" : false,
            "enableAttributesToGetSearchResultsHandler" : false
        },
        "poolConfigOption" : {
            "maxObjects" : 1000,
            "maxIdle" : 1,
            "maxWait" : 150000,
            "minEvictableIdleTimeMillis" : 1000,
            "minIdle" : 1
        },
        "operationTimeout" : {
            "CREATE" : -1,
            "VALIDATE" : -1,
            "TEST" : -1,
            "SCRIPT_ON_CONNECTOR" : -1,
            "SCHEMA" : -1,
            "DELETE" : -1,
            "UPDATE" : -1,
            "SYNC" : -1,
            "AUTHENTICATE" : -1,
            "GET" : -1,
            "SCRIPT_ON_RESOURCE" : -1,
            "SEARCH" : -1
        },
        "syncFailureHandler" : {
            "maxRetries" : 0,
            "postRetryAction" : "logged-ignore"
        },
        "objectTypes" : {
            "group" : {
                "$schema" : "http://json-schema.org/draft-03/schema",
                "id" : "__GROUP__",
                "type" : "object",
                "nativeType" : "__GROUP__",
                "properties" : {
                    "seeAlso" : {
                        "type" : "array",
                        "items" : {
                            "type" : "string",
                            "nativeType" : "string"
                        },
                        "nativeName" : "seeAlso",
                        "nativeType" : "string"
                    },
                    "description" : {
                        "type" : "array",
                        "items" : {
                            "type" : "string",
                            "nativeType" : "string"
                        },
                        "nativeName" : "description",
                        "nativeType" : "string"
                    },
                    "uniqueMember" : {
                        "type" : "array",
                        "items" : {
                            "type" : "string",
                            "nativeType" : "string"
                        },
                        "nativeName" : "uniqueMember",
                        "nativeType" : "string"
                    },
                    "dn" : {
                        "type" : "string",
                        "required" : true,
                        "nativeName" : "__NAME__",
                        "nativeType" : "string"
                    },
                    "o" : {
                        "type" : "array",
                        "items" : {
                            "type" : "string",
                            "nativeType" : "string"
                        },
                        "nativeName" : "o",
                        "nativeType" : "string"
                    },
                    "ou" : {
                        "type" : "array",
                        "items" : {
                            "type" : "string",
                            "nativeType" : "string"
                        },
                        "nativeName" : "ou",
                        "nativeType" : "string"
                    },
                    "businessCategory" : {
                        "type" : "array",
                        "items" : {
                            "type" : "string",
                            "nativeType" : "string"
                        },
                        "nativeName" : "businessCategory",
                        "nativeType" : "string"
                    },
                    "owner" : {
                        "type" : "array",
                        "items" : {
                            "type" : "string",
                            "nativeType" : "string"
                        },
                        "nativeName" : "owner",
                        "nativeType" : "string"
                    },
                    "cn" : {
                        "type" : "array",
                        "items" : {
                            "type" : "string",
                            "nativeType" : "string"
                        },
                        "required" : true,
                        "nativeName" : "cn",
                        "nativeType" : "string"
                    }
                }
            },
            "account" : {
                "$schema" : "http://json-schema.org/draft-03/schema",
                "id" : "__ACCOUNT__",
                "type" : "object",
                "nativeType" : "__ACCOUNT__",
                "properties" : {
                    "cn" : {
                        "type" : "string",
                        "nativeType" : "string",
                        "nativeName" : "cn",
                        "required" : false
                    },
                    "employeeType" : {
                        "type" : "string",
                        "nativeType" : "string",
                        "nativeName" : "employeeType",
                        "required" : false
                    },
                    "description" : {
                        "type" : "string",
                        "nativeType" : "string",
                        "nativeName" : "description",
                        "required" : false
                    },
                    "givenName" : {
                        "type" : "string",
                        "nativeType" : "string",
                        "nativeName" : "givenName",
                        "required" : false
                    },
                    "mail" : {
                        "type" : "string",
                        "nativeType" : "string",
                        "nativeName" : "mail",
                        "required" : false
                    },
                    "telephoneNumber" : {
                        "type" : "string",
                        "nativeType" : "string",
                        "nativeName" : "telephoneNumber",
                        "required" : false
                    },
                    "sn" : {
                        "type" : "string",
                        "nativeType" : "string",
                        "nativeName" : "sn",
                        "required" : false
                    },
                    "uid" : {
                        "type" : "string",
                        "nativeType" : "string",
                        "nativeName" : "uid",
                        "required" : false
                    },
                    "dn" : {
                        "type" : "string",
                        "nativeType" : "string",
                        "nativeName" : "__NAME__",
                        "required" : true
                    },
                    "userPassword" : {
                        "type" : "string",
                        "nativeType" : "string",
                        "nativeName" : "userPassword",
                        "required" : false,
                        "flags" : [
                            "NOT_READABLE",
                            "NOT_RETURNED_BY_DEFAULT"
                        ]
                    },
                    "ldapGroups" : {
                        "type" : "array",
                        "nativeType" : "string",
                        "nativeName" : "ldapGroups",
                        "required" : false,
                        "items" : {
                            "type" : "string",
                            "nativeType" : "string"
                        }
                    },
                    "disabled" : {
                        "type" : "string",
                        "nativeType" : "boolean",
                        "nativeName" : "ds-pwp-account-disabled",
                        "required" : false
                    },
                    "DPDEPTID" : {
                        "type" : "string",
                        "nativeType" : "string",
                        "nativeName" : "DPDEPTID",
                        "required" : false
                    },
                    "dphkid" : {
                        "type" : "string",
                        "nativeType" : "string",
                        "nativeName" : "dphkid",
                        "required" : false
                    },
                    "DPRANKCODE" : {
                        "type" : "string",
                        "nativeType" : "string",
                        "nativeName" : "DPRANKCODE",
                        "required" : false
                    },
                    "dpstaffgroup" : {
                        "type" : "string",
                        "nativeType" : "string",
                        "nativeName" : "dpstaffgroup",
                        "required" : false
                    },
                    "inetUserStatus" : {
                        "type" : "string",
                        "nativeType" : "string",
                        "nativeName" : "inetUserStatus",
                        "required" : false
                    },
                    "password" : {
                        "type" : "string",
                        "nativeType" : "JAVA_TYPE_GUARDEDSTRING",
                        "nativeName" : "__PASSWORD__",
                        "required" : false,
                        "flags" : [
                            "NOT_READABLE",
                            "NOT_RETURNED_BY_DEFAULT"
                        ]
                    }
                }
            }
        },
        "operationOptions" : {
            "DELETE" : {
                "denied" : false,
                "onDeny" : "DO_NOTHING"
            },
            "UPDATE" : {
                "denied" : false,
                "onDeny" : "DO_NOTHING"
            },
            "CREATE" : {
                "denied" : false,
                "onDeny" : "DO_NOTHING"
            }
        },
        "enabled" : true,
        "enable" : "provisioner.openicf-ldap"
    }

    and the sync setting:

    {
        "mappings" : [
            {
                "target" : "system/ldap/account",
                "source" : "managed/user",
                "name" : "managedUser_ldapAccount",
                "properties" : [
                    {
                        "target" : "uid",
                        "source" : "userName"
                    },
                    {
                        "target" : "cn",
                        "source" : "cn"
                    },
                    {
                        "target" : "givenName",
                        "source" : "givenName"
                    },
                    {
                        "target" : "sn",
                        "source" : "sn"
                    },
                    {
                        "target" : "dphkid",
                        "source" : "dphkid",
                        "transform" : {
                            "type" : "text/javascript",
                            "globals" : { },
                            "file" : "ui/onRetrieveManagedUser_Ldap.js"
                        }
                    },
                    {
                        "target" : "employeeType",
                        "source" : "employeeType"
                    },
                    {
                        "target" : "dpstaffgroup",
                        "source" : "dpstaffgroup"
                    },
                    {
                        "target" : "DPRANKCODE",
                        "source" : "dprankcode"
                    },
                    {
                        "target" : "DPDEPTID",
                        "source" : "dpdeptid"
                    },
                    {
                        "target" : "description",
                        "source" : "office"
                    },
                    {
                        "target" : "inetUserStatus",
                        "source" : "accountStatus"
                    },
                    {
                        "target" : "userPassword",
                        "source" : "password",
                        "condition" : {
                            "type" : "text/javascript",
                            "globals" : { },
                            "source" : "object.password != null && object.pwdChanged ==='true'"
                        },
                        "transform" : {
                            "type" : "text/javascript",
                            "globals" : { },
                            "source" : "console.log(openidm.decrypt(source));\nopenidm.decrypt(source);"
                        }
                    }
                ],
                "policies" : [
                    {
                        "action" : "IGNORE",
                        "situation" : "AMBIGUOUS"
                    },
                    {
                        "action" : "DELETE",
                        "situation" : "SOURCE_MISSING"
                    },
                    {
                        "action" : "IGNORE",
                        "situation" : "MISSING"
                    },
                    {
                        "action" : "LINK",
                        "situation" : "FOUND_ALREADY_LINKED"
                    },
                    {
                        "action" : "IGNORE",
                        "situation" : "UNQUALIFIED"
                    },
                    {
                        "action" : "IGNORE",
                        "situation" : "UNASSIGNED"
                    },
                    {
                        "action" : "IGNORE",
                        "situation" : "LINK_ONLY"
                    },
                    {
                        "action" : "IGNORE",
                        "situation" : "TARGET_IGNORED"
                    },
                    {
                        "action" : "IGNORE",
                        "situation" : "SOURCE_IGNORED"
                    },
                    {
                        "action" : "IGNORE",
                        "situation" : "ALL_GONE"
                    },
                    {
                        "action" : "UPDATE",
                        "situation" : "CONFIRMED"
                    },
                    {
                        "action" : "LINK",
                        "situation" : "FOUND"
                    },
                    {
                        "action" : "CREATE",
                        "situation" : "ABSENT"
                    }
                ],
                "onCreate" : {
                    "type" : "text/javascript",
                    "globals" : { },
                    "source" : "target.dn = 'uid=' + source.userName + ',ou=people,ou=users,o=example,dc=com,dc=hk';"
                },
                "enableSync" : true,
                "correlationQuery" : [
                    {
                        "linkQualifier" : "default",
                        "expressionTree" : {
                            "any" : [
                                "uid"
                            ]
                        },
                        "mapping" : "managedUser_ldapAccount",
                        "type" : "text/javascript",
                        "file" : "ui/correlateTreeToQueryFilter.js"
                    }
                ],
                "sourceQuery" : {
                    "_queryFilter" : "(userName sw \"\")"
                },
                "targetQuery" : {
                    "_queryFilter" : "uid sw \"\""
                },
                "recon" : {
                    "_id" : "1dbe6d8c-a977-4c6f-8689-db67c21e9cab-4441",
                    "mapping" : "managedUser_ldapAccount",
                    "state" : "SUCCESS",
                    "stage" : "COMPLETED_SUCCESS",
                    "stageDescription" : "reconciliation completed.",
                    "progress" : {
                        "source" : {
                            "existing" : {
                                "processed" : 25,
                                "total" : "25"
                            }
                        },
                        "target" : {
                            "existing" : {
                                "processed" : 23,
                                "total" : "23"
                            },
                            "created" : 0
                        },
                        "links" : {
                            "existing" : {
                                "processed" : 11,
                                "total" : "12"
                            },
                            "created" : 0
                        }
                    },
                    "situationSummary" : {
                        "SOURCE_IGNORED" : 0,
                        "UNASSIGNED" : 12,
                        "AMBIGUOUS" : 0,
                        "CONFIRMED" : 11,
                        "FOUND_ALREADY_LINKED" : 0,
                        "UNQUALIFIED" : 0,
                        "ABSENT" : 14,
                        "TARGET_IGNORED" : 0,
                        "SOURCE_MISSING" : 0,
                        "MISSING" : 0,
                        "FOUND" : 0
                    },
                    "statusSummary" : {
                        "SUCCESS" : 12,
                        "FAILURE" : 25
                    },
                    "parameters" : {
                        "sourceQuery" : {
                            "resourceName" : "managed/user",
                            "queryId" : "query-all-ids"
                        },
                        "targetQuery" : {
                            "resourceName" : "system/ldap/account",
                            "queryId" : "query-all-ids"
                        }
                    },
                    "started" : "2016-09-14T08:53:38.386Z",
                    "ended" : "2016-09-14T08:53:41.338Z",
                    "duration" : 2952
                }
            }
        ]
    }

    When I changed password for a user, it sometimes takes a pretty short time (1s) while sometimes takes a long period (>30s). I captured the logs of long period:

    Dec 23, 2016 1:46:58 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService query
    FINEST: Full id: managed/user Extracted type: managed/user
    Dec 23, 2016 1:46:58 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries query
    FINE: Querying {_pageSize=2147483647, _queryFilter=null, _queryExpression=null, _pagedResultsOffset=0, _resource=managed/user, username=openidm-admin, _queryId=credential-query, _sortKeys=[]}
    Dec 23, 2016 1:46:58 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries resolveQuery
    FINE: Prepared statement: SELECT * FROM openidm.managed_user WHERE userName = ? and accountStatus = 'active'
    Dec 23, 2016 1:46:58 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService query
    FINEST: Full id: internal/user Extracted type: internal/user
    Dec 23, 2016 1:46:58 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries query
    FINE: Querying {_pageSize=2147483647, _queryFilter=null, _queryExpression=null, _pagedResultsOffset=0, _resource=internal/user, username=openidm-admin, _queryId=credential-internaluser-query, _sortKeys=[]}
    Dec 23, 2016 1:46:58 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries resolveQuery
    FINE: Prepared statement: SELECT objectid, pwd, roles FROM openidm.internaluser WHERE objectid = ?
    Dec 23, 2016 1:46:58 PM org.forgerock.openidm.repo.jdbc.impl.Mapping mapToJsonValue
    FINE: Mapped rs [email protected] to { "_id": "openidm-admin", "password": "openidm-admin", "roles": [ { "_ref": "repo/internal/role/openidm-admin" }, { "_ref": "repo/internal/role/openidm-authorized" } ] }
    Dec 23, 2016 1:46:58 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Create with fullid audit/authentication/18647504-cd46-448f-a945-f3f5dadf46ce-421
    Dec 23, 2016 1:46:58 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Preparing statement INSERT INTO openidm.auditauthentication (objectid, transactionid, activitydate, userid, eventname, result, principals, context, entries, trackingids) VALUES ( ?,?,?,?,?,?,?,?,?,?) with audit/authentication, 18647504-cd46-448f-a945-f3f5dadf46ce-421, 0
    Dec 23, 2016 1:46:58 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 7 from /principal is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: [ "openidm-admin" ]
    Dec 23, 2016 1:46:58 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 8 from /context is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: { "component": "repo/internal/user", "roles": [ "openidm-admin", "openidm-authorized" ], "ipAddress": "10.77.15.76", "id": "openidm-admin" }
    Dec 23, 2016 1:46:58 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 9 from /entries is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: [ { "moduleId": "JwtSession", "result": "FAILED", "reason": {  }, "info": {  } }, { "moduleId": "STATIC_USER", "result": "FAILED", "reason": {  }, "info": { "org.forgerock.authentication.principal": "openidm-admin" } }, { "moduleId": "MANAGED_USER", "result": "FAILED", "reason": {  }, "info": { "org.forgerock.authentication.principal": "openidm-admin" } }, { "moduleId": "INTERNAL_USER", "result": "SUCCESSFUL", "info": { "org.forgerock.authentication.principal": "openidm-admin" } } ]
    Dec 23, 2016 1:46:58 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 10 from /trackingIds is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: [ "4491caa0-aab8-406e-8f6b-0ea5f24eac2b" ]
    Dec 23, 2016 1:46:58 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Executing: INSERT INTO openidm.auditauthentication (objectid, transactionid, activitydate, userid, eventname, result, principals, context, entries, trackingids) VALUES ( ?,?,?,?,?,?,?,?,?,?)
    Dec 23, 2016 1:46:58 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Created object for id audit/authentication/18647504-cd46-448f-a945-f3f5dadf46ce-421 with rev 0
    Dec 23, 2016 1:46:58 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService create
    FINE: Commited created object for id: audit/authentication/18647504-cd46-448f-a945-f3f5dadf46ce-421
    Dec 23, 2016 1:46:58 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService query
    FINEST: Full id: managed/user Extracted type: managed/user
    Dec 23, 2016 1:46:58 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries query
    FINE: Querying {_pageSize=2147483647, uid=test2016122301, _queryFilter=null, _queryExpression=null, _pagedResultsOffset=0, _resource=managed/user, _queryId=query-userName-no-case-sensitive, _sortKeys=[]}
    Dec 23, 2016 1:46:58 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries resolveQuery
    FINE: Prepared statement: SELECT *, convert(from_base64(dphkid),CHAR(225)) hkid FROM openidm.managed_user WHERE lower(userName) = lower(?)
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.Mapping mapToJsonValue
    FINE: Mapped rs [email protected] to { "_id": "aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994", "_rev": "4", "userName": "test2016122301", "password": { "$crypto": { "type": "x-simple-encryption", "value": { "cipher": "AES/CBC/PKCS5Padding", "data": "vEMI8au7CtJG63ADht9cgQ==", "iv": "c0rtN+EDT+1JWblyI6y3GA==", "key": "openidm-sym-default" } } }, "accountStatus": "active", "givenName": "test2016122301", "sn": "test2016122301", "office": "eACPC", "dphkid": "MTFMQU5EU0R0ZXN0MjAxNjEyMjMwMQ==", "rank": "1", "dprankcode": "1", "dpstaffgroup": "1", "dpdeptid": "LANDSD", "firstdayofapp": null, "idminformation": "uid=test2016122301,ou=people,ou=users,o=example,dc=com,dc=hk", "employeeType": "CS", "cn": "test2016122301", "adminFlag": "0", "oldPassword": { "$crypto": { "type": "x-simple-encryption", "value": { "cipher": "AES/CBC/PKCS5Padding", "data": "jbhSndDWXSvtkWsCEJKfgw==", "iv": "+WKAkQvBeLWFbPtVr4YX+Q==", "key": "openidm-sym-default" } } }, "amsUserName": null, "amsPassword": null, "amsDLO": null, "amsDevUserName": null, "amsDevPassword": null, "amsDevDLO": null, "grpsUserName": null, "grpsPassword": null, "grpsdevUserName": null, "grpsdevPassword": null, "shisUserName": null, "shisPassword": null, "visUserName": null, "visPassword": null, "visDevUserName": null, "visDevPassword": null, "pwdChanged": "true", "dateofbirth": null }
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Create with fullid audit/access/18647504-cd46-448f-a945-f3f5dadf46ce-422
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Preparing statement INSERT INTO openidm.auditaccess (objectid, activitydate, eventname, transactionid, userid, trackingids, server_ip, server_port, client_ip, client_port, request_protocol, request_operation, request_detail, http_request_secure, http_request_method, http_request_path, http_request_queryparameters, http_request_headers, http_request_cookies, http_response_headers, response_status, response_statuscode, response_elapsedtime, response_elapsedtimeunits, roles) VALUES ( ?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?) with audit/access, 18647504-cd46-448f-a945-f3f5dadf46ce-422, 0
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 8 from /server/port is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: 18443
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 10 from /client/port is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: 40681
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 13 from /request/detail is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: { "action": "query" }
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 14 from /http/request/secure is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: true
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 17 from /http/request/queryParameters is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: { "_action": [ "query" ], "userName": [ "test2016122301" ] }
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 18 from /http/request/headers is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: { "Accept": [ "application/json, text/javascript, */*; q=0.01" ], "Charset": [ "UTF-8" ], "Connection": [ "keep-alive" ], "Content-Length": [ "0" ], "Content-Type": [ "application/json" ], "Host": [ "10.77.15.90:18443" ], "User-Agent": [ "Apache-HttpClient/4.5.2 (Java/1.7.0)" ], "X-OpenIDM-Username": [ "openidm-admin" ] }
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 19 from /http/request/cookies is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: {  }
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 23 from /response/elapsedTime is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: 37
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 25 from /roles is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: [ "openidm-admin", "openidm-authorized" ]
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Executing: INSERT INTO openidm.auditaccess (objectid, activitydate, eventname, transactionid, userid, trackingids, server_ip, server_port, client_ip, client_port, request_protocol, request_operation, request_detail, http_request_secure, http_request_method, http_request_path, http_request_queryparameters, http_request_headers, http_request_cookies, http_response_headers, response_status, response_statuscode, response_elapsedtime, response_elapsedtimeunits, roles) VALUES ( ?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?)
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Created object for id audit/access/18647504-cd46-448f-a945-f3f5dadf46ce-422 with rev 0
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService create
    FINE: Commited created object for id: audit/access/18647504-cd46-448f-a945-f3f5dadf46ce-422
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService query
    FINEST: Full id: managed/user Extracted type: managed/user
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries query
    FINE: Querying {_pageSize=2147483647, _queryFilter=null, _queryExpression=null, _pagedResultsOffset=0, _resource=managed/user, username=openidm-admin, _queryId=credential-query, _sortKeys=[]}
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries resolveQuery
    FINE: Prepared statement: SELECT * FROM openidm.managed_user WHERE userName = ? and accountStatus = 'active'
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService query
    FINEST: Full id: internal/user Extracted type: internal/user
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries query
    FINE: Querying {_pageSize=2147483647, _queryFilter=null, _queryExpression=null, _pagedResultsOffset=0, _resource=internal/user, username=openidm-admin, _queryId=credential-internaluser-query, _sortKeys=[]}
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries resolveQuery
    FINE: Prepared statement: SELECT objectid, pwd, roles FROM openidm.internaluser WHERE objectid = ?
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.Mapping mapToJsonValue
    FINE: Mapped rs [email protected] to { "_id": "openidm-admin", "password": "openidm-admin", "roles": [ { "_ref": "repo/internal/role/openidm-admin" }, { "_ref": "repo/internal/role/openidm-authorized" } ] }
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Create with fullid audit/authentication/18647504-cd46-448f-a945-f3f5dadf46ce-432
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Preparing statement INSERT INTO openidm.auditauthentication (objectid, transactionid, activitydate, userid, eventname, result, principals, context, entries, trackingids) VALUES ( ?,?,?,?,?,?,?,?,?,?) with audit/authentication, 18647504-cd46-448f-a945-f3f5dadf46ce-432, 0
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 7 from /principal is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: [ "openidm-admin" ]
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 8 from /context is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: { "component": "repo/internal/user", "roles": [ "openidm-admin", "openidm-authorized" ], "ipAddress": "10.77.15.76", "id": "openidm-admin" }
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 9 from /entries is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: [ { "moduleId": "JwtSession", "result": "FAILED", "reason": {  }, "info": {  } }, { "moduleId": "STATIC_USER", "result": "FAILED", "reason": {  }, "info": { "org.forgerock.authentication.principal": "openidm-admin" } }, { "moduleId": "MANAGED_USER", "result": "FAILED", "reason": {  }, "info": { "org.forgerock.authentication.principal": "openidm-admin" } }, { "moduleId": "INTERNAL_USER", "result": "SUCCESSFUL", "info": { "org.forgerock.authentication.principal": "openidm-admin" } } ]
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 10 from /trackingIds is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: [ "ea5e7df7-18e2-4bc0-bd58-5d95a1d7648c" ]
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Executing: INSERT INTO openidm.auditauthentication (objectid, transactionid, activitydate, userid, eventname, result, principals, context, entries, trackingids) VALUES ( ?,?,?,?,?,?,?,?,?,?)
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Created object for id audit/authentication/18647504-cd46-448f-a945-f3f5dadf46ce-432 with rev 0
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService create
    FINE: Commited created object for id: audit/authentication/18647504-cd46-448f-a945-f3f5dadf46ce-432
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler read
    FINE: Populating prepared statement SELECT * FROM openidm.managed_user WHERE objectid = ? for managed/user/aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler read
    FINE: Executing: SELECT * FROM openidm.managed_user WHERE objectid = ?
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.Mapping mapToJsonValue
    FINE: Mapped rs [email protected] to { "_id": "aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994", "_rev": "4", "userName": "test2016122301", "password": { "$crypto": { "type": "x-simple-encryption", "value": { "cipher": "AES/CBC/PKCS5Padding", "data": "vEMI8au7CtJG63ADht9cgQ==", "iv": "c0rtN+EDT+1JWblyI6y3GA==", "key": "openidm-sym-default" } } }, "accountStatus": "active", "givenName": "test2016122301", "sn": "test2016122301", "office": "eACPC", "dphkid": "MTFMQU5EU0R0ZXN0MjAxNjEyMjMwMQ==", "rank": "1", "dprankcode": "1", "dpstaffgroup": "1", "dpdeptid": "LANDSD", "firstdayofapp": null, "idminformation": "uid=test2016122301,ou=people,ou=users,o=example,dc=com,dc=hk", "employeeType": "CS", "cn": "test2016122301", "adminFlag": "0", "oldPassword": { "$crypto": { "type": "x-simple-encryption", "value": { "cipher": "AES/CBC/PKCS5Padding", "data": "jbhSndDWXSvtkWsCEJKfgw==", "iv": "+WKAkQvBeLWFbPtVr4YX+Q==", "key": "openidm-sym-default" } } }, "amsUserName": null, "amsPassword": null, "amsDLO": null, "amsDevUserName": null, "amsDevPassword": null, "amsDevDLO": null, "grpsUserName": null, "grpsPassword": null, "grpsdevUserName": null, "grpsdevPassword": null, "shisUserName": null, "shisPassword": null, "visUserName": null, "visPassword": null, "visDevUserName": null, "visDevPassword": null, "pwdChanged": "true", "dateofbirth": null }
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler read
    FINE:  full id: managed/user/aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994, rev: "4", obj { "_id": "aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994", "_rev": "4", "userName": "test2016122301", "password": { "$crypto": { "type": "x-simple-encryption", "value": { "cipher": "AES/CBC/PKCS5Padding", "data": "vEMI8au7CtJG63ADht9cgQ==", "iv": "c0rtN+EDT+1JWblyI6y3GA==", "key": "openidm-sym-default" } } }, "accountStatus": "active", "givenName": "test2016122301", "sn": "test2016122301", "office": "eACPC", "dphkid": "MTFMQU5EU0R0ZXN0MjAxNjEyMjMwMQ==", "rank": "1", "dprankcode": "1", "dpstaffgroup": "1", "dpdeptid": "LANDSD", "firstdayofapp": null, "idminformation": "uid=test2016122301,ou=people,ou=users,o=example,dc=com,dc=hk", "employeeType": "CS", "cn": "test2016122301", "adminFlag": "0", "oldPassword": { "$crypto": { "type": "x-simple-encryption", "value": { "cipher": "AES/CBC/PKCS5Padding", "data": "jbhSndDWXSvtkWsCEJKfgw==", "iv": "+WKAkQvBeLWFbPtVr4YX+Q==", "key": "openidm-sym-default" } } }, "amsUserName": null, "amsPassword": null, "amsDLO": null, "amsDevUserName": null, "amsDevPassword": null, "amsDevDLO": null, "grpsUserName": null, "grpsPassword": null, "grpsdevUserName": null, "grpsdevPassword": null, "shisUserName": null, "shisPassword": null, "visUserName": null, "visPassword": null, "visDevUserName": null, "visDevPassword": null, "pwdChanged": "true", "dateofbirth": null }
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService query
    FINEST: Full id: managed/user Extracted type: managed/user
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries query
    FINE: Querying {_pageSize=2147483647, uid=test2016122301, _queryFilter=null, _queryExpression=null, _pagedResultsOffset=0, _resource=managed/user, _queryId=for-userName, _sortKeys=[]}
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries resolveQuery
    FINE: Prepared statement: SELECT * FROM openidm.managed_user WHERE userName = ?
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.Mapping mapToJsonValue
    FINE: Mapped rs [email protected] to { "_id": "aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994", "_rev": "4", "userName": "test2016122301", "password": { "$crypto": { "type": "x-simple-encryption", "value": { "cipher": "AES/CBC/PKCS5Padding", "data": "vEMI8au7CtJG63ADht9cgQ==", "iv": "c0rtN+EDT+1JWblyI6y3GA==", "key": "openidm-sym-default" } } }, "accountStatus": "active", "givenName": "test2016122301", "sn": "test2016122301", "office": "eACPC", "dphkid": "MTFMQU5EU0R0ZXN0MjAxNjEyMjMwMQ==", "rank": "1", "dprankcode": "1", "dpstaffgroup": "1", "dpdeptid": "LANDSD", "firstdayofapp": null, "idminformation": "uid=test2016122301,ou=people,ou=users,o=example,dc=com,dc=hk", "employeeType": "CS", "cn": "test2016122301", "adminFlag": "0", "oldPassword": { "$crypto": { "type": "x-simple-encryption", "value": { "cipher": "AES/CBC/PKCS5Padding", "data": "jbhSndDWXSvtkWsCEJKfgw==", "iv": "+WKAkQvBeLWFbPtVr4YX+Q==", "key": "openidm-sym-default" } } }, "amsUserName": null, "amsPassword": null, "amsDLO": null, "amsDevUserName": null, "amsDevPassword": null, "amsDevDLO": null, "grpsUserName": null, "grpsPassword": null, "grpsdevUserName": null, "grpsdevPassword": null, "shisUserName": null, "shisPassword": null, "visUserName": null, "visPassword": null, "visDevUserName": null, "visDevPassword": null, "pwdChanged": "true", "dateofbirth": null }
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler update
    FINE: Update with fullid managed/user/aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler readForUpdate
    FINEST: Populating prepared statement SELECT * FROM openidm.managed_user WHERE objectid = ? FOR UPDATE for managed/user/aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler readForUpdate
    FINE: Executing: SELECT * FROM openidm.managed_user WHERE objectid = ? FOR UPDATE
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler readForUpdate
    FINE: Read for update full id: managed/user/aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler update
    FINE: Update existing object managed/user/aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994 rev: 4 
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler update
    FINEST: Populating prepared statement UPDATE openidm.managed_user SET objectid = ?, rev = ?, userName = ?, password = ?, accountStatus = ?, givenName = ?, sn = ?, office = ?, dphkid = ?, rank = ?, dprankcode = ?, dpstaffgroup = ?, dpdeptid = ?, firstdayofapp = ?, idminformation = ?, employeeType = ?, cn = ?, adminFlag = ?, oldPassword = ?, amsUserName = ?, amsPassword = ?, amsDLO = ?, amsDevUserName = ?, amsDevPassword = ?, amsDevDLO = ?, grpsUserName = ?, grpsPassword = ?, grpsdevUserName = ?, grpsdevPassword = ?, shisUserName = ?, shisPassword = ?, visUserName = ?, visPassword = ?, visDevUserName = ?, visDevPassword = ?, pwdChanged = ?, dateofbirth = ? WHERE objectid = ? for managed/user/aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994 aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994 5
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 4 from /password is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: { "$crypto": { "type": "x-simple-encryption", "value": { "cipher": "AES/CBC/PKCS5Padding", "data": "Yu8MDixdFrLixnfsJOjR6w==", "iv": "fjXgyG55gZ+kbZj+8KYCNA==", "key": "openidm-sym-default" } } }
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 19 from /oldPassword is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: { "$crypto": { "type": "x-simple-encryption", "value": { "cipher": "AES/CBC/PKCS5Padding", "data": "fGCZ7xA9JlJZS7v8+kQ/kg==", "iv": "f2hNexmGqYmm7aKsdlE4/g==", "key": "openidm-sym-default" } } }
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler update
    FINE: Update statement: UPDATE openidm.managed_user SET objectid = ?, rev = ?, userName = ?, password = ?, accountStatus = ?, givenName = ?, sn = ?, office = ?, dphkid = ?, rank = ?, dprankcode = ?, dpstaffgroup = ?, dpdeptid = ?, firstdayofapp = ?, idminformation = ?, employeeType = ?, cn = ?, adminFlag = ?, oldPassword = ?, amsUserName = ?, amsPassword = ?, amsDLO = ?, amsDevUserName = ?, amsDevPassword = ?, amsDevDLO = ?, grpsUserName = ?, grpsPassword = ?, grpsdevUserName = ?, grpsdevPassword = ?, shisUserName = ?, shisPassword = ?, visUserName = ?, visPassword = ?, visDevUserName = ?, visDevPassword = ?, pwdChanged = ?, dateofbirth = ? WHERE objectid = ?
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler update
    FINEST: Updated rows: 1 for managed/user/aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService update
    FINE: Commited updated object for id: managed/user/aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler read
    FINE: Populating prepared statement SELECT * FROM openidm.managed_user WHERE objectid = ? for managed/user/aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler read
    FINE: Executing: SELECT * FROM openidm.managed_user WHERE objectid = ?
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.Mapping mapToJsonValue
    FINE: Mapped rs [email protected] to { "_id": "aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994", "_rev": "5", "userName": "test2016122301", "password": { "$crypto": { "type": "x-simple-encryption", "value": { "cipher": "AES/CBC/PKCS5Padding", "data": "Yu8MDixdFrLixnfsJOjR6w==", "iv": "fjXgyG55gZ+kbZj+8KYCNA==", "key": "openidm-sym-default" } } }, "accountStatus": "active", "givenName": "test2016122301", "sn": "test2016122301", "office": "eACPC", "dphkid": "MTFMQU5EU0R0ZXN0MjAxNjEyMjMwMQ==", "rank": "1", "dprankcode": "1", "dpstaffgroup": "1", "dpdeptid": "LANDSD", "firstdayofapp": null, "idminformation": "uid=test2016122301,ou=people,ou=users,o=example,dc=com,dc=hk", "employeeType": "CS", "cn": "test2016122301", "adminFlag": "0", "oldPassword": { "$crypto": { "type": "x-simple-encryption", "value": { "cipher": "AES/CBC/PKCS5Padding", "data": "fGCZ7xA9JlJZS7v8+kQ/kg==", "iv": "f2hNexmGqYmm7aKsdlE4/g==", "key": "openidm-sym-default" } } }, "amsUserName": null, "amsPassword": null, "amsDLO": null, "amsDevUserName": null, "amsDevPassword": null, "amsDevDLO": null, "grpsUserName": null, "grpsPassword": null, "grpsdevUserName": null, "grpsdevPassword": null, "shisUserName": null, "shisPassword": null, "visUserName": null, "visPassword": null, "visDevUserName": null, "visDevPassword": null, "pwdChanged": "true", "dateofbirth": null }
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler read
    FINE:  full id: managed/user/aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994, rev: "5", obj { "_id": "aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994", "_rev": "5", "userName": "test2016122301", "password": { "$crypto": { "type": "x-simple-encryption", "value": { "cipher": "AES/CBC/PKCS5Padding", "data": "Yu8MDixdFrLixnfsJOjR6w==", "iv": "fjXgyG55gZ+kbZj+8KYCNA==", "key": "openidm-sym-default" } } }, "accountStatus": "active", "givenName": "test2016122301", "sn": "test2016122301", "office": "eACPC", "dphkid": "MTFMQU5EU0R0ZXN0MjAxNjEyMjMwMQ==", "rank": "1", "dprankcode": "1", "dpstaffgroup": "1", "dpdeptid": "LANDSD", "firstdayofapp": null, "idminformation": "uid=test2016122301,ou=people,ou=users,o=example,dc=com,dc=hk", "employeeType": "CS", "cn": "test2016122301", "adminFlag": "0", "oldPassword": { "$crypto": { "type": "x-simple-encryption", "value": { "cipher": "AES/CBC/PKCS5Padding", "data": "fGCZ7xA9JlJZS7v8+kQ/kg==", "iv": "f2hNexmGqYmm7aKsdlE4/g==", "key": "openidm-sym-default" } } }, "amsUserName": null, "amsPassword": null, "amsDLO": null, "amsDevUserName": null, "amsDevPassword": null, "amsDevDLO": null, "grpsUserName": null, "grpsPassword": null, "grpsdevUserName": null, "grpsdevPassword": null, "shisUserName": null, "shisPassword": null, "visUserName": null, "visPassword": null, "visDevUserName": null, "visDevPassword": null, "pwdChanged": "true", "dateofbirth": null }
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService query
    FINEST: Full id: link Extracted type: link
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries query
    FINE: Querying {_pageSize=2147483647, _queryFilter=(/linkType eq "managedUser_ldapAccount" and /linkQualifier eq "default" and /firstId eq "aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994"), _queryExpression=null, _pagedResultsOffset=0, _resource=link, _queryId=null, _sortKeys=[]}
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries parseQueryFilter
    FINE: Tokenized statement: SELECT obj.* FROM ${_dbSchema}.${_mainTable} obj WHERE (linktype = ${v55} AND linkqualifier = ${v56} AND firstid = ${v57}) LIMIT 2147483647 OFFSET 0 with replacementTokens: {v55=managedUser_ldapAccount, v56=default, v57=aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994}
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries resolveQuery
    FINE: Prepared statement: SELECT obj.* FROM openidm.links obj WHERE (linktype = ? AND linkqualifier = ? AND firstid = ?) LIMIT 2147483647 OFFSET 0
    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.Mapping mapToJsonValue
    FINE: Mapped rs [email protected] to { "_id": "ea4292b8-d311-4a4c-b90f-882440235da9", "_rev": "0", "linkType": "managedUser_ldapAccount", "firstId": "aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994", "secondId": "uid=test2016122301,ou=people,ou=users,o=example,dc=com,dc=hk", "linkQualifier": "default" }
    
    Dec 23, 2016 1:47:08 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Create with fullid audit/activity/18647504-cd46-448f-a945-f3f5dadf46ce-433
    Dec 23, 2016 1:47:08 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Preparing statement INSERT INTO openidm.auditactivity (objectid, activitydate, eventname, transactionid, userid, trackingids, runas, activityobjectid, operation, subjectbefore, subjectafter, changedfields, subjectrev, passwordchanged, message, status) VALUES ( ?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?) with audit/activity, 18647504-cd46-448f-a945-f3f5dadf46ce-433, 0
    Dec 23, 2016 1:47:08 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 10 from /before is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: { "employeeType": "CS", "telephoneNumber": null, "dn": "uid=test2016122301,ou=people,ou=users,o=example,dc=com,dc=hk", "cn": "test2016122301", "givenName": "test2016122301", "dphkid": "11LANDSDtest2016122301", "disabled": null, "uid": "test2016122301", "DPDEPTID": "LANDSD", "description": "eACPC", "DPRANKCODE": "1", "ldapGroups": [  ], "inetUserStatus": "active", "dpstaffgroup": "1", "mail": null, "sn": "test2016122301", "_id": "uid=test2016122301,ou=people,ou=users,o=example,dc=com,dc=hk" }
    Dec 23, 2016 1:47:08 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 11 from /after is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: { "employeeType": "CS", "telephoneNumber": null, "dn": "uid=test2016122301,ou=people,ou=users,o=example,dc=com,dc=hk", "cn": "test2016122301", "givenName": "test2016122301", "dphkid": "11LANDSDtest2016122301", "disabled": null, "uid": "test2016122301", "DPDEPTID": "LANDSD", "description": "eACPC", "DPRANKCODE": "1", "ldapGroups": [  ], "inetUserStatus": "active", "dpstaffgroup": "1", "mail": null, "sn": "test2016122301", "_id": "uid=test2016122301,ou=people,ou=users,o=example,dc=com,dc=hk" }
    Dec 23, 2016 1:47:08 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 12 from /changedFields is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: [  ]
    Dec 23, 2016 1:47:08 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 14 from /passwordChanged is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: false
    Dec 23, 2016 1:47:08 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Executing: INSERT INTO openidm.auditactivity (objectid, activitydate, eventname, transactionid, userid, trackingids, runas, activityobjectid, operation, subjectbefore, subjectafter, changedfields, subjectrev, passwordchanged, message, status) VALUES ( ?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?)
    Dec 23, 2016 1:47:08 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Created object for id audit/activity/18647504-cd46-448f-a945-f3f5dadf46ce-433 with rev 0
    Dec 23, 2016 1:47:08 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService create
    FINE: Commited created object for id: audit/activity/18647504-cd46-448f-a945-f3f5dadf46ce-433
    Dec 23, 2016 1:47:08 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Create with fullid audit/sync/18647504-cd46-448f-a945-f3f5dadf46ce-434
    Dec 23, 2016 1:47:08 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Preparing statement INSERT INTO openidm.auditsync (objectid, transactionid, activitydate, eventname, userid, trackingids, activity, exceptiondetail, linkqualifier, mapping, message, messagedetail, situation, sourceobjectid, status, targetobjectid) VALUES ( ?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?) with audit/sync, 18647504-cd46-448f-a945-f3f5dadf46ce-434, 0
    Dec 23, 2016 1:47:08 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Executing: INSERT INTO openidm.auditsync (objectid, transactionid, activitydate, eventname, userid, trackingids, activity, exceptiondetail, linkqualifier, mapping, message, messagedetail, situation, sourceobjectid, status, targetobjectid) VALUES ( ?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?)
    Dec 23, 2016 1:47:08 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Created object for id audit/sync/18647504-cd46-448f-a945-f3f5dadf46ce-434 with rev 0
    Dec 23, 2016 1:47:08 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService create
    FINE: Commited created object for id: audit/sync/18647504-cd46-448f-a945-f3f5dadf46ce-434
    Dec 23, 2016 1:47:08 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService query
    FINEST: Full id: link Extracted type: link
    Dec 23, 2016 1:47:08 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries query
    FINE: Querying {_pageSize=2147483647, _queryFilter=(/linkType eq "managedUser_sourceSmoadprodAccount" and /linkQualifier eq "default" and /firstId eq "aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994"), _queryExpression=null, _pagedResultsOffset=0, _resource=link, _queryId=null, _sortKeys=[]}
    Dec 23, 2016 1:47:08 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries parseQueryFilter
    FINE: Tokenized statement: SELECT obj.* FROM ${_dbSchema}.${_mainTable} obj WHERE (linktype = ${v58} AND linkqualifier = ${v59} AND firstid = ${v60}) LIMIT 2147483647 OFFSET 0 with replacementTokens: {v58=managedUser_sourceSmoadprodAccount, v59=default, v60=aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994}
    Dec 23, 2016 1:47:08 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries resolveQuery
    FINE: Prepared statement: SELECT obj.* FROM openidm.links obj WHERE (linktype = ? AND linkqualifier = ? AND firstid = ?) LIMIT 2147483647 OFFSET 0
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Create with fullid audit/sync/18647504-cd46-448f-a945-f3f5dadf46ce-435
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Preparing statement INSERT INTO openidm.auditsync (objectid, transactionid, activitydate, eventname, userid, trackingids, activity, exceptiondetail, linkqualifier, mapping, message, messagedetail, situation, sourceobjectid, status, targetobjectid) VALUES ( ?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?) with audit/sync, 18647504-cd46-448f-a945-f3f5dadf46ce-435, 0
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Executing: INSERT INTO openidm.auditsync (objectid, transactionid, activitydate, eventname, userid, trackingids, activity, exceptiondetail, linkqualifier, mapping, message, messagedetail, situation, sourceobjectid, status, targetobjectid) VALUES ( ?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?)
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Created object for id audit/sync/18647504-cd46-448f-a945-f3f5dadf46ce-435 with rev 0
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService create
    FINE: Commited created object for id: audit/sync/18647504-cd46-448f-a945-f3f5dadf46ce-435
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService query
    FINEST: Full id: link Extracted type: link
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries query
    FINE: Querying {_pageSize=2147483647, _queryFilter=(/linkType eq "managedUser_sourceLaoadprodAccount" and /linkQualifier eq "default" and /firstId eq "aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994"), _queryExpression=null, _pagedResultsOffset=0, _resource=link, _queryId=null, _sortKeys=[]}
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries parseQueryFilter
    FINE: Tokenized statement: SELECT obj.* FROM ${_dbSchema}.${_mainTable} obj WHERE (linktype = ${v61} AND linkqualifier = ${v62} AND firstid = ${v63}) LIMIT 2147483647 OFFSET 0 with replacementTokens: {v61=managedUser_sourceLaoadprodAccount, v62=default, v63=aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994}
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries resolveQuery
    FINE: Prepared statement: SELECT obj.* FROM openidm.links obj WHERE (linktype = ? AND linkqualifier = ? AND firstid = ?) LIMIT 2147483647 OFFSET 0
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Create with fullid audit/sync/18647504-cd46-448f-a945-f3f5dadf46ce-436
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Preparing statement INSERT INTO openidm.auditsync (objectid, transactionid, activitydate, eventname, userid, trackingids, activity, exceptiondetail, linkqualifier, mapping, message, messagedetail, situation, sourceobjectid, status, targetobjectid) VALUES ( ?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?) with audit/sync, 18647504-cd46-448f-a945-f3f5dadf46ce-436, 0
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Executing: INSERT INTO openidm.auditsync (objectid, transactionid, activitydate, eventname, userid, trackingids, activity, exceptiondetail, linkqualifier, mapping, message, messagedetail, situation, sourceobjectid, status, targetobjectid) VALUES ( ?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?)
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Created object for id audit/sync/18647504-cd46-448f-a945-f3f5dadf46ce-436 with rev 0
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService create
    FINE: Commited created object for id: audit/sync/18647504-cd46-448f-a945-f3f5dadf46ce-436
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService query
    FINEST: Full id: link Extracted type: link
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries query
    FINE: Querying {_pageSize=2147483647, _queryFilter=(/linkType eq "managedUser_sourceLacoadprodAccount" and /linkQualifier eq "default" and /firstId eq "aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994"), _queryExpression=null, _pagedResultsOffset=0, _resource=link, _queryId=null, _sortKeys=[]}
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries parseQueryFilter
    FINE: Tokenized statement: SELECT obj.* FROM ${_dbSchema}.${_mainTable} obj WHERE (linktype = ${v64} AND linkqualifier = ${v65} AND firstid = ${v66}) LIMIT 2147483647 OFFSET 0 with replacementTokens: {v64=managedUser_sourceLacoadprodAccount, v65=default, v66=aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994}
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries resolveQuery
    FINE: Prepared statement: SELECT obj.* FROM openidm.links obj WHERE (linktype = ? AND linkqualifier = ? AND firstid = ?) LIMIT 2147483647 OFFSET 0
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Create with fullid audit/sync/18647504-cd46-448f-a945-f3f5dadf46ce-437
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Preparing statement INSERT INTO openidm.auditsync (objectid, transactionid, activitydate, eventname, userid, trackingids, activity, exceptiondetail, linkqualifier, mapping, message, messagedetail, situation, sourceobjectid, status, targetobjectid) VALUES ( ?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?) with audit/sync, 18647504-cd46-448f-a945-f3f5dadf46ce-437, 0
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Executing: INSERT INTO openidm.auditsync (objectid, transactionid, activitydate, eventname, userid, trackingids, activity, exceptiondetail, linkqualifier, mapping, message, messagedetail, situation, sourceobjectid, status, targetobjectid) VALUES ( ?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?)
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Created object for id audit/sync/18647504-cd46-448f-a945-f3f5dadf46ce-437 with rev 0
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService create
    FINE: Commited created object for id: audit/sync/18647504-cd46-448f-a945-f3f5dadf46ce-437
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService query
    FINEST: Full id: link Extracted type: link
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries query
    FINE: Querying {_pageSize=2147483647, _queryFilter=(/linkType eq "managedUser_sourceGenericldap__ACCOUNT__" and /linkQualifier eq "default" and /firstId eq "aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994"), _queryExpression=null, _pagedResultsOffset=0, _resource=link, _queryId=null, _sortKeys=[]}
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries parseQueryFilter
    FINE: Tokenized statement: SELECT obj.* FROM ${_dbSchema}.${_mainTable} obj WHERE (linktype = ${v67} AND linkqualifier = ${v68} AND firstid = ${v69}) LIMIT 2147483647 OFFSET 0 with replacementTokens: {v67=managedUser_sourceGenericldap__ACCOUNT__, v68=default, v69=aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994}
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries resolveQuery
    FINE: Prepared statement: SELECT obj.* FROM openidm.links obj WHERE (linktype = ? AND linkqualifier = ? AND firstid = ?) LIMIT 2147483647 OFFSET 0
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService query
    FINEST: Full id: link Extracted type: link
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries query
    FINE: Querying {_pageSize=2147483647, _queryFilter=(/linkType eq "managedUser_sourceGenericldap__ACCOUNT__" and /linkQualifier eq "default" and /secondId eq "df40746d-7eb1-4ae6-9a73-f0626bd5e01d"), _queryExpression=null, _pagedResultsOffset=0, _resource=link, _queryId=null, _sortKeys=[]}
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries parseQueryFilter
    FINE: Tokenized statement: SELECT obj.* FROM ${_dbSchema}.${_mainTable} obj WHERE (linktype = ${v70} AND linkqualifier = ${v71} AND secondid = ${v72}) LIMIT 2147483647 OFFSET 0 with replacementTokens: {v70=managedUser_sourceGenericldap__ACCOUNT__, v71=default, v72=df40746d-7eb1-4ae6-9a73-f0626bd5e01d}
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries resolveQuery
    FINE: Prepared statement: SELECT obj.* FROM openidm.links obj WHERE (linktype = ? AND linkqualifier = ? AND secondid = ?) LIMIT 2147483647 OFFSET 0
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Create with fullid audit/sync/18647504-cd46-448f-a945-f3f5dadf46ce-438
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Preparing statement INSERT INTO openidm.auditsync (objectid, transactionid, activitydate, eventname, userid, trackingids, activity, exceptiondetail, linkqualifier, mapping, message, messagedetail, situation, sourceobjectid, status, targetobjectid) VALUES ( ?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?) with audit/sync, 18647504-cd46-448f-a945-f3f5dadf46ce-438, 0
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Executing: INSERT INTO openidm.auditsync (objectid, transactionid, activitydate, eventname, userid, trackingids, activity, exceptiondetail, linkqualifier, mapping, message, messagedetail, situation, sourceobjectid, status, targetobjectid) VALUES ( ?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?)
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Created object for id audit/sync/18647504-cd46-448f-a945-f3f5dadf46ce-438 with rev 0
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService create
    FINE: Commited created object for id: audit/sync/18647504-cd46-448f-a945-f3f5dadf46ce-438
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Create with fullid audit/activity/18647504-cd46-448f-a945-f3f5dadf46ce-439
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Preparing statement INSERT INTO openidm.auditactivity (objectid, activitydate, eventname, transactionid, userid, trackingids, runas, activityobjectid, operation, subjectbefore, subjectafter, changedfields, subjectrev, passwordchanged, message, status) VALUES ( ?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?) with audit/activity, 18647504-cd46-448f-a945-f3f5dadf46ce-439, 0
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 10 from /before is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: { "_id": "aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994", "_rev": "4", "userName": "test2016122301", "password": { "$crypto": { "type": "x-simple-encryption", "value": { "cipher": "AES/CBC/PKCS5Padding", "data": "vEMI8au7CtJG63ADht9cgQ==", "iv": "c0rtN+EDT+1JWblyI6y3GA==", "key": "openidm-sym-default" } } }, "accountStatus": "active", "givenName": "test2016122301", "sn": "test2016122301", "office": "eACPC", "dphkid": "MTFMQU5EU0R0ZXN0MjAxNjEyMjMwMQ==", "rank": "1", "dprankcode": "1", "dpstaffgroup": "1", "dpdeptid": "LANDSD", "firstdayofapp": null, "idminformation": "uid=test2016122301,ou=people,ou=users,o=example,dc=com,dc=hk", "employeeType": "CS", "cn": "test2016122301", "adminFlag": "0", "oldPassword": { "$crypto": { "type": "x-simple-encryption", "value": { "cipher": "AES/CBC/PKCS5Padding", "data": "jbhSndDWXSvtkWsCEJKfgw==", "iv": "+WKAkQvBeLWFbPtVr4YX+Q==", "key": "openidm-sym-default" } } }, "amsUserName": null, "amsPassword": null, "amsDLO": null, "amsDevUserName": null, "amsDevPassword": null, "amsDevDLO": null, "grpsUserName": null, "grpsPassword": null, "grpsdevUserName": null, "grpsdevPassword": null, "shisUserName": null, "shisPassword": null, "visUserName": null, "visPassword": null, "visDevUserName": null, "visDevPassword": null, "pwdChanged": "true", "dateofbirth": null }
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 11 from /after is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: { "_id": "aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994", "_rev": "5", "userName": "test2016122301", "password": { "$crypto": { "type": "x-simple-encryption", "value": { "cipher": "AES/CBC/PKCS5Padding", "data": "Yu8MDixdFrLixnfsJOjR6w==", "iv": "fjXgyG55gZ+kbZj+8KYCNA==", "key": "openidm-sym-default" } } }, "accountStatus": "active", "givenName": "test2016122301", "sn": "test2016122301", "office": "eACPC", "dphkid": "MTFMQU5EU0R0ZXN0MjAxNjEyMjMwMQ==", "rank": "1", "dprankcode": "1", "dpstaffgroup": "1", "dpdeptid": "LANDSD", "firstdayofapp": null, "idminformation": "uid=test2016122301,ou=people,ou=users,o=example,dc=com,dc=hk", "employeeType": "CS", "cn": "test2016122301", "adminFlag": "0", "oldPassword": { "$crypto": { "type": "x-simple-encryption", "value": { "cipher": "AES/CBC/PKCS5Padding", "data": "fGCZ7xA9JlJZS7v8+kQ/kg==", "iv": "f2hNexmGqYmm7aKsdlE4/g==", "key": "openidm-sym-default" } } }, "amsUserName": null, "amsPassword": null, "amsDLO": null, "amsDevUserName": null, "amsDevPassword": null, "amsDevDLO": null, "grpsUserName": null, "grpsPassword": null, "grpsdevUserName": null, "grpsdevPassword": null, "shisUserName": null, "shisPassword": null, "visUserName": null, "visPassword": null, "visDevUserName": null, "visDevPassword": null, "pwdChanged": "true", "dateofbirth": null }
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 12 from /changedFields is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: [  ]
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 14 from /passwordChanged is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: true
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Executing: INSERT INTO openidm.auditactivity (objectid, activitydate, eventname, transactionid, userid, trackingids, runas, activityobjectid, operation, subjectbefore, subjectafter, changedfields, subjectrev, passwordchanged, message, status) VALUES ( ?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?)
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Created object for id audit/activity/18647504-cd46-448f-a945-f3f5dadf46ce-439 with rev 0
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService create
    FINE: Commited created object for id: audit/activity/18647504-cd46-448f-a945-f3f5dadf46ce-439
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Create with fullid audit/access/18647504-cd46-448f-a945-f3f5dadf46ce-440
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Preparing statement INSERT INTO openidm.auditaccess (objectid, activitydate, eventname, transactionid, userid, trackingids, server_ip, server_port, client_ip, client_port, request_protocol, request_operation, request_detail, http_request_secure, http_request_method, http_request_path, http_request_queryparameters, http_request_headers, http_request_cookies, http_response_headers, response_status, response_statuscode, response_elapsedtime, response_elapsedtimeunits, roles) VALUES ( ?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?) with audit/access, 18647504-cd46-448f-a945-f3f5dadf46ce-440, 0
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 8 from /server/port is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: 18443
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 10 from /client/port is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: 40683
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 14 from /http/request/secure is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: true
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 17 from /http/request/queryParameters is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: {  }
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 18 from /http/request/headers is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: { "Accept": [ "application/json, text/javascript, */*; q=0.01" ], "Connection": [ "keep-alive" ], "Content-Length": [ "195" ], "Content-Type": [ "application/json" ], "Host": [ "10.77.15.90:18443" ], "If-Match": [ "*" ], "User-Agent": [ "Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 10.0; WOW64; Trident/7.0; .NET4.0C; .NET4.0E; .NET CLR 2.0.50727; .NET CLR 3.0.30729; .NET CLR 3.5.30729)" ], "X-OpenIDM-Username": [ "openidm-admin" ] }
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 19 from /http/request/cookies is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: {  }
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 23 from /response/elapsedTime is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: 10265
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 25 from /roles is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: [ "openidm-admin", "openidm-authorized" ]
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Executing: INSERT INTO openidm.auditaccess (objectid, activitydate, eventname, transactionid, userid, trackingids, server_ip, server_port, client_ip, client_port, request_protocol, request_operation, request_detail, http_request_secure, http_request_method, http_request_path, http_request_queryparameters, http_request_headers, http_request_cookies, http_response_headers, response_status, response_statuscode, response_elapsedtime, response_elapsedtimeunits, roles) VALUES ( ?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?)
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Created object for id audit/access/18647504-cd46-448f-a945-f3f5dadf46ce-440 with rev 0
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService create
    FINE: Commited created object for id: audit/access/18647504-cd46-448f-a945-f3f5dadf46ce-440
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.GenericTableHandler read
    FINEST: Populating prepared statement SELECT obj.rev, obj.fullobject FROM openidm.objecttypes objtype, openidm.schedulerobjects obj WHERE obj.objecttypes_id = objtype.id AND objtype.objecttype = ? AND obj.objectid  = ? for scheduler/waitingTriggers
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.GenericTableHandler read
    FINE: Executing: SELECT obj.rev, obj.fullobject FROM openidm.objecttypes objtype, openidm.schedulerobjects obj WHERE obj.objecttypes_id = objtype.id AND objtype.objecttype = ? AND obj.objectid  = ?
    Dec 23, 2016 1:47:09 PM org.forgerock.openidm.repo.jdbc.impl.GenericTableHandler read
    FINE:  full id: scheduler/waitingTriggers, rev: 0, obj {names=[], _id=waitingTriggers, _rev=0}

    and short period:

    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService query
    FINEST: Full id: managed/user Extracted type: managed/user
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries query
    FINE: Querying {_pageSize=2147483647, _queryFilter=null, _queryExpression=null, _pagedResultsOffset=0, _resource=managed/user, username=openidm-admin, _queryId=credential-query, _sortKeys=[]}
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries resolveQuery
    FINE: Prepared statement: SELECT * FROM openidm.managed_user WHERE userName = ? and accountStatus = 'active'
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService query
    FINEST: Full id: internal/user Extracted type: internal/user
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries query
    FINE: Querying {_pageSize=2147483647, _queryFilter=null, _queryExpression=null, _pagedResultsOffset=0, _resource=internal/user, username=openidm-admin, _queryId=credential-internaluser-query, _sortKeys=[]}
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries resolveQuery
    FINE: Prepared statement: SELECT objectid, pwd, roles FROM openidm.internaluser WHERE objectid = ?
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.Mapping mapToJsonValue
    FINE: Mapped rs [email protected] to { "_id": "openidm-admin", "password": "openidm-admin", "roles": [ { "_ref": "repo/internal/role/openidm-admin" }, { "_ref": "repo/internal/role/openidm-authorized" } ] }
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Create with fullid audit/authentication/18647504-cd46-448f-a945-f3f5dadf46ce-469
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Preparing statement INSERT INTO openidm.auditauthentication (objectid, transactionid, activitydate, userid, eventname, result, principals, context, entries, trackingids) VALUES ( ?,?,?,?,?,?,?,?,?,?) with audit/authentication, 18647504-cd46-448f-a945-f3f5dadf46ce-469, 0
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 7 from /principal is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: [ "openidm-admin" ]
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 8 from /context is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: { "component": "repo/internal/user", "roles": [ "openidm-admin", "openidm-authorized" ], "ipAddress": "10.77.15.76", "id": "openidm-admin" }
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 9 from /entries is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: [ { "moduleId": "JwtSession", "result": "FAILED", "reason": {  }, "info": {  } }, { "moduleId": "STATIC_USER", "result": "FAILED", "reason": {  }, "info": { "org.forgerock.authentication.principal": "openidm-admin" } }, { "moduleId": "MANAGED_USER", "result": "FAILED", "reason": {  }, "info": { "org.forgerock.authentication.principal": "openidm-admin" } }, { "moduleId": "INTERNAL_USER", "result": "SUCCESSFUL", "info": { "org.forgerock.authentication.principal": "openidm-admin" } } ]
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 10 from /trackingIds is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: [ "28fb85d3-aaf8-44c5-b1b2-6a0b23ad61c9" ]
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Executing: INSERT INTO openidm.auditauthentication (objectid, transactionid, activitydate, userid, eventname, result, principals, context, entries, trackingids) VALUES ( ?,?,?,?,?,?,?,?,?,?)
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Created object for id audit/authentication/18647504-cd46-448f-a945-f3f5dadf46ce-469 with rev 0
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService create
    FINE: Commited created object for id: audit/authentication/18647504-cd46-448f-a945-f3f5dadf46ce-469
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService query
    FINEST: Full id: managed/user Extracted type: managed/user
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries query
    FINE: Querying {_pageSize=2147483647, uid=test2016122301, _queryFilter=null, _queryExpression=null, _pagedResultsOffset=0, _resource=managed/user, _queryId=query-userName-no-case-sensitive, _sortKeys=[]}
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries resolveQuery
    FINE: Prepared statement: SELECT *, convert(from_base64(dphkid),CHAR(225)) hkid FROM openidm.managed_user WHERE lower(userName) = lower(?)
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.Mapping mapToJsonValue
    FINE: Mapped rs [email protected] to { "_id": "aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994", "_rev": "5", "userName": "test2016122301", "password": { "$crypto": { "type": "x-simple-encryption", "value": { "cipher": "AES/CBC/PKCS5Padding", "data": "Yu8MDixdFrLixnfsJOjR6w==", "iv": "fjXgyG55gZ+kbZj+8KYCNA==", "key": "openidm-sym-default" } } }, "accountStatus": "active", "givenName": "test2016122301", "sn": "test2016122301", "office": "eACPC", "dphkid": "MTFMQU5EU0R0ZXN0MjAxNjEyMjMwMQ==", "rank": "1", "dprankcode": "1", "dpstaffgroup": "1", "dpdeptid": "LANDSD", "firstdayofapp": null, "idminformation": "uid=test2016122301,ou=people,ou=users,o=example,dc=com,dc=hk", "employeeType": "CS", "cn": "test2016122301", "adminFlag": "0", "oldPassword": { "$crypto": { "type": "x-simple-encryption", "value": { "cipher": "AES/CBC/PKCS5Padding", "data": "fGCZ7xA9JlJZS7v8+kQ/kg==", "iv": "f2hNexmGqYmm7aKsdlE4/g==", "key": "openidm-sym-default" } } }, "amsUserName": null, "amsPassword": null, "amsDLO": null, "amsDevUserName": null, "amsDevPassword": null, "amsDevDLO": null, "grpsUserName": null, "grpsPassword": null, "grpsdevUserName": null, "grpsdevPassword": null, "shisUserName": null, "shisPassword": null, "visUserName": null, "visPassword": null, "visDevUserName": null, "visDevPassword": null, "pwdChanged": "true", "dateofbirth": null }
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Create with fullid audit/access/18647504-cd46-448f-a945-f3f5dadf46ce-470
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Preparing statement INSERT INTO openidm.auditaccess (objectid, activitydate, eventname, transactionid, userid, trackingids, server_ip, server_port, client_ip, client_port, request_protocol, request_operation, request_detail, http_request_secure, http_request_method, http_request_path, http_request_queryparameters, http_request_headers, http_request_cookies, http_response_headers, response_status, response_statuscode, response_elapsedtime, response_elapsedtimeunits, roles) VALUES ( ?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?) with audit/access, 18647504-cd46-448f-a945-f3f5dadf46ce-470, 0
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 8 from /server/port is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: 18443
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 10 from /client/port is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: 40689
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 13 from /request/detail is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: { "action": "query" }
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 14 from /http/request/secure is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: true
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 17 from /http/request/queryParameters is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: { "_action": [ "query" ], "userName": [ "test2016122301" ] }
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 18 from /http/request/headers is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: { "Accept": [ "application/json, text/javascript, */*; q=0.01" ], "Charset": [ "UTF-8" ], "Connection": [ "keep-alive" ], "Content-Length": [ "0" ], "Content-Type": [ "application/json" ], "Host": [ "10.77.15.90:18443" ], "User-Agent": [ "Apache-HttpClient/4.5.2 (Java/1.7.0)" ], "X-OpenIDM-Username": [ "openidm-admin" ] }
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 19 from /http/request/cookies is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: {  }
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 23 from /response/elapsedTime is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: 37
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 25 from /roles is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: [ "openidm-admin", "openidm-authorized" ]
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Executing: INSERT INTO openidm.auditaccess (objectid, activitydate, eventname, transactionid, userid, trackingids, server_ip, server_port, client_ip, client_port, request_protocol, request_operation, request_detail, http_request_secure, http_request_method, http_request_path, http_request_queryparameters, http_request_headers, http_request_cookies, http_response_headers, response_status, response_statuscode, response_elapsedtime, response_elapsedtimeunits, roles) VALUES ( ?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?)
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Created object for id audit/access/18647504-cd46-448f-a945-f3f5dadf46ce-470 with rev 0
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService create
    FINE: Commited created object for id: audit/access/18647504-cd46-448f-a945-f3f5dadf46ce-470
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService query
    FINEST: Full id: managed/user Extracted type: managed/user
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries query
    FINE: Querying {_pageSize=2147483647, _queryFilter=null, _queryExpression=null, _pagedResultsOffset=0, _resource=managed/user, username=openidm-admin, _queryId=credential-query, _sortKeys=[]}
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries resolveQuery
    FINE: Prepared statement: SELECT * FROM openidm.managed_user WHERE userName = ? and accountStatus = 'active'
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService query
    FINEST: Full id: internal/user Extracted type: internal/user
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries query
    FINE: Querying {_pageSize=2147483647, _queryFilter=null, _queryExpression=null, _pagedResultsOffset=0, _resource=internal/user, username=openidm-admin, _queryId=credential-internaluser-query, _sortKeys=[]}
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries resolveQuery
    FINE: Prepared statement: SELECT objectid, pwd, roles FROM openidm.internaluser WHERE objectid = ?
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.Mapping mapToJsonValue
    FINE: Mapped rs [email protected] to { "_id": "openidm-admin", "password": "openidm-admin", "roles": [ { "_ref": "repo/internal/role/openidm-admin" }, { "_ref": "repo/internal/role/openidm-authorized" } ] }
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Create with fullid audit/authentication/18647504-cd46-448f-a945-f3f5dadf46ce-480
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Preparing statement INSERT INTO openidm.auditauthentication (objectid, transactionid, activitydate, userid, eventname, result, principals, context, entries, trackingids) VALUES ( ?,?,?,?,?,?,?,?,?,?) with audit/authentication, 18647504-cd46-448f-a945-f3f5dadf46ce-480, 0
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 7 from /principal is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: [ "openidm-admin" ]
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 8 from /context is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: { "component": "repo/internal/user", "roles": [ "openidm-admin", "openidm-authorized" ], "ipAddress": "10.77.15.76", "id": "openidm-admin" }
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 9 from /entries is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: [ { "moduleId": "JwtSession", "result": "FAILED", "reason": {  }, "info": {  } }, { "moduleId": "STATIC_USER", "result": "FAILED", "reason": {  }, "info": { "org.forgerock.authentication.principal": "openidm-admin" } }, { "moduleId": "MANAGED_USER", "result": "FAILED", "reason": {  }, "info": { "org.forgerock.authentication.principal": "openidm-admin" } }, { "moduleId": "INTERNAL_USER", "result": "SUCCESSFUL", "info": { "org.forgerock.authentication.principal": "openidm-admin" } } ]
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 10 from /trackingIds is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: [ "9f11860e-1757-435b-a9da-7918e03e2d5e" ]
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Executing: INSERT INTO openidm.auditauthentication (objectid, transactionid, activitydate, userid, eventname, result, principals, context, entries, trackingids) VALUES ( ?,?,?,?,?,?,?,?,?,?)
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Created object for id audit/authentication/18647504-cd46-448f-a945-f3f5dadf46ce-480 with rev 0
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService create
    FINE: Commited created object for id: audit/authentication/18647504-cd46-448f-a945-f3f5dadf46ce-480
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler read
    FINE: Populating prepared statement SELECT * FROM openidm.managed_user WHERE objectid = ? for managed/user/aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler read
    FINE: Executing: SELECT * FROM openidm.managed_user WHERE objectid = ?
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.Mapping mapToJsonValue
    FINE: Mapped rs [email protected] to { "_id": "aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994", "_rev": "5", "userName": "test2016122301", "password": { "$crypto": { "type": "x-simple-encryption", "value": { "cipher": "AES/CBC/PKCS5Padding", "data": "Yu8MDixdFrLixnfsJOjR6w==", "iv": "fjXgyG55gZ+kbZj+8KYCNA==", "key": "openidm-sym-default" } } }, "accountStatus": "active", "givenName": "test2016122301", "sn": "test2016122301", "office": "eACPC", "dphkid": "MTFMQU5EU0R0ZXN0MjAxNjEyMjMwMQ==", "rank": "1", "dprankcode": "1", "dpstaffgroup": "1", "dpdeptid": "LANDSD", "firstdayofapp": null, "idminformation": "uid=test2016122301,ou=people,ou=users,o=example,dc=com,dc=hk", "employeeType": "CS", "cn": "test2016122301", "adminFlag": "0", "oldPassword": { "$crypto": { "type": "x-simple-encryption", "value": { "cipher": "AES/CBC/PKCS5Padding", "data": "fGCZ7xA9JlJZS7v8+kQ/kg==", "iv": "f2hNexmGqYmm7aKsdlE4/g==", "key": "openidm-sym-default" } } }, "amsUserName": null, "amsPassword": null, "amsDLO": null, "amsDevUserName": null, "amsDevPassword": null, "amsDevDLO": null, "grpsUserName": null, "grpsPassword": null, "grpsdevUserName": null, "grpsdevPassword": null, "shisUserName": null, "shisPassword": null, "visUserName": null, "visPassword": null, "visDevUserName": null, "visDevPassword": null, "pwdChanged": "true", "dateofbirth": null }
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler read
    FINE:  full id: managed/user/aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994, rev: "5", obj { "_id": "aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994", "_rev": "5", "userName": "test2016122301", "password": { "$crypto": { "type": "x-simple-encryption", "value": { "cipher": "AES/CBC/PKCS5Padding", "data": "Yu8MDixdFrLixnfsJOjR6w==", "iv": "fjXgyG55gZ+kbZj+8KYCNA==", "key": "openidm-sym-default" } } }, "accountStatus": "active", "givenName": "test2016122301", "sn": "test2016122301", "office": "eACPC", "dphkid": "MTFMQU5EU0R0ZXN0MjAxNjEyMjMwMQ==", "rank": "1", "dprankcode": "1", "dpstaffgroup": "1", "dpdeptid": "LANDSD", "firstdayofapp": null, "idminformation": "uid=test2016122301,ou=people,ou=users,o=example,dc=com,dc=hk", "employeeType": "CS", "cn": "test2016122301", "adminFlag": "0", "oldPassword": { "$crypto": { "type": "x-simple-encryption", "value": { "cipher": "AES/CBC/PKCS5Padding", "data": "fGCZ7xA9JlJZS7v8+kQ/kg==", "iv": "f2hNexmGqYmm7aKsdlE4/g==", "key": "openidm-sym-default" } } }, "amsUserName": null, "amsPassword": null, "amsDLO": null, "amsDevUserName": null, "amsDevPassword": null, "amsDevDLO": null, "grpsUserName": null, "grpsPassword": null, "grpsdevUserName": null, "grpsdevPassword": null, "shisUserName": null, "shisPassword": null, "visUserName": null, "visPassword": null, "visDevUserName": null, "visDevPassword": null, "pwdChanged": "true", "dateofbirth": null }
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService query
    FINEST: Full id: managed/user Extracted type: managed/user
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries query
    FINE: Querying {_pageSize=2147483647, uid=test2016122301, _queryFilter=null, _queryExpression=null, _pagedResultsOffset=0, _resource=managed/user, _queryId=for-userName, _sortKeys=[]}
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries resolveQuery
    FINE: Prepared statement: SELECT * FROM openidm.managed_user WHERE userName = ?
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.Mapping mapToJsonValue
    FINE: Mapped rs [email protected] to { "_id": "aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994", "_rev": "5", "userName": "test2016122301", "password": { "$crypto": { "type": "x-simple-encryption", "value": { "cipher": "AES/CBC/PKCS5Padding", "data": "Yu8MDixdFrLixnfsJOjR6w==", "iv": "fjXgyG55gZ+kbZj+8KYCNA==", "key": "openidm-sym-default" } } }, "accountStatus": "active", "givenName": "test2016122301", "sn": "test2016122301", "office": "eACPC", "dphkid": "MTFMQU5EU0R0ZXN0MjAxNjEyMjMwMQ==", "rank": "1", "dprankcode": "1", "dpstaffgroup": "1", "dpdeptid": "LANDSD", "firstdayofapp": null, "idminformation": "uid=test2016122301,ou=people,ou=users,o=example,dc=com,dc=hk", "employeeType": "CS", "cn": "test2016122301", "adminFlag": "0", "oldPassword": { "$crypto": { "type": "x-simple-encryption", "value": { "cipher": "AES/CBC/PKCS5Padding", "data": "fGCZ7xA9JlJZS7v8+kQ/kg==", "iv": "f2hNexmGqYmm7aKsdlE4/g==", "key": "openidm-sym-default" } } }, "amsUserName": null, "amsPassword": null, "amsDLO": null, "amsDevUserName": null, "amsDevPassword": null, "amsDevDLO": null, "grpsUserName": null, "grpsPassword": null, "grpsdevUserName": null, "grpsdevPassword": null, "shisUserName": null, "shisPassword": null, "visUserName": null, "visPassword": null, "visDevUserName": null, "visDevPassword": null, "pwdChanged": "true", "dateofbirth": null }
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler update
    FINE: Update with fullid managed/user/aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler readForUpdate
    FINEST: Populating prepared statement SELECT * FROM openidm.managed_user WHERE objectid = ? FOR UPDATE for managed/user/aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler readForUpdate
    FINE: Executing: SELECT * FROM openidm.managed_user WHERE objectid = ? FOR UPDATE
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler readForUpdate
    FINE: Read for update full id: managed/user/aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler update
    FINE: Update existing object managed/user/aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994 rev: 5 
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler update
    FINEST: Populating prepared statement UPDATE openidm.managed_user SET objectid = ?, rev = ?, userName = ?, password = ?, accountStatus = ?, givenName = ?, sn = ?, office = ?, dphkid = ?, rank = ?, dprankcode = ?, dpstaffgroup = ?, dpdeptid = ?, firstdayofapp = ?, idminformation = ?, employeeType = ?, cn = ?, adminFlag = ?, oldPassword = ?, amsUserName = ?, amsPassword = ?, amsDLO = ?, amsDevUserName = ?, amsDevPassword = ?, amsDevDLO = ?, grpsUserName = ?, grpsPassword = ?, grpsdevUserName = ?, grpsdevPassword = ?, shisUserName = ?, shisPassword = ?, visUserName = ?, visPassword = ?, visDevUserName = ?, visDevPassword = ?, pwdChanged = ?, dateofbirth = ? WHERE objectid = ? for managed/user/aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994 aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994 6
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 4 from /password is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: { "$crypto": { "type": "x-simple-encryption", "value": { "cipher": "AES/CBC/PKCS5Padding", "data": "zxvjNbh4MWCAzFW+cNxd5g==", "iv": "3NOREHJebKBrr9IRKWrNbw==", "key": "openidm-sym-default" } } }
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 19 from /oldPassword is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: { "$crypto": { "type": "x-simple-encryption", "value": { "cipher": "AES/CBC/PKCS5Padding", "data": "OjMNbIvk1nu5DDqzGQYeUw==", "iv": "H584kSahn7ixBLKVQQrz3g==", "key": "openidm-sym-default" } } }
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler update
    FINE: Update statement: UPDATE openidm.managed_user SET objectid = ?, rev = ?, userName = ?, password = ?, accountStatus = ?, givenName = ?, sn = ?, office = ?, dphkid = ?, rank = ?, dprankcode = ?, dpstaffgroup = ?, dpdeptid = ?, firstdayofapp = ?, idminformation = ?, employeeType = ?, cn = ?, adminFlag = ?, oldPassword = ?, amsUserName = ?, amsPassword = ?, amsDLO = ?, amsDevUserName = ?, amsDevPassword = ?, amsDevDLO = ?, grpsUserName = ?, grpsPassword = ?, grpsdevUserName = ?, grpsdevPassword = ?, shisUserName = ?, shisPassword = ?, visUserName = ?, visPassword = ?, visDevUserName = ?, visDevPassword = ?, pwdChanged = ?, dateofbirth = ? WHERE objectid = ?
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler update
    FINEST: Updated rows: 1 for managed/user/aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService update
    FINE: Commited updated object for id: managed/user/aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler read
    FINE: Populating prepared statement SELECT * FROM openidm.managed_user WHERE objectid = ? for managed/user/aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler read
    FINE: Executing: SELECT * FROM openidm.managed_user WHERE objectid = ?
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.Mapping mapToJsonValue
    FINE: Mapped rs [email protected] to { "_id": "aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994", "_rev": "6", "userName": "test2016122301", "password": { "$crypto": { "type": "x-simple-encryption", "value": { "cipher": "AES/CBC/PKCS5Padding", "data": "zxvjNbh4MWCAzFW+cNxd5g==", "iv": "3NOREHJebKBrr9IRKWrNbw==", "key": "openidm-sym-default" } } }, "accountStatus": "active", "givenName": "test2016122301", "sn": "test2016122301", "office": "eACPC", "dphkid": "MTFMQU5EU0R0ZXN0MjAxNjEyMjMwMQ==", "rank": "1", "dprankcode": "1", "dpstaffgroup": "1", "dpdeptid": "LANDSD", "firstdayofapp": null, "idminformation": "uid=test2016122301,ou=people,ou=users,o=example,dc=com,dc=hk", "employeeType": "CS", "cn": "test2016122301", "adminFlag": "0", "oldPassword": { "$crypto": { "type": "x-simple-encryption", "value": { "cipher": "AES/CBC/PKCS5Padding", "data": "OjMNbIvk1nu5DDqzGQYeUw==", "iv": "H584kSahn7ixBLKVQQrz3g==", "key": "openidm-sym-default" } } }, "amsUserName": null, "amsPassword": null, "amsDLO": null, "amsDevUserName": null, "amsDevPassword": null, "amsDevDLO": null, "grpsUserName": null, "grpsPassword": null, "grpsdevUserName": null, "grpsdevPassword": null, "shisUserName": null, "shisPassword": null, "visUserName": null, "visPassword": null, "visDevUserName": null, "visDevPassword": null, "pwdChanged": "true", "dateofbirth": null }
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler read
    FINE:  full id: managed/user/aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994, rev: "6", obj { "_id": "aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994", "_rev": "6", "userName": "test2016122301", "password": { "$crypto": { "type": "x-simple-encryption", "value": { "cipher": "AES/CBC/PKCS5Padding", "data": "zxvjNbh4MWCAzFW+cNxd5g==", "iv": "3NOREHJebKBrr9IRKWrNbw==", "key": "openidm-sym-default" } } }, "accountStatus": "active", "givenName": "test2016122301", "sn": "test2016122301", "office": "eACPC", "dphkid": "MTFMQU5EU0R0ZXN0MjAxNjEyMjMwMQ==", "rank": "1", "dprankcode": "1", "dpstaffgroup": "1", "dpdeptid": "LANDSD", "firstdayofapp": null, "idminformation": "uid=test2016122301,ou=people,ou=users,o=example,dc=com,dc=hk", "employeeType": "CS", "cn": "test2016122301", "adminFlag": "0", "oldPassword": { "$crypto": { "type": "x-simple-encryption", "value": { "cipher": "AES/CBC/PKCS5Padding", "data": "OjMNbIvk1nu5DDqzGQYeUw==", "iv": "H584kSahn7ixBLKVQQrz3g==", "key": "openidm-sym-default" } } }, "amsUserName": null, "amsPassword": null, "amsDLO": null, "amsDevUserName": null, "amsDevPassword": null, "amsDevDLO": null, "grpsUserName": null, "grpsPassword": null, "grpsdevUserName": null, "grpsdevPassword": null, "shisUserName": null, "shisPassword": null, "visUserName": null, "visPassword": null, "visDevUserName": null, "visDevPassword": null, "pwdChanged": "true", "dateofbirth": null }
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService query
    FINEST: Full id: link Extracted type: link
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries query
    FINE: Querying {_pageSize=2147483647, _queryFilter=(/linkType eq "managedUser_ldapAccount" and /linkQualifier eq "default" and /firstId eq "aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994"), _queryExpression=null, _pagedResultsOffset=0, _resource=link, _queryId=null, _sortKeys=[]}
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries parseQueryFilter
    FINE: Tokenized statement: SELECT obj.* FROM ${_dbSchema}.${_mainTable} obj WHERE (linktype = ${v73} AND linkqualifier = ${v74} AND firstid = ${v75}) LIMIT 2147483647 OFFSET 0 with replacementTokens: {v73=managedUser_ldapAccount, v74=default, v75=aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994}
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries resolveQuery
    FINE: Prepared statement: SELECT obj.* FROM openidm.links obj WHERE (linktype = ? AND linkqualifier = ? AND firstid = ?) LIMIT 2147483647 OFFSET 0
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.Mapping mapToJsonValue
    FINE: Mapped rs [email protected] to { "_id": "ea4292b8-d311-4a4c-b90f-882440235da9", "_rev": "0", "linkType": "managedUser_ldapAccount", "firstId": "aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994", "secondId": "uid=test2016122301,ou=people,ou=users,o=example,dc=com,dc=hk", "linkQualifier": "default" }
    
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Create with fullid audit/activity/18647504-cd46-448f-a945-f3f5dadf46ce-481
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Preparing statement INSERT INTO openidm.auditactivity (objectid, activitydate, eventname, transactionid, userid, trackingids, runas, activityobjectid, operation, subjectbefore, subjectafter, changedfields, subjectrev, passwordchanged, message, status) VALUES ( ?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?) with audit/activity, 18647504-cd46-448f-a945-f3f5dadf46ce-481, 0
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 10 from /before is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: { "employeeType": "CS", "telephoneNumber": null, "dn": "uid=test2016122301,ou=people,ou=users,o=example,dc=com,dc=hk", "cn": "test2016122301", "givenName": "test2016122301", "dphkid": "11LANDSDtest2016122301", "disabled": null, "uid": "test2016122301", "DPDEPTID": "LANDSD", "description": "eACPC", "DPRANKCODE": "1", "ldapGroups": [  ], "inetUserStatus": "active", "dpstaffgroup": "1", "mail": null, "sn": "test2016122301", "_id": "uid=test2016122301,ou=people,ou=users,o=example,dc=com,dc=hk" }
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 11 from /after is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: { "employeeType": "CS", "telephoneNumber": null, "dn": "uid=test2016122301,ou=people,ou=users,o=example,dc=com,dc=hk", "cn": "test2016122301", "givenName": "test2016122301", "dphkid": "11LANDSDtest2016122301", "disabled": null, "uid": "test2016122301", "DPDEPTID": "LANDSD", "description": "eACPC", "DPRANKCODE": "1", "ldapGroups": [  ], "inetUserStatus": "active", "dpstaffgroup": "1", "mail": null, "sn": "test2016122301", "_id": "uid=test2016122301,ou=people,ou=users,o=example,dc=com,dc=hk" }
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 12 from /changedFields is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: [  ]
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 14 from /passwordChanged is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: false
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Executing: INSERT INTO openidm.auditactivity (objectid, activitydate, eventname, transactionid, userid, trackingids, runas, activityobjectid, operation, subjectbefore, subjectafter, changedfields, subjectrev, passwordchanged, message, status) VALUES ( ?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?)
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Created object for id audit/activity/18647504-cd46-448f-a945-f3f5dadf46ce-481 with rev 0
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService create
    FINE: Commited created object for id: audit/activity/18647504-cd46-448f-a945-f3f5dadf46ce-481
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Create with fullid audit/sync/18647504-cd46-448f-a945-f3f5dadf46ce-482
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Preparing statement INSERT INTO openidm.auditsync (objectid, transactionid, activitydate, eventname, userid, trackingids, activity, exceptiondetail, linkqualifier, mapping, message, messagedetail, situation, sourceobjectid, status, targetobjectid) VALUES ( ?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?) with audit/sync, 18647504-cd46-448f-a945-f3f5dadf46ce-482, 0
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Executing: INSERT INTO openidm.auditsync (objectid, transactionid, activitydate, eventname, userid, trackingids, activity, exceptiondetail, linkqualifier, mapping, message, messagedetail, situation, sourceobjectid, status, targetobjectid) VALUES ( ?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?)
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Created object for id audit/sync/18647504-cd46-448f-a945-f3f5dadf46ce-482 with rev 0
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService create
    FINE: Commited created object for id: audit/sync/18647504-cd46-448f-a945-f3f5dadf46ce-482
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService query
    FINEST: Full id: link Extracted type: link
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries query
    FINE: Querying {_pageSize=2147483647, _queryFilter=(/linkType eq "managedUser_sourceSmoadprodAccount" and /linkQualifier eq "default" and /firstId eq "aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994"), _queryExpression=null, _pagedResultsOffset=0, _resource=link, _queryId=null, _sortKeys=[]}
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries parseQueryFilter
    FINE: Tokenized statement: SELECT obj.* FROM ${_dbSchema}.${_mainTable} obj WHERE (linktype = ${v76} AND linkqualifier = ${v77} AND firstid = ${v78}) LIMIT 2147483647 OFFSET 0 with replacementTokens: {v76=managedUser_sourceSmoadprodAccount, v77=default, v78=aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994}
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries resolveQuery
    FINE: Prepared statement: SELECT obj.* FROM openidm.links obj WHERE (linktype = ? AND linkqualifier = ? AND firstid = ?) LIMIT 2147483647 OFFSET 0
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Create with fullid audit/sync/18647504-cd46-448f-a945-f3f5dadf46ce-483
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Preparing statement INSERT INTO openidm.auditsync (objectid, transactionid, activitydate, eventname, userid, trackingids, activity, exceptiondetail, linkqualifier, mapping, message, messagedetail, situation, sourceobjectid, status, targetobjectid) VALUES ( ?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?) with audit/sync, 18647504-cd46-448f-a945-f3f5dadf46ce-483, 0
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Executing: INSERT INTO openidm.auditsync (objectid, transactionid, activitydate, eventname, userid, trackingids, activity, exceptiondetail, linkqualifier, mapping, message, messagedetail, situation, sourceobjectid, status, targetobjectid) VALUES ( ?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?)
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Created object for id audit/sync/18647504-cd46-448f-a945-f3f5dadf46ce-483 with rev 0
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService create
    FINE: Commited created object for id: audit/sync/18647504-cd46-448f-a945-f3f5dadf46ce-483
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService query
    FINEST: Full id: link Extracted type: link
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries query
    FINE: Querying {_pageSize=2147483647, _queryFilter=(/linkType eq "managedUser_sourceLaoadprodAccount" and /linkQualifier eq "default" and /firstId eq "aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994"), _queryExpression=null, _pagedResultsOffset=0, _resource=link, _queryId=null, _sortKeys=[]}
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries parseQueryFilter
    FINE: Tokenized statement: SELECT obj.* FROM ${_dbSchema}.${_mainTable} obj WHERE (linktype = ${v79} AND linkqualifier = ${v80} AND firstid = ${v81}) LIMIT 2147483647 OFFSET 0 with replacementTokens: {v79=managedUser_sourceLaoadprodAccount, v80=default, v81=aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994}
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries resolveQuery
    FINE: Prepared statement: SELECT obj.* FROM openidm.links obj WHERE (linktype = ? AND linkqualifier = ? AND firstid = ?) LIMIT 2147483647 OFFSET 0
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Create with fullid audit/sync/18647504-cd46-448f-a945-f3f5dadf46ce-484
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Preparing statement INSERT INTO openidm.auditsync (objectid, transactionid, activitydate, eventname, userid, trackingids, activity, exceptiondetail, linkqualifier, mapping, message, messagedetail, situation, sourceobjectid, status, targetobjectid) VALUES ( ?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?) with audit/sync, 18647504-cd46-448f-a945-f3f5dadf46ce-484, 0
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Executing: INSERT INTO openidm.auditsync (objectid, transactionid, activitydate, eventname, userid, trackingids, activity, exceptiondetail, linkqualifier, mapping, message, messagedetail, situation, sourceobjectid, status, targetobjectid) VALUES ( ?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?)
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Created object for id audit/sync/18647504-cd46-448f-a945-f3f5dadf46ce-484 with rev 0
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService create
    FINE: Commited created object for id: audit/sync/18647504-cd46-448f-a945-f3f5dadf46ce-484
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService query
    FINEST: Full id: link Extracted type: link
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries query
    FINE: Querying {_pageSize=2147483647, _queryFilter=(/linkType eq "managedUser_sourceLacoadprodAccount" and /linkQualifier eq "default" and /firstId eq "aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994"), _queryExpression=null, _pagedResultsOffset=0, _resource=link, _queryId=null, _sortKeys=[]}
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries parseQueryFilter
    FINE: Tokenized statement: SELECT obj.* FROM ${_dbSchema}.${_mainTable} obj WHERE (linktype = ${v82} AND linkqualifier = ${v83} AND firstid = ${v84}) LIMIT 2147483647 OFFSET 0 with replacementTokens: {v82=managedUser_sourceLacoadprodAccount, v83=default, v84=aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994}
    Dec 23, 2016 1:55:04 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries resolveQuery
    FINE: Prepared statement: SELECT obj.* FROM openidm.links obj WHERE (linktype = ? AND linkqualifier = ? AND firstid = ?) LIMIT 2147483647 OFFSET 0
    Dec 23, 2016 1:55:05 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Create with fullid audit/sync/18647504-cd46-448f-a945-f3f5dadf46ce-485
    Dec 23, 2016 1:55:05 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Preparing statement INSERT INTO openidm.auditsync (objectid, transactionid, activitydate, eventname, userid, trackingids, activity, exceptiondetail, linkqualifier, mapping, message, messagedetail, situation, sourceobjectid, status, targetobjectid) VALUES ( ?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?) with audit/sync, 18647504-cd46-448f-a945-f3f5dadf46ce-485, 0
    Dec 23, 2016 1:55:05 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Executing: INSERT INTO openidm.auditsync (objectid, transactionid, activitydate, eventname, userid, trackingids, activity, exceptiondetail, linkqualifier, mapping, message, messagedetail, situation, sourceobjectid, status, targetobjectid) VALUES ( ?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?)
    Dec 23, 2016 1:55:05 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Created object for id audit/sync/18647504-cd46-448f-a945-f3f5dadf46ce-485 with rev 0
    Dec 23, 2016 1:55:05 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService create
    FINE: Commited created object for id: audit/sync/18647504-cd46-448f-a945-f3f5dadf46ce-485
    Dec 23, 2016 1:55:05 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService query
    FINEST: Full id: link Extracted type: link
    Dec 23, 2016 1:55:05 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries query
    FINE: Querying {_pageSize=2147483647, _queryFilter=(/linkType eq "managedUser_sourceGenericldap__ACCOUNT__" and /linkQualifier eq "default" and /firstId eq "aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994"), _queryExpression=null, _pagedResultsOffset=0, _resource=link, _queryId=null, _sortKeys=[]}
    Dec 23, 2016 1:55:05 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries parseQueryFilter
    FINE: Tokenized statement: SELECT obj.* FROM ${_dbSchema}.${_mainTable} obj WHERE (linktype = ${v85} AND linkqualifier = ${v86} AND firstid = ${v87}) LIMIT 2147483647 OFFSET 0 with replacementTokens: {v85=managedUser_sourceGenericldap__ACCOUNT__, v86=default, v87=aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994}
    Dec 23, 2016 1:55:05 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries resolveQuery
    FINE: Prepared statement: SELECT obj.* FROM openidm.links obj WHERE (linktype = ? AND linkqualifier = ? AND firstid = ?) LIMIT 2147483647 OFFSET 0
    Dec 23, 2016 1:55:05 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService query
    FINEST: Full id: link Extracted type: link
    Dec 23, 2016 1:55:05 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries query
    FINE: Querying {_pageSize=2147483647, _queryFilter=(/linkType eq "managedUser_sourceGenericldap__ACCOUNT__" and /linkQualifier eq "default" and /secondId eq "df40746d-7eb1-4ae6-9a73-f0626bd5e01d"), _queryExpression=null, _pagedResultsOffset=0, _resource=link, _queryId=null, _sortKeys=[]}
    Dec 23, 2016 1:55:05 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries parseQueryFilter
    FINE: Tokenized statement: SELECT obj.* FROM ${_dbSchema}.${_mainTable} obj WHERE (linktype = ${v88} AND linkqualifier = ${v89} AND secondid = ${v90}) LIMIT 2147483647 OFFSET 0 with replacementTokens: {v88=managedUser_sourceGenericldap__ACCOUNT__, v89=default, v90=df40746d-7eb1-4ae6-9a73-f0626bd5e01d}
    Dec 23, 2016 1:55:05 PM org.forgerock.openidm.repo.jdbc.impl.query.TableQueries resolveQuery
    FINE: Prepared statement: SELECT obj.* FROM openidm.links obj WHERE (linktype = ? AND linkqualifier = ? AND secondid = ?) LIMIT 2147483647 OFFSET 0
    Dec 23, 2016 1:55:05 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Create with fullid audit/sync/18647504-cd46-448f-a945-f3f5dadf46ce-486
    Dec 23, 2016 1:55:05 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Preparing statement INSERT INTO openidm.auditsync (objectid, transactionid, activitydate, eventname, userid, trackingids, activity, exceptiondetail, linkqualifier, mapping, message, messagedetail, situation, sourceobjectid, status, targetobjectid) VALUES ( ?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?) with audit/sync, 18647504-cd46-448f-a945-f3f5dadf46ce-486, 0
    Dec 23, 2016 1:55:05 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Executing: INSERT INTO openidm.auditsync (objectid, transactionid, activitydate, eventname, userid, trackingids, activity, exceptiondetail, linkqualifier, mapping, message, messagedetail, situation, sourceobjectid, status, targetobjectid) VALUES ( ?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?)
    Dec 23, 2016 1:55:05 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Created object for id audit/sync/18647504-cd46-448f-a945-f3f5dadf46ce-486 with rev 0
    Dec 23, 2016 1:55:05 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService create
    FINE: Commited created object for id: audit/sync/18647504-cd46-448f-a945-f3f5dadf46ce-486
    Dec 23, 2016 1:55:05 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Create with fullid audit/activity/18647504-cd46-448f-a945-f3f5dadf46ce-487
    Dec 23, 2016 1:55:05 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Preparing statement INSERT INTO openidm.auditactivity (objectid, activitydate, eventname, transactionid, userid, trackingids, runas, activityobjectid, operation, subjectbefore, subjectafter, changedfields, subjectrev, passwordchanged, message, status) VALUES ( ?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?) with audit/activity, 18647504-cd46-448f-a945-f3f5dadf46ce-487, 0
    Dec 23, 2016 1:55:05 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 10 from /before is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: { "_id": "aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994", "_rev": "5", "userName": "test2016122301", "password": { "$crypto": { "type": "x-simple-encryption", "value": { "cipher": "AES/CBC/PKCS5Padding", "data": "Yu8MDixdFrLixnfsJOjR6w==", "iv": "fjXgyG55gZ+kbZj+8KYCNA==", "key": "openidm-sym-default" } } }, "accountStatus": "active", "givenName": "test2016122301", "sn": "test2016122301", "office": "eACPC", "dphkid": "MTFMQU5EU0R0ZXN0MjAxNjEyMjMwMQ==", "rank": "1", "dprankcode": "1", "dpstaffgroup": "1", "dpdeptid": "LANDSD", "firstdayofapp": null, "idminformation": "uid=test2016122301,ou=people,ou=users,o=example,dc=com,dc=hk", "employeeType": "CS", "cn": "test2016122301", "adminFlag": "0", "oldPassword": { "$crypto": { "type": "x-simple-encryption", "value": { "cipher": "AES/CBC/PKCS5Padding", "data": "fGCZ7xA9JlJZS7v8+kQ/kg==", "iv": "f2hNexmGqYmm7aKsdlE4/g==", "key": "openidm-sym-default" } } }, "amsUserName": null, "amsPassword": null, "amsDLO": null, "amsDevUserName": null, "amsDevPassword": null, "amsDevDLO": null, "grpsUserName": null, "grpsPassword": null, "grpsdevUserName": null, "grpsdevPassword": null, "shisUserName": null, "shisPassword": null, "visUserName": null, "visPassword": null, "visDevUserName": null, "visDevPassword": null, "pwdChanged": "true", "dateofbirth": null }
    Dec 23, 2016 1:55:05 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 11 from /after is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: { "_id": "aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994", "_rev": "6", "userName": "test2016122301", "password": { "$crypto": { "type": "x-simple-encryption", "value": { "cipher": "AES/CBC/PKCS5Padding", "data": "zxvjNbh4MWCAzFW+cNxd5g==", "iv": "3NOREHJebKBrr9IRKWrNbw==", "key": "openidm-sym-default" } } }, "accountStatus": "active", "givenName": "test2016122301", "sn": "test2016122301", "office": "eACPC", "dphkid": "MTFMQU5EU0R0ZXN0MjAxNjEyMjMwMQ==", "rank": "1", "dprankcode": "1", "dpstaffgroup": "1", "dpdeptid": "LANDSD", "firstdayofapp": null, "idminformation": "uid=test2016122301,ou=people,ou=users,o=example,dc=com,dc=hk", "employeeType": "CS", "cn": "test2016122301", "adminFlag": "0", "oldPassword": { "$crypto": { "type": "x-simple-encryption", "value": { "cipher": "AES/CBC/PKCS5Padding", "data": "OjMNbIvk1nu5DDqzGQYeUw==", "iv": "H584kSahn7ixBLKVQQrz3g==", "key": "openidm-sym-default" } } }, "amsUserName": null, "amsPassword": null, "amsDLO": null, "amsDevUserName": null, "amsDevPassword": null, "amsDevDLO": null, "grpsUserName": null, "grpsPassword": null, "grpsdevUserName": null, "grpsdevPassword": null, "shisUserName": null, "shisPassword": null, "visUserName": null, "visPassword": null, "visDevUserName": null, "visDevPassword": null, "pwdChanged": "true", "dateofbirth": null }
    Dec 23, 2016 1:55:05 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 12 from /changedFields is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: [  ]
    Dec 23, 2016 1:55:05 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 14 from /passwordChanged is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: true
    Dec 23, 2016 1:55:05 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Executing: INSERT INTO openidm.auditactivity (objectid, activitydate, eventname, transactionid, userid, trackingids, runas, activityobjectid, operation, subjectbefore, subjectafter, changedfields, subjectrev, passwordchanged, message, status) VALUES ( ?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?)
    Dec 23, 2016 1:55:05 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Created object for id audit/activity/18647504-cd46-448f-a945-f3f5dadf46ce-487 with rev 0
    Dec 23, 2016 1:55:05 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService create
    FINE: Commited created object for id: audit/activity/18647504-cd46-448f-a945-f3f5dadf46ce-487
    Dec 23, 2016 1:55:05 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Create with fullid audit/access/18647504-cd46-448f-a945-f3f5dadf46ce-488
    Dec 23, 2016 1:55:05 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Preparing statement INSERT INTO openidm.auditaccess (objectid, activitydate, eventname, transactionid, userid, trackingids, server_ip, server_port, client_ip, client_port, request_protocol, request_operation, request_detail, http_request_secure, http_request_method, http_request_path, http_request_queryparameters, http_request_headers, http_request_cookies, http_response_headers, response_status, response_statuscode, response_elapsedtime, response_elapsedtimeunits, roles) VALUES ( ?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?) with audit/access, 18647504-cd46-448f-a945-f3f5dadf46ce-488, 0
    Dec 23, 2016 1:55:05 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 8 from /server/port is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: 18443
    Dec 23, 2016 1:55:05 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 10 from /client/port is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: 40691
    Dec 23, 2016 1:55:05 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 14 from /http/request/secure is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: true
    Dec 23, 2016 1:55:05 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 17 from /http/request/queryParameters is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: {  }
    Dec 23, 2016 1:55:05 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 18 from /http/request/headers is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: { "Accept": [ "application/json, text/javascript, */*; q=0.01" ], "Connection": [ "keep-alive" ], "Content-Length": [ "195" ], "Content-Type": [ "application/json" ], "Host": [ "10.77.15.90:18443" ], "If-Match": [ "*" ], "User-Agent": [ "Mozilla/4.0 (compatible; MSIE 7.0; Windows NT 10.0; WOW64; Trident/7.0; .NET4.0C; .NET4.0E; .NET CLR 2.0.50727; .NET CLR 3.0.30729; .NET CLR 3.5.30729)" ], "X-OpenIDM-Username": [ "openidm-admin" ] }
    Dec 23, 2016 1:55:05 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 19 from /http/request/cookies is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: {  }
    Dec 23, 2016 1:55:05 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 23 from /response/elapsedTime is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: 707
    Dec 23, 2016 1:55:05 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler populatePrepStatementColumns
    FINEST: Value for col 25 from /roles is getting Stringified from type class org.forgerock.json.JsonValue to store in a STRING column as value: [ "openidm-admin", "openidm-authorized" ]
    Dec 23, 2016 1:55:05 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Executing: INSERT INTO openidm.auditaccess (objectid, activitydate, eventname, transactionid, userid, trackingids, server_ip, server_port, client_ip, client_port, request_protocol, request_operation, request_detail, http_request_secure, http_request_method, http_request_path, http_request_queryparameters, http_request_headers, http_request_cookies, http_response_headers, response_status, response_statuscode, response_elapsedtime, response_elapsedtimeunits, roles) VALUES ( ?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?)
    Dec 23, 2016 1:55:05 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Created object for id audit/access/18647504-cd46-448f-a945-f3f5dadf46ce-488 with rev 0
    Dec 23, 2016 1:55:05 PM org.forgerock.openidm.repo.jdbc.impl.JDBCRepoService create
    FINE: Commited created object for id: audit/access/18647504-cd46-448f-a945-f3f5dadf46ce-488
    Dec 23, 2016 1:55:22 PM org.forgerock.openidm.repo.jdbc.impl.GenericTableHandler read
    FINEST: Populating prepared statement SELECT obj.rev, obj.fullobject FROM openidm.objecttypes objtype, openidm.schedulerobjects obj WHERE obj.objecttypes_id = objtype.id AND objtype.objecttype = ? AND obj.objectid  = ? for scheduler/waitingTriggers
    Dec 23, 2016 1:55:22 PM org.forgerock.openidm.repo.jdbc.impl.GenericTableHandler read
    FINE: Executing: SELECT obj.rev, obj.fullobject FROM openidm.objecttypes objtype, openidm.schedulerobjects obj WHERE obj.objecttypes_id = objtype.id AND objtype.objecttype = ? AND obj.objectid  = ?
    Dec 23, 2016 1:55:22 PM org.forgerock.openidm.repo.jdbc.impl.GenericTableHandler read
    FINE:  full id: scheduler/waitingTriggers, rev: 0, obj {names=[], _id=waitingTriggers, _rev=0}

    Forget about the logs for synchronization to ADs, I just did not captured their configuration as they were not problematic.

    Notice the long wait between the logs

    Dec 23, 2016 1:46:59 PM org.forgerock.openidm.repo.jdbc.impl.Mapping mapToJsonValue
    FINE: Mapped rs [email protected] to { "_id": "ea4292b8-d311-4a4c-b90f-882440235da9", "_rev": "0", "linkType": "managedUser_ldapAccount", "firstId": "aa62b1c3-a9d5-4e66-8eb7-abf7a65b8994", "secondId": "uid=test2016122301,ou=people,ou=users,o=landsd,dc=ccgo,dc=hksarg", "linkQualifier": "default" }

    and

    Dec 23, 2016 1:47:08 PM org.forgerock.openidm.repo.jdbc.impl.MappedTableHandler create
    FINE: Create with fullid audit/activity/18647504-cd46-448f-a945-f3f5dadf46ce-433

    I am wondering what was OpenIDM busy on during this period. Anyone has idea?

    Thanks!

    • This topic was modified 5 years, 6 months ago by timmychan.
    #15055
     Bill Nelson
    Participant

    I spent a fair amount of time looking at your log files (what else was I going to do at 2:30am, right). I performed a side by side comparison of the log files produced when the response was fast vs when it was slow. You can find my document here:

    https://dl.dropboxusercontent.com/u/13293536/ForgeRockForum/OpenIDM_sync_password_to_OpenDJ_performance_issue.docx

    I saw the same observations as you – there was a 9 sec delay between the lookup of the user object and the preparation for entering it into the audit/activity log. While this, in and of itself is not entirely out of the norm (as something could have been been going on at that time), if this slow behavior is consistent, it could be indicative of a bigger issue – one that could occur due to many reasons.

    One area I would start with is your Java configuration. Is it possible that you may be hitting a Garbage Collection scenario right at this time? If you haven’t already done this, I would suggest that you capture your gc logs and see what was going on when you see this slowdown. For posterity’s sake, here is how you can enable gc logging if you don’t already have this information handy.

    Change:

    # Only set OPENIDM_OPTS if not already set
    [ -z "$OPENIDM_OPTS" ] && OPENIDM_OPTS="-Xmx1024m -Xms1024m"

    To:

    [ -z "$OPENIDM_OPTS" ] && OPENIDM_OPTS="-Xmx32768m -Xms32768m -verbose:gc -Xloggc:$OPENIDM_HOME/logs/gc.log -XX:+PrintClassHistogram -XX:+PrintGCTimeStamps -XX:+PrintGCDateStamps -XX:+PrintTenuringDistribution -XX:+PrintGCDetails"

    Note: I had my max/initial settings set at 32G for testing purposes. You should set this to whatever you need, but odds are good that the default 1G is probably not be enough.

    If you find that you are not hitting garbage collection at this time, then I would suggest that you open a ticket with ForgeRock (assuming you have a Backstage account, of course).

    bill

    • This reply was modified 5 years, 6 months ago by Bill Nelson.
    #15057
     timmychan
    Participant

    Dear Bill,

    Thank you for spending time on my case! Unfortunately, the gc logs showed nothing meaningful.

    I did further investigation in the OpenDJ server, found something interesting which showed the long waiting time is stuck at OpenDJ side. I checked the access log:

    [23/Dec/2016:17:00:28 +0800] SEARCH REQ conn=877 op=102 msgID=103 base="" scope=base filter="(objectClass=*)" attrs="subschemaSubentry"
    [23/Dec/2016:17:00:28 +0800] SEARCH RES conn=877 op=102 msgID=103 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:28 +0800] SEARCH REQ conn=877 op=103 msgID=104 base="uid=test2016122310,ou=people,ou=users,o=example,dc=com,dc=hk" scope=base filter="(&(objectClass=top)(objectClass=person)(objectClass=organizationalPerson)(objectClass=inetOrgPerson)(objectClass=inetuser)(objectClass=dpPerson))" attrs="cn,description,DPDEPTID,dphkid,DPRANKCODE,dpstaffgroup,ds-pwp-account-disabled,employeeType,givenName,inetUserStatus,mail,sn,telephoneNumber,uid"
    [23/Dec/2016:17:00:28 +0800] SEARCH RES conn=877 op=103 msgID=104 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:28 +0800] SEARCH REQ conn=877 op=104 msgID=105 base="ou=people,ou=users,o=example,dc=com,dc=hk" scope=sub filter="(uniqueMember=uid=test2016122310,ou=people,ou=users,o=example,dc=com,dc=hk)" attrs="1.1"
    [23/Dec/2016:17:00:28 +0800] SEARCH RES conn=877 op=104 msgID=105 result=0 nentries=0 etime=0
    [23/Dec/2016:17:00:28 +0800] SEARCH REQ conn=877 op=105 msgID=106 base="" scope=base filter="(objectClass=*)" attrs="subschemaSubentry"
    [23/Dec/2016:17:00:28 +0800] SEARCH RES conn=877 op=105 msgID=106 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:28 +0800] SEARCH REQ conn=877 op=106 msgID=107 base="uid=test2016122310,ou=people,ou=users,o=example,dc=com,dc=hk" scope=base filter="(&(objectClass=top)(objectClass=person)(objectClass=organizationalPerson)(objectClass=inetOrgPerson)(objectClass=inetuser)(objectClass=dpPerson))" attrs="cn,description,DPDEPTID,dphkid,DPRANKCODE,dpstaffgroup,ds-pwp-account-disabled,employeeType,givenName,inetUserStatus,mail,sn,telephoneNumber,uid"
    [23/Dec/2016:17:00:28 +0800] SEARCH RES conn=877 op=106 msgID=107 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:28 +0800] SEARCH REQ conn=877 op=107 msgID=108 base="ou=people,ou=users,o=example,dc=com,dc=hk" scope=sub filter="(uniqueMember=uid=test2016122310,ou=people,ou=users,o=example,dc=com,dc=hk)" attrs="1.1"
    [23/Dec/2016:17:00:28 +0800] SEARCH RES conn=877 op=107 msgID=108 result=0 nentries=0 etime=0
    [23/Dec/2016:17:00:28 +0800] SEARCH REQ conn=877 op=108 msgID=109 base="" scope=base filter="(objectClass=*)" attrs="subschemaSubentry"
    [23/Dec/2016:17:00:28 +0800] SEARCH RES conn=877 op=108 msgID=109 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:28 +0800] SEARCH REQ conn=877 op=109 msgID=110 base="uid=test2016122310,ou=people,ou=users,o=example,dc=com,dc=hk" scope=base filter="(&(objectClass=top)(objectClass=person)(objectClass=organizationalPerson)(objectClass=inetOrgPerson)(objectClass=inetuser)(objectClass=dpPerson))" attrs="1.1"
    [23/Dec/2016:17:00:28 +0800] SEARCH RES conn=877 op=109 msgID=110 result=0 nentries=1 etime=0
    <strong><em>[23/Dec/2016:17:00:28 +0800] MODIFY REQ conn=877 op=110 msgID=111 dn="uid=test2016122310,ou=people,ou=users,o=example,dc=com,dc=hk"</em></strong>
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=627 op=1266 msgID=1267 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=627 op=1266 msgID=1267 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=672 op=1663 msgID=1664 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=672 op=1663 msgID=1664 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=638 op=1266 msgID=1267 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=638 op=1266 msgID=1267 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=625 op=1266 msgID=1267 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=625 op=1266 msgID=1267 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=623 op=1266 msgID=1267 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=623 op=1266 msgID=1267 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=635 op=1266 msgID=1267 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=635 op=1266 msgID=1267 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=642 op=1266 msgID=1267 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=678 op=1266 msgID=1267 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=666 op=1266 msgID=1267 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=678 op=1266 msgID=1267 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=626 op=1266 msgID=1267 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=666 op=1266 msgID=1267 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=626 op=1266 msgID=1267 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=648 op=1335 msgID=1336 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=648 op=1335 msgID=1336 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=628 op=1365 msgID=1366 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=628 op=1365 msgID=1366 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=634 op=1266 msgID=1267 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=634 op=1266 msgID=1267 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=630 op=1318 msgID=1319 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=640 op=1266 msgID=1267 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=630 op=1318 msgID=1319 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=632 op=1266 msgID=1267 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=640 op=1266 msgID=1267 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=676 op=1279 msgID=1280 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=632 op=1266 msgID=1267 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=670 op=1282 msgID=1283 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=676 op=1279 msgID=1280 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=668 op=1266 msgID=1267 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=670 op=1282 msgID=1283 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=624 op=1270 msgID=1271 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=668 op=1266 msgID=1267 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=646 op=1325 msgID=1326 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=624 op=1270 msgID=1271 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=662 op=1267 msgID=1268 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=646 op=1325 msgID=1326 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=674 op=1306 msgID=1307 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=662 op=1267 msgID=1268 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=656 op=1331 msgID=1332 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=674 op=1306 msgID=1307 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=650 op=1266 msgID=1267 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=656 op=1331 msgID=1332 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=652 op=1266 msgID=1267 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=650 op=1266 msgID=1267 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=680 op=1266 msgID=1267 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=652 op=1266 msgID=1267 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=682 op=1266 msgID=1267 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=680 op=1266 msgID=1267 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=664 op=1282 msgID=1283 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=682 op=1266 msgID=1267 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=660 op=1326 msgID=1327 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=664 op=1282 msgID=1283 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=654 op=1360 msgID=1361 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=654 op=1360 msgID=1361 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=684 op=1282 msgID=1283 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=660 op=1326 msgID=1327 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=684 op=1282 msgID=1283 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=686 op=1386 msgID=1387 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=686 op=1386 msgID=1387 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=644 op=1266 msgID=1267 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=644 op=1266 msgID=1267 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=636 op=1266 msgID=1267 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=658 op=1266 msgID=1267 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=636 op=1266 msgID=1267 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=658 op=1266 msgID=1267 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=762 op=1398 msgID=1399 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=760 op=1274 msgID=1275 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=642 op=1266 msgID=1267 result=0 nentries=1 etime=5
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=762 op=1398 msgID=1399 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=760 op=1274 msgID=1275 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=663 op=1266 msgID=1267 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=643 op=1340 msgID=1341 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=639 op=1369 msgID=1370 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=681 op=1266 msgID=1267 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=639 op=1369 msgID=1370 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=681 op=1266 msgID=1267 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=685 op=1266 msgID=1267 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=647 op=1268 msgID=1269 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=643 op=1340 msgID=1341 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=647 op=1268 msgID=1269 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=667 op=1275 msgID=1276 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=655 op=1266 msgID=1267 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=685 op=1266 msgID=1267 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=655 op=1266 msgID=1267 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=683 op=1305 msgID=1306 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=667 op=1275 msgID=1276 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=649 op=1267 msgID=1268 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=683 op=1305 msgID=1306 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=631 op=1277 msgID=1278 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=651 op=1266 msgID=1267 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=631 op=1277 msgID=1278 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=677 op=1337 msgID=1338 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=651 op=1266 msgID=1267 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=633 op=1376 msgID=1377 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=677 op=1337 msgID=1338 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=653 op=1266 msgID=1267 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=633 op=1376 msgID=1377 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=671 op=1266 msgID=1267 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=649 op=1267 msgID=1268 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=653 op=1266 msgID=1267 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=641 op=1268 msgID=1269 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=671 op=1266 msgID=1267 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=661 op=1266 msgID=1267 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=665 op=1266 msgID=1267 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=661 op=1266 msgID=1267 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=675 op=1266 msgID=1267 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=641 op=1268 msgID=1269 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=629 op=1266 msgID=1267 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=665 op=1266 msgID=1267 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=645 op=1269 msgID=1270 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=629 op=1266 msgID=1267 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=659 op=1278 msgID=1279 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=675 op=1266 msgID=1267 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=657 op=1266 msgID=1267 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=659 op=1278 msgID=1279 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=771 op=1195 msgID=1196 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=645 op=1269 msgID=1270 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=657 op=1266 msgID=1267 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=771 op=1195 msgID=1196 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=687 op=1250 msgID=1251 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=637 op=1332 msgID=1333 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=669 op=1266 msgID=1267 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=637 op=1332 msgID=1333 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=673 op=1321 msgID=1322 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=669 op=1266 msgID=1267 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=679 op=1301 msgID=1302 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=687 op=1250 msgID=1251 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=673 op=1321 msgID=1322 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=663 op=1266 msgID=1267 result=0 nentries=1 etime=4
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=679 op=1301 msgID=1302 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:32 +0800] SEARCH REQ conn=763 op=1262 msgID=1263 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:32 +0800] SEARCH RES conn=763 op=1262 msgID=1263 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:33 +0800] SEARCH REQ conn=843 op=584 msgID=585 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:33 +0800] SEARCH RES conn=843 op=584 msgID=585 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=702 op=1263 msgID=1264 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=706 op=1263 msgID=1264 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=702 op=1263 msgID=1264 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=706 op=1263 msgID=1264 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=698 op=1263 msgID=1264 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=700 op=1303 msgID=1304 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=700 op=1303 msgID=1304 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=708 op=1280 msgID=1281 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=698 op=1263 msgID=1264 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=708 op=1280 msgID=1281 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=704 op=1265 msgID=1266 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=695 op=1263 msgID=1264 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=704 op=1265 msgID=1266 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=695 op=1263 msgID=1264 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=696 op=1263 msgID=1264 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=696 op=1263 msgID=1264 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=716 op=1303 msgID=1304 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=732 op=1263 msgID=1264 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=716 op=1303 msgID=1304 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=722 op=1263 msgID=1264 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=738 op=1329 msgID=1330 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=732 op=1263 msgID=1264 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=722 op=1263 msgID=1264 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=734 op=1263 msgID=1264 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=728 op=1263 msgID=1264 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=738 op=1329 msgID=1330 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=734 op=1263 msgID=1264 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=728 op=1263 msgID=1264 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=742 op=1263 msgID=1264 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=724 op=1263 msgID=1264 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=736 op=1263 msgID=1264 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=724 op=1263 msgID=1264 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=710 op=1347 msgID=1348 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=736 op=1263 msgID=1264 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=726 op=1266 msgID=1267 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=742 op=1263 msgID=1264 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=710 op=1347 msgID=1348 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=740 op=1309 msgID=1310 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=726 op=1266 msgID=1267 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=740 op=1309 msgID=1310 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=712 op=1263 msgID=1264 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=712 op=1263 msgID=1264 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=746 op=1263 msgID=1264 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=714 op=1263 msgID=1264 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=750 op=1263 msgID=1264 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=748 op=1376 msgID=1377 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=746 op=1263 msgID=1264 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=750 op=1263 msgID=1264 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=748 op=1376 msgID=1377 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=754 op=1263 msgID=1264 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=752 op=1264 msgID=1265 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=754 op=1263 msgID=1264 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=718 op=1265 msgID=1266 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=752 op=1264 msgID=1265 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=718 op=1265 msgID=1266 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=730 op=1316 msgID=1317 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=758 op=1263 msgID=1264 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=730 op=1316 msgID=1317 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=744 op=1313 msgID=1314 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=758 op=1263 msgID=1264 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=714 op=1263 msgID=1264 result=0 nentries=1 etime=2
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=744 op=1313 msgID=1314 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=720 op=1331 msgID=1332 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=720 op=1331 msgID=1332 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=839 op=616 msgID=617 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=756 op=1264 msgID=1265 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=839 op=616 msgID=617 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=751 op=1304 msgID=1305 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=756 op=1264 msgID=1265 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=723 op=1266 msgID=1267 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=755 op=1263 msgID=1264 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=751 op=1304 msgID=1305 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=699 op=1263 msgID=1264 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=723 op=1266 msgID=1267 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=717 op=1303 msgID=1304 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=711 op=1263 msgID=1264 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=699 op=1263 msgID=1264 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=838 op=617 msgID=618 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=717 op=1303 msgID=1304 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=703 op=1278 msgID=1279 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=711 op=1263 msgID=1264 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=727 op=1263 msgID=1264 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=838 op=617 msgID=618 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=747 op=1263 msgID=1264 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=727 op=1263 msgID=1264 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=757 op=1263 msgID=1264 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=703 op=1278 msgID=1279 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=747 op=1263 msgID=1264 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=697 op=1263 msgID=1264 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=705 op=1263 msgID=1264 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=757 op=1263 msgID=1264 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=753 op=1263 msgID=1264 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=705 op=1263 msgID=1264 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=749 op=1289 msgID=1290 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=697 op=1263 msgID=1264 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=759 op=1250 msgID=1251 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=759 op=1250 msgID=1251 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=753 op=1263 msgID=1264 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=749 op=1289 msgID=1290 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=735 op=1382 msgID=1383 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=713 op=1263 msgID=1264 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=719 op=1263 msgID=1264 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=735 op=1382 msgID=1383 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=725 op=1263 msgID=1264 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=719 op=1263 msgID=1264 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=707 op=1324 msgID=1325 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=713 op=1263 msgID=1264 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=721 op=1263 msgID=1264 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=707 op=1324 msgID=1325 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=701 op=1263 msgID=1264 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=725 op=1263 msgID=1264 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=733 op=1263 msgID=1264 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=701 op=1263 msgID=1264 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=739 op=1265 msgID=1266 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=721 op=1263 msgID=1264 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=737 op=1263 msgID=1264 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=739 op=1265 msgID=1266 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=709 op=1306 msgID=1307 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=733 op=1263 msgID=1264 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=745 op=1263 msgID=1264 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=709 op=1306 msgID=1307 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=743 op=1263 msgID=1264 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=737 op=1263 msgID=1264 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=745 op=1263 msgID=1264 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=741 op=1263 msgID=1264 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=743 op=1263 msgID=1264 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=729 op=1265 msgID=1266 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=715 op=1265 msgID=1266 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=741 op=1263 msgID=1264 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=731 op=1263 msgID=1264 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=729 op=1265 msgID=1266 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=715 op=1265 msgID=1266 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=731 op=1263 msgID=1264 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=755 op=1263 msgID=1264 result=0 nentries=1 etime=6
    [23/Dec/2016:17:00:38 +0800] SEARCH REQ conn=783 op=1737 msgID=1738 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:38 +0800] SEARCH REQ conn=777 op=1735 msgID=1736 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:38 +0800] SEARCH REQ conn=779 op=1734 msgID=1735 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:38 +0800] SEARCH RES conn=783 op=1737 msgID=1738 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:38 +0800] SEARCH RES conn=779 op=1734 msgID=1735 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:38 +0800] SEARCH RES conn=777 op=1735 msgID=1736 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:38 +0800] SEARCH REQ conn=781 op=1732 msgID=1733 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:38 +0800] SEARCH RES conn=781 op=1732 msgID=1733 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:38 +0800] SEARCH REQ conn=789 op=1731 msgID=1732 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:38 +0800] SEARCH REQ conn=787 op=1732 msgID=1733 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:38 +0800] SEARCH REQ conn=782 op=1735 msgID=1736 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:38 +0800] SEARCH RES conn=782 op=1735 msgID=1736 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:38 +0800] SEARCH RES conn=789 op=1731 msgID=1732 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:38 +0800] SEARCH REQ conn=785 op=1730 msgID=1731 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:38 +0800] SEARCH REQ conn=772 op=1754 msgID=1755 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:38 +0800] SEARCH RES conn=787 op=1732 msgID=1733 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:38 +0800] SEARCH REQ conn=778 op=1736 msgID=1737 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:38 +0800] SEARCH RES conn=772 op=1754 msgID=1755 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:38 +0800] SEARCH REQ conn=776 op=1734 msgID=1735 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:38 +0800] SEARCH RES conn=785 op=1730 msgID=1731 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:38 +0800] SEARCH RES conn=778 op=1736 msgID=1737 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:38 +0800] SEARCH RES conn=776 op=1734 msgID=1735 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:38 +0800] SEARCH REQ conn=773 op=1195 msgID=1196 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:38 +0800] SEARCH RES conn=773 op=1195 msgID=1196 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:38 +0800] SEARCH REQ conn=788 op=1731 msgID=1732 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:38 +0800] SEARCH REQ conn=780 op=1737 msgID=1738 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:38 +0800] SEARCH RES conn=788 op=1731 msgID=1732 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:38 +0800] SEARCH REQ conn=786 op=1732 msgID=1733 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:38 +0800] SEARCH REQ conn=790 op=1731 msgID=1732 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:38 +0800] SEARCH REQ conn=784 op=1735 msgID=1736 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:38 +0800] SEARCH RES conn=786 op=1732 msgID=1733 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:38 +0800] SEARCH REQ conn=764 op=1259 msgID=1260 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:38 +0800] SEARCH RES conn=790 op=1731 msgID=1732 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:38 +0800] SEARCH RES conn=764 op=1259 msgID=1260 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:38 +0800] SEARCH RES conn=784 op=1735 msgID=1736 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:38 +0800] SEARCH RES conn=780 op=1737 msgID=1738 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:40 +0800] SEARCH REQ conn=693 op=311 msgID=312 base="ou=default,ou=default,ou=OrganizationConfig,ou=1.0,ou=sunEntitlementIndexes,ou=services,o=sunamhiddenrealmdelegationservicepermissions,ou=services,dc=osdp,dc=ccgo,dc=hksarg" scope=sub filter="(&(|(sunxmlKeyValue=hostindex=://dc=osdp,dc=ccgo,dc=hksarg)(sunxmlKeyValue=hostindex=://))(|(sunxmlKeyValue=pathindex=/iplanetamloggingservice/1.0/application)(sunxmlKeyValue=pathindex=/iplanetamloggingservice)(sunxmlKeyValue=pathindex=/iplanetamloggingservice/1.0)(sunxmlKeyValue=pathindex=/iplanetamloggingservice/1.0/application/logwrite)(sunxmlKeyValue=pathindex=/)))" attrs="sunKeyValue,sunxmlKeyValue"
    [23/Dec/2016:17:00:40 +0800] SEARCH REQ conn=621 op=553 msgID=554 base="ou=default,ou=default,ou=OrganizationConfig,ou=1.0,ou=sunEntitlementIndexes,ou=services,o=sunamhiddenrealmdelegationservicepermissions,ou=services,dc=osdp,dc=ccgo,dc=hksarg" scope=sub filter="(&(|(sunxmlKeyValue=hostindex=://dc=osdp,dc=ccgo,dc=hksarg)(sunxmlKeyValue=hostindex=://))(|(sunxmlKeyValue=pathindex=/iplanetamloggingservice/1.0/application)(sunxmlKeyValue=pathindex=/iplanetamloggingservice)(sunxmlKeyValue=pathindex=/iplanetamloggingservice/1.0)(sunxmlKeyValue=pathindex=/iplanetamloggingservice/1.0/application/logwrite)(sunxmlKeyValue=pathindex=/)))" attrs="sunKeyValue,sunxmlKeyValue"
    [23/Dec/2016:17:00:40 +0800] SEARCH RES conn=621 op=553 msgID=554 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:40 +0800] SEARCH RES conn=693 op=311 msgID=312 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:40 +0800] SEARCH REQ conn=794 op=228 msgID=229 base="ou=default,ou=default,ou=OrganizationConfig,ou=1.0,ou=sunEntitlementIndexes,ou=services,o=sunamhiddenrealmdelegationservicepermissions,ou=services,dc=osdp,dc=ccgo,dc=hksarg" scope=sub filter="(&(|(sunxmlKeyValue=hostindex=://dc=osdp,dc=ccgo,dc=hksarg)(sunxmlKeyValue=hostindex=://))(|(sunxmlKeyValue=pathindex=/iplanetamloggingservice/1.0/application)(sunxmlKeyValue=pathindex=/iplanetamloggingservice)(sunxmlKeyValue=pathindex=/iplanetamloggingservice/1.0)(sunxmlKeyValue=pathindex=/iplanetamloggingservice/1.0/application/logwrite)(sunxmlKeyValue=pathindex=/)))" attrs="sunKeyValue,sunxmlKeyValue"
    [23/Dec/2016:17:00:40 +0800] SEARCH RES conn=794 op=228 msgID=229 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=625 op=1267 msgID=1268 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=642 op=1267 msgID=1268 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=625 op=1267 msgID=1268 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=626 op=1267 msgID=1268 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=642 op=1267 msgID=1268 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=640 op=1267 msgID=1268 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=626 op=1267 msgID=1268 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=623 op=1267 msgID=1268 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=640 op=1267 msgID=1268 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=678 op=1267 msgID=1268 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=623 op=1267 msgID=1268 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=648 op=1336 msgID=1337 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=678 op=1267 msgID=1268 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=648 op=1336 msgID=1337 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=632 op=1267 msgID=1268 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=638 op=1267 msgID=1268 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=632 op=1267 msgID=1268 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=666 op=1267 msgID=1268 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=638 op=1267 msgID=1268 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=666 op=1267 msgID=1268 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=672 op=1664 msgID=1665 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=676 op=1280 msgID=1281 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=670 op=1283 msgID=1284 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=672 op=1664 msgID=1665 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=668 op=1267 msgID=1268 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=676 op=1280 msgID=1281 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=656 op=1332 msgID=1333 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=670 op=1283 msgID=1284 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=650 op=1267 msgID=1268 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=656 op=1332 msgID=1333 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=628 op=1366 msgID=1367 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=668 op=1267 msgID=1268 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=634 op=1267 msgID=1268 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=628 op=1366 msgID=1367 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=630 op=1319 msgID=1320 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=650 op=1267 msgID=1268 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=654 op=1361 msgID=1362 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=630 op=1319 msgID=1320 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=684 op=1283 msgID=1284 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=634 op=1267 msgID=1268 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=654 op=1361 msgID=1362 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=658 op=1267 msgID=1268 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=684 op=1283 msgID=1284 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=624 op=1271 msgID=1272 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=624 op=1271 msgID=1272 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=658 op=1267 msgID=1268 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=646 op=1326 msgID=1327 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=646 op=1326 msgID=1327 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=627 op=1267 msgID=1268 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=627 op=1267 msgID=1268 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=631 op=1278 msgID=1279 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=631 op=1278 msgID=1279 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=633 op=1377 msgID=1378 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=635 op=1267 msgID=1268 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=633 op=1377 msgID=1378 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=635 op=1267 msgID=1268 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=662 op=1268 msgID=1269 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=662 op=1268 msgID=1269 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=674 op=1307 msgID=1308 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=674 op=1307 msgID=1308 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=652 op=1267 msgID=1268 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=652 op=1267 msgID=1268 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=680 op=1267 msgID=1268 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=682 op=1267 msgID=1268 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=664 op=1283 msgID=1284 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=680 op=1267 msgID=1268 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=664 op=1283 msgID=1284 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=660 op=1327 msgID=1328 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=686 op=1387 msgID=1388 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=682 op=1267 msgID=1268 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=762 op=1399 msgID=1400 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=686 op=1387 msgID=1388 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=760 op=1275 msgID=1276 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=660 op=1327 msgID=1328 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=762 op=1399 msgID=1400 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=644 op=1267 msgID=1268 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=636 op=1267 msgID=1268 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=760 op=1275 msgID=1276 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=636 op=1267 msgID=1268 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=644 op=1267 msgID=1268 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=663 op=1267 msgID=1268 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=663 op=1267 msgID=1268 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=665 op=1267 msgID=1268 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=679 op=1302 msgID=1303 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=643 op=1341 msgID=1342 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=665 op=1267 msgID=1268 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=639 op=1370 msgID=1371 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=643 op=1341 msgID=1342 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=639 op=1370 msgID=1371 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=681 op=1267 msgID=1268 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=685 op=1267 msgID=1268 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=681 op=1267 msgID=1268 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=675 op=1267 msgID=1268 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=685 op=1267 msgID=1268 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=629 op=1267 msgID=1268 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=675 op=1267 msgID=1268 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=647 op=1269 msgID=1270 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=629 op=1267 msgID=1268 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=667 op=1276 msgID=1277 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=647 op=1269 msgID=1270 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=655 op=1267 msgID=1268 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=679 op=1302 msgID=1303 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=667 op=1276 msgID=1277 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=683 op=1306 msgID=1307 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=655 op=1267 msgID=1268 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=645 op=1270 msgID=1271 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=683 op=1306 msgID=1307 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=645 op=1270 msgID=1271 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=649 op=1268 msgID=1269 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=659 op=1279 msgID=1280 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=649 op=1268 msgID=1269 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=651 op=1267 msgID=1268 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=659 op=1279 msgID=1280 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=657 op=1267 msgID=1268 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=651 op=1267 msgID=1268 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=771 op=1196 msgID=1197 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=771 op=1196 msgID=1197 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=677 op=1338 msgID=1339 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=657 op=1267 msgID=1268 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=687 op=1251 msgID=1252 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=677 op=1338 msgID=1339 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=653 op=1267 msgID=1268 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=687 op=1251 msgID=1252 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=671 op=1267 msgID=1268 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=653 op=1267 msgID=1268 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=671 op=1267 msgID=1268 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=637 op=1333 msgID=1334 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=641 op=1269 msgID=1270 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=669 op=1267 msgID=1268 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=637 op=1333 msgID=1334 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=661 op=1267 msgID=1268 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=641 op=1269 msgID=1270 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=661 op=1267 msgID=1268 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=673 op=1322 msgID=1323 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=673 op=1322 msgID=1323 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:41 +0800] SEARCH RES conn=669 op=1267 msgID=1268 result=0 nentries=1 etime=1
    <strong><em>[23/Dec/2016:17:00:42 +0800] MODIFY RES conn=877 op=110 msgID=111 result=0 etime=13534</em></strong>
    [23/Dec/2016:17:00:42 +0800] SEARCH REQ conn=877 op=111 msgID=112 base="ou=people,ou=users,o=example,dc=com,dc=hk" scope=sub filter="(uniqueMember=uid=test2016122310,ou=people,ou=users,o=example,dc=com,dc=hk)" attrs="1.1"
    [23/Dec/2016:17:00:42 +0800] SEARCH RES conn=877 op=111 msgID=112 result=0 nentries=0 etime=1
    [23/Dec/2016:17:00:42 +0800] SEARCH REQ conn=877 op=112 msgID=113 base="uid=test2016122310,ou=people,ou=users,o=example,dc=com,dc=hk" scope=base filter="(objectclass=*)" attrs="ALL"
    [23/Dec/2016:17:00:42 +0800] SEARCH RES conn=877 op=112 msgID=113 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:42 +0800] SEARCH REQ conn=877 op=113 msgID=114 base="" scope=base filter="(objectClass=*)" attrs="subschemaSubentry"
    [23/Dec/2016:17:00:42 +0800] SEARCH RES conn=877 op=113 msgID=114 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:42 +0800] SEARCH REQ conn=877 op=114 msgID=115 base="uid=test2016122310,ou=people,ou=users,o=example,dc=com,dc=hk" scope=base filter="(&(objectClass=top)(objectClass=person)(objectClass=organizationalPerson)(objectClass=inetOrgPerson)(objectClass=inetuser)(objectClass=dpPerson))" attrs="cn,description,DPDEPTID,dphkid,DPRANKCODE,dpstaffgroup,ds-pwp-account-disabled,employeeType,givenName,inetUserStatus,mail,sn,telephoneNumber,uid"
    [23/Dec/2016:17:00:42 +0800] SEARCH RES conn=877 op=114 msgID=115 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:42 +0800] SEARCH REQ conn=877 op=115 msgID=116 base="ou=people,ou=users,o=example,dc=com,dc=hk" scope=sub filter="(uniqueMember=uid=test2016122310,ou=people,ou=users,o=example,dc=com,dc=hk)" attrs="1.1"
    [23/Dec/2016:17:00:42 +0800] SEARCH RES conn=877 op=115 msgID=116 result=0 nentries=0 etime=0
    [23/Dec/2016:17:00:42 +0800] SEARCH REQ conn=763 op=1263 msgID=1264 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:42 +0800] SEARCH RES conn=763 op=1263 msgID=1264 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:43 +0800] SEARCH REQ conn=843 op=585 msgID=586 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:43 +0800] SEARCH RES conn=843 op=585 msgID=586 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=697 op=1264 msgID=1265 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=700 op=1304 msgID=1305 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=706 op=1264 msgID=1265 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=697 op=1264 msgID=1265 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=708 op=1281 msgID=1282 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=700 op=1304 msgID=1305 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=698 op=1264 msgID=1265 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=708 op=1281 msgID=1282 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=704 op=1266 msgID=1267 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=698 op=1264 msgID=1265 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=704 op=1266 msgID=1267 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=695 op=1264 msgID=1265 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=706 op=1264 msgID=1265 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=695 op=1264 msgID=1265 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=702 op=1264 msgID=1265 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=702 op=1264 msgID=1265 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=696 op=1264 msgID=1265 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=696 op=1264 msgID=1265 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=699 op=1264 msgID=1265 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=699 op=1264 msgID=1265 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=717 op=1304 msgID=1305 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=732 op=1264 msgID=1265 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=717 op=1304 msgID=1305 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=703 op=1279 msgID=1280 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=727 op=1264 msgID=1265 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=727 op=1264 msgID=1265 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=705 op=1264 msgID=1265 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=703 op=1279 msgID=1280 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=707 op=1325 msgID=1326 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=705 op=1264 msgID=1265 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=721 op=1264 msgID=1265 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=707 op=1325 msgID=1326 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=709 op=1307 msgID=1308 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=709 op=1307 msgID=1308 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=723 op=1267 msgID=1268 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=721 op=1264 msgID=1265 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=711 op=1264 msgID=1265 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=723 op=1267 msgID=1268 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=735 op=1383 msgID=1384 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=711 op=1264 msgID=1265 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=735 op=1383 msgID=1384 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=713 op=1264 msgID=1265 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=719 op=1264 msgID=1265 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=713 op=1264 msgID=1265 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=725 op=1264 msgID=1265 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=701 op=1264 msgID=1265 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=719 op=1264 msgID=1265 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=725 op=1264 msgID=1265 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=701 op=1264 msgID=1265 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=733 op=1264 msgID=1265 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=739 op=1266 msgID=1267 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=737 op=1264 msgID=1265 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=739 op=1266 msgID=1267 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=733 op=1264 msgID=1265 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=729 op=1266 msgID=1267 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=731 op=1264 msgID=1265 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=729 op=1266 msgID=1267 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=741 op=1264 msgID=1265 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=737 op=1264 msgID=1265 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=731 op=1264 msgID=1265 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=743 op=1264 msgID=1265 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=741 op=1264 msgID=1265 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=715 op=1266 msgID=1267 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=728 op=1264 msgID=1265 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=743 op=1264 msgID=1265 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=728 op=1264 msgID=1265 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=716 op=1304 msgID=1305 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=722 op=1264 msgID=1265 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=715 op=1266 msgID=1267 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=738 op=1330 msgID=1331 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=722 op=1264 msgID=1265 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=716 op=1304 msgID=1305 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=738 op=1330 msgID=1331 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=734 op=1264 msgID=1265 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=748 op=1377 msgID=1378 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=740 op=1310 msgID=1311 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=734 op=1264 msgID=1265 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=740 op=1310 msgID=1311 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=748 op=1377 msgID=1378 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=720 op=1332 msgID=1333 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=742 op=1264 msgID=1265 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=724 op=1264 msgID=1265 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=742 op=1264 msgID=1265 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=710 op=1348 msgID=1349 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=724 op=1264 msgID=1265 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=714 op=1264 msgID=1265 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=720 op=1332 msgID=1333 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=736 op=1264 msgID=1265 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=714 op=1264 msgID=1265 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=750 op=1264 msgID=1265 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=710 op=1348 msgID=1349 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=746 op=1264 msgID=1265 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=750 op=1264 msgID=1265 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=736 op=1264 msgID=1265 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=726 op=1267 msgID=1268 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=746 op=1264 msgID=1265 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=712 op=1264 msgID=1265 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=754 op=1264 msgID=1265 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=726 op=1267 msgID=1268 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=718 op=1266 msgID=1267 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=712 op=1264 msgID=1265 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=730 op=1317 msgID=1318 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=754 op=1264 msgID=1265 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=718 op=1266 msgID=1267 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=751 op=1305 msgID=1306 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=730 op=1317 msgID=1318 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=749 op=1290 msgID=1291 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=747 op=1264 msgID=1265 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=751 op=1305 msgID=1306 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=747 op=1264 msgID=1265 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=752 op=1265 msgID=1266 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=749 op=1290 msgID=1291 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=752 op=1265 msgID=1266 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=839 op=617 msgID=618 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=758 op=1264 msgID=1265 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=756 op=1265 msgID=1266 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=839 op=617 msgID=618 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=758 op=1264 msgID=1265 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=755 op=1264 msgID=1265 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=744 op=1314 msgID=1315 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=756 op=1265 msgID=1266 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=838 op=618 msgID=619 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=755 op=1264 msgID=1265 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=757 op=1264 msgID=1265 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=744 op=1314 msgID=1315 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=753 op=1264 msgID=1265 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=838 op=618 msgID=619 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=745 op=1264 msgID=1265 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=757 op=1264 msgID=1265 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=753 op=1264 msgID=1265 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=745 op=1264 msgID=1265 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=732 op=1264 msgID=1265 result=0 nentries=1 etime=9

    The MODIFY action run from 17:00:28 to 17:00:42. I guess it should be the point of delay. Do I conclude the fluctuating performance is originated in OpenDJ side instead of OpenIDM?

    Thanks!

    #15060
     Bill Nelson
    Participant

    The following log entry in OpenDJ demonstrates the MOD operation that you are referring to:

    [23/Dec/2016:17:00:42 +0800] MODIFY RES conn=877 op=110 msgID=111 result=0 etime=13534

    It took roughly 13 seconds to run and yes, that is a bit much. I scanned through the logs to see all operations associated with that connection and found the following:

    [23/Dec/2016:17:00:28 +0800] SEARCH REQ conn=877 op=102 msgID=103 base="" scope=base filter="(objectClass=*)" attrs="subschemaSubentry"
    [23/Dec/2016:17:00:28 +0800] SEARCH RES conn=877 op=102 msgID=103 result=0 nentries=1 etime=0
    [23/Dec/2016:17:00:28 +0800] SEARCH REQ conn=877 op=103 msgID=104 base="uid=test2016122310,ou=people,ou=users,o=example,dc=com,dc=hk" scope=base filter="(&(objectClass=top)(objectClass=person)(objectClass=organizationalPerson)(objectClass=inetOrgPerson)(objectClass=inetuser)(objectClass=dpPerson))" attrs="cn,description,DPDEPTID,dphkid,DPRANKCODE,dpstaffgroup,ds-pwp-account-disabled,employeeType,givenName,inetUserStatus,mail,sn,telephoneNumber,uid"
    [23/Dec/2016:17:00:28 +0800] SEARCH RES conn=877 op=103 msgID=104 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:28 +0800] SEARCH REQ conn=877 op=104 msgID=105 base="ou=people,ou=users,o=example,dc=com,dc=hk" scope=sub filter="(uniqueMember=uid=test2016122310,ou=people,ou=users,o=example,dc=com,dc=hk)" attrs="1.1"
    [23/Dec/2016:17:00:28 +0800] SEARCH RES conn=877 op=104 msgID=105 result=0 nentries=0 etime=0
    [23/Dec/2016:17:00:28 +0800] SEARCH REQ conn=877 op=105 msgID=106 base="" scope=base filter="(objectClass=*)" attrs="subschemaSubentry"
    [23/Dec/2016:17:00:28 +0800] SEARCH RES conn=877 op=105 msgID=106 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:28 +0800] SEARCH REQ conn=877 op=106 msgID=107 base="uid=test2016122310,ou=people,ou=users,o=example,dc=com,dc=hk" scope=base filter="(&(objectClass=top)(objectClass=person)(objectClass=organizationalPerson)(objectClass=inetOrgPerson)(objectClass=inetuser)(objectClass=dpPerson))" attrs="cn,description,DPDEPTID,dphkid,DPRANKCODE,dpstaffgroup,ds-pwp-account-disabled,employeeType,givenName,inetUserStatus,mail,sn,telephoneNumber,uid"
    [23/Dec/2016:17:00:28 +0800] SEARCH RES conn=877 op=106 msgID=107 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:28 +0800] SEARCH REQ conn=877 op=107 msgID=108 base="ou=people,ou=users,o=example,dc=com,dc=hk" scope=sub filter="(uniqueMember=uid=test2016122310,ou=people,ou=users,o=example,dc=com,dc=hk)" attrs="1.1"
    [23/Dec/2016:17:00:28 +0800] SEARCH RES conn=877 op=107 msgID=108 result=0 nentries=0 etime=0
    [23/Dec/2016:17:00:28 +0800] SEARCH REQ conn=877 op=108 msgID=109 base="" scope=base filter="(objectClass=*)" attrs="subschemaSubentry"
    [23/Dec/2016:17:00:28 +0800] SEARCH RES conn=877 op=108 msgID=109 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:28 +0800] SEARCH REQ conn=877 op=109 msgID=110 base="uid=test2016122310,ou=people,ou=users,o=example,dc=com,dc=hk" scope=base filter="(&(objectClass=top)(objectClass=person)(objectClass=organizationalPerson)(objectClass=inetOrgPerson)(objectClass=inetuser)(objectClass=dpPerson))" attrs="1.1"
    [23/Dec/2016:17:00:28 +0800] SEARCH RES conn=877 op=109 msgID=110 result=0 nentries=1 etime=0
    
    [23/Dec/2016:17:00:28 +0800] MODIFY REQ conn=877 op=110 msgID=111 dn="uid=test2016122310,ou=people,ou=users,o=example,dc=com,dc=hk"
    [23/Dec/2016:17:00:42 +0800] MODIFY RES conn=877 op=110 msgID=111 result=0 etime=13534
    
    [23/Dec/2016:17:00:42 +0800] SEARCH REQ conn=877 op=111 msgID=112 base="ou=people,ou=users,o=example,dc=com,dc=hk" scope=sub filter="(uniqueMember=uid=test2016122310,ou=people,ou=users,o=example,dc=com,dc=hk)" attrs="1.1"
    [23/Dec/2016:17:00:42 +0800] SEARCH RES conn=877 op=111 msgID=112 result=0 nentries=0 etime=1
    [23/Dec/2016:17:00:42 +0800] SEARCH REQ conn=877 op=112 msgID=113 base="uid=test2016122310,ou=people,ou=users,o=example,dc=com,dc=hk" scope=base filter="(objectclass=*)" attrs="ALL"
    [23/Dec/2016:17:00:42 +0800] SEARCH RES conn=877 op=112 msgID=113 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:42 +0800] SEARCH REQ conn=877 op=113 msgID=114 base="" scope=base filter="(objectClass=*)" attrs="subschemaSubentry"
    [23/Dec/2016:17:00:42 +0800] SEARCH RES conn=877 op=113 msgID=114 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:42 +0800] SEARCH REQ conn=877 op=114 msgID=115 base="uid=test2016122310,ou=people,ou=users,o=example,dc=com,dc=hk" scope=base filter="(&(objectClass=top)(objectClass=person)(objectClass=organizationalPerson)(objectClass=inetOrgPerson)(objectClass=inetuser)(objectClass=dpPerson))" attrs="cn,description,DPDEPTID,dphkid,DPRANKCODE,dpstaffgroup,ds-pwp-account-disabled,employeeType,givenName,inetUserStatus,mail,sn,telephoneNumber,uid"
    [23/Dec/2016:17:00:42 +0800] SEARCH RES conn=877 op=114 msgID=115 result=0 nentries=1 etime=1
    [23/Dec/2016:17:00:42 +0800] SEARCH REQ conn=877 op=115 msgID=116 base="ou=people,ou=users,o=example,dc=com,dc=hk" scope=sub filter="(uniqueMember=uid=test2016122310,ou=people,ou=users,o=example,dc=com,dc=hk)" attrs="1.1"
    [23/Dec/2016:17:00:42 +0800] SEARCH RES conn=877 op=115 msgID=116 result=0 nentries=0 etime=0

    For the most part, all other responses had relatively fine elapsed times (etime) with the exception of the following (I have grouped them together for ease of reading):

    [23/Dec/2016:17:00:41 +0800] SEARCH REQ conn=642 op=1267 msgID=1268 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=642 op=1266 msgID=1267 result=0 nentries=1 etime=5
    [23/Dec/2016:17:00:31 +0800] SEARCH REQ conn=663 op=1266 msgID=1267 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:31 +0800] SEARCH RES conn=663 op=1266 msgID=1267 result=0 nentries=1 etime=4
    [23/Dec/2016:17:00:34 +0800] SEARCH REQ conn=755 op=1263 msgID=1264 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:34 +0800] SEARCH RES conn=755 op=1263 msgID=1264 result=0 nentries=1 etime=6
    [23/Dec/2016:17:00:44 +0800] SEARCH REQ conn=732 op=1264 msgID=1265 base="" scope=base filter="(objectClass=*)" attrs="1.1"
    [23/Dec/2016:17:00:44 +0800] SEARCH RES conn=732 op=1264 msgID=1265 result=0 nentries=1 etime=9

    I typically like to see response times for searches to be less than 4 unless there is a really good reason for it, but in your case, I only see a few of these > 4 and in all cases the filter was objectclass=*. The interesting thing is that not all searches with this filter take a long time; most of them are in the 0 to 1 range which is perfectly acceptable.

    So looking closer at OpenDJ is a reasonable option at this point and you need to find out why your write option is taking so long. Are you running in “Safe Read” mode on OpenDJ? Are you rotating log files right at this time? Are you rebuilding massive indexes at that time? I don’t see any evidence of any of this in what you have presented but I have to ask.

    What version of OpenDJ are you running? And if it the latest, what database backend did you choose (JE or PDB)? Are your DBs located on the same host as your OpenDJ software (Note: OpenDJ DBs are not supported in a SAN or NAS environment). How much RAM have you configured in OpenDJ’s backend? Are you using the Default Password Policy or a modified version? What is your password policy? Have you modified it with your own custom validators? Are there any “interesting” entries in your server.out log? Ultimately you need to find out where the bottleneck is on OpenDJ as that will definitely cause you issues down the road.

    On a different note, one thing I noted in your OpenIDM account synch filter (in your provisioner file) was the following:

    "accountSynchronizationFilter" : "(&(objectClass=top)(objectClass=person)(objectClass=inetOrgPerson)(objectClass=organizationalPerson)(objectClass=inetuser)(objectClass=dpPerson))",

    This is not your problem, but you can easily get away with just setting this to “(&(objectClass=inetuser)(objectClass=dpPerson))” – assuming of course that dpPerson is a structural object class that has extended inetOrgPerson.

    • This reply was modified 5 years, 6 months ago by Bill Nelson.
Viewing 4 posts - 1 through 4 (of 4 total)

You must be logged in to reply to this topic.

©2022 ForgeRock - we provide an identity and access platform to secure every online relationship for the enterprise market, educational sector and even entire countries. Click to view our privacy policy and terms of use.

Log in with your credentials

Forgot your details?