virtual user & domain problem

[laser144]

Hi,

Having followed the Virtual Users And Domains With Postfix, Courier And MySQL (Debian Etch)" (to the letterr I believe), I am having problems with both pop & smtp.

This is the error I get when trying to send mail:
PERM_FAILURE: SMTP Error (state 13): 554 5.7.1 <postmaster@quitter.nl>: Relay access denied

The pop errors are in the log below.

All help an/or pointers are most welcome.

Here is my mail log:

Jan 6 13:41:32 magistervela postfix/postfix-script: refreshing the Postfix mail system
Jan 6 13:41:32 magistervela postfix/master[22484]: reload configuration /etc/postfix
Jan 6 16:49:41 magistervela courierpop3login: Connection, ip=[::ffff:84.245.31.182]
Jan 6 16:50:02 magistervela courierpop3login: LOGIN FAILED, user=schipper, ip=[::ffff:84.245.31.182]
Jan 6 16:50:23 magistervela courierpop3login: LOGIN FAILED, user=schipper, ip=[::ffff:84.245.31.182]
Jan 6 16:51:29 magistervela courierpop3login: Disconnected, ip=[::ffff:84.245.31.182]
Jan 6 17:01:24 magistervela courierpop3login: Connection, ip=[::ffff:84.245.31.182]
Jan 6 17:01:29 magistervela courierpop3login: couriertls: accept: error:14094412:SSL routines:SSL3_READ_BYTES:sslv3 alert bad certificate
Jan 6 17:01:29 magistervela courierpop3login: Disconnected, ip=[::ffff:84.245.31.182]
Jan 6 17:12:33 magistervela courierpop3login: Connection, ip=[::ffff:84.245.31.182]
Jan 6 17:13:03 magistervela courierpop3login: chdir quitter.nl/anders/: No such file or directory
Jan 6 17:30:11 magistervela courierpop3login: Connection, ip=[::ffff:84.245.31.182]
Jan 6 17:30:29 magistervela courierpop3login: chdir quitter.nl/webmaster/: No such file or directory
Jan 6 17:30:41 magistervela courierpop3login: Connection, ip=[::ffff:84.245.31.182]
Jan 6 17:30:58 magistervela courierpop3login: chdir anders/anders/: No such file or directory
Jan 6 17:31:10 magistervela courierpop3login: Connection, ip=[::ffff:84.245.31.182]
Jan 6 17:31:26 magistervela courierpop3login: chdir sailmaster.nl/schipper/: No such file or directory
Jan 6 17:31:40 magistervela courierpop3login: Connection, ip=[::ffff:84.245.31.182]
Jan 6 17:31:48 magistervela courierpop3login: Disconnected, ip=[::ffff:84.245.31.182]
Jan 6 17:32:56 magistervela courierpop3login: Connection, ip=[::ffff:84.245.31.182]
Jan 6 17:32:56 magistervela courierpop3login: chdir anders/anders/: No such file or directory
Jan 6 17:39:48 magistervela courierpop3login: Connection, ip=[::ffff:84.245.31.182]
Jan 6 17:39:48 magistervela courierpop3login: chdir quitter.nl/webmaster/: No such file or directory
Jan 6 17:40:21 magistervela courierpop3login: Connection, ip=[::ffff:84.245.31.182]
Jan 6 17:40:50 magistervela courierpop3login: chdir sailmaster.nl/schipper/: No such file or directory
Jan 6 17:40:52 magistervela courierpop3login: Connection, ip=[::ffff:84.245.31.182]
Jan 6 17:40:52 magistervela courierpop3login: chdir quitter.nl/webmaster/: No such file or directory
Jan 6 17:41:09 magistervela courierpop3login: Connection, ip=[::ffff:84.245.31.182]
Jan 6 17:41:09 magistervela courierpop3login: chdir anders/anders/: No such file or directory
Jan 6 17:41:16 magistervela courierpop3login: Connection, ip=[::ffff:84.245.31.182]
Jan 6 17:41:17 magistervela courierpop3login: chdir sailmaster.nl/schipper/: No such file or directory
Jan 6 17:41:28 magistervela courierpop3login: Connection, ip=[::ffff:84.245.31.182]
Jan 6 17:41:37 magistervela courierpop3login: chdir sailmaster.nl/webmaster/: No such file or directory
Jan 6 17:42:56 magistervela courierpop3login: Connection, ip=[::ffff:84.245.31.182]
Jan 6 17:42:56 magistervela courierpop3login: LOGIN FAILED, user=anders, ip=[::ffff:84.245.31.182]
Jan 6 17:43:21 magistervela last message repeated 5 times
Jan 6 17:43:26 magistervela courierpop3login: Disconnected, ip=[::ffff:84.245.31.182]
Jan 6 17:45:47 magistervela postfix/postfix-script: refreshing the Postfix mail system
Jan 6 17:45:47 magistervela postfix/master[22484]: reload configuration /etc/postfix
Jan 6 17:48:23 magistervela courierpop3login: Connection, ip=[::ffff:84.245.31.182]
Jan 6 17:48:23 magistervela courierpop3login: chdir sailmaster.nl/webmaster/: No such file or directory
Jan 6 17:49:48 magistervela courierpop3login: Connection, ip=[::ffff:84.245.31.182]
Jan 6 17:49:48 magistervela courierpop3login: chdir quitter.nl/webmaster/: No such file or directory
Jan 6 17:50:21 magistervela courierpop3login: Connection, ip=[::ffff:84.245.31.182]
Jan 6 17:51:18 magistervela courierpop3login: chdir sailmaster.nl/schipper/: No such file or directory
Jan 6 17:59:48 magistervela courierpop3login: Connection, ip=[::ffff:84.245.31.182]
Jan 6 17:59:48 magistervela courierpop3login: chdir quitter.nl/webmaster/: No such file or directory
Jan 6 18:00:21 magistervela courierpop3login: Connection, ip=[::ffff:84.245.31.182]
Jan 6 18:07:03 magistervela courierpop3login: Unexpected SSL connection shutdown.
Jan 6 18:07:03 magistervela courierpop3login: Disconnected, ip=[::ffff:84.245.31.182]
Jan 6 18:09:39 magistervela courierpop3login: Connection, ip=[::ffff:84.245.31.182]
Jan 6 18:09:39 magistervela courierpop3login: chdir quitter.nl/webmaster/: No such file or directory
Jan 6 18:10:21 magistervela courierpop3login: Connection, ip=[::ffff:84.245.31.182]
Jan 6 18:10:21 magistervela courierpop3login: couriertls: accept: error:1408A10B:SSL routines:SSL3_GET_CLIENT_HELLO:wrong version number
Jan 6 18:10:21 magistervela courierpop3login: Disconnected, ip=[::ffff:84.245.31.182]
Jan 6 18:14:35 magistervela courierpop3login: Connection, ip=[::ffff:84.245.31.182]
Jan 6 18:14:43 magistervela courierpop3login: chdir quitter.nl/anders/: No such file or directory
Jan 6 18:14:50 magistervela courierpop3login: Connection, ip=[::ffff:84.245.31.182]


