search

sympa-community / sympa

Sympa, Mailing List Management Software
https://www.sympa.community/sympa
GNU General Public License v2.0
244 stars 96 forks source link

Sympa don't proccess mail received from outside #686

Closed stylersnico closed 5 years ago

stylersnico commented 5 years ago

Hi,

So to make it simple, I use the last Sympa 6.2.16 on Debian 9.

Everything works well except that Sympa don't process mail received from external mail address:

They stay stuck in:

root@sympa-mailing:/var/spool/sympa/msg# ls
bad  moved  test_postfix@mailing.demo.com.1562680823.36914

If I send the mail from the Sympa web interface everythings works well:

root@sympa-mailing:/var/log# cat mail.info 

Jul  9 15:58:47 sympa-mailing postfix/pickup[36873]: C38701C002A: uid=110 from=<test_postfix-owner@mailing.demo.com>
Jul  9 15:58:47 sympa-mailing postfix/cleanup[36898]: C38701C002A: message-id=<sympa.1562680703.36889.529@mailing.demo.com>
Jul  9 15:58:48 sympa-mailing postfix/qmgr[36874]: C38701C002A: from=<mailing@demo.com>, size=68681, nrcpt=2 (queue active)
Jul  9 15:58:49 sympa-mailing postfix/smtp[36901]: connect to smtp.office365.com[2603:1026:300:c9::2]:587: Cannot assign requested address
Jul  9 15:58:51 sympa-mailing postfix/smtp[36901]: C38701C002A: to=<nicolas.simond@external.com>, relay=smtp.office365.com[40.101.70.194]:587, delay=3.7, delays=0.83/1.4/1.1/0.37, dsn=2.0.0, status=sent (250 2.0.0 OK <sympa.1562680703.36889.529@mailing.demo.com> [Hostname=AM0PR01MB5554.eurprd01.prod.exchangelabs.com])
Jul  9 15:58:51 sympa-mailing postfix/smtp[36901]: C38701C002A: to=<tech@external.com>, relay=smtp.office365.com[40.101.70.194]:587, delay=3.7, delays=0.83/1.4/1.1/0.37, dsn=2.0.0, status=sent (250 2.0.0 OK <sympa.1562680703.36889.529@mailing.demo.com> [Hostname=AM0PR01MB5554.eurprd01.prod.exchangelabs.com])
Jul  9 15:58:51 sympa-mailing postfix/qmgr[36874]: C38701C002A: removed

But, If I email test_postfix@mailing.demo.com from someemail@demo.com it seems that Sympa doesn't process the email:

root@sympa-mailing:/var/log# cat mail.info 

Jul  9 16:00:23 sympa-mailing postfix/smtpd[36911]: connect from mail-eopbgr80129.outbound.protection.outlook.com[40.107.8.129]
Jul  9 16:00:23 sympa-mailing postfix/smtpd[36911]: 218851C002A: client=mail-eopbgr80129.outbound.protection.outlook.com[40.107.8.129]
Jul  9 16:00:23 sympa-mailing postfix/cleanup[36898]: 218851C002A: message-id=<AM0PR01MB5554759D570CF76C3A11D8C6DAF10@AM0PR01MB5554.eurprd01.prod.exchangelabs.com>
Jul  9 16:00:23 sympa-mailing postfix/qmgr[36874]: 218851C002A: from=<mailing@demo.com>, size=70302, nrcpt=1 (queue active)
Jul  9 16:00:23 sympa-mailing postfix/smtpd[36911]: disconnect from mail-eopbgr80129.outbound.protection.outlook.com[40.107.8.129] ehlo=2 starttls=1 mail=1 rcpt=1 data=1 quit=1 commands=7
Jul  9 16:00:24 sympa-mailing postfix/local[36913]: 218851C002A: to=<test_postfix@mailing.demo.com>, relay=local, delay=1.3, delays=0.25/0.03/0/1.1, dsn=2.0.0, status=sent (delivered to command:  /usr/lib/sympa/bin/queue test_postfix@mailing.demo.com)
Jul  9 16:00:24 sympa-mailing postfix/qmgr[36874]: 218851C002A: removed
root@sympa-mailing:/var/log#

Any idea?

/etc/postfix/main.cf

myhostname = mailing.demo.com
alias_maps = hash:/etc/aliases,hash:/etc/sympa/aliases.sympa.postfix,hash:/etc/mail/sympa/aliases
alias_database = hash:/etc/aliases,hash:/etc/sympa/aliases.sympa.postfix,hash:/etc/mail/sympa/aliases
myorigin = /etc/mailname
mydestination = localhost, localhost.localdomain, mailing.demo.com
relayhost = [smtp.office365.com]:587

/etc/sympa/sympa/sympa.conf

###\\\\ Site customization ////###

## domain
## Main robot hostname
## Example: domain      domain.tld
domain  mailing.demo.com
aliases_program postalias

Thanks

ikedas commented 5 years ago

Hi @stylersnico,

Could you please show sympa's log?

What is the content of the directory /var/spool/sympa/msg?

stylersnico commented 5 years ago

Hi @ikedas,

I've made some change since yesterday and I've solved some problems.

Now, postfix seems to proccess the emails, I received this:

One new message from list testjuly from mailing@mailing.demo.comch arrived.

They are 1 messages awaiting moderation.
To browse the awaiting messages, you should click on the following link:
<http://mailing.demo.comch/wws/ticket/39173525996080>

To distribute the attached message in list testjuly:
<mailto:sympa@mailing.demo.comch?subject=DISTRIBUTE%20testjuly%20ce77e9132bbba1f008e959bb16fde868>
Or send a message to sympa@mailing.demo.comch with the following subject:
DISTRIBUTE testjuly ce77e9132bbba1f008e959bb16fde868

To reject it (it will be removed):
<mailto:sympa@mailing.demo.comch?subject=REJECT%20testjuly%20ce77e9132bbba1f008e959bb16fde868>
Or send a message to sympa@mailing.demo.comch with the following subject:
REJECT testjuly ce77e9132bbba1f008e959bb16fde868

The messages moderating documentation:
<http://mailing.demo.comch/wws/help/admin#moderate>

And that when I reply:

Message ce77e9132bbba1f008e959bb16fde868 for list 'testjuly' has been
distributed.

The message is proccessed by sympa and I see it in the archive on the web interface:

