[Ekiga-list] Problems with Registering



I have just installed ekiga and cannot seem to get it to register.
The operating system is Mint Linux and I have used the Mint repository
to install Ekiga.

The computer is behind a router and the port forwarding has been set to
the computer static address:
SIP - 5000 - 5100 UDP
STUN - 3478 - 3479 UDP
H323 - 1720 TCP


I ran the following from the command line to get debugging information:

ekiga -d 4 2> ekiga0.log

The output file is attached.

Do you have any ideas on what I am doing wrong to get connected?

Regards,
David Hughes

2011/08/16 15:56:52.688	  0:00.070	                       		Version 3.2.7 by  on Unix Linux (2.6.38-8-generic-i686) with PTLib (v2.6.7) at 2011/8/16 15:56:52.688
2011/08/16 15:56:52.688	  0:00.070	                       	Ekiga git revision: unknown
2011/08/16 15:56:52.691	  0:00.073	                       	Ekiga registered on D-Bus: org.ekiga.Ekiga
2011/08/16 15:56:52.692	  0:00.074	                       	PWLib	File handle high water mark set: 19 Thread unblock pipe
2011/08/16 15:56:52.692	  0:00.074	                       	PTLib	No permission to set priority level 4
2011/08/16 15:56:52.692	  0:00.074	                       	PTLib	Thread high water mark set: 2
2011/08/16 15:56:52.692	  0:00.074	                       	PWLib	File handle high water mark set: 21 Thread unblock pipe
2011/08/16 15:56:52.692	  0:00.074	                       	PTLib	No permission to set priority level 4
2011/08/16 15:56:52.692	  0:00.074	                       	PTLib	Thread high water mark set: 3
2011/08/16 15:56:52.693	  0:00.074	                       	PWLib	File handle high water mark set: 23 Thread unblock pipe
2011/08/16 15:56:52.694	  0:00.075	                       	PWLib	File handle high water mark set: 25 Thread unblock pipe
2011/08/16 15:56:52.695	  0:00.077	                       	PTLib	No permission to set priority level 4
2011/08/16 15:56:52.695	  0:00.077	                       	PTLib	Thread high water mark set: 4
2011/08/16 15:56:52.695	  0:00.077	                       	HalManager_dbus	Initialising HAL Manager
2011/08/16 15:56:52.697	  0:00.079	                       	HalManager_dbus	Populating device list
2011/08/16 15:56:52.795	  0:00.176	                       	HalManager_dbus	Populated device list with 9 devices
2011/08/16 15:56:52.795	  0:00.177	                       	HalManager_dbus	Populating interface list
2011/08/16 15:56:52.795	  0:00.177	                       	HalManager_dbus	Populating full interface list failed - Method "getDevices" with signature "" on interface "org.freedesktop.NetworkManager" doesn't exist

2011/08/16 15:56:52.812	  0:00.194	                       	Detecting V4L2 devices
2011/08/16 15:56:52.812	  0:00.194	                       	PV4L2Plugin	detected device metadata at /sys/class/video4linux/
2011/08/16 15:56:52.829	  0:00.211	                       	PWLib	File handle high water mark set: 30 Thread unblock pipe
2011/08/16 15:56:52.829	  0:00.211	                       	PTLib	Thread high water mark set: 5
2011/08/16 15:56:52.829	  0:00.211	                       	OpalMan	Created manager.
2011/08/16 15:56:52.829	  0:00.211	                       	OpalMan	Attached endpoint with prefix pc
2011/08/16 15:56:52.829	  0:00.211	                       	OpalEP	Created endpoint: pc
2011/08/16 15:56:52.905	  0:00.287	                       	PCSS	Created PC sound system endpoint.
Players:
Default
HDA Intel
EKIGA
*.wav
Recorders:
Default
HDA Intel
EKIGA
*.wav
USB Device 0x46d:0x8dd

2011/08/16 15:56:52.906	  0:00.287	                       	OPAL	SetMediaFormatOrder()
2011/08/16 15:56:52.906	  0:00.287	                       	OPAL	SetMediaFormatMask()
2011/08/16 15:56:52.906	  0:00.288	                       	OpalMan	Attached endpoint with prefix sip
2011/08/16 15:56:52.906	  0:00.288	                       	OpalEP	Created endpoint: sip
2011/08/16 15:56:52.907	  0:00.288	                       	PWLib	File handle high water mark set: 31 PUDPSocket
2011/08/16 15:56:52.907	  0:00.288	                       	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.2.90 [fe:80:00:00:00:00:00:00:02:24:d2:ff:fe:92:5f:de] <00-24-D2-92-5F-DE> (wlan0)

2011/08/16 15:56:52.907	  0:00.288	                       	PWLib	File handle high water mark set: 32 Thread unblock pipe
2011/08/16 15:56:52.907	  0:00.289	                       	PTLib	Thread high water mark set: 6
2011/08/16 15:56:52.907	  0:00.289	Network In...:0x1f04b70	IfaceMon	Started interface monitor thread.
2011/08/16 15:56:52.907	  0:00.289	                       	PWLib	File handle high water mark set: 34 Thread unblock pipe
2011/08/16 15:56:52.907	  0:00.289	                       	PTLib	Thread high water mark set: 7
2011/08/16 15:56:52.907	  0:00.289	Network In...:0x1f04b70	PWLib	File handle high water mark set: 35 PUDPSocket
2011/08/16 15:56:52.907	  0:00.289	                       	OpalMan	Attached endpoint with prefix sips
2011/08/16 15:56:52.907	  0:00.289	                       	SIP	Created endpoint.
2011/08/16 15:56:52.908	  0:00.289	                       	MonSock	Created socket bundle for all interfaces.
2011/08/16 15:56:52.908	  0:00.289	                       	PWLib	File handle high water mark set: 36 PUDPSocket
2011/08/16 15:56:52.908	  0:00.290	                       	MonSock	Created bundled UDP socket 192.168.2.90:5060
2011/08/16 15:56:52.908	  0:00.290	                       	PWLib	File handle high water mark set: 38 Thread unblock pipe
2011/08/16 15:56:52.908	  0:00.290	                       	PTLib	Thread high water mark set: 8
2011/08/16 15:56:52.908	  0:00.290	                       	PTLib	No permission to set priority level 4
2011/08/16 15:56:52.908	  0:00.290	Opal Listener:0x1eb0b70	Listen	Started listening thread on udp$*:5060
2011/08/16 15:56:52.909	  0:00.290	                       	OpalMan	Added route "sip:.*=pc:*"
2011/08/16 15:56:52.909	  0:00.290	                       	OpalMan	Added route "pc:.*=sip:<da>"
2011/08/16 15:56:52.909	  0:00.291	                       	OpalMan	Attached endpoint with prefix h323
2011/08/16 15:56:52.909	  0:00.291	                       	OpalEP	Created endpoint: h323
2011/08/16 15:56:52.909	  0:00.291	                       	OpalMan	Attached endpoint with prefix h323s
2011/08/16 15:56:52.909	  0:00.291	                       	H323	Created endpoint.
2011/08/16 15:56:52.909	  0:00.291	                       	PWLib	File handle high water mark set: 39 PTCPSocket
2011/08/16 15:56:52.909	  0:00.291	                       	PWLib	File handle high water mark set: 41 Thread unblock pipe
2011/08/16 15:56:52.910	  0:00.291	                       	PTLib	Thread high water mark set: 9
2011/08/16 15:56:52.910	  0:00.291	Opal Listener:0x1f45b70	Listen	Started listening thread on tcp$*:1720
2011/08/16 15:56:52.910	  0:00.291	                       	OpalMan	Added route "h323:.*=pc:<db>"
2011/08/16 15:56:52.910	  0:00.291	Opal Listener:0x1f45b70	Listen	Waiting on socket accept on tcp$*:1720
2011/08/16 15:56:52.910	  0:00.291	                       	OpalMan	Added route "pc:.*=h323:<da>"
2011/08/16 15:56:52.919	  0:00.301	                       	MediaFormat	Removing codecs SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,theora,H.261,H.261-CIF,H.261-QCIF
2011/08/16 15:56:52.920	  0:00.301	                       	OPAL	SetMediaFormatMask(PCM-16-48kHz,CELT-48K,PCM-16-32kHz,CELT-32K,PCM-16-16kHz,G.722-64k,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz)
2011/08/16 15:56:52.920	  0:00.302	                       	OPAL	SetMediaFormatOrder(SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,theora,H.261,H.261-CIF,H.261-QCIF)
2011/08/16 15:56:52.928	  0:00.310	                       	MediaFormat	Removing codecs SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,theora,H.261,H.261-CIF,H.261-QCIF
2011/08/16 15:56:52.928	  0:00.310	                       	OPAL	SetMediaFormatMask(PCM-16-48kHz,CELT-48K,PCM-16-32kHz,CELT-32K,PCM-16-16kHz,G.722-64k,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz)
2011/08/16 15:56:52.928	  0:00.310	                       	OPAL	SetMediaFormatOrder(SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,theora,H.261,H.261-CIF,H.261-QCIF)
2011/08/16 15:56:52.943	  0:00.325	                       	PWLib	File handle high water mark set: 43 Thread unblock pipe
2011/08/16 15:56:52.943	  0:00.325	                       	PTLib	Thread high water mark set: 10
2011/08/16 15:56:52.943	  0:00.325	   subscriber:0x7303b70	SIP	Start REGISTER
        aor=dwhughes00 ekiga net
  registrar=ekiga.net
    contact=
     authID=dwhughes00
      realm=
     expire=3600
    restore=30
   minRetry=0.000
   maxRetry=0.000
