Mail get stuck in queue

[Torsson]

Hi i have installed sql driven postfix from this gude "Virtual Users And Domains With Postfix, Courier, MySQL And SquirrelMail (Ubuntu 9.04)".

And the problem is all mails i send to it gets stuck in queue and i can't figure out why it dosen't get delivered. Here is what the log says when it tries to retrew mail

Jul 27 23:17:50 web1 postfix/smtpd[13769]: BF399166C8: client=localhost[127.0.0.1]
Jul 27 23:17:50 web1 postfix/cleanup[13751]: BF399166C8: message-id=<722a9c1.88082b09d467faa056b3275fc3ba7057@webma il.indexdesigns.se>
Jul 27 23:17:50 web1 postfix/qmgr[28340]: BF399166C8: from=<alexander@*****.se>, size=3008, nrcpt=1 (queue active)
Jul 27 23:17:50 web1 postfix/smtpd[13769]: disconnect from localhost[127.0.0.1]
Jul 27 23:17:50 web1 amavis[31802]: (31802-02) Passed CLEAN, [213.180.95.118] [213.180.95.118] <alexander@*****.se> -> <info@**********.com>, Message-ID: <722a9c1.88082b09d467faa056b3275fc3ba7057@webmail. indexdesigns.se>, mail_id: 3XY9jd5he9yf, Hits: 3.492, size: 2572, queued_as: BF399166C8, 7305 ms
Jul 27 23:17:50 web1 postfix/smtp[13752]: 73ED81666B: to=<info@**********.com>, relay=127.0.0.1[127.0.0.1]:10024, delay=7.3, delays=0.03/0.01/0/7.3, dsn=2.0.0, status=sent (250 2.0.0 Ok, id=31802-02, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as BF399166C8)

found this in /var/log/mail.err log, its a few hours erlier but will post it anyway
Jul 27 20:43:04 web1 postfix/qmgr[565]: fatal: timeout connecting to transport: virtual

[Mark_NL]

Jul 27 20:43:04 web1 postfix/qmgr[565]: fatal: timeout connecting to transport: virtual

You get this more often?

add a "-v" to the virtual line in your master.cf to see why it's giving that error.

[Torsson]

After i put in -v in master.cf i got this when i revice mail

Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const mail
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const ipv4
Jul 28 11:18:09 web1 postfix/virtual[7865]: name_mask: ipv4
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const mail.wendecodns.com
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const wendecodns.com
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const Postfix
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const postfix
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const postfix
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const postdrop
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const mail.wendecodns.com, localhost, localhost.localdomain
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const /etc/mailname
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const /usr/lib/postfix
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const /var/lib/postfix
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const /usr/sbin
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const /var/spool/postfix
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const pid
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const all
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const double-bounce
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const nobody
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const hash:/etc/aliases
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const 20080902
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const 2.5.5
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const hash
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const deferred, defer
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const +
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: expand $mydestination -> mail.wendecodns.com, localhost, localhost.localdomain
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: expand $relay_domains -> mail.wendecodns.com, localhost, localhost.localdomain
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const TZ MAIL_CONFIG LANG
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const MAIL_CONFIG MAIL_DEBUG MAIL_LOGTAG TZ XAUTHORITY DISPLAY LANG=C
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const subnet
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const +=
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const -=+
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const debug_peer_list,fast_flush_domains,mynetworks,perm it_mx_backup_networks,qmqpd_authorized_clients,smt pd_access_maps
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const bounce
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const cleanup
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const defer
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const pickup
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const qmgr
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const rewrite
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const showq
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const error
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const flush
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const verify
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const trace
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const 100s
Jul 28 11:18:09 web1 last message repeated 3 times
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const 3600s
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const 3600s
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const 5s
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const 5s
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const 1000s
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const 1000s
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const 10s
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const 10s
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const 1s
Jul 28 11:18:09 web1 last message repeated 3 times
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const 500s
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const 500s
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const 18000s
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const 18000s
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const 1s
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const 1s
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const 127.0.0.0/8
Jul 28 11:18:09 web1 postfix/virtual[7865]: inet_addr_local: configured 4 IPv4 addresses
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const /var/mail
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const proxy:mysql:/etc/postfix/mysql-virtual_mailboxes.cf
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const static:5000
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const static:5000
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const /home/vmail/
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const fcntl, dotlock
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_eval: const yes
Jul 28 11:18:09 web1 postfix/virtual[7865]: process generation: 17 (17)
Jul 28 11:18:09 web1 postfix/virtual[7865]: match_string: fast_flush_domains ~? debug_peer_list
Jul 28 11:18:09 web1 postfix/virtual[7865]: match_string: fast_flush_domains ~? fast_flush_domains
Jul 28 11:18:09 web1 postfix/virtual[7865]: set_eugid: euid 105 egid 106
Jul 28 11:18:09 web1 postfix/virtual[7865]: connect to subsystem private/proxymap
Jul 28 11:18:09 web1 postfix/virtual[7865]: send attr request = open
Jul 28 11:18:09 web1 postfix/virtual[7865]: send attr table = mysql:/etc/postfix/mysql-virtual_mailboxes.cf
Jul 28 11:18:09 web1 postfix/virtual[7865]: send attr flags = 64
Jul 28 11:18:09 web1 postfix/virtual[7865]: private/proxymap socket: wanted attribute: status
Jul 28 11:18:09 web1 postfix/virtual[7865]: input attribute name: status
Jul 28 11:18:09 web1 postfix/virtual[7865]: input attribute value: 0
Jul 28 11:18:09 web1 postfix/virtual[7865]: private/proxymap socket: wanted attribute: flags
Jul 28 11:18:09 web1 postfix/virtual[7865]: input attribute name: flags
Jul 28 11:18:09 web1 postfix/virtual[7865]: input attribute value: 80
Jul 28 11:18:09 web1 postfix/virtual[7865]: private/proxymap socket: wanted attribute: (list terminator)
Jul 28 11:18:09 web1 postfix/virtual[7865]: input attribute name: (end)
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_proxy_open: connect to map=mysql:/etc/postfix/mysql-virtual_mailboxes.cf status=0 server_flags=fixed|lock
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_open: proxy:mysql:/etc/postfix/mysql-virtual_mailboxes.cf
Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_open: static:5000
Jul 28 11:18:09 web1 postfix/master[7686]: warning: process /usr/lib/postfix/virtual pid 7865 killed by signal 11
Jul 28 11:18:09 web1 postfix/master[7686]: warning: /usr/lib/postfix/virtual: bad command startup -- throttling

Could this be the issue?

Jul 28 11:18:09 web1 postfix/master[7686]: warning: process /usr/lib/postfix/virtual pid 7865 killed by signal 11
Jul 28 11:18:09 web1 postfix/master[7686]: warning: /usr/lib/postfix/virtual: bad command startup -- throttling

[Mark_NL]

Jul 28 11:18:09 web1 postfix/virtual[7865]: dict_open: proxy:mysql:/etc/postfix/mysql-virtual_mailboxes.cf

if i'm not mistaking, that should be:

mysql:/etc/postfix/mysql-virtual_mailboxes.cf

(in main.cf)

edit: Also, could you show me the contents of /etc/postfix/mysql-virtual_mailboxes.cf and the contents of /etc/hosts and the output of "netstat -tapn | grep 3306" ?

[Torsson]

Changing from:
proxy:mysql:/etc/postfix/mysql-virtual_mailboxes.cf
to
mysql:/etc/postfix/mysql-virtual_mailboxes.cf

did the trick, now every mail has been delivered.

Thank you very mutch for your help!

[Mark_NL]

awesome