[pjsip] RTP port in SDP switched by ALG when sending a re-INVITE
Javier Gálvez Guerrero
javier.galvez.guerrero at gmail.com
Tue Jun 9 09:40:34 EDT 2009
Hi all,
I'm experiencing a problem when performing a handover while being in a
SIP/VoIP session with the pjsua app (pjproject 1.2).
You can see my testbed in the attached arch.txt file. I have a Debian laptop
attached to a LAN (192.168.0.0/24) acting as a VoIP 'client' that starts a
SIP/VoIP session with a Debian box (147.83.47.178) acting as a VoIP
'server'. These are the issued commands:
server: ./pjsua-i686-pc-linux-gnu --play-file
/home/dulceangustia/Desktop/file2.wav --auto-answer 200 --auto-play --no-tcp
client: ./pjsua-i686-pc-linux-gnu sip:147.83.47.178:5060 --add-codec=pcma
--null-audio
Note I force PCMA in order to get VoIP sessions playable by Wireshark and
the '--null-audio' option is issued due to a mighty hardware error that
doesn't allow me to play the audio in real-time.
The moving box has two WiFi interfaces, intf1 always connects to
192.168.0.0/24 with IP 192.168.0.2 and intf2 always connects to
192.168.1.0/24 with IP 192.168.1.2. When the connection is established with
the new network, then I issue a re-INVITE ('v' command) and bring down the
connection with the old network.
Both gateways are D-LINK DIR-635 with SIP ALG support enabled, so o and c
headers in SDP files are properly changed (private IP from the box to public
IP from the gw). Anyway, it seems to change an additional parameter that
brings my problem up: the RTP port announced in the m header when issuing a
re-INVITE from the second interface (in this case, intf2, this is NOT the
one that started the VoIP session) is changed also by the ALG from 4000 to a
random port >20000 in all my tests. This causes pjsua in the server side to
change the RTP port to the announced in the received SDP file instead of
keeping 4000 but the cilent doesn't receive the RTP packets then. After 9-12
packets are lost and the server sends a RTCP sender report (always in this
specific point of the communication), it (the pjsua server side)
'automatically' changes back the destination RTP port to 4000 and the client
continues receiving the stream properly. So I have a gap in the client side
of about 9-12 missing packets ONLY when going to the network that was not
the one which originated the session: if I test it in a ping-pong fashion,
from first network to second network I always lose 9-12 packets but in the
reverse path I never lose any packet (the SIP ALG preservers port 4000 in
the m header as the RTP destination port)
I've tried to change the NAT type managed by the routers (endpoint
independent, address restrictive and address and port restrictive options
are available) but all three options give the same results. If I switch off
the SIP ALG support then I always lose these 9-12 packets, because after
sending these packets to the private IP the pjsua server switches the
destination RTP IP from the private one (box) to the gw (public) and this
(gw) routes to the private IP.
I also attach the pjsua log files from a session both in the client and the
server side.
I would like to know if anyone have any idea about what is happening here.
Why the SIP ALG changes the RTP port in the SDP file? Might it be used by
another application? Why does it always happen with the network different
from the one where the session was started in?
Any help would be much appreciated. Thanks a lot for your time.
Regards,
Javi
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.pjsip.org/pipermail/pjsip_lists.pjsip.org/attachments/20090609/a9d75f57/attachment-0001.html>
-------------- next part --------------
+----------+
| |
| VoIP |
| server | 147.83.47.178
| |
+---+------+
|
---+---
-/ \-
/ \
/ \
| IP |
\ /
--\ /
147.83.47.176 -/ -\ /---- 147.83.47.177
+--------/ ------- \-+--------+
| gwA | | gwB |
+--------+ +--------+
192.168.0.0/24 192.168.1.0/24
+-----------+
| VoIP |
intf1| client |intf2
192.168.0.2| |192.168.1.2
+-----------+
------------------------->
handover
-------------- next part --------------
16:28:30.461 sip_endpoint.c Module "mod-pjsua-log" registered
16:28:30.461 sip_endpoint.c Module "mod-tsx-layer" registered
16:28:30.461 sip_endpoint.c Module "mod-stateful-util" registered
16:28:30.461 sip_endpoint.c Module "mod-ua" registered
16:28:30.461 sip_endpoint.c Module "mod-100rel" registered
16:28:30.461 sip_endpoint.c Module "mod-pjsua" registered
16:28:30.461 sip_endpoint.c Module "mod-invite" registered
16:28:30.492 pa_dev.c PortAudio sound library initialized, status=0
16:28:30.492 pa_dev.c PortAudio host api count=1
16:28:30.492 pa_dev.c Sound device count=1
16:28:30.493 pjlib select() I/O Queue created (0x904608c)
16:28:30.493 conference.c Creating conference bridge with 254 ports
16:28:30.493 conference.c Sound device successfully created for port 0
16:28:30.540 sip_endpoint.c Module "mod-evsub" registered
16:28:30.540 sip_endpoint.c Module "mod-presence" registered
16:28:30.540 evsub.c Event pkg "presence" registered by mod-presence
16:28:30.540 sip_endpoint.c Module "mod-refer" registered
16:28:30.540 evsub.c Event pkg "refer" registered by mod-refer
16:28:30.540 sip_endpoint.c Module "mod-pjsua-pres" registered
16:28:30.540 sip_endpoint.c Module "mod-pjsua-im" registered
16:28:30.540 sip_endpoint.c Module "mod-pjsua-options" registered
16:28:30.548 pjsua_core.c 1 SIP worker threads created
16:28:30.548 pjsua_core.c pjsua version 1.2 for i686-pc-linux-gnu initialized
16:28:30.548 sip_endpoint.c Module "mod-default-handler" registered
16:28:30.548 pjsua_core.c SIP UDP socket reachable at 192.168.0.2:5060
16:28:30.548 udp0x9055e28 SIP UDP transport started, published address is 192.168.0.2:5060
16:28:30.548 pjsua_acc.c Account <sip:192.168.0.2:5060> added with id 0
16:28:30.548 tcplis:5060 SIP TCP listener ready for incoming connections at 192.168.0.2:5060
16:28:30.548 pjsua_acc.c Account <sip:192.168.0.2:5060;transport=TCP> added with id 1
16:28:30.548 pjsua_media.c RTP socket reachable at 192.168.0.2:4000
16:28:30.548 pjsua_media.c RTCP socket reachable at 192.168.0.2:4001
16:28:30.548 pjsua_media.c RTP socket reachable at 192.168.0.2:4002
16:28:30.548 pjsua_media.c RTCP socket reachable at 192.168.0.2:4003
16:28:30.548 pjsua_media.c RTP socket reachable at 192.168.0.2:4004
16:28:30.548 pjsua_media.c RTCP socket reachable at 192.168.0.2:4005
16:28:30.549 pjsua_media.c RTP socket reachable at 192.168.0.2:4006
16:28:30.549 pjsua_media.c RTCP socket reachable at 192.168.0.2:4007
16:28:30.549 pjsua_media.c Opening null sound device..
16:28:30.560 pjsua_call.c Making call with acc #1 to sip:147.83.47.178:5060
16:28:30.560 dlg0x90631b4 UAC dialog created
16:28:30.560 pjsua_media.c Media index 0 selected for call 0
16:28:30.560 dlg0x90631b4 Module mod-invite added as dialog usage, data=0x9063868
16:28:30.560 dlg0x90631b4 Session count inc to 3 by mod-invite
16:28:30.560 dlg0x90631b4 Module mod-100rel added as dialog usage, data=0x9065c10
16:28:30.560 dlg0x90631b4 100rel module attached
16:28:30.560 inv0x90631b4 UAC invite session created for dialog dlg0x90631b4
16:28:30.560 endpoint Request msg INVITE/cseq=14304 (tdta0x9066140) created.
16:28:30.560 inv0x90631b4 Sending Request msg INVITE/cseq=14304 (tdta0x9066140)
16:28:30.560 dlg0x90631b4 Sending Request msg INVITE/cseq=14304 (tdta0x9066140)
16:28:30.560 tsx0x90671ac Transaction created for Request msg INVITE/cseq=14303 (tdta0x9066140)
16:28:30.560 tsx0x90671ac Sending Request msg INVITE/cseq=14303 (tdta0x9066140) in state Null
16:28:30.560 sip_resolve.c Target '147.83.47.178:5060' type=Unspecified resolved to '147.83.47.178:5060' type=UDP (UDP transport)
16:28:30.560 pjsua_core.c TX 1015 bytes Request msg INVITE/cseq=14303 (tdta0x9066140) to UDP 147.83.47.178:5060:
INVITE sip:147.83.47.178:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.0.2:5060;rport;branch=z9hG4bKPjcmxtzJs4rxf8qpy-qTZvTzYricwYUAL6
Max-Forwards: 70
From: <sip:192.168.0.2>;tag=.oTifxb.Uvlmv8hQYMB6gpEbW-dYji4-
To: sip:147.83.47.178
Contact: <sip:192.168.0.2:5060>
Call-ID: 6WP79L6pbZ9mo9.bedoUWbragcyqrT8p
CSeq: 14303 INVITE
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, norefersub
User-Agent: PJSUA v1.2/i686-pc-linux-gnu
Content-Type: application/sdp
Content-Length: 456
v=0
o=- 3452596110 3452596110 IN IP4 192.168.0.2
s=pjmedia
c=IN IP4 192.168.0.2
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 8 103 102 113 3 0 104 9 101
a=rtcp:4001 IN IP4 192.168.0.2
a=rtpmap:8 PCMA/8000
a=rtpmap:103 speex/16000
a=rtpmap:102 speex/8000
a=rtpmap:113 iLBC/8000
a=fmtp:113 mode=30
a=rtpmap:3 GSM/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:104 speex/32000
a=rtpmap:9 G722/8000
a=sendrecv
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
--end msg--
16:28:30.560 tsx0x90671ac State changed from Null to Calling, event=TX_MSG
16:28:30.560 dlg0x90631b4 Transaction tsx0x90671ac state changed to Calling
16:28:30.560 pjsua_app.c Call 0 state changed to CALLING
16:28:30.567 sip_endpoint.c Processing incoming message: Response msg 100/INVITE/cseq=14303 (rdata0x905629c)
16:28:30.567 pjsua_core.c RX 310 bytes Response msg 100/INVITE/cseq=14303 (rdata0x905629c) from UDP 147.83.47.178:5060:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.0.2:5060;rport=5060;received=147.83.47.176;branch=z9hG4bKPjcmxtzJs4rxf8qpy-qTZvTzYricwYUAL6
Call-ID: 6WP79L6pbZ9mo9.bedoUWbragcyqrT8p
From: <sip:192.168.0.2>;tag=.oTifxb.Uvlmv8hQYMB6gpEbW-dYji4-
To: <sip:147.83.47.178>
CSeq: 14303 INVITE
Content-Length: 0
--end msg--
16:28:30.580 tsx0x90671ac Incoming Response msg 100/INVITE/cseq=14303 (rdata0x905629c) in state Calling
16:28:30.580 tsx0x90671ac State changed from Calling to Proceeding, event=RX_MSG
16:28:30.580 dlg0x90631b4 Received Response msg 100/INVITE/cseq=14303 (rdata0x905629c)
16:28:30.580 dlg0x90631b4 Transaction tsx0x90671ac state changed to Proceeding
16:28:30.613 sip_endpoint.c Processing incoming message: Response msg 200/INVITE/cseq=14303 (rdata0x905629c)
16:28:30.613 pjsua_core.c RX 799 bytes Response msg 200/INVITE/cseq=14303 (rdata0x905629c) from UDP 147.83.47.178:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.0.2:5060;rport=5060;received=147.83.47.176;branch=z9hG4bKPjcmxtzJs4rxf8qpy-qTZvTzYricwYUAL6
Call-ID: 6WP79L6pbZ9mo9.bedoUWbragcyqrT8p
From: <sip:192.168.0.2>;tag=.oTifxb.Uvlmv8hQYMB6gpEbW-dYji4-
To: <sip:147.83.47.178>;tag=fU9ZIWypQSTVLoGgeWaUZy37U6d8Cyn9
CSeq: 14303 INVITE
Contact: <sip:147.83.47.178:5060>
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, norefersub
Content-Type: application/sdp
Content-Length: 254
v=0
o=- 3452596081 3452596082 IN IP4 147.83.47.178
s=pjmedia
c=IN IP4 147.83.47.178
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 8 101
a=rtcp:4001 IN IP4 147.83.47.178
a=rtpmap:8 PCMA/8000
a=sendrecv
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
--end msg--
16:28:30.613 tsx0x90671ac Incoming Response msg 200/INVITE/cseq=14303 (rdata0x905629c) in state Proceeding
16:28:30.613 tsx0x90671ac State changed from Proceeding to Terminated, event=RX_MSG
16:28:30.613 dlg0x90631b4 Received Response msg 200/INVITE/cseq=14303 (rdata0x905629c)
16:28:30.613 dlg0x90631b4 Route-set updated
16:28:30.613 dlg0x90631b4 Route-set frozen
16:28:30.613 dlg0x90631b4 Transaction tsx0x90671ac state changed to Terminated
16:28:30.613 pjsua_app.c Call 0 state changed to CONNECTING
16:28:30.613 inv0x90631b4 Got SDP answer in Response msg 200/INVITE/cseq=14303 (rdata0x905629c)
16:28:30.613 inv0x90631b4 SDP negotiation done, status=0
16:28:30.613 pjsua_call.c Call 0: remote NAT type is 0 (Unknown)
16:28:30.613 strm0x906a61c VAD temporarily disabled
16:28:30.613 rtp.c pjmedia_rtp_session_init: ses=0x906be80, default_pt=8, ssrc=0x4746d464
16:28:30.613 rtp.c pjmedia_rtp_session_init: ses=0x906c4a4, default_pt=8, ssrc=0x4746d464
16:28:30.613 stream.c Stream strm0x906a61c created
16:28:30.614 strm0x906a61c Encoder stream started
16:28:30.614 strm0x906a61c Decoder stream started
16:28:30.614 resample.c resample created: high qualiy, large filter, in/out rate=8000/16000
16:28:30.614 resample.c resample created: high qualiy, large filter, in/out rate=16000/8000
16:28:30.614 pjsua_media.c Media updates, stream #0: PCMA (sendrecv)
16:28:30.614 conference.c Port 3 (sip:147.83.47.178:5060) transmitting to port 0 (Master/sound)
16:28:30.614 conference.c Port 0 (Master/sound) transmitting to port 3 (sip:147.83.47.178:5060)
16:28:30.614 pjsua_app.c Media for call 0 is active
16:28:30.614 inv0x90631b4 Received Response msg 200/INVITE/cseq=14303 (rdata0x905629c), sending ACK
16:28:30.614 endpoint Request msg ACK/cseq=14303 (tdta0x906e0d8) created.
16:28:30.614 dlg0x90631b4 Sending Request msg ACK/cseq=14303 (tdta0x906e0d8)
16:28:30.614 sip_resolve.c Target '147.83.47.178:5060' type=Unspecified resolved to '147.83.47.178:5060' type=UDP (UDP transport)
16:28:30.614 pjsua_core.c TX 348 bytes Request msg ACK/cseq=14303 (tdta0x906e0d8) to UDP 147.83.47.178:5060:
ACK sip:147.83.47.178:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.0.2:5060;rport;branch=z9hG4bKPjQMqAc.ipG3M84de1cX243-aNJeyXZt-h
Max-Forwards: 70
From: <sip:192.168.0.2>;tag=.oTifxb.Uvlmv8hQYMB6gpEbW-dYji4-
To: sip:147.83.47.178;tag=fU9ZIWypQSTVLoGgeWaUZy37U6d8Cyn9
Call-ID: 6WP79L6pbZ9mo9.bedoUWbragcyqrT8p
CSeq: 14303 ACK
Content-Length: 0
--end msg--
16:28:30.614 pjsua_app.c Call 0 state changed to CONFIRMED
16:28:30.614 tsx0x90671ac Timeout timer event
16:28:30.614 tsx0x90671ac State changed from Terminated to Destroyed, event=TIMER
16:28:30.614 tsx0x90671ac Transaction destroyed!
16:28:30.619 strm0x906a61c Jitter buffer empty (prefetch=0)
16:28:30.619 strm0x906a61c Start talksprut..
16:28:30.993 strm0x906a61c RTP status: badpt=0, badssrc=0, dup=0, outorder=0, probation=-1, restart=0
16:28:31.119 strm0x906a61c jb updated(2), prefetch=4, size=39
16:28:31.159 strm0x906a61c JB shrinking 1 frame(s), cur size=35
16:28:31.242 strm0x906a61c VAD re-enabled
16:28:31.242 strm0x906a61c Starting silence
16:28:31.359 strm0x906a61c JB shrinking 1 frame(s), cur size=34
16:28:31.559 strm0x906a61c JB shrinking 1 frame(s), cur size=31
16:28:31.759 strm0x906a61c JB shrinking 1 frame(s), cur size=34
16:28:31.959 strm0x906a61c JB shrinking 1 frame(s), cur size=31
16:28:32.159 strm0x906a61c JB shrinking 1 frame(s), cur size=30
16:28:32.359 strm0x906a61c JB shrinking 1 frame(s), cur size=27
16:28:32.559 strm0x906a61c JB shrinking 1 frame(s), cur size=30
16:28:32.748 strm0x906a61c jb updated(2), prefetch=6, size=31
16:28:32.759 strm0x906a61c JB shrinking 1 frame(s), cur size=27
16:28:32.959 strm0x906a61c JB shrinking 1 frame(s), cur size=26
16:28:33.159 strm0x906a61c JB shrinking 1 frame(s), cur size=25
16:28:33.359 strm0x906a61c JB shrinking 1 frame(s), cur size=26
16:28:33.559 strm0x906a61c JB shrinking 1 frame(s), cur size=23
16:28:33.759 strm0x906a61c JB shrinking 1 frame(s), cur size=22
16:28:33.959 strm0x906a61c JB shrinking 1 frame(s), cur size=19
16:28:34.159 strm0x906a61c JB shrinking 1 frame(s), cur size=22
16:28:34.359 strm0x906a61c JB shrinking 1 frame(s), cur size=19
16:28:34.559 strm0x906a61c JB shrinking 1 frame(s), cur size=18
16:28:34.759 strm0x906a61c JB shrinking 1 frame(s), cur size=17
16:28:34.961 strm0x906a61c JB shrinking 1 frame(s), cur size=18
16:28:35.159 strm0x906a61c JB shrinking 1 frame(s), cur size=15
16:28:35.219 silencedet.c Re-adjust threshold (in silence)to 0
16:28:35.359 strm0x906a61c JB shrinking 1 frame(s), cur size=14
16:28:35.559 strm0x906a61c JB shrinking 1 frame(s), cur size=13
16:28:35.580 strm0x906a61c Start talksprut..
16:28:35.600 strm0x906a61c Starting silence
16:28:35.759 strm0x906a61c JB shrinking 1 frame(s), cur size=14
16:28:35.973 strm0x906a61c JB shrinking 1 frame(s), cur size=12
16:28:36.165 strm0x906a61c JB shrinking 1 frame(s), cur size=12
16:28:36.390 strm0x906a61c JB shrinking 1 frame(s), cur size=12
16:28:36.613 strm0x906a61c JB shrinking 1 frame(s), cur size=12
16:28:37.159 strm0x906a61c jb updated(1), prefetch=5, size=11
16:28:37.159 strm0x906a61c JB shrinking 1 frame(s), cur size=10
16:28:37.413 strm0x906a61c JB shrinking 1 frame(s), cur size=10
16:28:39.219 silencedet.c Re-adjust threshold (in silence)to 0
16:28:40.359 strm0x906a61c jb updated(1), prefetch=4, size=9
16:28:40.360 strm0x906a61c JB shrinking 1 frame(s), cur size=8
16:28:40.580 strm0x906a61c Start talksprut..
16:28:40.600 strm0x906a61c Starting silence
16:28:40.612 strm0x906a61c JB shrinking 1 frame(s), cur size=8
16:28:41.057 strm0x906a61c JB shrinking 1 frame(s), cur size=8
16:28:41.064 strm0x906a61c jb updated(2), prefetch=6, size=8
16:28:43.220 silencedet.c Re-adjust threshold (in silence)to 0
16:28:44.072 strm0x906a61c jb updated(2), prefetch=8, size=10
16:28:45.580 strm0x906a61c Start talksprut..
16:28:45.600 strm0x906a61c Starting silence
16:28:47.220 silencedet.c Re-adjust threshold (in silence)to 0
16:28:47.339 strm0x906a61c jb updated(1), prefetch=7, size=6
16:28:50.339 strm0x906a61c jb updated(1), prefetch=6, size=6
16:28:50.580 strm0x906a61c Start talksprut..
16:28:50.600 strm0x906a61c Starting silence
16:28:51.220 silencedet.c Re-adjust threshold (in silence)to 0
16:28:55.220 silencedet.c Re-adjust threshold (in silence)to 0
16:28:55.580 strm0x906a61c Start talksprut..
16:28:55.600 strm0x906a61c Starting silence
16:28:57.088 strm0x906a61c jb updated(1), prefetch=5, size=8
16:28:59.219 silencedet.c Re-adjust threshold (in silence)to 0
16:29:00.050 strm0x906a61c JB shrinking 1 frame(s), cur size=10
16:29:00.058 strm0x906a61c jb updated(2), prefetch=8, size=11
16:29:00.580 strm0x906a61c Start talksprut..
16:29:00.600 strm0x906a61c Starting silence
16:29:03.099 strm0x906a61c jb updated(1), prefetch=7, size=7
16:29:03.220 silencedet.c Re-adjust threshold (in silence)to 0
16:29:04.057 strm0x906a61c jb updated(2), prefetch=8, size=11
16:29:04.925 strm0x906a61c Jitter buffer empty (prefetch=8)
16:29:05.580 strm0x906a61c Start talksprut..
16:29:05.600 strm0x906a61c Starting silence
16:29:07.220 silencedet.c Re-adjust threshold (in silence)to 0
16:29:08.035 endpoint Request msg INVITE/cseq=14305 (tdta0x906f0e0) created.
16:29:08.035 inv0x90631b4 Sending Request msg INVITE/cseq=14305 (tdta0x906f0e0)
16:29:08.035 dlg0x90631b4 Sending Request msg INVITE/cseq=14305 (tdta0x906f0e0)
16:29:08.035 tsx0x90710f4 Transaction created for Request msg INVITE/cseq=14304 (tdta0x906f0e0)
16:29:08.035 tsx0x90710f4 Sending Request msg INVITE/cseq=14304 (tdta0x906f0e0) in state Null
16:29:08.035 sip_resolve.c Target '147.83.47.178:5060' type=Unspecified resolved to '147.83.47.178:5060' type=UDP (UDP transport)
16:29:08.035 pjsua_core.c TX 1052 bytes Request msg INVITE/cseq=14304 (tdta0x906f0e0) to UDP 147.83.47.178:5060:
INVITE sip:147.83.47.178:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.0.2:5060;rport;branch=z9hG4bKPjiYYjN76y2Qu.u5IAoqFJlVTnUked47K2
Max-Forwards: 70
From: <sip:192.168.0.2>;tag=.oTifxb.Uvlmv8hQYMB6gpEbW-dYji4-
To: sip:147.83.47.178;tag=fU9ZIWypQSTVLoGgeWaUZy37U6d8Cyn9
Contact: <sip:192.168.0.2:5060>
Call-ID: 6WP79L6pbZ9mo9.bedoUWbragcyqrT8p
CSeq: 14304 INVITE
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, norefersub
User-Agent: PJSUA v1.2/i686-pc-linux-gnu
Content-Type: application/sdp
Content-Length: 456
v=0
o=- 3452596110 3452596111 IN IP4 192.168.0.2
s=pjmedia
c=IN IP4 192.168.0.2
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 8 103 102 113 3 0 104 9 101
a=rtcp:4001 IN IP4 192.168.0.2
a=rtpmap:8 PCMA/8000
a=rtpmap:103 speex/16000
a=rtpmap:102 speex/8000
a=rtpmap:113 iLBC/8000
a=fmtp:113 mode=30
a=rtpmap:3 GSM/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:104 speex/32000
a=rtpmap:9 G722/8000
a=sendrecv
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
--end msg--
16:29:08.036 tsx0x90710f4 State changed from Null to Calling, event=TX_MSG
16:29:08.036 dlg0x90631b4 Transaction tsx0x90710f4 state changed to Calling
16:29:08.050 sip_endpoint.c Processing incoming message: Response msg 200/INVITE/cseq=14304 (rdata0x905629c)
16:29:08.050 pjsua_core.c RX 801 bytes Response msg 200/INVITE/cseq=14304 (rdata0x905629c) from UDP 147.83.47.178:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 147.83.47.177:2590;rport=5060;received=147.83.47.177;branch=z9hG4bKPjiYYjN76y2Qu.u5IAoqFJlVTnUked47K2
Call-ID: 6WP79L6pbZ9mo9.bedoUWbragcyqrT8p
From: <sip:192.168.0.2>;tag=.oTifxb.Uvlmv8hQYMB6gpEbW-dYji4-
To: <sip:147.83.47.178>;tag=fU9ZIWypQSTVLoGgeWaUZy37U6d8Cyn9
CSeq: 14304 INVITE
Contact: <sip:147.83.47.178:5060>
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, norefersub
Content-Type: application/sdp
Content-Length: 254
v=0
o=- 3452596081 3452596083 IN IP4 147.83.47.178
s=pjmedia
c=IN IP4 147.83.47.178
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 8 101
a=rtcp:4001 IN IP4 147.83.47.178
a=rtpmap:8 PCMA/8000
a=sendrecv
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
--end msg--
16:29:08.050 tsx0x90710f4 Incoming Response msg 200/INVITE/cseq=14304 (rdata0x905629c) in state Calling
16:29:08.050 tsx0x90710f4 State changed from Calling to Terminated, event=RX_MSG
16:29:08.050 dlg0x90631b4 Received Response msg 200/INVITE/cseq=14304 (rdata0x905629c)
16:29:08.050 dlg0x90631b4 Transaction tsx0x90710f4 state changed to Terminated
16:29:08.050 inv0x90631b4 Got SDP answer in Response msg 200/INVITE/cseq=14304 (rdata0x905629c)
16:29:08.050 inv0x90631b4 SDP negotiation done, status=0
16:29:08.050 pjsua_call.c Call 0: remote NAT type is 0 (Unknown)
16:29:08.050 strm0x906a61c
JB summary:
size=0 prefetch=8
delay (min/max/avg/dev)=50/400/106/75 ms
burst (min/max/avg/dev)=2/8/3/0 frames
lost=0 discard=34 empty=176
16:29:08.050 pjsua_media.c Media session for call 0 is destroyed
16:29:08.050 strm0x90735bc VAD temporarily disabled
16:29:08.050 rtp.c pjmedia_rtp_session_init: ses=0x9074e20, default_pt=8, ssrc=0x4746d464
16:29:08.050 rtp.c pjmedia_rtp_session_init: ses=0x9075444, default_pt=8, ssrc=0x4746d464
16:29:08.050 stream.c Stream strm0x90735bc created
16:29:08.050 strm0x90735bc Encoder stream started
16:29:08.050 strm0x90735bc Decoder stream started
16:29:08.050 resample.c resample created: high qualiy, large filter, in/out rate=8000/16000
16:29:08.050 resample.c resample created: high qualiy, large filter, in/out rate=16000/8000
16:29:08.050 pjsua_media.c Media updates, stream #0: PCMA (sendrecv)
16:29:08.050 conference.c Port 3 (sip:147.83.47.178:5060) transmitting to port 0 (Master/sound)
16:29:08.050 conference.c Port 0 (Master/sound) transmitting to port 3 (sip:147.83.47.178:5060)
16:29:08.050 pjsua_app.c Media for call 0 is active
16:29:08.050 inv0x90631b4 Received Response msg 200/INVITE/cseq=14304 (rdata0x905629c), sending ACK
16:29:08.050 tdta0x906e0d8 Destroying txdata Request msg ACK/cseq=14303 (tdta0x906e0d8)
16:29:08.050 endpoint Request msg ACK/cseq=14304 (tdta0x906e0d8) created.
16:29:08.050 dlg0x90631b4 Sending Request msg ACK/cseq=14304 (tdta0x906e0d8)
16:29:08.050 sip_resolve.c Target '147.83.47.178:5060' type=Unspecified resolved to '147.83.47.178:5060' type=UDP (UDP transport)
16:29:08.050 pjsua_core.c TX 348 bytes Request msg ACK/cseq=14304 (tdta0x906e0d8) to UDP 147.83.47.178:5060:
ACK sip:147.83.47.178:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.0.2:5060;rport;branch=z9hG4bKPj.D8X1t6AanvSxDNSIHcs6nB-1bHve5Lc
Max-Forwards: 70
From: <sip:192.168.0.2>;tag=.oTifxb.Uvlmv8hQYMB6gpEbW-dYji4-
To: sip:147.83.47.178;tag=fU9ZIWypQSTVLoGgeWaUZy37U6d8Cyn9
Call-ID: 6WP79L6pbZ9mo9.bedoUWbragcyqrT8p
CSeq: 14304 ACK
Content-Length: 0
--end msg--
16:29:08.050 tsx0x90710f4 Timeout timer event
16:29:08.050 tsx0x90710f4 State changed from Terminated to Destroyed, event=TIMER
16:29:08.051 tdta0x906f0e0 Destroying txdata Request msg INVITE/cseq=14304 (tdta0x906f0e0)
16:29:08.051 tsx0x90710f4 Transaction destroyed!
16:29:08.072 strm0x90735bc Jitter buffer empty (prefetch=0)
16:29:08.072 strm0x90735bc Start talksprut..
16:29:08.236 strm0x90735bc RTP status: badpt=0, badssrc=0, dup=0, outorder=0, probation=-1, restart=0
16:29:08.260 strm0x90735bc Jitter buffer empty (prefetch=0)
16:29:08.379 strm0x90735bc jb updated(2), prefetch=4, size=3
16:29:08.660 strm0x90735bc VAD re-enabled
16:29:08.661 strm0x90735bc Starting silence
16:29:10.071 strm0x90735bc jb updated(2), prefetch=6, size=5
16:29:10.580 strm0x90735bc Start talksprut..
16:29:10.600 strm0x90735bc Starting silence
16:29:11.840 silencedet.c Re-adjust threshold (in silence)to 0
16:29:13.299 strm0x90735bc jb updated(1), prefetch=5, size=3
16:29:15.057 strm0x90735bc jb updated(2), prefetch=6, size=7
16:29:15.580 strm0x90735bc Start talksprut..
16:29:15.600 strm0x90735bc Starting silence
16:29:15.840 silencedet.c Re-adjust threshold (in silence)to 0
16:29:16.050 strm0x90735bc jb updated(2), prefetch=8, size=7
16:29:19.279 strm0x90735bc jb updated(1), prefetch=7, size=3
16:29:19.319 strm0x90735bc Jitter buffer empty (prefetch=7)
16:29:19.840 silencedet.c Re-adjust threshold (in silence)to 0
16:29:20.039 strm0x90735bc jb updated(2), prefetch=8, size=9
16:29:20.580 strm0x90735bc Start talksprut..
16:29:20.601 strm0x90735bc Starting silence
16:29:23.179 strm0x90735bc jb updated(1), prefetch=7, size=5
16:29:23.840 silencedet.c Re-adjust threshold (in silence)to 0
16:29:25.580 strm0x90735bc Start talksprut..
16:29:25.600 strm0x90735bc Starting silence
16:29:26.219 strm0x90735bc jb updated(1), prefetch=6, size=5
16:29:27.841 silencedet.c Re-adjust threshold (in silence)to 0
16:29:30.581 strm0x90735bc Start talksprut..
16:29:30.600 strm0x90735bc Starting silence
16:29:31.841 silencedet.c Re-adjust threshold (in silence)to 0
16:29:35.083 strm0x90735bc jb updated(2), prefetch=8, size=11
16:29:35.580 strm0x90735bc Start talksprut..
16:29:35.600 strm0x90735bc Starting silence
16:29:35.840 silencedet.c Re-adjust threshold (in silence)to 0
16:29:38.340 strm0x90735bc jb updated(1), prefetch=7, size=7
16:29:38.917 strm0x90735bc jb updated(2), prefetch=8, size=11
16:29:39.840 silencedet.c Re-adjust threshold (in silence)to 0
16:29:40.580 strm0x90735bc Start talksprut..
16:29:40.600 strm0x90735bc Starting silence
16:29:41.879 strm0x90735bc jb updated(1), prefetch=7, size=7
16:29:43.840 silencedet.c Re-adjust threshold (in silence)to 0
16:29:45.199 strm0x90735bc jb updated(1), prefetch=6, size=7
16:29:45.580 strm0x90735bc Start talksprut..
16:29:45.600 strm0x90735bc Starting silence
16:29:47.840 silencedet.c Re-adjust threshold (in silence)to 0
16:29:48.703 strm0x90735bc Jitter buffer empty (prefetch=6)
16:29:50.600 strm0x90735bc Start talksprut..
16:29:50.601 strm0x90735bc Starting silence
16:29:51.701 strm0x90735bc RTT value 18417 usec is normalized to 2523 usec
16:29:51.753 endpoint Request msg INVITE/cseq=14306 (tdta0x906f0e0) created.
16:29:51.753 inv0x90631b4 Sending Request msg INVITE/cseq=14306 (tdta0x906f0e0)
16:29:51.753 dlg0x90631b4 Sending Request msg INVITE/cseq=14306 (tdta0x906f0e0)
16:29:51.753 tsx0x90710f4 Transaction created for Request msg INVITE/cseq=14305 (tdta0x906f0e0)
16:29:51.753 tsx0x90710f4 Sending Request msg INVITE/cseq=14305 (tdta0x906f0e0) in state Null
16:29:51.753 sip_resolve.c Target '147.83.47.178:5060' type=Unspecified resolved to '147.83.47.178:5060' type=UDP (UDP transport)
16:29:51.753 pjsua_core.c TX 1052 bytes Request msg INVITE/cseq=14305 (tdta0x906f0e0) to UDP 147.83.47.178:5060:
INVITE sip:147.83.47.178:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.0.2:5060;rport;branch=z9hG4bKPjgCNAixQRjiHueBLLitZqtcx1m1ZqFqLe
Max-Forwards: 70
From: <sip:192.168.0.2>;tag=.oTifxb.Uvlmv8hQYMB6gpEbW-dYji4-
To: sip:147.83.47.178;tag=fU9ZIWypQSTVLoGgeWaUZy37U6d8Cyn9
Contact: <sip:192.168.0.2:5060>
Call-ID: 6WP79L6pbZ9mo9.bedoUWbragcyqrT8p
CSeq: 14305 INVITE
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, norefersub
User-Agent: PJSUA v1.2/i686-pc-linux-gnu
Content-Type: application/sdp
Content-Length: 456
v=0
o=- 3452596110 3452596112 IN IP4 192.168.0.2
s=pjmedia
c=IN IP4 192.168.0.2
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 8 103 102 113 3 0 104 9 101
a=rtcp:4001 IN IP4 192.168.0.2
a=rtpmap:8 PCMA/8000
a=rtpmap:103 speex/16000
a=rtpmap:102 speex/8000
a=rtpmap:113 iLBC/8000
a=fmtp:113 mode=30
a=rtpmap:3 GSM/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:104 speex/32000
a=rtpmap:9 G722/8000
a=sendrecv
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
--end msg--
16:29:51.754 tsx0x90710f4 State changed from Null to Calling, event=TX_MSG
16:29:51.754 dlg0x90631b4 Transaction tsx0x90710f4 state changed to Calling
16:29:51.762 sip_endpoint.c Processing incoming message: Response msg 200/INVITE/cseq=14305 (rdata0x905629c)
16:29:51.762 pjsua_core.c RX 799 bytes Response msg 200/INVITE/cseq=14305 (rdata0x905629c) from UDP 147.83.47.178:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.0.2:5060;rport=5060;received=147.83.47.176;branch=z9hG4bKPjgCNAixQRjiHueBLLitZqtcx1m1ZqFqLe
Call-ID: 6WP79L6pbZ9mo9.bedoUWbragcyqrT8p
From: <sip:192.168.0.2>;tag=.oTifxb.Uvlmv8hQYMB6gpEbW-dYji4-
To: <sip:147.83.47.178>;tag=fU9ZIWypQSTVLoGgeWaUZy37U6d8Cyn9
CSeq: 14305 INVITE
Contact: <sip:147.83.47.178:5060>
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, norefersub
Content-Type: application/sdp
Content-Length: 254
v=0
o=- 3452596081 3452596084 IN IP4 147.83.47.178
s=pjmedia
c=IN IP4 147.83.47.178
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 8 101
a=rtcp:4001 IN IP4 147.83.47.178
a=rtpmap:8 PCMA/8000
a=sendrecv
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
--end msg--
16:29:51.762 tsx0x90710f4 Incoming Response msg 200/INVITE/cseq=14305 (rdata0x905629c) in state Calling
16:29:51.762 tsx0x90710f4 State changed from Calling to Terminated, event=RX_MSG
16:29:51.762 dlg0x90631b4 Received Response msg 200/INVITE/cseq=14305 (rdata0x905629c)
16:29:51.762 dlg0x90631b4 Transaction tsx0x90710f4 state changed to Terminated
16:29:51.762 inv0x90631b4 Got SDP answer in Response msg 200/INVITE/cseq=14305 (rdata0x905629c)
16:29:51.762 inv0x90631b4 SDP negotiation done, status=0
16:29:51.762 pjsua_call.c Call 0: remote NAT type is 0 (Unknown)
16:29:51.762 strm0x90735bc
JB summary:
size=0 prefetch=6
delay (min/max/avg/dev)=20/120/67/18 ms
burst (min/max/avg/dev)=2/8/3/0 frames
lost=0 discard=0 empty=167
16:29:51.762 pjsua_media.c Media session for call 0 is destroyed
16:29:51.762 strm0x90737c4 VAD temporarily disabled
16:29:51.762 rtp.c pjmedia_rtp_session_init: ses=0x9075028, default_pt=8, ssrc=0x4746d464
16:29:51.762 rtp.c pjmedia_rtp_session_init: ses=0x907564c, default_pt=8, ssrc=0x4746d464
16:29:51.764 stream.c Stream strm0x90737c4 created
16:29:51.764 strm0x90737c4 Encoder stream started
16:29:51.764 strm0x90737c4 Decoder stream started
16:29:51.764 resample.c resample created: high qualiy, large filter, in/out rate=8000/16000
16:29:51.764 resample.c resample created: high qualiy, large filter, in/out rate=16000/8000
16:29:51.764 pjsua_media.c Media updates, stream #0: PCMA (sendrecv)
16:29:51.764 conference.c Port 3 (sip:147.83.47.178:5060) transmitting to port 0 (Master/sound)
16:29:51.764 conference.c Port 0 (Master/sound) transmitting to port 3 (sip:147.83.47.178:5060)
16:29:51.764 pjsua_app.c Media for call 0 is active
16:29:51.764 inv0x90631b4 Received Response msg 200/INVITE/cseq=14305 (rdata0x905629c), sending ACK
16:29:51.764 tdta0x906e0d8 Destroying txdata Request msg ACK/cseq=14304 (tdta0x906e0d8)
16:29:51.764 endpoint Request msg ACK/cseq=14305 (tdta0x906e0d8) created.
16:29:51.764 dlg0x90631b4 Sending Request msg ACK/cseq=14305 (tdta0x906e0d8)
16:29:51.764 sip_resolve.c Target '147.83.47.178:5060' type=Unspecified resolved to '147.83.47.178:5060' type=UDP (UDP transport)
16:29:51.764 pjsua_core.c TX 348 bytes Request msg ACK/cseq=14305 (tdta0x906e0d8) to UDP 147.83.47.178:5060:
ACK sip:147.83.47.178:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.0.2:5060;rport;branch=z9hG4bKPjrlrTrthB9A5MQiqrKEp5Iyhfa6hNR-rh
Max-Forwards: 70
From: <sip:192.168.0.2>;tag=.oTifxb.Uvlmv8hQYMB6gpEbW-dYji4-
To: sip:147.83.47.178;tag=fU9ZIWypQSTVLoGgeWaUZy37U6d8Cyn9
Call-ID: 6WP79L6pbZ9mo9.bedoUWbragcyqrT8p
CSeq: 14305 ACK
Content-Length: 0
--end msg--
16:29:51.764 tsx0x90710f4 Timeout timer event
16:29:51.764 tsx0x90710f4 State changed from Terminated to Destroyed, event=TIMER
16:29:51.764 tdta0x906f0e0 Destroying txdata Request msg INVITE/cseq=14305 (tdta0x906f0e0)
16:29:51.764 tsx0x90710f4 Transaction destroyed!
16:29:51.798 strm0x90737c4 RTP status: badpt=0, badssrc=0, dup=0, outorder=0, probation=-1, restart=0
16:29:51.798 strm0x90737c4 Start talksprut..
16:29:51.805 strm0x90737c4 Jitter buffer empty (prefetch=0)
16:29:51.919 strm0x90737c4 jb updated(2), prefetch=4, size=5
16:29:52.399 strm0x90737c4 VAD re-enabled
16:29:52.399 strm0x90737c4 Starting silence
16:29:52.479 silencedet.c Re-adjust threshold (in silence)to 0
16:29:52.845 strm0x90737c4 jb updated(2), prefetch=6, size=7
16:29:55.580 strm0x90737c4 Start talksprut..
16:29:55.599 strm0x90737c4 Starting silence
16:29:56.460 silencedet.c Re-adjust threshold (in silence)to 0
16:30:00.460 silencedet.c Re-adjust threshold (in silence)to 0
16:30:00.581 strm0x90737c4 Start talksprut..
16:30:00.601 strm0x90737c4 Starting silence
16:30:03.279 strm0x90737c4 jb updated(1), prefetch=5, size=5
16:30:04.037 strm0x90737c4 jb updated(2), prefetch=6, size=7
16:30:04.460 silencedet.c Re-adjust threshold (in silence)to 0
16:30:05.580 strm0x90737c4 Start talksprut..
16:30:05.600 strm0x90737c4 Starting silence
16:30:08.461 silencedet.c Re-adjust threshold (in silence)to 0
16:30:09.959 strm0x90737c4 jb updated(1), prefetch=5, size=5
16:30:10.052 strm0x90737c4 jb updated(2), prefetch=6, size=9
16:30:10.580 strm0x90737c4 Start talksprut..
16:30:10.600 strm0x90737c4 Starting silence
16:30:11.046 strm0x90737c4 jb updated(2), prefetch=8, size=9
16:30:12.460 silencedet.c Re-adjust threshold (in silence)to 0
16:30:14.060 strm0x90737c4 jb updated(1), prefetch=7, size=9
16:30:15.580 strm0x90737c4 Start talksprut..
16:30:15.600 strm0x90737c4 Starting silence
16:30:16.460 silencedet.c Re-adjust threshold (in silence)to 0
16:30:17.119 strm0x90737c4 jb updated(1), prefetch=6, size=7
16:30:18.161 strm0x90737c4 Jitter buffer empty (prefetch=6)
16:30:20.460 silencedet.c Re-adjust threshold (in silence)to 0
16:30:20.580 strm0x90737c4 Start talksprut..
16:30:20.600 strm0x90737c4 Starting silence
16:30:21.158 endpoint Request msg INVITE/cseq=14307 (tdta0x9070310) created.
16:30:21.158 inv0x90631b4 Sending Request msg INVITE/cseq=14307 (tdta0x9070310)
16:30:21.158 dlg0x90631b4 Sending Request msg INVITE/cseq=14307 (tdta0x9070310)
16:30:21.158 tsx0x90772e4 Transaction created for Request msg INVITE/cseq=14306 (tdta0x9070310)
16:30:21.158 tsx0x90772e4 Sending Request msg INVITE/cseq=14306 (tdta0x9070310) in state Null
16:30:21.158 sip_resolve.c Target '147.83.47.178:5060' type=Unspecified resolved to '147.83.47.178:5060' type=UDP (UDP transport)
16:30:21.158 pjsua_core.c TX 1052 bytes Request msg INVITE/cseq=14306 (tdta0x9070310) to UDP 147.83.47.178:5060:
INVITE sip:147.83.47.178:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.0.2:5060;rport;branch=z9hG4bKPjk84hEwSddLUNN8x4m2LnsnerWvVannnf
Max-Forwards: 70
From: <sip:192.168.0.2>;tag=.oTifxb.Uvlmv8hQYMB6gpEbW-dYji4-
To: sip:147.83.47.178;tag=fU9ZIWypQSTVLoGgeWaUZy37U6d8Cyn9
Contact: <sip:192.168.0.2:5060>
Call-ID: 6WP79L6pbZ9mo9.bedoUWbragcyqrT8p
CSeq: 14306 INVITE
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, norefersub
User-Agent: PJSUA v1.2/i686-pc-linux-gnu
Content-Type: application/sdp
Content-Length: 456
v=0
o=- 3452596110 3452596113 IN IP4 192.168.0.2
s=pjmedia
c=IN IP4 192.168.0.2
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 8 103 102 113 3 0 104 9 101
a=rtcp:4001 IN IP4 192.168.0.2
a=rtpmap:8 PCMA/8000
a=rtpmap:103 speex/16000
a=rtpmap:102 speex/8000
a=rtpmap:113 iLBC/8000
a=fmtp:113 mode=30
a=rtpmap:3 GSM/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:104 speex/32000
a=rtpmap:9 G722/8000
a=sendrecv
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
--end msg--
16:30:21.158 tsx0x90772e4 State changed from Null to Calling, event=TX_MSG
16:30:21.158 dlg0x90631b4 Transaction tsx0x90772e4 state changed to Calling
16:30:21.168 sip_endpoint.c Processing incoming message: Response msg 200/INVITE/cseq=14306 (rdata0x905629c)
16:30:21.168 pjsua_core.c RX 801 bytes Response msg 200/INVITE/cseq=14306 (rdata0x905629c) from UDP 147.83.47.178:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 147.83.47.177:2590;rport=5060;received=147.83.47.177;branch=z9hG4bKPjk84hEwSddLUNN8x4m2LnsnerWvVannnf
Call-ID: 6WP79L6pbZ9mo9.bedoUWbragcyqrT8p
From: <sip:192.168.0.2>;tag=.oTifxb.Uvlmv8hQYMB6gpEbW-dYji4-
To: <sip:147.83.47.178>;tag=fU9ZIWypQSTVLoGgeWaUZy37U6d8Cyn9
CSeq: 14306 INVITE
Contact: <sip:147.83.47.178:5060>
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, norefersub
Content-Type: application/sdp
Content-Length: 254
v=0
o=- 3452596081 3452596085 IN IP4 147.83.47.178
s=pjmedia
c=IN IP4 147.83.47.178
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 8 101
a=rtcp:4001 IN IP4 147.83.47.178
a=rtpmap:8 PCMA/8000
a=sendrecv
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
--end msg--
16:30:21.168 tsx0x90772e4 Incoming Response msg 200/INVITE/cseq=14306 (rdata0x905629c) in state Calling
16:30:21.168 tsx0x90772e4 State changed from Calling to Terminated, event=RX_MSG
16:30:21.168 dlg0x90631b4 Received Response msg 200/INVITE/cseq=14306 (rdata0x905629c)
16:30:21.168 dlg0x90631b4 Transaction tsx0x90772e4 state changed to Terminated
16:30:21.168 inv0x90631b4 Got SDP answer in Response msg 200/INVITE/cseq=14306 (rdata0x905629c)
16:30:21.168 inv0x90631b4 SDP negotiation done, status=0
16:30:21.168 pjsua_call.c Call 0: remote NAT type is 0 (Unknown)
16:30:21.169 strm0x90737c4
JB summary:
size=0 prefetch=6
delay (min/max/avg/dev)=20/100/61/9 ms
burst (min/max/avg/dev)=2/8/3/0 frames
lost=0 discard=0 empty=152
16:30:21.169 pjsua_media.c Media session for call 0 is destroyed
16:30:21.169 strm0x90737c4 VAD temporarily disabled
16:30:21.169 rtp.c pjmedia_rtp_session_init: ses=0x9075028, default_pt=8, ssrc=0x4746d464
16:30:21.169 rtp.c pjmedia_rtp_session_init: ses=0x907564c, default_pt=8, ssrc=0x4746d464
16:30:21.169 stream.c Stream strm0x90737c4 created
16:30:21.169 strm0x90737c4 Encoder stream started
16:30:21.169 strm0x90737c4 Decoder stream started
16:30:21.169 resample.c resample created: high qualiy, large filter, in/out rate=8000/16000
16:30:21.169 resample.c resample created: high qualiy, large filter, in/out rate=16000/8000
16:30:21.169 pjsua_media.c Media updates, stream #0: PCMA (sendrecv)
16:30:21.169 conference.c Port 3 (sip:147.83.47.178:5060) transmitting to port 0 (Master/sound)
16:30:21.169 conference.c Port 0 (Master/sound) transmitting to port 3 (sip:147.83.47.178:5060)
16:30:21.169 pjsua_app.c Media for call 0 is active
16:30:21.169 inv0x90631b4 Received Response msg 200/INVITE/cseq=14306 (rdata0x905629c), sending ACK
16:30:21.169 tdta0x906e0d8 Destroying txdata Request msg ACK/cseq=14305 (tdta0x906e0d8)
16:30:21.169 endpoint Request msg ACK/cseq=14306 (tdta0x906e0d8) created.
16:30:21.169 dlg0x90631b4 Sending Request msg ACK/cseq=14306 (tdta0x906e0d8)
16:30:21.169 sip_resolve.c Target '147.83.47.178:5060' type=Unspecified resolved to '147.83.47.178:5060' type=UDP (UDP transport)
16:30:21.169 pjsua_core.c TX 348 bytes Request msg ACK/cseq=14306 (tdta0x906e0d8) to UDP 147.83.47.178:5060:
ACK sip:147.83.47.178:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.0.2:5060;rport;branch=z9hG4bKPjPi0wighQDhczZUajT-KGx04qgAsapcYl
Max-Forwards: 70
From: <sip:192.168.0.2>;tag=.oTifxb.Uvlmv8hQYMB6gpEbW-dYji4-
To: sip:147.83.47.178;tag=fU9ZIWypQSTVLoGgeWaUZy37U6d8Cyn9
Call-ID: 6WP79L6pbZ9mo9.bedoUWbragcyqrT8p
CSeq: 14306 ACK
Content-Length: 0
--end msg--
16:30:21.169 tsx0x90772e4 Timeout timer event
16:30:21.169 tsx0x90772e4 State changed from Terminated to Destroyed, event=TIMER
16:30:21.169 tdta0x9070310 Destroying txdata Request msg INVITE/cseq=14306 (tdta0x9070310)
16:30:21.169 tsx0x90772e4 Transaction destroyed!
16:30:21.196 strm0x90737c4 Jitter buffer empty (prefetch=0)
16:30:21.196 strm0x90737c4 Start talksprut..
16:30:21.363 strm0x90737c4 RTP status: badpt=0, badssrc=0, dup=0, outorder=0, probation=-1, restart=0
16:30:21.499 strm0x90737c4 jb updated(2), prefetch=4, size=3
16:30:21.780 strm0x90737c4 VAD re-enabled
16:30:21.780 strm0x90737c4 Starting silence
16:30:25.050 strm0x90737c4 jb updated(2), prefetch=6, size=5
16:30:25.080 silencedet.c Re-adjust threshold (in silence)to 0
16:30:25.580 strm0x90737c4 Start talksprut..
16:30:25.600 strm0x90737c4 Starting silence
16:30:25.719 strm0x90737c4 Jitter buffer empty (prefetch=6)
16:30:29.080 silencedet.c Re-adjust threshold (in silence)to 0
16:30:30.071 strm0x90737c4 jb updated(2), prefetch=8, size=9
16:30:30.580 strm0x90737c4 Start talksprut..
16:30:30.601 strm0x90737c4 Starting silence
16:30:33.079 silencedet.c Re-adjust threshold (in silence)to 0
16:30:33.119 strm0x90737c4 Jitter buffer empty (prefetch=8)
16:30:35.580 strm0x90737c4 Start talksprut..
16:30:35.600 strm0x90737c4 Starting silence
16:30:35.680 strm0x90737c4 Jitter buffer empty (prefetch=8)
16:30:35.699 strm0x90737c4 jb updated(2), prefetch=10, size=9
16:30:37.079 silencedet.c Re-adjust threshold (in silence)to 0
16:30:38.839 strm0x90737c4 jb updated(1), prefetch=9, size=9
16:30:40.460 endpoint Request msg BYE/cseq=14308 (tdta0x9077280) created.
16:30:40.460 inv0x90631b4 Sending Request msg BYE/cseq=14308 (tdta0x9077280)
16:30:40.460 dlg0x90631b4 Sending Request msg BYE/cseq=14308 (tdta0x9077280)
16:30:40.460 tsx0x9053f2c Transaction created for Request msg BYE/cseq=14307 (tdta0x9077280)
16:30:40.460 tsx0x9053f2c Sending Request msg BYE/cseq=14307 (tdta0x9077280) in state Null
16:30:40.460 sip_resolve.c Target '147.83.47.178:5060' type=Unspecified resolved to '147.83.47.178:5060' type=UDP (UDP transport)
16:30:40.460 pjsua_core.c TX 390 bytes Request msg BYE/cseq=14307 (tdta0x9077280) to UDP 147.83.47.178:5060:
BYE sip:147.83.47.178:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.0.2:5060;rport;branch=z9hG4bKPjd5XMx2zACil-e-4NT04QE5b7CZCMEOxG
Max-Forwards: 70
From: <sip:192.168.0.2>;tag=.oTifxb.Uvlmv8hQYMB6gpEbW-dYji4-
To: sip:147.83.47.178;tag=fU9ZIWypQSTVLoGgeWaUZy37U6d8Cyn9
Call-ID: 6WP79L6pbZ9mo9.bedoUWbragcyqrT8p
CSeq: 14307 BYE
User-Agent: PJSUA v1.2/i686-pc-linux-gnu
Content-Length: 0
--end msg--
16:30:40.460 tsx0x9053f2c State changed from Null to Calling, event=TX_MSG
16:30:40.460 dlg0x90631b4 Transaction tsx0x9053f2c state changed to Calling
16:30:40.460 pjsua_media.c Closing null sound device..
16:30:40.461 strm0x90737c4
JB summary:
size=10 prefetch=9
delay (min/max/avg/dev)=20/140/69/26 ms
burst (min/max/avg/dev)=2/10/3/0 frames
lost=0 discard=0 empty=14
16:30:40.461 pjsua_media.c Media session for call 0 is destroyed
16:30:40.952 pa_dev.c PortAudio sound library shutting down..
16:30:40.952 pjsua_core.c Shutting down...
16:30:40.952 sip_endpoint.c Processing incoming message: Response msg 200/BYE/cseq=14307 (rdata0x905629c)
16:30:40.952 pjsua_core.c RX 342 bytes Response msg 200/BYE/cseq=14307 (rdata0x905629c) from UDP 147.83.47.178:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 147.83.47.177:2590;rport=5060;received=147.83.47.177;branch=z9hG4bKPjd5XMx2zACil-e-4NT04QE5b7CZCMEOxG
Call-ID: 6WP79L6pbZ9mo9.bedoUWbragcyqrT8p
From: <sip:192.168.0.2>;tag=.oTifxb.Uvlmv8hQYMB6gpEbW-dYji4-
To: <sip:147.83.47.178>;tag=fU9ZIWypQSTVLoGgeWaUZy37U6d8Cyn9
CSeq: 14307 BYE
Content-Length: 0
--end msg--
16:30:40.952 tsx0x9053f2c Incoming Response msg 200/BYE/cseq=14307 (rdata0x905629c) in state Calling
16:30:40.952 tsx0x9053f2c State changed from Calling to Completed, event=RX_MSG
16:30:40.952 dlg0x90631b4 Received Response msg 200/BYE/cseq=14307 (rdata0x905629c)
16:30:40.952 dlg0x90631b4 Transaction tsx0x9053f2c state changed to Completed
16:30:40.952 pjsua_app.c Call 0 is DISCONNECTED [reason=200 (Normal call clearing)]
16:30:40.952 pjsua_app.c Call 0 disconnected, dumping media stats..
16:30:40.952 pjsua_app.c
[DISCONNCTD] To: sip:147.83.47.178;tag=fU9ZIWypQSTVLoGgeWaUZy37U6d8Cyn9
Call time: 00h:02m:10s, 1st res in 53 ms, conn in 54ms
16:30:40.952 tdta0x906e0d8 Destroying txdata Request msg ACK/cseq=14306 (tdta0x906e0d8)
16:30:40.952 tdta0x9066140 Destroying txdata Request msg INVITE/cseq=14303 (tdta0x9066140)
16:30:40.952 dlg0x90631b4 Session count dec to 1 by mod-invite
16:30:41.960 pjsua_core.c Destroying...
16:30:41.960 sip_endpoint.c Destroying endpoing instance..
16:30:41.960 sip_transactio Stopping transaction layer module
16:30:41.960 tsx0x9053f2c Request to terminate transaction
16:30:41.960 tsx0x9053f2c State changed from Completed to Terminated, event=USER
16:30:41.960 dlg0x90631b4 Transaction tsx0x9053f2c state changed to Terminated
16:30:41.960 dlg0x90631b4 Dialog destroyed
16:30:41.960 tdta0x9077280 Destroying txdata Request msg BYE/cseq=14307 (tdta0x9077280)
16:30:41.960 tsx0x9053f2c Transaction destroyed!
16:30:41.960 sip_endpoint.c Module "mod-default-handler" unregistered
16:30:41.960 sip_endpoint.c Module "mod-pjsua-options" unregistered
16:30:41.960 sip_endpoint.c Module "mod-pjsua-im" unregistered
16:30:41.960 sip_endpoint.c Module "mod-pjsua-pres" unregistered
16:30:41.960 sip_endpoint.c Module "mod-pjsua" unregistered
16:30:41.960 sip_endpoint.c Module "mod-stateful-util" unregistered
16:30:41.960 sip_endpoint.c Module "mod-refer" unregistered
16:30:41.960 sip_endpoint.c Module "mod-presence" unregistered
16:30:41.960 sip_endpoint.c Module "mod-evsub" unregistered
16:30:41.960 sip_endpoint.c Module "mod-invite" unregistered
16:30:41.960 sip_endpoint.c Module "mod-100rel" unregistered
16:30:41.960 sip_endpoint.c Module "mod-ua" unregistered
16:30:41.960 sip_transactio Transaction layer module destroyed
16:30:41.960 sip_endpoint.c Module "mod-tsx-layer" unregistered
16:30:41.960 sip_endpoint.c Module "mod-msg-print" unregistered
16:30:41.960 sip_endpoint.c Module "mod-pjsua-log" unregistered
16:30:41.960 sip_transport. Destroying transport manager
16:30:41.964 tcplis:5060 SIP TCP listener destroyed
16:30:41.964 sip_endpoint.c Endpoint 0x903717c destroyed
16:30:41.964 pjsua_core.c PJSUA destroyed...
-------------- next part --------------
16:27:53.260 sip_endpoint.c Module "mod-pjsua-log" registered
16:27:53.260 sip_endpoint.c Module "mod-tsx-layer" registered
16:27:53.260 sip_endpoint.c Module "mod-stateful-util" registered
16:27:53.260 sip_endpoint.c Module "mod-ua" registered
16:27:53.260 sip_endpoint.c Module "mod-100rel" registered
16:27:53.260 sip_endpoint.c Module "mod-pjsua" registered
16:27:53.260 sip_endpoint.c Module "mod-invite" registered
16:27:53.268 pa_dev.c PortAudio sound library initialized, status=0
16:27:53.268 pa_dev.c PortAudio host api count=1
16:27:53.268 pa_dev.c Sound device count=1
16:27:53.268 pjlib select() I/O Queue created (0x85b3094)
16:27:53.276 conference.c Creating conference bridge with 254 ports
16:27:53.276 conference.c Sound device successfully created for port 0
16:27:53.340 sip_endpoint.c Module "mod-evsub" registered
16:27:53.340 sip_endpoint.c Module "mod-presence" registered
16:27:53.340 evsub.c Event pkg "presence" registered by mod-presence
16:27:53.340 sip_endpoint.c Module "mod-refer" registered
16:27:53.340 evsub.c Event pkg "refer" registered by mod-refer
16:27:53.340 sip_endpoint.c Module "mod-pjsua-pres" registered
16:27:53.340 sip_endpoint.c Module "mod-pjsua-im" registered
16:27:53.340 sip_endpoint.c Module "mod-pjsua-options" registered
16:27:53.350 pjsua_core.c 1 SIP worker threads created
16:27:53.351 pjsua_core.c pjsua version 1.2 for i686-pc-linux-gnu initialized
16:27:53.351 sip_endpoint.c Module "mod-default-handler" registered
16:27:53.351 wav_player.c File player '/home/dulceangustia/Desktop/file2.wav' created: samp.rate=8000, ch=1, bufsize=4KB, filesize=2919KB
16:27:53.351 resample.c resample created: high qualiy, large filter, in/out rate=8000/16000
16:27:53.351 resample.c resample created: high qualiy, large filter, in/out rate=16000/8000
16:27:53.351 pjsua_core.c SIP UDP socket reachable at 147.83.47.178:5060
16:27:53.351 udp0x85c5ac0 SIP UDP transport started, published address is 147.83.47.178:5060
16:27:53.351 pjsua_acc.c Account <sip:147.83.47.178:5060> added with id 0
16:27:53.352 pjsua_media.c RTP socket reachable at 147.83.47.178:4000
16:27:53.352 pjsua_media.c RTCP socket reachable at 147.83.47.178:4001
16:27:53.352 pjsua_media.c RTP socket reachable at 147.83.47.178:4002
16:27:53.352 pjsua_media.c RTCP socket reachable at 147.83.47.178:4003
16:27:53.352 pjsua_media.c RTP socket reachable at 147.83.47.178:4004
16:27:53.352 pjsua_media.c RTCP socket reachable at 147.83.47.178:4005
16:27:53.353 pjsua_media.c RTP socket reachable at 147.83.47.178:4006
16:27:53.353 pjsua_media.c RTCP socket reachable at 147.83.47.178:4007
16:28:01.542 sip_endpoint.c Processing incoming message: Request msg INVITE/cseq=14303 (rdata0x85c5f34)
16:28:01.542 pjsua_core.c RX 1023 bytes Request msg INVITE/cseq=14303 (rdata0x85c5f34) from UDP 147.83.47.176:5060:
INVITE sip:147.83.47.178:5060 SIP/2.0
Via: SIP/2.0/UDP 147.83.47.176:5060;rport;branch=z9hG4bKPjcmxtzJs4rxf8qpy-qTZvTzYricwYUAL6
Max-Forwards: 70
From: <sip:192.168.0.2>;tag=.oTifxb.Uvlmv8hQYMB6gpEbW-dYji4-
To: sip:147.83.47.178
Contact: <sip:147.83.47.176:5060>
Call-ID: 6WP79L6pbZ9mo9.bedoUWbragcyqrT8p
CSeq: 14303 INVITE
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, norefersub
User-Agent: PJSUA v1.2/i686-pc-linux-gnu
Content-Type: application/sdp
Content-Length: 460
v=0
o=- 3452596110 3452596110 IN IP4 147.83.47.176
s=pjmedia
c=IN IP4 147.83.47.176
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 8 103 102 113 3 0 104 9 101
a=rtcp:4001 IN IP4 192.168.0.2
a=rtpmap:8 PCMA/8000
a=rtpmap:103 speex/16000
a=rtpmap:102 speex/8000
a=rtpmap:113 iLBC/8000
a=fmtp:113 mode=30
a=rtpmap:3 GSM/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:104 speex/32000
a=rtpmap:9 G722/8000
a=sendrecv
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
--end msg--
16:28:01.542 pjsua_media.c Media index 0 selected for call 0
16:28:01.542 tsx0x85d5c34 Transaction created for Request msg INVITE/cseq=14303 (rdata0x85c5f34)
16:28:01.543 tsx0x85d5c34 Incoming Request msg INVITE/cseq=14303 (rdata0x85c5f34) in state Null
16:28:01.543 tsx0x85d5c34 State changed from Null to Trying, event=RX_MSG
16:28:01.543 dlg0x85d542c Transaction tsx0x85d5c34 state changed to Trying
16:28:01.543 dlg0x85d542c UAS dialog created
16:28:01.543 dlg0x85d542c Module mod-invite added as dialog usage, data=0x85d5ac0
16:28:01.543 dlg0x85d542c Session count inc to 2 by mod-invite
16:28:01.543 inv0x85d542c UAS invite session created for dialog dlg0x85d542c
16:28:01.543 pjsua_call.c Call 0: remote NAT type is 0 (Unknown)
16:28:01.543 endpoint Response msg 100/INVITE/cseq=14303 (tdta0x85d7e20) created
16:28:01.543 dlg0x85d542c Initial answer Response msg 100/INVITE/cseq=14303 (tdta0x85d7e20)
16:28:01.543 inv0x85d542c Sending Response msg 100/INVITE/cseq=14303 (tdta0x85d7e20)
16:28:01.543 dlg0x85d542c Sending Response msg 100/INVITE/cseq=14303 (tdta0x85d7e20)
16:28:01.543 tsx0x85d5c34 Sending Response msg 100/INVITE/cseq=14303 (tdta0x85d7e20) in state Trying
16:28:01.543 pjsua_core.c TX 312 bytes Response msg 100/INVITE/cseq=14303 (tdta0x85d7e20) to UDP 147.83.47.176:5060:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 147.83.47.176:5060;rport=5060;received=147.83.47.176;branch=z9hG4bKPjcmxtzJs4rxf8qpy-qTZvTzYricwYUAL6
Call-ID: 6WP79L6pbZ9mo9.bedoUWbragcyqrT8p
From: <sip:192.168.0.2>;tag=.oTifxb.Uvlmv8hQYMB6gpEbW-dYji4-
To: <sip:147.83.47.178>
CSeq: 14303 INVITE
Content-Length: 0
--end msg--
16:28:01.543 tsx0x85d5c34 State changed from Trying to Proceeding, event=TX_MSG
16:28:01.543 dlg0x85d542c Transaction tsx0x85d5c34 state changed to Proceeding
16:28:01.543 pjsua_media.c Opening sound device PCM at 16000/1/20ms
16:28:01.544 pa_dev.c Opened device /dev/dsp(OSS)//dev/dsp(OSS) for recording and playback, sample rate=16000, ch=1, bits=16, 320 samples per frame, input latency=96 ms, output latency=96 ms
16:28:01.544 ec0x85dd440 Creating AEC
16:28:01.545 ec0x85dd440 AEC created, clock_rate=16000, channel=1, samples per frame=320, tail length=200 ms, latency=96 ms
16:28:01.546 pa_dev.c Starting /dev/dsp stream..
16:28:01.587 pa_dev.c Recorder thread started
16:28:01.587 os_core_unix.c Info: possibly re-registering existing thread
16:28:01.587 pa_dev.c Player thread started
16:28:01.588 pa_dev.c Done, status=0
16:28:01.588 conference.c Port 3 (ring) transmitting to port 0 (/dev/dsp)
16:28:01.588 inv0x85d542c SDP negotiation done, status=0
16:28:01.588 pjsua_call.c Call 0: remote NAT type is 0 (Unknown)
16:28:01.588 strm0x860cc74 VAD temporarily disabled
16:28:01.588 rtp.c pjmedia_rtp_session_init: ses=0x860e4d8, default_pt=8, ssrc=0x5477824c
16:28:01.588 rtp.c pjmedia_rtp_session_init: ses=0x860eafc, default_pt=8, ssrc=0x5477824c
16:28:01.588 stream.c Stream strm0x860cc74 created
16:28:01.588 strm0x860cc74 Encoder stream started
16:28:01.588 strm0x860cc74 Decoder stream started
16:28:01.588 resample.c resample created: high qualiy, large filter, in/out rate=8000/16000
16:28:01.588 resample.c resample created: high qualiy, large filter, in/out rate=16000/8000
16:28:01.588 pjsua_media.c Media updates, stream #0: PCMA (sendrecv)
16:28:01.588 conference.c Port 3 (ring) stop transmitting to port 0 (/dev/dsp)
16:28:01.588 conference.c Port 1 (/home/dulceangustia/Desktop/file2.wav) transmitting to port 4 (sip:192.168.0.2)
16:28:01.588 pjsua_app.c Media for call 0 is active
16:28:01.588 inv0x85d542c Sending Response msg 200/INVITE/cseq=14303 (tdta0x85d7e20)
16:28:01.588 dlg0x85d542c Sending Response msg 200/INVITE/cseq=14303 (tdta0x85d7e20)
16:28:01.588 tsx0x85d5c34 Sending Response msg 200/INVITE/cseq=14303 (tdta0x85d7e20) in state Proceeding
16:28:01.588 pjsua_core.c TX 801 bytes Response msg 200/INVITE/cseq=14303 (tdta0x85d7e20) to UDP 147.83.47.176:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 147.83.47.176:5060;rport=5060;received=147.83.47.176;branch=z9hG4bKPjcmxtzJs4rxf8qpy-qTZvTzYricwYUAL6
Call-ID: 6WP79L6pbZ9mo9.bedoUWbragcyqrT8p
From: <sip:192.168.0.2>;tag=.oTifxb.Uvlmv8hQYMB6gpEbW-dYji4-
To: <sip:147.83.47.178>;tag=fU9ZIWypQSTVLoGgeWaUZy37U6d8Cyn9
CSeq: 14303 INVITE
Contact: <sip:147.83.47.178:5060>
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, norefersub
Content-Type: application/sdp
Content-Length: 254
v=0
o=- 3452596081 3452596082 IN IP4 147.83.47.178
s=pjmedia
c=IN IP4 147.83.47.178
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 8 101
a=rtcp:4001 IN IP4 147.83.47.178
a=rtpmap:8 PCMA/8000
a=sendrecv
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
--end msg--
16:28:01.589 tsx0x85d5c34 State changed from Proceeding to Completed, event=TX_MSG
16:28:01.589 dlg0x85d542c Transaction tsx0x85d5c34 state changed to Completed
16:28:01.589 pjsua_app.c Call 0 state changed to CONNECTING
16:28:01.593 udp0x85c70e0 Remote RTCP address switched to 147.83.47.176:4001
16:28:01.594 sip_endpoint.c Processing incoming message: Request msg ACK/cseq=14303 (rdata0x85c5f34)
16:28:01.594 pjsua_core.c RX 350 bytes Request msg ACK/cseq=14303 (rdata0x85c5f34) from UDP 147.83.47.176:5060:
ACK sip:147.83.47.178:5060 SIP/2.0
Via: SIP/2.0/UDP 147.83.47.176:5060;rport;branch=z9hG4bKPjQMqAc.ipG3M84de1cX243-aNJeyXZt-h
Max-Forwards: 70
From: <sip:192.168.0.2>;tag=.oTifxb.Uvlmv8hQYMB6gpEbW-dYji4-
To: sip:147.83.47.178;tag=fU9ZIWypQSTVLoGgeWaUZy37U6d8Cyn9
Call-ID: 6WP79L6pbZ9mo9.bedoUWbragcyqrT8p
CSeq: 14303 ACK
Content-Length: 0
--end msg--
16:28:01.594 dlg0x85d542c Received Request msg ACK/cseq=14303 (rdata0x85c5f34)
16:28:01.594 tsx0x85d5c34 Request to terminate transaction
16:28:01.594 tsx0x85d5c34 State changed from Completed to Terminated, event=USER
16:28:01.594 dlg0x85d542c Transaction tsx0x85d5c34 state changed to Terminated
16:28:01.594 pjsua_app.c Call 0 state changed to CONFIRMED
16:28:01.595 tsx0x85d5c34 Timeout timer event
16:28:01.595 tsx0x85d5c34 State changed from Terminated to Destroyed, event=TIMER
16:28:01.595 tdta0x85d7e20 Destroying txdata Response msg 200/INVITE/cseq=14303 (tdta0x85d7e20)
16:28:01.595 tsx0x85d5c34 Transaction destroyed!
16:28:01.599 strm0x860cc74 RTP status: badpt=0, badssrc=0, dup=0, outorder=0, probation=-1, restart=0
16:28:01.611 ec0x85dd440 Prefetching..
16:28:01.611 ec0x85dd440 Prefetching..
16:28:01.612 strm0x860cc74 Start talksprut..
16:28:01.643 ec0x85dd440 Prefetching..
16:28:01.644 ec0x85dd440 Latency bufferring complete
16:28:01.677 ec0x85dd440 Underflow, buf_cnt=0, will generate 1 frame
16:28:02.222 strm0x860cc74 VAD re-enabled
16:28:02.222 silencedet.c Starting talk burst (level=4650 threshold=1000)
16:28:06.570 sound_port.c EC suspended because of inactivity
16:28:10.214 silencedet.c Re-adjust threshold (in talk burst)to 2002
16:28:18.367 silencedet.c Re-adjust threshold (in talk burst)to 2812
16:28:29.399 silencedet.c Re-adjust threshold (in talk burst)to 3653
16:28:39.016 sip_endpoint.c Processing incoming message: Request msg INVITE/cseq=14304 (rdata0x85c5f34)
16:28:39.016 pjsua_core.c RX 1061 bytes Request msg INVITE/cseq=14304 (rdata0x85c5f34) from UDP 147.83.47.177:5060:
INVITE sip:147.83.47.178:5060 SIP/2.0
Via: SIP/2.0/UDP 147.83.47.177:2590;rport;branch=z9hG4bKPjiYYjN76y2Qu.u5IAoqFJlVTnUked47K2
Max-Forwards: 70
From: <sip:192.168.0.2>;tag=.oTifxb.Uvlmv8hQYMB6gpEbW-dYji4-
To: sip:147.83.47.178;tag=fU9ZIWypQSTVLoGgeWaUZy37U6d8Cyn9
Contact: <sip:147.83.47.177:2590>
Call-ID: 6WP79L6pbZ9mo9.bedoUWbragcyqrT8p
CSeq: 14304 INVITE
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, norefersub
User-Agent: PJSUA v1.2/i686-pc-linux-gnu
Content-Type: application/sdp
Content-Length: 461
v=0
o=- 3452596110 3452596111 IN IP4 147.83.47.177
s=pjmedia
c=IN IP4 147.83.47.177
t=0 0
a=X-nat:0
m=audio 25590 RTP/AVP 8 103 102 113 3 0 104 9 101
a=rtcp:4001 IN IP4 192.168.0.2
a=rtpmap:8 PCMA/8000
a=rtpmap:103 speex/16000
a=rtpmap:102 speex/8000
a=rtpmap:113 iLBC/8000
a=fmtp:113 mode=30
a=rtpmap:3 GSM/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:104 speex/32000
a=rtpmap:9 G722/8000
a=sendrecv
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
--end msg--
16:28:39.017 dlg0x85d542c Received Request msg INVITE/cseq=14304 (rdata0x85c5f34)
16:28:39.017 tsx0x85d5c34 Transaction created for Request msg INVITE/cseq=14304 (rdata0x85c5f34)
16:28:39.017 tsx0x85d5c34 Incoming Request msg INVITE/cseq=14304 (rdata0x85c5f34) in state Null
16:28:39.017 tsx0x85d5c34 State changed from Null to Trying, event=RX_MSG
16:28:39.017 dlg0x85d542c Transaction tsx0x85d5c34 state changed to Trying
16:28:39.017 inv0x85d542c Got SDP offer in Request msg INVITE/cseq=14304 (rdata0x85c5f34)
16:28:39.017 pjsua_call.c Call 0: received updated media offer
16:28:39.017 endpoint Response msg 200/INVITE/cseq=14304 (tdta0x85d3478) created
16:28:39.017 inv0x85d542c SDP negotiation done, status=0
16:28:39.017 pjsua_call.c Call 0: remote NAT type is 0 (Unknown)
16:28:39.017 strm0x860cc74
JB summary:
size=2 prefetch=0
delay (min/max/avg/dev)=0/0/0/0 ms
burst (min/max/avg/dev)=0/0/0/0 frames
lost=0 discard=74 empty=0
16:28:39.017 pjsua_media.c Media session for call 0 is destroyed
16:28:39.017 strm0x860cc74 VAD temporarily disabled
16:28:39.017 rtp.c pjmedia_rtp_session_init: ses=0x860e4d8, default_pt=8, ssrc=0x5477824c
16:28:39.017 rtp.c pjmedia_rtp_session_init: ses=0x860eafc, default_pt=8, ssrc=0x5477824c
16:28:39.017 stream.c Stream strm0x860cc74 created
16:28:39.017 strm0x860cc74 Encoder stream started
16:28:39.017 strm0x860cc74 Decoder stream started
16:28:39.017 resample.c resample created: high qualiy, large filter, in/out rate=8000/16000
16:28:39.017 resample.c resample created: high qualiy, large filter, in/out rate=16000/8000
16:28:39.018 pjsua_media.c Media updates, stream #0: PCMA (sendrecv)
16:28:39.018 conference.c Port 1 (/home/dulceangustia/Desktop/file2.wav) transmitting to port 4 (sip:192.168.0.2)
16:28:39.018 pjsua_app.c Media for call 0 is active
16:28:39.018 inv0x85d542c Sending Response msg 200/INVITE/cseq=14304 (tdta0x85d3478)
16:28:39.018 dlg0x85d542c Sending Response msg 200/INVITE/cseq=14304 (tdta0x85d3478)
16:28:39.018 tsx0x85d5c34 Sending Response msg 200/INVITE/cseq=14304 (tdta0x85d3478) in state Trying
16:28:39.018 pjsua_core.c TX 801 bytes Response msg 200/INVITE/cseq=14304 (tdta0x85d3478) to UDP 147.83.47.177:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 147.83.47.177:2590;rport=5060;received=147.83.47.177;branch=z9hG4bKPjiYYjN76y2Qu.u5IAoqFJlVTnUked47K2
Call-ID: 6WP79L6pbZ9mo9.bedoUWbragcyqrT8p
From: <sip:192.168.0.2>;tag=.oTifxb.Uvlmv8hQYMB6gpEbW-dYji4-
To: <sip:147.83.47.178>;tag=fU9ZIWypQSTVLoGgeWaUZy37U6d8Cyn9
CSeq: 14304 INVITE
Contact: <sip:147.83.47.178:5060>
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, norefersub
Content-Type: application/sdp
Content-Length: 254
v=0
o=- 3452596081 3452596083 IN IP4 147.83.47.178
s=pjmedia
c=IN IP4 147.83.47.178
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 8 101
a=rtcp:4001 IN IP4 147.83.47.178
a=rtpmap:8 PCMA/8000
a=sendrecv
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
--end msg--
16:28:39.018 tsx0x85d5c34 State changed from Trying to Completed, event=TX_MSG
16:28:39.018 dlg0x85d542c Transaction tsx0x85d5c34 state changed to Completed
16:28:39.022 strm0x860cc74 Start talksprut..
16:28:39.036 udp0x85c70e0 Remote RTCP address switched to 147.83.47.177:4001
16:28:39.037 sip_endpoint.c Processing incoming message: Request msg ACK/cseq=14304 (rdata0x85c5f34)
16:28:39.037 pjsua_core.c RX 350 bytes Request msg ACK/cseq=14304 (rdata0x85c5f34) from UDP 147.83.47.177:5060:
ACK sip:147.83.47.178:5060 SIP/2.0
Via: SIP/2.0/UDP 147.83.47.177:2590;rport;branch=z9hG4bKPj.D8X1t6AanvSxDNSIHcs6nB-1bHve5Lc
Max-Forwards: 70
From: <sip:192.168.0.2>;tag=.oTifxb.Uvlmv8hQYMB6gpEbW-dYji4-
To: sip:147.83.47.178;tag=fU9ZIWypQSTVLoGgeWaUZy37U6d8Cyn9
Call-ID: 6WP79L6pbZ9mo9.bedoUWbragcyqrT8p
CSeq: 14304 ACK
Content-Length: 0
--end msg--
16:28:39.037 dlg0x85d542c Received Request msg ACK/cseq=14304 (rdata0x85c5f34)
16:28:39.037 tsx0x85d5c34 Request to terminate transaction
16:28:39.037 tsx0x85d5c34 State changed from Completed to Terminated, event=USER
16:28:39.037 dlg0x85d542c Transaction tsx0x85d5c34 state changed to Terminated
16:28:39.037 tsx0x85d5c34 Timeout timer event
16:28:39.037 tsx0x85d5c34 State changed from Terminated to Destroyed, event=TIMER
16:28:39.037 tdta0x85d3478 Destroying txdata Response msg 200/INVITE/cseq=14304 (tdta0x85d3478)
16:28:39.037 tsx0x85d5c34 Transaction destroyed!
16:28:39.053 strm0x860cc74 RTP status: badpt=0, badssrc=0, dup=0, outorder=0, probation=-1, restart=0
16:28:39.198 udp0x85c70e0 Remote RTP address switched to 147.83.47.177:4000
16:28:39.631 strm0x860cc74 VAD re-enabled
16:29:19.884 strm0x860cc74 RTT value 7446 usec is normalized to 4815 usec
16:29:19.884 udp0x85c70e0 Remote RTCP address switched to 147.83.47.176:4001
16:29:22.733 sip_endpoint.c Processing incoming message: Request msg INVITE/cseq=14305 (rdata0x85c5f34)
16:29:22.733 pjsua_core.c RX 1060 bytes Request msg INVITE/cseq=14305 (rdata0x85c5f34) from UDP 147.83.47.176:5060:
INVITE sip:147.83.47.178:5060 SIP/2.0
Via: SIP/2.0/UDP 147.83.47.176:5060;rport;branch=z9hG4bKPjgCNAixQRjiHueBLLitZqtcx1m1ZqFqLe
Max-Forwards: 70
From: <sip:192.168.0.2>;tag=.oTifxb.Uvlmv8hQYMB6gpEbW-dYji4-
To: sip:147.83.47.178;tag=fU9ZIWypQSTVLoGgeWaUZy37U6d8Cyn9
Contact: <sip:147.83.47.176:5060>
Call-ID: 6WP79L6pbZ9mo9.bedoUWbragcyqrT8p
CSeq: 14305 INVITE
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, norefersub
User-Agent: PJSUA v1.2/i686-pc-linux-gnu
Content-Type: application/sdp
Content-Length: 460
v=0
o=- 3452596110 3452596112 IN IP4 147.83.47.176
s=pjmedia
c=IN IP4 147.83.47.176
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 8 103 102 113 3 0 104 9 101
a=rtcp:4001 IN IP4 192.168.0.2
a=rtpmap:8 PCMA/8000
a=rtpmap:103 speex/16000
a=rtpmap:102 speex/8000
a=rtpmap:113 iLBC/8000
a=fmtp:113 mode=30
a=rtpmap:3 GSM/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:104 speex/32000
a=rtpmap:9 G722/8000
a=sendrecv
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
--end msg--
16:29:22.733 dlg0x85d542c Received Request msg INVITE/cseq=14305 (rdata0x85c5f34)
16:29:22.733 tsx0x85d5c34 Transaction created for Request msg INVITE/cseq=14305 (rdata0x85c5f34)
16:29:22.733 tsx0x85d5c34 Incoming Request msg INVITE/cseq=14305 (rdata0x85c5f34) in state Null
16:29:22.733 tsx0x85d5c34 State changed from Null to Trying, event=RX_MSG
16:29:22.733 dlg0x85d542c Transaction tsx0x85d5c34 state changed to Trying
16:29:22.733 inv0x85d542c Got SDP offer in Request msg INVITE/cseq=14305 (rdata0x85c5f34)
16:29:22.733 pjsua_call.c Call 0: received updated media offer
16:29:22.734 endpoint Response msg 200/INVITE/cseq=14305 (tdta0x8611348) created
16:29:22.734 inv0x85d542c SDP negotiation done, status=0
16:29:22.734 pjsua_call.c Call 0: remote NAT type is 0 (Unknown)
16:29:22.734 strm0x860cc74
JB summary:
size=2 prefetch=0
delay (min/max/avg/dev)=0/0/0/0 ms
burst (min/max/avg/dev)=0/0/0/0 frames
lost=0 discard=78 empty=0
16:29:22.734 pjsua_media.c Media session for call 0 is destroyed
16:29:22.734 strm0x86136dc VAD temporarily disabled
16:29:22.734 rtp.c pjmedia_rtp_session_init: ses=0x8614f40, default_pt=8, ssrc=0x5477824c
16:29:22.734 rtp.c pjmedia_rtp_session_init: ses=0x8615564, default_pt=8, ssrc=0x5477824c
16:29:22.734 stream.c Stream strm0x86136dc created
16:29:22.734 strm0x86136dc Encoder stream started
16:29:22.734 strm0x86136dc Decoder stream started
16:29:22.734 resample.c resample created: high qualiy, large filter, in/out rate=8000/16000
16:29:22.735 resample.c resample created: high qualiy, large filter, in/out rate=16000/8000
16:29:22.735 pjsua_media.c Media updates, stream #0: PCMA (sendrecv)
16:29:22.735 conference.c Port 1 (/home/dulceangustia/Desktop/file2.wav) transmitting to port 4 (sip:192.168.0.2)
16:29:22.735 pjsua_app.c Media for call 0 is active
16:29:22.735 inv0x85d542c Sending Response msg 200/INVITE/cseq=14305 (tdta0x8611348)
16:29:22.735 dlg0x85d542c Sending Response msg 200/INVITE/cseq=14305 (tdta0x8611348)
16:29:22.735 tsx0x85d5c34 Sending Response msg 200/INVITE/cseq=14305 (tdta0x8611348) in state Trying
16:29:22.735 pjsua_core.c TX 801 bytes Response msg 200/INVITE/cseq=14305 (tdta0x8611348) to UDP 147.83.47.176:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 147.83.47.176:5060;rport=5060;received=147.83.47.176;branch=z9hG4bKPjgCNAixQRjiHueBLLitZqtcx1m1ZqFqLe
Call-ID: 6WP79L6pbZ9mo9.bedoUWbragcyqrT8p
From: <sip:192.168.0.2>;tag=.oTifxb.Uvlmv8hQYMB6gpEbW-dYji4-
To: <sip:147.83.47.178>;tag=fU9ZIWypQSTVLoGgeWaUZy37U6d8Cyn9
CSeq: 14305 INVITE
Contact: <sip:147.83.47.178:5060>
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, norefersub
Content-Type: application/sdp
Content-Length: 254
v=0
o=- 3452596081 3452596084 IN IP4 147.83.47.178
s=pjmedia
c=IN IP4 147.83.47.178
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 8 101
a=rtcp:4001 IN IP4 147.83.47.178
a=rtpmap:8 PCMA/8000
a=sendrecv
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
--end msg--
16:29:22.735 tsx0x85d5c34 State changed from Trying to Completed, event=TX_MSG
16:29:22.735 dlg0x85d542c Transaction tsx0x85d5c34 state changed to Completed
16:29:22.740 udp0x85c70e0 Remote RTCP address switched to 147.83.47.176:4001
16:29:22.743 sip_endpoint.c Processing incoming message: Request msg ACK/cseq=14305 (rdata0x85c5f34)
16:29:22.743 pjsua_core.c RX 350 bytes Request msg ACK/cseq=14305 (rdata0x85c5f34) from UDP 147.83.47.176:5060:
ACK sip:147.83.47.178:5060 SIP/2.0
Via: SIP/2.0/UDP 147.83.47.176:5060;rport;branch=z9hG4bKPjrlrTrthB9A5MQiqrKEp5Iyhfa6hNR-rh
Max-Forwards: 70
From: <sip:192.168.0.2>;tag=.oTifxb.Uvlmv8hQYMB6gpEbW-dYji4-
To: sip:147.83.47.178;tag=fU9ZIWypQSTVLoGgeWaUZy37U6d8Cyn9
Call-ID: 6WP79L6pbZ9mo9.bedoUWbragcyqrT8p
CSeq: 14305 ACK
Content-Length: 0
--end msg--
16:29:22.743 dlg0x85d542c Received Request msg ACK/cseq=14305 (rdata0x85c5f34)
16:29:22.743 tsx0x85d5c34 Request to terminate transaction
16:29:22.743 tsx0x85d5c34 State changed from Completed to Terminated, event=USER
16:29:22.743 dlg0x85d542c Transaction tsx0x85d5c34 state changed to Terminated
16:29:22.743 tsx0x85d5c34 Timeout timer event
16:29:22.743 tsx0x85d5c34 State changed from Terminated to Destroyed, event=TIMER
16:29:22.743 tdta0x8611348 Destroying txdata Response msg 200/INVITE/cseq=14305 (tdta0x8611348)
16:29:22.743 tsx0x85d5c34 Transaction destroyed!
16:29:22.763 strm0x86136dc Start talksprut..
16:29:22.776 strm0x86136dc RTP status: badpt=0, badssrc=0, dup=0, outorder=0, probation=-1, restart=0
16:29:23.372 strm0x86136dc VAD re-enabled
16:29:52.137 sip_endpoint.c Processing incoming message: Request msg INVITE/cseq=14306 (rdata0x85c5f34)
16:29:52.137 pjsua_core.c RX 1061 bytes Request msg INVITE/cseq=14306 (rdata0x85c5f34) from UDP 147.83.47.177:5060:
INVITE sip:147.83.47.178:5060 SIP/2.0
Via: SIP/2.0/UDP 147.83.47.177:2590;rport;branch=z9hG4bKPjk84hEwSddLUNN8x4m2LnsnerWvVannnf
Max-Forwards: 70
From: <sip:192.168.0.2>;tag=.oTifxb.Uvlmv8hQYMB6gpEbW-dYji4-
To: sip:147.83.47.178;tag=fU9ZIWypQSTVLoGgeWaUZy37U6d8Cyn9
Contact: <sip:147.83.47.177:2590>
Call-ID: 6WP79L6pbZ9mo9.bedoUWbragcyqrT8p
CSeq: 14306 INVITE
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, norefersub
User-Agent: PJSUA v1.2/i686-pc-linux-gnu
Content-Type: application/sdp
Content-Length: 461
v=0
o=- 3452596110 3452596113 IN IP4 147.83.47.177
s=pjmedia
c=IN IP4 147.83.47.177
t=0 0
a=X-nat:0
m=audio 25590 RTP/AVP 8 103 102 113 3 0 104 9 101
a=rtcp:4001 IN IP4 192.168.0.2
a=rtpmap:8 PCMA/8000
a=rtpmap:103 speex/16000
a=rtpmap:102 speex/8000
a=rtpmap:113 iLBC/8000
a=fmtp:113 mode=30
a=rtpmap:3 GSM/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:104 speex/32000
a=rtpmap:9 G722/8000
a=sendrecv
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
--end msg--
16:29:52.137 dlg0x85d542c Received Request msg INVITE/cseq=14306 (rdata0x85c5f34)
16:29:52.137 tsx0x85d5c34 Transaction created for Request msg INVITE/cseq=14306 (rdata0x85c5f34)
16:29:52.137 tsx0x85d5c34 Incoming Request msg INVITE/cseq=14306 (rdata0x85c5f34) in state Null
16:29:52.137 tsx0x85d5c34 State changed from Null to Trying, event=RX_MSG
16:29:52.137 dlg0x85d542c Transaction tsx0x85d5c34 state changed to Trying
16:29:52.137 inv0x85d542c Got SDP offer in Request msg INVITE/cseq=14306 (rdata0x85c5f34)
16:29:52.137 pjsua_call.c Call 0: received updated media offer
16:29:52.137 endpoint Response msg 200/INVITE/cseq=14306 (tdta0x860dd38) created
16:29:52.138 inv0x85d542c SDP negotiation done, status=0
16:29:52.138 pjsua_call.c Call 0: remote NAT type is 0 (Unknown)
16:29:52.138 strm0x86136dc
JB summary:
size=2 prefetch=0
delay (min/max/avg/dev)=0/0/0/0 ms
burst (min/max/avg/dev)=0/0/0/0 frames
lost=0 discard=72 empty=0
16:29:52.138 pjsua_media.c Media session for call 0 is destroyed
16:29:52.138 strm0x86136dc VAD temporarily disabled
16:29:52.138 rtp.c pjmedia_rtp_session_init: ses=0x8614f40, default_pt=8, ssrc=0x5477824c
16:29:52.138 rtp.c pjmedia_rtp_session_init: ses=0x8615564, default_pt=8, ssrc=0x5477824c
16:29:52.138 stream.c Stream strm0x86136dc created
16:29:52.138 strm0x86136dc Encoder stream started
16:29:52.138 strm0x86136dc Decoder stream started
16:29:52.138 resample.c resample created: high qualiy, large filter, in/out rate=8000/16000
16:29:52.138 resample.c resample created: high qualiy, large filter, in/out rate=16000/8000
16:29:52.138 pjsua_media.c Media updates, stream #0: PCMA (sendrecv)
16:29:52.138 conference.c Port 1 (/home/dulceangustia/Desktop/file2.wav) transmitting to port 4 (sip:192.168.0.2)
16:29:52.138 pjsua_app.c Media for call 0 is active
16:29:52.138 inv0x85d542c Sending Response msg 200/INVITE/cseq=14306 (tdta0x860dd38)
16:29:52.138 dlg0x85d542c Sending Response msg 200/INVITE/cseq=14306 (tdta0x860dd38)
16:29:52.138 tsx0x85d5c34 Sending Response msg 200/INVITE/cseq=14306 (tdta0x860dd38) in state Trying
16:29:52.138 pjsua_core.c TX 801 bytes Response msg 200/INVITE/cseq=14306 (tdta0x860dd38) to UDP 147.83.47.177:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 147.83.47.177:2590;rport=5060;received=147.83.47.177;branch=z9hG4bKPjk84hEwSddLUNN8x4m2LnsnerWvVannnf
Call-ID: 6WP79L6pbZ9mo9.bedoUWbragcyqrT8p
From: <sip:192.168.0.2>;tag=.oTifxb.Uvlmv8hQYMB6gpEbW-dYji4-
To: <sip:147.83.47.178>;tag=fU9ZIWypQSTVLoGgeWaUZy37U6d8Cyn9
CSeq: 14306 INVITE
Contact: <sip:147.83.47.178:5060>
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, norefersub
Content-Type: application/sdp
Content-Length: 254
v=0
o=- 3452596081 3452596085 IN IP4 147.83.47.178
s=pjmedia
c=IN IP4 147.83.47.178
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 8 101
a=rtcp:4001 IN IP4 147.83.47.178
a=rtpmap:8 PCMA/8000
a=sendrecv
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
--end msg--
16:29:52.138 tsx0x85d5c34 State changed from Trying to Completed, event=TX_MSG
16:29:52.138 dlg0x85d542c Transaction tsx0x85d5c34 state changed to Completed
16:29:52.146 udp0x85c70e0 Remote RTCP address switched to 147.83.47.177:4001
16:29:52.147 sip_endpoint.c Processing incoming message: Request msg ACK/cseq=14306 (rdata0x85c5f34)
16:29:52.147 pjsua_core.c RX 350 bytes Request msg ACK/cseq=14306 (rdata0x85c5f34) from UDP 147.83.47.177:5060:
ACK sip:147.83.47.178:5060 SIP/2.0
Via: SIP/2.0/UDP 147.83.47.177:2590;rport;branch=z9hG4bKPjPi0wighQDhczZUajT-KGx04qgAsapcYl
Max-Forwards: 70
From: <sip:192.168.0.2>;tag=.oTifxb.Uvlmv8hQYMB6gpEbW-dYji4-
To: sip:147.83.47.178;tag=fU9ZIWypQSTVLoGgeWaUZy37U6d8Cyn9
Call-ID: 6WP79L6pbZ9mo9.bedoUWbragcyqrT8p
CSeq: 14306 ACK
Content-Length: 0
--end msg--
16:29:52.148 dlg0x85d542c Received Request msg ACK/cseq=14306 (rdata0x85c5f34)
16:29:52.148 tsx0x85d5c34 Request to terminate transaction
16:29:52.148 tsx0x85d5c34 State changed from Completed to Terminated, event=USER
16:29:52.148 dlg0x85d542c Transaction tsx0x85d5c34 state changed to Terminated
16:29:52.148 tsx0x85d5c34 Timeout timer event
16:29:52.148 tsx0x85d5c34 State changed from Terminated to Destroyed, event=TIMER
16:29:52.148 tdta0x860dd38 Destroying txdata Response msg 200/INVITE/cseq=14306 (tdta0x860dd38)
16:29:52.148 tsx0x85d5c34 Transaction destroyed!
16:29:52.158 strm0x86136dc Start talksprut..
16:29:52.174 strm0x86136dc RTP status: badpt=0, badssrc=0, dup=0, outorder=0, probation=-1, restart=0
16:29:52.318 udp0x85c70e0 Remote RTP address switched to 147.83.47.177:4000
16:29:52.756 strm0x86136dc VAD re-enabled
16:30:11.437 sip_endpoint.c Processing incoming message: Request msg BYE/cseq=14307 (rdata0x85c5f34)
16:30:11.437 pjsua_core.c RX 392 bytes Request msg BYE/cseq=14307 (rdata0x85c5f34) from UDP 147.83.47.177:5060:
BYE sip:147.83.47.178:5060 SIP/2.0
Via: SIP/2.0/UDP 147.83.47.177:2590;rport;branch=z9hG4bKPjd5XMx2zACil-e-4NT04QE5b7CZCMEOxG
Max-Forwards: 70
From: <sip:192.168.0.2>;tag=.oTifxb.Uvlmv8hQYMB6gpEbW-dYji4-
To: sip:147.83.47.178;tag=fU9ZIWypQSTVLoGgeWaUZy37U6d8Cyn9
Call-ID: 6WP79L6pbZ9mo9.bedoUWbragcyqrT8p
CSeq: 14307 BYE
User-Agent: PJSUA v1.2/i686-pc-linux-gnu
Content-Length: 0
--end msg--
16:30:11.437 dlg0x85d542c Received Request msg BYE/cseq=14307 (rdata0x85c5f34)
16:30:11.438 tsx0x85d5c34 Transaction created for Request msg BYE/cseq=14307 (rdata0x85c5f34)
16:30:11.438 tsx0x85d5c34 Incoming Request msg BYE/cseq=14307 (rdata0x85c5f34) in state Null
16:30:11.438 tsx0x85d5c34 State changed from Null to Trying, event=RX_MSG
16:30:11.438 dlg0x85d542c Transaction tsx0x85d5c34 state changed to Trying
16:30:11.438 endpoint Response msg 200/BYE/cseq=14307 (tdta0x860dd38) created
16:30:11.438 dlg0x85d542c Sending Response msg 200/BYE/cseq=14307 (tdta0x860dd38)
16:30:11.438 tsx0x85d5c34 Sending Response msg 200/BYE/cseq=14307 (tdta0x860dd38) in state Trying
16:30:11.438 pjsua_core.c TX 342 bytes Response msg 200/BYE/cseq=14307 (tdta0x860dd38) to UDP 147.83.47.177:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 147.83.47.177:2590;rport=5060;received=147.83.47.177;branch=z9hG4bKPjd5XMx2zACil-e-4NT04QE5b7CZCMEOxG
Call-ID: 6WP79L6pbZ9mo9.bedoUWbragcyqrT8p
From: <sip:192.168.0.2>;tag=.oTifxb.Uvlmv8hQYMB6gpEbW-dYji4-
To: <sip:147.83.47.178>;tag=fU9ZIWypQSTVLoGgeWaUZy37U6d8Cyn9
CSeq: 14307 BYE
Content-Length: 0
--end msg--
16:30:11.438 tsx0x85d5c34 State changed from Trying to Completed, event=TX_MSG
16:30:11.438 dlg0x85d542c Transaction tsx0x85d5c34 state changed to Completed
16:30:11.438 pjsua_app.c Call 0 is DISCONNECTED [reason=200 (Normal call clearing)]
16:30:11.438 pjsua_app.c Call 0 disconnected, dumping media stats..
16:30:11.438 pjsua_app.c
[DISCONNCTD] To: <sip:192.168.0.2>;tag=.oTifxb.Uvlmv8hQYMB6gpEbW-dYji4-
Call time: 00h:02m:09s, 1st res in 46 ms, conn in 52ms
SRTP status: Not active Crypto-suite: (null)
#0 PCMA @8KHz, sendrecv, peer=147.83.47.177:4000
RX pt=8, stat last update: 00h:00m:00.040s ago
total 34pkt 5.4KB (6.8KB +IP hdr) @avg=2.2Kbps/2.8Kbps
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.125 0.400 1.000 0.250 0.334
TX pt=8, ptime=20ms, stat last update: 00h:00m:00.409s ago
total 967pkt 154.7KB (193.4KB +IP hdr) @avg 64.1Kbps/80.1Kbps
pkt loss=0 (0.0%), dup=0 (0.0%), reorder=0 (0.0%)
(msec) min avg max last dev
loss period: 0.000 0.000 0.000 0.000 0.000
jitter : 0.000 11.475 14.875 14.250 5.744
RTT msec : 0.549 1.825 3.921 0.656 1.481
16:30:11.438 strm0x86136dc
JB summary:
size=2 prefetch=0
delay (min/max/avg/dev)=0/0/0/0 ms
burst (min/max/avg/dev)=0/0/0/0 frames
lost=0 discard=66 empty=0
16:30:11.438 pjsua_media.c Media session for call 0 is destroyed
16:30:11.438 dlg0x85d542c Session count dec to 3 by mod-invite
16:30:12.439 pjsua_media.c Closing sound device after idle for 1 seconds
16:30:12.439 pjsua_media.c Closing /dev/dsp sound playback device and /dev/dsp sound capture device
16:30:13.463 pa_dev.c Stopping stream..
16:30:13.463 pa_dev.c Done, status=0
16:30:13.565 pa_dev.c Closing /dev/dsp: 0 underflow, 0 overflow
16:30:16.935 pa_dev.c PortAudio sound library shutting down..
16:30:16.935 pjsua_core.c Shutting down...
16:30:17.943 pjsua_core.c Destroying...
16:30:17.943 sip_endpoint.c Destroying endpoing instance..
16:30:17.943 sip_transactio Stopping transaction layer module
16:30:17.943 tsx0x85d5c34 Request to terminate transaction
16:30:17.943 tsx0x85d5c34 State changed from Completed to Terminated, event=USER
16:30:17.943 dlg0x85d542c Transaction tsx0x85d5c34 state changed to Terminated
16:30:17.943 dlg0x85d542c Dialog destroyed
16:30:17.943 tdta0x860dd38 Destroying txdata Response msg 200/BYE/cseq=14307 (tdta0x860dd38)
16:30:17.943 tsx0x85d5c34 Transaction destroyed!
16:30:17.943 sip_endpoint.c Module "mod-default-handler" unregistered
16:30:17.943 sip_endpoint.c Module "mod-pjsua-options" unregistered
16:30:17.943 sip_endpoint.c Module "mod-pjsua-im" unregistered
16:30:17.943 sip_endpoint.c Module "mod-pjsua-pres" unregistered
16:30:17.943 sip_endpoint.c Module "mod-pjsua" unregistered
16:30:17.943 sip_endpoint.c Module "mod-stateful-util" unregistered
16:30:17.943 sip_endpoint.c Module "mod-refer" unregistered
16:30:17.943 sip_endpoint.c Module "mod-presence" unregistered
16:30:17.943 sip_endpoint.c Module "mod-evsub" unregistered
16:30:17.943 sip_endpoint.c Module "mod-invite" unregistered
16:30:17.943 sip_endpoint.c Module "mod-100rel" unregistered
16:30:17.943 sip_endpoint.c Module "mod-ua" unregistered
16:30:17.943 sip_transactio Transaction layer module destroyed
16:30:17.943 sip_endpoint.c Module "mod-tsx-layer" unregistered
16:30:17.943 sip_endpoint.c Module "mod-msg-print" unregistered
16:30:17.943 sip_endpoint.c Module "mod-pjsua-log" unregistered
16:30:17.943 sip_transport. Destroying transport manager
16:30:17.944 sip_endpoint.c Endpoint 0x85a4164 destroyed
16:30:17.944 file2.wav Pool is not released by application, releasing now
16:30:17.944 pjsua_core.c PJSUA destroyed...
More information about the pjsip
mailing list