Voicemail Message not heard when call originating outside

Newbie here…

I have several SIP Trunks which are working correctly (incoming calls and outgoing calls have good audio and connect just fine). On incoming calls, I have a ringgroup (ext 600) which rings all extensions for 20 seconds. If no one answers, then it goes to a voicemail blast group (ext 500) where I have recorded a custom announcement to be played and then voicemails are sent to the relevant extensions.

When I dial from an internal line either extension 600 or 500, the behavior is exactly as I would like and voicemail messages are played and heard on the line.

When I dial in from an external number on the SIP Trunk, all the extensions light up, but after the 20 seconds, I don’t hear anything and no voicemail is recorded.

Here are the two logs:

From Internal:

    -- Nobody picked up in 20000 ms
    -- Executing [[email protected]:8] Set("SIP/11-0000009e", "DIALSTATUS=NOANSWER") in new stack
    -- Executing [[email protected]:9] GosubIf("SIP/11-0000009e", "0?NOANSWER,1") in new stack
    -- Executing [[email protected]:13] Gosub("SIP/11-0000009e", "sub-record-cancel,s,1()") in new stack
    -- Executing [[email protected]:1] Return("SIP/11-0000009e", "") in new stack
    -- Executing [[email protected]:14] Set("SIP/11-0000009e", "RingGroupMethod=") in new stack
    -- Executing [[email protected]:15] GotoIf("SIP/11-0000009e", "0?nodest") in new stack
    -- Executing [[email protected]:16] Set("SIP/11-0000009e", "__CWIGNORE=") in new stack
    -- Executing [[email protected]:17] Set("SIP/11-0000009e", "__NODEST=") in new stack
    -- Executing [[email protected]:18] Macro("SIP/11-0000009e", "blkvm-clr,") in new stack
    -- Executing [[email protected]:1] Set("SIP/11-0000009e", "SHARED(BLKVM,SIP/11-0000009e)=") in new stack
    -- Executing [[email protected]:2] Set("SIP/11-0000009e", "GOSUB_RETVAL=") in new stack
    -- Executing [[email protected]:3] MacroExit("SIP/11-0000009e", "") in new stack
    -- Executing [[email protected]:19] Goto("SIP/11-0000009e", "vmblast-grp,500,1") in new stack
    -- Goto (vmblast-grp,500,1)
    -- Executing [[email protected]:1] Macro("SIP/11-0000009e", "user-callerid,") in new stack
    -- Executing [[email protected]:1] Set("SIP/11-0000009e", "TOUCH_MONITOR=1424131768.158") in new stack
    -- Executing [[email protected]:2] Set("SIP/11-0000009e", "AMPUSER=11") in new stack
    -- Executing [[email protected]:3] GotoIf("SIP/11-0000009e", "15?report") in new stack
    -- Goto (macro-user-callerid,s,16)
    -- Executing [[email protected]:16] GotoIf("SIP/11-0000009e", "0?continue") in new stack
    -- Executing [[email protected]:17] ExecIf("SIP/11-0000009e", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
    -- Executing [[email protected]:18] Set("SIP/11-0000009e", "__TTL=63") in new stack
    -- Executing [[email protected]:19] GotoIf("SIP/11-0000009e", "1?continue") in new stack
    -- Goto (macro-user-callerid,s,30)
    -- Executing [[email protected]:30] Set("SIP/11-0000009e", "CALLERID(number)=11") in new stack
    -- Executing [[email protected]:31] Set("SIP/11-0000009e", "CALLERID(name)=Chris Deskphone") in new stack
    -- Executing [[email protected]:32] Set("SIP/11-0000009e", "CDR(cnum)=11") in new stack
    -- Executing [[email protected]:33] Set("SIP/11-0000009e", "CDR(cnam)=Chris Deskphone") in new stack
    -- Executing [[email protected]:34] Set("SIP/11-0000009e", "CHANNEL(language)=en") in new stack
    -- Executing [[email protected]:2] Answer("SIP/11-0000009e", "") in new stack
       > 0x7f21904538c0 -- Probation passed - setting RTP source address to 192.168.20.211:5004
    -- Executing [[email protected]:3] Wait("SIP/11-0000009e", "1") in new stack
    -- Executing [[email protected]:4] Set("SIP/11-0000009e", "GRPLIST=") in new stack
    -- Executing [[email protected]:5] Macro("SIP/11-0000009e", "get-vmcontext,11") in new stack
    -- Executing [[email protected]:1] Set("SIP/11-0000009e", "VMCONTEXT=default") in new stack
    -- Executing [[email protected]:2] GotoIf("SIP/11-0000009e", "0?200:300") in new stack
    -- Goto (macro-get-vmcontext,s,300)
    -- Executing [[email protected]:300] NoOp("SIP/11-0000009e", "") in new stack
    -- Executing [[email protected]:6] Set("SIP/11-0000009e", "GRPLIST=&[email protected]") in new stack
    -- Executing [[email protected]:7] Macro("SIP/11-0000009e", "get-vmcontext,13") in new stack
    -- Executing [[email protected]:1] Set("SIP/11-0000009e", "VMCONTEXT=default") in new stack
    -- Executing [[email protected]:2] GotoIf("SIP/11-0000009e", "0?200:300") in new stack
    -- Goto (macro-get-vmcontext,s,300)
    -- Executing [[email protected]:300] NoOp("SIP/11-0000009e", "") in new stack
    -- Executing [[email protected]:8] Set("SIP/11-0000009e", "GRPLIST=&[email protected]&[email protected]") in new stack
    -- Executing [[email protected]:9] Set("SIP/11-0000009e", "VMBMSG=custom/Chris_Codespark_Greeting") in new stack
    -- Executing [[email protected]:10] Goto("SIP/11-0000009e", "app-vmblast,vmblast,msg") in new stack
    -- Goto (app-vmblast,vmblast,2)
    -- Executing [[email protected]:2] ExecIf("SIP/11-0000009e", "1?Background(custom/Chris_Codespark_Greeting)") in new stack
    -- <SIP/11-0000009e> Playing 'custom/Chris_Codespark_Greeting.slin' (language 'en')
    -- Executing [[email protected]:3] BackGround("SIP/11-0000009e", "if-correct-press&digits/1") in new stack
    -- <SIP/11-0000009e> Playing 'if-correct-press.ulaw' (language 'en')
    -- <SIP/11-0000009e> Playing 'digits/1.ulaw' (language 'en')
    -- Executing [[email protected]:4] WaitExten("SIP/11-0000009e", "20,") in new stack
  == CDR updated on SIP/11-0000009e
    -- Executing [[email protected]:1] VoiceMail("SIP/11-0000009e", "[email protected]&[email protected],s") in new stack
    -- <SIP/11-0000009e> Playing 'beep.ulaw' (language 'en')
    -- Recording the message
    -- x=0, open writing:  /var/spool/asterisk/voicemail/default/11/tmp/nOjAk9 format: wav, 0x7f217802c848
    -- User hung up
[2015-02-17 01:10:10] NOTICE[5506][C-00000045]: app_voicemail.c:5726 copy_message: Copying message from [email protected] to [email protected]
  == Parsing '/var/spool/asterisk/voicemail/default/13/INBOX/msg0004.txt': Found
  == Parsing '/var/spool/asterisk/voicemail/default/13/INBOX/msg0004.txt': Found
  == Parsing '/var/spool/asterisk/voicemail/default/11/INBOX/msg0002.txt': Found
  == Parsing '/var/spool/asterisk/voicemail/default/11/INBOX/msg0002.txt': Found
  == Spawn extension (app-vmblast, 1, 1) exited non-zero on 'SIP/11-0000009e'
  == End MixMonitor Recording SIP/11-0000009e

From External Call:

-- Nobody picked up in 20000 ms
-- Executing [[email protected]:8] Set("SIP/sipcall-4990-000000a1", "DIALSTATUS=NOANSWER") in new stack
    -- Executing [[email protected]:9] GosubIf("SIP/sipcall-4990-000000a1", "0?NOANSWER,1") in new stack
    -- Executing [[email protected]:13] Gosub("SIP/sipcall-4990-000000a1", "sub-record-cancel,s,1()") in new stack
    -- Executing [[email protected]:1] Return("SIP/sipcall-4990-000000a1", "") in new stack
    -- Executing [[email protected]:14] Set("SIP/sipcall-4990-000000a1", "RingGroupMethod=") in new stack
    -- Executing [[email protected]:15] GotoIf("SIP/sipcall-4990-000000a1", "0?nodest") in new stack
    -- Executing [[email protected]:16] Set("SIP/sipcall-4990-000000a1", "__CWIGNORE=") in new stack
    -- Executing [[email protected]:17] Set("SIP/sipcall-4990-000000a1", "__NODEST=") in new stack
    -- Executing [[email protected]:18] Macro("SIP/sipcall-4990-000000a1", "blkvm-clr,") in new stack
    -- Executing [[email protected]:1] Set("SIP/sipcall-4990-000000a1", "SHARED(BLKVM,SIP/sipcall-4990-000000a1)=") in new stack
    -- Executing [[email protected]:2] Set("SIP/sipcall-4990-000000a1", "GOSUB_RETVAL=") in new stack
    -- Executing [[email protected]:3] MacroExit("SIP/sipcall-4990-000000a1", "") in new stack
    -- Executing [[email protected]:19] Goto("SIP/sipcall-4990-000000a1", "vmblast-grp,500,1") in new stack
    -- Goto (vmblast-grp,500,1)
    -- Executing [[email protected]:1] Macro("SIP/sipcall-4990-000000a1", "user-callerid,") in new stack
    -- Executing [[email protected]:1] Set("SIP/sipcall-4990-000000a1", "TOUCH_MONITOR=1424131866.161") in new stack
    -- Executing [[email protected]:2] Set("SIP/sipcall-4990-000000a1", "AMPUSER=0445004990") in new stack
    -- Executing [[email protected]:3] GotoIf("SIP/sipcall-4990-000000a1", "0?report") in new stack
    -- Executing [[email protected]:4] ExecIf("SIP/sipcall-4990-000000a1", "0?Set(REALCALLERIDNUM=0445004990)") in new stack
    -- Executing [[email protected]:5] Set("SIP/sipcall-4990-000000a1", "AMPUSER=") in new stack
    -- Executing [[email protected]:6] GotoIf("SIP/sipcall-4990-000000a1", "0?limit") in new stack
    -- Executing [[email protected]:7] Set("SIP/sipcall-4990-000000a1", "AMPUSERCIDNAME=") in new stack
    -- Executing [[email protected]:8] GotoIf("SIP/sipcall-4990-000000a1", "1?report") in new stack
    -- Goto (macro-user-callerid,s,16)
    -- Executing [[email protected]:16] GotoIf("SIP/sipcall-4990-000000a1", "0?continue") in new stack
    -- Executing [[email protected]:17] ExecIf("SIP/sipcall-4990-000000a1", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
    -- Executing [[email protected]:18] Set("SIP/sipcall-4990-000000a1", "__TTL=63") in new stack
    -- Executing [[email protected]:19] GotoIf("SIP/sipcall-4990-000000a1", "1?continue") in new stack
    -- Goto (macro-user-callerid,s,30)
    -- Executing [[email protected]:30] Set("SIP/sipcall-4990-000000a1", "CALLERID(number)=0445004990") in new stack
    -- Executing [[email protected]:31] Set("SIP/sipcall-4990-000000a1", "CALLERID(name)=Main Line: Codespark") in new stack
    -- Executing [[email protected]:32] Set("SIP/sipcall-4990-000000a1", "CDR(cnum)=0445004990") in new stack
    -- Executing [[email protected]:33] Set("SIP/sipcall-4990-000000a1", "CDR(cnam)=Main Line: Codespark") in new stack
    -- Executing [[email protected]:34] Set("SIP/sipcall-4990-000000a1", "CHANNEL(language)=en") in new stack
    -- Executing [[email protected]:2] Answer("SIP/sipcall-4990-000000a1", "") in new stack
    -- Executing [[email protected]:3] Wait("SIP/sipcall-4990-000000a1", "1") in new stack
    -- Executing [[email protected]:4] Set("SIP/sipcall-4990-000000a1", "GRPLIST=") in new stack
    -- Executing [[email protected]:5] Macro("SIP/sipcall-4990-000000a1", "get-vmcontext,11") in new stack
    -- Executing [[email protected]:1] Set("SIP/sipcall-4990-000000a1", "VMCONTEXT=default") in new stack
    -- Executing [[email protected]:2] GotoIf("SIP/sipcall-4990-000000a1", "0?200:300") in new stack
    -- Goto (macro-get-vmcontext,s,300)
    -- Executing [[email protected]:300] NoOp("SIP/sipcall-4990-000000a1", "") in new stack
    -- Executing [[email protected]:6] Set("SIP/sipcall-4990-000000a1", "GRPLIST=&[email protected]") in new stack
    -- Executing [[email protected]:7] Macro("SIP/sipcall-4990-000000a1", "get-vmcontext,13") in new stack
    -- Executing [[email protected]:1] Set("SIP/sipcall-4990-000000a1", "VMCONTEXT=default") in new stack
    -- Executing [[email protected]:2] GotoIf("SIP/sipcall-4990-000000a1", "0?200:300") in new stack
    -- Goto (macro-get-vmcontext,s,300)
    -- Executing [[email protected]:300] NoOp("SIP/sipcall-4990-000000a1", "") in new stack
    -- Executing [[email protected]:8] Set("SIP/sipcall-4990-000000a1", "GRPLIST=&[email protected]&[email protected]") in new stack
    -- Executing [[email protected]:9] Set("SIP/sipcall-4990-000000a1", "VMBMSG=custom/Chris_Codespark_Greeting") in new stack
    -- Executing [[email protected]:10] Goto("SIP/sipcall-4990-000000a1", "app-vmblast,vmblast,msg") in new stack
    -- Goto (app-vmblast,vmblast,2)
    -- Executing [[email protected]:2] ExecIf("SIP/sipcall-4990-000000a1", "1?Background(custom/Chris_Codespark_Greeting)") in new stack
    -- <SIP/sipcall-4990-000000a1> Playing 'custom/Chris_Codespark_Greeting.slin' (language 'en')
    -- Executing [[email protected]:3] BackGround("SIP/sipcall-4990-000000a1", "if-correct-press&digits/1") in new stack
    -- <SIP/sipcall-4990-000000a1> Playing 'if-correct-press.ulaw' (language 'en')
    -- <SIP/sipcall-4990-000000a1> Playing 'digits/1.ulaw' (language 'en')
    -- Executing [[email protected]:4] WaitExten("SIP/sipcall-4990-000000a1", "20,") in new stack
  == Spawn extension (app-vmblast, vmblast, 4) exited non-zero on 'SIP/sipcall-4990-000000a1'
  == End MixMonitor Recording SIP/sipcall-4990-000000a1

The logs are indicating that the voicemail message was played, however, it wasn’t heard on the caller’s line and no voicemail was saved.

Very strange and I don’t know how to debug from here.

Thank you very much for any insights here :smile:

-Chris

The obvious thing that I saw is that your “Greeting” is encoded as S-Linear, which may not be compatible with your SIP provider. The U-Law stuff should work though, unless your provider is dumping the audio when the .slin file gets played.

Try removing the greeting and just use ulaw pieces and see of that makes any difference.