auth_passdb_request_finished request fires twice on IMAP login

[Aki Tuomi]

> On 19/09/2020 18:14 Eirik Rye <rye at trojka.no> wrote:
> 
>  
> Hi,
> 
> Dovecot v2.3.11.3 (502c39af9)
> 
> auth_passdb_request_finished appears to fire twice for an IMAP login 
> using a Redis passdb. auth_request_finished only fires once.
> 
> In the debug log below, the line "Finished passdb lookup" appears to be 
> logged twice (once by "auth" and once by "auth-worker"). However, the 
> actual passdb lookup against Redis is only performed once. This is 
> confirmed by monitoring queries made against Redis.
> 
> Configuration:
> --------------
> 
> metric auth_passdb_request_finished {
>    event_name = auth_passdb_request_finished
> }
> 
> auth_debug = yes
> passdb {
>    auth_verbose = yes
>    override_fields = proxy=y
>    name = redis
>    driver = dict
>    args = /etc/dovecot/dovecot-redis.conf.ext
> }
> 
> --------------
> 
> With the above configuration, I am seeing the 
> auth_passdb_request_finished metric being incremented twice for every 
> successful login:

<snip/>

> 
> Best regards,
> Eirik

You can add 

category = service:auth 

to your metric to make it fire only once.

Aki