Topic: La mailbox si blocca

[filloweb]

Ciao a tutti.

Ho uno SME in cui alcune mailbox, a random, si bloccano e smettono di ricevere. E' sufficente dare i seguenti due comandi:

Code: [Select]

[root@mail ~]# signal-event post-upgrade ; signal-event reboot

per far ripartire tutto e ricevere le mail che sono rimaste in coda su qmail.

Rilevo le mail bloccate tramite il comando qmHandle ma non riesco a sbloccarle.

Credo che il problema sia dovuto ad una formattazione errata delle mail che inviano dal gestionale.

Per avere maggiori info ho impostato il loglevel a 9 come sotto:

Code: [Select]

[root@mail ~]# config show qpsmtpd
qpsmtpd=service
    Bcc=disabled
    BccMode=cc
    BccUser=maillog
    DNSBL=disabled
    LogLevel=9
    MaxScannerSize=25000000
    RBLList=bl.spamcop.net:combined.njabl.org:dnsbl.ahbl.org:dnsbl-1.uceprotect.net:dnsbl-2.uceprotect.net:list.dsbl.org:multihop.dsbl.org:psbl.surriel.com:zen.spamhaus.org
    RHSBL=disabled
    RequireResolvableFromHost=no
    SBLList=bogusmx.rfc-ignorant.org:multi.surbl.org:black.uribl.com:rhsbl.sorbs.net:bulk.rhs.mailpolice.com:fraud.rhs.mailpolice.com:porn.rhs.mailpolice.com:adult.rhs.mailpolice.com:ex.dnsbl.org
    TlsBeforeAuth=1
    access=public
    qplogsumm=disabled
    status=enabled
[root@mail ~]#

Avete altri suggerimenti da darmi per cercare di capire dove si incastrano le mail ??

Grazie.
filloweb

[Stefano]

primo: si incastrano in che senso? ricezione di email interne? esterne?
secondo: con qmHandle cosa vedi? che mail?
terzo: nel log di qmail cosa trovi?
quarto: sempre gli stessi account? gli stessi mittenti? messaggi strani? strani allegati?

[filloweb]

1) Le mail si bloccano nel senso che la coda di una data mailbx non riceve piu' alcuna mail, sia interna sia esterna.

2) con qmHandle vedo le mail in coda locali e remote, anche quelle bloccate.

3) ho aumentato il loglevel a 9 oggi pomeriggio e per il momento non ho ancora mail bloccate.

4) gli account sono sempre gli stessi 2 perche' sono gli unici che inoltrano, dal gestionale, mail a KPNQwest per la trasmissione di mail2sms agli agenti.

Grazie ancora.
filloweb

[Stefano]

1) Le mail si bloccano nel senso che la coda di una data mailbx non riceve piu' alcuna mail, sia interna sia esterna.

quindi mi stai dicendo che 2 utenti, casualmente, si "bloccano" e non inviano nè ricevono email? sia interne (i.e. da account dello stesso SME) che DA account esterni?

2) con qmHandle vedo le mail in coda locali e remote, anche quelle bloccate.

cosa vedi con qmHandle mi è noto, nel senso che so cosa visualizza tale programma ma:
- la coda remota di qmail è quella con le mail in uscita verso altri server
- se SME è v.8, occhio che qmHandle può bloccare qmail (ci sono dei post in merito nei forum e se non erro un bug in bugzilla)

3) ho aumentato il loglevel a 9 oggi pomeriggio e per il momento non ho ancora mail bloccate.

per le meail dall'esterno, verifica anche cosa dice qpsmtpd

4) gli account sono sempre gli stessi 2 perche' sono gli unici che inoltrano, dal gestionale, mail a KPNQwest per la trasmissione di mail2sms agli agenti.

definisci, chiarendo dove è il gestionale, come invia le email, se tali mail sono well-formed, se il dominio  è valido o tipo .local ecc.
perchè se si bloccando solo queste ho il sospetto che il problema possa essere altrove

[filloweb]

Code: [Select]

