Remote Client behind Cisco 2911

I’m using Asterisk Version: 13.9.1

Same ip phone (CHAN_SIP) works behind different routers but having difficulty behind Cisco 2911
At first it will connect for one minute then it will unregistered.
Even it was unregistered, I still can make outgoing calls but not incoming.
I turned off SIP/ALG and created and an access-list on Cisco 2911 that opens UDP 5060/5061 and ports 10000 to 20000. At this point the extension stays registered. I can make outgoing calls but still no incoming,
For incoming, I sometime hear a short ring followed by busy signal.

I appreciate if someone can look at my debug output and give me some ideas on what to check next.
This debug is for ext 102 calling 5005 both in different network. Ext 5005 is behind the Cisco 2911
No problem with ext 102

– Executing [5005@from-internal:1] GotoIf(“PJSIP/102-000000dc”, “0?ext-local,5005,1:followme-check,5005,1”) in new stack
– Goto (followme-check,5005,1)
– Executing [5005@followme-check:1] Gosub(“PJSIP/102-000000dc”, “followme-sub,5005,1()”) in new stack
– Executing [5005@followme-sub:1] Macro(“PJSIP/102-000000dc”, “user-callerid,”) in new stack
– Executing [s@macro-user-callerid:1] Set(“PJSIP/102-000000dc”, “TOUCH_MONITOR=1484644274.994”) in new stack
– Executing [s@macro-user-callerid:2] Set(“PJSIP/102-000000dc”, “AMPUSER=102”) in new stack
– Executing [s@macro-user-callerid:3] GotoIf(“PJSIP/102-000000dc”, “0?report”) in new stack
– Executing [s@macro-user-callerid:4] ExecIf(“PJSIP/102-000000dc”, “1?Set(REALCALLERIDNUM=102)”) in new stack
– Executing [s@macro-user-callerid:5] Set(“PJSIP/102-000000dc”, “AMPUSER=102”) in new stack
– Executing [s@macro-user-callerid:6] GotoIf(“PJSIP/102-000000dc”, “0?limit”) in new stack
– Executing [s@macro-user-callerid:7] Set(“PJSIP/102-000000dc”, “AMPUSERCIDNAME=Demo 102”) in new stack
– Executing [s@macro-user-callerid:8] GotoIf(“PJSIP/102-000000dc”, “0?report”) in new stack
– Executing [s@macro-user-callerid:9] Set(“PJSIP/102-000000dc”, “AMPUSERCID=102”) in new stack
– Executing [s@macro-user-callerid:10] Set(“PJSIP/102-000000dc”, “__DIAL_OPTIONS=Ttr”) in new stack
– Executing [s@macro-user-callerid:11] Set(“PJSIP/102-000000dc”, “CALLERID(all)=“Demo 102” <102>”) in new stack
– Executing [s@macro-user-callerid:12] GotoIf(“PJSIP/102-000000dc”, “0?limit”) in new stack
– Executing [s@macro-user-callerid:13] ExecIf(“PJSIP/102-000000dc”, “0?Set(GROUP(concurrency_limit)=102)”) in new stack
– Executing [s@macro-user-callerid:14] ExecIf(“PJSIP/102-000000dc”, “0?Set(CHANNEL(language)=)”) in new stack
– Executing [s@macro-user-callerid:15] GotoIf(“PJSIP/102-000000dc”, “0?continue”) in new stack
– Executing [s@macro-user-callerid:16] ExecIf(“PJSIP/102-000000dc”, “1?Set(__CALLEE_ACCOUNCODE=)”) in new stack
– Executing [s@macro-user-callerid:17] Set(“PJSIP/102-000000dc”, “__TTL=64”) in new stack
– Executing [s@macro-user-callerid:18] GotoIf(“PJSIP/102-000000dc”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,29)
– Executing [s@macro-user-callerid:29] Set(“PJSIP/102-000000dc”, “CALLERID(number)=102”) in new stack
– Executing [s@macro-user-callerid:30] Set(“PJSIP/102-000000dc”, “CALLERID(name)=Demo 102”) in new stack
– Executing [s@macro-user-callerid:31] GotoIf(“PJSIP/102-000000dc”, “0?cnum”) in new stack
– Executing [s@macro-user-callerid:32] Set(“PJSIP/102-000000dc”, “CDR(cnam)=Demo 102”) in new stack
– Executing [s@macro-user-callerid:33] Set(“PJSIP/102-000000dc”, “CDR(cnum)=102”) in new stack
– Executing [s@macro-user-callerid:34] Set(“PJSIP/102-000000dc”, “CHANNEL(language)=en”) in new stack
– Executing [5005@followme-sub:2] Set(“PJSIP/102-000000dc”, “DIAL_OPTIONS=TtrI”) in new stack
– Executing [5005@followme-sub:3] Set(“PJSIP/102-000000dc”, “CONNECTEDLINE(num,i)=5005”) in new stack
– Executing [5005@followme-sub:4] Gosub(“PJSIP/102-000000dc”, “sub-presencestate-display,s,1(5005)”) in new stack
– Executing [s@sub-presencestate-display:1] Goto(“PJSIP/102-000000dc”, “state-not_set,1”) in new stack
– Goto (sub-presencestate-display,state-not_set,1)
– Executing [state-not_set@sub-presencestate-display:1] Set(“PJSIP/102-000000dc”, “PRESENCESTATE_DISPLAY=”) in new stack
– Executing [state-not_set@sub-presencestate-display:2] Return(“PJSIP/102-000000dc”, “”) in new stack
– Executing [5005@followme-sub:5] Set(“PJSIP/102-000000dc”, “CONNECTEDLINE(name)=5005”) in new stack
– Executing [5005@followme-sub:6] Set(“PJSIP/102-000000dc”, “FM_DIALSTATUS=NOT_INUSE”) in new stack
– Executing [5005@followme-sub:7] Set(“PJSIP/102-000000dc”, “__EXTTOCALL=5005”) in new stack
– Executing [5005@followme-sub:8] Set(“PJSIP/102-000000dc”, “__PICKUPMARK=5005”) in new stack
– Executing [5005@followme-sub:9] Macro(“PJSIP/102-000000dc”, “blkvm-setifempty,”) in new stack
– Executing [s@macro-blkvm-setifempty:1] GotoIf(“PJSIP/102-000000dc”, “1?init”) in new stack
– Goto (macro-blkvm-setifempty,s,4)
– Executing [s@macro-blkvm-setifempty:4] Set(“PJSIP/102-000000dc”, “__BLKVM_CHANNEL=PJSIP/102-000000dc”) in new stack
– Executing [s@macro-blkvm-setifempty:5] Set(“PJSIP/102-000000dc”, “SHARED(BLKVM,PJSIP/102-000000dc)=TRUE”) in new stack
– Executing [s@macro-blkvm-setifempty:6] Set(“PJSIP/102-000000dc”, “GOSUB_RETVAL=TRUE”) in new stack
– Executing [s@macro-blkvm-setifempty:7] MacroExit(“PJSIP/102-000000dc”, “”) in new stack
– Executing [5005@followme-sub:10] GotoIf(“PJSIP/102-000000dc”, “1?skipov”) in new stack
– Goto (followme-sub,5005,13)
– Executing [5005@followme-sub:13] Set(“PJSIP/102-000000dc”, “RRNODEST=”) in new stack
– Executing [5005@followme-sub:14] Set(“PJSIP/102-000000dc”, “__NODEST=5005”) in new stack
– Executing [5005@followme-sub:15] GosubIf(“PJSIP/102-000000dc”, “0?sub-fmsetcid,s,1()”) in new stack
– Executing [5005@followme-sub:16] GotoIf(“PJSIP/102-000000dc”, “1?skipprepend”) in new stack
– Goto (followme-sub,5005,18)
– Executing [5005@followme-sub:18] Set(“PJSIP/102-000000dc”, “RecordMethod=Group”) in new stack
– Executing [5005@followme-sub:19] Gosub(“PJSIP/102-000000dc”, “sub-record-check,s,1(exten,5005,)”) in new stack
– Executing [s@sub-record-check:1] GotoIf(“PJSIP/102-000000dc”, “0?initialized”) in new stack
– Executing [s@sub-record-check:2] Set(“PJSIP/102-000000dc”, “__REC_STATUS=INITIALIZED”) in new stack
– Executing [s@sub-record-check:3] Set(“PJSIP/102-000000dc”, “NOW=1484644274”) in new stack
– Executing [s@sub-record-check:4] Set(“PJSIP/102-000000dc”, “__DAY=17”) in new stack
– Executing [s@sub-record-check:5] Set(“PJSIP/102-000000dc”, “__MONTH=01”) in new stack
– Executing [s@sub-record-check:6] Set(“PJSIP/102-000000dc”, “__YEAR=2017”) in new stack
– Executing [s@sub-record-check:7] Set(“PJSIP/102-000000dc”, “__TIMESTR=20170117-011114”) in new stack
– Executing [s@sub-record-check:8] Set(“PJSIP/102-000000dc”, “__FROMEXTEN=102”) in new stack
– Executing [s@sub-record-check:9] Set(“PJSIP/102-000000dc”, “__MON_FMT=wav”) in new stack
– Executing [s@sub-record-check:10] NoOp(“PJSIP/102-000000dc”, “Recordings initialized”) in new stack
– Executing [s@sub-record-check:11] ExecIf(“PJSIP/102-000000dc”, “1?Set(ARG3=dontcare)”) in new stack
– Executing [s@sub-record-check:12] Set(“PJSIP/102-000000dc”, “REC_POLICY_MODE_SAVE=”) in new stack
– Executing [s@sub-record-check:13] ExecIf(“PJSIP/102-000000dc”, “0?Set(REC_STATUS=NO)”) in new stack
– Executing [s@sub-record-check:14] GotoIf(“PJSIP/102-000000dc”, “5?checkaction”) in new stack
– Goto (sub-record-check,s,17)
– Executing [s@sub-record-check:17] GotoIf(“PJSIP/102-000000dc”, “1?sub-record-check,exten,1”) in new stack
– Goto (sub-record-check,exten,1)
– Executing [exten@sub-record-check:1] NoOp(“PJSIP/102-000000dc”, “Exten Recording Check between 102 and 5005”) in new stack
– Executing [exten@sub-record-check:2] Set(“PJSIP/102-000000dc”, “CALLTYPE=internal”) in new stack
– Executing [exten@sub-record-check:3] ExecIf(“PJSIP/102-000000dc”, “0?Set(CALLTYPE=)”) in new stack
– Executing [exten@sub-record-check:4] Set(“PJSIP/102-000000dc”, “CALLEE=dontcare”) in new stack
– Executing [exten@sub-record-check:5] ExecIf(“PJSIP/102-000000dc”, “0?Set(CALLEE=dontcare)”) in new stack
– Executing [exten@sub-record-check:6] GotoIf(“PJSIP/102-000000dc”, “0?callee”) in new stack
– Executing [exten@sub-record-check:7] GotoIf(“PJSIP/102-000000dc”, “1?caller”) in new stack
– Goto (sub-record-check,exten,13)
– Executing [exten@sub-record-check:13] Set(“PJSIP/102-000000dc”, “RECMODE=dontcare”) in new stack
– Executing [exten@sub-record-check:14] ExecIf(“PJSIP/102-000000dc”, “0?Set(RECMODE=dontcare)”) in new stack
– Executing [exten@sub-record-check:15] ExecIf(“PJSIP/102-000000dc”, “1?Set(RECMODE=dontcare)”) in new stack
– Executing [exten@sub-record-check:16] Gosub(“PJSIP/102-000000dc”, “recordcheck,1(dontcare,internal,5005)”) in new stack
– Executing [recordcheck@sub-record-check:1] NoOp(“PJSIP/102-000000dc”, “Starting recording check against dontcare”) in new stack
– Executing [recordcheck@sub-record-check:2] Goto(“PJSIP/102-000000dc”, “dontcare”) in new stack
– Goto (sub-record-check,recordcheck,3)
– Executing [recordcheck@sub-record-check:3] Return(“PJSIP/102-000000dc”, “”) in new stack
– Executing [exten@sub-record-check:17] Return(“PJSIP/102-000000dc”, “”) in new stack
– Executing [5005@followme-sub:20] GotoIf(“PJSIP/102-000000dc”, “1?skipdring”) in new stack
– Goto (followme-sub,5005,23)
– Executing [5005@followme-sub:23] Set(“PJSIP/102-000000dc”, “STRATEGY=ringallv2-prim”) in new stack
– Executing [5005@followme-sub:24] Set(“PJSIP/102-000000dc”, “RVOL=”) in new stack
– Executing [5005@followme-sub:25] GotoIf(“PJSIP/102-000000dc”, “1?skipsimple”) in new stack
– Goto (followme-sub,5005,28)
– Executing [5005@followme-sub:28] Set(“PJSIP/102-000000dc”, “RingGroupMethod=ringallv2-prim”) in new stack
– Executing [5005@followme-sub:29] Set(“PJSIP/102-000000dc”, “_FMGRP=5005”) in new stack
– Executing [5005@followme-sub:30] GotoIf(“PJSIP/102-000000dc”, “1?DIALGRP”) in new stack
– Goto (followme-sub,5005,34)
– Executing [5005@followme-sub:34] ExecIf(“PJSIP/102-000000dc”, “1?Set(DOPTS=TtrI):Set(DOPTS=m(Ring)TtI)”) in new stack
– Executing [5005@followme-sub:35] GotoIf(“PJSIP/102-000000dc”, “0?doconfirm”) in new stack
– Executing [5005@followme-sub:36] GotoIf(“PJSIP/102-000000dc”, “1?ringallv21”) in new stack
– Goto (followme-sub,5005,39)
– Executing [5005@followme-sub:39] Macro(“PJSIP/102-000000dc”, “dial,27,TtrI,5005”) in new stack
– Executing [s@macro-dial:1] NoOp(“PJSIP/102-000000dc”, "Blind Transfer: , Attended Transfer: , User: 102, Alert Info: ") in new stack
– Executing [s@macro-dial:2] ExecIf(“PJSIP/102-000000dc”, “1?Set(ALERT_INFO=)”) in new stack
– Executing [s@macro-dial:3] ExecIf(“PJSIP/102-000000dc”, “0?Set(ALERT_INFO=)”) in new stack
– Executing [s@macro-dial:4] ExecIf(“PJSIP/102-000000dc”, “0?Set(ALERT_INFO=)”) in new stack
– Executing [s@macro-dial:5] ExecIf(“PJSIP/102-000000dc”, “0?Set(CHANNEL(musicclass)=)”) in new stack
– Executing [s@macro-dial:6] AGI(“PJSIP/102-000000dc”, “dialparties.agi”) in new stack
– Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
dialparties.agi: Starting New Dialparties.agi
dialparties.agi: Caller ID name is ‘Demo 102’ number is ‘102’

