Extension to extension calls not working

Asterisk Asterisk Support
1

Recently install Asterisk 1.8.26.1 with FreePBX 2.11. enabled TLS and SRTP, everything works good but cant make calls between extensions. on the odd occasion it works but not all the time. here is my config

SIP config
tlsenable = yes
tlscertfile = /etc/asterisk/keys/asterisk.pem
tcpenable = yes
tlsbindaddr = 0.0.0.0
tlscipher = ALL
tlsclientmethod = tlsv1
srtpcapable = yes
encryption = no
tlscafile = /etc/asterisk/keys/ca.crt
tlsdontverifyserver = yes

Extension 5001 and 5002
dtmfmode = RFC 2833
trustpid = yes
sendrpid = no
type = friend
nat = yes
port = 5061
qualify = yes
qualify freq 60
transport = TLS only
encryption = Yes (SRTP only)

the following scenarios work

1.extension with no SRTP is able to communicate with an extension which has SRTP enabled.
2.Two extensions with no SRTP enabled were able to communicate.
3.Incoming and outgoing calls for outside numbers work fine for extension with SRTP enabled.

but two extensions with SRTP enabled work only once or twice out of 10 to 15 attempts. here are the logs

== Using SIP RTP CoS mark 5
– Executing [5001@from-internal:1] Set(“SIP/5002-00000002”, “__RINGTIMER=15”) in new stack
– Executing [5001@from-internal:2] Macro(“SIP/5002-00000002”, “exten-vm,novm,5001,0,0,0”) in new stack
– Executing [s@macro-exten-vm:1] Macro(“SIP/5002-00000002”, “user-callerid,”) in new stack
– Executing [s@macro-user-callerid:1] Set(“SIP/5002-00000002”, “TOUCH_MONITOR=1396614029.2”) in new stack
– Executing [s@macro-user-callerid:2] Set(“SIP/5002-00000002”, “AMPUSER=5002”) in new stack
– Executing [s@macro-user-callerid:3] GotoIf(“SIP/5002-00000002”, “0?report”) in new stack
– Executing [s@macro-user-callerid:4] ExecIf(“SIP/5002-00000002”, “1?Set(REALCALLERIDNUM=5002)”) in new stack
– Executing [s@macro-user-callerid:5] Set(“SIP/5002-00000002”, “AMPUSER=5002”) in new stack
– Executing [s@macro-user-callerid:6] GotoIf(“SIP/5002-00000002”, “0?limit”) in new stack
– Executing [s@macro-user-callerid:7] Set(“SIP/5002-00000002”, “AMPUSERCIDNAME=Vincent”) in new stack
– Executing [s@macro-user-callerid:8] GotoIf(“SIP/5002-00000002”, “0?report”) in new stack
– Executing [s@macro-user-callerid:9] Set(“SIP/5002-00000002”, “AMPUSERCID=5002”) in new stack
– Executing [s@macro-user-callerid:10] Set(“SIP/5002-00000002”, “__DIAL_OPTIONS=tr”) in new stack
– Executing [s@macro-user-callerid:11] Set(“SIP/5002-00000002”, “CALLERID(all)=“Vincent” <5002>”) in new stack
– Executing [s@macro-user-callerid:12] GotoIf(“SIP/5002-00000002”, “0?limit”) in new stack
– Executing [s@macro-user-callerid:13] ExecIf(“SIP/5002-00000002”, “0?Set(GROUP(concurrency_limit)=5002)”) in new stack
– Executing [s@macro-user-callerid:14] ExecIf(“SIP/5002-00000002”, “0?Set(CHANNEL(language)=)”) in new stack
– Executing [s@macro-user-callerid:15] GosubIf(“SIP/5002-00000002”, “7?sub-ccss,s,1(macro-exten-vm,5001)”) in new stack
– Executing [s@sub-ccss:1] ExecIf(“SIP/5002-00000002”, “0?Return()”) in new stack
– Executing [s@sub-ccss:2] Set(“SIP/5002-00000002”, “CCSS_SETUP=TRUE”) in new stack
– Executing [s@sub-ccss:3] GosubIf(“SIP/5002-00000002”, “0?monitor_config,1(macro-exten-vm,5001):monitor_default,1(macro-exten-vm,5001)”) in new stack
– Executing [monitor_default@sub-ccss:1] GotoIf(“SIP/5002-00000002”, “1?is_exten”) in new stack
– Goto (sub-ccss,monitor_default,4)
– Executing [monitor_default@sub-ccss:4] Set(“SIP/5002-00000002”, “CALLCOMPLETION(cc_monitor_policy)=generic”) in new stack
– Executing [monitor_default@sub-ccss:5] Set(“SIP/5002-00000002”, “CALLCOMPLETION(cc_max_monitors)=5”) in new stack
– Executing [monitor_default@sub-ccss:6] Return(“SIP/5002-00000002”, “TRUE”) in new stack
– Executing [s@sub-ccss:4] GosubIf(“SIP/5002-00000002”, “7?agent_config,1():agent_default,1()”) in new stack
– Executing [agent_config@sub-ccss:1] Set(“SIP/5002-00000002”, “CALLCOMPLETION(cc_agent_policy)=generic”) in new stack
– Executing [agent_config@sub-ccss:2] Set(“SIP/5002-00000002”, “CALLCOMPLETION(cc_offer_timer)=30”) in new stack
– Executing [agent_config@sub-ccss:3] Set(“SIP/5002-00000002”, “CALLCOMPLETION(ccbs_available_timer)=”) in new stack
– Executing [agent_config@sub-ccss:4] Set(“SIP/5002-00000002”, “CALLCOMPLETION(ccnr_available_timer)=”) in new stack
– Executing [agent_config@sub-ccss:5] Set(“SIP/5002-00000002”, “CALLCOMPLETION(cc_callback_macro)=ccss-default”) in new stack
– Executing [agent_config@sub-ccss:6] ExecIf(“SIP/5002-00000002”, “1?Set(CALLCOMPLETION(cc_recall_timer)=)”) in new stack
– Executing [agent_config@sub-ccss:7] ExecIf(“SIP/5002-00000002”, “1?Set(CALLCOMPLETION(cc_max_agents)=)”) in new stack
– Executing [agent_config@sub-ccss:8] ExecIf(“SIP/5002-00000002”, “0?Set(CALLCOMPLETION(cc_agent_dialstring)=Local/5002_5001@from-ccss-)”) in new stack
– Executing [agent_config@sub-ccss:9] Set(“SIP/5002-00000002”, “CALLCOMPLETION(cc_callback_macro)=ccss-default”) in new stack
– Executing [agent_config@sub-ccss:10] Return(“SIP/5002-00000002”, “”) in new stack
– Executing [s@sub-ccss:5] Set(“SIP/5002-00000002”, “DB(AMPUSER/5002/ccss/last_number)=5001”) in new stack
– Executing [s@sub-ccss:6] Return(“SIP/5002-00000002”, “”) in new stack
– Executing [s@macro-user-callerid:16] GotoIf(“SIP/5002-00000002”, “0?continue”) in new stack
– Executing [s@macro-user-callerid:17] Set(“SIP/5002-00000002”, “__TTL=64”) in new stack
– Executing [s@macro-user-callerid:18] GotoIf(“SIP/5002-00000002”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,29)
– Executing [s@macro-user-callerid:29] Set(“SIP/5002-00000002”, “CALLERID(number)=5002”) in new stack
– Executing [s@macro-user-callerid:30] Set(“SIP/5002-00000002”, “CALLERID(name)=Vincent”) in new stack
– Executing [s@macro-user-callerid:31] Set(“SIP/5002-00000002”, “CDR(cnum)=5002”) in new stack
– Executing [s@macro-user-callerid:32] Set(“SIP/5002-00000002”, “CDR(cnam)=Vincent”) in new stack
– Executing [s@macro-user-callerid:33] Set(“SIP/5002-00000002”, “CHANNEL(language)=en”) in new stack
– Executing [s@macro-exten-vm:2] Set(“SIP/5002-00000002”, “RingGroupMethod=none”) in new stack
– Executing [s@macro-exten-vm:3] Set(“SIP/5002-00000002”, “__EXTTOCALL=5001”) in new stack
– Executing [s@macro-exten-vm:4] Set(“SIP/5002-00000002”, “__PICKUPMARK=5001”) in new stack
– Executing [s@macro-exten-vm:5] Set(“SIP/5002-00000002”, “RT=”) in new stack
– Executing [s@macro-exten-vm:6] ExecIf(“SIP/5002-00000002”, “0?Macro(vm,novm,DIRECTDIAL,)”) in new stack
– Executing [s@macro-exten-vm:7] ExecIf(“SIP/5002-00000002”, “0?MacroExit()”) in new stack
– Executing [s@macro-exten-vm:8] Gosub(“SIP/5002-00000002”, “sub-record-check,s,1(exten,5001,)”) in new stack
– Executing [s@sub-record-check:1] Set(“SIP/5002-00000002”, “REC_POLICY_MODE_SAVE=”) in new stack
– Executing [s@sub-record-check:2] GotoIf(“SIP/5002-00000002”, “1?check”) in new stack
– Goto (sub-record-check,s,7)
– Executing [s@sub-record-check:7] Set(“SIP/5002-00000002”, “__MON_FMT=wav”) in new stack
– Executing [s@sub-record-check:8] GotoIf(“SIP/5002-00000002”, “1?next”) in new stack
– Goto (sub-record-check,s,11)
– Executing [s@sub-record-check:11] ExecIf(“SIP/5002-00000002”, “0?Return()”) in new stack
– Executing [s@sub-record-check:12] ExecIf(“SIP/5002-00000002”, “0?Set(__REC_POLICY_MODE=)”) in new stack
– Executing [s@sub-record-check:13] GotoIf(“SIP/5002-00000002”, “0?exten,1”) in new stack
– Executing [s@sub-record-check:14] Set(“SIP/5002-00000002”, “__REC_STATUS=INITIALIZED”) in new stack
– Executing [s@sub-record-check:15] Set(“SIP/5002-00000002”, “NOW=1396614029”) in new stack
– Executing [s@sub-record-check:16] Set(“SIP/5002-00000002”, “__DAY=04”) in new stack
– Executing [s@sub-record-check:17] Set(“SIP/5002-00000002”, “__MONTH=04”) in new stack
– Executing [s@sub-record-check:18] Set(“SIP/5002-00000002”, “__YEAR=2014”) in new stack
– Executing [s@sub-record-check:19] Set(“SIP/5002-00000002”, “__TIMESTR=20140404-072029”) in new stack
– Executing [s@sub-record-check:20] Set(“SIP/5002-00000002”, “__FROMEXTEN=5002”) in new stack
– Executing [s@sub-record-check:21] Set(“SIP/5002-00000002”, “__CALLFILENAME=exten-5001-5002-20140404-072029-1396614029.2”) in new stack
– Executing [s@sub-record-check:22] Goto(“SIP/5002-00000002”, “exten,1”) in new stack
– Goto (sub-record-check,exten,1)
– Executing [exten@sub-record-check:1] GotoIf(“SIP/5002-00000002”, “0?callee”) in new stack
– Executing [exten@sub-record-check:2] Set(“SIP/5002-00000002”, “__REC_POLICY_MODE=dontcare”) in new stack
– Executing [exten@sub-record-check:3] GotoIf(“SIP/5002-00000002”, “1?caller”) in new stack
– Goto (sub-record-check,exten,10)
– Executing [exten@sub-record-check:10] Set(“SIP/5002-00000002”, “__REC_POLICY_MODE=dontcare”) in new stack
– Executing [exten@sub-record-check:11] GosubIf(“SIP/5002-00000002”, “0?record,1(exten,5001,5002)”) in new stack
– Executing [exten@sub-record-check:12] Return(“SIP/5002-00000002”, “”) in new stack
– Executing [s@macro-exten-vm:9] Macro(“SIP/5002-00000002”, “dial-one,tr,5001”) in new stack
– Executing [s@macro-dial-one:1] Set(“SIP/5002-00000002”, “DEXTEN=5001”) in new stack
– Executing [s@macro-dial-one:2] Set(“SIP/5002-00000002”, “DIALSTATUS_CW=”) in new stack
– Executing [s@macro-dial-one:3] GosubIf(“SIP/5002-00000002”, “0?screen,1()”) in new stack
– Executing [s@macro-dial-one:4] GosubIf(“SIP/5002-00000002”, “0?cf,1()”) in new stack
– Executing [s@macro-dial-one:5] GotoIf(“SIP/5002-00000002”, “1?skip1”) in new stack
– Goto (macro-dial-one,s,8)
– Executing [s@macro-dial-one:8] GotoIf(“SIP/5002-00000002”, “0?nodial”) in new stack
– Executing [s@macro-dial-one:9] GotoIf(“SIP/5002-00000002”, “0?continue”) in new stack
– Executing [s@macro-dial-one:10] Set(“SIP/5002-00000002”, “EXTHASCW=ENABLED”) in new stack
– Executing [s@macro-dial-one:11] GotoIf(“SIP/5002-00000002”, “0?next1:cwinusebusy”) in new stack
– Goto (macro-dial-one,s,23)
– Executing [s@macro-dial-one:23] GotoIf(“SIP/5002-00000002”, “1?next3:continue”) in new stack
– Goto (macro-dial-one,s,24)
– Executing [s@macro-dial-one:24] ExecIf(“SIP/5002-00000002”, “0?Set(DIALSTATUS_CW=BUSY)”) in new stack
– Executing [s@macro-dial-one:25] GotoIf(“SIP/5002-00000002”, “0?nodial”) in new stack
– Executing [s@macro-dial-one:26] GosubIf(“SIP/5002-00000002”, “1?dstring,1():dlocal,1()”) in new stack
– Executing [dstring@macro-dial-one:1] Set(“SIP/5002-00000002”, “DSTRING=”) in new stack
– Executing [dstring@macro-dial-one:2] Set(“SIP/5002-00000002”, “DEVICES=5001”) in new stack
– Executing [dstring@macro-dial-one:3] ExecIf(“SIP/5002-00000002”, “0?Return()”) in new stack
– Executing [dstring@macro-dial-one:4] ExecIf(“SIP/5002-00000002”, “0?Set(DEVICES=001)”) in new stack
– Executing [dstring@macro-dial-one:5] Set(“SIP/5002-00000002”, “LOOPCNT=1”) in new stack
– Executing [dstring@macro-dial-one:6] Set(“SIP/5002-00000002”, “ITER=1”) in new stack
– Executing [dstring@macro-dial-one:7] Set(“SIP/5002-00000002”, “THISDIAL=SIP/5001”) in new stack
– Executing [dstring@macro-dial-one:8] GosubIf(“SIP/5002-00000002”, “1?zap2dahdi,1()”) in new stack
– Executing [zap2dahdi@macro-dial-one:1] ExecIf(“SIP/5002-00000002”, “0?Return()”) in new stack
– Executing [zap2dahdi@macro-dial-one:2] Set(“SIP/5002-00000002”, “NEWDIAL=”) in new stack
– Executing [zap2dahdi@macro-dial-one:3] Set(“SIP/5002-00000002”, “LOOPCNT2=1”) in new stack
– Executing [zap2dahdi@macro-dial-one:4] Set(“SIP/5002-00000002”, “ITER2=1”) in new stack
– Executing [zap2dahdi@macro-dial-one:5] Set(“SIP/5002-00000002”, “THISPART2=SIP/5001”) in new stack
– Executing [zap2dahdi@macro-dial-one:6] ExecIf(“SIP/5002-00000002”, “0?Set(THISPART2=DAHDI/5001)”) in new stack
– Executing [zap2dahdi@macro-dial-one:7] Set(“SIP/5002-00000002”, “NEWDIAL=SIP/5001&”) in new stack
– Executing [zap2dahdi@macro-dial-one:8] Set(“SIP/5002-00000002”, “ITER2=2”) in new stack
– Executing [zap2dahdi@macro-dial-one:9] GotoIf(“SIP/5002-00000002”, “0?begin2”) in new stack
– Executing [zap2dahdi@macro-dial-one:10] Set(“SIP/5002-00000002”, “THISDIAL=SIP/5001”) in new stack
– Executing [zap2dahdi@macro-dial-one:11] Return(“SIP/5002-00000002”, “”) in new stack
– Executing [dstring@macro-dial-one:9] Set(“SIP/5002-00000002”, “DSTRING=SIP/5001&”) in new stack
– Executing [dstring@macro-dial-one:10] Set(“SIP/5002-00000002”, “ITER=2”) in new stack
– Executing [dstring@macro-dial-one:11] GotoIf(“SIP/5002-00000002”, “0?begin”) in new stack
– Executing [dstring@macro-dial-one:12] Set(“SIP/5002-00000002”, “DSTRING=SIP/5001”) in new stack
– Executing [dstring@macro-dial-one:13] Return(“SIP/5002-00000002”, “”) in new stack
– Executing [s@macro-dial-one:27] GotoIf(“SIP/5002-00000002”, “0?nodial”) in new stack
– Executing [s@macro-dial-one:28] GotoIf(“SIP/5002-00000002”, “0?skiptrace”) in new stack
– Executing [s@macro-dial-one:29] GosubIf(“SIP/5002-00000002”, “1?ctset,1():ctclear,1()”) in new stack
– Executing [ctset@macro-dial-one:1] Set(“SIP/5002-00000002”, “DB(CALLTRACE/5001)=5002”) in new stack
– Executing [ctset@macro-dial-one:2] Return(“SIP/5002-00000002”, “”) in new stack
– Executing [s@macro-dial-one:30] Set(“SIP/5002-00000002”, “D_OPTIONS=tr”) in new stack
– Executing [s@macro-dial-one:31] ExecIf(“SIP/5002-00000002”, “0?SIPAddHeader(Alert-Info: )”) in new stack
– Executing [s@macro-dial-one:32] ExecIf(“SIP/5002-00000002”, “0?SIPAddHeader()”) in new stack
– Executing [s@macro-dial-one:33] ExecIf(“SIP/5002-00000002”, “0?Set(CHANNEL(musicclass)=)”) in new stack
– Executing [s@macro-dial-one:34] GosubIf(“SIP/5002-00000002”, “0?qwait,1()”) in new stack
– Executing [s@macro-dial-one:35] Set(“SIP/5002-00000002”, “__CWIGNORE=”) in new stack
– Executing [s@macro-dial-one:36] Set(“SIP/5002-00000002”, “__KEEPCID=TRUE”) in new stack
– Executing [s@macro-dial-one:37] GotoIf(“SIP/5002-00000002”, “0?usegoto,1”) in new stack
– Executing [s@macro-dial-one:38] GotoIf(“SIP/5002-00000002”, “0?godial”) in new stack
– Executing [s@macro-dial-one:39] Set(“SIP/5002-00000002”, “CONNECTEDLINE(name,i)=Curtis”) in new stack
– Executing [s@macro-dial-one:40] Set(“SIP/5002-00000002”, “CONNECTEDLINE(num)=5001”) in new stack
– Executing [s@macro-dial-one:41] Set(“SIP/5002-00000002”, “D_OPTIONS=trI”) in new stack
– Executing [s@macro-dial-one:42] Dial(“SIP/5002-00000002”, “SIP/5001,trI”) in new stack
== Everyone is busy/congested at this time (1:0/0/1)
– Executing [s@macro-dial-one:43] ExecIf(“SIP/5002-00000002”, “0?MacroExit()”) in new stack
– Executing [s@macro-dial-one:44] ExecIf(“SIP/5002-00000002”, “0?Set(DIALSTATUS=)”) in new stack
– Executing [s@macro-dial-one:45] GosubIf(“SIP/5002-00000002”, “0?s-CHANUNAVAIL,1()”) in new stack
– Executing [s@macro-dial-one:46] MacroExit(“SIP/5002-00000002”, “”) in new stack
– Executing [s@macro-exten-vm:10] Set(“SIP/5002-00000002”, “SV_DIALSTATUS=CHANUNAVAIL”) in new stack
– Executing [s@macro-exten-vm:11] GosubIf(“SIP/5002-00000002”, “0?docfu,1()”) in new stack
– Executing [s@macro-exten-vm:12] GosubIf(“SIP/5002-00000002”, “0?docfb,1()”) in new stack
– Executing [s@macro-exten-vm:13] Set(“SIP/5002-00000002”, “DIALSTATUS=CHANUNAVAIL”) in new stack
– Executing [s@macro-exten-vm:14] ExecIf(“SIP/5002-00000002”, “0?MacroExit()”) in new stack
– Executing [s@macro-exten-vm:15] GotoIf(“SIP/5002-00000002”, “1?s-CHANUNAVAIL,1”) in new stack
– Goto (macro-exten-vm,s-CHANUNAVAIL,1)
– Executing [s-CHANUNAVAIL@macro-exten-vm:1] GotoIf(“SIP/5002-00000002”, “0?exit,1”) in new stack
– Executing [s-CHANUNAVAIL@macro-exten-vm:2] PlayTones(“SIP/5002-00000002”, “congestion”) in new stack
– Executing [s-CHANUNAVAIL@macro-exten-vm:3] Congestion(“SIP/5002-00000002”, “10”) in new stack
== Spawn extension (macro-exten-vm, s-CHANUNAVAIL, 3) exited non-zero on ‘SIP/5002-00000002’ in macro ‘exten-vm’
== Spawn extension (from-internal, 5001, 2) exited non-zero on ‘SIP/5002-00000002’
– Executing [h@from-internal:1] Hangup(“SIP/5002-00000002”, “”) in new stack
== Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/5002-00000002’

