No email from voicemail but i can send mail from sendmail

FreePBX Installation / Upgrade
1

Hi

I have set up a Asterisk Free PBX running 4.211.64.7 FreePBX and Asterisk 11.5.1

I have red through a good number of posts on this subject however i am stuck. And i have worked with this for 2 days now.

Mail can be sent from the command line with telnet, and with sendmail, to the same address that voice mail is set up to send to, however the voicemail emails do not go through so hopefully i have set the postfix correctly.

The email que is empty.

I am getting system emails from Asterisk.

The mail-server is Exchange 2010 and is located on the same subnet thus no routers are involved.

Below is the event from the maillog.

Please tell me what i am doing wrong.

Chris

Oct 13 17:01:23 localhost postfix/pickup[1873]: 6B9996AA1CD6: uid=499 from=
Oct 13 17:01:23 localhost postfix/cleanup[2394]: 6B9996AA1CD6: message-id=[email protected]
Oct 13 17:01:23 localhost postfix/qmgr[1874]: 6B9996AA1CD6: from=[email protected], size=429, nrcpt=1 (queue active)
Oct 13 17:01:23 localhost postfix/pickup[1873]: 8548B6AA1CD1: uid=499 from=
Oct 13 17:01:23 localhost postfix/cleanup[2394]: 8548B6AA1CD1: message-id=[email protected]
Oct 13 17:01:23 localhost postfix/qmgr[1874]: 8548B6AA1CD1: from=[email protected], size=100866, nrcpt=1 (queue active)
Oct 13 17:01:23 localhost postfix/smtp[2399]: 8548B6AA1CD1: to=[email protected], relay=172.20.0.22[172.20.0.22]:25, delay=0.54, delays=0.37/0.01/0/0.16, dsn=2.6.0, status=sent (250 2.6.0 [email protected] [InternalId=11871767] Queued mail for delivery)
Oct 13 17:01:23 localhost postfix/qmgr[1874]: 8548B6AA1CD1: removed
Oct 13 17:01:24 localhost postfix/smtp[2398]: 6B9996AA1CD6: to=[email protected], relay=172.20.0.22[172.20.0.22]:25, delay=0.99, delays=0.26/0.04/0/0.69, dsn=2.6.0, status=sent (250 2.6.0 [email protected] [InternalId=11871766] Queued mail for delivery)
Oct 13 17:01:24 localhost postfix/qmgr[1874]: 6B9996AA1CD6: removed

2

tail -f /var/log/maillog in a shell. Make a call and post the output from the maillog.

Are you sure you have the extension configured correctly to send the voicemail as email?

3

Hi

Thank you for responding. As i am new i can not be 100% sure however i believe that what i have done is correct.

I am using the GUI to set this up, “Email Attachment” is set to yes, i have placed the email addresses in the box marked “Email Address”, and i varied the address in that box to insure that the messages in /var/log/maillog reflected the settings contained in that “Extensions” setup was actually being sent. I Then added a email address to the “Pager” field to also confirm.

Beyond those settings and the settings in “main.cf” go get the mail going thru, have made no other settings for the voicemail.

Below please find the log output you requested. It shows both email addresses entered in “Extensions” as being sent out.

Any help is greatly appreciated.

Thank you

Chris

