NEED MORE INFO Outbound Trunk Issues on 13

[boilermanc]

Hi all, installed the 13.13 on a pi today. I have had it running on an older build for some time with no issues. So didnt change any of the outside variables. But I have a vitelity and simon trunks that i use. Again, they all worked on my other build. I have my inbound routes working. But I cannot get the outbound to work for either one.

I have a support ticket into vitelity so will see what they say but wanted to see what i can troubleshoot. The inbound registers but not the outbound. I have a support ticket into bill too.

I have NAT setup and have configured them in the NAT settings.

I followed the examples on this post: https://pbxinaflash.com/community/threads/outbound-calls-to-vitelity-going-to-inbound-server.17833/

That didnt work. I am assuming its a trunk issue since it cannot register with Vitelity.

Where to start?

Thanks!

 

[dallas]

Has your new pi replaced the old pi? It using the same IP address?
What do you see in the asterisk logs when you try to place a call?

 

[boilermanc]

Has your new pi replaced the old pi? It using the same IP address?
What do you see in the asterisk logs when you try to place a call?

hey dallas, same pi. same ip address. literally pulled the card and reloaded with 13 then fired it back up. no changes to the router. no changes to my pap (i did change the secret in the pap to match the new codes). has to be something in the pbx...

 

[boilermanc]

Trying to do some tracing. Just tried another outbound call and this is what is in the log. I now get a dial tone but i get a fast busy when i dial out. Still not registering with Vitelity on the outbound side.