Please let me know if you need any additional information. Thanks you.

2

CHANUNAVAIL on SIP normally means that the destination device (NB not extension - that is FreePBX usage and this is the wrong place for FreePBX expertise) has not registered, so you need to use sip show peers to check that, and may need to use sip set debug on to see what happens with a registration request.

3

Thanks for the Reply

“sip show peers” shows the extensions unreachable some times, not sure if its a problem with blink.

OPTIONS
PBX*CLI> sip show peers
Name/username Host Dyn Forcerport ACL Port Status
5001/56840391 D N A 54884 UNREACHABLE
5002/80374519 D N A 50462 UNREACHABLE
INBOUND N 5060 Unmonitored
3 sip peers [Monitored: 0 online, 2 offline Unmonitored: 1 online, 0 offline]

Here are the debug logs.

PBXCLI>
PBXCLI>
PBXCLI>
PBXCLI>
PBXCLI>
PBXCLI>
PBXCLI>
PBXCLI>
PBX*CLI>

<— SIP read from TLS::55178 —>
PUBLISH sip:5001@ SIP/2.0
Via: SIP/2.0/TLS 172.16.30.221:55178;rport;branch=z9hG4bKPja61baeb747824af4801256c1cd58f7dd;alias
Max-Forwards: 70
From: “curtis” <sip:5001@>;tag=101048fd410041fbb6f8d9f55a0f1d4c
To: “curtis” <sip:5001@>
Call-ID: 697a1c4327844d46b2be91d6d749f946
CSeq: 1 PUBLISH
Event: presence
Expires: 600
User-Agent: Blink 0.7.0 (Windows)
Content-Type: application/pidf+xml
Content-Length: 1832

