Updated from 12 to 13, no voice randomly from time to time

Hello!
Would appreciate any help.
A couple of days ago I performed a significant work on PBX:

  1. updated FreePBX from 12 to 13.
  2. installed VMWare tools (Debian), PBX is on VMWare 5.5.
  3. changed external IP in SIP settings
  4. changed firewall rulle, because of new external IP

Exactely after this, local users randomly began to face “no voice” in phones. They hang up, redial => all ok.

In CDR report it looks like this. No recording to play
Screenshots: http://joxi.ru/VrwVk6EI74GP4A

Call event log of this two calls is the same 15 events
unsuccessfull call: http://joxi.ru/8239yRdU98Q0pr
successfull call: http://joxi.ru/VrwVk6EI74Gy4A

I can’t to figure out why this happens.
I tried to SET SIP DEBUG PEER 123, but it’s difficult to catch a “no voice” situation, always loged normal call flow ))

Would appreciate any help.
Sergey.

After upgrading and making changes to Asterisk SIP Settings, did you restart Asterisk?

Please provide a call trace of a failed call.
https://wiki.freepbx.org/display/SUP/Providing+Great+Debug#ProvidingGreatDebug-AsteriskLogs

Yes, PBX was restarted
I managed to trace a failed call !
Seems like traditional “rtp is missing, hang up after 30 sec”. Strange, almost all clients are in LAN segment, and also face this issue.
All clients are SIP, not PJSIP.
By the way, doesn’t this notice matter? “Function PJSIP_HEADER not registered”

Call trace log

<— SIP read from UDP:21.110.150.108:5060 —>
INVITE sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.0.164:5060;branch=z9hG4bK7201f73e
Max-Forwards: 70
To: sip:[email protected]
From: sip:[email protected];tag=4035842273
Call-ID: [email protected]
CSeq: 1 INVITE
Contact: sip:[email protected]:5060
Supported: replaces
Allow: INVITE,ACK,CANCEL,BYE,OPTIONS,NOTIFY,REFER,UPDATE
Content-Type: application/sdp
User-Agent: Panasonic_KX-TGP500B09/22.85 (0080f0fd4486)
Content-Length: 293

v=0
o=- 1545908987 1545908987 IN IP4 192.168.0.164
s=-
c=IN IP4 192.168.0.164
t=0 0
m=audio 16024 RTP/AVP 8 0 2 18 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
a=ptime:20
<------------->
— (13 headers 14 lines) —
Sending to 21.110.150.108:5060 (NAT)
Sending to 21.110.150.108:5060 (NAT)
Using INVITE request as basis request - [email protected]
Found peer ‘102’ for ‘102’ from 21.110.150.108:5060

<— Reliably Transmitting (NAT) to 21.110.150.108:5060 —>
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.0.164:5060;branch=z9hG4bK7201f73e;received=21.110.150.108;rport=5060
From: sip:[email protected];tag=4035842273
To: sip:[email protected];tag=as6a288743
Call-ID: [email protected]
CSeq: 1 INVITE
Server: BTELV
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
WWW-Authenticate: Digest algorithm=MD5, realm=“BTELV”, nonce=“5857afb2”
Content-Length: 0

<------------>
Scheduling destruction of SIP dialog ‘[email protected]’ in 8000 ms (Method: INVITE)

<— SIP read from UDP:21.110.150.108:5060 —>
ACK sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.0.164:5060;branch=z9hG4bK7201f73e
Max-Forwards: 70
To: sip:[email protected];tag=as6a288743
From: sip:[email protected];tag=4035842273
Call-ID: [email protected]
CSeq: 1 ACK
Content-Length: 0

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

<— SIP read from UDP:21.110.150.108:5060 —>
INVITE sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.0.164:5060;branch=z9hG4bK400cf9c6
Max-Forwards: 70
To: sip:[email protected]
From: sip:[email protected];tag=4035842273
Call-ID: [email protected]
CSeq: 2 INVITE
Contact: sip:[email protected]:5060
Supported: replaces
Authorization: Digest realm=“BTELV”, nonce=“5857afb2”, algorithm=MD5, uri=“sip:[email protected]:5060”, username=“102”, response=“25ba86c06ad90a9e92a29c1ba6eac31c”
Allow: INVITE,ACK,CANCEL,BYE,OPTIONS,NOTIFY,REFER,UPDATE
Content-Type: application/sdp
User-Agent: Panasonic_KX-TGP500B09/22.85 (0080f0fd4486)
Content-Length: 293

