[Dovecot] dict sql iterate failed: Not connected to database (was Re: backtrace from postgres dict)

[Steffen Kaiser]

-----BEGIN PGP SIGNED MESSAGE-----
Hash: SHA1

On Wed, 28 Nov 2012, Steffen Kaiser wrote:

hmm, it seems to be more than just an idle timeout.

since I enabled ACLs I get following backtrace. It looks like that it happens if the ACLs had been changed only. Also, it looks like that it always happens 1 minute after last "dict: Info: pgsql(localhost): Connected to database dovecot", no matter if the user is logged in (and idle) or logged off. Postgres logs: "could not receive data from client: Connection reset by peer" and "unexpected EOF on client connection".

Is there some TCP keep alive issue?
Postgres is using the "system default" for TCP keep alive.

I'm currently testing ACLs via telnet, so the activity with the ACL dict is pretty low, hence, an idle timeout would make much sense.

When I use this script:

( echo 1 login user pass
        sleep 1
        echo 2 getacl Junk
        sleep 1
        for a in $(seq 1 ${1-4}); do
                echo 3-$a deleteAcl Junk user2
                sleep 30
                echo 3-$a SetAcl Junk user2 kxilrsc
                sleep 30
        done
        echo + logout
) | nc -q1 localhost 143

I get (attached are more lines of the log):


scanning for dovecot-acl

scanning for dovecot-acl

scanning for dovecot-acl

scanning for dovecot-acl

After that the connection is broken and I get the backtrace eventually.

2012-11-28 11:08:07 dict: Info: pgsql(localhost): Connected to database dovecot 2012-11-28 11:09:07 dict: Info: pgsql(localhost): Connected to database dovecot 2012-11-28 11:09:37 dict: Info: pgsql(localhost): Connected to database dovecot 2012-11-28 11:10:07 dict: Error: dict sql iterate failed: Not connected to database 2012-11-28 11:10:07 IMAP(user) [23358]: Error: acl: dict iteration failed, can't update dict

# ../../sbin/dovecot --version
2.1.10 (a4f02f6c0d32)

dict connect string:
connect = host=localhost dbname=db user=user

-----BEGIN PGP SIGNATURE-----
Version: GnuPG v1.4.11 (GNU/Linux)

iQEVAwUBULXqy2oxLS8a3A9mAQL4+Af+PQIzpT0/kbot90jYRYmRBA96frDlDubc
lsl9iktKGYyxLOQXRLLcUpOgzWqUvxoLtsCMlkcOG8tjyQLr2OsAufnVUL7LUH8y
iGIi8lZjfHRUQWWLgC6KG0eNOMT5PEGLB5dBcqMZxsgENjoe0SEYf3SN2bT7UxR7
N6/cbU+qnQ/IVpCCcFTtY1Nv7pHcpBwt7IQqaLTdFJ2OQBtUCFsZlkH2SArBZktC
82rHKvHkN4rsYiOhKQFyG3p7mmdmq3snUpP9fvUE+2nxsIQw2BIht8gGPMsuVoTb
DC1JjE6JGuj4z3HrEs2tYRHgnTUuUn2j28bXIp2gNDLUQzbL5DLfLQ==
=XUQP
-----END PGP SIGNATURE-----

- -- Steffen Kaiser

acl-pg-error.log.gz
Description: Binary data