v2.3.19.1 released

Mon Jun 20 21:43:57 UTC 2022

Hi Timo,

attached is the log with auth_debug=true from the starting process and 
running "doveadm auth test ralfimaptest at egroupware.org" and one other 
regular passdb lookup.

I replaced passwords and the customer email with XXXXXX.

I also run "doveadm user '*'" to test the iteration, which worked.

Ralf

Am 20.06.22 um 13:32 schrieb Ralf Becker:
> Hi Timo,  > > Am 20.06.22 um 12:17 schrieb Timo Sirainen: >> On 20. Jun 2022, at 
10.03, Ralf Becker <rb at egroupware.org> wrote: >>>> Fixes: Panic: file 
userdb-blocking.c: line 125 >>>> (userdb_blocking_iter_next): assertion 
failed: (ctx->conn != >>>> NULL) >>> As the above Panic is fixed I tried 
again (see my attached mail >>> to the 2.3.19 release) and I can confirm 
to no longer get the >>> Panic, BUT authentication is NOT working either 
:( >>> >>> Reverting back to a container with Dovecot 2.3.16, get's >>> 
everything working again. >>> >>> We use a hourly updated local SQLight 
database and a dict for >>> user- and passdb. >>> >>> Is the usage of 
multiple backends no longer supported, or did >>> something in that 
regard changed between 2.3.16 and 2.3.19.1? >> We have lots of tests 
using multiple backends for authentication, >> and lots of people are 
using many passdbs/userdbs in production. I >> was only aware of 
iteration being broken with multiple userdbs, >> since that's not used 
so much. And we added a test to verify that >> multiple userdb iteration 
is actually returning results from both >> userdbs, so that shouldn't be 
completely broken either. >> >> So I'd need more details of what exactly 
goes wrong and how. Is it >> the authentication or the iteration that is 
now broken? > > I only seen authentication errors in doveadm log errors 
and our > montioring trying to access the backend with user credentials. 
 > >> Logs with auth_debug=yes would likely help. > > I will get you the 
logs tonight, don't want to switch (one leg of) > the production system 
during daytime. I can then also try eg. doveadm > user -A to check the 
iteration. > >> Also: >> >>> Here's the relevant part of my config (full 
doveadm config -n is >>> attached): >>> >>> userdb { args = 
/etc/dovecot/dovecot-sql.conf driver = sql } >>> userdb { args = 
/etc/dovecot/dovecot-dict-auth.conf driver = >>> dict } passdb { args = 
 >>> /etc/dovecot/dovecot-dict-master-auth.conf driver = dict master = 
 >>> yes } passdb { args = /etc/dovecot/dovecot-dict-auth.conf driver 
 >>> = dict } >> What do these external conf files contain? > > 