v=0
o=- 1545908987 1545908987 IN IP4 192.168.0.164
s=-
c=IN IP4 192.168.0.164
t=0 0
m=audio 16024 RTP/AVP 8 0 2 18 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
a=ptime:20
<------------->
— (14 headers 14 lines) —
Sending to 21.110.150.108:5060 (NAT)
Using INVITE request as basis request - [email protected]
Found peer ‘102’ for ‘102’ from 21.110.150.108:5060
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
Found RTP audio format 8
Found RTP audio format 0
Found RTP audio format 2
Found RTP audio format 18
Found RTP audio format 101
Found audio description format PCMA for ID 8
Found audio description format PCMU for ID 0
Found audio description format G726-32 for ID 2
Found audio description format G729 for ID 18
Found audio description format telephone-event for ID 101
Capabilities: us - (ulaw|alaw|gsm|g729|g726), peer - audio=(ulaw|g726|alaw|g729)/video=(nothing)/text=(nothing), combined - (ulaw|alaw|g729|g726)
Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|)
Peer audio RTP is at port 192.168.0.164:16024
Looking for 5002 in from-internal (domain 48.74.162.186)
sip_route_dump: route/path hop: sip:[email protected]:5060

<— Transmitting (NAT) to 21.110.150.108:5060 —>
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.0.164:5060;branch=z9hG4bK400cf9c6;received=21.110.150.108;rport=5060
From: sip:[email protected];tag=4035842273
To: sip:[email protected]
Call-ID: [email protected]
CSeq: 2 INVITE
Server: BTELV
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Contact: sip:[email protected]:5060
Content-Length: 0