2011/08/16 15:56:52.944	  0:00.325	   subscriber:0x7303b70	PWLib	File handle high water mark set: 44 PUDPSocket
2011/08/16 15:56:52.944	  0:00.326	   subscriber:0x7303b70	SIP	Changing REGISTER handler from Unavailable to Subscribing, target=sip:dwhughes00 ekiga net, id=ca4607f0-b7c6-e011-9ffa-0024d2925fde@portable
2011/08/16 15:56:52.944	  0:00.326	   subscriber:0x7303b70	DNS	SRV Lookup ekiga.net service _sip._udp
2011/08/16 15:56:52.986	  0:00.367	                       	SIP	Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:500 ekiga net, id=dab70df0-b7c6-e011-9ffa-0024d2925fde@portable
2011/08/16 15:56:52.986	  0:00.367	                       	DNS	SRV Lookup ekiga.net service _sip._udp
2011/08/16 15:56:53.097	  0:00.479	   subscriber:0x7303b70	SIP	No SRV record found.
2011/08/16 15:56:53.141	  0:00.523	                       	SIP	No SRV record found.
2011/08/16 15:56:53.249	  0:00.631	   subscriber:0x7303b70	OpalUDP	Binding to interface: 0.0.0.0:5060
2011/08/16 15:56:53.249	  0:00.631	   subscriber:0x7303b70	SIP	Created transport udp$86.64.162.35:5060<if=udp$*:5060>
2011/08/16 15:56:53.250	  0:00.632	   subscriber:0x7303b70	OpalUDP	Started connect to 86.64.162.35:5060
2011/08/16 15:56:53.250	  0:00.632	   subscriber:0x7303b70	OpalUDP	Writing to interface 0 - "192.168.2.90%wlan0"
2011/08/16 15:56:53.253	  0:00.635	   subscriber:0x7303b70	OpalMan	Listener interfaces: associated transport=udp$192.168.2.90:5060
    udp$192.168.2.90:5060
2011/08/16 15:56:53.254	  0:00.636	   subscriber:0x7303b70	SIP	Transaction created.
2011/08/16 15:56:53.257	  0:00.639	   subscriber:0x7303b70	DNS	SRV Lookup ekiga.net service _sip._udp
2011/08/16 15:56:53.292	  0:00.674	                       	OpalUDP	Binding to interface: 0.0.0.0:5060
2011/08/16 15:56:53.293	  0:00.674	                       	PWLib	File handle high water mark set: 50 PUDPSocket
2011/08/16 15:56:53.292	  0:00.674	                       	SIP	Created transport udp$86.64.162.35:5060<if=udp$*:5060>
2011/08/16 15:56:53.293	  0:00.675	                       	OpalUDP	Started connect to 86.64.162.35:5060
2011/08/16 15:56:53.293	  0:00.675	                       	OpalUDP	Writing to interface 0 - "192.168.2.90%wlan0"
2011/08/16 15:56:53.294	  0:00.675	                       	SIP	Transaction created.
2011/08/16 15:56:53.298	  0:00.680	                       	DNS	SRV Lookup ekiga.net service _sip._udp
2011/08/16 15:56:53.414	  0:00.796	   subscriber:0x7303b70	SIP	No SRV record found.
2011/08/16 15:56:53.414	  0:00.796	   subscriber:0x7303b70	SIP	Transaction remote address is udp$ekiga.net:5060
2011/08/16 15:56:53.415	  0:00.797	   subscriber:0x7303b70	PWLib	File handle low water mark set: 44 PUDPSocket
2011/08/16 15:56:53.415	  0:00.796	   subscriber:0x7303b70	SIP	Sending PDU (508 bytes) to: rem=udp$86.64.162.35:5060,local=udp$192.168.2.90:5060,if=192.168.2.90%wlan0
REGISTER sip:ekiga.net SIP/2.0
CSeq: 1 REGISTER
Via: SIP/2.0/UDP 192.168.2.90:5060;branch=z9hG4bK261537f0-b7c6-e011-9ffa-0024d2925fde;rport
User-Agent: Ekiga/3.2.7
From: <sip:dwhughes00 ekiga net>;tag=c86107f0-b7c6-e011-9ffa-0024d2925fde
Call-ID: ca4607f0-b7c6-e011-9ffa-0024d2925fde@portable
To: <sip:dwhughes00 ekiga net>
Contact: <sip:dwhughes00@192.168.2.90>;q=1
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Expires: 3600
Content-Length: 0
Max-Forwards: 70


