No voicemail notification by mail

elsinga · Jan 8, 2010

Hi!

I am trying to get voicemail notification set up, but can't get 3CX to send a mail when a new voicemail is present. All mail server settings are okay, since sending an email when creating a new extention works fine.

My environment:
- Windows 2003 server with latest patches/SP's
- 3CX version 8.0.10116.583 (latest version)
- no license aquired, since I'm running this for testing on a small system right now
- mail server is the smtp server for my cable internet company (smtp.ziggo.nl), no authenticatiopn neccesary

Mails get sent through the smtp server just fine:
- when creating a new extention
- with Thunderbird
- even with telnet to port 25 and manually typing SMTP commands :mrgreen:

The only time no mails are sent is when a voicemail is recorded by 3CX. The voicemail messages are left in the mailbox, even when set to mail and delete.

What is going wrong here?

LeonidasG_3CX · Jan 8, 2010

You can check the VoiceBox manager log in the Logs folder to see what's going on.

I tried this yesterday and E-Mails with the attachements worked fine. It's propably something with your Mail Server, some just ban you and others just block the attachements.

As i said the log will provide more info.

elsinga · Jan 9, 2010

I tried to find the Logs folder in the 3CX installation folder (C:\Program Files\3CX PhoneSystem) but there is no Logs folder there. The 3CX Wiki didn't have anything about the location either.

Just tried setting the Log Level to Verbose, to see if there are more log details then. But no info on sending emails:

Code:

08:21:40.968  [CM503008]: Call(4): Call is terminated
08:21:20.671  Session 244 of leg C:4.1 is confirmed
08:21:20.578  [CM503007]: Call(4): Device joined: sip:[email protected]:40600;rinstance=c6cd1b4d5fed3dba
08:21:20.562  [CM503007]: Call(4): Device joined: sip:[email protected];uniq=6248F2127480F0C015C8FE291EC7C
08:21:20.562  [MS210005] C:4.1:Answer provided. Connection(proxy mode):10.0.0.10:7006(7007)
08:21:20.562  [MS210001] C:4.2:Answer received. RTP connection[unsecure]: 127.0.0.1:40610(40611)
08:21:20.546  Remote SDP is set for legC:4.2
08:21:20.546  [CM505001]: Ext.999: Device info: Device Identified: [Man: 3CX Ltd.;Mod: Voice Mail Menu;Rev: General] Capabilities:[reinvite, replaces, able-no-sdp, recvonly] UserAgent: [3CX Voice Mail Menu] PBX contact: [sip:[email protected]:5060]
08:21:20.546  [CM503002]: Call(4): Alerting sip:[email protected]:40600;rinstance=c6cd1b4d5fed3dba
08:21:20.375  [CM503025]: Call(4): Calling Ext:Ext.999@[Dev:sip:[email protected]:40600;rinstance=c6cd1b4d5fed3dba]
08:21:20.343  [MS210004] C:4.2:Offer provided. Connection(proxy mode): 127.0.0.1:7008(7009)
08:21:20.296  [CM503005]: Call(4): Forwarding: Ext:Ext.999@[Dev:sip:[email protected]:40600;rinstance=c6cd1b4d5fed3dba]
08:21:20.296  [CM503016]: Call(4): Attempt to reach <sip:[email protected]> failed. Reason: Not Registered
08:21:20.296  [CM503017]: Call(4): Target is not registered: Ext:Ext.900
08:21:20.296  [CM503010]: Making route(s) to <sip:[email protected]>
08:21:20.296  [MS210000] C:4.1:Offer received. RTP connection: 10.0.0.1:7082(7083)
08:21:20.296  Remote SDP is set for legC:4.1
08:21:20.281  [CM505001]: Ext.100: Device info: Device Not Identified: User Agent not matched; Capabilities:[reinvite, replaces, able-no-sdp, recvonly] UserAgent: [AVM FRITZ!Box Fon WLAN 7050 14.04.33 (May 10 2007)] PBX contact: [sip:[email protected]:5060]
08:21:20.218  [CM503001]: Call(4): Incoming call from Ext.100 to <sip:[email protected]>
08:21:20.203  [CM500002]: Info on incoming INVITE:
  INVITE sip:[email protected] SIP/2.0
  Via: SIP/2.0/UDP 10.0.0.1:5060;branch=z9hG4bKA55A3F8983B634A1
  Max-Forwards: 70
  Route: <sip:10.0.0.10;lr>
  Contact: <sip:[email protected];uniq=6248F2127480F0C015C8FE291EC7C>
  To: <sip:[email protected]>
  From: <sip:[email protected]>;tag=59445030D53B2F04
  Call-ID: [email protected]
  CSeq: 6467 INVITE
  Expires: 120
  Accept: application/sdp, multipart/mixed
  Accept-Encoding: identity
  Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, UPDATE, PRACK, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE
  Proxy-Authorization: Digest username="100",realm="3CXPhoneSystem",nonce="414d535c0158bf7031:afbc17ac09c506fb223315e6de4c3c92",uri="sip:[email protected]",response="79bef2b9a5e71499832b13bb0aec6831",algorithm=MD5
  Supported: 100rel, replaces, timer
  User-Agent: AVM FRITZ!Box Fon WLAN 7050 14.04.33 (May 10 2007)
  Allow-Events: telephone-event, refer
  Content-Length: 0
  
