Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Mail don't appear in the interface and not send back to members #264

Closed
Zwordi opened this issue Apr 13, 2018 · 8 comments
Closed

Mail don't appear in the interface and not send back to members #264

Zwordi opened this issue Apr 13, 2018 · 8 comments
Labels

Comments

@Zwordi
Copy link

Zwordi commented Apr 13, 2018

Hi,

In my journey of setting up an Sympa with CAS/LDAP i have to face now a strange behavior.

I can send mail to lists by telnet and my mail is processed normally as i can find my mail into /var/spool/sympa/msg/*
All my logs seems ok.
I have check my configuration a lot based on the documentation but i don't understand why it's happenning.

Any hints or idea will be nice :)

@ikedas
Copy link
Member

ikedas commented Apr 14, 2018

I can send mail to lists by telnet and my mail is processed normally as i can find my mail into /var/spool/sympa/msg/*

It is expected: It means that you have successfully configured MTA. For the next, you may check if Sympa services (sympa_msg.pl etc.) is running properly. Can you show us following information?

  • Corresponding logs (log of Sympa and MTA).
  • Sympa configuration (sympa.conf).
  • Content of Sympa/Constants.pm file.

Note: You would be better to read "Installing Sympa", if you have not.

@Zwordi
Copy link
Author

Zwordi commented Apr 15, 2018

Hello @ikedas,

It was i thought when i found out my mail into theses folders. I have ran sympa_msg.pl -d:

info main::_load() Configuration file read, log level set using options: 2
debug2 Conf::cookie_changed() Cookie is stable
debug2 Sympa::Database::new(Sympa::Database, mysql)
debug2 Sympa::Database::connect() Connected to Database Sympa::DatabaseDriver::MySQL <db_host=localhost;db_name=sympa;db_port=3306;db_user=sympadmin>
debug Conf::_load_auth(/etc/sympa/auth.conf)
debug2 Conf::get_robots_list() Retrieving the list of robots on the server
debug Conf::_load_auth(/etc/sympa/lists.YYYYYYYY/auth.conf)
notice Sympa::Process::write_pid() Previous process 25760 died suddenly; notifying listmaster
debug2 Sympa::send_notify_to_listmaster(*, crash, HASH)
debug2 Sympa::Tools::SMIME::find_keys(*, sign)
debug2 Sympa::Message::Template::_new_from_template(Sympa::Message::Template, *, listmaster_notification.tt2, ARRAY, HASH)
debug2 Sympa::Message::new(Sympa::Message::Template, ...)
notice Sympa::Spindle::ProcessTemplate::_twist() Processing Sympa::Message::Template <sympa@lists.YYYYYYYY.1523809391.25764,7313>; envelope_sender=sympa-request@lists.YYYYYYYY; message_id=sympa.1523809391.25764.959@lists.YYYYYYYY; recipients=ARRAY; sender=sympa@lists.YYYYYYYY; template=listmaster_notification; type=crash
notice Sympa::Mailer::store() Done sending message Sympa::Message::Template <sympa@lists.YYYYYYYY.1523809391.25764,7313> for * (priority 1) in 0 seconds since scheduled expedition date
notice main:: Sympa/msg 6.2.30 Started
debug2 Sympa::Process::_reap_child() Child process 25768 for <sympa@lists.YYYYYYYY.1523809391.25764,7313> exited normally

Sympa.conf & robots.conf

domain	lists.YYYYYYYY
listmaster	XXXXX@YYYYYYYY
lang	en-US
supported_lang	ca,cs,de,el,en-US,es,et,eu,fi,fr,gl,hu,it,ja,ko,nb,nl,oc,pl,pt-BR,ru,sv,tr,vi,zh-CN,zh-TW
title	YYYYYYYYY  Mailing lists service
gecos	SYMPA-Your Mailling-Lists Assistant
db_type	mysql
db_host	localhost
db_port	3306
db_name	sympa
db_user	sympadmin
db_passwd ET4nNPt8QBCaL3Z6
syslog	LOCAL1
log_socket_type	unix
log_level 3
default_max_list_members	100
max_size	5242880
remove_headers	X-Sympa-To,X-Family-To,Return-Receipt-To,Precedence,X-Sequence,Disposition-Notification-To,Sender
rfc2369_header_fields	help,subscribe,unsubscribe,post,owner,archive
sendmail	/usr/sbin/sendmail
aliases_program postmap
aliases_db_type hash
sendmail_aliases /etc/sympa/sympa_transport
spool	/var/spool/sympa
queue	/var/spool/sympa/msg
queuemod	/var/spool/sympa/moderation
queuedigest	/var/spool/sympa/digest
queueauth	/var/spool/sympa/auth
queueoutgoing	/var/spool/sympa/outgoing
queuesubscribe	/var/spool/sympa/subscribe
queuetopic	/var/spool/sympa/topic
queuebounce	/var/spool/sympa/bounce
queuetask	/var/spool/sympa/task
queueautomatic	/var/spool/sympa/automatic
queuebulk	/var/spool/sympa/bulk
viewmail_dir	/var/spool/sympa/viewmail
cookie 600aed228b77c950b3d3bc8415097de0
wwsympa_url	https://mail.YYYYYYYY/sympa
use_html_editor	on
cafile /etc/ssl/lets-encrypt-x3-cross-signed.pem	
capath /etc/pki/tls/certs	
use_fast_cgi	1

Robots.conf under YYYYY folder /etc/sympa/YYYYYY

wwsympa_url	https://lists.YYYYY/sympa

Constants.pm

# -*- indent-tabs-mode: nil; -*-
# vim:ft=perl:et:sw=4
# $Id$

# Sympa - SYsteme de Multi-Postage Automatique
#
# Copyright (c) 1997, 1998, 1999 Institut Pasteur & Christophe Wolfhugel
# Copyright (c) 1997, 1998, 1999, 2000, 2001, 2002, 2003, 2004, 2005,
# 2006, 2007, 2008, 2009, 2010, 2011 Comite Reseau des Universites
# Copyright (c) 2011, 2012, 2013, 2014, 2015, 2016, 2017 GIP RENATER
# Copyright 2018 The Sympa Community. See the AUTHORS.md file at the
# top-level directory of this distribution and at
# <https://github.com/sympa-community/sympa.git>.
#
# This program is free software; you can redistribute it and/or modify
# it under the terms of the GNU General Public License as published by
# the Free Software Foundation; either version 2 of the License, or
# (at your option) any later version.
#
# This program is distributed in the hope that it will be useful,
# but WITHOUT ANY WARRANTY; without even the implied warranty of
# MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE.  See the
# GNU General Public License for more details.
#
# You should have received a copy of the GNU General Public License
# along with this program.  If not, see <http://www.gnu.org/licenses/>.

package Sympa::Constants;

use strict;

use constant VERSION => '6.2.30';
use constant USER    => 'sympa';
use constant GROUP   => 'sympa';

use constant CONFIG           => '/etc/sympa/sympa.conf';
use constant WWSCONFIG        => '/etc/sympa/wwsympa.conf';
use constant SENDMAIL_ALIASES => '/var/lib/sympa/sympa_aliases';

use constant PIDDIR      => '/run/sympa';
use constant EXPLDIR     => '/var/lib/sympa/list_data';
use constant SPOOLDIR    => '/var/spool/sympa';
use constant SYSCONFDIR  => '/etc/sympa';
use constant LOCALEDIR   => '/usr/share/locale';
use constant LIBEXECDIR  => '/usr/libexec/sympa';
use constant SBINDIR     => '/usr/sbin';
use constant SCRIPTDIR   => '/usr/share/sympa/bin';
use constant MODULEDIR   => '/usr/share/sympa/lib';
use constant DEFAULTDIR  => '/usr/share/sympa/default';
use constant ARCDIR      => '/var/lib/sympa/arc';
use constant BOUNCEDIR   => '/var/lib/sympa/bounce';
use constant EXECCGIDIR  => '/usr/libexec/sympa';
use constant STATICDIR   => '/usr/share/sympa/static_content';
use constant CSSDIR      => '/var/lib/sympa/css';
use constant PICTURESDIR => '/var/lib/sympa/pictures';

use constant EMAIL_LEN  => 100;
use constant FAMILY_LEN => 50;
use constant LIST_LEN   => 50;
use constant ROBOT_LEN  => 80;

1;
__END__

=encoding utf-8

=head1 NAME

Sympa::Constants - Definition of constants

=head1 DESCRIPTION

This module keeps definition of constants used by Sympa software.

=cut

Logs during reception of an email with telnet:

Apr 15 18:36:08 mail amavis[15846]: (15846-01) ESMTP [127.0.0.1]:10024 /var/spool/amavisd/tmp/amavis-20180415T183608-15846-6BqrhLmF: <toto@titi.fr> -> <technical@lists.YYYYYYYY> Received: from mail.YYYYYYYY ([127.0.0.1]) by localhost (mail.YYYYYYYY [127.0.0.1]) (amavisd-new, port 10024) with ESMTP for <technical@lists.YYYYYYYY>; Sun, 15 Apr 2018 18:36:08 +0200 (CEST)
Apr 15 18:36:08 mail amavis[15846]: (15846-01) Checking: JEtfWZK6M5dD [172.0.0.2] <toto@titi.fr> -> <technical@lists.YYYYYYYY>
Apr 15 18:36:08 mail amavis[15846]: (15846-01) Open relay? Nonlocal recips but not originating: technical@lists.YYYYYYYY
Apr 15 18:36:08 mail amavis[15846]: (15846-01) WARN: MIME::Parser error: unexpected end of header
Apr 15 18:36:08 mail amavis[15846]: (15846-01) p001 1 Content-Type: text/plain, 7bit, size: 0, SHA1 digest: da39a3ee5e6b4b0d3255bfef95601890afd80709
Apr 15 18:36:08 mail amavis[15846]: (15846-01) check_header: 7, Invalid header field syntax: World\n
Apr 15 18:36:08 mail amavis[15846]: (15846-01) check_header: 7, Missing required header field: "Date"
Apr 15 18:36:08 mail amavis[15846]: (15846-01) check_header: 7, Missing required header field: "From"
Apr 15 18:36:12 mail amavis[15846]: (15846-01) mangling NO: 0 (was: disclaimer), discl_allowed=0, <toto@titi.fr> -> <technical@lists.YYYYYYYY>
Apr 15 18:36:12 mail amavis[15846]: (15846-01) header_edits_for_quar: <toto@titi.fr> -> <technical@lists.YYYYYYYY>, Yes, score=8.111 tag=-1000 tag2=5 kill=15 tests=[MISSING_DATE=1.396, MISSING_FROM=1, MISSING_HEADERS=1.207, MISSING_MID=0.14, SPF_HELO_SOFTFAIL=0.896, SPF_SOFTFAIL=0.972, TVD_SPACE_RATIO=0.001, TVD_SPACE_RATIO_MINFP=2.499] autolearn=no autolearn_force=no
Apr 15 18:36:12 mail amavis[15846]: (15846-01) skip local delivery(3): <toto@titi.fr> -> <bad-header-quarantine>
Apr 15 18:36:12 mail postfix/smtpd[26540]: warning: database /etc/sympa/transport.sympa.db is older than source file /etc/sympa/transport.sympa
Apr 15 18:36:12 mail postfix/smtpd[26540]: connect from localhost[127.0.0.1]
Apr 15 18:36:12 mail postfix/trivial-rewrite[26507]: warning: do not list domain lists.YYYYYYYY in BOTH mydestination and virtual_mailbox_domains
Apr 15 18:36:12 mail postfix/smtpd[26540]: 6C2E3F05: client=localhost[127.0.0.1], orig_client=ns1.libre[172.0.0.2]
Apr 15 18:36:12 mail postfix/cleanup[26541]: 6C2E3F05: message-id=<>
Apr 15 18:36:12 mail postfix/qmgr[2061]: 6C2E3F05: from=<toto@titi.fr>, size=754, nrcpt=1 (queue active)
Apr 15 18:36:12 mail postfix/smtpd[26540]: disconnect from localhost[127.0.0.1]
Apr 15 18:36:12 mail postfix/trivial-rewrite[26507]: warning: do not list domain lists.YYYYYYYY in BOTH mydestination and virtual_mailbox_domains
Apr 15 18:36:12 mail amavis[15846]: (15846-01) JEtfWZK6M5dD FWD from <toto@titi.fr> -> <technical@lists.YYYYYYYY>, BODY=7BIT 250 2.0.0 from MTA(smtp:[127.0.0.1]:10025): 250 2.0.0 Ok: queued as 6C2E3F05
Apr 15 18:36:12 mail amavis[15846]: (15846-01) Passed SPAMMY {RelayedOpenRelay,Quarantined}, [172.0.0.2]:55220 [172.0.0.2] <toto@titi.fr> -> <technical@lists.YYYYYYYY>, mail_id: JEtfWZK6M5dD, Hits: 8.111, size: 201, queued_as: 6C2E3F05, 4331 ms
Apr 15 18:36:12 mail amavis[15846]: (15846-01) TIMING-SA total 3957 ms - parse: 1.51 (0.0%), extract_message_metadata: 3.7 (0.1%), get_uri_detail_list: 0.11 (0.0%), tests_pri_-1000: 3.0 (0.1%), tests_pri_-950: 0.92 (0.0%), tests_pri_-900: 0.91 (0.0%), tests_pri_-400: 0.67 (0.0%), tests_pri_0: 1181 (29.8%), check_dkim_signature: 0.79 (0.0%), check_spf: 332 (8.4%), poll_dns_idle: 2997 (75.7%), check_razor2: 802 (20.3%), check_pyzor: 0.22 (0.0%), tests_pri_500: 2717 (68.7%), get_report: 0.26 (0.0%)
Apr 15 18:36:12 mail amavis[15846]: (15846-01) size: 201, TIMING [total 4332 ms] - SMTP greeting: 9 (0%)0, SMTP EHLO: 1.0 (0%)0, SMTP pre-MAIL: 0.6 (0%)0, mkdir tempdir: 1.2 (0%)0, create email.txt: 0.3 (0%)0, SMTP MAIL: 1.0 (0%)0, SMTP pre-DATA-flush: 12 (0%)1, SMTP DATA: 0.9 (0%)1, check_init: 0.6 (0%)1, digest_hdr: 0.5 (0%)1, digest_body: 0.2 (0%)1, collect_info: 1.4 (0%)1, mkdir parts: 1.3 (0%)1, mime_decode: 13 (0%)1, get-file-type0: 0.3 (0%)1, parts_decode: 0.0 (0%)1, check_header: 0.5 (0%)1, AV-scan-1: 115 (3%)4, spam-wb-list: 0.5 (0%)4, SA msg read: 0.4 (0%)4, SA parse: 2.7 (0%)4, SA check: 3954 (91%)95, decide_mail_destiny: 7 (0%)95, notif-quar: 0.4 (0%)95, quar-hdrs: 1.0 (0%)95, save-to-local-mailbox: 1.2 (0%)95, fwd-connect: 48 (1%)96, fwd-xforward: 0.4 (0%)96, fwd-mail-pip: 8 (0%)97, fwd-rcpt-pip: 0.2 (0%)97, fwd-data-chkpnt: 0.0 (0%)97, write-header: 0.2 (0%)97, fwd-data-contents: 0.0 (0%)97, fwd-end-chkpnt: 144 (3%)100, prepare-dsn: 0.7 (0%)100, report: 1.2 (0%)100, main_log_entry: 3....
Apr 15 18:36:12 mail amavis[15846]: (15846-01) ...2 (0%)100, SMTP pre-response: 0.1 (0%)100, SMTP response: 0.1 (0%)100, unlink-1-files: 0.2 (0%)100, rundown: 0.3 (0%)100
Apr 15 18:36:12 mail postfix/smtpd[26489]: proxy-accept: END-OF-MESSAGE: 250 2.0.0 from MTA(smtp:[127.0.0.1]:10025): 250 2.0.0 Ok: queued as 6C2E3F05; from=<toto@titi.fr> to=<technical@lists.YYYYYYYY> proto=ESMTP helo=<calzi.fr>
Apr 15 18:36:12 mail amavis[15846]: (15846-01) extra modules loaded: unicore/lib/Gc/Nd.pl
Apr 15 18:36:13 mail postfix/pipe[26542]: 6C2E3F05: to=<technical@lists.YYYYYYYY>, relay=sympa, delay=1.2, delays=0.15/0.03/0/1, dsn=2.0.0, status=sent (delivered via sympa service)
Apr 15 18:36:13 mail postfix/qmgr[2061]: 6C2E3F05: removed

==> /var/log/messages <==
Apr 15 18:36:15 mail sympa_msg[4164]: err main::#243 > Sympa::Spindle::spin#83 > Sympa::Spindle::ProcessIncoming::_twist#108 Message Sympa::Message <technical@lists.YYYYYYYY.1523810172.26543> has no message ID

I think that the last log its the key. I might have something wrong with the postfix and and read again the documentation part about «Virtual Domain» with Postfix and i can't see what i'm missing at the moment.

Anyway, thanks for your feedback.

@ikedas
Copy link
Member

ikedas commented Apr 16, 2018

Hi @Zwordi,

Additionally, can you show the logs by Sympa during reception of an email? According to sympa.conf, they are issued with LOCAL1 syslog facility.

@Zwordi
Copy link
Author

Zwordi commented Apr 16, 2018

Hello @ikedas

There is my sympa.log during the reception of an email.

Apr 16 09:23:43 mail task_manager[3454]: debug3 main:: Processing of tasks anterior to the current date
Apr 16 09:23:43 mail task_manager[3454]: debug Sympa::Task::get_task_list() Getting tasks list
Apr 16 09:23:43 mail task_manager[3454]: debug3 main:: Procesing /var/spool/sympa/task/1523869400.ACTION.process_bouncers._global
Apr 16 09:24:43 mail task_manager[3454]: debug Sympa::Task::list_tasks() Listing all tasks
Apr 16 09:24:43 mail task_manager[3454]: debug2 Sympa::Task::new(/var/spool/sympa/task/1523869400.ACTION.process_bouncers._global)
---
Apr 16 09:24:43 mail task_manager[3454]: debug Sympa::Task::get_used_models() Getting used models for list ""
Apr 16 09:24:43 mail task_manager[3454]: debug2 Sympa::List::get_robots()
Apr 16 09:24:43 mail task_manager[3454]: debug2 Sympa::List::get_lists(lists.YYYYYYYYYY, )
Apr 16 09:24:43 mail task_manager[3454]: debug3 Sympa::List::get_lists() filter ;
Apr 16 09:24:43 mail task_manager[3454]: debug3 Sympa::List::get_lists() order ; name_list
Apr 16 09:24:43 mail task_manager[3454]: debug2 Sympa::List::new(technical, lists.YYYYYYYYYY, skip_sync_admin/skip_name_check)
Apr 16 09:24:43 mail task_manager[3454]: debug2 Sympa::List::load(Sympa::List <technical@lists.YYYYYYYYYY>, technical, lists.YYYYYYYYYY, ...)
Apr 16 09:24:43 mail task_manager[3454]: debug Sympa::Task::get_used_models() Getting used models for list "technical@lists.YYYYYYYYYY"
Apr 16 09:24:43 mail task_manager[3454]: debug2 Sympa::Task::get_used_models() Did not find any used models for list "technical@lists.YYYYYYYYYY"
Apr 16 09:24:43 mail task_manager[3454]: debug Sympa::Task::list_tasks() Listing all tasks
Apr 16 09:24:43 mail task_manager[3454]: debug2 Sympa::Task::new(/var/spool/sympa/task/1523869400.ACTION.process_bouncers._global)
---
Apr 16 09:24:43 mail task_manager[3454]: debug3 main:: Processing of tasks anterior to the current date
Apr 16 09:24:43 mail task_manager[3454]: debug Sympa::Task::get_task_list() Getting tasks list
Apr 16 09:24:43 mail task_manager[3454]: debug3 main:: Procesing /var/spool/sympa/task/1523869400.ACTION.process_bouncers._global
Apr 16 09:25:43 mail task_manager[3454]: debug Sympa::Task::list_tasks() Listing all tasks
Apr 16 09:25:43 mail task_manager[3454]: debug2 Sympa::Task::new(/var/spool/sympa/task/1523869400.ACTION.process_bouncers._global)
---
Apr 16 09:25:43 mail task_manager[3454]: debug Sympa::Task::get_used_models() Getting used models for list ""
Apr 16 09:25:43 mail task_manager[3454]: debug2 Sympa::List::get_robots()
Apr 16 09:25:43 mail task_manager[3454]: debug2 Sympa::List::get_lists(lists.YYYYYYYYYY, )
Apr 16 09:25:43 mail task_manager[3454]: debug3 Sympa::List::get_lists() filter ;
Apr 16 09:25:43 mail task_manager[3454]: debug3 Sympa::List::get_lists() order ; name_list
Apr 16 09:25:43 mail task_manager[3454]: debug2 Sympa::List::new(technical, lists.YYYYYYYYYY, skip_sync_admin/skip_name_check)
Apr 16 09:25:43 mail task_manager[3454]: debug2 Sympa::List::load(Sympa::List <technical@lists.YYYYYYYYYY>, technical, lists.YYYYYYYYYY, ...)
Apr 16 09:25:43 mail task_manager[3454]: debug Sympa::Task::get_used_models() Getting used models for list "technical@lists.YYYYYYYYYY"
Apr 16 09:25:43 mail task_manager[3454]: debug2 Sympa::Task::get_used_models() Did not find any used models for list "technical@lists.YYYYYYYYYY"
Apr 16 09:25:43 mail task_manager[3454]: debug Sympa::Task::list_tasks() Listing all tasks
Apr 16 09:25:43 mail task_manager[3454]: debug2 Sympa::Task::new(/var/spool/sympa/task/1523869400.ACTION.process_bouncers._global)
---
Apr 16 09:25:43 mail task_manager[3454]: debug3 main:: Processing of tasks anterior to the current date
Apr 16 09:25:43 mail task_manager[3454]: debug Sympa::Task::get_task_list() Getting tasks list
Apr 16 09:25:43 mail task_manager[3454]: debug3 main:: Procesing /var/spool/sympa/task/1523869400.ACTION.process_bouncers._global
Apr 16 09:26:40 mail sympa_msg[4164]: debug3 Sympa::Spool::unmarshal_metadata(/var/spool/sympa/msg, technical@lists.YYYYYYYYYY.1523863599.12613, qr<(?^:\A([^\s\@]+)(?:\@([\w\.\-]+))?\.(\d+)(?:,.*)?\z)>)
Apr 16 09:26:40 mail sympa_msg[4164]: debug2 Sympa::List::new(technical, lists.YYYYYYYYYY, just_try)
Apr 16 09:26:40 mail sympa_msg[4164]: debug2 Sympa::List::load(Sympa::List <technical@lists.YYYYYYYYYY>, technical, lists.YYYYYYYYYY, ...)
Apr 16 09:26:40 mail sympa_msg[4164]: debug3 Sympa::Spool::unmarshal_metadata() messagekey=technical@lists.YYYYYYYYYY.1523863599.12613, context=Sympa::List <technical@lists.YYYYYYYYYYrity=5
Apr 16 09:26:40 mail sympa_msg[4164]: debug2 Sympa::Message::new(Sympa::Message, ...)

Apr 16 09:26:40 mail sympa_msg[4164]: err main::#243 > Sympa::Spindle::spin#83 > Sympa::Spindle::ProcessIncoming::_twist#108 Message Sympa::Message <technical@lists.YYYYYYYYYY.1523863599.1as no message ID

Apr 16 09:26:40 mail sympa_msg[4164]: debug3 Sympa::Database::do_prepared_query() Will perform query "INSERT INTO logs_table (date_logs, usec_logs, robot_logs, list_logs, action_logs, parameter target_email_logs, msg_id_logs, status_logs, error_type_logs, user_email_logs, client_logs, daemon_logs) VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?)"
Apr 16 09:26:40 mail sympa_msg[4164]: debug3 Sympa::Database::connect(Sympa::DatabaseDriver::MySQL <db_host=localhost;db_name=sympa;db_port=3306;db_user=sympadmin>)
Apr 16 09:26:40 mail sympa_msg[4164]: debug2 Sympa::Database::connect() Connected to Database Sympa::DatabaseDriver::MySQL <db_host=localhost;db_name=sympa;db_port=3306;db_user=sympadmin>
Apr 16 09:26:43 mail task_manager[3454]: debug Sympa::Task::list_tasks() Listing all tasks
Apr 16 09:26:43 mail task_manager[3454]: debug2 Sympa::Task::new(/var/spool/sympa/task/1523869400.ACTION.process_bouncers._global)
---
Apr 16 09:26:43 mail task_manager[3454]: debug Sympa::Task::get_used_models() Getting used models for list ""
Apr 16 09:26:43 mail task_manager[3454]: debug2 Sympa::List::get_robots()
Apr 16 09:26:43 mail task_manager[3454]: debug2 Sympa::List::get_lists(lists.YYYYYYYYYY, )
Apr 16 09:26:43 mail task_manager[3454]: debug3 Sympa::List::get_lists() filter ;
Apr 16 09:26:43 mail task_manager[3454]: debug3 Sympa::List::get_lists() order ; name_list
Apr 16 09:26:43 mail task_manager[3454]: debug2 Sympa::List::new(technical, lists.YYYYYYYYYY, skip_sync_admin/skip_name_check)
Apr 16 09:26:43 mail task_manager[3454]: debug2 Sympa::List::load(Sympa::List <technical@lists.YYYYYYYYYY>, technical, lists.YYYYYYYYYY, ...)
Apr 16 09:26:43 mail task_manager[3454]: debug Sympa::Task::get_used_models() Getting used models for list "technical@lists.YYYYYYYYYY"
Apr 16 09:26:43 mail task_manager[3454]: debug2 Sympa::Task::get_used_models() Did not find any used models for list "technical@lists.YYYYYYYYYY"
Apr 16 09:26:43 mail task_manager[3454]: debug Sympa::Task::list_tasks() Listing all tasks
Apr 16 09:26:43 mail task_manager[3454]: debug2 Sympa::Task::new(/var/spool/sympa/task/1523869400.ACTION.process_bouncers._global)
---
Apr 16 09:26:43 mail task_manager[3454]: debug3 main:: Processing of tasks anterior to the current date
Apr 16 09:26:43 mail task_manager[3454]: debug Sympa::Task::get_task_list() Getting tasks list
Apr 16 09:26:43 mail task_manager[3454]: debug3 main:: Procesing /var/spool/sympa/task/1523869400.ACTION.process_bouncers._global
Apr 16 09:27:43 mail task_manager[3454]: debug Sympa::Task::list_tasks() Listing all tasks
Apr 16 09:27:43 mail task_manager[3454]: debug2 Sympa::Task::new(/var/spool/sympa/task/1523869400.ACTION.process_bouncers._global)
---
Apr 16 09:27:43 mail task_manager[3454]: debug2 Sympa::Task::new(/var/spool/sympa/task/1525856600.ACTION.purge_user_table._global)
Apr 16 09:27:43 mail task_manager[3454]: debug Sympa::Task::get_used_models() Getting used models for list ""
Apr 16 09:27:43 mail task_manager[3454]: debug2 Sympa::List::get_robots()
Apr 16 09:27:43 mail task_manager[3454]: debug2 Sympa::List::get_lists(lists.YYYYYYYYYY, )
Apr 16 09:27:43 mail task_manager[3454]: debug3 Sympa::List::get_lists() filter ;
Apr 16 09:27:43 mail task_manager[3454]: debug3 Sympa::List::get_lists() order ; name_list
Apr 16 09:27:43 mail task_manager[3454]: debug2 Sympa::List::new(technical, lists.YYYYYYYYYY, skip_sync_admin/skip_name_check)
Apr 16 09:27:43 mail task_manager[3454]: debug2 Sympa::List::load(Sympa::List <technical@lists.YYYYYYYYYY>, technical, lists.YYYYYYYYYY, ...)
Apr 16 09:27:43 mail task_manager[3454]: debug Sympa::Task::get_used_models() Getting used models for list "technical@lists.YYYYYYYYYY"
Apr 16 09:27:43 mail task_manager[3454]: debug2 Sympa::Task::get_used_models() Did not find any used models for list "technical@lists.YYYYYYYYYY"
Apr 16 09:27:43 mail task_manager[3454]: debug Sympa::Task::list_tasks() Listing all tasks
Apr 16 09:27:43 mail task_manager[3454]: debug2 Sympa::Task::new(/var/spool/sympa
---
Apr 16 09:27:43 mail task_manager[3454]: debug3 main:: Processing of tasks anterior to the current date
Apr 16 09:27:43 mail task_manager[3454]: debug Sympa::Task::get_task_list() Getting tasks list
Apr 16 09:27:43 mail task_manager[3454]: debug3 main:: Procesing /var/spool/sympa/task/1523869400.ACTION.process_bouncers._global
Apr 16 09:28:43 mail task_manager[3454]: debug Sympa::Task::list_tasks() Listing all tasks
Apr 16 09:28:43 mail task_manager[3454]: debug2 Sympa::Task::new(/var/spool/sympa/task/1523869400.ACTION.process_bouncers._global)
---
Apr 16 09:28:43 mail task_manager[3454]: debug Sympa::Task::get_used_models() Getting used models for list ""
Apr 16 09:28:43 mail task_manager[3454]: debug2 Sympa::List::get_robots()
Apr 16 09:28:43 mail task_manager[3454]: debug2 Sympa::List::get_lists(lists.YYYYYYYYYY, )
Apr 16 09:28:43 mail task_manager[3454]: debug3 Sympa::List::get_lists() filter ;
Apr 16 09:28:43 mail task_manager[3454]: debug3 Sympa::List::get_lists() order ; name_list
Apr 16 09:28:43 mail task_manager[3454]: debug2 Sympa::List::new(technical, lists.YYYYYYYYYY, skip_sync_admin/skip_name_check)
Apr 16 09:28:43 mail task_manager[3454]: debug2 Sympa::List::load(Sympa::List <technical@lists.YYYYYYYYYY>, technical, lists.YYYYYYYYYY, ...)
Apr 16 09:28:43 mail task_manager[3454]: debug Sympa::Task::get_used_models() Getting used models for list "technical@lists.YYYYYYYYYY"
Apr 16 09:28:43 mail task_manager[3454]: debug2 Sympa::Task::get_used_models() Did not find any used models for list "technical@lists.YYYYYYYYYY"
Apr 16 09:28:43 mail task_manager[3454]: debug Sympa::Task::list_tasks() Listing all tasks
Apr 16 09:28:43 mail task_manager[3454]: debug2 Sympa::Task::new(/var/spool/sympa
---
Apr 16 09:28:43 mail task_manager[3454]: debug3 main:: Processing of tasks anterior to the current date
Apr 16 09:28:43 mail task_manager[3454]: debug Sympa::Task::get_task_list() Getting tasks list
Apr 16 09:28:43 mail task_manager[3454]: debug3 main:: Procesing /var/spool/sympa/task/1523869400.ACTION.process_bouncers._global

And again the same logs from messages:

Apr 16 09:26:40 mail sympa_msg[4164]: err main::#243 > Sympa::Spindle::spin#83 > Sympa::Spindle::ProcessIncoming::_twist#108 Message Sympa::Message <technical@lists.libre-cloud.org.1523863599.1
2613> has no message ID

Many thanks for your help.

@ikedas
Copy link
Member

ikedas commented Apr 16, 2018

As a feature, Sympa quarantines incoming messages with no Message-ID: field (quarantined messages are moved into bad subdirectory of msg spool). Can you please send message with appropriate Message-ID: field?

@Zwordi
Copy link
Author

Zwordi commented Apr 16, 2018

OK,
So it's a dummy issue as it's related to the fact that i'm using simple telnet smtp commands to send an email.
Sorry for this dummy question but at least it might be helpfull for other guys. ( I might pull an update to the documentation once i will get it working as testing with telnet is a classic behavior with mail).

@Zwordi
Copy link
Author

Zwordi commented Apr 16, 2018

I got an email working and listing into the archives section so let's update the documentation with an telnet example.
Thanks a lot @ikedas for your help.

@sanstepan
Copy link

As a feature, Sympa quarantines incoming messages with no Message-ID: field (quarantined messages are moved into bad subdirectory of msg spool). Can you please send message with appropriate Message-ID: field?

I wonder if this behaviour can be changed.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
Projects
None yet
Development

No branches or pull requests

3 participants