2011/08/16 15:56:53.416	  0:00.797	   subscriber:0x7303b70	OpalUDP	Setting interface to 192.168.2.90%wlan0
2011/08/16 15:56:53.416	  0:00.798	   subscriber:0x7303b70	SIP	Transaction timers set: retry=0.500, completion=6.000
2011/08/16 15:56:53.471	  0:00.853	                       	SIP	No SRV record found.
2011/08/16 15:56:53.471	  0:00.853	                       	SIP	Transaction remote address is udp$ekiga.net:5060
2011/08/16 15:56:53.472	  0:00.854	                       	PWLib	File handle low water mark set: 42 PUDPSocket
2011/08/16 15:56:53.472	  0:00.854	                       	SIP	Sending PDU (549 bytes) to: rem=udp$86.64.162.35:5060,local=udp$192.168.2.90:5060,if=192.168.2.90%wlan0
SUBSCRIBE sip:500 ekiga net SIP/2.0
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 192.168.2.90:5060;branch=z9hG4bK94143df0-b7c6-e011-9ffa-0024d2925fde;rport
User-Agent: Ekiga/3.2.7
From: <sip:dwhughes00 ekiga net>;tag=7abb3cf0-b7c6-e011-9ffa-0024d2925fde
Call-ID: dab70df0-b7c6-e011-9ffa-0024d2925fde@portable
To: <sip:500 ekiga net>
Contact: <sip:dwhughes00@192.168.2.90>
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/08/16 15:56:53.473	  0:00.855	                       	OpalUDP	Setting interface to 192.168.2.90%wlan0
2011/08/16 15:56:53.473	  0:00.855	                       	SIP	Transaction timers set: retry=0.500, completion=6.000
2011/08/16 15:56:53.474	  0:00.855	                       	SIP	Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:500 ekiga net, id=3c3358f0-b7c6-e011-9ffa-0024d2925fde@portable
2011/08/16 15:56:53.474	  0:00.856	                       	DNS	SRV Lookup ekiga.net service _sip._udp
2011/08/16 15:56:53.648	  0:01.029	                       	SIP	No SRV record found.
2011/08/16 15:56:53.648	  0:01.030	                       	OpalUDP	Binding to interface: 0.0.0.0:5060
2011/08/16 15:56:53.648	  0:01.030	                       	SIP	Created transport udp$86.64.162.35:5060<if=udp$*:5060>
2011/08/16 15:56:53.649	  0:01.031	                       	OpalUDP	Started connect to 86.64.162.35:5060
2011/08/16 15:56:53.649	  0:01.031	                       	OpalUDP	Writing to interface 0 - "192.168.2.90%wlan0"
2011/08/16 15:56:53.650	  0:01.031	                       	SIP	Transaction created.
2011/08/16 15:56:53.654	  0:01.036	                       	DNS	SRV Lookup ekiga.net service _sip._udp
2011/08/16 15:56:53.736	  0:01.118	Opal Listener:0x1eb0b70	OpalUDP	Binding to interface: 192.168.2.90:5060
2011/08/16 15:56:53.736	  0:01.118	Opal Listener:0x1eb0b70	SIP	Waiting for PDU on udp$86.64.162.35:5060<if=udp$192.168.2.90:5060>
2011/08/16 15:56:53.737	  0:01.119	Opal Listener:0x1eb0b70	SIP	PDU received: rem=udp$86.64.162.35:5060,local=udp$192.168.2.90:5060,if=192.168.2.90%wlan0
SIP/2.0 606 Not Acceptable
CSeq: 1 REGISTER
Via: SIP/2.0/UDP 192.168.2.90:5060;branch=z9hG4bK261537f0-b7c6-e011-9ffa-0024d2925fde;rport=61537;received=173.184.21.5
Server: Kamailio (1.5.3-notls (i386/linux))
From: <sip:dwhughes00 ekiga net>;tag=c86107f0-b7c6-e011-9ffa-0024d2925fde
Call-ID: ca4607f0-b7c6-e011-9ffa-0024d2925fde@portable
To: <sip:dwhughes00 ekiga net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.697a
Content-Length: 0


2011/08/16 15:56:53.738	  0:01.120	Opal Listener:0x1eb0b70	SIP	Queueing PDU "1 REGISTER <606>", transaction=z9hG4bK261537f0-b7c6-e011-9ffa-0024d2925fde, token=ca4607f0-b7c6-e011-9ffa-0024d2925fde@portable
2011/08/16 15:56:53.738	  0:01.120	Opal Listener:0x1eb0b70	PTLib	No permission to set priority level 3
2011/08/16 15:56:53.739	  0:01.120	Opal Listener:0x1eb0b70	Opal	Transport clean up on termination
2011/08/16 15:56:53.739	  0:01.120	Opal Listener:0x1eb0b70	Opal	Transport Close
2011/08/16 15:56:53.739	  0:01.120	         Pool:0x7303b70	SIP	Handling PDU "1 REGISTER <606>" for transaction=z9hG4bK261537f0-b7c6-e011-9ffa-0024d2925fde
2011/08/16 15:56:53.739	  0:01.121	         Pool:0x7303b70	SIP	REGISTER transaction id=z9hG4bK261537f0-b7c6-e011-9ffa-0024d2925fde completed.
2011/08/16 15:56:53.739	  0:01.121	         Pool:0x7303b70	OpalUDP	Setting interface to 192.168.2.90%wlan0
2011/08/16 15:56:53.739	  0:01.121	Opal Listener:0x1eb0b70	PWLib	File handle high water mark set: 51 PUDPSocket
2011/08/16 15:56:53.739	  0:01.121	         Pool:0x7303b70	SIP	Not retrying REGISTER due to error response 606 Not Acceptable
2011/08/16 15:56:53.739	  0:01.121	         Pool:0x7303b70	SIP	Changing REGISTER handler from Subscribing to Unsubscribed, target=sip:dwhughes00 ekiga net, id=ca4607f0-b7c6-e011-9ffa-0024d2925fde@portable
2011/08/16 15:56:53.739	  0:01.120	Opal Listener:0x1eb0b70	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$192.168.2.90:5060>
2011/08/16 15:56:53.740	  0:01.121	         Pool:0x7303b70	SIP	Handled PDU "1 REGISTER <606>"
2011/08/16 15:56:53.785	  0:01.167	Opal Listener:0x1eb0b70	PWLib	File handle low water mark set: 50 PUDPSocket
2011/08/16 15:56:53.785	  0:01.167	Opal Listener:0x1eb0b70	OpalUDP	Binding to interface: 192.168.2.90:5060
2011/08/16 15:56:53.785	  0:01.167	Opal Listener:0x1eb0b70	SIP	Waiting for PDU on udp$86.64.162.35:5060<if=udp$192.168.2.90:5060>
2011/08/16 15:56:53.786	  0:01.168	Opal Listener:0x1eb0b70	SIP	PDU received: rem=udp$86.64.162.35:5060,local=udp$192.168.2.90:5060,if=192.168.2.90%wlan0
SIP/2.0 489 Event Not Supported
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 192.168.2.90:5060;branch=z9hG4bK94143df0-b7c6-e011-9ffa-0024d2925fde;rport=61537;received=173.184.21.5
Server: Kamailio (1.5.3-notls (i386/linux))
From: <sip:dwhughes00 ekiga net>;tag=7abb3cf0-b7c6-e011-9ffa-0024d2925fde
Call-ID: dab70df0-b7c6-e011-9ffa-0024d2925fde@portable
To: <sip:500 ekiga net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.b91f
Content-Length: 0


