Topic: "Recipient unknown" su nuovi utenti

[gbartolini]

Ciao a tutti,

un saluto a tutto il forum, questo e' il mio primo post.

Nel mio ufficio gestisco uno SME 7.5.1 usato solo come mail server per una decina di utenti. Questa macchina e' stata aggiornata nel tempo partendo da una 7.1.
Non ho avuto mai alcun problema, e ultimamente su richiesta degli utenti ho aggiunto i moduli "User Vacations" e "User Panel Access". In precedenza avevo aggiunto "Recupero email" e "Email WBL".

Il server gestisce un singolo dominio di posta "miodominio.it" ed il suo hostname e' "srv04.miodominio.it"

Ha funzionato tutto bene fino all'altro giorno, quando ho avuto la necessita' di aggiungere un nuovo utente "nuovoutente@miodominio.it"

Quando provo a spedire posta al nuovo utente, il server restituisce il seguente messaggio di errore:

Hi. This is the qmail-send program at miodominio.it.
I'm afraid I wasn't able to deliver your message to the following addresses.
This is a permanent error; I've given up. Sorry it didn't work out.

<nuovoutente@srv04.miodominio.it>:
Recipient unknown

Ma se vado ad analizzare il file di log /var/log/qpsmtpd/current ottengo:

2011-09-04 11:32:24.127791500 525 dispatching RCPT TO:<nuovoutente@miodominio.it>
2011-09-04 11:32:24.394625500 525 check_goodrcptto plugin (rcpt): stripping '-' extensions
2011-09-04 11:32:24.394630500 525 250 <nuovoutente@miodominio.it>, recipient ok

Mentre /var/log/qmail/current scrive:

2011-09-04 11:32:25.746638500 new msg 6848513
2011-09-04 11:32:25.746644500 info msg 6848513: bytes 1906 from <mittente@altrodominio.it> qp 543 uid 453
2011-09-04 11:32:25.752457500 starting delivery 83: msg 6848513 to local alias-localdelivery-nuovoutente@miodominio.it
2011-09-04 11:32:25.752462500 status: local 1/10 remote 0/20
2011-09-04 11:32:25.798049500 new msg 66790
2011-09-04 11:32:25.798054500 info msg 66790: bytes 2029 from <mittente@altrodominio.it> qp 546 uid 400
2011-09-04 11:32:25.830009500 starting delivery 84: msg 66790 to local nuovoutente@srv04.miodominio.it
2011-09-04 11:32:25.830013500 status: local 2/10 remote 0/20
2011-09-04 11:32:25.830017500 delivery 83: success: forward:_qp_546/did_0+0+1/
2011-09-04 11:32:25.830019500 status: local 1/10 remote 0/20
2011-09-04 11:32:25.830022500 end msg 6848513
2011-09-04 11:32:25.851645500 delivery 84: failure: Recipient_unknown/
2011-09-04 11:32:25.853041500 status: local 0/10 remote 0/20
2011-09-04 11:32:25.858044500 bounce msg 66790 qp 550
2011-09-04 11:32:25.857450500 end msg 66790

Ho evidenziato in rosso la riga dove appare il dominio errato. Sembra che ci sia del casino nella gestione degli pseudonimi, infatti altra cosa anomala e forse correlata e' che non riesco piu' a cancellare quelli che vengono creati in automatico con il nuovo utente: nome.cognome e nome_cognome. Se ci provo ottengo l'errore "Error occurred while removing pseudonym."

Il bello e' che la posta destinata agli utenti gia' presenti viene ricevuta correttamente.
Non capisco perche' il dominio di posta dell'utente venga ora modificato con il fqdn del server, e solo per i nuovi utenti.

C'e' da dire che e' molto tempo che non creo nuovi utenti, quindi non so dire se il problema e' nato da qualche aggiornamento a release intermedie o da qualche modulo aggiuntivo installato ultimamente.

Qualcuno ha qualche suggerimento? Inutile dire che in questo momento i nuovi utenti non riescono a ricevere la posta e mi stanno mettendo in croce

Grazie mille
Gianfranco

[Stefano]

ciao

prima di tutto apri un terminale, entra come root, e dai

Code: [Select]

tail -f /var/log/messages

poi prova a cancellare uno pseudonimo e riporta qui il messaggio di errore

per la questione invio/ricezione.. il problema è solo da esterno verso interno o anche tra mittente/destinatario interno?