<?xml version='1.0' encoding='UTF-8'?>

openagp-pidf:extendedbusy</agp-pidf:extended>caps:servcapscaps:audiotrue</caps:audio>caps:messagetrue</caps:message>caps:textfalse</caps:text>agp-caps:file-transferfalse</agp-caps:file-transfer>agp-caps:screen-sharing-serverfalse</agp-caps:screen-sharing-server>agp-caps:screen-sharing-clientfalse</agp-caps:screen-sharing-client></caps:servcaps><c:display-name>curtis</c:display-name><agp-pidf:device-info id=“cf950620-b287-4bd9-8ff4-2d0281ff4c37”>agp-pidf:descriptionPmttT400-PC</agp-pidf:description>agp-pidf:user-agentBlink 0.7.0 (Windows)</agp-pidf:user-agent>agp-pidf:time-offset1140</agp-pidf:time-offset></agp-pidf:device-info><rpid:user-input idle-threshold=“600”>active</rpid:user-input>dm:deviceIDcf950620-b287-4bd9-8ff4-2d0281ff4c37</dm:deviceID>sip%3A5001%40On the phone2014-04-06T00:40:25.722000-05:00<dm:person id=“PID-5c5bd08f01887e7fb33f4b881a80b4e3”>rpid:activitiesrpid:busy/</rpid:activities>dm:timestamp2014-04-06T00:40:25.722000-05:00</dm:timestamp></dm:person><dm:device id=“DID-cf950620-b287-4bd9-8ff4-2d0281ff4c37”>dm:deviceIDcf950620-b287-4bd9-8ff4-2d0281ff4c37</dm:deviceID>dm:noteBlink 0.7.0 (Windows) at PmttT400-PC</dm:note>dm:timestamp2014-04-06T00:40:25.722000-05:00</dm:timestamp></dm:device>
<------------->
— (12 headers 2 lines) —

<— Transmitting (NAT) to :55178 —>
SIP/2.0 489 Bad Event
Via: SIP/2.0/TLS 172.16.30.221:55178;branch=z9hG4bKPja61baeb747824af4801256c1cd58f7dd;alias;received=;rport=55178
From: “curtis” <sip:5001@>;tag=101048fd410041fbb6f8d9f55a0f1d4c
To: “curtis” <sip:5001@>;tag=as48625f34
Call-ID: 697a1c4327844d46b2be91d6d749f946
CSeq: 1 PUBLISH
Server: FPBX-2.11.0(1.8.26.1)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Content-Length: 0

<------------>

<— SIP read from TLS::55178 —>
INVITE sip:5002@ SIP/2.0
Via: SIP/2.0/TLS 172.16.30.221:55178;rport;branch=z9hG4bKPj7b744696d3364d9b84f377e8dc8a2f6f;alias
Max-Forwards: 70
From: “curtis” <sip:5001@>;tag=21aade99df194af4b28b2202cd4a4594
To: <sip:5002@>
Contact: sip:17243806@172.16.30.221:5061;transport=tls
Call-ID: 980e9aff5406479e98f240e1dd603abf
CSeq: 28033 INVITE
Allow: SUBSCRIBE, NOTIFY, PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, MESSAGE, REFER
Supported: 100rel, replaces, norefersub, gruu
User-Agent: Blink 0.7.0 (Windows)
Content-Type: application/sdp
Content-Length: 571

v=0
o=- 3605733625 3605733625 IN IP4 172.16.30.221
s=Blink 0.7.0 (Windows)
c=IN IP4 172.16.30.221
t=0 0
m=audio 10008 RTP/SAVP 113 104 103 9 0 8 101
a=rtcp:10009
a=rtpmap:113 opus/48000
a=fmtp:113 useinbandfec=1
a=rtpmap:104 speex/32000
a=rtpmap:103 speex/16000
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:wPP6nypbQI8kKYSzdEcyvmpsUwa2bqXQY3hoJSvR
a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:UITcYmaAsenb+GLzqkPaVg3wHv8bocI1bPGMMUvR
a=sendrecv
<------------->
— (13 headers 19 lines) —
Sending to :55178 (NAT)
Using INVITE request as basis request - 980e9aff5406479e98f240e1dd603abf
Found peer ‘5001’ for ‘5001’ from :55178

<— Reliably Transmitting (NAT) to :55178 —>
SIP/2.0 401 Unauthorized
Via: SIP/2.0/TLS 172.16.30.221:55178;branch=z9hG4bKPj7b744696d3364d9b84f377e8dc8a2f6f;alias;received=;rport=55178
From: “curtis” <sip:5001@>;tag=21aade99df194af4b28b2202cd4a4594
To: <sip:5002@>;tag=as2bb88c9c
Call-ID: 980e9aff5406479e98f240e1dd603abf
CSeq: 28033 INVITE
Server: FPBX-2.11.0(1.8.26.1)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
WWW-Authenticate: Digest algorithm=MD5, realm=“asterisk”, nonce="4bb445d5"
Content-Length: 0

<------------>
Scheduling destruction of SIP dialog ‘980e9aff5406479e98f240e1dd603abf’ in 6400 ms (Method: INVITE)
Really destroying SIP dialog ‘697a1c4327844d46b2be91d6d749f946’ Method: PUBLISH