2011/08/16 15:56:53.787	  0:01.169	Opal Listener:0x1eb0b70	SIP	Queueing PDU "2 SUBSCRIBE <489>", transaction=z9hG4bK94143df0-b7c6-e011-9ffa-0024d2925fde, token=dab70df0-b7c6-e011-9ffa-0024d2925fde@portable
2011/08/16 15:56:53.787	  0:01.169	Opal Listener:0x1eb0b70	Opal	Transport clean up on termination
2011/08/16 15:56:53.787	  0:01.169	Opal Listener:0x1eb0b70	Opal	Transport Close
2011/08/16 15:56:53.787	  0:01.169	         Pool:0x7303b70	SIP	Handling PDU "2 SUBSCRIBE <489>" for transaction=z9hG4bK94143df0-b7c6-e011-9ffa-0024d2925fde
2011/08/16 15:56:53.788	  0:01.169	         Pool:0x7303b70	SIP	SUBSCRIBE transaction id=z9hG4bK94143df0-b7c6-e011-9ffa-0024d2925fde completed.
2011/08/16 15:56:53.788	  0:01.170	         Pool:0x7303b70	OpalUDP	Setting interface to 192.168.2.90%wlan0
2011/08/16 15:56:53.788	  0:01.170	         Pool:0x7303b70	SIP	Not retrying SUBSCRIBE due to error response 489 Bad Event
2011/08/16 15:56:53.788	  0:01.170	         Pool:0x7303b70	SIP	Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:500 ekiga net, id=dab70df0-b7c6-e011-9ffa-0024d2925fde@portable
2011/08/16 15:56:53.788	  0:01.170	         Pool:0x7303b70	SIP	Handled PDU "2 SUBSCRIBE <489>"
2011/08/16 15:56:53.788	  0:01.169	Opal Listener:0x1eb0b70	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$192.168.2.90:5060>
2011/08/16 15:56:53.803	  0:01.185	                       	SIP	No SRV record found.
2011/08/16 15:56:53.803	  0:01.185	                       	SIP	Transaction remote address is udp$ekiga.net:5060
2011/08/16 15:56:53.804	  0:01.186	                       	PWLib	File handle low water mark set: 42 PUDPSocket
2011/08/16 15:56:53.804	  0:01.185	                       	SIP	Sending PDU (554 bytes) to: rem=udp$86.64.162.35:5060,local=udp$192.168.2.90:5060,if=192.168.2.90%wlan0
SUBSCRIBE sip:500 ekiga net SIP/2.0
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 192.168.2.90:5060;branch=z9hG4bKec6273f0-b7c6-e011-9ffa-0024d2925fde;rport
User-Agent: Ekiga/3.2.7
From: <sip:dwhughes00 ekiga net>;tag=cc0a73f0-b7c6-e011-9ffa-0024d2925fde
Call-ID: 3c3358f0-b7c6-e011-9ffa-0024d2925fde@portable
To: <sip:500 ekiga net>
Contact: <sip:500@192.168.2.90>
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/08/16 15:56:53.805	  0:01.186	                       	OpalUDP	Setting interface to 192.168.2.90%wlan0
2011/08/16 15:56:53.805	  0:01.187	                       	SIP	Transaction timers set: retry=0.500, completion=6.000
2011/08/16 15:56:53.806	  0:01.188	                       	SIP	Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:501 ekiga net, id=bae18af0-b7c6-e011-9ffa-0024d2925fde@portable
2011/08/16 15:56:53.806	  0:01.188	                       	DNS	SRV Lookup ekiga.net service _sip._udp
2011/08/16 15:56:53.829	  0:01.211	 Opal Garbage:0x1e2eb70	SIP	Deleting SIPRegisterHandler sip:dwhughes00 ekiga net
2011/08/16 15:56:53.830	  0:01.211	 Opal Garbage:0x1e2eb70	Opal	Transport clean up on termination
2011/08/16 15:56:53.830	  0:01.211	 Opal Garbage:0x1e2eb70	Opal	Transport Close
2011/08/16 15:56:53.830	  0:01.211	 Opal Garbage:0x1e2eb70	Opal	Transport clean up on termination
2011/08/16 15:56:53.830	  0:01.211	 Opal Garbage:0x1e2eb70	Opal	Transport Close
2011/08/16 15:56:53.830	  0:01.212	 Opal Garbage:0x1e2eb70	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$192.168.2.90:5060>
2011/08/16 15:56:53.831	  0:01.212	 Opal Garbage:0x1e2eb70	SIP	Deleted handler.
2011/08/16 15:56:53.831	  0:01.213	 Opal Garbage:0x1e2eb70	SIP	Deleting SIPSubscribeHandler sip:500 ekiga net
2011/08/16 15:56:53.831	  0:01.213	 Opal Garbage:0x1e2eb70	Opal	Transport clean up on termination
2011/08/16 15:56:53.831	  0:01.213	 Opal Garbage:0x1e2eb70	Opal	Transport Close
2011/08/16 15:56:53.831	  0:01.213	 Opal Garbage:0x1e2eb70	Opal	Transport clean up on termination
2011/08/16 15:56:53.831	  0:01.213	 Opal Garbage:0x1e2eb70	Opal	Transport Close
2011/08/16 15:56:53.831	  0:01.213	 Opal Garbage:0x1e2eb70	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$192.168.2.90:5060>
2011/08/16 15:56:53.832	  0:01.214	 Opal Garbage:0x1e2eb70	SIP	Deleted handler.
2011/08/16 15:56:53.973	  0:01.354	                       	SIP	No SRV record found.
2011/08/16 15:56:53.973	  0:01.355	                       	OpalUDP	Binding to interface: 0.0.0.0:5060
2011/08/16 15:56:53.973	  0:01.355	                       	SIP	Created transport udp$86.64.162.35:5060<if=udp$*:5060>
2011/08/16 15:56:53.974	  0:01.356	                       	OpalUDP	Started connect to 86.64.162.35:5060
2011/08/16 15:56:53.974	  0:01.356	                       	OpalUDP	Writing to interface 0 - "192.168.2.90%wlan0"
2011/08/16 15:56:53.975	  0:01.356	                       	SIP	Transaction created.
2011/08/16 15:56:53.979	  0:01.361	                       	DNS	SRV Lookup ekiga.net service _sip._udp
2011/08/16 15:56:54.144	  0:01.526	                       	SIP	No SRV record found.
2011/08/16 15:56:54.144	  0:01.526	                       	SIP	Transaction remote address is udp$ekiga.net:5060
2011/08/16 15:56:54.145	  0:01.526	                       	SIP	Sending PDU (549 bytes) to: rem=udp$86.64.162.35:5060,local=udp$192.168.2.90:5060,if=192.168.2.90%wlan0
SUBSCRIBE sip:501 ekiga net SIP/2.0
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 192.168.2.90:5060;branch=z9hG4bK80f7a4f0-b7c6-e011-9ffa-0024d2925fde;rport
User-Agent: Ekiga/3.2.7
From: <sip:dwhughes00 ekiga net>;tag=a89da4f0-b7c6-e011-9ffa-0024d2925fde
Call-ID: bae18af0-b7c6-e011-9ffa-0024d2925fde@portable
To: <sip:501 ekiga net>
Contact: <sip:dwhughes00@192.168.2.90>
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/08/16 15:56:54.145	  0:01.527	                       	OpalUDP	Setting interface to 192.168.2.90%wlan0
2011/08/16 15:56:54.146	  0:01.527	                       	SIP	Transaction timers set: retry=0.500, completion=6.000
2011/08/16 15:56:54.146	  0:01.528	                       	SIP	Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:501 ekiga net, id=d4cfbef0-b7c6-e011-9ffa-0024d2925fde@portable
2011/08/16 15:56:54.146	  0:01.528	                       	DNS	SRV Lookup ekiga.net service _sip._udp
2011/08/16 15:56:54.159	  0:01.541	Opal Listener:0x1eb0b70	OpalUDP	Binding to interface: 192.168.2.90:5060
2011/08/16 15:56:54.159	  0:01.541	Opal Listener:0x1eb0b70	SIP	Waiting for PDU on udp$86.64.162.35:5060<if=udp$192.168.2.90:5060>
2011/08/16 15:56:54.160	  0:01.542	Opal Listener:0x1eb0b70	SIP	PDU received: rem=udp$86.64.162.35:5060,local=udp$192.168.2.90:5060,if=192.168.2.90%wlan0
SIP/2.0 489 Event Not Supported
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 192.168.2.90:5060;branch=z9hG4bKec6273f0-b7c6-e011-9ffa-0024d2925fde;rport=61537;received=173.184.21.5
Server: Kamailio (1.5.3-notls (i386/linux))
From: <sip:dwhughes00 ekiga net>;tag=cc0a73f0-b7c6-e011-9ffa-0024d2925fde
Call-ID: 3c3358f0-b7c6-e011-9ffa-0024d2925fde@portable
To: <sip:500 ekiga net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.62e3
Content-Length: 0


