Page 1 of 2

Sending mails failed

Posted: Thu Aug 10, 2006 1:01 pm
by kd
Hi,

I have installed openemm at a gentoo system (OpenEMM-5.0.binary.tar.gz, Gentoo default-linux/x86/2006.0).

All works fine expect of sending mails to mailing lists. It's possible to send messages to the administrator/test group, but sending mails to other mailing lists failed without any error message. After clicking at the send button, the distribution status shows "This mailing is beeing sent at the moment", but the mail never arrives. But the xml version of this message is stored in /var/spool/META:

Code: Select all

...
AgnMail=D20060810114745=1=35=002=liaMngA.final
AgnMail=D20060810114745=1=35=002=liaMngA.stamp
AgnMail=D20060810114745=1=35=002=liaMngA.xml.gz

The processlist:

Code: Select all

$ ps ax -o args
python /opt/openemm/bin/scripts/bav-trigger.py
/opt/openemm/bin/bav -L INFO
sendmail: accepting connections
sendmail: Queue runner@00:05:00 for /var/spool/clientmqueue     
sendmail: Queue runner@00:01:00 for /opt/openemm/var/spool/ADMIN               
sendmail: Queue runner@00:15:00 for /opt/openemm/var/spool/QUEUE                
python /opt/openemm/bin/scripts/slrtscn.py
perl /opt/openemm/bin/wrapper.pl -chdir -name httpd ...
/opt/sun-jdk-1.5.0.07/bin/java -Xss1m -Dresin.home=...

console_stdout.log after sending to the administrator group (this mail arrives):

Code: Select all

...
Init mailing backend store proc. done
Update log done at message no:0
Start creation of mails
DB-Exec: SELECT cust.customer_id,cust.mailtype,cust.customer_id,cust.email FROM customer_1_tbl cust, customer_1_binding_tbl bind WHERE bind.customer_id = cust.customer
_id AND (bind.mailinglist_id = 1 AND (bind.user_status = 1 AND bind.user_type = 'A'))
Start block 1 using blocksize 0
Mailtype/Number/Bytes:
        0/0
        1/0
        2/0
Currently at 0 mails (in block 1: 0) 
Update log done at message no:0
End block 1
Skip validation of XML document
DB-Updt: UPDATE mailing_backend_log_tbl SET current_mails = 1, total_mails = 1 WHERE status_id = 33
Final update backend_log done.
Total mail message written: 1
Mailtype 0: 1 message
Mailtype 1: 0 messages
Mailtype 2: 0 messages
Try to execute /home/openemm/bin/xmlback -vql -E /tmp/error34227.tmp -o generate:media=email;temporary=true;syslog=true;path=/home/openemm/var/spool/ADMIN /home/openem
m/var/spool/META/AgnMail=D20060810101437=1=32=00001=liaMngA.xml
DB-Updt: INSERT INTO mailtrack_tbl (company_id, status_id, mailing_id, customer_id) SELECT 1, 33, 32, cust.customer_id FROM customer_1_tbl cust, customer_1_binding_tbl
 bind WHERE bind.customer_id = cust.customer_id AND (bind.mailinglist_id = 1 AND (bind.user_status = 1 AND bind.user_type = 'A'))
