Re: [Ekiga-devel-list] No common audio codec



Hi, I attach the output.txt with speex 16k

2011/12/16 Ignacio Palacios <ig pal san gmail com>
Hi Eugen, thank you for your reply.

I can't do that untill the monday, but I have yet a output.txt, I don't remember what codec I used.

I attach the file.


2011/12/15 Eugen Dedu <Eugen Dedu pu-pm univ-fcomte fr>
On 15/12/11 14:33, Ignacio Palacios wrote:
Hi all!

I am doing calls between two computers, both with Ekiga 3.2.7, but I only
can do it with PCMU and PCMA audio codecs. With the others codecs Ekiga say
me "No common codecs", but it's wrong because I select  the same audio
codec in both computers. The video codec is disable.

It is unbelievable.  Could you select Speex16 for ex. on both computers and give us the -d 4 output of one of the computers when they show No common codec?

--
Eugen
_______________________________________________
ekiga-devel-list mailing list
ekiga-devel-list gnome org
http://mail.gnome.org/mailman/listinfo/ekiga-devel-list


2011/12/20 15:56:32.550	  0:00.065	                       		Version 3.2.7 by  on Unix Linux (2.6.35-22-generic-i686) with PTLib (v2.6.7) at 2011/12/20 15:56:32.549
2011/12/20 15:56:32.550	  0:00.066	                       	Ekiga git revision: unknown
2011/12/20 15:56:32.552	  0:00.068	                       	Ekiga registered on D-Bus: org.ekiga.Ekiga
2011/12/20 15:56:32.553	  0:00.069	                       	PWLib	File handle high water mark set: 19 Thread unblock pipe
2011/12/20 15:56:32.553	  0:00.069	                       	PTLib	No permission to set priority level 4
2011/12/20 15:56:32.553	  0:00.069	                       	PTLib	Thread high water mark set: 2
2011/12/20 15:56:32.553	  0:00.069	                       	PWLib	File handle high water mark set: 21 Thread unblock pipe
2011/12/20 15:56:32.553	  0:00.069	                       	PTLib	No permission to set priority level 4
2011/12/20 15:56:32.554	  0:00.069	                       	PTLib	Thread high water mark set: 3
2011/12/20 15:56:32.554	  0:00.070	                       	PWLib	File handle high water mark set: 23 Thread unblock pipe
2011/12/20 15:56:32.555	  0:00.070	                       	PWLib	File handle high water mark set: 25 Thread unblock pipe
2011/12/20 15:56:32.555	  0:00.071	                       	PTLib	No permission to set priority level 4
2011/12/20 15:56:32.556	  0:00.071	                       	PTLib	Thread high water mark set: 4
2011/12/20 15:56:32.556	  0:00.072	                       	HalManager_dbus	Initialising HAL Manager
2011/12/20 15:56:32.558	  0:00.074	                       	HalManager_dbus	Populating device list
2011/12/20 15:56:32.559	  0:00.074	                       	HalManager_dbus	Populating full device list failed - The name org.freedesktop.Hal was not provided by any .service files
2011/12/20 15:56:32.559	  0:00.075	                       	HalManager_dbus	Populating interface list
2011/12/20 15:56:32.560	  0:00.076	                       	HalManager_dbus	Populating full interface list failed - Method "getDevices" with signature "" on interface "org.freedesktop.NetworkManager" doesn't exist

2011/12/20 15:56:32.560	  0:00.076	                       	Detecting V4L2 devices
2011/12/20 15:56:32.560	  0:00.076	                       	PV4L2Plugin	detected device metadata at /sys/class/video4linux/
2011/12/20 15:56:32.577	  0:00.093	                       	PWLib	File handle high water mark set: 30 Thread unblock pipe
2011/12/20 15:56:32.578	  0:00.093	                       	PTLib	Thread high water mark set: 5
2011/12/20 15:56:32.578	  0:00.093	                       	OpalMan	Created manager.
2011/12/20 15:56:32.578	  0:00.094	                       	OpalMan	Attached endpoint with prefix pc
2011/12/20 15:56:32.578	  0:00.094	                       	OpalEP	Created endpoint: pc
2011/12/20 15:56:32.591	  0:00.107	                       	PCSS	Created PC sound system endpoint.
Players:
Default
HDA Intel
HDA Intel (1)
EKIGA
HDA NVidia
HDA NVidia (1)
HDA NVidia (2)
HDA NVidia (3)
*.wav
Recorders:
Default
HDA Intel
EKIGA
*.wav

2011/12/20 15:56:32.591	  0:00.107	                       	OPAL	SetMediaFormatOrder()
2011/12/20 15:56:32.591	  0:00.107	                       	OPAL	SetMediaFormatMask()
2011/12/20 15:56:32.592	  0:00.108	                       	OpalMan	Attached endpoint with prefix sip
2011/12/20 15:56:32.592	  0:00.108	                       	OpalEP	Created endpoint: sip
2011/12/20 15:56:32.592	  0:00.108	                       	PWLib	File handle high water mark set: 31 PUDPSocket
2011/12/20 15:56:32.593	  0:00.109	                       	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.35 [fe:80:00:00:00:00:00:00:02:26:b6:ff:fe:bd:8d:3e] <00-26-B6-BD-8D-3E> (wlan0)

2011/12/20 15:56:32.593	  0:00.109	                       	PWLib	File handle high water mark set: 32 Thread unblock pipe
2011/12/20 15:56:32.593	  0:00.109	                       	PTLib	Thread high water mark set: 6
2011/12/20 15:56:32.593	  0:00.109	Network In...0xb6a1db70	IfaceMon	Started interface monitor thread.
2011/12/20 15:56:32.593	  0:00.109	                       	PWLib	File handle high water mark set: 34 Thread unblock pipe
2011/12/20 15:56:32.593	  0:00.109	Network In...0xb6a1db70	PWLib	File handle high water mark set: 35 PUDPSocket
2011/12/20 15:56:32.593	  0:00.109	                       	PTLib	Thread high water mark set: 7
2011/12/20 15:56:32.593	  0:00.109	                       	SIP	Created endpoint.
2011/12/20 15:56:32.594	  0:00.110	                       	MonSock	Created socket bundle for all interfaces.
2011/12/20 15:56:32.594	  0:00.110	                       	PWLib	File handle high water mark set: 36 PUDPSocket
2011/12/20 15:56:32.594	  0:00.110	                       	MonSock	Created bundled UDP socket 192.168.1.35:5060
2011/12/20 15:56:32.594	  0:00.110	                       	PWLib	File handle high water mark set: 38 Thread unblock pipe
2011/12/20 15:56:32.594	  0:00.110	                       	PTLib	Thread high water mark set: 8
2011/12/20 15:56:32.594	  0:00.110	                       	PTLib	No permission to set priority level 4
2011/12/20 15:56:32.594	  0:00.110	Opal Liste...0xb699bb70	Listen	Started listening thread on udp$*:5060
2011/12/20 15:56:32.595	  0:00.111	                       	OpalMan	Added route "sip:.*=pc:*"
2011/12/20 15:56:32.595	  0:00.111	                       	OpalMan	Added route "pc:.*=sip:<da>"
2011/12/20 15:56:32.595	  0:00.111	                       	OpalMan	Attached endpoint with prefix h323
2011/12/20 15:56:32.595	  0:00.111	                       	OpalEP	Created endpoint: h323
2011/12/20 15:56:32.595	  0:00.111	                       	OpalMan	Attached endpoint with prefix h323s
2011/12/20 15:56:32.595	  0:00.111	                       	H323	Created endpoint.
2011/12/20 15:56:32.595	  0:00.111	                       	PWLib	File handle high water mark set: 39 PTCPSocket
2011/12/20 15:56:32.595	  0:00.111	                       	PWLib	File handle high water mark set: 41 Thread unblock pipe
2011/12/20 15:56:32.595	  0:00.111	                       	PTLib	Thread high water mark set: 9
2011/12/20 15:56:32.596	  0:00.111	Opal Liste...0xb695ab70	Listen	Started listening thread on tcp$*:1720
2011/12/20 15:56:32.596	  0:00.112	                       	OpalMan	Added route "h323:.*=pc:<db>"
2011/12/20 15:56:32.596	  0:00.112	                       	OpalMan	Added route "pc:.*=h323:<da>"
2011/12/20 15:56:32.596	  0:00.112	Opal Liste...0xb695ab70	Listen	Waiting on socket accept on tcp$*:1720
2011/12/20 15:56:32.605	  0:00.121	                       	MediaFormat	Removing codecs SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,H.261,H.261-CIF,H.261-QCIF
2011/12/20 15:56:32.605	  0:00.121	                       	OPAL	SetMediaFormatMask(PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,G.722-64k,PCM-16,G.711-ALaw-64k,G.711-uLaw-64k,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,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,iLBC,iLBC-13k3,iLBC-15k2,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz)
2011/12/20 15:56:32.605	  0:00.121	                       	OPAL	SetMediaFormatOrder(SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,H.261,H.261-CIF,H.261-QCIF)
2011/12/20 15:56:32.612	  0:00.128	                       	MediaFormat	Removing codecs SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,H.261,H.261-CIF,H.261-QCIF
2011/12/20 15:56:32.613	  0:00.129	                       	OPAL	SetMediaFormatMask(PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,G.722-64k,PCM-16,G.711-ALaw-64k,G.711-uLaw-64k,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,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,iLBC,iLBC-13k3,iLBC-15k2,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz)
2011/12/20 15:56:32.613	  0:00.129	                       	OPAL	SetMediaFormatOrder(SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,H.261,H.261-CIF,H.261-QCIF)
2011/12/20 15:56:32.626	  0:00.142	                       	PWLib	File handle high water mark set: 43 Thread unblock pipe
2011/12/20 15:56:32.626	  0:00.142	                       	PTLib	Thread high water mark set: 10
2011/12/20 15:56:32.626	  0:00.142	StunDetector:0xb6919b70	PWLib	File handle high water mark set: 44 PUDPSocket
2011/12/20 15:56:32.671	  0:00.187	                       	PWLib	File handle high water mark set: 48 PUDPSocket
2011/12/20 15:56:32.672	  0:00.188	                       	SIP	Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:500 ekiga net, id=361b6479-8829-e111-81bd-0026b6bd8d3e@ubuntu
2011/12/20 15:56:32.672	  0:00.188	                       	DNS	SRV Lookup ekiga.net service _sip._udp
2011/12/20 15:56:32.772	  0:00.288	                       	SIP	No SRV record found.
2011/12/20 15:56:32.976	  0:00.492	                       	OpalUDP	Binding to interface: 0.0.0.0:5060
2011/12/20 15:56:32.976	  0:00.492	                       	SIP	Created transport udp$86.64.162.35:5060<if=udp$*:5060>
2011/12/20 15:56:32.977	  0:00.493	                       	OpalUDP	Started connect to 86.64.162.35:5060
2011/12/20 15:56:32.977	  0:00.493	                       	OpalUDP	Writing to interface 0 - "192.168.1.35%wlan0"
2011/12/20 15:56:32.977	  0:00.493	                       	OpalMan	Listener interfaces: associated transport=None
    udp$192.168.1.35:5060
2011/12/20 15:56:33.891	  0:01.407	Opal Liste...0xb699bb70	PWLib	File handle high water mark set: 50 PUDPSocket
2011/12/20 15:56:33.892	  0:01.408	Opal Liste...0xb699bb70	OpalUDP	Binding to interface: 192.168.1.35:5060
2011/12/20 15:56:33.892	  0:01.408	Opal Liste...0xb699bb70	SIP	Waiting for PDU on udp$193.147.53.4:5060<if=udp$192.168.1.35:5060>
2011/12/20 15:56:33.893	  0:01.409	Opal Liste...0xb699bb70	SIP	PDU received: rem=udp$193.147.53.4:5060,local=udp$192.168.1.35:5060,if=192.168.1.35%wlan0
OPTIONS sip:58@83.55.44.186:11557 SIP/2.0
Date: Tue, 20 Dec 2011 14:55:15 GMT
CSeq: 102 OPTIONS
Via: SIP/2.0/UDP 193.147.53.4:5060;branch=z9hG4bK0ddbaa38;rport
User-Agent: Asterisk PBX
From: "asterisk" <sip:asterisk@193.147.53.4>;tag=as1f5165dd
Call-ID: 244daf820bd6e5ed6797ea5f593f96d8@193.147.53.4
To: <sip:58@83.55.44.186:11557>
Contact: <sip:asterisk@193.147.53.4>
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0
Max-Forwards: 70


2011/12/20 15:56:37.823	  0:05.339	StunDetector:0xb6919b70	PWLib	File handle low water mark set: 44 PUDPSocket
2011/12/20 15:56:38.103	  0:05.619	StunDetector:0xb6919b70	OPAL	STUN server "stun.ekiga.net" replies Cone NAT, external IP 83.55.44.186
2011/12/20 15:56:38.164	  0:05.680	                       	PWLib	File handle low water mark set: 42 PUDPSocket
2011/12/20 15:56:38.363	  0:05.879	                       	SIP	Transaction created.
2011/12/20 15:56:38.366	  0:05.882	                       	DNS	SRV Lookup ekiga.net service _sip._udp
2011/12/20 15:56:38.489	  0:06.005	                       	SIP	No SRV record found.
2011/12/20 15:56:38.490	  0:06.006	                       	SIP	Transaction remote address is udp$ekiga.net:5060
2011/12/20 15:56:38.490	  0:06.006	                       	SIP	Sending PDU (545 bytes) to: rem=udp$86.64.162.35:5060,local=udp$192.168.1.35:5060,if=192.168.1.35%wlan0
SUBSCRIBE sip:500 ekiga net SIP/2.0
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 83.55.44.186:5060;branch=z9hG4bKf2c4c87c-8829-e111-81bd-0026b6bd8d3e;rport
User-Agent: Ekiga/3.2.7
From: "Naxo" <sip:naxo@83.55.44.186>;tag=6e85c87c-8829-e111-81bd-0026b6bd8d3e
Call-ID: 361b6479-8829-e111-81bd-0026b6bd8d3e@ubuntu
To: <sip:500 ekiga net>
Contact: <sip:naxo@83.55.44.186>
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


2011/12/20 15:56:38.491	  0:06.007	                       	OpalUDP	Setting interface to 192.168.1.35%wlan0
2011/12/20 15:56:38.491	  0:06.007	                       	SIP	Transaction timers set: retry=0.500, completion=6.000
2011/12/20 15:56:38.492	  0:06.007	                       	SIP	Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:500 ekiga net, id=8825dc7c-8829-e111-81bd-0026b6bd8d3e@ubuntu
2011/12/20 15:56:38.492	  0:06.008	                       	DNS	SRV Lookup ekiga.net service _sip._udp
2011/12/20 15:56:38.613	  0:06.128	                       	SIP	No SRV record found.
2011/12/20 15:56:38.613	  0:06.129	                       	OpalUDP	Binding to interface: 0.0.0.0:5060
2011/12/20 15:56:38.613	  0:06.129	                       	SIP	Created transport udp$86.64.162.35:5060<if=udp$*:5060>
2011/12/20 15:56:38.614	  0:06.130	                       	OpalUDP	Started connect to 86.64.162.35:5060
2011/12/20 15:56:38.614	  0:06.130	                       	OpalUDP	Writing to interface 0 - "192.168.1.35%wlan0"
2011/12/20 15:56:38.615	  0:06.131	                       	OpalMan	Listener interfaces: associated transport=None
    udp$192.168.1.35:5060
2011/12/20 15:56:38.616	  0:06.132	                       	SIP	Transaction created.
2011/12/20 15:56:38.620	  0:06.136	                       	DNS	SRV Lookup ekiga.net service _sip._udp
2011/12/20 15:56:38.721	  0:06.237	                       	SIP	No SRV record found.
2011/12/20 15:56:38.721	  0:06.237	                       	SIP	Transaction remote address is udp$ekiga.net:5060
2011/12/20 15:56:38.721	  0:06.237	                       	SIP	Sending PDU (556 bytes) to: rem=udp$86.64.162.35:5060,local=udp$192.168.1.35:5060,if=192.168.1.35%wlan0
SUBSCRIBE sip:500 ekiga net SIP/2.0
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 83.55.44.186:5060;branch=z9hG4bK6c6fef7c-8829-e111-81bd-0026b6bd8d3e;rport
User-Agent: Ekiga/3.2.7
From: "Naxo" <sip:naxo@83.55.44.186>;tag=722def7c-8829-e111-81bd-0026b6bd8d3e
Call-ID: 8825dc7c-8829-e111-81bd-0026b6bd8d3e@ubuntu
To: <sip:500 ekiga net>
Contact: <sip:500@83.55.44.186>
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


2011/12/20 15:56:38.722	  0:06.238	                       	OpalUDP	Setting interface to 192.168.1.35%wlan0
2011/12/20 15:56:38.722	  0:06.238	                       	SIP	Transaction timers set: retry=0.500, completion=6.000
2011/12/20 15:56:38.723	  0:06.239	                       	SIP	Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:501 ekiga net, id=a670ff7c-8829-e111-81bd-0026b6bd8d3e@ubuntu
2011/12/20 15:56:38.723	  0:06.239	                       	DNS	SRV Lookup ekiga.net service _sip._udp
2011/12/20 15:56:38.841	  0:06.357	                       	SIP	No SRV record found.
2011/12/20 15:56:38.841	  0:06.357	                       	OpalUDP	Binding to interface: 0.0.0.0:5060
2011/12/20 15:56:38.841	  0:06.357	                       	SIP	Created transport udp$86.64.162.35:5060<if=udp$*:5060>
2011/12/20 15:56:38.842	  0:06.358	                       	OpalUDP	Started connect to 86.64.162.35:5060
2011/12/20 15:56:38.842	  0:06.358	                       	OpalUDP	Writing to interface 0 - "192.168.1.35%wlan0"
2011/12/20 15:56:38.843	  0:06.359	                       	OpalMan	Listener interfaces: associated transport=None
    udp$192.168.1.35:5060
2011/12/20 15:56:38.845	  0:06.361	                       	SIP	Transaction created.
2011/12/20 15:56:38.850	  0:06.366	                       	DNS	SRV Lookup ekiga.net service _sip._udp
2011/12/20 15:56:38.966	  0:06.482	                       	SIP	No SRV record found.
2011/12/20 15:56:38.967	  0:06.483	                       	SIP	Transaction remote address is udp$ekiga.net:5060
2011/12/20 15:56:38.967	  0:06.483	                       	SIP	Sending PDU (545 bytes) to: rem=udp$86.64.162.35:5060,local=udp$192.168.1.35:5060,if=192.168.1.35%wlan0
SUBSCRIBE sip:501 ekiga net SIP/2.0
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 83.55.44.186:5060;branch=z9hG4bK7282127d-8829-e111-81bd-0026b6bd8d3e;rport
User-Agent: Ekiga/3.2.7
From: "Naxo" <sip:naxo@83.55.44.186>;tag=ca1b127d-8829-e111-81bd-0026b6bd8d3e
Call-ID: a670ff7c-8829-e111-81bd-0026b6bd8d3e@ubuntu
To: <sip:501 ekiga net>
Contact: <sip:naxo@83.55.44.186>
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


2011/12/20 15:56:38.968	  0:06.484	                       	OpalUDP	Setting interface to 192.168.1.35%wlan0
2011/12/20 15:56:38.969	  0:06.485	                       	SIP	Transaction timers set: retry=0.500, completion=6.000
2011/12/20 15:56:38.970	  0:06.485	                       	SIP	Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:501 ekiga net, id=d214257d-8829-e111-81bd-0026b6bd8d3e@ubuntu
2011/12/20 15:56:38.970	  0:06.486	                       	DNS	SRV Lookup ekiga.net service _sip._udp
2011/12/20 15:56:38.991	  0:06.507	 Housekeeper:0xb69dcb70	SIP	SUBSCRIBE transaction id=z9hG4bKf2c4c87c-8829-e111-81bd-0026b6bd8d3e timeout, making retry 1, timeout 1.000
2011/12/20 15:56:38.992	  0:06.508	 Housekeeper:0xb69dcb70	SIP	Sending PDU (545 bytes) to: rem=udp$86.64.162.35:5060,local=udp$192.168.1.35:5060,if=192.168.1.35%wlan0
SUBSCRIBE sip:500 ekiga net SIP/2.0
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 83.55.44.186:5060;branch=z9hG4bKf2c4c87c-8829-e111-81bd-0026b6bd8d3e;rport
User-Agent: Ekiga/3.2.7
From: "Naxo" <sip:naxo@83.55.44.186>;tag=6e85c87c-8829-e111-81bd-0026b6bd8d3e
Call-ID: 361b6479-8829-e111-81bd-0026b6bd8d3e@ubuntu
To: <sip:500 ekiga net>
Contact: <sip:naxo@83.55.44.186>
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


