Outgoing calls through IAX2 trunk don’t work! HELP PLIS

bobandroid24 · January 9, 2022, 3:03am

Hello everyone, I currently have 5 servers in different areas (* New York *, Tennessee, Holland, Brazil and Mexico) that are linked through IAX2. However, the New York headquarters has stopped working and has tried many options without success

I can receive calls to * New York * without any inconvenience.

However, outgoing calls from * New York * are presenting a strange scenario. When the call is started in * New York * it remains in CALLING state:

After accepting the call from the other side, * New York * switches to the RINGING state.

On the other side, the call is open but without receiving anything.

I have tried various configurations and the IAX2 shows registered.

Please, if someone can help me I would appreciate it infinitely

bobandroid24 · January 9, 2022, 3:40am

HELP! plisssss

jgttgns · January 9, 2022, 10:28am

You need to provide your configuration and the output of iax2 set debug on. Without the technical info it’s basically crystal ball gazing and that is something you can do for yourself.

bobandroid24 · January 9, 2022, 1:10pm

You’re right, forget to paste that information. I have tried the trunk configuration in many different ways and even with the same one that works for me in the other exchanges. Right now I have the following:

Trunk Name:FIRST-SERVER

host=10.X.X.X (ip SERVER2)
username=SECOND-SERVER
secret=somesecret
type=friend
context=from-internal
qualify=yes
transfer=no
allow=alaw&ulaw
disallow=all

Trunk Another SITE
Outgoing
Trunk Name:

host=10.X.X.X (ip NEWYORK)
username=FIRST-SERVER
secret=somesecret
type=friend
context=from-internal
qualify=yes
transfer=no
allow=alaw&ulaw
disallow=all

Name/Username Host Mask Port Status Description
SECOND-SERVER/F 10.X.X.X (S) 255.255.255.255 4569 OK (130 ms)

bobandroid24 · January 9, 2022, 1:11pm

The debug in New York shows the following:

freepbx*CLI> iax2 set debug on
IAX2 Debugging Enabled
Tx-Frame Retry[000] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE
Timestamp: 00003ms SCall: 13556 DCall: 00000 10.X.X.X:4569

Rx-Frame Retry[ No] – OSeqno: 000 ISeqno: 001 Type: IAX Subclass: PONG
Timestamp: 00003ms SCall: 00001 DCall: 13556 10.X.X.X:4569
Tx-Frame Retry[-01] – OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK
Timestamp: 00003ms SCall: 13556 DCall: 00001 10.X.X.X:4569
Rx-Frame Retry[ No] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE
Timestamp: 00007ms SCall: 02467 DCall: 00000 10.X.X.X:4569

