All was well, incoming and outgoing calls were processed correctly. Old IP range was 192.168.1.1/254. New Router introduced into the system, DHCP broadcast new IP’s in the range 192.168.178.1/254. Now Outgoing calls fail with BUSY TONE. Incoming calls work fine.
With sip set debug on set via the Asterrisk CLI, I get;
<------------->
954 [2025-03-31 15:58:24] VERBOSE[2858] chan_sip.c: — (10 headers 0 lines) —
955 [2025-03-31 15:58:24] VERBOSE[2858] chan_sip.c: Sending to 192.168.178.129:5160 (NAT)
956 [2025-03-31 15:58:24] VERBOSE[2858] chan_sip.c: Looking for searcher in from-sip-external (domain 192.168.178.116)
957 [2025-03-31 15:58:24] VERBOSE[2858] chan_sip.c:
958 <— Transmitting (NAT) to 192.168.178.129:5160 —>
959 SIP/2.0 200 OK
960 Via: SIP/2.0/UDP 192.168.178.129:5160;branch=z9hG4bK-vega1-0002-0001-004E-C693746C;received=192.168.178.129;rport=5160
961 From: sip:[email protected];tag=00F0-0015-00001234
962 To: sip:[email protected];tag=as0d4fa37d
963 Call-ID: 00F0-0039-AA5C6CFC-0000000C@EF3E9AD56860C895A
964 CSeq: 130554 OPTIONS
965 Server: FPBX-16.0.40.11(16.30.0)
966 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
967 Supported: replaces, timer
968 Contact: sip:192.168.178.116:5160
969 Accept: application/sdp
970 Content-Length: 0
The FreePbx IP address is 192.168.178.116 and the Vega Gateway IP address is 192.168.178.129.
Thanks
Peter
There are no calls in the log.
You aren’t showing the incoming OPTIONs, and because it is incoming, one can’t tell if the response actually arrived, only that it was sent.
You are using a channel driver that is now only supported by third parties, but probably not using the version they support.
Thanks David, but the error is in out going calls
Exactly, so you need to show a log that is for a call and, specifically for one that is outgoing.
Here is an out going call (Extn 106 called 17070 (BT Quite Line)). Part 1
[2025-03-31 22:40:38] VERBOSE[24081] netsock2.c: Using SIP RTP Audio TOS bits 184
[2025-03-31 22:40:38] VERBOSE[24081] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
[2025-03-31 22:40:38] VERBOSE[24081] netsock2.c: Using SIP RTP Audio CoS mark 5
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [17070@from-internal:1] Macro(“PJSIP/106-00000019”, “user-callerid,LIMIT,EXTERNAL,”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-user-callerid:1] Set(“PJSIP/106-00000019”, “TOUCH_MONITOR=1743457238.40”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-user-callerid:2] Set(“PJSIP/106-00000019”, “CHANCONTEXT=”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-user-callerid:3] Set(“PJSIP/106-00000019”, “CHANCONTEXT=”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-user-callerid:4] Set(“PJSIP/106-00000019”, “CHANEXTENCONTEXT=106-00000019”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-user-callerid:5] Set(“PJSIP/106-00000019”, “CHANEXTEN=106-00000019”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-user-callerid:6] Set(“PJSIP/106-00000019”, “CALLERID(number)=106”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-user-callerid:7] Set(“PJSIP/106-00000019”, “AMPUSER=106”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-user-callerid:8] Set(“PJSIP/106-00000019”, “HOTDESCKCHAN=106-00000019”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-user-callerid:9] Set(“PJSIP/106-00000019”, “HOTDESKEXTEN=106”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-user-callerid:10] Set(“PJSIP/106-00000019”, “HOTDESKCALL=0”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-user-callerid:11] ExecIf(“PJSIP/106-00000019”, “0?Set(HOTDESKCALL=1)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-user-callerid:12] ExecIf(“PJSIP/106-00000019”, “0?Set(CALLERID(name)=)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-user-callerid:13] GotoIf(“PJSIP/106-00000019”, “0?report”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-user-callerid:14] ExecIf(“PJSIP/106-00000019”, “1?Set(REALCALLERIDNUM=106)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-user-callerid:15] Set(“PJSIP/106-00000019”, “AMPUSER=106”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-user-callerid:16] GotoIf(“PJSIP/106-00000019”, “0?limit”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-user-callerid:17] Set(“PJSIP/106-00000019”, “AMPUSERCIDNAME=Lounge”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-user-callerid:18] ExecIf(“PJSIP/106-00000019”, “0?Set(__CIDMASQUERADING=TRUE)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-user-callerid:19] GotoIf(“PJSIP/106-00000019”, “0?report”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-user-callerid:20] Set(“PJSIP/106-00000019”, “AMPUSERCID=106”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-user-callerid:21] Set(“PJSIP/106-00000019”, “__DIAL_OPTIONS=HhTtr”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-user-callerid:22] Set(“PJSIP/106-00000019”, “CALLERID(all)=“Lounge” <106>”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-user-callerid:23] ExecIf(“PJSIP/106-00000019”, “0?Set(CUSDIAL=)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-user-callerid:24] ExecIf(“PJSIP/106-00000019”, “0?Set(CALLERID(all)=“Lounge” <106>)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-user-callerid:25] GotoIf(“PJSIP/106-00000019”, “0?limit”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-user-callerid:26] ExecIf(“PJSIP/106-00000019”, “1?Set(GROUP(concurrency_limit)=106)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-user-callerid:27] ExecIf(“PJSIP/106-00000019”, “0?Set(CHANNEL(language)=)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-user-callerid:28] NoOp(“PJSIP/106-00000019”, “Macro Depth is 1”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-user-callerid:29] GotoIf(“PJSIP/106-00000019”, “1?report2:macroerror”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx_builtins.c: Goto (macro-user-callerid,s,30)
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-user-callerid:30] GotoIf(“PJSIP/106-00000019”, “1?continue”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx_builtins.c: Goto (macro-user-callerid,s,49)
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-user-callerid:49] Set(“PJSIP/106-00000019”, “CALLERID(number)=106”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-user-callerid:50] Set(“PJSIP/106-00000019”, “CALLERID(name)=Lounge”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-user-callerid:51] GotoIf(“PJSIP/106-00000019”, “0?cnum”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-user-callerid:52] Set(“PJSIP/106-00000019”, “__MCNUM=106”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-user-callerid:53] Set(“PJSIP/106-00000019”, “__MCNAME=Lounge”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-user-callerid:54] Set(“PJSIP/106-00000019”, “__MCEXTEN=106”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-user-callerid:55] Set(“PJSIP/106-00000019”, “__MCORGCHAN=PJSIP/106-00000019”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-user-callerid:56] Set(“PJSIP/106-00000019”, “CDR(cnam)=Lounge”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-user-callerid:57] Set(“PJSIP/106-00000019”, “CDR(cnum)=106”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-user-callerid:58] Set(“PJSIP/106-00000019”, “CHANNEL(language)=en”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [17070@from-internal:2] Gosub(“PJSIP/106-00000019”, “sub-record-check,s,1(out,17070,dontcare)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@sub-record-check:1] GotoIf(“PJSIP/106-00000019”, “0?initialized”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@sub-record-check:2] Set(“PJSIP/106-00000019”, “__REC_STATUS=INITIALIZED”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@sub-record-check:3] Set(“PJSIP/106-00000019”, “NOW=1743457238”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@sub-record-check:4] Set(“PJSIP/106-00000019”, “__DAY=31”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@sub-record-check:5] Set(“PJSIP/106-00000019”, “__MONTH=03”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@sub-record-check:6] Set(“PJSIP/106-00000019”, “__YEAR=2025”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@sub-record-check:7] Set(“PJSIP/106-00000019”, “__TIMESTR=20250331-224038”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@sub-record-check:8] Set(“PJSIP/106-00000019”, “__FROMEXTEN=106”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@sub-record-check:9] Set(“PJSIP/106-00000019”, “__MON_FMT=wav”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@sub-record-check:10] NoOp(“PJSIP/106-00000019”, “Recordings initialized”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@sub-record-check:11] ExecIf(“PJSIP/106-00000019”, “0?Set(ARG3=dontcare)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@sub-record-check:12] Set(“PJSIP/106-00000019”, “REC_POLICY_MODE_SAVE=”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@sub-record-check:13] ExecIf(“PJSIP/106-00000019”, “0?Set(REC_STATUS=NO)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@sub-record-check:14] GotoIf(“PJSIP/106-00000019”, “3?checkaction”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx_builtins.c: Goto (sub-record-check,s,17)
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@sub-record-check:17] GotoIf(“PJSIP/106-00000019”, “1?sub-record-check,out,1”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx_builtins.c: Goto (sub-record-check,out,1)
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [out@sub-record-check:1] NoOp(“PJSIP/106-00000019”, “Outbound Recording Check from 106 to 17070”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [out@sub-record-check:2] Set(“PJSIP/106-00000019”, “RECMODE=dontcare”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [out@sub-record-check:3] ExecIf(“PJSIP/106-00000019”, “1?Goto(routewins)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx_builtins.c: Goto (sub-record-check,out,7)
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [out@sub-record-check:7] Gosub(“PJSIP/106-00000019”, “recordcheck,1(dontcare,out,17070)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“PJSIP/106-00000019”, “Starting recording check against dontcare”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“PJSIP/106-00000019”, “dontcare”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [recordcheck@sub-record-check:3] Return(“PJSIP/106-00000019”, “”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [out@sub-record-check:8] Return(“PJSIP/106-00000019”, “”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [17070@from-internal:3] ExecIf(“PJSIP/106-00000019”, “0 ?Set(CDR(accountcode)=)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [17070@from-internal:4] Set(“PJSIP/106-00000019”, “_ROUTEID=1”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [17070@from-internal:5] Set(“PJSIP/106-00000019”, “_ROUTENAME=outbound”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [17070@from-internal:6] Set(“PJSIP/106-00000019”, “MOHCLASS=default”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [17070@from-internal:7] ExecIf(“PJSIP/106-00000019”, “0?Set(TRUNKCIDOVERRIDE=01474871213)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [17070@from-internal:8] Set(“PJSIP/106-00000019”, “_CALLERIDNAMEINTERNAL=Lounge”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [17070@from-internal:9] Set(“PJSIP/106-00000019”, “_CALLERIDNUMINTERNAL=106”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [17070@from-internal:10] Set(“PJSIP/106-00000019”, “_EMAILNOTIFICATION=FALSE”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [17070@from-internal:11] Set(“PJSIP/106-00000019”, “_NODEST=”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [17070@from-internal:12] Macro(“PJSIP/106-00000019”, “dialout-trunk,1,17070,off”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-dialout-trunk:1] Set(“PJSIP/106-00000019”, “DIAL_TRUNK=1”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-dialout-trunk:2] ExecIf(“PJSIP/106-00000019”, “0?Set(DIAL_OPTIONS=Hhtr)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-dialout-trunk:3] ExecIf(“PJSIP/106-00000019”, “0?Set(DIAL_OPTIONS=HhTr)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-dialout-trunk:4] ExecIf(“PJSIP/106-00000019”, “0?Set(DIAL_OPTIONS=Hhtr)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-dialout-trunk:5] GosubIf(“PJSIP/106-00000019”, “0?sub-pincheck,s,1()”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-dialout-trunk:6] ExecIf(“PJSIP/106-00000019”, “0?Set(CALLERID(num)=106)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-dialout-trunk:7] GotoIf(“PJSIP/106-00000019”, “0?disabletrunk,1”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-dialout-trunk:8] Set(“PJSIP/106-00000019”, “DIAL_NUMBER=17070”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-dialout-trunk:9] Set(“PJSIP/106-00000019”, “DIAL_TRUNK_OPTIONS=HhTtr”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-dialout-trunk:10] Set(“PJSIP/106-00000019”, “OUTBOUND_GROUP=OUT_1”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-dialout-trunk:11] Set(“PJSIP/106-00000019”, “DIAL_TRUNK_OPTIONS=T”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-dialout-trunk:12] ExecIf(“PJSIP/106-00000019”, “0?Set(DIAL_TRUNK_OPTIONS=)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-dialout-trunk:13] GotoIf(“PJSIP/106-00000019”, “1?nomax”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx_builtins.c: Goto (macro-dialout-trunk,s,15)
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-dialout-trunk:15] GotoIf(“PJSIP/106-00000019”, “0?skipoutcid”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-dialout-trunk:16] Macro(“PJSIP/106-00000019”, “outbound-callerid,1”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-outbound-callerid:1] NoOp(“PJSIP/106-00000019”, “106”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-outbound-callerid:2] NoOp(“PJSIP/106-00000019”, “”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-outbound-callerid:3] NoOp(“PJSIP/106-00000019”, “off”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-outbound-callerid:4] ExecIf(“PJSIP/106-00000019”, “0?Set(CALLERPRES(name-pres)=)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-outbound-callerid:5] ExecIf(“PJSIP/106-00000019”, “0?Set(CALLERPRES(num-pres)=)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-outbound-callerid:6] Set(“PJSIP/106-00000019”, “HOTDESCKCHAN=106-00000019”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-outbound-callerid:7] Set(“PJSIP/106-00000019”, “HOTDESKEXTEN=106”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-outbound-callerid:8] Set(“PJSIP/106-00000019”, “HOTDESKCALL=0”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-outbound-callerid:9] ExecIf(“PJSIP/106-00000019”, “0?Set(HOTDESKCALL=1)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-outbound-callerid:10] ExecIf(“PJSIP/106-00000019”, “0?Set(CALLERID(name)=)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-outbound-callerid:11] Set(“PJSIP/106-00000019”, “ALLOWTHISROUTE=NO”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-outbound-callerid:12] ExecIf(“PJSIP/106-00000019”, “0?Set(ALLOWTHISROUTE=YES)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-outbound-callerid:13] ExecIf(“PJSIP/106-00000019”, “0?Hangup()”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-outbound-callerid:14] ExecIf(“PJSIP/106-00000019”, “0?Set(REALCALLERIDNUM=106)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-outbound-callerid:15] ExecIf(“PJSIP/106-00000019”, “0?Set(AMPUSER=106)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-outbound-callerid:16] GotoIf(“PJSIP/106-00000019”, “1?normcid”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx_builtins.c: Goto (macro-outbound-callerid,s,20)
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-outbound-callerid:20] Set(“PJSIP/106-00000019”, “USEROUTCID=106”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-outbound-callerid:21] Set(“PJSIP/106-00000019”, “EMERGENCYCID=”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-outbound-callerid:22] ExecIf(“PJSIP/106-00000019”, “0?Set(EMERGENCYCID=)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-outbound-callerid:23] Set(“PJSIP/106-00000019”, “TRUNKOUTCID=01474871213”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-outbound-callerid:24] GotoIf(“PJSIP/106-00000019”, “1?trunkcid”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx_builtins.c: Goto (macro-outbound-callerid,s,30)
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-outbound-callerid:30] ExecIf(“PJSIP/106-00000019”, “1?Set(CALLERID(all)=01474871213)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-outbound-callerid:31] ExecIf(“PJSIP/106-00000019”, “1?Set(CALLERID(all)=106)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-outbound-callerid:32] ExecIf(“PJSIP/106-00000019”, “0?Set(CALLERID(all)=)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-outbound-callerid:33] ExecIf(“PJSIP/106-00000019”, “0?Set(CALLERID(all)=106)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-outbound-callerid:34] ExecIf(“PJSIP/106-00000019”, “0?Set(CALLERID(all)=106)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-outbound-callerid:35] Set(“PJSIP/106-00000019”, “TIOHIDE=no”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-outbound-callerid:36] ExecIf(“PJSIP/106-00000019”, “0?Set(CALLERPRES(name-pres)=prohib_passed_screen)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-outbound-callerid:37] ExecIf(“PJSIP/106-00000019”, “0?Set(CALLERPRES(num-pres)=prohib_passed_screen)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-outbound-callerid:38] ExecIf(“PJSIP/106-00000019”, “0?Set(CALLERPRES(name-pres)=prohib_passed_screen)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-outbound-callerid:39] ExecIf(“PJSIP/106-00000019”, “0?Set(CALLERPRES(num-pres)=prohib_passed_screen)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-outbound-callerid:40] Set(“PJSIP/106-00000019”, “CDR(outbound_cnum)=106”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-outbound-callerid:41] Set(“PJSIP/106-00000019”, “CDR(outbound_cnam)=”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-dialout-trunk:17] GosubIf(“PJSIP/106-00000019”, “0?sub-flp-1,s,1()”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-dialout-trunk:18] Set(“PJSIP/106-00000019”, “OUTNUM=17070”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-dialout-trunk:19] Set(“PJSIP/106-00000019”, “custom=SIP/to-Vega”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-dialout-trunk:20] ExecIf(“PJSIP/106-00000019”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-dialout-trunk:21] ExecIf(“PJSIP/106-00000019”, “0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-dialout-trunk:22] ExecIf(“PJSIP/106-00000019”, “0?AGI(allowlist-autoadd.agi,)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-dialout-trunk:23] Macro(“PJSIP/106-00000019”, “dialout-trunk-predial-hook,”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit(“PJSIP/106-00000019”, “”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-dialout-trunk:24] GotoIf(“PJSIP/106-00000019”, “0?skipcrm”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-dialout-trunk:25] Set(“PJSIP/106-00000019”, “__CRM_DIRECTION=OUTBOUND”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-dialout-trunk:26] Set(“PJSIP/106-00000019”, “__CRM_DESTINATION=17070”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-dialout-trunk:27] Set(“PJSIP/106-00000019”, “__CRM_SOURCE=106”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-dialout-trunk:28] AGI(“PJSIP/106-00000019”, “sangomacrm.agi”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] res_agi.c: <PJSIP/106-00000019>AGI Script sangomacrm.agi completed, returning 0
Part 2
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-dialout-trunk:29] Set(“PJSIP/106-00000019”, “CHANNEL(hangup_handler_push)=crm-hangup,s,1”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-dialout-trunk:30] NoOp(“PJSIP/106-00000019”, “CRM Finished”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-dialout-trunk:31] GotoIf(“PJSIP/106-00000019”, “0?bypass,1”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-dialout-trunk:32] ExecIf(“PJSIP/106-00000019”, “1?Set(CONNECTEDLINE(num,i)=17070)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-dialout-trunk:33] ExecIf(“PJSIP/106-00000019”, “1?Set(CONNECTEDLINE(name,i)=CID:106)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-dialout-trunk:34] ExecIf(“PJSIP/106-00000019”, “0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)106)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-dialout-trunk:35] GotoIf(“PJSIP/106-00000019”, “0?customtrunk”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-dialout-trunk:36] ExecIf(“PJSIP/106-00000019”, “0?Set(DIAL_TRUNK_OPTIONS=)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-dialout-trunk:37] Set(“PJSIP/106-00000019”, “HASH(__SIPHEADERS,Alert-Info)=unset”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-dialout-trunk:38] Dial(“PJSIP/106-00000019”, “SIP/to-Vega/17070,300,Tb(func-apply-sipheaders^s^1,(1))U(sub-send-obroute-email^17070^17070^1^1743457238^^106)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] netsock2.c: Using SIP RTP TOS bits 184
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] netsock2.c: Using SIP RTP CoS mark 5
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] app_stack.c: SIP/to-Vega-0000000f Internal Gosub(func-apply-sipheaders,s,1(1)) start
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf(“SIP/to-Vega-0000000f”, “0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp(“SIP/to-Vega-0000000f”, “Applying SIP Headers to channel SIP/to-Vega-0000000f”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@func-apply-sipheaders:3] Set(“SIP/to-Vega-0000000f”, “localchan=to-Vega-0000000f”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@func-apply-sipheaders:4] Set(“SIP/to-Vega-0000000f”, “DialMCEXT=to”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@func-apply-sipheaders:5] Set(“SIP/to-Vega-0000000f”, “CHANNEL(hangup_handler_push)=app-missedcall-hangup,to,1”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@func-apply-sipheaders:6] Set(“SIP/to-Vega-0000000f”, “TECH=SIP”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@func-apply-sipheaders:7] Set(“SIP/to-Vega-0000000f”, “SIPHEADERKEYS=Alert-Info”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@func-apply-sipheaders:8] While(“SIP/to-Vega-0000000f”, “1”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@func-apply-sipheaders:9] Set(“SIP/to-Vega-0000000f”, “sipheader=unset”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf(“SIP/to-Vega-0000000f”, “1?SIPRemoveHeader(Alert-Info:)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf(“SIP/to-Vega-0000000f”, “0?Set(PJSIP_HEADER(remove,Alert-Info)=)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@func-apply-sipheaders:12] ExecIf(“SIP/to-Vega-0000000f”, “0?Set(sipheader=http://127.0.0.1;info=unset)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@func-apply-sipheaders:13] ExecIf(“SIP/to-Vega-0000000f”, “0?Set(sipheader=http://127.0.0.1unset)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@func-apply-sipheaders:14] ExecIf(“SIP/to-Vega-0000000f”, “0?SIPAddHeader(Alert-Info:unset)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@func-apply-sipheaders:15] ExecIf(“SIP/to-Vega-0000000f”, “0?Set(PJSIP_HEADER(add,Alert-Info)=unset)”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@func-apply-sipheaders:16] EndWhile(“SIP/to-Vega-0000000f”, “”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@func-apply-sipheaders:8] While(“SIP/to-Vega-0000000f”, “0”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] pbx.c: Executing [s@func-apply-sipheaders:17] Return(“SIP/to-Vega-0000000f”, “”) in new stack
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] app_stack.c: Spawn extension (from-trunk-sip-to-Vega, 17070, 1) exited non-zero on ‘SIP/to-Vega-0000000f’
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] app_stack.c: SIP/to-Vega-0000000f Internal Gosub(func-apply-sipheaders,s,1(1)) complete GOSUB_RETVAL=
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] chan_sip.c: Audio is at 18372
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] chan_sip.c: Adding codec ulaw to SDP
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] chan_sip.c: Adding codec alaw to SDP
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] chan_sip.c: Reliably Transmitting (NAT) to 192.168.178.129:5160:
INVITE sip:[email protected]:5160 SIP/2.0
Via: SIP/2.0/UDP 192.168.178.116:5160;branch=z9hG4bK0b8e8d00;rport
Max-Forwards: 70
From: sip:[email protected]:5160;tag=as74dcb4a5
To: sip:[email protected]:5160
Contact: sip:[email protected]:5160
Call-ID: [email protected]:5160
CSeq: 102 INVITE
User-Agent: FPBX-16.0.40.11(16.30.0)
Date: Mon, 31 Mar 2025 21:40:38 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Remote-Party-ID: “106” sip:[email protected];party=calling;privacy=off;screen=no
Content-Type: application/sdp
Content-Length: 282
v=0
o=root 1890452523 1890452523 IN IP4 192.168.178.116
s=Asterisk PBX 16.30.0
c=IN IP4 192.168.178.116
t=0 0
m=audio 18372 RTP/AVP 0 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=maxptime:150
a=sendrecv
[2025-03-31 22:40:38] VERBOSE[25809][C-00000011] app_dial.c: Called SIP/to-Vega/17070
[2025-03-31 22:40:38] VERBOSE[2858] chan_sip.c:
<— SIP read from UDP:192.168.178.129:5160 —>
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.178.116:5160;branch=z9hG4bK0b8e8d00;rport=5160
From: sip:[email protected]:5160;tag=as74dcb4a5
To: sip:[email protected]:5160
Call-ID: [email protected]:5160
CSeq: 102 INVITE
Contact: sip:[email protected]:5160
User-Agent: VEGAARM/16.02.11.1xS018
Content-Length: 0
<------------->
[2025-03-31 22:40:38] VERBOSE[2858] chan_sip.c: — (9 headers 0 lines) —
[2025-03-31 22:40:39] VERBOSE[2858] chan_sip.c:
<— SIP read from UDP:192.168.178.129:5160 —>
SIP/2.0 404 Not Found
Via: SIP/2.0/UDP 192.168.178.116:5160;branch=z9hG4bK0b8e8d00;rport=5160
From: sip:[email protected]:5160;tag=as74dcb4a5
To: sip:[email protected]:5160;tag=00F6-05A7-FF5C6571
Call-ID: [email protected]:5160
CSeq: 102 INVITE
Contact: sip:[email protected]:5160
Reason: Q.850 ;cause=1
User-Agent: VEGAARM/16.02.11.1xS018
Content-Length: 0
<------------->
[2025-03-31 22:40:39] VERBOSE[2858] chan_sip.c: — (10 headers 0 lines) —
[2025-03-31 22:40:39] VERBOSE[2858][C-00000011] chan_sip.c: Transmitting (NAT) to 192.168.178.129:5160:
ACK sip:[email protected]:5160 SIP/2.0
Via: SIP/2.0/UDP 192.168.178.116:5160;branch=z9hG4bK0b8e8d00;rport
Max-Forwards: 70
From: sip:[email protected]:5160;tag=as74dcb4a5
To: sip:[email protected]:5160;tag=00F6-05A7-FF5C6571
Contact: sip:[email protected]:5160
Call-ID: [email protected]:5160
CSeq: 102 ACK
User-Agent: FPBX-16.0.40.11(16.30.0)
Content-Length: 0
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] app_stack.c: SIP/to-Vega-0000000f Internal Gosub(app-missedcall-hangup,to,1) start
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] pbx.c: Executing [to@app-missedcall-hangup:1] NoOp(“SIP/to-Vega-0000000f”, “Dialed: to”) in new stack
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] pbx.c: Executing [to@app-missedcall-hangup:2] NoOp(“SIP/to-Vega-0000000f”, “Caller: 106”) in new stack
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] pbx.c: Executing [to@app-missedcall-hangup:3] GotoIf(“SIP/to-Vega-0000000f”, “0?exit”) in new stack
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] pbx.c: Executing [to@app-missedcall-hangup:4] Set(“SIP/to-Vega-0000000f”, “EXTENNUM=to”) in new stack
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] pbx.c: Executing [to@app-missedcall-hangup:5] Set(“SIP/to-Vega-0000000f”, “FEXTENNUM=to”) in new stack
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] pbx.c: Executing [to@app-missedcall-hangup:6] GotoIf(“SIP/to-Vega-0000000f”, “0?exit”) in new stack
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] pbx.c: Executing [to@app-missedcall-hangup:7] AGI(“SIP/to-Vega-0000000f”, “missedcallnotify.php,to,to,0,SIP/to-Vega-0000000f,”) in new stack
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/missedcallnotify.php
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] res_agi.c: <SIP/to-Vega-0000000f>AGI Script missedcallnotify.php completed, returning 0
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] pbx.c: Executing [to@app-missedcall-hangup:8] Return(“SIP/to-Vega-0000000f”, “”) in new stack
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] app_stack.c: Spawn extension (from-trunk-sip-to-Vega, 17070, 1) exited non-zero on ‘SIP/to-Vega-0000000f’
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] app_stack.c: SIP/to-Vega-0000000f Internal Gosub(app-missedcall-hangup,to,1) complete GOSUB_RETVAL=
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] chan_sip.c: Scheduling destruction of SIP dialog ‘[email protected]:5160’ in 6400 ms (Method: INVITE)
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] app_dial.c: Everyone is busy/congested at this time (1:0/0/1)
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-dialout-trunk:39] NoOp(“PJSIP/106-00000019”, “Dial failed for some reason with DIALSTATUS = CHANUNAVAIL and HANGUPCAUSE = 1”) in new stack
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-dialout-trunk:40] GotoIf(“PJSIP/106-00000019”, “0?continue,1:s-CHANUNAVAIL,1”) in new stack
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] pbx_builtins.c: Goto (macro-dialout-trunk,s-CHANUNAVAIL,1)
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] pbx.c: Executing [s-CHANUNAVAIL@macro-dialout-trunk:1] Set(“PJSIP/106-00000019”, “RC=1”) in new stack
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] pbx.c: Executing [s-CHANUNAVAIL@macro-dialout-trunk:2] Goto(“PJSIP/106-00000019”, “1,1”) in new stack
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] pbx_builtins.c: Goto (macro-dialout-trunk,1,1)
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] pbx.c: Executing [1@macro-dialout-trunk:1] Goto(“PJSIP/106-00000019”, “s-INVALIDNMBR,1”) in new stack
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] pbx_builtins.c: Goto (macro-dialout-trunk,s-INVALIDNMBR,1)
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] pbx.c: Executing [s-INVALIDNMBR@macro-dialout-trunk:1] NoOp(“PJSIP/106-00000019”, “Dial failed due to trunk reporting Address Incomplete - giving up”) in new stack
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] pbx.c: Executing [s-INVALIDNMBR@macro-dialout-trunk:2] Progress(“PJSIP/106-00000019”, “”) in new stack
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] pbx.c: Executing [s-INVALIDNMBR@macro-dialout-trunk:3] PlayTones(“PJSIP/106-00000019”, “congestion”) in new stack
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] pbx.c: Executing [s-INVALIDNMBR@macro-dialout-trunk:4] Busy(“PJSIP/106-00000019”, “20”) in new stack
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] app_macro.c: Spawn extension (macro-dialout-trunk, s-INVALIDNMBR, 4) exited non-zero on ‘PJSIP/106-00000019’ in macro ‘dialout-trunk’
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] pbx.c: Spawn extension (from-internal, 17070, 12) exited non-zero on ‘PJSIP/106-00000019’
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] pbx.c: Executing [h@from-internal:1] Macro(“PJSIP/106-00000019”, “hangupcall”) in new stack
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-hangupcall:1] Set(“PJSIP/106-00000019”, “__MCVMSTATUS=”) in new stack
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-hangupcall:2] Gosub(“PJSIP/106-00000019”, “app-missedcall-hangup,s,1()”) in new stack
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] pbx.c: Executing [s@app-missedcall-hangup:1] NoOp(“PJSIP/106-00000019”, “Dialed: s”) in new stack
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] pbx.c: Executing [s@app-missedcall-hangup:2] NoOp(“PJSIP/106-00000019”, “Caller: 106”) in new stack
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] pbx.c: Executing [s@app-missedcall-hangup:3] GotoIf(“PJSIP/106-00000019”, “0?exit”) in new stack
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] pbx.c: Executing [s@app-missedcall-hangup:4] Set(“PJSIP/106-00000019”, “EXTENNUM=s”) in new stack
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] pbx.c: Executing [s@app-missedcall-hangup:5] Set(“PJSIP/106-00000019”, “FEXTENNUM=s”) in new stack
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] pbx.c: Executing [s@app-missedcall-hangup:6] GotoIf(“PJSIP/106-00000019”, “0?exit”) in new stack
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] pbx.c: Executing [s@app-missedcall-hangup:7] AGI(“PJSIP/106-00000019”, “missedcallnotify.php,s,s,0,PJSIP/106-00000019,CHANUNAVAIL,”) in new stack
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/missedcallnotify.php
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] res_agi.c: <PJSIP/106-00000019>AGI Script missedcallnotify.php completed, returning 0
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] pbx.c: Executing [s@app-missedcall-hangup:8] Return(“PJSIP/106-00000019”, “”) in new stack
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-hangupcall:3] GotoIf(“PJSIP/106-00000019”, “1?theend”) in new stack
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] pbx_builtins.c: Goto (macro-hangupcall,s,5)
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-hangupcall:5] ExecIf(“PJSIP/106-00000019”, “0?Set(CDR(recordingfile)=)”) in new stack
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] pbx.c: Executing [s@macro-hangupcall:6] Hangup(“PJSIP/106-00000019”, “”) in new stack
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] app_macro.c: Spawn extension (macro-hangupcall, s, 6) exited non-zero on ‘PJSIP/106-00000019’ in macro ‘hangupcall’
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on ‘PJSIP/106-00000019’
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] app_stack.c: PJSIP/106-00000019 Internal Gosub(crm-hangup,s,1) start
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] pbx.c: Executing [s@crm-hangup:1] NoOp(“PJSIP/106-00000019”, “Sending Hangup to CRM”) in new stack
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] pbx.c: Executing [s@crm-hangup:2] NoOp(“PJSIP/106-00000019”, “HANGUP CAUSE: 17”) in new stack
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] pbx.c: Executing [s@crm-hangup:3] ExecIf(“PJSIP/106-00000019”, “0?Set(__CRM_VOICEMAIL=)”) in new stack
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] pbx.c: Executing [s@crm-hangup:4] NoOp(“PJSIP/106-00000019”, “MASTER CHANNEL: 1743457238.40 = 1743457238.40”) in new stack
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] pbx.c: Executing [s@crm-hangup:5] GotoIf(“PJSIP/106-00000019”, “0?return”) in new stack
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] pbx.c: Executing [s@crm-hangup:6] Set(“PJSIP/106-00000019”, “__CRM_HANGUP=1”) in new stack
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] pbx.c: Executing [s@crm-hangup:7] AGI(“PJSIP/106-00000019”, “sangomacrm.agi”) in new stack
[2025-03-31 22:40:39] VERBOSE[25809][C-00000011] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
[2025-03-31 22:40:40] VERBOSE[25809][C-00000011] res_agi.c: <PJSIP/106-00000019>AGI Script sangomacrm.agi completed, returning 0
[2025-03-31 22:40:40] VERBOSE[25809][C-00000011] pbx.c: Executing [s@crm-hangup:8] Return(“PJSIP/106-00000019”, “”) in new stack
[2025-03-31 22:40:40] VERBOSE[25809][C-00000011] app_stack.c: Spawn extension (from-internal, h, 1) exited non-zero on ‘PJSIP/106-00000019’
[2025-03-31 22:40:40] VERBOSE[25809][C-00000011] app_stack.c: PJSIP/106-00000019 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
[2025-03-31 22:40:46] VERBOSE[2858] chan_sip.c: Really destroying SIP dialog ‘[email protected]:5160’ Method: INVITE
Here is the Vega Config File;
;This Config file is generated by FreePBX DO NOT EDIT OR DELETE THIS LINE
;FREEPBX-TRUNKID=1
;FREEPBX-FXOPORTS={“p1”:“1”,“p2”:“1”,“p3”:“1”,“p4”:“1”}
set quick.country=“UK”
set quick.voip.local_rx_port=“5060”
set quick.hostname=“192.168.178.129”
set quick.emergency_numbers=“999,112,911,000”
set lan.file_transfer_method=“TFTP”
set quick.fxs.digital_rx_gain=“-1”
set quick.fxs.digital_tx_gain=“-2”
set quick.lan.ip=“192.168.178.129”
set quick.lan.subnet=“255.255.255.0”
set quick.lan.gateway=“192.168.178.1”
set quick.lan.ntp=“uk.pool.ntp.org”
set quick.lan.dns1=“8.8.8.8”
set quick.lan.dns2=“1.1.1.1”
set quick.fxo.callerid_type=“bt”
set quick.fxo.digital_rx_gain=“-1”
set quick.fxo.digital_tx_gain=“3”
quick.fxo.1.handle_emergency_calls=“1”
quick.fxo.1.clid_enable=“1”
quick.fxo.1.name=“FXO1”
quick.fxo.1.this_tel=“0201”
quick.fxo.1.numlist=““(Correct PSTN number redacted)
quick.fxo.1.incoming_forward=””(Correct PSTN number redacted)
quick.fxo.2.handle_emergency_calls=“1”
quick.fxo.2.clid_enable=“1”
quick.fxo.2.name=“FXO2”
quick.fxo.2.this_tel=“0202”
quick.fxo.2.numlist=“."
quick.fxo.2.incoming_forward=“***********” (Correct PSTN number redacted)
quick.fxo.3.handle_emergency_calls=“1”
quick.fxo.3.clid_enable=“1”
quick.fxo.3.name=“FXO3”
quick.fxo.3.this_tel=“0203”
quick.fxo.3.numlist=".”
quick.fxo.3.incoming_forward=“" (Correct PSTN number redacted)
quick.fxo.4.handle_emergency_calls=“1”
quick.fxo.4.clid_enable=“1”
quick.fxo.4.name=“FXO4”
quick.fxo.4.this_tel=“0204”
quick.fxo.4.numlist=“.*”
quick.fxo.4.incoming_forward="” (Correct PSTN number redacted)
Have you updated the local networks on the SIP settings page?
If you defined the peer with IP authentication, have you updated the peer’s definition?
The Vega doesn’t consider 17070 to be a valid number. Probably a Vega configuration error.
But you have
So I suspect that your peer details for the outgoing trunk has
port=5160
when it should have
port=5060
If that’s not your issue, please explain. I would not expect IP address changes to result in any port number changes.
Tried chaning the port to 5060, no joy so reset back to 5160.
Here is the Vega log for an outgoing call;
LOG: 01/04/2025 10:55:03.845 ROUTER (A)Rb0C00 FINDROUTE: rejected; no route
← SIP [2,1] dest=NAME:07860352075,TEL:07860352075
LOG: 01/04/2025 10:55:03.845 ROUTER (I)R07C00 no route to destination
call ref=[01b4001f]
LOG: 01/04/2025 10:55:03.845 SIP (I)R04C10 disconnect(disc req) 1
call ref=[01b4001f]
Here is the FreePBX SIP Setting on the SIP strunk going to the Vega;
Out Going route – Free PBX
host=192.168.178.129
port=5160
type=peer
insecure=very
qualify=yes
trustrpid=yes
sendrpid=yes
dtmfmode=rfc2833
disallow=all
allow=ulaw,alaw
Here are the SIP & VoIP settings on the Vega;
Vega SIP Settings
General
Local SIP Port 5160
Local SIP TLS Port 5061
Accept Non-Proxy Invites OFF
VoIP Config
General VoIP Configuration
Registration Mode OFF
Local Gateway Configuration
SIP Local Port 5160
Remote Server Configuration
SIP Server IP/FQDN 192.168.178.116
SIP Server Port 5160
|SIP Server Transport Mode| | UDP
|—|—|
|SIP Accessibility Check| | Options
Registration and Authentication ID Reg & Auth ID
Authentication Password *****
Here is the SIP registrations status from the Vega;
SIP REG
SIP Profile 1 - registration expiry = 600s
SIP REG USER 1
— address - [email protected]
— registrar - 0.0.0.0:0
— auth user - auth user disabled
— contact - sip:[email protected]:5160
— state - unregistered (user 1)
— TTL - 0 seconds
SIP REG USER 2
— address - [email protected]
— registrar - 0.0.0.0:0
— auth user -
— contact - sip:[email protected]:5160
— state - unregistered (user 2)
— TTL - 0 seconds
SIP REG USER 3
— address - [email protected]
— registrar - 0.0.0.0:0
— auth user -
— contact - sip:[email protected]:5160
— state - unregistered (user 3)
— TTL - 0 seconds
SIP REG USER 4
— address - [email protected]
— registrar - 0.0.0.0:0
— auth user -
— contact - sip:[email protected]:5160
— state - unregistered (user 4)
— TTL - 0 seconds
This isn’t the number being used in the Asterisk log, but it comes to the same thing. The Vega does not know how to handle calls to 07869352075, either.
I don’t think there is anything wrong on the Asterisk side, except, possibly, a missing prefix on the called number.
I’d suggest a thorough read of chapter 9 of https://ftp.sangoma.com/vega/custom/TTSL/Vega_Admin_Guide_R11_v1.4.pdf