2011/12/20 15:56:38.992	  0:06.508	 Housekeeper:0xb69dcb70	OpalUDP	Setting interface to 192.168.1.35%wlan0
2011/12/20 15:56:39.102	  0:06.618	                       	SIP	No SRV record found.
2011/12/20 15:56:39.103	  0:06.619	                       	OpalUDP	Binding to interface: 0.0.0.0:5060
2011/12/20 15:56:39.103	  0:06.619	                       	SIP	Created transport udp$86.64.162.35:5060<if=udp$*:5060>
2011/12/20 15:56:39.103	  0:06.619	                       	OpalUDP	Started connect to 86.64.162.35:5060
2011/12/20 15:56:39.103	  0:06.619	                       	OpalUDP	Writing to interface 0 - "192.168.1.35%wlan0"
2011/12/20 15:56:39.104	  0:06.619	                       	OpalMan	Listener interfaces: associated transport=None
    udp$192.168.1.35:5060
2011/12/20 15:56:39.194	  0:06.710	                       	SIP	Transaction created.
2011/12/20 15:56:39.196	  0:06.712	                       	DNS	SRV Lookup ekiga.net service _sip._udp
2011/12/20 15:56:39.213	  0:06.729	Opal Liste...0xb699bb70	SIP	Sending PDU (303 bytes) to: rem=udp$193.147.53.4:5060,local=udp$192.168.1.35:5060,if=192.168.1.35%wlan0
SIP/2.0 200 OK
CSeq: 102 OPTIONS
Via: SIP/2.0/UDP 193.147.53.4:5060;branch=z9hG4bK0ddbaa38;rport
From: "asterisk" <sip:asterisk@193.147.53.4>;tag=as1f5165dd
Call-ID: 244daf820bd6e5ed6797ea5f593f96d8@193.147.53.4
To: <sip:58@83.55.44.186:11557>
Contact: <sip:58@83.55.44.186>
Content-Length: 0


2011/12/20 15:56:39.213	  0:06.729	Opal Liste...0xb699bb70	OpalUDP	Setting interface to 192.168.1.35%wlan0
2011/12/20 15:56:39.214	  0:06.730	Opal Liste...0xb699bb70	Opal	Transport clean up on termination
2011/12/20 15:56:39.214	  0:06.730	Opal Liste...0xb699bb70	Opal	Transport Close
2011/12/20 15:56:39.214	  0:06.730	Opal Liste...0xb699bb70	Opal	Deleted transport udp$193.147.53.4:5060<if=udp$192.168.1.35:5060>
2011/12/20 15:56:39.215	  0:06.731	Opal Liste...0xb699bb70	OpalUDP	Binding to interface: 192.168.1.35:5060
2011/12/20 15:56:39.215	  0:06.731	Opal Liste...0xb699bb70	SIP	Waiting for PDU on udp$193.147.53.4:5060<if=udp$192.168.1.35:5060>
2011/12/20 15:56:39.216	  0:06.731	Opal Liste...0xb699bb70	SIP	PDU received: rem=udp$193.147.53.4:5060,local=udp$192.168.1.35:5060,if=192.168.1.35%wlan0
OPTIONS sip:58@83.55.44.186:11557 SIP/2.0
Date: Tue, 20 Dec 2011 14:55:15 GMT
CSeq: 102 OPTIONS
Via: SIP/2.0/UDP 193.147.53.4:5060;branch=z9hG4bK0ddbaa38;rport
User-Agent: Asterisk PBX
From: "asterisk" <sip:asterisk@193.147.53.4>;tag=as1f5165dd
Call-ID: 244daf820bd6e5ed6797ea5f593f96d8@193.147.53.4
To: <sip:58@83.55.44.186:11557>
Contact: <sip:asterisk@193.147.53.4>
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0
Max-Forwards: 70


2011/12/20 15:56:39.218	  0:06.734	Opal Liste...0xb699bb70	SIP	Sending PDU (303 bytes) to: rem=udp$193.147.53.4:5060,local=udp$192.168.1.35:5060,if=192.168.1.35%wlan0
SIP/2.0 200 OK
CSeq: 102 OPTIONS
Via: SIP/2.0/UDP 193.147.53.4:5060;branch=z9hG4bK0ddbaa38;rport
From: "asterisk" <sip:asterisk@193.147.53.4>;tag=as1f5165dd
Call-ID: 244daf820bd6e5ed6797ea5f593f96d8@193.147.53.4
To: <sip:58@83.55.44.186:11557>
Contact: <sip:58@83.55.44.186>
Content-Length: 0


2011/12/20 15:56:39.219	  0:06.735	Opal Liste...0xb699bb70	OpalUDP	Setting interface to 192.168.1.35%wlan0
2011/12/20 15:56:39.219	  0:06.735	Opal Liste...0xb699bb70	Opal	Transport clean up on termination
2011/12/20 15:56:39.219	  0:06.735	Opal Liste...0xb699bb70	Opal	Transport Close
2011/12/20 15:56:39.219	  0:06.735	Opal Liste...0xb699bb70	Opal	Deleted transport udp$193.147.53.4:5060<if=udp$192.168.1.35:5060>
2011/12/20 15:56:39.220	  0:06.736	Opal Liste...0xb699bb70	OpalUDP	Binding to interface: 192.168.1.35:5060
2011/12/20 15:56:39.220	  0:06.736	Opal Liste...0xb699bb70	SIP	Waiting for PDU on udp$193.147.53.4:5060<if=udp$192.168.1.35:5060>
2011/12/20 15:56:39.221	  0:06.736	Opal Liste...0xb699bb70	SIP	PDU received: rem=udp$193.147.53.4:5060,local=udp$192.168.1.35:5060,if=192.168.1.35%wlan0
OPTIONS sip:58@83.55.44.186:11557 SIP/2.0
Date: Tue, 20 Dec 2011 14:55:15 GMT
CSeq: 102 OPTIONS
Via: SIP/2.0/UDP 193.147.53.4:5060;branch=z9hG4bK0ddbaa38;rport
User-Agent: Asterisk PBX
From: "asterisk" <sip:asterisk@193.147.53.4>;tag=as1f5165dd
Call-ID: 244daf820bd6e5ed6797ea5f593f96d8@193.147.53.4
To: <sip:58@83.55.44.186:11557>
Contact: <sip:asterisk@193.147.53.4>
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0
Max-Forwards: 70


2011/12/20 15:56:39.223	  0:06.739	Opal Liste...0xb699bb70	SIP	Sending PDU (303 bytes) to: rem=udp$193.147.53.4:5060,local=udp$192.168.1.35:5060,if=192.168.1.35%wlan0
SIP/2.0 200 OK
CSeq: 102 OPTIONS
Via: SIP/2.0/UDP 193.147.53.4:5060;branch=z9hG4bK0ddbaa38;rport
From: "asterisk" <sip:asterisk@193.147.53.4>;tag=as1f5165dd
Call-ID: 244daf820bd6e5ed6797ea5f593f96d8@193.147.53.4
To: <sip:58@83.55.44.186:11557>
Contact: <sip:58@83.55.44.186>
Content-Length: 0


2011/12/20 15:56:39.224	  0:06.740	Opal Liste...0xb699bb70	OpalUDP	Setting interface to 192.168.1.35%wlan0
2011/12/20 15:56:39.224	  0:06.740	 Housekeeper:0xb69dcb70	SIP	SUBSCRIBE transaction id=z9hG4bK6c6fef7c-8829-e111-81bd-0026b6bd8d3e timeout, making retry 1, timeout 1.000
2011/12/20 15:56:39.224	  0:06.740	Opal Liste...0xb699bb70	Opal	Transport clean up on termination
2011/12/20 15:56:39.224	  0:06.740	Opal Liste...0xb699bb70	Opal	Transport Close
2011/12/20 15:56:39.224	  0:06.740	Opal Liste...0xb699bb70	Opal	Deleted transport udp$193.147.53.4:5060<if=udp$192.168.1.35:5060>
2011/12/20 15:56:39.224	  0:06.740	 Housekeeper:0xb69dcb70	SIP	Sending PDU (556 bytes) to: rem=udp$86.64.162.35:5060,local=udp$192.168.1.35:5060,if=192.168.1.35%wlan0
SUBSCRIBE sip:500 ekiga net SIP/2.0
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 83.55.44.186:5060;branch=z9hG4bK6c6fef7c-8829-e111-81bd-0026b6bd8d3e;rport
User-Agent: Ekiga/3.2.7
From: "Naxo" <sip:naxo@83.55.44.186>;tag=722def7c-8829-e111-81bd-0026b6bd8d3e
Call-ID: 8825dc7c-8829-e111-81bd-0026b6bd8d3e@ubuntu
To: <sip:500 ekiga net>
Contact: <sip:500@83.55.44.186>
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


2011/12/20 15:56:39.225	  0:06.741	 Housekeeper:0xb69dcb70	OpalUDP	Setting interface to 192.168.1.35%wlan0
2011/12/20 15:56:39.225	  0:06.741	Opal Liste...0xb699bb70	OpalUDP	Binding to interface: 192.168.1.35:5060
2011/12/20 15:56:39.225	  0:06.741	Opal Liste...0xb699bb70	SIP	Waiting for PDU on udp$193.147.53.4:5060<if=udp$192.168.1.35:5060>
2011/12/20 15:56:39.226	  0:06.742	Opal Liste...0xb699bb70	SIP	PDU received: rem=udp$193.147.53.4:5060,local=udp$192.168.1.35:5060,if=192.168.1.35%wlan0
OPTIONS sip:58@83.55.44.186:11557 SIP/2.0
Date: Tue, 20 Dec 2011 14:55:15 GMT
CSeq: 102 OPTIONS
Via: SIP/2.0/UDP 193.147.53.4:5060;branch=z9hG4bK0ddbaa38;rport
User-Agent: Asterisk PBX
From: "asterisk" <sip:asterisk@193.147.53.4>;tag=as1f5165dd
Call-ID: 244daf820bd6e5ed6797ea5f593f96d8@193.147.53.4
To: <sip:58@83.55.44.186:11557>
Contact: <sip:asterisk@193.147.53.4>
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0
Max-Forwards: 70


2011/12/20 15:56:39.228	  0:06.744	Opal Liste...0xb699bb70	SIP	Sending PDU (303 bytes) to: rem=udp$193.147.53.4:5060,local=udp$192.168.1.35:5060,if=192.168.1.35%wlan0
SIP/2.0 200 OK
CSeq: 102 OPTIONS
Via: SIP/2.0/UDP 193.147.53.4:5060;branch=z9hG4bK0ddbaa38;rport
From: "asterisk" <sip:asterisk@193.147.53.4>;tag=as1f5165dd
Call-ID: 244daf820bd6e5ed6797ea5f593f96d8@193.147.53.4
To: <sip:58@83.55.44.186:11557>
Contact: <sip:58@83.55.44.186>
Content-Length: 0


2011/12/20 15:56:39.229	  0:06.745	Opal Liste...0xb699bb70	OpalUDP	Setting interface to 192.168.1.35%wlan0
2011/12/20 15:56:39.229	  0:06.745	Opal Liste...0xb699bb70	Opal	Transport clean up on termination
2011/12/20 15:56:39.229	  0:06.745	Opal Liste...0xb699bb70	Opal	Transport Close
2011/12/20 15:56:39.229	  0:06.745	Opal Liste...0xb699bb70	Opal	Deleted transport udp$193.147.53.4:5060<if=udp$192.168.1.35:5060>
2011/12/20 15:56:39.230	  0:06.746	Opal Liste...0xb699bb70	OpalUDP	Binding to interface: 192.168.1.35:5060
2011/12/20 15:56:39.230	  0:06.746	Opal Liste...0xb699bb70	SIP	Waiting for PDU on udp$193.147.53.4:5060<if=udp$192.168.1.35:5060>
2011/12/20 15:56:39.231	  0:06.747	Opal Liste...0xb699bb70	SIP	PDU received: rem=udp$193.147.53.4:5060,local=udp$192.168.1.35:5060,if=192.168.1.35%wlan0
OPTIONS sip:58@83.55.44.186:11557 SIP/2.0
Date: Tue, 20 Dec 2011 14:55:15 GMT
CSeq: 102 OPTIONS
Via: SIP/2.0/UDP 193.147.53.4:5060;branch=z9hG4bK0ddbaa38;rport
User-Agent: Asterisk PBX
From: "asterisk" <sip:asterisk@193.147.53.4>;tag=as1f5165dd
Call-ID: 244daf820bd6e5ed6797ea5f593f96d8@193.147.53.4
To: <sip:58@83.55.44.186:11557>
Contact: <sip:asterisk@193.147.53.4>
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0
Max-Forwards: 70


2011/12/20 15:56:39.232	  0:06.748	Opal Liste...0xb699bb70	SIP	Sending PDU (303 bytes) to: rem=udp$193.147.53.4:5060,local=udp$192.168.1.35:5060,if=192.168.1.35%wlan0
SIP/2.0 200 OK
CSeq: 102 OPTIONS
Via: SIP/2.0/UDP 193.147.53.4:5060;branch=z9hG4bK0ddbaa38;rport
From: "asterisk" <sip:asterisk@193.147.53.4>;tag=as1f5165dd
Call-ID: 244daf820bd6e5ed6797ea5f593f96d8@193.147.53.4
To: <sip:58@83.55.44.186:11557>
Contact: <sip:58@83.55.44.186>
Content-Length: 0


2011/12/20 15:56:39.233	  0:06.749	Opal Liste...0xb699bb70	OpalUDP	Setting interface to 192.168.1.35%wlan0
2011/12/20 15:56:39.233	  0:06.749	Opal Liste...0xb699bb70	Opal	Transport clean up on termination
2011/12/20 15:56:39.233	  0:06.749	Opal Liste...0xb699bb70	Opal	Transport Close
2011/12/20 15:56:39.233	  0:06.749	Opal Liste...0xb699bb70	Opal	Deleted transport udp$193.147.53.4:5060<if=udp$192.168.1.35:5060>
2011/12/20 15:56:39.234	  0:06.750	Opal Liste...0xb699bb70	OpalUDP	Binding to interface: 192.168.1.35:5060
2011/12/20 15:56:39.234	  0:06.750	Opal Liste...0xb699bb70	SIP	Waiting for PDU on udp$86.64.162.35:5060<if=udp$192.168.1.35:5060>
2011/12/20 15:56:39.234	  0:06.750	Opal Liste...0xb699bb70	SIP	PDU received: rem=udp$86.64.162.35:5060,local=udp$192.168.1.35:5060,if=192.168.1.35%wlan0
SIP/2.0 489 Event Not Supported
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 83.55.44.186:5060;branch=z9hG4bKf2c4c87c-8829-e111-81bd-0026b6bd8d3e;rport=11557
Server: Kamailio (1.5.3-notls (i386/linux))
From: "Naxo" <sip:naxo@83.55.44.186>;tag=6e85c87c-8829-e111-81bd-0026b6bd8d3e
Call-ID: 361b6479-8829-e111-81bd-0026b6bd8d3e@ubuntu
To: <sip:500 ekiga net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.0ee2
Content-Length: 0


2011/12/20 15:56:39.235	  0:06.750	Opal Liste...0xb699bb70	SIP	Queueing PDU "2 SUBSCRIBE <489>", transaction=z9hG4bKf2c4c87c-8829-e111-81bd-0026b6bd8d3e, token=361b6479-8829-e111-81bd-0026b6bd8d3e@ubuntu
2011/12/20 15:56:39.235	  0:06.751	Opal Liste...0xb699bb70	PTLib	No permission to set priority level 3
2011/12/20 15:56:39.235	  0:06.751	Opal Liste...0xb699bb70	Opal	Transport clean up on termination
2011/12/20 15:56:39.235	  0:06.751	Opal Liste...0xb699bb70	Opal	Transport Close
2011/12/20 15:56:39.235	  0:06.751	        Pool:0xb6919b70	SIP	Handling PDU "2 SUBSCRIBE <489>" for transaction=z9hG4bKf2c4c87c-8829-e111-81bd-0026b6bd8d3e
2011/12/20 15:56:39.235	  0:06.751	        Pool:0xb6919b70	SIP	SUBSCRIBE transaction id=z9hG4bKf2c4c87c-8829-e111-81bd-0026b6bd8d3e completed.
2011/12/20 15:56:39.235	  0:06.751	        Pool:0xb6919b70	OpalUDP	Setting interface to 192.168.1.35%wlan0
2011/12/20 15:56:39.235	  0:06.751	Opal Liste...0xb699bb70	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$192.168.1.35:5060>
2011/12/20 15:56:39.235	  0:06.751	        Pool:0xb6919b70	SIP	Not retrying SUBSCRIBE due to error response 489 Bad Event
2011/12/20 15:56:39.235	  0:06.751	        Pool:0xb6919b70	SIP	Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:500 ekiga net, id=361b6479-8829-e111-81bd-0026b6bd8d3e@ubuntu
2011/12/20 15:56:39.235	  0:06.751	        Pool:0xb6919b70	SIP	Handled PDU "2 SUBSCRIBE <489>"
2011/12/20 15:56:39.236	  0:06.752	Opal Liste...0xb699bb70	OpalUDP	Binding to interface: 192.168.1.35:5060
2011/12/20 15:56:39.236	  0:06.752	Opal Liste...0xb699bb70	SIP	Waiting for PDU on udp$86.64.162.35:5060<if=udp$192.168.1.35:5060>
2011/12/20 15:56:39.236	  0:06.752	Opal Liste...0xb699bb70	SIP	PDU received: rem=udp$86.64.162.35:5060,local=udp$192.168.1.35:5060,if=192.168.1.35%wlan0
SIP/2.0 489 Event Not Supported
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 83.55.44.186:5060;branch=z9hG4bK6c6fef7c-8829-e111-81bd-0026b6bd8d3e;rport=11557
Server: Kamailio (1.5.3-notls (i386/linux))
From: "Naxo" <sip:naxo@83.55.44.186>;tag=722def7c-8829-e111-81bd-0026b6bd8d3e
Call-ID: 8825dc7c-8829-e111-81bd-0026b6bd8d3e@ubuntu
To: <sip:500 ekiga net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.cd0e
Content-Length: 0


2011/12/20 15:56:39.237	  0:06.753	Opal Liste...0xb699bb70	SIP	Queueing PDU "2 SUBSCRIBE <489>", transaction=z9hG4bK6c6fef7c-8829-e111-81bd-0026b6bd8d3e, token=8825dc7c-8829-e111-81bd-0026b6bd8d3e@ubuntu
2011/12/20 15:56:39.237	  0:06.753	        Pool:0xb6919b70	SIP	Handling PDU "2 SUBSCRIBE <489>" for transaction=z9hG4bK6c6fef7c-8829-e111-81bd-0026b6bd8d3e
2011/12/20 15:56:39.237	  0:06.753	Opal Liste...0xb699bb70	Opal	Transport clean up on termination
2011/12/20 15:56:39.237	  0:06.753	Opal Liste...0xb699bb70	Opal	Transport Close
2011/12/20 15:56:39.237	  0:06.753	        Pool:0xb6919b70	SIP	SUBSCRIBE transaction id=z9hG4bK6c6fef7c-8829-e111-81bd-0026b6bd8d3e completed.
2011/12/20 15:56:39.237	  0:06.753	        Pool:0xb6919b70	OpalUDP	Setting interface to 192.168.1.35%wlan0
2011/12/20 15:56:39.237	  0:06.753	        Pool:0xb6919b70	SIP	Not retrying SUBSCRIBE due to error response 489 Bad Event
2011/12/20 15:56:39.238	  0:06.753	        Pool:0xb6919b70	SIP	Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:500 ekiga net, id=8825dc7c-8829-e111-81bd-0026b6bd8d3e@ubuntu
2011/12/20 15:56:39.238	  0:06.754	        Pool:0xb6919b70	SIP	Handled PDU "2 SUBSCRIBE <489>"
2011/12/20 15:56:39.237	  0:06.753	Opal Liste...0xb699bb70	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$192.168.1.35:5060>
2011/12/20 15:56:39.238	  0:06.754	Opal Liste...0xb699bb70	OpalUDP	Binding to interface: 192.168.1.35:5060
2011/12/20 15:56:39.238	  0:06.754	Opal Liste...0xb699bb70	SIP	Waiting for PDU on udp$86.64.162.35:5060<if=udp$192.168.1.35:5060>
2011/12/20 15:56:39.239	  0:06.755	Opal Liste...0xb699bb70	SIP	PDU received: rem=udp$86.64.162.35:5060,local=udp$192.168.1.35:5060,if=192.168.1.35%wlan0
SIP/2.0 489 Event Not Supported
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 83.55.44.186:5060;branch=z9hG4bK7282127d-8829-e111-81bd-0026b6bd8d3e;rport=11557
Server: Kamailio (1.5.3-notls (i386/linux))
From: "Naxo" <sip:naxo@83.55.44.186>;tag=ca1b127d-8829-e111-81bd-0026b6bd8d3e
Call-ID: a670ff7c-8829-e111-81bd-0026b6bd8d3e@ubuntu
To: <sip:501 ekiga net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.c04a
Content-Length: 0