<------------>
– Executing [[email protected]:1] Set(“SIP/102-00000310”, “__RINGTIMER=45”) in new stack
– Executing [[email protected]:2] Macro(“SIP/102-00000310”, “exten-vm,novm,5002,0,0,0”) in new stack
– Executing [[email protected]:1] Macro(“SIP/102-00000310”, “user-callerid,”) in new stack
– Executing [[email protected]:1] Set(“SIP/102-00000310”, “TOUCH_MONITOR=1545908986.6644”) in new stack
– Executing [[email protected]:2] Set(“SIP/102-00000310”, “AMPUSER=102”) in new stack
– Executing [[email protected]:3] GotoIf(“SIP/102-00000310”, “0?report”) in new stack
– Executing [[email protected]:4] ExecIf(“SIP/102-00000310”, “1?Set(REALCALLERIDNUM=102)”) in new stack
– Executing [[email protected]:5] Set(“SIP/102-00000310”, “AMPUSER=102”) in new stack
– Executing [[email protected]:6] GotoIf(“SIP/102-00000310”, “0?limit”) in new stack
– Executing [[email protected]:7] Set(“SIP/102-00000310”, “AMPUSERCIDNAME=Sergio Home”) in new stack
– Executing [[email protected]:8] ExecIf(“SIP/102-00000310”, “0?Set(__CIDMASQUERADING=TRUE)”) in new stack
– Executing [[email protected]:9] GotoIf(“SIP/102-00000310”, “0?report”) in new stack
– Executing [[email protected]:10] Set(“SIP/102-00000310”, “AMPUSERCID=102”) in new stack
– Executing [[email protected]:11] Set(“SIP/102-00000310”, “__DIAL_OPTIONS=TtrL(7200000:5400000)”) in new stack
– Executing [[email protected]:12] Set(“SIP/102-00000310”, “CALLERID(all)=“Sergio Home” <102>”) in new stack
– Executing [[email protected]:13] GotoIf(“SIP/102-00000310”, “0?limit”) in new stack
– Executing [[email protected]:14] ExecIf(“SIP/102-00000310”, “0?Set(GROUP(concurrency_limit)=102)”) in new stack
– Executing [[email protected]:15] ExecIf(“SIP/102-00000310”, “0?Set(CHANNEL(language)=)”) in new stack
– Executing [[email protected]:16] NoOp(“SIP/102-00000310”, “Macro Depth is 2”) in new stack
– Executing [[email protected]:17] GotoIf(“SIP/102-00000310”, “1?report2:macroerror”) in new stack
– Goto (macro-user-callerid,s,18)
– Executing [[email protected]:18] GotoIf(“SIP/102-00000310”, “0?continue”) in new stack
– Executing [[email protected]:19] ExecIf(“SIP/102-00000310”, “1?Set(__CALLEE_ACCOUNCODE=)”) in new stack
– Executing [[email protected]:20] Set(“SIP/102-00000310”, “__TTL=64”) in new stack
– Executing [[email protected]:21] GotoIf(“SIP/102-00000310”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,37)
– Executing [[email protected]:37] Set(“SIP/102-00000310”, “CALLERID(number)=102”) in new stack
– Executing [[email protected]:38] Set(“SIP/102-00000310”, “CALLERID(name)=Sergio Home”) in new stack
– Executing [[email protected]:39] GotoIf(“SIP/102-00000310”, “0?cnum”) in new stack
– Executing [[email protected]:40] Set(“SIP/102-00000310”, “CDR(cnam)=Sergio Home”) in new stack
– Executing [[email protected]:41] Set(“SIP/102-00000310”, “CDR(cnum)=102”) in new stack
– Executing [[email protected]:42] Set(“SIP/102-00000310”, “CHANNEL(language)=ru”) in new stack
– Executing [[email protected]:2] Set(“SIP/102-00000310”, “RingGroupMethod=none”) in new stack
– Executing [[email protected]:3] Set(“SIP/102-00000310”, “__EXTTOCALL=5002”) in new stack
– Executing [[email protected]:4] Set(“SIP/102-00000310”, “__PICKUPMARK=5002”) in new stack
– Executing [[email protected]:5] Set(“SIP/102-00000310”, “RT=”) in new stack
– Executing [[email protected]:6] ExecIf(“SIP/102-00000310”, “0?Macro(vm,novm,DIRECTDIAL,)”) in new stack
– Executing [[email protected]:7] ExecIf(“SIP/102-00000310”, “0?MacroExit()”) in new stack
– Executing [[email protected]:8] ExecIf(“SIP/102-00000310”, “0?Gosub(ext-intercom,*805002,1())”) in new stack
– Executing [[email protected]:9] ExecIf(“SIP/102-00000310”, “0?MacroExit()”) in new stack
– Executing [[email protected]:10] ExecIf(“SIP/102-00000310”, “0?ChanSpy(SIP/5002,q)”) in new stack
– Executing [[email protected]:11] ExecIf(“SIP/102-00000310”, “0?MacroExit()”) in new stack
[2018-12-27 14:09:46] ERROR[5620][C-00000174]: pbx.c:4343 ast_func_read: Function PJSIP_HEADER not registered
– Executing [[email protected]:12] ExecIf(“SIP/102-00000310”, “0?Macro(vm,novm,DIRECTDIAL,)”) in new stack
[2018-12-27 14:09:46] ERROR[5620][C-00000174]: pbx.c:4382 ast_func_read2: Function PJSIP_HEADER not registered
[2018-12-27 14:09:46] ERROR[5620][C-00000174]: pbx.c:4343 ast_func_read: Function PJSIP_HEADER not registered
– Executing [[email protected]:13] ExecIf(“SIP/102-00000310”, “0?MacroExit()”) in new stack
[2018-12-27 14:09:46] ERROR[5620][C-00000174]: pbx.c:4382 ast_func_read2: Function PJSIP_HEADER not registered
[2018-12-27 14:09:46] ERROR[5620][C-00000174]: pbx.c:4343 ast_func_read: Function PJSIP_HEADER not registered
– Executing [[email protected]:14] ExecIf(“SIP/102-00000310”, “0?Gosub(ext-intercom,*805002,1())”) in new stack
[2018-12-27 14:09:46] ERROR[5620][C-00000174]: pbx.c:4382 ast_func_read2: Function PJSIP_HEADER not registered
[2018-12-27 14:09:46] ERROR[5620][C-00000174]: pbx.c:4343 ast_func_read: Function PJSIP_HEADER not registered
– Executing [[email protected]:15] ExecIf(“SIP/102-00000310”, “0?MacroExit()”) in new stack
[2018-12-27 14:09:46] ERROR[5620][C-00000174]: pbx.c:4382 ast_func_read2: Function PJSIP_HEADER not registered
[2018-12-27 14:09:46] ERROR[5620][C-00000174]: pbx.c:4343 ast_func_read: Function PJSIP_HEADER not registered
– Executing [[email protected]:16] ExecIf(“SIP/102-00000310”, “0?ChanSpy(SIP/5002,q)”) in new stack
[2018-12-27 14:09:46] ERROR[5620][C-00000174]: pbx.c:4382 ast_func_read2: Function PJSIP_HEADER not registered
[2018-12-27 14:09:46] ERROR[5620][C-00000174]: pbx.c:4343 ast_func_read: Function PJSIP_HEADER not registered
– Executing [[email protected]:17] ExecIf(“SIP/102-00000310”, “0?MacroExit()”) in new stack
[2018-12-27 14:09:46] ERROR[5620][C-00000174]: pbx.c:4382 ast_func_read2: Function PJSIP_HEADER not registered
– Executing [[email protected]:18] Gosub(“SIP/102-00000310”, “sub-record-check,s,1(exten,5002,dontcare)”) in new stack
– Executing [[email protected]:1] GotoIf(“SIP/102-00000310”, “0?initialized”) in new stack
– Executing [[email protected]:2] Set(“SIP/102-00000310”, “__REC_STATUS=INITIALIZED”) in new stack
– Executing [[email protected]:3] Set(“SIP/102-00000310”, “NOW=1545908986”) in new stack
– Executing [[email protected]:4] Set(“SIP/102-00000310”, “__DAY=27”) in new stack
– Executing [[email protected]:5] Set(“SIP/102-00000310”, “__MONTH=12”) in new stack
– Executing [[email protected]:6] Set(“SIP/102-00000310”, “__YEAR=2018”) in new stack
– Executing [[email protected]:7] Set(“SIP/102-00000310”, “__TIMESTR=20181227-140946”) in new stack
– Executing [[email protected]:8] Set(“SIP/102-00000310”, “__FROMEXTEN=102”) in new stack
– Executing [[email protected]:9] Set(“SIP/102-00000310”, “__MON_FMT=gsm”) in new stack
– Executing [[email protected]:10] NoOp(“SIP/102-00000310”, “Recordings initialized”) in new stack
– Executing [[email protected]:11] ExecIf(“SIP/102-00000310”, “0?Set(ARG3=dontcare)”) in new stack
– Executing [[email protected]:12] Set(“SIP/102-00000310”, “REC_POLICY_MODE_SAVE=”) in new stack
– Executing [[email protected]:13] ExecIf(“SIP/102-00000310”, “0?Set(REC_STATUS=NO)”) in new stack
– Executing [[email protected]:14] GotoIf(“SIP/102-00000310”, “5?checkaction”) in new stack
– Goto (sub-record-check,s,17)
– Executing [[email protected]:17] GotoIf(“SIP/102-00000310”, “1?sub-record-check,exten,1”) in new stack
– Goto (sub-record-check,exten,1)
– Executing [[email protected]:1] NoOp(“SIP/102-00000310”, “Exten Recording Check between 102 and 5002”) in new stack
– Executing [[email protected]:2] Set(“SIP/102-00000310”, “CALLTYPE=internal”) in new stack
– Executing [[email protected]:3] ExecIf(“SIP/102-00000310”, “0?Set(CALLTYPE=)”) in new stack
– Executing [[email protected]:4] Set(“SIP/102-00000310”, “CALLEE=force”) in new stack
– Executing [[email protected]:5] ExecIf(“SIP/102-00000310”, “0?Set(CALLEE=dontcare)”) in new stack
– Executing [[email protected]:6] GotoIf(“SIP/102-00000310”, “0?callee”) in new stack
– Executing [[email protected]:7] GotoIf(“SIP/102-00000310”, “0?caller”) in new stack
– Executing [[email protected]:8] ExecIf(“SIP/102-00000310”, “2?Set(CALLER_PRI=10):Set(CALLER_PRI=0)”) in new stack
– Executing [[email protected]:9] ExecIf(“SIP/102-00000310”, “2?Set(CALLEE_PRI=10):Set(CALLEE_PRI=0)”) in new stack
– Executing [[email protected]:10] GotoIf(“SIP/102-00000310”, “1?caller:callee”) in new stack
– Goto (sub-record-check,exten,13)
– Executing [[email protected]:13] Set(“SIP/102-00000310”, “RECMODE=never”) in new stack
– Executing [[email protected]:14] ExecIf(“SIP/102-00000310”, “0?Set(RECMODE=dontcare)”) in new stack
– Executing [[email protected]:15] ExecIf(“SIP/102-00000310”, “0?Set(RECMODE=force)”) in new stack
– Executing [[email protected]:16] Gosub(“SIP/102-00000310”, “recordcheck,1(never,internal,5002)”) in new stack
– Executing [[email protected]:1] NoOp(“SIP/102-00000310”, “Starting recording check against never”) in new stack
– Executing [[email protected]:2] Goto(“SIP/102-00000310”, “never”) in new stack
– Goto (sub-record-check,recordcheck,14)
– Executing [[email protected]:14] Set(“SIP/102-00000310”, “__REC_POLICY_MODE=NEVER”) in new stack
– Executing [[email protected]:15] Goto(“SIP/102-00000310”, “stoprec”) in new stack
– Goto (sub-record-check,recordcheck,25)
– Executing [[email protected]:25] NoOp(“SIP/102-00000310”, “Stopping recording: internal, 5002”) in new stack
– Executing [[email protected]:26] Set(“SIP/102-00000310”, “__REC_STATUS=STOPPED”) in new stack
– Executing [[email protected]:27] System(“SIP/102-00000310”, "/var/lib/asterisk/bin/stoprecording.php “SIP/102-00000310"”) in new stack
– Executing [[email protected]:28] Return(“SIP/102-00000310”, “”) in new stack
– Executing [[email protected]:17] Return(“SIP/102-00000310”, “”) in new stack
– Executing [[email protected]:19] GotoIf(“SIP/102-00000310”, “1?macrodial”) in new stack
– Goto (macro-exten-vm,s,25)
– Executing [[email protected]:25] GosubIf(“SIP/102-00000310”, “0?clrheader,1()”) in new stack
– Executing [[email protected]:26] Macro(“SIP/102-00000310”, “dial-one,TtrL(7200000:5400000),5002”) in new stack
– Executing [[email protected]:1] Set(“SIP/102-00000310”, “DEXTEN=5002”) in new stack
– Executing [[email protected]:2] ExecIf(“SIP/102-00000310”, “0?Set(__EXTTOCALL=5002)”) in new stack
– Executing [[email protected]:3] Set(“SIP/102-00000310”, “DIALSTATUS_CW=”) in new stack
– Executing [[email protected]:4] GosubIf(“SIP/102-00000310”, “0?screen,1()”) in new stack
– Executing [[email protected]:5] GosubIf(“SIP/102-00000310”, “0?cf,1()”) in new stack
– Executing [[email protected]:6] GotoIf(“SIP/102-00000310”, “1?skip1”) in new stack
– Goto (macro-dial-one,s,9)
– Executing [[email protected]:9] GotoIf(“SIP/102-00000310”, “0?nodial”) in new stack
– Executing [[email protected]:10] GotoIf(“SIP/102-00000310”, “0?continue”) in new stack
– Executing [[email protected]:11] Set(“SIP/102-00000310”, “EXTHASCW=ENABLED”) in new stack
– Executing [[email protected]:12] GotoIf(“SIP/102-00000310”, “0?next1:cwinusebusy”) in new stack
– Goto (macro-dial-one,s,24)
– Executing [[email protected]:24] GotoIf(“SIP/102-00000310”, “0?next3:continue”) in new stack
– Goto (macro-dial-one,s,26)
– Executing [[email protected]:26] GotoIf(“SIP/102-00000310”, “0?nodial”) in new stack
– Executing [[email protected]:27] GosubIf(“SIP/102-00000310”, “1?dstring,1():dlocal,1()”) in new stack
– Executing [[email protected]:1] Set(“SIP/102-00000310”, “DSTRING=”) in new stack
– Executing [[email protected]:2] Set(“SIP/102-00000310”, “DEVICES=5002”) in new stack
– Executing [[email protected]:3] ExecIf(“SIP/102-00000310”, “0?Return()”) in new stack
– Executing [[email protected]:4] ExecIf(“SIP/102-00000310”, “0?Set(DEVICES=002)”) in new stack
– Executing [[email protected]:5] Set(“SIP/102-00000310”, “LOOPCNT=1”) in new stack
– Executing [[email protected]:6] Set(“SIP/102-00000310”, “ITER=1”) in new stack
– Executing [[email protected]:7] Set(“SIP/102-00000310”, “THISDIAL=SIP/5002”) in new stack
– Executing [[email protected]:8] GosubIf(“SIP/102-00000310”, “1?zap2dahdi,1()”) in new stack
– Executing [[email protected]:1] ExecIf(“SIP/102-00000310”, “0?Return()”) in new stack
– Executing [[email protected]:2] Set(“SIP/102-00000310”, “NEWDIAL=”) in new stack
– Executing [[email protected]:3] Set(“SIP/102-00000310”, “LOOPCNT2=1”) in new stack
– Executing [[email protected]:4] Set(“SIP/102-00000310”, “ITER2=1”) in new stack
– Executing [[email protected]:5] Set(“SIP/102-00000310”, “THISPART2=SIP/5002”) in new stack
– Executing [[email protected]:6] ExecIf(“SIP/102-00000310”, “0?Set(THISPART2=DAHDI/5002)”) in new stack
– Executing [[email protected]:7] Set(“SIP/102-00000310”, “NEWDIAL=SIP/5002&”) in new stack
– Executing [[email protected]:8] Set(“SIP/102-00000310”, “ITER2=2”) in new stack
– Executing [[email protected]:9] GotoIf(“SIP/102-00000310”, “0?begin2”) in new stack
– Executing [[email protected]:10] Set(“SIP/102-00000310”, “THISDIAL=SIP/5002”) in new stack
– Executing [[email protected]:11] Return(“SIP/102-00000310”, “”) in new stack
– Executing [[email protected]:9] GotoIf(“SIP/102-00000310”, “1?docheck”) in new stack
– Goto (macro-dial-one,dstring,14)
– Executing [[email protected]:14] GotoIf(“SIP/102-00000310”, “0?skipset”) in new stack
– Executing [[email protected]:15] Set(“SIP/102-00000310”, “DSTRING=SIP/5002&”) in new stack
– Executing [[email protected]:16] Set(“SIP/102-00000310”, “ITER=2”) in new stack
– Executing [[email protected]:17] GotoIf(“SIP/102-00000310”, “0?begin”) in new stack
– Executing [[email protected]:18] ExecIf(“SIP/102-00000310”, “0?Return()”) in new stack
– Executing [[email protected]:19] Set(“SIP/102-00000310”, “DSTRING=SIP/5002”) in new stack
– Executing [[email protected]:20] Return(“SIP/102-00000310”, “”) in new stack
– Executing [[email protected]:28] GotoIf(“SIP/102-00000310”, “0?nodial”) in new stack
– Executing [[email protected]:29] GotoIf(“SIP/102-00000310”, “0?skiptrace”) in new stack
– Executing [[email protected]:30] GosubIf(“SIP/102-00000310”, “1?ctset,1():ctclear,1()”) in new stack
– Executing [[email protected]:1] Set(“SIP/102-00000310”, “DB(CALLTRACE/5002)=102”) in new stack
– Executing [[email protected]:2] Return(“SIP/102-00000310”, “”) in new stack
– Executing [[email protected]:31] Set(“SIP/102-00000310”, “D_OPTIONS=TtrL(7200000:5400000)”) in new stack
– Executing [[email protected]:32] GosubIf(“SIP/102-00000310”, “0?func-set-sipheader,s,1(Alert-Info,)”) in new stack
– Executing [[email protected]:33] NoOp(“SIP/102-00000310”, "Blind Transfer: , Attended Transfer: , User: 102, Alert Info: ") in new stack
– Executing [[email protected]:34] ExecIf(“SIP/102-00000310”, “1?Set(ALERT_INFO=)”) in new stack
– Executing [[email protected]:35] ExecIf(“SIP/102-00000310”, “0?Set(ALERT_INFO=)”) in new stack
– Executing [[email protected]:36] ExecIf(“SIP/102-00000310”, “0?Set(ALERT_INFO=)”) in new stack
– Executing [[email protected]:37] ExecIf(“SIP/102-00000310”, “0?Set(ALERT_INFO=Normal;volume=)”) in new stack
– Executing [[email protected]:38] ExecIf(“SIP/102-00000310”, “0?Set(ALERT_INFO=Normal;volume=)”) in new stack
– Executing [[email protected]:39] GosubIf(“SIP/102-00000310”, “0?func-set-sipheader,s,1(Alert-Info,)”) in new stack
– Executing [[email protected]:40] ExecIf(“SIP/102-00000310”, “0?Set(CHANNEL(musicclass)=)”) in new stack
– Executing [[email protected]:41] GosubIf(“SIP/102-00000310”, “0?qwait,1()”) in new stack
– Executing [[email protected]:42] Set(“SIP/102-00000310”, “__CWIGNORE=”) in new stack
– Executing [[email protected]:43] Set(“SIP/102-00000310”, “__KEEPCID=TRUE”) in new stack
– Executing [[email protected]:44] GotoIf(“SIP/102-00000310”, “0?usegoto,1”) in new stack
– Executing [[email protected]:45] GotoIf(“SIP/102-00000310”, “0?godial”) in new stack
– Executing [[email protected]:46] Gosub(“SIP/102-00000310”, “sub-presencestate-display,s,1(5002)”) in new stack
[2018-12-27 14:09:46] WARNING[5620][C-00000174]: func_presencestate.c:133 presence_read: PRESENCE_STATE unknown
– Executing [[email protected]:1] Goto(“SIP/102-00000310”, “state-,1”) in new stack
– Goto (sub-presencestate-display,state-,1)
– Executing [state-@sub-presencestate-display:1] Set(“SIP/102-00000310”, “PRESENCESTATE_DISPLAY=”) in new stack
– Executing [state-@sub-presencestate-display:2] Return(“SIP/102-00000310”, “”) in new stack
– Executing [[email protected]:47] Set(“SIP/102-00000310”, “CONNECTEDLINE(name,i)=5002 Palantir FXO1”) in new stack
– Executing [[email protected]:48] Set(“SIP/102-00000310”, “CONNECTEDLINE(num)=5002”) in new stack
– Executing [[email protected]:49] Set(“SIP/102-00000310”, “D_OPTIONS=TtrL(7200000:5400000)I”) in new stack
– Executing [[email protected]:50] Macro(“SIP/102-00000310”, “dialout-one-predial-hook,”) in new stack
– Executing [[email protected]:1] MacroExit(“SIP/102-00000310”, “”) in new stack
– Executing [[email protected]:51] ExecIf(“SIP/102-00000310”, “0?Set(D_OPTIONS=trL(7200000:5400000)II)”) in new stack
– Executing [[email protected]:52] NoOp(“SIP/102-00000310”, “”) in new stack
– Executing [[email protected]:53] ExecIf(“SIP/102-00000310”, “0?Set(D_OPTIONS=TtrL(7200000:5400000)Ig)”) in new stack
– Executing [[email protected]:54] Dial(“SIP/102-00000310”, “SIP/5002,TtrL(7200000:5400000)Ib(func-apply-sipheaders^s^1)”) in new stack
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– SIP/5002-00000311 Internal Gosub(func-apply-sipheaders,s,1) start
– Executing [[email protected]:1] NoOp(“SIP/5002-00000311”, “Applying SIP Headers to channel”) in new stack
– Executing [[email protected]:2] Set(“SIP/5002-00000311”, “SIPHEADERKEYS=”) in new stack
– Executing [[email protected]:3] ExecIf(“SIP/5002-00000311”, “0?Set(Rheader=1)”) in new stack
– Executing [[email protected]:4] While(“SIP/5002-00000311”, “0”) in new stack
– Jumping to priority 7
– Executing [[email protected]:8] ExecIf(“SIP/5002-00000311”, “0?SIPRemoveHeader(Alert-Info:)”) in new stack
– Executing [[email protected]:9] Return(“SIP/5002-00000311”, “”) in new stack
== Spawn extension (from-internal, 5002, 1) exited non-zero on ‘SIP/5002-00000311’
– SIP/5002-00000311 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
– Called SIP/5002

