Upgraded to * 11 and have incoming call issue

Morning all,

I am implementing a new Asterisk server version 11.7 and our current one is 1.4. I believe my current dial plan to be migrated to include changes between the two however when I moved my server last night incoming calls that dialed an extension were getting the invalid message. I can call internal extensions just fine and can dial out but inbound to an extension does not work. My initial thought is that something may have changed in the macro usage? Below is my dial plan, I think the issue may be the localexten macro in that its not passing the correct extension?

[general]
static=yes
writeprotect=no
autofallthrough=yes

[globals]

MAX_TIME = 20
BRDWNG = DAHDI/g1
LINEONE => SIP/270&SIP/270a&SIP/270b&SIP/270c
LINETWO => SIP/271&SIP/271a&SIP/271b&SIP/271c
LINETHREE => SIP/272&SIP/272a&SIP/272b&SIP/272c
LINEFOUR => SIP/273&SIP/273a&SIP/273b&SIP/273c

[home]

include => test
include => default
include => conference
include => outbound
include => ConferenceRooms

[ConferenceRooms]
exten => 602,1,NoOp()
same => n,Set(CONFBRIDGE(user,pin)=)
same => n,ConfBridge(${EXTEN})

exten => 7000,1,NoOp()
same => n,Set(CONFBRIDGE(user,pin)=)
same => n,ConfBridge(${EXTEN})

[conference]

exten => 75,1,Answer
exten => 75,2,Wait(1)
exten => 75,3,Authenticate()
exten => 75,4,MeetMe(75,p,c,M)
exten => 75,5,Playback(vmr-goobye)
exten => 75,6,Hangup

exten => 7050,1,Answer
exten => 7050,2,Wait(1)
exten => 7050,3,Authenticate()
exten => 7050,4,Meetme(7050,p,c,M)
exten => 7050,5,Playback(vmr-goobye)
exten => 7050,6,Hangup

exten => 900,1,Answer
exten => 900,2,Wait(1)
exten => 900,3,Authenticate()
exten => 900,4,MeetMe(900,p,c,M)
exten => 900,5,Playback(vmr-goobye)
exten => 900,6,Hangup

exten => 905,1,Answer
exten => 905,2,Wait(1)
exten => 905,3,Authenticate()
exten => 905,4,MeetMe(905,p,c,M)
exten => 905,5,Playback(vmr-goobye)
exten => 905,6,Hangup

exten => 4005,1,Answer
exten => 4005,2,Wait(1)
exten => 4005,3,Authenticate()
exten => 4005,4,MeetMe(4000,p,c,M)
exten => 4005,5,Playback(vm-goodbye)
exten => 4005,6,Hangup

exten => 5000,1,Answer
exten => 5000,2,Wait(1)
exten => 5000,3,Authenticate()
exten => 5000,4,Meetme(5000,p,c,M)
exten => 5000,5,Playback(vm-goodbye)
exten => 5000,6,Hangup

exten => 6000,1,Answer
exten => 6000,2,Wait(1)
exten => 6000,3,Authenticate()
exten => 6000,4,Meetme(6000,p,c,M)
exten => 6000,5,Playback(vm-goodbye)
exten => 6000,6,Hangup

exten => 7000,1,Answer
exten => 7000,2,Wait(1)
exten => 7000,3,Authenticate()
exten => 7000,4,Meetme(7000,p,c,M)
exten => 7000,5,Playback(vm-goodbye)
exten => 7000,6,Hangup

[scott]

exten => s,1,Dial(SIP/201&SIP/202&SIP/203&SIP/230,20,Tt)
exten => s,2,Voicemail(u201)
exten => s,102,Voicemail(b201)

[fax-in]
exten => _X.,1,Dial(IAX2/iaxmodem)

[fax-out]
exten => _X.,1,Dial(SIP/312xxxxxx/${EXTEN})

[default]

exten => 270,1,Dial(${LINEONE},${MAX_TIME},Tt)
exten => 270,n,Voicemail(u270)
exten => 271,1,Dial(${LINETWO},${MAX_TIME},Tt)
exten => 271,n,Voicemail(u271)
exten => 272,1,Dial(${LINETHREE},${MAX_TIME},Tt)
exten => 272,n,Voicemail(u272)
exten => 273,1,Dial(${LINEFOUR},${MAX_TIME},Tt)
exten => 273,n,Voicemail(u273)

