Nový PJSIP přitvrdil?

tls,srtp,zrtp a bezpečnostní potíže VoIP
Netolish
Příspěvky: 23
Registrován: pon 04. bře 2013 12:24:16

Re: Nový PJSIP přitvrdil?

Příspěvek od Netolish »

Moc díky.

Teď to ještě zkoumám. Volání 419194 (PJSIP) -> 687543 (SPA112) projde - telefon zvoní
hovor funguje. Na obou linkách mám zapnuto SRTP pro příchozí hovory.
Volání obráceně 687543 -> 419194 skončí 480 SIPS required. Asi je PJSIP
striktnější při kontrole pravidel než SPA112. Zřejmě není ale tolik užívaná,
tak si moc lidí nestěžuje. Každopádně snad to přispěje k dalšímu zkvalitnění
vašich už tak skvělých služeb.
Diky
Uživatelský avatar
kovik
Příspěvky: 505
Registrován: stř 16. lis 2011 11:07:52

Re: Nový PJSIP přitvrdil?

Příspěvek od kovik »

Ano,pjsip chce jeste 'sips:' v contaktu,mam to pripravene po nocnim restartu by to melo fungovat komplet.
Dekuji za test a budu vdecny za dalsi.
Netolish
Příspěvky: 23
Registrován: pon 04. bře 2013 12:24:16

Re: Nový PJSIP přitvrdil?

Příspěvek od Netolish »

Dnes jsem zkusil všechny kombinace, které mne napadly
tedy PJSIP na počítači, CSipSimple v telefonu a SPA112
a vše fungovalo jak má.
Diky moc za rychlé vyřešení.
Uživatelský avatar
kovik
Příspěvky: 505
Registrován: stř 16. lis 2011 11:07:52

Re: Nový PJSIP přitvrdil?

Příspěvek od kovik »

Netolish píše:Dnes jsem zkusil všechny kombinace, které mne napadly
tedy PJSIP na počítači, CSipSimple v telefonu a SPA112
a vše fungovalo jak má.
Diky moc za rychlé vyřešení.
Ja dekuji za nahlaseni.
Netolish
Příspěvky: 23
Registrován: pon 04. bře 2013 12:24:16

Re: Nový PJSIP přitvrdil?

Příspěvek od Netolish »

Aktualizoval jsem si PJSIP na verzi 2.8 a je tu podobný problém v domluvě na šifrování. Tentokrát ale nevím,
zda se chová PJSIP korektně. Po prvním úspěšném INVITE totiž pošle ještě jednou skoro stejný,
dle debug logu, z důvodu "Call 0 sending re-INVITE for updating media session to use only one codec".

V prvním INVITE jsou 4 a:crypto. Server si vybere ten 3. a použije v odpovědi. Ve druhém INVITE
je však ze strany PJSIP už pouze jeden a:crypto s číslem 1. Server si však ponechá původní
s číslem 3 a komunikace skončí na Invalid "SRTP crypto tag". Neboť dle RFC4568 5.1.2
by měly tagy "sedět".

Můžete prosím prověřit jestli je to chyba PJSIP (posílat dvakrát INVITE),
nebo serveru (ponechá si původní klíč s, vzhledem k novému invite, neplatným tagem)?

Omlouvám se, že s tím pořád otravuju, ale po té co gůgl vyhodil CSipSimple,
řekl jsem si že si ho budu buildit sám a rovnou při té příležitosti zaktualizuji
i pjsip který používá.

Díky Netolický

Kód: Vybrat vše

INVITE sips:*081@sip.odorik.cz:6670 SIP/2.0
Via: SIP/2.0/TLS 37.157.194.179:57375;rport;branch=z9hG4bKPjfd24b587-4f17-4d7f-86c4-91e0874a4848;alias
Max-Forwards: 70
From: sips:419194@sip.odorik.cz;tag=88036dd1-5345-4a4b-bcad-bab7e1808450
To: sips:*081@sip.odorik.cz
Contact: <sips:419194@172.16.3.6:57375;transport=TLS;ob>
Call-ID: 7692b4d2-aa32-4bca-89d4-e4086c9ac5e1
CSeq: 5640 INVITE
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Session-Expires: 1800
Min-SE: 90
User-Agent: PJSUA v2.8 Linux-5.0.1/x86_64/glibc-2.28
Proxy-Authorization: Digest username="419194", realm="sip.odorik.cz", nonce="XIj1t1yI9IvSVsECGhs9JzOn2zv4eK3D", uri="sips:*081@sip.odorik.cz:6670", response="***************"
Content-Type: application/sdp
Content-Length:   821

v=0
o=- 3761468171 3761468171 IN IP4 172.16.3.6
s=pjmedia
b=AS:117
t=0 0
a=X-nat:0
m=audio 4000 RTP/SAVP 104 3 8 120 96
c=IN IP4 172.16.3.6
b=TIAS:96000
a=rtcp:4001 IN IP4 172.16.3.6
a=sendrecv
a=rtpmap:104 iLBC/8000
a=fmtp:104 mode=30
a=rtpmap:3 GSM/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:120 opus/48000/2
a=fmtp:120 useinbandfec=1
a=rtpmap:96 telephone-event/8000
a=fmtp:96 0-16
a=ssrc:316208229 cname:2ce8bdf93f6e3cff
a=crypto:1 AES_256_CM_HMAC_SHA1_80 inline:U3m0Qx8xHyZhDHkgrZPiG/wJa5z6VGIj0JIFWqFa/fV35qi5aZTx8jdtyO/43A==
a=crypto:2 AES_256_CM_HMAC_SHA1_32 inline:7X19aSagSPN+JbOs8TmDi8QoVsv50ziN3czGkFoID1krVU1mHNzSLvFJYTOoNA==
a=crypto:3 AES_CM_128_HMAC_SHA1_80 inline:VOMK3U4bKK4V2a8EcyK5twU7q69crUNuEkz92aUG
a=crypto:4 AES_CM_128_HMAC_SHA1_32 inline:PfQFbG5poXjMIu2ZnbxDZZR9F6RNVX5qOhLgjnyb

--end msg--

SIP/2.0 100 Trying
Via: SIP/2.0/TLS 37.157.194.179:57375;rport=57375;branch=z9hG4bKPjfd24b587-4f17-4d7f-86c4-91e0874a4848;alias;received=37.157.194.179
From: sips:419194@sip.odorik.cz;tag=88036dd1-5345-4a4b-bcad-bab7e1808450
To: sips:*081@sip.odorik.cz
Call-ID: 7692b4d2-aa32-4bca-89d4-e4086c9ac5e1
CSeq: 5640 INVITE
Content-Length: 0


--end msg--

SIP/2.0 200 OK
Via: SIP/2.0/TLS 37.157.194.179:57375;received=37.157.194.179;rport=57375;branch=z9hG4bKPjfd24b587-4f17-4d7f-86c4-91e0874a4848;alias
Record-Route: <sips:*081@81.31.45.51;r2=on;lr=on;ftag=88036dd1-5345-4a4b-bcad-bab7e1808450;did=4719.58be2;nat=yes>
Record-Route: <sips:*081@81.31.45.51:6670;transport=tls;r2=on;lr=on;ftag=88036dd1-5345-4a4b-bcad-bab7e1808450;did=4719.58be2;nat=yes>
From: sips:419194@sip.odorik.cz;tag=88036dd1-5345-4a4b-bcad-bab7e1808450
To: sips:*081@sip.odorik.cz;tag=as58fb17ff
Call-ID: 7692b4d2-aa32-4bca-89d4-e4086c9ac5e1
CSeq: 5640 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces
Contact: <sips:*081@89.185.255.38:5060;alias=89.185.255.38~5060~1>
Content-Type: application/sdp
Content-Length: 370

v=0
o=root 575156978 575156978 IN IP4 89.185.255.38
s=-
c=IN IP4 89.185.255.38
t=0 0
m=audio 22810 RTP/SAVP 8 3 104 96
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:104 iLBC/8000
a=fmtp:104 mode=30
a=rtpmap:96 telephone-event/8000
a=fmtp:96 0-16
a=ptime:20
a=sendrecv
a=crypto:3 AES_CM_128_HMAC_SHA1_80 inline:DG9UQ/x7u8HaWfZuJXzQ2MbN2WkXAtkFhDhmnpzk

--end msg--

ACK sips:*081@89.185.255.38:5060;alias=89.185.255.38~5060~1 SIP/2.0
Via: SIP/2.0/TLS 37.157.194.179:57375;rport;branch=z9hG4bKPj0f7e91cf-c7ca-421c-88cd-1799d0a13dd0;alias
Max-Forwards: 70
From: sips:419194@sip.odorik.cz;tag=88036dd1-5345-4a4b-bcad-bab7e1808450
To: sips:*081@sip.odorik.cz;tag=as58fb17ff
Call-ID: 7692b4d2-aa32-4bca-89d4-e4086c9ac5e1
CSeq: 5640 ACK
Route: <sips:*081@81.31.45.51:6670;transport=tls;lr;r2=on;ftag=88036dd1-5345-4a4b-bcad-bab7e1808450;did=4719.58be2;nat=yes>
Route: <sips:*081@81.31.45.51;lr;r2=on;ftag=88036dd1-5345-4a4b-bcad-bab7e1808450;did=4719.58be2;nat=yes>
Content-Length:  0


--end msg--
13:16:12.983           pjsua_call.c  .Call 0 sending re-INVITE for updating media session to use only one codec

INVITE sips:*081@89.185.255.38:5060;alias=89.185.255.38~5060~1 SIP/2.0
Via: SIP/2.0/TLS 37.157.194.179:57375;rport;branch=z9hG4bKPj8421afe6-71f8-40aa-bfaa-1bda51a3c0e5;alias
Max-Forwards: 70
From: sips:419194@sip.odorik.cz;tag=88036dd1-5345-4a4b-bcad-bab7e1808450
To: sips:*081@sip.odorik.cz;tag=as58fb17ff
Contact: <sips:419194@172.16.3.6:57375;transport=TLS;ob>
Call-ID: 7692b4d2-aa32-4bca-89d4-e4086c9ac5e1
CSeq: 5641 INVITE
Route: <sips:*081@81.31.45.51:6670;transport=tls;lr;r2=on;ftag=88036dd1-5345-4a4b-bcad-bab7e1808450;did=4719.58be2;nat=yes>
Route: <sips:*081@81.31.45.51;lr;r2=on;ftag=88036dd1-5345-4a4b-bcad-bab7e1808450;did=4719.58be2;nat=yes>
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Session-Expires: 1800
Min-SE: 90
Content-Type: application/sdp
Content-Length:   392

v=0
o=- 3761468171 3761468172 IN IP4 172.16.3.6
s=pjmedia
b=AS:117
t=0 0
a=X-nat:0
m=audio 4000 RTP/SAVP 8 96
c=IN IP4 172.16.3.6
b=TIAS:96000
a=rtcp:4001 IN IP4 172.16.3.6
a=ssrc:316208229 cname:2ce8bdf93f6e3cff
a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:VOMK3U4bKK4V2a8EcyK5twU7q69crUNuEkz92aUG
a=rtpmap:8 PCMA/8000
a=rtpmap:96 telephone-event/8000
a=fmtp:96 0-16
a=sendrecv

--end msg--

SIP/2.0 100 Trying
Via: SIP/2.0/TLS 37.157.194.179:57375;rport=57375;branch=z9hG4bKPj8421afe6-71f8-40aa-bfaa-1bda51a3c0e5;alias;received=37.157.194.179
From: sips:419194@sip.odorik.cz;tag=88036dd1-5345-4a4b-bcad-bab7e1808450
To: sips:*081@sip.odorik.cz;tag=as58fb17ff
Call-ID: 7692b4d2-aa32-4bca-89d4-e4086c9ac5e1
CSeq: 5641 INVITE
Content-Length: 0

--end msg--

SIP/2.0 200 OK
Via: SIP/2.0/TLS 37.157.194.179:57375;received=37.157.194.179;rport=57375;branch=z9hG4bKPj8421afe6-71f8-40aa-bfaa-1bda51a3c0e5;alias
Record-Route: <sips:*081@81.31.45.51;r2=on;lr=on;ftag=88036dd1-5345-4a4b-bcad-bab7e1808450;did=4719.58be2>
Record-Route: <sips:*081@81.31.45.51:6670;transport=tls;r2=on;lr=on;ftag=88036dd1-5345-4a4b-bcad-bab7e1808450;did=4719.58be2>
From: sips:419194@sip.odorik.cz;tag=88036dd1-5345-4a4b-bcad-bab7e1808450
To: sips:*081@sip.odorik.cz;tag=as58fb17ff
Call-ID: 7692b4d2-aa32-4bca-89d4-e4086c9ac5e1
CSeq: 5641 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces
Contact: <sips:*081@89.185.255.38:5060;alias=89.185.255.38~5060~1>
Content-Type: application/sdp
Content-Length: 299

v=0
o=root 575156978 575156979 IN IP4 89.185.255.38
s=-
c=IN IP4 89.185.255.38
t=0 0
m=audio 22810 RTP/SAVP 8 96
a=rtpmap:8 PCMA/8000
a=rtpmap:96 telephone-event/8000
a=fmtp:96 0-16
a=ptime:20
a=sendrecv
a=crypto:3 AES_CM_128_HMAC_SHA1_80 inline:DG9UQ/x7u8HaWfZuJXzQ2MbN2WkXAtkFhDhmnpzk

--end msg--
13:16:13.094          pjsua_media.c  ......pjmedia_transport_media_start() failed for call_id 0 media 0: Invalid SRTP crypto tag 
Uživatelský avatar
kovik
Příspěvky: 505
Registrován: stř 16. lis 2011 11:07:52