<— Transmitting (NAT) to 21.110.150.108:5060 —>
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 192.168.0.164:5060;branch=z9hG4bK400cf9c6;received=21.110.150.108;rport=5060
From: sip:[email protected];tag=4035842273
To: sip:[email protected];tag=as61b55c19
Call-ID: [email protected]
CSeq: 2 INVITE
Server: BTELV
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Contact: sip:[email protected]:5060
Content-Length: 0

<------------>
– Connected line update to SIP/102-00000310 prevented.
– Connected line update to SIP/102-00000310 prevented.
– SIP/5002-00000311 answered SIP/102-00000310
Audio is at 21302
Adding codec ulaw to SDP
Adding codec alaw to SDP
Adding codec g729 to SDP
Adding codec g726 to SDP
Adding codec gsm to SDP
Adding non-codec 0x1 (telephone-event) to SDP

<— Reliably Transmitting (NAT) to 21.110.150.108:5060 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.0.164:5060;branch=z9hG4bK400cf9c6;received=21.110.150.108;rport=5060
From: sip:[email protected];tag=4035842273
To: sip:[email protected];tag=as61b55c19
Call-ID: [email protected]
CSeq: 2 INVITE
Server: BTELV
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Contact: sip:[email protected]:5060
Content-Type: application/sdp
Content-Length: 374

