note: I did not install any of this manually, this was all installed via the Virtualmin sh install script. I have reinstalled by their script 4 times and have this same issue everytime. Yet no one else seems to have ever delt with this?
Can't get postfix to work in combination with Dovecot, I'm pretty new to this, but this is the data I can think to provide for help on this.
Any help would be greatly appreciated,
The primary issue originally was that Postfix wouldn't even run, then I changed the following line in /etc/postfix/main.cf and added an = to the end.
"permit_sasl_authenticated , permit_mynetworks , reject_unauth_destination ="
as per the guidance of another because those variables are not initialized, it worked to get it running again.
Now I have postfix running but cannot "connect to storage server". If I use the wrong login credentials, I get "failed to login" If I use the right credentials I get "cannot connect to storage server" from roundcube, showing me that dovecot is working fine, but somewhere in transition to talking with Postfix there is a break. ( I assume )
I have no idea where to go from here.
I have excluded any data to other programs that are not affiliated, If I may have left something out, let me know.
I am not seeing anything from postfix in any of the loggs or pulled data though.
SS -tulpan
tcp LISTEN 0 0 *:25 *:* users:(("master",pid=596,fd=13))
tcp LISTEN 0 0 *:993 *:* users:(("dovecot",pid=881,fd=38))
tcp LISTEN 0 0 *:995 *:* users:(("dovecot",pid=881,fd=25))
tcp LISTEN 0 0 :::110 :::* users:(("dovecot",pid=881,fd=24))
tcp LISTEN 0 0 :::143 :::* users:(("dovecot",pid=881,fd=37))
tcp LISTEN 0 0 :::25 :::* users:(("master",pid=596,fd=14))
ss -anlp | grep :25
tcp LISTEN 0 0 *:25 *:* users:(("master",pid=574,fd=13))
tcp LISTEN 0 0 :::25 :::* users:(("master",pid=574,fd=14))
tcp LISTEN 0 0 *:25 *:* users:(("master",pid=574,fd=13))
tcp LISTEN 0 0 :::25 :::* users:(("master",pid=574,fd=14))
Here is ( ps aux | grep sasl ) Results
root 148 0.0 0.0 71192 976 ? Ss 03:11 0:00 /usr/sbin/saslauthd -m /run/saslauthd -a pam -n 2 -r
root 149 0.0 0.0 71192 720 ? S 03:11 0:00 /usr/sbin/saslauthd -m /run/saslauthd -a pam -n 2 -r
root 3135 0.0 0.0 8988 928 pts/0 S+ 04:11 0:00 grep --color=auto sasl
[root@dns ~]# ps auxw | grep saslauth
root 148 0.0 0.0 71192 976 ? Ss 03:11 0:00 /usr/sbin/saslauthd -m /run/saslauthd -a pam -n 2 -r
root 149 0.0 0.0 71192 720 ? S 03:11 0:00 /usr/sbin/saslauthd -m /run/saslauthd -a pam -n 2 -r
root 3137 0.0 0.0 8988 936 pts/0 S+ 04:12 0:00 grep --color=auto saslauth
Shouldn't information for Postfix be here also?
Recent logs from maillog
Dec 21 03:11:40 dns postfix/postfix-script[583]: starting the Postfix mail system
Dec 21 03:11:40 dns postfix/master[596]: daemon started -- version 2.10.1, configuration /etc/postfix
Dec 21 03:11:46 dns dovecot: master: Dovecot v2.2.10 starting up for imap, pop3 (core dumps disabled)
Dec 21 03:11:47 dns spamd[432]: spamd: server started on IO::Socket::IP [127.0.0.1]:783, IO::Socket::IP [::1]:783 (running version 3.4.0)
Dec 21 03:11:47 dns spamd[432]: spamd: server pid: 432
Dec 21 03:11:48 dns spamd[432]: spamd: server successfully spawned child process, pid 890
Dec 21 03:11:48 dns spamd[432]: spamd: server successfully spawned child process, pid 891
Dec 21 03:11:48 dns spamd[432]: prefork: child states: IS
Dec 21 03:11:48 dns spamd[432]: prefork: child states: II
Dec 21 04:53:52 dns dovecot: imap: Error: user (username): Mail access for users with UID 503 not permitted (see first_valid_uid in config file, uid from userdb lookup).
Dec 21 04:53:52 dns dovecot: imap: Error: Invalid user settings. Refer to server log for more information.
systemctl status postfix -l
Active: active (running) since Wed 2016-12-21 03:11:40 UTC; 3h 24min ago
Process: 161 ExecStart=/usr/sbin/postfix start (code=exited, status=0/SUCCESS)
Process: 150 ExecStartPre=/usr/libexec/postfix/chroot-update (code=exited, sta tus=0/SUCCESS)
Process: 119 ExecStartPre=/usr/libexec/postfix/aliasesdb (code=exited, status= 0/SUCCESS)
Main PID: 596 (master)
CGroup: /system.slice/postfix.service
|- 596 /usr/libexec/postfix/master -w
|- 618 qmgr -l -t unix -u
`-7229 pickup -l -t unix -u
Dec 21 03:11:35 dns systemd[1]: Starting Postfix Mail Transport Agent...
Dec 21 03:11:40 dns postfix/postfix-script[583]: starting the Postfix mail syste m
Dec 21 03:11:40 dns postfix/master[596]: daemon started -- version 2.10.1, confi guration /etc/postfix
Dec 21 03:11:40 dns systemd[1]: Started Postfix Mail Transport Agent.
Dec 21 05:04:04 dns postfix/smtpd[4801]: connect from rrcs-69-75-253-234.west.bi z.rr.com[69.75.253.234]
Dec 21 05:04:07 dns postfix/smtpd[4801]: warning: rrcs-69-75-253-234.west.biz.rr .com[69.75.253.234]: SASL LOGIN authentication failed: authentication failure
Dec 21 05:04:07 dns postfix/smtpd[4801]: disconnect from rrcs-69-75-253-234.west .biz.rr.com[69.75.253.234]
Dec 21 06:18:06 dns postfix/smtpd[6817]: connect from rrcs-69-75-253-234.west.bi z.rr.com[69.75.253.234]
Dec 21 06:18:08 dns postfix/smtpd[6817]: warning: rrcs-69-75-253-234.west.biz.rr .com[69.75.253.234]: SASL LOGIN authentication failed: authentication failure
Dec 21 06:18:08 dns postfix/smtpd[6817]: disconnect from rrcs-69-75-253-234.west .biz.rr.com[69.75.253.234]
This remote address that is trying to login via postfi/smtpd is not me, could this "attacker" have anything to do with it?
systemctl status dovecot -l
Active: active (running) since Wed 2016-12-21 03:11:46 UTC; 3h 25min ago
Process: 872 ExecStartPre=/usr/libexec/dovecot/prestartscript (code=exited, status=0/SUCCESS)
Main PID: 881 (dovecot)
CGroup: /system.slice/dovecot.service
|- 881 /usr/sbin/dovecot -F
|- 886 dovecot/anvil
|- 887 dovecot/log
|-4184 dovecot/config
`-4185 dovecot/auth
Dec 21 04:08:08 dns dovecot[887]: imap: Error: Invalid user settings. Refer to server log for more information.
Dec 21 04:40:10 dns auth[3830]: pam_unix(dovecot:auth): check pass; user unknown
Dec 21 04:40:10 dns auth[3830]: pam_unix(dovecot:auth): authentication failure; logname= uid=0 euid=0 tty=dovecot ruser=USERNAME rhost=::1
Dec 21 04:40:13 dns dovecot[887]: imap-login: Disconnected (auth failed, 1 attempts in 3 secs): user=<FULLUSERNAME>, method=PLAIN, rip=::1, lip=: :1, secured, session=<ppp6vyNEHAAAAAAAAAAAAAAAAAAAAAAB>
Dec 21 04:40:29 dns dovecot[887]: imap-login: Login: user=<FULLUSERNAME>, method=PLAIN, rip=::1, lip=::1, mpid=3833, secured, ses sion=<qptWwCNEiAAAAAAAAAAAAAAAAAAAAAAB>
Dec 21 04:40:29 dns dovecot[887]: imap: Error: user USERNAME: Mail access for users with UID 503 not permitted (see first_val id_uid in config file, uid from userdb lookup).
Dec 21 04:40:29 dns dovecot[887]: imap: Error: Invalid user settings. Refer to server log for more information.
Dec 21 04:53:52 dns dovecot[887]: imap-login: Login: user=<FULLUSERNAME>, method=PLAIN, rip=::1, lip=::1, mpid=4187, secured, ses sion=<odB38CNEKgAAAAAAAAAAAAAAAAAAAAAB>
Dec 21 04:53:52 dns dovecot[887]: imap: Error: user FULLUSERNAME: Mail access for users with UID 503 not permitted (see first_val id_uid in config file, uid from userdb lookup).
Dec 21 04:53:52 dns dovecot[887]: imap: Error: Invalid user settings. Refer to server log for more information.
Something to note: When I run "ss" I get a lot of "Cannot open netlink socket: Protocol not supported" apparently because Centos7 Does Not support all of the features that netlink did with ss. So it's possible that some of my collected data is no good. Let me know what you may need and I will provide it.
UPDATE: Postfix has once again gone down. Lol, with new information.
Active: failed (Result: exit-code) since Wed 2016-12-21 08:35:18 UTC; 25s ago
Process: 9028 ExecStop=/usr/sbin/postfix stop (code=exited, status=1/FAILURE)
Process: 12229 ExecStart=/usr/sbin/postfix start (code=exited, status=1/FAILURE)
Process: 12226 ExecStartPre=/usr/libexec/postfix/chroot-update (code=exited, status=0/SUCCESS)
Process: 12222 ExecStartPre=/usr/libexec/postfix/aliasesdb (code=exited, status=0/SUCCESS)
Main PID: 596 (code=killed, signal=TERM)
Dec 21 08:35:15 dns systemd[1]: Starting Postfix Mail Transport Agent...
Dec 21 08:35:16 dns postfix/postfix-script[12298]: starting the Postfix mail system
Dec 21 08:35:16 dns postfix/master[12300]: fatal: open lock file /var/lib/postfix/master.lock: unable to set exclusive lock: Resource temporarily unavailable
Dec 21 08:35:17 dns postfix/master[12299]: fatal: daemon initialization failure
Dec 21 08:35:18 dns postfix/postfix-script[12301]: fatal: mail system startup failed
Dec 21 08:35:18 dns systemd[1]: postfix.service: control process exited, code=exited status=1
Dec 21 08:35:18 dns systemd[1]: Failed to start Postfix Mail Transport Agent.
Dec 21 08:35:18 dns systemd[1]: Unit postfix.service entered failed state.
Dec 21 08:35:18 dns systemd[1]: postfix.service failed.
Thanks in advance for any help. Regards, Kyle