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