Jul 10 08:17:54 sympa-mailing wwsympa[5239]: info main:: WWSympa started, process 5239
Jul 10 08:17:54 sympa-mailing wwsympa[5239]: info main:: WWSympa started, process 5239
Jul 10 08:17:55 sympa-mailing wwsympa[5239]: info main::do_modindex() [robot mailing.demo.com] [session 53405079030145] [client 192.168.1.242] [user mailing@demo.com] [list testjuly]
Jul 10 08:17:55 sympa-mailing wwsympa[5239]: info main::do_modindex() [robot mailing.demo.com] [session 53405079030145] [client 192.168.1.242] [user mailing@demo.com] [list testjuly]
Jul 10 08:17:57 sympa-mailing wwsympa[5239]: info Sympa::Session::renew() [robot mailing.demo.com] [session 53405079030145] [client 192.168.1.242] [user mailing@demo.com] new session 81807272144029
Jul 10 08:17:57 sympa-mailing wwsympa[5239]: info Sympa::Session::renew() [robot mailing.demo.com] [session 53405079030145] [client 192.168.1.242] [user mailing@demo.com] new session 81807272144029
Jul 10 08:19:28 sympa-mailing wwsympa[5251]: info main:: WWSympa started, process 5251
Jul 10 08:19:28 sympa-mailing wwsympa[5251]: info main:: WWSympa started, process 5251
Jul 10 08:19:29 sympa-mailing wwsympa[5251]: info main::do_arc(, ) [robot mailing.demo.com] [session 81807272144029] [client 192.168.1.242] [user mailing@demo.com] [list testjuly]
Jul 10 08:19:29 sympa-mailing wwsympa[5251]: info main::do_arc(, ) [robot mailing.demo.com] [session 81807272144029] [client 192.168.1.242] [user mailing@demo.com] [list testjuly]
Jul 10 08:19:32 sympa-mailing wwsympa[5251]: info Sympa::Session::renew() [robot mailing.demo.com] [session 81807272144029] [client 192.168.1.242] [user mailing@demo.com] new session 35160358073107
Jul 10 08:19:32 sympa-mailing wwsympa[5251]: info Sympa::Session::renew() [robot mailing.demo.com] [session 81807272144029] [client 192.168.1.242] [user mailing@demo.com] new session 35160358073107
Jul 10 08:19:32 sympa-mailing wwsympa[5251]: notice main:: Redirecting to http://192.168.1.49/wws/arc/testjuly/2019-07/
Jul 10 08:19:32 sympa-mailing wwsympa[5251]: notice main:: Redirecting to http://192.168.1.49/wws/arc/testjuly/2019-07/
Jul 10 08:19:33 sympa-mailing wwsympa[5253]: info main:: WWSympa started, process 5253
Jul 10 08:19:33 sympa-mailing wwsympa[5253]: info main:: WWSympa started, process 5253
Jul 10 08:19:33 sympa-mailing wwsympa[5253]: info main::do_arc(2019-07, /) [robot mailing.demo.com] [session 35160358073107] [client 192.168.1.242] [user mailing@demo.com] [list testjuly]
Jul 10 08:19:33 sympa-mailing wwsympa[5253]: info main::do_arc(2019-07, /) [robot mailing.demo.com] [session 35160358073107] [client 192.168.1.242] [user mailing@demo.com] [list testjuly]
Jul 10 08:19:41 sympa-mailing wwsympa[5255]: info main:: WWSympa started, process 5255
Jul 10 08:19:41 sympa-mailing wwsympa[5255]: info main:: WWSympa started, process 5255
Jul 10 08:19:42 sympa-mailing wwsympa[5255]: info main::do_arc(2019-07, msg00003.html) [robot mailing.demo.com] [session 35160358073107] [client 192.168.1.242] [user mailing@demo.com] [list testjuly]
Jul 10 08:19:42 sympa-mailing wwsympa[5255]: info main::do_arc(2019-07, msg00003.html) [robot mailing.demo.com] [session 35160358073107] [client 192.168.1.242] [user mailing@demo.com] [list testjuly]
Jul 10 08:19:45 sympa-mailing wwsympa[5257]: info main:: WWSympa started, process 5257
Jul 10 08:19:45 sympa-mailing wwsympa[5257]: info main:: WWSympa started, process 5257
Jul 10 08:19:45 sympa-mailing wwsympa[5257]: info main::do_arc(2019-07, pngjU4XEAYC0S.png) [robot mailing.demo.com] [session 35160358073107] [client 192.168.1.242] [user mailing@demo.com] [list testjuly]
Jul 10 08:19:45 sympa-mailing wwsympa[5257]: info main::do_arc(2019-07, pngjU4XEAYC0S.png) [robot mailing.demo.com] [session 35160358073107] [client 192.168.1.242] [user mailing@demo.com] [list testjuly]
Jul 10 08:19:52 sympa-mailing wwsympa[5259]: info main:: WWSympa started, process 5259
Jul 10 08:19:52 sympa-mailing wwsympa[5259]: info main:: WWSympa started, process 5259
Jul 10 08:19:52 sympa-mailing wwsympa[5259]: info main::do_send_me(testjuly, 2019, 07, VI1PR10MB3357FBCAF823B69F1039FB9896F00@VI1PR10MB3357.EURPRD10.PROD.OUTLOOK.COM) [robot mailing.demo.com] [session 35160358073107] [client 192.168.1.242] [user mailing@demo.com] [list testjuly]
Jul 10 08:19:52 sympa-mailing wwsympa[5259]: info main::do_send_me(testjuly, 2019, 07, VI1PR10MB3357FBCAF823B69F1039FB9896F00@VI1PR10MB3357.EURPRD10.PROD.OUTLOOK.COM) [robot mailing.demo.com] [session 35160358073107] [client 192.168.1.242] [user mailing@demo.com] [list testjuly]
Jul 10 08:19:53 sympa-mailing wwsympa[5259]: notice Sympa::Bulk::store() Message Sympa::Message <4> is stored into bulk spool as <5.5.1562739593.1562739593.019213.testjuly@mailing.demo.com_z,5259,1904>
Jul 10 08:19:53 sympa-mailing wwsympa[5259]: notice Sympa::Bulk::store() Message Sympa::Message <4> is stored into bulk spool as <5.5.1562739593.1562739593.019213.testjuly@mailing.demo.com_z,5259,1904>
Jul 10 08:19:53 sympa-mailing wwsympa[5259]: notice Sympa::Spindle::ToList::_send_msg() No VERP subscribers left to distribute message to list Sympa::List <testjuly@mailing.demo.com>
Jul 10 08:19:53 sympa-mailing wwsympa[5259]: notice Sympa::Spindle::ToList::_send_msg() No VERP subscribers left to distribute message to list Sympa::List <testjuly@mailing.demo.com>
Jul 10 08:19:54 sympa-mailing bulk[4865]: notice Sympa::Mailer::store() Done sending message Sympa::Message <5.5.1562739593.1562739593.019213.testjuly@mailing.demo.com_z,5259,1904/z> for Sympa::List <testjuly@mailing.demo.com> (priority 5) in 1 seconds since scheduled expedition date
Jul 10 08:19:55 sympa-mailing wwsympa[5259]: info Sympa::Spindle::ToList::_twist() Message Sympa::Message <4> for Sympa::List <testjuly@mailing.demo.com> from mailing@demo.com accepted (2.21 seconds, 1 sessions, 5 subscribers), message ID=VI1PR10MB3357FBCAF823B69F1039FB9896F00@VI1PR10MB3357.EURPRD10.PROD.OUTLOOK.COM, size=34475
Jul 10 08:19:55 sympa-mailing wwsympa[5259]: info main::do_send_me() [robot mailing.demo.com] [session 35160358073107] [client 192.168.1.242] [user mailing@demo.com] [list testjuly] Message VI1PR10MB3357FBCAF823B69F1039FB9896F00@VI1PR10MB3357.EURPRD10.PROD.OUTLOOK.COM spooled for mailing@demo.com
Jul 10 08:19:55 sympa-mailing wwsympa[5259]: info Sympa::Spindle::ToList::_twist() Message Sympa::Message <4> for Sympa::List <testjuly@mailing.demo.com> from mailing@demo.com accepted (2.21 seconds, 1 sessions, 5 subscribers), message ID=VI1PR10MB3357FBCAF823B69F1039FB9896F00@VI1PR10MB3357.EURPRD10.PROD.OUTLOOK.COM, size=34475
Jul 10 08:19:55 sympa-mailing wwsympa[5259]: info main::do_send_me() [robot mailing.demo.com] [session 35160358073107] [client 192.168.1.242] [user mailing@demo.com] [list testjuly] Message VI1PR10MB3357FBCAF823B69F1039FB9896F00@VI1PR10MB3357.EURPRD10.PROD.OUTLOOK.COM spooled for mailing@demo.com
Jul 10 08:19:55 sympa-mailing wwsympa[5259]: info main::do_arc(2019-07, ) [robot mailing.demo.com] [session 35160358073107] [client 192.168.1.242] [user mailing@demo.com] [list testjuly]
Jul 10 08:19:55 sympa-mailing wwsympa[5259]: info main::do_arc(2019-07, ) [robot mailing.demo.com] [session 35160358073107] [client 192.168.1.242] [user mailing@demo.com] [list testjuly]
Jul 10 08:19:56 sympa-mailing wwsympa[5259]: notice main:: Redirecting to http://192.168.1.49/wws/arc/testjuly/2019-07/
Jul 10 08:19:56 sympa-mailing wwsympa[5259]: notice main:: Redirecting to http://192.168.1.49/wws/arc/testjuly/2019-07/
Jul 10 08:19:58 sympa-mailing wwsympa[5266]: info main:: WWSympa started, process 5266
Jul 10 08:19:58 sympa-mailing wwsympa[5266]: info main:: WWSympa started, process 5266
Jul 10 08:19:58 sympa-mailing wwsympa[5266]: info main::do_arc(2019-07, /) [robot mailing.demo.com] [session 35160358073107] [client 192.168.1.242] [user mailing@demo.com] [list testjuly]
Jul 10 08:19:58 sympa-mailing wwsympa[5266]: info main::do_arc(2019-07, /) [robot mailing.demo.com] [session 35160358073107] [client 192.168.1.242] [user mailing@demo.com] [list testjuly]
Jul 10 08:20:42 sympa-mailing sympa_msg[4863]: notice Sympa::Spindle::ProcessIncoming::_twist() Processing Sympa::Message <testjuly@mailing.demo.com.1562739641.5272>; envelope_sender=mailing@demo.com; message_id=AM0PR01MB5554BF10C060258C828D810DDAF00@AM0PR01MB5554.eurprd01.prod.exchangelabs.com; sender=mailing@demo.com
Jul 10 08:20:42 sympa-mailing sympa_msg[4863]: info Sympa::Spindle::DoMessage::_twist() Processing message Sympa::Message <testjuly@mailing.demo.com.1562739641.5272> for Sympa::List <testjuly@mailing.demo.com> with priority 5, <AM0PR01MB5554BF10C060258C828D810DDAF00@AM0PR01MB5554.eurprd01.prod.exchangelabs.com>
Jul 10 08:20:42 sympa-mailing sympa_msg[4863]: notice Sympa::Spool::store() Sympa::Message <testjuly@mailing.demo.com.1562739641.5272> is stored into Sympa::Spool::Archive as <1562739641.1562739642.565228.testjuly@mailing.demo.com,4863,3749>
Jul 10 08:20:42 sympa-mailing sympa_msg[4863]: notice Sympa::Spool::store() Sympa::Message <testjuly@mailing.demo.com.1562739641.5272> is stored into Sympa::Spool::Digest <testjuly@mailing.demo.com> as <1562739641.1562739642.579922,4863,6791>
Jul 10 08:20:42 sympa-mailing sympa_msg[4863]: info Sympa::Spindle::ToList::_send_msg() No subscriber for sending msg in list Sympa::List <testjuly@mailing.demo.com>
Jul 10 08:20:42 sympa-mailing sympa_msg[4863]: info Sympa::Spindle::ToList::_twist() Message Sympa::Message <testjuly@mailing.demo.com.1562739641.5272> for Sympa::List <testjuly@mailing.demo.com> from mailing@demo.com accepted (0.12 seconds, 0 sessions, 5 subscribers), message ID=AM0PR01MB5554BF10C060258C828D810DDAF00@AM0PR01MB5554.eurprd01.prod.exchangelabs.com, size=34602
Jul 10 08:20:47 sympa-mailing archived[4864]: notice Sympa::Spindle::ProcessArchive::_twist() Processing Sympa::Message <1562739641.1562739642.565228.testjuly@mailing.demo.com,4863,3749>; sender: mailing@demo.com; message ID: AM0PR01MB5554BF10C060258C828D810DDAF00@AM0PR01MB5554.eurprd01.prod.exchangelabs.com
Jul 10 08:20:47 sympa-mailing archived[4864]: notice Sympa::Spindle::ProcessArchive::_twist() Archiving Sympa::Message <1562739641.1562739642.565228.testjuly@mailing.demo.com,4863,3749> for list Sympa::List <testjuly@mailing.demo.com>
Jul 10 08:20:47 sympa-mailing archived[4864]: notice Sympa::Archive::store() Message Sympa::Message <1562739641.1562739642.565228.testjuly@mailing.demo.com,4863,3749> is stored into archive Sympa::Archive <testjuly@mailing.demo.com/2019-07> as <5>
Jul 10 08:21:23 sympa-mailing wwsympa[5277]: info main:: WWSympa started, process 5277
Jul 10 08:21:23 sympa-mailing wwsympa[5277]: info main:: WWSympa started, process 5277
Jul 10 08:21:23 sympa-mailing wwsympa[5277]: info main::do_arc(, ) [robot mailing.demo.com] [session 35160358073107] [client 192.168.1.242] [user mailing@demo.com] [list testjuly]
Jul 10 08:21:23 sympa-mailing wwsympa[5277]: info main::do_arc(, ) [robot mailing.demo.com] [session 35160358073107] [client 192.168.1.242] [user mailing@demo.com] [list testjuly]
Jul 10 08:21:26 sympa-mailing wwsympa[5277]: info Sympa::Session::renew() [robot mailing.demo.com] [session 35160358073107] [client 192.168.1.242] [user mailing@demo.com] new session 19025253887213
Jul 10 08:21:26 sympa-mailing wwsympa[5277]: info Sympa::Session::renew() [robot mailing.demo.com] [session 35160358073107] [client 192.168.1.242] [user mailing@demo.com] new session 19025253887213
Jul 10 08:21:26 sympa-mailing wwsympa[5277]: notice main:: Redirecting to http://192.168.1.49/wws/arc/testjuly/2019-07/
Jul 10 08:21:26 sympa-mailing wwsympa[5277]: notice main:: Redirecting to http://192.168.1.49/wws/arc/testjuly/2019-07/
Jul 10 08:21:27 sympa-mailing wwsympa[5279]: info main:: WWSympa started, process 5279
Jul 10 08:21:27 sympa-mailing wwsympa[5279]: info main:: WWSympa started, process 5279
Jul 10 08:21:27 sympa-mailing wwsympa[5279]: info main::do_arc(2019-07, /) [robot mailing.demo.com] [session 19025253887213] [client 192.168.1.242] [user mailing@demo.com] [list testjuly]
Jul 10 08:21:27 sympa-mailing wwsympa[5279]: info main::do_arc(2019-07, /) [robot mailing.demo.com] [session 19025253887213] [client 192.168.1.242] [user mailing@demo.com] [list testjuly]
Jul 10 08:21:43 sympa-mailing wwsympa[5282]: info main:: WWSympa started, process 5282
Jul 10 08:21:43 sympa-mailing wwsympa[5282]: info main:: WWSympa started, process 5282
Jul 10 08:21:44 sympa-mailing wwsympa[5282]: info main::do_arc(2019-07, msg00004.html) [robot mailing.demo.com] [session 19025253887213] [client 192.168.1.242] [user mailing@demo.com] [list testjuly]
Jul 10 08:21:44 sympa-mailing wwsympa[5282]: info main::do_arc(2019-07, msg00004.html) [robot mailing.demo.com] [session 19025253887213] [client 192.168.1.242] [user mailing@demo.com] [list testjuly]
Jul 10 08:21:47 sympa-mailing wwsympa[5282]: info Sympa::Session::renew() [robot mailing.demo.com] [session 19025253887213] [client 192.168.1.242] [user mailing@demo.com] new session 58794708193646
Jul 10 08:21:47 sympa-mailing wwsympa[5282]: info Sympa::Session::renew() [robot mailing.demo.com] [session 19025253887213] [client 192.168.1.242] [user mailing@demo.com] new session 58794708193646
Jul 10 08:21:48 sympa-mailing wwsympa[5284]: info main:: WWSympa started, process 5284
Jul 10 08:21:48 sympa-mailing wwsympa[5284]: info main:: WWSympa started, process 5284
Jul 10 08:21:49 sympa-mailing wwsympa[5284]: info main::do_arc(2019-07, pngAO2XVdvc97.png) [robot mailing.demo.com] [session 58794708193646] [client 192.168.1.242] [user mailing@demo.com] [list testjuly]
Jul 10 08:21:49 sympa-mailing wwsympa[5284]: info main::do_arc(2019-07, pngAO2XVdvc97.png) [robot mailing.demo.com] [session 58794708193646] [client 192.168.1.242] [user mailing@demo.com] [list testjuly]
Jul 10 08:22:09 sympa-mailing wwsympa[5287]: info main:: WWSympa started, process 5287
Jul 10 08:22:09 sympa-mailing wwsympa[5287]: info main:: WWSympa started, process 5287
Jul 10 08:22:09 sympa-mailing wwsympa[5287]: info main::do_review() [robot mailing.demo.com] [session 58794708193646] [client 192.168.1.242] [user mailing@demo.com] [list testjuly]
Jul 10 08:22:09 sympa-mailing wwsympa[5287]: info main::do_review() [robot mailing.demo.com] [session 58794708193646] [client 192.168.1.242] [user mailing@demo.com] [list testjuly]
Jul 10 08:26:07 sympa-mailing sympa_msg[4863]: notice Sympa::Spindle::ProcessIncoming::_twist() Processing Sympa::Message <testjuly@mailing.demo.com.1562739963.5452>; envelope_sender=mailing@mailing.demo.com; message_id=20190710062603.748001C0CF5@sympa-mailing.cicad.local; sender=mailing@mailing.demo.com
Jul 10 08:26:07 sympa-mailing sympa_msg[4863]: info Sympa::Spindle::DoMessage::_twist() Processing message Sympa::Message <testjuly@mailing.demo.com.1562739963.5452> for Sympa::List <testjuly@mailing.demo.com> with priority 5, <20190710062603.748001C0CF5@sympa-mailing.cicad.local>
Jul 10 08:26:07 sympa-mailing sympa_msg[4863]: notice Sympa::Spool::store() Sympa::Message <testjuly@mailing.demo.com.1562739963.5452> is stored into Sympa::Spool::Moderation as <testjuly@mailing.demo.com_ce77e9132bbba1f008e959bb16fde868>
Jul 10 08:26:08 sympa-mailing sympa_msg[4863]: notice Sympa::Spindle::ProcessTemplate::_twist() Processing Sympa::Message::Template <testjuly@mailing.demo.com.1562739969.4863,7926>; envelope_sender=testjuly-owner@mailing.demo.com; message_id=sympa.1562739968.4863.721@mailing.demo.com; recipients=mailing@demo.com; sender=testjuly-request@mailing.demo.com; template=moderate;
Jul 10 08:26:08 sympa-mailing sympa_msg[4863]: notice Sympa::Bulk::store() Message Sympa::Message::Template <testjuly@mailing.demo.com.1562739969.4863,7926> is stored into bulk spool as <5.5.1562739969.1562739968.900750.testjuly@mailing.demo.com_s,4863,851>
Jul 10 08:26:08 sympa-mailing sympa_msg[4863]: info Sympa::Spindle::ToModeration::_twist() Key ce77e9132bbba1f008e959bb16fde868 of message Sympa::Message <testjuly@mailing.demo.com.1562739963.5452> for list Sympa::List <testjuly@mailing.demo.com> from mailing@mailing.demo.com sent to editors (1.45 seconds)
Jul 10 08:26:08 sympa-mailing sympa_msg[4863]: notice Sympa::Spindle::ProcessTemplate::_twist() Processing Sympa::Message::Template <testjuly@mailing.demo.com.1562739969.4863,6605>; envelope_sender=testjuly-owner@mailing.demo.com; message_id=sympa.1562739968.4863.43@mailing.demo.com; recipients=mailing@mailing.demo.com; sender=sympa@mailing.demo.com; template=message_report; type=success
Jul 10 08:26:08 sympa-mailing sympa_msg[4863]: notice Sympa::Bulk::store() Message Sympa::Message::Template <testjuly@mailing.demo.com.1562739969.4863,6605> is stored into bulk spool as <5.5.1562739969.1562739968.962986.testjuly@mailing.demo.com_s,4863,7131>
Jul 10 08:26:09 sympa-mailing bulk[4865]: notice Sympa::Spindle::ProcessOutgoing::_twist() Start sending message Sympa::Message <5.5.1562739969.1562739968.900750.testjuly@mailing.demo.com_s,4863,851/s> to Sympa::List <testjuly@mailing.demo.com> (priority 5) (starting 0 seconds after scheduled expedition date)
Jul 10 08:26:09 sympa-mailing bulk[4865]: notice Sympa::Mailer::store() Done sending message Sympa::Message <5.5.1562739969.1562739968.900750.testjuly@mailing.demo.com_s,4863,851/s> for Sympa::List <testjuly@mailing.demo.com> (priority 5) in 0 seconds since scheduled expedition date
Jul 10 08:26:09 sympa-mailing bulk[4865]: notice Sympa::Spindle::ProcessOutgoing::_twist() Start sending message Sympa::Message <5.5.1562739969.1562739968.962986.testjuly@mailing.demo.com_s,4863,7131/s> to Sympa::List <testjuly@mailing.demo.com> (priority 5) (starting 0 seconds after scheduled expedition date)
Jul 10 08:26:09 sympa-mailing bulk[4865]: notice Sympa::Mailer::store() Done sending message Sympa::Message <5.5.1562739969.1562739968.962986.testjuly@mailing.demo.com_s,4863,7131/s> for Sympa::List <testjuly@mailing.demo.com> (priority 5) in 0 seconds since scheduled expedition date
Jul 10 08:26:28 sympa-mailing sympa_msg[4863]: notice Sympa::Spindle::ProcessIncoming::_twist() Processing Sympa::Message <sympa@mailing.demo.com.1562739984.5462>; envelope_sender=mailing@demo.com; message_id=AM0PR01MB55548599414DFFA4E141F19FDAF00@AM0PR01MB5554.eurprd01.prod.exchangelabs.com; sender=mailing@demo.com
Jul 10 08:26:29 sympa-mailing sympa_msg[4863]: notice Sympa::Request::Message::__parse() Parsing: DISTRIBUTE testjuly ce77e9132bbba1f008e959bb16fde868
Jul 10 08:26:29 sympa-mailing sympa_msg[4863]: notice Sympa::Spindle::ProcessMessage::_twist() Processing Sympa::Request <action=distribute;context=testjuly@mailing.demo.com>
Jul 10 08:26:29 sympa-mailing sympa_msg[4863]: notice Sympa::Spindle::ProcessTemplate::_twist() Processing Sympa::Message::Template <testjuly@mailing.demo.com.1562739989.4863,2998>; envelope_sender=<>; message_id=sympa.1562739989.4863.743@mailing.demo.com; recipients=mailing@mailing.demo.com; sender=sympa-request@mailing.demo.com; template=delivery_status_notification; action=delivered; status=2.1.5
Jul 10 08:26:29 sympa-mailing sympa_msg[4863]: notice Sympa::Bulk::store() Message Sympa::Message::Template <testjuly@mailing.demo.com.1562739989.4863,2998> is stored into bulk spool as <5.5.1562739989.1562739989.208792.testjuly@mailing.demo.com_s,4863,4385>
Jul 10 08:26:29 sympa-mailing sympa_msg[4863]: notice Sympa::Spool::store() Sympa::Message <testjuly@mailing.demo.com_ce77e9132bbba1f008e959bb16fde868> is stored into Sympa::Spool::Archive as <1562739989.1562739989.232437.testjuly@mailing.demo.com,4863,303>
Jul 10 08:26:29 sympa-mailing sympa_msg[4863]: notice Sympa::Spool::store() Sympa::Message <testjuly@mailing.demo.com_ce77e9132bbba1f008e959bb16fde868> is stored into Sympa::Spool::Digest <testjuly@mailing.demo.com> as <1562739989.1562739989.249283,4863,2199>
Jul 10 08:26:29 sympa-mailing sympa_msg[4863]: info Sympa::Spindle::ToList::_send_msg() No subscriber for sending msg in list Sympa::List <testjuly@mailing.demo.com>
Jul 10 08:26:29 sympa-mailing sympa_msg[4863]: notice Sympa::Spindle::ProcessTemplate::_twist() Processing Sympa::Message::Template <testjuly@mailing.demo.com.1562739990.4863,4582>; envelope_sender=testjuly-owner@mailing.demo.com; message_id=sympa.1562739989.4863.93@mailing.demo.com; recipients=mailing@demo.com; sender=sympa@mailing.demo.com; template=message_report; type=success
Jul 10 08:26:29 sympa-mailing sympa_msg[4863]: notice Sympa::Bulk::store() Message Sympa::Message::Template <testjuly@mailing.demo.com.1562739990.4863,4582> is stored into bulk spool as <5.5.1562739990.1562739989.307773.testjuly@mailing.demo.com_s,4863,2998>
Jul 10 08:26:29 sympa-mailing sympa_msg[4863]: info Sympa::Spindle::ToList::_twist() Message Sympa::Message <testjuly@mailing.demo.com_ce77e9132bbba1f008e959bb16fde868> for Sympa::List <testjuly@mailing.demo.com> from mailing@demo.com accepted (0.25 seconds, 0 sessions, 5 subscribers), message ID=20190710062603.748001C0CF5@sympa-mailing.cicad.local, size=452
Jul 10 08:26:29 sympa-mailing sympa_msg[4863]: info Sympa::Request::Handler::distribute::_twist() DISTRIBUTE testjuly ce77e9132bbba1f008e959bb16fde868 from mailing@demo.com accepted (0.30 seconds)
Jul 10 08:26:29 sympa-mailing bulk[4865]: notice Sympa::Spindle::ProcessOutgoing::_twist() Start sending message Sympa::Message <5.5.1562739989.1562739989.208792.testjuly@mailing.demo.com_s,4863,4385/s> to Sympa::List <testjuly@mailing.demo.com> (priority 5) (starting 0 seconds after scheduled expedition date)
Jul 10 08:26:29 sympa-mailing bulk[4865]: notice Sympa::Mailer::store() Done sending message Sympa::Message <5.5.1562739989.1562739989.208792.testjuly@mailing.demo.com_s,4863,4385/s> for Sympa::List <testjuly@mailing.demo.com> (priority 5) in 0 seconds since scheduled expedition date
Jul 10 08:26:31 sympa-mailing bulk[4865]: notice Sympa::Spindle::ProcessOutgoing::_twist() Start sending message Sympa::Message <5.5.1562739990.1562739989.307773.testjuly@mailing.demo.com_s,4863,2998/s> to Sympa::List <testjuly@mailing.demo.com> (priority 5) (starting 1 seconds after scheduled expedition date)
Jul 10 08:26:31 sympa-mailing bulk[4865]: notice Sympa::Mailer::store() Done sending message Sympa::Message <5.5.1562739990.1562739989.307773.testjuly@mailing.demo.com_s,4863,2998/s> for Sympa::List <testjuly@mailing.demo.com> (priority 5) in 1 seconds since scheduled expedition date
Jul 10 08:26:32 sympa-mailing archived[4864]: notice Sympa::Spindle::ProcessArchive::_twist() Processing Sympa::Message <1562739989.1562739989.232437.testjuly@mailing.demo.com,4863,303>; sender: mailing@mailing.demo.com; message ID: 20190710062603.748001C0CF5@sympa-mailing.cicad.local
Jul 10 08:26:32 sympa-mailing archived[4864]: notice Sympa::Spindle::ProcessArchive::_twist() Archiving Sympa::Message <1562739989.1562739989.232437.testjuly@mailing.demo.com,4863,303> for list Sympa::List <testjuly@mailing.demo.com>
Jul 10 08:26:32 sympa-mailing archived[4864]: notice Sympa::Archive::store() Message Sympa::Message <1562739989.1562739989.232437.testjuly@mailing.demo.com,4863,303> is stored into archive Sympa::Archive <testjuly@mailing.demo.com/2019-07> as <6>

