R
ramuva
Guest
Hello everyone,
I've had a complaint from a user (same messages is being delivered over and over again).
quick googling revealed similar problem for some german guy:
http://www.plesk-forum.de/viewtopic.php?f=15&t=1437
I've had similar log entries:
---------------------------
May 27 03:38:45 domain postfix/qmgr[3306]: F27E5D6C006C: from=<www-data@mail.bc.lt>, size=90965, nrcpt=1 (queue active)
May 27 03:38:45 domain postfix-local[9806]: postfix-local: from=www-data@mail.bc.lt, to=info@domain.lt, dirname=/var/qmail/mailnames
May 27 03:38:45 domain postfix-local[9806]: hook_dir = '/usr/local/psa/handlers/before-local'
May 27 03:38:45 domain postfix-local[9806]: recipient[3] = 'info@domain.lt'
May 27 03:38:45 domain postfix-local[9806]: handlers dir = '/usr/local/psa/handlers/before-local/recipient/info@domain.lt'
May 27 03:38:45 domain postfix-local[9806]: call_handlers: call executable = '/usr/local/psa/handlers/info/10-spam-cHYvzG/executable'
May 27 03:38:45 domain spamd[30226]: spamd: got connection over /tmp/spamd_full.sock
May 27 03:38:45 domain spamd[30226]: spamd: using default config for info@domain.lt: /var/qmail/mailnames/domain.lt/info/.spamassassin/user_prefs
May 27 03:38:45 domain spamd[30226]: spamd: processing message <30c00c4165c1dee7319db0385354b41c@mail.bc.lt> for info@domain.lt:110
May 27 03:38:49 domain spamd[30226]: spamd: clean message (1.3/7.0) for info@domain.lt:110 in 3.8 seconds, 89810 bytes.
May 27 03:38:49 domain spamd[30226]: spamd: result: . 1 - AWL,FR_ALMOST_VIAG2,HTML_MESSAGE,RDNS_NONE scantime=3.8,size=89810,user=info@domain.lt,uid=110
,required_score=7.0,rhost=localhost,raddr=127.0.0.1,rport=/tmp/spamd_full.sock,mid=<30c00c4165c1dee7319db0385354b41c@mail.bc.lt>,autolearn=no
May 27 03:38:49 domain postfix-local[9806]: handlers_stderr: PASS
May 27 03:38:49 domain postfix-local[9806]: call_handlers: PASS during call '/usr/local/psa/handlers/info/10-spam-cHYvzG/executable' handler
May 27 03:38:49 domain spamd[30571]: prefork: child states: I
May 27 03:38:49 domain postfix-local[9806]: files: write buf 0xbfae8548[4096] to fd (7) error - (32) Broken pipe
May 27 03:38:49 domain postfix-local[9806]: files: cannot write chuck from 5 to 7 - (32) Broken pipe
May 27 03:38:49 domain postfix-local[9806]: LOG Unable to forward message to: marius@domain.lt
May 27 03:38:49 domain postfix-local[9806]: Unable to send mail for: marius@domain.lt
May 27 00:38:49 domain postfix/pickup[24364]: 71C72D6C0074: uid=110 from=<www-data@mail.bc.lt>
May 27 03:38:49 domain postfix/cleanup[9900]: 71C72D6C0074: message-id=<30c00c4165c1dee7319db0385354b41c@mail.bc.lt>
May 27 03:38:49 domain postfix/pipe[9805]: F27E5D6C006C: to=<info@domain.lt>, relay=plesk_virtual, delay=32215, delays=32212/0.03/0/3.9, dsn=4.2.1, stat
us=deferred (Message can not be delivered at this time )
May 27 03:38:49 domain postfix/qmgr[3306]: 71C72D6C0074: from=<www-data@mail.bc.lt>, size=1694, nrcpt=1 (queue active)
May 27 03:38:49 domain postfix-local[9902]: postfix-local: from=www-data@mail.bc.lt, to=marius@domain.lt, dirname=/var/qmail/mailnames
May 27 03:38:49 domain postfix-local[9902]: hook_dir = '/usr/local/psa/handlers/before-local'
May 27 03:38:49 domain postfix-local[9902]: recipient[3] = 'marius@domain.lt'
May 27 03:38:49 domain postfix-local[9902]: handlers dir = '/usr/local/psa/handlers/before-local/recipient/marius@domain.lt'
May 27 03:38:49 domain postfix-local[9902]: call_handlers: call executable = '/usr/local/psa/handlers/info/10-spam-Lgp4nG/executable'
May 27 03:38:49 domain spamd[30226]: spamd: got connection over /tmp/spamd_full.sock
May 27 03:38:49 domain spamd[30226]: spamd: using default config for marius@domain.lt: /var/qmail/mailnames/domain.lt/marius/.spamassassin/user_prefs
May 27 03:38:49 domain spamd[30226]: spamd: processing message <30c00c4165c1dee7319db0385354b41c@mail.bc.lt> for marius@domain.lt:110
May 27 03:38:54 domain spamd[30226]: spamd: clean message (-1.2/7.0) for marius@domain.lt:110 in 4.6 seconds, 1753 bytes.
May 27 03:38:54 domain spamd[30226]: spamd: result: . -1 - AWL,BAYES_05,RDNS_NONE scantime=4.6,size=1753,user=marius@domain.lt,uid=110,required_score=7.
0,rhost=localhost,raddr=127.0.0.1,rport=/tmp/spamd_full.sock,mid=<30c00c4165c1dee7319db0385354b41c@mail.bc.lt>,bayes=0.024401,autolearn=no
May 27 03:38:54 domain postfix-local[9902]: handlers_stderr: PASS
May 27 03:38:54 domain postfix-local[9902]: call_handlers: PASS during call '/usr/local/psa/handlers/info/10-spam-Lgp4nG/executable' handler
May 27 03:38:54 domain spamd[30571]: prefork: child states: I
May 27 03:38:54 domain postfix/pipe[9805]: 71C72D6C0074: to=<marius@domain.lt>, relay=plesk_virtual, delay=4.7, delays=0.01/0/0/4.7, dsn=2.0.0, status=s
ent (delivered via plesk_virtual service)
May 27 03:38:54 domain postfix/qmgr[3306]: 71C72D6C0074: removed
=================================================
This definately looks like the buggy behaviour for me. Since I can't submit a ticket, posting here.
I've had a complaint from a user (same messages is being delivered over and over again).
quick googling revealed similar problem for some german guy:
http://www.plesk-forum.de/viewtopic.php?f=15&t=1437
I've had similar log entries:
---------------------------
May 27 03:38:45 domain postfix/qmgr[3306]: F27E5D6C006C: from=<www-data@mail.bc.lt>, size=90965, nrcpt=1 (queue active)
May 27 03:38:45 domain postfix-local[9806]: postfix-local: from=www-data@mail.bc.lt, to=info@domain.lt, dirname=/var/qmail/mailnames
May 27 03:38:45 domain postfix-local[9806]: hook_dir = '/usr/local/psa/handlers/before-local'
May 27 03:38:45 domain postfix-local[9806]: recipient[3] = 'info@domain.lt'
May 27 03:38:45 domain postfix-local[9806]: handlers dir = '/usr/local/psa/handlers/before-local/recipient/info@domain.lt'
May 27 03:38:45 domain postfix-local[9806]: call_handlers: call executable = '/usr/local/psa/handlers/info/10-spam-cHYvzG/executable'
May 27 03:38:45 domain spamd[30226]: spamd: got connection over /tmp/spamd_full.sock
May 27 03:38:45 domain spamd[30226]: spamd: using default config for info@domain.lt: /var/qmail/mailnames/domain.lt/info/.spamassassin/user_prefs
May 27 03:38:45 domain spamd[30226]: spamd: processing message <30c00c4165c1dee7319db0385354b41c@mail.bc.lt> for info@domain.lt:110
May 27 03:38:49 domain spamd[30226]: spamd: clean message (1.3/7.0) for info@domain.lt:110 in 3.8 seconds, 89810 bytes.
May 27 03:38:49 domain spamd[30226]: spamd: result: . 1 - AWL,FR_ALMOST_VIAG2,HTML_MESSAGE,RDNS_NONE scantime=3.8,size=89810,user=info@domain.lt,uid=110
,required_score=7.0,rhost=localhost,raddr=127.0.0.1,rport=/tmp/spamd_full.sock,mid=<30c00c4165c1dee7319db0385354b41c@mail.bc.lt>,autolearn=no
May 27 03:38:49 domain postfix-local[9806]: handlers_stderr: PASS
May 27 03:38:49 domain postfix-local[9806]: call_handlers: PASS during call '/usr/local/psa/handlers/info/10-spam-cHYvzG/executable' handler
May 27 03:38:49 domain spamd[30571]: prefork: child states: I
May 27 03:38:49 domain postfix-local[9806]: files: write buf 0xbfae8548[4096] to fd (7) error - (32) Broken pipe
May 27 03:38:49 domain postfix-local[9806]: files: cannot write chuck from 5 to 7 - (32) Broken pipe
May 27 03:38:49 domain postfix-local[9806]: LOG Unable to forward message to: marius@domain.lt
May 27 03:38:49 domain postfix-local[9806]: Unable to send mail for: marius@domain.lt
May 27 00:38:49 domain postfix/pickup[24364]: 71C72D6C0074: uid=110 from=<www-data@mail.bc.lt>
May 27 03:38:49 domain postfix/cleanup[9900]: 71C72D6C0074: message-id=<30c00c4165c1dee7319db0385354b41c@mail.bc.lt>
May 27 03:38:49 domain postfix/pipe[9805]: F27E5D6C006C: to=<info@domain.lt>, relay=plesk_virtual, delay=32215, delays=32212/0.03/0/3.9, dsn=4.2.1, stat
us=deferred (Message can not be delivered at this time )
May 27 03:38:49 domain postfix/qmgr[3306]: 71C72D6C0074: from=<www-data@mail.bc.lt>, size=1694, nrcpt=1 (queue active)
May 27 03:38:49 domain postfix-local[9902]: postfix-local: from=www-data@mail.bc.lt, to=marius@domain.lt, dirname=/var/qmail/mailnames
May 27 03:38:49 domain postfix-local[9902]: hook_dir = '/usr/local/psa/handlers/before-local'
May 27 03:38:49 domain postfix-local[9902]: recipient[3] = 'marius@domain.lt'
May 27 03:38:49 domain postfix-local[9902]: handlers dir = '/usr/local/psa/handlers/before-local/recipient/marius@domain.lt'
May 27 03:38:49 domain postfix-local[9902]: call_handlers: call executable = '/usr/local/psa/handlers/info/10-spam-Lgp4nG/executable'
May 27 03:38:49 domain spamd[30226]: spamd: got connection over /tmp/spamd_full.sock
May 27 03:38:49 domain spamd[30226]: spamd: using default config for marius@domain.lt: /var/qmail/mailnames/domain.lt/marius/.spamassassin/user_prefs
May 27 03:38:49 domain spamd[30226]: spamd: processing message <30c00c4165c1dee7319db0385354b41c@mail.bc.lt> for marius@domain.lt:110
May 27 03:38:54 domain spamd[30226]: spamd: clean message (-1.2/7.0) for marius@domain.lt:110 in 4.6 seconds, 1753 bytes.
May 27 03:38:54 domain spamd[30226]: spamd: result: . -1 - AWL,BAYES_05,RDNS_NONE scantime=4.6,size=1753,user=marius@domain.lt,uid=110,required_score=7.
0,rhost=localhost,raddr=127.0.0.1,rport=/tmp/spamd_full.sock,mid=<30c00c4165c1dee7319db0385354b41c@mail.bc.lt>,bayes=0.024401,autolearn=no
May 27 03:38:54 domain postfix-local[9902]: handlers_stderr: PASS
May 27 03:38:54 domain postfix-local[9902]: call_handlers: PASS during call '/usr/local/psa/handlers/info/10-spam-Lgp4nG/executable' handler
May 27 03:38:54 domain spamd[30571]: prefork: child states: I
May 27 03:38:54 domain postfix/pipe[9805]: 71C72D6C0074: to=<marius@domain.lt>, relay=plesk_virtual, delay=4.7, delays=0.01/0/0/4.7, dsn=2.0.0, status=s
ent (delivered via plesk_virtual service)
May 27 03:38:54 domain postfix/qmgr[3306]: 71C72D6C0074: removed
=================================================
This definately looks like the buggy behaviour for me. Since I can't submit a ticket, posting here.