<— SIP read from TLS::55178 —>
ACK sip:5002@ SIP/2.0
Via: SIP/2.0/TLS 172.16.30.221:55178;rport;branch=z9hG4bKPj7b744696d3364d9b84f377e8dc8a2f6f;alias
Max-Forwards: 70
From: “curtis” <sip:5001@>;tag=21aade99df194af4b28b2202cd4a4594
To: <sip:5002@>;tag=as2bb88c9c
Call-ID: 980e9aff5406479e98f240e1dd603abf
CSeq: 28033 ACK
User-Agent: Blink 0.7.0 (Windows)
Content-Length: 0

<------------->
— (9 headers 0 lines) —

<— SIP read from TLS::55178 —>
INVITE sip:5002@ SIP/2.0
Via: SIP/2.0/TLS 172.16.30.221:55178;rport;branch=z9hG4bKPjd8fcf2ea6d194aad91c38648f66fd026;alias
Max-Forwards: 70
From: “curtis” <sip:5001@>;tag=21aade99df194af4b28b2202cd4a4594
To: <sip:5002@>
Contact: sip:17243806@172.16.30.221:5061;transport=tls
Call-ID: 980e9aff5406479e98f240e1dd603abf
CSeq: 28034 INVITE
Allow: SUBSCRIBE, NOTIFY, PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, MESSAGE, REFER
Supported: 100rel, replaces, norefersub, gruu
User-Agent: Blink 0.7.0 (Windows)
Authorization: Digest username=“5001”, realm=“asterisk”, nonce=“4bb445d5”, uri=“sip:5002@”, response=“be2fcda475635169e00ba1fa4e77fe52”, algorithm=MD5
Content-Type: application/sdp
Content-Length: 571

v=0
o=- 3605733625 3605733625 IN IP4 172.16.30.221
s=Blink 0.7.0 (Windows)
c=IN IP4 172.16.30.221
t=0 0
m=audio 10008 RTP/SAVP 113 104 103 9 0 8 101
a=rtcp:10009
a=rtpmap:113 opus/48000
a=fmtp:113 useinbandfec=1
a=rtpmap:104 speex/32000
a=rtpmap:103 speex/16000
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:wPP6nypbQI8kKYSzdEcyvmpsUwa2bqXQY3hoJSvR
a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:UITcYmaAsenb+GLzqkPaVg3wHv8bocI1bPGMMUvR
a=sendrecv
<------------->
— (14 headers 19 lines) —
Sending to :55178 (NAT)
Using INVITE request as basis request - 980e9aff5406479e98f240e1dd603abf
Found peer ‘5001’ for ‘5001’ from :55178
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
Found RTP audio format 113
Found RTP audio format 104
Found RTP audio format 103
Found RTP audio format 9
Found RTP audio format 0
Found RTP audio format 8
Found RTP audio format 101
Found unknown media description format opus for ID 113
Found unknown media description format speex for ID 104
Found audio description format speex for ID 103
Found audio description format G722 for ID 9
Found audio description format PCMU for ID 0
Found audio description format PCMA for ID 8
Found audio description format telephone-event for ID 101
Capabilities: us - 0xe (gsm|ulaw|alaw), peer - audio=0x20000100c (ulaw|alaw|speex16|g722)/video=0x0 (nothing)/text=0x0 (nothing), combined - 0xc (ulaw|alaw)
Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|)
Peer audio RTP is at port 172.16.30.221:10008
Looking for 5002 in from-internal (domain )
list_route: hop: sip:17243806@172.16.30.221:5061;transport=tls

<— Transmitting (NAT) to :55178 —>
SIP/2.0 100 Trying
Via: SIP/2.0/TLS 172.16.30.221:55178;branch=z9hG4bKPjd8fcf2ea6d194aad91c38648f66fd026;alias;received=;rport=55178
From: “curtis” <sip:5001@>;tag=21aade99df194af4b28b2202cd4a4594
To: <sip:5002@>
Call-ID: 980e9aff5406479e98f240e1dd603abf
CSeq: 28034 INVITE
Server: FPBX-2.11.0(1.8.26.1)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Contact: <sip:5002@:5061;transport=TLS>
Content-Length: 0

