Opened 5 years ago

Closed 5 years ago

#290 closed баг (invalid)

Не работает исходящий вызов через TLS на телефон Yealink

Reported by: alx Owned by: alx
Priority: средний Milestone: 2 очередь
Component: any Keywords:
Cc:

Description (last modified by alx)

Телефон Yealink зарегистрирован на плате VE-01 через транспорт TLS, номер 122.
Звонки с телефона проходят нормально. При вызове номера 122 вызывающий немедленно получает ответ "480 Temporarily Unavailable". Лог звонка:

sip_ua[521]: fxs.cpp:419: ---> ts=127, state=Idle: CAS A activity detected, ts=127, flags=0000, data=0
sip_ua[521]: fxs.cpp:419: ---> ts=127, state=Idle: CAS event, ts=127, flags=0000, data=7
sip_ua[521]: fxs.cpp:702: ==> ts 127: manual CAS event processing (abcd=13)
sip_ua[521]: fxs.cpp:419: ---> ts=127, state=Dialtone: CAS event, ts=127, flags=0000, data=7
sip_ua[521]: fxs.cpp:419: ---> ts=127, state=Dialtone: DTMF detected, ts=127, flags=0000, data=1
sip_ua[521]: fxs.cpp:419: ---> ts=127, state=Dialing: Tone completed, ts=127, flags=0000, data=3
sip_ua[521]: fxs.cpp:419: ---> ts=127, state=Dialing: Tone detected, ts=127, flags=0000, data=255
sip_ua[521]: fxs.cpp:419: ---> ts=127, state=Dialing: DTMF detected, ts=127, flags=0000, data=2
sip_ua[521]: fxs.cpp:419: ---> ts=127, state=Dialing: Tone detected, ts=127, flags=0000, data=255
sip_ua[521]: fxs.cpp:419: ---> ts=127, state=Dialing: DTMF detected, ts=127, flags=0000, data=2
sip_ua[521]: fxs.cpp:419: ---> ts=127, state=Dialing: Tone detected, ts=127, flags=0000, data=255
sip_ua[521]: fxs.cpp:241: ts 127: dialing 122
sip_ua[521]: user_agent.cpp:3099: --> ua_dial_out() <sip:111@192.168.1.67> -> sip:122@192.168.1.67 ()...
DEBUG | 20181116-055333.891 | repro | RESIP:TRANSPORT | 44042 | Transport.cxx:392 | incoming from: [ V4 127.0.0.1:6060 UDP flowKey=21 transportKey=1 ]
DEBUG | 20181116-055333.892 | repro | RESIP:TRANSACTION | 43017 | TuSelector.cxx:189 | TuSelector::selectTransactionUser: Checking which TU message belongs to:

INVITE sip:122@192.168.1.67 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.67:6060;rport=6060;branch=z9hG4bK1008681416;received=127.0.0.1
Max-Forwards: 70
Contact: <sip:111@127.0.0.1:6060>
To: <sip:122@192.168.1.67>
From: <sip:111@192.168.1.67>;tag=1421584185
Call-ID: 318460658
CSeq: 20 INVITE
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, REFER, NOTIFY, INFO, UPDATE
Content-Type: application/sdp
Supported: replaces, timer
User-Agent: eXosip/4.1.0
X-Endpoint-Type: FXS
Content-Length: 955

v=0
o=gateway 0 0 IN IP4 192.168.1.67
s=conversation
c=IN IP4 192.168.1.67
t=0 0
m=audio 10254 RTP/SAVP 8 0 96 18 101 13 98
a=altc:1 IP6 2a01:540:2f05:7f00:ad:c3ff:fe00:6 10254
a=altc:2 IP4 192.168.1.67 10254
a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:UNoBOLysHhZNJqdg1T/6cJ9QBoqNQ1MAtrIEupjc
a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:jFwyWwVA9GBmjTdPnjdab79TtRFrbegoEHziWuTK
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:96 G726-32/8000
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=rtpmap:13 CN/8000
a=rtpmap:98 PCMA/8000
a=gpmd:98 vbd=yes
a=ptime:30
m=audio 10254 RTP/AVP 8 0 96 18 101 13 98
a=altc:1 IP6 2a01:540:2f05:7f00:ad:c3ff:fe00:6 10254
a=altc:2 IP4 192.168.1.67 10254
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:96 G726-32/8000
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=rtpmap:13 CN/8000
a=rtpmap:98 PCMA/8000
a=gpmd:98 vbd=yes
a=ptime:30

DEBUG | 20181116-055333.895 | repro | RESIP:TRANSACTION | 43017 | TransactionUser.cxx:83 | TransactionUser::isForMe: TU=DialogUsageManager, Checking rule... : 
SipReq:  INVITE 122@192.168.1.67 tid=1008681416 cseq=20 INVITE contact=111@127.0.0.1:6060 / 20 from(wire)
DEBUG | 20181116-055333.896 | repro | RESIP:TRANSACTION | 43017 | MessageFilterRule.cxx:66 |   MessageFilterRule::matches: Method is not in list. Rule does not
 match.