2011/08/16 15:56:54.161	  0:01.543	Opal Listener:0x1eb0b70	SIP	Queueing PDU "2 SUBSCRIBE <489>", transaction=z9hG4bKec6273f0-b7c6-e011-9ffa-0024d2925fde, token=3c3358f0-b7c6-e011-9ffa-0024d2925fde@portable
2011/08/16 15:56:54.161	  0:01.543	Opal Listener:0x1eb0b70	Opal	Transport clean up on termination
2011/08/16 15:56:54.161	  0:01.543	Opal Listener:0x1eb0b70	Opal	Transport Close
2011/08/16 15:56:54.161	  0:01.543	         Pool:0x7303b70	SIP	Handling PDU "2 SUBSCRIBE <489>" for transaction=z9hG4bKec6273f0-b7c6-e011-9ffa-0024d2925fde
2011/08/16 15:56:54.161	  0:01.543	         Pool:0x7303b70	SIP	SUBSCRIBE transaction id=z9hG4bKec6273f0-b7c6-e011-9ffa-0024d2925fde completed.
2011/08/16 15:56:54.161	  0:01.543	         Pool:0x7303b70	OpalUDP	Setting interface to 192.168.2.90%wlan0
2011/08/16 15:56:54.162	  0:01.543	         Pool:0x7303b70	SIP	Not retrying SUBSCRIBE due to error response 489 Bad Event
2011/08/16 15:56:54.162	  0:01.543	         Pool:0x7303b70	SIP	Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:500 ekiga net, id=3c3358f0-b7c6-e011-9ffa-0024d2925fde@portable
2011/08/16 15:56:54.162	  0:01.543	         Pool:0x7303b70	SIP	Handled PDU "2 SUBSCRIBE <489>"
2011/08/16 15:56:54.161	  0:01.543	Opal Listener:0x1eb0b70	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$192.168.2.90:5060>
2011/08/16 15:56:54.302	  0:01.684	                       	SIP	No SRV record found.
2011/08/16 15:56:54.303	  0:01.684	                       	OpalUDP	Binding to interface: 0.0.0.0:5060
2011/08/16 15:56:54.303	  0:01.685	                       	SIP	Created transport udp$86.64.162.35:5060<if=udp$*:5060>
2011/08/16 15:56:54.304	  0:01.686	                       	OpalUDP	Started connect to 86.64.162.35:5060
2011/08/16 15:56:54.304	  0:01.686	                       	OpalUDP	Writing to interface 0 - "192.168.2.90%wlan0"
2011/08/16 15:56:54.305	  0:01.686	                       	SIP	Transaction created.
2011/08/16 15:56:54.309	  0:01.691	                       	DNS	SRV Lookup ekiga.net service _sip._udp
2011/08/16 15:56:54.459	  0:01.840	                       	SIP	No SRV record found.
2011/08/16 15:56:54.459	  0:01.840	                       	SIP	Transaction remote address is udp$ekiga.net:5060
2011/08/16 15:56:54.459	  0:01.841	                       	SIP	Sending PDU (554 bytes) to: rem=udp$86.64.162.35:5060,local=udp$192.168.2.90:5060,if=192.168.2.90%wlan0
SUBSCRIBE sip:501 ekiga net SIP/2.0
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 192.168.2.90:5060;branch=z9hG4bK2868d7f0-b7c6-e011-9ffa-0024d2925fde;rport
User-Agent: Ekiga/3.2.7
From: <sip:dwhughes00 ekiga net>;tag=6800d7f0-b7c6-e011-9ffa-0024d2925fde
Call-ID: d4cfbef0-b7c6-e011-9ffa-0024d2925fde@portable
To: <sip:501 ekiga net>
Contact: <sip:501@192.168.2.90>
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/08/16 15:56:54.460	  0:01.842	                       	OpalUDP	Setting interface to 192.168.2.90%wlan0
2011/08/16 15:56:54.461	  0:01.842	                       	SIP	Transaction timers set: retry=0.500, completion=6.000
2011/08/16 15:56:54.461	  0:01.843	                       	VideoOutputCoreConfBridge	Updating video view
2011/08/16 15:56:54.462	  0:01.843	                       	VideoOutputCoreConfBridge	Updating zoom
2011/08/16 15:56:54.462	  0:01.844	                       	VideoOutputCoreConfBridge	Updating Video Settings
2011/08/16 15:56:54.463	  0:01.845	                       	VideoOutputCoreConfBridge	Updating Video Settings
2011/08/16 15:56:54.463	  0:01.845	                       	VideoOutputCoreConfBridge	Updating Video Settings
2011/08/16 15:56:54.463	  0:01.845	                       	VideoOutputCoreConfBridge	Updating Video Settings
2011/08/16 15:56:54.463	  0:01.845	                       	VidInputCoreConfBridge	Updating preview size and fps
2011/08/16 15:56:54.464	  0:01.845	                       	VidInputCore	Setting new preview config: 176x144/30
2011/08/16 15:56:54.468	  0:01.850	                       	VidInputCoreConfBridge	Updating preview size and fps
2011/08/16 15:56:54.468	  0:01.850	                       	VidInputCore	Setting new preview config: 176x144/30
2011/08/16 15:56:54.473	  0:01.855	                       	VidInputCoreConfBridge	Updating device
2011/08/16 15:56:54.474	  0:01.855	Opal Listener:0x1eb0b70	OpalUDP	Binding to interface: 192.168.2.90:5060
2011/08/16 15:56:54.474	  0:01.856	                       	VidInputCore	Setting device: USB Camera (046d:08dd) (PTLIB/V4L2)
2011/08/16 15:56:54.474	  0:01.856	                       	GMVideoInputManager_ptlib	Setting Device USB Camera (046d:08dd) (PTLIB/V4L2)
2011/08/16 15:56:54.474	  0:01.856	                       	VidInputCoreConfBridge	Updating device
2011/08/16 15:56:54.474	  0:01.856	                       	VidInputCore	Setting device: USB Camera (046d:08dd) (PTLIB/V4L2)
2011/08/16 15:56:54.475	  0:01.856	                       	GMVideoInputManager_ptlib	Setting Device USB Camera (046d:08dd) (PTLIB/V4L2)
2011/08/16 15:56:54.475	  0:01.856	                       	VidInputCoreConfBridge	Updating device
2011/08/16 15:56:54.474	  0:01.856	Opal Listener:0x1eb0b70	SIP	Waiting for PDU on udp$86.64.162.35:5060<if=udp$192.168.2.90:5060>
2011/08/16 15:56:54.475	  0:01.856	                       	VidInputCore	Setting device: USB Camera (046d:08dd) (PTLIB/V4L2)
2011/08/16 15:56:54.475	  0:01.857	                       	GMVideoInputManager_ptlib	Setting Device USB Camera (046d:08dd) (PTLIB/V4L2)
2011/08/16 15:56:54.475	  0:01.857	                       	VidInputCoreConfBridge	Updating image
2011/08/16 15:56:54.476	  0:01.858	                       	VidInputCoreConfBridge	Updating preview
2011/08/16 15:56:54.476	  0:01.858	                       	VidInputCore	Starting preview 176x144/30
2011/08/16 15:56:54.476	  0:01.858	                       	VidInputCore	Opening device with 176x144/30
2011/08/16 15:56:54.475	  0:01.857	Opal Listener:0x1eb0b70	SIP	PDU received: rem=udp$86.64.162.35:5060,local=udp$192.168.2.90:5060,if=192.168.2.90%wlan0
SIP/2.0 489 Event Not Supported
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 192.168.2.90:5060;branch=z9hG4bK80f7a4f0-b7c6-e011-9ffa-0024d2925fde;rport=61537;received=173.184.21.5
Server: Kamailio (1.5.3-notls (i386/linux))
From: <sip:dwhughes00 ekiga net>;tag=a89da4f0-b7c6-e011-9ffa-0024d2925fde
Call-ID: bae18af0-b7c6-e011-9ffa-0024d2925fde@portable
To: <sip:501 ekiga net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.46b9
Content-Length: 0


