[Ekiga-list] Receiving SIP RTP before media description [was ekiga answers with delay ...?]

Damien Sandras dsandras at seconix.com
Mon Nov 27 09:51:16 UTC 2006


Le lundi 27 novembre 2006 à 20:40 +1100, Craig Southeren a écrit :

> 
> What is OPAL doing during the one second gap? 
> 


Haven't you received the output.txt file?

Here it is again.

It is just doing stuff and starting threads and so on ;-)
-- 
 _      Damien Sandras
(o-     
//\   Ekiga Softphone : http://www.ekiga.org/
v_/_ NOVACOM         : http://www.novacom.be/
          FOSDEM          : http://www.fosdem.org/
          SIP Phone       : sip:dsandras at ekiga.net
                       
-------------- next part --------------
2006/11/27 10:23:56.481	  0:00.234	       ekiga	Detected audio plugins: ALSA,OSS,ESD
2006/11/27 10:23:56.482	  0:00.234	       ekiga	Detected video plugins: YUVFile,Shm,Picture,V4L
2006/11/27 10:23:56.482	  0:00.234	       ekiga	Detected audio plugins: ALSA,OSS,ESD
2006/11/27 10:23:56.482	  0:00.234	       ekiga	Detected video plugins: YUVFile,Shm,Picture,V4L
2006/11/27 10:23:56.488	  0:00.240	       ekiga	Detected the following audio input devices: SiS SI7012,SiS SI7013 Modem,Default with plugin ALSA
2006/11/27 10:23:56.488	  0:00.240	       ekiga	Detected the following audio output devices: SiS SI7012,SiS SI7013 Modem,Default with plugin ALSA
2006/11/27 10:23:56.488	  0:00.240	       ekiga	Detected the following video input devices: StaticPicture,MovingLogo with plugin Picture
2006/11/27 10:23:56.488	  0:00.240	       ekiga	Detected the following audio input devices: SiS SI7012,SiS SI7013 Modem,Default with plugin ALSA
2006/11/27 10:23:56.488	  0:00.240	       ekiga	Detected the following audio output devices: SiS SI7012,SiS SI7013 Modem,Default with plugin ALSA
2006/11/27 10:23:56.488	  0:00.240	       ekiga	Detected the following video input devices: StaticPicture,MovingLogo with plugin Picture
2006/11/27 10:23:56.960	  0:00.713	       ekiga	Ekiga version 2.1.0
2006/11/27 10:23:56.961	  0:00.713	       ekiga	OPAL version 2.3.2
2006/11/27 10:23:56.961	  0:00.713	       ekiga	PWLIB version 1.11.2
2006/11/27 10:23:56.961	  0:00.713	       ekiga	GNOME support disabled
2006/11/27 10:23:56.961	  0:00.713	       ekiga	Fullscreen support enabled
2006/11/27 10:23:56.961	  0:00.714	       ekiga	DBUS support enabled
2006/11/27 10:23:56.964	  0:00.716	       ekiga	Set TCP port range to 30000:30010
2006/11/27 10:23:56.964	  0:00.716	       ekiga	Set RTP port range to 5000:5059
2006/11/27 10:23:56.964	  0:00.716	       ekiga	Set UDP port range to 5060:5100
2006/11/27 10:23:56.964	  0:00.717	       ekiga	OpalEP	Created endpoint: h323
2006/11/27 10:23:56.965	  0:00.717	       ekiga	H460	Endpoint Attached
2006/11/27 10:23:56.965	  0:00.717	       ekiga	H323	Created endpoint.
2006/11/27 10:23:56.965	  0:00.717	       ekiga	OpalMan	Added route "pc:.*=h323:<da>"
2006/11/27 10:23:56.966	  0:00.718	       ekiga	OpalEP	Created endpoint: sip
2006/11/27 10:23:56.966	  0:00.718	       ekiga	SIP	Created endpoint.
2006/11/27 10:23:56.966	  0:00.718	       ekiga	OpalMan	Added route "pc:.*=sip:<da>"
2006/11/27 10:23:56.966	  0:00.718	       ekiga	OpalEP	Created endpoint: pc
2006/11/27 10:23:57.012	  0:00.765	       ekiga	PCSS	Created PC sound system endpoint.
2006/11/27 10:23:57.013	  0:00.765	       ekiga	OpalMan	Added route "h323:.*=pc:<da>"
2006/11/27 10:23:57.013	  0:00.765	       ekiga	OpalMan	Added route "sip:.*=pc:<da>"
2006/11/27 10:23:57.018	  0:00.770	Opal Liste...er:83f93c8	Listen	Started listening thread on udp$172.16.100.166:1720
2006/11/27 10:23:57.018	  0:00.770	Opal Liste...er:83f93c8	Listen	Waiting on UDP packet on udp$172.16.100.166:1720
2006/11/27 10:23:57.019	  0:00.771	Opal Liste...er:83fab50	Listen	Started listening thread on udp$172.16.100.166:5060
2006/11/27 10:23:57.019	  0:00.771	Opal Liste...er:83fab50	Listen	Waiting on UDP packet on udp$172.16.100.166:5060
2006/11/27 10:23:57.043	  0:00.795	       ekiga	Detected audio codecs: G.711-ALaw-64k,G.711-uLaw-64k,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,iLBC-13k3,LPC-10,SpeexNB,SpeexIETFNarrow-8k,SpeexWNarrow-8k,SpeexWB,SpeexIETFWide-20.6k
2006/11/27 10:23:57.092	  0:00.844	       ekiga	Detected video codecs: H.261,H.261-CIF,H.261-QCIF
2006/11/27 10:23:57.179	  0:00.931	GMAccounts...t:083fd178	SIP	Could not find active REGISTER for dsandras at ekiga.net
2006/11/27 10:23:57.179	  0:00.931	GMAccounts...t:083fd178	SIP	Could not find active REGISTER for damien.sandras at voip.wengo.fr
2006/11/27 10:23:57.221	  0:00.973	GMAccounts...t:083fd178	OpalUDP	Binding to interface: 172.16.100.166:32791
2006/11/27 10:23:57.221	  0:00.973	GMAccounts...t:083fd178	SIP	Created transport udp$0.0.0.0<if=udp$172.16.100.166:32791>
2006/11/27 10:23:57.222	  0:00.974	GMAccounts...t:083fd178	OpalUDP	Started connect to 172.16.100.198:5060
2006/11/27 10:23:57.223	  0:00.975	GMAccounts...t:083fd178	OpalUDP	Connect on pre-bound interface: 172.16.100.166
2006/11/27 10:23:57.224	  0:00.976	GMAccounts...t:083fd178	SIP	Created new transport for 172.16.100.198 : udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
2006/11/27 10:23:57.230	  0:00.982	GMAccounts...t:083fd178	SIP	Sending PDU on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
REGISTER sip:172.16.100.198 SIP/2.0
CSeq: 1 REGISTER
Via: SIP/2.0/UDP 172.16.100.166:5061;branch=z9hG4bK841b39ad-667c-db11-8a96-00c09f2d8163;rport
User-Agent: Ekiga/2.1.0
From: <sip:9197 at 172.16.100.198>;tag=1e0439ad-667c-db11-8a96-00c09f2d8163
Call-ID: f49337ad-667c-db11-8a96-00c09f2d8163 at golgoth01
To: <sip:9197 at 172.16.100.198>
Contact: <sip:9197 at 172.16.100.166:5061;transport=udp>
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,NOTIFY,REFER,MESSAGE,INFO,PING,PUBLISH
Expires: 3600
Content-Length: 0
Max-Forwards: 70


2006/11/27 10:23:57.232	  0:00.984	GMAccounts...t:083fd178	SIP	Could not find active REGISTER for 274738 at fwd.pulver.com
2006/11/27 10:23:57.232	  0:00.984	GMAccounts...t:083fd178	SIP	Could not find active REGISTER for 06213804954 at sip2.neophonex.hu
2006/11/27 10:23:57.233	  0:00.985	GMAccounts...t:083fd178	SIP	Could not find active REGISTER for 6359 at itbx-polymedis
2006/11/27 10:23:57.233	  0:00.985	SIP Transp...t:b5e2cb40	SIP	Read thread started.
2006/11/27 10:23:57.234	  0:00.986	SIP Transp...t:b5e2cb40	SIP	Waiting for PDU on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
2006/11/27 10:23:57.235	  0:00.987	SIP Transp...t:b5e2cb40	SIP	PDU Received on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
SIP/2.0 100 Trying
CSeq: 1 REGISTER
Via: SIP/2.0/UDP 172.16.100.166:5061;branch=z9hG4bK841b39ad-667c-db11-8a96-00c09f2d8163;rport;received=172.16.100.166
User-Agent: Asterisk PBX
From: <sip:9197 at 172.16.100.198>;tag=1e0439ad-667c-db11-8a96-00c09f2d8163
Call-ID: f49337ad-667c-db11-8a96-00c09f2d8163 at golgoth01
To: <sip:9197 at 172.16.100.198>
Contact: <sip:9197 at 172.16.100.198>
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0


2006/11/27 10:23:57.237	  0:00.989	SIP Transp...t:b5e2cb40	SIP	Transaction 1 REGISTER proceeding.
2006/11/27 10:23:57.237	  0:00.989	SIP Transp...t:b5e2cb40	OpalUDP	Ended connect, selecting 172.16.100.166:5061
2006/11/27 10:23:57.238	  0:00.990	SIP Transp...t:b5e2cb40	SIP	Waiting for PDU on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
2006/11/27 10:23:57.239	  0:00.991	SIP Transp...t:b5e2cb40	SIP	PDU Received on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
SIP/2.0 401 Unauthorized
CSeq: 1 REGISTER
Via: SIP/2.0/UDP 172.16.100.166:5061;branch=z9hG4bK841b39ad-667c-db11-8a96-00c09f2d8163;rport;received=172.16.100.166
User-Agent: Asterisk PBX
From: <sip:9197 at 172.16.100.198>;tag=1e0439ad-667c-db11-8a96-00c09f2d8163
Call-ID: f49337ad-667c-db11-8a96-00c09f2d8163 at golgoth01
To: <sip:9197 at 172.16.100.198>;tag=as4b2f79e2
Contact: <sip:9197 at 172.16.100.198>
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0
WWW-Authenticate: Digest algorithm=MD5, realm="novacom", nonce="2c83b4d4"


2006/11/27 10:23:57.240	  0:00.992	SIP Transp...t:b5e2cb40	SIP	Transaction 1 REGISTER completed.
2006/11/27 10:23:57.240	  0:00.993	SIP Transp...t:b5e2cb40	SIP	Received Authentication Required response
2006/11/27 10:23:57.241	  0:00.993	SIP Transp...t:b5e2cb40	SIP	Updated realm to novacom
2006/11/27 10:23:57.245	  0:00.997	SIP Transp...t:b5e2cb40	SIP	Adding authentication information
2006/11/27 10:23:57.246	  0:00.998	SIP Transp...t:b5e2cb40	SIP	Sending PDU on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
REGISTER sip:172.16.100.198 SIP/2.0
CSeq: 2 REGISTER
Via: SIP/2.0/UDP 172.16.100.166:5061;branch=z9hG4bKd6a83bad-667c-db11-8a96-00c09f2d8163;rport
User-Agent: Ekiga/2.1.0
Authorization: Digest username="9197", realm="novacom", nonce="2c83b4d4", uri="sip:172.16.100.198", algorithm=md5, response="c45a43a6d7ee52b35ea35860d0344cd2"
From: <sip:9197 at 172.16.100.198>;tag=1e0439ad-667c-db11-8a96-00c09f2d8163
Call-ID: f49337ad-667c-db11-8a96-00c09f2d8163 at golgoth01
To: <sip:9197 at 172.16.100.198>
Contact: <sip:9197 at 172.16.100.166:5061;transport=udp>
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,NOTIFY,REFER,MESSAGE,INFO,PING,PUBLISH
Expires: 3600
Content-Length: 0
Max-Forwards: 70


2006/11/27 10:23:57.247	  0:00.999	SIP Transp...t:b5e2cb40	SIP	Waiting for PDU on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
2006/11/27 10:23:57.248	  0:01.000	SIP Transp...t:b5e2cb40	SIP	PDU Received on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
SIP/2.0 100 Trying
CSeq: 2 REGISTER
Via: SIP/2.0/UDP 172.16.100.166:5061;branch=z9hG4bKd6a83bad-667c-db11-8a96-00c09f2d8163;rport;received=172.16.100.166
User-Agent: Asterisk PBX
From: <sip:9197 at 172.16.100.198>;tag=1e0439ad-667c-db11-8a96-00c09f2d8163
Call-ID: f49337ad-667c-db11-8a96-00c09f2d8163 at golgoth01
To: <sip:9197 at 172.16.100.198>
Contact: <sip:9197 at 172.16.100.198>
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0


2006/11/27 10:23:57.249	  0:01.002	SIP Transp...t:b5e2cb40	SIP	Transaction 2 REGISTER proceeding.
2006/11/27 10:23:57.250	  0:01.002	SIP Transp...t:b5e2cb40	SIP	Waiting for PDU on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
2006/11/27 10:23:57.252	  0:01.004	SIP Transp...t:b5e2cb40	SIP	PDU Received on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
OPTIONS sip:9197 at 172.16.100.166:5061;transport=udp SIP/2.0
Date: Mon, 27 Nov 2006 09:51:08 GMT
CSeq: 102 OPTIONS
Via: SIP/2.0/UDP 172.16.100.198:5060;branch=z9hG4bK50a7bacb;rport
User-Agent: Asterisk PBX
From: "asterisk" <sip:asterisk at 172.16.100.198>;tag=as085fd1a2
Call-ID: 5d4666de20206c12242782446b178996 at 172.16.100.198
To: <sip:9197 at 172.16.100.166:5061;transport=udp>
Contact: <sip:asterisk at 172.16.100.198>
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0
Max-Forwards: 70


2006/11/27 10:23:57.253	  0:01.006	SIP Transp...t:b5e2cb40	SIP	Tranport remote address change from Via: udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
2006/11/27 10:23:57.256	  0:01.008	SIP Transp...t:b5e2cb40	SIP	Sending PDU on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
SIP/2.0 200 OK
CSeq: 102 OPTIONS
Via: SIP/2.0/UDP 172.16.100.198:5060;branch=z9hG4bK50a7bacb;rport=5060;received=172.16.100.198
From: "asterisk" <sip:asterisk at 172.16.100.198>;tag=as085fd1a2
Call-ID: 5d4666de20206c12242782446b178996 at 172.16.100.198
To: <sip:9197 at 172.16.100.166:5061;transport=udp>
Contact: <sip:9197 at 172.16.100.166:5061;transport=udp>
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,NOTIFY,REFER,MESSAGE,INFO,PING,PUBLISH
Content-Length: 0


2006/11/27 10:23:57.257	  0:01.009	SIP Transp...t:b5e2cb40	SIP	Waiting for PDU on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
2006/11/27 10:23:57.258	  0:01.010	SIP Transp...t:b5e2cb40	SIP	PDU Received on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
SIP/2.0 200 OK
Date: Mon, 27 Nov 2006 09:51:08 GMT
CSeq: 2 REGISTER
Via: SIP/2.0/UDP 172.16.100.166:5061;branch=z9hG4bKd6a83bad-667c-db11-8a96-00c09f2d8163;rport;received=172.16.100.166
User-Agent: Asterisk PBX
From: <sip:9197 at 172.16.100.198>;tag=1e0439ad-667c-db11-8a96-00c09f2d8163
Call-ID: f49337ad-667c-db11-8a96-00c09f2d8163 at golgoth01
To: <sip:9197 at 172.16.100.198>;tag=as4b2f79e2
Contact: <sip:9197 at 172.16.100.166:5061;transport=udp>;expires=3600
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Expires: 3600
Content-Length: 0


2006/11/27 10:23:57.259	  0:01.011	SIP Transp...t:b5e2cb40	SIP	Transaction 2 REGISTER completed.
2006/11/27 10:23:57.356	  0:01.108	SIP Transp...t:b5e2cb40	SIP	Using existing transport udp$172.16.100.198:5060<if=udp$172.16.100.166:5061> for 172.16.100.198
2006/11/27 10:23:57.361	  0:01.113	GMAccounts...t:083fd178	SIP	Using existing transport udp$172.16.100.198:5060<if=udp$172.16.100.166:5061> for 172.16.100.198
2006/11/27 10:23:57.361	  0:01.113	SIP Transp...t:b5e2cb40	SIP	Sending PDU on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
PUBLISH sip:9197 at 172.16.100.198 SIP/2.0
CSeq: 3 PUBLISH
Via: SIP/2.0/UDP 172.16.100.166:5061;branch=z9hG4bK98204dad-667c-db11-8a96-00c09f2d8163;rport
User-Agent: Ekiga/2.1.0
From: <sip:9197 at 172.16.100.198>;tag=18194dad-667c-db11-8a96-00c09f2d8163
Call-ID: e6f54cad-667c-db11-8a96-00c09f2d8163 at golgoth01
To: <sip:9197 at 172.16.100.198>
Contact: <sip:9197 at 172.16.100.166:5061;transport=udp>
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,NOTIFY,REFER,MESSAGE,INFO,PING,PUBLISH
Expires: 500
Event: presence
Content-Type: application/pidf+xml
Content-Length: 310
Max-Forwards: 70

<?xml version="1.0" encoding="UTF-8"?>
<presence xmlns="urn:ietf:params:xml:ns:pidf" entity="pres:9197 at 172.16.100.198">
<tuple id="daa24cad-667c-db11-8a96-00c09f2d8163">
<note>Away</note>
<status>
<basic>open</basic>
</status>
<contact priority="1">9197 at 172.16.100.198</contact>
</tuple>
</presence>

2006/11/27 10:23:57.362	  0:01.114	SIP Transp...t:b5e2cb40	SIP	Waiting for PDU on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
2006/11/27 10:23:57.363	  0:01.115	SIP Transp...t:b5e2cb40	SIP	PDU Received on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
SIP/2.0 501 Method Not Implemented
CSeq: 3 PUBLISH
Via: SIP/2.0/UDP 172.16.100.166:5061;branch=z9hG4bK98204dad-667c-db11-8a96-00c09f2d8163;rport;received=172.16.100.166
User-Agent: Asterisk PBX
From: <sip:9197 at 172.16.100.198>;tag=18194dad-667c-db11-8a96-00c09f2d8163
Call-ID: e6f54cad-667c-db11-8a96-00c09f2d8163 at golgoth01
To: <sip:9197 at 172.16.100.198>;tag=as6c1fb783
Accept: application/sdp
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0


2006/11/27 10:23:57.364	  0:01.116	SIP Transp...t:b5e2cb40	SIP	Transaction 3 PUBLISH completed.
2006/11/27 10:23:57.365	  0:01.117	SIP Transp...t:b5e2cb40	SIP	Waiting for PDU on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
2006/11/27 10:23:57.365	  0:01.118	GMAccounts...t:083fd178	SIP	Sending PDU on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
SUBSCRIBE sip:9105 at 172.16.100.198 SIP/2.0
CSeq: 1 SUBSCRIBE
Via: SIP/2.0/UDP 172.16.100.166:5061;branch=z9hG4bKc4f34dad-667c-db11-8a96-00c09f2d8163;rport
User-Agent: Ekiga/2.1.0
From: <sip:9197 at 172.16.100.198>;tag=eac34dad-667c-db11-8a96-00c09f2d8163
Call-ID: 127e4dad-667c-db11-8a96-00c09f2d8163 at golgoth01
To: <sip:9105 at 172.16.100.198>
Contact: <sip:9197 at 172.16.100.166:5061;transport=udp>
Accept: application/pidf+xml
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,NOTIFY,REFER,MESSAGE,INFO,PING,PUBLISH
Expires: 500
Event: presence
Content-Length: 0
Max-Forwards: 70


2006/11/27 10:23:57.367	  0:01.120	SIP Transp...t:b5e2cb40	SIP	PDU Received on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
SIP/2.0 401 Unauthorized
CSeq: 1 SUBSCRIBE
Via: SIP/2.0/UDP 172.16.100.166:5061;branch=z9hG4bKc4f34dad-667c-db11-8a96-00c09f2d8163;rport;received=172.16.100.166
User-Agent: Asterisk PBX
From: <sip:9197 at 172.16.100.198>;tag=eac34dad-667c-db11-8a96-00c09f2d8163
Call-ID: 127e4dad-667c-db11-8a96-00c09f2d8163 at golgoth01
To: <sip:9105 at 172.16.100.198>;tag=as12885a66
Contact: <sip:9105 at 172.16.100.198>
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0
WWW-Authenticate: Digest algorithm=MD5, realm="novacom", nonce="048b5552"


2006/11/27 10:23:57.369	  0:01.121	SIP Transp...t:b5e2cb40	SIP	Transaction 1 SUBSCRIBE completed.
2006/11/27 10:23:57.369	  0:01.121	SIP Transp...t:b5e2cb40	SIP	Received Authentication Required response
2006/11/27 10:23:57.372	  0:01.124	SIP Transp...t:b5e2cb40	SIP	Adding authentication information
2006/11/27 10:23:57.373	  0:01.125	SIP Transp...t:b5e2cb40	SIP	Sending PDU on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
SUBSCRIBE sip:9105 at 172.16.100.198 SIP/2.0
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 172.16.100.166:5061;branch=z9hG4bKaa1b4fad-667c-db11-8a96-00c09f2d8163;rport
User-Agent: Ekiga/2.1.0
Authorization: Digest username="9197", realm="novacom", nonce="048b5552", uri="sip:9105 at 172.16.100.198", algorithm=md5, response="0c81d5a630f2c65f8826aa2646769e77"
From: <sip:9197 at 172.16.100.198>;tag=eac34dad-667c-db11-8a96-00c09f2d8163
Call-ID: 127e4dad-667c-db11-8a96-00c09f2d8163 at golgoth01
To: <sip:9105 at 172.16.100.198>
Contact: <sip:9197 at 172.16.100.166:5061;transport=udp>
Accept: application/pidf+xml
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,NOTIFY,REFER,MESSAGE,INFO,PING,PUBLISH
Expires: 500
Event: presence
Content-Length: 0
Max-Forwards: 70


2006/11/27 10:23:57.373	  0:01.125	SIP Transp...t:b5e2cb40	SIP	Waiting for PDU on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
2006/11/27 10:23:57.375	  0:01.127	SIP Transp...t:b5e2cb40	SIP	PDU Received on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
SIP/2.0 200 OK
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 172.16.100.166:5061;branch=z9hG4bKaa1b4fad-667c-db11-8a96-00c09f2d8163;rport;received=172.16.100.166
User-Agent: Asterisk PBX
From: <sip:9197 at 172.16.100.198>;tag=eac34dad-667c-db11-8a96-00c09f2d8163
Call-ID: 127e4dad-667c-db11-8a96-00c09f2d8163 at golgoth01
To: <sip:9105 at 172.16.100.198>;tag=as12885a66
Contact: <sip:9105 at 172.16.100.198>;expires=500
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Expires: 500
Content-Length: 0


2006/11/27 10:23:57.376	  0:01.128	SIP Transp...t:b5e2cb40	SIP	Transaction 2 SUBSCRIBE completed.
2006/11/27 10:23:57.377	  0:01.129	SIP Transp...t:b5e2cb40	SIP	Waiting for PDU on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
2006/11/27 10:23:57.378	  0:01.130	SIP Transp...t:b5e2cb40	SIP	PDU Received on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
NOTIFY sip:9197 at 172.16.100.198 SIP/2.0
CSeq: 102 NOTIFY
Via: SIP/2.0/UDP 172.16.100.198:5060;branch=z9hG4bK208a40f7;rport
User-Agent: Asterisk PBX
From: <sip:9105 at 172.16.100.198>;tag=as12885a66
Call-ID: 127e4dad-667c-db11-8a96-00c09f2d8163 at golgoth01
To: <sip:9197 at 172.16.100.198>;tag=eac34dad-667c-db11-8a96-00c09f2d8163
Contact: <sip:9105 at 172.16.100.198>
Subscription-State: active
Event: presence
Content-Type: application/pidf+xml
Content-Length: 483
Max-Forwards: 70

<?xml version="1.0" encoding="ISO-8859-1"?>
<presence xmlns="urn:ietf:params:xml:ns:pidf" 
xmlns:pp="urn:ietf:params:xml:ns:pidf:person"
xmlns:es="urn:ietf:params:xml:ns:pidf:rpid:status:rpid-status"
xmlns:ep="urn:ietf:params:xml:ns:pidf:rpid:rpid-person"
entity="sip:9197 at 172.16.100.198">
<pp:person><status>
</status></pp:person>
<note>Ready</note>
<tuple id="9105">
<contact priority="1">sip:9105 at 172.16.100.198</contact>
<status><basic>open</basic></status>
</tuple>
</presence>

2006/11/27 10:23:57.379	  0:01.131	SIP Transp...t:b5e2cb40	SIP	Tranport remote address change from Via: udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
2006/11/27 10:23:57.379	  0:01.131	SIP Transp...t:b5e2cb40	SIP	Received NOTIFY
2006/11/27 10:23:57.380	  0:01.132	SIP Transp...t:b5e2cb40	SIP	Found a SUBSCRIBE corresponding to the NOTIFY
2006/11/27 10:23:57.380	  0:01.132	SIP Transp...t:b5e2cb40	SIP	Subscription is active
2006/11/27 10:23:57.384	  0:01.136	SIP Transp...t:b5e2cb40	SIP	Sending PDU on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
SIP/2.0 200 OK
CSeq: 102 NOTIFY
Via: SIP/2.0/UDP 172.16.100.198:5060;branch=z9hG4bK208a40f7;rport=5060;received=172.16.100.198
From: <sip:9105 at 172.16.100.198>;tag=as12885a66
Call-ID: 127e4dad-667c-db11-8a96-00c09f2d8163 at golgoth01
To: <sip:9197 at 172.16.100.198>;tag=eac34dad-667c-db11-8a96-00c09f2d8163
Contact: <sip:9197 at 172.16.100.166:5061;transport=udp>
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,NOTIFY,REFER,MESSAGE,INFO,PING,PUBLISH
Content-Length: 0


2006/11/27 10:23:57.385	  0:01.137	SIP Transp...t:b5e2cb40	SIP	Waiting for PDU on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
2006/11/27 10:23:58.243	  0:01.995	 Housekeeper	SIP	Set state Terminated_Success for transaction 1 REGISTER
2006/11/27 10:23:58.259	  0:02.011	 Housekeeper	SIP	Set state Terminated_Success for transaction 2 REGISTER
2006/11/27 10:23:58.367	  0:02.119	 Housekeeper	SIP	Set state Terminated_Success for transaction 3 PUBLISH
2006/11/27 10:23:58.371	  0:02.123	 Housekeeper	SIP	Set state Terminated_Success for transaction 1 SUBSCRIBE
2006/11/27 10:23:58.379	  0:02.131	 Housekeeper	SIP	Set state Terminated_Success for transaction 2 SUBSCRIBE
2006/11/27 10:24:06.492	  0:10.244	 Housekeeper	SIP	Deleting SIPPublishInfo sip:9197 at 172.16.100.198
2006/11/27 10:24:06.492	  0:10.245	 Housekeeper	SIP	Deleting SIPInfo sip:9197 at 172.16.100.198
2006/11/27 10:24:08.382	  0:12.134	SIP Transp...t:b5e2cb40	SIP	PDU Received on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
NOTIFY sip:9197 at 172.16.100.166:5061;transport=udp SIP/2.0
CSeq: 102 NOTIFY
Via: SIP/2.0/UDP 172.16.100.198:5060;branch=z9hG4bK5803d7b4;rport
User-Agent: Asterisk PBX
From: "asterisk" <sip:asterisk at 172.16.100.198>;tag=as222fac2f
Call-ID: 34323c203ec199a764bc23d952195410 at 172.16.100.198
To: <sip:9197 at 172.16.100.166:5061;transport=udp>
Contact: <sip:asterisk at 172.16.100.198>
Event: message-summary
Content-Type: application/simple-message-summary
Content-Length: 94
Max-Forwards: 70

Messages-Waiting: no
Message-Account: sip:asterisk at 172.16.100.198
Voice-Message: 0/0 (0/0)

2006/11/27 10:24:08.383	  0:12.135	SIP Transp...t:b5e2cb40	SIP	Tranport remote address change from Via: udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
2006/11/27 10:24:08.383	  0:12.135	SIP Transp...t:b5e2cb40	SIP	Received NOTIFY
2006/11/27 10:24:08.383	  0:12.135	SIP Transp...t:b5e2cb40	SIP	Could not find a SUBSCRIBE corresponding to the NOTIFY
2006/11/27 10:24:08.383	  0:12.135	SIP Transp...t:b5e2cb40	SIP	Waiting for PDU on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
2006/11/27 10:24:08.397	  0:12.149	SIP Transp...t:b5e2cb40	SIP	PDU Received on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
NOTIFY sip:9197 at 172.16.100.166:5061;transport=udp SIP/2.0
CSeq: 102 NOTIFY
Via: SIP/2.0/UDP 172.16.100.198:5060;branch=z9hG4bK5803d7b4;rport
User-Agent: Asterisk PBX
From: "asterisk" <sip:asterisk at 172.16.100.198>;tag=as222fac2f
Call-ID: 34323c203ec199a764bc23d952195410 at 172.16.100.198
To: <sip:9197 at 172.16.100.166:5061;transport=udp>
Contact: <sip:asterisk at 172.16.100.198>
Event: message-summary
Content-Type: application/simple-message-summary
Content-Length: 94
Max-Forwards: 70

Messages-Waiting: no
Message-Account: sip:asterisk at 172.16.100.198
Voice-Message: 0/0 (0/0)

2006/11/27 10:24:08.398	  0:12.150	SIP Transp...t:b5e2cb40	SIP	Tranport remote address change from Via: udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
2006/11/27 10:24:08.398	  0:12.150	SIP Transp...t:b5e2cb40	SIP	Received NOTIFY
2006/11/27 10:24:08.399	  0:12.151	SIP Transp...t:b5e2cb40	SIP	Could not find a SUBSCRIBE corresponding to the NOTIFY
2006/11/27 10:24:08.399	  0:12.151	SIP Transp...t:b5e2cb40	SIP	Waiting for PDU on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
2006/11/27 10:24:08.414	  0:12.166	SIP Transp...t:b5e2cb40	SIP	PDU Received on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
NOTIFY sip:9197 at 172.16.100.166:5061;transport=udp SIP/2.0
CSeq: 102 NOTIFY
Via: SIP/2.0/UDP 172.16.100.198:5060;branch=z9hG4bK5803d7b4;rport
User-Agent: Asterisk PBX
From: "asterisk" <sip:asterisk at 172.16.100.198>;tag=as222fac2f
Call-ID: 34323c203ec199a764bc23d952195410 at 172.16.100.198
To: <sip:9197 at 172.16.100.166:5061;transport=udp>
Contact: <sip:asterisk at 172.16.100.198>
Event: message-summary
Content-Type: application/simple-message-summary
Content-Length: 94
Max-Forwards: 70

Messages-Waiting: no
Message-Account: sip:asterisk at 172.16.100.198
Voice-Message: 0/0 (0/0)

2006/11/27 10:24:08.414	  0:12.166	SIP Transp...t:b5e2cb40	SIP	Tranport remote address change from Via: udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
2006/11/27 10:24:08.415	  0:12.167	SIP Transp...t:b5e2cb40	SIP	Received NOTIFY
2006/11/27 10:24:08.415	  0:12.167	SIP Transp...t:b5e2cb40	SIP	Could not find a SUBSCRIBE corresponding to the NOTIFY
2006/11/27 10:24:08.415	  0:12.167	SIP Transp...t:b5e2cb40	SIP	Waiting for PDU on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
2006/11/27 10:24:08.446	  0:12.198	SIP Transp...t:b5e2cb40	SIP	PDU Received on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
NOTIFY sip:9197 at 172.16.100.166:5061;transport=udp SIP/2.0
CSeq: 102 NOTIFY
Via: SIP/2.0/UDP 172.16.100.198:5060;branch=z9hG4bK5803d7b4;rport
User-Agent: Asterisk PBX
From: "asterisk" <sip:asterisk at 172.16.100.198>;tag=as222fac2f
Call-ID: 34323c203ec199a764bc23d952195410 at 172.16.100.198
To: <sip:9197 at 172.16.100.166:5061;transport=udp>
Contact: <sip:asterisk at 172.16.100.198>
Event: message-summary
Content-Type: application/simple-message-summary
Content-Length: 94
Max-Forwards: 70

Messages-Waiting: no
Message-Account: sip:asterisk at 172.16.100.198
Voice-Message: 0/0 (0/0)

2006/11/27 10:24:08.446	  0:12.198	SIP Transp...t:b5e2cb40	SIP	Tranport remote address change from Via: udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
2006/11/27 10:24:08.447	  0:12.199	SIP Transp...t:b5e2cb40	SIP	Received NOTIFY
2006/11/27 10:24:08.447	  0:12.199	SIP Transp...t:b5e2cb40	SIP	Could not find a SUBSCRIBE corresponding to the NOTIFY
2006/11/27 10:24:08.447	  0:12.199	SIP Transp...t:b5e2cb40	SIP	Waiting for PDU on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
2006/11/27 10:24:08.510	  0:12.262	SIP Transp...t:b5e2cb40	SIP	PDU Received on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
NOTIFY sip:9197 at 172.16.100.166:5061;transport=udp SIP/2.0
CSeq: 102 NOTIFY
Via: SIP/2.0/UDP 172.16.100.198:5060;branch=z9hG4bK5803d7b4;rport
User-Agent: Asterisk PBX
From: "asterisk" <sip:asterisk at 172.16.100.198>;tag=as222fac2f
Call-ID: 34323c203ec199a764bc23d952195410 at 172.16.100.198
To: <sip:9197 at 172.16.100.166:5061;transport=udp>
Contact: <sip:asterisk at 172.16.100.198>
Event: message-summary
Content-Type: application/simple-message-summary
Content-Length: 94
Max-Forwards: 70

Messages-Waiting: no
Message-Account: sip:asterisk at 172.16.100.198
Voice-Message: 0/0 (0/0)

2006/11/27 10:24:08.510	  0:12.262	SIP Transp...t:b5e2cb40	SIP	Tranport remote address change from Via: udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
2006/11/27 10:24:08.511	  0:12.263	SIP Transp...t:b5e2cb40	SIP	Received NOTIFY
2006/11/27 10:24:08.511	  0:12.263	SIP Transp...t:b5e2cb40	SIP	Could not find a SUBSCRIBE corresponding to the NOTIFY
2006/11/27 10:24:08.511	  0:12.263	SIP Transp...t:b5e2cb40	SIP	Waiting for PDU on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
2006/11/27 10:24:08.638	  0:12.390	SIP Transp...t:b5e2cb40	SIP	PDU Received on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
NOTIFY sip:9197 at 172.16.100.166:5061;transport=udp SIP/2.0
CSeq: 102 NOTIFY
Via: SIP/2.0/UDP 172.16.100.198:5060;branch=z9hG4bK5803d7b4;rport
User-Agent: Asterisk PBX
From: "asterisk" <sip:asterisk at 172.16.100.198>;tag=as222fac2f
Call-ID: 34323c203ec199a764bc23d952195410 at 172.16.100.198
To: <sip:9197 at 172.16.100.166:5061;transport=udp>
Contact: <sip:asterisk at 172.16.100.198>
Event: message-summary
Content-Type: application/simple-message-summary
Content-Length: 94
Max-Forwards: 70

Messages-Waiting: no
Message-Account: sip:asterisk at 172.16.100.198
Voice-Message: 0/0 (0/0)

2006/11/27 10:24:08.638	  0:12.390	SIP Transp...t:b5e2cb40	SIP	Tranport remote address change from Via: udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
2006/11/27 10:24:08.639	  0:12.391	SIP Transp...t:b5e2cb40	SIP	Received NOTIFY
2006/11/27 10:24:08.639	  0:12.391	SIP Transp...t:b5e2cb40	SIP	Could not find a SUBSCRIBE corresponding to the NOTIFY
2006/11/27 10:24:08.639	  0:12.391	SIP Transp...t:b5e2cb40	SIP	Waiting for PDU on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
2006/11/27 10:24:08.897	  0:12.650	SIP Transp...t:b5e2cb40	SIP	PDU Received on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
NOTIFY sip:9197 at 172.16.100.166:5061;transport=udp SIP/2.0
CSeq: 102 NOTIFY
Via: SIP/2.0/UDP 172.16.100.198:5060;branch=z9hG4bK5803d7b4;rport
User-Agent: Asterisk PBX
From: "asterisk" <sip:asterisk at 172.16.100.198>;tag=as222fac2f
Call-ID: 34323c203ec199a764bc23d952195410 at 172.16.100.198
To: <sip:9197 at 172.16.100.166:5061;transport=udp>
Contact: <sip:asterisk at 172.16.100.198>
Event: message-summary
Content-Type: application/simple-message-summary
Content-Length: 94
Max-Forwards: 70

Messages-Waiting: no
Message-Account: sip:asterisk at 172.16.100.198
Voice-Message: 0/0 (0/0)

2006/11/27 10:24:08.898	  0:12.650	SIP Transp...t:b5e2cb40	SIP	Tranport remote address change from Via: udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
2006/11/27 10:24:08.898	  0:12.650	SIP Transp...t:b5e2cb40	SIP	Received NOTIFY
2006/11/27 10:24:08.899	  0:12.651	SIP Transp...t:b5e2cb40	SIP	Could not find a SUBSCRIBE corresponding to the NOTIFY
2006/11/27 10:24:08.899	  0:12.651	SIP Transp...t:b5e2cb40	SIP	Waiting for PDU on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
2006/11/27 10:24:09.959	  0:13.711	GMURLHandl...r:0843fa00	OpalMan	Set up call from pc:* to sip:444 at 172.16.100.198
2006/11/27 10:24:09.959	  0:13.711	GMURLHandl...r:0843fa00	Call	Created Call[1]
2006/11/27 10:24:09.960	  0:13.712	GMURLHandl...r:0843fa00	OpalMan	Set up connection to "pc:*"
2006/11/27 10:24:10.012	  0:13.767	GMURLHandl...r:0843fa00	OpalCon	Created connection Call[1]-EP<pc>[Default]
2006/11/27 10:24:10.015	  0:13.767	GMURLHandl...r:0843fa00	RFC2833	Handler created
2006/11/27 10:24:10.015	  0:13.767	GMURLHandl...r:0843fa00	Silence	Handler created
2006/11/27 10:24:10.015	  0:13.767	GMURLHandl...r:0843fa00	Echo Canceler	Handler created
2006/11/27 10:24:10.015	  0:13.768	GMURLHandl...r:0843fa00	PCSS	Created PC sound system connection.
2006/11/27 10:24:10.016	  0:13.768	GMURLHandl...r:0843fa00	OpalMan	On incoming connection Call[1]-EP<pc>[Default]
2006/11/27 10:24:10.016	  0:13.768	GMURLHandl...r:0843fa00	Call	GetOtherPartyConnection Call[1]-EP<pc>[Default]
2006/11/27 10:24:10.016	  0:13.768	GMURLHandl...r:0843fa00	OpalMan	Set up connection to "sip:444 at 172.16.100.198"
2006/11/27 10:24:10.017	  0:13.769	GMURLHandl...r:0843fa00	OpalCon	Created connection Call[1]-EP<sip>[e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01]
2006/11/27 10:24:10.017	  0:13.769	GMURLHandl...r:0843fa00	RFC2833	Handler created
2006/11/27 10:24:10.018	  0:13.770	GMURLHandl...r:0843fa00	SIP	Created connection.
2006/11/27 10:24:10.018	  0:13.770	GMURLHandl...r:0843fa00	PCSS	Outgoing call routed to sip:444 at 172.16.100.198 for Call[1]-EP<pc>[Default]
2006/11/27 10:24:10.019	  0:13.771	GMURLHandl...r:0843fa00	Call	OnSetUp Call[1]-EP<pc>[Default]
2006/11/27 10:24:10.019	  0:13.771	GMURLHandl...r:0843fa00	SIP	SetUpConnection: <sip:444 at 172.16.100.198>
2006/11/27 10:24:10.020	  0:13.772	GMURLHandl...r:0843fa00	OpalUDP	Binding to interface: 172.16.100.166:32792
2006/11/27 10:24:10.020	  0:13.772	GMURLHandl...r:0843fa00	SIP	Created transport udp$0.0.0.0<if=udp$172.16.100.166:32792>
2006/11/27 10:24:10.020	  0:13.773	GMURLHandl...r:0843fa00	OpalUDP	Started connect to 172.16.100.198:5060
2006/11/27 10:24:10.021	  0:13.773	GMURLHandl...r:0843fa00	OpalUDP	Connect on pre-bound interface: 172.16.100.166
2006/11/27 10:24:10.023	  0:13.775	GMURLHandl...r:0843fa00	SIP	Transaction 1 INVITE created.
2006/11/27 10:24:10.023	  0:13.775	GMURLHandl...r:0843fa00	Call	CanDoMediaBypass Call[1]-EP<sip>[e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01] session 1
2006/11/27 10:24:10.023	  0:13.775	GMURLHandl...r:0843fa00	OpalMan	IsMediaBypassPossible: session 1
2006/11/27 10:24:10.023	  0:13.775	GMURLHandl...r:0843fa00	SIP	IsMediaBypassPossible: session 1
2006/11/27 10:24:10.023	  0:13.775	GMURLHandl...r:0843fa00	OpalCon	IsMediaBypassPossible: default returns FALSE
2006/11/27 10:24:10.024	  0:13.776	GMURLHandl...r:0843fa00	RTP_UDP	RTP session created with NAT flag set to 0
2006/11/27 10:24:10.024	  0:13.776	SIP Transp...rt:84677a8	SIP	Read thread started.
2006/11/27 10:24:10.024	  0:13.776	SIP Transp...rt:84677a8	SIP	Waiting for PDU on udp$172.16.100.198:5060<if=udp$172.16.100.166:5062>
2006/11/27 10:24:10.024	  0:13.776	GMURLHandl...r:0843fa00	RTP_UDP	Session 1 created: 172.16.100.166:5000-5001 ssrc=37556996
2006/11/27 10:24:10.025	  0:13.777	GMURLHandl...r:0843fa00	RTP	Adding session RTP_UDP
2006/11/27 10:24:10.035	  0:13.787	GMURLHandl...r:0843fa00	Call	GetMediaFormats for Call[1]-EP<sip>[e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01]
H.261
H.261-CIF
H.261-QCIF
GSM-06.10
G.711-uLaw-64k
G.711-ALaw-64k
iLBC-13k3
PCM-16
YUV420P
RGB32
RGB24

2006/11/27 10:24:10.042	  0:13.794	GMURLHandl...r:0843fa00	SIP	Using RTP payload [pt=101] for NTE
2006/11/27 10:24:10.042	  0:13.794	GMURLHandl...r:0843fa00	Call	CanDoMediaBypass Call[1]-EP<sip>[e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01] session 2
2006/11/27 10:24:10.043	  0:13.795	GMURLHandl...r:0843fa00	OpalMan	IsMediaBypassPossible: session 2
2006/11/27 10:24:10.043	  0:13.795	GMURLHandl...r:0843fa00	SIP	IsMediaBypassPossible: session 2
2006/11/27 10:24:10.043	  0:13.795	GMURLHandl...r:0843fa00	OpalCon	IsMediaBypassPossible: default returns FALSE
2006/11/27 10:24:10.043	  0:13.795	GMURLHandl...r:0843fa00	RTP_UDP	RTP session created with NAT flag set to 0
2006/11/27 10:24:10.043	  0:13.795	GMURLHandl...r:0843fa00	RTP_UDP	Session 2 created: 172.16.100.166:5002-5003 ssrc=1969090190
2006/11/27 10:24:10.044	  0:13.796	GMURLHandl...r:0843fa00	RTP	Adding session RTP_UDP
2006/11/27 10:24:10.061	  0:13.813	GMURLHandl...r:0843fa00	Call	GetMediaFormats for Call[1]-EP<sip>[e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01]
H.261
H.261-CIF
H.261-QCIF
GSM-06.10
G.711-uLaw-64k
G.711-ALaw-64k
iLBC-13k3
PCM-16
YUV420P
RGB32
RGB24

2006/11/27 10:24:10.061	  0:13.813	GMURLHandl...r:0843fa00	SIP	Creating INVITE request
2006/11/27 10:24:10.062	  0:13.814	GMURLHandl...r:0843fa00	SIP	No authentication information present
2006/11/27 10:24:10.063	  0:13.815	GMURLHandl...r:0843fa00	SIP	Sending PDU on udp$172.16.100.198:5060<if=udp$172.16.100.166:5062>
INVITE sip:444 at 172.16.100.198 SIP/2.0
Date: Mon, 27 Nov 2006 09:24:10 GMT
CSeq: 1 INVITE
Via: SIP/2.0/UDP 172.16.100.166:5062;branch=z9hG4bKc69ad9b4-667c-db11-8a96-00c09f2d8163;rport
User-Agent: Ekiga/2.1.0
From: "Damien Sandras" <sip:9197 at 172.16.100.198>;tag=82b2d8b4-667c-db11-8a96-00c09f2d8163
Call-ID: e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01
To: <sip:444 at 172.16.100.198>
Contact: <sip:9197 at 172.16.100.166:5062;transport=udp>
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,NOTIFY,REFER,MESSAGE,INFO,PING,PUBLISH
Content-Type: application/sdp
Content-Length: 332
Max-Forwards: 70

v=0
o=- 1164619450 1164619450 IN IP4 172.16.100.166
s=Opal SIP Session
c=IN IP4 172.16.100.166
t=0 0
m=audio 5000 RTP/AVP 3 0 8 107 101
a=rtpmap:3 gsm/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:107 iLBC/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
m=video 5002 RTP/AVP 31
a=rtpmap:31 h261/90000

2006/11/27 10:24:10.063	  0:13.815	GMURLHandl...r:0843fa00	OpalCon	OnSetUpConnectionCall[1]-EP<sip>[e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01]
2006/11/27 10:24:10.063	  0:13.815	GMURLHandl...r:0843fa00	OpalEP	OnSetUpConnection Call[1]-EP<sip>[e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01]
2006/11/27 10:24:10.063	  0:13.815	GMURLHandl...r:0843fa00	SetUpCall succeeded, call=Call[1]
2006/11/27 10:24:10.065	  0:13.817	SIP Transp...rt:84677a8	SIP	PDU Received on udp$172.16.100.198:5060<if=udp$172.16.100.166:5062>
SIP/2.0 407 Proxy Authentication Required
CSeq: 1 INVITE
Via: SIP/2.0/UDP 172.16.100.166:5062;branch=z9hG4bKc69ad9b4-667c-db11-8a96-00c09f2d8163;rport;received=172.16.100.166
User-Agent: Asterisk PBX
From: "Damien Sandras" <sip:9197 at 172.16.100.198>;tag=82b2d8b4-667c-db11-8a96-00c09f2d8163
Call-ID: e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01
To: <sip:444 at 172.16.100.198>;tag=as6959fcbc
Contact: <sip:444 at 172.16.100.198>
Proxy-Authenticate: Digest algorithm=MD5, realm="novacom", nonce="7667840a"
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0


2006/11/27 10:24:10.065	  0:13.817	SIP Transp...rt:84677a8	OpalUDP	Ended connect, selecting 172.16.100.166:5062
2006/11/27 10:24:10.066	  0:13.818	SIP Transp...rt:84677a8	SIP	Queueing PDU: 1 INVITE <407>
2006/11/27 10:24:10.066	  0:13.818	SIP Transp...rt:84677a8	SIP	Waiting for PDU on udp$172.16.100.198:5060<if=udp$172.16.100.166:5062>
2006/11/27 10:24:10.066	  0:13.818	SIP Handle...er:843fa00	SIP	PDU handler thread started.
2006/11/27 10:24:10.066	  0:13.818	SIP Handle...er:843fa00	SIP	Awaiting next PDU.
2006/11/27 10:24:10.066	  0:13.818	SIP Handle...er:843fa00	SIP	Handling PDU 1 INVITE <407>
2006/11/27 10:24:10.067	  0:13.819	SIP Handle...er:843fa00	SIP	Transaction 1 INVITE completed.
2006/11/27 10:24:10.068	  0:13.821	SIP Handle...er:843fa00	SIP	Sending PDU on udp$172.16.100.198:5060<if=udp$172.16.100.166:5062>
ACK sip:444 at 172.16.100.198 SIP/2.0
CSeq: 1 ACK
Via: SIP/2.0/UDP 172.16.100.166:5062;branch=z9hG4bKc69ad9b4-667c-db11-8a96-00c09f2d8163;rport
From: "Damien Sandras" <sip:9197 at 172.16.100.198>;tag=82b2d8b4-667c-db11-8a96-00c09f2d8163
Call-ID: e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01
To: <sip:444 at 172.16.100.198>;tag=as6959fcbc
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,NOTIFY,REFER,MESSAGE,INFO,PING,PUBLISH
Content-Length: 0
Max-Forwards: 70


2006/11/27 10:24:10.069	  0:13.821	SIP Handle...er:843fa00	SIP	Received Proxy Authentication Required response
2006/11/27 10:24:10.070	  0:13.822	SIP Handle...er:843fa00	SIP	Transaction 2 INVITE created.
2006/11/27 10:24:10.070	  0:13.822	SIP Handle...er:843fa00	Call	CanDoMediaBypass Call[1]-EP<sip>[e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01] session 1
2006/11/27 10:24:10.070	  0:13.822	SIP Handle...er:843fa00	OpalMan	IsMediaBypassPossible: session 1
2006/11/27 10:24:10.070	  0:13.822	SIP Handle...er:843fa00	SIP	IsMediaBypassPossible: session 1
2006/11/27 10:24:10.070	  0:13.822	SIP Handle...er:843fa00	OpalCon	IsMediaBypassPossible: default returns FALSE
2006/11/27 10:24:10.071	  0:13.823	SIP Handle...er:843fa00	RTP_UDP	RTP session created with NAT flag set to 0
2006/11/27 10:24:10.071	  0:13.823	SIP Handle...er:843fa00	RTP_UDP	Session 1 created: 172.16.100.166:5004-5005 ssrc=1931258738
2006/11/27 10:24:10.071	  0:13.823	SIP Handle...er:843fa00	RTP	Adding session RTP_UDP
2006/11/27 10:24:10.081	  0:13.833	SIP Handle...er:843fa00	Call	GetMediaFormats for Call[1]-EP<sip>[e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01]
H.261
H.261-CIF
H.261-QCIF
GSM-06.10
G.711-uLaw-64k
G.711-ALaw-64k
iLBC-13k3
PCM-16
YUV420P
RGB32
RGB24

2006/11/27 10:24:10.082	  0:13.834	SIP Handle...er:843fa00	SIP	Using RTP payload [pt=101] for NTE
2006/11/27 10:24:10.082	  0:13.834	SIP Handle...er:843fa00	Call	CanDoMediaBypass Call[1]-EP<sip>[e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01] session 2
2006/11/27 10:24:10.082	  0:13.834	SIP Handle...er:843fa00	OpalMan	IsMediaBypassPossible: session 2
2006/11/27 10:24:10.082	  0:13.834	SIP Handle...er:843fa00	SIP	IsMediaBypassPossible: session 2
2006/11/27 10:24:10.083	  0:13.835	SIP Handle...er:843fa00	OpalCon	IsMediaBypassPossible: default returns FALSE
2006/11/27 10:24:10.083	  0:13.835	SIP Handle...er:843fa00	RTP_UDP	RTP session created with NAT flag set to 0
2006/11/27 10:24:10.083	  0:13.835	SIP Handle...er:843fa00	RTP_UDP	Session 2 created: 172.16.100.166:5006-5007 ssrc=65754113
2006/11/27 10:24:10.083	  0:13.835	SIP Handle...er:843fa00	RTP	Adding session RTP_UDP
2006/11/27 10:24:10.096	  0:13.848	SIP Handle...er:843fa00	Call	GetMediaFormats for Call[1]-EP<sip>[e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01]
H.261
H.261-CIF
H.261-QCIF
GSM-06.10
G.711-uLaw-64k
G.711-ALaw-64k
iLBC-13k3
PCM-16
YUV420P
RGB32
RGB24

2006/11/27 10:24:10.098	  0:13.850	SIP Handle...er:843fa00	SIP	Creating INVITE request
2006/11/27 10:24:10.099	  0:13.851	SIP Handle...er:843fa00	SIP	Adding authentication information
2006/11/27 10:24:10.100	  0:13.852	SIP Handle...er:843fa00	SIP	Sending PDU on udp$172.16.100.198:5060<if=udp$172.16.100.166:5062>
INVITE sip:444 at 172.16.100.198 SIP/2.0
Date: Mon, 27 Nov 2006 09:24:10 GMT
CSeq: 2 INVITE
Via: SIP/2.0/UDP 172.16.100.166:5062;branch=z9hG4bK34d0e0b4-667c-db11-8a96-00c09f2d8163;rport
User-Agent: Ekiga/2.1.0
From: "Damien Sandras" <sip:9197 at 172.16.100.198>;tag=82b2d8b4-667c-db11-8a96-00c09f2d8163
Call-ID: e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01
To: <sip:444 at 172.16.100.198>
Contact: <sip:9197 at 172.16.100.166:5062;transport=udp>
Proxy-Authorization: Digest username="9197", realm="novacom", nonce="7667840a", uri="sip:444 at 172.16.100.198", algorithm=md5, response="f0c88e73029e2ee264996aa51acfeb58"
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,NOTIFY,REFER,MESSAGE,INFO,PING,PUBLISH
Content-Type: application/sdp
Content-Length: 332
Max-Forwards: 70

v=0
o=- 1164619450 1164619450 IN IP4 172.16.100.166
s=Opal SIP Session
c=IN IP4 172.16.100.166
t=0 0
m=audio 5004 RTP/AVP 3 0 8 107 101
a=rtpmap:3 gsm/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:107 iLBC/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
m=video 5006 RTP/AVP 31
a=rtpmap:31 h261/90000

2006/11/27 10:24:10.101	  0:13.853	SIP Handle...er:843fa00	SIP	Awaiting next PDU.
2006/11/27 10:24:10.102	  0:13.854	SIP Transp...rt:84677a8	SIP	PDU Received on udp$172.16.100.198:5060<if=udp$172.16.100.166:5062>
SIP/2.0 100 Trying
CSeq: 2 INVITE
Via: SIP/2.0/UDP 172.16.100.166:5062;branch=z9hG4bK34d0e0b4-667c-db11-8a96-00c09f2d8163;rport;received=172.16.100.166
User-Agent: Asterisk PBX
From: "Damien Sandras" <sip:9197 at 172.16.100.198>;tag=82b2d8b4-667c-db11-8a96-00c09f2d8163
Call-ID: e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01
To: <sip:444 at 172.16.100.198>
Contact: <sip:444 at 172.16.100.198>
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0


2006/11/27 10:24:10.104	  0:13.856	SIP Transp...rt:84677a8	SIP	Queueing PDU: 2 INVITE <100>
2006/11/27 10:24:10.104	  0:13.856	SIP Transp...rt:84677a8	SIP	Waiting for PDU on udp$172.16.100.198:5060<if=udp$172.16.100.166:5062>
2006/11/27 10:24:10.105	  0:13.857	SIP Transp...rt:84677a8	SDP	Media session port=19144
2006/11/27 10:24:10.105	  0:13.857	SIP Transp...rt:84677a8	SDP	Adding media session with 2 formats
2006/11/27 10:24:10.106	  0:13.858	SIP Transp...rt:84677a8	SDP	Media attribute silenceSupp found for unknown RTP type PCMU
2006/11/27 10:24:10.106	  0:13.858	SIP Transp...rt:84677a8	SIP	PDU Received on udp$172.16.100.198:5060<if=udp$172.16.100.166:5062>
SIP/2.0 200 OK
CSeq: 2 INVITE
Via: SIP/2.0/UDP 172.16.100.166:5062;branch=z9hG4bK34d0e0b4-667c-db11-8a96-00c09f2d8163;rport;received=172.16.100.166
User-Agent: Asterisk PBX
From: "Damien Sandras" <sip:9197 at 172.16.100.198>;tag=82b2d8b4-667c-db11-8a96-00c09f2d8163
Call-ID: e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01
To: <sip:444 at 172.16.100.198>;tag=as3d2c9853
Contact: <sip:444 at 172.16.100.198>
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Type: application/sdp
Content-Length: 218

v=0
o=root 5150 5150 IN IP4 172.16.100.198
s=session
c=IN IP4 172.16.100.198
t=0 0
m=audio 19144 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -

2006/11/27 10:24:10.107	  0:13.859	SIP Transp...rt:84677a8	SIP	Queueing PDU: 2 INVITE <200>
2006/11/27 10:24:10.107	  0:13.859	SIP Transp...rt:84677a8	SIP	Waiting for PDU on udp$172.16.100.198:5060<if=udp$172.16.100.166:5062>
2006/11/27 10:24:10.107	  0:13.859	SIP Handle...er:843fa00	SIP	Handling PDU 2 INVITE <100>
2006/11/27 10:24:10.107	  0:13.859	SIP Handle...er:843fa00	SIP	Transaction 2 INVITE proceeding.
2006/11/27 10:24:10.108	  0:13.860	SIP Handle...er:843fa00	SIP	Set targetAddress to sip:444 at 172.16.100.198
2006/11/27 10:24:10.108	  0:13.860	SIP Handle...er:843fa00	SIP	Received Trying response
2006/11/27 10:24:10.108	  0:13.860	SIP Handle...er:843fa00	SIP	Awaiting next PDU.
2006/11/27 10:24:10.108	  0:13.860	SIP Handle...er:843fa00	SIP	Handling PDU 2 INVITE <200>
2006/11/27 10:24:10.109	  0:13.861	SIP Handle...er:843fa00	SIP	Transaction 2 INVITE completed.
2006/11/27 10:24:10.109	  0:13.861	SIP Handle...er:843fa00	SIP	Set targetAddress to sip:444 at 172.16.100.198
2006/11/27 10:24:10.110	  0:13.862	SIP Handle...er:843fa00	SIP	Adding authentication information
2006/11/27 10:24:10.110	  0:13.862	SIP Handle...er:843fa00	SIP	Sending PDU on udp$172.16.100.198:5060<if=udp$172.16.100.166:5062>
ACK sip:444 at 172.16.100.198 SIP/2.0
CSeq: 2 ACK
Via: SIP/2.0/UDP 172.16.100.166:5062;branch=z9hG4bK1eefe6b4-667c-db11-8a96-00c09f2d8163;rport
From: "Damien Sandras" <sip:9197 at 172.16.100.198>;tag=82b2d8b4-667c-db11-8a96-00c09f2d8163
Call-ID: e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01
To: <sip:444 at 172.16.100.198>;tag=as3d2c9853
Contact: <sip:9197 at 172.16.100.166:5062;transport=udp>
Proxy-Authorization: Digest username="9197", realm="novacom", nonce="7667840a", uri="sip:444 at 172.16.100.198", algorithm=md5, response="82ebe1ee326fe5e5c349e7f2569809a4"
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,NOTIFY,REFER,MESSAGE,INFO,PING,PUBLISH
Content-Length: 0
Max-Forwards: 70


2006/11/27 10:24:10.111	  0:13.863	SIP Handle...er:843fa00	SIP	Received INVITE OK response
2006/11/27 10:24:10.111	  0:13.863	SIP Handle...er:843fa00	Call	GetOtherPartyConnection Call[1]-EP<sip>[e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01]
2006/11/27 10:24:10.111	  0:13.863	SIP Handle...er:843fa00	Call	CanDoMediaBypass Call[1]-EP<sip>[e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01] session 1
2006/11/27 10:24:10.111	  0:13.863	SIP Handle...er:843fa00	OpalMan	IsMediaBypassPossible: session 1
2006/11/27 10:24:10.111	  0:13.863	SIP Handle...er:843fa00	SIP	IsMediaBypassPossible: session 1
2006/11/27 10:24:10.111	  0:13.863	SIP Handle...er:843fa00	OpalCon	IsMediaBypassPossible: default returns FALSE
2006/11/27 10:24:10.111	  0:13.864	SIP Handle...er:843fa00	RTP	Found existing session 1
2006/11/27 10:24:10.112	  0:13.864	SIP Handle...er:843fa00	SIP	RTP payload type PCMA matched to codec G.711-ALaw-64k
2006/11/27 10:24:10.113	  0:13.865	SIP Handle...er:843fa00	Call	OpenSourceMediaStreams for session 1 with media G.711-ALaw-64k
2006/11/27 10:24:10.114	  0:13.866	SIP Handle...er:843fa00	OpalCon	OpenSourceMediaStream for session 1 on Call[1]-EP<pc>[Default]
2006/11/27 10:24:10.115	  0:13.867	SIP Handle...er:843fa00	OpalCon	Selected media stream PCM-16 -> G.711-ALaw-64k
2006/11/27 10:24:11.192	  0:14.944	 Housekeeper	SIP	Set state Terminated_Success for transaction 2 INVITE
2006/11/27 10:24:11.198	  0:14.950	SIP Handle...er:843fa00	OpalMan	OnOpenMediaStream Call[1]-EP<pc>[Default],OpalAudioMediaStream-Source-PCM-16
2006/11/27 10:24:11.199	  0:14.951	SIP Handle...er:843fa00	Call	PatchMediaStreams Call[1]-EP<pc>[Default]
2006/11/27 10:24:11.199	  0:14.951	SIP Handle...er:843fa00	OpalCon	OpenSinkMediaStream Call[1]-EP<sip>[e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01] session=1
2006/11/27 10:24:11.200	  0:14.952	SIP Handle...er:843fa00	OpalCon	OpenSinkMediaStream, selected PCM-16 -> G.711-ALaw-64k
2006/11/27 10:24:11.200	  0:14.952	SIP Handle...er:843fa00	Call	CanDoMediaBypass Call[1]-EP<sip>[e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01] session 1
2006/11/27 10:24:11.200	  0:14.952	SIP Handle...er:843fa00	OpalMan	IsMediaBypassPossible: session 1
2006/11/27 10:24:11.201	  0:14.953	SIP Handle...er:843fa00	SIP	IsMediaBypassPossible: session 1
2006/11/27 10:24:11.201	  0:14.953	SIP Handle...er:843fa00	OpalCon	IsMediaBypassPossible: default returns FALSE
2006/11/27 10:24:11.201	  0:14.953	SIP Handle...er:843fa00	RTP	Found existing session 1
2006/11/27 10:24:11.201	  0:14.953	SIP Handle...er:843fa00	RTP	Found existing session 1
2006/11/27 10:24:11.202	  0:14.954	SIP Handle...er:843fa00	OpalMan	OnOpenMediaStream Call[1]-EP<sip>[e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01],OpalRTPMediaStream-Sink-G.711-ALaw-64k
2006/11/27 10:24:11.206	  0:14.958	SIP Handle...er:843fa00	Patch	Added sink
  from PCM-16
         Clock Rate = 8000
         Frame Time = 8
         Max Bit Rate = 64000
         Max Frame Size = 16
         Needs Jitter = 1
         Rx Frames Per Packet = 240
         Tx Frames Per Packet = 30
    to G.711-ALaw-64k
         Clock Rate = 8000
         Frame Time = 8
         Max Bit Rate = 64000
         Max Frame Size = 8
         Needs Jitter = 1
         Rx Frames Per Packet = 240
         Tx Frames Per Packet = 30

2006/11/27 10:24:11.207	  0:14.959	SIP Handle...er:843fa00	Codec	G711-ALaw-64k encoder created
2006/11/27 10:24:11.208	  0:14.960	SIP Handle...er:843fa00	Patch	Added media stream sink OpalRTPMediaStream-Sink-G.711-ALaw-64k using transcoder PCM-16->G.711-ALaw-64k
2006/11/27 10:24:11.208	  0:14.960	SIP Handle...er:843fa00	Media	Audio source data size set to  320 bytes and 4 buffers.
2006/11/27 10:24:11.209	  0:14.961	SIP Handle...er:843fa00	OpalCon	New patch created
2006/11/27 10:24:11.209	  0:14.961	SIP Handle...er:843fa00	OpalCon	New patch created
2006/11/27 10:24:11.209	  0:14.961	SIP Handle...er:843fa00	OpalCOn	Adding RFC2833 transmit handler
2006/11/27 10:24:11.210	  0:14.962	SIP Handle...er:843fa00	Call	OpenSourceMediaStreams for session 1 adjusted media to G.711-ALaw-64k
2006/11/27 10:24:11.210	  0:14.962	SIP Handle...er:843fa00	Call	GetOtherPartyConnection Call[1]-EP<sip>[e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01]
2006/11/27 10:24:11.211	  0:14.963	SIP Handle...er:843fa00	OpalCon	OpenSourceMediaStream for session 1 on Call[1]-EP<sip>[e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01]
2006/11/27 10:24:11.212	  0:14.964	SIP Handle...er:843fa00	OpalCon	Selected media stream G.711-ALaw-64k -> PCM-16
2006/11/27 10:24:11.212	  0:14.964	SIP Handle...er:843fa00	Call	CanDoMediaBypass Call[1]-EP<sip>[e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01] session 1
2006/11/27 10:24:11.212	  0:14.964	SIP Handle...er:843fa00	OpalMan	IsMediaBypassPossible: session 1
2006/11/27 10:24:11.212	  0:14.965	SIP Handle...er:843fa00	SIP	IsMediaBypassPossible: session 1
2006/11/27 10:24:11.213	  0:14.965	SIP Handle...er:843fa00	OpalCon	IsMediaBypassPossible: default returns FALSE
2006/11/27 10:24:11.213	  0:14.965	SIP Handle...er:843fa00	RTP	Found existing session 1
2006/11/27 10:24:11.213	  0:14.965	SIP Handle...er:843fa00	RTP	Found existing session 1
2006/11/27 10:24:11.213	  0:14.965	SIP Handle...er:843fa00	OpalMan	OnOpenMediaStream Call[1]-EP<sip>[e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01],OpalRTPMediaStream-Source-G.711-ALaw-64k
2006/11/27 10:24:11.213	  0:14.965	SIP Handle...er:843fa00	Call	PatchMediaStreams Call[1]-EP<sip>[e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01]
2006/11/27 10:24:11.213	  0:14.965	SIP Handle...er:843fa00	OpalCon	OpenSinkMediaStream Call[1]-EP<pc>[Default] session=1
2006/11/27 10:24:11.214	  0:14.966	SIP Handle...er:843fa00	OpalCon	OpenSinkMediaStream, selected G.711-ALaw-64k -> PCM-16
2006/11/27 10:24:11.217	  0:14.969	SIP Handle...er:843fa00	OpalMan	OnOpenMediaStream Call[1]-EP<pc>[Default],OpalAudioMediaStream-Sink-PCM-16
2006/11/27 10:24:11.218	  0:14.970	SIP Handle...er:843fa00	Patch	Added sink
  from G.711-ALaw-64k
         Clock Rate = 8000
         Frame Time = 8
         Max Bit Rate = 64000
         Max Frame Size = 8
         Needs Jitter = 1
         Rx Frames Per Packet = 240
         Tx Frames Per Packet = 30
    to PCM-16
         Clock Rate = 8000
         Frame Time = 8
         Max Bit Rate = 128000
         Max Frame Size = 16
         Needs Jitter = 1
         Rx Frames Per Packet = 240
         Tx Frames Per Packet = 30

2006/11/27 10:24:11.219	  0:14.971	SIP Handle...er:843fa00	Codec	G711-ALaw-64k decoder created
2006/11/27 10:24:11.219	  0:14.971	SIP Handle...er:843fa00	Media	Audio sink data size set to  320 bytes and 4 buffers.
2006/11/27 10:24:11.219	  0:14.972	SIP Handle...er:843fa00	Patch	Added media stream sink OpalAudioMediaStream-Sink-PCM-16 using transcoder G.711-ALaw-64k->PCM-16
2006/11/27 10:24:11.220	  0:14.972	SIP Handle...er:843fa00	OpalCon	New patch created
2006/11/27 10:24:11.220	  0:14.972	SIP Handle...er:843fa00	OpalCon	New patch created
2006/11/27 10:24:11.220	  0:14.972	SIP Handle...er:843fa00	OpalCon	Adding RFC2833 receive handler
2006/11/27 10:24:11.223	  0:14.975	SIP Handle...er:843fa00	OpalCon	OpenSourceMediaStream (already opened) for session 1 on Call[1]-EP<sip>[e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01]
2006/11/27 10:24:11.224	  0:14.976	SIP Handle...er:843fa00	RTP_UDP	SetRemoteSocketInfo: session=1 data channel, new=172.16.100.198:19144, local=172.16.100.166:5004-5005, remote=0.0.0.0:0-0
2006/11/27 10:24:11.224	  0:14.976	SIP Handle...er:843fa00	SIP	Could not find SDP media description for Video
2006/11/27 10:24:11.225	  0:14.977	SIP Handle...er:843fa00	SIP	Could not find SDP media description for Image
2006/11/27 10:24:11.225	  0:14.977	SIP Handle...er:843fa00	OpalMan	OnConnected Call[1]-EP<sip>[e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01]
2006/11/27 10:24:11.225	  0:14.978	SIP Handle...er:843fa00	Call	OnConnected Call[1]-EP<sip>[e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01]
2006/11/27 10:24:11.226	  0:14.978	SIP Handle...er:843fa00	PCSS	SetConnected()
2006/11/27 10:24:11.226	  0:14.978	SIP Handle...er:843fa00	GMPCSSEndpoint	 PCSS connection established
2006/11/27 10:24:11.226	  0:14.978	SIP Handle...er:843fa00	GMManager	 Will establish the connection
2006/11/27 10:24:11.226	  0:14.978	SIP Handle...er:843fa00	OpalMan	OnEstablished Call[1]-EP<pc>[Default]
2006/11/27 10:24:11.227	  0:14.979	SIP Handle...er:843fa00	Call	OnEstablished Call[1]-EP<pc>[Default]
2006/11/27 10:24:11.238	  0:14.990	SIP Handle...er:843fa00	Call	GetMediaFormats for Call[1]-EP<pc>[Default]
G.711-ALaw-64k
PCM-16

2006/11/27 10:24:11.238	  0:14.990	SIP Handle...er:843fa00	Call	OpenSourceMediaStreams for session 1 with media G.711-ALaw-64k,PCM-16
2006/11/27 10:24:11.238	  0:14.990	SIP Handle...er:843fa00	OpalCon	OpenSourceMediaStream (already opened) for session 1 on Call[1]-EP<sip>[e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01]
2006/11/27 10:24:11.239	  0:14.991	SIP Handle...er:843fa00	Call	OpenSourceMediaStreams for session 2 with media G.711-ALaw-64k,PCM-16
2006/11/27 10:24:11.239	  0:14.991	SIP Handle...er:843fa00	Call	OpenSourceMediaStreams for session 3 with media G.711-ALaw-64k,PCM-16
2006/11/27 10:24:11.250	  0:15.002	SIP Handle...er:843fa00	Call	GetMediaFormats for Call[1]-EP<sip>[e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01]
G.711-ALaw-64k
PCM-16

2006/11/27 10:24:11.251	  0:15.003	SIP Handle...er:843fa00	Call	OpenSourceMediaStreams for session 1 with media G.711-ALaw-64k,PCM-16
2006/11/27 10:24:11.251	  0:15.003	SIP Handle...er:843fa00	OpalCon	OpenSourceMediaStream (already opened) for session 1 on Call[1]-EP<pc>[Default]
2006/11/27 10:24:11.251	  0:15.003	SIP Handle...er:843fa00	Call	OpenSourceMediaStreams for session 1 adjusted media to PCM-16,G.711-ALaw-64k
2006/11/27 10:24:11.251	  0:15.003	SIP Handle...er:843fa00	Call	OpenSourceMediaStreams for session 2 with media G.711-ALaw-64k,PCM-16
2006/11/27 10:24:11.251	  0:15.003	SIP Handle...er:843fa00	Call	OpenSourceMediaStreams for session 3 with media G.711-ALaw-64k,PCM-16
2006/11/27 10:24:11.251	  0:15.003	Media Patc...ch:8470550	Patch	Thread started for Patch OpalAudioMediaStream-Source-PCM-16 -> OpalRTPMediaStream-Sink-G.711-ALaw-64k
2006/11/27 10:24:11.252	  0:15.005	SIP Handle...er:843fa00	Media	Starting thread Media Patch:8470550
2006/11/27 10:24:11.253	  0:15.005	Media Patc...h:b5e38f18	Patch	Thread started for Patch OpalRTPMediaStream-Source-G.711-ALaw-64k -> OpalAudioMediaStream-Sink-PCM-16
2006/11/27 10:24:11.253	  0:15.005	Media Patc...h:b5e38f18	RTP	Jitter buffer created: size=101 delay=160-4000/160 (20ms) obj=0x840c988
2006/11/27 10:24:11.255	  0:15.007	RTP Jitter...er:84b0eb8	RTP	Jitter RTP receive thread started: 0x840c988
2006/11/27 10:24:11.256	  0:15.008	RTP Jitter...er:84b0eb8	RTP	First receive data: ver=2 pt=PCMA psz=160 m=0 x=0 seq=40148 ts=160 src=803175037 ccnt=0
2006/11/27 10:24:11.256	  0:15.008	SIP Handle...er:843fa00	Media	Starting thread Media Patch:b5e38f18
2006/11/27 10:24:11.256	  0:15.008	SIP Handle...er:843fa00	OpalCon	Media stream threads started.
2006/11/27 10:24:11.257	  0:15.009	SIP Handle...er:843fa00	OpalCon	Media stream threads started.
2006/11/27 10:24:11.257	  0:15.009	SIP Handle...er:843fa00	OpalCon	SetPhase from UninitialisedPhase to EstablishedPhase
2006/11/27 10:24:11.257	  0:15.009	SIP Handle...er:843fa00	GMSIPEndpoint	 SIP connection established
2006/11/27 10:24:11.260	  0:15.012	SIP Handle...er:843fa00	RTP	Found existing session 1
2006/11/27 10:24:11.260	  0:15.012	SIP Handle...er:843fa00	RTP	Found existing session 2
2006/11/27 10:24:11.260	  0:15.013	SIP Handle...er:843fa00	GMManager	 Will establish the connection
2006/11/27 10:24:11.261	  0:15.013	SIP Handle...er:843fa00	OpalMan	OnEstablished Call[1]-EP<sip>[e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01]
2006/11/27 10:24:11.261	  0:15.013	SIP Handle...er:843fa00	Call	OnEstablished Call[1]-EP<sip>[e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01]
2006/11/27 10:24:11.274	  0:15.026	SIP Handle...er:843fa00	SIP	Awaiting next PDU.
2006/11/27 10:24:11.276	  0:15.028	Media Patc...h:b5e38f18	RTP	Jitter buffer length exceeded
2006/11/27 10:24:11.276	  0:15.028	Media Patc...h:b5e38f18	RTP	Jitter buffer length exceed was prior to first write. Not increasing buffer size
2006/11/27 10:24:11.279	  0:15.031	Media Patc...ch:8470550	RTP	First sent data: ver=2 pt=PCMA psz=160 m=1 x=0 seq=39800 ts=0 src=1931258738 ccnt=0
2006/11/27 10:24:12.139	  0:15.891	RTP Jitter...er:84b0eb8	RTP	Receive statistics:  packets=102 octets=16320 lost=0 tooLate=0 order=0 avgTime=8 maxTime=22 minTime=0 jitter=0 maxJitter=2
2006/11/27 10:24:12.272	  0:16.024	 Housekeeper	RTP	Found existing session 1
2006/11/27 10:24:12.272	  0:16.024	 Housekeeper	RTP	Found existing session 2
2006/11/27 10:24:13.280	  0:17.032	 Housekeeper	RTP	Found existing session 1
2006/11/27 10:24:13.280	  0:17.033	 Housekeeper	RTP	Found existing session 2
2006/11/27 10:24:13.282	  0:17.034	Media Patc...ch:8470550	RTP	Transmit statistics:  packets=101 octets=16160 avgTime=20 maxTime=28 minTime=15
2006/11/27 10:24:14.152	  0:17.904	RTP Jitter...er:84b0eb8	RTP	Receive statistics:  packets=202 octets=32320 lost=0 tooLate=0 order=0 avgTime=20 maxTime=25 minTime=15 jitter=1 maxJitter=2
2006/11/27 10:24:14.302	  0:18.054	 Housekeeper	RTP	Found existing session 1
2006/11/27 10:24:14.302	  0:18.054	 Housekeeper	RTP	Found existing session 2
2006/11/27 10:24:15.297	  0:19.050	 Housekeeper	RTP	Found existing session 1
2006/11/27 10:24:15.298	  0:19.050	 Housekeeper	RTP	Found existing session 2
2006/11/27 10:24:15.365	  0:19.117	Media Patc...ch:8470550	RTP	Transmit statistics:  packets=201 octets=32160 avgTime=20 maxTime=25 minTime=15
2006/11/27 10:24:15.935	  0:19.687	       ekiga	Call	Clearing Call[1] reason=EndedByLocalUser
2006/11/27 10:24:15.935	  0:19.687	       ekiga	OpalCon	SetPhase from EstablishedPhase to ReleasingPhase
2006/11/27 10:24:15.935	  0:19.687	       ekiga	OpalCon	Releasing Call[1]-EP<pc>[Default]
2006/11/27 10:24:15.935	  0:19.687	       ekiga	OpalCon	Call end reason for Default set to EndedByLocalUser
2006/11/27 10:24:15.936	  0:19.688	       ekiga	OpalCon	SetPhase from EstablishedPhase to ReleasingPhase
2006/11/27 10:24:15.936	  0:19.688	       ekiga	OpalCon	Releasing Call[1]-EP<sip>[e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01]
2006/11/27 10:24:15.936	  0:19.688	       ekiga	OpalCon	Call end reason for e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01 set to EndedByLocalUser
2006/11/27 10:24:15.936	  0:19.688	OnRelease:...se:84fcbb8	SIP	OnReleased: Call[1]-EP<sip>[e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01], phase = ReleasingPhase
2006/11/27 10:24:15.936	  0:19.689	OnRelease:...se:84fcbb8	OpalCon	SetPhase from ReleasingPhase to ReleasingPhase
2006/11/27 10:24:15.938	  0:19.691	OnRelease:...se:84fcbb8	SIP	Transaction 4 BYE created.
2006/11/27 10:24:15.940	  0:19.692	OnRelease:...se:84fcbb8	Media	Closing RTP for OpalRTPMediaStream-Sink-G.711-ALaw-64k
2006/11/27 10:24:15.941	  0:19.693	OnRelease:...se:84fcbb8	RTP_UDP	Session 1, Shutting down write.
2006/11/27 10:24:15.941	  0:19.693	OnRelease:...se:84fcbb8	Media	Closing stream OpalRTPMediaStream-Sink-G.711-ALaw-64k
2006/11/27 10:24:15.941	  0:19.693	OnRelease:...se:84fcbb8	Media	Disconnecting OpalRTPMediaStream-Sink-G.711-ALaw-64k from patch thread Patch OpalAudioMediaStream-Source-PCM-16 -> OpalRTPMediaStream-Sink-G.711-ALaw-64k
2006/11/27 10:24:15.941	  0:19.693	OnRelease:...se:84fcbb8	Patch	Removing media stream sink OpalRTPMediaStream-Sink-G.711-ALaw-64k
2006/11/27 10:24:15.942	  0:19.694	OnRelease:...se:84b20e0	OpalCon	OnReleased Call[1]-EP<pc>[Default]
2006/11/27 10:24:15.943	  0:19.695	OnRelease:...se:84b20e0	Media	Closing raw media stream OpalAudioMediaStream-Source-PCM-16
2006/11/27 10:24:15.943	  0:19.695	OnRelease:...se:84b20e0	Media	Closing stream OpalAudioMediaStream-Source-PCM-16
2006/11/27 10:24:15.943	  0:19.695	OnRelease:...se:84b20e0	Media	Disconnecting OpalAudioMediaStream-Source-PCM-16 from patch thread Patch OpalAudioMediaStream-Source-PCM-16
2006/11/27 10:24:15.943	  0:19.695	OnRelease:...se:84b20e0	Patch	Closing media patch Patch OpalAudioMediaStream-Source-PCM-16
2006/11/27 10:24:15.943	  0:19.695	OnRelease:...se:84b20e0	Media	Closing raw media stream OpalAudioMediaStream-Source-PCM-16
2006/11/27 10:24:15.944	  0:19.696	OnRelease:...se:84b20e0	Patch	Waiting for media patch thread to stop Patch OpalAudioMediaStream-Source-PCM-16
2006/11/27 10:24:15.945	  0:19.697	OnRelease:...se:84fcbb8	Media	Closing RTP for OpalRTPMediaStream-Source-G.711-ALaw-64k
2006/11/27 10:24:15.945	  0:19.697	OnRelease:...se:84fcbb8	RTP_UDP	Session 1, Shutting down read.
2006/11/27 10:24:15.945	  0:19.697	OnRelease:...se:84fcbb8	Media	Closing stream OpalRTPMediaStream-Source-G.711-ALaw-64k
2006/11/27 10:24:15.946	  0:19.698	OnRelease:...se:84fcbb8	Media	Disconnecting OpalRTPMediaStream-Source-G.711-ALaw-64k from patch thread Patch OpalRTPMediaStream-Source-G.711-ALaw-64k -> OpalAudioMediaStream-Sink-PCM-16
2006/11/27 10:24:15.949	  0:19.702	OnRelease:...se:84fcbb8	Patch	Closing media patch Patch OpalRTPMediaStream-Source-G.711-ALaw-64k -> OpalAudioMediaStream-Sink-PCM-16
2006/11/27 10:24:15.950	  0:19.702	Media Patc...ch:8470550	Patch	Thread ended for Patch OpalAudioMediaStream-Source-PCM-16
2006/11/27 10:24:15.950	  0:19.702	RTP Jitter...er:84b0eb8	RTP_UDP	Session 1, Read shutdown.
2006/11/27 10:24:15.950	  0:19.702	OnRelease:...se:84fcbb8	Media	Closing RTP for OpalRTPMediaStream-Source-G.711-ALaw-64k
2006/11/27 10:24:15.950	  0:19.702	OnRelease:...se:84fcbb8	RTP_UDP	Session 1, Shutting down read.
2006/11/27 10:24:15.950	  0:19.702	RTP Jitter...er:84b0eb8	RTP	Jitter RTP receive thread ended
2006/11/27 10:24:15.950	  0:19.702	OnRelease:...se:84fcbb8	Patch	Removing media stream sink OpalAudioMediaStream-Sink-PCM-16
2006/11/27 10:24:15.950	  0:19.702	RTP Jitter...er:84b0eb8	RTP	Jitter RTP receive thread finished: 0x840c988
2006/11/27 10:24:15.951	  0:19.703	OnRelease:...se:84fcbb8	Patch	Waiting for media patch thread to stop Patch OpalRTPMediaStream-Source-G.711-ALaw-64k
2006/11/27 10:24:15.956	  0:19.708	Media Patc...h:b5e38f18	Patch	Thread ended for Patch OpalRTPMediaStream-Source-G.711-ALaw-64k
2006/11/27 10:24:15.956	  0:19.708	OnRelease:...se:84b20e0	Patch	Media patch thread Patch OpalAudioMediaStream-Source-PCM-16 destroyed.
2006/11/27 10:24:15.957	  0:19.709	OnRelease:...se:84b20e0	Media	Closing raw media stream OpalAudioMediaStream-Sink-PCM-16
2006/11/27 10:24:15.957	  0:19.709	OnRelease:...se:84b20e0	Media	Closing stream OpalAudioMediaStream-Sink-PCM-16
2006/11/27 10:24:15.957	  0:19.709	OnRelease:...se:84b20e0	OpalCon	Media stream threads closed.
2006/11/27 10:24:15.958	  0:19.710	OnRelease:...se:84b20e0	GMPCSSEndpoint	 PCSS connection released
2006/11/27 10:24:15.958	  0:19.710	OnRelease:...se:84b20e0	OpalEP	OnReleased Call[1]-EP<pc>[Default]
2006/11/27 10:24:15.958	  0:19.710	OnRelease:...se:84b20e0	GMManager	 Will release the connection
2006/11/27 10:24:15.959	  0:19.711	OnRelease:...se:84b20e0	OpalMan	OnReleased Call[1]-EP<pc>[Default]
2006/11/27 10:24:15.959	  0:19.711	OnRelease:...se:84b20e0	Call	OnReleased Call[1]-EP<pc>[Default]
2006/11/27 10:24:15.959	  0:19.711	OnRelease:...se:84b20e0	OpalCon	Already released Call[1]-EP<sip>[e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01]
2006/11/27 10:24:15.959	  0:19.711	OnRelease:...se:84b20e0	OpalCon	OnRelease thread completed for Default
2006/11/27 10:24:15.964	  0:19.716	OnRelease:...se:84fcbb8	Patch	Media patch thread Patch OpalRTPMediaStream-Source-G.711-ALaw-64k destroyed.
2006/11/27 10:24:15.964	  0:19.716	OnRelease:...se:84fcbb8	OpalCon	Media stream threads closed.
2006/11/27 10:24:15.965	  0:19.717	OnRelease:...se:84fcbb8	SIP	Adding authentication information
2006/11/27 10:24:15.965	  0:19.717	OnRelease:...se:84fcbb8	SIP	Sending PDU on udp$172.16.100.198:5060<if=udp$172.16.100.166:5062>
BYE sip:444 at 172.16.100.198 SIP/2.0
CSeq: 4 BYE
Via: SIP/2.0/UDP 172.16.100.166:5062;branch=z9hG4bK843960b8-667c-db11-8a96-00c09f2d8163;rport
From: "Damien Sandras" <sip:9197 at 172.16.100.198>;tag=82b2d8b4-667c-db11-8a96-00c09f2d8163
Call-ID: e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01
To: <sip:444 at 172.16.100.198>;tag=as3d2c9853
Contact: <sip:9197 at 172.16.100.166:5062;transport=udp>
Proxy-Authorization: Digest username="9197", realm="novacom", nonce="7667840a", uri="sip:444 at 172.16.100.198", algorithm=md5, response="9e726640173d81fb90121f519715cecc"
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,NOTIFY,REFER,MESSAGE,INFO,PING,PUBLISH
Content-Length: 0
Max-Forwards: 70


2006/11/27 10:24:15.967	  0:19.719	SIP Transp...rt:84677a8	SIP	PDU Received on udp$172.16.100.198:5060<if=udp$172.16.100.166:5062>
SIP/2.0 200 OK
CSeq: 4 BYE
Via: SIP/2.0/UDP 172.16.100.166:5062;branch=z9hG4bK843960b8-667c-db11-8a96-00c09f2d8163;received=172.16.100.166;rport=5062
User-Agent: Asterisk PBX
From: "Damien Sandras" <sip:9197 at 172.16.100.198>;tag=82b2d8b4-667c-db11-8a96-00c09f2d8163
Call-ID: e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01
To: <sip:444 at 172.16.100.198>;tag=as3d2c9853
Contact: <sip:444 at 172.16.100.198>
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0


2006/11/27 10:24:15.967	  0:19.719	SIP Transp...rt:84677a8	SIP	Queueing PDU: 4 BYE <200>
2006/11/27 10:24:15.968	  0:19.720	SIP Transp...rt:84677a8	SIP	Waiting for PDU on udp$172.16.100.198:5060<if=udp$172.16.100.166:5062>
2006/11/27 10:24:15.968	  0:19.720	SIP Handle...er:843fa00	SIP	Handling PDU 4 BYE <200>
2006/11/27 10:24:15.968	  0:19.720	SIP Handle...er:843fa00	SIP	Transaction 4 BYE completed.
2006/11/27 10:24:15.969	  0:19.721	SIP Handle...er:843fa00	SIP	Received OK response for non INVITE
2006/11/27 10:24:15.969	  0:19.721	OnRelease:...se:84fcbb8	SIP	Transaction 4 BYE aborted.
2006/11/27 10:24:15.969	  0:19.721	SIP Handle...er:843fa00	SIP	Awaiting next PDU.
2006/11/27 10:24:15.969	  0:19.721	OnRelease:...se:84fcbb8	SIP	Transaction 4 BYE destroyed.
2006/11/27 10:24:15.969	  0:19.721	OnRelease:...se:84fcbb8	OpalCon	SetPhase from ReleasingPhase to ReleasedPhase
2006/11/27 10:24:15.970	  0:19.722	SIP Handle...er:843fa00	SIP	PDU handler thread finished.
2006/11/27 10:24:15.981	  0:19.733	OnRelease:...se:84fcbb8	Opal	Transport clean up on termination
2006/11/27 10:24:15.981	  0:19.733	OnRelease:...se:84fcbb8	OpalUDP	Close
2006/11/27 10:24:15.981	  0:19.733	OnRelease:...se:84fcbb8	Opal	Transport Close
2006/11/27 10:24:15.982	  0:19.734	SIP Transp...rt:84677a8	SIP	Read thread finished.
2006/11/27 10:24:15.992	  0:19.744	OnRelease:...se:84fcbb8	OpalCon	OnReleased Call[1]-EP<sip>[e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01]
2006/11/27 10:24:15.993	  0:19.745	OnRelease:...se:84fcbb8	OpalCon	Media stream threads closed.
2006/11/27 10:24:15.993	  0:19.745	OnRelease:...se:84fcbb8	GMSIPEndpoint	 SIP connection released
2006/11/27 10:24:15.993	  0:19.745	OnRelease:...se:84fcbb8	OpalEP	OnReleased Call[1]-EP<sip>[e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01]
2006/11/27 10:24:16.028	  0:19.780	OnRelease:...se:84fcbb8	GMManager	 Will release the connection
2006/11/27 10:24:16.029	  0:19.781	OnRelease:...se:84fcbb8	OpalMan	OnReleased Call[1]-EP<sip>[e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01]
2006/11/27 10:24:16.029	  0:19.781	OnRelease:...se:84fcbb8	Call	OnReleased Call[1]-EP<sip>[e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01]
2006/11/27 10:24:16.572	  0:20.456	 OpalGarbage	PCSS	Deleted PC sound system connection.
2006/11/27 10:24:16.705	  0:20.457	 OpalGarbage	OpalCon	Connection Call[1]-EP<pc>[Default] destroyed.
2006/11/27 10:24:18.112	  0:21.865	       ekiga	Call	Clearing Call[1] reason=EndedByLocalUser
2006/11/27 10:24:19.279	  0:23.031	OnRelease:...se:84fcbb8	RTP_UDP	Session 1, Shutting down read.
2006/11/27 10:24:19.279	  0:23.031	OnRelease:...se:84fcbb8	RTP_UDP	Session 1, Shutting down write.
2006/11/27 10:24:19.279	  0:23.031	OnRelease:...se:84fcbb8	RTP_UDP	Session 2, Shutting down read.
2006/11/27 10:24:19.279	  0:23.032	OnRelease:...se:84fcbb8	RTP_UDP	Session 2, Shutting down write.
2006/11/27 10:24:19.280	  0:23.032	OnRelease:...se:84fcbb8	SIP	Transaction 1 INVITE aborted.
2006/11/27 10:24:19.280	  0:23.032	OnRelease:...se:84fcbb8	SIP	Transaction 1 INVITE destroyed.
2006/11/27 10:24:19.280	  0:23.032	OnRelease:...se:84fcbb8	SIP	Transaction 2 INVITE destroyed.
2006/11/27 10:24:19.280	  0:23.032	OnRelease:...se:84fcbb8	OpalCon	OnRelease thread completed for e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01
2006/11/27 10:24:19.717	  0:23.469	 OpalGarbage	Opal	Transport clean up on termination
2006/11/27 10:24:19.717	  0:23.469	 OpalGarbage	OpalUDP	Close
2006/11/27 10:24:19.717	  0:23.469	 OpalGarbage	Opal	Transport Close
2006/11/27 10:24:19.717	  0:23.469	 OpalGarbage	Opal	Deleted transport udp$172.16.100.198:5060<if=udp$172.16.100.166:5062>
2006/11/27 10:24:19.717	  0:23.469	 OpalGarbage	SIP	Deleted connection.
2006/11/27 10:24:19.718	  0:23.470	 OpalGarbage	OpalCon	Connection Call[1]-EP<sip>[e6a8d8b4-667c-db11-8a96-00c09f2d8163 at golgoth01] destroyed.
2006/11/27 10:24:19.718	  0:23.470	 OpalGarbage	RTP_UDP	Session 1, Shutting down write.
2006/11/27 10:24:19.718	  0:23.470	 OpalGarbage	RTP	Final statistics:
    packetsSent       = 229
    octetsSent        = 36640
    averageSendTime   = 20
    maximumSendTime   = 25
    minimumSendTime   = 15
    packetsReceived   = 287
    octetsReceived    = 45920
    packetsLost       = 0
    packetsTooLate    = 0
    packetsOutOfOrder = 0
    averageReceiveTime= 20
    maximumReceiveTime= 25
    minimumReceiveTime= 15
    averageJitter     = 0
    maximumJitter     = 3
2006/11/27 10:24:19.718	  0:23.470	 OpalGarbage	RTP	Removing jitter buffer 0x840c988 RTP Jitter:84b0eb8
2006/11/27 10:24:19.718	  0:23.471	 OpalGarbage	RTP_UDP	Session 2, Shutting down read.
2006/11/27 10:24:19.719	  0:23.471	 OpalGarbage	RTP_UDP	Session 2, Shutting down write.
2006/11/27 10:24:20.725	  0:24.477	 OpalGarbage	Call	Call[1] destroyed.
2006/11/27 10:24:22.733	  0:26.485	       ekiga	Listen	Stopping listening thread on udp$172.16.100.166:1720
2006/11/27 10:24:22.733	  0:26.485	Opal Liste...er:83f93c8	Listen	UDP select error: Appel système interrompu
2006/11/27 10:24:22.745	  0:26.497	       ekiga	H323	Deleted endpoint.
2006/11/27 10:24:22.745	  0:26.497	       ekiga	OpalEP	h323 endpoint destroyed.
2006/11/27 10:24:22.748	  0:26.500	       ekiga	SIP	Sending PDU on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
REGISTER sip:172.16.100.198 SIP/2.0
CSeq: 4 REGISTER
Via: SIP/2.0/UDP 172.16.100.166:5061;branch=z9hG4bK0a326fbc-667c-db11-8a96-00c09f2d8163;rport
User-Agent: Ekiga/2.1.0
From: <sip:9197 at 172.16.100.198>;tag=d2286fbc-667c-db11-8a96-00c09f2d8163
Call-ID: f49337ad-667c-db11-8a96-00c09f2d8163 at golgoth01
To: <sip:9197 at 172.16.100.198>
Contact: <sip:9197 at 172.16.100.166:5061;transport=udp>
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,NOTIFY,REFER,MESSAGE,INFO,PING,PUBLISH
Expires: 0
Content-Length: 0
Max-Forwards: 70


2006/11/27 10:24:22.749	  0:26.501	SIP Transp...t:b5e2cb40	SIP	PDU Received on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
SIP/2.0 100 Trying
CSeq: 4 REGISTER
Via: SIP/2.0/UDP 172.16.100.166:5061;branch=z9hG4bK0a326fbc-667c-db11-8a96-00c09f2d8163;rport;received=172.16.100.166
User-Agent: Asterisk PBX
From: <sip:9197 at 172.16.100.198>;tag=d2286fbc-667c-db11-8a96-00c09f2d8163
Call-ID: f49337ad-667c-db11-8a96-00c09f2d8163 at golgoth01
To: <sip:9197 at 172.16.100.198>
Contact: <sip:9197 at 172.16.100.198>
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0


2006/11/27 10:24:22.750	  0:26.502	SIP Transp...t:b5e2cb40	SIP	Transaction 4 REGISTER proceeding.
2006/11/27 10:24:22.750	  0:26.502	SIP Transp...t:b5e2cb40	SIP	Waiting for PDU on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
2006/11/27 10:24:22.751	  0:26.503	SIP Transp...t:b5e2cb40	SIP	PDU Received on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
SIP/2.0 401 Unauthorized
CSeq: 4 REGISTER
Via: SIP/2.0/UDP 172.16.100.166:5061;branch=z9hG4bK0a326fbc-667c-db11-8a96-00c09f2d8163;rport;received=172.16.100.166
User-Agent: Asterisk PBX
From: <sip:9197 at 172.16.100.198>;tag=d2286fbc-667c-db11-8a96-00c09f2d8163
Call-ID: f49337ad-667c-db11-8a96-00c09f2d8163 at golgoth01
To: <sip:9197 at 172.16.100.198>;tag=as432694d3
Contact: <sip:9197 at 172.16.100.198>
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0
WWW-Authenticate: Digest algorithm=MD5, realm="novacom", nonce="29deca2a"


2006/11/27 10:24:22.752	  0:26.504	SIP Transp...t:b5e2cb40	SIP	Transaction 4 REGISTER completed.
2006/11/27 10:24:22.752	  0:26.504	SIP Transp...t:b5e2cb40	SIP	Received Authentication Required response
2006/11/27 10:24:22.754	  0:26.506	SIP Transp...t:b5e2cb40	SIP	Adding authentication information
2006/11/27 10:24:22.754	  0:26.506	SIP Transp...t:b5e2cb40	SIP	Sending PDU on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
REGISTER sip:172.16.100.198 SIP/2.0
CSeq: 5 REGISTER
Via: SIP/2.0/UDP 172.16.100.166:5061;branch=z9hG4bKe42470bc-667c-db11-8a96-00c09f2d8163;rport
User-Agent: Ekiga/2.1.0
Authorization: Digest username="9197", realm="novacom", nonce="29deca2a", uri="sip:172.16.100.198", algorithm=md5, response="1304adc75acad701286422a13fd8deba"
From: <sip:9197 at 172.16.100.198>;tag=d2286fbc-667c-db11-8a96-00c09f2d8163
Call-ID: f49337ad-667c-db11-8a96-00c09f2d8163 at golgoth01
To: <sip:9197 at 172.16.100.198>
Contact: <sip:9197 at 172.16.100.166:5061;transport=udp>
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,NOTIFY,REFER,MESSAGE,INFO,PING,PUBLISH
Expires: 0
Content-Length: 0
Max-Forwards: 70


2006/11/27 10:24:22.755	  0:26.507	SIP Transp...t:b5e2cb40	SIP	Waiting for PDU on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
2006/11/27 10:24:22.756	  0:26.508	SIP Transp...t:b5e2cb40	SIP	PDU Received on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
SIP/2.0 100 Trying
CSeq: 5 REGISTER
Via: SIP/2.0/UDP 172.16.100.166:5061;branch=z9hG4bKe42470bc-667c-db11-8a96-00c09f2d8163;rport;received=172.16.100.166
User-Agent: Asterisk PBX
From: <sip:9197 at 172.16.100.198>;tag=d2286fbc-667c-db11-8a96-00c09f2d8163
Call-ID: f49337ad-667c-db11-8a96-00c09f2d8163 at golgoth01
To: <sip:9197 at 172.16.100.198>
Contact: <sip:9197 at 172.16.100.198>
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0


2006/11/27 10:24:22.756	  0:26.508	SIP Transp...t:b5e2cb40	SIP	Transaction 5 REGISTER proceeding.
2006/11/27 10:24:22.757	  0:26.509	SIP Transp...t:b5e2cb40	SIP	Waiting for PDU on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
2006/11/27 10:24:22.758	  0:26.510	SIP Transp...t:b5e2cb40	SIP	PDU Received on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
SIP/2.0 200 OK
Date: Mon, 27 Nov 2006 09:51:33 GMT
CSeq: 5 REGISTER
Via: SIP/2.0/UDP 172.16.100.166:5061;branch=z9hG4bKe42470bc-667c-db11-8a96-00c09f2d8163;rport;received=172.16.100.166
User-Agent: Asterisk PBX
From: <sip:9197 at 172.16.100.198>;tag=d2286fbc-667c-db11-8a96-00c09f2d8163
Call-ID: f49337ad-667c-db11-8a96-00c09f2d8163 at golgoth01
To: <sip:9197 at 172.16.100.198>;tag=as432694d3
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Expires: 0
Content-Length: 0


2006/11/27 10:24:22.758	  0:26.510	SIP Transp...t:b5e2cb40	SIP	Transaction 5 REGISTER completed.
2006/11/27 10:24:22.759	  0:26.511	SIP Transp...t:b5e2cb40	SIP	Waiting for PDU on udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
2006/11/27 10:24:22.761	  0:26.513	       ekiga	SIP	Deleting SIPRegisterInfo sip:9197 at 172.16.100.198
2006/11/27 10:24:22.761	  0:26.513	       ekiga	SIP	Deleting SIPInfo sip:9197 at 172.16.100.198
2006/11/27 10:24:22.761	  0:26.513	       ekiga	SIP	Deleting SIPSubscribeInfo sip:9105 at 172.16.100.198
2006/11/27 10:24:22.761	  0:26.513	       ekiga	SIP	Deleting SIPInfo sip:9105 at 172.16.100.198
2006/11/27 10:24:22.761	  0:26.513	       ekiga	Listen	Stopping listening thread on udp$172.16.100.166:5060
2006/11/27 10:24:22.761	  0:26.514	Opal Liste...er:83fab50	Listen	UDP select error: Appel système interrompu
2006/11/27 10:24:22.775	  0:26.527	       ekiga	SIP	Deleted endpoint.
2006/11/27 10:24:22.776	  0:26.528	       ekiga	SIP	Deleting transport udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
2006/11/27 10:24:22.776	  0:26.528	       ekiga	OpalUDP	Close
2006/11/27 10:24:22.776	  0:26.528	       ekiga	Opal	Transport Close
2006/11/27 10:24:22.776	  0:26.528	SIP Transp...t:b5e2cb40	SIP	Read thread finished.
2006/11/27 10:24:22.776	  0:26.528	       ekiga	Opal	Transport clean up on termination
2006/11/27 10:24:22.776	  0:26.528	       ekiga	OpalUDP	Close
2006/11/27 10:24:22.776	  0:26.528	       ekiga	Opal	Transport Close
2006/11/27 10:24:22.777	  0:26.529	       ekiga	Opal	Deleted transport udp$172.16.100.198:5060<if=udp$172.16.100.166:5061>
2006/11/27 10:24:22.777	  0:26.529	       ekiga	OpalEP	sip endpoint destroyed.
2006/11/27 10:24:22.777	  0:26.529	       ekiga	PCSS	Deleted PC sound system endpoint.
2006/11/27 10:24:22.779	  0:26.531	       ekiga	OpalEP	pc endpoint destroyed.
2006/11/27 10:24:22.779	  0:26.531	       ekiga	OpalMan	Deleted manager.


More information about the ekiga-list mailing list