Hi,
I'm trying to upgrade from 2.2 to 3.0, which I built from source on debian.
With libzdb2.10 and dbmail3 built from source I get the following when trying
to connect to my postgres server:
Failed to start connection pool -- FATAL: password authentication failed for
user "username"
Previously I've had no problems running dbmail with md5 Auth, now I've tried
md5 as well as password and always get this same error message. Installed
postgresql-client on the mail server just to check, and have no problem
connecting through that.
This is how I built libzdb-2.10:
/configure --with-potgresql=/usr/ --enable-openssl=/usr/ --prefix=/usr/
This is how I built dbmail:
../configure --with-zdb=/usr/ --with-sieve=/usr/ --with-ldap=/usr/
--prefix=/usr/ --exec-prefix=/usr/
Turned logging up to 511 then:
Starting dbmail as is:
root@mail:/etc# /etc/init.d/dbmail start
Starting dbmail servers: dbmail-imapdFeb 15 10:17:49 mail dbmail-imapd[26801]:
[0xc69f50] Debug:[server] server_config_load(+869): timeout [4000] seconds
Feb 15 10:17:49 mail dbmail-imapd[26801]: [0xc69f50] Debug:[server]
server_config_load(+880): login_timeout [60] seconds
Feb 15 10:17:49 mail dbmail-imapd[26801]: [0xc69f50] Debug:[server]
server_config_load(+885): no value for SOCKET in config file
Feb 15 10:17:49 mail dbmail-imapd[26801]: [0xc69f50] Debug:[server]
server_config_load(+887): socket []
Feb 15 10:17:49 mail dbmail-imapd[26801]: [0xc69f50] Debug:[server]
server_config_load(+897): binding to PORT []
Feb 15 10:17:49 mail dbmail-imapd[26801]: [0xc69f50] Debug:[server]
server_config_load(+901): binding to SSL_PORT [993]
Feb 15 10:17:49 mail dbmail-imapd[26801]: [0xc69f50] Debug:[server]
server_config_load(+925): binding to IP [0.0.0.0]
Feb 15 10:17:49 mail dbmail-imapd[26801]: [0xc69f50] Debug:[server]
server_config_load(+931): no value for BACKLOG in config file. Using default
value [16]
Feb 15 10:17:49 mail dbmail-imapd[26801]: [0xc69f50] Debug:[server]
server_config_load(+941): resolving client IP
Feb 15 10:17:49 mail dbmail-imapd[26801]: [0xc69f50] Debug:[server]
server_config_load(+950): Disabling IMAP-before-SMTP
Feb 15 10:17:49 mail dbmail-imapd[26801]: [0xc69f50] Debug:[server]
server_config_load(+957): Enabling IMAP Authentication logging
Feb 15 10:17:49 mail dbmail-imapd[26801]: [0xc69f50] Debug:[server]
server_config_load(+968): effective user shall be [dbmail]
Feb 15 10:17:49 mail dbmail-imapd[26801]: [0xc69f50] Debug:[server]
server_config_load(+978): effective group shall be [dbmail]
Feb 15 10:17:49 mail dbmail-imapd[26801]: [0xc69f50] Debug:[server]
server_config_load(+987): CA file is set to [/etc/postfix/certs/cacert.pem]
Feb 15 10:17:49 mail dbmail-imapd[26801]: [0xc69f50] Debug:[server]
server_config_load(+996): Certificate file is set to
[/etc/postfix/certs/servercrt.pem]
Feb 15 10:17:49 mail dbmail-imapd[26801]: [0xc69f50] Debug:[server]
server_config_load(+1005): Key file is set to [/etc/postfix/certs/erverkey.pem]
Feb 15 10:17:49 mail dbmail-imapd[26801]: [0xc69f50] Info:[server]
server_config_load(+1010): no value for TLS_CIPHERS in config file
Feb 15 10:17:49 mail dbmail-imapd[26801]: [0xc69f50] Debug:[server]
server_config_load(+1014): Cipher string is set to []
dbmail-lmtpdFeb 15 10:17:49 mail dbmail-lmtpd[26803]: [0x12fbf50]
Debug:[server] server_config_load(+869): timeout [300] seconds
Feb 15 10:17:49 mail dbmail-lmtpd[26803]: [0x12fbf50] Debug:[server]
server_config_load(+880): login_timeout [60] seconds
Feb 15 10:17:49 mail dbmail-lmtpd[26803]: [0x12fbf50] Debug:[server]
server_config_load(+885): no value for SOCKET in config file
Feb 15 10:17:49 mail dbmail-lmtpd[26803]: [0x12fbf50] Debug:[server]
server_config_load(+887): socket []
Feb 15 10:17:49 mail dbmail-lmtpd[26803]: [0x12fbf50] Debug:[server]
server_config_load(+897): binding to PORT [24]
Feb 15 10:17:49 mail dbmail-lmtpd[26803]: [0x12fbf50] Debug:[server]
server_config_load(+925): binding to IP [0.0.0.0]
Feb 15 10:17:49 mail dbmail-lmtpd[26803]: [0x12fbf50] Debug:[server]
server_config_load(+931): no value for BACKLOG in config file. Using default
value [16]
Feb 15 10:17:49 mail dbmail-lmtpd[26803]: [0x12fbf50] Debug:[server]
server_config_load(+941): resolving client IP
Feb 15 10:17:49 mail dbmail-lmtpd[26803]: [0x12fbf50] Debug:[server]
server_config_load(+948): no value for LMTP_BEFORE_SMTP in config file
Feb 15 10:17:49 mail dbmail-lmtpd[26803]: [0x12fbf50] Debug:[server]
server_config_load(+950): Disabling LMTP-before-SMTP
Feb 15 10:17:49 mail dbmail-lmtpd[26803]: [0x12fbf50] Debug:[server]
server_config_load(+957): Enabling LMTP Authentication logging
Feb 15 10:17:49 mail dbmail-lmtpd[26803]: [0x12fbf50] Debug:[server]
server_config_load(+968): effective user shall be [dbmail]
Feb 15 10:17:49 mail dbmail-lmtpd[26803]: [0x12fbf50] Debug:[server]
server_config_load(+978): effective group shall be [dbmail]
Feb 15 10:17:49 mail dbmail-lmtpd[26803]: [0x12fbf50] Debug:[server]
server_config_load(+987): CA file is set to [/etc/postfix/certs/cacert.pem]
Feb 15 10:17:49 mail dbmail-lmtpd[26803]: [0x12fbf50] Debug:[server]
server_config_load(+996): Certificate file is set to
[/etc/postfix/certs/servercrt.pem]
Feb 15 10:17:49 mail dbmail-lmtpd[26803]: [0x12fbf50] Debug:[server]
server_config_load(+1005): Key file is set to [/etc/postfix/certs/erverkey.pem]
Feb 15 10:17:49 mail dbmail-lmtpd[26803]: [0x12fbf50] Info:[server]
server_config_load(+1010): no value for TLS_CIPHERS in config file
Feb 15 10:17:49 mail dbmail-lmtpd[26803]: [0x12fbf50] Debug:[server]
server_config_load(+1014): Cipher string is set to []
dbmail-timsievedFeb 15 10:17:49 mail dbmail-timsieved[26806]: [0xc82f50]
Debug:[server] server_config_load(+869): timeout [300] seconds
Feb 15 10:17:49 mail dbmail-timsieved[26806]: [0xc82f50] Debug:[server]
server_config_load(+880): login_timeout [60] seconds
Feb 15 10:17:49 mail dbmail-timsieved[26806]: [0xc82f50] Debug:[server]
server_config_load(+885): no value for SOCKET in config file
Feb 15 10:17:49 mail dbmail-timsieved[26806]: [0xc82f50] Debug:[server]
server_config_load(+887): socket []
Feb 15 10:17:49 mail dbmail-timsieved[26806]: [0xc82f50] Debug:[server]
server_config_load(+897): binding to PORT []
Feb 15 10:17:49 mail dbmail-timsieved[26806]: [0xc82f50] Debug:[server]
server_config_load(+901): binding to SSL_PORT [2000]
Feb 15 10:17:49 mail dbmail-timsieved[26806]: [0xc82f50] Debug:[server]
server_config_load(+925): binding to IP [0.0.0.0]
Feb 15 10:17:49 mail dbmail-timsieved[26806]: [0xc82f50] Debug:[server]
server_config_load(+931): no value for BACKLOG in config file. Using default
value [16]
Feb 15 10:17:49 mail dbmail-timsieved[26806]: [0xc82f50] Debug:[server]
server_config_load(+941): resolving client IP
Feb 15 10:17:49 mail dbmail-timsieved[26806]: [0xc82f50] Debug:[server]
server_config_load(+948): no value for SIEVE_BEFORE_SMTP in config file
Feb 15 10:17:49 mail dbmail-timsieved[26806]: [0xc82f50] Debug:[server]
server_config_load(+950): Disabling SIEVE-before-SMTP
Feb 15 10:17:49 mail dbmail-timsieved[26806]: [0xc82f50] Debug:[server]
server_config_load(+957): Enabling SIEVE Authentication logging
Feb 15 10:17:49 mail dbmail-timsieved[26806]: [0xc82f50] Debug:[server]
server_config_load(+968): effective user shall be [dbmail]
Feb 15 10:17:49 mail dbmail-timsieved[26806]: [0xc82f50] Debug:[server]
server_config_load(+978): effective group shall be [dbmail]
Feb 15 10:17:49 mail dbmail-timsieved[26806]: [0xc82f50] Debug:[server]
server_config_load(+987): CA file is set to [/etc/postfix/certs/cacert.pem]
Feb 15 10:17:49 mail dbmail-timsieved[26806]: [0xc82f50] Debug:[server]
server_config_load(+996): Certificate file is set to
[/etc/postfix/certs/servercrt.pem]
Feb 15 10:17:49 mail dbmail-timsieved[26806]: [0xc82f50] Debug:[server]
server_config_load(+1005): Key file is set to [/etc/postfix/certs/erverkey.pem]
Feb 15 10:17:49 mail dbmail-timsieved[26806]: [0xc82f50] Info:[server]
server_config_load(+1010): no value for TLS_CIPHERS in config file
Feb 15 10:17:49 mail dbmail-timsieved[26806]: [0xc82f50] Debug:[server]
server_config_load(+1014): Cipher string is set to []
Feb 15 10:17:49 mail dbmail-timsieved[26806]: [0xc82f50] Debug:[sort]
sort_load_driver(+45): no value for library_directory, using default []
Feb 15 10:17:49 mail dbmail-timsieved[26806]: [0xc82f50] Debug:[sort]
sort_load_driver(+61): looking for sort_sieve as libsort_sieve.so
Feb 15 10:17:49 mail dbmail-timsieved[26806]: [0xc82f50] Debug:[sort]
sort_listextensions(+787): Sieve vacation enabled.
..
It doesn't give any errors, but its not started either, so I started digging
and tried starting dbmail-deliver and here it comes:
root@mail:/etc# /usr/sbin/dbmail-deliver -f /etc/dbmail.conf
Feb 15 10:20:21 mail dbmail-deliver[26826]: [0x1486040] Database:[db]
db_connect(+189): db at url:
[postgresql://dbhost:5432/mailDB?user=mailuser&password=password]
Feb 15 10:20:21 mail dbmail-deliver[26826]: [0x1486040] Info:[db]
db_connect(+201): database connection pool created with maximum connections of
[10]
Feb 15 10:20:21 mail dbmail-deliver[26826]: [0x1486040] Database:[db]
db_connect(+205): run a database connection reaper thread every [60] seconds
Failed to start connection pool -- FATAL: password authentication failed for
user "mailuser"
When however I connect using psql there is no error whatsoever:
root@mail:/etc# psql mailDB -U mailuser -h dbhost -p 5432
Password for user mailuser:
psql (8.4.10)
SSL connection (cipher: DHE-RSA-AES256-SHA, bits: 256)
Type "help" for help.
mailDB=>
dbhost is _not_ localhost, I am connecting by its DNS name though.
Any hints, am I doing something wrong here?
Julian
_______________________________________________
DBmail mailing list
DBmail@dbmail.org
http://mailman.fastxs.nl/cgi-bin/mailman/listinfo/dbmail
