Dialplan - processing issue

Hi everybody,

I purchased another DID from my VOIP provider and modified the dialplan the way that each DID would ring on a specific line, but it is not working. From the log I can see that it processes following part of “outbound-local” content

[outbound-local] exten => _NXXXXXXXXX,1,Set(CALLFILENAME=${STRFTIME(${EPOCH},,%Y%m%d-%H%M%S)}-out-${EXTEN}) exten => _NXXXXXXXXX,n,Monitor(wav,${CALLFILENAME},m) exten => _NXXXXXXXXX,n,Dial(${OUTBOUND}/${EXTEN}@${SIPADDRES},${DIAL_TOUT},wW)
instead of “voipms-inbound”, but I have no idea why. Any help or hint what I have wrong in my dialplan is appreciated.

Thank you in advance for all your replies.

[general]
static=yes
writeprotect=no
priorityjumping=no
autofallthrough=yes
clearglobalvars=no
;t38pt_udptl=yes

[globals]
; Default digit timeout.
DIG_TOUT=3
; Default response timeout.
RESP_TOUT=5
; Default dial timeout.
;DIAL_TOUT=30
DIAL_TOUT=40
OUTBOUND=SIP
SIPADDRES=voipms
;GENERAL=SIP/linksys-pap2&SIP/xlite-robert&SIP/polycom
GENERAL=SIP/polycom
BZSIGNS=SIP/bzsigns


;Entry context for external callers.
[mySIPcontext]
; Make sure to include inbound prior to outbound because the _NXXNXXXXXX handler will match the incoming call and create a loop
include => voipms-inbound
include => voipms-outbound


[voipms-inbound]
exten => s,1,Answer() 
exten => s,n,GotoIf($["${BLACKLIST()}" = "1"]?callerid_blacklisted:)
exten => s,n,Set(DB(CALLTRACE/LASTNUMBER)=${CALLERID(num)})
;exten => s,n,Goto(polaskovi,s,1)
exten => 9054690612,n,Goto(polaskovi,s,1)
exten => 2894305436,n,Goto(bzsigns,s,1)
exten => s,n(callerid_blacklisted),Playback(blacklist_voice)
exten => s,n,GotoIf($["${PLAYBACKSTATUS}" = "SUCCESS"]?:junkvoicemail_hangup)
exten => s,n(junkvoicemail_hangup),Wait(3)
exten => s,n,VoiceMail(00@default)
exten => s,n,Hangup()
exten => fax,1,Set(FAXFILE=/var/spool/asterisk/fax/${STRFTIME(${EPOCH},,%Y%m%d-%H%M%S)}-${CALLERID(num)}.tif)
exten => fax,n,Set(FAXFILENOEXT=/var/spool/asterisk/fax/${STRFTIME(${EPOCH},,%Y%m%d-%H%M%S)}-${CALLERID(num)})
exten => fax,n,Wait(6) 
exten => fax,n,ReceiveFAX(${FAXFILE})
exten => fax,n,System('/usr/bin/fax2mail --cid-number ${CALLERID(num)} --cid-name "${CALLERID(name)}" --dest-email polasekr@gmail.com -f ${FAXFILENOEXT} -p')
exten => fax,n,Hangup()
exten => t,1,Goto(polaskovi,s,1)
exten => i,1,Goto(polaskovi,s,1)

;polaskovi
[polaskovi]

