2009/05/05 20:48:46.436	  0:00.193	                       		Version 3.2.0 by  on Unix Linux (2.6.29.1-118.fc11.i586-i686) with PTLib (v2.6.1) at 2009/5/5 20:48:46.436
2009/05/05 20:48:46.438	  0:00.195	                       	Ekiga SVN revision: unknown
2009/05/05 20:48:46.448	  0:00.206	                       	Ekiga registered on D-Bus: org.ekiga.Ekiga
2009/05/05 20:48:46.451	  0:00.208	                       	PWLib	File handle high water mark set: 17 Thread unblock pipe
2009/05/05 20:48:46.451	  0:00.208	                       	PWLib	Thread high water mark set: 2
2009/05/05 20:48:46.451	  0:00.208	                       	PWLib	File handle high water mark set: 19 Thread unblock pipe
2009/05/05 20:48:46.452	  0:00.209	                       	PWLib	Thread high water mark set: 3
2009/05/05 20:48:46.452	  0:00.209	                       	PWLib	File handle high water mark set: 21 Thread unblock pipe
2009/05/05 20:48:46.454	  0:00.211	                       	PWLib	File handle high water mark set: 23 Thread unblock pipe
2009/05/05 20:48:46.459	  0:00.216	                       	PWLib	Thread high water mark set: 4
2009/05/05 20:48:46.461	  0:00.218	                       	HalManager_dbus	Initialising HAL Manager
2009/05/05 20:48:46.476	  0:00.233	                       	HalManager_dbus	Populating device list
2009/05/05 20:48:46.721	  0:00.478	                       	HalManager_dbus	Populated device list with 11 devices
2009/05/05 20:48:46.721	  0:00.478	                       	HalManager_dbus	Populating interface list
2009/05/05 20:48:46.723	  0:00.480	                       	HalManager_dbus	Populating full interface list failed - Method "getDevices" with signature "" on interface "org.freedesktop.NetworkManager" doesn't exist

2009/05/05 20:48:46.724	  0:00.481	                       	Detecting V4L2 devices
2009/05/05 20:48:46.724	  0:00.481	                       	Unable to detect v4l2 directory
2009/05/05 20:48:46.771	  0:00.528	                       	PWLib	File handle high water mark set: 28 Thread unblock pipe
2009/05/05 20:48:46.772	  0:00.529	                       	PWLib	Thread high water mark set: 5
2009/05/05 20:48:46.772	  0:00.529	                       	OpalMan	Created manager.
2009/05/05 20:48:46.773	  0:00.530	                       	OpalMan	Registered endpoint with prefix pc
2009/05/05 20:48:46.773	  0:00.530	                       	OpalEP	Created endpoint: pc
2009/05/05 20:48:46.778	  0:00.535	                       	PCSS	Created PC sound system endpoint.
Players:
Default
EKIGA
*.wav
Intel 82801DB-ICH4
Intel 82801DB-ICH4 (1)
Intel 82801DB-ICH4 Modem
Recorders:
Default
EKIGA
*.wav
Intel 82801DB-ICH4
Intel 82801DB-ICH4 (1)
Intel 82801DB-ICH4 (2)
Intel 82801DB-ICH4 (3)
Intel 82801DB-ICH4 Modem

2009/05/05 20:48:46.779	  0:00.536	                       	OPAL	SetMediaFormatOrder()
2009/05/05 20:48:46.779	  0:00.536	                       	OPAL	SetMediaFormatMask()
2009/05/05 20:48:46.780	  0:00.537	                       	OpalMan	Registered endpoint with prefix sip
2009/05/05 20:48:46.780	  0:00.537	                       	OpalEP	Created endpoint: sip
2009/05/05 20:48:46.782	  0:00.539	                       	PWLib	File handle high water mark set: 29 PUDPSocket
2009/05/05 20:48:46.782	  0:00.539	                       	IfaceMon	Initial interface list:
127.0.0.1 [00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:01] <00-00-00-00-00-00> (lo)
192.168.1.12 [fe:80:00:00:00:00:00:00:02:90:4b:ff:fe:4f:cd:2b] <00-90-4B-4F-CD-2B> (wlan0)

2009/05/05 20:48:46.783	  0:00.540	                       	PWLib	File handle high water mark set: 30 Thread unblock pipe
2009/05/05 20:48:46.783	  0:00.540	                       	PWLib	Thread high water mark set: 6
2009/05/05 20:48:46.783	  0:00.540	  PThreadObj:0xb7affb70	IfaceMon	Started interface monitor thread.
2009/05/05 20:48:46.783	  0:00.540	  PThreadObj:0xb7affb70	PWLib	File handle high water mark set: 31 PUDPSocket
2009/05/05 20:48:46.783	  0:00.540	                       	PWLib	File handle high water mark set: 32 Thread unblock pipe
2009/05/05 20:48:46.783	  0:00.540	                       	PWLib	Thread high water mark set: 7
2009/05/05 20:48:46.785	  0:00.542	                       	OpalMan	Registered endpoint with prefix sips
2009/05/05 20:48:46.785	  0:00.542	                       	SIP	Created endpoint.
2009/05/05 20:48:46.786	  0:00.543	                       	PWLib	File handle high water mark set: 33 PUDPSocket
2009/05/05 20:48:46.786	  0:00.543	                       	MonSock	Created socket bundle for all interfaces.
2009/05/05 20:48:46.786	  0:00.543	                       	PWLib	File handle high water mark set: 34 PUDPSocket
2009/05/05 20:48:46.786	  0:00.543	                       	MonSock	Created bundled UDP socket 192.168.1.12:5060
2009/05/05 20:48:46.786	  0:00.543	                       	PWLib	File handle high water mark set: 36 Thread unblock pipe
2009/05/05 20:48:46.787	  0:00.544	                       	PWLib	Thread high water mark set: 8
2009/05/05 20:48:46.787	  0:00.544	Opal Liste...0xb7a7db70	Listen	Started listening thread on udp$*:5060
2009/05/05 20:48:46.787	  0:00.544	                       	OpalMan	Added route "sip:.*=pc:*"
2009/05/05 20:48:46.787	  0:00.544	                       	OpalMan	Added route "pc:.*=sip:<da>"
2009/05/05 20:48:46.788	  0:00.545	                       	OpalMan	Registered endpoint with prefix h323
2009/05/05 20:48:46.788	  0:00.545	                       	OpalEP	Created endpoint: h323
2009/05/05 20:48:46.788	  0:00.545	                       	OpalMan	Registered endpoint with prefix h323s
2009/05/05 20:48:46.788	  0:00.545	                       	H323	Created endpoint.
2009/05/05 20:48:46.788	  0:00.545	                       	PWLib	File handle high water mark set: 37 PTCPSocket
2009/05/05 20:48:46.788	  0:00.545	                       	PWLib	File handle high water mark set: 39 Thread unblock pipe
2009/05/05 20:48:46.788	  0:00.546	                       	PWLib	Thread high water mark set: 9
2009/05/05 20:48:46.789	  0:00.546	Opal Liste...0xb78ffb70	Listen	Started listening thread on tcp$*:1720
2009/05/05 20:48:46.789	  0:00.546	Opal Liste...0xb78ffb70	Listen	Waiting on socket accept on tcp$*:1720
2009/05/05 20:48:46.789	  0:00.546	                       	OpalMan	Added route "h323:.*=pc:<db>"
2009/05/05 20:48:46.789	  0:00.546	                       	OpalMan	Added route "pc:.*=h323:<da>"
2009/05/05 20:48:46.805	  0:00.562	                       	MediaFormat	Removing codecs theora,H.261,H.261-CIF,H.261-QCIF,SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,G.722-64k
2009/05/05 20:48:46.806	  0:00.563	                       	OPAL	SetMediaFormatMask(PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz)
2009/05/05 20:48:46.806	  0:00.563	                       	OPAL	SetMediaFormatOrder(theora,H.261,H.261-CIF,H.261-QCIF,SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,G.722-64k)
2009/05/05 20:48:46.821	  0:00.578	                       	MediaFormat	Removing codecs theora,H.261,H.261-CIF,H.261-QCIF,SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,G.722-64k
2009/05/05 20:48:46.821	  0:00.579	                       	OPAL	SetMediaFormatMask(PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz)
2009/05/05 20:48:46.822	  0:00.579	                       	OPAL	SetMediaFormatOrder(theora,H.261,H.261-CIF,H.261-QCIF,SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,G.722-64k)
2009/05/05 20:48:46.845	  0:00.602	                       	PWLib	File handle high water mark set: 41 Thread unblock pipe
2009/05/05 20:48:46.846	  0:00.603	                       	PWLib	Thread high water mark set: 10
2009/05/05 20:48:46.847	  0:00.604	StunDetector:0xb78beb70	PWLib	File handle high water mark set: 42 PUDPSocket
2009/05/05 20:48:46.953	  0:00.710	                       	VideoOutputCoreConfBridge	Updating video view
2009/05/05 20:48:46.954	  0:00.711	                       	VideoOutputCoreConfBridge	Updating zoom
2009/05/05 20:48:46.954	  0:00.711	                       	VideoOutputCoreConfBridge	Updating Video Settings
2009/05/05 20:48:46.955	  0:00.712	                       	VideoOutputCoreConfBridge	Updating Video Settings
2009/05/05 20:48:46.955	  0:00.712	                       	VideoOutputCoreConfBridge	Updating Video Settings
2009/05/05 20:48:46.955	  0:00.712	                       	VideoOutputCoreConfBridge	Updating Video Settings
2009/05/05 20:48:46.955	  0:00.712	                       	VidInputCoreConfBridge	Updating preview size and fps
2009/05/05 20:48:46.956	  0:00.713	                       	VidInputCore	Setting new preview config: 320x240/30
2009/05/05 20:48:46.959	  0:00.716	                       	VidInputCoreConfBridge	Updating preview size and fps
2009/05/05 20:48:46.959	  0:00.716	                       	VidInputCore	Setting new preview config: 320x240/30
2009/05/05 20:48:46.964	  0:00.721	                       	VidInputCoreConfBridge	Updating device
2009/05/05 20:48:46.965	  0:00.722	                       	VidInputCore	Setting device: Moving Logo (Moving Logo/Moving Logo) (PTLIB/Picture)
2009/05/05 20:48:46.965	  0:00.722	                       	GMVideoInputManager_ptlib	Setting Device Moving Logo (Moving Logo/Moving Logo) (PTLIB/Picture)
2009/05/05 20:48:46.965	  0:00.722	                       	VidInputCoreConfBridge	Updating device
2009/05/05 20:48:46.965	  0:00.722	                       	VidInputCore	Setting device: Moving Logo (Moving Logo/Moving Logo) (PTLIB/Picture)
2009/05/05 20:48:46.966	  0:00.723	                       	GMVideoInputManager_ptlib	Setting Device Moving Logo (Moving Logo/Moving Logo) (PTLIB/Picture)
2009/05/05 20:48:46.966	  0:00.723	                       	VidInputCoreConfBridge	Updating device
2009/05/05 20:48:46.966	  0:00.723	                       	VidInputCore	Setting device: Moving Logo (Moving Logo/Moving Logo) (PTLIB/Picture)
2009/05/05 20:48:46.966	  0:00.723	                       	GMVideoInputManager_ptlib	Setting Device Moving Logo (Moving Logo/Moving Logo) (PTLIB/Picture)
2009/05/05 20:48:46.966	  0:00.723	                       	VidInputCoreConfBridge	Updating image
2009/05/05 20:48:46.966	  0:00.723	                       	VidInputCoreConfBridge	Updating preview
2009/05/05 20:48:46.966	  0:00.723	                       	VidInputCore	Stopping Preview
2009/05/05 20:48:46.967	  0:00.724	                       	AudioOutputCoreConfBridge	Updating device
2009/05/05 20:48:46.967	  0:00.724	                       	AudioOutputCore	Setting device[0]: Default (PTLIB/ALSA)
2009/05/05 20:48:46.967	  0:00.724	                       	GMAudioOutputManager_ptlib	Setting Device[0] Default (PTLIB/ALSA)
2009/05/05 20:48:46.967	  0:00.724	                       	AudioOutputCoreConfBridge	Updating device
2009/05/05 20:48:46.967	  0:00.724	                       	AudioOutputCore	Setting device[1]: Default (PTLIB/ALSA)
2009/05/05 20:48:46.970	  0:00.727	                       	AudioInputCoreConfBridge	Updating device
2009/05/05 20:48:46.970	  0:00.727	                       	AudioInputCore	Setting device: Default (PTLIB/ALSA)
2009/05/05 20:48:46.970	  0:00.727	                       	GMAudioInputManager_ptlib	Setting Device Default (PTLIB/ALSA)
2009/05/05 20:48:47.073	  0:00.830	StunDetector:0xb78beb70	OPAL	STUN server "stun01.sipphone.com" replies Cone NAT, external IP 69.196.166.233
2009/05/05 20:48:47.194	  0:00.951	                       	Ekiga version 3.2.0
2009/05/05 20:48:47.195	  0:00.952	                       	OPAL version 3.6.1
2009/05/05 20:48:47.195	  0:00.952	                       	PTLIB version 2.6.1
2009/05/05 20:48:47.195	  0:00.952	                       	GNOME support disabled
2009/05/05 20:48:47.195	  0:00.952	                       	Accelerated rendering support enabled
2009/05/05 20:48:47.195	  0:00.952	                       	DBUS support enabled
2009/05/05 20:48:47.195	  0:00.952	                       	GConf support enabled
2009/05/05 20:48:47.195	  0:00.952	                       	ESound support disabled
2009/05/05 20:48:49.012	  0:02.769	                       	Listen	Stopping listening thread on udp$*:5060
2009/05/05 20:48:49.013	  0:02.770	                       	MonSock	Closed UDP socket 0x85c3c10
2009/05/05 20:48:49.013	  0:02.770	Opal Liste...0xb7a7db70	Listen	UDP read error.
2009/05/05 20:48:49.033	  0:02.790	                       	MonSock	Deleting UDP socket 0x85c3c10
2009/05/05 20:48:49.034	  0:02.791	                       	PWLib	Destroyed thread 0x85c3e30 Opal Listener:0xb7a7db70(id = 0)
2009/05/05 20:48:49.035	  0:02.792	                       	PWLib	File handle low water mark set: 33 PUDPSocket
2009/05/05 20:48:49.035	  0:02.792	                       	MonSock	Created socket bundle for all interfaces.
2009/05/05 20:48:49.097	  0:02.854	                       	MonSock	Created bundled UDP socket via STUN, internal=192.168.1.12:5060, external=69.196.166.233:60426
2009/05/05 20:48:49.099	  0:02.856	Opal Liste...0xb7a7db70	Listen	Started listening thread on udp$*:5060
2009/05/05 20:48:49.099	  0:02.856	                       	Listen	Stopping listening thread on tcp$*:1720
2009/05/05 20:48:49.100	  0:02.857	                       	PWLib	Destroyed thread 0x85c6110 Opal Listener:0xb78ffb70(id = 0)
2009/05/05 20:48:49.101	  0:02.858	Opal Liste...0xb78ffb70	Listen	Started listening thread on tcp$*:1720
2009/05/05 20:48:49.101	  0:02.858	Opal Liste...0xb78ffb70	Listen	Waiting on socket accept on tcp$*:1720
2009/05/05 20:48:49.101	  0:02.858	                       	PWLib	File handle high water mark set: 54 Thread unblock pipe
2009/05/05 20:48:49.101	  0:02.858	  subscriber:0xb506fb70	SIP	Start REGISTER
        aor=17476942064@proxy01.sipphone.com
  registrar=proxy01.sipphone.com
    contact=
     authID=17476942064
      realm=
     expire=3600
    restore=30
   minRetry=0.000
   maxRetry=0.000
2009/05/05 20:48:49.103	  0:02.860	  subscriber:0xb506fb70	PWLib	File handle high water mark set: 56 PUDPSocket
2009/05/05 20:48:49.104	  0:02.861	  subscriber:0xb506fb70	SIP	Changing REGISTER handler from Unavailable to Subscribing, target=sip:17476942064@proxy01.sipphone.com, id=8ed99a54-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
2009/05/05 20:48:49.152	  0:02.909	  subscriber:0xb506fb70	OpalUDP	Binding to interface: 0.0.0.0:5060
2009/05/05 20:48:49.153	  0:02.910	  subscriber:0xb506fb70	SIP	Created transport udp$198.65.166.131:5060<if=udp$*:5060>
2009/05/05 20:48:49.155	  0:02.912	  subscriber:0xb506fb70	OpalUDP	Started connect to 198.65.166.131:5060
2009/05/05 20:48:49.155	  0:02.912	  subscriber:0xb506fb70	OpalUDP	Writing to interface 0 - "192.168.1.12%wlan0"
2009/05/05 20:48:49.241	  0:02.998	  subscriber:0xb506fb70	OpalMan	Listener interfaces: associated transport=udp$69.196.166.233:60426
    udp$69.196.166.233:60426,udp$69.196.166.233:5060,udp$192.168.1.12:5060
2009/05/05 20:48:49.244	  0:03.001	  subscriber:0xb506fb70	SIP	Transaction  created.
2009/05/05 20:48:49.250	  0:03.007	  subscriber:0xb506fb70	SIP	No SRV lookup as has explicit port number.
2009/05/05 20:48:49.250	  0:03.007	  subscriber:0xb506fb70	SIP	Transaction remote address is udp$proxy01.sipphone.com:5060
2009/05/05 20:48:49.255	  0:03.012	  subscriber:0xb506fb70	SIP	Sending PDU (686 bytes) to: rem=udp$198.65.166.131:5060,local=udp$69.196.166.233:60426,if=192.168.1.12%wlan0
REGISTER sip:proxy01.sipphone.com SIP/2.0
Route: <sip:proxy01.sipphone.com:5060;lr>
CSeq: 1 REGISTER
Via: SIP/2.0/UDP 69.196.166.233:60426;branch=z9hG4bK6a32b154-4538-de11-9fe8-00904b4fcd2b;rport
User-Agent: Ekiga/3.2.0
From: <sip:17476942064@proxy01.sipphone.com>;tag=d2219b54-4538-de11-9fe8-00904b4fcd2b
Call-ID: 8ed99a54-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
To: <sip:17476942064@proxy01.sipphone.com>
Contact: <sip:17476942064@69.196.166.233:60426>;q=1, <sip:17476942064@69.196.166.233>;q=0.667, <sip:17476942064@192.168.1.12>;q=0.334
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Expires: 3600
Content-Length: 0
Max-Forwards: 70


2009/05/05 20:48:49.290	  0:03.047	  subscriber:0xb506fb70	OpalUDP	Setting interface to 192.168.1.12%wlan0
2009/05/05 20:48:49.290	  0:03.047	  subscriber:0xb506fb70	SIP	Transaction timers set: retry=0.500, completion=6.000
2009/05/05 20:48:49.403	  0:03.160	                       	OpalMan	Set up call from pc:* to sip:echo@proxy01.sipphone.com
2009/05/05 20:48:49.405	  0:03.162	                       	Call	Created Call[jb1729b981]
2009/05/05 20:48:49.405	  0:03.162	                       	OpalMan	Set up connection to "pc:*"
2009/05/05 20:48:49.409	  0:03.166	                       	OpalCon	Created connection Call[jb1729b981]-EP<pc>[e963b32e12]
2009/05/05 20:48:49.409	  0:03.166	                       	Silence	Parameters set: mode=NoSilenceDetection, threshold=0, silencedb=3200, signaldb=80, period=4800
2009/05/05 20:48:49.410	  0:03.167	                       	Silence	Handler created
2009/05/05 20:48:49.410	  0:03.167	                       	Echo Canceler	Handler created
2009/05/05 20:48:49.410	  0:03.167	                       	PCSS	Created PC sound system connection: token="e963b32e12" player="EKIGA" recorder="EKIGA"
2009/05/05 20:48:49.410	  0:03.167	                       	OpalCon	SetPhase from UninitialisedPhase to SetUpPhase for Call[jb1729b981]-EP<pc>[e963b32e12]
2009/05/05 20:48:49.411	  0:03.168	                       	OpalMan	OnIncoming connection Call[jb1729b981]-EP<pc>[e963b32e12]
2009/05/05 20:48:49.411	  0:03.168	                       	OpalCon	Applying string options:


