Installation et paramétrage

Toony · April 6, 2012, 11:12pm

Oups, désolé… L’heure se fait sentir
Je voulais dire:

/etc/init.d/bm-lmtpd stop
ps auwx|grep bm-lmtpd

En tous les cas, ce que vous me fournissez comme résultat n’est pas correct. Faites :

kill -9 20082 20173
/etc/init.d/bm-lmtpd start
ps auwx|grep bm-lmtpd

Qu’obtenez-vous ?

pierrotlo · April 6, 2012, 11:15pm

OK je la donne en plus grand :
Request DefaultHttpRequest(chunked: false)
GET /location/host/mail/imap/pierre@cryptos-sys.ch HTTP/1.1
Host: 192.168.1.12:8084
Connection: keep-alive
Accept: /
User-Agent: NING/1.0

Response DefaultHttpResponse(chunked: false)
HTTP/1.1 200 OK
Content-Type: text/plain; charset=utf-8
Content-Length: 13
Server: Jetty(6.1.x)

2012-04-06 23:11:23,780 c.n.h.c.p.n.NettyConnectionsPool DEBUG - Adding uri: https://192.168.1.12:8084 for channel [id: 0x1fbbc779, /192.168.1.12:34202 => /192.168.1.12:8084]
2012-04-06 23:11:23,802 n.b.l.c.LmtpClient DEBUG - LMTP client connect called
2012-04-06 23:11:23,803 n.b.l.c.LmtpClientSupport DEBUG - Getting init lock
2012-04-06 23:11:23,813 n.b.l.c.LmtpClientHandler DEBUG - Adding LMTP filter to MINA session
2012-04-06 23:11:23,817 n.b.l.c.LmtpClientSupport DEBUG - Connection established.
2012-04-06 23:11:28,818 n.b.l.c.LmtpClientSupport ERROR - Don’t receive LMTPd banner, connection fail !
2012-04-06 23:11:28,819 n.b.l.c.LmtpClient ERROR - Can’t get LMTPd connection
2012-04-06 23:11:28,820 n.b.d.l.LmtpdDeliveryAgent DEBUG - Can’t connect to LMTPd on: 192.168.1.12:24
2012-04-06 23:11:28,820 n.b.l.i.LmtpHandler ERROR - Can’t connect to LMTPd
2012-04-06 23:11:28,820 n.b.l.i.MinaLmtpHandler DEBUG - QUIT
2012-04-06 23:11:28,820 n.b.l.i.LmtpHandler DEBUG - command=QUIT arg=null
2012-04-06 23:11:28,823 n.b.m.MinaIoHandler DEBUG - session closed
2012-04-06 23:11:33,463 o.a.m.f.c.ProtocolCodecFilter DEBUG - Processing a MESSAGE_RECEIVED for session 1
2012-04-06 23:11:33,463 n.b.l.i.MinaLmtpHandler DEBUG - LHLO bluemind
2012-04-06 23:11:33,463 n.b.l.i.LmtpHandler DEBUG - command=LHLO arg=bluemind
2012-04-06 23:11:33,463 o.a.m.f.c.ProtocolCodecFilter DEBUG - Processing a MESSAGE_RECEIVED for session 3
2012-04-06 23:11:33,464 n.b.l.i.MinaLmtpHandler DEBUG - LHLO bluemind
2012-04-06 23:11:33,464 n.b.l.i.LmtpHandler DEBUG - command=LHLO arg=bluemind
2012-04-06 23:11:33,464 o.a.m.f.c.ProtocolCodecFilter DEBUG - Processing a MESSAGE_RECEIVED for session 1
2012-04-06 23:11:33,465 n.b.l.i.MinaLmtpHandler DEBUG - MAIL FROM:p.locicero@netplus.ch SIZE=1033 BODY=7BIT
2012-04-06 23:11:33,465 n.b.l.i.LmtpHandler DEBUG - command=MAIL arg=FROM:p.locicero@netplus.ch SIZE=1033 BODY=7BIT
2012-04-06 23:11:33,465 n.b.l.i.MinaLmtpHandler DEBUG - RCPT TO:pierre@cryptos-sys.ch
2012-04-06 23:11:33,465 n.b.l.i.LmtpHandler DEBUG - command=RCPT arg=TO:pierre@cryptos-sys.ch
2012-04-06 23:11:33,465 n.b.l.i.LmtpBackend DEBUG - addressStatus(pierre@cryptos-sys.ch)
2012-04-06 23:11:33,465 n.b.d.l.MboxValidator INFO - Checking address status: pierre@cryptos-sys.ch
2012-04-06 23:11:33,465 n.b.l.i.MinaLmtpHandler DEBUG - DATA
2012-04-06 23:11:33,465 n.b.l.i.LmtpHandler DEBUG - command=DATA arg=null
2012-04-06 23:11:33,469 o.a.m.f.c.ProtocolCodecFilter DEBUG - Processing a MESSAGE_RECEIVED for session 3
2012-04-06 23:11:33,470 n.b.l.i.MinaLmtpHandler DEBUG - MAIL FROM:p.locicero@netplus.ch SIZE=1031 BODY=7BIT
2012-04-06 23:11:33,470 n.b.l.i.LmtpHandler DEBUG - command=MAIL arg=FROM:p.locicero@netplus.ch SIZE=1031 BODY=7BIT
2012-04-06 23:11:33,470 n.b.l.i.MinaLmtpHandler DEBUG - RCPT TO:pierre@cryptos-sys.ch
2012-04-06 23:11:33,470 n.b.l.i.LmtpHandler DEBUG - command=RCPT arg=TO:pierre@cryptos-sys.ch
2012-04-06 23:11:33,470 n.b.l.i.LmtpBackend DEBUG - addressStatus(pierre@cryptos-sys.ch)
2012-04-06 23:11:33,470 n.b.d.l.MboxValidator INFO - Checking address status: pierre@cryptos-sys.ch
2012-04-06 23:11:33,471 n.b.l.i.MinaLmtpHandler DEBUG - DATA
2012-04-06 23:11:33,471 n.b.l.i.LmtpHandler DEBUG - command=DATA arg=null
2012-04-06 23:11:33,506 o.a.m.f.c.ProtocolCodecFilter DEBUG - Processing a MESSAGE_RECEIVED for session 1
2012-04-06 23:11:33,508 o.a.m.f.c.ProtocolCodecFilter DEBUG - Processing a MESSAGE_RECEIVED for session 3
2012-04-06 23:11:33,509 o.a.m.f.c.ProtocolCodecFilter DEBUG - Processing a MESSAGE_RECEIVED for session 1
2012-04-06 23:11:33,580 n.b.m.BlobBuilder DEBUG - stored /tmp/blob4055722125910378167.bin: data size=1167, file size=1167
2012-04-06 23:11:33,581 n.b.l.i.LmtpBackend DEBUG - filter(net.bluemind.lmtp.backend.LmtpEnvelope@79f7896f, blob: Blob: { path=/tmp/blob4055722125910378167.bin, size=1167 })
2012-04-06 23:11:33,582 n.b.l.i.LmtpBackend DEBUG - deliver(net.bluemind.lmtp.backend.LmtpEnvelope@79f7896f, blob: Blob: { path=/tmp/blob4055722125910378167.bin, size=1167 })
2012-04-06 23:11:33,583 n.b.d.l.LmtpdDeliveryAgent INFO - Start LMTPd delivering
2012-04-06 23:11:33,583 n.b.l.c.LmtpClient DEBUG - LMTP client connect called
2012-04-06 23:11:33,583 n.b.l.c.LmtpClientSupport DEBUG - Getting init lock
2012-04-06 23:11:33,587 n.b.l.c.LmtpClientHandler DEBUG - Adding LMTP filter to MINA session
2012-04-06 23:11:33,588 n.b.l.c.LmtpClientSupport DEBUG - Connection established.
2012-04-06 23:11:37,640 c.n.h.c.p.n.NettyConnectionsPool DEBUG - Entry count for : https://192.168.1.12:8084 : 1
2012-04-06 23:11:37,966 c.n.h.c.p.n.NettyConnectionsPool DEBUG - Entry count for : http://192.168.1.12:8085 : 1
2012-04-06 23:11:38,588 n.b.l.c.LmtpClientSupport ERROR - Don’t receive LMTPd banner, connection fail !
2012-04-06 23:11:38,589 n.b.l.c.LmtpClient ERROR - Can’t get LMTPd connection
2012-04-06 23:11:38,599 n.b.d.l.LmtpdDeliveryAgent DEBUG - Can’t connect to LMTPd on: 192.168.1.12:24
2012-04-06 23:11:38,599 n.b.l.i.LmtpHandler ERROR - Can’t connect to LMTPd
2012-04-06 23:11:38,599 n.b.l.i.MinaLmtpHandler DEBUG - QUIT
2012-04-06 23:11:38,600 n.b.l.i.LmtpHandler DEBUG - command=QUIT arg=null
2012-04-06 23:11:38,600 o.a.m.f.c.ProtocolCodecFilter DEBUG - Processing a MESSAGE_RECEIVED for session 3
2012-04-06 23:11:38,641 n.b.m.BlobBuilder DEBUG - stored /tmp/blob1597959621206084590.bin: data size=1165, file size=1165
2012-04-06 23:11:38,641 n.b.l.i.LmtpBackend DEBUG - filter(net.bluemind.lmtp.backend.LmtpEnvelope@21e3cc77, blob: Blob: { path=/tmp/blob1597959621206084590.bin, size=1165 })
2012-04-06 23:11:38,643 n.b.l.i.LmtpBackend DEBUG - deliver(net.bluemind.lmtp.backend.LmtpEnvelope@21e3cc77, blob: Blob: { path=/tmp/blob1597959621206084590.bin, size=1165 })
2012-04-06 23:11:38,643 n.b.d.l.LmtpdDeliveryAgent INFO - Start LMTPd delivering
2012-04-06 23:11:38,643 n.b.l.c.LmtpClient DEBUG - LMTP client connect called
2012-04-06 23:11:38,643 n.b.l.c.LmtpClientSupport DEBUG - Getting init lock
2012-04-06 23:11:38,647 n.b.l.c.LmtpClientHandler DEBUG - Adding LMTP filter to MINA session
2012-04-06 23:11:38,647 n.b.l.c.LmtpClientSupport DEBUG - Connection established.
2012-04-06 23:11:43,648 n.b.l.c.LmtpClientSupport ERROR - Don’t receive LMTPd banner, connection fail !
2012-04-06 23:11:43,727 n.b.l.c.LmtpClient ERROR - Can’t get LMTPd connection
2012-04-06 23:11:43,728 n.b.d.l.LmtpdDeliveryAgent DEBUG - Can’t connect to LMTPd on: 192.168.1.12:24
2012-04-06 23:11:43,728 n.b.l.i.LmtpHandler ERROR - Can’t connect to LMTPd
2012-04-06 23:11:43,728 n.b.l.i.MinaLmtpHandler DEBUG - QUIT
2012-04-06 23:11:43,728 n.b.l.i.LmtpHandler DEBUG - command=QUIT arg=null
2012-04-06 23:11:43,729 n.b.m.MinaIoHandler DEBUG - session closed
2012-04-06 23:11:43,729 n.b.m.MinaIoHandler DEBUG - session closed
2012-04-06 23:12:37,640 c.n.h.c.p.n.NettyConnectionsPool DEBUG - Entry count for : https://192.168.1.12:8084 : 1
2012-04-06 23:12:37,641 c.n.h.c.p.n.NettyConnectionsPool DEBUG - Adding Candidate Idle Channel [id: 0x1fbbc779, /192.168.1.12:34202 => /192.168.1.12:8084]
2012-04-06 23:12:37,641 c.n.h.c.p.n.NettyConnectionsPool DEBUG - Closing Idle Channel [id: 0x1fbbc779, /192.168.1.12:34202 => /192.168.1.12:8084]
2012-04-06 23:12:37,644 c.n.h.c.p.n.NettyAsyncHttpProvider DEBUG - Channel Closed: [id: 0x1fbbc779, /192.168.1.12:34202 :> /192.168.1.12:8084] with attachment com.ning.http.client.providers.netty.NettyAsyncHttpProvider$DiscardEvent@29949f78
2012-04-06 23:12:37,967 c.n.h.c.p.n.NettyConnectionsPool DEBUG - Entry count for : http://192.168.1.12:8085 : 1
2012-04-06 23:12:37,967 c.n.h.c.p.n.NettyConnectionsPool DEBUG - Adding Candidate Idle Channel [id: 0x48c5186e, /192.168.1.12:36412 => /192.168.1.12:8085]
2012-04-06 23:12:37,967 c.n.h.c.p.n.NettyConnectionsPool DEBUG - Closing Idle Channel [id: 0x48c5186e, /192.168.1.12:36412 => /192.168.1.12:8085]
2012-04-06 23:12:37,968 c.n.h.c.p.n.NettyAsyncHttpProvider DEBUG - Channel Closed: [id: 0x48c5186e, /192.168.1.12:36412 :> /192.168.1.12:8085] with attachment com.ning.http.client.providers.netty.NettyAsyncHttpProvider$DiscardEvent@3852eef4
2012-04-06 23:13:37,640 c.n.h.c.p.n.NettyConnectionsPool DEBUG - Entry count for : https://192.168.1.12:8084 : 0
2012-04-06 23:13:37,966 c.n.h.c.p.n.NettyConnectionsPool DEBUG - Entry count for : http://192.168.1.12:8085 : 0
2012-04-06 23:14:37,641 c.n.h.c.p.n.NettyConnectionsPool DEBUG - Entry count for : https://192.168.1.12:8084 : 0
2012-04-06 23:14:37,966 c.n.h.c.p.n.NettyConnectionsPool DEBUG - Entry count for : http://192.168.1.12:8085 : 0