<------------>
– Executing [5002@from-internal:1] Set(“SIP/5001-00000012”, “__RINGTIMER=15”) in new stack
– Executing [5002@from-internal:2] Macro(“SIP/5001-00000012”, “exten-vm,novm,5002,0,0,0”) in new stack
– Executing [s@macro-exten-vm:1] Macro(“SIP/5001-00000012”, “user-callerid,”) in new stack
– Executing [s@macro-user-callerid:1] Set(“SIP/5001-00000012”, “TOUCH_MONITOR=1396682858.18”) in new stack
– Executing [s@macro-user-callerid:2] Set(“SIP/5001-00000012”, “AMPUSER=5001”) in new stack
– Executing [s@macro-user-callerid:3] GotoIf(“SIP/5001-00000012”, “0?report”) in new stack
– Executing [s@macro-user-callerid:4] ExecIf(“SIP/5001-00000012”, “1?Set(REALCALLERIDNUM=5001)”) in new stack
– Executing [s@macro-user-callerid:5] Set(“SIP/5001-00000012”, “AMPUSER=5001”) in new stack
– Executing [s@macro-user-callerid:6] GotoIf(“SIP/5001-00000012”, “0?limit”) in new stack
– Executing [s@macro-user-callerid:7] Set(“SIP/5001-00000012”, “AMPUSERCIDNAME=Curtis”) in new stack
– Executing [s@macro-user-callerid:8] GotoIf(“SIP/5001-00000012”, “0?report”) in new stack
– Executing [s@macro-user-callerid:9] Set(“SIP/5001-00000012”, “AMPUSERCID=5001”) in new stack
– Executing [s@macro-user-callerid:10] Set(“SIP/5001-00000012”, “__DIAL_OPTIONS=tr”) in new stack
– Executing [s@macro-user-callerid:11] Set(“SIP/5001-00000012”, “CALLERID(all)=“Curtis” <5001>”) in new stack
– Executing [s@macro-user-callerid:12] GotoIf(“SIP/5001-00000012”, “0?limit”) in new stack
– Executing [s@macro-user-callerid:13] ExecIf(“SIP/5001-00000012”, “0?Set(GROUP(concurrency_limit)=5001)”) in new stack
– Executing [s@macro-user-callerid:14] ExecIf(“SIP/5001-00000012”, “0?Set(CHANNEL(language)=)”) in new stack
– Executing [s@macro-user-callerid:15] GosubIf(“SIP/5001-00000012”, “7?sub-ccss,s,1(macro-exten-vm,5002)”) in new stack
– Executing [s@sub-ccss:1] ExecIf(“SIP/5001-00000012”, “0?Return()”) in new stack
– Executing [s@sub-ccss:2] Set(“SIP/5001-00000012”, “CCSS_SETUP=TRUE”) in new stack
– Executing [s@sub-ccss:3] GosubIf(“SIP/5001-00000012”, “0?monitor_config,1(macro-exten-vm,5002):monitor_default,1(macro-exten-vm,5002)”) in new stack
– Executing [monitor_default@sub-ccss:1] GotoIf(“SIP/5001-00000012”, “1?is_exten”) in new stack
– Goto (sub-ccss,monitor_default,4)
– Executing [monitor_default@sub-ccss:4] Set(“SIP/5001-00000012”, “CALLCOMPLETION(cc_monitor_policy)=generic”) in new stack
– Executing [monitor_default@sub-ccss:5] Set(“SIP/5001-00000012”, “CALLCOMPLETION(cc_max_monitors)=5”) in new stack
– Executing [monitor_default@sub-ccss:6] Return(“SIP/5001-00000012”, “TRUE”) in new stack
– Executing [s@sub-ccss:4] GosubIf(“SIP/5001-00000012”, “7?agent_config,1():agent_default,1()”) in new stack
– Executing [agent_config@sub-ccss:1] Set(“SIP/5001-00000012”, “CALLCOMPLETION(cc_agent_policy)=generic”) in new stack
– Executing [agent_config@sub-ccss:2] Set(“SIP/5001-00000012”, “CALLCOMPLETION(cc_offer_timer)=30”) in new stack
– Executing [agent_config@sub-ccss:3] Set(“SIP/5001-00000012”, “CALLCOMPLETION(ccbs_available_timer)=”) in new stack
– Executing [agent_config@sub-ccss:4] Set(“SIP/5001-00000012”, “CALLCOMPLETION(ccnr_available_timer)=”) in new stack
– Executing [agent_config@sub-ccss:5] Set(“SIP/5001-00000012”, “CALLCOMPLETION(cc_callback_macro)=ccss-default”) in new stack
– Executing [agent_config@sub-ccss:6] ExecIf(“SIP/5001-00000012”, “1?Set(CALLCOMPLETION(cc_recall_timer)=)”) in new stack
– Executing [agent_config@sub-ccss:7] ExecIf(“SIP/5001-00000012”, “1?Set(CALLCOMPLETION(cc_max_agents)=)”) in new stack
– Executing [agent_config@sub-ccss:8] ExecIf(“SIP/5001-00000012”, “0?Set(CALLCOMPLETION(cc_agent_dialstring)=Local/5001_5002@from-ccss-)”) in new stack
– Executing [agent_config@sub-ccss:9] Set(“SIP/5001-00000012”, “CALLCOMPLETION(cc_callback_macro)=ccss-default”) in new stack
– Executing [agent_config@sub-ccss:10] Return(“SIP/5001-00000012”, “”) in new stack
– Executing [s@sub-ccss:5] Set(“SIP/5001-00000012”, “DB(AMPUSER/5001/ccss/last_number)=5002”) in new stack
– Executing [s@sub-ccss:6] Return(“SIP/5001-00000012”, “”) in new stack
– Executing [s@macro-user-callerid:16] GotoIf(“SIP/5001-00000012”, “0?continue”) in new stack
– Executing [s@macro-user-callerid:17] Set(“SIP/5001-00000012”, “__TTL=64”) in new stack
– Executing [s@macro-user-callerid:18] GotoIf(“SIP/5001-00000012”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,29)
– Executing [s@macro-user-callerid:29] Set(“SIP/5001-00000012”, “CALLERID(number)=5001”) in new stack
– Executing [s@macro-user-callerid:30] Set(“SIP/5001-00000012”, “CALLERID(name)=Curtis”) in new stack
– Executing [s@macro-user-callerid:31] Set(“SIP/5001-00000012”, “CDR(cnum)=5001”) in new stack
– Executing [s@macro-user-callerid:32] Set(“SIP/5001-00000012”, “CDR(cnam)=Curtis”) in new stack
– Executing [s@macro-user-callerid:33] Set(“SIP/5001-00000012”, “CHANNEL(language)=en”) in new stack
– Executing [s@macro-exten-vm:2] Set(“SIP/5001-00000012”, “RingGroupMethod=none”) in new stack
– Executing [s@macro-exten-vm:3] Set(“SIP/5001-00000012”, “__EXTTOCALL=5002”) in new stack
– Executing [s@macro-exten-vm:4] Set(“SIP/5001-00000012”, “__PICKUPMARK=5002”) in new stack
– Executing [s@macro-exten-vm:5] Set(“SIP/5001-00000012”, “RT=”) in new stack
– Executing [s@macro-exten-vm:6] ExecIf(“SIP/5001-00000012”, “0?Macro(vm,novm,DIRECTDIAL,)”) in new stack
– Executing [s@macro-exten-vm:7] ExecIf(“SIP/5001-00000012”, “0?MacroExit()”) in new stack
– Executing [s@macro-exten-vm:8] Gosub(“SIP/5001-00000012”, “sub-record-check,s,1(exten,5002,)”) in new stack
– Executing [s@sub-record-check:1] Set(“SIP/5001-00000012”, “REC_POLICY_MODE_SAVE=”) in new stack
– Executing [s@sub-record-check:2] GotoIf(“SIP/5001-00000012”, “1?check”) in new stack
– Goto (sub-record-check,s,7)
– Executing [s@sub-record-check:7] Set(“SIP/5001-00000012”, “__MON_FMT=wav”) in new stack
– Executing [s@sub-record-check:8] GotoIf(“SIP/5001-00000012”, “1?next”) in new stack
– Goto (sub-record-check,s,11)
– Executing [s@sub-record-check:11] ExecIf(“SIP/5001-00000012”, “0?Return()”) in new stack
– Executing [s@sub-record-check:12] ExecIf(“SIP/5001-00000012”, “0?Set(__REC_POLICY_MODE=)”) in new stack
– Executing [s@sub-record-check:13] GotoIf(“SIP/5001-00000012”, “0?exten,1”) in new stack
– Executing [s@sub-record-check:14] Set(“SIP/5001-00000012”, “__REC_STATUS=INITIALIZED”) in new stack
– Executing [s@sub-record-check:15] Set(“SIP/5001-00000012”, “NOW=1396682858”) in new stack
– Executing [s@sub-record-check:16] Set(“SIP/5001-00000012”, “__DAY=05”) in new stack
– Executing [s@sub-record-check:17] Set(“SIP/5001-00000012”, “__MONTH=04”) in new stack
– Executing [s@sub-record-check:18] Set(“SIP/5001-00000012”, “__YEAR=2014”) in new stack
– Executing [s@sub-record-check:19] Set(“SIP/5001-00000012”, “__TIMESTR=20140405-022738”) in new stack
– Executing [s@sub-record-check:20] Set(“SIP/5001-00000012”, “__FROMEXTEN=5001”) in new stack
– Executing [s@sub-record-check:21] Set(“SIP/5001-00000012”, “__CALLFILENAME=exten-5002-5001-20140405-022738-1396682858.18”) in new stack
– Executing [s@sub-record-check:22] Goto(“SIP/5001-00000012”, “exten,1”) in new stack
– Goto (sub-record-check,exten,1)
– Executing [exten@sub-record-check:1] GotoIf(“SIP/5001-00000012”, “0?callee”) in new stack
– Executing [exten@sub-record-check:2] Set(“SIP/5001-00000012”, “__REC_POLICY_MODE=dontcare”) in new stack
– Executing [exten@sub-record-check:3] GotoIf(“SIP/5001-00000012”, “1?caller”) in new stack
– Goto (sub-record-check,exten,10)
– Executing [exten@sub-record-check:10] Set(“SIP/5001-00000012”, “__REC_POLICY_MODE=dontcare”) in new stack
– Executing [exten@sub-record-check:11] GosubIf(“SIP/5001-00000012”, “0?record,1(exten,5002,5001)”) in new stack
– Executing [exten@sub-record-check:12] Return(“SIP/5001-00000012”, “”) in new stack
– Executing [s@macro-exten-vm:9] Macro(“SIP/5001-00000012”, “dial-one,tr,5002”) in new stack
– Executing [s@macro-dial-one:1] Set(“SIP/5001-00000012”, “DEXTEN=5002”) in new stack
– Executing [s@macro-dial-one:2] Set(“SIP/5001-00000012”, “DIALSTATUS_CW=”) in new stack
– Executing [s@macro-dial-one:3] GosubIf(“SIP/5001-00000012”, “0?screen,1()”) in new stack
– Executing [s@macro-dial-one:4] GosubIf(“SIP/5001-00000012”, “0?cf,1()”) in new stack
– Executing [s@macro-dial-one:5] GotoIf(“SIP/5001-00000012”, “1?skip1”) in new stack
– Goto (macro-dial-one,s,8)
– Executing [s@macro-dial-one:8] GotoIf(“SIP/5001-00000012”, “0?nodial”) in new stack
– Executing [s@macro-dial-one:9] GotoIf(“SIP/5001-00000012”, “0?continue”) in new stack
– Executing [s@macro-dial-one:10] Set(“SIP/5001-00000012”, “EXTHASCW=ENABLED”) in new stack
– Executing [s@macro-dial-one:11] GotoIf(“SIP/5001-00000012”, “0?next1:cwinusebusy”) in new stack
– Goto (macro-dial-one,s,23)
– Executing [s@macro-dial-one:23] GotoIf(“SIP/5001-00000012”, “1?next3:continue”) in new stack
– Goto (macro-dial-one,s,24)
– Executing [s@macro-dial-one:24] ExecIf(“SIP/5001-00000012”, “0?Set(DIALSTATUS_CW=BUSY)”) in new stack
– Executing [s@macro-dial-one:25] GotoIf(“SIP/5001-00000012”, “0?nodial”) in new stack
– Executing [s@macro-dial-one:26] GosubIf(“SIP/5001-00000012”, “1?dstring,1():dlocal,1()”) in new stack
– Executing [dstring@macro-dial-one:1] Set(“SIP/5001-00000012”, “DSTRING=”) in new stack
– Executing [dstring@macro-dial-one:2] Set(“SIP/5001-00000012”, “DEVICES=5002”) in new stack
– Executing [dstring@macro-dial-one:3] ExecIf(“SIP/5001-00000012”, “0?Return()”) in new stack
– Executing [dstring@macro-dial-one:4] ExecIf(“SIP/5001-00000012”, “0?Set(DEVICES=002)”) in new stack
– Executing [dstring@macro-dial-one:5] Set(“SIP/5001-00000012”, “LOOPCNT=1”) in new stack
– Executing [dstring@macro-dial-one:6] Set(“SIP/5001-00000012”, “ITER=1”) in new stack
– Executing [dstring@macro-dial-one:7] Set(“SIP/5001-00000012”, “THISDIAL=SIP/5002”) in new stack
– Executing [dstring@macro-dial-one:8] GosubIf(“SIP/5001-00000012”, “1?zap2dahdi,1()”) in new stack
– Executing [zap2dahdi@macro-dial-one:1] ExecIf(“SIP/5001-00000012”, “0?Return()”) in new stack
– Executing [zap2dahdi@macro-dial-one:2] Set(“SIP/5001-00000012”, “NEWDIAL=”) in new stack
– Executing [zap2dahdi@macro-dial-one:3] Set(“SIP/5001-00000012”, “LOOPCNT2=1”) in new stack
– Executing [zap2dahdi@macro-dial-one:4] Set(“SIP/5001-00000012”, “ITER2=1”) in new stack
– Executing [zap2dahdi@macro-dial-one:5] Set(“SIP/5001-00000012”, “THISPART2=SIP/5002”) in new stack
– Executing [zap2dahdi@macro-dial-one:6] ExecIf(“SIP/5001-00000012”, “0?Set(THISPART2=DAHDI/5002)”) in new stack
– Executing [zap2dahdi@macro-dial-one:7] Set(“SIP/5001-00000012”, “NEWDIAL=SIP/5002&”) in new stack
– Executing [zap2dahdi@macro-dial-one:8] Set(“SIP/5001-00000012”, “ITER2=2”) in new stack
– Executing [zap2dahdi@macro-dial-one:9] GotoIf(“SIP/5001-00000012”, “0?begin2”) in new stack
– Executing [zap2dahdi@macro-dial-one:10] Set(“SIP/5001-00000012”, “THISDIAL=SIP/5002”) in new stack
– Executing [zap2dahdi@macro-dial-one:11] Return(“SIP/5001-00000012”, “”) in new stack
– Executing [dstring@macro-dial-one:9] Set(“SIP/5001-00000012”, “DSTRING=SIP/5002&”) in new stack
– Executing [dstring@macro-dial-one:10] Set(“SIP/5001-00000012”, “ITER=2”) in new stack
– Executing [dstring@macro-dial-one:11] GotoIf(“SIP/5001-00000012”, “0?begin”) in new stack
– Executing [dstring@macro-dial-one:12] Set(“SIP/5001-00000012”, “DSTRING=SIP/5002”) in new stack
– Executing [dstring@macro-dial-one:13] Return(“SIP/5001-00000012”, “”) in new stack
– Executing [s@macro-dial-one:27] GotoIf(“SIP/5001-00000012”, “0?nodial”) in new stack
– Executing [s@macro-dial-one:28] GotoIf(“SIP/5001-00000012”, “0?skiptrace”) in new stack
– Executing [s@macro-dial-one:29] GosubIf(“SIP/5001-00000012”, “1?ctset,1():ctclear,1()”) in new stack
– Executing [ctset@macro-dial-one:1] Set(“SIP/5001-00000012”, “DB(CALLTRACE/5002)=5001”) in new stack
– Executing [ctset@macro-dial-one:2] Return(“SIP/5001-00000012”, “”) in new stack
– Executing [s@macro-dial-one:30] Set(“SIP/5001-00000012”, “D_OPTIONS=tr”) in new stack
– Executing [s@macro-dial-one:31] ExecIf(“SIP/5001-00000012”, “0?SIPAddHeader(Alert-Info: )”) in new stack
– Executing [s@macro-dial-one:32] ExecIf(“SIP/5001-00000012”, “0?SIPAddHeader()”) in new stack
– Executing [s@macro-dial-one:33] ExecIf(“SIP/5001-00000012”, “0?Set(CHANNEL(musicclass)=)”) in new stack
– Executing [s@macro-dial-one:34] GosubIf(“SIP/5001-00000012”, “0?qwait,1()”) in new stack
– Executing [s@macro-dial-one:35] Set(“SIP/5001-00000012”, “__CWIGNORE=”) in new stack
– Executing [s@macro-dial-one:36] Set(“SIP/5001-00000012”, “__KEEPCID=TRUE”) in new stack
– Executing [s@macro-dial-one:37] GotoIf(“SIP/5001-00000012”, “0?usegoto,1”) in new stack
– Executing [s@macro-dial-one:38] GotoIf(“SIP/5001-00000012”, “0?godial”) in new stack
– Executing [s@macro-dial-one:39] Set(“SIP/5001-00000012”, “CONNECTEDLINE(name,i)=Vincent”) in new stack
– Executing [s@macro-dial-one:40] Set(“SIP/5001-00000012”, “CONNECTEDLINE(num)=5002”) in new stack
– Executing [s@macro-dial-one:41] Set(“SIP/5001-00000012”, “D_OPTIONS=trI”) in new stack
– Executing [s@macro-dial-one:42] Dial(“SIP/5001-00000012”, “SIP/5002,trI”) in new stack
Really destroying SIP dialog ‘40dc3404676c56e01aa3fb5d76e6d113@192.168.40.109:5060’ Method: INVITE
== Everyone is busy/congested at this time (1:0/0/1)
– Executing [s@macro-dial-one:43] ExecIf(“SIP/5001-00000012”, “0?MacroExit()”) in new stack
– Executing [s@macro-dial-one:44] ExecIf(“SIP/5001-00000012”, “0?Set(DIALSTATUS=)”) in new stack
– Executing [s@macro-dial-one:45] GosubIf(“SIP/5001-00000012”, “0?s-CHANUNAVAIL,1()”) in new stack
– Executing [s@macro-dial-one:46] MacroExit(“SIP/5001-00000012”, “”) in new stack
– Executing [s@macro-exten-vm:10] Set(“SIP/5001-00000012”, “SV_DIALSTATUS=CHANUNAVAIL”) in new stack
– Executing [s@macro-exten-vm:11] GosubIf(“SIP/5001-00000012”, “0?docfu,1()”) in new stack
– Executing [s@macro-exten-vm:12] GosubIf(“SIP/5001-00000012”, “0?docfb,1()”) in new stack
– Executing [s@macro-exten-vm:13] Set(“SIP/5001-00000012”, “DIALSTATUS=CHANUNAVAIL”) in new stack
– Executing [s@macro-exten-vm:14] ExecIf(“SIP/5001-00000012”, “0?MacroExit()”) in new stack
– Executing [s@macro-exten-vm:15] GotoIf(“SIP/5001-00000012”, “1?s-CHANUNAVAIL,1”) in new stack
– Goto (macro-exten-vm,s-CHANUNAVAIL,1)
– Executing [s-CHANUNAVAIL@macro-exten-vm:1] GotoIf(“SIP/5001-00000012”, “0?exit,1”) in new stack
– Executing [s-CHANUNAVAIL@macro-exten-vm:2] PlayTones(“SIP/5001-00000012”, “congestion”) in new stack
– Executing [s-CHANUNAVAIL@macro-exten-vm:3] Congestion(“SIP/5001-00000012”, “10”) in new stack