exten => _2XX,1,Macro(localexten,SIP/${EXTEN})
exten => _1XX,1,Macro(localexten,SIP/${EXTEN})

exten => 8,1,VoicemailMain

[macro-localexten]

exten => s,1,Dial(${ARG1},${MAX_TIME},Tt)
exten => s,2,Voicemail(u${MACRO_EXTEN})
exten => s,102,Voicemail(b${MACRO_EXTEN})

[internal]
exten => 270,1,Dial(${LINEONE},${MAX_TIME},Tt)
exten => 270,n,Voicemail(u270)
exten => 271,1,Dial(${LINETWO},${MAX_TIME},Tt)
exten => 271,n,Voicemail(u271)(SETCALLERID(num)=312xxxxxx)
exten => 272,1,Dial(${LINETHREE},${MAX_TIME},Tt)
exten => 272,n,Voicemail(u272)
exten => 273,1,Dial(${LINEFOUR},${MAX_TIME},Tt)
exten => 273,n,Voicemail(u273)

exten => _2XX,1,Goto(SIP/${EXTEN})

[outbound]

exten => _1NXXNXXXXX.,1,Set(CALLERID(num)=312xxxxxx)
exten => _1NXXNXXXXX.,n,Dial(${BRDWNG}/${EXTEN})
exten => _1NXXNXXXXX.,n,Hangup

exten => _NXXNXXXXXX,1,Set(CALLERID(num)=312xxxxxx)
exten => _NXXNXXXXXX,n,Dial(${BRDWNG}/1${EXTEN})
exten => _NXXNXXXXXX,n,Hangup

exten => _NXXXXXX,1,Set(CALLERID(num)=312xxxxxx)
exten => _NXXXXXX,n,Dial(${BRDWNG}/1312${EXTEN})
exten => _NXXXXXX,n,Hangup

exten => _011.,1,Set(CALLERID(num)=312xxxxxx)
exten => _011.,n,Dial(${BRDWNG}/${EXTEN})
exten => _011.,n,Hangup

[PLAR]

exten => s,1,Dial(SIP/222&SIP/219)
exten => s,n,Hangup

;[follow-ext]

; exten => _2XX,1,Macro(localexten,SIP/${EXTEN})
; exten => _1XX,1,Macro(localexten,SIP/${EXTEN})

; include = outbound

[bell]

; JUMP TO SPECIFIC PHONE
exten => xxxx,1,Goto(default,218,1) ;
exten => xxxx,1,Goto(default,206,1) ;
exten => xxxx,1,Goto(default,211,1) ;
exten => xxxx,1,Goto(default,216,1) ;
exten => xxxx,1,Goto(default,205,1) ;
exten => xxxx,1,Goto(default,212,1) ;
exten => xxxx,1,Goto(default,217,1) ;
;exten => xxxx,1,Goto(default,4000,1)
;exten => xxxx,1,Goto(default,290,1) ;
;exten => xxxx,1,Goto(default,224,1) ;
exten => xxxx,1,Goto(scott,s,1) ;
exten => xxxx,1,Goto(default,202,1) ;
exten => xxxx,1,Goto(default,203,1) ;
exten => xxxx,1,Goto(default,220,1) ;
exten => xxxx,1,Goto(default,208,1) ;
exten => xxxx,1,Goto(default,101,1) ;

;exten => xxxx,1,Goto(default,270,1)
exten => xxxx,1,Goto(default,271,1)
exten => xxxx,1,Goto(default,272,1)
exten => xxxx,1,Goto(default,273,1)

;exten => 212,1,Followme(4444)

; JUMP TO OPERATOR
;exten => 312xxxxxxx,1,Goto(s,1)
;exten => 9911,1,Goto(s,1)
exten => 5096,1,Goto(s,1)