pierrotlo · April 6, 2012, 11:18pm

ps auwx|grep bm-lmtpd
root 20396 50.7 4.8 741820 98984 pts/1 Sl 01:18 0:04 /usr/lib/jvm/java-1.6.0-openjdk/bin/java -server -Xms256m -Xmx256m -XX:MaxPermSize=128m -Xss4m -XX:+HeapDumpOnOutOfMemoryError -XX:HeapDumpPath=/tmp -XX:+UseCompressedOops -Djava.awt.headless=true -Dosgi.noShutdown=true -Duser.timezone=GMT -Djava.security.egd=file:/dev/./urandom -Dlogback.configurationFile=/etc/bm-lmtpd/bm-lmtpd.logback.xml -cp /usr/share/bm-lmtpd/plugins/org.eclipse.equinox.launcher_1.0.201.R35x_v20090715.jar org.eclipse.equinox.launcher.Main -application net.bluemind.lmtp.id1
pierre 20430 0.0 0.0 7640 932 pts/1 S+ 01:18 0:00 grep --color=auto bm-lmtpd

Toony · April 6, 2012, 11:21pm

L’IP 192.168.1.12 est bien celle de votre VM ?

Que donne la commande :

telnet 192.168.1.12 24

pierrotlo · April 6, 2012, 11:26pm