exten => s,1,Set(CALLFILENAME=${STRFTIME(${EPOCH},,%Y%m%d-%H%M%S)}-${CALLERID(num)})
exten => s,n,Monitor(wav,${CALLFILENAME},m) 
;exten => s,n,Playback(conversation_recorded)
exten => s,n,Dial(${GENERAL},${DIAL_TOUT},wW)
exten => s,n,GotoIf($["${DIALSTATUS}" = "CHANUNAVAIL"]?t,ext-99-unavail:)
exten => s,n,GotoIf($["${DIALSTATUS}" = "NOANSWER"]?t,ext-99-unavail:)
exten => s,n,GotoIf($["${DIALSTATUS}" = "BUSY"]?t,ext-99-unavail:)
exten => s,n,Hangup()
exten => t,1(ext-99-unavail),Set(TIMEOUT(response)=${RESP_TOUT})
exten => t,n,Set(TIMEOUT(digit)=${DIG_TOUT})
exten => t,n,Goto(1,1)
exten => t,n,Playback(bye)
exten => t,n,Hangup()
exten => i,1,Goto(t,ext-99-unavail)
;exten => 1,1,VoiceMail(99@default,u)
exten => 1,1,VoiceMail(99@default)
exten => 1,n,GotoIf($["${VMSTATUS}" = "USEREXIT"]?s,ext-99-return)
exten => 1,n,Hangup()
exten => 2,1,Hangup()
exten => fax,1,Set(FAXFILE=/var/spool/asterisk/fax/${STRFTIME(${EPOCH},,%Y%m%d-%H%M%S)}-${CALLERID(num)}.tif)
exten => fax,n,Set(FAXFILENOEXT=/var/spool/asterisk/fax/${STRFTIME(${EPOCH},,%Y%m%d-%H%M%S)}-${CALLERID(num)})
exten => fax,n,Wait(6) 
exten => fax,n,ReceiveFAX(${FAXFILE})
exten => fax,n,System('/usr/bin/fax2mail --cid-number ${CALLERID(num)} --cid-name "${CALLERID(name)}" --dest-email polasekr@gmail.com -f ${FAXFILENOEXT} -p')
exten => fax,n,Hangup()


;bzsigns
[bzsigns]

exten => s,1,Set(CALLFILENAME=bzsigns-${STRFTIME(${EPOCH},,%Y%m%d-%H%M%S)}-${CALLERID(num)})
exten => s,n,Monitor(wav,${CALLFILENAME},m) 
;exten => s,n,Playback(conversation_recorded)
exten => s,n,Dial(${BZSIGNS},${DIAL_TOUT},wW)
exten => s,n,GotoIf($["${DIALSTATUS}" = "CHANUNAVAIL"]?t,ext-99-unavail:)
exten => s,n,GotoIf($["${DIALSTATUS}" = "NOANSWER"]?t,ext-99-unavail:)
exten => s,n,GotoIf($["${DIALSTATUS}" = "BUSY"]?t,ext-99-unavail:)
exten => s,n,Hangup()
exten => t,1(ext-99-unavail),Set(TIMEOUT(response)=${RESP_TOUT})
exten => t,n,Set(TIMEOUT(digit)=${DIG_TOUT})
exten => t,n,Goto(1,1)
exten => t,n,Playback(bye)
exten => t,n,Hangup()
exten => i,1,Goto(t,ext-99-unavail)
;exten => 1,1,VoiceMail(99@default,u)
exten => 1,1,VoiceMail(99@default)
exten => 1,n,GotoIf($["${VMSTATUS}" = "USEREXIT"]?s,ext-99-return)
exten => 1,n,Hangup()
exten => 2,1,Hangup()
exten => fax,1,Set(FAXFILE=/var/spool/asterisk/fax/${STRFTIME(${EPOCH},,%Y%m%d-%H%M%S)}-${CALLERID(num)}.tif)
exten => fax,n,Set(FAXFILENOEXT=/var/spool/asterisk/fax/${STRFTIME(${EPOCH},,%Y%m%d-%H%M%S)}-${CALLERID(num)})
exten => fax,n,Wait(6) 
exten => fax,n,ReceiveFAX(${FAXFILE})
exten => fax,n,System('/usr/bin/fax2mail --cid-number ${CALLERID(num)} --cid-name "${CALLERID(name)}" --dest-email polasekr@gmail.com -f ${FAXFILENOEXT} -p')
exten => fax,n,Hangup()