<— Reliably Transmitting (NAT) to :55178 —>
SIP/2.0 503 Service Unavailable
Via: SIP/2.0/TLS 172.16.30.221:55178;branch=z9hG4bKPjd8fcf2ea6d194aad91c38648f66fd026;alias;received=;rport=55178
From: “curtis” <sip:5001@>;tag=21aade99df194af4b28b2202cd4a4594
To: <sip:5002@>;tag=as4976bc5e
Call-ID: 980e9aff5406479e98f240e1dd603abf
CSeq: 28034 INVITE
Server: FPBX-2.11.0(1.8.26.1)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
X-Asterisk-HangupCause: Subscriber absent
X-Asterisk-HangupCauseCode: 20
Content-Length: 0

<------------>
== Spawn extension (macro-exten-vm, s-CHANUNAVAIL, 3) exited non-zero on ‘SIP/5001-00000012’ in macro ‘exten-vm’
== Spawn extension (from-internal, 5002, 2) exited non-zero on ‘SIP/5001-00000012’
– Executing [h@from-internal:1] Hangup(“SIP/5001-00000012”, “”) in new stack
== Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/5001-00000012’

<— SIP read from TLS::55178 —>
ACK sip:5002@ SIP/2.0
Via: SIP/2.0/TLS 172.16.30.221:55178;rport;branch=z9hG4bKPjd8fcf2ea6d194aad91c38648f66fd026;alias
Max-Forwards: 70
From: “curtis” <sip:5001@>;tag=21aade99df194af4b28b2202cd4a4594
To: <sip:5002@>;tag=as4976bc5e
Call-ID: 980e9aff5406479e98f240e1dd603abf
CSeq: 28034 ACK
User-Agent: Blink 0.7.0 (Windows)
Content-Length: 0

<------------->
— (9 headers 0 lines) —
Really destroying SIP dialog ‘980e9aff5406479e98f240e1dd603abf’ Method: INVITE

<— SIP read from TLS::55178 —>
PUBLISH sip:5001@ SIP/2.0
Via: SIP/2.0/TLS 172.16.30.221:55178;rport;branch=z9hG4bKPja8170bc1da214ab0a691853646ee0c12;alias
Max-Forwards: 70
From: “curtis” <sip:5001@>;tag=0ab5b8a065674bf4804c6c744e4d24de
To: “curtis” <sip:5001@>
Call-ID: ccca4efb24514abd90827a9f77716df4
CSeq: 1 PUBLISH
Event: presence
Expires: 600
User-Agent: Blink 0.7.0 (Windows)
Content-Type: application/pidf+xml
Content-Length: 1834

<?xml version='1.0' encoding='UTF-8'?>

openagp-pidf:extendedavailable</agp-pidf:extended>caps:servcapscaps:audiotrue</caps:audio>caps:messagetrue</caps:message>caps:textfalse</caps:text>agp-caps:file-transferfalse</agp-caps:file-transfer>agp-caps:screen-sharing-serverfalse</agp-caps:screen-sharing-server>agp-caps:screen-sharing-clientfalse</agp-caps:screen-sharing-client></caps:servcaps><c:display-name>curtis</c:display-name><agp-pidf:device-info id=“cf950620-b287-4bd9-8ff4-2d0281ff4c37”>agp-pidf:descriptionPmttT400-PC</agp-pidf:description>agp-pidf:user-agentBlink 0.7.0 (Windows)</agp-pidf:user-agent>agp-pidf:time-offset1140</agp-pidf:time-offset></agp-pidf:device-info><rpid:user-input idle-threshold=“600”>active</rpid:user-input>dm:deviceIDcf950620-b287-4bd9-8ff4-2d0281ff4c37</dm:deviceID>sip%3A5001%402014-04-06T00:40:28.016000-05:00<dm:person id=“PID-5c5bd08f01887e7fb33f4b881a80b4e3”>rpid:activitiesrpid:otheravailable</rpid:other></rpid:activities>dm:timestamp2014-04-06T00:40:28.016000-05:00</dm:timestamp></dm:person><dm:device id=“DID-cf950620-b287-4bd9-8ff4-2d0281ff4c37”>dm:deviceIDcf950620-b287-4bd9-8ff4-2d0281ff4c37</dm:deviceID>dm:noteBlink 0.7.0 (Windows) at PmttT400-PC</dm:note>dm:timestamp2014-04-06T00:40:28.016000-05:00</dm:timestamp></dm:device>
<------------->
— (12 headers 2 lines) —