exten => s,1,Answer
exten => s,2,Background(WELCOME)
exten => s,3,Set(TIMEOUT(digit)=4)
exten => s,4,Set(TIMEOUT(response)=10)
;exten => s,3,DigitTimeout(4)
;exten => s,4,ResponseTimeout(10)
exten => s,5,WaitExten

exten => 1,1,Directory(default)

exten => 8,1,VoicemailMain()

Show us the cli output.

Here is what I have in the logs, the server is not hooked up to my PRI line right now as I have to move back to my 1.4 server.

[Feb 11 15:11:07] VERBOSE[2034][C-00000004] sig_pri.c: – Accepting call from ‘3123999376’ to ‘5096’ on channel 0/1, span 1
[Feb 11 15:11:07] VERBOSE[3044][C-00000004] pbx.c: – Executing [5096@bell:1] Goto(“DAHDI/i1/3123999376-3”, “s,1”) in new stack
[Feb 11 15:11:07] VERBOSE[3044][C-00000004] pbx.c: – Goto (bell,s,1)
[Feb 11 15:11:07] VERBOSE[3044][C-00000004] pbx.c: – Executing [s@bell:1] Answer(“DAHDI/i1/3123999376-3”, “”) in new stack
[Feb 11 15:11:07] VERBOSE[3044][C-00000004] pbx.c: – Executing [s@bell:2] BackGround(“DAHDI/i1/3123999376-3”, “WELCOME”) in new stack
[Feb 11 15:11:07] VERBOSE[3044][C-00000004] file.c: – <DAHDI/i1/3123999376-3> Playing ‘WELCOME.gsm’ (language ‘en’)
[Feb 11 15:11:15] VERBOSE[3044][C-00000004] pbx.c: == CDR updated on DAHDI/i1/3123999376-3
[Feb 11 15:11:15] VERBOSE[3044][C-00000004] pbx.c: – Executing [205@bell:1] Goto(“DAHDI/i1/3123999376-3”, “default,205|1”) in new stack
[Feb 11 15:11:15] VERBOSE[3044][C-00000004] pbx.c: – Goto (bell,default,205)
[Feb 11 15:11:15] VERBOSE[3044][C-00000004] pbx.c: – Channel ‘DAHDI/i1/3123999376-3’ sent to invalid extension: context,exten,priority=bell,default,205
[Feb 11 15:11:15] VERBOSE[3044][C-00000004] pbx.c: – Executing [i@bell:1] Playback(“DAHDI/i1/3123999376-3”, “invalid”) in new stack
[Feb 11 15:11:15] VERBOSE[3044][C-00000004] file.c: – <DAHDI/i1/3123999376-3> Playing ‘invalid.ulaw’ (language ‘en’)
[Feb 11 15:11:19] VERBOSE[3044][C-00000004] pbx.c: – Executing [i@bell:2] Goto(“DAHDI/i1/3123999376-3”, “s,1”) in new stack
[Feb 11 15:11:19] VERBOSE[3044][C-00000004] pbx.c: – Goto (bell,s,1)
[Feb 11 15:11:19] VERBOSE[3044][C-00000004] pbx.c: – Executing [s@bell:1] Answer(“DAHDI/i1/3123999376-3”, “”) in new stack
[Feb 11 15:11:19] VERBOSE[3044][C-00000004] pbx.c: – Executing [s@bell:2] BackGround(“DAHDI/i1/3123999376-3”, “WELCOME”) in new stack
[Feb 11 15:11:19] VERBOSE[3044][C-00000004] file.c: – <DAHDI/i1/3123999376-3> Playing ‘WELCOME.gsm’ (language ‘en’)
[Feb 11 15:11:22] VERBOSE[2034][C-00000004] sig_pri.c: – Span 1: Channel 0/1 got hangup request, cause 16
[Feb 11 15:11:22] VERBOSE[3044][C-00000004] pbx.c: == Spawn extension (bell, s, 2) exited non-zero on ‘DAHDI/i1/3123999376-3’
[Feb 11 15:11:22] VERBOSE[3044][C-00000004] chan_dahdi.c: – Hungup ‘DAHDI/i1/3123999376-3’
[Feb 11 15:12:03] VERBOSE[2034][C-00000005] sig_pri.c: – Accepting call from ‘3123999376’ to ‘5096’ on channel 0/1, span 1
[Feb 11 15:12:03] VERBOSE[3055][C-00000005] pbx.c: – Executing [5096@bell:1] Goto(“DAHDI/i1/3123999376-4”, “s,1”) in new stack
[Feb 11 15:12:03] VERBOSE[3055][C-00000005] pbx.c: – Goto (bell,s,1)
[Feb 11 15:12:03] VERBOSE[3055][C-00000005] pbx.c: – Executing [s@bell:1] Answer(“DAHDI/i1/3123999376-4”, “”) in new stack
[Feb 11 15:12:03] VERBOSE[3055][C-00000005] pbx.c: – Executing [s@bell:2] BackGround(“DAHDI/i1/3123999376-4”, “WELCOME”) in new stack
[Feb 11 15:12:03] VERBOSE[3055][C-00000005] file.c: – <DAHDI/i1/3123999376-4> Playing ‘WELCOME.gsm’ (language ‘en’)
[Feb 11 15:12:06] VERBOSE[2034][C-00000006] sig_pri.c: – Accepting call from ‘8887370590’ to ‘5096’ on channel 0/2, span 1
[Feb 11 15:12:06] VERBOSE[3057][C-00000006] pbx.c: – Executing [5096@bell:1] Goto(“DAHDI/i1/8887370590-5”, “s,1”) in new stack
[Feb 11 15:12:06] VERBOSE[3057][C-00000006] pbx.c: – Goto (bell,s,1)
[Feb 11 15:12:06] VERBOSE[3057][C-00000006] pbx.c: – Executing [s@bell:1] Answer(“DAHDI/i1/8887370590-5”, “”) in new stack
[Feb 11 15:12:06] VERBOSE[3057][C-00000006] pbx.c: – Executing [s@bell:2] BackGround(“DAHDI/i1/8887370590-5”, “WELCOME”) in new stack
[Feb 11 15:12:06] VERBOSE[3057][C-00000006] file.c: – <DAHDI/i1/8887370590-5> Playing ‘WELCOME.gsm’ (language ‘en’)
[Feb 11 15:12:13] VERBOSE[2034][C-00000006] sig_pri.c: – Span 1: Channel 0/2 got hangup request, cause 16
[Feb 11 15:12:13] VERBOSE[3057][C-00000006] pbx.c: == Spawn extension (bell, s, 2) exited non-zero on ‘DAHDI/i1/8887370590-5’
[Feb 11 15:12:13] VERBOSE[3057][C-00000006] chan_dahdi.c: – Hungup ‘DAHDI/i1/8887370590-5’
[Feb 11 15:12:13] VERBOSE[3055][C-00000005] pbx.c: – Invalid extension ‘70’ in context ‘bell’ on DAHDI/i1/3123999376-4
[Feb 11 15:12:13] VERBOSE[3055][C-00000005] pbx.c: == CDR updated on DAHDI/i1/3123999376-4
[Feb 11 15:12:13] VERBOSE[3055][C-00000005] pbx.c: – Executing [i@bell:1] Playback(“DAHDI/i1/3123999376-4”, “invalid”) in new stack
[Feb 11 15:12:13] VERBOSE[3055][C-00000005] file.c: – <DAHDI/i1/3123999376-4> Playing ‘invalid.ulaw’ (language ‘en’)
[Feb 11 15:12:17] VERBOSE[3055][C-00000005] pbx.c: – Executing [i@bell:2] Goto(“DAHDI/i1/3123999376-4”, “s,1”) in new stack
[Feb 11 15:12:17] VERBOSE[3055][C-00000005] pbx.c: – Goto (bell,s,1)
[Feb 11 15:12:17] VERBOSE[3055][C-00000005] pbx.c: – Executing [s@bell:1] Answer(“DAHDI/i1/3123999376-4”, “”) in new stack
[Feb 11 15:12:17] VERBOSE[3055][C-00000005] pbx.c: – Executing [s@bell:2] BackGround(“DAHDI/i1/3123999376-4”, “WELCOME”) in new stack
[Feb 11 15:12:17] VERBOSE[3055][C-00000005] file.c: – <DAHDI/i1/3123999376-4> Playing ‘WELCOME.gsm’ (language ‘en’)
[Feb 11 15:12:20] VERBOSE[3055][C-00000005] pbx.c: == CDR updated on DAHDI/i1/3123999376-4
[Feb 11 15:12:20] VERBOSE[3055][C-00000005] pbx.c: – Executing [200@bell:1] Goto(“DAHDI/i1/3123999376-4”, “default,200|1”) in new stack
[Feb 11 15:12:20] VERBOSE[3055][C-00000005] pbx.c: – Goto (bell,default,200)
[Feb 11 15:12:20] VERBOSE[3055][C-00000005] pbx.c: – Channel ‘DAHDI/i1/3123999376-4’ sent to invalid extension: context,exten,priority=bell,default,200
[Feb 11 15:12:20] VERBOSE[3055][C-00000005] pbx.c: – Executing [i@bell:1] Playback(“DAHDI/i1/3123999376-4”, “invalid”) in new stack
[Feb 11 15:12:20] VERBOSE[3055][C-00000005] file.c: – <DAHDI/i1/3123999376-4> Playing ‘invalid.ulaw’ (language ‘en’)
[Feb 11 15:12:21] VERBOSE[2037] chan_sip.c: – Registered SIP ‘206’ at 10.11.1.13:5060
[Feb 11 15:12:21] NOTICE[2037] chan_sip.c: Peer ‘206’ is now Reachable. (220ms / 2000ms)
[Feb 11 15:12:24] VERBOSE[3055][C-00000005] pbx.c: – Executing [i@bell:2] Goto(“DAHDI/i1/3123999376-4”, “s,1”) in new stack
[Feb 11 15:12:24] VERBOSE[3055][C-00000005] pbx.c: – Goto (bell,s,1)
[Feb 11 15:12:24] VERBOSE[3055][C-00000005] pbx.c: – Executing [s@bell:1] Answer(“DAHDI/i1/3123999376-4”, “”) in new stack
[Feb 11 15:12:24] VERBOSE[3055][C-00000005] pbx.c: – Executing [s@bell:2] BackGround(“DAHDI/i1/3123999376-4”, “WELCOME”) in new stack
[Feb 11 15:12:24] VERBOSE[3055][C-00000005] file.c: – <DAHDI/i1/3123999376-4> Playing ‘WELCOME.gsm’ (language ‘en’)
[Feb 11 15:12:27] VERBOSE[3055][C-00000005] pbx.c: == CDR updated on DAHDI/i1/3123999376-4
[Feb 11 15:12:27] VERBOSE[3055][C-00000005] pbx.c: – Executing [7000@bell:1] Goto(“DAHDI/i1/3123999376-4”, “conference,7000,1”) in new stack
[Feb 11 15:12:27] VERBOSE[3055][C-00000005] pbx.c: – Goto (conference,7000,1)
[Feb 11 15:12:27] WARNING[3055][C-00000005] pbx.c: Channel ‘DAHDI/i1/3123999376-4’ sent to invalid extension but no invalid handler: context,exten,priority=conference,7000,1
[Feb 11 15:12:27] VERBOSE[3055][C-00000005] chan_dahdi.c: – Hungup ‘DAHDI/i1/3123999376-4’