v=0
o=admin 2111758532 2111758532 IN IP4 48.74.162.186
s=Asterisk PBX 13.1.1
c=IN IP4 48.74.162.186
t=0 0
m=audio 21302 RTP/AVP 0 8 18 2 3 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:2 G726-32/8000
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=maxptime:150
a=sendrecv

<------------>
– Channel SIP/102-00000310 joined ‘simple_bridge’ basic-bridge <65f37aaf-6e77-4e82-a675-5125a7149e50>
– Channel SIP/5002-00000311 joined ‘simple_bridge’ basic-bridge <65f37aaf-6e77-4e82-a675-5125a7149e50>

<— SIP read from UDP:21.110.150.108:5060 —>
ACK sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.0.164:5060;branch=z9hG4bKf6a71e71
Max-Forwards: 70
To: sip:[email protected];tag=as61b55c19
From: sip:[email protected];tag=4035842273
Call-ID: [email protected]
CSeq: 2 ACK
Authorization: Digest realm=“BTELV”, nonce=“5857afb2”, algorithm=MD5, uri=“sip:[email protected]:5060”, username=“102”, response=“5693319cf0844100d439cba34b2a5d57”
Content-Length: 0

<------------->
— (9 headers 0 lines) —
Reliably Transmitting (NAT) to 21.110.150.108:5060:
OPTIONS sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP 48.74.162.186:5060;branch=z9hG4bK03a5dfce;rport
Max-Forwards: 70
From: “Unknown” sip:[email protected]:5060;tag=as48a03543
To: sip:[email protected]:5060
Contact: sip:[email protected]:5060
Call-ID: [email protected]:5060
CSeq: 102 OPTIONS
User-Agent: BTELV
Date: Thu, 27 Dec 2018 11:09:53 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Length: 0