2011/12/20 15:56:39.240	  0:06.756	Opal Liste...0xb699bb70	SIP	Queueing PDU "2 SUBSCRIBE <489>", transaction=z9hG4bK7282127d-8829-e111-81bd-0026b6bd8d3e, token=a670ff7c-8829-e111-81bd-0026b6bd8d3e@ubuntu
2011/12/20 15:56:39.240	  0:06.756	Opal Liste...0xb699bb70	Opal	Transport clean up on termination
2011/12/20 15:56:39.240	  0:06.756	Opal Liste...0xb699bb70	Opal	Transport Close
2011/12/20 15:56:39.240	  0:06.756	        Pool:0xb6919b70	SIP	Handling PDU "2 SUBSCRIBE <489>" for transaction=z9hG4bK7282127d-8829-e111-81bd-0026b6bd8d3e
2011/12/20 15:56:39.240	  0:06.756	        Pool:0xb6919b70	SIP	SUBSCRIBE transaction id=z9hG4bK7282127d-8829-e111-81bd-0026b6bd8d3e completed.
2011/12/20 15:56:39.240	  0:06.756	        Pool:0xb6919b70	OpalUDP	Setting interface to 192.168.1.35%wlan0
2011/12/20 15:56:39.240	  0:06.756	        Pool:0xb6919b70	SIP	Not retrying SUBSCRIBE due to error response 489 Bad Event
2011/12/20 15:56:39.240	  0:06.756	        Pool:0xb6919b70	SIP	Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:501 ekiga net, id=a670ff7c-8829-e111-81bd-0026b6bd8d3e@ubuntu
2011/12/20 15:56:39.240	  0:06.756	Opal Liste...0xb699bb70	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$192.168.1.35:5060>
2011/12/20 15:56:39.241	  0:06.756	        Pool:0xb6919b70	SIP	Handled PDU "2 SUBSCRIBE <489>"
2011/12/20 15:56:39.241	  0:06.757	Opal Liste...0xb699bb70	OpalUDP	Binding to interface: 192.168.1.35:5060
2011/12/20 15:56:39.241	  0:06.757	Opal Liste...0xb699bb70	SIP	Waiting for PDU on udp$86.64.162.35:5060<if=udp$192.168.1.35:5060>
2011/12/20 15:56:39.241	  0:06.757	Opal Liste...0xb699bb70	SIP	PDU received: rem=udp$86.64.162.35:5060,local=udp$192.168.1.35:5060,if=192.168.1.35%wlan0
SIP/2.0 489 Event Not Supported
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 83.55.44.186:5060;branch=z9hG4bKf2c4c87c-8829-e111-81bd-0026b6bd8d3e;rport=11557
Server: Kamailio (1.5.3-notls (i386/linux))
From: "Naxo" <sip:naxo@83.55.44.186>;tag=6e85c87c-8829-e111-81bd-0026b6bd8d3e
Call-ID: 361b6479-8829-e111-81bd-0026b6bd8d3e@ubuntu
To: <sip:500 ekiga net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.0ee2
Content-Length: 0


2011/12/20 15:56:39.242	  0:06.758	Opal Liste...0xb699bb70	SIP	Queueing PDU "2 SUBSCRIBE <489>", transaction=z9hG4bKf2c4c87c-8829-e111-81bd-0026b6bd8d3e, token=361b6479-8829-e111-81bd-0026b6bd8d3e@ubuntu
2011/12/20 15:56:39.242	  0:06.758	Opal Liste...0xb699bb70	Opal	Transport clean up on termination
2011/12/20 15:56:39.242	  0:06.758	Opal Liste...0xb699bb70	Opal	Transport Close
2011/12/20 15:56:39.242	  0:06.758	        Pool:0xb6919b70	SIP	Handling PDU "2 SUBSCRIBE <489>" for transaction=z9hG4bKf2c4c87c-8829-e111-81bd-0026b6bd8d3e
2011/12/20 15:56:39.242	  0:06.758	        Pool:0xb6919b70	SIP	Handled PDU "2 SUBSCRIBE <489>"
2011/12/20 15:56:39.242	  0:06.758	Opal Liste...0xb699bb70	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$192.168.1.35:5060>
2011/12/20 15:56:39.338	  0:06.854	                       	SIP	No SRV record found.
2011/12/20 15:56:39.339	  0:06.855	                       	SIP	Transaction remote address is udp$ekiga.net:5060
2011/12/20 15:56:39.339	  0:06.855	                       	SIP	Sending PDU (556 bytes) to: rem=udp$86.64.162.35:5060,local=udp$192.168.1.35:5060,if=192.168.1.35%wlan0
SUBSCRIBE sip:501 ekiga net SIP/2.0
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 83.55.44.186:5060;branch=z9hG4bKb492477d-8829-e111-81bd-0026b6bd8d3e;rport
User-Agent: Ekiga/3.2.7
From: "Naxo" <sip:naxo@83.55.44.186>;tag=f466477d-8829-e111-81bd-0026b6bd8d3e
Call-ID: d214257d-8829-e111-81bd-0026b6bd8d3e@ubuntu
To: <sip:501 ekiga net>
Contact: <sip:501@83.55.44.186>
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


2011/12/20 15:56:39.340	  0:06.856	                       	OpalUDP	Setting interface to 192.168.1.35%wlan0
2011/12/20 15:56:39.340	  0:06.856	                       	SIP	Transaction timers set: retry=0.500, completion=6.000
2011/12/20 15:56:39.340	  0:06.856	                       	VideoOutputCoreConfBridge	Updating video view
2011/12/20 15:56:39.341	  0:06.857	                       	VideoOutputCoreConfBridge	Updating zoom
2011/12/20 15:56:39.341	  0:06.857	                       	VideoOutputCoreConfBridge	Updating Video Settings
2011/12/20 15:56:39.342	  0:06.858	                       	VideoOutputCoreConfBridge	Updating Video Settings
2011/12/20 15:56:39.342	  0:06.858	                       	VideoOutputCoreConfBridge	Updating Video Settings
2011/12/20 15:56:39.342	  0:06.858	                       	VideoOutputCoreConfBridge	Updating Video Settings
2011/12/20 15:56:39.342	  0:06.858	                       	VidInputCoreConfBridge	Updating preview size and fps
2011/12/20 15:56:39.342	  0:06.858	                       	VidInputCore	Setting new preview config: 352x288/30
2011/12/20 15:56:39.345	  0:06.861	                       	VidInputCoreConfBridge	Updating preview size and fps
2011/12/20 15:56:39.345	  0:06.861	                       	VidInputCore	Setting new preview config: 352x288/30
2011/12/20 15:56:39.347	  0:06.863	                       	VidInputCoreConfBridge	Updating device
2011/12/20 15:56:39.348	  0:06.864	                       	VidInputCore	Setting device: USB2.0 UVC WebCam (PTLIB/V4L2)
2011/12/20 15:56:39.348	  0:06.864	                       	GMVideoInputManager_ptlib	Setting Device USB2.0 UVC WebCam (PTLIB/V4L2)
2011/12/20 15:56:39.348	  0:06.864	                       	VidInputCoreConfBridge	Updating device
2011/12/20 15:56:39.348	  0:06.864	                       	VidInputCore	Setting device: USB2.0 UVC WebCam (PTLIB/V4L2)
2011/12/20 15:56:39.348	  0:06.864	                       	GMVideoInputManager_ptlib	Setting Device USB2.0 UVC WebCam (PTLIB/V4L2)
2011/12/20 15:56:39.348	  0:06.864	                       	VidInputCoreConfBridge	Updating device
2011/12/20 15:56:39.348	  0:06.864	                       	VidInputCore	Setting device: USB2.0 UVC WebCam (PTLIB/V4L2)
2011/12/20 15:56:39.348	  0:06.864	                       	GMVideoInputManager_ptlib	Setting Device USB2.0 UVC WebCam (PTLIB/V4L2)
2011/12/20 15:56:39.348	  0:06.864	                       	VidInputCoreConfBridge	Updating image
2011/12/20 15:56:39.349	  0:06.864	                       	VidInputCoreConfBridge	Updating preview
2011/12/20 15:56:39.349	  0:06.865	                       	VidInputCore	Starting preview 352x288/30
2011/12/20 15:56:39.349	  0:06.865	                       	VidInputCore	Opening device with 352x288/30
2011/12/20 15:56:39.349	  0:06.865	                       	GMVideoInputManager_ptlib	Opening Device USB2.0 UVC WebCam (PTLIB/V4L2)
2011/12/20 15:56:39.349	  0:06.865	                       	GMVideoInputManager_ptlib	Opening Device with 352x288/30
2011/12/20 15:56:39.349	  0:06.865	                       	Detecting V4L2 devices
2011/12/20 15:56:39.349	  0:06.865	                       	PV4L2Plugin	detected device metadata at /sys/class/video4linux/
2011/12/20 15:56:39.350	  0:06.866	Opal Liste...0xb699bb70	OpalUDP	Binding to interface: 192.168.1.35:5060
2011/12/20 15:56:39.350	  0:06.866	Opal Liste...0xb699bb70	SIP	Waiting for PDU on udp$86.64.162.35:5060<if=udp$192.168.1.35:5060>
2011/12/20 15:56:39.351	  0:06.867	Opal Liste...0xb699bb70	SIP	PDU received: rem=udp$86.64.162.35:5060,local=udp$192.168.1.35:5060,if=192.168.1.35%wlan0
SIP/2.0 489 Event Not Supported
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 83.55.44.186:5060;branch=z9hG4bK6c6fef7c-8829-e111-81bd-0026b6bd8d3e;rport=11557
Server: Kamailio (1.5.3-notls (i386/linux))
From: "Naxo" <sip:naxo@83.55.44.186>;tag=722def7c-8829-e111-81bd-0026b6bd8d3e
Call-ID: 8825dc7c-8829-e111-81bd-0026b6bd8d3e@ubuntu
To: <sip:500 ekiga net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.cd0e
Content-Length: 0


2011/12/20 15:56:39.351	  0:06.867	Opal Liste...0xb699bb70	SIP	Queueing PDU "2 SUBSCRIBE <489>", transaction=z9hG4bK6c6fef7c-8829-e111-81bd-0026b6bd8d3e, token=8825dc7c-8829-e111-81bd-0026b6bd8d3e@ubuntu
2011/12/20 15:56:39.351	  0:06.867	Opal Liste...0xb699bb70	Opal	Transport clean up on termination
2011/12/20 15:56:39.352	  0:06.867	Opal Liste...0xb699bb70	Opal	Transport Close
2011/12/20 15:56:39.352	  0:06.868	        Pool:0xb6919b70	SIP	Handling PDU "2 SUBSCRIBE <489>" for transaction=z9hG4bK6c6fef7c-8829-e111-81bd-0026b6bd8d3e
2011/12/20 15:56:39.352	  0:06.868	        Pool:0xb6919b70	SIP	Handled PDU "2 SUBSCRIBE <489>"
2011/12/20 15:56:39.356	  0:06.872	Opal Liste...0xb699bb70	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$192.168.1.35:5060>
2011/12/20 15:56:39.365	  0:06.881	                       	PVidInDev	Open()	videoFd:-1
2011/12/20 15:56:39.365	  0:06.881	                       	PVidInDev	Close()	videoFd:-1  started:0
2011/12/20 15:56:39.365	  0:06.881	                       	Detecting V4L2 devices
2011/12/20 15:56:39.365	  0:06.881	                       	PV4L2Plugin	detected device metadata at /sys/class/video4linux/
2011/12/20 15:56:39.379	  0:06.895	                       	PVidInDev	Open()	devName:/dev/video0  videoFd:-1
2011/12/20 15:56:39.379	  0:06.895	                       	VideoInputDevice	S_STD failed : Argumento inválido
2011/12/20 15:56:39.379	  0:06.895	                       	PVidInDev	S_FMT failed : Argumento inválido
2011/12/20 15:56:39.379	  0:06.895	                       		used code of 842093913 for palette: YUV420P
2011/12/20 15:56:39.379	  0:06.895	                       	PVidDev	SetColourFormatConverter, want YUV420P trying YUV420P
2011/12/20 15:56:39.380	  0:06.895	                       	PVidInDev	S_FMT failed : Argumento inválido
2011/12/20 15:56:39.380	  0:06.895	                       		used code of 842093913 for palette: YUV420P
2011/12/20 15:56:39.380	  0:06.895	                       	PVidDev	SetColourFormatConverter, want YUV420P trying I420
2011/12/20 15:56:39.380	  0:06.896	                       	PVidDev	SetColourFormatConverter, want YUV420P trying IYUV
2011/12/20 15:56:39.380	  0:06.896	                       	PVidDev	SetColourFormatConverter, want YUV420P trying YUV420
2011/12/20 15:56:39.380	  0:06.896	                       	PVidInDev	S_FMT failed : Argumento inválido
2011/12/20 15:56:39.380	  0:06.896	                       		used code of 825382478 for palette: YUV420
2011/12/20 15:56:39.380	  0:06.896	                       	PVidDev	SetColourFormatConverter, want YUV420P trying RGB32
2011/12/20 15:56:39.380	  0:06.896	                       	PVidInDev	S_FMT failed : Argumento inválido
2011/12/20 15:56:39.380	  0:06.896	                       		used code of 876758866 for palette: RGB32
2011/12/20 15:56:39.380	  0:06.896	                       	PVidDev	SetColourFormatConverter, want YUV420P trying BGR32
2011/12/20 15:56:39.380	  0:06.896	                       	PVidInDev	S_FMT failed : Argumento inválido
2011/12/20 15:56:39.380	  0:06.896	                       		used code of 877807426 for palette: BGR32
2011/12/20 15:56:39.380	  0:06.896	                       	PVidDev	SetColourFormatConverter, want YUV420P trying RGB24
2011/12/20 15:56:39.380	  0:06.896	                       	PVidInDev	S_FMT failed : Argumento inválido
2011/12/20 15:56:39.380	  0:06.896	                       		used code of 859981650 for palette: RGB24
2011/12/20 15:56:39.380	  0:06.896	                       	PVidDev	SetColourFormatConverter, want YUV420P trying BGR24
2011/12/20 15:56:39.380	  0:06.896	                       	PVidInDev	S_FMT failed : Argumento inválido
2011/12/20 15:56:39.380	  0:06.896	                       		used code of 861030210 for palette: BGR24
2011/12/20 15:56:39.380	  0:06.896	                       	PVidDev	SetColourFormatConverter, want YUV420P trying YUY2
2011/12/20 15:56:39.387	  0:06.903	                       	PVidDev	SetColourFormatConverter set camera to YUY2
2011/12/20 15:56:39.387	  0:06.903	                       	PVidDev	SetColourFormatConverter succeeded for YUV420P and device using YUY2
2011/12/20 15:56:39.393	  0:06.909	                       	PVidDev	SetFrameSize to 352x288
2011/12/20 15:56:39.396	  0:06.912	                       	PVidInDev	frame size mismatch.
2011/12/20 15:56:39.399	  0:06.915	                       	PVidDev	SetFrameSize to 320x240
2011/12/20 15:56:39.400	  0:06.916	                       	PVidInDev	VerifyHardwareFrameSize failed for size 352x288
2011/12/20 15:56:39.403	  0:06.919	                       	PVidDev	SetFrameSize to 704x576
2011/12/20 15:56:39.406	  0:06.922	                       	PVidInDev	frame size mismatch.
2011/12/20 15:56:39.409	  0:06.925	                       	PVidDev	SetFrameSize to 640x480
2011/12/20 15:56:39.409	  0:06.925	                       	PVidInDev	VerifyHardwareFrameSize failed for size 704x576
2011/12/20 15:56:39.418	  0:06.934	                       	PVidDev	Colour converter used from 640x480 [YUY2] to 352x288 [YUV420P]
2011/12/20 15:56:39.451	  0:06.967	Opal Liste...0xb699bb70	OpalUDP	Binding to interface: 192.168.1.35:5060
2011/12/20 15:56:39.452	  0:06.967	Opal Liste...0xb699bb70	SIP	Waiting for PDU on udp$86.64.162.35:5060<if=udp$192.168.1.35:5060>
2011/12/20 15:56:39.452	  0:06.968	Opal Liste...0xb699bb70	SIP	PDU received: rem=udp$86.64.162.35:5060,local=udp$192.168.1.35:5060,if=192.168.1.35%wlan0
SIP/2.0 489 Event Not Supported
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 83.55.44.186:5060;branch=z9hG4bKb492477d-8829-e111-81bd-0026b6bd8d3e;rport=11557
Server: Kamailio (1.5.3-notls (i386/linux))
From: "Naxo" <sip:naxo@83.55.44.186>;tag=f466477d-8829-e111-81bd-0026b6bd8d3e
Call-ID: d214257d-8829-e111-81bd-0026b6bd8d3e@ubuntu
To: <sip:501 ekiga net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.a286
Content-Length: 0