1) Le mail si bloccano nel senso che la coda di una data mailbx non riceve piu' alcuna mail, sia interna sia esterna.
quindi mi stai dicendo che 2 utenti, casualmente, si "bloccano" e non inviano nè ricevono email? sia interne (i.e. da account dello stesso SME) che DA account esterni?
Esatto. Smettono COMPLETAMENTE di ricevere ed inviare.

Code: [Select]

2) con qmHandle vedo le mail in coda locali e remote, anche quelle bloccate.
cosa vedi con qmHandle mi è noto, nel senso che so cosa visualizza tale programma ma:
- la coda remota di qmail è quella con le mail in uscita verso altri server
- se SME è v.8, occhio che qmHandle può bloccare qmail (ci sono dei post in merito nei forum e se non erro un bug in bugzilla)

Uso ancora SME 7.5.1

Code: [Select]

3) ho aumentato il loglevel a 9 oggi pomeriggio e per il momento non ho ancora mail bloccate.
per le meail dall'esterno, verifica anche cosa dice qpsmtpd
Utilizzo lo SME in DMZ ( 192.168.100.200 ) con LAN ( 129.168.10.xxx ) in modalita' "server-only" e dentro il log di QPSMTPD ho trovato degli errori proprio inerenti all'utente che si blocca appena prima del blocco:

Code: [Select]

