[Ekiga-list] Receiving SIP RTP before media description [was ekiga answers with delay ...?]
Damien Sandras
dsandras at seconix.com
Mon Nov 27 09:26:58 UTC 2006
Le lundi 27 novembre 2006 à 10:15 +1100, Craig Southeren a écrit :
> On Mon, 27 Nov 2006 00:01:28 +0100
> "Bent Bagger" <bbagger at gmail.com> wrote:
>
> > I have uploaded the trace to Savefile.com
> >
> > http://www.savefile.com/files/293084
>
> Thank you for taking the time to do this, because hopefully we can now
> move on to solving the actual problem rather than continuing the rather
> ridiculous discussion about how to guess the contents of an RTP packet :)
>
> The log file you provided shows the following:
>
> 0.0513 Send INVITE
> 0.0555 Receive 100 Trying
> 0.0571 Receive 200 OK
> 0.0603 Send ACK
> 0.0619 Receive first G.711 RTP packet
> 2.7807 Receive first H.261 RTP packet
>
> This obviously shows that Asterisk waits until receiving the ACK before
> send RTP. I'd be curious to know if Asterisk supports early media via
> 183 Session Progress - if that is enabled we should see media after the
> 183 but before the 200.
>
> Back to the problem at hand...
>
> My guess is that OPAL/Ekiga is not playing the audio until the first
> H.261 packet is received. I can't think why this would be happening -
> I'll need a level 4 trace log from OPAL before I can determine why this
> would be happening.
>
The problem is that OPAL opens the media streams when sending/receiving
the ACK and that opening the media streams (soundcard and so on) on a
GNU/Linux system takes about 2 seconds.
See the attached output.txt :
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^M
(10:24:10.110)
Then :
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.
(We are 1 second later)
[...]
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
> BTW: does simpleopal do the same thing?
Yes, it does the same thing... (both of them are using OPAL).
--
_ 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