Aug  5 16:44:09 work slapd[17541]: conn=2987 op=6 SRCH base="dc=HOSTING,dc=SPNET,dc=NET" scope=1 deref=2 filter="(&(objectClass=mailDomain)(dc=justillusion.net))"
Aug  5 16:44:09 work slapd[17541]: conn=2987 op=6 SRCH attr=spamassassin
Aug  5 16:44:09 work slapd[17541]: ==> limits_get: conn=2987 op=6 self="cn=nss,dc=ldapaccess" this="dc=hosting,dc=spnet,dc=net"
Aug  5 16:44:09 work slapd[17541]: query template of incoming query = (&(objectClass=)(dc=))
Aug  5 16:44:09 work slapd[17541]: Entering QC, querystr = (&(objectClass=mailDomain)(dc=justillusion.net))
Aug  5 16:44:09 work slapd[17541]: Lock QC index = 0x180d140
Aug  5 16:44:09 work slapd[17541]: Not answerable: Unlock QC index=0x180d140
Aug  5 16:44:09 work slapd[17541]: QUERY NOT ANSWERABLE
Aug  5 16:44:09 work slapd[17541]: QUERY CACHEABLE
Aug  5 16:44:09 work slapd[17541]: ==>backsql_search(): base="dc=hosting,dc=spnet,dc=net", filter="(&(objectClass=mailDomain)(dc=justillusion.net))", scope=1,
Aug  5 16:44:09 work slapd[17541]:  deref=2, attrsonly=0, attributes to load: custom list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_get_db_conn()
Aug  5 16:44:09 work slapd[17541]: <==backsql_get_db_conn()
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): adding "cn" to list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "cn" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): adding "uid" to list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "cn" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "uid" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): adding "uidNumber" to list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "cn" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "uid" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "uidNumber" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): adding "gidNumber" to list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "cn" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "uid" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "uidNumber" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "gidNumber" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): adding "loginShell" to list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "cn" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "uid" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "uidNumber" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "gidNumber" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "loginShell" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): adding "homeDirectory" to list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "cn" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "uid" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "uidNumber" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "gidNumber" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "loginShell" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "homeDirectory" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): adding "userPassword" to list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "cn" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "uid" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "uidNumber" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "gidNumber" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "loginShell" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "homeDirectory" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "userPassword" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): adding "description" to list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "cn" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "uid" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "uidNumber" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "gidNumber" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "loginShell" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "homeDirectory" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "userPassword" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "description" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): adding "gecos" to list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "cn" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "uid" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "uidNumber" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "gidNumber" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "loginShell" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "homeDirectory" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "userPassword" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "description" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "gecos" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): adding "memberUid" to list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "cn" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "uid" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "uidNumber" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "gidNumber" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "loginShell" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "homeDirectory" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "userPassword" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "description" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "gecos" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "memberUid" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): adding "dc" to list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "cn" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "uid" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "uidNumber" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "gidNumber" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "loginShell" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "homeDirectory" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "userPassword" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "description" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "gecos" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "memberUid" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "dc" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): adding "spamassassin" to list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "cn" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "uid" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "uidNumber" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "gidNumber" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "loginShell" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "homeDirectory" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "userPassword" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "description" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "gecos" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "memberUid" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "dc" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "spamassassin" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): adding "objectClass" to list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_dn2id("dc=hosting,dc=spnet,dc=net") matched expected
Aug  5 16:44:09 work slapd[17541]:    backsql_dn2id("dc=hosting,dc=spnet,dc=net"): id_query "SELECT id,keyval,oc_map_id,dn FROM ldap_entries WHERE UPPER(dn)=UPPER(?)"
Aug  5 16:44:09 work slapd[17541]:    backsql_dn2id("dc=hosting,dc=spnet,dc=net"): id=2 keyval=0 oc_id=1 dn=DC=HOSTING,DC=SPNET,DC=NET
Aug  5 16:44:09 work slapd[17541]: >>> dnPrettyNormal: <DC=HOSTING,DC=SPNET,DC=NET>
Aug  5 16:44:09 work slapd[17541]: <<< dnPrettyNormal: <dc=HOSTING,dc=SPNET,dc=NET>, <dc=hosting,dc=spnet,dc=net>
Aug  5 16:44:09 work slapd[17541]: <==backsql_dn2id("dc=hosting,dc=spnet,dc=net"): err=0
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "cn" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "uid" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "uidNumber" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "gidNumber" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "loginShell" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "homeDirectory" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "userPassword" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "description" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "gecos" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "memberUid" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "dc" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "spamassassin" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "objectClass" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): adding "ref" to list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_id2entry()
Aug  5 16:44:09 work slapd[17541]: backsql_id2entry(): custom attribute list
Aug  5 16:44:09 work slapd[17541]: backsql_id2entry(): attribute "cn" is not defined for objectlass "top"
Aug  5 16:44:09 work slapd[17541]: backsql_id2entry(): attribute "uid" is not defined for objectlass "top"
Aug  5 16:44:09 work slapd[17541]: backsql_id2entry(): attribute "uidNumber" is not defined for objectlass "top"
Aug  5 16:44:09 work slapd[17541]: backsql_id2entry(): attribute "gidNumber" is not defined for objectlass "top"
Aug  5 16:44:09 work slapd[17541]: backsql_id2entry(): attribute "loginShell" is not defined for objectlass "top"
Aug  5 16:44:09 work slapd[17541]: backsql_id2entry(): attribute "homeDirectory" is not defined for objectlass "top"
Aug  5 16:44:09 work slapd[17541]: backsql_id2entry(): attribute "userPassword" is not defined for objectlass "top"
Aug  5 16:44:09 work slapd[17541]: backsql_id2entry(): attribute "description" is not defined for objectlass "top"
Aug  5 16:44:09 work slapd[17541]: backsql_id2entry(): attribute "gecos" is not defined for objectlass "top"
Aug  5 16:44:09 work slapd[17541]: backsql_id2entry(): attribute "memberUid" is not defined for objectlass "top"
Aug  5 16:44:09 work slapd[17541]: backsql_id2entry(): attribute "dc" is not defined for objectlass "top"
Aug  5 16:44:09 work slapd[17541]: backsql_id2entry(): attribute "spamassassin" is not defined for objectlass "top"
Aug  5 16:44:09 work slapd[17541]: ==>backsql_get_attr_vals(): oc="top" attr="objectClass" keyval=0
Aug  5 16:44:09 work slapd[17541]: backsql_get_attr_vals(): number of values in query: 0
Aug  5 16:44:09 work slapd[17541]: backsql_id2entry(): attribute "ref" is not defined for objectlass "top"
Aug  5 16:44:09 work slapd[17541]: <==backsql_id2entry()
Aug  5 16:44:09 work slapd[17541]: ==>backsql_oc_get_candidates(): oc="top"
Aug  5 16:44:09 work slapd[17541]: ==>backsql_srch_query()
Aug  5 16:44:09 work slapd[17541]: ==>backsql_process_filter()
Aug  5 16:44:09 work slapd[17541]: ==>backsql_process_filter()
Aug  5 16:44:09 work slapd[17541]: ==>backsql_process_filter_attr(objectClass)
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "cn" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "uid" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "uidNumber" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "gidNumber" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "loginShell" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "homeDirectory" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "userPassword" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "description" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "gecos" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "memberUid" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "dc" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "spamassassin" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "objectClass" is in list
Aug  5 16:44:09 work slapd[17541]: <==backsql_process_filter_attr(objectClass)
Aug  5 16:44:09 work slapd[17541]: <==backsql_process_filter() succeeded
Aug  5 16:44:09 work slapd[17541]: ==>backsql_process_filter()
Aug  5 16:44:09 work slapd[17541]: <==backsql_process_filter() succeeded
Aug  5 16:44:09 work slapd[17541]: <==backsql_process_filter() succeeded
Aug  5 16:44:09 work slapd[17541]: <==backsql_srch_query() returns SELECT DISTINCT ldap_entries.id,stat_options.type,'top' AS "objectClass",ldap_entries.dn AS "dn" FROM ldap_entries,stat_options,ldap_entry_objclasses WHERE stat_options.type=ldap_entries.keyval AND ldap_entries.oc_map_id=? AND ldap_entries.parent=? AND ldap_entries.keyval=stat_options.type AND ldap_entries.oc_map_id=1 AND ((ldap_entries.id=ldap_entry_objclasses.entry_id AND ldap_entry_objclasses.oc_name='mailDomain') AND 7=7)
Aug  5 16:44:09 work slapd[17541]: Constructed query: SELECT DISTINCT ldap_entries.id,stat_options.type,'top' AS "objectClass",ldap_entries.dn AS "dn" FROM ldap_entries,stat_options,ldap_entry_objclasses WHERE stat_options.type=ldap_entries.keyval AND ldap_entries.oc_map_id=? AND ldap_entries.parent=? AND ldap_entries.keyval=stat_options.type AND ldap_entries.oc_map_id=1 AND ((ldap_entries.id=ldap_entry_objclasses.entry_id AND ldap_entry_objclasses.oc_name='mailDomain') AND 7=7)
Aug  5 16:44:09 work slapd[17541]: id: '1'
Aug  5 16:44:09 work slapd[17541]: (one)id: '2'
Aug  5 16:44:09 work slapd[17541]: <==backsql_oc_get_candidates(): 0
Aug  5 16:44:09 work slapd[17541]: ==>backsql_oc_get_candidates(): oc="posixAccount"
Aug  5 16:44:09 work slapd[17541]: ==>backsql_srch_query()
Aug  5 16:44:09 work slapd[17541]: ==>backsql_process_filter()
Aug  5 16:44:09 work slapd[17541]: ==>backsql_process_filter()
Aug  5 16:44:09 work slapd[17541]: ==>backsql_process_filter_attr(objectClass)
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "cn" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "uid" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "uidNumber" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "gidNumber" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "loginShell" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "homeDirectory" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "userPassword" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "description" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "gecos" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "memberUid" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "dc" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "spamassassin" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "objectClass" is in list
Aug  5 16:44:09 work slapd[17541]: <==backsql_process_filter_attr(objectClass)
Aug  5 16:44:09 work slapd[17541]: <==backsql_process_filter() succeeded
Aug  5 16:44:09 work slapd[17541]: ==>backsql_process_filter()
Aug  5 16:44:09 work slapd[17541]: ==>backsql_process_filter_attr(dc)
Aug  5 16:44:09 work slapd[17541]: bdb_modify: updated id=00000008 dn="dc=mallvarna.bg,dc=HOSTING,dc=SPNET,dc=NET"
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "cn" is in list
Aug  5 16:44:09 work slapd[17541]: send_ldap_result: conn=-1 op=0 p=3
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "uid" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "uidNumber" is in list
Aug  5 16:44:09 work slapd[17541]: ENTRY ADDED/MERGED, CACHED ENTRIES=102
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "gidNumber" is in list
Aug  5 16:44:09 work slapd[17541]: STORED QUERIES = 614
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "loginShell" is in list
Aug  5 16:44:09 work slapd[17541]: conn=2987 op=5 SEARCH RESULT tag=101 err=0 nentries=1 text=
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "homeDirectory" is in list
Aug  5 16:44:09 work slapd[17541]: <==backsql_search()
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "userPassword" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "description" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "gecos" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "memberUid" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "dc" is in list
Aug  5 16:44:09 work slapd[17541]: <==backsql_process_filter_attr(dc)
Aug  5 16:44:09 work slapd[17541]: <==backsql_process_filter() succeeded
Aug  5 16:44:09 work slapd[17541]: <==backsql_process_filter() succeeded
Aug  5 16:44:09 work slapd[17541]: <==backsql_srch_query() returns SELECT DISTINCT ldap_entries.id,hosting.service_id,'posixAccount' AS "objectClass",ldap_entries.dn AS "dn" FROM ldap_entries,hosting,ldap_entry_objclasses WHERE hosting.service_id=ldap_entries.keyval AND ldap_entries.oc_map_id=? AND ldap_entries.parent=? AND ldap_entries.keyval=hosting.service_id AND ldap_entries.oc_map_id=2 AND ((ldap_entries.id=ldap_entry_objclasses.entry_id AND ldap_entry_objclasses.oc_name='mailDomain') AND (UPPER(nvl(mail_domain,hosting.service_id))='JUSTILLUSION.NET'))
Aug  5 16:44:09 work slapd[17541]: Constructed query: SELECT DISTINCT ldap_entries.id,hosting.service_id,'posixAccount' AS "objectClass",ldap_entries.dn AS "dn" FROM ldap_entries,hosting,ldap_entry_objclasses WHERE hosting.service_id=ldap_entries.keyval AND ldap_entries.oc_map_id=? AND ldap_entries.parent=? AND ldap_entries.keyval=hosting.service_id AND ldap_entries.oc_map_id=2 AND ((ldap_entries.id=ldap_entry_objclasses.entry_id AND ldap_entry_objclasses.oc_name='mailDomain') AND (UPPER(nvl(mail_domain,hosting.service_id))='JUSTILLUSION.NET'))
Aug  5 16:44:09 work slapd[17541]: id: '2'
Aug  5 16:44:09 work slapd[17541]: (one)id: '2'
Aug  5 16:44:09 work slapd[17541]: >>> dnPrettyNormal: <DC=justillusion.net,DC=HOSTING,DC=SPNET,DC=NET>
Aug  5 16:44:09 work slapd[17541]: <<< dnPrettyNormal: <dc=justillusion.net,dc=HOSTING,dc=SPNET,dc=NET>, <dc=justillusion.net,dc=hosting,dc=spnet,dc=net>
Aug  5 16:44:09 work slapd[17541]: backsql_oc_get_candidates(): added entry id=100150762, keyval=150762 dn="DC=justillusion.net,DC=HOSTING,DC=SPNET,DC=NET"
Aug  5 16:44:09 work slapd[17541]: <==backsql_oc_get_candidates(): 1
Aug  5 16:44:09 work slapd[17541]: ==>backsql_oc_get_candidates(): oc="mailUser"
Aug  5 16:44:09 work slapd[17541]: ==>backsql_srch_query()
Aug  5 16:44:09 work slapd[17541]: ==>backsql_process_filter()
Aug  5 16:44:09 work slapd[17541]: ==>backsql_process_filter()
Aug  5 16:44:09 work slapd[17541]: ==>backsql_process_filter_attr(objectClass)
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "cn" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "uid" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "uidNumber" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "gidNumber" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "loginShell" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "homeDirectory" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "userPassword" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "description" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "gecos" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "memberUid" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "dc" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "spamassassin" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "objectClass" is in list
Aug  5 16:44:09 work slapd[17541]: <==backsql_process_filter_attr(objectClass)
Aug  5 16:44:09 work slapd[17541]: <==backsql_process_filter() succeeded
Aug  5 16:44:09 work slapd[17541]: ==>backsql_process_filter()
Aug  5 16:44:09 work slapd[17541]: ==>backsql_process_filter_attr(dc)
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "cn" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "uid" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "uidNumber" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "gidNumber" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "loginShell" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "homeDirectory" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "userPassword" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "description" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "gecos" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "memberUid" is in list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_attrlist_add(): attribute "dc" is in list
Aug  5 16:44:09 work slapd[17541]: <==backsql_process_filter_attr(dc)
Aug  5 16:44:09 work slapd[17541]: <==backsql_process_filter() succeeded
Aug  5 16:44:09 work slapd[17541]: <==backsql_process_filter() succeeded
Aug  5 16:44:09 work slapd[17541]: <==backsql_srch_query() returns SELECT DISTINCT ldap_entries.id,mail_user.mail_user_id,'mailUser' AS "objectClass",ldap_entries.dn AS "dn" FROM ldap_entries,mail_user,ldap_entry_objclasses,hosting WHERE mail_user.mail_user_id=ldap_entries.keyval AND ldap_entries.oc_map_id=? AND ldap_entries.parent=? AND ldap_entries.keyval=mail_user.mail_user_id AND ldap_entries.oc_map_id=3 AND hosting.service_id=mail_user.service_id AND ((ldap_entries.id=ldap_entry_objclasses.entry_id AND ldap_entry_objclasses.oc_name='mailDomain') AND (UPPER(nvl(mail_domain,hosting.service_id))='JUSTILLUSION.NET'))
Aug  5 16:44:09 work slapd[17541]: Constructed query: SELECT DISTINCT ldap_entries.id,mail_user.mail_user_id,'mailUser' AS "objectClass",ldap_entries.dn AS "dn" FROM ldap_entries,mail_user,ldap_entry_objclasses,hosting WHERE mail_user.mail_user_id=ldap_entries.keyval AND ldap_entries.oc_map_id=? AND ldap_entries.parent=? AND ldap_entries.keyval=mail_user.mail_user_id AND ldap_entries.oc_map_id=3 AND hosting.service_id=mail_user.service_id AND ((ldap_entries.id=ldap_entry_objclasses.entry_id AND ldap_entry_objclasses.oc_name='mailDomain') AND (UPPER(nvl(mail_domain,hosting.service_id))='JUSTILLUSION.NET'))
ug  5 16:44:09 work slapd[17541]: id: '3'
Aug  5 16:44:09 work slapd[17541]: (one)id: '2'
Aug  5 16:44:09 work slapd[17541]: <==backsql_oc_get_candidates(): 0
Aug  5 16:44:09 work slapd[17541]: backsql_search(): loading data for entry id=100150762, oc_id=2, keyval=150762
Aug  5 16:44:09 work slapd[17541]: ==>backsql_id2entry()
Aug  5 16:44:09 work slapd[17541]: backsql_id2entry(): custom attribute list
Aug  5 16:44:09 work slapd[17541]: ==>backsql_get_attr_vals(): oc="posixAccount" attr="cn" keyval=150762
Aug  5 16:44:09 work slapd[17541]: backsql_get_attr_vals(): number of values in query: 1
Aug  5 16:44:09 work slapd[17541]: <==backsql_get_attr_vals()
Aug  5 16:44:09 work slapd[17541]: ==>backsql_get_attr_vals(): oc="posixAccount" attr="uid" keyval=150762
Aug  5 16:44:09 work slapd[17541]: backsql_get_attr_vals(): number of values in query: 1
Aug  5 16:44:09 work slapd[17541]: <==backsql_get_attr_vals()
Aug  5 16:44:09 work slapd[17541]: ==>backsql_get_attr_vals(): oc="posixAccount" attr="uidNumber" keyval=150762
Aug  5 16:44:09 work slapd[17541]: backsql_get_attr_vals(): number of values in query: 1
Aug  5 16:44:09 work slapd[17541]: <==backsql_get_attr_vals()
Aug  5 16:44:09 work slapd[17541]: ==>backsql_get_attr_vals(): oc="posixAccount" attr="gidNumber" keyval=150762
Aug  5 16:44:09 work slapd[17541]: backsql_get_attr_vals(): number of values in query: 1
Aug  5 16:44:09 work slapd[17541]: <==backsql_get_attr_vals()
Aug  5 16:44:09 work slapd[17541]: ==>backsql_get_attr_vals(): oc="posixAccount" attr="loginShell" keyval=150762
Aug  5 16:44:09 work slapd[17541]: backsql_get_attr_vals(): number of values in query: 1
Aug  5 16:44:09 work slapd[17541]: <==backsql_get_attr_vals()
Aug  5 16:44:09 work slapd[17541]: ==>backsql_get_attr_vals(): oc="posixAccount" attr="homeDirectory" keyval=150762
Aug  5 16:44:09 work slapd[17541]: backsql_get_attr_vals(): number of values in query: 1
Aug  5 16:44:09 work slapd[17541]: <==backsql_get_attr_vals()
Aug  5 16:44:09 work slapd[17541]: ==>backsql_get_attr_vals(): oc="posixAccount" attr="userPassword" keyval=150762
Aug  5 16:44:09 work slapd[17541]: backsql_get_attr_vals(): number of values in query: 1
Aug  5 16:44:09 work slapd[17541]: <==backsql_get_attr_vals()
Aug  5 16:44:09 work slapd[17541]: ==>backsql_get_attr_vals(): oc="posixAccount" attr="description" keyval=150762
Aug  5 16:44:09 work slapd[17541]: backsql_get_attr_vals(): number of values in query: 1
Aug  5 16:44:09 work slapd[17541]: <==backsql_get_attr_vals()
Aug  5 16:44:09 work slapd[17541]: ==>backsql_get_attr_vals(): oc="posixAccount" attr="gecos" keyval=150762
Aug  5 16:44:09 work slapd[17541]: backsql_get_attr_vals(): number of values in query: 1
Aug  5 16:44:09 work slapd[17541]: <==backsql_get_attr_vals()
Aug  5 16:44:09 work slapd[17541]: ==>backsql_get_attr_vals(): oc="posixAccount" attr="memberUid" keyval=150762
Aug  5 16:44:09 work slapd[17541]: backsql_get_attr_vals(): number of values in query: 1
Aug  5 16:44:09 work slapd[17541]: <==backsql_get_attr_vals()
Aug  5 16:44:09 work slapd[17541]: ==>backsql_get_attr_vals(): oc="posixAccount" attr="dc" keyval=150762
Aug  5 16:44:09 work slapd[17541]: backsql_get_attr_vals(): number of values in query: 1
Aug  5 16:44:09 work slapd[17541]: <==backsql_get_attr_vals()
Aug  5 16:44:09 work slapd[17541]: ==>backsql_get_attr_vals(): oc="posixAccount" attr="spamassassin" keyval=150762
Aug  5 16:44:09 work slapd[17541]: backsql_get_attr_vals(): number of values in query: 1
Aug  5 16:44:09 work slapd[17541]: <==backsql_get_attr_vals()
Aug  5 16:44:09 work slapd[17541]: ==>backsql_get_attr_vals(): oc="posixAccount" attr="objectClass" keyval=150762
Aug  5 16:44:09 work slapd[17541]: backsql_get_attr_vals(): number of values in query: 3
Aug  5 16:44:09 work slapd[17541]: <==backsql_get_attr_vals()
Aug  5 16:44:09 work slapd[17541]: backsql_id2entry(): attribute "ref" is not defined for objectlass "posixAccount"
Aug  5 16:44:09 work slapd[17541]: <==backsql_id2entry()
Aug  5 16:44:09 work slapd[17541]: ==>backsql_operational(): entry "dc=justillusion.net,dc=hosting,dc=spnet,dc=net"
Aug  5 16:44:09 work slapd[17541]: ==>backsql_get_db_conn()
Aug  5 16:44:09 work slapd[17541]: <==backsql_get_db_conn()
Aug  5 16:44:09 work slapd[17541]: <==backsql_operational(0)
Aug  5 16:44:09 work slapd[17541]: => send_search_entry: conn 2987 dn="dc=justillusion.net,dc=HOSTING,dc=SPNET,dc=NET"
Aug  5 16:44:09 work slapd[17541]: conn=2987 op=6 ENTRY dn="dc=justillusion.net,dc=hosting,dc=spnet,dc=net"
Aug  5 16:44:09 work slapd[17541]: <= send_search_entry: conn 2987 exit.
Aug  5 16:44:09 work slapd[17541]: send_ldap_result: conn=2987 op=6 p=3
Aug  5 16:44:09 work slapd[17541]: send_ldap_response: msgid=19 tag=101 err=0
Aug  5 16:44:09 work slapd[17541]: Added query expires at 1249483449 (POSITIVE)
Aug  5 16:44:09 work slapd[17541]: Lock AQ index = 0x180d140
Aug  5 16:44:09 work slapd[17541]: TEMPLATE 0x180d140 QUERIES++ 6
Aug  5 16:44:09 work slapd[17541]: Unlock AQ index = 0x180d140
Aug  5 16:44:09 work slapd[17541]: Base of added query = dc=hosting,dc=spnet,dc=net
Aug  5 16:44:09 work slapd[17541]: UUID for query being added = c79ab454-47c5-4019-a9cb-40ea588b7565
Aug  5 16:44:09 work slapd[17541]: bdb_dn2entry("dc=justillusion.net,dc=hosting,dc=spnet,dc=net")
Aug  5 16:44:09 work slapd[17541]: send_ldap_result: conn=-1 op=0 p=3
Aug  5 16:44:09 work slapd[17541]: bdb_dn2entry("dc=justillusion.net,dc=hosting,dc=spnet,dc=net")
Aug  5 16:44:09 work slapd[17541]: send_ldap_result: conn=-1 op=0 p=3
Aug  5 16:44:09 work slapd[17541]: ENTRY ADDED/MERGED, CACHED ENTRIES=102
Aug  5 16:44:09 work slapd[17541]: STORED QUERIES = 615
Aug  5 16:44:09 work slapd[17541]: conn=2987 op=6 SEARCH RESULT tag=101 err=0 nentries=1 text=
Aug  5 16:44:09 work slapd[17541]: <==backsql_search()
....

