Re: [pjsip] Possible bug (Buffer overflow) - PJSUA unable to handle SIP message sent via Cisco 8510 MCU when registered to a Cisco VCS via TCP/TLS
Further to this, I have one more oddity to throw into the mix.
The VCS's are able to interwork SIP <----> H.323 (and vice versa) and so if we have PJSUA registered to the VCS using TCP or TLS, and the MCU make an H.323 call instead of SIP call, the VCS will interwork H.323 --> SIP and the call is placed successfully.
Chris
From: Swinney C.
Sent: 26 June 2014 19:15
To: pjsip@lists.pjsip.org
Subject: Possible bug (Buffer overflow) - PJSUA unable to handle SIP message sent via Cisco 8510 MCU when registered to a Cisco VCS via TCP/TLS
Hi all,
We are trying to get PJSIP and the PJSUA application working when we register to an account via either TCP or TLS transport. The Registrar is a Cisco VCS and we are trying to accept an inbound call initiated from a Cisco 8510 MCU. PJSIP is running on version 2.2.1 on a Raspberry Pi running Raspian.
If we register PJSUA to the VCS using UDP as the transport, we can accept a call from the MCU with no issue. However, if we try to register via TCP as the transport mechanism to the VCS and make the same call from the same MCU, PJSUA is unable to handle the message and says there is a buffer overflow. Is there anything we can do to rectify this?
For reference, I have included below the relevant log sections (level 5) from the failed call and the good call (obscure IP address and URIs). If there is anything else that might help (such as high level logs or packet captures), then let us know:
Log from FAILED inbound call when registered via TCP transport
08:53:02.110 sip_endpoint.c Error processing packet from 222.222.222.1:5060: Rx buffer overflow (PJSIP_ERXOVERFLOW) [code 171062]:
INVITE sip:pi@222.222.222.222:5060;transport=TCP;ob SIP/2.0
Via: SIP/2.0/TCP 222.222.222.1:5060;egress-zone=DefaultSubZone;branch=z9hG4bK2539fce0618acc914d081ece26433f06907450.e8169c45cc678bd159754752343056b4;proxy-call-id=1582591a-fd53-11e3-bd38-0010f30f5a60;rport
Via: SIP/2.0/TLS 111.111.111.111:7029;egress-zone=OurUniversity;branch=z9hG4bK895b55f2f3bf606bac3fc0651749fa5b61290.73594a4f2852aa8a3c87a40dd8159206;proxy-call-id=157cb19a-fd53-11e3-81d7-0010f30f56f0;received=111.111.111.111;rport=7029;ingress-zone=OurUniversity
Via: SIP/2.0/TLS 9.9.9.9:5061;egress-zone=dnsout;branch=z9hG4bK6fd1beced1963d9a99dca0da8be9743b1214218.bf97dcdf757250e2330b681719b63e91;proxy-call-id=d09ae921-6d48-490b-a44d-89da5ecb1735;received=9.9.9.9;rport=26521;ingress-zone=DefaultZone
Via: SIP/2.0/TCP 1.1.1.1:5060;branch=z9hG4bK17429394;received=1.1.1.1;ingress-zone=DefaultSubZone
Call-ID: C67D8C81@1.1.1.1
CSeq: 1 INVITE
Contact: sip:8510@thierdomain.ac.uk;gr=urn:uuid:e42a7487-dd80-52f6-b21b-c718aaff8e59;isfocus
From: "CONFERENCE1" sip:8510@thierdomain.ac.uk;tag=DC5D7A6BD0B50001
To: sip:pi@mydomain.ac.uk
Max-Forwards: 13
Record-Route: sip:proxy-call-id=1582591a-fd53-11e3-bd38-0010f30f5a60@222.222.222.1:5060;transport=tcp;lr
Record-Route: sip:proxy-call-id=1582591a-fd53-11e3-bd38-0010f30f5a60@222.222.222.1:5061;transport=tls;lr
Record-Route: sip:proxy-call-id=157cb19a-fd53-11e3-81d7-0010f30f56f0@111.111.111.111:7029;transport=tls;lr
Record-Route: sip:proxy-call-id=157cb19a-fd53-11e3-81d7-0010f30f56f0@111.111.111.111:5061;transport=tls;lr
Record-Route: sip:proxy-call-id=d09ae921-6d48-490b-a44d-89da5ecb1735@9.9.9.9:5061;transport=tls;lr
Record-Route: sip:proxy-call-id=d09ae921-6d48-490b-a44d-89da5ecb1735@9.9.9.9:5060;transport=tcp;lr
Allow: INVITE,ACK,CANCEL,OPTIONS,UPDATE,INFO,NOTIFY,BYE,REFER
User-Agent: Codian MSE 8510 v4.4 (3.42)
Supported: timer,outbound
Session-Expires: 500
X-TAATag: 7da68719-daec-4724-a92c-8c534dc98431
Content-Type: application/sdp
Content-Length: 2660
v=0
o=CODIAN 799447510 799447510 IN IP4 1.1.1.1
s=-
i=Codian MSE 8510 v4.4 (3.42)
c=IN IP4 222.222.222.1
b=AS:2046
t=0 0
m=audio 51620 RTP/AVP 113 108 109 110 98 114 115 118 99 100 102 9 104 105 0 8 15 18 101
a=rtpmap:113 MP4A-LATM/90000
a=fmtp:113 profile-level-id=24;object=23;bitrate=96000
a=rtpmap:108 MP4A-LATM/90000
a=fmtp:108 profile-level-id=24;object=23;bitrate=64000
a=rtpmap:109 MP4A-LATM/90000
a=fmtp:109 profile-level-id=24;object=23;bitrate=56000
a=rtpmap:110 MP4A-LATM/90000
a=fmtp:110 profile-level-id=24;object=23;bitrate=48000
a=rtpmap:98 MP4A-LATM/32000
a=fmtp:98 profile-level-id=2;object=2;bitrate=96000
a=rtpmap:114 SIREN14/16000
a=fmtp:114 bitrate=48000
a=rtpmap:115 SIREN14/16000
a=fmtp:115 bitrate=32000
a=rtpmap:118 SIREN14/16000
a=fmtp:118 bitrate=24000
a=rtpmap:99 G7221/32000
a=fmtp:99 bitrate=48000
a=rtpmap:100 G7221/32000
a=fmtp:100 bitrate=32000
a=rtpmap:102 G7221/32000
a=fmtp:102 bitrate=24000
a=rtpmap:9 G722/8000
a=rtpmap:104 G7221/16000
a=fmtp:104 bitrate=32000
a=rtpmap:105 G7221/16000
a=fmtp:105 bitrate=24000
a=rtpmap:0 PCMU/8000/1
a=rtpmap:8 PCMA/8000/1
a=rtpmap:15 G728/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
m=video 51610 RTP/AVP 126 97 96 34 31
b=AS:2000
a=rtpmap:126 H264/90000
a=fmtp:126 profile-level-id=42e014;max-mbps=108000;max-fs=3600;max-dpb=5400;max-fps=6000;packetization-mode=1
a=rtpmap:97 H264/90000
a=fmtp:97 profile-level-id=42e014;max-mbps=108000;max-fs=3600;max-dpb=5400;max-fps=6000
a=rtpmap:96 H263-1998/90000
a=fmtp:96 CUSTOM=1280,720,1;CUSTOM=1024,768,1;CUSTOM=1024,576,1;CUSTOM=848,480,1;CIF4=1;CUSTOM=512,288,1;CIF=1;QCIF=1
a=rtpmap:34 H263/90000
a=fmtp:34 CIF4=1;CIF=1;QCIF=1
a=rtpmap:31 H261/90000
a=fmtp:31 CIF=
08:53:53.443 pjsua_core.c !Shutting down, flags=0...
08:53:53.444 pjsua_core.c PJSUA state changed: RUNNING --> CLOSING
08:53:53.446 pjsua_call.c .Hangup all calls..
08:53:53.447 pjsua_media.c .Call 0: deinitializing media..
08:53:53.449 pjsua_pres.c .Shutting down presence..
08:53:53.451 pjsua_acc.c .Acc 3: setting unregistration..
08:53:53.452 endpoint ..Request msg REGISTER/cseq=33220 (tdta0x20e9320) created.
08:53:53.453 tsx0x20ea38c ...Transaction created for Request msg REGISTER/cseq=33221 (tdta0x20e9320)
08:53:53.453 tsx0x20ea38c ..Sending Request msg REGISTER/cseq=33221 (tdta0x20e9320) in state Null
08:53:53.453 sip_resolve.c ...DNS resolver not available, target 'vcs.mydomain.ac.uk:0' type=TCP will be resolved with getaddrinfo()
08:53:53.455 sip_resolve.c ...Target 'vcs.mydomain.ac.uk:0' type=TCP resolved to '222.222.222.1:5060' type=TCP (TCP transport)
08:53:53.455 pjsua_core.c ...TX 573 bytes Request msg REGISTER/cseq=33221 (tdta0x20e9320) to TCP 222.222.222.1:5060:
REGISTER sip:vcs.mydomain.ac.uk;transport=tcp SIP/2.0
Via: SIP/2.0/TCP 222.222.222.222:36452;rport;branch=z9hG4bKPjTJU486ZxLCQr4WanbdX7mrZxf9-fVxl2;alias
Max-Forwards: 70
From: sip:pi@mydomain.ac.uk;tag=CiChlIjlVCJH3rYPYAcI4g47JhGXvEli
To: sip:pi@mydomain.ac.uk
Call-ID: iQjdX7e-R7SzQRN-RzaeEHGl4aYC1jeb
CSeq: 33221 REGISTER
User-Agent: PJSUA v2.2.1 Linux-3.12.22/armv6l/glibc-2.13
Supported: outbound, path
Contact: sip:pi@222.222.222.222:5060;transport=TCP;ob;reg-id=1;+sip.instance="urn:uuid:00000000-0000-0000-0000-0000f361b107"
Expires: 0
Content-Length: 0
--end msg--
08:53:53.461 tsx0x20ea38c ...State changed from Null to Calling, event=TX_MSG
08:53:53.461 pjsua_acc.c ..Acc 3: Unregistration sent
08:53:53.466 sip_transport. .Error processing 1361 bytes packet from TCP 222.222.222.1:5060 : PJSIP syntax error exception when parsing '' header on line 1 col 3:
rl:s-only
a=confid:160339198
a=floorid:2 mstrm:12
a=userid:37917
a=connection:new
m=application 51628 RTP/AVP 100
a=rtpmap:100 H224/4800
a=sendrecv
m=video 51618 RTP/AVP 96 126 97
b=AS:2000
a=rtpmap:96 H263-1998/90000
a=fmtp:96 CUSTOM=1280,720,2;CUSTOM=1024,768,2;CUSTOM=1024,576,2;CUSTOM=848,480,1;CUSTOM=800,600,2;CUSTOM=768,448,1;CUSTOM=640,480,1;CUSTOM=512,288,1;CIF=1
a=rtpmap:126 H264/90000
a=fmtp:126 profile-level-id=42e014;max-mbps=27000;max-fs=3600;max-dpb=1350;max-fps=6000;packetization-mode=1
a=rtpmap:97 H264/90000
a=fmtp:97 profile-level-id=42e014;max-mbps=27000;max-fs=3600;max-dpb=1350;max-fps=6000
a=rtcp-fb:* nack pli
a=rtcp-fb:* ccm fir
a=sendrecv
a=content:slides
a=label:12
SIP/2.0 200 OK
Via: SIP/2.0/TCP 222.222.222.222:36452;branch=z9hG4bKPjTJU486ZxLCQr4WanbdX7mrZxf9-fVxl2;alias;received=222.222.222.222;rport=36452
Call-ID: iQjdX7e-R7SzQRN-RzaeEHGl4aYC1jeb
CSeq: 33221 REGISTER
From: sip:pi@mydomain.ac.uk;tag=CiChlIjlVCJH3rYPYAcI4g47JhGXvEli
To: sip:pi@mydomain.ac.uk;tag=ac91598765bd6a93
Path: sip:flow-token=MwAAAAAAAAAADwAAABPEAACJLKwBkn8AACAAAHiSfwAAYOPZfQAAAAAAAAAADwAAAI5kAACJLKw8AAAAAAAAAAAAAAAAAAAAACphL8vMwOZlk2A=@222.222.222.1;ob;lr
Server: TANDBERG/4120 (X7.2.3)
Date: Thu, 26 Jun 2014 16:59:15 GMT
Supported: com.tandberg.sdp.extensions.v1,gruu,outbound,path
Require: outbound
Content-Length: 0
Log from GOOD inbound call when registered via UDP transport
09:04:18.880 sip_endpoint.c Processing incoming message: Request msg INVITE/cseq=1 (rdata0xdd8b74)
09:04:18.880 pjsua_core.c .RX 4000 bytes Request msg INVITE/cseq=1 (rdata0xdd8b74) from UDP 222.222.222.1:5060:
INVITE sip:pi@222.222.222.222:5060;ob SIP/2.0
Via: SIP/2.0/UDP 222.222.222.1:5060;egress-zone=DefaultSubZone;branch=z9hG4bKa75e2d5ac3d58fa40ac094bf43746267907573.16b26c94fddfcc8e7270188161369418;proxy-call-id=a8e7a434-fd54-11e3-9bf8-0010f30f5a60;rport
Via: SIP/2.0/TLS 111.111.111.111:7029;egress-zone=OurUniversity;branch=z9hG4bK14b7b6b9ff6d433db7986af2d079fd5161301.8edbe65b1b55c77532ca579af82eea53;proxy-call-id=a8e1e0f8-fd54-11e3-89fe-0010f30f56f0;received=111.111.111.111;rport=7029;ingress-zone=OurUniversity
Via: SIP/2.0/TLS 9.9.9.9:5061;egress-zone=dnsout;branch=z9hG4bK0a581568f00c1d1218885d78c67f7e801214350.9ecf1523bb6f1feb6b39c5f18c6fe9e8;proxy-call-id=bc33e9ef-3d31-40ec-9417-c44e1323e9f6;received=9.9.9.9;rport=26521;ingress-zone=DefaultZone
Via: SIP/2.0/TCP 1.1.1.1:5060;branch=z9hG4bK1742939a;received=1.1.1.1;ingress-zone=DefaultSubZone
Call-ID: C67D8C82@1.1.1.1
CSeq: 1 INVITE
Contact: sip:8510@thierdomain.ac.uk;gr=urn:uuid:e42a7487-dd80-52f6-b21b-c718aaff8e59;isfocus
From: "CONFERENCE2" sip:8510@thierdomain.ac.uk;tag=71193C78D0B60001
To: sip:pi@mydomain.ac.uk
Max-Forwards: 13
Record-Route: sip:proxy-call-id=a8e7a434-fd54-11e3-9bf8-0010f30f5a60@222.222.222.1:5060;transport=udp;lr
Record-Route: sip:proxy-call-id=a8e7a434-fd54-11e3-9bf8-0010f30f5a60@222.222.222.1:5061;transport=tls;lr
Record-Route: sip:proxy-call-id=a8e1e0f8-fd54-11e3-89fe-0010f30f56f0@111.111.111.111:7029;transport=tls;lr
Record-Route: sip:proxy-call-id=a8e1e0f8-fd54-11e3-89fe-0010f30f56f0@111.111.111.111:5061;transport=tls;lr
Record-Route: sip:proxy-call-id=bc33e9ef-3d31-40ec-9417-c44e1323e9f6@9.9.9.9:5061;transport=tls;lr
Record-Route: sip:proxy-call-id=bc33e9ef-3d31-40ec-9417-c44e1323e9f6@9.9.9.9:5060;transport=tcp;lr
Allow: INVITE,ACK,CANCEL,OPTIONS,UPDATE,INFO,NOTIFY,BYE,REFER
User-Agent: Codian MSE 8510 v4.4 (3.42)
Supported: timer,outbound
Session-Expires: 500
X-TAATag: 74766091-9c9c-4970-bccb-77a8c4d6818d
Content-Type: application/sdp
Content-Length: 2661
v=0
o=CODIAN 800124289 800124289 IN IP4 1.1.1.1
s=-
i=Codian MSE 8510 v4.4 (3.42)
c=IN IP4 222.222.222.1
b=AS:2046
t=0 0
m=audio 51622 RTP/AVP 113 108 109 110 98 114 115 118 99 100 102 9 104 105 0 8 15 18 101
a=rtpmap:113 MP4A-LATM/90000
a=fmtp:113 profile-level-id=24;object=23;bitrate=96000
a=rtpmap:108 MP4A-LATM/90000
a=fmtp:108 profile-level-id=24;object=23;bitrate=64000
a=rtpmap:109 MP4A-LATM/90000
a=fmtp:109 profile-level-id=24;object=23;bitrate=56000
a=rtpmap:110 MP4A-LATM/90000
a=fmtp:110 profile-level-id=24;object=23;bitrate=48000
a=rtpmap:98 MP4A-LATM/32000
a=fmtp:98 profile-level-id=2;object=2;bitrate=96000
a=rtpmap:114 SIREN14/16000
a=fmtp:114 bitrate=48000
a=rtpmap:115 SIREN14/16000
a=fmtp:115 bitrate=32000
a=rtpmap:118 SIREN14/16000
a=fmtp:118 bitrate=24000
a=rtpmap:99 G7221/32000
a=fmtp:99 bitrate=48000
a=rtpmap:100 G7221/32000
a=fmtp:100 bitrate=32000
a=rtpmap:102 G7221/32000
a=fmtp:102 bitrate=24000
a=rtpmap:9 G722/8000
a=rtpmap:104 G7221/16000
a=fmtp:104 bitrate=32000
a=rtpmap:105 G7221/16000
a=fmtp:105 bitrate=24000
a=rtpmap:0 PCMU/8000/1
a=rtpmap:8 PCMA/8000/1
a=rtpmap:15 G728/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
m=video 51626 RTP/AVP 126 97 96 34 31
b=AS:2000
a=rtpmap:126 H264/90000
a=fmtp:126 profile-level-id=42e014;max-mbps=108000;max-fs=3600;max-dpb=5400;max-fps=6000;packetization-mode=1
a=rtpmap:97 H264/90000
a=fmtp:97 profile-level-id=42e014;max-mbps=108000;max-fs=3600;max-dpb=5400;max-fps=6000
a=rtpmap:96 H263-1998/90000
a=fmtp:96 CUSTOM=1280,720,1;CUSTOM=1024,768,1;CUSTOM=1024,576,1;CUSTOM=848,480,1;CIF4=1;CUSTOM=512,288,1;CIF=1;QCIF=1
a=rtpmap:34 H263/90000
a=fmtp:34 CIF4=1;CIF=1;QCIF=1
a=rtpmap:31 H261/90000
a=fmtp:31 CIF=1;QCIF=1
a=rtcp-fb:* nack pli
09:04:18.899 pjsua_call.c .Incoming Request msg INVITE/cseq=1 (rdata0xdd8b74)
09:04:18.900 tsx0xde1be4 ...Transaction created for Request msg INVITE/cseq=1 (rdata0xdd8b74)
09:04:18.900 tsx0xde1be4 ..Incoming Request msg INVITE/cseq=1 (rdata0xdd8b74) in state Null
09:04:18.900 tsx0xde1be4 ...State changed from Null to Trying, event=RX_MSG
09:04:18.900 dlg0xde0fcc ....Transaction tsx0xde1be4 state changed to Trying
09:04:18.900 dlg0xde0fcc ..UAS dialog created
09:04:18.900 dlg0xde0fcc ..Module mod-invite added as dialog usage, data=0xddd544
09:04:18.900 dlg0xde0fcc ...Session count inc to 2 by mod-invite
09:04:18.901 inv0xde0fcc ..UAS invite session created for dialog dlg0xde0fcc
09:04:18.901 pjsua_media.c ..Call 0: initializing media..
09:04:18.903 pjsua_media.c ...RTP socket reachable at 222.222.222.222:4000
09:04:18.903 pjsua_media.c ...RTCP socket reachable at 222.222.222.222:4001
09:04:18.903 pjsua_media.c ...Media index 0 selected for audio call 0
09:04:18.906 pjsua_call.c ..Call 0: remote NAT type is 0 (Unknown)
09:04:18.906 endpoint ...Response msg 100/INVITE/cseq=1 (tdta0xdec680) created
09:04:18.906 dlg0xde0fcc ...Initial answer Response msg 100/INVITE/cseq=1 (tdta0xdec680)
09:04:18.906 inv0xde0fcc ...Sending Response msg 100/INVITE/cseq=1 (tdta0xdec680)
09:04:18.906 dlg0xde0fcc ....Sending Response msg 100/INVITE/cseq=1 (tdta0xdec680)
09:04:18.906 tsx0xde1be4 ....Sending Response msg 100/INVITE/cseq=1 (tdta0xdec680) in state Trying
09:04:18.906 pjsua_core.c .....TX 1702 bytes Response msg 100/INVITE/cseq=1 (tdta0xdec680) to UDP 222.222.222.1:5060:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 222.222.222.1:5060;rport=5060;received=222.222.222.1;branch=z9hG4bKa75e2d5ac3d58fa40ac094bf43746267907573.16b26c94fddfcc8e7270188161369418;egress-zone=DefaultSubZone;proxy-call-id=a8e7a434-fd54-11e3-9bf8-0010f30f5a60
Via: SIP/2.0/TLS 111.111.111.111:7029;rport=7029;received=111.111.111.111;branch=z9hG4bK14b7b6b9ff6d433db7986af2d079fd5161301.8edbe65b1b55c77532ca579af82eea53;egress-zone=OurUniversity;proxy-call-id=a8e1e0f8-fd54-11e3-89fe-0010f30f56f0;ingress-zone=OurUniversity
Via: SIP/2.0/TLS 9.9.9.9:5061;rport=26521;received=9.9.9.9;branch=z9hG4bK0a581568f00c1d1218885d78c67f7e801214350.9ecf1523bb6f1feb6b39c5f18c6fe9e8;egress-zone=dnsout;proxy-call-id=bc33e9ef-3d31-40ec-9417-c44e1323e9f6;ingress-zone=DefaultZone
Via: SIP/2.0/TCP 1.1.1.1:5060;received=1.1.1.1;branch=z9hG4bK1742939a;ingress-zone=DefaultSubZone
Record-Route: sip:proxy-call-id=a8e7a434-fd54-11e3-9bf8-0010f30f5a60@222.222.222.1:5060;transport=udp;lr
Record-Route: sip:proxy-call-id=a8e7a434-fd54-11e3-9bf8-0010f30f5a60@222.222.222.1:5061;transport=tls;lr
Record-Route: sip:proxy-call-id=a8e1e0f8-fd54-11e3-89fe-0010f30f56f0@111.111.111.111:7029;transport=tls;lr
Record-Route: sip:proxy-call-id=a8e1e0f8-fd54-11e3-89fe-0010f30f56f0@111.111.111.111:5061;transport=tls;lr
Record-Route: sip:proxy-call-id=bc33e9ef-3d31-40ec-9417-c44e1323e9f6@9.9.9.9:5061;transport=tls;lr
Record-Route: sip:proxy-call-id=bc33e9ef-3d31-40ec-9417-c44e1323e9f6@9.9.9.9:5060;transport=tcp;lr
Call-ID: C67D8C82@1.1.1.1
From: "CONFERENCE2" sip:8510@thierdomain.ac.uk;tag=71193C78D0B60001
To: sip:pi@mydomain.ac.uk
CSeq: 1 INVITE
Content-Length: 0
--end msg--
09:04:18.908 tsx0xde1be4 .....State changed from Trying to Proceeding, event=TX_MSG
09:04:18.908 dlg0xde0fcc ......Transaction tsx0xde1be4 state changed to Proceeding
09:04:18.908 pjsua_aud.c ..Conf connect: 2 --> 0
09:04:18.911 pjsua_aud.c ...Set sound device: capture=2, playback=0
09:04:18.912 pjsua_app.c ....Turning sound device ON
09:04:18.913 pjsua_aud.c ....Opening sound device PCM@16000/1/20ms
09:04:18.915 alsa_dev.c .....open_playback: Open playback device 'default:CARD=ALSA'
09:04:18.942 alsa_dev.c .....Opened device alsa(default:CARD=ALSA) for playing, sample rate=16000, ch=1, bits=16, period size=320 frames, latency=140 ms
09:04:18.942 alsa_dev.c .....open_capture: Open capture device 'default:CARD=Dummy'
09:04:18.950 alsa_dev.c .....Opened device alsa(default:CARD=Dummy) for capture, sample rate=16000, ch=1, bits=16, period size=320 frames, latency=100 ms
09:04:18.950 ec0xdf1a28 .....Creating AEC
09:04:18.953 ec0xdf1a28 .....AEC created, clock_rate=16000, channel=1, samples per frame=320, tail length=200 ms, latency=0 ms
09:04:18.955 conference.c ...Port 2 (ring) transmitting to port 0 (default:CARD=Dummy)
09:04:18.956 pjsua_call.c ..Answering call 0: code=200
09:04:18.959 inv0xde0fcc ....SDP negotiation done, status=0
09:04:18.960 pjsua_call.c .....Call 0: remote NAT type is 0 (Unknown)
09:04:18.960 pjsua_media.c .....Call 0: updating media..
09:04:18.961 alsa_dev.c !ca_thread_func(10075): Set thread priority for audio capture thread.
09:04:18.961 alsa_dev.c Unable to increase thread priority, root access needed.
09:04:18.962 pjsua_aud.c !......Audio channel update..
09:04:18.964 speex !warning: discarded first playback frame
09:04:18.965 speex warning: discarded first playback frame
09:04:18.964 strm0xe1de84 !.......VAD temporarily disabled
09:04:18.967 rtp.c .......pjmedia_rtp_session_init: ses=0xe20510, default_pt=9, ssrc=0x55a981c0
09:04:18.967 rtp.c .......pjmedia_rtp_session_init: ses=0xe20b98, default_pt=9, ssrc=0x55a981c0
09:04:18.967 stream.c .......Stream strm0xe1de84 created
09:04:18.967 strm0xe1de84 .......Encoder stream started
09:04:18.969 speex !warning: discarded first playback frame
09:04:18.970 speex warning: discarded first playback frame
09:04:18.970 speex warning: discarded first playback frame
09:04:18.970 speex warning: discarded first playback frame
09:04:18.970 strm0xe1de84 !.......Decoder stream started
09:04:18.972 speex !warning: discarded first playback frame
09:04:18.973 pjsua_media.c !......Audio updated, stream #0: G722 (sendrecv)
09:04:18.975 strm0xe1de84 !Start talksprut..
09:04:18.975 speex warning: discarded first playback frame
09:04:18.976 pjsua_media.c !......Error updating media call00:1: Invalid media type (PJMEDIA_EINVALIMEDIATYPE)
09:04:18.977 pjsua_media.c ......Error updating media call00:2: Invalid media type (PJMEDIA_EINVALIMEDIATYPE)
09:04:18.979 pjsua_app.c .....Call 0 media 0 [type=audio], status is Active
09:04:18.981 pjsua_aud.c .....Conf disconnect: 2 -x- 0
09:04:18.988 conference.c ......Port 2 (ring) stop transmitting to port 0 (default:CARD=Dummy)
09:04:18.990 pjsua_aud.c .....Conf connect: 3 --> 0
09:04:18.992 conference.c ......Port 3 (sip:8510@thierdomain.ac.uk) transmitting to port 0 (default:CARD=Dummy)
09:04:18.993 pjsua_aud.c .....Conf connect: 0 --> 3
09:04:18.995 conference.c ......Port 0 (default:CARD=Dummy) transmitting to port 3 (sip:8510@thierdomain.ac.uk)
09:04:18.997 inv0xde0fcc ....Sending Response msg 200/INVITE/cseq=1 (tdta0xdec680)
09:04:18.997 dlg0xde0fcc .....Sending Response msg 200/INVITE/cseq=1 (tdta0xdec680)
09:04:18.997 tsx0xde1be4 .....Sending Response msg 200/INVITE/cseq=1 (tdta0xdec680) in state Proceeding
09:04:18.998 pjsua_core.c ......TX 2399 bytes Response msg 200/INVITE/cseq=1 (tdta0xdec680) to UDP 222.222.222.1:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 222.222.222.1:5060;rport=5060;received=222.222.222.1;branch=z9hG4bKa75e2d5ac3d58fa40ac094bf43746267907573.16b26c94fddfcc8e7270188161369418;egress-zone=DefaultSubZone;proxy-call-id=a8e7a434-fd54-11e3-9bf8-0010f30f5a60
Via: SIP/2.0/TLS 111.111.111.111:7029;rport=7029;received=111.111.111.111;branch=z9hG4bK14b7b6b9ff6d433db7986af2d079fd5161301.8edbe65b1b55c77532ca579af82eea53;egress-zone=OurUniversity;proxy-call-id=a8e1e0f8-fd54-11e3-89fe-0010f30f56f0;ingress-zone=OurUniversity
Via: SIP/2.0/TLS 9.9.9.9:5061;rport=26521;received=9.9.9.9;branch=z9hG4bK0a581568f00c1d1218885d78c67f7e801214350.9ecf1523bb6f1feb6b39c5f18c6fe9e8;egress-zone=dnsout;proxy-call-id=bc33e9ef-3d31-40ec-9417-c44e1323e9f6;ingress-zone=DefaultZone
Via: SIP/2.0/TCP 1.1.1.1:5060;received=1.1.1.1;branch=z9hG4bK1742939a;ingress-zone=DefaultSubZone
Record-Route: sip:proxy-call-id=a8e7a434-fd54-11e3-9bf8-0010f30f5a60@222.222.222.1:5060;transport=udp;lr
Record-Route: sip:proxy-call-id=a8e7a434-fd54-11e3-9bf8-0010f30f5a60@222.222.222.1:5061;transport=tls;lr
Record-Route: sip:proxy-call-id=a8e1e0f8-fd54-11e3-89fe-0010f30f56f0@111.111.111.111:7029;transport=tls;lr
Record-Route: sip:proxy-call-id=a8e1e0f8-fd54-11e3-89fe-0010f30f56f0@111.111.111.111:5061;transport=tls;lr
Record-Route: sip:proxy-call-id=bc33e9ef-3d31-40ec-9417-c44e1323e9f6@9.9.9.9:5061;transport=tls;lr
Record-Route: sip:proxy-call-id=bc33e9ef-3d31-40ec-9417-c44e1323e9f6@9.9.9.9:5060;transport=tcp;lr
Call-ID: C67D8C82@1.1.1.1
From: "CONFERENCE2" sip:8510@thierdomain.ac.uk;tag=71193C78D0B60001
To: sip:pi@mydomain.ac.uk;tag=R3gbdJaQddbbOldCYhjdJ5Y5CaXTtRra
CSeq: 1 INVITE
Contact: sip:pi@222.222.222.222:5060;ob
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Session-Expires: 500;refresher=uac
Require: timer
Content-Type: application/sdp
Content-Length: 391
v=0
o=- 3605072658 3605072659 IN IP4 222.222.222.222
s=pjmedia
b=AS:84
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 9 101
c=IN IP4 222.222.222.222
b=TIAS:64000
a=rtcp:4001 IN IP4 222.222.222.222
a=sendrecv
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
m=video 0 RTP/AVP 126 97 96 34 31
c=IN IP4 127.0.0.1
b=AS:2000
m=application 0 UDP/BFCP *
c=IN IP4 127.0.0.1
--end msg--
09:04:19.020 strm0xe1de84 !Jitter buffer is bufferring (prefetch=0), plc invoked
09:04:19.021 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:19.021 Master/sound Underflow, buf_cnt=0, will generate 1 frame
09:04:19.037 tsx0xde1be4 !......State changed from Proceeding to Completed, event=TX_MSG
09:04:19.037 dlg0xde0fcc .......Transaction tsx0xde1be4 state changed to Completed
09:04:19.037 pjsua_app.c .........Call 0 state changed to CONNECTING
09:04:19.039 Master/sound !Underflow, buf_cnt=0, will generate 1 frame
09:04:19.043 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:19.074 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:19.154 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:19.155 sip_endpoint.c !Processing incoming message: Request msg ACK/cseq=1 (rdata0xdd8b74)
09:04:19.155 pjsua_core.c .RX 1303 bytes Request msg ACK/cseq=1 (rdata0xdd8b74) from UDP 222.222.222.1:5060:
ACK sip:pi@222.222.222.222:5060;ob SIP/2.0
Via: SIP/2.0/UDP 222.222.222.1:5060;egress-zone=DefaultSubZone;branch=z9hG4bKeb907f62b074f8adf7c7e96b765cbdd2907575.16b26c94fddfcc8e7270188161369418;proxy-call-id=a8e7a434-fd54-11e3-9bf8-0010f30f5a60;rport
Via: SIP/2.0/TLS 111.111.111.111:7029;egress-zone=OurUniversity;branch=z9hG4bK5b01ac49ed365b43d7187b1cefbeb55a61302.8edbe65b1b55c77532ca579af82eea53;proxy-call-id=a8e1e0f8-fd54-11e3-89fe-0010f30f56f0;received=111.111.111.111;rport=7029;ingress-zone=OurUniversity
Via: SIP/2.0/TLS 9.9.9.9:5061;egress-zone=dnsout;branch=z9hG4bK229006a387aa5b022c13fac064f2ac0d1214351.9ecf1523bb6f1feb6b39c5f18c6fe9e8;proxy-call-id=bc33e9ef-3d31-40ec-9417-c44e1323e9f6;received=9.9.9.9;rport=26521;ingress-zone=DefaultZone
Via: SIP/2.0/TCP 1.1.1.1:5060;branch=z9hG4bK1742939b;received=1.1.1.1;ingress-zone=DefaultSubZone
Call-ID: C67D8C82@1.1.1.1
CSeq: 1 ACK
Contact: sip:8510@thierdomain.ac.uk;gr=urn:uuid:e42a7487-dd80-52f6-b21b-c718aaff8e59;isfocus
From: "CONFERENCE2" sip:8510@thierdomain.ac.uk;tag=71193C78D0B60001
To: sip:pi@mydomain.ac.uk;tag=R3gbdJaQddbbOldCYhjdJ5Y5CaXTtRra
Max-Forwards: 67
User-Agent: Codian MSE 8510 v4.4 (3.42)
Supported: timer,outbound
X-TAATag: 74766091-9c9c-4970-bccb-77a8c4d6818d
Content-Length: 0
--end msg--
09:04:19.163 dlg0xde0fcc .Received Request msg ACK/cseq=1 (rdata0xdd8b74)
09:04:19.163 tsx0xde1be4 ..Request to terminate transaction
09:04:19.163 tsx0xde1be4 ...State changed from Completed to Terminated, event=USER
09:04:19.163 dlg0xde0fcc ....Transaction tsx0xde1be4 state changed to Terminated
09:04:19.163 pjsua_app.c ...Call 0 state changed to CONFIRMED
09:04:19.168 tsx0xde1be4 Timeout timer event
09:04:19.168 tsx0xde1be4 .State changed from Terminated to Destroyed, event=TIMER
09:04:19.169 tdta0xdec680 ..Destroying txdata Response msg 200/INVITE/cseq=1 (tdta0xdec680)
09:04:19.169 tsx0xde1be4 Transaction destroyed!
09:04:19.176 speex !warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:19.177 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:19.214 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:19.294 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:19.296 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:19.352 strm0xe1de84 !RTP status: badpt=0, badssrc=0, dup=0, outorder=0, probation=-1, restart=0
09:04:19.352 stream.c G722 codec used, remote samples per frame detected = 80
09:04:19.355 strm0xe1de84 !Jitter buffer starts returning normal frames (after 17 empty/lost)
09:04:19.434 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:19.437 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:19.574 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:19.577 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:19.603 strm0xe1de84 VAD re-enabled
09:04:19.605 strm0xe1de84 Starting silence
09:04:19.713 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:19.715 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:19.853 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:19.855 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:19.895 sip_endpoint.c !Processing incoming message: Request msg UPDATE/cseq=2 (rdata0xdd8b74)
09:04:19.895 pjsua_core.c .RX 4000 bytes Request msg UPDATE/cseq=2 (rdata0xdd8b74) from UDP 222.222.222.1:5060:
UPDATE sip:pi@222.222.222.222:5060;ob SIP/2.0
Via: SIP/2.0/UDP 222.222.222.1:5060;egress-zone=DefaultSubZone;branch=z9hG4bK3c0043c58d581648f53497392dfb3fbe907576.16b26c94fddfcc8e7270188161369418;proxy-call-id=a8e7a434-fd54-11e3-9bf8-0010f30f5a60;rport
Via: SIP/2.0/TLS 111.111.111.111:7029;egress-zone=OurUniversity;branch=z9hG4bK8cf7d6c613301828071f2c6e74492f9a61303.8edbe65b1b55c77532ca579af82eea53;proxy-call-id=a8e1e0f8-fd54-11e3-89fe-0010f30f56f0;received=111.111.111.111;rport=7029;ingress-zone=OurUniversity
Via: SIP/2.0/TLS 9.9.9.9:5061;egress-zone=dnsout;branch=z9hG4bKde3fda41e0b160af58904f51bd71ef461214352.9ecf1523bb6f1feb6b39c5f18c6fe9e8;proxy-call-id=bc33e9ef-3d31-40ec-9417-c44e1323e9f6;received=9.9.9.9;rport=26521;ingress-zone=DefaultZone
Via: SIP/2.0/TCP 1.1.1.1:5060;branch=z9hG4bK1742939c;received=1.1.1.1;ingress-zone=DefaultSubZone
Call-ID: C67D8C82@1.1.1.1
CSeq: 2 UPDATE
Contact: sip:8510@thierdomain.ac.uk;gr=urn:uuid:e42a7487-dd80-52f6-b21b-c718aaff8e59;isfocus
From: "CONFERENCE2" sip:8510@thierdomain.ac.uk;tag=71193C78D0B60001
To: sip:pi@mydomain.ac.uk;tag=R3gbdJaQddbbOldCYhjdJ5Y5CaXTtRra
Max-Forwards: 67
Record-Route: sip:proxy-call-id=a8e7a434-fd54-11e3-9bf8-0010f30f5a60@222.222.222.1:5060;transport=udp;lr
Record-Route: sip:proxy-call-id=a8e7a434-fd54-11e3-9bf8-0010f30f5a60@222.222.222.1:5061;transport=tls;lr
Record-Route: sip:proxy-call-id=a8e1e0f8-fd54-11e3-89fe-0010f30f56f0@111.111.111.111:7029;transport=tls;lr
Record-Route: sip:proxy-call-id=a8e1e0f8-fd54-11e3-89fe-0010f30f56f0@111.111.111.111:5061;transport=tls;lr
Record-Route: sip:proxy-call-id=bc33e9ef-3d31-40ec-9417-c44e1323e9f6@9.9.9.9:5061;transport=tls;lr
Record-Route: sip:proxy-call-id=bc33e9ef-3d31-40ec-9417-c44e1323e9f6@9.9.9.9:5060;transport=tcp;lr
User-Agent: Codian MSE 8510 v4.4 (3.42)
Supported: timer,outbound
Session-Expires: 500;refresher=uac
X-TAATag: 74766091-9c9c-4970-bccb-77a8c4d6818d
Content-Type: application/sdp
Content-Length: 2661
v=0
o=CODIAN 800124289 800124290 IN IP4 1.1.1.1
s=-
i=Codian MSE 8510 v4.4 (3.42)
c=IN IP4 222.222.222.1
b=AS:2046
t=0 0
m=audio 51622 RTP/AVP 113 108 109 110 98 114 115 118 99 100 102 9 104 105 0 8 15 18 101
a=rtpmap:113 MP4A-LATM/90000
a=fmtp:113 profile-level-id=24;object=23;bitrate=96000
a=rtpmap:108 MP4A-LATM/90000
a=fmtp:108 profile-level-id=24;object=23;bitrate=64000
a=rtpmap:109 MP4A-LATM/90000
a=fmtp:109 profile-level-id=24;object=23;bitrate=56000
a=rtpmap:110 MP4A-LATM/90000
a=fmtp:110 profile-level-id=24;object=23;bitrate=48000
a=rtpmap:98 MP4A-LATM/32000
a=fmtp:98 profile-level-id=2;object=2;bitrate=96000
a=rtpmap:114 SIREN14/16000
a=fmtp:114 bitrate=48000
a=rtpmap:115 SIREN14/16000
a=fmtp:115 bitrate=32000
a=rtpmap:118 SIREN14/16000
a=fmtp:118 bitrate=24000
a=rtpmap:99 G7221/32000
a=fmtp:99 bitrate=48000
a=rtpmap:100 G7221/32000
a=fmtp:100 bitrate=32000
a=rtpmap:102 G7221/32000
a=fmtp:102 bitrate=24000
a=rtpmap:9 G722/8000
a=rtpmap:104 G7221/16000
a=fmtp:104 bitrate=32000
a=rtpmap:105 G7221/16000
a=fmtp:105 bitrate=24000
a=rtpmap:0 PCMU/8000/1
a=rtpmap:8 PCMA/8000/1
a=rtpmap:15 G728/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
m=video 51636 RTP/AVP 126 97 96 34 31
b=AS:2000
a=rtpmap:126 H264/90000
a=fmtp:126 profile-level-id=42e014;max-mbps=108000;max-fs=3600;max-dpb=5400;max-fps=6000;packetization-mode=1
a=rtpmap:97 H264/90000
a=fmtp:97 profile-level-id=42e014;max-mbps=108000;max-fs=3600;max-dpb=5400;max-fps=6000
a=rtpmap:96 H263-1998/90000
a=fmtp:96 CUSTOM=1280,720,1;CUSTOM=1024,768,1;CUSTOM=1024,576,1;CUSTOM=848,480,1;CIF4=1;CUSTOM=512,288,1;CIF=1;QCIF=1
a=rtpmap:34 H263/90000
a=fmtp:34 CIF4=1;CIF=1;QCIF=1
a=rtpmap:31 H261/90000
a=fmtp:31 CIF=1;QCIF=1
a=rtcp-fb:* nack pli
a=rtcp-fb:*
09:04:19.906 dlg0xde0fcc .Received Request msg UPDATE/cseq=2 (rdata0xdd8b74)
09:04:19.906 tsx0xde1be4 ...Transaction created for Request msg UPDATE/cseq=2 (rdata0xdd8b74)
09:04:19.906 tsx0xde1be4 ..Incoming Request msg UPDATE/cseq=2 (rdata0xdd8b74) in state Null
09:04:19.906 tsx0xde1be4 ...State changed from Null to Trying, event=RX_MSG
09:04:19.906 dlg0xde0fcc ....Transaction tsx0xde1be4 state changed to Trying
09:04:19.907 inv0xde0fcc .....Got SDP offer in Request msg UPDATE/cseq=2 (rdata0xdd8b74)
09:04:19.907 pjsua_call.c .....Call 0: received updated media offer
09:04:19.914 strm0xe1de84 !Frame lost, recovered!
09:04:19.914 strm0xe1de84 Jitter buffer starts returning normal frames (after 1 empty/lost)
09:04:19.915 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:19.918 pjsua_media.c !......Call 0: re-initializing media..
09:04:19.923 pjsua_media.c .......Media index 0 selected for audio call 0
09:04:19.924 inv0xde0fcc .....SDP negotiation done, status=0
09:04:19.925 pjsua_call.c ......Call 0: remote NAT type is 0 (Unknown)
09:04:19.925 pjsua_media.c ......Call 0: updating media..
09:04:19.940 pjsua_media.c .......Call 0: stream #0 (audio) unchanged.
09:04:19.942 pjsua_media.c .......Audio updated, stream #0: G722 (sendrecv)
09:04:19.945 pjsua_media.c .......Error updating media call00:1: Invalid media type (PJMEDIA_EINVALIMEDIATYPE)
09:04:19.946 pjsua_media.c .......Error updating media call00:2: Invalid media type (PJMEDIA_EINVALIMEDIATYPE)
09:04:19.946 pjsua_app.c ......Call 0 media 0 [type=audio], status is Active
09:04:19.948 pjsua_aud.c ......Conf connect: 3 --> 0
09:04:19.948 pjsua_aud.c ......Conf connect: 0 --> 3
09:04:19.948 endpoint .....Response msg 200/UPDATE/cseq=2 (tdta0xddf020) created
09:04:19.949 dlg0xde0fcc ......Sending Response msg 200/UPDATE/cseq=2 (tdta0xddf020)
09:04:19.949 tsx0xde1be4 ......Sending Response msg 200/UPDATE/cseq=2 (tdta0xddf020) in state Trying
09:04:19.949 pjsua_core.c .......TX 2399 bytes Response msg 200/UPDATE/cseq=2 (tdta0xddf020) to UDP 222.222.222.1:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 222.222.222.1:5060;rport=5060;received=222.222.222.1;branch=z9hG4bK3c0043c58d581648f53497392dfb3fbe907576.16b26c94fddfcc8e7270188161369418;egress-zone=DefaultSubZone;proxy-call-id=a8e7a434-fd54-11e3-9bf8-0010f30f5a60
Via: SIP/2.0/TLS 111.111.111.111:7029;rport=7029;received=111.111.111.111;branch=z9hG4bK8cf7d6c613301828071f2c6e74492f9a61303.8edbe65b1b55c77532ca579af82eea53;egress-zone=OurUniversity;proxy-call-id=a8e1e0f8-fd54-11e3-89fe-0010f30f56f0;ingress-zone=OurUniversity
Via: SIP/2.0/TLS 9.9.9.9:5061;rport=26521;received=9.9.9.9;branch=z9hG4bKde3fda41e0b160af58904f51bd71ef461214352.9ecf1523bb6f1feb6b39c5f18c6fe9e8;egress-zone=dnsout;proxy-call-id=bc33e9ef-3d31-40ec-9417-c44e1323e9f6;ingress-zone=DefaultZone
Via: SIP/2.0/TCP 1.1.1.1:5060;received=1.1.1.1;branch=z9hG4bK1742939c;ingress-zone=DefaultSubZone
Record-Route: sip:proxy-call-id=a8e7a434-fd54-11e3-9bf8-0010f30f5a60@222.222.222.1:5060;transport=udp;lr
Record-Route: sip:proxy-call-id=a8e7a434-fd54-11e3-9bf8-0010f30f5a60@222.222.222.1:5061;transport=tls;lr
Record-Route: sip:proxy-call-id=a8e1e0f8-fd54-11e3-89fe-0010f30f56f0@111.111.111.111:7029;transport=tls;lr
Record-Route: sip:proxy-call-id=a8e1e0f8-fd54-11e3-89fe-0010f30f56f0@111.111.111.111:5061;transport=tls;lr
Record-Route: sip:proxy-call-id=bc33e9ef-3d31-40ec-9417-c44e1323e9f6@9.9.9.9:5061;transport=tls;lr
Record-Route: sip:proxy-call-id=bc33e9ef-3d31-40ec-9417-c44e1323e9f6@9.9.9.9:5060;transport=tcp;lr
Call-ID: C67D8C82@1.1.1.1
From: "CONFERENCE2" sip:8510@thierdomain.ac.uk;tag=71193C78D0B60001
To: sip:pi@mydomain.ac.uk;tag=R3gbdJaQddbbOldCYhjdJ5Y5CaXTtRra
CSeq: 2 UPDATE
Session-Expires: 500;refresher=uac
Require: timer
Contact: sip:pi@222.222.222.222:5060;ob
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Content-Type: application/sdp
Content-Length: 391
v=0
o=- 3605072658 3605072660 IN IP4 222.222.222.222
s=pjmedia
b=AS:84
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 9 101
c=IN IP4 222.222.222.222
b=TIAS:64000
a=rtcp:4001 IN IP4 222.222.222.222
a=sendrecv
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
m=video 0 RTP/AVP 126 97 96 34 31
c=IN IP4 127.0.0.1
b=AS:2000
m=application 0 UDP/BFCP *
c=IN IP4 127.0.0.1
--end msg--
09:04:19.964 tsx0xde1be4 .......State changed from Trying to Completed, event=TX_MSG
09:04:19.964 dlg0xde0fcc ........Transaction tsx0xde1be4 state changed to Completed
09:04:19.991 speex !warning: No playback frame available (your application is buggy and/or got xruns)
09:04:19.993 speex !warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:19.995 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:20.083 strm0xe1de84 Frame lost, recovered!
09:04:20.133 strm0xe1de84 Jitter buffer starts returning normal frames (after 1 empty/lost)
09:04:20.133 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:20.135 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:20.273 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:20.275 strm0xe1de84 Frame lost, recovered!
09:04:20.275 strm0xe1de84 Jitter buffer starts returning normal frames (after 1 empty/lost)
09:04:20.275 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:20.413 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:20.415 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:20.474 strm0xe1de84 Frame lost, recovered!
09:04:20.474 strm0xe1de84 Jitter buffer starts returning normal frames (after 1 empty/lost)
09:04:20.553 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:20.555 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:20.693 strm0xe1de84 Frame lost, recovered!
09:04:20.694 strm0xe1de84 Jitter buffer starts returning normal frames (after 1 empty/lost)
09:04:20.694 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:20.695 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:20.833 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:20.835 strm0xe1de84 Frame lost, recovered!
09:04:20.835 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:20.863 strm0xe1de84 Jitter buffer starts returning normal frames (after 1 empty/lost)
09:04:20.973 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:20.975 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:21.034 strm0xe1de84 Frame lost, recovered!
09:04:21.034 strm0xe1de84 Jitter buffer starts returning normal frames (after 1 empty/lost)
09:04:21.113 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:21.115 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:21.254 strm0xe1de84 Frame lost, recovered!
09:04:21.254 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:21.255 strm0xe1de84 Jitter buffer starts returning normal frames (after 1 empty/lost)
09:04:21.256 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:21.393 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:21.395 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:21.454 strm0xe1de84 Frame lost, recovered!
09:04:21.454 strm0xe1de84 Jitter buffer starts returning normal frames (after 1 empty/lost)
09:04:21.533 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:21.535 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:21.673 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:21.675 strm0xe1de84 Frame lost, recovered!
09:04:21.675 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:21.703 strm0xe1de84 Jitter buffer starts returning normal frames (after 1 empty/lost)
09:04:21.813 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:21.815 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:21.903 strm0xe1de84 Frame lost, recovered!
09:04:21.904 strm0xe1de84 Jitter buffer starts returning normal frames (after 1 empty/lost)
09:04:21.953 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:21.954 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:22.093 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:22.095 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:22.154 strm0xe1de84 Frame lost, recovered!
09:04:22.183 strm0xe1de84 Jitter buffer starts returning normal frames (after 1 empty/lost)
09:04:22.233 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:22.234 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:22.373 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:22.375 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:22.434 strm0xe1de84 Frame lost, recovered!
09:04:22.434 strm0xe1de84 Jitter buffer starts returning normal frames (after 1 empty/lost)
09:04:22.513 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:22.515 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:22.653 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:22.655 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:22.793 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:22.795 strm0xe1de84 Frame lost, recovered!
09:04:22.795 strm0xe1de84 Jitter buffer starts returning normal frames (after 1 empty/lost)
09:04:22.795 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:22.933 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:22.935 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:23.073 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:23.075 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:23.213 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:23.215 strm0xe1de84 Frame lost, recovered!
09:04:23.215 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:23.243 strm0xe1de84 Jitter buffer starts returning normal frames (after 1 empty/lost)
09:04:23.353 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:23.355 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:23.493 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:23.495 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:23.583 silencedet.c Re-adjust threshold (in silence)to 0
09:04:23.633 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:23.635 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:23.773 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:23.775 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:23.834 strm0xe1de84 Start talksprut..
09:04:23.863 strm0xe1de84 Starting silence
09:04:23.913 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:23.915 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:24.053 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:24.055 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:24.193 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:24.194 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:24.333 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:24.335 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:24.473 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:24.475 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:24.613 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:24.615 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:24.753 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:24.754 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:24.893 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:24.895 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:25.033 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:25.035 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:25.173 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:25.175 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:25.313 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:25.314 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:25.453 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:25.455 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:25.593 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:25.594 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:25.733 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:25.735 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:25.873 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:25.874 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:26.013 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:26.015 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:26.153 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:26.154 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:26.293 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:26.294 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:26.433 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:26.435 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:26.573 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:26.575 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:26.713 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:26.714 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:26.853 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:26.854 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:26.987 pjsua_acc.c !Sending 2 bytes keep-alive packet for acc 3 to 222.222.222.1:5060
09:04:26.988 tdta0xde9810 Destroying txdata raw
09:04:26.993 speex !warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:26.994 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:27.133 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:27.134 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:27.273 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:27.275 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:27.413 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:27.415 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:27.553 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:27.554 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:27.583 silencedet.c Re-adjust threshold (in silence)to 0
09:04:27.693 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:27.694 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:27.833 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:27.834 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:27.973 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:27.975 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:28.113 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:28.114 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:28.253 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:28.255 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:28.393 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:28.394 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:28.531 sip_endpoint.c !Processing incoming message: Request msg BYE/cseq=3 (rdata0xdd8b74)
09:04:28.531 pjsua_core.c .RX 1303 bytes Request msg BYE/cseq=3 (rdata0xdd8b74) from UDP 222.222.222.1:5060:
BYE sip:pi@222.222.222.222:5060;ob SIP/2.0
Via: SIP/2.0/UDP 222.222.222.1:5060;egress-zone=DefaultSubZone;branch=z9hG4bK47e0c65c8faea51fa147e2fbed45470c907579.16b26c94fddfcc8e7270188161369418;proxy-call-id=a8e7a434-fd54-11e3-9bf8-0010f30f5a60;rport
Via: SIP/2.0/TLS 111.111.111.111:7029;egress-zone=OurUniversity;branch=z9hG4bK786e05648a0b2de9f7035391276d21d561304.8edbe65b1b55c77532ca579af82eea53;proxy-call-id=a8e1e0f8-fd54-11e3-89fe-0010f30f56f0;received=111.111.111.111;rport=7029;ingress-zone=OurUniversity
Via: SIP/2.0/TLS 9.9.9.9:5061;egress-zone=dnsout;branch=z9hG4bK6002b76c47dc04becb08051a3f37b7371214355.9ecf1523bb6f1feb6b39c5f18c6fe9e8;proxy-call-id=bc33e9ef-3d31-40ec-9417-c44e1323e9f6;received=9.9.9.9;rport=26521;ingress-zone=DefaultZone
Via: SIP/2.0/TCP 1.1.1.1:5060;branch=z9hG4bK1742939d;received=1.1.1.1;ingress-zone=DefaultSubZone
Call-ID: C67D8C82@1.1.1.1
CSeq: 3 BYE
Contact: sip:8510@thierdomain.ac.uk;gr=urn:uuid:e42a7487-dd80-52f6-b21b-c718aaff8e59;isfocus
From: "CONFERENCE2" sip:8510@thierdomain.ac.uk;tag=71193C78D0B60001
To: sip:pi@mydomain.ac.uk;tag=R3gbdJaQddbbOldCYhjdJ5Y5CaXTtRra
Max-Forwards: 67
User-Agent: Codian MSE 8510 v4.4 (3.42)
Supported: timer,outbound
X-TAATag: 74766091-9c9c-4970-bccb-77a8c4d6818d
Content-Length: 0
--end msg--
09:04:28.535 strm0xe1de84 !Jitter buffer empty (prefetch=0), plc invoked
09:04:28.536 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:28.547 dlg0xde0fcc !.Received Request msg BYE/cseq=3 (rdata0xdd8b74)
09:04:28.547 tsx0xe1d124 ...Transaction created for Request msg BYE/cseq=3 (rdata0xdd8b74)
09:04:28.547 tsx0xe1d124 ..Incoming Request msg BYE/cseq=3 (rdata0xdd8b74) in state Null
09:04:28.547 tsx0xe1d124 ...State changed from Null to Trying, event=RX_MSG
09:04:28.547 dlg0xde0fcc ....Transaction tsx0xe1d124 state changed to Trying
09:04:28.547 endpoint .....Response msg 200/BYE/cseq=3 (tdta0xde9810) created
09:04:28.548 dlg0xde0fcc ......Sending Response msg 200/BYE/cseq=3 (tdta0xde9810)
09:04:28.548 tsx0xe1d124 ......Sending Response msg 200/BYE/cseq=3 (tdta0xde9810) in state Trying
09:04:28.548 pjsua_core.c .......TX 1080 bytes Response msg 200/BYE/cseq=3 (tdta0xde9810) to UDP 222.222.222.1:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 222.222.222.1:5060;rport=5060;received=222.222.222.1;branch=z9hG4bK47e0c65c8faea51fa147e2fbed45470c907579.16b26c94fddfcc8e7270188161369418;egress-zone=DefaultSubZone;proxy-call-id=a8e7a434-fd54-11e3-9bf8-0010f30f5a60
Via: SIP/2.0/TLS 111.111.111.111:7029;rport=7029;received=111.111.111.111;branch=z9hG4bK786e05648a0b2de9f7035391276d21d561304.8edbe65b1b55c77532ca579af82eea53;egress-zone=OurUniversity;proxy-call-id=a8e1e0f8-fd54-11e3-89fe-0010f30f56f0;ingress-zone=OurUniversity
Via: SIP/2.0/TLS 9.9.9.9:5061;rport=26521;received=9.9.9.9;branch=z9hG4bK6002b76c47dc04becb08051a3f37b7371214355.9ecf1523bb6f1feb6b39c5f18c6fe9e8;egress-zone=dnsout;proxy-call-id=bc33e9ef-3d31-40ec-9417-c44e1323e9f6;ingress-zone=DefaultZone
Via: SIP/2.0/TCP 1.1.1.1:5060;received=1.1.1.1;branch=z9hG4bK1742939d;ingress-zone=DefaultSubZone
Call-ID: C67D8C82@1.1.1.1
From: "CONFERENCE2" sip:8510@thierdomain.ac.uk;tag=71193C78D0B60001
To: sip:pi@mydomain.ac.uk;tag=R3gbdJaQddbbOldCYhjdJ5Y5CaXTtRra
CSeq: 3 BYE
Content-Length: 0
--end msg--
09:04:28.551 speex !warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:28.572 tsx0xe1d124 !.......State changed from Trying to Completed, event=TX_MSG
09:04:28.572 dlg0xde0fcc ........Transaction tsx0xe1d124 state changed to Completed
09:04:28.572 pjsua_app.c ......Call 0 is DISCONNECTED [reason=200 (Normal call clearing)]
09:04:28.575 pjsua_app.c ......Call 0 disconnected, dumping media stats..
09:04:28.576 pjsua_app_comm ......
[DISCONNCTD] To: "CONFERENCE2" sip:8510@thierdomain.ac.uk;tag=71193C78D0B60001
Call time: 00h:00m:09s, 1st res in 59 ms, conn in 264ms
#0 audio G722 @16kHz, sendrecv, peer=222.222.222.1:51622
SRTP status: Not active Crypto-suite:
RX pt=9, last update:00h:00m:09.221s ago
total 467pkt 74.7KB (93.4KB +IP hdr) @avg=62.2Kbps/77.7Kbps
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.210 0.875 0.125 0.154
TX pt=9, ptime=20, last update:00h:00m:02.478s ago
total 32pkt 5.0KB (6.3KB +IP hdr) @avg=4.1Kbps/5.2Kbps
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 : 4.875 4.875 4.875 4.875 0.000
RTT msec : 0.000 0.000 0.000 0.000 0.000
#1 unknown deactivated
#2 unknown deactivated
09:04:28.582 pjsua_media.c ......Call 0: deinitializing media..
09:04:28.583 strm0xe1de84 ........JB summary:
size=0/eff=0 prefetch=0 level=4
delay (min/max/avg/dev)=70/240/106/47 ms
burst (min/max/avg/dev)=2/4/3/0 frames
lost=15 discard=0 empty=21
09:04:28.583 pjsua_media.c ........Media stream call00:0 is destroyed
09:04:28.584 pjsua_media.c ........Media stream call00:1 is destroyed
09:04:28.584 pjsua_media.c ........Media stream call00:2 is destroyed
09:04:28.584 dlg0xde0fcc ......Session count dec to 3 by mod-invite
09:04:28.596 speex !warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:28.596 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:28.621 speex !warning: No playback frame available (your application is buggy and/or got xruns)
09:04:28.622 speex !warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:28.673 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:28.673 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:28.812 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:28.813 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:28.953 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:28.953 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:29.092 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:29.093 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:29.232 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:29.233 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:29.372 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:29.373 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:29.513 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:29.513 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:29.582 pjsua_aud.c !Closing sound device after idle for 1 second(s)
09:04:29.584 pjsua_app.c .Turning sound device OFF
09:04:29.585 pjsua_aud.c .Closing default:CARD=ALSA sound playback device and default:CARD=Dummy sound capture device
09:04:41.417 pjsua_core.c !Shutting down, flags=0...
09:04:41.418 pjsua_core.c PJSUA state changed: RUNNING --> CLOSING
09:04:41.425 pjsua_call.c .Hangup all calls..
09:04:41.426 pjsua_media.c .Call 0: deinitializing media..
09:04:41.428 pjsua_pres.c .Shutting down presence..
09:04:41.430 pjsua_acc.c .Acc 3: setting unregistration..
09:04:41.432 endpoint ..Request msg REGISTER/cseq=24270 (tdta0xe22160) created.
09:04:41.432 tsx0xe231cc ...Transaction created for Request msg REGISTER/cseq=24271 (tdta0xe22160)
09:04:41.432 tsx0xe231cc ..Sending Request msg REGISTER/cseq=24271 (tdta0xe22160) in state Null
09:04:41.432 sip_resolve.c ...DNS resolver not available, target 'vcs.mydomain.ac.uk:0' type=Unspecified will be resolved with getaddrinfo()
09:04:41.434 sip_resolve.c ...Target 'vcs.mydomain.ac.uk:0' type=Unspecified resolved to '222.222.222.1:5060' type=UDP (UDP transport)
09:04:41.434 pjsua_core.c ...TX 438 bytes Request msg REGISTER/cseq=24271 (tdta0xe22160) to UDP 222.222.222.1:5060:
REGISTER sip:vcs.mydomain.ac.uk SIP/2.0
Via: SIP/2.0/UDP 222.222.222.222:5060;rport;branch=z9hG4bKPj6-rBuPWXpvqtQvDjApSz8-FkZZ7ZlNam
Max-Forwards: 70
From: sip:pi@mydomain.ac.uk;tag=gsAF9Gl66vfJG8GVkseqKyTY4psar4SY
To: sip:pi@mydomain.ac.uk
Call-ID: tuaaqJ-yv0dfsMPfd.ucRmeKYOEvt7oS
CSeq: 24271 REGISTER
User-Agent: PJSUA v2.2.1 Linux-3.12.22/armv6l/glibc-2.13
Contact: sip:pi@222.222.222.222:5060;ob
Expires: 0
Content-Length: 0
--end msg--
09:04:41.440 tsx0xe231cc ...State changed from Null to Calling, event=TX_MSG
09:04:41.440 pjsua_acc.c ..Acc 3: Unregistration sent
09:04:41.445 sip_endpoint.c .Processing incoming message: Response msg 200/REGISTER/cseq=24271 (rdata0xdd8b74)
09:04:41.445 pjsua_core.c ..RX 461 bytes Response msg 200/REGISTER/cseq=24271 (rdata0xdd8b74) from UDP 222.222.222.1:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 222.222.222.222:5060;branch=z9hG4bKPj6-rBuPWXpvqtQvDjApSz8-FkZZ7ZlNam;received=222.222.222.222;rport=5060
Call-ID: tuaaqJ-yv0dfsMPfd.ucRmeKYOEvt7oS
CSeq: 24271 REGISTER
From: sip:pi@mydomain.ac.uk;tag=gsAF9Gl66vfJG8GVkseqKyTY4psar4SY
To: sip:pi@mydomain.ac.uk;tag=27ad3bdee3050157
Server: TANDBERG/4120 (X7.2.3)
Date: Thu, 26 Jun 2014 17:10:03 GMT
Supported: com.tandberg.sdp.extensions.v1,gruu,outbound,path
Content-Length: 0
--end msg--
Cheers
Chris
Hi,
Try increasing PJSIP_MAX_PKT_LEN (default 4000). I think you may be
going over that, the SDP is 2660 bytes and the header has a lot of
Record-Route lines. I find it surprising that it works correctly using UDP.
Bill
On 6/26/2014 4:51 PM, Swinney C. wrote:
Further to this, I have one more oddity to throw into the mix.
The VCS's are able to interwork SIP <----> H.323 (and vice versa) and so if we have PJSUA registered to the VCS using TCP or TLS, and the MCU make an H.323 call instead of SIP call, the VCS will interwork H.323 --> SIP and the call is placed successfully.
Chris
From: Swinney C.
Sent: 26 June 2014 19:15
To: pjsip@lists.pjsip.org
Subject: Possible bug (Buffer overflow) - PJSUA unable to handle SIP message sent via Cisco 8510 MCU when registered to a Cisco VCS via TCP/TLS
Hi all,
We are trying to get PJSIP and the PJSUA application working when we register to an account via either TCP or TLS transport. The Registrar is a Cisco VCS and we are trying to accept an inbound call initiated from a Cisco 8510 MCU. PJSIP is running on version 2.2.1 on a Raspberry Pi running Raspian.
If we register PJSUA to the VCS using UDP as the transport, we can accept a call from the MCU with no issue. However, if we try to register via TCP as the transport mechanism to the VCS and make the same call from the same MCU, PJSUA is unable to handle the message and says there is a buffer overflow. Is there anything we can do to rectify this?
For reference, I have included below the relevant log sections (level 5) from the failed call and the good call (obscure IP address and URIs). If there is anything else that might help (such as high level logs or packet captures), then let us know:
Log from FAILED inbound call when registered via TCP transport
08:53:02.110 sip_endpoint.c Error processing packet from 222.222.222.1:5060: Rx buffer overflow (PJSIP_ERXOVERFLOW) [code 171062]:
INVITE sip:pi@222.222.222.222:5060;transport=TCP;ob SIP/2.0
Via: SIP/2.0/TCP 222.222.222.1:5060;egress-zone=DefaultSubZone;branch=z9hG4bK2539fce0618acc914d081ece26433f06907450.e8169c45cc678bd159754752343056b4;proxy-call-id=1582591a-fd53-11e3-bd38-0010f30f5a60;rport
Via: SIP/2.0/TLS 111.111.111.111:7029;egress-zone=OurUniversity;branch=z9hG4bK895b55f2f3bf606bac3fc0651749fa5b61290.73594a4f2852aa8a3c87a40dd8159206;proxy-call-id=157cb19a-fd53-11e3-81d7-0010f30f56f0;received=111.111.111.111;rport=7029;ingress-zone=OurUniversity
Via: SIP/2.0/TLS 9.9.9.9:5061;egress-zone=dnsout;branch=z9hG4bK6fd1beced1963d9a99dca0da8be9743b1214218.bf97dcdf757250e2330b681719b63e91;proxy-call-id=d09ae921-6d48-490b-a44d-89da5ecb1735;received=9.9.9.9;rport=26521;ingress-zone=DefaultZone
Via: SIP/2.0/TCP 1.1.1.1:5060;branch=z9hG4bK17429394;received=1.1.1.1;ingress-zone=DefaultSubZone
Call-ID: C67D8C81@1.1.1.1
CSeq: 1 INVITE
Contact: sip:8510@thierdomain.ac.uk;gr=urn:uuid:e42a7487-dd80-52f6-b21b-c718aaff8e59;isfocus
From: "CONFERENCE1" sip:8510@thierdomain.ac.uk;tag=DC5D7A6BD0B50001
To: sip:pi@mydomain.ac.uk
Max-Forwards: 13
Record-Route: sip:proxy-call-id=1582591a-fd53-11e3-bd38-0010f30f5a60@222.222.222.1:5060;transport=tcp;lr
Record-Route: sip:proxy-call-id=1582591a-fd53-11e3-bd38-0010f30f5a60@222.222.222.1:5061;transport=tls;lr
Record-Route: sip:proxy-call-id=157cb19a-fd53-11e3-81d7-0010f30f56f0@111.111.111.111:7029;transport=tls;lr
Record-Route: sip:proxy-call-id=157cb19a-fd53-11e3-81d7-0010f30f56f0@111.111.111.111:5061;transport=tls;lr
Record-Route: sip:proxy-call-id=d09ae921-6d48-490b-a44d-89da5ecb1735@9.9.9.9:5061;transport=tls;lr
Record-Route: sip:proxy-call-id=d09ae921-6d48-490b-a44d-89da5ecb1735@9.9.9.9:5060;transport=tcp;lr
Allow: INVITE,ACK,CANCEL,OPTIONS,UPDATE,INFO,NOTIFY,BYE,REFER
User-Agent: Codian MSE 8510 v4.4 (3.42)
Supported: timer,outbound
Session-Expires: 500
X-TAATag: 7da68719-daec-4724-a92c-8c534dc98431
Content-Type: application/sdp
Content-Length: 2660
v=0
o=CODIAN 799447510 799447510 IN IP4 1.1.1.1
s=-
i=Codian MSE 8510 v4.4 (3.42)
c=IN IP4 222.222.222.1
b=AS:2046
t=0 0
m=audio 51620 RTP/AVP 113 108 109 110 98 114 115 118 99 100 102 9 104 105 0 8 15 18 101
a=rtpmap:113 MP4A-LATM/90000
a=fmtp:113 profile-level-id=24;object=23;bitrate=96000
a=rtpmap:108 MP4A-LATM/90000
a=fmtp:108 profile-level-id=24;object=23;bitrate=64000
a=rtpmap:109 MP4A-LATM/90000
a=fmtp:109 profile-level-id=24;object=23;bitrate=56000
a=rtpmap:110 MP4A-LATM/90000
a=fmtp:110 profile-level-id=24;object=23;bitrate=48000
a=rtpmap:98 MP4A-LATM/32000
a=fmtp:98 profile-level-id=2;object=2;bitrate=96000
a=rtpmap:114 SIREN14/16000
a=fmtp:114 bitrate=48000
a=rtpmap:115 SIREN14/16000
a=fmtp:115 bitrate=32000
a=rtpmap:118 SIREN14/16000
a=fmtp:118 bitrate=24000
a=rtpmap:99 G7221/32000
a=fmtp:99 bitrate=48000
a=rtpmap:100 G7221/32000
a=fmtp:100 bitrate=32000
a=rtpmap:102 G7221/32000
a=fmtp:102 bitrate=24000
a=rtpmap:9 G722/8000
a=rtpmap:104 G7221/16000
a=fmtp:104 bitrate=32000
a=rtpmap:105 G7221/16000
a=fmtp:105 bitrate=24000
a=rtpmap:0 PCMU/8000/1
a=rtpmap:8 PCMA/8000/1
a=rtpmap:15 G728/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
m=video 51610 RTP/AVP 126 97 96 34 31
b=AS:2000
a=rtpmap:126 H264/90000
a=fmtp:126 profile-level-id=42e014;max-mbps=108000;max-fs=3600;max-dpb=5400;max-fps=6000;packetization-mode=1
a=rtpmap:97 H264/90000
a=fmtp:97 profile-level-id=42e014;max-mbps=108000;max-fs=3600;max-dpb=5400;max-fps=6000
a=rtpmap:96 H263-1998/90000
a=fmtp:96 CUSTOM=1280,720,1;CUSTOM=1024,768,1;CUSTOM=1024,576,1;CUSTOM=848,480,1;CIF4=1;CUSTOM=512,288,1;CIF=1;QCIF=1
a=rtpmap:34 H263/90000
a=fmtp:34 CIF4=1;CIF=1;QCIF=1
a=rtpmap:31 H261/90000
a=fmtp:31 CIF=
08:53:53.443 pjsua_core.c !Shutting down, flags=0...
08:53:53.444 pjsua_core.c PJSUA state changed: RUNNING --> CLOSING
08:53:53.446 pjsua_call.c .Hangup all calls..
08:53:53.447 pjsua_media.c .Call 0: deinitializing media..
08:53:53.449 pjsua_pres.c .Shutting down presence..
08:53:53.451 pjsua_acc.c .Acc 3: setting unregistration..
08:53:53.452 endpoint ..Request msg REGISTER/cseq=33220 (tdta0x20e9320) created.
08:53:53.453 tsx0x20ea38c ...Transaction created for Request msg REGISTER/cseq=33221 (tdta0x20e9320)
08:53:53.453 tsx0x20ea38c ..Sending Request msg REGISTER/cseq=33221 (tdta0x20e9320) in state Null
08:53:53.453 sip_resolve.c ...DNS resolver not available, target 'vcs.mydomain.ac.uk:0' type=TCP will be resolved with getaddrinfo()
08:53:53.455 sip_resolve.c ...Target 'vcs.mydomain.ac.uk:0' type=TCP resolved to '222.222.222.1:5060' type=TCP (TCP transport)
08:53:53.455 pjsua_core.c ...TX 573 bytes Request msg REGISTER/cseq=33221 (tdta0x20e9320) to TCP 222.222.222.1:5060:
REGISTER sip:vcs.mydomain.ac.uk;transport=tcp SIP/2.0
Via: SIP/2.0/TCP 222.222.222.222:36452;rport;branch=z9hG4bKPjTJU486ZxLCQr4WanbdX7mrZxf9-fVxl2;alias
Max-Forwards: 70
From: sip:pi@mydomain.ac.uk;tag=CiChlIjlVCJH3rYPYAcI4g47JhGXvEli
To: sip:pi@mydomain.ac.uk
Call-ID: iQjdX7e-R7SzQRN-RzaeEHGl4aYC1jeb
CSeq: 33221 REGISTER
User-Agent: PJSUA v2.2.1 Linux-3.12.22/armv6l/glibc-2.13
Supported: outbound, path
Contact: sip:pi@222.222.222.222:5060;transport=TCP;ob;reg-id=1;+sip.instance="urn:uuid:00000000-0000-0000-0000-0000f361b107"
Expires: 0
Content-Length: 0
--end msg--
08:53:53.461 tsx0x20ea38c ...State changed from Null to Calling, event=TX_MSG
08:53:53.461 pjsua_acc.c ..Acc 3: Unregistration sent
08:53:53.466 sip_transport. .Error processing 1361 bytes packet from TCP 222.222.222.1:5060 : PJSIP syntax error exception when parsing '' header on line 1 col 3:
rl:s-only
a=confid:160339198
a=floorid:2 mstrm:12
a=userid:37917
a=connection:new
m=application 51628 RTP/AVP 100
a=rtpmap:100 H224/4800
a=sendrecv
m=video 51618 RTP/AVP 96 126 97
b=AS:2000
a=rtpmap:96 H263-1998/90000
a=fmtp:96 CUSTOM=1280,720,2;CUSTOM=1024,768,2;CUSTOM=1024,576,2;CUSTOM=848,480,1;CUSTOM=800,600,2;CUSTOM=768,448,1;CUSTOM=640,480,1;CUSTOM=512,288,1;CIF=1
a=rtpmap:126 H264/90000
a=fmtp:126 profile-level-id=42e014;max-mbps=27000;max-fs=3600;max-dpb=1350;max-fps=6000;packetization-mode=1
a=rtpmap:97 H264/90000
a=fmtp:97 profile-level-id=42e014;max-mbps=27000;max-fs=3600;max-dpb=1350;max-fps=6000
a=rtcp-fb:* nack pli
a=rtcp-fb:* ccm fir
a=sendrecv
a=content:slides
a=label:12
SIP/2.0 200 OK
Via: SIP/2.0/TCP 222.222.222.222:36452;branch=z9hG4bKPjTJU486ZxLCQr4WanbdX7mrZxf9-fVxl2;alias;received=222.222.222.222;rport=36452
Call-ID: iQjdX7e-R7SzQRN-RzaeEHGl4aYC1jeb
CSeq: 33221 REGISTER
From: sip:pi@mydomain.ac.uk;tag=CiChlIjlVCJH3rYPYAcI4g47JhGXvEli
To: sip:pi@mydomain.ac.uk;tag=ac91598765bd6a93
Path: sip:flow-token=MwAAAAAAAAAADwAAABPEAACJLKwBkn8AACAAAHiSfwAAYOPZfQAAAAAAAAAADwAAAI5kAACJLKw8AAAAAAAAAAAAAAAAAAAAACphL8vMwOZlk2A=@222.222.222.1;ob;lr
Server: TANDBERG/4120 (X7.2.3)
Date: Thu, 26 Jun 2014 16:59:15 GMT
Supported: com.tandberg.sdp.extensions.v1,gruu,outbound,path
Require: outbound
Content-Length: 0
Log from GOOD inbound call when registered via UDP transport
09:04:18.880 sip_endpoint.c Processing incoming message: Request msg INVITE/cseq=1 (rdata0xdd8b74)
09:04:18.880 pjsua_core.c .RX 4000 bytes Request msg INVITE/cseq=1 (rdata0xdd8b74) from UDP 222.222.222.1:5060:
INVITE sip:pi@222.222.222.222:5060;ob SIP/2.0
Via: SIP/2.0/UDP 222.222.222.1:5060;egress-zone=DefaultSubZone;branch=z9hG4bKa75e2d5ac3d58fa40ac094bf43746267907573.16b26c94fddfcc8e7270188161369418;proxy-call-id=a8e7a434-fd54-11e3-9bf8-0010f30f5a60;rport
Via: SIP/2.0/TLS 111.111.111.111:7029;egress-zone=OurUniversity;branch=z9hG4bK14b7b6b9ff6d433db7986af2d079fd5161301.8edbe65b1b55c77532ca579af82eea53;proxy-call-id=a8e1e0f8-fd54-11e3-89fe-0010f30f56f0;received=111.111.111.111;rport=7029;ingress-zone=OurUniversity
Via: SIP/2.0/TLS 9.9.9.9:5061;egress-zone=dnsout;branch=z9hG4bK0a581568f00c1d1218885d78c67f7e801214350.9ecf1523bb6f1feb6b39c5f18c6fe9e8;proxy-call-id=bc33e9ef-3d31-40ec-9417-c44e1323e9f6;received=9.9.9.9;rport=26521;ingress-zone=DefaultZone
Via: SIP/2.0/TCP 1.1.1.1:5060;branch=z9hG4bK1742939a;received=1.1.1.1;ingress-zone=DefaultSubZone
Call-ID: C67D8C82@1.1.1.1
CSeq: 1 INVITE
Contact: sip:8510@thierdomain.ac.uk;gr=urn:uuid:e42a7487-dd80-52f6-b21b-c718aaff8e59;isfocus
From: "CONFERENCE2" sip:8510@thierdomain.ac.uk;tag=71193C78D0B60001
To: sip:pi@mydomain.ac.uk
Max-Forwards: 13
Record-Route: sip:proxy-call-id=a8e7a434-fd54-11e3-9bf8-0010f30f5a60@222.222.222.1:5060;transport=udp;lr
Record-Route: sip:proxy-call-id=a8e7a434-fd54-11e3-9bf8-0010f30f5a60@222.222.222.1:5061;transport=tls;lr
Record-Route: sip:proxy-call-id=a8e1e0f8-fd54-11e3-89fe-0010f30f56f0@111.111.111.111:7029;transport=tls;lr
Record-Route: sip:proxy-call-id=a8e1e0f8-fd54-11e3-89fe-0010f30f56f0@111.111.111.111:5061;transport=tls;lr
Record-Route: sip:proxy-call-id=bc33e9ef-3d31-40ec-9417-c44e1323e9f6@9.9.9.9:5061;transport=tls;lr
Record-Route: sip:proxy-call-id=bc33e9ef-3d31-40ec-9417-c44e1323e9f6@9.9.9.9:5060;transport=tcp;lr
Allow: INVITE,ACK,CANCEL,OPTIONS,UPDATE,INFO,NOTIFY,BYE,REFER
User-Agent: Codian MSE 8510 v4.4 (3.42)
Supported: timer,outbound
Session-Expires: 500
X-TAATag: 74766091-9c9c-4970-bccb-77a8c4d6818d
Content-Type: application/sdp
Content-Length: 2661
v=0
o=CODIAN 800124289 800124289 IN IP4 1.1.1.1
s=-
i=Codian MSE 8510 v4.4 (3.42)
c=IN IP4 222.222.222.1
b=AS:2046
t=0 0
m=audio 51622 RTP/AVP 113 108 109 110 98 114 115 118 99 100 102 9 104 105 0 8 15 18 101
a=rtpmap:113 MP4A-LATM/90000
a=fmtp:113 profile-level-id=24;object=23;bitrate=96000
a=rtpmap:108 MP4A-LATM/90000
a=fmtp:108 profile-level-id=24;object=23;bitrate=64000
a=rtpmap:109 MP4A-LATM/90000
a=fmtp:109 profile-level-id=24;object=23;bitrate=56000
a=rtpmap:110 MP4A-LATM/90000
a=fmtp:110 profile-level-id=24;object=23;bitrate=48000
a=rtpmap:98 MP4A-LATM/32000
a=fmtp:98 profile-level-id=2;object=2;bitrate=96000
a=rtpmap:114 SIREN14/16000
a=fmtp:114 bitrate=48000
a=rtpmap:115 SIREN14/16000
a=fmtp:115 bitrate=32000
a=rtpmap:118 SIREN14/16000
a=fmtp:118 bitrate=24000
a=rtpmap:99 G7221/32000
a=fmtp:99 bitrate=48000
a=rtpmap:100 G7221/32000
a=fmtp:100 bitrate=32000
a=rtpmap:102 G7221/32000
a=fmtp:102 bitrate=24000
a=rtpmap:9 G722/8000
a=rtpmap:104 G7221/16000
a=fmtp:104 bitrate=32000
a=rtpmap:105 G7221/16000
a=fmtp:105 bitrate=24000
a=rtpmap:0 PCMU/8000/1
a=rtpmap:8 PCMA/8000/1
a=rtpmap:15 G728/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
m=video 51626 RTP/AVP 126 97 96 34 31
b=AS:2000
a=rtpmap:126 H264/90000
a=fmtp:126 profile-level-id=42e014;max-mbps=108000;max-fs=3600;max-dpb=5400;max-fps=6000;packetization-mode=1
a=rtpmap:97 H264/90000
a=fmtp:97 profile-level-id=42e014;max-mbps=108000;max-fs=3600;max-dpb=5400;max-fps=6000
a=rtpmap:96 H263-1998/90000
a=fmtp:96 CUSTOM=1280,720,1;CUSTOM=1024,768,1;CUSTOM=1024,576,1;CUSTOM=848,480,1;CIF4=1;CUSTOM=512,288,1;CIF=1;QCIF=1
a=rtpmap:34 H263/90000
a=fmtp:34 CIF4=1;CIF=1;QCIF=1
a=rtpmap:31 H261/90000
a=fmtp:31 CIF=1;QCIF=1
a=rtcp-fb:* nack pli
09:04:18.899 pjsua_call.c .Incoming Request msg INVITE/cseq=1 (rdata0xdd8b74)
09:04:18.900 tsx0xde1be4 ...Transaction created for Request msg INVITE/cseq=1 (rdata0xdd8b74)
09:04:18.900 tsx0xde1be4 ..Incoming Request msg INVITE/cseq=1 (rdata0xdd8b74) in state Null
09:04:18.900 tsx0xde1be4 ...State changed from Null to Trying, event=RX_MSG
09:04:18.900 dlg0xde0fcc ....Transaction tsx0xde1be4 state changed to Trying
09:04:18.900 dlg0xde0fcc ..UAS dialog created
09:04:18.900 dlg0xde0fcc ..Module mod-invite added as dialog usage, data=0xddd544
09:04:18.900 dlg0xde0fcc ...Session count inc to 2 by mod-invite
09:04:18.901 inv0xde0fcc ..UAS invite session created for dialog dlg0xde0fcc
09:04:18.901 pjsua_media.c ..Call 0: initializing media..
09:04:18.903 pjsua_media.c ...RTP socket reachable at 222.222.222.222:4000
09:04:18.903 pjsua_media.c ...RTCP socket reachable at 222.222.222.222:4001
09:04:18.903 pjsua_media.c ...Media index 0 selected for audio call 0
09:04:18.906 pjsua_call.c ..Call 0: remote NAT type is 0 (Unknown)
09:04:18.906 endpoint ...Response msg 100/INVITE/cseq=1 (tdta0xdec680) created
09:04:18.906 dlg0xde0fcc ...Initial answer Response msg 100/INVITE/cseq=1 (tdta0xdec680)
09:04:18.906 inv0xde0fcc ...Sending Response msg 100/INVITE/cseq=1 (tdta0xdec680)
09:04:18.906 dlg0xde0fcc ....Sending Response msg 100/INVITE/cseq=1 (tdta0xdec680)
09:04:18.906 tsx0xde1be4 ....Sending Response msg 100/INVITE/cseq=1 (tdta0xdec680) in state Trying
09:04:18.906 pjsua_core.c .....TX 1702 bytes Response msg 100/INVITE/cseq=1 (tdta0xdec680) to UDP 222.222.222.1:5060:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 222.222.222.1:5060;rport=5060;received=222.222.222.1;branch=z9hG4bKa75e2d5ac3d58fa40ac094bf43746267907573.16b26c94fddfcc8e7270188161369418;egress-zone=DefaultSubZone;proxy-call-id=a8e7a434-fd54-11e3-9bf8-0010f30f5a60
Via: SIP/2.0/TLS 111.111.111.111:7029;rport=7029;received=111.111.111.111;branch=z9hG4bK14b7b6b9ff6d433db7986af2d079fd5161301.8edbe65b1b55c77532ca579af82eea53;egress-zone=OurUniversity;proxy-call-id=a8e1e0f8-fd54-11e3-89fe-0010f30f56f0;ingress-zone=OurUniversity
Via: SIP/2.0/TLS 9.9.9.9:5061;rport=26521;received=9.9.9.9;branch=z9hG4bK0a581568f00c1d1218885d78c67f7e801214350.9ecf1523bb6f1feb6b39c5f18c6fe9e8;egress-zone=dnsout;proxy-call-id=bc33e9ef-3d31-40ec-9417-c44e1323e9f6;ingress-zone=DefaultZone
Via: SIP/2.0/TCP 1.1.1.1:5060;received=1.1.1.1;branch=z9hG4bK1742939a;ingress-zone=DefaultSubZone
Record-Route: sip:proxy-call-id=a8e7a434-fd54-11e3-9bf8-0010f30f5a60@222.222.222.1:5060;transport=udp;lr
Record-Route: sip:proxy-call-id=a8e7a434-fd54-11e3-9bf8-0010f30f5a60@222.222.222.1:5061;transport=tls;lr
Record-Route: sip:proxy-call-id=a8e1e0f8-fd54-11e3-89fe-0010f30f56f0@111.111.111.111:7029;transport=tls;lr
Record-Route: sip:proxy-call-id=a8e1e0f8-fd54-11e3-89fe-0010f30f56f0@111.111.111.111:5061;transport=tls;lr
Record-Route: sip:proxy-call-id=bc33e9ef-3d31-40ec-9417-c44e1323e9f6@9.9.9.9:5061;transport=tls;lr
Record-Route: sip:proxy-call-id=bc33e9ef-3d31-40ec-9417-c44e1323e9f6@9.9.9.9:5060;transport=tcp;lr
Call-ID: C67D8C82@1.1.1.1
From: "CONFERENCE2" sip:8510@thierdomain.ac.uk;tag=71193C78D0B60001
To: sip:pi@mydomain.ac.uk
CSeq: 1 INVITE
Content-Length: 0
--end msg--
09:04:18.908 tsx0xde1be4 .....State changed from Trying to Proceeding, event=TX_MSG
09:04:18.908 dlg0xde0fcc ......Transaction tsx0xde1be4 state changed to Proceeding
09:04:18.908 pjsua_aud.c ..Conf connect: 2 --> 0
09:04:18.911 pjsua_aud.c ...Set sound device: capture=2, playback=0
09:04:18.912 pjsua_app.c ....Turning sound device ON
09:04:18.913 pjsua_aud.c ....Opening sound device PCM@16000/1/20ms
09:04:18.915 alsa_dev.c .....open_playback: Open playback device 'default:CARD=ALSA'
09:04:18.942 alsa_dev.c .....Opened device alsa(default:CARD=ALSA) for playing, sample rate=16000, ch=1, bits=16, period size=320 frames, latency=140 ms
09:04:18.942 alsa_dev.c .....open_capture: Open capture device 'default:CARD=Dummy'
09:04:18.950 alsa_dev.c .....Opened device alsa(default:CARD=Dummy) for capture, sample rate=16000, ch=1, bits=16, period size=320 frames, latency=100 ms
09:04:18.950 ec0xdf1a28 .....Creating AEC
09:04:18.953 ec0xdf1a28 .....AEC created, clock_rate=16000, channel=1, samples per frame=320, tail length=200 ms, latency=0 ms
09:04:18.955 conference.c ...Port 2 (ring) transmitting to port 0 (default:CARD=Dummy)
09:04:18.956 pjsua_call.c ..Answering call 0: code=200
09:04:18.959 inv0xde0fcc ....SDP negotiation done, status=0
09:04:18.960 pjsua_call.c .....Call 0: remote NAT type is 0 (Unknown)
09:04:18.960 pjsua_media.c .....Call 0: updating media..
09:04:18.961 alsa_dev.c !ca_thread_func(10075): Set thread priority for audio capture thread.
09:04:18.961 alsa_dev.c Unable to increase thread priority, root access needed.
09:04:18.962 pjsua_aud.c !......Audio channel update..
09:04:18.964 speex !warning: discarded first playback frame
09:04:18.965 speex warning: discarded first playback frame
09:04:18.964 strm0xe1de84 !.......VAD temporarily disabled
09:04:18.967 rtp.c .......pjmedia_rtp_session_init: ses=0xe20510, default_pt=9, ssrc=0x55a981c0
09:04:18.967 rtp.c .......pjmedia_rtp_session_init: ses=0xe20b98, default_pt=9, ssrc=0x55a981c0
09:04:18.967 stream.c .......Stream strm0xe1de84 created
09:04:18.967 strm0xe1de84 .......Encoder stream started
09:04:18.969 speex !warning: discarded first playback frame
09:04:18.970 speex warning: discarded first playback frame
09:04:18.970 speex warning: discarded first playback frame
09:04:18.970 speex warning: discarded first playback frame
09:04:18.970 strm0xe1de84 !.......Decoder stream started
09:04:18.972 speex !warning: discarded first playback frame
09:04:18.973 pjsua_media.c !......Audio updated, stream #0: G722 (sendrecv)
09:04:18.975 strm0xe1de84 !Start talksprut..
09:04:18.975 speex warning: discarded first playback frame
09:04:18.976 pjsua_media.c !......Error updating media call00:1: Invalid media type (PJMEDIA_EINVALIMEDIATYPE)
09:04:18.977 pjsua_media.c ......Error updating media call00:2: Invalid media type (PJMEDIA_EINVALIMEDIATYPE)
09:04:18.979 pjsua_app.c .....Call 0 media 0 [type=audio], status is Active
09:04:18.981 pjsua_aud.c .....Conf disconnect: 2 -x- 0
09:04:18.988 conference.c ......Port 2 (ring) stop transmitting to port 0 (default:CARD=Dummy)
09:04:18.990 pjsua_aud.c .....Conf connect: 3 --> 0
09:04:18.992 conference.c ......Port 3 (sip:8510@thierdomain.ac.uk) transmitting to port 0 (default:CARD=Dummy)
09:04:18.993 pjsua_aud.c .....Conf connect: 0 --> 3
09:04:18.995 conference.c ......Port 0 (default:CARD=Dummy) transmitting to port 3 (sip:8510@thierdomain.ac.uk)
09:04:18.997 inv0xde0fcc ....Sending Response msg 200/INVITE/cseq=1 (tdta0xdec680)
09:04:18.997 dlg0xde0fcc .....Sending Response msg 200/INVITE/cseq=1 (tdta0xdec680)
09:04:18.997 tsx0xde1be4 .....Sending Response msg 200/INVITE/cseq=1 (tdta0xdec680) in state Proceeding
09:04:18.998 pjsua_core.c ......TX 2399 bytes Response msg 200/INVITE/cseq=1 (tdta0xdec680) to UDP 222.222.222.1:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 222.222.222.1:5060;rport=5060;received=222.222.222.1;branch=z9hG4bKa75e2d5ac3d58fa40ac094bf43746267907573.16b26c94fddfcc8e7270188161369418;egress-zone=DefaultSubZone;proxy-call-id=a8e7a434-fd54-11e3-9bf8-0010f30f5a60
Via: SIP/2.0/TLS 111.111.111.111:7029;rport=7029;received=111.111.111.111;branch=z9hG4bK14b7b6b9ff6d433db7986af2d079fd5161301.8edbe65b1b55c77532ca579af82eea53;egress-zone=OurUniversity;proxy-call-id=a8e1e0f8-fd54-11e3-89fe-0010f30f56f0;ingress-zone=OurUniversity
Via: SIP/2.0/TLS 9.9.9.9:5061;rport=26521;received=9.9.9.9;branch=z9hG4bK0a581568f00c1d1218885d78c67f7e801214350.9ecf1523bb6f1feb6b39c5f18c6fe9e8;egress-zone=dnsout;proxy-call-id=bc33e9ef-3d31-40ec-9417-c44e1323e9f6;ingress-zone=DefaultZone
Via: SIP/2.0/TCP 1.1.1.1:5060;received=1.1.1.1;branch=z9hG4bK1742939a;ingress-zone=DefaultSubZone
Record-Route: sip:proxy-call-id=a8e7a434-fd54-11e3-9bf8-0010f30f5a60@222.222.222.1:5060;transport=udp;lr
Record-Route: sip:proxy-call-id=a8e7a434-fd54-11e3-9bf8-0010f30f5a60@222.222.222.1:5061;transport=tls;lr
Record-Route: sip:proxy-call-id=a8e1e0f8-fd54-11e3-89fe-0010f30f56f0@111.111.111.111:7029;transport=tls;lr
Record-Route: sip:proxy-call-id=a8e1e0f8-fd54-11e3-89fe-0010f30f56f0@111.111.111.111:5061;transport=tls;lr
Record-Route: sip:proxy-call-id=bc33e9ef-3d31-40ec-9417-c44e1323e9f6@9.9.9.9:5061;transport=tls;lr
Record-Route: sip:proxy-call-id=bc33e9ef-3d31-40ec-9417-c44e1323e9f6@9.9.9.9:5060;transport=tcp;lr
Call-ID: C67D8C82@1.1.1.1
From: "CONFERENCE2" sip:8510@thierdomain.ac.uk;tag=71193C78D0B60001
To: sip:pi@mydomain.ac.uk;tag=R3gbdJaQddbbOldCYhjdJ5Y5CaXTtRra
CSeq: 1 INVITE
Contact: sip:pi@222.222.222.222:5060;ob
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Session-Expires: 500;refresher=uac
Require: timer
Content-Type: application/sdp
Content-Length: 391
v=0
o=- 3605072658 3605072659 IN IP4 222.222.222.222
s=pjmedia
b=AS:84
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 9 101
c=IN IP4 222.222.222.222
b=TIAS:64000
a=rtcp:4001 IN IP4 222.222.222.222
a=sendrecv
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
m=video 0 RTP/AVP 126 97 96 34 31
c=IN IP4 127.0.0.1
b=AS:2000
m=application 0 UDP/BFCP *
c=IN IP4 127.0.0.1
--end msg--
09:04:19.020 strm0xe1de84 !Jitter buffer is bufferring (prefetch=0), plc invoked
09:04:19.021 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:19.021 Master/sound Underflow, buf_cnt=0, will generate 1 frame
09:04:19.037 tsx0xde1be4 !......State changed from Proceeding to Completed, event=TX_MSG
09:04:19.037 dlg0xde0fcc .......Transaction tsx0xde1be4 state changed to Completed
09:04:19.037 pjsua_app.c .........Call 0 state changed to CONNECTING
09:04:19.039 Master/sound !Underflow, buf_cnt=0, will generate 1 frame
09:04:19.043 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:19.074 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:19.154 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:19.155 sip_endpoint.c !Processing incoming message: Request msg ACK/cseq=1 (rdata0xdd8b74)
09:04:19.155 pjsua_core.c .RX 1303 bytes Request msg ACK/cseq=1 (rdata0xdd8b74) from UDP 222.222.222.1:5060:
ACK sip:pi@222.222.222.222:5060;ob SIP/2.0
Via: SIP/2.0/UDP 222.222.222.1:5060;egress-zone=DefaultSubZone;branch=z9hG4bKeb907f62b074f8adf7c7e96b765cbdd2907575.16b26c94fddfcc8e7270188161369418;proxy-call-id=a8e7a434-fd54-11e3-9bf8-0010f30f5a60;rport
Via: SIP/2.0/TLS 111.111.111.111:7029;egress-zone=OurUniversity;branch=z9hG4bK5b01ac49ed365b43d7187b1cefbeb55a61302.8edbe65b1b55c77532ca579af82eea53;proxy-call-id=a8e1e0f8-fd54-11e3-89fe-0010f30f56f0;received=111.111.111.111;rport=7029;ingress-zone=OurUniversity
Via: SIP/2.0/TLS 9.9.9.9:5061;egress-zone=dnsout;branch=z9hG4bK229006a387aa5b022c13fac064f2ac0d1214351.9ecf1523bb6f1feb6b39c5f18c6fe9e8;proxy-call-id=bc33e9ef-3d31-40ec-9417-c44e1323e9f6;received=9.9.9.9;rport=26521;ingress-zone=DefaultZone
Via: SIP/2.0/TCP 1.1.1.1:5060;branch=z9hG4bK1742939b;received=1.1.1.1;ingress-zone=DefaultSubZone
Call-ID: C67D8C82@1.1.1.1
CSeq: 1 ACK
Contact: sip:8510@thierdomain.ac.uk;gr=urn:uuid:e42a7487-dd80-52f6-b21b-c718aaff8e59;isfocus
From: "CONFERENCE2" sip:8510@thierdomain.ac.uk;tag=71193C78D0B60001
To: sip:pi@mydomain.ac.uk;tag=R3gbdJaQddbbOldCYhjdJ5Y5CaXTtRra
Max-Forwards: 67
User-Agent: Codian MSE 8510 v4.4 (3.42)
Supported: timer,outbound
X-TAATag: 74766091-9c9c-4970-bccb-77a8c4d6818d
Content-Length: 0
--end msg--
09:04:19.163 dlg0xde0fcc .Received Request msg ACK/cseq=1 (rdata0xdd8b74)
09:04:19.163 tsx0xde1be4 ..Request to terminate transaction
09:04:19.163 tsx0xde1be4 ...State changed from Completed to Terminated, event=USER
09:04:19.163 dlg0xde0fcc ....Transaction tsx0xde1be4 state changed to Terminated
09:04:19.163 pjsua_app.c ...Call 0 state changed to CONFIRMED
09:04:19.168 tsx0xde1be4 Timeout timer event
09:04:19.168 tsx0xde1be4 .State changed from Terminated to Destroyed, event=TIMER
09:04:19.169 tdta0xdec680 ..Destroying txdata Response msg 200/INVITE/cseq=1 (tdta0xdec680)
09:04:19.169 tsx0xde1be4 Transaction destroyed!
09:04:19.176 speex !warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:19.177 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:19.214 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:19.294 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:19.296 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:19.352 strm0xe1de84 !RTP status: badpt=0, badssrc=0, dup=0, outorder=0, probation=-1, restart=0
09:04:19.352 stream.c G722 codec used, remote samples per frame detected = 80
09:04:19.355 strm0xe1de84 !Jitter buffer starts returning normal frames (after 17 empty/lost)
09:04:19.434 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:19.437 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:19.574 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:19.577 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:19.603 strm0xe1de84 VAD re-enabled
09:04:19.605 strm0xe1de84 Starting silence
09:04:19.713 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:19.715 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:19.853 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:19.855 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:19.895 sip_endpoint.c !Processing incoming message: Request msg UPDATE/cseq=2 (rdata0xdd8b74)
09:04:19.895 pjsua_core.c .RX 4000 bytes Request msg UPDATE/cseq=2 (rdata0xdd8b74) from UDP 222.222.222.1:5060:
UPDATE sip:pi@222.222.222.222:5060;ob SIP/2.0
Via: SIP/2.0/UDP 222.222.222.1:5060;egress-zone=DefaultSubZone;branch=z9hG4bK3c0043c58d581648f53497392dfb3fbe907576.16b26c94fddfcc8e7270188161369418;proxy-call-id=a8e7a434-fd54-11e3-9bf8-0010f30f5a60;rport
Via: SIP/2.0/TLS 111.111.111.111:7029;egress-zone=OurUniversity;branch=z9hG4bK8cf7d6c613301828071f2c6e74492f9a61303.8edbe65b1b55c77532ca579af82eea53;proxy-call-id=a8e1e0f8-fd54-11e3-89fe-0010f30f56f0;received=111.111.111.111;rport=7029;ingress-zone=OurUniversity
Via: SIP/2.0/TLS 9.9.9.9:5061;egress-zone=dnsout;branch=z9hG4bKde3fda41e0b160af58904f51bd71ef461214352.9ecf1523bb6f1feb6b39c5f18c6fe9e8;proxy-call-id=bc33e9ef-3d31-40ec-9417-c44e1323e9f6;received=9.9.9.9;rport=26521;ingress-zone=DefaultZone
Via: SIP/2.0/TCP 1.1.1.1:5060;branch=z9hG4bK1742939c;received=1.1.1.1;ingress-zone=DefaultSubZone
Call-ID: C67D8C82@1.1.1.1
CSeq: 2 UPDATE
Contact: sip:8510@thierdomain.ac.uk;gr=urn:uuid:e42a7487-dd80-52f6-b21b-c718aaff8e59;isfocus
From: "CONFERENCE2" sip:8510@thierdomain.ac.uk;tag=71193C78D0B60001
To: sip:pi@mydomain.ac.uk;tag=R3gbdJaQddbbOldCYhjdJ5Y5CaXTtRra
Max-Forwards: 67
Record-Route: sip:proxy-call-id=a8e7a434-fd54-11e3-9bf8-0010f30f5a60@222.222.222.1:5060;transport=udp;lr
Record-Route: sip:proxy-call-id=a8e7a434-fd54-11e3-9bf8-0010f30f5a60@222.222.222.1:5061;transport=tls;lr
Record-Route: sip:proxy-call-id=a8e1e0f8-fd54-11e3-89fe-0010f30f56f0@111.111.111.111:7029;transport=tls;lr
Record-Route: sip:proxy-call-id=a8e1e0f8-fd54-11e3-89fe-0010f30f56f0@111.111.111.111:5061;transport=tls;lr
Record-Route: sip:proxy-call-id=bc33e9ef-3d31-40ec-9417-c44e1323e9f6@9.9.9.9:5061;transport=tls;lr
Record-Route: sip:proxy-call-id=bc33e9ef-3d31-40ec-9417-c44e1323e9f6@9.9.9.9:5060;transport=tcp;lr
User-Agent: Codian MSE 8510 v4.4 (3.42)
Supported: timer,outbound
Session-Expires: 500;refresher=uac
X-TAATag: 74766091-9c9c-4970-bccb-77a8c4d6818d
Content-Type: application/sdp
Content-Length: 2661
v=0
o=CODIAN 800124289 800124290 IN IP4 1.1.1.1
s=-
i=Codian MSE 8510 v4.4 (3.42)
c=IN IP4 222.222.222.1
b=AS:2046
t=0 0
m=audio 51622 RTP/AVP 113 108 109 110 98 114 115 118 99 100 102 9 104 105 0 8 15 18 101
a=rtpmap:113 MP4A-LATM/90000
a=fmtp:113 profile-level-id=24;object=23;bitrate=96000
a=rtpmap:108 MP4A-LATM/90000
a=fmtp:108 profile-level-id=24;object=23;bitrate=64000
a=rtpmap:109 MP4A-LATM/90000
a=fmtp:109 profile-level-id=24;object=23;bitrate=56000
a=rtpmap:110 MP4A-LATM/90000
a=fmtp:110 profile-level-id=24;object=23;bitrate=48000
a=rtpmap:98 MP4A-LATM/32000
a=fmtp:98 profile-level-id=2;object=2;bitrate=96000
a=rtpmap:114 SIREN14/16000
a=fmtp:114 bitrate=48000
a=rtpmap:115 SIREN14/16000
a=fmtp:115 bitrate=32000
a=rtpmap:118 SIREN14/16000
a=fmtp:118 bitrate=24000
a=rtpmap:99 G7221/32000
a=fmtp:99 bitrate=48000
a=rtpmap:100 G7221/32000
a=fmtp:100 bitrate=32000
a=rtpmap:102 G7221/32000
a=fmtp:102 bitrate=24000
a=rtpmap:9 G722/8000
a=rtpmap:104 G7221/16000
a=fmtp:104 bitrate=32000
a=rtpmap:105 G7221/16000
a=fmtp:105 bitrate=24000
a=rtpmap:0 PCMU/8000/1
a=rtpmap:8 PCMA/8000/1
a=rtpmap:15 G728/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
m=video 51636 RTP/AVP 126 97 96 34 31
b=AS:2000
a=rtpmap:126 H264/90000
a=fmtp:126 profile-level-id=42e014;max-mbps=108000;max-fs=3600;max-dpb=5400;max-fps=6000;packetization-mode=1
a=rtpmap:97 H264/90000
a=fmtp:97 profile-level-id=42e014;max-mbps=108000;max-fs=3600;max-dpb=5400;max-fps=6000
a=rtpmap:96 H263-1998/90000
a=fmtp:96 CUSTOM=1280,720,1;CUSTOM=1024,768,1;CUSTOM=1024,576,1;CUSTOM=848,480,1;CIF4=1;CUSTOM=512,288,1;CIF=1;QCIF=1
a=rtpmap:34 H263/90000
a=fmtp:34 CIF4=1;CIF=1;QCIF=1
a=rtpmap:31 H261/90000
a=fmtp:31 CIF=1;QCIF=1
a=rtcp-fb:* nack pli
a=rtcp-fb:*
09:04:19.906 dlg0xde0fcc .Received Request msg UPDATE/cseq=2 (rdata0xdd8b74)
09:04:19.906 tsx0xde1be4 ...Transaction created for Request msg UPDATE/cseq=2 (rdata0xdd8b74)
09:04:19.906 tsx0xde1be4 ..Incoming Request msg UPDATE/cseq=2 (rdata0xdd8b74) in state Null
09:04:19.906 tsx0xde1be4 ...State changed from Null to Trying, event=RX_MSG
09:04:19.906 dlg0xde0fcc ....Transaction tsx0xde1be4 state changed to Trying
09:04:19.907 inv0xde0fcc .....Got SDP offer in Request msg UPDATE/cseq=2 (rdata0xdd8b74)
09:04:19.907 pjsua_call.c .....Call 0: received updated media offer
09:04:19.914 strm0xe1de84 !Frame lost, recovered!
09:04:19.914 strm0xe1de84 Jitter buffer starts returning normal frames (after 1 empty/lost)
09:04:19.915 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:19.918 pjsua_media.c !......Call 0: re-initializing media..
09:04:19.923 pjsua_media.c .......Media index 0 selected for audio call 0
09:04:19.924 inv0xde0fcc .....SDP negotiation done, status=0
09:04:19.925 pjsua_call.c ......Call 0: remote NAT type is 0 (Unknown)
09:04:19.925 pjsua_media.c ......Call 0: updating media..
09:04:19.940 pjsua_media.c .......Call 0: stream #0 (audio) unchanged.
09:04:19.942 pjsua_media.c .......Audio updated, stream #0: G722 (sendrecv)
09:04:19.945 pjsua_media.c .......Error updating media call00:1: Invalid media type (PJMEDIA_EINVALIMEDIATYPE)
09:04:19.946 pjsua_media.c .......Error updating media call00:2: Invalid media type (PJMEDIA_EINVALIMEDIATYPE)
09:04:19.946 pjsua_app.c ......Call 0 media 0 [type=audio], status is Active
09:04:19.948 pjsua_aud.c ......Conf connect: 3 --> 0
09:04:19.948 pjsua_aud.c ......Conf connect: 0 --> 3
09:04:19.948 endpoint .....Response msg 200/UPDATE/cseq=2 (tdta0xddf020) created
09:04:19.949 dlg0xde0fcc ......Sending Response msg 200/UPDATE/cseq=2 (tdta0xddf020)
09:04:19.949 tsx0xde1be4 ......Sending Response msg 200/UPDATE/cseq=2 (tdta0xddf020) in state Trying
09:04:19.949 pjsua_core.c .......TX 2399 bytes Response msg 200/UPDATE/cseq=2 (tdta0xddf020) to UDP 222.222.222.1:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 222.222.222.1:5060;rport=5060;received=222.222.222.1;branch=z9hG4bK3c0043c58d581648f53497392dfb3fbe907576.16b26c94fddfcc8e7270188161369418;egress-zone=DefaultSubZone;proxy-call-id=a8e7a434-fd54-11e3-9bf8-0010f30f5a60
Via: SIP/2.0/TLS 111.111.111.111:7029;rport=7029;received=111.111.111.111;branch=z9hG4bK8cf7d6c613301828071f2c6e74492f9a61303.8edbe65b1b55c77532ca579af82eea53;egress-zone=OurUniversity;proxy-call-id=a8e1e0f8-fd54-11e3-89fe-0010f30f56f0;ingress-zone=OurUniversity
Via: SIP/2.0/TLS 9.9.9.9:5061;rport=26521;received=9.9.9.9;branch=z9hG4bKde3fda41e0b160af58904f51bd71ef461214352.9ecf1523bb6f1feb6b39c5f18c6fe9e8;egress-zone=dnsout;proxy-call-id=bc33e9ef-3d31-40ec-9417-c44e1323e9f6;ingress-zone=DefaultZone
Via: SIP/2.0/TCP 1.1.1.1:5060;received=1.1.1.1;branch=z9hG4bK1742939c;ingress-zone=DefaultSubZone
Record-Route: sip:proxy-call-id=a8e7a434-fd54-11e3-9bf8-0010f30f5a60@222.222.222.1:5060;transport=udp;lr
Record-Route: sip:proxy-call-id=a8e7a434-fd54-11e3-9bf8-0010f30f5a60@222.222.222.1:5061;transport=tls;lr
Record-Route: sip:proxy-call-id=a8e1e0f8-fd54-11e3-89fe-0010f30f56f0@111.111.111.111:7029;transport=tls;lr
Record-Route: sip:proxy-call-id=a8e1e0f8-fd54-11e3-89fe-0010f30f56f0@111.111.111.111:5061;transport=tls;lr
Record-Route: sip:proxy-call-id=bc33e9ef-3d31-40ec-9417-c44e1323e9f6@9.9.9.9:5061;transport=tls;lr
Record-Route: sip:proxy-call-id=bc33e9ef-3d31-40ec-9417-c44e1323e9f6@9.9.9.9:5060;transport=tcp;lr
Call-ID: C67D8C82@1.1.1.1
From: "CONFERENCE2" sip:8510@thierdomain.ac.uk;tag=71193C78D0B60001
To: sip:pi@mydomain.ac.uk;tag=R3gbdJaQddbbOldCYhjdJ5Y5CaXTtRra
CSeq: 2 UPDATE
Session-Expires: 500;refresher=uac
Require: timer
Contact: sip:pi@222.222.222.222:5060;ob
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Supported: replaces, 100rel, timer, norefersub
Content-Type: application/sdp
Content-Length: 391
v=0
o=- 3605072658 3605072660 IN IP4 222.222.222.222
s=pjmedia
b=AS:84
t=0 0
a=X-nat:0
m=audio 4000 RTP/AVP 9 101
c=IN IP4 222.222.222.222
b=TIAS:64000
a=rtcp:4001 IN IP4 222.222.222.222
a=sendrecv
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
m=video 0 RTP/AVP 126 97 96 34 31
c=IN IP4 127.0.0.1
b=AS:2000
m=application 0 UDP/BFCP *
c=IN IP4 127.0.0.1
--end msg--
09:04:19.964 tsx0xde1be4 .......State changed from Trying to Completed, event=TX_MSG
09:04:19.964 dlg0xde0fcc ........Transaction tsx0xde1be4 state changed to Completed
09:04:19.991 speex !warning: No playback frame available (your application is buggy and/or got xruns)
09:04:19.993 speex !warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:19.995 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:20.083 strm0xe1de84 Frame lost, recovered!
09:04:20.133 strm0xe1de84 Jitter buffer starts returning normal frames (after 1 empty/lost)
09:04:20.133 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:20.135 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:20.273 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:20.275 strm0xe1de84 Frame lost, recovered!
09:04:20.275 strm0xe1de84 Jitter buffer starts returning normal frames (after 1 empty/lost)
09:04:20.275 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:20.413 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:20.415 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:20.474 strm0xe1de84 Frame lost, recovered!
09:04:20.474 strm0xe1de84 Jitter buffer starts returning normal frames (after 1 empty/lost)
09:04:20.553 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:20.555 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:20.693 strm0xe1de84 Frame lost, recovered!
09:04:20.694 strm0xe1de84 Jitter buffer starts returning normal frames (after 1 empty/lost)
09:04:20.694 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:20.695 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:20.833 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:20.835 strm0xe1de84 Frame lost, recovered!
09:04:20.835 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:20.863 strm0xe1de84 Jitter buffer starts returning normal frames (after 1 empty/lost)
09:04:20.973 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:20.975 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:21.034 strm0xe1de84 Frame lost, recovered!
09:04:21.034 strm0xe1de84 Jitter buffer starts returning normal frames (after 1 empty/lost)
09:04:21.113 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:21.115 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:21.254 strm0xe1de84 Frame lost, recovered!
09:04:21.254 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:21.255 strm0xe1de84 Jitter buffer starts returning normal frames (after 1 empty/lost)
09:04:21.256 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:21.393 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:21.395 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:21.454 strm0xe1de84 Frame lost, recovered!
09:04:21.454 strm0xe1de84 Jitter buffer starts returning normal frames (after 1 empty/lost)
09:04:21.533 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:21.535 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:21.673 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:21.675 strm0xe1de84 Frame lost, recovered!
09:04:21.675 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:21.703 strm0xe1de84 Jitter buffer starts returning normal frames (after 1 empty/lost)
09:04:21.813 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:21.815 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:21.903 strm0xe1de84 Frame lost, recovered!
09:04:21.904 strm0xe1de84 Jitter buffer starts returning normal frames (after 1 empty/lost)
09:04:21.953 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:21.954 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:22.093 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:22.095 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:22.154 strm0xe1de84 Frame lost, recovered!
09:04:22.183 strm0xe1de84 Jitter buffer starts returning normal frames (after 1 empty/lost)
09:04:22.233 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:22.234 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:22.373 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:22.375 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:22.434 strm0xe1de84 Frame lost, recovered!
09:04:22.434 strm0xe1de84 Jitter buffer starts returning normal frames (after 1 empty/lost)
09:04:22.513 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:22.515 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:22.653 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:22.655 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:22.793 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:22.795 strm0xe1de84 Frame lost, recovered!
09:04:22.795 strm0xe1de84 Jitter buffer starts returning normal frames (after 1 empty/lost)
09:04:22.795 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:22.933 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:22.935 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:23.073 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:23.075 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:23.213 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:23.215 strm0xe1de84 Frame lost, recovered!
09:04:23.215 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:23.243 strm0xe1de84 Jitter buffer starts returning normal frames (after 1 empty/lost)
09:04:23.353 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:23.355 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:23.493 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:23.495 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:23.583 silencedet.c Re-adjust threshold (in silence)to 0
09:04:23.633 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:23.635 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:23.773 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:23.775 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:23.834 strm0xe1de84 Start talksprut..
09:04:23.863 strm0xe1de84 Starting silence
09:04:23.913 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:23.915 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:24.053 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:24.055 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:24.193 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:24.194 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:24.333 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:24.335 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:24.473 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:24.475 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:24.613 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:24.615 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:24.753 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:24.754 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:24.893 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:24.895 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:25.033 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:25.035 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:25.173 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:25.175 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:25.313 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:25.314 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:25.453 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:25.455 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:25.593 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:25.594 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:25.733 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:25.735 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:25.873 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:25.874 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:26.013 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:26.015 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:26.153 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:26.154 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:26.293 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:26.294 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:26.433 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:26.435 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:26.573 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:26.575 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:26.713 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:26.714 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:26.853 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:26.854 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:26.987 pjsua_acc.c !Sending 2 bytes keep-alive packet for acc 3 to 222.222.222.1:5060
09:04:26.988 tdta0xde9810 Destroying txdata raw
09:04:26.993 speex !warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:26.994 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:27.133 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:27.134 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:27.273 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:27.275 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:27.413 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:27.415 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:27.553 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:27.554 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:27.583 silencedet.c Re-adjust threshold (in silence)to 0
09:04:27.693 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:27.694 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:27.833 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:27.834 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:27.973 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:27.975 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:28.113 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:28.114 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:28.253 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:28.255 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:28.393 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:28.394 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:28.531 sip_endpoint.c !Processing incoming message: Request msg BYE/cseq=3 (rdata0xdd8b74)
09:04:28.531 pjsua_core.c .RX 1303 bytes Request msg BYE/cseq=3 (rdata0xdd8b74) from UDP 222.222.222.1:5060:
BYE sip:pi@222.222.222.222:5060;ob SIP/2.0
Via: SIP/2.0/UDP 222.222.222.1:5060;egress-zone=DefaultSubZone;branch=z9hG4bK47e0c65c8faea51fa147e2fbed45470c907579.16b26c94fddfcc8e7270188161369418;proxy-call-id=a8e7a434-fd54-11e3-9bf8-0010f30f5a60;rport
Via: SIP/2.0/TLS 111.111.111.111:7029;egress-zone=OurUniversity;branch=z9hG4bK786e05648a0b2de9f7035391276d21d561304.8edbe65b1b55c77532ca579af82eea53;proxy-call-id=a8e1e0f8-fd54-11e3-89fe-0010f30f56f0;received=111.111.111.111;rport=7029;ingress-zone=OurUniversity
Via: SIP/2.0/TLS 9.9.9.9:5061;egress-zone=dnsout;branch=z9hG4bK6002b76c47dc04becb08051a3f37b7371214355.9ecf1523bb6f1feb6b39c5f18c6fe9e8;proxy-call-id=bc33e9ef-3d31-40ec-9417-c44e1323e9f6;received=9.9.9.9;rport=26521;ingress-zone=DefaultZone
Via: SIP/2.0/TCP 1.1.1.1:5060;branch=z9hG4bK1742939d;received=1.1.1.1;ingress-zone=DefaultSubZone
Call-ID: C67D8C82@1.1.1.1
CSeq: 3 BYE
Contact: sip:8510@thierdomain.ac.uk;gr=urn:uuid:e42a7487-dd80-52f6-b21b-c718aaff8e59;isfocus
From: "CONFERENCE2" sip:8510@thierdomain.ac.uk;tag=71193C78D0B60001
To: sip:pi@mydomain.ac.uk;tag=R3gbdJaQddbbOldCYhjdJ5Y5CaXTtRra
Max-Forwards: 67
User-Agent: Codian MSE 8510 v4.4 (3.42)
Supported: timer,outbound
X-TAATag: 74766091-9c9c-4970-bccb-77a8c4d6818d
Content-Length: 0
--end msg--
09:04:28.535 strm0xe1de84 !Jitter buffer empty (prefetch=0), plc invoked
09:04:28.536 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:28.547 dlg0xde0fcc !.Received Request msg BYE/cseq=3 (rdata0xdd8b74)
09:04:28.547 tsx0xe1d124 ...Transaction created for Request msg BYE/cseq=3 (rdata0xdd8b74)
09:04:28.547 tsx0xe1d124 ..Incoming Request msg BYE/cseq=3 (rdata0xdd8b74) in state Null
09:04:28.547 tsx0xe1d124 ...State changed from Null to Trying, event=RX_MSG
09:04:28.547 dlg0xde0fcc ....Transaction tsx0xe1d124 state changed to Trying
09:04:28.547 endpoint .....Response msg 200/BYE/cseq=3 (tdta0xde9810) created
09:04:28.548 dlg0xde0fcc ......Sending Response msg 200/BYE/cseq=3 (tdta0xde9810)
09:04:28.548 tsx0xe1d124 ......Sending Response msg 200/BYE/cseq=3 (tdta0xde9810) in state Trying
09:04:28.548 pjsua_core.c .......TX 1080 bytes Response msg 200/BYE/cseq=3 (tdta0xde9810) to UDP 222.222.222.1:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 222.222.222.1:5060;rport=5060;received=222.222.222.1;branch=z9hG4bK47e0c65c8faea51fa147e2fbed45470c907579.16b26c94fddfcc8e7270188161369418;egress-zone=DefaultSubZone;proxy-call-id=a8e7a434-fd54-11e3-9bf8-0010f30f5a60
Via: SIP/2.0/TLS 111.111.111.111:7029;rport=7029;received=111.111.111.111;branch=z9hG4bK786e05648a0b2de9f7035391276d21d561304.8edbe65b1b55c77532ca579af82eea53;egress-zone=OurUniversity;proxy-call-id=a8e1e0f8-fd54-11e3-89fe-0010f30f56f0;ingress-zone=OurUniversity
Via: SIP/2.0/TLS 9.9.9.9:5061;rport=26521;received=9.9.9.9;branch=z9hG4bK6002b76c47dc04becb08051a3f37b7371214355.9ecf1523bb6f1feb6b39c5f18c6fe9e8;egress-zone=dnsout;proxy-call-id=bc33e9ef-3d31-40ec-9417-c44e1323e9f6;ingress-zone=DefaultZone
Via: SIP/2.0/TCP 1.1.1.1:5060;received=1.1.1.1;branch=z9hG4bK1742939d;ingress-zone=DefaultSubZone
Call-ID: C67D8C82@1.1.1.1
From: "CONFERENCE2" sip:8510@thierdomain.ac.uk;tag=71193C78D0B60001
To: sip:pi@mydomain.ac.uk;tag=R3gbdJaQddbbOldCYhjdJ5Y5CaXTtRra
CSeq: 3 BYE
Content-Length: 0
--end msg--
09:04:28.551 speex !warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:28.572 tsx0xe1d124 !.......State changed from Trying to Completed, event=TX_MSG
09:04:28.572 dlg0xde0fcc ........Transaction tsx0xe1d124 state changed to Completed
09:04:28.572 pjsua_app.c ......Call 0 is DISCONNECTED [reason=200 (Normal call clearing)]
09:04:28.575 pjsua_app.c ......Call 0 disconnected, dumping media stats..
09:04:28.576 pjsua_app_comm ......
[DISCONNCTD] To: "CONFERENCE2" sip:8510@thierdomain.ac.uk;tag=71193C78D0B60001
Call time: 00h:00m:09s, 1st res in 59 ms, conn in 264ms
#0 audio G722 @16kHz, sendrecv, peer=222.222.222.1:51622
SRTP status: Not active Crypto-suite:
RX pt=9, last update:00h:00m:09.221s ago
total 467pkt 74.7KB (93.4KB +IP hdr) @avg=62.2Kbps/77.7Kbps
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.210 0.875 0.125 0.154
TX pt=9, ptime=20, last update:00h:00m:02.478s ago
total 32pkt 5.0KB (6.3KB +IP hdr) @avg=4.1Kbps/5.2Kbps
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 : 4.875 4.875 4.875 4.875 0.000
RTT msec : 0.000 0.000 0.000 0.000 0.000
#1 unknown deactivated
#2 unknown deactivated
09:04:28.582 pjsua_media.c ......Call 0: deinitializing media..
09:04:28.583 strm0xe1de84 ........JB summary:
size=0/eff=0 prefetch=0 level=4
delay (min/max/avg/dev)=70/240/106/47 ms
burst (min/max/avg/dev)=2/4/3/0 frames
lost=15 discard=0 empty=21
09:04:28.583 pjsua_media.c ........Media stream call00:0 is destroyed
09:04:28.584 pjsua_media.c ........Media stream call00:1 is destroyed
09:04:28.584 pjsua_media.c ........Media stream call00:2 is destroyed
09:04:28.584 dlg0xde0fcc ......Session count dec to 3 by mod-invite
09:04:28.596 speex !warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:28.596 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:28.621 speex !warning: No playback frame available (your application is buggy and/or got xruns)
09:04:28.622 speex !warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:28.673 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:28.673 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:28.812 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:28.813 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:28.953 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:28.953 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:29.092 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:29.093 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:29.232 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:29.233 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:29.372 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:29.373 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:29.513 speex warning: Auto-filling the buffer (your application is buggy and/or got xruns)
09:04:29.513 speex warning: Had to discard a playback frame (your application is buggy and/or got xruns)
09:04:29.582 pjsua_aud.c !Closing sound device after idle for 1 second(s)
09:04:29.584 pjsua_app.c .Turning sound device OFF
09:04:29.585 pjsua_aud.c .Closing default:CARD=ALSA sound playback device and default:CARD=Dummy sound capture device
09:04:41.417 pjsua_core.c !Shutting down, flags=0...
09:04:41.418 pjsua_core.c PJSUA state changed: RUNNING --> CLOSING
09:04:41.425 pjsua_call.c .Hangup all calls..
09:04:41.426 pjsua_media.c .Call 0: deinitializing media..
09:04:41.428 pjsua_pres.c .Shutting down presence..
09:04:41.430 pjsua_acc.c .Acc 3: setting unregistration..
09:04:41.432 endpoint ..Request msg REGISTER/cseq=24270 (tdta0xe22160) created.
09:04:41.432 tsx0xe231cc ...Transaction created for Request msg REGISTER/cseq=24271 (tdta0xe22160)
09:04:41.432 tsx0xe231cc ..Sending Request msg REGISTER/cseq=24271 (tdta0xe22160) in state Null
09:04:41.432 sip_resolve.c ...DNS resolver not available, target 'vcs.mydomain.ac.uk:0' type=Unspecified will be resolved with getaddrinfo()
09:04:41.434 sip_resolve.c ...Target 'vcs.mydomain.ac.uk:0' type=Unspecified resolved to '222.222.222.1:5060' type=UDP (UDP transport)
09:04:41.434 pjsua_core.c ...TX 438 bytes Request msg REGISTER/cseq=24271 (tdta0xe22160) to UDP 222.222.222.1:5060:
REGISTER sip:vcs.mydomain.ac.uk SIP/2.0
Via: SIP/2.0/UDP 222.222.222.222:5060;rport;branch=z9hG4bKPj6-rBuPWXpvqtQvDjApSz8-FkZZ7ZlNam
Max-Forwards: 70
From: sip:pi@mydomain.ac.uk;tag=gsAF9Gl66vfJG8GVkseqKyTY4psar4SY
To: sip:pi@mydomain.ac.uk
Call-ID: tuaaqJ-yv0dfsMPfd.ucRmeKYOEvt7oS
CSeq: 24271 REGISTER
User-Agent: PJSUA v2.2.1 Linux-3.12.22/armv6l/glibc-2.13
Contact: sip:pi@222.222.222.222:5060;ob
Expires: 0
Content-Length: 0
--end msg--
09:04:41.440 tsx0xe231cc ...State changed from Null to Calling, event=TX_MSG
09:04:41.440 pjsua_acc.c ..Acc 3: Unregistration sent
09:04:41.445 sip_endpoint.c .Processing incoming message: Response msg 200/REGISTER/cseq=24271 (rdata0xdd8b74)
09:04:41.445 pjsua_core.c ..RX 461 bytes Response msg 200/REGISTER/cseq=24271 (rdata0xdd8b74) from UDP 222.222.222.1:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 222.222.222.222:5060;branch=z9hG4bKPj6-rBuPWXpvqtQvDjApSz8-FkZZ7ZlNam;received=222.222.222.222;rport=5060
Call-ID: tuaaqJ-yv0dfsMPfd.ucRmeKYOEvt7oS
CSeq: 24271 REGISTER
From: sip:pi@mydomain.ac.uk;tag=gsAF9Gl66vfJG8GVkseqKyTY4psar4SY
To: sip:pi@mydomain.ac.uk;tag=27ad3bdee3050157
Server: TANDBERG/4120 (X7.2.3)
Date: Thu, 26 Jun 2014 17:10:03 GMT
Supported: com.tandberg.sdp.extensions.v1,gruu,outbound,path
Content-Length: 0
--end msg--
Cheers
Chris
Visit our blog: http://blog.pjsip.org
pjsip mailing list
pjsip@lists.pjsip.org
http://lists.pjsip.org/mailman/listinfo/pjsip_lists.pjsip.org
-----Original Message-----
From: Bill Gardner
Subject: Re: [pjsip] Possible bug (Buffer overflow) - PJSUA unable to handle
SIP message sent via Cisco 8510 MCU when registered to a Cisco VCS via
TCP/TLS
Hi,
Try increasing PJSIP_MAX_PKT_LEN (default 4000). I think you may be
going over that, the SDP is 2660 bytes and the header has a lot of
Record-Route lines. I find it surprising that it works correctly using UDP.
Bill
Thanks Bill. I added the line:
# define PJSIP_MAX_PKT_LEN 6000
To the config_site.h file and re-compiled, then re-tested. This time all SIP calls from various types of MCU through the VCSs worked as expected. Many thanks for this.
Can I take it that we may well have been on the cusp of a buffer limit with the default setting, hence the reason why it worked with UDP but not TCP? With some MCUs (an SD Cisco 8420) we also previously also received a "Bad SDP" error, but this too has disappeared. What are the downsides of increasing the buffer size? I have chosen 6000 as an arbitrary figure, but is this too much?
Many thanks
Chris
Hi Chris,
I'm glad its working. I'd assume increasing the max packet length is
harmless except for increased memory usage. I would have expected the
UDP transport to trigger some kind of error if the SDP was cropped, the
code can check the number of bytes read and compare against the
content-length header to detect this.
Bill
On 6/27/2014 12:23 PM, Swinney C. wrote:
-----Original Message-----
From: Bill Gardner
Subject: Re: [pjsip] Possible bug (Buffer overflow) - PJSUA unable to handle
SIP message sent via Cisco 8510 MCU when registered to a Cisco VCS via
TCP/TLS
Hi,
Try increasing PJSIP_MAX_PKT_LEN (default 4000). I think you may be
going over that, the SDP is 2660 bytes and the header has a lot of
Record-Route lines. I find it surprising that it works correctly using UDP.
Bill
Thanks Bill. I added the line:
# define PJSIP_MAX_PKT_LEN 6000
To the config_site.h file and re-compiled, then re-tested. This time all SIP calls from various types of MCU through the VCSs worked as expected. Many thanks for this.
Can I take it that we may well have been on the cusp of a buffer limit with the default setting, hence the reason why it worked with UDP but not TCP? With some MCUs (an SD Cisco 8420) we also previously also received a "Bad SDP" error, but this too has disappeared. What are the downsides of increasing the buffer size? I have chosen 6000 as an arbitrary figure, but is this too much?
Many thanks
Chris