Unable to call extension over TLS


#1

Hello there,

I am not a complete Noob to Asterisk. I have been doing SIP on asterisk for over a year now.
I am having trouble calling to/from an extension that I registered over the internet on TLS.
I am using Asterisk 15.4.1, Linux talk 3.16.0-4-amd64 #1 SMP Debian 3.16.51-3 (2017-12-13) x86_64 GNU/Linux.
Following are the logs when I tried calling from ext 801 to 802.
801 is a softphone on my cell and 802 is a Yealink T27G, both registered over the internet on TLS.
I can confirm that these extensions are working and tested when they were on UDP.
Please suggest if I am doing anything wrong.

root@talk:/var/log/asterisk# asterisk -rvvvvv
Asterisk 15.4.1, Copyright © 1999 - 2016, Digium, Inc. and others.
Created by Mark Spencer markster@digium.com
Asterisk comes with ABSOLUTELY NO WARRANTY; type ‘core show warranty’ for details.
This is free software, with components licensed under the GNU General Public
License version 2 and other licenses; you are welcome to redistribute it under
certain conditions. Type ‘core show license’ for details.

Connected to Asterisk 15.4.1 currently running on talk (pid = 1547)
talk*CLI> sip show peers
Name/username Host Dyn Forcerport Comedia ACL Port Status Description
800 (Unspecified) D No No A 0 UNKNOWN
801/801 xxx.xxx.xx2.240 D Yes Yes A 39150 OK (136 ms)
802/802 xxx.xxx.xx2.34 D Yes Yes A 12589 OK (20 ms)
3 sip peers [Monitored: 2 online, 1 offline Unmonitored: 0 online, 0 offline]