2012-09-24 15:31:00.996644500 4079 221 modendiesel.it closing connection. Have a wonderful day.
2012-09-24 15:31:00.996706500 4079 click, disconnecting
2012-09-24 15:31:00.996886500 4079 running plugin (post-connection): tls
2012-09-24 15:31:00.997047500 4079 Plugin tls, hook post-connection returned DECLINED,
2012-09-24 15:31:01.145916500 4230 cleaning up after 4079
2012-09-24 15:31:22.583272500 4230 running plugin (pre-connection): hosts_allow
2012-09-24 15:31:22.583474500 4230 Plugin hosts_allow, hook pre-connection returned DECLINED,
2012-09-24 15:31:22.603543500 4194 Accepted connection 0/40 from 212.97.32.74 / smtp-out-06.comm2000.it
2012-09-24 15:31:22.603686500 4194 Connection from smtp-out-06.comm2000.it [212.97.32.74]
2012-09-24 15:31:22.604173500 4194 running plugin (set_hooks): peers
2012-09-24 15:31:22.605236500 4194 peers hooking valid_auth
2012-09-24 15:31:22.605315500 4194 peers hooking set_hooks
2012-09-24 15:31:22.605657500 4194 logging::logterse hooking queue
2012-09-24 15:31:22.605756500 4194 logging::logterse hooking deny
2012-09-24 15:31:22.606031500 4194 tls plugin (init): ciphers: ALL:!aNULL:!ADH:!eNULL:!LOW:!EXP:RC4+RSA:+HIGH:+MEDIUM
2012-09-24 15:31:22.606890500 4194 tls hooking connect
2012-09-24 15:31:22.606944500 4194 tls hooking ehlo
2012-09-24 15:31:22.607016500 4194 tls hooking helo
2012-09-24 15:31:22.607096500 4194 tls hooking rcpt
2012-09-24 15:31:22.607149500 4194 tls hooking mail
2012-09-24 15:31:22.607200500 4194 tls hooking data
2012-09-24 15:31:22.607299500 4194 tls hooking post-connection
2012-09-24 15:31:22.607343500 4194 tls hooking unrecognized_command
2012-09-24 15:31:22.608045500 4194 check_earlytalker hooking connect
2012-09-24 15:31:22.608101500 4194 check_earlytalker hooking data
2012-09-24 15:31:22.608273500 4194 count_unrecognized_commands hooking connect
2012-09-24 15:31:22.608453500 4194 count_unrecognized_commands hooking unrecognized_command
2012-09-24 15:31:22.608666500 4194 check_relay hooking connect
2012-09-24 15:31:22.608975500 4194 check_norelay hooking connect
2012-09-24 15:31:22.609326500 4194 require_resolvable_fromhost hooking mail
2012-09-24 15:31:22.609752500 4194 check_basicheaders hooking data_post
2012-09-24 15:31:22.610063500 4194 check_badmailfrom hooking rcpt
2012-09-24 15:31:22.610123500 4194 check_badmailfrom hooking mail
2012-09-24 15:31:22.610410500 4194 check_badrcptto_patterns hooking rcpt
2012-09-24 15:31:22.610722500 4194 check_badrcptto hooking rcpt
2012-09-24 15:31:22.610978500 4194 check_spamhelo hooking ehlo
2012-09-24 15:31:22.611036500 4194 check_spamhelo hooking helo
2012-09-24 15:31:22.611450500 4194 check_goodrcptto hooking rcpt
2012-09-24 15:31:22.611710500 4194 rcpt_ok hooking rcpt
2012-09-24 15:31:22.612090500 4194 tnef2mime hooking data_post
2012-09-24 15:31:22.612348500 4194 spamassassin hooking data_post
2012-09-24 15:31:22.612708500 4194 virus::clamav hooking data_post
2012-09-24 15:31:22.613128500 4194 queue::qmail_2dqueue hooking queue
2012-09-24 15:31:22.613660500 4194 Plugin peers, hook set_hooks returned DECLINED,
2012-09-24 15:31:22.613764500 4194 running plugin (connect): tls
2012-09-24 15:31:22.613836500 4194 Plugin tls, hook connect returned DECLINED,
2012-09-24 15:31:22.613867500 4194 running plugin (connect): check_earlytalker
2012-09-24 15:31:23.614157500 4194 check_earlytalker plugin (connect): remote host said nothing spontaneous, proceeding
2012-09-24 15:31:23.614234500 4194 Plugin check_earlytalker, hook connect returned DECLINED,
2012-09-24 15:31:23.614270500 4194 running plugin (connect): count_unrecognized_commands
2012-09-24 15:31:23.614367500 4194 Plugin count_unrecognized_commands, hook connect returned DECLINED,
2012-09-24 15:31:23.614409500 4194 running plugin (connect): check_relay
2012-09-24 15:31:23.614523500 4194 trying to get config for relayclients
2012-09-24 15:31:23.615266500 4194 trying to get config for morerelayclients
2012-09-24 15:31:23.615505500 4194 Plugin check_relay, hook connect returned DECLINED,
2012-09-24 15:31:23.615538500 4194 running plugin (connect): check_norelay
2012-09-24 15:31:23.615600500 4194 trying to get config for norelayclients
2012-09-24 15:31:23.615846500 4194 Plugin check_norelay, hook connect returned DECLINED,
2012-09-24 15:31:23.615916500 4194 trying to get config for smtpgreeting
2012-09-24 15:31:23.616156500 4194 220 mail.modendiesel.it ESMTP
2012-09-24 15:31:23.616261500 4194 trying to get config for timeoutsmtpd
2012-09-24 15:31:23.658932500 4194 dispatching EHLO smtp-out-06.comm2000.it
2012-09-24 15:31:23.659275500 4194 running plugin (ehlo): tls
2012-09-24 15:31:23.659462500 4194 Plugin tls, hook ehlo returned DECLINED,
2012-09-24 15:31:23.659496500 4194 running plugin (ehlo): check_spamhelo
2012-09-24 15:31:23.659549500 4194 trying to get config for badhelo
2012-09-24 15:31:23.659804500 4194 Plugin check_spamhelo, hook ehlo returned DECLINED,
2012-09-24 15:31:23.659967500 4194 trying to get config for tls_before_auth
2012-09-24 15:31:23.660201500 4194 trying to get config for me
2012-09-24 15:31:23.660352500 4194 trying to get config for databytes
2012-09-24 15:31:23.660522500 4194 250-modendiesel.it Hi smtp-out-06.comm2000.it [212.97.32.74]
2012-09-24 15:31:23.660540500 4194 250-PIPELINING
2012-09-24 15:31:23.660565500 4194 250-8BITMIME
2012-09-24 15:31:23.660594500 4194 250-SIZE 50000000
2012-09-24 15:31:23.660623500 4194 250 STARTTLS
2012-09-24 15:31:23.705144500 4194 dispatching MAIL FROM:<mittente=dominiomittente.it@mug.bounce.ngl.kqumg.it> SIZE=1211
2012-09-24 15:31:23.705394500 4194 full from_parameter: FROM:<mittente=dominiomittente.it@mug.bounce.ngl.kqumg.it> SIZE=1211
2012-09-24 15:31:23.705613500 4194 from email address : [<mittente=dominiomittente.it@mug.bounce.ngl.kqumg.it>]
2012-09-24 15:31:23.706078500 4194 running plugin (mail): tls
2012-09-24 15:31:23.706153500 4194 Plugin tls, hook mail returned DECLINED,
2012-09-24 15:31:23.706185500 4194 running plugin (mail): require_resolvable_fromhost
2012-09-24 15:31:23.706274500 4194 trying to get config for invalid_resolvable_fromhost
2012-09-24 15:31:23.706971500 4194 trying to get config for require_resolvable_fromhost
2012-09-24 15:31:23.707192500 4194 Plugin require_resolvable_fromhost, hook mail returned DECLINED,
2012-09-24 15:31:23.707227500 4194 running plugin (mail): check_badmailfrom
2012-09-24 15:31:23.707304500 4194 trying to get config for badmailfrom
2012-09-24 15:31:23.707513500 4194 Plugin check_badmailfrom, hook mail returned DECLINED,
2012-09-24 15:31:23.707625500 4194 getting mail from <mittente=dominiomittente.it@mug.bounce.ngl.kqumg.it>
2012-09-24 15:31:23.707687500 4194 250 <mittente=dominiomittente.it@mug.bounce.ngl.kqumg.it>, sender OK - how exciting to get mail from you!
2012-09-24 15:31:23.707793500 4194 dispatching RCPT TO:<mittente@dominiomittente.it>
2012-09-24 15:31:23.708044500 4194 to email address : [<mittente@dominiomittente.it>]
2012-09-24 15:31:23.708160500 4194 running plugin (rcpt): tls
2012-09-24 15:31:23.708226500 4194 Plugin tls, hook rcpt returned DECLINED,
2012-09-24 15:31:23.708257500 4194 running plugin (rcpt): check_badmailfrom
2012-09-24 15:31:23.708331500 4194 Plugin check_badmailfrom, hook rcpt returned DECLINED,
2012-09-24 15:31:23.708368500 4194 running plugin (rcpt): check_badrcptto_patterns
2012-09-24 15:31:23.708418500 4194 trying to get config for badrcptto_patterns
2012-09-24 15:31:23.708744500 4194 Plugin check_badrcptto_patterns, hook rcpt returned DECLINED,
2012-09-24 15:31:23.708779500 4194 running plugin (rcpt): check_badrcptto
2012-09-24 15:31:23.708835500 4194 trying to get config for badrcptto
2012-09-24 15:31:23.709207500 4194 Plugin check_badrcptto, hook rcpt returned DECLINED,
2012-09-24 15:31:23.709241500 4194 running plugin (rcpt): check_goodrcptto
2012-09-24 15:31:23.709322500 4194 check_goodrcptto plugin (rcpt): stripping '-' extensions
2012-09-24 15:31:23.709368500 4194 trying to get config for goodrcptto
2012-09-24 15:31:23.710154500 4194 check_goodrcptto plugin (rcpt): address includes extn '-', checking users: andrea.borghi
2012-09-24 15:31:23.710260500 4194 Plugin check_goodrcptto, hook rcpt returned DECLINED,
2012-09-24 15:31:23.710293500 4194 running plugin (rcpt): rcpt_ok
2012-09-24 15:31:23.710364500 4194 trying to get config for rcpthosts
2012-09-24 15:31:23.710632500 4194 Plugin rcpt_ok, hook rcpt returned OK,
2012-09-24 15:31:23.710726500 4194 250 <mittente@dominiomittente.it>, recipient ok
2012-09-24 15:31:23.710819500 4194 dispatching DATA
2012-09-24 15:31:23.710905500 4194 running plugin (data): tls
2012-09-24 15:31:23.710993500 4194 Plugin tls, hook data returned DECLINED,
2012-09-24 15:31:23.711026500 4194 running plugin (data): check_earlytalker
2012-09-24 15:31:23.711147500 4194 Plugin check_earlytalker, hook data returned DECLINED,
2012-09-24 15:31:23.711334500 4194 354 go ahead
2012-09-24 15:31:23.711381500 4194 max_size: 50000000 / size: 0
2012-09-24 15:31:23.711535500 4194 trying to get config for timeout
2012-09-24 15:31:23.833998500 4194 spooling message to disk
2012-09-24 15:31:23.837013500 4194 max_size: 50000000 / size: 1171
2012-09-24 15:31:23.837429500 4194 running plugin (data_post): check_basicheaders
2012-09-24 15:31:23.837615500 4194 Plugin check_basicheaders, hook data_post returned DECLINED,
2012-09-24 15:31:23.837649500 4194 running plugin (data_post): tnef2mime
2012-09-24 15:31:23.845343500 4194 Plugin tnef2mime, hook data_post returned DECLINED,
2012-09-24 15:31:23.845367500 4194 running plugin (data_post): spamassassin
2012-09-24 15:31:23.845443500 4194 spamassassin plugin (data_post): check_spam
2012-09-24 15:31:23.846007500 4194 spamassassin plugin (data_post): check_spam: connected to spamd
2012-09-24 15:31:23.846875500 4194 spamassassin plugin (data_post): check_spam: finished sending to spamd
2012-09-24 15:31:32.195092500 4194 spamassassin plugin (data_post): check_spam: spamd: SPAMD/1.1 0 EX_OK
2012-09-24 15:31:32.195096500 4194 spamassassin plugin (data_post): check_spam: spamd: Content-length: 18
2012-09-24 15:31:32.195098500 4194 spamassassin plugin (data_post): check_spam: spamd: Spam: False ; 0.0 / 5.0
2012-09-24 15:31:32.195100500 4194 spamassassin plugin (data_post): check_spam: spamd:
2012-09-24 15:31:32.197585500 4194 spamassassin plugin (data_post): check_spam: finished reading from spamd
2012-09-24 15:31:32.197588500 4194 spamassassin plugin (data_post): check_spam: No, hits=0.0, required=5.0, tests=RCVD_IN_DNSWL_NONE
2012-09-24 15:31:32.197590500 4194 Plugin spamassassin, hook data_post returned DECLINED,
2012-09-24 15:31:32.197592500 4194 running plugin (data_post): virus::clamav
2012-09-24 15:31:32.197594500 4194 virus::clamav plugin (data_post): Changing permissions on file to permit scanner access
2012-09-24 15:31:32.197596500 4194 virus::clamav plugin (data_post): Running: /usr/bin/clamdscan --stdout  --config-file=/etc/clamd.conf --no-summary /var/spool/qpsmtpd/1348493483:4194:0 2>&1
2012-09-24 15:31:32.214552500 4194 virus::clamav plugin (data_post): clamscan results: /var/spool/qpsmtpd/1348493483:4194:0: OK
2012-09-24 15:31:32.214555500 4194 Plugin virus::clamav, hook data_post returned DECLINED,
2012-09-24 15:31:32.214558500 4194 running plugin (queue): logging::logterse
2012-09-24 15:31:32.214560500 4194 logging::logterse plugin (queue): ` 212.97.32.74 smtp-out-06.comm2000.it smtp-out-06.comm2000.it <mittente=dominiomittente.it@mug.bounce.ngl.kqumg.it> <mittente@dominiomittente.it> queued <20120924133121.B31381D85A8@smtp-out-06.comm2000.it> No, hits=0.0 required=5.0_
2012-09-24 15:31:32.214584500 4194 Plugin logging::logterse, hook queue returned DECLINED,
2012-09-24 15:31:32.214587500 4194 running plugin (queue): queue::qmail_2dqueue
2012-09-24 15:31:32.214589500 4204 queue::qmail_2dqueue plugin (queue): (for 4194 ) Queuing qp 4204 to /var/qmail/bin/qmail-queue
2012-09-24 15:31:32.222350500 4194 Plugin queue::qmail_2dqueue, hook queue returned OK, Queued! 1348493492 qp 4204 <20120924133121.B31381D85A8@smtp-out-06.comm2000.it>
2012-09-24 15:31:32.222651500 4194 250 Queued! 1348493492 qp 4204 <20120924133121.B31381D85A8@smtp-out-06.comm2000.it>
2012-09-24 15:31:32.223228500 4194 dispatching QUIT
Considera che il destinatario della mail e' un cellulare ( servizio mail2sms di KPNQWest ) nel formato numerodicellulare@i80.c0-mx1.kqumg.it

Code: [Select]

4) gli account sono sempre gli stessi 2 perche' sono gli unici che inoltrano, dal gestionale, mail a KPNQwest per la trasmissione di mail2sms agli agenti.
definisci, chiarendo dove è il gestionale, come invia le email, se tali mail sono well-formed, se il dominio  è valido o tipo .local ecc.
perchè se si bloccando solo queste ho il sospetto che il problema possa essere altrove
Il gestionale e' sulla LAN. Purtroppo non saprei come controllare se sono well-formed. Il gestionale, infatti, utilizza un motore in Java tramite le librerie di Java stesso per inoltrare tramite protocollo SMTP la mail allo SME.
Il dominio dei PC in LAN di ActiveDirectory e' "ditta.local".

Qualche idea?
Non sarebbe sufficente rimouvere il controllo "invalid_resolvable_fromhost"?

Grazie.
filloweb

[Stefano]

Esatto. Smettono COMPLETAMENTE di ricevere ed inviare.

mmmhhh.. non ha molto senso, in quanto le mail che entrano negli account:
- se sono interne (tra 2 account residenti sullo stesso server) viaggiano direttamente su qmail
- se vengono dall'esterno le processa qpsmtpd e poi le passa a qmail
quindi parrebbe qmail andare in confusione, ma le altre mail non hanno problemi, pare

Utilizzo lo SME in DMZ ( 192.168.100.200 ) con LAN ( 129.168.10.xxx ) in modalita' "server-only" e dentro il log di QPSMTPD ho trovato degli errori proprio inerenti all'utente che si blocca appena prima del blocco:

Code: [Select]

2012-09-24 15:31:32.222651500 4194 250 Queued! 1348493492 qp 4204 <20120924133121.B31381D85A8@smtp-out-06.comm2000.it>
2012-09-24 15:31:32.223228500 4194 dispatching QUIT

non ho visto errori, il messaggio è queued.. dove hai visto errori? nei vari DECLINED? è normale che sia così

Considera che il destinatario della mail e' un cellulare ( servizio mail2sms di KPNQWest ) nel formato numerodicellulare@i80.c0-mx1.kqumg.it

non vedo cosa cambi.. inoltre mi dici che l'account sul server non riceve nè invia, da nessun indirizzo.. quindi questa informazione è inutile

Il gestionale e' sulla LAN. Purtroppo non saprei come controllare se sono well-formed. Il gestionale, infatti, utilizza un motore in Java tramite le librerie di Java stesso per inoltrare tramite protocollo SMTP la mail allo SME.
Il dominio dei PC in LAN di ActiveDirectory e' "ditta.local".

e SME è su ditta.local?
manda direttamente a SME? nel senso che l'applicativo ha un server smtp? o è configurabile per usare un servizio smtp? in tal caso usa un account apposito su SME con user e password

Qualche idea?

difficile.. anche perchè il problema è assolutamente strano: l'invio/ricezione delle email sono 2 processi divisi, succede solo per 2 account e per invio/ricezione da account esterni ed interni.. pare strano..

Non sarebbe sufficente rimouvere il controllo "invalid_resolvable_fromhost"?

no.. perchè prima di tutto devi capire cosa succede..
SME è su ditta.local? se no, aggiungi quel dominio a quelli gestiti

resto comunque convinto che non abbiamo tutte le info per capire, ti chiedo quindi di:
- rimettere il log della posta al valore di default (6)
- testare, nel momento in cui uno dei due account appare "bloccato", l'invio di email verso tale account da account esterni (i.e. libero ecc) e da uno sullo stesso server
- testare, nel momento in cui uno dei due account appare "bloccato", l'invio di email da tale account verso indirizzi esterni e residenti sullo stesso server

quando hai i risultati, riportaceli, grazie

[andreasantini]

Ciao,
a me capita a volte questo problema,perchè è il servizio fetchmail che si blocca.
Lo risolvo killando fetchmail, senza riavviare il server.

pidof fetchmail
kill .......

Ciao.