/etc/dovecot/dovecot-sql.conf: > > driver = sqlite connect = 
/etc/dovecot/users.sqlite > > #password_query = SELECT userid AS 
username, domain, password \ # > FROM users WHERE userid = '%n' AND 
domain = '%d' #user_query = SELECT > home, uid, gid FROM users WHERE 
userid = '%n' AND domain = '%d' # > return no userdb, as db contains 
only user-names #user_query = SELECT > home,NULL AS uid,NULL AS gid FROM 
users WHERE userid = '%n' AND > domain = '%d' user_query = SELECT 
home,NULL AS uid,NULL AS gid, \ > '*:bytes='||(quota*1048576) AS 
quota_rule, \ userid||'@'||domain AS > master_user, \ 
LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS > acl_groups \ 
FROM users WHERE userid = '%n' AND domain = '%d' > > # For using doveadm 
-A: iterate_query = SELECT userid AS username, > domain FROM users > > 
/etc/dovecot/dovecot-dict-auth.conf: > > uri = 
proxy:/var/run/dovecot_auth_proxy/socket:somewhere #uri = > 
proxy:10.44.99.180:2001:somewhere > > password_key = passdb/%u/%w 
user_key = userdb/%u iterate_disable = > yes #iterate_disable = no 
#iterate_prefix = userdb/ > default_pass_scheme = md5 > > 
/etc/dovecot/dovecot-dict-master-auth.conf: > > uri = 
proxy:/var/run/dovecot_auth_proxy/socket:somewhere #uri = > 
proxy:10.44.99.180:2001:somewhere > > #password_key = 
master/%{login_domain}/%u/%w password_key = > master/%{login_user}/%u/%w 
iterate_disable = yes default_pass_scheme > = md5 > > Thanks :) > > Ralf >
-- 
Ralf Becker
EGroupware GmbH [www.egroupware.org]
Handelsregister HRB Kaiserslautern 3587
Geschäftsführer Birgit und Ralf Becker
Leibnizstr. 17, 67663 Kaiserslautern, Germany
Telefon +49 631 31657-0
-------------- next part --------------
Jun 20 21:22:35 master: Info: Dovecot v2.3.19.1 (9b53102964) starting up for imap, pop3, lmtp, sieve
Jun 20 21:22:35 auth: Debug: Loading modules from directory: /usr/lib/dovecot/modules/auth
Jun 20 21:22:35 auth: Debug: Module loaded: /usr/lib/dovecot/modules/auth/lib20_auth_var_expand_crypt.so
Jun 20 21:22:35 auth: Debug: Module loaded: /usr/lib/dovecot/modules/auth/libdriver_mysql.so
Jun 20 21:22:35 auth: Debug: Module loaded: /usr/lib/dovecot/modules/auth/libdriver_sqlite.so
Jun 20 21:22:35 auth: Debug: dict(proxy): dict created (uri=proxy:/var/run/dovecot_auth_proxy/socket:somewhere, base_dir=/var/run/dovecot)
Jun 20 21:22:35 auth: Debug: dict(proxy): dict created (uri=proxy:/var/run/dovecot_auth_proxy/socket:somewhere, base_dir=/var/run/dovecot)
Jun 20 21:22:35 auth: Debug: Wrote new auth token secret to /var/run/dovecot/auth-token-secret.dat
Jun 20 21:22:35 auth: Debug: auth client connected (pid=9)
Jun 20 21:22:35 auth: Debug: master in: LIST	1	user=*	service=replicator
Jun 20 21:22:35 auth: Debug: auth client connected (pid=13)
Jun 20 21:22:35 auth: Debug: auth client connected (pid=15)
Jun 20 21:22:35 auth: Debug: auth client connected (pid=14)
Jun 20 21:22:35 auth: Debug: auth client connected (pid=12)
Jun 20 21:22:35 auth-worker(20): Debug: Loading modules from directory: /usr/lib/dovecot/modules/auth
Jun 20 21:22:35 auth-worker(20): Debug: Module loaded: /usr/lib/dovecot/modules/auth/lib20_auth_var_expand_crypt.so
Jun 20 21:22:35 auth-worker(20): Debug: Module loaded: /usr/lib/dovecot/modules/auth/libdriver_mysql.so
Jun 20 21:22:35 auth-worker(20): Debug: Module loaded: /usr/lib/dovecot/modules/auth/libdriver_sqlite.so
Jun 20 21:22:35 auth-worker(20): Debug: dict(proxy): dict created (uri=proxy:/var/run/dovecot_auth_proxy/socket:somewhere, base_dir=/var/run/dovecot)
Jun 20 21:22:35 auth-worker(20): Debug: dict(proxy): dict created (uri=proxy:/var/run/dovecot_auth_proxy/socket:somewhere, base_dir=/var/run/dovecot)
Jun 20 21:22:35 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): Server accepted connection (fd=14)
Jun 20 21:22:35 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): Sending version handshake
Jun 20 21:22:35 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: Handling LIST request
Jun 20 21:22:35 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: sql(*): Performing userdb lookup
Jun 20 21:22:35 auth-worker(20): Debug: sqlite: Finished query 'SELECT userid AS username, domain FROM users' in 0 msecs
Jun 20 21:22:35 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: sql(*): SELECT userid AS username, domain FROM users
Jun 20 21:22:35 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: sql(*): Finished userdb lookup
Jun 20 21:22:35 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: Finished
Jun 20 21:22:35 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<2>: Handling LIST request
Jun 20 21:22:35 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<2>: dict(*): Performing userdb lookup
Jun 20 21:22:35 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<2>: dict(*): Finished userdb lookup
Jun 20 21:22:35 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<2>: Finished
Jun 20 21:22:35 auth: Debug: master in: PASS	1	arash 2student at bb-trunk.egroupware.de	service=doveadm
Jun 20 21:22:35 auth: Info: Username character disallowed by auth_username_chars: 0x20 (username: arash 2student at bb-trunk.egroupware.de)
Jun 20 21:22:35 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:35 auth: Debug: master in: USER	2	arash 2student at bb-trunk.egroupware.de	service=doveadm
Jun 20 21:22:35 auth: Info: Username character disallowed by auth_username_chars: 0x20 (username: arash 2student at bb-trunk.egroupware.de)
Jun 20 21:22:35 auth: Debug: userdb out: NOTFOUND	2
Jun 20 21:22:35 doveadm(arash 2student at bb-trunk.egroupware.de): Error: User doesn't exist
Jun 20 21:22:35 auth: Debug: master in: PASS	1	zhou at zbt.de	service=doveadm
Jun 20 21:22:35 auth: Debug: dict(zhou at zbt.de): Performing passdb lookup
Jun 20 21:22:35 auth: Debug: dict(zhou at zbt.de): cache miss
Jun 20 21:22:35 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<3>: Handling PASSL request
Jun 20 21:22:35 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<3>: dict(zhou at zbt.de): Performing passdb lookup
Jun 20 21:22:35 auth-worker(20): Debug: dict(proxy): Looking up 'shared/passdb/zhou at zbt.de/'
Jun 20 21:22:35 auth-worker(20): Debug: dict(proxy): Looking up (async) 'shared/passdb/zhou at zbt.de/'
Jun 20 21:22:35 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket: Connecting
Jun 20 21:22:35 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:35 auth: Debug: master in: PASS	1	ysv at zbt.de	service=doveadm
Jun 20 21:22:35 auth: Debug: dict(ysv at zbt.de): Performing passdb lookup
Jun 20 21:22:35 auth: Debug: dict(ysv at zbt.de): cache miss
Jun 20 21:22:35 auth-worker(25): Debug: Loading modules from directory: /usr/lib/dovecot/modules/auth
Jun 20 21:22:35 auth-worker(25): Debug: Module loaded: /usr/lib/dovecot/modules/auth/lib20_auth_var_expand_crypt.so
Jun 20 21:22:35 auth-worker(25): Debug: Module loaded: /usr/lib/dovecot/modules/auth/libdriver_mysql.so
Jun 20 21:22:35 auth-worker(25): Debug: Module loaded: /usr/lib/dovecot/modules/auth/libdriver_sqlite.so
Jun 20 21:22:35 auth-worker(25): Debug: dict(proxy): dict created (uri=proxy:/var/run/dovecot_auth_proxy/socket:somewhere, base_dir=/var/run/dovecot)
Jun 20 21:22:35 auth-worker(25): Debug: dict(proxy): dict created (uri=proxy:/var/run/dovecot_auth_proxy/socket:somewhere, base_dir=/var/run/dovecot)
Jun 20 21:22:35 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): Server accepted connection (fd=14)
Jun 20 21:22:35 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): Sending version handshake
Jun 20 21:22:35 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: Handling PASSL request
Jun 20 21:22:35 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: dict(ysv at zbt.de): Performing passdb lookup
Jun 20 21:22:35 auth-worker(25): Debug: dict(proxy): Looking up 'shared/passdb/ysv at zbt.de/'
Jun 20 21:22:35 auth-worker(25): Debug: dict(proxy): Looking up (async) 'shared/passdb/ysv at zbt.de/'
Jun 20 21:22:35 auth-worker(25): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket: Connecting
Jun 20 21:22:35 auth-worker(25): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:35 auth: Debug: master in: PASS	1	ykr at zbt.de	service=doveadm
Jun 20 21:22:35 auth: Debug: dict(ykr at zbt.de): Performing passdb lookup
Jun 20 21:22:35 auth: Debug: dict(ykr at zbt.de): cache miss
Jun 20 21:22:35 auth-worker(27): Debug: Loading modules from directory: /usr/lib/dovecot/modules/auth
Jun 20 21:22:35 auth-worker(27): Debug: Module loaded: /usr/lib/dovecot/modules/auth/lib20_auth_var_expand_crypt.so
Jun 20 21:22:35 auth: Debug: master in: PASS	1	wzl at zbt.de	service=doveadm
Jun 20 21:22:35 auth: Debug: dict(wzl at zbt.de): Performing passdb lookup
Jun 20 21:22:35 auth: Debug: dict(wzl at zbt.de): cache miss
Jun 20 21:22:35 auth-worker(27): Debug: Module loaded: /usr/lib/dovecot/modules/auth/libdriver_mysql.so
Jun 20 21:22:35 auth-worker(27): Debug: Module loaded: /usr/lib/dovecot/modules/auth/libdriver_sqlite.so
Jun 20 21:22:35 auth-worker(27): Debug: dict(proxy): dict created (uri=proxy:/var/run/dovecot_auth_proxy/socket:somewhere, base_dir=/var/run/dovecot)
Jun 20 21:22:35 auth-worker(27): Debug: dict(proxy): dict created (uri=proxy:/var/run/dovecot_auth_proxy/socket:somewhere, base_dir=/var/run/dovecot)
Jun 20 21:22:35 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): Server accepted connection (fd=14)
Jun 20 21:22:35 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): Sending version handshake
Jun 20 21:22:35 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: Handling PASSL request
Jun 20 21:22:35 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: dict(ykr at zbt.de): Performing passdb lookup
Jun 20 21:22:35 auth-worker(27): Debug: dict(proxy): Looking up 'shared/passdb/ykr at zbt.de/'
Jun 20 21:22:35 auth-worker(27): Debug: dict(proxy): Looking up (async) 'shared/passdb/ykr at zbt.de/'
Jun 20 21:22:35 auth-worker(27): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket: Connecting
Jun 20 21:22:35 auth-worker(27): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:35 auth-worker(29): Debug: Loading modules from directory: /usr/lib/dovecot/modules/auth
Jun 20 21:22:35 auth-worker(29): Debug: Module loaded: /usr/lib/dovecot/modules/auth/lib20_auth_var_expand_crypt.so
Jun 20 21:22:35 auth: Debug: master in: PASS	1	wpi at zbt.de	service=doveadm
Jun 20 21:22:35 auth: Debug: dict(wpi at zbt.de): Performing passdb lookup
Jun 20 21:22:35 auth: Debug: dict(wpi at zbt.de): cache miss
Jun 20 21:22:35 auth-worker(29): Debug: Module loaded: /usr/lib/dovecot/modules/auth/libdriver_mysql.so
Jun 20 21:22:35 auth-worker(29): Debug: Module loaded: /usr/lib/dovecot/modules/auth/libdriver_sqlite.so
Jun 20 21:22:35 auth-worker(29): Debug: dict(proxy): dict created (uri=proxy:/var/run/dovecot_auth_proxy/socket:somewhere, base_dir=/var/run/dovecot)
Jun 20 21:22:35 auth-worker(29): Debug: dict(proxy): dict created (uri=proxy:/var/run/dovecot_auth_proxy/socket:somewhere, base_dir=/var/run/dovecot)
Jun 20 21:22:35 auth-worker(29): Debug: conn unix:auth-worker (pid=19,uid=90): Server accepted connection (fd=14)
Jun 20 21:22:35 auth-worker(29): Debug: conn unix:auth-worker (pid=19,uid=90): Sending version handshake
Jun 20 21:22:35 auth-worker(29): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: Handling PASSL request
Jun 20 21:22:35 auth-worker(29): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: dict(wzl at zbt.de): Performing passdb lookup
Jun 20 21:22:35 auth-worker(29): Debug: dict(proxy): Looking up 'shared/passdb/wzl at zbt.de/'
Jun 20 21:22:35 auth-worker(29): Debug: dict(proxy): Looking up (async) 'shared/passdb/wzl at zbt.de/'
Jun 20 21:22:35 auth-worker(29): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket: Connecting
Jun 20 21:22:35 auth-worker(29): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:35 auth-worker(31): Debug: Loading modules from directory: /usr/lib/dovecot/modules/auth
Jun 20 21:22:35 auth-worker(31): Debug: Module loaded: /usr/lib/dovecot/modules/auth/lib20_auth_var_expand_crypt.so
Jun 20 21:22:35 auth: Debug: master in: PASS	1	vwr at zbt.de	service=doveadm
Jun 20 21:22:35 auth: Debug: dict(vwr at zbt.de): Performing passdb lookup
Jun 20 21:22:35 auth: Debug: dict(vwr at zbt.de): cache miss
Jun 20 21:22:35 auth-worker(31): Debug: Module loaded: /usr/lib/dovecot/modules/auth/libdriver_mysql.so
Jun 20 21:22:35 auth-worker(31): Debug: Module loaded: /usr/lib/dovecot/modules/auth/libdriver_sqlite.so
Jun 20 21:22:35 auth-worker(31): Debug: dict(proxy): dict created (uri=proxy:/var/run/dovecot_auth_proxy/socket:somewhere, base_dir=/var/run/dovecot)
Jun 20 21:22:35 auth-worker(31): Debug: dict(proxy): dict created (uri=proxy:/var/run/dovecot_auth_proxy/socket:somewhere, base_dir=/var/run/dovecot)
Jun 20 21:22:35 auth-worker(31): Debug: conn unix:auth-worker (pid=19,uid=90): Server accepted connection (fd=14)
Jun 20 21:22:35 auth-worker(31): Debug: conn unix:auth-worker (pid=19,uid=90): Sending version handshake
Jun 20 21:22:35 auth-worker(31): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: Handling PASSL request
Jun 20 21:22:35 auth-worker(31): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: dict(wpi at zbt.de): Performing passdb lookup
Jun 20 21:22:35 auth-worker(31): Debug: dict(proxy): Looking up 'shared/passdb/wpi at zbt.de/'
Jun 20 21:22:35 auth-worker(31): Debug: dict(proxy): Looking up (async) 'shared/passdb/wpi at zbt.de/'
Jun 20 21:22:35 auth-worker(31): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket: Connecting
Jun 20 21:22:35 auth-worker(31): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:35 auth-worker(33): Debug: Loading modules from directory: /usr/lib/dovecot/modules/auth
Jun 20 21:22:35 auth-worker(33): Debug: Module loaded: /usr/lib/dovecot/modules/auth/lib20_auth_var_expand_crypt.so
Jun 20 21:22:35 auth-worker(33): Debug: Module loaded: /usr/lib/dovecot/modules/auth/libdriver_mysql.so
Jun 20 21:22:35 auth-worker(33): Debug: Module loaded: /usr/lib/dovecot/modules/auth/libdriver_sqlite.so
Jun 20 21:22:35 auth-worker(33): Debug: dict(proxy): dict created (uri=proxy:/var/run/dovecot_auth_proxy/socket:somewhere, base_dir=/var/run/dovecot)
Jun 20 21:22:35 auth-worker(33): Debug: dict(proxy): dict created (uri=proxy:/var/run/dovecot_auth_proxy/socket:somewhere, base_dir=/var/run/dovecot)
Jun 20 21:22:35 auth-worker(33): Debug: conn unix:auth-worker (pid=19,uid=90): Server accepted connection (fd=14)
Jun 20 21:22:35 auth-worker(33): Debug: conn unix:auth-worker (pid=19,uid=90): Sending version handshake
Jun 20 21:22:35 auth-worker(33): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: Handling PASSL request
Jun 20 21:22:35 auth-worker(33): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: dict(vwr at zbt.de): Performing passdb lookup
Jun 20 21:22:35 auth-worker(33): Debug: dict(proxy): Looking up 'shared/passdb/vwr at zbt.de/'
Jun 20 21:22:35 auth-worker(33): Debug: dict(proxy): Looking up (async) 'shared/passdb/vwr at zbt.de/'
Jun 20 21:22:35 auth-worker(33): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket: Connecting
Jun 20 21:22:35 auth-worker(33): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:35 auth: Debug: master in: PASS	1	vpe at zbt.de	service=doveadm
Jun 20 21:22:35 auth: Debug: dict(vpe at zbt.de): Performing passdb lookup
Jun 20 21:22:35 auth: Debug: dict(vpe at zbt.de): cache miss
Jun 20 21:22:35 auth-worker(35): Debug: Loading modules from directory: /usr/lib/dovecot/modules/auth
Jun 20 21:22:35 auth-worker(35): Debug: Module loaded: /usr/lib/dovecot/modules/auth/lib20_auth_var_expand_crypt.so
Jun 20 21:22:35 auth-worker(35): Debug: Module loaded: /usr/lib/dovecot/modules/auth/libdriver_mysql.so
Jun 20 21:22:35 auth-worker(35): Debug: Module loaded: /usr/lib/dovecot/modules/auth/libdriver_sqlite.so
Jun 20 21:22:35 auth: Debug: master in: PASS	1	vmt at zbt.de	service=doveadm
Jun 20 21:22:35 auth: Debug: dict(vmt at zbt.de): Performing passdb lookup
Jun 20 21:22:35 auth: Debug: dict(vmt at zbt.de): cache miss
Jun 20 21:22:35 auth-worker(35): Debug: dict(proxy): dict created (uri=proxy:/var/run/dovecot_auth_proxy/socket:somewhere, base_dir=/var/run/dovecot)
Jun 20 21:22:35 auth-worker(35): Debug: dict(proxy): dict created (uri=proxy:/var/run/dovecot_auth_proxy/socket:somewhere, base_dir=/var/run/dovecot)
Jun 20 21:22:35 auth-worker(35): Debug: conn unix:auth-worker (pid=19,uid=90): Server accepted connection (fd=14)
Jun 20 21:22:35 auth-worker(35): Debug: conn unix:auth-worker (pid=19,uid=90): Sending version handshake
Jun 20 21:22:35 auth-worker(35): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: Handling PASSL request
Jun 20 21:22:35 auth-worker(35): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: dict(vpe at zbt.de): Performing passdb lookup
Jun 20 21:22:35 auth-worker(35): Debug: dict(proxy): Looking up 'shared/passdb/vpe at zbt.de/'
Jun 20 21:22:35 auth-worker(35): Debug: dict(proxy): Looking up (async) 'shared/passdb/vpe at zbt.de/'
Jun 20 21:22:35 auth-worker(35): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket: Connecting
Jun 20 21:22:35 auth-worker(35): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:35 auth-worker(37): Debug: Loading modules from directory: /usr/lib/dovecot/modules/auth
Jun 20 21:22:35 auth-worker(37): Debug: Module loaded: /usr/lib/dovecot/modules/auth/lib20_auth_var_expand_crypt.so
Jun 20 21:22:35 auth: Debug: master in: PASS	1	vlk at zbt.de	service=doveadm
Jun 20 21:22:35 auth: Debug: dict(vlk at zbt.de): Performing passdb lookup
Jun 20 21:22:35 auth: Debug: dict(vlk at zbt.de): cache miss
Jun 20 21:22:35 auth-worker(37): Debug: Module loaded: /usr/lib/dovecot/modules/auth/libdriver_mysql.so
Jun 20 21:22:35 auth-worker(37): Debug: Module loaded: /usr/lib/dovecot/modules/auth/libdriver_sqlite.so
Jun 20 21:22:35 auth-worker(37): Debug: dict(proxy): dict created (uri=proxy:/var/run/dovecot_auth_proxy/socket:somewhere, base_dir=/var/run/dovecot)
Jun 20 21:22:35 auth-worker(37): Debug: dict(proxy): dict created (uri=proxy:/var/run/dovecot_auth_proxy/socket:somewhere, base_dir=/var/run/dovecot)
Jun 20 21:22:35 auth-worker(37): Debug: conn unix:auth-worker (pid=19,uid=90): Server accepted connection (fd=14)
Jun 20 21:22:35 auth-worker(37): Debug: conn unix:auth-worker (pid=19,uid=90): Sending version handshake
Jun 20 21:22:35 auth-worker(37): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: Handling PASSL request
Jun 20 21:22:35 auth-worker(37): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: dict(vmt at zbt.de): Performing passdb lookup
Jun 20 21:22:35 auth-worker(37): Debug: dict(proxy): Looking up 'shared/passdb/vmt at zbt.de/'
Jun 20 21:22:35 auth-worker(37): Debug: dict(proxy): Looking up (async) 'shared/passdb/vmt at zbt.de/'
Jun 20 21:22:35 auth-worker(37): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket: Connecting
Jun 20 21:22:35 auth-worker(37): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:35 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/zhou at zbt.de/': not found
Jun 20 21:22:35 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/zhou at zbt.de/': not found
Jun 20 21:22:35 auth-worker(20): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<3>: dict(zhou at zbt.de): unknown user
Jun 20 21:22:35 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<3>: dict(zhou at zbt.de): Finished passdb lookup
Jun 20 21:22:35 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<3>: Finished
Jun 20 21:22:35 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:35 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<4>: Handling USER request
Jun 20 21:22:35 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<4>: sql(zhou at zbt.de): Performing userdb lookup
Jun 20 21:22:35 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<4>: sql(zhou at zbt.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'zhou' AND domain = 'zbt.de'
Jun 20 21:22:35 auth-worker(20): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'zhou' AND domain = 'zbt.de'' in 0 msecs
Jun 20 21:22:35 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<4>: sql(zhou at zbt.de): Finished userdb lookup
Jun 20 21:22:35 auth: Debug: dict(zhou at zbt.de): Finished passdb lookup
Jun 20 21:22:35 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:35 auth: Debug: master in: USER	2	zhou at zbt.de	service=doveadm
Jun 20 21:22:35 auth: Debug: sql(zhou at zbt.de): Performing userdb lookup
Jun 20 21:22:35 auth: Debug: sql(zhou at zbt.de): userdb cache miss
Jun 20 21:22:35 auth: Debug: sql(zhou at zbt.de): Finished userdb lookup
Jun 20 21:22:35 auth: Debug: userdb out: USER	2	zhou at zbt.de	home=/var/dovecot/imap/zbt.de/zhou	quota_rule=*:bytes=524288000	master_user=zhou at zbt.de	acl_groups=default at zbt.de,_zbt-studenten/innen at zbt.de,
Jun 20 21:22:35 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<4>: Finished
Jun 20 21:22:35 auth: Debug: master in: PASS	1	umz at zbt.de	service=doveadm
Jun 20 21:22:35 auth: Debug: dict(umz at zbt.de): Performing passdb lookup
Jun 20 21:22:35 auth: Debug: dict(umz at zbt.de): cache miss
Jun 20 21:22:35 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<5>: Handling PASSL request
Jun 20 21:22:35 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<5>: dict(umz at zbt.de): Performing passdb lookup
Jun 20 21:22:35 auth-worker(20): Debug: dict(proxy): Looking up 'shared/passdb/umz at zbt.de/'
Jun 20 21:22:35 auth-worker(20): Debug: dict(proxy): Looking up (async) 'shared/passdb/umz at zbt.de/'
Jun 20 21:22:35 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:35 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:35 auth-worker(39): Debug: Loading modules from directory: /usr/lib/dovecot/modules/auth
Jun 20 21:22:35 auth-worker(39): Debug: Module loaded: /usr/lib/dovecot/modules/auth/lib20_auth_var_expand_crypt.so
Jun 20 21:22:35 auth-worker(39): Debug: Module loaded: /usr/lib/dovecot/modules/auth/libdriver_mysql.so
Jun 20 21:22:35 auth-worker(39): Debug: Module loaded: /usr/lib/dovecot/modules/auth/libdriver_sqlite.so
Jun 20 21:22:35 auth-worker(39): Debug: dict(proxy): dict created (uri=proxy:/var/run/dovecot_auth_proxy/socket:somewhere, base_dir=/var/run/dovecot)
Jun 20 21:22:35 auth-worker(39): Debug: dict(proxy): dict created (uri=proxy:/var/run/dovecot_auth_proxy/socket:somewhere, base_dir=/var/run/dovecot)
Jun 20 21:22:35 auth-worker(39): Debug: conn unix:auth-worker (pid=19,uid=90): Server accepted connection (fd=14)
Jun 20 21:22:35 auth-worker(39): Debug: conn unix:auth-worker (pid=19,uid=90): Sending version handshake
Jun 20 21:22:35 auth-worker(39): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: Handling PASSL request
Jun 20 21:22:35 auth-worker(39): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: dict(vlk at zbt.de): Performing passdb lookup
Jun 20 21:22:35 auth-worker(39): Debug: dict(proxy): Looking up 'shared/passdb/vlk at zbt.de/'
Jun 20 21:22:35 auth-worker(39): Debug: dict(proxy): Looking up (async) 'shared/passdb/vlk at zbt.de/'
Jun 20 21:22:35 auth-worker(39): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket: Connecting
Jun 20 21:22:35 auth-worker(39): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:36 auth-worker(27): Debug: dict(proxy): Lookup finished for 'shared/passdb/ykr at zbt.de/': not found
Jun 20 21:22:36 auth-worker(27): Debug: dict(proxy): Lookup finished for 'shared/passdb/ykr at zbt.de/': not found
Jun 20 21:22:36 auth-worker(27): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: dict(ykr at zbt.de): unknown user
Jun 20 21:22:36 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: dict(ykr at zbt.de): Finished passdb lookup
Jun 20 21:22:36 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: Finished
Jun 20 21:22:36 auth-worker(27): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:36 auth: Debug: dict(ykr at zbt.de): Finished passdb lookup
Jun 20 21:22:36 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:36 auth: Debug: master in: USER	2	ykr at zbt.de	service=doveadm
Jun 20 21:22:36 auth: Debug: sql(ykr at zbt.de): Performing userdb lookup
Jun 20 21:22:36 auth: Debug: sql(ykr at zbt.de): userdb cache miss
Jun 20 21:22:36 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<2>: Handling USER request
Jun 20 21:22:36 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<2>: sql(ykr at zbt.de): Performing userdb lookup
Jun 20 21:22:36 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<2>: sql(ykr at zbt.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'ykr' AND domain = 'zbt.de'
Jun 20 21:22:36 auth-worker(27): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'ykr' AND domain = 'zbt.de'' in 0 msecs
Jun 20 21:22:36 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<2>: sql(ykr at zbt.de): Finished userdb lookup
Jun 20 21:22:36 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<2>: Finished
Jun 20 21:22:36 auth: Debug: sql(ykr at zbt.de): Finished userdb lookup
Jun 20 21:22:36 auth: Debug: userdb out: USER	2	ykr at zbt.de	home=/var/dovecot/imap/zbt.de/ygr	quota_rule=*:bytes=1048576000	master_user=ykr at zbt.de	acl_groups=_organisatorische einheiten at zbt.de,_uni-festangestellte at zbt.de,
Jun 20 21:22:36 auth-worker(25): Debug: dict(proxy): Lookup finished for 'shared/passdb/ysv at zbt.de/': not found
Jun 20 21:22:36 auth-worker(25): Debug: dict(proxy): Lookup finished for 'shared/passdb/ysv at zbt.de/': not found
Jun 20 21:22:36 auth-worker(25): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: dict(ysv at zbt.de): unknown user
Jun 20 21:22:36 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: dict(ysv at zbt.de): Finished passdb lookup
Jun 20 21:22:36 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: Finished
Jun 20 21:22:36 auth-worker(25): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:36 auth: Debug: dict(ysv at zbt.de): Finished passdb lookup
Jun 20 21:22:36 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:36 auth: Debug: master in: USER	2	ysv at zbt.de	service=doveadm
Jun 20 21:22:36 auth: Debug: sql(ysv at zbt.de): Performing userdb lookup
Jun 20 21:22:36 auth: Debug: sql(ysv at zbt.de): userdb cache miss
Jun 20 21:22:36 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<2>: Handling USER request
Jun 20 21:22:36 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<2>: sql(ysv at zbt.de): Performing userdb lookup
Jun 20 21:22:36 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<2>: sql(ysv at zbt.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'ysv' AND domain = 'zbt.de'
Jun 20 21:22:36 auth-worker(25): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'ysv' AND domain = 'zbt.de'' in 0 msecs
Jun 20 21:22:36 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<2>: sql(ysv at zbt.de): Finished userdb lookup
Jun 20 21:22:36 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<2>: Finished
Jun 20 21:22:36 auth: Debug: sql(ysv at zbt.de): Finished userdb lookup
Jun 20 21:22:36 auth: Debug: userdb out: USER	2	ysv at zbt.de	home=/var/dovecot/imap/zbt.de/ysv	quota_rule=*:bytes=4194304000	master_user=ysv at zbt.de	acl_groups=default at zbt.de,_alle-festangestellte at zbt.de,_organisatorische einheiten at zbt.de,_zbt-festangestellte at zbt.de,_zbt-abteilung_9 - ktd at zbt.de,
Jun 20 21:22:36 auth-worker(29): Debug: dict(proxy): Lookup finished for 'shared/passdb/wzl at zbt.de/': not found
Jun 20 21:22:36 auth-worker(29): Debug: dict(proxy): Lookup finished for 'shared/passdb/wzl at zbt.de/': not found
Jun 20 21:22:36 auth-worker(29): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: dict(wzl at zbt.de): unknown user
Jun 20 21:22:36 auth-worker(29): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: dict(wzl at zbt.de): Finished passdb lookup
Jun 20 21:22:36 auth-worker(29): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: Finished
Jun 20 21:22:36 auth-worker(29): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:36 auth: Debug: dict(wzl at zbt.de): Finished passdb lookup
Jun 20 21:22:36 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:36 auth: Debug: master in: USER	2	wzl at zbt.de	service=doveadm
Jun 20 21:22:36 auth: Debug: sql(wzl at zbt.de): Performing userdb lookup
Jun 20 21:22:36 auth: Debug: sql(wzl at zbt.de): userdb cache miss
Jun 20 21:22:36 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<3>: Handling USER request
Jun 20 21:22:36 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<3>: sql(wzl at zbt.de): Performing userdb lookup
Jun 20 21:22:36 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<3>: sql(wzl at zbt.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'wzl' AND domain = 'zbt.de'
Jun 20 21:22:36 auth-worker(25): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'wzl' AND domain = 'zbt.de'' in 0 msecs
Jun 20 21:22:36 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<3>: sql(wzl at zbt.de): Finished userdb lookup
Jun 20 21:22:36 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<3>: Finished
Jun 20 21:22:36 auth: Debug: sql(wzl at zbt.de): Finished userdb lookup
Jun 20 21:22:36 auth: Debug: userdb out: USER	2	wzl at zbt.de	home=/var/dovecot/imap/zbt.de/wzl	quota_rule=*:bytes=1048576000	master_user=wzl at zbt.de	acl_groups=default at zbt.de,
Jun 20 21:22:36 auth-worker(31): Debug: dict(proxy): Lookup finished for 'shared/passdb/wpi at zbt.de/': not found
Jun 20 21:22:36 auth-worker(31): Debug: dict(proxy): Lookup finished for 'shared/passdb/wpi at zbt.de/': not found
Jun 20 21:22:36 auth-worker(31): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: dict(wpi at zbt.de): unknown user
Jun 20 21:22:36 auth-worker(31): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: dict(wpi at zbt.de): Finished passdb lookup
Jun 20 21:22:36 auth-worker(31): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: Finished
Jun 20 21:22:36 auth-worker(31): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:36 auth: Debug: dict(wpi at zbt.de): Finished passdb lookup
Jun 20 21:22:36 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:36 auth: Debug: master in: USER	2	wpi at zbt.de	service=doveadm
Jun 20 21:22:36 auth: Debug: sql(wpi at zbt.de): Performing userdb lookup
Jun 20 21:22:36 auth: Debug: sql(wpi at zbt.de): userdb cache miss
Jun 20 21:22:36 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<4>: Handling USER request
Jun 20 21:22:36 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<4>: sql(wpi at zbt.de): Performing userdb lookup
Jun 20 21:22:36 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<4>: sql(wpi at zbt.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'wpi' AND domain = 'zbt.de'
Jun 20 21:22:36 auth-worker(25): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'wpi' AND domain = 'zbt.de'' in 0 msecs
Jun 20 21:22:36 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<4>: sql(wpi at zbt.de): Finished userdb lookup
Jun 20 21:22:36 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<4>: Finished
Jun 20 21:22:36 auth: Debug: sql(wpi at zbt.de): Finished userdb lookup
Jun 20 21:22:36 auth: Debug: userdb out: USER	2	wpi at zbt.de	home=/var/dovecot/imap/zbt.de/wpi	quota_rule=*:bytes=6291456000	master_user=wpi at zbt.de	acl_groups=_alle-festangestellte at zbt.de,_organisatorische einheiten at zbt.de,_zbt-festangestellte at zbt.de,_zbt-abteilung_4 - ek at zbt.de,
Jun 20 21:22:36 auth-worker(35): Debug: dict(proxy): Lookup finished for 'shared/passdb/vpe at zbt.de/': not found
Jun 20 21:22:36 auth-worker(35): Debug: dict(proxy): Lookup finished for 'shared/passdb/vpe at zbt.de/': not found
Jun 20 21:22:36 auth-worker(35): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: dict(vpe at zbt.de): unknown user
Jun 20 21:22:36 auth-worker(35): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: dict(vpe at zbt.de): Finished passdb lookup
Jun 20 21:22:36 auth-worker(35): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: Finished
Jun 20 21:22:36 auth-worker(35): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:36 auth: Debug: dict(vpe at zbt.de): Finished passdb lookup
Jun 20 21:22:36 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:36 auth: Debug: master in: USER	2	vpe at zbt.de	service=doveadm
Jun 20 21:22:36 auth: Debug: sql(vpe at zbt.de): Performing userdb lookup
Jun 20 21:22:36 auth: Debug: sql(vpe at zbt.de): userdb cache miss
Jun 20 21:22:36 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<5>: Handling USER request
Jun 20 21:22:36 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<5>: sql(vpe at zbt.de): Performing userdb lookup
Jun 20 21:22:36 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<5>: sql(vpe at zbt.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'vpe' AND domain = 'zbt.de'
Jun 20 21:22:36 auth-worker(25): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'vpe' AND domain = 'zbt.de'' in 0 msecs
Jun 20 21:22:36 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<5>: sql(vpe at zbt.de): Finished userdb lookup
Jun 20 21:22:36 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<5>: Finished
Jun 20 21:22:36 auth: Debug: sql(vpe at zbt.de): Finished userdb lookup
Jun 20 21:22:36 auth: Debug: userdb out: USER	2	vpe at zbt.de	home=/var/dovecot/imap/zbt.de/vpe	quota_rule=*:bytes=6291456000	master_user=vpe at zbt.de	acl_groups=_alle-festangestellte at zbt.de,_organisatorische einheiten at zbt.de,_zbt-abteilungsleitung at zbt.de,_zbt-abteilung_4 - ek at zbt.de,_zbt-festangestellte at zbt.de,
Jun 20 21:22:36 auth-worker(33): Debug: dict(proxy): Lookup finished for 'shared/passdb/vwr at zbt.de/': not found
Jun 20 21:22:36 auth-worker(33): Debug: dict(proxy): Lookup finished for 'shared/passdb/vwr at zbt.de/': not found
Jun 20 21:22:36 auth-worker(33): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: dict(vwr at zbt.de): unknown user
Jun 20 21:22:36 auth: Debug: dict(vwr at zbt.de): Finished passdb lookup
Jun 20 21:22:36 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:36 auth-worker(33): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: dict(vwr at zbt.de): Finished passdb lookup
Jun 20 21:22:36 auth-worker(33): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: Finished
Jun 20 21:22:36 auth-worker(33): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:36 auth: Debug: master in: USER	2	vwr at zbt.de	service=doveadm
Jun 20 21:22:36 auth: Debug: sql(vwr at zbt.de): Performing userdb lookup
Jun 20 21:22:36 auth: Debug: sql(vwr at zbt.de): userdb cache miss
Jun 20 21:22:36 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<6>: Handling USER request
Jun 20 21:22:36 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<6>: sql(vwr at zbt.de): Performing userdb lookup
Jun 20 21:22:36 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<6>: sql(vwr at zbt.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'vwr' AND domain = 'zbt.de'
Jun 20 21:22:36 auth-worker(25): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'vwr' AND domain = 'zbt.de'' in 0 msecs
Jun 20 21:22:36 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<6>: sql(vwr at zbt.de): Finished userdb lookup
Jun 20 21:22:36 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<6>: Finished
Jun 20 21:22:36 auth: Debug: sql(vwr at zbt.de): Finished userdb lookup
Jun 20 21:22:36 auth: Debug: userdb out: USER	2	vwr at zbt.de	home=/var/dovecot/imap/zbt.de/vwr	quota_rule=*:bytes=1048576000	master_user=vwr at zbt.de	acl_groups=default at zbt.de,
Jun 20 21:22:36 auth-worker(37): Debug: dict(proxy): Lookup finished for 'shared/passdb/vmt at zbt.de/': not found
Jun 20 21:22:36 auth-worker(37): Debug: dict(proxy): Lookup finished for 'shared/passdb/vmt at zbt.de/': not found
Jun 20 21:22:36 auth-worker(37): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: dict(vmt at zbt.de): unknown user
Jun 20 21:22:36 auth-worker(37): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: dict(vmt at zbt.de): Finished passdb lookup
Jun 20 21:22:36 auth-worker(37): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: Finished
Jun 20 21:22:36 auth-worker(37): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:36 auth: Debug: dict(vmt at zbt.de): Finished passdb lookup
Jun 20 21:22:36 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:36 auth: Debug: master in: USER	2	vmt at zbt.de	service=doveadm
Jun 20 21:22:36 auth: Debug: sql(vmt at zbt.de): Performing userdb lookup
Jun 20 21:22:36 auth: Debug: sql(vmt at zbt.de): userdb cache miss
Jun 20 21:22:36 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<7>: Handling USER request
Jun 20 21:22:36 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<7>: sql(vmt at zbt.de): Performing userdb lookup
Jun 20 21:22:36 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<7>: sql(vmt at zbt.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'vmt' AND domain = 'zbt.de'
Jun 20 21:22:36 auth-worker(25): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'vmt' AND domain = 'zbt.de'' in 0 msecs
Jun 20 21:22:36 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<7>: sql(vmt at zbt.de): Finished userdb lookup
Jun 20 21:22:36 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<7>: Finished
Jun 20 21:22:36 auth: Debug: sql(vmt at zbt.de): Finished userdb lookup
Jun 20 21:22:36 auth: Debug: userdb out: USER	2	vmt at zbt.de	home=/var/dovecot/imap/zbt.de/vmt	master_user=vmt at zbt.de	acl_groups=_alle-festangestellte at zbt.de,_organisatorische einheiten at zbt.de,_zbt-abteilung_6 - nmt at zbt.de,_zbt-festangestellte at zbt.de,
Jun 20 21:22:36 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/umz at zbt.de/': not found
Jun 20 21:22:36 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/umz at zbt.de/': not found
Jun 20 21:22:36 auth-worker(20): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<5>: dict(umz at zbt.de): unknown user
Jun 20 21:22:36 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<5>: dict(umz at zbt.de): Finished passdb lookup
Jun 20 21:22:36 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<5>: Finished
Jun 20 21:22:36 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:36 auth: Debug: dict(umz at zbt.de): Finished passdb lookup
Jun 20 21:22:36 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:36 auth: Debug: master in: USER	2	umz at zbt.de	service=doveadm
Jun 20 21:22:36 auth: Debug: sql(umz at zbt.de): Performing userdb lookup
Jun 20 21:22:36 auth: Debug: sql(umz at zbt.de): userdb cache miss
Jun 20 21:22:36 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<6>: Handling USER request
Jun 20 21:22:36 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<6>: sql(umz at zbt.de): Performing userdb lookup
Jun 20 21:22:36 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<6>: sql(umz at zbt.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'umz' AND domain = 'zbt.de'
Jun 20 21:22:36 auth-worker(20): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'umz' AND domain = 'zbt.de'' in 0 msecs
Jun 20 21:22:36 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<6>: sql(umz at zbt.de): Finished userdb lookup
Jun 20 21:22:36 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<6>: Finished
Jun 20 21:22:36 auth: Debug: sql(umz at zbt.de): Finished userdb lookup
Jun 20 21:22:36 auth: Debug: userdb out: USER	2	umz at zbt.de	home=/var/dovecot/imap/zbt.de/umz	quota_rule=*:bytes=6291456000	master_user=umz at zbt.de	acl_groups=_alle-festangestellte at zbt.de,_organisatorische einheiten at zbt.de,_zbt-festangestellte at zbt.de,_zbt-abteilung_3 - bzs at zbt.de,_zbt-abteilungsleitung at zbt.de,
Jun 20 21:22:36 auth-worker(39): Debug: dict(proxy): Lookup finished for 'shared/passdb/vlk at zbt.de/': not found
Jun 20 21:22:36 auth-worker(39): Debug: dict(proxy): Lookup finished for 'shared/passdb/vlk at zbt.de/': not found
Jun 20 21:22:36 auth-worker(39): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: dict(vlk at zbt.de): unknown user
Jun 20 21:22:36 auth-worker(39): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: dict(vlk at zbt.de): Finished passdb lookup
Jun 20 21:22:36 auth-worker(39): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<1>: Finished
Jun 20 21:22:36 auth-worker(39): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:36 auth: Debug: dict(vlk at zbt.de): Finished passdb lookup
Jun 20 21:22:36 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:36 auth: Debug: master in: USER	2	vlk at zbt.de	service=doveadm
Jun 20 21:22:36 auth: Debug: sql(vlk at zbt.de): Performing userdb lookup
Jun 20 21:22:36 auth: Debug: sql(vlk at zbt.de): userdb cache miss
Jun 20 21:22:36 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<7>: Handling USER request
Jun 20 21:22:36 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<7>: sql(vlk at zbt.de): Performing userdb lookup
Jun 20 21:22:36 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<7>: sql(vlk at zbt.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'vlk' AND domain = 'zbt.de'
Jun 20 21:22:36 auth-worker(20): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'vlk' AND domain = 'zbt.de'' in 0 msecs
Jun 20 21:22:36 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<7>: sql(vlk at zbt.de): Finished userdb lookup
Jun 20 21:22:36 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<7>: Finished
Jun 20 21:22:36 auth: Debug: sql(vlk at zbt.de): Finished userdb lookup
Jun 20 21:22:36 auth: Debug: userdb out: USER	2	vlk at zbt.de	home=/var/dovecot/imap/zbt.de/vlk	quota_rule=*:bytes=8388608000	master_user=vlk at zbt.de	acl_groups=_alle-festangestellte at zbt.de,_organisatorische einheiten at zbt.de,_zbt-festangestellte at zbt.de,_zbt-abteilung_6 - nmt at zbt.de,
Jun 20 21:22:36 auth: Debug: client in: AUTH	1	PLAIN	service=imap	session=cWj1s+fhYVYKCh4L	lip=172.18.0.2	rip=10.10.30.11	lport=143	rport=22113	resp=<hidden>
Jun 20 21:22:36 auth: Debug: dict(hadi at bb-trunk.egroupware.de,10.10.30.11,<cWj1s+fhYVYKCh4L>): Performing passdb lookup
Jun 20 21:22:36 auth: Debug: dict(hadi at bb-trunk.egroupware.de,10.10.30.11,<cWj1s+fhYVYKCh4L>): cache miss
Jun 20 21:22:36 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<8>: Handling PASSV request
Jun 20 21:22:36 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<8>: dict(hadi at bb-trunk.egroupware.de,10.10.30.11,<cWj1s+fhYVYKCh4L>): Performing passdb lookup
Jun 20 21:22:36 auth-worker(20): Debug: dict(proxy): Looking up 'shared/passdb/hadi at bb-trunk.egroupware.de/Secret1122!'
Jun 20 21:22:36 auth-worker(20): Debug: dict(proxy): Looking up (async) 'shared/passdb/hadi at bb-trunk.egroupware.de/Secret1122!'
Jun 20 21:22:36 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:36 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:36 auth: Debug: auth client connected (pid=40)
Jun 20 21:22:36 auth: Debug: master in: PASS	1	arash teacher at bb-trunk.egroupware.de	service=doveadm
Jun 20 21:22:36 auth: Info: Username character disallowed by auth_username_chars: 0x20 (username: arash teacher at bb-trunk.egroupware.de)
Jun 20 21:22:36 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:36 auth: Debug: master in: USER	2	arash teacher at bb-trunk.egroupware.de	service=doveadm
Jun 20 21:22:36 auth: Info: Username character disallowed by auth_username_chars: 0x20 (username: arash teacher at bb-trunk.egroupware.de)
Jun 20 21:22:36 auth: Debug: userdb out: NOTFOUND	2
Jun 20 21:22:36 doveadm(arash teacher at bb-trunk.egroupware.de): Error: User doesn't exist
Jun 20 21:22:36 auth: Debug: master in: PASS	1	twn at zbt.de	service=doveadm
Jun 20 21:22:36 auth: Debug: dict(twn at zbt.de): Performing passdb lookup
Jun 20 21:22:36 auth: Debug: dict(twn at zbt.de): cache miss
Jun 20 21:22:36 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<8>: Handling PASSL request
Jun 20 21:22:36 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<8>: dict(twn at zbt.de): Performing passdb lookup
Jun 20 21:22:36 auth-worker(25): Debug: dict(proxy): Looking up 'shared/passdb/twn at zbt.de/'
Jun 20 21:22:36 auth-worker(25): Debug: dict(proxy): Looking up (async) 'shared/passdb/twn at zbt.de/'
Jun 20 21:22:36 auth-worker(25): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:36 auth-worker(25): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:36 auth: Debug: master in: PASS	1	ugn at zbt.de	service=doveadm
Jun 20 21:22:36 auth: Debug: dict(ugn at zbt.de): Performing passdb lookup
Jun 20 21:22:36 auth: Debug: dict(ugn at zbt.de): cache miss
Jun 20 21:22:36 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<3>: Handling PASSL request
Jun 20 21:22:36 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<3>: dict(ugn at zbt.de): Performing passdb lookup
Jun 20 21:22:36 auth-worker(27): Debug: dict(proxy): Looking up 'shared/passdb/ugn at zbt.de/'
Jun 20 21:22:36 auth-worker(27): Debug: dict(proxy): Looking up (async) 'shared/passdb/ugn at zbt.de/'
Jun 20 21:22:36 auth-worker(27): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:36 auth-worker(27): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:36 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/hadi at bb-trunk.egroupware.de/Secret1122!': not found
Jun 20 21:22:36 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/hadi at bb-trunk.egroupware.de/Secret1122!': not found
Jun 20 21:22:36 auth-worker(20): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<8>: dict(hadi at bb-trunk.egroupware.de,10.10.30.11,<cWj1s+fhYVYKCh4L>): unknown user
Jun 20 21:22:36 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<8>: dict(hadi at bb-trunk.egroupware.de,10.10.30.11,<cWj1s+fhYVYKCh4L>): Finished passdb lookup
Jun 20 21:22:36 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<8>: Finished: user_unknown
Jun 20 21:22:36 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:36 auth: Debug: dict(hadi at bb-trunk.egroupware.de,10.10.30.11,<cWj1s+fhYVYKCh4L>): Finished passdb lookup
Jun 20 21:22:36 auth: Debug: auth(hadi at bb-trunk.egroupware.de,10.10.30.11,<cWj1s+fhYVYKCh4L>): Auth request finished
Jun 20 21:22:36 auth-worker(25): Debug: dict(proxy): Lookup finished for 'shared/passdb/twn at zbt.de/': not found
Jun 20 21:22:36 auth-worker(25): Debug: dict(proxy): Lookup finished for 'shared/passdb/twn at zbt.de/': not found
Jun 20 21:22:36 auth-worker(25): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<8>: dict(twn at zbt.de): unknown user
Jun 20 21:22:36 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<8>: dict(twn at zbt.de): Finished passdb lookup
Jun 20 21:22:36 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<8>: Finished
Jun 20 21:22:36 auth-worker(25): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:36 auth: Debug: dict(twn at zbt.de): Finished passdb lookup
Jun 20 21:22:36 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:36 auth: Debug: master in: USER	2	twn at zbt.de	service=doveadm
Jun 20 21:22:36 auth: Debug: sql(twn at zbt.de): Performing userdb lookup
Jun 20 21:22:36 auth: Debug: sql(twn at zbt.de): userdb cache miss
Jun 20 21:22:36 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<9>: Handling USER request
Jun 20 21:22:36 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<9>: sql(twn at zbt.de): Performing userdb lookup
Jun 20 21:22:36 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<9>: sql(twn at zbt.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'twn' AND domain = 'zbt.de'
Jun 20 21:22:36 auth-worker(20): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'twn' AND domain = 'zbt.de'' in 0 msecs
Jun 20 21:22:36 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<9>: sql(twn at zbt.de): Finished userdb lookup
Jun 20 21:22:36 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<9>: Finished
Jun 20 21:22:36 auth: Debug: sql(twn at zbt.de): Finished userdb lookup
Jun 20 21:22:36 auth: Debug: userdb out: USER	2	twn at zbt.de	home=/var/dovecot/imap/zbt.de/twn	master_user=twn at zbt.de	acl_groups=_alle-festangestellte at zbt.de,_organisatorische einheiten at zbt.de,_zbt-abteilung_7 - eb at zbt.de,_zbt-festangestellte at zbt.de,
Jun 20 21:22:37 auth: Debug: master in: PASS	1	christoph thyssen at bb-trunk.egroupware.de	service=doveadm
Jun 20 21:22:37 auth: Info: Username character disallowed by auth_username_chars: 0x20 (username: christoph thyssen at bb-trunk.egroupware.de)
Jun 20 21:22:37 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:37 auth: Debug: master in: USER	2	christoph thyssen at bb-trunk.egroupware.de	service=doveadm
Jun 20 21:22:37 auth: Info: Username character disallowed by auth_username_chars: 0x20 (username: christoph thyssen at bb-trunk.egroupware.de)
Jun 20 21:22:37 auth: Debug: userdb out: NOTFOUND	2
Jun 20 21:22:37 doveadm(christoph thyssen at bb-trunk.egroupware.de): Error: User doesn't exist
Jun 20 21:22:37 auth: Debug: master in: PASS	1	tot at zbt.de	service=doveadm
Jun 20 21:22:37 auth: Debug: dict(tot at zbt.de): Performing passdb lookup
Jun 20 21:22:37 auth: Debug: dict(tot at zbt.de): cache miss
Jun 20 21:22:37 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<10>: Handling PASSL request
Jun 20 21:22:37 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<10>: dict(tot at zbt.de): Performing passdb lookup
Jun 20 21:22:37 auth-worker(20): Debug: dict(proxy): Looking up 'shared/passdb/tot at zbt.de/'
Jun 20 21:22:37 auth-worker(20): Debug: dict(proxy): Looking up (async) 'shared/passdb/tot at zbt.de/'
Jun 20 21:22:37 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:37 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:37 auth: Debug: master in: PASS	1	bb-neu at bb-trunk.egroupware.de	service=doveadm
Jun 20 21:22:37 auth: Debug: dict(bb-neu at bb-trunk.egroupware.de): Performing passdb lookup
Jun 20 21:22:37 auth: Debug: dict(bb-neu at bb-trunk.egroupware.de): cache miss
Jun 20 21:22:37 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<9>: Handling PASSL request
Jun 20 21:22:37 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<9>: dict(bb-neu at bb-trunk.egroupware.de): Performing passdb lookup
Jun 20 21:22:37 auth-worker(25): Debug: dict(proxy): Looking up 'shared/passdb/bb-neu at bb-trunk.egroupware.de/'
Jun 20 21:22:37 auth-worker(25): Debug: dict(proxy): Looking up (async) 'shared/passdb/bb-neu at bb-trunk.egroupware.de/'
Jun 20 21:22:37 auth-worker(25): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:37 auth-worker(25): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:37 auth-worker(27): Debug: dict(proxy): Lookup finished for 'shared/passdb/ugn at zbt.de/': not found
Jun 20 21:22:37 auth-worker(27): Debug: dict(proxy): Lookup finished for 'shared/passdb/ugn at zbt.de/': not found
Jun 20 21:22:37 auth-worker(27): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<3>: dict(ugn at zbt.de): unknown user
Jun 20 21:22:37 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<3>: dict(ugn at zbt.de): Finished passdb lookup
Jun 20 21:22:37 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<3>: Finished
Jun 20 21:22:37 auth-worker(27): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:37 auth: Debug: dict(ugn at zbt.de): Finished passdb lookup
Jun 20 21:22:37 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:37 auth: Debug: master in: USER	2	ugn at zbt.de	service=doveadm
Jun 20 21:22:37 auth: Debug: sql(ugn at zbt.de): Performing userdb lookup
Jun 20 21:22:37 auth: Debug: sql(ugn at zbt.de): userdb cache miss
Jun 20 21:22:37 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<4>: Handling USER request
Jun 20 21:22:37 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<4>: sql(ugn at zbt.de): Performing userdb lookup
Jun 20 21:22:37 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<4>: sql(ugn at zbt.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'ugn' AND domain = 'zbt.de'
Jun 20 21:22:37 auth-worker(27): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'ugn' AND domain = 'zbt.de'' in 0 msecs
Jun 20 21:22:37 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<4>: sql(ugn at zbt.de): Finished userdb lookup
Jun 20 21:22:37 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<4>: Finished
Jun 20 21:22:37 auth: Debug: sql(ugn at zbt.de): Finished userdb lookup
Jun 20 21:22:37 auth: Debug: userdb out: USER	2	ugn at zbt.de	home=/var/dovecot/imap/zbt.de/ugn	quota_rule=*:bytes=6291456000	master_user=ugn at zbt.de	acl_groups=_alle-festangestellte at zbt.de,_organisatorische einheiten at zbt.de,_zbt-festangestellte at zbt.de,_zbt-abteilung_2 - ep at zbt.de,
Jun 20 21:22:37 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/tot at zbt.de/': not found
Jun 20 21:22:37 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/tot at zbt.de/': not found
Jun 20 21:22:37 auth-worker(20): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<10>: dict(tot at zbt.de): unknown user
Jun 20 21:22:37 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<10>: dict(tot at zbt.de): Finished passdb lookup
Jun 20 21:22:37 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<10>: Finished
Jun 20 21:22:37 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:37 auth: Debug: dict(tot at zbt.de): Finished passdb lookup
Jun 20 21:22:37 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:37 auth: Debug: master in: USER	2	tot at zbt.de	service=doveadm
Jun 20 21:22:37 auth: Debug: sql(tot at zbt.de): Performing userdb lookup
Jun 20 21:22:37 auth: Debug: sql(tot at zbt.de): userdb cache miss
Jun 20 21:22:37 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<11>: Handling USER request
Jun 20 21:22:37 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<11>: sql(tot at zbt.de): Performing userdb lookup
Jun 20 21:22:37 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<11>: sql(tot at zbt.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'tot' AND domain = 'zbt.de'
Jun 20 21:22:37 auth-worker(20): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'tot' AND domain = 'zbt.de'' in 0 msecs
Jun 20 21:22:37 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<11>: sql(tot at zbt.de): Finished userdb lookup
Jun 20 21:22:37 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<11>: Finished
Jun 20 21:22:37 auth: Debug: sql(tot at zbt.de): Finished userdb lookup
Jun 20 21:22:37 auth: Debug: userdb out: USER	2	tot at zbt.de	home=/var/dovecot/imap/zbt.de/tot	quota_rule=*:bytes=4194304000	master_user=tot at zbt.de	acl_groups=_alle-festangestellte at zbt.de,_organisatorische einheiten at zbt.de,_zbt-abteilung_5 - wi at zbt.de,_zbt-festangestellte at zbt.de,
Jun 20 21:22:37 auth-worker(25): Debug: dict(proxy): Lookup finished for 'shared/passdb/bb-neu at bb-trunk.egroupware.de/': not found
Jun 20 21:22:37 auth-worker(25): Debug: dict(proxy): Lookup finished for 'shared/passdb/bb-neu at bb-trunk.egroupware.de/': not found
Jun 20 21:22:37 auth-worker(25): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<9>: dict(bb-neu at bb-trunk.egroupware.de): unknown user
Jun 20 21:22:37 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<9>: dict(bb-neu at bb-trunk.egroupware.de): Finished passdb lookup
Jun 20 21:22:37 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<9>: Finished
Jun 20 21:22:37 auth-worker(25): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:37 auth: Debug: dict(bb-neu at bb-trunk.egroupware.de): Finished passdb lookup
Jun 20 21:22:37 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:37 auth: Debug: master in: USER	2	bb-neu at bb-trunk.egroupware.de	service=doveadm
Jun 20 21:22:37 auth: Debug: sql(bb-neu at bb-trunk.egroupware.de): Performing userdb lookup
Jun 20 21:22:37 auth: Debug: sql(bb-neu at bb-trunk.egroupware.de): userdb cache miss
Jun 20 21:22:37 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<12>: Handling USER request
Jun 20 21:22:37 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<12>: sql(bb-neu at bb-trunk.egroupware.de): Performing userdb lookup
Jun 20 21:22:37 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<12>: sql(bb-neu at bb-trunk.egroupware.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'bb-neu' AND domain = 'bb-trunk.egroupware.de'
Jun 20 21:22:37 auth-worker(20): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'bb-neu' AND domain = 'bb-trunk.egroupware.de'' in 0 msecs
Jun 20 21:22:37 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<12>: sql(bb-neu at bb-trunk.egroupware.de): Finished userdb lookup
Jun 20 21:22:37 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<12>: Finished
Jun 20 21:22:37 auth: Debug: sql(bb-neu at bb-trunk.egroupware.de): Finished userdb lookup
Jun 20 21:22:37 auth: Debug: userdb out: USER	2	bb-neu at bb-trunk.egroupware.de	home=/var/dovecot/imap/bb-trunk.egroupware.de/bb-neu	master_user=bb-neu at bb-trunk.egroupware.de	acl_groups=innendienst at bb-trunk.egroupware.de,default at bb-trunk.egroupware.de,
Jun 20 21:22:37 auth: Debug: master in: PASS	1	berberich at bbspirmasens.de	service=doveadm
Jun 20 21:22:37 auth: Debug: dict(berberich at bbspirmasens.de): Performing passdb lookup
Jun 20 21:22:37 auth: Debug: dict(berberich at bbspirmasens.de): cache miss
Jun 20 21:22:37 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<13>: Handling PASSL request
Jun 20 21:22:37 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<13>: dict(berberich at bbspirmasens.de): Performing passdb lookup
Jun 20 21:22:37 auth-worker(20): Debug: dict(proxy): Looking up 'shared/passdb/berberich at bbspirmasens.de/'
Jun 20 21:22:37 auth-worker(20): Debug: dict(proxy): Looking up (async) 'shared/passdb/berberich at bbspirmasens.de/'
Jun 20 21:22:37 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:37 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:37 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/berberich at bbspirmasens.de/': not found
Jun 20 21:22:37 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/berberich at bbspirmasens.de/': not found
Jun 20 21:22:37 auth-worker(20): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<13>: dict(berberich at bbspirmasens.de): unknown user
Jun 20 21:22:37 auth: Debug: dict(berberich at bbspirmasens.de): Finished passdb lookup
Jun 20 21:22:37 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:37 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<13>: dict(berberich at bbspirmasens.de): Finished passdb lookup
Jun 20 21:22:37 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<13>: Finished
Jun 20 21:22:37 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:37 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<14>: Handling USER request
Jun 20 21:22:37 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<14>: sql(berberich at bbspirmasens.de): Performing userdb lookup
Jun 20 21:22:37 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<14>: sql(berberich at bbspirmasens.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'berberich' AND domain = 'bbspirmasens.de'
Jun 20 21:22:37 auth-worker(20): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'berberich' AND domain = 'bbspirmasens.de'' in 0 msecs
Jun 20 21:22:37 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<14>: sql(berberich at bbspirmasens.de): Finished userdb lookup
Jun 20 21:22:37 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<14>: Finished
Jun 20 21:22:37 auth: Debug: master in: USER	2	berberich at bbspirmasens.de	service=doveadm
Jun 20 21:22:37 auth: Debug: sql(berberich at bbspirmasens.de): Performing userdb lookup
Jun 20 21:22:37 auth: Debug: sql(berberich at bbspirmasens.de): userdb cache miss
Jun 20 21:22:37 auth: Debug: sql(berberich at bbspirmasens.de): Finished userdb lookup
Jun 20 21:22:37 auth: Debug: userdb out: USER	2	berberich at bbspirmasens.de	home=/var/dovecot/imap/bbspirmasens.de/Berberich	master_user=berberich at bbspirmasens.de	acl_groups=alle bbsps at bbspirmasens.de,kollegium at bbspirmasens.de,
Jun 20 21:22:37 auth: Debug: master in: PASS	1	tnf at zbt.de	service=doveadm
Jun 20 21:22:37 auth: Debug: dict(tnf at zbt.de): Performing passdb lookup
Jun 20 21:22:37 auth: Debug: dict(tnf at zbt.de): cache miss
Jun 20 21:22:37 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<15>: Handling PASSL request
Jun 20 21:22:37 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<15>: dict(tnf at zbt.de): Performing passdb lookup
Jun 20 21:22:37 auth-worker(20): Debug: dict(proxy): Looking up 'shared/passdb/tnf at zbt.de/'
Jun 20 21:22:37 auth-worker(20): Debug: dict(proxy): Looking up (async) 'shared/passdb/tnf at zbt.de/'
Jun 20 21:22:37 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:37 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:38 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/tnf at zbt.de/': not found
Jun 20 21:22:38 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/tnf at zbt.de/': not found
Jun 20 21:22:38 auth-worker(20): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<15>: dict(tnf at zbt.de): unknown user
Jun 20 21:22:38 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<15>: dict(tnf at zbt.de): Finished passdb lookup
Jun 20 21:22:38 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<15>: Finished
Jun 20 21:22:38 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:38 auth: Debug: dict(tnf at zbt.de): Finished passdb lookup
Jun 20 21:22:38 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:38 auth: Debug: master in: USER	2	tnf at zbt.de	service=doveadm
Jun 20 21:22:38 auth: Debug: sql(tnf at zbt.de): Performing userdb lookup
Jun 20 21:22:38 auth: Debug: sql(tnf at zbt.de): userdb cache miss
Jun 20 21:22:38 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<16>: Handling USER request
Jun 20 21:22:38 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<16>: sql(tnf at zbt.de): Performing userdb lookup
Jun 20 21:22:38 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<16>: sql(tnf at zbt.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'tnf' AND domain = 'zbt.de'
Jun 20 21:22:38 auth-worker(20): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'tnf' AND domain = 'zbt.de'' in 0 msecs
Jun 20 21:22:38 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<16>: sql(tnf at zbt.de): Finished userdb lookup
Jun 20 21:22:38 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<16>: Finished
Jun 20 21:22:38 auth: Debug: sql(tnf at zbt.de): Finished userdb lookup
Jun 20 21:22:38 auth: Debug: userdb out: USER	2	tnf at zbt.de	home=/var/dovecot/imap/zbt.de/tnf	quota_rule=*:bytes=6291456000	master_user=tnf at zbt.de	acl_groups=_alle-festangestellte at zbt.de,_organisatorische einheiten at zbt.de,_zbt-abteilung_8 - bz at zbt.de,_zbt-festangestellte at zbt.de,
Jun 20 21:22:38 auth: Debug: master in: PASS	1	may-wack at bbspirmasens.de	service=doveadm
Jun 20 21:22:38 auth: Debug: dict(may-wack at bbspirmasens.de): Performing passdb lookup
Jun 20 21:22:38 auth: Debug: dict(may-wack at bbspirmasens.de): cache miss
Jun 20 21:22:38 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<17>: Handling PASSL request
Jun 20 21:22:38 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<17>: dict(may-wack at bbspirmasens.de): Performing passdb lookup
Jun 20 21:22:38 auth-worker(20): Debug: dict(proxy): Looking up 'shared/passdb/may-wack at bbspirmasens.de/'
Jun 20 21:22:38 auth-worker(20): Debug: dict(proxy): Looking up (async) 'shared/passdb/may-wack at bbspirmasens.de/'
Jun 20 21:22:38 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:38 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:38 auth: Debug: client passdb out: FAIL	1	user=hadi at bb-trunk.egroupware.de
Jun 20 21:22:38 imap-login: Info: Disconnected: Connection closed (auth failed, 1 attempts in 2 secs): user=<hadi at bb-trunk.egroupware.de>, method=PLAIN, rip=10.10.30.11, lip=172.18.0.2, session=<cWj1s+fhYVYKCh4L>
Jun 20 21:22:38 auth: Debug: client in: AUTH	1	PLAIN	service=imap	session=a0cStOfhbkIKCh4L	lip=172.18.0.2	rip=10.10.30.11	lport=143	rport=17006	resp=<hidden>
Jun 20 21:22:38 auth: Debug: auth client connected (pid=50)
Jun 20 21:22:38 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/may-wack at bbspirmasens.de/': not found
Jun 20 21:22:38 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/may-wack at bbspirmasens.de/': not found
Jun 20 21:22:38 auth-worker(20): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<17>: dict(may-wack at bbspirmasens.de): unknown user
Jun 20 21:22:38 auth: Debug: dict(may-wack at bbspirmasens.de): Finished passdb lookup
Jun 20 21:22:38 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:38 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<17>: dict(may-wack at bbspirmasens.de): Finished passdb lookup
Jun 20 21:22:38 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<17>: Finished
Jun 20 21:22:38 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:38 auth: Debug: master in: USER	2	may-wack at bbspirmasens.de	service=doveadm
Jun 20 21:22:38 auth: Debug: sql(may-wack at bbspirmasens.de): Performing userdb lookup
Jun 20 21:22:38 auth: Debug: sql(may-wack at bbspirmasens.de): userdb cache miss
Jun 20 21:22:38 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<18>: Handling USER request
Jun 20 21:22:38 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<18>: sql(may-wack at bbspirmasens.de): Performing userdb lookup
Jun 20 21:22:38 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<18>: sql(may-wack at bbspirmasens.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'may-wack' AND domain = 'bbspirmasens.de'
Jun 20 21:22:38 auth-worker(20): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'may-wack' AND domain = 'bbspirmasens.de'' in 0 msecs
Jun 20 21:22:38 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<18>: sql(may-wack at bbspirmasens.de): Finished userdb lookup
Jun 20 21:22:38 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<18>: Finished
Jun 20 21:22:38 auth: Debug: sql(may-wack at bbspirmasens.de): Finished userdb lookup
Jun 20 21:22:38 auth: Debug: userdb out: USER	2	may-wack at bbspirmasens.de	home=/var/dovecot/imap/bbspirmasens.de/May-Wack	master_user=may-wack at bbspirmasens.de	acl_groups=alle bbsps at bbspirmasens.de,kollegium at bbspirmasens.de,
Jun 20 21:22:38 auth: Debug: master in: PASS	1	gssued at nordXXXXXX.de	service=doveadm
Jun 20 21:22:38 auth: Debug: dict(gssued at nordXXXXXX.de): Performing passdb lookup
Jun 20 21:22:38 auth: Debug: dict(gssued at nordXXXXXX.de): cache miss
Jun 20 21:22:38 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<19>: Handling PASSL request
Jun 20 21:22:38 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<19>: dict(gssued at nordXXXXXX.de): Performing passdb lookup
Jun 20 21:22:38 auth-worker(20): Debug: dict(proxy): Looking up 'shared/passdb/gssued at nordXXXXXX.de/'
Jun 20 21:22:38 auth-worker(20): Debug: dict(proxy): Looking up (async) 'shared/passdb/gssued at nordXXXXXX.de/'
Jun 20 21:22:38 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:38 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:38 auth: Debug: auth client connected (pid=52)
Jun 20 21:22:38 auth: Debug: client in: AUTH	1	PLAIN	service=pop3	session=kkEYtOfhXyYKCh4L	lip=172.18.0.2	rip=10.10.30.11	lport=110	rport=9823	resp=<hidden>
Jun 20 21:22:38 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/gssued at nordXXXXXX.de/': not found
Jun 20 21:22:38 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/gssued at nordXXXXXX.de/': not found
Jun 20 21:22:38 auth-worker(20): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<19>: dict(gssued at nordXXXXXX.de): unknown user
Jun 20 21:22:38 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<19>: dict(gssued at nordXXXXXX.de): Finished passdb lookup
Jun 20 21:22:38 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<19>: Finished
Jun 20 21:22:38 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:38 auth: Debug: dict(gssued at nordXXXXXX.de): Finished passdb lookup
Jun 20 21:22:38 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:38 auth: Debug: master in: USER	2	gssued at nordXXXXXX.de	service=doveadm
Jun 20 21:22:38 auth: Debug: sql(gssued at nordXXXXXX.de): Performing userdb lookup
Jun 20 21:22:38 auth: Debug: sql(gssued at nordXXXXXX.de): userdb cache miss
Jun 20 21:22:38 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<20>: Handling USER request
Jun 20 21:22:38 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<20>: sql(gssued at nordXXXXXX.de): Performing userdb lookup
Jun 20 21:22:38 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<20>: sql(gssued at nordXXXXXX.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'gssued' AND domain = 'nordXXXXXX.de'
Jun 20 21:22:38 auth-worker(20): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'gssued' AND domain = 'nordXXXXXX.de'' in 0 msecs
Jun 20 21:22:38 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<20>: sql(gssued at nordXXXXXX.de): Finished userdb lookup
Jun 20 21:22:38 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<20>: Finished
Jun 20 21:22:38 auth: Debug: sql(gssued at nordXXXXXX.de): Finished userdb lookup
Jun 20 21:22:38 auth: Debug: userdb out: USER	2	gssued at nordXXXXXX.de	home=/var/dovecot/imap/nordXXXXXX.de/gssued	master_user=gssued at nordXXXXXX.de	acl_groups=domain users at nordXXXXXX.de,
Jun 20 21:22:39 auth: Debug: master in: PASS	1	c.knoll at rbz-schuetzenpark.de	service=doveadm
Jun 20 21:22:39 auth: Debug: dict(c.knoll at rbz-schuetzenpark.de): Performing passdb lookup
Jun 20 21:22:39 auth: Debug: dict(c.knoll at rbz-schuetzenpark.de): cache miss
Jun 20 21:22:39 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<21>: Handling PASSL request
Jun 20 21:22:39 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<21>: dict(c.knoll at rbz-schuetzenpark.de): Performing passdb lookup
Jun 20 21:22:39 auth-worker(20): Debug: dict(proxy): Looking up 'shared/passdb/c.knoll at rbz-schuetzenpark.de/'
Jun 20 21:22:39 auth-worker(20): Debug: dict(proxy): Looking up (async) 'shared/passdb/c.knoll at rbz-schuetzenpark.de/'
Jun 20 21:22:39 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:39 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:39 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/c.knoll at rbz-schuetzenpark.de/': not found
Jun 20 21:22:39 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/c.knoll at rbz-schuetzenpark.de/': not found
Jun 20 21:22:39 auth-worker(20): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<21>: dict(c.knoll at rbz-schuetzenpark.de): unknown user
Jun 20 21:22:39 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<21>: dict(c.knoll at rbz-schuetzenpark.de): Finished passdb lookup
Jun 20 21:22:39 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<21>: Finished
Jun 20 21:22:39 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:39 auth: Debug: dict(c.knoll at rbz-schuetzenpark.de): Finished passdb lookup
Jun 20 21:22:39 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:39 auth: Debug: master in: USER	2	c.knoll at rbz-schuetzenpark.de	service=doveadm
Jun 20 21:22:39 auth: Debug: sql(c.knoll at rbz-schuetzenpark.de): Performing userdb lookup
Jun 20 21:22:39 auth: Debug: sql(c.knoll at rbz-schuetzenpark.de): userdb cache miss
Jun 20 21:22:39 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<22>: Handling USER request
Jun 20 21:22:39 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<22>: sql(c.knoll at rbz-schuetzenpark.de): Performing userdb lookup
Jun 20 21:22:39 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<22>: sql(c.knoll at rbz-schuetzenpark.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'c.knoll' AND domain = 'rbz-schuetzenpark.de'
Jun 20 21:22:39 auth-worker(20): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'c.knoll' AND domain = 'rbz-schuetzenpark.de'' in 0 msecs
Jun 20 21:22:39 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<22>: sql(c.knoll at rbz-schuetzenpark.de): Finished userdb lookup
Jun 20 21:22:39 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<22>: Finished
Jun 20 21:22:39 auth: Debug: sql(c.knoll at rbz-schuetzenpark.de): Finished userdb lookup
Jun 20 21:22:39 auth: Debug: userdb out: USER	2	c.knoll at rbz-schuetzenpark.de	home=/var/dovecot/imap/rbz-schuetzenpark.de/c.knoll	master_user=c.knoll at rbz-schuetzenpark.de	acl_groups=bg at rbz-schuetzenpark.de,default at rbz-schuetzenpark.de,kollegium at rbz-schuetzenpark.de,rbz-am-schützenpark at rbz-schuetzenpark.de,naturwissenschaften at rbz-schuetzenpark.de,bv-av-basis at rbz-schuetzenpark.de,greenteam at rbz-schuetzenpark.de,labore at rbz-schuetzenpark.de,bv at rbz-schuetzenpark.de,gartenbau-floristik at rbz-schuetzenpark.de,
Jun 20 21:22:39 auth: Debug: master in: PASS	1	c.gruetzner at rbz-schuetzenpark.de	service=doveadm
Jun 20 21:22:39 auth: Debug: dict(c.gruetzner at rbz-schuetzenpark.de): Performing passdb lookup
Jun 20 21:22:39 auth: Debug: dict(c.gruetzner at rbz-schuetzenpark.de): cache miss
Jun 20 21:22:39 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<23>: Handling PASSL request
Jun 20 21:22:39 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<23>: dict(c.gruetzner at rbz-schuetzenpark.de): Performing passdb lookup
Jun 20 21:22:39 auth-worker(20): Debug: dict(proxy): Looking up 'shared/passdb/c.gruetzner at rbz-schuetzenpark.de/'
Jun 20 21:22:39 auth-worker(20): Debug: dict(proxy): Looking up (async) 'shared/passdb/c.gruetzner at rbz-schuetzenpark.de/'
Jun 20 21:22:39 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:39 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:39 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/c.gruetzner at rbz-schuetzenpark.de/': not found
Jun 20 21:22:39 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/c.gruetzner at rbz-schuetzenpark.de/': not found
Jun 20 21:22:39 auth-worker(20): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<23>: dict(c.gruetzner at rbz-schuetzenpark.de): unknown user
Jun 20 21:22:39 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<23>: dict(c.gruetzner at rbz-schuetzenpark.de): Finished passdb lookup
Jun 20 21:22:39 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<23>: Finished
Jun 20 21:22:39 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:39 auth: Debug: dict(c.gruetzner at rbz-schuetzenpark.de): Finished passdb lookup
Jun 20 21:22:39 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:39 auth: Debug: master in: USER	2	c.gruetzner at rbz-schuetzenpark.de	service=doveadm
Jun 20 21:22:39 auth: Debug: sql(c.gruetzner at rbz-schuetzenpark.de): Performing userdb lookup
Jun 20 21:22:39 auth: Debug: sql(c.gruetzner at rbz-schuetzenpark.de): userdb cache miss
Jun 20 21:22:39 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<24>: Handling USER request
Jun 20 21:22:39 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<24>: sql(c.gruetzner at rbz-schuetzenpark.de): Performing userdb lookup
Jun 20 21:22:39 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<24>: sql(c.gruetzner at rbz-schuetzenpark.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'c.gruetzner' AND domain = 'rbz-schuetzenpark.de'
Jun 20 21:22:39 auth-worker(20): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'c.gruetzner' AND domain = 'rbz-schuetzenpark.de'' in 0 msecs
Jun 20 21:22:39 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<24>: sql(c.gruetzner at rbz-schuetzenpark.de): Finished userdb lookup
Jun 20 21:22:39 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<24>: Finished
Jun 20 21:22:39 auth: Debug: sql(c.gruetzner at rbz-schuetzenpark.de): Finished userdb lookup
Jun 20 21:22:39 auth: Debug: userdb out: USER	2	c.gruetzner at rbz-schuetzenpark.de	home=/var/dovecot/imap/rbz-schuetzenpark.de/c.gruetzner	master_user=c.gruetzner at rbz-schuetzenpark.de	acl_groups=wipo at rbz-schuetzenpark.de,gastronomie at rbz-schuetzenpark.de,rbz-am-schützenpark at rbz-schuetzenpark.de,default at rbz-schuetzenpark.de,gestalter-visuelles-marketing at rbz-schuetzenpark.de,kollegium at rbz-schuetzenpark.de,
Jun 20 21:22:40 auth: Debug: master in: PASS	1	gs.fah at nordXXXXXX.de	service=doveadm
Jun 20 21:22:40 auth: Debug: dict(gs.fah at nordXXXXXX.de): Performing passdb lookup
Jun 20 21:22:40 auth: Debug: dict(gs.fah at nordXXXXXX.de): cache miss
Jun 20 21:22:40 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<25>: Handling PASSL request
Jun 20 21:22:40 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<25>: dict(gs.fah at nordXXXXXX.de): Performing passdb lookup
Jun 20 21:22:40 auth-worker(20): Debug: dict(proxy): Looking up 'shared/passdb/gs.fah at nordXXXXXX.de/'
Jun 20 21:22:40 auth-worker(20): Debug: dict(proxy): Looking up (async) 'shared/passdb/gs.fah at nordXXXXXX.de/'
Jun 20 21:22:40 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:40 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:40 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/gs.fah at nordXXXXXX.de/': not found
Jun 20 21:22:40 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/gs.fah at nordXXXXXX.de/': not found
Jun 20 21:22:40 auth-worker(20): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<25>: dict(gs.fah at nordXXXXXX.de): unknown user
Jun 20 21:22:40 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<25>: dict(gs.fah at nordXXXXXX.de): Finished passdb lookup
Jun 20 21:22:40 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<25>: Finished
Jun 20 21:22:40 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:40 auth: Debug: dict(gs.fah at nordXXXXXX.de): Finished passdb lookup
Jun 20 21:22:40 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:40 auth: Debug: master in: USER	2	gs.fah at nordXXXXXX.de	service=doveadm
Jun 20 21:22:40 auth: Debug: sql(gs.fah at nordXXXXXX.de): Performing userdb lookup
Jun 20 21:22:40 auth: Debug: sql(gs.fah at nordXXXXXX.de): userdb cache miss
Jun 20 21:22:40 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<26>: Handling USER request
Jun 20 21:22:40 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<26>: sql(gs.fah at nordXXXXXX.de): Performing userdb lookup
Jun 20 21:22:40 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<26>: sql(gs.fah at nordXXXXXX.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'gs.fah' AND domain = 'nordXXXXXX.de'
Jun 20 21:22:40 auth-worker(20): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'gs.fah' AND domain = 'nordXXXXXX.de'' in 0 msecs
Jun 20 21:22:40 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<26>: sql(gs.fah at nordXXXXXX.de): Finished userdb lookup
Jun 20 21:22:40 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<26>: Finished
Jun 20 21:22:40 auth: Debug: sql(gs.fah at nordXXXXXX.de): Finished userdb lookup
Jun 20 21:22:40 auth: Debug: userdb out: USER	2	gs.fah at nordXXXXXX.de	home=/var/dovecot/imap/nordXXXXXX.de/gs.fah	master_user=gs.fah at nordXXXXXX.de	acl_groups=domain users at nordXXXXXX.de,
Jun 20 21:22:40 auth: Debug: master in: PASS	1	c.gramke at rbz-schuetzenpark.de	service=doveadm
Jun 20 21:22:40 auth: Debug: dict(c.gramke at rbz-schuetzenpark.de): Performing passdb lookup
Jun 20 21:22:40 auth: Debug: dict(c.gramke at rbz-schuetzenpark.de): cache miss
Jun 20 21:22:40 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<27>: Handling PASSL request
Jun 20 21:22:40 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<27>: dict(c.gramke at rbz-schuetzenpark.de): Performing passdb lookup
Jun 20 21:22:40 auth-worker(20): Debug: dict(proxy): Looking up 'shared/passdb/c.gramke at rbz-schuetzenpark.de/'
Jun 20 21:22:40 auth-worker(20): Debug: dict(proxy): Looking up (async) 'shared/passdb/c.gramke at rbz-schuetzenpark.de/'
Jun 20 21:22:40 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:40 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:40 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/c.gramke at rbz-schuetzenpark.de/': not found
Jun 20 21:22:40 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/c.gramke at rbz-schuetzenpark.de/': not found
Jun 20 21:22:40 auth-worker(20): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<27>: dict(c.gramke at rbz-schuetzenpark.de): unknown user
Jun 20 21:22:40 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<27>: dict(c.gramke at rbz-schuetzenpark.de): Finished passdb lookup
Jun 20 21:22:40 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<27>: Finished
Jun 20 21:22:40 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:40 auth: Debug: dict(c.gramke at rbz-schuetzenpark.de): Finished passdb lookup
Jun 20 21:22:40 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:40 auth: Debug: master in: USER	2	c.gramke at rbz-schuetzenpark.de	service=doveadm
Jun 20 21:22:40 auth: Debug: sql(c.gramke at rbz-schuetzenpark.de): Performing userdb lookup
Jun 20 21:22:40 auth: Debug: sql(c.gramke at rbz-schuetzenpark.de): userdb cache miss
Jun 20 21:22:40 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<28>: Handling USER request
Jun 20 21:22:40 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<28>: sql(c.gramke at rbz-schuetzenpark.de): Performing userdb lookup
Jun 20 21:22:40 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<28>: sql(c.gramke at rbz-schuetzenpark.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'c.gramke' AND domain = 'rbz-schuetzenpark.de'
Jun 20 21:22:40 auth-worker(20): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'c.gramke' AND domain = 'rbz-schuetzenpark.de'' in 0 msecs
Jun 20 21:22:40 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<28>: sql(c.gramke at rbz-schuetzenpark.de): Finished userdb lookup
Jun 20 21:22:40 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<28>: Finished
Jun 20 21:22:40 auth: Debug: sql(c.gramke at rbz-schuetzenpark.de): Finished userdb lookup
Jun 20 21:22:40 auth: Debug: userdb out: USER	2	c.gramke at rbz-schuetzenpark.de	home=/var/dovecot/imap/rbz-schuetzenpark.de/c.gramke	quota_rule=*:bytes=107374182400	master_user=c.gramke at rbz-schuetzenpark.de	acl_groups=collabora at rbz-schuetzenpark.de,informatik at rbz-schuetzenpark.de,mathematik at rbz-schuetzenpark.de,rbz-am-schützenpark at rbz-schuetzenpark.de,wiki-admin at rbz-schuetzenpark.de,default at rbz-schuetzenpark.de,kollegium at rbz-schuetzenpark.de,bv-av-esa at rbz-schuetzenpark.de,bv-berufsmodul at rbz-schuetzenpark.de,bv at rbz-schuetzenpark.de,
Jun 20 21:22:41 auth: Debug: master in: PASS	1	c.frach at rbz-schuetzenpark.de	service=doveadm
Jun 20 21:22:41 auth: Debug: dict(c.frach at rbz-schuetzenpark.de): Performing passdb lookup
Jun 20 21:22:41 auth: Debug: dict(c.frach at rbz-schuetzenpark.de): cache miss
Jun 20 21:22:41 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<29>: Handling PASSL request
Jun 20 21:22:41 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<29>: dict(c.frach at rbz-schuetzenpark.de): Performing passdb lookup
Jun 20 21:22:41 auth-worker(20): Debug: dict(proxy): Looking up 'shared/passdb/c.frach at rbz-schuetzenpark.de/'
Jun 20 21:22:41 auth-worker(20): Debug: dict(proxy): Looking up (async) 'shared/passdb/c.frach at rbz-schuetzenpark.de/'
Jun 20 21:22:41 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:41 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:41 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/c.frach at rbz-schuetzenpark.de/': not found
Jun 20 21:22:41 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/c.frach at rbz-schuetzenpark.de/': not found
Jun 20 21:22:41 auth-worker(20): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<29>: dict(c.frach at rbz-schuetzenpark.de): unknown user
Jun 20 21:22:41 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<29>: dict(c.frach at rbz-schuetzenpark.de): Finished passdb lookup
Jun 20 21:22:41 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<29>: Finished
Jun 20 21:22:41 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:41 auth: Debug: dict(c.frach at rbz-schuetzenpark.de): Finished passdb lookup
Jun 20 21:22:41 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:41 auth: Debug: master in: USER	2	c.frach at rbz-schuetzenpark.de	service=doveadm
Jun 20 21:22:41 auth: Debug: sql(c.frach at rbz-schuetzenpark.de): Performing userdb lookup
Jun 20 21:22:41 auth: Debug: sql(c.frach at rbz-schuetzenpark.de): userdb cache miss
Jun 20 21:22:41 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<30>: Handling USER request
Jun 20 21:22:41 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<30>: sql(c.frach at rbz-schuetzenpark.de): Performing userdb lookup
Jun 20 21:22:41 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<30>: sql(c.frach at rbz-schuetzenpark.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'c.frach' AND domain = 'rbz-schuetzenpark.de'
Jun 20 21:22:41 auth-worker(20): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'c.frach' AND domain = 'rbz-schuetzenpark.de'' in 0 msecs
Jun 20 21:22:41 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<30>: sql(c.frach at rbz-schuetzenpark.de): Finished userdb lookup
Jun 20 21:22:41 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<30>: Finished
Jun 20 21:22:41 auth: Debug: sql(c.frach at rbz-schuetzenpark.de): Finished userdb lookup
Jun 20 21:22:41 auth: Debug: userdb out: USER	2	c.frach at rbz-schuetzenpark.de	home=/var/dovecot/imap/rbz-schuetzenpark.de/c.frach	master_user=c.frach at rbz-schuetzenpark.de	acl_groups=gastronomie at rbz-schuetzenpark.de,rbz-am-schützenpark at rbz-schuetzenpark.de,default at rbz-schuetzenpark.de,kollegium at rbz-schuetzenpark.de,lehrkräfte-in-vorbereitung at rbz-schuetzenpark.de,bfs at rbz-schuetzenpark.de,
Jun 20 21:22:41 auth: Debug: master in: PASS	1	mattill at bbspirmasens.de	service=doveadm
Jun 20 21:22:41 auth: Debug: dict(mattill at bbspirmasens.de): Performing passdb lookup
Jun 20 21:22:41 auth: Debug: dict(mattill at bbspirmasens.de): cache miss
Jun 20 21:22:41 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<31>: Handling PASSL request
Jun 20 21:22:41 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<31>: dict(mattill at bbspirmasens.de): Performing passdb lookup
Jun 20 21:22:41 auth-worker(20): Debug: dict(proxy): Looking up 'shared/passdb/mattill at bbspirmasens.de/'
Jun 20 21:22:41 auth-worker(20): Debug: dict(proxy): Looking up (async) 'shared/passdb/mattill at bbspirmasens.de/'
Jun 20 21:22:41 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:41 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:41 auth: Debug: master in: PASS	1	gs.abbehausen at nordXXXXXX.de	service=doveadm
Jun 20 21:22:41 auth: Debug: dict(gs.abbehausen at nordXXXXXX.de): Performing passdb lookup
Jun 20 21:22:41 auth: Debug: dict(gs.abbehausen at nordXXXXXX.de): cache miss
Jun 20 21:22:41 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<10>: Handling PASSL request
Jun 20 21:22:41 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<10>: dict(gs.abbehausen at nordXXXXXX.de): Performing passdb lookup
Jun 20 21:22:41 auth-worker(25): Debug: dict(proxy): Looking up 'shared/passdb/gs.abbehausen at nordXXXXXX.de/'
Jun 20 21:22:41 auth-worker(25): Debug: dict(proxy): Looking up (async) 'shared/passdb/gs.abbehausen at nordXXXXXX.de/'
Jun 20 21:22:41 auth-worker(25): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:41 auth-worker(25): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:41 auth: Debug: master in: PASS	1	c.delfs at rbz-schuetzenpark.de	service=doveadm
Jun 20 21:22:41 auth: Debug: dict(c.delfs at rbz-schuetzenpark.de): Performing passdb lookup
Jun 20 21:22:41 auth: Debug: dict(c.delfs at rbz-schuetzenpark.de): cache miss
Jun 20 21:22:41 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<5>: Handling PASSL request
Jun 20 21:22:41 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<5>: dict(c.delfs at rbz-schuetzenpark.de): Performing passdb lookup
Jun 20 21:22:41 auth-worker(27): Debug: dict(proxy): Looking up 'shared/passdb/c.delfs at rbz-schuetzenpark.de/'
Jun 20 21:22:41 auth-worker(27): Debug: dict(proxy): Looking up (async) 'shared/passdb/c.delfs at rbz-schuetzenpark.de/'
Jun 20 21:22:41 auth-worker(27): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:41 auth-worker(27): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:41 auth: Debug: master in: PASS	1	c.dalpiaz at rbz-schuetzenpark.de	service=doveadm
Jun 20 21:22:41 auth: Debug: dict(c.dalpiaz at rbz-schuetzenpark.de): Performing passdb lookup
Jun 20 21:22:41 auth: Debug: dict(c.dalpiaz at rbz-schuetzenpark.de): cache miss
Jun 20 21:22:41 auth-worker(29): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<2>: Handling PASSL request
Jun 20 21:22:41 auth-worker(29): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<2>: dict(c.dalpiaz at rbz-schuetzenpark.de): Performing passdb lookup
Jun 20 21:22:41 auth-worker(29): Debug: dict(proxy): Looking up 'shared/passdb/c.dalpiaz at rbz-schuetzenpark.de/'
Jun 20 21:22:41 auth-worker(29): Debug: dict(proxy): Looking up (async) 'shared/passdb/c.dalpiaz at rbz-schuetzenpark.de/'
Jun 20 21:22:41 auth-worker(29): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:41 auth-worker(29): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:41 auth-worker(29): Debug: dict(proxy): Lookup finished for 'shared/passdb/c.dalpiaz at rbz-schuetzenpark.de/': not found
Jun 20 21:22:41 auth-worker(29): Debug: dict(proxy): Lookup finished for 'shared/passdb/c.dalpiaz at rbz-schuetzenpark.de/': not found
Jun 20 21:22:41 auth-worker(29): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<2>: dict(c.dalpiaz at rbz-schuetzenpark.de): unknown user
Jun 20 21:22:41 auth-worker(29): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<2>: dict(c.dalpiaz at rbz-schuetzenpark.de): Finished passdb lookup
Jun 20 21:22:41 auth-worker(29): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<2>: Finished
Jun 20 21:22:41 auth-worker(29): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:41 auth: Debug: dict(c.dalpiaz at rbz-schuetzenpark.de): Finished passdb lookup
Jun 20 21:22:41 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:41 auth: Debug: master in: USER	2	c.dalpiaz at rbz-schuetzenpark.de	service=doveadm
Jun 20 21:22:41 auth: Debug: sql(c.dalpiaz at rbz-schuetzenpark.de): Performing userdb lookup
Jun 20 21:22:41 auth: Debug: sql(c.dalpiaz at rbz-schuetzenpark.de): userdb cache miss
Jun 20 21:22:41 auth-worker(29): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<3>: Handling USER request
Jun 20 21:22:41 auth-worker(29): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<3>: sql(c.dalpiaz at rbz-schuetzenpark.de): Performing userdb lookup
Jun 20 21:22:41 auth-worker(29): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<3>: sql(c.dalpiaz at rbz-schuetzenpark.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'c.dalpiaz' AND domain = 'rbz-schuetzenpark.de'
Jun 20 21:22:41 auth-worker(29): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'c.dalpiaz' AND domain = 'rbz-schuetzenpark.de'' in 0 msecs
Jun 20 21:22:41 auth-worker(29): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<3>: sql(c.dalpiaz at rbz-schuetzenpark.de): Finished userdb lookup
Jun 20 21:22:41 auth-worker(29): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<3>: Finished
Jun 20 21:22:41 auth: Debug: sql(c.dalpiaz at rbz-schuetzenpark.de): Finished userdb lookup
Jun 20 21:22:41 auth: Debug: userdb out: USER	2	c.dalpiaz at rbz-schuetzenpark.de	home=/var/dovecot/imap/rbz-schuetzenpark.de/c.dalpiaz	master_user=c.dalpiaz at rbz-schuetzenpark.de	acl_groups=default at rbz-schuetzenpark.de,rbz-am-schützenpark at rbz-schuetzenpark.de,hausmeister at rbz-schuetzenpark.de,telefonanlage at rbz-schuetzenpark.de,bv at rbz-schuetzenpark.de,sport at rbz-schuetzenpark.de,
Jun 20 21:22:41 auth-worker(27): Debug: dict(proxy): Lookup finished for 'shared/passdb/c.delfs at rbz-schuetzenpark.de/': not found
Jun 20 21:22:41 auth-worker(27): Debug: dict(proxy): Lookup finished for 'shared/passdb/c.delfs at rbz-schuetzenpark.de/': not found
Jun 20 21:22:41 auth-worker(27): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<5>: dict(c.delfs at rbz-schuetzenpark.de): unknown user
Jun 20 21:22:41 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<5>: dict(c.delfs at rbz-schuetzenpark.de): Finished passdb lookup
Jun 20 21:22:41 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<5>: Finished
Jun 20 21:22:41 auth-worker(27): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:41 auth: Debug: dict(c.delfs at rbz-schuetzenpark.de): Finished passdb lookup
Jun 20 21:22:41 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:41 auth: Debug: master in: USER	2	c.delfs at rbz-schuetzenpark.de	service=doveadm
Jun 20 21:22:41 auth: Debug: sql(c.delfs at rbz-schuetzenpark.de): Performing userdb lookup
Jun 20 21:22:41 auth: Debug: sql(c.delfs at rbz-schuetzenpark.de): userdb cache miss
Jun 20 21:22:41 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<6>: Handling USER request
Jun 20 21:22:41 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<6>: sql(c.delfs at rbz-schuetzenpark.de): Performing userdb lookup
Jun 20 21:22:41 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<6>: sql(c.delfs at rbz-schuetzenpark.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'c.delfs' AND domain = 'rbz-schuetzenpark.de'
Jun 20 21:22:41 auth-worker(27): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'c.delfs' AND domain = 'rbz-schuetzenpark.de'' in 0 msecs
Jun 20 21:22:41 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<6>: sql(c.delfs at rbz-schuetzenpark.de): Finished userdb lookup
Jun 20 21:22:41 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<6>: Finished
Jun 20 21:22:41 auth: Debug: sql(c.delfs at rbz-schuetzenpark.de): Finished userdb lookup
Jun 20 21:22:41 auth: Debug: userdb out: USER	2	c.delfs at rbz-schuetzenpark.de	home=/var/dovecot/imap/rbz-schuetzenpark.de/c.delfs	master_user=c.delfs at rbz-schuetzenpark.de	acl_groups=fremdsprachen at rbz-schuetzenpark.de,wipo at rbz-schuetzenpark.de,Öpr at rbz-schuetzenpark.de,englisch at rbz-schuetzenpark.de,rbz-am-schützenpark at rbz-schuetzenpark.de,default at rbz-schuetzenpark.de,kollegium at rbz-schuetzenpark.de,lerncoaching at rbz-schuetzenpark.de,bg at rbz-schuetzenpark.de,abteilungen-gross at rbz-schuetzenpark.de,corona at rbz-schuetzenpark.de,
Jun 20 21:22:41 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/mattill at bbspirmasens.de/': not found
Jun 20 21:22:41 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/mattill at bbspirmasens.de/': not found
Jun 20 21:22:41 auth-worker(20): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<31>: dict(mattill at bbspirmasens.de): unknown user
Jun 20 21:22:41 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<31>: dict(mattill at bbspirmasens.de): Finished passdb lookup
Jun 20 21:22:41 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<31>: Finished
Jun 20 21:22:41 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:41 auth: Debug: dict(mattill at bbspirmasens.de): Finished passdb lookup
Jun 20 21:22:41 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:41 auth: Debug: master in: USER	2	mattill at bbspirmasens.de	service=doveadm
Jun 20 21:22:41 auth: Debug: sql(mattill at bbspirmasens.de): Performing userdb lookup
Jun 20 21:22:41 auth: Debug: sql(mattill at bbspirmasens.de): userdb cache miss
Jun 20 21:22:41 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<32>: Handling USER request
Jun 20 21:22:41 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<32>: sql(mattill at bbspirmasens.de): Performing userdb lookup
Jun 20 21:22:41 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<32>: sql(mattill at bbspirmasens.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'mattill' AND domain = 'bbspirmasens.de'
Jun 20 21:22:41 auth-worker(20): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'mattill' AND domain = 'bbspirmasens.de'' in 0 msecs
Jun 20 21:22:41 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<32>: sql(mattill at bbspirmasens.de): Finished userdb lookup
Jun 20 21:22:41 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<32>: Finished
Jun 20 21:22:41 auth: Debug: sql(mattill at bbspirmasens.de): Finished userdb lookup
Jun 20 21:22:41 auth: Debug: userdb out: USER	2	mattill at bbspirmasens.de	home=/var/dovecot/imap/bbspirmasens.de/Mattill	master_user=mattill at bbspirmasens.de	acl_groups=default at bbspirmasens.de,alle bbsps at bbspirmasens.de,kollegium at bbspirmasens.de,
Jun 20 21:22:42 auth-worker(25): Debug: dict(proxy): Lookup finished for 'shared/passdb/gs.abbehausen at nordXXXXXX.de/': not found
Jun 20 21:22:42 auth-worker(25): Debug: dict(proxy): Lookup finished for 'shared/passdb/gs.abbehausen at nordXXXXXX.de/': not found
Jun 20 21:22:42 auth-worker(25): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<10>: dict(gs.abbehausen at nordXXXXXX.de): unknown user
Jun 20 21:22:42 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<10>: dict(gs.abbehausen at nordXXXXXX.de): Finished passdb lookup
Jun 20 21:22:42 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<10>: Finished
Jun 20 21:22:42 auth-worker(25): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:42 auth: Debug: dict(gs.abbehausen at nordXXXXXX.de): Finished passdb lookup
Jun 20 21:22:42 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:42 auth: Debug: master in: USER	2	gs.abbehausen at nordXXXXXX.de	service=doveadm
Jun 20 21:22:42 auth: Debug: sql(gs.abbehausen at nordXXXXXX.de): Performing userdb lookup
Jun 20 21:22:42 auth: Debug: sql(gs.abbehausen at nordXXXXXX.de): userdb cache miss
Jun 20 21:22:42 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<33>: Handling USER request
Jun 20 21:22:42 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<33>: sql(gs.abbehausen at nordXXXXXX.de): Performing userdb lookup
Jun 20 21:22:42 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<33>: sql(gs.abbehausen at nordXXXXXX.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'gs.abbehausen' AND domain = 'nordXXXXXX.de'
Jun 20 21:22:42 auth-worker(20): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'gs.abbehausen' AND domain = 'nordXXXXXX.de'' in 0 msecs
Jun 20 21:22:42 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<33>: sql(gs.abbehausen at nordXXXXXX.de): Finished userdb lookup
Jun 20 21:22:42 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<33>: Finished
Jun 20 21:22:42 auth: Debug: sql(gs.abbehausen at nordXXXXXX.de): Finished userdb lookup
Jun 20 21:22:42 auth: Debug: userdb out: USER	2	gs.abbehausen at nordXXXXXX.de	home=/var/dovecot/imap/nordXXXXXX.de/gs.abbehausen	master_user=gs.abbehausen at nordXXXXXX.de	acl_groups=domain users at nordXXXXXX.de,
Jun 20 21:22:42 auth: Debug: master in: PASS	1	grundsicherung at nordXXXXXX.de	service=doveadm
Jun 20 21:22:42 auth: Debug: dict(grundsicherung at nordXXXXXX.de): Performing passdb lookup
Jun 20 21:22:42 auth: Debug: dict(grundsicherung at nordXXXXXX.de): cache miss
Jun 20 21:22:42 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<34>: Handling PASSL request
Jun 20 21:22:42 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<34>: dict(grundsicherung at nordXXXXXX.de): Performing passdb lookup
Jun 20 21:22:42 auth-worker(20): Debug: dict(proxy): Looking up 'shared/passdb/grundsicherung at nordXXXXXX.de/'
Jun 20 21:22:42 auth-worker(20): Debug: dict(proxy): Looking up (async) 'shared/passdb/grundsicherung at nordXXXXXX.de/'
Jun 20 21:22:42 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:42 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:42 auth: Debug: dict(hadi at bb-trunk.egroupware.de,10.10.30.11,<a0cStOfhbkIKCh4L>): Performing passdb lookup
Jun 20 21:22:42 auth: Debug: dict(hadi at bb-trunk.egroupware.de,10.10.30.11,<a0cStOfhbkIKCh4L>): cache hit: 
Jun 20 21:22:42 auth: Info: dict(hadi at bb-trunk.egroupware.de,10.10.30.11,<a0cStOfhbkIKCh4L>): unknown user
Jun 20 21:22:42 auth: Debug: dict(hadi at bb-trunk.egroupware.de,10.10.30.11,<a0cStOfhbkIKCh4L>): Finished passdb lookup
Jun 20 21:22:42 auth: Debug: auth(hadi at bb-trunk.egroupware.de,10.10.30.11,<a0cStOfhbkIKCh4L>): Auth request finished
Jun 20 21:22:42 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/grundsicherung at nordXXXXXX.de/': not found
Jun 20 21:22:42 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/grundsicherung at nordXXXXXX.de/': not found
Jun 20 21:22:42 auth-worker(20): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<34>: dict(grundsicherung at nordXXXXXX.de): unknown user
Jun 20 21:22:42 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<34>: dict(grundsicherung at nordXXXXXX.de): Finished passdb lookup
Jun 20 21:22:42 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<34>: Finished
Jun 20 21:22:42 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:42 auth: Debug: dict(grundsicherung at nordXXXXXX.de): Finished passdb lookup
Jun 20 21:22:42 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:42 auth: Debug: master in: USER	2	grundsicherung at nordXXXXXX.de	service=doveadm
Jun 20 21:22:42 auth: Debug: sql(grundsicherung at nordXXXXXX.de): Performing userdb lookup
Jun 20 21:22:42 auth: Debug: sql(grundsicherung at nordXXXXXX.de): userdb cache miss
Jun 20 21:22:42 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<35>: Handling USER request
Jun 20 21:22:42 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<35>: sql(grundsicherung at nordXXXXXX.de): Performing userdb lookup
Jun 20 21:22:42 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<35>: sql(grundsicherung at nordXXXXXX.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'grundsicherung' AND domain = 'nordXXXXXX.de'
Jun 20 21:22:42 auth-worker(20): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'grundsicherung' AND domain = 'nordXXXXXX.de'' in 0 msecs
Jun 20 21:22:42 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<35>: sql(grundsicherung at nordXXXXXX.de): Finished userdb lookup
Jun 20 21:22:42 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<35>: Finished
Jun 20 21:22:42 auth: Debug: sql(grundsicherung at nordXXXXXX.de): Finished userdb lookup
Jun 20 21:22:42 auth: Debug: userdb out: USER	2	grundsicherung at nordXXXXXX.de	home=/var/dovecot/imap/nordXXXXXX.de/grundsicherung	master_user=grundsicherung at nordXXXXXX.de	acl_groups=domain users at nordXXXXXX.de,
Jun 20 21:22:42 auth: Debug: dict(discourse at outdoor-training.de,10.10.30.11,<kkEYtOfhXyYKCh4L>): Performing passdb lookup
Jun 20 21:22:42 auth: Debug: dict(discourse at outdoor-training.de,10.10.30.11,<kkEYtOfhXyYKCh4L>): cache miss
Jun 20 21:22:42 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<36>: Handling PASSV request
Jun 20 21:22:42 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<36>: dict(discourse at outdoor-training.de,10.10.30.11,<kkEYtOfhXyYKCh4L>): Performing passdb lookup
Jun 20 21:22:42 auth-worker(20): Debug: dict(proxy): Looking up 'shared/passdb/discourse at outdoor-training.de/tHv2b4O9t/{4'
Jun 20 21:22:42 auth-worker(20): Debug: dict(proxy): Looking up (async) 'shared/passdb/discourse at outdoor-training.de/tHv2b4O9t/{4'
Jun 20 21:22:42 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:42 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:42 auth: Debug: master in: PASS	1	c.amelunxen at rbz-schuetzenpark.de	service=doveadm
Jun 20 21:22:42 auth: Debug: dict(c.amelunxen at rbz-schuetzenpark.de): Performing passdb lookup
Jun 20 21:22:42 auth: Debug: dict(c.amelunxen at rbz-schuetzenpark.de): cache miss
Jun 20 21:22:42 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<11>: Handling PASSL request
Jun 20 21:22:42 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<11>: dict(c.amelunxen at rbz-schuetzenpark.de): Performing passdb lookup
Jun 20 21:22:42 auth-worker(25): Debug: dict(proxy): Looking up 'shared/passdb/c.amelunxen at rbz-schuetzenpark.de/'
Jun 20 21:22:42 auth-worker(25): Debug: dict(proxy): Looking up (async) 'shared/passdb/c.amelunxen at rbz-schuetzenpark.de/'
Jun 20 21:22:42 auth-worker(25): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:42 auth-worker(25): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:42 auth: Debug: master in: PASS	1	c.alkis at rbz-schuetzenpark.de	service=doveadm
Jun 20 21:22:42 auth: Debug: dict(c.alkis at rbz-schuetzenpark.de): Performing passdb lookup
Jun 20 21:22:42 auth: Debug: dict(c.alkis at rbz-schuetzenpark.de): cache miss
Jun 20 21:22:42 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<7>: Handling PASSL request
Jun 20 21:22:42 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<7>: dict(c.alkis at rbz-schuetzenpark.de): Performing passdb lookup
Jun 20 21:22:42 auth-worker(27): Debug: dict(proxy): Looking up 'shared/passdb/c.alkis at rbz-schuetzenpark.de/'
Jun 20 21:22:42 auth-worker(27): Debug: dict(proxy): Looking up (async) 'shared/passdb/c.alkis at rbz-schuetzenpark.de/'
Jun 20 21:22:42 auth-worker(27): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:42 auth-worker(27): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:42 imap-login: Info: Disconnected: Aborted login by logging out (no auth attempts in 0 secs): user=<>, rip=10.9.94.14, lip=172.18.0.2, session=<9YZYtOfhp0UKCV4O>
Jun 20 21:22:42 auth: Debug: auth client connected (pid=65)
Jun 20 21:22:43 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/discourse at outdoor-training.de/tHv2b4O9t/{4': not found
Jun 20 21:22:43 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/discourse at outdoor-training.de/tHv2b4O9t/{4': not found
Jun 20 21:22:43 auth-worker(20): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<36>: dict(discourse at outdoor-training.de,10.10.30.11,<kkEYtOfhXyYKCh4L>): unknown user
Jun 20 21:22:43 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<36>: dict(discourse at outdoor-training.de,10.10.30.11,<kkEYtOfhXyYKCh4L>): Finished passdb lookup
Jun 20 21:22:43 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<36>: Finished: user_unknown
Jun 20 21:22:43 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:43 auth: Debug: dict(discourse at outdoor-training.de,10.10.30.11,<kkEYtOfhXyYKCh4L>): Finished passdb lookup
Jun 20 21:22:43 auth: Debug: auth(discourse at outdoor-training.de,10.10.30.11,<kkEYtOfhXyYKCh4L>): Auth request finished
Jun 20 21:22:43 auth: Debug: master in: PASS	1	beckerc at bbspirmasens.de	service=doveadm
Jun 20 21:22:43 auth: Debug: dict(beckerc at bbspirmasens.de): Performing passdb lookup
Jun 20 21:22:43 auth: Debug: dict(beckerc at bbspirmasens.de): cache miss
Jun 20 21:22:43 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<37>: Handling PASSL request
Jun 20 21:22:43 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<37>: dict(beckerc at bbspirmasens.de): Performing passdb lookup
Jun 20 21:22:43 auth-worker(20): Debug: dict(proxy): Looking up 'shared/passdb/beckerc at bbspirmasens.de/'
Jun 20 21:22:43 auth-worker(20): Debug: dict(proxy): Looking up (async) 'shared/passdb/beckerc at bbspirmasens.de/'
Jun 20 21:22:43 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:43 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:43 auth-worker(27): Debug: dict(proxy): Lookup finished for 'shared/passdb/c.alkis at rbz-schuetzenpark.de/': not found
Jun 20 21:22:43 auth-worker(27): Debug: dict(proxy): Lookup finished for 'shared/passdb/c.alkis at rbz-schuetzenpark.de/': not found
Jun 20 21:22:43 auth-worker(27): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<7>: dict(c.alkis at rbz-schuetzenpark.de): unknown user
Jun 20 21:22:43 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<7>: dict(c.alkis at rbz-schuetzenpark.de): Finished passdb lookup
Jun 20 21:22:43 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<7>: Finished
Jun 20 21:22:43 auth-worker(27): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:43 auth: Debug: dict(c.alkis at rbz-schuetzenpark.de): Finished passdb lookup
Jun 20 21:22:43 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:43 auth: Debug: master in: USER	2	c.alkis at rbz-schuetzenpark.de	service=doveadm
Jun 20 21:22:43 auth: Debug: sql(c.alkis at rbz-schuetzenpark.de): Performing userdb lookup
Jun 20 21:22:43 auth: Debug: sql(c.alkis at rbz-schuetzenpark.de): userdb cache miss
Jun 20 21:22:43 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<8>: Handling USER request
Jun 20 21:22:43 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<8>: sql(c.alkis at rbz-schuetzenpark.de): Performing userdb lookup
Jun 20 21:22:43 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<8>: sql(c.alkis at rbz-schuetzenpark.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'c.alkis' AND domain = 'rbz-schuetzenpark.de'
Jun 20 21:22:43 auth-worker(27): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'c.alkis' AND domain = 'rbz-schuetzenpark.de'' in 0 msecs
Jun 20 21:22:43 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<8>: sql(c.alkis at rbz-schuetzenpark.de): Finished userdb lookup
Jun 20 21:22:43 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<8>: Finished
Jun 20 21:22:43 auth: Debug: sql(c.alkis at rbz-schuetzenpark.de): Finished userdb lookup
Jun 20 21:22:43 auth: Debug: userdb out: USER	2	c.alkis at rbz-schuetzenpark.de	home=/var/dovecot/imap/rbz-schuetzenpark.de/c.alkis	master_user=c.alkis at rbz-schuetzenpark.de	acl_groups=default at rbz-schuetzenpark.de,gastronomie at rbz-schuetzenpark.de,kollegium at rbz-schuetzenpark.de,rbz-am-schützenpark at rbz-schuetzenpark.de,deutsch-förderunterricht at rbz-schuetzenpark.de,bv-bvb at rbz-schuetzenpark.de,bv-deutsch-komm at rbz-schuetzenpark.de,deutsch at rbz-schuetzenpark.de,lehrkräfte-in-vorbereitung at rbz-schuetzenpark.de,nahrung at rbz-schuetzenpark.de,fos-bos at rbz-schuetzenpark.de,
Jun 20 21:22:43 auth-worker(25): Debug: dict(proxy): Lookup finished for 'shared/passdb/c.amelunxen at rbz-schuetzenpark.de/': not found
Jun 20 21:22:43 auth-worker(25): Debug: dict(proxy): Lookup finished for 'shared/passdb/c.amelunxen at rbz-schuetzenpark.de/': not found
Jun 20 21:22:43 auth-worker(25): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<11>: dict(c.amelunxen at rbz-schuetzenpark.de): unknown user
Jun 20 21:22:43 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<11>: dict(c.amelunxen at rbz-schuetzenpark.de): Finished passdb lookup
Jun 20 21:22:43 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<11>: Finished
Jun 20 21:22:43 auth-worker(25): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:43 auth: Debug: dict(c.amelunxen at rbz-schuetzenpark.de): Finished passdb lookup
Jun 20 21:22:43 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:43 auth: Debug: master in: USER	2	c.amelunxen at rbz-schuetzenpark.de	service=doveadm
Jun 20 21:22:43 auth: Debug: sql(c.amelunxen at rbz-schuetzenpark.de): Performing userdb lookup
Jun 20 21:22:43 auth: Debug: sql(c.amelunxen at rbz-schuetzenpark.de): userdb cache miss
Jun 20 21:22:43 auth: Debug: sql(c.amelunxen at rbz-schuetzenpark.de): Finished userdb lookup
Jun 20 21:22:43 auth: Debug: userdb out: USER	2	c.amelunxen at rbz-schuetzenpark.de	home=/var/dovecot/imap/rbz-schuetzenpark.de/c.amelunxen	master_user=c.amelunxen at rbz-schuetzenpark.de	acl_groups=default at rbz-schuetzenpark.de,
Jun 20 21:22:43 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<12>: Handling USER request
Jun 20 21:22:43 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<12>: sql(c.amelunxen at rbz-schuetzenpark.de): Performing userdb lookup
Jun 20 21:22:43 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<12>: sql(c.amelunxen at rbz-schuetzenpark.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'c.amelunxen' AND domain = 'rbz-schuetzenpark.de'
Jun 20 21:22:43 auth-worker(25): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'c.amelunxen' AND domain = 'rbz-schuetzenpark.de'' in 0 msecs
Jun 20 21:22:43 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<12>: sql(c.amelunxen at rbz-schuetzenpark.de): Finished userdb lookup
Jun 20 21:22:43 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<12>: Finished
Jun 20 21:22:43 auth: Debug: master in: PASS	1	marondel at bbspirmasens.de	service=doveadm
Jun 20 21:22:43 auth: Debug: dict(marondel at bbspirmasens.de): Performing passdb lookup
Jun 20 21:22:43 auth: Debug: dict(marondel at bbspirmasens.de): cache miss
Jun 20 21:22:43 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<13>: Handling PASSL request
Jun 20 21:22:43 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<13>: dict(marondel at bbspirmasens.de): Performing passdb lookup
Jun 20 21:22:43 auth-worker(25): Debug: dict(proxy): Looking up 'shared/passdb/marondel at bbspirmasens.de/'
Jun 20 21:22:43 auth-worker(25): Debug: dict(proxy): Looking up (async) 'shared/passdb/marondel at bbspirmasens.de/'
Jun 20 21:22:43 auth-worker(25): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:43 auth-worker(25): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:43 auth: Debug: master in: PASS	1	gebaeudewirtschaft at nordXXXXXX.de	service=doveadm
Jun 20 21:22:43 auth: Debug: dict(gebaeudewirtschaft at nordXXXXXX.de): Performing passdb lookup
Jun 20 21:22:43 auth: Debug: dict(gebaeudewirtschaft at nordXXXXXX.de): cache miss
Jun 20 21:22:43 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<9>: Handling PASSL request
Jun 20 21:22:43 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<9>: dict(gebaeudewirtschaft at nordXXXXXX.de): Performing passdb lookup
Jun 20 21:22:43 auth-worker(27): Debug: dict(proxy): Looking up 'shared/passdb/gebaeudewirtschaft at nordXXXXXX.de/'
Jun 20 21:22:43 auth-worker(27): Debug: dict(proxy): Looking up (async) 'shared/passdb/gebaeudewirtschaft at nordXXXXXX.de/'
Jun 20 21:22:43 auth-worker(27): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:43 auth-worker(27): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:43 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/beckerc at bbspirmasens.de/': not found
Jun 20 21:22:43 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/beckerc at bbspirmasens.de/': not found
Jun 20 21:22:43 auth-worker(20): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<37>: dict(beckerc at bbspirmasens.de): unknown user
Jun 20 21:22:43 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<37>: dict(beckerc at bbspirmasens.de): Finished passdb lookup
Jun 20 21:22:43 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<37>: Finished
Jun 20 21:22:43 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:43 auth: Debug: dict(beckerc at bbspirmasens.de): Finished passdb lookup
Jun 20 21:22:43 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:43 auth: Debug: master in: USER	2	beckerc at bbspirmasens.de	service=doveadm
Jun 20 21:22:43 auth: Debug: sql(beckerc at bbspirmasens.de): Performing userdb lookup
Jun 20 21:22:43 auth: Debug: sql(beckerc at bbspirmasens.de): userdb cache miss
Jun 20 21:22:43 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<38>: Handling USER request
Jun 20 21:22:43 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<38>: sql(beckerc at bbspirmasens.de): Performing userdb lookup
Jun 20 21:22:43 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<38>: sql(beckerc at bbspirmasens.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'beckerc' AND domain = 'bbspirmasens.de'
Jun 20 21:22:43 auth-worker(20): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'beckerc' AND domain = 'bbspirmasens.de'' in 0 msecs
Jun 20 21:22:43 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<38>: sql(beckerc at bbspirmasens.de): Finished userdb lookup
Jun 20 21:22:43 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<38>: Finished
Jun 20 21:22:43 auth: Debug: sql(beckerc at bbspirmasens.de): Finished userdb lookup
Jun 20 21:22:43 auth: Debug: userdb out: USER	2	beckerc at bbspirmasens.de	home=/var/dovecot/imap/bbspirmasens.de/BeckerC	master_user=beckerc at bbspirmasens.de	acl_groups=alle bbsps at bbspirmasens.de,kollegium at bbspirmasens.de,
Jun 20 21:22:43 auth-worker(25): Debug: dict(proxy): Lookup finished for 'shared/passdb/marondel at bbspirmasens.de/': not found
Jun 20 21:22:43 auth-worker(25): Debug: dict(proxy): Lookup finished for 'shared/passdb/marondel at bbspirmasens.de/': not found
Jun 20 21:22:43 auth-worker(25): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<13>: dict(marondel at bbspirmasens.de): unknown user
Jun 20 21:22:43 auth: Debug: dict(marondel at bbspirmasens.de): Finished passdb lookup
Jun 20 21:22:43 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:43 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<13>: dict(marondel at bbspirmasens.de): Finished passdb lookup
Jun 20 21:22:43 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<13>: Finished
Jun 20 21:22:43 auth-worker(25): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:43 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<39>: Handling USER request
Jun 20 21:22:43 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<39>: sql(marondel at bbspirmasens.de): Performing userdb lookup
Jun 20 21:22:43 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<39>: sql(marondel at bbspirmasens.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'marondel' AND domain = 'bbspirmasens.de'
Jun 20 21:22:43 auth-worker(20): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'marondel' AND domain = 'bbspirmasens.de'' in 0 msecs
Jun 20 21:22:43 auth: Debug: master in: USER	2	marondel at bbspirmasens.de	service=doveadm
Jun 20 21:22:43 auth: Debug: sql(marondel at bbspirmasens.de): Performing userdb lookup
Jun 20 21:22:43 auth: Debug: sql(marondel at bbspirmasens.de): userdb cache miss
Jun 20 21:22:43 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<39>: sql(marondel at bbspirmasens.de): Finished userdb lookup
Jun 20 21:22:43 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<39>: Finished
Jun 20 21:22:43 auth: Debug: sql(marondel at bbspirmasens.de): Finished userdb lookup
Jun 20 21:22:43 auth: Debug: userdb out: USER	2	marondel at bbspirmasens.de	home=/var/dovecot/imap/bbspirmasens.de/Marondel	master_user=marondel at bbspirmasens.de	acl_groups=alle bbsps at bbspirmasens.de,kollegium at bbspirmasens.de,
Jun 20 21:22:43 imap-login: Info: Disconnected: Aborted login by logging out (no auth attempts in 0 secs): user=<>, rip=10.10.30.11, lip=172.18.0.2, session=<pzxmtOfhgOEKCh4L>
Jun 20 21:22:43 auth: Debug: auth client connected (pid=69)
Jun 20 21:22:43 auth-worker(27): Debug: dict(proxy): Lookup finished for 'shared/passdb/gebaeudewirtschaft at nordXXXXXX.de/': not found
Jun 20 21:22:43 auth-worker(27): Debug: dict(proxy): Lookup finished for 'shared/passdb/gebaeudewirtschaft at nordXXXXXX.de/': not found
Jun 20 21:22:43 auth-worker(27): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<9>: dict(gebaeudewirtschaft at nordXXXXXX.de): unknown user
Jun 20 21:22:43 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<9>: dict(gebaeudewirtschaft at nordXXXXXX.de): Finished passdb lookup
Jun 20 21:22:43 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<9>: Finished
Jun 20 21:22:43 auth-worker(27): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:43 auth: Debug: dict(gebaeudewirtschaft at nordXXXXXX.de): Finished passdb lookup
Jun 20 21:22:43 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:43 auth: Debug: master in: USER	2	gebaeudewirtschaft at nordXXXXXX.de	service=doveadm
Jun 20 21:22:43 auth: Debug: sql(gebaeudewirtschaft at nordXXXXXX.de): Performing userdb lookup
Jun 20 21:22:43 auth: Debug: sql(gebaeudewirtschaft at nordXXXXXX.de): userdb cache miss
Jun 20 21:22:43 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<40>: Handling USER request
Jun 20 21:22:43 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<40>: sql(gebaeudewirtschaft at nordXXXXXX.de): Performing userdb lookup
Jun 20 21:22:43 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<40>: sql(gebaeudewirtschaft at nordXXXXXX.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'gebaeudewirtschaft' AND domain = 'nordXXXXXX.de'
Jun 20 21:22:43 auth-worker(20): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'gebaeudewirtschaft' AND domain = 'nordXXXXXX.de'' in 0 msecs
Jun 20 21:22:43 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<40>: sql(gebaeudewirtschaft at nordXXXXXX.de): Finished userdb lookup
Jun 20 21:22:43 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<40>: Finished
Jun 20 21:22:43 auth: Debug: sql(gebaeudewirtschaft at nordXXXXXX.de): Finished userdb lookup
Jun 20 21:22:43 auth: Debug: userdb out: USER	2	gebaeudewirtschaft at nordXXXXXX.de	home=/var/dovecot/imap/nordXXXXXX.de/gebaeudewirtschaft	quota_rule=*:bytes=214748364800	master_user=gebaeudewirtschaft at nordXXXXXX.de	acl_groups=default at nordXXXXXX.de,
Jun 20 21:22:43 auth: Debug: master in: PASS	1	b.zurek at rbz-schuetzenpark.de	service=doveadm
Jun 20 21:22:43 auth: Debug: dict(b.zurek at rbz-schuetzenpark.de): Performing passdb lookup
Jun 20 21:22:43 auth: Debug: dict(b.zurek at rbz-schuetzenpark.de): cache miss
Jun 20 21:22:43 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<41>: Handling PASSL request
Jun 20 21:22:43 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<41>: dict(b.zurek at rbz-schuetzenpark.de): Performing passdb lookup
Jun 20 21:22:43 auth-worker(20): Debug: dict(proxy): Looking up 'shared/passdb/b.zurek at rbz-schuetzenpark.de/'
Jun 20 21:22:43 auth-worker(20): Debug: dict(proxy): Looking up (async) 'shared/passdb/b.zurek at rbz-schuetzenpark.de/'
Jun 20 21:22:43 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:43 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:44 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/b.zurek at rbz-schuetzenpark.de/': not found
Jun 20 21:22:44 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/b.zurek at rbz-schuetzenpark.de/': not found
Jun 20 21:22:44 auth-worker(20): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<41>: dict(b.zurek at rbz-schuetzenpark.de): unknown user
Jun 20 21:22:44 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<41>: dict(b.zurek at rbz-schuetzenpark.de): Finished passdb lookup
Jun 20 21:22:44 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<41>: Finished
Jun 20 21:22:44 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:44 auth: Debug: dict(b.zurek at rbz-schuetzenpark.de): Finished passdb lookup
Jun 20 21:22:44 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:44 auth: Debug: master in: USER	2	b.zurek at rbz-schuetzenpark.de	service=doveadm
Jun 20 21:22:44 auth: Debug: sql(b.zurek at rbz-schuetzenpark.de): Performing userdb lookup
Jun 20 21:22:44 auth: Debug: sql(b.zurek at rbz-schuetzenpark.de): userdb cache miss
Jun 20 21:22:44 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<42>: Handling USER request
Jun 20 21:22:44 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<42>: sql(b.zurek at rbz-schuetzenpark.de): Performing userdb lookup
Jun 20 21:22:44 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<42>: sql(b.zurek at rbz-schuetzenpark.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'b.zurek' AND domain = 'rbz-schuetzenpark.de'
Jun 20 21:22:44 auth-worker(20): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'b.zurek' AND domain = 'rbz-schuetzenpark.de'' in 0 msecs
Jun 20 21:22:44 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<42>: sql(b.zurek at rbz-schuetzenpark.de): Finished userdb lookup
Jun 20 21:22:44 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<42>: Finished
Jun 20 21:22:44 auth: Debug: sql(b.zurek at rbz-schuetzenpark.de): Finished userdb lookup
Jun 20 21:22:44 auth: Debug: userdb out: USER	2	b.zurek at rbz-schuetzenpark.de	home=/var/dovecot/imap/rbz-schuetzenpark.de/b.zurek	master_user=b.zurek at rbz-schuetzenpark.de	acl_groups=default at rbz-schuetzenpark.de,kollegium at rbz-schuetzenpark.de,rbz-am-schützenpark at rbz-schuetzenpark.de,bv at rbz-schuetzenpark.de,bv-bik at rbz-schuetzenpark.de,
Jun 20 21:22:44 auth: Debug: master in: PASS	1	b.vagt at rbz-schuetzenpark.de	service=doveadm
Jun 20 21:22:44 auth: Debug: dict(b.vagt at rbz-schuetzenpark.de): Performing passdb lookup
Jun 20 21:22:44 auth: Debug: dict(b.vagt at rbz-schuetzenpark.de): cache miss
Jun 20 21:22:44 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<43>: Handling PASSL request
Jun 20 21:22:44 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<43>: dict(b.vagt at rbz-schuetzenpark.de): Performing passdb lookup
Jun 20 21:22:44 auth-worker(20): Debug: dict(proxy): Looking up 'shared/passdb/b.vagt at rbz-schuetzenpark.de/'
Jun 20 21:22:44 auth-worker(20): Debug: dict(proxy): Looking up (async) 'shared/passdb/b.vagt at rbz-schuetzenpark.de/'
Jun 20 21:22:44 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:44 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:44 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/b.vagt at rbz-schuetzenpark.de/': not found
Jun 20 21:22:44 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/b.vagt at rbz-schuetzenpark.de/': not found
Jun 20 21:22:44 auth-worker(20): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<43>: dict(b.vagt at rbz-schuetzenpark.de): unknown user
Jun 20 21:22:44 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<43>: dict(b.vagt at rbz-schuetzenpark.de): Finished passdb lookup
Jun 20 21:22:44 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<43>: Finished
Jun 20 21:22:44 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:44 auth: Debug: dict(b.vagt at rbz-schuetzenpark.de): Finished passdb lookup
Jun 20 21:22:44 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:44 auth: Debug: master in: USER	2	b.vagt at rbz-schuetzenpark.de	service=doveadm
Jun 20 21:22:44 auth: Debug: sql(b.vagt at rbz-schuetzenpark.de): Performing userdb lookup
Jun 20 21:22:44 auth: Debug: sql(b.vagt at rbz-schuetzenpark.de): userdb cache miss
Jun 20 21:22:44 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<44>: Handling USER request
Jun 20 21:22:44 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<44>: sql(b.vagt at rbz-schuetzenpark.de): Performing userdb lookup
Jun 20 21:22:44 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<44>: sql(b.vagt at rbz-schuetzenpark.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'b.vagt' AND domain = 'rbz-schuetzenpark.de'
Jun 20 21:22:44 auth-worker(20): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'b.vagt' AND domain = 'rbz-schuetzenpark.de'' in 0 msecs
Jun 20 21:22:44 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<44>: sql(b.vagt at rbz-schuetzenpark.de): Finished userdb lookup
Jun 20 21:22:44 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<44>: Finished
Jun 20 21:22:44 auth: Debug: sql(b.vagt at rbz-schuetzenpark.de): Finished userdb lookup
Jun 20 21:22:44 auth: Debug: userdb out: USER	2	b.vagt at rbz-schuetzenpark.de	home=/var/dovecot/imap/rbz-schuetzenpark.de/b.vagt	master_user=b.vagt at rbz-schuetzenpark.de	acl_groups=rbz-am-schützenpark at rbz-schuetzenpark.de,default at rbz-schuetzenpark.de,mensa at rbz-schuetzenpark.de,kollegium at rbz-schuetzenpark.de,bv-av-d at rbz-schuetzenpark.de,bfs at rbz-schuetzenpark.de,bv at rbz-schuetzenpark.de,
Jun 20 21:22:44 auth: Debug: master in: PASS	1	fynbru01 at nordXXXXXX.de	service=doveadm
Jun 20 21:22:44 auth: Debug: dict(fynbru01 at nordXXXXXX.de): Performing passdb lookup
Jun 20 21:22:44 auth: Debug: dict(fynbru01 at nordXXXXXX.de): cache miss
Jun 20 21:22:44 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<45>: Handling PASSL request
Jun 20 21:22:44 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<45>: dict(fynbru01 at nordXXXXXX.de): Performing passdb lookup
Jun 20 21:22:44 auth-worker(20): Debug: dict(proxy): Looking up 'shared/passdb/fynbru01 at nordXXXXXX.de/'
Jun 20 21:22:44 auth-worker(20): Debug: dict(proxy): Looking up (async) 'shared/passdb/fynbru01 at nordXXXXXX.de/'
Jun 20 21:22:44 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:44 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:44 auth: Debug: client passdb out: FAIL	1	user=hadi at bb-trunk.egroupware.de
Jun 20 21:22:44 imap-login: Info: Disconnected: Connection closed (auth failed, 1 attempts in 6 secs): user=<hadi at bb-trunk.egroupware.de>, method=PLAIN, rip=10.10.30.11, lip=172.18.0.2, session=<a0cStOfhbkIKCh4L>
Jun 20 21:22:44 auth: Debug: client in: AUTH	1	PLAIN	service=imap	session=jwVutOfhSZoKCh4L	lip=172.18.0.2	rip=10.10.30.11	lport=143	rport=39497	resp=<hidden>
Jun 20 21:22:44 auth: Debug: auth client connected (pid=80)
Jun 20 21:22:44 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/fynbru01 at nordXXXXXX.de/': not found
Jun 20 21:22:44 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/fynbru01 at nordXXXXXX.de/': not found
Jun 20 21:22:44 auth-worker(20): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<45>: dict(fynbru01 at nordXXXXXX.de): unknown user
Jun 20 21:22:44 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<45>: dict(fynbru01 at nordXXXXXX.de): Finished passdb lookup
Jun 20 21:22:44 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<45>: Finished
Jun 20 21:22:44 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:44 auth: Debug: dict(fynbru01 at nordXXXXXX.de): Finished passdb lookup
Jun 20 21:22:44 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:44 auth: Debug: master in: USER	2	fynbru01 at nordXXXXXX.de	service=doveadm
Jun 20 21:22:44 auth: Debug: sql(fynbru01 at nordXXXXXX.de): Performing userdb lookup
Jun 20 21:22:44 auth: Debug: sql(fynbru01 at nordXXXXXX.de): userdb cache miss
Jun 20 21:22:44 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<46>: Handling USER request
Jun 20 21:22:44 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<46>: sql(fynbru01 at nordXXXXXX.de): Performing userdb lookup
Jun 20 21:22:44 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<46>: sql(fynbru01 at nordXXXXXX.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'fynbru01' AND domain = 'nordXXXXXX.de'
Jun 20 21:22:44 auth-worker(20): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'fynbru01' AND domain = 'nordXXXXXX.de'' in 0 msecs
Jun 20 21:22:44 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<46>: sql(fynbru01 at nordXXXXXX.de): Finished userdb lookup
Jun 20 21:22:44 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<46>: Finished
Jun 20 21:22:44 auth: Debug: sql(fynbru01 at nordXXXXXX.de): Finished userdb lookup
Jun 20 21:22:44 auth: Debug: userdb out: USER	2	fynbru01 at nordXXXXXX.de	home=/var/dovecot/imap/nordXXXXXX.de/fynbru01	master_user=fynbru01 at nordXXXXXX.de	acl_groups=default at nordXXXXXX.de,domain users at nordXXXXXX.de,rathaus at nordXXXXXX.de,
Jun 20 21:22:44 auth: Debug: master in: PASS	1	b.steenbock at rbz-schuetzenpark.de	service=doveadm
Jun 20 21:22:44 auth: Debug: dict(b.steenbock at rbz-schuetzenpark.de): Performing passdb lookup
Jun 20 21:22:44 auth: Debug: dict(b.steenbock at rbz-schuetzenpark.de): cache miss
Jun 20 21:22:44 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<47>: Handling PASSL request
Jun 20 21:22:44 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<47>: dict(b.steenbock at rbz-schuetzenpark.de): Performing passdb lookup
Jun 20 21:22:44 auth-worker(20): Debug: dict(proxy): Looking up 'shared/passdb/b.steenbock at rbz-schuetzenpark.de/'
Jun 20 21:22:44 auth-worker(20): Debug: dict(proxy): Looking up (async) 'shared/passdb/b.steenbock at rbz-schuetzenpark.de/'
Jun 20 21:22:44 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:44 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:44 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/b.steenbock at rbz-schuetzenpark.de/': not found
Jun 20 21:22:44 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/b.steenbock at rbz-schuetzenpark.de/': not found
Jun 20 21:22:44 auth-worker(20): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<47>: dict(b.steenbock at rbz-schuetzenpark.de): unknown user
Jun 20 21:22:44 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<47>: dict(b.steenbock at rbz-schuetzenpark.de): Finished passdb lookup
Jun 20 21:22:44 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<47>: Finished
Jun 20 21:22:44 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:44 auth: Debug: dict(b.steenbock at rbz-schuetzenpark.de): Finished passdb lookup
Jun 20 21:22:44 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:44 auth: Debug: master in: USER	2	b.steenbock at rbz-schuetzenpark.de	service=doveadm
Jun 20 21:22:44 auth: Debug: sql(b.steenbock at rbz-schuetzenpark.de): Performing userdb lookup
Jun 20 21:22:44 auth: Debug: sql(b.steenbock at rbz-schuetzenpark.de): userdb cache miss
Jun 20 21:22:44 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<48>: Handling USER request
Jun 20 21:22:44 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<48>: sql(b.steenbock at rbz-schuetzenpark.de): Performing userdb lookup
Jun 20 21:22:44 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<48>: sql(b.steenbock at rbz-schuetzenpark.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'b.steenbock' AND domain = 'rbz-schuetzenpark.de'
Jun 20 21:22:44 auth-worker(20): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'b.steenbock' AND domain = 'rbz-schuetzenpark.de'' in 0 msecs
Jun 20 21:22:44 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<48>: sql(b.steenbock at rbz-schuetzenpark.de): Finished userdb lookup
Jun 20 21:22:44 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<48>: Finished
Jun 20 21:22:44 auth: Debug: sql(b.steenbock at rbz-schuetzenpark.de): Finished userdb lookup
Jun 20 21:22:44 auth: Debug: userdb out: USER	2	b.steenbock at rbz-schuetzenpark.de	home=/var/dovecot/imap/rbz-schuetzenpark.de/b.steenbock	master_user=b.steenbock at rbz-schuetzenpark.de	acl_groups=wipo at rbz-schuetzenpark.de,rbz-am-schützenpark at rbz-schuetzenpark.de,default at rbz-schuetzenpark.de,kollegium at rbz-schuetzenpark.de,bfs at rbz-schuetzenpark.de,vertretung at rbz-schuetzenpark.de,pädagogische-konferenz at rbz-schuetzenpark.de,bv at rbz-schuetzenpark.de,europaschule at rbz-schuetzenpark.de,
Jun 20 21:22:44 auth: Debug: master in: PASS	1	b.schultenkaemper at rbz-schuetzenpark.de	service=doveadm
Jun 20 21:22:44 auth: Debug: dict(b.schultenkaemper at rbz-schuetzenpark.de): Performing passdb lookup
Jun 20 21:22:44 auth: Debug: dict(b.schultenkaemper at rbz-schuetzenpark.de): cache miss
Jun 20 21:22:44 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<49>: Handling PASSL request
Jun 20 21:22:44 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<49>: dict(b.schultenkaemper at rbz-schuetzenpark.de): Performing passdb lookup
Jun 20 21:22:44 auth-worker(20): Debug: dict(proxy): Looking up 'shared/passdb/b.schultenkaemper at rbz-schuetzenpark.de/'
Jun 20 21:22:44 auth-worker(20): Debug: dict(proxy): Looking up (async) 'shared/passdb/b.schultenkaemper at rbz-schuetzenpark.de/'
Jun 20 21:22:44 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:44 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:44 auth: Debug: master in: PASS	1	loeser at bbspirmasens.de	service=doveadm
Jun 20 21:22:44 auth: Debug: dict(loeser at bbspirmasens.de): Performing passdb lookup
Jun 20 21:22:44 auth: Debug: dict(loeser at bbspirmasens.de): cache miss
Jun 20 21:22:44 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<14>: Handling PASSL request
Jun 20 21:22:44 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<14>: dict(loeser at bbspirmasens.de): Performing passdb lookup
Jun 20 21:22:44 auth-worker(25): Debug: dict(proxy): Looking up 'shared/passdb/loeser at bbspirmasens.de/'
Jun 20 21:22:44 auth-worker(25): Debug: dict(proxy): Looking up (async) 'shared/passdb/loeser at bbspirmasens.de/'
Jun 20 21:22:44 auth-worker(25): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:44 auth-worker(25): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:45 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/b.schultenkaemper at rbz-schuetzenpark.de/': not found
Jun 20 21:22:45 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/b.schultenkaemper at rbz-schuetzenpark.de/': not found
Jun 20 21:22:45 auth-worker(20): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<49>: dict(b.schultenkaemper at rbz-schuetzenpark.de): unknown user
Jun 20 21:22:45 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<49>: dict(b.schultenkaemper at rbz-schuetzenpark.de): Finished passdb lookup
Jun 20 21:22:45 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<49>: Finished
Jun 20 21:22:45 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:45 auth: Debug: dict(b.schultenkaemper at rbz-schuetzenpark.de): Finished passdb lookup
Jun 20 21:22:45 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:45 auth: Debug: master in: USER	2	b.schultenkaemper at rbz-schuetzenpark.de	service=doveadm
Jun 20 21:22:45 auth: Debug: sql(b.schultenkaemper at rbz-schuetzenpark.de): Performing userdb lookup
Jun 20 21:22:45 auth: Debug: sql(b.schultenkaemper at rbz-schuetzenpark.de): userdb cache miss
Jun 20 21:22:45 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<50>: Handling USER request
Jun 20 21:22:45 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<50>: sql(b.schultenkaemper at rbz-schuetzenpark.de): Performing userdb lookup
Jun 20 21:22:45 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<50>: sql(b.schultenkaemper at rbz-schuetzenpark.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'b.schultenkaemper' AND domain = 'rbz-schuetzenpark.de'
Jun 20 21:22:45 auth-worker(20): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'b.schultenkaemper' AND domain = 'rbz-schuetzenpark.de'' in 0 msecs
Jun 20 21:22:45 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<50>: sql(b.schultenkaemper at rbz-schuetzenpark.de): Finished userdb lookup
Jun 20 21:22:45 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<50>: Finished
Jun 20 21:22:45 auth: Debug: sql(b.schultenkaemper at rbz-schuetzenpark.de): Finished userdb lookup
Jun 20 21:22:45 auth: Debug: userdb out: USER	2	b.schultenkaemper at rbz-schuetzenpark.de	home=/var/dovecot/imap/rbz-schuetzenpark.de/b.schultenkaemper	master_user=b.schultenkaemper at rbz-schuetzenpark.de	acl_groups=wipo at rbz-schuetzenpark.de,zfa at rbz-schuetzenpark.de,mfa at rbz-schuetzenpark.de,rbz-am-schützenpark at rbz-schuetzenpark.de,default at rbz-schuetzenpark.de,kollegium at rbz-schuetzenpark.de,pädagogische-konferenz at rbz-schuetzenpark.de,gesundheit at rbz-schuetzenpark.de,
Jun 20 21:22:45 auth: Debug: master in: PASS	1	fynbau01 at nordXXXXXX.de	service=doveadm
Jun 20 21:22:45 auth: Debug: dict(fynbau01 at nordXXXXXX.de): Performing passdb lookup
Jun 20 21:22:45 auth: Debug: dict(fynbau01 at nordXXXXXX.de): cache miss
Jun 20 21:22:45 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<51>: Handling PASSL request
Jun 20 21:22:45 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<51>: dict(fynbau01 at nordXXXXXX.de): Performing passdb lookup
Jun 20 21:22:45 auth-worker(20): Debug: dict(proxy): Looking up 'shared/passdb/fynbau01 at nordXXXXXX.de/'
Jun 20 21:22:45 auth-worker(20): Debug: dict(proxy): Looking up (async) 'shared/passdb/fynbau01 at nordXXXXXX.de/'
Jun 20 21:22:45 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:45 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:45 auth: Debug: client passdb out: FAIL	1	user=discourse at outdoor-training.de
Jun 20 21:22:45 pop3-login: Info: Disconnected: Connection closed (auth failed, 1 attempts in 7 secs): user=<discourse at outdoor-training.de>, method=PLAIN, rip=10.10.30.11, lip=172.18.0.2, session=<kkEYtOfhXyYKCh4L>
Jun 20 21:22:45 auth-worker(25): Debug: dict(proxy): Lookup finished for 'shared/passdb/loeser at bbspirmasens.de/': not found
Jun 20 21:22:45 auth-worker(25): Debug: dict(proxy): Lookup finished for 'shared/passdb/loeser at bbspirmasens.de/': not found
Jun 20 21:22:45 auth-worker(25): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<14>: dict(loeser at bbspirmasens.de): unknown user
Jun 20 21:22:45 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<14>: dict(loeser at bbspirmasens.de): Finished passdb lookup
Jun 20 21:22:45 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<14>: Finished
Jun 20 21:22:45 auth-worker(25): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:45 auth: Debug: dict(loeser at bbspirmasens.de): Finished passdb lookup
Jun 20 21:22:45 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:45 auth: Debug: master in: USER	2	loeser at bbspirmasens.de	service=doveadm
Jun 20 21:22:45 auth: Debug: sql(loeser at bbspirmasens.de): Performing userdb lookup
Jun 20 21:22:45 auth: Debug: sql(loeser at bbspirmasens.de): userdb cache miss
Jun 20 21:22:45 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<15>: Handling USER request
Jun 20 21:22:45 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<15>: sql(loeser at bbspirmasens.de): Performing userdb lookup
Jun 20 21:22:45 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<15>: sql(loeser at bbspirmasens.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'loeser' AND domain = 'bbspirmasens.de'
Jun 20 21:22:45 auth-worker(25): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'loeser' AND domain = 'bbspirmasens.de'' in 0 msecs
Jun 20 21:22:45 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<15>: sql(loeser at bbspirmasens.de): Finished userdb lookup
Jun 20 21:22:45 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<15>: Finished
Jun 20 21:22:45 auth: Debug: sql(loeser at bbspirmasens.de): Finished userdb lookup
Jun 20 21:22:45 auth: Debug: userdb out: USER	2	loeser at bbspirmasens.de	home=/var/dovecot/imap/bbspirmasens.de/Loeser	quota_rule=*:bytes=107374182400	master_user=loeser at bbspirmasens.de	acl_groups=alle bbsps at bbspirmasens.de,kollegium at bbspirmasens.de,
Jun 20 21:22:45 auth: Debug: master in: PASS	1	b.schmitz at rbz-schuetzenpark.de	service=doveadm
Jun 20 21:22:45 auth: Debug: dict(b.schmitz at rbz-schuetzenpark.de): Performing passdb lookup
Jun 20 21:22:45 auth: Debug: dict(b.schmitz at rbz-schuetzenpark.de): cache miss
Jun 20 21:22:45 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<16>: Handling PASSL request
Jun 20 21:22:45 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<16>: dict(b.schmitz at rbz-schuetzenpark.de): Performing passdb lookup
Jun 20 21:22:45 auth-worker(25): Debug: dict(proxy): Looking up 'shared/passdb/b.schmitz at rbz-schuetzenpark.de/'
Jun 20 21:22:45 auth-worker(25): Debug: dict(proxy): Looking up (async) 'shared/passdb/b.schmitz at rbz-schuetzenpark.de/'
Jun 20 21:22:45 auth-worker(25): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:45 auth-worker(25): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:45 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/fynbau01 at nordXXXXXX.de/': not found
Jun 20 21:22:45 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/fynbau01 at nordXXXXXX.de/': not found
Jun 20 21:22:45 auth-worker(20): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<51>: dict(fynbau01 at nordXXXXXX.de): unknown user
Jun 20 21:22:45 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<51>: dict(fynbau01 at nordXXXXXX.de): Finished passdb lookup
Jun 20 21:22:45 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<51>: Finished
Jun 20 21:22:45 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:45 auth: Debug: dict(fynbau01 at nordXXXXXX.de): Finished passdb lookup
Jun 20 21:22:45 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:45 auth: Debug: master in: USER	2	fynbau01 at nordXXXXXX.de	service=doveadm
Jun 20 21:22:45 auth: Debug: sql(fynbau01 at nordXXXXXX.de): Performing userdb lookup
Jun 20 21:22:45 auth: Debug: sql(fynbau01 at nordXXXXXX.de): userdb cache miss
Jun 20 21:22:45 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<52>: Handling USER request
Jun 20 21:22:45 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<52>: sql(fynbau01 at nordXXXXXX.de): Performing userdb lookup
Jun 20 21:22:45 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<52>: sql(fynbau01 at nordXXXXXX.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'fynbau01' AND domain = 'nordXXXXXX.de'
Jun 20 21:22:45 auth-worker(20): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'fynbau01' AND domain = 'nordXXXXXX.de'' in 0 msecs
Jun 20 21:22:45 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<52>: sql(fynbau01 at nordXXXXXX.de): Finished userdb lookup
Jun 20 21:22:45 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<52>: Finished
Jun 20 21:22:45 auth: Debug: sql(fynbau01 at nordXXXXXX.de): Finished userdb lookup
Jun 20 21:22:45 auth: Debug: userdb out: USER	2	fynbau01 at nordXXXXXX.de	home=/var/dovecot/imap/nordXXXXXX.de/fynbau01	master_user=fynbau01 at nordXXXXXX.de	acl_groups=domain users at nordXXXXXX.de,rathaus at nordXXXXXX.de,
Jun 20 21:22:45 auth: Debug: master in: PASS	1	b.schmidt at rbz-schuetzenpark.de	service=doveadm
Jun 20 21:22:45 auth: Debug: dict(b.schmidt at rbz-schuetzenpark.de): Performing passdb lookup
Jun 20 21:22:45 auth: Debug: dict(b.schmidt at rbz-schuetzenpark.de): cache miss
Jun 20 21:22:45 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<53>: Handling PASSL request
Jun 20 21:22:45 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<53>: dict(b.schmidt at rbz-schuetzenpark.de): Performing passdb lookup
Jun 20 21:22:45 auth-worker(20): Debug: dict(proxy): Looking up 'shared/passdb/b.schmidt at rbz-schuetzenpark.de/'
Jun 20 21:22:45 auth-worker(20): Debug: dict(proxy): Looking up (async) 'shared/passdb/b.schmidt at rbz-schuetzenpark.de/'
Jun 20 21:22:45 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:45 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:45 auth-worker(25): Debug: dict(proxy): Lookup finished for 'shared/passdb/b.schmitz at rbz-schuetzenpark.de/': not found
Jun 20 21:22:45 auth-worker(25): Debug: dict(proxy): Lookup finished for 'shared/passdb/b.schmitz at rbz-schuetzenpark.de/': not found
Jun 20 21:22:45 auth-worker(25): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<16>: dict(b.schmitz at rbz-schuetzenpark.de): unknown user
Jun 20 21:22:45 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<16>: dict(b.schmitz at rbz-schuetzenpark.de): Finished passdb lookup
Jun 20 21:22:45 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<16>: Finished
Jun 20 21:22:45 auth-worker(25): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:45 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<17>: Handling USER request
Jun 20 21:22:45 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<17>: sql(b.schmitz at rbz-schuetzenpark.de): Performing userdb lookup
Jun 20 21:22:45 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<17>: sql(b.schmitz at rbz-schuetzenpark.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'b.schmitz' AND domain = 'rbz-schuetzenpark.de'
Jun 20 21:22:45 auth-worker(25): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'b.schmitz' AND domain = 'rbz-schuetzenpark.de'' in 0 msecs
Jun 20 21:22:45 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<17>: sql(b.schmitz at rbz-schuetzenpark.de): Finished userdb lookup
Jun 20 21:22:45 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<17>: Finished
Jun 20 21:22:45 auth: Debug: dict(b.schmitz at rbz-schuetzenpark.de): Finished passdb lookup
Jun 20 21:22:45 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:45 auth: Debug: master in: USER	2	b.schmitz at rbz-schuetzenpark.de	service=doveadm
Jun 20 21:22:45 auth: Debug: sql(b.schmitz at rbz-schuetzenpark.de): Performing userdb lookup
Jun 20 21:22:45 auth: Debug: sql(b.schmitz at rbz-schuetzenpark.de): userdb cache miss
Jun 20 21:22:45 auth: Debug: sql(b.schmitz at rbz-schuetzenpark.de): Finished userdb lookup
Jun 20 21:22:45 auth: Debug: userdb out: USER	2	b.schmitz at rbz-schuetzenpark.de	home=/var/dovecot/imap/rbz-schuetzenpark.de/b.schmidt	master_user=b.schmitz at rbz-schuetzenpark.de	acl_groups=default at rbz-schuetzenpark.de,
Jun 20 21:22:45 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/b.schmidt at rbz-schuetzenpark.de/': not found
Jun 20 21:22:45 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/b.schmidt at rbz-schuetzenpark.de/': not found
Jun 20 21:22:45 auth-worker(20): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<53>: dict(b.schmidt at rbz-schuetzenpark.de): unknown user
Jun 20 21:22:45 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<53>: dict(b.schmidt at rbz-schuetzenpark.de): Finished passdb lookup
Jun 20 21:22:45 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<53>: Finished
Jun 20 21:22:45 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:45 auth: Debug: dict(b.schmidt at rbz-schuetzenpark.de): Finished passdb lookup
Jun 20 21:22:45 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:45 auth: Debug: master in: USER	2	b.schmidt at rbz-schuetzenpark.de	service=doveadm
Jun 20 21:22:45 auth: Debug: sql(b.schmidt at rbz-schuetzenpark.de): Performing userdb lookup
Jun 20 21:22:45 auth: Debug: sql(b.schmidt at rbz-schuetzenpark.de): userdb cache miss
Jun 20 21:22:45 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<54>: Handling USER request
Jun 20 21:22:45 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<54>: sql(b.schmidt at rbz-schuetzenpark.de): Performing userdb lookup
Jun 20 21:22:45 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<54>: sql(b.schmidt at rbz-schuetzenpark.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'b.schmidt' AND domain = 'rbz-schuetzenpark.de'
Jun 20 21:22:45 auth-worker(20): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'b.schmidt' AND domain = 'rbz-schuetzenpark.de'' in 0 msecs
Jun 20 21:22:45 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<54>: sql(b.schmidt at rbz-schuetzenpark.de): Finished userdb lookup
Jun 20 21:22:45 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<54>: Finished
Jun 20 21:22:45 auth: Debug: sql(b.schmidt at rbz-schuetzenpark.de): Finished userdb lookup
Jun 20 21:22:45 auth: Debug: userdb out: USER	2	b.schmidt at rbz-schuetzenpark.de	home=/var/dovecot/imap/rbz-schuetzenpark.de/ b.schmidt	master_user=b.schmidt at rbz-schuetzenpark.de	acl_groups=default at rbz-schuetzenpark.de,kollegium at rbz-schuetzenpark.de,rbz-am-schützenpark at rbz-schuetzenpark.de,bh-holz-geoinformationstechnik at rbz-schuetzenpark.de,lehrkräfte-in-vorbereitung at rbz-schuetzenpark.de,sport at rbz-schuetzenpark.de,bfs at rbz-schuetzenpark.de,bg at rbz-schuetzenpark.de,
Jun 20 21:22:46 auth: Debug: master in: PASS	1	fragol01 at nordXXXXXX.de	service=doveadm
Jun 20 21:22:46 auth: Debug: dict(fragol01 at nordXXXXXX.de): Performing passdb lookup
Jun 20 21:22:46 auth: Debug: dict(fragol01 at nordXXXXXX.de): cache miss
Jun 20 21:22:46 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<55>: Handling PASSL request
Jun 20 21:22:46 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<55>: dict(fragol01 at nordXXXXXX.de): Performing passdb lookup
Jun 20 21:22:46 auth-worker(20): Debug: dict(proxy): Looking up 'shared/passdb/fragol01 at nordXXXXXX.de/'
Jun 20 21:22:46 auth-worker(20): Debug: dict(proxy): Looking up (async) 'shared/passdb/fragol01 at nordXXXXXX.de/'
Jun 20 21:22:46 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:46 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:46 auth: Debug: master in: PASS	1	b.saggau at rbz-schuetzenpark.de	service=doveadm
Jun 20 21:22:46 auth: Debug: dict(b.saggau at rbz-schuetzenpark.de): Performing passdb lookup
Jun 20 21:22:46 auth: Debug: dict(b.saggau at rbz-schuetzenpark.de): cache miss
Jun 20 21:22:46 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<18>: Handling PASSL request
Jun 20 21:22:46 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<18>: dict(b.saggau at rbz-schuetzenpark.de): Performing passdb lookup
Jun 20 21:22:46 auth-worker(25): Debug: dict(proxy): Looking up 'shared/passdb/b.saggau at rbz-schuetzenpark.de/'
Jun 20 21:22:46 auth-worker(25): Debug: dict(proxy): Looking up (async) 'shared/passdb/b.saggau at rbz-schuetzenpark.de/'
Jun 20 21:22:46 auth-worker(25): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:46 auth-worker(25): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:46 auth-worker(25): Debug: dict(proxy): Lookup finished for 'shared/passdb/b.saggau at rbz-schuetzenpark.de/': not found
Jun 20 21:22:46 auth-worker(25): Debug: dict(proxy): Lookup finished for 'shared/passdb/b.saggau at rbz-schuetzenpark.de/': not found
Jun 20 21:22:46 auth-worker(25): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<18>: dict(b.saggau at rbz-schuetzenpark.de): unknown user
Jun 20 21:22:46 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<18>: dict(b.saggau at rbz-schuetzenpark.de): Finished passdb lookup
Jun 20 21:22:46 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<18>: Finished
Jun 20 21:22:46 auth-worker(25): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:46 auth: Debug: dict(b.saggau at rbz-schuetzenpark.de): Finished passdb lookup
Jun 20 21:22:46 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:46 auth: Debug: master in: PASS	1	b.pooker at rbz-schuetzenpark.de	service=doveadm
Jun 20 21:22:46 auth: Debug: dict(b.pooker at rbz-schuetzenpark.de): Performing passdb lookup
Jun 20 21:22:46 auth: Debug: dict(b.pooker at rbz-schuetzenpark.de): cache miss
Jun 20 21:22:46 auth: Debug: master in: USER	2	b.saggau at rbz-schuetzenpark.de	service=doveadm
Jun 20 21:22:46 auth: Debug: sql(b.saggau at rbz-schuetzenpark.de): Performing userdb lookup
Jun 20 21:22:46 auth: Debug: sql(b.saggau at rbz-schuetzenpark.de): userdb cache miss
Jun 20 21:22:46 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<19>: Handling PASSL request
Jun 20 21:22:46 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<19>: dict(b.pooker at rbz-schuetzenpark.de): Performing passdb lookup
Jun 20 21:22:46 auth-worker(25): Debug: dict(proxy): Looking up 'shared/passdb/b.pooker at rbz-schuetzenpark.de/'
Jun 20 21:22:46 auth-worker(25): Debug: dict(proxy): Looking up (async) 'shared/passdb/b.pooker at rbz-schuetzenpark.de/'
Jun 20 21:22:46 auth-worker(25): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:46 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<10>: Handling USER request
Jun 20 21:22:46 auth-worker(25): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:46 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<10>: sql(b.saggau at rbz-schuetzenpark.de): Performing userdb lookup
Jun 20 21:22:46 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<10>: sql(b.saggau at rbz-schuetzenpark.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'b.saggau' AND domain = 'rbz-schuetzenpark.de'
Jun 20 21:22:46 auth-worker(27): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'b.saggau' AND domain = 'rbz-schuetzenpark.de'' in 0 msecs
Jun 20 21:22:46 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<10>: sql(b.saggau at rbz-schuetzenpark.de): Finished userdb lookup
Jun 20 21:22:46 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<10>: Finished
Jun 20 21:22:46 auth: Debug: sql(b.saggau at rbz-schuetzenpark.de): Finished userdb lookup
Jun 20 21:22:46 auth: Debug: userdb out: USER	2	b.saggau at rbz-schuetzenpark.de	home=/var/dovecot/imap/rbz-schuetzenpark.de/b.saggau	master_user=b.saggau at rbz-schuetzenpark.de	acl_groups=rbz-am-schützenpark at rbz-schuetzenpark.de,default at rbz-schuetzenpark.de,kollegium at rbz-schuetzenpark.de,
Jun 20 21:22:46 auth: Debug: master in: PASS	1	ingo at bb-trunk.egroupware.de	service=doveadm
Jun 20 21:22:46 auth: Debug: dict(ingo at bb-trunk.egroupware.de): Performing passdb lookup
Jun 20 21:22:46 auth: Debug: dict(ingo at bb-trunk.egroupware.de): cache miss
Jun 20 21:22:46 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<11>: Handling PASSL request
Jun 20 21:22:46 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<11>: dict(ingo at bb-trunk.egroupware.de): Performing passdb lookup
Jun 20 21:22:46 auth-worker(27): Debug: dict(proxy): Looking up 'shared/passdb/ingo at bb-trunk.egroupware.de/'
Jun 20 21:22:46 auth-worker(27): Debug: dict(proxy): Looking up (async) 'shared/passdb/ingo at bb-trunk.egroupware.de/'
Jun 20 21:22:46 auth-worker(27): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:46 auth-worker(27): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:46 auth: Debug: master in: PASS	1	bbskreativ at bbspirmasens.de	service=doveadm
Jun 20 21:22:46 auth: Debug: dict(bbskreativ at bbspirmasens.de): Performing passdb lookup
Jun 20 21:22:46 auth: Debug: dict(bbskreativ at bbspirmasens.de): cache miss
Jun 20 21:22:46 auth-worker(29): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<4>: Handling PASSL request
Jun 20 21:22:46 auth-worker(29): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<4>: dict(bbskreativ at bbspirmasens.de): Performing passdb lookup
Jun 20 21:22:46 auth-worker(29): Debug: dict(proxy): Looking up 'shared/passdb/bbskreativ at bbspirmasens.de/'
Jun 20 21:22:46 auth-worker(29): Debug: dict(proxy): Looking up (async) 'shared/passdb/bbskreativ at bbspirmasens.de/'
Jun 20 21:22:46 auth-worker(29): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:46 auth-worker(29): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:46 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/fragol01 at nordXXXXXX.de/': not found
Jun 20 21:22:46 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/fragol01 at nordXXXXXX.de/': not found
Jun 20 21:22:46 auth-worker(20): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<55>: dict(fragol01 at nordXXXXXX.de): unknown user
Jun 20 21:22:46 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<55>: dict(fragol01 at nordXXXXXX.de): Finished passdb lookup
Jun 20 21:22:46 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<55>: Finished
Jun 20 21:22:46 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:46 auth: Debug: dict(fragol01 at nordXXXXXX.de): Finished passdb lookup
Jun 20 21:22:46 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:46 auth: Debug: master in: USER	2	fragol01 at nordXXXXXX.de	service=doveadm
Jun 20 21:22:46 auth: Debug: sql(fragol01 at nordXXXXXX.de): Performing userdb lookup
Jun 20 21:22:46 auth: Debug: sql(fragol01 at nordXXXXXX.de): userdb cache miss
Jun 20 21:22:46 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<56>: Handling USER request
Jun 20 21:22:46 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<56>: sql(fragol01 at nordXXXXXX.de): Performing userdb lookup
Jun 20 21:22:46 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<56>: sql(fragol01 at nordXXXXXX.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'fragol01' AND domain = 'nordXXXXXX.de'
Jun 20 21:22:46 auth-worker(20): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'fragol01' AND domain = 'nordXXXXXX.de'' in 0 msecs
Jun 20 21:22:46 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<56>: sql(fragol01 at nordXXXXXX.de): Finished userdb lookup
Jun 20 21:22:46 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<56>: Finished
Jun 20 21:22:46 auth: Debug: sql(fragol01 at nordXXXXXX.de): Finished userdb lookup
Jun 20 21:22:46 auth: Debug: userdb out: USER	2	fragol01 at nordXXXXXX.de	home=/var/dovecot/imap/nordXXXXXX.de/fragol01	master_user=fragol01 at nordXXXXXX.de	acl_groups=domain users at nordXXXXXX.de,rpa_gruppe at nordXXXXXX.de,somacos at nordXXXXXX.de,ordnungsamt_gruppe at nordXXXXXX.de,
Jun 20 21:22:46 auth-worker(25): Debug: dict(proxy): Lookup finished for 'shared/passdb/b.pooker at rbz-schuetzenpark.de/': not found
Jun 20 21:22:46 auth-worker(25): Debug: dict(proxy): Lookup finished for 'shared/passdb/b.pooker at rbz-schuetzenpark.de/': not found
Jun 20 21:22:46 auth-worker(25): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<19>: dict(b.pooker at rbz-schuetzenpark.de): unknown user
Jun 20 21:22:46 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<19>: dict(b.pooker at rbz-schuetzenpark.de): Finished passdb lookup
Jun 20 21:22:46 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<19>: Finished
Jun 20 21:22:46 auth-worker(25): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:46 auth: Debug: dict(b.pooker at rbz-schuetzenpark.de): Finished passdb lookup
Jun 20 21:22:46 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:46 auth: Debug: master in: USER	2	b.pooker at rbz-schuetzenpark.de	service=doveadm
Jun 20 21:22:46 auth: Debug: sql(b.pooker at rbz-schuetzenpark.de): Performing userdb lookup
Jun 20 21:22:46 auth: Debug: sql(b.pooker at rbz-schuetzenpark.de): userdb cache miss
Jun 20 21:22:46 auth: Debug: sql(b.pooker at rbz-schuetzenpark.de): Finished userdb lookup
Jun 20 21:22:46 auth: Debug: userdb out: USER	2	b.pooker at rbz-schuetzenpark.de	home=/var/dovecot/imap/rbz-schuetzenpark.de/b.pooker	master_user=b.pooker at rbz-schuetzenpark.de	acl_groups=rbz-aufspaltung at rbz-schuetzenpark.de,
Jun 20 21:22:46 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<57>: Handling USER request
Jun 20 21:22:46 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<57>: sql(b.pooker at rbz-schuetzenpark.de): Performing userdb lookup
Jun 20 21:22:46 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<57>: sql(b.pooker at rbz-schuetzenpark.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'b.pooker' AND domain = 'rbz-schuetzenpark.de'
Jun 20 21:22:46 auth-worker(20): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'b.pooker' AND domain = 'rbz-schuetzenpark.de'' in 0 msecs
Jun 20 21:22:46 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<57>: sql(b.pooker at rbz-schuetzenpark.de): Finished userdb lookup
Jun 20 21:22:46 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<57>: Finished
Jun 20 21:22:46 auth-worker(27): Debug: dict(proxy): Lookup finished for 'shared/passdb/ingo at bb-trunk.egroupware.de/': not found
Jun 20 21:22:46 auth-worker(27): Debug: dict(proxy): Lookup finished for 'shared/passdb/ingo at bb-trunk.egroupware.de/': not found
Jun 20 21:22:46 auth-worker(27): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<11>: dict(ingo at bb-trunk.egroupware.de): unknown user
Jun 20 21:22:46 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<11>: dict(ingo at bb-trunk.egroupware.de): Finished passdb lookup
Jun 20 21:22:46 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<11>: Finished
Jun 20 21:22:46 auth-worker(27): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:46 auth: Debug: dict(ingo at bb-trunk.egroupware.de): Finished passdb lookup
Jun 20 21:22:46 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:46 auth: Debug: master in: USER	2	ingo at bb-trunk.egroupware.de	service=doveadm
Jun 20 21:22:46 auth: Debug: sql(ingo at bb-trunk.egroupware.de): Performing userdb lookup
Jun 20 21:22:46 auth: Debug: sql(ingo at bb-trunk.egroupware.de): userdb cache miss
Jun 20 21:22:46 auth: Debug: sql(ingo at bb-trunk.egroupware.de): Finished userdb lookup
Jun 20 21:22:46 auth: Debug: userdb out: USER	2	ingo at bb-trunk.egroupware.de	home=/var/dovecot/imap/bb-trunk.egroupware.de/ingo	master_user=ingo at bb-trunk.egroupware.de	acl_groups=collabora at bb-trunk.egroupware.de,default at bb-trunk.egroupware.de,
Jun 20 21:22:46 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<58>: Handling USER request
Jun 20 21:22:46 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<58>: sql(ingo at bb-trunk.egroupware.de): Performing userdb lookup
Jun 20 21:22:46 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<58>: sql(ingo at bb-trunk.egroupware.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'ingo' AND domain = 'bb-trunk.egroupware.de'
Jun 20 21:22:46 auth-worker(20): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'ingo' AND domain = 'bb-trunk.egroupware.de'' in 0 msecs
Jun 20 21:22:46 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<58>: sql(ingo at bb-trunk.egroupware.de): Finished userdb lookup
Jun 20 21:22:46 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<58>: Finished
Jun 20 21:22:46 auth: Debug: master in: PASS	1	loescher at bbspirmasens.de	service=doveadm
Jun 20 21:22:46 auth: Debug: dict(loescher at bbspirmasens.de): Performing passdb lookup
Jun 20 21:22:46 auth: Debug: dict(loescher at bbspirmasens.de): cache miss
Jun 20 21:22:46 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<59>: Handling PASSL request
Jun 20 21:22:46 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<59>: dict(loescher at bbspirmasens.de): Performing passdb lookup
Jun 20 21:22:46 auth-worker(20): Debug: dict(proxy): Looking up 'shared/passdb/loescher at bbspirmasens.de/'
Jun 20 21:22:46 auth-worker(20): Debug: dict(proxy): Looking up (async) 'shared/passdb/loescher at bbspirmasens.de/'
Jun 20 21:22:46 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:46 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:47 auth: Debug: master in: PASS	1	fraeve01 at nordXXXXXX.de	service=doveadm
Jun 20 21:22:47 auth: Debug: dict(fraeve01 at nordXXXXXX.de): Performing passdb lookup
Jun 20 21:22:47 auth: Debug: dict(fraeve01 at nordXXXXXX.de): cache miss
Jun 20 21:22:47 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<20>: Handling PASSL request
Jun 20 21:22:47 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<20>: dict(fraeve01 at nordXXXXXX.de): Performing passdb lookup
Jun 20 21:22:47 auth-worker(25): Debug: dict(proxy): Looking up 'shared/passdb/fraeve01 at nordXXXXXX.de/'
Jun 20 21:22:47 auth-worker(25): Debug: dict(proxy): Looking up (async) 'shared/passdb/fraeve01 at nordXXXXXX.de/'
Jun 20 21:22:47 auth-worker(25): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:47 auth-worker(25): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:47 auth-worker(29): Debug: dict(proxy): Lookup finished for 'shared/passdb/bbskreativ at bbspirmasens.de/': not found
Jun 20 21:22:47 auth-worker(29): Debug: dict(proxy): Lookup finished for 'shared/passdb/bbskreativ at bbspirmasens.de/': not found
Jun 20 21:22:47 auth-worker(29): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<4>: dict(bbskreativ at bbspirmasens.de): unknown user
Jun 20 21:22:47 auth-worker(29): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<4>: dict(bbskreativ at bbspirmasens.de): Finished passdb lookup
Jun 20 21:22:47 auth-worker(29): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<4>: Finished
Jun 20 21:22:47 auth-worker(29): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:47 auth: Debug: dict(bbskreativ at bbspirmasens.de): Finished passdb lookup
Jun 20 21:22:47 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:47 auth: Debug: master in: USER	2	bbskreativ at bbspirmasens.de	service=doveadm
Jun 20 21:22:47 auth: Debug: sql(bbskreativ at bbspirmasens.de): Performing userdb lookup
Jun 20 21:22:47 auth: Debug: sql(bbskreativ at bbspirmasens.de): userdb cache miss
Jun 20 21:22:47 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<12>: Handling USER request
Jun 20 21:22:47 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<12>: sql(bbskreativ at bbspirmasens.de): Performing userdb lookup
Jun 20 21:22:47 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<12>: sql(bbskreativ at bbspirmasens.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'bbskreativ' AND domain = 'bbspirmasens.de'
Jun 20 21:22:47 auth-worker(27): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'bbskreativ' AND domain = 'bbspirmasens.de'' in 0 msecs
Jun 20 21:22:47 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<12>: sql(bbskreativ at bbspirmasens.de): Finished userdb lookup
Jun 20 21:22:47 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<12>: Finished
Jun 20 21:22:47 auth: Debug: sql(bbskreativ at bbspirmasens.de): Finished userdb lookup
Jun 20 21:22:47 auth: Debug: userdb out: USER	2	bbskreativ at bbspirmasens.de	home=/var/dovecot/imap/bbspirmasens.de/bbskreativ	master_user=bbskreativ at bbspirmasens.de	acl_groups=default at bbspirmasens.de,
Jun 20 21:22:47 auth: Debug: client in: AUTH	1	PLAIN	service=imap	session=tzqYtOfhCkIKCh4L	lip=172.18.0.2	rip=10.10.30.11	lport=143	rport=16906	resp=<hidden>
Jun 20 21:22:47 auth: Debug: auth client connected (pid=94)
Jun 20 21:22:47 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/loescher at bbspirmasens.de/': not found
Jun 20 21:22:47 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/loescher at bbspirmasens.de/': not found
Jun 20 21:22:47 auth-worker(20): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<59>: dict(loescher at bbspirmasens.de): unknown user
Jun 20 21:22:47 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<59>: dict(loescher at bbspirmasens.de): Finished passdb lookup
Jun 20 21:22:47 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<59>: Finished
Jun 20 21:22:47 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:47 auth: Debug: dict(loescher at bbspirmasens.de): Finished passdb lookup
Jun 20 21:22:47 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:47 auth: Debug: master in: USER	2	loescher at bbspirmasens.de	service=doveadm
Jun 20 21:22:47 auth: Debug: sql(loescher at bbspirmasens.de): Performing userdb lookup
Jun 20 21:22:47 auth: Debug: sql(loescher at bbspirmasens.de): userdb cache miss
Jun 20 21:22:47 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<60>: Handling USER request
Jun 20 21:22:47 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<60>: sql(loescher at bbspirmasens.de): Performing userdb lookup
Jun 20 21:22:47 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<60>: sql(loescher at bbspirmasens.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'loescher' AND domain = 'bbspirmasens.de'
Jun 20 21:22:47 auth-worker(20): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'loescher' AND domain = 'bbspirmasens.de'' in 0 msecs
Jun 20 21:22:47 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<60>: sql(loescher at bbspirmasens.de): Finished userdb lookup
Jun 20 21:22:47 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<60>: Finished
Jun 20 21:22:47 auth: Debug: sql(loescher at bbspirmasens.de): Finished userdb lookup
Jun 20 21:22:47 auth: Debug: userdb out: USER	2	loescher at bbspirmasens.de	home=/var/dovecot/imap/bbspirmasens.de/Loescher	master_user=loescher at bbspirmasens.de	acl_groups=alle bbsps at bbspirmasens.de,kollegium at bbspirmasens.de,
Jun 20 21:22:47 auth: Debug: master in: PASS	1	b.ofterdinger at rbz-schuetzenpark.de	service=doveadm
Jun 20 21:22:47 auth: Debug: dict(b.ofterdinger at rbz-schuetzenpark.de): Performing passdb lookup
Jun 20 21:22:47 auth: Debug: dict(b.ofterdinger at rbz-schuetzenpark.de): cache miss
Jun 20 21:22:47 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<61>: Handling PASSL request
Jun 20 21:22:47 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<61>: dict(b.ofterdinger at rbz-schuetzenpark.de): Performing passdb lookup
Jun 20 21:22:47 auth-worker(20): Debug: dict(proxy): Looking up 'shared/passdb/b.ofterdinger at rbz-schuetzenpark.de/'
Jun 20 21:22:47 auth-worker(20): Debug: dict(proxy): Looking up (async) 'shared/passdb/b.ofterdinger at rbz-schuetzenpark.de/'
Jun 20 21:22:47 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:47 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:47 auth: Debug: master in: PASS	1	b.linke at rbz-schuetzenpark.de	service=doveadm
Jun 20 21:22:47 auth: Debug: dict(b.linke at rbz-schuetzenpark.de): Performing passdb lookup
Jun 20 21:22:47 auth: Debug: dict(b.linke at rbz-schuetzenpark.de): cache miss
Jun 20 21:22:47 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<13>: Handling PASSL request
Jun 20 21:22:47 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<13>: dict(b.linke at rbz-schuetzenpark.de): Performing passdb lookup
Jun 20 21:22:47 auth-worker(27): Debug: dict(proxy): Looking up 'shared/passdb/b.linke at rbz-schuetzenpark.de/'
Jun 20 21:22:47 auth-worker(27): Debug: dict(proxy): Looking up (async) 'shared/passdb/b.linke at rbz-schuetzenpark.de/'
Jun 20 21:22:47 auth-worker(27): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:47 auth-worker(27): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:47 auth: Debug: master in: PASS	1	fiotim01 at nordXXXXXX.de	service=doveadm
Jun 20 21:22:47 auth: Debug: dict(fiotim01 at nordXXXXXX.de): Performing passdb lookup
Jun 20 21:22:47 auth: Debug: dict(fiotim01 at nordXXXXXX.de): cache miss
Jun 20 21:22:47 auth-worker(29): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<5>: Handling PASSL request
Jun 20 21:22:47 auth-worker(29): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<5>: dict(fiotim01 at nordXXXXXX.de): Performing passdb lookup
Jun 20 21:22:47 auth-worker(29): Debug: dict(proxy): Looking up 'shared/passdb/fiotim01 at nordXXXXXX.de/'
Jun 20 21:22:47 auth-worker(29): Debug: dict(proxy): Looking up (async) 'shared/passdb/fiotim01 at nordXXXXXX.de/'
Jun 20 21:22:47 auth-worker(29): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:47 auth-worker(29): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:47 auth-worker(27): Debug: dict(proxy): Lookup finished for 'shared/passdb/b.linke at rbz-schuetzenpark.de/': not found
Jun 20 21:22:47 auth-worker(27): Debug: dict(proxy): Lookup finished for 'shared/passdb/b.linke at rbz-schuetzenpark.de/': not found
Jun 20 21:22:47 auth-worker(27): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<13>: dict(b.linke at rbz-schuetzenpark.de): unknown user
Jun 20 21:22:47 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<13>: dict(b.linke at rbz-schuetzenpark.de): Finished passdb lookup
Jun 20 21:22:47 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<13>: Finished
Jun 20 21:22:47 auth-worker(27): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:47 auth: Debug: dict(b.linke at rbz-schuetzenpark.de): Finished passdb lookup
Jun 20 21:22:47 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:47 auth: Debug: master in: USER	2	b.linke at rbz-schuetzenpark.de	service=doveadm
Jun 20 21:22:47 auth: Debug: sql(b.linke at rbz-schuetzenpark.de): Performing userdb lookup
Jun 20 21:22:47 auth: Debug: sql(b.linke at rbz-schuetzenpark.de): userdb cache miss
Jun 20 21:22:47 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<14>: Handling USER request
Jun 20 21:22:47 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<14>: sql(b.linke at rbz-schuetzenpark.de): Performing userdb lookup
Jun 20 21:22:47 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<14>: sql(b.linke at rbz-schuetzenpark.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'b.linke' AND domain = 'rbz-schuetzenpark.de'
Jun 20 21:22:47 auth-worker(27): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'b.linke' AND domain = 'rbz-schuetzenpark.de'' in 0 msecs
Jun 20 21:22:47 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<14>: sql(b.linke at rbz-schuetzenpark.de): Finished userdb lookup
Jun 20 21:22:47 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<14>: Finished
Jun 20 21:22:47 auth: Debug: sql(b.linke at rbz-schuetzenpark.de): Finished userdb lookup
Jun 20 21:22:47 auth: Debug: userdb out: USER	2	b.linke at rbz-schuetzenpark.de	home=/var/dovecot/imap/rbz-schuetzenpark.de/b.linke	quota_rule=*:bytes=524288000	master_user=b.linke at rbz-schuetzenpark.de	acl_groups=default at rbz-schuetzenpark.de,homepage at rbz-schuetzenpark.de,kollegium at rbz-schuetzenpark.de,rbz-am-schützenpark at rbz-schuetzenpark.de,bv-redaktion at rbz-schuetzenpark.de,bv at rbz-schuetzenpark.de,
Jun 20 21:22:47 auth: Debug: master in: PASS	1	b.kock at rbz-schuetzenpark.de	service=doveadm
Jun 20 21:22:47 auth: Debug: dict(b.kock at rbz-schuetzenpark.de): Performing passdb lookup
Jun 20 21:22:47 auth: Debug: dict(b.kock at rbz-schuetzenpark.de): cache miss
Jun 20 21:22:47 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<15>: Handling PASSL request
Jun 20 21:22:47 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<15>: dict(b.kock at rbz-schuetzenpark.de): Performing passdb lookup
Jun 20 21:22:47 auth-worker(27): Debug: dict(proxy): Looking up 'shared/passdb/b.kock at rbz-schuetzenpark.de/'
Jun 20 21:22:47 auth-worker(27): Debug: dict(proxy): Looking up (async) 'shared/passdb/b.kock at rbz-schuetzenpark.de/'
Jun 20 21:22:47 auth-worker(27): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:47 auth-worker(27): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:47 auth-worker(27): Debug: dict(proxy): Lookup finished for 'shared/passdb/b.kock at rbz-schuetzenpark.de/': not found
Jun 20 21:22:47 auth-worker(27): Debug: dict(proxy): Lookup finished for 'shared/passdb/b.kock at rbz-schuetzenpark.de/': not found
Jun 20 21:22:47 auth-worker(27): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<15>: dict(b.kock at rbz-schuetzenpark.de): unknown user
Jun 20 21:22:47 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<15>: dict(b.kock at rbz-schuetzenpark.de): Finished passdb lookup
Jun 20 21:22:47 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<15>: Finished
Jun 20 21:22:47 auth-worker(27): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:47 auth: Debug: dict(b.kock at rbz-schuetzenpark.de): Finished passdb lookup
Jun 20 21:22:47 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:47 auth: Debug: master in: USER	2	b.kock at rbz-schuetzenpark.de	service=doveadm
Jun 20 21:22:47 auth: Debug: sql(b.kock at rbz-schuetzenpark.de): Performing userdb lookup
Jun 20 21:22:47 auth: Debug: sql(b.kock at rbz-schuetzenpark.de): userdb cache miss
Jun 20 21:22:47 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<16>: Handling USER request
Jun 20 21:22:47 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<16>: sql(b.kock at rbz-schuetzenpark.de): Performing userdb lookup
Jun 20 21:22:47 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<16>: sql(b.kock at rbz-schuetzenpark.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'b.kock' AND domain = 'rbz-schuetzenpark.de'
Jun 20 21:22:47 auth-worker(27): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'b.kock' AND domain = 'rbz-schuetzenpark.de'' in 0 msecs
Jun 20 21:22:47 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<16>: sql(b.kock at rbz-schuetzenpark.de): Finished userdb lookup
Jun 20 21:22:47 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<16>: Finished
Jun 20 21:22:47 auth: Debug: sql(b.kock at rbz-schuetzenpark.de): Finished userdb lookup
Jun 20 21:22:47 auth: Debug: userdb out: USER	2	b.kock at rbz-schuetzenpark.de	home=/var/dovecot/imap/rbz-schuetzenpark.de/b.kock	master_user=b.kock at rbz-schuetzenpark.de	acl_groups=rbz-am-schützenpark at rbz-schuetzenpark.de,default at rbz-schuetzenpark.de,kollegium at rbz-schuetzenpark.de,bfs at rbz-schuetzenpark.de,bg at rbz-schuetzenpark.de,
Jun 20 21:22:47 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/b.ofterdinger at rbz-schuetzenpark.de/': not found
Jun 20 21:22:47 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/b.ofterdinger at rbz-schuetzenpark.de/': not found
Jun 20 21:22:47 auth-worker(20): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<61>: dict(b.ofterdinger at rbz-schuetzenpark.de): unknown user
Jun 20 21:22:47 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<61>: dict(b.ofterdinger at rbz-schuetzenpark.de): Finished passdb lookup
Jun 20 21:22:47 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<61>: Finished
Jun 20 21:22:47 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:47 auth: Debug: dict(b.ofterdinger at rbz-schuetzenpark.de): Finished passdb lookup
Jun 20 21:22:47 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:47 auth: Debug: master in: USER	2	b.ofterdinger at rbz-schuetzenpark.de	service=doveadm
Jun 20 21:22:47 auth: Debug: sql(b.ofterdinger at rbz-schuetzenpark.de): Performing userdb lookup
Jun 20 21:22:47 auth: Debug: sql(b.ofterdinger at rbz-schuetzenpark.de): userdb cache miss
Jun 20 21:22:47 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<62>: Handling USER request
Jun 20 21:22:47 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<62>: sql(b.ofterdinger at rbz-schuetzenpark.de): Performing userdb lookup
Jun 20 21:22:47 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<62>: sql(b.ofterdinger at rbz-schuetzenpark.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'b.ofterdinger' AND domain = 'rbz-schuetzenpark.de'
Jun 20 21:22:47 auth-worker(20): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'b.ofterdinger' AND domain = 'rbz-schuetzenpark.de'' in 0 msecs
Jun 20 21:22:47 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<62>: sql(b.ofterdinger at rbz-schuetzenpark.de): Finished userdb lookup
Jun 20 21:22:47 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<62>: Finished
Jun 20 21:22:47 auth: Debug: sql(b.ofterdinger at rbz-schuetzenpark.de): Finished userdb lookup
Jun 20 21:22:47 auth: Debug: userdb out: USER	2	b.ofterdinger at rbz-schuetzenpark.de	home=/var/dovecot/imap/rbz-schuetzenpark.de/b.ofterdinger	quota_rule=*:bytes=214748364800	master_user=b.ofterdinger at rbz-schuetzenpark.de	acl_groups=rbz-am-schützenpark at rbz-schuetzenpark.de,default at rbz-schuetzenpark.de,kollegium at rbz-schuetzenpark.de,
Jun 20 21:22:47 auth-worker(29): Debug: dict(proxy): Lookup finished for 'shared/passdb/fiotim01 at nordXXXXXX.de/': not found
Jun 20 21:22:47 auth-worker(29): Debug: dict(proxy): Lookup finished for 'shared/passdb/fiotim01 at nordXXXXXX.de/': not found
Jun 20 21:22:47 auth-worker(29): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<5>: dict(fiotim01 at nordXXXXXX.de): unknown user
Jun 20 21:22:47 auth-worker(29): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<5>: dict(fiotim01 at nordXXXXXX.de): Finished passdb lookup
Jun 20 21:22:47 auth-worker(29): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<5>: Finished
Jun 20 21:22:47 auth-worker(29): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:47 auth: Debug: dict(fiotim01 at nordXXXXXX.de): Finished passdb lookup
Jun 20 21:22:47 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:47 auth: Debug: master in: USER	2	fiotim01 at nordXXXXXX.de	service=doveadm
Jun 20 21:22:47 auth: Debug: sql(fiotim01 at nordXXXXXX.de): Performing userdb lookup
Jun 20 21:22:47 auth: Debug: sql(fiotim01 at nordXXXXXX.de): userdb cache miss
Jun 20 21:22:47 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<63>: Handling USER request
Jun 20 21:22:47 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<63>: sql(fiotim01 at nordXXXXXX.de): Performing userdb lookup
Jun 20 21:22:47 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<63>: sql(fiotim01 at nordXXXXXX.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'fiotim01' AND domain = 'nordXXXXXX.de'
Jun 20 21:22:47 auth-worker(20): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'fiotim01' AND domain = 'nordXXXXXX.de'' in 0 msecs
Jun 20 21:22:47 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<63>: sql(fiotim01 at nordXXXXXX.de): Finished userdb lookup
Jun 20 21:22:47 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<63>: Finished
Jun 20 21:22:47 auth: Debug: sql(fiotim01 at nordXXXXXX.de): Finished userdb lookup
Jun 20 21:22:47 auth: Debug: userdb out: USER	2	fiotim01 at nordXXXXXX.de	home=/var/dovecot/imap/nordXXXXXX.de/fiotim01	master_user=fiotim01 at nordXXXXXX.de	acl_groups=default at nordXXXXXX.de,domain users at nordXXXXXX.de,rathaus at nordXXXXXX.de,
Jun 20 21:22:47 auth-worker(25): Debug: dict(proxy): Lookup finished for 'shared/passdb/fraeve01 at nordXXXXXX.de/': not found
Jun 20 21:22:47 auth-worker(25): Debug: dict(proxy): Lookup finished for 'shared/passdb/fraeve01 at nordXXXXXX.de/': not found
Jun 20 21:22:47 auth-worker(25): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<20>: dict(fraeve01 at nordXXXXXX.de): unknown user
Jun 20 21:22:47 auth: Debug: dict(fraeve01 at nordXXXXXX.de): Finished passdb lookup
Jun 20 21:22:47 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:47 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<20>: dict(fraeve01 at nordXXXXXX.de): Finished passdb lookup
Jun 20 21:22:47 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<20>: Finished
Jun 20 21:22:47 auth-worker(25): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:47 auth: Debug: master in: USER	2	fraeve01 at nordXXXXXX.de	service=doveadm
Jun 20 21:22:47 auth: Debug: sql(fraeve01 at nordXXXXXX.de): Performing userdb lookup
Jun 20 21:22:47 auth: Debug: sql(fraeve01 at nordXXXXXX.de): userdb cache miss
Jun 20 21:22:47 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<64>: Handling USER request
Jun 20 21:22:47 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<64>: sql(fraeve01 at nordXXXXXX.de): Performing userdb lookup
Jun 20 21:22:47 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<64>: sql(fraeve01 at nordXXXXXX.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'fraeve01' AND domain = 'nordXXXXXX.de'
Jun 20 21:22:47 auth-worker(20): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'fraeve01' AND domain = 'nordXXXXXX.de'' in 0 msecs
Jun 20 21:22:47 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<64>: sql(fraeve01 at nordXXXXXX.de): Finished userdb lookup
Jun 20 21:22:47 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<64>: Finished
Jun 20 21:22:47 auth: Debug: sql(fraeve01 at nordXXXXXX.de): Finished userdb lookup
Jun 20 21:22:47 auth: Debug: userdb out: USER	2	fraeve01 at nordXXXXXX.de	home=/var/dovecot/imap/nordXXXXXX.de/fraeve01	quota_rule=*:bytes=214748364800	master_user=fraeve01 at nordXXXXXX.de	acl_groups=domain users at nordXXXXXX.de,gebaeude at nordXXXXXX.de,gebaeudewirtschaft_gruppe at nordXXXXXX.de,rathaus at nordXXXXXX.de,
Jun 20 21:22:47 auth: Debug: master in: PASS	1	b.karakaplan at rbz-schuetzenpark.de	service=doveadm
Jun 20 21:22:47 auth: Debug: dict(b.karakaplan at rbz-schuetzenpark.de): Performing passdb lookup
Jun 20 21:22:47 auth: Debug: dict(b.karakaplan at rbz-schuetzenpark.de): cache miss
Jun 20 21:22:47 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<65>: Handling PASSL request
Jun 20 21:22:47 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<65>: dict(b.karakaplan at rbz-schuetzenpark.de): Performing passdb lookup
Jun 20 21:22:47 auth-worker(20): Debug: dict(proxy): Looking up 'shared/passdb/b.karakaplan at rbz-schuetzenpark.de/'
Jun 20 21:22:47 auth-worker(20): Debug: dict(proxy): Looking up (async) 'shared/passdb/b.karakaplan at rbz-schuetzenpark.de/'
Jun 20 21:22:47 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:47 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:48 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/b.karakaplan at rbz-schuetzenpark.de/': not found
Jun 20 21:22:48 auth-worker(20): Debug: dict(proxy): Lookup finished for 'shared/passdb/b.karakaplan at rbz-schuetzenpark.de/': not found
Jun 20 21:22:48 auth-worker(20): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<65>: dict(b.karakaplan at rbz-schuetzenpark.de): unknown user
Jun 20 21:22:48 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<65>: dict(b.karakaplan at rbz-schuetzenpark.de): Finished passdb lookup
Jun 20 21:22:48 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<65>: Finished
Jun 20 21:22:48 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:48 auth: Debug: dict(b.karakaplan at rbz-schuetzenpark.de): Finished passdb lookup
Jun 20 21:22:48 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:48 auth: Debug: master in: USER	2	b.karakaplan at rbz-schuetzenpark.de	service=doveadm
Jun 20 21:22:48 auth: Debug: sql(b.karakaplan at rbz-schuetzenpark.de): Performing userdb lookup
Jun 20 21:22:48 auth: Debug: sql(b.karakaplan at rbz-schuetzenpark.de): userdb cache miss
Jun 20 21:22:48 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<66>: Handling USER request
Jun 20 21:22:48 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<66>: sql(b.karakaplan at rbz-schuetzenpark.de): Performing userdb lookup
Jun 20 21:22:48 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<66>: sql(b.karakaplan at rbz-schuetzenpark.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'b.karakaplan' AND domain = 'rbz-schuetzenpark.de'
Jun 20 21:22:48 auth-worker(20): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'b.karakaplan' AND domain = 'rbz-schuetzenpark.de'' in 0 msecs
Jun 20 21:22:48 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<66>: sql(b.karakaplan at rbz-schuetzenpark.de): Finished userdb lookup
Jun 20 21:22:48 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<66>: Finished
Jun 20 21:22:48 auth: Debug: sql(b.karakaplan at rbz-schuetzenpark.de): Finished userdb lookup
Jun 20 21:22:48 auth: Debug: userdb out: USER	2	b.karakaplan at rbz-schuetzenpark.de	home=/var/dovecot/imap/rbz-schuetzenpark.de/b.karakaplan	master_user=b.karakaplan at rbz-schuetzenpark.de	acl_groups=bg at rbz-schuetzenpark.de,default at rbz-schuetzenpark.de,rbz-am-schützenpark at rbz-schuetzenpark.de,nahrung at rbz-schuetzenpark.de,sekretärinnen at rbz-schuetzenpark.de,gesundheit at rbz-schuetzenpark.de,abteilungen-gross at rbz-schuetzenpark.de,vertretung at rbz-schuetzenpark.de,
Jun 20 21:22:48 auth: Debug: client in: AUTH	1	PLAIN	service=imap	session=jnOotOfhI1UKCh4L	lip=172.18.0.2	rip=10.10.30.11	lport=143	rport=21795	resp=<hidden>
Jun 20 21:22:48 auth: Debug: auth client connected (pid=100)
Jun 20 21:22:48 auth: Debug: master in: PASS	1	llk at bbspirmasens.de	service=doveadm
Jun 20 21:22:48 auth: Debug: dict(llk at bbspirmasens.de): Performing passdb lookup
Jun 20 21:22:48 auth: Debug: dict(llk at bbspirmasens.de): cache miss
Jun 20 21:22:48 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<67>: Handling PASSL request
Jun 20 21:22:48 auth-worker(20): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<67>: dict(llk at bbspirmasens.de): Performing passdb lookup
Jun 20 21:22:48 auth-worker(20): Debug: dict(proxy): Looking up 'shared/passdb/llk at bbspirmasens.de/'
Jun 20 21:22:48 auth-worker(20): Debug: dict(proxy): Looking up (async) 'shared/passdb/llk at bbspirmasens.de/'
Jun 20 21:22:48 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:48 auth-worker(20): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:48 auth: Debug: auth client connected (pid=0)
Jun 20 21:22:48 auth: Debug: client in: AUTH	1	PLAIN	service=doveadm	debug	resp=<hidden>
Jun 20 21:22:48 auth: Debug: dict(ralfimaptest at egroupware.org): Performing passdb lookup
Jun 20 21:22:48 auth: Debug: dict(ralfimaptest at egroupware.org): cache miss
Jun 20 21:22:48 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<21>: Handling PASSV request
Jun 20 21:22:48 auth-worker(25): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<21>: dict(ralfimaptest at egroupware.org): Performing passdb lookup
Jun 20 21:22:48 auth-worker(25): Debug: dict(proxy): Looking up 'shared/passdb/ralfimaptest at egroupware.org/XXXXXXXXX'
Jun 20 21:22:48 auth-worker(25): Debug: dict(proxy): Looking up (async) 'shared/passdb/ralfimaptest at egroupware.org/XXXXXXXXX'
Jun 20 21:22:48 auth-worker(25): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:48 auth-worker(25): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:48 auth: Debug: master in: PASS	1	entwXXXXXXXX at nordXXXXXX.de	service=doveadm
Jun 20 21:22:48 auth: Debug: dict(entwXXXXXXXX at nordXXXXXX.de): Performing passdb lookup
Jun 20 21:22:48 auth: Debug: dict(entwXXXXXXXX at nordXXXXXX.de): cache miss
Jun 20 21:22:48 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<17>: Handling PASSL request
Jun 20 21:22:48 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<17>: dict(entwXXXXXXXX at nordXXXXXX.de): Performing passdb lookup
Jun 20 21:22:48 auth-worker(27): Debug: dict(proxy): Looking up 'shared/passdb/entwXXXXXXXX at nordXXXXXX.de/'
Jun 20 21:22:48 auth-worker(27): Debug: dict(proxy): Looking up (async) 'shared/passdb/entwXXXXXXXX at nordXXXXXX.de/'
Jun 20 21:22:48 auth-worker(27): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Connecting
Jun 20 21:22:48 auth-worker(27): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Client connected (fd=7)
Jun 20 21:22:48 auth-worker(27): Debug: dict(proxy): Lookup finished for 'shared/passdb/entwXXXXXXXX at nordXXXXXX.de/': not found
Jun 20 21:22:48 auth-worker(27): Debug: dict(proxy): Lookup finished for 'shared/passdb/entwXXXXXXXX at nordXXXXXX.de/': not found
Jun 20 21:22:48 auth-worker(27): Info: conn unix:auth-worker (pid=19,uid=90): auth-worker<17>: dict(entwXXXXXXXX at nordXXXXXX.de): unknown user
Jun 20 21:22:48 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<17>: dict(entwXXXXXXXX at nordXXXXXX.de): Finished passdb lookup
Jun 20 21:22:48 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<17>: Finished
Jun 20 21:22:48 auth-worker(27): Debug: dict(proxy): conn unix:/var/run/dovecot_auth_proxy/socket (pid=0,uid=0): Disconnected: Connection closed (fd=7)
Jun 20 21:22:48 auth: Debug: dict(entwXXXXXXXX at nordXXXXXX.de): Finished passdb lookup
Jun 20 21:22:48 auth: Debug: passdb out: NOTFOUND	1
Jun 20 21:22:48 auth: Debug: master in: USER	2	entwXXXXXXXX at nordXXXXXX.de	service=doveadm
Jun 20 21:22:48 auth: Debug: sql(entwXXXXXXXX at nordXXXXXX.de): Performing userdb lookup
Jun 20 21:22:48 auth: Debug: sql(entwXXXXXXXX at nordXXXXXX.de): userdb cache miss
Jun 20 21:22:48 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<18>: Handling USER request
Jun 20 21:22:48 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<18>: sql(entwXXXXXXXX at nordXXXXXX.de): Performing userdb lookup
Jun 20 21:22:48 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<18>: sql(entwXXXXXXXX at nordXXXXXX.de): SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'entwXXXXXXXX' AND domain = 'nordXXXXXX.de'
Jun 20 21:22:48 auth-worker(27): Debug: sqlite: Finished query 'SELECT home,NULL AS uid,NULL AS gid, '*:bytes='||(quota*1048576) AS quota_rule, userid||'@'||domain AS master_user, LOWER(REPLACE(groups||',', ',', '@'||domain||',')) AS acl_groups FROM users WHERE userid = 'entwXXXXXXXX' AND domain = 'nordXXXXXX.de'' in 0 msecs
Jun 20 21:22:48 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<18>: sql(entwXXXXXXXX at nordXXXXXX.de): Finished userdb lookup
Jun 20 21:22:48 auth-worker(27): Debug: conn unix:auth-worker (pid=19,uid=90): auth-worker<18>: Finished
Jun 20 21:22:48 auth: Debug: sql(entwXXXXXXXX at nordXXXXXX.de): Finished userdb lookup
Jun 20 21:22:48 auth: Debug: userdb out: USER	2	entwXXXXXXXX at nordXXXXXX.de	home=/var/dovecot/imap/nordXXXXXX.de/entwXXXXXXXX	master_user=entwXXXXXXXX at nordXXXXXX.de	acl_groups=default at nordXXXXXX.de,