08:20:53.578  [CM504001]: Ext.IVRForward: new contact is registered. Contact(s): [sip:[email protected]:40600;rinstance=f4f2cef911885402/IVRForward]

Changing the loglevel needed a restart of 3CX, which seemed to be enough to get voicemail notification working again.

andymars · Jan 14, 2010

no such luck for me, trace log indicates success, but no email

Code:

18:39:07.952|Inf(03)|  Creating email notification for 210
18:39:07.952|Inf(03)|  Sending email notification to [email protected] using mail.company.com
18:39:08.343|Inf(03)|  Email sent to [email protected]
18:39:23.537|Inf(03)|  Save DNInfo ext=210 Tot=3 New=2

restarted services a few times, new extensions DO receive emails though.....

EDIT - actual had a typo in the expected email address' domain - resolved now

dwstorage · Mar 25, 2010

I seem to have the same problem -

some notes:
- verbose logging level
- voicemail via email set to yes for the proper extensions
- working SMTP server in use by other (non-3cx) utilities
- zero mention of "Creating email notification" in logs
- voicemail classified as "new" when i login to Welcome to the 'MyPhone' User portal
- any thoughts of where I'm going wrong?

----------------
Logs Below
-----------------

Code:

22:40:57.572  Active calls counted toward license limit: []
22:40:25.576  Active calls counted toward license limit: []
22:40:13.264  [CM503008]: Call(30): Call is terminated
22:40:03.969  Session 1891 of leg C:30.1 is confirmed
22:40:03.875  [CM503007]: Call(30): Device joined: sip:[email protected]:40600;rinstance=f57eb789882fb673
22:40:03.859  [CM503007]: Call(30): Device joined: sip:[email protected]:5060
22:40:03.859  [MS210005] C:30.1:Answer provided. Connection(proxy mode):192.168.0.4:7164(7165)
22:40:03.859  [MS210001] C:30.3:Answer received. RTP connection[unsecure]: 127.0.0.1:40634(40635)
22:40:03.859  Remote SDP is set for legC:30.3
22:40:03.859  [CM505001]: Ext.999: Device info: Device Identified: [Man: 3CX Ltd.;Mod: Voice Mail Menu;Rev: General] Capabilities:[reinvite, replaces, able-no-sdp, recvonly] UserAgent: [3CX Voice Mail Menu] PBX contact: [sip:[email protected]:5060]
22:40:03.859  [CM503002]: Call(30): Alerting sip:[email protected]:40600;rinstance=f57eb789882fb673
22:40:03.656  [CM503003]: Call(30): Call to sip:[email protected]:5060 has failed; Cause: 302 Moved Temporarily; from IP:192.168.0.15:2387
22:40:03.656  [CM503025]: Call(30): Calling Ext:Ext.999@[Dev:sip:[email protected]:40600;rinstance=f57eb789882fb673]
22:40:03.656  [MS210004] C:30.3:Offer provided. Connection(proxy mode): 127.0.0.1:7168(7169)
22:40:03.656  [CM503004]: Call(30): Route Ext:Ext.999@[Dev:sip:[email protected]:40600;rinstance=f57eb789882fb673]: 
22:40:03.656  [CM503006]: Call(30): Diverted to: <sip:[email protected]:5060;user=vmail>
22:39:59.719  [CM505001]: Ext.120: Device info: Device Not Identified: User Agent not matched; Capabilities:[reinvite, replaces, able-no-sdp, recvonly] UserAgent: [3CXPhone 4.0.11015.0] PBX contact: [sip:[email protected]:5060]
22:39:59.719  [CM503002]: Call(30): Alerting sip:[email protected]:2387;rinstance=c21767f254267f45
22:39:59.563  [CM503025]: Call(30): Calling Ext:Ext.120@[Dev:sip:[email protected]:2387;rinstance=c21767f254267f45]
22:39:59.563  [MS210006] C:30.2:Offer provided. Connection(by pass mode): 192.168.0.42:5006(5007)
22:39:59.516  [CM503004]: Call(30): Route 1: Ext:Ext.120@[Dev:sip:[email protected]:2387;rinstance=c21767f254267f45]
22:39:59.516  [CM503010]: Making route(s) to <sip:[email protected]>
22:39:59.516  [MS210000] C:30.1:Offer received. RTP connection: 192.168.0.42:5006(5007)
22:39:59.516  Remote SDP is set for legC:30.1
22:39:59.516  [CM505001]: Ext.103: Device info: Device Not Identified: User Agent not matched; Capabilities:[reinvite, replaces, able-no-sdp, recvonly] UserAgent: [Aastra MBU/01.08 MAC=00087B074ED9] PBX contact: [sip:[email protected]:5060]
22:39:59.500  [CM503001]: Call(30): Incoming call from Ext.103 to <sip:[email protected]>
22:39:59.500  [CM500002]: Info on incoming INVITE:
  INVITE sip:[email protected] SIP/2.0
  Via: SIP/2.0/UDP 192.168.0.42;branch=z9hG4bKufaFj6XExqNs7wl
  Max-Forwards: 70
  Contact: <sip:[email protected]>
  To: <sip:[email protected]>
  From: "TR #1"<sip:[email protected]>;tag=le1SMj
  Call-ID: [email protected]
  CSeq: 65476 INVITE
  Allow: INVITE, CANCEL, BYE, ACK, REGISTER, OPTIONS, REFER, SUBSCRIBE, NOTIFY, MESSAGE, INFO, PRACK
  Proxy-Authorization: Digest username="103",realm="3CXPhoneSystem",nonce="414d535c01bb6c0f16:2a490045b615419c9d88b511c0a640fe",uri="sip:[email protected]",response="d544c16ce6653a47923f5dc021a4c0be",algorithm=MD5
  Supported: replaces
  User-Agent: Aastra MBU/01.08 MAC=00087B074ED9
  Content-Length: 0
  