in ogni caso, prova a disabilitare da server-manager -> email l'impostazione in merito agli utenti sconosciuti.. prova a guardare anche in questo caso /var/log/messages per eventuali errori.. disattivalo, verifica, riattivalo e riverifica..

la macchina è aggiornata? anche per i contribs, intendo..

[gbartolini]

Ciao, intanto grazie per l'aiuto

ecco il risultato quando cerco di cancellare uno pseudonimo (nel dettaglio cerco di cancellare lo pseudonimo nome.cognome dell'utente "utente"

Sep  5 12:22:20 srv04 /etc/e-smith/web/panels/manager/cgi-bin/pseudonyms[9484]: /home/e-smith/db/accounts: OLD utente=pseudonym|Account|utente.miodominio.it
Sep  5 12:22:20 srv04 /etc/e-smith/web/panels/manager/cgi-bin/pseudonyms[9484]: /home/e-smith/db/accounts: NEW utente=pseudonym-deleted|Account|utente.miodominio.it
Sep  5 12:22:20 srv04 esmith::event[9485]: Processing event: pseudonym-delete utente
Sep  5 12:22:20 srv04 esmith::event[9485]: Running event handler: /etc/e-smith/events/actions/generic_template_expand
Sep  5 12:22:20 srv04 esmith::event[9485]: expanding /var/qmail/users/assign
Sep  5 12:22:20 srv04 esmith::event[9485]: expanding /var/qmail/control/virtualdomains
Sep  5 12:22:20 srv04 esmith::event[9485]: expanding /var/qmail/control/badrcptto
Sep  5 12:22:20 srv04 esmith::event[9485]: expanding /var/service/qpsmtpd/config/goodrcptto
Sep  5 12:22:20 srv04 esmith::event[9485]: generic_template_expand=action|Event|pseudonym-delete|Action|generic_template_expand|Start|1315218140 335879|End|1315218140 903811|Elapsed|0.567932
Sep  5 12:22:20 srv04 esmith::event[9485]: Running event handler: /etc/e-smith/events/pseudonym-delete/S55email-assign
Sep  5 12:22:21 srv04 esmith::event[9485]: /etc/e-smith/events/pseudonym-delete/S55email-assign: /etc/e-smith/events/pseudonym-delete/S55email-assign: cannot execute binary file
Sep  5 12:22:21 srv04 esmith::event[9485]: S55email-assign=action|Event|pseudonym-delete|Action|S55email-assign|Start|1315218140 904129|End|1315218141 15907|Elapsed|0.111778|Status|32256
Sep  5 12:22:21 srv04 esmith::event[9485]: Running event handler: /etc/e-smith/events/actions/adjust-services
Sep  5 12:22:21 srv04 esmith::event[9485]: adjusting supervised qmail (sighup)
Sep  5 12:22:21 srv04 esmith::event[9485]: adjusting supervised qmail (up)
Sep  5 12:22:21 srv04 esmith::event[9485]: adjust-services=action|Event|pseudonym-delete|Action|adjust-services|Start|1315218141 15527|End|1315218141 166023|Elapsed|0.150496
Sep  5 12:22:21 srv04 /etc/e-smith/web/panels/manager/cgi-bin/pseudonyms[9484]: /home/e-smith/db/accounts: DELETE utente=pseudonym-deleted|Account|utente.miodominio.it

sembra ci sia un problema come evidenziato nella riga in risso, infatti nella webgui mi scrive "Error occurred while removing pseudonym." e se faccio un refresh lo pseudonimo e' ancora presente.

Inoltre sembra che l'errore si presenti anche se cerco di creare un nuovo utente, questo e' l'estratto del log dove cerco di creare gianfranco@miodominio.it:

Sep  5 12:45:03 srv04 /etc/e-smith/web/panels/manager/cgi-bin/useraccounts[10066]: /home/e-smith/db/accounts: OLD gianfranco.miodominio.it=(undefined)
Sep  5 12:45:03 srv04 /etc/e-smith/web/panels/manager/cgi-bin/useraccounts[10066]: /home/e-smith/db/accounts: NEW gianfranco.miodominio.it=
Sep  5 12:45:03 srv04 /etc/e-smith/web/panels/manager/cgi-bin/useraccounts[10066]: /home/e-smith/db/accounts: OLD gianfranco.miodominio.it=
Sep  5 12:45:03 srv04 /etc/e-smith/web/panels/manager/cgi-bin/useraccounts[10066]: /home/e-smith/db/accounts: NEW gianfranco.miodominio.it=user|City|Rimini|Company|Studio|Dept|Studio|EmailForward|local|FirstName|Gianfranco|ForwardAddress||FreeBusy||LastName|Bartolini|PasswordSet|no|Phone|0000000|Street|indirizzo|VPNClientAccess|no
Sep  5 12:45:03 srv04 /etc/e-smith/web/panels/manager/cgi-bin/useraccounts[10066]: /home/e-smith/db/accounts: OLD gianfranco.bartolini=(undefined)
Sep  5 12:45:03 srv04 /etc/e-smith/web/panels/manager/cgi-bin/useraccounts[10066]: /home/e-smith/db/accounts: NEW gianfranco.bartolini=pseudonym|Account|gianfranco.miodominio.it
Sep  5 12:45:03 srv04 /etc/e-smith/web/panels/manager/cgi-bin/useraccounts[10066]: /home/e-smith/db/accounts: OLD gianfranco_bartolini=(undefined)
Sep  5 12:45:03 srv04 /etc/e-smith/web/panels/manager/cgi-bin/useraccounts[10066]: /home/e-smith/db/accounts: NEW gianfranco_bartolini=pseudonym|Account|gianfranco.miodominio.it
Sep  5 12:45:03 srv04 esmith::event[10067]: Processing event: user-create gianfranco.miodominio.it
Sep  5 12:45:03 srv04 esmith::event[10067]: Running event handler: /etc/e-smith/events/user-create/S04user-create-unix
Sep  5 12:45:03 srv04 /etc/e-smith/events/user-create/S04user-create-unix[10068]: /home/e-smith/db/configuration: OLD MinUid=5016
Sep  5 12:45:03 srv04 /etc/e-smith/events/user-create/S04user-create-unix[10068]: /home/e-smith/db/configuration: NEW MinUid=5017
Sep  5 12:45:03 srv04 /etc/e-smith/events/user-create/S04user-create-unix[10068]: /home/e-smith/db/accounts: OLD gianfranco.miodominio.it=user|City|Rimini|Company|Studio|Dept|Studio|EmailForward|local|FirstName|Gianfranco|ForwardAddress||FreeBusy||LastName|Bartolini|PasswordSet|no|Phone|0000000|Street|indirizzo|VPNClientAccess|no
Sep  5 12:45:03 srv04 /etc/e-smith/events/user-create/S04user-create-unix[10068]: /home/e-smith/db/accounts: NEW gianfranco.miodominio.it=user|City|Rimini|Company|Studio|Dept|Studio|EmailForward|local|FirstName|Gianfranco|ForwardAddress||FreeBusy||LastName|Bartolini|PasswordSet|no|Phone|00000000|Street|indirizzo|Uid|5016|VPNClientAccess|no
Sep  5 12:45:04 srv04 esmith::event[10067]: Locking password for user gianfranco.miodominio.it.
Sep  5 12:45:04 srv04 esmith::event[10067]: passwd: Success
Sep  5 12:45:05 srv04 esmith::event[10067]: Added user gianfranco.miodominio.it.
Sep  5 12:45:05 srv04 esmith::event[10067]: S04user-create-unix=action|Event|user-create|Action|S04user-create-unix|Start|1315219503 630515|End|1315219505 196132|Elapsed|1.565617
Sep  5 12:45:05 srv04 esmith::event[10067]: Running event handler: /etc/e-smith/events/actions/generic_template_expand
Sep  5 12:45:05 srv04 esmith::event[10067]: expanding /etc/pptpd.conf
Sep  5 12:45:05 srv04 esmith::event[10067]: expanding /etc/dhcpd.conf
Sep  5 12:45:05 srv04 esmith::event[10067]: expanding /etc/rssh.conf
Sep  5 12:45:05 srv04 esmith::event[10067]: expanding /etc/raddb/radiusd.conf
Sep  5 12:45:05 srv04 esmith::event[10067]: expanding /etc/raddb/users
Sep  5 12:45:05 srv04 esmith::event[10067]: expanding /etc/ppp/ip-up.local
Sep  5 12:45:05 srv04 esmith::event[10067]: expanding /etc/ppp/options.pptpd
Sep  5 12:45:05 srv04 esmith::event[10067]: expanding /etc/ppp/ip-down.local
Sep  5 12:45:05 srv04 esmith::event[10067]: expanding /etc/e-smith/pam/accounts.allow
Sep  5 12:45:06 srv04 esmith::event[10067]: expanding /etc/e-smith/pam/users.allow
Sep  5 12:45:06 srv04 esmith::event[10067]: expanding /etc/e-smith/pam/accounts.deny
Sep  5 12:45:06 srv04 esmith::event[10067]: expanding /var/qmail/alias/.qmail-shared
Sep  5 12:45:06 srv04 esmith::event[10067]: expanding /var/qmail/users/assign
Sep  5 12:45:06 srv04 esmith::event[10067]: expanding /var/qmail/control/virtualdomains
Sep  5 12:45:06 srv04 esmith::event[10067]: expanding /var/qmail/control/badrcptto
Sep  5 12:45:06 srv04 esmith::event[10067]: expanding /var/service/qpsmtpd/config/goodrcptto
Sep  5 12:45:06 srv04 esmith::event[10067]: generic_template_expand=action|Event|user-create|Action|generic_template_expand|Start|1315219505 196400|End|1315219506 457869|Elapsed|1.261469
Sep  5 12:45:06 srv04 esmith::event[10067]: Running event handler: /etc/e-smith/events/user-create/S20qmail-update-group
Sep  5 12:45:06 srv04 esmith::event[10067]: S20qmail-update-group=action|Event|user-create|Action|S20qmail-update-group|Start|1315219506 458157|End|1315219506 602274|Elapsed|0.144117
Sep  5 12:45:06 srv04 esmith::event[10067]: Running event handler: /etc/e-smith/events/user-create/S20qmail-update-user
Sep  5 12:45:06 srv04 esmith::event[10067]: S20qmail-update-user=action|Event|user-create|Action|S20qmail-update-user|Start|1315219506 603757|End|1315219506 859532|Elapsed|0.255775
Sep  5 12:45:06 srv04 esmith::event[10067]: Running event handler: /etc/e-smith/events/user-create/S20user-create-profiledir
Sep  5 12:45:06 srv04 esmith::event[10067]: S20user-create-profiledir=action|Event|user-create|Action|S20user-create-profiledir|Start|1315219506 859214|End|1315219506 994829|Elapsed|0.135615
Sep  5 12:45:06 srv04 esmith::event[10067]: Running event handler: /etc/e-smith/events/user-create/S25count-active-user-accounts
Sep  5 12:45:07 srv04 esmith::event[10067]: S25count-active-user-accounts=action|Event|user-create|Action|S25count-active-user-accounts|Start|1315219506 996308|End|1315219507 195760|Elapsed|0.199452
Sep  5 12:45:07 srv04 esmith::event[10067]: Running event handler: /etc/e-smith/events/user-create/S25ldap-update
Sep  5 12:45:07 srv04 slapd[3816]: conn=27 fd=7 ACCEPT from IP=127.0.0.1:33973 (IP=0.0.0.0:389)
Sep  5 12:45:07 srv04 slapd[3816]: conn=27 op=0 BIND dn="cn=root,dc=miodominio,dc=it" method=128
Sep  5 12:45:07 srv04 slapd[3816]: conn=27 op=0 BIND dn="cn=root,dc=miodominio,dc=it" mech=SIMPLE ssf=0
Sep  5 12:45:07 srv04 slapd[3816]: conn=27 op=0 RESULT tag=97 err=0 text=
Sep  5 12:45:08 srv04 slapd[3816]: conn=27 op=1 ADD dn="uid=gianfranco.miodominio.it,dc=miodominio,dc=it"
Sep  5 12:45:08 srv04 slapd[3816]: conn=27 op=1 RESULT tag=105 err=0 text=
Sep  5 12:45:08 srv04 esmith::event[10067]: S25ldap-update=action|Event|user-create|Action|S25ldap-update|Start|1315219507 196210|End|1315219508 162314|Elapsed|0.966104
Sep  5 12:45:08 srv04 slapd[3816]: conn=27 op=2 UNBIND
Sep  5 12:45:08 srv04 slapd[3816]: conn=27 fd=7 closed
Sep  5 12:45:08 srv04 esmith::event[10067]: Running event handler: /etc/e-smith/events/user-create/S25user-update-fetchmail
Sep  5 12:45:08 srv04 esmith::event[10067]: S25user-update-fetchmail=action|Event|user-create|Action|S25user-update-fetchmail|Start|1315219508 162176|End|1315219508 382077|Elapsed|0.219901
Sep  5 12:45:08 srv04 esmith::event[10067]: Running event handler: /etc/e-smith/events/user-create/S50user-modify-quota
Sep  5 12:45:08 srv04 esmith::event[10067]: S50user-modify-quota=action|Event|user-create|Action|S50user-modify-quota|Start|1315219508 382567|End|1315219508 517306|Elapsed|0.134739
Sep  5 12:45:08 srv04 esmith::event[10067]: Running event handler: /etc/e-smith/events/user-create/S55email-assign
Sep  5 12:45:08 srv04 esmith::event[10067]: /etc/e-smith/events/user-create/S55email-assign: /etc/e-smith/events/user-create/S55email-assign: cannot execute binary file
Sep  5 12:45:08 srv04 esmith::event[10067]: S55email-assign=action|Event|user-create|Action|S55email-assign|Start|1315219508 517072|End|1315219508 550402|Elapsed|0.03333|Status|32256
Sep  5 12:45:08 srv04 esmith::event[10067]: Running event handler: /etc/e-smith/events/user-create/S85user-group-modify
Sep  5 12:45:08 srv04 esmith::event[10067]: S85user-group-modify=action|Event|user-create|Action|S85user-group-modify|Start|1315219508 549905|End|1315219508 710055|Elapsed|0.16015
Sep  5 12:45:08 srv04 esmith::event[10067]: Running event handler: /etc/e-smith/events/actions/adjust-services
Sep  5 12:45:08 srv04 esmith::event[10067]: adjusting supervised radiusd (sigterm)
Sep  5 12:45:08 srv04 esmith::event[10067]: adjusting supervised radiusd (up)
Sep  5 12:45:08 srv04 esmith::event[10067]: adjusting supervised qmail (sighup)
Sep  5 12:45:08 srv04 esmith::event[10067]: adjusting supervised qmail (up)
Sep  5 12:45:08 srv04 esmith::event[10067]: adjusting supervised smbd (sighup)
Sep  5 12:45:08 srv04 esmith::event[10067]: adjusting supervised smbd (up)
Sep  5 12:45:08 srv04 esmith::event[10067]: adjust-services=action|Event|user-create|Action|adjust-services|Start|1315219508 710534|End|1315219508 850120|Elapsed|0.139586

Sembra che l'errore sia nell'esecuzione dello script /etc/e-smith/events/user-create/S55email-assign, che ho evidenziato in rosso. Alla fine dell'operazione nella webgui appare "Errore durante la creazione dell'utente" pero' poi il nuovo utente viene creato.

Per la questione invio/ricezione il problema si presenta sempre, sia ricevendo da email esterne che interne allo stesso dominio. Di contro, la posta in uscita funziona correttamente, sia dai vecchi che dai nuovi indirizzi email.

In merito agli utenti sconosciuti, se imposto "rifiuta" il server rimanda indietro al mittente il messaggio con l'errore "user unknown" come ho scritto inizialmente. Se invece imposto "invia a" il messaggio viene consegnato ovviamente non al destinatario originale ma all'utente di cui ho impostato l'inoltro, e questo per ora e' l'unico modo che ho trovato per tamponare il problema (i messaggi almeno arrivano anche se ad un altro utente)

Il server l'ho sempre tenuto aggiornato dal pannello "installazione software" ma non saprei invece come aggiornare anche i contribs, occorre uno yum update a mano nella shell?

Ciao
Gianfranco

[Stefano]

ciao

per cortesia, posta il risultato di

Code: [Select]

ls -la /var/qmail/bin/qmail-newu

e di

Code: [Select]

rpm -qf /var/qmail/bin/qmail-newu

per tenere aggiornati anche i contribs, dai da shell

Code: [Select]

yum --enablerepo=smecontribs update

consiglio caldamente di usare la shell per aggiornare la macchina in quanto il feedback è maggiore, è possibile usare repo diversi e usare inclusioni/esclusioni

[gbartolini]

Infatti il problema era proprio li', gli script puntavano al file binario /var/qmail/bin/qmail-newu che per qualche motivo misterioso era corrotto! Infatti se provavo ad eseguirlo direttamente da shell ottenevo lo stesso errore. Forse un crash passato del server, ricordo che durante la pausa natalizia ci furono problemi di alimentazione elettrica ed anche gli ups si scaricarono.

Ho installato al volo una versione aggiornata di SME 7.5.1 in macchina virtuale e mi sono copiato il binario sul server operativo, da quel momento tutto ha ripreso ha funzionare!
Ovviamente per sicurezza ho cancellato tutti gli utenti creati recentemente che avevano problemi e li ho ricreati da zero, questa volta senza alcun intoppo.

Grazie per l'aiuto e le dritte!

Ciao
Gianfranco