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,