I’m thinking maybe the macro is not getting the correct information and therefor getting invalid. If this is the reason for the behavior should it be changed like so? Just thinking out loud…

[macro-localexten]

exten => s,1,Dial(${ARG1},${MAX_TIME},Tt)
exten => s,2,Voicemail(u${MACRO_EXTEN})
exten => s,102,Voicemail(b${MACRO_EXTEN})

[macro-localexten]

exten => s,1,Dial(${ARG1},10)
exten => s,2,Voicemail(${MACRO_EXTEN},u)
exten => s,102,Voicemail(${MACRO_EXTEN},u)

exten => xxxx must be exten => _XXXX

I took out the real numbers in the dial plan and put X’s in there

So this is what I have narrowed it down to:

On my production box 1.4, when I call the main number and dial an extension this the normal response from the dial plan in the cli…

– Accepting call from ‘3123999376’ to ‘5096’ on channel 0/7, span 1
– Executing [5096@bell:1] Goto(“DAHDI/7-1”, “s|1”) in new stack
– Goto (bell,s,1)
– Executing [s@bell:1] Answer(“DAHDI/7-1”, “”) in new stack
– Executing [s@bell:2] BackGround(“DAHDI/7-1”, “WELCOME”) in new stack
– <DAHDI/7-1> Playing ‘WELCOME’ (language ‘en’)
[Feb 12 09:03:53] NOTICE[10535]: rtp.c:1334 ast_rtp_read: Unknown RTP codec 126 received from ‘192.168.30.9’
== CDR updated on DAHDI/7-1
– Executing [212@bell:1] Goto(“DAHDI/7-1”, “default|212|1”) in new stack
– Goto (default,212,1)
– Executing [212@default:1] Macro(“DAHDI/7-1”, “localexten|SIP/212”) in new stack
– Executing [s@macro-localexten:1] Dial(“DAHDI/7-1”, “SIP/212|20|Tt|M”) in new stack
– Called 212
– SIP/212-0c1b8a60 is ringing
– Channel 0/7, span 1 got hangup request, cause 16
== Spawn extension (macro-localexten, s, 1) exited non-zero on ‘DAHDI/7-1’ in macro ‘localexten’
== Spawn extension (default, 212, 1) exited non-zero on ‘DAHDI/7-1’
– Hungup ‘DAHDI/7-1’

