Attachment 867419 Details for Bug 1069663 – gdb output

gdb output

gdb-ekiga.txt (text/plain), 178.43 KB, created by Martin Simon on 2014-02-25 13:30:46 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Martin Simon

Created: 2014-02-25 13:30:46 UTC

Size: 178.43 KB

patch

obsolete

>GNU gdb (GDB) Red Hat Enterprise Linux 7.6.1-50.el7
>Copyright (C) 2013 Free Software Foundation, Inc.
>License GPLv3+: GNU GPL version 3 or later <http://gnu.org/licenses/gpl.html>
>This is free software: you are free to change and redistribute it.
>There is NO WARRANTY, to the extent permitted by law.  Type "show copying"
>and "show warranty" for details.
>This GDB was configured as "x86_64-redhat-linux-gnu".
>For bug reporting instructions, please see:
><http://www.gnu.org/software/gdb/bugs/>...
>Reading symbols from /usr/bin/ekiga...Reading symbols from /usr/lib/debug/usr/bin/ekiga.debug...done.
>done.
>[?1034h(gdb) run
>Starting program: /usr/bin/ekiga -d 4
>Traceback (most recent call last):
>  File "/usr/share/gdb/auto-load/usr/lib64/libgobject-2.0.so.0.3600.3-gdb.py", line 9, in <module>
>    from gobject import register
>  File "/usr/share/glib-2.0/gdb/gobject.py", line 3, in <module>
>    import gdb.backtrace
>ImportError: No module named backtrace
>Traceback (most recent call last):
>  File "/usr/share/gdb/auto-load/usr/lib64/libgobject-2.0.so.0.3600.3-gdb.py", line 9, in <module>
>    from gobject import register
>  File "/usr/share/glib-2.0/gdb/gobject.py", line 3, in <module>
>    import gdb.backtrace
>ImportError: No module named backtrace
>[Thread debugging using libthread_db enabled]
>Using host libthread_db library "/lib64/libthread_db.so.1".
>Missing separate debuginfo for /lib64/libgraphite2.so.3
>Try: yum --enablerepo='*debug*' install /usr/lib/debug/.build-id/74/23a0f508f95af1468998bacddd3f1e9f18afa8.debug
> on Unix Linux (3.10.0-90.el7.x86_64-x86_64) with PTLib (v2.10.10 (svn:29149)) at 2014/2/25 14:25:17.156
>2014/02/25 14:25:17.158	  0:00.002	                       	PTLib	Maximum per-process file handles is 1024
>2014/02/25 14:25:17.158	  0:00.002	                       	PLUGIN	Enumerating plugin directory /usr/lib64/ptlib-2.10.10/
>2014/02/25 14:25:17.158	  0:00.002	                       	PLUGIN	Enumerating plugin directory /usr/lib64/ptlib-2.10.10/devices/
>2014/02/25 14:25:17.158	  0:00.002	                       	PLUGIN	Enumerating plugin directory /usr/lib64/ptlib-2.10.10/devices/videoinput/
>2014/02/25 14:25:17.159	  0:00.003	                       	UDLL	opening /usr/lib64/ptlib-2.10.10/devices/videoinput/v4l2_pwplugin.so
>2014/02/25 14:25:17.166	  0:00.010	                       	PLUGIN	Enumerating plugin directory /usr/lib64/ptlib-2.10.10/devices/sound/
>2014/02/25 14:25:17.166	  0:00.010	                       	UDLL	opening /usr/lib64/ptlib-2.10.10/devices/sound/pulse_pwplugin.so
>2014/02/25 14:25:17.208	  0:00.052	                       	UDLL	opening /usr/lib64/ptlib-2.10.10/devices/sound/alsa_pwplugin.so
>[New Thread 0x7fffde745700 (LWP 24056)]
>2014/02/25 14:25:17.220	  0:00.064	                       	PLUGIN	Enumerating plugin directory /usr/lib64/opal-3.10.10/
>2014/02/25 14:25:17.221	  0:00.065	                       	PLUGIN	Enumerating plugin directory /usr/lib64/opal-3.10.10/codecs/
>2014/02/25 14:25:17.221	  0:00.065	                       	PLUGIN	Enumerating plugin directory /usr/lib64/opal-3.10.10/codecs/audio/
>2014/02/25 14:25:17.221	  0:00.065	                       	UDLL	opening /usr/lib64/opal-3.10.10/codecs/audio/lpc10_ptplugin.so
>2014/02/25 14:25:17.223	  0:00.067	                       	UDLL	opening /usr/lib64/opal-3.10.10/codecs/audio/ima_adpcm_ptplugin.so
>2014/02/25 14:25:17.225	  0:00.069	                       	UDLL	opening /usr/lib64/opal-3.10.10/codecs/audio/g726_ptplugin.so
>2014/02/25 14:25:17.228	  0:00.072	                       	UDLL	opening /usr/lib64/opal-3.10.10/codecs/audio/speex_ptplugin.so
>2014/02/25 14:25:17.233	  0:00.077	                       	UDLL	opening /usr/lib64/opal-3.10.10/codecs/audio/g7221_ptplugin.so
>2014/02/25 14:25:17.235	  0:00.079	                       	UDLL	opening /usr/lib64/opal-3.10.10/codecs/audio/gsm0610_ptplugin.so
>2014/02/25 14:25:17.237	  0:00.081	                       	UDLL	opening /usr/lib64/opal-3.10.10/codecs/audio/g7222_ptplugin.so
>2014/02/25 14:25:17.240	  0:00.084	                       	UDLL	opening /usr/lib64/opal-3.10.10/codecs/audio/iLBC_ptplugin.so
>2014/02/25 14:25:17.242	  0:00.086	                       	UDLL	opening /usr/lib64/opal-3.10.10/codecs/audio/g722_ptplugin.so
>2014/02/25 14:25:17.244	  0:00.088	                       	UDLL	opening /usr/lib64/opal-3.10.10/codecs/audio/gsmamrcodec_ptplugin.so
>2014/02/25 14:25:17.246	  0:00.090	                       	PLUGIN	Enumerating plugin directory /usr/lib64/opal-3.10.10/codecs/video/
>2014/02/25 14:25:17.246	  0:00.090	                       	UDLL	opening /usr/lib64/opal-3.10.10/codecs/video/h261_vic_ptplugin.so
>2014/02/25 14:25:17.249	  0:00.093	                       	UDLL	opening /usr/lib64/opal-3.10.10/codecs/video/theora_ptplugin.so
>2014/02/25 14:25:17.254	  0:00.098	                       	OpalPlugin	Using default handler for plugin codec lpc10_ptplugin
>2014/02/25 14:25:17.254	  0:00.098	                       	OpalPlugin	Creating new media format LPC-10
>2014/02/25 14:25:17.254	  0:00.098	                       	OpalPlugin	Using default handler for plugin codec ima_adpcm_ptplugin
>2014/02/25 14:25:17.254	  0:00.098	                       	OpalPlugin	Creating new media format MS-IMA-ADPCM
>2014/02/25 14:25:17.254	  0:00.098	                       	OpalPlugin	Using default handler for plugin codec g726_ptplugin
>2014/02/25 14:25:17.254	  0:00.098	                       	OpalPlugin	Creating new media format G.726-40k
>2014/02/25 14:25:17.254	  0:00.099	                       	OpalPlugin	Creating new media format G.726-32k
>2014/02/25 14:25:17.255	  0:00.099	                       	OpalPlugin	Creating new media format G.726-24k
>2014/02/25 14:25:17.255	  0:00.099	                       	OpalPlugin	Creating new media format G.726-16k
>2014/02/25 14:25:17.255	  0:00.099	                       	OpalPlugin	Using default handler for plugin codec speex_ptplugin
>2014/02/25 14:25:17.255	  0:00.099	                       	OpalPlugin	Creating new media format SpeexIETFNarrow-5.95k
>2014/02/25 14:25:17.255	  0:00.099	                       	OpalPlugin	Creating new media format SpeexIETFNarrow-8k
>2014/02/25 14:25:17.255	  0:00.099	                       	OpalPlugin	Creating new media format SpeexIETFNarrow-11k
>2014/02/25 14:25:17.255	  0:00.099	                       	OpalPlugin	Creating new media format SpeexIETFNarrow-15k
>2014/02/25 14:25:17.255	  0:00.099	                       	OpalPlugin	Creating new media format SpeexIETFNarrow-18.2k
>2014/02/25 14:25:17.256	  0:00.100	                       	OpalPlugin	Creating new media format SpeexIETFNarrow-24.6k
>2014/02/25 14:25:17.256	  0:00.100	                       	OpalPlugin	Creating new media format SpeexIETFWide-20.6k
>2014/02/25 14:25:17.256	  0:00.100	                       	OpalPlugin	Creating new media format SpeexWNarrow-8k
>2014/02/25 14:25:17.256	  0:00.100	                       	OpalPlugin	Creating new media format SpeexWide-20.6k
>2014/02/25 14:25:17.256	  0:00.100	                       	OpalPlugin	Creating new media format SpeexNB
>2014/02/25 14:25:17.256	  0:00.100	                       	OpalPlugin	Creating new media format SpeexWB
>2014/02/25 14:25:17.257	  0:00.101	                       	OpalPlugin	Using default handler for plugin codec g7221_ptplugin
>2014/02/25 14:25:17.257	  0:00.101	                       	OpalPlugin	Creating new media format G.722.1-24k
>2014/02/25 14:25:17.257	  0:00.101	                       	OpalPlugin	Creating new media format G.722.1-32k
>2014/02/25 14:25:17.257	  0:00.101	                       	OpalPlugin	Using default handler for plugin codec gsm0610_ptplugin
>2014/02/25 14:25:17.257	  0:00.101	                       	OpalPlugin	Creating new media format GSM-06.10
>2014/02/25 14:25:17.257	  0:00.101	                       	OpalPlugin	Creating new media format MS-GSM
>2014/02/25 14:25:17.257	  0:00.102	                       	OpalPlugin	Using default handler for plugin codec g7222_ptplugin
>2014/02/25 14:25:17.258	  0:00.102	                       	OpalPlugin	Creating new media format G.722.2
>2014/02/25 14:25:17.258	  0:00.102	                       	OpalPlugin	Using default handler for plugin codec iLBC_ptplugin
>2014/02/25 14:25:17.258	  0:00.102	                       	OpalPlugin	Creating new media format iLBC
>2014/02/25 14:25:17.258	  0:00.102	                       	OpalPlugin	Creating new media format iLBC-13k3
>2014/02/25 14:25:17.258	  0:00.102	                       	OpalPlugin	Creating new media format iLBC-15k2
>2014/02/25 14:25:17.258	  0:00.102	                       	OpalPlugin	Using default handler for plugin codec g722_ptplugin
>2014/02/25 14:25:17.258	  0:00.102	                       	OpalPlugin	Creating new media format G.722-64k
>2014/02/25 14:25:17.259	  0:00.103	                       	OpalPlugin	Using default handler for plugin codec gsmamrcodec_ptplugin
>2014/02/25 14:25:17.259	  0:00.103	                       	OpalPlugin	Creating new media format GSM-AMR
>2014/02/25 14:25:17.259	  0:00.103	                       	OpalPlugin	Using default handler for plugin codec h261_vic_ptplugin
>2014/02/25 14:25:17.259	  0:00.103	                       	OpalPlugin	Creating new media format H.261
>2014/02/25 14:25:17.259	  0:00.103	                       	OpalPlugin	Creating H.261 plugin capability
>2014/02/25 14:25:17.259	  0:00.103	                       	OpalPlugin	Creating H.261 plugin capability
>2014/02/25 14:25:17.259	  0:00.103	                       	OpalPlugin	Using default handler for plugin codec theora_ptplugin
>2014/02/25 14:25:17.259	  0:00.103	                       	Plugin	Started logging.
>2014/02/25 14:25:17.259	  0:00.103	                       	OpalPlugin	Creating new media format theora
>2014/02/25 14:25:17.261	  0:00.105	                       	PWLib	File handle high water mark set: 14 Thread unblock pipe
>2014/02/25 14:25:17.261	  0:00.105	                       	PWLib	File handle high water mark set: 16 Thread unblock pipe
>2014/02/25 14:25:17.261	  0:00.105	                       	PTLib	No permission to set priority level 4
>2014/02/25 14:25:17.263	  0:00.107	                       	PTLib	Thread high water mark set: 2
>2014/02/25 14:25:17.263	  0:00.107	                       	PTLib	No permission to set priority level 4
>2014/02/25 14:25:17.263	  0:00.107	                       	PTLib	Thread high water mark set: 3
>2014/02/25 14:25:17.263	  0:00.107	                       	PWLib	File handle high water mark set: 21 Thread unblock pipe
>2014/02/25 14:25:17.263	  0:00.107	                       	PTLib	No permission to set priority level 4
>2014/02/25 14:25:17.264	  0:00.108	                       	PTLib	Thread high water mark set: 4
>2014/02/25 14:25:17.264	  0:00.108	                       	PWLib	File handle high water mark set: 23 Thread unblock pipe
>2014/02/25 14:25:17.265	  0:00.109	                       	PTLib	No permission to set priority level 4
>2014/02/25 14:25:17.265	  0:00.109	                       	PTLib	Thread high water mark set: 5
>2014/02/25 14:25:17.266	  0:00.110	                       	HalManager_dbus	Initialising HAL Manager
>2014/02/25 14:25:17.266	  0:00.111	                       	HalManager_dbus	Populating device list
>2014/02/25 14:25:17.267	  0:00.111	                       	HalManager_dbus	Populating full device list failed - The name org.freedesktop.Hal was not provided by any .service files
>2014/02/25 14:25:17.267	  0:00.111	                       	HalManager_dbus	Populating interface list
>2014/02/25 14:25:17.267	  0:00.111	                       	HalManager_dbus	Populating full interface list failed - Method "getDevices" with signature "" on interface "org.freedesktop.NetworkManager" doesn't exist
>
>[New Thread 0x7ffff7f97700 (LWP 24057)]
>[New Thread 0x7ffff7f56700 (LWP 24058)]
>[New Thread 0x7ffff7f15700 (LWP 24059)]
>[New Thread 0x7ffff7ed4700 (LWP 24060)]
>2014/02/25 14:25:17.272	  0:00.116	                       	PV4L2Plugin	Detecting V4L2 devices
>2014/02/25 14:25:17.272	  0:00.116	                       	PV4L2Plugin	detected device metadata at /sys/class/video4linux/
>2014/02/25 14:25:17.325	  0:00.169	                       	PWLib	File handle high water mark set: 29 Thread unblock pipe
>2014/02/25 14:25:17.326	  0:00.170	                       	PTLib	Thread high water mark set: 6
>2014/02/25 14:25:17.326	  0:00.170	                       	OpalMan	Created manager.
>2014/02/25 14:25:17.326	  0:00.170	                       	PV4L2Plugin	Detecting V4L2 devices
>2014/02/25 14:25:17.326	  0:00.170	                       	PV4L2Plugin	detected device metadata at /sys/class/video4linux/
>[New Thread 0x7ffff7e93700 (LWP 24061)]
>2014/02/25 14:25:17.342	  0:00.186	                       	OpalMan	Attached endpoint with prefix pc
>2014/02/25 14:25:17.342	  0:00.186	                       	OpalEP	Created endpoint: pc
>2014/02/25 14:25:17.342	  0:00.186	                       	LocalEP	Created endpoint.
>2014/02/25 14:25:17.344	  0:00.189	                       	PCSS	Created PC sound system endpoint.
>Players:
>alsa_output.pci-0000_00_1b.0.analog-stereo
>Null Audio
>Default
>PulseAudio
>HDA Intel PCH [ALC269VC Analog]
>HDA Intel PCH [HDMI 0]
>HDA Intel PCH [HDMI 1]
>HDA Intel PCH [HDMI 2]
>EKIGA
>*.wav
>Recorders:
>Null Audio
>Default
>PulseAudio
>alsa_input.pci-0000_00_1b.0.analog-stereo
>HDA Intel PCH [ALC269VC Analog]
>EKIGA
>*.wav
>
>2014/02/25 14:25:17.345	  0:00.189	                       	OPAL	SetMediaFormatOrder()
>2014/02/25 14:25:17.345	  0:00.190	                       	OPAL	SetMediaFormatMask()
>2014/02/25 14:25:17.346	  0:00.190	                       	OpalMan	Attached endpoint with prefix sip
>2014/02/25 14:25:17.346	  0:00.190	                       	OpalEP	Created endpoint: sip
>2014/02/25 14:25:17.346	  0:00.190	                       	PWLib	File handle high water mark set: 30 PUDPSocket
>2014/02/25 14:25:17.346	  0:00.190	                       	IfaceMon	Initial interface list:
>127.0.0.1 <00-00-00-00-00-00> (lo)
>10.34.130.146 <3C-97-0E-8E-D1-1E> (em1)
>
>2014/02/25 14:25:17.346	  0:00.191	                       	PTLIB	Opened NetLink socket
>2014/02/25 14:25:17.347	  0:00.191	                       	PWLib	File handle high water mark set: 34 Thread unblock pipe
>2014/02/25 14:25:17.348	  0:00.192	                       	PTLib	Thread high water mark set: 7
>2014/02/25 14:25:17.348	  0:00.192	Network In...fff7e52700	IfaceMon	Started interface monitor thread.
>2014/02/25 14:25:17.349	  0:00.193	                       	OpalMan	Attached endpoint with prefix sips
>2014/02/25 14:25:17.349	  0:00.193	                       	SIP	Created endpoint.
>2014/02/25 14:25:17.349	  0:00.193	                       	PWLib	File handle high water mark set: 35 PUDPSocket
>2014/02/25 14:25:17.349	  0:00.193	                       	MonSock	Created socket bundle for all interfaces.
>2014/02/25 14:25:17.349	  0:00.193	                       	PWLib	File handle high water mark set: 36 PUDPSocket
>2014/02/25 14:25:17.349	  0:00.193	                       	MonSock	Created bundled UDP socket 10.34.130.146:5060
>2014/02/25 14:25:17.349	  0:00.193	                       	PWLib	File handle high water mark set: 38 Thread unblock pipe
>2014/02/25 14:25:17.350	  0:00.194	                       	PTLib	Thread high water mark set: 8
>2014/02/25 14:25:17.350	  0:00.194	                       	PTLib	No permission to set priority level 4
>2014/02/25 14:25:17.350	  0:00.194	Opal Liste...ffd8116700	Listen	Started listening thread on udp$*:5060
>2014/02/25 14:25:17.350	  0:00.194	                       	OpalMan	Added route "sip:.*=pc:*"
>2014/02/25 14:25:17.350	  0:00.194	                       	OpalMan	Added route "pc:.*=sip:<da>"
>2014/02/25 14:25:17.350	  0:00.194	                       	OpalMan	Attached endpoint with prefix h323
>2014/02/25 14:25:17.350	  0:00.194	                       	OpalEP	Created endpoint: h323
>2014/02/25 14:25:17.350	  0:00.194	                       	OpalMan	Attached endpoint with prefix h323s
>2014/02/25 14:25:17.350	  0:00.194	                       	H323	Created endpoint.
>2014/02/25 14:25:17.350	  0:00.194	                       	PWLib	File handle high water mark set: 39 PTCPSocket
>2014/02/25 14:25:17.350	  0:00.194	                       	PWLib	File handle high water mark set: 41 Thread unblock pipe
>2014/02/25 14:25:17.352	  0:00.196	                       	PTLib	Thread high water mark set: 9
>2014/02/25 14:25:17.352	  0:00.196	                       	OpalMan	Added route "h323:.*=pc:*"
>2014/02/25 14:25:17.352	  0:00.196	                       	OpalMan	Added route "pc:.*=h323:<da>"
>2014/02/25 14:25:17.352	  0:00.196	Opal Liste...ffd80d5700	Listen	Started listening thread on tcp$*:1720
>2014/02/25 14:25:17.352	  0:00.196	Opal Liste...ffd80d5700	Listen	Waiting on socket accept on tcp$*:1720
>2014/02/25 14:25:17.352	  0:00.196	                       	Error: cannot create presentity for h323:desktopqe@spranto.net
>2014/02/25 14:25:17.352	  0:00.197	                       	PWLib	File handle high water mark set: 42 PUDPSocket
>2014/02/25 14:25:17.353	  0:00.197	                       	OpalMan	Added presentity for SIP_Presentity
>2014/02/25 14:25:17.353	  0:00.197	                       	Created presentity for sip:desktopqe@ekiga.net
>2014/02/25 14:25:17.358	  0:00.202	                       	MediaFormat	Removing codecs SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,theora,H.261,PCM-16S-48kHz,PCM-16S-32kHz,PCM-16S-16kHz,PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,UserInput/RFC2833,NamedSignalEvent,YUV420P,MSRP,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling
>2014/02/25 14:25:17.358	  0:00.202	                       	OPAL	SetMediaFormatMask(G.722-64k,G.722.1-24k,G.722.1-32k,G.722.2,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,RFC4175_YCbCr-4:2:0,T.140,MSRP,SIP-IM,NamedSignalEvent)
>2014/02/25 14:25:17.358	  0:00.203	                       	OPAL	SetMediaFormatOrder(SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,theora,H.261,PCM-16S-48kHz,PCM-16S-32kHz,PCM-16S-16kHz,PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,UserInput/RFC2833,NamedSignalEvent,YUV420P,MSRP,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling)
>2014/02/25 14:25:17.363	  0:00.207	                       	MediaFormat	Removing codecs SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,theora,H.261,PCM-16S-48kHz,PCM-16S-32kHz,PCM-16S-16kHz,PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,UserInput/RFC2833,NamedSignalEvent,YUV420P,MSRP,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling
>2014/02/25 14:25:17.363	  0:00.207	                       	OPAL	SetMediaFormatMask(G.722-64k,G.722.1-24k,G.722.1-32k,G.722.2,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,RFC4175_YCbCr-4:2:0,T.140,MSRP,SIP-IM,NamedSignalEvent)
>2014/02/25 14:25:17.363	  0:00.207	                       	OPAL	SetMediaFormatOrder(SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,theora,H.261,PCM-16S-48kHz,PCM-16S-32kHz,PCM-16S-16kHz,PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,PCM-16,UserInput/RFC2833,NamedSignalEvent,YUV420P,MSRP,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling)
>2014/02/25 14:25:17.364	  0:00.208	                       	OpalConfBridge	maximum_video_tx_bitrate set to 1024kb/s
>2014/02/25 14:25:17.370	  0:00.214	                       	Ekiga	Nat binding delay set to 10
>2014/02/25 14:25:17.370	  0:00.214	                       	Ekiga	Sent its own presence (publish) for sip:desktopqe@ekiga.net: available, note I'm available using Ekiga
>2014/02/25 14:25:17.372	  0:00.216	                       	PWLib	File handle high water mark set: 43 Thread unblock pipe
>2014/02/25 14:25:17.372	  0:00.216	                       	Ekiga	Started STUN detector
>2014/02/25 14:25:17.373	  0:00.217	                       	PTLib	Thread high water mark set: 10
>2014/02/25 14:25:17.373	  0:00.217	StunDetect...ffd8094700	PWLib	File handle high water mark set: 44 PUDPSocket
>[New Thread 0x7ffff7e52700 (LWP 24062)]
>[New Thread 0x7fffd8116700 (LWP 24063)]
>[New Thread 0x7fffd80d5700 (LWP 24064)]
>[New Thread 0x7fffd8094700 (LWP 24065)]
>2014/02/25 14:25:17.419	  0:00.263	                       	AudioOutputCore	Detected Device: PulseAudio (PTLIB/Pulse)
>2014/02/25 14:25:17.419	  0:00.263	                       	AudioOutputCore	Detected Device: alsa_output.pci-0000_00_1b.0.analog-stereo (PTLIB/Pulse)
>2014/02/25 14:25:17.419	  0:00.263	                       	AudioOutputCore	Detected Device: Default (PTLIB/ALSA)
>2014/02/25 14:25:17.419	  0:00.263	                       	AudioOutputCore	Detected Device: HDA Intel PCH [ALC269VC Analog] (PTLIB/ALSA)
>2014/02/25 14:25:17.419	  0:00.263	                       	AudioOutputCore	Detected Device: HDA Intel PCH [HDMI 0] (PTLIB/ALSA)
>2014/02/25 14:25:17.419	  0:00.263	                       	AudioOutputCore	Detected Device: HDA Intel PCH [HDMI 1] (PTLIB/ALSA)
>2014/02/25 14:25:17.419	  0:00.263	                       	AudioOutputCore	Detected Device: HDA Intel PCH [HDMI 2] (PTLIB/ALSA)
>[New Thread 0x7fffd1583700 (LWP 24066)]
>[New Thread 0x7fffa7c91700 (LWP 24067)]
>[New Thread 0x7fffa7490700 (LWP 24068)]
>2014/02/25 14:25:17.482	  0:00.327	StunDetect...ffd8094700	PWLib	File handle high water mark set: 52 PUDPSocket
>2014/02/25 14:25:17.492	  0:00.336	                       	AudioOutputCore	Detected Device: PulseAudio (PTLIB/Pulse)
>2014/02/25 14:25:17.492	  0:00.336	                       	AudioOutputCore	Detected Device: alsa_output.pci-0000_00_1b.0.analog-stereo (PTLIB/Pulse)
>2014/02/25 14:25:17.492	  0:00.336	                       	AudioOutputCore	Detected Device: Default (PTLIB/ALSA)
>2014/02/25 14:25:17.492	  0:00.336	                       	AudioOutputCore	Detected Device: HDA Intel PCH [ALC269VC Analog] (PTLIB/ALSA)
>2014/02/25 14:25:17.492	  0:00.336	                       	AudioOutputCore	Detected Device: HDA Intel PCH [HDMI 0] (PTLIB/ALSA)
>2014/02/25 14:25:17.492	  0:00.336	                       	AudioOutputCore	Detected Device: HDA Intel PCH [HDMI 1] (PTLIB/ALSA)
>2014/02/25 14:25:17.492	  0:00.336	                       	AudioOutputCore	Detected Device: HDA Intel PCH [HDMI 2] (PTLIB/ALSA)
>2014/02/25 14:25:17.496	  0:00.340	                       	AudioInputCore	Detected Device: PulseAudio (PTLIB/Pulse)
>2014/02/25 14:25:17.496	  0:00.340	                       	AudioInputCore	Detected Device: alsa_input.pci-0000_00_1b.0.analog-stereo (PTLIB/Pulse)
>2014/02/25 14:25:17.496	  0:00.340	                       	AudioInputCore	Detected Device: Default (PTLIB/ALSA)
>2014/02/25 14:25:17.496	  0:00.340	                       	AudioInputCore	Detected Device: HDA Intel PCH [ALC269VC Analog] (PTLIB/ALSA)
>2014/02/25 14:25:17.500	  0:00.344	                       	PV4L2Plugin	Detecting V4L2 devices
>2014/02/25 14:25:17.500	  0:00.344	                       	PV4L2Plugin	detected device metadata at /sys/class/video4linux/
>[New Thread 0x7fffa6c8f700 (LWP 24070)]
>[New Thread 0x7fffa648e700 (LWP 24071)]
>[New Thread 0x7fffa5c8d700 (LWP 24072)]
>[New Thread 0x7fffa548c700 (LWP 24073)]
>2014/02/25 14:25:17.516	  0:00.360	                       	VidInputCore	Detected Device: Moving Logo (Moving Logo/Moving Logo)
>2014/02/25 14:25:17.516	  0:00.360	                       	VidInputCore	Detected Device: Integrated Camera (PTLIB/V4L2)
>[New Thread 0x7fff85d36700 (LWP 24074)]
>[New Thread 0x7fff85535700 (LWP 24075)]
>[New Thread 0x7fff7ffff700 (LWP 24076)]
>2014/02/25 14:25:17.693	  0:00.537	                       	VideoOutputCoreConfBridge	Updating video view
>2014/02/25 14:25:17.693	  0:00.537	                       	VideoOutputCoreConfBridge	Updating zoom
>2014/02/25 14:25:17.693	  0:00.537	                       	VideoOutputCoreConfBridge	Updating Video Settings
>2014/02/25 14:25:17.693	  0:00.537	                       	VideoOutputCoreConfBridge	Updating Video Settings
>2014/02/25 14:25:17.693	  0:00.538	                       	VideoOutputCoreConfBridge	Updating Video Settings
>2014/02/25 14:25:17.694	  0:00.538	                       	VideoOutputCoreConfBridge	Updating Video Settings
>2014/02/25 14:25:17.694	  0:00.538	                       	VidInputCoreConfBridge	Updating preview size and fps
>2014/02/25 14:25:17.694	  0:00.538	                       	VidInputCore	Setting new preview config: 320x240/30
>2014/02/25 14:25:17.694	  0:00.538	                       	VidInputCoreConfBridge	Updating preview size and fps
>2014/02/25 14:25:17.695	  0:00.539	                       	VidInputCore	Setting new preview config: 320x240/30
>2014/02/25 14:25:17.695	  0:00.539	                       	PV4L2Plugin	Detecting V4L2 devices
>2014/02/25 14:25:17.695	  0:00.539	                       	PV4L2Plugin	detected device metadata at /sys/class/video4linux/
>2014/02/25 14:25:17.714	  0:00.558	                       	VidInputCore	Detected Device: Moving Logo (Moving Logo/Moving Logo)
>2014/02/25 14:25:17.714	  0:00.558	                       	VidInputCore	Detected Device: Integrated Camera (PTLIB/V4L2)
>2014/02/25 14:25:17.714	  0:00.558	                       	VidInputCoreConfBridge	Updating device
>2014/02/25 14:25:17.714	  0:00.558	                       	VidInputCore	Setting device: Integrated Camera (PTLIB/V4L2)
>2014/02/25 14:25:17.714	  0:00.558	                       	GMVideoInputManager_ptlib	Setting Device Integrated Camera (PTLIB/V4L2)
>2014/02/25 14:25:17.714	  0:00.558	                       	PV4L2Plugin	Detecting V4L2 devices
>2014/02/25 14:25:17.714	  0:00.558	                       	PV4L2Plugin	detected device metadata at /sys/class/video4linux/
>2014/02/25 14:25:17.732	  0:00.576	                       	VidInputCore	Detected Device: Moving Logo (Moving Logo/Moving Logo)
>2014/02/25 14:25:17.732	  0:00.576	                       	VidInputCore	Detected Device: Integrated Camera (PTLIB/V4L2)
>2014/02/25 14:25:17.732	  0:00.576	                       	VidInputCoreConfBridge	Updating device
>2014/02/25 14:25:17.732	  0:00.577	                       	VidInputCore	Setting device: Integrated Camera (PTLIB/V4L2)
>2014/02/25 14:25:17.733	  0:00.577	                       	GMVideoInputManager_ptlib	Setting Device Integrated Camera (PTLIB/V4L2)
>2014/02/25 14:25:17.733	  0:00.577	                       	PV4L2Plugin	Detecting V4L2 devices
>2014/02/25 14:25:17.733	  0:00.577	                       	PV4L2Plugin	detected device metadata at /sys/class/video4linux/
>2014/02/25 14:25:17.751	  0:00.596	                       	VidInputCore	Detected Device: Moving Logo (Moving Logo/Moving Logo)
>2014/02/25 14:25:17.752	  0:00.596	                       	VidInputCore	Detected Device: Integrated Camera (PTLIB/V4L2)
>2014/02/25 14:25:17.752	  0:00.596	                       	VidInputCoreConfBridge	Updating device
>2014/02/25 14:25:17.752	  0:00.596	                       	VidInputCore	Setting device: Integrated Camera (PTLIB/V4L2)
>2014/02/25 14:25:17.752	  0:00.596	                       	GMVideoInputManager_ptlib	Setting Device Integrated Camera (PTLIB/V4L2)
>2014/02/25 14:25:17.753	  0:00.597	                       	VidInputCoreConfBridge	Updating image
>2014/02/25 14:25:17.753	  0:00.597	                       	AudioOutputCore	Detected Device: PulseAudio (PTLIB/Pulse)
>2014/02/25 14:25:17.754	  0:00.598	                       	AudioOutputCore	Detected Device: alsa_output.pci-0000_00_1b.0.analog-stereo (PTLIB/Pulse)
>2014/02/25 14:25:17.754	  0:00.598	                       	AudioOutputCore	Detected Device: Default (PTLIB/ALSA)
>2014/02/25 14:25:17.754	  0:00.598	                       	AudioOutputCore	Detected Device: HDA Intel PCH [ALC269VC Analog] (PTLIB/ALSA)
>2014/02/25 14:25:17.754	  0:00.598	                       	AudioOutputCore	Detected Device: HDA Intel PCH [HDMI 0] (PTLIB/ALSA)
>2014/02/25 14:25:17.754	  0:00.598	                       	AudioOutputCore	Detected Device: HDA Intel PCH [HDMI 1] (PTLIB/ALSA)
>2014/02/25 14:25:17.756	  0:00.600	                       	AudioOutputCore	Detected Device: HDA Intel PCH [HDMI 2] (PTLIB/ALSA)
>2014/02/25 14:25:17.756	  0:00.600	                       	AudioOutputCoreConfBridge	Set device to ALSA/Default
>2014/02/25 14:25:17.756	  0:00.600	                       	AudioOutputCore	Setting device[0]: Default (PTLIB/ALSA)
>2014/02/25 14:25:17.756	  0:00.600	                       	GMAudioOutputManager_ptlib	Setting Device[0] Default (PTLIB/ALSA)
>2014/02/25 14:25:17.756	  0:00.600	                       	AudioOutputCoreConfBridge	Updating device
>2014/02/25 14:25:17.756	  0:00.600	                       	AudioOutputCore	Setting device[1]: Default (PTLIB/ALSA)
>2014/02/25 14:25:17.771	  0:00.615	                       	AudioInputCore	Detected Device: PulseAudio (PTLIB/Pulse)
>2014/02/25 14:25:17.771	  0:00.615	                       	AudioInputCore	Detected Device: alsa_input.pci-0000_00_1b.0.analog-stereo (PTLIB/Pulse)
>2014/02/25 14:25:17.771	  0:00.615	                       	AudioInputCore	Detected Device: Default (PTLIB/ALSA)
>2014/02/25 14:25:17.771	  0:00.615	                       	AudioInputCore	Detected Device: HDA Intel PCH [ALC269VC Analog] (PTLIB/ALSA)
>2014/02/25 14:25:17.771	  0:00.615	                       	AudioInputCore	Setting device: Default (PTLIB/ALSA)
>2014/02/25 14:25:17.771	  0:00.615	                       	GMAudioInputManager_ptlib	Setting Device Default (PTLIB/ALSA)
>2014/02/25 14:25:17.771	  0:00.615	                       	AudioInputCore	Set device to ALSA/Default
>2014/02/25 14:25:17.791	  0:00.635	                       	Ekiga version 4.0.1
>2014/02/25 14:25:17.791	  0:00.635	                       	PTLIB version 2.10.10
>2014/02/25 14:25:17.791	  0:00.635	                       	OPAL version 3.10.10
>2014/02/25 14:25:17.791	  0:00.635	                       	Accelerated rendering support enabled
>2014/02/25 14:25:17.791	  0:00.635	                       	DBUS support enabled
>2014/02/25 14:25:17.791	  0:00.635	                       	GConf support enabled
>2014/02/25 14:25:22.507	  0:05.351	StunDetect...ffd8094700	PWLib	File handle low water mark set: 44 PUDPSocket
>2014/02/25 14:25:22.568	  0:05.412	StunDetect...ffd8094700	OPAL	STUN server "stun.ekiga.net" replies Port Restricted NAT, external IP 209.132.186.34
>2014/02/25 14:25:22.569	  0:05.413	StunDetect...ffd8094700	PTLib	Thread ended: name="StunDetector:0x7fffd8094700", real=5.195, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%)
>2014/02/25 14:25:22.767	  0:05.611	                       	Listen	Stopping listening thread on udp$*:5060
>2014/02/25 14:25:22.767	  0:05.611	                       	MonSock	Closed UDP socket 0x85a8d0
>2014/02/25 14:25:22.768	  0:05.612	Opal Liste...ffd8116700	Listen	UDP read error.
>2014/02/25 14:25:22.768	  0:05.612	Opal Liste...ffd8116700	PWLib	File handle low water mark set: 36 PTextFile
>2014/02/25 14:25:22.768	  0:05.612	Opal Liste...ffd8116700	PTLib	Thread ended: name="Opal Listener:0x7fffd8116700", real=5.418, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%)
>2014/02/25 14:25:22.788	  0:05.632	                       	MonSock	Deleting UDP socket 0x85a8d0
>2014/02/25 14:25:22.788	  0:05.632	Housekeepe...fff7f97700	Ekiga	Stopped STUN detector
>2014/02/25 14:25:22.788	  0:05.632	                       	PWLib	File handle low water mark set: 35 PUDPSocket
>2014/02/25 14:25:22.788	  0:05.632	                       	MonSock	Created socket bundle for all interfaces.
>2014/02/25 14:25:22.847	  0:05.691	                       	MonSock	Created bundled UDP socket via STUN, internal=10.34.130.146:5060, external=209.132.186.34:5060
>2014/02/25 14:25:22.848	  0:05.692	                       	PTLib	No permission to set priority level 4
>2014/02/25 14:25:22.848	  0:05.692	Opal Liste...ffd8116700	Listen	Started listening thread on udp$*:5060
>2014/02/25 14:25:22.848	  0:05.692	                       	Listen	Stopping listening thread on tcp$*:1720
>2014/02/25 14:25:22.848	  0:05.692	Opal Liste...ffd80d5700	PTLib	Thread ended: name="Opal Listener:0x7fffd80d5700", real=5.496, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%)
>2014/02/25 14:25:22.860	  0:05.704	Opal Liste...ffd80d5700	Listen	Started listening thread on tcp$*:1720
>2014/02/25 14:25:22.860	  0:05.704	Opal Liste...ffd80d5700	Listen	Waiting on socket accept on tcp$*:1720
>2014/02/25 14:25:22.860	  0:05.704	                       	PWLib	File handle high water mark set: 58 Thread unblock pipe
>2014/02/25 14:25:22.861	  0:05.705	                       	PTLib	Thread high water mark set: 11
>2014/02/25 14:25:22.861	  0:05.705	subscriber...ffd00b0700	OpalPres	'sip:desktopqe@ekiga.net' opening.
>2014/02/25 14:25:22.861	  0:05.705	subscriber...ffd00b0700	SIPPres	Using sip:desktopqe@ekiga.net as presence server for sip:desktopqe@ekiga.net
>2014/02/25 14:25:22.861	  0:05.705	subscriber...ffd00b0700	PWLib	File handle high water mark set: 60 Thread unblock pipe
>2014/02/25 14:25:22.861	  0:05.705	subscriber...ffd00f1700	MonSock	Created socket bundle for all interfaces.
>2014/02/25 14:25:22.862	  0:05.706	subscriber...ffd00b0700	PTLib	Thread high water mark set: 12
>2014/02/25 14:25:22.862	  0:05.706	subscriber...ffd00b0700	OpalPres	Creating presentity command 'SIP_Presentity21SIPWatcherInfoCommand'
>2014/02/25 14:25:22.862	  0:05.706	subscriber...ffd00b0700	SIP	Start REGISTER
>          aor=desktopqe@ekiga.net
>       remote=ekiga.net
>        local=
>      contact=
>        proxy=
>       authID=desktopqe
>        realm=
>       expire=3600
>      restore=30
>     minRetry=default
>     maxRetry=default
>compatibility=FullyCompliant
>2014/02/25 14:25:22.862	  0:05.706	subscriber...ffd00b0700	SIP	Constructed REGISTER handler for sip:desktopqe@ekiga.net
>2014/02/25 14:25:22.862	  0:05.706	subscriber...ffd00b0700	SIP	Executing state change to Subscribing for REGISTER handler, target=sip:desktopqe@ekiga.net, id=80f3c5f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:22.862	  0:05.707	subscriber...ffd00b0700	SIP	Changing REGISTER handler from Unavailable to Subscribing, target=sip:desktopqe@ekiga.net, id=80f3c5f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:22.863	  0:05.707	subscriber...ffd00b0700	DNS	SRV Lookup "_sip._udp.ekiga.net"
>2014/02/25 14:25:22.863	  0:05.707	PThreadObj...ffd006f700	OpalPres	Command thread started
>2014/02/25 14:25:22.863	  0:05.707	PThreadObj...ffd006f700	SIPPres	'sip:desktopqe@ekiga.net' sending subscribe for own presence.watcherinfo
>2014/02/25 14:25:22.863	  0:05.707	PThreadObj...ffd006f700	SIP	Start SUBSCRIBE
> eventPackage=presence.winfo
>          aor=sip:desktopqe@ekiga.net
>       remote=sip:desktopqe@ekiga.net;transport=tcp
>        local=sip:desktopqe@ekiga.net
>      contact=
>        proxy=
>       authID=desktopqe
>        realm=
>       expire=300
>      restore=30
>     minRetry=default
>     maxRetry=default
>2014/02/25 14:25:22.863	  0:05.707	PThreadObj...ffd006f700	SIP	Constructed SUBSCRIBE handler for sip:desktopqe@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net
>2014/02/25 14:25:22.863	  0:05.707	PThreadObj...ffd006f700	SIP	Executing state change to Subscribing for SUBSCRIBE handler, target=sip:desktopqe@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net, id=c017c6f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:22.863	  0:05.707	PThreadObj...ffd006f700	SIP	Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:desktopqe@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net, id=c017c6f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:22.863	  0:05.707	PThreadObj...ffd006f700	DNS	SRV Lookup "_sip._tcp.ekiga.net"
>2014/02/25 14:25:22.864	  0:05.708	subscriber...ffd00f1700	H323	Authenticator list is size 3
>2014/02/25 14:25:22.864	  0:05.708	subscriber...ffd00f1700	H460	Endpoint Attached
>2014/02/25 14:25:22.864	  0:05.708	subscriber...ffd00f1700	PWLib	File handle high water mark set: 63 Thread unblock pipe
>2014/02/25 14:25:22.865	  0:05.709	subscriber...ffd00f1700	PTLib	Thread high water mark set: 13
>2014/02/25 14:25:22.865	  0:05.709	subscriber...ffd00f1700	H460	Endpoint Attached
>2014/02/25 14:25:22.865	  0:05.709	GkMonitor:...ffa40ef700	RAS	Background thread started
>2014/02/25 14:25:22.901	  0:05.745	subscriber...ffd00f1700	OpalUDP	Started connect to 23.23.152.202:1719
>2014/02/25 14:25:22.901	  0:05.745	subscriber...ffd00f1700	PWLib	File handle high water mark set: 64 PUDPSocket
>2014/02/25 14:25:22.935	  0:05.779	subscriber...ffd00b0700	SIP	Attempting SRV record entry 0: 86.64.162.35:5060
>2014/02/25 14:25:22.935	  0:05.779	subscriber...ffd00b0700	OpalUDP	Binding to interface: 0.0.0.0:5060
>2014/02/25 14:25:22.935	  0:05.779	subscriber...ffd00b0700	PWLib	File handle high water mark set: 65 PUDPSocket
>2014/02/25 14:25:22.935	  0:05.779	subscriber...ffd00b0700	SIP	Created transport udp$86.64.162.35:5060<if=udp$*:5060>
>2014/02/25 14:25:22.935	  0:05.779	subscriber...ffd00b0700	OpalUDP	Started connect to 86.64.162.35:5060
>2014/02/25 14:25:22.935	  0:05.779	subscriber...ffd00b0700	OpalUDP	Writing to interface 0 - "10.34.130.146%em1"
>2014/02/25 14:25:22.936	  0:05.780	subscriber...ffd00b0700	OpalMan	Listener interfaces: associated transport=udp$209.132.186.34:5060
>    udp$209.132.186.34:5060,udp$10.34.130.146:5060
>2014/02/25 14:25:22.936	  0:05.780	subscriber...ffd00b0700	SIP	Transaction created.
>2014/02/25 14:25:22.936	  0:05.780	subscriber...ffd00b0700	SIP	Transaction remembering interface "10.34.130.146%em1"
>2014/02/25 14:25:22.936	  0:05.780	subscriber...ffd00b0700	DNS	SRV Lookup "_sip._udp.ekiga.net"
>2014/02/25 14:25:22.944	  0:05.788	subscriber...ffd00f1700	MonSock	Created bundled UDP socket via STUN, internal=10.34.130.146:5061, external=209.132.186.34:5061
>2014/02/25 14:25:22.944	  0:05.788	subscriber...ffd00f1700	PWLib	File handle high water mark set: 66 Thread unblock pipe
>2014/02/25 14:25:22.945	  0:05.789	subscriber...ffd00f1700	PTLib	Thread high water mark set: 14
>2014/02/25 14:25:22.945	  0:05.789	subscriber...ffd00f1700	PWLib	File handle high water mark set: 67 PTextFile
>2014/02/25 14:25:22.945	  0:05.789	subscriber...ffd00f1700	PTLib	Thread ended: name="subscriber:0x7fffd00f1700", real=0.085, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%)
>2014/02/25 14:25:22.945	  0:05.789	Transactor...ffa40ae700	Trans	Starting listener thread on udp$23.23.152.202:1719<if=udp$*:5061>
>2014/02/25 14:25:22.981	  0:05.825	                       	OpalPres	Creating presentity command 'SIP_Presentity27OpalSetLocalPresenceCommand'
>2014/02/25 14:25:22.981	  0:05.825	                       	Ekiga	Sent its own presence (publish) for sip:desktopqe@ekiga.net: available, note I'm available using Ekiga
>2014/02/25 14:25:23.045	  0:05.889	PThreadObj...ffd006f700	DNS	Query failed: error=-1
>2014/02/25 14:25:23.045	  0:05.889	PThreadObj...ffd006f700	SIP	No SRV record found.
>2014/02/25 14:25:23.045	  0:05.889	subscriber...ffd00b0700	SIP	Attempting SRV record entry 0: 86.64.162.35:5060
>2014/02/25 14:25:23.045	  0:05.889	PThreadObj...ffd006f700	SIP	No compatible listener to create transport for tcp$ekiga.net:5060
>2014/02/25 14:25:23.045	  0:05.889	subscriber...ffd00b0700	SIP	Transaction remote address is udp$86.64.162.35:5060
>2014/02/25 14:25:23.045	  0:05.889	PThreadObj...ffd006f700	SIP	Changing SUBSCRIBE handler from Subscribing to Unavailable, target=sip:desktopqe@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net, id=c017c6f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:23.045	  0:05.889	PThreadObj...ffd006f700	SIP	Retrying SUBSCRIBE after 30 seconds.
>2014/02/25 14:25:23.045	  0:05.889	PThreadObj...ffd006f700	SIPPres	'sip:desktopqe@ekiga.net' sending own presence Available/I'm available using Ekiga
>2014/02/25 14:25:23.046	  0:05.890	subscriber...ffd00b0700	PWLib	File handle low water mark set: 42 PUDPSocket
>2014/02/25 14:25:23.046	  0:05.890	PThreadObj...ffd006f700	SIPPres	PIDF entity derived from AOR as pres:desktopqe@ekiga.net
>2014/02/25 14:25:23.046	  0:05.890	PThreadObj...ffd006f700	SIP	Start PUBLISH
> eventPackage=presence
>          aor=sip:desktopqe@ekiga.net
>       remote=sip:desktopqe@ekiga.net
>        local=
>      contact=
>        proxy=
>       authID=
>        realm=
>       expire=300
>      restore=30
>     minRetry=default
>     maxRetry=default
>2014/02/25 14:25:23.045	  0:05.889	subscriber...ffd00b0700	SIP	Sending PDU (577 bytes) to: rem=udp$86.64.162.35:5060,local=udp$209.132.186.34:5060,if=10.34.130.146%em1
>REGISTER sip:ekiga.net SIP/2.0
>CSeq: 1 REGISTER
>Via: SIP/2.0/UDP 209.132.186.34:5060;branch=z9hG4bK1431d1f2-8d9c-e311-86d5-3c970e8ed11e;rport
>User-Agent: Ekiga/4.0.1
>From: <sip:desktopqe@ekiga.net>;tag=2cf7c5f2-8d9c-e311-86d5-3c970e8ed11e
>Call-ID: 80f3c5f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>To: <sip:desktopqe@ekiga.net>
>Contact: <sip:desktopqe@209.132.186.34:5060>;q=1, <sip:desktopqe@10.34.130.146:5060>;q=0.500
>Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK
>Expires: 3600
>Content-Length: 0
>Max-Forwards: 70
>
>
>2014/02/25 14:25:23.046	  0:05.890	subscriber...ffd00b0700	OpalUDP	Setting interface to 10.34.130.146%em1
>2014/02/25 14:25:23.046	  0:05.890	subscriber...ffd00b0700	SIP	Transaction timers set: retry=0.500, completion=6.000
>2014/02/25 14:25:23.046	  0:05.890	PThreadObj...ffd006f700	SIP	Constructed PUBLISH handler for sip:desktopqe@ekiga.net
>2014/02/25 14:25:23.046	  0:05.890	subscriber...ffd00b0700	PTLib	Thread ended: name="subscriber:0x7fffd00b0700", real=0.185, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%)
>2014/02/25 14:25:23.046	  0:05.890	PThreadObj...ffd006f700	SIP	Executing state change to Subscribing for PUBLISH handler, target=sip:desktopqe@ekiga.net, id=d0fae1f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:23.046	  0:05.890	PThreadObj...ffd006f700	SIP	Changing PUBLISH handler from Unavailable to Subscribing, target=sip:desktopqe@ekiga.net, id=d0fae1f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:23.046	  0:05.891	PThreadObj...ffd006f700	DNS	SRV Lookup "_sip._udp.ekiga.net"
>2014/02/25 14:25:23.047	  0:05.891	PThreadObj...ffd006f700	SIP	Attempting SRV record entry 0: 86.64.162.35:5060
>2014/02/25 14:25:23.047	  0:05.891	PThreadObj...ffd006f700	OpalUDP	Binding to interface: 0.0.0.0:5060
>2014/02/25 14:25:23.047	  0:05.891	PThreadObj...ffd006f700	SIP	Created transport udp$86.64.162.35:5060<if=udp$*:5060>
>2014/02/25 14:25:23.047	  0:05.891	PThreadObj...ffd006f700	OpalUDP	Started connect to 86.64.162.35:5060
>2014/02/25 14:25:23.047	  0:05.891	PThreadObj...ffd006f700	OpalUDP	Writing to interface 0 - "10.34.130.146%em1"
>2014/02/25 14:25:23.047	  0:05.891	PThreadObj...ffd006f700	SIP	Transaction created.
>2014/02/25 14:25:23.047	  0:05.891	PThreadObj...ffd006f700	SIP	Transaction remembering interface "10.34.130.146%em1"
>2014/02/25 14:25:23.047	  0:05.891	PThreadObj...ffd006f700	DNS	SRV Lookup "_sip._udp.ekiga.net"
>2014/02/25 14:25:23.048	  0:05.892	PThreadObj...ffd006f700	SIP	Attempting SRV record entry 0: 86.64.162.35:5060
>2014/02/25 14:25:23.048	  0:05.892	PThreadObj...ffd006f700	SIP	Transaction remote address is udp$86.64.162.35:5060
>2014/02/25 14:25:23.048	  0:05.892	PThreadObj...ffd006f700	SIP	Sending PDU (909 bytes) to: rem=udp$86.64.162.35:5060,local=udp$209.132.186.34:5060,if=10.34.130.146%em1
>PUBLISH sip:desktopqe@ekiga.net SIP/2.0
>CSeq: 2 PUBLISH
>Via: SIP/2.0/UDP 209.132.186.34:5060;branch=z9hG4bK5a2fe2f2-8d9c-e311-86d5-3c970e8ed11e;rport
>User-Agent: Ekiga/4.0.1
>From: <sip:desktopqe@ekiga.net>
>Call-ID: d0fae1f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>To: <sip:desktopqe@ekiga.net>
>Expires: 300
>Event: presence
>Content-Length: 490
>Content-Type: application/pidf+xml
>Max-Forwards: 70
>
><?xml version="1.0" encoding="UTF-8"?>
><presence xmlns="urn:ietf:params:xml:ns:pidf"  xmlns:dm="urn:ietf:params:xml:ns:pidf:data-model" xmlns:rpid="urn:ietf:params:xml:ns:pidf:rpid" entity="pres:desktopqe@ekiga.net">
>  <tuple id="TA969AE4E">
>    <status>
>      <basic>open</basic>
>    </status>
>    <contact priority="1">sip:desktopqe@ekiga.net</contact>
>    <note>I&apos;m available using Ekiga</note>
>    <timestamp>2014-02-25T14:25:23+01:00</timestamp>
>  </tuple>
></presence>
>
>2014/02/25 14:25:23.048	  0:05.892	PThreadObj...ffd006f700	OpalUDP	Setting interface to 10.34.130.146%em1
>2014/02/25 14:25:23.048	  0:05.892	PThreadObj...ffd006f700	SIP	Transaction timers set: retry=0.500, completion=6.000
>2014/02/25 14:25:23.093	  0:05.937	Opal Liste...ffd8116700	OpalUDP	Binding to interface: 10.34.130.146:5060
>2014/02/25 14:25:23.093	  0:05.937	Opal Liste...ffd8116700	SIP	Waiting for PDU on udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:23.093	  0:05.937	Opal Liste...ffd8116700	SIP	PDU received: rem=udp$86.64.162.35:5060,local=udp$209.132.186.34:5060,if=10.34.130.146%em1
>SIP/2.0 401 Unauthorized
>CSeq: 1 REGISTER
>Via: SIP/2.0/UDP 10.34.130.146:5060;branch=z9hG4bK1431d1f2-8d9c-e311-86d5-3c970e8ed11e;rport=5060
>Server: Kamailio (1.5.3-notls (i386/linux))
>From: <sip:desktopqe@ekiga.net>;tag=2cf7c5f2-8d9c-e311-86d5-3c970e8ed11e
>Call-ID: 80f3c5f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>To: <sip:desktopqe@ekiga.net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.763c
>Content-Length: 0
>WWW-Authenticate: Digest realm="ekiga.net", nonce="530c99e10000ce4f2457c13b8ba9dec16fe0fd54a4c22bb4"
>
>
>2014/02/25 14:25:23.093	  0:05.937	Opal Liste...ffd8116700	SIP	Queueing PDU "1 REGISTER <401>", transaction=z9hG4bK1431d1f2-8d9c-e311-86d5-3c970e8ed11e, token=80f3c5f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:23.093	  0:05.937	Opal Liste...ffd8116700	PTLib	No permission to set priority level 3
>2014/02/25 14:25:23.094	  0:05.938	Opal Liste...ffd8116700	Opal	Transport clean up on termination
>2014/02/25 14:25:23.094	  0:05.938	Opal Liste...ffd8116700	Opal	Transport Close
>2014/02/25 14:25:23.094	  0:05.938	    Pool:0x7fffa406d700	SIP	Handling PDU "1 REGISTER <401>" for transaction=z9hG4bK1431d1f2-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:23.094	  0:05.938	    Pool:0x7fffa406d700	SIP	REGISTER transaction id=z9hG4bK1431d1f2-8d9c-e311-86d5-3c970e8ed11e completing.
>2014/02/25 14:25:23.094	  0:05.938	    Pool:0x7fffa406d700	SIP	Finalising handlers interface "10.34.130.146%em1"
>2014/02/25 14:25:23.094	  0:05.938	    Pool:0x7fffa406d700	OpalUDP	Setting interface to 10.34.130.146%em1
>2014/02/25 14:25:23.094	  0:05.938	Opal Liste...ffd8116700	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:23.094	  0:05.939	    Pool:0x7fffa406d700	SIP	Received Authentication Required response for z9hG4bK1431d1f2-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:23.095	  0:05.939	    Pool:0x7fffa406d700	SIP	Creating authentication credentials of user "desktopqe" for realm "ekiga.net"
>2014/02/25 14:25:23.095	  0:05.939	    Pool:0x7fffa406d700	SIP	Auth realm set to ekiga.net
>2014/02/25 14:25:23.095	  0:05.939	    Pool:0x7fffa406d700	OpalMan	Listener interfaces: associated transport=udp$209.132.186.34:5060
>    udp$209.132.186.34:5060,udp$10.34.130.146:5060
>2014/02/25 14:25:23.095	  0:05.939	    Pool:0x7fffa406d700	SIP	Transaction created.
>2014/02/25 14:25:23.096	  0:05.940	    Pool:0x7fffa406d700	HTTP	Adding authentication information
>2014/02/25 14:25:23.096	  0:05.940	    Pool:0x7fffa406d700	SIP	Transaction remembering interface "10.34.130.146%em1"
>2014/02/25 14:25:23.096	  0:05.940	    Pool:0x7fffa406d700	DNS	SRV Lookup "_sip._udp.ekiga.net"
>2014/02/25 14:25:23.096	  0:05.940	    Pool:0x7fffa406d700	SIP	Attempting SRV record entry 0: 86.64.162.35:5060
>2014/02/25 14:25:23.096	  0:05.940	Opal Liste...ffd8116700	OpalUDP	Binding to interface: 10.34.130.146:5060
>2014/02/25 14:25:23.096	  0:05.940	    Pool:0x7fffa406d700	SIP	Transaction remote address is udp$86.64.162.35:5060
>2014/02/25 14:25:23.096	  0:05.940	Opal Liste...ffd8116700	SIP	Waiting for PDU on udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:23.096	  0:05.940	    Pool:0x7fffa406d700	SIP	Sending PDU (779 bytes) to: rem=udp$86.64.162.35:5060,local=udp$209.132.186.34:5060,if=10.34.130.146%em1
>REGISTER sip:ekiga.net SIP/2.0
>CSeq: 3 REGISTER
>Via: SIP/2.0/UDP 209.132.186.34:5060;branch=z9hG4bK1284e9f2-8d9c-e311-86d5-3c970e8ed11e;rport
>User-Agent: Ekiga/4.0.1
>Authorization: Digest username="desktopqe", realm="ekiga.net", nonce="530c99e10000ce4f2457c13b8ba9dec16fe0fd54a4c22bb4", uri="sip:ekiga.net", algorithm=MD5, response="b3369dbd2885ef725fc215a572afe0e2"
>From: <sip:desktopqe@ekiga.net>;tag=2cf7c5f2-8d9c-e311-86d5-3c970e8ed11e
>Call-ID: 80f3c5f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>To: <sip:desktopqe@ekiga.net>
>Contact: <sip:desktopqe@209.132.186.34:5060>;q=1, <sip:desktopqe@10.34.130.146:5060>;q=0.500
>Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK
>Expires: 3600
>Content-Length: 0
>Max-Forwards: 70
>
>
>2014/02/25 14:25:23.096	  0:05.940	    Pool:0x7fffa406d700	OpalUDP	Setting interface to 10.34.130.146%em1
>2014/02/25 14:25:23.096	  0:05.940	    Pool:0x7fffa406d700	SIP	Transaction timers set: retry=0.500, completion=6.000
>2014/02/25 14:25:23.096	  0:05.940	    Pool:0x7fffa406d700	SIP	REGISTER transaction id=z9hG4bK1431d1f2-8d9c-e311-86d5-3c970e8ed11e completed.
>2014/02/25 14:25:23.096	  0:05.940	Opal Liste...ffd8116700	SIP	PDU received: rem=udp$86.64.162.35:5060,local=udp$209.132.186.34:5060,if=10.34.130.146%em1
>SIP/2.0 200 OK
>CSeq: 2 PUBLISH
>Via: SIP/2.0/UDP 10.34.130.146:5060;branch=z9hG4bK5a2fe2f2-8d9c-e311-86d5-3c970e8ed11e;rport=5060
>Server: Kamailio (1.5.3-notls (i386/linux))
>From: <sip:desktopqe@ekiga.net>
>Call-ID: d0fae1f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>To: <sip:desktopqe@ekiga.net>;tag=f85b0bd16aaafa8479586ac9f88b3198-929c
>Expires: 295
>Content-Length: 0
>SIP-ETag: a.1392937081.776.597152.0
>
>
>2014/02/25 14:25:23.096	  0:05.940	    Pool:0x7fffa406d700	SIP	Handled PDU "1 REGISTER <401>"
>2014/02/25 14:25:23.096	  0:05.940	Opal Liste...ffd8116700	SIP	Queueing PDU "2 PUBLISH <200>", transaction=z9hG4bK5a2fe2f2-8d9c-e311-86d5-3c970e8ed11e, token=d0fae1f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:23.096	  0:05.940	Opal Liste...ffd8116700	Opal	Transport clean up on termination
>2014/02/25 14:25:23.096	  0:05.940	Opal Liste...ffd8116700	Opal	Transport Close
>2014/02/25 14:25:23.096	  0:05.940	    Pool:0x7fffa406d700	SIP	Handling PDU "2 PUBLISH <200>" for transaction=z9hG4bK5a2fe2f2-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:23.096	  0:05.940	    Pool:0x7fffa406d700	SIP	PUBLISH transaction id=z9hG4bK5a2fe2f2-8d9c-e311-86d5-3c970e8ed11e completing.
>2014/02/25 14:25:23.096	  0:05.940	    Pool:0x7fffa406d700	SIP	Finalising handlers interface "10.34.130.146%em1"
>2014/02/25 14:25:23.096	  0:05.940	    Pool:0x7fffa406d700	OpalUDP	Setting interface to 10.34.130.146%em1
>2014/02/25 14:25:23.096	  0:05.940	    Pool:0x7fffa406d700	SIP	Expiry time for PUBLISH set to 295 seconds.
>2014/02/25 14:25:23.096	  0:05.940	    Pool:0x7fffa406d700	SIP	Product Info: name="Kamailio", version="", vendor="", comments="(1.5.3-notls (i386/linux))"
>2014/02/25 14:25:23.096	  0:05.940	Opal Liste...ffd8116700	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:23.096	  0:05.940	    Pool:0x7fffa406d700	SIP	Changing PUBLISH handler from Subscribing to Subscribed, target=sip:desktopqe@ekiga.net, id=d0fae1f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:23.096	  0:05.940	    Pool:0x7fffa406d700	SIP	PUBLISH transaction id=z9hG4bK5a2fe2f2-8d9c-e311-86d5-3c970e8ed11e completed.
>2014/02/25 14:25:23.096	  0:05.940	    Pool:0x7fffa406d700	SIP	Handled PDU "2 PUBLISH <200>"
>2014/02/25 14:25:23.161	  0:06.005	Opal Liste...ffd8116700	OpalUDP	Binding to interface: 10.34.130.146:5060
>2014/02/25 14:25:23.161	  0:06.005	Opal Liste...ffd8116700	SIP	Waiting for PDU on udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:23.162	  0:06.006	Opal Liste...ffd8116700	SIP	PDU received: rem=udp$86.64.162.35:5060,local=udp$209.132.186.34:5060,if=10.34.130.146%em1
>SIP/2.0 200 OK
>CSeq: 3 REGISTER
>Via: SIP/2.0/UDP 10.34.130.146:5060;branch=z9hG4bK1284e9f2-8d9c-e311-86d5-3c970e8ed11e;rport=5060
>Server: Kamailio (1.5.3-notls (i386/linux))
>From: <sip:desktopqe@ekiga.net>;tag=2cf7c5f2-8d9c-e311-86d5-3c970e8ed11e
>Call-ID: 80f3c5f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>To: <sip:desktopqe@ekiga.net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.606a
>Contact: <sip:desktopqe@10.34.130.146:5060>;q=1;expires=1200
>Content-Length: 0
>
>
>2014/02/25 14:25:23.162	  0:06.006	Opal Liste...ffd8116700	SIP	Queueing PDU "3 REGISTER <200>", transaction=z9hG4bK1284e9f2-8d9c-e311-86d5-3c970e8ed11e, token=80f3c5f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:23.162	  0:06.006	Opal Liste...ffd8116700	Opal	Transport clean up on termination
>2014/02/25 14:25:23.162	  0:06.006	Opal Liste...ffd8116700	Opal	Transport Close
>2014/02/25 14:25:23.162	  0:06.006	    Pool:0x7fffa406d700	SIP	Handling PDU "3 REGISTER <200>" for transaction=z9hG4bK1284e9f2-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:23.162	  0:06.006	    Pool:0x7fffa406d700	SIP	REGISTER transaction id=z9hG4bK1284e9f2-8d9c-e311-86d5-3c970e8ed11e completing.
>2014/02/25 14:25:23.162	  0:06.006	    Pool:0x7fffa406d700	SIP	Finalising handlers interface "10.34.130.146%em1"
>2014/02/25 14:25:23.162	  0:06.006	    Pool:0x7fffa406d700	OpalUDP	Setting interface to 10.34.130.146%em1
>2014/02/25 14:25:23.162	  0:06.006	Opal Liste...ffd8116700	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:23.162	  0:06.006	    Pool:0x7fffa406d700	SIP	Product Info: name="Kamailio", version="", vendor="", comments="(1.5.3-notls (i386/linux))"
>2014/02/25 14:25:23.162	  0:06.006	    Pool:0x7fffa406d700	SIP	Expiry time for REGISTER set to 1200 seconds.
>2014/02/25 14:25:23.162	  0:06.006	    Pool:0x7fffa406d700	SIP	Changing REGISTER handler from Subscribing to Subscribed, target=sip:desktopqe@ekiga.net, id=80f3c5f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:23.162	  0:06.006	    Pool:0x7fffa406d700	SIP	REGISTER transaction id=z9hG4bK1284e9f2-8d9c-e311-86d5-3c970e8ed11e completed.
>2014/02/25 14:25:23.162	  0:06.006	    Pool:0x7fffa406d700	SIP	Handled PDU "3 REGISTER <200>"
>2014/02/25 14:25:23.183	  0:06.027	                       	Ekiga	SubscribeToPresence for sip:500@ekiga.net (Account Registered)
>2014/02/25 14:25:23.183	  0:06.027	                       	OpalPres	Creating presentity command 'SIP_Presentity30OpalSubscribeToPresenceCommand'
>2014/02/25 14:25:23.183	  0:06.027	                       	Ekiga	SubscribeToPresence for sip:501@ekiga.net (Account Registered)
>2014/02/25 14:25:23.183	  0:06.027	                       	OpalPres	Creating presentity command 'SIP_Presentity30OpalSubscribeToPresenceCommand'
>2014/02/25 14:25:23.183	  0:06.027	                       	Ekiga	SubscribeToPresence for sip:520@ekiga.net (Account Registered)
>2014/02/25 14:25:23.183	  0:06.027	                       	OpalPres	Creating presentity command 'SIP_Presentity30OpalSubscribeToPresenceCommand'
>2014/02/25 14:25:23.183	  0:06.027	PThreadObj...ffd006f700	SIPPres	'sip:desktopqe@ekiga.net' subscribing to presence of 'sip:500@ekiga.net'
>2014/02/25 14:25:23.183	  0:06.027	                       	Ekiga	SubscribeToPresence for sip:qedesktop@ekiga.net (Account Registered)
>2014/02/25 14:25:23.183	  0:06.027	PThreadObj...ffd006f700	SIP	Start SUBSCRIBE
> eventPackage=presence
>          aor=sip:500@ekiga.net
>       remote=
>        local=sip:desktopqe@ekiga.net
>      contact=
>        proxy=
>       authID=desktopqe
>        realm=
>       expire=300
>      restore=30
>     minRetry=default
>     maxRetry=default
>2014/02/25 14:25:23.183	  0:06.027	                       	OpalPres	Creating presentity command 'SIP_Presentity30OpalSubscribeToPresenceCommand'
>2014/02/25 14:25:23.183	  0:06.027	                       	OpalPres	Creating presentity command 'SIP_Presentity27OpalSetLocalPresenceCommand'
>2014/02/25 14:25:23.183	  0:06.027	                       	SIP	Start SUBSCRIBE
> eventPackage=message-summary
>          aor=sip:desktopqe@ekiga.net
>       remote=
>        local=
>      contact=
>        proxy=
>       authID=
>        realm=
>       expire=3600
>      restore=30
>     minRetry=default
>     maxRetry=default
>2014/02/25 14:25:23.183	  0:06.027	                       	SIP	Constructed SUBSCRIBE handler for sip:desktopqe@ekiga.net
>2014/02/25 14:25:23.183	  0:06.027	PThreadObj...ffd006f700	SIP	Constructed SUBSCRIBE handler for sip:500@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net
>2014/02/25 14:25:23.183	  0:06.027	                       	SIP	Executing state change to Subscribing for SUBSCRIBE handler, target=sip:desktopqe@ekiga.net, id=a2f0f6f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:23.183	  0:06.027	PThreadObj...ffd006f700	SIP	Executing state change to Subscribing for SUBSCRIBE handler, target=sip:500@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net, id=5af2f6f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:23.183	  0:06.027	                       	SIP	Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:desktopqe@ekiga.net, id=a2f0f6f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:23.183	  0:06.028	                       	DNS	SRV Lookup "_sip._udp.ekiga.net"
>2014/02/25 14:25:23.184	  0:06.028	PThreadObj...ffd006f700	SIP	Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:500@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net, id=5af2f6f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:23.184	  0:06.028	PThreadObj...ffd006f700	DNS	SRV Lookup "_sip._udp.ekiga.net"
>2014/02/25 14:25:23.184	  0:06.028	                       	SIP	Attempting SRV record entry 0: 86.64.162.35:5060
>2014/02/25 14:25:23.184	  0:06.028	PThreadObj...ffd006f700	SIP	Attempting SRV record entry 0: 86.64.162.35:5060
>2014/02/25 14:25:23.184	  0:06.028	PThreadObj...ffd006f700	OpalUDP	Binding to interface: 0.0.0.0:5060
>2014/02/25 14:25:23.184	  0:06.028	                       	OpalUDP	Binding to interface: 0.0.0.0:5060
>2014/02/25 14:25:23.184	  0:06.028	PThreadObj...ffd006f700	SIP	Created transport udp$86.64.162.35:5060<if=udp$*:5060>
>2014/02/25 14:25:23.184	  0:06.028	PThreadObj...ffd006f700	OpalUDP	Started connect to 86.64.162.35:5060
>2014/02/25 14:25:23.184	  0:06.028	                       	SIP	Created transport udp$86.64.162.35:5060<if=udp$*:5060>
>2014/02/25 14:25:23.184	  0:06.028	                       	OpalUDP	Started connect to 86.64.162.35:5060
>2014/02/25 14:25:23.184	  0:06.028	PThreadObj...ffd006f700	OpalUDP	Writing to interface 0 - "10.34.130.146%em1"
>2014/02/25 14:25:23.184	  0:06.028	                       	OpalUDP	Writing to interface 0 - "10.34.130.146%em1"
>2014/02/25 14:25:23.184	  0:06.028	                       	SIP	Transaction created.
>2014/02/25 14:25:23.184	  0:06.028	PThreadObj...ffd006f700	SIP	Transaction created.
>2014/02/25 14:25:23.185	  0:06.029	                       	SIP	Transaction remembering interface "10.34.130.146%em1"
>2014/02/25 14:25:23.185	  0:06.029	                       	DNS	SRV Lookup "_sip._udp.ekiga.net"
>2014/02/25 14:25:23.185	  0:06.029	PThreadObj...ffd006f700	SIP	Transaction remembering interface "10.34.130.146%em1"
>2014/02/25 14:25:23.185	  0:06.029	PThreadObj...ffd006f700	DNS	SRV Lookup "_sip._udp.ekiga.net"
>2014/02/25 14:25:23.185	  0:06.029	                       	SIP	Attempting SRV record entry 0: 86.64.162.35:5060
>2014/02/25 14:25:23.185	  0:06.029	                       	SIP	Transaction remote address is udp$86.64.162.35:5060
>2014/02/25 14:25:23.185	  0:06.029	PThreadObj...ffd006f700	SIP	Attempting SRV record entry 0: 86.64.162.35:5060
>2014/02/25 14:25:23.185	  0:06.029	PThreadObj...ffd006f700	SIP	Transaction remote address is udp$86.64.162.35:5060
>2014/02/25 14:25:23.185	  0:06.029	                       	SIP	Sending PDU (604 bytes) to: rem=udp$86.64.162.35:5060,local=udp$209.132.186.34:5060,if=10.34.130.146%em1
>SUBSCRIBE sip:desktopqe@ekiga.net SIP/2.0
>CSeq: 1 SUBSCRIBE
>Via: SIP/2.0/UDP 209.132.186.34:5060;branch=z9hG4bKc216f7f2-8d9c-e311-86d5-3c970e8ed11e;rport
>User-Agent: Ekiga/4.0.1
>From: <sip:desktopqe@ekiga.net>;tag=7211f7f2-8d9c-e311-86d5-3c970e8ed11e
>Call-ID: a2f0f6f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>To: <sip:desktopqe@ekiga.net>
>Accept: application/simple-message-summary
>Contact: <sip:desktopqe@209.132.186.34>
>Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK
>Expires: 3600
>Event: message-summary
>Content-Length: 0
>Max-Forwards: 70
>
>
>2014/02/25 14:25:23.185	  0:06.029	                       	OpalUDP	Setting interface to 10.34.130.146%em1
>2014/02/25 14:25:23.185	  0:06.029	PThreadObj...ffd006f700	SIP	Sending PDU (649 bytes) to: rem=udp$86.64.162.35:5060,local=udp$209.132.186.34:5060,if=10.34.130.146%em1
>SUBSCRIBE sip:500@ekiga.net SIP/2.0
>CSeq: 1 SUBSCRIBE
>Via: SIP/2.0/UDP 209.132.186.34:5060;branch=z9hG4bK5c18f7f2-8d9c-e311-86d5-3c970e8ed11e;rport
>User-Agent: Ekiga/4.0.1
>From: <sip:desktopqe@ekiga.net>;tag=3611f7f2-8d9c-e311-86d5-3c970e8ed11e
>Call-ID: 5af2f6f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>Supported: eventlist
>To: <sip:500@ekiga.net>
>Accept: application/pidf+xml
>Accept: multipart/related
>Accept: application/rlmi+xml
>Contact: <sip:desktopqe@209.132.186.34>
>Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK
>Expires: 300
>Event: presence
>Content-Length: 0
>Max-Forwards: 70
>
>
>2014/02/25 14:25:23.185	  0:06.029	                       	SIP	Transaction timers set: retry=0.500, completion=6.000
>2014/02/25 14:25:23.185	  0:06.029	PThreadObj...ffd006f700	OpalUDP	Setting interface to 10.34.130.146%em1
>2014/02/25 14:25:23.185	  0:06.029	                       	OpalPres	Creating presentity command 'SIP_Presentity27OpalSetLocalPresenceCommand'
>2014/02/25 14:25:23.185	  0:06.029	                       	Ekiga	Sent its own presence (publish) for sip:desktopqe@ekiga.net: available, note I'm available using Ekiga
>2014/02/25 14:25:23.185	  0:06.029	PThreadObj...ffd006f700	SIP	Transaction timers set: retry=0.500, completion=6.000
>2014/02/25 14:25:23.185	  0:06.030	PThreadObj...ffd006f700	SIPPres	'sip:desktopqe@ekiga.net' subscribing to presence of 'sip:501@ekiga.net'
>2014/02/25 14:25:23.186	  0:06.030	PThreadObj...ffd006f700	SIP	Start SUBSCRIBE
> eventPackage=presence
>          aor=sip:501@ekiga.net
>       remote=
>        local=sip:desktopqe@ekiga.net
>      contact=
>        proxy=
>       authID=desktopqe
>        realm=
>       expire=300
>      restore=30
>     minRetry=default
>     maxRetry=default
>2014/02/25 14:25:23.186	  0:06.030	PThreadObj...ffd006f700	SIP	Constructed SUBSCRIBE handler for sip:501@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net
>2014/02/25 14:25:23.186	  0:06.030	PThreadObj...ffd006f700	SIP	Executing state change to Subscribing for SUBSCRIBE handler, target=sip:501@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net, id=0c4ff7f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:23.186	  0:06.030	PThreadObj...ffd006f700	SIP	Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:501@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net, id=0c4ff7f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:23.186	  0:06.030	PThreadObj...ffd006f700	DNS	SRV Lookup "_sip._udp.ekiga.net"
>2014/02/25 14:25:23.186	  0:06.030	PThreadObj...ffd006f700	SIP	Attempting SRV record entry 0: 86.64.162.35:5060
>2014/02/25 14:25:23.186	  0:06.030	PThreadObj...ffd006f700	OpalUDP	Binding to interface: 0.0.0.0:5060
>2014/02/25 14:25:23.186	  0:06.030	PThreadObj...ffd006f700	SIP	Created transport udp$86.64.162.35:5060<if=udp$*:5060>
>2014/02/25 14:25:23.186	  0:06.030	PThreadObj...ffd006f700	OpalUDP	Started connect to 86.64.162.35:5060
>2014/02/25 14:25:23.186	  0:06.030	PThreadObj...ffd006f700	OpalUDP	Writing to interface 0 - "10.34.130.146%em1"
>2014/02/25 14:25:23.187	  0:06.031	PThreadObj...ffd006f700	SIP	Transaction created.
>2014/02/25 14:25:23.187	  0:06.031	PThreadObj...ffd006f700	SIP	Transaction remembering interface "10.34.130.146%em1"
>2014/02/25 14:25:23.187	  0:06.031	PThreadObj...ffd006f700	DNS	SRV Lookup "_sip._udp.ekiga.net"
>2014/02/25 14:25:23.187	  0:06.031	PThreadObj...ffd006f700	SIP	Attempting SRV record entry 0: 86.64.162.35:5060
>2014/02/25 14:25:23.187	  0:06.031	PThreadObj...ffd006f700	SIP	Transaction remote address is udp$86.64.162.35:5060
>2014/02/25 14:25:23.188	  0:06.032	PThreadObj...ffd006f700	SIP	Sending PDU (649 bytes) to: rem=udp$86.64.162.35:5060,local=udp$209.132.186.34:5060,if=10.34.130.146%em1
>SUBSCRIBE sip:501@ekiga.net SIP/2.0
>CSeq: 1 SUBSCRIBE
>Via: SIP/2.0/UDP 209.132.186.34:5060;branch=z9hG4bK1073f7f2-8d9c-e311-86d5-3c970e8ed11e;rport
>User-Agent: Ekiga/4.0.1
>From: <sip:desktopqe@ekiga.net>;tag=086cf7f2-8d9c-e311-86d5-3c970e8ed11e
>Call-ID: 0c4ff7f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>Supported: eventlist
>To: <sip:501@ekiga.net>
>Accept: application/pidf+xml
>Accept: multipart/related
>Accept: application/rlmi+xml
>Contact: <sip:desktopqe@209.132.186.34>
>Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK
>Expires: 300
>Event: presence
>Content-Length: 0
>Max-Forwards: 70
>
>
>2014/02/25 14:25:23.188	  0:06.032	PThreadObj...ffd006f700	OpalUDP	Setting interface to 10.34.130.146%em1
>2014/02/25 14:25:23.188	  0:06.032	PThreadObj...ffd006f700	SIP	Transaction timers set: retry=0.500, completion=6.000
>2014/02/25 14:25:23.232	  0:06.076	Opal Liste...ffd8116700	OpalUDP	Binding to interface: 10.34.130.146:5060
>2014/02/25 14:25:23.232	  0:06.076	Opal Liste...ffd8116700	SIP	Waiting for PDU on udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:23.232	  0:06.077	Opal Liste...ffd8116700	SIP	PDU received: rem=udp$86.64.162.35:5060,local=udp$209.132.186.34:5060,if=10.34.130.146%em1
>SIP/2.0 489 Event Not Supported
>CSeq: 1 SUBSCRIBE
>Via: SIP/2.0/UDP 10.34.130.146:5060;branch=z9hG4bKc216f7f2-8d9c-e311-86d5-3c970e8ed11e;rport=5060
>Server: Kamailio (1.5.3-notls (i386/linux))
>From: <sip:desktopqe@ekiga.net>;tag=7211f7f2-8d9c-e311-86d5-3c970e8ed11e
>Call-ID: a2f0f6f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>To: <sip:desktopqe@ekiga.net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.2b26
>Content-Length: 0
>
>
>2014/02/25 14:25:23.233	  0:06.077	Opal Liste...ffd8116700	SIP	Queueing PDU "1 SUBSCRIBE <489>", transaction=z9hG4bKc216f7f2-8d9c-e311-86d5-3c970e8ed11e, token=a2f0f6f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:23.233	  0:06.077	Opal Liste...ffd8116700	Opal	Transport clean up on termination
>2014/02/25 14:25:23.233	  0:06.077	Opal Liste...ffd8116700	Opal	Transport Close
>2014/02/25 14:25:23.233	  0:06.077	    Pool:0x7fffa406d700	SIP	Handling PDU "1 SUBSCRIBE <489>" for transaction=z9hG4bKc216f7f2-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:23.233	  0:06.077	    Pool:0x7fffa406d700	SIP	SUBSCRIBE transaction id=z9hG4bKc216f7f2-8d9c-e311-86d5-3c970e8ed11e completing.
>2014/02/25 14:25:23.233	  0:06.077	Opal Liste...ffd8116700	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:23.233	  0:06.077	    Pool:0x7fffa406d700	SIP	Not retrying SUBSCRIBE due to error response 489 Bad Event
>2014/02/25 14:25:23.233	  0:06.077	    Pool:0x7fffa406d700	SIP	Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:desktopqe@ekiga.net, id=a2f0f6f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:23.233	  0:06.077	    Pool:0x7fffa406d700	SIP	SUBSCRIBE transaction id=z9hG4bKc216f7f2-8d9c-e311-86d5-3c970e8ed11e completed.
>2014/02/25 14:25:23.233	  0:06.077	    Pool:0x7fffa406d700	SIP	Handled PDU "1 SUBSCRIBE <489>"
>2014/02/25 14:25:23.233	  0:06.077	Opal Liste...ffd8116700	OpalUDP	Binding to interface: 10.34.130.146:5060
>2014/02/25 14:25:23.233	  0:06.077	Opal Liste...ffd8116700	SIP	Waiting for PDU on udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:23.233	  0:06.077	Opal Liste...ffd8116700	SIP	PDU received: rem=udp$86.64.162.35:5060,local=udp$209.132.186.34:5060,if=10.34.130.146%em1
>SIP/2.0 489 Event Not Supported
>CSeq: 1 SUBSCRIBE
>Via: SIP/2.0/UDP 10.34.130.146:5060;branch=z9hG4bK5c18f7f2-8d9c-e311-86d5-3c970e8ed11e;rport=5060
>Server: Kamailio (1.5.3-notls (i386/linux))
>From: <sip:desktopqe@ekiga.net>;tag=3611f7f2-8d9c-e311-86d5-3c970e8ed11e
>Call-ID: 5af2f6f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>To: <sip:500@ekiga.net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.8845
>Content-Length: 0
>
>
>2014/02/25 14:25:23.233	  0:06.078	Opal Liste...ffd8116700	SIP	Queueing PDU "1 SUBSCRIBE <489>", transaction=z9hG4bK5c18f7f2-8d9c-e311-86d5-3c970e8ed11e, token=5af2f6f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:23.234	  0:06.078	Opal Liste...ffd8116700	Opal	Transport clean up on termination
>2014/02/25 14:25:23.234	  0:06.078	Opal Liste...ffd8116700	Opal	Transport Close
>2014/02/25 14:25:23.234	  0:06.078	    Pool:0x7fffa406d700	SIP	Handling PDU "1 SUBSCRIBE <489>" for transaction=z9hG4bK5c18f7f2-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:23.234	  0:06.078	    Pool:0x7fffa406d700	SIP	SUBSCRIBE transaction id=z9hG4bK5c18f7f2-8d9c-e311-86d5-3c970e8ed11e completing.
>2014/02/25 14:25:23.234	  0:06.078	    Pool:0x7fffa406d700	SIPPres	'sip:desktopqe@ekiga.net' error subscribing to presence of 'sip:500@ekiga.net'
>2014/02/25 14:25:23.234	  0:06.078	Opal Liste...ffd8116700	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:23.234	  0:06.078	    Pool:0x7fffa406d700	SIP	Expiry time for SUBSCRIBE set to 300 seconds.
>2014/02/25 14:25:23.234	  0:06.078	    Pool:0x7fffa406d700	SIP	Queueing state change to Unsubscribing for SUBSCRIBE handler while in Subscribing state, target=sip:500@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net, id=5af2f6f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:23.234	  0:06.078	    Pool:0x7fffa406d700	SIP	Not retrying SUBSCRIBE due to error response 489 Bad Event
>2014/02/25 14:25:23.234	  0:06.078	    Pool:0x7fffa406d700	SIP	Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:500@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net, id=5af2f6f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:23.234	  0:06.078	    Pool:0x7fffa406d700	SIP	Changing SUBSCRIBE handler from Unsubscribed to Unsubscribing, target=sip:500@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net, id=5af2f6f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:23.234	  0:06.078	    Pool:0x7fffa406d700	OpalUDP	Writing to interface 0 - "10.34.130.146%em1"
>2014/02/25 14:25:23.234	  0:06.078	    Pool:0x7fffa406d700	SIP	Transaction created.
>2014/02/25 14:25:23.235	  0:06.079	    Pool:0x7fffa406d700	SIP	Transaction remembering interface "10.34.130.146%em1"
>2014/02/25 14:25:23.235	  0:06.079	    Pool:0x7fffa406d700	DNS	SRV Lookup "_sip._udp.ekiga.net"
>2014/02/25 14:25:23.235	  0:06.079	Opal Liste...ffd8116700	OpalUDP	Binding to interface: 10.34.130.146:5060
>2014/02/25 14:25:23.235	  0:06.079	    Pool:0x7fffa406d700	SIP	Attempting SRV record entry 0: 86.64.162.35:5060
>2014/02/25 14:25:23.235	  0:06.079	    Pool:0x7fffa406d700	SIP	Transaction remote address is udp$86.64.162.35:5060
>2014/02/25 14:25:23.235	  0:06.079	Opal Liste...ffd8116700	SIP	Waiting for PDU on udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:23.235	  0:06.079	    Pool:0x7fffa406d700	SIP	Sending PDU (647 bytes) to: rem=udp$86.64.162.35:5060,local=udp$209.132.186.34:5060,if=10.34.130.146%em1
>SUBSCRIBE sip:500@ekiga.net SIP/2.0
>CSeq: 1 SUBSCRIBE
>Via: SIP/2.0/UDP 209.132.186.34:5060;branch=z9hG4bKdebbfef2-8d9c-e311-86d5-3c970e8ed11e;rport
>User-Agent: Ekiga/4.0.1
>From: <sip:desktopqe@ekiga.net>;tag=3611f7f2-8d9c-e311-86d5-3c970e8ed11e
>Call-ID: 5af2f6f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>Supported: eventlist
>To: <sip:500@ekiga.net>
>Accept: application/pidf+xml
>Accept: multipart/related
>Accept: application/rlmi+xml
>Contact: <sip:desktopqe@209.132.186.34>
>Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK
>Expires: 0
>Event: presence
>Content-Length: 0
>Max-Forwards: 70
>
>
>2014/02/25 14:25:23.236	  0:06.080	    Pool:0x7fffa406d700	OpalUDP	Setting interface to 10.34.130.146%em1
>2014/02/25 14:25:23.236	  0:06.080	Opal Liste...ffd8116700	SIP	PDU received: rem=udp$86.64.162.35:5060,local=udp$209.132.186.34:5060,if=10.34.130.146%em1
>SIP/2.0 489 Event Not Supported
>CSeq: 1 SUBSCRIBE
>Via: SIP/2.0/UDP 10.34.130.146:5060;branch=z9hG4bK1073f7f2-8d9c-e311-86d5-3c970e8ed11e;rport=5060
>Server: Kamailio (1.5.3-notls (i386/linux))
>From: <sip:desktopqe@ekiga.net>;tag=086cf7f2-8d9c-e311-86d5-3c970e8ed11e
>Call-ID: 0c4ff7f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>To: <sip:501@ekiga.net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.e785
>Content-Length: 0
>
>
>2014/02/25 14:25:23.236	  0:06.080	    Pool:0x7fffa406d700	SIP	Transaction timers set: retry=0.500, completion=6.000
>2014/02/25 14:25:23.236	  0:06.080	Opal Liste...ffd8116700	SIP	Queueing PDU "1 SUBSCRIBE <489>", transaction=z9hG4bK1073f7f2-8d9c-e311-86d5-3c970e8ed11e, token=0c4ff7f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:23.236	  0:06.080	Opal Liste...ffd8116700	PTLib	No permission to set priority level 3
>2014/02/25 14:25:23.236	  0:06.080	    Pool:0x7fffa406d700	SIP	SUBSCRIBE transaction id=z9hG4bK5c18f7f2-8d9c-e311-86d5-3c970e8ed11e completed.
>2014/02/25 14:25:23.237	  0:06.081	    Pool:0x7fffa406d700	SIP	Handled PDU "1 SUBSCRIBE <489>"
>2014/02/25 14:25:23.237	  0:06.081	Opal Liste...ffd8116700	Opal	Transport clean up on termination
>2014/02/25 14:25:23.237	  0:06.081	Opal Liste...ffd8116700	Opal	Transport Close
>2014/02/25 14:25:23.237	  0:06.081	Opal Liste...ffd8116700	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:23.237	  0:06.081	    Pool:0x7fff840d8700	SIP	Handling PDU "1 SUBSCRIBE <489>" for transaction=z9hG4bK1073f7f2-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:23.237	  0:06.081	    Pool:0x7fff840d8700	SIP	SUBSCRIBE transaction id=z9hG4bK1073f7f2-8d9c-e311-86d5-3c970e8ed11e completing.
>2014/02/25 14:25:23.237	  0:06.081	    Pool:0x7fff840d8700	SIPPres	'sip:desktopqe@ekiga.net' error subscribing to presence of 'sip:501@ekiga.net'
>2014/02/25 14:25:23.238	  0:06.082	    Pool:0x7fff840d8700	SIP	Expiry time for SUBSCRIBE set to 300 seconds.
>2014/02/25 14:25:23.238	  0:06.082	    Pool:0x7fff840d8700	SIP	Queueing state change to Unsubscribing for SUBSCRIBE handler while in Subscribing state, target=sip:501@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net, id=0c4ff7f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:23.238	  0:06.082	    Pool:0x7fff840d8700	SIP	Not retrying SUBSCRIBE due to error response 489 Bad Event
>2014/02/25 14:25:23.238	  0:06.082	    Pool:0x7fff840d8700	SIP	Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:501@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net, id=0c4ff7f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:23.238	  0:06.082	    Pool:0x7fff840d8700	SIP	Changing SUBSCRIBE handler from Unsubscribed to Unsubscribing, target=sip:501@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net, id=0c4ff7f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:23.238	  0:06.082	    Pool:0x7fff840d8700	OpalUDP	Writing to interface 0 - "10.34.130.146%em1"
>2014/02/25 14:25:23.238	  0:06.082	    Pool:0x7fff840d8700	SIP	Transaction created.
>2014/02/25 14:25:23.239	  0:06.083	    Pool:0x7fff840d8700	SIP	Transaction remembering interface "10.34.130.146%em1"
>2014/02/25 14:25:23.239	  0:06.083	    Pool:0x7fff840d8700	DNS	SRV Lookup "_sip._udp.ekiga.net"
>2014/02/25 14:25:23.239	  0:06.083	    Pool:0x7fff840d8700	SIP	Attempting SRV record entry 0: 86.64.162.35:5060
>2014/02/25 14:25:23.239	  0:06.083	    Pool:0x7fff840d8700	SIP	Transaction remote address is udp$86.64.162.35:5060
>2014/02/25 14:25:23.239	  0:06.084	    Pool:0x7fff840d8700	SIP	Sending PDU (647 bytes) to: rem=udp$86.64.162.35:5060,local=udp$209.132.186.34:5060,if=10.34.130.146%em1
>SUBSCRIBE sip:501@ekiga.net SIP/2.0
>CSeq: 1 SUBSCRIBE
>Via: SIP/2.0/UDP 209.132.186.34:5060;branch=z9hG4bK4e5afff2-8d9c-e311-86d5-3c970e8ed11e;rport
>User-Agent: Ekiga/4.0.1
>From: <sip:desktopqe@ekiga.net>;tag=086cf7f2-8d9c-e311-86d5-3c970e8ed11e
>Call-ID: 0c4ff7f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>Supported: eventlist
>To: <sip:501@ekiga.net>
>Accept: application/pidf+xml
>Accept: multipart/related
>Accept: application/rlmi+xml
>Contact: <sip:desktopqe@209.132.186.34>
>Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK
>Expires: 0
>Event: presence
>Content-Length: 0
>Max-Forwards: 70
>
>
>2014/02/25 14:25:23.240	  0:06.084	    Pool:0x7fff840d8700	OpalUDP	Setting interface to 10.34.130.146%em1
>2014/02/25 14:25:23.240	  0:06.084	    Pool:0x7fff840d8700	SIP	Transaction timers set: retry=0.500, completion=6.000
>2014/02/25 14:25:23.240	  0:06.084	    Pool:0x7fff840d8700	SIP	SUBSCRIBE transaction id=z9hG4bK1073f7f2-8d9c-e311-86d5-3c970e8ed11e completed.
>2014/02/25 14:25:23.240	  0:06.084	    Pool:0x7fff840d8700	SIP	Handled PDU "1 SUBSCRIBE <489>"
>2014/02/25 14:25:23.285	  0:06.129	Opal Liste...ffd8116700	OpalUDP	Binding to interface: 10.34.130.146:5060
>2014/02/25 14:25:23.286	  0:06.130	Opal Liste...ffd8116700	SIP	Waiting for PDU on udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:23.286	  0:06.130	Opal Liste...ffd8116700	SIP	PDU received: rem=udp$86.64.162.35:5060,local=udp$209.132.186.34:5060,if=10.34.130.146%em1
>SIP/2.0 489 Event Not Supported
>CSeq: 1 SUBSCRIBE
>Via: SIP/2.0/UDP 10.34.130.146:5060;branch=z9hG4bKdebbfef2-8d9c-e311-86d5-3c970e8ed11e;rport=5060
>Server: Kamailio (1.5.3-notls (i386/linux))
>From: <sip:desktopqe@ekiga.net>;tag=3611f7f2-8d9c-e311-86d5-3c970e8ed11e
>Call-ID: 5af2f6f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>To: <sip:500@ekiga.net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.a214
>Content-Length: 0
>
>
>2014/02/25 14:25:23.286	  0:06.130	Opal Liste...ffd8116700	SIP	Queueing PDU "1 SUBSCRIBE <489>", transaction=z9hG4bKdebbfef2-8d9c-e311-86d5-3c970e8ed11e, token=5af2f6f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:23.286	  0:06.130	Opal Liste...ffd8116700	Opal	Transport clean up on termination
>2014/02/25 14:25:23.286	  0:06.130	Opal Liste...ffd8116700	Opal	Transport Close
>2014/02/25 14:25:23.286	  0:06.130	    Pool:0x7fffa406d700	SIP	Handling PDU "1 SUBSCRIBE <489>" for transaction=z9hG4bKdebbfef2-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:23.286	  0:06.130	Opal Liste...ffd8116700	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:23.286	  0:06.130	    Pool:0x7fffa406d700	SIP	SUBSCRIBE transaction id=z9hG4bKdebbfef2-8d9c-e311-86d5-3c970e8ed11e completing.
>2014/02/25 14:25:23.286	  0:06.130	    Pool:0x7fffa406d700	SIP	Not retrying SUBSCRIBE due to error response 489 Bad Event
>2014/02/25 14:25:23.286	  0:06.130	    Pool:0x7fffa406d700	SIP	Changing SUBSCRIBE handler from Unsubscribing to Unsubscribed, target=sip:500@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net, id=5af2f6f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:23.286	  0:06.130	    Pool:0x7fffa406d700	SIP	SUBSCRIBE transaction id=z9hG4bKdebbfef2-8d9c-e311-86d5-3c970e8ed11e completed.
>2014/02/25 14:25:23.286	  0:06.130	    Pool:0x7fffa406d700	SIP	Handled PDU "1 SUBSCRIBE <489>"
>2014/02/25 14:25:23.289	  0:06.133	Opal Liste...ffd8116700	OpalUDP	Binding to interface: 10.34.130.146:5060
>2014/02/25 14:25:23.289	  0:06.133	Opal Liste...ffd8116700	SIP	Waiting for PDU on udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:23.289	  0:06.133	Opal Liste...ffd8116700	SIP	PDU received: rem=udp$86.64.162.35:5060,local=udp$209.132.186.34:5060,if=10.34.130.146%em1
>SIP/2.0 489 Event Not Supported
>CSeq: 1 SUBSCRIBE
>Via: SIP/2.0/UDP 10.34.130.146:5060;branch=z9hG4bK4e5afff2-8d9c-e311-86d5-3c970e8ed11e;rport=5060
>Server: Kamailio (1.5.3-notls (i386/linux))
>From: <sip:desktopqe@ekiga.net>;tag=086cf7f2-8d9c-e311-86d5-3c970e8ed11e
>Call-ID: 0c4ff7f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>To: <sip:501@ekiga.net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.14d4
>Content-Length: 0
>
>
>2014/02/25 14:25:23.290	  0:06.134	Opal Liste...ffd8116700	SIP	Queueing PDU "1 SUBSCRIBE <489>", transaction=z9hG4bK4e5afff2-8d9c-e311-86d5-3c970e8ed11e, token=0c4ff7f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:23.290	  0:06.134	Opal Liste...ffd8116700	Opal	Transport clean up on termination
>2014/02/25 14:25:23.290	  0:06.134	Opal Liste...ffd8116700	Opal	Transport Close
>2014/02/25 14:25:23.290	  0:06.134	    Pool:0x7fffa406d700	SIP	Handling PDU "1 SUBSCRIBE <489>" for transaction=z9hG4bK4e5afff2-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:23.290	  0:06.134	    Pool:0x7fffa406d700	SIP	SUBSCRIBE transaction id=z9hG4bK4e5afff2-8d9c-e311-86d5-3c970e8ed11e completing.
>2014/02/25 14:25:23.290	  0:06.134	Opal Liste...ffd8116700	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:23.290	  0:06.134	    Pool:0x7fffa406d700	SIP	Not retrying SUBSCRIBE due to error response 489 Bad Event
>2014/02/25 14:25:23.290	  0:06.134	    Pool:0x7fffa406d700	SIP	Changing SUBSCRIBE handler from Unsubscribing to Unsubscribed, target=sip:501@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net, id=0c4ff7f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:23.290	  0:06.134	    Pool:0x7fffa406d700	SIP	SUBSCRIBE transaction id=z9hG4bK4e5afff2-8d9c-e311-86d5-3c970e8ed11e completed.
>2014/02/25 14:25:23.290	  0:06.134	    Pool:0x7fffa406d700	SIP	Handled PDU "1 SUBSCRIBE <489>"
>2014/02/25 14:25:23.328	  0:06.172	Opal Garba...fff7e93700	Opal	Transport clean up on termination
>2014/02/25 14:25:23.328	  0:06.172	Opal Garba...fff7e93700	Opal	Transport Close
>2014/02/25 14:25:23.328	  0:06.172	Opal Garba...fff7e93700	Opal	Transport clean up on termination
>2014/02/25 14:25:23.328	  0:06.172	Opal Garba...fff7e93700	Opal	Transport Close
>2014/02/25 14:25:23.328	  0:06.172	Opal Garba...fff7e93700	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$*:5060>
>2014/02/25 14:25:23.328	  0:06.172	Opal Garba...fff7e93700	SIP	Destroyed SUBSCRIBE handler for sip:desktopqe@ekiga.net
>2014/02/25 14:25:23.328	  0:06.172	Opal Garba...fff7e93700	Opal	Transport clean up on termination
>2014/02/25 14:25:23.328	  0:06.172	Opal Garba...fff7e93700	Opal	Transport Close
>2014/02/25 14:25:23.328	  0:06.172	Opal Garba...fff7e93700	Opal	Transport clean up on termination
>2014/02/25 14:25:23.328	  0:06.172	Opal Garba...fff7e93700	Opal	Transport Close
>2014/02/25 14:25:23.328	  0:06.172	Opal Garba...fff7e93700	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$*:5060>
>2014/02/25 14:25:23.328	  0:06.172	Opal Garba...fff7e93700	SIP	Destroyed SUBSCRIBE handler for sip:500@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net
>2014/02/25 14:25:23.328	  0:06.172	Opal Garba...fff7e93700	Opal	Transport clean up on termination
>2014/02/25 14:25:23.328	  0:06.172	Opal Garba...fff7e93700	Opal	Transport Close
>2014/02/25 14:25:23.328	  0:06.172	Opal Garba...fff7e93700	Opal	Transport clean up on termination
>2014/02/25 14:25:23.328	  0:06.172	Opal Garba...fff7e93700	Opal	Transport Close
>2014/02/25 14:25:23.328	  0:06.172	Opal Garba...fff7e93700	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$*:5060>
>2014/02/25 14:25:23.329	  0:06.173	Opal Garba...fff7e93700	SIP	Destroyed SUBSCRIBE handler for sip:501@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net
>2014/02/25 14:25:23.445	  0:06.289	GkMonitor:...ffa40ef700	RAS	Time To Live reregistration
>2014/02/25 14:25:23.445	  0:06.289	GkMonitor:...ffa40ef700	OpalUDP	Writing to interface 0 - "10.34.130.146%em1"
>2014/02/25 14:25:23.446	  0:06.290	GkMonitor:...ffa40ef700	OpalMan	Listener interfaces: associated transport=udp$209.132.186.34:5061
>    tcp$209.132.186.34:1720,tcp$10.34.130.146:1720
>2014/02/25 14:25:23.446	  0:06.290	GkMonitor:...ffa40ef700	Trans	Sending PDU:
>  gatekeeperRequest {
>    requestSeqNum = 52308
>    protocolIdentifier = 0.0.8.2250.0.6
>    rasAddress = ipAddress {
>      ip =  4 octets {
>        d1 84 ba 22                                        ..."
>      }
>      port = 5061
>    }
>    endpointType = {
>      vendor = {
>        vendor = {
>          t35CountryCode = 9
>          t35Extension = 0
>          manufacturerCode = 61
>        }
>        productId =  3 octets {
>          00 00 00                                           ...
>        }
>        versionId =  35 octets {
>          34 2e 30 2e 31 20 28 4f  50 41 4c 20 76 33 2e 31   4.0.1 (OPAL v3.1
>          30 2e 31 30 20 28 73 76  6e 3a 32 39 31 35 30 29   0.10 (svn:29150)
>          ...
>        }
>      }
>      terminal = {
>      }
>      mc = false
>      undefinedNode = false
>    }
>    endpointAlias = 2 entries {
>      [0]=h323_ID  9 characters {
>        0064 0065 0073 006b 0074 006f 0070 0071   desktopq
>        0065                                      e
>      }
>      [1]=h323_ID  12 characters {
>        004d 0061 0072 0074 0069 006e 0020 0053   Martin S
>        0069 006d 006f 006e                       imon
>      }
>    }
>    authenticationCapability = 2 entries {
>      [0]=pwdHash <<null>>
>      [1]=authenticationBES radius <<null>>
>    }
>    algorithmOIDs = 3 entries {
>      [0]=0.0.8.235.0.2.6
>      [1]=1.2.840.113548.10.1.2.1
>      [2]=1.2.840.113549.2.5
>    }
>    supportsAltGK = <<null>>
>  }
>2014/02/25 14:25:23.446	  0:06.290	GkMonitor:...ffa40ef700	OpalUDP	Setting interface to 
>2014/02/25 14:25:23.446	  0:06.290	GkMonitor:...ffa40ef700	Trans	Waiting on response to seqnum=52308 for 5.0 seconds
>2014/02/25 14:25:23.628	  0:06.472	Transactor...ffa40ae700	H225RAS	Receiving PDU:
>  gatekeeperConfirm {
>    requestSeqNum = 52308
>    protocolIdentifier = 0.0.8.2250.0.6
>    gatekeeperIdentifier =  9 characters {
>      0068 0033 0032 0033 006e 0065 0074 005f   h323net_
>      0034                                      4
>    }
>    rasAddress = ipAddress {
>      ip =  4 octets {
>        17 17 98 ca                                        ....
>      }
>      port = 1719
>    }
>    alternateGatekeeper = 2 entries {
>      [0]={
>        rasAddress = ipAddress {
>          ip =  4 octets {
>            6b 15 e7 2e                                        k...
>          }
>          port = 20075
>        }
>        needToRegister = true
>        priority = 0
>      }
>      [1]={
>        rasAddress = ipAddress {
>          ip =  4 octets {
>            36 e1 ef 65                                        6..e
>          }
>          port = 20075
>        }
>        needToRegister = true
>        priority = 1
>      }
>    }
>    authenticationMode = pwdHash <<null>>
>    algorithmOID = 1.2.840.113549.2.5
>  }
>2014/02/25 14:25:23.628	  0:06.472	Transactor...ffa40ae700	OpalUDP	Setting interface to 10.34.130.146%em1
>2014/02/25 14:25:23.628	  0:06.472	Transactor...ffa40ae700	RAS	Gatekeeper discovered at: udp$23.23.152.202:1719 (if=udp$209.132.186.34:5061)
>2014/02/25 14:25:23.628	  0:06.472	Transactor...ffa40ae700	RAS	Set alternate gatekeepers:
>ip$107.21.231.46:20075
>ip$54.225.239.101:20075;priority=1
>
>2014/02/25 14:25:23.672	  0:06.516	GkMonitor:...ffa40ef700	OpalMan	Listener interfaces: associated transport=udp$209.132.186.34:5061
>    tcp$209.132.186.34:1720,tcp$10.34.130.146:1720
>2014/02/25 14:25:23.672	  0:06.516	GkMonitor:...ffa40ef700	Trans	Making request: registrationRequest
>2014/02/25 14:25:23.672	  0:06.516	GkMonitor:...ffa40ef700	H235RAS	Prepared PDU with authenticator MD5<active>
>2014/02/25 14:25:23.672	  0:06.516	GkMonitor:...ffa40ef700	Trans	Sending PDU:
>  registrationRequest {
>    requestSeqNum = 52309
>    protocolIdentifier = 0.0.8.2250.0.6
>    discoveryComplete = true
>    callSignalAddress = 2 entries {
>      [0]=ipAddress {
>        ip =  4 octets {
>          d1 84 ba 22                                        ..."
>        }
>        port = 1720
>      }
>      [1]=ipAddress {
>        ip =  4 octets {
>          0a 22 82 92                                        ."..
>        }
>        port = 1720
>      }
>    }
>    rasAddress = 1 entries {
>      [0]=ipAddress {
>        ip =  4 octets {
>          d1 84 ba 22                                        ..."
>        }
>        port = 5061
>      }
>    }
>    terminalType = {
>      vendor = {
>        vendor = {
>          t35CountryCode = 9
>          t35Extension = 0
>          manufacturerCode = 61
>        }
>        productId =  3 octets {
>          00 00 00                                           ...
>        }
>        versionId =  35 octets {
>          34 2e 30 2e 31 20 28 4f  50 41 4c 20 76 33 2e 31   4.0.1 (OPAL v3.1
>          30 2e 31 30 20 28 73 76  6e 3a 32 39 31 35 30 29   0.10 (svn:29150)
>          ...
>        }
>      }
>      terminal = {
>      }
>      mc = false
>      undefinedNode = false
>    }
>    terminalAlias = 2 entries {
>      [0]=h323_ID  9 characters {
>        0064 0065 0073 006b 0074 006f 0070 0071   desktopq
>        0065                                      e
>      }
>      [1]=h323_ID  12 characters {
>        004d 0061 0072 0074 0069 006e 0020 0053   Martin S
>        0069 006d 006f 006e                       imon
>      }
>    }
>    gatekeeperIdentifier =  9 characters {
>      0068 0033 0032 0033 006e 0065 0074 005f   h323net_
>      0034                                      4
>    }
>    endpointVendor = {
>      vendor = {
>        t35CountryCode = 9
>        t35Extension = 0
>        manufacturerCode = 61
>      }
>      productId =  3 octets {
>        00 00 00                                           ...
>      }
>      versionId =  35 octets {
>        34 2e 30 2e 31 20 28 4f  50 41 4c 20 76 33 2e 31   4.0.1 (OPAL v3.1
>        30 2e 31 30 20 28 73 76  6e 3a 32 39 31 35 30 29   0.10 (svn:29150)
>        ...
>      }
>    }
>    timeToLive = 3600
>    cryptoTokens = 1 entries {
>      [0]=cryptoEPPwdHash {
>        alias = h323_ID  9 characters {
>          0064 0065 0073 006b 0074 006f 0070 0071   desktopq
>          0065                                      e
>        }
>        timeStamp = 1393334723
>        token = {
>          algorithmOID = 1.2.840.113549.2.5
>          paramS = {
>          }
>          hash = Hex:  34 d1 16 d8 bf af 9a 28  e2 fb ba 47 32 df 78 bb 
>        }
>      }
>    }
>    keepAlive = false
>    willSupplyUUIEs = true
>    maintainConnection = false
>    supportsAltGK = <<null>>
>    usageReportingCapability = {
>      nonStandardUsageTypes = 0 entries {
>      }
>      startTime = <<null>>
>      endTime = <<null>>
>      terminationCause = <<null>>
>    }
>    callCreditCapability = {
>      canEnforceDurationLimit = true
>    }
>  }
>2014/02/25 14:25:23.672	  0:06.516	GkMonitor:...ffa40ef700	Trans	Waiting on response to seqnum=52309 for 3.0 seconds
>2014/02/25 14:25:23.875	  0:06.719	Transactor...ffa40ae700	H225RAS	Receiving PDU:
>  registrationConfirm {
>    requestSeqNum = 52309
>    protocolIdentifier = 0.0.8.2250.0.6
>    nonStandardData = {
>      nonStandardIdentifier = h221NonStandard {
>        t35CountryCode = 138
>        t35Extension = 2
>        manufacturerCode = 2
>      }
>      data =  5 octets {
>        4e 6f 4e 41 54                                     NoNAT
>      }
>    }
>    callSignalAddress = 1 entries {
>      [0]=ipAddress {
>        ip =  4 octets {
>          17 17 98 ca                                        ....
>        }
>        port = 1720
>      }
>    }
>    terminalAlias = 4 entries {
>      [0]=url_ID "desktopqe@spranto.net"
>      [1]=dialedDigits "1081333021"
>      [2]=dialedDigits "878401081333021"
>      [3]=h323_ID  9 characters {
>        0064 0065 0073 006b 0074 006f 0070 0071   desktopq
>        0065                                      e
>      }
>    }
>    gatekeeperIdentifier =  9 characters {
>      0068 0033 0032 0033 006e 0065 0074 005f   h323net_
>      0034                                      4
>    }
>    endpointIdentifier =  9 characters {
>      0035 0031 0030 0035 005f 0065 006e 0064   5105_end
>      0070                                      p
>    }
>    alternateGatekeeper = 2 entries {
>      [0]={
>        rasAddress = ipAddress {
>          ip =  4 octets {
>            6b 15 e7 2e                                        k...
>          }
>          port = 20075
>        }
>        needToRegister = true
>        priority = 0
>      }
>      [1]={
>        rasAddress = ipAddress {
>          ip =  4 octets {
>            36 e1 ef 65                                        6..e
>          }
>          port = 20075
>        }
>        needToRegister = true
>        priority = 1
>      }
>    }
>    timeToLive = 30
>    willRespondToIRR = false
>    maintainConnection = false
>    serviceControl = 1 entries {
>      [0]={
>        sessionId = 0
>        contents = callCreditServiceControl {
>          callStartingPoint = connect <<null>>
>        }
>        reason = open <<null>>
>      }
>    }
>    supportsAdditiveRegistration = <<null>>
>  }
>2014/02/25 14:25:23.875	  0:06.719	Transactor...ffa40ae700	Trans	Using credentials from request: H235AnnexD_Procedure1<disabled>,CAT<disabled>,MD5<active>
>2014/02/25 14:25:23.875	  0:06.719	Transactor...ffa40ae700	RAS	Registered 5105_endp with h323net_4
>2014/02/25 14:25:23.875	  0:06.719	Transactor...ffa40ae700	RAS	Set alternate gatekeepers:
>ip$54.225.239.101:20075;priority=1
>ip$107.21.231.46:20075
>
>2014/02/25 14:25:23.875	  0:06.719	Transactor...ffa40ae700	RAS	Gatekeeper add of alias "desktopqe@spranto.net"
>2014/02/25 14:25:23.875	  0:06.719	Transactor...ffa40ae700	RAS	Gatekeeper add of alias "1081333021"
>2014/02/25 14:25:23.875	  0:06.719	Transactor...ffa40ae700	RAS	Gatekeeper add of alias "878401081333021"
>2014/02/25 14:25:23.875	  0:06.719	Transactor...ffa40ae700	RAS	Gatekeeper removal of alias "Martin Simon"
>2014/02/25 14:25:23.875	  0:06.719	Transactor...ffa40ae700	SvcCtrl	OnChange Call Credit service control  debit 0
>2014/02/25 14:25:24.097	  0:06.941	Housekeepe...fff7f97700	SIP	Set state Terminated_Success for REGISTER transaction id=z9hG4bK1431d1f2-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:24.097	  0:06.941	Housekeepe...fff7f97700	SIP	Completed state for transaction id=z9hG4bK1431d1f2-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:24.097	  0:06.941	Housekeepe...fff7f97700	SIP	Set state Terminated_Success for PUBLISH transaction id=z9hG4bK5a2fe2f2-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:24.097	  0:06.941	Housekeepe...fff7f97700	SIP	Completed state for transaction id=z9hG4bK5a2fe2f2-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:24.162	  0:07.006	Housekeepe...fff7f97700	SIP	Set state Terminated_Success for REGISTER transaction id=z9hG4bK1284e9f2-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:24.162	  0:07.006	Housekeepe...fff7f97700	SIP	Completed state for transaction id=z9hG4bK1284e9f2-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:24.188	  0:07.032	PThreadObj...ffd006f700	SIPPres	'sip:desktopqe@ekiga.net' subscribing to presence of 'sip:520@ekiga.net'
>2014/02/25 14:25:24.188	  0:07.032	PThreadObj...ffd006f700	SIP	Start SUBSCRIBE
> eventPackage=presence
>          aor=sip:520@ekiga.net
>       remote=
>        local=sip:desktopqe@ekiga.net
>      contact=
>        proxy=
>       authID=desktopqe
>        realm=
>       expire=300
>      restore=30
>     minRetry=default
>     maxRetry=default
>2014/02/25 14:25:24.188	  0:07.032	PThreadObj...ffd006f700	SIP	Constructed SUBSCRIBE handler for sip:520@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net
>2014/02/25 14:25:24.188	  0:07.032	PThreadObj...ffd006f700	SIP	Executing state change to Subscribing for SUBSCRIBE handler, target=sip:520@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net, id=de4c90f3-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:24.189	  0:07.033	PThreadObj...ffd006f700	SIP	Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:520@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net, id=de4c90f3-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:24.189	  0:07.033	PThreadObj...ffd006f700	DNS	SRV Lookup "_sip._udp.ekiga.net"
>2014/02/25 14:25:24.189	  0:07.033	PThreadObj...ffd006f700	SIP	Attempting SRV record entry 0: 86.64.162.35:5060
>2014/02/25 14:25:24.189	  0:07.033	PThreadObj...ffd006f700	OpalUDP	Binding to interface: 0.0.0.0:5060
>2014/02/25 14:25:24.189	  0:07.033	PThreadObj...ffd006f700	SIP	Created transport udp$86.64.162.35:5060<if=udp$*:5060>
>2014/02/25 14:25:24.189	  0:07.033	PThreadObj...ffd006f700	OpalUDP	Started connect to 86.64.162.35:5060
>2014/02/25 14:25:24.189	  0:07.033	PThreadObj...ffd006f700	OpalUDP	Writing to interface 0 - "10.34.130.146%em1"
>2014/02/25 14:25:24.189	  0:07.033	PThreadObj...ffd006f700	SIP	Transaction created.
>2014/02/25 14:25:24.190	  0:07.034	PThreadObj...ffd006f700	SIP	Transaction remembering interface "10.34.130.146%em1"
>2014/02/25 14:25:24.190	  0:07.034	PThreadObj...ffd006f700	DNS	SRV Lookup "_sip._udp.ekiga.net"
>2014/02/25 14:25:24.190	  0:07.034	PThreadObj...ffd006f700	SIP	Attempting SRV record entry 0: 86.64.162.35:5060
>2014/02/25 14:25:24.190	  0:07.034	PThreadObj...ffd006f700	SIP	Transaction remote address is udp$86.64.162.35:5060
>2014/02/25 14:25:24.190	  0:07.034	PThreadObj...ffd006f700	SIP	Sending PDU (649 bytes) to: rem=udp$86.64.162.35:5060,local=udp$209.132.186.34:5060,if=10.34.130.146%em1
>SUBSCRIBE sip:520@ekiga.net SIP/2.0
>CSeq: 1 SUBSCRIBE
>Via: SIP/2.0/UDP 209.132.186.34:5060;branch=z9hG4bKa47290f3-8d9c-e311-86d5-3c970e8ed11e;rport
>User-Agent: Ekiga/4.0.1
>From: <sip:desktopqe@ekiga.net>;tag=186d90f3-8d9c-e311-86d5-3c970e8ed11e
>Call-ID: de4c90f3-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>Supported: eventlist
>To: <sip:520@ekiga.net>
>Accept: application/pidf+xml
>Accept: multipart/related
>Accept: application/rlmi+xml
>Contact: <sip:desktopqe@209.132.186.34>
>Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK
>Expires: 300
>Event: presence
>Content-Length: 0
>Max-Forwards: 70
>
>
>2014/02/25 14:25:24.191	  0:07.035	PThreadObj...ffd006f700	OpalUDP	Setting interface to 10.34.130.146%em1
>2014/02/25 14:25:24.191	  0:07.035	PThreadObj...ffd006f700	SIP	Transaction timers set: retry=0.500, completion=6.000
>2014/02/25 14:25:24.233	  0:07.077	Housekeepe...fff7f97700	SIP	Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bKc216f7f2-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:24.233	  0:07.077	Housekeepe...fff7f97700	SIP	Completed state for transaction id=z9hG4bKc216f7f2-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:24.241	  0:07.085	Opal Liste...ffd8116700	OpalUDP	Binding to interface: 10.34.130.146:5060
>2014/02/25 14:25:24.241	  0:07.085	Opal Liste...ffd8116700	SIP	Waiting for PDU on udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:24.241	  0:07.085	Opal Liste...ffd8116700	SIP	PDU received: rem=udp$86.64.162.35:5060,local=udp$209.132.186.34:5060,if=10.34.130.146%em1
>SIP/2.0 489 Event Not Supported
>CSeq: 1 SUBSCRIBE
>Via: SIP/2.0/UDP 10.34.130.146:5060;branch=z9hG4bKa47290f3-8d9c-e311-86d5-3c970e8ed11e;rport=5060
>Server: Kamailio (1.5.3-notls (i386/linux))
>From: <sip:desktopqe@ekiga.net>;tag=186d90f3-8d9c-e311-86d5-3c970e8ed11e
>Call-ID: de4c90f3-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>To: <sip:520@ekiga.net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.07bd
>Content-Length: 0
>
>
>2014/02/25 14:25:24.241	  0:07.085	Opal Liste...ffd8116700	SIP	Queueing PDU "1 SUBSCRIBE <489>", transaction=z9hG4bKa47290f3-8d9c-e311-86d5-3c970e8ed11e, token=de4c90f3-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:24.241	  0:07.085	Opal Liste...ffd8116700	Opal	Transport clean up on termination
>2014/02/25 14:25:24.241	  0:07.085	Opal Liste...ffd8116700	Opal	Transport Close
>2014/02/25 14:25:24.241	  0:07.085	    Pool:0x7fffa406d700	SIP	Handling PDU "1 SUBSCRIBE <489>" for transaction=z9hG4bKa47290f3-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:24.241	  0:07.085	    Pool:0x7fffa406d700	SIP	SUBSCRIBE transaction id=z9hG4bKa47290f3-8d9c-e311-86d5-3c970e8ed11e completing.
>2014/02/25 14:25:24.241	  0:07.085	Housekeepe...fff7f97700	SIP	Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bK5c18f7f2-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:24.241	  0:07.085	Opal Liste...ffd8116700	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:24.241	  0:07.085	Housekeepe...fff7f97700	SIP	Completed state for transaction id=z9hG4bK5c18f7f2-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:24.241	  0:07.085	    Pool:0x7fffa406d700	SIPPres	'sip:desktopqe@ekiga.net' error subscribing to presence of 'sip:520@ekiga.net'
>2014/02/25 14:25:24.241	  0:07.085	Housekeepe...fff7f97700	SIP	Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bK1073f7f2-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:24.241	  0:07.085	Housekeepe...fff7f97700	SIP	Completed state for transaction id=z9hG4bK1073f7f2-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:24.242	  0:07.086	    Pool:0x7fffa406d700	SIP	Expiry time for SUBSCRIBE set to 300 seconds.
>2014/02/25 14:25:24.242	  0:07.086	    Pool:0x7fffa406d700	SIP	Queueing state change to Unsubscribing for SUBSCRIBE handler while in Subscribing state, target=sip:520@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net, id=de4c90f3-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:24.242	  0:07.086	    Pool:0x7fffa406d700	SIP	Not retrying SUBSCRIBE due to error response 489 Bad Event
>2014/02/25 14:25:24.242	  0:07.086	    Pool:0x7fffa406d700	SIP	Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:520@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net, id=de4c90f3-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:24.242	  0:07.086	    Pool:0x7fffa406d700	SIP	Changing SUBSCRIBE handler from Unsubscribed to Unsubscribing, target=sip:520@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net, id=de4c90f3-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:24.242	  0:07.086	    Pool:0x7fffa406d700	OpalUDP	Writing to interface 0 - "10.34.130.146%em1"
>2014/02/25 14:25:24.242	  0:07.086	    Pool:0x7fffa406d700	SIP	Transaction created.
>2014/02/25 14:25:24.242	  0:07.086	    Pool:0x7fffa406d700	SIP	Transaction remembering interface "10.34.130.146%em1"
>2014/02/25 14:25:24.242	  0:07.087	    Pool:0x7fffa406d700	DNS	SRV Lookup "_sip._udp.ekiga.net"
>2014/02/25 14:25:24.243	  0:07.087	    Pool:0x7fffa406d700	SIP	Attempting SRV record entry 0: 86.64.162.35:5060
>2014/02/25 14:25:24.243	  0:07.087	    Pool:0x7fffa406d700	SIP	Transaction remote address is udp$86.64.162.35:5060
>2014/02/25 14:25:24.243	  0:07.087	    Pool:0x7fffa406d700	SIP	Sending PDU (647 bytes) to: rem=udp$86.64.162.35:5060,local=udp$209.132.186.34:5060,if=10.34.130.146%em1
>SUBSCRIBE sip:520@ekiga.net SIP/2.0
>CSeq: 1 SUBSCRIBE
>Via: SIP/2.0/UDP 209.132.186.34:5060;branch=z9hG4bK687998f3-8d9c-e311-86d5-3c970e8ed11e;rport
>User-Agent: Ekiga/4.0.1
>From: <sip:desktopqe@ekiga.net>;tag=186d90f3-8d9c-e311-86d5-3c970e8ed11e
>Call-ID: de4c90f3-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>Supported: eventlist
>To: <sip:520@ekiga.net>
>Accept: application/pidf+xml
>Accept: multipart/related
>Accept: application/rlmi+xml
>Contact: <sip:desktopqe@209.132.186.34>
>Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK
>Expires: 0
>Event: presence
>Content-Length: 0
>Max-Forwards: 70
>
>
>2014/02/25 14:25:24.243	  0:07.087	    Pool:0x7fffa406d700	OpalUDP	Setting interface to 10.34.130.146%em1
>2014/02/25 14:25:24.243	  0:07.087	    Pool:0x7fffa406d700	SIP	Transaction timers set: retry=0.500, completion=6.000
>2014/02/25 14:25:24.243	  0:07.087	    Pool:0x7fffa406d700	SIP	SUBSCRIBE transaction id=z9hG4bKa47290f3-8d9c-e311-86d5-3c970e8ed11e completed.
>2014/02/25 14:25:24.243	  0:07.087	    Pool:0x7fffa406d700	SIP	Handled PDU "1 SUBSCRIBE <489>"
>2014/02/25 14:25:24.286	  0:07.130	Housekeepe...fff7f97700	SIP	Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bKdebbfef2-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:24.286	  0:07.130	Housekeepe...fff7f97700	SIP	Completed state for transaction id=z9hG4bKdebbfef2-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:24.294	  0:07.138	Opal Liste...ffd8116700	OpalUDP	Binding to interface: 10.34.130.146:5060
>2014/02/25 14:25:24.294	  0:07.138	Opal Liste...ffd8116700	SIP	Waiting for PDU on udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:24.294	  0:07.138	Opal Liste...ffd8116700	SIP	PDU received: rem=udp$86.64.162.35:5060,local=udp$209.132.186.34:5060,if=10.34.130.146%em1
>SIP/2.0 489 Event Not Supported
>CSeq: 1 SUBSCRIBE
>Via: SIP/2.0/UDP 10.34.130.146:5060;branch=z9hG4bK687998f3-8d9c-e311-86d5-3c970e8ed11e;rport=5060
>Server: Kamailio (1.5.3-notls (i386/linux))
>From: <sip:desktopqe@ekiga.net>;tag=186d90f3-8d9c-e311-86d5-3c970e8ed11e
>Call-ID: de4c90f3-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>To: <sip:520@ekiga.net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.bc4c
>Content-Length: 0
>
>
>2014/02/25 14:25:24.294	  0:07.138	Opal Liste...ffd8116700	SIP	Queueing PDU "1 SUBSCRIBE <489>", transaction=z9hG4bK687998f3-8d9c-e311-86d5-3c970e8ed11e, token=de4c90f3-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:24.294	  0:07.138	Opal Liste...ffd8116700	Opal	Transport clean up on termination
>2014/02/25 14:25:24.294	  0:07.138	Opal Liste...ffd8116700	Opal	Transport Close
>2014/02/25 14:25:24.294	  0:07.138	    Pool:0x7fffa406d700	SIP	Handling PDU "1 SUBSCRIBE <489>" for transaction=z9hG4bK687998f3-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:24.294	  0:07.138	    Pool:0x7fffa406d700	SIP	SUBSCRIBE transaction id=z9hG4bK687998f3-8d9c-e311-86d5-3c970e8ed11e completing.
>2014/02/25 14:25:24.294	  0:07.138	Housekeepe...fff7f97700	SIP	Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bK4e5afff2-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:24.294	  0:07.138	Housekeepe...fff7f97700	SIP	Completed state for transaction id=z9hG4bK4e5afff2-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:24.294	  0:07.138	Opal Liste...ffd8116700	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:24.295	  0:07.139	    Pool:0x7fffa406d700	SIP	Not retrying SUBSCRIBE due to error response 489 Bad Event
>2014/02/25 14:25:24.295	  0:07.139	    Pool:0x7fffa406d700	SIP	Changing SUBSCRIBE handler from Unsubscribing to Unsubscribed, target=sip:520@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net, id=de4c90f3-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:24.295	  0:07.139	    Pool:0x7fffa406d700	SIP	SUBSCRIBE transaction id=z9hG4bK687998f3-8d9c-e311-86d5-3c970e8ed11e completed.
>2014/02/25 14:25:24.295	  0:07.139	    Pool:0x7fffa406d700	SIP	Handled PDU "1 SUBSCRIBE <489>"
>2014/02/25 14:25:24.329	  0:07.173	Opal Garba...fff7e93700	SIP	Transaction id=z9hG4bK4e5afff2-8d9c-e311-86d5-3c970e8ed11e destroyed.
>2014/02/25 14:25:24.329	  0:07.173	Opal Garba...fff7e93700	SIP	Transaction id=z9hG4bK5a2fe2f2-8d9c-e311-86d5-3c970e8ed11e destroyed.
>2014/02/25 14:25:24.329	  0:07.173	Opal Garba...fff7e93700	SIP	Transaction id=z9hG4bK5c18f7f2-8d9c-e311-86d5-3c970e8ed11e destroyed.
>2014/02/25 14:25:24.329	  0:07.174	Opal Garba...fff7e93700	SIP	Transaction id=z9hG4bK1431d1f2-8d9c-e311-86d5-3c970e8ed11e destroyed.
>2014/02/25 14:25:24.330	  0:07.174	Opal Garba...fff7e93700	SIP	Transaction id=z9hG4bK1284e9f2-8d9c-e311-86d5-3c970e8ed11e destroyed.
>2014/02/25 14:25:24.330	  0:07.174	Opal Garba...fff7e93700	SIP	Transaction id=z9hG4bK1073f7f2-8d9c-e311-86d5-3c970e8ed11e destroyed.
>2014/02/25 14:25:24.330	  0:07.174	Opal Garba...fff7e93700	SIP	Transaction id=z9hG4bKdebbfef2-8d9c-e311-86d5-3c970e8ed11e destroyed.
>2014/02/25 14:25:24.330	  0:07.174	Opal Garba...fff7e93700	SIP	Transaction id=z9hG4bKc216f7f2-8d9c-e311-86d5-3c970e8ed11e destroyed.
>2014/02/25 14:25:24.330	  0:07.174	Opal Garba...fff7e93700	Opal	Transport clean up on termination
>2014/02/25 14:25:24.330	  0:07.174	Opal Garba...fff7e93700	Opal	Transport Close
>2014/02/25 14:25:24.330	  0:07.174	Opal Garba...fff7e93700	Opal	Transport clean up on termination
>2014/02/25 14:25:24.330	  0:07.174	Opal Garba...fff7e93700	Opal	Transport Close
>2014/02/25 14:25:24.330	  0:07.174	Opal Garba...fff7e93700	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$*:5060>
>2014/02/25 14:25:24.330	  0:07.174	Opal Garba...fff7e93700	SIP	Destroyed SUBSCRIBE handler for sip:520@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net
>2014/02/25 14:25:25.191	  0:08.035	PThreadObj...ffd006f700	SIPPres	'sip:desktopqe@ekiga.net' subscribing to presence of 'sip:qedesktop@ekiga.net'
>2014/02/25 14:25:25.191	  0:08.035	PThreadObj...ffd006f700	SIP	Start SUBSCRIBE
> eventPackage=presence
>          aor=sip:qedesktop@ekiga.net
>       remote=
>        local=sip:desktopqe@ekiga.net
>      contact=
>        proxy=
>       authID=desktopqe
>        realm=
>       expire=300
>      restore=30
>     minRetry=default
>     maxRetry=default
>2014/02/25 14:25:25.191	  0:08.035	PThreadObj...ffd006f700	SIP	Constructed SUBSCRIBE handler for sip:qedesktop@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net
>2014/02/25 14:25:25.191	  0:08.035	PThreadObj...ffd006f700	SIP	Executing state change to Subscribing for SUBSCRIBE handler, target=sip:qedesktop@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net, id=e45729f4-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:25.192	  0:08.036	PThreadObj...ffd006f700	SIP	Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:qedesktop@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net, id=e45729f4-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:25.192	  0:08.036	PThreadObj...ffd006f700	DNS	SRV Lookup "_sip._udp.ekiga.net"
>2014/02/25 14:25:25.192	  0:08.036	PThreadObj...ffd006f700	SIP	Attempting SRV record entry 0: 86.64.162.35:5060
>2014/02/25 14:25:25.192	  0:08.036	PThreadObj...ffd006f700	OpalUDP	Binding to interface: 0.0.0.0:5060
>2014/02/25 14:25:25.192	  0:08.036	PThreadObj...ffd006f700	SIP	Created transport udp$86.64.162.35:5060<if=udp$*:5060>
>2014/02/25 14:25:25.192	  0:08.036	PThreadObj...ffd006f700	OpalUDP	Started connect to 86.64.162.35:5060
>2014/02/25 14:25:25.192	  0:08.036	PThreadObj...ffd006f700	OpalUDP	Writing to interface 0 - "10.34.130.146%em1"
>2014/02/25 14:25:25.192	  0:08.036	PThreadObj...ffd006f700	SIP	Transaction created.
>2014/02/25 14:25:25.193	  0:08.037	PThreadObj...ffd006f700	SIP	Transaction remembering interface "10.34.130.146%em1"
>2014/02/25 14:25:25.193	  0:08.037	PThreadObj...ffd006f700	DNS	SRV Lookup "_sip._udp.ekiga.net"
>2014/02/25 14:25:25.193	  0:08.037	PThreadObj...ffd006f700	SIP	Attempting SRV record entry 0: 86.64.162.35:5060
>2014/02/25 14:25:25.193	  0:08.037	PThreadObj...ffd006f700	SIP	Transaction remote address is udp$86.64.162.35:5060
>2014/02/25 14:25:25.193	  0:08.037	PThreadObj...ffd006f700	SIP	Sending PDU (661 bytes) to: rem=udp$86.64.162.35:5060,local=udp$209.132.186.34:5060,if=10.34.130.146%em1
>SUBSCRIBE sip:qedesktop@ekiga.net SIP/2.0
>CSeq: 1 SUBSCRIBE
>Via: SIP/2.0/UDP 209.132.186.34:5060;branch=z9hG4bKdc7829f4-8d9c-e311-86d5-3c970e8ed11e;rport
>User-Agent: Ekiga/4.0.1
>From: <sip:desktopqe@ekiga.net>;tag=647329f4-8d9c-e311-86d5-3c970e8ed11e
>Call-ID: e45729f4-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>Supported: eventlist
>To: <sip:qedesktop@ekiga.net>
>Accept: application/pidf+xml
>Accept: multipart/related
>Accept: application/rlmi+xml
>Contact: <sip:desktopqe@209.132.186.34>
>Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK
>Expires: 300
>Event: presence
>Content-Length: 0
>Max-Forwards: 70
>
>
>2014/02/25 14:25:25.193	  0:08.037	PThreadObj...ffd006f700	OpalUDP	Setting interface to 10.34.130.146%em1
>2014/02/25 14:25:25.193	  0:08.037	PThreadObj...ffd006f700	SIP	Transaction timers set: retry=0.500, completion=6.000
>2014/02/25 14:25:25.243	  0:08.087	Housekeepe...fff7f97700	SIP	Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bKa47290f3-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:25.243	  0:08.087	Housekeepe...fff7f97700	SIP	Completed state for transaction id=z9hG4bKa47290f3-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:25.264	  0:08.108	Opal Liste...ffd8116700	OpalUDP	Binding to interface: 10.34.130.146:5060
>2014/02/25 14:25:25.264	  0:08.108	Opal Liste...ffd8116700	SIP	Waiting for PDU on udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:25.264	  0:08.108	Opal Liste...ffd8116700	SIP	PDU received: rem=udp$86.64.162.35:5060,local=udp$209.132.186.34:5060,if=10.34.130.146%em1
>SIP/2.0 202 OK
>CSeq: 1 SUBSCRIBE
>Via: SIP/2.0/UDP 10.34.130.146:5060;branch=z9hG4bKdc7829f4-8d9c-e311-86d5-3c970e8ed11e;rport=5060
>Server: Kamailio (1.5.3-notls (i386/linux))
>From: <sip:desktopqe@ekiga.net>;tag=647329f4-8d9c-e311-86d5-3c970e8ed11e
>Call-ID: e45729f4-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>To: <sip:qedesktop@ekiga.net>;tag=f85b0bd16aaafa8479586ac9f88b3198-35af
>Contact: <sip:86.64.162.35:5060>
>Expires: 300
>Content-Length: 0
>
>
>2014/02/25 14:25:25.265	  0:08.109	Opal Liste...ffd8116700	SIP	Queueing PDU "1 SUBSCRIBE <202>", transaction=z9hG4bKdc7829f4-8d9c-e311-86d5-3c970e8ed11e, token=e45729f4-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:25.265	  0:08.109	Opal Liste...ffd8116700	Opal	Transport clean up on termination
>2014/02/25 14:25:25.265	  0:08.109	Opal Liste...ffd8116700	Opal	Transport Close
>2014/02/25 14:25:25.265	  0:08.109	    Pool:0x7fffa406d700	SIP	Handling PDU "1 SUBSCRIBE <202>" for transaction=z9hG4bKdc7829f4-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:25.265	  0:08.109	    Pool:0x7fffa406d700	SIP	SUBSCRIBE transaction id=z9hG4bKdc7829f4-8d9c-e311-86d5-3c970e8ed11e completing.
>2014/02/25 14:25:25.265	  0:08.109	    Pool:0x7fffa406d700	SIP	Finalising handlers interface "10.34.130.146%em1"
>2014/02/25 14:25:25.265	  0:08.109	    Pool:0x7fffa406d700	OpalUDP	Setting interface to 10.34.130.146%em1
>2014/02/25 14:25:25.265	  0:08.109	    Pool:0x7fffa406d700	SIP	Expiry time for SUBSCRIBE set to 300 seconds.
>2014/02/25 14:25:25.265	  0:08.109	Opal Liste...ffd8116700	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:25.265	  0:08.109	    Pool:0x7fffa406d700	SIP	Route set is 
>2014/02/25 14:25:25.265	  0:08.109	    Pool:0x7fffa406d700	SIP	Set Request URI to sip:86.64.162.35:5060
>2014/02/25 14:25:25.265	  0:08.109	Opal Liste...ffd8116700	OpalUDP	Binding to interface: 10.34.130.146:5060
>2014/02/25 14:25:25.265	  0:08.109	    Pool:0x7fffa406d700	SIP	Updating dialog tag from "" to "f85b0bd16aaafa8479586ac9f88b3198-35af"
>2014/02/25 14:25:25.265	  0:08.109	Opal Liste...ffd8116700	SIP	Waiting for PDU on udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:25.265	  0:08.109	    Pool:0x7fffa406d700	SIP	Product Info: name="Kamailio", version="", vendor="", comments="(1.5.3-notls (i386/linux))"
>2014/02/25 14:25:25.265	  0:08.109	    Pool:0x7fffa406d700	SIP	Changing SUBSCRIBE handler from Subscribing to Subscribed, target=sip:qedesktop@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net, id=e45729f4-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:25.265	  0:08.109	    Pool:0x7fffa406d700	SIP	SUBSCRIBE transaction id=z9hG4bKdc7829f4-8d9c-e311-86d5-3c970e8ed11e completed.
>2014/02/25 14:25:25.265	  0:08.110	    Pool:0x7fffa406d700	SIP	Handled PDU "1 SUBSCRIBE <202>"
>2014/02/25 14:25:25.265	  0:08.109	Opal Liste...ffd8116700	SIP	PDU received: rem=udp$86.64.162.35:5060,local=udp$209.132.186.34:5060,if=10.34.130.146%em1
>NOTIFY sip:desktopqe@10.34.130.146 SIP/2.0
>CSeq: 1 NOTIFY
>Via: SIP/2.0/UDP 86.64.162.35;branch=z9hG4bK40a6.c7c3e607.0
>User-Agent: Kamailio (1.5.3-notls (i386/linux))
>From: sip:qedesktop@ekiga.net;tag=f85b0bd16aaafa8479586ac9f88b3198-35af
>Call-ID: e45729f4-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>To: sip:desktopqe@ekiga.net;tag=647329f4-8d9c-e311-86d5-3c970e8ed11e
>Contact: <sip:86.64.162.35:5060>
>Subscription-State: active;expires=370
>Event: presence
>Content-Length: 0
>Max-Forwards: 70
>
>
>2014/02/25 14:25:25.266	  0:08.110	Opal Liste...ffd8116700	SIP	Received NOTIFY presence
>2014/02/25 14:25:25.266	  0:08.110	Opal Liste...ffd8116700	SIP	Found a SUBSCRIBE corresponding to the NOTIFY presence
>2014/02/25 14:25:25.266	  0:08.110	Opal Liste...ffd8116700	SIP	Product Info: name="Kamailio", version="", vendor="", comments="(1.5.3-notls (i386/linux))"
>2014/02/25 14:25:25.266	  0:08.110	Opal Liste...ffd8116700	SIP	Dialog initial sequence number 1
>2014/02/25 14:25:25.266	  0:08.110	Opal Liste...ffd8116700	SIP	Subscription is Subscribed
>2014/02/25 14:25:25.266	  0:08.110	Opal Liste...ffd8116700	SIP	Calling NOTIFY callback for AOR "sip:qedesktop@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net"
>2014/02/25 14:25:25.266	  0:08.110	Opal Liste...ffd8116700	SIPPres	Empty body on presence NOTIFY, ignoring
>2014/02/25 14:25:25.267	  0:08.111	Opal Liste...ffd8116700	SIP	Sending PDU (367 bytes) to: rem=udp$86.64.162.35:5060,local=udp$209.132.186.34:5060,if=10.34.130.146%em1
>SIP/2.0 200 OK
>CSeq: 1 NOTIFY
>Via: SIP/2.0/UDP 86.64.162.35;branch=z9hG4bK40a6.c7c3e607.0
>From: sip:qedesktop@ekiga.net;tag=f85b0bd16aaafa8479586ac9f88b3198-35af
>Call-ID: e45729f4-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>To: sip:desktopqe@ekiga.net;tag=647329f4-8d9c-e311-86d5-3c970e8ed11e
>Contact: <sip:desktopqe@209.132.186.34>
>Content-Length: 0
>
>
>2014/02/25 14:25:25.267	  0:08.111	Opal Liste...ffd8116700	OpalUDP	Setting interface to 10.34.130.146%em1
>2014/02/25 14:25:25.267	  0:08.111	Opal Liste...ffd8116700	Opal	Transport clean up on termination
>2014/02/25 14:25:25.267	  0:08.111	Opal Liste...ffd8116700	Opal	Transport Close
>2014/02/25 14:25:25.267	  0:08.111	Opal Liste...ffd8116700	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:25.295	  0:08.139	Housekeepe...fff7f97700	SIP	Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bK687998f3-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:25.295	  0:08.139	Housekeepe...fff7f97700	SIP	Completed state for transaction id=z9hG4bK687998f3-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:25.331	  0:08.175	Opal Garba...fff7e93700	SIP	Transaction id=z9hG4bK687998f3-8d9c-e311-86d5-3c970e8ed11e destroyed.
>2014/02/25 14:25:25.331	  0:08.175	Opal Garba...fff7e93700	SIP	Transaction id=z9hG4bKa47290f3-8d9c-e311-86d5-3c970e8ed11e destroyed.
>2014/02/25 14:25:26.193	  0:09.037	PThreadObj...ffd006f700	SIPPres	'sip:desktopqe@ekiga.net' sending own presence Available/I'm available using Ekiga
>2014/02/25 14:25:26.194	  0:09.038	PThreadObj...ffd006f700	SIPPres	PIDF entity derived from AOR as pres:desktopqe@ekiga.net
>2014/02/25 14:25:26.194	  0:09.038	PThreadObj...ffd006f700	SIP	Start PUBLISH
> eventPackage=presence
>          aor=sip:desktopqe@ekiga.net
>       remote=sip:desktopqe@ekiga.net
>        local=
>      contact=
>        proxy=
>       authID=
>        realm=
>       expire=300
>      restore=30
>     minRetry=default
>     maxRetry=default
>2014/02/25 14:25:26.194	  0:09.038	PThreadObj...ffd006f700	SIP	Executing state change to Subscribing for PUBLISH handler, target=sip:desktopqe@ekiga.net, id=d0fae1f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:26.194	  0:09.038	PThreadObj...ffd006f700	SIP	Changing PUBLISH handler from Subscribed to Subscribing, target=sip:desktopqe@ekiga.net, id=d0fae1f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:26.194	  0:09.038	PThreadObj...ffd006f700	SIP	Transaction created.
>2014/02/25 14:25:26.194	  0:09.038	PThreadObj...ffd006f700	SIP	Transaction remembering interface "10.34.130.146%em1"
>2014/02/25 14:25:26.194	  0:09.038	PThreadObj...ffd006f700	DNS	SRV Lookup "_sip._udp.ekiga.net"
>2014/02/25 14:25:26.194	  0:09.038	PThreadObj...ffd006f700	SIP	Attempting SRV record entry 0: 86.64.162.35:5060
>2014/02/25 14:25:26.194	  0:09.038	PThreadObj...ffd006f700	SIP	Transaction remote address is udp$86.64.162.35:5060
>2014/02/25 14:25:26.194	  0:09.039	PThreadObj...ffd006f700	SIP	Sending PDU (950 bytes) to: rem=udp$86.64.162.35:5060,local=udp$209.132.186.34:5060,if=10.34.130.146%em1
>PUBLISH sip:desktopqe@ekiga.net SIP/2.0
>CSeq: 4 PUBLISH
>Via: SIP/2.0/UDP 209.132.186.34:5060;branch=z9hG4bK645cc2f4-8d9c-e311-86d5-3c970e8ed11e;rport
>User-Agent: Ekiga/4.0.1
>From: <sip:desktopqe@ekiga.net>
>Call-ID: d0fae1f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>To: <sip:desktopqe@ekiga.net>
>SIP-If-Match: a.1392937081.776.597152.0
>Expires: 300
>Event: presence
>Content-Length: 490
>Content-Type: application/pidf+xml
>Max-Forwards: 70
>
><?xml version="1.0" encoding="UTF-8"?>
><presence xmlns="urn:ietf:params:xml:ns:pidf"  xmlns:dm="urn:ietf:params:xml:ns:pidf:data-model" xmlns:rpid="urn:ietf:params:xml:ns:pidf:rpid" entity="pres:desktopqe@ekiga.net">
>  <tuple id="TA969AE4E">
>    <status>
>      <basic>open</basic>
>    </status>
>    <contact priority="1">sip:desktopqe@ekiga.net</contact>
>    <note>I&apos;m available using Ekiga</note>
>    <timestamp>2014-02-25T14:25:26+01:00</timestamp>
>  </tuple>
></presence>
>
>2014/02/25 14:25:26.195	  0:09.039	PThreadObj...ffd006f700	OpalUDP	Setting interface to 10.34.130.146%em1
>2014/02/25 14:25:26.195	  0:09.039	PThreadObj...ffd006f700	SIP	Transaction timers set: retry=0.500, completion=6.000
>2014/02/25 14:25:26.266	  0:09.110	Housekeepe...fff7f97700	SIP	Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bKdc7829f4-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:26.266	  0:09.110	Housekeepe...fff7f97700	SIP	Completed state for transaction id=z9hG4bKdc7829f4-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:26.331	  0:09.175	Opal Garba...fff7e93700	SIP	Transaction id=z9hG4bKdc7829f4-8d9c-e311-86d5-3c970e8ed11e destroyed.
>2014/02/25 14:25:26.335	  0:09.179	Opal Liste...ffd8116700	OpalUDP	Binding to interface: 10.34.130.146:5060
>2014/02/25 14:25:26.335	  0:09.179	Opal Liste...ffd8116700	SIP	Waiting for PDU on udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:26.336	  0:09.180	Opal Liste...ffd8116700	SIP	PDU received: rem=udp$86.64.162.35:5060,local=udp$209.132.186.34:5060,if=10.34.130.146%em1
>SIP/2.0 200 OK
>CSeq: 4 PUBLISH
>Via: SIP/2.0/UDP 10.34.130.146:5060;branch=z9hG4bK645cc2f4-8d9c-e311-86d5-3c970e8ed11e;rport=5060
>Server: Kamailio (1.5.3-notls (i386/linux))
>From: <sip:desktopqe@ekiga.net>
>Call-ID: d0fae1f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>To: <sip:desktopqe@ekiga.net>;tag=f85b0bd16aaafa8479586ac9f88b3198-2f04
>Expires: 295
>Content-Length: 0
>SIP-ETag: a.1392937081.776.597157.1
>
>
>2014/02/25 14:25:26.336	  0:09.180	Opal Liste...ffd8116700	SIP	Queueing PDU "4 PUBLISH <200>", transaction=z9hG4bK645cc2f4-8d9c-e311-86d5-3c970e8ed11e, token=d0fae1f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:26.336	  0:09.180	Opal Liste...ffd8116700	Opal	Transport clean up on termination
>2014/02/25 14:25:26.336	  0:09.180	Opal Liste...ffd8116700	Opal	Transport Close
>2014/02/25 14:25:26.336	  0:09.180	    Pool:0x7fffa406d700	SIP	Handling PDU "4 PUBLISH <200>" for transaction=z9hG4bK645cc2f4-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:26.336	  0:09.180	Opal Liste...ffd8116700	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:26.336	  0:09.180	    Pool:0x7fffa406d700	SIP	PUBLISH transaction id=z9hG4bK645cc2f4-8d9c-e311-86d5-3c970e8ed11e completing.
>2014/02/25 14:25:26.336	  0:09.180	    Pool:0x7fffa406d700	SIP	Finalising handlers interface "10.34.130.146%em1"
>2014/02/25 14:25:26.336	  0:09.180	    Pool:0x7fffa406d700	OpalUDP	Setting interface to 10.34.130.146%em1
>2014/02/25 14:25:26.336	  0:09.180	    Pool:0x7fffa406d700	SIP	Expiry time for PUBLISH set to 295 seconds.
>2014/02/25 14:25:26.336	  0:09.180	    Pool:0x7fffa406d700	SIP	Product Info: name="Kamailio", version="", vendor="", comments="(1.5.3-notls (i386/linux))"
>2014/02/25 14:25:26.336	  0:09.180	    Pool:0x7fffa406d700	SIP	Changing PUBLISH handler from Subscribing to Subscribed, target=sip:desktopqe@ekiga.net, id=d0fae1f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:26.336	  0:09.180	    Pool:0x7fffa406d700	SIP	PUBLISH transaction id=z9hG4bK645cc2f4-8d9c-e311-86d5-3c970e8ed11e completed.
>2014/02/25 14:25:26.336	  0:09.180	    Pool:0x7fffa406d700	SIP	Handled PDU "4 PUBLISH <200>"
>2014/02/25 14:25:27.195	  0:10.039	PThreadObj...ffd006f700	SIPPres	'sip:desktopqe@ekiga.net' sending own presence Available/I'm available using Ekiga
>2014/02/25 14:25:27.195	  0:10.039	PThreadObj...ffd006f700	SIPPres	PIDF entity derived from AOR as pres:desktopqe@ekiga.net
>2014/02/25 14:25:27.195	  0:10.039	PThreadObj...ffd006f700	SIP	Start PUBLISH
> eventPackage=presence
>          aor=sip:desktopqe@ekiga.net
>       remote=sip:desktopqe@ekiga.net
>        local=
>      contact=
>        proxy=
>       authID=
>        realm=
>       expire=300
>      restore=30
>     minRetry=default
>     maxRetry=default
>2014/02/25 14:25:27.195	  0:10.039	PThreadObj...ffd006f700	SIP	Executing state change to Subscribing for PUBLISH handler, target=sip:desktopqe@ekiga.net, id=d0fae1f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:27.195	  0:10.039	PThreadObj...ffd006f700	SIP	Changing PUBLISH handler from Subscribed to Subscribing, target=sip:desktopqe@ekiga.net, id=d0fae1f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:27.195	  0:10.039	PThreadObj...ffd006f700	SIP	Transaction created.
>2014/02/25 14:25:27.196	  0:10.040	PThreadObj...ffd006f700	SIP	Transaction remembering interface "10.34.130.146%em1"
>2014/02/25 14:25:27.196	  0:10.040	PThreadObj...ffd006f700	DNS	SRV Lookup "_sip._udp.ekiga.net"
>2014/02/25 14:25:27.196	  0:10.040	PThreadObj...ffd006f700	SIP	Attempting SRV record entry 0: 86.64.162.35:5060
>2014/02/25 14:25:27.196	  0:10.040	PThreadObj...ffd006f700	SIP	Transaction remote address is udp$86.64.162.35:5060
>2014/02/25 14:25:27.196	  0:10.040	PThreadObj...ffd006f700	SIP	Sending PDU (950 bytes) to: rem=udp$86.64.162.35:5060,local=udp$209.132.186.34:5060,if=10.34.130.146%em1
>PUBLISH sip:desktopqe@ekiga.net SIP/2.0
>CSeq: 5 PUBLISH
>Via: SIP/2.0/UDP 209.132.186.34:5060;branch=z9hG4bKc8275bf5-8d9c-e311-86d5-3c970e8ed11e;rport
>User-Agent: Ekiga/4.0.1
>From: <sip:desktopqe@ekiga.net>
>Call-ID: d0fae1f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>To: <sip:desktopqe@ekiga.net>
>SIP-If-Match: a.1392937081.776.597157.1
>Expires: 300
>Event: presence
>Content-Length: 490
>Content-Type: application/pidf+xml
>Max-Forwards: 70
>
><?xml version="1.0" encoding="UTF-8"?>
><presence xmlns="urn:ietf:params:xml:ns:pidf"  xmlns:dm="urn:ietf:params:xml:ns:pidf:data-model" xmlns:rpid="urn:ietf:params:xml:ns:pidf:rpid" entity="pres:desktopqe@ekiga.net">
>  <tuple id="TA969AE4E">
>    <status>
>      <basic>open</basic>
>    </status>
>    <contact priority="1">sip:desktopqe@ekiga.net</contact>
>    <note>I&apos;m available using Ekiga</note>
>    <timestamp>2014-02-25T14:25:27+01:00</timestamp>
>  </tuple>
></presence>
>
>2014/02/25 14:25:27.196	  0:10.040	PThreadObj...ffd006f700	OpalUDP	Setting interface to 10.34.130.146%em1
>2014/02/25 14:25:27.196	  0:10.040	PThreadObj...ffd006f700	SIP	Transaction timers set: retry=0.500, completion=6.000
>2014/02/25 14:25:27.259	  0:10.103	Opal Liste...ffd8116700	OpalUDP	Binding to interface: 10.34.130.146:5060
>2014/02/25 14:25:27.259	  0:10.103	Opal Liste...ffd8116700	SIP	Waiting for PDU on udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:27.259	  0:10.103	Opal Liste...ffd8116700	SIP	PDU received: rem=udp$86.64.162.35:5060,local=udp$209.132.186.34:5060,if=10.34.130.146%em1
>SIP/2.0 200 OK
>CSeq: 5 PUBLISH
>Via: SIP/2.0/UDP 10.34.130.146:5060;branch=z9hG4bKc8275bf5-8d9c-e311-86d5-3c970e8ed11e;rport=5060
>Server: Kamailio (1.5.3-notls (i386/linux))
>From: <sip:desktopqe@ekiga.net>
>Call-ID: d0fae1f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>To: <sip:desktopqe@ekiga.net>;tag=f85b0bd16aaafa8479586ac9f88b3198-e6e8
>Expires: 295
>Content-Length: 0
>SIP-ETag: a.1392937081.774.596994.2
>
>
>2014/02/25 14:25:27.259	  0:10.103	Opal Liste...ffd8116700	SIP	Queueing PDU "5 PUBLISH <200>", transaction=z9hG4bKc8275bf5-8d9c-e311-86d5-3c970e8ed11e, token=d0fae1f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:27.259	  0:10.103	Opal Liste...ffd8116700	Opal	Transport clean up on termination
>2014/02/25 14:25:27.259	  0:10.103	Opal Liste...ffd8116700	Opal	Transport Close
>2014/02/25 14:25:27.260	  0:10.104	    Pool:0x7fffa406d700	SIP	Handling PDU "5 PUBLISH <200>" for transaction=z9hG4bKc8275bf5-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:27.260	  0:10.104	    Pool:0x7fffa406d700	SIP	PUBLISH transaction id=z9hG4bKc8275bf5-8d9c-e311-86d5-3c970e8ed11e completing.
>2014/02/25 14:25:27.259	  0:10.104	Opal Liste...ffd8116700	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:27.260	  0:10.104	    Pool:0x7fffa406d700	SIP	Finalising handlers interface "10.34.130.146%em1"
>2014/02/25 14:25:27.260	  0:10.104	    Pool:0x7fffa406d700	OpalUDP	Setting interface to 10.34.130.146%em1
>2014/02/25 14:25:27.260	  0:10.104	    Pool:0x7fffa406d700	SIP	Expiry time for PUBLISH set to 295 seconds.
>2014/02/25 14:25:27.260	  0:10.104	    Pool:0x7fffa406d700	SIP	Product Info: name="Kamailio", version="", vendor="", comments="(1.5.3-notls (i386/linux))"
>2014/02/25 14:25:27.260	  0:10.104	    Pool:0x7fffa406d700	SIP	Changing PUBLISH handler from Subscribing to Subscribed, target=sip:desktopqe@ekiga.net, id=d0fae1f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:27.260	  0:10.104	    Pool:0x7fffa406d700	SIP	PUBLISH transaction id=z9hG4bKc8275bf5-8d9c-e311-86d5-3c970e8ed11e completed.
>2014/02/25 14:25:27.260	  0:10.104	    Pool:0x7fffa406d700	SIP	Handled PDU "5 PUBLISH <200>"
>2014/02/25 14:25:27.336	  0:10.180	Housekeepe...fff7f97700	SIP	Set state Terminated_Success for PUBLISH transaction id=z9hG4bK645cc2f4-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:27.336	  0:10.180	Housekeepe...fff7f97700	SIP	Completed state for transaction id=z9hG4bK645cc2f4-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:27.370	  0:10.214	Housekeepe...fff7f97700	SIP	NAT Binding refresh for REGISTER to sip:desktopqe@ekiga.net, id=80f3c5f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:27.371	  0:10.215	Housekeepe...fff7f97700	SIP	Constructed OPTIONS handler for sip:desktopqe@ekiga.net
>2014/02/25 14:25:27.371	  0:10.215	Housekeepe...fff7f97700	SIP	Changing OPTIONS handler from Unavailable to Subscribed, target=sip:desktopqe@ekiga.net, id=0ce275f5-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:27.371	  0:10.215	Housekeepe...fff7f97700	SIP	Executing state change to Unsubscribing for OPTIONS handler, target=sip:desktopqe@ekiga.net, id=0ce275f5-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:27.371	  0:10.215	Housekeepe...fff7f97700	SIP	Changing OPTIONS handler from Subscribed to Unsubscribing, target=sip:desktopqe@ekiga.net, id=0ce275f5-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:27.371	  0:10.215	Housekeepe...fff7f97700	OpalUDP	Binding to interface: 10.34.130.146:5060
>2014/02/25 14:25:27.371	  0:10.215	Housekeepe...fff7f97700	SIP	Created transport udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:27.371	  0:10.215	Housekeepe...fff7f97700	OpalUDP	Started connect to 86.64.162.35:5060
>2014/02/25 14:25:27.371	  0:10.215	Housekeepe...fff7f97700	SIP	Transaction created.
>2014/02/25 14:25:27.372	  0:10.216	Housekeepe...fff7f97700	SIP	Transaction remembering interface "10.34.130.146%em1"
>2014/02/25 14:25:27.372	  0:10.216	Housekeepe...fff7f97700	SIP	No SRV lookup as has explicit port number.
>2014/02/25 14:25:27.372	  0:10.216	Housekeepe...fff7f97700	SIP	Transaction remote address is udp$86.64.162.35:5060
>2014/02/25 14:25:27.372	  0:10.216	Housekeepe...fff7f97700	SIP	Sending PDU (621 bytes) to: rem=udp$86.64.162.35:5060,local=udp$209.132.186.34:5060,if=10.34.130.146%em1
>OPTIONS sip:desktopqe@ekiga.net SIP/2.0
>Route: <sip:86.64.162.35:5060;lr>
>CSeq: 6 OPTIONS
>Via: SIP/2.0/UDP 209.132.186.34:5060;branch=z9hG4bK8efb75f5-8d9c-e311-86d5-3c970e8ed11e;rport
>User-Agent: Ekiga/4.0.1
>From: <sip:desktopqe@ekiga.net>;tag=baf775f5-8d9c-e311-86d5-3c970e8ed11e
>Call-ID: 0ce275f5-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>To: <sip:desktopqe@ekiga.net>
>Accept: application/sdp, application/media_control+xml, application/dtmf, application/dtmf-relay
>Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK
>Expires: 0
>Content-Length: 0
>Max-Forwards: 70
>
>
>2014/02/25 14:25:27.372	  0:10.216	Housekeepe...fff7f97700	OpalUDP	Setting interface to 10.34.130.146%em1
>2014/02/25 14:25:27.372	  0:10.216	Housekeepe...fff7f97700	SIP	Transaction timers set: retry=0.500, completion=6.000
>2014/02/25 14:25:27.372	  0:10.216	Housekeepe...fff7f97700	SIP	NAT Binding refresh for SUBSCRIBE to sip:qedesktop@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net, id=e45729f4-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:27.373	  0:10.217	Housekeepe...fff7f97700	SIP	Constructed OPTIONS handler for sip:qedesktop@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net
>2014/02/25 14:25:27.373	  0:10.217	Housekeepe...fff7f97700	SIP	Changing OPTIONS handler from Unavailable to Subscribed, target=sip:qedesktop@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net, id=942c76f5-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:27.373	  0:10.217	Housekeepe...fff7f97700	SIP	Executing state change to Unsubscribing for OPTIONS handler, target=sip:qedesktop@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net, id=942c76f5-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:27.373	  0:10.217	Housekeepe...fff7f97700	SIP	Changing OPTIONS handler from Subscribed to Unsubscribing, target=sip:qedesktop@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net, id=942c76f5-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:27.373	  0:10.217	Housekeepe...fff7f97700	OpalUDP	Binding to interface: 10.34.130.146:5060
>2014/02/25 14:25:27.373	  0:10.217	Housekeepe...fff7f97700	SIP	Created transport udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:27.373	  0:10.217	Housekeepe...fff7f97700	OpalUDP	Started connect to 86.64.162.35:5060
>2014/02/25 14:25:27.373	  0:10.217	Housekeepe...fff7f97700	SIP	Transaction created.
>2014/02/25 14:25:27.373	  0:10.218	Housekeepe...fff7f97700	SIP	Transaction remembering interface "10.34.130.146%em1"
>2014/02/25 14:25:27.374	  0:10.218	Housekeepe...fff7f97700	SIP	No SRV lookup as has explicit port number.
>2014/02/25 14:25:27.374	  0:10.218	Housekeepe...fff7f97700	SIP	Transaction remote address is udp$86.64.162.35:5060
>2014/02/25 14:25:27.374	  0:10.218	Housekeepe...fff7f97700	SIP	Sending PDU (621 bytes) to: rem=udp$86.64.162.35:5060,local=udp$209.132.186.34:5060,if=10.34.130.146%em1
>OPTIONS sip:qedesktop@ekiga.net SIP/2.0
>Route: <sip:86.64.162.35:5060;lr>
>CSeq: 7 OPTIONS
>Via: SIP/2.0/UDP 209.132.186.34:5060;branch=z9hG4bKd24876f5-8d9c-e311-86d5-3c970e8ed11e;rport
>User-Agent: Ekiga/4.0.1
>From: <sip:desktopqe@ekiga.net>;tag=fe4476f5-8d9c-e311-86d5-3c970e8ed11e
>Call-ID: 942c76f5-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>To: <sip:qedesktop@ekiga.net>
>Accept: application/sdp, application/media_control+xml, application/dtmf, application/dtmf-relay
>Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK
>Expires: 0
>Content-Length: 0
>Max-Forwards: 70
>
>
>2014/02/25 14:25:27.374	  0:10.218	Housekeepe...fff7f97700	OpalUDP	Setting interface to 10.34.130.146%em1
>2014/02/25 14:25:27.374	  0:10.218	Housekeepe...fff7f97700	SIP	Transaction timers set: retry=0.500, completion=6.000
>2014/02/25 14:25:27.427	  0:10.272	Opal Liste...ffd8116700	OpalUDP	Binding to interface: 10.34.130.146:5060
>2014/02/25 14:25:27.428	  0:10.272	Opal Liste...ffd8116700	SIP	Waiting for PDU on udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:27.428	  0:10.272	Opal Liste...ffd8116700	SIP	PDU received: rem=udp$86.64.162.35:5060,local=udp$209.132.186.34:5060,if=10.34.130.146%em1
>SIP/2.0 200 OK
>CSeq: 6 OPTIONS
>Via: SIP/2.0/UDP 10.34.130.146:5060;branch=z9hG4bK8efb75f5-8d9c-e311-86d5-3c970e8ed11e;rport=5060
>Server: Kamailio (1.5.3-notls (i386/linux))
>From: <sip:desktopqe@ekiga.net>;tag=baf775f5-8d9c-e311-86d5-3c970e8ed11e
>Call-ID: 0ce275f5-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>To: <sip:desktopqe@ekiga.net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.d1a3
>Content-Length: 0
>
>
>2014/02/25 14:25:27.428	  0:10.272	Opal Liste...ffd8116700	SIP	Queueing PDU "6 OPTIONS <200>", transaction=z9hG4bK8efb75f5-8d9c-e311-86d5-3c970e8ed11e, token=0ce275f5-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:27.428	  0:10.272	Opal Liste...ffd8116700	Opal	Transport clean up on termination
>2014/02/25 14:25:27.428	  0:10.272	Opal Liste...ffd8116700	Opal	Transport Close
>2014/02/25 14:25:27.428	  0:10.272	    Pool:0x7fffa406d700	SIP	Handling PDU "6 OPTIONS <200>" for transaction=z9hG4bK8efb75f5-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:27.428	  0:10.272	    Pool:0x7fffa406d700	SIP	OPTIONS transaction id=z9hG4bK8efb75f5-8d9c-e311-86d5-3c970e8ed11e completing.
>2014/02/25 14:25:27.428	  0:10.272	    Pool:0x7fffa406d700	SIP	Finalising handlers interface "10.34.130.146%em1"
>2014/02/25 14:25:27.428	  0:10.272	    Pool:0x7fffa406d700	OpalUDP	Setting interface to 10.34.130.146%em1
>2014/02/25 14:25:27.428	  0:10.272	    Pool:0x7fffa406d700	SIP	Completed OPTIONS command to sip:desktopqe@ekiga.net;OPAL-proxy=86.64.162.35;OPAL-interface=10.34.130.146%25em1, status=200 OK
>2014/02/25 14:25:27.428	  0:10.272	Opal Liste...ffd8116700	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:27.428	  0:10.273	    Pool:0x7fffa406d700	SIP	Product Info: name="Kamailio", version="", vendor="", comments="(1.5.3-notls (i386/linux))"
>2014/02/25 14:25:27.429	  0:10.273	    Pool:0x7fffa406d700	SIP	Changing OPTIONS handler from Unsubscribing to Unsubscribed, target=sip:desktopqe@ekiga.net, id=0ce275f5-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:27.429	  0:10.273	    Pool:0x7fffa406d700	SIP	OPTIONS transaction id=z9hG4bK8efb75f5-8d9c-e311-86d5-3c970e8ed11e completed.
>2014/02/25 14:25:27.429	  0:10.273	    Pool:0x7fffa406d700	SIP	Handled PDU "6 OPTIONS <200>"
>2014/02/25 14:25:27.432	  0:10.276	Opal Liste...ffd8116700	OpalUDP	Binding to interface: 10.34.130.146:5060
>2014/02/25 14:25:27.432	  0:10.276	Opal Liste...ffd8116700	SIP	Waiting for PDU on udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:27.432	  0:10.276	Opal Liste...ffd8116700	SIP	PDU received: rem=udp$86.64.162.35:5060,local=udp$209.132.186.34:5060,if=10.34.130.146%em1
>SIP/2.0 200 OK
>CSeq: 7 OPTIONS
>Via: SIP/2.0/UDP 10.34.130.146:5060;branch=z9hG4bKd24876f5-8d9c-e311-86d5-3c970e8ed11e;rport=5060
>Server: Kamailio (1.5.3-notls (i386/linux))
>From: <sip:desktopqe@ekiga.net>;tag=fe4476f5-8d9c-e311-86d5-3c970e8ed11e
>Call-ID: 942c76f5-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>To: <sip:qedesktop@ekiga.net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.c498
>Content-Length: 0
>
>
>2014/02/25 14:25:27.432	  0:10.276	Opal Liste...ffd8116700	SIP	Queueing PDU "7 OPTIONS <200>", transaction=z9hG4bKd24876f5-8d9c-e311-86d5-3c970e8ed11e, token=942c76f5-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:27.432	  0:10.276	Opal Liste...ffd8116700	Opal	Transport clean up on termination
>2014/02/25 14:25:27.432	  0:10.276	    Pool:0x7fffa406d700	SIP	Handling PDU "7 OPTIONS <200>" for transaction=z9hG4bKd24876f5-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:27.432	  0:10.276	Opal Liste...ffd8116700	Opal	Transport Close
>2014/02/25 14:25:27.432	  0:10.276	    Pool:0x7fffa406d700	SIP	OPTIONS transaction id=z9hG4bKd24876f5-8d9c-e311-86d5-3c970e8ed11e completing.
>2014/02/25 14:25:27.432	  0:10.276	    Pool:0x7fffa406d700	SIP	Finalising handlers interface "10.34.130.146%em1"
>2014/02/25 14:25:27.432	  0:10.276	    Pool:0x7fffa406d700	OpalUDP	Setting interface to 10.34.130.146%em1
>2014/02/25 14:25:27.432	  0:10.276	    Pool:0x7fffa406d700	SIP	Completed OPTIONS command to sip:qedesktop@ekiga.net;OPAL-proxy=86.64.162.35;OPAL-local-id=sip:desktopqe%40ekiga.net;OPAL-interface=10.34.130.146%25em1, status=200 OK
>2014/02/25 14:25:27.432	  0:10.276	    Pool:0x7fffa406d700	SIP	Product Info: name="Kamailio", version="", vendor="", comments="(1.5.3-notls (i386/linux))"
>2014/02/25 14:25:27.432	  0:10.276	Opal Liste...ffd8116700	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:27.432	  0:10.276	    Pool:0x7fffa406d700	SIP	Changing OPTIONS handler from Unsubscribing to Unsubscribed, target=sip:qedesktop@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net, id=942c76f5-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:27.432	  0:10.276	    Pool:0x7fffa406d700	SIP	OPTIONS transaction id=z9hG4bKd24876f5-8d9c-e311-86d5-3c970e8ed11e completed.
>2014/02/25 14:25:27.432	  0:10.276	    Pool:0x7fffa406d700	SIP	Handled PDU "7 OPTIONS <200>"
>2014/02/25 14:25:28.260	  0:11.104	Housekeepe...fff7f97700	SIP	Set state Terminated_Success for PUBLISH transaction id=z9hG4bKc8275bf5-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:28.260	  0:11.104	Housekeepe...fff7f97700	SIP	Completed state for transaction id=z9hG4bKc8275bf5-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:28.332	  0:11.176	Opal Garba...fff7e93700	SIP	Transaction id=z9hG4bK645cc2f4-8d9c-e311-86d5-3c970e8ed11e destroyed.
>2014/02/25 14:25:28.332	  0:11.176	Opal Garba...fff7e93700	SIP	Transaction id=z9hG4bKc8275bf5-8d9c-e311-86d5-3c970e8ed11e destroyed.
>2014/02/25 14:25:28.333	  0:11.177	Opal Garba...fff7e93700	Opal	Transport clean up on termination
>2014/02/25 14:25:28.333	  0:11.177	Opal Garba...fff7e93700	Opal	Transport Close
>2014/02/25 14:25:28.333	  0:11.177	Opal Garba...fff7e93700	Opal	Transport clean up on termination
>2014/02/25 14:25:28.333	  0:11.177	Opal Garba...fff7e93700	Opal	Transport Close
>2014/02/25 14:25:28.333	  0:11.177	Opal Garba...fff7e93700	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:28.333	  0:11.177	Opal Garba...fff7e93700	SIP	Destroyed OPTIONS handler for sip:desktopqe@ekiga.net
>2014/02/25 14:25:28.333	  0:11.177	Opal Garba...fff7e93700	Opal	Transport clean up on termination
>2014/02/25 14:25:28.333	  0:11.177	Opal Garba...fff7e93700	Opal	Transport Close
>2014/02/25 14:25:28.333	  0:11.177	Opal Garba...fff7e93700	Opal	Transport clean up on termination
>2014/02/25 14:25:28.333	  0:11.177	Opal Garba...fff7e93700	Opal	Transport Close
>2014/02/25 14:25:28.333	  0:11.177	Opal Garba...fff7e93700	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:28.333	  0:11.177	Opal Garba...fff7e93700	SIP	Destroyed OPTIONS handler for sip:qedesktop@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net
>2014/02/25 14:25:28.429	  0:11.273	Housekeepe...fff7f97700	SIP	Set state Terminated_Success for OPTIONS transaction id=z9hG4bK8efb75f5-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:28.429	  0:11.273	Housekeepe...fff7f97700	SIP	Completed state for transaction id=z9hG4bK8efb75f5-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:28.454	  0:11.298	Housekeepe...fff7f97700	SIP	Set state Terminated_Success for OPTIONS transaction id=z9hG4bKd24876f5-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:28.454	  0:11.298	Housekeepe...fff7f97700	SIP	Completed state for transaction id=z9hG4bKd24876f5-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:29.334	  0:12.178	Opal Garba...fff7e93700	SIP	Transaction id=z9hG4bK8efb75f5-8d9c-e311-86d5-3c970e8ed11e destroyed.
>2014/02/25 14:25:29.334	  0:12.178	Opal Garba...fff7e93700	SIP	Transaction id=z9hG4bKd24876f5-8d9c-e311-86d5-3c970e8ed11e destroyed.
>2014/02/25 14:25:37.371	  0:20.215	Housekeepe...fff7f97700	SIP	NAT Binding refresh for REGISTER to sip:desktopqe@ekiga.net, id=80f3c5f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:37.371	  0:20.215	Housekeepe...fff7f97700	SIP	Constructed OPTIONS handler for sip:desktopqe@ekiga.net
>2014/02/25 14:25:37.371	  0:20.215	Housekeepe...fff7f97700	SIP	Changing OPTIONS handler from Unavailable to Subscribed, target=sip:desktopqe@ekiga.net, id=64d96bfb-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:37.371	  0:20.215	Housekeepe...fff7f97700	SIP	Executing state change to Unsubscribing for OPTIONS handler, target=sip:desktopqe@ekiga.net, id=64d96bfb-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:37.371	  0:20.216	Housekeepe...fff7f97700	SIP	Changing OPTIONS handler from Subscribed to Unsubscribing, target=sip:desktopqe@ekiga.net, id=64d96bfb-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:37.372	  0:20.216	Housekeepe...fff7f97700	OpalUDP	Binding to interface: 10.34.130.146:5060
>2014/02/25 14:25:37.372	  0:20.216	Housekeepe...fff7f97700	SIP	Created transport udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:37.372	  0:20.216	Housekeepe...fff7f97700	OpalUDP	Started connect to 86.64.162.35:5060
>2014/02/25 14:25:37.372	  0:20.216	Housekeepe...fff7f97700	SIP	Transaction created.
>2014/02/25 14:25:37.372	  0:20.216	Housekeepe...fff7f97700	SIP	Transaction remembering interface "10.34.130.146%em1"
>2014/02/25 14:25:37.372	  0:20.216	Housekeepe...fff7f97700	SIP	No SRV lookup as has explicit port number.
>2014/02/25 14:25:37.372	  0:20.217	Housekeepe...fff7f97700	SIP	Transaction remote address is udp$86.64.162.35:5060
>2014/02/25 14:25:37.373	  0:20.217	Housekeepe...fff7f97700	SIP	Sending PDU (621 bytes) to: rem=udp$86.64.162.35:5060,local=udp$209.132.186.34:5060,if=10.34.130.146%em1
>OPTIONS sip:desktopqe@ekiga.net SIP/2.0
>Route: <sip:86.64.162.35:5060;lr>
>CSeq: 8 OPTIONS
>Via: SIP/2.0/UDP 209.132.186.34:5060;branch=z9hG4bKbafb6bfb-8d9c-e311-86d5-3c970e8ed11e;rport
>User-Agent: Ekiga/4.0.1
>From: <sip:desktopqe@ekiga.net>;tag=9cf66bfb-8d9c-e311-86d5-3c970e8ed11e
>Call-ID: 64d96bfb-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>To: <sip:desktopqe@ekiga.net>
>Accept: application/sdp, application/media_control+xml, application/dtmf, application/dtmf-relay
>Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK
>Expires: 0
>Content-Length: 0
>Max-Forwards: 70
>
>
>2014/02/25 14:25:37.373	  0:20.217	Housekeepe...fff7f97700	OpalUDP	Setting interface to 10.34.130.146%em1
>2014/02/25 14:25:37.373	  0:20.217	Housekeepe...fff7f97700	SIP	Transaction timers set: retry=0.500, completion=6.000
>2014/02/25 14:25:37.373	  0:20.217	Housekeepe...fff7f97700	SIP	NAT Binding refresh for SUBSCRIBE to sip:qedesktop@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net, id=e45729f4-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:37.374	  0:20.218	Housekeepe...fff7f97700	SIP	Constructed OPTIONS handler for sip:qedesktop@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net
>2014/02/25 14:25:37.374	  0:20.218	Housekeepe...fff7f97700	SIP	Changing OPTIONS handler from Unavailable to Subscribed, target=sip:qedesktop@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net, id=02316cfb-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:37.374	  0:20.218	Housekeepe...fff7f97700	SIP	Executing state change to Unsubscribing for OPTIONS handler, target=sip:qedesktop@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net, id=02316cfb-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:37.374	  0:20.218	Housekeepe...fff7f97700	SIP	Changing OPTIONS handler from Subscribed to Unsubscribing, target=sip:qedesktop@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net, id=02316cfb-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:37.374	  0:20.218	Housekeepe...fff7f97700	OpalUDP	Binding to interface: 10.34.130.146:5060
>2014/02/25 14:25:37.374	  0:20.218	Housekeepe...fff7f97700	SIP	Created transport udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:37.374	  0:20.218	Housekeepe...fff7f97700	OpalUDP	Started connect to 86.64.162.35:5060
>2014/02/25 14:25:37.374	  0:20.218	Housekeepe...fff7f97700	SIP	Transaction created.
>2014/02/25 14:25:37.375	  0:20.219	Housekeepe...fff7f97700	SIP	Transaction remembering interface "10.34.130.146%em1"
>2014/02/25 14:25:37.375	  0:20.219	Housekeepe...fff7f97700	SIP	No SRV lookup as has explicit port number.
>2014/02/25 14:25:37.375	  0:20.219	Housekeepe...fff7f97700	SIP	Transaction remote address is udp$86.64.162.35:5060
>2014/02/25 14:25:37.375	  0:20.219	Housekeepe...fff7f97700	SIP	Sending PDU (621 bytes) to: rem=udp$86.64.162.35:5060,local=udp$209.132.186.34:5060,if=10.34.130.146%em1
>OPTIONS sip:qedesktop@ekiga.net SIP/2.0
>Route: <sip:86.64.162.35:5060;lr>
>CSeq: 9 OPTIONS
>Via: SIP/2.0/UDP 209.132.186.34:5060;branch=z9hG4bK0a516cfb-8d9c-e311-86d5-3c970e8ed11e;rport
>User-Agent: Ekiga/4.0.1
>From: <sip:desktopqe@ekiga.net>;tag=004c6cfb-8d9c-e311-86d5-3c970e8ed11e
>Call-ID: 02316cfb-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>To: <sip:qedesktop@ekiga.net>
>Accept: application/sdp, application/media_control+xml, application/dtmf, application/dtmf-relay
>Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK
>Expires: 0
>Content-Length: 0
>Max-Forwards: 70
>
>
>2014/02/25 14:25:37.375	  0:20.219	Housekeepe...fff7f97700	OpalUDP	Setting interface to 10.34.130.146%em1
>2014/02/25 14:25:37.375	  0:20.219	Housekeepe...fff7f97700	SIP	Transaction timers set: retry=0.500, completion=6.000
>2014/02/25 14:25:37.631	  0:20.475	Opal Liste...ffd8116700	OpalUDP	Binding to interface: 10.34.130.146:5060
>2014/02/25 14:25:37.632	  0:20.476	Opal Liste...ffd8116700	SIP	Waiting for PDU on udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:37.632	  0:20.476	Opal Liste...ffd8116700	SIP	PDU received: rem=udp$86.64.162.35:5060,local=udp$209.132.186.34:5060,if=10.34.130.146%em1
>SIP/2.0 200 OK
>CSeq: 8 OPTIONS
>Via: SIP/2.0/UDP 10.34.130.146:5060;branch=z9hG4bKbafb6bfb-8d9c-e311-86d5-3c970e8ed11e;rport=5060
>Server: Kamailio (1.5.3-notls (i386/linux))
>From: <sip:desktopqe@ekiga.net>;tag=9cf66bfb-8d9c-e311-86d5-3c970e8ed11e
>Call-ID: 64d96bfb-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>To: <sip:desktopqe@ekiga.net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.ee3a
>Content-Length: 0
>
>
>2014/02/25 14:25:37.632	  0:20.476	Opal Liste...ffd8116700	SIP	Queueing PDU "8 OPTIONS <200>", transaction=z9hG4bKbafb6bfb-8d9c-e311-86d5-3c970e8ed11e, token=64d96bfb-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:37.632	  0:20.476	Opal Liste...ffd8116700	Opal	Transport clean up on termination
>2014/02/25 14:25:37.632	  0:20.476	Opal Liste...ffd8116700	Opal	Transport Close
>2014/02/25 14:25:37.632	  0:20.476	    Pool:0x7fffa406d700	SIP	Handling PDU "8 OPTIONS <200>" for transaction=z9hG4bKbafb6bfb-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:37.632	  0:20.476	    Pool:0x7fffa406d700	SIP	OPTIONS transaction id=z9hG4bKbafb6bfb-8d9c-e311-86d5-3c970e8ed11e completing.
>2014/02/25 14:25:37.632	  0:20.476	    Pool:0x7fffa406d700	SIP	Finalising handlers interface "10.34.130.146%em1"
>2014/02/25 14:25:37.632	  0:20.476	    Pool:0x7fffa406d700	OpalUDP	Setting interface to 10.34.130.146%em1
>2014/02/25 14:25:37.632	  0:20.476	    Pool:0x7fffa406d700	SIP	Completed OPTIONS command to sip:desktopqe@ekiga.net;OPAL-proxy=86.64.162.35;OPAL-interface=10.34.130.146%25em1, status=200 OK
>2014/02/25 14:25:37.632	  0:20.476	Opal Liste...ffd8116700	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:37.632	  0:20.476	    Pool:0x7fffa406d700	SIP	Product Info: name="Kamailio", version="", vendor="", comments="(1.5.3-notls (i386/linux))"
>2014/02/25 14:25:37.632	  0:20.476	    Pool:0x7fffa406d700	SIP	Changing OPTIONS handler from Unsubscribing to Unsubscribed, target=sip:desktopqe@ekiga.net, id=64d96bfb-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:37.632	  0:20.476	    Pool:0x7fffa406d700	SIP	OPTIONS transaction id=z9hG4bKbafb6bfb-8d9c-e311-86d5-3c970e8ed11e completed.
>2014/02/25 14:25:37.632	  0:20.476	    Pool:0x7fffa406d700	SIP	Handled PDU "8 OPTIONS <200>"
>2014/02/25 14:25:37.634	  0:20.478	Opal Liste...ffd8116700	OpalUDP	Binding to interface: 10.34.130.146:5060
>2014/02/25 14:25:37.634	  0:20.478	Opal Liste...ffd8116700	SIP	Waiting for PDU on udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:37.634	  0:20.478	Opal Liste...ffd8116700	SIP	PDU received: rem=udp$86.64.162.35:5060,local=udp$209.132.186.34:5060,if=10.34.130.146%em1
>SIP/2.0 200 OK
>CSeq: 9 OPTIONS
>Via: SIP/2.0/UDP 10.34.130.146:5060;branch=z9hG4bK0a516cfb-8d9c-e311-86d5-3c970e8ed11e;rport=5060
>Server: Kamailio (1.5.3-notls (i386/linux))
>From: <sip:desktopqe@ekiga.net>;tag=004c6cfb-8d9c-e311-86d5-3c970e8ed11e
>Call-ID: 02316cfb-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>To: <sip:qedesktop@ekiga.net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.2bd8
>Content-Length: 0
>
>
>2014/02/25 14:25:37.634	  0:20.478	Opal Liste...ffd8116700	SIP	Queueing PDU "9 OPTIONS <200>", transaction=z9hG4bK0a516cfb-8d9c-e311-86d5-3c970e8ed11e, token=02316cfb-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:37.634	  0:20.478	Opal Liste...ffd8116700	Opal	Transport clean up on termination
>2014/02/25 14:25:37.634	  0:20.478	    Pool:0x7fffa406d700	SIP	Handling PDU "9 OPTIONS <200>" for transaction=z9hG4bK0a516cfb-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:37.634	  0:20.478	Opal Liste...ffd8116700	Opal	Transport Close
>2014/02/25 14:25:37.634	  0:20.478	    Pool:0x7fffa406d700	SIP	OPTIONS transaction id=z9hG4bK0a516cfb-8d9c-e311-86d5-3c970e8ed11e completing.
>2014/02/25 14:25:37.634	  0:20.478	    Pool:0x7fffa406d700	SIP	Finalising handlers interface "10.34.130.146%em1"
>2014/02/25 14:25:37.634	  0:20.478	    Pool:0x7fffa406d700	OpalUDP	Setting interface to 10.34.130.146%em1
>2014/02/25 14:25:37.634	  0:20.478	    Pool:0x7fffa406d700	SIP	Completed OPTIONS command to sip:qedesktop@ekiga.net;OPAL-proxy=86.64.162.35;OPAL-local-id=sip:desktopqe%40ekiga.net;OPAL-interface=10.34.130.146%25em1, status=200 OK
>2014/02/25 14:25:37.634	  0:20.478	    Pool:0x7fffa406d700	SIP	Product Info: name="Kamailio", version="", vendor="", comments="(1.5.3-notls (i386/linux))"
>2014/02/25 14:25:37.634	  0:20.478	    Pool:0x7fffa406d700	SIP	Changing OPTIONS handler from Unsubscribing to Unsubscribed, target=sip:qedesktop@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net, id=02316cfb-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:37.634	  0:20.478	Opal Liste...ffd8116700	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:37.634	  0:20.478	    Pool:0x7fffa406d700	SIP	OPTIONS transaction id=z9hG4bK0a516cfb-8d9c-e311-86d5-3c970e8ed11e completed.
>2014/02/25 14:25:37.634	  0:20.478	    Pool:0x7fffa406d700	SIP	Handled PDU "9 OPTIONS <200>"
>2014/02/25 14:25:38.336	  0:21.180	Opal Garba...fff7e93700	Opal	Transport clean up on termination
>2014/02/25 14:25:38.336	  0:21.180	Opal Garba...fff7e93700	Opal	Transport Close
>2014/02/25 14:25:38.336	  0:21.180	Opal Garba...fff7e93700	Opal	Transport clean up on termination
>2014/02/25 14:25:38.336	  0:21.180	Opal Garba...fff7e93700	Opal	Transport Close
>2014/02/25 14:25:38.336	  0:21.180	Opal Garba...fff7e93700	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:38.337	  0:21.181	Opal Garba...fff7e93700	SIP	Destroyed OPTIONS handler for sip:desktopqe@ekiga.net
>2014/02/25 14:25:38.337	  0:21.181	Opal Garba...fff7e93700	Opal	Transport clean up on termination
>2014/02/25 14:25:38.337	  0:21.181	Opal Garba...fff7e93700	Opal	Transport Close
>2014/02/25 14:25:38.337	  0:21.181	Opal Garba...fff7e93700	Opal	Transport clean up on termination
>2014/02/25 14:25:38.337	  0:21.181	Opal Garba...fff7e93700	Opal	Transport Close
>2014/02/25 14:25:38.337	  0:21.181	Opal Garba...fff7e93700	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:38.337	  0:21.181	Opal Garba...fff7e93700	SIP	Destroyed OPTIONS handler for sip:qedesktop@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net
>2014/02/25 14:25:38.632	  0:21.477	Housekeepe...fff7f97700	SIP	Set state Terminated_Success for OPTIONS transaction id=z9hG4bKbafb6bfb-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:38.633	  0:21.477	Housekeepe...fff7f97700	SIP	Completed state for transaction id=z9hG4bKbafb6bfb-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:38.658	  0:21.502	Housekeepe...fff7f97700	SIP	Set state Terminated_Success for OPTIONS transaction id=z9hG4bK0a516cfb-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:38.658	  0:21.502	Housekeepe...fff7f97700	SIP	Completed state for transaction id=z9hG4bK0a516cfb-8d9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:39.338	  0:22.182	Opal Garba...fff7e93700	SIP	Transaction id=z9hG4bK0a516cfb-8d9c-e311-86d5-3c970e8ed11e destroyed.
>2014/02/25 14:25:39.338	  0:22.182	Opal Garba...fff7e93700	SIP	Transaction id=z9hG4bKbafb6bfb-8d9c-e311-86d5-3c970e8ed11e destroyed.
>2014/02/25 14:25:45.830	  0:28.674	                       	Ekiga	SubscribeToPresence for h323:qedesktop@spranto.net (fetch)
>2014/02/25 14:25:45.830	  0:28.674	                       	PWLib	Assertion fail: Null pointer reference, file /usr/include/ptlib/safecoll.h, line 877, Error=11
>Assertion fail: Null pointer reference, file /usr/include/ptlib/safecoll.h, line 877, Error=11
>
><A>bort, <C>ore dump, <I>gnore? 2014/02/25 14:25:47.371	  0:30.215	Housekeepe...fff7f97700	SIP	NAT Binding refresh for REGISTER to sip:desktopqe@ekiga.net, id=80f3c5f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:47.371	  0:30.216	Housekeepe...fff7f97700	SIP	Constructed OPTIONS handler for sip:desktopqe@ekiga.net
>2014/02/25 14:25:47.372	  0:30.216	Housekeepe...fff7f97700	SIP	Changing OPTIONS handler from Unavailable to Subscribed, target=sip:desktopqe@ekiga.net, id=c8bf6101-8e9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:47.372	  0:30.216	Housekeepe...fff7f97700	SIP	Executing state change to Unsubscribing for OPTIONS handler, target=sip:desktopqe@ekiga.net, id=c8bf6101-8e9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:47.372	  0:30.216	Housekeepe...fff7f97700	SIP	Changing OPTIONS handler from Subscribed to Unsubscribing, target=sip:desktopqe@ekiga.net, id=c8bf6101-8e9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:47.372	  0:30.216	Housekeepe...fff7f97700	OpalUDP	Binding to interface: 10.34.130.146:5060
>2014/02/25 14:25:47.372	  0:30.216	Housekeepe...fff7f97700	SIP	Created transport udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:47.372	  0:30.216	Housekeepe...fff7f97700	OpalUDP	Started connect to 86.64.162.35:5060
>2014/02/25 14:25:47.372	  0:30.216	Housekeepe...fff7f97700	SIP	Transaction created.
>2014/02/25 14:25:47.372	  0:30.216	Housekeepe...fff7f97700	SIP	Transaction remembering interface "10.34.130.146%em1"
>2014/02/25 14:25:47.372	  0:30.217	Housekeepe...fff7f97700	SIP	No SRV lookup as has explicit port number.
>2014/02/25 14:25:47.373	  0:30.217	Housekeepe...fff7f97700	SIP	Transaction remote address is udp$86.64.162.35:5060
>2014/02/25 14:25:47.373	  0:30.217	Housekeepe...fff7f97700	SIP	Sending PDU (622 bytes) to: rem=udp$86.64.162.35:5060,local=udp$209.132.186.34:5060,if=10.34.130.146%em1
>OPTIONS sip:desktopqe@ekiga.net SIP/2.0
>Route: <sip:86.64.162.35:5060;lr>
>CSeq: 10 OPTIONS
>Via: SIP/2.0/UDP 209.132.186.34:5060;branch=z9hG4bK9ade6101-8e9c-e311-86d5-3c970e8ed11e;rport
>User-Agent: Ekiga/4.0.1
>From: <sip:desktopqe@ekiga.net>;tag=fed96101-8e9c-e311-86d5-3c970e8ed11e
>Call-ID: c8bf6101-8e9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>To: <sip:desktopqe@ekiga.net>
>Accept: application/sdp, application/media_control+xml, application/dtmf, application/dtmf-relay
>Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK
>Expires: 0
>Content-Length: 0
>Max-Forwards: 70
>
>
>2014/02/25 14:25:47.373	  0:30.217	Housekeepe...fff7f97700	OpalUDP	Setting interface to 10.34.130.146%em1
>2014/02/25 14:25:47.373	  0:30.217	Housekeepe...fff7f97700	SIP	Transaction timers set: retry=0.500, completion=6.000
>2014/02/25 14:25:47.373	  0:30.217	Housekeepe...fff7f97700	SIP	NAT Binding refresh for SUBSCRIBE to sip:qedesktop@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net, id=e45729f4-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:47.373	  0:30.218	Housekeepe...fff7f97700	SIP	Constructed OPTIONS handler for sip:qedesktop@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net
>2014/02/25 14:25:47.374	  0:30.218	Housekeepe...fff7f97700	SIP	Changing OPTIONS handler from Unavailable to Subscribed, target=sip:qedesktop@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net, id=100e6201-8e9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:47.374	  0:30.218	Housekeepe...fff7f97700	SIP	Executing state change to Unsubscribing for OPTIONS handler, target=sip:qedesktop@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net, id=100e6201-8e9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:47.374	  0:30.218	Housekeepe...fff7f97700	SIP	Changing OPTIONS handler from Subscribed to Unsubscribing, target=sip:qedesktop@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net, id=100e6201-8e9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:47.374	  0:30.218	Housekeepe...fff7f97700	OpalUDP	Binding to interface: 10.34.130.146:5060
>2014/02/25 14:25:47.374	  0:30.218	Housekeepe...fff7f97700	SIP	Created transport udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:47.374	  0:30.218	Housekeepe...fff7f97700	OpalUDP	Started connect to 86.64.162.35:5060
>2014/02/25 14:25:47.374	  0:30.218	Housekeepe...fff7f97700	SIP	Transaction created.
>2014/02/25 14:25:47.374	  0:30.218	Housekeepe...fff7f97700	SIP	Transaction remembering interface "10.34.130.146%em1"
>2014/02/25 14:25:47.374	  0:30.218	Housekeepe...fff7f97700	SIP	No SRV lookup as has explicit port number.
>2014/02/25 14:25:47.374	  0:30.218	Housekeepe...fff7f97700	SIP	Transaction remote address is udp$86.64.162.35:5060
>2014/02/25 14:25:47.374	  0:30.218	Housekeepe...fff7f97700	SIP	Sending PDU (622 bytes) to: rem=udp$86.64.162.35:5060,local=udp$209.132.186.34:5060,if=10.34.130.146%em1
>OPTIONS sip:qedesktop@ekiga.net SIP/2.0
>Route: <sip:86.64.162.35:5060;lr>
>CSeq: 11 OPTIONS
>Via: SIP/2.0/UDP 209.132.186.34:5060;branch=z9hG4bKb4286201-8e9c-e311-86d5-3c970e8ed11e;rport
>User-Agent: Ekiga/4.0.1
>From: <sip:desktopqe@ekiga.net>;tag=22246201-8e9c-e311-86d5-3c970e8ed11e
>Call-ID: 100e6201-8e9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>To: <sip:qedesktop@ekiga.net>
>Accept: application/sdp, application/media_control+xml, application/dtmf, application/dtmf-relay
>Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK
>Expires: 0
>Content-Length: 0
>Max-Forwards: 70
>
>
>2014/02/25 14:25:47.375	  0:30.219	Housekeepe...fff7f97700	OpalUDP	Setting interface to 10.34.130.146%em1
>2014/02/25 14:25:47.375	  0:30.219	Housekeepe...fff7f97700	SIP	Transaction timers set: retry=0.500, completion=6.000
>2014/02/25 14:25:47.566	  0:30.410	Opal Liste...ffd8116700	OpalUDP	Binding to interface: 10.34.130.146:5060
>2014/02/25 14:25:47.566	  0:30.410	Opal Liste...ffd8116700	SIP	Waiting for PDU on udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:47.566	  0:30.410	Opal Liste...ffd8116700	SIP	PDU received: rem=udp$86.64.162.35:5060,local=udp$209.132.186.34:5060,if=10.34.130.146%em1
>SIP/2.0 200 OK
>CSeq: 10 OPTIONS
>Via: SIP/2.0/UDP 10.34.130.146:5060;branch=z9hG4bK9ade6101-8e9c-e311-86d5-3c970e8ed11e;rport=5060
>Server: Kamailio (1.5.3-notls (i386/linux))
>From: <sip:desktopqe@ekiga.net>;tag=fed96101-8e9c-e311-86d5-3c970e8ed11e
>Call-ID: c8bf6101-8e9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>To: <sip:desktopqe@ekiga.net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.2632
>Content-Length: 0
>
>
>2014/02/25 14:25:47.566	  0:30.410	Opal Liste...ffd8116700	SIP	Queueing PDU "10 OPTIONS <200>", transaction=z9hG4bK9ade6101-8e9c-e311-86d5-3c970e8ed11e, token=c8bf6101-8e9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:47.566	  0:30.410	Opal Liste...ffd8116700	Opal	Transport clean up on termination
>2014/02/25 14:25:47.566	  0:30.410	Opal Liste...ffd8116700	Opal	Transport Close
>2014/02/25 14:25:47.566	  0:30.411	    Pool:0x7fffa406d700	SIP	Handling PDU "10 OPTIONS <200>" for transaction=z9hG4bK9ade6101-8e9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:47.567	  0:30.411	    Pool:0x7fffa406d700	SIP	OPTIONS transaction id=z9hG4bK9ade6101-8e9c-e311-86d5-3c970e8ed11e completing.
>2014/02/25 14:25:47.567	  0:30.411	    Pool:0x7fffa406d700	SIP	Finalising handlers interface "10.34.130.146%em1"
>2014/02/25 14:25:47.567	  0:30.411	    Pool:0x7fffa406d700	OpalUDP	Setting interface to 10.34.130.146%em1
>2014/02/25 14:25:47.567	  0:30.411	Opal Liste...ffd8116700	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:47.567	  0:30.411	    Pool:0x7fffa406d700	SIP	Completed OPTIONS command to sip:desktopqe@ekiga.net;OPAL-proxy=86.64.162.35;OPAL-interface=10.34.130.146%25em1, status=200 OK
>2014/02/25 14:25:47.567	  0:30.411	    Pool:0x7fffa406d700	SIP	Product Info: name="Kamailio", version="", vendor="", comments="(1.5.3-notls (i386/linux))"
>2014/02/25 14:25:47.567	  0:30.411	    Pool:0x7fffa406d700	SIP	Changing OPTIONS handler from Unsubscribing to Unsubscribed, target=sip:desktopqe@ekiga.net, id=c8bf6101-8e9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:47.567	  0:30.411	    Pool:0x7fffa406d700	SIP	OPTIONS transaction id=z9hG4bK9ade6101-8e9c-e311-86d5-3c970e8ed11e completed.
>2014/02/25 14:25:47.567	  0:30.411	    Pool:0x7fffa406d700	SIP	Handled PDU "10 OPTIONS <200>"
>2014/02/25 14:25:47.573	  0:30.417	Opal Liste...ffd8116700	OpalUDP	Binding to interface: 10.34.130.146:5060
>2014/02/25 14:25:47.573	  0:30.417	Opal Liste...ffd8116700	SIP	Waiting for PDU on udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:47.573	  0:30.417	Opal Liste...ffd8116700	SIP	PDU received: rem=udp$86.64.162.35:5060,local=udp$209.132.186.34:5060,if=10.34.130.146%em1
>SIP/2.0 200 OK
>CSeq: 11 OPTIONS
>Via: SIP/2.0/UDP 10.34.130.146:5060;branch=z9hG4bKb4286201-8e9c-e311-86d5-3c970e8ed11e;rport=5060
>Server: Kamailio (1.5.3-notls (i386/linux))
>From: <sip:desktopqe@ekiga.net>;tag=22246201-8e9c-e311-86d5-3c970e8ed11e
>Call-ID: 100e6201-8e9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>To: <sip:qedesktop@ekiga.net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.2c15
>Content-Length: 0
>
>
>2014/02/25 14:25:47.573	  0:30.417	Opal Liste...ffd8116700	SIP	Queueing PDU "11 OPTIONS <200>", transaction=z9hG4bKb4286201-8e9c-e311-86d5-3c970e8ed11e, token=100e6201-8e9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:47.573	  0:30.417	Opal Liste...ffd8116700	Opal	Transport clean up on termination
>2014/02/25 14:25:47.573	  0:30.417	Opal Liste...ffd8116700	Opal	Transport Close
>2014/02/25 14:25:47.573	  0:30.417	    Pool:0x7fffa406d700	SIP	Handling PDU "11 OPTIONS <200>" for transaction=z9hG4bKb4286201-8e9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:47.573	  0:30.417	    Pool:0x7fffa406d700	SIP	OPTIONS transaction id=z9hG4bKb4286201-8e9c-e311-86d5-3c970e8ed11e completing.
>2014/02/25 14:25:47.573	  0:30.417	Opal Liste...ffd8116700	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:47.573	  0:30.417	    Pool:0x7fffa406d700	SIP	Finalising handlers interface "10.34.130.146%em1"
>2014/02/25 14:25:47.573	  0:30.417	    Pool:0x7fffa406d700	OpalUDP	Setting interface to 10.34.130.146%em1
>2014/02/25 14:25:47.573	  0:30.417	    Pool:0x7fffa406d700	SIP	Completed OPTIONS command to sip:qedesktop@ekiga.net;OPAL-proxy=86.64.162.35;OPAL-local-id=sip:desktopqe%40ekiga.net;OPAL-interface=10.34.130.146%25em1, status=200 OK
>2014/02/25 14:25:47.573	  0:30.417	    Pool:0x7fffa406d700	SIP	Product Info: name="Kamailio", version="", vendor="", comments="(1.5.3-notls (i386/linux))"
>2014/02/25 14:25:47.573	  0:30.417	    Pool:0x7fffa406d700	SIP	Changing OPTIONS handler from Unsubscribing to Unsubscribed, target=sip:qedesktop@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net, id=100e6201-8e9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:47.573	  0:30.417	    Pool:0x7fffa406d700	SIP	OPTIONS transaction id=z9hG4bKb4286201-8e9c-e311-86d5-3c970e8ed11e completed.
>2014/02/25 14:25:47.574	  0:30.418	    Pool:0x7fffa406d700	SIP	Handled PDU "11 OPTIONS <200>"
>2014/02/25 14:25:48.340	  0:31.184	Opal Garba...fff7e93700	Opal	Transport clean up on termination
>2014/02/25 14:25:48.340	  0:31.184	Opal Garba...fff7e93700	Opal	Transport Close
>2014/02/25 14:25:48.340	  0:31.184	Opal Garba...fff7e93700	Opal	Transport clean up on termination
>2014/02/25 14:25:48.340	  0:31.184	Opal Garba...fff7e93700	Opal	Transport Close
>2014/02/25 14:25:48.340	  0:31.184	Opal Garba...fff7e93700	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:48.340	  0:31.184	Opal Garba...fff7e93700	SIP	Destroyed OPTIONS handler for sip:desktopqe@ekiga.net
>2014/02/25 14:25:48.341	  0:31.185	Opal Garba...fff7e93700	Opal	Transport clean up on termination
>2014/02/25 14:25:48.341	  0:31.185	Opal Garba...fff7e93700	Opal	Transport Close
>2014/02/25 14:25:48.341	  0:31.185	Opal Garba...fff7e93700	Opal	Transport clean up on termination
>2014/02/25 14:25:48.341	  0:31.185	Opal Garba...fff7e93700	Opal	Transport Close
>2014/02/25 14:25:48.341	  0:31.185	Opal Garba...fff7e93700	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:48.341	  0:31.185	Opal Garba...fff7e93700	SIP	Destroyed OPTIONS handler for sip:qedesktop@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net
>2014/02/25 14:25:48.567	  0:31.411	Housekeepe...fff7f97700	SIP	Set state Terminated_Success for OPTIONS transaction id=z9hG4bK9ade6101-8e9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:48.567	  0:31.411	Housekeepe...fff7f97700	SIP	Completed state for transaction id=z9hG4bK9ade6101-8e9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:48.592	  0:31.437	Housekeepe...fff7f97700	SIP	Set state Terminated_Success for OPTIONS transaction id=z9hG4bKb4286201-8e9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:48.593	  0:31.437	Housekeepe...fff7f97700	SIP	Completed state for transaction id=z9hG4bKb4286201-8e9c-e311-86d5-3c970e8ed11e
>2014/02/25 14:25:48.876	  0:31.720	GkMonitor:...ffa40ef700	RAS	Time To Live reregistration
>2014/02/25 14:25:49.075	  0:31.919	GkMonitor:...ffa40ef700	OpalMan	Listener interfaces: associated transport=udp$209.132.186.34:5061
>    tcp$209.132.186.34:1720,tcp$10.34.130.146:1720
>2014/02/25 14:25:49.075	  0:31.919	GkMonitor:...ffa40ef700	Trans	Making request: registrationRequest
>2014/02/25 14:25:49.075	  0:31.919	GkMonitor:...ffa40ef700	H235RAS	Prepared PDU with authenticator MD5<active>
>2014/02/25 14:25:49.075	  0:31.919	GkMonitor:...ffa40ef700	Trans	Sending PDU:
>  registrationRequest {
>    requestSeqNum = 52310
>    protocolIdentifier = 0.0.8.2250.0.6
>    discoveryComplete = false
>    callSignalAddress = 2 entries {
>      [0]=ipAddress {
>        ip =  4 octets {
>          d1 84 ba 22                                        ..."
>        }
>        port = 1720
>      }
>      [1]=ipAddress {
>        ip =  4 octets {
>          0a 22 82 92                                        ."..
>        }
>        port = 1720
>      }
>    }
>    rasAddress = 1 entries {
>      [0]=ipAddress {
>        ip =  4 octets {
>          d1 84 ba 22                                        ..."
>        }
>        port = 5061
>      }
>    }
>    terminalType = {
>      vendor = {
>        vendor = {
>          t35CountryCode = 9
>          t35Extension = 0
>          manufacturerCode = 61
>        }
>        productId =  3 octets {
>          00 00 00                                           ...
>        }
>        versionId =  35 octets {
>          34 2e 30 2e 31 20 28 4f  50 41 4c 20 76 33 2e 31   4.0.1 (OPAL v3.1
>          30 2e 31 30 20 28 73 76  6e 3a 32 39 31 35 30 29   0.10 (svn:29150)
>          ...
>        }
>      }
>      terminal = {
>      }
>      mc = false
>      undefinedNode = false
>    }
>    terminalAlias = 4 entries {
>      [0]=h323_ID  9 characters {
>        0064 0065 0073 006b 0074 006f 0070 0071   desktopq
>        0065                                      e
>      }
>      [1]=h323_ID  21 characters {
>        0064 0065 0073 006b 0074 006f 0070 0071   desktopq
>        0065 0040 0073 0070 0072 0061 006e 0074   e@sprant
>        006f 002e 006e 0065 0074                  o.net
>      }
>      [2]=dialedDigits "1081333021"
>      [3]=dialedDigits "878401081333021"
>    }
>    gatekeeperIdentifier =  9 characters {
>      0068 0033 0032 0033 006e 0065 0074 005f   h323net_
>      0034                                      4
>    }
>    endpointVendor = {
>      vendor = {
>        t35CountryCode = 9
>        t35Extension = 0
>        manufacturerCode = 61
>      }
>      productId =  3 octets {
>        00 00 00                                           ...
>      }
>      versionId =  35 octets {
>        34 2e 30 2e 31 20 28 4f  50 41 4c 20 76 33 2e 31   4.0.1 (OPAL v3.1
>        30 2e 31 30 20 28 73 76  6e 3a 32 39 31 35 30 29   0.10 (svn:29150)
>        ...
>      }
>    }
>    timeToLive = 3600
>    cryptoTokens = 1 entries {
>      [0]=cryptoEPPwdHash {
>        alias = h323_ID  9 characters {
>          0064 0065 0073 006b 0074 006f 0070 0071   desktopq
>          0065                                      e
>        }
>        timeStamp = 1393334749
>        token = {
>          algorithmOID = 1.2.840.113549.2.5
>          paramS = {
>          }
>          hash = Hex:  a8 5a c1 6b df 13 4c 6c  bf 82 e5 38 a7 87 8d 74 
>        }
>      }
>    }
>    keepAlive = true
>    endpointIdentifier =  9 characters {
>      0035 0031 0030 0035 005f 0065 006e 0064   5105_end
>      0070                                      p
>    }
>    willSupplyUUIEs = true
>    maintainConnection = false
>    supportsAltGK = <<null>>
>    usageReportingCapability = {
>      nonStandardUsageTypes = 0 entries {
>      }
>      startTime = <<null>>
>      endTime = <<null>>
>      terminationCause = <<null>>
>    }
>    callCreditCapability = {
>      canEnforceDurationLimit = true
>    }
>  }
>2014/02/25 14:25:49.075	  0:31.919	GkMonitor:...ffa40ef700	Trans	Waiting on response to seqnum=52310 for 3.0 seconds
>2014/02/25 14:25:49.341	  0:32.186	Opal Garba...fff7e93700	SIP	Transaction id=z9hG4bK9ade6101-8e9c-e311-86d5-3c970e8ed11e destroyed.
>2014/02/25 14:25:49.342	  0:32.186	Opal Garba...fff7e93700	SIP	Transaction id=z9hG4bKb4286201-8e9c-e311-86d5-3c970e8ed11e destroyed.
>2014/02/25 14:25:49.390	  0:32.234	Transactor...ffa40ae700	H225RAS	Receiving PDU:
>  registrationConfirm {
>    requestSeqNum = 52310
>    protocolIdentifier = 0.0.8.2250.0.6
>    callSignalAddress = 1 entries {
>      [0]=ipAddress {
>        ip =  4 octets {
>          17 17 98 ca                                        ....
>        }
>        port = 1720
>      }
>    }
>    terminalAlias = 4 entries {
>      [0]=url_ID "desktopqe@spranto.net"
>      [1]=dialedDigits "1081333021"
>      [2]=dialedDigits "878401081333021"
>      [3]=h323_ID  9 characters {
>        0064 0065 0073 006b 0074 006f 0070 0071   desktopq
>        0065                                      e
>      }
>    }
>    gatekeeperIdentifier =  9 characters {
>      0068 0033 0032 0033 006e 0065 0074 005f   h323net_
>      0034                                      4
>    }
>    endpointIdentifier =  9 characters {
>      0035 0031 0030 0035 005f 0065 006e 0064   5105_end
>      0070                                      p
>    }
>    timeToLive = 30
>    willRespondToIRR = false
>    maintainConnection = false
>    supportsAdditiveRegistration = <<null>>
>  }
>2014/02/25 14:25:49.390	  0:32.234	Transactor...ffa40ae700	Trans	Using credentials from request: H235AnnexD_Procedure1<disabled>,CAT<disabled>,MD5<active>
>2014/02/25 14:25:49.390	  0:32.234	Transactor...ffa40ae700	RAS	Registered 5105_endp with h323net_4
>2014/02/25 14:25:53.045	  0:35.889	Housekeepe...fff7f97700	SIP	Starting SUBSCRIBE for offline retry
>2014/02/25 14:25:53.045	  0:35.889	Housekeepe...fff7f97700	SIP	Changing SUBSCRIBE handler from Unavailable to Restoring, target=sip:desktopqe@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net, id=c017c6f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:53.045	  0:35.889	Housekeepe...fff7f97700	DNS	SRV Lookup "_sip._tcp.ekiga.net"
>2014/02/25 14:25:53.443	  0:36.287	Housekeepe...fff7f97700	DNS	Query failed: error=-1
>2014/02/25 14:25:53.443	  0:36.287	Housekeepe...fff7f97700	SIP	No SRV record found.
>2014/02/25 14:25:53.443	  0:36.287	Housekeepe...fff7f97700	SIP	No compatible listener to create transport for tcp$ekiga.net:5060
>2014/02/25 14:25:53.443	  0:36.287	Housekeepe...fff7f97700	SIP	Changing SUBSCRIBE handler from Restoring to Unavailable, target=sip:desktopqe@ekiga.net;OPAL-local-id=sip:desktopqe%40ekiga.net, id=c017c6f2-8d9c-e311-86d5-3c970e8ed11e@msimon.brq.redhat.com
>2014/02/25 14:25:53.443	  0:36.287	Housekeepe...fff7f97700	SIP	Retrying SUBSCRIBE after 30 seconds.
>2014/02/25 14:25:54.547	  0:37.391	Opal Liste...ffd8116700	OpalUDP	Binding to interface: 10.34.130.146:5060
>2014/02/25 14:25:54.547	  0:37.391	Opal Liste...ffd8116700	SIP	Waiting for PDU on udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>2014/02/25 14:25:54.548	  0:37.392	Opal Liste...ffd8116700	SIP	PDU received: rem=udp$86.64.162.35:5060,local=udp$209.132.186.34:5060,if=10.34.130.146%em1
>NOTIFY sip:desktopqe@10.34.130.146 SIP/2.0
>CSeq: 10 NOTIFY
>Via: SIP/2.0/UDP 86.64.162.35;branch=z9hG4bKeb3e.1f1e21f2.0
>User-Agent: Kamailio (1.5.3-notls (i386/linux))
>From: sip:qedesktop@ekiga.net;tag=f85b0bd16aaafa8479586ac9f88b3198-b20f
>Call-ID: ba7200bc-8a9c-e311-85f0-3c970e8ed11e@msimon.brq.redhat.com
>To: sip:desktopqe@ekiga.net;tag=108600bc-8a9c-e311-85f0-3c970e8ed11e
>Contact: <sip:86.64.162.35:5060>
>Subscription-State: terminated;reason=timeout
>Event: presence
>Content-Length: 0
>Max-Forwards: 70
>
>
>2014/02/25 14:25:54.548	  0:37.392	Opal Liste...ffd8116700	SIP	Received NOTIFY presence
>2014/02/25 14:25:54.548	  0:37.392	Opal Liste...ffd8116700	SIP	Could not find a SUBSCRIBE corresponding to the NOTIFY presence
>2014/02/25 14:25:54.549	  0:37.393	Opal Liste...ffd8116700	SIP	Sending PDU (401 bytes) to: rem=udp$86.64.162.35:5060,local=udp$209.132.186.34:5060,if=10.34.130.146%em1
>SIP/2.0 481 Call Leg/Transaction Does Not Exist
>CSeq: 10 NOTIFY
>Via: SIP/2.0/UDP 86.64.162.35;branch=z9hG4bKeb3e.1f1e21f2.0
>From: sip:qedesktop@ekiga.net;tag=f85b0bd16aaafa8479586ac9f88b3198-b20f
>Call-ID: ba7200bc-8a9c-e311-85f0-3c970e8ed11e@msimon.brq.redhat.com
>To: sip:desktopqe@ekiga.net;tag=108600bc-8a9c-e311-85f0-3c970e8ed11e
>Contact: <sip:desktopqe@209.132.186.34>
>Content-Length: 0
>
>
>2014/02/25 14:25:54.549	  0:37.393	Opal Liste...ffd8116700	OpalUDP	Setting interface to 10.34.130.146%em1
>2014/02/25 14:25:54.549	  0:37.393	Opal Liste...ffd8116700	Opal	Transport clean up on termination
>2014/02/25 14:25:54.549	  0:37.393	Opal Liste...ffd8116700	Opal	Transport Close
>2014/02/25 14:25:54.549	  0:37.393	Opal Liste...ffd8116700	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$209.132.186.34:5060>
>
>Dumping core.
>[Thread 0x7fffa5c8d700 (LWP 24072) exited]
>[Thread 0x7fffa7c91700 (LWP 24067) exited]
>[Thread 0x7fffa7490700 (LWP 24068) exited]
>[Thread 0x7fffa648e700 (LWP 24071) exited]
>[Thread 0x7fffd8094700 (LWP 24065) exited]
>[Thread 0x7fffd8116700 (LWP 24063) exited]
>[New Thread 0x7fffd8116700 (LWP 24083)]
>[Thread 0x7fffd80d5700 (LWP 24064) exited]
>[New Thread 0x7fffd80d5700 (LWP 24084)]
>[New Thread 0x7fffd00f1700 (LWP 24085)]
>[New Thread 0x7fffd00b0700 (LWP 24086)]
>[New Thread 0x7fffd006f700 (LWP 24087)]
>[New Thread 0x7fffa40ef700 (LWP 24088)]
>[New Thread 0x7fffa40ae700 (LWP 24089)]
>[Thread 0x7fffd00f1700 (LWP 24085) exited]
>[Thread 0x7fffd00b0700 (LWP 24086) exited]
>[New Thread 0x7fffa406d700 (LWP 24090)]
>[New Thread 0x7fff840d8700 (LWP 24091)]
>[Thread 0x7fffa548c700 (LWP 24073) exited]
>
>Program received signal SIGABRT, Aborted.
>0x00007ffff1e8800b in raise () from /lib64/libpthread.so.0
>Missing separate debuginfos, use: debuginfo-install GConf2-3.2.6-8.el7.x86_64 PackageKit-gtk3-module-0.8.9-10.el7.x86_64 SDL-1.2.15-11.el7.x86_64 adwaita-gtk2-theme-3.8.4-3.el7.x86_64 alsa-lib-1.0.27.2-3.el7.x86_64 atk-2.8.0-4.el7.x86_64 boost-signals-1.53.0-16.el7.x86_64 cairo-1.12.14-6.el7.x86_64 cyrus-sasl-gssapi-2.1.26-17.el7.x86_64 cyrus-sasl-lib-2.1.26-17.el7.x86_64 cyrus-sasl-md5-2.1.26-17.el7.x86_64 cyrus-sasl-plain-2.1.26-17.el7.x86_64 cyrus-sasl-scram-2.1.26-17.el7.x86_64 dbus-glib-0.100-7.el7.x86_64 dbus-libs-1.6.12-8.el7.x86_64 dconf-0.16.0-5.el7.x86_64 evolution-data-server-3.8.5-21.el7.x86_64 expat-2.1.0-8.el7.x86_64 flac-libs-1.3.0-4.el7.x86_64 fontconfig-2.10.95-7.el7.x86_64 freetype-2.4.11-9.el7.x86_64 gcr-3.8.2-4.el7.x86_64 gdk-pixbuf2-2.28.2-4.el7.x86_64 glib2-2.36.3-5.el7.x86_64 glibc-2.17-48.el7.x86_64 gsm-1.0.13-11.el7.x86_64 gtk2-2.24.22-5.el7.x86_64 gvfs-1.16.4-6.el7.x86_64 harfbuzz-0.9.20-3.el7.x86_64 ibus-gtk2-1.5.3-11.el7.x86_64 ibus-libs-1.5.3-11.el7.x86_64 json-c-0.11-3.el7.x86_64 keyutils-libs-1.5.8-3.el7.x86_64 krb5-libs-1.11.3-47.el7.x86_64 libICE-1.0.8-7.el7.x86_64 libSM-1.2.1-7.el7.x86_64 libX11-1.6.0-2.1.el7.x86_64 libXau-1.0.8-2.1.el7.x86_64 libXcomposite-0.4.4-4.1.el7.x86_64 libXcursor-1.1.14-2.1.el7.x86_64 libXdamage-1.1.4-4.1.el7.x86_64 libXext-1.3.2-2.1.el7.x86_64 libXfixes-5.0.1-2.1.el7.x86_64 libXi-1.7.2-2.1.el7.x86_64 libXinerama-1.1.3-2.1.el7.x86_64 libXrandr-1.4.1-2.1.el7.x86_64 libXrender-0.9.8-2.1.el7.x86_64 libXtst-1.2.2-2.1.el7.x86_64 libXv-1.0.9-2.1.el7.x86_64 libXxf86vm-1.1.3-2.1.el7.x86_64 libasyncns-0.8-7.el7.x86_64 libattr-2.4.46-12.el7.x86_64 libbluray-0.2.3-5.el7.x86_64 libcanberra-0.30-5.el7.x86_64 libcanberra-gtk2-0.30-5.el7.x86_64 libcap-2.22-8.el7.x86_64 libcom_err-1.42.9-3.el7.x86_64 libdb-5.3.21-17.el7.x86_64 libdrm-2.4.50-1.1.el7.x86_64 libffi-3.0.13-11.el7.x86_64 libgcc-4.8.2-14.el7.x86_64 libgcrypt-1.5.3-4.el7.x86_64 libgpg-error-1.12-3.el7.x86_64 libjpeg-turbo-1.2.90-5.el7.x86_64 libnotify-0.7.5-7.el7.x86_64 libogg-1.3.0-7.el7.x86_64 libpng-1.5.13-5.el7.x86_64 libsecret-0.15-3.el7.x86_64 libselinux-2.2.2-4.el7.x86_64 libsndfile-1.0.25-9.el7.x86_64 libsoup-2.42.2-3.el7.x86_64 libstdc++-4.8.2-14.el7.x86_64 libtdb-1.2.12-3.el7.x86_64 libtheora-1.1.1-8.el7.x86_64 libtool-ltdl-2.4.2-20.el7.x86_64 libuuid-2.23.2-13.el7.x86_64 libv4l-0.9.5-4.el7.x86_64 libvorbis-1.3.3-8.el7.x86_64 libxcb-1.9-5.el7.x86_64 libxml2-2.9.1-5.el7.x86_64 mesa-libEGL-9.2.5-4.20131218.el7.x86_64 mesa-libGL-9.2.5-4.20131218.el7.x86_64 mesa-libgbm-9.2.5-4.20131218.el7.x86_64 mesa-libglapi-9.2.5-4.20131218.el7.x86_64 nspr-4.10.2-4.el7.x86_64 nss-3.15.4-5.el7.x86_64 nss-softokn-freebl-3.15.4-2.el7.x86_64 nss-util-3.15.4-2.el7.x86_64 opal-3.10.10-4.el7.x86_64 openldap-2.4.39-2.el7.x86_64 openssl-libs-1.0.1e-33.el7.x86_64 p11-kit-0.18.7-4.el7.x86_64 pango-1.34.1-5.el7.x86_64 pcre-8.32-12.el7.x86_64 pixman-0.32.4-3.el7.x86_64 ptlib-2.10.10-5.el7.x86_64 pulseaudio-libs-3.0-22.el7.x86_64 speex-1.2-0.19.rc1.el7.x86_64 sqlite-3.7.17-4.el7.x86_64 systemd-libs-208-4.el7.x86_64 tcp_wrappers-libs-7.6-77.el7.x86_64 xz-libs-5.1.2-8alpha.el7.x86_64 zlib-1.2.7-13.el7.x86_64
>(gdb) quit
>A debugging session is active.
>
>	Inferior 1 [process 24048] will be killed.
>
>Quit anyway? (y or n)

Actions: View

Attachments on bug 1069663: 867419 | 868916 | 872637