[voipms-outbound]
include => outbound-local
include => outbound-long-distance
include => outbound-international
exten => 91,1,Dial(${DAYCARE},${DIAL_TOUT},wW)
exten => 91,n,Hangup()
exten => daycare,1,Dial(${DAYCARE},${DIAL_TOUT},wW)
exten => daycare,n,Hangup()
exten => 92,1,Dial(${E_and_A},${DIAL_TOUT},wW)
exten => 92,n,Hangup()
exten => e_and_a,1,Dial(${E_and_A},${DIAL_TOUT},wW)
exten => e_and_a,n,Hangup()
exten => 99,1,Dial(${GENERAL},${DIAL_TOUT},wW)
exten => 99,n,Hangup()
exten => polaskovi,1,Dial(${GENERAL},${DIAL_TOUT},wW)
exten => polaskovi,n,Hangup()

exten => t,1(main-menu-out-repeat),Set(TIMEOUT(response)=${RESP_TOUT})
exten => t,n,Set(TIMEOUT(digit)=${DIG_TOUT})
exten => t,n,Background(no_key_pressed)
exten => t,n(main-menu-out-entry),Set(TIMEOUT(response)=${RESP_TOUT})
exten => t,n,Set(TIMEOUT(digit)=${DIG_TOUT})
exten => t,n,Background(main_menu_out)
exten => t,n,WaitExten(${RESP_TOUT})
exten => t,n,Goto(main-menu-out-repeat)
exten => i,1,Goto(t,main-menu-out-repeat)
exten => *100,1,Goto(t,main-menu-out-entry)
exten => *30,1,Goto(custom-blacklist-last,s,1)
exten => *31,1,Goto(custom-blacklist-add,s,1)
exten => *32,1,Goto(custom-blacklist-remove,s,1)
;exten => *50,1,Goto(voice-mailboxes,s,1)
exten => *50,1,VoicemailMain(s${CALLERIDNUM})
exten => polycom,1,VoicemailMain(s${CALLERIDNUM})
exten => *50,2,Hangup
exten => *1,1,Dial(${OUTBOUND}/4168838835@${SIPADDRES},${DIAL_TOUT},wW)
exten => *1,n,Congestion()
exten => *1,n,Hangup()
exten => *11,1,Dial(${OUTBOUND}/4168838835@${SIPADDRES},${DIAL_TOUT},wW)
exten => *11,n,Congestion()
exten => *11,n,Hangup()

[outbound-local]
exten => _NXXXXXXXXX,1,Set(CALLFILENAME=${STRFTIME(${EPOCH},,%Y%m%d-%H%M%S)}-out-${EXTEN})
exten => _NXXXXXXXXX,n,Monitor(wav,${CALLFILENAME},m) 
exten => _NXXXXXXXXX,n,Dial(${OUTBOUND}/${EXTEN}@${SIPADDRES},${DIAL_TOUT},wW)
exten => _NXXXXXXXXX,n,Congestion()
exten => _NXXXXXXXXX,n,Hangup()
exten => 911,1,Dial(${OUTBOUND}/911,${DIAL_TOUT},wW)
exten => 911,n,Hangup()
exten => 9911,1,Dial(${OUTBOUND}/911,${DIAL_TOUT},wW)
exten => 9911,n,Hangup()

[outbound-long-distance]
exten => _1NXXNXXXXXX,1,Set(CALLFILENAME=${STRFTIME(${EPOCH},,%Y%m%d-%H%M%S)}-out-${EXTEN})
exten => _1NXXNXXXXXX,n,Monitor(wav,${CALLFILENAME},m) 
exten => _1NXXNXXXXXX,n,Dial(${OUTBOUND}/${EXTEN}@${SIPADDRES},${DIAL_TOUT},wW)
exten => _1NXXNXXXXXX,n,Playtones(congestion)
exten => _1NXXNXXXXXX,n,Hangup()