DEBUG | 20181116-055333.897 | repro | RESIP:TRANSACTION | 43017 | TransactionUser.cxx:90 | TransactionUser::isForMe: TU=DialogUsageManager, No matching rule fo
und : SipReq:  INVITE 122@192.168.1.67 tid=1008681416 cseq=20 INVITE contact=111@127.0.0.1:6060 / 20 from(wire)
DEBUG | 20181116-055333.899 | repro | RESIP:TRANSACTION | 43017 | TransactionUser.cxx:83 | TransactionUser::isForMe: TU=Proxy, Checking rule... : SipReq:  INVI
TE 122@192.168.1.67 tid=1008681416 cseq=20 INVITE contact=111@127.0.0.1:6060 / 20 from(wire)
DEBUG | 20181116-055333.900 | repro | RESIP:TRANSACTION | 43017 | TransactionUser.cxx:86 | TransactionUser::isForMe: TU=Proxy, Match! : SipReq:  INVITE 122@192
.168.1.67 tid=1008681416 cseq=20 INVITE contact=111@127.0.0.1:6060 / 20 from(wire)
DEBUG | 20181116-055333.902 | repro | RESIP | 43017 | Helper.cxx:374 | Helper::makeResponse(SipReq:  INVITE 122@192.168.1.67 tid=1008681416 cseq=20 INVITE cont
act=111@127.0.0.1:6060 / 20 from(wire) code=100 reason=
DEBUG | 20181116-055333.904 | repro | RESIP:TRANSACTION | 43017 | TimerQueue.cxx:50 | Adding timer: Timer Trying tid=1008681416 ms=80
DEBUG | 20181116-055333.905 | repro | RESIP:TRANSACTION | 43017 | TuSelector.cxx:70 | Send to TU: Proxy size=0 

INVITE sip:122@192.168.1.67 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.67:6060;rport=6060;branch=z9hG4bK1008681416;received=127.0.0.1
Max-Forwards: 70
Contact: <sip:111@127.0.0.1:6060>
To: <sip:122@192.168.1.67>
From: <sip:111@192.168.1.67>;tag=1421584185
Call-ID: 318460658
CSeq: 20 INVITE
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, REFER, NOTIFY, INFO, UPDATE
Content-Type: application/sdp
Supported: replaces, timer
User-Agent: eXosip/4.1.0
X-Endpoint-Type: FXS
Content-Length: 955

v=0
o=gateway 0 0 IN IP4 192.168.1.67
s=conversation
c=IN IP4 192.168.1.67
t=0 0
m=audio 10254 RTP/SAVP 8 0 96 18 101 13 98
a=altc:1 IP6 2a01:540:2f05:7f00:ad:c3ff:fe00:6 10254
a=altc:2 IP4 192.168.1.67 10254
a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:UNoBOLysHhZNJqdg1T/6cJ9QBoqNQ1MAtrIEupjc
a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:jFwyWwVA9GBmjTdPnjdab79TtRFrbegoEHziWuTK
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:96 G726-32/8000
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=rtpmap:13 CN/8000
a=rtpmap:98 PCMA/8000
a=gpmd:98 vbd=yes
a=ptime:30
m=audio 10254 RTP/AVP 8 0 96 18 101 13 98
a=altc:1 IP6 2a01:540:2f05:7f00:ad:c3ff:fe00:6 10254
a=altc:2 IP4 192.168.1.67 10254
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:96 G726-32/8000
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=rtpmap:13 CN/8000
a=rtpmap:98 PCMA/8000
a=gpmd:98 vbd=yes
a=ptime:30

DEBUG | 20181116-055334.240 | repro | RESIP:TRANSPORT | 43017 | TransportSelector.cxx:1075 | Found transport: [ V4 0.0.0.0:5060 UDP flowKey=21 transportKey=1 ]
DEBUG | 20181116-055334.242 | repro | RESIP:TRANSPORT | 43017 | TransportSelector.cxx:871 | Looked up source for destination: [ V4 127.0.0.1:6060 UDP flowKey=2
1 transportKey=1 ] -> [ V4 127.0.0.1:0 UDP flowKey=21 transportKey=1 ] sent-by=192.168.1.67 sent-port=6060
DEBUG | 20181116-055334.244 | repro | RESIP:TRANSPORT | 43017 | TransportSelector.cxx:1291 | Transmitting to [ V4 127.0.0.1:6060 UDP flowKey=21 transportKey=1 
] tlsDomain= via [ V4 127.0.0.1:5060 UDP flowKey=21 transportKey=1 ]

SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.1.67:6060;rport=6060;branch=z9hG4bK1008681416;received=127.0.0.1
To: <sip:122@192.168.1.67>
From: <sip:111@192.168.1.67>;tag=1421584185
Call-ID: 318460658
CSeq: 20 INVITE
Content-Length: 0

sigcomp id=
DEBUG | 20181116-055334.241 | repro | REPRO:APP | 47117 | Proxy.cxx:167 | Got: INVITE sip:122@192.168.1.67 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.67:6060;rport=6060;branch=z9hG4bK1008681416;received=127.0.0.1
Max-Forwards: 70
Contact: <sip:111@127.0.0.1:6060>
To: <sip:122@192.168.1.67>
From: <sip:111@192.168.1.67>;tag=1421584185
Call-ID: 318460658
CSeq: 20 INVITE
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, REFER, NOTIFY, INFO, UPDATE
Content-Type: application/sdp
Supported: replaces, timer
User-Agent: eXosip/4.1.0
X-Endpoint-Type: FXS
Content-Length: 955

v=0
o=gateway 0 0 IN IP4 192.168.1.67
s=conversation
c=IN IP4 192.168.1.67
t=0 0
m=audio 10254 RTP/SAVP 8 0 96 18 101 13 98
a=altc:1 IP6 2a01:540:2f05:7f00:ad:c3ff:fe00:6 10254
a=altc:2 IP4 192.168.1.67 10254
a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:UNoBOLysHhZNJqdg1T/6cJ9QBoqNQ1MAtrIEupjc
a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:jFwyWwVA9GBmjTdPnjdab79TtRFrbegoEHziWuTK
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:96 G726-32/8000
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=rtpmap:13 CN/8000
a=rtpmap:98 PCMA/8000
a=gpmd:98 vbd=yes
a=ptime:30
m=audio 10254 RTP/AVP 8 0 96 18 101 13 98
a=altc:1 IP6 2a01:540:2f05:7f00:ad:c3ff:fe00:6 10254
a=altc:2 IP4 192.168.1.67 10254
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:96 G726-32/8000
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=rtpmap:13 CN/8000
a=rtpmap:98 PCMA/8000
a=gpmd:98 vbd=yes
a=ptime:30

INFO | 20181116-055334.248 | repro | REPRO:APP | 47117 | Proxy.cxx:366 | New RequestContext tid=1008681416 : SipReq:  INVITE 122@192.168.1.67 tid=1008681416 cs
eq=20 INVITE contact=111@127.0.0.1:6060 / 20 from(wire)
INFO | 20181116-055334.250 | repro | REPRO:APP | 47117 | Proxy.cxx:376 | Inserting new RequestContext tid=1008681416 -> numtrans=1 final=0
INFO | 20181116-055334.251 | repro | REPRO:APP | 47117 | RequestContext.cxx:92 | RequestContext::process(SipMessage) 1008681416
DEBUG | 20181116-055334.252 | repro | REPRO:APP | 47117 | RequestContext.cxx:152 | Got a request.
DEBUG | 20181116-055334.253 | repro | REPRO:APP | 47117 | ProcessorChain.cxx:95 | Chain invoking RequestProcessor: StrictRouteFixup
DEBUG | 20181116-055334.254 | repro | REPRO:APP | 47117 | monkeys/StrictRouteFixup.cxx:39 | Monkey handling request: StrictRouteFixup; reqcontext = numtrans=1 
final=0 req=SipReq:  INVITE 122@192.168.1.67 tid=1008681416 cseq=20 INVITE contact=111@127.0.0.1:6060 / 20 from(wire)
DEBUG | 20181116-055334.587 | repro | REPRO:APP | 47117 | ProcessorChain.cxx:95 | Chain invoking RequestProcessor: IsTrustedNode
DEBUG | 20181116-055334.588 | repro | REPRO:APP | 47117 | monkeys/IsTrustedNode.cxx:36 | Monkey handling request: IsTrustedNode; reqcontext = numtrans=1 final=
0 req=SipReq:  INVITE 122@192.168.1.67 tid=1008681416 cseq=20 INVITE contact=111@127.0.0.1:6060 / 20 from(wire)
sip_ua[548]: user_agent.cpp:1911: ---> transport 0: SIP event 5 (Call is being processed!): cid=1, did=0, tid=6, rid=0, sid=0, nid=0
INFO | 20181116-055334.590 | repro | REPRO:APP | 47117 | AclStore.cxx:553 | AclStore - source address IS trusted: 127.0.0.1:6060 UDP
DEBUG | 20181116-055334.592 | repro | REPRO:APP | 47117 | ProcessorChain.cxx:95 | Chain invoking RequestProcessor: DigestAuthenticator
DEBUG | 20181116-055334.593 | repro | REPRO:APP | 47117 | monkeys/DigestAuthenticator.cxx:51 | Monkey handling request: DigestAuthenticator; reqcontext = numtr
ans=1 final=0 req=SipReq:  INVITE 122@192.168.1.67 tid=1008681416 cseq=20 INVITE contact=111@127.0.0.1:6060 / 20 from(wire)
DEBUG | 20181116-055334.594 | repro | REPRO:APP | 47117 | ProcessorChain.cxx:95 | Chain invoking RequestProcessor: AmIResponsible
DEBUG | 20181116-055334.595 | repro | REPRO:APP | 47117 | monkeys/AmIResponsible.cxx:32 | Monkey handling request: AmIResponsible; reqcontext = numtrans=1 fina
l=0 req=SipReq:  INVITE 122@192.168.1.67 tid=1008681416 cseq=20 INVITE contact=111@127.0.0.1:6060 / 20 from(wire)
sip_ua[629]: repro.cpp:439: doSessionAccounting(): Session Created 'branch=z9hG4bK1008681416'
DEBUG | 20181116-055334.599 | repro | REPRO:APP | 47117 | Proxy.cxx:595 | Proxy::isMyUri sip:122@192.168.1.67 1
DEBUG | 20181116-055334.600 | repro | REPRO:APP | 47117 | ProcessorChain.cxx:95 | Chain invoking RequestProcessor: RequestFilter
DEBUG | 20181116-055334.601 | repro | REPRO:APP | 47117 | monkeys/RequestFilter.cxx:154 | Monkey handling request: RequestFilter; reqcontext = numtrans=1 final
=0 req=SipReq:  INVITE 122@192.168.1.67 tid=1008681416 cseq=20 INVITE contact=111@127.0.0.1:6060 / 20 from(wire)
DEBUG | 20181116-055334.603 | repro | REPRO:APP | 47117 | monkeys/RequestFilter.cxx:147 | Request is accepted
DEBUG | 20181116-055334.604 | repro | REPRO:APP | 47117 | ProcessorChain.cxx:95 | Chain invoking RequestProcessor: MyStaticRoute
DEBUG | 20181116-055334.605 | repro | REPRO:APP | 47117 | reproStaticRoute.cpp:38 | Monkey handling request: MyStaticRoute; reqcontext = numtrans=1 final=0 req
=SipReq:  INVITE 122@192.168.1.67 tid=1008681416 cseq=20 INVITE contact=111@127.0.0.1:6060 / 20 from(wire)
DEBUG | 20181116-055334.607 | repro | REPRO:APP | 47117 | ProcessorChain.cxx:95 | Chain invoking RequestProcessor: MyLocationServer
DEBUG | 20181116-055334.608 | repro | RESIP:DUM | 47117 | InMemorySyncRegDb.cxx:258 | InMemorySyncRegDb::lockRecord:  aor=sip:122@192.168.1.67 threadid=47117
DEBUG | 20181116-055334.609 | repro | RESIP:DUM | 47117 | InMemorySyncRegDb.cxx:279 | InMemorySyncRegDb::unlockRecord:  aor=sip:122@192.168.1.67 threadid=47117
DEBUG | 20181116-055334.613 | repro | REPRO:APP | 47117 | ProcessorChain.cxx:107 | RequestProcessor waiting for async response: MyLocationServer
DEBUG | 20181116-055334.615 | repro | REPRO:APP | 36868 | UserAuthGrabber.cxx:31 | Grabbed user info for 122@192.168.1.67 : 52272286bf32cdd1ed3285f914984c4d
DEBUG | 20181116-055334.616 | repro | RESIP:TRANSACTION | 36868 | TuSelector.cxx:70 | Send to TU: Proxy size=0 

UserInfoMessage(tid=1008681416)
DEBUG | 20181116-055334.617 | repro | REPRO:APP | 47117 | Proxy.cxx:167 | Got: UserInfoMessage(tid=1008681416)
DEBUG | 20181116-055334.619 | repro | REPRO:APP | 47117 | Proxy.cxx:438 | Trying to dispatch : UserInfoMessage(tid=1008681416)
DEBUG | 20181116-055334.620 | repro | REPRO:APP | 47117 | Proxy.cxx:443 | Sending UserInfoMessage(tid=1008681416) to numtrans=1 final=0 req=SipReq:  INVITE 122
@192.168.1.67 tid=1008681416 cseq=20 INVITE contact=111@127.0.0.1:6060 / 20 from(wire)
INFO | 20181116-055334.622 | repro | REPRO:APP | 47117 | RequestContext.cxx:657 | RequestContext::process(ApplicationMessage) UserInfoMessage(tid=1008681416)
DEBUG | 20181116-055334.955 | repro | REPRO:APP | 47117 | ProcessorChain.cxx:95 | Chain invoking RequestProcessor: MyLocationServer
DEBUG | 20181116-055334.956 | repro | RESIP | 47117 | Helper.cxx:374 | Helper::makeResponse(SipReq:  INVITE 122@192.168.1.67 tid=1008681416 cseq=20 INVITE cont
act=111@127.0.0.1:6060 / 20 from(wire) code=480 reason=
DEBUG | 20181116-055334.958 | repro | REPRO:APP | 47117 | RequestContext.cxx:937 | tid of orig req: 1008681416
DEBUG | 20181116-055334.959 | repro | REPRO:APP | 47117 | RequestContext.cxx:972 | Ensuring orig tid matches tid of response: 1008681416 == 1008681416
DEBUG | 20181116-055334.960 | repro | REPRO:APP | 47117 | RequestContext.cxx:979 | Sending final response.
DEBUG | 20181116-055334.962 | repro | RESIP | 47117 | SipStack.cxx:822 | SEND: SipResp: 480 tid=1008681416 cseq=20 INVITE / 20 from(tu)
DEBUG | 20181116-055334.964 | repro | REPRO:APP | 47117 | ProcessorChain.cxx:113 | RequestProcessor skipping current chain: MyLocationServer
DEBUG | 20181116-055334.966 | repro | RESIP:TRANSACTION | 43017 | TimerQueue.cxx:50 | Adding timer: Timer H tid=1008681416 ms=32000
DEBUG | 20181116-055334.967 | repro | RESIP:TRANSACTION | 43017 | TimerQueue.cxx:50 | Adding timer: Timer G tid=1008681416 ms=500
DEBUG | 20181116-055334.968 | repro | RESIP:TRANSPORT | 43017 | TransportSelector.cxx:1075 | Found transport: [ V4 0.0.0.0:5060 UDP flowKey=21 transportKey=1 ]
DEBUG | 20181116-055334.969 | repro | RESIP:TRANSPORT | 43017 | TransportSelector.cxx:871 | Looked up source for destination: [ V4 127.0.0.1:6060 UDP flowKey=2
1 transportKey=1 ] -> [ V4 127.0.0.1:0 UDP flowKey=21 transportKey=1 ] sent-by=192.168.1.67 sent-port=6060
DEBUG | 20181116-055334.971 | repro | RESIP:TRANSPORT | 43017 | TransportSelector.cxx:1291 | Transmitting to [ V4 127.0.0.1:6060 UDP flowKey=21 transportKey=1 
] tlsDomain= via [ V4 127.0.0.1:5060 UDP flowKey=21 transportKey=1 ]

SIP/2.0 480 Temporarily Unavailable
Via: SIP/2.0/UDP 192.168.1.67:6060;rport=6060;branch=z9hG4bK1008681416;received=127.0.0.1
To: <sip:122@192.168.1.67>;tag=e7c8bc13
From: <sip:111@192.168.1.67>;tag=1421584185
Call-ID: 318460658
CSeq: 20 INVITE
Server: repro 1.10.2
Content-Length: 0

sigcomp id=
DEBUG | 20181116-055334.979 | repro | RESIP:TRANSPORT | 44042 | Transport.cxx:392 | incoming from: [ V4 127.0.0.1:6060 UDP flowKey=21 transportKey=1 ]
sip_ua[548]: user_agent.cpp:1911: ---> transport 0: SIP event 9 (4xx received for Call!): cid=1, did=0, tid=6, rid=0, sid=0, nid=0
sip_ua[521]: fxs.cpp:419: ---> ts=127, state=Calling: Call disconnected, ts=127, flags=0000, data=1
sip_ua[521]: comcerto.cpp:6475: ts 127: stopping RTP stream
sip_ua[521]: comcerto.cpp:6475: ts 127: stopping RTP stream

repro-1.10.2

Change History (4)

comment:1 by alx, 5 years ago

Description: modified (diff)

comment:2 by alx, 5 years ago

Вот для сравнения успешный вызов телефона Grandstream, также зарегистрированного по TLS:

sip_ua[755]: fxs.cpp:419: ---> ts=127, state=Idle: CAS A activity detected, ts=127, flags=0000, data=0
sip_ua[755]: fxs.cpp:419: ---> ts=127, state=Idle: CAS event, ts=127, flags=0000, data=7
sip_ua[755]: fxs.cpp:702: ==> ts 127: manual CAS event processing (abcd=13)
sip_ua[755]: fxs.cpp:419: ---> ts=127, state=Dialtone: CAS event, ts=127, flags=0000, data=7
sip_ua[755]: fxs.cpp:419: ---> ts=127, state=Dialtone: DTMF detected, ts=127, flags=0000, data=1
sip_ua[755]: fxs.cpp:419: ---> ts=127, state=Dialing: Tone completed, ts=127, flags=0000, data=3
sip_ua[755]: fxs.cpp:419: ---> ts=127, state=Dialing: Tone detected, ts=127, flags=0000, data=255
sip_ua[755]: fxs.cpp:419: ---> ts=127, state=Dialing: DTMF detected, ts=127, flags=0000, data=2
sip_ua[755]: fxs.cpp:419: ---> ts=127, state=Dialing: Tone detected, ts=127, flags=0000, data=255
sip_ua[755]: fxs.cpp:419: ---> ts=127, state=Dialing: DTMF detected, ts=127, flags=0000, data=4
sip_ua[755]: fxs.cpp:419: ---> ts=127, state=Dialing: Tone detected, ts=127, flags=0000, data=255
DEBUG | 20181116-061746.608 | repro | RESIP:TRANSPORT | 28682 | ConnectionBase.cxx:137 | In State: NewMessage
DEBUG | 20181116-061746.609 | repro | RESIP:TRANSPORT | 28682 | ConnectionBase.cxx:147 | Got incoming double-CRLF keepalive (aka ping).
DEBUG | 20181116-061746.610 | repro | RESIP:TRANSPORT | 28682 | Connection.cxx:457 | Sending response CRLF (aka pong).
DEBUG | 20181116-061746.611 | repro | RESIP:TRANSPORT | 28682 | Connection.cxx:426 | Connection::performReads()  read=4
DEBUG | 20181116-061746.612 | repro | RESIP:TRANSPORT | 28682 | ConnectionBase.cxx:1017 | Creating buffer for CONN_BASE: 0x3a18f0 [ V4 192.168.0.105:11965 TLS 
flowKey=67 transportKey=3 ]
DEBUG | 20181116-061746.614 | repro | RESIP:TRANSPORT | 28682 | ssl/TlsConnection.cxx:499 | Transportwrite--Up fall through to write
sip_ua[755]: fxs.cpp:241: ts 127: dialing 124
sip_ua[755]: user_agent.cpp:3099: --> ua_dial_out() <sip:111@192.168.1.67> -> sip:124@192.168.1.67 ()...
DEBUG | 20181116-061747.183 | repro | RESIP:TRANSPORT | 28682 | Transport.cxx:392 | incoming from: [ V4 127.0.0.1:6060 UDP flowKey=21 transportKey=1 ]
DEBUG | 20181116-061747.185 | repro | RESIP:TRANSACTION | 27657 | TuSelector.cxx:189 | TuSelector::selectTransactionUser: Checking which TU message belongs to:

INVITE sip:124@192.168.1.67 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.67:6060;rport=6060;branch=z9hG4bK779376268;received=127.0.0.1
Max-Forwards: 70
Contact: <sip:111@127.0.0.1:6060>
To: <sip:124@192.168.1.67>
From: <sip:111@192.168.1.67>;tag=1949970815
Call-ID: 1101133270
CSeq: 20 INVITE
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, REFER, NOTIFY, INFO, UPDATE
Content-Type: application/sdp
Supported: replaces, timer
User-Agent: eXosip/4.1.0
X-Endpoint-Type: FXS
Content-Length: 955

v=0
o=gateway 0 0 IN IP4 192.168.1.67
s=conversation
c=IN IP4 192.168.1.67
t=0 0
m=audio 10254 RTP/SAVP 8 0 96 18 101 13 98
a=altc:1 IP6 2a01:540:2f05:7f00:ad:c3ff:fe00:6 10254
a=altc:2 IP4 192.168.1.67 10254
a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:X3OYQv2CgStVbcShx+lrkQCJgMqRs9mMKvmGDoV9
a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:ooAKuSX1/HiBjtlnzaSDtO420gKZewUhSz/PHpfg
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:96 G726-32/8000
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=rtpmap:13 CN/8000
a=rtpmap:98 PCMA/8000
a=gpmd:98 vbd=yes
a=ptime:30
m=audio 10254 RTP/AVP 8 0 96 18 101 13 98
a=altc:1 IP6 2a01:540:2f05:7f00:ad:c3ff:fe00:6 10254
a=altc:2 IP4 192.168.1.67 10254
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:96 G726-32/8000
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=rtpmap:13 CN/8000
a=rtpmap:98 PCMA/8000
a=gpmd:98 vbd=yes
a=ptime:30

DEBUG | 20181116-061747.187 | repro | RESIP:TRANSACTION | 27657 | TransactionUser.cxx:83 | TransactionUser::isForMe: TU=DialogUsageManager, Checking rule... : 
SipReq:  INVITE 124@192.168.1.67 tid=779376268 cseq=20 INVITE contact=111@127.0.0.1:6060 / 20 from(wire)
DEBUG | 20181116-061747.189 | repro | RESIP:TRANSACTION | 27657 | MessageFilterRule.cxx:66 |   MessageFilterRule::matches: Method is not in list. Rule does not
 match.
DEBUG | 20181116-061747.190 | repro | RESIP:TRANSACTION | 27657 | TransactionUser.cxx:90 | TransactionUser::isForMe: TU=DialogUsageManager, No matching rule fo
und : SipReq:  INVITE 124@192.168.1.67 tid=779376268 cseq=20 INVITE contact=111@127.0.0.1:6060 / 20 from(wire)
DEBUG | 20181116-061747.191 | repro | RESIP:TRANSACTION | 27657 | TransactionUser.cxx:83 | TransactionUser::isForMe: TU=Proxy, Checking rule... : SipReq:  INVI
TE 124@192.168.1.67 tid=779376268 cseq=20 INVITE contact=111@127.0.0.1:6060 / 20 from(wire)
DEBUG | 20181116-061747.192 | repro | RESIP:TRANSACTION | 27657 | TransactionUser.cxx:86 | TransactionUser::isForMe: TU=Proxy, Match! : SipReq:  INVITE 124@192
.168.1.67 tid=779376268 cseq=20 INVITE contact=111@127.0.0.1:6060 / 20 from(wire)
DEBUG | 20181116-061747.194 | repro | RESIP | 27657 | Helper.cxx:374 | Helper::makeResponse(SipReq:  INVITE 124@192.168.1.67 tid=779376268 cseq=20 INVITE conta
ct=111@127.0.0.1:6060 / 20 from(wire) code=100 reason=
DEBUG | 20181116-061747.196 | repro | RESIP:TRANSACTION | 27657 | TimerQueue.cxx:50 | Adding timer: Timer Trying tid=779376268 ms=80
DEBUG | 20181116-061747.197 | repro | RESIP:TRANSACTION | 27657 | TuSelector.cxx:70 | Send to TU: Proxy size=0 

INVITE sip:124@192.168.1.67 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.67:6060;rport=6060;branch=z9hG4bK779376268;received=127.0.0.1
Max-Forwards: 70
Contact: <sip:111@127.0.0.1:6060>
To: <sip:124@192.168.1.67>
From: <sip:111@192.168.1.67>;tag=1949970815
Call-ID: 1101133270
CSeq: 20 INVITE
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, REFER, NOTIFY, INFO, UPDATE
Content-Type: application/sdp
Supported: replaces, timer
User-Agent: eXosip/4.1.0
X-Endpoint-Type: FXS
Content-Length: 955

v=0
o=gateway 0 0 IN IP4 192.168.1.67
s=conversation
c=IN IP4 192.168.1.67
t=0 0
m=audio 10254 RTP/SAVP 8 0 96 18 101 13 98
a=altc:1 IP6 2a01:540:2f05:7f00:ad:c3ff:fe00:6 10254
a=altc:2 IP4 192.168.1.67 10254
a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:X3OYQv2CgStVbcShx+lrkQCJgMqRs9mMKvmGDoV9
a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:ooAKuSX1/HiBjtlnzaSDtO420gKZewUhSz/PHpfg
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:96 G726-32/8000
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=rtpmap:13 CN/8000
a=rtpmap:98 PCMA/8000
a=gpmd:98 vbd=yes
a=ptime:30
m=audio 10254 RTP/AVP 8 0 96 18 101 13 98
a=altc:1 IP6 2a01:540:2f05:7f00:ad:c3ff:fe00:6 10254
a=altc:2 IP4 192.168.1.67 10254
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:96 G726-32/8000
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=rtpmap:13 CN/8000
a=rtpmap:98 PCMA/8000
a=gpmd:98 vbd=yes
a=ptime:30

DEBUG | 20181116-061747.534 | repro | REPRO:APP | 31757 | Proxy.cxx:167 | Got: INVITE sip:124@192.168.1.67 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.67:6060;rport=6060;branch=z9hG4bK779376268;received=127.0.0.1
Max-Forwards: 70
Contact: <sip:111@127.0.0.1:6060>
To: <sip:124@192.168.1.67>
From: <sip:111@192.168.1.67>;tag=1949970815
Call-ID: 1101133270
CSeq: 20 INVITE
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, REFER, NOTIFY, INFO, UPDATE
Content-Type: application/sdp
Supported: replaces, timer
User-Agent: eXosip/4.1.0
X-Endpoint-Type: FXS
Content-Length: 955

v=0
o=gateway 0 0 IN IP4 192.168.1.67
s=conversation
c=IN IP4 192.168.1.67
t=0 0
m=audio 10254 RTP/SAVP 8 0 96 18 101 13 98
a=altc:1 IP6 2a01:540:2f05:7f00:ad:c3ff:fe00:6 10254
a=altc:2 IP4 192.168.1.67 10254
a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:X3OYQv2CgStVbcShx+lrkQCJgMqRs9mMKvmGDoV9
a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:ooAKuSX1/HiBjtlnzaSDtO420gKZewUhSz/PHpfg
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:96 G726-32/8000
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=rtpmap:13 CN/8000
a=rtpmap:98 PCMA/8000
a=gpmd:98 vbd=yes
a=ptime:30
m=audio 10254 RTP/AVP 8 0 96 18 101 13 98
a=altc:1 IP6 2a01:540:2f05:7f00:ad:c3ff:fe00:6 10254
a=altc:2 IP4 192.168.1.67 10254
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:96 G726-32/8000
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=rtpmap:13 CN/8000
a=rtpmap:98 PCMA/8000
a=gpmd:98 vbd=yes
a=ptime:30

INFO | 20181116-061747.536 | repro | REPRO:APP | 31757 | Proxy.cxx:366 | New RequestContext tid=779376268 : SipReq:  INVITE 124@192.168.1.67 tid=779376268 cseq
=20 INVITE contact=111@127.0.0.1:6060 / 20 from(wire)
INFO | 20181116-061747.538 | repro | REPRO:APP | 31757 | Proxy.cxx:376 | Inserting new RequestContext tid=779376268 -> numtrans=1 final=0
INFO | 20181116-061747.539 | repro | REPRO:APP | 31757 | RequestContext.cxx:92 | RequestContext::process(SipMessage) 779376268
DEBUG | 20181116-061747.539 | repro | REPRO:APP | 31757 | RequestContext.cxx:152 | Got a request.
DEBUG | 20181116-061747.540 | repro | REPRO:APP | 31757 | ProcessorChain.cxx:95 | Chain invoking RequestProcessor: StrictRouteFixup
DEBUG | 20181116-061747.541 | repro | REPRO:APP | 31757 | monkeys/StrictRouteFixup.cxx:39 | Monkey handling request: StrictRouteFixup; reqcontext = numtrans=1 
final=0 req=SipReq:  INVITE 124@192.168.1.67 tid=779376268 cseq=20 INVITE contact=111@127.0.0.1:6060 / 20 from(wire)
DEBUG | 20181116-061747.533 | repro | RESIP:TRANSPORT | 27657 | TransportSelector.cxx:1075 | Found transport: [ V4 0.0.0.0:5060 UDP flowKey=21 transportKey=1 ]
DEBUG | 20181116-061747.546 | repro | RESIP:TRANSPORT | 27657 | TransportSelector.cxx:871 | Looked up source for destination: [ V4 127.0.0.1:6060 UDP flowKey=2
1 transportKey=1 ] -> [ V4 127.0.0.1:0 UDP flowKey=21 transportKey=1 ] sent-by=192.168.1.67 sent-port=6060
DEBUG | 20181116-061747.548 | repro | RESIP:TRANSPORT | 27657 | TransportSelector.cxx:1291 | Transmitting to [ V4 127.0.0.1:6060 UDP flowKey=21 transportKey=1 
] tlsDomain= via [ V4 127.0.0.1:5060 UDP flowKey=21 transportKey=1 ]

SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.1.67:6060;rport=6060;branch=z9hG4bK779376268;received=127.0.0.1
To: <sip:124@192.168.1.67>
From: <sip:111@192.168.1.67>;tag=1949970815
Call-ID: 1101133270
CSeq: 20 INVITE
Content-Length: 0

sigcomp id=
DEBUG | 20181116-061747.543 | repro | REPRO:APP | 31757 | ProcessorChain.cxx:95 | Chain invoking RequestProcessor: IsTrustedNode
DEBUG | 20181116-061747.687 | repro | RESIP:TRANSPORT | 28682 | Transport.cxx:392 | incoming from: [ V4 127.0.0.1:6060 UDP flowKey=21 transportKey=1 ]
DEBUG | 20181116-061747.882 | repro | REPRO:APP | 31757 | monkeys/IsTrustedNode.cxx:36 | Monkey handling request: IsTrustedNode; reqcontext = numtrans=1 final=
0 req=SipReq:  INVITE 124@192.168.1.67 tid=779376268 cseq=20 INVITE contact=111@127.0.0.1:6060 / 20 from(wire)
INFO | 20181116-061747.885 | repro | REPRO:APP | 31757 | AclStore.cxx:553 | AclStore - source address IS trusted: 127.0.0.1:6060 UDP
DEBUG | 20181116-061747.886 | repro | REPRO:APP | 31757 | ProcessorChain.cxx:95 | Chain invoking RequestProcessor: DigestAuthenticator
DEBUG | 20181116-061747.887 | repro | REPRO:APP | 31757 | monkeys/DigestAuthenticator.cxx:51 | Monkey handling request: DigestAuthenticator; reqcontext = numtr
ans=1 final=0 req=SipReq:  INVITE 124@192.168.1.67 tid=779376268 cseq=20 INVITE contact=111@127.0.0.1:6060 / 20 from(wire)
DEBUG | 20181116-061747.888 | repro | REPRO:APP | 31757 | ProcessorChain.cxx:95 | Chain invoking RequestProcessor: AmIResponsible
DEBUG | 20181116-061747.889 | repro | REPRO:APP | 31757 | monkeys/AmIResponsible.cxx:32 | Monkey handling request: AmIResponsible; reqcontext = numtrans=1 fina
l=0 req=SipReq:  INVITE 124@192.168.1.67 tid=779376268 cseq=20 INVITE contact=111@127.0.0.1:6060 / 20 from(wire)
sip_ua[870]: repro.cpp:439: doSessionAccounting(): Session Created 'branch=z9hG4bK779376268'
DEBUG | 20181116-061747.892 | repro | REPRO:APP | 31757 | Proxy.cxx:595 | Proxy::isMyUri sip:124@192.168.1.67 1
DEBUG | 20181116-061747.899 | repro | REPRO:APP | 31757 | ProcessorChain.cxx:95 | Chain invoking RequestProcessor: RequestFilter
DEBUG | 20181116-061747.900 | repro | REPRO:APP | 31757 | monkeys/RequestFilter.cxx:154 | Monkey handling request: RequestFilter; reqcontext = numtrans=1 final
=0 req=SipReq:  INVITE 124@192.168.1.67 tid=779376268 cseq=20 INVITE contact=111@127.0.0.1:6060 / 20 from(wire)
DEBUG | 20181116-061747.901 | repro | REPRO:APP | 31757 | monkeys/RequestFilter.cxx:147 | Request is accepted
DEBUG | 20181116-061747.902 | repro | REPRO:APP | 31757 | ProcessorChain.cxx:95 | Chain invoking RequestProcessor: MyStaticRoute
DEBUG | 20181116-061747.903 | repro | REPRO:APP | 31757 | reproStaticRoute.cpp:38 | Monkey handling request: MyStaticRoute; reqcontext = numtrans=1 final=0 req
=SipReq:  INVITE 124@192.168.1.67 tid=779376268 cseq=20 INVITE contact=111@127.0.0.1:6060 / 20 from(wire)
DEBUG | 20181116-061747.906 | repro | REPRO:APP | 31757 | ProcessorChain.cxx:95 | Chain invoking RequestProcessor: MyLocationServer
DEBUG | 20181116-061747.907 | repro | RESIP:DUM | 31757 | InMemorySyncRegDb.cxx:258 | InMemorySyncRegDb::lockRecord:  aor=sip:124@192.168.1.67 threadid=31757
INFO | 20181116-061747.909 | repro | REPRO:APP | 31757 | repro.cpp:203 | MyLocationServer adding target <sip:124@192.168.0.118:24522;transport=tls>;reg-id=3;+s
ip.instance="<urn:uuid:00000000-0000-1000-8000-000B8253B715>" with tuple [ V4 192.168.0.118:40257 TLS flowKey=68 transportKey=3 ]
DEBUG | 20181116-061747.911 | repro | RESIP:DUM | 31757 | InMemorySyncRegDb.cxx:279 | InMemorySyncRegDb::unlockRecord:  aor=sip:124@192.168.1.67 threadid=31757
DEBUG | 20181116-061747.912 | repro | REPRO:APP | 31757 | ResponseContext.cxx:151 | Adding Target to Candidates: sip:124@192.168.0.118:24522;transport=tls tid=
68628505e1e48d26
DEBUG | 20181116-061747.913 | repro | REPRO:APP | 31757 | ProcessorChain.cxx:95 | Chain invoking RequestProcessor: DialogTracker
INFO | 20181116-061747.918 | repro | REPRO:APP | 31757 | RequestContext.cxx:445 | numtrans=1 final=0 req=SipReq:  INVITE 124@192.168.1.67 tid=779376268 cseq=20
 INVITE contact=111@127.0.0.1:6060 / 20 from(wire) there are 1 candidates -> continue
DEBUG | 20181116-061747.919 | repro | REPRO:APP | 31757 | ProcessorChain.cxx:95 | Chain invoking TargetProcessor: DialogTracker
DEBUG | 20181116-061747.921 | repro | REPRO:APP | 31757 | ProcessorChain.cxx:95 | Chain invoking TargetProcessor: QValueTargetHandler
DEBUG | 20181116-061748.254 | repro | REPRO:APP | 31757 | monkeys/QValueTargetHandler.cxx:106 | No ForkControlMessage for me.
DEBUG | 20181116-061748.255 | repro | REPRO:APP | 31757 | monkeys/QValueTargetHandler.cxx:126 | QValueTargetHandler: Found a queue of QValueTargets. Are any of
 them active?
DEBUG | 20181116-061748.256 | repro | REPRO:APP | 31757 | monkeys/QValueTargetHandler.cxx:150 | There are no active targets here. Looking for a group to start.
DEBUG | 20181116-061748.257 | repro | REPRO:APP | 31757 | monkeys/QValueTargetHandler.cxx:164 | This queue has a group of targets in it. Trying to start this g
roup.
DEBUG | 20181116-061748.259 | repro | REPRO:APP | 31757 | Proxy.cxx:595 | Proxy::isMyUri sip:124@192.168.0.118:24522;transport=tls 0
INFO | 20181116-061748.260 | repro | REPRO:APP | 31757 | ResponseContext.cxx:673 | Added Record-Route: <sip:192.168.1.67:5060;transport=udp;lr>
DEBUG | 20181116-061748.262 | repro | REPRO:APP | 31757 | ResponseContext.cxx:563 | Set tuple dest: [ V4 0.0.0.0:0 UNKNOWN_TRANSPORT ]
sip_ua[782]: user_agent.cpp:1911: ---> transport 0: SIP event 5 (Call is being processed!): cid=1, did=0, tid=3, rid=0, sid=0, nid=0
sip_ua[782]: user_agent.cpp:1911: ---> transport 0: SIP event 5 (Call is being processed!): cid=1, did=0, tid=3, rid=0, sid=0, nid=0
INFO | 20181116-061748.263 | repro | REPRO:APP | 31757 | RequestContext.cxx:890 | Updating timer C.
INFO | 20181116-061748.266 | repro | REPRO:APP | 31757 | Proxy.cxx:554 | Posting timer C
DEBUG | 20181116-061748.267 | repro | RESIP:TRANSACTION | 31757 | TimerQueue.cxx:124 | Adding application timer: TimerCMessage(tid=779376268) ms=180000
INFO | 20181116-061748.268 | repro | REPRO:APP | 31757 | Proxy.cxx:540 | add client transaction tid=68628505e1e48d26 0x395250
DEBUG | 20181116-061748.270 | repro | REPRO:APP | 31757 | Proxy.cxx:595 | Proxy::isMyUri sip:124@192.168.0.118:24522;transport=tls 0
DEBUG | 20181116-061748.271 | repro | RESIP | 31757 | SipStack.cxx:822 | SEND: SipReq:  INVITE 124@192.168.0.118:24522 tid=68628505e1e48d26 cseq=20 INVITE cont
act=111@127.0.0.1:6060 / 20 from(wire)
DEBUG | 20181116-061748.275 | repro | RESIP:TRANSACTION | 27657 | TimerQueue.cxx:50 | Adding timer: Timer B tid=68628505e1e48d26 ms=32000
DEBUG | 20181116-061748.276 | repro | RESIP:TRANSPORT | 27657 | TransportSelector.cxx:556 | Looking up dns entries for sip:124@192.168.0.118:24522;transport=tl
s
DEBUG | 20181116-061748.277 | repro | RESIP:DNS | 27657 | DnsResult.cxx:261 | DnsResult::lookup sip:124@192.168.0.118:24522;transport=tls
INFO | 20181116-061748.279 | repro | REPRO:APP | 31757 | ResponseContext.cxx:234 | Creating new client transaction 68628505e1e48d26 -> sip:124@192.168.0.118:24
522;transport=tls
DEBUG | 20181116-061748.280 | repro | REPRO:APP | 31757 | monkeys/QValueTargetHandler.cxx:179 | Successfully started some targets.
DEBUG | 20181116-061748.281 | repro | RESIP:TRANSACTION | 31757 | TimerQueue.cxx:124 | Adding application timer: ForkControlMessage(tid=779376268):  newTrans=[
] cancelTrans=[68628505e1e48d26] cancelAll=0 ms=30000
DEBUG | 20181116-061748.284 | repro | RESIP:DNS | 26632 | DnsResult.cxx:328 | Found immediate result: [ V4 192.168.0.118:24522 TLS targetDomain=192.168.0.118 ]
DEBUG | 20181116-061748.283 | repro | REPRO:APP | 31757 | ProcessorChain.cxx:101 | TargetProcessor aborted all chains: QValueTargetHandler
DEBUG | 20181116-061748.287 | repro | RESIP:TRANSPORT | 27657 | TransportSelector.cxx:938 | Found transport: [ V4 0.0.0.0:5061 TLS transportKey=3 ]
DEBUG | 20181116-061748.288 | repro | RESIP:TRANSPORT | 27657 | TransportSelector.cxx:871 | Looked up source for destination: [ V4 192.168.0.118:24522 TLS targ
etDomain=192.168.0.118 ] -> [ V4 192.168.1.67:0 TLS targetDomain=192.168.0.118 ] sent-by= sent-port=0
DEBUG | 20181116-061748.289 | repro | REPRO:APP | 27657 | RRDecorator.cxx:60 | Proxy::decorateMessage called.
INFO | 20181116-061748.290 | repro | REPRO:APP | 27657 | RRDecorator.cxx:173 | Adding outbound Record-Route: <sip:192.168.1.67:5061;transport=tls;lr;drr>
DEBUG | 20181116-061748.293 | repro | RESIP:TRANSPORT | 27657 | TransportSelector.cxx:1291 | Transmitting to [ V4 192.168.0.118:24522 TLS targetDomain=192.168.
0.118 transportKey=3 ] tlsDomain= via [ V4 192.168.1.67:5061 TLS targetDomain=192.168.0.118 ]

INVITE sip:124@192.168.0.118:24522;transport=tls SIP/2.0
Via: SIP/2.0/TLS 192.168.1.67:5061;branch=z9hG4bK-524287-1---68628505e1e48d26;rport
Via: SIP/2.0/UDP 192.168.1.67:6060;rport=6060;branch=z9hG4bK779376268;received=127.0.0.1
Max-Forwards: 69
Record-Route: <sip:192.168.1.67:5061;transport=tls;lr;drr>
Record-Route: <sip:192.168.1.67:5060;transport=udp;lr;drr>
Contact: <sip:111@127.0.0.1:6060>
To: <sip:124@192.168.1.67>
From: <sip:111@192.168.1.67>;tag=1949970815
Call-ID: 1101133270
CSeq: 20 INVITE
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, REFER, NOTIFY, INFO, UPDATE
Content-Type: application/sdp
Supported: replaces, timer
User-Agent: eXosip/4.1.0
X-Endpoint-Type: FXS
Content-Length: 955

v=0
o=gateway 0 0 IN IP4 192.168.1.67
s=conversation
c=IN IP4 192.168.1.67
t=0 0
m=audio 10254 RTP/SAVP 8 0 96 18 101 13 98
a=altc:1 IP6 2a01:540:2f05:7f00:ad:c3ff:fe00:6 10254
a=altc:2 IP4 192.168.1.67 10254
a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:X3OYQv2CgStVbcShx+lrkQCJgMqRs9mMKvmGDoV9
a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:ooAKuSX1/HiBjtlnzaSDtO420gKZewUhSz/PHpfg
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:96 G726-32/8000
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=rtpmap:13 CN/8000
a=rtpmap:98 PCMA/8000
a=gpmd:98 vbd=yes
a=ptime:30
m=audio 10254 RTP/AVP 8 0 96 18 101 13 98
a=altc:1 IP6 2a01:540:2f05:7f00:ad:c3ff:fe00:6 10254
a=altc:2 IP4 192.168.1.67 10254
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:96 G726-32/8000
a=rtpmap:18 G729/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=rtpmap:13 CN/8000
a=rtpmap:98 PCMA/8000
a=gpmd:98 vbd=yes
a=ptime:30
sigcomp id=

comment:3 by alx, 5 years ago

Обнаружено отличие в регистрациях: телефон Grandstream зарегистрирован на AOR sip:124@192.168.1.67, а телефон Yealink зарегистрирован на AOR 122@192.168.1.67:5060. При вызове номера 122 в базе данных выполняется поиск по 122@192.168.1.67, в результате контакты не находятся...

Вот запросы регистрации этих телефонов:

REGISTER sip:192.168.1.67:5061 SIP/2.0
Via: SIP/2.0/TLS 192.168.0.118:24522;branch=z9hG4bK1071222818;rport=59715;alias
Max-Forwards: 70
Contact: <sip:124@192.168.0.118:24522;transport=tls>;reg-id=3;+sip.instance="<urn:uuid:00000000-0000-1000-8000-000B8253B715>"
To: <sip:124@192.168.1.67:5061>
From: <sip:124@192.168.1.67:5061>;tag=1187789185
Call-ID: 1989048639-24522-1@BJC.BGI.A.BBI
CSeq: 2936 REGISTER
Expires: 300
Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
Supported: path
User-Agent: Grandstream GXV3175v2 1.0.1.55
Content-Length: 0
REGISTER sip:192.168.1.67:5060 SIP/2.0
Via: SIP/2.0/TLS 192.168.0.105:12019;branch=z9hG4bK1838334395
Max-Forwards: 70
Contact: <sip:122@192.168.0.105:12019;transport=TLS>
To: "122" <sip:122@192.168.1.67:5060>
From: "122" <sip:122@192.168.1.67:5060>;tag=4014138986
Call-ID: 1_1445704748@192.168.0.105
CSeq: 1 REGISTER
Expires: 300
Allow: INVITE, INFO, PRACK, ACK, BYE, CANCEL, OPTIONS, NOTIFY, REGISTER, SUBSCRIBE, REFER, PUBLISH, UPDATE, MESSAGE
User-Agent: Yealink SIP-T21P_E2 52.80.14.2
Allow-Events: talk, hold, conference, refer, check-sync
Content-Length: 0

Похоже, что причиной является указание неверного номера порта (5060 вместо 5061) телефоном Yealink...

comment:4 by alx, 5 years ago

Resolution: invalid
Status: newclosed

Да, предположение подтвердилось. Так как телефон указал номер порта, отличного от дефолтного порта для используемого типа транспорта (5061 для SIPS), номер порта был добавлен к AOR. В результате LocationServer при последующем вызове не нашел регистрацию в базе данных.

Note: See TracTickets for help on using tickets.