<— Transmitting (NAT) to :55178 —>
SIP/2.0 489 Bad Event
Via: SIP/2.0/TLS 172.16.30.221:55178;branch=z9hG4bKPja8170bc1da214ab0a691853646ee0c12;alias;received=;rport=55178
From: “curtis” <sip:5001@>;tag=0ab5b8a065674bf4804c6c744e4d24de
To: “curtis” <sip:5001@>;tag=as6e61979f
Call-ID: ccca4efb24514abd90827a9f77716df4
CSeq: 1 PUBLISH
Server: FPBX-2.11.0(1.8.26.1)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Content-Length: 0

<------------>
Really destroying SIP dialog ‘ccca4efb24514abd90827a9f77716df4’ Method: PUBLISH
Reliably Transmitting (NAT) to :54884:
OPTIONS sip:17243806@172.16.30.221:5061;transport=tls SIP/2.0
Via: SIP/2.0/TLS :5061;branch=z9hG4bK31bfecf9;rport
Max-Forwards: 70
From: “Unknown” <sip:Unknown@>;tag=as654071db
To: sip:17243806@172.16.30.221:5061;transport=tls
Contact: <sip:Unknown@:5061;transport=TLS>
Call-ID: 04637ff66baae17174e3b39c270473d8@:5061
CSeq: 102 OPTIONS
User-Agent: FPBX-2.11.0(1.8.26.1)
Date: Sat, 05 Apr 2014 07:27:40 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Content-Length: 0

Reliably Transmitting (NAT) to :50462:
OPTIONS sip:81659274@172.16.30.220:50459;transport=tls SIP/2.0
Via: SIP/2.0/TLS :5061;branch=z9hG4bK3cd72ea5;rport
Max-Forwards: 70
From: “Unknown” <sip:Unknown@>;tag=as64c375c9
To: sip:81659274@172.16.30.220:50459;transport=tls
Contact: <sip:Unknown@:5061;transport=TLS>
Call-ID: 213a098424f1868227e25e0a1f0ea8b3@:5061
CSeq: 102 OPTIONS
User-Agent: FPBX-2.11.0(1.8.26.1)
Date: Sat, 05 Apr 2014 07:27:43 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Content-Length: 0

Really destroying SIP dialog ‘04637ff66baae17174e3b39c270473d8@:5061’ Method: OPTIONS
Really destroying SIP dialog ‘213a098424f1868227e25e0a1f0ea8b3@:5061’ Method: OPTIONS

<— SIP read from TLS::50703 —>
REGISTER sip: SIP/2.0
Via: SIP/2.0/TLS 172.16.30.220:50703;rport;branch=z9hG4bKPj359ffb9aacd2469fae485aaa18cc7349;alias
Max-Forwards: 70
From: “vincent” <sip:5002@>;tag=ed1db5ab94f540d1af63c9c3e63480ba
To: “vincent” <sip:5002@>
Contact: sip:81659274@172.16.30.220:50459;transport=tls;+sip.instance="urn:uuid:5d015afa-feb5-426d-a911-f8d8bafddd6a"
Call-ID: 03106be814184a0eb4189cf28fff1f77
CSeq: 5 REGISTER
Expires: 600
Supported: gruu
User-Agent: Blink 0.7.0 (Windows)
Content-Length: 0

<------------->
— (12 headers 0 lines) —
Sending to :50703 (NAT)

<— Transmitting (NAT) to :50703 —>
SIP/2.0 401 Unauthorized
Via: SIP/2.0/TLS 172.16.30.220:50703;branch=z9hG4bKPj359ffb9aacd2469fae485aaa18cc7349;alias;received=;rport=50703
From: “vincent” <sip:5002@>;tag=ed1db5ab94f540d1af63c9c3e63480ba
To: “vincent” <sip:5002@>;tag=as29d8ea3a
Call-ID: 03106be814184a0eb4189cf28fff1f77
CSeq: 5 REGISTER
Server: FPBX-2.11.0(1.8.26.1)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
WWW-Authenticate: Digest algorithm=MD5, realm=“asterisk”, nonce="489060c0"
Content-Length: 0

<------------>
Scheduling destruction of SIP dialog ‘03106be814184a0eb4189cf28fff1f77’ in 32000 ms (Method: REGISTER)

<— SIP read from TLS::50703 —>
REGISTER sip: SIP/2.0
Via: SIP/2.0/TLS 172.16.30.220:50703;rport;branch=z9hG4bKPj88d8913185fb4e8ea4471d8d3181ff8b;alias
Max-Forwards: 70
From: “vincent” <sip:5002@>;tag=ed1db5ab94f540d1af63c9c3e63480ba
To: “vincent” <sip:5002@>
Contact: sip:81659274@172.16.30.220:50459;transport=tls;+sip.instance="urn:uuid:5d015afa-feb5-426d-a911-f8d8bafddd6a"
Call-ID: 03106be814184a0eb4189cf28fff1f77
CSeq: 6 REGISTER
Expires: 600
Supported: gruu
User-Agent: Blink 0.7.0 (Windows)
Authorization: Digest username=“5002”, realm=“asterisk”, nonce=“489060c0”, uri=“sip:”, response=“fe1dcbde323aec9b4fc2877e117b152f”, algorithm=MD5
Content-Length: 0

<------------->
— (13 headers 0 lines) —
Sending to :50703 (NAT)
– Registered SIP ‘5002’ at :50703
Reliably Transmitting (NAT) to :50703:
OPTIONS sip:81659274@172.16.30.220:50459;transport=tls SIP/2.0
Via: SIP/2.0/TLS :5061;branch=z9hG4bK137b474d;rport
Max-Forwards: 70
From: “Unknown” <sip:Unknown@>;tag=as35ba9f9b
To: sip:81659274@172.16.30.220:50459;transport=tls
Contact: <sip:Unknown@:5061;transport=TLS>
Call-ID: 4aa414da1ee70db14ae863d4444012e8@:5061
CSeq: 102 OPTIONS
User-Agent: FPBX-2.11.0(1.8.26.1)
Date: Sat, 05 Apr 2014 07:27:50 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Content-Length: 0

<— Transmitting (NAT) to :50703 —>
SIP/2.0 200 OK
Via: SIP/2.0/TLS 172.16.30.220:50703;branch=z9hG4bKPj88d8913185fb4e8ea4471d8d3181ff8b;alias;received=;rport=50703
From: “vincent” <sip:5002@>;tag=ed1db5ab94f540d1af63c9c3e63480ba
To: “vincent” <sip:5002@>;tag=as29d8ea3a
Call-ID: 03106be814184a0eb4189cf28fff1f77
CSeq: 6 REGISTER
Server: FPBX-2.11.0(1.8.26.1)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Expires: 600
Contact: sip:81659274@172.16.30.220:50459;transport=tls;expires=600
Date: Sat, 05 Apr 2014 07:27:50 GMT
Content-Length: 0

<------------>
Reliably Transmitting (NAT) to :50608:
NOTIFY sip:81659274@172.16.30.220:50459;transport=tls SIP/2.0
Via: SIP/2.0/TLS :5061;branch=z9hG4bK62c189f9;rport
Max-Forwards: 70
Route: sip:81659274@172.16.30.220:50459;transport=tls
From: “Unknown” <sip:Unknown@>;tag=as10a4fcd4
To: sip:81659274@172.16.30.220:50459;transport=tls;tag=f85a11f359b943a98a95655208aeec06
Contact: <sip:Unknown@:5061;transport=TLS>
Call-ID: d4a42fa14035408f9092accea6864344
CSeq: 103 NOTIFY
User-Agent: FPBX-2.11.0(1.8.26.1)
Event: message-summary
Content-Type: application/simple-message-summary
Subscription-State: active
Content-Length: 101

Messages-Waiting: no
Message-Account: sip:*97@;transport=TLS
Voice-Message: 0/0 (0/0)

Scheduling destruction of SIP dialog ‘03106be814184a0eb4189cf28fff1f77’ in 32000 ms (Method: REGISTER)

<— SIP read from TLS::50703 —>
SIP/2.0 200 OK
Via: SIP/2.0/TLS :5061;rport=5061;received=;branch=z9hG4bK137b474d
Call-ID: 4aa414da1ee70db14ae863d4444012e8@:5061
From: “Unknown” <sip:Unknown@>;tag=as35ba9f9b
To: sip:81659274@172.16.30.220;tag=z9hG4bK137b474d
CSeq: 102 OPTIONS
Allow: SUBSCRIBE, NOTIFY, PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, MESSAGE, REFER
Accept: application/sdp, application/conference-info+xml, application/simple-message-summary, multipart/related, application/rlmi+xml, application/dialog-info+xml, multipart/related, application/rlmi+xml, application/pidf+xml, application/watcherinfo+xml, application/xcap-diff+xml, application/watcherinfo+xml, message/sipfrag;version=2.0
Supported: 100rel, replaces, norefersub, gruu
Server: Blink 0.7.0 (Windows)
Content-Length: 0