Re: Nový PJSIP přitvrdil?

Příspěvek od kovik »

Dobry den,
aktualizoval jsem take na 2.8 z svn, zkusil hovor a prosel.

Pouzil sem pjsua, a aby to neposilalo RE-INVITE, tak jsem odstranil codeky, ale slo mi to i bez odstraneni codeku.

Kód: Vybrat vše

pjsua  --registrar=sips:sip.odorik.cz --id=sips:405277@sip.odorik.cz --realm=* --username=405277 --password=XxXxXxXx --disable-stun --use-tls --use-srtp=2 sips:*412879@sip.odorik.cz --null-audio --dis-codec=speex --dis-codec=GSM --dis-codec=iLBC --dis-codec=G722 --dis-codec=PCMU --dis-codec=opus --log-file=/tmp/xxx

Kód: Vybrat vše

17:04:11.388         sip_endpoint.c  .Module "mod-pjsua-log" registered
17:04:11.388         sip_endpoint.c  .Module "mod-tsx-layer" registered
17:04:11.388         sip_endpoint.c  .Module "mod-stateful-util" registered
17:04:11.388         sip_endpoint.c  .Module "mod-ua" registered
17:04:11.388         sip_endpoint.c  .Module "mod-100rel" registered
17:04:11.388         sip_endpoint.c  .Module "mod-pjsua" registered
17:04:11.389         sip_endpoint.c  .Module "mod-invite" registered
17:04:11.389                  pjlib  ..select() I/O Queue created (0x55e8f9c9e718)
17:04:11.389           conference.c  ..Creating conference bridge with 254 ports
17:04:11.389           Master/sound  ..Using delay buffer with WSOLA.
17:04:11.394         sip_endpoint.c  .Module "mod-evsub" registered
17:04:11.394         sip_endpoint.c  .Module "mod-presence" registered
17:04:11.394                evsub.c  .Event pkg "presence" registered by mod-presence
17:04:11.394         sip_endpoint.c  .Module "mod-mwi" registered
17:04:11.394                evsub.c  .Event pkg "message-summary" registered by mod-mwi
17:04:11.394         sip_endpoint.c  .Module "mod-refer" registered
17:04:11.394                evsub.c  .Event pkg "refer" registered by mod-refer
17:04:11.394         sip_endpoint.c  .Module "mod-pjsua-pres" registered
17:04:11.394         sip_endpoint.c  .Module "mod-pjsua-im" registered
17:04:11.394         sip_endpoint.c  .Module "mod-pjsua-options" registered
17:04:11.394           pjsua_core.c  .1 SIP worker threads created
17:04:11.394           pjsua_core.c  .pjsua version 2.8-svn for Linux-4.9.0.8/x86_64/glibc-2.24 initialized
17:04:11.394           pjsua_core.c  .PJSUA state changed: CREATED --> INIT
17:04:11.394         sip_endpoint.c  Module "mod-default-handler" registered
17:04:11.400           pjsua_core.c  SIP UDP socket reachable at 192.168.2.111:5060
17:04:11.400      udp0x55e8f9cb70e0  SIP UDP transport started, published address is 192.168.2.111:5060
17:04:11.400            pjsua_acc.c  Adding account: id=<sip:192.168.2.111:5060>
17:04:11.400            pjsua_acc.c  .Account <sip:192.168.2.111:5060> added with id 0
17:04:11.400            pjsua_acc.c  Modifying account 0
17:04:11.400            pjsua_acc.c  Acc 0: setting online status to 1..
17:04:11.405             tcptp:5060  SIP TCP listener ready for incoming connections at 192.168.2.111:5060
17:04:11.405            pjsua_acc.c  Adding account: id=<sip:192.168.2.111:5060;transport=TCP>
17:04:11.405            pjsua_acc.c  .Account <sip:192.168.2.111:5060;transport=TCP> added with id 1
17:04:11.406            pjsua_acc.c  Modifying account 1
17:04:11.406            pjsua_acc.c  Acc 1: setting online status to 1..
17:04:11.412             tlstp:5061  SIP TLS listener is ready for incoming connections at 192.168.2.111:5061
17:04:11.412            pjsua_acc.c  Adding account: id=<sip:192.168.2.111:5061;transport=TLS>
17:04:11.412            pjsua_acc.c  .Account <sip:192.168.2.111:5061;transport=TLS> added with id 2
17:04:11.412            pjsua_acc.c  Modifying account 2
17:04:11.412            pjsua_acc.c  Acc 2: setting online status to 1..
17:04:11.412            pjsua_acc.c  Adding account: id=sips:405277@sip.odorik.cz
17:04:11.412            pjsua_acc.c  .Account sips:405277@sip.odorik.cz added with id 3
17:04:11.412            pjsua_acc.c  .Acc 3: setting registration..
17:04:11.419     tlsc0x55e8f9cc0508  ..TLS client transport created
17:04:11.419     tlsc0x55e8f9cc0508  ..TLS transport 192.168.2.111:48375 is connecting to sip.odorik.cz:5061...
17:04:11.419            pjsua_acc.c  ..Contact for acc 3 updated: <sips:405277@192.168.2.111:48375;transport=TLS;ob>;reg-id=1;+sip.instance="<urn:uuid:00000000-0000-0000-0000-0000b08a276a>"
17:04:11.419               endpoint  ..Request msg REGISTER/cseq=27481 (tdta0x55e8f9cc2fe8) created.
17:04:11.425      tsx0x55e8f9cc4fa8  ...Transaction created for Request msg REGISTER/cseq=27482 (tdta0x55e8f9cc2fe8)
17:04:11.425      tsx0x55e8f9cc4fa8  ..Sending Request msg REGISTER/cseq=27482 (tdta0x55e8f9cc2fe8) in state Null
17:04:11.425          sip_resolve.c  ...DNS resolver not available, target 'sip.odorik.cz:0' type=TLS will be resolved with getaddrinfo()
17:04:11.430          sip_resolve.c  ...Target 'sip.odorik.cz:0' type=TLS resolved to '81.31.45.51:5061' type=TLS (TLS transport)
17:04:11.430           pjsua_core.c  ...TX 679 bytes Request msg REGISTER/cseq=27482 (tdta0x55e8f9cc2fe8) to TLS 81.31.45.51:5061:
REGISTER sips:sip.odorik.cz SIP/2.0
Via: SIP/2.0/TLS 192.168.2.111:48375;rport;branch=z9hG4bKPjNYotOxwUtbr.TM-iZdRm4hsB2yWo8753;alias
Max-Forwards: 70
From: <sips:405277@sip.odorik.cz>;tag=SiGejr.ogYndipLf5Fmfnkvs6OzxSb34
To: <sips:405277@sip.odorik.cz>
Call-ID: 6qsIOAHVSzbGp6tlXSgSGVh3OECxb7Sf
CSeq: 27482 REGISTER
User-Agent: PJSUA v2.8-svn Linux-4.9.0.8/x86_64/glibc-2.24
Supported: outbound, path
Contact: <sips:405277@192.168.2.111:48375;transport=TLS;ob>;reg-id=1;+sip.instance="<urn:uuid:00000000-0000-0000-0000-0000b08a276a>"
Expires: 300
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Content-Length:  0


--end msg--
17:04:11.431      tsx0x55e8f9cc4fa8  ...State changed from Null to Calling, event=TX_MSG
17:04:11.431            pjsua_acc.c  ..Acc 3: Registration sent
17:04:11.431            pjsua_acc.c  Acc 3: setting online status to 1..
17:04:11.431           pjsua_pres.c  Adding buddy: sips:*412879@sip.odorik.cz
17:04:11.431           pjsua_pres.c  .Buddy 0 added.
17:04:11.431           pjsua_pres.c  ..Buddy 0: updating presence..
17:04:11.431            pjsua_aud.c  Setting null sound device..
17:04:11.431            pjsua_app.c  .Turning sound device -99 -99 ON
17:04:11.431            pjsua_aud.c  .Opening null sound device..
17:04:11.431           pjsua_core.c  PJSUA state changed: INIT --> STARTING
17:04:11.431         sip_endpoint.c  .Module "mod-unsolicited-mwi" registered
17:04:11.431           pjsua_core.c  .PJSUA state changed: STARTING --> RUNNING
17:04:11.431                 main.c  Ready: Success
17:04:11.431           pjsua_call.c  Making call with acc #3 to sips:*412879@sip.odorik.cz
17:04:11.431      dlg0x55e8f9cc8928  .UAC dialog created
17:04:11.438      dlg0x55e8f9cc8928  ..Session count inc to 2 by mod-pjsua
17:04:11.438          pjsua_media.c  .Call 0: initializing media..
17:04:11.444          pjsua_media.c  ..RTP socket reachable at 192.168.2.111:4000
17:04:11.444          pjsua_media.c  ..RTCP socket reachable at 192.168.2.111:4001
17:04:11.444     srtp0x55e8f9ccc900  ..SRTP keying SDES created
17:04:11.444          pjsua_media.c  ..Media index 0 selected for audio call 0
17:04:11.444          pjsua_media.c  ..Call 0: media transport initialization complete: Success
17:04:11.444      dlg0x55e8f9cc8928  ..Session count dec to 2 by mod-pjsua
17:04:11.444     srtp0x55e8f9ccc900  .SRTP uses keying method SDES
17:04:11.444      dlg0x55e8f9cc8928  .Module mod-invite added as dialog usage, data=0x55e8f9cceae8
17:04:11.444      dlg0x55e8f9cc8928  ..Session count inc to 4 by mod-invite
17:04:11.444      dlg0x55e8f9cc8928  .Module mod-100rel added as dialog usage, data=0x55e8f9cd07f0
17:04:11.444      dlg0x55e8f9cc8928  .100rel module attached
17:04:11.444      inv0x55e8f9cc8928  .UAC invite session created for dialog dlg0x55e8f9cc8928
17:04:11.444               endpoint  .Request msg INVITE/cseq=3486 (tdta0x55e8f9cd0af8) created.
17:04:11.444      inv0x55e8f9cc8928  ..Sending Request msg INVITE/cseq=3486 (tdta0x55e8f9cd0af8)
17:04:11.444      dlg0x55e8f9cc8928  ...Sending Request msg INVITE/cseq=3486 (tdta0x55e8f9cd0af8)
17:04:11.444      tsx0x55e8f9cd3a68  ....Transaction created for Request msg INVITE/cseq=3485 (tdta0x55e8f9cd0af8)
17:04:11.444      tsx0x55e8f9cd3a68  ...Sending Request msg INVITE/cseq=3485 (tdta0x55e8f9cd0af8) in state Null
17:04:11.444          sip_resolve.c  ....DNS resolver not available, target 'sip.odorik.cz:0' type=TLS will be resolved with getaddrinfo()
17:04:11.450          sip_resolve.c  ....Target 'sip.odorik.cz:0' type=TLS resolved to '81.31.45.51:5061' type=TLS (TLS transport)
17:04:11.450           pjsua_core.c  ....TX 1383 bytes Request msg INVITE/cseq=3485 (tdta0x55e8f9cd0af8) to TLS 81.31.45.51:5061:
INVITE sips:*412879@sip.odorik.cz SIP/2.0
Via: SIP/2.0/TLS 192.168.2.111:48375;rport;branch=z9hG4bKPj9bXAdOO6xLcWCR3jtRltdUkaC-njOPUJ;alias
Max-Forwards: 70
From: sips:405277@sip.odorik.cz;tag=9phURiud6sS-CCFQjOwfrqFsW7AKbUmf
To: sips:*412879@sip.odorik.cz
Contact: <sips:405277@192.168.2.111:48375;transport=TLS;ob>
Call-ID: vhX1dbDmK7ASzl-5kUZrQW9SKJyV8Fs3
CSeq: 3485 INVITE
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Session-Expires: 1800
Min-SE: 90
User-Agent: PJSUA v2.8-svn Linux-4.9.0.8/x86_64/glibc-2.24
Content-Type: application/sdp
Content-Length:   701

v=0
o=- 3761481851 3761481851 IN IP4 192.168.2.111
s=pjmedia
b=AS:84
t=0 0
a=X-nat:0
m=audio 4000 RTP/SAVP 8 96
c=IN IP4 192.168.2.111
b=TIAS:64000
a=rtcp:4001 IN IP4 192.168.2.111
a=sendrecv
a=rtpmap:8 PCMA/8000
a=rtpmap:96 telephone-event/8000
a=fmtp:96 0-16
a=ssrc:1248634765 cname:7c1044e84fbb5062
a=crypto:1 AES_256_CM_HMAC_SHA1_80 inline:iPi1GV4WPns8Zhh+i8i2/dhF/NAJLuDdLYLmWqW0BAfFinVGT/jqHmsdtVV5dw==
a=crypto:2 AES_256_CM_HMAC_SHA1_32 inline:2ljIoyJWcnufWFCAKIOWwVx0aB6PQsna1OFs5W6pJzCT9nIxqh4ovZUZw96CVQ==
a=crypto:3 AES_CM_128_HMAC_SHA1_80 inline:58gGK3hKsYqvkNQSWVYY+iEgrFek2LrHoh6YwMfl
a=crypto:4 AES_CM_128_HMAC_SHA1_32 inline:IvPcEV6GS5tm4PfSdn/egs/RYvb4VnmEZ4FgPMVh