Oct 14 07:05:01 localhost postfix/smtp[5556]: 6DA246AA1CDE: to=[email protected], orig_to=, relay=172.20.0.22[172.20.0.22]:25, delay=0.47, delays=0.16/0.01/0/0.3, dsn=2.6.0, status=sent (250 2.6.0 [email protected] [InternalId=11875851] Queued mail for delivery)
Oct 14 07:05:01 localhost postfix/qmgr[1874]: 6DA246AA1CDE: removed
Oct 14 07:10:01 localhost postfix/sendmail[5567]: warning: the Postfix sendmail command has set-uid root file permissions
Oct 14 07:10:01 localhost postfix/sendmail[5567]: warning: or the command is run from a set-uid root process
Oct 14 07:10:01 localhost postfix/sendmail[5567]: warning: the Postfix sendmail command must be installed without set-uid root file permissions
Oct 14 07:10:01 localhost postfix/pickup[5491]: 9ED3B6AA1CDE: uid=499 from=
Oct 14 07:10:01 localhost postfix/cleanup[5569]: 9ED3B6AA1CDE: message-id=[email protected]
Oct 14 07:10:01 localhost postfix/qmgr[1874]: 9ED3B6AA1CDE: from=[email protected], size=1277, nrcpt=1 (queue active)
Oct 14 07:10:02 localhost postfix/smtp[5571]: 9ED3B6AA1CDE: to=[email protected], orig_to=, relay=172.20.0.22[172.20.0.22]:25, delay=0.47, delays=0.18/0.01/0/0.29, dsn=2.6.0, status=sent (250 2.6.0 [email protected] [InternalId=11875955] Queued mail for delivery)
Oct 14 07:10:02 localhost postfix/qmgr[1874]: 9ED3B6AA1CDE: removed
Oct 14 07:13:48 localhost postfix/pickup[5491]: 5ECCE6AA1CDF: uid=499 from=
Oct 14 07:13:48 localhost postfix/cleanup[5587]: 5ECCE6AA1CDF: message-id=[email protected]
Oct 14 07:13:48 localhost postfix/qmgr[1874]: 5ECCE6AA1CDF: from=[email protected], size=126741, nrcpt=1 (queue active)
Oct 14 07:13:48 localhost postfix/pickup[5491]: 6F88C6AA1CD5: uid=499 from=
Oct 14 07:13:48 localhost postfix/cleanup[5587]: 6F88C6AA1CD5: message-id=[email protected]
Oct 14 07:13:48 localhost postfix/qmgr[1874]: 6F88C6AA1CD5: from=[email protected], size=429, nrcpt=1 (queue active)
Oct 14 07:13:48 localhost postfix/smtp[5592]: 6F88C6AA1CD5: to=[email protected], relay=172.20.0.22[172.20.0.22]:25, delay=0.41, delays=0.21/0.01/0/0.19, dsn=2.6.0, status=sent (250 2.6.0 [email protected] [InternalId=11876064] Queued mail for delivery)
Oct 14 07:13:48 localhost postfix/qmgr[1874]: 6F88C6AA1CD5: removed
Oct 14 07:13:50 localhost postfix/smtp[5591]: 5ECCE6AA1CDF: to=[email protected], relay=172.20.0.22[172.20.0.22]:25, delay=2.3, delays=0.15/0.01/1/1.2, dsn=2.6.0, status=sent (250 2.6.0 [email protected] [InternalId=11876065] Queued mail for delivery)
Oct 14 07:13:50 localhost postfix/qmgr[1874]: 5ECCE6AA1CDF: removed

4

The email is getting sent…

Oct 14 07:13:50 localhost postfix/smtp[5591]: 5ECCE6AA1CDF: to=, relay=172.20.0.22[172.20.0.22]:25, delay=2.3, delays=0.15/0.01/1/1.2, dsn=2.6.0, status=sent (250 2.6.0 [InternalId=11876065] Queued mail for delivery)
Oct 14 07:13:50 localhost postfix/qmgr[1874]: 5ECCE6AA1CDF: removed

…not the ‘removed’ part after it was queued… BUT the

to=,relay=…

part is wrong… did you remove that email address for posting on the board… or did that get removed by the posting software… or is that really empty? That should be the email address your sending the VM Notification to…

Is that to: info really missing or just hidden from the post for some reason?

jack

5

Thanks for responding.

I did not edit the file, i put back the original main.cf and re-edited it adding the relayhost = again.

here are the results from the /var/log/maillog below this i will post a log of it working from sendmail.

Oct 14 14:11:02 localhost postfix/pickup[2762]: 243B86AA1CDE: uid=499 from=
Oct 14 14:11:02 localhost postfix/cleanup[2778]: 243B86AA1CDE: message-id=[email protected]
Oct 14 14:11:02 localhost postfix/qmgr[1879]: 243B86AA1CDE: from=[email protected], size=264436, nrcpt=1 (queue active)
Oct 14 14:11:02 localhost postfix/smtp[2782]: 243B86AA1CDE: to=[email protected], relay=172.20.0.22[172.20.0.22]:25, delay=0.86, delays=0.11/0/0/0.75, dsn=2.6.0, status=sent (250 2.6.0 [email protected] [InternalId=11888302] Queued mail for delivery)
Oct 14 14:11:02 localhost postfix/qmgr[1879]: 243B86AA1CDE: removed

Here is a mail that went through.

Oct 14 14:05:59 localhost postfix/pickup[1878]: 58FDE6AA1CDE: uid=0 from=
Oct 14 14:05:59 localhost postfix/cleanup[2729]: 58FDE6AA1CDE: message-id=[email protected]
Oct 14 14:05:59 localhost postfix/qmgr[1879]: 58FDE6AA1CDE: from=[email protected], size=312, nrcpt=1 (queue active)
Oct 14 14:05:59 localhost postfix/smtp[2760]: 58FDE6AA1CDE: to=[email protected], relay=172.20.0.22[172.20.0.22]:25, delay=4.2, delays=4.1/0.01/0/0.15, dsn=2.6.0, status=sent (250 2.6.0 [email protected] [InternalId=11888151] Queued mail for delivery)
Oct 14 14:05:59 localhost postfix/qmgr[1879]: 58FDE6AA1CDE: removed

