leap_mx doesn't process mails after first deployment
- i did a fresh deploy on ~21:00, leap_mx got started for the first time on 21:10
- i created a test user "testuser1" at ~21:25 using the pixelated-useragent
- i sent 3 testmails on 21:28 using
swaks -f swaks@example.org -t testuser1@example.org -s example.org
they ended up in /var/mail/vmail/Maildir/new/:
root@node1:~# ls -al /var/mail/vmail/Maildir/new/ total 20 drwx------ 2 vmail vmail 4096 Jan 29 21:30 . drwx------ 5 vmail vmail 4096 Jan 29 21:28 .. -rw------- 1 vmail vmail 591 Jan 29 21:28 1422566910.Vfe00I3ec65M402449.node1 -rw------- 1 vmail vmail 591 Jan 29 21:28 1422566912.Vfe00I3ec68M178517.node1 -rw------- 1 vmail vmail 591 Jan 29 21:28 1422566913.Vfe00I3ec69M86919.node1
on 21:30, i restarted leap_mx, send again a test mail that was delivered fine:
root@node1:~# cat /var/log/leap_mx.log 2015-01-29 21:10:55+0000 [-] Log opened. 2015-01-29 21:10:55+0000 [-] twistd 13.2.0 (/usr/bin/python 2.7.3) starting up. 2015-01-29 21:10:55+0000 [-] reactor class: twisted.internet.epollreactor.EPollReactor. 2015-01-29 21:10:55+0000 [-] AliasResolverFactory starting on 4242 2015-01-29 21:10:55+0000 [-] Starting factory 2015-01-29 21:10:55+0000 [-] CheckRecipientAccessFactory starting on 2244 2015-01-29 21:10:55+0000 [-] Starting factory 2015-01-29 21:10:55+0000 [-] Watching '/var/mail/vmail/Maildir' --- Recursive: True 2015-01-29 21:10:56+0000 [-] Received SIGTERM, shutting down. 2015-01-29 21:10:56+0000 [-] (TCP Port 2244 Closed) 2015-01-29 21:10:56+0000 [-] Stopping factory 2015-01-29 21:10:56+0000 [-] (TCP Port 4242 Closed) 2015-01-29 21:10:56+0000 [-] Stopping factory 2015-01-29 21:10:56+0000 [-] Main loop terminated. 2015-01-29 21:10:56+0000 [-] Server Shut Down. 2015-01-29 21:10:56+0000 [-] Log opened. 2015-01-29 21:10:56+0000 [-] twistd 13.2.0 (/usr/bin/python 2.7.3) starting up. 2015-01-29 21:10:56+0000 [-] reactor class: twisted.internet.epollreactor.EPollReactor. 2015-01-29 21:10:56+0000 [-] AliasResolverFactory starting on 4242 2015-01-29 21:10:56+0000 [-] Starting factory 2015-01-29 21:10:56+0000 [-] CheckRecipientAccessFactory starting on 2244 2015-01-29 21:10:56+0000 [-] Starting factory 2015-01-29 21:10:56+0000 [-] Watching '/var/mail/vmail/Maildir' --- Recursive: True 2015-01-29 21:28:29+0000 [LEAPPostFixTCPMapserver,0,127.0.0.1] Query key: example.org 2015-01-29 21:28:30+0000 [LEAPPostFixTCPMapserver,0,127.0.0.1] Starting factory 2015-01-29 21:28:30+0000 [HTTP11ClientProtocol,client] Result not found 2015-01-29 21:28:30+0000 [HTTP11ClientProtocol,client] Stopping factory 2015-01-29 21:28:30+0000 [LEAPPostFixTCPMapserver,0,127.0.0.1] Query key: example.org 2015-01-29 21:28:30+0000 [LEAPPostFixTCPMapserver,0,127.0.0.1] Starting factory 2015-01-29 21:28:30+0000 [HTTP11ClientProtocol,client] Result not found 2015-01-29 21:28:30+0000 [HTTP11ClientProtocol,client] Stopping factory 2015-01-29 21:28:30+0000 [LEAPPostFixTCPMapserver,1,127.0.0.1] Query key: testuser1@example.org 2015-01-29 21:28:30+0000 [LEAPPostFixTCPMapserver,1,127.0.0.1] Starting factory 2015-01-29 21:28:30+0000 [HTTP11ClientProtocol,client] Stopping factory 2015-01-29 21:28:30+0000 [LEAPPostFixTCPMapserver,2,127.0.0.1] Query key: testuser1@example.org 2015-01-29 21:28:30+0000 [LEAPPostFixTCPMapserver,2,127.0.0.1] Starting factory 2015-01-29 21:28:30+0000 [HTTP11ClientProtocol,client] Stopping factory 2015-01-29 21:28:30+0000 [LEAPPostFixTCPMapserver,2,127.0.0.1] Query key: b898f239b2c7c68f8f3cb84dd31f8cbc@node1.example.org 2015-01-29 21:28:30+0000 [LEAPPostFixTCPMapserver,2,127.0.0.1] Starting factory 2015-01-29 21:28:30+0000 [HTTP11ClientProtocol,client] Result not found 2015-01-29 21:28:30+0000 [HTTP11ClientProtocol,client] Stopping factory 2015-01-29 21:28:30+0000 [LEAPPostFixTCPMapserver,0,127.0.0.1] Query key: node1.example.org 2015-01-29 21:28:30+0000 [LEAPPostFixTCPMapserver,0,127.0.0.1] Starting factory 2015-01-29 21:28:30+0000 [HTTP11ClientProtocol,client] Result not found 2015-01-29 21:28:30+0000 [HTTP11ClientProtocol,client] Stopping factory 2015-01-29 21:28:30+0000 [LEAPPostFixTCPMapserver,0,127.0.0.1] Query key: node1.example.org 2015-01-29 21:28:30+0000 [LEAPPostFixTCPMapserver,0,127.0.0.1] Starting factory 2015-01-29 21:28:30+0000 [HTTP11ClientProtocol,client] Result not found 2015-01-29 21:28:30+0000 [HTTP11ClientProtocol,client] Stopping factory 2015-01-29 21:28:32+0000 [LEAPPostFixTCPMapserver,1,127.0.0.1] Query key: testuser1@example.org 2015-01-29 21:28:32+0000 [LEAPPostFixTCPMapserver,1,127.0.0.1] Starting factory 2015-01-29 21:28:32+0000 [HTTP11ClientProtocol,client] Stopping factory 2015-01-29 21:28:32+0000 [LEAPPostFixTCPMapserver,2,127.0.0.1] Query key: testuser1@example.org 2015-01-29 21:28:32+0000 [LEAPPostFixTCPMapserver,2,127.0.0.1] Starting factory 2015-01-29 21:28:32+0000 [HTTP11ClientProtocol,client] Stopping factory 2015-01-29 21:28:32+0000 [LEAPPostFixTCPMapserver,2,127.0.0.1] Query key: b898f239b2c7c68f8f3cb84dd31f8cbc@node1.example.org 2015-01-29 21:28:32+0000 [LEAPPostFixTCPMapserver,2,127.0.0.1] Starting factory 2015-01-29 21:28:32+0000 [HTTP11ClientProtocol,client] Result not found 2015-01-29 21:28:32+0000 [HTTP11ClientProtocol,client] Stopping factory 2015-01-29 21:28:33+0000 [LEAPPostFixTCPMapserver,1,127.0.0.1] Query key: testuser1@example.org 2015-01-29 21:28:33+0000 [LEAPPostFixTCPMapserver,1,127.0.0.1] Starting factory 2015-01-29 21:28:33+0000 [HTTP11ClientProtocol,client] Stopping factory 2015-01-29 21:28:33+0000 [LEAPPostFixTCPMapserver,2,127.0.0.1] Query key: testuser1@example.org 2015-01-29 21:28:33+0000 [LEAPPostFixTCPMapserver,2,127.0.0.1] Starting factory 2015-01-29 21:28:33+0000 [HTTP11ClientProtocol,client] Stopping factory 2015-01-29 21:28:33+0000 [LEAPPostFixTCPMapserver,2,127.0.0.1] Query key: b898f239b2c7c68f8f3cb84dd31f8cbc@node1.example.org 2015-01-29 21:28:33+0000 [LEAPPostFixTCPMapserver,2,127.0.0.1] Starting factory 2015-01-29 21:28:33+0000 [HTTP11ClientProtocol,client] Result not found 2015-01-29 21:28:33+0000 [HTTP11ClientProtocol,client] Stopping factory 2015-01-29 21:30:17+0000 [-] Received SIGTERM, shutting down. 2015-01-29 21:30:17+0000 [-] (TCP Port 2244 Closed) 2015-01-29 21:30:17+0000 [-] Stopping factory 2015-01-29 21:30:17+0000 [-] (TCP Port 4242 Closed) 2015-01-29 21:30:17+0000 [-] Stopping factory 2015-01-29 21:30:17+0000 [-] Main loop terminated. 2015-01-29 21:30:17+0000 [-] Server Shut Down. 2015-01-29 21:30:17+0000 [-] Log opened. 2015-01-29 21:30:17+0000 [-] twistd 13.2.0 (/usr/bin/python 2.7.3) starting up. 2015-01-29 21:30:17+0000 [-] reactor class: twisted.internet.epollreactor.EPollReactor. 2015-01-29 21:30:17+0000 [-] AliasResolverFactory starting on 4242 2015-01-29 21:30:17+0000 [-] Starting factory 2015-01-29 21:30:17+0000 [-] CheckRecipientAccessFactory starting on 2244 2015-01-29 21:30:17+0000 [-] Starting factory 2015-01-29 21:30:17+0000 [-] Watching '/var/mail/vmail/Maildir' --- Recursive: True 2015-01-29 21:30:33+0000 [LEAPPostFixTCPMapserver,0,127.0.0.1] Query key: example.org 2015-01-29 21:30:33+0000 [LEAPPostFixTCPMapserver,0,127.0.0.1] Starting factory 2015-01-29 21:30:33+0000 [HTTP11ClientProtocol,client] Result not found 2015-01-29 21:30:33+0000 [HTTP11ClientProtocol,client] Stopping factory 2015-01-29 21:30:33+0000 [LEAPPostFixTCPMapserver,0,127.0.0.1] Query key: example.org 2015-01-29 21:30:33+0000 [LEAPPostFixTCPMapserver,0,127.0.0.1] Starting factory 2015-01-29 21:30:33+0000 [HTTP11ClientProtocol,client] Result not found 2015-01-29 21:30:33+0000 [HTTP11ClientProtocol,client] Stopping factory 2015-01-29 21:30:33+0000 [LEAPPostFixTCPMapserver,1,127.0.0.1] Query key: testuser1@example.org 2015-01-29 21:30:33+0000 [LEAPPostFixTCPMapserver,1,127.0.0.1] Starting factory 2015-01-29 21:30:33+0000 [HTTP11ClientProtocol,client] Stopping factory 2015-01-29 21:30:33+0000 [LEAPPostFixTCPMapserver,2,127.0.0.1] Query key: testuser1@example.org 2015-01-29 21:30:33+0000 [LEAPPostFixTCPMapserver,2,127.0.0.1] Starting factory 2015-01-29 21:30:33+0000 [HTTP11ClientProtocol,client] Stopping factory 2015-01-29 21:30:33+0000 [LEAPPostFixTCPMapserver,2,127.0.0.1] Query key: b898f239b2c7c68f8f3cb84dd31f8cbc@node1.example.org 2015-01-29 21:30:33+0000 [LEAPPostFixTCPMapserver,2,127.0.0.1] Starting factory 2015-01-29 21:30:33+0000 [HTTP11ClientProtocol,client] Result not found 2015-01-29 21:30:33+0000 [HTTP11ClientProtocol,client] Stopping factory 2015-01-29 21:30:33+0000 [LEAPPostFixTCPMapserver,0,127.0.0.1] Query key: node1.example.org 2015-01-29 21:30:33+0000 [LEAPPostFixTCPMapserver,0,127.0.0.1] Starting factory 2015-01-29 21:30:33+0000 [HTTP11ClientProtocol,client] Result not found 2015-01-29 21:30:33+0000 [HTTP11ClientProtocol,client] Stopping factory 2015-01-29 21:30:33+0000 [LEAPPostFixTCPMapserver,0,127.0.0.1] Query key: node1.example.org 2015-01-29 21:30:33+0000 [LEAPPostFixTCPMapserver,0,127.0.0.1] Starting factory 2015-01-29 21:30:33+0000 [HTTP11ClientProtocol,client] Result not found 2015-01-29 21:30:33+0000 [HTTP11ClientProtocol,client] Stopping factory 2015-01-29 21:30:33+0000 [-] Processing new mail at '/var/mail/vmail/Maildir/new/1422567033.Vfe00I3ec6aM339655.node1' 2015-01-29 21:30:33+0000 [-] Mail owner: b898f239b2c7c68f8f3cb84dd31f8cbc 2015-01-29 21:30:33+0000 [-] Starting factory 2015-01-29 21:30:33+0000 [HTTP11ClientProtocol,client] Encrypting message to b898f239b2c7c68f8f3cb84dd31f8cbc's pubkey 2015-01-29 21:30:33+0000 [HTTP11ClientProtocol,client] Exporting message for b898f239b2c7c68f8f3cb84dd31f8cbc 2015-01-29 21:30:33+0000 [HTTP11ClientProtocol,client] Done exporting 2015-01-29 21:30:33+0000 [HTTP11ClientProtocol,client] Removing '/var/mail/vmail/Maildir/new/1422567033.Vfe00I3ec6aM339655.node1' 2015-01-29 21:30:33+0000 [HTTP11ClientProtocol,client] Done removing 2015-01-29 21:30:33+0000 [HTTP11ClientProtocol,client] Stopping factory
(from redmine: created on 2015-01-29, closed on 2015-04-21, relates #2591 (closed), relates #6706 (closed), relates #6739, relates #6856 (closed))