DEBUG : 15-03-24 09:12:27:567 +0400 : LDAP connect DEBUG : 15-03-24 09:12:27:567 +0400 : Binding to ldap... DEBUG : 15-03-24 09:12:27:568 +0400 : Connection params: {:host=>"192.168.59.103", :port=>49389, :base=>"dc=adharacs,dc=lan", :auth=>{:method=>:simple, :username=>"cn=admin,dc=adharacs,dc=lan", :password=>"admin"}} DEBUG : 15-03-24 09:12:27:582 +0400 : Connection succeed DEBUG : 15-03-24 09:12:27:582 +0400 : LDAP connect DEBUG : 15-03-24 09:12:27:583 +0400 : Binding to ldap... DEBUG : 15-03-24 09:12:27:583 +0400 : Connection params: {:host=>"127.0.0.1", :port=>49390, :base=>"dc=adharacs,dc=lan", :auth=>{:method=>:simple, :username=>"cn=admin,dc=adharacs,dc=lan", :password=>"admin"}} ERROR : 15-03-24 09:12:27:583 +0400 : Connection refused '#' INFO : 15-03-24 09:12:27:584 +0400 : Search base '' filter '' attributes '' INFO : 15-03-24 09:12:27:604 +0400 : Search result SUCCESS return code:0, matched_dn: , message:Success, data:[#["dc=adharacs,dc=lan"], :objectclass=>["top", "dcObject", "organization"], :o=>["'LDAP Test'"], :dc=>["adharacs"]}>, #["cn=admin,dc=adharacs,dc=lan"], :objectclass=>["simpleSecurityObject", "organizationalRole"], :cn=>["admin"], :description=>["LDAP administrator"], :userpassword=>["{SSHA}gfWXs6CZOCDlQXMwol0YPSye3w12V/XU"]}>, #["ou=people,dc=adharacs,dc=lan"], :objectclass=>["organizationalUnit", "top"], :ou=>["people"]}>, #["ou=mailbox,dc=adharacs,dc=lan"], :objectclass=>["organizationalUnit", "top"], :ou=>["mailbox"]}>, #["ou=alias,dc=adharacs,dc=lan"], :objectclass=>["organizationalUnit", "top"], :ou=>["alias"]}>, #["ou=mailinglist,dc=adharacs,dc=lan"], :objectclass=>["organizationalUnit", "top"], :ou=>["mailinglist"]}>, #["ou=domain,dc=adharacs,dc=lan"], :objectclass=>["organizationalUnit", "top"], :ou=>["domain"]}>, #["ou=vacation,dc=adharacs,dc=lan"], :objectclass=>["organizationalUnit", "top"], :ou=>["vacation"]}>, #["ou=backup,dc=adharacs,dc=lan"], :objectclass=>["organizationalUnit", "top"], :ou=>["backup"]}>, #["ou=vpn,dc=adharacs,dc=lan"], :objectclass=>["organizationalUnit", "top"], :ou=>["vpn"]}>, #["ou=service,dc=adharacs,dc=lan"], :objectclass=>["organizationalUnit", "top"], :ou=>["service"]}>, #["uid=postfix,ou=service,dc=adharacs,dc=lan"], :objectclass=>["account", "simpleSecurityObject", "top"], :uid=>["postfix"], :userpassword=>["{SSHA}Eh7VTDYqC8qoZrvSkg68rMSnFoc3J5X3"]}>, #["uid=gnarwl,ou=service,dc=adharacs,dc=lan"], :objectclass=>["account", "simpleSecurityObject", "top"], :uid=>["postfix", "gnarwl"], :userpassword=>["{SSHA}WNBFAIOiPCRBrg4aB5jGQBAjDKVeHB6c"]}>, #["uid=dovecot,ou=service,dc=adharacs,dc=lan"], :objectclass=>["account", "simpleSecurityObject", "top"], :uid=>["dovecot"], :userpassword=>["{SSHA}hhmido6GNmtGo4iXqj7hdvIPAIxytxYY"]}>, #["uid=saslauthd,ou=service,dc=adharacs,dc=lan"], :objectclass=>["account", "simpleSecurityObject", "top"], :uid=>["saslauthd"], :userpassword=>["{SSHA}WsKuZD5GxtX8oSGx5ZLd2KUMMoSgNi74"]}>, #["uid=radicale,ou=service,dc=adharacs,dc=lan"], :objectclass=>["account", "simpleSecurityObject", "top"], :uid=>["radicale"], :userpassword=>["{SSHA}PyObb9eltm9LCr1OSVh9qzNhXO54q3K7"]}>, #["uid=proftpd,ou=service,dc=adharacs,dc=lan"], :objectclass=>["account", "simpleSecurityObject", "top"], :uid=>["proftpd"], :userpassword=>["{SSHA}rjeE6bAqX4UhLXLxMKSYT5qoXYjTuT8l"]}>, #["uid=openvpn,ou=service,dc=adharacs,dc=lan"], :objectclass=>["account", "simpleSecurityObject", "top"], :uid=>["openvpn"], :userpassword=>["{SSHA}5vaBhOIqxkNOgfhd4zUQJ3Qij+nCf3Jj"]}>] INFO : 15-03-24 09:12:27:604 +0400 : Add dn 'uid=1,ou=people,dc=adharacs,dc=lan' attributes '{:sn=>"john.doe", :cn=>"John Doe", :givenName=>"John Doe", :mail=>"john.doe@adharacs.lan", :userPassword=>"{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1", :objectClass=>["organizationalPerson", "person", "top", "extensibleObject"]}' INFO : 15-03-24 09:12:27:632 +0400 : Add result SUCCESS return code:0, matched_dn: , message:Success, data:nil INFO : 15-03-24 09:12:27:635 +0400 : Search base 'ou=people,dc=adharacs,dc=lan' filter '(mail=john.doe@adharacs.lan)' attributes 'mail' INFO : 15-03-24 09:12:27:646 +0400 : Search result SUCCESS return code:0, matched_dn: , message:Success, data:[#["uid=1,ou=people,dc=adharacs,dc=lan"], :mail=>["john.doe@adharacs.lan"]}>] INFO : 15-03-24 09:12:27:647 +0400 : Delete dn 'uid=1,ou=people,dc=adharacs,dc=lan' INFO : 15-03-24 09:12:27:655 +0400 : Delete result SUCCESS return code:0, matched_dn: , message:Success, data:nil DEBUG : 15-03-24 09:12:27:656 +0400 : Acs::Ldap::Mapper with options methods_separator '.' force_method_calls 'false' DEBUG : 15-03-24 09:12:27:656 +0400 : Acs::Ldap::Mapper chain_method on object '[[[1, 2], 3], 4]' with chain 'shift.shift.shift' separator '.' force_method_calls 'false' DEBUG : 15-03-24 09:12:27:657 +0400 : Acs::Ldap::Mapper chain_method on object '[[1, 2], 3]' with chain 'shift.shift' separator '.' force_method_calls 'false' DEBUG : 15-03-24 09:12:27:657 +0400 : Acs::Ldap::Mapper chain_method on object '[1, 2]' with chain 'shift' separator '.' force_method_calls 'false' DEBUG : 15-03-24 09:12:27:657 +0400 : Acs::Ldap::Mapper chain_method returns '1' DEBUG : 15-03-24 09:12:27:657 +0400 : Acs::Ldap::Mapper with options methods_separator '.' force_method_calls 'false' DEBUG : 15-03-24 09:12:27:657 +0400 : Acs::Ldap::Mapper chain_method on object '#' with chain 'get' separator '.' force_method_calls 'false' DEBUG : 15-03-24 09:12:27:657 +0400 : Acs::Ldap::Mapper chain_method returns 'test' DEBUG : 15-03-24 09:12:27:657 +0400 : Acs::Ldap::Mapper with options methods_separator '.' force_method_calls 'false' DEBUG : 15-03-24 09:12:27:657 +0400 : Acs::Ldap::Mapper chain_method on object '#' with chain 'get.get' separator '.' force_method_calls 'false' DEBUG : 15-03-24 09:12:27:657 +0400 : Acs::Ldap::Mapper chain_method on object '#' with chain 'get' separator '.' force_method_calls 'false' DEBUG : 15-03-24 09:12:27:657 +0400 : Acs::Ldap::Mapper chain_method returns 'test' DEBUG : 15-03-24 09:12:27:658 +0400 : Acs::Ldap::Mapper with options methods_separator '.' force_method_calls 'false' DEBUG : 15-03-24 09:12:27:658 +0400 : Acs::Ldap::Mapper chain_method on object '[[[1, 2], 3], 4]' with chain 'shift:shift:shift' separator ':' force_method_calls 'false' DEBUG : 15-03-24 09:12:27:658 +0400 : Acs::Ldap::Mapper chain_method on object '[[1, 2], 3]' with chain 'shift:shift' separator ':' force_method_calls 'false' DEBUG : 15-03-24 09:12:27:658 +0400 : Acs::Ldap::Mapper chain_method on object '[1, 2]' with chain 'shift' separator ':' force_method_calls 'false' DEBUG : 15-03-24 09:12:27:658 +0400 : Acs::Ldap::Mapper chain_method returns '1' DEBUG : 15-03-24 09:12:27:658 +0400 : Acs::Ldap::Mapper with options methods_separator '.' force_method_calls 'false' DEBUG : 15-03-24 09:12:27:658 +0400 : Acs::Ldap::Mapper chain_method on object '#' with chain 'get:get' separator ':' force_method_calls 'false' DEBUG : 15-03-24 09:12:27:658 +0400 : Acs::Ldap::Mapper chain_method on object '#' with chain 'get' separator ':' force_method_calls 'false' DEBUG : 15-03-24 09:12:27:658 +0400 : Acs::Ldap::Mapper chain_method returns 'test' DEBUG : 15-03-24 09:12:27:659 +0400 : Acs::Ldap::Mapper with options methods_separator '.' force_method_calls 'false' DEBUG : 15-03-24 09:12:27:659 +0400 : Acs::Ldap::Mapper chain_method on object '#' with chain 'get.get' separator '.' force_method_calls 'false' DEBUG : 15-03-24 09:12:27:659 +0400 : Acs::Ldap::Mapper chain_method on object '#' with chain 'get' separator '.' force_method_calls 'false' DEBUG : 15-03-24 09:12:27:659 +0400 : Acs::Ldap::Mapper chain_method returns 'test' DEBUG : 15-03-24 09:12:27:660 +0400 : Acs::Ldap::Mapper with options methods_separator '.' force_method_calls 'false' DEBUG : 15-03-24 09:12:27:660 +0400 : Acs::Ldap::Mapper chain_method on object '#' with chain 'get' separator '.' force_method_calls 'false' DEBUG : 15-03-24 09:12:27:660 +0400 : Acs::Ldap::Mapper chain_method returns 'test' DEBUG : 15-03-24 09:12:27:661 +0400 : Acs::Ldap::Mapper with options methods_separator '.' force_method_calls 'false' DEBUG : 15-03-24 09:12:27:661 +0400 : Acs::Ldap::Mapper chain_method on object '#' with chain 'get.get' separator '.' force_method_calls 'false' DEBUG : 15-03-24 09:12:27:661 +0400 : Acs::Ldap::Mapper chain_method on object '#' with chain 'get' separator '.' force_method_calls 'false' DEBUG : 15-03-24 09:12:27:661 +0400 : Acs::Ldap::Mapper chain_method returns 'test' DEBUG : 15-03-24 09:12:27:661 +0400 : Acs::Ldap::Mapper with options methods_separator '.' force_method_calls 'false' DEBUG : 15-03-24 09:12:27:662 +0400 : Acs::Ldap::Mapper chain_method on object '#' with chain 'get' separator '.' force_method_calls 'false' DEBUG : 15-03-24 09:12:27:662 +0400 : Acs::Ldap::Mapper chain_method returns 'test' DEBUG : 15-03-24 09:12:27:662 +0400 : Acs::Ldap::Mapper with options methods_separator '.' force_method_calls 'false' DEBUG : 15-03-24 09:12:27:662 +0400 : Acs::Ldap::Mapper chain_method on object '#' with chain 'get' separator '.' force_method_calls 'false' DEBUG : 15-03-24 09:12:27:663 +0400 : Acs::Ldap::Mapper chain_method returns 'test' DEBUG : 15-03-24 09:12:27:663 +0400 : Acs::Ldap::Mapper chain_method on object '#' with chain 'get2' separator '.' force_method_calls 'false' DEBUG : 15-03-24 09:12:27:663 +0400 : Acs::Ldap::Mapper chain_method returns 'value2' DEBUG : 15-03-24 09:12:27:664 +0400 : Acs::Ldap::Mapper with options methods_separator '.' force_method_calls 'true' INFO : 15-03-24 09:12:27:664 +0400 : Delete all ou=people INFO : 15-03-24 09:12:27:664 +0400 : Search base 'ou=people,dc=adharacs,dc=lan' filter '' attributes 'uid' DEBUG : 15-03-24 09:12:27:664 +0400 : LDAP connect DEBUG : 15-03-24 09:12:27:664 +0400 : Binding to ldap... DEBUG : 15-03-24 09:12:27:664 +0400 : Connection params: {:host=>"192.168.59.103", :port=>49389, :base=>"dc=adharacs,dc=lan", :auth=>{:method=>:simple, :username=>"cn=admin,dc=adharacs,dc=lan", :password=>"admin"}} DEBUG : 15-03-24 09:12:27:670 +0400 : Connection succeed INFO : 15-03-24 09:12:27:684 +0400 : Search result SUCCESS return code:0, matched_dn: , message:Success, data:[#["ou=people,dc=adharacs,dc=lan"]}>] INFO : 15-03-24 09:12:27:684 +0400 : Search base 'ou=people,dc=adharacs,dc=lan' filter '' attributes '' INFO : 15-03-24 09:12:27:690 +0400 : Search result SUCCESS return code:0, matched_dn: , message:Success, data:[#["ou=people,dc=adharacs,dc=lan"], :objectclass=>["organizationalUnit", "top"], :ou=>["people"]}>] DEBUG : 15-03-24 09:12:27:690 +0400 : Acs::Ldap::Mapper with options methods_separator '.' force_method_calls 'true' DEBUG : 15-03-24 09:12:27:691 +0400 : Acs::Ldap::Mapper chain_method on object '{:id=>2, :sn=>"dark.vador", :cn=>"dark.vador", :givenName=>"Dark Vador", :userPassword=>"{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1", :mail=>"dvador@adharacs.lan"}' with chain 'id' separator '.' force_method_calls 'true' DEBUG : 15-03-24 09:12:27:691 +0400 : Acs::Ldap::Mapper chain_method returns '2' DEBUG : 15-03-24 09:12:27:691 +0400 : Acs::Ldap::Mapper chain_method on object '{:id=>2, :sn=>"dark.vador", :cn=>"dark.vador", :givenName=>"Dark Vador", :userPassword=>"{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1", :mail=>"dvador@adharacs.lan"}' with chain 'sn' separator '.' force_method_calls 'true' DEBUG : 15-03-24 09:12:27:691 +0400 : Acs::Ldap::Mapper chain_method returns 'dark.vador' DEBUG : 15-03-24 09:12:27:691 +0400 : Acs::Ldap::Mapper chain_method on object '{:id=>2, :sn=>"dark.vador", :cn=>"dark.vador", :givenName=>"Dark Vador", :userPassword=>"{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1", :mail=>"dvador@adharacs.lan"}' with chain 'cn' separator '.' force_method_calls 'true' DEBUG : 15-03-24 09:12:27:691 +0400 : Acs::Ldap::Mapper chain_method returns 'dark.vador' DEBUG : 15-03-24 09:12:27:691 +0400 : Acs::Ldap::Mapper chain_method on object '{:id=>2, :sn=>"dark.vador", :cn=>"dark.vador", :givenName=>"Dark Vador", :userPassword=>"{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1", :mail=>"dvador@adharacs.lan"}' with chain 'givenName' separator '.' force_method_calls 'true' DEBUG : 15-03-24 09:12:27:691 +0400 : Acs::Ldap::Mapper chain_method returns 'Dark Vador' DEBUG : 15-03-24 09:12:27:692 +0400 : Acs::Ldap::Mapper chain_method on object '{:id=>2, :sn=>"dark.vador", :cn=>"dark.vador", :givenName=>"Dark Vador", :userPassword=>"{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1", :mail=>"dvador@adharacs.lan"}' with chain 'mail' separator '.' force_method_calls 'true' DEBUG : 15-03-24 09:12:27:692 +0400 : Acs::Ldap::Mapper chain_method returns 'dvador@adharacs.lan' DEBUG : 15-03-24 09:12:27:692 +0400 : Acs::Ldap::Mapper chain_method on object '{:id=>2, :sn=>"dark.vador", :cn=>"dark.vador", :givenName=>"Dark Vador", :userPassword=>"{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1", :mail=>"dvador@adharacs.lan"}' with chain 'userPassword' separator '.' force_method_calls 'true' DEBUG : 15-03-24 09:12:27:692 +0400 : Acs::Ldap::Mapper chain_method returns '{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1' DEBUG : 15-03-24 09:12:27:692 +0400 : Acs::Ldap::Mapper chain_method on object '{:id=>2, :sn=>"dark.vador", :cn=>"dark.vador", :givenName=>"Dark Vador", :userPassword=>"{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1", :mail=>"dvador@adharacs.lan"}' with chain 'id' separator '.' force_method_calls 'true' DEBUG : 15-03-24 09:12:27:692 +0400 : Acs::Ldap::Mapper chain_method returns '2' DEBUG : 15-03-24 09:12:27:692 +0400 : Pusher#create dn 'uid=2,ou=people,dc=adharacs,dc=lan' attributes '{:sn=>"dark.vador", :cn=>"dark.vador", :givenName=>"Dark Vador", :mail=>"dvador@adharacs.lan", :userPassword=>"{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1", :objectClass=>["organizationalPerson", "person", "top", "extensibleObject"]}' DEBUG : 15-03-24 09:12:27:692 +0400 : Acs::Ldap::Mapper chain_method on object '{:id=>2, :sn=>"dark.vador", :cn=>"dark.vador", :givenName=>"Dark Vador", :userPassword=>"{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1", :mail=>"dvador@adharacs.lan"}' with chain 'id' separator '.' force_method_calls 'true' DEBUG : 15-03-24 09:12:27:692 +0400 : Acs::Ldap::Mapper chain_method returns '2' INFO : 15-03-24 09:12:27:693 +0400 : Add dn 'uid=2,ou=people,dc=adharacs,dc=lan' attributes '{:sn=>"dark.vador", :cn=>"dark.vador", :givenName=>"Dark Vador", :mail=>"dvador@adharacs.lan", :userPassword=>"{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1", :objectClass=>["organizationalPerson", "person", "top", "extensibleObject"]}' DEBUG : 15-03-24 09:12:27:693 +0400 : LDAP connect DEBUG : 15-03-24 09:12:27:693 +0400 : Binding to ldap... DEBUG : 15-03-24 09:12:27:693 +0400 : Connection params: {:host=>"192.168.59.103", :port=>49389, :base=>"dc=adharacs,dc=lan", :auth=>{:method=>:simple, :username=>"cn=admin,dc=adharacs,dc=lan", :password=>"admin"}} DEBUG : 15-03-24 09:12:27:718 +0400 : Connection succeed INFO : 15-03-24 09:12:27:736 +0400 : Add result SUCCESS return code:0, matched_dn: , message:Success, data:nil INFO : 15-03-24 09:12:27:736 +0400 : Search base 'ou=people,dc=adharacs,dc=lan' filter '' attributes '' INFO : 15-03-24 09:12:27:749 +0400 : Search result SUCCESS return code:0, matched_dn: , message:Success, data:[#["ou=people,dc=adharacs,dc=lan"], :objectclass=>["organizationalUnit", "top"], :ou=>["people"]}>, #["uid=2,ou=people,dc=adharacs,dc=lan"], :sn=>["dark.vador"], :cn=>["dark.vador"], :givenname=>["Dark Vador"], :mail=>["dvador@adharacs.lan"], :userpassword=>["{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1"], :objectclass=>["organizationalPerson", "person", "top", "extensibleObject"], :uid=>["2"]}>] DEBUG : 15-03-24 09:12:27:749 +0400 : Acs::Ldap::Mapper with options methods_separator '.' force_method_calls 'true' DEBUG : 15-03-24 09:12:27:750 +0400 : Acs::Ldap::Mapper chain_method on object '{:id=>2, :sn=>"dark.vador", :cn=>"dark.vador", :givenName=>"Vador Dark", :userPassword=>"{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1", :mail=>"dvador@adharacs.lan"}' with chain 'id' separator '.' force_method_calls 'true' DEBUG : 15-03-24 09:12:27:750 +0400 : Acs::Ldap::Mapper chain_method returns '2' DEBUG : 15-03-24 09:12:27:750 +0400 : Acs::Ldap::Mapper chain_method on object '{:id=>2, :sn=>"dark.vador", :cn=>"dark.vador", :givenName=>"Vador Dark", :userPassword=>"{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1", :mail=>"dvador@adharacs.lan"}' with chain 'sn' separator '.' force_method_calls 'true' DEBUG : 15-03-24 09:12:27:750 +0400 : Acs::Ldap::Mapper chain_method returns 'dark.vador' DEBUG : 15-03-24 09:12:27:750 +0400 : Acs::Ldap::Mapper chain_method on object '{:id=>2, :sn=>"dark.vador", :cn=>"dark.vador", :givenName=>"Vador Dark", :userPassword=>"{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1", :mail=>"dvador@adharacs.lan"}' with chain 'cn' separator '.' force_method_calls 'true' DEBUG : 15-03-24 09:12:27:750 +0400 : Acs::Ldap::Mapper chain_method returns 'dark.vador' DEBUG : 15-03-24 09:12:27:750 +0400 : Acs::Ldap::Mapper chain_method on object '{:id=>2, :sn=>"dark.vador", :cn=>"dark.vador", :givenName=>"Vador Dark", :userPassword=>"{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1", :mail=>"dvador@adharacs.lan"}' with chain 'givenName' separator '.' force_method_calls 'true' DEBUG : 15-03-24 09:12:27:750 +0400 : Acs::Ldap::Mapper chain_method returns 'Vador Dark' DEBUG : 15-03-24 09:12:27:750 +0400 : Acs::Ldap::Mapper chain_method on object '{:id=>2, :sn=>"dark.vador", :cn=>"dark.vador", :givenName=>"Vador Dark", :userPassword=>"{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1", :mail=>"dvador@adharacs.lan"}' with chain 'mail' separator '.' force_method_calls 'true' DEBUG : 15-03-24 09:12:27:750 +0400 : Acs::Ldap::Mapper chain_method returns 'dvador@adharacs.lan' DEBUG : 15-03-24 09:12:27:751 +0400 : Acs::Ldap::Mapper chain_method on object '{:id=>2, :sn=>"dark.vador", :cn=>"dark.vador", :givenName=>"Vador Dark", :userPassword=>"{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1", :mail=>"dvador@adharacs.lan"}' with chain 'userPassword' separator '.' force_method_calls 'true' DEBUG : 15-03-24 09:12:27:751 +0400 : Acs::Ldap::Mapper chain_method returns '{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1' DEBUG : 15-03-24 09:12:27:751 +0400 : Acs::Ldap::Mapper chain_method on object '{:id=>2, :sn=>"dark.vador", :cn=>"dark.vador", :givenName=>"Vador Dark", :userPassword=>"{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1", :mail=>"dvador@adharacs.lan"}' with chain 'id' separator '.' force_method_calls 'true' DEBUG : 15-03-24 09:12:27:751 +0400 : Acs::Ldap::Mapper chain_method returns '2' INFO : 15-03-24 09:12:27:751 +0400 : Modify dn 'uid=2,ou=people,dc=adharacs,dc=lan' operations '[[:replace, "sn", "dark.vador"], [:replace, "cn", "dark.vador"], [:replace, "givenName", "Vador Dark"], [:replace, "mail", "dvador@adharacs.lan"], [:replace, "userPassword", "{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1"]]' DEBUG : 15-03-24 09:12:27:751 +0400 : LDAP connect DEBUG : 15-03-24 09:12:27:751 +0400 : Binding to ldap... DEBUG : 15-03-24 09:12:27:751 +0400 : Connection params: {:host=>"192.168.59.103", :port=>49389, :base=>"dc=adharacs,dc=lan", :auth=>{:method=>:simple, :username=>"cn=admin,dc=adharacs,dc=lan", :password=>"admin"}} DEBUG : 15-03-24 09:12:27:764 +0400 : Connection succeed INFO : 15-03-24 09:12:27:772 +0400 : Modify result SUCCESS return code:0, matched_dn: , message:Success, data:nil INFO : 15-03-24 09:12:27:772 +0400 : Search base 'ou=people,dc=adharacs,dc=lan' filter '(uid=2)' attributes '' INFO : 15-03-24 09:12:27:784 +0400 : Search result SUCCESS return code:0, matched_dn: , message:Success, data:[#["uid=2,ou=people,dc=adharacs,dc=lan"], :objectclass=>["organizationalPerson", "person", "top", "extensibleObject"], :uid=>["2"], :sn=>["dark.vador"], :cn=>["dark.vador"], :givenname=>["Vador Dark"], :mail=>["dvador@adharacs.lan"], :userpassword=>["{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1"]}>] DEBUG : 15-03-24 09:12:27:785 +0400 : Acs::Ldap::Mapper with options methods_separator '.' force_method_calls 'true' DEBUG : 15-03-24 09:12:27:786 +0400 : Acs::Ldap::Mapper chain_method on object '{:id=>2, :sn=>"dark.vador", :cn=>"dark.vador", :givenName=>"D V", :userPassword=>"{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1", :mail=>"dv@adharacs.lan"}' with chain 'id' separator '.' force_method_calls 'true' DEBUG : 15-03-24 09:12:27:786 +0400 : Acs::Ldap::Mapper chain_method returns '2' DEBUG : 15-03-24 09:12:27:786 +0400 : Acs::Ldap::Mapper chain_method on object '{:id=>2, :sn=>"dark.vador", :cn=>"dark.vador", :givenName=>"D V", :userPassword=>"{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1", :mail=>"dv@adharacs.lan"}' with chain 'sn' separator '.' force_method_calls 'true' DEBUG : 15-03-24 09:12:27:786 +0400 : Acs::Ldap::Mapper chain_method returns 'dark.vador' DEBUG : 15-03-24 09:12:27:786 +0400 : Acs::Ldap::Mapper chain_method on object '{:id=>2, :sn=>"dark.vador", :cn=>"dark.vador", :givenName=>"D V", :userPassword=>"{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1", :mail=>"dv@adharacs.lan"}' with chain 'cn' separator '.' force_method_calls 'true' DEBUG : 15-03-24 09:12:27:786 +0400 : Acs::Ldap::Mapper chain_method returns 'dark.vador' DEBUG : 15-03-24 09:12:27:786 +0400 : Acs::Ldap::Mapper chain_method on object '{:id=>2, :sn=>"dark.vador", :cn=>"dark.vador", :givenName=>"D V", :userPassword=>"{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1", :mail=>"dv@adharacs.lan"}' with chain 'givenName' separator '.' force_method_calls 'true' DEBUG : 15-03-24 09:12:27:786 +0400 : Acs::Ldap::Mapper chain_method returns 'D V' DEBUG : 15-03-24 09:12:27:786 +0400 : Acs::Ldap::Mapper chain_method on object '{:id=>2, :sn=>"dark.vador", :cn=>"dark.vador", :givenName=>"D V", :userPassword=>"{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1", :mail=>"dv@adharacs.lan"}' with chain 'mail' separator '.' force_method_calls 'true' DEBUG : 15-03-24 09:12:27:786 +0400 : Acs::Ldap::Mapper chain_method returns 'dv@adharacs.lan' DEBUG : 15-03-24 09:12:27:786 +0400 : Acs::Ldap::Mapper chain_method on object '{:id=>2, :sn=>"dark.vador", :cn=>"dark.vador", :givenName=>"D V", :userPassword=>"{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1", :mail=>"dv@adharacs.lan"}' with chain 'userPassword' separator '.' force_method_calls 'true' DEBUG : 15-03-24 09:12:27:786 +0400 : Acs::Ldap::Mapper chain_method returns '{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1' DEBUG : 15-03-24 09:12:27:786 +0400 : Acs::Ldap::Mapper chain_method on object '{:id=>2, :sn=>"dark.vador", :cn=>"dark.vador", :givenName=>"D V", :userPassword=>"{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1", :mail=>"dv@adharacs.lan"}' with chain 'id' separator '.' force_method_calls 'true' DEBUG : 15-03-24 09:12:27:786 +0400 : Acs::Ldap::Mapper chain_method returns '2' INFO : 15-03-24 09:12:27:786 +0400 : Modify dn 'uid=2,ou=people,dc=adharacs,dc=lan' operations '[[:replace, "givenName", "D V"]]' DEBUG : 15-03-24 09:12:27:787 +0400 : LDAP connect DEBUG : 15-03-24 09:12:27:787 +0400 : Binding to ldap... DEBUG : 15-03-24 09:12:27:787 +0400 : Connection params: {:host=>"192.168.59.103", :port=>49389, :base=>"dc=adharacs,dc=lan", :auth=>{:method=>:simple, :username=>"cn=admin,dc=adharacs,dc=lan", :password=>"admin"}} DEBUG : 15-03-24 09:12:27:790 +0400 : Connection succeed INFO : 15-03-24 09:12:27:800 +0400 : Modify result SUCCESS return code:0, matched_dn: , message:Success, data:nil INFO : 15-03-24 09:12:27:800 +0400 : Search base 'ou=people,dc=adharacs,dc=lan' filter '(uid=2)' attributes '' INFO : 15-03-24 09:12:27:815 +0400 : Search result SUCCESS return code:0, matched_dn: , message:Success, data:[#["uid=2,ou=people,dc=adharacs,dc=lan"], :objectclass=>["organizationalPerson", "person", "top", "extensibleObject"], :uid=>["2"], :sn=>["dark.vador"], :cn=>["dark.vador"], :mail=>["dvador@adharacs.lan"], :userpassword=>["{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1"], :givenname=>["D V"]}>] INFO : 15-03-24 09:12:27:815 +0400 : Search base 'ou=people,dc=adharacs,dc=lan' filter '(uid=2)' attributes '' INFO : 15-03-24 09:12:27:831 +0400 : Search result SUCCESS return code:0, matched_dn: , message:Success, data:[#["uid=2,ou=people,dc=adharacs,dc=lan"], :objectclass=>["organizationalPerson", "person", "top", "extensibleObject"], :uid=>["2"], :sn=>["dark.vador"], :cn=>["dark.vador"], :mail=>["dvador@adharacs.lan"], :userpassword=>["{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1"], :givenname=>["D V"]}>] DEBUG : 15-03-24 09:12:27:832 +0400 : Acs::Ldap::Mapper with options methods_separator '.' force_method_calls 'true' DEBUG : 15-03-24 09:12:27:832 +0400 : Acs::Ldap::Mapper chain_method on object '{:id=>2, :sn=>"dark.vador", :cn=>"dark.vador", :givenName=>"Dark Vador", :userPassword=>"{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1", :mail=>"dvador@adharacs.lan"}' with chain 'id' separator '.' force_method_calls 'true' DEBUG : 15-03-24 09:12:27:832 +0400 : Acs::Ldap::Mapper chain_method returns '2' INFO : 15-03-24 09:12:27:832 +0400 : Delete dn 'uid=2,ou=people,dc=adharacs,dc=lan' DEBUG : 15-03-24 09:12:27:832 +0400 : LDAP connect DEBUG : 15-03-24 09:12:27:832 +0400 : Binding to ldap... DEBUG : 15-03-24 09:12:27:832 +0400 : Connection params: {:host=>"192.168.59.103", :port=>49389, :base=>"dc=adharacs,dc=lan", :auth=>{:method=>:simple, :username=>"cn=admin,dc=adharacs,dc=lan", :password=>"admin"}} DEBUG : 15-03-24 09:12:27:835 +0400 : Connection succeed INFO : 15-03-24 09:12:27:851 +0400 : Delete result SUCCESS return code:0, matched_dn: , message:Success, data:nil INFO : 15-03-24 09:12:27:851 +0400 : Search base 'ou=people,dc=adharacs,dc=lan' filter '' attributes '' INFO : 15-03-24 09:12:27:867 +0400 : Search result SUCCESS return code:0, matched_dn: , message:Success, data:[#["ou=people,dc=adharacs,dc=lan"], :objectclass=>["organizationalUnit", "top"], :ou=>["people"]}>] DEBUG : 15-03-24 09:12:27:869 +0400 : Acs::Ldap::Mapper with options methods_separator '.' force_method_calls 'true' INFO : 15-03-24 09:12:27:869 +0400 : Delete all ou=people INFO : 15-03-24 09:12:27:869 +0400 : Search base 'ou=people,dc=adharacs,dc=lan' filter '' attributes 'uid' DEBUG : 15-03-24 09:12:27:869 +0400 : LDAP connect DEBUG : 15-03-24 09:12:27:869 +0400 : Binding to ldap... DEBUG : 15-03-24 09:12:27:869 +0400 : Connection params: {:host=>"192.168.59.103", :port=>49389, :base=>"dc=adharacs,dc=lan", :auth=>{:method=>:simple, :username=>"cn=admin,dc=adharacs,dc=lan", :password=>"admin"}} DEBUG : 15-03-24 09:12:27:884 +0400 : Connection succeed INFO : 15-03-24 09:12:27:902 +0400 : Search result SUCCESS return code:0, matched_dn: , message:Success, data:[#["ou=people,dc=adharacs,dc=lan"]}>] DEBUG : 15-03-24 09:12:27:902 +0400 : Acs::Ldap::Mapper chain_method on object '{:id=>2, :sn=>"dark.vador", :cn=>"dark.vador", :givenName=>"Dark Vador", :userPassword=>"{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1", :mail=>"dvador@adharacs.lan"}' with chain 'id' separator '.' force_method_calls 'true' DEBUG : 15-03-24 09:12:27:902 +0400 : Acs::Ldap::Mapper chain_method returns '2' INFO : 15-03-24 09:12:27:903 +0400 : Search base 'uid=2,ou=people,dc=adharacs,dc=lan' filter '' attributes '' INFO : 15-03-24 09:12:27:908 +0400 : Search result ERROR return code:32, matched_dn: ou=people,dc=adharacs,dc=lan, message:No Such Object, data:[] DEBUG : 15-03-24 09:12:27:908 +0400 : Acs::Ldap::Mapper chain_method on object '{:id=>2, :sn=>"dark.vador", :cn=>"dark.vador", :givenName=>"Dark Vador", :userPassword=>"{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1", :mail=>"dvador@adharacs.lan"}' with chain 'id' separator '.' force_method_calls 'true' DEBUG : 15-03-24 09:12:27:910 +0400 : Acs::Ldap::Mapper chain_method returns '2' DEBUG : 15-03-24 09:12:27:910 +0400 : Acs::Ldap::Mapper chain_method on object '{:id=>2, :sn=>"dark.vador", :cn=>"dark.vador", :givenName=>"Dark Vador", :userPassword=>"{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1", :mail=>"dvador@adharacs.lan"}' with chain 'sn' separator '.' force_method_calls 'true' DEBUG : 15-03-24 09:12:27:910 +0400 : Acs::Ldap::Mapper chain_method returns 'dark.vador' DEBUG : 15-03-24 09:12:27:910 +0400 : Acs::Ldap::Mapper chain_method on object '{:id=>2, :sn=>"dark.vador", :cn=>"dark.vador", :givenName=>"Dark Vador", :userPassword=>"{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1", :mail=>"dvador@adharacs.lan"}' with chain 'cn' separator '.' force_method_calls 'true' DEBUG : 15-03-24 09:12:27:910 +0400 : Acs::Ldap::Mapper chain_method returns 'dark.vador' DEBUG : 15-03-24 09:12:27:910 +0400 : Acs::Ldap::Mapper chain_method on object '{:id=>2, :sn=>"dark.vador", :cn=>"dark.vador", :givenName=>"Dark Vador", :userPassword=>"{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1", :mail=>"dvador@adharacs.lan"}' with chain 'givenName' separator '.' force_method_calls 'true' DEBUG : 15-03-24 09:12:27:910 +0400 : Acs::Ldap::Mapper chain_method returns 'Dark Vador' DEBUG : 15-03-24 09:12:27:911 +0400 : Acs::Ldap::Mapper chain_method on object '{:id=>2, :sn=>"dark.vador", :cn=>"dark.vador", :givenName=>"Dark Vador", :userPassword=>"{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1", :mail=>"dvador@adharacs.lan"}' with chain 'mail' separator '.' force_method_calls 'true' DEBUG : 15-03-24 09:12:27:911 +0400 : Acs::Ldap::Mapper chain_method returns 'dvador@adharacs.lan' DEBUG : 15-03-24 09:12:27:911 +0400 : Acs::Ldap::Mapper chain_method on object '{:id=>2, :sn=>"dark.vador", :cn=>"dark.vador", :givenName=>"Dark Vador", :userPassword=>"{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1", :mail=>"dvador@adharacs.lan"}' with chain 'userPassword' separator '.' force_method_calls 'true' DEBUG : 15-03-24 09:12:27:911 +0400 : Acs::Ldap::Mapper chain_method returns '{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1' DEBUG : 15-03-24 09:12:27:911 +0400 : Acs::Ldap::Mapper chain_method on object '{:id=>2, :sn=>"dark.vador", :cn=>"dark.vador", :givenName=>"Dark Vador", :userPassword=>"{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1", :mail=>"dvador@adharacs.lan"}' with chain 'id' separator '.' force_method_calls 'true' DEBUG : 15-03-24 09:12:27:911 +0400 : Acs::Ldap::Mapper chain_method returns '2' DEBUG : 15-03-24 09:12:27:911 +0400 : Pusher#create dn 'uid=2,ou=people,dc=adharacs,dc=lan' attributes '{:sn=>"dark.vador", :cn=>"dark.vador", :givenName=>"Dark Vador", :mail=>"dvador@adharacs.lan", :userPassword=>"{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1", :objectClass=>["organizationalPerson", "person", "top", "extensibleObject"]}' DEBUG : 15-03-24 09:12:27:912 +0400 : Acs::Ldap::Mapper chain_method on object '{:id=>2, :sn=>"dark.vador", :cn=>"dark.vador", :givenName=>"Dark Vador", :userPassword=>"{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1", :mail=>"dvador@adharacs.lan"}' with chain 'id' separator '.' force_method_calls 'true' DEBUG : 15-03-24 09:12:27:912 +0400 : Acs::Ldap::Mapper chain_method returns '2' INFO : 15-03-24 09:12:27:912 +0400 : Add dn 'uid=2,ou=people,dc=adharacs,dc=lan' attributes '{:sn=>"dark.vador", :cn=>"dark.vador", :givenName=>"Dark Vador", :mail=>"dvador@adharacs.lan", :userPassword=>"{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1", :objectClass=>["organizationalPerson", "person", "top", "extensibleObject"]}' INFO : 15-03-24 09:12:27:928 +0400 : Add result SUCCESS return code:0, matched_dn: , message:Success, data:nil DEBUG : 15-03-24 09:12:27:928 +0400 : Acs::Ldap::Mapper chain_method on object '{:id=>2, :sn=>"dark.vador", :cn=>"dark.vador", :givenName=>"Dark Vador", :userPassword=>"{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1", :mail=>"dvador@adharacs.lan"}' with chain 'id' separator '.' force_method_calls 'true' DEBUG : 15-03-24 09:12:27:928 +0400 : Acs::Ldap::Mapper chain_method returns '2' INFO : 15-03-24 09:12:27:928 +0400 : Search base 'uid=2,ou=people,dc=adharacs,dc=lan' filter '' attributes '' INFO : 15-03-24 09:12:27:941 +0400 : Search result SUCCESS return code:0, matched_dn: , message:Success, data:[#["uid=2,ou=people,dc=adharacs,dc=lan"], :sn=>["dark.vador"], :cn=>["dark.vador"], :givenname=>["Dark Vador"], :mail=>["dvador@adharacs.lan"], :userpassword=>["{SSHA}+MBMtUqzkOeH8hI1KVnl+djdqzw0YmU5M2Y5MmQyOTgxMDU1"], :objectclass=>["organizationalPerson", "person", "top", "extensibleObject"], :uid=>["2"]}>] INFO : 15-03-24 09:12:27:941 +0400 : Delete all ou=people INFO : 15-03-24 09:12:27:941 +0400 : Search base 'ou=people,dc=adharacs,dc=lan' filter '' attributes 'uid' INFO : 15-03-24 09:12:27:948 +0400 : Search result SUCCESS return code:0, matched_dn: , message:Success, data:[#["ou=people,dc=adharacs,dc=lan"]}>, #["uid=2,ou=people,dc=adharacs,dc=lan"], :uid=>["2"]}>] INFO : 15-03-24 09:12:27:948 +0400 : Delete dn 'uid=2,ou=people,dc=adharacs,dc=lan' INFO : 15-03-24 09:12:27:956 +0400 : Delete result SUCCESS return code:0, matched_dn: , message:Success, data:nil