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