2009/05/05 20:48:49.411	  0:03.168	                       	Call	GetOtherPartyConnection Call[jb1729b981]-EP<pc>[e963b32e12]
2009/05/05 20:48:49.411	  0:03.168	                       	OpalMan	Searching for route "pc:jesse	sip:echo@proxy01.sipphone.com"
2009/05/05 20:48:49.412	  0:03.169	                       	OpalMan	Set up connection to "sip:echo@proxy01.sipphone.com"
2009/05/05 20:48:49.412	  0:03.170	                       	OpalCon	Created connection Call[jb1729b981]-EP<sip>[842cca54-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:48:49.413	  0:03.170	                       	RFC2833	Handler created
2009/05/05 20:48:49.414	  0:03.171	                       	RFC2833	Handler created
2009/05/05 20:48:49.416	  0:03.173	                       	SIP	Created connection.
2009/05/05 20:48:49.417	  0:03.174	                       	PCSS	Outgoing call routed to sip:echo@proxy01.sipphone.com for Call[jb1729b981]-EP<pc>[e963b32e12]
2009/05/05 20:48:49.417	  0:03.174	                       	PWLib	File handle low water mark set: 54 Thread unblock pipe
2009/05/05 20:48:49.418	  0:03.175	   CallSetup:0xb502eb70	Call	OnSetUp Call[jb1729b981]-EP<pc>[e963b32e12]
2009/05/05 20:48:49.418	  0:03.175	   CallSetup:0xb502eb70	SIP	SetUpConnection: sip:echo@proxy01.sipphone.com
2009/05/05 20:48:49.419	  0:03.176	   CallSetup:0xb502eb70	OpalCon	SetPhase from UninitialisedPhase to SetUpPhase for Call[jb1729b981]-EP<sip>[842cca54-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:48:49.419	  0:03.176	   CallSetup:0xb502eb70	OpalCon	Applying string options:


2009/05/05 20:48:49.419	  0:03.176	   CallSetup:0xb502eb70	SIP	Found registrar on domain proxy01.sipphone.com, using interface 192.168.1.12%wlan0
2009/05/05 20:48:49.421	  0:03.178	   CallSetup:0xb502eb70	OpalUDP	Binding to interface: 192.168.1.12:5060
2009/05/05 20:48:49.421	  0:03.178	   CallSetup:0xb502eb70	SIP	Created transport udp$198.65.166.131:5060<if=udp$69.196.166.233:60426>
2009/05/05 20:48:49.423	  0:03.180	   CallSetup:0xb502eb70	OpalUDP	Started connect to 198.65.166.131:5060
2009/05/05 20:48:49.424	  0:03.181	   CallSetup:0xb502eb70	SIP	Updating dialog tag from "" to "842cca54-4538-de11-9fe8-00904b4fcd2b"
2009/05/05 20:48:49.434	  0:03.191	   CallSetup:0xb502eb70	SIP	Transaction 1 INVITE created.
2009/05/05 20:48:49.435	  0:03.192	                       	OpalMan	SetUpCall succeeded, call=Call[jb1729b981]
2009/05/05 20:48:49.487	  0:03.244	Opal Liste...0xb7a7db70	OpalUDP	Binding to interface: 69.196.166.233:60426
2009/05/05 20:48:49.488	  0:03.245	Opal Liste...0xb7a7db70	SIP	Waiting for PDU on udp$198.65.166.131:5060<if=udp$69.196.166.233:60426>
2009/05/05 20:48:49.490	  0:03.247	Opal Liste...0xb7a7db70	SIP	PDU received: rem=udp$198.65.166.131:5060,local=udp$69.196.166.233:60426,if=192.168.1.12%wlan0
SIP/2.0 401 Unauthorized
P-Behind-NAT: Yes
CSeq: 1 REGISTER
Via: SIP/2.0/UDP 69.196.166.233:60426;branch=z9hG4bK6a32b154-4538-de11-9fe8-00904b4fcd2b;rport=60426
From: <sip:17476942064@proxy01.sipphone.com>;tag=d2219b54-4538-de11-9fe8-00904b4fcd2b
Call-ID: 8ed99a54-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
To: <sip:17476942064@proxy01.sipphone.com>;tag=92390300a369f0d75803e369c733575e.0c3f
Content-Length: 0
WWW-Authenticate: Digest realm="proxy01.sipphone.com", nonce="4a00df9d1acdcc21d5a5c048405b0d50c694984b"


2009/05/05 20:48:49.493	  0:03.250	Opal Liste...0xb7a7db70	SIP	Transaction 1 REGISTER completed.
2009/05/05 20:48:49.493	  0:03.250	Opal Liste...0xb7a7db70	SIP	Received Authentication Required response
2009/05/05 20:48:49.494	  0:03.251	Opal Liste...0xb7a7db70	OpalUDP	Setting interface to 192.168.1.12%wlan0
2009/05/05 20:48:49.494	  0:03.251	Opal Liste...0xb7a7db70	SIP	Changing REGISTER handler from Subscribing to Subscribing, target=sip:17476942064@proxy01.sipphone.com, id=8ed99a54-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
2009/05/05 20:48:49.498	  0:03.255	Opal Liste...0xb7a7db70	OpalMan	Listener interfaces: associated transport=udp$69.196.166.233:60426
    udp$69.196.166.233:60426,udp$69.196.166.233:5060,udp$192.168.1.12:5060
2009/05/05 20:48:49.501	  0:03.258	Opal Liste...0xb7a7db70	SIP	Transaction  created.
2009/05/05 20:48:49.506	  0:03.263	Opal Liste...0xb7a7db70	SIP	Adding authentication information
2009/05/05 20:48:49.507	  0:03.264	Opal Liste...0xb7a7db70	SIP	No SRV lookup as has explicit port number.
2009/05/05 20:48:49.508	  0:03.265	Opal Liste...0xb7a7db70	SIP	Transaction remote address is udp$proxy01.sipphone.com:5060
2009/05/05 20:48:49.509	  0:03.266	Opal Liste...0xb7a7db70	SIP	Sending PDU (904 bytes) to: rem=udp$198.65.166.131:5060,local=udp$69.196.166.233:60426,if=192.168.1.12%wlan0
REGISTER sip:proxy01.sipphone.com SIP/2.0
Route: <sip:proxy01.sipphone.com:5060;lr>
CSeq: 2 REGISTER
Via: SIP/2.0/UDP 69.196.166.233:60426;branch=z9hG4bKb67dd854-4538-de11-9fe8-00904b4fcd2b;rport
User-Agent: Ekiga/3.2.0
Authorization: Digest username="17476942064", realm="proxy01.sipphone.com", nonce="4a00df9d1acdcc21d5a5c048405b0d50c694984b", uri="sip:proxy01.sipphone.com", algorithm=MD5, response="caf4be7d73599bdbd2ad3064eb556f1a"
From: <sip:17476942064@proxy01.sipphone.com>;tag=d2219b54-4538-de11-9fe8-00904b4fcd2b
Call-ID: 8ed99a54-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
To: <sip:17476942064@proxy01.sipphone.com>
Contact: <sip:17476942064@69.196.166.233:60426>;q=1, <sip:17476942064@69.196.166.233>;q=0.667, <sip:17476942064@192.168.1.12>;q=0.334
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Expires: 3600
Content-Length: 0
Max-Forwards: 70


2009/05/05 20:48:49.518	  0:03.275	Opal Liste...0xb7a7db70	OpalUDP	Setting interface to 192.168.1.12%wlan0
2009/05/05 20:48:49.520	  0:03.277	Opal Liste...0xb7a7db70	SIP	Transaction timers set: retry=0.500, completion=6.000
2009/05/05 20:48:49.521	  0:03.278	Opal Liste...0xb7a7db70	Opal	Transport clean up on termination
2009/05/05 20:48:49.521	  0:03.278	Opal Liste...0xb7a7db70	Opal	Transport Close
2009/05/05 20:48:49.521	  0:03.278	Opal Liste...0xb7a7db70	Opal	Deleted transport udp$198.65.166.131:5060<if=udp$69.196.166.233:60426>
2009/05/05 20:48:49.540	  0:03.297	   CallSetup:0xb502eb70	MediaFormat	Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz
2009/05/05 20:48:49.545	  0:03.302	   CallSetup:0xb502eb70	MediaFormat	Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz
2009/05/05 20:48:49.546	  0:03.303	   CallSetup:0xb502eb70	Call	GetMediaFormats for Call[jb1729b981]-EP<sip>[842cca54-4538-de11-9fe8-00904b4fcd2b]
theora
H.261
H.261-CIF
H.261-QCIF
SpeexIETFWide-20.6k
SpeexWB
SpeexWide-20.6k
G.711-uLaw-64k
G.711-ALaw-64k
G.722-64k

2009/05/05 20:48:49.546	  0:03.303	   CallSetup:0xb502eb70	SIP	Offering media type audio in SDP with formats
theora,H.261,H.261-CIF,H.261-QCIF,SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,G.722-64k
2009/05/05 20:48:49.547	  0:03.304	   CallSetup:0xb502eb70	Call	IsMediaBypassPossible Call[jb1729b981]-EP<sip>[842cca54-4538-de11-9fe8-00904b4fcd2b] session 1
2009/05/05 20:48:49.547	  0:03.304	   CallSetup:0xb502eb70	OpalMan	IsMediaBypassPossible: session 1
2009/05/05 20:48:49.547	  0:03.304	   CallSetup:0xb502eb70	OpalCon	IsMediaBypassPossible: default returns false
2009/05/05 20:48:49.547	  0:03.304	   CallSetup:0xb502eb70	RTP	Cannot find media session 1
2009/05/05 20:48:49.547	  0:03.304	   CallSetup:0xb502eb70	RTP	Cannot find RTP session 1
2009/05/05 20:48:49.559	  0:03.316	   CallSetup:0xb502eb70	RTP_UDP	Session 1, created with NAT flag set to 0
2009/05/05 20:48:49.560	  0:03.317	   CallSetup:0xb502eb70	PWLib	File handle high water mark set: 57 PUDPSocket
2009/05/05 20:48:49.561	  0:03.318	   CallSetup:0xb502eb70	PWLib	File handle high water mark set: 58 PUDPSocket
2009/05/05 20:48:49.561	  0:03.318	   CallSetup:0xb502eb70	PWLib	File handle high water mark set: 59 PUDPSocket
2009/05/05 20:48:49.694	  0:03.451	Opal Liste...0xb7a7db70	PWLib	File handle high water mark set: 60 PUDPSocket
2009/05/05 20:48:49.694	  0:03.451	Opal Liste...0xb7a7db70	OpalUDP	Binding to interface: 69.196.166.233:60426
2009/05/05 20:48:49.694	  0:03.452	Opal Liste...0xb7a7db70	SIP	Waiting for PDU on udp$198.65.166.131:5060<if=udp$69.196.166.233:60426>
2009/05/05 20:48:49.696	  0:03.453	Opal Liste...0xb7a7db70	SIP	PDU received: rem=udp$198.65.166.131:5060,local=udp$69.196.166.233:60426,if=192.168.1.12%wlan0
SIP/2.0 200 OK
P-Behind-NAT: Yes
CSeq: 2 REGISTER
Via: SIP/2.0/UDP 69.196.166.233:60426;branch=z9hG4bKb67dd854-4538-de11-9fe8-00904b4fcd2b;rport=60426
From: <sip:17476942064@proxy01.sipphone.com>;tag=d2219b54-4538-de11-9fe8-00904b4fcd2b
Call-ID: 8ed99a54-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
To: <sip:17476942064@proxy01.sipphone.com>;tag=92390300a369f0d75803e369c733575e.7bfb
Contact: <sip:17476942064@69.196.166.233:60426>;q=1;expires=3600, <sip:17476942064@69.196.166.233>;q=0.667;expires=3600
Content-Length: 0


2009/05/05 20:48:49.699	  0:03.456	Opal Liste...0xb7a7db70	SIP	Transaction 2 REGISTER completed.
2009/05/05 20:48:49.699	  0:03.456	Opal Liste...0xb7a7db70	SIP	Changing REGISTER handler from Subscribing to Subscribed, target=sip:17476942064@proxy01.sipphone.com, id=8ed99a54-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
2009/05/05 20:48:49.699	  0:03.456	Opal Liste...0xb7a7db70	OpalUDP	Setting interface to 192.168.1.12%wlan0
2009/05/05 20:48:49.702	  0:03.459	Opal Liste...0xb7a7db70	SIP	Expiry time for REGISTER set to 3600 seconds.
2009/05/05 20:48:49.702	  0:03.459	Opal Liste...0xb7a7db70	SIP	Expiry time for REGISTER set to 3600 seconds.
2009/05/05 20:48:49.704	  0:03.461	Opal Liste...0xb7a7db70	SIP	Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:record@proxy01.sipphone.com, id=a89af654-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
2009/05/05 20:48:49.704	  0:03.461	Opal Liste...0xb7a7db70	OpalUDP	Binding to interface: 0.0.0.0:5060
2009/05/05 20:48:49.705	  0:03.462	Opal Liste...0xb7a7db70	SIP	Created transport udp$198.65.166.131:5060<if=udp$*:5060>
2009/05/05 20:48:49.706	  0:03.463	Opal Liste...0xb7a7db70	OpalUDP	Started connect to 198.65.166.131:5060
2009/05/05 20:48:49.708	  0:03.465	Opal Liste...0xb7a7db70	OpalUDP	Writing to interface 0 - "192.168.1.12%wlan0"
2009/05/05 20:48:49.710	  0:03.467	Opal Liste...0xb7a7db70	SIP	Transaction  created.
2009/05/05 20:48:49.719	  0:03.476	Opal Liste...0xb7a7db70	SIP	No SRV lookup as has explicit port number.
2009/05/05 20:48:49.720	  0:03.477	Opal Liste...0xb7a7db70	SIP	Transaction remote address is udp$proxy01.sipphone.com:5060
2009/05/05 20:48:49.722	  0:03.479	Opal Liste...0xb7a7db70	SIP	Sending PDU (666 bytes) to: rem=udp$198.65.166.131:5060,local=udp$69.196.166.233:60426,if=192.168.1.12%wlan0
SUBSCRIBE sip:record@proxy01.sipphone.com SIP/2.0
Route: <sip:proxy01.sipphone.com:5060;lr>
CSeq: 1 SUBSCRIBE
Via: SIP/2.0/UDP 69.196.166.233:60426;branch=z9hG4bK242cf854-4538-de11-9fe8-00904b4fcd2b;rport
User-Agent: Ekiga/3.2.0
From: "Jesse Kahtava" <sip:17476942064@proxy01.sipphone.com>;tag=b898f754-4538-de11-9fe8-00904b4fcd2b
Call-ID: a89af654-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
To: <sip:record@proxy01.sipphone.com>
Contact: <sip:17476942064@69.196.166.233:60426>
Accept: application/pidf+xml
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Expires: 300
Event: presence
Content-Length: 0
Max-Forwards: 70


2009/05/05 20:48:49.723	  0:03.480	Opal Liste...0xb7a7db70	OpalUDP	Setting interface to 192.168.1.12%wlan0
2009/05/05 20:48:49.724	  0:03.481	Opal Liste...0xb7a7db70	SIP	Transaction timers set: retry=0.500, completion=6.000
2009/05/05 20:48:49.725	  0:03.482	Opal Liste...0xb7a7db70	SIP	Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:record@proxy01.sipphone.com, id=c8e5f954-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
2009/05/05 20:48:49.741	  0:03.499	Opal Liste...0xb7a7db70	OpalUDP	Binding to interface: 0.0.0.0:5060
2009/05/05 20:48:49.743	  0:03.500	Opal Liste...0xb7a7db70	SIP	Created transport udp$198.65.166.131:5060<if=udp$*:5060>
2009/05/05 20:48:49.744	  0:03.501	Opal Liste...0xb7a7db70	OpalUDP	Started connect to 198.65.166.131:5060
2009/05/05 20:48:49.745	  0:03.502	Opal Liste...0xb7a7db70	OpalUDP	Writing to interface 0 - "192.168.1.12%wlan0"
2009/05/05 20:48:49.746	  0:03.503	Opal Liste...0xb7a7db70	SIP	Transaction  created.
2009/05/05 20:48:49.755	  0:03.513	Opal Liste...0xb7a7db70	SIP	No SRV lookup as has explicit port number.
2009/05/05 20:48:49.756	  0:03.513	Opal Liste...0xb7a7db70	SIP	Transaction remote address is udp$proxy01.sipphone.com:5060
2009/05/05 20:48:49.758	  0:03.515	Opal Liste...0xb7a7db70	SIP	Sending PDU (673 bytes) to: rem=udp$198.65.166.131:5060,local=udp$69.196.166.233:60426,if=192.168.1.12%wlan0
SUBSCRIBE sip:record@proxy01.sipphone.com SIP/2.0
Route: <sip:proxy01.sipphone.com:5060;lr>
CSeq: 1 SUBSCRIBE
Via: SIP/2.0/UDP 69.196.166.233:60426;branch=z9hG4bK1298fd54-4538-de11-9fe8-00904b4fcd2b;rport
User-Agent: Ekiga/3.2.0
From: "Jesse Kahtava" <sip:17476942064@proxy01.sipphone.com>;tag=d8fffc54-4538-de11-9fe8-00904b4fcd2b
Call-ID: c8e5f954-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
To: <sip:record@proxy01.sipphone.com>
Contact: <sip:record@69.196.166.233:60426>
Accept: application/dialog-info+xml
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Expires: 300
Event: dialog;sla;ma
Content-Length: 0
Max-Forwards: 70


2009/05/05 20:48:49.759	  0:03.516	Opal Liste...0xb7a7db70	OpalUDP	Setting interface to 192.168.1.12%wlan0
2009/05/05 20:48:49.760	  0:03.517	Opal Liste...0xb7a7db70	SIP	Transaction timers set: retry=0.500, completion=6.000
2009/05/05 20:48:49.762	  0:03.519	Opal Liste...0xb7a7db70	SIP	Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:echo@proxy01.sipphone.com, id=e270ff54-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
2009/05/05 20:48:49.762	  0:03.519	Opal Liste...0xb7a7db70	OpalUDP	Binding to interface: 0.0.0.0:5060
2009/05/05 20:48:49.763	  0:03.520	Opal Liste...0xb7a7db70	SIP	Created transport udp$198.65.166.131:5060<if=udp$*:5060>
2009/05/05 20:48:49.764	  0:03.521	Opal Liste...0xb7a7db70	OpalUDP	Started connect to 198.65.166.131:5060
2009/05/05 20:48:49.764	  0:03.521	Opal Liste...0xb7a7db70	OpalUDP	Writing to interface 0 - "192.168.1.12%wlan0"
2009/05/05 20:48:49.767	  0:03.524	Opal Liste...0xb7a7db70	SIP	Transaction  created.
2009/05/05 20:48:49.776	  0:03.533	Opal Liste...0xb7a7db70	SIP	No SRV lookup as has explicit port number.
2009/05/05 20:48:49.776	  0:03.533	Opal Liste...0xb7a7db70	SIP	Transaction remote address is udp$proxy01.sipphone.com:5060
2009/05/05 20:48:49.779	  0:03.536	Opal Liste...0xb7a7db70	SIP	Sending PDU (662 bytes) to: rem=udp$198.65.166.131:5060,local=udp$69.196.166.233:60426,if=192.168.1.12%wlan0
SUBSCRIBE sip:echo@proxy01.sipphone.com SIP/2.0
Route: <sip:proxy01.sipphone.com:5060;lr>
CSeq: 1 SUBSCRIBE
Via: SIP/2.0/UDP 69.196.166.233:60426;branch=z9hG4bK56f60055-4538-de11-9fe8-00904b4fcd2b;rport
User-Agent: Ekiga/3.2.0
From: "Jesse Kahtava" <sip:17476942064@proxy01.sipphone.com>;tag=66460055-4538-de11-9fe8-00904b4fcd2b
Call-ID: e270ff54-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
To: <sip:echo@proxy01.sipphone.com>
Contact: <sip:17476942064@69.196.166.233:60426>
Accept: application/pidf+xml
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Expires: 300
Event: presence
Content-Length: 0
Max-Forwards: 70


2009/05/05 20:48:49.781	  0:03.538	Opal Liste...0xb7a7db70	OpalUDP	Setting interface to 192.168.1.12%wlan0
2009/05/05 20:48:49.782	  0:03.539	Opal Liste...0xb7a7db70	SIP	Transaction timers set: retry=0.500, completion=6.000
2009/05/05 20:48:49.784	  0:03.541	Opal Liste...0xb7a7db70	SIP	Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:echo@proxy01.sipphone.com, id=aece0255-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
2009/05/05 20:48:49.784	  0:03.541	Opal Liste...0xb7a7db70	OpalUDP	Binding to interface: 0.0.0.0:5060
2009/05/05 20:48:49.785	  0:03.542	Opal Liste...0xb7a7db70	SIP	Created transport udp$198.65.166.131:5060<if=udp$*:5060>
2009/05/05 20:48:49.786	  0:03.543	Opal Liste...0xb7a7db70	OpalUDP	Started connect to 198.65.166.131:5060
2009/05/05 20:48:49.786	  0:03.543	Opal Liste...0xb7a7db70	OpalUDP	Writing to interface 0 - "192.168.1.12%wlan0"
2009/05/05 20:48:49.788	  0:03.545	Opal Liste...0xb7a7db70	SIP	Transaction  created.
2009/05/05 20:48:49.797	  0:03.554	Opal Liste...0xb7a7db70	SIP	No SRV lookup as has explicit port number.
2009/05/05 20:48:49.798	  0:03.555	Opal Liste...0xb7a7db70	SIP	Transaction remote address is udp$proxy01.sipphone.com:5060
2009/05/05 20:48:49.799	  0:03.556	Opal Liste...0xb7a7db70	SIP	Sending PDU (667 bytes) to: rem=udp$198.65.166.131:5060,local=udp$69.196.166.233:60426,if=192.168.1.12%wlan0
SUBSCRIBE sip:echo@proxy01.sipphone.com SIP/2.0
Route: <sip:proxy01.sipphone.com:5060;lr>
CSeq: 1 SUBSCRIBE
Via: SIP/2.0/UDP 69.196.166.233:60426;branch=z9hG4bK923e0455-4538-de11-9fe8-00904b4fcd2b;rport
User-Agent: Ekiga/3.2.0
From: "Jesse Kahtava" <sip:17476942064@proxy01.sipphone.com>;tag=786d0355-4538-de11-9fe8-00904b4fcd2b
Call-ID: aece0255-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
To: <sip:echo@proxy01.sipphone.com>
Contact: <sip:echo@69.196.166.233:60426>
Accept: application/dialog-info+xml
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Expires: 300
Event: dialog;sla;ma
Content-Length: 0
Max-Forwards: 70


2009/05/05 20:48:49.801	  0:03.558	Opal Liste...0xb7a7db70	OpalUDP	Setting interface to 192.168.1.12%wlan0
2009/05/05 20:48:49.801	  0:03.559	Opal Liste...0xb7a7db70	SIP	Transaction timers set: retry=0.500, completion=6.000
2009/05/05 20:48:49.804	  0:03.561	Opal Liste...0xb7a7db70	SIP	Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:17476942064@proxy01.sipphone.com, id=80e00555-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
2009/05/05 20:48:49.805	  0:03.562	Opal Liste...0xb7a7db70	OpalUDP	Binding to interface: 0.0.0.0:5060
2009/05/05 20:48:49.805	  0:03.562	Opal Liste...0xb7a7db70	SIP	Created transport udp$198.65.166.131:5060<if=udp$*:5060>
2009/05/05 20:48:49.806	  0:03.563	Opal Liste...0xb7a7db70	OpalUDP	Started connect to 198.65.166.131:5060
2009/05/05 20:48:49.808	  0:03.565	Opal Liste...0xb7a7db70	OpalUDP	Writing to interface 0 - "192.168.1.12%wlan0"
2009/05/05 20:48:49.810	  0:03.567	Opal Liste...0xb7a7db70	SIP	Transaction  created.
2009/05/05 20:48:49.812	  0:03.569	   CallSetup:0xb502eb70	STUN	Could not get a pair of adjacent port numbers from NAT
2009/05/05 20:48:49.812	  0:03.569	   CallSetup:0xb502eb70	RTP	Session 1, STUN could not create RTP/RTCP socket pair; trying to create RTP socket anyway.
2009/05/05 20:48:49.813	  0:03.570	   CallSetup:0xb502eb70	PWLib	File handle low water mark set: 56 PUDPSocket
2009/05/05 20:48:49.824	  0:03.581	Opal Liste...0xb7a7db70	SIP	No SRV lookup as has explicit port number.
2009/05/05 20:48:49.825	  0:03.582	Opal Liste...0xb7a7db70	SIP	Transaction remote address is udp$proxy01.sipphone.com:5060
2009/05/05 20:48:49.826	  0:03.583	Opal Liste...0xb7a7db70	SIP	Sending PDU (698 bytes) to: rem=udp$198.65.166.131:5060,local=udp$69.196.166.233:60426,if=192.168.1.12%wlan0
SUBSCRIBE sip:17476942064@proxy01.sipphone.com SIP/2.0
Route: <sip:proxy01.sipphone.com:5060;lr>
CSeq: 1 SUBSCRIBE
Via: SIP/2.0/UDP 69.196.166.233:60426;branch=z9hG4bK02ad0755-4538-de11-9fe8-00904b4fcd2b;rport
User-Agent: Ekiga/3.2.0
From: "Jesse Kahtava" <sip:17476942064@proxy01.sipphone.com>;tag=0ae20655-4538-de11-9fe8-00904b4fcd2b
Call-ID: 80e00555-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
To: <sip:17476942064@proxy01.sipphone.com>
Contact: <sip:17476942064@69.196.166.233:60426>
Accept: application/simple-message-summary
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Expires: 3600
Event: message-summary
Content-Length: 0
Max-Forwards: 70


2009/05/05 20:48:49.828	  0:03.585	Opal Liste...0xb7a7db70	OpalUDP	Setting interface to 192.168.1.12%wlan0
2009/05/05 20:48:49.829	  0:03.586	Opal Liste...0xb7a7db70	SIP	Transaction timers set: retry=0.500, completion=6.000
2009/05/05 20:48:49.830	  0:03.587	Opal Liste...0xb7a7db70	Opal	Transport clean up on termination
2009/05/05 20:48:49.830	  0:03.587	Opal Liste...0xb7a7db70	Opal	Transport Close
2009/05/05 20:48:49.830	  0:03.587	Opal Liste...0xb7a7db70	Opal	Deleted transport udp$198.65.166.131:5060<if=udp$69.196.166.233:60426>
2009/05/05 20:48:49.898	  0:03.656	Opal Liste...0xb7a7db70	OpalUDP	Binding to interface: 69.196.166.233:60426
2009/05/05 20:48:49.900	  0:03.657	Opal Liste...0xb7a7db70	SIP	Waiting for PDU on udp$198.65.166.131:5060<if=udp$69.196.166.233:60426>
2009/05/05 20:48:49.902	  0:03.659	Opal Liste...0xb7a7db70	SIP	PDU received: rem=udp$198.65.166.131:5060,local=udp$69.196.166.233:60426,if=192.168.1.12%wlan0
SIP/2.0 501 method not understood here
CSeq: 1 SUBSCRIBE
Via: SIP/2.0/UDP 69.196.166.233:60426;branch=z9hG4bK242cf854-4538-de11-9fe8-00904b4fcd2b;rport=60426
Server: Sip EXpress router (0.8.12-tcp_nonb (i386/linux))
From: "Jesse Kahtava" <sip:17476942064@proxy01.sipphone.com>;tag=b898f754-4538-de11-9fe8-00904b4fcd2b
RemoteIP: 198.65.166.130
Call-ID: a89af654-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
To: <sip:record@proxy01.sipphone.com>;tag=66a86bf2dbe7aa36519dba52f24a951e.116f
Warning: 392 198.65.166.130:5060 "Noisy feedback tells:  pid=29142 req_src_ip=198.65.166.131 req_src_port=5060 in_uri=sip:record@sems01.sipphone.com out_uri=sip:record@sems01.sipphone.com via_cnt==2"
Content-Length: 0


2009/05/05 20:48:49.904	  0:03.661	Opal Liste...0xb7a7db70	SIP	Transaction 1 SUBSCRIBE completed.
2009/05/05 20:48:49.904	  0:03.661	Opal Liste...0xb7a7db70	SIP	Not retrying SUBSCRIBE due to error response 501 Not Implemented
2009/05/05 20:48:49.904	  0:03.661	Opal Liste...0xb7a7db70	SIP	Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:record@proxy01.sipphone.com, id=a89af654-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
2009/05/05 20:48:49.904	  0:03.662	Opal Liste...0xb7a7db70	SIP	Set state Terminated_Aborted for transaction 1 SUBSCRIBE
2009/05/05 20:48:49.905	  0:03.662	Opal Liste...0xb7a7db70	SIP	Not retrying SUBSCRIBE due to error response 487 Request Terminated
2009/05/05 20:48:49.905	  0:03.662	Opal Liste...0xb7a7db70	SIP	Changing SUBSCRIBE handler from Unsubscribed to Unsubscribed, target=sip:record@proxy01.sipphone.com, id=a89af654-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
2009/05/05 20:48:49.905	  0:03.663	Opal Liste...0xb7a7db70	Opal	Transport clean up on termination
2009/05/05 20:48:49.906	  0:03.663	Opal Liste...0xb7a7db70	Opal	Transport Close
2009/05/05 20:48:49.906	  0:03.663	Opal Liste...0xb7a7db70	Opal	Deleted transport udp$198.65.166.131:5060<if=udp$69.196.166.233:60426>
2009/05/05 20:48:49.932	  0:03.689	Opal Liste...0xb7a7db70	OpalUDP	Binding to interface: 69.196.166.233:60426
2009/05/05 20:48:49.933	  0:03.691	Opal Liste...0xb7a7db70	SIP	Waiting for PDU on udp$198.65.166.131:5060<if=udp$69.196.166.233:60426>
2009/05/05 20:48:49.935	  0:03.692	Opal Liste...0xb7a7db70	SIP	PDU received: rem=udp$198.65.166.131:5060,local=udp$69.196.166.233:60426,if=192.168.1.12%wlan0
SIP/2.0 501 method not understood here
CSeq: 1 SUBSCRIBE
Via: SIP/2.0/UDP 69.196.166.233:60426;branch=z9hG4bK1298fd54-4538-de11-9fe8-00904b4fcd2b;rport=60426
Server: Sip EXpress router (0.8.12-tcp_nonb (i386/linux))
From: "Jesse Kahtava" <sip:17476942064@proxy01.sipphone.com>;tag=d8fffc54-4538-de11-9fe8-00904b4fcd2b
RemoteIP: 198.65.166.130
Call-ID: c8e5f954-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
To: <sip:record@proxy01.sipphone.com>;tag=66a86bf2dbe7aa36519dba52f24a951e.5756
Warning: 392 198.65.166.130:5060 "Noisy feedback tells:  pid=29142 req_src_ip=198.65.166.131 req_src_port=5060 in_uri=sip:record@sems01.sipphone.com out_uri=sip:record@sems01.sipphone.com via_cnt==2"
Content-Length: 0


2009/05/05 20:48:49.937	  0:03.694	Opal Liste...0xb7a7db70	SIP	Transaction 1 SUBSCRIBE completed.
2009/05/05 20:48:49.938	  0:03.695	Opal Liste...0xb7a7db70	SIP	Not retrying SUBSCRIBE due to error response 501 Not Implemented
2009/05/05 20:48:49.938	  0:03.695	Opal Liste...0xb7a7db70	SIP	Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:record@proxy01.sipphone.com, id=c8e5f954-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
2009/05/05 20:48:49.938	  0:03.695	Opal Liste...0xb7a7db70	SIP	Set state Terminated_Aborted for transaction 1 SUBSCRIBE
2009/05/05 20:48:49.938	  0:03.696	Opal Liste...0xb7a7db70	SIP	Not retrying SUBSCRIBE due to error response 487 Request Terminated
2009/05/05 20:48:49.939	  0:03.696	   CallSetup:0xb502eb70	RTP_UDP	Session 1 created: 69.196.166.233:60016-60017 ssrc=2290997804
2009/05/05 20:48:49.940	  0:03.697	   CallSetup:0xb502eb70	RTP	Creating new session RTP_UDP
2009/05/05 20:48:49.940	  0:03.697	   CallSetup:0xb502eb70	RTP	Found existing media session 1
2009/05/05 20:48:49.942	  0:03.699	   CallSetup:0xb502eb70	SDP	SDP not including SpeexIETFWide-20.6k as it is not a SIP transportable format
2009/05/05 20:48:49.942	  0:03.699	   CallSetup:0xb502eb70	SDP	SDP not including SpeexWide-20.6k as it is not a SIP transportable format
2009/05/05 20:48:49.943	  0:03.700	   CallSetup:0xb502eb70	SIP	Using default RTP payload [pt=101] for UserInput/RFC2833
2009/05/05 20:48:49.943	  0:03.700	   CallSetup:0xb502eb70	SIP	Using default RTP payload [pt=120] for NamedSignalEvent
2009/05/05 20:48:49.959	  0:03.716	   CallSetup:0xb502eb70	MediaFormat	Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz
2009/05/05 20:48:49.963	  0:03.720	   CallSetup:0xb502eb70	MediaFormat	Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz
2009/05/05 20:48:49.965	  0:03.722	   CallSetup:0xb502eb70	Call	GetMediaFormats for Call[jb1729b981]-EP<sip>[842cca54-4538-de11-9fe8-00904b4fcd2b]
theora
H.261
H.261-CIF
H.261-QCIF
SpeexIETFWide-20.6k
SpeexWB
SpeexWide-20.6k
G.711-uLaw-64k
G.711-ALaw-64k
G.722-64k

2009/05/05 20:48:49.965	  0:03.722	   CallSetup:0xb502eb70	SIP	Offering media type video in SDP with formats
theora,H.261,H.261-CIF,H.261-QCIF,SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,G.722-64k
2009/05/05 20:48:49.965	  0:03.722	   CallSetup:0xb502eb70	Call	IsMediaBypassPossible Call[jb1729b981]-EP<sip>[842cca54-4538-de11-9fe8-00904b4fcd2b] session 2
2009/05/05 20:48:49.965	  0:03.722	   CallSetup:0xb502eb70	OpalMan	IsMediaBypassPossible: session 2
2009/05/05 20:48:49.965	  0:03.722	   CallSetup:0xb502eb70	OpalCon	IsMediaBypassPossible: default returns false
2009/05/05 20:48:49.966	  0:03.723	   CallSetup:0xb502eb70	RTP	Cannot find media session 2
2009/05/05 20:48:49.966	  0:03.723	   CallSetup:0xb502eb70	RTP	Cannot find RTP session 2
2009/05/05 20:48:49.939	  0:03.696	Opal Liste...0xb7a7db70	SIP	Changing SUBSCRIBE handler from Unsubscribed to Unsubscribed, target=sip:record@proxy01.sipphone.com, id=c8e5f954-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
2009/05/05 20:48:49.971	  0:03.728	Opal Liste...0xb7a7db70	Opal	Transport clean up on termination
2009/05/05 20:48:49.971	  0:03.728	Opal Liste...0xb7a7db70	Opal	Transport Close
2009/05/05 20:48:49.972	  0:03.729	Opal Liste...0xb7a7db70	Opal	Deleted transport udp$198.65.166.131:5060<if=udp$69.196.166.233:60426>
2009/05/05 20:48:49.973	  0:03.730	   CallSetup:0xb502eb70	RTP_UDP	Session 2, created with NAT flag set to 0
2009/05/05 20:48:49.974	  0:03.731	   CallSetup:0xb502eb70	PWLib	File handle high water mark set: 61 PUDPSocket
2009/05/05 20:48:50.000	  0:03.757	Opal Liste...0xb7a7db70	PWLib	File handle high water mark set: 62 PUDPSocket
2009/05/05 20:48:50.001	  0:03.758	Opal Liste...0xb7a7db70	OpalUDP	Binding to interface: 69.196.166.233:60426
2009/05/05 20:48:50.001	  0:03.758	Opal Liste...0xb7a7db70	SIP	Waiting for PDU on udp$198.65.166.131:5060<if=udp$69.196.166.233:60426>
2009/05/05 20:48:50.004	  0:03.761	Opal Liste...0xb7a7db70	SIP	PDU received: rem=udp$198.65.166.131:5060,local=udp$69.196.166.233:60426,if=192.168.1.12%wlan0
SUBSCRIBE sip:17476942064@69.196.166.233:60426 SIP/2.0
CSeq: 1 SUBSCRIBE
Via: SIP/2.0/UDP 198.65.166.131;branch=z9hG4bKfdd5.0a455ef5.0
Via: SIP/2.0/UDP 69.196.166.233:60426;branch=z9hG4bK02ad0755-4538-de11-9fe8-00904b4fcd2b;rport=60426
P-RTP-Proxy: YES (1)
User-Agent: Ekiga/3.2.0
From: "Jesse Kahtava" <sip:17476942064@proxy01.sipphone.com>;tag=0ae20655-4538-de11-9fe8-00904b4fcd2b
Call-ID: 80e00555-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
To: <sip:17476942064@proxy01.sipphone.com>
Contact: <sip:17476942064@69.196.166.233:60426;nat=yes>
Accept: application/simple-message-summary
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Expires: 3600
Event: message-summary
Content-Length: 0
Max-Forwards: 69
Record-Route: <sip:198.65.166.131;lr;ftag=0ae20655-4538-de11-9fe8-00904b4fcd2b>


2009/05/05 20:48:50.030	  0:03.787	Opal Liste...0xb7a7db70	SIP	Sending PDU (564 bytes) to: rem=udp$198.65.166.131:5060,local=udp$69.196.166.233:60426,if=192.168.1.12%wlan0
SIP/2.0 100 Trying
CSeq: 1 SUBSCRIBE
Via: SIP/2.0/UDP 198.65.166.131;branch=z9hG4bKfdd5.0a455ef5.0
Via: SIP/2.0/UDP 69.196.166.233:60426;branch=z9hG4bK02ad0755-4538-de11-9fe8-00904b4fcd2b;rport=60426
From: "Jesse Kahtava" <sip:17476942064@proxy01.sipphone.com>;tag=0ae20655-4538-de11-9fe8-00904b4fcd2b
Call-ID: 80e00555-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
To: <sip:17476942064@proxy01.sipphone.com>
Contact: <sip:17476942064@69.196.166.233:60426>
Content-Length: 0
Record-Route: <sip:198.65.166.131;lr;ftag=0ae20655-4538-de11-9fe8-00904b4fcd2b>


2009/05/05 20:48:50.039	  0:03.796	Opal Liste...0xb7a7db70	OpalUDP	Setting interface to 192.168.1.12%wlan0
2009/05/05 20:48:50.045	  0:03.802	Opal Liste...0xb7a7db70	SIP	Sending PDU (655 bytes) to: rem=udp$198.65.166.131:5060,local=udp$69.196.166.233:60426,if=192.168.1.12%wlan0
SIP/2.0 405 Method Not Allowed
CSeq: 1 SUBSCRIBE
Via: SIP/2.0/UDP 198.65.166.131;branch=z9hG4bKfdd5.0a455ef5.0
Via: SIP/2.0/UDP 69.196.166.233:60426;branch=z9hG4bK02ad0755-4538-de11-9fe8-00904b4fcd2b;rport=60426
From: "Jesse Kahtava" <sip:17476942064@proxy01.sipphone.com>;tag=0ae20655-4538-de11-9fe8-00904b4fcd2b
Call-ID: 80e00555-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
To: <sip:17476942064@proxy01.sipphone.com>
Contact: <sip:17476942064@69.196.166.233:60426>
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Content-Length: 0
Record-Route: <sip:198.65.166.131;lr;ftag=0ae20655-4538-de11-9fe8-00904b4fcd2b>


2009/05/05 20:48:50.046	  0:03.803	Opal Liste...0xb7a7db70	OpalUDP	Setting interface to 192.168.1.12%wlan0
2009/05/05 20:48:50.053	  0:03.810	Opal Liste...0xb7a7db70	Opal	Transport clean up on termination
2009/05/05 20:48:50.056	  0:03.813	Opal Liste...0xb7a7db70	Opal	Transport Close
2009/05/05 20:48:50.056	  0:03.813	Opal Liste...0xb7a7db70	Opal	Deleted transport udp$198.65.166.131:5060<if=udp$69.196.166.233:60426>
2009/05/05 20:48:50.225	  0:03.982	   CallSetup:0xb502eb70	RTP	Session 2, STUN created RTP/RTCP socket pair.
2009/05/05 20:48:50.225	  0:03.982	   CallSetup:0xb502eb70	RTP_UDP	Session 2 created: 69.196.166.233:60062-60063 ssrc=34879833
2009/05/05 20:48:50.226	  0:03.983	   CallSetup:0xb502eb70	PWLib	File handle low water mark set: 58 PUDPSocket
2009/05/05 20:48:50.226	  0:03.983	   CallSetup:0xb502eb70	RTP	Creating new session RTP_UDP
2009/05/05 20:48:50.226	  0:03.983	   CallSetup:0xb502eb70	RTP	Found existing media session 2
2009/05/05 20:48:50.229	  0:03.986	   CallSetup:0xb502eb70	SDP	SDP not including H.261-CIF as it is not a SIP transportable format
2009/05/05 20:48:50.229	  0:03.986	   CallSetup:0xb502eb70	SDP	SDP not including H.261-QCIF as it is not a SIP transportable format
2009/05/05 20:48:50.229	  0:03.986	   CallSetup:0xb502eb70	SIP	Creating INVITE request
2009/05/05 20:48:50.230	  0:03.987	   CallSetup:0xb502eb70	SIP	No SRV lookup as has explicit port number.
2009/05/05 20:48:50.230	  0:03.987	   CallSetup:0xb502eb70	SIP	Transaction remote address is udp$proxy01.sipphone.com:5060
2009/05/05 20:48:50.233	  0:03.990	   CallSetup:0xb502eb70	OpalPlugin	to_customised_options: theora
2009/05/05 20:48:50.236	  0:03.993	   CallSetup:0xb502eb70	OpalPlugin	to_customised_options: H.261
2009/05/05 20:48:50.241	  0:03.998	   CallSetup:0xb502eb70	OpalPlugin	to_customised_options changed option "Frame Width" from "320" to "176"
2009/05/05 20:48:50.242	  0:03.999	   CallSetup:0xb502eb70	OpalPlugin	to_customised_options changed option "Frame Height" from "240" to "144"
2009/05/05 20:48:50.243	  0:04.001	   CallSetup:0xb502eb70	SIP	Sending PDU (1251 bytes) to: rem=udp$198.65.166.131:5060,local=udp$69.196.166.233:60426,if=192.168.1.12%wlan0
INVITE sip:echo@proxy01.sipphone.com SIP/2.0
Route: <sip:proxy01.sipphone.com:5060;lr>
Date: Wed, 06 May 2009 00:48:49 GMT
CSeq: 1 INVITE
Via: SIP/2.0/UDP 69.196.166.233:60426;branch=z9hG4bK3a5fcd54-4538-de11-9fe8-00904b4fcd2b;rport
User-Agent: Ekiga/3.2.0
From: "Jesse Kahtava" <sip:17476942064@proxy01.sipphone.com>;tag=842cca54-4538-de11-9fe8-00904b4fcd2b
Call-ID: a49dca54-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
To: <sip:echo@proxy01.sipphone.com>
Contact: <sip:17476942064@69.196.166.233:60426>
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Content-Type: application/sdp
Content-Length: 586
Max-Forwards: 70

v=0
o=- 1241570929 1241570929 IN IP4 69.196.166.233
s=Opal SIP Session
c=IN IP4 69.196.166.233
t=0 0
m=audio 60016 RTP/AVP 113 0 8 9 101 120
a=sendrecv
a=rtpmap:113 Speex/16000/1
a=fmtp:113 sr=16000,mode=any
a=rtpmap:0 PCMU/8000/1
a=rtpmap:8 PCMA/8000/1
a=rtpmap:9 G722/8000/1
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16,32,36
a=rtpmap:120 NSE/8000
a=fmtp:120 192-193
m=video 60062 RTP/AVP 99 31
a=sendrecv
a=rtpmap:99 theora/90000
a=fmtp:99 delivery-method="in_band";height=576;sampling="YCbCr-4:2:0";width=704
a=rtpmap:31 h261/90000
a=fmtp:31 CIF=1;QCIF=1

2009/05/05 20:48:50.245	  0:04.002	   CallSetup:0xb502eb70	OpalUDP	Setting interface to 192.168.1.12%wlan0
2009/05/05 20:48:50.246	  0:04.003	   CallSetup:0xb502eb70	SIP	Transaction timers set: retry=0.500, completion=1:00.000
2009/05/05 20:48:50.246	  0:04.003	   CallSetup:0xb502eb70	OpalCon	OnSetUpConnectionCall[jb1729b981]-EP<sip>[842cca54-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:48:50.247	  0:04.004	   CallSetup:0xb502eb70	OpalEP	OnSetUpConnection Call[jb1729b981]-EP<sip>[842cca54-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:48:50.284	  0:04.041	 Housekeeper:0xb7abeb70	PWLib	File handle low water mark set: 42 PUDPSocket
2009/05/05 20:48:50.284	  0:04.041	 Housekeeper:0xb7abeb70	SIP	Sending PDU (662 bytes) to: rem=udp$198.65.166.131:5060,local=udp$69.196.166.233:60426,if=192.168.1.12%wlan0
SUBSCRIBE sip:echo@proxy01.sipphone.com SIP/2.0
Route: <sip:proxy01.sipphone.com:5060;lr>
CSeq: 1 SUBSCRIBE
Via: SIP/2.0/UDP 69.196.166.233:60426;branch=z9hG4bK56f60055-4538-de11-9fe8-00904b4fcd2b;rport
User-Agent: Ekiga/3.2.0
From: "Jesse Kahtava" <sip:17476942064@proxy01.sipphone.com>;tag=66460055-4538-de11-9fe8-00904b4fcd2b
Call-ID: e270ff54-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
To: <sip:echo@proxy01.sipphone.com>
Contact: <sip:17476942064@69.196.166.233:60426>
Accept: application/pidf+xml
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Expires: 300
Event: presence
Content-Length: 0
Max-Forwards: 70


2009/05/05 20:48:50.285	  0:04.042	 Housekeeper:0xb7abeb70	OpalUDP	Setting interface to 192.168.1.12%wlan0
2009/05/05 20:48:50.286	  0:04.043	 Housekeeper:0xb7abeb70	SIP	Transaction 1 SUBSCRIBE timeout, making retry 1, timeout 1.000
2009/05/05 20:48:50.308	  0:04.065	 Housekeeper:0xb7abeb70	SIP	Sending PDU (667 bytes) to: rem=udp$198.65.166.131:5060,local=udp$69.196.166.233:60426,if=192.168.1.12%wlan0
SUBSCRIBE sip:echo@proxy01.sipphone.com SIP/2.0
Route: <sip:proxy01.sipphone.com:5060;lr>
CSeq: 1 SUBSCRIBE
Via: SIP/2.0/UDP 69.196.166.233:60426;branch=z9hG4bK923e0455-4538-de11-9fe8-00904b4fcd2b;rport
User-Agent: Ekiga/3.2.0
From: "Jesse Kahtava" <sip:17476942064@proxy01.sipphone.com>;tag=786d0355-4538-de11-9fe8-00904b4fcd2b
Call-ID: aece0255-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
To: <sip:echo@proxy01.sipphone.com>
Contact: <sip:echo@69.196.166.233:60426>
Accept: application/dialog-info+xml
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Expires: 300
Event: dialog;sla;ma
Content-Length: 0
Max-Forwards: 70


2009/05/05 20:48:50.309	  0:04.066	 Housekeeper:0xb7abeb70	OpalUDP	Setting interface to 192.168.1.12%wlan0
2009/05/05 20:48:50.310	  0:04.067	 Housekeeper:0xb7abeb70	SIP	Transaction 1 SUBSCRIBE timeout, making retry 1, timeout 1.000
2009/05/05 20:48:50.334	  0:04.091	 Housekeeper:0xb7abeb70	SIP	Sending PDU (698 bytes) to: rem=udp$198.65.166.131:5060,local=udp$69.196.166.233:60426,if=192.168.1.12%wlan0
SUBSCRIBE sip:17476942064@proxy01.sipphone.com SIP/2.0
Route: <sip:proxy01.sipphone.com:5060;lr>
CSeq: 1 SUBSCRIBE
Via: SIP/2.0/UDP 69.196.166.233:60426;branch=z9hG4bK02ad0755-4538-de11-9fe8-00904b4fcd2b;rport
User-Agent: Ekiga/3.2.0
From: "Jesse Kahtava" <sip:17476942064@proxy01.sipphone.com>;tag=0ae20655-4538-de11-9fe8-00904b4fcd2b
Call-ID: 80e00555-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
To: <sip:17476942064@proxy01.sipphone.com>
Contact: <sip:17476942064@69.196.166.233:60426>
Accept: application/simple-message-summary
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Expires: 3600
Event: message-summary
Content-Length: 0
Max-Forwards: 70


2009/05/05 20:48:50.335	  0:04.092	 Housekeeper:0xb7abeb70	OpalUDP	Setting interface to 192.168.1.12%wlan0
2009/05/05 20:48:50.336	  0:04.093	 Housekeeper:0xb7abeb70	SIP	Transaction 1 SUBSCRIBE timeout, making retry 1, timeout 1.000
2009/05/05 20:48:50.347	  0:04.104	Opal Liste...0xb7a7db70	OpalUDP	Binding to interface: 69.196.166.233:60426
2009/05/05 20:48:50.348	  0:04.105	Opal Liste...0xb7a7db70	SIP	Waiting for PDU on udp$198.65.166.131:5060<if=udp$69.196.166.233:60426>
2009/05/05 20:48:50.350	  0:04.107	Opal Liste...0xb7a7db70	SIP	PDU received: rem=udp$198.65.166.131:5060,local=udp$69.196.166.233:60426,if=192.168.1.12%wlan0
SIP/2.0 404 Not found
CSeq: 1 SUBSCRIBE
Via: SIP/2.0/UDP 69.196.166.233:60426;branch=z9hG4bK923e0455-4538-de11-9fe8-00904b4fcd2b;rport=60426
User-Agent: SIPCallProxy1
From: "Jesse Kahtava" <sip:17476942064@proxy01.sipphone.com>;tag=786d0355-4538-de11-9fe8-00904b4fcd2b
RemoteIP: 130.94.88.70
Call-ID: aece0255-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
To: <sip:echo@proxy01.sipphone.com>;tag=as64db64d3
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0


2009/05/05 20:48:50.352	  0:04.109	Opal Liste...0xb7a7db70	SIP	Transaction 1 SUBSCRIBE completed.
2009/05/05 20:48:50.352	  0:04.109	Opal Liste...0xb7a7db70	SIP	Not retrying SUBSCRIBE due to error response 404 Not Found
2009/05/05 20:48:50.352	  0:04.110	Opal Liste...0xb7a7db70	SIP	Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:echo@proxy01.sipphone.com, id=aece0255-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
2009/05/05 20:48:50.353	  0:04.110	Opal Liste...0xb7a7db70	SIP	Set state Terminated_Aborted for transaction 1 SUBSCRIBE
2009/05/05 20:48:50.353	  0:04.110	Opal Liste...0xb7a7db70	SIP	Not retrying SUBSCRIBE due to error response 487 Request Terminated
2009/05/05 20:48:50.353	  0:04.110	Opal Liste...0xb7a7db70	SIP	Changing SUBSCRIBE handler from Unsubscribed to Unsubscribed, target=sip:echo@proxy01.sipphone.com, id=aece0255-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
2009/05/05 20:48:50.354	  0:04.111	Opal Liste...0xb7a7db70	Opal	Transport clean up on termination
2009/05/05 20:48:50.354	  0:04.111	Opal Liste...0xb7a7db70	Opal	Transport Close
2009/05/05 20:48:50.354	  0:04.111	Opal Liste...0xb7a7db70	Opal	Deleted transport udp$198.65.166.131:5060<if=udp$69.196.166.233:60426>
2009/05/05 20:48:50.376	  0:04.133	Opal Liste...0xb7a7db70	OpalUDP	Binding to interface: 69.196.166.233:60426
2009/05/05 20:48:50.377	  0:04.134	Opal Liste...0xb7a7db70	SIP	Waiting for PDU on udp$198.65.166.131:5060<if=udp$69.196.166.233:60426>
2009/05/05 20:48:50.379	  0:04.136	Opal Liste...0xb7a7db70	SIP	PDU received: rem=udp$198.65.166.131:5060,local=udp$69.196.166.233:60426,if=192.168.1.12%wlan0
SIP/2.0 404 Not found
CSeq: 1 SUBSCRIBE
Via: SIP/2.0/UDP 69.196.166.233:60426;branch=z9hG4bK56f60055-4538-de11-9fe8-00904b4fcd2b;rport=60426
User-Agent: SIPCallProxy1
From: "Jesse Kahtava" <sip:17476942064@proxy01.sipphone.com>;tag=66460055-4538-de11-9fe8-00904b4fcd2b
RemoteIP: 130.94.88.70
Call-ID: e270ff54-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
To: <sip:echo@proxy01.sipphone.com>;tag=as788675fe
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0


2009/05/05 20:48:50.381	  0:04.138	Opal Liste...0xb7a7db70	SIP	Transaction 1 SUBSCRIBE completed.
2009/05/05 20:48:50.381	  0:04.138	Opal Liste...0xb7a7db70	SIP	Not retrying SUBSCRIBE due to error response 404 Not Found
2009/05/05 20:48:50.382	  0:04.139	Opal Liste...0xb7a7db70	SIP	Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:echo@proxy01.sipphone.com, id=e270ff54-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
2009/05/05 20:48:50.382	  0:04.139	Opal Liste...0xb7a7db70	SIP	Set state Terminated_Aborted for transaction 1 SUBSCRIBE
2009/05/05 20:48:50.382	  0:04.139	Opal Liste...0xb7a7db70	SIP	Not retrying SUBSCRIBE due to error response 487 Request Terminated
2009/05/05 20:48:50.382	  0:04.139	Opal Liste...0xb7a7db70	SIP	Changing SUBSCRIBE handler from Unsubscribed to Unsubscribed, target=sip:echo@proxy01.sipphone.com, id=e270ff54-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
2009/05/05 20:48:50.383	  0:04.140	Opal Liste...0xb7a7db70	Opal	Transport clean up on termination
2009/05/05 20:48:50.383	  0:04.140	Opal Liste...0xb7a7db70	Opal	Transport Close
2009/05/05 20:48:50.383	  0:04.140	Opal Liste...0xb7a7db70	Opal	Deleted transport udp$198.65.166.131:5060<if=udp$69.196.166.233:60426>
2009/05/05 20:48:50.520	  0:04.277	 Housekeeper:0xb7abeb70	SIP	Set state Terminated_Success for transaction 1 REGISTER
2009/05/05 20:48:50.748	  0:04.505	 Housekeeper:0xb7abeb70	OpalPlugin	to_customised_options: theora
2009/05/05 20:48:50.753	  0:04.510	 Housekeeper:0xb7abeb70	OpalPlugin	to_customised_options: H.261
2009/05/05 20:48:50.757	  0:04.514	 Housekeeper:0xb7abeb70	SIP	Sending PDU (1251 bytes) to: rem=udp$198.65.166.131:5060,local=udp$69.196.166.233:60426,if=192.168.1.12%wlan0
INVITE sip:echo@proxy01.sipphone.com SIP/2.0
Route: <sip:proxy01.sipphone.com:5060;lr>
Date: Wed, 06 May 2009 00:48:49 GMT
CSeq: 1 INVITE
Via: SIP/2.0/UDP 69.196.166.233:60426;branch=z9hG4bK3a5fcd54-4538-de11-9fe8-00904b4fcd2b;rport
User-Agent: Ekiga/3.2.0
From: "Jesse Kahtava" <sip:17476942064@proxy01.sipphone.com>;tag=842cca54-4538-de11-9fe8-00904b4fcd2b
Call-ID: a49dca54-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
To: <sip:echo@proxy01.sipphone.com>
Contact: <sip:17476942064@69.196.166.233:60426>
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Content-Type: application/sdp
Content-Length: 586
Max-Forwards: 70

v=0
o=- 1241570929 1241570929 IN IP4 69.196.166.233
s=Opal SIP Session
c=IN IP4 69.196.166.233
t=0 0
m=audio 60016 RTP/AVP 113 0 8 9 101 120
a=sendrecv
a=rtpmap:113 Speex/16000/1
a=fmtp:113 sr=16000,mode=any
a=rtpmap:0 PCMU/8000/1
a=rtpmap:8 PCMA/8000/1
a=rtpmap:9 G722/8000/1
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16,32,36
a=rtpmap:120 NSE/8000
a=fmtp:120 192-193
m=video 60062 RTP/AVP 99 31
a=sendrecv
a=rtpmap:99 theora/90000
a=fmtp:99 delivery-method="in_band";height=576;sampling="YCbCr-4:2:0";width=704
a=rtpmap:31 h261/90000
a=fmtp:31 CIF=1;QCIF=1

2009/05/05 20:48:50.759	  0:04.516	 Housekeeper:0xb7abeb70	OpalUDP	Setting interface to 192.168.1.12%wlan0
2009/05/05 20:48:50.759	  0:04.516	 Housekeeper:0xb7abeb70	SIP	Transaction 1 INVITE timeout, making retry 1, timeout 1.000
2009/05/05 20:48:50.831	  0:04.588	Opal Garbage:0xb7c79b70	SIP	Transaction 1 SUBSCRIBE destroyed.
2009/05/05 20:48:50.832	  0:04.589	Opal Garbage:0xb7c79b70	SIP	Transaction 1 REGISTER destroyed.
2009/05/05 20:48:50.832	  0:04.589	Opal Garbage:0xb7c79b70	SIP	Transaction 1 SUBSCRIBE destroyed.
2009/05/05 20:48:50.833	  0:04.590	Opal Garbage:0xb7c79b70	SIP	Transaction 1 SUBSCRIBE destroyed.
2009/05/05 20:48:50.833	  0:04.590	Opal Garbage:0xb7c79b70	SIP	Transaction 1 SUBSCRIBE destroyed.
2009/05/05 20:48:50.833	  0:04.590	Opal Garbage:0xb7c79b70	SIP	Deleting SIPSubscribeHandler sip:record@proxy01.sipphone.com
2009/05/05 20:48:50.834	  0:04.591	Opal Garbage:0xb7c79b70	Opal	Transport clean up on termination
2009/05/05 20:48:50.834	  0:04.591	Opal Garbage:0xb7c79b70	Opal	Transport Close
2009/05/05 20:48:50.834	  0:04.591	Opal Garbage:0xb7c79b70	Opal	Transport clean up on termination
2009/05/05 20:48:50.834	  0:04.591	Opal Garbage:0xb7c79b70	Opal	Transport Close
2009/05/05 20:48:50.834	  0:04.591	Opal Garbage:0xb7c79b70	Opal	Deleted transport udp$198.65.166.131:5060<if=udp$69.196.166.233:60426>
2009/05/05 20:48:50.836	  0:04.593	Opal Garbage:0xb7c79b70	SIP	Deleted handler.
2009/05/05 20:48:50.836	  0:04.593	Opal Garbage:0xb7c79b70	SIP	Deleting SIPSubscribeHandler sip:record@proxy01.sipphone.com
2009/05/05 20:48:50.836	  0:04.593	Opal Garbage:0xb7c79b70	Opal	Transport clean up on termination
2009/05/05 20:48:50.836	  0:04.593	Opal Garbage:0xb7c79b70	Opal	Transport Close
2009/05/05 20:48:50.836	  0:04.593	Opal Garbage:0xb7c79b70	Opal	Transport clean up on termination
2009/05/05 20:48:50.836	  0:04.593	Opal Garbage:0xb7c79b70	Opal	Transport Close
2009/05/05 20:48:50.837	  0:04.594	Opal Garbage:0xb7c79b70	Opal	Deleted transport udp$198.65.166.131:5060<if=udp$69.196.166.233:60426>
2009/05/05 20:48:50.838	  0:04.595	Opal Garbage:0xb7c79b70	SIP	Deleted handler.
2009/05/05 20:48:50.838	  0:04.595	 Housekeeper:0xb7abeb70	SIP	Set state Terminated_Success for transaction 2 REGISTER
2009/05/05 20:48:50.838	  0:04.595	Opal Garbage:0xb7c79b70	SIP	Deleting SIPSubscribeHandler sip:echo@proxy01.sipphone.com
2009/05/05 20:48:50.838	  0:04.595	Opal Garbage:0xb7c79b70	Opal	Transport clean up on termination
2009/05/05 20:48:50.839	  0:04.596	Opal Garbage:0xb7c79b70	Opal	Transport Close
2009/05/05 20:48:50.839	  0:04.596	Opal Garbage:0xb7c79b70	Opal	Transport clean up on termination
2009/05/05 20:48:50.839	  0:04.596	Opal Garbage:0xb7c79b70	Opal	Transport Close
2009/05/05 20:48:50.839	  0:04.596	Opal Garbage:0xb7c79b70	Opal	Deleted transport udp$198.65.166.131:5060<if=udp$69.196.166.233:60426>
2009/05/05 20:48:50.840	  0:04.597	Opal Garbage:0xb7c79b70	SIP	Deleted handler.
2009/05/05 20:48:50.842	  0:04.599	Opal Garbage:0xb7c79b70	SIP	Deleting SIPSubscribeHandler sip:echo@proxy01.sipphone.com
2009/05/05 20:48:50.843	  0:04.600	Opal Garbage:0xb7c79b70	Opal	Transport clean up on termination
2009/05/05 20:48:50.843	  0:04.600	Opal Garbage:0xb7c79b70	Opal	Transport Close
2009/05/05 20:48:50.843	  0:04.600	Opal Garbage:0xb7c79b70	Opal	Transport clean up on termination
2009/05/05 20:48:50.843	  0:04.600	Opal Garbage:0xb7c79b70	Opal	Transport Close
2009/05/05 20:48:50.843	  0:04.601	Opal Garbage:0xb7c79b70	Opal	Deleted transport udp$198.65.166.131:5060<if=udp$69.196.166.233:60426>
2009/05/05 20:48:50.845	  0:04.602	Opal Garbage:0xb7c79b70	SIP	Deleted handler.
2009/05/05 20:48:50.876	  0:04.633	Opal Liste...0xb7a7db70	OpalUDP	Binding to interface: 69.196.166.233:60426
2009/05/05 20:48:50.876	  0:04.633	Opal Liste...0xb7a7db70	SIP	Waiting for PDU on udp$198.65.166.131:5060<if=udp$69.196.166.233:60426>
2009/05/05 20:48:50.878	  0:04.635	Opal Liste...0xb7a7db70	SIP	PDU received: rem=udp$198.65.166.131:5060,local=udp$69.196.166.233:60426,if=192.168.1.12%wlan0
SIP/2.0 404 Not found
CSeq: 1 SUBSCRIBE
Via: SIP/2.0/UDP 69.196.166.233:60426;branch=z9hG4bK56f60055-4538-de11-9fe8-00904b4fcd2b;rport=60426
User-Agent: SIPCallProxy1
From: "Jesse Kahtava" <sip:17476942064@proxy01.sipphone.com>;tag=66460055-4538-de11-9fe8-00904b4fcd2b
RemoteIP: 130.94.88.70
Call-ID: e270ff54-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
To: <sip:echo@proxy01.sipphone.com>;tag=as788675fe
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0


2009/05/05 20:48:50.880	  0:04.638	Opal Liste...0xb7a7db70	Opal	Transport clean up on termination
2009/05/05 20:48:50.881	  0:04.638	Opal Liste...0xb7a7db70	Opal	Transport Close
2009/05/05 20:48:50.881	  0:04.638	Opal Liste...0xb7a7db70	Opal	Deleted transport udp$198.65.166.131:5060<if=udp$69.196.166.233:60426>
2009/05/05 20:48:50.912	  0:04.669	Opal Liste...0xb7a7db70	OpalUDP	Binding to interface: 69.196.166.233:60426
2009/05/05 20:48:50.913	  0:04.670	Opal Liste...0xb7a7db70	SIP	Waiting for PDU on udp$198.65.166.131:5060<if=udp$69.196.166.233:60426>
2009/05/05 20:48:50.915	  0:04.672	Opal Liste...0xb7a7db70	SIP	PDU received: rem=udp$198.65.166.131:5060,local=udp$69.196.166.233:60426,if=192.168.1.12%wlan0
SIP/2.0 100 Giving a try
CSeq: 1 INVITE
Via: SIP/2.0/UDP 69.196.166.233:60426;branch=z9hG4bK3a5fcd54-4538-de11-9fe8-00904b4fcd2b;rport=60426
From: "Jesse Kahtava" <sip:17476942064@proxy01.sipphone.com>;tag=842cca54-4538-de11-9fe8-00904b4fcd2b
Call-ID: a49dca54-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
To: <sip:echo@proxy01.sipphone.com>
Content-Length: 0


2009/05/05 20:48:50.919	  0:04.676	  Aggregator:0xb4fedb70	SIP	Transaction 1 INVITE proceeding.
2009/05/05 20:48:50.919	  0:04.676	  Aggregator:0xb4fedb70	SIP	Received Trying response
2009/05/05 20:48:50.920	  0:04.677	  Aggregator:0xb4fedb70	OpalCon	SetPhase from SetUpPhase to ProceedingPhase for Call[jb1729b981]-EP<sip>[842cca54-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:48:50.920	  0:04.677	  Aggregator:0xb4fedb70	OpalMan	OnProceeding Call[jb1729b981]-EP<sip>[842cca54-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:48:50.920	  0:04.677	  Aggregator:0xb4fedb70	Call	OnProceeding Call[jb1729b981]-EP<sip>[842cca54-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:48:50.921	  0:04.678	Opal Liste...0xb7a7db70	Opal	Transport clean up on termination
2009/05/05 20:48:50.921	  0:04.678	Opal Liste...0xb7a7db70	Opal	Transport Close
2009/05/05 20:48:50.921	  0:04.678	Opal Liste...0xb7a7db70	Opal	Deleted transport udp$198.65.166.131:5060<if=udp$69.196.166.233:60426>
2009/05/05 20:48:50.924	  0:04.681	Opal Liste...0xb7a7db70	OpalUDP	Binding to interface: 69.196.166.233:60426
2009/05/05 20:48:50.925	  0:04.682	Opal Liste...0xb7a7db70	SIP	Waiting for PDU on udp$198.65.166.131:5060<if=udp$69.196.166.233:60426>
2009/05/05 20:48:50.928	  0:04.685	Opal Liste...0xb7a7db70	SIP	PDU received: rem=udp$198.65.166.131:5060,local=udp$69.196.166.233:60426,if=192.168.1.12%wlan0
SIP/2.0 200 OK
CSeq: 1 INVITE
Via: SIP/2.0/UDP 69.196.166.233:60426;branch=z9hG4bK3a5fcd54-4538-de11-9fe8-00904b4fcd2b;rport=60426
User-Agent: SIPCallProxy1
From: "Jesse Kahtava" <sip:17476942064@proxy01.sipphone.com>;tag=842cca54-4538-de11-9fe8-00904b4fcd2b
RemoteIP: 130.94.88.70
Call-ID: a49dca54-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
To: <sip:echo@proxy01.sipphone.com>;tag=as1d5b85c5
Contact: <sip:echo@130.94.88.70:5060>
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Type: application/sdp
Content-Length: 240
Record-Route: <sip:198.65.166.131;lr;ftag=842cca54-4538-de11-9fe8-00904b4fcd2b>

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

2009/05/05 20:48:50.930	  0:04.687	  Aggregator:0xb4fedb70	SIP	Set Request URI to sip:echo@130.94.88.70:5060
2009/05/05 20:48:50.931	  0:04.688	  Aggregator:0xb4fedb70	SIP	Updating dialog tag from "" to "as1d5b85c5"
2009/05/05 20:48:50.932	  0:04.689	  Aggregator:0xb4fedb70	OpalUDP	Setting interface to 192.168.1.12%wlan0
2009/05/05 20:48:50.932	  0:04.690	  Aggregator:0xb4fedb70	SDP	Media session port=16538
2009/05/05 20:48:50.933	  0:04.690	  Aggregator:0xb4fedb70	SDP	Unknown media attribute silenceSupp
2009/05/05 20:48:50.934	  0:04.691	  Aggregator:0xb4fedb70	SDP	Parsed media session with 3 'audio' formats
2009/05/05 20:48:50.935	  0:04.692	  Aggregator:0xb4fedb70	SIP	Transaction remote address changed to udp$proxy01.sipphone.com:5060
2009/05/05 20:48:50.945	  0:04.702	  Aggregator:0xb4fedb70	SIP	Sending PDU (497 bytes) to: rem=udp$198.65.166.131:5060,local=udp$69.196.166.233:60426,if=192.168.1.12%wlan0
ACK sip:echo@130.94.88.70:5060 SIP/2.0
Route: <sip:proxy01.sipphone.com:5060;lr>
CSeq: 1 ACK
Via: SIP/2.0/UDP 69.196.166.233:60426;branch=z9hG4bK8ec9b355-4538-de11-9fe8-00904b4fcd2b;rport
From: "Jesse Kahtava" <sip:17476942064@proxy01.sipphone.com>;tag=842cca54-4538-de11-9fe8-00904b4fcd2b
Call-ID: a49dca54-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
To: <sip:echo@proxy01.sipphone.com>;tag=as1d5b85c5
Contact: <sip:17476942064@69.196.166.233:60426>
Content-Length: 0
Max-Forwards: 70


2009/05/05 20:48:50.948	  0:04.705	  Aggregator:0xb4fedb70	OpalUDP	Setting interface to 192.168.1.12%wlan0
2009/05/05 20:48:50.948	  0:04.705	  Aggregator:0xb4fedb70	SIP	Transaction 1 INVITE completed.
2009/05/05 20:48:50.948	  0:04.705	  Aggregator:0xb4fedb70	SIP	Handling 200 OK response for INVITE
2009/05/05 20:48:50.950	  0:04.707	  Aggregator:0xb4fedb70	SIP	Received INVITE OK response
2009/05/05 20:48:50.951	  0:04.708	  Aggregator:0xb4fedb70	SIP	Processing received SDP media description for audio
2009/05/05 20:48:50.951	  0:04.708	  Aggregator:0xb4fedb70	SIP	RTP payload type PCMU matched to codec G.711-uLaw-64k
2009/05/05 20:48:50.951	  0:04.708	  Aggregator:0xb4fedb70	SIP	RTP payload type PCMA matched to codec G.711-ALaw-64k
2009/05/05 20:48:50.952	  0:04.709	  Aggregator:0xb4fedb70	MediaFormat	Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz
2009/05/05 20:48:50.953	  0:04.710	  Aggregator:0xb4fedb70	Call	GetOtherPartyConnection Call[jb1729b981]-EP<sip>[842cca54-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:48:50.953	  0:04.710	  Aggregator:0xb4fedb70	Call	IsMediaBypassPossible Call[jb1729b981]-EP<sip>[842cca54-4538-de11-9fe8-00904b4fcd2b] session 1
2009/05/05 20:48:50.953	  0:04.710	  Aggregator:0xb4fedb70	OpalMan	IsMediaBypassPossible: session 1
2009/05/05 20:48:50.953	  0:04.710	  Aggregator:0xb4fedb70	OpalCon	IsMediaBypassPossible: default returns false
2009/05/05 20:48:50.953	  0:04.710	  Aggregator:0xb4fedb70	RTP	Found existing RTP session 1
2009/05/05 20:48:50.955	  0:04.712	  Aggregator:0xb4fedb70	RTP_UDP	Session 1, SetRemoteSocketInfo: data channel, new=130.94.88.70:16538, local=69.196.166.233:60016-60017, remote=0.0.0.0:0-0
2009/05/05 20:48:50.956	  0:04.713	  Aggregator:0xb4fedb70	Call	OpenSourceMediaStreams opening audio session 1 on Call[jb1729b981]-EP<sip>[842cca54-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:48:50.957	  0:04.714	Opal Liste...0xb7a7db70	Opal	Transport clean up on termination
2009/05/05 20:48:50.958	  0:04.715	Opal Liste...0xb7a7db70	Opal	Transport Close
2009/05/05 20:48:50.958	  0:04.715	Opal Liste...0xb7a7db70	Opal	Deleted transport udp$198.65.166.131:5060<if=udp$69.196.166.233:60426>
2009/05/05 20:48:50.979	  0:04.736	  Aggregator:0xb4fedb70	MediaFormat	Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz
2009/05/05 20:48:50.983	  0:04.740	  Aggregator:0xb4fedb70	MediaFormat	Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz
2009/05/05 20:48:50.985	  0:04.742	  Aggregator:0xb4fedb70	Call	GetMediaFormats for Call[jb1729b981]-EP<sip>[842cca54-4538-de11-9fe8-00904b4fcd2b]
theora
H.261
H.261-CIF
H.261-QCIF
SpeexIETFWide-20.6k
SpeexWB
SpeexWide-20.6k
G.711-uLaw-64k
G.711-ALaw-64k
G.722-64k

2009/05/05 20:48:50.986	  0:04.743	  Aggregator:0xb4fedb70	MediaFormat	Merging G.711-uLaw-64k into G.711-uLaw-64k
2009/05/05 20:48:50.986	  0:04.743	  Aggregator:0xb4fedb70	MediaFormat	Merging PCM-16 into G.711-uLaw-64k
2009/05/05 20:48:50.987	  0:04.744	  Aggregator:0xb4fedb70	MediaFormat	Merging G.711-uLaw-64k into PCM-16
2009/05/05 20:48:50.987	  0:04.744	  Aggregator:0xb4fedb70	MediaFormat	Changed media option "Bandwidth-TIAS" from 128000 to 64000
2009/05/05 20:48:50.987	  0:04.744	  Aggregator:0xb4fedb70	MediaFormat	Changed media option "Max Bit Rate" from 128000 to 64000
2009/05/05 20:48:50.987	  0:04.744	  Aggregator:0xb4fedb70	Call	Selected media formats G.711-uLaw-64k -> PCM-16
2009/05/05 20:48:50.988	  0:04.745	  Aggregator:0xb4fedb70	Call	IsMediaBypassPossible Call[jb1729b981]-EP<sip>[842cca54-4538-de11-9fe8-00904b4fcd2b] session 1
2009/05/05 20:48:50.988	  0:04.745	  Aggregator:0xb4fedb70	OpalMan	IsMediaBypassPossible: session 1
2009/05/05 20:48:50.988	  0:04.745	  Aggregator:0xb4fedb70	OpalCon	IsMediaBypassPossible: default returns false
2009/05/05 20:48:50.988	  0:04.745	  Aggregator:0xb4fedb70	RTP	Found existing media session 1
2009/05/05 20:48:50.990	  0:04.747	  Aggregator:0xb4fedb70	OpalMan	OnOpenMediaStream Call[jb1729b981]-EP<sip>[842cca54-4538-de11-9fe8-00904b4fcd2b],OpalRTPMediaStream-Source-G.711-uLaw-64k
2009/05/05 20:48:50.991	  0:04.748	  Aggregator:0xb4fedb70	OpalCon	Opened source stream jb1729b981_1 with format G.711-uLaw-64k
2009/05/05 20:48:50.992	  0:04.749	  Aggregator:0xb4fedb70	AudioOutputCore	Opening device[0] with 1-8000/16
2009/05/05 20:48:50.992	  0:04.749	  Aggregator:0xb4fedb70	GMAudioOutputManager_ptlib	Opening Device Default (PTLIB/ALSA)
2009/05/05 20:48:50.992	  0:04.749	  Aggregator:0xb4fedb70	GMAudioOutputManager_ptlib	Opening Device with 1-8000/16
2009/05/05 20:48:50.998	  0:04.755	Opal Liste...0xb7a7db70	OpalUDP	Binding to interface: 69.196.166.233:60426
2009/05/05 20:48:50.999	  0:04.756	Opal Liste...0xb7a7db70	SIP	Waiting for PDU on udp$198.65.166.131:5060<if=udp$69.196.166.233:60426>
2009/05/05 20:48:51.000	  0:04.757	Opal Liste...0xb7a7db70	SIP	PDU received: rem=udp$198.65.166.131:5060,local=udp$69.196.166.233:60426,if=192.168.1.12%wlan0
SIP/2.0 404 Not found
CSeq: 1 SUBSCRIBE
Via: SIP/2.0/UDP 69.196.166.233:60426;branch=z9hG4bK923e0455-4538-de11-9fe8-00904b4fcd2b;rport=60426
User-Agent: SIPCallProxy1
From: "Jesse Kahtava" <sip:17476942064@proxy01.sipphone.com>;tag=786d0355-4538-de11-9fe8-00904b4fcd2b
RemoteIP: 130.94.88.70
Call-ID: aece0255-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
To: <sip:echo@proxy01.sipphone.com>;tag=as64db64d3
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0


2009/05/05 20:48:51.004	  0:04.761	Opal Liste...0xb7a7db70	Opal	Transport clean up on termination
2009/05/05 20:48:51.004	  0:04.761	Opal Liste...0xb7a7db70	Opal	Transport Close
2009/05/05 20:48:51.004	  0:04.761	Opal Liste...0xb7a7db70	Opal	Deleted transport udp$198.65.166.131:5060<if=udp$69.196.166.233:60426>
2009/05/05 20:48:51.042	  0:04.799	  Aggregator:0xb4fedb70	Alsa	Buffer time is 16000
2009/05/05 20:48:51.043	  0:04.800	  Aggregator:0xb4fedb70	Alsa	Period time is 4000
2009/05/05 20:48:51.089	  0:04.846	  Aggregator:0xb4fedb70	ALSA	Device default Opened
2009/05/05 20:48:51.179	  0:04.936	  Aggregator:0xb4fedb70	Got volume 57080
2009/05/05 20:48:51.181	  0:04.938	  Aggregator:0xb4fedb70	PCSS	Opened mono sound channel "EKIGA" for playing at 8.0 kHz.
2009/05/05 20:48:51.181	  0:04.938	  Aggregator:0xb4fedb70	OpalMan	OnOpenMediaStream Call[jb1729b981]-EP<pc>[e963b32e12],OpalAudioMediaStream-Sink-PCM-16
2009/05/05 20:48:51.182	  0:04.939	  Aggregator:0xb4fedb70	OpalCon	Opened sink stream jb1729b981_1 with format PCM-16
2009/05/05 20:48:51.182	  0:04.939	  Aggregator:0xb4fedb70	Patch	Created Sink: format=PCM-16
2009/05/05 20:48:51.183	  0:04.940	  Aggregator:0xb4fedb70	Codec	G711-uLaw-64k decoder created
2009/05/05 20:48:51.183	  0:04.940	  Aggregator:0xb4fedb70	Patch	Created primary codec G.711-uLaw-64k->PCM-16 with ID jb1729b981_1
2009/05/05 20:48:51.183	  0:04.940	  Aggregator:0xb4fedb70	Media	Audio sink data size set to 3840 bytes and 5 buffers.
2009/05/05 20:48:51.184	  0:04.941	  Aggregator:0xb4fedb70	Media	Set data size from 16 to 3840
2009/05/05 20:48:51.184	  0:04.941	  Aggregator:0xb4fedb70	GMAudioOutputManager_ptlib	Setting buffer size of device[0] 3840/5
2009/05/05 20:48:51.184	  0:04.941	  Aggregator:0xb4fedb70	Patch	Added media stream sink OpalAudioMediaStream-Sink-PCM-16 using transcoder G.711-uLaw-64k->PCM-16, data size=3840
2009/05/05 20:48:51.184	  0:04.941	  Aggregator:0xb4fedb70	Media	RTP data size cannot be changed to 8, fixed at 2048
2009/05/05 20:48:51.184	  0:04.941	  Aggregator:0xb4fedb70	PCSS	Adding filters to patch
2009/05/05 20:48:51.184	  0:04.942	  Aggregator:0xb4fedb70	OpalCon	Source stream of connection Call[jb1729b981]-EP<sip>[842cca54-4538-de11-9fe8-00904b4fcd2b] uses patch Patch OpalRTPMediaStream-Source-G.711-uLaw-64k -> OpalAudioMediaStream-Sink-PCM-16
2009/05/05 20:48:51.185	  0:04.942	  Aggregator:0xb4fedb70	RTPCon	Adding RFC2833 receive handler
2009/05/05 20:48:51.185	  0:04.942	  Aggregator:0xb4fedb70	RTPCon	Adding Cisco NSE receive handler
2009/05/05 20:48:51.185	  0:04.942	  Aggregator:0xb4fedb70	Call	GetOtherPartyConnection Call[jb1729b981]-EP<sip>[842cca54-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:48:51.185	  0:04.942	  Aggregator:0xb4fedb70	Call	OpenSourceMediaStreams opening audio session 1 on Call[jb1729b981]-EP<pc>[e963b32e12]
2009/05/05 20:48:51.196	  0:04.953	  Aggregator:0xb4fedb70	MediaFormat	Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz
2009/05/05 20:48:51.199	  0:04.956	  Aggregator:0xb4fedb70	MediaFormat	Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz
2009/05/05 20:48:51.200	  0:04.957	  Aggregator:0xb4fedb70	Call	GetMediaFormats for Call[jb1729b981]-EP<pc>[e963b32e12]
G.711-uLaw-64k
G.711-ALaw-64k
T.38
UserInput/RFC2833

2009/05/05 20:48:51.201	  0:04.958	  Aggregator:0xb4fedb70	MediaFormat	Merging G.711-uLaw-64k into G.711-uLaw-64k
2009/05/05 20:48:51.201	  0:04.958	  Aggregator:0xb4fedb70	MediaFormat	Merging G.711-uLaw-64k into PCM-16
2009/05/05 20:48:51.201	  0:04.958	  Aggregator:0xb4fedb70	MediaFormat	Changed media option "Bandwidth-TIAS" from 128000 to 64000
2009/05/05 20:48:51.201	  0:04.958	  Aggregator:0xb4fedb70	MediaFormat	Changed media option "Max Bit Rate" from 128000 to 64000
2009/05/05 20:48:51.201	  0:04.958	  Aggregator:0xb4fedb70	MediaFormat	Merging PCM-16 into G.711-uLaw-64k
2009/05/05 20:48:51.202	  0:04.959	  Aggregator:0xb4fedb70	Call	Selected media formats PCM-16 -> G.711-uLaw-64k
2009/05/05 20:48:51.202	  0:04.959	  Aggregator:0xb4fedb70	AudioInputCore	Starting stream 1x8000/16
2009/05/05 20:48:51.202	  0:04.959	  Aggregator:0xb4fedb70	AudioInputCore	Opening device with 1-8000/16
2009/05/05 20:48:51.202	  0:04.959	  Aggregator:0xb4fedb70	GMAudioInputManager_ptlib	Opening Device Default (PTLIB/ALSA)
2009/05/05 20:48:51.203	  0:04.960	  Aggregator:0xb4fedb70	GMAudioInputManager_ptlib	Opening Device with 1-8000/16
2009/05/05 20:48:51.215	  0:04.972	  Aggregator:0xb4fedb70	Alsa	Buffer time is 16000
2009/05/05 20:48:51.215	  0:04.972	  Aggregator:0xb4fedb70	Alsa	Period time is 4000
2009/05/05 20:48:51.253	  0:05.010	  Aggregator:0xb4fedb70	ALSA	Device default Opened
2009/05/05 20:48:51.278	  0:05.035	Opal Liste...0xb7a7db70	PWLib	File handle high water mark set: 75 PUDPSocket
2009/05/05 20:48:51.279	  0:05.036	Opal Liste...0xb7a7db70	OpalUDP	Binding to interface: 69.196.166.233:60426
2009/05/05 20:48:51.279	  0:05.036	Opal Liste...0xb7a7db70	SIP	Waiting for PDU on udp$198.65.166.131:5060<if=udp$69.196.166.233:60426>
2009/05/05 20:48:51.283	  0:05.040	Opal Liste...0xb7a7db70	SIP	PDU received: rem=udp$198.65.166.131:5060,local=udp$69.196.166.233:60426,if=192.168.1.12%wlan0
SIP/2.0 200 OK
CSeq: 1 INVITE
Via: SIP/2.0/UDP 69.196.166.233:60426;branch=z9hG4bK3a5fcd54-4538-de11-9fe8-00904b4fcd2b;rport=60426
User-Agent: SIPCallProxy1
From: "Jesse Kahtava" <sip:17476942064@proxy01.sipphone.com>;tag=842cca54-4538-de11-9fe8-00904b4fcd2b
RemoteIP: 130.94.88.70
Call-ID: a49dca54-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
To: <sip:echo@proxy01.sipphone.com>;tag=as1d5b85c5
Contact: <sip:echo@130.94.88.70:5060>
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Type: application/sdp
Content-Length: 240
Record-Route: <sip:198.65.166.131;lr;ftag=842cca54-4538-de11-9fe8-00904b4fcd2b>

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

2009/05/05 20:48:51.285	  0:05.042	Opal Liste...0xb7a7db70	Opal	Transport clean up on termination
2009/05/05 20:48:51.285	  0:05.042	Opal Liste...0xb7a7db70	Opal	Transport Close
2009/05/05 20:48:51.285	  0:05.042	Opal Liste...0xb7a7db70	Opal	Deleted transport udp$198.65.166.131:5060<if=udp$69.196.166.233:60426>
2009/05/05 20:48:51.326	  0:05.083	  Aggregator:0xb4fedb70	Got volume 58327
2009/05/05 20:48:51.327	  0:05.085	  Aggregator:0xb4fedb70	PCSS	Opened mono sound channel "EKIGA" for recording at 8.0 kHz.
2009/05/05 20:48:51.328	  0:05.085	  Aggregator:0xb4fedb70	OpalMan	OnOpenMediaStream Call[jb1729b981]-EP<pc>[e963b32e12],OpalAudioMediaStream-Source-PCM-16
2009/05/05 20:48:51.328	  0:05.085	  Aggregator:0xb4fedb70	OpalCon	Opened source stream jb1729b981_1 with format PCM-16
2009/05/05 20:48:51.329	  0:05.086	  Aggregator:0xb4fedb70	Call	IsMediaBypassPossible Call[jb1729b981]-EP<sip>[842cca54-4538-de11-9fe8-00904b4fcd2b] session 1
2009/05/05 20:48:51.329	  0:05.086	  Aggregator:0xb4fedb70	OpalMan	IsMediaBypassPossible: session 1
2009/05/05 20:48:51.329	  0:05.087	  Aggregator:0xb4fedb70	OpalCon	IsMediaBypassPossible: default returns false
2009/05/05 20:48:51.330	  0:05.087	  Aggregator:0xb4fedb70	RTP	Found existing media session 1
2009/05/05 20:48:51.331	  0:05.088	  Aggregator:0xb4fedb70	OpalMan	OnOpenMediaStream Call[jb1729b981]-EP<sip>[842cca54-4538-de11-9fe8-00904b4fcd2b],OpalRTPMediaStream-Sink-G.711-uLaw-64k
2009/05/05 20:48:51.332	  0:05.089	  Aggregator:0xb4fedb70	OpalCon	Opened sink stream jb1729b981_1 with format G.711-uLaw-64k
2009/05/05 20:48:51.332	  0:05.089	  Aggregator:0xb4fedb70	Patch	Created Sink: format=G.711-uLaw-64k
2009/05/05 20:48:51.332	  0:05.089	  Aggregator:0xb4fedb70	Codec	G711-uLaw-64k encoder created
2009/05/05 20:48:51.335	  0:05.092	 Housekeeper:0xb7abeb70	PWLib	File handle low water mark set: 70 PUDPSocket
2009/05/05 20:48:51.334	  0:05.091	 Housekeeper:0xb7abeb70	SIP	Sending PDU (698 bytes) to: rem=udp$198.65.166.131:5060,local=udp$69.196.166.233:60426,if=192.168.1.12%wlan0
SUBSCRIBE sip:17476942064@proxy01.sipphone.com SIP/2.0
Route: <sip:proxy01.sipphone.com:5060;lr>
CSeq: 1 SUBSCRIBE
Via: SIP/2.0/UDP 69.196.166.233:60426;branch=z9hG4bK02ad0755-4538-de11-9fe8-00904b4fcd2b;rport
User-Agent: Ekiga/3.2.0
From: "Jesse Kahtava" <sip:17476942064@proxy01.sipphone.com>;tag=0ae20655-4538-de11-9fe8-00904b4fcd2b
Call-ID: 80e00555-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
To: <sip:17476942064@proxy01.sipphone.com>
Contact: <sip:17476942064@69.196.166.233:60426>
Accept: application/simple-message-summary
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Expires: 3600
Event: message-summary
Content-Length: 0
Max-Forwards: 70


2009/05/05 20:48:51.335	  0:05.092	 Housekeeper:0xb7abeb70	OpalUDP	Setting interface to 192.168.1.12%wlan0
2009/05/05 20:48:51.336	  0:05.093	 Housekeeper:0xb7abeb70	SIP	Transaction 1 SUBSCRIBE timeout, making retry 2, timeout 2.000
2009/05/05 20:48:51.332	  0:05.091	  Aggregator:0xb4fedb70	Patch	Created primary codec PCM-16->G.711-uLaw-64k with ID jb1729b981_1
2009/05/05 20:48:51.339	  0:05.096	  Aggregator:0xb4fedb70	Media	RTP data size cannot be changed to 1920, fixed at 528
2009/05/05 20:48:51.339	  0:05.096	  Aggregator:0xb4fedb70	Patch	Added media stream sink OpalRTPMediaStream-Sink-G.711-uLaw-64k using transcoder PCM-16->G.711-uLaw-64k, data size=528
2009/05/05 20:48:51.339	  0:05.096	  Aggregator:0xb4fedb70	Media	Audio source data size set to 320 bytes and 5 buffers.
2009/05/05 20:48:51.339	  0:05.096	  Aggregator:0xb4fedb70	Media	Set data size from 16 to 320
2009/05/05 20:48:51.339	  0:05.096	  Aggregator:0xb4fedb70	AudioInputCore	Setting stream buffer size 5/320
2009/05/05 20:48:51.339	  0:05.096	  Aggregator:0xb4fedb70	GMAudioInputManager_ptlib	Setting buffer size to 320/5
2009/05/05 20:48:51.340	  0:05.097	  Aggregator:0xb4fedb70	PCSS	Adding filters to patch
2009/05/05 20:48:51.344	  0:05.101	  Aggregator:0xb4fedb70	OpalCon	Sink stream of connection Call[jb1729b981]-EP<sip>[842cca54-4538-de11-9fe8-00904b4fcd2b] uses patch Patch OpalAudioMediaStream-Source-PCM-16 -> OpalRTPMediaStream-Sink-G.711-uLaw-64k
2009/05/05 20:48:51.345	  0:05.102	  Aggregator:0xb4fedb70	OpalCon	SetPhase from ProceedingPhase to ConnectedPhase for Call[jb1729b981]-EP<sip>[842cca54-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:48:51.345	  0:05.102	  Aggregator:0xb4fedb70	OpalCon	OnConnected for Call[jb1729b981]-EP<sip>[842cca54-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:48:51.345	  0:05.102	  Aggregator:0xb4fedb70	OpalMan	OnConnected Call[jb1729b981]-EP<sip>[842cca54-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:48:51.345	  0:05.102	  Aggregator:0xb4fedb70	Call	OnConnected Call[jb1729b981]-EP<sip>[842cca54-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:48:51.345	  0:05.103	  Aggregator:0xb4fedb70	OpalCon	SetConnected for Call[jb1729b981]-EP<pc>[e963b32e12]
2009/05/05 20:48:51.346	  0:05.103	  Aggregator:0xb4fedb70	OpalCon	SetPhase from SetUpPhase to ConnectedPhase for Call[jb1729b981]-EP<pc>[e963b32e12]
2009/05/05 20:48:51.346	  0:05.103	  Aggregator:0xb4fedb70	OpalCon	SetPhase from ConnectedPhase to EstablishedPhase for Call[jb1729b981]-EP<pc>[e963b32e12]
2009/05/05 20:48:51.346	  0:05.103	  Aggregator:0xb4fedb70	OpalCon	OnEstablished Call[jb1729b981]-EP<pc>[e963b32e12]
2009/05/05 20:48:51.346	  0:05.103	  Aggregator:0xb4fedb70	PWLib	Thread high water mark set: 11
2009/05/05 20:48:51.346	  0:05.103	 Media Patch:0xa85a8b70	Patch	Thread started for Patch OpalRTPMediaStream-Source-G.711-uLaw-64k -> OpalAudioMediaStream-Sink-PCM-16
2009/05/05 20:48:51.347	  0:05.104	 Media Patch:0xa85a8b70	InfLID	Setting jitter buffer time from 160 to 4000
2009/05/05 20:48:51.349	  0:05.107	 Media Patch:0xa85a8b70	RTP	Opal jitter buffer created: size=101 delay=20-500 ms  /160 (20ms) obj=0xb7b00698
2009/05/05 20:48:51.351	  0:05.108	 Media Patch:0xa85a8b70	PWLib	Thread high water mark set: 12
2009/05/05 20:48:51.351	  0:05.108	  RTP Jitter:0xa8567b70	RTP	Jitter RTP receive thread started: 0xb7b00698
2009/05/05 20:48:51.352	  0:05.109	  Aggregator:0xb4fedb70	Media	Starting thread Media Patch:0xa85a8b70
2009/05/05 20:48:51.352	  0:05.109	  Aggregator:0xb4fedb70	PWLib	Thread high water mark set: 13
2009/05/05 20:48:51.352	  0:05.110	 Media Patch:0xa8526b70	Patch	Thread started for Patch OpalAudioMediaStream-Source-PCM-16 -> OpalRTPMediaStream-Sink-G.711-uLaw-64k
2009/05/05 20:48:51.353	  0:05.110	  Aggregator:0xb4fedb70	Media	Starting thread Media Patch:0xa8526b70
2009/05/05 20:48:51.353	  0:05.110	  Aggregator:0xb4fedb70	OpalCon	Media stream threads started.
2009/05/05 20:48:51.353	  0:05.110	  Aggregator:0xb4fedb70	OpalMan	OnEstablished Call[jb1729b981]-EP<pc>[e963b32e12]
2009/05/05 20:48:51.353	  0:05.110	  Aggregator:0xb4fedb70	Call	OnEstablished Call[jb1729b981]-EP<pc>[e963b32e12]
2009/05/05 20:48:51.353	  0:05.110	  Aggregator:0xb4fedb70	OpalCon	Media stream threads started.
2009/05/05 20:48:51.354	  0:05.111	  Aggregator:0xb4fedb70	OpalCon	SetPhase from ConnectedPhase to EstablishedPhase for Call[jb1729b981]-EP<sip>[842cca54-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:48:51.354	  0:05.111	  Aggregator:0xb4fedb70	OpalCon	OnEstablished Call[jb1729b981]-EP<sip>[842cca54-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:48:51.355	  0:05.112	  Aggregator:0xb4fedb70	OpalCon	Media stream threads started.
2009/05/05 20:48:51.355	  0:05.112	  Aggregator:0xb4fedb70	OpalMan	OnEstablished Call[jb1729b981]-EP<sip>[842cca54-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:48:51.356	  0:05.113	  Aggregator:0xb4fedb70	RTP	Found existing RTP session 1
2009/05/05 20:48:51.356	  0:05.113	  Aggregator:0xb4fedb70	Call	OnEstablished Call[jb1729b981]-EP<sip>[842cca54-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:48:51.357	  0:05.114	 Media Patch:0xa85a8b70	Alsa	Buffer time is 600000
2009/05/05 20:48:51.357	  0:05.114	 Media Patch:0xa85a8b70	Alsa	Period time is 120000
2009/05/05 20:48:51.358	  0:05.115	 Media Patch:0xa8526b70	Alsa	Buffer time is 50000
2009/05/05 20:48:51.358	  0:05.115	 Media Patch:0xa8526b70	Alsa	Period time is 10000
2009/05/05 20:48:51.440	  0:05.197	 Media Patch:0xa8526b70	RTP	Session 1, first sent data: ver=2 pt=PCMU psz=160 m=1 x=0 seq=44143 ts=0 src=888dda2c ccnt=0
2009/05/05 20:48:51.468	  0:05.225	  RTP Jitter:0xa8567b70	RTP	Session 1, first receive data: ver=2 pt=PCMU psz=160 m=0 x=0 seq=58526 ts=603847728 src=c682d654 ccnt=0
2009/05/05 20:48:51.357	  0:05.114	  Aggregator:0xb4fedb70	OpalCon	Media stream threads started.
2009/05/05 20:48:51.523	  0:05.280	  Aggregator:0xb4fedb70	SIP	Transaction remote address changed to udp$proxy01.sipphone.com:5060
2009/05/05 20:48:51.525	  0:05.282	  Aggregator:0xb4fedb70	PWLib	File handle high water mark set: 76 PUDPSocket
2009/05/05 20:48:51.544	  0:05.301	                       	AEScheduler	Removing Event incoming_call_sound from queue
2009/05/05 20:48:51.545	  0:05.302	                       	AEScheduler	Removing Event ring_tone_sound from queue
2009/05/05 20:48:51.567	  0:05.324	 Media Patch:0xa85a8b70	RTP	Jitter buffer length exceeded
2009/05/05 20:48:51.568	  0:05.325	 Media Patch:0xa85a8b70	RTP	Jitter buffer length exceed was prior to first write. Not increasing buffer size
2009/05/05 20:48:51.575	  0:05.332	  Aggregator:0xb4fedb70	SIP	Sending PDU (497 bytes) to: rem=udp$198.65.166.131:5060,local=udp$69.196.166.233:60426,if=192.168.1.12%wlan0
ACK sip:echo@130.94.88.70:5060 SIP/2.0
Route: <sip:proxy01.sipphone.com:5060;lr>
CSeq: 1 ACK
Via: SIP/2.0/UDP 69.196.166.233:60426;branch=z9hG4bK94031456-4538-de11-9fe8-00904b4fcd2b;rport
From: "Jesse Kahtava" <sip:17476942064@proxy01.sipphone.com>;tag=842cca54-4538-de11-9fe8-00904b4fcd2b
Call-ID: a49dca54-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
To: <sip:echo@proxy01.sipphone.com>;tag=as1d5b85c5
Contact: <sip:17476942064@69.196.166.233:60426>
Content-Length: 0
Max-Forwards: 70


2009/05/05 20:48:51.606	  0:05.363	  Aggregator:0xb4fedb70	OpalUDP	Setting interface to 192.168.1.12%wlan0
2009/05/05 20:48:51.846	  0:05.603	Opal Garbage:0xb7c79b70	SIP	Transaction 2 REGISTER destroyed.
2009/05/05 20:48:51.878	  0:05.635	 Media Patch:0xa85a8b70	RTP	Jitter buffer length exceeded
2009/05/05 20:48:51.878	  0:05.635	 Media Patch:0xa85a8b70	RTP	Jitter buffer size increased to 2080 (260ms)
2009/05/05 20:48:52.197	  0:05.954	 Media Patch:0xa85a8b70	RTP	Jitter buffer length exceeded
2009/05/05 20:48:52.197	  0:05.954	 Media Patch:0xa85a8b70	RTP	Jitter buffer size increased to 2400 (300ms)
2009/05/05 20:48:52.478	  0:06.235	  RTP Jitter:0xa8567b70	RTP	Session 1, receive statistics: packets=51 octets=8160 lost=0 tooLate=0 order=0 avgTime=20 maxTime=31 minTime=12 jitter=3 maxJitter=3
2009/05/05 20:48:52.495	  0:06.252	 Media Patch:0xa8526b70	RTP	Session 1, transmit statistics:  packets=51 octets=8160 avgTime=21 maxTime=88 minTime=1
2009/05/05 20:48:52.581	  0:06.338	 Media Patch:0xa85a8b70	RTP	Jitter buffer length exceeded
2009/05/05 20:48:52.581	  0:06.338	 Media Patch:0xa85a8b70	RTP	Jitter buffer size increased to 2880 (360ms)
2009/05/05 20:48:52.606	  0:06.363	 Housekeeper:0xb7abeb70	SIP	Set state Terminated_Success for transaction 1 INVITE
2009/05/05 20:48:53.150	  0:06.907	 Media Patch:0xa85a8b70	RTP	Jitter buffer length exceeded
2009/05/05 20:48:53.150	  0:06.907	 Media Patch:0xa85a8b70	RTP	Jitter buffer size increased to 3200 (400ms)
2009/05/05 20:48:53.333	  0:07.090	 Housekeeper:0xb7abeb70	SIP	Sending PDU (698 bytes) to: rem=udp$198.65.166.131:5060,local=udp$69.196.166.233:60426,if=192.168.1.12%wlan0
SUBSCRIBE sip:17476942064@proxy01.sipphone.com SIP/2.0
Route: <sip:proxy01.sipphone.com:5060;lr>
CSeq: 1 SUBSCRIBE
Via: SIP/2.0/UDP 69.196.166.233:60426;branch=z9hG4bK02ad0755-4538-de11-9fe8-00904b4fcd2b;rport
User-Agent: Ekiga/3.2.0
From: "Jesse Kahtava" <sip:17476942064@proxy01.sipphone.com>;tag=0ae20655-4538-de11-9fe8-00904b4fcd2b
Call-ID: 80e00555-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
To: <sip:17476942064@proxy01.sipphone.com>
Contact: <sip:17476942064@69.196.166.233:60426>
Accept: application/simple-message-summary
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Expires: 3600
Event: message-summary
Content-Length: 0
Max-Forwards: 70


2009/05/05 20:48:53.334	  0:07.091	 Housekeeper:0xb7abeb70	OpalUDP	Setting interface to 192.168.1.12%wlan0
2009/05/05 20:48:53.334	  0:07.091	 Housekeeper:0xb7abeb70	SIP	Transaction 1 SUBSCRIBE timeout, making retry 3, timeout 4.000
2009/05/05 20:48:53.463	  0:07.220	  RTP Jitter:0xa8567b70	RTP	Session 1, receive statistics: packets=101 octets=16160 lost=0 tooLate=0 order=0 avgTime=19 maxTime=43 minTime=1 jitter=3 maxJitter=6
2009/05/05 20:48:53.482	  0:07.239	 Media Patch:0xa8526b70	RTP	Session 1, transmit statistics:  packets=101 octets=16160 avgTime=19 maxTime=47 minTime=1
2009/05/05 20:48:54.457	  0:08.214	  RTP Jitter:0xa8567b70	RTP	Session 1, receive statistics: packets=151 octets=24160 lost=0 tooLate=0 order=0 avgTime=19 maxTime=25 minTime=14 jitter=2 maxJitter=6
2009/05/05 20:48:54.486	  0:08.243	 Media Patch:0xa8526b70	RTP	Session 1, transmit statistics:  packets=151 octets=24160 avgTime=20 maxTime=44 minTime=1
2009/05/05 20:48:55.460	  0:09.217	  RTP Jitter:0xa8567b70	RTP	Session 1, receive statistics: packets=201 octets=32160 lost=0 tooLate=0 order=0 avgTime=20 maxTime=24 minTime=15 jitter=1 maxJitter=6
2009/05/05 20:48:55.490	  0:09.247	 Media Patch:0xa8526b70	RTP	Session 1, transmit statistics:  packets=201 octets=32160 avgTime=20 maxTime=46 minTime=1
2009/05/05 20:48:55.830	  0:09.587	 Housekeeper:0xb7abeb70	SIP	Set state Terminated_Timeout for transaction 1 SUBSCRIBE
2009/05/05 20:48:55.831	  0:09.588	 Housekeeper:0xb7abeb70	SIP	Changing SUBSCRIBE handler from Subscribing to Unavailable, target=sip:17476942064@proxy01.sipphone.com, id=80e00555-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
2009/05/05 20:48:55.831	  0:09.588	 Housekeeper:0xb7abeb70	SIP	Retrying SUBSCRIBE in 30 seconds.
2009/05/05 20:48:55.847	  0:09.604	Opal Garbage:0xb7c79b70	SIP	Transaction 1 SUBSCRIBE destroyed.
2009/05/05 20:48:56.027	  0:09.784	 Media Patch:0xa85a8b70	RTP	Jitter buffer length exceeded
2009/05/05 20:48:56.027	  0:09.784	 Media Patch:0xa85a8b70	RTP	Jitter buffer size increased to 3520 (440ms)
2009/05/05 20:48:56.374	  0:10.131	Opal Liste...0xb7a7db70	OpalUDP	Binding to interface: 69.196.166.233:60426
2009/05/05 20:48:56.374	  0:10.131	Opal Liste...0xb7a7db70	SIP	Waiting for PDU on udp$198.65.166.131:5060<if=udp$69.196.166.233:60426>
2009/05/05 20:48:56.375	  0:10.132	Opal Liste...0xb7a7db70	SIP	PDU received: rem=udp$198.65.166.131:5060,local=udp$69.196.166.233:60426,if=192.168.1.12%wlan0
BYE sip:17476942064@69.196.166.233:60426 SIP/2.0
CSeq: 102 BYE
Via: SIP/2.0/UDP 198.65.166.131;branch=z9hG4bKa391.e24795a7.0
Via: SIP/2.0/UDP 130.94.88.70:5060;branch=z9hG4bK1952d7fc;rport=5060
User-Agent: SIPCallProxy1
From: <sip:echo@proxy01.sipphone.com>;tag=as393f1755
P-hint: rr-enforced
RemoteIP: 130.94.88.70
Call-ID: 64c8dd33-4538-de11-8095-00904b4fcd2b@flump.hornpipe
To: "Jesse Kahtava" <sip:17476942064@proxy01.sipphone.com>;tag=84addd33-4538-de11-8095-00904b4fcd2b
Content-Length: 0
Max-Forwards: 69
Record-Route: <sip:198.65.166.131;lr;ftag=as393f1755>


2009/05/05 20:48:56.378	  0:10.135	Opal Liste...0xb7a7db70	SIP	Sending PDU (510 bytes) to: rem=udp$198.65.166.131:5060,local=udp$69.196.166.233:60426,if=192.168.1.12%wlan0
SIP/2.0 100 Trying
CSeq: 102 BYE
Via: SIP/2.0/UDP 198.65.166.131;branch=z9hG4bKa391.e24795a7.0
Via: SIP/2.0/UDP 130.94.88.70:5060;branch=z9hG4bK1952d7fc;rport=5060
From: <sip:echo@proxy01.sipphone.com>;tag=as393f1755
Call-ID: 64c8dd33-4538-de11-8095-00904b4fcd2b@flump.hornpipe
To: "Jesse Kahtava" <sip:17476942064@proxy01.sipphone.com>;tag=84addd33-4538-de11-8095-00904b4fcd2b
Contact: <sip:17476942064@69.196.166.233:60426>
Content-Length: 0
Record-Route: <sip:198.65.166.131;lr;ftag=as393f1755>


2009/05/05 20:48:56.379	  0:10.136	Opal Liste...0xb7a7db70	OpalUDP	Setting interface to 192.168.1.12%wlan0
2009/05/05 20:48:56.382	  0:10.139	Opal Liste...0xb7a7db70	SIP	Sending PDU (539 bytes) to: rem=udp$198.65.166.131:5060,local=udp$69.196.166.233:60426,if=192.168.1.12%wlan0
SIP/2.0 481 Call Leg/Transaction Does Not Exist
CSeq: 102 BYE
Via: SIP/2.0/UDP 198.65.166.131;branch=z9hG4bKa391.e24795a7.0
Via: SIP/2.0/UDP 130.94.88.70:5060;branch=z9hG4bK1952d7fc;rport=5060
From: <sip:echo@proxy01.sipphone.com>;tag=as393f1755
Call-ID: 64c8dd33-4538-de11-8095-00904b4fcd2b@flump.hornpipe
To: "Jesse Kahtava" <sip:17476942064@proxy01.sipphone.com>;tag=84addd33-4538-de11-8095-00904b4fcd2b
Contact: <sip:17476942064@69.196.166.233:60426>
Content-Length: 0
Record-Route: <sip:198.65.166.131;lr;ftag=as393f1755>


2009/05/05 20:48:56.383	  0:10.140	Opal Liste...0xb7a7db70	OpalUDP	Setting interface to 192.168.1.12%wlan0
2009/05/05 20:48:56.383	  0:10.140	Opal Liste...0xb7a7db70	Opal	Transport clean up on termination
2009/05/05 20:48:56.383	  0:10.140	Opal Liste...0xb7a7db70	Opal	Transport Close
2009/05/05 20:48:56.383	  0:10.140	Opal Liste...0xb7a7db70	Opal	Deleted transport udp$198.65.166.131:5060<if=udp$69.196.166.233:60426>
2009/05/05 20:48:56.465	  0:10.222	  RTP Jitter:0xa8567b70	RTP	Session 1, receive statistics: packets=251 octets=40160 lost=0 tooLate=0 order=0 avgTime=20 maxTime=28 minTime=13 jitter=3 maxJitter=6
2009/05/05 20:48:56.497	  0:10.254	 Media Patch:0xa8526b70	RTP	Session 1, transmit statistics:  packets=251 octets=40160 avgTime=20 maxTime=70 minTime=0
2009/05/05 20:48:57.413	  0:11.170	Opal Liste...0xb7a7db70	OpalUDP	Binding to interface: 69.196.166.233:60426
2009/05/05 20:48:57.413	  0:11.170	Opal Liste...0xb7a7db70	SIP	Waiting for PDU on udp$198.65.166.131:5060<if=udp$69.196.166.233:60426>
2009/05/05 20:48:57.414	  0:11.171	Opal Liste...0xb7a7db70	SIP	PDU received: rem=udp$198.65.166.131:5060,local=udp$69.196.166.233:60426,if=192.168.1.12%wlan0
SIP/2.0 405 Method Not Allowed
CSeq: 1 SUBSCRIBE
Via: SIP/2.0/UDP 69.196.166.233:60426;branch=z9hG4bK02ad0755-4538-de11-9fe8-00904b4fcd2b;rport=60426
From: "Jesse Kahtava" <sip:17476942064@proxy01.sipphone.com>;tag=0ae20655-4538-de11-9fe8-00904b4fcd2b
RemoteIP: 69.196.166.233
Call-ID: 80e00555-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
To: <sip:17476942064@proxy01.sipphone.com>
Contact: <sip:17476942064@69.196.166.233:60426>
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Content-Length: 0
Record-Route: <sip:198.65.166.131;lr;ftag=0ae20655-4538-de11-9fe8-00904b4fcd2b>


2009/05/05 20:48:57.415	  0:11.172	Opal Liste...0xb7a7db70	Opal	Transport clean up on termination
2009/05/05 20:48:57.415	  0:11.172	Opal Liste...0xb7a7db70	Opal	Transport Close
2009/05/05 20:48:57.415	  0:11.172	Opal Liste...0xb7a7db70	Opal	Deleted transport udp$198.65.166.131:5060<if=udp$69.196.166.233:60426>
2009/05/05 20:48:57.463	  0:11.220	  RTP Jitter:0xa8567b70	RTP	Session 1, receive statistics: packets=301 octets=48160 lost=0 tooLate=0 order=0 avgTime=19 maxTime=27 minTime=13 jitter=3 maxJitter=6
2009/05/05 20:48:57.491	  0:11.248	 Media Patch:0xa8526b70	RTP	Session 1, transmit statistics:  packets=301 octets=48160 avgTime=19 maxTime=35 minTime=1
2009/05/05 20:48:58.458	  0:12.215	  RTP Jitter:0xa8567b70	RTP	Session 1, receive statistics: packets=351 octets=56160 lost=0 tooLate=0 order=0 avgTime=19 maxTime=24 minTime=16 jitter=1 maxJitter=6
2009/05/05 20:48:58.492	  0:12.249	 Media Patch:0xa8526b70	RTP	Session 1, transmit statistics:  packets=351 octets=56160 avgTime=20 maxTime=44 minTime=1
2009/05/05 20:48:59.399	  0:13.156	                       	Call	Clearing Call[jb1729b981] reason=EndedByLocalUser
2009/05/05 20:48:59.399	  0:13.156	                       	OpalCon	SetPhase from EstablishedPhase to ReleasingPhase for Call[jb1729b981]-EP<pc>[e963b32e12]
2009/05/05 20:48:59.400	  0:13.157	                       	OpalCon	Releasing Call[jb1729b981]-EP<pc>[e963b32e12]
2009/05/05 20:48:59.400	  0:13.157	                       	OpalCon	Call end reason for Call[jb1729b981]-EP<pc>[e963b32e12] set to EndedByLocalUser
2009/05/05 20:48:59.400	  0:13.157	                       	OpalCon	OnReleased Call[jb1729b981]-EP<pc>[e963b32e12]
2009/05/05 20:48:59.400	  0:13.157	                       	OpalEP	OnReleased Call[jb1729b981]-EP<pc>[e963b32e12]
2009/05/05 20:48:59.400	  0:13.157	                       	OpalMan	OnReleased Call[jb1729b981]-EP<pc>[e963b32e12]
2009/05/05 20:48:59.400	  0:13.157	                       	Call	OnReleased Call[jb1729b981]-EP<pc>[e963b32e12]
2009/05/05 20:48:59.400	  0:13.157	                       	OpalCon	SetPhase from EstablishedPhase to ReleasingPhase for Call[jb1729b981]-EP<sip>[842cca54-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:48:59.400	  0:13.157	                       	OpalCon	Releasing Call[jb1729b981]-EP<sip>[842cca54-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:48:59.400	  0:13.157	                       	OpalCon	Call end reason for Call[jb1729b981]-EP<sip>[842cca54-4538-de11-9fe8-00904b4fcd2b] set to EndedByLocalUser
2009/05/05 20:48:59.400	  0:13.157	                       	PWLib	File handle high water mark set: 77 Thread unblock pipe
2009/05/05 20:48:59.401	  0:13.158	                       	PWLib	Thread high water mark set: 14
2009/05/05 20:48:59.401	  0:13.158	   OnRelease:0xa8460b70	SIP	OnReleased: Call[jb1729b981]-EP<sip>[842cca54-4538-de11-9fe8-00904b4fcd2b], phase = ReleasingPhase
2009/05/05 20:48:59.401	  0:13.158	   OnRelease:0xa8460b70	OpalCon	SetPhase from ReleasingPhase to ReleasingPhase for Call[jb1729b981]-EP<sip>[842cca54-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:48:59.402	  0:13.159	   OnRelease:0xa8460b70	PWLib	File handle high water mark set: 78 PUDPSocket
2009/05/05 20:48:59.404	  0:13.161	   OnRelease:0xa8460b70	SIP	Transaction 4 BYE created.
2009/05/05 20:48:59.405	  0:13.162	   OnRelease:0xa8460b70	Media	Closing RTP for OpalRTPMediaStream-Source-G.711-uLaw-64k
2009/05/05 20:48:59.405	  0:13.162	   OnRelease:0xa8460b70	RTP_UDP	Session 1, Shutting down read.
2009/05/05 20:48:59.405	  0:13.162	   OnRelease:0xa8460b70	Media	Closing stream OpalRTPMediaStream-Source-G.711-uLaw-64k
2009/05/05 20:48:59.405	  0:13.162	   OnRelease:0xa8460b70	Media	Disconnecting OpalRTPMediaStream-Source-G.711-uLaw-64k from patch thread Patch OpalRTPMediaStream-Source-G.711-uLaw-64k -> OpalAudioMediaStream-Sink-PCM-16
2009/05/05 20:48:59.405	  0:13.162	   OnRelease:0xa8460b70	Patch	Closing media patch Patch OpalRTPMediaStream-Source-G.711-uLaw-64k -> OpalAudioMediaStream-Sink-PCM-16
2009/05/05 20:48:59.406	  0:13.163	  RTP Jitter:0xa8567b70	RTP_UDP	Session 1, Read shutdown.
2009/05/05 20:48:59.406	  0:13.163	  RTP Jitter:0xa8567b70	RTP	Jitter RTP receive thread ended
2009/05/05 20:48:59.407	  0:13.164	  RTP Jitter:0xa8567b70	PWLib	Assertion fail: Function pthread_mutex_unlock failed, file ptlib/unix/tlibthrd.cxx, line 1421
Assertion fail: Function pthread_mutex_unlock failed, file ptlib/unix/tlibthrd.cxx, line 1421

<A>bort, <C>ore dump? 2009/05/05 20:48:59.419	  0:13.176	 Media Patch:0xa8526b70	RTP_UDP	Session 1, data port on remote not ready.
2009/05/05 20:48:59.425	  0:13.182	                       	Media	Closing stream OpalAudioMediaStream-Sink-PCM-16
2009/05/05 20:48:59.482	  0:13.239	 Media Patch:0xa8526b70	RTP	Session 1, transmit statistics:  packets=401 octets=64160 avgTime=19 maxTime=39 minTime=1
2009/05/05 20:48:59.425	  0:13.182	                       	Media	Disconnecting OpalAudioMediaStream-Sink-PCM-16 from patch thread Patch OpalRTPMediaStream-Source-G.711-uLaw-64k -> OpalAudioMediaStream-Sink-PCM-16
2009/05/05 20:48:59.647	  0:13.404	                       	Patch	Removing media stream sink OpalAudioMediaStream-Sink-PCM-16
2009/05/05 20:48:59.647	  0:13.404	                       	AudioOutputCore	Closing current device
2009/05/05 20:48:59.647	  0:13.404	                       	GMAudioOutputManager_ptlib	Closing device[0] Default (PTLIB/ALSA)
2009/05/05 20:48:59.646	  0:13.403	 Media Patch:0xa85a8b70	Patch	Thread ended for Patch OpalRTPMediaStream-Source-G.711-uLaw-64k
2009/05/05 20:48:59.653	  0:13.410	                       	GMAudioOutputManager_ptlib	Setting Device[0] Default (PTLIB/ALSA)
2009/05/05 20:48:59.653	  0:13.410	                       	Call	GetOtherPartyConnection Call[jb1729b981]-EP<pc>[e963b32e12]
2009/05/05 20:48:59.653	  0:13.410	                       	Media	Closing stream OpalAudioMediaStream-Source-PCM-16
2009/05/05 20:48:59.654	  0:13.411	                       	Media	Disconnecting OpalAudioMediaStream-Source-PCM-16 from patch thread Patch OpalAudioMediaStream-Source-PCM-16 -> OpalRTPMediaStream-Sink-G.711-uLaw-64k
2009/05/05 20:48:59.654	  0:13.411	                       	Patch	Closing media patch Patch OpalAudioMediaStream-Source-PCM-16 -> OpalRTPMediaStream-Sink-G.711-uLaw-64k
2009/05/05 20:48:59.654	  0:13.411	                       	Media	Closing RTP for OpalRTPMediaStream-Sink-G.711-uLaw-64k
2009/05/05 20:48:59.654	  0:13.411	                       	RTP_UDP	Session 1, Shutting down write.
2009/05/05 20:48:59.654	  0:13.411	                       	Media	Closing stream OpalRTPMediaStream-Sink-G.711-uLaw-64k
2009/05/05 20:48:59.654	  0:13.411	                       	Media	Disconnecting OpalRTPMediaStream-Sink-G.711-uLaw-64k from patch thread Patch OpalAudioMediaStream-Source-PCM-16 -> OpalRTPMediaStream-Sink-G.711-uLaw-64k
2009/05/05 20:48:59.654	  0:13.411	                       	Patch	Removing media stream sink OpalRTPMediaStream-Sink-G.711-uLaw-64k
2009/05/05 20:48:59.654	  0:13.411	                       	Patch	Waiting for media patch thread to stop Patch OpalAudioMediaStream-Source-PCM-16
2009/05/05 20:48:59.657	  0:13.414	   OnRelease:0xa8460b70	Patch	Waiting for media patch thread to stop Patch OpalRTPMediaStream-Source-G.711-uLaw-64k
2009/05/05 20:48:59.657	  0:13.414	   OnRelease:0xa8460b70	PWLib	Destroyed thread 0x88e2410 Media Patch:0xa85a8b70(id = 0)
2009/05/05 20:48:59.657	  0:13.414	   OnRelease:0xa8460b70	OpalCon	Media streams closed.
2009/05/05 20:48:59.658	  0:13.415	   OnRelease:0xa8460b70	SIP	No SRV lookup as has explicit port number.
2009/05/05 20:48:59.658	  0:13.415	   OnRelease:0xa8460b70	SIP	Transaction remote address is udp$proxy01.sipphone.com:5060
2009/05/05 20:48:59.659	  0:13.416	   OnRelease:0xa8460b70	PWLib	File handle low water mark set: 58 PUDPSocket
2009/05/05 20:48:59.658	  0:13.415	   OnRelease:0xa8460b70	SIP	Sending PDU (497 bytes) to: rem=udp$198.65.166.131:5060,local=udp$69.196.166.233:60426,if=192.168.1.12%wlan0
BYE sip:echo@130.94.88.70:5060 SIP/2.0
Route: <sip:proxy01.sipphone.com:5060;lr>
CSeq: 4 BYE
Via: SIP/2.0/UDP 69.196.166.233:60426;branch=z9hG4bKbecfbe5a-4538-de11-9fe8-00904b4fcd2b;rport
From: "Jesse Kahtava" <sip:17476942064@proxy01.sipphone.com>;tag=842cca54-4538-de11-9fe8-00904b4fcd2b
Call-ID: a49dca54-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
To: <sip:echo@proxy01.sipphone.com>;tag=as1d5b85c5
Contact: <sip:17476942064@69.196.166.233:60426>
Content-Length: 0
Max-Forwards: 70


2009/05/05 20:48:59.659	  0:13.416	   OnRelease:0xa8460b70	OpalUDP	Setting interface to 192.168.1.12%wlan0
2009/05/05 20:48:59.660	  0:13.417	   OnRelease:0xa8460b70	SIP	Transaction timers set: retry=0.500, completion=6.000
2009/05/05 20:48:59.667	  0:13.424	 Media Patch:0xa8526b70	Patch	Thread ended because all sink writes failed failed
2009/05/05 20:48:59.668	  0:13.425	 Media Patch:0xa8526b70	Patch	Thread ended for Patch OpalAudioMediaStream-Source-PCM-16
2009/05/05 20:48:59.675	  0:13.432	                       	PWLib	Destroyed thread 0x88e2370 Media Patch:0xa8526b70(id = 0)
2009/05/05 20:48:59.675	  0:13.432	                       	AudioInputCore	Stopping Stream
2009/05/05 20:48:59.675	  0:13.432	                       	AudioInputCore	Closing current device
2009/05/05 20:48:59.675	  0:13.432	                       	GMAudioInputManager_ptlib	Closing device Default (PTLIB/ALSA)
2009/05/05 20:48:59.680	  0:13.437	                       	GMAudioInputManager_ptlib	Setting Device Default (PTLIB/ALSA)
2009/05/05 20:48:59.680	  0:13.437	                       	OpalCon	Media streams closed.
2009/05/05 20:48:59.834	  0:13.591	Opal Liste...0xb7a7db70	OpalUDP	Binding to interface: 69.196.166.233:60426
2009/05/05 20:48:59.834	  0:13.591	Opal Liste...0xb7a7db70	SIP	Waiting for PDU on udp$198.65.166.131:5060<if=udp$69.196.166.233:60426>
2009/05/05 20:48:59.835	  0:13.592	Opal Liste...0xb7a7db70	SIP	PDU received: rem=udp$198.65.166.131:5060,local=udp$69.196.166.233:60426,if=192.168.1.12%wlan0
SIP/2.0 200 OK
CSeq: 4 BYE
Via: SIP/2.0/UDP 69.196.166.233:60426;branch=z9hG4bKbecfbe5a-4538-de11-9fe8-00904b4fcd2b;rport=60426
User-Agent: SIPCallProxy1
From: "Jesse Kahtava" <sip:17476942064@proxy01.sipphone.com>;tag=842cca54-4538-de11-9fe8-00904b4fcd2b
RemoteIP: 130.94.88.70
Call-ID: a49dca54-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
To: <sip:echo@proxy01.sipphone.com>;tag=as1d5b85c5
Contact: <sip:echo@130.94.88.70:5060>
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0
Record-Route: <sip:198.65.166.131;lr;ftag=842cca54-4538-de11-9fe8-00904b4fcd2b>


2009/05/05 20:48:59.836	  0:13.593	Opal Liste...0xb7a7db70	Opal	Transport clean up on termination
2009/05/05 20:48:59.836	  0:13.593	Opal Liste...0xb7a7db70	Opal	Transport Close
2009/05/05 20:48:59.836	  0:13.593	Opal Liste...0xb7a7db70	Opal	Deleted transport udp$198.65.166.131:5060<if=udp$69.196.166.233:60426>
2009/05/05 20:48:59.837	  0:13.594	  Aggregator:0xb4fedb70	SIP	Transaction 4 BYE completed.
2009/05/05 20:48:59.837	  0:13.594	  Aggregator:0xb4fedb70	SIP	Handling 200 OK response for BYE
2009/05/05 20:48:59.837	  0:13.594	   OnRelease:0xa8460b70	OpalCon	SetPhase from ReleasingPhase to ReleasedPhase for Call[jb1729b981]-EP<sip>[842cca54-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:48:59.838	  0:13.595	   OnRelease:0xa8460b70	OpalCon	OnReleased Call[jb1729b981]-EP<sip>[842cca54-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:48:59.838	  0:13.595	   OnRelease:0xa8460b70	OpalEP	OnReleased Call[jb1729b981]-EP<sip>[842cca54-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:48:59.838	  0:13.595	   OnRelease:0xa8460b70	OpalMan	OnReleased Call[jb1729b981]-EP<sip>[842cca54-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:48:59.838	  0:13.595	   OnRelease:0xa8460b70	Call	OnReleased Call[jb1729b981]-EP<sip>[842cca54-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:48:59.838	  0:13.595	   OnRelease:0xa8460b70	OpalCon	Media streams closed.
2009/05/05 20:48:59.838	  0:13.595	   OnRelease:0xa8460b70	OpalCon	OnRelease thread completed for Call[jb1729b981]-EP<sip>[842cca54-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:48:59.852	  0:13.609	Opal Garbage:0xb7c79b70	PCSS	Deleted PC sound system connection.
2009/05/05 20:48:59.852	  0:13.609	Opal Garbage:0xb7c79b70	OpalCon	Connection Call[jb1729b981]-EP<pc>[e963b32e12] destroyed.
2009/05/05 20:48:59.852	  0:13.609	Opal Garbage:0xb7c79b70	SIP	Transaction 1 INVITE destroyed.
2009/05/05 20:48:59.939	  0:13.696	                       	AEScheduler	Removing Event incoming_call_sound from queue
2009/05/05 20:48:59.940	  0:13.697	                       	AEScheduler	Removing Event ring_tone_sound from queue
2009/05/05 20:48:59.940	  0:13.697	                       	OpalMan	OnClearedCall Call[jb1729b981] from "pc:jesse" to "sip:echo@proxy01.sipphone.com"
2009/05/05 20:48:59.940	  0:13.697	                       	OPAL	Attempting to close recording on call jb1729b981 when not recording.
2009/05/05 20:49:00.839	  0:14.596	 Housekeeper:0xb7abeb70	SIP	Set state Terminated_Success for transaction 4 BYE
2009/05/05 20:49:00.853	  0:14.610	Opal Garbage:0xb7c79b70	SIP	Transaction 4 BYE destroyed.
2009/05/05 20:49:00.854	  0:14.611	Opal Garbage:0xb7c79b70	Opal	Transport clean up on termination
2009/05/05 20:49:00.854	  0:14.611	Opal Garbage:0xb7c79b70	Opal	Transport Close
2009/05/05 20:49:00.854	  0:14.611	Opal Garbage:0xb7c79b70	Opal	Transport clean up on termination
2009/05/05 20:49:00.854	  0:14.611	Opal Garbage:0xb7c79b70	Opal	Transport Close
2009/05/05 20:49:00.854	  0:14.611	Opal Garbage:0xb7c79b70	Opal	Deleted transport udp$198.65.166.131:5060<if=udp$69.196.166.233:60426>
2009/05/05 20:49:00.855	  0:14.612	Opal Garbage:0xb7c79b70	SIP	Deleted connection.
2009/05/05 20:49:00.857	  0:14.614	Opal Garbage:0xb7c79b70	RTP	Closing session 1
2009/05/05 20:49:00.857	  0:14.614	Opal Garbage:0xb7c79b70	RTP	Deleting session 1
2009/05/05 20:49:00.857	  0:14.614	Opal Garbage:0xb7c79b70	RTP_UDP	Session 1, Shutting down read.
2009/05/05 20:49:00.857	  0:14.614	Opal Garbage:0xb7c79b70	InfLID	Switching off jitter buffer.
2009/05/05 20:49:00.857	  0:14.614	Opal Garbage:0xb7c79b70	RTP	Removing jitter buffer 0xb7b00698 RTP Jitter:0xa8567b70
2009/05/05 20:49:01.958	  0:15.715	                       	OpalMan	Set up call from pc:* to sip:echo@proxy01.sipphone.com
2009/05/05 20:49:01.958	  0:15.715	                       	Call	Created Call[f881717d83]
2009/05/05 20:49:01.959	  0:15.716	                       	OpalMan	Set up connection to "pc:*"
2009/05/05 20:49:01.963	  0:15.720	                       	OpalCon	Created connection Call[f881717d83]-EP<pc>[h8a50f8244]
2009/05/05 20:49:01.963	  0:15.720	                       	Silence	Parameters set: mode=NoSilenceDetection, threshold=0, silencedb=3200, signaldb=80, period=4800
2009/05/05 20:49:01.963	  0:15.720	                       	Silence	Handler created
2009/05/05 20:49:01.964	  0:15.721	                       	Echo Canceler	Handler created
2009/05/05 20:49:01.964	  0:15.721	                       	PCSS	Created PC sound system connection: token="h8a50f8244" player="EKIGA" recorder="EKIGA"
2009/05/05 20:49:01.964	  0:15.721	                       	OpalCon	SetPhase from UninitialisedPhase to SetUpPhase for Call[f881717d83]-EP<pc>[h8a50f8244]
2009/05/05 20:49:01.964	  0:15.721	                       	OpalMan	OnIncoming connection Call[f881717d83]-EP<pc>[h8a50f8244]
2009/05/05 20:49:01.964	  0:15.721	                       	OpalCon	Applying string options:


2009/05/05 20:49:01.964	  0:15.722	                       	Call	GetOtherPartyConnection Call[f881717d83]-EP<pc>[h8a50f8244]
2009/05/05 20:49:01.965	  0:15.722	                       	OpalMan	Searching for route "pc:jesse	sip:echo@proxy01.sipphone.com"
2009/05/05 20:49:01.965	  0:15.722	                       	OpalMan	Set up connection to "sip:echo@proxy01.sipphone.com"
2009/05/05 20:49:01.965	  0:15.723	                       	OpalCon	Created connection Call[f881717d83]-EP<sip>[1c9e455c-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:49:01.966	  0:15.723	                       	RFC2833	Handler created
2009/05/05 20:49:01.966	  0:15.723	                       	RFC2833	Handler created
2009/05/05 20:49:01.967	  0:15.724	                       	SIP	Created connection.
2009/05/05 20:49:01.967	  0:15.724	                       	PCSS	Outgoing call routed to sip:echo@proxy01.sipphone.com for Call[f881717d83]-EP<pc>[h8a50f8244]
2009/05/05 20:49:01.973	  0:15.730	   CallSetup:0xa8526b70	Call	OnSetUp Call[f881717d83]-EP<pc>[h8a50f8244]
2009/05/05 20:49:01.974	  0:15.731	   CallSetup:0xa8526b70	SIP	SetUpConnection: sip:echo@proxy01.sipphone.com
2009/05/05 20:49:01.974	  0:15.731	   CallSetup:0xa8526b70	OpalCon	SetPhase from UninitialisedPhase to SetUpPhase for Call[f881717d83]-EP<sip>[1c9e455c-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:49:01.974	  0:15.732	   CallSetup:0xa8526b70	OpalCon	Applying string options:


2009/05/05 20:49:01.976	  0:15.733	   CallSetup:0xa8526b70	SIP	Found registrar on domain proxy01.sipphone.com, using interface 192.168.1.12%wlan0
2009/05/05 20:49:01.977	  0:15.734	   CallSetup:0xa8526b70	OpalUDP	Binding to interface: 192.168.1.12:5060
2009/05/05 20:49:01.978	  0:15.735	   CallSetup:0xa8526b70	SIP	Created transport udp$198.65.166.131:5060<if=udp$69.196.166.233:60426>
2009/05/05 20:49:01.979	  0:15.736	   CallSetup:0xa8526b70	OpalUDP	Started connect to 198.65.166.131:5060
2009/05/05 20:49:01.980	  0:15.737	   CallSetup:0xa8526b70	SIP	Updating dialog tag from "" to "1c9e455c-4538-de11-9fe8-00904b4fcd2b"
2009/05/05 20:49:02.008	  0:15.765	                       	OpalMan	SetUpCall succeeded, call=Call[f881717d83]
2009/05/05 20:49:02.010	  0:15.767	   CallSetup:0xa8526b70	SIP	Transaction 1 INVITE created.
2009/05/05 20:49:02.072	  0:15.829	   CallSetup:0xa8526b70	MediaFormat	Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz
2009/05/05 20:49:02.085	  0:15.842	   CallSetup:0xa8526b70	MediaFormat	Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz
2009/05/05 20:49:02.091	  0:15.848	   CallSetup:0xa8526b70	Call	GetMediaFormats for Call[f881717d83]-EP<sip>[1c9e455c-4538-de11-9fe8-00904b4fcd2b]
theora
H.261
H.261-CIF
H.261-QCIF
SpeexIETFWide-20.6k
SpeexWB
SpeexWide-20.6k
G.711-uLaw-64k
G.711-ALaw-64k
G.722-64k

2009/05/05 20:49:02.091	  0:15.848	   CallSetup:0xa8526b70	SIP	Offering media type audio in SDP with formats
theora,H.261,H.261-CIF,H.261-QCIF,SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,G.722-64k
2009/05/05 20:49:02.092	  0:15.849	   CallSetup:0xa8526b70	Call	IsMediaBypassPossible Call[f881717d83]-EP<sip>[1c9e455c-4538-de11-9fe8-00904b4fcd2b] session 1
2009/05/05 20:49:02.092	  0:15.849	   CallSetup:0xa8526b70	OpalMan	IsMediaBypassPossible: session 1
2009/05/05 20:49:02.092	  0:15.849	   CallSetup:0xa8526b70	OpalCon	IsMediaBypassPossible: default returns false
2009/05/05 20:49:02.092	  0:15.849	   CallSetup:0xa8526b70	RTP	Cannot find media session 1
2009/05/05 20:49:02.092	  0:15.849	   CallSetup:0xa8526b70	RTP	Cannot find RTP session 1
2009/05/05 20:49:02.095	  0:15.852	   CallSetup:0xa8526b70	RTP_UDP	Session 1, created with NAT flag set to 0
2009/05/05 20:49:02.351	  0:16.108	   CallSetup:0xa8526b70	RTP	Session 1, STUN created RTP/RTCP socket pair.
2009/05/05 20:49:02.352	  0:16.109	   CallSetup:0xa8526b70	RTP_UDP	Session 1 created: 69.196.166.233:60126-60127 ssrc=1416696901
2009/05/05 20:49:02.353	  0:16.110	   CallSetup:0xa8526b70	RTP	Creating new session RTP_UDP
2009/05/05 20:49:02.354	  0:16.111	   CallSetup:0xa8526b70	RTP	Found existing media session 1
2009/05/05 20:49:02.356	  0:16.113	   CallSetup:0xa8526b70	SDP	SDP not including SpeexIETFWide-20.6k as it is not a SIP transportable format
2009/05/05 20:49:02.356	  0:16.113	   CallSetup:0xa8526b70	SDP	SDP not including SpeexWide-20.6k as it is not a SIP transportable format
2009/05/05 20:49:02.356	  0:16.113	   CallSetup:0xa8526b70	SIP	Using default RTP payload [pt=101] for UserInput/RFC2833
2009/05/05 20:49:02.357	  0:16.114	   CallSetup:0xa8526b70	SIP	Using default RTP payload [pt=120] for NamedSignalEvent
2009/05/05 20:49:02.375	  0:16.132	   CallSetup:0xa8526b70	MediaFormat	Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz
2009/05/05 20:49:02.380	  0:16.137	   CallSetup:0xa8526b70	MediaFormat	Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz
2009/05/05 20:49:02.382	  0:16.139	   CallSetup:0xa8526b70	Call	GetMediaFormats for Call[f881717d83]-EP<sip>[1c9e455c-4538-de11-9fe8-00904b4fcd2b]
theora
H.261
H.261-CIF
H.261-QCIF
SpeexIETFWide-20.6k
SpeexWB
SpeexWide-20.6k
G.711-uLaw-64k
G.711-ALaw-64k
G.722-64k

2009/05/05 20:49:02.382	  0:16.139	   CallSetup:0xa8526b70	SIP	Offering media type video in SDP with formats
theora,H.261,H.261-CIF,H.261-QCIF,SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,G.722-64k
2009/05/05 20:49:02.382	  0:16.139	   CallSetup:0xa8526b70	Call	IsMediaBypassPossible Call[f881717d83]-EP<sip>[1c9e455c-4538-de11-9fe8-00904b4fcd2b] session 2
2009/05/05 20:49:02.382	  0:16.139	   CallSetup:0xa8526b70	OpalMan	IsMediaBypassPossible: session 2
2009/05/05 20:49:02.382	  0:16.139	   CallSetup:0xa8526b70	OpalCon	IsMediaBypassPossible: default returns false
2009/05/05 20:49:02.382	  0:16.139	   CallSetup:0xa8526b70	RTP	Cannot find media session 2
2009/05/05 20:49:02.383	  0:16.140	   CallSetup:0xa8526b70	RTP	Cannot find RTP session 2
2009/05/05 20:49:02.386	  0:16.143	   CallSetup:0xa8526b70	RTP_UDP	Session 2, created with NAT flag set to 0
2009/05/05 20:49:02.635	  0:16.392	   CallSetup:0xa8526b70	RTP	Session 2, STUN created RTP/RTCP socket pair.
2009/05/05 20:49:02.636	  0:16.393	   CallSetup:0xa8526b70	RTP_UDP	Session 2 created: 69.196.166.233:60130-60131 ssrc=1122117660
2009/05/05 20:49:02.637	  0:16.394	   CallSetup:0xa8526b70	RTP	Creating new session RTP_UDP
2009/05/05 20:49:02.637	  0:16.394	   CallSetup:0xa8526b70	RTP	Found existing media session 2
2009/05/05 20:49:02.639	  0:16.396	   CallSetup:0xa8526b70	SDP	SDP not including H.261-CIF as it is not a SIP transportable format
2009/05/05 20:49:02.640	  0:16.397	   CallSetup:0xa8526b70	SDP	SDP not including H.261-QCIF as it is not a SIP transportable format
2009/05/05 20:49:02.640	  0:16.397	   CallSetup:0xa8526b70	SIP	Creating INVITE request
2009/05/05 20:49:02.640	  0:16.397	   CallSetup:0xa8526b70	SIP	No SRV lookup as has explicit port number.
2009/05/05 20:49:02.641	  0:16.398	   CallSetup:0xa8526b70	SIP	Transaction remote address is udp$proxy01.sipphone.com:5060
2009/05/05 20:49:02.643	  0:16.400	   CallSetup:0xa8526b70	OpalPlugin	to_customised_options: theora
2009/05/05 20:49:02.651	  0:16.408	   CallSetup:0xa8526b70	OpalPlugin	to_customised_options: H.261
2009/05/05 20:49:02.655	  0:16.412	   CallSetup:0xa8526b70	OpalPlugin	to_customised_options changed option "Frame Width" from "320" to "176"
2009/05/05 20:49:02.655	  0:16.412	   CallSetup:0xa8526b70	OpalPlugin	to_customised_options changed option "Frame Height" from "240" to "144"
2009/05/05 20:49:02.657	  0:16.414	   CallSetup:0xa8526b70	SIP	Sending PDU (1251 bytes) to: rem=udp$198.65.166.131:5060,local=udp$69.196.166.233:60426,if=192.168.1.12%wlan0
INVITE sip:echo@proxy01.sipphone.com SIP/2.0
Route: <sip:proxy01.sipphone.com:5060;lr>
Date: Wed, 06 May 2009 00:49:02 GMT
CSeq: 1 INVITE
Via: SIP/2.0/UDP 69.196.166.233:60426;branch=z9hG4bKdc094c5c-4538-de11-9fe8-00904b4fcd2b;rport
User-Agent: Ekiga/3.2.0
From: "Jesse Kahtava" <sip:17476942064@proxy01.sipphone.com>;tag=1c9e455c-4538-de11-9fe8-00904b4fcd2b
Call-ID: faba455c-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
To: <sip:echo@proxy01.sipphone.com>
Contact: <sip:17476942064@69.196.166.233:60426>
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Content-Type: application/sdp
Content-Length: 586
Max-Forwards: 70

v=0
o=- 1241570942 1241570942 IN IP4 69.196.166.233
s=Opal SIP Session
c=IN IP4 69.196.166.233
t=0 0
m=audio 60126 RTP/AVP 113 0 8 9 101 120
a=sendrecv
a=rtpmap:113 Speex/16000/1
a=fmtp:113 sr=16000,mode=any
a=rtpmap:0 PCMU/8000/1
a=rtpmap:8 PCMA/8000/1
a=rtpmap:9 G722/8000/1
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16,32,36
a=rtpmap:120 NSE/8000
a=fmtp:120 192-193
m=video 60130 RTP/AVP 99 31
a=sendrecv
a=rtpmap:99 theora/90000
a=fmtp:99 delivery-method="in_band";height=576;sampling="YCbCr-4:2:0";width=704
a=rtpmap:31 h261/90000
a=fmtp:31 CIF=1;QCIF=1

2009/05/05 20:49:02.658	  0:16.415	   CallSetup:0xa8526b70	OpalUDP	Setting interface to 192.168.1.12%wlan0
2009/05/05 20:49:02.659	  0:16.416	   CallSetup:0xa8526b70	SIP	Transaction timers set: retry=0.500, completion=1:00.000
2009/05/05 20:49:02.659	  0:16.416	   CallSetup:0xa8526b70	OpalCon	OnSetUpConnectionCall[f881717d83]-EP<sip>[1c9e455c-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:49:02.660	  0:16.417	   CallSetup:0xa8526b70	OpalEP	OnSetUpConnection Call[f881717d83]-EP<sip>[1c9e455c-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:49:03.161	  0:16.918	 Housekeeper:0xb7abeb70	OpalPlugin	to_customised_options: theora
2009/05/05 20:49:03.165	  0:16.922	 Housekeeper:0xb7abeb70	OpalPlugin	to_customised_options: H.261
2009/05/05 20:49:03.170	  0:16.927	 Housekeeper:0xb7abeb70	SIP	Sending PDU (1251 bytes) to: rem=udp$198.65.166.131:5060,local=udp$69.196.166.233:60426,if=192.168.1.12%wlan0
INVITE sip:echo@proxy01.sipphone.com SIP/2.0
Route: <sip:proxy01.sipphone.com:5060;lr>
Date: Wed, 06 May 2009 00:49:02 GMT
CSeq: 1 INVITE
Via: SIP/2.0/UDP 69.196.166.233:60426;branch=z9hG4bKdc094c5c-4538-de11-9fe8-00904b4fcd2b;rport
User-Agent: Ekiga/3.2.0
From: "Jesse Kahtava" <sip:17476942064@proxy01.sipphone.com>;tag=1c9e455c-4538-de11-9fe8-00904b4fcd2b
Call-ID: faba455c-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
To: <sip:echo@proxy01.sipphone.com>
Contact: <sip:17476942064@69.196.166.233:60426>
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Content-Type: application/sdp
Content-Length: 586
Max-Forwards: 70

v=0
o=- 1241570942 1241570942 IN IP4 69.196.166.233
s=Opal SIP Session
c=IN IP4 69.196.166.233
t=0 0
m=audio 60126 RTP/AVP 113 0 8 9 101 120
a=sendrecv
a=rtpmap:113 Speex/16000/1
a=fmtp:113 sr=16000,mode=any
a=rtpmap:0 PCMU/8000/1
a=rtpmap:8 PCMA/8000/1
a=rtpmap:9 G722/8000/1
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16,32,36
a=rtpmap:120 NSE/8000
a=fmtp:120 192-193
m=video 60130 RTP/AVP 99 31
a=sendrecv
a=rtpmap:99 theora/90000
a=fmtp:99 delivery-method="in_band";height=576;sampling="YCbCr-4:2:0";width=704
a=rtpmap:31 h261/90000
a=fmtp:31 CIF=1;QCIF=1

2009/05/05 20:49:03.172	  0:16.929	 Housekeeper:0xb7abeb70	OpalUDP	Setting interface to 192.168.1.12%wlan0
2009/05/05 20:49:03.172	  0:16.929	 Housekeeper:0xb7abeb70	SIP	Transaction 1 INVITE timeout, making retry 1, timeout 1.000
2009/05/05 20:49:03.660	  0:17.417	Opal Liste...0xb7a7db70	OpalUDP	Binding to interface: 69.196.166.233:60426
2009/05/05 20:49:03.661	  0:17.418	Opal Liste...0xb7a7db70	SIP	Waiting for PDU on udp$198.65.166.131:5060<if=udp$69.196.166.233:60426>
2009/05/05 20:49:03.662	  0:17.419	Opal Liste...0xb7a7db70	SIP	PDU received: rem=udp$198.65.166.131:5060,local=udp$69.196.166.233:60426,if=192.168.1.12%wlan0
SIP/2.0 100 Giving a try
CSeq: 1 INVITE
Via: SIP/2.0/UDP 69.196.166.233:60426;branch=z9hG4bKdc094c5c-4538-de11-9fe8-00904b4fcd2b;rport=60426
From: "Jesse Kahtava" <sip:17476942064@proxy01.sipphone.com>;tag=1c9e455c-4538-de11-9fe8-00904b4fcd2b
Call-ID: faba455c-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
To: <sip:echo@proxy01.sipphone.com>
Content-Length: 0


2009/05/05 20:49:03.665	  0:17.422	Opal Liste...0xb7a7db70	Opal	Transport clean up on termination
2009/05/05 20:49:03.665	  0:17.422	Opal Liste...0xb7a7db70	Opal	Transport Close
2009/05/05 20:49:03.665	  0:17.422	Opal Liste...0xb7a7db70	Opal	Deleted transport udp$198.65.166.131:5060<if=udp$69.196.166.233:60426>
2009/05/05 20:49:03.668	  0:17.425	Opal Liste...0xb7a7db70	OpalUDP	Binding to interface: 69.196.166.233:60426
2009/05/05 20:49:03.670	  0:17.427	  Aggregator:0xb4fedb70	SIP	Transaction 1 INVITE proceeding.
2009/05/05 20:49:03.670	  0:17.427	  Aggregator:0xb4fedb70	SIP	Received Trying response
2009/05/05 20:49:03.671	  0:17.428	  Aggregator:0xb4fedb70	OpalCon	SetPhase from SetUpPhase to ProceedingPhase for Call[f881717d83]-EP<sip>[1c9e455c-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:49:03.671	  0:17.428	  Aggregator:0xb4fedb70	OpalMan	OnProceeding Call[f881717d83]-EP<sip>[1c9e455c-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:49:03.671	  0:17.428	  Aggregator:0xb4fedb70	Call	OnProceeding Call[f881717d83]-EP<sip>[1c9e455c-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:49:03.668	  0:17.425	Opal Liste...0xb7a7db70	SIP	Waiting for PDU on udp$198.65.166.131:5060<if=udp$69.196.166.233:60426>
2009/05/05 20:49:03.674	  0:17.431	Opal Liste...0xb7a7db70	SIP	PDU received: rem=udp$198.65.166.131:5060,local=udp$69.196.166.233:60426,if=192.168.1.12%wlan0
SIP/2.0 200 OK
CSeq: 1 INVITE
Via: SIP/2.0/UDP 69.196.166.233:60426;branch=z9hG4bKdc094c5c-4538-de11-9fe8-00904b4fcd2b;rport=60426
User-Agent: SIPCallProxy1
From: "Jesse Kahtava" <sip:17476942064@proxy01.sipphone.com>;tag=1c9e455c-4538-de11-9fe8-00904b4fcd2b
RemoteIP: 130.94.88.94
Call-ID: faba455c-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
To: <sip:echo@proxy01.sipphone.com>;tag=as75ca7426
Contact: <sip:echo@130.94.88.94:5060>
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Type: application/sdp
Content-Length: 240
Record-Route: <sip:198.65.166.131;lr;ftag=1c9e455c-4538-de11-9fe8-00904b4fcd2b>

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

2009/05/05 20:49:03.678	  0:17.435	  Aggregator:0xb4fedb70	SIP	Set Request URI to sip:echo@130.94.88.94:5060
2009/05/05 20:49:03.678	  0:17.435	  Aggregator:0xb4fedb70	SIP	Updating dialog tag from "" to "as75ca7426"
2009/05/05 20:49:03.679	  0:17.436	  Aggregator:0xb4fedb70	OpalUDP	Setting interface to 192.168.1.12%wlan0
2009/05/05 20:49:03.680	  0:17.437	  Aggregator:0xb4fedb70	SDP	Media session port=13438
2009/05/05 20:49:03.681	  0:17.438	  Aggregator:0xb4fedb70	SDP	Unknown media attribute silenceSupp
2009/05/05 20:49:03.681	  0:17.438	  Aggregator:0xb4fedb70	SDP	Parsed media session with 3 'audio' formats
2009/05/05 20:49:03.682	  0:17.439	  Aggregator:0xb4fedb70	SIP	Transaction remote address changed to udp$proxy01.sipphone.com:5060
2009/05/05 20:49:03.694	  0:17.451	  Aggregator:0xb4fedb70	SIP	Sending PDU (497 bytes) to: rem=udp$198.65.166.131:5060,local=udp$69.196.166.233:60426,if=192.168.1.12%wlan0
ACK sip:echo@130.94.88.94:5060 SIP/2.0
Route: <sip:proxy01.sipphone.com:5060;lr>
CSeq: 1 ACK
Via: SIP/2.0/UDP 69.196.166.233:60426;branch=z9hG4bK9e274d5d-4538-de11-9fe8-00904b4fcd2b;rport
From: "Jesse Kahtava" <sip:17476942064@proxy01.sipphone.com>;tag=1c9e455c-4538-de11-9fe8-00904b4fcd2b
Call-ID: faba455c-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
To: <sip:echo@proxy01.sipphone.com>;tag=as75ca7426
Contact: <sip:17476942064@69.196.166.233:60426>
Content-Length: 0
Max-Forwards: 70


2009/05/05 20:49:03.696	  0:17.453	Opal Liste...0xb7a7db70	Opal	Transport clean up on termination
2009/05/05 20:49:03.696	  0:17.453	Opal Liste...0xb7a7db70	Opal	Transport Close
2009/05/05 20:49:03.696	  0:17.453	Opal Liste...0xb7a7db70	Opal	Deleted transport udp$198.65.166.131:5060<if=udp$69.196.166.233:60426>
2009/05/05 20:49:03.698	  0:17.455	  Aggregator:0xb4fedb70	OpalUDP	Setting interface to 192.168.1.12%wlan0
2009/05/05 20:49:03.698	  0:17.455	  Aggregator:0xb4fedb70	SIP	Transaction 1 INVITE completed.
2009/05/05 20:49:03.699	  0:17.456	  Aggregator:0xb4fedb70	SIP	Handling 200 OK response for INVITE
2009/05/05 20:49:03.700	  0:17.457	  Aggregator:0xb4fedb70	SIP	Received INVITE OK response
2009/05/05 20:49:03.701	  0:17.458	  Aggregator:0xb4fedb70	SIP	Processing received SDP media description for audio
2009/05/05 20:49:03.701	  0:17.458	  Aggregator:0xb4fedb70	SIP	RTP payload type PCMU matched to codec G.711-uLaw-64k
2009/05/05 20:49:03.701	  0:17.458	  Aggregator:0xb4fedb70	SIP	RTP payload type PCMA matched to codec G.711-ALaw-64k
2009/05/05 20:49:03.701	  0:17.459	  Aggregator:0xb4fedb70	MediaFormat	Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz
2009/05/05 20:49:03.708	  0:17.465	  Aggregator:0xb4fedb70	Call	GetOtherPartyConnection Call[f881717d83]-EP<sip>[1c9e455c-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:49:03.709	  0:17.466	  Aggregator:0xb4fedb70	Call	IsMediaBypassPossible Call[f881717d83]-EP<sip>[1c9e455c-4538-de11-9fe8-00904b4fcd2b] session 1
2009/05/05 20:49:03.709	  0:17.466	  Aggregator:0xb4fedb70	OpalMan	IsMediaBypassPossible: session 1
2009/05/05 20:49:03.709	  0:17.467	  Aggregator:0xb4fedb70	OpalCon	IsMediaBypassPossible: default returns false
2009/05/05 20:49:03.710	  0:17.467	  Aggregator:0xb4fedb70	RTP	Found existing RTP session 1
2009/05/05 20:49:03.712	  0:17.469	  Aggregator:0xb4fedb70	RTP_UDP	Session 1, SetRemoteSocketInfo: data channel, new=130.94.88.94:13438, local=69.196.166.233:60126-60127, remote=0.0.0.0:0-0
2009/05/05 20:49:03.712	  0:17.469	  Aggregator:0xb4fedb70	Call	OpenSourceMediaStreams opening audio session 1 on Call[f881717d83]-EP<sip>[1c9e455c-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:49:03.743	  0:17.500	  Aggregator:0xb4fedb70	MediaFormat	Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz
2009/05/05 20:49:03.747	  0:17.504	  Aggregator:0xb4fedb70	MediaFormat	Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz
2009/05/05 20:49:03.749	  0:17.506	  Aggregator:0xb4fedb70	Call	GetMediaFormats for Call[f881717d83]-EP<sip>[1c9e455c-4538-de11-9fe8-00904b4fcd2b]
theora
H.261
H.261-CIF
H.261-QCIF
SpeexIETFWide-20.6k
SpeexWB
SpeexWide-20.6k
G.711-uLaw-64k
G.711-ALaw-64k
G.722-64k

2009/05/05 20:49:03.750	  0:17.507	  Aggregator:0xb4fedb70	MediaFormat	Merging G.711-uLaw-64k into G.711-uLaw-64k
2009/05/05 20:49:03.751	  0:17.508	  Aggregator:0xb4fedb70	MediaFormat	Merging PCM-16 into G.711-uLaw-64k
2009/05/05 20:49:03.751	  0:17.508	  Aggregator:0xb4fedb70	MediaFormat	Merging G.711-uLaw-64k into PCM-16
2009/05/05 20:49:03.751	  0:17.508	  Aggregator:0xb4fedb70	MediaFormat	Changed media option "Bandwidth-TIAS" from 128000 to 64000
2009/05/05 20:49:03.751	  0:17.508	  Aggregator:0xb4fedb70	MediaFormat	Changed media option "Max Bit Rate" from 128000 to 64000
2009/05/05 20:49:03.751	  0:17.509	  Aggregator:0xb4fedb70	Call	Selected media formats G.711-uLaw-64k -> PCM-16
2009/05/05 20:49:03.752	  0:17.509	  Aggregator:0xb4fedb70	Call	IsMediaBypassPossible Call[f881717d83]-EP<sip>[1c9e455c-4538-de11-9fe8-00904b4fcd2b] session 1
2009/05/05 20:49:03.752	  0:17.509	  Aggregator:0xb4fedb70	OpalMan	IsMediaBypassPossible: session 1
2009/05/05 20:49:03.752	  0:17.509	  Aggregator:0xb4fedb70	OpalCon	IsMediaBypassPossible: default returns false
2009/05/05 20:49:03.752	  0:17.509	  Aggregator:0xb4fedb70	RTP	Found existing media session 1
2009/05/05 20:49:03.754	  0:17.511	  Aggregator:0xb4fedb70	OpalMan	OnOpenMediaStream Call[f881717d83]-EP<sip>[1c9e455c-4538-de11-9fe8-00904b4fcd2b],OpalRTPMediaStream-Source-G.711-uLaw-64k
2009/05/05 20:49:03.755	  0:17.512	  Aggregator:0xb4fedb70	OpalCon	Opened source stream f881717d83_1 with format G.711-uLaw-64k
2009/05/05 20:49:03.756	  0:17.513	  Aggregator:0xb4fedb70	AudioOutputCore	Opening device[0] with 1-8000/16
2009/05/05 20:49:03.756	  0:17.513	  Aggregator:0xb4fedb70	GMAudioOutputManager_ptlib	Opening Device Default (PTLIB/ALSA)
2009/05/05 20:49:03.756	  0:17.513	  Aggregator:0xb4fedb70	GMAudioOutputManager_ptlib	Opening Device with 1-8000/16
2009/05/05 20:49:03.766	  0:17.523	  Aggregator:0xb4fedb70	Alsa	Buffer time is 16000
2009/05/05 20:49:03.766	  0:17.523	  Aggregator:0xb4fedb70	Alsa	Period time is 4000
2009/05/05 20:49:03.770	  0:17.527	  Aggregator:0xb4fedb70	ALSA	Device default Opened
2009/05/05 20:49:03.852	  0:17.610	  Aggregator:0xb4fedb70	Got volume 57080
2009/05/05 20:49:03.854	  0:17.611	  Aggregator:0xb4fedb70	PCSS	Opened mono sound channel "EKIGA" for playing at 8.0 kHz.
2009/05/05 20:49:03.855	  0:17.612	  Aggregator:0xb4fedb70	OpalMan	OnOpenMediaStream Call[f881717d83]-EP<pc>[h8a50f8244],OpalAudioMediaStream-Sink-PCM-16
2009/05/05 20:49:03.855	  0:17.612	  Aggregator:0xb4fedb70	OpalCon	Opened sink stream f881717d83_1 with format PCM-16
2009/05/05 20:49:03.855	  0:17.612	  Aggregator:0xb4fedb70	Patch	Created Sink: format=PCM-16
2009/05/05 20:49:03.855	  0:17.612	  Aggregator:0xb4fedb70	Codec	G711-uLaw-64k decoder created
2009/05/05 20:49:03.856	  0:17.613	  Aggregator:0xb4fedb70	Patch	Created primary codec G.711-uLaw-64k->PCM-16 with ID f881717d83_1
2009/05/05 20:49:03.856	  0:17.613	  Aggregator:0xb4fedb70	Media	Audio sink data size set to 3840 bytes and 5 buffers.
2009/05/05 20:49:03.856	  0:17.613	  Aggregator:0xb4fedb70	Media	Set data size from 16 to 3840
2009/05/05 20:49:03.856	  0:17.613	  Aggregator:0xb4fedb70	GMAudioOutputManager_ptlib	Setting buffer size of device[0] 3840/5
2009/05/05 20:49:03.856	  0:17.613	  Aggregator:0xb4fedb70	Patch	Added media stream sink OpalAudioMediaStream-Sink-PCM-16 using transcoder G.711-uLaw-64k->PCM-16, data size=3840
2009/05/05 20:49:03.856	  0:17.613	  Aggregator:0xb4fedb70	Media	RTP data size cannot be changed to 8, fixed at 2048
2009/05/05 20:49:03.857	  0:17.614	  Aggregator:0xb4fedb70	PCSS	Adding filters to patch
2009/05/05 20:49:03.857	  0:17.614	  Aggregator:0xb4fedb70	OpalCon	Source stream of connection Call[f881717d83]-EP<sip>[1c9e455c-4538-de11-9fe8-00904b4fcd2b] uses patch Patch OpalRTPMediaStream-Source-G.711-uLaw-64k -> OpalAudioMediaStream-Sink-PCM-16
2009/05/05 20:49:03.857	  0:17.614	  Aggregator:0xb4fedb70	RTPCon	Adding RFC2833 receive handler
2009/05/05 20:49:03.857	  0:17.614	  Aggregator:0xb4fedb70	RTPCon	Adding Cisco NSE receive handler
2009/05/05 20:49:03.858	  0:17.615	  Aggregator:0xb4fedb70	Call	GetOtherPartyConnection Call[f881717d83]-EP<sip>[1c9e455c-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:49:03.858	  0:17.615	  Aggregator:0xb4fedb70	Call	OpenSourceMediaStreams opening audio session 1 on Call[f881717d83]-EP<pc>[h8a50f8244]
2009/05/05 20:49:03.869	  0:17.626	  Aggregator:0xb4fedb70	MediaFormat	Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz
2009/05/05 20:49:03.872	  0:17.629	  Aggregator:0xb4fedb70	MediaFormat	Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz
2009/05/05 20:49:03.873	  0:17.630	  Aggregator:0xb4fedb70	Call	GetMediaFormats for Call[f881717d83]-EP<pc>[h8a50f8244]
G.711-uLaw-64k
G.711-ALaw-64k
T.38
UserInput/RFC2833

2009/05/05 20:49:03.874	  0:17.631	  Aggregator:0xb4fedb70	MediaFormat	Merging G.711-uLaw-64k into G.711-uLaw-64k
2009/05/05 20:49:03.874	  0:17.631	  Aggregator:0xb4fedb70	MediaFormat	Merging G.711-uLaw-64k into PCM-16
2009/05/05 20:49:03.874	  0:17.631	  Aggregator:0xb4fedb70	MediaFormat	Changed media option "Bandwidth-TIAS" from 128000 to 64000
2009/05/05 20:49:03.874	  0:17.631	  Aggregator:0xb4fedb70	MediaFormat	Changed media option "Max Bit Rate" from 128000 to 64000
2009/05/05 20:49:03.875	  0:17.632	  Aggregator:0xb4fedb70	MediaFormat	Merging PCM-16 into G.711-uLaw-64k
2009/05/05 20:49:03.875	  0:17.632	  Aggregator:0xb4fedb70	Call	Selected media formats PCM-16 -> G.711-uLaw-64k
2009/05/05 20:49:03.875	  0:17.632	  Aggregator:0xb4fedb70	AudioInputCore	Starting stream 1x8000/16
2009/05/05 20:49:03.875	  0:17.632	  Aggregator:0xb4fedb70	AudioInputCore	Opening device with 1-8000/16
2009/05/05 20:49:03.876	  0:17.633	  Aggregator:0xb4fedb70	GMAudioInputManager_ptlib	Opening Device Default (PTLIB/ALSA)
2009/05/05 20:49:03.876	  0:17.633	  Aggregator:0xb4fedb70	GMAudioInputManager_ptlib	Opening Device with 1-8000/16
2009/05/05 20:49:03.889	  0:17.646	  Aggregator:0xb4fedb70	Alsa	Buffer time is 16000
2009/05/05 20:49:03.889	  0:17.646	  Aggregator:0xb4fedb70	Alsa	Period time is 4000
2009/05/05 20:49:03.906	  0:17.663	  Aggregator:0xb4fedb70	ALSA	Device default Opened
2009/05/05 20:49:03.925	  0:17.682	Opal Liste...0xb7a7db70	PWLib	File handle high water mark set: 81 PUDPSocket
2009/05/05 20:49:03.925	  0:17.682	Opal Liste...0xb7a7db70	OpalUDP	Binding to interface: 69.196.166.233:60426
2009/05/05 20:49:03.926	  0:17.683	Opal Liste...0xb7a7db70	SIP	Waiting for PDU on udp$198.65.166.131:5060<if=udp$69.196.166.233:60426>
2009/05/05 20:49:03.930	  0:17.687	Opal Liste...0xb7a7db70	SIP	PDU received: rem=udp$198.65.166.131:5060,local=udp$69.196.166.233:60426,if=192.168.1.12%wlan0
SIP/2.0 200 OK
CSeq: 1 INVITE
Via: SIP/2.0/UDP 69.196.166.233:60426;branch=z9hG4bKdc094c5c-4538-de11-9fe8-00904b4fcd2b;rport=60426
User-Agent: SIPCallProxy1
From: "Jesse Kahtava" <sip:17476942064@proxy01.sipphone.com>;tag=1c9e455c-4538-de11-9fe8-00904b4fcd2b
RemoteIP: 130.94.88.94
Call-ID: faba455c-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
To: <sip:echo@proxy01.sipphone.com>;tag=as75ca7426
Contact: <sip:echo@130.94.88.94:5060>
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Type: application/sdp
Content-Length: 240
Record-Route: <sip:198.65.166.131;lr;ftag=1c9e455c-4538-de11-9fe8-00904b4fcd2b>

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

2009/05/05 20:49:03.933	  0:17.690	Opal Liste...0xb7a7db70	Opal	Transport clean up on termination
2009/05/05 20:49:03.933	  0:17.690	Opal Liste...0xb7a7db70	Opal	Transport Close
2009/05/05 20:49:03.933	  0:17.690	Opal Liste...0xb7a7db70	Opal	Deleted transport udp$198.65.166.131:5060<if=udp$69.196.166.233:60426>
2009/05/05 20:49:03.979	  0:17.736	  Aggregator:0xb4fedb70	Got volume 58327
2009/05/05 20:49:03.981	  0:17.738	  Aggregator:0xb4fedb70	PCSS	Opened mono sound channel "EKIGA" for recording at 8.0 kHz.
2009/05/05 20:49:03.982	  0:17.739	  Aggregator:0xb4fedb70	OpalMan	OnOpenMediaStream Call[f881717d83]-EP<pc>[h8a50f8244],OpalAudioMediaStream-Source-PCM-16
2009/05/05 20:49:03.982	  0:17.739	  Aggregator:0xb4fedb70	OpalCon	Opened source stream f881717d83_1 with format PCM-16
2009/05/05 20:49:03.983	  0:17.740	  Aggregator:0xb4fedb70	Call	IsMediaBypassPossible Call[f881717d83]-EP<sip>[1c9e455c-4538-de11-9fe8-00904b4fcd2b] session 1
2009/05/05 20:49:03.983	  0:17.740	  Aggregator:0xb4fedb70	OpalMan	IsMediaBypassPossible: session 1
2009/05/05 20:49:03.983	  0:17.740	  Aggregator:0xb4fedb70	OpalCon	IsMediaBypassPossible: default returns false
2009/05/05 20:49:03.983	  0:17.740	  Aggregator:0xb4fedb70	RTP	Found existing media session 1
2009/05/05 20:49:03.984	  0:17.741	  Aggregator:0xb4fedb70	OpalMan	OnOpenMediaStream Call[f881717d83]-EP<sip>[1c9e455c-4538-de11-9fe8-00904b4fcd2b],OpalRTPMediaStream-Sink-G.711-uLaw-64k
2009/05/05 20:49:03.984	  0:17.741	  Aggregator:0xb4fedb70	OpalCon	Opened sink stream f881717d83_1 with format G.711-uLaw-64k
2009/05/05 20:49:03.985	  0:17.742	  Aggregator:0xb4fedb70	Patch	Created Sink: format=G.711-uLaw-64k
2009/05/05 20:49:03.985	  0:17.742	  Aggregator:0xb4fedb70	Codec	G711-uLaw-64k encoder created
2009/05/05 20:49:03.985	  0:17.742	  Aggregator:0xb4fedb70	Patch	Created primary codec PCM-16->G.711-uLaw-64k with ID f881717d83_1
2009/05/05 20:49:03.985	  0:17.742	  Aggregator:0xb4fedb70	Media	RTP data size cannot be changed to 1920, fixed at 528
2009/05/05 20:49:03.985	  0:17.742	  Aggregator:0xb4fedb70	Patch	Added media stream sink OpalRTPMediaStream-Sink-G.711-uLaw-64k using transcoder PCM-16->G.711-uLaw-64k, data size=528
2009/05/05 20:49:03.986	  0:17.743	  Aggregator:0xb4fedb70	Media	Audio source data size set to 320 bytes and 5 buffers.
2009/05/05 20:49:03.986	  0:17.743	  Aggregator:0xb4fedb70	Media	Set data size from 16 to 320
2009/05/05 20:49:03.986	  0:17.743	  Aggregator:0xb4fedb70	AudioInputCore	Setting stream buffer size 5/320
2009/05/05 20:49:03.986	  0:17.743	  Aggregator:0xb4fedb70	GMAudioInputManager_ptlib	Setting buffer size to 320/5
2009/05/05 20:49:03.986	  0:17.743	  Aggregator:0xb4fedb70	PCSS	Adding filters to patch
2009/05/05 20:49:03.986	  0:17.743	  Aggregator:0xb4fedb70	OpalCon	Sink stream of connection Call[f881717d83]-EP<sip>[1c9e455c-4538-de11-9fe8-00904b4fcd2b] uses patch Patch OpalAudioMediaStream-Source-PCM-16 -> OpalRTPMediaStream-Sink-G.711-uLaw-64k
2009/05/05 20:49:03.987	  0:17.744	  Aggregator:0xb4fedb70	OpalCon	SetPhase from ProceedingPhase to ConnectedPhase for Call[f881717d83]-EP<sip>[1c9e455c-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:49:03.987	  0:17.744	  Aggregator:0xb4fedb70	OpalCon	OnConnected for Call[f881717d83]-EP<sip>[1c9e455c-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:49:03.987	  0:17.744	  Aggregator:0xb4fedb70	OpalMan	OnConnected Call[f881717d83]-EP<sip>[1c9e455c-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:49:03.987	  0:17.744	  Aggregator:0xb4fedb70	Call	OnConnected Call[f881717d83]-EP<sip>[1c9e455c-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:49:04.008	  0:17.765	  Aggregator:0xb4fedb70	OpalCon	SetConnected for Call[f881717d83]-EP<pc>[h8a50f8244]
2009/05/05 20:49:04.009	  0:17.766	  Aggregator:0xb4fedb70	OpalCon	SetPhase from SetUpPhase to ConnectedPhase for Call[f881717d83]-EP<pc>[h8a50f8244]
2009/05/05 20:49:04.009	  0:17.766	  Aggregator:0xb4fedb70	OpalCon	SetPhase from ConnectedPhase to EstablishedPhase for Call[f881717d83]-EP<pc>[h8a50f8244]
2009/05/05 20:49:04.009	  0:17.766	  Aggregator:0xb4fedb70	OpalCon	OnEstablished Call[f881717d83]-EP<pc>[h8a50f8244]
2009/05/05 20:49:04.010	  0:17.767	  Aggregator:0xb4fedb70	PWLib	File handle low water mark set: 77 Thread unblock pipe
2009/05/05 20:49:04.010	  0:17.767	 Media Patch:0xa85a8b70	Patch	Thread started for Patch OpalRTPMediaStream-Source-G.711-uLaw-64k -> OpalAudioMediaStream-Sink-PCM-16
2009/05/05 20:49:04.010	  0:17.767	 Media Patch:0xa85a8b70	InfLID	Setting jitter buffer time from 160 to 4000
2009/05/05 20:49:04.011	  0:17.768	 Media Patch:0xa85a8b70	RTP	Opal jitter buffer created: size=101 delay=20-500 ms  /160 (20ms) obj=0xb7910630
2009/05/05 20:49:04.012	  0:17.769	  RTP Jitter:0xa841eb70	RTP	Jitter RTP receive thread started: 0xb7910630
2009/05/05 20:49:04.012	  0:17.769	  Aggregator:0xb4fedb70	Media	Starting thread Media Patch:0xa85a8b70
2009/05/05 20:49:04.013	  0:17.770	 Media Patch:0xa83ddb70	Patch	Thread started for Patch OpalAudioMediaStream-Source-PCM-16 -> OpalRTPMediaStream-Sink-G.711-uLaw-64k
2009/05/05 20:49:04.013	  0:17.770	  Aggregator:0xb4fedb70	Media	Starting thread Media Patch:0xa83ddb70
2009/05/05 20:49:04.013	  0:17.770	  Aggregator:0xb4fedb70	OpalCon	Media stream threads started.
2009/05/05 20:49:04.013	  0:17.770	  Aggregator:0xb4fedb70	OpalMan	OnEstablished Call[f881717d83]-EP<pc>[h8a50f8244]
2009/05/05 20:49:04.014	  0:17.771	  Aggregator:0xb4fedb70	Call	OnEstablished Call[f881717d83]-EP<pc>[h8a50f8244]
2009/05/05 20:49:04.014	  0:17.771	  Aggregator:0xb4fedb70	OpalCon	Media stream threads started.
2009/05/05 20:49:04.015	  0:17.772	  Aggregator:0xb4fedb70	OpalCon	SetPhase from ConnectedPhase to EstablishedPhase for Call[f881717d83]-EP<sip>[1c9e455c-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:49:04.015	  0:17.773	  Aggregator:0xb4fedb70	OpalCon	OnEstablished Call[f881717d83]-EP<sip>[1c9e455c-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:49:04.016	  0:17.773	  Aggregator:0xb4fedb70	OpalCon	Media stream threads started.
2009/05/05 20:49:04.016	  0:17.773	  Aggregator:0xb4fedb70	OpalMan	OnEstablished Call[f881717d83]-EP<sip>[1c9e455c-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:49:04.019	  0:17.776	 Media Patch:0xa83ddb70	Alsa	Buffer time is 50000
2009/05/05 20:49:04.020	  0:17.777	 Media Patch:0xa83ddb70	Alsa	Period time is 10000
2009/05/05 20:49:04.020	  0:17.777	 Media Patch:0xa85a8b70	Alsa	Buffer time is 600000
2009/05/05 20:49:04.020	  0:17.777	 Media Patch:0xa85a8b70	Alsa	Period time is 120000
2009/05/05 20:49:04.074	  0:17.831	 Media Patch:0xa83ddb70	RTP	Session 1, first sent data: ver=2 pt=PCMU psz=160 m=1 x=0 seq=7920 ts=0 src=54711445 ccnt=0
2009/05/05 20:49:04.080	  0:17.838	  RTP Jitter:0xa841eb70	RTP	Session 1, first receive data: ver=2 pt=PCMU psz=160 m=0 x=0 seq=43075 ts=749538306 src=31d6832e ccnt=0
2009/05/05 20:49:04.155	  0:17.912	  Aggregator:0xb4fedb70	RTP	Found existing RTP session 1
2009/05/05 20:49:04.157	  0:17.914	  Aggregator:0xb4fedb70	Call	OnEstablished Call[f881717d83]-EP<sip>[1c9e455c-4538-de11-9fe8-00904b4fcd2b]
2009/05/05 20:49:04.157	  0:17.914	  Aggregator:0xb4fedb70	OpalCon	Media stream threads started.
2009/05/05 20:49:04.158	  0:17.915	  Aggregator:0xb4fedb70	SIP	Transaction remote address changed to udp$proxy01.sipphone.com:5060
2009/05/05 20:49:04.164	  0:17.921	 Media Patch:0xa85a8b70	RTP	Jitter buffer length exceeded
2009/05/05 20:49:04.164	  0:17.921	 Media Patch:0xa85a8b70	RTP	Jitter buffer length exceed was prior to first write. Not increasing buffer size
2009/05/05 20:49:04.182	  0:17.939	  Aggregator:0xb4fedb70	PWLib	File handle high water mark set: 82 PUDPSocket
2009/05/05 20:49:04.191	  0:17.948	  Aggregator:0xb4fedb70	SIP	Sending PDU (497 bytes) to: rem=udp$198.65.166.131:5060,local=udp$69.196.166.233:60426,if=192.168.1.12%wlan0
ACK sip:echo@130.94.88.94:5060 SIP/2.0
Route: <sip:proxy01.sipphone.com:5060;lr>
CSeq: 1 ACK
Via: SIP/2.0/UDP 69.196.166.233:60426;branch=z9hG4bKcaff985d-4538-de11-9fe8-00904b4fcd2b;rport
From: "Jesse Kahtava" <sip:17476942064@proxy01.sipphone.com>;tag=1c9e455c-4538-de11-9fe8-00904b4fcd2b
Call-ID: faba455c-4538-de11-9fe8-00904b4fcd2b@flump.hornpipe
To: <sip:echo@proxy01.sipphone.com>;tag=as75ca7426
Contact: <sip:17476942064@69.196.166.233:60426>
Content-Length: 0
Max-Forwards: 70


2009/05/05 20:49:04.225	  0:17.982	  Aggregator:0xb4fedb70	OpalUDP	Setting interface to 192.168.1.12%wlan0
2009/05/05 20:49:04.244	  0:18.001	                       	AEScheduler	Removing Event incoming_call_sound from queue
2009/05/05 20:49:04.245	  0:18.002	                       	AEScheduler	Removing Event ring_tone_sound from queue
2009/05/05 20:49:04.321	  0:18.078	 Media Patch:0xa85a8b70	RTP	Jitter buffer length exceeded
2009/05/05 20:49:04.323	  0:18.080	 Media Patch:0xa85a8b70	RTP	Jitter buffer size increased to 480 (60ms)
2009/05/05 20:49:04.480	  0:18.237	 Media Patch:0xa85a8b70	RTP	Jitter buffer length exceeded
2009/05/05 20:49:04.481	  0:18.238	 Media Patch:0xa85a8b70	RTP	Jitter buffer size increased to 640 (80ms)
2009/05/05 20:49:04.829	  0:18.586	 Media Patch:0xa85a8b70	RTP	Jitter buffer length exceeded
2009/05/05 20:49:04.829	  0:18.586	 Media Patch:0xa85a8b70	RTP	Jitter buffer size increased to 2560 (320ms)
2009/05/05 20:49:05.055	  0:18.812	 Media Patch:0xa83ddb70	RTP	Session 1, transmit statistics:  packets=51 octets=8160 avgTime=19 maxTime=94 minTime=1
2009/05/05 20:49:05.144	  0:18.901	 Media Patch:0xa85a8b70	RTP	Jitter buffer length exceeded
2009/05/05 20:49:05.144	  0:18.901	 Media Patch:0xa85a8b70	RTP	Jitter buffer size increased to 2720 (340ms)
2009/05/05 20:49:05.145	  0:18.902	  RTP Jitter:0xa841eb70	RTP	Session 1, receive statistics: packets=54 octets=8640 lost=0 tooLate=0 order=0 avgTime=20 maxTime=22 minTime=17 jitter=1 maxJitter=1
2009/05/05 20:49:05.226	  0:18.983	 Housekeeper:0xb7abeb70	SIP	Set state Terminated_Success for transaction 1 INVITE
2009/05/05 20:49:05.761	  0:19.518	 Media Patch:0xa85a8b70	RTP	Jitter buffer length exceeded
2009/05/05 20:49:05.761	  0:19.518	 Media Patch:0xa85a8b70	RTP	Jitter buffer size increased to 2880 (360ms)
2009/05/05 20:49:06.054	  0:19.811	 Media Patch:0xa83ddb70	RTP	Session 1, transmit statistics:  packets=101 octets=16160 avgTime=19 maxTime=34 minTime=1
2009/05/05 20:49:06.141	  0:19.898	  RTP Jitter:0xa841eb70	RTP	Session 1, receive statistics: packets=104 octets=16640 lost=0 tooLate=0 order=0 avgTime=19 maxTime=25 minTime=16 jitter=2 maxJitter=2
2009/05/05 20:49:06.172	  0:19.929	 Media Patch:0xa85a8b70	RTP	Jitter buffer length exceeded
2009/05/05 20:49:06.173	  0:19.930	 Media Patch:0xa85a8b70	RTP	Jitter buffer size increased to 3040 (380ms)
2009/05/05 20:49:07.045	  0:20.802	 Media Patch:0xa83ddb70	RTP	Session 1, transmit statistics:  packets=151 octets=24160 avgTime=19 maxTime=36 minTime=1
2009/05/05 20:49:07.138	  0:20.895	  RTP Jitter:0xa841eb70	RTP	Session 1, receive statistics: packets=154 octets=24640 lost=0 tooLate=0 order=0 avgTime=19 maxTime=24 minTime=15 jitter=1 maxJitter=2
2009/05/05 20:49:08.045	  0:21.802	 Media Patch:0xa83ddb70	RTP	Session 1, transmit statistics:  packets=201 octets=32160 avgTime=20 maxTime=38 minTime=1
2009/05/05 20:49:08.966	  0:22.723	  RTP Jitter:0xa841eb70	RTP	Session 1, receive statistics: packets=205 octets=32800 lost=0 tooLate=0 order=0 avgTime=20 maxTime=23 minTime=9 jitter=2 maxJitter=2
2009/05/05 20:49:09.050	  0:22.807	 Media Patch:0xa83ddb70	RTP	Session 1, transmit statistics:  packets=251 octets=40160 avgTime=20 maxTime=27 minTime=1
2009/05/05 20:49:09.190	  0:22.947	 Media Patch:0xa85a8b70	RTP	Jitter buffer length exceeded
2009/05/05 20:49:09.190	  0:22.948	 Media Patch:0xa85a8b70	RTP	Jitter buffer size increased to 3200 (400ms)
2009/05/05 20:49:09.963	  0:23.720	  RTP Jitter:0xa841eb70	RTP	Session 1, receive statistics: packets=255 octets=40800 lost=0 tooLate=0 order=0 avgTime=19 maxTime=22 minTime=17 jitter=1 maxJitter=2
2009/05/05 20:49:10.048	  0:23.805	 Media Patch:0xa83ddb70	RTP	Session 1, transmit statistics:  packets=301 octets=48160 avgTime=19 maxTime=47 minTime=1
2009/05/05 20:49:10.868	  0:24.625	Opal Garbage:0xb7c79b70	PWLib	Forcing termination of thread 0xb7b1d6f8
2009/05/05 20:49:10.888	  0:24.645	Opal Garbage:0xb7c79b70	PWLib	Destroyed thread 0xb7b1d6f8 RTP Jitter:0xa8567b70(id = a8567b70)
2009/05/05 20:49:10.889	  0:24.646	Opal Garbage:0xb7c79b70	RTP_UDP	Session 1, Shutting down write.
2009/05/05 20:49:10.889	  0:24.646	Opal Garbage:0xb7c79b70	RTP	Session 1, final statistics:
    packetsSent       = 408
    octetsSent        = 65280
    averageSendTime   = 19
    maximumSendTime   = 39
    minimumSendTime   = 1
    packetsReceived   = 398
    octetsReceived    = 63680
    packetsLost       = 0
    packetsTooLate    = 0
    packetOverruns    = 0
    packetsOutOfOrder = 0
    averageReceiveTime= 19
    maximumReceiveTime= 24
    minimumReceiveTime= 16
    averageJitter     = 1
    maximumJitter     = 6