dialparties.agi: USE_CONFIRMATION: 'FALSE’
dialparties.agi: RINGGROUP_INDEX: ''
dialparties.agi: Methodology of ring is ‘ringallv2-prim’
– dialparties.agi: Added extension 5005 to extension map
dialparties.agi: got fmgrp_prering: 7, fmgrp_grptime: 20
dialparties.agi: fmgrp_totalprering: 27
dialparties.agi: found extension in pre-ring and array
dialparties.agi: ringallv2 ring times: REALPRERING: 27, PRERING: 7
– dialparties.agi: Extension 5005 cf is disabled
– dialparties.agi: Extension 5005 do not disturb is disabled
dialparties.agi: extnum 5005 has: cw: 1; hascfb: 0 [] hascfu: 0 []
dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
dialparties.agi: Extension 5005 has ExtensionState: 0
– dialparties.agi: dbset CALLTRACE/5005 to 102
– dialparties.agi: Filtered ARG3: 5005
dialparties.agi: NODEST: 5005 adding M(auto-blkvm) to dialopts: TtrIM(auto-blkvm)
dialparties.agi: NODEST: 5005 blkvm enabled macro already in dialopts: TtrIM(auto-blkvm)
– <PJSIP/102-000000dc>AGI Script dialparties.agi completed, returning 0
– Executing [s@macro-dial:9] NoOp(“PJSIP/102-000000dc”, “Returned from dialparties with groups to dial”) in new stack
– Executing [s@macro-dial:10] Set(“PJSIP/102-000000dc”, “LOOPCNT=1”) in new stack
– Executing [s@macro-dial:11] Set(“PJSIP/102-000000dc”, “ITER=1”) in new stack
– Executing [s@macro-dial:12] Set(“PJSIP/102-000000dc”, “EXTTOCALL=5005”) in new stack
– Executing [s@macro-dial:13] NoOp(“PJSIP/102-000000dc”, “Working with 5005”) in new stack
– Executing [s@macro-dial:14] ExecIf(“PJSIP/102-000000dc”, “0?Set(HASH(__SIPHEADERS,Alert-Info)=;volume=)”) in new stack
– Executing [s@macro-dial:15] ExecIf(“PJSIP/102-000000dc”, “0?Set(HASH(__SIPHEADERS,Alert-Info)=;volume=)”) in new stack
– Executing [s@macro-dial:16] Set(“PJSIP/102-000000dc”, “ITER=2”) in new stack
– Executing [s@macro-dial:17] GotoIf(“PJSIP/102-000000dc”, “0?ndloopbegin”) in new stack
– Executing [s@macro-dial:18] Macro(“PJSIP/102-000000dc”, “dial-ringall-predial-hook,”) in new stack
– Executing [s@macro-dial-ringall-predial-hook:1] MacroExit(“PJSIP/102-000000dc”, “”) in new stack
– Executing [s@macro-dial:19] Dial(“PJSIP/102-000000dc”, “SIP/5005,27,TtrIM(auto-blkvm)b(func-apply-sipheaders^s^1),”) in new stack
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– SIP/5005-0000013a Internal Gosub(func-apply-sipheaders,s,1) start
– Executing [s@func-apply-sipheaders:1] NoOp(“SIP/5005-0000013a”, “Applying SIP Headers to channel”) in new stack
– Executing [s@func-apply-sipheaders:2] Set(“SIP/5005-0000013a”, “SIPHEADERKEYS=”) in new stack
– Executing [s@func-apply-sipheaders:3] While(“SIP/5005-0000013a”, “0”) in new stack
– Jumping to priority 7
– Executing [s@func-apply-sipheaders:8] Return(“SIP/5005-0000013a”, “”) in new stack
== Spawn extension (from-internal, 5005, 1) exited non-zero on ‘SIP/5005-0000013a’
– SIP/5005-0000013a Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
Audio is at 17104
Adding codec ulaw to SDP
Adding codec alaw to SDP
Adding codec gsm to SDP
Adding codec g726 to SDP
Adding non-codec 0x1 (telephone-event) to SDP
Reliably Transmitting (NAT) to 71.x.x.x:1024:
INVITE sip:5005@71.x.x.x:1025;user=phone;transport=udp SIP/2.0
Via: SIP/2.0/UDP 162.x.x.x:5060;branch=z9hG4bK4f81716f;rport
Max-Forwards: 70
From: “Demo 102” sip:102@162.x.x.x;tag=as751873ef
To: sip:5005@71.x.x.x:1025;user=phone;transport=udp
Contact: sip:102@162.x.x.x:5060
Call-ID: 610c972e5d8676fe022661f6020eb0a6@162.x.x.x:5060
CSeq: 102 INVITE
User-Agent: FPBX-13.0.190.7(13.9.1)
Date: Tue, 17 Jan 2017 09:11:15 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
P-Asserted-Identity: “Demo 102” sip:102@162.x.x.x
Content-Type: application/sdp
Content-Length: 331