--end msg--
17:04:11.450      tsx0x55e8f9cd3a68  ....State changed from Null to Calling, event=TX_MSG
17:04:11.450      dlg0x55e8f9cc8928  .....Transaction tsx0x55e8f9cd3a68 state changed to Calling
17:04:11.450            pjsua_app.c  .......Call 0 state changed to CALLING
17:04:11.464            pjsua_app.c !SIP TLS transport is connected to sip.odorik.cz:5061
17:04:11.464            pjsua_app.c  TLS cipher used: 0x00009D/AES256-GCM-SHA384
17:04:11.464            pjsua_app.c  TLS cert info of sip.odorik.cz:5061:
  Version    : v3
  Serial     : 01
  Subject    : sip.odorik.cz
               /C=CZ/ST=Czech Republic/O=miniTEL s.r.o./OU=odorik.cz/CN=sip.odorik.cz/emailAddress=kontakt@odorik.cz
  Issuer     : OdorikCA
               /CN=OdorikCA/ST=Czech Republic/C=CZ/emailAddress=kontakt@minitel.cz/O=miniTEL s.r.o.
  Valid from : Tue 2016-04-26 15:24:51.000 GMT
  Valid to   : Wed 2055-09-29 15:24:51.000 GMT

17:04:11.464            pjsua_app.c  TLS cert verification result of sip.odorik.cz:5061 : 
17:04:11.464            pjsua_app.c  - The certificate is untrusted
17:04:11.464            pjsua_app.c  - Unknown verification error
17:04:11.464            pjsua_app.c  PJSUA is configured to ignore TLS cert verification errors
17:04:11.464     tlsc0x55e8f9cc0508  TLS transport 192.168.2.111:48375 is connected to sip.odorik.cz:5061
17:04:11.476         sip_endpoint.c  Processing incoming message: Response msg 401/REGISTER/cseq=27482 (rdata0x55e8f9cc0820)
17:04:11.476           pjsua_core.c  .RX 478 bytes Response msg 401/REGISTER/cseq=27482 (rdata0x55e8f9cc0820) from TLS 81.31.45.51:5061:
SIP/2.0 401 Unauthorized
Via: SIP/2.0/TLS 192.168.2.111:48375;rport=48375;branch=z9hG4bKPjNYotOxwUtbr.TM-iZdRm4hsB2yWo8753;alias;received=85.135.106.202
From: <sips:405277@sip.odorik.cz>;tag=SiGejr.ogYndipLf5Fmfnkvs6OzxSb34
To: <sips:405277@sip.odorik.cz>;tag=d9f55a8611ba6e9295acea97d3a19a47.2b20
Call-ID: 6qsIOAHVSzbGp6tlXSgSGVh3OECxb7Sf
CSeq: 27482 REGISTER
WWW-Authenticate: Digest realm="sip.odorik.cz", nonce="XIkrKVyJKf1kdmCT+3WNa2eB11qHlU1p"
Content-Length: 0


--end msg--
17:04:11.476      tsx0x55e8f9cc4fa8  .Incoming Response msg 401/REGISTER/cseq=27482 (rdata0x55e8f9cc0820) in state Calling
17:04:11.476      tsx0x55e8f9cc4fa8  ..State changed from Calling to Completed, event=RX_MSG
17:04:11.477            pjsua_acc.c  ....IP address change detected for account 3 (192.168.2.111:48375 --> 85.135.106.202:48375). Updating registration (using method 4)
17:04:11.477            pjsua_acc.c  ....Contact for acc 3 updated: <sips:405277@85.135.106.202:48375;transport=TLS;ob>;reg-id=1;+sip.instance="<urn:uuid:00000000-0000-0000-0000-0000b08a276a>"
17:04:11.477      tsx0x7fef14022178  ....Transaction created for Request msg REGISTER/cseq=27483 (tdta0x55e8f9cc2fe8)
17:04:11.477      tsx0x7fef14022178  ...Sending Request msg REGISTER/cseq=27483 (tdta0x55e8f9cc2fe8) in state Null
17:04:11.477               endpoint  ....Request msg REGISTER/cseq=27483 (tdta0x55e8f9cc2fe8): skipping target resolution because address is already set
17:04:11.477           pjsua_core.c  ....TX 858 bytes Request msg REGISTER/cseq=27483 (tdta0x55e8f9cc2fe8) to TLS 81.31.45.51:5061:
REGISTER sips:sip.odorik.cz SIP/2.0
Via: SIP/2.0/TLS 85.135.106.202:48375;rport;branch=z9hG4bKPjdH-GIgvuU-9j.hww3I4sFc4r80cPtwW-;alias
Max-Forwards: 70
From: <sips:405277@sip.odorik.cz>;tag=SiGejr.ogYndipLf5Fmfnkvs6OzxSb34
To: <sips:405277@sip.odorik.cz>
Call-ID: 6qsIOAHVSzbGp6tlXSgSGVh3OECxb7Sf
CSeq: 27483 REGISTER
User-Agent: PJSUA v2.8-svn Linux-4.9.0.8/x86_64/glibc-2.24
Supported: outbound, path
Contact: <sips:405277@85.135.106.202:48375;transport=TLS;ob>;reg-id=1;+sip.instance="<urn:uuid:00000000-0000-0000-0000-0000b08a276a>"
Expires: 300
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Authorization: Digest username="405277", realm="sip.odorik.cz", nonce="XIkrKVyJKf1kdmCT+3WNa2eB11qHlU1p", uri="sips:sip.odorik.cz", response="5d7b624eb06c951aca6a70e329c6da98"
Content-Length:  0


--end msg--
17:04:11.477      tsx0x7fef14022178  ....State changed from Null to Calling, event=TX_MSG
17:04:11.477      tsx0x55e8f9cc4fa8  Timeout timer event
17:04:11.477      tsx0x55e8f9cc4fa8  .State changed from Completed to Terminated, event=TIMER
17:04:11.477      tsx0x55e8f9cc4fa8  Timeout timer event
17:04:11.477      tsx0x55e8f9cc4fa8  .State changed from Terminated to Destroyed, event=TIMER
17:04:11.477      tsx0x55e8f9cc4fa8  Transaction destroyed!
17:04:11.488         sip_endpoint.c  Processing incoming message: Response msg 407/INVITE/cseq=3485 (rdata0x55e8f9cc0820)
17:04:11.488           pjsua_core.c  .RX 491 bytes Response msg 407/INVITE/cseq=3485 (rdata0x55e8f9cc0820) from TLS 81.31.45.51:5061:
SIP/2.0 407 Proxy Authentication Required
Via: SIP/2.0/TLS 192.168.2.111:48375;rport=48375;branch=z9hG4bKPj9bXAdOO6xLcWCR3jtRltdUkaC-njOPUJ;alias;received=85.135.106.202
From: sips:405277@sip.odorik.cz;tag=9phURiud6sS-CCFQjOwfrqFsW7AKbUmf
To: sips:*412879@sip.odorik.cz;tag=d9f55a8611ba6e9295acea97d3a19a47.8147
Call-ID: vhX1dbDmK7ASzl-5kUZrQW9SKJyV8Fs3
CSeq: 3485 INVITE
Proxy-Authenticate: Digest realm="sip.odorik.cz", nonce="XIkrKVyJKf1kdmCT+3WNa2eB11qHlU1p"
Content-Length: 0


--end msg--
17:04:11.488      tsx0x55e8f9cd3a68  .Incoming Response msg 407/INVITE/cseq=3485 (rdata0x55e8f9cc0820) in state Calling
17:04:11.488               endpoint  ..Request msg ACK/cseq=3485 (tdta0x7fef14022988) created.
17:04:11.488           pjsua_core.c  ..TX 382 bytes Request msg ACK/cseq=3485 (tdta0x7fef14022988) to TLS 81.31.45.51:5061:
ACK sips:*412879@sip.odorik.cz SIP/2.0
Via: SIP/2.0/TLS 192.168.2.111:48375;rport;branch=z9hG4bKPj9bXAdOO6xLcWCR3jtRltdUkaC-njOPUJ;alias
Max-Forwards: 70
From: sips:405277@sip.odorik.cz;tag=9phURiud6sS-CCFQjOwfrqFsW7AKbUmf
To: sips:*412879@sip.odorik.cz;tag=d9f55a8611ba6e9295acea97d3a19a47.8147
Call-ID: vhX1dbDmK7ASzl-5kUZrQW9SKJyV8Fs3
CSeq: 3485 ACK
Content-Length:  0


--end msg--
17:04:11.488      tsx0x55e8f9cd3a68  ..State changed from Calling to Completed, event=RX_MSG
17:04:11.488      dlg0x55e8f9cc8928  ...Received Response msg 407/INVITE/cseq=3485 (rdata0x55e8f9cc0820)
17:04:11.488      dlg0x55e8f9cc8928  ...Transaction tsx0x55e8f9cd3a68 state changed to Completed
17:04:11.488      inv0x55e8f9cc8928  .....Sending Request msg INVITE/cseq=3485 (tdta0x55e8f9cd0af8)
17:04:11.488      dlg0x55e8f9cc8928  ......Sending Request msg INVITE/cseq=3485 (tdta0x55e8f9cd0af8)
17:04:11.488      tsx0x7fef14025cf8  .......Transaction created for Request msg INVITE/cseq=3486 (tdta0x55e8f9cd0af8)
17:04:11.488      tsx0x7fef14025cf8  ......Sending Request msg INVITE/cseq=3486 (tdta0x55e8f9cd0af8) in state Null
17:04:11.488               endpoint  .......Request msg INVITE/cseq=3486 (tdta0x55e8f9cd0af8): skipping target resolution because address is already set
17:04:11.488           pjsua_core.c  .......TX 1574 bytes Request msg INVITE/cseq=3486 (tdta0x55e8f9cd0af8) to TLS 81.31.45.51:5061:
INVITE sips:*412879@sip.odorik.cz SIP/2.0
Via: SIP/2.0/TLS 192.168.2.111:48375;rport;branch=z9hG4bKPjIZvqhdnFBJRE7OaHChSvHDCOnVkM3tpV;alias
Max-Forwards: 70
From: sips:405277@sip.odorik.cz;tag=9phURiud6sS-CCFQjOwfrqFsW7AKbUmf
To: sips:*412879@sip.odorik.cz
Contact: <sips:405277@192.168.2.111:48375;transport=TLS;ob>
Call-ID: vhX1dbDmK7ASzl-5kUZrQW9SKJyV8Fs3
CSeq: 3486 INVITE
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Session-Expires: 1800
Min-SE: 90
User-Agent: PJSUA v2.8-svn Linux-4.9.0.8/x86_64/glibc-2.24
Proxy-Authorization: Digest username="405277", realm="sip.odorik.cz", nonce="XIkrKVyJKf1kdmCT+3WNa2eB11qHlU1p", uri="sips:*412879@sip.odorik.cz", response="7772a3728b0a51887a92b422c6b9b41e"
Content-Type: application/sdp
Content-Length:   701

v=0
o=- 3761481851 3761481851 IN IP4 192.168.2.111
s=pjmedia
b=AS:84
t=0 0
a=X-nat:0
m=audio 4000 RTP/SAVP 8 96
c=IN IP4 192.168.2.111
b=TIAS:64000
a=rtcp:4001 IN IP4 192.168.2.111
a=sendrecv
a=rtpmap:8 PCMA/8000
a=rtpmap:96 telephone-event/8000
a=fmtp:96 0-16
a=ssrc:1248634765 cname:7c1044e84fbb5062
a=crypto:1 AES_256_CM_HMAC_SHA1_80 inline:iPi1GV4WPns8Zhh+i8i2/dhF/NAJLuDdLYLmWqW0BAfFinVGT/jqHmsdtVV5dw==
a=crypto:2 AES_256_CM_HMAC_SHA1_32 inline:2ljIoyJWcnufWFCAKIOWwVx0aB6PQsna1OFs5W6pJzCT9nIxqh4ovZUZw96CVQ==
a=crypto:3 AES_CM_128_HMAC_SHA1_80 inline:58gGK3hKsYqvkNQSWVYY+iEgrFek2LrHoh6YwMfl
a=crypto:4 AES_CM_128_HMAC_SHA1_32 inline:IvPcEV6GS5tm4PfSdn/egs/RYvb4VnmEZ4FgPMVh

--end msg--
17:04:11.489      tsx0x7fef14025cf8  .......State changed from Null to Calling, event=TX_MSG
17:04:11.489      dlg0x55e8f9cc8928  ........Transaction tsx0x7fef14025cf8 state changed to Calling
17:04:11.489      tsx0x55e8f9cd3a68  Timeout timer event
17:04:11.489      tsx0x55e8f9cd3a68  .State changed from Completed to Terminated, event=TIMER
17:04:11.489      dlg0x55e8f9cc8928  ..Transaction tsx0x55e8f9cd3a68 state changed to Terminated
17:04:11.489      tsx0x55e8f9cd3a68  Timeout timer event
17:04:11.489      tsx0x55e8f9cd3a68  .State changed from Terminated to Destroyed, event=TIMER
17:04:11.489     tdta0x7fef14022988  ..Destroying txdata Request msg ACK/cseq=3485 (tdta0x7fef14022988)
17:04:11.489      tsx0x55e8f9cd3a68  Transaction destroyed!
17:04:11.502         sip_endpoint.c  Processing incoming message: Response msg 200/REGISTER/cseq=27483 (rdata0x55e8f9cc0820)
17:04:11.502           pjsua_core.c  .RX 573 bytes Response msg 200/REGISTER/cseq=27483 (rdata0x55e8f9cc0820) from TLS 81.31.45.51:5061:
SIP/2.0 200 OK
Via: SIP/2.0/TLS 85.135.106.202:48375;rport=48375;branch=z9hG4bKPjdH-GIgvuU-9j.hww3I4sFc4r80cPtwW-;alias;received=85.135.106.202
From: <sips:405277@sip.odorik.cz>;tag=SiGejr.ogYndipLf5Fmfnkvs6OzxSb34
To: <sips:405277@sip.odorik.cz>;tag=d9f55a8611ba6e9295acea97d3a19a47.13cc
Call-ID: 6qsIOAHVSzbGp6tlXSgSGVh3OECxb7Sf
CSeq: 27483 REGISTER
Contact: <sip:405277@88.150.183.67:35060>;expires=3306, <sips:405277@85.135.106.202:48375;transport=TLS;ob>;expires=300;+sip.instance="<urn:uuid:00000000-0000-0000-0000-0000b08a276a>";reg-id=1
Content-Length: 0