Tx-Frame Retry[ No] – OSeqno: 000 ISeqno: 001 Type: IAX Subclass: PONG
Timestamp: 00007ms SCall: 00001 DCall: 02467 10.X.X.X:4569
Rx-Frame Retry[ No] – OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK
Timestamp: 00007ms SCall: 02467 DCall: 00001 10.X.X.X:4569
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
> 0x7f73482720c0 – Strict RTP learning after remote address set to: 10.0.X.X:62237
– Executing [2100@from-internal:1] Macro(“SIP/503-0000076c”, “user-callerid,LIMIT,EXTERNAL,”) in new stack
– Executing [s@macro-user-callerid:1] Set(“SIP/503-0000076c”, “TOUCH_MONITOR=1641733247.1973”) in new stack
– Executing [s@macro-user-callerid:2] Set(“SIP/503-0000076c”, “CHANCONTEXT=”) in new stack
– Executing [s@macro-user-callerid:3] Set(“SIP/503-0000076c”, “CHANCONTEXT=”) in new stack
– Executing [s@macro-user-callerid:4] Set(“SIP/503-0000076c”, “CHANEXTENCONTEXT=503-0000076c”) in new stack
– Executing [s@macro-user-callerid:5] Set(“SIP/503-0000076c”, “CHANEXTEN=503-0000076c”) in new stack
– Executing [s@macro-user-callerid:6] Set(“SIP/503-0000076c”, “CALLERID(number)=503”) in new stack
– Executing [s@macro-user-callerid:7] Set(“SIP/503-0000076c”, “AMPUSER=503”) in new stack
– Executing [s@macro-user-callerid:8] Set(“SIP/503-0000076c”, “HOTDESCKCHAN=503-0000076c”) in new stack
– Executing [s@macro-user-callerid:9] Set(“SIP/503-0000076c”, “HOTDESKEXTEN=503”) in new stack
– Executing [s@macro-user-callerid:10] Set(“SIP/503-0000076c”, “HOTDESKCALL=0”) in new stack
– Executing [s@macro-user-callerid:11] ExecIf(“SIP/503-0000076c”, “0?Set(HOTDESKCALL=1)”) in new stack
– Executing [s@macro-user-callerid:12] ExecIf(“SIP/503-0000076c”, “0?Set(CALLERID(name)=)”) in new stack
– Executing [s@macro-user-callerid:13] GotoIf(“SIP/503-0000076c”, “0?report”) in new stack
– Executing [s@macro-user-callerid:14] ExecIf(“SIP/503-0000076c”, “1?Set(REALCALLERIDNUM=503)”) in new stack
– Executing [s@macro-user-callerid:15] Set(“SIP/503-0000076c”, “AMPUSER=503”) in new stack
– Executing [s@macro-user-callerid:16] GotoIf(“SIP/503-0000076c”, “0?limit”) in new stack
– Executing [s@macro-user-callerid:17] Set(“SIP/503-0000076c”, “AMPUSERCIDNAME=Test”) in new stack
– Executing [s@macro-user-callerid:18] ExecIf(“SIP/503-0000076c”, “0?Set(__CIDMASQUERADING=TRUE)”) in new stack
– Executing [s@macro-user-callerid:19] GotoIf(“SIP/503-0000076c”, “0?report”) in new stack
– Executing [s@macro-user-callerid:20] Set(“SIP/503-0000076c”, “AMPUSERCID=503”) in new stack
– Executing [s@macro-user-callerid:21] Set(“SIP/503-0000076c”, “__DIAL_OPTIONS=HhTtr”) in new stack
– Executing [s@macro-user-callerid:22] Set(“SIP/503-0000076c”, “CALLERID(all)=“Test” <503>”) in new stack
– Executing [s@macro-user-callerid:23] ExecIf(“SIP/503-0000076c”, “0?Set(CUSDIAL=)”) in new stack
– Executing [s@macro-user-callerid:24] ExecIf(“SIP/503-0000076c”, “0?Set(CALLERID(all)=“Test” <503>)”) in new stack
– Executing [s@macro-user-callerid:25] GotoIf(“SIP/503-0000076c”, “0?limit”) in new stack
– Executing [s@macro-user-callerid:26] ExecIf(“SIP/503-0000076c”, “1?Set(GROUP(concurrency_limit)=503)”) in new stack
– Executing [s@macro-user-callerid:27] ExecIf(“SIP/503-0000076c”, “0?Set(CHANNEL(language)=)”) in new stack
– Executing [s@macro-user-callerid:28] NoOp(“SIP/503-0000076c”, “Macro Depth is 1”) in new stack
– Executing [s@macro-user-callerid:29] GotoIf(“SIP/503-0000076c”, “1?report2:macroerror”) in new stack
– Goto (macro-user-callerid,s,30)
– Executing [s@macro-user-callerid:30] GotoIf(“SIP/503-0000076c”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,49)
– Executing [s@macro-user-callerid:49] Set(“SIP/503-0000076c”, “CALLERID(number)=503”) in new stack
– Executing [s@macro-user-callerid:50] Set(“SIP/503-0000076c”, “CALLERID(name)=Test”) in new stack
– Executing [s@macro-user-callerid:51] GotoIf(“SIP/503-0000076c”, “0?cnum”) in new stack
– Executing [s@macro-user-callerid:52] Set(“SIP/503-0000076c”, “CDR(cnam)=Test”) in new stack
– Executing [s@macro-user-callerid:53] Set(“SIP/503-0000076c”, “CDR(cnum)=503”) in new stack
– Executing [s@macro-user-callerid:54] Set(“SIP/503-0000076c”, “CHANNEL(language)=en”) in new stack
– Executing [2100@from-internal:2] Gosub(“SIP/503-0000076c”, “sub-record-check,s,1(out,2100,dontcare)”) in new stack
– Executing [s@sub-record-check:1] GotoIf(“SIP/503-0000076c”, “0?initialized”) in new stack
– Executing [s@sub-record-check:2] Set(“SIP/503-0000076c”, “__REC_STATUS=INITIALIZED”) in new stack
– Executing [s@sub-record-check:3] Set(“SIP/503-0000076c”, “NOW=1641733247”) in new stack
– Executing [s@sub-record-check:4] Set(“SIP/503-0000076c”, “__DAY=09”) in new stack
– Executing [s@sub-record-check:5] Set(“SIP/503-0000076c”, “__MONTH=01”) in new stack
– Executing [s@sub-record-check:6] Set(“SIP/503-0000076c”, “__YEAR=2022”) in new stack
– Executing [s@sub-record-check:7] Set(“SIP/503-0000076c”, “__TIMESTR=20220109-080047”) in new stack
– Executing [s@sub-record-check:8] Set(“SIP/503-0000076c”, “__FROMEXTEN=503”) in new stack
– Executing [s@sub-record-check:9] Set(“SIP/503-0000076c”, “__MON_FMT=wav”) in new stack
– Executing [s@sub-record-check:10] NoOp(“SIP/503-0000076c”, “Recordings initialized”) in new stack
– Executing [s@sub-record-check:11] ExecIf(“SIP/503-0000076c”, “0?Set(ARG3=dontcare)”) in new stack
– Executing [s@sub-record-check:12] Set(“SIP/503-0000076c”, “REC_POLICY_MODE_SAVE=”) in new stack
– Executing [s@sub-record-check:13] ExecIf(“SIP/503-0000076c”, “0?Set(REC_STATUS=NO)”) in new stack
– Executing [s@sub-record-check:14] GotoIf(“SIP/503-0000076c”, “3?checkaction”) in new stack
– Goto (sub-record-check,s,17)
– Executing [s@sub-record-check:17] GotoIf(“SIP/503-0000076c”, “1?sub-record-check,out,1”) in new stack
– Goto (sub-record-check,out,1)
– Executing [out@sub-record-check:1] NoOp(“SIP/503-0000076c”, “Outbound Recording Check from 503 to 2100”) in new stack
– Executing [out@sub-record-check:2] Set(“SIP/503-0000076c”, “RECMODE=dontcare”) in new stack
– Executing [out@sub-record-check:3] ExecIf(“SIP/503-0000076c”, “1?Goto(routewins)”) in new stack
– Goto (sub-record-check,out,7)
– Executing [out@sub-record-check:7] Gosub(“SIP/503-0000076c”, “recordcheck,1(dontcare,out,2100)”) in new stack
– Executing [recordcheck@sub-record-check:1] NoOp(“SIP/503-0000076c”, “Starting recording check against dontcare”) in new stack
– Executing [recordcheck@sub-record-check:2] Goto(“SIP/503-0000076c”, “dontcare”) in new stack
– Goto (sub-record-check,recordcheck,3)
– Executing [recordcheck@sub-record-check:3] Return(“SIP/503-0000076c”, “”) in new stack
– Executing [out@sub-record-check:8] Return(“SIP/503-0000076c”, “”) in new stack
– Executing [2100@from-internal:3] ExecIf(“SIP/503-0000076c”, “0 ?Set(CDR(accountcode)=)”) in new stack
– Executing [2100@from-internal:4] Set(“SIP/503-0000076c”, “_ROUTEID=13”) in new stack
– Executing [2100@from-internal:5] Set(“SIP/503-0000076c”, “_ROUTENAME=Out_NL”) in new stack
– Executing [2100@from-internal:6] Set(“SIP/503-0000076c”, “INTRACOMPANYROUTE=YES”) in new stack
– Executing [2100@from-internal:7] Set(“SIP/503-0000076c”, “MOHCLASS=default”) in new stack
– Executing [2100@from-internal:8] ExecIf(“SIP/503-0000076c”, “1?Set(TRUNKCIDOVERRIDE=LHI)”) in new stack
– Executing [2100@from-internal:9] Set(“SIP/503-0000076c”, “_CALLERIDNAMEINTERNAL=Test”) in new stack
– Executing [2100@from-internal:10] Set(“SIP/503-0000076c”, “_CALLERIDNUMINTERNAL=503”) in new stack
– Executing [2100@from-internal:11] Set(“SIP/503-0000076c”, “_EMAILNOTIFICATION=FALSE”) in new stack
– Executing [2100@from-internal:12] Set(“SIP/503-0000076c”, “_NODEST=”) in new stack
– Executing [2100@from-internal:13] Macro(“SIP/503-0000076c”, “dialout-trunk,3,100,on”) in new stack
– Executing [s@macro-dialout-trunk:1] Set(“SIP/503-0000076c”, “DIAL_TRUNK=3”) in new stack
– Executing [s@macro-dialout-trunk:2] ExecIf(“SIP/503-0000076c”, “0?Set(DIAL_OPTIONS=Hhtr)”) in new stack
– Executing [s@macro-dialout-trunk:3] GosubIf(“SIP/503-0000076c”, “0?sub-pincheck,s,1()”) in new stack
– Executing [s@macro-dialout-trunk:4] ExecIf(“SIP/503-0000076c”, “0?Set(CALLERID(num)=503)”) in new stack
– Executing [s@macro-dialout-trunk:5] GotoIf(“SIP/503-0000076c”, “0?disabletrunk,1”) in new stack
– Executing [s@macro-dialout-trunk:6] Set(“SIP/503-0000076c”, “DIAL_NUMBER=100”) in new stack
– Executing [s@macro-dialout-trunk:7] Set(“SIP/503-0000076c”, “DIAL_TRUNK_OPTIONS=HhTtr”) in new stack
– Executing [s@macro-dialout-trunk:8] Set(“SIP/503-0000076c”, “OUTBOUND_GROUP=OUT_3”) in new stack
– Executing [s@macro-dialout-trunk:9] Set(“SIP/503-0000076c”, “DIAL_TRUNK_OPTIONS=T”) in new stack
– Executing [s@macro-dialout-trunk:10] GotoIf(“SIP/503-0000076c”, “1?nomax”) in new stack
– Goto (macro-dialout-trunk,s,12)
– Executing [s@macro-dialout-trunk:12] GotoIf(“SIP/503-0000076c”, “1?skipoutcid”) in new stack
– Goto (macro-dialout-trunk,s,14)
– Executing [s@macro-dialout-trunk:14] GosubIf(“SIP/503-0000076c”, “0?sub-flp-3,s,1()”) in new stack
– Executing [s@macro-dialout-trunk:15] Set(“SIP/503-0000076c”, “OUTNUM=100”) in new stack
– Executing [s@macro-dialout-trunk:16] Set(“SIP/503-0000076c”, “custom=IAX2/FIRST-SERVER”) in new stack
– Executing [s@macro-dialout-trunk:17] ExecIf(“SIP/503-0000076c”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)”) in new stack
– Executing [s@macro-dialout-trunk:18] ExecIf(“SIP/503-0000076c”, “0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))”) in new stack
– Executing [s@macro-dialout-trunk:19] Macro(“SIP/503-0000076c”, “dialout-trunk-predial-hook,”) in new stack
– Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit(“SIP/503-0000076c”, “”) in new stack
– Executing [s@macro-dialout-trunk:20] GotoIf(“SIP/503-0000076c”, “0?skipcrm”) in new stack
– Executing [s@macro-dialout-trunk:21] Set(“SIP/503-0000076c”, “__CRM_DIRECTION=OUTBOUND”) in new stack
– Executing [s@macro-dialout-trunk:22] Set(“SIP/503-0000076c”, “__CRM_DESTINATION=100”) in new stack
– Executing [s@macro-dialout-trunk:23] Set(“SIP/503-0000076c”, “__CRM_SOURCE=503”) in new stack
– Executing [s@macro-dialout-trunk:24] AGI(“SIP/503-0000076c”, “sangomacrm.agi”) in new stack
– Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
– <SIP/503-0000076c>AGI Script sangomacrm.agi completed, returning 0
– Executing [s@macro-dialout-trunk:25] Set(“SIP/503-0000076c”, “CHANNEL(hangup_handler_push)=crm-hangup,s,1”) in new stack
– Executing [s@macro-dialout-trunk:26] NoOp(“SIP/503-0000076c”, “CRM Finished”) in new stack
– Executing [s@macro-dialout-trunk:27] GotoIf(“SIP/503-0000076c”, “0?bypass,1”) in new stack
– Executing [s@macro-dialout-trunk:28] ExecIf(“SIP/503-0000076c”, “1?Set(CONNECTEDLINE(num,i)=100)”) in new stack
– Executing [s@macro-dialout-trunk:29] ExecIf(“SIP/503-0000076c”, “1?Set(CONNECTEDLINE(name,i)=CID:503)”) in new stack
– Executing [s@macro-dialout-trunk:30] ExecIf(“SIP/503-0000076c”, “0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)503)”) in new stack
– Executing [s@macro-dialout-trunk:31] GotoIf(“SIP/503-0000076c”, “0?customtrunk”) in new stack
– Executing [s@macro-dialout-trunk:32] ExecIf(“SIP/503-0000076c”, “0?Set(DIAL_TRUNK_OPTIONS=)”) in new stack
– Executing [s@macro-dialout-trunk:33] Set(“SIP/503-0000076c”, “HASH(__SIPHEADERS,Alert-Info)=unset”) in new stack
– Executing [s@macro-dialout-trunk:34] Dial(“SIP/503-0000076c”, “IAX2/FIRST-SERVER/100,300,Tb(func-apply-sipheaders^s^1,(3))U(sub-send-obroute-email^100^2100^3^1641733247^Test^503)”) in new stack
– IAX2/FIRST-SERVER-1979 Internal Gosub(func-apply-sipheaders,s,1(3)) start
– Executing [s@func-apply-sipheaders:1] ExecIf(“IAX2/FIRST-SERVER-1979”, “0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)”) in new stack
– Executing [s@func-apply-sipheaders:2] NoOp(“IAX2/FIRST-SERVER-1979”, “Applying SIP Headers to channel IAX2/FIRST-SERVER-1979”) in new stack
– Executing [s@func-apply-sipheaders:3] Set(“IAX2/FIRST-SERVER-1979”, “TECH=IAX2”) in new stack
– Executing [s@func-apply-sipheaders:4] Set(“IAX2/FIRST-SERVER-1979”, “SIPHEADERKEYS=Alert-Info”) in new stack
– Executing [s@func-apply-sipheaders:5] While(“IAX2/FIRST-SERVER-1979”, “1”) in new stack
– Executing [s@func-apply-sipheaders:6] Set(“IAX2/FIRST-SERVER-1979”, “sipheader=unset”) in new stack
– Executing [s@func-apply-sipheaders:7] ExecIf(“IAX2/FIRST-SERVER-1979”, “0?SIPRemoveHeader(Alert-Info:)”) in new stack
– Executing [s@func-apply-sipheaders:8] ExecIf(“IAX2/FIRST-SERVER-1979”, “0?Set(PJSIP_HEADER(remove,Alert-Info)=)”) in new stack
– Executing [s@func-apply-sipheaders:9] ExecIf(“IAX2/FIRST-SERVER-1979”, “0?Set(sipheader=http://127.0.0.1;info=unset)”) in new stack
– Executing [s@func-apply-sipheaders:10] ExecIf(“IAX2/FIRST-SERVER-1979”, “0?Set(sipheader=http://127.0.0.1unset)”) in new stack
– Executing [s@func-apply-sipheaders:11] ExecIf(“IAX2/FIRST-SERVER-1979”, “0?SIPAddHeader(Alert-Info:unset)”) in new stack
– Executing [s@func-apply-sipheaders:12] ExecIf(“IAX2/FIRST-SERVER-1979”, “0?Set(PJSIP_HEADER(add,Alert-Info)=unset)”) in new stack
– Executing [s@func-apply-sipheaders:13] EndWhile(“IAX2/FIRST-SERVER-1979”, “”) in new stack
– Executing [s@func-apply-sipheaders:5] While(“IAX2/FIRST-SERVER-1979”, “0”) in new stack
– Executing [s@func-apply-sipheaders:14] Return(“IAX2/FIRST-SERVER-1979”, “”) in new stack
== Spawn extension (, 2100, 1) exited non-zero on ‘IAX2/FIRST-SERVER-1979’
– IAX2/FIRST-SERVER-1979 Internal Gosub(func-apply-sipheaders,s,1(3)) complete GOSUB_RETVAL=
– Called IAX2/FIRST-SERVER/100
Tx-Frame Retry[000] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: NEW
Timestamp: 00001ms SCall: 01979 DCall: 00000 10.X.X.X:4569
VERSION : 2
CALLED NUMBER : 100
CODEC_PREFS : (ulaw|alaw)
CALLING NUMBER : 503
CALLING PRESNTN : 0
CALLING TYPEOFN : 0
CALLING TRANSIT : 0
CALLING NAME : Test
LANGUAGE : en
USERNAME : SECOND-SERVER
FORMAT : 4
FORMAT2 : ulaw
CAPABILITY : 12
CAPABILITY2 : Unknown
ADSICPE : 2
DATE TIME : 2022-01-09 08:00:48

Rx-Frame Retry[ No] – OSeqno: 000 ISeqno: 001 Type: IAX Subclass: CTOKEN
Timestamp: 00001ms SCall: 00001 DCall: 01979 10.X.X.X:4569
CALLTOKEN : 51 bytes

Tx-Frame Retry[000] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: NEW
Timestamp: 00131ms SCall: 01979 DCall: 00000 10.X.X.X:4569
VERSION : 2
CALLED NUMBER : 100
CODEC_PREFS : (ulaw|alaw)
CALLING NUMBER : 503
CALLING PRESNTN : 0
CALLING TYPEOFN : 0
CALLING TRANSIT : 0
CALLING NAME : Test
LANGUAGE : en
USERNAME : SECOND-SERVER
FORMAT : 4
FORMAT2 : ulaw
CAPABILITY : 12
CAPABILITY2 : Unknown
ADSICPE : 2
DATE TIME : 2022-01-09 08:00:48
CALLTOKEN : 51 bytes

Rx-Frame Retry[ No] – OSeqno: 000 ISeqno: 001 Type: IAX Subclass: ACK
Timestamp: 00131ms SCall: 07250 DCall: 01979 10.X.X.X:4569
Rx-Frame Retry[ No] – OSeqno: 000 ISeqno: 001 Type: IAX Subclass: AUTHREQ
Timestamp: 00008ms SCall: 07250 DCall: 01979 10.X.X.X:4569
AUTHMETHODS : 3
CHALLENGE : \x31\x34\x33\x36\x32\x33\x39\x30\x31
USERNAME : SECOND-SERVER

Tx-Frame Retry[000] – OSeqno: 001 ISeqno: 001 Type: IAX Subclass: AUTHREP
Timestamp: 00262ms SCall: 01979 DCall: 07250 10.X.X.X:4569
MD5 RESULT : 4c44dd9ee211267e3d311e4e5d7e395d

Rx-Frame Retry[ No] – OSeqno: 001 ISeqno: 002 Type: IAX Subclass: ACK
Timestamp: 00262ms SCall: 07250 DCall: 01979 10.X.X.X:4569
Rx-Frame Retry[ No] – OSeqno: 001 ISeqno: 002 Type: IAX Subclass: ACCEPT
Timestamp: 00138ms SCall: 07250 DCall: 01979 10.X.X.X:4569
FORMAT : 8
FORMAT2 : alaw

-- Call accepted by 10.X.X.X:4569 (format alaw)
-- Format for call is (alaw)

Tx-Frame Retry[-01] – OSeqno: 002 ISeqno: 002 Type: IAX Subclass: ACK
Timestamp: 00138ms SCall: 01979 DCall: 07250 10.X.X.X:4569
Rx-Frame Retry[ No] – OSeqno: 002 ISeqno: 002 Type: CONTROL Subclass: RINGING
Timestamp: 00141ms SCall: 07250 DCall: 01979 10.X.X.X:4569
Tx-Frame Retry[-01] – OSeqno: 002 ISeqno: 003 Type: IAX Subclass: ACK
Timestamp: 00141ms SCall: 01979 DCall: 07250 10.X.X.X:4569
Rx-Frame Retry[ No] – OSeqno: 003 ISeqno: 002 Type: CONTROL Subclass: RINGING
Timestamp: 01709ms SCall: 07250 DCall: 01979 10.X.X.X:4569
Tx-Frame Retry[-01] – OSeqno: 002 ISeqno: 004 Type: IAX Subclass: ACK
Timestamp: 01709ms SCall: 01979 DCall: 07250 10.X.X.X:4569
Rx-Frame Retry[ No] – OSeqno: 004 ISeqno: 002 Type: CONTROL Subclass: (255?)
Timestamp: 07442ms SCall: 07250 DCall: 01979 10.X.X.X:4569
Tx-Frame Retry[-01] – OSeqno: 002 ISeqno: 005 Type: IAX Subclass: ACK
Timestamp: 07442ms SCall: 01979 DCall: 07250 10.X.X.X:4569
Rx-Frame Retry[ No] – OSeqno: 005 ISeqno: 002 Type: CONTROL Subclass: ANSWER
Timestamp: 07445ms SCall: 07250 DCall: 01979 10.X.X.X:4569
Tx-Frame Retry[-01] – OSeqno: 002 ISeqno: 006 Type: IAX Subclass: ACK
Timestamp: 07445ms SCall: 01979 DCall: 07250 10.X.X.X:4569
Rx-Frame Retry[ No] – OSeqno: 006 ISeqno: 002 Type: CONTROL Subclass: (255?)
Timestamp: 07448ms SCall: 07250 DCall: 01979 10.X.X.X:4569
Tx-Frame Retry[-01] – OSeqno: 002 ISeqno: 007 Type: IAX Subclass: ACK
Timestamp: 07448ms SCall: 01979 DCall: 07250 10.X.X.X:4569
Tx-Frame Retry[000] – OSeqno: 007 ISeqno: 007 Type: IAX Subclass: VNAK
Timestamp: 07982ms SCall: 01979 DCall: 07250 10.X.X.X:4569
Tx-Frame Retry[000] – OSeqno: 007 ISeqno: 007 Type: IAX Subclass: VNAK
Timestamp: 07985ms SCall: 01979 DCall: 07250 10.X.X.X:4569
Rx-Frame Retry[ No] – OSeqno: 007 ISeqno: 002 Type: VOICE Subclass: 8
Timestamp: 07728ms SCall: 07250 DCall: 01979 10.X.X.X:4569
Tx-Frame Retry[-01] – OSeqno: 002 ISeqno: 008 Type: IAX Subclass: ACK
Timestamp: 07728ms SCall: 01979 DCall: 07250 10.X.X.X:4569
– IAX2/FIRST-SERVER-1979 is ringing
– IAX2/FIRST-SERVER-1979 is ringing
Rx-Frame Retry[ No] – OSeqno: 007 ISeqno: 002 Type: VOICE Subclass: 8
Timestamp: 07728ms SCall: 07250 DCall: 01979 10.X.X.X:4569
Tx-Frame Retry[-01] – OSeqno: 002 ISeqno: 008 Type: IAX Subclass: ACK
Timestamp: 07728ms SCall: 01979 DCall: 07250 10.X.X.X:4569
Rx-Frame Retry[ No] – OSeqno: 007 ISeqno: 002 Type: VOICE Subclass: 8
Timestamp: 07728ms SCall: 07250 DCall: 01979 10.X.X.X:4569
Tx-Frame Retry[-01] – OSeqno: 002 ISeqno: 008 Type: IAX Subclass: ACK
Timestamp: 07728ms SCall: 01979 DCall: 07250 10.X.X.X:4569
Tx-Frame Retry[000] – OSeqno: 002 ISeqno: 008 Type: IAX Subclass: LAGRQ
Timestamp: 09975ms SCall: 01979 DCall: 07250 10.X.X.X:4569
Rx-Frame Retry[ No] – OSeqno: 008 ISeqno: 003 Type: IAX Subclass: LAGRP
Timestamp: 09975ms SCall: 07250 DCall: 01979 10.X.X.X:4569
Tx-Frame Retry[-01] – OSeqno: 003 ISeqno: 009 Type: IAX Subclass: ACK
Timestamp: 09975ms SCall: 01979 DCall: 07250 10.X.X.X:4569
Rx-Frame Retry[ No] – OSeqno: 009 ISeqno: 003 Type: IAX Subclass: LAGRQ
Timestamp: 10007ms SCall: 07250 DCall: 01979 10.X.X.X:4569
Tx-Frame Retry[000] – OSeqno: 003 ISeqno: 010 Type: IAX Subclass: LAGRP
Timestamp: 10007ms SCall: 01979 DCall: 07250 10.X.X.X:4569
Rx-Frame Retry[ No] – OSeqno: 010 ISeqno: 004 Type: IAX Subclass: ACK
Timestamp: 10007ms SCall: 07250 DCall: 01979 10.X.X.X:4569
Tx-Frame Retry[000] – OSeqno: 004 ISeqno: 010 Type: IAX Subclass: LAGRQ
Timestamp: 19974ms SCall: 01979 DCall: 07250 10.X.X.X:4569
Rx-Frame Retry[ No] – OSeqno: 010 ISeqno: 005 Type: IAX Subclass: LAGRP
Timestamp: 19974ms SCall: 07250 DCall: 01979 10.X.X.X:4569
Tx-Frame Retry[-01] – OSeqno: 005 ISeqno: 011 Type: IAX Subclass: ACK
Timestamp: 19974ms SCall: 01979 DCall: 07250 10.X.X.X:4569
Rx-Frame Retry[ No] – OSeqno: 011 ISeqno: 005 Type: IAX Subclass: LAGRQ
Timestamp: 20007ms SCall: 07250 DCall: 01979 10.X.X.X:4569
Tx-Frame Retry[000] – OSeqno: 005 ISeqno: 012 Type: IAX Subclass: LAGRP
Timestamp: 20007ms SCall: 01979 DCall: 07250 10.X.X.X:4569
Rx-Frame Retry[ No] – OSeqno: 012 ISeqno: 006 Type: IAX Subclass: ACK
Timestamp: 20007ms SCall: 07250 DCall: 01979 10.X.X.X:4569
Tx-Frame Retry[000] – OSeqno: 006 ISeqno: 012 Type: IAX Subclass: PING
Timestamp: 20976ms SCall: 01979 DCall: 07250 10.X.X.X:4569
Rx-Frame Retry[ No] – OSeqno: 012 ISeqno: 007 Type: IAX Subclass: PONG
Timestamp: 20976ms SCall: 07250 DCall: 01979 10.X.X.X:4569
RR_JITTER : 0
RR_LOSS : 0
RR_PKTS : 1
RR_DELAY : 40
RR_DROPPED : 0
RR_OUTOFORDER : 0

Tx-Frame Retry[-01] – OSeqno: 007 ISeqno: 013 Type: IAX Subclass: ACK
Timestamp: 20976ms SCall: 01979 DCall: 07250 10.X.X.X:4569
Rx-Frame Retry[ No] – OSeqno: 013 ISeqno: 007 Type: IAX Subclass: PING
Timestamp: 21007ms SCall: 07250 DCall: 01979 10.X.X.X:4569
Tx-Frame Retry[000] – OSeqno: 007 ISeqno: 014 Type: IAX Subclass: PONG
Timestamp: 21007ms SCall: 01979 DCall: 07250 10.X.X.X:4569
RR_JITTER : 98
RR_LOSS : 318767098
RR_PKTS : 641
RR_DELAY : 138
RR_DROPPED : 4
RR_OUTOFORDER : 0

Rx-Frame Retry[ No] – OSeqno: 014 ISeqno: 008 Type: IAX Subclass: ACK
Timestamp: 21007ms SCall: 07250 DCall: 01979 10.X.X.X:4569
Rx-Frame Retry[ No] – OSeqno: 014 ISeqno: 008 Type: IAX Subclass: HANGUP
Timestamp: 24647ms SCall: 07250 DCall: 01979 10.X.X.X:4569
CAUSE CODE : 16

Tx-Frame Retry[-01] – OSeqno: 008 ISeqno: 015 Type: IAX Subclass: ACK
Timestamp: 24647ms SCall: 01979 DCall: 07250 10.X.X.X:4569
– Hungup ‘IAX2/FIRST-SERVER-1979’
– No one is available to answer at this time (1:0/0/0)
– Executing [s@macro-dialout-trunk:35] NoOp(“SIP/503-0000076c”, “Dial failed for some reason with DIALSTATUS = NOANSWER and HANGUPCAUSE = 16”) in new stack
– Executing [s@macro-dialout-trunk:36] GotoIf(“SIP/503-0000076c”, “1?continue,1:s-NOANSWER,1”) in new stack
– Goto (macro-dialout-trunk,continue,1)
– Executing [continue@macro-dialout-trunk:1] NoOp(“SIP/503-0000076c”, “TRUNK Dial failed due to NOANSWER HANGUPCAUSE: 16 - failing through to other trunks”) in new stack
– Executing [continue@macro-dialout-trunk:2] ExecIf(“SIP/503-0000076c”, “1?Set(CALLERID(number)=503)”) in new stack
– Executing [2100@from-internal:14] Macro(“SIP/503-0000076c”, “outisbusy,”) in new stack
– Executing [s@macro-outisbusy:1] Progress(“SIP/503-0000076c”, “”) in new stack
– Executing [s@macro-outisbusy:2] GotoIf(“SIP/503-0000076c”, “0?emergency,1”) in new stack
– Executing [s@macro-outisbusy:3] GotoIf(“SIP/503-0000076c”, “1?intracompany,1”) in new stack
– Goto (macro-outisbusy,intracompany,1)
– Executing [intracompany@macro-outisbusy:1] Playback(“SIP/503-0000076c”, “all-circuits-busy-now&please-try-call-later, noanswer”) in new stack
– <SIP/503-0000076c> Playing ‘all-circuits-busy-now.ulaw’ (language ‘en’)
> 0x7f73482720c0 – Strict RTP switching to RTP target address 10.0.X.X:62237 as source
> 0x7f73482720c0 – Strict RTP learning complete - Locking on source address 10.0.X.X:62237
Tx-Frame Retry[000] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE
Timestamp: 00009ms SCall: 12328 DCall: 00000 10.X.X.X:4569

Rx-Frame Retry[ No] – OSeqno: 000 ISeqno: 001 Type: IAX Subclass: PONG
Timestamp: 00009ms SCall: 00001 DCall: 12328 10.X.X.X:4569
Tx-Frame Retry[-01] – OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK
Timestamp: 00009ms SCall: 12328 DCall: 00001 10.X.X.X:4569
– <SIP/503-0000076c> Playing ‘please-try-call-later.ulaw’ (language ‘en’)
– Executing [intracompany@macro-outisbusy:2] Congestion(“SIP/503-0000076c”, “20”) in new stack
[2022-01-09 08:01:17] WARNING[32313][C-00000772]: channel.c:5195 ast_prod: Prodding channel ‘SIP/503-0000076c’ failed
== Spawn extension (macro-outisbusy, intracompany, 2) exited non-zero on ‘SIP/503-0000076c’ in macro ‘outisbusy’
== Spawn extension (from-internal, 2100, 14) exited non-zero on ‘SIP/503-0000076c’
– Executing [h@from-internal:1] Macro(“SIP/503-0000076c”, “hangupcall”) in new stack
– Executing [s@macro-hangupcall:1] GotoIf(“SIP/503-0000076c”, “1?theend”) in new stack
– Goto (macro-hangupcall,s,3)
– Executing [s@macro-hangupcall:3] ExecIf(“SIP/503-0000076c”, “0?Set(CDR(recordingfile)=)”) in new stack
– Executing [s@macro-hangupcall:4] NoOp(“SIP/503-0000076c”, " montior file= ") in new stack
– Executing [s@macro-hangupcall:5] GotoIf(“SIP/503-0000076c”, “1?skipagi”) in new stack
– Goto (macro-hangupcall,s,7)
– Executing [s@macro-hangupcall:7] Hangup(“SIP/503-0000076c”, “”) in new stack
== Spawn extension (macro-hangupcall, s, 7) exited non-zero on ‘SIP/503-0000076c’ in macro ‘hangupcall’
== Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/503-0000076c’
– SIP/503-0000076c Internal Gosub(crm-hangup,s,1) start
– Executing [s@crm-hangup:1] NoOp(“SIP/503-0000076c”, “Sending Hangup to CRM”) in new stack
– Executing [s@crm-hangup:2] NoOp(“SIP/503-0000076c”, “HANGUP CAUSE: 34”) in new stack
– Executing [s@crm-hangup:3] ExecIf(“SIP/503-0000076c”, “0?Set(__CRM_VOICEMAIL=)”) in new stack
– Executing [s@crm-hangup:4] NoOp(“SIP/503-0000076c”, “MASTER CHANNEL: 1641733247.1973 = 1641733247.1973”) in new stack
– Executing [s@crm-hangup:5] GotoIf(“SIP/503-0000076c”, “0?return”) in new stack
– Executing [s@crm-hangup:6] Set(“SIP/503-0000076c”, “__CRM_HANGUP=1”) in new stack
– Executing [s@crm-hangup:7] AGI(“SIP/503-0000076c”, “sangomacrm.agi”) in new stack
– Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
– <SIP/503-0000076c>AGI Script sangomacrm.agi completed, returning 0
– Executing [s@crm-hangup:8] Return(“SIP/503-0000076c”, “”) in new stack
== Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/503-0000076c’
– SIP/503-0000076c Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
Rx-Frame Retry[ No] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: POKE
Timestamp: 00019ms SCall: 13616 DCall: 00000 10.X.X.X:4569

Tx-Frame Retry[ No] – OSeqno: 000 ISeqno: 001 Type: IAX Subclass: PONG
Timestamp: 00019ms SCall: 00001 DCall: 13616 10.X.X.X:4569
Rx-Frame Retry[ No] – OSeqno: 001 ISeqno: 001 Type: IAX Subclass: ACK
Timestamp: 00019ms SCall: 13616 DCall: 00001 10.X.X.X:4569
freepbxCLI> iax2 set debug off
IAX2 Debugging Disabled
freepbxCLI>

bobandroid24 · January 9, 2022, 2:15pm

If someone could help me I would appreciate it infinitely.

lgaetz · January 9, 2022, 4:10pm

Hi Bob, welcome to the forum.

The participants here are doing so voluntarily. You may not get an immediate response, particularly on the weekend, so pose your question and wait patiently for an answer. There is no need to renew your plea hourly.

jgttgns · January 10, 2022, 7:33am

Poke and Pong seems to work and it looks as if you are not using a trunk, but rather simple IAX accounts on a private LAN (10…). There is probably some kind of VPN tunnel. Since IAX uses only a single port, the connection is likely fine itself.

Since NOANSWER gets signalled, it would now be interesting to see what arrives at the other side. Maybe it is simply that the wrong phone is ringing and nobody answers?

bobandroid24 · January 11, 2022, 11:47am

Hello friend, thanks for answering.

If there is a VPN between the two centrals, I cannot make calls from New York but I can receive them without problems. IAX2 extensions I don’t have, only trunks and chan_sip extensions.

It’s weird because if I create an IAX2 extension and make a call, that same trunk is used and it works without problems. Any suggestions, because I’m in trouble

bobandroid24 · January 11, 2022, 1:31pm

Rx-Frame Retry[ No] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: NEW
Timestamp: 00008ms SCall: 01394 DCall: 00000 10.X.X.X:4569
VERSION : 2
CALLED NUMBER : 100
CODEC_PREFS : (ulaw|alaw)
CALLING NUMBER : 503
CALLING PRESNTN : 0
CALLING TYPEOFN : 0
CALLING TRANSIT : 0
CALLING NAME : Test
LANGUAGE : en
USERNAME : SECOND-SERVER
FORMAT : 4
FORMAT2 : ulaw
CAPABILITY : 12
CAPABILITY2 : Unknown
ADSICPE : 2
DATE TIME : 2022-01-11 08:18:36

Tx-Frame Retry[ No] – OSeqno: 000 ISeqno: 001 Type: IAX Subclass: CTOKEN
Timestamp: 00008ms SCall: 00001 DCall: 01394 10.X.X.X:4569
CALLTOKEN : 51 bytes

Rx-Frame Retry[ No] – OSeqno: 000 ISeqno: 000 Type: IAX Subclass: NEW
Timestamp: 00138ms SCall: 01394 DCall: 00000 10.X.X.X:4569
VERSION : 2
CALLED NUMBER : 100
CODEC_PREFS : (ulaw|alaw)
CALLING NUMBER : 503
CALLING PRESNTN : 0
CALLING TYPEOFN : 0
CALLING TRANSIT : 0
CALLING NAME : Test
LANGUAGE : en
USERNAME : SECOND-SERVER
FORMAT : 4
FORMAT2 : ulaw
CAPABILITY : 12
CAPABILITY2 : Unknown
ADSICPE : 2
DATE TIME : 2022-01-11 08:18:36
CALLTOKEN : 51 bytes

Tx-Frame Retry[-01] – OSeqno: 000 ISeqno: 001 Type: IAX Subclass: ACK
Timestamp: 00138ms SCall: 15472 DCall: 01394 10.X.X.X:4569
Tx-Frame Retry[000] – OSeqno: 000 ISeqno: 001 Type: IAX Subclass: AUTHREQ
Timestamp: 00003ms SCall: 15472 DCall: 01394 10.X.X.X:4569
AUTHMETHODS : 3
CHALLENGE : \x32\x30\x39\x30\x32\x36\x30\x31\x35
USERNAME : SECOND-SERVER

Rx-Frame Retry[ No] – OSeqno: 001 ISeqno: 001 Type: IAX Subclass: AUTHREP
Timestamp: 00269ms SCall: 01394 DCall: 15472 10.X.X.X:4569
MD5 RESULT : 4b5cc2c9b80523a135045dbde2f2863f

Tx-Frame Retry[-01] – OSeqno: 001 ISeqno: 002 Type: IAX Subclass: ACK
Timestamp: 00269ms SCall: 15472 DCall: 01394 10.X.X.X:4569
– Accepting AUTHENTICATED call from 10.X.X.X:4569:
– > requested format = ulaw,
– > requested prefs = (ulaw|alaw),
– > actual format = alaw,
– > host prefs = (alaw|ulaw),
– > priority = mine
Tx-Frame Retry[000] – OSeqno: 001 ISeqno: 002 Type: IAX Subclass: ACCEPT
Timestamp: 00130ms SCall: 15472 DCall: 01394 10.X.X.X:4569
FORMAT : 8
FORMAT2 : alaw

-- Executing [100@from-internal:1] GotoIf("IAX2/SECOND-SERVER-15472", "1?ext-local,100,1:followme-check,100,1") in new stack
-- Goto (ext-local,100,1)
-- Executing [100@ext-local:1] Set("IAX2/SECOND-SERVER-15472", "__RINGTIMER=15") in new stack
-- Executing [100@ext-local:2] ExecIf("IAX2/SECOND-SERVER-15472", "0?Set(__CWIGNORE=)") in new stack
-- Executing [100@ext-local:3] Macro("IAX2/SECOND-SERVER-15472", "exten-vm,100,100,0,0,0") in new stack
-- Executing [s@macro-exten-vm:1] Macro("IAX2/SECOND-SERVER-15472", "user-callerid,") in new stack
-- Executing [s@macro-user-callerid:1] Set("IAX2/SECOND-SERVER-15472", "TOUCH_MONITOR=1641907116.4") in new stack
-- Executing [s@macro-user-callerid:2] Set("IAX2/SECOND-SERVER-15472", "CHANCONTEXT=") in new stack
-- Executing [s@macro-user-callerid:3] Set("IAX2/SECOND-SERVER-15472", "CHANCONTEXT=") in new stack
-- Executing [s@macro-user-callerid:4] Set("IAX2/SECOND-SERVER-15472", "CHANEXTENCONTEXT=SECOND-SERVER-15472") in new stack
-- Executing [s@macro-user-callerid:5] Set("IAX2/SECOND-SERVER-15472", "CHANEXTEN=SECOND-SERVER-15472") in new stack
-- Executing [s@macro-user-callerid:6] Set("IAX2/SECOND-SERVER-15472", "CALLERID(number)=503") in new stack
-- Executing [s@macro-user-callerid:7] Set("IAX2/SECOND-SERVER-15472", "AMPUSER=503") in new stack
-- Executing [s@macro-user-callerid:8] Set("IAX2/SECOND-SERVER-15472", "HOTDESCKCHAN=SECOND-SERVER-15472") in new stack
-- Executing [s@macro-user-callerid:9] Set("IAX2/SECOND-SERVER-15472", "HOTDESKEXTEN=SECOND") in new stack
-- Executing [s@macro-user-callerid:10] Set("IAX2/SECOND-SERVER-15472", "HOTDESKCALL=0") in new stack
-- Executing [s@macro-user-callerid:11] ExecIf("IAX2/SECOND-SERVER-15472", "0?Set(HOTDESKCALL=1)") in new stack
-- Executing [s@macro-user-callerid:12] ExecIf("IAX2/SECOND-SERVER-15472", "0?Set(CALLERID(name)=)") in new stack
-- Executing [s@macro-user-callerid:13] GotoIf("IAX2/SECOND-SERVER-15472", "0?report") in new stack
-- Executing [s@macro-user-callerid:14] ExecIf("IAX2/SECOND-SERVER-15472", "1?Set(REALCALLERIDNUM=503)") in new stack
-- Executing [s@macro-user-callerid:15] Set("IAX2/SECOND-SERVER-15472", "AMPUSER=") in new stack
-- Executing [s@macro-user-callerid:16] GotoIf("IAX2/SECOND-SERVER-15472", "0?limit") in new stack
-- Executing [s@macro-user-callerid:17] Set("IAX2/SECOND-SERVER-15472", "AMPUSERCIDNAME=") in new stack
-- Executing [s@macro-user-callerid:18] ExecIf("IAX2/SECOND-SERVER-15472", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
-- Executing [s@macro-user-callerid:19] GotoIf("IAX2/SECOND-SERVER-15472", "1?report") in new stack
-- Goto (macro-user-callerid,s,28)
-- Executing [s@macro-user-callerid:28] NoOp("IAX2/SECOND-SERVER-15472", "Macro Depth is 2") in new stack
-- Executing [s@macro-user-callerid:29] GotoIf("IAX2/SECOND-SERVER-15472", "1?report2:macroerror") in new stack
-- Goto (macro-user-callerid,s,30)
-- Executing [s@macro-user-callerid:30] GotoIf("IAX2/SECOND-SERVER-15472", "0?continue") in new stack
-- Executing [s@macro-user-callerid:31] ExecIf("IAX2/SECOND-SERVER-15472", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
-- Executing [s@macro-user-callerid:32] Set("IAX2/SECOND-SERVER-15472", "__TTL=64") in new stack
-- Executing [s@macro-user-callerid:33] GotoIf("IAX2/SECOND-SERVER-15472", "1?continue") in new stack
-- Goto (macro-user-callerid,s,49)
-- Executing [s@macro-user-callerid:49] Set("IAX2/SECOND-SERVER-15472", "CALLERID(number)=503") in new stack
-- Executing [s@macro-user-callerid:50] Set("IAX2/SECOND-SERVER-15472", "CALLERID(name)=Test") in new stack
-- Executing [s@macro-user-callerid:51] GotoIf("IAX2/SECOND-SERVER-15472", "0?cnum") in new stack
-- Executing [s@macro-user-callerid:52] Set("IAX2/SECOND-SERVER-15472", "CDR(cnam)=Test") in new stack
-- Executing [s@macro-user-callerid:53] Set("IAX2/SECOND-SERVER-15472", "CDR(cnum)=503") in new stack
-- Executing [s@macro-user-callerid:54] Set("IAX2/SECOND-SERVER-15472", "CHANNEL(language)=en") in new stack
-- Executing [s@macro-exten-vm:2] Set("IAX2/SECOND-SERVER-15472", "RingGroupMethod=none") in new stack
-- Executing [s@macro-exten-vm:3] Set("IAX2/SECOND-SERVER-15472", "__EXTTOCALL=100") in new stack
-- Executing [s@macro-exten-vm:4] Set("IAX2/SECOND-SERVER-15472", "__PICKUPMARK=100") in new stack
-- Executing [s@macro-exten-vm:5] Set("IAX2/SECOND-SERVER-15472", "RT=15") in new stack

[2022-01-11 14:18:36] WARNING[16016][C-00000003]: chan_sip.c:23022 func_header_read: This function can only be used on SIP channels.
– Executing [s@macro-exten-vm:6] ExecIf(“IAX2/SECOND-SERVER-15472”, “0?Macro(vm,100,DIRECTDIAL,)”) in new stack
[2022-01-11 14:18:36] WARNING[16016][C-00000003]: chan_sip.c:23022 func_header_read: This function can only be used on SIP channels.
[2022-01-11 14:18:36] WARNING[16016][C-00000003]: chan_sip.c:23022 func_header_read: This function can only be used on SIP channels.
– Executing [s@macro-exten-vm:7] ExecIf(“IAX2/SECOND-SERVER-15472”, “0?MacroExit()”) in new stack
[2022-01-11 14:18:36] WARNING[16016][C-00000003]: chan_sip.c:23022 func_header_read: This function can only be used on SIP channels.
[2022-01-11 14:18:36] WARNING[16016][C-00000003]: chan_sip.c:23022 func_header_read: This function can only be used on SIP channels.
– Executing [s@macro-exten-vm:8] ExecIf(“IAX2/SECOND-SERVER-15472”, “0?Gosub(ext-intercom,*80100,1())”) in new stack
[2022-01-11 14:18:36] WARNING[16016][C-00000003]: chan_sip.c:23022 func_header_read: This function can only be used on SIP channels.
[2022-01-11 14:18:36] WARNING[16016][C-00000003]: chan_sip.c:23022 func_header_read: This function can only be used on SIP channels.
– Executing [s@macro-exten-vm:9] ExecIf(“IAX2/SECOND-SERVER-15472”, “0?MacroExit()”) in new stack
[2022-01-11 14:18:36] WARNING[16016][C-00000003]: chan_sip.c:23022 func_header_read: This function can only be used on SIP channels.
[2022-01-11 14:18:36] WARNING[16016][C-00000003]: chan_sip.c:23022 func_header_read: This function can only be used on SIP channels.
– Executing [s@macro-exten-vm:10] ExecIf(“IAX2/SECOND-SERVER-15472”, “0?ChanSpy(SIP/100,q)”) in new stack
[2022-01-11 14:18:36] WARNING[16016][C-00000003]: chan_sip.c:23022 func_header_read: This function can only be used on SIP channels.
[2022-01-11 14:18:36] WARNING[16016][C-00000003]: chan_sip.c:23022 func_header_read: This function can only be used on SIP channels.
– Executing [s@macro-exten-vm:11] ExecIf(“IAX2/SECOND-SERVER-15472”, “0?MacroExit()”) in new stack
[2022-01-11 14:18:36] WARNING[16016][C-00000003]: chan_sip.c:23022 func_header_read: This function can only be used on SIP channels.
[2022-01-11 14:18:36] ERROR[16016][C-00000003]: res_pjsip_header_funcs.c:461 func_read_header: This function requires a PJSIP channel.
– Executing [s@macro-exten-vm:12] ExecIf(“IAX2/SECOND-SERVER-15472”, “0?Macro(vm,100,DIRECTDIAL,)”) in new stack
[2022-01-11 14:18:36] ERROR[16016][C-00000003]: res_pjsip_header_funcs.c:461 func_read_header: This function requires a PJSIP channel.
[2022-01-11 14:18:36] ERROR[16016][C-00000003]: res_pjsip_header_funcs.c:461 func_read_header: This function requires a PJSIP channel.
– Executing [s@macro-exten-vm:13] ExecIf(“IAX2/SECOND-SERVER-15472”, “0?MacroExit()”) in new stack
[2022-01-11 14:18:36] ERROR[16016][C-00000003]: res_pjsip_header_funcs.c:461 func_read_header: This function requires a PJSIP channel.
[2022-01-11 14:18:36] ERROR[16016][C-00000003]: res_pjsip_header_funcs.c:461 func_read_header: This function requires a PJSIP channel.
– Executing [s@macro-exten-vm:14] ExecIf(“IAX2/SECOND-SERVER-15472”, “0?Gosub(ext-intercom,*80100,1())”) in new stack
[2022-01-11 14:18:36] ERROR[16016][C-00000003]: res_pjsip_header_funcs.c:461 func_read_header: This function requires a PJSIP channel.
[2022-01-11 14:18:36] ERROR[16016][C-00000003]: res_pjsip_header_funcs.c:461 func_read_header: This function requires a PJSIP channel.
– Executing [s@macro-exten-vm:15] ExecIf(“IAX2/SECOND-SERVER-15472”, “0?MacroExit()”) in new stack
[2022-01-11 14:18:36] ERROR[16016][C-00000003]: res_pjsip_header_funcs.c:461 func_read_header: This function requires a PJSIP channel.
[2022-01-11 14:18:36] ERROR[16016][C-00000003]: res_pjsip_header_funcs.c:461 func_read_header: This function requires a PJSIP channel.
– Executing [s@macro-exten-vm:16] ExecIf(“IAX2/SECOND-SERVER-15472”, “0?ChanSpy(SIP/100,q)”) in new stack
[2022-01-11 14:18:36] ERROR[16016][C-00000003]: res_pjsip_header_funcs.c:461 func_read_header: This function requires a PJSIP channel.
[2022-01-11 14:18:36] ERROR[16016][C-00000003]: res_pjsip_header_funcs.c:461 func_read_header: This function requires a PJSIP channel.
– Executing [s@macro-exten-vm:17] ExecIf(“IAX2/SECOND-SERVER-15472”, “0?MacroExit()”) in new stack
[2022-01-11 14:18:36] ERROR[16016][C-00000003]: res_pjsip_header_funcs.c:461 func_read_header: This function requires a PJSIP channel.
[2022-01-11 14:18:36] WARNING[16016][C-00000003]: chan_sip.c:23022 func_header_read: This function can only be used on SIP channels.
– Executing [s@macro-exten-vm:18] ExecIf(“IAX2/SECOND-SERVER-15472”, “0?Macro(vm,100,DIRECTDIAL,)”) in new stack
[2022-01-11 14:18:36] WARNING[16016][C-00000003]: chan_sip.c:23022 func_header_read: This function can only be used on SIP channels.
[2022-01-11 14:18:36] WARNING[16016][C-00000003]: chan_sip.c:23022 func_header_read: This function can only be used on SIP channels.
– Executing [s@macro-exten-vm:19] ExecIf(“IAX2/SECOND-SERVER-15472”, “0?MacroExit()”) in new stack
[2022-01-11 14:18:36] WARNING[16016][C-00000003]: chan_sip.c:23022 func_header_read: This function can only be used on SIP channels.
[2022-01-11 14:18:36] ERROR[16016][C-00000003]: res_pjsip_header_funcs.c:461 func_read_header: This function requires a PJSIP channel.
– Executing [s@macro-exten-vm:20] ExecIf(“IAX2/SECOND-SERVER-15472”, “0?Macro(vm,100,DIRECTDIAL,)”) in new stack
[2022-01-11 14:18:36] ERROR[16016][C-00000003]: res_pjsip_header_funcs.c:461 func_read_header: This function requires a PJSIP channel.
[2022-01-11 14:18:36] ERROR[16016][C-00000003]: res_pjsip_header_funcs.c:461 func_read_header: This function requires a PJSIP channel.
– Executing [s@macro-exten-vm:21] ExecIf(“IAX2/SECOND-SERVER-15472”, “0?MacroExit()”) in new stack
[2022-01-11 14:18:36] ERROR[16016][C-00000003]: res_pjsip_header_funcs.c:461 func_read_header: This function requires a PJSIP channel.
– Executing [s@macro-exten-vm:22] Gosub(“IAX2/SECOND-SERVER-15472”, “sub-record-check,s,1(exten,100,dontcare)”) in new stack
– Executing [s@sub-record-check:1] GotoIf(“IAX2/SECOND-SERVER-15472”, “0?initialized”) in new stack
– Executing [s@sub-record-check:2] Set(“IAX2/SECOND-SERVER-15472”, “__REC_STATUS=INITIALIZED”) in new stack
– Executing [s@sub-record-check:3] Set(“IAX2/SECOND-SERVER-15472”, “NOW=1641907116”) in new stack
– Executing [s@sub-record-check:4] Set(“IAX2/SECOND-SERVER-15472”, “__DAY=11”) in new stack
– Executing [s@sub-record-check:5] Set(“IAX2/SECOND-SERVER-15472”, “__MONTH=01”) in new stack
– Executing [s@sub-record-check:6] Set(“IAX2/SECOND-SERVER-15472”, “__YEAR=2022”) in new stack
– Executing [s@sub-record-check:7] Set(“IAX2/SECOND-SERVER-15472”, “__TIMESTR=20220111-141836”) in new stack
– Executing [s@sub-record-check:8] Set(“IAX2/SECOND-SERVER-15472”, “__FROMEXTEN=503”) in new stack
– Executing [s@sub-record-check:9] Set(“IAX2/SECOND-SERVER-15472”, “__MON_FMT=wav”) in new stack
– Executing [s@sub-record-check:10] NoOp(“IAX2/SECOND-SERVER-15472”, “Recordings initialized”) in new stack
– Executing [s@sub-record-check:11] ExecIf(“IAX2/SECOND-SERVER-15472”, “0?Set(ARG3=dontcare)”) in new stack
– Executing [s@sub-record-check:12] Set(“IAX2/SECOND-SERVER-15472”, “REC_POLICY_MODE_SAVE=”) in new stack
– Executing [s@sub-record-check:13] ExecIf(“IAX2/SECOND-SERVER-15472”, “0?Set(REC_STATUS=NO)”) in new stack
– Executing [s@sub-record-check:14] GotoIf(“IAX2/SECOND-SERVER-15472”, “5?checkaction”) in new stack
– Goto (sub-record-check,s,17)
– Executing [s@sub-record-check:17] GotoIf(“IAX2/SECOND-SERVER-15472”, “1?sub-record-check,exten,1”) in new stack
– Goto (sub-record-check,exten,1)
– Executing [exten@sub-record-check:1] NoOp(“IAX2/SECOND-SERVER-15472”, “Exten Recording Check between 503 and 100”) in new stack
– Executing [exten@sub-record-check:2] Set(“IAX2/SECOND-SERVER-15472”, “CALLTYPE=internal”) in new stack
– Executing [exten@sub-record-check:3] ExecIf(“IAX2/SECOND-SERVER-15472”, “0?Set(CALLTYPE=)”) in new stack
– Executing [exten@sub-record-check:4] Set(“IAX2/SECOND-SERVER-15472”, “CALLEE=dontcare”) in new stack
– Executing [exten@sub-record-check:5] ExecIf(“IAX2/SECOND-SERVER-15472”, “0?Set(CALLEE=dontcare)”) in new stack
– Executing [exten@sub-record-check:6] GotoIf(“IAX2/SECOND-SERVER-15472”, “0?callee”) in new stack
– Executing [exten@sub-record-check:7] GotoIf(“IAX2/SECOND-SERVER-15472”, “1?caller”) in new stack
– Goto (sub-record-check,exten,13)
– Executing [exten@sub-record-check:13] Set(“IAX2/SECOND-SERVER-15472”, “RECMODE=”) in new stack
– Executing [exten@sub-record-check:14] Set(“IAX2/SECOND-SERVER-15472”, “CALLERRECMODE=”) in new stack
– Executing [exten@sub-record-check:15] Set(“IAX2/SECOND-SERVER-15472”, “CALEERECMODE=dontcare”) in new stack
– Executing [exten@sub-record-check:16] GotoIf(“IAX2/SECOND-SERVER-15472”, “0?processnormal”) in new stack
– Executing [exten@sub-record-check:17] ExecIf(“IAX2/SECOND-SERVER-15472”, “0?Set(RECMODE=dontcare)”) in new stack
– Executing [exten@sub-record-check:18] ExecIf(“IAX2/SECOND-SERVER-15472”, “0?Set(RECMODE=dontcare)”) in new stack
– Executing [exten@sub-record-check:19] ExecIf(“IAX2/SECOND-SERVER-15472”, “0?Set(RECMODE=dontcare)”) in new stack
– Executing [exten@sub-record-check:20] ExecIf(“IAX2/SECOND-SERVER-15472”, “0?Set(RECMODE=dontcare)”) in new stack
– Executing [exten@sub-record-check:21] ExecIf(“IAX2/SECOND-SERVER-15472”, “1?Set(RECMODE=dontcare)”) in new stack
– Executing [exten@sub-record-check:22] ExecIf(“IAX2/SECOND-SERVER-15472”, “1?Set(RECMODE=dontcare)”) in new stack
– Executing [exten@sub-record-check:23] Gosub(“IAX2/SECOND-SERVER-15472”, “recordcheck,1(dontcare,internal,100)”) in new stack
– Executing [recordcheck@sub-record-check:1] NoOp(“IAX2/SECOND-SERVER-15472”, “Starting recording check against dontcare”) in new stack
– Executing [recordcheck@sub-record-check:2] Goto(“IAX2/SECOND-SERVER-15472”, “dontcare”) in new stack
– Goto (sub-record-check,recordcheck,3)
– Executing [recordcheck@sub-record-check:3] Return(“IAX2/SECOND-SERVER-15472”, “”) in new stack
– Executing [exten@sub-record-check:24] Return(“IAX2/SECOND-SERVER-15472”, “”) in new stack
– Executing [s@macro-exten-vm:23] GotoIf(“IAX2/SECOND-SERVER-15472”, “1?macrodial”) in new stack
– Goto (macro-exten-vm,s,29)
– Executing [s@macro-exten-vm:29] GosubIf(“IAX2/SECOND-SERVER-15472”, “0?clrheader,1()”) in new stack
– Executing [s@macro-exten-vm:30] Macro(“IAX2/SECOND-SERVER-15472”, “dial-one,15,HhTtr,100”) in new stack
– Executing [s@macro-dial-one:1] Set(“IAX2/SECOND-SERVER-15472”, “DEXTEN=100”) in new stack
– Executing [s@macro-dial-one:2] ExecIf(“IAX2/SECOND-SERVER-15472”, “0?Set(__EXTTOCALL=100)”) in new stack
– Executing [s@macro-dial-one:3] Set(“IAX2/SECOND-SERVER-15472”, “DIALSTATUS_CW=”) in new stack
– Executing [s@macro-dial-one:4] GosubIf(“IAX2/SECOND-SERVER-15472”, “0?screen,1()”) in new stack
– Executing [s@macro-dial-one:5] GosubIf(“IAX2/SECOND-SERVER-15472”, “0?cf,1()”) in new stack
– Executing [s@macro-dial-one:6] GotoIf(“IAX2/SECOND-SERVER-15472”, “1?skip1”) in new stack
– Goto (macro-dial-one,s,9)
– Executing [s@macro-dial-one:9] GotoIf(“IAX2/SECOND-SERVER-15472”, “0?nodial”) in new stack
– Executing [s@macro-dial-one:10] GotoIf(“IAX2/SECOND-SERVER-15472”, “0?continue”) in new stack
– Executing [s@macro-dial-one:11] Set(“IAX2/SECOND-SERVER-15472”, “EXTHASCW=ENABLED”) in new stack
– Executing [s@macro-dial-one:12] GotoIf(“IAX2/SECOND-SERVER-15472”, “0?next1:cwinusebusy”) in new stack
– Goto (macro-dial-one,s,24)
– Executing [s@macro-dial-one:24] GotoIf(“IAX2/SECOND-SERVER-15472”, “0?next3:continue”) in new stack
– Goto (macro-dial-one,s,26)
– Executing [s@macro-dial-one:26] GotoIf(“IAX2/SECOND-SERVER-15472”, “0?nodial”) in new stack
– Executing [s@macro-dial-one:27] GosubIf(“IAX2/SECOND-SERVER-15472”, “1?dstring,1():dlocal,1()”) in new stack
– Executing [dstring@macro-dial-one:1] Set(“IAX2/SECOND-SERVER-15472”, “DSTRING=”) in new stack
– Executing [dstring@macro-dial-one:2] Set(“IAX2/SECOND-SERVER-15472”, “DEVICES=100”) in new stack
– Executing [dstring@macro-dial-one:3] ExecIf(“IAX2/SECOND-SERVER-15472”, “0?Return()”) in new stack
– Executing [dstring@macro-dial-one:4] ExecIf(“IAX2/SECOND-SERVER-15472”, “0?Set(DEVICES=00)”) in new stack
– Executing [dstring@macro-dial-one:5] Set(“IAX2/SECOND-SERVER-15472”, “LOOPCNT=1”) in new stack
– Executing [dstring@macro-dial-one:6] Set(“IAX2/SECOND-SERVER-15472”, “ITER=1”) in new stack
– Executing [dstring@macro-dial-one:7] Set(“IAX2/SECOND-SERVER-15472”, “THISDIAL=SIP/100”) in new stack
– Executing [dstring@macro-dial-one:8] GotoIf(“IAX2/SECOND-SERVER-15472”, “1?docheck”) in new stack
– Goto (macro-dial-one,dstring,14)
– Executing [dstring@macro-dial-one:14] GotoIf(“IAX2/SECOND-SERVER-15472”, “0?skipset”) in new stack
– Executing [dstring@macro-dial-one:15] Set(“IAX2/SECOND-SERVER-15472”, “DSTRING=SIP/100&”) in new stack
– Executing [dstring@macro-dial-one:16] Set(“IAX2/SECOND-SERVER-15472”, “ITER=2”) in new stack
– Executing [dstring@macro-dial-one:17] GotoIf(“IAX2/SECOND-SERVER-15472”, “0?begin”) in new stack
– Executing [dstring@macro-dial-one:18] ExecIf(“IAX2/SECOND-SERVER-15472”, “0?Return()”) in new stack
– Executing [dstring@macro-dial-one:19] Set(“IAX2/SECOND-SERVER-15472”, “DSTRING=SIP/100”) in new stack
– Executing [dstring@macro-dial-one:20] Return(“IAX2/SECOND-SERVER-15472”, “”) in new stack
– Executing [s@macro-dial-one:28] GotoIf(“IAX2/SECOND-SERVER-15472”, “0?nodial”) in new stack
– Executing [s@macro-dial-one:29] GotoIf(“IAX2/SECOND-SERVER-15472”, “0?skiptrace”) in new stack
– Executing [s@macro-dial-one:30] GosubIf(“IAX2/SECOND-SERVER-15472”, “1?ctset,1():ctclear,1()”) in new stack
– Executing [ctset@macro-dial-one:1] Set(“IAX2/SECOND-SERVER-15472”, “DB(CALLTRACE/100)=503”) in new stack
– Executing [ctset@macro-dial-one:2] Return(“IAX2/SECOND-SERVER-15472”, “”) in new stack
– Executing [s@macro-dial-one:31] Set(“IAX2/SECOND-SERVER-15472”, “D_OPTIONS=HhTtr”) in new stack
– Executing [s@macro-dial-one:32] GosubIf(“IAX2/SECOND-SERVER-15472”, “0?func-set-sipheader,s,1(Alert-Info,)”) in new stack
– Executing [s@macro-dial-one:33] NoOp(“IAX2/SECOND-SERVER-15472”, "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
– Executing [s@macro-dial-one:34] ExecIf(“IAX2/SECOND-SERVER-15472”, “0?Set(ALERT_INFO=)”) in new stack
– Executing [s@macro-dial-one:35] ExecIf(“IAX2/SECOND-SERVER-15472”, “0?Set(ALERT_INFO=)”) in new stack
– Executing [s@macro-dial-one:36] ExecIf(“IAX2/SECOND-SERVER-15472”, “0?Set(ALERT_INFO=)”) in new stack
– Executing [s@macro-dial-one:37] ExecIf(“IAX2/SECOND-SERVER-15472”, “0?Set(ALERT_INFO=Normal;volume=)”) in new stack
– Executing [s@macro-dial-one:38] ExecIf(“IAX2/SECOND-SERVER-15472”, “0?Set(ALERT_INFO=Normal;volume=)”) in new stack
– Executing [s@macro-dial-one:39] GosubIf(“IAX2/SECOND-SERVER-15472”, “0?func-set-sipheader,s,1(Alert-Info,)”) in new stack
– Executing [s@macro-dial-one:40] ExecIf(“IAX2/SECOND-SERVER-15472”, “0?Set(CHANNEL(musicclass)=)”) in new stack
– Executing [s@macro-dial-one:41] GosubIf(“IAX2/SECOND-SERVER-15472”, “0?qwait,1()”) in new stack
– Executing [s@macro-dial-one:42] Set(“IAX2/SECOND-SERVER-15472”, “__CWIGNORE=”) in new stack
– Executing [s@macro-dial-one:43] Set(“IAX2/SECOND-SERVER-15472”, “__KEEPCID=TRUE”) in new stack
– Executing [s@macro-dial-one:44] GotoIf(“IAX2/SECOND-SERVER-15472”, “0?usegoto,1”) in new stack
– Executing [s@macro-dial-one:45] GotoIf(“IAX2/SECOND-SERVER-15472”, “1?godial”) in new stack
– Goto (macro-dial-one,s,50)
– Executing [s@macro-dial-one:50] Macro(“IAX2/SECOND-SERVER-15472”, “dialout-one-predial-hook,”) in new stack
– Executing [s@macro-dialout-one-predial-hook:1] MacroExit(“IAX2/SECOND-SERVER-15472”, “”) in new stack
– Executing [s@macro-dial-one:51] ExecIf(“IAX2/SECOND-SERVER-15472”, “0?Set(D_OPTIONS=HhtrI)”) in new stack
– Executing [s@macro-dial-one:52] ExecIf(“IAX2/SECOND-SERVER-15472”, “0?Set(CWRING=r(callwaiting)):Set(CWRING=)”) in new stack
– Executing [s@macro-dial-one:53] NoOp(“IAX2/SECOND-SERVER-15472”, “”) in new stack
– Executing [s@macro-dial-one:54] ExecIf(“IAX2/SECOND-SERVER-15472”, “0?Set(D_OPTIONS=HhTtrg)”) in new stack
– Executing [s@macro-dial-one:55] Dial(“IAX2/SECOND-SERVER-15472”, “SIP/100,15,HhTtrb(func-apply-sipheaders^s^1)”) in new stack
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– SIP/100-00000003 Internal Gosub(func-apply-sipheaders,s,1) start
– Executing [s@func-apply-sipheaders:1] NoOp(“SIP/100-00000003”, “Applying SIP Headers to channel SIP/100-00000003”) in new stack
– Executing [s@func-apply-sipheaders:2] Set(“SIP/100-00000003”, “TECH=SIP”) in new stack
– Executing [s@func-apply-sipheaders:3] Set(“SIP/100-00000003”, “SIPHEADERKEYS=”) in new stack
– Executing [s@func-apply-sipheaders:4] While(“SIP/100-00000003”, “0”) in new stack
[2022-01-11 14:18:36] WARNING[16016][C-00000003]: taskprocessor.c:888 taskprocessor_push: The ‘subm:endpoint_topic_all-cached-00000008’ task processor queue reached 500 scheduled tasks again.
[2022-01-11 14:18:36] WARNING[16016][C-00000003]: taskprocessor.c:888 taskprocessor_push: The ‘subm:endpoint_topic_all-cached-00000092’ task processor queue reached 500 scheduled tasks again.
– Jumping to priority 12
– Executing [s@func-apply-sipheaders:13] Return(“SIP/100-00000003”, “”) in new stack
[2022-01-11 14:18:36] WARNING[16016][C-00000003]: taskprocessor.c:888 taskprocessor_push: The ‘subm:ast_channel_topic_all-cached-0000008b’ task processor queue reached 500 scheduled tasks again.
[2022-01-11 14:18:36] WARNING[16016][C-00000003]: taskprocessor.c:888 taskprocessor_push: The ‘subm:ast_channel_topic_all-cached-0000008c’ task processor queue reached 500 scheduled tasks again.
[2022-01-11 14:18:36] WARNING[16016][C-00000003]: taskprocessor.c:888 taskprocessor_push: The ‘subm:ast_channel_topic_all-cached-0000008d’ task processor queue reached 500 scheduled tasks again.
== Spawn extension (from-internal, 100, 1) exited non-zero on ‘SIP/100-00000003’
– SIP/100-00000003 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
– Called SIP/100
Tx-Frame Retry[000] – OSeqno: 002 ISeqno: 002 Type: CONTROL Subclass: RINGING
Timestamp: 00133ms SCall: 15472 DCall: 01394 10.X.X.X:4569
Rx-Frame Retry[ No] – OSeqno: 002 ISeqno: 002 Type: IAX Subclass: ACK
Timestamp: 00130ms SCall: 01394 DCall: 15472 10.X.X.X:4569
Rx-Frame Retry[ No] – OSeqno: 002 ISeqno: 003 Type: IAX Subclass: ACK
Timestamp: 00133ms SCall: 01394 DCall: 15472 10.X.X.X:4569
– SIP/100-00000003 is ringing
Tx-Frame Retry[000] – OSeqno: 003 ISeqno: 002 Type: CONTROL Subclass: RINGING
Timestamp: 01062ms SCall: 15472 DCall: 01394 10.X.X.X:4569
Rx-Frame Retry[ No] – OSeqno: 002 ISeqno: 004 Type: IAX Subclass: ACK
Timestamp: 01062ms SCall: 01394 DCall: 15472 10.X.X.X:4569
– SIP/100-00000003 answered IAX2/SECOND-SERVER-15472
Tx-Frame Retry[000] – OSeqno: 004 ISeqno: 002 Type: CONTROL Subclass: (255?)
Timestamp: 08336ms SCall: 15472 DCall: 01394 10.X.X.X:4569
Tx-Frame Retry[000] – OSeqno: 005 ISeqno: 002 Type: CONTROL Subclass: ANSWER
Timestamp: 08339ms SCall: 15472 DCall: 01394 10.X.X.X:4569
Tx-Frame Retry[000] – OSeqno: 006 ISeqno: 002 Type: CONTROL Subclass: (255?)
Timestamp: 08342ms SCall: 15472 DCall: 01394 10.X.X.X:4569
– Channel SIP/100-00000003 joined ‘simple_bridge’ basic-bridge <1d04c5c9-0c77-492f-b0de-f92bd128c4b6>
– Channel IAX2/SECOND-SERVER-15472 joined ‘simple_bridge’ basic-bridge <1d04c5c9-0c77-492f-b0de-f92bd128c4b6>
[2022-01-11 14:18:44] WARNING[16031][C-00000003]: chan_iax2.c:1230 jb_warning_output: Resyncing the jb. last_delay 0, this delay -44644706, threshold 1000, new offset 44644706
Tx-Frame Retry[000] – OSeqno: 007 ISeqno: 002 Type: VOICE Subclass: 8
Timestamp: 08425ms SCall: 15472 DCall: 01394 10.X.X.X:4569
Rx-Frame Retry[ No] – OSeqno: 002 ISeqno: 005 Type: IAX Subclass: ACK
Timestamp: 08336ms SCall: 01394 DCall: 15472 10.X.X.X:4569
Rx-Frame Retry[ No] – OSeqno: 002 ISeqno: 007 Type: IAX Subclass: ACK
Timestamp: 08342ms SCall: 01394 DCall: 15472 10.X.X.X:4569
Rx-Frame Retry[ No] – OSeqno: 002 ISeqno: 006 Type: IAX Subclass: ACK
Timestamp: 08339ms SCall: 01394 DCall: 15472 10.X.X.X:4569
Rx-Frame Retry[ No] – OSeqno: 007 ISeqno: 007 Type: IAX Subclass: VNAK
Timestamp: 08697ms SCall: 01394 DCall: 15472 10.X.X.X:4569
Tx-Frame Retry[001] – OSeqno: 007 ISeqno: 002 Type: VOICE Subclass: 8
Timestamp: 08425ms SCall: 15472 DCall: 01394 10.X.X.X:4569
Rx-Frame Retry[ No] – OSeqno: 007 ISeqno: 007 Type: IAX Subclass: VNAK
Timestamp: 08694ms SCall: 01394 DCall: 15472 10.X.X.X:4569
Tx-Frame Retry[001] – OSeqno: 007 ISeqno: 002 Type: VOICE Subclass: 8
Timestamp: 08425ms SCall: 15472 DCall: 01394 10.X.X.X:4569
Rx-Frame Retry[ No] – OSeqno: 002 ISeqno: 008 Type: IAX Subclass: ACK
Timestamp: 08425ms SCall: 01394 DCall: 15472 10.X.X.X:4569
Rx-Frame Retry[ No] – OSeqno: 002 ISeqno: 008 Type: IAX Subclass: ACK
Timestamp: 08425ms SCall: 01394 DCall: 15472 10.X.X.X:4569
Rx-Frame Retry[ No] – OSeqno: 002 ISeqno: 008 Type: IAX Subclass: ACK
Timestamp: 08425ms SCall: 01394 DCall: 15472 10.X.X.X:4569
Rx-Frame Retry[ No] – OSeqno: 002 ISeqno: 008 Type: IAX Subclass: LAGRQ
Timestamp: 09982ms SCall: 01394 DCall: 15472 10.X.X.X:4569
Tx-Frame Retry[000] – OSeqno: 008 ISeqno: 003 Type: IAX Subclass: LAGRP
Timestamp: 09982ms SCall: 15472 DCall: 01394 10.X.X.X:4569
Rx-Frame Retry[ No] – OSeqno: 003 ISeqno: 009 Type: IAX Subclass: ACK
Timestamp: 09982ms SCall: 01394 DCall: 15472 10.X.X.X:4569
Tx-Frame Retry[000] – OSeqno: 009 ISeqno: 003 Type: IAX Subclass: LAGRQ
Timestamp: 09999ms SCall: 15472 DCall: 01394 10.X.X.X:4569
Rx-Frame Retry[ No] – OSeqno: 003 ISeqno: 010 Type: IAX Subclass: LAGRP
Timestamp: 09999ms SCall: 01394 DCall: 15472 10.X.X.X:4569
Tx-Frame Retry[-01] – OSeqno: 010 ISeqno: 004 Type: IAX Subclass: ACK
Timestamp: 09999ms SCall: 15472 DCall: 01394 10.X.X.X:4569
Rx-Frame Retry[ No] – OSeqno: 004 ISeqno: 010 Type: IAX Subclass: LAGRQ
Timestamp: 19982ms SCall: 01394 DCall: 15472 10.X.X.X:4569
Tx-Frame Retry[000] – OSeqno: 010 ISeqno: 005 Type: IAX Subclass: LAGRP
Timestamp: 19982ms SCall: 15472 DCall: 01394 10.X.X.X:4569
Rx-Frame Retry[ No] – OSeqno: 005 ISeqno: 011 Type: IAX Subclass: ACK
Timestamp: 19982ms SCall: 01394 DCall: 15472 10.X.X.X:4569
Tx-Frame Retry[000] – OSeqno: 011 ISeqno: 005 Type: IAX Subclass: LAGRQ
Timestamp: 19999ms SCall: 15472 DCall: 01394 10.X.X.X:4569
Rx-Frame Retry[ No] – OSeqno: 005 ISeqno: 012 Type: IAX Subclass: LAGRP
Timestamp: 19999ms SCall: 01394 DCall: 15472 10.X.X.X:4569
Tx-Frame Retry[-01] – OSeqno: 012 ISeqno: 006 Type: IAX Subclass: ACK
Timestamp: 19999ms SCall: 15472 DCall: 01394 10.X.X.X:4569
Rx-Frame Retry[ No] – OSeqno: 006 ISeqno: 012 Type: IAX Subclass: HANGUP
Timestamp: 20880ms SCall: 01394 DCall: 15472 10.X.X.X:4569
CAUSE CODE : 0

bobandroid24 · January 11, 2022, 1:32pm

Tx-Frame Retry[-01] – OSeqno: 012 ISeqno: 007 Type: IAX Subclass: ACK
Timestamp: 20880ms SCall: 15472 DCall: 01394 10.X.X.X:4569
– Channel IAX2/SECOND-SERVER-15472 left ‘simple_bridge’ basic-bridge <1d04c5c9-0c77-492f-b0de-f92bd128c4b6>
== Spawn extension (macro-dial-one, s, 55) exited non-zero on ‘IAX2/SECOND-SERVER-15472’ in macro ‘dial-one’
== Spawn extension (macro-exten-vm, s, 30) exited non-zero on ‘IAX2/SECOND-SERVER-15472’ in macro ‘exten-vm’
== Spawn extension (ext-local, 100, 3) exited non-zero on ‘IAX2/SECOND-SERVER-15472’
– Executing [h@ext-local:1] Macro(“IAX2/SECOND-SERVER-15472”, “hangupcall,”) in new stack
– Executing [s@macro-hangupcall:1] GotoIf(“IAX2/SECOND-SERVER-15472”, “1?theend”) in new stack
– Goto (macro-hangupcall,s,3)
– Executing [s@macro-hangupcall:3] ExecIf(“IAX2/SECOND-SERVER-15472”, “0?Set(CDR(recordingfile)=)”) in new stack
– Executing [s@macro-hangupcall:4] NoOp(“IAX2/SECOND-SERVER-15472”, "SIP/100-00000003 montior file= ") in new stack
– Executing [s@macro-hangupcall:5] GotoIf(“IAX2/SECOND-SERVER-15472”, “1?skipagi”) in new stack
– Goto (macro-hangupcall,s,7)
– Executing [s@macro-hangupcall:7] Hangup(“IAX2/SECOND-SERVER-15472”, “”) in new stack
== Spawn extension (macro-hangupcall, s, 7) exited non-zero on ‘IAX2/SECOND-SERVER-15472’ in macro ‘hangupcall’
== Spawn extension (ext-local, h, 1) exited non-zero on ‘IAX2/SECOND-SERVER-15472’
– Hungup ‘IAX2/SECOND-SERVER-15472’
– Channel SIP/100-00000003 left ‘simple_bridge’ basic-bridge <1d04c5c9-0c77-492f-b0de-f92bd128c4b6>
freepbxnlCLI> iax2 set debug off
IAX2 Debugging Disabled
freepbxnlCLI>

This is what I get from the other side,

jgttgns · January 11, 2022, 5:33pm

As written before, you need to figure out what is going on on the other side. The calling side gets messages like “No one is available to answer at this time.” and that indicates that the problem could be the called side.

bobandroid24 · January 11, 2022, 5:37pm

The called side has other trunks working without problem.

On the other hand, if I use a SIP trunk and allow invite is YES, it works!

But if Allow invite is NO, I can’t receive incoming calls even to my DID

bobandroid24 · January 11, 2022, 5:39pm

Allow guests “YES”, hacked my pbx and made a lot of calls. How can I block those attacks?

dicko · January 11, 2022, 6:24pm

Don’t use UDP/5060 .

bobandroid24 · January 11, 2022, 6:36pm

What else can I do and how exactly can I switch to another port without problems?

I don’t really want to allow guests, but I can’t get calls. Any suggestion?

dicko · January 11, 2022, 8:08pm

In 2022, I would suggest you use chan_pjsip not chan_sip nor IAX2 for your ‘tie-trunks’

Bind chan_pjsip to something outside 5000-5999 and have your extensions use that port. Better to use TCP or better yet use TLS for your extensions’ transport.

These Fora, the wiki and google will provide you the recipes if you look hard enough.

jgttgns · January 12, 2022, 7:33am

The called side has other trunks working without problem.

You haven’t shown us what is going on on the other side. You cannot deduce anything from other trunks. It could still be a basic configuration problem or a simple typo that prevents things from happening. We simply do not know.

bobandroid24 · January 12, 2022, 12:29pm

First side *, the call stays in Calling and reaches the other end. Once it is accepted, it changes to the Ringing state and at the other end the open call is reflected without receiving anything.

bobandroid24 · January 12, 2022, 12:34pm

Segundo lado *, esto es lo que se observa cuando la llamada llega y es aceptada.