Ok je vous laisse en vous souhaitant une bonne nuit tout de même.
Comme elle porte conseil, on peut espérer que ce sera mieux demain

Bien à vous

pierrotlo · April 7, 2012, 6:10am

Sans aucuns doutes possible

telnet 192.168.1.12 24
Trying 192.168.1.12…
Connected to 192.168.1.12.
Escape character is ‘^]’.

eth1 Link encap:Ethernet HWaddr 52:54:00:51:5e:73
inet adr:192.168.1.12 Bcast:192.168.1.255 Masque:255.255.255.0
adr inet6: fe80::5054:ff:fe51:5e73/64 Scope:Lien

pierrotlo · April 7, 2012, 6:18am

Une autre choses. Nous sommes concentré sur le webmail.

PAr contre, il n’y a aucune lenteur au niveau du calendrier qui semble parfaitement fonctionner

pierrotlo · April 7, 2012, 9:44am

Je vais faire une chose : installer BlueMind sur un pc en direct sans passer par une VM Histoire de tout au moins éliminer une cause possible de ce disfonctionnement
LA machine est une Hp 7800 sff / Processeur Intel Vpro 2 Gb de mémoire

Toony · April 7, 2012, 11:40am

[quote=pierrotlo]Sans aucuns doutes possible

telnet 192.168.1.12 24
Trying 192.168.1.12…
Connected to 192.168.1.12.
Escape character is ‘^]’.