22:40:13.264  [CM503008]: Call(30): Call is terminated
22:40:03.969  Session 1891 of leg C:30.1 is confirmed
22:40:03.875  [CM503007]: Call(30): Device joined: sip:[email protected]:40600;rinstance=f57eb789882fb673
22:40:03.859  [CM503007]: Call(30): Device joined: sip:[email protected]:5060
22:40:03.859  [MS210005] C:30.1:Answer provided. Connection(proxy mode):192.168.0.4:7164(7165)
22:40:03.859  [MS210001] C:30.3:Answer received. RTP connection[unsecure]: 127.0.0.1:40634(40635)
22:40:03.859  Remote SDP is set for legC:30.3
22:40:03.859  [CM505001]: Ext.999: Device info: Device Identified: [Man: 3CX Ltd.;Mod: Voice Mail Menu;Rev: General] Capabilities:[reinvite, replaces, able-no-sdp, recvonly] UserAgent: [3CX Voice Mail Menu] PBX contact: [sip:[email protected]:5060]
22:40:03.859  [CM503002]: Call(30): Alerting sip:[email protected]:40600;rinstance=f57eb789882fb673
22:40:03.656  [CM503003]: Call(30): Call to sip:[email protected]:5060 has failed; Cause: 302 Moved Temporarily; from IP:192.168.0.15:2387
22:40:03.656  [CM503025]: Call(30): Calling Ext:Ext.999@[Dev:sip:[email protected]:40600;rinstance=f57eb789882fb673]
22:40:03.656  [MS210004] C:30.3:Offer provided. Connection(proxy mode): 127.0.0.1:7168(7169)
22:40:03.656  [CM503004]: Call(30): Route Ext:Ext.999@[Dev:sip:[email protected]:40600;rinstance=f57eb789882fb673]: 
22:40:03.656  [CM503006]: Call(30): Diverted to: <sip:[email protected]:5060;user=vmail>
22:39:59.719  [CM505001]: Ext.120: Device info: Device Not Identified: User Agent not matched; Capabilities:[reinvite, replaces, able-no-sdp, recvonly] UserAgent: [3CXPhone 4.0.11015.0] PBX contact: [sip:[email protected]:5060]
22:39:59.719  [CM503002]: Call(30): Alerting sip:[email protected]:2387;rinstance=c21767f254267f45
22:39:59.563  [CM503025]: Call(30): Calling Ext:Ext.120@[Dev:sip:[email protected]:2387;rinstance=c21767f254267f45]
22:39:59.563  [MS210006] C:30.2:Offer provided. Connection(by pass mode): 192.168.0.42:5006(5007)
22:39:59.516  [CM503004]: Call(30): Route 1: Ext:Ext.120@[Dev:sip:[email protected]:2387;rinstance=c21767f254267f45]
22:39:59.516  [CM503010]: Making route(s) to <sip:[email protected]>
22:39:59.516  [MS210000] C:30.1:Offer received. RTP connection: 192.168.0.42:5006(5007)
22:39:59.516  Remote SDP is set for legC:30.1
22:39:59.516  [CM505001]: Ext.103: Device info: Device Not Identified: User Agent not matched; Capabilities:[reinvite, replaces, able-no-sdp, recvonly] UserAgent: [Aastra MBU/01.08 MAC=00087B074ED9] PBX contact: [sip:[email protected]:5060]
22:39:59.500  [CM503001]: Call(30): Incoming call from Ext.103 to <sip:[email protected]>
22:39:59.500  [CM500002]: Info on incoming INVITE:
  INVITE sip:[email protected] SIP/2.0
  Via: SIP/2.0/UDP 192.168.0.42;branch=z9hG4bKufaFj6XExqNs7wl
  Max-Forwards: 70
  Contact: <sip:[email protected]>
  To: <sip:[email protected]>
  From: "TR #1"<sip:[email protected]>;tag=le1SMj
  Call-ID: [email protected]
  CSeq: 65476 INVITE
  Allow: INVITE, CANCEL, BYE, ACK, REGISTER, OPTIONS, REFER, SUBSCRIBE, NOTIFY, MESSAGE, INFO, PRACK
  Proxy-Authorization: Digest username="103",realm="3CXPhoneSystem",nonce="414d535c01bb6c0f16:2a490045b615419c9d88b511c0a640fe",uri="sip:[email protected]",response="d544c16ce6653a47923f5dc021a4c0be",algorithm=MD5
  Supported: replaces
  User-Agent: Aastra MBU/01.08 MAC=00087B074ED9
  Content-Length: 0