When I was dialing yesterday I got this in the cli. for some reason its not even hitting the macro for dialing internal extensions. It should get the call, grab the extension and go to the default context and then be passed to the macro. I did find in the bell context a pipe instead of a comma so that may be throwing everything off.

[Feb 11 15:19:25] VERBOSE[2034][C-00000008] sig_pri.c: – Accepting call from ‘3123999376’ to ‘5096’ on channel 0/1, span 1
[Feb 11 15:19:25] VERBOSE[3249][C-00000008] pbx.c: – Executing [5096@bell:1] Goto(“DAHDI/i1/3123999376-7”, “s,1”) in new stack
[Feb 11 15:19:25] VERBOSE[3249][C-00000008] pbx.c: – Goto (bell,s,1)
[Feb 11 15:19:25] VERBOSE[3249][C-00000008] pbx.c: – Executing [s@bell:1] Answer(“DAHDI/i1/3123999376-7”, “”) in new stack
[Feb 11 15:19:25] VERBOSE[3249][C-00000008] pbx.c: – Executing [s@bell:2] BackGround(“DAHDI/i1/3123999376-7”, “WELCOME”) in new st
ack
[Feb 11 15:19:25] VERBOSE[3249][C-00000008] file.c: – <DAHDI/i1/3123999376-7> Playing ‘WELCOME.gsm’ (language ‘en’)
[Feb 11 15:19:30] VERBOSE[3249][C-00000008] pbx.c: == CDR updated on DAHDI/i1/3123999376-7
[Feb 11 15:19:30] VERBOSE[3249][C-00000008] pbx.c: – Executing [206@bell:1] Goto(“DAHDI/i1/3123999376-7”, “default,206|1”) in new
stack
[Feb 11 15:19:30] VERBOSE[3249][C-00000008] pbx.c: – Goto (bell,default,206)
[Feb 11 15:19:30] VERBOSE[3249][C-00000008] pbx.c: – Channel ‘DAHDI/i1/3123999376-7’ sent to invalid extension: context,exten,prio
rity=bell,default,206
[Feb 11 15:19:30] VERBOSE[3249][C-00000008] pbx.c: – Executing [i@bell:1] Playback(“DAHDI/i1/3123999376-7”, “invalid”) in new stac
k
[Feb 11 15:19:30] VERBOSE[3249][C-00000008] file.c: – <DAHDI/i1/3123999376-7> Playing ‘invalid.ulaw’ (language ‘en’)

This issue was resolved, a line that did not copy over

exten => _2XX,1,Goto(default,${EXTEN}|1)

The pipe after the extension variable was causing the issue not passing the correct variables for the call, once it was changed to a comma everything works great.