But nothing is send by postfix and I don't have anything in spam:

Jul 10 08:17:11 sympa-mailing postfix/smtpd[5230]: connect from dispatch1-eu1.ppe-hosted.com[91.209.104.157]
Jul 10 08:17:11 sympa-mailing postfix/smtpd[5230]: 2384E1C0CF5: client=dispatch1-eu1.ppe-hosted.com[91.209.104.157]
Jul 10 08:17:11 sympa-mailing postfix/cleanup[5233]: 2384E1C0CF5: message-id=<VI1PR10MB3357FBCAF823B69F1039FB9896F00@VI1PR10MB3357.EURPRD10.PROD.OUTLOOK.COM>
Jul 10 08:17:11 sympa-mailing postfix/qmgr[4795]: 2384E1C0CF5: from=<nicolas.simond@external.com>, size=34902, nrcpt=1 (queue active)
Jul 10 08:17:11 sympa-mailing postfix/smtpd[5230]: disconnect from dispatch1-eu1.ppe-hosted.com[91.209.104.157] ehlo=2 starttls=1 mail=1 rcpt=1 data=1 quit=1 commands=7
Jul 10 08:17:12 sympa-mailing postfix/local[5234]: 2384E1C0CF5: to=<testjuly@mailing.demo.com>, relay=local, delay=1.7, delays=0.72/0.01/0/1, dsn=2.0.0, status=sent (delivered to command:  /usr/lib/sympa/bin/queue testjuly@mailing.demo.com)
Jul 10 08:17:12 sympa-mailing postfix/qmgr[4795]: 2384E1C0CF5: removed
Jul 10 08:19:55 sympa-mailing postfix/pickup[4794]: 215751C0CF5: uid=110 from=<testjuly-owner@mailing.demo.com>
Jul 10 08:19:55 sympa-mailing postfix/cleanup[5263]: 215751C0CF5: message-id=<VI1PR10MB3357FBCAF823B69F1039FB9896F00@VI1PR10MB3357.EURPRD10.PROD.OUTLOOK.COM>
Jul 10 08:19:55 sympa-mailing postfix/qmgr[4795]: 215751C0CF5: from=<testjuly-owner@mailing.demo.com>, size=35906, nrcpt=1 (queue active)
Jul 10 08:19:56 sympa-mailing postfix/smtp[5265]: 215751C0CF5: to=<mailing@demo.com>, relay=cicad-ch.mail.protection.outlook.com[104.47.1.36]:25, delay=3.2, delays=2.1/0.01/0.28/0.76, dsn=2.6.0, status=sent (250 2.6.0 <VI1PR10MB3357FBCAF823B69F1039FB9896F00@VI1PR10MB3357.EURPRD10.PROD.OUTLOOK.COM> [InternalId=15264313771537, Hostname=VE1PR01MB5565.eurprd01.prod.exchangelabs.com] 45013 bytes in 0.182, 240.648 KB/sec Queued mail for delivery)
Jul 10 08:19:56 sympa-mailing postfix/qmgr[4795]: 215751C0CF5: removed
Jul 10 08:20:31 sympa-mailing postfix/anvil[5194]: statistics: max connection rate 1/60s for (smtp:40.107.2.90) at Jul 10 08:13:57
Jul 10 08:20:31 sympa-mailing postfix/anvil[5194]: statistics: max connection count 1 for (smtp:40.107.2.90) at Jul 10 08:13:57
Jul 10 08:20:31 sympa-mailing postfix/anvil[5194]: statistics: max cache size 1 at Jul 10 08:13:57
Jul 10 08:20:41 sympa-mailing postfix/smtpd[5268]: connect from mail-eopbgr50091.outbound.protection.outlook.com[40.107.5.91]
Jul 10 08:20:41 sympa-mailing postfix/smtpd[5268]: 5C8811C0CF5: client=mail-eopbgr50091.outbound.protection.outlook.com[40.107.5.91]
Jul 10 08:20:41 sympa-mailing postfix/cleanup[5263]: 5C8811C0CF5: message-id=<AM0PR01MB5554BF10C060258C828D810DDAF00@AM0PR01MB5554.eurprd01.prod.exchangelabs.com>
Jul 10 08:20:41 sympa-mailing postfix/qmgr[4795]: 5C8811C0CF5: from=<mailing@demo.com>, size=35060, nrcpt=1 (queue active)
Jul 10 08:20:41 sympa-mailing postfix/smtpd[5268]: disconnect from mail-eopbgr50091.outbound.protection.outlook.com[40.107.5.91] ehlo=2 starttls=1 mail=1 rcpt=1 data=1 quit=1 commands=7
Jul 10 08:20:42 sympa-mailing postfix/local[5271]: 5C8811C0CF5: to=<testjuly@mailing.demo.com>, relay=local, delay=1.4, delays=0.41/0.01/0/1, dsn=2.0.0, status=sent (delivered to command:  /usr/lib/sympa/bin/queue testjuly@mailing.demo.com)
Jul 10 08:20:42 sympa-mailing postfix/qmgr[4795]: 5C8811C0CF5: removed
Jul 10 08:24:01 sympa-mailing postfix/anvil[5270]: statistics: max connection rate 1/60s for (smtp:40.107.5.91) at Jul 10 08:20:41
Jul 10 08:24:01 sympa-mailing postfix/anvil[5270]: statistics: max connection count 1 for (smtp:40.107.5.91) at Jul 10 08:20:41
Jul 10 08:24:01 sympa-mailing postfix/anvil[5270]: statistics: max cache size 1 at Jul 10 08:20:41
Jul 10 08:26:03 sympa-mailing postfix/pickup[4794]: 748001C0CF5: uid=0 from=<mailing@mailing.demo.com>
Jul 10 08:26:03 sympa-mailing postfix/cleanup[5449]: 748001C0CF5: message-id=<20190710062603.748001C0CF5@sympa-mailing.cicad.local>
Jul 10 08:26:03 sympa-mailing postfix/qmgr[4795]: 748001C0CF5: from=<mailing@mailing.demo.com>, size=381, nrcpt=1 (queue active)
Jul 10 08:26:04 sympa-mailing postfix/local[5451]: 748001C0CF5: to=<testjuly@mailing.demo.com>, relay=local, delay=1.8, delays=0.68/0.03/0/1.1, dsn=2.0.0, status=sent (delivered to command:  /usr/lib/sympa/bin/queue testjuly@mailing.demo.com)
Jul 10 08:26:04 sympa-mailing postfix/qmgr[4795]: 748001C0CF5: removed
Jul 10 08:26:10 sympa-mailing postfix/pickup[4794]: 34DBD1C0CF1: uid=110 from=<testjuly-owner@mailing.demo.com>
Jul 10 08:26:10 sympa-mailing postfix/cleanup[5449]: 34DBD1C0CF1: message-id=<sympa.1562739968.4863.43@mailing.demo.com>
Jul 10 08:26:10 sympa-mailing postfix/qmgr[4795]: 34DBD1C0CF1: from=<testjuly-owner@mailing.demo.com>, size=539, nrcpt=1 (queue active)
Jul 10 08:26:10 sympa-mailing postfix/pickup[4794]: A182B1C0DA7: uid=110 from=<testjuly-owner@mailing.demo.com>
Jul 10 08:26:10 sympa-mailing postfix/cleanup[5449]: A182B1C0DA7: message-id=<sympa.1562739968.4863.721@mailing.demo.com>
Jul 10 08:26:11 sympa-mailing postfix/local[5451]: 34DBD1C0CF1: to=<mailing@mailing.demo.com>, relay=local, delay=1.6, delays=1.1/0/0/0.45, dsn=2.0.0, status=sent (delivered to mailbox)
Jul 10 08:26:11 sympa-mailing postfix/qmgr[4795]: 34DBD1C0CF1: removed
Jul 10 08:26:11 sympa-mailing postfix/qmgr[4795]: A182B1C0DA7: from=<testjuly-owner@mailing.demo.com>, size=2437, nrcpt=1 (queue active)
Jul 10 08:26:12 sympa-mailing postfix/smtp[5458]: A182B1C0DA7: to=<mailing@demo.com>, relay=cicad-ch.mail.protection.outlook.com[104.47.2.36]:25, delay=3.6, delays=1.9/0.07/0.56/1.1, dsn=2.6.0, status=sent (250 2.6.0 <sympa.1562739968.4863.721@mailing.demo.com> [InternalId=15268608740760, Hostname=VE1PR01MB5566.eurprd01.prod.exchangelabs.com] 10739 bytes in 0.217, 48.168 KB/sec Queued mail for delivery)
Jul 10 08:26:12 sympa-mailing postfix/qmgr[4795]: A182B1C0DA7: removed
Jul 10 08:26:23 sympa-mailing postfix/smtpd[5459]: connect from mail-eopbgr150114.outbound.protection.outlook.com[40.107.15.114]
Jul 10 08:26:24 sympa-mailing postfix/smtpd[5459]: 14DA91C0CF1: client=mail-eopbgr150114.outbound.protection.outlook.com[40.107.15.114]
Jul 10 08:26:24 sympa-mailing postfix/cleanup[5449]: 14DA91C0CF1: message-id=<AM0PR01MB55548599414DFFA4E141F19FDAF00@AM0PR01MB5554.eurprd01.prod.exchangelabs.com>
Jul 10 08:26:24 sympa-mailing postfix/qmgr[4795]: 14DA91C0CF1: from=<mailing@demo.com>, size=5197, nrcpt=1 (queue active)
Jul 10 08:26:24 sympa-mailing postfix/smtpd[5459]: disconnect from mail-eopbgr150114.outbound.protection.outlook.com[40.107.15.114] ehlo=2 starttls=1 mail=1 rcpt=1 data=1 quit=1 commands=7
Jul 10 08:26:25 sympa-mailing postfix/local[5451]: 14DA91C0CF1: to=<sympa@mailing.demo.com>, relay=local, delay=1.4, delays=0.42/0/0/1, dsn=2.0.0, status=sent (delivered to command:  /usr/lib/sympa/bin/queue sympa@mailing.demo.com)
Jul 10 08:26:25 sympa-mailing postfix/qmgr[4795]: 14DA91C0CF1: removed
Jul 10 08:26:30 sympa-mailing postfix/pickup[4794]: 4260D1C0CF1: uid=110 from=<>
Jul 10 08:26:30 sympa-mailing postfix/cleanup[5449]: 4260D1C0CF1: message-id=<sympa.1562739989.4863.743@mailing.demo.com>
Jul 10 08:26:30 sympa-mailing postfix/qmgr[4795]: 4260D1C0CF1: from=<>, size=2108, nrcpt=1 (queue active)
Jul 10 08:26:30 sympa-mailing postfix/local[5451]: 4260D1C0CF1: to=<mailing@mailing.demo.com>, relay=local, delay=1.4, delays=1/0/0/0.37, dsn=2.0.0, status=sent (delivered to mailbox)
Jul 10 08:26:30 sympa-mailing postfix/qmgr[4795]: 4260D1C0CF1: removed
Jul 10 08:26:31 sympa-mailing postfix/pickup[4794]: C80DA1C0CF1: uid=110 from=<testjuly-owner@mailing.demo.com>
Jul 10 08:26:31 sympa-mailing postfix/cleanup[5449]: C80DA1C0CF1: message-id=<sympa.1562739989.4863.93@mailing.demo.com>
Jul 10 08:26:32 sympa-mailing postfix/qmgr[4795]: C80DA1C0CF1: from=<testjuly-owner@mailing.demo.com>, size=552, nrcpt=1 (queue active)
Jul 10 08:26:33 sympa-mailing postfix/smtp[5458]: C80DA1C0CF1: to=<mailing@demo.com>, relay=cicad-ch.mail.protection.outlook.com[104.47.2.36]:25, delay=2, delays=0.94/0/0.25/0.76, dsn=2.6.0, status=sent (250 2.6.0 <sympa.1562739989.4863.93@mailing.demo.com> [InternalId=15268608739233, Hostname=AM6PR01MB5559.eurprd01.prod.exchangelabs.com] 8771 bytes in 0.196, 43.483 KB/sec Queued mail for delivery)
Jul 10 08:26:33 sympa-mailing postfix/qmgr[4795]: C80DA1C0CF1: removed
Jul 10 08:29:44 sympa-mailing postfix/anvil[5461]: statistics: max connection rate 1/60s for (smtp:40.107.15.114) at Jul 10 08:26:23
Jul 10 08:29:44 sympa-mailing postfix/anvil[5461]: statistics: max connection count 1 for (smtp:40.107.15.114) at Jul 10 08:26:23
Jul 10 08:29:44 sympa-mailing postfix/anvil[5461]: statistics: max cache size 1 at Jul 10 08:26:23