dwstorage · Mar 25, 2010

oops. a thousand apologies. I checked the wrong logs.
To clarify for everyone, the 3CxVoiceBoxManager.log is what you're looking for ... located in the log folder.
an easy way to get to it is to use the 3cx help menu to generate support files.

some info on these logs

SMTP server #1
System.Net.Sockets.SocketException: No connection could be made because the target machine actively refused it 69.17.117.59:25

but i changed mail servers b/c it looks like my ISP (Comcast) is blocking port 25

SMTP server #2 (using gmail)

Code:

(System.Net.Sockets.SocketException: No connection could be made because the target machine actively refused it 74.125.95.109:587)

and with port 465, a different error:
System.Net.Mail.SmtpException: The operation has timed out.
   at System.Net.Mail.SmtpClient.Send(MailMessage message)
   at GenericUtilsNet.Mail.Send()
   at VoiceMailScanner.EmailNotif.SendMail()
   at VoiceMailScanner.BoxesMonitor.ProcessList(List`1 list)

any thoughts?

garethb_83 · Mar 25, 2010

I had simialr issues.. I was directing SMTP to our exchange server and I was trying to send to an external address. This is relaying which is disabled on our exchange... Maybe Obvious but thought I'd share. Also, make sure authentication is setup within General, Mail server.

leejor · Mar 25, 2010

Does this feature work on the "Free" version?

dwstorage · Mar 25, 2010

Ok...

it works! (voicemail is enabled in the free version)
how? Hard server restart. (ugh)
no matter how many times i soft-restarted 3cx in the admin control panel last night it didn't solve the problem.

another issue i was troubleshooting caused the restart today and voila... 5 emails in my inbox with vmail attachment.

resolution steps were relatively simple

1. Get log files (Help ... Create Support Info)
2. Use VoiceBox log to see what the error is

If 3cx is trying to send the email via the account and failing
3. troubleshoot the port that email *should* be sending out on via telnet
4. troubleshoot the mail account with another server
5. soft restart 3cx
6. hard restart 3cx

Banana Jack · Jul 13, 2011

I had this same problem and it took several days before anyone in the company noticed that no voicemails were being received by email attachment. I could find no trace of any file called 3CxVoiceBoxManager.log (maybe that is relevant only to an earlier version of 3CX?).

Anyway the problem turned out to be that the "3CX PhoneSystem Call History" service had stopped/crashed. It seems that this service needs to be running in order for voicemails to be delivered by email. I am now using PRTG to constantly check that the service is running, and if it isn't, PRTG will automatically restart it.

I am using 3CX v10.0.19547.1805 SP.1.1 running on Hyper-V inside Windows Server 2008 R2, and our mail server is Exchange 2003.

eagle2 · Jul 13, 2011

The issue could be your SMTP server is requiring secured ports.

Regards

GMan · Jul 21, 2011

I am having a similar problem, no e-mail notifications.

Extract from my CallHistoryService.log

16:22:53.370|Inf(03)| VMMonitor: Scan VMBox:'10'
16:22:53.370|Inf(03)| VMMonitor: File vmail_07768123456_10_20110721152253 added
16:22:53.372|Inf(03)| Number of VoiceMails directories:2
16:22:53.372|Inf(03)| 10:1 voicemails
16:22:53.372|Inf(03)| 11:0 voicemails
16:22:53.376|Inf(03)| VMMonitor: Creating email notification for 10
16:22:53.432|Inf(03)| VMMonitor: Sending email notification to xxxxxx@yyyy using zzzzzz
16:22:54.587|Inf(03)| VMMonitor: Email sent to xxxxxx@yyyy
16:22:54.593|Inf(03)| VMMonitor: Save DNInfo ext=10 Tot=1 New=1
16:22:54.596|Inf(03)| VMMonitor: Scan VMBox:'10'

Using Wireshark to check what's happening on the network shows that 3CX is Authenticated by the mail server, both the MAIL FROM & RCPT TO are OK'd by the mail server but then 3CX issues an RSET, thus aborting the e-mail.

Any ideas?

Tried it again having switched the verbose logging on and found this:

17:15:14.735|Inf(03)| 10 updated(changed)
17:15:14.737|Inf(03)| VMMonitor: Scan VMBox:'10'
17:15:14.741|Inf(03)| 10 updated(changed)
17:15:14.743|Inf(03)| VMMonitor: File vmail_07768123456_10_20110721161514 added
17:15:14.782|Err(01)| VMMonitor: Can not load voicemail info from file 'C:\ProgramData\3CX\Data\Ivr\Voicemail\Extensions\10\vmail_07768123456_10_20110721161514.xml'
17:15:14.845|Exc(03)| System.IO.IOException: The process cannot access the file 'C:\ProgramData\3CX\Data\Ivr\Voicemail\Extensions\10\vmail_07768123456_10_20110721161514.xml' because it is being used by another process.
at System.IO.__Error.WinIOError(Int32 errorCode, String maybeFullPath)
at System.IO.FileStream.Init(String path, FileMode mode, FileAccess access, Int32 rights, Boolean useRights, FileShare share, Int32 bufferSize, FileOptions options, SECURITY_ATTRIBUTES secAttrs, String msgPath, Boolean bFromProxy)
at System.IO.FileStream..ctor(String path, FileMode mode, FileAccess access, FileShare share, Int32 bufferSize, FileOptions options, String msgPath, Boolean bFromProxy)
at System.IO.FileStream..ctor(String path, FileMode mode, FileAccess access, FileShare share, Int32 bufferSize)
at System.Xml.XmlDownloadManager.GetStream(Uri uri, ICredentials credentials)
at System.Xml.XmlUrlResolver.GetEntity(Uri absoluteUri, String role, Type ofObjectToReturn)
at System.Xml.XmlTextReaderImpl.OpenUrlDelegate(Object xmlResolver)
at System.Threading.CompressedStack.runTryCode(Object userData)
at System.Runtime.CompilerServices.RuntimeHelpers.ExecuteCodeWithGuaranteedCleanup(TryCode code, CleanupCode backoutCode, Object userData)
at System.Threading.CompressedStack.Run(CompressedStack compressedStack, ContextCallback callback, Object state)
at System.Xml.XmlTextReaderImpl.OpenUrl()
at System.Xml.XmlTextReaderImpl.Read()
at System.Xml.XmlLoader.Load(XmlDocument doc, XmlReader reader, Boolean preserveWhitespace)
at System.Xml.XmlDocument.Load(XmlReader reader)
at System.Xml.XmlDocument.Load(String filename)
at VBMutils.VoiceMailInfo.LoadInfo()
17:15:14.848|Inf(03)| Number of VoiceMails directories:2
17:15:14.851|Inf(03)| 10:2 voicemails
17:15:14.854|Inf(03)| 11:0 voicemails
17:15:14.860|Inf(03)| VMMonitor: Scan VMBox:'10'
17:15:14.868|Inf(03)| Number of VoiceMails directories:2
17:15:14.871|Inf(03)| 10:2 voicemails
17:15:14.874|Inf(03)| 11:0 voicemails
17:15:14.885|Inf(03)| VMMonitor: Creating email notification for 10
17:15:14.909|Inf(03)| VMMonitor: Sending email notification to xxxxxx@yyyy using zzzz
17:15:15.967|Inf(03)| 10 updated(changed)
17:15:15.976|Inf(03)| VMMonitor: Email sent to xxxxxx@yyyy

Could the fact that it can't open the xml file for the voicemail cause it to abort sending the e-mail? What other process could be blocking access?

Search

No voicemail notification by mail

elsinga

LeonidasG_3CX

Product Manager

elsinga

andymars

dwstorage

dwstorage

garethb_83

leejor

dwstorage

Banana Jack

eagle2

GMan

Latest Posts

Members Online Now

Forum statistics

20 Years of VoIP
Industry Leadership

Company

Solutions

Useful Links

Learn More

No voicemail notification by mail

Product Manager

Forum statistics

20 Years of VoIP Industry Leadership

Company

Solutions

Useful Links

Learn More

20 Years of VoIP
Industry Leadership