On Thu, 16 Mar 2017 13:48:27 +0200
Aki Tuomi <aki.tu...@dovecot.fi> wrote:
> These are always worth looking into. Please do post, also any auth debug
> logs are welcome.
Ok.
Theese the logfile lines:
Mar 14 18:06:12 master: Warning: Killed with signal 15 (by pid=7390 uid=0
code=kill)
Mar 14 18:06:20 auth: Error: net_connect_unix(auth-worker) failed: Connection
refused
Mar 14 18:06:42 auth: Warning: Timeout leak: 0x805e480
(auth-request-handler.c:550)
Mar 14 18:06:42 auth: Warning: Timeout leak: 0x805e480
(auth-request-handler.c:550)
Mar 14 18:06:42 auth: Warning: Timeout leak: 0x805e480
(auth-request-handler.c:550)
Here is the logfile.info of the relevant minute (a bit longish, passwors
censored :)...
Mar 14 18:06:01 pop3(l.radice_dls): Info: Disconnected: Logged out top=0/0,
retr=0/0, del=0/7415, size=950442332
Mar 14 18:06:01 auth: Debug: auth client connected (pid=7375)
Mar 14 18:06:01 auth: Debug: client in: AUTH 3 LOGIN service=smtp
nologin lip=212.45.144.70 rip=89.248.171.132
Mar 14 18:06:01 auth: Debug: client passdb out: CONT 3 VXNlcm5hbWU6
Mar 14 18:06:01 auth: Debug: client in: CONT 3
XXXXXXXXXXXXXXXXXXXXXXXX (previous base64 data may contain sensitive data)
Mar 14 18:06:01 auth: Debug: client passdb out: CONT 3 UGFzc3dvcmQ6
Mar 14 18:06:01 auth: Debug: client in: CONT 3 XXXXXXXX (previous
base64 data may contain sensitive data)
Mar 14 18:06:01 auth-worker(6538): Debug: pam(ad...@bilink.net,89.248.171.132):
lookup service=dovecot
Mar 14 18:06:01 auth-worker(6538): Debug: pam(ad...@bilink.net,89.248.171.132):
#1/1 style=1 msg=Password:
Mar 14 18:06:02 auth: Debug: auth client connected (pid=7377)
Mar 14 18:06:02 auth: Debug: client in: AUTH 1 PLAIN service=pop3
session=zgbC17NKLOvAqAvR lip=192.168.134.44
rip=192.168.11.209lport=110 rport=60204
Mar 14 18:06:02 auth: Debug: client passdb out: CONT 1
Mar 14 18:06:02 auth: Debug: client in: CONT 1
XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX (previous base64 data may contain
sensitive data)
Mar 14 18:06:02 auth-worker(7121): Debug:
pam(m.castrechini_mms,192.168.11.209,<zgbC17NKLOvAqAvR>): lookup service=dovecot
Mar 14 18:06:02 auth-worker(7121): Debug:
pam(m.castrechini_mms,192.168.11.209,<zgbC17NKLOvAqAvR>): #1/1 style=1
msg=Password:
Mar 14 18:06:02 auth: Debug: client passdb out: OK 1
user=m.castrechini_mms
Mar 14 18:06:03 auth: Debug: master in: REQUEST 2912419841 7377 1
e4d4a4c42ae38c0214a0def0bb0f0915 session_pid=7378
Mar 14 18:06:03 auth: Debug:
passwd(m.castrechini_mms,192.168.11.209,<zgbC17NKLOvAqAvR>): userdb cache hit:
system_groups_user=m.castrechini_mms uid=3002 gid=111
home=/home/m.castrechini_mms
Mar 14 18:06:03 auth: Debug: master userdb out: USER 2912419841
m.castrechini_mms system_groups_user=m.castrechini_mms uid=3002
gid=111 home=/home/m.castrechini_mms
Mar 14 18:06:03 pop3-login: Info: Login: user=<m.castrechini_mms>,
method=PLAIN, rip=192.168.11.209, lip=192.168.134.44, mpid=7378,
session=<zgbC17NKLOvAqAvR>
Mar 14 18:06:03 pop3(m.castrechini_mms): Info: Disconnected: Logged out
top=0/0, retr=0/0, del=0/0, size=0
Mar 14 18:06:03 auth-worker(6538): Info: pam(ad...@bilink.net,89.248.171.132):
unknown user
Mar 14 18:06:05 auth: Debug: client passdb out: FAIL 3
user=ad...@bilink.net
Mar 14 18:06:06 auth: Debug: auth client connected (pid=7380)
Mar 14 18:06:06 auth: Debug: client in: AUTH 1 PLAIN service=pop3
session=khD317NKD+PAqAtY lip=192.168.134.44 rip=192.168.11.88
lport=110 rport=58127 resp=XXXXXXXXXXXXXXXXXXXXXXXXXXXX (previous
base64 data may contain sensitive data)
Mar 14 18:06:06 auth-worker(6538): Debug:
pam(a.zurlo_mms,192.168.11.88,<khD317NKD+PAqAtY>): lookup service=dovecot
Mar 14 18:06:06 auth-worker(6538): Debug:
pam(a.zurlo_mms,192.168.11.88,<khD317NKD+PAqAtY>): #1/1 style=1 msg=Password:
Mar 14 18:06:06 auth: Debug: client passdb out: OK 1
user=a.zurlo_mms
Mar 14 18:06:06 auth: Debug: master in: REQUEST 3493330945 7380 1
e4c2a1b6ab1b7ede85f4fd8611a960c8 session_pid=7382
Mar 14 18:06:06 auth: Debug:
passwd(a.zurlo_mms,192.168.11.88,<khD317NKD+PAqAtY>): userdb cache hit:
system_groups_user=a.zurlo_mms uid=3005 gid=111
home=/home/a.zurlo_mms
Mar 14 18:06:06 auth: Debug: master userdb out: USER 3493330945
a.zurlo_mms system_groups_user=a.zurlo_mms uid=3005 gid=111
home=/home/a.zurlo_mms
Mar 14 18:06:06 pop3-login: Info: Login: user=<a.zurlo_mms>, method=PLAIN,
rip=192.168.11.88, lip=192.168.134.44, mpid=7382, session=<khD317NKD+PAqAtY>
Mar 14 18:06:06 pop3(a.zurlo_mms): Info: Disconnected: Logged out top=0/0,
retr=0/0, del=0/0, size=0
Mar 14 18:06:06 auth: Debug: auth client connected (pid=7383)
Mar 14 18:06:06 auth: Debug: client in: AUTH 1 PLAIN service=pop3
session=KP/817NKEuPAqAtY lip=192.168.134.44 rip=192.168.11.88
lport=110 rport=58130
resp=XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX (previous base64 data may
contain sensitive data)
Mar 14 18:06:06 auth-worker(6538): Debug:
pam(controllovideorobot_mms,192.168.11.88,<KP/817NKEuPAqAtY>): lookup
service=dovecot
Mar 14 18:06:06 auth-worker(6538): Debug:
pam(controllovideorobot_mms,192.168.11.88,<KP/817NKEuPAqAtY>): #1/1 style=1
msg=Password:
Mar 14 18:06:06 auth: Debug: client passdb out: OK 1
user=controllovideorobot_mms
Mar 14 18:06:06 auth: Debug: master in: REQUEST 1922301953 7383 1
233822ae3d04cb50fb2e019e50750e2e session_pid=7384
Mar 14 18:06:06 auth: Debug:
passwd(controllovideorobot_mms,192.168.11.88,<KP/817NKEuPAqAtY>): userdb cache
hit: system_groups_user=controllovideorobot_mmsuid=3008 gid=111
home=/home/controllovideorobot_mms
Mar 14 18:06:06 auth: Debug: master userdb out: USER 1922301953
controllovideorobot_mms system_groups_user=controllovideorobot_mms
uid=3008 gid=111 home=/home/controllovideorobot_mms
Mar 14 18:06:06 pop3-login: Info: Login: user=<controllovideorobot_mms>,
method=PLAIN, rip=192.168.11.88, lip=192.168.134.44, mpid=7384,
session=<KP/817NKEuPAqAtY>
Mar 14 18:06:06 pop3(controllovideorobot_mms): Info: Disconnected: Logged out
top=0/0, retr=0/0, del=0/0, size=0
Mar 14 18:06:08 imap(info_eml): Info: Logged out in=557 out=45291
Mar 14 18:06:08 imap(info_eml): Info: Logged out in=91 out=801
Mar 14 18:06:12 imap(mau_mct): Info: Server shutting down. in=1273 out=27116
Mar 14 18:06:12 imap(mau_mct): Info: Server shutting down. in=296 out=3481
Mar 14 18:06:12 imap(mau_mct): Info: Server shutting down. in=528 out=1528
Mar 14 18:06:12 imap(mau_mct): Info: Server shutting down. in=1090 out=24607
Mar 14 18:06:12 imap(mau_mct): Info: Server shutting down. in=568 out=10682
Mar 14 18:06:12 imap(milena): Info: Server shutting down. in=87414 out=316725
Mar 14 18:06:12 imap(mau_mct): Info: Server shutting down. in=1005 out=49462
Mar 14 18:06:12 imap(alessandro.lucchini_pds): Info: Server shutting down.
in=1512 out=3937
Mar 14 18:06:12 imap(studiofrasi): Info: Server shutting down. in=144 out=1263
Mar 14 18:06:12 imap(mau_mct): Info: Server shutting down. in=519 out=1483
Mar 14 18:06:12 imap(alessandro.lucchini_pds): Info: Server shutting down.
in=382 out=1808
Mar 14 18:06:12 imap(presidente_pfp): Info: Server shutting down. in=1367
out=4035
Mar 14 18:06:12 imap(presidente_pfp): Info: Server shutting down. in=1455
out=2839
Mar 14 18:06:12 imap(presidente_pfp): Info: Server shutting down. in=997
out=2359
Mar 14 18:06:13 pop3(enricoarienti_cpx): Info: Disconnected: Logged out
top=0/0, retr=0/0, del=0/224, size=31522996
Mar 14 18:06:15 auth: Debug: client in: AUTH 3 LOGIN service=smtp
nologin lip=212.45.144.44 rip=93.174.95.34
Mar 14 18:06:15 auth: Debug: client passdb out: CONT 3 VXNlcm5hbWU6
Mar 14 18:06:15 auth: Debug: client in: CONT 3
XXXXXXXXXXXXXXXXXXXXXXXX (previous base64 data may contain sensitive data)
Mar 14 18:06:15 auth: Debug: client passdb out: CONT 3 UGFzc3dvcmQ6
Mar 14 18:06:15 auth: Debug: client in: CONT 3 XXXXXXXX (previous
base64 data may contain sensitive data)
Mar 14 18:06:20 imap(presidente_pfp): Info: Server shutting down. in=7841
out=38312
Mar 14 18:06:20 auth: Debug: client in: AUTH 7 LOGIN service=smtp
nologin lip=212.45.144.44 rip=94.102.58.20
Mar 14 18:06:20 auth: Debug: client passdb out: CONT 7 VXNlcm5hbWU6
Mar 14 18:06:20 auth: Debug: client in: CONT 7
XXXXXXXXXXXXXXXXXXXXXXXXXXXX (previous base64 data may contain sensitive data)
Mar 14 18:06:20 auth: Debug: client passdb out: CONT 7 UGFzc3dvcmQ6
Mar 14 18:06:20 auth: Debug: client in: CONT 7 XXXXXXXXXXXX (previous
base64 data may contain sensitive data)
Mar 14 18:06:25 master: Info: Dovecot v2.2.28 (bed8434) starting up for pop3,
imap
Mar 14 18:06:27 auth: Debug: Loading modules from directory:
/usr/lib/dovecot/auth
Mar 14 18:06:27 auth: Debug: Read auth token secret from
/var/run/dovecot/auth-token-secret.dat
Mar 14 18:06:27 auth: Debug: auth client connected (pid=7425)
Mar 14 18:06:28 auth: Debug: auth client connected (pid=7427)
Mar 14 18:06:28 auth: Debug: client in: AUTH 1 PLAIN service=pop3
session=u35A2bNKZNYC5OBN lip=212.45.144.44 rip=2.228.224.77
lport=110 rport=54884 resp=XXXXXXXXXXXXXXXXXXXXXXXX (previous base64
data may contain sensitive data)
Mar 14 18:06:28 auth-worker(7428): Debug: Loading modules from directory:
/usr/lib/dovecot/auth
Mar 14 18:06:28 auth-worker(7428): Debug:
pam(info_mpw,2.228.224.77,<u35A2bNKZNYC5OBN>): lookup service=dovecot
Mar 14 18:06:28 auth-worker(7428): Debug:
pam(info_mpw,2.228.224.77,<u35A2bNKZNYC5OBN>): #1/1 style=1 msg=Password:
Mar 14 18:06:28 auth: Debug: client passdb out: OK 1 user=info_mpw
Mar 14 18:06:28 auth: Debug: master in: REQUEST 1603010561 7427 1
91eec6a77f3c053d100bfd66724d2bf8 session_pid=7429
Mar 14 18:06:28 auth: Debug: passwd(info_mpw,2.228.224.77,<u35A2bNKZNYC5OBN>):
userdb cache miss
Mar 14 18:06:28 auth-worker(7428): Debug:
passwd(info_mpw,2.228.224.77,<u35A2bNKZNYC5OBN>): lookup
Mar 14 18:06:28 auth: Debug: master userdb out: USER 1603010561
info_mpw system_groups_user=info_mpw uid=2766 gid=111
home=/home/info_mpw
Mar 14 18:06:28 pop3-login: Info: Login: user=<info_mpw>, method=PLAIN,
rip=2.228.224.77, lip=212.45.144.44, mpid=7429, session=<u35A2bNKZNYC5OBN>
Mar 14 18:06:28 pop3(info_mpw): Info: Disconnected: Logged out top=0/0,
retr=0/0, del=0/156, size=21305074
Mar 14 18:06:28 auth: Debug: auth client connected (pid=7430)
Mar 14 18:06:28 auth: Debug: client in: AUTH 1 PLAIN service=pop3
session=mRpC2bNKZtYC5OBN lip=212.45.144.44 rip=2.228.224.77
lport=110 rport=54886 resp=XXXXXXXXXXXXXXXXXXXXXXXX (previous base64
data may contain sensitive data)
Mar 14 18:06:28 auth-worker(7428): Debug:
pam(info_auv,2.228.224.77,<mRpC2bNKZtYC5OBN>): lookup service=dovecot
Mar 14 18:06:28 auth-worker(7428): Debug:
pam(info_auv,2.228.224.77,<mRpC2bNKZtYC5OBN>): #1/1 style=1 msg=Password:
Mar 14 18:06:28 auth: Debug: client passdb out: OK 1 user=info_auv
Mar 14 18:06:28 auth: Debug: master in: REQUEST 3943038977 7430 1
2c8b6c326334aca7e7cb662f218ff917 session_pid=7431
Mar 14 18:06:28 auth: Debug: passwd(info_auv,2.228.224.77,<mRpC2bNKZtYC5OBN>):
userdb cache miss
Mar 14 18:06:28 auth-worker(7428): Debug:
passwd(info_auv,2.228.224.77,<mRpC2bNKZtYC5OBN>): lookup
Mar 14 18:06:28 auth: Debug: master userdb out: USER 3943038977
info_auv system_groups_user=info_auv uid=2719 gid=111
home=/home/info_auv
Mar 14 18:06:28 pop3-login: Info: Login: user=<info_auv>, method=PLAIN,
rip=2.228.224.77, lip=212.45.144.44, mpid=7431, session=<mRpC2bNKZtYC5OBN>
Mar 14 18:06:28 pop3(info_auv): Info: Disconnected: Logged out top=0/0,
retr=0/0, del=0/21, size=1691958
Mar 14 18:06:28 auth: Debug: client in: AUTH 1 PLAIN service=imap
session=3sNC2bNKP602zbWH lip=212.45.144.44
rip=54.205.181.135lport=143 rport=44351
resp=XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX (previous base64 data may contain
sensitive data)
Mar 14 18:06:28 auth-worker(7428): Debug:
pam(presidente_pfp,54.205.181.135,<3sNC2bNKP602zbWH>): lookup service=dovecot
Mar 14 18:06:28 auth-worker(7428): Debug:
pam(presidente_pfp,54.205.181.135,<3sNC2bNKP602zbWH>): #1/1 style=1
msg=Password:
Mar 14 18:06:28 auth: Debug: client passdb out: OK 1
user=presidente_pfp
Mar 14 18:06:28 auth: Debug: master in: REQUEST 1901068289 7425 1
5fe7716d8d250abfdf4ff45a3305cfe6 session_pid=7433
request_auth_token
Mar 14 18:06:28 auth: Debug:
passwd(presidente_pfp,54.205.181.135,<3sNC2bNKP602zbWH>): userdb cache miss
Mar 14 18:06:28 auth-worker(7428): Debug:
passwd(presidente_pfp,54.205.181.135,<3sNC2bNKP602zbWH>): lookup
Mar 14 18:06:28 auth: Debug: master userdb out: USER 1901068289
presidente_pfp system_groups_user=presidente_pfp uid=2343 gid=111
home=/home/presidente_pfp
auth_token=ecf98b41fdd6c8455c79daec0e131d886ec24a4d
Mar 14 18:06:28 imap-login: Info: Login: user=<presidente_pfp>, method=PLAIN,
rip=54.205.181.135, lip=212.45.144.44, mpid=7433, session=<3sNC2bNKP602zbWH>
Mar 14 18:06:28 auth: Debug: client in: AUTH 4 LOGIN service=smtp
nologin lip=212.45.144.44 rip=93.174.93.18
Mar 14 18:06:28 auth: Debug: client passdb out: CONT 4 VXNlcm5hbWU6
Mar 14 18:06:28 auth: Debug: client in: CONT 4
XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX (previous base64 data may contain sensitive
data)
Mar 14 18:06:28 auth: Debug: client passdb out: CONT 4 UGFzc3dvcmQ6
Mar 14 18:06:28 auth: Debug: client in: CONT 4 XXXXXXXXXXXX (previous
base64 data may contain sensitive data)
Mar 14 18:06:32 auth: Debug: client in: AUTH 1 PLAIN service=pop3
session=RguE2bNKqMJf8bzq lip=212.45.144.70
rip=95.241.188.234lport=110 rport=49832
resp=XXXXXXXXXXXXXXXXXXXXXXXXXXXX (previous base64 data may contain sensitive
data)
Mar 14 18:06:33 auth: Debug: auth client connected (pid=7436)
Mar 14 18:06:33 auth: Debug: client in: AUTH 1 PLAIN service=pop3
session=4XKV2bNKan/AqKeg lip=192.168.134.44
rip=192.168.167.160lport=110 rport=32618
Mar 14 18:06:33 auth: Debug: client passdb out: CONT 1
Mar 14 18:06:33 auth: Debug: client in: CONT 1
XXXXXXXXXXXXXXXXXXXXXXXXXXXX (previous base64 data may contain sensitive data)
Mar 14 18:06:33 auth-worker(7428): Debug:
pam(dabbene_mct,192.168.167.160,<4XKV2bNKan/AqKeg>): lookup service=dovecot
Mar 14 18:06:33 auth-worker(7428): Debug:
pam(dabbene_mct,192.168.167.160,<4XKV2bNKan/AqKeg>): #1/1 style=1 msg=Password:
Mar 14 18:06:33 auth: Debug: client passdb out: OK 1
user=dabbene_mct
Mar 14 18:06:33 auth: Debug: master in: REQUEST 2830106625 7436 1
99d57dabac099af0f5f1a719a38325d7 session_pid=7437
Mar 14 18:06:33 auth: Debug:
passwd(dabbene_mct,192.168.167.160,<4XKV2bNKan/AqKeg>): userdb cache miss
Mar 14 18:06:33 auth-worker(7428): Debug:
passwd(dabbene_mct,192.168.167.160,<4XKV2bNKan/AqKeg>): lookup
Mar 14 18:06:33 auth: Debug: master userdb out: USER 2830106625
dabbene_mct system_groups_user=dabbene_mct uid=3384 gid=111
home=/home/dabbene_mct
Mar 14 18:06:33 pop3-login: Info: Login: user=<dabbene_mct>, method=PLAIN,
rip=192.168.167.160, lip=192.168.134.44, mpid=7437, session=<4XKV2bNKan/AqKeg>
Mar 14 18:06:33 pop3(dabbene_mct): Info: Disconnected: Logged out top=0/0,
retr=0/0, del=0/9450, size=1755467592
Mar 14 18:06:34 auth: Debug: auth client connected (pid=7438)
Mar 14 18:06:34 auth: Debug: client in: AUTH 1 PLAIN service=pop3
session=qASe2bNKbH/AqKeg lip=192.168.134.44
rip=192.168.167.160lport=110 rport=32620
Mar 14 18:06:34 auth: Debug: client passdb out: CONT 1
Mar 14 18:06:34 auth: Debug: client in: CONT 1
XXXXXXXXXXXXXXXXXXXXXXXXXXXX (previous base64 data may contain sensitive data)
Mar 14 18:06:34 auth-worker(7428): Debug:
pam(dabbene_mct,192.168.167.160,<qASe2bNKbH/AqKeg>): lookup service=dovecot
Mar 14 18:06:34 auth-worker(7428): Debug:
pam(dabbene_mct,192.168.167.160,<qASe2bNKbH/AqKeg>): #1/1 style=1 msg=Password:
Mar 14 18:06:34 auth: Debug: client passdb out: OK 1
user=dabbene_mct
Mar 14 18:06:34 auth: Debug: master in: REQUEST 770310145 7438 1
a8d9f74f6a53257915bd79f7efd1123a session_pid=7439
Mar 14 18:06:34 auth: Debug:
passwd(dabbene_mct,192.168.167.160,<qASe2bNKbH/AqKeg>): userdb cache hit:
system_groups_user=dabbene_mct uid=3384 gid=111 home=/home/dabbene_mct
Mar 14 18:06:34 auth: Debug: master userdb out: USER 770310145
dabbene_mct system_groups_user=dabbene_mct uid=3384 gid=111
home=/home/dabbene_mct
Mar 14 18:06:34 pop3-login: Info: Login: user=<dabbene_mct>, method=PLAIN,
rip=192.168.167.160, lip=192.168.134.44, mpid=7439, session=<qASe2bNKbH/AqKeg>
Mar 14 18:06:34 pop3(dabbene_mct): Info: Disconnected: Logged out top=0/0,
retr=0/0, del=0/9450, size=1755467592
Mar 14 18:06:34 auth: Debug: auth client connected (pid=7440)
Mar 14 18:06:34 auth: Debug: client in: AUTH 1 PLAIN service=pop3
session=Jgqk2bNKbn/AqKeg lip=192.168.134.44
rip=192.168.167.160lport=110 rport=32622
Mar 14 18:06:34 auth: Debug: client passdb out: CONT 1
Mar 14 18:06:34 auth: Debug: client in: CONT 1
XXXXXXXXXXXXXXXXXXXXXXXXXXXX (previous base64 data may contain sensitive data)
Mar 14 18:06:34 auth-worker(7428): Debug:
pam(dabbene_mct,192.168.167.160,<Jgqk2bNKbn/AqKeg>): lookup service=dovecot
Mar 14 18:06:34 auth-worker(7428): Debug:
pam(dabbene_mct,192.168.167.160,<Jgqk2bNKbn/AqKeg>): #1/1 style=1 msg=Password:
Mar 14 18:06:34 auth: Debug: client passdb out: OK 1
user=dabbene_mct
Mar 14 18:06:34 auth: Debug: master in: REQUEST 380239873 7440 1
65ec4289d0817fac99e77a6748b572b0 session_pid=7441
Mar 14 18:06:34 auth: Debug:
passwd(dabbene_mct,192.168.167.160,<Jgqk2bNKbn/AqKeg>): userdb cache hit:
system_groups_user=dabbene_mct uid=3384 gid=111 home=/home/dabbene_mct
Mar 14 18:06:34 auth: Debug: master userdb out: USER 380239873
dabbene_mct system_groups_user=dabbene_mct uid=3384 gid=111
home=/home/dabbene_mct
Mar 14 18:06:34 pop3-login: Info: Login: user=<dabbene_mct>, method=PLAIN,
rip=192.168.167.160, lip=192.168.134.44, mpid=7441, session=<Jgqk2bNKbn/AqKeg>
Mar 14 18:06:34 pop3(dabbene_mct): Info: Disconnected: Logged out top=0/0,
retr=0/0, del=0/9450, size=1755467592
Mar 14 18:06:34 auth: Debug: auth client connected (pid=7443)
Mar 14 18:06:34 auth: Debug: client in: AUTH 1 PLAIN service=pop3
session=N3yq2bNKcH/AqKeg lip=192.168.134.44
rip=192.168.167.160lport=110 rport=32624
Mar 14 18:06:34 auth: Debug: client passdb out: CONT 1
Mar 14 18:06:34 auth: Debug: client in: CONT 1
XXXXXXXXXXXXXXXXXXXXXXXXXXXX (previous base64 data may contain sensitive data)
Mar 14 18:06:34 auth-worker(7428): Debug:
pam(dabbene_mct,192.168.167.160,<N3yq2bNKcH/AqKeg>): lookup service=dovecot
Mar 14 18:06:34 auth-worker(7428): Debug:
pam(dabbene_mct,192.168.167.160,<N3yq2bNKcH/AqKeg>): #1/1 style=1 msg=Password:
Mar 14 18:06:34 auth: Debug: client passdb out: OK 1
user=dabbene_mct
Mar 14 18:06:34 auth: Debug: master in: REQUEST 3907125249 7443 1
5e61646a95e7b3f57680a997a6e857db session_pid=7444
Mar 14 18:06:34 auth: Debug:
passwd(dabbene_mct,192.168.167.160,<N3yq2bNKcH/AqKeg>): userdb cache hit:
system_groups_user=dabbene_mct uid=3384 gid=111 home=/home/dabbene_mct
Mar 14 18:06:34 auth: Debug: master userdb out: USER 3907125249
dabbene_mct system_groups_user=dabbene_mct uid=3384 gid=111
home=/home/dabbene_mct
Mar 14 18:06:34 pop3-login: Info: Login: user=<dabbene_mct>, method=PLAIN,
rip=192.168.167.160, lip=192.168.134.44, mpid=7444, session=<N3yq2bNKcH/AqKeg>
Mar 14 18:06:35 pop3(dabbene_mct): Info: Disconnected: Logged out top=0/0,
retr=0/0, del=0/9450, size=1755467592
Mar 14 18:06:36 auth: Debug: auth client connected (pid=7445)
Mar 14 18:06:36 auth: Debug: client in: AUTH 1 PLAIN service=imap
secured no-penalty session=pKbC2bNKvcrAqIYQ
lip=192.168.134.44rip=192.168.134.16 lport=143 rport=51901
resp=XXXXXXXXXXXXXXXXXXXXXXXXXXXX (previous base64 data may contain sensitive
data)
Mar 14 18:06:36 auth-worker(7428): Debug:
pam(pteros_gan,192.168.134.16,<pKbC2bNKvcrAqIYQ>): lookup service=dovecot
Mar 14 18:06:36 auth-worker(7428): Debug:
pam(pteros_gan,192.168.134.16,<pKbC2bNKvcrAqIYQ>): #1/1 style=1 msg=Password:
Mar 14 18:06:36 auth: Debug: client passdb out: OK 1 user=pteros_gan
Mar 14 18:06:36 auth: Debug: master in: REQUEST 2847801345 7445 1
7cdbea1c357d06b96e9440d1cf0a5aae session_pid=7446
request_auth_token
Mar 14 18:06:36 auth: Debug:
passwd(pteros_gan,192.168.134.16,<pKbC2bNKvcrAqIYQ>): userdb cache miss
Mar 14 18:06:36 auth-worker(7428): Debug:
passwd(pteros_gan,192.168.134.16,<pKbC2bNKvcrAqIYQ>): lookup
Mar 14 18:06:36 auth: Debug: master userdb out: USER 2847801345
pteros_gan system_groups_user=pteros_gan uid=3264 gid=111
home=/home/pteros_gan auth_token=e8801f4334e57f578eba11e501062d2a221a76ec
Mar 14 18:06:36 imap-login: Info: Login: user=<pteros_gan>, method=PLAIN,
rip=192.168.134.16, lip=192.168.134.44, mpid=7446, secured,
session=<pKbC2bNKvcrAqIYQ>
Mar 14 18:06:37 imap(pteros_gan): Info: Logged out in=92 out=993
Mar 14 18:06:41 auth: Debug: auth client connected (pid=7449)
Mar 14 18:06:41 auth: Debug: client in: AUTH 1 PLAIN service=imap
session=HkkJ2rNKJs5dISSB lip=212.45.144.44 rip=93.33.36.129
lport=143 rport=52774 resp=XXXXXXXXXXXXXXXXXXXX (previous base64 data
may contain sensitive data)
Mar 14 18:06:41 auth-worker(7428): Debug:
pam(milena,93.33.36.129,<HkkJ2rNKJs5dISSB>): lookup service=dovecot
Mar 14 18:06:41 auth-worker(7428): Debug:
pam(milena,93.33.36.129,<HkkJ2rNKJs5dISSB>): #1/1 style=1 msg=Password:
Mar 14 18:06:41 auth: Debug: client passdb out: OK 1 user=milena
Mar 14 18:06:41 auth: Debug: master in: REQUEST 2300051457 7449 1
1c19961e792ebed350ce962c74eca78a session_pid=7451
request_auth_token
Mar 14 18:06:41 auth: Debug: passwd(milena,93.33.36.129,<HkkJ2rNKJs5dISSB>):
userdb cache miss
Mar 14 18:06:41 auth-worker(7428): Debug:
passwd(milena,93.33.36.129,<HkkJ2rNKJs5dISSB>): lookup
Mar 14 18:06:41 auth: Debug: master userdb out: USER 2300051457 milena
system_groups_user=milena uid=2049 gid=111 home=/home/milena
auth_token=e3057d97e713495f979aad9b9d74454e1a8aa532
Mar 14 18:06:41 imap-login: Info: Login: user=<milena>, method=PLAIN,
rip=93.33.36.129, lip=212.45.144.44, mpid=7451, session=<HkkJ2rNKJs5dISSB>
Mar 14 18:06:42 pop3(carnevali): Info: Server shutting down. top=0/0, retr=0/0,
del=0/1407, size=134075183
Mar 14 18:06:42 pop3(board_cwl): Info: Server shutting down. top=0/0, retr=0/0,
del=0/0, size=0
Mar 14 18:06:42 pop3-login: Info: Disconnected: Shutting down (disconnected
while authenticating, waited 10 secs): user=<>, method=PLAIN,
rip=95.241.188.234, lip=212.45.144.70, session=<RguE2bNKqMJf8bzq>
Mar 14 18:06:42 auth: Debug: client in: CANCEL 1
Mar 14 18:06:47 auth: Debug: auth client connected (pid=7452)
Mar 14 18:06:47 auth: Debug: client in: AUTH 1 PLAIN service=pop3
session=bE5l2rNKoPVf8bzq lip=212.45.144.70
rip=95.241.188.234lport=110 rport=62880
resp=XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX(previous base64 data may contain
sensitive data)
Mar 14 18:06:47 auth-worker(7428): Debug:
pam(m.ceriani_dls,95.241.188.234,<bE5l2rNKoPVf8bzq>): lookup service=dovecot
Mar 14 18:06:47 auth-worker(7428): Debug:
pam(m.ceriani_dls,95.241.188.234,<bE5l2rNKoPVf8bzq>): #1/1 style=1
msg=Password:
Mar 14 18:06:47 auth: Debug: client passdb out: OK 1
user=m.ceriani_dls
Mar 14 18:06:47 auth: Debug: master in: REQUEST 4273209345 7452 1
bdc6b6e0ff022b2b36b73145f7c8e49f session_pid=7453
Mar 14 18:06:47 auth: Debug:
passwd(m.ceriani_dls,95.241.188.234,<bE5l2rNKoPVf8bzq>): userdb cache miss
Mar 14 18:06:47 auth-worker(7428): Debug:
passwd(m.ceriani_dls,95.241.188.234,<bE5l2rNKoPVf8bzq>): lookup
Mar 14 18:06:47 auth: Debug: master userdb out: USER 4273209345
m.ceriani_dls system_groups_user=m.ceriani_dls uid=3370 gid=111
home=/home/m.ceriani_dls
Mar 14 18:06:47 pop3-login: Info: Login: user=<m.ceriani_dls>, method=PLAIN,
rip=95.241.188.234, lip=212.45.144.70, mpid=7453, session=<bE5l2rNKoPVf8bzq>
Mar 14 18:06:47 pop3(m.ceriani_dls): Info: Disconnected: Logged out top=0/0,
retr=0/0, del=0/0, size=0
Mar 14 18:06:51 auth: Debug: auth client connected (pid=7458)
Mar 14 18:06:51 auth: Debug: auth client connected (pid=7459)
Mar 14 18:06:51 auth: Debug: auth client connected (pid=7460)
Mar 14 18:06:51 auth: Debug: client in: AUTH 1 PLAIN service=pop3
session=Cg+l2rNKyMJPGI56 lip=212.45.144.44 rip=79.24.142.122
lport=110 rport=49864 resp=XXXXXXXXXXXXXXXXXXXXXXXX (previous base64
data may contain sensitive data)
Mar 14 18:06:51 auth-worker(7428): Debug:
pam(lombardi,79.24.142.122,<Cg+l2rNKyMJPGI56>): lookup service=dovecot
Mar 14 18:06:51 auth: Debug: client in: AUTH 1 PLAIN service=pop3
session=PBal2rNKme9PGI56 lip=212.45.144.44 rip=79.24.142.122
lport=110 rport=61337 resp=XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX
(previous base64 data may contain sensitive data)
Mar 14 18:06:51 auth-worker(7428): Debug:
pam(lombardi,79.24.142.122,<Cg+l2rNKyMJPGI56>): #1/1 style=1 msg=Password:
Mar 14 18:06:51 auth: Debug: client passdb out: OK 1 user=lombardi
Mar 14 18:06:51 auth: Debug: master in: REQUEST 3172204545 7458 1
a5455141079bfe1cd3f6ab39db6a79b5 session_pid=7462
Mar 14 18:06:51 auth: Debug: passwd(lombardi,79.24.142.122,<Cg+l2rNKyMJPGI56>):
userdb cache miss
Mar 14 18:06:51 auth-worker(7428): Debug:
passwd(lombardi,79.24.142.122,<Cg+l2rNKyMJPGI56>): lookup
Mar 14 18:06:51 auth: Debug: master userdb out: USER 3172204545
lombardi system_groups_user=lombardi uid=1637 gid=111
home=/home/lombardi
Mar 14 18:06:51 pop3-login: Info: Login: user=<lombardi>, method=PLAIN,
rip=79.24.142.122, lip=212.45.144.44, mpid=7462, session=<Cg+l2rNKyMJPGI56>
Mar 14 18:06:51 auth-worker(7461): Debug: Loading modules from directory:
/usr/lib/dovecot/auth
Mar 14 18:06:51 auth-worker(7461): Debug:
pam(mara.lombardi_pds,79.24.142.122,<PBal2rNKme9PGI56>): lookup service=dovecot
Mar 14 18:06:51 auth-worker(7461): Debug:
pam(mara.lombardi_pds,79.24.142.122,<PBal2rNKme9PGI56>): #1/1 style=1
msg=Password:
Mar 14 18:06:51 auth: Debug: client passdb out: OK 1
user=mara.lombardi_pds
Mar 14 18:06:51 auth: Debug: master in: REQUEST 799670273 7459 1
0137c0af693e392e968a6539a56c0150 session_pid=7463
Mar 14 18:06:51 auth: Debug:
passwd(mara.lombardi_pds,79.24.142.122,<PBal2rNKme9PGI56>): userdb cache miss
Mar 14 18:06:51 auth-worker(7428): Debug:
passwd(mara.lombardi_pds,79.24.142.122,<PBal2rNKme9PGI56>): lookup
Mar 14 18:06:51 auth: Debug: master userdb out: USER 799670273
mara.lombardi_pds system_groups_user=mara.lombardi_pds uid=2498
gid=111 home=/home/mara.lombardi_pds
Mar 14 18:06:51 pop3-login: Info: Login: user=<mara.lombardi_pds>,
method=PLAIN, rip=79.24.142.122, lip=212.45.144.44, mpid=7463,
session=<PBal2rNKme9PGI56>
Mar 14 18:06:51 auth: Debug: client in: AUTH 1 PLAIN service=pop3
session=Mv6l2rNKJMtPGI56 lip=212.45.144.44 rip=79.24.142.122
lport=110 rport=52004 resp=XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXus
base64 data may contain sensitive data)
Mar 14 18:06:51 auth-worker(7428): Debug:
pam(clmara_mrc,79.24.142.122,<Mv6l2rNKJMtPGI56>): lookup service=dovecot
Mar 14 18:06:51 auth-worker(7428): Debug:
pam(clmara_mrc,79.24.142.122,<Mv6l2rNKJMtPGI56>): #1/1 style=1 msg=Password:
Mar 14 18:06:51 auth: Debug: client passdb out: OK 1 user=clmara_mrc
Mar 14 18:06:51 auth: Debug: master in: REQUEST 1579548673 7460 1
29fc24ff3e462806e1633496ff00838c session_pid=7464
Mar 14 18:06:51 auth: Debug:
passwd(clmara_mrc,79.24.142.122,<Mv6l2rNKJMtPGI56>): userdb cache miss
Mar 14 18:06:51 auth-worker(7428): Debug:
passwd(clmara_mrc,79.24.142.122,<Mv6l2rNKJMtPGI56>): lookup
Mar 14 18:06:51 auth: Debug: master userdb out: USER 1579548673
clmara_mrc system_groups_user=clmara_mrc uid=2429 gid=111
home=/home/clmara_mrc
Mar 14 18:06:51 pop3-login: Info: Login: user=<clmara_mrc>, method=PLAIN,
rip=79.24.142.122, lip=212.45.144.44, mpid=7464, session=<Mv6l2rNKJMtPGI56>
Mar 14 18:06:51 pop3(lombardi): Info: Disconnected: Logged out top=0/0,
retr=0/0, del=0/0, size=0
Mar 14 18:06:51 pop3(mara.lombardi_pds): Info: Disconnected: Logged out
top=0/0, retr=0/0, del=0/0, size=0
Mar 14 18:06:51 pop3(clmara_mrc): Info: Disconnected: Logged out top=0/0,
retr=0/0, del=0/0, size=0
Mar 14 18:06:56 auth: Debug: auth client connected (pid=7467)
Mar 14 18:06:57 auth: Debug: client in: AUTH 1 PLAIN service=imap
secured session=O00C27NKqfqXQs+K lip=212.45.144.44
rip=151.66.207.138 lport=993 rport=64169
local_name=baobab.bilink.net resp=XXXXXXXXXXXXXXXXXXXXXXXXXXXX (previous
base64 data may contain sensitive data)
Mar 14 18:06:57 auth-worker(7428): Debug:
pam(gloria_mct,151.66.207.138,<O00C27NKqfqXQs+K>): lookup service=dovecot
Mar 14 18:06:57 auth-worker(7428): Debug:
pam(gloria_mct,151.66.207.138,<O00C27NKqfqXQs+K>): #1/1 style=1 msg=Password:
Mar 14 18:06:57 auth: Debug: client passdb out: OK 1 user=gloria_mct
Mar 14 18:06:57 auth: Debug: master in: REQUEST 1863581697 7467 1
5de654b8e8dc647f0f1caadf293acb0d session_pid=7470
request_auth_token
Mar 14 18:06:57 auth: Debug:
passwd(gloria_mct,151.66.207.138,<O00C27NKqfqXQs+K>): userdb cache miss
Mar 14 18:06:57 auth-worker(7428): Debug:
passwd(gloria_mct,151.66.207.138,<O00C27NKqfqXQs+K>): lookup
Mar 14 18:06:57 auth: Debug: master userdb out: USER 1863581697
gloria_mct system_groups_user=gloria_mct uid=2052 gid=111
home=/home/gloria_mct auth_token=965428ab0cd3e9840929cb145aa4b695cf7102f4
Mar 14 18:06:57 imap-login: Info: Login: user=<gloria_mct>, method=PLAIN,
rip=151.66.207.138, lip=212.45.144.44, mpid=7470, TLS,
session=<O00C27NKqfqXQs+K>
Mar 14 18:06:58 auth: Debug: auth client connected (pid=7471)
Mar 14 18:06:58 auth: Debug: client in: AUTH 1 PLAIN service=pop3
session=WXwT27NKY41KfVKS lip=212.45.144.44 rip=74.125.82.146
lport=110 rport=36195 resp=XXXXXXXXXXXXXXXXXXXXXXXX (previous base64
data may contain sensitive data)
Mar 14 18:06:58 auth-worker(7428): Debug:
pam(rod_mcs,74.125.82.146,<WXwT27NKY41KfVKS>): lookup service=dovecot
Mar 14 18:06:58 auth-worker(7428): Debug:
pam(rod_mcs,74.125.82.146,<WXwT27NKY41KfVKS>): #1/1 style=1 msg=Password:
Mar 14 18:06:58 auth: Debug: client passdb out: OK 1 user=rod_mcs
Mar 14 18:06:58 auth: Debug: master in: REQUEST 1814953985 7471 1
31068e449668e6b779966eb3d0913b90 session_pid=7472
Mar 14 18:06:58 auth: Debug: passwd(rod_mcs,74.125.82.146,<WXwT27NKY41KfVKS>):
userdb cache miss
Mar 14 18:06:58 auth-worker(7428): Debug:
passwd(rod_mcs,74.125.82.146,<WXwT27NKY41KfVKS>): lookup
Mar 14 18:06:58 auth: Debug: master userdb out: USER 1814953985 rod_mcs
system_groups_user=rod_mcs uid=3478 gid=111 home=/home/rod_mcs
Mar 14 18:06:58 pop3-login: Info: Login: user=<rod_mcs>, method=PLAIN,
rip=74.125.82.146, lip=212.45.144.44, mpid=7472, session=<WXwT27NKY41KfVKS>
Mar 14 18:06:59 auth: Debug: auth client connected (pid=7473)
Mar 14 18:06:59 auth: Debug: client in: AUTH 1 PLAIN service=imap
secured session=3r0e27NK4dWXQs+K lip=212.45.144.44
rip=151.66.207.138 lport=993 rport=54753
local_name=baobab.bilink.net resp=XXXXXXXXXXXXXXXXXXXXXXXXXXXX (previous
base64 data may contain sensitive data)
Mar 14 18:06:59 auth-worker(7428): Debug:
pam(gloria_mct,151.66.207.138,<3r0e27NK4dWXQs+K>): lookup service=dovecot
Mar 14 18:06:59 auth-worker(7428): Debug:
pam(gloria_mct,151.66.207.138,<3r0e27NK4dWXQs+K>): #1/1 style=1 msg=Password:
Mar 14 18:06:59 auth: Debug: client passdb out: OK 1 user=gloria_mct
Mar 14 18:06:59 auth: Debug: master in: REQUEST 776994817 7473 1
a5fc0b1823093916d7af6bccfe478d0b session_pid=7474
request_auth_token
Mar 14 18:06:59 auth: Debug:
passwd(gloria_mct,151.66.207.138,<3r0e27NK4dWXQs+K>): userdb cache hit:
system_groups_user=gloria_mct uid=2052 gid=111 home=/home/gloria_mct
Mar 14 18:06:59 auth: Debug: master userdb out: USER 776994817
gloria_mct system_groups_user=gloria_mct uid=2052 gid=111
home=/home/gloria_mct auth_token=4abadc259a1dd45540394cbdefe390a198bcbd80
Mar 14 18:06:59 imap-login: Info: Login: user=<gloria_mct>, method=PLAIN,
rip=151.66.207.138, lip=212.45.144.44, mpid=7474, TLS,
session=<3r0e27NK4dWXQs+K>
Mar 14 18:06:59 pop3(rod_mcs): Info: Disconnected: Logged out top=0/0,
retr=0/0, del=0/334, size=78892358
And Here is my doveconf -n:
# 2.2.28 (bed8434): /usr/local/etc/dovecot/dovecot.conf
# Pigeonhole version 0.4.17 (e179378)
# OS: Linux 3.0.101-105-pae i686 openSUSE 11.4 (i586) ext3
auth_cache_size = 3 k
auth_debug = yes
auth_debug_passwords = yes
auth_mechanisms = plain login
auth_verbose = yes
default_client_limit = 1249
default_vsz_limit = 712 M
disable_plaintext_auth = no
first_valid_gid = 0
first_valid_uid = 100
info_log_path = /var/log/dovecot/logfile.info
listen = *
log_path = /var/log/dovecot/logfile
login_greeting = Dovecot at Baobab ready.
login_trusted_networks = 127.0.0.0/8 212.45.144.0/24 192.168.134.0/24
mail_location =
mbox:/var/spool/mailboxes/%u:INBOX=/var/spool/mail/%u:DIRNAME=mbox:INDEX=/var/dovecot_indexes/%u
maildir_copy_with_hardlinks = no
managesieve_notify_capability = mailto
managesieve_sieve_capability = fileinto reject envelope encoded-character
vacation subaddress comparator-i;ascii-numeric relational regex imap4flags copy
include variables body enotify environment mailbox date index ihave duplicate
mime foreverypart extracttext
mbox_lock_timeout = 443 secs
namespace {
inbox = yes
location =
prefix =
separator = .
type = private
}
passdb {
driver = pam
}
plugin {
mail_log_events = delete undelete expunge copy mailbox_delete mailbox_rename
mail_log_fields = uid box msgid size
}
protocols = pop3 imap
service auth {
unix_listener /var/spool/postfix/private/auth {
group = postfix
mode = 0660
user = postfix
}
user = root
}
service imap-login {
inet_listener imaps {
address = *
}
process_limit = 512
}
service pop3-login {
chroot =
executable = pop3-login -D
inet_listener pop3s {
address = *
}
process_limit = 512
}
ssl_ca = </etc/ssl/ca.crt
ssl_cert = </etc/ssl/certs/dovecot.pem
ssl_key = # hidden, use -P to show it
userdb {
driver = passwd
}
version_ignore = yes
protocol imap {
imap_client_workarounds = delay-newmail
mail_plugins = mail_log notify
}
protocol pop3 {
pop3_client_workarounds = outlook-no-nuls oe-ns-eoh
pop3_enable_last = yes
}
protocol lda {
info_log_path = /var/log/dovecot/lda.log
log_path = /var/log/dovecot/lda-errors.log
postmaster_address = postmas...@mcs.it
}
Cheers,
Luciano.
--
/"\ /Via A. Salaino, 7 - 20144 Milano (Italy)
\ / ASCII RIBBON CAMPAIGN / PHONE : +39 2 485781 FAX: +39 2 48578250
X AGAINST HTML MAIL / E-MAIL: posthams...@sublink.sublink.org
/ \ AND POSTINGS / WWW: http://www.lesassaie.IT/