--end msg--
17:04:11.502      tsx0x7fef14022178  .Incoming Response msg 200/REGISTER/cseq=27483 (rdata0x55e8f9cc0820) in state Calling
17:04:11.502      tsx0x7fef14022178  ..State changed from Calling to Completed, event=RX_MSG
17:04:11.502            pjsua_acc.c  ....SIP outbound status for acc 3 is not active
17:04:11.502            pjsua_acc.c  ....sips:405277@sip.odorik.cz: registration success, status=200 (OK), will re-register in 300 seconds
17:04:11.502      tsx0x7fef14022178  Timeout timer event
17:04:11.502      tsx0x7fef14022178  .State changed from Completed to Terminated, event=TIMER
17:04:11.502      tsx0x7fef14022178  Timeout timer event
17:04:11.502      tsx0x7fef14022178  .State changed from Terminated to Destroyed, event=TIMER
17:04:11.502     tdta0x55e8f9cc2fe8  ..Destroying txdata Request msg REGISTER/cseq=27483 (tdta0x55e8f9cc2fe8)
17:04:11.502      tsx0x7fef14022178  Transaction destroyed!
17:04:11.513         sip_endpoint.c  Processing incoming message: Response msg 100/INVITE/cseq=3486 (rdata0x55e8f9cc0820)
17:04:11.513           pjsua_core.c  .RX 334 bytes Response msg 100/INVITE/cseq=3486 (rdata0x55e8f9cc0820) from TLS 81.31.45.51:5061:
SIP/2.0 100 Trying
Via: SIP/2.0/TLS 192.168.2.111:48375;rport=48375;branch=z9hG4bKPjIZvqhdnFBJRE7OaHChSvHDCOnVkM3tpV;alias;received=85.135.106.202
From: sips:405277@sip.odorik.cz;tag=9phURiud6sS-CCFQjOwfrqFsW7AKbUmf
To: sips:*412879@sip.odorik.cz
Call-ID: vhX1dbDmK7ASzl-5kUZrQW9SKJyV8Fs3
CSeq: 3486 INVITE
Content-Length: 0


--end msg--
17:04:11.513      tsx0x7fef14025cf8  .Incoming Response msg 100/INVITE/cseq=3486 (rdata0x55e8f9cc0820) in state Calling
17:04:11.513      tsx0x7fef14025cf8  ..State changed from Calling to Proceeding, event=RX_MSG
17:04:11.513      dlg0x55e8f9cc8928  ...Received Response msg 100/INVITE/cseq=3486 (rdata0x55e8f9cc0820)
17:04:11.513      dlg0x55e8f9cc8928  ...Transaction tsx0x7fef14025cf8 state changed to Proceeding
17:04:11.728         sip_endpoint.c  Processing incoming message: Response msg 200/INVITE/cseq=3486 (rdata0x55e8f9cc0820)
17:04:11.728           pjsua_core.c  .RX 1124 bytes Response msg 200/INVITE/cseq=3486 (rdata0x55e8f9cc0820) from TLS 81.31.45.51:5061:
SIP/2.0 200 OK
Via: SIP/2.0/TLS 192.168.2.111:48375;received=85.135.106.202;rport=48375;branch=z9hG4bKPjIZvqhdnFBJRE7OaHChSvHDCOnVkM3tpV;alias
Record-Route: <sips:*412879@81.31.45.51;r2=on;lr=on;ftag=9phURiud6sS-CCFQjOwfrqFsW7AKbUmf;did=2efd.57462;nat=yes>
Record-Route: <sips:*412879@81.31.45.51:5061;transport=tls;r2=on;lr=on;ftag=9phURiud6sS-CCFQjOwfrqFsW7AKbUmf;did=2efd.57462;nat=yes>
From: sips:405277@sip.odorik.cz;tag=9phURiud6sS-CCFQjOwfrqFsW7AKbUmf
To: sips:*412879@sip.odorik.cz;tag=as1e8206bd
Call-ID: vhX1dbDmK7ASzl-5kUZrQW9SKJyV8Fs3
CSeq: 3486 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces
Contact: <sips:*086*412879@81.31.45.59:5060;alias=81.31.45.59~5060~1>
Content-Type: application/sdp
Content-Length: 322

v=0
o=root 221091432 221091432 IN IP4 81.31.45.59
s=-
c=IN IP4 81.31.45.59
t=0 0
m=audio 22066 RTP/SAVP 8 96
a=rtpmap:8 PCMA/8000
a=rtpmap:96 telephone-event/8000
a=fmtp:96 0-16
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv
a=crypto:3 AES_CM_128_HMAC_SHA1_80 inline:nRsmds+L/TG0BBRrR6+xeFUmO47VqUl2iSZU8LrF

--end msg--
17:04:11.729      tsx0x7fef14025cf8  .Incoming Response msg 200/INVITE/cseq=3486 (rdata0x55e8f9cc0820) in state Proceeding
17:04:11.729      tsx0x7fef14025cf8  ..State changed from Proceeding to Terminated, event=RX_MSG
17:04:11.729      dlg0x55e8f9cc8928  ...Received Response msg 200/INVITE/cseq=3486 (rdata0x55e8f9cc0820)
17:04:11.729      dlg0x55e8f9cc8928  ....Route-set updated
17:04:11.729      dlg0x55e8f9cc8928  ....Route-set frozen
17:04:11.729      dlg0x55e8f9cc8928  ...Transaction tsx0x7fef14025cf8 state changed to Terminated
17:04:11.729            pjsua_app.c  .....Call 0 state changed to CONNECTING
17:04:11.729      inv0x55e8f9cc8928  ....Got SDP answer in Response msg 200/INVITE/cseq=3486 (rdata0x55e8f9cc0820)
17:04:11.729      inv0x55e8f9cc8928  ....SDP negotiation done, status=0
17:04:11.729           pjsua_call.c  .....Call 0: remote NAT type is 0 (Unknown)
17:04:11.729          pjsua_media.c  .....Call 0: updating media..
17:04:11.729          pjsua_media.c  .......Media stream call00:0 is destroyed
17:04:11.729     srtp0x55e8f9ccc900  ......TX: AES_CM_128_HMAC_SHA1_80 key=58gGK3hKsYqvkNQSWVYY+iEgrFek2LrHoh6YwMfl
17:04:11.729     srtp0x55e8f9ccc900  ......RX: AES_CM_128_HMAC_SHA1_80 key=nRsmds+L/TG0BBRrR6+xeFUmO47VqUl2iSZU8LrF
17:04:11.729     srtp0x55e8f9ccc900  ......SRTP started, keying=SDES, crypto=AES_CM_128_HMAC_SHA1_80
17:04:11.729            pjsua_aud.c  ......Audio channel update..
17:04:11.729     strm0x7fef14037458  .......VAD temporarily disabled
17:04:11.729                  rtp.c  .......pjmedia_rtp_session_init: ses=0x7fef1403a2c0, default_pt=8, ssrc=0x4a6ca78d
17:04:11.729                  rtp.c  .......pjmedia_rtp_session_init: ses=0x7fef1403a940, default_pt=8, ssrc=0x4a6ca78d
17:04:11.729               stream.c  .......Stream strm0x7fef14037458 created
17:04:11.729     strm0x7fef14037458  .......Encoder stream started
17:04:11.729     strm0x7fef14037458  .......Decoder stream started
17:04:11.730             resample.c  .......resample created: high qualiy, large filter, in/out rate=8000/16000
17:04:11.730             resample.c  .......resample created: high qualiy, large filter, in/out rate=16000/8000
17:04:11.730          pjsua_media.c  ......Audio updated, stream #0: PCMA (sendrecv)
17:04:11.730            pjsua_app.c  .....Call 0 media 0 [type=audio], status is Active
17:04:11.730            pjsua_aud.c  .....Conf connect: 3 --> 0
17:04:11.730           conference.c  ......Port 3 (sips:*412879@sip.odorik.cz) transmitting to port 0 (Master/sound)
17:04:11.730            pjsua_aud.c  .....Conf connect: 0 --> 3
17:04:11.730           conference.c  ......Port 0 (Master/sound) transmitting to port 3 (sips:*412879@sip.odorik.cz)
17:04:11.730      inv0x55e8f9cc8928  ....Received Response msg 200/INVITE/cseq=3486 (rdata0x55e8f9cc0820), sending ACK
17:04:11.730               endpoint  ....Request msg ACK/cseq=3486 (tdta0x7fef1403cbe8) created.
17:04:11.730      dlg0x55e8f9cc8928  .....Sending Request msg ACK/cseq=3486 (tdta0x7fef1403cbe8)
17:04:11.730          sip_resolve.c  .....Target '81.31.45.51:5061' type=TLS resolved to '81.31.45.51:5061' type=TLS (TLS transport)
17:04:11.730           pjsua_core.c  .....TX 616 bytes Request msg ACK/cseq=3486 (tdta0x7fef1403cbe8) to TLS 81.31.45.51:5061:
ACK sips:*086*412879@81.31.45.59:5060;alias=81.31.45.59~5060~1 SIP/2.0
Via: SIP/2.0/TLS 192.168.2.111:48375;rport;branch=z9hG4bKPjsvU2ksLtdOByx1RcsF9W89jT7Stqk3qO;alias
Max-Forwards: 70
From: sips:405277@sip.odorik.cz;tag=9phURiud6sS-CCFQjOwfrqFsW7AKbUmf
To: sips:*412879@sip.odorik.cz;tag=as1e8206bd
Call-ID: vhX1dbDmK7ASzl-5kUZrQW9SKJyV8Fs3
CSeq: 3486 ACK
Route: <sips:*412879@81.31.45.51:5061;transport=tls;lr;r2=on;ftag=9phURiud6sS-CCFQjOwfrqFsW7AKbUmf;did=2efd.57462;nat=yes>
Route: <sips:*412879@81.31.45.51;lr;r2=on;ftag=9phURiud6sS-CCFQjOwfrqFsW7AKbUmf;did=2efd.57462;nat=yes>
Content-Length:  0


--end msg--
17:04:11.731            pjsua_app.c  .....Call 0 state changed to CONFIRMED
17:04:11.731      tsx0x7fef14025cf8  Timeout timer event
17:04:11.731      tsx0x7fef14025cf8  .State changed from Terminated to Destroyed, event=TIMER
17:04:11.731      tsx0x7fef14025cf8  Transaction destroyed!
17:04:11.733     strm0x7fef14037458 !Jitter buffer is bufferring (prefetch=0)
17:04:11.734     strm0x7fef14037458  Start talksprut..
17:04:11.890     strm0x7fef14037458 !RTP status: badpt=0, badssrc=0, dup=0, outorder=0, probation=-1, restart=0
17:04:11.893     strm0x7fef14037458 !Jitter buffer starts returning normal frames (after 8 empty/lost)
17:04:12.332     strm0x7fef14037458  Frame lost, recovered!
17:04:12.332     strm0x7fef14037458  Jitter buffer starts returning normal frames (after 1 empty/lost)
17:04:12.353     strm0x7fef14037458  VAD re-enabled
17:04:12.353     strm0x7fef14037458  Starting silence
17:04:12.619     strm0x7fef14037458  Frame lost, recovered!
17:04:12.619     strm0x7fef14037458  Jitter buffer starts returning normal frames (after 1 empty/lost)
17:04:12.933     strm0x7fef14037458  Frame lost, recovered!
17:04:12.953     strm0x7fef14037458  Jitter buffer starts returning normal frames (after 1 empty/lost)
17:04:13.177     strm0x7fef14037458  Jitter buffer empty (prefetch=0), plc invoked
17:04:13.459     strm0x7fef14037458  Jitter buffer starts returning normal frames (after 14 empty/lost)
17:04:15.297     strm0x7fef14037458  Jitter buffer empty (prefetch=0), plc invoked
17:04:16.333           silencedet.c  Re-adjust threshold (in silence)to 0
17:04:16.452     strm0x7fef14037458  Start talksprut..
17:04:16.477     strm0x7fef14037458  Starting silence
17:04:20.334           silencedet.c  Re-adjust threshold (in silence)to 0
17:04:21.455     strm0x7fef14037458  Start talksprut..
17:04:21.473     strm0x7fef14037458  Starting silence
17:04:24.333           silencedet.c  Re-adjust threshold (in silence)to 0
17:04:26.453     strm0x7fef14037458  Start talksprut..
17:04:26.472     strm0x7fef14037458  Starting silence
17:04:28.333           silencedet.c  Re-adjust threshold (in silence)to 0
17:04:31.452     strm0x7fef14037458  Start talksprut..
17:04:31.473     strm0x7fef14037458  Starting silence
17:04:31.790         sip_endpoint.c !Processing incoming message: Request msg BYE/cseq=102 (rdata0x55e8f9cc0820)
17:04:31.790           pjsua_core.c  .RX 455 bytes Request msg BYE/cseq=102 (rdata0x55e8f9cc0820) from TLS 81.31.45.51:5061:
BYE sips:405277@192.168.2.111:48375;transport=TLS;ob SIP/2.0
Via: SIP/2.0/TLS 81.31.45.51:5061;branch=z9hG4bK2bef.f7ba88e3879645f825d04ec40b024444.0
Via: SIP/2.0/UDP 81.31.45.59:5060;received=81.31.45.59;branch=z9hG4bK4ee97515;rport=5060
Max-Forwards: 69
From: sips:*412879@sip.odorik.cz;tag=as1e8206bd
To: sips:405277@sip.odorik.cz;tag=9phURiud6sS-CCFQjOwfrqFsW7AKbUmf
Call-ID: vhX1dbDmK7ASzl-5kUZrQW9SKJyV8Fs3
CSeq: 102 BYE
Content-Length: 0


