<div dir="ltr">Yes ldapsearch is working perfectly ..</div><div class="gmail_extra"><br><br><div class="gmail_quote">On Sun, Oct 13, 2013 at 9:58 PM, Patrick Ben Koetter via amavis-users <span dir="ltr"><<a href="mailto:amavis-users@amavis.org" target="_blank">amavis-users@amavis.org</a>></span> wrote:<br>
<blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex">amavis loads the LDAP driver, but fails to connect. Have you tried to make the<br>
same connect and query on command line using the ldapsearch command?<br>
<br>
p@rick<br>
<br>
<br>
<br>
<br>
* Joy via amavis-users <<a href="mailto:pj.netfilter@gmail.com">pj.netfilter@gmail.com</a>>:<br>
<div class="HOEnZb"><div class="h5">> Dear Expert,<br>
> I am trying to setup amavisd-new against ldap on local<br>
> system. my ldap is working perfectly against all module like smtp, imap and<br>
> pop3 but when configuring amavisd-new to use ldap getting the following<br>
> error in mail delivery:-<br>
><br>
> Please help me to resolve the issue ...<br>
><br>
> Oct 13 21:08:21.930 <a href="http://mx1.silvereye.co" target="_blank">mx1.silvereye.co</a> /usr/sbin/amavisd-new[4014]:<br>
> (04014-01) ESMTP< RCPT TO:<<a href="mailto:postmaster@springbirds.co.in">postmaster@springbirds.co.in</a>> ORCPT=<br>
> <a href="mailto:rfc822%3Bpostmaster@springbirds.co.in">rfc822;postmaster@springbirds.co.in</a>\r\n<br>
> Oct 13 21:08:21.930 <a href="http://mx1.silvereye.co" target="_blank">mx1.silvereye.co</a> /usr/sbin/amavisd-new[4014]:<br>
> (04014-01) get_deadline switch_to_my_time(rx SMTP RCPT) - deadline in 480.0<br>
> s, set to 336.000 s<br>
> Oct 13 21:08:21.930 <a href="http://mx1.silvereye.co" target="_blank">mx1.silvereye.co</a> /usr/sbin/amavisd-new[4014]:<br>
> (04014-01) prolong_timer switch_to_my_time(rx SMTP RCPT): timer 336, was<br>
> 480, deadline in 480.0 s<br>
> Oct 13 21:08:21.931 <a href="http://mx1.silvereye.co" target="_blank">mx1.silvereye.co</a> /usr/sbin/amavisd-new[4014]:<br>
> (04014-01) lookup => undef, "<a href="mailto:postmaster@springbirds.co.in">postmaster@springbirds.co.in</a>", no lookup tables<br>
> Oct 13 21:08:21.931 <a href="http://mx1.silvereye.co" target="_blank">mx1.silvereye.co</a> /usr/sbin/amavisd-new[4014]:<br>
> (04014-01) lookup_acl(<a href="mailto:postmaster@springbirds.co.in">postmaster@springbirds.co.in</a>) matches key "<br>
> <a href="http://springbirds.co.in" target="_blank">springbirds.co.in</a>", result=1<br>
> Oct 13 21:08:21.932 <a href="http://mx1.silvereye.co" target="_blank">mx1.silvereye.co</a> /usr/sbin/amavisd-new[4014]:<br>
> (04014-01) lookup [local_domains] => true, "<a href="mailto:postmaster@springbirds.co.in">postmaster@springbirds.co.in</a>"<br>
> matches, result="1", matching_key="<a href="http://springbirds.co.in" target="_blank">springbirds.co.in</a>"<br>
> Oct 13 21:08:21.932 <a href="http://mx1.silvereye.co" target="_blank">mx1.silvereye.co</a> /usr/sbin/amavisd-new[4014]:<br>
> (04014-01) query_keys: <a href="mailto:postmaster@springbirds.co.in">postmaster@springbirds.co.in</a>, postmaster, @<br>
> <a href="http://springbirds.co.in" target="_blank">springbirds.co.in</a>, @.<a href="http://springbirds.co.in" target="_blank">springbirds.co.in</a>, @.<a href="http://co.in" target="_blank">co.in</a>, @.in, @.<br>
> Oct 13 21:08:21.933 <a href="http://mx1.silvereye.co" target="_blank">mx1.silvereye.co</a> /usr/sbin/amavisd-new[4014]:<br>
> (04014-01) lookup_ldap "<a href="mailto:postmaster@springbirds.co.in">postmaster@springbirds.co.in</a>", query keys: "<br>
> <a href="mailto:postmaster@springbirds.co.in">postmaster@springbirds.co.in</a>", "postmaster", "@<a href="http://springbirds.co.in" target="_blank">springbirds.co.in</a>", "@.<br>
> <a href="http://springbirds.co.in" target="_blank">springbirds.co.in</a>", "@.<a href="http://co.in" target="_blank">co.in</a>", "@.in", "@.", base:<br>
> o=domains,dc=silvereye,dc=co, filter:<br>
> (&(objectClass=mailUser)(objectClass=amavisAccount)(accountStatus=active)(|(mail=%m)(shadowAddress=%m)))<br>
> Oct 13 21:08:21.933 <a href="http://mx1.silvereye.co" target="_blank">mx1.silvereye.co</a> /usr/sbin/amavisd-new[4014]:<br>
> (04014-01) ldap begin_work<br>
> Oct 13 21:08:21.933 <a href="http://mx1.silvereye.co" target="_blank">mx1.silvereye.co</a> /usr/sbin/amavisd-new[4014]:<br>
> (04014-01) Connecting to LDAP server<br>
> Oct 13 21:08:21.933 <a href="http://mx1.silvereye.co" target="_blank">mx1.silvereye.co</a> /usr/sbin/amavisd-new[4014]:<br>
> (04014-01) connect_to_ldap: trying 127.0.0.1<br>
> Oct 13 21:08:21.934 <a href="http://mx1.silvereye.co" target="_blank">mx1.silvereye.co</a> /usr/sbin/amavisd-new[4014]:<br>
> (04014-01) (!)connect_to_ldap: unable to connect to host 127.0.0.1<br>
> Oct 13 21:08:21.935 <a href="http://mx1.silvereye.co" target="_blank">mx1.silvereye.co</a> /usr/sbin/amavisd-new[4014]:<br>
> (04014-01) (!!)TROUBLE in process_request: connect_to_ldap: unable to<br>
> connect at (eval 116) line 145.<br>
> Oct 13 21:08:21.935 <a href="http://mx1.silvereye.co" target="_blank">mx1.silvereye.co</a> /usr/sbin/amavisd-new[4014]:<br>
> (04014-01) (!)Requesting process rundown after fatal error<br>
> Oct 13 21:08:21.935 <a href="http://mx1.silvereye.co" target="_blank">mx1.silvereye.co</a> /usr/sbin/amavisd-new[4014]:<br>
> (04014-01) ESMTP> 421 4.3.2 Service shutting down, closing channel<br>
> Oct 13 21:08:21.935 <a href="http://mx1.silvereye.co" target="_blank">mx1.silvereye.co</a> /usr/sbin/amavisd-new[4014]:<br>
> (04014-01) switch_to_client_time 480 s, smtp response sent<br>
> Oct 13 21:08:21.935 <a href="http://mx1.silvereye.co" target="_blank">mx1.silvereye.co</a> /usr/sbin/amavisd-new[4014]:<br>
> (04014-01) TempDir::DESTROY called<br>
> Oct 13 21:08:21.936 <a href="http://mx1.silvereye.co" target="_blank">mx1.silvereye.co</a> /usr/sbin/amavisd-new[4014]:<br>
> (04014-01) TempDir removal: empty tempdir is being removed:<br>
> /var/lib/amavis/tmp/amavis-20131013T210821-04014-BIHk8Dzm, nothing to<br>
> preserve<br>
> Oct 13 21:08:21.936 <a href="http://mx1.silvereye.co" target="_blank">mx1.silvereye.co</a> /usr/sbin/amavisd-new[4014]:<br>
> (04014-01) rmdir_recursively:<br>
> /var/lib/amavis/tmp/amavis-20131013T210821-04014-BIHk8Dzm, excl=<br>
> Oct 13 21:08:21.939 <a href="http://mx1.silvereye.co" target="_blank">mx1.silvereye.co</a> /usr/sbin/amavisd-new[4014]:<br>
> (04014-01) exiting process_request<br>
> Oct 13 21:08:21.939 <a href="http://mx1.silvereye.co" target="_blank">mx1.silvereye.co</a> /usr/sbin/amavisd-new[4014]:<br>
> (04014-01) idle_proc, bye: was busy, 9.6 ms, total idle 0.002 s, busy 0.028<br>
> s<br>
> Oct 13 21:08:21.940 <a href="http://mx1.silvereye.co" target="_blank">mx1.silvereye.co</a> /usr/sbin/amavisd-new[4014]:<br>
> (04014-01) load: 95 %, total idle 0.002 s, busy 0.028 s<br>
> Oct 13 21:08:21.940 <a href="http://mx1.silvereye.co" target="_blank">mx1.silvereye.co</a> /usr/sbin/amavisd-new[4014]:<br>
> (04014-01) SA rundown_child (0)<br>
> Oct 13 21:08:21.941 <a href="http://mx1.silvereye.co" target="_blank">mx1.silvereye.co</a> /usr/sbin/amavisd-new[4014]:<br>
> (04014-01) SpamControl: rundown_child on SpamAssassin done<br>
> Oct 13 21:08:21.941 <a href="http://mx1.silvereye.co" target="_blank">mx1.silvereye.co</a> /usr/sbin/amavisd-new[4014]:<br>
> (04014-01) child_finish_hook: invoking DESTROY methods<br>
> Oct 13 21:08:21.942 <a href="http://mx1.silvereye.co" target="_blank">mx1.silvereye.co</a> /usr/sbin/amavisd-new[4014]:<br>
> (04014-01) Amavis::Out::SQL::Log DESTROY called<br>
> Oct 13 21:08:21.942 <a href="http://mx1.silvereye.co" target="_blank">mx1.silvereye.co</a> /usr/sbin/amavisd-new[4014]:<br>
> (04014-01) Amavis::Out::SQL::Connection DESTROY called<br>
> Oct 13 21:08:21.942 <a href="http://mx1.silvereye.co" target="_blank">mx1.silvereye.co</a> /usr/sbin/amavisd-new[4014]:<br>
> (04014-01) Amavis::DB::SNMP DESTROY called<br>
> Oct 13 21:08:21.995 <a href="http://mx1.silvereye.co" target="_blank">mx1.silvereye.co</a> /usr/sbin/amavisd-new[4014]:<br>
> (04014-01) Net::Server: Child leaving (20)<br>
> Oct 13 21:08:22.001 <a href="http://mx1.silvereye.co" target="_blank">mx1.silvereye.co</a> /usr/sbin/amavisd-new[4014]:<br>
> (04014-01) Amavis::LDAP::Connection DESTROY called<br>
> Oct 13 21:08:22.001 <a href="http://mx1.silvereye.co" target="_blank">mx1.silvereye.co</a> /usr/sbin/amavisd-new[4014]:<br>
> (04014-01) Amavis::Lookup::LDAP DESTROY called<br>
> Oct 13 21:08:22.051 <a href="http://mx1.silvereye.co" target="_blank">mx1.silvereye.co</a> /usr/sbin/amavisd-new[4003]:<br>
> Net::Server: Starting "1" children<br>
> Oct 13 21:08:22.057 <a href="http://mx1.silvereye.co" target="_blank">mx1.silvereye.co</a> /usr/sbin/amavisd-new[4021]:<br>
> Net::Server: Child Preforked (4021)<br>
> Oct 13 21:08:22.058 <a href="http://mx1.silvereye.co" target="_blank">mx1.silvereye.co</a> /usr/sbin/amavisd-new[4021]: entered<br>
> child_init_hook<br>
> Oct 13 21:08:22.066 <a href="http://mx1.silvereye.co" target="_blank">mx1.silvereye.co</a> /usr/sbin/amavisd-new[4021]: only<br>
> storage connections to SQL, no lookups<br>
> Oct 13 21:08:22.067 <a href="http://mx1.silvereye.co" target="_blank">mx1.silvereye.co</a> /usr/sbin/amavisd-new[4021]:<br>
> SpamControl: init_child on SpamAssassin done<br>
><br>
> Here is the Startup details of amavisd-new :-<br>
><br>
> Oct 13 21:11:43 mx1 amavis[4039]: starting. /usr/sbin/amavisd-new at<br>
> <a href="http://mx1.silvereye.co" target="_blank">mx1.silvereye.co</a> amavisd-new-2.7.1 (20120429), Unicode aware, LANG="en_IN"<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Net::Server: Group Not Defined.<br>
> Defaulting to EGID '111 111'<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Net::Server: User Not Defined.<br>
> Defaulting to EUID '107'<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Module Amavis::Conf 2.303<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Module Archive::Zip 1.30<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Module Authen::SASL 2.16<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Module BerkeleyDB 0.51<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Module Compress::Zlib 2.033<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Module Convert::TNEF 0.17<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Module Convert::UUlib 1.4<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Module Crypt::OpenSSL::RSA 0.28<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Module DBD::mysql 4.021<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Module DBI 1.628<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Module DB_File 1.821<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Module Digest::MD5 2.51<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Module Digest::SHA 5.61<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Module File::Temp 0.22<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Module IO::Socket::INET6 2.71<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Module MIME::Entity 5.503<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Module MIME::Parser 5.503<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Module MIME::Tools 5.503<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Module Mail::DKIM::Signer 0.4<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Module Mail::DKIM::Verifier 0.4<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Module Mail::Header 2.09<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Module Mail::Internet 2.09<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Module Mail::SPF v2.009<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Module Mail::SpamAssassin 3.003002<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Module Net::DNS 0.72<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Module Net::LDAP 0.57<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Module Net::Server 2.006<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Module NetAddr::IP 4.071<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Module Socket6 0.23<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Module Time::HiRes 1.972101<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Module URI 1.60<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Module Unix::Syslog 1.1<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Amavis::DB code loaded<br>
> Oct 13 21:11:43 mx1 amavis[4046]: SQL base code loaded<br>
> Oct 13 21:11:43 mx1 amavis[4046]: SQL::Log code loaded<br>
> Oct 13 21:11:43 mx1 amavis[4046]: SQL::Quarantine NOT loaded<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Lookup::SQL code NOT loaded<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Lookup::LDAP code loaded<br>
> Oct 13 21:11:43 mx1 amavis[4046]: AM.PDP-in proto code loaded<br>
> Oct 13 21:11:43 mx1 amavis[4046]: SMTP-in proto code loaded<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Courier proto code NOT loaded<br>
> Oct 13 21:11:43 mx1 amavis[4046]: SMTP-out proto code loaded<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Pipe-out proto code NOT loaded<br>
> Oct 13 21:11:43 mx1 amavis[4046]: BSMTP-out proto code NOT loaded<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Local-out proto code loaded<br>
> Oct 13 21:11:43 mx1 amavis[4046]: OS_Fingerprint code NOT loaded<br>
> Oct 13 21:11:43 mx1 amavis[4046]: ANTI-VIRUS code loaded<br>
> Oct 13 21:11:43 mx1 amavis[4046]: ANTI-SPAM code loaded<br>
> Oct 13 21:11:43 mx1 amavis[4046]: ANTI-SPAM-EXT code NOT loaded<br>
> Oct 13 21:11:43 mx1 amavis[4046]: ANTI-SPAM-C code NOT loaded<br>
> Oct 13 21:11:43 mx1 amavis[4046]: ANTI-SPAM-SA code loaded<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Unpackers code loaded<br>
> Oct 13 21:11:43 mx1 amavis[4046]: DKIM code loaded<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Tools code NOT loaded<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Found $file at /usr/bin/file<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Found $altermime at<br>
> /usr/bin/altermime<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Internal decoder for .mail<br>
> Oct 13 21:11:43 mx1 amavis[4046]: No decoder for .F<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Found decoder for .Z at<br>
> /bin/uncompress<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Internal decoder for .gz<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Found decoder for .bz2 at /bin/bzip2<br>
> -d<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Found decoder for .xz at /usr/bin/xz<br>
> -dc<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Found decoder for .lzma at /usr/bin/xz<br>
> -dc --format=lzma<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Found decoder for .lzo at<br>
> /usr/bin/lzop -d<br>
> Oct 13 21:11:43 mx1 amavis[4046]: No decoder for .rpm tried:<br>
> <a href="http://rpm2cpio.pl" target="_blank">rpm2cpio.pl</a>, rpm2cpio<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Found decoder for .cpio at /bin/pax<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Found decoder for .tar at /bin/pax<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Found decoder for .deb at /usr/bin/ar<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Internal decoder for .zip<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Internal decoder for .kmz<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Found decoder for .7z at /usr/bin/7zr<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Found decoder for .rar at<br>
> /usr/bin/unrar-free<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Found decoder for .arj at /usr/bin/arj<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Found decoder for .arc at<br>
> /usr/bin/nomarch<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Found decoder for .zoo at /usr/bin/zoo<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Found decoder for .doc at<br>
> /usr/bin/ripole<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Found decoder for .cab at<br>
> /usr/bin/cabextract<br>
> Oct 13 21:11:43 mx1 amavis[4046]: No decoder for .tnef<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Internal decoder for .tnef<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Found decoder for .exe at<br>
> /usr/bin/unrar-free; /usr/bin/arj<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Using primary internal av scanner code<br>
> for ClamAV-clamd<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Found secondary av scanner<br>
> ClamAV-clamscan at /usr/local/bin/clamscan<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Deleting db files<br>
> __db.003,__db.002,snmp.db,__db.004,__db.001,nanny.db in /var/lib/amavis/db<br>
> Oct 13 21:11:43 mx1 amavis[4046]: Creating db in /var/lib/amavis/db/;<br>
> BerkeleyDB 0.51, libdb 5.1<br>
<br>
</div></div><span class="HOEnZb"><font color="#888888">--<br>
[*] sys4 AG<br>
<br>
<a href="http://sys4.de" target="_blank">http://sys4.de</a>, +49 (89) 30 90 46 64<br>
Franziskanerstraße 15, 81669 München<br>
<br>
Sitz der Gesellschaft: München, Amtsgericht München: HRB 199263<br>
Vorstand: Patrick Ben Koetter, Axel von der Ohe, Marc Schiffbauer<br>
Aufsichtsratsvorsitzender: Florian Kirstein<br>
<br>
</font></span></blockquote></div><br></div>