[27/Jun/2013:13:23:56 +0200] - --> pagedresults_is_timedout [27/Jun/2013:13:23:56 +0200] - <-- pagedresults_is_timedout: - [27/Jun/2013:13:23:56 +0200] - --> pagedresults_is_timedout [27/Jun/2013:13:23:56 +0200] - <-- pagedresults_is_timedout: - [27/Jun/2013:13:23:56 +0200] - --> pagedresults_is_timedout [27/Jun/2013:13:23:56 +0200] - <-- pagedresults_is_timedout: - [27/Jun/2013:13:23:56 +0200] - --> pagedresults_is_timedout [27/Jun/2013:13:23:56 +0200] - <-- pagedresults_is_timedout: - [27/Jun/2013:13:23:57 +0200] - --> pagedresults_is_timedout [27/Jun/2013:13:23:57 +0200] - <-- pagedresults_is_timedout: - [27/Jun/2013:13:23:57 +0200] - activity on 64r [27/Jun/2013:13:23:57 +0200] - read activity on 64 [27/Jun/2013:13:23:57 +0200] - add_pb [27/Jun/2013:13:23:57 +0200] - get_pb [27/Jun/2013:13:23:57 +0200] - conn 5287 activity level = 7 [27/Jun/2013:13:23:57 +0200] - --> pagedresults_in_use [27/Jun/2013:13:23:57 +0200] - <-- pagedresults_in_use: 0 [27/Jun/2013:13:23:57 +0200] - conn 5287 turbo rank = 0 out of 1 conns [27/Jun/2013:13:23:57 +0200] - conn 5287 entering turbo mode [27/Jun/2013:13:23:57 +0200] - do_unbind [27/Jun/2013:13:23:57 +0200] - => get_ldapmessage_controls [27/Jun/2013:13:23:57 +0200] - <= get_ldapmessage_controls no controls [27/Jun/2013:13:23:57 +0200] - defbackend_noop [27/Jun/2013:13:23:57 +0200] - --> pagedresults_reset_timedout [27/Jun/2013:13:23:57 +0200] - <-- pagedresults_reset_timedout: 0 [27/Jun/2013:13:23:57 +0200] - conn 5287 leaving turbo mode due to 3 [27/Jun/2013:13:23:57 +0200] - listener got signaled [27/Jun/2013:13:23:57 +0200] - => reslimit_update_from_entry() conn=0xc81f2e10, entry=0x0 [27/Jun/2013:13:23:57 +0200] - reslimit_update_from_entry(): setting limit for handle 0 (based on nsLookThroughLimit) [27/Jun/2013:13:23:57 +0200] - reslimit_update_from_entry(): setting limit for handle 1 (based on nsIDListScanLimit) [27/Jun/2013:13:23:57 +0200] - reslimit_update_from_entry(): setting limit for handle 2 (based on nsPagedLookThroughLimit) [27/Jun/2013:13:23:57 +0200] - reslimit_update_from_entry(): setting limit for handle 3 (based on nsPagedIDListScanLimit) [27/Jun/2013:13:23:57 +0200] - reslimit_update_from_entry(): setting limit for handle 4 (based on nsRangeSearchLookThroughLimit) [27/Jun/2013:13:23:57 +0200] - reslimit_update_from_entry(): setting limit for handle 5 (based on nsSizeLimit) [27/Jun/2013:13:23:57 +0200] - reslimit_update_from_entry(): setting limit for handle 6 (based on nsTimeLimit) [27/Jun/2013:13:23:57 +0200] - reslimit_update_from_entry(): setting limit for handle 7 (based on nsPagedSizeLimit) [27/Jun/2013:13:23:57 +0200] - reslimit_update_from_entry(): setting limit for handle 8 (based on nsIdleTimeout) [27/Jun/2013:13:23:57 +0200] - <= reslimit_update_from_entry() returning status 0 [27/Jun/2013:13:23:57 +0200] - --> pagedresults_cleanup [27/Jun/2013:13:23:57 +0200] - <-- pagedresults_cleanup: 0 [27/Jun/2013:13:24:05 +0200] - Event id 11ef230 called at 1372332245 (scheduled for 1372332245) [27/Jun/2013:13:24:12 +0200] - Event id 7fb82000f770 called at 1372332252 (scheduled for 1372332252) [27/Jun/2013:13:24:15 +0200] - ldbm backend flushing [27/Jun/2013:13:24:15 +0200] - ldbm backend done flushing [27/Jun/2013:13:24:15 +0200] - Event id 11ef230 called at 1372332255 (scheduled for 1372332255) [27/Jun/2013:13:24:25 +0200] - Event id 10e16a0 called at 1372332265 (scheduled for 1372332265) [27/Jun/2013:13:24:25 +0200] - Event id 11ef230 called at 1372332265 (scheduled for 1372332265) [27/Jun/2013:13:24:35 +0200] - Event id 11ef230 called at 1372332275 (scheduled for 1372332275) [27/Jun/2013:13:24:42 +0200] - Event id 7fb82000f770 called at 1372332282 (scheduled for 1372332282) [27/Jun/2013:13:24:45 +0200] - ldbm backend flushing [27/Jun/2013:13:24:45 +0200] - ldbm backend done flushing [27/Jun/2013:13:24:45 +0200] - Event id 11ef230 called at 1372332285 (scheduled for 1372332285) [27/Jun/2013:13:24:55 +0200] - Event id 11ef230 called at 1372332295 (scheduled for 1372332295) [27/Jun/2013:13:25:05 +0200] - Event id 11ef230 called at 1372332305 (scheduled for 1372332305) [27/Jun/2013:13:25:12 +0200] - Event id 7fb82000f770 called at 1372332312 (scheduled for 1372332312) [27/Jun/2013:13:25:15 +0200] - ldbm backend flushing [27/Jun/2013:13:25:15 +0200] - ldbm backend done flushing [27/Jun/2013:13:25:15 +0200] - Event id 11ef230 called at 1372332315 (scheduled for 1372332315) [27/Jun/2013:13:25:25 +0200] - Event id 10e16a0 called at 1372332325 (scheduled for 1372332325) [27/Jun/2013:13:25:25 +0200] - Event id 11ef230 called at 1372332325 (scheduled for 1372332325) [27/Jun/2013:13:25:35 +0200] - Event id 11ef230 called at 1372332335 (scheduled for 1372332335) [27/Jun/2013:13:25:42 +0200] - Event id 7fb82000f770 called at 1372332342 (scheduled for 1372332342) [27/Jun/2013:13:25:45 +0200] - ldbm backend flushing [27/Jun/2013:13:25:45 +0200] - ldbm backend done flushing [27/Jun/2013:13:25:45 +0200] - Event id 11ef230 called at 1372332345 (scheduled for 1372332345) [27/Jun/2013:13:25:55 +0200] - Event id 11ef230 called at 1372332355 (scheduled for 1372332355) [27/Jun/2013:13:25:55 +0200] - --> pagedresults_cleanup [27/Jun/2013:13:25:55 +0200] - <-- pagedresults_cleanup: 0 [27/Jun/2013:13:25:55 +0200] - new SSL connection on 64 [27/Jun/2013:13:25:55 +0200] - => ids_sasl_server_new (CONSUMER_TWO.COMPANY.si) [27/Jun/2013:13:25:55 +0200] - ids_sasl_getopt: plugin= option=log_level [27/Jun/2013:13:25:55 +0200] - ids_sasl_getopt: plugin= option=auto_transition [27/Jun/2013:13:25:55 +0200] - <= ids_sasl_server_new [27/Jun/2013:13:25:55 +0200] - => slapi_reslimit_get_integer_limit() conn=0xc81f2e10, handle=8 [27/Jun/2013:13:25:55 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:25:55 +0200] - --> pagedresults_is_timedout [27/Jun/2013:13:25:55 +0200] - <-- pagedresults_is_timedout: - [27/Jun/2013:13:25:55 +0200] - activity on 64r [27/Jun/2013:13:25:55 +0200] - read activity on 64 [27/Jun/2013:13:25:55 +0200] - add_pb [27/Jun/2013:13:25:55 +0200] - get_pb [27/Jun/2013:13:25:55 +0200] - PR_Recv for connection 5288 returns -5938 (Encountered end of file.) [27/Jun/2013:13:25:55 +0200] - --> pagedresults_reset_timedout [27/Jun/2013:13:25:55 +0200] - <-- pagedresults_reset_timedout: 0 [27/Jun/2013:13:25:56 +0200] - conn 5288 activity level = 0 [27/Jun/2013:13:25:56 +0200] - --> pagedresults_in_use [27/Jun/2013:13:25:56 +0200] - <-- pagedresults_in_use: 0 [27/Jun/2013:13:25:56 +0200] - conn 5288 leaving turbo mode due to 3 [27/Jun/2013:13:25:56 +0200] - listener got signaled [27/Jun/2013:13:25:56 +0200] - --> pagedresults_cleanup [27/Jun/2013:13:25:56 +0200] - <-- pagedresults_cleanup: 0 [27/Jun/2013:13:25:56 +0200] - --> pagedresults_cleanup [27/Jun/2013:13:25:56 +0200] - <-- pagedresults_cleanup: 0 [27/Jun/2013:13:25:56 +0200] - new connection on 64 [27/Jun/2013:13:25:56 +0200] - => ids_sasl_server_new (CONSUMER_TWO.COMPANY.si) [27/Jun/2013:13:25:56 +0200] - ids_sasl_getopt: plugin= option=log_level [27/Jun/2013:13:25:56 +0200] - ids_sasl_getopt: plugin= option=auto_transition [27/Jun/2013:13:25:56 +0200] - <= ids_sasl_server_new [27/Jun/2013:13:25:56 +0200] - => slapi_reslimit_get_integer_limit() conn=0xc81f2e10, handle=8 [27/Jun/2013:13:25:56 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:25:56 +0200] - --> pagedresults_is_timedout [27/Jun/2013:13:25:56 +0200] - <-- pagedresults_is_timedout: - [27/Jun/2013:13:25:56 +0200] - activity on 64r [27/Jun/2013:13:25:56 +0200] - read activity on 64 [27/Jun/2013:13:25:56 +0200] - add_pb [27/Jun/2013:13:25:56 +0200] - get_pb [27/Jun/2013:13:25:56 +0200] - --> pagedresults_reset_timedout [27/Jun/2013:13:25:56 +0200] - <-- pagedresults_reset_timedout: 0 [27/Jun/2013:13:25:56 +0200] - conn 5289 activity level = 0 [27/Jun/2013:13:25:56 +0200] - --> pagedresults_in_use [27/Jun/2013:13:25:56 +0200] - <-- pagedresults_in_use: 0 [27/Jun/2013:13:25:56 +0200] - conn 5289 leaving turbo mode due to 3 [27/Jun/2013:13:25:56 +0200] - listener got signaled [27/Jun/2013:13:25:56 +0200] - --> pagedresults_cleanup [27/Jun/2013:13:25:56 +0200] - <-- pagedresults_cleanup: 0 [27/Jun/2013:13:25:56 +0200] - --> pagedresults_cleanup [27/Jun/2013:13:25:56 +0200] - <-- pagedresults_cleanup: 0 [27/Jun/2013:13:25:56 +0200] - new connection on 64 [27/Jun/2013:13:25:56 +0200] - => ids_sasl_server_new (CONSUMER_TWO.COMPANY.si) [27/Jun/2013:13:25:56 +0200] - ids_sasl_getopt: plugin= option=log_level [27/Jun/2013:13:25:56 +0200] - ids_sasl_getopt: plugin= option=auto_transition [27/Jun/2013:13:25:56 +0200] - <= ids_sasl_server_new [27/Jun/2013:13:25:56 +0200] - => slapi_reslimit_get_integer_limit() conn=0xc81f2e10, handle=8 [27/Jun/2013:13:25:56 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:25:56 +0200] - --> pagedresults_is_timedout [27/Jun/2013:13:25:56 +0200] - <-- pagedresults_is_timedout: - [27/Jun/2013:13:25:56 +0200] - activity on 64r [27/Jun/2013:13:25:56 +0200] - read activity on 64 [27/Jun/2013:13:25:56 +0200] - add_pb [27/Jun/2013:13:25:56 +0200] - get_pb [27/Jun/2013:13:25:56 +0200] - conn 5290 activity level = 0 [27/Jun/2013:13:25:56 +0200] - --> pagedresults_in_use [27/Jun/2013:13:25:56 +0200] - <-- pagedresults_in_use: 0 [27/Jun/2013:13:25:56 +0200] - do_bind [27/Jun/2013:13:25:56 +0200] - => slapi_reslimit_get_integer_limit() conn=0xc81f2e10, handle=8 [27/Jun/2013:13:25:56 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:25:56 +0200] - BIND dn="eduPersonPrincipalName=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si" method=128 version=3 [27/Jun/2013:13:25:56 +0200] - listener got signaled [27/Jun/2013:13:25:56 +0200] - => get_ldapmessage_controls [27/Jun/2013:13:25:56 +0200] - <= get_ldapmessage_controls no controls [27/Jun/2013:13:25:56 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.16) [27/Jun/2013:13:25:56 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:25:56 +0200] - --> pagedresults_is_timedout [27/Jun/2013:13:25:56 +0200] - <-- pagedresults_is_timedout: - [27/Jun/2013:13:25:56 +0200] - do_bind: version 3 method 0x80 dn eduPersonPrincipalName=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si [27/Jun/2013:13:25:56 +0200] - mapping tree selected backend : COMPANY [27/Jun/2013:13:25:56 +0200] - Calling plugin 'Legacy replication preoperation plugin' #6 type 401 [27/Jun/2013:13:25:56 +0200] - Calling plugin 'Multimaster replication preoperation plugin' #9 type 401 [27/Jun/2013:13:25:56 +0200] - Calling plugin 'Pass Through Authentication' #10 type 401 [27/Jun/2013:13:25:56 +0200] passthru-plugin - => passthru_bindpreop [27/Jun/2013:13:25:56 +0200] passthru-plugin - <= not handled (not one of our suffixes) [27/Jun/2013:13:25:56 +0200] - slapi_str2filter "(|(objectclass=*)(objectclass=ldapsubentry))" [27/Jun/2013:13:25:56 +0200] - put_filter: OR [27/Jun/2013:13:25:56 +0200] - str2list "(objectclass=*)(objectclass=ldapsubentry)" [27/Jun/2013:13:25:56 +0200] - slapi_str2filter "(objectclass=*)" [27/Jun/2013:13:25:56 +0200] - slapi_str2filter: simple [27/Jun/2013:13:25:56 +0200] - str2simple "objectclass=*" [27/Jun/2013:13:25:56 +0200] - slapi_str2filter "(objectclass=ldapsubentry)" [27/Jun/2013:13:25:56 +0200] - slapi_str2filter: simple [27/Jun/2013:13:25:56 +0200] - str2simple "objectclass=ldapsubentry" [27/Jun/2013:13:25:56 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12) [27/Jun/2013:13:25:56 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:25:56 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18) [27/Jun/2013:13:25:56 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:25:56 +0200] - mapping tree selected backend : COMPANY [27/Jun/2013:13:25:56 +0200] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=6 [27/Jun/2013:13:25:56 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:25:56 +0200] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=5 [27/Jun/2013:13:25:56 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:25:56 +0200] - => compute_limits: sizelimit=-1, timelimit=-1 [27/Jun/2013:13:25:56 +0200] - Calling plugin 'Account Usability Plugin' #1 type 403 [27/Jun/2013:13:25:56 +0200] account-usability-plugin - --> auc_pre_search [27/Jun/2013:13:25:56 +0200] account-usability-plugin - <-- auc_pre_op [27/Jun/2013:13:25:56 +0200] - Calling plugin 'ACL preoperation' #2 type 403 [27/Jun/2013:13:25:56 +0200] - Calling plugin 'deref' #4 type 403 [27/Jun/2013:13:25:56 +0200] deref-plugin - --> deref_pre_search [27/Jun/2013:13:25:56 +0200] deref-plugin - <-- deref_pre_op [27/Jun/2013:13:25:56 +0200] - Calling plugin 'Legacy replication preoperation plugin' #6 type 403 [27/Jun/2013:13:25:56 +0200] - Calling plugin 'Multimaster replication preoperation plugin' #9 type 403 [27/Jun/2013:13:25:56 +0200] index_subsys_assign_filter_decoders - before: (|(objectClass=*)(objectClass=ldapsubentry)) [27/Jun/2013:13:25:56 +0200] index_subsys_assign_filter_decoders - after: (|(objectClass=*)(objectClass=ldapsubentry)) [27/Jun/2013:13:25:56 +0200] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=0 [27/Jun/2013:13:25:56 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:25:56 +0200] - => find_entry_internal (dn=edupersonprincipalname=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si) lock 0 [27/Jun/2013:13:25:56 +0200] - => dn2entry_ext "edupersonprincipalname=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si" [27/Jun/2013:13:25:56 +0200] - <= dn2entry_ext 7fb88c002680 [27/Jun/2013:13:25:56 +0200] - <= find_entry_internal_dn found (edupersonprincipalname=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si) [27/Jun/2013:13:25:56 +0200] - <= find_entry_internal [27/Jun/2013:13:25:56 +0200] - candidate list has 1 ids [27/Jun/2013:13:25:56 +0200] - slapi_filter_dup type 0xA1 [27/Jun/2013:13:25:56 +0200] - slapi_filter_dup type 0x87 [27/Jun/2013:13:25:56 +0200] - slapi_filter_dup type 0xA3 [27/Jun/2013:13:25:56 +0200] id2entry - => id2entry(6) [27/Jun/2013:13:25:56 +0200] id2entry - <= id2entry 7fb88c002680, dn "edupersonprincipalname=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si" (cache) [27/Jun/2013:13:25:56 +0200] id2entry - <= id2entry( 6 ) 7fb88c002680 (disk) [27/Jun/2013:13:25:56 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:25:56 +0200] - => test_substring_filter [27/Jun/2013:13:25:56 +0200] - OR [27/Jun/2013:13:25:56 +0200] - => vattr_test_filter_list [27/Jun/2013:13:25:56 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:25:56 +0200] - => test_substring_filter [27/Jun/2013:13:25:56 +0200] - PRESENT [27/Jun/2013:13:25:56 +0200] - <= slapi_vattr_filter_test 0 [27/Jun/2013:13:25:56 +0200] - <= test_filter_list 0 [27/Jun/2013:13:25:56 +0200] - <= slapi_vattr_filter_test 0 [27/Jun/2013:13:25:56 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:25:56 +0200] - => test_substring_filter [27/Jun/2013:13:25:56 +0200] - OR [27/Jun/2013:13:25:56 +0200] - => vattr_test_filter_list [27/Jun/2013:13:25:56 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:25:56 +0200] - => test_substring_filter [27/Jun/2013:13:25:56 +0200] - PRESENT [27/Jun/2013:13:25:56 +0200] - <= test_filter_list 0 [27/Jun/2013:13:25:56 +0200] - <= slapi_vattr_filter_test 0 [27/Jun/2013:13:25:56 +0200] - => send_ldap_search_entry (eduPersonPrincipalName=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si) [27/Jun/2013:13:25:56 +0200] - Calling plugin 'Account Usability Plugin' #1 type 410 [27/Jun/2013:13:25:56 +0200] - Calling plugin 'deref' #4 type 410 [27/Jun/2013:13:25:56 +0200] - Calling plugin 'Legacy replication preoperation plugin' #6 type 410 [27/Jun/2013:13:25:56 +0200] - <= send_ldap_search_entry [27/Jun/2013:13:25:56 +0200] - --> pagedresults_set_search_result: idx=-1, sr=0 [27/Jun/2013:13:25:56 +0200] - <-- pagedresults_set_search_result: -1 [27/Jun/2013:13:25:56 +0200] - slapi_filter_free type 0xA1 [27/Jun/2013:13:25:56 +0200] - slapi_filter_free type 0x87 [27/Jun/2013:13:25:56 +0200] - slapi_filter_free type 0xA3 [27/Jun/2013:13:25:56 +0200] - => send_ldap_result 0:: [27/Jun/2013:13:25:56 +0200] - <= send_ldap_result [27/Jun/2013:13:25:56 +0200] - mapping tree release backend : COMPANY [27/Jun/2013:13:25:56 +0200] - slapi_filter_free type 0xA1 [27/Jun/2013:13:25:56 +0200] - slapi_filter_free type 0x87 [27/Jun/2013:13:25:56 +0200] - slapi_filter_free type 0xA3 [27/Jun/2013:13:25:56 +0200] - slapi_str2filter "(|(objectclass=*)(objectclass=ldapsubentry))" [27/Jun/2013:13:25:56 +0200] - put_filter: OR [27/Jun/2013:13:25:56 +0200] - str2list "(objectclass=*)(objectclass=ldapsubentry)" [27/Jun/2013:13:25:56 +0200] - slapi_str2filter "(objectclass=*)" [27/Jun/2013:13:25:56 +0200] - slapi_str2filter: simple [27/Jun/2013:13:25:56 +0200] - str2simple "objectclass=*" [27/Jun/2013:13:25:56 +0200] - slapi_str2filter "(objectclass=ldapsubentry)" [27/Jun/2013:13:25:56 +0200] - slapi_str2filter: simple [27/Jun/2013:13:25:56 +0200] - str2simple "objectclass=ldapsubentry" [27/Jun/2013:13:25:56 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12) [27/Jun/2013:13:25:56 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:25:56 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18) [27/Jun/2013:13:25:56 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:25:56 +0200] - mapping tree selected backend : COMPANY [27/Jun/2013:13:25:56 +0200] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=6 [27/Jun/2013:13:25:56 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:25:56 +0200] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=5 [27/Jun/2013:13:25:56 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:25:56 +0200] - => compute_limits: sizelimit=-1, timelimit=-1 [27/Jun/2013:13:25:56 +0200] - Calling plugin 'Account Usability Plugin' #1 type 403 [27/Jun/2013:13:25:56 +0200] account-usability-plugin - --> auc_pre_search [27/Jun/2013:13:25:56 +0200] account-usability-plugin - <-- auc_pre_op [27/Jun/2013:13:25:56 +0200] - Calling plugin 'ACL preoperation' #2 type 403 [27/Jun/2013:13:25:56 +0200] - Calling plugin 'deref' #4 type 403 [27/Jun/2013:13:25:56 +0200] deref-plugin - --> deref_pre_search [27/Jun/2013:13:25:56 +0200] deref-plugin - <-- deref_pre_op [27/Jun/2013:13:25:56 +0200] - Calling plugin 'Legacy replication preoperation plugin' #6 type 403 [27/Jun/2013:13:25:56 +0200] - Calling plugin 'Multimaster replication preoperation plugin' #9 type 403 [27/Jun/2013:13:25:56 +0200] index_subsys_assign_filter_decoders - before: (|(objectClass=*)(objectClass=ldapsubentry)) [27/Jun/2013:13:25:56 +0200] index_subsys_assign_filter_decoders - after: (|(objectClass=*)(objectClass=ldapsubentry)) [27/Jun/2013:13:25:56 +0200] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=0 [27/Jun/2013:13:25:56 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:25:56 +0200] - => find_entry_internal (dn=edupersonprincipalname=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si) lock 0 [27/Jun/2013:13:25:56 +0200] - => dn2entry_ext "edupersonprincipalname=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si" [27/Jun/2013:13:25:56 +0200] - <= dn2entry_ext 7fb88c002680 [27/Jun/2013:13:25:56 +0200] - <= find_entry_internal_dn found (edupersonprincipalname=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si) [27/Jun/2013:13:25:56 +0200] - <= find_entry_internal [27/Jun/2013:13:25:56 +0200] - candidate list has 1 ids [27/Jun/2013:13:25:56 +0200] - slapi_filter_dup type 0xA1 [27/Jun/2013:13:25:56 +0200] - slapi_filter_dup type 0x87 [27/Jun/2013:13:25:56 +0200] - slapi_filter_dup type 0xA3 [27/Jun/2013:13:25:56 +0200] id2entry - => id2entry(6) [27/Jun/2013:13:25:56 +0200] id2entry - <= id2entry 7fb88c002680, dn "edupersonprincipalname=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si" (cache) [27/Jun/2013:13:25:56 +0200] id2entry - <= id2entry( 6 ) 7fb88c002680 (disk) [27/Jun/2013:13:25:56 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:25:56 +0200] - => test_substring_filter [27/Jun/2013:13:25:56 +0200] - OR [27/Jun/2013:13:25:56 +0200] - => vattr_test_filter_list [27/Jun/2013:13:25:56 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:25:56 +0200] - => test_substring_filter [27/Jun/2013:13:25:56 +0200] - PRESENT [27/Jun/2013:13:25:56 +0200] - <= slapi_vattr_filter_test 0 [27/Jun/2013:13:25:56 +0200] - <= test_filter_list 0 [27/Jun/2013:13:25:56 +0200] - <= slapi_vattr_filter_test 0 [27/Jun/2013:13:25:56 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:25:56 +0200] - => test_substring_filter [27/Jun/2013:13:25:56 +0200] - OR [27/Jun/2013:13:25:56 +0200] - => vattr_test_filter_list [27/Jun/2013:13:25:56 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:25:56 +0200] - => test_substring_filter [27/Jun/2013:13:25:56 +0200] - PRESENT [27/Jun/2013:13:25:56 +0200] - <= test_filter_list 0 [27/Jun/2013:13:25:56 +0200] - <= slapi_vattr_filter_test 0 [27/Jun/2013:13:25:56 +0200] - => send_ldap_search_entry (eduPersonPrincipalName=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si) [27/Jun/2013:13:25:56 +0200] - Calling plugin 'Account Usability Plugin' #1 type 410 [27/Jun/2013:13:25:56 +0200] - Calling plugin 'deref' #4 type 410 [27/Jun/2013:13:25:56 +0200] - Calling plugin 'Legacy replication preoperation plugin' #6 type 410 [27/Jun/2013:13:25:56 +0200] - <= send_ldap_search_entry [27/Jun/2013:13:25:56 +0200] - --> pagedresults_set_search_result: idx=-1, sr=0 [27/Jun/2013:13:25:56 +0200] - <-- pagedresults_set_search_result: -1 [27/Jun/2013:13:25:56 +0200] - slapi_filter_free type 0xA1 [27/Jun/2013:13:25:56 +0200] - slapi_filter_free type 0x87 [27/Jun/2013:13:25:56 +0200] - slapi_filter_free type 0xA3 [27/Jun/2013:13:25:56 +0200] - => send_ldap_result 0:: [27/Jun/2013:13:25:56 +0200] - <= send_ldap_result [27/Jun/2013:13:25:56 +0200] - mapping tree release backend : COMPANY [27/Jun/2013:13:25:56 +0200] - slapi_filter_free type 0xA1 [27/Jun/2013:13:25:56 +0200] - slapi_filter_free type 0x87 [27/Jun/2013:13:25:56 +0200] - slapi_filter_free type 0xA3 [27/Jun/2013:13:25:56 +0200] - => find_entry_internal (dn=eduPersonPrincipalName=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si) lock 0 [27/Jun/2013:13:25:56 +0200] - => dn2entry_ext "eduPersonPrincipalName=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si" [27/Jun/2013:13:25:56 +0200] - <= dn2entry_ext 7fb88c002680 [27/Jun/2013:13:25:56 +0200] - <= find_entry_internal_dn found (eduPersonPrincipalName=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si) [27/Jun/2013:13:25:56 +0200] - <= find_entry_internal [27/Jun/2013:13:25:56 +0200] - => slapi_pw_find value: "test0st3r0n" [27/Jun/2013:13:25:56 +0200] - <= slapi_pw_find matched "test0st3r0n" using scheme "CLEAR" [27/Jun/2013:13:25:56 +0200] - => reslimit_update_from_entry() conn=0xc81f2e10, entry=0x6c00c420 [27/Jun/2013:13:25:56 +0200] - reslimit_update_from_entry(): setting limit for handle 0 (based on nsLookThroughLimit) [27/Jun/2013:13:25:56 +0200] - reslimit_update_from_entry(): setting limit for handle 1 (based on nsIDListScanLimit) [27/Jun/2013:13:25:56 +0200] - reslimit_update_from_entry(): setting limit for handle 2 (based on nsPagedLookThroughLimit) [27/Jun/2013:13:25:56 +0200] - reslimit_update_from_entry(): setting limit for handle 3 (based on nsPagedIDListScanLimit) [27/Jun/2013:13:25:56 +0200] - reslimit_update_from_entry(): setting limit for handle 4 (based on nsRangeSearchLookThroughLimit) [27/Jun/2013:13:25:56 +0200] - reslimit_update_from_entry(): setting limit for handle 5 (based on nsSizeLimit) [27/Jun/2013:13:25:56 +0200] - reslimit_update_from_entry(): setting limit for handle 6 (based on nsTimeLimit) [27/Jun/2013:13:25:56 +0200] - reslimit_update_from_entry(): setting limit for handle 7 (based on nsPagedSizeLimit) [27/Jun/2013:13:25:56 +0200] - reslimit_update_from_entry(): setting limit for handle 8 (based on nsIdleTimeout) [27/Jun/2013:13:25:56 +0200] - <= reslimit_update_from_entry() returning status 0 [27/Jun/2013:13:25:56 +0200] - slapi_str2filter "(|(objectclass=*)(objectclass=ldapsubentry))" [27/Jun/2013:13:25:56 +0200] - put_filter: OR [27/Jun/2013:13:25:56 +0200] - str2list "(objectclass=*)(objectclass=ldapsubentry)" [27/Jun/2013:13:25:56 +0200] - slapi_str2filter "(objectclass=*)" [27/Jun/2013:13:25:56 +0200] - slapi_str2filter: simple [27/Jun/2013:13:25:56 +0200] - str2simple "objectclass=*" [27/Jun/2013:13:25:56 +0200] - slapi_str2filter "(objectclass=ldapsubentry)" [27/Jun/2013:13:25:56 +0200] - slapi_str2filter: simple [27/Jun/2013:13:25:56 +0200] - str2simple "objectclass=ldapsubentry" [27/Jun/2013:13:25:56 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12) [27/Jun/2013:13:25:56 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:25:56 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18) [27/Jun/2013:13:25:56 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:25:56 +0200] - mapping tree selected backend : COMPANY [27/Jun/2013:13:25:56 +0200] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=6 [27/Jun/2013:13:25:56 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:25:56 +0200] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=5 [27/Jun/2013:13:25:56 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:25:56 +0200] - => compute_limits: sizelimit=-1, timelimit=-1 [27/Jun/2013:13:25:56 +0200] - Calling plugin 'Account Usability Plugin' #1 type 403 [27/Jun/2013:13:25:56 +0200] account-usability-plugin - --> auc_pre_search [27/Jun/2013:13:25:56 +0200] account-usability-plugin - <-- auc_pre_op [27/Jun/2013:13:25:56 +0200] - Calling plugin 'ACL preoperation' #2 type 403 [27/Jun/2013:13:25:56 +0200] - Calling plugin 'deref' #4 type 403 [27/Jun/2013:13:25:56 +0200] deref-plugin - --> deref_pre_search [27/Jun/2013:13:25:56 +0200] deref-plugin - <-- deref_pre_op [27/Jun/2013:13:25:56 +0200] - Calling plugin 'Legacy replication preoperation plugin' #6 type 403 [27/Jun/2013:13:25:56 +0200] - Calling plugin 'Multimaster replication preoperation plugin' #9 type 403 [27/Jun/2013:13:25:56 +0200] index_subsys_assign_filter_decoders - before: (|(objectClass=*)(objectClass=ldapsubentry)) [27/Jun/2013:13:25:56 +0200] index_subsys_assign_filter_decoders - after: (|(objectClass=*)(objectClass=ldapsubentry)) [27/Jun/2013:13:25:56 +0200] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=0 [27/Jun/2013:13:25:56 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:25:56 +0200] - => find_entry_internal (dn=edupersonprincipalname=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si) lock 0 [27/Jun/2013:13:25:56 +0200] - => dn2entry_ext "edupersonprincipalname=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si" [27/Jun/2013:13:25:56 +0200] - <= dn2entry_ext 7fb88c002680 [27/Jun/2013:13:25:56 +0200] - <= find_entry_internal_dn found (edupersonprincipalname=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si) [27/Jun/2013:13:25:56 +0200] - <= find_entry_internal [27/Jun/2013:13:25:56 +0200] - candidate list has 1 ids [27/Jun/2013:13:25:56 +0200] - slapi_filter_dup type 0xA1 [27/Jun/2013:13:25:56 +0200] - slapi_filter_dup type 0x87 [27/Jun/2013:13:25:56 +0200] - slapi_filter_dup type 0xA3 [27/Jun/2013:13:25:56 +0200] id2entry - => id2entry(6) [27/Jun/2013:13:25:56 +0200] id2entry - <= id2entry 7fb88c002680, dn "edupersonprincipalname=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si" (cache) [27/Jun/2013:13:25:56 +0200] id2entry - <= id2entry( 6 ) 7fb88c002680 (disk) [27/Jun/2013:13:25:56 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:25:56 +0200] - => test_substring_filter [27/Jun/2013:13:25:56 +0200] - OR [27/Jun/2013:13:25:56 +0200] - => vattr_test_filter_list [27/Jun/2013:13:25:56 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:25:56 +0200] - => test_substring_filter [27/Jun/2013:13:25:56 +0200] - PRESENT [27/Jun/2013:13:25:56 +0200] - <= slapi_vattr_filter_test 0 [27/Jun/2013:13:25:56 +0200] - <= test_filter_list 0 [27/Jun/2013:13:25:56 +0200] - <= slapi_vattr_filter_test 0 [27/Jun/2013:13:25:56 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:25:56 +0200] - => test_substring_filter [27/Jun/2013:13:25:56 +0200] - OR [27/Jun/2013:13:25:56 +0200] - => vattr_test_filter_list [27/Jun/2013:13:25:56 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:25:56 +0200] - => test_substring_filter [27/Jun/2013:13:25:56 +0200] - PRESENT [27/Jun/2013:13:25:56 +0200] - <= test_filter_list 0 [27/Jun/2013:13:25:56 +0200] - <= slapi_vattr_filter_test 0 [27/Jun/2013:13:25:56 +0200] - => send_ldap_search_entry (eduPersonPrincipalName=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si) [27/Jun/2013:13:25:56 +0200] - Calling plugin 'Account Usability Plugin' #1 type 410 [27/Jun/2013:13:25:56 +0200] - Calling plugin 'deref' #4 type 410 [27/Jun/2013:13:25:56 +0200] - Calling plugin 'Legacy replication preoperation plugin' #6 type 410 [27/Jun/2013:13:25:56 +0200] - <= send_ldap_search_entry [27/Jun/2013:13:25:56 +0200] - --> pagedresults_set_search_result: idx=-1, sr=0 [27/Jun/2013:13:25:56 +0200] - <-- pagedresults_set_search_result: -1 [27/Jun/2013:13:25:56 +0200] - slapi_filter_free type 0xA1 [27/Jun/2013:13:25:56 +0200] - slapi_filter_free type 0x87 [27/Jun/2013:13:25:56 +0200] - slapi_filter_free type 0xA3 [27/Jun/2013:13:25:56 +0200] - => send_ldap_result 0:: [27/Jun/2013:13:25:56 +0200] - <= send_ldap_result [27/Jun/2013:13:25:56 +0200] - mapping tree release backend : COMPANY [27/Jun/2013:13:25:56 +0200] - slapi_filter_free type 0xA1 [27/Jun/2013:13:25:56 +0200] - slapi_filter_free type 0x87 [27/Jun/2013:13:25:56 +0200] - slapi_filter_free type 0xA3 [27/Jun/2013:13:25:56 +0200] - => send_ldap_result 0:: [27/Jun/2013:13:25:56 +0200] - flush_ber() wrote 14 bytes to socket 64 [27/Jun/2013:13:25:56 +0200] - <= send_ldap_result [27/Jun/2013:13:25:56 +0200] - activity on 64r [27/Jun/2013:13:25:56 +0200] - Calling plugin 'Multimaster replication postoperation plugin' #5 type 501 [27/Jun/2013:13:25:56 +0200] - read activity on 64 [27/Jun/2013:13:25:56 +0200] - add_pb [27/Jun/2013:13:25:56 +0200] - get_pb [27/Jun/2013:13:25:56 +0200] - do_search [27/Jun/2013:13:25:56 +0200] - => slapi_reslimit_get_integer_limit() conn=0xc81f2e10, handle=8 [27/Jun/2013:13:25:56 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:25:56 +0200] - => get_filter_internal [27/Jun/2013:13:25:56 +0200] - listener got signaled [27/Jun/2013:13:25:56 +0200] - EQUALITY [27/Jun/2013:13:25:56 +0200] - --> pagedresults_is_timedout [27/Jun/2013:13:25:56 +0200] - <-- pagedresults_is_timedout: - [27/Jun/2013:13:25:56 +0200] - <= get_filter_internal 0 [27/Jun/2013:13:25:56 +0200] get_filter - before optimize: [27/Jun/2013:13:25:56 +0200] get_filter - after optimize: [27/Jun/2013:13:25:56 +0200] - SRCH base="(null)" scope=0 deref=0 sizelimit=0 timelimit=0 attrsonly=0 filter="(cn=test)" attrs=ALL [27/Jun/2013:13:25:56 +0200] - => get_ldapmessage_controls [27/Jun/2013:13:25:56 +0200] - <= get_ldapmessage_controls no controls [27/Jun/2013:13:25:56 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.3) [27/Jun/2013:13:25:56 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:25:56 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12) [27/Jun/2013:13:25:56 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:25:56 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18) [27/Jun/2013:13:25:56 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:25:56 +0200] - mapping tree selected backend : COMPANY [27/Jun/2013:13:25:56 +0200] - => slapi_reslimit_get_integer_limit() conn=0xc81f2e10, handle=6 [27/Jun/2013:13:25:56 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:25:56 +0200] - => slapi_reslimit_get_integer_limit() conn=0xc81f2e10, handle=5 [27/Jun/2013:13:25:56 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:25:56 +0200] - => compute_limits: sizelimit=2000, timelimit=3600 [27/Jun/2013:13:25:56 +0200] - Calling plugin 'Account Usability Plugin' #1 type 403 [27/Jun/2013:13:25:56 +0200] account-usability-plugin - --> auc_pre_search [27/Jun/2013:13:25:56 +0200] account-usability-plugin - <-- auc_pre_op [27/Jun/2013:13:25:56 +0200] - Calling plugin 'ACL preoperation' #2 type 403 [27/Jun/2013:13:25:56 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12) [27/Jun/2013:13:25:56 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:25:56 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18) [27/Jun/2013:13:25:56 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:25:56 +0200] NSACLPlugin - acl_init_userGroup: found in cache for dn:edupersonprincipalname=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si [27/Jun/2013:13:25:56 +0200] NSACLPlugin - Failed to find root for base: dc=si [27/Jun/2013:13:25:56 +0200] - Calling plugin 'deref' #4 type 403 [27/Jun/2013:13:25:56 +0200] deref-plugin - --> deref_pre_search [27/Jun/2013:13:25:56 +0200] deref-plugin - <-- deref_pre_op [27/Jun/2013:13:25:56 +0200] - Calling plugin 'Legacy replication preoperation plugin' #6 type 403 [27/Jun/2013:13:25:56 +0200] - Calling plugin 'Multimaster replication preoperation plugin' #9 type 403 [27/Jun/2013:13:25:56 +0200] index_subsys_assign_filter_decoders - before: (cn=test) [27/Jun/2013:13:25:56 +0200] index_subsys_assign_filter_decoders - after: (cn=test) [27/Jun/2013:13:25:56 +0200] - => slapi_reslimit_get_integer_limit() conn=0xc81f2e10, handle=0 [27/Jun/2013:13:25:56 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:25:56 +0200] - => find_entry_internal (dn=dc=TIER2,dc=COMPANY,dc=si) lock 0 [27/Jun/2013:13:25:56 +0200] - => dn2entry_ext "dc=TIER2,dc=COMPANY,dc=si" [27/Jun/2013:13:25:56 +0200] - <= dn2entry_ext 7fb83000af80 [27/Jun/2013:13:25:56 +0200] - <= find_entry_internal_dn found (dc=TIER2,dc=COMPANY,dc=si) [27/Jun/2013:13:25:56 +0200] - <= find_entry_internal [27/Jun/2013:13:25:56 +0200] - candidate list has 1 ids [27/Jun/2013:13:25:56 +0200] - slapi_filter_dup type 0xA3 [27/Jun/2013:13:25:56 +0200] id2entry - => id2entry(3) [27/Jun/2013:13:25:56 +0200] id2entry - <= id2entry 7fb83000af80, dn "dc=TIER2,dc=COMPANY,dc=si" (cache) [27/Jun/2013:13:25:56 +0200] id2entry - <= id2entry( 3 ) 7fb83000af80 (disk) [27/Jun/2013:13:25:56 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:25:56 +0200] - => test_substring_filter [27/Jun/2013:13:25:56 +0200] - EQUALITY [27/Jun/2013:13:25:56 +0200] NSACLPlugin - #### conn=5290 op=1 binddn="edupersonprincipalname=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si" [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ************ RESOURCE INFO STARTS ********* [27/Jun/2013:13:25:56 +0200] NSACLPlugin - Client DN: edupersonprincipalname=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si [27/Jun/2013:13:25:56 +0200] NSACLPlugin - resource type:256(search target_DN ) [27/Jun/2013:13:25:56 +0200] NSACLPlugin - Slapi_Entry DN: dc=TIER2,dc=COMPANY,dc=si [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ATTR: cn [27/Jun/2013:13:25:56 +0200] NSACLPlugin - rights:search [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ************ RESOURCE INFO ENDS ********* [27/Jun/2013:13:25:56 +0200] NSACLPlugin - Using ACL Container:0 for evaluation [27/Jun/2013:13:25:56 +0200] NSACLPlugin - Using ACL Container:1 for evaluation [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ***BEGIN ACL INFO[ Name: "Allow write to TIER2.COMPANY.si – root"]*** [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ACL Index:44 ACL_ELEVEL:2 [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ACI type:(compare search read write delete add target_DN target_attr acltxt allow_rule ) [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ACI RULE type:(userdn ip ) [27/Jun/2013:13:25:56 +0200] NSACLPlugin - Slapi_Entry DN:dc=TIER2,dc=COMPANY,dc=si [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ***END ACL INFO***************************** [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ***BEGIN ACL INFO[ Name: "Allow read - self"]*** [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ACL Index:45 ACL_ELEVEL:2 [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ACI type:(compare search read target_DN target_attr acltxt allow_rule ) [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ACI RULE type:(userdn ) [27/Jun/2013:13:25:56 +0200] NSACLPlugin - Slapi_Entry DN:dc=TIER2,dc=COMPANY,dc=si [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ***END ACL INFO***************************** [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ***BEGIN ACL INFO[ Name: "Allow read to TIER2.COMPANY.si - idp"]*** [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ACL Index:47 ACL_ELEVEL:2 [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ACI type:(compare search read target_attr acltxt allow_rule ) [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ACI RULE type:(userdn ) [27/Jun/2013:13:25:56 +0200] NSACLPlugin - Slapi_Entry DN:dc=TIER2,dc=COMPANY,dc=si [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ***END ACL INFO***************************** [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ***BEGIN ACL INFO[ Name: "Configuration Adminstrator"]*** [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ACL Index:34 ACL_ELEVEL:2 [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ACI type:(compare search read write delete add self target_attr acltxt allow_rule ) [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ACI RULE type:(userdn ) [27/Jun/2013:13:25:56 +0200] NSACLPlugin - Slapi_Entry DN:dc=COMPANY,dc=si [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ***END ACL INFO***************************** [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ***BEGIN ACL INFO[ Name: "Configuration Administrators Group"]*** [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ACL Index:35 ACL_ELEVEL:6 [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ACI type:(compare search read write delete add self target_attr acltxt allow_rule ) [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ACI RULE type:(groupdn ) [27/Jun/2013:13:25:56 +0200] NSACLPlugin - Slapi_Entry DN:dc=COMPANY,dc=si [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ***END ACL INFO***************************** [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ***BEGIN ACL INFO[ Name: "SIE Group"]*** [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ACL Index:36 ACL_ELEVEL:6 [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ACI type:(compare search read write delete add self target_attr acltxt allow_rule ) [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ACI RULE type:(groupdn ) [27/Jun/2013:13:25:56 +0200] NSACLPlugin - Slapi_Entry DN:dc=COMPANY,dc=si [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ***END ACL INFO***************************** [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ***BEGIN ACL INFO[ Name: "Directory Administrators Group"]*** [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ACL Index:37 ACL_ELEVEL:6 [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ACI type:(compare search read write delete add self target_attr acltxt allow_rule ) [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ACI RULE type:(groupdn ) [27/Jun/2013:13:25:56 +0200] NSACLPlugin - Slapi_Entry DN:dc=COMPANY,dc=si [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ***END ACL INFO***************************** [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ***BEGIN ACL INFO[ Name: "Write to COMPANY.si - root@COMPANY.si"]*** [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ACL Index:39 ACL_ELEVEL:2 [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ACI type:(compare search read write delete add target_DN target_attr acltxt allow_rule ) [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ACI RULE type:(userdn ip ) [27/Jun/2013:13:25:56 +0200] NSACLPlugin - Slapi_Entry DN:dc=COMPANY,dc=si [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ***END ACL INFO***************************** [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ***BEGIN ACL INFO[ Name: "Write to COMPANY.si - cn=root,dc=COMPANY,dc=si"]*** [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ACL Index:43 ACL_ELEVEL:2 [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ACI type:(compare search read write delete add target_DN target_attr acltxt allow_rule ) [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ACI RULE type:(userdn ) [27/Jun/2013:13:25:56 +0200] NSACLPlugin - Slapi_Entry DN:dc=COMPANY,dc=si [27/Jun/2013:13:25:56 +0200] NSACLPlugin - ***END ACL INFO***************************** [27/Jun/2013:13:25:56 +0200] NSACLPlugin - Num of ALLOW Handles:9, DENY handles:0 [27/Jun/2013:13:25:56 +0200] NSACLPlugin - Processed attr:cn for entry:dc=TIER2,dc=COMPANY,dc=si [27/Jun/2013:13:25:56 +0200] NSACLPlugin - 1. Evaluating ALLOW aci(44) " "Allow write to TIER2.COMPANY.si – root"" [27/Jun/2013:13:25:56 +0200] NSACLPlugin - 2. Evaluating ALLOW aci(35) " "Configuration Administrators Group"" [27/Jun/2013:13:25:56 +0200] NSACLPlugin - Evaluating user edupersonprincipalname=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si in group cn=Configuration Administrators,ou=Groups,ou=TopologyManagement,o=NetscapeRoot? [27/Jun/2013:13:25:56 +0200] NSACLPlugin - -- Not in cn=Configuration Administrators,ou=Groups,ou=TopologyManagement,o=NetscapeRoot [27/Jun/2013:13:25:56 +0200] NSACLPlugin - Evaluated ACL_FALSE [27/Jun/2013:13:25:56 +0200] NSACLPlugin - 3. Evaluating ALLOW aci(45) " "Allow read - self"" [27/Jun/2013:13:25:56 +0200] NSACLPlugin - 4. Evaluating ALLOW aci(47) " "Allow read to TIER2.COMPANY.si - idp"" [27/Jun/2013:13:25:56 +0200] NSACLPlugin - 5. Evaluating ALLOW aci(34) " "Configuration Adminstrator"" [27/Jun/2013:13:25:56 +0200] NSACLPlugin - 6. Evaluating ALLOW aci(36) " "SIE Group"" [27/Jun/2013:13:25:56 +0200] NSACLPlugin - Evaluating user edupersonprincipalname=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si in group dc=COMPANY,dc=si? [27/Jun/2013:13:25:56 +0200] NSACLPlugin - -- Not in cn=Configuration Administrators,ou=Groups,ou=TopologyManagement,o=NetscapeRoot [27/Jun/2013:13:25:56 +0200] NSACLPlugin - -- Not in dc=COMPANY,dc=si [27/Jun/2013:13:25:56 +0200] NSACLPlugin - Evaluated ACL_FALSE [27/Jun/2013:13:25:56 +0200] NSACLPlugin - 7. Evaluating ALLOW aci(37) " "Directory Administrators Group"" [27/Jun/2013:13:25:56 +0200] NSACLPlugin - Evaluating user edupersonprincipalname=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si in group cn=Directory Administrators,dc=COMPANY,dc=si? [27/Jun/2013:13:25:56 +0200] NSACLPlugin - -- Not in cn=Configuration Administrators,ou=Groups,ou=TopologyManagement,o=NetscapeRoot [27/Jun/2013:13:25:56 +0200] NSACLPlugin - -- Not in dc=COMPANY,dc=si [27/Jun/2013:13:25:56 +0200] NSACLPlugin - -- Not in cn=Directory Administrators,dc=COMPANY,dc=si [27/Jun/2013:13:25:56 +0200] NSACLPlugin - Evaluated ACL_FALSE [27/Jun/2013:13:25:56 +0200] NSACLPlugin - 8. Evaluating ALLOW aci(39) " "Write to COMPANY.si - root@COMPANY.si"" [27/Jun/2013:13:25:56 +0200] NSACLPlugin - 9. Evaluating ALLOW aci(43) " "Write to COMPANY.si - cn=root,dc=COMPANY,dc=si"" [27/Jun/2013:13:25:56 +0200] NSACLPlugin - conn=5290 op=1 (main): Deny search on entry(dc=TIER2,dc=COMPANY,dc=si).attr(cn) to edupersonprincipalname=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si: no aci matched the subject by aci(43): aciname= "Write to COMPANY.si - cn=root,dc=COMPANY,dc=si", acidn="dc=COMPANY,dc=si" [27/Jun/2013:13:25:56 +0200] - --> pagedresults_set_search_result: idx=-1, sr=0 [27/Jun/2013:13:25:56 +0200] - <-- pagedresults_set_search_result: -1 [27/Jun/2013:13:25:56 +0200] - slapi_filter_free type 0xA3 [27/Jun/2013:13:25:56 +0200] - => send_ldap_result 0:: [27/Jun/2013:13:25:56 +0200] - flush_ber() wrote 14 bytes to socket 64 [27/Jun/2013:13:25:56 +0200] - <= send_ldap_result [27/Jun/2013:13:25:56 +0200] - mapping tree release backend : COMPANY [27/Jun/2013:13:25:56 +0200] - slapi_filter_free type 0xA3 [27/Jun/2013:13:25:56 +0200] - activity on 64r [27/Jun/2013:13:25:56 +0200] - read activity on 64 [27/Jun/2013:13:25:56 +0200] - add_pb [27/Jun/2013:13:25:56 +0200] - get_pb [27/Jun/2013:13:25:56 +0200] - do_unbind [27/Jun/2013:13:25:56 +0200] - => get_ldapmessage_controls [27/Jun/2013:13:25:56 +0200] - <= get_ldapmessage_controls no controls [27/Jun/2013:13:25:56 +0200] - defbackend_noop [27/Jun/2013:13:25:56 +0200] - --> pagedresults_reset_timedout [27/Jun/2013:13:25:56 +0200] - <-- pagedresults_reset_timedout: 0 [27/Jun/2013:13:25:56 +0200] - => reslimit_update_from_entry() conn=0xc81f2e10, entry=0x0 [27/Jun/2013:13:25:56 +0200] - reslimit_update_from_entry(): setting limit for handle 0 (based on nsLookThroughLimit) [27/Jun/2013:13:25:56 +0200] - reslimit_update_from_entry(): setting limit for handle 1 (based on nsIDListScanLimit) [27/Jun/2013:13:25:56 +0200] - reslimit_update_from_entry(): setting limit for handle 2 (based on nsPagedLookThroughLimit) [27/Jun/2013:13:25:56 +0200] - reslimit_update_from_entry(): setting limit for handle 3 (based on nsPagedIDListScanLimit) [27/Jun/2013:13:25:56 +0200] - reslimit_update_from_entry(): setting limit for handle 4 (based on nsRangeSearchLookThroughLimit) [27/Jun/2013:13:25:56 +0200] - reslimit_update_from_entry(): setting limit for handle 5 (based on nsSizeLimit) [27/Jun/2013:13:25:56 +0200] - reslimit_update_from_entry(): setting limit for handle 6 (based on nsTimeLimit) [27/Jun/2013:13:25:56 +0200] - reslimit_update_from_entry(): setting limit for handle 7 (based on nsPagedSizeLimit) [27/Jun/2013:13:25:56 +0200] - reslimit_update_from_entry(): setting limit for handle 8 (based on nsIdleTimeout) [27/Jun/2013:13:25:56 +0200] - <= reslimit_update_from_entry() returning status 0 [27/Jun/2013:13:25:56 +0200] - --> pagedresults_cleanup [27/Jun/2013:13:25:56 +0200] - <-- pagedresults_cleanup: 0 [27/Jun/2013:13:26:05 +0200] - Event id 11ef230 called at 1372332365 (scheduled for 1372332365) [27/Jun/2013:13:26:12 +0200] - Event id 7fb82000f770 called at 1372332372 (scheduled for 1372332372) [27/Jun/2013:13:26:15 +0200] - ldbm backend flushing [27/Jun/2013:13:26:15 +0200] - ldbm backend done flushing [27/Jun/2013:13:26:15 +0200] - Event id 11ef230 called at 1372332375 (scheduled for 1372332375) [27/Jun/2013:13:26:25 +0200] - Event id 10e16a0 called at 1372332385 (scheduled for 1372332385) [27/Jun/2013:13:26:25 +0200] - Event id 11ef230 called at 1372332385 (scheduled for 1372332385) [27/Jun/2013:13:26:35 +0200] - Event id 11ef230 called at 1372332395 (scheduled for 1372332395) [27/Jun/2013:13:26:42 +0200] - Event id 7fb82000f770 called at 1372332402 (scheduled for 1372332402) [27/Jun/2013:13:26:45 +0200] - ldbm backend flushing [27/Jun/2013:13:26:45 +0200] - ldbm backend done flushing [27/Jun/2013:13:26:45 +0200] - Event id 11ef230 called at 1372332405 (scheduled for 1372332405) [27/Jun/2013:13:26:55 +0200] - Event id 11ef230 called at 1372332415 (scheduled for 1372332415) [27/Jun/2013:13:27:05 +0200] - Event id 11ef230 called at 1372332425 (scheduled for 1372332425) [27/Jun/2013:13:27:12 +0200] - Event id 7fb82000f770 called at 1372332432 (scheduled for 1372332432) [27/Jun/2013:13:27:14 +0200] - ldbm backend flushing [27/Jun/2013:13:27:14 +0200] - ldbm backend done flushing [27/Jun/2013:13:27:15 +0200] - Event id 11ef230 called at 1372332435 (scheduled for 1372332435) [27/Jun/2013:13:27:25 +0200] - Event id 10e16a0 called at 1372332445 (scheduled for 1372332445) [27/Jun/2013:13:27:25 +0200] - Event id 11ef230 called at 1372332445 (scheduled for 1372332445) [27/Jun/2013:13:27:35 +0200] - Event id 11ef230 called at 1372332455 (scheduled for 1372332455) [27/Jun/2013:13:27:42 +0200] - Event id 7fb82000f770 called at 1372332462 (scheduled for 1372332462) [27/Jun/2013:13:27:44 +0200] - ldbm backend flushing [27/Jun/2013:13:27:44 +0200] - ldbm backend done flushing [27/Jun/2013:13:27:45 +0200] - Event id 11ef230 called at 1372332465 (scheduled for 1372332465) [27/Jun/2013:13:27:55 +0200] - Event id 11ef230 called at 1372332475 (scheduled for 1372332475) [27/Jun/2013:13:28:05 +0200] - Event id 11ef230 called at 1372332485 (scheduled for 1372332485) [27/Jun/2013:13:28:12 +0200] - Event id 7fb82000f770 called at 1372332492 (scheduled for 1372332492) [27/Jun/2013:13:28:14 +0200] - ldbm backend flushing [27/Jun/2013:13:28:14 +0200] - ldbm backend done flushing [27/Jun/2013:13:28:15 +0200] - Event id 11ef230 called at 1372332495 (scheduled for 1372332495) [27/Jun/2013:13:28:25 +0200] - Event id 10e16a0 called at 1372332505 (scheduled for 1372332505) [27/Jun/2013:13:28:25 +0200] - Event id 11ef230 called at 1372332505 (scheduled for 1372332505) [27/Jun/2013:13:28:35 +0200] - Event id 11ef230 called at 1372332515 (scheduled for 1372332515) [27/Jun/2013:13:28:42 +0200] - Event id 7fb82000f770 called at 1372332522 (scheduled for 1372332522) [27/Jun/2013:13:28:44 +0200] - ldbm backend flushing [27/Jun/2013:13:28:44 +0200] - ldbm backend done flushing [27/Jun/2013:13:28:45 +0200] - Event id 11ef230 called at 1372332525 (scheduled for 1372332525) [27/Jun/2013:13:28:55 +0200] - Event id 11ef230 called at 1372332535 (scheduled for 1372332535) [27/Jun/2013:13:29:05 +0200] - Event id 11ef230 called at 1372332545 (scheduled for 1372332545) [27/Jun/2013:13:29:12 +0200] - Event id 7fb82000f770 called at 1372332552 (scheduled for 1372332552) [27/Jun/2013:13:29:14 +0200] - ldbm backend flushing [27/Jun/2013:13:29:14 +0200] - ldbm backend done flushing [27/Jun/2013:13:29:15 +0200] - Event id 11ef230 called at 1372332555 (scheduled for 1372332555) [27/Jun/2013:13:29:25 +0200] - Event id 10e16a0 called at 1372332565 (scheduled for 1372332565) [27/Jun/2013:13:29:25 +0200] - Event id 11ef230 called at 1372332565 (scheduled for 1372332565) [27/Jun/2013:13:29:35 +0200] - Event id 11ef230 called at 1372332575 (scheduled for 1372332575) [27/Jun/2013:13:29:42 +0200] - Event id 7fb82000f770 called at 1372332582 (scheduled for 1372332582) [27/Jun/2013:13:29:44 +0200] - ldbm backend flushing [27/Jun/2013:13:29:44 +0200] - ldbm backend done flushing [27/Jun/2013:13:29:45 +0200] - Event id 11ef230 called at 1372332585 (scheduled for 1372332585) [27/Jun/2013:13:29:55 +0200] - Event id 11ef230 called at 1372332595 (scheduled for 1372332595) [27/Jun/2013:13:30:05 +0200] - Event id 11ef230 called at 1372332605 (scheduled for 1372332605) [27/Jun/2013:13:30:12 +0200] - Event id 7fb82000f770 called at 1372332612 (scheduled for 1372332612) [27/Jun/2013:13:30:14 +0200] - ldbm backend flushing [27/Jun/2013:13:30:14 +0200] - ldbm backend done flushing [27/Jun/2013:13:30:15 +0200] - Event id 11ef230 called at 1372332615 (scheduled for 1372332615) [27/Jun/2013:13:30:25 +0200] - Event id 10e16a0 called at 1372332625 (scheduled for 1372332625) [27/Jun/2013:13:30:25 +0200] - Event id 11ef230 called at 1372332625 (scheduled for 1372332625) [27/Jun/2013:13:30:35 +0200] - Event id 11ef230 called at 1372332635 (scheduled for 1372332635) [27/Jun/2013:13:30:42 +0200] - Event id 7fb82000f770 called at 1372332642 (scheduled for 1372332642) [27/Jun/2013:13:30:44 +0200] - ldbm backend flushing [27/Jun/2013:13:30:44 +0200] - ldbm backend done flushing [27/Jun/2013:13:30:45 +0200] - Event id 11ef230 called at 1372332645 (scheduled for 1372332645) [27/Jun/2013:13:30:55 +0200] - Event id 11ef230 called at 1372332655 (scheduled for 1372332655) [27/Jun/2013:13:30:55 +0200] - --> pagedresults_cleanup [27/Jun/2013:13:30:55 +0200] - <-- pagedresults_cleanup: 0 [27/Jun/2013:13:30:55 +0200] - new SSL connection on 64 [27/Jun/2013:13:30:55 +0200] - => ids_sasl_server_new (CONSUMER_TWO.COMPANY.si) [27/Jun/2013:13:30:55 +0200] - ids_sasl_getopt: plugin= option=log_level [27/Jun/2013:13:30:55 +0200] - ids_sasl_getopt: plugin= option=auto_transition [27/Jun/2013:13:30:55 +0200] - <= ids_sasl_server_new [27/Jun/2013:13:30:55 +0200] - => slapi_reslimit_get_integer_limit() conn=0xc81f2e10, handle=8 [27/Jun/2013:13:30:55 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:30:55 +0200] - --> pagedresults_is_timedout [27/Jun/2013:13:30:55 +0200] - <-- pagedresults_is_timedout: - [27/Jun/2013:13:30:55 +0200] - activity on 64r [27/Jun/2013:13:30:55 +0200] - read activity on 64 [27/Jun/2013:13:30:55 +0200] - add_pb [27/Jun/2013:13:30:55 +0200] - get_pb [27/Jun/2013:13:30:55 +0200] - PR_Recv for connection 5291 returns -5938 (Encountered end of file.) [27/Jun/2013:13:30:55 +0200] - --> pagedresults_reset_timedout [27/Jun/2013:13:30:55 +0200] - <-- pagedresults_reset_timedout: 0 [27/Jun/2013:13:30:55 +0200] - conn 5291 activity level = 0 [27/Jun/2013:13:30:55 +0200] - --> pagedresults_in_use [27/Jun/2013:13:30:55 +0200] - <-- pagedresults_in_use: 0 [27/Jun/2013:13:30:55 +0200] - conn 5291 leaving turbo mode due to 3 [27/Jun/2013:13:30:55 +0200] - listener got signaled [27/Jun/2013:13:30:55 +0200] - --> pagedresults_cleanup [27/Jun/2013:13:30:55 +0200] - <-- pagedresults_cleanup: 0 [27/Jun/2013:13:30:55 +0200] - --> pagedresults_cleanup [27/Jun/2013:13:30:55 +0200] - <-- pagedresults_cleanup: 0 [27/Jun/2013:13:30:55 +0200] - new connection on 64 [27/Jun/2013:13:30:55 +0200] - => ids_sasl_server_new (CONSUMER_TWO.COMPANY.si) [27/Jun/2013:13:30:55 +0200] - ids_sasl_getopt: plugin= option=log_level [27/Jun/2013:13:30:55 +0200] - ids_sasl_getopt: plugin= option=auto_transition [27/Jun/2013:13:30:55 +0200] - <= ids_sasl_server_new [27/Jun/2013:13:30:55 +0200] - => slapi_reslimit_get_integer_limit() conn=0xc81f2e10, handle=8 [27/Jun/2013:13:30:55 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:30:55 +0200] - --> pagedresults_is_timedout [27/Jun/2013:13:30:55 +0200] - <-- pagedresults_is_timedout: - [27/Jun/2013:13:30:55 +0200] - activity on 64r [27/Jun/2013:13:30:55 +0200] - read activity on 64 [27/Jun/2013:13:30:55 +0200] - add_pb [27/Jun/2013:13:30:55 +0200] - get_pb [27/Jun/2013:13:30:55 +0200] - --> pagedresults_reset_timedout [27/Jun/2013:13:30:55 +0200] - <-- pagedresults_reset_timedout: 0 [27/Jun/2013:13:30:55 +0200] - conn 5292 activity level = 0 [27/Jun/2013:13:30:55 +0200] - --> pagedresults_in_use [27/Jun/2013:13:30:55 +0200] - <-- pagedresults_in_use: 0 [27/Jun/2013:13:30:55 +0200] - conn 5292 leaving turbo mode due to 3 [27/Jun/2013:13:30:55 +0200] - listener got signaled [27/Jun/2013:13:30:55 +0200] - --> pagedresults_cleanup [27/Jun/2013:13:30:55 +0200] - <-- pagedresults_cleanup: 0 [27/Jun/2013:13:30:55 +0200] - --> pagedresults_cleanup [27/Jun/2013:13:30:55 +0200] - <-- pagedresults_cleanup: 0 [27/Jun/2013:13:30:55 +0200] - new connection on 64 [27/Jun/2013:13:30:55 +0200] - => ids_sasl_server_new (CONSUMER_TWO.COMPANY.si) [27/Jun/2013:13:30:55 +0200] - ids_sasl_getopt: plugin= option=log_level [27/Jun/2013:13:30:55 +0200] - ids_sasl_getopt: plugin= option=auto_transition [27/Jun/2013:13:30:55 +0200] - <= ids_sasl_server_new [27/Jun/2013:13:30:55 +0200] - => slapi_reslimit_get_integer_limit() conn=0xc81f2e10, handle=8 [27/Jun/2013:13:30:55 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:30:55 +0200] - --> pagedresults_is_timedout [27/Jun/2013:13:30:55 +0200] - <-- pagedresults_is_timedout: - [27/Jun/2013:13:30:55 +0200] - activity on 64r [27/Jun/2013:13:30:55 +0200] - read activity on 64 [27/Jun/2013:13:30:55 +0200] - add_pb [27/Jun/2013:13:30:55 +0200] - get_pb [27/Jun/2013:13:30:55 +0200] - conn 5293 activity level = 0 [27/Jun/2013:13:30:55 +0200] - --> pagedresults_in_use [27/Jun/2013:13:30:55 +0200] - <-- pagedresults_in_use: 0 [27/Jun/2013:13:30:55 +0200] - do_bind [27/Jun/2013:13:30:55 +0200] - => slapi_reslimit_get_integer_limit() conn=0xc81f2e10, handle=8 [27/Jun/2013:13:30:55 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:30:55 +0200] - BIND dn="eduPersonPrincipalName=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si" method=128 version=3 [27/Jun/2013:13:30:55 +0200] - listener got signaled [27/Jun/2013:13:30:55 +0200] - => get_ldapmessage_controls [27/Jun/2013:13:30:55 +0200] - <= get_ldapmessage_controls no controls [27/Jun/2013:13:30:55 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.16) [27/Jun/2013:13:30:55 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:30:55 +0200] - --> pagedresults_is_timedout [27/Jun/2013:13:30:55 +0200] - <-- pagedresults_is_timedout: - [27/Jun/2013:13:30:55 +0200] - do_bind: version 3 method 0x80 dn eduPersonPrincipalName=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si [27/Jun/2013:13:30:55 +0200] - mapping tree selected backend : COMPANY [27/Jun/2013:13:30:55 +0200] - Calling plugin 'Legacy replication preoperation plugin' #6 type 401 [27/Jun/2013:13:30:55 +0200] - Calling plugin 'Multimaster replication preoperation plugin' #9 type 401 [27/Jun/2013:13:30:55 +0200] - Calling plugin 'Pass Through Authentication' #10 type 401 [27/Jun/2013:13:30:55 +0200] passthru-plugin - => passthru_bindpreop [27/Jun/2013:13:30:55 +0200] passthru-plugin - <= not handled (not one of our suffixes) [27/Jun/2013:13:30:55 +0200] - slapi_str2filter "(|(objectclass=*)(objectclass=ldapsubentry))" [27/Jun/2013:13:30:55 +0200] - put_filter: OR [27/Jun/2013:13:30:55 +0200] - str2list "(objectclass=*)(objectclass=ldapsubentry)" [27/Jun/2013:13:30:55 +0200] - slapi_str2filter "(objectclass=*)" [27/Jun/2013:13:30:55 +0200] - slapi_str2filter: simple [27/Jun/2013:13:30:55 +0200] - str2simple "objectclass=*" [27/Jun/2013:13:30:55 +0200] - slapi_str2filter "(objectclass=ldapsubentry)" [27/Jun/2013:13:30:55 +0200] - slapi_str2filter: simple [27/Jun/2013:13:30:55 +0200] - str2simple "objectclass=ldapsubentry" [27/Jun/2013:13:30:55 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12) [27/Jun/2013:13:30:55 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:30:55 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18) [27/Jun/2013:13:30:55 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:30:55 +0200] - mapping tree selected backend : COMPANY [27/Jun/2013:13:30:55 +0200] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=6 [27/Jun/2013:13:30:55 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:30:55 +0200] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=5 [27/Jun/2013:13:30:55 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:30:55 +0200] - => compute_limits: sizelimit=-1, timelimit=-1 [27/Jun/2013:13:30:55 +0200] - Calling plugin 'Account Usability Plugin' #1 type 403 [27/Jun/2013:13:30:55 +0200] account-usability-plugin - --> auc_pre_search [27/Jun/2013:13:30:55 +0200] account-usability-plugin - <-- auc_pre_op [27/Jun/2013:13:30:55 +0200] - Calling plugin 'ACL preoperation' #2 type 403 [27/Jun/2013:13:30:55 +0200] - Calling plugin 'deref' #4 type 403 [27/Jun/2013:13:30:55 +0200] deref-plugin - --> deref_pre_search [27/Jun/2013:13:30:55 +0200] deref-plugin - <-- deref_pre_op [27/Jun/2013:13:30:55 +0200] - Calling plugin 'Legacy replication preoperation plugin' #6 type 403 [27/Jun/2013:13:30:55 +0200] - Calling plugin 'Multimaster replication preoperation plugin' #9 type 403 [27/Jun/2013:13:30:55 +0200] index_subsys_assign_filter_decoders - before: (|(objectClass=*)(objectClass=ldapsubentry)) [27/Jun/2013:13:30:55 +0200] index_subsys_assign_filter_decoders - after: (|(objectClass=*)(objectClass=ldapsubentry)) [27/Jun/2013:13:30:55 +0200] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=0 [27/Jun/2013:13:30:55 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:30:55 +0200] - => find_entry_internal (dn=edupersonprincipalname=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si) lock 0 [27/Jun/2013:13:30:55 +0200] - => dn2entry_ext "edupersonprincipalname=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si" [27/Jun/2013:13:30:55 +0200] - <= dn2entry_ext 7fb88c002680 [27/Jun/2013:13:30:55 +0200] - <= find_entry_internal_dn found (edupersonprincipalname=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si) [27/Jun/2013:13:30:55 +0200] - <= find_entry_internal [27/Jun/2013:13:30:55 +0200] - candidate list has 1 ids [27/Jun/2013:13:30:55 +0200] - slapi_filter_dup type 0xA1 [27/Jun/2013:13:30:55 +0200] - slapi_filter_dup type 0x87 [27/Jun/2013:13:30:55 +0200] - slapi_filter_dup type 0xA3 [27/Jun/2013:13:30:55 +0200] id2entry - => id2entry(6) [27/Jun/2013:13:30:55 +0200] id2entry - <= id2entry 7fb88c002680, dn "edupersonprincipalname=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si" (cache) [27/Jun/2013:13:30:55 +0200] id2entry - <= id2entry( 6 ) 7fb88c002680 (disk) [27/Jun/2013:13:30:55 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:30:55 +0200] - => test_substring_filter [27/Jun/2013:13:30:55 +0200] - OR [27/Jun/2013:13:30:55 +0200] - => vattr_test_filter_list [27/Jun/2013:13:30:55 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:30:55 +0200] - => test_substring_filter [27/Jun/2013:13:30:55 +0200] - PRESENT [27/Jun/2013:13:30:55 +0200] - <= slapi_vattr_filter_test 0 [27/Jun/2013:13:30:55 +0200] - <= test_filter_list 0 [27/Jun/2013:13:30:55 +0200] - <= slapi_vattr_filter_test 0 [27/Jun/2013:13:30:55 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:30:55 +0200] - => test_substring_filter [27/Jun/2013:13:30:55 +0200] - OR [27/Jun/2013:13:30:55 +0200] - => vattr_test_filter_list [27/Jun/2013:13:30:55 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:30:55 +0200] - => test_substring_filter [27/Jun/2013:13:30:55 +0200] - PRESENT [27/Jun/2013:13:30:55 +0200] - <= test_filter_list 0 [27/Jun/2013:13:30:55 +0200] - <= slapi_vattr_filter_test 0 [27/Jun/2013:13:30:55 +0200] - => send_ldap_search_entry (eduPersonPrincipalName=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si) [27/Jun/2013:13:30:55 +0200] - Calling plugin 'Account Usability Plugin' #1 type 410 [27/Jun/2013:13:30:55 +0200] - Calling plugin 'deref' #4 type 410 [27/Jun/2013:13:30:55 +0200] - Calling plugin 'Legacy replication preoperation plugin' #6 type 410 [27/Jun/2013:13:30:55 +0200] - <= send_ldap_search_entry [27/Jun/2013:13:30:55 +0200] - --> pagedresults_set_search_result: idx=-1, sr=0 [27/Jun/2013:13:30:55 +0200] - <-- pagedresults_set_search_result: -1 [27/Jun/2013:13:30:55 +0200] - slapi_filter_free type 0xA1 [27/Jun/2013:13:30:55 +0200] - slapi_filter_free type 0x87 [27/Jun/2013:13:30:55 +0200] - slapi_filter_free type 0xA3 [27/Jun/2013:13:30:55 +0200] - => send_ldap_result 0:: [27/Jun/2013:13:30:55 +0200] - <= send_ldap_result [27/Jun/2013:13:30:55 +0200] - mapping tree release backend : COMPANY [27/Jun/2013:13:30:55 +0200] - slapi_filter_free type 0xA1 [27/Jun/2013:13:30:55 +0200] - slapi_filter_free type 0x87 [27/Jun/2013:13:30:55 +0200] - slapi_filter_free type 0xA3 [27/Jun/2013:13:30:55 +0200] - slapi_str2filter "(|(objectclass=*)(objectclass=ldapsubentry))" [27/Jun/2013:13:30:55 +0200] - put_filter: OR [27/Jun/2013:13:30:55 +0200] - str2list "(objectclass=*)(objectclass=ldapsubentry)" [27/Jun/2013:13:30:55 +0200] - slapi_str2filter "(objectclass=*)" [27/Jun/2013:13:30:55 +0200] - slapi_str2filter: simple [27/Jun/2013:13:30:55 +0200] - str2simple "objectclass=*" [27/Jun/2013:13:30:55 +0200] - slapi_str2filter "(objectclass=ldapsubentry)" [27/Jun/2013:13:30:55 +0200] - slapi_str2filter: simple [27/Jun/2013:13:30:55 +0200] - str2simple "objectclass=ldapsubentry" [27/Jun/2013:13:30:55 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12) [27/Jun/2013:13:30:55 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:30:55 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18) [27/Jun/2013:13:30:55 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:30:55 +0200] - mapping tree selected backend : COMPANY [27/Jun/2013:13:30:55 +0200] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=6 [27/Jun/2013:13:30:55 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:30:55 +0200] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=5 [27/Jun/2013:13:30:55 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:30:55 +0200] - => compute_limits: sizelimit=-1, timelimit=-1 [27/Jun/2013:13:30:55 +0200] - Calling plugin 'Account Usability Plugin' #1 type 403 [27/Jun/2013:13:30:55 +0200] account-usability-plugin - --> auc_pre_search [27/Jun/2013:13:30:55 +0200] account-usability-plugin - <-- auc_pre_op [27/Jun/2013:13:30:55 +0200] - Calling plugin 'ACL preoperation' #2 type 403 [27/Jun/2013:13:30:55 +0200] - Calling plugin 'deref' #4 type 403 [27/Jun/2013:13:30:55 +0200] deref-plugin - --> deref_pre_search [27/Jun/2013:13:30:55 +0200] deref-plugin - <-- deref_pre_op [27/Jun/2013:13:30:55 +0200] - Calling plugin 'Legacy replication preoperation plugin' #6 type 403 [27/Jun/2013:13:30:55 +0200] - Calling plugin 'Multimaster replication preoperation plugin' #9 type 403 [27/Jun/2013:13:30:55 +0200] index_subsys_assign_filter_decoders - before: (|(objectClass=*)(objectClass=ldapsubentry)) [27/Jun/2013:13:30:55 +0200] index_subsys_assign_filter_decoders - after: (|(objectClass=*)(objectClass=ldapsubentry)) [27/Jun/2013:13:30:55 +0200] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=0 [27/Jun/2013:13:30:55 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:30:55 +0200] - => find_entry_internal (dn=edupersonprincipalname=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si) lock 0 [27/Jun/2013:13:30:55 +0200] - => dn2entry_ext "edupersonprincipalname=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si" [27/Jun/2013:13:30:55 +0200] - <= dn2entry_ext 7fb88c002680 [27/Jun/2013:13:30:55 +0200] - <= find_entry_internal_dn found (edupersonprincipalname=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si) [27/Jun/2013:13:30:55 +0200] - <= find_entry_internal [27/Jun/2013:13:30:55 +0200] - candidate list has 1 ids [27/Jun/2013:13:30:55 +0200] - slapi_filter_dup type 0xA1 [27/Jun/2013:13:30:55 +0200] - slapi_filter_dup type 0x87 [27/Jun/2013:13:30:55 +0200] - slapi_filter_dup type 0xA3 [27/Jun/2013:13:30:55 +0200] id2entry - => id2entry(6) [27/Jun/2013:13:30:55 +0200] id2entry - <= id2entry 7fb88c002680, dn "edupersonprincipalname=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si" (cache) [27/Jun/2013:13:30:55 +0200] id2entry - <= id2entry( 6 ) 7fb88c002680 (disk) [27/Jun/2013:13:30:55 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:30:55 +0200] - => test_substring_filter [27/Jun/2013:13:30:55 +0200] - OR [27/Jun/2013:13:30:55 +0200] - => vattr_test_filter_list [27/Jun/2013:13:30:55 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:30:55 +0200] - => test_substring_filter [27/Jun/2013:13:30:55 +0200] - PRESENT [27/Jun/2013:13:30:55 +0200] - <= slapi_vattr_filter_test 0 [27/Jun/2013:13:30:55 +0200] - <= test_filter_list 0 [27/Jun/2013:13:30:55 +0200] - <= slapi_vattr_filter_test 0 [27/Jun/2013:13:30:55 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:30:55 +0200] - => test_substring_filter [27/Jun/2013:13:30:55 +0200] - OR [27/Jun/2013:13:30:55 +0200] - => vattr_test_filter_list [27/Jun/2013:13:30:55 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:30:55 +0200] - => test_substring_filter [27/Jun/2013:13:30:55 +0200] - PRESENT [27/Jun/2013:13:30:55 +0200] - <= test_filter_list 0 [27/Jun/2013:13:30:55 +0200] - <= slapi_vattr_filter_test 0 [27/Jun/2013:13:30:55 +0200] - => send_ldap_search_entry (eduPersonPrincipalName=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si) [27/Jun/2013:13:30:55 +0200] - Calling plugin 'Account Usability Plugin' #1 type 410 [27/Jun/2013:13:30:55 +0200] - Calling plugin 'deref' #4 type 410 [27/Jun/2013:13:30:55 +0200] - Calling plugin 'Legacy replication preoperation plugin' #6 type 410 [27/Jun/2013:13:30:55 +0200] - <= send_ldap_search_entry [27/Jun/2013:13:30:55 +0200] - --> pagedresults_set_search_result: idx=-1, sr=0 [27/Jun/2013:13:30:55 +0200] - <-- pagedresults_set_search_result: -1 [27/Jun/2013:13:30:55 +0200] - slapi_filter_free type 0xA1 [27/Jun/2013:13:30:55 +0200] - slapi_filter_free type 0x87 [27/Jun/2013:13:30:55 +0200] - slapi_filter_free type 0xA3 [27/Jun/2013:13:30:55 +0200] - => send_ldap_result 0:: [27/Jun/2013:13:30:55 +0200] - <= send_ldap_result [27/Jun/2013:13:30:55 +0200] - mapping tree release backend : COMPANY [27/Jun/2013:13:30:55 +0200] - slapi_filter_free type 0xA1 [27/Jun/2013:13:30:55 +0200] - slapi_filter_free type 0x87 [27/Jun/2013:13:30:55 +0200] - slapi_filter_free type 0xA3 [27/Jun/2013:13:30:55 +0200] - => find_entry_internal (dn=eduPersonPrincipalName=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si) lock 0 [27/Jun/2013:13:30:55 +0200] - => dn2entry_ext "eduPersonPrincipalName=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si" [27/Jun/2013:13:30:55 +0200] - <= dn2entry_ext 7fb88c002680 [27/Jun/2013:13:30:55 +0200] - <= find_entry_internal_dn found (eduPersonPrincipalName=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si) [27/Jun/2013:13:30:55 +0200] - <= find_entry_internal [27/Jun/2013:13:30:55 +0200] - => slapi_pw_find value: "test0st3r0n" [27/Jun/2013:13:30:55 +0200] - <= slapi_pw_find matched "test0st3r0n" using scheme "CLEAR" [27/Jun/2013:13:30:55 +0200] - => reslimit_update_from_entry() conn=0xc81f2e10, entry=0x54015a70 [27/Jun/2013:13:30:55 +0200] - reslimit_update_from_entry(): setting limit for handle 0 (based on nsLookThroughLimit) [27/Jun/2013:13:30:55 +0200] - reslimit_update_from_entry(): setting limit for handle 1 (based on nsIDListScanLimit) [27/Jun/2013:13:30:55 +0200] - reslimit_update_from_entry(): setting limit for handle 2 (based on nsPagedLookThroughLimit) [27/Jun/2013:13:30:55 +0200] - reslimit_update_from_entry(): setting limit for handle 3 (based on nsPagedIDListScanLimit) [27/Jun/2013:13:30:55 +0200] - reslimit_update_from_entry(): setting limit for handle 4 (based on nsRangeSearchLookThroughLimit) [27/Jun/2013:13:30:55 +0200] - reslimit_update_from_entry(): setting limit for handle 5 (based on nsSizeLimit) [27/Jun/2013:13:30:55 +0200] - reslimit_update_from_entry(): setting limit for handle 6 (based on nsTimeLimit) [27/Jun/2013:13:30:55 +0200] - reslimit_update_from_entry(): setting limit for handle 7 (based on nsPagedSizeLimit) [27/Jun/2013:13:30:55 +0200] - reslimit_update_from_entry(): setting limit for handle 8 (based on nsIdleTimeout) [27/Jun/2013:13:30:55 +0200] - <= reslimit_update_from_entry() returning status 0 [27/Jun/2013:13:30:55 +0200] - slapi_str2filter "(|(objectclass=*)(objectclass=ldapsubentry))" [27/Jun/2013:13:30:55 +0200] - put_filter: OR [27/Jun/2013:13:30:55 +0200] - str2list "(objectclass=*)(objectclass=ldapsubentry)" [27/Jun/2013:13:30:55 +0200] - slapi_str2filter "(objectclass=*)" [27/Jun/2013:13:30:55 +0200] - slapi_str2filter: simple [27/Jun/2013:13:30:55 +0200] - str2simple "objectclass=*" [27/Jun/2013:13:30:55 +0200] - slapi_str2filter "(objectclass=ldapsubentry)" [27/Jun/2013:13:30:55 +0200] - slapi_str2filter: simple [27/Jun/2013:13:30:55 +0200] - str2simple "objectclass=ldapsubentry" [27/Jun/2013:13:30:55 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12) [27/Jun/2013:13:30:55 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:30:55 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18) [27/Jun/2013:13:30:55 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:30:55 +0200] - mapping tree selected backend : COMPANY [27/Jun/2013:13:30:55 +0200] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=6 [27/Jun/2013:13:30:55 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:30:55 +0200] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=5 [27/Jun/2013:13:30:55 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:30:55 +0200] - => compute_limits: sizelimit=-1, timelimit=-1 [27/Jun/2013:13:30:55 +0200] - Calling plugin 'Account Usability Plugin' #1 type 403 [27/Jun/2013:13:30:55 +0200] account-usability-plugin - --> auc_pre_search [27/Jun/2013:13:30:55 +0200] account-usability-plugin - <-- auc_pre_op [27/Jun/2013:13:30:55 +0200] - Calling plugin 'ACL preoperation' #2 type 403 [27/Jun/2013:13:30:55 +0200] - Calling plugin 'deref' #4 type 403 [27/Jun/2013:13:30:55 +0200] deref-plugin - --> deref_pre_search [27/Jun/2013:13:30:55 +0200] deref-plugin - <-- deref_pre_op [27/Jun/2013:13:30:55 +0200] - Calling plugin 'Legacy replication preoperation plugin' #6 type 403 [27/Jun/2013:13:30:55 +0200] - Calling plugin 'Multimaster replication preoperation plugin' #9 type 403 [27/Jun/2013:13:30:55 +0200] index_subsys_assign_filter_decoders - before: (|(objectClass=*)(objectClass=ldapsubentry)) [27/Jun/2013:13:30:55 +0200] index_subsys_assign_filter_decoders - after: (|(objectClass=*)(objectClass=ldapsubentry)) [27/Jun/2013:13:30:55 +0200] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=0 [27/Jun/2013:13:30:55 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:30:55 +0200] - => find_entry_internal (dn=edupersonprincipalname=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si) lock 0 [27/Jun/2013:13:30:55 +0200] - => dn2entry_ext "edupersonprincipalname=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si" [27/Jun/2013:13:30:55 +0200] - <= dn2entry_ext 7fb88c002680 [27/Jun/2013:13:30:55 +0200] - <= find_entry_internal_dn found (edupersonprincipalname=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si) [27/Jun/2013:13:30:55 +0200] - <= find_entry_internal [27/Jun/2013:13:30:55 +0200] - candidate list has 1 ids [27/Jun/2013:13:30:55 +0200] - slapi_filter_dup type 0xA1 [27/Jun/2013:13:30:55 +0200] - slapi_filter_dup type 0x87 [27/Jun/2013:13:30:55 +0200] - slapi_filter_dup type 0xA3 [27/Jun/2013:13:30:55 +0200] id2entry - => id2entry(6) [27/Jun/2013:13:30:55 +0200] id2entry - <= id2entry 7fb88c002680, dn "edupersonprincipalname=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si" (cache) [27/Jun/2013:13:30:55 +0200] id2entry - <= id2entry( 6 ) 7fb88c002680 (disk) [27/Jun/2013:13:30:55 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:30:55 +0200] - => test_substring_filter [27/Jun/2013:13:30:55 +0200] - OR [27/Jun/2013:13:30:55 +0200] - => vattr_test_filter_list [27/Jun/2013:13:30:55 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:30:55 +0200] - => test_substring_filter [27/Jun/2013:13:30:55 +0200] - PRESENT [27/Jun/2013:13:30:55 +0200] - <= slapi_vattr_filter_test 0 [27/Jun/2013:13:30:55 +0200] - <= test_filter_list 0 [27/Jun/2013:13:30:55 +0200] - <= slapi_vattr_filter_test 0 [27/Jun/2013:13:30:55 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:30:55 +0200] - => test_substring_filter [27/Jun/2013:13:30:55 +0200] - OR [27/Jun/2013:13:30:55 +0200] - => vattr_test_filter_list [27/Jun/2013:13:30:55 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:30:55 +0200] - => test_substring_filter [27/Jun/2013:13:30:55 +0200] - PRESENT [27/Jun/2013:13:30:55 +0200] - <= test_filter_list 0 [27/Jun/2013:13:30:55 +0200] - <= slapi_vattr_filter_test 0 [27/Jun/2013:13:30:55 +0200] - => send_ldap_search_entry (eduPersonPrincipalName=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si) [27/Jun/2013:13:30:55 +0200] - Calling plugin 'Account Usability Plugin' #1 type 410 [27/Jun/2013:13:30:55 +0200] - Calling plugin 'deref' #4 type 410 [27/Jun/2013:13:30:55 +0200] - Calling plugin 'Legacy replication preoperation plugin' #6 type 410 [27/Jun/2013:13:30:55 +0200] - <= send_ldap_search_entry [27/Jun/2013:13:30:55 +0200] - --> pagedresults_set_search_result: idx=-1, sr=0 [27/Jun/2013:13:30:55 +0200] - <-- pagedresults_set_search_result: -1 [27/Jun/2013:13:30:55 +0200] - slapi_filter_free type 0xA1 [27/Jun/2013:13:30:55 +0200] - slapi_filter_free type 0x87 [27/Jun/2013:13:30:55 +0200] - slapi_filter_free type 0xA3 [27/Jun/2013:13:30:55 +0200] - => send_ldap_result 0:: [27/Jun/2013:13:30:55 +0200] - <= send_ldap_result [27/Jun/2013:13:30:55 +0200] - mapping tree release backend : COMPANY [27/Jun/2013:13:30:55 +0200] - slapi_filter_free type 0xA1 [27/Jun/2013:13:30:55 +0200] - slapi_filter_free type 0x87 [27/Jun/2013:13:30:55 +0200] - slapi_filter_free type 0xA3 [27/Jun/2013:13:30:55 +0200] - => send_ldap_result 0:: [27/Jun/2013:13:30:55 +0200] - flush_ber() wrote 14 bytes to socket 64 [27/Jun/2013:13:30:55 +0200] - <= send_ldap_result [27/Jun/2013:13:30:55 +0200] - Calling plugin 'Multimaster replication postoperation plugin' #5 type 501 [27/Jun/2013:13:30:55 +0200] - activity on 64r [27/Jun/2013:13:30:55 +0200] - read activity on 64 [27/Jun/2013:13:30:55 +0200] - add_pb [27/Jun/2013:13:30:55 +0200] - get_pb [27/Jun/2013:13:30:55 +0200] - do_search [27/Jun/2013:13:30:55 +0200] - => slapi_reslimit_get_integer_limit() conn=0xc81f2e10, handle=8 [27/Jun/2013:13:30:55 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:30:55 +0200] - => get_filter_internal [27/Jun/2013:13:30:55 +0200] - EQUALITY [27/Jun/2013:13:30:55 +0200] - listener got signaled [27/Jun/2013:13:30:55 +0200] - <= get_filter_internal 0 [27/Jun/2013:13:30:55 +0200] get_filter - before optimize: [27/Jun/2013:13:30:55 +0200] get_filter - after optimize: [27/Jun/2013:13:30:55 +0200] - SRCH base="(null)" scope=0 deref=0 sizelimit=0 timelimit=0 attrsonly=0 filter="(cn=test)" attrs=ALL [27/Jun/2013:13:30:55 +0200] - => get_ldapmessage_controls [27/Jun/2013:13:30:55 +0200] - <= get_ldapmessage_controls no controls [27/Jun/2013:13:30:55 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.3) [27/Jun/2013:13:30:55 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:30:55 +0200] - --> pagedresults_is_timedout [27/Jun/2013:13:30:55 +0200] - <-- pagedresults_is_timedout: - [27/Jun/2013:13:30:55 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12) [27/Jun/2013:13:30:55 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:30:55 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18) [27/Jun/2013:13:30:55 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:30:55 +0200] - mapping tree selected backend : COMPANY [27/Jun/2013:13:30:55 +0200] - => slapi_reslimit_get_integer_limit() conn=0xc81f2e10, handle=6 [27/Jun/2013:13:30:55 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:30:55 +0200] - => slapi_reslimit_get_integer_limit() conn=0xc81f2e10, handle=5 [27/Jun/2013:13:30:55 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:30:55 +0200] - => compute_limits: sizelimit=2000, timelimit=3600 [27/Jun/2013:13:30:55 +0200] - Calling plugin 'Account Usability Plugin' #1 type 403 [27/Jun/2013:13:30:55 +0200] account-usability-plugin - --> auc_pre_search [27/Jun/2013:13:30:55 +0200] account-usability-plugin - <-- auc_pre_op [27/Jun/2013:13:30:55 +0200] - Calling plugin 'ACL preoperation' #2 type 403 [27/Jun/2013:13:30:55 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12) [27/Jun/2013:13:30:55 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:30:55 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18) [27/Jun/2013:13:30:55 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:30:55 +0200] NSACLPlugin - acl_init_userGroup: found in cache for dn:edupersonprincipalname=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si [27/Jun/2013:13:30:55 +0200] NSACLPlugin - Failed to find root for base: dc=si [27/Jun/2013:13:30:55 +0200] - Calling plugin 'deref' #4 type 403 [27/Jun/2013:13:30:55 +0200] deref-plugin - --> deref_pre_search [27/Jun/2013:13:30:55 +0200] deref-plugin - <-- deref_pre_op [27/Jun/2013:13:30:55 +0200] - Calling plugin 'Legacy replication preoperation plugin' #6 type 403 [27/Jun/2013:13:30:55 +0200] - Calling plugin 'Multimaster replication preoperation plugin' #9 type 403 [27/Jun/2013:13:30:55 +0200] index_subsys_assign_filter_decoders - before: (cn=test) [27/Jun/2013:13:30:55 +0200] index_subsys_assign_filter_decoders - after: (cn=test) [27/Jun/2013:13:30:55 +0200] - => slapi_reslimit_get_integer_limit() conn=0xc81f2e10, handle=0 [27/Jun/2013:13:30:55 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:30:55 +0200] - => find_entry_internal (dn=dc=TIER2,dc=COMPANY,dc=si) lock 0 [27/Jun/2013:13:30:55 +0200] - => dn2entry_ext "dc=TIER2,dc=COMPANY,dc=si" [27/Jun/2013:13:30:55 +0200] - <= dn2entry_ext 7fb83000af80 [27/Jun/2013:13:30:55 +0200] - <= find_entry_internal_dn found (dc=TIER2,dc=COMPANY,dc=si) [27/Jun/2013:13:30:55 +0200] - <= find_entry_internal [27/Jun/2013:13:30:55 +0200] - candidate list has 1 ids [27/Jun/2013:13:30:55 +0200] - slapi_filter_dup type 0xA3 [27/Jun/2013:13:30:55 +0200] id2entry - => id2entry(3) [27/Jun/2013:13:30:55 +0200] id2entry - <= id2entry 7fb83000af80, dn "dc=TIER2,dc=COMPANY,dc=si" (cache) [27/Jun/2013:13:30:55 +0200] id2entry - <= id2entry( 3 ) 7fb83000af80 (disk) [27/Jun/2013:13:30:55 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:30:55 +0200] - => test_substring_filter [27/Jun/2013:13:30:55 +0200] - EQUALITY [27/Jun/2013:13:30:55 +0200] NSACLPlugin - #### conn=5293 op=1 binddn="edupersonprincipalname=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si" [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ************ RESOURCE INFO STARTS ********* [27/Jun/2013:13:30:55 +0200] NSACLPlugin - Client DN: edupersonprincipalname=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si [27/Jun/2013:13:30:55 +0200] NSACLPlugin - resource type:256(search target_DN ) [27/Jun/2013:13:30:55 +0200] NSACLPlugin - Slapi_Entry DN: dc=TIER2,dc=COMPANY,dc=si [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ATTR: cn [27/Jun/2013:13:30:55 +0200] NSACLPlugin - rights:search [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ************ RESOURCE INFO ENDS ********* [27/Jun/2013:13:30:55 +0200] NSACLPlugin - Using ACL Container:0 for evaluation [27/Jun/2013:13:30:55 +0200] NSACLPlugin - Using ACL Container:1 for evaluation [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ***BEGIN ACL INFO[ Name: "Allow write to TIER2.COMPANY.si – root"]*** [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ACL Index:44 ACL_ELEVEL:2 [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ACI type:(compare search read write delete add target_DN target_attr acltxt allow_rule ) [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ACI RULE type:(userdn ip ) [27/Jun/2013:13:30:55 +0200] NSACLPlugin - Slapi_Entry DN:dc=TIER2,dc=COMPANY,dc=si [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ***END ACL INFO***************************** [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ***BEGIN ACL INFO[ Name: "Allow read - self"]*** [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ACL Index:45 ACL_ELEVEL:2 [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ACI type:(compare search read target_DN target_attr acltxt allow_rule ) [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ACI RULE type:(userdn ) [27/Jun/2013:13:30:55 +0200] NSACLPlugin - Slapi_Entry DN:dc=TIER2,dc=COMPANY,dc=si [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ***END ACL INFO***************************** [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ***BEGIN ACL INFO[ Name: "Allow read to TIER2.COMPANY.si - idp"]*** [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ACL Index:47 ACL_ELEVEL:2 [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ACI type:(compare search read target_attr acltxt allow_rule ) [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ACI RULE type:(userdn ) [27/Jun/2013:13:30:55 +0200] NSACLPlugin - Slapi_Entry DN:dc=TIER2,dc=COMPANY,dc=si [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ***END ACL INFO***************************** [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ***BEGIN ACL INFO[ Name: "Configuration Adminstrator"]*** [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ACL Index:34 ACL_ELEVEL:2 [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ACI type:(compare search read write delete add self target_attr acltxt allow_rule ) [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ACI RULE type:(userdn ) [27/Jun/2013:13:30:55 +0200] NSACLPlugin - Slapi_Entry DN:dc=COMPANY,dc=si [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ***END ACL INFO***************************** [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ***BEGIN ACL INFO[ Name: "Configuration Administrators Group"]*** [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ACL Index:35 ACL_ELEVEL:6 [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ACI type:(compare search read write delete add self target_attr acltxt allow_rule ) [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ACI RULE type:(groupdn ) [27/Jun/2013:13:30:55 +0200] NSACLPlugin - Slapi_Entry DN:dc=COMPANY,dc=si [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ***END ACL INFO***************************** [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ***BEGIN ACL INFO[ Name: "SIE Group"]*** [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ACL Index:36 ACL_ELEVEL:6 [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ACI type:(compare search read write delete add self target_attr acltxt allow_rule ) [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ACI RULE type:(groupdn ) [27/Jun/2013:13:30:55 +0200] NSACLPlugin - Slapi_Entry DN:dc=COMPANY,dc=si [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ***END ACL INFO***************************** [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ***BEGIN ACL INFO[ Name: "Directory Administrators Group"]*** [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ACL Index:37 ACL_ELEVEL:6 [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ACI type:(compare search read write delete add self target_attr acltxt allow_rule ) [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ACI RULE type:(groupdn ) [27/Jun/2013:13:30:55 +0200] NSACLPlugin - Slapi_Entry DN:dc=COMPANY,dc=si [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ***END ACL INFO***************************** [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ***BEGIN ACL INFO[ Name: "Write to COMPANY.si - root@COMPANY.si"]*** [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ACL Index:39 ACL_ELEVEL:2 [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ACI type:(compare search read write delete add target_DN target_attr acltxt allow_rule ) [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ACI RULE type:(userdn ip ) [27/Jun/2013:13:30:55 +0200] NSACLPlugin - Slapi_Entry DN:dc=COMPANY,dc=si [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ***END ACL INFO***************************** [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ***BEGIN ACL INFO[ Name: "Write to COMPANY.si - cn=root,dc=COMPANY,dc=si"]*** [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ACL Index:43 ACL_ELEVEL:2 [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ACI type:(compare search read write delete add target_DN target_attr acltxt allow_rule ) [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ACI RULE type:(userdn ) [27/Jun/2013:13:30:55 +0200] NSACLPlugin - Slapi_Entry DN:dc=COMPANY,dc=si [27/Jun/2013:13:30:55 +0200] NSACLPlugin - ***END ACL INFO***************************** [27/Jun/2013:13:30:55 +0200] NSACLPlugin - Num of ALLOW Handles:9, DENY handles:0 [27/Jun/2013:13:30:55 +0200] NSACLPlugin - Processed attr:cn for entry:dc=TIER2,dc=COMPANY,dc=si [27/Jun/2013:13:30:55 +0200] NSACLPlugin - 1. Evaluating ALLOW aci(44) " "Allow write to TIER2.COMPANY.si – root"" [27/Jun/2013:13:30:55 +0200] NSACLPlugin - 2. Evaluating ALLOW aci(35) " "Configuration Administrators Group"" [27/Jun/2013:13:30:55 +0200] NSACLPlugin - Evaluating user edupersonprincipalname=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si in group cn=Configuration Administrators,ou=Groups,ou=TopologyManagement,o=NetscapeRoot? [27/Jun/2013:13:30:55 +0200] NSACLPlugin - -- Not in cn=Configuration Administrators,ou=Groups,ou=TopologyManagement,o=NetscapeRoot [27/Jun/2013:13:30:55 +0200] NSACLPlugin - Evaluated ACL_FALSE [27/Jun/2013:13:30:55 +0200] NSACLPlugin - 3. Evaluating ALLOW aci(45) " "Allow read - self"" [27/Jun/2013:13:30:55 +0200] NSACLPlugin - 4. Evaluating ALLOW aci(47) " "Allow read to TIER2.COMPANY.si - idp"" [27/Jun/2013:13:30:55 +0200] NSACLPlugin - 5. Evaluating ALLOW aci(34) " "Configuration Adminstrator"" [27/Jun/2013:13:30:55 +0200] NSACLPlugin - 6. Evaluating ALLOW aci(36) " "SIE Group"" [27/Jun/2013:13:30:55 +0200] NSACLPlugin - Evaluating user edupersonprincipalname=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si in group dc=COMPANY,dc=si? [27/Jun/2013:13:30:55 +0200] NSACLPlugin - -- Not in cn=Configuration Administrators,ou=Groups,ou=TopologyManagement,o=NetscapeRoot [27/Jun/2013:13:30:55 +0200] NSACLPlugin - -- Not in dc=COMPANY,dc=si [27/Jun/2013:13:30:55 +0200] NSACLPlugin - Evaluated ACL_FALSE [27/Jun/2013:13:30:55 +0200] NSACLPlugin - 7. Evaluating ALLOW aci(37) " "Directory Administrators Group"" [27/Jun/2013:13:30:55 +0200] NSACLPlugin - Evaluating user edupersonprincipalname=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si in group cn=Directory Administrators,dc=COMPANY,dc=si? [27/Jun/2013:13:30:55 +0200] NSACLPlugin - -- Not in cn=Configuration Administrators,ou=Groups,ou=TopologyManagement,o=NetscapeRoot [27/Jun/2013:13:30:55 +0200] NSACLPlugin - -- Not in dc=COMPANY,dc=si [27/Jun/2013:13:30:55 +0200] NSACLPlugin - -- Not in cn=Directory Administrators,dc=COMPANY,dc=si [27/Jun/2013:13:30:55 +0200] NSACLPlugin - Evaluated ACL_FALSE [27/Jun/2013:13:30:55 +0200] NSACLPlugin - 8. Evaluating ALLOW aci(39) " "Write to COMPANY.si - root@COMPANY.si"" [27/Jun/2013:13:30:55 +0200] NSACLPlugin - 9. Evaluating ALLOW aci(43) " "Write to COMPANY.si - cn=root,dc=COMPANY,dc=si"" [27/Jun/2013:13:30:55 +0200] NSACLPlugin - conn=5293 op=1 (main): Deny search on entry(dc=TIER2,dc=COMPANY,dc=si).attr(cn) to edupersonprincipalname=TEST@TIER2.COMPANY.si,dc=TIER2,dc=COMPANY,dc=si: no aci matched the subject by aci(43): aciname= "Write to COMPANY.si - cn=root,dc=COMPANY,dc=si", acidn="dc=COMPANY,dc=si" [27/Jun/2013:13:30:55 +0200] - --> pagedresults_set_search_result: idx=-1, sr=0 [27/Jun/2013:13:30:55 +0200] - <-- pagedresults_set_search_result: -1 [27/Jun/2013:13:30:55 +0200] - slapi_filter_free type 0xA3 [27/Jun/2013:13:30:55 +0200] - => send_ldap_result 0:: [27/Jun/2013:13:30:55 +0200] - flush_ber() wrote 14 bytes to socket 64 [27/Jun/2013:13:30:55 +0200] - <= send_ldap_result [27/Jun/2013:13:30:55 +0200] - mapping tree release backend : COMPANY [27/Jun/2013:13:30:55 +0200] - slapi_filter_free type 0xA3 [27/Jun/2013:13:30:55 +0200] - activity on 64r [27/Jun/2013:13:30:55 +0200] - read activity on 64 [27/Jun/2013:13:30:55 +0200] - add_pb [27/Jun/2013:13:30:55 +0200] - get_pb [27/Jun/2013:13:30:55 +0200] - do_unbind [27/Jun/2013:13:30:55 +0200] - => get_ldapmessage_controls [27/Jun/2013:13:30:55 +0200] - <= get_ldapmessage_controls no controls [27/Jun/2013:13:30:55 +0200] - defbackend_noop [27/Jun/2013:13:30:55 +0200] - --> pagedresults_reset_timedout [27/Jun/2013:13:30:55 +0200] - <-- pagedresults_reset_timedout: 0 [27/Jun/2013:13:30:56 +0200] - => reslimit_update_from_entry() conn=0xc81f2e10, entry=0x0 [27/Jun/2013:13:30:56 +0200] - reslimit_update_from_entry(): setting limit for handle 0 (based on nsLookThroughLimit) [27/Jun/2013:13:30:56 +0200] - reslimit_update_from_entry(): setting limit for handle 1 (based on nsIDListScanLimit) [27/Jun/2013:13:30:56 +0200] - reslimit_update_from_entry(): setting limit for handle 2 (based on nsPagedLookThroughLimit) [27/Jun/2013:13:30:56 +0200] - reslimit_update_from_entry(): setting limit for handle 3 (based on nsPagedIDListScanLimit) [27/Jun/2013:13:30:56 +0200] - reslimit_update_from_entry(): setting limit for handle 4 (based on nsRangeSearchLookThroughLimit) [27/Jun/2013:13:30:56 +0200] - reslimit_update_from_entry(): setting limit for handle 5 (based on nsSizeLimit) [27/Jun/2013:13:30:56 +0200] - reslimit_update_from_entry(): setting limit for handle 6 (based on nsTimeLimit) [27/Jun/2013:13:30:56 +0200] - reslimit_update_from_entry(): setting limit for handle 7 (based on nsPagedSizeLimit) [27/Jun/2013:13:30:56 +0200] - reslimit_update_from_entry(): setting limit for handle 8 (based on nsIdleTimeout) [27/Jun/2013:13:30:56 +0200] - <= reslimit_update_from_entry() returning status 0 [27/Jun/2013:13:30:56 +0200] - --> pagedresults_cleanup [27/Jun/2013:13:30:56 +0200] - <-- pagedresults_cleanup: 0 [27/Jun/2013:13:31:00 +0200] - --> pagedresults_cleanup [27/Jun/2013:13:31:00 +0200] - <-- pagedresults_cleanup: 0 [27/Jun/2013:13:31:00 +0200] - new connection on 64 [27/Jun/2013:13:31:00 +0200] - => ids_sasl_server_new (CONSUMER_TWO.COMPANY.si) [27/Jun/2013:13:31:00 +0200] - ids_sasl_getopt: plugin= option=log_level [27/Jun/2013:13:31:00 +0200] - ids_sasl_getopt: plugin= option=auto_transition [27/Jun/2013:13:31:00 +0200] - <= ids_sasl_server_new [27/Jun/2013:13:31:00 +0200] - => slapi_reslimit_get_integer_limit() conn=0xc81f2e10, handle=8 [27/Jun/2013:13:31:00 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:31:00 +0200] - --> pagedresults_is_timedout [27/Jun/2013:13:31:00 +0200] - <-- pagedresults_is_timedout: - [27/Jun/2013:13:31:00 +0200] - activity on 64r [27/Jun/2013:13:31:00 +0200] - read activity on 64 [27/Jun/2013:13:31:00 +0200] - add_pb [27/Jun/2013:13:31:00 +0200] - get_pb [27/Jun/2013:13:31:00 +0200] - conn 5294 activity level = 0 [27/Jun/2013:13:31:00 +0200] - --> pagedresults_in_use [27/Jun/2013:13:31:00 +0200] - <-- pagedresults_in_use: 0 [27/Jun/2013:13:31:00 +0200] - do_extended [27/Jun/2013:13:31:00 +0200] - => slapi_reslimit_get_integer_limit() conn=0xc81f2e10, handle=8 [27/Jun/2013:13:31:00 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:31:00 +0200] - do_extended: oid (1.3.6.1.4.1.1466.20037-startTLS) [27/Jun/2013:13:31:00 +0200] - listener got signaled [27/Jun/2013:13:31:00 +0200] - => get_ldapmessage_controls [27/Jun/2013:13:31:00 +0200] - <= get_ldapmessage_controls no controls [27/Jun/2013:13:31:00 +0200] - --> pagedresults_is_timedout [27/Jun/2013:13:31:00 +0200] - <-- pagedresults_is_timedout: - [27/Jun/2013:13:31:00 +0200] start_tls - Received extended operation request with OID 1.3.6.1.4.1.1466.20037 [27/Jun/2013:13:31:00 +0200] start_tls - Start TLS extended operation request confirmed. [27/Jun/2013:13:31:00 +0200] start_tls - Start TLS request accepted.Server willing to negotiate SSL. [27/Jun/2013:13:31:00 +0200] - => send_ldap_result 0::Start TLS request accepted.Server willing to negotiate SSL. [27/Jun/2013:13:31:00 +0200] - flush_ber() wrote 97 bytes to socket 64 [27/Jun/2013:13:31:00 +0200] - <= send_ldap_result [27/Jun/2013:13:31:00 +0200] start_tls - Starting SSL Handshake. [27/Jun/2013:13:31:00 +0200] - activity on 64r [27/Jun/2013:13:31:00 +0200] - read activity on 64 [27/Jun/2013:13:31:00 +0200] - add_pb [27/Jun/2013:13:31:00 +0200] - get_pb [27/Jun/2013:13:31:00 +0200] - do_bind [27/Jun/2013:13:31:00 +0200] - => slapi_reslimit_get_integer_limit() conn=0xc81f2e10, handle=8 [27/Jun/2013:13:31:00 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:31:00 +0200] - BIND dn="cn=replication manager,cn=config" method=128 version=3 [27/Jun/2013:13:31:00 +0200] - listener got signaled [27/Jun/2013:13:31:00 +0200] - => get_ldapmessage_controls [27/Jun/2013:13:31:00 +0200] - <= get_ldapmessage_controls no controls [27/Jun/2013:13:31:00 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.16) [27/Jun/2013:13:31:00 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:31:00 +0200] - --> pagedresults_is_timedout [27/Jun/2013:13:31:00 +0200] - <-- pagedresults_is_timedout: - [27/Jun/2013:13:31:00 +0200] - do_bind: version 3 method 0x80 dn cn=replication manager,cn=config [27/Jun/2013:13:31:00 +0200] - mapping tree selected backend : frontend-internal [27/Jun/2013:13:31:00 +0200] - Calling plugin 'Legacy replication preoperation plugin' #6 type 401 [27/Jun/2013:13:31:00 +0200] - Calling plugin 'Multimaster replication preoperation plugin' #9 type 401 [27/Jun/2013:13:31:00 +0200] - Calling plugin 'Pass Through Authentication' #10 type 401 [27/Jun/2013:13:31:00 +0200] passthru-plugin - => passthru_bindpreop [27/Jun/2013:13:31:00 +0200] passthru-plugin - <= not handled (not one of our suffixes) [27/Jun/2013:13:31:00 +0200] - slapi_str2filter "(|(objectclass=*)(objectclass=ldapsubentry))" [27/Jun/2013:13:31:00 +0200] - put_filter: OR [27/Jun/2013:13:31:00 +0200] - str2list "(objectclass=*)(objectclass=ldapsubentry)" [27/Jun/2013:13:31:00 +0200] - slapi_str2filter "(objectclass=*)" [27/Jun/2013:13:31:00 +0200] - slapi_str2filter: simple [27/Jun/2013:13:31:00 +0200] - str2simple "objectclass=*" [27/Jun/2013:13:31:00 +0200] - slapi_str2filter "(objectclass=ldapsubentry)" [27/Jun/2013:13:31:00 +0200] - slapi_str2filter: simple [27/Jun/2013:13:31:00 +0200] - str2simple "objectclass=ldapsubentry" [27/Jun/2013:13:31:00 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12) [27/Jun/2013:13:31:00 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:31:00 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18) [27/Jun/2013:13:31:00 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:31:00 +0200] - mapping tree selected backend : frontend-internal [27/Jun/2013:13:31:00 +0200] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=6 [27/Jun/2013:13:31:00 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:31:00 +0200] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=5 [27/Jun/2013:13:31:00 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:31:00 +0200] - => compute_limits: sizelimit=-1, timelimit=-1 [27/Jun/2013:13:31:00 +0200] - Calling plugin 'Account Usability Plugin' #1 type 403 [27/Jun/2013:13:31:00 +0200] account-usability-plugin - --> auc_pre_search [27/Jun/2013:13:31:00 +0200] account-usability-plugin - <-- auc_pre_op [27/Jun/2013:13:31:00 +0200] - Calling plugin 'ACL preoperation' #2 type 403 [27/Jun/2013:13:31:00 +0200] - Calling plugin 'deref' #4 type 403 [27/Jun/2013:13:31:00 +0200] deref-plugin - --> deref_pre_search [27/Jun/2013:13:31:00 +0200] deref-plugin - <-- deref_pre_op [27/Jun/2013:13:31:00 +0200] - Calling plugin 'Legacy replication preoperation plugin' #6 type 403 [27/Jun/2013:13:31:00 +0200] - Calling plugin 'Multimaster replication preoperation plugin' #9 type 403 [27/Jun/2013:13:31:00 +0200] index_subsys_assign_filter_decoders - before: (|(objectClass=*)(objectClass=ldapsubentry)) [27/Jun/2013:13:31:00 +0200] index_subsys_assign_filter_decoders - after: (|(objectClass=*)(objectClass=ldapsubentry)) [27/Jun/2013:13:31:00 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:31:00 +0200] - => test_substring_filter [27/Jun/2013:13:31:00 +0200] - OR [27/Jun/2013:13:31:00 +0200] - => vattr_test_filter_list [27/Jun/2013:13:31:00 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:31:00 +0200] - => test_substring_filter [27/Jun/2013:13:31:00 +0200] - PRESENT [27/Jun/2013:13:31:00 +0200] - <= slapi_vattr_filter_test 0 [27/Jun/2013:13:31:00 +0200] - <= test_filter_list 0 [27/Jun/2013:13:31:00 +0200] - <= slapi_vattr_filter_test 0 [27/Jun/2013:13:31:00 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:31:00 +0200] - => test_substring_filter [27/Jun/2013:13:31:00 +0200] - OR [27/Jun/2013:13:31:00 +0200] - => vattr_test_filter_list [27/Jun/2013:13:31:00 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:31:00 +0200] - => test_substring_filter [27/Jun/2013:13:31:00 +0200] - PRESENT [27/Jun/2013:13:31:00 +0200] - <= test_filter_list 0 [27/Jun/2013:13:31:00 +0200] - <= slapi_vattr_filter_test 0 [27/Jun/2013:13:31:00 +0200] - => send_ldap_search_entry (cn=replication manager,cn=config) [27/Jun/2013:13:31:00 +0200] - Calling plugin 'Account Usability Plugin' #1 type 410 [27/Jun/2013:13:31:00 +0200] - Calling plugin 'deref' #4 type 410 [27/Jun/2013:13:31:00 +0200] - Calling plugin 'Legacy replication preoperation plugin' #6 type 410 [27/Jun/2013:13:31:00 +0200] - <= send_ldap_search_entry [27/Jun/2013:13:31:00 +0200] - => send_ldap_result 0:: [27/Jun/2013:13:31:00 +0200] - <= send_ldap_result [27/Jun/2013:13:31:00 +0200] - mapping tree release backend : frontend-internal [27/Jun/2013:13:31:00 +0200] - slapi_filter_free type 0xA1 [27/Jun/2013:13:31:00 +0200] - slapi_filter_free type 0x87 [27/Jun/2013:13:31:00 +0200] - slapi_filter_free type 0xA3 [27/Jun/2013:13:31:00 +0200] - slapi_str2filter "(|(objectclass=*)(objectclass=ldapsubentry))" [27/Jun/2013:13:31:00 +0200] - put_filter: OR [27/Jun/2013:13:31:00 +0200] - str2list "(objectclass=*)(objectclass=ldapsubentry)" [27/Jun/2013:13:31:00 +0200] - slapi_str2filter "(objectclass=*)" [27/Jun/2013:13:31:00 +0200] - slapi_str2filter: simple [27/Jun/2013:13:31:00 +0200] - str2simple "objectclass=*" [27/Jun/2013:13:31:00 +0200] - slapi_str2filter "(objectclass=ldapsubentry)" [27/Jun/2013:13:31:00 +0200] - slapi_str2filter: simple [27/Jun/2013:13:31:00 +0200] - str2simple "objectclass=ldapsubentry" [27/Jun/2013:13:31:00 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12) [27/Jun/2013:13:31:00 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:31:00 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18) [27/Jun/2013:13:31:00 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:31:00 +0200] - mapping tree selected backend : frontend-internal [27/Jun/2013:13:31:00 +0200] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=6 [27/Jun/2013:13:31:00 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:31:00 +0200] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=5 [27/Jun/2013:13:31:00 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:31:00 +0200] - => compute_limits: sizelimit=-1, timelimit=-1 [27/Jun/2013:13:31:00 +0200] - Calling plugin 'Account Usability Plugin' #1 type 403 [27/Jun/2013:13:31:00 +0200] account-usability-plugin - --> auc_pre_search [27/Jun/2013:13:31:00 +0200] account-usability-plugin - <-- auc_pre_op [27/Jun/2013:13:31:00 +0200] - Calling plugin 'ACL preoperation' #2 type 403 [27/Jun/2013:13:31:00 +0200] - Calling plugin 'deref' #4 type 403 [27/Jun/2013:13:31:00 +0200] deref-plugin - --> deref_pre_search [27/Jun/2013:13:31:00 +0200] deref-plugin - <-- deref_pre_op [27/Jun/2013:13:31:00 +0200] - Calling plugin 'Legacy replication preoperation plugin' #6 type 403 [27/Jun/2013:13:31:00 +0200] - Calling plugin 'Multimaster replication preoperation plugin' #9 type 403 [27/Jun/2013:13:31:00 +0200] index_subsys_assign_filter_decoders - before: (|(objectClass=*)(objectClass=ldapsubentry)) [27/Jun/2013:13:31:00 +0200] index_subsys_assign_filter_decoders - after: (|(objectClass=*)(objectClass=ldapsubentry)) [27/Jun/2013:13:31:00 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:31:00 +0200] - => test_substring_filter [27/Jun/2013:13:31:00 +0200] - OR [27/Jun/2013:13:31:00 +0200] - => vattr_test_filter_list [27/Jun/2013:13:31:00 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:31:00 +0200] - => test_substring_filter [27/Jun/2013:13:31:00 +0200] - PRESENT [27/Jun/2013:13:31:00 +0200] - <= slapi_vattr_filter_test 0 [27/Jun/2013:13:31:00 +0200] - <= test_filter_list 0 [27/Jun/2013:13:31:00 +0200] - <= slapi_vattr_filter_test 0 [27/Jun/2013:13:31:00 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:31:00 +0200] - => test_substring_filter [27/Jun/2013:13:31:00 +0200] - OR [27/Jun/2013:13:31:00 +0200] - => vattr_test_filter_list [27/Jun/2013:13:31:00 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:31:00 +0200] - => test_substring_filter [27/Jun/2013:13:31:00 +0200] - PRESENT [27/Jun/2013:13:31:00 +0200] - <= test_filter_list 0 [27/Jun/2013:13:31:00 +0200] - <= slapi_vattr_filter_test 0 [27/Jun/2013:13:31:00 +0200] - => send_ldap_search_entry (cn=replication manager,cn=config) [27/Jun/2013:13:31:00 +0200] - Calling plugin 'Account Usability Plugin' #1 type 410 [27/Jun/2013:13:31:00 +0200] - Calling plugin 'deref' #4 type 410 [27/Jun/2013:13:31:00 +0200] - Calling plugin 'Legacy replication preoperation plugin' #6 type 410 [27/Jun/2013:13:31:00 +0200] - <= send_ldap_search_entry [27/Jun/2013:13:31:00 +0200] - => send_ldap_result 0:: [27/Jun/2013:13:31:00 +0200] - <= send_ldap_result [27/Jun/2013:13:31:00 +0200] - mapping tree release backend : frontend-internal [27/Jun/2013:13:31:00 +0200] - slapi_filter_free type 0xA1 [27/Jun/2013:13:31:00 +0200] - slapi_filter_free type 0x87 [27/Jun/2013:13:31:00 +0200] - slapi_filter_free type 0xA3 [27/Jun/2013:13:31:00 +0200] - => slapi_pw_find value: "r3pl1k4t0r" [27/Jun/2013:13:31:00 +0200] - <= slapi_pw_find matched "r3pl1k4t0r" using scheme "CLEAR" [27/Jun/2013:13:31:00 +0200] - => reslimit_update_from_entry() conn=0xc81f2e10, entry=0x880137f0 [27/Jun/2013:13:31:00 +0200] - reslimit_update_from_entry(): setting limit for handle 0 (based on nsLookThroughLimit) [27/Jun/2013:13:31:00 +0200] - reslimit_update_from_entry(): setting limit for handle 1 (based on nsIDListScanLimit) [27/Jun/2013:13:31:00 +0200] - reslimit_update_from_entry(): setting limit for handle 2 (based on nsPagedLookThroughLimit) [27/Jun/2013:13:31:00 +0200] - reslimit_update_from_entry(): setting limit for handle 3 (based on nsPagedIDListScanLimit) [27/Jun/2013:13:31:00 +0200] - reslimit_update_from_entry(): setting limit for handle 4 (based on nsRangeSearchLookThroughLimit) [27/Jun/2013:13:31:00 +0200] - reslimit_update_from_entry(): setting limit for handle 5 (based on nsSizeLimit) [27/Jun/2013:13:31:00 +0200] - reslimit_update_from_entry(): setting limit for handle 6 (based on nsTimeLimit) [27/Jun/2013:13:31:00 +0200] - reslimit_update_from_entry(): setting limit for handle 7 (based on nsPagedSizeLimit) [27/Jun/2013:13:31:00 +0200] - reslimit_update_from_entry(): setting limit for handle 8 (based on nsIdleTimeout) [27/Jun/2013:13:31:00 +0200] - reslimit_update_from_entry(): set limit based on nsIdleTimeout to 0 [27/Jun/2013:13:31:00 +0200] - <= reslimit_update_from_entry() returning status 0 [27/Jun/2013:13:31:00 +0200] - slapi_str2filter "(|(objectclass=*)(objectclass=ldapsubentry))" [27/Jun/2013:13:31:00 +0200] - put_filter: OR [27/Jun/2013:13:31:00 +0200] - str2list "(objectclass=*)(objectclass=ldapsubentry)" [27/Jun/2013:13:31:00 +0200] - slapi_str2filter "(objectclass=*)" [27/Jun/2013:13:31:00 +0200] - slapi_str2filter: simple [27/Jun/2013:13:31:00 +0200] - str2simple "objectclass=*" [27/Jun/2013:13:31:00 +0200] - slapi_str2filter "(objectclass=ldapsubentry)" [27/Jun/2013:13:31:00 +0200] - slapi_str2filter: simple [27/Jun/2013:13:31:00 +0200] - str2simple "objectclass=ldapsubentry" [27/Jun/2013:13:31:00 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12) [27/Jun/2013:13:31:00 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:31:00 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18) [27/Jun/2013:13:31:00 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:31:00 +0200] - mapping tree selected backend : frontend-internal [27/Jun/2013:13:31:00 +0200] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=6 [27/Jun/2013:13:31:00 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:31:00 +0200] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=5 [27/Jun/2013:13:31:00 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:31:00 +0200] - => compute_limits: sizelimit=-1, timelimit=-1 [27/Jun/2013:13:31:00 +0200] - Calling plugin 'Account Usability Plugin' #1 type 403 [27/Jun/2013:13:31:00 +0200] account-usability-plugin - --> auc_pre_search [27/Jun/2013:13:31:00 +0200] account-usability-plugin - <-- auc_pre_op [27/Jun/2013:13:31:00 +0200] - Calling plugin 'ACL preoperation' #2 type 403 [27/Jun/2013:13:31:00 +0200] - Calling plugin 'deref' #4 type 403 [27/Jun/2013:13:31:00 +0200] deref-plugin - --> deref_pre_search [27/Jun/2013:13:31:00 +0200] deref-plugin - <-- deref_pre_op [27/Jun/2013:13:31:00 +0200] - Calling plugin 'Legacy replication preoperation plugin' #6 type 403 [27/Jun/2013:13:31:00 +0200] - Calling plugin 'Multimaster replication preoperation plugin' #9 type 403 [27/Jun/2013:13:31:00 +0200] index_subsys_assign_filter_decoders - before: (|(objectClass=*)(objectClass=ldapsubentry)) [27/Jun/2013:13:31:00 +0200] index_subsys_assign_filter_decoders - after: (|(objectClass=*)(objectClass=ldapsubentry)) [27/Jun/2013:13:31:00 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:31:00 +0200] - => test_substring_filter [27/Jun/2013:13:31:00 +0200] - OR [27/Jun/2013:13:31:00 +0200] - => vattr_test_filter_list [27/Jun/2013:13:31:00 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:31:00 +0200] - => test_substring_filter [27/Jun/2013:13:31:00 +0200] - PRESENT [27/Jun/2013:13:31:00 +0200] - <= slapi_vattr_filter_test 0 [27/Jun/2013:13:31:00 +0200] - <= test_filter_list 0 [27/Jun/2013:13:31:00 +0200] - <= slapi_vattr_filter_test 0 [27/Jun/2013:13:31:00 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:31:00 +0200] - => test_substring_filter [27/Jun/2013:13:31:00 +0200] - OR [27/Jun/2013:13:31:00 +0200] - => vattr_test_filter_list [27/Jun/2013:13:31:00 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:31:00 +0200] - => test_substring_filter [27/Jun/2013:13:31:00 +0200] - PRESENT [27/Jun/2013:13:31:00 +0200] - <= test_filter_list 0 [27/Jun/2013:13:31:00 +0200] - <= slapi_vattr_filter_test 0 [27/Jun/2013:13:31:00 +0200] - => send_ldap_search_entry (cn=replication manager,cn=config) [27/Jun/2013:13:31:00 +0200] - Calling plugin 'Account Usability Plugin' #1 type 410 [27/Jun/2013:13:31:00 +0200] - Calling plugin 'deref' #4 type 410 [27/Jun/2013:13:31:00 +0200] - Calling plugin 'Legacy replication preoperation plugin' #6 type 410 [27/Jun/2013:13:31:00 +0200] - <= send_ldap_search_entry [27/Jun/2013:13:31:00 +0200] - => send_ldap_result 0:: [27/Jun/2013:13:31:00 +0200] - <= send_ldap_result [27/Jun/2013:13:31:00 +0200] - mapping tree release backend : frontend-internal [27/Jun/2013:13:31:01 +0200] - slapi_filter_free type 0xA1 [27/Jun/2013:13:31:01 +0200] - slapi_filter_free type 0x87 [27/Jun/2013:13:31:01 +0200] - slapi_filter_free type 0xA3 [27/Jun/2013:13:31:01 +0200] - => send_ldap_result 0:: [27/Jun/2013:13:31:01 +0200] - flush_ber() wrote 14 bytes to socket 64 [27/Jun/2013:13:31:01 +0200] - <= send_ldap_result [27/Jun/2013:13:31:01 +0200] - Calling plugin 'Multimaster replication postoperation plugin' #5 type 501 [27/Jun/2013:13:31:01 +0200] - activity on 64r [27/Jun/2013:13:31:01 +0200] - read activity on 64 [27/Jun/2013:13:31:01 +0200] - add_pb [27/Jun/2013:13:31:01 +0200] - get_pb [27/Jun/2013:13:31:01 +0200] - do_search [27/Jun/2013:13:31:01 +0200] - => slapi_reslimit_get_integer_limit() conn=0xc81f2e10, handle=8 [27/Jun/2013:13:31:01 +0200] - <= slapi_reslimit_get_integer_limit() returning SUCCESS, value=0 [27/Jun/2013:13:31:01 +0200] - listener got signaled [27/Jun/2013:13:31:01 +0200] - => get_filter_internal [27/Jun/2013:13:31:01 +0200] - --> pagedresults_is_timedout [27/Jun/2013:13:31:01 +0200] - <-- pagedresults_is_timedout: - [27/Jun/2013:13:31:01 +0200] - PRESENT [27/Jun/2013:13:31:01 +0200] - <= get_filter_internal 0 [27/Jun/2013:13:31:01 +0200] get_filter - before optimize: (objectClass=*) [27/Jun/2013:13:31:01 +0200] get_filter - after optimize: (objectClass=*) [27/Jun/2013:13:31:01 +0200] - SRCH base="(null)" scope=0 deref=0 sizelimit=0 timelimit=600 attrsonly=0 filter="(objectClass=*)" attrs="supportedControl supportedExtension" [27/Jun/2013:13:31:01 +0200] - => get_ldapmessage_controls [27/Jun/2013:13:31:01 +0200] - <= get_ldapmessage_controls no controls [27/Jun/2013:13:31:01 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.3) [27/Jun/2013:13:31:01 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:31:01 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12) [27/Jun/2013:13:31:01 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:31:01 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18) [27/Jun/2013:13:31:01 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:31:01 +0200] - => slapi_reslimit_get_integer_limit() conn=0xc81f2e10, handle=6 [27/Jun/2013:13:31:01 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:31:01 +0200] - => slapi_reslimit_get_integer_limit() conn=0xc81f2e10, handle=5 [27/Jun/2013:13:31:01 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:31:01 +0200] - => compute_limits: sizelimit=2000, timelimit=600 [27/Jun/2013:13:31:01 +0200] - Calling plugin 'Account Usability Plugin' #1 type 403 [27/Jun/2013:13:31:01 +0200] account-usability-plugin - --> auc_pre_search [27/Jun/2013:13:31:01 +0200] account-usability-plugin - <-- auc_pre_op [27/Jun/2013:13:31:01 +0200] - Calling plugin 'ACL preoperation' #2 type 403 [27/Jun/2013:13:31:01 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12) [27/Jun/2013:13:31:01 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:31:01 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18) [27/Jun/2013:13:31:01 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:31:01 +0200] - Calling plugin 'deref' #4 type 403 [27/Jun/2013:13:31:01 +0200] deref-plugin - --> deref_pre_search [27/Jun/2013:13:31:01 +0200] deref-plugin - <-- deref_pre_op [27/Jun/2013:13:31:01 +0200] - Calling plugin 'Legacy replication preoperation plugin' #6 type 403 [27/Jun/2013:13:31:01 +0200] - Calling plugin 'Multimaster replication preoperation plugin' #9 type 403 [27/Jun/2013:13:31:01 +0200] index_subsys_assign_filter_decoders - before: (objectClass=*) [27/Jun/2013:13:31:01 +0200] index_subsys_assign_filter_decoders - after: (objectClass=*) [27/Jun/2013:13:31:01 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:31:01 +0200] - => test_substring_filter [27/Jun/2013:13:31:01 +0200] - PRESENT [27/Jun/2013:13:31:01 +0200] - <= slapi_vattr_filter_test 0 [27/Jun/2013:13:31:01 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:31:01 +0200] - => test_substring_filter [27/Jun/2013:13:31:01 +0200] - PRESENT [27/Jun/2013:13:31:01 +0200] - <= slapi_vattr_filter_test 0 [27/Jun/2013:13:31:01 +0200] - => ids_sasl_listmech [27/Jun/2013:13:31:01 +0200] - ids_sasl_getopt: plugin= option=mech_list [27/Jun/2013:13:31:01 +0200] - ids_sasl_getopt: plugin= option=mech_list [27/Jun/2013:13:31:01 +0200] - ids_sasl_getopt: plugin= option=mech_list [27/Jun/2013:13:31:01 +0200] - ids_sasl_getopt: plugin= option=mech_list [27/Jun/2013:13:31:01 +0200] - ids_sasl_getopt: plugin= option=mech_list [27/Jun/2013:13:31:01 +0200] - ids_sasl_getopt: plugin= option=mech_list [27/Jun/2013:13:31:01 +0200] - ids_sasl_getopt: plugin= option=mech_list [27/Jun/2013:13:31:01 +0200] - ids_sasl_getopt: plugin= option=mech_list [27/Jun/2013:13:31:01 +0200] - ids_sasl_getopt: plugin= option=mech_list [27/Jun/2013:13:31:01 +0200] - ids_sasl_getopt: plugin= option=mech_list [27/Jun/2013:13:31:01 +0200] - sasl library mechs: CRAM-MD5,GSSAPI,DIGEST-MD5,ANONYMOUS [27/Jun/2013:13:31:01 +0200] - <= ids_sasl_listmech [27/Jun/2013:13:31:01 +0200] - slapi_str2filter "objectclass=vlvsearch" [27/Jun/2013:13:31:01 +0200] - slapi_str2filter: default [27/Jun/2013:13:31:01 +0200] - str2simple "objectclass=vlvsearch" [27/Jun/2013:13:31:01 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12) [27/Jun/2013:13:31:01 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:31:01 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18) [27/Jun/2013:13:31:01 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:31:01 +0200] - mapping tree selected backend : frontend-internal [27/Jun/2013:13:31:01 +0200] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=6 [27/Jun/2013:13:31:01 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:31:01 +0200] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=5 [27/Jun/2013:13:31:01 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:31:01 +0200] - => compute_limits: sizelimit=-1, timelimit=-1 [27/Jun/2013:13:31:01 +0200] - Calling plugin 'Account Usability Plugin' #1 type 403 [27/Jun/2013:13:31:01 +0200] account-usability-plugin - --> auc_pre_search [27/Jun/2013:13:31:01 +0200] account-usability-plugin - <-- auc_pre_op [27/Jun/2013:13:31:01 +0200] - Calling plugin 'ACL preoperation' #2 type 403 [27/Jun/2013:13:31:01 +0200] - Calling plugin 'deref' #4 type 403 [27/Jun/2013:13:31:01 +0200] deref-plugin - --> deref_pre_search [27/Jun/2013:13:31:01 +0200] deref-plugin - <-- deref_pre_op [27/Jun/2013:13:31:01 +0200] - Calling plugin 'Legacy replication preoperation plugin' #6 type 403 [27/Jun/2013:13:31:01 +0200] - Calling plugin 'Multimaster replication preoperation plugin' #9 type 403 [27/Jun/2013:13:31:01 +0200] index_subsys_assign_filter_decoders - before: (objectClass=vlvsearch) [27/Jun/2013:13:31:01 +0200] index_subsys_assign_filter_decoders - after: (objectClass=vlvsearch) [27/Jun/2013:13:31:01 +0200] - => send_ldap_result 0:: [27/Jun/2013:13:31:01 +0200] - <= send_ldap_result [27/Jun/2013:13:31:01 +0200] - mapping tree release backend : frontend-internal [27/Jun/2013:13:31:01 +0200] - slapi_filter_free type 0xA3 [27/Jun/2013:13:31:01 +0200] - => send_ldap_search_entry () [27/Jun/2013:13:31:01 +0200] - Calling plugin 'Account Usability Plugin' #1 type 410 [27/Jun/2013:13:31:01 +0200] - Calling plugin 'deref' #4 type 410 [27/Jun/2013:13:31:01 +0200] - Calling plugin 'Legacy replication preoperation plugin' #6 type 410 [27/Jun/2013:13:31:01 +0200] NSACLPlugin - #### conn=5294 op=2 binddn="cn=replication manager,cn=config" [27/Jun/2013:13:31:01 +0200] NSACLPlugin - ************ RESOURCE INFO STARTS ********* [27/Jun/2013:13:31:01 +0200] NSACLPlugin - Client DN: cn=replication manager,cn=config [27/Jun/2013:13:31:01 +0200] NSACLPlugin - resource type:256(read target_DN ) [27/Jun/2013:13:31:01 +0200] NSACLPlugin - Slapi_Entry DN: [27/Jun/2013:13:31:01 +0200] NSACLPlugin - ATTR: objectClass [27/Jun/2013:13:31:01 +0200] NSACLPlugin - rights:read [27/Jun/2013:13:31:01 +0200] NSACLPlugin - ************ RESOURCE INFO ENDS ********* [27/Jun/2013:13:31:01 +0200] NSACLPlugin - Using ACL Container:0 for evaluation [27/Jun/2013:13:31:01 +0200] NSACLPlugin - ***BEGIN ACL INFO[ Name: "rootdse anon read access"]*** [27/Jun/2013:13:31:01 +0200] NSACLPlugin - ACL Index:1 ACL_ELEVEL:2 [27/Jun/2013:13:31:01 +0200] NSACLPlugin - ACI type:(compare search read target_attr acltxt target_attr_not allow_rule ) [27/Jun/2013:13:31:01 +0200] NSACLPlugin - ACI RULE type:(userdn ) [27/Jun/2013:13:31:01 +0200] NSACLPlugin - Slapi_Entry DN: [27/Jun/2013:13:31:01 +0200] NSACLPlugin - ***END ACL INFO***************************** [27/Jun/2013:13:31:01 +0200] NSACLPlugin - Num of ALLOW Handles:1, DENY handles:0 [27/Jun/2013:13:31:01 +0200] NSACLPlugin - Processed attr:objectClass for entry: [27/Jun/2013:13:31:01 +0200] NSACLPlugin - 1. Evaluating ALLOW aci(1) " "rootdse anon read access"" [27/Jun/2013:13:31:01 +0200] NSACLPlugin - conn=5294 op=2 (main): Allow read on entry().attr(objectClass) to cn=replication manager,cn=config: allowed by aci(1): aciname= "rootdse anon read access", acidn="" [27/Jun/2013:13:31:01 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.17) [27/Jun/2013:13:31:01 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:31:01 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.19) [27/Jun/2013:13:31:01 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:31:01 +0200] NSACLPlugin - Using ACL Container:0 for evaluation [27/Jun/2013:13:31:01 +0200] NSACLPlugin - ***BEGIN ACL INFO[ Name: "rootdse anon read access"]*** [27/Jun/2013:13:31:01 +0200] NSACLPlugin - ACL Index:1 ACL_ELEVEL:2 [27/Jun/2013:13:31:01 +0200] NSACLPlugin - ACI type:(compare search read target_attr acltxt target_attr_not allow_rule ) [27/Jun/2013:13:31:01 +0200] NSACLPlugin - ACI RULE type:(userdn ) [27/Jun/2013:13:31:01 +0200] NSACLPlugin - Slapi_Entry DN: [27/Jun/2013:13:31:01 +0200] NSACLPlugin - ***END ACL INFO***************************** [27/Jun/2013:13:31:01 +0200] NSACLPlugin - Num of ALLOW Handles:1, DENY handles:0 [27/Jun/2013:13:31:01 +0200] NSACLPlugin - Processed attr:supportedControl for entry: [27/Jun/2013:13:31:01 +0200] NSACLPlugin - 1. Evaluating ALLOW aci(1) " "rootdse anon read access"" [27/Jun/2013:13:31:01 +0200] NSACLPlugin - Found READ ALLOW in cache [27/Jun/2013:13:31:01 +0200] NSACLPlugin - conn=5294 op=2 (main): Allow read on entry().attr(supportedControl) to cn=replication manager,cn=config: cached allow by aci(1) [27/Jun/2013:13:31:01 +0200] NSACLPlugin - Using ACL Container:0 for evaluation [27/Jun/2013:13:31:01 +0200] NSACLPlugin - ***BEGIN ACL INFO[ Name: "rootdse anon read access"]*** [27/Jun/2013:13:31:01 +0200] NSACLPlugin - ACL Index:1 ACL_ELEVEL:2 [27/Jun/2013:13:31:01 +0200] NSACLPlugin - ACI type:(compare search read target_attr acltxt target_attr_not allow_rule ) [27/Jun/2013:13:31:01 +0200] NSACLPlugin - ACI RULE type:(userdn ) [27/Jun/2013:13:31:01 +0200] NSACLPlugin - Slapi_Entry DN: [27/Jun/2013:13:31:01 +0200] NSACLPlugin - ***END ACL INFO***************************** [27/Jun/2013:13:31:01 +0200] NSACLPlugin - Num of ALLOW Handles:1, DENY handles:0 [27/Jun/2013:13:31:01 +0200] NSACLPlugin - Processed attr:supportedExtension for entry: [27/Jun/2013:13:31:01 +0200] NSACLPlugin - 1. Evaluating ALLOW aci(1) " "rootdse anon read access"" [27/Jun/2013:13:31:01 +0200] NSACLPlugin - Found READ ALLOW in cache [27/Jun/2013:13:31:01 +0200] NSACLPlugin - conn=5294 op=2 (main): Allow read on entry().attr(supportedExtension) to cn=replication manager,cn=config: cached allow by aci(1) [27/Jun/2013:13:31:01 +0200] - flush_ber() wrote 963 bytes to socket 64 [27/Jun/2013:13:31:01 +0200] - <= send_ldap_search_entry [27/Jun/2013:13:31:01 +0200] - => send_ldap_result 0:: [27/Jun/2013:13:31:01 +0200] - flush_ber() wrote 14 bytes to socket 64 [27/Jun/2013:13:31:01 +0200] - <= send_ldap_result [27/Jun/2013:13:31:01 +0200] - mapping tree release backend : frontend-internal [27/Jun/2013:13:31:01 +0200] - slapi_filter_free type 0x87 [27/Jun/2013:13:31:01 +0200] - activity on 64r [27/Jun/2013:13:31:01 +0200] - read activity on 64 [27/Jun/2013:13:31:01 +0200] - add_pb [27/Jun/2013:13:31:01 +0200] - get_pb [27/Jun/2013:13:31:01 +0200] - do_search [27/Jun/2013:13:31:01 +0200] - => slapi_reslimit_get_integer_limit() conn=0xc81f2e10, handle=8 [27/Jun/2013:13:31:01 +0200] - <= slapi_reslimit_get_integer_limit() returning SUCCESS, value=0 [27/Jun/2013:13:31:01 +0200] - listener got signaled [27/Jun/2013:13:31:01 +0200] - => get_filter_internal [27/Jun/2013:13:31:01 +0200] - PRESENT [27/Jun/2013:13:31:01 +0200] - --> pagedresults_is_timedout [27/Jun/2013:13:31:01 +0200] - <-- pagedresults_is_timedout: - [27/Jun/2013:13:31:01 +0200] - <= get_filter_internal 0 [27/Jun/2013:13:31:01 +0200] get_filter - before optimize: (objectClass=*) [27/Jun/2013:13:31:01 +0200] get_filter - after optimize: (objectClass=*) [27/Jun/2013:13:31:01 +0200] - SRCH base="(null)" scope=0 deref=0 sizelimit=0 timelimit=600 attrsonly=0 filter="(objectClass=*)" attrs="supportedControl supportedExtension" [27/Jun/2013:13:31:01 +0200] - => get_ldapmessage_controls [27/Jun/2013:13:31:01 +0200] - <= get_ldapmessage_controls no controls [27/Jun/2013:13:31:01 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.3) [27/Jun/2013:13:31:01 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:31:01 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12) [27/Jun/2013:13:31:01 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:31:01 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18) [27/Jun/2013:13:31:01 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:31:01 +0200] - => slapi_reslimit_get_integer_limit() conn=0xc81f2e10, handle=6 [27/Jun/2013:13:31:01 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:31:01 +0200] - => slapi_reslimit_get_integer_limit() conn=0xc81f2e10, handle=5 [27/Jun/2013:13:31:01 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:31:01 +0200] - => compute_limits: sizelimit=2000, timelimit=600 [27/Jun/2013:13:31:01 +0200] - Calling plugin 'Account Usability Plugin' #1 type 403 [27/Jun/2013:13:31:01 +0200] account-usability-plugin - --> auc_pre_search [27/Jun/2013:13:31:01 +0200] account-usability-plugin - <-- auc_pre_op [27/Jun/2013:13:31:01 +0200] - Calling plugin 'ACL preoperation' #2 type 403 [27/Jun/2013:13:31:01 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12) [27/Jun/2013:13:31:01 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:31:01 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18) [27/Jun/2013:13:31:01 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:31:01 +0200] - Calling plugin 'deref' #4 type 403 [27/Jun/2013:13:31:01 +0200] deref-plugin - --> deref_pre_search [27/Jun/2013:13:31:01 +0200] deref-plugin - <-- deref_pre_op [27/Jun/2013:13:31:01 +0200] - Calling plugin 'Legacy replication preoperation plugin' #6 type 403 [27/Jun/2013:13:31:01 +0200] - Calling plugin 'Multimaster replication preoperation plugin' #9 type 403 [27/Jun/2013:13:31:01 +0200] index_subsys_assign_filter_decoders - before: (objectClass=*) [27/Jun/2013:13:31:01 +0200] index_subsys_assign_filter_decoders - after: (objectClass=*) [27/Jun/2013:13:31:01 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:31:01 +0200] - => test_substring_filter [27/Jun/2013:13:31:01 +0200] - PRESENT [27/Jun/2013:13:31:01 +0200] - <= slapi_vattr_filter_test 0 [27/Jun/2013:13:31:01 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:31:01 +0200] - => test_substring_filter [27/Jun/2013:13:31:01 +0200] - PRESENT [27/Jun/2013:13:31:01 +0200] - <= slapi_vattr_filter_test 0 [27/Jun/2013:13:31:01 +0200] - => ids_sasl_listmech [27/Jun/2013:13:31:01 +0200] - ids_sasl_getopt: plugin= option=mech_list [27/Jun/2013:13:31:01 +0200] - ids_sasl_getopt: plugin= option=mech_list [27/Jun/2013:13:31:01 +0200] - ids_sasl_getopt: plugin= option=mech_list [27/Jun/2013:13:31:01 +0200] - ids_sasl_getopt: plugin= option=mech_list [27/Jun/2013:13:31:01 +0200] - ids_sasl_getopt: plugin= option=mech_list [27/Jun/2013:13:31:01 +0200] - ids_sasl_getopt: plugin= option=mech_list [27/Jun/2013:13:31:01 +0200] - ids_sasl_getopt: plugin= option=mech_list [27/Jun/2013:13:31:01 +0200] - ids_sasl_getopt: plugin= option=mech_list [27/Jun/2013:13:31:01 +0200] - ids_sasl_getopt: plugin= option=mech_list [27/Jun/2013:13:31:01 +0200] - ids_sasl_getopt: plugin= option=mech_list [27/Jun/2013:13:31:01 +0200] - sasl library mechs: CRAM-MD5,GSSAPI,DIGEST-MD5,ANONYMOUS [27/Jun/2013:13:31:01 +0200] - <= ids_sasl_listmech [27/Jun/2013:13:31:01 +0200] - slapi_str2filter "objectclass=vlvsearch" [27/Jun/2013:13:31:01 +0200] - slapi_str2filter: default [27/Jun/2013:13:31:01 +0200] - str2simple "objectclass=vlvsearch" [27/Jun/2013:13:31:01 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12) [27/Jun/2013:13:31:01 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:31:01 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18) [27/Jun/2013:13:31:01 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:31:01 +0200] - mapping tree selected backend : frontend-internal [27/Jun/2013:13:31:01 +0200] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=6 [27/Jun/2013:13:31:01 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:31:01 +0200] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=5 [27/Jun/2013:13:31:01 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:31:01 +0200] - => compute_limits: sizelimit=-1, timelimit=-1 [27/Jun/2013:13:31:01 +0200] - Calling plugin 'Account Usability Plugin' #1 type 403 [27/Jun/2013:13:31:01 +0200] account-usability-plugin - --> auc_pre_search [27/Jun/2013:13:31:01 +0200] account-usability-plugin - <-- auc_pre_op [27/Jun/2013:13:31:01 +0200] - Calling plugin 'ACL preoperation' #2 type 403 [27/Jun/2013:13:31:01 +0200] - Calling plugin 'deref' #4 type 403 [27/Jun/2013:13:31:01 +0200] deref-plugin - --> deref_pre_search [27/Jun/2013:13:31:01 +0200] deref-plugin - <-- deref_pre_op [27/Jun/2013:13:31:01 +0200] - Calling plugin 'Legacy replication preoperation plugin' #6 type 403 [27/Jun/2013:13:31:01 +0200] - Calling plugin 'Multimaster replication preoperation plugin' #9 type 403 [27/Jun/2013:13:31:01 +0200] index_subsys_assign_filter_decoders - before: (objectClass=vlvsearch) [27/Jun/2013:13:31:01 +0200] index_subsys_assign_filter_decoders - after: (objectClass=vlvsearch) [27/Jun/2013:13:31:01 +0200] - => send_ldap_result 0:: [27/Jun/2013:13:31:01 +0200] - <= send_ldap_result [27/Jun/2013:13:31:01 +0200] - mapping tree release backend : frontend-internal [27/Jun/2013:13:31:01 +0200] - slapi_filter_free type 0xA3 [27/Jun/2013:13:31:01 +0200] - => send_ldap_search_entry () [27/Jun/2013:13:31:01 +0200] - Calling plugin 'Account Usability Plugin' #1 type 410 [27/Jun/2013:13:31:01 +0200] - Calling plugin 'deref' #4 type 410 [27/Jun/2013:13:31:01 +0200] - Calling plugin 'Legacy replication preoperation plugin' #6 type 410 [27/Jun/2013:13:31:01 +0200] NSACLPlugin - #### conn=5294 op=3 binddn="cn=replication manager,cn=config" [27/Jun/2013:13:31:01 +0200] NSACLPlugin - ************ RESOURCE INFO STARTS ********* [27/Jun/2013:13:31:01 +0200] NSACLPlugin - Client DN: cn=replication manager,cn=config [27/Jun/2013:13:31:01 +0200] NSACLPlugin - resource type:256(read target_DN ) [27/Jun/2013:13:31:01 +0200] NSACLPlugin - Slapi_Entry DN: [27/Jun/2013:13:31:01 +0200] NSACLPlugin - ATTR: objectClass [27/Jun/2013:13:31:01 +0200] NSACLPlugin - rights:read [27/Jun/2013:13:31:01 +0200] NSACLPlugin - ************ RESOURCE INFO ENDS ********* [27/Jun/2013:13:31:01 +0200] NSACLPlugin - Using ACL Container:0 for evaluation [27/Jun/2013:13:31:01 +0200] NSACLPlugin - ***BEGIN ACL INFO[ Name: "rootdse anon read access"]*** [27/Jun/2013:13:31:01 +0200] NSACLPlugin - ACL Index:1 ACL_ELEVEL:2 [27/Jun/2013:13:31:01 +0200] NSACLPlugin - ACI type:(compare search read target_attr acltxt target_attr_not allow_rule ) [27/Jun/2013:13:31:01 +0200] NSACLPlugin - ACI RULE type:(userdn ) [27/Jun/2013:13:31:01 +0200] NSACLPlugin - Slapi_Entry DN: [27/Jun/2013:13:31:01 +0200] NSACLPlugin - ***END ACL INFO***************************** [27/Jun/2013:13:31:01 +0200] NSACLPlugin - Num of ALLOW Handles:1, DENY handles:0 [27/Jun/2013:13:31:01 +0200] NSACLPlugin - Processed attr:objectClass for entry: [27/Jun/2013:13:31:01 +0200] NSACLPlugin - 1. Evaluating ALLOW aci(1) " "rootdse anon read access"" [27/Jun/2013:13:31:01 +0200] NSACLPlugin - conn=5294 op=3 (main): Allow read on entry().attr(objectClass) to cn=replication manager,cn=config: allowed by aci(1): aciname= "rootdse anon read access", acidn="" [27/Jun/2013:13:31:01 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.17) [27/Jun/2013:13:31:01 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:31:01 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.19) [27/Jun/2013:13:31:01 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:31:01 +0200] NSACLPlugin - Using ACL Container:0 for evaluation [27/Jun/2013:13:31:01 +0200] NSACLPlugin - ***BEGIN ACL INFO[ Name: "rootdse anon read access"]*** [27/Jun/2013:13:31:01 +0200] NSACLPlugin - ACL Index:1 ACL_ELEVEL:2 [27/Jun/2013:13:31:01 +0200] NSACLPlugin - ACI type:(compare search read target_attr acltxt target_attr_not allow_rule ) [27/Jun/2013:13:31:01 +0200] NSACLPlugin - ACI RULE type:(userdn ) [27/Jun/2013:13:31:01 +0200] NSACLPlugin - Slapi_Entry DN: [27/Jun/2013:13:31:01 +0200] NSACLPlugin - ***END ACL INFO***************************** [27/Jun/2013:13:31:01 +0200] NSACLPlugin - Num of ALLOW Handles:1, DENY handles:0 [27/Jun/2013:13:31:01 +0200] NSACLPlugin - Processed attr:supportedControl for entry: [27/Jun/2013:13:31:01 +0200] NSACLPlugin - 1. Evaluating ALLOW aci(1) " "rootdse anon read access"" [27/Jun/2013:13:31:01 +0200] NSACLPlugin - Found READ ALLOW in cache [27/Jun/2013:13:31:01 +0200] NSACLPlugin - conn=5294 op=3 (main): Allow read on entry().attr(supportedControl) to cn=replication manager,cn=config: cached allow by aci(1) [27/Jun/2013:13:31:01 +0200] NSACLPlugin - Using ACL Container:0 for evaluation [27/Jun/2013:13:31:01 +0200] NSACLPlugin - ***BEGIN ACL INFO[ Name: "rootdse anon read access"]*** [27/Jun/2013:13:31:01 +0200] NSACLPlugin - ACL Index:1 ACL_ELEVEL:2 [27/Jun/2013:13:31:01 +0200] NSACLPlugin - ACI type:(compare search read target_attr acltxt target_attr_not allow_rule ) [27/Jun/2013:13:31:01 +0200] NSACLPlugin - ACI RULE type:(userdn ) [27/Jun/2013:13:31:01 +0200] NSACLPlugin - Slapi_Entry DN: [27/Jun/2013:13:31:01 +0200] NSACLPlugin - ***END ACL INFO***************************** [27/Jun/2013:13:31:01 +0200] NSACLPlugin - Num of ALLOW Handles:1, DENY handles:0 [27/Jun/2013:13:31:01 +0200] NSACLPlugin - Processed attr:supportedExtension for entry: [27/Jun/2013:13:31:01 +0200] NSACLPlugin - 1. Evaluating ALLOW aci(1) " "rootdse anon read access"" [27/Jun/2013:13:31:01 +0200] NSACLPlugin - Found READ ALLOW in cache [27/Jun/2013:13:31:01 +0200] NSACLPlugin - conn=5294 op=3 (main): Allow read on entry().attr(supportedExtension) to cn=replication manager,cn=config: cached allow by aci(1) [27/Jun/2013:13:31:01 +0200] - flush_ber() wrote 963 bytes to socket 64 [27/Jun/2013:13:31:01 +0200] - <= send_ldap_search_entry [27/Jun/2013:13:31:01 +0200] - => send_ldap_result 0:: [27/Jun/2013:13:31:01 +0200] - flush_ber() wrote 14 bytes to socket 64 [27/Jun/2013:13:31:01 +0200] - <= send_ldap_result [27/Jun/2013:13:31:01 +0200] - mapping tree release backend : frontend-internal [27/Jun/2013:13:31:01 +0200] - slapi_filter_free type 0x87 [27/Jun/2013:13:31:01 +0200] - activity on 64r [27/Jun/2013:13:31:01 +0200] - read activity on 64 [27/Jun/2013:13:31:01 +0200] - add_pb [27/Jun/2013:13:31:01 +0200] - get_pb [27/Jun/2013:13:31:01 +0200] - do_extended [27/Jun/2013:13:31:01 +0200] - => slapi_reslimit_get_integer_limit() conn=0xc81f2e10, handle=8 [27/Jun/2013:13:31:01 +0200] - do_extended: oid (2.16.840.1.113730.3.5.3-Netscape Replication Start Session) [27/Jun/2013:13:31:01 +0200] - <= slapi_reslimit_get_integer_limit() returning SUCCESS, value=0 [27/Jun/2013:13:31:01 +0200] - listener got signaled [27/Jun/2013:13:31:01 +0200] - => get_ldapmessage_controls [27/Jun/2013:13:31:01 +0200] - --> pagedresults_is_timedout [27/Jun/2013:13:31:01 +0200] - <-- pagedresults_is_timedout: - [27/Jun/2013:13:31:01 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.2) [27/Jun/2013:13:31:01 +0200] - <= slapi_control_present 1 (FOUND) [27/Jun/2013:13:31:01 +0200] - => slapi_control_present (looking for 1.3.6.1.4.1.42.2.27.8.5.1) [27/Jun/2013:13:31:01 +0200] - <= slapi_control_present 0 (NOT FOUND) [27/Jun/2013:13:31:01 +0200] - <= get_ldapmessage_controls 1 controls [27/Jun/2013:13:31:01 +0200] NSMMReplicationPlugin - conn=5294 op=4 Acquired consumer connection extension [27/Jun/2013:13:31:01 +0200] NSMMReplicationPlugin - conn=5294 op=4 repl="dc=COMPANY,dc=si": Begin incremental protocol [27/Jun/2013:13:31:01 +0200] - csngen_adjust_time: gen state before 51cc20920002:1372332176:0:2 [27/Jun/2013:13:31:01 +0200] - _csngen_adjust_local_time: gen state before 51cc20920002:1372332176:0:2 [27/Jun/2013:13:31:01 +0200] - _csngen_adjust_local_time: gen state after 51cc22770000:1372332661:0:2 [27/Jun/2013:13:31:01 +0200] - csngen_adjust_time: gen state after 51cc22770004:1372332661:0:2 [27/Jun/2013:13:31:01 +0200] NSMMReplicationPlugin - conn=5294 op=4 repl="dc=COMPANY,dc=si": Acquired replica [27/Jun/2013:13:31:01 +0200] - slapi_str2filter "objectclass=*" [27/Jun/2013:13:31:01 +0200] - slapi_str2filter: default [27/Jun/2013:13:31:01 +0200] - str2simple "objectclass=*" [27/Jun/2013:13:31:01 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12) [27/Jun/2013:13:31:01 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:31:01 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18) [27/Jun/2013:13:31:01 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:31:01 +0200] - mapping tree selected backend : frontend-internal [27/Jun/2013:13:31:01 +0200] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=6 [27/Jun/2013:13:31:01 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:31:01 +0200] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=5 [27/Jun/2013:13:31:01 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:31:01 +0200] - => compute_limits: sizelimit=-1, timelimit=-1 [27/Jun/2013:13:31:01 +0200] - Calling plugin 'Account Usability Plugin' #1 type 403 [27/Jun/2013:13:31:01 +0200] account-usability-plugin - --> auc_pre_search [27/Jun/2013:13:31:01 +0200] account-usability-plugin - <-- auc_pre_op [27/Jun/2013:13:31:01 +0200] - Calling plugin 'ACL preoperation' #2 type 403 [27/Jun/2013:13:31:01 +0200] - Calling plugin 'deref' #4 type 403 [27/Jun/2013:13:31:01 +0200] deref-plugin - --> deref_pre_search [27/Jun/2013:13:31:01 +0200] deref-plugin - <-- deref_pre_op [27/Jun/2013:13:31:01 +0200] - Calling plugin 'Legacy replication preoperation plugin' #6 type 403 [27/Jun/2013:13:31:01 +0200] - Calling plugin 'Multimaster replication preoperation plugin' #9 type 403 [27/Jun/2013:13:31:01 +0200] index_subsys_assign_filter_decoders - before: (objectClass=*) [27/Jun/2013:13:31:01 +0200] index_subsys_assign_filter_decoders - after: (objectClass=*) [27/Jun/2013:13:31:01 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:31:01 +0200] - => test_substring_filter [27/Jun/2013:13:31:01 +0200] - PRESENT [27/Jun/2013:13:31:01 +0200] - <= slapi_vattr_filter_test 0 [27/Jun/2013:13:31:01 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:31:01 +0200] - => test_substring_filter [27/Jun/2013:13:31:01 +0200] - EQUALITY [27/Jun/2013:13:31:01 +0200] - => test_ava_filter [27/Jun/2013:13:31:01 +0200] - => plugin_call_syntax_filter_ava objectClass=nsDS5Replica [27/Jun/2013:13:31:01 +0200] - <= plugin_call_syntax_filter_ava -1 [27/Jun/2013:13:31:01 +0200] - <= test_ava_filter -1 [27/Jun/2013:13:31:01 +0200] - <= slapi_vattr_filter_test -1 [27/Jun/2013:13:31:01 +0200] - => send_ldap_search_entry (cn=dc\3DCOMPANY\2Cdc\3Dsi,cn=mapping tree,cn=config) [27/Jun/2013:13:31:01 +0200] - Calling plugin 'Account Usability Plugin' #1 type 410 [27/Jun/2013:13:31:01 +0200] - Calling plugin 'deref' #4 type 410 [27/Jun/2013:13:31:01 +0200] - Calling plugin 'Legacy replication preoperation plugin' #6 type 410 [27/Jun/2013:13:31:01 +0200] - <= send_ldap_search_entry [27/Jun/2013:13:31:01 +0200] - => send_ldap_result 0:: [27/Jun/2013:13:31:01 +0200] - <= send_ldap_result [27/Jun/2013:13:31:01 +0200] - mapping tree release backend : frontend-internal [27/Jun/2013:13:31:01 +0200] - slapi_filter_free type 0x87 [27/Jun/2013:13:31:01 +0200] - slapi_str2filter "objectclass=nsMappingTree" [27/Jun/2013:13:31:01 +0200] - slapi_str2filter: default [27/Jun/2013:13:31:01 +0200] - str2simple "objectclass=nsMappingTree" [27/Jun/2013:13:31:01 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12) [27/Jun/2013:13:31:01 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:31:01 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18) [27/Jun/2013:13:31:01 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:31:01 +0200] - mapping tree selected backend : frontend-internal [27/Jun/2013:13:31:01 +0200] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=6 [27/Jun/2013:13:31:01 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:31:01 +0200] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=5 [27/Jun/2013:13:31:01 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:31:01 +0200] - => compute_limits: sizelimit=-1, timelimit=-1 [27/Jun/2013:13:31:01 +0200] - Calling plugin 'Account Usability Plugin' #1 type 403 [27/Jun/2013:13:31:01 +0200] account-usability-plugin - --> auc_pre_search [27/Jun/2013:13:31:01 +0200] account-usability-plugin - <-- auc_pre_op [27/Jun/2013:13:31:01 +0200] - Calling plugin 'ACL preoperation' #2 type 403 [27/Jun/2013:13:31:01 +0200] - Calling plugin 'deref' #4 type 403 [27/Jun/2013:13:31:01 +0200] deref-plugin - --> deref_pre_search [27/Jun/2013:13:31:01 +0200] deref-plugin - <-- deref_pre_op [27/Jun/2013:13:31:01 +0200] - Calling plugin 'Legacy replication preoperation plugin' #6 type 403 [27/Jun/2013:13:31:01 +0200] - Calling plugin 'Multimaster replication preoperation plugin' #9 type 403 [27/Jun/2013:13:31:01 +0200] index_subsys_assign_filter_decoders - before: (objectClass=nsMappingTree) [27/Jun/2013:13:31:01 +0200] index_subsys_assign_filter_decoders - after: (objectClass=nsMappingTree) [27/Jun/2013:13:31:01 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:31:01 +0200] - => test_substring_filter [27/Jun/2013:13:31:01 +0200] - EQUALITY [27/Jun/2013:13:31:01 +0200] - => test_ava_filter [27/Jun/2013:13:31:01 +0200] - => plugin_call_syntax_filter_ava objectClass=nsMappingTree [27/Jun/2013:13:31:01 +0200] - <= plugin_call_syntax_filter_ava 0 [27/Jun/2013:13:31:01 +0200] - <= test_ava_filter 0 [27/Jun/2013:13:31:01 +0200] - <= slapi_vattr_filter_test 0 [27/Jun/2013:13:31:01 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:31:01 +0200] - => test_substring_filter [27/Jun/2013:13:31:01 +0200] - EQUALITY [27/Jun/2013:13:31:01 +0200] - => test_ava_filter [27/Jun/2013:13:31:01 +0200] - => plugin_call_syntax_filter_ava objectClass=nsDS5Replica [27/Jun/2013:13:31:01 +0200] - <= plugin_call_syntax_filter_ava -1 [27/Jun/2013:13:31:01 +0200] - <= test_ava_filter -1 [27/Jun/2013:13:31:01 +0200] - <= slapi_vattr_filter_test -1 [27/Jun/2013:13:31:01 +0200] - => send_ldap_search_entry (cn=dc\3DCOMPANY\2Cdc\3Dsi,cn=mapping tree,cn=config) [27/Jun/2013:13:31:01 +0200] - Calling plugin 'Account Usability Plugin' #1 type 410 [27/Jun/2013:13:31:01 +0200] - Calling plugin 'deref' #4 type 410 [27/Jun/2013:13:31:01 +0200] - Calling plugin 'Legacy replication preoperation plugin' #6 type 410 [27/Jun/2013:13:31:01 +0200] - <= send_ldap_search_entry [27/Jun/2013:13:31:01 +0200] - => send_ldap_result 0:: [27/Jun/2013:13:31:01 +0200] - <= send_ldap_result [27/Jun/2013:13:31:01 +0200] - mapping tree release backend : frontend-internal [27/Jun/2013:13:31:01 +0200] - slapi_filter_free type 0xA3 [27/Jun/2013:13:31:01 +0200] - slapi_str2filter "objectclass=nsMappingTree" [27/Jun/2013:13:31:01 +0200] - slapi_str2filter: default [27/Jun/2013:13:31:01 +0200] - str2simple "objectclass=nsMappingTree" [27/Jun/2013:13:31:01 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12) [27/Jun/2013:13:31:01 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:31:01 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18) [27/Jun/2013:13:31:01 +0200] - <= slapi_control_present 0 (NO CONTROLS) [27/Jun/2013:13:31:01 +0200] - mapping tree selected backend : frontend-internal [27/Jun/2013:13:31:01 +0200] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=6 [27/Jun/2013:13:31:01 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:31:01 +0200] - => slapi_reslimit_get_integer_limit() conn=0x0, handle=5 [27/Jun/2013:13:31:01 +0200] - <= slapi_reslimit_get_integer_limit() returning NO VALUE [27/Jun/2013:13:31:01 +0200] - => compute_limits: sizelimit=-1, timelimit=-1 [27/Jun/2013:13:31:01 +0200] - Calling plugin 'Account Usability Plugin' #1 type 403 [27/Jun/2013:13:31:01 +0200] account-usability-plugin - --> auc_pre_search [27/Jun/2013:13:31:01 +0200] account-usability-plugin - <-- auc_pre_op [27/Jun/2013:13:31:01 +0200] - Calling plugin 'ACL preoperation' #2 type 403 [27/Jun/2013:13:31:01 +0200] - Calling plugin 'deref' #4 type 403 [27/Jun/2013:13:31:01 +0200] deref-plugin - --> deref_pre_search [27/Jun/2013:13:31:01 +0200] deref-plugin - <-- deref_pre_op [27/Jun/2013:13:31:01 +0200] - Calling plugin 'Legacy replication preoperation plugin' #6 type 403 [27/Jun/2013:13:31:01 +0200] - Calling plugin 'Multimaster replication preoperation plugin' #9 type 403 [27/Jun/2013:13:31:01 +0200] index_subsys_assign_filter_decoders - before: (objectClass=nsMappingTree) [27/Jun/2013:13:31:01 +0200] index_subsys_assign_filter_decoders - after: (objectClass=nsMappingTree) [27/Jun/2013:13:31:01 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:31:01 +0200] - => test_substring_filter [27/Jun/2013:13:31:01 +0200] - EQUALITY [27/Jun/2013:13:31:01 +0200] - => test_ava_filter [27/Jun/2013:13:31:01 +0200] - => plugin_call_syntax_filter_ava objectClass=nsMappingTree [27/Jun/2013:13:31:01 +0200] - <= plugin_call_syntax_filter_ava 0 [27/Jun/2013:13:31:01 +0200] - <= test_ava_filter 0 [27/Jun/2013:13:31:01 +0200] - <= slapi_vattr_filter_test 0 [27/Jun/2013:13:31:01 +0200] - => slapi_vattr_filter_test_ext [27/Jun/2013:13:31:01 +0200] - => test_substring_filter [27/Jun/2013:13:31:01 +0200] - EQUALITY [27/Jun/2013:13:31:01 +0200] - => test_ava_filter [27/Jun/2013:13:31:01 +0200] - => plugin_call_syntax_filter_ava objectClass=nsDS5Replica [27/Jun/2013:13:31:01 +0200] - <= plugin_call_syntax_filter_ava -1 [27/Jun/2013:13:31:01 +0200] - <= test_ava_filter -1 [27/Jun/2013:13:31:01 +0200] - <= slapi_vattr_filter_test -1 [27/Jun/2013:13:31:01 +0200] - => send_ldap_search_entry (cn=dc\3DCOMPANY\2Cdc\3Dsi,cn=mapping tree,cn=config) [27/Jun/2013:13:31:01 +0200] - Calling plugin 'Account Usability Plugin' #1 type 410 [27/Jun/2013:13:31:01 +0200] - Calling plugin 'deref' #4 type 410 [27/Jun/2013:13:31:01 +0200] - Calling plugin 'Legacy replication preoperation plugin' #6 type 410 [27/Jun/2013:13:31:01 +0200] - <= send_ldap_search_entry [27/Jun/2013:13:31:01 +0200] - => send_ldap_result 0:: [27/Jun/2013:13:31:01 +0200] - <= send_ldap_result [27/Jun/2013:13:31:01 +0200] - mapping tree release backend : frontend-internal [27/Jun/2013:13:31:01 +0200] - slapi_filter_free type 0xA3 [27/Jun/2013:13:31:01 +0200] NSMMReplicationPlugin - conn=5294 op=4 repl="dc=COMPANY,dc=si": StartNSDS50ReplicationRequest: response=0 rc=0 [27/Jun/2013:13:31:01 +0200] - => send_ldap_result 0:: [27/Jun/2013:13:31:01 +0200] - flush_ber() wrote 176 bytes to socket 64 [27/Jun/2013:13:31:01 +0200] - <= send_ldap_result [27/Jun/2013:13:31:01 +0200] NSMMReplicationPlugin - conn=5294 op=4 Relinquishing consumer connection extension [27/Jun/2013:13:31:01 +0200] - activity on 64r [27/Jun/2013:13:31:01 +0200] - read activity on 64 [27/Jun/2013:13:31:01 +0200] - add_pb [27/Jun/2013:13:31:01 +0200] - get_pb [27/Jun/2013:13:31:01 +0200] - do_modify [27/Jun/2013:13:31:01 +0200] - do_modify: dn (edupersonprincipalname=COMPANY.USER@COMPANY.si,dc=COMPANY,dc=si) [27/Jun/2013:13:31:01 +0200] - => get_ldapmessage_controls [27/Jun/2013:13:31:01 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.2) [27/Jun/2013:13:31:01 +0200] - <= slapi_control_present 1 (FOUND) [27/Jun/2013:13:31:01 +0200] - => slapi_control_present (looking for 1.3.6.1.4.1.42.2.27.8.5.1) [27/Jun/2013:13:31:01 +0200] - <= slapi_control_present 0 (NOT FOUND) [27/Jun/2013:13:31:01 +0200] - <= get_ldapmessage_controls 2 controls [27/Jun/2013:13:31:01 +0200] - modifications: [27/Jun/2013:13:31:01 +0200] - delete: userPassword [27/Jun/2013:13:31:01 +0200] - add: userPassword [27/Jun/2013:13:31:01 +0200] - replace: modifiersName [27/Jun/2013:13:31:01 +0200] - replace: modifyTimestamp [27/Jun/2013:13:31:01 +0200] - delete: unhashed#user#password [27/Jun/2013:13:31:01 +0200] - add: unhashed#user#password [27/Jun/2013:13:31:01 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12) [27/Jun/2013:13:31:01 +0200] - <= slapi_control_present 0 (NOT FOUND) [27/Jun/2013:13:31:01 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18) [27/Jun/2013:13:31:01 +0200] - <= slapi_control_present 0 (NOT FOUND) [27/Jun/2013:13:31:01 +0200] - mapping tree selected backend : COMPANY [27/Jun/2013:13:31:01 +0200] - Calling plugin '7-bit check' #0 type 405 [27/Jun/2013:13:31:01 +0200] NS7bitAttr - MODIFY begin [27/Jun/2013:13:31:01 +0200] - Calling plugin 'ACL preoperation' #2 type 405 [27/Jun/2013:13:31:01 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.12) [27/Jun/2013:13:31:01 +0200] - <= slapi_control_present 0 (NOT FOUND) [27/Jun/2013:13:31:01 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.18) [27/Jun/2013:13:31:01 +0200] - <= slapi_control_present 0 (NOT FOUND) [27/Jun/2013:13:31:01 +0200] - Calling plugin 'Auto Membership Plugin' #3 type 405 [27/Jun/2013:13:31:01 +0200] auto-membership-plugin - --> automember_pre_op [27/Jun/2013:13:31:01 +0200] auto-membership-plugin - --> automember_get_sdn [27/Jun/2013:13:31:01 +0200] auto-membership-plugin - <-- automember_get_sdn [27/Jun/2013:13:31:01 +0200] auto-membership-plugin - --> automember_dn_is_config [27/Jun/2013:13:31:01 +0200] auto-membership-plugin - <-- automember_dn_is_config [27/Jun/2013:13:31:01 +0200] auto-membership-plugin - <-- automember_pre_op [27/Jun/2013:13:31:01 +0200] - Calling plugin 'Legacy replication preoperation plugin' #6 type 405 [27/Jun/2013:13:31:01 +0200] - Calling plugin 'Linked Attributes' #7 type 405 [27/Jun/2013:13:31:01 +0200] linkedattrs-plugin - --> linked_attrs_pre_op [27/Jun/2013:13:31:01 +0200] linkedattrs-plugin - --> linked_attrs_get_dn [27/Jun/2013:13:31:01 +0200] linkedattrs-plugin - <-- linked_attrs_get_dn [27/Jun/2013:13:31:01 +0200] linkedattrs-plugin - --> linked_attrs_dn_is_config [27/Jun/2013:13:31:01 +0200] linkedattrs-plugin - <-- linked_attrs_dn_is_config [27/Jun/2013:13:31:01 +0200] linkedattrs-plugin - <-- linked_attrs_pre_op [27/Jun/2013:13:31:01 +0200] - Calling plugin 'Managed Entries' #8 type 405 [27/Jun/2013:13:31:01 +0200] managed-entries-plugin - --> mep_pre_op [27/Jun/2013:13:31:01 +0200] managed-entries-plugin - --> mep_get_sdn [27/Jun/2013:13:31:01 +0200] managed-entries-plugin - <-- mep_get_sdn [27/Jun/2013:13:31:01 +0200] managed-entries-plugin - --> mep_dn_is_config [27/Jun/2013:13:31:01 +0200] managed-entries-plugin - <-- mep_dn_is_config [27/Jun/2013:13:31:01 +0200] managed-entries-plugin - --> mep_isrepl [27/Jun/2013:13:31:01 +0200] managed-entries-plugin - <-- mep_isrepl [27/Jun/2013:13:31:01 +0200] managed-entries-plugin - <-- mep_pre_op [27/Jun/2013:13:31:01 +0200] - Calling plugin 'Multimaster replication preoperation plugin' #9 type 405 [27/Jun/2013:13:31:01 +0200] - => slapi_control_present (looking for 2.16.840.1.113730.3.4.13) [27/Jun/2013:13:31:01 +0200] - <= slapi_control_present 1 (FOUND) [27/Jun/2013:13:31:01 +0200] NSMMReplicationPlugin - ruv_add_csn_inprogress: successfully inserted csn 51cc22770000000b0000 into pending list [27/Jun/2013:13:31:01 +0200] - => find_entry_internal (uniqueid=de7a8356-1dd111b2-9c59da0a-92130000) lock 1 [27/Jun/2013:13:31:01 +0200] - => uniqueid2entry "de7a8356-1dd111b2-9c59da0a-92130000" [27/Jun/2013:13:31:01 +0200] - => index_read( "nsuniqueid" = "de7a8356-1dd111b2-9c59da0a-92130000" ) [27/Jun/2013:13:31:01 +0200] - indextype: "eq" indexmask: 0x2 [27/Jun/2013:13:31:01 +0200] - bulk fetch buffer nids=1 [27/Jun/2013:13:31:01 +0200] - idl_new_fetch =de7a8356-1dd111b2-9c59da0a-92130000 returns nids=1 [27/Jun/2013:13:31:01 +0200] - <= index_read 1 candidates [27/Jun/2013:13:31:01 +0200] id2entry - => id2entry(66064) [27/Jun/2013:13:31:01 +0200] entryrdn-index - --> entryrdn_lookup_dn [27/Jun/2013:13:31:01 +0200] entryrdn-index - <-- entryrdn_lookup_dn [27/Jun/2013:13:31:01 +0200] id2entry - entryrdn_lookup_dn returned: eduPersonPrincipalName=COMPANY.USER@COMPANY.si,dc=COMPANY,dc=si, and set to dn cache (id 66064) [27/Jun/2013:13:31:01 +0200] - slapi_str2entry_ext: flags=0x400, dn="eduPersonPrincipalName=COMPANY.USER@COMPANY.si,dc=COMPANY,dc=si", entry="rdn: eduPersonPrincipalName=COMPANY.USER@arne..." [27/Jun/2013:13:31:01 +0200] - => str2entry_dupcheck [27/Jun/2013:13:31:01 +0200] - <= str2entry_dupcheck 0x98005e50 "eduPersonPrincipalName=COMPANY.USER@COMPANY.si,dc=COMPANY,dc=si" [27/Jun/2013:13:31:01 +0200] id2entry - id2entry id: 66064, dn "edupersonprincipalname=COMPANY.USER@COMPANY.si,dc=COMPANY,dc=si" -- adding it to cache [27/Jun/2013:13:31:01 +0200] - -> attrcrypt_decrypt_entry [27/Jun/2013:13:31:01 +0200] - <- attrcrypt_decrypt_entry [27/Jun/2013:13:31:01 +0200] id2entry - <= id2entry( 66064 ) 7fb898000950 (disk) [27/Jun/2013:13:31:01 +0200] - <= uniqueid2entry 7fb898000950 [27/Jun/2013:13:31:01 +0200] - <= find_entry_internal_uniqueid found; uniqueid = (de7a8356-1dd111b2-9c59da0a-92130000) [27/Jun/2013:13:31:01 +0200] - Calling plugin 'Multimaster replication bepreoperation plugin' #0 type 451 [27/Jun/2013:13:31:01 +0200] NSMMReplicationPlugin - Purged state information from entry eduPersonPrincipalName=COMPANY.USER@COMPANY.si,dc=COMPANY,dc=si up to CSN 51c2e60f0001000b0000 [27/Jun/2013:13:31:01 +0200] - => entry_apply_mods_wsi [27/Jun/2013:13:31:01 +0200] - delete: userPassword [27/Jun/2013:13:31:01 +0200] - removing entire attribute userPassword [27/Jun/2013:13:31:01 +0200] - => plugin_call_syntax_filter_ava userPassword=fT4QKNGC [27/Jun/2013:13:31:01 +0200] - <= plugin_call_syntax_filter_ava 0 [27/Jun/2013:13:31:01 +0200] - - [27/Jun/2013:13:31:01 +0200] - add: userPassword [27/Jun/2013:13:31:01 +0200] - => plugin_call_syntax_filter_ava userPassword=fT4QKNGC [27/Jun/2013:13:31:01 +0200] - <= plugin_call_syntax_filter_ava -1 [27/Jun/2013:13:31:01 +0200] - userPassword: fT4QKN13 [27/Jun/2013:13:31:01 +0200] - - [27/Jun/2013:13:31:01 +0200] - replace: modifiersName [27/Jun/2013:13:31:01 +0200] - removing entire attribute modifiersName [27/Jun/2013:13:31:01 +0200] - => plugin_call_syntax_filter_ava modifiersName=cn=directory manager [27/Jun/2013:13:31:01 +0200] - <= plugin_call_syntax_filter_ava 0 [27/Jun/2013:13:31:01 +0200] - modifiersName: edupersonprincipalname=COMPANY.USER@COMPANY.si,dc=COMPANY,dc=si [27/Jun/2013:13:31:01 +0200] - - [27/Jun/2013:13:31:01 +0200] - replace: modifyTimestamp [27/Jun/2013:13:31:01 +0200] - removing entire attribute modifyTimestamp [27/Jun/2013:13:31:01 +0200] - => plugin_call_syntax_filter_ava modifyTimestamp=20130621063652Z [27/Jun/2013:13:31:01 +0200] - <= plugin_call_syntax_filter_ava 0 [27/Jun/2013:13:31:01 +0200] - modifyTimestamp: 20130627113100Z [27/Jun/2013:13:31:01 +0200] - - [27/Jun/2013:13:31:01 +0200] - delete: unhashed#user#password [27/Jun/2013:13:31:01 +0200] - - [27/Jun/2013:13:31:01 +0200] - add: unhashed#user#password [27/Jun/2013:13:31:01 +0200] - unhashed#user#password: fT4QKN13 [27/Jun/2013:13:31:01 +0200] - - [27/Jun/2013:13:31:01 +0200] - <= entry_apply_mods_wsi 0 [27/Jun/2013:13:31:01 +0200] - => find_entry_internal (uniqueid=ffffffff-ffffffff-ffffffff-ffffffff) lock 1 [27/Jun/2013:13:31:01 +0200] - => uniqueid2entry "ffffffff-ffffffff-ffffffff-ffffffff" [27/Jun/2013:13:31:01 +0200] - => index_read( "nsuniqueid" = "ffffffff-ffffffff-ffffffff-ffffffff" ) [27/Jun/2013:13:31:01 +0200] - indextype: "eq" indexmask: 0x2 [27/Jun/2013:13:31:01 +0200] - bulk fetch buffer nids=1 [27/Jun/2013:13:31:01 +0200] - idl_new_fetch =ffffffff-ffffffff-ffffffff-ffffffff returns nids=1 [27/Jun/2013:13:31:01 +0200] - <= index_read 1 candidates [27/Jun/2013:13:31:01 +0200] id2entry - => id2entry(66140) [27/Jun/2013:13:31:01 +0200] id2entry - <= id2entry 7fb8b4014b90, dn "nsuniqueid=ffffffff-ffffffff-ffffffff-ffffffff,dc=COMPANY,dc=si" (cache) [27/Jun/2013:13:31:01 +0200] id2entry - <= id2entry( 66140 ) 7fb8b4014b90 (disk) [27/Jun/2013:13:31:01 +0200] - <= uniqueid2entry 7fb8b4014b90 [27/Jun/2013:13:31:01 +0200] - <= find_entry_internal_uniqueid found; uniqueid = (ffffffff-ffffffff-ffffffff-ffffffff) [27/Jun/2013:13:31:01 +0200] - => entry_apply_mods [27/Jun/2013:13:31:01 +0200] - nsds50ruv: {replicageneration} 4c5167560000000b0000 [27/Jun/2013:13:31:01 +0200] - nsds50ruv: {replica 11 ldap://PROVIDER.COMPANY.si:389} 4c5170850000000b0000 51cc22770000000b0000 [27/Jun/2013:13:31:01 +0200] - replace: nsds50ruv [27/Jun/2013:13:31:01 +0200] - => slapi_attr_values2keys_sv [27/Jun/2013:13:31:01 +0200] - <= slapi_call_syntax_values2keys 0 [27/Jun/2013:13:31:01 +0200] - - [27/Jun/2013:13:31:01 +0200] - nsruvReplicaLastModified: {replica 11 ldap://PROVIDER.COMPANY.si:389} 51cc2275 [27/Jun/2013:13:31:01 +0200] - replace: nsruvReplicaLastModified [27/Jun/2013:13:31:01 +0200] - - [27/Jun/2013:13:31:01 +0200] - <= entry_apply_mods 0 [27/Jun/2013:13:31:01 +0200] - => id2entry_add( 66064, "edupersonprincipalname=COMPANY.USER@COMPANY.si,dc=COMPANY,dc=si" ) [27/Jun/2013:13:31:01 +0200] - -> attrcrypt_encrypt_entry [27/Jun/2013:13:31:01 +0200] - <- attrcrypt_encrypt_entry [27/Jun/2013:13:31:01 +0200] - => id2entry_add (dncache) ( 66064, "eduPersonPrincipalName=COMPANY.USER@COMPANY.si,dc=COMPANY,dc=si" ) [27/Jun/2013:13:31:01 +0200] - <= id2entry_add 0 [27/Jun/2013:13:31:01 +0200] - => plugin_call_syntax_filter_ava userPassword=fT4QKN13 [27/Jun/2013:13:31:01 +0200] - <= plugin_call_syntax_filter_ava -1 [27/Jun/2013:13:31:01 +0200] - => index_addordel_values_ext_sv( "userPassword", 66064 ) [27/Jun/2013:13:31:01 +0200] - => plugin_call_syntax_filter_ava userPassword=fT4QKN13 [27/Jun/2013:13:31:01 +0200] - <= plugin_call_syntax_filter_ava 0 [27/Jun/2013:13:31:01 +0200] - => index_addordel_values_ext_sv( "userPassword", 66064 ) [27/Jun/2013:13:31:01 +0200] - => plugin_call_syntax_filter_ava modifiersName=edupersonprincipalname=COMPANY.USER@COMPANY.si,dc=COMPANY,dc=si [27/Jun/2013:13:31:01 +0200] - <= plugin_call_syntax_filter_ava 0 [27/Jun/2013:13:31:01 +0200] - => index_addordel_values_ext_sv( "modifiersName", 66064 ) [27/Jun/2013:13:31:01 +0200] - => plugin_call_syntax_filter_ava modifiersName=edupersonprincipalname=COMPANY.USER@COMPANY.si,dc=COMPANY,dc=si [27/Jun/2013:13:31:01 +0200] - <= plugin_call_syntax_filter_ava 0 [27/Jun/2013:13:31:01 +0200] - => index_addordel_values_ext_sv( "modifiersName", 66064 ) [27/Jun/2013:13:31:01 +0200] - => plugin_call_syntax_filter_ava modifyTimestamp=20130627113100Z [27/Jun/2013:13:31:01 +0200] - <= plugin_call_syntax_filter_ava 0 [27/Jun/2013:13:31:01 +0200] - => index_addordel_values_ext_sv( "modifyTimestamp", 66064 ) [27/Jun/2013:13:31:01 +0200] - => plugin_call_syntax_filter_ava modifyTimestamp=20130627113100Z [27/Jun/2013:13:31:01 +0200] - <= plugin_call_syntax_filter_ava 0 [27/Jun/2013:13:31:01 +0200] - => index_addordel_values_ext_sv( "modifyTimestamp", 66064 )