--end msg--
17:04:31.790      dlg0x55e8f9cc8928  .Received Request msg BYE/cseq=102 (rdata0x55e8f9cc0820)
17:04:31.790      tsx0x7fef14025cf8  ...Transaction created for Request msg BYE/cseq=102 (rdata0x55e8f9cc0820)
17:04:31.790      tsx0x7fef14025cf8  ..Incoming Request msg BYE/cseq=102 (rdata0x55e8f9cc0820) in state Null
17:04:31.790      tsx0x7fef14025cf8  ...State changed from Null to Trying, event=RX_MSG
17:04:31.790      dlg0x55e8f9cc8928  ....Transaction tsx0x7fef14025cf8 state changed to Trying
17:04:31.790               endpoint  .....Response msg 200/BYE/cseq=102 (tdta0x7fef14040af8) created
17:04:31.790      dlg0x55e8f9cc8928  ......Sending Response msg 200/BYE/cseq=102 (tdta0x7fef14040af8)
17:04:31.790      tsx0x7fef14025cf8  ......Sending Response msg 200/BYE/cseq=102 (tdta0x7fef14040af8) in state Trying
17:04:31.790           pjsua_core.c  .......TX 417 bytes Response msg 200/BYE/cseq=102 (tdta0x7fef14040af8) to TLS 81.31.45.51:5061:
SIP/2.0 200 OK
Via: SIP/2.0/TLS 81.31.45.51:5061;received=81.31.45.51;branch=z9hG4bK2bef.f7ba88e3879645f825d04ec40b024444.0
Via: SIP/2.0/UDP 81.31.45.59:5060;rport=5060;received=81.31.45.59;branch=z9hG4bK4ee97515
Call-ID: vhX1dbDmK7ASzl-5kUZrQW9SKJyV8Fs3
From: <sips:*412879@sip.odorik.cz>;tag=as1e8206bd
To: <sips:405277@sip.odorik.cz>;tag=9phURiud6sS-CCFQjOwfrqFsW7AKbUmf
CSeq: 102 BYE
Content-Length:  0


--end msg--
17:04:31.791      tsx0x7fef14025cf8  .......State changed from Trying to Completed, event=TX_MSG
17:04:31.791      dlg0x55e8f9cc8928  ........Transaction tsx0x7fef14025cf8 state changed to Completed
17:04:31.791            pjsua_app.c  ......Call 0 is DISCONNECTED [reason=200 (Normal call clearing)]
17:04:31.791            pjsua_app.c  ......Call 0 disconnected, dumping media stats..
17:04:31.791     pjsua_app_common.c  ......
  [DISCONNCTD] To: sips:*412879@sip.odorik.cz;tag=as1e8206bd
    Call time: 00h:00m:20s, 1st res in 298 ms, conn in 300ms
    #0 audio PCMA @8kHz, sendrecv, peer=81.31.45.59:22066
       SRTP status: Active Crypto-suite: AES_CM_128_HMAC_SHA1_80
       RX pt=8, last update:00h:00m:00.536s ago
          total 158pkt 25.2KB (31.6KB +IP hdr) @avg=10.0Kbps/12.6Kbps
          pkt loss=0 (0.0%), discrd=0 (0.0%), dup=0 (0.0%), reord=0 (0.0%)
                (msec)    min     avg     max     last    dev
          loss period:   0.000   0.000   0.000   0.000   0.000
          jitter     :   0.000   0.430   0.750   0.625   0.148
       TX pt=8, ptime=20, last update:00h:00m:00.005s ago
          total 35pkt 5.6KB (7.0KB +IP hdr) @avg=2.2Kbps/2.7Kbps
          pkt loss=3 (8.6%), dup=0 (0.0%), reorder=0 (0.0%)
                (msec)    min     avg     max     last    dev 
          loss period
Netolish
Příspěvky: 23
Registrován: pon 04. bře 2013 12:24:16

Re: Nový PJSIP přitvrdil?

Příspěvek od Netolish »

Díky za rychlou reakci.

Je pravda, že pokud zakážu všechny kodeky až na PCMA tak to projde. Jako rychlý workaround
na pokusy to stačí. V reálu by ale nebylo špatné aby si systémy mohly kodeky dohodnout
z průniku podporovaných. Třeba zrovna Opus se mi na VOIP zdá jako moc pěkný kodek
(z pohledu kvalita/datový tok).
Přiznávám, že jsem líný číst všechny RFC. Je tedy re-invite který dělá PJSIP špatné/nevhodné řešení?
Pokud ano, povrtám se ve zdrojácích - a zkusím s nich tuhle vlastnost vyhodit.

Díky Jiří Netolický
Uživatelský avatar
kovik
Příspěvky: 505
Registrován: stř 16. lis 2011 11:07:52

Re: Nový PJSIP přitvrdil?

Příspěvek od kovik »

Dobry den,
v poslednim prispevku pisu, ze mi hovor projde i bez odstraneni codeku s re-INVITEM, prikladam log.

Nevim, proc pjsip posila re-INVITE,` normalne` se to nedela stejne v re-INVITU posle ALAW.

Odorik opus nepodporuje ani to mema moc smysl, protoze by dochazelo k transkodovani na alaw.

prikaz:

Kód: Vybrat vše

pjsua  --id=sips:405277@sip.odorik.cz --realm=* --username=405277 --password=XxXXxXX --disable-stun --use-tls --use-srtp=2 sips:*412879@sip.odorik.cz --null-audio --log-file=/tmp/xxx
log:

Kód: Vybrat vše

15:26:59.105         sip_endpoint.c  .Module "mod-pjsua-log" registered
15:26:59.105         sip_endpoint.c  .Module "mod-tsx-layer" registered
15:26:59.105         sip_endpoint.c  .Module "mod-stateful-util" registered
15:26:59.105         sip_endpoint.c  .Module "mod-ua" registered
15:26:59.105         sip_endpoint.c  .Module "mod-100rel" registered
15:26:59.105         sip_endpoint.c  .Module "mod-pjsua" registered
15:26:59.106         sip_endpoint.c  .Module "mod-invite" registered
15:26:59.106                  pjlib  ..select() I/O Queue created (0x556c963fb718)
15:26:59.106           conference.c  ..Creating conference bridge with 254 ports
15:26:59.106           Master/sound  ..Using delay buffer with WSOLA.
15:26:59.110         sip_endpoint.c  .Module "mod-evsub" registered
15:26:59.110         sip_endpoint.c  .Module "mod-presence" registered
15:26:59.110                evsub.c  .Event pkg "presence" registered by mod-presence
15:26:59.110         sip_endpoint.c  .Module "mod-mwi" registered
15:26:59.110                evsub.c  .Event pkg "message-summary" registered by mod-mwi
15:26:59.110         sip_endpoint.c  .Module "mod-refer" registered
15:26:59.110                evsub.c  .Event pkg "refer" registered by mod-refer
15:26:59.110         sip_endpoint.c  .Module "mod-pjsua-pres" registered
15:26:59.110         sip_endpoint.c  .Module "mod-pjsua-im" registered
15:26:59.110         sip_endpoint.c  .Module "mod-pjsua-options" registered
15:26:59.110           pjsua_core.c  .1 SIP worker threads created
15:26:59.110           pjsua_core.c  .pjsua version 2.8-svn for Linux-4.9.0.8/x86_64/glibc-2.24 initialized
15:26:59.110           pjsua_core.c  .PJSUA state changed: CREATED --> INIT
15:26:59.110         sip_endpoint.c  Module "mod-default-handler" registered
15:26:59.116           pjsua_core.c  SIP UDP socket reachable at 192.168.2.111:5060
15:26:59.116      udp0x556c964140e0  SIP UDP transport started, published address is 192.168.2.111:5060
15:26:59.116            pjsua_acc.c  Adding account: id=<sip:192.168.2.111:5060>
15:26:59.116            pjsua_acc.c  .Account <sip:192.168.2.111:5060> added with id 0
15:26:59.116            pjsua_acc.c  Modifying account 0
15:26:59.116            pjsua_acc.c  Acc 0: setting online status to 1..
15:26:59.121             tcptp:5060  SIP TCP listener ready for incoming connections at 192.168.2.111:5060
15:26:59.121            pjsua_acc.c  Adding account: id=<sip:192.168.2.111:5060;transport=TCP>
15:26:59.121            pjsua_acc.c  .Account <sip:192.168.2.111:5060;transport=TCP> added with id 1
15:26:59.121            pjsua_acc.c  Modifying account 1
15:26:59.121            pjsua_acc.c  Acc 1: setting online status to 1..
15:26:59.125             tlstp:5061  SIP TLS listener is ready for incoming connections at 192.168.2.111:5061
15:26:59.125            pjsua_acc.c  Adding account: id=<sip:192.168.2.111:5061;transport=TLS>
15:26:59.125            pjsua_acc.c  .Account <sip:192.168.2.111:5061;transport=TLS> added with id 2
15:26:59.125            pjsua_acc.c  Modifying account 2
15:26:59.125            pjsua_acc.c  Acc 2: setting online status to 1..
15:26:59.125            pjsua_acc.c  Adding account: id=sips:405277@sip.odorik.cz
15:26:59.125            pjsua_acc.c  .Account sips:405277@sip.odorik.cz added with id 3
15:26:59.125            pjsua_acc.c  Acc 3: setting online status to 1..
15:26:59.125           pjsua_pres.c  Adding buddy: sips:*412879@sip.odorik.cz
15:26:59.125           pjsua_pres.c  .Buddy 0 added.
15:26:59.125           pjsua_pres.c  ..Buddy 0: updating presence..
15:26:59.125            pjsua_aud.c  Setting null sound device..
15:26:59.125            pjsua_app.c  .Turning sound device -99 -99 ON
15:26:59.125            pjsua_aud.c  .Opening null sound device..
15:26:59.125           pjsua_core.c  PJSUA state changed: INIT --> STARTING
15:26:59.125         sip_endpoint.c  .Module "mod-unsolicited-mwi" registered
15:26:59.125           pjsua_core.c  .PJSUA state changed: STARTING --> RUNNING
15:26:59.125                 main.c  Ready: Success
15:26:59.125           pjsua_call.c  Making call with acc #3 to sips:*412879@sip.odorik.cz
15:26:59.130     tlsc0x556c9641dfa8  .TLS client transport created
15:26:59.130     tlsc0x556c9641dfa8  .TLS transport 192.168.2.111:53517 is connecting to sip.odorik.cz:5061...
15:26:59.130      dlg0x556c9641fa48  .UAC dialog created
15:26:59.135      dlg0x556c9641fa48  ..Session count inc to 2 by mod-pjsua
15:26:59.135          pjsua_media.c  .Call 0: initializing media..
15:26:59.139          pjsua_media.c  ..RTP socket reachable at 192.168.2.111:4000
15:26:59.139          pjsua_media.c  ..RTCP socket reachable at 192.168.2.111:4001
15:26:59.139     srtp0x556c96423a20  ..SRTP keying SDES created
15:26:59.139          pjsua_media.c  ..Media index 0 selected for audio call 0
15:26:59.140          pjsua_media.c  ..Call 0: media transport initialization complete: Success
15:26:59.140      dlg0x556c9641fa48  ..Session count dec to 2 by mod-pjsua
15:26:59.140     srtp0x556c96423a20  .SRTP uses keying method SDES
15:26:59.140      dlg0x556c9641fa48  .Module mod-invite added as dialog usage, data=0x556c96425e18
15:26:59.140      dlg0x556c9641fa48  ..Session count inc to 4 by mod-invite
15:26:59.140      dlg0x556c9641fa48  .Module mod-100rel added as dialog usage, data=0x556c96428220
15:26:59.140      dlg0x556c9641fa48  .100rel module attached
15:26:59.140      inv0x556c9641fa48  .UAC invite session created for dialog dlg0x556c9641fa48
15:26:59.140               endpoint  .Request msg INVITE/cseq=15573 (tdta0x556c96428568) created.
15:26:59.140      inv0x556c9641fa48  ..Sending Request msg INVITE/cseq=15573 (tdta0x556c96428568)
15:26:59.140      dlg0x556c9641fa48  ...Sending Request msg INVITE/cseq=15573 (tdta0x556c96428568)
15:26:59.140      tsx0x556c9642b4d8  ....Transaction created for Request msg INVITE/cseq=15572 (tdta0x556c96428568)
15:26:59.140      tsx0x556c9642b4d8  ...Sending Request msg INVITE/cseq=15572 (tdta0x556c96428568) in state Null
15:26:59.140          sip_resolve.c  ....DNS resolver not available, target 'sip.odorik.cz:0' type=TLS will be resolved with getaddrinfo()
15:26:59.144          sip_resolve.c  ....Target 'sip.odorik.cz:0' type=TLS resolved to '81.31.45.51:5061' type=TLS (TLS transport)
15:26:59.144           pjsua_core.c  ....TX 1644 bytes Request msg INVITE/cseq=15572 (tdta0x556c96428568) to TLS 81.31.45.51:5061:
INVITE sips:*412879@sip.odorik.cz SIP/2.0
Via: SIP/2.0/TLS 192.168.2.111:53517;rport;branch=z9hG4bKPj2nN28.PXXsGw8vXIXIb70ES304ZrY8wq;alias
Max-Forwards: 70
From: sips:405277@sip.odorik.cz;tag=C3nkkXpG3tTT3es.ol6CDn94e2Hk2RpS
To: sips:*412879@sip.odorik.cz
Contact: <sips:405277@192.168.2.111:53517;transport=TLS;ob>
Call-ID: d8dVCzU5H23u.i6eiIJsjJaU9nj0UJ9-
CSeq: 15572 INVITE
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Session-Expires: 1800
Min-SE: 90
User-Agent: PJSUA v2.8-svn Linux-4.9.0.8/x86_64/glibc-2.24
Content-Type: application/sdp
Content-Length:   961