2011/08/16 15:56:54.476	  0:01.858	                       	GMVideoInputManager_ptlib	Opening Device USB Camera (046d:08dd) (PTLIB/V4L2)
2011/08/16 15:56:54.476	  0:01.858	                       	GMVideoInputManager_ptlib	Opening Device with 176x144/30
2011/08/16 15:56:54.476	  0:01.858	Opal Listener:0x1eb0b70	SIP	Queueing PDU "2 SUBSCRIBE <489>", transaction=z9hG4bK80f7a4f0-b7c6-e011-9ffa-0024d2925fde, token=bae18af0-b7c6-e011-9ffa-0024d2925fde@portable
2011/08/16 15:56:54.476	  0:01.858	         Pool:0x7303b70	SIP	Handling PDU "2 SUBSCRIBE <489>" for transaction=z9hG4bK80f7a4f0-b7c6-e011-9ffa-0024d2925fde
2011/08/16 15:56:54.476	  0:01.858	         Pool:0x7303b70	SIP	SUBSCRIBE transaction id=z9hG4bK80f7a4f0-b7c6-e011-9ffa-0024d2925fde completed.
2011/08/16 15:56:54.476	  0:01.858	         Pool:0x7303b70	OpalUDP	Setting interface to 192.168.2.90%wlan0
2011/08/16 15:56:54.477	  0:01.858	         Pool:0x7303b70	SIP	Not retrying SUBSCRIBE due to error response 489 Bad Event
2011/08/16 15:56:54.477	  0:01.858	                       	Detecting V4L2 devices
2011/08/16 15:56:54.477	  0:01.858	         Pool:0x7303b70	SIP	Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:501 ekiga net, id=bae18af0-b7c6-e011-9ffa-0024d2925fde@portable
2011/08/16 15:56:54.477	  0:01.858	         Pool:0x7303b70	SIP	Handled PDU "2 SUBSCRIBE <489>"
2011/08/16 15:56:54.477	  0:01.858	                       	PV4L2Plugin	detected device metadata at /sys/class/video4linux/
2011/08/16 15:56:54.477	  0:01.859	Opal Listener:0x1eb0b70	Opal	Transport clean up on termination
2011/08/16 15:56:54.477	  0:01.859	Opal Listener:0x1eb0b70	Opal	Transport Close
2011/08/16 15:56:54.477	  0:01.859	Opal Listener:0x1eb0b70	PWLib	File handle high water mark set: 52 PUDPSocket
2011/08/16 15:56:54.477	  0:01.859	Opal Listener:0x1eb0b70	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$192.168.2.90:5060>
2011/08/16 15:56:54.493	  0:01.875	                       	PVidInDev	Open()	videoFd:-1
2011/08/16 15:56:54.493	  0:01.875	                       	PVidInDev	Close()	videoFd:-1  started:0
2011/08/16 15:56:54.493	  0:01.875	                       	Detecting V4L2 devices
2011/08/16 15:56:54.494	  0:01.875	                       	PV4L2Plugin	detected device metadata at /sys/class/video4linux/
2011/08/16 15:56:54.510	  0:01.892	                       	PVidInDev	Open()	devName:/dev/video0  videoFd:-1
2011/08/16 15:56:54.510	  0:01.892	                       	VideoInputDevice	S_STD failed : Invalid argument
2011/08/16 15:56:54.510	  0:01.892	                       	PVidInDev	G_PARM failed (preserving frame rate may not work) : Invalid argument
2011/08/16 15:56:54.510	  0:01.892	                       	PVidInDev	unable to reset frame rate.
2011/08/16 15:56:54.511	  0:01.892	                       	PVidDev	SetColourFormatConverter success for native YUV420P
2011/08/16 15:56:54.511	  0:01.892	                       	PVidInDev	G_PARM failed (preserving frame rate may not work) : Invalid argument
2011/08/16 15:56:54.511	  0:01.892	                       	PVidInDev	frame size mismatch.
2011/08/16 15:56:54.511	  0:01.892	                       	PVidDev	SetFrameSize to 320x232
2011/08/16 15:56:54.511	  0:01.892	                       	PVidInDev	VerifyHardwareFrameSize failed for size 176x144
2011/08/16 15:56:54.511	  0:01.892	                       	PVidDev	SetFrameSize to 352x288
2011/08/16 15:56:54.511	  0:01.892	                       	PVidInDev	G_PARM failed (preserving frame rate may not work) : Invalid argument
2011/08/16 15:56:54.511	  0:01.892	                       	PVidInDev	frame size mismatch.
2011/08/16 15:56:54.511	  0:01.892	                       	PVidDev	SetFrameSize to 320x232
2011/08/16 15:56:54.511	  0:01.892	                       	PVidInDev	VerifyHardwareFrameSize failed for size 352x288
2011/08/16 15:56:54.511	  0:01.893	                       	PVidDev	SetFrameSize to 352x240
2011/08/16 15:56:54.511	  0:01.893	                       	PVidInDev	G_PARM failed (preserving frame rate may not work) : Invalid argument
2011/08/16 15:56:54.511	  0:01.893	                       	PVidInDev	frame size mismatch.
2011/08/16 15:56:54.511	  0:01.893	                       	PVidDev	SetFrameSize to 320x232
2011/08/16 15:56:54.511	  0:01.893	                       	PVidInDev	VerifyHardwareFrameSize failed for size 352x240
2011/08/16 15:56:54.511	  0:01.893	                       	PVidDev	SetFrameSize to 320x240
2011/08/16 15:56:54.511	  0:01.893	                       	PVidInDev	G_PARM failed (preserving frame rate may not work) : Invalid argument
2011/08/16 15:56:54.511	  0:01.893	                       	PVidInDev	unable to reset frame rate.
2011/08/16 15:56:54.511	  0:01.893	                       	PVidDev	Colour converter used from 320x240 [YUV420P] to 176x144 [YUV420P]
2011/08/16 15:56:54.739	  0:02.121	  Housekeeper:0x1e6fb70	SIP	Set state Terminated_Success for REGISTER transaction id=z9hG4bK261537f0-b7c6-e011-9ffa-0024d2925fde
2011/08/16 15:56:54.788	  0:02.170	  Housekeeper:0x1e6fb70	SIP	Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bK94143df0-b7c6-e011-9ffa-0024d2925fde
2011/08/16 15:56:54.811	  0:02.193	Opal Listener:0x1eb0b70	PWLib	File handle low water mark set: 50 PUDPSocket
2011/08/16 15:56:54.812	  0:02.193	Opal Listener:0x1eb0b70	OpalUDP	Binding to interface: 192.168.2.90:5060
2011/08/16 15:56:54.812	  0:02.193	Opal Listener:0x1eb0b70	SIP	Waiting for PDU on udp$86.64.162.35:5060<if=udp$192.168.2.90:5060>
2011/08/16 15:56:54.813	  0:02.194	Opal Listener:0x1eb0b70	SIP	PDU received: rem=udp$86.64.162.35:5060,local=udp$192.168.2.90:5060,if=192.168.2.90%wlan0
SIP/2.0 489 Event Not Supported
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 192.168.2.90:5060;branch=z9hG4bK2868d7f0-b7c6-e011-9ffa-0024d2925fde;rport=61537;received=173.184.21.5
Server: Kamailio (1.5.3-notls (i386/linux))
From: <sip:dwhughes00 ekiga net>;tag=6800d7f0-b7c6-e011-9ffa-0024d2925fde
Call-ID: d4cfbef0-b7c6-e011-9ffa-0024d2925fde@portable
To: <sip:501 ekiga net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.b243
Content-Length: 0