Any idea?

Thanks

stylersnico commented 5 years ago

One more information,

If I test a "Remind subscriber" from web interface it works.

I receive this:

You requested a subscription reminder to be sent to each subscriber of list
testjuly

If you want this action to be taken, please

- reply to this mail
OR
- send a message to sympa@mailing.demo.com with subject
 AUTH a5bf9c57d1a5b3b1 REMIND testjuly
OR
- hit the following mailto

mailto:sympa@mailing.demo.com?subject=AUTH%20a5bf9c57d1a5b3b1%20REMIND%20testjuly

If you do not want this action to be taken, you can safely ignore this
message.

I validate by email and receive this:

Command has been performed:

> REMIND testjuly
> remind
Subscription reminder sent to each of 5 testjuly subscribers.

-----------==========================================-----------

For further information, check the mailing list web site
http://mailing.demo.com/wws

And all subscriber receive this (all external ones):


Reminder of your subscription to testjuly
testjuly-request@mailing.demo.com
Aujourd’hui, 08:36IDDI Nicolas Simond
Please be cautious
This email originated outside of IDDI Informatique. Please help keep our organization, customers and partners safe. It's up to us; think before you click
________________________________

Your are subscriber of list testjuly@mailing.demo.com with  email
nicolas.simond@external.com

Everything about this list: http://mailing.demo.com/wws/info/testjuly

Unsubscription:
mailto:sympa@mailing.demo.com?subject=sig%20testjuly%20nicolas.simond%40external.com
ikedas commented 5 years ago

Hi @stylersnico,

In sympa log:

Jul 10 08:26:29 sympa-mailing sympa_msg[4863]: info Sympa::Spindle::ToList::_send_msg() No subscriber for sending msg in list Sympa::List <testjuly@mailing.demo.com>

There seems no members (subscribers) in the list testjuly, and the message was not delivered to anyone.

stylersnico commented 5 years ago

Hi @ikedas

After some test, I completly scratched the server and started again and now it works, it's seem that you must follow the doc on github on not on the official website

nel-khoury commented 4 years ago

Running into the same issue. It would help to post the changes or which documentation on Github you followed to resolve the problem.

stylersnico commented 4 years ago

Running into the same issue. It would help to post the changes or which documentation on Github you followed to resolve the problem.

The doc in the readme: https://sympa-community.github.io/manual/