<------------->
— (11 headers 0 lines) —
Really destroying SIP dialog ‘4aa414da1ee70db14ae863d4444012e8@:5061’ Method: OPTIONS
Reliably Transmitting (NAT) to :54884:
OPTIONS sip:17243806@172.16.30.221:5061;transport=tls SIP/2.0
Via: SIP/2.0/TLS :5061;branch=z9hG4bK44989131;rport
Max-Forwards: 70
From: “Unknown” <sip:Unknown@>;tag=as0352c8d3
To: sip:17243806@172.16.30.221:5061;transport=tls
Contact: <sip:Unknown@:5061;transport=TLS>
Call-ID: 1202479e77fccea0114a19034c1617e1@:5061
CSeq: 102 OPTIONS
User-Agent: FPBX-2.11.0(1.8.26.1)
Date: Sat, 05 Apr 2014 07:27:54 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Content-Length: 0

<— SIP read from TLS::55178 —>
REGISTER sip: SIP/2.0
Via: SIP/2.0/TLS 172.16.30.221:55178;rport;branch=z9hG4bKPj962de725a06848fcb02e03c350a8c598;alias
Max-Forwards: 70
From: “curtis” <sip:5001@>;tag=17f298a3bebf4626b8fdb59db5a05648
To: “curtis” <sip:5001@>
Contact: sip:17243806@172.16.30.221:5061;transport=tls;+sip.instance="urn:uuid:cf950620-b287-4bd9-8ff4-2d0281ff4c37"
Call-ID: 6d96ff1a0736431ea01e736d83125ce5
CSeq: 5 REGISTER
Expires: 600
Supported: gruu
User-Agent: Blink 0.7.0 (Windows)
Content-Length: 0

<------------->
— (12 headers 0 lines) —
Sending to :55178 (NAT)

<— Transmitting (NAT) to :55178 —>
SIP/2.0 401 Unauthorized
Via: SIP/2.0/TLS 172.16.30.221:55178;branch=z9hG4bKPj962de725a06848fcb02e03c350a8c598;alias;received=;rport=55178
From: “curtis” <sip:5001@>;tag=17f298a3bebf4626b8fdb59db5a05648
To: “curtis” <sip:5001@>;tag=as0dff4b91
Call-ID: 6d96ff1a0736431ea01e736d83125ce5
CSeq: 5 REGISTER
Server: FPBX-2.11.0(1.8.26.1)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
WWW-Authenticate: Digest algorithm=MD5, realm=“asterisk”, nonce="10d6b9e6"
Content-Length: 0

<------------>
Scheduling destruction of SIP dialog ‘6d96ff1a0736431ea01e736d83125ce5’ in 32000 ms (Method: REGISTER)

<— SIP read from TLS::55178 —>
REGISTER sip: SIP/2.0
Via: SIP/2.0/TLS 172.16.30.221:55178;rport;branch=z9hG4bKPjbb4c1b595ac74907ba5942b016177269;alias
Max-Forwards: 70
From: “curtis” <sip:5001@>;tag=17f298a3bebf4626b8fdb59db5a05648
To: “curtis” <sip:5001@>
Contact: sip:17243806@172.16.30.221:5061;transport=tls;+sip.instance="urn:uuid:cf950620-b287-4bd9-8ff4-2d0281ff4c37"
Call-ID: 6d96ff1a0736431ea01e736d83125ce5
CSeq: 6 REGISTER
Expires: 600
Supported: gruu
User-Agent: Blink 0.7.0 (Windows)
Authorization: Digest username=“5001”, realm=“asterisk”, nonce=“10d6b9e6”, uri=“sip:”, response=“5f5a3c83eafe1e4a4d51abb4ad1837ac”, algorithm=MD5
Content-Length: 0

<------------->
— (13 headers 0 lines) —
Sending to :55178 (NAT)
– Registered SIP ‘5001’ at :55178
Really destroying SIP dialog ‘1202479e77fccea0114a19034c1617e1@:5061’ Method: OPTIONS
Reliably Transmitting (NAT) to :55178:
PTIONS sip:17243806@172.16.30.221:5061;transport=tls SIP/2.0
Via: SIP/2.0/TLS :5061;branch=z9hG4bK41bfec2b;rport
Max-Forwards: 70
From: “Unknown” <sip:Unknown@>;tag=as0e25d8ba
To: sip:17243806@172.16.30.221:5061;transport=tls
Contact: <sip:Unknown@:5061;transport=TLS>
Call-ID: 0d91ed2a30a4973c41ebaa890ab27131@:5061
CSeq: 102 OPTIONS
User-Agent: FPBX-2.11.0(1.8.26.1)
Date: Sat, 05 Apr 2014 07:27:57 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Content-Length: 0

<— Transmitting (NAT) to :55178 —>
SIP/2.0 200 OK
Via: SIP/2.0/TLS 172.16.30.221:55178;branch=z9hG4bKPjbb4c1b595ac74907ba5942b016177269;alias;received=;rport=55178
From: “curtis” <sip:5001@>;tag=17f298a3bebf4626b8fdb59db5a05648
To: “curtis” <sip:5001@>;tag=as0dff4b91
Call-ID: 6d96ff1a0736431ea01e736d83125ce5
CSeq: 6 REGISTER
Server: FPBX-2.11.0(1.8.26.1)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Expires: 600
Contact: sip:17243806@172.16.30.221:5061;transport=tls;expires=600
Date: Sat, 05 Apr 2014 07:27:57 GMT
Content-Length: 0

<------------>
Reliably Transmitting (NAT) to :55105:
NOTIFY sip:17243806@172.16.30.221:5061;transport=tls SIP/2.0
Via: SIP/2.0/TLS :5061;branch=z9hG4bK6247c5e1;rport
Max-Forwards: 70
Route: sip:17243806@172.16.30.221:5061;transport=tls
From: “Unknown” <sip:Unknown@>;tag=as3ad20b19
To: sip:17243806@172.16.30.221:5061;transport=tls;tag=834c2aa1cedd4c37bbf8dbaee3d0794d
Contact: <sip:Unknown@:5061;transport=TLS>
Call-ID: cefd97923d6442ab8902166e9bd0e039
CSeq: 105 NOTIFY
User-Agent: FPBX-2.11.0(1.8.26.1)
Event: message-summary
Content-Type: application/simple-message-summary
Subscription-State: active
Content-Length: 101

Messages-Waiting: no
Message-Account: sip:*97@;transport=TLS
Voice-Message: 0/0 (0/0)

Scheduling destruction of SIP dialog ‘6d96ff1a0736431ea01e736d83125ce5’ in 32000 ms (Method: REGISTER)

<— SIP read from TLS::55178 —>
SIP/2.0 200 OK
Via: SIP/2.0/TLS :5061;rport=5061;received=;branch=z9hG4bK41bfec2b
Call-ID: 0d91ed2a30a4973c41ebaa890ab27131@:5061
From: “Unknown” <sip:Unknown@>;tag=as0e25d8ba
To: sip:17243806@172.16.30.221;tag=z9hG4bK41bfec2b
CSeq: 102 OPTIONS
Allow: SUBSCRIBE, NOTIFY, PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, MESSAGE, REFER
Accept: application/sdp, application/conference-info+xml, application/simple-message-summary, multipart/related, application/rlmi+xml, application/dialog-info+xml, multipart/related, application/rlmi+xml, application/pidf+xml, application/watcherinfo+xml, application/xcap-diff+xml, application/watcherinfo+xml, message/sipfrag;version=2.0
Supported: 100rel, replaces, norefersub, gruu
Server: Blink 0.7.0 (Windows)
Content-Length: 0

<------------->
— (11 headers 0 lines) —
Really destroying SIP dialog ‘0d91ed2a30a4973c41ebaa890ab27131@:5061’ Method: OPTIONS
Really destroying SIP dialog ‘03106be814184a0eb4189cf28fff1f77’ Method: REGISTER
PBXCLI>
PBXCLI>
PBXCLI>
PBXCLI>
PBXCLI>
Really destroying SIP dialog ‘6d96ff1a0736431ea01e736d83125ce5’ Method: REGISTER
PBXCLI> sip set debug off
SIP Debugging Disabled

Please suggest

4

Unreachable is sufficient cause. That is generally a network problem. You have a too high packet loss rate. Ask on freepbx.org/forums how to disable qualify under FreePBX, but accept that that will mean that you are using the network under conditions were call quality could be rather poor and there could be extended call setup times.

I didn’t understand the reference to “blink”.

5

Blink is a softphone installed on my computer,

I am testing with a polycom phone, I hope this one works.

6

HELLO,

Im also having an issue dialing extensions. Im on a new setup and just making some test calls in the CLI
as shown below.

Connected to Asterisk 1.8.5.0 currently running on ip-172-31-15-248 (pid = 3549)
Verbosity is at least 3
ip-172-31-15-248CLI> core show channels
Channel Location State Application(Data)
0 active channels
0 active calls
2 calls processed
ip-172-31-15-248CLI> console dial 200
[Apr 9 18:25:40] WARNING[4793]: chan_oss.c:488 setformat: Unable to re-open DSP device /dev/dsp: No such file or directory
[Apr 9 18:25:40] NOTICE[4793]: console_video.c:133 console_video_start: voice only, console video support not present
– Executing [200@default:1] Answer(“Console/dsp”, “”) in new stack
<< Console call has been answered >>
– Executing [200@default:2] Hangup(“Console/dsp”, “”) in new stack
== Spawn extension (default, 200, 2) exited non-zero on ‘Console/dsp’
<< Hangup on console >>
ip-172-31-15-248*CLI>

Also im dialing in thru SIPP and nothing hits the system…

CAN SOMEONE ASSIST ME AS SOON AS POSSIBLE?

THANKS

7

Please do not tail end unrelated threads.

For rapid responses, you need to pay, so you should use the biz and jobs forum.