v=0
o=root 742969263 742969263 IN IP4 162.x.x.x
s=Asterisk PBX 13.9.1
c=IN IP4 162.x.x.x
t=0 0
m=audio 17104 RTP/AVP 0 8 3 111 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:111 G726-32/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=maxptime:150
a=sendrecv


– Called SIP/5005
– Connected line update to PJSIP/102-000000dc prevented.

<— SIP read from UDP:71.x.x.x:1024 —>
SIP/2.0 404 Not Found
Via: SIP/2.0/UDP 162.x.x.x:5060;branch=z9hG4bK4f81716f;rport
From: “Demo 102” sip:102@162.x.x.x;tag=as751873ef
To: sip:5005@71.x.x.x:1025;user=phone;transport=udp
Call-ID: 610c972e5d8676fe022661f6020eb0a6@162.x.x.x:5060
Date: Tue, 17 Jan 2017 09:11:15 GMT
CSeq: 102 INVITE
Content-Length: 0

<------------->
— (8 headers 0 lines) —
Transmitting (NAT) to 71.x.x.x:1024:
ACK sip:5005@71.x.x.x:1025;user=phone;transport=udp SIP/2.0
Via: SIP/2.0/UDP 162.x.x.x:5060;branch=z9hG4bK4f81716f;rport
Max-Forwards: 70
From: “Demo 102” sip:102@162.x.x.x;tag=as751873ef
To: sip:5005@71.x.x.x:1025;user=phone;transport=udp
Contact: sip:102@162.x.x.x:5060
Call-ID: 610c972e5d8676fe022661f6020eb0a6@162.x.x.x:5060
CSeq: 102 ACK
User-Agent: FPBX-13.0.190.7(13.9.1)
Content-Length: 0