eth1 Link encap:Ethernet HWaddr 52:54:00:51:5e:73
inet adr:192.168.1.12 Bcast:192.168.1.255 Masque:255.255.255.0
adr inet6: fe80::5054:ff:fe51:5e73/64 Scope:Lien[/quote]

Ok, c’est ça le soucis… C’est étonnant que vous puissiez vous connecter au port 24 sur l’IP 127.0.0.1 et pas sur l’IP 192.168.1.12…
N’auriez-vous pas un pare-feu actif sur votre Ubuntu ?

iptables -L -v -n

pierrotlo · April 7, 2012, 11:52am

Heue non en fait :
depuis une machine externe
Telnet 192.168.1.12 24 → OK → 220 bluemind Cyrus LMTP v2.4.13-Debian-2.4.13-3hudson89 server ready

depuis la machine bluemind
Telnet 192.168.1.12 24 → OK → 220 bluemind Cyrus LMTP v2.4.13-Debian-2.4.13-3hudson89 server ready

Telnet 127.0.0.1 24 → OK → 220 bluemind Cyrus LMTP v2.4.13-Debian-2.4.13-3hudson89 server ready

Sauf erreur de ma part il n’y a pas de firewall sur une installation standerd sur la 10.04 server LTS

iptables -L -v -n