v=0
o=- 3761648819 3761648819 IN IP4 192.168.2.111
s=pjmedia
b=AS:117
t=0 0
a=X-nat:0
m=audio 4000 RTP/SAVP 98 97 99 104 3 0 8 9 120 96
c=IN IP4 192.168.2.111
b=TIAS:96000
a=rtcp:4001 IN IP4 192.168.2.111
a=sendrecv
a=rtpmap:98 speex/16000
a=rtpmap:97 speex/8000
a=rtpmap:99 speex/32000
a=rtpmap:104 iLBC/8000
a=fmtp:104 mode=30
a=rtpmap:3 GSM/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:9 G722/8000
a=rtpmap:120 opus/48000/2
a=fmtp:120 useinbandfec=1
a=rtpmap:96 telephone-event/8000
a=fmtp:96 0-16
a=ssrc:698982219 cname:0629d1f05b40e9d3
a=crypto:1 AES_256_CM_HMAC_SHA1_80 inline:LMYPMX09hAjNdtDlM4rk4kk5BoKqjMjvtwzO6so9pYiLfWbLu6GJyyefc9rF8A==
a=crypto:2 AES_256_CM_HMAC_SHA1_32 inline:rvB4OdvjFQYe62d7i84H72viCEzdRMPu4XDYbnnaC0I7EERl0/WommACFbstJA==
a=crypto:3 AES_CM_128_HMAC_SHA1_80 inline:y89ybfcvUE6lifPWvK6o5TjhM+wQ7z2uBhMrjzgU
a=crypto:4 AES_CM_128_HMAC_SHA1_32 inline:YUlHRq8qDMd4ZWwihCUgso47Ywzpzp7A/xhnW6/B

--end msg--
15:26:59.144      tsx0x556c9642b4d8  ....State changed from Null to Calling, event=TX_MSG
15:26:59.144      dlg0x556c9641fa48  .....Transaction tsx0x556c9642b4d8 state changed to Calling
15:26:59.144            pjsua_app.c  .......Call 0 state changed to CALLING
15:26:59.169            pjsua_app.c !SIP TLS transport is connected to sip.odorik.cz:5061
15:26:59.169            pjsua_app.c  TLS cipher used: 0x00009D/AES256-GCM-SHA384
15:26:59.169            pjsua_app.c  TLS cert info of sip.odorik.cz:5061:
  Version    : v3
  Serial     : 01
  Subject    : sip.odorik.cz
               /C=CZ/ST=Czech Republic/O=miniTEL s.r.o./OU=odorik.cz/CN=sip.odorik.cz/emailAddress=kontakt@odorik.cz
  Issuer     : OdorikCA
               /CN=OdorikCA/ST=Czech Republic/C=CZ/emailAddress=kontakt@minitel.cz/O=miniTEL s.r.o.
  Valid from : Tue 2016-04-26 15:24:51.000 GMT
  Valid to   : Wed 2055-09-29 15:24:51.000 GMT

15:26:59.169            pjsua_app.c  TLS cert verification result of sip.odorik.cz:5061 : 
15:26:59.169            pjsua_app.c  - The certificate is untrusted
15:26:59.169            pjsua_app.c  - Unknown verification error
15:26:59.169            pjsua_app.c  PJSUA is configured to ignore TLS cert verification errors
15:26:59.169     tlsc0x556c9641dfa8  TLS transport 192.168.2.111:53517 is connected to sip.odorik.cz:5061
15:26:59.180         sip_endpoint.c  Processing incoming message: Response msg 407/INVITE/cseq=15572 (rdata0x556c9641e2c0)
15:26:59.180           pjsua_core.c  .RX 492 bytes Response msg 407/INVITE/cseq=15572 (rdata0x556c9641e2c0) from TLS 81.31.45.51:5061:
SIP/2.0 407 Proxy Authentication Required
Via: SIP/2.0/TLS 192.168.2.111:53517;rport=53517;branch=z9hG4bKPj2nN28.PXXsGw8vXIXIb70ES304ZrY8wq;alias;received=85.135.106.202
From: sips:405277@sip.odorik.cz;tag=C3nkkXpG3tTT3es.ol6CDn94e2Hk2RpS
To: sips:*412879@sip.odorik.cz;tag=d9f55a8611ba6e9295acea97d3a19a47.7e28
Call-ID: d8dVCzU5H23u.i6eiIJsjJaU9nj0UJ9-
CSeq: 15572 INVITE
Proxy-Authenticate: Digest realm="sip.odorik.cz", nonce="XIu4rFyLt4CSpS5vODfOyoetwAYszLT0"
Content-Length: 0


--end msg--
15:26:59.180      tsx0x556c9642b4d8  .Incoming Response msg 407/INVITE/cseq=15572 (rdata0x556c9641e2c0) in state Calling
15:26:59.180               endpoint  ..Request msg ACK/cseq=15572 (tdta0x7fe4b4022048) created.
15:26:59.180           pjsua_core.c  ..TX 383 bytes Request msg ACK/cseq=15572 (tdta0x7fe4b4022048) to TLS 81.31.45.51:5061:
ACK sips:*412879@sip.odorik.cz SIP/2.0
Via: SIP/2.0/TLS 192.168.2.111:53517;rport;branch=z9hG4bKPj2nN28.PXXsGw8vXIXIb70ES304ZrY8wq;alias
Max-Forwards: 70
From: sips:405277@sip.odorik.cz;tag=C3nkkXpG3tTT3es.ol6CDn94e2Hk2RpS
To: sips:*412879@sip.odorik.cz;tag=d9f55a8611ba6e9295acea97d3a19a47.7e28
Call-ID: d8dVCzU5H23u.i6eiIJsjJaU9nj0UJ9-
CSeq: 15572 ACK
Content-Length:  0


--end msg--
15:26:59.180      tsx0x556c9642b4d8  ..State changed from Calling to Completed, event=RX_MSG
15:26:59.180      dlg0x556c9641fa48  ...Received Response msg 407/INVITE/cseq=15572 (rdata0x556c9641e2c0)
15:26:59.180      dlg0x556c9641fa48  ...Transaction tsx0x556c9642b4d8 state changed to Completed
15:26:59.180      inv0x556c9641fa48  .....Sending Request msg INVITE/cseq=15572 (tdta0x556c96428568)
15:26:59.180      dlg0x556c9641fa48  ......Sending Request msg INVITE/cseq=15572 (tdta0x556c96428568)
15:26:59.180      tsx0x7fe4b4024fa8  .......Transaction created for Request msg INVITE/cseq=15573 (tdta0x556c96428568)
15:26:59.180      tsx0x7fe4b4024fa8  ......Sending Request msg INVITE/cseq=15573 (tdta0x556c96428568) in state Null
15:26:59.180               endpoint  .......Request msg INVITE/cseq=15573 (tdta0x556c96428568): skipping target resolution because address is already set
15:26:59.180           pjsua_core.c  .......TX 1835 bytes Request msg INVITE/cseq=15573 (tdta0x556c96428568) to TLS 81.31.45.51:5061:
INVITE sips:*412879@sip.odorik.cz SIP/2.0
Via: SIP/2.0/TLS 192.168.2.111:53517;rport;branch=z9hG4bKPjeCYOscGOcvHMlMPfkl1RLki-tOcUziq5;alias
Max-Forwards: 70
From: sips:405277@sip.odorik.cz;tag=C3nkkXpG3tTT3es.ol6CDn94e2Hk2RpS
To: sips:*412879@sip.odorik.cz
Contact: <sips:405277@192.168.2.111:53517;transport=TLS;ob>
Call-ID: d8dVCzU5H23u.i6eiIJsjJaU9nj0UJ9-
CSeq: 15573 INVITE
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Session-Expires: 1800
Min-SE: 90
User-Agent: PJSUA v2.8-svn Linux-4.9.0.8/x86_64/glibc-2.24
Proxy-Authorization: Digest username="405277", realm="sip.odorik.cz", nonce="XIu4rFyLt4CSpS5vODfOyoetwAYszLT0", uri="sips:*412879@sip.odorik.cz", response="18ef0093ae151dd1b34c5047a798bbe6"
Content-Type: application/sdp
Content-Length:   961

v=0
o=- 3761648819 3761648819 IN IP4 192.168.2.111
s=pjmedia
b=AS:117
t=0 0
a=X-nat:0
m=audio 4000 RTP/SAVP 98 97 99 104 3 0 8 9 120 96
c=IN IP4 192.168.2.111
b=TIAS:96000
a=rtcp:4001 IN IP4 192.168.2.111
a=sendrecv
a=rtpmap:98 speex/16000
a=rtpmap:97 speex/8000
a=rtpmap:99 speex/32000
a=rtpmap:104 iLBC/8000
a=fmtp:104 mode=30
a=rtpmap:3 GSM/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:9 G722/8000
a=rtpmap:120 opus/48000/2
a=fmtp:120 useinbandfec=1
a=rtpmap:96 telephone-event/8000
a=fmtp:96 0-16
a=ssrc:698982219 cname:0629d1f05b40e9d3
a=crypto:1 AES_256_CM_HMAC_SHA1_80 inline:LMYPMX09hAjNdtDlM4rk4kk5BoKqjMjvtwzO6so9pYiLfWbLu6GJyyefc9rF8A==
a=crypto:2 AES_256_CM_HMAC_SHA1_32 inline:rvB4OdvjFQYe62d7i84H72viCEzdRMPu4XDYbnnaC0I7EERl0/WommACFbstJA==
a=crypto:3 AES_CM_128_HMAC_SHA1_80 inline:y89ybfcvUE6lifPWvK6o5TjhM+wQ7z2uBhMrjzgU
a=crypto:4 AES_CM_128_HMAC_SHA1_32 inline:YUlHRq8qDMd4ZWwihCUgso47Ywzpzp7A/xhnW6/B

--end msg--
15:26:59.180      tsx0x7fe4b4024fa8  .......State changed from Null to Calling, event=TX_MSG
15:26:59.180      dlg0x556c9641fa48  ........Transaction tsx0x7fe4b4024fa8 state changed to Calling
15:26:59.180      tsx0x556c9642b4d8  Timeout timer event
15:26:59.180      tsx0x556c9642b4d8  .State changed from Completed to Terminated, event=TIMER
15:26:59.180      dlg0x556c9641fa48  ..Transaction tsx0x556c9642b4d8 state changed to Terminated
15:26:59.180      tsx0x556c9642b4d8  Timeout timer event
15:26:59.180      tsx0x556c9642b4d8  .State changed from Terminated to Destroyed, event=TIMER
15:26:59.181     tdta0x7fe4b4022048  ..Destroying txdata Request msg ACK/cseq=15572 (tdta0x7fe4b4022048)
15:26:59.181      tsx0x556c9642b4d8  Transaction destroyed!
15:26:59.202         sip_endpoint.c  Processing incoming message: Response msg 100/INVITE/cseq=15573 (rdata0x556c9641e2c0)
15:26:59.202           pjsua_core.c  .RX 335 bytes Response msg 100/INVITE/cseq=15573 (rdata0x556c9641e2c0) from TLS 81.31.45.51:5061:
SIP/2.0 100 Trying
Via: SIP/2.0/TLS 192.168.2.111:53517;rport=53517;branch=z9hG4bKPjeCYOscGOcvHMlMPfkl1RLki-tOcUziq5;alias;received=85.135.106.202
From: sips:405277@sip.odorik.cz;tag=C3nkkXpG3tTT3es.ol6CDn94e2Hk2RpS
To: sips:*412879@sip.odorik.cz
Call-ID: d8dVCzU5H23u.i6eiIJsjJaU9nj0UJ9-
CSeq: 15573 INVITE
Content-Length: 0


--end msg--
15:26:59.202      tsx0x7fe4b4024fa8  .Incoming Response msg 100/INVITE/cseq=15573 (rdata0x556c9641e2c0) in state Calling
15:26:59.202      tsx0x7fe4b4024fa8  ..State changed from Calling to Proceeding, event=RX_MSG
15:26:59.202      dlg0x556c9641fa48  ...Received Response msg 100/INVITE/cseq=15573 (rdata0x556c9641e2c0)
15:26:59.202      dlg0x556c9641fa48  ...Transaction tsx0x7fe4b4024fa8 state changed to Proceeding
15:26:59.394         sip_endpoint.c  Processing incoming message: Response msg 200/INVITE/cseq=15573 (rdata0x556c9641e2c0)
15:26:59.394           pjsua_core.c  .RX 1245 bytes Response msg 200/INVITE/cseq=15573 (rdata0x556c9641e2c0) from TLS 81.31.45.51:5061:
SIP/2.0 200 OK
Via: SIP/2.0/TLS 192.168.2.111:53517;received=85.135.106.202;rport=53517;branch=z9hG4bKPjeCYOscGOcvHMlMPfkl1RLki-tOcUziq5;alias
Record-Route: <sips:*412879@81.31.45.51;r2=on;lr=on;ftag=C3nkkXpG3tTT3es.ol6CDn94e2Hk2RpS;did=73e5.e83f;nat=yes>
Record-Route: <sips:*412879@81.31.45.51:5061;transport=tls;r2=on;lr=on;ftag=C3nkkXpG3tTT3es.ol6CDn94e2Hk2RpS;did=73e5.e83f;nat=yes>
From: sips:405277@sip.odorik.cz;tag=C3nkkXpG3tTT3es.ol6CDn94e2Hk2RpS
To: sips:*412879@sip.odorik.cz;tag=as059b3d29
Call-ID: d8dVCzU5H23u.i6eiIJsjJaU9nj0UJ9-
CSeq: 15573 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces
Contact: <sips:*086*412879@81.31.45.59:5060;alias=81.31.45.59~5060~1>
Content-Type: application/sdp
Content-Length: 444