DB-Updt: UPDATE maildrop_status_tbl SET genchange = now(), genstatus = 3 WHERE status_id = 33 AND genstatus = 2
Successful end
DB-Exec: SELECT 1 FROM DUAL
DB-Exec: SELECT date_format(now(), '%Y-%m-%d') FROM dual
DB-Exec: SELECT mailing_id, date_format(lastsent, '%Y-%m-%d') FROM rulebased_sent_tbl
DB-Exec: SELECT status_id, mailing_id FROM maildrop_status_tbl WHERE status_field = 'R' AND genstatus = 1 AND date_format(senddate, '%H') = '10'
Read 0 maildrop entries
DB-Exec: SELECT 1 FROM DUAL
DB-Exec: SELECT status_id, mailing_id FROM maildrop_status_tbl WHERE genstatus = 0 AND status_field = 'W' AND gendate < now() ORDER BY gendate
console_stdout.log after sending to a mailing list (this mails don't arrive):

Code: Select all

...
Init mailing backend store proc. done
Update log done at message no:0
Start creation of mails
DB-Exec: SELECT cust.customer_id,cust.mailtype,cust.customer_id,cust.email FROM customer_1_tbl cust, customer_1_binding_tbl bind WHERE bind.customer_id = cust.customer
_id AND (bind.mailinglist_id = 1 AND (bind.user_status = 1 AND bind.user_type IN ('A', 'T')))
Start block 1 using blocksize 5000
Mailtype/Number/Bytes:
        0/0
        1/0
        2/0
Currently at 0 mails (in block 1: 0) 
Update log done at message no:0
End block 1
Skip validation of XML document
Start block 2 using blocksize 5000
Mailtype/Number/Bytes:
        0/1
        1/0
        2/0
DB-Exec: SELECT cust.customer_id,cust.mailtype,cust.customer_id,cust.email FROM customer_1_tbl cust, customer_1_binding_tbl bind WHERE bind.customer_id = cust.customer
_id AND (bind.mailinglist_id = 1 AND (bind.user_status = 1 AND bind.user_type = 'W'))
End block 2
Skip validation of XML document
DB-Updt: UPDATE mailing_backend_log_tbl SET current_mails = 2, total_mails = 2 WHERE status_id = 34
Final update backend_log done.
Total mail messages written: 2
Mailtype 0: 2 messages
Mailtype 1: 0 messages
Mailtype 2: 0 messages
DB-Updt: INSERT INTO mailtrack_tbl (company_id, status_id, mailing_id, customer_id) SELECT 1, 34, 33, cust.customer_id FROM customer_1_tbl cust, customer_1_binding_tbl
 bind WHERE bind.customer_id = cust.customer_id AND (bind.mailinglist_id = 1 AND (bind.user_status = 1))
DB-Updt: UPDATE maildrop_status_tbl SET genchange = now(), genstatus = 3 WHERE status_id = 34 AND genstatus = 2
Successful end
Hibernate: select mailing0_.mailing_id as mailing1_9_, mailing0_.company_id as company2_9_, mailing0_.campaign_id as campaign3_9_, mailing0_.shortname as shortname9_, 
mailing0_.description as descript5_9_, mailing0_.mailing_type as mailing6_9_, mailing0_.is_template as is7_9_, mailing0_.needs_target as needs8_9_, mailing0_.mailtempl
ate_id as mailtemp9_9_, mailing0_.mailinglist_id as mailing10_9_, mailing0_.deleted as deleted9_, mailing0_.target_expression as target12_9_, mailing0_.creation_date a
s creation13_9_ from mailing_tbl mailing0_ where mailing0_.mailing_id=? and mailing0_.company_id=? and mailing0_.deleted<>1
Hibernate: select target0_.target_id as target1_0_, target0_.company_id as company2_0_, target0_.target_description as target3_0_, target0_.target_shortname as target4
_0_, target0_.target_representation as target5_0_, target0_.target_sql as target6_0_ from dyn_target_tbl target0_ where target0_.company_id=?
DB-Exec: SELECT 1 FROM DUAL
DB-Exec: SELECT status_id, mailing_id FROM maildrop_status_tbl WHERE genstatus = 0 AND status_field = 'W' AND gendate < now() ORDER BY gendate

Only the "administrator"-log shows "Try to execute /home/openemm/bin/xmlback ...". Is xmlback responsible for sending mails?

It would be very nice, if someone could explain some details about the sending process (involved queues, processes, files, programs, ...).

Thanks for your help,
Simon

Posted: Wed Aug 16, 2006 12:56 am
by sfantul
I'm having exactly the same problems on Ubuntu so does everybody have any idea on what can be done?

Posted: Thu Aug 17, 2006 10:03 am
by ud
Looking at the logfiles shows a missing package for proper operation. The mysql database module for python is missing. The gentoo package name is "dev-python/mysql-python."

-- ud

Solution ?

Posted: Tue Aug 29, 2006 8:35 am
by olivier.r
I have exactly the same problem and my python-mysql package seems to be install properly ... anyone have an idea ?

Posted: Tue Aug 29, 2006 8:40 am
by ud
Can you post the content of your ~openemm/var/log/<current>-<hostname>-pickdist.log, please?

-- ud

Posted: Tue Aug 29, 2006 8:57 am
by olivier.r

Code: Select all

[openemm@ci log]$ cat 20060829-ci-pickdist.log
[29.08.2006  09:43:44] 3594 INFO/main: Starting up
[29.08.2006  09:43:45] 3594 INFO/block: Unpacking /home/openemm/var/spool/META/AgnMail=D20060828153659=1=2=001=liaMngA.xml.gz
[29.08.2006  09:43:45] 3594 VERBOSE/block: Removing stamp file /home/openemm/var/spool/META/AgnMail=D20060828153659=1=2=001=liaMngA.stamp
[29.08.2006  09:43:45] 3594 DEBUG/block: Calling xmlback '-vlogenerate:media=email;path=/home/openemm/var/spool/QUEUE' '/home/openemm/var/spool/META/AgnMail=D20060828153659=1=2=001=liaMngA.xml.gz'
[29.08.2006  09:43:45] 3594 DEBUG/block: Moved /home/openemm/var/spool/META/AgnMail=D20060828153659=1=2=001=liaMngA.xml.gz to /home/openemm/var/spool/ARCHIVE/20060829
[29.08.2006  09:43:45] 3594 INFO/block: Unpacking /home/openemm/var/spool/META/AgnMail=D20060828155057=1=3=001=liaMngA.xml.gz
[29.08.2006  09:43:45] 3594 VERBOSE/block: Removing stamp file /home/openemm/var/spool/META/AgnMail=D20060828155057=1=3=001=liaMngA.stamp
[29.08.2006  09:43:45] 3594 DEBUG/block: Calling xmlback '-vlogenerate:media=email;path=/home/openemm/var/spool/QUEUE' '/home/openemm/var/spool/META/AgnMail=D20060828155057=1=3=001=liaMngA.xml.gz'
[29.08.2006  09:43:45] 3594 DEBUG/block: Moved /home/openemm/var/spool/META/AgnMail=D20060828155057=1=3=001=liaMngA.xml.gz to /home/openemm/var/spool/ARCHIVE/20060829
[29.08.2006  09:43:45] 3594 INFO/block: Unpacking /home/openemm/var/spool/META/AgnMail=D20060828161057=1=4=001=liaMngA.xml.gz
[29.08.2006  09:43:45] 3594 VERBOSE/block: Removing stamp file /home/openemm/var/spool/META/AgnMail=D20060828161057=1=4=001=liaMngA.stamp
[29.08.2006  09:43:45] 3594 DEBUG/block: Calling xmlback '-vlogenerate:media=email;path=/home/openemm/var/spool/QUEUE' '/home/openemm/var/spool/META/AgnMail=D20060828161057=1=4=001=liaMngA.xml.gz'
[29.08.2006  09:43:45] 3594 DEBUG/block: Moved /home/openemm/var/spool/META/AgnMail=D20060828161057=1=4=001=liaMngA.xml.gz to /home/openemm/var/spool/ARCHIVE/20060829
[29.08.2006  09:43:45] 3594 INFO/block: Unpacking /home/openemm/var/spool/META/AgnMail=D20060828162550=1=5=001=liaMngA.xml.gz
[29.08.2006  09:43:45] 3594 VERBOSE/block: Removing stamp file /home/openemm/var/spool/META/AgnMail=D20060828162550=1=5=001=liaMngA.stamp
[29.08.2006  09:43:45] 3594 DEBUG/block: Calling xmlback '-vlogenerate:media=email;path=/home/openemm/var/spool/QUEUE' '/home/openemm/var/spool/META/AgnMail=D20060828162550=1=5=001=liaMngA.xml.gz'
[29.08.2006  09:43:46] 3594 DEBUG/block: Moved /home/openemm/var/spool/META/AgnMail=D20060828162550=1=5=001=liaMngA.xml.gz to /home/openemm/var/spool/ARCHIVE/20060829
[29.08.2006  09:43:46] 3594 INFO/block: Unpacking /home/openemm/var/spool/META/AgnMail=D20060828163546=1=6=001=liaMngA.xml.gz
[29.08.2006  09:43:46] 3594 VERBOSE/block: Removing stamp file /home/openemm/var/spool/META/AgnMail=D20060828163546=1=6=001=liaMngA.stamp
[29.08.2006  09:43:46] 3594 DEBUG/block: Calling xmlback '-vlogenerate:media=email;path=/home/openemm/var/spool/QUEUE' '/home/openemm/var/spool/META/AgnMail=D20060828163546=1=6=001=liaMngA.xml.gz'
[29.08.2006  09:43:46] 3594 DEBUG/block: Moved /home/openemm/var/spool/META/AgnMail=D20060828163546=1=6=001=liaMngA.xml.gz to /home/openemm/var/spool/ARCHIVE/20060829
[29.08.2006  09:43:46] 3594 INFO/block: Unpacking /home/openemm/var/spool/META/AgnMail=D20060828164729=1=7=001=liaMngA.xml.gz
[29.08.2006  09:43:46] 3594 VERBOSE/block: Removing stamp file /home/openemm/var/spool/META/AgnMail=D20060828164729=1=7=001=liaMngA.stamp
[29.08.2006  09:43:46] 3594 DEBUG/block: Calling xmlback '-vlogenerate:media=email;path=/home/openemm/var/spool/QUEUE' '/home/openemm/var/spool/META/AgnMail=D20060828164729=1=7=001=liaMngA.xml.gz'
[29.08.2006  09:43:46] 3594 DEBUG/block: Moved /home/openemm/var/spool/META/AgnMail=D20060828164729=1=7=001=liaMngA.xml.gz to /home/openemm/var/spool/ARCHIVE/20060829
[29.08.2006  09:43:46] 3594 INFO/block: Unpacking /home/openemm/var/spool/META/AgnMail=D20060828165653=1=8=001=liaMngA.xml.gz
[29.08.2006  09:43:46] 3594 VERBOSE/block: Removing stamp file /home/openemm/var/spool/META/AgnMail=D20060828165653=1=8=001=liaMngA.stamp
[29.08.2006  09:43:46] 3594 DEBUG/block: Calling xmlback '-vlogenerate:media=email;path=/home/openemm/var/spool/QUEUE' '/home/openemm/var/spool/META/AgnMail=D20060828165653=1=8=001=liaMngA.xml.gz'
[29.08.2006  09:43:46] 3594 DEBUG/block: Moved /home/openemm/var/spool/META/AgnMail=D20060828165653=1=8=001=liaMngA.xml.gz to /home/openemm/var/spool/ARCHIVE/20060829
[29.08.2006  09:43:46] 3594 INFO/block: Unpacking /home/openemm/var/spool/META/AgnMail=D20060828170545=1=9=001=liaMngA.xml.gz
[29.08.2006  09:43:46] 3594 VERBOSE/block: Removing stamp file /home/openemm/var/spool/META/AgnMail=D20060828170545=1=9=001=liaMngA.stamp
[29.08.2006  09:43:46] 3594 DEBUG/block: Calling xmlback '-vlogenerate:media=email;path=/home/openemm/var/spool/QUEUE' '/home/openemm/var/spool/META/AgnMail=D20060828170545=1=9=001=liaMngA.xml.gz'
[29.08.2006  09:43:46] 3594 DEBUG/block: Moved /home/openemm/var/spool/META/AgnMail=D20060828170545=1=9=001=liaMngA.xml.gz to /home/openemm/var/spool/ARCHIVE/20060829
[29.08.2006  09:43:47] 3594 DEBUG/block: Moved /home/openemm/var/spool/META/AgnMail=D20060828164729=1=7=001=liaMngA.final to /home/openemm/var/spool/ARCHIVE/20060829
[29.08.2006  09:43:47] 3594 DEBUG/block: Moved /home/openemm/var/spool/META/AgnMail=D20060828153659=1=2=001=liaMngA.final to /home/openemm/var/spool/ARCHIVE/20060829
[29.08.2006  09:43:47] 3594 DEBUG/block: Moved /home/openemm/var/spool/META/AgnMail=D20060828161057=1=4=001=liaMngA.final to /home/openemm/var/spool/ARCHIVE/20060829
[29.08.2006  09:43:47] 3594 DEBUG/block: Moved /home/openemm/var/spool/META/AgnMail=D20060828170545=1=9=001=liaMngA.final to /home/openemm/var/spool/ARCHIVE/20060829
[29.08.2006  09:43:47] 3594 DEBUG/block: Moved /home/openemm/var/spool/META/AgnMail=D20060828163546=1=6=001=liaMngA.final to /home/openemm/var/spool/ARCHIVE/20060829
[29.08.2006  09:43:47] 3594 DEBUG/block: Moved /home/openemm/var/spool/META/AgnMail=D20060828165653=1=8=001=liaMngA.final to /home/openemm/var/spool/ARCHIVE/20060829
[29.08.2006  09:43:47] 3594 DEBUG/block: Moved /home/openemm/var/spool/META/AgnMail=D20060828155057=1=3=001=liaMngA.final to /home/openemm/var/spool/ARCHIVE/20060829
[29.08.2006  09:43:47] 3594 DEBUG/block: Moved /home/openemm/var/spool/META/AgnMail=D20060828162550=1=5=001=liaMngA.final to /home/openemm/var/spool/ARCHIVE/20060829
[29.08.2006  09:43:47] 3594 VERBOSE/loop: No ready to send data file found
[29.08.2006  09:44:18] 3594 VERBOSE/loop: No ready to send data file found
[29.08.2006  09:44:49] 3594 VERBOSE/loop: No ready to send data file found
[29.08.2006  09:45:20] 3594 VERBOSE/loop: No ready to send data file found
[29.08.2006  09:45:51] 3594 VERBOSE/loop: No ready to send data file found
[29.08.2006  09:46:22] 3594 VERBOSE/loop: No ready to send data file found
[29.08.2006  09:46:53] 3594 VERBOSE/loop: No ready to send data file found
[29.08.2006  09:47:24] 3594 VERBOSE/loop: No ready to send data file found
[29.08.2006  09:47:55] 3594 VERBOSE/loop: No ready to send data file found
[29.08.2006  09:48:26] 3594 VERBOSE/loop: No ready to send data file found
[29.08.2006  09:48:57] 3594 VERBOSE/loop: No ready to send data file found
[29.08.2006  09:49:28] 3594 VERBOSE/loop: No ready to send data file found
[29.08.2006  09:49:59] 3594 VERBOSE/loop: No ready to send data file found
[29.08.2006  09:50:30] 3594 VERBOSE/loop: No ready to send data file found
[29.08.2006  09:51:01] 3594 VERBOSE/loop: No ready to send data file found
[29.08.2006  09:51:32] 3594 VERBOSE/loop: No ready to send data file found
[29.08.2006  09:52:03] 3594 VERBOSE/loop: No ready to send data file found
[29.08.2006  09:52:34] 3594 VERBOSE/loop: No ready to send data file found


Posted: Tue Aug 29, 2006 9:03 am
by ud
Your Mysql installation is working, the next possible point of failure is the sending process, sendmail. So you can look into ~openemm/var/spool/QUEUE and glance through the control files (qf*) and also have a look at /var/log/maillog for possible reasons.

-- ud

Maillog ...and spool/QUEUE

Posted: Tue Aug 29, 2006 9:17 am
by olivier.r
I have no file in spool/QUEUE ....

Code: Select all

[openemm@ci log]$ cd ../spool/QUEUE/
[openemm@ci QUEUE]$ ll
total 0
When I send a message with test user I have some information in /var/log/maillog :

Code: Select all

Aug 29 10:10:58 ci xmlback[4421]: mail creation: 0;1;1;11;12;T;1;0;0;1;527
Aug 29 10:11:45 ci sendmail[4432]: 1145Td04a00000001: to=<revollat>, ctladdr=<openemm> (502/502), delay=00:00:47, xdelay=00:00:00, mailer=relay, pri=0, relay=helix.cornut.fr [193.108.197.230], dsn=2.0.0, stat=Sent (OK id=1GHykV-0007ki-Pz)
But when I try to send mailing to "normal users" ... nothing is happening in maillog ... it seems that OpenEMM don't try to connect on the smtp server ...[/quote]

Mails not sent

Posted: Sun Dec 24, 2006 1:43 pm
by trax
Could anyone solve this problem?

Administrator und Test Mails get out.
But no normal E-Mails.

My pickdist.log:
[19.12.2006 19:28:51] 24039 FATAL/except: CAUGHT EXCEPTION:
Traceback (most recent call last):
File "/home/openemm/bin/scripts/pickdist.py", line 180, in ?
if pd.scanForData () == 0:
File "/home/openemm/bin/scripts/pickdist.py", line 109, in scanForData
db = agn.DBase ()
AttributeError: 'module' object has no attribute 'DBase'

Posted: Sun Dec 24, 2006 10:30 pm
by sfantul
Seems that u dont have the python-mysql module installed

Posted: Fri Jan 05, 2007 12:12 am
by trax
Thanks.. absolutly right!

Posted: Thu Jun 26, 2008 2:02 am
by henry_shadowjet
Wow! This totally true!

Thanks a lot ^^

Posted: Tue Oct 28, 2008 2:59 pm
by dirkfries
Hi @all,

I've installed openemm 5.5.1 on Ubuntu 8.04 and I've got a similar problem.
Mails are being generated and openEMM tells be that those mails are beeing sent, but I can't see anything in /var/log/mail.log
~var/spool/QUEUE is empty, too.
Unfortunaly there is no pickdist.log for today.
python-mysqldb is installed.
Does anyone habe an idea where I could look for further hints ?

Thanks in advance !

Bye Dirk

Posted: Tue Jun 30, 2009 10:26 am
by herber-herber
push: Hi @ all,

I discovered this "old" post, we got exactly the same problem, we are not able to send emails via mailinglist.

my pickdist looks like :
[26.06.2009 01:27:33] 7089 INFO/loop: -- MARK --
[26.06.2009 04:27:52] 7089 INFO/loop: -- MARK --
[26.06.2009 07:28:12] 7089 INFO/loop: -- MARK --
[26.06.2009 10:28:31] 7089 INFO/loop: -- MARK --
[26.06.2009 13:28:50] 7089 INFO/loop: -- MARK --
[26.06.2009 14:37:33] 7089 INFO/block: Unpacking /openemm/var/spool/META/AgnMail=D20090626143716=1=63=1C00002367=liaMngA.xml.gz
[26.06.2009 14:57:43] 7089 INFO/block: Unpacking /openemm/var/spool/META/AgnMail=D20090626145723=1=63=1C00002368=liaMngA.xml.gz
[26.06.2009 15:20:28] 7089 INFO/block: Unpacking /openemm/var/spool/META/AgnMail=D20090626152022=1=63=1C00002369=liaMngA.xml.gz
[26.06.2009 15:54:35] 7089 INFO/block: Unpacking /openemm/var/spool/META/AgnMail=D20090626155419=1=63=1C00002368=liaMngA.xml.gz
[26.06.2009 17:32:15] 7089 INFO/block: Unpacking /openemm/var/spool/META/AgnMail=D20090626173157=1=63=1C00002370=liaMngA.xml.gz
[26.06.2009 17:32:47] 7089 INFO/block: Unpacking /openemm/var/spool/META/AgnMail=D20090626173235=1=63=2C00002370=liaMngA.xml.gz
[26.06.2009 20:33:07] 7089 INFO/loop: -- MARK --
[26.06.2009 23:33:26] 7089 INFO/loop: -- MARK --

so it seems not to be the python-mysql package.

Any one got other ideas?
thanks in advance!

Thanks!

Posted: Sun Feb 21, 2010 2:17 pm
by pbolgar
This was it for me as well, thank you! Under Ubuntu it was installing the python-mysqldb package.

Code: Select all

sudo apt-get install python-mysqldb
Best, Peter