Attaching debug here after few hours in debug mode:
# amavisd -u amavis -c /etc/amavisd/amavisd.conf debug
Feb 5 13:47:30.134 xx.xxxxx.com /usr/sbin/amavisd[4715]: logging initiali zed, log level 0, syslog: amavis.mail
Feb 5 13:47:30.139 xx.xxxxx.com /usr/sbin/amavisd[4715]: run_command: [47 16] /usr/bin/uptime </dev/null 2>/dev/null
Feb 5 13:47:30.150 xx.xxxxx.com /usr/sbin/amavisd[4715]: system uptime 1 15:50:00: 13:47:30 up 1 day, 15:50, 1 user, load average: 0.00, 0.01, 0.00
Feb 5 13:47:30.151 xx.xxxxx.com /usr/sbin/amavisd[4715]: Valid PID file ( younger than sys uptime 1 15:50:00)
Feb 5 13:47:30.151 xx.xxxxx.com /usr/sbin/amavisd[4715]: starting. /usr/s bin/amavisd at xx.xxxxx.com amavisd-new-2.9.1 (20140627), Unicode aware, L ANG="en_US.UTF-8"
Feb 5 13:47:30.152 xx.xxxxx.com /usr/sbin/amavisd[4715]: perl=5.016003, u ser=amavis, EUID: 995 (995); group=, EGID: 994 (994)
Feb 5 13:47:30.247 xx.xxxxx.com /usr/sbin/amavisd[4715]: INFO: no optiona l modules: unicore::lib::Nt::De.pl Unix::Getrusage
Feb 5 13:47:30.247 xx.xxxxx.com /usr/sbin/amavisd[4715]: SpamControl: att empting to load scanner SpamAssassin, module Amavis::SpamControl::SpamAssassin
Feb 5 13:47:30.247 xx.xxxxx.com /usr/sbin/amavisd[4715]: SpamControl: sca nner SpamAssassin, module Amavis::SpamControl::SpamAssassin
Feb 5 13:47:30.675 xx.xxxxx.com /usr/sbin/amavisd[4715]: INFO: SA version : 3.3.2, 3.003002, no optional modules: Net::CIDR::Lite Image::Info Image::Info: :GIF Image::Info::JPEG Image::Info::PNG Image::Info::BMP Image::Info::TIFF
Feb 5 13:47:30.675 xx.xxxxx.com /usr/sbin/amavisd[4715]: SpamControl: ini t_pre_chroot on SpamAssassin done
Feb 5 13:47:30.675 xx.xxxxx.com /usr/sbin/amavisd[4715]: socket module IO ::Socket::IP, protocol families available: INET, INET6
Feb 5 13:47:30.676 xx.xxxxx.com /usr/sbin/amavisd[4715]: bind to /var/spo ol/amavisd/amavisd.sock|unix, 127.0.0.1:10024/tcp, [::1]:10024/tcp, 127.0.0.1:99 98/tcp, [::1]:9998/tcp
Pid_file "/var/run/amavisd/amavisd.pid" already exists. Overwriting!
Feb 5 13:47:30.677 xx.xxxxx.com /usr/sbin/amavisd[4715]: Net::Server: 201 5/02/05-13:47:30 Amavis (type Net::Server::PreForkSimple) starting! pid(4715)
Feb 5 13:47:30.682 xx.xxxxx.com /usr/sbin/amavisd[4715]: Net::Server: Bin ding to UNIX socket file "/var/spool/amavisd/amavisd.sock"
Feb 5 13:47:30.682 xx.xxxxx.com /usr/sbin/amavisd[4715]: Net::Server: Bin ding to TCP port 10024 on host 127.0.0.1 with IPv4
Feb 5 13:47:30.683 xx.xxxxx.com /usr/sbin/amavisd[4715]: Net::Server: Bin ding to TCP port 10024 on host ::1 with IPv6
Feb 5 13:47:30.683 xx.xxxxx.com /usr/sbin/amavisd[4715]: Net::Server: Bin ding to TCP port 9998 on host 127.0.0.1 with IPv4
Feb 5 13:47:30.683 xx.xxxxx.com /usr/sbin/amavisd[4715]: Net::Server: Bin ding to TCP port 9998 on host ::1 with IPv6
Feb 5 13:47:30.684 xx.xxxxx.com /usr/sbin/amavisd[4715]: Net::Server: Gro up Not Defined. Defaulting to EGID '994'
Feb 5 13:47:30.684 xx.xxxxx.com /usr/sbin/amavisd[4715]: Net::Server: Use r Not Defined. Defaulting to EUID '995'
Feb 5 13:47:30.684 xx.xxxxx.com /usr/sbin/amavisd[4715]: Net::Server: Set ting up serialization via flock
Feb 5 13:47:30.685 xx.xxxxx.com /usr/sbin/amavisd[4715]: after_chroot_ini t: EUID: 995 (995); EGID: 994 (994)
Feb 5 13:47:30.685 xx.xxxxx.com /usr/sbin/amavisd[4715]: config files rea d: /etc/amavisd/amavisd.conf
Feb 5 13:47:30.710 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module Amavis::C onf 2.321
Feb 5 13:47:30.710 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module Archive:: Zip 1.30
Feb 5 13:47:30.710 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module BerkeleyD B 0.51
Feb 5 13:47:30.710 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module Compress: :Raw::Zlib 2.061
Feb 5 13:47:30.710 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module Compress: :Zlib 2.061
Feb 5 13:47:30.711 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module Crypt::Op enSSL::RSA 0.28
Feb 5 13:47:30.711 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module DBD::mysq l 4.023
Feb 5 13:47:30.711 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module DBI 1.627
Feb 5 13:47:30.711 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module DB_File 1.83
Feb 5 13:47:30.711 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module Digest::M D5 2.52
Feb 5 13:47:30.712 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module Digest::S HA 5.85
Feb 5 13:47:30.712 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module Encode 2.51
Feb 5 13:47:30.712 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module File::Tem p 0.2301
Feb 5 13:47:30.713 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module IO::Socke t::INET6 2.69
Feb 5 13:47:30.713 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module IO::Socke t::IP 0.21
Feb 5 13:47:30.713 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module MIME::Ent ity 5.505
Feb 5 13:47:30.713 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module MIME::Par ser 5.505
Feb 5 13:47:30.714 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module MIME::Too ls 5.505
Feb 5 13:47:30.714 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module Mail::DKI M::Signer 0.39
Feb 5 13:47:30.714 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module Mail::DKI M::Verifier 0.39
Feb 5 13:47:30.714 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module Mail::Hea der 2.12
Feb 5 13:47:30.714 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module Mail::Int ernet 2.12
Feb 5 13:47:30.714 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module Mail::SPF v2.008
Feb 5 13:47:30.714 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module Mail::Spa mAssassin 3.003002
Feb 5 13:47:30.715 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module Net::DNS 0.72
Feb 5 13:47:30.715 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module Net::Serv er 2.007
Feb 5 13:47:30.715 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module NetAddr:: IP 4.069
Feb 5 13:47:30.715 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module Razor2::C lient::Version 2.84
Feb 5 13:47:30.715 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module Scalar::U til 1.27
Feb 5 13:47:30.716 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module Socket 2.010
Feb 5 13:47:30.716 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module Socket6 0.23
Feb 5 13:47:30.716 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module Time::HiR es 1.9725
Feb 5 13:47:30.716 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module URI 1.60
Feb 5 13:47:30.716 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module Unix::Sys log 1.1
Feb 5 13:47:30.716 xx.xxxxx.com /usr/sbin/amavisd[4715]: Amavis::ZMQ code NOT loaded
Feb 5 13:47:30.716 xx.xxxxx.com /usr/sbin/amavisd[4715]: Amavis::DB code loaded
Feb 5 13:47:30.716 xx.xxxxx.com /usr/sbin/amavisd[4715]: SQL base code loaded
Feb 5 13:47:30.716 xx.xxxxx.com /usr/sbin/amavisd[4715]: SQL::Log code loaded
Feb 5 13:47:30.716 xx.xxxxx.com /usr/sbin/amavisd[4715]: SQL::Quarantine loaded
Feb 5 13:47:30.717 xx.xxxxx.com /usr/sbin/amavisd[4715]: Lookup::SQL code loaded
Feb 5 13:47:30.717 xx.xxxxx.com /usr/sbin/amavisd[4715]: Lookup::LDAP cod e NOT loaded
Feb 5 13:47:30.717 xx.xxxxx.com /usr/sbin/amavisd[4715]: AM.PDP-in proto code loaded
Feb 5 13:47:30.717 xx.xxxxx.com /usr/sbin/amavisd[4715]: SMTP-in proto co de loaded
Feb 5 13:47:30.717 xx.xxxxx.com /usr/sbin/amavisd[4715]: Courier proto co de NOT loaded
Feb 5 13:47:30.717 xx.xxxxx.com /usr/sbin/amavisd[4715]: SMTP-out proto c ode loaded
Feb 5 13:47:30.717 xx.xxxxx.com /usr/sbin/amavisd[4715]: Pipe-out proto c ode NOT loaded
Feb 5 13:47:30.717 xx.xxxxx.com /usr/sbin/amavisd[4715]: BSMTP-out proto code NOT loaded
Feb 5 13:47:30.717 xx.xxxxx.com /usr/sbin/amavisd[4715]: Local-out proto code NOT loaded
Feb 5 13:47:30.717 xx.xxxxx.com /usr/sbin/amavisd[4715]: OS_Fingerprint c ode NOT loaded
Feb 5 13:47:30.717 xx.xxxxx.com /usr/sbin/amavisd[4715]: ANTI-VIRUS code loaded
Feb 5 13:47:30.717 xx.xxxxx.com /usr/sbin/amavisd[4715]: ANTI-SPAM code loaded
Feb 5 13:47:30.717 xx.xxxxx.com /usr/sbin/amavisd[4715]: ANTI-SPAM-EXT co de NOT loaded
Feb 5 13:47:30.717 xx.xxxxx.com /usr/sbin/amavisd[4715]: ANTI-SPAM-C code NOT loaded
Feb 5 13:47:30.717 xx.xxxxx.com /usr/sbin/amavisd[4715]: ANTI-SPAM-SA cod e loaded
Feb 5 13:47:30.717 xx.xxxxx.com /usr/sbin/amavisd[4715]: Unpackers code loaded
Feb 5 13:47:30.717 xx.xxxxx.com /usr/sbin/amavisd[4715]: DKIM code loaded
Feb 5 13:47:30.717 xx.xxxxx.com /usr/sbin/amavisd[4715]: Tools code NOT loaded
Feb 5 13:47:30.718 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found $file at /usr/bin/file
Feb 5 13:47:30.718 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found $altermime at /usr/bin/altermime
Feb 5 13:47:30.718 xx.xxxxx.com /usr/sbin/amavisd[4715]: Internal decoder for .mail
Feb 5 13:47:30.718 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo r .F at /usr/bin/unfreeze
Feb 5 13:47:30.718 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo r .Z at /usr/bin/gzip -d
Feb 5 13:47:30.718 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo r .gz at /usr/bin/gzip -d
Feb 5 13:47:30.718 xx.xxxxx.com /usr/sbin/amavisd[4715]: Internal decoder for .gz (backup, not used)
Feb 5 13:47:30.718 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo r .bz2 at /usr/bin/bzip2 -d
Feb 5 13:47:30.718 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo r .xz at /usr/bin/xzdec
Feb 5 13:47:30.719 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo r .lzma at /usr/bin/xz -dc --format=lzma
Feb 5 13:47:30.719 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo r .lrz at /usr/bin/lrzip -q -k -d -o -
Feb 5 13:47:30.719 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo r .lzo at /usr/bin/lzop -d
Feb 5 13:47:30.719 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo r .rpm at /usr/bin/rpm2cpio
Feb 5 13:47:30.719 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo r .cpio at /usr/bin/cpio
Feb 5 13:47:30.719 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo r .tar at /usr/bin/cpio
Feb 5 13:47:30.719 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo r .deb at /usr/bin/ar
Feb 5 13:47:30.719 xx.xxxxx.com /usr/sbin/amavisd[4715]: No ext program f or .rar, tried: unrar, rar
Feb 5 13:47:30.719 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo r .arj at /usr/bin/unarj
Feb 5 13:47:30.719 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo r .arc at /usr/bin/nomarch
Feb 5 13:47:30.720 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo r .zoo at /usr/bin/unzoo
Feb 5 13:47:30.720 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo r .cab at /usr/bin/cabextract
Feb 5 13:47:30.720 xx.xxxxx.com /usr/sbin/amavisd[4715]: Internal decoder for .tnef
Feb 5 13:47:30.720 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo r .zip at /usr/bin/7za
Feb 5 13:47:30.720 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo r .kmz at /usr/bin/7za
Feb 5 13:47:30.720 xx.xxxxx.com /usr/sbin/amavisd[4715]: Internal decoder for .zip (backup, not used)
Feb 5 13:47:30.720 xx.xxxxx.com /usr/sbin/amavisd[4715]: Internal decoder for .kmz (backup, not used)
Feb 5 13:47:30.720 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo r .7z at /usr/bin/7za
Feb 5 13:47:30.720 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo r .7z at /usr/bin/7za (backup, not used)
Feb 5 13:47:30.720 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo r .zip at /usr/bin/7za (backup, not used)
Feb 5 13:47:30.720 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo r .gz at /usr/bin/7za (backup, not used)
Feb 5 13:47:30.720 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo r .bz2 at /usr/bin/7za (backup, not used)
Feb 5 13:47:30.720 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo r .Z at /usr/bin/7za (backup, not used)
Feb 5 13:47:30.720 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo r .tar at /usr/bin/7za (backup, not used)
Feb 5 13:47:30.720 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo r .xz at /usr/bin/7z (backup, not used)
Feb 5 13:47:30.721 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo r .lzma at /usr/bin/7z (backup, not used)
Feb 5 13:47:30.721 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo r .jar at /usr/bin/7z
Feb 5 13:47:30.721 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo r .cpio at /usr/bin/7z (backup, not used)
Feb 5 13:47:30.721 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo r .arj at /usr/bin/7z (backup, not used)
Feb 5 13:47:30.721 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo r .rar at /usr/bin/7z
Feb 5 13:47:30.721 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo r .swf at /usr/bin/7z
Feb 5 13:47:30.721 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo r .lha at /usr/bin/7z
Feb 5 13:47:30.721 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo r .iso at /usr/bin/7z
Feb 5 13:47:30.721 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo r .cab at /usr/bin/7z (backup, not used)
Feb 5 13:47:30.721 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo r .deb at /usr/bin/7z (backup, not used)
Feb 5 13:47:30.721 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo r .rpm at /usr/bin/7z (backup, not used)
Feb 5 13:47:30.721 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo r .exe at /usr/bin/unarj
Feb 5 13:47:30.721 xx.xxxxx.com /usr/sbin/amavisd[4715]: Using primary in ternal av scanner code for ClamAV-clamd
Feb 5 13:47:30.721 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found secondary av scanner ClamAV-clamscan at /usr/bin/clamscan
Feb 5 13:47:30.721 xx.xxxxx.com /usr/sbin/amavisd[4715]: Using internal s pam scanner code for SpamAssassin
Feb 5 13:47:30.722 xx.xxxxx.com /usr/sbin/amavisd[4715]: Deleting db file s snmp.db,nanny.db,__db.001,__db.003,__db.002 in /var/spool/amavisd/db
Feb 5 13:47:30.724 xx.xxxxx.com /usr/sbin/amavisd[4715]: Creating db in / var/spool/amavisd/db/; BerkeleyDB 0.51, libdb 5.3
Feb 5 13:47:30.811 xx.xxxxx.com /usr/sbin/amavisd[4715]: initializing Mai l::SpamAssassin (0)
Feb 5 13:47:30.811 xx.xxxxx.com /usr/sbin/amavisd[4715]: SpamAssassin deb ug facilities: info
Feb 5 13:48:02.251 xx.xxxxx.com /usr/sbin/amavisd[4715]: SpamAssassin loaded plugins: AutoLearnThreshold, Bayes, BodyE val, Check, DKIM, DNSEval, FreeMail, HTMLEval, HTTPSMismatch, Hashcash, HeaderEval, ImageInfo, MIMEEval, MIMEHeader, Pyzor, R azor2, RelayEval, ReplaceTags, SPF, SpamCop, URIDNSBL, URIDetail, URIEval, VBounce, WLBLEval, WhiteListSubject
Feb 5 13:48:02.251 xx.xxxxx.com /usr/sbin/amavisd[4715]: SpamControl: init_pre_fork on SpamAssassin done
Feb 5 13:48:02.251 xx.xxxxx.com /usr/sbin/amavisd[4715]: extra modules loaded after daemonizing/chrooting: /usr/share/ perl5/Net/libnet.cfg, Mail/SpamAssassin/Plugin/FreeMail.pm, Mail/SpamAssassin/Plugin/SpamCop.pm, Net/Cmd.pm, Net/Config.pm, N et/SMTP.pm
Feb 5 13:48:02.252 xx.xxxxx.com /usr/sbin/amavisd[4715]: Net::Server: Beginning prefork (4 processes)
Feb 5 13:48:02.253 xx.xxxxx.com /usr/sbin/amavisd[4715]: Net::Server: Starting "4" children
Feb 5 13:48:02.272 xx.xxxxx.com /usr/sbin/amavisd[4717]: Net::Server: Child Preforked (4717)
Feb 5 13:48:02.272 xx.xxxxx.com /usr/sbin/amavisd[4717]: entered child_init_hook
Feb 5 13:48:02.281 xx.xxxxx.com /usr/sbin/amavisd[4718]: Net::Server: Child Preforked (4718)
Feb 5 13:48:02.281 xx.xxxxx.com /usr/sbin/amavisd[4718]: entered child_init_hook
Feb 5 13:48:02.287 xx.xxxxx.com /usr/sbin/amavisd[4717]: storage and lookups will use the same connection to SQL
Feb 5 13:48:02.347 xx.xxxxx.com /usr/sbin/amavisd[4719]: Net::Server: Child Preforked (4719)
Feb 5 13:48:02.348 xx.xxxxx.com /usr/sbin/amavisd[4719]: entered child_init_hook
Feb 5 13:48:02.349 xx.xxxxx.com /usr/sbin/amavisd[4715]: Net::Server: Parent ready for children.
Feb 5 13:48:02.357 xx.xxxxx.com /usr/sbin/amavisd[4717]: SpamControl: init_child on SpamAssassin done
Feb 5 13:48:02.357 xx.xxxxx.com /usr/sbin/amavisd[4718]: storage and lookups will use the same connection to SQL
Feb 5 13:48:02.359 xx.xxxxx.com /usr/sbin/amavisd[4720]: Net::Server: Child Preforked (4720)
Feb 5 13:48:02.360 xx.xxxxx.com /usr/sbin/amavisd[4720]: entered child_init_hook
Feb 5 13:48:02.361 xx.xxxxx.com /usr/sbin/amavisd[4718]: SpamControl: init_child on SpamAssassin done
Feb 5 13:48:02.363 xx.xxxxx.com /usr/sbin/amavisd[4719]: storage and lookups will use the same connection to SQL
Feb 5 13:48:02.369 xx.xxxxx.com /usr/sbin/amavisd[4720]: storage and lookups will use the same connection to SQL
Feb 5 13:48:02.371 xx.xxxxx.com /usr/sbin/amavisd[4719]: SpamControl: init_child on SpamAssassin done
Feb 5 13:48:02.371 xx.xxxxx.com /usr/sbin/amavisd[4720]: SpamControl: init_child on SpamAssassin done
^CFeb 5 21:27:34.856 xx.xxxxx.com /usr/sbin/amavisd[4718]: child_goes_idle (child finishing)
Feb 5 21:27:34.857 xx.xxxxx.com /usr/sbin/amavisd[4719]: child_goes_idle (child finishing)
Feb 5 21:27:34.857 xx.xxxxx.com /usr/sbin/amavisd[4719]: child_goes_idle: disconnected none (child finishing)
Feb 5 21:27:34.857 xx.xxxxx.com /usr/sbin/amavisd[4719]: SA rundown_child (0)
Feb 5 21:27:34.859 xx.xxxxx.com /usr/sbin/amavisd[4717]: child_goes_idle (child finishing)
Feb 5 21:27:34.859 xx.xxxxx.com /usr/sbin/amavisd[4717]: child_goes_idle: disconnected none (child finishing)
Feb 5 21:27:34.860 xx.xxxxx.com /usr/sbin/amavisd[4717]: SA rundown_child (0)
Feb 5 21:27:34.860 xx.xxxxx.com /usr/sbin/amavisd[4720]: child_goes_idle (child finishing)
Feb 5 21:27:34.860 xx.xxxxx.com /usr/sbin/amavisd[4720]: child_goes_idle: disconnected none (child finishing)
Feb 5 21:27:34.861 xx.xxxxx.com /usr/sbin/amavisd[4720]: SA rundown_child (0)
Feb 5 21:27:34.861 xx.xxxxx.com /usr/sbin/amavisd[4718]: child_goes_idle: disconnected none (child finishing)
Feb 5 21:27:34.861 xx.xxxxx.com /usr/sbin/amavisd[4718]: SA rundown_child (0)
Feb 5 21:27:34.863 xx.xxxxx.com /usr/sbin/amavisd[4717]: SpamControl: rundown_child on SpamAssassin done
Feb 5 21:27:34.863 xx.xxxxx.com /usr/sbin/amavisd[4717]: child_finish_hook: invoking DESTROY methods
Feb 5 21:27:34.863 xx.xxxxx.com /usr/sbin/amavisd[4717]: Amavis::Out::SQL::Log DESTROY called
Feb 5 21:27:34.863 xx.xxxxx.com /usr/sbin/amavisd[4717]: Amavis::Lookup::SQL DESTROY called
Feb 5 21:27:34.863 xx.xxxxx.com /usr/sbin/amavisd[4717]: Amavis::Lookup::SQL DESTROY called
Feb 5 21:27:34.863 xx.xxxxx.com /usr/sbin/amavisd[4717]: Amavis::Out::SQL::Connection DESTROY called
Feb 5 21:27:34.865 xx.xxxxx.com /usr/sbin/amavisd[4720]: SpamControl: rundown_child on SpamAssassin done
Feb 5 21:27:34.865 xx.xxxxx.com /usr/sbin/amavisd[4720]: child_finish_hook: invoking DESTROY methods
Feb 5 21:27:34.865 xx.xxxxx.com /usr/sbin/amavisd[4720]: Amavis::Out::SQL::Log DESTROY called
Feb 5 21:27:34.865 xx.xxxxx.com /usr/sbin/amavisd[4720]: Amavis::Lookup::SQL DESTROY called
Feb 5 21:27:34.865 xx.xxxxx.com /usr/sbin/amavisd[4720]: Amavis::Lookup::SQL DESTROY called
Feb 5 21:27:34.865 xx.xxxxx.com /usr/sbin/amavisd[4720]: Amavis::Out::SQL::Connection DESTROY called
Feb 5 21:27:34.866 xx.xxxxx.com /usr/sbin/amavisd[4718]: SpamControl: rundown_child on SpamAssassin done
Feb 5 21:27:34.867 xx.xxxxx.com /usr/sbin/amavisd[4718]: child_finish_hook: invoking DESTROY methods
Feb 5 21:27:34.867 xx.xxxxx.com /usr/sbin/amavisd[4719]: SpamControl: rundown_child on SpamAssassin done
Feb 5 21:27:34.867 xx.xxxxx.com /usr/sbin/amavisd[4719]: child_finish_hook: invoking DESTROY methods
Feb 5 21:27:34.867 xx.xxxxx.com /usr/sbin/amavisd[4719]: Amavis::Out::SQL::Log DESTROY called
Feb 5 21:27:34.867 xx.xxxxx.com /usr/sbin/amavisd[4719]: Amavis::Lookup::SQL DESTROY called
Feb 5 21:27:34.868 xx.xxxxx.com /usr/sbin/amavisd[4719]: Amavis::Lookup::SQL DESTROY called
Feb 5 21:27:34.868 xx.xxxxx.com /usr/sbin/amavisd[4718]: Amavis::Out::SQL::Log DESTROY called
Feb 5 21:27:34.868 xx.xxxxx.com /usr/sbin/amavisd[4718]: Amavis::Lookup::SQL DESTROY called
Feb 5 21:27:34.868 xx.xxxxx.com /usr/sbin/amavisd[4718]: Amavis::Lookup::SQL DESTROY called
Feb 5 21:27:34.868 xx.xxxxx.com /usr/sbin/amavisd[4718]: Amavis::Out::SQL::Connection DESTROY called
Feb 5 21:27:34.869 xx.xxxxx.com /usr/sbin/amavisd[4719]: Amavis::Out::SQL::Connection DESTROY called
Feb 5 21:27:34.870 xx.xxxxx.com /usr/sbin/amavisd[4715]: Net::Server: 2015/02/05-21:27:34 Server closing!
Feb 5 21:27:34.870 xx.xxxxx.com /usr/sbin/amavisd[4715]: Net::Server: Kill TERM pid 4718
Feb 5 21:27:34.871 xx.xxxxx.com /usr/sbin/amavisd[4715]: Net::Server: Kill TERM pid 4719
Feb 5 21:27:34.871 xx.xxxxx.com /usr/sbin/amavisd[4715]: Net::Server: Kill TERM pid 4717
Feb 5 21:27:34.871 xx.xxxxx.com /usr/sbin/amavisd[4715]: Net::Server: Kill TERM pid 4720
Feb 5 21:27:34.905 xx.xxxxx.com /usr/sbin/amavisd[4718]: child_goes_idle (child finishing)
Feb 5 21:27:34.905 xx.xxxxx.com /usr/sbin/amavisd[4718]: child_goes_idle: disconnected none (child finishing)
Feb 5 21:27:34.905 xx.xxxxx.com /usr/sbin/amavisd[4718]: SA rundown_child (0)
Feb 5 21:27:34.906 xx.xxxxx.com /usr/sbin/amavisd[4718]: SpamControl: rundown_child on SpamAssassin done
Feb 5 21:27:34.906 xx.xxxxx.com /usr/sbin/amavisd[4718]: child_finish_hook: invoking DESTROY methods
Feb 5 21:27:34.906 xx.xxxxx.com /usr/sbin/amavisd[4719]: child_goes_idle (child finishing)
Feb 5 21:27:34.906 xx.xxxxx.com /usr/sbin/amavisd[4719]: child_goes_idle: disconnected none (child finishing)
Feb 5 21:27:34.906 xx.xxxxx.com /usr/sbin/amavisd[4719]: SA rundown_child (0)
Feb 5 21:27:34.906 xx.xxxxx.com /usr/sbin/amavisd[4719]: SpamControl: rundown_child on SpamAssassin done
Feb 5 21:27:34.907 xx.xxxxx.com /usr/sbin/amavisd[4719]: child_finish_hook: invoking DESTROY methods
Feb 5 21:27:34.907 xx.xxxxx.com /usr/sbin/amavisd[4717]: child_goes_idle (child finishing)
Feb 5 21:27:34.907 xx.xxxxx.com /usr/sbin/amavisd[4717]: child_goes_idle: disconnected none (child finishing)
Feb 5 21:27:34.907 xx.xxxxx.com /usr/sbin/amavisd[4717]: SA rundown_child (0)
Feb 5 21:27:34.907 xx.xxxxx.com /usr/sbin/amavisd[4717]: SpamControl: rundown_child on SpamAssassin done
Feb 5 21:27:34.907 xx.xxxxx.com /usr/sbin/amavisd[4717]: child_finish_hook: invoking DESTROY methods
Feb 5 21:27:34.909 xx.xxxxx.com /usr/sbin/amavisd[4720]: child_goes_idle (child finishing)
Feb 5 21:27:34.909 xx.xxxxx.com /usr/sbin/amavisd[4720]: child_goes_idle: disconnected none (child finishing)
Feb 5 21:27:34.909 xx.xxxxx.com /usr/sbin/amavisd[4720]: SA rundown_child (0)
Feb 5 21:27:34.909 xx.xxxxx.com /usr/sbin/amavisd[4720]: SpamControl: rundown_child on SpamAssassin done
Feb 5 21:27:34.909 xx.xxxxx.com /usr/sbin/amavisd[4720]: child_finish_hook: invoking DESTROY methods
Feb 5 21:27:34.911 xx.xxxxx.com /usr/sbin/amavisd[4717]: Amavis::DB::SNMP DESTROY called
Feb 5 21:27:34.912 xx.xxxxx.com /usr/sbin/amavisd[4720]: Amavis::DB::SNMP DESTROY called
Feb 5 21:27:34.913 xx.xxxxx.com /usr/sbin/amavisd[4719]: Amavis::DB::SNMP DESTROY called
Feb 5 21:27:34.913 xx.xxxxx.com /usr/sbin/amavisd[4718]: Amavis::DB::SNMP DESTROY called