v=0
o=root 815884359 815884359 IN IP4 81.31.45.59
s=-
c=IN IP4 81.31.45.59
t=0 0
m=audio 13010 RTP/SAVP 8 97 3 104 9 96
a=rtpmap:8 PCMA/8000
a=rtpmap:97 speex/8000
a=rtpmap:3 GSM/8000
a=rtpmap:104 iLBC/8000
a=fmtp:104 mode=30
a=rtpmap:9 G722/8000
a=rtpmap:96 telephone-event/8000
a=fmtp:96 0-16
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv
a=crypto:3 AES_CM_128_HMAC_SHA1_80 inline:U77xcr7Y8lQ3nksege5+vZ4Ht4mOq9ky3VwAYR0A

--end msg--
15:26:59.394      tsx0x7fe4b4024fa8  .Incoming Response msg 200/INVITE/cseq=15573 (rdata0x556c9641e2c0) in state Proceeding
15:26:59.394      tsx0x7fe4b4024fa8  ..State changed from Proceeding to Terminated, event=RX_MSG
15:26:59.394      dlg0x556c9641fa48  ...Received Response msg 200/INVITE/cseq=15573 (rdata0x556c9641e2c0)
15:26:59.395      dlg0x556c9641fa48  ....Route-set updated
15:26:59.395      dlg0x556c9641fa48  ....Route-set frozen
15:26:59.395      dlg0x556c9641fa48  ...Transaction tsx0x7fe4b4024fa8 state changed to Terminated
15:26:59.395            pjsua_app.c  .....Call 0 state changed to CONNECTING
15:26:59.395      inv0x556c9641fa48  ....Got SDP answer in Response msg 200/INVITE/cseq=15573 (rdata0x556c9641e2c0)
15:26:59.395      inv0x556c9641fa48  ....SDP negotiation done, status=0
15:26:59.395           pjsua_call.c  .....Call 0: remote NAT type is 0 (Unknown)
15:26:59.395          pjsua_media.c  .....Call 0: updating media..
15:26:59.395          pjsua_media.c  .......Media stream call00:0 is destroyed
15:26:59.395     srtp0x556c96423a20  ......TX: AES_CM_128_HMAC_SHA1_80 key=y89ybfcvUE6lifPWvK6o5TjhM+wQ7z2uBhMrjzgU
15:26:59.395     srtp0x556c96423a20  ......RX: AES_CM_128_HMAC_SHA1_80 key=U77xcr7Y8lQ3nksege5+vZ4Ht4mOq9ky3VwAYR0A
15:26:59.395     srtp0x556c96423a20  ......SRTP started, keying=SDES, crypto=AES_CM_128_HMAC_SHA1_80
15:26:59.395            pjsua_aud.c  ......Audio channel update..
15:26:59.395     strm0x7fe4b4036f48  .......VAD temporarily disabled
15:26:59.395                  rtp.c  .......pjmedia_rtp_session_init: ses=0x7fe4b40395d0, default_pt=8, ssrc=0x29a99f4b
15:26:59.395                  rtp.c  .......pjmedia_rtp_session_init: ses=0x7fe4b4039c50, default_pt=8, ssrc=0x29a99f4b
15:26:59.395               stream.c  .......Stream strm0x7fe4b4036f48 created
15:26:59.395     strm0x7fe4b4036f48  .......Encoder stream started
15:26:59.395     strm0x7fe4b4036f48  .......Decoder stream started
15:26:59.395             resample.c  .......resample created: high qualiy, large filter, in/out rate=8000/16000
15:26:59.395             resample.c  .......resample created: high qualiy, large filter, in/out rate=16000/8000
15:26:59.395          pjsua_media.c  ......Audio updated, stream #0: PCMA (sendrecv)
15:26:59.395            pjsua_app.c  .....Call 0 media 0 [type=audio], status is Active
15:26:59.395            pjsua_aud.c  .....Conf connect: 3 --> 0
15:26:59.395           conference.c  ......Port 3 (sips:*412879@sip.odorik.cz) transmitting to port 0 (Master/sound)
15:26:59.395            pjsua_aud.c  .....Conf connect: 0 --> 3
15:26:59.395           conference.c  ......Port 0 (Master/sound) transmitting to port 3 (sips:*412879@sip.odorik.cz)
15:26:59.395      inv0x556c9641fa48  ....Received Response msg 200/INVITE/cseq=15573 (rdata0x556c9641e2c0), sending ACK
15:26:59.395               endpoint  ....Request msg ACK/cseq=15573 (tdta0x7fe4b403c308) created.
15:26:59.395      dlg0x556c9641fa48  .....Sending Request msg ACK/cseq=15573 (tdta0x7fe4b403c308)
15:26:59.395          sip_resolve.c  .....Target '81.31.45.51:5061' type=TLS resolved to '81.31.45.51:5061' type=TLS (TLS transport)
15:26:59.395           pjsua_core.c  .....TX 615 bytes Request msg ACK/cseq=15573 (tdta0x7fe4b403c308) to TLS 81.31.45.51:5061:
ACK sips:*086*412879@81.31.45.59:5060;alias=81.31.45.59~5060~1 SIP/2.0
Via: SIP/2.0/TLS 192.168.2.111:53517;rport;branch=z9hG4bKPj6YgDNpH4ate.g3O7vnbaMuFugkRHC.Px;alias
Max-Forwards: 70
From: sips:405277@sip.odorik.cz;tag=C3nkkXpG3tTT3es.ol6CDn94e2Hk2RpS
To: sips:*412879@sip.odorik.cz;tag=as059b3d29
Call-ID: d8dVCzU5H23u.i6eiIJsjJaU9nj0UJ9-
CSeq: 15573 ACK
Route: <sips:*412879@81.31.45.51:5061;transport=tls;lr;r2=on;ftag=C3nkkXpG3tTT3es.ol6CDn94e2Hk2RpS;did=73e5.e83f;nat=yes>
Route: <sips:*412879@81.31.45.51;lr;r2=on;ftag=C3nkkXpG3tTT3es.ol6CDn94e2Hk2RpS;did=73e5.e83f;nat=yes>
Content-Length:  0


--end msg--
15:26:59.395            pjsua_app.c  .....Call 0 state changed to CONFIRMED
15:26:59.395           pjsua_call.c  .Call 0 sending re-INVITE for updating media session to use only one codec
15:26:59.395     srtp0x556c96423a20  .SRTP uses keying method SDES
15:26:59.395               endpoint  ..Request msg INVITE/cseq=15575 (tdta0x7fe4b4041898) created.
15:26:59.395      inv0x556c9641fa48  ..Sending Request msg INVITE/cseq=15575 (tdta0x7fe4b4041898)
15:26:59.395      dlg0x556c9641fa48  ...Sending Request msg INVITE/cseq=15575 (tdta0x7fe4b4041898)
15:26:59.395      tsx0x7fe4b4044808  ....Transaction created for Request msg INVITE/cseq=15574 (tdta0x7fe4b4041898)
15:26:59.395      tsx0x7fe4b4044808  ...Sending Request msg INVITE/cseq=15574 (tdta0x7fe4b4041898) in state Null
15:26:59.395          sip_resolve.c  ....Target '81.31.45.51:5061' type=TLS resolved to '81.31.45.51:5061' type=TLS (TLS transport)
15:26:59.395           pjsua_core.c  ....TX 1298 bytes Request msg INVITE/cseq=15574 (tdta0x7fe4b4041898) to TLS 81.31.45.51:5061:
INVITE sips:*086*412879@81.31.45.59:5060;alias=81.31.45.59~5060~1 SIP/2.0
Via: SIP/2.0/TLS 192.168.2.111:53517;rport;branch=z9hG4bKPj.CKNK29H2JUW1v5pp1UEYbARap-3LJ.i;alias
Max-Forwards: 70
From: sips:405277@sip.odorik.cz;tag=C3nkkXpG3tTT3es.ol6CDn94e2Hk2RpS
To: sips:*412879@sip.odorik.cz;tag=as059b3d29
Contact: <sips:405277@192.168.2.111:53517;transport=TLS;ob>
Call-ID: d8dVCzU5H23u.i6eiIJsjJaU9nj0UJ9-
CSeq: 15574 INVITE
Route: <sips:*412879@81.31.45.51:5061;transport=tls;lr;r2=on;ftag=C3nkkXpG3tTT3es.ol6CDn94e2Hk2RpS;did=73e5.e83f;nat=yes>
Route: <sips:*412879@81.31.45.51;lr;r2=on;ftag=C3nkkXpG3tTT3es.ol6CDn94e2Hk2RpS;did=73e5.e83f;nat=yes>
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Session-Expires: 1800
Min-SE: 90
Content-Type: application/sdp
Content-Length:   401

v=0
o=- 3761648819 3761648820 IN IP4 192.168.2.111
s=pjmedia
b=AS:117
t=0 0
a=X-nat:0
m=audio 4000 RTP/SAVP 8 96
c=IN IP4 192.168.2.111
b=TIAS:96000
a=rtcp:4001 IN IP4 192.168.2.111
a=ssrc:698982219 cname:0629d1f05b40e9d3
a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:y89ybfcvUE6lifPWvK6o5TjhM+wQ7z2uBhMrjzgU
a=rtpmap:8 PCMA/8000
a=rtpmap:96 telephone-event/8000
a=fmtp:96 0-16
a=sendrecv

--end msg--
15:26:59.396      tsx0x7fe4b4044808  ....State changed from Null to Calling, event=TX_MSG
15:26:59.396      dlg0x556c9641fa48  .....Transaction tsx0x7fe4b4044808 state changed to Calling
15:26:59.396      tsx0x7fe4b4024fa8  Timeout timer event
15:26:59.396      tsx0x7fe4b4024fa8  .State changed from Terminated to Destroyed, event=TIMER
15:26:59.396      tsx0x7fe4b4024fa8  Transaction destroyed!
15:26:59.406     strm0x7fe4b4036f48 !Jitter buffer is bufferring (prefetch=0)
15:26:59.406     strm0x7fe4b4036f48  Start talksprut..
15:26:59.456         sip_endpoint.c !Processing incoming message: Response msg 100/INVITE/cseq=15574 (rdata0x556c9641e2c0)
15:26:59.456           pjsua_core.c  .RX 350 bytes Response msg 100/INVITE/cseq=15574 (rdata0x556c9641e2c0) from TLS 81.31.45.51:5061:
SIP/2.0 100 Trying
Via: SIP/2.0/TLS 192.168.2.111:53517;rport=53517;branch=z9hG4bKPj.CKNK29H2JUW1v5pp1UEYbARap-3LJ.i;alias;received=85.135.106.202
From: sips:405277@sip.odorik.cz;tag=C3nkkXpG3tTT3es.ol6CDn94e2Hk2RpS
To: sips:*412879@sip.odorik.cz;tag=as059b3d29
Call-ID: d8dVCzU5H23u.i6eiIJsjJaU9nj0UJ9-
CSeq: 15574 INVITE
Content-Length: 0


--end msg--
15:26:59.456      tsx0x7fe4b4044808  .Incoming Response msg 100/INVITE/cseq=15574 (rdata0x556c9641e2c0) in state Calling
15:26:59.456      tsx0x7fe4b4044808  ..State changed from Calling to Proceeding, event=RX_MSG
15:26:59.456      dlg0x556c9641fa48  ...Received Response msg 100/INVITE/cseq=15574 (rdata0x556c9641e2c0)
15:26:59.456      dlg0x556c9641fa48  ...Transaction tsx0x7fe4b4044808 state changed to Proceeding
15:26:59.458         sip_endpoint.c  Processing incoming message: Response msg 200/INVITE/cseq=15574 (rdata0x556c9641e2c0)
15:26:59.458           pjsua_core.c  .RX 1115 bytes Response msg 200/INVITE/cseq=15574 (rdata0x556c9641e2c0) from TLS 81.31.45.51:5061:
SIP/2.0 200 OK
Via: SIP/2.0/TLS 192.168.2.111:53517;received=85.135.106.202;rport=53517;branch=z9hG4bKPj.CKNK29H2JUW1v5pp1UEYbARap-3LJ.i;alias
Record-Route: <sips:*086*412879@81.31.45.51;r2=on;lr=on;ftag=C3nkkXpG3tTT3es.ol6CDn94e2Hk2RpS;did=73e5.e83f>
Record-Route: <sips:*086*412879@81.31.45.51:5061;transport=tls;r2=on;lr=on;ftag=C3nkkXpG3tTT3es.ol6CDn94e2Hk2RpS;did=73e5.e83f>
From: sips:405277@sip.odorik.cz;tag=C3nkkXpG3tTT3es.ol6CDn94e2Hk2RpS
To: sips:*412879@sip.odorik.cz;tag=as059b3d29
Call-ID: d8dVCzU5H23u.i6eiIJsjJaU9nj0UJ9-
CSeq: 15574 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces
Contact: <sips:*086*412879@81.31.45.59:5060;alias=81.31.45.59~5060~1>
Content-Type: application/sdp
Content-Length: 322