Scheduling destruction of SIP dialog ‘610c972e5d8676fe022661f6020eb0a6@162.x.x.x:5060’ in 7040 ms (Method: INVITE)
== Everyone is busy/congested at this time (1:0/0/1)
– Executing [s@macro-dial:20] Set(“PJSIP/102-000000dc”, “DIALSTATUS=CHANUNAVAIL”) in new stack
– Executing [s@macro-dial:21] GosubIf(“PJSIP/102-000000dc”, “0?CHANUNAVAIL,1()”) in new stack
– Executing [s@macro-dial:22] NoOp(“PJSIP/102-000000dc”, “Returning since nobody answered”) in new stack
– Executing [s@macro-dial:23] MacroExit(“PJSIP/102-000000dc”, “”) in new stack
– Executing [5005@followme-sub:40] Goto(“PJSIP/102-000000dc”, “nextstep”) in new stack
– Goto (followme-sub,5005,45)
– Executing [5005@followme-sub:45] Set(“PJSIP/102-000000dc”, “RingGroupMethod=”) in new stack
– Executing [5005@followme-sub:46] GotoIf(“PJSIP/102-000000dc”, “0?nodest”) in new stack
– Executing [5005@followme-sub:47] Set(“PJSIP/102-000000dc”, “__NODEST=”) in new stack
– Executing [5005@followme-sub:48] Set(“PJSIP/102-000000dc”, “__PICKUPMARK=”) in new stack
– Executing [5005@followme-sub:49] Macro(“PJSIP/102-000000dc”, “blkvm-clr,”) in new stack
– Executing [s@macro-blkvm-clr:1] Set(“PJSIP/102-000000dc”, “SHARED(BLKVM,PJSIP/102-000000dc)=”) in new stack
– Executing [s@macro-blkvm-clr:2] Set(“PJSIP/102-000000dc”, “GOSUB_RETVAL=”) in new stack
– Executing [s@macro-blkvm-clr:3] MacroExit(“PJSIP/102-000000dc”, “”) in new stack
– Executing [5005@followme-sub:50] Set(“PJSIP/102-000000dc”, “DIALSTATUS=CHANUNAVAIL”) in new stack
– Executing [5005@followme-sub:51] GotoIf(“PJSIP/102-000000dc”, “0?dohangup”) in new stack
– Executing [5005@followme-sub:52] Goto(“PJSIP/102-000000dc”, “ext-local,5005,dest”) in new stack
– Goto (ext-local,5005,3)
– Executing [5005@ext-local:3] Set(“PJSIP/102-000000dc”, “__PICKUPMARK=”) in new stack
– Executing [5005@ext-local:4] Goto(“PJSIP/102-000000dc”, “,return,1”) in new stack
– Goto (ext-local,return,1)
[2017-01-17 01:11:15] WARNING[24609][C-00000125]: pbx.c:4365 __ast_pbx_run: Channel ‘PJSIP/102-000000dc’ sent to invalid extension but no invalid handler: context,exten,priority=ext-local,return,1
– Executing [h@ext-local:1] Macro(“PJSIP/102-000000dc”, “hangupcall,”) in new stack
– Executing [s@macro-hangupcall:1] GotoIf(“PJSIP/102-000000dc”, “1?theend”) in new stack
– Goto (macro-hangupcall,s,3)
– Executing [s@macro-hangupcall:3] ExecIf(“PJSIP/102-000000dc”, “0?Set(CDR(recordingfile)=)”) in new stack
– Executing [s@macro-hangupcall:4] Hangup(“PJSIP/102-000000dc”, “”) in new stack
== Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘PJSIP/102-000000dc’ in macro ‘hangupcall’
== Spawn extension (ext-local, h, 1) exited non-zero on 'PJSIP/102-000000dc’
Really destroying SIP dialog ‘610c972e5d8676fe022661f6020eb0a6@162.x.x.x:5060’ Method: INVITE
Reliably Transmitting (NAT) to 71.x.x.x:1024:
OPTIONS sip:5005@71.x.x.x:1025;user=phone;transport=udp SIP/2.0
Via: SIP/2.0/UDP 162.x.x.x:5060;branch=z9hG4bK03225e67;rport
Max-Forwards: 70
From: “Unknown” sip:Unknown@162.x.x.x;tag=as121292f1
To: sip:5005@71.x.x.x:1025;user=phone;transport=udp
Contact: sip:Unknown@162.x.x.x:5060
Call-ID: 757bc37428696e2608b599481b252a90@162.x.x.x:5060
CSeq: 102 OPTIONS
User-Agent: FPBX-13.0.190.7(13.9.1)
Date: Tue, 17 Jan 2017 09:11:26 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Length: 0


<— SIP read from UDP:71.x.x.x:1024 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP 162.x.x.x:5060;branch=z9hG4bK03225e67;rport
From: “Unknown” sip:Unknown@162.x.x.x;tag=as121292f1
To: sip:5005@71.x.x.x:1025;user=phone;transport=udp;tag=001794627d081a964e6a7b2c-5490a23d
Call-ID: 757bc37428696e2608b599481b252a90@162.x.x.x:5060
Date: Tue, 17 Jan 2017 09:11:25 GMT
CSeq: 102 OPTIONS
Server: Cisco-CP7960G/8.0
Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE
Accept: application/sdp,multipart/mixed,multipart/alternative
Accept-Encoding: identity
Accept-Language: en
Supported: replaces,join,norefersub
Content-Length: 233
Content-Type: application/sdp
Content-Disposition: session;handling=optional

v=0
o=Cisco-SIPUA 2430 0 IN IP4 10.26.9.1
s=SIP Call
t=0 0
m=audio 0 RTP/AVP 0 8 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15