Aug  5 16:44:22 work slapd[17541]: connection_get(14): got connid=3005
Aug  5 16:44:22 work slapd[17541]: connection_read(14): checking for input on id=3005
Aug  5 16:44:22 work slapd[17541]: op tag 0x63, time 1249479862
Aug  5 16:44:22 work slapd[17541]: conn=3005 op=6 do_search
Aug  5 16:44:22 work slapd[17541]: >>> dnPrettyNormal: <dc=HOSTING,dc=SPNET,dc=NET>
Aug  5 16:44:22 work slapd[17541]: <<< dnPrettyNormal: <dc=HOSTING,dc=SPNET,dc=NET>, <dc=hosting,dc=spnet,dc=net>
Aug  5 16:44:22 work slapd[17541]: conn=3005 op=6 SRCH base="dc=HOSTING,dc=SPNET,dc=NET" scope=1 deref=2 filter="(&(objectClass=mailDomain)(dc=justillusion.net))"
Aug  5 16:44:22 work slapd[17541]: conn=3005 op=6 SRCH attr=spamassassin
Aug  5 16:44:22 work slapd[17541]: ==> limits_get: conn=3005 op=6 self="cn=nss,dc=ldapaccess" this="dc=hosting,dc=spnet,dc=net"
Aug  5 16:44:22 work slapd[17541]: query template of incoming query = (&(objectClass=)(dc=))
Aug  5 16:44:22 work slapd[17541]: Entering QC, querystr = (&(objectClass=mailDomain)(dc=justillusion.net))
Aug  5 16:44:22 work slapd[17541]: Lock QC index = 0x180d140
Aug  5 16:44:22 work slapd[17541]: Base of added query = dc=hosting,dc=spnet,dc=net
Aug  5 16:44:22 work slapd[17541]: QUERY ANSWERABLE
Aug  5 16:44:22 work slapd[17541]: => bdb_search
Aug  5 16:44:22 work slapd[17541]: bdb_dn2entry("dc=hosting,dc=spnet,dc=net")
Aug  5 16:44:22 work slapd[17541]: search_candidates: base="dc=hosting,dc=spnet,dc=net" (0x00000002) scope=1
Aug  5 16:44:22 work slapd[17541]: => bdb_dn2idl("dc=hosting,dc=spnet,dc=net")
Aug  5 16:44:22 work slapd[17541]: <= bdb_dn2idl: id=11 first=3 last=114
Aug  5 16:44:22 work slapd[17541]: => bdb_equality_candidates (objectClass)
Aug  5 16:44:22 work slapd[17541]: => key_read
Aug  5 16:44:22 work slapd[17541]: <= bdb_index_read: failed (-30989)
Aug  5 16:44:22 work slapd[17541]: <= bdb_equality_candidates: id=0, first=0, last=0
Aug  5 16:44:22 work slapd[17541]: => bdb_equality_candidates (objectClass)
Aug  5 16:44:22 work slapd[17541]: => key_read
Aug  5 16:44:22 work slapd[17541]: <= bdb_index_read 10 candidates
Aug  5 16:44:22 work slapd[17541]: <= bdb_equality_candidates: id=10, first=3, last=114
Aug  5 16:44:22 work slapd[17541]: => bdb_equality_candidates (dc)
Aug  5 16:44:22 work slapd[17541]: => key_read
Aug  5 16:44:22 work slapd[17541]: <= bdb_index_read 1 candidates
Aug  5 16:44:22 work slapd[17541]: <= bdb_equality_candidates: id=1, first=87, last=87
Aug  5 16:44:22 work slapd[17541]: bdb_search_candidates: id=1 first=87 last=87
Aug  5 16:44:22 work slapd[17541]: bdb_search: 87 scope not okay
Aug  5 16:44:22 work slapd[17541]: send_ldap_result: conn=3005 op=6 p=3
Aug  5 16:44:22 work slapd[17541]: send_ldap_response: msgid=19 tag=101 err=0
Aug  5 16:44:22 work slapd[17541]: conn=3005 op=6 SEARCH RESULT tag=101 err=0 nentries=0 text=
Aug  5 16:44:22 work slapd[17541]: connection_get(14): got connid=3005
.... good cache hit
Aug  5 16:44:22 work slapd[17541]: op tag 0x63, time 1249479862
Aug  5 16:44:22 work slapd[17541]: conn=3005 op=7 do_search
Aug  5 16:44:22 work slapd[17541]: >>> dnPrettyNormal: <dc=HOSTING,dc=SPNET,dc=NET>
Aug  5 16:44:22 work slapd[17541]: <<< dnPrettyNormal: <dc=HOSTING,dc=SPNET,dc=NET>, <dc=hosting,dc=spnet,dc=net>
Aug  5 16:44:22 work slapd[17541]: conn=3005 op=7 SRCH base="dc=HOSTING,dc=SPNET,dc=NET" scope=1 deref=2 filter="(&(objectClass=mailDomain)(dc=hotspot.spnet.net))"
Aug  5 16:44:22 work slapd[17541]: conn=3005 op=7 SRCH attr=spamassassin
Aug  5 16:44:22 work slapd[17541]: ==> limits_get: conn=3005 op=7 self="cn=nss,dc=ldapaccess" this="dc=hosting,dc=spnet,dc=net"
Aug  5 16:44:22 work slapd[17541]: query template of incoming query = (&(objectClass=)(dc=))
Aug  5 16:44:22 work slapd[17541]: Entering QC, querystr = (&(objectClass=mailDomain)(dc=hotspot.spnet.net))
Aug  5 16:44:22 work slapd[17541]: Lock QC index = 0x180d140
Aug  5 16:44:22 work slapd[17541]: Base of added query = dc=hosting,dc=spnet,dc=net
Aug  5 16:44:22 work slapd[17541]: QUERY ANSWERABLE
Aug  5 16:44:22 work slapd[17541]: => bdb_search
Aug  5 16:44:22 work slapd[17541]: bdb_dn2entry("dc=hosting,dc=spnet,dc=net")
Aug  5 16:44:22 work slapd[17541]: search_candidates: base="dc=hosting,dc=spnet,dc=net" (0x00000002) scope=1
Aug  5 16:44:22 work slapd[17541]: => bdb_dn2idl("dc=hosting,dc=spnet,dc=net")
Aug  5 16:44:22 work slapd[17541]: <= bdb_dn2idl: id=11 first=3 last=114
Aug  5 16:44:22 work slapd[17541]: => bdb_equality_candidates (objectClass)
Aug  5 16:44:22 work slapd[17541]: => key_read
Aug  5 16:44:22 work slapd[17541]: <= bdb_index_read: failed (-30989)
Aug  5 16:44:22 work slapd[17541]: <= bdb_equality_candidates: id=0, first=0, last=0
Aug  5 16:44:22 work slapd[17541]: => bdb_equality_candidates (objectClass)
Aug  5 16:44:22 work slapd[17541]: => key_read
Aug  5 16:44:22 work slapd[17541]: <= bdb_index_read 10 candidates
Aug  5 16:44:22 work slapd[17541]: <= bdb_equality_candidates: id=10, first=3, last=114
Aug  5 16:44:22 work slapd[17541]: => bdb_equality_candidates (dc)
Aug  5 16:44:22 work slapd[17541]: => key_read
Aug  5 16:44:22 work slapd[17541]: <= bdb_index_read 1 candidates
Aug  5 16:44:22 work slapd[17541]: <= bdb_equality_candidates: id=1, first=112, last=112
Aug  5 16:44:22 work slapd[17541]: bdb_search_candidates: id=1 first=112 last=112
Aug  5 16:44:22 work slapd[17541]: ==>backsql_operational(): entry "dc=hotspot.spnet.net,dc=hosting,dc=spnet,dc=net"
Aug  5 16:44:22 work slapd[17541]: ==>backsql_get_db_conn()
Aug  5 16:44:22 work slapd[17541]: <==backsql_get_db_conn()
Aug  5 16:44:22 work slapd[17541]: <==backsql_operational(0)
Aug  5 16:44:22 work slapd[17541]: => send_search_entry: conn 3005 dn="dc=hotspot.spnet.net,dc=HOSTING,dc=SPNET,dc=NET"
Aug  5 16:44:22 work slapd[17541]: conn=3005 op=7 ENTRY dn="dc=hotspot.spnet.net,dc=hosting,dc=spnet,dc=net"
Aug  5 16:44:22 work slapd[17541]: <= send_search_entry: conn 3005 exit.