Thx,

Andy

[falko]

This is the error I get when trying to send mail:
PERM_FAILURE: SMTP Error (state 13): 554 5.7.1 <postmaster@quitter.nl>: Relay access denied
Please enable "Server requires authentication" in your email client.


Jan 6 17:13:03 magistervela courierpop3login: chdir quitter.nl/anders/: No such file or directory

You must send an email first to that user before you try to fetch email for the first time. The directories are created when the first email arrives.

[laser144]

Hi,

:confused: In order to make sure I did'n have any typos, I did a complete reinstall (copy/paste) from the tutorial. I now have these problems:

I still get the no dir exists warning when trying to log in, but mail sent from my GMail account doesn't bounce but times out:

Technical details of temporary failure:
TEMP_FAILURE: Could not initiate SMTP conversation with any hosts:
[mail.sailmaster.nl. (5): Connection timed out]

Mail sent to the second domain I set up bounces with the previously mentioned relay error
(I have entered 2 domains in mySQL - sailmaster.nl and quitter.nl. The hostname of my server is magistervela.sailmaster.nl.) The quitter.nl mail bounces.

Regards,

Anders

[falko]

Does mail.sailmaster.nl point to the right server? You can check that with dig mail.sailmaster.nl

[laser144]

Here is the output:

; <<>> DiG 9.3.4 <<>> mail.sailmaster.nl
;; global options: printcmd
;; Got answer:
;; ->>HEADER<<- opcode: QUERY, status: NOERROR, id: 51884
;; flags: qr rd ra; QUERY: 1, ANSWER: 1, AUTHORITY: 0, ADDITIONAL: 0

