After upgrade from Ubuntu 9.10 to 10.10 Mail Queue won't empty - Amavis??? Hi Guys, I upgraded from Ubuntu 9.10 to 10.10 and everything seems to work OK except incoming mail isn't getting out of the mail queue. It seems to be something to do with amavis. If I comment out the line in /etc/postfix/main.cf for amavis, then mail is delivered. Of course, now I have no spam/virus checking ;( I see a similar post and they upgraded amavis, but when I tried their instructions, the site for the ppa couldn't be found/resolved. Here's the line in /etc/postfix/main.cf: Code: diff main.cf.orig main.cf 71c71 < content_filter = amavis:[127.0.0.1]:10024 --- > # content_filter = amavis:[127.0.0.1]:10024 Amavis is running on that port: Code: tcp 0 0 127.0.0.1:10024 0.0.0.0:* LISTEN 2115/amavisd (maste tcp 1 0 127.0.0.1:10024 127.0.0.1:51560 CLOSE_WAIT - tcp 0 0 127.0.0.1:10024 127.0.0.1:55250 ESTABLISHED - amavis 2115 99.8 0.6 195620 76796 ? Rs 22:06 22:57 amavisd (master) postfix 6314 0.0 0.0 50852 2880 ? S 22:27 0:00 smtp -n amavis -t unix -u -c -o smtp_data_done_timeout=1200 -o smtp_send_xforward_command=yes postfix 6315 0.0 0.0 50852 2880 ? S 22:27 0:00 smtp -n amavis -t unix -u -c -o smtp_data_done_timeout=1200 -o smtp_send_xforward_command=yes root 6361 0.0 0.0 7628 960 pts/1 S+ 22:29 0:00 grep amavis tcp 0 0 127.0.0.1:55251 127.0.0.1:10024 ESTABLISHED 6315/smtp tcp 1 0 127.0.0.1:10024 127.0.0.1:58023 CLOSE_WAIT - tcp 1 0 127.0.0.1:10024 127.0.0.1:58022 CLOSE_WAIT - tcp 1 0 127.0.0.1:10024 127.0.0.1:51761 CLOSE_WAIT - tcp 0 0 127.0.0.1:58023 127.0.0.1:10024 FIN_WAIT2 - tcp 0 0 127.0.0.1:10024 127.0.0.1:55251 ESTABLISHED - tcp 1 0 127.0.0.1:10024 127.0.0.1:45032 CLOSE_WAIT - tcp 1 0 127.0.0.1:10024 127.0.0.1:57909 CLOSE_WAIT - tcp 1 0 127.0.0.1:10024 127.0.0.1:51762 CLOSE_WAIT - tcp 1 0 127.0.0.1:10024 127.0.0.1:49483 CLOSE_WAIT - tcp 1 0 127.0.0.1:10024 127.0.0.1:51559 CLOSE_WAIT - tcp 1 0 127.0.0.1:10024 127.0.0.1:45033 CLOSE_WAIT - tcp 1 0 127.0.0.1:10024 127.0.0.1:49482 CLOSE_WAIT - tcp 0 0 127.0.0.1:55250 127.0.0.1:10024 ESTABLISHED 6314/smtp tcp 0 0 127.0.0.1:58022 127.0.0.1:10024 FIN_WAIT2 - tcp 1 0 127.0.0.1:10024 127.0.0.1:57908 CLOSE_WAIT - I tried to flush it, but it seems to go back to the initial state: Code: root@inferno:/etc/init.d# postqueue -p -Queue ID- --Size-- ----Arrival Time---- -Sender/Recipient------- 13440F625F* 9446 Sat Dec 11 19:51:02 [email protected] [email protected] 2DD77F6253 1910 Sat Dec 11 18:57:45 [email protected] (conversation with 127.0.0.1[127.0.0.1] timed out while receiving the initial server greeting) [email protected] 2FEC9F6261 14398 Sat Dec 11 19:53:32 [email protected] (lost connection with 127.0.0.1[127.0.0.1] while receiving the initial server greeting) [email protected] 50133F6591 854 Sat Dec 11 18:40:48 [email protected] (conversation with 127.0.0.1[127.0.0.1] timed out while receiving the initial server greeting) [email protected] 98DDBF625D 284805 Sat Dec 11 19:13:34 [email protected] (conversation with 127.0.0.1[127.0.0.1] timed out while receiving the initial server greeting) [email protected] 913F9F61F4 10846 Sat Dec 11 18:39:05 [email protected] (conversation with 127.0.0.1[127.0.0.1] timed out while receiving the initial server greeting) [email protected] 68AD4F625E 2486 Sat Dec 11 19:24:12 [email protected] (conversation with 127.0.0.1[127.0.0.1] timed out while receiving the initial server greeting) [email protected] -- 322 Kbytes in 7 Requests. Then I tried flushing the queue and that looked promising ... Code: root@inferno:/etc/init.d# postqueue -f root@inferno:/etc/init.d# postqueue -p -Queue ID- --Size-- ----Arrival Time---- -Sender/Recipient------- 13440F625F* 9446 Sat Dec 11 19:51:02 [email protected] [email protected] 2DD77F6253* 1910 Sat Dec 11 18:57:45 [email protected] [email protected] 2FEC9F6261* 14398 Sat Dec 11 19:53:32 [email protected] [email protected] 50133F6591* 854 Sat Dec 11 18:40:48 [email protected] [email protected] 98DDBF625D* 284805 Sat Dec 11 19:13:34 [email protected] [email protected] 913F9F61F4* 10846 Sat Dec 11 18:39:05 [email protected] [email protected] 68AD4F625E* 2486 Sat Dec 11 19:24:12 [email protected] [email protected] -- 322 Kbytes in 7 Requests. But still no mail reached my mail client, so I ran the -p again and see: Code: root@inferno:/etc/init.d# postqueue -p -Queue ID- --Size-- ----Arrival Time---- -Sender/Recipient------- 2DD77F6253* 1910 Sat Dec 11 18:57:45 [email protected] [email protected] 2FEC9F6261* 14398 Sat Dec 11 19:53:32 [email protected] [email protected] 50133F6591* 854 Sat Dec 11 18:40:48 [email protected] [email protected] 98DDBF625D* 284805 Sat Dec 11 19:13:34 [email protected] [email protected] 913F9F61F4* 10846 Sat Dec 11 18:39:05 [email protected] [email protected] 68AD4F625E* 2486 Sat Dec 11 19:24:12 [email protected] [email protected] 13440F625F 9446 Sat Dec 11 19:51:02 [email protected] (conversation with 127.0.0.1[127.0.0.1] [B]timed out while receiving the initial server greeting)[/B] [email protected]
What's the output of Code: netstat -tap ? What's in your mail log if you try to deliver a mail with amavisd enabled in your Postfix configuration?
Here's the mail log. I enabled amavis in postfix main.cf, restarted spamassassin, amavis and postfix: Code: Dec 12 14:14:43 inferno spamd[26247]: spamd: server started on port 783/tcp (running version 3.3.1) Dec 12 14:14:43 inferno spamd[26247]: spamd: server pid: 26247 Dec 12 14:14:43 inferno spamd[26247]: spamd: server successfully spawned child process, pid 26257 Dec 12 14:14:43 inferno spamd[26247]: spamd: server successfully spawned child process, pid 26258 Dec 12 14:14:43 inferno spamd[26247]: prefork: child states: IS Dec 12 14:14:43 inferno spamd[26247]: prefork: child states: II Dec 12 14:14:49 inferno amavis[26280]: starting. /usr/sbin/amavisd-new at inferno.cocoanet.us amavisd-new-2.6.4 (20090625), Unicode aware, LANG="en_US.UTF-8" Dec 12 14:14:49 inferno amavis[26280]: Perl version 5.010001 Dec 12 14:14:49 inferno amavis[26286]: Net::Server: Group Not Defined. Defaulting to EGID '128 128' Dec 12 14:14:49 inferno amavis[26286]: Net::Server: User Not Defined. Defaulting to EUID '117' Dec 12 14:14:49 inferno amavis[26286]: Module Amavis::Conf 2.207 Dec 12 14:14:49 inferno amavis[26286]: Module Archive::Zip 1.30 Dec 12 14:14:49 inferno amavis[26286]: Module BerkeleyDB 0.39 Dec 12 14:14:49 inferno amavis[26286]: Module Compress::Zlib 2.022 Dec 12 14:14:49 inferno amavis[26286]: Module Convert::TNEF 0.17 Dec 12 14:14:49 inferno amavis[26286]: Module Convert::UUlib 1.12 Dec 12 14:14:49 inferno amavis[26286]: Module Crypt::OpenSSL::RSA 0.25 Dec 12 14:14:49 inferno amavis[26286]: Module Digest::MD5 2.39 Dec 12 14:14:49 inferno amavis[26286]: Module Digest::SHA 5.47 Dec 12 14:14:49 inferno amavis[26286]: Module IO::Socket::INET6 2.54 Dec 12 14:14:49 inferno amavis[26286]: Module MIME::Entity 5.427 Dec 12 14:14:49 inferno amavis[26286]: Module MIME::Parser 5.427 Dec 12 14:14:49 inferno amavis[26286]: Module MIME::Tools 5.427 Dec 12 14:14:49 inferno amavis[26286]: Module Mail::DKIM::Signer 0.38 Dec 12 14:14:49 inferno amavis[26286]: Module Mail::DKIM::Verifier 0.38 Dec 12 14:14:49 inferno amavis[26286]: Module Mail::Header 2.05 Dec 12 14:14:49 inferno amavis[26286]: Module Mail::Internet 2.05 Dec 12 14:14:49 inferno amavis[26286]: Module Net::DNS 0.65 Dec 12 14:14:49 inferno amavis[26286]: Module Net::Server 0.97 Dec 12 14:14:49 inferno amavis[26286]: Module Socket6 0.23 Dec 12 14:14:49 inferno amavis[26286]: Module Time::HiRes 1.9719 Dec 12 14:14:49 inferno amavis[26286]: Module Unix::Syslog 1.1 Dec 12 14:14:49 inferno amavis[26286]: Amavis::DB code loaded Dec 12 14:14:49 inferno amavis[26286]: Amavis::Cache code loaded Dec 12 14:14:49 inferno amavis[26286]: SQL base code NOT loaded Dec 12 14:14:49 inferno amavis[26286]: SQL::Log code NOT loaded Dec 12 14:14:49 inferno amavis[26286]: SQL::Quarantine NOT loaded Dec 12 14:14:49 inferno amavis[26286]: Lookup::SQL code NOT loaded Dec 12 14:14:49 inferno amavis[26286]: Lookup::LDAP code NOT loaded Dec 12 14:14:49 inferno amavis[26286]: AM.PDP-in proto code loaded Dec 12 14:14:49 inferno amavis[26286]: SMTP-in proto code loaded Dec 12 14:14:49 inferno amavis[26286]: Courier proto code NOT loaded Dec 12 14:14:49 inferno amavis[26286]: SMTP-out proto code loaded Dec 12 14:14:49 inferno amavis[26286]: Pipe-out proto code NOT loaded Dec 12 14:14:49 inferno amavis[26286]: BSMTP-out proto code NOT loaded Dec 12 14:14:49 inferno amavis[26286]: Local-out proto code loaded Dec 12 14:14:49 inferno amavis[26286]: OS_Fingerprint code NOT loaded Dec 12 14:14:49 inferno amavis[26286]: ANTI-VIRUS code NOT loaded Dec 12 14:14:49 inferno amavis[26286]: ANTI-SPAM code NOT loaded Dec 12 14:14:49 inferno amavis[26286]: ANTI-SPAM-EXT code NOT loaded Dec 12 14:14:49 inferno amavis[26286]: ANTI-SPAM-C code NOT loaded Dec 12 14:14:49 inferno amavis[26286]: ANTI-SPAM-SA code NOT loaded Dec 12 14:14:49 inferno amavis[26286]: Unpackers code loaded Dec 12 14:14:49 inferno amavis[26286]: DKIM code loaded Dec 12 14:14:49 inferno amavis[26286]: Tools code NOT loaded Dec 12 14:14:49 inferno amavis[26286]: Found $file at /usr/bin/file Dec 12 14:14:49 inferno amavis[26286]: No $altermime, not using it Dec 12 14:14:49 inferno amavis[26286]: Internal decoder for .mail Dec 12 14:14:49 inferno amavis[26286]: No decoder for .F Dec 12 14:14:49 inferno amavis[26286]: Found decoder for .Z at /bin/uncompress Dec 12 14:14:49 inferno amavis[26286]: Internal decoder for .gz Dec 12 14:14:49 inferno amavis[26286]: Found decoder for .bz2 at /bin/bzip2 -d Dec 12 14:14:49 inferno amavis[26286]: Found decoder for .lzo at /usr/bin/lzop -d Dec 12 14:14:49 inferno amavis[26286]: No decoder for .rpm tried: rpm2cpio.pl, rpm2cpio Dec 12 14:14:49 inferno amavis[26286]: Found decoder for .cpio at /usr/bin/pax Dec 12 14:14:49 inferno amavis[26286]: Found decoder for .tar at /usr/bin/pax Dec 12 14:14:49 inferno amavis[26286]: Found decoder for .deb at /usr/bin/ar Dec 12 14:14:49 inferno amavis[26286]: Internal decoder for .zip Dec 12 14:14:49 inferno amavis[26286]: No decoder for .7z tried: 7zr, 7za, 7z Dec 12 14:14:49 inferno amavis[26286]: No decoder for .rar tried: unrar-free Dec 12 14:14:49 inferno amavis[26286]: Found decoder for .arj at /usr/bin/arj Dec 12 14:14:49 inferno amavis[26286]: Found decoder for .arc at /usr/bin/nomarch Dec 12 14:14:49 inferno amavis[26286]: Found decoder for .zoo at /usr/bin/zoo Dec 12 14:14:49 inferno amavis[26286]: No decoder for .lha Dec 12 14:14:49 inferno amavis[26286]: No decoder for .doc tried: ripole Dec 12 14:14:49 inferno amavis[26286]: Found decoder for .cab at /usr/bin/cabextract Dec 12 14:14:49 inferno amavis[26286]: No decoder for .tnef Dec 12 14:14:49 inferno amavis[26286]: Internal decoder for .tnef Dec 12 14:14:49 inferno amavis[26286]: Found decoder for .exe at /usr/bin/arj Dec 12 14:14:49 inferno amavis[26286]: Creating db in /var/lib/amavis/db/; BerkeleyDB 0.39, libdb 4.8 Dec 12 14:15:01 inferno pop3d: Connection, ip=[::1] Dec 12 14:15:01 inferno imapd: Connection, ip=[::1] Dec 12 14:15:01 inferno pop3d: Disconnected, ip=[::1] Dec 12 14:15:01 inferno imapd: Disconnected, ip=[::1], time=0 Dec 12 14:15:01 inferno postfix/postqueue[26451]: warning: Mail system is down -- accessing queue directly Dec 12 14:15:01 inferno postfix/master[26457]: daemon started -- version 2.7.0, configuration /etc/postfix netstat -tap with everything running: Code: netstat -tap Active Internet connections (servers and established) Proto Recv-Q Send-Q Local Address Foreign Address State PID/Program name tcp 0 0 inferno.cocoanet.u:6011 *:* LISTEN 26145/6 tcp 0 0 *:8443 *:* LISTEN 3932/apache2 tcp 0 0 *:https *:* LISTEN 3932/apache2 tcp 0 0 *:microsoft-ds *:* LISTEN 1376/smbd tcp 0 0 *:16001 *:* LISTEN 4246/pulseaudio tcp 0 0 *:9443 *:* LISTEN 3932/apache2 tcp 0 0 inferno.cocoanet.:10024 *:* LISTEN 26286/amavisd (mast tcp 0 0 inferno.cocoanet.:10025 *:* LISTEN 26457/master tcp 0 0 *:4713 *:* LISTEN 4246/pulseaudio tcp 0 0 *:mysql *:* LISTEN 1525/mysqld tcp 0 0 *:netbios-ssn *:* LISTEN 1376/smbd tcp 0 0 inferno.cocoanet.:spamd *:* LISTEN 26247/spamd.pid tcp 0 0 *:www *:* LISTEN 3932/apache2 tcp 0 0 *:5552 *:* LISTEN 1674/tprintdaemon tcp 0 0 *:tproxy *:* LISTEN 3932/apache2 tcp 0 0 inferno.cocoanet.u:7634 *:* LISTEN 3573/hddtemp tcp 0 0 *:ftp *:* LISTEN 18770/pure-ftpd (SE tcp 0 0 h-74-1-46-164.sf:domain *:* LISTEN 1553/named tcp 0 0 h-74-1-46-168.sf:domain *:* LISTEN 1553/named tcp 0 0 h-74-1-46-167.sf:domain *:* LISTEN 1553/named tcp 0 0 h-74-1-46-166.sf:domain *:* LISTEN 1553/named tcp 0 0 h-74-1-46-165.sf:domain *:* LISTEN 1553/named tcp 0 0 www.cocoanet.us:domain *:* LISTEN 1553/named tcp 0 0 inferno.cocoanet:domain *:* LISTEN 1553/named tcp 0 0 inferno.cocoanet:domain *:* LISTEN 1553/named tcp 0 0 *:ssh *:* LISTEN 2095/sshd tcp 0 0 *:ipp *:* LISTEN 3870/cupsd tcp 0 0 *:smtp *:* LISTEN 26457/master tcp 0 0 inferno.cocoanet.u:5433 *:* LISTEN 2138/postgres tcp 0 0 inferno.cocoanet.us:953 *:* LISTEN 1553/named tcp 0 0 inferno.cocoanet.u:6010 *:* LISTEN 25582/5 tcp 38 0 h-74-1-46-164.sfl:48148 one-ubuntu-com.ca:https CLOSE_WAIT 4694/python tcp 0 0 h-74-1-46-164.sfl:59872 cluster003.ovh.net:www TIME_WAIT - tcp 0 0 h-74-1-46-164.sfl:56145 a184-84-255-154.dep:www ESTABLISHED 4324/gweather-apple tcp 0 0 h-74-1-46-164.sfl:59866 cluster003.ovh.net:www TIME_WAIT - tcp 0 0 h-74-1-46-164.sfl:59904 cluster003.ovh.net:www TIME_WAIT - tcp 0 0 h-74-1-46-164.sfl:59889 cluster003.ovh.net:www TIME_WAIT - tcp 0 0 h-74-1-46-164.sfl:39565 ec2-174-129-241-1:https ESTABLISHED 4694/python tcp 0 0 h-74-1-46-164.sfl:59883 cluster003.ovh.net:www TIME_WAIT - tcp 0 0 h-74-1-46-164.sfl:56146 a184-84-255-154.dep:www ESTABLISHED 4324/gweather-apple tcp 0 0 inferno.cocoanet.us:ssh h-74-1-46-171.sfl:55056 ESTABLISHED 25505/sshd: dante [ tcp 0 0 h-74-1-46-164.sfl:59891 cluster003.ovh.net:www TIME_WAIT - tcp 0 0 h-74-1-46-164.sfl:59892 cluster003.ovh.net:www TIME_WAIT - tcp 0 0 inferno.co:microsoft-ds 74.81.152.184:1209 ESTABLISHED 26778/smbd tcp 0 0 www.cocoanet.us:www h-74-1-46-171.sfl:55132 ESTABLISHED 18738/apache2 tcp 0 0 h-74-1-46-164.sfl:59868 cluster003.ovh.net:www TIME_WAIT - tcp 0 0 h-74-1-46-164.sfl:59862 cluster003.ovh.net:www TIME_WAIT - tcp 1 0 h-74-1-46-164.sfl:43769 205.128.76.126:www CLOSE_WAIT 4324/gweather-apple tcp 0 0 h-74-1-46-164.sfl:59865 cluster003.ovh.net:www TIME_WAIT - tcp 0 0 h-74-1-46-:microsoft-ds 74.81.152.184:1318 ESTABLISHED 26785/smbd tcp 0 0 h-74-1-46-164.sfl:59905 cluster003.ovh.net:www TIME_WAIT - tcp 0 0 h-74-1-46-:microsoft-ds 74.81.152.184:1242 ESTABLISHED 26780/smbd tcp 0 0 h-74-1-46-164.sfl:59896 cluster003.ovh.net:www TIME_WAIT - tcp 0 0 h-74-1-46-164.sfl:59898 cluster003.ovh.net:www TIME_WAIT - tcp 0 0 h-74-1-46-164.sfl:59885 cluster003.ovh.net:www TIME_WAIT - tcp 0 0 inferno.co:microsoft-ds 74.81.152.184:2129 ESTABLISHED - tcp 0 0 h-74-1-46-164.sfl:59908 cluster003.ovh.net:www TIME_WAIT - tcp 0 0 inferno.cocoanet.us:ssh h-74-1-46-171.sfl:55191 ESTABLISHED 26078/sshd: dante [ tcp 0 0 h-74-1-46-164.sfl:59890 cluster003.ovh.net:www TIME_WAIT - tcp 0 0 www.cocoan:microsoft-ds 74.81.152.184:2203 ESTABLISHED - tcp 0 0 h-74-1-46-:microsoft-ds 74.81.152.184:2205 ESTABLISHED - tcp 0 0 h-74-1-46-164.sfl:59894 cluster003.ovh.net:www TIME_WAIT - tcp 0 0 h-74-1-46-164.sfl:59895 cluster003.ovh.net:www TIME_WAIT - tcp 0 0 h-74-1-46-164.sfl:59907 cluster003.ovh.net:www TIME_WAIT - tcp 0 0 h-74-1-46-164.sfl:59884 cluster003.ovh.net:www TIME_WAIT - tcp 0 0 h-74-1-46-164.sfl:59906 cluster003.ovh.net:www TIME_WAIT - tcp6 0 0 localhost:6011 [::]:* LISTEN 26145/6 tcp6 0 0 [::]:16001 [::]:* LISTEN 4246/pulseaudio tcp6 0 0 [::]:imaps [::]:* LISTEN 3409/couriertcpd tcp6 0 0 [::]:pop3s [::]:* LISTEN 3443/couriertcpd tcp6 0 0 [::]:4713 [::]:* LISTEN 4246/pulseaudio tcp6 0 0 [::]:pop3 [::]:* LISTEN 3423/couriertcpd tcp6 0 0 [::]:imap2 [::]:* LISTEN 3389/couriertcpd tcp6 0 0 [::]:ftp [::]:* LISTEN 18770/pure-ftpd (SE tcp6 0 0 [::]:domain [::]:* LISTEN 1553/named tcp6 0 0 [::]:ssh [::]:* LISTEN 2095/sshd tcp6 0 0 [::]:ipp [::]:* LISTEN 3870/cupsd tcp6 0 0 localhost:5433 [::]:* LISTEN 2138/postgres tcp6 0 0 localhost:953 [::]:* LISTEN 1553/named tcp6 0 0 localhost:6010 [::]:* LISTEN 25582/5 tcp6 0 0 www.cocoanet.us:imaps h-74-1-46-171.sfl:55024 ESTABLISHED 4967/couriertls tcp6 0 0 h-74-1-46-166.sfl:imaps h-74-1-46-171.sfl:55045 ESTABLISHED 25343/couriertls tcp6 0 0 h-74-1-46-166.sfl:imaps h-74-1-46-171.sfl:55047 ESTABLISHED 20806/couriertls tcp6 0 0 www.cocoanet.us:imaps h-74-1-46-171.sfl:55031 ESTABLISHED 12335/couriertls tcp6 0 0 h-74-1-46-166.sfl:imaps h-74-1-46-171.sfl:55096 ESTABLISHED 25368/couriertls tcp6 0 0 h-74-1-46-166.sfl:imaps h-74-1-46-171.sfl:55025 ESTABLISHED 4961/couriertls tcp6 0 0 www.cocoanet.us:imaps h-74-1-46-171.sfl:55029 ESTABLISHED 4963/couriertls tcp6 0 0 www.cocoanet.us:imaps h-74-1-46-171.sfl:55005 ESTABLISHED 4904/couriertls tcp6 0 0 www.cocoanet.us:imaps h-74-1-46-171.sfl:55013 ESTABLISHED 4576/couriertls tcp6 0 0 www.cocoanet.us:imaps h-74-1-46-171.sfl:55011 ESTABLISHED 24080/couriertls tcp6 0 0 h-74-1-46-166.sfl:imaps h-74-1-46-171.sfl:55105 ESTABLISHED 25366/couriertls tcp6 0 0 www.cocoanet.us:imaps h-74-1-46-171.sfl:55049 ESTABLISHED 25354/couriertls tcp6 0 0 h-74-1-46-166.sfl:imaps h-74-1-46-171.sfl:55021 ESTABLISHED 4581/couriertls tcp6 0 0 www.cocoanet.us:imaps h-74-1-46-171.sfl:55055 ESTABLISHED 18570/couriertls tcp6 0 0 h-74-1-46-166.sfl:imaps h-74-1-46-171.sfl:55002 ESTABLISHED 4789/couriertls tcp6 0 0 h-74-1-46-166.sfl:imaps h-74-1-46-171.sfl:55069 ESTABLISHED 21908/couriertls tcp6 0 0 www.cocoanet.us:imaps h-74-1-46-171.sfl:55038 ESTABLISHED 24921/couriertls tcp6 0 0 www.cocoanet.us:imaps h-74-1-46-171.sfl:55015 ESTABLISHED 5382/couriertls tcp6 0 0 www.cocoanet.us:imaps h-74-1-46-171.sfl:55017 ESTABLISHED 4909/couriertls tcp6 0 0 www.cocoanet.us:imaps h-74-1-46-171.sfl:55036 ESTABLISHED 22980/couriertls tcp6 0 0 www.cocoanet.us:imaps h-74-1-46-171.sfl:55081 ESTABLISHED 25134/couriertls tcp6 0 0 www.cocoanet.us:imaps h-74-1-46-171.sfl:55008 ESTABLISHED 4908/couriertls tcp6 0 0 www.cocoanet.us:imaps h-74-1-46-171.sfl:55003 ESTABLISHED 4811/couriertls tcp6 0 0 www.cocoanet.us:imaps h-74-1-46-171.sfl:55035 ESTABLISHED 13974/couriertls tcp6 0 0 www.cocoanet.us:imaps h-74-1-46-171.sfl:55022 ESTABLISHED 4964/couriertls tcp6 0 0 www.cocoanet.us:imaps h-74-1-46-171.sfl:55068 ESTABLISHED 5264/couriertls tcp6 0 0 h-74-1-46-166.sfl:imaps h-74-1-46-171.sfl:55007 ESTABLISHED 4986/couriertls Here's the mail.log after sending from gmail to a few accounts on my server: Code: Dec 12 14:18:33 inferno postfix/smtpd[26985]: connect from mail-ww0-f41.google.com[74.125.82.41] Dec 12 14:18:34 inferno postfix/smtpd[26985]: 11E95F61C6: client=mail-ww0-f41.google.com[74.125.82.41] Dec 12 14:18:34 inferno postfix/cleanup[26995]: 11E95F61C6: message-id=<[email protected]> Dec 12 14:18:34 inferno postfix/qmgr[26460]: 11E95F61C6: from=<[email protected]>, size=11511, nrcpt=1 (queue active) Dec 12 14:18:34 inferno postfix/smtpd[27195]: connect from inferno.cocoanet.us[127.0.0.1] Dec 12 14:18:34 inferno postfix/smtpd[27195]: E4972F61C8: client=inferno.cocoanet.us[127.0.0.1] Dec 12 14:18:34 inferno postfix/cleanup[26995]: E4972F61C8: message-id=<[email protected]> Dec 12 14:18:34 inferno postfix/qmgr[26460]: E4972F61C8: from=<[email protected]>, size=11933, nrcpt=1 (queue active) Dec 12 14:18:34 inferno amavis[26288]: (26288-01) Passed CLEAN, [74.125.82.41] [74.125.82.41] <[email protected]> -> <[email protected]>, Message-ID: <[email protected]>, mail_id: bO2Jbgr+mlgI, Hits: -, size: 11511, queued_as: E4972F61C8, [email protected],[email protected], 455 ms Dec 12 14:18:34 inferno postfix/smtp[26998]: 11E95F61C6: to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:10024, delay=1, delays=0.56/0/0.01/0.45, dsn=2.0.0, status=sent (250 2.0.0 Ok, id=26288-01, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as E4972F61C8) Dec 12 14:18:34 inferno postfix/qmgr[26460]: 11E95F61C6: removed Dec 12 14:18:34 inferno postfix/pipe[27002]: E4972F61C8: to=<[email protected]>, relay=maildrop, delay=0.03, delays=0.01/0/0/0.02, dsn=2.0.0, status=sent (delivered via maildrop service) Dec 12 14:18:34 inferno postfix/qmgr[26460]: E4972F61C8: removed Dec 12 14:18:35 inferno postfix/smtpd[27204]: connect from mail-ww0-f48.google.com[74.125.82.48] Dec 12 14:18:36 inferno postfix/smtpd[27204]: 4C1AAF61C6: client=mail-ww0-f48.google.com[74.125.82.48] Dec 12 14:18:36 inferno postfix/cleanup[26995]: 4C1AAF61C6: message-id=<[email protected]> Dec 12 14:18:36 inferno postfix/qmgr[26460]: 4C1AAF61C6: from=<[email protected]>, size=11471, nrcpt=2 (queue active) Dec 12 14:18:36 inferno postfix/smtpd[27000]: E7932F61C8: client=inferno.cocoanet.us[127.0.0.1] Dec 12 14:18:36 inferno postfix/cleanup[26995]: E7932F61C8: message-id=<[email protected]> Dec 12 14:18:36 inferno postfix/qmgr[26460]: E7932F61C8: from=<[email protected]>, size=11825, nrcpt=2 (queue active) Dec 12 14:18:36 inferno postfix/smtpd[27000]: disconnect from inferno.cocoanet.us[127.0.0.1] Dec 12 14:18:36 inferno amavis[26287]: (26287-02) Passed CLEAN, [74.125.82.48] [74.125.82.48] <[email protected]> -> <[email protected]>,<[email protected]>, Message-ID: <[email protected]>, mail_id: jwx0VdoySMKs, Hits: -, size: 11471, queued_as: E7932F61C8, [email protected],[email protected], 105 ms Dec 12 14:18:36 inferno postfix/smtp[26998]: 4C1AAF61C6: to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:10024, delay=0.79, delays=0.68/0/0/0.11, dsn=2.0.0, status=sent (250 2.0.0 Ok, id=26287-02, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as E7932F61C8) Dec 12 14:18:36 inferno postfix/smtp[26998]: 4C1AAF61C6: to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:10024, delay=0.79, delays=0.68/0/0/0.11, dsn=2.0.0, status=sent (250 2.0.0 Ok, id=26287-02, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as E7932F61C8) Dec 12 14:18:36 inferno postfix/qmgr[26460]: 4C1AAF61C6: removed Dec 12 14:18:36 inferno postfix/pipe[27002]: E7932F61C8: to=<[email protected]>, relay=maildrop, delay=0.04, delays=0.01/0/0/0.03, dsn=2.0.0, status=sent (delivered via maildrop service) Dec 12 14:18:37 inferno postfix/pipe[27214]: E7932F61C8: to=<[email protected]>, relay=maildrop, delay=0.08, delays=0.01/0.01/0/0.06, dsn=2.0.0, status=sent (delivered via maildrop service) Dec 12 14:18:37 inferno postfix/qmgr[26460]: E7932F61C8: removed Dec 12 14:19:04 inferno postfix/smtpd[26985]: disconnect from mail-ww0-f41.google.com[74.125.82.41] Dec 12 14:19:06 inferno postfix/smtpd[27204]: disconnect from mail-ww0-f48.google.com[74.125.82.48] ... Dec 12 14:21:49 inferno postfix/cleanup[27907]: A5F53F61C8: message-id=<[email protected]> Dec 12 14:21:49 inferno postfix/smtpd[27195]: disconnect from inferno.cocoanet.us[127.0.0.1] Dec 12 14:21:49 inferno postfix/qmgr[26460]: A5F53F61C8: from=<[email protected]>, size=2801, nrcpt=2 (queue active) Dec 12 14:21:49 inferno amavis[26288]: (26288-02) Passed CLEAN, [209.235.129.76] [74.1.46.171] <[email protected]> -> <[email protected]>,<[email protected]>, Message-ID: <[email protected]>, mail_id: D6GAe--wN7j6, Hits: -, size: 2447, queued_as: A5F53F61C8, 90 ms Dec 12 14:21:49 inferno postfix/smtp[27908]: 719F4F61C6: to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:10024, delay=0.27, delays=0.17/0.01/0/0.09, dsn=2.0.0, status=sent (250 2.0.0 Ok, id=26288-02, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as A5F53F61C8) Dec 12 14:21:49 inferno postfix/smtp[27908]: 719F4F61C6: to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:10024, delay=0.27, delays=0.17/0.01/0/0.09, dsn=2.0.0, status=sent (250 2.0.0 Ok, id=26288-02, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as A5F53F61C8) Dec 12 14:21:49 inferno postfix/qmgr[26460]: 719F4F61C6: removed Dec 12 14:21:49 inferno postfix/pipe[27911]: A5F53F61C8: to=<[email protected]>, relay=maildrop, delay=0.04, delays=0.01/0.01/0/0.03, dsn=2.0.0, status=sent (delivered via maildrop service) Dec 12 14:21:49 inferno postfix/pipe[27912]: A5F53F61C8: to=<[email protected]>, relay=maildrop, delay=0.08, delays=0.01/0.01/0/0.06, dsn=2.0.0, status=sent (delivered via maildrop service) Dec 12 14:21:49 inferno postfix/qmgr[26460]: A5F53F61C8: removed Not sure why, but things look better now. Mail is finally beind pushed out the postfix queue, but all kinds of spam is coming through and I don't see any messages about amavis or spamassassin dumping bad messages. Did I lose the config files for amavis & spam assassin?
postfix config files: main.cf Code: # See /usr/share/postfix/main.cf.dist for a commented, more complete version # Debian specific: Specifying a file name will cause the first # line of that file to be used as the name. The Debian default # is /etc/mailname. #myorigin = /etc/mailname smtpd_banner = $myhostname ESMTP $mail_name (Ubuntu) biff = no # appending .domain is the MUA's job. append_dot_mydomain = no # Uncomment the next line to generate "delayed mail" warnings #delay_warning_time = 4h readme_directory = /usr/share/doc/postfix # TLS parameters smtpd_tls_cert_file = /etc/postfix/smtpd.cert smtpd_tls_key_file = /etc/postfix/smtpd.key smtpd_use_tls = yes smtpd_tls_session_cache_database = btree:${data_directory}/smtpd_scache smtp_tls_session_cache_database = btree:${data_directory}/smtp_scache # See /usr/share/doc/postfix/TLS_README.gz in the postfix-doc package for # information on enabling SSL in the smtp client. myhostname = inferno.cocoanet.us alias_maps = hash:/etc/aliases alias_database = hash:/etc/aliases myorigin = /etc/mailname mydestination = inferno.cocoanet.us, localhost, localhost.localdomain relayhost = mynetworks = 127.0.0.0/8 [::1]/128 mailbox_size_limit = 0 recipient_delimiter = + inet_interfaces = all html_directory = /usr/share/doc/postfix/html virtual_alias_domains = virtual_alias_maps = proxy:mysql:/etc/postfix/mysql-virtual_forwardings.cf, mysql:/etc/postfix/mysql-virtual_email2email.cf virtual_mailbox_domains = proxy:mysql:/etc/postfix/mysql-virtual_domains.cf virtual_mailbox_maps = proxy:mysql:/etc/postfix/mysql-virtual_mailboxes.cf virtual_mailbox_base = /var/vmail virtual_uid_maps = static:5000 virtual_gid_maps = static:5000 smtpd_sasl_auth_enable = yes broken_sasl_auth_clients = yes smtpd_sasl_authenticated_header = yes smtpd_recipient_restrictions = permit_mynetworks, permit_sasl_authenticated, check_recipient_access mysql:/etc/postfix/mysql-virtual_recipient.cf, reject_unauth_destination smtpd_tls_security_level = may transport_maps = proxy:mysql:/etc/postfix/mysql-virtual_transports.cf relay_domains = mysql:/etc/postfix/mysql-virtual_relaydomains.cf virtual_create_maildirsize = yes virtual_maildir_extended = yes virtual_mailbox_limit_maps = proxy:mysql:/etc/postfix/mysql-virtual_mailbox_limit_maps.cf virtual_mailbox_limit_override = yes virtual_maildir_limit_message = "The user you are trying to reach is over quota." virtual_overquota_bounce = yes proxy_read_maps = $local_recipient_maps $mydestination $virtual_alias_maps $virtual_alias_domains $virtual_mailbox_maps $virtual_mailbox_domains $relay_recipient_maps $relay_domains $canonical_maps $sender_canonical_maps $recipient_canonical_maps $relocated_maps $transport_maps $mynetworks $virtual_mailbox_limit_maps smtpd_sender_restrictions = check_sender_access mysql:/etc/postfix/mysql-virtual_sender.cf smtpd_client_restrictions = check_client_access mysql:/etc/postfix/mysql-virtual_client.cf maildrop_destination_concurrency_limit = 1 maildrop_destination_recipient_limit = 1 virtual_transport = maildrop header_checks = regexp:/etc/postfix/header_checks mime_header_checks = regexp:/etc/postfix/mime_header_checks nested_header_checks = regexp:/etc/postfix/nested_header_checks body_checks = regexp:/etc/postfix/body_checks content_filter = amavis:[127.0.0.1]:10024 receive_override_options = no_address_mappings message_size_limit = 0 relay_recipient_maps = mysql:/etc/postfix/mysql-virtual_relayrecipientmaps.cf master.cf Code: # # Postfix master process configuration file. For details on the format # of the file, see the master(5) manual page (command: "man 5 master"). # # Do not forget to execute "postfix reload" after editing this file. # # ========================================================================== # service type private unpriv chroot wakeup maxproc command + args # (yes) (yes) (yes) (never) (100) # ========================================================================== smtp inet n - - - - smtpd #submission inet n - - - - smtpd # -o smtpd_tls_security_level=encrypt # -o smtpd_sasl_auth_enable=yes # -o smtpd_client_restrictions=permit_sasl_authenticated,reject # -o milter_macro_daemon_name=ORIGINATING #smtps inet n - - - - smtpd # -o smtpd_tls_wrappermode=yes # -o smtpd_sasl_auth_enable=yes # -o smtpd_client_restrictions=permit_sasl_authenticated,reject # -o milter_macro_daemon_name=ORIGINATING #628 inet n - - - - qmqpd pickup fifo n - - 60 1 pickup cleanup unix n - - - 0 cleanup qmgr fifo n - n 300 1 qmgr #qmgr fifo n - - 300 1 oqmgr tlsmgr unix - - - 1000? 1 tlsmgr rewrite unix - - - - - trivial-rewrite bounce unix - - - - 0 bounce defer unix - - - - 0 bounce trace unix - - - - 0 bounce verify unix - - - - 1 verify flush unix n - - 1000? 0 flush proxymap unix - - n - - proxymap proxywrite unix - - n - 1 proxymap smtp unix - - - - - smtp # When relaying mail as backup MX, disable fallback_relay to avoid MX loops relay unix - - - - - smtp -o smtp_fallback_relay= # -o smtp_helo_timeout=5 -o smtp_connect_timeout=5 showq unix n - - - - showq error unix - - - - - error retry unix - - - - - error discard unix - - - - - discard local unix - n n - - local virtual unix - n n - - virtual lmtp unix - - - - - lmtp anvil unix - - - - 1 anvil scache unix - - - - 1 scache # # ==================================================================== # Interfaces to non-Postfix software. Be sure to examine the manual # pages of the non-Postfix software to find out what options it wants. # # Many of the following services use the Postfix pipe(8) delivery # agent. See the pipe(8) man page for information about ${recipient} # and other message envelope options. # ==================================================================== # # maildrop. See the Postfix MAILDROP_README file for details. # Also specify in main.cf: maildrop_destination_recipient_limit=1 # maildrop unix - n n - - pipe flags=DRhu user=vmail argv=/usr/bin/maildrop -d vmail ${extension} ${recipient} ${user} ${nexthop} ${sender} # # See the Postfix UUCP_README file for configuration details. # uucp unix - n n - - pipe flags=Fqhu user=uucp argv=uux -r -n -z -a$sender - $nexthop!rmail ($recipient) # # Other external delivery methods. # ifmail unix - n n - - pipe flags=F user=ftn argv=/usr/lib/ifmail/ifmail -r $nexthop ($recipient) bsmtp unix - n n - - pipe flags=Fq. user=bsmtp argv=/usr/lib/bsmtp/bsmtp -t$nexthop -f$sender $recipient scalemail-backend unix - n n - 2 pipe flags=R user=scalemail argv=/usr/lib/scalemail/bin/scalemail-store ${nexthop} ${user} ${extension} mailman unix - n n - - pipe flags=FR user=list argv=/usr/lib/mailman/bin/postfix-to-mailman.py ${nexthop} ${user} amavis unix - - - - 2 smtp -o smtp_data_done_timeout=1200 -o smtp_send_xforward_command=yes 127.0.0.1:10025 inet n - - - - smtpd -o content_filter= -o local_recipient_maps= -o relay_recipient_maps= -o smtpd_restriction_classes= -o smtpd_client_restrictions= -o smtpd_helo_restrictions= -o smtpd_sender_restrictions= -o smtpd_recipient_restrictions=permit_mynetworks,reject -o mynetworks=127.0.0.0/8 -o strict_rfc821_envelopes=yes -o receive_override_options=no_unknown_recipient_checks,no_header_body_checks -o smtpd_bind_address=127.0.0.1 I think these haven't changed other than me commenting out the amavis stuff for debugging. Also, I am seeing messages from amavis in /var/log/syslog as expected. The reason I didn't see them before is that amavisd-new was using 100% CPU and probably was looping on something. However, tons of spam is still getting through. Does spamassassin have to re-learn everything after an upgrade? Code: Dec 13 09:57:50 inferno amavis[30597]: (30597-10) Passed CLEAN, [91.113.132.50] [202.189.118.253] <[email protected]> -> <[email protected]>, Message-ID: <[email protected]>, mail_id: I-kGCGDWHMwB, Hits: -, size: 1487, queued_as: 1A43EF61C6, 92 ms
Well, the power went out for 8 hours, when the server came back online amavisd-new was back to using 100% CPU. So I ran it in debug mode via Code: service amavis debug and see this spewing out to the screen: Code: Use of uninitialized value $fam_listen in numeric ne (!=) at /usr/share/perl5/IO/Socket/INET6.pm line 182. --- Thousands of times!! So I did some searching and it turns out I had this exact same messages spewing out after upgrading to 9.10! See this post: http://www.howtoforge.com/forums/showpost.php?p=213433&postcount=3 The problem is that for some reason, the perl that I have after upgrading doesn't have proper IPV6 support. Not sure why, and not sure where it comes from, but this upgrade had the same issue! So, I followed the instructions in the previous post of mine and it looks like things are working, again. Didn't reboot yet (I'm not there so I don't want it to fail and I have some major, major work to do this week!).