[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [802@from-internal:1] Set(“SIP/801-00000000”, “__RINGTIMER=15”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [802@from-internal:2] Macro(“SIP/801-00000000”, “exten-vm,novm,802,0,0,0”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-exten-vm:1] Macro(“SIP/801-00000000”, “user-callerid,”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-user-callerid:1] Set(“SIP/801-00000000”, “TOUCH_MONITOR=1531160475.0”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-user-callerid:2] Set(“SIP/801-00000000”, “AMPUSER=801”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-user-callerid:3] GotoIf(“SIP/801-00000000”, “0?report”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-user-callerid:4] ExecIf(“SIP/801-00000000”, “1?Set(REALCALLERIDNUM=801)”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-user-callerid:5] Set(“SIP/801-00000000”, “AMPUSER=801”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-user-callerid:6] GotoIf(“SIP/801-00000000”, “0?limit”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-user-callerid:7] Set(“SIP/801-00000000”, “AMPUSERCIDNAME=Mohit Godiya”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-user-callerid:8] ExecIf(“SIP/801-00000000”, “0?Set(__CIDMASQUERADING=TRUE)”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-user-callerid:9] GotoIf(“SIP/801-00000000”, “0?report”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-user-callerid:10] Set(“SIP/801-00000000”, “AMPUSERCID=801”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-user-callerid:11] Set(“SIP/801-00000000”, “__DIAL_OPTIONS=HhTtr”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-user-callerid:12] Set(“SIP/801-00000000”, “CALLERID(all)=“Mohit Godiya” <801>”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-user-callerid:13] GotoIf(“SIP/801-00000000”, “0?limit”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-user-callerid:14] ExecIf(“SIP/801-00000000”, “0?Set(GROUP(concurrency_limit)=801)”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-user-callerid:15] NoOp(“SIP/801-00000000”, “Macro Depth is 2”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-user-callerid:16] GotoIf(“SIP/801-00000000”, “1?report2:macroerror”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx_builtins.c: Goto (macro-user-callerid,s,17)
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-user-callerid:17] GotoIf(“SIP/801-00000000”, “0?continue”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-user-callerid:18] Set(“SIP/801-00000000”, “__TTL=64”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-user-callerid:19] GotoIf(“SIP/801-00000000”, “1?continue”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx_builtins.c: Goto (macro-user-callerid,s,35)
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-user-callerid:35] Set(“SIP/801-00000000”, “CALLERID(number)=801”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-user-callerid:36] Set(“SIP/801-00000000”, “CALLERID(name)=Mohit Godiya”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-user-callerid:37] GotoIf(“SIP/801-00000000”, “0?cnum”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-user-callerid:38] Set(“SIP/801-00000000”, “CDR(cnam)=Mohit Godiya”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-user-callerid:39] Set(“SIP/801-00000000”, “CDR(cnum)=801”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-user-callerid:40] Set(“SIP/801-00000000”, “CHANNEL(language)=en”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-exten-vm:2] Set(“SIP/801-00000000”, “RingGroupMethod=none”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-exten-vm:3] Set(“SIP/801-00000000”, “__EXTTOCALL=802”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-exten-vm:4] Set(“SIP/801-00000000”, “__PICKUPMARK=802”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-exten-vm:5] Set(“SIP/801-00000000”, “RT=”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-exten-vm:6] Gosub(“SIP/801-00000000”, “sub-record-check,s,1(exten,802,dontcare)”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@sub-record-check:1] GotoIf(“SIP/801-00000000”, “0?initialized”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@sub-record-check:2] Set(“SIP/801-00000000”, “__REC_STATUS=INITIALIZED”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@sub-record-check:3] Set(“SIP/801-00000000”, “NOW=1531160475”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@sub-record-check:4] Set(“SIP/801-00000000”, “__DAY=09”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@sub-record-check:5] Set(“SIP/801-00000000”, “__MONTH=07”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@sub-record-check:6] Set(“SIP/801-00000000”, “__YEAR=2018”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@sub-record-check:7] Set(“SIP/801-00000000”, “__TIMESTR=20180709-112115”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@sub-record-check:8] Set(“SIP/801-00000000”, “__FROMEXTEN=801”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@sub-record-check:9] Set(“SIP/801-00000000”, “__MON_FMT=wav”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@sub-record-check:10] NoOp(“SIP/801-00000000”, “Recordings initialized”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@sub-record-check:11] ExecIf(“SIP/801-00000000”, “0?Set(ARG3=dontcare)”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@sub-record-check:12] Set(“SIP/801-00000000”, “REC_POLICY_MODE_SAVE=”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@sub-record-check:13] ExecIf(“SIP/801-00000000”, “0?Set(REC_STATUS=NO)”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@sub-record-check:14] GotoIf(“SIP/801-00000000”, “5?checkaction”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx_builtins.c: Goto (sub-record-check,s,17)
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@sub-record-check:17] GotoIf(“SIP/801-00000000”, “1?sub-record-check,exten,1”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx_builtins.c: Goto (sub-record-check,exten,1)
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [exten@sub-record-check:1] NoOp(“SIP/801-00000000”, “Exten Recording Check between 801 and 802”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [exten@sub-record-check:2] Set(“SIP/801-00000000”, “CALLTYPE=internal”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [exten@sub-record-check:3] ExecIf(“SIP/801-00000000”, “0?Set(CALLTYPE=)”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [exten@sub-record-check:4] Set(“SIP/801-00000000”, “CALLEE=dontcare”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [exten@sub-record-check:5] ExecIf(“SIP/801-00000000”, “0?Set(CALLEE=dontcare)”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [exten@sub-record-check:6] GotoIf(“SIP/801-00000000”, “0?callee”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [exten@sub-record-check:7] GotoIf(“SIP/801-00000000”, “1?caller”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx_builtins.c: Goto (sub-record-check,exten,13)
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [exten@sub-record-check:13] Set(“SIP/801-00000000”, “RECMODE=dontcare”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [exten@sub-record-check:14] ExecIf(“SIP/801-00000000”, “0?Set(RECMODE=dontcare)”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [exten@sub-record-check:15] ExecIf(“SIP/801-00000000”, “1?Set(RECMODE=dontcare)”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [exten@sub-record-check:16] Gosub(“SIP/801-00000000”, “recordcheck,1(dontcare,internal,802)”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“SIP/801-00000000”, “Starting recording check against dontcare”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“SIP/801-00000000”, “dontcare”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [recordcheck@sub-record-check:3] Return(“SIP/801-00000000”, “”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [exten@sub-record-check:17] Return(“SIP/801-00000000”, “”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-exten-vm:7] GotoIf(“SIP/801-00000000”, “1?macrodial”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx_builtins.c: Goto (macro-exten-vm,s,13)
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-exten-vm:13] GosubIf(“SIP/801-00000000”, “0?clrheader,1()”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-exten-vm:14] Macro(“SIP/801-00000000”, “dial-one,HhTtr,802”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dial-one:1] Set(“SIP/801-00000000”, “DEXTEN=802”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dial-one:2] ExecIf(“SIP/801-00000000”, “0?Set(__EXTTOCALL=802)”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dial-one:3] Set(“SIP/801-00000000”, “DIALSTATUS_CW=”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dial-one:4] GosubIf(“SIP/801-00000000”, “0?screen,1()”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dial-one:5] GosubIf(“SIP/801-00000000”, “0?cf,1()”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dial-one:6] GotoIf(“SIP/801-00000000”, “1?skip1”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx_builtins.c: Goto (macro-dial-one,s,9)
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dial-one:9] GotoIf(“SIP/801-00000000”, “0?nodial”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dial-one:10] GotoIf(“SIP/801-00000000”, “0?continue”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dial-one:11] Set(“SIP/801-00000000”, “EXTHASCW=ENABLED”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dial-one:12] GotoIf(“SIP/801-00000000”, “0?next1:cwinusebusy”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx_builtins.c: Goto (macro-dial-one,s,24)
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dial-one:24] GotoIf(“SIP/801-00000000”, “0?next3:continue”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx_builtins.c: Goto (macro-dial-one,s,26)
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dial-one:26] GotoIf(“SIP/801-00000000”, “0?nodial”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dial-one:27] GosubIf(“SIP/801-00000000”, “1?dstring,1():dlocal,1()”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [dstring@macro-dial-one:1] Set(“SIP/801-00000000”, “DSTRING=”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [dstring@macro-dial-one:2] Set(“SIP/801-00000000”, “DEVICES=802”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [dstring@macro-dial-one:3] ExecIf(“SIP/801-00000000”, “0?Return()”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [dstring@macro-dial-one:4] ExecIf(“SIP/801-00000000”, “0?Set(DEVICES=02)”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [dstring@macro-dial-one:5] Set(“SIP/801-00000000”, “LOOPCNT=1”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [dstring@macro-dial-one:6] Set(“SIP/801-00000000”, “ITER=1”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [dstring@macro-dial-one:7] Set(“SIP/801-00000000”, “THISDIAL=SIP/802”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [dstring@macro-dial-one:8] GosubIf(“SIP/801-00000000”, “1?zap2dahdi,1()”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [zap2dahdi@macro-dial-one:1] ExecIf(“SIP/801-00000000”, “0?Return()”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [zap2dahdi@macro-dial-one:2] Set(“SIP/801-00000000”, “NEWDIAL=”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [zap2dahdi@macro-dial-one:3] Set(“SIP/801-00000000”, “LOOPCNT2=1”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [zap2dahdi@macro-dial-one:4] Set(“SIP/801-00000000”, “ITER2=1”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [zap2dahdi@macro-dial-one:5] Set(“SIP/801-00000000”, “THISPART2=SIP/802”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [zap2dahdi@macro-dial-one:6] ExecIf(“SIP/801-00000000”, “0?Set(THISPART2=DAHDI/802)”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [zap2dahdi@macro-dial-one:7] Set(“SIP/801-00000000”, “NEWDIAL=SIP/802&”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [zap2dahdi@macro-dial-one:8] Set(“SIP/801-00000000”, “ITER2=2”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [zap2dahdi@macro-dial-one:9] GotoIf(“SIP/801-00000000”, “0?begin2”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [zap2dahdi@macro-dial-one:10] Set(“SIP/801-00000000”, “THISDIAL=SIP/802”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [zap2dahdi@macro-dial-one:11] Return(“SIP/801-00000000”, “”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [dstring@macro-dial-one:9] GotoIf(“SIP/801-00000000”, “1?docheck”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx_builtins.c: Goto (macro-dial-one,dstring,14)
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [dstring@macro-dial-one:14] GotoIf(“SIP/801-00000000”, “0?skipset”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [dstring@macro-dial-one:15] Set(“SIP/801-00000000”, “DSTRING=SIP/802&”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [dstring@macro-dial-one:16] Set(“SIP/801-00000000”, “ITER=2”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [dstring@macro-dial-one:17] GotoIf(“SIP/801-00000000”, “0?begin”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [dstring@macro-dial-one:18] ExecIf(“SIP/801-00000000”, “0?Return()”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [dstring@macro-dial-one:19] Set(“SIP/801-00000000”, “DSTRING=SIP/802”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [dstring@macro-dial-one:20] Return(“SIP/801-00000000”, “”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dial-one:28] GotoIf(“SIP/801-00000000”, “0?nodial”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dial-one:29] GotoIf(“SIP/801-00000000”, “0?skiptrace”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dial-one:30] GosubIf(“SIP/801-00000000”, “1?ctset,1():ctclear,1()”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [ctset@macro-dial-one:1] Set(“SIP/801-00000000”, “DB(CALLTRACE/802)=801”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [ctset@macro-dial-one:2] Return(“SIP/801-00000000”, “”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dial-one:31] Set(“SIP/801-00000000”, “D_OPTIONS=HhTtr”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dial-one:32] GosubIf(“SIP/801-00000000”, “0?func-set-sipheader,s,1(Alert-Info,)”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dial-one:33] NoOp(“SIP/801-00000000”, "Blind Transfer: , Attended Transfer: , User: 801, Alert Info: ") in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dial-one:34] ExecIf(“SIP/801-00000000”, “1?Set(ALERT_INFO=)”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dial-one:35] ExecIf(“SIP/801-00000000”, “0?Set(ALERT_INFO=)”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dial-one:36] ExecIf(“SIP/801-00000000”, “0?Set(ALERT_INFO=)”) in new stack
[2018-07-09 11:21:15] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dial-one:37] ExecIf(“SIP/801-00000000”, “0?Set(ALERT_INFO=Normal;volume=)”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dial-one:38] ExecIf(“SIP/801-00000000”, “0?Set(ALERT_INFO=Normal;volume=)”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dial-one:39] GosubIf(“SIP/801-00000000”, “0?func-set-sipheader,s,1(Alert-Info,)”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dial-one:40] ExecIf(“SIP/801-00000000”, “0?Set(CHANNEL(musicclass)=)”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dial-one:41] GosubIf(“SIP/801-00000000”, “0?qwait,1()”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dial-one:42] Set(“SIP/801-00000000”, “__CWIGNORE=”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dial-one:43] Set(“SIP/801-00000000”, “__KEEPCID=TRUE”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dial-one:44] GotoIf(“SIP/801-00000000”, “0?usegoto,1”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dial-one:45] GotoIf(“SIP/801-00000000”, “0?godial”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dial-one:46] Gosub(“SIP/801-00000000”, “sub-presencestate-display,s,1(802)”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [s@sub-presencestate-display:1] Goto(“SIP/801-00000000”, “state-not_set,1”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx_builtins.c: Goto (sub-presencestate-display,state-not_set,1)
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [state-not_set@sub-presencestate-display:1] Set(“SIP/801-00000000”, “PRESENCESTATE_DISPLAY=”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [state-not_set@sub-presencestate-display:2] Return(“SIP/801-00000000”, “”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dial-one:47] Set(“SIP/801-00000000”, “CONNECTEDLINE(name,i)=Mohit Godiya”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dial-one:48] Set(“SIP/801-00000000”, “CONNECTEDLINE(num)=802”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dial-one:49] Set(“SIP/801-00000000”, “D_OPTIONS=HhTtrI”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dial-one:50] Macro(“SIP/801-00000000”, “dialout-one-predial-hook,”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dialout-one-predial-hook:1] MacroExit(“SIP/801-00000000”, “”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dial-one:51] ExecIf(“SIP/801-00000000”, “0?Set(D_OPTIONS=HhtrII)”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dial-one:52] NoOp(“SIP/801-00000000”, “”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dial-one:53] ExecIf(“SIP/801-00000000”, “0?Set(D_OPTIONS=HhTtrIg)”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dial-one:54] Dial(“SIP/801-00000000”, “SIP/802,HhTtrIb(func-apply-sipheaders^s^1)”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] netsock2.c: Using SIP VIDEO TOS bits 136
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] netsock2.c: Using SIP VIDEO CoS mark 6
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] netsock2.c: Using SIP RTP TOS bits 184
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] netsock2.c: Using SIP RTP CoS mark 5
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] app_stack.c: SIP/802-00000001 Internal Gosub(func-apply-sipheaders,s,1) start
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp(“SIP/802-00000001”, “Applying SIP Headers to channel”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [s@func-apply-sipheaders:2] Set(“SIP/802-00000001”, “SIPHEADERKEYS=”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [s@func-apply-sipheaders:3] ExecIf(“SIP/802-00000001”, “0?Set(Rheader=1)”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [s@func-apply-sipheaders:4] While(“SIP/802-00000001”, “0”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] app_while.c: Jumping to priority 8
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [s@func-apply-sipheaders:9] ExecIf(“SIP/802-00000001”, “0?SIPRemoveHeader(Alert-Info:)”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf(“SIP/802-00000001”, “0?Set(PJSIP_HEADER(remove,Alert-Info)=)”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [s@func-apply-sipheaders:11] Return(“SIP/802-00000001”, “”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] app_stack.c: Spawn extension (from-internal, 802, 1) exited non-zero on ‘SIP/802-00000001’
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] app_stack.c: SIP/802-00000001 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] app_dial.c: Called SIP/802
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] app_dial.c: Connected line update to SIP/801-00000000 prevented.
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] app_dial.c: Everyone is busy/congested at this time (1:0/0/1)
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dial-one:55] ExecIf(“SIP/801-00000000”, “0?MacroExit()”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dial-one:56] ExecIf(“SIP/801-00000000”, “0?Set(DIALSTATUS=)”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dial-one:57] GosubIf(“SIP/801-00000000”, “0?s-CHANUNAVAIL,1()”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-dial-one:58] MacroExit(“SIP/801-00000000”, “”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-exten-vm:15] Set(“SIP/801-00000000”, “SV_DIALSTATUS=CHANUNAVAIL”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-exten-vm:16] GosubIf(“SIP/801-00000000”, “0?docfu,1()”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-exten-vm:17] GosubIf(“SIP/801-00000000”, “0?docfb,1()”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-exten-vm:18] Set(“SIP/801-00000000”, “DIALSTATUS=CHANUNAVAIL”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-exten-vm:19] ExecIf(“SIP/801-00000000”, “0?MacroExit()”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-exten-vm:20] GotoIf(“SIP/801-00000000”, “1?s-CHANUNAVAIL,1”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx_builtins.c: Goto (macro-exten-vm,s-CHANUNAVAIL,1)
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [s-CHANUNAVAIL@macro-exten-vm:1] GotoIf(“SIP/801-00000000”, “0?exit,1”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [s-CHANUNAVAIL@macro-exten-vm:2] PlayTones(“SIP/801-00000000”, “congestion”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [s-CHANUNAVAIL@macro-exten-vm:3] Congestion(“SIP/801-00000000”, “10”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] app_macro.c: Spawn extension (macro-exten-vm, s-CHANUNAVAIL, 3) exited non-zero on ‘SIP/801-00000000’ in macro ‘exten-vm’
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Spawn extension (from-internal, 802, 2) exited non-zero on ‘SIP/801-00000000’
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [h@from-internal:1] Macro(“SIP/801-00000000”, “hangupcall”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“SIP/801-00000000”, “1?theend”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“SIP/801-00000000”, “0?Set(CDR(recordingfile)=)”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-hangupcall:4] NoOp(“SIP/801-00000000”, " monior file= ") in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-hangupcall:5] AGI(“SIP/801-00000000”, “attendedtransfer-rec-restart.php,”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/attendedtransfer-rec-restart.php
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] res_agi.c: <SIP/801-00000000>AGI Script attendedtransfer-rec-restart.php completed, returning 0
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Executing [s@macro-hangupcall:6] Hangup(“SIP/801-00000000”, “”) in new stack
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] app_macro.c: Spawn extension (macro-hangupcall, s, 6) exited non-zero on ‘SIP/801-00000000’ in macro ‘hangupcall’
[2018-07-09 11:21:16] VERBOSE[6664][C-00000004] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/801-00000000’


#2

You are either not a newbie, or someone else wrote your complex dialplan. In the latter case you need to get support from them as we have no idea what they wrote.

You haven’t got sufficient logging, but you have an unavailable status, which includes trying to call unregistered dynamic peers, but can also be the response to certain SIP status codes. You are missing the detailed logging that would identify which.


#3

thanks for the feedback @david551, As fas as I know I have not setup any dial patterns on my system.
My skillset as a system admin for freepbx is not very much. I have a couple PBX kicking around but using purely using UDP in a firewalled environment.
But I am trying to figure out why my extension wont work over TLS, even when I am in my home network and regardless of my connection it is registered but not making any calls.

here is my asterisk with debug 9 and verbose 9

https://pastebin.com/7JMg5QhH

Any Help appreciated.


#4

FreePBX wrote the dialplans, so you need to get support from them, e.g. https://community.freepbx.org/