;; QUESTION SECTION:
;mail.sailmaster.nl. IN A

;; ANSWER SECTION:
mail.sailmaster.nl. 1 IN A 84.245.31.182

;; Query time: 281 msec
;; SERVER: 192.168.1.1#53(192.168.1.1)
;; WHEN: Tue Jan 8 18:18:21 2008
;; MSG SIZE rcvd: 52

Regards,

Anders

[falko]

Is 84.245.31.182 the correct IP?

[laser144]

Yes it is.

It just does not seem to make the new dir.
FYI: I did yet another clean install, still running in to the problem. Then I added Virtual Hosting With Proftpd And MySQL as described in the tutorial and there everything works like a charm. I guess I am missing something, but really don't know what...

Anders

[falko]

I can't connect either:

mh1:~# telnet mail.sailmaster.nl 25
Trying 84.245.31.182...

mh1:~#Are you sure that Postfix is running?
What's the output of netstat -tap and iptables -L?
Is the server behind a router? If so, did you forward port 25 from your router to the server?

[laser144]

netstat -tap
Active Internet connections (servers and established)
Proto Recv-Q Send-Q Local Address Foreign Address State PID/Program name
tcp 0 0 localhost.localdo:10024 *:* LISTEN 17442/amavisd (mast
tcp 0 0 localhost.localdo:10025 *:* LISTEN 17270/master
tcp 0 0 localhost.localdo:mysql *:* LISTEN 15536/mysqld
tcp 0 0 *:sunrpc *:* LISTEN 1660/portmap
tcp 0 0 *:auth *:* LISTEN 2008/inetd
tcp 0 0 *:ftp *:* LISTEN 7804/proftpd: (acce
tcp 0 0 *:smtp *:* LISTEN 17270/master
tcp 0 0 *:2974 *:* LISTEN 2049/rpc.statd
tcp6 0 0 *:imaps *:* LISTEN 15809/couriertcpd
tcp6 0 0 *:pop3s *:* LISTEN 15838/couriertcpd
tcp6 0 0 *:pop3 *:* LISTEN 15819/couriertcpd
tcp6 0 0 *:imap2 *:* LISTEN 15792/couriertcpd
tcp6 0 0 *:www *:* LISTEN 7371/apache2
tcp6 0 0 *:ssh *:* LISTEN 2030/sshd
tcp6 0 0 *:smtp *:* LISTEN 17270/master
tcp6 0 0 *:https *:* LISTEN 7371/apache2
tcp6 0 296 magistervela.sailma:ssh ::ffff:192.168.1.5:1472 ESTABLISHED20843/0



iptables -L
Chain INPUT (policy ACCEPT)
target prot opt source destination

Chain FORWARD (policy ACCEPT)
target prot opt source destination

Chain OUTPUT (policy ACCEPT)
target prot opt source destination


Ports 110 & 25 are forwarded

Regards,

Anders

[falko]

Please ask your ISP if they block port 25.

[laser144]

The don't block or filter the port. I checked my router/firewall and found I have exactly the same rule for my port 80 as I have for 25 & 110. Apache is perfectly accessable...

Anders

[falko]

And there are no errors in your mail log?

[laser144]

Here is the last bit of mail.log.

Regards,

Anders

Jan 18 00:00:07 magistervela postfix/virtual[4207]: 4094322C33A: to=<postmaster@sailmaster.nl>, orig_to=<root@magistervela.sailmaster.nl>, relay=virtual, delay=0.11, delays=0.0$
Jan 18 00:00:07 magistervela postfix/qmgr[2475]: 4094322C33A: removed
Jan 19 00:00:00 magistervela dccifd[2386]: 1.2.74 rejected messages to 0 targets and discarded messages to 0 targets among 0 total since 01/18/08 00:00:00
Jan 19 00:00:02 magistervela postfix/pickup[4939]: 1CE4022C33C: uid=0 from=<root>
Jan 19 00:00:02 magistervela postfix/cleanup[4945]: 1CE4022C33C: message-id=<20080118230002.1CE4022C33C@magistervela.sailmaster .nl>
Jan 19 00:00:02 magistervela postfix/qmgr[2475]: 1CE4022C33C: from=<root@magistervela.sailmaster.nl>, size=509, nrcpt=1 (queue active)
Jan 19 00:00:07 magistervela postfix/smtpd[4952]: connect from localhost.localdomain[127.0.0.1]
Jan 19 00:00:07 magistervela postfix/smtpd[4952]: 26A2F22C33B: client=localhost.localdomain[127.0.0.1]
Jan 19 00:00:07 magistervela postfix/cleanup[4945]: 26A2F22C33B: message-id=<20080118230002.1CE4022C33C@magistervela.sailmaster .nl>
Jan 19 00:00:07 magistervela postfix/qmgr[2475]: 26A2F22C33B: from=<root@magistervela.sailmaster.nl>, size=1012, nrcpt=1 (queue active)
Jan 19 00:00:07 magistervela amavis[2265]: (02265-02) Passed CLEAN, <root@magistervela.sailmaster.nl> -> <postmaster@domain.tld>, Message-ID: <20080118230002.1CE4022C33C@magist$
Jan 19 00:00:07 magistervela postfix/smtp[4948]: 1CE4022C33C: to=<postmaster@domain.tld>, relay=127.0.0.1[127.0.0.1]:10024, delay=5.6, delays=0.74/0.21/0.01/4.6, dsn=2.6.0, sta$
Jan 19 00:00:07 magistervela postfix/qmgr[2475]: 1CE4022C33C: removed
Jan 19 00:00:07 magistervela postfix/smtpd[4952]: disconnect from localhost.localdomain[127.0.0.1]
Jan 19 00:00:07 magistervela postfix/smtp[4953]: 26A2F22C33B: to=<postmaster@domain.tld>, relay=none, delay=0.2, delays=0.08/0.06/0.07/0, dsn=5.4.4, status=bounced (Host or dom$
Jan 19 00:00:07 magistervela postfix/cleanup[4945]: 683F022C33D: message-id=<20080118230007.683F022C33D@magistervela.sailmaster .nl>
Jan 19 00:00:07 magistervela postfix/qmgr[2475]: 683F022C33D: from=<>, size=3042, nrcpt=1 (queue active)
Jan 19 00:00:07 magistervela postfix/bounce[4955]: 26A2F22C33B: sender non-delivery notification: 683F022C33D
Jan 19 00:00:07 magistervela postfix/qmgr[2475]: 26A2F22C33B: removed
Jan 19 00:00:07 magistervela postfix/cleanup[4945]: 93EF022C33B: message-id=<20080118230007.683F022C33D@magistervela.sailmaster .nl>
Jan 19 00:00:07 magistervela postfix/qmgr[2475]: 93EF022C33B: from=<>, size=3196, nrcpt=1 (queue active)
Jan 19 00:00:07 magistervela postfix/local[4956]: 683F022C33D: to=<root@magistervela.sailmaster.nl>, relay=local, delay=0.2, delays=0.01/0.16/0/0.02, dsn=2.0.0, status=sent (fo$
Jan 19 00:00:07 magistervela postfix/qmgr[2475]: 683F022C33D: removed
Jan 19 00:00:07 magistervela postfix/virtual[4957]: 93EF022C33B: to=<postmaster@sailmaster.nl>, orig_to=<root@magistervela.sailmaster.nl>, relay=virtual, delay=0.11, delays=0.0$
Jan 19 00:00:07 magistervela postfix/qmgr[2475]: 93EF022C33B: removed

[falko]

I can't find any errors... :confused:

[laser144]

Neither can I, but I get this error massage when I send mail:

This is an automatically generated Delivery Status Notification

Delivery to the following recipient failed permanently:

schipper@sailmaster.nl

Technical details of permanent failure:
TEMP_FAILURE: Could not initiate SMTP conversation with any hosts:
[mail.sailmaster.nl. (5): Connection timed out]

[falko]

I still can't connect:
mh1:~# telnet 84.245.31.182 25
Trying 84.245.31.182...

mh1:~#
I'd check the router settings again and maybe restart it.

[laser144]

Thank you very much Falko!

It was the router after all.

Anders