[outbound-international]
exten => _011XXXXXXXXXXXX,1,Set(CALLFILENAME=${STRFTIME(${EPOCH},,%Y%m%d-%H%M%S)}-out-${EXTEN})
exten => _011XXXXXXXXXXXX,n,Monitor(wav,${CALLFILENAME},m) 
exten => _011XXXXXXXXXXXX,n,Dial(${OUTBOUND}/${EXTEN}@${SIPADDRES},${DIAL_TOUT},wW)
exten => _011XXXXXXXXXXXX,n,Playtones(congestion)
exten => _011XXXXXXXXXXXX,n,Hangup()

This is the a log snippet when I call my first number from outside

[Oct 31 21:12:56] VERBOSE[12017][C-00000000] netsock2.c:   == Using SIP RTP CoS mark 5
[Oct 31 21:12:56] VERBOSE[12073][C-00000000] pbx.c:     -- Executing [9054690612@mySIPcontext:1] Set("SIP/voipms-00000000", "CALLFILENAME=20141031-211256-out-9054690612") in new stack
[Oct 31 21:12:56] VERBOSE[12073][C-00000000] pbx.c:     -- Executing [9054690612@mySIPcontext:2] Monitor("SIP/voipms-00000000", "wav,20141031-211256-out-9054690612,m") in new stack
[Oct 31 21:12:56] VERBOSE[12073][C-00000000] pbx.c:     -- Executing [9054690612@mySIPcontext:3] Dial("SIP/voipms-00000000", "SIP/9054690612@voipms,40,wW") in new stack
[Oct 31 21:12:56] VERBOSE[12073][C-00000000] netsock2.c:   == Using SIP RTP CoS mark 5
[Oct 31 21:12:56] VERBOSE[12073][C-00000000] app_dial.c:     -- Called SIP/9054690612@voipms
[Oct 31 21:12:56] VERBOSE[12017][C-00000001] netsock2.c:   == Using SIP RTP CoS mark 5
[Oct 31 21:12:56] VERBOSE[12074][C-00000001] pbx.c:     -- Executing [9054690612@mySIPcontext:1] Set("SIP/voipms-00000002", "CALLFILENAME=20141031-211256-out-9054690612") in new stack
[Oct 31 21:12:56] VERBOSE[12074][C-00000001] pbx.c:     -- Executing [9054690612@mySIPcontext:2] Monitor("SIP/voipms-00000002", "wav,20141031-211256-out-9054690612,m") in new stack
[Oct 31 21:12:56] VERBOSE[12074][C-00000001] pbx.c:     -- Executing [9054690612@mySIPcontext:3] Dial("SIP/voipms-00000002", "SIP/9054690612@voipms,40,wW") in new stack
[Oct 31 21:12:56] VERBOSE[12074][C-00000001] netsock2.c:   == Using SIP RTP CoS mark 5
[Oct 31 21:12:56] VERBOSE[12074][C-00000001] app_dial.c:     -- Called SIP/9054690612@voipms
[Oct 31 21:12:56] VERBOSE[12017][C-00000002] netsock2.c:   == Using SIP RTP CoS mark 5
[Oct 31 21:12:56] VERBOSE[12075][C-00000002] pbx.c:     -- Executing [9054690612@mySIPcontext:1] Set("SIP/voipms-00000004", "CALLFILENAME=20141031-211256-out-9054690612") in new stack
[Oct 31 21:12:56] VERBOSE[12075][C-00000002] pbx.c:     -- Executing [9054690612@mySIPcontext:2] Monitor("SIP/voipms-00000004", "wav,20141031-211256-out-9054690612,m") in new stack
[Oct 31 21:12:56] VERBOSE[12075][C-00000002] pbx.c:     -- Executing [9054690612@mySIPcontext:3] Dial("SIP/voipms-00000004", "SIP/9054690612@voipms,40,wW") in new stack
[Oct 31 21:12:56] VERBOSE[12075][C-00000002] netsock2.c:   == Using SIP RTP CoS mark 5
[Oct 31 21:12:56] VERBOSE[12075][C-00000002] app_dial.c:     -- Called SIP/9054690612@voipms
[Oct 31 21:12:56] VERBOSE[12017][C-00000002] chan_sip.c:     -- Got SIP response 486 "Busy Here" back from 184.75.213.210:5060
[Oct 31 21:12:56] VERBOSE[12075][C-00000002] app_dial.c:     -- SIP/voipms-00000005 is busy
[Oct 31 21:12:56] VERBOSE[12075][C-00000002] app_dial.c:   == Everyone is busy/congested at this time (1:1/0/0)
[Oct 31 21:12:56] VERBOSE[12075][C-00000002] pbx.c:     -- Executing [9054690612@mySIPcontext:4] Goto("SIP/voipms-00000004", "polaskovi,s,1") in new stack
[Oct 31 21:12:56] VERBOSE[12075][C-00000002] pbx.c:     -- Goto (polaskovi,s,1)
[Oct 31 21:12:56] VERBOSE[12075][C-00000002] pbx.c:     -- Executing [s@polaskovi:1] Set("SIP/voipms-00000004", "CALLFILENAME=20141031-211256-9054690612") in new stack
[Oct 31 21:12:56] VERBOSE[12075][C-00000002] pbx.c:     -- Executing [s@polaskovi:2] Monitor("SIP/voipms-00000004", "wav,20141031-211256-9054690612,m") in new stack
[Oct 31 21:12:56] VERBOSE[12075][C-00000002] pbx.c:     -- Executing [s@polaskovi:3] Dial("SIP/voipms-00000004", "SIP/polycom,40,wW") in new stack
[Oct 31 21:12:56] VERBOSE[12075][C-00000002] netsock2.c:   == Using SIP RTP CoS mark 5
[Oct 31 21:12:56] VERBOSE[12075][C-00000002] app_dial.c:     -- Called SIP/polycom
[Oct 31 21:12:56] VERBOSE[12075][C-00000002] app_dial.c:     -- SIP/polycom-00000006 is ringing
[Oct 31 21:12:56] VERBOSE[12074][C-00000001] app_dial.c:     -- SIP/voipms-00000003 is ringing
[Oct 31 21:12:56] VERBOSE[12073][C-00000000] app_dial.c:     -- SIP/voipms-00000001 is ringing
[Oct 31 21:12:58] VERBOSE[12075][C-00000002] app_dial.c:     -- SIP/polycom-00000006 answered SIP/voipms-00000004
[Oct 31 21:12:58] VERBOSE[12074][C-00000001] app_dial.c:     -- SIP/voipms-00000003 answered SIP/voipms-00000002
[Oct 31 21:12:58] VERBOSE[12073][C-00000000] app_dial.c:     -- SIP/voipms-00000001 answered SIP/voipms-00000000
[Oct 31 21:13:06] VERBOSE[12073][C-00000000] pbx.c:   == Spawn extension (mySIPcontext, 9054690612, 3) exited non-zero on 'SIP/voipms-00000000'
[Oct 31 21:13:06] VERBOSE[12074][C-00000001] pbx.c:   == Spawn extension (mySIPcontext, 9054690612, 3) exited non-zero on 'SIP/voipms-00000002'
[Oct 31 21:13:07] VERBOSE[12075][C-00000002] pbx.c:   == Spawn extension (polaskovi, s, 3) exited non-zero on 'SIP/voipms-00000004'
[Oct 31 21:13:07] WARNING[12075][C-00000002] res_monitor.c: File /var/spool/asterisk/monitor/20141031-211256-out-9054690612-in not found
[Oct 31 21:13:07] WARNING[12075][C-00000002] res_monitor.c: File /var/spool/asterisk/monitor/20141031-211256-out-9054690612-out not found