2011/08/16 15:56:54.814	  0:02.195	Opal Listener:0x1eb0b70	SIP	Queueing PDU "2 SUBSCRIBE <489>", transaction=z9hG4bK2868d7f0-b7c6-e011-9ffa-0024d2925fde, token=d4cfbef0-b7c6-e011-9ffa-0024d2925fde@portable
2011/08/16 15:56:54.814	  0:02.195	Opal Listener:0x1eb0b70	Opal	Transport clean up on termination
2011/08/16 15:56:54.814	  0:02.196	Opal Listener:0x1eb0b70	Opal	Transport Close
2011/08/16 15:56:54.814	  0:02.196	         Pool:0x7303b70	SIP	Handling PDU "2 SUBSCRIBE <489>" for transaction=z9hG4bK2868d7f0-b7c6-e011-9ffa-0024d2925fde
2011/08/16 15:56:54.814	  0:02.196	         Pool:0x7303b70	SIP	SUBSCRIBE transaction id=z9hG4bK2868d7f0-b7c6-e011-9ffa-0024d2925fde completed.
2011/08/16 15:56:54.814	  0:02.196	         Pool:0x7303b70	OpalUDP	Setting interface to 192.168.2.90%wlan0
2011/08/16 15:56:54.814	  0:02.196	         Pool:0x7303b70	SIP	Not retrying SUBSCRIBE due to error response 489 Bad Event
2011/08/16 15:56:54.815	  0:02.196	         Pool:0x7303b70	SIP	Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:501 ekiga net, id=d4cfbef0-b7c6-e011-9ffa-0024d2925fde@portable
2011/08/16 15:56:54.815	  0:02.196	         Pool:0x7303b70	SIP	Handled PDU "2 SUBSCRIBE <489>"
2011/08/16 15:56:54.814	  0:02.196	Opal Listener:0x1eb0b70	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$192.168.2.90:5060>
2011/08/16 15:56:54.833	  0:02.214	 Opal Garbage:0x1e2eb70	SIP	Transaction id=z9hG4bK94143df0-b7c6-e011-9ffa-0024d2925fde destroyed.
2011/08/16 15:56:54.833	  0:02.215	 Opal Garbage:0x1e2eb70	SIP	Transaction id=z9hG4bK261537f0-b7c6-e011-9ffa-0024d2925fde destroyed.
2011/08/16 15:56:54.833	  0:02.215	 Opal Garbage:0x1e2eb70	SIP	Deleting SIPSubscribeHandler sip:500 ekiga net
2011/08/16 15:56:54.833	  0:02.215	 Opal Garbage:0x1e2eb70	Opal	Transport clean up on termination
2011/08/16 15:56:54.833	  0:02.215	 Opal Garbage:0x1e2eb70	Opal	Transport Close
2011/08/16 15:56:54.834	  0:02.215	 Opal Garbage:0x1e2eb70	Opal	Transport clean up on termination
2011/08/16 15:56:54.834	  0:02.215	 Opal Garbage:0x1e2eb70	Opal	Transport Close
2011/08/16 15:56:54.834	  0:02.215	 Opal Garbage:0x1e2eb70	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$192.168.2.90:5060>
2011/08/16 15:56:54.834	  0:02.216	 Opal Garbage:0x1e2eb70	SIP	Deleted handler.
2011/08/16 15:56:54.835	  0:02.216	 Opal Garbage:0x1e2eb70	SIP	Deleting SIPSubscribeHandler sip:501 ekiga net
2011/08/16 15:56:54.835	  0:02.216	 Opal Garbage:0x1e2eb70	Opal	Transport clean up on termination
2011/08/16 15:56:54.835	  0:02.217	 Opal Garbage:0x1e2eb70	Opal	Transport Close
2011/08/16 15:56:54.835	  0:02.217	 Opal Garbage:0x1e2eb70	Opal	Transport clean up on termination
2011/08/16 15:56:54.835	  0:02.217	 Opal Garbage:0x1e2eb70	Opal	Transport Close
2011/08/16 15:56:54.835	  0:02.217	 Opal Garbage:0x1e2eb70	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$192.168.2.90:5060>
2011/08/16 15:56:54.836	  0:02.217	 Opal Garbage:0x1e2eb70	SIP	Deleted handler.
2011/08/16 15:56:54.836	  0:02.218	 Opal Garbage:0x1e2eb70	SIP	Deleting SIPSubscribeHandler sip:501 ekiga net
2011/08/16 15:56:54.836	  0:02.218	 Opal Garbage:0x1e2eb70	Opal	Transport clean up on termination
2011/08/16 15:56:54.836	  0:02.218	 Opal Garbage:0x1e2eb70	Opal	Transport Close
2011/08/16 15:56:54.836	  0:02.218	 Opal Garbage:0x1e2eb70	Opal	Transport clean up on termination
2011/08/16 15:56:54.836	  0:02.218	 Opal Garbage:0x1e2eb70	Opal	Transport Close
2011/08/16 15:56:54.836	  0:02.218	 Opal Garbage:0x1e2eb70	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$192.168.2.90:5060>
2011/08/16 15:56:54.837	  0:02.219	 Opal Garbage:0x1e2eb70	SIP	Deleted handler.
2011/08/16 15:56:55.161	  0:02.543	  Housekeeper:0x1e6fb70	SIP	Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bKec6273f0-b7c6-e011-9ffa-0024d2925fde
2011/08/16 15:56:55.478	  0:02.859	  Housekeeper:0x1e6fb70	SIP	Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bK80f7a4f0-b7c6-e011-9ffa-0024d2925fde
2011/08/16 15:56:55.815	  0:03.197	  Housekeeper:0x1e6fb70	SIP	Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bK2868d7f0-b7c6-e011-9ffa-0024d2925fde
2011/08/16 15:56:55.838	  0:03.219	 Opal Garbage:0x1e2eb70	SIP	Transaction id=z9hG4bK80f7a4f0-b7c6-e011-9ffa-0024d2925fde destroyed.
2011/08/16 15:56:55.838	  0:03.220	 Opal Garbage:0x1e2eb70	SIP	Transaction id=z9hG4bK2868d7f0-b7c6-e011-9ffa-0024d2925fde destroyed.
2011/08/16 15:56:55.838	  0:03.220	 Opal Garbage:0x1e2eb70	SIP	Transaction id=z9hG4bKec6273f0-b7c6-e011-9ffa-0024d2925fde destroyed.
2011/08/16 15:56:56.324	  0:03.706	                       	PreviewManager	Starting Preview
2011/08/16 15:56:56.325	  0:03.707	                       	AudioOutputCoreConfBridge	Updating device
2011/08/16 15:56:56.325	  0:03.707	                       	AudioOutputCore	Setting device[0]: HDA Intel (PTLIB/ALSA)
2011/08/16 15:56:56.325	  0:03.707	                       	GMAudioOutputManager_ptlib	Setting Device[0] HDA Intel (PTLIB/ALSA)
2011/08/16 15:56:56.326	  0:03.707	                       	AudioOutputCoreConfBridge	Updating device
2011/08/16 15:56:56.326	  0:03.707	                       	AudioOutputCore	Setting device[1]: HDA Intel (PTLIB/ALSA)
2011/08/16 15:56:56.329	  0:03.711	                       	AudioInputCoreConfBridge	Updating device
2011/08/16 15:56:56.329	  0:03.711	                       	AudioInputCore	Setting device: HDA Intel (PTLIB/ALSA)
2011/08/16 15:56:56.329	  0:03.711	                       	GMAudioInputManager_ptlib	Setting Device HDA Intel (PTLIB/ALSA)
2011/08/16 15:56:56.511	  0:03.893	                       	Ekiga version 3.2.7
2011/08/16 15:56:56.511	  0:03.893	                       	OPAL version 3.6.8
2011/08/16 15:56:56.511	  0:03.893	                       	PTLIB version 2.6.7
2011/08/16 15:56:56.511	  0:03.893	                       	GNOME support disabled
2011/08/16 15:56:56.511	  0:03.893	                       	Accelerated rendering support enabled
2011/08/16 15:56:56.511	  0:03.893	                       	DBUS support enabled
2011/08/16 15:56:56.511	  0:03.893	                       	GConf support enabled
2011/08/16 15:56:56.511	  0:03.893	                       	ESound support disabled
2011/08/16 15:56:56.531	  0:03.912	GMVideoOut...:0x700bb70	GMVideoOutputManager_X	Widget not yet realized or gconf info not yet set, not opening display
2011/08/16 15:56:56.594	  0:03.976	GMVideoOut...:0x700bb70	GMVideoOutputManager_X	Widget not yet realized or gconf info not yet set, not opening display
2011/08/16 15:56:56.691	  0:04.072	GMVideoOut...:0x700bb70	GMVideoOutputManager_X	Opening VO_MODE_LOCAL display with image of 176x144
2011/08/16 15:56:56.691	  0:04.072	GMVideoOut...:0x700bb70	XVideo	Initializing XV window with 176x144 at 26,26
2011/08/16 15:56:56.696	  0:04.078	GMVideoOut...:0x700bb70	XVideo	XvQueryExtension: Version: 2 Release: 2 Request Base: 132 Event Base: 72 Error Base: 140
2011/08/16 15:56:56.697	  0:04.078	GMVideoOut...:0x700bb70	XVideo	#0, Adaptor: Intel(R) Textured Video, type: input | image | , ports: 16, first port:�74
2011/08/16 15:56:56.697	  0:04.078	GMVideoOut...:0x700bb70	XVideo	Encoding List for Port 74:  id=0 name=XV_IMAGE size=2048x2048 numerator=1 denominator=1
2011/08/16 15:56:56.697	  0:04.079	GMVideoOut...:0x700bb70	XVideo	Attribute List for Port 74:
2011/08/16 15:56:56.697	  0:04.079	GMVideoOut...:0x700bb70	 �name: � � � XV_BRIGHTNESS
2011/08/16 15:56:56.697	  0:04.079	GMVideoOut...:0x700bb70	 �flags: � �  get set
2011/08/16 15:56:56.697	  0:04.079	GMVideoOut...:0x700bb70	 �min_color: �-128
2011/08/16 15:56:56.697	  0:04.079	GMVideoOut...:0x700bb70	 �max_color: �127
2011/08/16 15:56:56.697	  0:04.079	GMVideoOut...:0x700bb70	 �name: � � � XV_CONTRAST
2011/08/16 15:56:56.697	  0:04.079	GMVideoOut...:0x700bb70	 �flags: � �  get set
2011/08/16 15:56:56.697	  0:04.079	GMVideoOut...:0x700bb70	 �min_color: �0
2011/08/16 15:56:56.697	  0:04.079	GMVideoOut...:0x700bb70	 �max_color: �255
2011/08/16 15:56:56.697	  0:04.079	GMVideoOut...:0x700bb70	 �name: � � � XV_SYNC_TO_VBLANK
2011/08/16 15:56:56.697	  0:04.079	GMVideoOut...:0x700bb70	 �flags: � �  get set
2011/08/16 15:56:56.697	  0:04.079	GMVideoOut...:0x700bb70	 �min_color: �-1
2011/08/16 15:56:56.697	  0:04.079	GMVideoOut...:0x700bb70	 �max_color: �1
2011/08/16 15:56:56.697	  0:04.079	GMVideoOut...:0x700bb70	XVideo	Image format list for Port 74:
2011/08/16 15:56:56.698	  0:04.079	GMVideoOut...:0x700bb70	  0x32595559 (YUY2) packed, order: YUYV
2011/08/16 15:56:56.698	  0:04.079	GMVideoOut...:0x700bb70	  0x32315659 (YV12) planar, order: YVU
2011/08/16 15:56:56.698	  0:04.079	GMVideoOut...:0x700bb70	  0x30323449 (I420) planar, order: YUV
2011/08/16 15:56:56.698	  0:04.079	GMVideoOut...:0x700bb70	  0x59565955 (UYVY) packed, order: UYVY
2011/08/16 15:56:56.698	  0:04.079	GMVideoOut...:0x700bb70	  0x434d5658 (XVMC) planar, order: YVU
2011/08/16 15:56:56.698	  0:04.080	GMVideoOut...:0x700bb70	XVideo	Grabbed Port: 74
2011/08/16 15:56:56.698	  0:04.080	GMVideoOut...:0x700bb70	XVideo	Using XVideo port: 74
2011/08/16 15:56:56.704	  0:04.086	GMVideoOut...:0x700bb70	XVideo	Found visual with colordepth of 24bits per pixel
2011/08/16 15:56:56.704	  0:04.086	GMVideoOut...:0x700bb70	X11	Created Window with ID 60817410
2011/08/16 15:56:56.708	  0:04.089	GMVideoOut...:0x700bb70	XVideo	Colorkey method: NONE
2011/08/16 15:56:56.708	  0:04.089	GMVideoOut...:0x700bb70	XVideo	Vertical sync successfully activated
2011/08/16 15:56:56.708	  0:04.090	GMVideoOut...:0x700bb70	XVideo	XQueryShmExtension success
2011/08/16 15:56:56.708	  0:04.090	GMVideoOut...:0x700bb70	XVideo	Created XvImage (176x144, data size: 38016, num_planes: 3
2011/08/16 15:56:56.708	  0:04.090	GMVideoOut...:0x700bb70	XVideo	  Plane 0: pitch=176, offset=0
2011/08/16 15:56:56.708	  0:04.090	GMVideoOut...:0x700bb70	XVideo	  Plane 1: pitch=88, offset=25344
2011/08/16 15:56:56.708	  0:04.090	GMVideoOut...:0x700bb70	XVideo	  Plane 2: pitch=88, offset=31680
2011/08/16 15:56:56.708	  0:04.090	GMVideoOut...:0x700bb70	XVideo	Using SHM extension
2011/08/16 15:56:56.709	  0:04.090	GMVideoOut...:0x700bb70	X11	Unknown wm type...
2011/08/16 15:56:56.709	  0:04.090	GMVideoOut...:0x700bb70	GMVideoOutputManager_X	VO_MODE_LOCAL: Successfully opened XV Window
2011/08/16 15:56:56.709	  0:04.090	GMVideoOut...:0x700bb70	X11	Unknown X Event 19 received
2011/08/16 15:57:07.829	  0:15.211	                       	PreviewManager	Stopping Preview
2011/08/16 15:57:07.834	  0:15.216	GMVideoOut...:0x700bb70	X11	Unmapping and destroying Window with ID 60817410
2011/08/16 15:57:07.836	  0:15.218	Network In...:0x1f04b70	IfaceMon	Finished interface monitor thread.


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