[2018-02-21 10:51:56] VERBOSE[1828][C-00000003] netsock2.c: Using SIP RTP TOS bits 184
[2018-02-21 10:51:56] VERBOSE[1828][C-00000003] netsock2.c: Using SIP RTP CoS mark 5
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [6785211798@from-internal:1] Macro("SIP/701-00000005", "user-callerid,LIMIT,EXTERNAL,") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/701-00000005", "TOUCH_MONITOR=1519228316.5") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [s@macro-user-callerid:2] Set("SIP/701-00000005", "AMPUSER=701") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [s@macro-user-callerid:3] GotoIf("SIP/701-00000005", "0?report") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [s@macro-user-callerid:4] ExecIf("SIP/701-00000005", "1?Set(REALCALLERIDNUM=701)") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [s@macro-user-callerid:5] Set("SIP/701-00000005", "AMPUSER=701") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [s@macro-user-callerid:6] GotoIf("SIP/701-00000005", "0?limit") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [s@macro-user-callerid:7] Set("SIP/701-00000005", "AMPUSERCIDNAME=701") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [s@macro-user-callerid:8] GotoIf("SIP/701-00000005", "0?report") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [s@macro-user-callerid:9] Set("SIP/701-00000005", "AMPUSERCID=701") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [s@macro-user-callerid:10] Set("SIP/701-00000005", "__DIAL_OPTIONS=Ttr") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [s@macro-user-callerid:11] Set("SIP/701-00000005", "CALLERID(all)="701" <701>") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [s@macro-user-callerid:12] GotoIf("SIP/701-00000005", "0?limit") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("SIP/701-00000005", "1?Set(GROUP(concurrency_limit)=701)") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [s@macro-user-callerid:14] GotoIf("SIP/701-00000005", "1?continue") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx_builtins.c: Goto (macro-user-callerid,s,27)
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [s@macro-user-callerid:27] Set("SIP/701-00000005", "CALLERID(number)=701") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [s@macro-user-callerid:28] Set("SIP/701-00000005", "CALLERID(name)=701") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [s@macro-user-callerid:29] GotoIf("SIP/701-00000005", "0?cnum") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [s@macro-user-callerid:30] Set("SIP/701-00000005", "CDR(cnam)=701") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [s@macro-user-callerid:31] Set("SIP/701-00000005", "CDR(cnum)=701") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [s@macro-user-callerid:32] Set("SIP/701-00000005", "CHANNEL(language)=en") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [6785211798@from-internal:2] Gosub("SIP/701-00000005", "sub-record-check,s,1(out,6785211798,dontcare)") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/701-00000005", "0?initialized") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [s@sub-record-check:2] Set("SIP/701-00000005", "__REC_STATUS=INITIALIZED") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [s@sub-record-check:3] Set("SIP/701-00000005", "NOW=1519228316") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [s@sub-record-check:4] Set("SIP/701-00000005", "__DAY=21") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [s@sub-record-check:5] Set("SIP/701-00000005", "__MONTH=02") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [s@sub-record-check:6] Set("SIP/701-00000005", "__YEAR=2018") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [s@sub-record-check:7] Set("SIP/701-00000005", "__TIMESTR=20180221-105156") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [s@sub-record-check:8] Set("SIP/701-00000005", "__FROMEXTEN=701") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [s@sub-record-check:9] Set("SIP/701-00000005", "__MON_FMT=wav") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/701-00000005", "Recordings initialized") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/701-00000005", "0?Set(ARG3=dontcare)") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [s@sub-record-check:12] Set("SIP/701-00000005", "REC_POLICY_MODE_SAVE=") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/701-00000005", "0?Set(REC_STATUS=NO)") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/701-00000005", "3?checkaction") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx_builtins.c: Goto (sub-record-check,s,17)
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/701-00000005", "1?sub-record-check,out,1") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx_builtins.c: Goto (sub-record-check,out,1)
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [out@sub-record-check:1] NoOp("SIP/701-00000005", "Outbound Recording Check from 701 to 6785211798") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [out@sub-record-check:2] Set("SIP/701-00000005", "RECMODE=dontcare") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [out@sub-record-check:3] ExecIf("SIP/701-00000005", "1?Goto(routewins)") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx_builtins.c: Goto (sub-record-check,out,7)
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [out@sub-record-check:7] Gosub("SIP/701-00000005", "recordcheck,1(dontcare,out,6785211798)") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/701-00000005", "Starting recording check against dontcare") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/701-00000005", "dontcare") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/701-00000005", "") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [out@sub-record-check:8] Return("SIP/701-00000005", "") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [6785211798@from-internal:3] Set("SIP/701-00000005", "MOHCLASS=default") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [6785211798@from-internal:4] Set("SIP/701-00000005", "_NODEST=") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [6785211798@from-internal:5] Macro("SIP/701-00000005", "outisbusy,") in new stack
[2018-02-21 10:51:56] WARNING[22063][C-00000003] app_macro.c: No such context 'macro-outisbusy' for macro 'outisbusy'. Was called by 6785211798@from-internal
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [6785211798@from-internal:6] Macro("SIP/701-00000005", "outisbusy,") in new stack
[2018-02-21 10:51:56] WARNING[22063][C-00000003] app_macro.c: No such context 'macro-outisbusy' for macro 'outisbusy'. Was called by 6785211798@from-internal
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [6785211798@from-internal:7] Congestion("SIP/701-00000005", "20") in new stack
[2018-02-21 10:51:56] WARNING[22063][C-00000003] channel.c: Prodding channel 'SIP/701-00000005' failed
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Spawn extension (from-internal, 6785211798, 7) exited non-zero on 'SIP/701-00000005'
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [h@from-internal:1] Macro("SIP/701-00000005", "hangupcall") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("SIP/701-00000005", "1?theend") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("SIP/701-00000005", "0?Set(CDR(recordingfile)=)") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [s@macro-hangupcall:4] Hangup("SIP/701-00000005", "") in new stack
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/701-00000005' in macro 'hangupcall'
[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/701-00000005'

 

[dallas]

[2018-02-21 10:51:56] VERBOSE[22063][C-00000003] pbx.c: Executing [6785211798@from-internal:5] Macro("SIP/701-00000005", "outisbusy,") in new stack
[2018-02-21 10:51:56] WARNING[22063][C-00000003] app_macro.c: No such context 'macro-outisbusy' for macro 'outisbusy'. Was called by 6785211798@from-internal

There's something odd here. The pbx is looking for a macro called "outisbusy" but it doesn't exist. Although this may be due to the trunks not being registered.

 

[boilermanc]

Yeah i dont know. Its been a real head pounder of a day. I am starting probably the 5th time to reinstall and see what will happen. I have read about everything i can find. I have no outbound working. But yet, yesterday morning outbound worked fine until i reloaded. thanks for your help. Will see what happens with the next build.

 

[wardmundy]

Look in the log and see what’s going on with the trunk registrations.

 

[dallas]

Yeah i dont know. Its been a real head pounder of a day. I am starting probably the 5th time to reinstall and see what will happen. I have read about everything i can find. I have no outbound working. But yet, yesterday morning outbound worked fine until i reloaded. thanks for your help. Will see what happens with the next build.

I'm happy to take a look at the log and see if I can spot something.

 

[boilermanc]

Thanks guys really appreciate it. I will post some logs but now see another issue that i think just cropped up. I googled some answers but didnt find much. My PAP is not connecting. I'm going to start another thread and see if i can figure that out and not convoluted this one. I have to get that back online so i can test again...