and this is a log snippet when I call my second number from outside

[Oct 31 21:14:29] VERBOSE[12017][C-00000003] netsock2.c: == Using SIP RTP CoS mark 5 [Oct 31 21:14:29] VERBOSE[12119][C-00000003] pbx.c: -- Executing [2894305436@mySIPcontext:1] Set("SIP/voipms-00000007", "CALLFILENAME=20141031-211429-out-2894305436") in new stack [Oct 31 21:14:29] VERBOSE[12119][C-00000003] pbx.c: -- Executing [2894305436@mySIPcontext:2] Monitor("SIP/voipms-00000007", "wav,20141031-211429-out-2894305436,m") in new stack [Oct 31 21:14:29] VERBOSE[12119][C-00000003] pbx.c: -- Executing [2894305436@mySIPcontext:3] Dial("SIP/voipms-00000007", "SIP/2894305436@voipms,40,wW") in new stack [Oct 31 21:14:29] VERBOSE[12119][C-00000003] netsock2.c: == Using SIP RTP CoS mark 5 [Oct 31 21:14:29] VERBOSE[12119][C-00000003] app_dial.c: -- Called SIP/2894305436@voipms [Oct 31 21:14:29] VERBOSE[12017][C-00000004] netsock2.c: == Using SIP RTP CoS mark 5 [Oct 31 21:14:29] VERBOSE[12120][C-00000004] pbx.c: -- Executing [2894305436@mySIPcontext:1] Set("SIP/voipms-00000009", "CALLFILENAME=20141031-211429-out-2894305436") in new stack [Oct 31 21:14:29] VERBOSE[12120][C-00000004] pbx.c: -- Executing [2894305436@mySIPcontext:2] Monitor("SIP/voipms-00000009", "wav,20141031-211429-out-2894305436,m") in new stack [Oct 31 21:14:29] VERBOSE[12120][C-00000004] pbx.c: -- Executing [2894305436@mySIPcontext:3] Dial("SIP/voipms-00000009", "SIP/2894305436@voipms,40,wW") in new stack [Oct 31 21:14:29] VERBOSE[12120][C-00000004] netsock2.c: == Using SIP RTP CoS mark 5 [Oct 31 21:14:29] VERBOSE[12120][C-00000004] app_dial.c: -- Called SIP/2894305436@voipms [Oct 31 21:14:29] VERBOSE[12017][C-00000005] netsock2.c: == Using SIP RTP CoS mark 5 [Oct 31 21:14:29] VERBOSE[12121][C-00000005] pbx.c: -- Executing [2894305436@mySIPcontext:1] Set("SIP/voipms-0000000b", "CALLFILENAME=20141031-211429-out-2894305436") in new stack [Oct 31 21:14:29] VERBOSE[12121][C-00000005] pbx.c: -- Executing [2894305436@mySIPcontext:2] Monitor("SIP/voipms-0000000b", "wav,20141031-211429-out-2894305436,m") in new stack [Oct 31 21:14:29] VERBOSE[12121][C-00000005] pbx.c: -- Executing [2894305436@mySIPcontext:3] Dial("SIP/voipms-0000000b", "SIP/2894305436@voipms,40,wW") in new stack [Oct 31 21:14:29] VERBOSE[12121][C-00000005] netsock2.c: == Using SIP RTP CoS mark 5 [Oct 31 21:14:29] VERBOSE[12121][C-00000005] app_dial.c: -- Called SIP/2894305436@voipms [Oct 31 21:14:29] VERBOSE[12017][C-00000005] chan_sip.c: -- Got SIP response 486 "Busy Here" back from 184.75.213.210:5060 [Oct 31 21:14:29] VERBOSE[12121][C-00000005] app_dial.c: -- SIP/voipms-0000000c is busy [Oct 31 21:14:29] VERBOSE[12121][C-00000005] app_dial.c: == Everyone is busy/congested at this time (1:1/0/0) [Oct 31 21:14:29] VERBOSE[12121][C-00000005] pbx.c: -- Executing [2894305436@mySIPcontext:4] Congestion("SIP/voipms-0000000b", "") in new stack [Oct 31 21:14:29] VERBOSE[12121][C-00000005] pbx.c: == Spawn extension (mySIPcontext, 2894305436, 4) exited non-zero on 'SIP/voipms-0000000b' [Oct 31 21:14:29] VERBOSE[12017][C-00000004] chan_sip.c: -- Got SIP response 503 "Service Unavailable" back from 184.75.213.210:5060 [Oct 31 21:14:29] VERBOSE[12120][C-00000004] app_dial.c: -- SIP/voipms-0000000a is circuit-busy [Oct 31 21:14:29] VERBOSE[12120][C-00000004] app_dial.c: == Everyone is busy/congested at this time (1:0/1/0) [Oct 31 21:14:29] VERBOSE[12120][C-00000004] pbx.c: -- Executing [2894305436@mySIPcontext:4] Congestion("SIP/voipms-00000009", "") in new stack [Oct 31 21:14:29] VERBOSE[12120][C-00000004] pbx.c: == Spawn extension (mySIPcontext, 2894305436, 4) exited non-zero on 'SIP/voipms-00000009' [Oct 31 21:14:29] VERBOSE[12017][C-00000003] chan_sip.c: -- Got SIP response 503 "Service Unavailable" back from 184.75.213.210:5060 [Oct 31 21:14:29] VERBOSE[12119][C-00000003] app_dial.c: -- SIP/voipms-00000008 is circuit-busy [Oct 31 21:14:29] VERBOSE[12119][C-00000003] app_dial.c: == Everyone is busy/congested at this time (1:0/1/0) [Oct 31 21:14:29] VERBOSE[12119][C-00000003] pbx.c: -- Executing [2894305436@mySIPcontext:4] Congestion("SIP/voipms-00000007", "") in new stack [Oct 31 21:14:29] VERBOSE[12119][C-00000003] pbx.c: == Spawn extension (mySIPcontext, 2894305436, 4) exited non-zero on 'SIP/voipms-00000007' [Oct 31 21:14:30] VERBOSE[12017][C-00000006] netsock2.c: == Using SIP RTP CoS mark 5 [Oct 31 21:14:30] VERBOSE[12132][C-00000006] pbx.c: -- Executing [2894305436@mySIPcontext:1] Set("SIP/voipms-0000000d", "CALLFILENAME=20141031-211430-out-2894305436") in new stack [Oct 31 21:14:30] VERBOSE[12132][C-00000006] pbx.c: -- Executing [2894305436@mySIPcontext:2] Monitor("SIP/voipms-0000000d", "wav,20141031-211430-out-2894305436,m") in new stack [Oct 31 21:14:30] VERBOSE[12132][C-00000006] pbx.c: -- Executing [2894305436@mySIPcontext:3] Dial("SIP/voipms-0000000d", "SIP/2894305436@voipms,40,wW") in new stack [Oct 31 21:14:30] VERBOSE[12132][C-00000006] netsock2.c: == Using SIP RTP CoS mark 5 [Oct 31 21:14:30] VERBOSE[12132][C-00000006] app_dial.c: -- Called SIP/2894305436@voipms [Oct 31 21:14:30] VERBOSE[12017][C-00000007] netsock2.c: == Using SIP RTP CoS mark 5 [Oct 31 21:14:30] VERBOSE[12133][C-00000007] pbx.c: -- Executing [2894305436@mySIPcontext:1] Set("SIP/voipms-0000000f", "CALLFILENAME=20141031-211430-out-2894305436") in new stack [Oct 31 21:14:30] VERBOSE[12133][C-00000007] pbx.c: -- Executing [2894305436@mySIPcontext:2] Monitor("SIP/voipms-0000000f", "wav,20141031-211430-out-2894305436,m") in new stack [Oct 31 21:14:30] VERBOSE[12133][C-00000007] pbx.c: -- Executing [2894305436@mySIPcontext:3] Dial("SIP/voipms-0000000f", "SIP/2894305436@voipms,40,wW") in new stack [Oct 31 21:14:30] VERBOSE[12133][C-00000007] netsock2.c: == Using SIP RTP CoS mark 5 [Oct 31 21:14:30] VERBOSE[12133][C-00000007] app_dial.c: -- Called SIP/2894305436@voipms [Oct 31 21:14:30] VERBOSE[12017][C-00000008] netsock2.c: == Using SIP RTP CoS mark 5 [Oct 31 21:14:30] VERBOSE[12134][C-00000008] pbx.c: -- Executing [2894305436@mySIPcontext:1] Set("SIP/voipms-00000011", "CALLFILENAME=20141031-211430-out-2894305436") in new stack [Oct 31 21:14:30] VERBOSE[12134][C-00000008] pbx.c: -- Executing [2894305436@mySIPcontext:2] Monitor("SIP/voipms-00000011", "wav,20141031-211430-out-2894305436,m") in new stack [Oct 31 21:14:30] VERBOSE[12134][C-00000008] pbx.c: -- Executing [2894305436@mySIPcontext:3] Dial("SIP/voipms-00000011", "SIP/2894305436@voipms,40,wW") in new stack [Oct 31 21:14:30] VERBOSE[12134][C-00000008] netsock2.c: == Using SIP RTP CoS mark 5 [Oct 31 21:14:30] VERBOSE[12134][C-00000008] app_dial.c: -- Called SIP/2894305436@voipms [Oct 31 21:14:30] VERBOSE[12017][C-00000008] chan_sip.c: -- Got SIP response 486 "Busy Here" back from 184.75.213.210:5060 [Oct 31 21:14:30] VERBOSE[12134][C-00000008] app_dial.c: -- SIP/voipms-00000012 is busy [Oct 31 21:14:30] VERBOSE[12134][C-00000008] app_dial.c: == Everyone is busy/congested at this time (1:1/0/0) [Oct 31 21:14:30] VERBOSE[12134][C-00000008] pbx.c: -- Executing [2894305436@mySIPcontext:4] Congestion("SIP/voipms-00000011", "") in new stack [Oct 31 21:14:30] VERBOSE[12134][C-00000008] pbx.c: == Spawn extension (mySIPcontext, 2894305436, 4) exited non-zero on 'SIP/voipms-00000011' [Oct 31 21:14:30] VERBOSE[12017][C-00000007] chan_sip.c: -- Got SIP response 503 "Service Unavailable" back from 184.75.213.210:5060 [Oct 31 21:14:30] VERBOSE[12133][C-00000007] app_dial.c: -- SIP/voipms-00000010 is circuit-busy [Oct 31 21:14:30] VERBOSE[12133][C-00000007] app_dial.c: == Everyone is busy/congested at this time (1:0/1/0) [Oct 31 21:14:30] VERBOSE[12133][C-00000007] pbx.c: -- Executing [2894305436@mySIPcontext:4] Congestion("SIP/voipms-0000000f", "") in new stack [Oct 31 21:14:30] VERBOSE[12133][C-00000007] pbx.c: == Spawn extension (mySIPcontext, 2894305436, 4) exited non-zero on 'SIP/voipms-0000000f' [Oct 31 21:14:30] VERBOSE[12017][C-00000006] chan_sip.c: -- Got SIP response 503 "Service Unavailable" back from 184.75.213.210:5060 [Oct 31 21:14:30] VERBOSE[12132][C-00000006] app_dial.c: -- SIP/voipms-0000000e is circuit-busy [Oct 31 21:14:30] VERBOSE[12132][C-00000006] app_dial.c: == Everyone is busy/congested at this time (1:0/1/0) [Oct 31 21:14:30] VERBOSE[12132][C-00000006] pbx.c: -- Executing [2894305436@mySIPcontext:4] Congestion("SIP/voipms-0000000d", "") in new stack [Oct 31 21:14:30] VERBOSE[12132][C-00000006] pbx.c: == Spawn extension (mySIPcontext, 2894305436, 4) exited non-zero on 'SIP/voipms-0000000d' [Oct 31 21:14:31] VERBOSE[12017][C-00000009] netsock2.c: == Using SIP RTP CoS mark 5

No priority one line matches the number in your inbound context.

More generally, you don’t want to include outbound rules in contexts reachable from inbound calls.

Thank you for your help. I made those changes and got it working.