Thank you

Chris

6

Update i noted in my last post the message id was missing.

I rebooted and ran the test again, mail still did not go through.

here is the maillog

Oct 14 14:25:42 localhost postfix/pickup[1867]: B3B046AA19AE: uid=499 from=
Oct 14 14:25:42 localhost postfix/cleanup[2376]: B3B046AA19AE: message-id=[email protected]
Oct 14 14:25:42 localhost postfix/qmgr[1868]: B3B046AA19AE: from=[email protected], size=116218, nrcpt=1 (queue active)
Oct 14 14:25:43 localhost postfix/smtp[2392]: B3B046AA19AE: to=[email protected], relay=172.20.0.22[172.20.0.22]:25, delay=1.4, delays=0.2/0.03/0/1.1, dsn=2.6.0, status=sent (250 2.6.0 [email protected] [InternalId=11888744] Queued mail for delivery)
Oct 14 14:25:43 localhost postfix/qmgr[1868]: B3B046AA19AE: removed

Thank you
Chris

7

Sorry

I am unable to paste without cutting off part of the log file.

I will keep at it until i figure it out.

8

Sorry for the confusion

Here is maillog without being cut off.

this is a voicemail that failed to email.

Oct 14 14:25:42 localhost postfix/pickup[1867]: B3B046AA19AE: uid=499 from=
Oct 14 14:25:42 localhost postfix/cleanup[2376]: B3B046AA19AE: message-id=[email protected]
Oct 14 14:25:42 localhost postfix/qmgr[1868]: B3B046AA19AE: from=[email protected], size=116218, nrcpt=1 (queue active)
Oct 14 14:25:43 localhost postfix/smtp[2392]: B3B046AA19AE: to=[email protected], relay=172.20.0.22[172.20.0.22]:25, delay=1.4, delays=0.2/0.03/0/1.1, dsn=2.6.0, status=sent (250 2.6.0 [email protected] [InternalId=11888744] Queued mail for delivery)
Oct 14 14:25:43 localhost postfix/qmgr[1868]: B3B046AA19AE: removed

9

ok… now that makes more sense… the email IS getting sent… Queued mail for delivery indicates that. The issue is the remote email server
is either 1) not able to deliver to the email address, 2) putting it in a SPAM folder and your not checking it, 3) just deleting it quietly becuase it thinks it is spam or 4) mail for that user is going someplace else.

I assume that you can send mail using other program to the email address and you know that that is a good email address.

You will probably need to look at the mail logs on the 172.20.0.22 mail server when
the message is received and see what it is doing with it. Once you see the Queued mail for delivery message… your freepbx box drops out of the equation.

The 172 email box might not like: @localhost.localdomain or from= and just not tell you that it does not like it.

you’ll need to look at those logs to verify.

jack

10

Thank you

That is what I originally thought however the weird thing is that if I use the sendmail command to the same address it goes thru. if use telnet from the same computer and spoof the from address it still goes thru, also all the system messages went thru. It is my email address I am testing with.

As all other methods work that is why I suspected the asterisk.

I will work further with the exchange 2010 box.

I built another asterisk and it does the same thing.

Thank you kindly for your assistance.

Chris

11

without seeing the logs on the remote mail server, it’s hard to guess at what it does not like. I am not 100% sure how freepbx sends the email.

Some things you might want to look at is setup postfix to authenticate with a userid/password for the remote mail server. Remote mail servers might act differently if you authenticate first.

I setup a voice mail script that runs when the voice mail is delivered to conver it to a MP3 file and use phpMailer to email the mp3 file. That could be configured to do SMTP AUTH with a userid and password or you can do that in postfix.

jack

12

Thank you for responding.

The Connector is configured such that it is only restricted by the IP address. It is set to accept from anyone with the correct ip address. That seems to work.

However we just started getting reports that our blackberry server is not relaying properly as well.

We are trying to get downtime to restart the email server as the blackberry server checks out OK as well and there have been no configuration changes of its email settings.

I will advise once we have rebooted it.

Chris

13

Thank you for your help and patience.

the Symantec Mail Security was set to not pass emails containing .wav files and it was set delete them, so i did not see them in the junk email folder.

Placed the server in the white list and all is good.

Thanks again

Chris