v=0
o=root 815884359 815884360 IN IP4 81.31.45.59
s=-
c=IN IP4 81.31.45.59
t=0 0
m=audio 13010 RTP/SAVP 8 96
a=rtpmap:8 PCMA/8000
a=rtpmap:96 telephone-event/8000
a=fmtp:96 0-16
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv
a=crypto:3 AES_CM_128_HMAC_SHA1_80 inline:U77xcr7Y8lQ3nksege5+vZ4Ht4mOq9ky3VwAYR0A

--end msg--
15:26:59.458      tsx0x7fe4b4044808  .Incoming Response msg 200/INVITE/cseq=15574 (rdata0x556c9641e2c0) in state Proceeding
15:26:59.458      tsx0x7fe4b4044808  ..State changed from Proceeding to Terminated, event=RX_MSG
15:26:59.458      dlg0x556c9641fa48  ...Received Response msg 200/INVITE/cseq=15574 (rdata0x556c9641e2c0)
15:26:59.458      dlg0x556c9641fa48  ...Transaction tsx0x7fe4b4044808 state changed to Terminated
15:26:59.458      inv0x556c9641fa48  ....Got SDP answer in Response msg 200/INVITE/cseq=15574 (rdata0x556c9641e2c0)
15:26:59.458      inv0x556c9641fa48  ....SDP negotiation done, status=0
15:26:59.458           pjsua_call.c  .....Call 0: remote NAT type is 0 (Unknown)
15:26:59.458          pjsua_media.c  .....Call 0: updating media..
15:26:59.458     strm0x7fe4b4036f48  .......JB summary:
  size=0/eff=0 prefetch=0 level=0
  delay (min/max/avg/dev)=0/0/0/0 ms
  burst (min/max/avg/dev)=0/0/0/0 frames
  lost=0 discard=0 empty=3
15:26:59.458          pjsua_media.c  .......Media stream call00:0 is destroyed
15:26:59.458          pjsua_media.c  ......pjmedia_transport_media_start() failed for call_id 0 media 0: Invalid SRTP crypto tag (PJMEDIA_SRTP_ESDPINCRYPTOTAG)
15:26:59.458          pjsua_media.c  .......Media stream call00:0 is destroyed
15:26:59.458          pjsua_media.c  ......Error updating media call00:0: Invalid SRTP crypto tag (PJMEDIA_SRTP_ESDPINCRYPTOTAG)
15:26:59.458            pjsua_app.c  .....Call 0 media 0 [type=audio], status is Error
15:26:59.458      inv0x556c9641fa48  ....Received Response msg 200/INVITE/cseq=15574 (rdata0x556c9641e2c0), sending ACK
15:26:59.458     tdta0x7fe4b403c308  ....Destroying txdata Request msg ACK/cseq=15573 (tdta0x7fe4b403c308)
15:26:59.458               endpoint  ....Request msg ACK/cseq=15574 (tdta0x7fe4b4023dc8) created.
15:26:59.458      dlg0x556c9641fa48  .....Sending Request msg ACK/cseq=15574 (tdta0x7fe4b4023dc8)
15:26:59.458          sip_resolve.c  .....Target '81.31.45.51:5061' type=TLS resolved to '81.31.45.51:5061' type=TLS (TLS transport)
15:26:59.458           pjsua_core.c  .....TX 615 bytes Request msg ACK/cseq=15574 (tdta0x7fe4b4023dc8) to TLS 81.31.45.51:5061:
ACK sips:*086*412879@81.31.45.59:5060;alias=81.31.45.59~5060~1 SIP/2.0
Via: SIP/2.0/TLS 192.168.2.111:53517;rport;branch=z9hG4bKPjaRztjoV5cswhxYEhqicpselRgjF5AXpO;alias
Max-Forwards: 70
From: sips:405277@sip.odorik.cz;tag=C3nkkXpG3tTT3es.ol6CDn94e2Hk2RpS
To: sips:*412879@sip.odorik.cz;tag=as059b3d29
Call-ID: d8dVCzU5H23u.i6eiIJsjJaU9nj0UJ9-
CSeq: 15574 ACK
Route: <sips:*412879@81.31.45.51:5061;transport=tls;lr;r2=on;ftag=C3nkkXpG3tTT3es.ol6CDn94e2Hk2RpS;did=73e5.e83f;nat=yes>
Route: <sips:*412879@81.31.45.51;lr;r2=on;ftag=C3nkkXpG3tTT3es.ol6CDn94e2Hk2RpS;did=73e5.e83f;nat=yes>
Content-Length:  0


--end msg--
15:26:59.458      tsx0x7fe4b4044808  Timeout timer event
15:26:59.458      tsx0x7fe4b4044808  .State changed from Terminated to Destroyed, event=TIMER
15:26:59.458     tdta0x7fe4b4041898  ..Destroying txdata Request msg INVITE/cseq=15574 (tdta0x7fe4b4041898)
15:26:59.458      tsx0x7fe4b4044808  Transaction destroyed!
15:27:15.743         sip_endpoint.c  Processing incoming message: Request msg BYE/cseq=102 (rdata0x556c9641e2c0)
15:27:15.743           pjsua_core.c  .RX 455 bytes Request msg BYE/cseq=102 (rdata0x556c9641e2c0) from TLS 81.31.45.51:5061:
BYE sips:405277@192.168.2.111:53517;transport=TLS;ob SIP/2.0
Via: SIP/2.0/TLS 81.31.45.51:5061;branch=z9hG4bK60d7.6fa56da1921fd59f8aa1cc02cf5db64e.0
Via: SIP/2.0/UDP 81.31.45.59:5060;received=81.31.45.59;branch=z9hG4bK24f53ee4;rport=5060
Max-Forwards: 69
From: sips:*412879@sip.odorik.cz;tag=as059b3d29
To: sips:405277@sip.odorik.cz;tag=C3nkkXpG3tTT3es.ol6CDn94e2Hk2RpS
Call-ID: d8dVCzU5H23u.i6eiIJsjJaU9nj0UJ9-
CSeq: 102 BYE
Content-Length: 0


--end msg--
15:27:15.744      dlg0x556c9641fa48  .Received Request msg BYE/cseq=102 (rdata0x556c9641e2c0)
15:27:15.744      tsx0x7fe4b4022ff8  ...Transaction created for Request msg BYE/cseq=102 (rdata0x556c9641e2c0)
15:27:15.744      tsx0x7fe4b4022ff8  ..Incoming Request msg BYE/cseq=102 (rdata0x556c9641e2c0) in state Null
15:27:15.744      tsx0x7fe4b4022ff8  ...State changed from Null to Trying, event=RX_MSG
15:27:15.744      dlg0x556c9641fa48  ....Transaction tsx0x7fe4b4022ff8 state changed to Trying
15:27:15.744               endpoint  .....Response msg 200/BYE/cseq=102 (tdta0x7fe4b4038f18) created
15:27:15.744      dlg0x556c9641fa48  ......Sending Response msg 200/BYE/cseq=102 (tdta0x7fe4b4038f18)
15:27:15.744      tsx0x7fe4b4022ff8  ......Sending Response msg 200/BYE/cseq=102 (tdta0x7fe4b4038f18) in state Trying
15:27:15.744           pjsua_core.c  .......TX 417 bytes Response msg 200/BYE/cseq=102 (tdta0x7fe4b4038f18) to TLS 81.31.45.51:5061:
SIP/2.0 200 OK
Via: SIP/2.0/TLS 81.31.45.51:5061;received=81.31.45.51;branch=z9hG4bK60d7.6fa56da1921fd59f8aa1cc02cf5db64e.0
Via: SIP/2.0/UDP 81.31.45.59:5060;rport=5060;received=81.31.45.59;branch=z9hG4bK24f53ee4
Call-ID: d8dVCzU5H23u.i6eiIJsjJaU9nj0UJ9-
From: <sips:*412879@sip.odorik.cz>;tag=as059b3d29
To: <sips:405277@sip.odorik.cz>;tag=C3nkkXpG3tTT3es.ol6CDn94e2Hk2RpS
CSeq: 102 BYE
Content-Length:  0


--end msg--
15:27:15.744      tsx0x7fe4b4022ff8  .......State changed from Trying to Completed, event=TX_MSG
15:27:15.744      dlg0x556c9641fa48  ........Transaction tsx0x7fe4b4022ff8 state changed to Completed
15:27:15.744            pjsua_app.c  ......Call 0 is DISCONNECTED [reason=200 (Normal call clearing)]
15:27:15.744            pjsua_app.c  ......Call 0 disconnected, dumping media stats..
15:27:15.744     pjsua_app_common.c  ......
  [DISCONNCTD] To: sips:*412879@sip.odorik.cz;tag=as059b3d29
    Call time: 00h:00m:16s, 1st res in 269 ms, conn in 269ms
    #0 audio deactivated
15:27:15.744          pjsua_media.c  ......Call 0: deinitializing media..
15:27:15.744          pjsua_media.c  ........Media stream call00:0 is destroyed
15:27:15.744     tdta0x7fe4b4023dc8  .....Destroying txdata Request msg ACK/cseq=15574 (tdta0x7fe4b4023dc8)
15:27:15.744     tdta0x556c96428568  .....Destroying txdata Request msg INVITE/cseq=15573 (tdta0x556c96428568)
15:27:15.745      dlg0x556c9641fa48  ......Session count dec to 3 by mod-invite
15:27:15.745      tsx0x7fe4b4022ff8  Timeout timer event
15:27:15.745      tsx0x7fe4b4022ff8  .State changed from Completed to Terminated, event=TIMER
15:27:15.745      dlg0x556c9641fa48  ..Transaction tsx0x7fe4b4022ff8 state changed to Terminated
15:27:15.745      dlg0x556c9641fa48  ...Dialog destroyed
15:27:15.745      tsx0x7fe4b4022ff8  Timeout timer event
15:27:15.745      tsx0x7fe4b4022ff8  .State changed from Terminated to Destroyed, event=TIMER
15:27:15.745     tdta0x7fe4b4038f18  ..Destroying txdata Response msg 200/BYE/cseq=102 (tdta0x7fe4b4038f18)
15:27:15.745      tsx0x7fe4b4022ff8  Transaction destroyed!
15:27:16.745            pjsua_aud.c  Closing sound device after idle for 1 second(s)
15:27:16.745            pjsua_app.c  .Turning sound device -99 -99 OFF
15:27:16.745            pjsua_aud.c  .Closing null sound device..
15:27:20.627           pjsua_core.c !Shutting down, flags=0...
15:27:20.627           pjsua_core.c  PJSUA state changed: RUNNING --> CLOSING
15:27:20.632           pjsua_call.c  .Hangup all calls..
15:27:20.632          pjsua_media.c  .Call 0: deinitializing media..
15:27:20.632          pjsua_media.c  .Call 1: deinitializing media..
15:27:20.632          pjsua_media.c  .Call 2: deinitializing media..
15:27:20.632          pjsua_media.c  .Call 3: deinitializing media..
15:27:20.632           pjsua_pres.c  .Shutting down presence..
15:27:21.647           pjsua_core.c  .Destroying...
15:27:21.647          pjsua_media.c  .Shutting down media..
15:27:21.705         sip_endpoint.c  .Destroying endpoing instance..
15:27:21.705      sip_transaction.c  .Stopping transaction layer module
15:27:21.705      sip_transaction.c  .Stopped transaction layer module
15:27:21.705         sip_endpoint.c  .Module "mod-default-handler" unregistered
15:27:21.706         sip_endpoint.c  .Module "mod-unsolicited-mwi" unregistered
15:27:21.706         sip_endpoint.c  .Module "mod-pjsua-options" unregistered
15:27:21.706         sip_endpoint.c  .Module "mod-pjsua-im" unregistered
15:27:21.706         sip_endpoint.c  .Module "mod-pjsua-pres" unregistered
15:27:21.706         sip_endpoint.c  .Module "mod-pjsua" unregistered
15:27:21.706         sip_endpoint.c  .Module "mod-stateful-util" unregistered
15:27:21.706         sip_endpoint.c  .Module "mod-refer" unregistered
15:27:21.706         sip_endpoint.c  .Module "mod-mwi" unregistered
15:27:21.706         sip_endpoint.c  .Module "mod-presence" unregistered
15:27:21.706         sip_endpoint.c  .Module "mod-evsub" unregistered
15:27:21.706         sip_endpoint.c  .Module "mod-invite" unregistered
15:27:21.706         sip_endpoint.c  .Module "mod-100rel" unregistered
15:27:21.706         sip_endpoint.c  .Module "mod-ua" unregistered
15:27:21.706      sip_transaction.c  .Transaction layer module destroyed
15:27:21.706         sip_endpoint.c  .Module "mod-tsx-layer" unregistered
15:27:21.706         sip_endpoint.c  .Module "mod-msg-print" unregistered
15:27:21.706         sip_endpoint.c  .Module "mod-pjsua-log" unregistered
15:27:21.706        sip_transport.c  .Destroying transport manager
15:27:21.709      udp0x556c964140e0  .SIP UDP transport destroyed
15:27:21.709     tlsc0x556c9641dfa8  .TLS transport destroyed normally
15:27:21.709             tcptp:5060  .SIP TCP transport destroyed
15:27:21.709             tlstp:5061  .SIP TLS transport destroyed
15:27:21.709         sip_endpoint.c  .Endpoint 0x556c963e1928 destroyed
15:27:21.709           pjsua_core.c  .PJSUA state changed: CLOSING --> NULL
15:27:21.709           pjsua_core.c  .PJSUA destroyed...

Odpovědět