2011/12/20 15:56:39.452	  0:06.968	Opal Liste...0xb699bb70	SIP	Queueing PDU "2 SUBSCRIBE <489>", transaction=z9hG4bKb492477d-8829-e111-81bd-0026b6bd8d3e, token=d214257d-8829-e111-81bd-0026b6bd8d3e@ubuntu
2011/12/20 15:56:39.452	  0:06.968	Opal Liste...0xb699bb70	Opal	Transport clean up on termination
2011/12/20 15:56:39.452	  0:06.968	Opal Liste...0xb699bb70	Opal	Transport Close
2011/12/20 15:56:39.453	  0:06.968	        Pool:0xb6919b70	SIP	Handling PDU "2 SUBSCRIBE <489>" for transaction=z9hG4bKb492477d-8829-e111-81bd-0026b6bd8d3e
2011/12/20 15:56:39.453	  0:06.969	        Pool:0xb6919b70	SIP	SUBSCRIBE transaction id=z9hG4bKb492477d-8829-e111-81bd-0026b6bd8d3e completed.
2011/12/20 15:56:39.453	  0:06.969	        Pool:0xb6919b70	OpalUDP	Setting interface to 192.168.1.35%wlan0
2011/12/20 15:56:39.453	  0:06.969	Opal Liste...0xb699bb70	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$192.168.1.35:5060>
2011/12/20 15:56:39.453	  0:06.969	        Pool:0xb6919b70	SIP	Not retrying SUBSCRIBE due to error response 489 Bad Event
2011/12/20 15:56:39.453	  0:06.969	        Pool:0xb6919b70	SIP	Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:501 ekiga net, id=d214257d-8829-e111-81bd-0026b6bd8d3e@ubuntu
2011/12/20 15:56:39.453	  0:06.969	        Pool:0xb6919b70	SIP	Handled PDU "2 SUBSCRIBE <489>"
2011/12/20 15:56:39.462	  0:06.978	                       	PreviewManager	Starting Preview
2011/12/20 15:56:39.462	  0:06.978	                       	AudioOutputCoreConfBridge	Updating device
2011/12/20 15:56:39.462	  0:06.978	                       	AudioOutputCore	Setting device[0]: Default (PTLIB/ALSA)
2011/12/20 15:56:39.462	  0:06.978	                       	GMAudioOutputManager_ptlib	Setting Device[0] Default (PTLIB/ALSA)
2011/12/20 15:56:39.463	  0:06.979	                       	AudioOutputCoreConfBridge	Updating device
2011/12/20 15:56:39.463	  0:06.979	                       	AudioOutputCore	Setting device[1]: Default (PTLIB/ALSA)
2011/12/20 15:56:39.465	  0:06.981	                       	AudioInputCoreConfBridge	Updating device
2011/12/20 15:56:39.466	  0:06.981	                       	AudioInputCore	Setting device: Default (PTLIB/ALSA)
2011/12/20 15:56:39.466	  0:06.982	                       	GMAudioInputManager_ptlib	Setting Device Default (PTLIB/ALSA)
2011/12/20 15:56:39.543	  0:07.059	                       	Ekiga version 3.2.7
2011/12/20 15:56:39.543	  0:07.059	                       	OPAL version 3.6.8
2011/12/20 15:56:39.543	  0:07.059	                       	PTLIB version 2.6.7
2011/12/20 15:56:39.543	  0:07.059	                       	GNOME support disabled
2011/12/20 15:56:39.543	  0:07.059	                       	Accelerated rendering support enabled
2011/12/20 15:56:39.543	  0:07.059	                       	DBUS support enabled
2011/12/20 15:56:39.543	  0:07.059	                       	GConf support enabled
2011/12/20 15:56:39.543	  0:07.059	                       	ESound support disabled
2011/12/20 15:56:39.579	  0:07.095	Opal Garbage:0xb6a5eb70	SIP	Deleting SIPSubscribeHandler sip:500 ekiga net
2011/12/20 15:56:39.579	  0:07.095	Opal Garbage:0xb6a5eb70	Opal	Transport clean up on termination
2011/12/20 15:56:39.579	  0:07.095	Opal Garbage:0xb6a5eb70	Opal	Transport Close
2011/12/20 15:56:39.579	  0:07.095	GMVideoOut...0xb6a9fb70	GMVideoOutputManager_X	Widget not yet realized or gconf info not yet set, not opening display
2011/12/20 15:56:39.579	  0:07.095	Opal Garbage:0xb6a5eb70	Opal	Transport clean up on termination
2011/12/20 15:56:39.579	  0:07.095	Opal Garbage:0xb6a5eb70	Opal	Transport Close
2011/12/20 15:56:39.579	  0:07.095	Opal Garbage:0xb6a5eb70	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$192.168.1.35:5060>
2011/12/20 15:56:39.580	  0:07.096	Opal Garbage:0xb6a5eb70	SIP	Deleted handler.
2011/12/20 15:56:39.580	  0:07.096	Opal Garbage:0xb6a5eb70	SIP	Deleting SIPSubscribeHandler sip:500 ekiga net
2011/12/20 15:56:39.580	  0:07.096	Opal Garbage:0xb6a5eb70	Opal	Transport clean up on termination
2011/12/20 15:56:39.580	  0:07.096	Opal Garbage:0xb6a5eb70	Opal	Transport Close
2011/12/20 15:56:39.580	  0:07.096	Opal Garbage:0xb6a5eb70	Opal	Transport clean up on termination
2011/12/20 15:56:39.580	  0:07.096	Opal Garbage:0xb6a5eb70	Opal	Transport Close
2011/12/20 15:56:39.580	  0:07.096	Opal Garbage:0xb6a5eb70	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$192.168.1.35:5060>
2011/12/20 15:56:39.581	  0:07.097	Opal Garbage:0xb6a5eb70	SIP	Deleted handler.
2011/12/20 15:56:39.581	  0:07.097	Opal Garbage:0xb6a5eb70	SIP	Deleting SIPSubscribeHandler sip:501 ekiga net
2011/12/20 15:56:39.581	  0:07.097	Opal Garbage:0xb6a5eb70	Opal	Transport clean up on termination
2011/12/20 15:56:39.581	  0:07.097	Opal Garbage:0xb6a5eb70	Opal	Transport Close
2011/12/20 15:56:39.581	  0:07.097	Opal Garbage:0xb6a5eb70	Opal	Transport clean up on termination
2011/12/20 15:56:39.581	  0:07.097	Opal Garbage:0xb6a5eb70	Opal	Transport Close
2011/12/20 15:56:39.581	  0:07.097	Opal Garbage:0xb6a5eb70	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$192.168.1.35:5060>
2011/12/20 15:56:39.581	  0:07.097	Opal Garbage:0xb6a5eb70	SIP	Deleted handler.
2011/12/20 15:56:39.582	  0:07.098	Opal Garbage:0xb6a5eb70	SIP	Deleting SIPSubscribeHandler sip:501 ekiga net
2011/12/20 15:56:39.582	  0:07.098	Opal Garbage:0xb6a5eb70	Opal	Transport clean up on termination
2011/12/20 15:56:39.582	  0:07.098	Opal Garbage:0xb6a5eb70	Opal	Transport Close
2011/12/20 15:56:39.582	  0:07.098	Opal Garbage:0xb6a5eb70	Opal	Transport clean up on termination
2011/12/20 15:56:39.582	  0:07.098	Opal Garbage:0xb6a5eb70	Opal	Transport Close
2011/12/20 15:56:39.582	  0:07.098	Opal Garbage:0xb6a5eb70	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$192.168.1.35:5060>
2011/12/20 15:56:39.582	  0:07.098	Opal Garbage:0xb6a5eb70	SIP	Deleted handler.
2011/12/20 15:56:39.659	  0:07.175	GMVideoOut...0xb6a9fb70	GMVideoOutputManager_X	Widget not yet realized or gconf info not yet set, not opening display
2011/12/20 15:56:39.739	  0:07.255	GMVideoOut...0xb6a9fb70	GMVideoOutputManager_X	Opening VO_MODE_LOCAL display with image of 352x288
2011/12/20 15:56:39.740	  0:07.256	GMVideoOut...0xb6a9fb70	XVideo	Initializing XV window with 352x288 at 26,26
2011/12/20 15:56:39.745	  0:07.261	GMVideoOut...0xb6a9fb70	XVideo	XvQueryExtension: Version: 2 Release: 2 Request Base: 132 Event Base: 72 Error Base: 140
2011/12/20 15:56:39.745	  0:07.261	GMVideoOut...0xb6a9fb70	XVideo	#0, Adaptor: Nouveau GeForce 8/9 Textured Video, type: input | image | , ports: 32, first port: 63
2011/12/20 15:56:39.746	  0:07.262	GMVideoOut...0xb6a9fb70	XVideo	Encoding List for Port 63:  id=0 name=XV_IMAGE size=4096x4096 numerator=1 denominator=1
2011/12/20 15:56:39.746	  0:07.262	GMVideoOut...0xb6a9fb70	XVideo	Attribute List for Port 63:
2011/12/20 15:56:39.746	  0:07.262	GMVideoOut...0xb6a9fb70	  name:       XV_SET_DEFAULTS
2011/12/20 15:56:39.746	  0:07.262	GMVideoOut...0xb6a9fb70	  flags:      set
2011/12/20 15:56:39.746	  0:07.262	GMVideoOut...0xb6a9fb70	  min_color:  0
2011/12/20 15:56:39.747	  0:07.262	GMVideoOut...0xb6a9fb70	  max_color:  0
2011/12/20 15:56:39.747	  0:07.263	GMVideoOut...0xb6a9fb70	  name:       XV_SYNC_TO_VBLANK
2011/12/20 15:56:39.747	  0:07.263	GMVideoOut...0xb6a9fb70	  flags:      get set
2011/12/20 15:56:39.747	  0:07.263	GMVideoOut...0xb6a9fb70	  min_color:  0
2011/12/20 15:56:39.747	  0:07.263	GMVideoOut...0xb6a9fb70	  max_color:  1
2011/12/20 15:56:39.747	  0:07.263	GMVideoOut...0xb6a9fb70	XVideo	Image format list for Port 63:
2011/12/20 15:56:39.747	  0:07.263	GMVideoOut...0xb6a9fb70	  0x32315659 (YV12) planar, order: YVU
2011/12/20 15:56:39.747	  0:07.263	GMVideoOut...0xb6a9fb70	  0x30323449 (I420) planar, order: YUV
2011/12/20 15:56:39.747	  0:07.263	GMVideoOut...0xb6a9fb70	  0x32595559 (YUY2) packed, order: YUYV
2011/12/20 15:56:39.748	  0:07.264	GMVideoOut...0xb6a9fb70	XVideo	Grabbed Port: 63
2011/12/20 15:56:39.748	  0:07.264	GMVideoOut...0xb6a9fb70	XVideo	Using XVideo port: 63
2011/12/20 15:56:39.755	  0:07.271	GMVideoOut...0xb6a9fb70	XVideo	Found visual with colordepth of 24bits per pixel
2011/12/20 15:56:39.755	  0:07.271	GMVideoOut...0xb6a9fb70	X11	Created Window with ID 77594626
2011/12/20 15:56:39.755	  0:07.271	GMVideoOut...0xb6a9fb70	XVideo	Colorkey method: NONE
2011/12/20 15:56:39.756	  0:07.271	GMVideoOut...0xb6a9fb70	XVideo	Vertical sync successfully activated
2011/12/20 15:56:39.757	  0:07.273	GMVideoOut...0xb6a9fb70	XVideo	XQueryShmExtension success
2011/12/20 15:56:39.757	  0:07.273	GMVideoOut...0xb6a9fb70	XVideo	Created XvImage (352x288, data size: 152064, num_planes: 3
2011/12/20 15:56:39.757	  0:07.273	GMVideoOut...0xb6a9fb70	XVideo	  Plane 0: pitch=352, offset=0
2011/12/20 15:56:39.757	  0:07.273	GMVideoOut...0xb6a9fb70	XVideo	  Plane 1: pitch=176, offset=101376
2011/12/20 15:56:39.757	  0:07.273	GMVideoOut...0xb6a9fb70	XVideo	  Plane 2: pitch=176, offset=126720
2011/12/20 15:56:39.758	  0:07.274	GMVideoOut...0xb6a9fb70	XVideo	Using SHM extension
2011/12/20 15:56:39.758	  0:07.274	GMVideoOut...0xb6a9fb70	X11	Unknown wm type...
2011/12/20 15:56:39.758	  0:07.274	GMVideoOut...0xb6a9fb70	GMVideoOutputManager_X	VO_MODE_LOCAL: Successfully opened XV Window
2011/12/20 15:56:39.758	  0:07.274	GMVideoOut...0xb6a9fb70	X11	Unknown X Event 19 received
2011/12/20 15:56:40.241	  0:07.757	 Housekeeper:0xb69dcb70	SIP	Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bK7282127d-8829-e111-81bd-0026b6bd8d3e
2011/12/20 15:56:40.242	  0:07.758	 Housekeeper:0xb69dcb70	SIP	Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bKf2c4c87c-8829-e111-81bd-0026b6bd8d3e
2011/12/20 15:56:40.352	  0:07.868	 Housekeeper:0xb69dcb70	SIP	Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bK6c6fef7c-8829-e111-81bd-0026b6bd8d3e
2011/12/20 15:56:40.454	  0:07.970	 Housekeeper:0xb69dcb70	SIP	Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bKb492477d-8829-e111-81bd-0026b6bd8d3e
2011/12/20 15:56:40.583	  0:08.099	Opal Garbage:0xb6a5eb70	SIP	Transaction id=z9hG4bK6c6fef7c-8829-e111-81bd-0026b6bd8d3e destroyed.
2011/12/20 15:56:40.583	  0:08.099	Opal Garbage:0xb6a5eb70	SIP	Transaction id=z9hG4bK7282127d-8829-e111-81bd-0026b6bd8d3e destroyed.
2011/12/20 15:56:40.584	  0:08.099	Opal Garbage:0xb6a5eb70	SIP	Transaction id=z9hG4bKf2c4c87c-8829-e111-81bd-0026b6bd8d3e destroyed.
2011/12/20 15:56:40.584	  0:08.100	Opal Garbage:0xb6a5eb70	SIP	Transaction id=z9hG4bKb492477d-8829-e111-81bd-0026b6bd8d3e destroyed.
2011/12/20 15:56:41.048	  0:08.564	                       	Listen	Stopping listening thread on udp$*:5060
2011/12/20 15:56:41.048	  0:08.564	                       	MonSock	Closed UDP socket 0xb6e05360
2011/12/20 15:56:41.048	  0:08.564	Opal Liste...0xb699bb70	Listen	UDP read error.
2011/12/20 15:56:41.069	  0:08.585	                       	MonSock	Deleting UDP socket 0xb6e05360
2011/12/20 15:56:41.069	  0:08.585	                       	PWLib	File handle low water mark set: 35 PUDPSocket
2011/12/20 15:56:41.069	  0:08.585	                       	MonSock	Created socket bundle for all interfaces.
2011/12/20 15:56:41.172	  0:08.688	                       	MonSock	Created bundled UDP socket via STUN, internal=192.168.1.35:5060, external=83.55.44.186:11557
2011/12/20 15:56:41.173	  0:08.689	                       	PTLib	No permission to set priority level 4
2011/12/20 15:56:41.173	  0:08.689	Opal Liste...0xb699bb70	Listen	Started listening thread on udp$*:5060
2011/12/20 15:56:41.173	  0:08.689	                       	Listen	Stopping listening thread on tcp$*:1720
2011/12/20 15:56:41.184	  0:08.700	Opal Liste...0xb695ab70	Listen	Started listening thread on tcp$*:1720
2011/12/20 15:56:41.184	  0:08.700	                       	PTLib	Thread high water mark set: 11
2011/12/20 15:56:41.184	  0:08.700	Opal Liste...0xb695ab70	Listen	Waiting on socket accept on tcp$*:1720
2011/12/20 15:56:41.184	  0:08.700	  subscriber:0xb39efb70	SIP	Start REGISTER
        aor=58@193.147.53.4
  registrar=193.147.53.4
    contact=
     authID=58
      realm=
     expire=3600
    restore=30
   minRetry=0.000
   maxRetry=0.000
2011/12/20 15:56:41.185	  0:08.701	  subscriber:0xb39efb70	SIP	Changing REGISTER handler from Unavailable to Subscribing, target=sip:58@193.147.53.4, id=aa16777e-8829-e111-81bd-0026b6bd8d3e@ubuntu
2011/12/20 15:56:41.186	  0:08.702	  subscriber:0xb39efb70	OpalUDP	Binding to interface: 0.0.0.0:5060
2011/12/20 15:56:41.186	  0:08.702	  subscriber:0xb39efb70	PWLib	File handle high water mark set: 51 PUDPSocket
2011/12/20 15:56:41.186	  0:08.702	  subscriber:0xb39efb70	SIP	Created transport udp$193.147.53.4:5060<if=udp$*:5060>
2011/12/20 15:56:41.187	  0:08.703	  subscriber:0xb39efb70	OpalUDP	Started connect to 193.147.53.4:5060
2011/12/20 15:56:41.187	  0:08.703	  subscriber:0xb39efb70	OpalUDP	Writing to interface 0 - "192.168.1.35%wlan0"
2011/12/20 15:56:41.282	  0:08.798	  subscriber:0xb39efb70	OpalMan	Listener interfaces: associated transport=udp$83.55.44.186:11557
    udp$83.55.44.186:11557,udp$83.55.44.186:5060,udp$192.168.1.35:5060
2011/12/20 15:56:41.283	  0:08.799	  subscriber:0xb39efb70	SIP	Transaction created.
2011/12/20 15:56:41.285	  0:08.801	  subscriber:0xb39efb70	SIP	Transaction remote address is udp$193.147.53.4:5060
2011/12/20 15:56:41.286	  0:08.801	  subscriber:0xb39efb70	SIP	Sending PDU (560 bytes) to: rem=udp$193.147.53.4:5060,local=udp$83.55.44.186:11557,if=192.168.1.35%wlan0
REGISTER sip:193.147.53.4 SIP/2.0
CSeq: 1 REGISTER
Via: SIP/2.0/UDP 83.55.44.186:11557;branch=z9hG4bK9c65867e-8829-e111-81bd-0026b6bd8d3e;rport
User-Agent: Ekiga/3.2.7
From: <sip:58@193.147.53.4>;tag=2429777e-8829-e111-81bd-0026b6bd8d3e
Call-ID: aa16777e-8829-e111-81bd-0026b6bd8d3e@ubuntu
To: <sip:58@193.147.53.4>
Contact: <sip:58@83.55.44.186:11557>;q=1, <sip:58@83.55.44.186>;q=0.667, <sip:58@192.168.1.35>;q=0.334
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Expires: 3600
Content-Length: 0
Max-Forwards: 70


2011/12/20 15:56:41.286	  0:08.802	  subscriber:0xb39efb70	OpalUDP	Setting interface to 192.168.1.35%wlan0
2011/12/20 15:56:41.287	  0:08.802	  subscriber:0xb39efb70	SIP	Transaction timers set: retry=0.500, completion=6.000
2011/12/20 15:56:41.357	  0:08.873	Opal Liste...0xb699bb70	PWLib	File handle low water mark set: 49 PUDPSocket
2011/12/20 15:56:41.357	  0:08.873	Opal Liste...0xb699bb70	OpalUDP	Binding to interface: 83.55.44.186:11557
2011/12/20 15:56:41.357	  0:08.873	Opal Liste...0xb699bb70	SIP	Waiting for PDU on udp$193.147.53.4:5060<if=udp$83.55.44.186:11557>
2011/12/20 15:56:41.358	  0:08.874	Opal Liste...0xb699bb70	SIP	PDU received: rem=udp$193.147.53.4:5060,local=udp$83.55.44.186:11557,if=192.168.1.35%wlan0
SIP/2.0 100 Trying
CSeq: 1 REGISTER
Via: SIP/2.0/UDP 83.55.44.186:11557;branch=z9hG4bK9c65867e-8829-e111-81bd-0026b6bd8d3e;received=83.55.44.186;rport=11557
User-Agent: Asterisk PBX
From: <sip:58@193.147.53.4>;tag=2429777e-8829-e111-81bd-0026b6bd8d3e
Call-ID: aa16777e-8829-e111-81bd-0026b6bd8d3e@ubuntu
To: <sip:58@193.147.53.4>
Contact: <sip:58@193.147.53.4>
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0


2011/12/20 15:56:41.359	  0:08.875	Opal Liste...0xb699bb70	SIP	Queueing PDU "1 REGISTER <100>", transaction=z9hG4bK9c65867e-8829-e111-81bd-0026b6bd8d3e, token=aa16777e-8829-e111-81bd-0026b6bd8d3e@ubuntu
2011/12/20 15:56:41.359	  0:08.875	Opal Liste...0xb699bb70	Opal	Transport clean up on termination
2011/12/20 15:56:41.359	  0:08.875	Opal Liste...0xb699bb70	Opal	Transport Close
2011/12/20 15:56:41.359	  0:08.875	        Pool:0xb6919b70	SIP	Handling PDU "1 REGISTER <100>" for transaction=z9hG4bK9c65867e-8829-e111-81bd-0026b6bd8d3e
2011/12/20 15:56:41.359	  0:08.875	        Pool:0xb6919b70	SIP	REGISTER transaction id=z9hG4bK9c65867e-8829-e111-81bd-0026b6bd8d3e proceeding.
2011/12/20 15:56:41.359	  0:08.875	        Pool:0xb6919b70	OpalUDP	Setting interface to 192.168.1.35%wlan0
2011/12/20 15:56:41.360	  0:08.876	        Pool:0xb6919b70	SIP	Handled PDU "1 REGISTER <100>"
2011/12/20 15:56:41.359	  0:08.875	Opal Liste...0xb699bb70	Opal	Deleted transport udp$193.147.53.4:5060<if=udp$83.55.44.186:11557>
2011/12/20 15:56:41.360	  0:08.876	Opal Liste...0xb699bb70	OpalUDP	Binding to interface: 83.55.44.186:11557
2011/12/20 15:56:41.360	  0:08.876	Opal Liste...0xb699bb70	SIP	Waiting for PDU on udp$193.147.53.4:5060<if=udp$83.55.44.186:11557>
2011/12/20 15:56:41.361	  0:08.877	Opal Liste...0xb699bb70	SIP	PDU received: rem=udp$193.147.53.4:5060,local=udp$83.55.44.186:11557,if=192.168.1.35%wlan0
SIP/2.0 401 Unauthorized
CSeq: 1 REGISTER
Via: SIP/2.0/UDP 83.55.44.186:11557;branch=z9hG4bK9c65867e-8829-e111-81bd-0026b6bd8d3e;received=83.55.44.186;rport=11557
User-Agent: Asterisk PBX
From: <sip:58@193.147.53.4>;tag=2429777e-8829-e111-81bd-0026b6bd8d3e
Call-ID: aa16777e-8829-e111-81bd-0026b6bd8d3e@ubuntu
To: <sip:58@193.147.53.4>;tag=as769bbf3a
Contact: <sip:58@193.147.53.4>
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0
WWW-Authenticate: Digest realm="asterisk", nonce="49fa6f49"


2011/12/20 15:56:41.362	  0:08.878	Opal Liste...0xb699bb70	SIP	Queueing PDU "1 REGISTER <401>", transaction=z9hG4bK9c65867e-8829-e111-81bd-0026b6bd8d3e, token=aa16777e-8829-e111-81bd-0026b6bd8d3e@ubuntu
2011/12/20 15:56:41.362	  0:08.878	Opal Liste...0xb699bb70	Opal	Transport clean up on termination
2011/12/20 15:56:41.362	  0:08.878	Opal Liste...0xb699bb70	Opal	Transport Close
2011/12/20 15:56:41.363	  0:08.878	        Pool:0xb6919b70	SIP	Handling PDU "1 REGISTER <401>" for transaction=z9hG4bK9c65867e-8829-e111-81bd-0026b6bd8d3e
2011/12/20 15:56:41.363	  0:08.879	        Pool:0xb6919b70	SIP	REGISTER transaction id=z9hG4bK9c65867e-8829-e111-81bd-0026b6bd8d3e completed.
2011/12/20 15:56:41.363	  0:08.879	        Pool:0xb6919b70	OpalUDP	Setting interface to 192.168.1.35%wlan0
2011/12/20 15:56:41.363	  0:08.879	        Pool:0xb6919b70	SIP	Received Authentication Required response
2011/12/20 15:56:41.362	  0:08.878	Opal Liste...0xb699bb70	Opal	Deleted transport udp$193.147.53.4:5060<if=udp$83.55.44.186:11557>
2011/12/20 15:56:41.464	  0:08.980	        Pool:0xb6919b70	Socket	Name lookup of "asterisk" failed: errno=-5
2011/12/20 15:56:41.464	  0:08.980	        Pool:0xb6919b70	SIP	Located existing credentials for ID "58" at realm "asterisk"
2011/12/20 15:56:41.465	  0:08.980	        Pool:0xb6919b70	SIP	Found auth info for realm asterisk
2011/12/20 15:56:41.465	  0:08.981	        Pool:0xb6919b70	SIP	Changing REGISTER handler from Unavailable to Subscribing, target=sip:58@193.147.53.4, id=aa16777e-8829-e111-81bd-0026b6bd8d3e@ubuntu
2011/12/20 15:56:41.467	  0:08.983	        Pool:0xb6919b70	OpalMan	Listener interfaces: associated transport=udp$83.55.44.186:11557
    udp$83.55.44.186:11557,udp$83.55.44.186:5060,udp$192.168.1.35:5060
2011/12/20 15:56:41.468	  0:08.984	        Pool:0xb6919b70	SIP	Transaction created.
2011/12/20 15:56:41.470	  0:08.985	        Pool:0xb6919b70	SIP	Adding authentication information for user "58" at realm "asterisk"
2011/12/20 15:56:41.470	  0:08.986	        Pool:0xb6919b70	SIP	Transaction remote address is udp$193.147.53.4:5060
2011/12/20 15:56:41.470	  0:08.986	        Pool:0xb6919b70	SIP	Sending PDU (717 bytes) to: rem=udp$193.147.53.4:5060,local=udp$83.55.44.186:11557,if=192.168.1.35%wlan0
REGISTER sip:193.147.53.4 SIP/2.0
CSeq: 2 REGISTER
Via: SIP/2.0/UDP 83.55.44.186:11557;branch=z9hG4bKb891a27e-8829-e111-81bd-0026b6bd8d3e;rport
User-Agent: Ekiga/3.2.7
Authorization: Digest username="58", realm="asterisk", nonce="49fa6f49", uri="sip:193.147.53.4", algorithm=MD5, response="f2e68a2aae7534e0503cd6505c91ff3a"
From: <sip:58@193.147.53.4>;tag=2429777e-8829-e111-81bd-0026b6bd8d3e
Call-ID: aa16777e-8829-e111-81bd-0026b6bd8d3e@ubuntu
To: <sip:58@193.147.53.4>
Contact: <sip:58@83.55.44.186:11557>;q=1, <sip:58@83.55.44.186>;q=0.667, <sip:58@192.168.1.35>;q=0.334
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Expires: 3600
Content-Length: 0
Max-Forwards: 70


2011/12/20 15:56:41.471	  0:08.987	        Pool:0xb6919b70	OpalUDP	Setting interface to 192.168.1.35%wlan0
2011/12/20 15:56:41.471	  0:08.987	        Pool:0xb6919b70	SIP	Transaction timers set: retry=0.500, completion=6.000
2011/12/20 15:56:41.471	  0:08.987	        Pool:0xb6919b70	SIP	Handled PDU "1 REGISTER <401>"
2011/12/20 15:56:41.546	  0:09.062	Opal Liste...0xb699bb70	OpalUDP	Binding to interface: 83.55.44.186:11557
2011/12/20 15:56:41.547	  0:09.063	Opal Liste...0xb699bb70	SIP	Waiting for PDU on udp$193.147.53.4:5060<if=udp$83.55.44.186:11557>
2011/12/20 15:56:41.547	  0:09.063	Opal Liste...0xb699bb70	SIP	PDU received: rem=udp$193.147.53.4:5060,local=udp$83.55.44.186:11557,if=192.168.1.35%wlan0
SIP/2.0 100 Trying
CSeq: 2 REGISTER
Via: SIP/2.0/UDP 83.55.44.186:11557;branch=z9hG4bKb891a27e-8829-e111-81bd-0026b6bd8d3e;received=83.55.44.186;rport=11557
User-Agent: Asterisk PBX
From: <sip:58@193.147.53.4>;tag=2429777e-8829-e111-81bd-0026b6bd8d3e
Call-ID: aa16777e-8829-e111-81bd-0026b6bd8d3e@ubuntu
To: <sip:58@193.147.53.4>
Contact: <sip:58@193.147.53.4>
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0


2011/12/20 15:56:41.548	  0:09.064	Opal Liste...0xb699bb70	SIP	Queueing PDU "2 REGISTER <100>", transaction=z9hG4bKb891a27e-8829-e111-81bd-0026b6bd8d3e, token=aa16777e-8829-e111-81bd-0026b6bd8d3e@ubuntu
2011/12/20 15:56:41.548	  0:09.064	Opal Liste...0xb699bb70	Opal	Transport clean up on termination
2011/12/20 15:56:41.548	  0:09.064	Opal Liste...0xb699bb70	Opal	Transport Close
2011/12/20 15:56:41.548	  0:09.064	        Pool:0xb6919b70	SIP	Handling PDU "2 REGISTER <100>" for transaction=z9hG4bKb891a27e-8829-e111-81bd-0026b6bd8d3e
2011/12/20 15:56:41.549	  0:09.065	        Pool:0xb6919b70	SIP	REGISTER transaction id=z9hG4bKb891a27e-8829-e111-81bd-0026b6bd8d3e proceeding.
2011/12/20 15:56:41.549	  0:09.065	        Pool:0xb6919b70	OpalUDP	Setting interface to 192.168.1.35%wlan0
2011/12/20 15:56:41.549	  0:09.064	Opal Liste...0xb699bb70	Opal	Deleted transport udp$193.147.53.4:5060<if=udp$83.55.44.186:11557>
2011/12/20 15:56:41.549	  0:09.065	        Pool:0xb6919b70	SIP	Handled PDU "2 REGISTER <100>"
2011/12/20 15:56:41.550	  0:09.066	Opal Liste...0xb699bb70	OpalUDP	Binding to interface: 83.55.44.186:11557
2011/12/20 15:56:41.550	  0:09.066	Opal Liste...0xb699bb70	SIP	Waiting for PDU on udp$193.147.53.4:5060<if=udp$83.55.44.186:11557>
2011/12/20 15:56:41.551	  0:09.067	Opal Liste...0xb699bb70	SIP	PDU received: rem=udp$193.147.53.4:5060,local=udp$83.55.44.186:11557,if=192.168.1.35%wlan0
SIP/2.0 200 OK
Date: Tue, 20 Dec 2011 14:55:22 GMT
CSeq: 2 REGISTER
Via: SIP/2.0/UDP 83.55.44.186:11557;branch=z9hG4bKb891a27e-8829-e111-81bd-0026b6bd8d3e;received=83.55.44.186;rport=11557
User-Agent: Asterisk PBX
From: <sip:58@193.147.53.4>;tag=2429777e-8829-e111-81bd-0026b6bd8d3e
Call-ID: aa16777e-8829-e111-81bd-0026b6bd8d3e@ubuntu
To: <sip:58@193.147.53.4>;tag=as769bbf3a
Contact: <sip:58@83.55.44.186:11557>;expires=3600
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Expires: 3600
Content-Length: 0


2011/12/20 15:56:41.551	  0:09.067	Opal Liste...0xb699bb70	SIP	Queueing PDU "2 REGISTER <200>", transaction=z9hG4bKb891a27e-8829-e111-81bd-0026b6bd8d3e, token=aa16777e-8829-e111-81bd-0026b6bd8d3e@ubuntu
2011/12/20 15:56:41.551	  0:09.067	Opal Liste...0xb699bb70	Opal	Transport clean up on termination
2011/12/20 15:56:41.551	  0:09.067	Opal Liste...0xb699bb70	Opal	Transport Close
2011/12/20 15:56:41.551	  0:09.067	        Pool:0xb6919b70	SIP	Handling PDU "2 REGISTER <200>" for transaction=z9hG4bKb891a27e-8829-e111-81bd-0026b6bd8d3e
2011/12/20 15:56:41.552	  0:09.067	        Pool:0xb6919b70	SIP	REGISTER transaction id=z9hG4bKb891a27e-8829-e111-81bd-0026b6bd8d3e completed.
2011/12/20 15:56:41.552	  0:09.068	        Pool:0xb6919b70	OpalUDP	Setting interface to 192.168.1.35%wlan0
2011/12/20 15:56:41.552	  0:09.068	        Pool:0xb6919b70	SIP	Product Info: name="Asterisk", version="", vendor="", comments="PBX"
2011/12/20 15:56:41.552	  0:09.068	        Pool:0xb6919b70	SIP	Changing REGISTER handler from Subscribing to Subscribed, target=sip:58@193.147.53.4, id=aa16777e-8829-e111-81bd-0026b6bd8d3e@ubuntu
2011/12/20 15:56:41.551	  0:09.067	Opal Liste...0xb699bb70	Opal	Deleted transport udp$193.147.53.4:5060<if=udp$83.55.44.186:11557>
2011/12/20 15:56:41.552	  0:09.068	        Pool:0xb6919b70	SIP	Expiry time for REGISTER set to 3600 seconds.
2011/12/20 15:56:41.552	  0:09.068	        Pool:0xb6919b70	SIP	Product Info: name="Asterisk", version="", vendor="", comments="PBX"
2011/12/20 15:56:41.553	  0:09.069	        Pool:0xb6919b70	SIP	Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:58@193.147.53.4, id=e24baf7e-8829-e111-81bd-0026b6bd8d3e@ubuntu
2011/12/20 15:56:41.553	  0:09.069	        Pool:0xb6919b70	OpalUDP	Binding to interface: 0.0.0.0:5060
2011/12/20 15:56:41.553	  0:09.069	        Pool:0xb6919b70	SIP	Created transport udp$193.147.53.4:5060<if=udp$*:5060>
2011/12/20 15:56:41.554	  0:09.070	        Pool:0xb6919b70	OpalUDP	Started connect to 193.147.53.4:5060
2011/12/20 15:56:41.554	  0:09.070	        Pool:0xb6919b70	OpalUDP	Writing to interface 0 - "192.168.1.35%wlan0"
2011/12/20 15:56:41.555	  0:09.071	        Pool:0xb6919b70	SIP	Transaction created.
2011/12/20 15:56:41.558	  0:09.074	        Pool:0xb6919b70	SIP	Transaction remote address is udp$193.147.53.4:5060
2011/12/20 15:56:41.559	  0:09.074	        Pool:0xb6919b70	SIP	Sending PDU (567 bytes) to: rem=udp$193.147.53.4:5060,local=udp$83.55.44.186:11557,if=192.168.1.35%wlan0
SUBSCRIBE sip:58@193.147.53.4 SIP/2.0
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 83.55.44.186:11557;branch=z9hG4bK6cd0af7e-8829-e111-81bd-0026b6bd8d3e;rport
User-Agent: Ekiga/3.2.7
From: <sip:58@193.147.53.4>;tag=ec8caf7e-8829-e111-81bd-0026b6bd8d3e
Call-ID: e24baf7e-8829-e111-81bd-0026b6bd8d3e@ubuntu
To: <sip:58@193.147.53.4>
Contact: <sip:58@83.55.44.186:11557>
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


2011/12/20 15:56:41.559	  0:09.075	        Pool:0xb6919b70	OpalUDP	Setting interface to 192.168.1.35%wlan0
2011/12/20 15:56:41.559	  0:09.075	        Pool:0xb6919b70	SIP	Transaction timers set: retry=0.500, completion=6.000
2011/12/20 15:56:41.560	  0:09.075	        Pool:0xb6919b70	SIP	Handled PDU "2 REGISTER <200>"
2011/12/20 15:56:41.563	  0:09.079	                       	SIP	Changing PUBLISH handler from Unavailable to Subscribing, target=sip:58@193.147.53.4, id=20cbb07e-8829-e111-81bd-0026b6bd8d3e@ubuntu
2011/12/20 15:56:41.564	  0:09.079	                       	OpalUDP	Binding to interface: 0.0.0.0:5060
2011/12/20 15:56:41.564	  0:09.080	                       	SIP	Created transport udp$193.147.53.4:5060<if=udp$*:5060>
2011/12/20 15:56:41.564	  0:09.080	                       	OpalUDP	Started connect to 193.147.53.4:5060
2011/12/20 15:56:41.565	  0:09.081	                       	OpalUDP	Writing to interface 0 - "192.168.1.35%wlan0"
2011/12/20 15:56:41.565	  0:09.081	                       	SIP	Transaction created.
2011/12/20 15:56:41.570	  0:09.086	                       	SIP	Transaction remote address is udp$193.147.53.4:5060
2011/12/20 15:56:41.571	  0:09.087	                       	SIP	Sending PDU (792 bytes) to: rem=udp$193.147.53.4:5060,local=udp$83.55.44.186:11557,if=192.168.1.35%wlan0
PUBLISH sip:58@193.147.53.4 SIP/2.0
CSeq: 3 PUBLISH
Via: SIP/2.0/UDP 83.55.44.186:11557;branch=z9hG4bK0a95b17e-8829-e111-81bd-0026b6bd8d3e;rport
User-Agent: Ekiga/3.2.7
From: <sip:58@193.147.53.4>;tag=0a31b17e-8829-e111-81bd-0026b6bd8d3e
Call-ID: 20cbb07e-8829-e111-81bd-0026b6bd8d3e@ubuntu
To: <sip:58@193.147.53.4>
Contact: <sip:58@83.55.44.186:11557>
Expires: 500
Event: presence
Content-Type: application/pidf+xml
Content-Length: 322
Max-Forwards: 70

<?xml version="1.0" encoding="UTF-8"?>
<presence xmlns="urn:ietf:params:xml:ns:pidf" entity="pres:58@193.147.53.4">
<tuple id="sip:58@193.147.53.4_on_ubuntu">
<note>online - I'm online using Ekiga</note>
<status>
<basic>open</basic>
</status>
<contact priority="1">58@193.147.53.4</contact>
</tuple>
</presence>

2011/12/20 15:56:41.571	  0:09.087	                       	OpalUDP	Setting interface to 192.168.1.35%wlan0
2011/12/20 15:56:41.571	  0:09.087	                       	SIP	Transaction timers set: retry=0.500, completion=6.000
2011/12/20 15:56:41.633	  0:09.149	Opal Liste...0xb699bb70	OpalUDP	Binding to interface: 83.55.44.186:11557
2011/12/20 15:56:41.634	  0:09.150	Opal Liste...0xb699bb70	SIP	Waiting for PDU on udp$193.147.53.4:5060<if=udp$83.55.44.186:11557>
2011/12/20 15:56:41.635	  0:09.150	Opal Liste...0xb699bb70	SIP	PDU received: rem=udp$193.147.53.4:5060,local=udp$83.55.44.186:11557,if=192.168.1.35%wlan0
SIP/2.0 401 Unauthorized
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 83.55.44.186:11557;branch=z9hG4bK6cd0af7e-8829-e111-81bd-0026b6bd8d3e;received=83.55.44.186;rport=11557
User-Agent: Asterisk PBX
From: <sip:58@193.147.53.4>;tag=ec8caf7e-8829-e111-81bd-0026b6bd8d3e
Call-ID: e24baf7e-8829-e111-81bd-0026b6bd8d3e@ubuntu
To: <sip:58@193.147.53.4>;tag=as6c357310
Contact: <sip:58@193.147.53.4>
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0
WWW-Authenticate: Digest realm="asterisk", nonce="37514d39"


2011/12/20 15:56:41.635	  0:09.151	Opal Liste...0xb699bb70	SIP	Queueing PDU "2 SUBSCRIBE <401>", transaction=z9hG4bK6cd0af7e-8829-e111-81bd-0026b6bd8d3e, token=e24baf7e-8829-e111-81bd-0026b6bd8d3e@ubuntu
2011/12/20 15:56:41.635	  0:09.151	Opal Liste...0xb699bb70	Opal	Transport clean up on termination
2011/12/20 15:56:41.636	  0:09.151	Opal Liste...0xb699bb70	Opal	Transport Close
2011/12/20 15:56:41.635	  0:09.151	        Pool:0xb6919b70	SIP	Handling PDU "2 SUBSCRIBE <401>" for transaction=z9hG4bK6cd0af7e-8829-e111-81bd-0026b6bd8d3e
2011/12/20 15:56:41.636	  0:09.152	        Pool:0xb6919b70	SIP	SUBSCRIBE transaction id=z9hG4bK6cd0af7e-8829-e111-81bd-0026b6bd8d3e completed.
2011/12/20 15:56:41.636	  0:09.152	        Pool:0xb6919b70	OpalUDP	Setting interface to 192.168.1.35%wlan0
2011/12/20 15:56:41.636	  0:09.152	        Pool:0xb6919b70	SIP	Received Authentication Required response
2011/12/20 15:56:41.636	  0:09.152	        Pool:0xb6919b70	Socket	Name lookup of "asterisk" failed: errno=4
2011/12/20 15:56:41.636	  0:09.152	        Pool:0xb6919b70	SIP	Located existing credentials for realm "asterisk"
2011/12/20 15:56:41.636	  0:09.152	        Pool:0xb6919b70	SIP	Found auth info for realm asterisk
2011/12/20 15:56:41.636	  0:09.152	        Pool:0xb6919b70	SIP	Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:58@193.147.53.4, id=e24baf7e-8829-e111-81bd-0026b6bd8d3e@ubuntu
2011/12/20 15:56:41.636	  0:09.152	        Pool:0xb6919b70	SIP	Transaction created.
2011/12/20 15:56:41.639	  0:09.155	Opal Liste...0xb699bb70	Opal	Deleted transport udp$193.147.53.4:5060<if=udp$83.55.44.186:11557>
2011/12/20 15:56:41.639	  0:09.155	        Pool:0xb6919b70	SIP	Adding authentication information for user "58" at realm "asterisk"
2011/12/20 15:56:41.639	  0:09.155	        Pool:0xb6919b70	SIP	Transaction remote address is udp$193.147.53.4:5060
2011/12/20 15:56:41.640	  0:09.156	        Pool:0xb6919b70	SIP	Sending PDU (727 bytes) to: rem=udp$193.147.53.4:5060,local=udp$83.55.44.186:11557,if=192.168.1.35%wlan0
SUBSCRIBE sip:58@193.147.53.4 SIP/2.0
CSeq: 3 SUBSCRIBE
Via: SIP/2.0/UDP 83.55.44.186:11557;branch=z9hG4bKf242bc7e-8829-e111-81bd-0026b6bd8d3e;rport
User-Agent: Ekiga/3.2.7
Authorization: Digest username="58", realm="asterisk", nonce="37514d39", uri="sip:58@193.147.53.4", algorithm=MD5, response="a36ce0ecb6144a547df9bd3b3ec58262"
From: <sip:58@193.147.53.4>;tag=ec8caf7e-8829-e111-81bd-0026b6bd8d3e
Call-ID: e24baf7e-8829-e111-81bd-0026b6bd8d3e@ubuntu
To: <sip:58@193.147.53.4>
Contact: <sip:58@83.55.44.186:11557>
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


2011/12/20 15:56:41.640	  0:09.156	        Pool:0xb6919b70	OpalUDP	Setting interface to 192.168.1.35%wlan0
2011/12/20 15:56:41.640	  0:09.156	        Pool:0xb6919b70	SIP	Transaction timers set: retry=0.500, completion=6.000
2011/12/20 15:56:41.640	  0:09.156	        Pool:0xb6919b70	SIP	Handled PDU "2 SUBSCRIBE <401>"
2011/12/20 15:56:41.655	  0:09.171	Opal Liste...0xb699bb70	OpalUDP	Binding to interface: 83.55.44.186:11557
2011/12/20 15:56:41.655	  0:09.171	Opal Liste...0xb699bb70	SIP	Waiting for PDU on udp$193.147.53.4:5060<if=udp$83.55.44.186:11557>
2011/12/20 15:56:41.656	  0:09.172	Opal Liste...0xb699bb70	SIP	PDU received: rem=udp$193.147.53.4:5060,local=udp$83.55.44.186:11557,if=192.168.1.35%wlan0
SIP/2.0 501 Method Not Implemented
CSeq: 3 PUBLISH
Via: SIP/2.0/UDP 83.55.44.186:11557;branch=z9hG4bK0a95b17e-8829-e111-81bd-0026b6bd8d3e;rport;received=83.55.44.186
User-Agent: Asterisk PBX
From: <sip:58@193.147.53.4>;tag=0a31b17e-8829-e111-81bd-0026b6bd8d3e
Call-ID: 20cbb07e-8829-e111-81bd-0026b6bd8d3e@ubuntu
To: <sip:58@193.147.53.4>;tag=as70a01b77
Accept: application/sdp
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0


2011/12/20 15:56:41.657	  0:09.173	Opal Liste...0xb699bb70	SIP	Queueing PDU "3 PUBLISH <501>", transaction=z9hG4bK0a95b17e-8829-e111-81bd-0026b6bd8d3e, token=20cbb07e-8829-e111-81bd-0026b6bd8d3e@ubuntu
2011/12/20 15:56:41.657	  0:09.173	Opal Liste...0xb699bb70	Opal	Transport clean up on termination
2011/12/20 15:56:41.657	  0:09.173	Opal Liste...0xb699bb70	Opal	Transport Close
2011/12/20 15:56:41.658	  0:09.174	        Pool:0xb6919b70	SIP	Handling PDU "3 PUBLISH <501>" for transaction=z9hG4bK0a95b17e-8829-e111-81bd-0026b6bd8d3e
2011/12/20 15:56:41.658	  0:09.174	        Pool:0xb6919b70	SIP	PUBLISH transaction id=z9hG4bK0a95b17e-8829-e111-81bd-0026b6bd8d3e completed.
2011/12/20 15:56:41.658	  0:09.174	        Pool:0xb6919b70	OpalUDP	Setting interface to 192.168.1.35%wlan0
2011/12/20 15:56:41.658	  0:09.174	        Pool:0xb6919b70	SIP	Not retrying PUBLISH due to error response 501 Not Implemented
2011/12/20 15:56:41.658	  0:09.174	        Pool:0xb6919b70	SIP	Changing PUBLISH handler from Subscribing to Unsubscribed, target=sip:58@193.147.53.4, id=20cbb07e-8829-e111-81bd-0026b6bd8d3e@ubuntu
2011/12/20 15:56:41.659	  0:09.174	        Pool:0xb6919b70	SIP	Handled PDU "3 PUBLISH <501>"
2011/12/20 15:56:41.658	  0:09.174	Opal Liste...0xb699bb70	Opal	Deleted transport udp$193.147.53.4:5060<if=udp$83.55.44.186:11557>
2011/12/20 15:56:41.717	  0:09.232	Opal Liste...0xb699bb70	OpalUDP	Binding to interface: 83.55.44.186:11557
2011/12/20 15:56:41.717	  0:09.233	Opal Liste...0xb699bb70	SIP	Waiting for PDU on udp$193.147.53.4:5060<if=udp$83.55.44.186:11557>
2011/12/20 15:56:41.718	  0:09.233	Opal Liste...0xb699bb70	SIP	PDU received: rem=udp$193.147.53.4:5060,local=udp$83.55.44.186:11557,if=192.168.1.35%wlan0
SIP/2.0 200 OK
CSeq: 3 SUBSCRIBE
Via: SIP/2.0/UDP 83.55.44.186:11557;branch=z9hG4bKf242bc7e-8829-e111-81bd-0026b6bd8d3e;received=83.55.44.186;rport=11557
User-Agent: Asterisk PBX
From: <sip:58@193.147.53.4>;tag=ec8caf7e-8829-e111-81bd-0026b6bd8d3e
Call-ID: e24baf7e-8829-e111-81bd-0026b6bd8d3e@ubuntu
To: <sip:58@193.147.53.4>;tag=as6c357310
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Expires: 0
Content-Length: 0


2011/12/20 15:56:41.718	  0:09.234	Opal Liste...0xb699bb70	SIP	Queueing PDU "3 SUBSCRIBE <200>", transaction=z9hG4bKf242bc7e-8829-e111-81bd-0026b6bd8d3e, token=e24baf7e-8829-e111-81bd-0026b6bd8d3e@ubuntu
2011/12/20 15:56:41.718	  0:09.234	Opal Liste...0xb699bb70	Opal	Transport clean up on termination
2011/12/20 15:56:41.719	  0:09.235	Opal Liste...0xb699bb70	Opal	Transport Close
2011/12/20 15:56:41.719	  0:09.235	        Pool:0xb6919b70	SIP	Handling PDU "3 SUBSCRIBE <200>" for transaction=z9hG4bKf242bc7e-8829-e111-81bd-0026b6bd8d3e
2011/12/20 15:56:41.719	  0:09.235	        Pool:0xb6919b70	SIP	SUBSCRIBE transaction id=z9hG4bKf242bc7e-8829-e111-81bd-0026b6bd8d3e completed.
2011/12/20 15:56:41.719	  0:09.235	        Pool:0xb6919b70	OpalUDP	Setting interface to 192.168.1.35%wlan0
2011/12/20 15:56:41.719	  0:09.235	        Pool:0xb6919b70	SIP	Expiry time for SUBSCRIBE set to 0 seconds.
2011/12/20 15:56:41.719	  0:09.235	Opal Liste...0xb699bb70	Opal	Deleted transport udp$193.147.53.4:5060<if=udp$83.55.44.186:11557>
2011/12/20 15:56:41.719	  0:09.235	        Pool:0xb6919b70	SIP	Product Info: name="Asterisk", version="", vendor="", comments="PBX"
2011/12/20 15:56:41.720	  0:09.235	        Pool:0xb6919b70	SIP	Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:58@193.147.53.4, id=e24baf7e-8829-e111-81bd-0026b6bd8d3e@ubuntu
2011/12/20 15:56:41.720	  0:09.236	        Pool:0xb6919b70	SIP	Updating dialog tag from "" to "as6c357310"
2011/12/20 15:56:41.720	  0:09.236	        Pool:0xb6919b70	SIP	Product Info: name="Asterisk", version="", vendor="", comments="PBX"
2011/12/20 15:56:41.720	  0:09.236	        Pool:0xb6919b70	SIP	Handled PDU "3 SUBSCRIBE <200>"
2011/12/20 15:56:42.471	  0:09.987	 Housekeeper:0xb69dcb70	SIP	Set state Terminated_Success for REGISTER transaction id=z9hG4bK9c65867e-8829-e111-81bd-0026b6bd8d3e
2011/12/20 15:56:42.560	  0:10.076	 Housekeeper:0xb69dcb70	SIP	Set state Terminated_Success for REGISTER transaction id=z9hG4bKb891a27e-8829-e111-81bd-0026b6bd8d3e
2011/12/20 15:56:42.585	  0:10.101	Opal Garbage:0xb6a5eb70	SIP	Transaction id=z9hG4bK9c65867e-8829-e111-81bd-0026b6bd8d3e destroyed.
2011/12/20 15:56:42.585	  0:10.101	Opal Garbage:0xb6a5eb70	SIP	Transaction id=z9hG4bKb891a27e-8829-e111-81bd-0026b6bd8d3e destroyed.
2011/12/20 15:56:42.585	  0:10.101	Opal Garbage:0xb6a5eb70	SIP	Deleting SIPSubscribeHandler sip:58@193.147.53.4
2011/12/20 15:56:42.585	  0:10.101	Opal Garbage:0xb6a5eb70	Opal	Transport clean up on termination
2011/12/20 15:56:42.585	  0:10.101	Opal Garbage:0xb6a5eb70	Opal	Transport Close
2011/12/20 15:56:42.585	  0:10.101	Opal Garbage:0xb6a5eb70	Opal	Transport clean up on termination
2011/12/20 15:56:42.585	  0:10.101	Opal Garbage:0xb6a5eb70	Opal	Transport Close
2011/12/20 15:56:42.585	  0:10.101	Opal Garbage:0xb6a5eb70	Opal	Deleted transport udp$193.147.53.4:5060<if=udp$83.55.44.186:11557>
2011/12/20 15:56:42.586	  0:10.102	Opal Garbage:0xb6a5eb70	SIP	Deleted handler.
2011/12/20 15:56:42.586	  0:10.102	Opal Garbage:0xb6a5eb70	SIP	Deleting SIPPublishHandler sip:58@193.147.53.4
2011/12/20 15:56:42.586	  0:10.102	Opal Garbage:0xb6a5eb70	Opal	Transport clean up on termination
2011/12/20 15:56:42.586	  0:10.102	Opal Garbage:0xb6a5eb70	Opal	Transport Close
2011/12/20 15:56:42.586	  0:10.102	Opal Garbage:0xb6a5eb70	Opal	Transport clean up on termination
2011/12/20 15:56:42.586	  0:10.102	Opal Garbage:0xb6a5eb70	Opal	Transport Close
2011/12/20 15:56:42.586	  0:10.102	Opal Garbage:0xb6a5eb70	Opal	Deleted transport udp$193.147.53.4:5060<if=udp$83.55.44.186:11557>
2011/12/20 15:56:42.586	  0:10.102	Opal Garbage:0xb6a5eb70	SIP	Deleted handler.
2011/12/20 15:56:42.641	  0:10.157	 Housekeeper:0xb69dcb70	SIP	Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bK6cd0af7e-8829-e111-81bd-0026b6bd8d3e
2011/12/20 15:56:42.660	  0:10.176	 Housekeeper:0xb69dcb70	SIP	Set state Terminated_Success for PUBLISH transaction id=z9hG4bK0a95b17e-8829-e111-81bd-0026b6bd8d3e
2011/12/20 15:56:42.720	  0:10.236	 Housekeeper:0xb69dcb70	SIP	Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bKf242bc7e-8829-e111-81bd-0026b6bd8d3e
2011/12/20 15:56:43.587	  0:11.103	Opal Garbage:0xb6a5eb70	SIP	Transaction id=z9hG4bK6cd0af7e-8829-e111-81bd-0026b6bd8d3e destroyed.
2011/12/20 15:56:43.587	  0:11.103	Opal Garbage:0xb6a5eb70	SIP	Transaction id=z9hG4bK0a95b17e-8829-e111-81bd-0026b6bd8d3e destroyed.
2011/12/20 15:56:43.588	  0:11.104	Opal Garbage:0xb6a5eb70	SIP	Transaction id=z9hG4bKf242bc7e-8829-e111-81bd-0026b6bd8d3e destroyed.
2011/12/20 15:56:47.892	  0:15.408	Opal Liste...0xb699bb70	OpalUDP	Binding to interface: 83.55.44.186:11557
2011/12/20 15:56:47.892	  0:15.408	Opal Liste...0xb699bb70	SIP	Waiting for PDU on udp$193.147.53.4:5060<if=udp$83.55.44.186:11557>
2011/12/20 15:56:47.893	  0:15.409	Opal Liste...0xb699bb70	SIP	PDU received: rem=udp$193.147.53.4:5060,local=udp$83.55.44.186:11557,if=192.168.1.35%wlan0
OPTIONS sip:58@83.55.44.186:11557 SIP/2.0
Date: Tue, 20 Dec 2011 14:55:29 GMT
CSeq: 102 OPTIONS
Via: SIP/2.0/UDP 193.147.53.4:5060;branch=z9hG4bK46f45abf;rport
User-Agent: Asterisk PBX
From: "asterisk" <sip:asterisk@193.147.53.4>;tag=as171f99f1
Call-ID: 46d961207a885de60b56c5c400d7ea0d@193.147.53.4
To: <sip:58@83.55.44.186:11557>
Contact: <sip:asterisk@193.147.53.4>
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0
Max-Forwards: 70


2011/12/20 15:56:47.897	  0:15.413	Opal Liste...0xb699bb70	SIP	Sending PDU (309 bytes) to: rem=udp$193.147.53.4:5060,local=udp$83.55.44.186:11557,if=192.168.1.35%wlan0
SIP/2.0 200 OK
CSeq: 102 OPTIONS
Via: SIP/2.0/UDP 193.147.53.4:5060;branch=z9hG4bK46f45abf;rport
From: "asterisk" <sip:asterisk@193.147.53.4>;tag=as171f99f1
Call-ID: 46d961207a885de60b56c5c400d7ea0d@193.147.53.4
To: <sip:58@83.55.44.186:11557>
Contact: <sip:58@83.55.44.186:11557>
Content-Length: 0


2011/12/20 15:56:47.897	  0:15.413	Opal Liste...0xb699bb70	OpalUDP	Setting interface to 192.168.1.35%wlan0
2011/12/20 15:56:47.897	  0:15.413	Opal Liste...0xb699bb70	Opal	Transport clean up on termination
2011/12/20 15:56:47.898	  0:15.413	Opal Liste...0xb699bb70	Opal	Transport Close
2011/12/20 15:56:47.898	  0:15.414	Opal Liste...0xb699bb70	Opal	Deleted transport udp$193.147.53.4:5060<if=udp$83.55.44.186:11557>
2011/12/20 15:56:49.456	  0:16.972	                       	OpalMan	Set up call from pc:* to sip:56@193.147.53.4
2011/12/20 15:56:49.456	  0:16.972	                       	Call	Created Call[ndd6eb9521]
2011/12/20 15:56:49.456	  0:16.972	                       	OpalMan	Set up connection to "pc:*"
2011/12/20 15:56:49.457	  0:16.973	                       	OpalCon	Created connection Call[ndd6eb9521]-EP<pc>[l181f87bb2]
2011/12/20 15:56:49.457	  0:16.973	                       	Silence	Parameters set: mode=NoSilenceDetection, threshold=0, silencedb=3200, signaldb=80, period=4800
2011/12/20 15:56:49.457	  0:16.973	                       	Silence	Handler created
2011/12/20 15:56:49.457	  0:16.973	                       	Echo Canceler	Handler created
2011/12/20 15:56:49.457	  0:16.973	                       	PCSS	Created PC sound system connection: token="l181f87bb2" player="EKIGA" recorder="EKIGA"
2011/12/20 15:56:49.457	  0:16.973	                       	OpalCon	SetPhase from UninitialisedPhase to SetUpPhase for Call[ndd6eb9521]-EP<pc>[l181f87bb2]
2011/12/20 15:56:49.457	  0:16.973	                       	OpalMan	OnIncoming connection Call[ndd6eb9521]-EP<pc>[l181f87bb2]
2011/12/20 15:56:49.457	  0:16.973	                       	OpalCon	Applying string options:


2011/12/20 15:56:49.457	  0:16.973	                       	Call	GetOtherPartyConnection Call[ndd6eb9521]-EP<pc>[l181f87bb2]
2011/12/20 15:56:49.457	  0:16.973	                       	OpalMan	Searching for route "pc:naxo	sip:56@193.147.53.4"
2011/12/20 15:56:49.457	  0:16.973	                       	OpalMan	Set up connection to "sip:56@193.147.53.4"
2011/12/20 15:56:49.457	  0:16.973	                       	OpalCon	Created connection Call[ndd6eb9521]-EP<sip>[cc6a6583-8829-e111-81bd-0026b6bd8d3e]
2011/12/20 15:56:49.457	  0:16.973	                       	RFC2833	Handler created
2011/12/20 15:56:49.458	  0:16.973	                       	RFC2833	Handler created
2011/12/20 15:56:49.458	  0:16.974	                       	SIP	Created connection.
2011/12/20 15:56:49.458	  0:16.974	                       	PCSS	Outgoing call routed to sip:56@193.147.53.4 for Call[ndd6eb9521]-EP<pc>[l181f87bb2]
2011/12/20 15:56:49.458	  0:16.974	                       	OpalMan	SetUpCall succeeded, call=Call[ndd6eb9521]
2011/12/20 15:56:49.458	  0:16.974	   CallSetup:0xb39efb70	Call	OnSetUp Call[ndd6eb9521]-EP<pc>[l181f87bb2]
2011/12/20 15:56:49.458	  0:16.974	   CallSetup:0xb39efb70	SIP	SetUpConnection: sip:56@193.147.53.4
2011/12/20 15:56:49.458	  0:16.974	   CallSetup:0xb39efb70	OpalCon	SetPhase from UninitialisedPhase to SetUpPhase for Call[ndd6eb9521]-EP<sip>[cc6a6583-8829-e111-81bd-0026b6bd8d3e]
2011/12/20 15:56:49.458	  0:16.974	   CallSetup:0xb39efb70	OpalCon	Applying string options:


2011/12/20 15:56:49.459	  0:16.975	   CallSetup:0xb39efb70	SIP	Connecting to sip:56@193.147.53.4 via sip:56@193.147.53.4
2011/12/20 15:56:49.459	  0:16.975	   CallSetup:0xb39efb70	SIP	Found registrar on domain 193.147.53.4, using interface 192.168.1.35%wlan0
2011/12/20 15:56:49.459	  0:16.975	   CallSetup:0xb39efb70	OpalUDP	Binding to interface: 192.168.1.35:5060
2011/12/20 15:56:49.460	  0:16.976	   CallSetup:0xb39efb70	SIP	Created transport udp$193.147.53.4:5060<if=udp$83.55.44.186:11557>
2011/12/20 15:56:49.460	  0:16.976	   CallSetup:0xb39efb70	OpalUDP	Started connect to 193.147.53.4:5060
2011/12/20 15:56:49.460	  0:16.976	   CallSetup:0xb39efb70	SIP	Updating dialog tag from "" to "cc6a6583-8829-e111-81bd-0026b6bd8d3e"
2011/12/20 15:56:49.464	  0:16.980	   CallSetup:0xb39efb70	SIP	INVITE transaction id=z9hG4bKfc616683-8829-e111-81bd-0026b6bd8d3e created.
2011/12/20 15:56:49.464	  0:16.980	   CallSetup:0xb39efb70	SIP	Creating INVITE request
2011/12/20 15:56:49.468	  0:16.983	   CallSetup:0xb39efb70	MediaFormat	Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,G.722-64k,PCM-16,G.711-ALaw-64k,G.711-uLaw-64k,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,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,iLBC,iLBC-13k3,iLBC-15k2,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz
2011/12/20 15:56:49.469	  0:16.985	   CallSetup:0xb39efb70	MediaFormat	Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,G.722-64k,PCM-16,G.711-ALaw-64k,G.711-uLaw-64k,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,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,iLBC,iLBC-13k3,iLBC-15k2,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz
2011/12/20 15:56:49.469	  0:16.985	   CallSetup:0xb39efb70	Call	GetMediaFormats for Call[ndd6eb9521]-EP<sip>[cc6a6583-8829-e111-81bd-0026b6bd8d3e]
SpeexIETFWide-20.6k
SpeexWB
SpeexWide-20.6k
H.261
H.261-CIF
H.261-QCIF

2011/12/20 15:56:49.469	  0:16.985	   CallSetup:0xb39efb70	SIP	Offering media type audio in SDP with formats
SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,H.261,H.261-CIF,H.261-QCIF
2011/12/20 15:56:49.469	  0:16.985	   CallSetup:0xb39efb70	Call	IsMediaBypassPossible Call[ndd6eb9521]-EP<sip>[cc6a6583-8829-e111-81bd-0026b6bd8d3e] session 1
2011/12/20 15:56:49.469	  0:16.985	   CallSetup:0xb39efb70	OpalMan	IsMediaBypassPossible: session 1
2011/12/20 15:56:49.470	  0:16.985	   CallSetup:0xb39efb70	OpalCon	IsMediaBypassPossible: default returns false
2011/12/20 15:56:49.470	  0:16.986	   CallSetup:0xb39efb70	RTP	Cannot find media session 1
2011/12/20 15:56:49.470	  0:16.986	   CallSetup:0xb39efb70	RTP	Cannot find RTP session 1
2011/12/20 15:56:49.471	  0:16.987	   CallSetup:0xb39efb70	RTP_UDP	Session 1, created with NAT flag set to 0
2011/12/20 15:56:49.471	  0:16.987	   CallSetup:0xb39efb70	PWLib	File handle high water mark set: 52 PUDPSocket
2011/12/20 15:56:49.471	  0:16.987	   CallSetup:0xb39efb70	PWLib	File handle high water mark set: 53 PUDPSocket
2011/12/20 15:56:49.471	  0:16.987	   CallSetup:0xb39efb70	PWLib	File handle high water mark set: 54 PUDPSocket
2011/12/20 15:56:49.851	  0:17.367	   CallSetup:0xb39efb70	RTP	Session 1, STUN created STUN RTP/RTCP socket pair.
2011/12/20 15:56:49.851	  0:17.367	   CallSetup:0xb39efb70	RTP_UDP	Session 1 created: 83.55.44.186:12062-12063 ssrc=677316709
2011/12/20 15:56:49.852	  0:17.367	   CallSetup:0xb39efb70	PWLib	File handle low water mark set: 53 PUDPSocket
2011/12/20 15:56:49.852	  0:17.368	   CallSetup:0xb39efb70	RTP	Creating new session RTP_UDP
2011/12/20 15:56:49.852	  0:17.368	   CallSetup:0xb39efb70	RTP	Found existing media session 1
2011/12/20 15:56:49.853	  0:17.369	   CallSetup:0xb39efb70	SDP	SDP not including SpeexIETFWide-20.6k as it is not a SIP transportable format
2011/12/20 15:56:49.853	  0:17.369	   CallSetup:0xb39efb70	SDP	SDP not including SpeexWide-20.6k as it is not a SIP transportable format
2011/12/20 15:56:49.853	  0:17.369	   CallSetup:0xb39efb70	SIP	Using default RTP payload [pt=101] for UserInput/RFC2833
2011/12/20 15:56:49.853	  0:17.369	   CallSetup:0xb39efb70	SIP	Using default RTP payload [pt=122] for NamedSignalEvent
2011/12/20 15:56:49.859	  0:17.375	   CallSetup:0xb39efb70	MediaFormat	Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,G.722-64k,PCM-16,G.711-ALaw-64k,G.711-uLaw-64k,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,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,iLBC,iLBC-13k3,iLBC-15k2,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz
2011/12/20 15:56:49.859	  0:17.375	   CallSetup:0xb39efb70	MediaFormat	Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,G.722-64k,PCM-16,G.711-ALaw-64k,G.711-uLaw-64k,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,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,iLBC,iLBC-13k3,iLBC-15k2,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz
2011/12/20 15:56:49.860	  0:17.376	   CallSetup:0xb39efb70	Call	GetMediaFormats for Call[ndd6eb9521]-EP<sip>[cc6a6583-8829-e111-81bd-0026b6bd8d3e]
SpeexIETFWide-20.6k
SpeexWB
SpeexWide-20.6k
H.261
H.261-CIF
H.261-QCIF

2011/12/20 15:56:49.860	  0:17.376	   CallSetup:0xb39efb70	SIP	Offering media type video in SDP with formats
SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,H.261,H.261-CIF,H.261-QCIF
2011/12/20 15:56:49.860	  0:17.376	   CallSetup:0xb39efb70	Call	IsMediaBypassPossible Call[ndd6eb9521]-EP<sip>[cc6a6583-8829-e111-81bd-0026b6bd8d3e] session 2
2011/12/20 15:56:49.860	  0:17.376	   CallSetup:0xb39efb70	OpalMan	IsMediaBypassPossible: session 2
2011/12/20 15:56:49.860	  0:17.376	   CallSetup:0xb39efb70	OpalCon	IsMediaBypassPossible: default returns false
2011/12/20 15:56:49.860	  0:17.376	   CallSetup:0xb39efb70	RTP	Cannot find media session 2
2011/12/20 15:56:49.860	  0:17.376	   CallSetup:0xb39efb70	RTP	Cannot find RTP session 2
2011/12/20 15:56:49.861	  0:17.377	   CallSetup:0xb39efb70	RTP_UDP	Session 2, created with NAT flag set to 0
2011/12/20 15:56:49.861	  0:17.377	   CallSetup:0xb39efb70	PWLib	File handle high water mark set: 55 PUDPSocket
2011/12/20 15:56:49.861	  0:17.377	   CallSetup:0xb39efb70	PWLib	File handle high water mark set: 56 PUDPSocket
2011/12/20 15:56:50.227	  0:17.743	   CallSetup:0xb39efb70	STUN	Could not get a pair of adjacent port numbers from NAT
2011/12/20 15:56:50.227	  0:17.743	   CallSetup:0xb39efb70	RTP	Session 2, STUN could not create STUN RTP/RTCP socket pair; trying to create individual sockets.
2011/12/20 15:56:50.228	  0:17.743	   CallSetup:0xb39efb70	PWLib	File handle low water mark set: 53 PUDPSocket
2011/12/20 15:56:50.425	  0:17.940	   CallSetup:0xb39efb70	RTP_UDP	Session 2 created: 83.55.44.186:12083-12084 ssrc=1151498799
2011/12/20 15:56:50.425	  0:17.941	   CallSetup:0xb39efb70	RTP	Creating new session RTP_UDP
2011/12/20 15:56:50.425	  0:17.941	   CallSetup:0xb39efb70	RTP	Found existing media session 2
2011/12/20 15:56:50.426	  0:17.942	   CallSetup:0xb39efb70	SDP	SDP not including H.261-CIF as it is not a SIP transportable format
2011/12/20 15:56:50.427	  0:17.942	   CallSetup:0xb39efb70	SDP	SDP not including H.261-QCIF as it is not a SIP transportable format
2011/12/20 15:56:50.427	  0:17.943	   CallSetup:0xb39efb70	SIP	Transaction remote address is udp$193.147.53.4:5060
2011/12/20 15:56:50.428	  0:17.944	   CallSetup:0xb39efb70	OpalPlugin	to_customised_options: H.261
2011/12/20 15:56:50.429	  0:17.945	   CallSetup:0xb39efb70	SIP	Sending PDU (967 bytes) to: rem=udp$193.147.53.4:5060,local=udp$83.55.44.186:11557,if=192.168.1.35%wlan0
INVITE sip:56@193.147.53.4 SIP/2.0
Date: Tue, 20 Dec 2011 14:56:49 GMT
CSeq: 1 INVITE
Via: SIP/2.0/UDP 83.55.44.186:11557;branch=z9hG4bKfc616683-8829-e111-81bd-0026b6bd8d3e;rport
User-Agent: Ekiga/3.2.7
From: "Naxo" <sip:58@193.147.53.4>;tag=cc6a6583-8829-e111-81bd-0026b6bd8d3e
Call-ID: 2c746583-8829-e111-81bd-0026b6bd8d3e@ubuntu
To: <sip:56@193.147.53.4>
Contact: <sip:58@83.55.44.186:11557>
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Content-Type: application/sdp
Content-Length: 412
Max-Forwards: 70

v=0
o=- 1324393009 1 IN IP4 83.55.44.186
s=Opal SIP Session
c=IN IP4 83.55.44.186
t=0 0
m=audio 12062 RTP/AVP 112 101 122
a=sendrecv
a=rtpmap:112 Speex/16000/1
a=fmtp:112 sr=16000,mode=any
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16,32,36
a=rtpmap:122 NSE/8000
a=fmtp:122 192-193
m=video 12083 RTP/AVP 31
b=AS:4096
b=TIAS:4096000
a=sendrecv
a=rtpmap:31 h261/90000
a=fmtp:31 CIF=1;QCIF=1

2011/12/20 15:56:50.430	  0:17.946	   CallSetup:0xb39efb70	OpalUDP	Setting interface to 192.168.1.35%wlan0
2011/12/20 15:56:50.430	  0:17.946	   CallSetup:0xb39efb70	SIP	Transaction timers set: retry=0.500, completion=1:00.000
2011/12/20 15:56:50.430	  0:17.946	   CallSetup:0xb39efb70	OpalCon	OnSetUpConnectionCall[ndd6eb9521]-EP<sip>[cc6a6583-8829-e111-81bd-0026b6bd8d3e]
2011/12/20 15:56:50.430	  0:17.946	   CallSetup:0xb39efb70	OpalEP	OnSetUpConnection Call[ndd6eb9521]-EP<sip>[cc6a6583-8829-e111-81bd-0026b6bd8d3e]
2011/12/20 15:56:50.512	  0:18.028	Opal Liste...0xb699bb70	PWLib	File handle low water mark set: 49 PUDPSocket
2011/12/20 15:56:50.513	  0:18.029	Opal Liste...0xb699bb70	OpalUDP	Binding to interface: 83.55.44.186:11557
2011/12/20 15:56:50.513	  0:18.029	Opal Liste...0xb699bb70	SIP	Waiting for PDU on udp$193.147.53.4:5060<if=udp$83.55.44.186:11557>
2011/12/20 15:56:50.514	  0:18.029	Opal Liste...0xb699bb70	SIP	PDU received: rem=udp$193.147.53.4:5060,local=udp$83.55.44.186:11557,if=192.168.1.35%wlan0
SIP/2.0 407 Proxy Authentication Required
CSeq: 1 INVITE
Via: SIP/2.0/UDP 83.55.44.186:11557;branch=z9hG4bKfc616683-8829-e111-81bd-0026b6bd8d3e;received=83.55.44.186;rport=11557
User-Agent: Asterisk PBX
From: "Naxo" <sip:58@193.147.53.4>;tag=cc6a6583-8829-e111-81bd-0026b6bd8d3e
Call-ID: 2c746583-8829-e111-81bd-0026b6bd8d3e@ubuntu
To: <sip:56@193.147.53.4>;tag=as344d3371
Contact: <sip:56@193.147.53.4>
Proxy-Authenticate: Digest realm="asterisk", nonce="2be856c5"
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0


2011/12/20 15:56:50.515	  0:18.030	Opal Liste...0xb699bb70	SIP	Queueing PDU "1 INVITE <407>", transaction=z9hG4bKfc616683-8829-e111-81bd-0026b6bd8d3e, token=cc6a6583-8829-e111-81bd-0026b6bd8d3e
2011/12/20 15:56:50.515	  0:18.031	Opal Liste...0xb699bb70	PTLib	No permission to set priority level 3
2011/12/20 15:56:50.515	  0:18.031	Opal Liste...0xb699bb70	Opal	Transport clean up on termination
2011/12/20 15:56:50.515	  0:18.031	Opal Liste...0xb699bb70	Opal	Transport Close
2011/12/20 15:56:50.515	  0:18.031	        Pool:0xb39efb70	SIP	Handling PDU "1 INVITE <407>" for transaction=z9hG4bKfc616683-8829-e111-81bd-0026b6bd8d3e
2011/12/20 15:56:50.515	  0:18.031	        Pool:0xb39efb70	PWLib	File handle high water mark set: 57 PUDPSocket
2011/12/20 15:56:50.515	  0:18.031	Opal Liste...0xb699bb70	PWLib	File handle low water mark set: 55 PUDPSocket
2011/12/20 15:56:50.515	  0:18.031	Opal Liste...0xb699bb70	Opal	Deleted transport udp$193.147.53.4:5060<if=udp$83.55.44.186:11557>
2011/12/20 15:56:50.516	  0:18.032	        Pool:0xb39efb70	SIP	Sending PDU (352 bytes) to: rem=udp$193.147.53.4:5060,local=udp$83.55.44.186:11557,if=192.168.1.35%wlan0
ACK sip:56@193.147.53.4 SIP/2.0
CSeq: 1 ACK
Via: SIP/2.0/UDP 83.55.44.186:11557;branch=z9hG4bKfc616683-8829-e111-81bd-0026b6bd8d3e;rport
From: "Naxo" <sip:58@193.147.53.4>;tag=cc6a6583-8829-e111-81bd-0026b6bd8d3e
Call-ID: 2c746583-8829-e111-81bd-0026b6bd8d3e@ubuntu
To: <sip:56@193.147.53.4>;tag=as344d3371
Content-Length: 0
Max-Forwards: 70


2011/12/20 15:56:50.517	  0:18.033	        Pool:0xb39efb70	OpalUDP	Setting interface to 192.168.1.35%wlan0
2011/12/20 15:56:50.517	  0:18.033	        Pool:0xb39efb70	SIP	INVITE transaction id=z9hG4bKfc616683-8829-e111-81bd-0026b6bd8d3e completed.
2011/12/20 15:56:50.517	  0:18.033	        Pool:0xb39efb70	SIP	Received Proxy Authentication Required response
2011/12/20 15:56:50.517	  0:18.033	        Pool:0xb39efb70	Socket	Name lookup of "asterisk" failed: errno=4
2011/12/20 15:56:50.517	  0:18.033	        Pool:0xb39efb70	SIP	Located existing credentials for ID "58" at realm "asterisk"
2011/12/20 15:56:50.517	  0:18.033	        Pool:0xb39efb70	SIP	Found auth info for realm "asterisk", user "58"
2011/12/20 15:56:50.517	  0:18.033	        Pool:0xb39efb70	OpalUDP	Setting interface to 192.168.1.35%wlan0
2011/12/20 15:56:50.522	  0:18.038	        Pool:0xb39efb70	SIP	INVITE transaction id=z9hG4bKe0db0784-8829-e111-81bd-0026b6bd8d3e created.
2011/12/20 15:56:50.522	  0:18.038	        Pool:0xb39efb70	SIP	Creating INVITE request
2011/12/20 15:56:50.528	  0:18.044	        Pool:0xb39efb70	MediaFormat	Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,G.722-64k,PCM-16,G.711-ALaw-64k,G.711-uLaw-64k,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,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,iLBC,iLBC-13k3,iLBC-15k2,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz
2011/12/20 15:56:50.529	  0:18.045	        Pool:0xb39efb70	MediaFormat	Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,G.722-64k,PCM-16,G.711-ALaw-64k,G.711-uLaw-64k,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,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,iLBC,iLBC-13k3,iLBC-15k2,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz
2011/12/20 15:56:50.530	  0:18.046	        Pool:0xb39efb70	Call	GetMediaFormats for Call[ndd6eb9521]-EP<sip>[cc6a6583-8829-e111-81bd-0026b6bd8d3e]
SpeexIETFWide-20.6k
SpeexWB
SpeexWide-20.6k
H.261
H.261-CIF
H.261-QCIF

2011/12/20 15:56:50.530	  0:18.046	        Pool:0xb39efb70	SIP	Offering media type audio in SDP with formats
SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,H.261,H.261-CIF,H.261-QCIF
2011/12/20 15:56:50.530	  0:18.046	        Pool:0xb39efb70	Call	IsMediaBypassPossible Call[ndd6eb9521]-EP<sip>[cc6a6583-8829-e111-81bd-0026b6bd8d3e] session 1
2011/12/20 15:56:50.530	  0:18.046	        Pool:0xb39efb70	OpalMan	IsMediaBypassPossible: session 1
2011/12/20 15:56:50.530	  0:18.046	        Pool:0xb39efb70	OpalCon	IsMediaBypassPossible: default returns false
2011/12/20 15:56:50.530	  0:18.046	        Pool:0xb39efb70	RTP	Found existing media session 1
2011/12/20 15:56:50.530	  0:18.046	        Pool:0xb39efb70	RTP	Found existing RTP session 1
2011/12/20 15:56:50.531	  0:18.047	        Pool:0xb39efb70	SDP	SDP not including SpeexIETFWide-20.6k as it is not a SIP transportable format
2011/12/20 15:56:50.531	  0:18.047	        Pool:0xb39efb70	SDP	SDP not including SpeexWide-20.6k as it is not a SIP transportable format
2011/12/20 15:56:50.531	  0:18.047	        Pool:0xb39efb70	SIP	Using default RTP payload [pt=101] for UserInput/RFC2833
2011/12/20 15:56:50.531	  0:18.047	        Pool:0xb39efb70	SIP	Using default RTP payload [pt=122] for NamedSignalEvent
2011/12/20 15:56:50.536	  0:18.052	        Pool:0xb39efb70	MediaFormat	Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,G.722-64k,PCM-16,G.711-ALaw-64k,G.711-uLaw-64k,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,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,iLBC,iLBC-13k3,iLBC-15k2,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz
2011/12/20 15:56:50.536	  0:18.052	        Pool:0xb39efb70	MediaFormat	Removing codecs PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,G.722-64k,PCM-16,G.711-ALaw-64k,G.711-uLaw-64k,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,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,iLBC,iLBC-13k3,iLBC-15k2,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz
2011/12/20 15:56:50.537	  0:18.052	        Pool:0xb39efb70	Call	GetMediaFormats for Call[ndd6eb9521]-EP<sip>[cc6a6583-8829-e111-81bd-0026b6bd8d3e]
SpeexIETFWide-20.6k
SpeexWB
SpeexWide-20.6k
H.261
H.261-CIF
H.261-QCIF

2011/12/20 15:56:50.537	  0:18.052	        Pool:0xb39efb70	SIP	Offering media type video in SDP with formats
SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,H.261,H.261-CIF,H.261-QCIF
2011/12/20 15:56:50.537	  0:18.053	        Pool:0xb39efb70	Call	IsMediaBypassPossible Call[ndd6eb9521]-EP<sip>[cc6a6583-8829-e111-81bd-0026b6bd8d3e] session 2
2011/12/20 15:56:50.537	  0:18.053	        Pool:0xb39efb70	OpalMan	IsMediaBypassPossible: session 2
2011/12/20 15:56:50.537	  0:18.053	        Pool:0xb39efb70	OpalCon	IsMediaBypassPossible: default returns false
2011/12/20 15:56:50.537	  0:18.053	        Pool:0xb39efb70	RTP	Found existing media session 2
2011/12/20 15:56:50.537	  0:18.053	        Pool:0xb39efb70	RTP	Found existing RTP session 2
2011/12/20 15:56:50.537	  0:18.053	        Pool:0xb39efb70	SDP	SDP not including H.261-CIF as it is not a SIP transportable format
2011/12/20 15:56:50.537	  0:18.053	        Pool:0xb39efb70	SDP	SDP not including H.261-QCIF as it is not a SIP transportable format
2011/12/20 15:56:50.537	  0:18.053	        Pool:0xb39efb70	SIP	Adding authentication information for user "58" at realm "asterisk"
2011/12/20 15:56:50.538	  0:18.053	        Pool:0xb39efb70	SIP	Transaction remote address is udp$193.147.53.4:5060
2011/12/20 15:56:50.538	  0:18.054	        Pool:0xb39efb70	OpalPlugin	to_customised_options: H.261
2011/12/20 15:56:50.539	  0:18.055	        Pool:0xb39efb70	SIP	Sending PDU (1133 bytes) to: rem=udp$193.147.53.4:5060,local=udp$83.55.44.186:11557,if=192.168.1.35%wlan0
INVITE sip:56@193.147.53.4 SIP/2.0
Date: Tue, 20 Dec 2011 14:56:50 GMT
CSeq: 2 INVITE
Via: SIP/2.0/UDP 83.55.44.186:11557;branch=z9hG4bKe0db0784-8829-e111-81bd-0026b6bd8d3e;rport
User-Agent: Ekiga/3.2.7
From: "Naxo" <sip:58@193.147.53.4>;tag=cc6a6583-8829-e111-81bd-0026b6bd8d3e
Call-ID: 2c746583-8829-e111-81bd-0026b6bd8d3e@ubuntu
To: <sip:56@193.147.53.4>
Contact: <sip:58@83.55.44.186:11557>
Proxy-Authorization: Digest username="58", realm="asterisk", nonce="2be856c5", uri="sip:56@193.147.53.4", algorithm=MD5, response="b2a7c7d0375f701b0d38bc07a79bcc77"
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Content-Type: application/sdp
Content-Length: 412
Max-Forwards: 70

v=0
o=- 1324393009 1 IN IP4 83.55.44.186
s=Opal SIP Session
c=IN IP4 83.55.44.186
t=0 0
m=audio 12062 RTP/AVP 112 101 122
a=sendrecv
a=rtpmap:112 Speex/16000/1
a=fmtp:112 sr=16000,mode=any
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16,32,36
a=rtpmap:122 NSE/8000
a=fmtp:122 192-193
m=video 12083 RTP/AVP 31
b=AS:4096
b=TIAS:4096000
a=sendrecv
a=rtpmap:31 h261/90000
a=fmtp:31 CIF=1;QCIF=1

2011/12/20 15:56:50.539	  0:18.055	        Pool:0xb39efb70	OpalUDP	Setting interface to 192.168.1.35%wlan0
2011/12/20 15:56:50.539	  0:18.055	        Pool:0xb39efb70	SIP	Transaction timers set: retry=0.500, completion=1:00.000
2011/12/20 15:56:50.539	  0:18.055	        Pool:0xb39efb70	SIP	Handled PDU "1 INVITE <407>"
2011/12/20 15:56:50.627	  0:18.143	Opal Liste...0xb699bb70	OpalUDP	Binding to interface: 83.55.44.186:11557
2011/12/20 15:56:50.627	  0:18.143	Opal Liste...0xb699bb70	SIP	Waiting for PDU on udp$193.147.53.4:5060<if=udp$83.55.44.186:11557>
2011/12/20 15:56:50.629	  0:18.145	Opal Liste...0xb699bb70	SIP	PDU received: rem=udp$193.147.53.4:5060,local=udp$83.55.44.186:11557,if=192.168.1.35%wlan0
SIP/2.0 488 Not acceptable here
CSeq: 2 INVITE
Via: SIP/2.0/UDP 83.55.44.186:11557;branch=z9hG4bKe0db0784-8829-e111-81bd-0026b6bd8d3e;received=83.55.44.186;rport=11557
User-Agent: Asterisk PBX
From: "Naxo" <sip:58@193.147.53.4>;tag=cc6a6583-8829-e111-81bd-0026b6bd8d3e
Call-ID: 2c746583-8829-e111-81bd-0026b6bd8d3e@ubuntu
To: <sip:56@193.147.53.4>;tag=as344d3371
Contact: <sip:56@193.147.53.4>
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0


2011/12/20 15:56:50.630	  0:18.146	Opal Liste...0xb699bb70	SIP	Queueing PDU "2 INVITE <488>", transaction=z9hG4bKe0db0784-8829-e111-81bd-0026b6bd8d3e, token=cc6a6583-8829-e111-81bd-0026b6bd8d3e
2011/12/20 15:56:50.630	  0:18.146	Opal Liste...0xb699bb70	Opal	Transport clean up on termination
2011/12/20 15:56:50.630	  0:18.146	Opal Liste...0xb699bb70	Opal	Transport Close
2011/12/20 15:56:50.630	  0:18.146	        Pool:0xb39efb70	SIP	Handling PDU "2 INVITE <488>" for transaction=z9hG4bKe0db0784-8829-e111-81bd-0026b6bd8d3e
2011/12/20 15:56:50.630	  0:18.146	Opal Liste...0xb699bb70	Opal	Deleted transport udp$193.147.53.4:5060<if=udp$83.55.44.186:11557>
2011/12/20 15:56:50.632	  0:18.148	        Pool:0xb39efb70	SIP	Adding authentication information for user "58" at realm "asterisk"
2011/12/20 15:56:50.633	  0:18.149	        Pool:0xb39efb70	SIP	Sending PDU (518 bytes) to: rem=udp$193.147.53.4:5060,local=udp$83.55.44.186:11557,if=192.168.1.35%wlan0
ACK sip:56@193.147.53.4 SIP/2.0
CSeq: 2 ACK
Via: SIP/2.0/UDP 83.55.44.186:11557;branch=z9hG4bKe0db0784-8829-e111-81bd-0026b6bd8d3e;rport
From: "Naxo" <sip:58@193.147.53.4>;tag=cc6a6583-8829-e111-81bd-0026b6bd8d3e
Call-ID: 2c746583-8829-e111-81bd-0026b6bd8d3e@ubuntu
To: <sip:56@193.147.53.4>;tag=as344d3371
Proxy-Authorization: Digest username="58", realm="asterisk", nonce="2be856c5", uri="sip:56@193.147.53.4", algorithm=MD5, response="0c374d6486db9ed89256a2a77b54a5ea"
Content-Length: 0
Max-Forwards: 70


2011/12/20 15:56:50.633	  0:18.149	        Pool:0xb39efb70	OpalUDP	Setting interface to 192.168.1.35%wlan0
2011/12/20 15:56:50.633	  0:18.149	        Pool:0xb39efb70	SIP	INVITE transaction id=z9hG4bKe0db0784-8829-e111-81bd-0026b6bd8d3e completed.
2011/12/20 15:56:50.634	  0:18.149	        Pool:0xb39efb70	OpalCon	SetPhase from SetUpPhase to ReleasingPhase for Call[ndd6eb9521]-EP<sip>[cc6a6583-8829-e111-81bd-0026b6bd8d3e]
2011/12/20 15:56:50.634	  0:18.150	        Pool:0xb39efb70	OpalCon	Releasing Call[ndd6eb9521]-EP<sip>[cc6a6583-8829-e111-81bd-0026b6bd8d3e]
2011/12/20 15:56:50.634	  0:18.150	        Pool:0xb39efb70	OpalCon	Call end reason for Call[ndd6eb9521]-EP<sip>[cc6a6583-8829-e111-81bd-0026b6bd8d3e] set to EndedByCapabilityExchange
2011/12/20 15:56:50.634	  0:18.150	        Pool:0xb39efb70	PTLib	Thread high water mark set: 12
2011/12/20 15:56:50.634	  0:18.150	   OnRelease:0xb39aeb70	SIP	OnReleased: Call[ndd6eb9521]-EP<sip>[cc6a6583-8829-e111-81bd-0026b6bd8d3e], phase = ReleasingPhase
2011/12/20 15:56:50.634	  0:18.150	   OnRelease:0xb39aeb70	OpalCon	SetPhase from ReleasingPhase to ReleasingPhase for Call[ndd6eb9521]-EP<sip>[cc6a6583-8829-e111-81bd-0026b6bd8d3e]
2011/12/20 15:56:50.634	  0:18.150	        Pool:0xb39efb70	SIP	Handled PDU "2 INVITE <488>"
2011/12/20 15:56:50.635	  0:18.151	   OnRelease:0xb39aeb70	OpalCon	Media streams closed.
2011/12/20 15:56:50.635	  0:18.151	   OnRelease:0xb39aeb70	SIP	Awaiting forked INVITE transaction completion, id=z9hG4bKfc616683-8829-e111-81bd-0026b6bd8d3e
2011/12/20 15:56:50.635	  0:18.151	   OnRelease:0xb39aeb70	SIP	Awaiting forked INVITE transaction completion, id=z9hG4bKe0db0784-8829-e111-81bd-0026b6bd8d3e
2011/12/20 15:56:50.635	  0:18.151	   OnRelease:0xb39aeb70	OpalCon	SetPhase from ReleasingPhase to ReleasedPhase for Call[ndd6eb9521]-EP<sip>[cc6a6583-8829-e111-81bd-0026b6bd8d3e]
2011/12/20 15:56:50.635	  0:18.151	   OnRelease:0xb39aeb70	OpalCon	OnReleased Call[ndd6eb9521]-EP<sip>[cc6a6583-8829-e111-81bd-0026b6bd8d3e]
2011/12/20 15:56:50.635	  0:18.151	   OnRelease:0xb39aeb70	OpalEP	OnReleased Call[ndd6eb9521]-EP<sip>[cc6a6583-8829-e111-81bd-0026b6bd8d3e]
2011/12/20 15:56:50.635	  0:18.151	   OnRelease:0xb39aeb70	OpalMan	OnReleased Call[ndd6eb9521]-EP<sip>[cc6a6583-8829-e111-81bd-0026b6bd8d3e]
2011/12/20 15:56:50.635	  0:18.151	   OnRelease:0xb39aeb70	Call	OnReleased Call[ndd6eb9521]-EP<sip>[cc6a6583-8829-e111-81bd-0026b6bd8d3e]
2011/12/20 15:56:50.635	  0:18.151	   OnRelease:0xb39aeb70	OpalCon	SetPhase from SetUpPhase to ReleasingPhase for Call[ndd6eb9521]-EP<pc>[l181f87bb2]
2011/12/20 15:56:50.635	  0:18.151	   OnRelease:0xb39aeb70	OpalCon	Releasing Call[ndd6eb9521]-EP<pc>[l181f87bb2]
2011/12/20 15:56:50.636	  0:18.151	   OnRelease:0xb39aeb70	OpalCon	Call end reason for Call[ndd6eb9521]-EP<pc>[l181f87bb2] set to EndedByCapabilityExchange
2011/12/20 15:56:50.636	  0:18.152	   OnRelease:0xb39aeb70	OpalCon	OnReleased Call[ndd6eb9521]-EP<pc>[l181f87bb2]
2011/12/20 15:56:50.636	  0:18.152	   OnRelease:0xb39aeb70	OpalEP	OnReleased Call[ndd6eb9521]-EP<pc>[l181f87bb2]
2011/12/20 15:56:50.636	  0:18.152	   OnRelease:0xb39aeb70	OpalMan	OnReleased Call[ndd6eb9521]-EP<pc>[l181f87bb2]
2011/12/20 15:56:50.636	  0:18.152	   OnRelease:0xb39aeb70	Call	OnReleased Call[ndd6eb9521]-EP<pc>[l181f87bb2]
2011/12/20 15:56:50.636	  0:18.152	   OnRelease:0xb39aeb70	OpalCon	Media streams closed.
2011/12/20 15:56:50.636	  0:18.152	   OnRelease:0xb39aeb70	OpalCon	Media streams closed.
2011/12/20 15:56:50.636	  0:18.152	   OnRelease:0xb39aeb70	OpalCon	OnRelease thread completed for Call[ndd6eb9521]-EP<sip>[cc6a6583-8829-e111-81bd-0026b6bd8d3e]
2011/12/20 15:56:50.664	  0:18.180	                       	AEScheduler	Removing Event incoming_call_sound from queue
2011/12/20 15:56:50.665	  0:18.180	                       	AEScheduler	Removing Event ring_tone_sound from queue
2011/12/20 15:56:50.665	  0:18.181	                       	OpalMan	OnClearedCall Call[ndd6eb9521] from "pc:naxo" to "sip:56@193.147.53.4"
2011/12/20 15:56:50.665	  0:18.181	                       	OPAL	Attempting to close recording on call ndd6eb9521 when not recording.
2011/12/20 15:56:50.707	  0:18.223	GMVideoOut...0xb6a9fb70	X11	Unmapping and destroying Window with ID 77594626
2011/12/20 15:56:50.708	  0:18.224	GMVideoOut...0xb6a9fb70	GMVideoOutputManager_X	Opening VO_MODE_LOCAL display with image of 352x288
2011/12/20 15:56:50.708	  0:18.224	GMVideoOut...0xb6a9fb70	XVideo	Initializing XV window with 352x288 at 26,50
2011/12/20 15:56:50.710	  0:18.226	GMVideoOut...0xb6a9fb70	XVideo	XvQueryExtension: Version: 2 Release: 2 Request Base: 132 Event Base: 72 Error Base: 140
2011/12/20 15:56:50.710	  0:18.226	GMVideoOut...0xb6a9fb70	XVideo	#0, Adaptor: Nouveau GeForce 8/9 Textured Video, type: input | image | , ports: 32, first port: 63
2011/12/20 15:56:50.712	  0:18.228	GMVideoOut...0xb6a9fb70	XVideo	Encoding List for Port 63:  id=0 name=XV_IMAGE size=4096x4096 numerator=1 denominator=1
2011/12/20 15:56:50.712	  0:18.228	GMVideoOut...0xb6a9fb70	XVideo	Attribute List for Port 63:
2011/12/20 15:56:50.712	  0:18.228	GMVideoOut...0xb6a9fb70	  name:       XV_SET_DEFAULTS
2011/12/20 15:56:50.712	  0:18.228	GMVideoOut...0xb6a9fb70	  flags:      set
2011/12/20 15:56:50.712	  0:18.228	GMVideoOut...0xb6a9fb70	  min_color:  0
2011/12/20 15:56:50.712	  0:18.228	GMVideoOut...0xb6a9fb70	  max_color:  0
2011/12/20 15:56:50.712	  0:18.228	GMVideoOut...0xb6a9fb70	  name:       XV_SYNC_TO_VBLANK
2011/12/20 15:56:50.712	  0:18.228	GMVideoOut...0xb6a9fb70	  flags:      get set
2011/12/20 15:56:50.712	  0:18.228	GMVideoOut...0xb6a9fb70	  min_color:  0
2011/12/20 15:56:50.712	  0:18.228	GMVideoOut...0xb6a9fb70	  max_color:  1
2011/12/20 15:56:50.712	  0:18.228	GMVideoOut...0xb6a9fb70	XVideo	Image format list for Port 63:
2011/12/20 15:56:50.713	  0:18.229	GMVideoOut...0xb6a9fb70	  0x32315659 (YV12) planar, order: YVU
2011/12/20 15:56:50.713	  0:18.229	GMVideoOut...0xb6a9fb70	  0x30323449 (I420) planar, order: YUV
2011/12/20 15:56:50.713	  0:18.229	GMVideoOut...0xb6a9fb70	  0x32595559 (YUY2) packed, order: YUYV
2011/12/20 15:56:50.714	  0:18.230	GMVideoOut...0xb6a9fb70	XVideo	Grabbed Port: 63
2011/12/20 15:56:50.714	  0:18.230	GMVideoOut...0xb6a9fb70	XVideo	Using XVideo port: 63
2011/12/20 15:56:50.714	  0:18.230	GMVideoOut...0xb6a9fb70	XVideo	Found visual with colordepth of 24bits per pixel
2011/12/20 15:56:50.714	  0:18.230	GMVideoOut...0xb6a9fb70	X11	Created Window with ID 77594629
2011/12/20 15:56:50.715	  0:18.231	GMVideoOut...0xb6a9fb70	XVideo	Colorkey method: NONE
2011/12/20 15:56:50.715	  0:18.231	GMVideoOut...0xb6a9fb70	XVideo	Vertical sync successfully activated
2011/12/20 15:56:50.715	  0:18.231	GMVideoOut...0xb6a9fb70	XVideo	XQueryShmExtension success
2011/12/20 15:56:50.715	  0:18.231	GMVideoOut...0xb6a9fb70	XVideo	Created XvImage (352x288, data size: 152064, num_planes: 3
2011/12/20 15:56:50.715	  0:18.231	GMVideoOut...0xb6a9fb70	XVideo	  Plane 0: pitch=352, offset=0
2011/12/20 15:56:50.715	  0:18.231	GMVideoOut...0xb6a9fb70	XVideo	  Plane 1: pitch=176, offset=101376
2011/12/20 15:56:50.716	  0:18.231	GMVideoOut...0xb6a9fb70	XVideo	  Plane 2: pitch=176, offset=126720
2011/12/20 15:56:50.716	  0:18.232	GMVideoOut...0xb6a9fb70	XVideo	Using SHM extension
2011/12/20 15:56:50.717	  0:18.233	GMVideoOut...0xb6a9fb70	X11	Unknown wm type...
2011/12/20 15:56:50.717	  0:18.233	GMVideoOut...0xb6a9fb70	GMVideoOutputManager_X	VO_MODE_LOCAL: Successfully opened XV Window
2011/12/20 15:56:50.717	  0:18.233	GMVideoOut...0xb6a9fb70	X11	Unknown X Event 19 received
2011/12/20 15:56:50.787	  0:18.303	GMVideoOut...0xb6a9fb70	X11	Unmapping and destroying Window with ID 77594629
2011/12/20 15:56:50.788	  0:18.303	GMVideoOut...0xb6a9fb70	GMVideoOutputManager_X	Opening VO_MODE_LOCAL display with image of 352x288
2011/12/20 15:56:50.788	  0:18.304	GMVideoOut...0xb6a9fb70	XVideo	Initializing XV window with 352x288 at 26,26
2011/12/20 15:56:50.788	  0:18.304	GMVideoOut...0xb6a9fb70	XVideo	XvQueryExtension: Version: 2 Release: 2 Request Base: 132 Event Base: 72 Error Base: 140
2011/12/20 15:56:50.788	  0:18.304	GMVideoOut...0xb6a9fb70	XVideo	#0, Adaptor: Nouveau GeForce 8/9 Textured Video, type: input | image | , ports: 32, first port: 63
2011/12/20 15:56:50.788	  0:18.304	GMVideoOut...0xb6a9fb70	XVideo	Encoding List for Port 63:  id=0 name=XV_IMAGE size=4096x4096 numerator=1 denominator=1
2011/12/20 15:56:50.789	  0:18.305	GMVideoOut...0xb6a9fb70	XVideo	Attribute List for Port 63:
2011/12/20 15:56:50.790	  0:18.306	GMVideoOut...0xb6a9fb70	  name:       XV_SET_DEFAULTS
2011/12/20 15:56:50.790	  0:18.306	GMVideoOut...0xb6a9fb70	  flags:      set
2011/12/20 15:56:50.790	  0:18.306	GMVideoOut...0xb6a9fb70	  min_color:  0
2011/12/20 15:56:50.790	  0:18.306	GMVideoOut...0xb6a9fb70	  max_color:  0
2011/12/20 15:56:50.790	  0:18.306	GMVideoOut...0xb6a9fb70	  name:       XV_SYNC_TO_VBLANK
2011/12/20 15:56:50.790	  0:18.306	GMVideoOut...0xb6a9fb70	  flags:      get set
2011/12/20 15:56:50.791	  0:18.306	GMVideoOut...0xb6a9fb70	  min_color:  0
2011/12/20 15:56:50.791	  0:18.307	GMVideoOut...0xb6a9fb70	  max_color:  1
2011/12/20 15:56:50.791	  0:18.307	GMVideoOut...0xb6a9fb70	XVideo	Image format list for Port 63:
2011/12/20 15:56:50.791	  0:18.307	GMVideoOut...0xb6a9fb70	  0x32315659 (YV12) planar, order: YVU
2011/12/20 15:56:50.791	  0:18.307	GMVideoOut...0xb6a9fb70	  0x30323449 (I420) planar, order: YUV
2011/12/20 15:56:50.791	  0:18.307	GMVideoOut...0xb6a9fb70	  0x32595559 (YUY2) packed, order: YUYV
2011/12/20 15:56:50.791	  0:18.307	GMVideoOut...0xb6a9fb70	XVideo	Grabbed Port: 63
2011/12/20 15:56:50.791	  0:18.307	GMVideoOut...0xb6a9fb70	XVideo	Using XVideo port: 63
2011/12/20 15:56:50.792	  0:18.307	GMVideoOut...0xb6a9fb70	XVideo	Found visual with colordepth of 24bits per pixel
2011/12/20 15:56:50.792	  0:18.308	GMVideoOut...0xb6a9fb70	X11	Created Window with ID 77594632
2011/12/20 15:56:50.792	  0:18.308	GMVideoOut...0xb6a9fb70	XVideo	Colorkey method: NONE
2011/12/20 15:56:50.792	  0:18.308	GMVideoOut...0xb6a9fb70	XVideo	Vertical sync successfully activated
2011/12/20 15:56:50.792	  0:18.308	GMVideoOut...0xb6a9fb70	XVideo	XQueryShmExtension success
2011/12/20 15:56:50.792	  0:18.308	GMVideoOut...0xb6a9fb70	XVideo	Created XvImage (352x288, data size: 152064, num_planes: 3
2011/12/20 15:56:50.793	  0:18.309	GMVideoOut...0xb6a9fb70	XVideo	  Plane 0: pitch=352, offset=0
2011/12/20 15:56:50.793	  0:18.309	GMVideoOut...0xb6a9fb70	XVideo	  Plane 1: pitch=176, offset=101376
2011/12/20 15:56:50.793	  0:18.309	GMVideoOut...0xb6a9fb70	XVideo	  Plane 2: pitch=176, offset=126720
2011/12/20 15:56:50.793	  0:18.309	GMVideoOut...0xb6a9fb70	XVideo	Using SHM extension
2011/12/20 15:56:50.793	  0:18.309	GMVideoOut...0xb6a9fb70	X11	Unknown wm type...
2011/12/20 15:56:50.793	  0:18.309	GMVideoOut...0xb6a9fb70	GMVideoOutputManager_X	VO_MODE_LOCAL: Successfully opened XV Window
2011/12/20 15:56:50.793	  0:18.309	GMVideoOut...0xb6a9fb70	X11	Unknown X Event 19 received
2011/12/20 15:56:51.539	  0:19.055	 Housekeeper:0xb69dcb70	SIP	Set state Terminated_Success for INVITE transaction id=z9hG4bKfc616683-8829-e111-81bd-0026b6bd8d3e
2011/12/20 15:56:51.589	  0:19.105	Opal Garbage:0xb6a5eb70	PCSS	Deleted PC sound system connection.
2011/12/20 15:56:51.589	  0:19.105	Opal Garbage:0xb6a5eb70	OpalCon	Connection Call[ndd6eb9521]-EP<pc>[l181f87bb2] destroyed.
2011/12/20 15:56:51.590	  0:19.106	Opal Garbage:0xb6a5eb70	SIP	Transaction id=z9hG4bKfc616683-8829-e111-81bd-0026b6bd8d3e destroyed.
2011/12/20 15:56:51.634	  0:19.150	 Housekeeper:0xb69dcb70	SIP	Set state Terminated_Success for INVITE transaction id=z9hG4bKe0db0784-8829-e111-81bd-0026b6bd8d3e
2011/12/20 15:56:52.590	  0:20.106	Opal Garbage:0xb6a5eb70	RTP	Closing session 1
2011/12/20 15:56:52.591	  0:20.106	Opal Garbage:0xb6a5eb70	RTP	Deleting session 1
2011/12/20 15:56:52.591	  0:20.107	Opal Garbage:0xb6a5eb70	RTP_UDP	Session 1, Shutting down read.
2011/12/20 15:56:52.591	  0:20.107	Opal Garbage:0xb6a5eb70	RTP_UDP	Session 1, Shutting down write.
2011/12/20 15:56:52.591	  0:20.107	Opal Garbage:0xb6a5eb70	RTP	Closing session 2
2011/12/20 15:56:52.591	  0:20.107	Opal Garbage:0xb6a5eb70	RTP	Deleting session 2
2011/12/20 15:56:52.591	  0:20.107	Opal Garbage:0xb6a5eb70	RTP_UDP	Session 2, Shutting down read.
2011/12/20 15:56:52.591	  0:20.107	Opal Garbage:0xb6a5eb70	RTP_UDP	Session 2, Shutting down write.
2011/12/20 15:56:52.592	  0:20.108	Opal Garbage:0xb6a5eb70	SIP	Transaction id=z9hG4bKe0db0784-8829-e111-81bd-0026b6bd8d3e destroyed.
2011/12/20 15:56:52.592	  0:20.108	Opal Garbage:0xb6a5eb70	Opal	Transport clean up on termination
2011/12/20 15:56:52.592	  0:20.108	Opal Garbage:0xb6a5eb70	Opal	Transport Close
2011/12/20 15:56:52.592	  0:20.108	Opal Garbage:0xb6a5eb70	Opal	Transport clean up on termination
2011/12/20 15:56:52.593	  0:20.108	Opal Garbage:0xb6a5eb70	Opal	Transport Close
2011/12/20 15:56:52.593	  0:20.109	Opal Garbage:0xb6a5eb70	PWLib	File handle low water mark set: 51 PUDPSocket
2011/12/20 15:56:52.593	  0:20.109	Opal Garbage:0xb6a5eb70	Opal	Deleted transport udp$193.147.53.4:5060<if=udp$83.55.44.186:11557>
2011/12/20 15:56:52.593	  0:20.109	Opal Garbage:0xb6a5eb70	SIP	Deleted connection.
2011/12/20 15:56:52.595	  0:20.111	Opal Garbage:0xb6a5eb70	OpalCon	Connection Call[ndd6eb9521]-EP<sip>[cc6a6583-8829-e111-81bd-0026b6bd8d3e] destroyed.
2011/12/20 15:56:53.665	  0:21.181	                       	Call	Call[ndd6eb9521] destroyed.
2011/12/20 15:57:09.439	  0:36.955	                       	PreviewManager	Stopping Preview
2011/12/20 15:57:09.448	  0:36.964	GMVideoOut...0xb6a9fb70	X11	Unmapping and destroying Window with ID 77594632
2011/12/20 15:57:09.449	  0:36.965	Network In...0xb6a1db70	IfaceMon	Finished interface monitor thread.


[Date Prev][Date Next]   [Thread Prev][Thread Next]   [Thread Index] [Date Index] [Author Index]