<— SIP read from UDP:21.110.150.108:5060 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP 48.74.162.186:5060;branch=z9hG4bK03a5dfce;rport=5060
To: sip:[email protected];tag=2160796565
From: “Unknown” sip:[email protected];tag=as48a03543
Call-ID: [email protected]:5060
CSeq: 102 OPTIONS
Contact: sip:192.168.0.164:5060
Allow: INVITE,ACK,CANCEL,BYE,OPTIONS,NOTIFY,REFER,UPDATE
Content-Length: 0

<------------->
— (9 headers 0 lines) —
Really destroying SIP dialog ‘[email protected]:5060’ Method: OPTIONS
– Channel SIP/5002-00000311 left ‘simple_bridge’ basic-bridge <65f37aaf-6e77-4e82-a675-5125a7149e50>
– Channel SIP/102-00000310 left ‘simple_bridge’ basic-bridge <65f37aaf-6e77-4e82-a675-5125a7149e50>
== Spawn extension (macro-dial-one, s, 54) exited non-zero on ‘SIP/102-00000310’ in macro ‘dial-one’
== Spawn extension (macro-exten-vm, s, 26) exited non-zero on ‘SIP/102-00000310’ in macro ‘exten-vm’
== Spawn extension (from-internal, 5002, 2) exited non-zero on ‘SIP/102-00000310’
– Executing [[email protected]:1] Macro(“SIP/102-00000310”, “hangupcall”) in new stack
– Executing [[email protected]:1] GotoIf(“SIP/102-00000310”, “1?theend”) in new stack
– Goto (macro-hangupcall,s,3)
– Executing [[email protected]:3] ExecIf(“SIP/102-00000310”, “0?Set(CDR(recordingfile)=)”) in new stack
– Executing [[email protected]:4] Hangup(“SIP/102-00000310”, “”) in new stack
== Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘SIP/102-00000310’ in macro ‘hangupcall’
== Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/102-00000310’
Scheduling destruction of SIP dialog ‘[email protected]’ in 8000 ms (Method: ACK)
Reliably Transmitting (NAT) to 21.110.150.108:5060:
BYE sip:[email protected]:5060 SIP/2.0
Via: SIP/2.0/UDP 48.74.162.186:5060;branch=z9hG4bK7f9c4dc0;rport
Max-Forwards: 70
From: sip:[email protected];tag=as61b55c19
To: sip:[email protected];tag=4035842273
Call-ID: [email protected]
CSeq: 102 BYE
User-Agent: BTELV
Proxy-Authorization: Digest username=“102”, realm=“BTELV”, algorithm=MD5, uri=“sip:48.74.162.186”, nonce=“5857afb2”, response=“69b87041c3582067464f9f96b0a029ff”
X-Asterisk-HangupCause: Normal Clearing
X-Asterisk-HangupCauseCode: 16
Content-Length: 0


<— SIP read from UDP:21.110.150.108:5060 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP 48.74.162.186:5060;branch=z9hG4bK7f9c4dc0;rport=5060
To: sip:[email protected];tag=4035842273
From: sip:[email protected];tag=as61b55c19
Call-ID: [email protected]
CSeq: 102 BYE
Content-Length: 0

<------------->
— (7 headers 0 lines) —
SIP Response message for INCOMING dialog BYE arrived
Really destroying SIP dialog ‘[email protected]’ Method: ACK

Another difference from 12 to 13. I’ve tried to change as was before
a) port range till 30000, changed now to 20 000
b) codec order: PCMU, PCMA, g729

Now seems to be good. I keep looking.

Externip and localnet are right?

Yes, they are right.
IP 48.74.162.186
LAN 192.168.0.0 / 255.255.0.0

After mentioned changes, seems working good.

This topic was automatically closed 7 days after the last reply. New replies are no longer allowed.