Chain INPUT (policy ACCEPT 0 packets, 0 bytes)
pkts bytes target prot opt in out source destination

Chain FORWARD (policy ACCEPT 0 packets, 0 bytes)
pkts bytes target prot opt in out source destination

Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes)
pkts bytes target prot opt in out source destination

Ensuite postqueue -f -puis postqueue -p → résultat identique

Toony · April 7, 2012, 11:55am

Effectivement, il n’y a pas de pare-feu par défaut sur une Ubuntu 10.04 server.

C’est vraiment étrange quand même votre problème… Pourriez-vous me donner accès en SSH à votre VM ? Je ne sais plus trop quoi vous conseiller/demander en l’état…
Ce cas me laisse perplexe.

pierrotlo · April 7, 2012, 11:57am

Pas de soucis, pouvez vous me donner votre ip publique par mail privé

Toony · April 7, 2012, 12:05pm

En regardant le code qui produit cette erreur dans le service bm-lmtpd, ce message indique que le service bm-lmtpd ne reçoit pas la banière du LMTPd de Cyrus dans les 5s suivant la tentative de connexion.

Soit votre VM est très lente (ce qui ne semble pas être le cas), soit l’outil de virtualisation rencontre un soucis avec son horloge.
Qu’utilisez-vous comme virtualiseur ?

pierrotlo · April 7, 2012, 12:06pm

KVM

Toony · April 7, 2012, 1:17pm

Bon, après avoir pris la main sur la VM le soucis n’était pas du tout liè au KVM, ni à votre installation de Blue Mind.

La commande:

telnet 192.168.1.12 24

répondait, mais après un moment. Le délais était suppérieur à 5s, d’où le message du bm-lmtpd qui se plaignait de ne pas arriver à se connecter.

Il semblerait que votre DNS ait un soucis pour faire les résolutions inverse:

nslookup 192.168.1.12
;; connection timed out; no servers could be reached

Nous obtenons cette réponse après un délais, qui lui aussi est supérieur à 5s.

En fait le service LMTP de cyrus fait une résolution inverse de l’IP source du client qui se connecte. Le délais d’obtention de la banière provient de là.

Pour contourner le soucis de DNS, j’ai adapté votre fichier /etc/hosts en ajoutant une ligne:
192.168.1.12 bluemind.cryptos-sys.ch bluemind

À partir de là, la commande:

telnet 192.168.1.12 24

répondait instantanéement, et la commande:

postqueue -f

à délivré les mails en attente.

La messagerie est très lié aux DNS et assez sensible à leur configuration.
Quelques pré-requis à l’installation de Blue Mind à ce sujet (mais je vous conseille de toujours vérifier celà après une installation d’un système):
[list=*]
]hostname: doit retourner le nom court de votre hôte (ex: toto);/]
]hostname -f: doit retourner le nom long de votre hôte (ex: toto.domaine.loc) ;/]
]ping www.blue-mind.net: doit répondre immédiatement/]
]nslookup adresseIp: doit répondre immédiatement, même si le résultat indique qu’il n’y a pas de réponse, l’important est le temps de réponse de cette commande/]
[/list]

Merci de votre collaboration.

pierrotlo · April 7, 2012, 1:43pm

Merci à vous. Pas mal de sueur mais très instructif, non seulement pour ce problème d’installation mais aussi sur le fonctionnement délicat d’un système mail.

Quand à la réactivité de Bluemind et spécialement de Toony, chapeau bas

Pierre