 on Unix Linux (3.8.0-31-generic-x86_64) with PTLib (v2.10.10 (svn:29149)) at 2013/10/29 18:18:31.527
2013/10/29 18:18:31.532	  0:00.005	                       	PTLib	Maximum per-process file handles is 1024
2013/10/29 18:18:31.532	  0:00.005	                       	PLUGIN	Enumerating plugin directory /usr/lib/ptlib-2.10.10/
2013/10/29 18:18:31.533	  0:00.005	                       	PLUGIN	Enumerating plugin directory /usr/lib/ptlib-2.10.10/devices/
2013/10/29 18:18:31.533	  0:00.005	                       	PLUGIN	Enumerating plugin directory /usr/lib/ptlib-2.10.10/devices/videoinput/
2013/10/29 18:18:31.533	  0:00.006	                       	UDLL	opening /usr/lib/ptlib-2.10.10/devices/videoinput/v4l2_pwplugin.so
2013/10/29 18:18:31.535	  0:00.008	                       	PLUGIN	Enumerating plugin directory /usr/lib/ptlib-2.10.10/devices/sound/
2013/10/29 18:18:31.535	  0:00.008	                       	UDLL	opening /usr/lib/ptlib-2.10.10/devices/sound/alsa_pwplugin.so
2013/10/29 18:18:31.537	  0:00.009	                       	UDLL	opening /usr/lib/ptlib-2.10.10/devices/sound/pulse_pwplugin.so
2013/10/29 18:18:31.565	  0:00.038	                       	UDLL	opening /usr/lib/ptlib-2.10.10/devices/sound/oss_pwplugin.so
2013/10/29 18:18:31.566	  0:00.039	                       	PLUGIN	Enumerating plugin directory /usr/lib/opal-3.10.10/
2013/10/29 18:18:31.566	  0:00.039	                       	PLUGIN	Enumerating plugin directory /usr/lib/opal-3.10.10/fax/
2013/10/29 18:18:31.566	  0:00.039	                       	UDLL	opening /usr/lib/opal-3.10.10/fax/spandsp_ptplugin.so
2013/10/29 18:18:31.570	  0:00.043	                       	PLUGIN	Enumerating plugin directory /usr/lib/opal-3.10.10/codecs/
2013/10/29 18:18:31.570	  0:00.043	                       	PLUGIN	Enumerating plugin directory /usr/lib/opal-3.10.10/codecs/audio/
2013/10/29 18:18:31.570	  0:00.043	                       	UDLL	opening /usr/lib/opal-3.10.10/codecs/audio/lpc10_ptplugin.so
2013/10/29 18:18:31.570	  0:00.043	                       	UDLL	opening /usr/lib/opal-3.10.10/codecs/audio/g7222_ptplugin.so
2013/10/29 18:18:31.571	  0:00.044	                       	UDLL	opening /usr/lib/opal-3.10.10/codecs/audio/g726_ptplugin.so
2013/10/29 18:18:31.571	  0:00.044	                       	UDLL	opening /usr/lib/opal-3.10.10/codecs/audio/iLBC_ptplugin.so
2013/10/29 18:18:31.572	  0:00.045	                       	UDLL	opening /usr/lib/opal-3.10.10/codecs/audio/g7221_ptplugin.so
2013/10/29 18:18:31.572	  0:00.045	                       	UDLL	opening /usr/lib/opal-3.10.10/codecs/audio/gsmamrcodec_ptplugin.so
2013/10/29 18:18:31.573	  0:00.046	                       	UDLL	opening /usr/lib/opal-3.10.10/codecs/audio/g722_ptplugin.so
2013/10/29 18:18:31.573	  0:00.046	                       	UDLL	opening /usr/lib/opal-3.10.10/codecs/audio/speex_ptplugin.so
2013/10/29 18:18:31.573	  0:00.046	                       	UDLL	opening /usr/lib/opal-3.10.10/codecs/audio/gsm0610_ptplugin.so
2013/10/29 18:18:31.574	  0:00.047	                       	UDLL	opening /usr/lib/opal-3.10.10/codecs/audio/ima_adpcm_ptplugin.so
2013/10/29 18:18:31.574	  0:00.047	                       	UDLL	opening /usr/lib/opal-3.10.10/codecs/audio/silk_ptplugin.so
2013/10/29 18:18:31.575	  0:00.048	                       	PLUGIN	Enumerating plugin directory /usr/lib/opal-3.10.10/codecs/video/
2013/10/29 18:18:31.576	  0:00.048	                       	UDLL	opening /usr/lib/opal-3.10.10/codecs/video/h261_vic_ptplugin.so
2013/10/29 18:18:31.576	  0:00.049	                       	UDLL	opening /usr/lib/opal-3.10.10/codecs/video/theora_ptplugin.so
2013/10/29 18:18:31.577	  0:00.050	                       	UDLL	opening /usr/lib/opal-3.10.10/codecs/video/h264_x264_ptplugin.so
2013/10/29 18:18:31.578	  0:00.051	                       	UDLL	opening /usr/lib/opal-3.10.10/codecs/video/mpeg4_ffmpeg_ptplugin.so
2013/10/29 18:18:31.579	  0:00.052	                       	UDLL	opening /usr/lib/opal-3.10.10/codecs/video/h263_ffmpeg_ptplugin.so
2013/10/29 18:18:31.581	  0:00.054	                       	OpalPlugin	Using default handler for plugin codec spandsp_ptplugin
2013/10/29 18:18:31.582	  0:00.054	                       	OpalPlugin	Creating new media format T.38
2013/10/29 18:18:31.582	  0:00.055	                       	OpalPlugin	Creating new media format TIFF-File
2013/10/29 18:18:31.583	  0:00.055	                       	OpalPlugin	Using default handler for plugin codec lpc10_ptplugin
2013/10/29 18:18:31.583	  0:00.055	                       	OpalPlugin	Creating new media format LPC-10
2013/10/29 18:18:31.583	  0:00.056	                       	OpalPlugin	Using default handler for plugin codec g7222_ptplugin
2013/10/29 18:18:31.583	  0:00.056	                       	OpalPlugin	Creating new media format G.722.2
2013/10/29 18:18:31.584	  0:00.056	                       	OpalPlugin	Using default handler for plugin codec g726_ptplugin
2013/10/29 18:18:31.584	  0:00.056	                       	OpalPlugin	Creating new media format G.726-40k
2013/10/29 18:18:31.584	  0:00.057	                       	OpalPlugin	Creating new media format G.726-32k
2013/10/29 18:18:31.584	  0:00.057	                       	OpalPlugin	Creating new media format G.726-24k
2013/10/29 18:18:31.585	  0:00.057	                       	OpalPlugin	Creating new media format G.726-16k
2013/10/29 18:18:31.585	  0:00.058	                       	OpalPlugin	Using default handler for plugin codec iLBC_ptplugin
2013/10/29 18:18:31.585	  0:00.058	                       	OpalPlugin	Creating new media format iLBC
2013/10/29 18:18:31.586	  0:00.058	                       	OpalPlugin	Creating new media format iLBC-13k3
2013/10/29 18:18:31.586	  0:00.059	                       	OpalPlugin	Creating new media format iLBC-15k2
2013/10/29 18:18:31.587	  0:00.060	                       	OpalPlugin	Using default handler for plugin codec g7221_ptplugin
2013/10/29 18:18:31.587	  0:00.060	                       	OpalPlugin	Creating new media format G.722.1-24k
2013/10/29 18:18:31.588	  0:00.060	                       	OpalPlugin	Creating new media format G.722.1-32k
2013/10/29 18:18:31.588	  0:00.061	                       	OpalPlugin	Using default handler for plugin codec gsmamrcodec_ptplugin
2013/10/29 18:18:31.588	  0:00.061	                       	OpalPlugin	Creating new media format GSM-AMR
2013/10/29 18:18:31.589	  0:00.062	                       	OpalPlugin	Using default handler for plugin codec g722_ptplugin
2013/10/29 18:18:31.589	  0:00.062	                       	OpalPlugin	Creating new media format G.722-64k
2013/10/29 18:18:31.589	  0:00.062	                       	OpalPlugin	Using default handler for plugin codec speex_ptplugin
2013/10/29 18:18:31.589	  0:00.062	                       	OpalPlugin	Creating new media format SpeexIETFNarrow-5.95k
2013/10/29 18:18:31.590	  0:00.063	                       	OpalPlugin	Creating new media format SpeexIETFNarrow-8k
2013/10/29 18:18:31.591	  0:00.064	                       	OpalPlugin	Creating new media format SpeexIETFNarrow-11k
2013/10/29 18:18:31.592	  0:00.064	                       	OpalPlugin	Creating new media format SpeexIETFNarrow-15k
2013/10/29 18:18:31.592	  0:00.065	                       	OpalPlugin	Creating new media format SpeexIETFNarrow-18.2k
2013/10/29 18:18:31.593	  0:00.066	                       	OpalPlugin	Creating new media format SpeexIETFNarrow-24.6k
2013/10/29 18:18:31.594	  0:00.067	                       	OpalPlugin	Creating new media format SpeexIETFWide-20.6k
2013/10/29 18:18:31.595	  0:00.068	                       	OpalPlugin	Creating new media format SpeexWNarrow-8k
2013/10/29 18:18:31.596	  0:00.069	                       	OpalPlugin	Creating new media format SpeexWide-20.6k
2013/10/29 18:18:31.597	  0:00.070	                       	OpalPlugin	Creating new media format SpeexNB
2013/10/29 18:18:31.598	  0:00.071	                       	OpalPlugin	Creating new media format SpeexWB
2013/10/29 18:18:31.599	  0:00.072	                       	OpalPlugin	Using default handler for plugin codec gsm0610_ptplugin
2013/10/29 18:18:31.599	  0:00.072	                       	OpalPlugin	Creating new media format GSM-06.10
2013/10/29 18:18:31.599	  0:00.072	                       	OpalPlugin	Creating new media format MS-GSM
2013/10/29 18:18:31.600	  0:00.073	                       	OpalPlugin	Using default handler for plugin codec ima_adpcm_ptplugin
2013/10/29 18:18:31.600	  0:00.073	                       	OpalPlugin	Creating new media format MS-IMA-ADPCM
2013/10/29 18:18:31.600	  0:00.073	                       	OpalPlugin	Using default handler for plugin codec silk_ptplugin
2013/10/29 18:18:31.600	  0:00.073	                       	Plugin	Started logging.
2013/10/29 18:18:31.600	  0:00.073	                       	OpalPlugin	Creating new media format SILK-8
2013/10/29 18:18:31.601	  0:00.074	                       	Plugin	Started logging.
2013/10/29 18:18:31.601	  0:00.074	                       	Plugin	Started logging.
2013/10/29 18:18:31.601	  0:00.074	                       	OpalPlugin	Creating new media format SILK-16
2013/10/29 18:18:31.601	  0:00.074	                       	Plugin	Started logging.
2013/10/29 18:18:31.601	  0:00.074	                       	OpalPlugin	Using default handler for plugin codec h261_vic_ptplugin
2013/10/29 18:18:31.601	  0:00.074	                       	OpalPlugin	Creating new media format H.261
2013/10/29 18:18:31.602	  0:00.075	                       	OpalPlugin	Creating H.261 plugin capability
2013/10/29 18:18:31.602	  0:00.075	                       	OpalPlugin	Creating H.261 plugin capability
2013/10/29 18:18:31.602	  0:00.075	                       	OpalPlugin	Using default handler for plugin codec theora_ptplugin
2013/10/29 18:18:31.602	  0:00.075	                       	Plugin	Started logging.
2013/10/29 18:18:31.602	  0:00.075	                       	OpalPlugin	Creating new media format theora
2013/10/29 18:18:31.603	  0:00.076	                       	OpalPlugin	Using default handler for plugin codec h264_x264_ptplugin
2013/10/29 18:18:31.604	  0:00.076	                       	Plugin	Started logging.
2013/10/29 18:18:31.604	  0:00.076	                       	OpalPlugin	Creating new media format H.264
2013/10/29 18:18:31.604	  0:00.077	                       	Plugin	Started logging.
2013/10/29 18:18:31.604	  0:00.077	                       	Plugin	Started logging.
2013/10/29 18:18:31.604	  0:00.077	                       	OpalPlugin	Creating new media format H.264-1
2013/10/29 18:18:31.605	  0:00.078	                       	Plugin	Started logging.
2013/10/29 18:18:31.605	  0:00.078	                       	Plugin	Started logging.
2013/10/29 18:18:31.605	  0:00.078	                       	OpalPlugin	Creating new media format H.264-0
2013/10/29 18:18:31.606	  0:00.078	                       	Plugin	Started logging.
2013/10/29 18:18:31.612	  0:00.085	                       	OpalPlugin	Using default handler for plugin codec mpeg4_ffmpeg_ptplugin
2013/10/29 18:18:31.612	  0:00.085	                       	Plugin	Started logging.
2013/10/29 18:18:31.612	  0:00.085	                       	OpalPlugin	Creating new media format MPEG4
2013/10/29 18:18:31.618	  0:00.091	                       	OpalPlugin	Not adding H.323 capability for plugin codec MPEG4 as this has been specifically disabled
2013/10/29 18:18:31.618	  0:00.091	                       	OpalPlugin	Using default handler for plugin codec h263_ffmpeg_ptplugin
2013/10/29 18:18:31.618	  0:00.091	                       	Plugin	Started logging.
2013/10/29 18:18:31.618	  0:00.091	                       	OpalPlugin	Creating new media format H.263plus
2013/10/29 18:18:31.619	  0:00.092	                       	OpalPlugin	Creating H.263 plugin capability
2013/10/29 18:18:31.620	  0:00.092	                       	OpalPlugin	Creating H.263 plugin capability
2013/10/29 18:18:31.620	  0:00.093	                       	Plugin	Started logging.
2013/10/29 18:18:31.620	  0:00.093	                       	OpalPlugin	Creating new media format H.263
2013/10/29 18:18:31.620	  0:00.093	                       	OpalPlugin	Creating H.263 plugin capability
2013/10/29 18:18:31.620	  0:00.093	                       	OpalPlugin	Creating H.263 plugin capability
2013/10/29 18:18:31.623	  0:00.096	                       	PWLib	File handle high water mark set: 14 Thread unblock pipe
2013/10/29 18:18:31.623	  0:00.096	                       	PWLib	File handle high water mark set: 16 Thread unblock pipe
2013/10/29 18:18:31.623	  0:00.096	                       	PTLib	No permission to set priority level 4
2013/10/29 18:18:31.623	  0:00.096	                       	PTLib	Thread high water mark set: 2
2013/10/29 18:18:31.623	  0:00.096	                       	PTLib	No permission to set priority level 4
2013/10/29 18:18:31.623	  0:00.096	                       	PTLib	Thread high water mark set: 3
2013/10/29 18:18:31.623	  0:00.096	                       	PWLib	File handle high water mark set: 21 Thread unblock pipe
2013/10/29 18:18:31.623	  0:00.096	                       	PTLib	No permission to set priority level 4
2013/10/29 18:18:31.623	  0:00.096	                       	PTLib	Thread high water mark set: 4
2013/10/29 18:18:31.637	  0:00.110	                       	PWLib	File handle high water mark set: 23 Thread unblock pipe
2013/10/29 18:18:31.638	  0:00.111	                       	PTLib	No permission to set priority level 4
2013/10/29 18:18:31.638	  0:00.111	                       	PTLib	Thread high water mark set: 5
2013/10/29 18:18:31.643	  0:00.116	                       	HalManager_dbus	Initialising HAL Manager
2013/10/29 18:18:31.654	  0:00.127	                       	HalManager_dbus	Populating device list
2013/10/29 18:18:31.654	  0:00.127	                       	HalManager_dbus	Populating full device list failed - The name org.freedesktop.Hal was not provided by any .service files
2013/10/29 18:18:31.655	  0:00.128	                       	HalManager_dbus	Populating interface list
2013/10/29 18:18:31.655	  0:00.128	                       	HalManager_dbus	Populating full interface list failed - Method "getDevices" with signature "" on interface "org.freedesktop.NetworkManager" doesn't exist

2013/10/29 18:18:31.656	  0:00.129	                       	PV4L2Plugin	Detecting V4L2 devices
2013/10/29 18:18:31.656	  0:00.129	                       	PV4L2Plugin	detected device metadata at /sys/class/video4linux/
2013/10/29 18:18:31.677	  0:00.150	                       	PWLib	File handle high water mark set: 29 Thread unblock pipe
2013/10/29 18:18:31.677	  0:00.150	                       	PTLib	Thread high water mark set: 6
2013/10/29 18:18:31.677	  0:00.150	                       	OpalMan	Created manager.
2013/10/29 18:18:31.678	  0:00.150	                       	PV4L2Plugin	Detecting V4L2 devices
2013/10/29 18:18:31.678	  0:00.150	                       	PV4L2Plugin	detected device metadata at /sys/class/video4linux/
2013/10/29 18:18:31.693	  0:00.166	                       	OpalMan	Attached endpoint with prefix pc
2013/10/29 18:18:31.693	  0:00.166	                       	OpalEP	Created endpoint: pc
2013/10/29 18:18:31.693	  0:00.166	                       	LocalEP	Created endpoint.
2013/10/29 18:18:31.767	  0:00.240	                       	PCSS	Created PC sound system endpoint.
Players:
alsa_output.pci-0000_02_00.1.hdmi-stereo
alsa_output.pci-0000_00_1b.0.analog-stereo
Null Audio
Default
PulseAudio
HDA Intel PCH [ALC889 Analog]
HDA Intel PCH [ALC889 Digital]
EKIGA
HDA NVidia [HDMI 0]
HDA NVidia [HDMI 1]
HDA NVidia [HDMI 2]
HDA NVidia [HDMI 3]
*.wav
Recorders:
Null Audio
Default
PulseAudio
alsa_input.pci-0000_00_1b.0.analog-stereo
alsa_input.usb-046d_0825_26E84B50-02-U0x46d0x825.analog-mono
HDA Intel PCH [ALC889 Analog]
HDA Intel PCH [ALC889 Analog] (1)
EKIGA
*.wav
USB Device 0x46d:0x825 [USB Audio]

2013/10/29 18:18:31.786	  0:00.258	                       	OPAL	SetMediaFormatOrder()
2013/10/29 18:18:31.786	  0:00.258	                       	OPAL	SetMediaFormatMask()
2013/10/29 18:18:31.786	  0:00.259	                       	OpalMan	Attached endpoint with prefix sip
2013/10/29 18:18:31.786	  0:00.259	                       	OpalEP	Created endpoint: sip
2013/10/29 18:18:31.787	  0:00.259	                       	PWLib	File handle high water mark set: 30 PUDPSocket
2013/10/29 18:18:31.787	  0:00.260	                       	IfaceMon	Initial interface list:
127.0.0.1 <00-00-00-00-00-00> (lo)
192.168.178.35 <F8-1A-67-0D-97-2B> (wlan0)

2013/10/29 18:18:31.787	  0:00.260	                       	PTLIB	Opened NetLink socket
2013/10/29 18:18:31.787	  0:00.260	                       	PWLib	File handle high water mark set: 34 Thread unblock pipe
2013/10/29 18:18:31.787	  0:00.260	                       	PTLib	Thread high water mark set: 7
2013/10/29 18:18:31.788	  0:00.260	                       	OpalMan	Attached endpoint with prefix sips
2013/10/29 18:18:31.788	  0:00.260	                       	SIP	Created endpoint.
2013/10/29 18:18:31.788	  0:00.261	                       	PWLib	File handle high water mark set: 35 PUDPSocket
2013/10/29 18:18:31.788	  0:00.261	                       	MonSock	Created socket bundle for all interfaces.
2013/10/29 18:18:31.788	  0:00.261	                       	PWLib	File handle high water mark set: 36 PUDPSocket
2013/10/29 18:18:31.788	  0:00.261	                       	MonSock	Created bundled UDP socket 192.168.178.35:5060
2013/10/29 18:18:31.788	  0:00.261	                       	PWLib	File handle high water mark set: 38 Thread unblock pipe
2013/10/29 18:18:31.788	  0:00.261	                       	PTLib	Thread high water mark set: 8
2013/10/29 18:18:31.788	  0:00.261	                       	PTLib	No permission to set priority level 4
2013/10/29 18:18:31.788	  0:00.261	                       	OpalMan	Added route "sip:.*=pc:*"
2013/10/29 18:18:31.788	  0:00.261	                       	OpalMan	Added route "pc:.*=sip:<da>"
2013/10/29 18:18:31.801	  0:00.274	                       	OpalMan	Attached endpoint with prefix h323
2013/10/29 18:18:31.801	  0:00.274	                       	OpalEP	Created endpoint: h323
2013/10/29 18:18:31.801	  0:00.274	Opal Liste...ff09dcd700	Listen	Started listening thread on udp$*:5060
2013/10/29 18:18:31.801	  0:00.274	Network In...ff2007a700	IfaceMon	Started interface monitor thread.
2013/10/29 18:18:31.801	  0:00.274	                       	OpalMan	Attached endpoint with prefix h323s
2013/10/29 18:18:31.801	  0:00.274	                       	H323	Created endpoint.
2013/10/29 18:18:31.801	  0:00.274	                       	PWLib	File handle high water mark set: 39 PTCPSocket
2013/10/29 18:18:31.801	  0:00.274	                       	PWLib	File handle high water mark set: 41 Thread unblock pipe
2013/10/29 18:18:31.802	  0:00.274	                       	PTLib	Thread high water mark set: 9
2013/10/29 18:18:31.802	  0:00.274	                       	OpalMan	Added route "h323:.*=pc:*"
2013/10/29 18:18:31.802	  0:00.275	                       	OpalMan	Added route "pc:.*=h323:<da>"
2013/10/29 18:18:31.803	  0:00.275	                       	PWLib	File handle high water mark set: 42 PUDPSocket
2013/10/29 18:18:31.803	  0:00.276	                       	OpalMan	Added presentity for SIP_Presentity
2013/10/29 18:18:31.803	  0:00.276	                       	Created presentity for sip:473936@sip.diamondcard.us
2013/10/29 18:18:31.803	  0:00.276	                       	OpalMan	Added presentity for SIP_Presentity
2013/10/29 18:18:31.803	  0:00.276	                       	Created presentity for sip:el_gallo_azul@ekiga.net
2013/10/29 18:18:31.805	  0:00.278	Opal Liste...ff09d8c700	Listen	Started listening thread on tcp$*:1720
2013/10/29 18:18:31.805	  0:00.278	Opal Liste...ff09d8c700	Listen	Waiting on socket accept on tcp$*:1720
2013/10/29 18:18:31.836	  0:00.309	                       	MediaFormat	Removing codecs SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,theora,H.261,H.263,H.263plus,H.264,H.264-0,H.264-1,MPEG4,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
2013/10/29 18:18:31.837	  0:00.310	                       	OPAL	SetMediaFormatMask(G.722-64k,G.722.1-24k,G.722.1-32k,G.722.2,SILK-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SILK-8,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,T.38,TIFF-File,iLBC,iLBC-13k3,iLBC-15k2,RFC4175_YCbCr-4:2:0,T.140,MSRP,SIP-IM,NamedSignalEvent)
2013/10/29 18:18:31.837	  0:00.310	                       	OPAL	SetMediaFormatOrder(SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,theora,H.261,H.263,H.263plus,H.264,H.264-0,H.264-1,MPEG4,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)
2013/10/29 18:18:31.865	  0:00.338	                       	MediaFormat	Removing codecs SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,theora,H.261,H.263,H.263plus,H.264,H.264-0,H.264-1,MPEG4,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
2013/10/29 18:18:31.866	  0:00.339	                       	OPAL	SetMediaFormatMask(G.722-64k,G.722.1-24k,G.722.1-32k,G.722.2,SILK-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SILK-8,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,T.38,TIFF-File,iLBC,iLBC-13k3,iLBC-15k2,RFC4175_YCbCr-4:2:0,T.140,MSRP,SIP-IM,NamedSignalEvent)
2013/10/29 18:18:31.866	  0:00.339	                       	OPAL	SetMediaFormatOrder(SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,theora,H.261,H.263,H.263plus,H.264,H.264-0,H.264-1,MPEG4,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)
2013/10/29 18:18:31.869	  0:00.341	                       	OpalConfBridge	maximum_video_tx_bitrate set to 64kb/s
2013/10/29 18:18:31.891	  0:00.364	                       	Ekiga	Nat binding delay set to 10
2013/10/29 18:18:31.891	  0:00.364	                       	Ekiga	Sent its own presence (publish) for sip:473936@sip.diamondcard.us: available, note I'm available using Ekiga
2013/10/29 18:18:31.891	  0:00.364	                       	Ekiga	Sent its own presence (publish) for sip:el_gallo_azul@ekiga.net: available, note I'm available using Ekiga
2013/10/29 18:18:31.899	  0:00.372	                       	PWLib	File handle high water mark set: 43 Thread unblock pipe
2013/10/29 18:18:31.899	  0:00.372	                       	Ekiga	Started STUN detector
2013/10/29 18:18:31.899	  0:00.372	                       	PTLib	Thread high water mark set: 10
2013/10/29 18:18:31.901	  0:00.374	StunDetect...ff09d4b700	PWLib	File handle high water mark set: 44 PUDPSocket
2013/10/29 18:18:31.901	  0:00.374	StunDetect...ff09d4b700	PWLib	File handle high water mark set: 45 PUDPSocket
2013/10/29 18:18:31.959	  0:00.432	                       	AudioOutputCore	Detected Device: Default (PTLIB/ALSA)
2013/10/29 18:18:31.959	  0:00.432	                       	AudioOutputCore	Detected Device: HDA Intel PCH [ALC889 Analog] (PTLIB/ALSA)
2013/10/29 18:18:31.959	  0:00.432	                       	AudioOutputCore	Detected Device: HDA Intel PCH [ALC889 Digital] (PTLIB/ALSA)
2013/10/29 18:18:31.960	  0:00.432	                       	AudioOutputCore	Detected Device: HDA NVidia [HDMI 0] (PTLIB/ALSA)
2013/10/29 18:18:31.960	  0:00.432	                       	AudioOutputCore	Detected Device: HDA NVidia [HDMI 1] (PTLIB/ALSA)
2013/10/29 18:18:31.960	  0:00.432	                       	AudioOutputCore	Detected Device: HDA NVidia [HDMI 2] (PTLIB/ALSA)
2013/10/29 18:18:31.960	  0:00.432	                       	AudioOutputCore	Detected Device: HDA NVidia [HDMI 3] (PTLIB/ALSA)
2013/10/29 18:18:31.960	  0:00.432	                       	AudioOutputCore	Detected Device: PulseAudio (PTLIB/Pulse)
2013/10/29 18:18:31.960	  0:00.433	                       	AudioOutputCore	Detected Device: alsa_output.pci-0000_02_00.1.hdmi-stereo (PTLIB/Pulse)
2013/10/29 18:18:31.960	  0:00.433	                       	AudioOutputCore	Detected Device: alsa_output.pci-0000_00_1b.0.analog-stereo (PTLIB/Pulse)
2013/10/29 18:18:32.076	  0:00.549	                       	AudioOutputCore	Detected Device: Default (PTLIB/ALSA)
2013/10/29 18:18:32.076	  0:00.549	                       	AudioOutputCore	Detected Device: HDA Intel PCH [ALC889 Analog] (PTLIB/ALSA)
2013/10/29 18:18:32.076	  0:00.549	                       	AudioOutputCore	Detected Device: HDA Intel PCH [ALC889 Digital] (PTLIB/ALSA)
2013/10/29 18:18:32.076	  0:00.549	                       	AudioOutputCore	Detected Device: HDA NVidia [HDMI 0] (PTLIB/ALSA)
2013/10/29 18:18:32.076	  0:00.549	                       	AudioOutputCore	Detected Device: HDA NVidia [HDMI 1] (PTLIB/ALSA)
2013/10/29 18:18:32.076	  0:00.549	                       	AudioOutputCore	Detected Device: HDA NVidia [HDMI 2] (PTLIB/ALSA)
2013/10/29 18:18:32.076	  0:00.549	                       	AudioOutputCore	Detected Device: HDA NVidia [HDMI 3] (PTLIB/ALSA)
2013/10/29 18:18:32.076	  0:00.549	                       	AudioOutputCore	Detected Device: PulseAudio (PTLIB/Pulse)
2013/10/29 18:18:32.076	  0:00.549	                       	AudioOutputCore	Detected Device: alsa_output.pci-0000_02_00.1.hdmi-stereo (PTLIB/Pulse)
2013/10/29 18:18:32.076	  0:00.549	                       	AudioOutputCore	Detected Device: alsa_output.pci-0000_00_1b.0.analog-stereo (PTLIB/Pulse)
2013/10/29 18:18:32.096	  0:00.568	                       	AudioInputCore	Detected Device: Default (PTLIB/ALSA)
2013/10/29 18:18:32.096	  0:00.569	                       	AudioInputCore	Detected Device: HDA Intel PCH [ALC889 Analog] (PTLIB/ALSA)
2013/10/29 18:18:32.096	  0:00.569	                       	AudioInputCore	Detected Device: HDA Intel PCH [ALC889 Analog] (1) (PTLIB/ALSA)
2013/10/29 18:18:32.096	  0:00.569	                       	AudioInputCore	Detected Device: USB Device 0x46d:0x825 [USB Audio] (PTLIB/ALSA)
2013/10/29 18:18:32.096	  0:00.569	                       	AudioInputCore	Detected Device: PulseAudio (PTLIB/Pulse)
2013/10/29 18:18:32.096	  0:00.569	                       	AudioInputCore	Detected Device: alsa_input.pci-0000_00_1b.0.analog-stereo (PTLIB/Pulse)
2013/10/29 18:18:32.096	  0:00.569	                       	AudioInputCore	Detected Device: alsa_input.usb-046d_0825_26E84B50-02-U0x46d0x825.analog-mono (PTLIB/Pulse)
2013/10/29 18:18:32.104	  0:00.577	                       	PV4L2Plugin	Detecting V4L2 devices
2013/10/29 18:18:32.104	  0:00.577	                       	PV4L2Plugin	detected device metadata at /sys/class/video4linux/
2013/10/29 18:18:32.115	  0:00.588	                       	VidInputCore	Detected Device: Moving Logo (Moving Logo/Moving Logo)
2013/10/29 18:18:32.115	  0:00.588	                       	VidInputCore	Detected Device: UVC Camera (046d:0825) (PTLIB/V4L2)
2013/10/29 18:18:32.209	  0:00.682	                       	VideoOutputCoreConfBridge	Updating video view
2013/10/29 18:18:32.212	  0:00.685	                       	VideoOutputCoreConfBridge	Updating zoom
2013/10/29 18:18:32.212	  0:00.685	                       	VideoOutputCoreConfBridge	Updating Video Settings
2013/10/29 18:18:32.216	  0:00.689	                       	VideoOutputCoreConfBridge	Updating Video Settings
2013/10/29 18:18:32.216	  0:00.689	                       	VideoOutputCoreConfBridge	Updating Video Settings
2013/10/29 18:18:32.216	  0:00.689	                       	VideoOutputCoreConfBridge	Updating Video Settings
2013/10/29 18:18:32.216	  0:00.689	                       	VidInputCoreConfBridge	Updating preview size and fps
2013/10/29 18:18:32.216	  0:00.689	                       	VidInputCore	Setting new preview config: 176x144/30
2013/10/29 18:18:32.221	  0:00.694	                       	VidInputCoreConfBridge	Updating preview size and fps
2013/10/29 18:18:32.221	  0:00.694	                       	VidInputCore	Setting new preview config: 176x144/30
2013/10/29 18:18:32.225	  0:00.698	                       	PV4L2Plugin	Detecting V4L2 devices
2013/10/29 18:18:32.225	  0:00.698	                       	PV4L2Plugin	detected device metadata at /sys/class/video4linux/
2013/10/29 18:18:32.237	  0:00.709	                       	VidInputCore	Detected Device: Moving Logo (Moving Logo/Moving Logo)
2013/10/29 18:18:32.237	  0:00.709	                       	VidInputCore	Detected Device: UVC Camera (046d:0825) (PTLIB/V4L2)
2013/10/29 18:18:32.237	  0:00.710	                       	VidInputCoreConfBridge	Updating device
2013/10/29 18:18:32.237	  0:00.710	                       	VidInputCore	Setting device: UVC Camera (046d:0825) (PTLIB/V4L2)
2013/10/29 18:18:32.237	  0:00.710	                       	GMVideoInputManager_ptlib	Setting Device UVC Camera (046d:0825) (PTLIB/V4L2)
2013/10/29 18:18:32.237	  0:00.710	                       	PV4L2Plugin	Detecting V4L2 devices
2013/10/29 18:18:32.237	  0:00.710	                       	PV4L2Plugin	detected device metadata at /sys/class/video4linux/
2013/10/29 18:18:32.248	  0:00.721	                       	VidInputCore	Detected Device: Moving Logo (Moving Logo/Moving Logo)
2013/10/29 18:18:32.248	  0:00.721	                       	VidInputCore	Detected Device: UVC Camera (046d:0825) (PTLIB/V4L2)
2013/10/29 18:18:32.248	  0:00.721	                       	VidInputCoreConfBridge	Updating device
2013/10/29 18:18:32.248	  0:00.721	                       	VidInputCore	Setting device: UVC Camera (046d:0825) (PTLIB/V4L2)
2013/10/29 18:18:32.248	  0:00.721	                       	GMVideoInputManager_ptlib	Setting Device UVC Camera (046d:0825) (PTLIB/V4L2)
2013/10/29 18:18:32.248	  0:00.721	                       	PV4L2Plugin	Detecting V4L2 devices
2013/10/29 18:18:32.249	  0:00.721	                       	PV4L2Plugin	detected device metadata at /sys/class/video4linux/
2013/10/29 18:18:32.260	  0:00.732	                       	VidInputCore	Detected Device: Moving Logo (Moving Logo/Moving Logo)
2013/10/29 18:18:32.260	  0:00.733	                       	VidInputCore	Detected Device: UVC Camera (046d:0825) (PTLIB/V4L2)
2013/10/29 18:18:32.260	  0:00.733	                       	VidInputCoreConfBridge	Updating device
2013/10/29 18:18:32.260	  0:00.733	                       	VidInputCore	Setting device: UVC Camera (046d:0825) (PTLIB/V4L2)
2013/10/29 18:18:32.260	  0:00.733	                       	GMVideoInputManager_ptlib	Setting Device UVC Camera (046d:0825) (PTLIB/V4L2)
2013/10/29 18:18:32.260	  0:00.733	                       	VidInputCoreConfBridge	Updating image
2013/10/29 18:18:32.268	  0:00.741	                       	AudioOutputCore	Detected Device: Default (PTLIB/ALSA)
2013/10/29 18:18:32.268	  0:00.741	                       	AudioOutputCore	Detected Device: HDA Intel PCH [ALC889 Analog] (PTLIB/ALSA)
2013/10/29 18:18:32.268	  0:00.741	                       	AudioOutputCore	Detected Device: HDA Intel PCH [ALC889 Digital] (PTLIB/ALSA)
2013/10/29 18:18:32.268	  0:00.741	                       	AudioOutputCore	Detected Device: HDA NVidia [HDMI 0] (PTLIB/ALSA)
2013/10/29 18:18:32.268	  0:00.741	                       	AudioOutputCore	Detected Device: HDA NVidia [HDMI 1] (PTLIB/ALSA)
2013/10/29 18:18:32.268	  0:00.741	                       	AudioOutputCore	Detected Device: HDA NVidia [HDMI 2] (PTLIB/ALSA)
2013/10/29 18:18:32.268	  0:00.741	                       	AudioOutputCore	Detected Device: HDA NVidia [HDMI 3] (PTLIB/ALSA)
2013/10/29 18:18:32.268	  0:00.741	                       	AudioOutputCore	Detected Device: PulseAudio (PTLIB/Pulse)
2013/10/29 18:18:32.268	  0:00.741	                       	AudioOutputCore	Detected Device: alsa_output.pci-0000_02_00.1.hdmi-stereo (PTLIB/Pulse)
2013/10/29 18:18:32.268	  0:00.741	                       	AudioOutputCore	Detected Device: alsa_output.pci-0000_00_1b.0.analog-stereo (PTLIB/Pulse)
2013/10/29 18:18:32.268	  0:00.741	                       	AudioOutputCoreConfBridge	Set device to ALSA/Default
2013/10/29 18:18:32.268	  0:00.741	                       	AudioOutputCore	Setting device[0]: Default (PTLIB/ALSA)
2013/10/29 18:18:32.268	  0:00.741	                       	GMAudioOutputManager_ptlib	Setting Device[0] Default (PTLIB/ALSA)
2013/10/29 18:18:32.268	  0:00.741	                       	AudioOutputCoreConfBridge	Updating device
2013/10/29 18:18:32.268	  0:00.741	                       	AudioOutputCore	Setting device[1]: Default (PTLIB/ALSA)
2013/10/29 18:18:32.279	  0:00.752	                       	AudioInputCore	Detected Device: Default (PTLIB/ALSA)
2013/10/29 18:18:32.279	  0:00.752	                       	AudioInputCore	Detected Device: HDA Intel PCH [ALC889 Analog] (PTLIB/ALSA)
2013/10/29 18:18:32.279	  0:00.752	                       	AudioInputCore	Detected Device: HDA Intel PCH [ALC889 Analog] (1) (PTLIB/ALSA)
2013/10/29 18:18:32.279	  0:00.752	                       	AudioInputCore	Detected Device: USB Device 0x46d:0x825 [USB Audio] (PTLIB/ALSA)
2013/10/29 18:18:32.279	  0:00.752	                       	AudioInputCore	Detected Device: PulseAudio (PTLIB/Pulse)
2013/10/29 18:18:32.279	  0:00.752	                       	AudioInputCore	Detected Device: alsa_input.pci-0000_00_1b.0.analog-stereo (PTLIB/Pulse)
2013/10/29 18:18:32.279	  0:00.752	                       	AudioInputCore	Detected Device: alsa_input.usb-046d_0825_26E84B50-02-U0x46d0x825.analog-mono (PTLIB/Pulse)
2013/10/29 18:18:32.279	  0:00.752	                       	AudioInputCore	Setting device: Default (PTLIB/ALSA)
2013/10/29 18:18:32.280	  0:00.752	                       	GMAudioInputManager_ptlib	Setting Device Default (PTLIB/ALSA)
2013/10/29 18:18:32.280	  0:00.752	                       	AudioInputCore	Set device to ALSA/Default
2013/10/29 18:18:32.321	  0:00.794	                       	Ekiga version 4.0.1
2013/10/29 18:18:32.322	  0:00.794	                       	PTLIB version 2.10.10
2013/10/29 18:18:32.322	  0:00.794	                       	OPAL version 3.10.10
2013/10/29 18:18:32.322	  0:00.795	                       	Accelerated rendering support enabled
2013/10/29 18:18:32.322	  0:00.795	                       	DBUS support enabled
2013/10/29 18:18:32.322	  0:00.795	                       	GConf support enabled
2013/10/29 18:18:32.373	  0:00.845	StunDetect...ff09d4b700	PWLib	File handle high water mark set: 50 PUDPSocket
2013/10/29 18:18:32.797	  0:01.270	StunDetect...ff09d4b700	OPAL	STUN server "stun.ekiga.net" replies Cone NAT, external IP 118.211.230.192
2013/10/29 18:18:32.797	  0:01.270	StunDetect...ff09d4b700	PWLib	File handle low water mark set: 45 PTextFile
2013/10/29 18:18:32.797	  0:01.270	StunDetect...ff09d4b700	PTLib	Thread ended: name="StunDetector:0x7eff09d4b700", real=0.896, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%)
2013/10/29 18:18:33.308	  0:01.781	                       	Listen	Stopping listening thread on udp$*:5060
2013/10/29 18:18:33.308	  0:01.781	                       	MonSock	Closed UDP socket 0x1a27420
2013/10/29 18:18:33.308	  0:01.781	Opal Liste...ff09dcd700	Listen	UDP read error.
2013/10/29 18:18:33.308	  0:01.781	Opal Liste...ff09dcd700	PWLib	File handle low water mark set: 36 PTextFile
2013/10/29 18:18:33.308	  0:01.781	Opal Liste...ff09dcd700	PTLib	Thread ended: name="Opal Listener:0x7eff09dcd700", real=1.507, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%)
2013/10/29 18:18:33.329	  0:01.801	                       	MonSock	Deleting UDP socket 0x1a27420
2013/10/29 18:18:33.329	  0:01.802	Housekeepe...ff368ea700	Ekiga	Stopped STUN detector
2013/10/29 18:18:33.329	  0:01.802	                       	PWLib	File handle low water mark set: 35 PUDPSocket
2013/10/29 18:18:33.329	  0:01.802	                       	MonSock	Created socket bundle for all interfaces.
2013/10/29 18:18:33.329	  0:01.802	                       	STUN	Failed to bind to local UDP port in range 5060-5060
2013/10/29 18:18:33.329	  0:01.802	                       	MonSock	Created bundled UDP socket 192.168.178.35:5060
2013/10/29 18:18:33.329	  0:01.802	                       	PTLib	No permission to set priority level 4
2013/10/29 18:18:33.329	  0:01.802	Opal Liste...ff09dcd700	Listen	Started listening thread on udp$*:5060
2013/10/29 18:18:33.329	  0:01.802	                       	Listen	Stopping listening thread on tcp$*:1720
2013/10/29 18:18:33.330	  0:01.802	Opal Liste...ff09d8c700	PTLib	Thread ended: name="Opal Listener:0x7eff09d8c700", real=1.524, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%)
2013/10/29 18:18:33.330	  0:01.803	Opal Liste...ff09d8c700	Listen	Started listening thread on tcp$*:1720
2013/10/29 18:18:33.330	  0:01.803	Opal Liste...ff09d8c700	Listen	Waiting on socket accept on tcp$*:1720
2013/10/29 18:18:33.330	  0:01.803	subscriber...fecb26c700	OpalPres	'sip:473936@sip.diamondcard.us' opening.
2013/10/29 18:18:33.330	  0:01.803	subscriber...fecb26c700	SIPPres	Using sip:473936@sip.diamondcard.us as presence server for sip:473936@sip.diamondcard.us
2013/10/29 18:18:33.330	  0:01.803	subscriber...fecb26c700	PTLib	Thread high water mark set: 11
2013/10/29 18:18:33.330	  0:01.803	PThreadObj...fecb22b700	OpalPres	Command thread started
2013/10/29 18:18:33.330	  0:01.803	subscriber...fecb26c700	OpalPres	Creating presentity command 'SIP_Presentity21SIPWatcherInfoCommand'
2013/10/29 18:18:33.330	  0:01.803	PThreadObj...fecb22b700	SIPPres	'sip:473936@sip.diamondcard.us' sending subscribe for own presence.watcherinfo
2013/10/29 18:18:33.330	  0:01.803	subscriber...fecb26c700	SIP	Start REGISTER
          aor=473936@sip.diamondcard.us
       remote=sip.diamondcard.us
        local=
      contact=
        proxy=
       authID=473936
        realm=
       expire=3600
      restore=30
     minRetry=default
     maxRetry=default
compatibility=FullyCompliant
2013/10/29 18:18:33.331	  0:01.803	                       	PWLib	File handle high water mark set: 52 Thread unblock pipe
2013/10/29 18:18:33.331	  0:01.803	PThreadObj...fecb22b700	SIP	Start SUBSCRIBE
 eventPackage=presence.winfo
          aor=sip:473936@sip.diamondcard.us
       remote=sip:473936@sip.diamondcard.us;transport=tcp
        local=sip:473936@sip.diamondcard.us
      contact=
        proxy=
       authID=473936
        realm=
       expire=300
      restore=30
     minRetry=default
     maxRetry=default
2013/10/29 18:18:33.331	  0:01.804	                       	PTLib	Thread high water mark set: 12
2013/10/29 18:18:33.331	  0:01.804	subscriber...fecb1ea700	OpalPres	'sip:el_gallo_azul@ekiga.net' opening.
2013/10/29 18:18:33.331	  0:01.804	subscriber...fecb1ea700	SIPPres	Using sip:el_gallo_azul@ekiga.net as presence server for sip:el_gallo_azul@ekiga.net
2013/10/29 18:18:33.331	  0:01.804	subscriber...fecb1ea700	PWLib	File handle high water mark set: 54 Thread unblock pipe
2013/10/29 18:18:33.331	  0:01.804	subscriber...fecb1ea700	PTLib	Thread high water mark set: 13
2013/10/29 18:18:33.331	  0:01.804	subscriber...fecb1ea700	OpalPres	Creating presentity command 'SIP_Presentity21SIPWatcherInfoCommand'
2013/10/29 18:18:33.331	  0:01.804	PThreadObj...fecb1a9700	OpalPres	Command thread started
2013/10/29 18:18:33.331	  0:01.804	subscriber...fecb26c700	SIP	Constructed REGISTER handler for sip:473936@sip.diamondcard.us
2013/10/29 18:18:33.331	  0:01.804	subscriber...fecb1ea700	SIP	Start REGISTER
          aor=el_gallo_azul@ekiga.net
       remote=ekiga.net
        local=
      contact=
        proxy=
       authID=el_gallo_azul
        realm=
       expire=3600
      restore=30
     minRetry=default
     maxRetry=default
compatibility=FullyCompliant
2013/10/29 18:18:33.331	  0:01.804	PThreadObj...fecb1a9700	SIPPres	'sip:el_gallo_azul@ekiga.net' sending subscribe for own presence.watcherinfo
2013/10/29 18:18:33.331	  0:01.804	PThreadObj...fecb22b700	SIP	Constructed SUBSCRIBE handler for sip:473936@sip.diamondcard.us;OPAL-local-id=sip:473936%40sip.diamondcard.us
2013/10/29 18:18:33.332	  0:01.804	PThreadObj...fecb1a9700	SIP	Start SUBSCRIBE
 eventPackage=presence.winfo
          aor=sip:el_gallo_azul@ekiga.net
       remote=sip:el_gallo_azul@ekiga.net;transport=tcp
        local=sip:el_gallo_azul@ekiga.net
      contact=
        proxy=
       authID=el_gallo_azul
        realm=
       expire=300
      restore=30
     minRetry=default
     maxRetry=default
2013/10/29 18:18:33.332	  0:01.804	subscriber...fecb26c700	SIP	Executing state change to Subscribing for REGISTER handler, target=sip:473936@sip.diamondcard.us, id=2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:33.332	  0:01.804	PThreadObj...fecb22b700	SIP	Executing state change to Subscribing for SUBSCRIBE handler, target=sip:473936@sip.diamondcard.us;OPAL-local-id=sip:473936%40sip.diamondcard.us, id=cc6f908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:33.332	  0:01.805	subscriber...fecb26c700	SIP	Changing REGISTER handler from Unavailable to Subscribing, target=sip:473936@sip.diamondcard.us, id=2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:33.332	  0:01.805	subscriber...fecb26c700	DNS	SRV Lookup "_sip._udp.sip.diamondcard.us"
2013/10/29 18:18:33.332	  0:01.805	PThreadObj...fecb22b700	SIP	Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:473936@sip.diamondcard.us;OPAL-local-id=sip:473936%40sip.diamondcard.us, id=cc6f908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:33.332	  0:01.805	PThreadObj...fecb22b700	DNS	SRV Lookup "_sip._tcp.sip.diamondcard.us"
2013/10/29 18:18:33.337	  0:01.810	subscriber...fecb1ea700	SIP	Constructed REGISTER handler for sip:el_gallo_azul@ekiga.net
2013/10/29 18:18:33.337	  0:01.810	subscriber...fecb1ea700	SIP	Executing state change to Subscribing for REGISTER handler, target=sip:el_gallo_azul@ekiga.net, id=c043918f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:33.337	  0:01.810	PThreadObj...fecb1a9700	SIP	Constructed SUBSCRIBE handler for sip:el_gallo_azul@ekiga.net;OPAL-local-id=sip:el_gallo_azul%40ekiga.net
2013/10/29 18:18:33.337	  0:01.810	subscriber...fecb1ea700	SIP	Changing REGISTER handler from Unavailable to Subscribing, target=sip:el_gallo_azul@ekiga.net, id=c043918f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:33.337	  0:01.810	subscriber...fecb1ea700	DNS	SRV Lookup "_sip._udp.ekiga.net"
2013/10/29 18:18:33.337	  0:01.810	PThreadObj...fecb1a9700	SIP	Executing state change to Subscribing for SUBSCRIBE handler, target=sip:el_gallo_azul@ekiga.net;OPAL-local-id=sip:el_gallo_azul%40ekiga.net, id=d452918f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:33.338	  0:01.810	PThreadObj...fecb1a9700	SIP	Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:el_gallo_azul@ekiga.net;OPAL-local-id=sip:el_gallo_azul%40ekiga.net, id=d452918f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:33.338	  0:01.811	PThreadObj...fecb1a9700	DNS	SRV Lookup "_sip._tcp.ekiga.net"
2013/10/29 18:18:33.340	  0:01.813	subscriber...fecb26c700	SIP	Attempting SRV record entry 0: 46.19.58.41:5060
2013/10/29 18:18:33.340	  0:01.813	subscriber...fecb26c700	OpalUDP	Binding to interface: 0.0.0.0:5060
2013/10/29 18:18:33.340	  0:01.813	subscriber...fecb26c700	PWLib	File handle high water mark set: 55 PUDPSocket
2013/10/29 18:18:33.340	  0:01.813	subscriber...fecb26c700	SIP	Created transport udp$46.19.58.41:5060<if=udp$*:5060>
2013/10/29 18:18:33.340	  0:01.813	subscriber...fecb26c700	OpalUDP	Started connect to 46.19.58.41:5060
2013/10/29 18:18:33.341	  0:01.813	subscriber...fecb26c700	OpalUDP	Writing to interface 0 - "192.168.178.35%wlan0"
2013/10/29 18:18:33.341	  0:01.814	subscriber...fecb26c700	PWLib	File handle high water mark set: 56 PUDPSocket
2013/10/29 18:18:33.341	  0:01.814	subscriber...fecb26c700	OpalMan	Listener interfaces: associated transport=udp$192.168.178.35:5060
    udp$118.211.230.192:5060,udp$192.168.178.35:5060
2013/10/29 18:18:33.342	  0:01.815	subscriber...fecb26c700	SIP	Transaction created.
2013/10/29 18:18:33.342	  0:01.815	subscriber...fecb26c700	SIP	Transaction remembering interface "192.168.178.35%wlan0"
2013/10/29 18:18:33.343	  0:01.815	subscriber...fecb26c700	DNS	SRV Lookup "_sip._udp.sip.diamondcard.us"
2013/10/29 18:18:33.349	  0:01.822	PThreadObj...fecb22b700	DNS	Query failed: error=-1
2013/10/29 18:18:33.349	  0:01.822	PThreadObj...fecb22b700	SIP	No SRV record found.
2013/10/29 18:18:33.349	  0:01.822	PThreadObj...fecb22b700	SIP	No compatible listener to create transport for tcp$sip.diamondcard.us:5060
2013/10/29 18:18:33.349	  0:01.822	PThreadObj...fecb22b700	SIP	Changing SUBSCRIBE handler from Subscribing to Unavailable, target=sip:473936@sip.diamondcard.us;OPAL-local-id=sip:473936%40sip.diamondcard.us, id=cc6f908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:33.349	  0:01.822	PThreadObj...fecb22b700	SIP	Retrying SUBSCRIBE after 30 seconds.
2013/10/29 18:18:33.354	  0:01.827	subscriber...fecb1ea700	SIP	Attempting SRV record entry 0: 86.64.162.35:5060
2013/10/29 18:18:33.354	  0:01.827	subscriber...fecb1ea700	OpalUDP	Binding to interface: 0.0.0.0:5060
2013/10/29 18:18:33.355	  0:01.827	subscriber...fecb1ea700	PWLib	File handle low water mark set: 55 PUDPSocket
2013/10/29 18:18:33.354	  0:01.827	subscriber...fecb1ea700	SIP	Created transport udp$86.64.162.35:5060<if=udp$*:5060>
2013/10/29 18:18:33.355	  0:01.827	subscriber...fecb1ea700	OpalUDP	Started connect to 86.64.162.35:5060
2013/10/29 18:18:33.355	  0:01.828	subscriber...fecb1ea700	OpalUDP	Writing to interface 0 - "192.168.178.35%wlan0"
2013/10/29 18:18:33.356	  0:01.828	subscriber...fecb1ea700	OpalMan	Listener interfaces: associated transport=udp$192.168.178.35:5060
    udp$118.211.230.192:5060,udp$192.168.178.35:5060
2013/10/29 18:18:33.356	  0:01.829	subscriber...fecb1ea700	SIP	Transaction created.
2013/10/29 18:18:33.357	  0:01.829	subscriber...fecb1ea700	SIP	Transaction remembering interface "192.168.178.35%wlan0"
2013/10/29 18:18:33.357	  0:01.829	subscriber...fecb1ea700	DNS	SRV Lookup "_sip._udp.ekiga.net"
2013/10/29 18:18:33.357	  0:01.830	PThreadObj...fecb1a9700	DNS	Query failed: error=-1
2013/10/29 18:18:33.357	  0:01.830	PThreadObj...fecb1a9700	SIP	No SRV record found.
2013/10/29 18:18:33.358	  0:01.830	subscriber...fecb26c700	SIP	Attempting SRV record entry 0: 46.19.58.41:5060
2013/10/29 18:18:33.358	  0:01.830	PThreadObj...fecb1a9700	SIP	No compatible listener to create transport for tcp$ekiga.net:5060
2013/10/29 18:18:33.358	  0:01.830	subscriber...fecb1ea700	SIP	Attempting SRV record entry 0: 86.64.162.35:5060
2013/10/29 18:18:33.358	  0:01.831	PThreadObj...fecb1a9700	SIP	Changing SUBSCRIBE handler from Subscribing to Unavailable, target=sip:el_gallo_azul@ekiga.net;OPAL-local-id=sip:el_gallo_azul%40ekiga.net, id=d452918f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:33.358	  0:01.831	subscriber...fecb26c700	SIP	Transaction remote address is udp$46.19.58.41:5060
2013/10/29 18:18:33.358	  0:01.831	subscriber...fecb1ea700	SIP	Transaction remote address is udp$86.64.162.35:5060
2013/10/29 18:18:33.358	  0:01.831	PThreadObj...fecb1a9700	SIP	Retrying SUBSCRIBE after 30 seconds.
2013/10/29 18:18:33.358	  0:01.831	subscriber...fecb26c700	SIP	Sending PDU (585 bytes) to: rem=udp$46.19.58.41:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
REGISTER sip:sip.diamondcard.us SIP/2.0
CSeq: 1 REGISTER
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bK7209928f-e43e-e311-9af5-f81a670d972b;rport
User-Agent: Ekiga/4.0.1
From: <sip:473936@sip.diamondcard.us>;tag=286e908f-e43e-e311-9af5-f81a670d972b
Call-ID: 2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:473936@sip.diamondcard.us>
Contact: <sip:473936@118.211.230.192:5060>;q=1, <sip:473936@192.168.178.35: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


2013/10/29 18:18:33.358	  0:01.831	subscriber...fecb1ea700	SIP	Sending PDU (586 bytes) to: rem=udp$86.64.162.35:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
REGISTER sip:ekiga.net SIP/2.0
CSeq: 2 REGISTER
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bK4430948f-e43e-e311-9af5-f81a670d972b;rport
User-Agent: Ekiga/4.0.1
From: <sip:el_gallo_azul@ekiga.net>;tag=184b918f-e43e-e311-9af5-f81a670d972b
Call-ID: c043918f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:el_gallo_azul@ekiga.net>
Contact: <sip:el_gallo_azul@118.211.230.192:5060>;q=1, <sip:el_gallo_azul@192.168.178.35: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


2013/10/29 18:18:33.358	  0:01.831	subscriber...fecb26c700	OpalUDP	Setting interface to 192.168.178.35%wlan0
2013/10/29 18:18:33.358	  0:01.831	subscriber...fecb1ea700	OpalUDP	Setting interface to 192.168.178.35%wlan0
2013/10/29 18:18:33.358	  0:01.831	subscriber...fecb26c700	SIP	Transaction timers set: retry=0.500, completion=6.000
2013/10/29 18:18:33.358	  0:01.831	subscriber...fecb1ea700	SIP	Transaction timers set: retry=0.500, completion=6.000
2013/10/29 18:18:33.359	  0:01.831	subscriber...fecb26c700	PTLib	Thread ended: name="subscriber:0x7efecb26c700", real=0.028, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%)
2013/10/29 18:18:33.359	  0:01.831	subscriber...fecb1ea700	PTLib	Thread ended: name="subscriber:0x7efecb1ea700", real=0.027, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%)
2013/10/29 18:18:33.788	  0:02.260	Opal Liste...ff09dcd700	OpalUDP	Binding to interface: 192.168.178.35:5060
2013/10/29 18:18:33.788	  0:02.261	Opal Liste...ff09dcd700	SIP	Waiting for PDU on udp$86.64.162.35:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:33.788	  0:02.261	Opal Liste...ff09dcd700	SIP	PDU received: rem=udp$86.64.162.35:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
SIP/2.0 606 Not Acceptable
CSeq: 2 REGISTER
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bK4430948f-e43e-e311-9af5-f81a670d972b;rport=61012;received=118.211.230.192
Server: Kamailio (1.5.3-notls (i386/linux))
From: <sip:el_gallo_azul@ekiga.net>;tag=184b918f-e43e-e311-9af5-f81a670d972b
Call-ID: c043918f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:el_gallo_azul@ekiga.net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.4880
Content-Length: 0


2013/10/29 18:18:33.788	  0:02.261	Opal Liste...ff09dcd700	SIP	Queueing PDU "2 REGISTER <606>", transaction=z9hG4bK4430948f-e43e-e311-9af5-f81a670d972b, token=c043918f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:33.789	  0:02.261	Opal Liste...ff09dcd700	PTLib	No permission to set priority level 3
2013/10/29 18:18:33.789	  0:02.262	Opal Liste...ff09dcd700	PTLib	Thread high water mark set: 14
2013/10/29 18:18:33.789	  0:02.262	Opal Liste...ff09dcd700	Opal	Transport clean up on termination
2013/10/29 18:18:33.789	  0:02.262	Opal Liste...ff09dcd700	Opal	Transport Close
2013/10/29 18:18:33.789	  0:02.262	    Pool:0x7efecb168700	SIP	Handling PDU "2 REGISTER <606>" for transaction=z9hG4bK4430948f-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:33.789	  0:02.262	Opal Liste...ff09dcd700	PWLib	File handle high water mark set: 57 PUDPSocket
2013/10/29 18:18:33.789	  0:02.262	    Pool:0x7efecb168700	SIP	REGISTER transaction id=z9hG4bK4430948f-e43e-e311-9af5-f81a670d972b completing.
2013/10/29 18:18:33.789	  0:02.262	Opal Liste...ff09dcd700	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:33.789	  0:02.262	    Pool:0x7efecb168700	SIP	Not retrying REGISTER due to error response 606 Not Acceptable
2013/10/29 18:18:33.789	  0:02.262	    Pool:0x7efecb168700	SIP	Changing REGISTER handler from Subscribing to Unsubscribed, target=sip:el_gallo_azul@ekiga.net, id=c043918f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:33.789	  0:02.262	    Pool:0x7efecb168700	SIP	REGISTER transaction id=z9hG4bK4430948f-e43e-e311-9af5-f81a670d972b completed.
2013/10/29 18:18:33.789	  0:02.262	    Pool:0x7efecb168700	SIP	Handled PDU "2 REGISTER <606>"
2013/10/29 18:18:33.790	  0:02.263	Opal Liste...ff09dcd700	OpalUDP	Binding to interface: 192.168.178.35:5060
2013/10/29 18:18:33.790	  0:02.263	Opal Liste...ff09dcd700	PWLib	File handle low water mark set: 42 PUDPSocket
2013/10/29 18:18:33.790	  0:02.263	Opal Liste...ff09dcd700	SIP	Waiting for PDU on udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:33.790	  0:02.263	Opal Liste...ff09dcd700	SIP	PDU received: rem=udp$46.19.58.41:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
SIP/2.0 100 Trying
CSeq: 1 REGISTER
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bK7209928f-e43e-e311-9af5-f81a670d972b;rport=61012;received=118.211.230.192
Server: Generic SIP Proxy
From: <sip:473936@sip.diamondcard.us>;tag=286e908f-e43e-e311-9af5-f81a670d972b
Call-ID: 2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:473936@sip.diamondcard.us>
Content-Length: 0


2013/10/29 18:18:33.790	  0:02.263	Opal Liste...ff09dcd700	SIP	Queueing PDU "1 REGISTER <100>", transaction=z9hG4bK7209928f-e43e-e311-9af5-f81a670d972b, token=2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:33.790	  0:02.263	Opal Liste...ff09dcd700	Opal	Transport clean up on termination
2013/10/29 18:18:33.790	  0:02.263	Opal Liste...ff09dcd700	Opal	Transport Close
2013/10/29 18:18:33.791	  0:02.263	    Pool:0x7efecb168700	SIP	Handling PDU "1 REGISTER <100>" for transaction=z9hG4bK7209928f-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:33.791	  0:02.263	    Pool:0x7efecb168700	SIP	REGISTER transaction id=z9hG4bK7209928f-e43e-e311-9af5-f81a670d972b proceeding.
2013/10/29 18:18:33.791	  0:02.263	Opal Liste...ff09dcd700	Opal	Deleted transport udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:33.791	  0:02.264	    Pool:0x7efecb168700	SIP	Handled PDU "1 REGISTER <100>"
2013/10/29 18:18:33.791	  0:02.264	Opal Liste...ff09dcd700	OpalUDP	Binding to interface: 192.168.178.35:5060
2013/10/29 18:18:33.791	  0:02.264	Opal Liste...ff09dcd700	SIP	Waiting for PDU on udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:33.791	  0:02.264	Opal Liste...ff09dcd700	SIP	PDU received: rem=udp$46.19.58.41:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
SIP/2.0 401 Unauthorized
CSeq: 1 REGISTER
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bK7209928f-e43e-e311-9af5-f81a670d972b;rport=61012;received=118.211.230.192
Server: Generic SIP Proxy
From: <sip:473936@sip.diamondcard.us>;tag=286e908f-e43e-e311-9af5-f81a670d972b
Call-ID: 2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:473936@sip.diamondcard.us>;tag=e0c20796a2acd5434c46ad46445d6de7.675f
Content-Length: 0
WWW-Authenticate: Digest realm="sip.diamondcard.us", nonce="526f767f0000f66139162af5d25c5dd15f9e2a8243e513df"


2013/10/29 18:18:33.791	  0:02.264	Opal Liste...ff09dcd700	SIP	Queueing PDU "1 REGISTER <401>", transaction=z9hG4bK7209928f-e43e-e311-9af5-f81a670d972b, token=2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:33.792	  0:02.264	Opal Liste...ff09dcd700	Opal	Transport clean up on termination
2013/10/29 18:18:33.792	  0:02.264	Opal Liste...ff09dcd700	Opal	Transport Close
2013/10/29 18:18:33.792	  0:02.264	    Pool:0x7efecb168700	SIP	Handling PDU "1 REGISTER <401>" for transaction=z9hG4bK7209928f-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:33.792	  0:02.265	    Pool:0x7efecb168700	SIP	REGISTER transaction id=z9hG4bK7209928f-e43e-e311-9af5-f81a670d972b completing.
2013/10/29 18:18:33.792	  0:02.264	Opal Liste...ff09dcd700	Opal	Deleted transport udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:33.792	  0:02.265	    Pool:0x7efecb168700	SIP	Finalising handlers interface "192.168.178.35%wlan0"
2013/10/29 18:18:33.792	  0:02.265	    Pool:0x7efecb168700	OpalUDP	Setting interface to 192.168.178.35%wlan0
2013/10/29 18:18:33.792	  0:02.265	    Pool:0x7efecb168700	SIP	Received Authentication Required response for z9hG4bK7209928f-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:33.792	  0:02.265	    Pool:0x7efecb168700	SIP	Creating authentication credentials of user "473936" for realm "sip.diamondcard.us"
2013/10/29 18:18:33.792	  0:02.265	    Pool:0x7efecb168700	SIP	Auth realm set to sip.diamondcard.us
2013/10/29 18:18:33.793	  0:02.266	    Pool:0x7efecb168700	OpalMan	Listener interfaces: associated transport=udp$192.168.178.35:5060
    udp$118.211.230.192:5060,udp$192.168.178.35:5060
2013/10/29 18:18:33.793	  0:02.266	    Pool:0x7efecb168700	SIP	Transaction created.
2013/10/29 18:18:33.794	  0:02.267	    Pool:0x7efecb168700	HTTP	Adding authentication information
2013/10/29 18:18:33.794	  0:02.267	    Pool:0x7efecb168700	SIP	Transaction remembering interface "192.168.178.35%wlan0"
2013/10/29 18:18:33.794	  0:02.267	    Pool:0x7efecb168700	DNS	SRV Lookup "_sip._udp.sip.diamondcard.us"
2013/10/29 18:18:33.794	  0:02.267	    Pool:0x7efecb168700	SIP	Attempting SRV record entry 0: 46.19.58.41:5060
2013/10/29 18:18:33.794	  0:02.267	    Pool:0x7efecb168700	SIP	Transaction remote address is udp$46.19.58.41:5060
2013/10/29 18:18:33.794	  0:02.267	    Pool:0x7efecb168700	SIP	Sending PDU (802 bytes) to: rem=udp$46.19.58.41:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
REGISTER sip:sip.diamondcard.us SIP/2.0
CSeq: 3 REGISTER
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bK0aebd68f-e43e-e311-9af5-f81a670d972b;rport
User-Agent: Ekiga/4.0.1
Authorization: Digest username="473936", realm="sip.diamondcard.us", nonce="526f767f0000f66139162af5d25c5dd15f9e2a8243e513df", uri="sip:sip.diamondcard.us", algorithm=MD5, response="3a25a7b6dbe3eebee1ce033ce880185d"
From: <sip:473936@sip.diamondcard.us>;tag=286e908f-e43e-e311-9af5-f81a670d972b
Call-ID: 2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:473936@sip.diamondcard.us>
Contact: <sip:473936@118.211.230.192:5060>;q=1, <sip:473936@192.168.178.35: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


2013/10/29 18:18:33.795	  0:02.267	    Pool:0x7efecb168700	OpalUDP	Setting interface to 192.168.178.35%wlan0
2013/10/29 18:18:33.795	  0:02.267	    Pool:0x7efecb168700	SIP	Transaction timers set: retry=0.499, completion=5.999
2013/10/29 18:18:33.795	  0:02.268	    Pool:0x7efecb168700	SIP	REGISTER transaction id=z9hG4bK7209928f-e43e-e311-9af5-f81a670d972b completed.
2013/10/29 18:18:33.795	  0:02.268	    Pool:0x7efecb168700	SIP	Handled PDU "1 REGISTER <401>"
2013/10/29 18:18:33.809	  0:02.282	                       	Register failed in FullyCompliant mode, retrying in CannotRegisterMultipleContacts mode
2013/10/29 18:18:33.809	  0:02.282	subscriber...fecb127700	SIP	Start REGISTER
          aor=el_gallo_azul@ekiga.net
       remote=ekiga.net
        local=
      contact=
        proxy=
       authID=el_gallo_azul
        realm=
       expire=3600
      restore=30
     minRetry=default
     maxRetry=default
compatibility=CannotRegisterMultipleContacts
2013/10/29 18:18:33.810	  0:02.282	subscriber...fecb127700	SIP	Handler SIPRegisterHandler unsubscribed, awaiting shutdown.
2013/10/29 18:18:33.810	  0:02.283	subscriber...fecb127700	SIP	Constructed REGISTER handler for sip:el_gallo_azul@ekiga.net
2013/10/29 18:18:33.810	  0:02.283	subscriber...fecb127700	SIP	Executing state change to Subscribing for REGISTER handler, target=sip:el_gallo_azul@ekiga.net, id=3e6ed98f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:33.810	  0:02.283	subscriber...fecb127700	SIP	Changing REGISTER handler from Unavailable to Subscribing, target=sip:el_gallo_azul@ekiga.net, id=3e6ed98f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:33.810	  0:02.283	subscriber...fecb127700	DNS	SRV Lookup "_sip._udp.ekiga.net"
2013/10/29 18:18:33.810	  0:02.283	subscriber...fecb127700	SIP	Attempting SRV record entry 0: 86.64.162.35:5060
2013/10/29 18:18:33.811	  0:02.283	subscriber...fecb127700	OpalUDP	Binding to interface: 0.0.0.0:5060
2013/10/29 18:18:33.811	  0:02.283	subscriber...fecb127700	SIP	Created transport udp$86.64.162.35:5060<if=udp$*:5060>
2013/10/29 18:18:33.811	  0:02.284	subscriber...fecb127700	OpalUDP	Started connect to 86.64.162.35:5060
2013/10/29 18:18:33.811	  0:02.284	subscriber...fecb127700	OpalUDP	Writing to interface 0 - "192.168.178.35%wlan0"
2013/10/29 18:18:34.199	  0:02.672	subscriber...fecb127700	OpalMan	Listener interfaces: associated transport=udp$192.168.178.35:5060
    udp$118.211.230.192:5060,udp$192.168.178.35:5060
2013/10/29 18:18:34.199	  0:02.672	subscriber...fecb127700	SIP	Transaction created.
2013/10/29 18:18:34.200	  0:02.673	subscriber...fecb127700	SIP	Transaction remembering interface "192.168.178.35%wlan0"
2013/10/29 18:18:34.200	  0:02.673	subscriber...fecb127700	DNS	SRV Lookup "_sip._udp.ekiga.net"
2013/10/29 18:18:34.200	  0:02.673	subscriber...fecb127700	SIP	Attempting SRV record entry 0: 86.64.162.35:5060
2013/10/29 18:18:34.200	  0:02.673	subscriber...fecb127700	SIP	Transaction remote address is udp$86.64.162.35:5060
2013/10/29 18:18:34.200	  0:02.673	subscriber...fecb127700	SIP	Sending PDU (533 bytes) to: rem=udp$86.64.162.35:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
REGISTER sip:ekiga.net SIP/2.0
CSeq: 4 REGISTER
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bK5adf1490-e43e-e311-9af5-f81a670d972b;rport
User-Agent: Ekiga/4.0.1
From: <sip:el_gallo_azul@ekiga.net>;tag=0075d98f-e43e-e311-9af5-f81a670d972b
Call-ID: 3e6ed98f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:el_gallo_azul@ekiga.net>
Contact: <sip:el_gallo_azul@118.211.230.192:5060>
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK
Expires: 3600
Content-Length: 0
Max-Forwards: 70


2013/10/29 18:18:34.201	  0:02.673	subscriber...fecb127700	OpalUDP	Setting interface to 192.168.178.35%wlan0
2013/10/29 18:18:34.201	  0:02.673	subscriber...fecb127700	SIP	Transaction timers set: retry=0.500, completion=6.000
2013/10/29 18:18:34.201	  0:02.674	subscriber...fecb127700	PTLib	Thread ended: name="subscriber:0x7efecb127700", real=0.391, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%)
2013/10/29 18:18:34.227	  0:02.700	Opal Liste...ff09dcd700	OpalUDP	Binding to interface: 192.168.178.35:5060
2013/10/29 18:18:34.227	  0:02.700	Opal Liste...ff09dcd700	SIP	Waiting for PDU on udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:34.228	  0:02.700	Opal Liste...ff09dcd700	SIP	PDU received: rem=udp$46.19.58.41:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
SIP/2.0 100 Trying
CSeq: 3 REGISTER
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bK0aebd68f-e43e-e311-9af5-f81a670d972b;rport=61012;received=118.211.230.192
Server: Generic SIP Proxy
From: <sip:473936@sip.diamondcard.us>;tag=286e908f-e43e-e311-9af5-f81a670d972b
Call-ID: 2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:473936@sip.diamondcard.us>
Content-Length: 0


2013/10/29 18:18:34.228	  0:02.701	Opal Liste...ff09dcd700	SIP	Queueing PDU "3 REGISTER <100>", transaction=z9hG4bK0aebd68f-e43e-e311-9af5-f81a670d972b, token=2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:34.228	  0:02.701	Opal Liste...ff09dcd700	Opal	Transport clean up on termination
2013/10/29 18:18:34.228	  0:02.701	Opal Liste...ff09dcd700	Opal	Transport Close
2013/10/29 18:18:34.228	  0:02.701	    Pool:0x7efecb168700	SIP	Handling PDU "3 REGISTER <100>" for transaction=z9hG4bK0aebd68f-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:34.228	  0:02.701	    Pool:0x7efecb168700	SIP	REGISTER transaction id=z9hG4bK0aebd68f-e43e-e311-9af5-f81a670d972b proceeding.
2013/10/29 18:18:34.228	  0:02.701	Opal Liste...ff09dcd700	Opal	Deleted transport udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:34.228	  0:02.701	    Pool:0x7efecb168700	SIP	Handled PDU "3 REGISTER <100>"
2013/10/29 18:18:34.231	  0:02.704	Opal Liste...ff09dcd700	OpalUDP	Binding to interface: 192.168.178.35:5060
2013/10/29 18:18:34.231	  0:02.704	Opal Liste...ff09dcd700	SIP	Waiting for PDU on udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:34.232	  0:02.705	Opal Liste...ff09dcd700	SIP	PDU received: rem=udp$46.19.58.41:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
SIP/2.0 200 OK
CSeq: 3 REGISTER
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bK0aebd68f-e43e-e311-9af5-f81a670d972b;rport=61012;received=118.211.230.192
Server: Generic SIP Proxy
From: <sip:473936@sip.diamondcard.us>;tag=286e908f-e43e-e311-9af5-f81a670d972b
Call-ID: 2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:473936@sip.diamondcard.us>;tag=e0c20796a2acd5434c46ad46445d6de7.339d
Contact: <sip:473936@118.211.230.192:5060>;q=1;expires=3600;received="sip:118.211.230.192:61012", <sip:473936@192.168.178.35:5060>;q=0.5;expires=3600;received="sip:118.211.230.192:61012", <sip:473936@192.168.178.27:51741;transport=udp>;expires=2430;received="sip:118.211.230.192:51741", <sip:473936@118.211.230.192;uniq=45A9092C6157C5D9ADFCAB362F2C9>;expires=1213
Content-Length: 0


2013/10/29 18:18:34.232	  0:02.705	Opal Liste...ff09dcd700	SIP	Queueing PDU "3 REGISTER <200>", transaction=z9hG4bK0aebd68f-e43e-e311-9af5-f81a670d972b, token=2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:34.232	  0:02.705	Opal Liste...ff09dcd700	Opal	Transport clean up on termination
2013/10/29 18:18:34.232	  0:02.705	Opal Liste...ff09dcd700	Opal	Transport Close
2013/10/29 18:18:34.232	  0:02.705	    Pool:0x7efecb168700	SIP	Handling PDU "3 REGISTER <200>" for transaction=z9hG4bK0aebd68f-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:34.232	  0:02.705	    Pool:0x7efecb168700	SIP	REGISTER transaction id=z9hG4bK0aebd68f-e43e-e311-9af5-f81a670d972b completing.
2013/10/29 18:18:34.232	  0:02.705	Opal Liste...ff09dcd700	Opal	Deleted transport udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:34.232	  0:02.705	    Pool:0x7efecb168700	SIP	Finalising handlers interface "192.168.178.35%wlan0"
2013/10/29 18:18:34.232	  0:02.705	    Pool:0x7efecb168700	OpalUDP	Setting interface to 192.168.178.35%wlan0
2013/10/29 18:18:34.232	  0:02.705	    Pool:0x7efecb168700	SIP	Product Info: name="Generic", version="", vendor="", comments="SIP Proxy"
2013/10/29 18:18:34.234	  0:02.706	    Pool:0x7efecb168700	SIP	Remote indicated change of REGISTER Contact address(s) (<sip:473936@118.211.230.192:5060>;q=1;received=sip:118.211.230.192:61012, <sip:473936@192.168.178.35:5060>;q=0.5;received=sip:118.211.230.192:61012, <sip:473936@118.211.230.192:5060;uniq=45A9092C6157C5D9ADFCAB362F2C9>) required due to NAT address udp$118.211.230.192:61012, previous=
2013/10/29 18:18:34.234	  0:02.707	    Pool:0x7efecb168700	SIP	Expiry time for REGISTER set to 0 seconds.
2013/10/29 18:18:34.234	  0:02.707	    Pool:0x7efecb168700	SIP	Changing REGISTER handler from Subscribing to Refreshing, target=sip:473936@sip.diamondcard.us, id=2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:34.234	  0:02.707	    Pool:0x7efecb168700	SIP	Transaction created.
2013/10/29 18:18:34.234	  0:02.707	    Pool:0x7efecb168700	HTTP	Adding authentication information
2013/10/29 18:18:34.235	  0:02.707	    Pool:0x7efecb168700	SIP	Transaction remembering interface "192.168.178.35%wlan0"
2013/10/29 18:18:34.235	  0:02.707	    Pool:0x7efecb168700	DNS	SRV Lookup "_sip._udp.sip.diamondcard.us"
2013/10/29 18:18:34.235	  0:02.708	    Pool:0x7efecb168700	SIP	Attempting SRV record entry 0: 46.19.58.41:5060
2013/10/29 18:18:34.235	  0:02.708	    Pool:0x7efecb168700	SIP	Transaction remote address is udp$46.19.58.41:5060
2013/10/29 18:18:34.235	  0:02.708	    Pool:0x7efecb168700	SIP	Sending PDU (937 bytes) to: rem=udp$46.19.58.41:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
REGISTER sip:sip.diamondcard.us SIP/2.0
CSeq: 5 REGISTER
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bKc2261a90-e43e-e311-9af5-f81a670d972b;rport
User-Agent: Ekiga/4.0.1
Authorization: Digest username="473936", realm="sip.diamondcard.us", nonce="526f767f0000f66139162af5d25c5dd15f9e2a8243e513df", uri="sip:sip.diamondcard.us", algorithm=MD5, response="3a25a7b6dbe3eebee1ce033ce880185d"
From: <sip:473936@sip.diamondcard.us>;tag=286e908f-e43e-e311-9af5-f81a670d972b
Call-ID: 2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:473936@sip.diamondcard.us>
Contact: <sip:473936@118.211.230.192:5060>;q=1;received=sip:118.211.230.192:61012, <sip:473936@192.168.178.35:5060>;q=0.5;received=sip:118.211.230.192:61012, <sip:473936@118.211.230.192:5060;uniq=45A9092C6157C5D9ADFCAB362F2C9>
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK
Expires: 0
Content-Length: 0
Max-Forwards: 70


2013/10/29 18:18:34.235	  0:02.708	    Pool:0x7efecb168700	OpalUDP	Setting interface to 192.168.178.35%wlan0
2013/10/29 18:18:34.235	  0:02.708	    Pool:0x7efecb168700	SIP	Transaction timers set: retry=0.500, completion=6.000
2013/10/29 18:18:34.235	  0:02.708	    Pool:0x7efecb168700	SIP	REGISTER transaction id=z9hG4bK0aebd68f-e43e-e311-9af5-f81a670d972b completed.
2013/10/29 18:18:34.235	  0:02.708	    Pool:0x7efecb168700	SIP	Handled PDU "3 REGISTER <200>"
2013/10/29 18:18:34.621	  0:03.094	Opal Liste...ff09dcd700	OpalUDP	Binding to interface: 192.168.178.35:5060
2013/10/29 18:18:34.621	  0:03.094	Opal Liste...ff09dcd700	SIP	Waiting for PDU on udp$86.64.162.35:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:34.621	  0:03.094	Opal Liste...ff09dcd700	SIP	PDU received: rem=udp$86.64.162.35:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
SIP/2.0 606 Not Acceptable
CSeq: 4 REGISTER
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bK5adf1490-e43e-e311-9af5-f81a670d972b;rport=61012;received=118.211.230.192
Server: Kamailio (1.5.3-notls (i386/linux))
From: <sip:el_gallo_azul@ekiga.net>;tag=0075d98f-e43e-e311-9af5-f81a670d972b
Call-ID: 3e6ed98f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:el_gallo_azul@ekiga.net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.da9a
Content-Length: 0


2013/10/29 18:18:34.622	  0:03.094	Opal Liste...ff09dcd700	SIP	Queueing PDU "4 REGISTER <606>", transaction=z9hG4bK5adf1490-e43e-e311-9af5-f81a670d972b, token=3e6ed98f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:34.622	  0:03.094	Opal Liste...ff09dcd700	Opal	Transport clean up on termination
2013/10/29 18:18:34.622	  0:03.094	Opal Liste...ff09dcd700	Opal	Transport Close
2013/10/29 18:18:34.622	  0:03.095	    Pool:0x7efecb168700	SIP	Handling PDU "4 REGISTER <606>" for transaction=z9hG4bK5adf1490-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:34.622	  0:03.095	    Pool:0x7efecb168700	SIP	REGISTER transaction id=z9hG4bK5adf1490-e43e-e311-9af5-f81a670d972b completing.
2013/10/29 18:18:34.622	  0:03.095	Opal Liste...ff09dcd700	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:34.622	  0:03.095	    Pool:0x7efecb168700	SIP	Not retrying REGISTER due to error response 606 Not Acceptable
2013/10/29 18:18:34.622	  0:03.095	    Pool:0x7efecb168700	SIP	Changing REGISTER handler from Subscribing to Unsubscribed, target=sip:el_gallo_azul@ekiga.net, id=3e6ed98f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:34.622	  0:03.095	    Pool:0x7efecb168700	SIP	REGISTER transaction id=z9hG4bK5adf1490-e43e-e311-9af5-f81a670d972b completed.
2013/10/29 18:18:34.622	  0:03.095	    Pool:0x7efecb168700	SIP	Handled PDU "4 REGISTER <606>"
2013/10/29 18:18:34.629	  0:03.102	                       	Register failed in CannotRegisterMultipleContacts mode, retrying in CannotRegisterPrivateContacts mode
2013/10/29 18:18:34.630	  0:03.102	subscriber...fecb0e6700	SIP	Start REGISTER
          aor=el_gallo_azul@ekiga.net
       remote=ekiga.net
        local=
      contact=
        proxy=
       authID=el_gallo_azul
        realm=
       expire=3600
      restore=30
     minRetry=default
     maxRetry=default
compatibility=CannotRegisterPrivateContacts
2013/10/29 18:18:34.630	  0:03.103	subscriber...fecb0e6700	SIP	Handler SIPRegisterHandler unsubscribed, awaiting shutdown.
2013/10/29 18:18:34.630	  0:03.103	subscriber...fecb0e6700	SIP	Constructed REGISTER handler for sip:el_gallo_azul@ekiga.net
2013/10/29 18:18:34.630	  0:03.103	subscriber...fecb0e6700	SIP	Executing state change to Subscribing for REGISTER handler, target=sip:el_gallo_azul@ekiga.net, id=c4975690-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:34.630	  0:03.103	subscriber...fecb0e6700	SIP	Changing REGISTER handler from Unavailable to Subscribing, target=sip:el_gallo_azul@ekiga.net, id=c4975690-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:34.630	  0:03.103	subscriber...fecb0e6700	DNS	SRV Lookup "_sip._udp.ekiga.net"
2013/10/29 18:18:34.631	  0:03.103	subscriber...fecb0e6700	SIP	Attempting SRV record entry 0: 86.64.162.35:5060
2013/10/29 18:18:34.631	  0:03.104	subscriber...fecb0e6700	OpalUDP	Binding to interface: 0.0.0.0:5060
2013/10/29 18:18:34.631	  0:03.104	subscriber...fecb0e6700	SIP	Created transport udp$86.64.162.35:5060<if=udp$*:5060>
2013/10/29 18:18:34.631	  0:03.104	subscriber...fecb0e6700	OpalUDP	Started connect to 86.64.162.35:5060
2013/10/29 18:18:34.631	  0:03.104	subscriber...fecb0e6700	OpalUDP	Writing to interface 0 - "192.168.178.35%wlan0"
2013/10/29 18:18:34.632	  0:03.105	subscriber...fecb0e6700	OpalMan	Listener interfaces: associated transport=udp$192.168.178.35:5060
    udp$118.211.230.192:5060,udp$192.168.178.35:5060
2013/10/29 18:18:34.632	  0:03.105	subscriber...fecb0e6700	SIP	Transaction created.
2013/10/29 18:18:34.633	  0:03.105	subscriber...fecb0e6700	SIP	Transaction remembering interface "192.168.178.35%wlan0"
2013/10/29 18:18:34.633	  0:03.105	subscriber...fecb0e6700	DNS	SRV Lookup "_sip._udp.ekiga.net"
2013/10/29 18:18:34.633	  0:03.106	subscriber...fecb0e6700	SIP	Attempting SRV record entry 0: 86.64.162.35:5060
2013/10/29 18:18:34.633	  0:03.106	subscriber...fecb0e6700	SIP	Transaction remote address is udp$86.64.162.35:5060
2013/10/29 18:18:34.633	  0:03.106	subscriber...fecb0e6700	SIP	Sending PDU (536 bytes) to: rem=udp$86.64.162.35:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
REGISTER sip:ekiga.net SIP/2.0
CSeq: 6 REGISTER
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bK9ce75690-e43e-e311-9af5-f81a670d972b;rport
User-Agent: Ekiga/4.0.1
From: <sip:el_gallo_azul@ekiga.net>;tag=549e5690-e43e-e311-9af5-f81a670d972b
Call-ID: c4975690-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:el_gallo_azul@ekiga.net>
Contact: <sip:el_gallo_azul@192.168.178.35:5060>;q=1
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK
Expires: 3600
Content-Length: 0
Max-Forwards: 70


2013/10/29 18:18:34.633	  0:03.106	subscriber...fecb0e6700	OpalUDP	Setting interface to 192.168.178.35%wlan0
2013/10/29 18:18:34.633	  0:03.106	subscriber...fecb0e6700	SIP	Transaction timers set: retry=0.500, completion=6.000
2013/10/29 18:18:34.633	  0:03.106	subscriber...fecb0e6700	PTLib	Thread ended: name="subscriber:0x7efecb0e6700", real=0.004, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%)
2013/10/29 18:18:34.670	  0:03.143	Opal Liste...ff09dcd700	OpalUDP	Binding to interface: 192.168.178.35:5060
2013/10/29 18:18:34.670	  0:03.143	Opal Liste...ff09dcd700	SIP	Waiting for PDU on udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:34.671	  0:03.143	Opal Liste...ff09dcd700	SIP	PDU received: rem=udp$46.19.58.41:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
SIP/2.0 100 Trying
CSeq: 5 REGISTER
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bKc2261a90-e43e-e311-9af5-f81a670d972b;rport=61012;received=118.211.230.192
Server: Generic SIP Proxy
From: <sip:473936@sip.diamondcard.us>;tag=286e908f-e43e-e311-9af5-f81a670d972b
Call-ID: 2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:473936@sip.diamondcard.us>
Content-Length: 0


2013/10/29 18:18:34.671	  0:03.144	Opal Liste...ff09dcd700	SIP	Queueing PDU "5 REGISTER <100>", transaction=z9hG4bKc2261a90-e43e-e311-9af5-f81a670d972b, token=2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:34.671	  0:03.144	Opal Liste...ff09dcd700	Opal	Transport clean up on termination
2013/10/29 18:18:34.671	  0:03.144	Opal Liste...ff09dcd700	Opal	Transport Close
2013/10/29 18:18:34.671	  0:03.144	    Pool:0x7efecb168700	SIP	Handling PDU "5 REGISTER <100>" for transaction=z9hG4bKc2261a90-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:34.671	  0:03.144	    Pool:0x7efecb168700	SIP	REGISTER transaction id=z9hG4bKc2261a90-e43e-e311-9af5-f81a670d972b proceeding.
2013/10/29 18:18:34.671	  0:03.144	Opal Liste...ff09dcd700	Opal	Deleted transport udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:34.671	  0:03.144	    Pool:0x7efecb168700	SIP	Handled PDU "5 REGISTER <100>"
2013/10/29 18:18:34.671	  0:03.144	Opal Liste...ff09dcd700	OpalUDP	Binding to interface: 192.168.178.35:5060
2013/10/29 18:18:34.672	  0:03.144	Opal Liste...ff09dcd700	SIP	Waiting for PDU on udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:34.672	  0:03.145	Opal Liste...ff09dcd700	SIP	PDU received: rem=udp$46.19.58.41:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
SIP/2.0 401 Unauthorized
CSeq: 5 REGISTER
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bKc2261a90-e43e-e311-9af5-f81a670d972b;rport=61012;received=118.211.230.192
Server: Generic SIP Proxy
From: <sip:473936@sip.diamondcard.us>;tag=286e908f-e43e-e311-9af5-f81a670d972b
Call-ID: 2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:473936@sip.diamondcard.us>;tag=e0c20796a2acd5434c46ad46445d6de7.50b9
Content-Length: 0
WWW-Authenticate: Digest realm="sip.diamondcard.us", nonce="526f76800000f6633810d5425139b03873ef66622be1d493"


2013/10/29 18:18:34.672	  0:03.145	Opal Liste...ff09dcd700	SIP	Queueing PDU "5 REGISTER <401>", transaction=z9hG4bKc2261a90-e43e-e311-9af5-f81a670d972b, token=2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:34.672	  0:03.145	Opal Liste...ff09dcd700	Opal	Transport clean up on termination
2013/10/29 18:18:34.672	  0:03.145	Opal Liste...ff09dcd700	Opal	Transport Close
2013/10/29 18:18:34.672	  0:03.145	    Pool:0x7efecb168700	SIP	Handling PDU "5 REGISTER <401>" for transaction=z9hG4bKc2261a90-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:34.672	  0:03.145	    Pool:0x7efecb168700	SIP	REGISTER transaction id=z9hG4bKc2261a90-e43e-e311-9af5-f81a670d972b completing.
2013/10/29 18:18:34.672	  0:03.145	Opal Liste...ff09dcd700	Opal	Deleted transport udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:34.672	  0:03.145	    Pool:0x7efecb168700	SIP	Finalising handlers interface "192.168.178.35%wlan0"
2013/10/29 18:18:34.672	  0:03.145	    Pool:0x7efecb168700	OpalUDP	Setting interface to 192.168.178.35%wlan0
2013/10/29 18:18:34.672	  0:03.145	    Pool:0x7efecb168700	SIP	Received Authentication Required response for z9hG4bKc2261a90-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:34.672	  0:03.145	    Pool:0x7efecb168700	SIP	Updating authentication credentials of user "473936" for realm "sip.diamondcard.us"
2013/10/29 18:18:34.673	  0:03.145	    Pool:0x7efecb168700	SIP	Transaction created.
2013/10/29 18:18:34.673	  0:03.146	    Pool:0x7efecb168700	HTTP	Adding authentication information
2013/10/29 18:18:34.673	  0:03.146	    Pool:0x7efecb168700	SIP	Transaction remembering interface "192.168.178.35%wlan0"
2013/10/29 18:18:34.673	  0:03.146	    Pool:0x7efecb168700	DNS	SRV Lookup "_sip._udp.sip.diamondcard.us"
2013/10/29 18:18:34.673	  0:03.146	    Pool:0x7efecb168700	SIP	Attempting SRV record entry 0: 46.19.58.41:5060
2013/10/29 18:18:34.674	  0:03.146	    Pool:0x7efecb168700	SIP	Transaction remote address is udp$46.19.58.41:5060
2013/10/29 18:18:34.674	  0:03.147	    Pool:0x7efecb168700	SIP	Sending PDU (937 bytes) to: rem=udp$46.19.58.41:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
REGISTER sip:sip.diamondcard.us SIP/2.0
CSeq: 7 REGISTER
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bKd2195d90-e43e-e311-9af5-f81a670d972b;rport
User-Agent: Ekiga/4.0.1
Authorization: Digest username="473936", realm="sip.diamondcard.us", nonce="526f76800000f6633810d5425139b03873ef66622be1d493", uri="sip:sip.diamondcard.us", algorithm=MD5, response="a3776362ce5b1ecae29bf8e984c20a98"
From: <sip:473936@sip.diamondcard.us>;tag=286e908f-e43e-e311-9af5-f81a670d972b
Call-ID: 2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:473936@sip.diamondcard.us>
Contact: <sip:473936@118.211.230.192:5060>;q=1;received=sip:118.211.230.192:61012, <sip:473936@192.168.178.35:5060>;q=0.5;received=sip:118.211.230.192:61012, <sip:473936@118.211.230.192:5060;uniq=45A9092C6157C5D9ADFCAB362F2C9>
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK
Expires: 0
Content-Length: 0
Max-Forwards: 70


2013/10/29 18:18:34.674	  0:03.147	    Pool:0x7efecb168700	OpalUDP	Setting interface to 192.168.178.35%wlan0
2013/10/29 18:18:34.674	  0:03.147	    Pool:0x7efecb168700	SIP	Transaction timers set: retry=0.500, completion=6.000
2013/10/29 18:18:34.674	  0:03.147	    Pool:0x7efecb168700	SIP	REGISTER transaction id=z9hG4bKc2261a90-e43e-e311-9af5-f81a670d972b completed.
2013/10/29 18:18:34.674	  0:03.147	    Pool:0x7efecb168700	SIP	Handled PDU "5 REGISTER <401>"
2013/10/29 18:18:34.681	  0:03.154	Opal Garba...ff367e6700	Opal	Transport clean up on termination
2013/10/29 18:18:34.681	  0:03.154	Opal Garba...ff367e6700	Opal	Transport Close
2013/10/29 18:18:34.681	  0:03.154	Opal Garba...ff367e6700	Opal	Transport clean up on termination
2013/10/29 18:18:34.681	  0:03.154	Opal Garba...ff367e6700	Opal	Transport Close
2013/10/29 18:18:34.681	  0:03.154	Opal Garba...ff367e6700	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$*:5060>
2013/10/29 18:18:34.682	  0:03.154	Opal Garba...ff367e6700	SIP	Destroyed REGISTER handler for sip:el_gallo_azul@ekiga.net
2013/10/29 18:18:34.682	  0:03.155	Opal Garba...ff367e6700	Opal	Transport clean up on termination
2013/10/29 18:18:34.682	  0:03.155	Opal Garba...ff367e6700	Opal	Transport Close
2013/10/29 18:18:34.682	  0:03.155	Opal Garba...ff367e6700	Opal	Transport clean up on termination
2013/10/29 18:18:34.682	  0:03.155	Opal Garba...ff367e6700	Opal	Transport Close
2013/10/29 18:18:34.682	  0:03.155	Opal Garba...ff367e6700	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$*:5060>
2013/10/29 18:18:34.682	  0:03.155	Opal Garba...ff367e6700	SIP	Destroyed REGISTER handler for sip:el_gallo_azul@ekiga.net
2013/10/29 18:18:34.789	  0:03.262	Housekeepe...ff368ea700	SIP	Set state Terminated_Success for REGISTER transaction id=z9hG4bK4430948f-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:34.790	  0:03.262	Housekeepe...ff368ea700	SIP	Completed state for transaction id=z9hG4bK4430948f-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:34.815	  0:03.288	Housekeepe...ff368ea700	SIP	Set state Terminated_Success for REGISTER transaction id=z9hG4bK7209928f-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:34.815	  0:03.288	Housekeepe...ff368ea700	SIP	Completed state for transaction id=z9hG4bK7209928f-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:35.054	  0:03.526	Opal Liste...ff09dcd700	OpalUDP	Binding to interface: 192.168.178.35:5060
2013/10/29 18:18:35.054	  0:03.527	Opal Liste...ff09dcd700	SIP	Waiting for PDU on udp$86.64.162.35:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:35.054	  0:03.527	Opal Liste...ff09dcd700	SIP	PDU received: rem=udp$86.64.162.35:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
SIP/2.0 606 Not Acceptable
CSeq: 6 REGISTER
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bK9ce75690-e43e-e311-9af5-f81a670d972b;rport=61012;received=118.211.230.192
Server: Kamailio (1.5.3-notls (i386/linux))
From: <sip:el_gallo_azul@ekiga.net>;tag=549e5690-e43e-e311-9af5-f81a670d972b
Call-ID: c4975690-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:el_gallo_azul@ekiga.net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.611a
Content-Length: 0


2013/10/29 18:18:35.054	  0:03.527	Opal Liste...ff09dcd700	SIP	Queueing PDU "6 REGISTER <606>", transaction=z9hG4bK9ce75690-e43e-e311-9af5-f81a670d972b, token=c4975690-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:35.054	  0:03.527	Opal Liste...ff09dcd700	Opal	Transport clean up on termination
2013/10/29 18:18:35.054	  0:03.527	Opal Liste...ff09dcd700	Opal	Transport Close
2013/10/29 18:18:35.055	  0:03.527	    Pool:0x7efecb168700	SIP	Handling PDU "6 REGISTER <606>" for transaction=z9hG4bK9ce75690-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:35.055	  0:03.528	    Pool:0x7efecb168700	SIP	REGISTER transaction id=z9hG4bK9ce75690-e43e-e311-9af5-f81a670d972b completing.
2013/10/29 18:18:35.055	  0:03.527	Opal Liste...ff09dcd700	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:35.055	  0:03.528	    Pool:0x7efecb168700	SIP	Not retrying REGISTER due to error response 606 Not Acceptable
2013/10/29 18:18:35.055	  0:03.528	    Pool:0x7efecb168700	SIP	Changing REGISTER handler from Subscribing to Unsubscribed, target=sip:el_gallo_azul@ekiga.net, id=c4975690-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:35.055	  0:03.528	    Pool:0x7efecb168700	SIP	REGISTER transaction id=z9hG4bK9ce75690-e43e-e311-9af5-f81a670d972b completed.
2013/10/29 18:18:35.055	  0:03.528	    Pool:0x7efecb168700	SIP	Handled PDU "6 REGISTER <606>"
2013/10/29 18:18:35.108	  0:03.581	Opal Liste...ff09dcd700	OpalUDP	Binding to interface: 192.168.178.35:5060
2013/10/29 18:18:35.108	  0:03.581	Opal Liste...ff09dcd700	SIP	Waiting for PDU on udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:35.108	  0:03.581	Opal Liste...ff09dcd700	SIP	PDU received: rem=udp$46.19.58.41:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
SIP/2.0 100 Trying
CSeq: 7 REGISTER
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bKd2195d90-e43e-e311-9af5-f81a670d972b;rport=61012;received=118.211.230.192
Server: Generic SIP Proxy
From: <sip:473936@sip.diamondcard.us>;tag=286e908f-e43e-e311-9af5-f81a670d972b
Call-ID: 2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:473936@sip.diamondcard.us>
Content-Length: 0


2013/10/29 18:18:35.108	  0:03.581	Opal Liste...ff09dcd700	SIP	Queueing PDU "7 REGISTER <100>", transaction=z9hG4bKd2195d90-e43e-e311-9af5-f81a670d972b, token=2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:35.109	  0:03.581	Opal Liste...ff09dcd700	Opal	Transport clean up on termination
2013/10/29 18:18:35.109	  0:03.581	Opal Liste...ff09dcd700	Opal	Transport Close
2013/10/29 18:18:35.109	  0:03.582	    Pool:0x7efecb168700	SIP	Handling PDU "7 REGISTER <100>" for transaction=z9hG4bKd2195d90-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:35.109	  0:03.582	    Pool:0x7efecb168700	SIP	REGISTER transaction id=z9hG4bKd2195d90-e43e-e311-9af5-f81a670d972b proceeding.
2013/10/29 18:18:35.109	  0:03.582	    Pool:0x7efecb168700	SIP	Handled PDU "7 REGISTER <100>"
2013/10/29 18:18:35.109	  0:03.582	Opal Liste...ff09dcd700	Opal	Deleted transport udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:35.111	  0:03.584	Opal Liste...ff09dcd700	OpalUDP	Binding to interface: 192.168.178.35:5060
2013/10/29 18:18:35.111	  0:03.584	Opal Liste...ff09dcd700	SIP	Waiting for PDU on udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:35.111	  0:03.584	Opal Liste...ff09dcd700	SIP	PDU received: rem=udp$46.19.58.41:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
SIP/2.0 200 OK
CSeq: 7 REGISTER
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bKd2195d90-e43e-e311-9af5-f81a670d972b;rport=61012;received=118.211.230.192
Server: Generic SIP Proxy
From: <sip:473936@sip.diamondcard.us>;tag=286e908f-e43e-e311-9af5-f81a670d972b
Call-ID: 2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:473936@sip.diamondcard.us>;tag=e0c20796a2acd5434c46ad46445d6de7.0f2f
Contact: <sip:473936@192.168.178.27:51741;transport=udp>;expires=2429;received="sip:118.211.230.192:51741", <sip:473936@118.211.230.192;uniq=45A9092C6157C5D9ADFCAB362F2C9>;expires=1212
Content-Length: 0


2013/10/29 18:18:35.111	  0:03.584	Opal Liste...ff09dcd700	SIP	Queueing PDU "7 REGISTER <200>", transaction=z9hG4bKd2195d90-e43e-e311-9af5-f81a670d972b, token=2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:35.111	  0:03.584	Opal Liste...ff09dcd700	Opal	Transport clean up on termination
2013/10/29 18:18:35.112	  0:03.584	Opal Liste...ff09dcd700	Opal	Transport Close
2013/10/29 18:18:35.112	  0:03.584	    Pool:0x7efecb168700	SIP	Handling PDU "7 REGISTER <200>" for transaction=z9hG4bKd2195d90-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:35.112	  0:03.585	    Pool:0x7efecb168700	SIP	REGISTER transaction id=z9hG4bKd2195d90-e43e-e311-9af5-f81a670d972b completing.
2013/10/29 18:18:35.112	  0:03.584	Opal Liste...ff09dcd700	Opal	Deleted transport udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:35.112	  0:03.585	    Pool:0x7efecb168700	SIP	Finalising handlers interface "192.168.178.35%wlan0"
2013/10/29 18:18:35.112	  0:03.585	    Pool:0x7efecb168700	OpalUDP	Setting interface to 192.168.178.35%wlan0
2013/10/29 18:18:35.112	  0:03.585	    Pool:0x7efecb168700	SIP	Product Info: name="Generic", version="", vendor="", comments="SIP Proxy"
2013/10/29 18:18:35.113	  0:03.586	    Pool:0x7efecb168700	SIP	Re-registering NAT address change (<sip:473936@118.211.230.192:5060>;q=1;received=sip:118.211.230.192:61012, <sip:473936@192.168.178.35:5060>;q=0.5;received=sip:118.211.230.192:61012, <sip:473936@118.211.230.192:5060;uniq=45A9092C6157C5D9ADFCAB362F2C9>) to udp$118.211.230.192:61012
2013/10/29 18:18:35.113	  0:03.586	    Pool:0x7efecb168700	SIP	Expiry time for REGISTER set to 3600 seconds.
2013/10/29 18:18:35.113	  0:03.586	    Pool:0x7efecb168700	SIP	Transaction created.
2013/10/29 18:18:35.114	  0:03.587	    Pool:0x7efecb168700	HTTP	Adding authentication information
2013/10/29 18:18:35.114	  0:03.587	    Pool:0x7efecb168700	SIP	Transaction remembering interface "192.168.178.35%wlan0"
2013/10/29 18:18:35.114	  0:03.587	    Pool:0x7efecb168700	DNS	SRV Lookup "_sip._udp.sip.diamondcard.us"
2013/10/29 18:18:35.114	  0:03.587	    Pool:0x7efecb168700	SIP	Attempting SRV record entry 0: 46.19.58.41:5060
2013/10/29 18:18:35.114	  0:03.587	    Pool:0x7efecb168700	SIP	Transaction remote address is udp$46.19.58.41:5060
2013/10/29 18:18:35.114	  0:03.587	    Pool:0x7efecb168700	SIP	Sending PDU (796 bytes) to: rem=udp$46.19.58.41:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
REGISTER sip:sip.diamondcard.us SIP/2.0
CSeq: 8 REGISTER
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bK5454a090-e43e-e311-9af5-f81a670d972b;rport
User-Agent: Ekiga/4.0.1
Authorization: Digest username="473936", realm="sip.diamondcard.us", nonce="526f76800000f6633810d5425139b03873ef66622be1d493", uri="sip:sip.diamondcard.us", algorithm=MD5, response="a3776362ce5b1ecae29bf8e984c20a98"
From: <sip:473936@sip.diamondcard.us>;tag=286e908f-e43e-e311-9af5-f81a670d972b
Call-ID: 2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:473936@sip.diamondcard.us>
Contact: <sip:473936@118.211.230.192:61012>;q=1;received=sip:118.211.230.192:61012
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK
Expires: 3600
Content-Length: 0
Max-Forwards: 70


2013/10/29 18:18:35.115	  0:03.587	    Pool:0x7efecb168700	OpalUDP	Setting interface to 192.168.178.35%wlan0
2013/10/29 18:18:35.115	  0:03.587	    Pool:0x7efecb168700	SIP	Transaction timers set: retry=0.500, completion=6.000
2013/10/29 18:18:35.115	  0:03.588	    Pool:0x7efecb168700	SIP	REGISTER transaction id=z9hG4bKd2195d90-e43e-e311-9af5-f81a670d972b completed.
2013/10/29 18:18:35.115	  0:03.588	    Pool:0x7efecb168700	SIP	Handled PDU "7 REGISTER <200>"
2013/10/29 18:18:35.147	  0:03.620	                       	Register failed in CannotRegisterPrivateContacts mode, retrying in HasApplicationLayerGateway mode
2013/10/29 18:18:35.148	  0:03.620	subscriber...fecb0a5700	SIP	Start REGISTER
          aor=el_gallo_azul@ekiga.net
       remote=ekiga.net
        local=
      contact=
        proxy=
       authID=el_gallo_azul
        realm=
       expire=3600
      restore=30
     minRetry=default
     maxRetry=default
compatibility=HasApplicationLayerGateway
2013/10/29 18:18:35.148	  0:03.621	subscriber...fecb0a5700	SIP	Handler SIPRegisterHandler unsubscribed, awaiting shutdown.
2013/10/29 18:18:35.148	  0:03.621	subscriber...fecb0a5700	SIP	Constructed REGISTER handler for sip:el_gallo_azul@ekiga.net
2013/10/29 18:18:35.148	  0:03.621	subscriber...fecb0a5700	SIP	Executing state change to Subscribing for REGISTER handler, target=sip:el_gallo_azul@ekiga.net, id=7ea2a590-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:35.148	  0:03.621	subscriber...fecb0a5700	SIP	Changing REGISTER handler from Unavailable to Subscribing, target=sip:el_gallo_azul@ekiga.net, id=7ea2a590-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:35.149	  0:03.621	subscriber...fecb0a5700	DNS	SRV Lookup "_sip._udp.ekiga.net"
2013/10/29 18:18:35.149	  0:03.621	subscriber...fecb0a5700	SIP	Attempting SRV record entry 0: 86.64.162.35:5060
2013/10/29 18:18:35.149	  0:03.622	subscriber...fecb0a5700	OpalUDP	Binding to interface: 0.0.0.0:5060
2013/10/29 18:18:35.149	  0:03.622	subscriber...fecb0a5700	SIP	Created transport udp$86.64.162.35:5060<if=udp$*:5060>
2013/10/29 18:18:35.149	  0:03.622	subscriber...fecb0a5700	OpalUDP	Started connect to 86.64.162.35:5060
2013/10/29 18:18:35.149	  0:03.622	subscriber...fecb0a5700	OpalUDP	Writing to interface 0 - "192.168.178.35%wlan0"
2013/10/29 18:18:35.150	  0:03.623	subscriber...fecb0a5700	OpalMan	Listener interfaces: associated transport=udp$192.168.178.35:5060
    udp$118.211.230.192:5060,udp$192.168.178.35:5060
2013/10/29 18:18:35.150	  0:03.623	subscriber...fecb0a5700	SIP	Transaction created.
2013/10/29 18:18:35.151	  0:03.623	subscriber...fecb0a5700	SIP	Transaction remembering interface "192.168.178.35%wlan0"
2013/10/29 18:18:35.151	  0:03.624	subscriber...fecb0a5700	DNS	SRV Lookup "_sip._udp.ekiga.net"
2013/10/29 18:18:35.151	  0:03.624	subscriber...fecb0a5700	SIP	Attempting SRV record entry 0: 86.64.162.35:5060
2013/10/29 18:18:35.151	  0:03.624	subscriber...fecb0a5700	SIP	Transaction remote address is udp$86.64.162.35:5060
2013/10/29 18:18:35.151	  0:03.624	subscriber...fecb0a5700	SIP	Sending PDU (536 bytes) to: rem=udp$86.64.162.35:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
REGISTER sip:ekiga.net SIP/2.0
CSeq: 9 REGISTER
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bK28f3a590-e43e-e311-9af5-f81a670d972b;rport
User-Agent: Ekiga/4.0.1
From: <sip:el_gallo_azul@ekiga.net>;tag=54a9a590-e43e-e311-9af5-f81a670d972b
Call-ID: 7ea2a590-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:el_gallo_azul@ekiga.net>
Contact: <sip:el_gallo_azul@192.168.178.35:5060>;q=1
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK
Expires: 3600
Content-Length: 0
Max-Forwards: 70


2013/10/29 18:18:35.151	  0:03.624	subscriber...fecb0a5700	OpalUDP	Setting interface to 192.168.178.35%wlan0
2013/10/29 18:18:35.151	  0:03.624	subscriber...fecb0a5700	SIP	Transaction timers set: retry=0.500, completion=6.000
2013/10/29 18:18:35.151	  0:03.624	subscriber...fecb0a5700	PTLib	Thread ended: name="subscriber:0x7efecb0a5700", real=0.004, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%)
2013/10/29 18:18:35.236	  0:03.708	Housekeepe...ff368ea700	SIP	Set state Terminated_Success for REGISTER transaction id=z9hG4bK0aebd68f-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:35.236	  0:03.709	Housekeepe...ff368ea700	SIP	Completed state for transaction id=z9hG4bK0aebd68f-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:35.547	  0:04.020	Opal Liste...ff09dcd700	OpalUDP	Binding to interface: 192.168.178.35:5060
2013/10/29 18:18:35.547	  0:04.020	Opal Liste...ff09dcd700	SIP	Waiting for PDU on udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:35.548	  0:04.020	Opal Liste...ff09dcd700	SIP	PDU received: rem=udp$46.19.58.41:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
SIP/2.0 100 Trying
CSeq: 8 REGISTER
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bK5454a090-e43e-e311-9af5-f81a670d972b;rport=61012;received=118.211.230.192
Server: Generic SIP Proxy
From: <sip:473936@sip.diamondcard.us>;tag=286e908f-e43e-e311-9af5-f81a670d972b
Call-ID: 2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:473936@sip.diamondcard.us>
Content-Length: 0


2013/10/29 18:18:35.548	  0:04.021	Opal Liste...ff09dcd700	SIP	Queueing PDU "8 REGISTER <100>", transaction=z9hG4bK5454a090-e43e-e311-9af5-f81a670d972b, token=2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:35.548	  0:04.021	Opal Liste...ff09dcd700	Opal	Transport clean up on termination
2013/10/29 18:18:35.548	  0:04.021	Opal Liste...ff09dcd700	Opal	Transport Close
2013/10/29 18:18:35.548	  0:04.021	    Pool:0x7efecb168700	SIP	Handling PDU "8 REGISTER <100>" for transaction=z9hG4bK5454a090-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:35.548	  0:04.021	    Pool:0x7efecb168700	SIP	REGISTER transaction id=z9hG4bK5454a090-e43e-e311-9af5-f81a670d972b proceeding.
2013/10/29 18:18:35.548	  0:04.021	Opal Liste...ff09dcd700	Opal	Deleted transport udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:35.548	  0:04.021	    Pool:0x7efecb168700	SIP	Handled PDU "8 REGISTER <100>"
2013/10/29 18:18:35.548	  0:04.021	Opal Liste...ff09dcd700	OpalUDP	Binding to interface: 192.168.178.35:5060
2013/10/29 18:18:35.549	  0:04.021	Opal Liste...ff09dcd700	SIP	Waiting for PDU on udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:35.549	  0:04.022	Opal Liste...ff09dcd700	SIP	PDU received: rem=udp$46.19.58.41:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
SIP/2.0 401 Unauthorized
CSeq: 8 REGISTER
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bK5454a090-e43e-e311-9af5-f81a670d972b;rport=61012;received=118.211.230.192
Server: Generic SIP Proxy
From: <sip:473936@sip.diamondcard.us>;tag=286e908f-e43e-e311-9af5-f81a670d972b
Call-ID: 2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:473936@sip.diamondcard.us>;tag=e0c20796a2acd5434c46ad46445d6de7.44be
Content-Length: 0
WWW-Authenticate: Digest realm="sip.diamondcard.us", nonce="526f76810000f666e519ae3c9c4497fc6447dc6d3ca40ad7"


2013/10/29 18:18:35.549	  0:04.022	Opal Liste...ff09dcd700	SIP	Queueing PDU "8 REGISTER <401>", transaction=z9hG4bK5454a090-e43e-e311-9af5-f81a670d972b, token=2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:35.549	  0:04.022	Opal Liste...ff09dcd700	Opal	Transport clean up on termination
2013/10/29 18:18:35.549	  0:04.022	Opal Liste...ff09dcd700	Opal	Transport Close
2013/10/29 18:18:35.549	  0:04.022	    Pool:0x7efecb168700	SIP	Handling PDU "8 REGISTER <401>" for transaction=z9hG4bK5454a090-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:35.549	  0:04.022	    Pool:0x7efecb168700	SIP	REGISTER transaction id=z9hG4bK5454a090-e43e-e311-9af5-f81a670d972b completing.
2013/10/29 18:18:35.549	  0:04.022	Opal Liste...ff09dcd700	Opal	Deleted transport udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:35.549	  0:04.022	    Pool:0x7efecb168700	SIP	Finalising handlers interface "192.168.178.35%wlan0"
2013/10/29 18:18:35.549	  0:04.022	    Pool:0x7efecb168700	OpalUDP	Setting interface to 192.168.178.35%wlan0
2013/10/29 18:18:35.549	  0:04.022	    Pool:0x7efecb168700	SIP	Received Authentication Required response for z9hG4bK5454a090-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:35.549	  0:04.022	    Pool:0x7efecb168700	SIP	Updating authentication credentials of user "473936" for realm "sip.diamondcard.us"
2013/10/29 18:18:35.550	  0:04.022	    Pool:0x7efecb168700	SIP	Transaction created.
2013/10/29 18:18:35.550	  0:04.023	    Pool:0x7efecb168700	HTTP	Adding authentication information
2013/10/29 18:18:35.550	  0:04.023	    Pool:0x7efecb168700	SIP	Transaction remembering interface "192.168.178.35%wlan0"
2013/10/29 18:18:35.550	  0:04.023	    Pool:0x7efecb168700	DNS	SRV Lookup "_sip._udp.sip.diamondcard.us"
2013/10/29 18:18:35.550	  0:04.023	    Pool:0x7efecb168700	SIP	Attempting SRV record entry 0: 46.19.58.41:5060
2013/10/29 18:18:35.551	  0:04.023	    Pool:0x7efecb168700	SIP	Transaction remote address is udp$46.19.58.41:5060
2013/10/29 18:18:35.551	  0:04.024	    Pool:0x7efecb168700	SIP	Sending PDU (797 bytes) to: rem=udp$46.19.58.41:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
REGISTER sip:sip.diamondcard.us SIP/2.0
CSeq: 10 REGISTER
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bK42ece290-e43e-e311-9af5-f81a670d972b;rport
User-Agent: Ekiga/4.0.1
Authorization: Digest username="473936", realm="sip.diamondcard.us", nonce="526f76810000f666e519ae3c9c4497fc6447dc6d3ca40ad7", uri="sip:sip.diamondcard.us", algorithm=MD5, response="247b393c8d8f4e60695293a6415ee107"
From: <sip:473936@sip.diamondcard.us>;tag=286e908f-e43e-e311-9af5-f81a670d972b
Call-ID: 2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:473936@sip.diamondcard.us>
Contact: <sip:473936@118.211.230.192:61012>;q=1;received=sip:118.211.230.192:61012
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK
Expires: 3600
Content-Length: 0
Max-Forwards: 70


2013/10/29 18:18:35.551	  0:04.024	    Pool:0x7efecb168700	OpalUDP	Setting interface to 192.168.178.35%wlan0
2013/10/29 18:18:35.551	  0:04.024	    Pool:0x7efecb168700	SIP	Transaction timers set: retry=0.500, completion=6.000
2013/10/29 18:18:35.551	  0:04.024	    Pool:0x7efecb168700	SIP	REGISTER transaction id=z9hG4bK5454a090-e43e-e311-9af5-f81a670d972b completed.
2013/10/29 18:18:35.551	  0:04.024	    Pool:0x7efecb168700	SIP	Handled PDU "8 REGISTER <401>"
2013/10/29 18:18:35.572	  0:04.045	Opal Liste...ff09dcd700	OpalUDP	Binding to interface: 192.168.178.35:5060
2013/10/29 18:18:35.572	  0:04.045	Opal Liste...ff09dcd700	SIP	Waiting for PDU on udp$86.64.162.35:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:35.572	  0:04.045	Opal Liste...ff09dcd700	SIP	PDU received: rem=udp$86.64.162.35:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
SIP/2.0 606 Not Acceptable
CSeq: 9 REGISTER
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bK28f3a590-e43e-e311-9af5-f81a670d972b;rport=61012;received=118.211.230.192
Server: Kamailio (1.5.3-notls (i386/linux))
From: <sip:el_gallo_azul@ekiga.net>;tag=54a9a590-e43e-e311-9af5-f81a670d972b
Call-ID: 7ea2a590-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:el_gallo_azul@ekiga.net>;tag=c64e1f832a41ec1c1f4e5673ac5b80f6.faaf
Content-Length: 0


2013/10/29 18:18:35.573	  0:04.046	Opal Liste...ff09dcd700	SIP	Queueing PDU "9 REGISTER <606>", transaction=z9hG4bK28f3a590-e43e-e311-9af5-f81a670d972b, token=7ea2a590-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:35.573	  0:04.046	Opal Liste...ff09dcd700	Opal	Transport clean up on termination
2013/10/29 18:18:35.573	  0:04.046	Opal Liste...ff09dcd700	Opal	Transport Close
2013/10/29 18:18:35.573	  0:04.046	    Pool:0x7efecb168700	SIP	Handling PDU "9 REGISTER <606>" for transaction=z9hG4bK28f3a590-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:35.573	  0:04.046	    Pool:0x7efecb168700	SIP	REGISTER transaction id=z9hG4bK28f3a590-e43e-e311-9af5-f81a670d972b completing.
2013/10/29 18:18:35.573	  0:04.046	Opal Liste...ff09dcd700	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:35.573	  0:04.046	    Pool:0x7efecb168700	SIP	Not retrying REGISTER due to error response 606 Not Acceptable
2013/10/29 18:18:35.573	  0:04.046	    Pool:0x7efecb168700	SIP	Changing REGISTER handler from Subscribing to Unsubscribed, target=sip:el_gallo_azul@ekiga.net, id=7ea2a590-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:35.573	  0:04.046	    Pool:0x7efecb168700	SIP	REGISTER transaction id=z9hG4bK28f3a590-e43e-e311-9af5-f81a670d972b completed.
2013/10/29 18:18:35.573	  0:04.046	    Pool:0x7efecb168700	SIP	Handled PDU "9 REGISTER <606>"
2013/10/29 18:18:35.640	  0:04.112	Housekeepe...ff368ea700	SIP	Set state Terminated_Success for REGISTER transaction id=z9hG4bK5adf1490-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:35.640	  0:04.113	Housekeepe...ff368ea700	SIP	Completed state for transaction id=z9hG4bK5adf1490-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:35.648	  0:04.121	                       	Register failed in HasApplicationLayerGateway mode, aborting registration
2013/10/29 18:18:35.682	  0:04.155	Housekeepe...ff368ea700	SIP	Set state Terminated_Success for REGISTER transaction id=z9hG4bKc2261a90-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:35.683	  0:04.155	Housekeepe...ff368ea700	SIP	Completed state for transaction id=z9hG4bKc2261a90-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:35.683	  0:04.155	Opal Garba...ff367e6700	SIP	Transaction id=z9hG4bK7209928f-e43e-e311-9af5-f81a670d972b destroyed.
2013/10/29 18:18:35.683	  0:04.156	Opal Garba...ff367e6700	SIP	Transaction id=z9hG4bK4430948f-e43e-e311-9af5-f81a670d972b destroyed.
2013/10/29 18:18:35.683	  0:04.156	Opal Garba...ff367e6700	SIP	Transaction id=z9hG4bK5adf1490-e43e-e311-9af5-f81a670d972b destroyed.
2013/10/29 18:18:35.683	  0:04.156	Opal Garba...ff367e6700	SIP	Transaction id=z9hG4bK0aebd68f-e43e-e311-9af5-f81a670d972b destroyed.
2013/10/29 18:18:35.683	  0:04.156	Opal Garba...ff367e6700	Opal	Transport clean up on termination
2013/10/29 18:18:35.683	  0:04.156	Opal Garba...ff367e6700	Opal	Transport Close
2013/10/29 18:18:35.683	  0:04.156	Opal Garba...ff367e6700	Opal	Transport clean up on termination
2013/10/29 18:18:35.683	  0:04.156	Opal Garba...ff367e6700	Opal	Transport Close
2013/10/29 18:18:35.683	  0:04.156	Opal Garba...ff367e6700	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$*:5060>
2013/10/29 18:18:35.684	  0:04.156	Opal Garba...ff367e6700	SIP	Destroyed REGISTER handler for sip:el_gallo_azul@ekiga.net
2013/10/29 18:18:35.684	  0:04.157	Opal Garba...ff367e6700	Opal	Transport clean up on termination
2013/10/29 18:18:35.684	  0:04.157	Opal Garba...ff367e6700	Opal	Transport Close
2013/10/29 18:18:35.684	  0:04.157	Opal Garba...ff367e6700	Opal	Transport clean up on termination
2013/10/29 18:18:35.684	  0:04.157	Opal Garba...ff367e6700	Opal	Transport Close
2013/10/29 18:18:35.684	  0:04.157	Opal Garba...ff367e6700	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$*:5060>
2013/10/29 18:18:35.684	  0:04.157	Opal Garba...ff367e6700	SIP	Destroyed REGISTER handler for sip:el_gallo_azul@ekiga.net
2013/10/29 18:18:35.983	  0:04.456	Opal Liste...ff09dcd700	OpalUDP	Binding to interface: 192.168.178.35:5060
2013/10/29 18:18:35.984	  0:04.456	Opal Liste...ff09dcd700	SIP	Waiting for PDU on udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:35.984	  0:04.457	Opal Liste...ff09dcd700	SIP	PDU received: rem=udp$46.19.58.41:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
SIP/2.0 100 Trying
CSeq: 10 REGISTER
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bK42ece290-e43e-e311-9af5-f81a670d972b;rport=61012;received=118.211.230.192
Server: Generic SIP Proxy
From: <sip:473936@sip.diamondcard.us>;tag=286e908f-e43e-e311-9af5-f81a670d972b
Call-ID: 2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:473936@sip.diamondcard.us>
Content-Length: 0


2013/10/29 18:18:35.984	  0:04.457	Opal Liste...ff09dcd700	SIP	Queueing PDU "10 REGISTER <100>", transaction=z9hG4bK42ece290-e43e-e311-9af5-f81a670d972b, token=2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:35.984	  0:04.457	Opal Liste...ff09dcd700	Opal	Transport clean up on termination
2013/10/29 18:18:35.984	  0:04.457	Opal Liste...ff09dcd700	Opal	Transport Close
2013/10/29 18:18:35.984	  0:04.457	    Pool:0x7efecb168700	SIP	Handling PDU "10 REGISTER <100>" for transaction=z9hG4bK42ece290-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:35.984	  0:04.457	    Pool:0x7efecb168700	SIP	REGISTER transaction id=z9hG4bK42ece290-e43e-e311-9af5-f81a670d972b proceeding.
2013/10/29 18:18:35.984	  0:04.457	Opal Liste...ff09dcd700	Opal	Deleted transport udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:35.984	  0:04.457	    Pool:0x7efecb168700	SIP	Handled PDU "10 REGISTER <100>"
2013/10/29 18:18:35.987	  0:04.460	Opal Liste...ff09dcd700	OpalUDP	Binding to interface: 192.168.178.35:5060
2013/10/29 18:18:35.987	  0:04.460	Opal Liste...ff09dcd700	SIP	Waiting for PDU on udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:35.987	  0:04.460	Opal Liste...ff09dcd700	SIP	PDU received: rem=udp$46.19.58.41:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
SIP/2.0 200 OK
CSeq: 10 REGISTER
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bK42ece290-e43e-e311-9af5-f81a670d972b;rport=61012;received=118.211.230.192
Server: Generic SIP Proxy
From: <sip:473936@sip.diamondcard.us>;tag=286e908f-e43e-e311-9af5-f81a670d972b
Call-ID: 2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:473936@sip.diamondcard.us>;tag=e0c20796a2acd5434c46ad46445d6de7.07ba
Contact: <sip:473936@118.211.230.192:61012>;q=1;expires=3600;received="sip:118.211.230.192:61012", <sip:473936@192.168.178.27:51741;transport=udp>;expires=2428;received="sip:118.211.230.192:51741", <sip:473936@118.211.230.192;uniq=45A9092C6157C5D9ADFCAB362F2C9>;expires=1211
Content-Length: 0


2013/10/29 18:18:35.987	  0:04.460	Opal Liste...ff09dcd700	SIP	Queueing PDU "10 REGISTER <200>", transaction=z9hG4bK42ece290-e43e-e311-9af5-f81a670d972b, token=2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:35.988	  0:04.460	Opal Liste...ff09dcd700	Opal	Transport clean up on termination
2013/10/29 18:18:35.988	  0:04.460	Opal Liste...ff09dcd700	Opal	Transport Close
2013/10/29 18:18:35.988	  0:04.460	    Pool:0x7efecb168700	SIP	Handling PDU "10 REGISTER <200>" for transaction=z9hG4bK42ece290-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:35.988	  0:04.461	    Pool:0x7efecb168700	SIP	REGISTER transaction id=z9hG4bK42ece290-e43e-e311-9af5-f81a670d972b completing.
2013/10/29 18:18:35.988	  0:04.461	Opal Liste...ff09dcd700	Opal	Deleted transport udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:35.988	  0:04.461	    Pool:0x7efecb168700	SIP	Finalising handlers interface "192.168.178.35%wlan0"
2013/10/29 18:18:35.988	  0:04.461	    Pool:0x7efecb168700	OpalUDP	Setting interface to 192.168.178.35%wlan0
2013/10/29 18:18:35.988	  0:04.461	    Pool:0x7efecb168700	SIP	Product Info: name="Generic", version="", vendor="", comments="SIP Proxy"
2013/10/29 18:18:35.989	  0:04.462	    Pool:0x7efecb168700	SIP	Expiry time for REGISTER set to 3600 seconds.
2013/10/29 18:18:35.989	  0:04.462	    Pool:0x7efecb168700	SIP	Changing REGISTER handler from Refreshing to Subscribed, target=sip:473936@sip.diamondcard.us, id=2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:35.989	  0:04.462	    Pool:0x7efecb168700	SIP	REGISTER transaction id=z9hG4bK42ece290-e43e-e311-9af5-f81a670d972b completed.
2013/10/29 18:18:35.989	  0:04.462	    Pool:0x7efecb168700	SIP	Handled PDU "10 REGISTER <200>"
2013/10/29 18:18:36.033	  0:04.506	                       	OpalPres	Creating presentity command 'SIP_Presentity27OpalSetLocalPresenceCommand'
2013/10/29 18:18:36.033	  0:04.506	PThreadObj...fecb22b700	SIPPres	'sip:473936@sip.diamondcard.us' sending own presence Available/I'm available using Ekiga
2013/10/29 18:18:36.033	  0:04.506	                       	SIP	Start SUBSCRIBE
 eventPackage=message-summary
          aor=sip:473936@sip.diamondcard.us
       remote=
        local=
      contact=
        proxy=
       authID=
        realm=
       expire=3600
      restore=30
     minRetry=default
     maxRetry=default
2013/10/29 18:18:36.034	  0:04.506	PThreadObj...fecb22b700	SIPPres	PIDF entity derived from AOR as pres:473936@sip.diamondcard.us
2013/10/29 18:18:36.034	  0:04.506	PThreadObj...fecb22b700	SIP	Start PUBLISH
 eventPackage=presence
          aor=sip:473936@sip.diamondcard.us
       remote=sip:473936@sip.diamondcard.us
        local=
      contact=
        proxy=
       authID=
        realm=
       expire=300
      restore=30
     minRetry=default
     maxRetry=default
2013/10/29 18:18:36.034	  0:04.507	                       	SIP	Constructed SUBSCRIBE handler for sip:473936@sip.diamondcard.us
2013/10/29 18:18:36.034	  0:04.507	                       	SIP	Executing state change to Subscribing for SUBSCRIBE handler, target=sip:473936@sip.diamondcard.us, id=42ca2c91-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:36.034	  0:04.507	PThreadObj...fecb22b700	SIP	Constructed PUBLISH handler for sip:473936@sip.diamondcard.us
2013/10/29 18:18:36.034	  0:04.507	                       	SIP	Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:473936@sip.diamondcard.us, id=42ca2c91-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:36.034	  0:04.507	PThreadObj...fecb22b700	SIP	Executing state change to Subscribing for PUBLISH handler, target=sip:473936@sip.diamondcard.us, id=88cf2c91-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:36.034	  0:04.507	PThreadObj...fecb22b700	SIP	Changing PUBLISH handler from Unavailable to Subscribing, target=sip:473936@sip.diamondcard.us, id=88cf2c91-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:36.034	  0:04.507	                       	DNS	SRV Lookup "_sip._udp.sip.diamondcard.us"
2013/10/29 18:18:36.034	  0:04.507	PThreadObj...fecb22b700	DNS	SRV Lookup "_sip._udp.sip.diamondcard.us"
2013/10/29 18:18:36.034	  0:04.507	                       	SIP	Attempting SRV record entry 0: 46.19.58.41:5060
2013/10/29 18:18:36.034	  0:04.507	PThreadObj...fecb22b700	SIP	Attempting SRV record entry 0: 46.19.58.41:5060
2013/10/29 18:18:36.035	  0:04.508	PThreadObj...fecb22b700	OpalUDP	Binding to interface: 0.0.0.0:5060
2013/10/29 18:18:36.035	  0:04.508	                       	OpalUDP	Binding to interface: 0.0.0.0:5060
2013/10/29 18:18:36.035	  0:04.508	                       	SIP	Created transport udp$46.19.58.41:5060<if=udp$*:5060>
2013/10/29 18:18:36.035	  0:04.508	PThreadObj...fecb22b700	SIP	Created transport udp$46.19.58.41:5060<if=udp$*:5060>
2013/10/29 18:18:36.035	  0:04.508	                       	OpalUDP	Started connect to 46.19.58.41:5060
2013/10/29 18:18:36.035	  0:04.508	PThreadObj...fecb22b700	OpalUDP	Started connect to 46.19.58.41:5060
2013/10/29 18:18:36.035	  0:04.508	                       	OpalUDP	Writing to interface 0 - "192.168.178.35%wlan0"
2013/10/29 18:18:36.035	  0:04.508	PThreadObj...fecb22b700	OpalUDP	Writing to interface 0 - "192.168.178.35%wlan0"
2013/10/29 18:18:36.035	  0:04.508	PThreadObj...fecb22b700	SIP	Transaction created.
2013/10/29 18:18:36.036	  0:04.508	                       	SIP	Transaction created.
2013/10/29 18:18:36.036	  0:04.508	PThreadObj...fecb22b700	SIP	Transaction remembering interface "192.168.178.35%wlan0"
2013/10/29 18:18:36.036	  0:04.509	PThreadObj...fecb22b700	DNS	SRV Lookup "_sip._udp.sip.diamondcard.us"
2013/10/29 18:18:36.036	  0:04.509	PThreadObj...fecb22b700	SIP	Attempting SRV record entry 0: 46.19.58.41:5060
2013/10/29 18:18:36.036	  0:04.509	PThreadObj...fecb22b700	SIP	Transaction remote address is udp$46.19.58.41:5060
2013/10/29 18:18:36.036	  0:04.509	PThreadObj...fecb22b700	SIP	Sending PDU (931 bytes) to: rem=udp$46.19.58.41:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
PUBLISH sip:473936@sip.diamondcard.us SIP/2.0
CSeq: 11 PUBLISH
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bK24062d91-e43e-e311-9af5-f81a670d972b;rport
User-Agent: Ekiga/4.0.1
From: <sip:473936@sip.diamondcard.us>
Call-ID: 88cf2c91-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:473936@sip.diamondcard.us>
Expires: 300
Event: presence
Content-Length: 502
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:473936@sip.diamondcard.us">
  <tuple id="TFA9B2B9A">
    <status>
      <basic>open</basic>
    </status>
    <contact priority="1">sip:473936@sip.diamondcard.us</contact>
    <note>I&apos;m available using Ekiga</note>
    <timestamp>2013-10-29T18:18:36+09:30</timestamp>
  </tuple>
</presence>

2013/10/29 18:18:36.036	  0:04.509	PThreadObj...fecb22b700	OpalUDP	Setting interface to 192.168.178.35%wlan0
2013/10/29 18:18:36.036	  0:04.509	                       	SIP	Transaction remembering interface "192.168.178.35%wlan0"
2013/10/29 18:18:36.036	  0:04.509	PThreadObj...fecb22b700	SIP	Transaction timers set: retry=0.500, completion=6.000
2013/10/29 18:18:36.036	  0:04.509	                       	DNS	SRV Lookup "_sip._udp.sip.diamondcard.us"
2013/10/29 18:18:36.037	  0:04.509	                       	SIP	Attempting SRV record entry 0: 46.19.58.41:5060
2013/10/29 18:18:36.037	  0:04.510	                       	SIP	Transaction remote address is udp$46.19.58.41:5060
2013/10/29 18:18:36.037	  0:04.510	                       	SIP	Sending PDU (652 bytes) to: rem=udp$46.19.58.41:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
SUBSCRIBE sip:473936@sip.diamondcard.us SIP/2.0
CSeq: 1 SUBSCRIBE
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bK980f2d91-e43e-e311-9af5-f81a670d972b;rport
User-Agent: Ekiga/4.0.1
From: <sip:473936@sip.diamondcard.us>;tag=46072d91-e43e-e311-9af5-f81a670d972b
Call-ID: 42ca2c91-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:473936@sip.diamondcard.us>
Accept: application/simple-message-summary
Contact: <sip:473936@118.211.230.192:61012>;received=sip:118.211.230.192:61012
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK
Expires: 3600
Event: message-summary
Content-Length: 0
Max-Forwards: 70


2013/10/29 18:18:36.037	  0:04.510	                       	OpalUDP	Setting interface to 192.168.178.35%wlan0
2013/10/29 18:18:36.037	  0:04.510	                       	SIP	Transaction timers set: retry=0.500, completion=6.000
2013/10/29 18:18:36.037	  0:04.510	                       	OpalPres	Creating presentity command 'SIP_Presentity27OpalSetLocalPresenceCommand'
2013/10/29 18:18:36.037	  0:04.510	                       	Ekiga	Sent its own presence (publish) for sip:473936@sip.diamondcard.us: available, note I'm available using Ekiga
2013/10/29 18:18:36.037	  0:04.510	PThreadObj...fecb22b700	SIPPres	'sip:473936@sip.diamondcard.us' sending own presence Available/I'm available using Ekiga
2013/10/29 18:18:36.037	  0:04.510	                       	OpalPres	Creating presentity command 'SIP_Presentity27OpalSetLocalPresenceCommand'
2013/10/29 18:18:36.037	  0:04.510	PThreadObj...fecb22b700	SIPPres	PIDF entity derived from AOR as pres:473936@sip.diamondcard.us
2013/10/29 18:18:36.037	  0:04.510	                       	Ekiga	Sent its own presence (publish) for sip:el_gallo_azul@ekiga.net: available, note I'm available using Ekiga
2013/10/29 18:18:36.038	  0:04.510	PThreadObj...fecb1a9700	SIPPres	'sip:el_gallo_azul@ekiga.net' sending own presence Available/I'm available using Ekiga
2013/10/29 18:18:36.038	  0:04.510	PThreadObj...fecb22b700	SIP	Start PUBLISH
 eventPackage=presence
          aor=sip:473936@sip.diamondcard.us
       remote=sip:473936@sip.diamondcard.us
        local=
      contact=
        proxy=
       authID=
        realm=
       expire=300
      restore=30
     minRetry=default
     maxRetry=default
2013/10/29 18:18:36.038	  0:04.511	PThreadObj...fecb1a9700	SIPPres	PIDF entity derived from AOR as pres:el_gallo_azul@ekiga.net
2013/10/29 18:18:36.038	  0:04.511	PThreadObj...fecb1a9700	SIP	Start PUBLISH
 eventPackage=presence
          aor=sip:el_gallo_azul@ekiga.net
       remote=sip:el_gallo_azul@ekiga.net
        local=
      contact=
        proxy=
       authID=
        realm=
       expire=300
      restore=30
     minRetry=default
     maxRetry=default
2013/10/29 18:18:36.038	  0:04.511	PThreadObj...fecb22b700	SIP	Queueing state change to Subscribing for PUBLISH handler while in Subscribing state, target=sip:473936@sip.diamondcard.us, id=88cf2c91-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:36.038	  0:04.511	PThreadObj...fecb1a9700	SIP	Constructed PUBLISH handler for sip:el_gallo_azul@ekiga.net
2013/10/29 18:18:36.038	  0:04.511	PThreadObj...fecb1a9700	SIP	Executing state change to Subscribing for PUBLISH handler, target=sip:el_gallo_azul@ekiga.net, id=3c752d91-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:36.038	  0:04.511	PThreadObj...fecb1a9700	SIP	Changing PUBLISH handler from Unavailable to Subscribing, target=sip:el_gallo_azul@ekiga.net, id=3c752d91-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:36.039	  0:04.511	PThreadObj...fecb1a9700	DNS	SRV Lookup "_sip._udp.ekiga.net"
2013/10/29 18:18:36.039	  0:04.511	PThreadObj...fecb1a9700	SIP	Attempting SRV record entry 0: 86.64.162.35:5060
2013/10/29 18:18:36.039	  0:04.512	PThreadObj...fecb1a9700	OpalUDP	Binding to interface: 0.0.0.0:5060
2013/10/29 18:18:36.039	  0:04.512	PThreadObj...fecb1a9700	SIP	Created transport udp$86.64.162.35:5060<if=udp$*:5060>
2013/10/29 18:18:36.039	  0:04.512	PThreadObj...fecb1a9700	OpalUDP	Started connect to 86.64.162.35:5060
2013/10/29 18:18:36.039	  0:04.512	PThreadObj...fecb1a9700	OpalUDP	Writing to interface 0 - "192.168.178.35%wlan0"
2013/10/29 18:18:36.039	  0:04.512	PThreadObj...fecb1a9700	SIP	Transaction created.
2013/10/29 18:18:36.040	  0:04.512	PThreadObj...fecb1a9700	SIP	Transaction remembering interface "192.168.178.35%wlan0"
2013/10/29 18:18:36.040	  0:04.512	PThreadObj...fecb1a9700	DNS	SRV Lookup "_sip._udp.ekiga.net"
2013/10/29 18:18:36.040	  0:04.513	PThreadObj...fecb1a9700	SIP	Attempting SRV record entry 0: 86.64.162.35:5060
2013/10/29 18:18:36.040	  0:04.513	PThreadObj...fecb1a9700	SIP	Transaction remote address is udp$86.64.162.35:5060
2013/10/29 18:18:36.040	  0:04.513	PThreadObj...fecb1a9700	SIP	Sending PDU (921 bytes) to: rem=udp$86.64.162.35:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
PUBLISH sip:el_gallo_azul@ekiga.net SIP/2.0
CSeq: 12 PUBLISH
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bK8a9f2d91-e43e-e311-9af5-f81a670d972b;rport
User-Agent: Ekiga/4.0.1
From: <sip:el_gallo_azul@ekiga.net>
Call-ID: 3c752d91-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:el_gallo_azul@ekiga.net>
Expires: 300
Event: presence
Content-Length: 498
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:el_gallo_azul@ekiga.net">
  <tuple id="TFA9B2B9C">
    <status>
      <basic>open</basic>
    </status>
    <contact priority="1">sip:el_gallo_azul@ekiga.net</contact>
    <note>I&apos;m available using Ekiga</note>
    <timestamp>2013-10-29T18:18:36+09:30</timestamp>
  </tuple>
</presence>

2013/10/29 18:18:36.040	  0:04.513	PThreadObj...fecb1a9700	OpalUDP	Setting interface to 192.168.178.35%wlan0
2013/10/29 18:18:36.040	  0:04.513	PThreadObj...fecb1a9700	SIP	Transaction timers set: retry=0.500, completion=6.000
2013/10/29 18:18:36.065	  0:04.538	Housekeepe...ff368ea700	SIP	Set state Terminated_Success for REGISTER transaction id=z9hG4bK9ce75690-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:36.066	  0:04.538	Housekeepe...ff368ea700	SIP	Completed state for transaction id=z9hG4bK9ce75690-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:36.116	  0:04.589	Housekeepe...ff368ea700	SIP	Set state Terminated_Success for REGISTER transaction id=z9hG4bKd2195d90-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:36.116	  0:04.589	Housekeepe...ff368ea700	SIP	Completed state for transaction id=z9hG4bKd2195d90-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:36.471	  0:04.943	Opal Liste...ff09dcd700	OpalUDP	Binding to interface: 192.168.178.35:5060
2013/10/29 18:18:36.471	  0:04.944	Opal Liste...ff09dcd700	SIP	Waiting for PDU on udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:36.471	  0:04.944	Opal Liste...ff09dcd700	SIP	PDU received: rem=udp$46.19.58.41:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
SIP/2.0 501 Method Not Supported Here
CSeq: 11 PUBLISH
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bK24062d91-e43e-e311-9af5-f81a670d972b;rport=61012;received=118.211.230.192
Server: Generic SIP Proxy
From: <sip:473936@sip.diamondcard.us>
Call-ID: 88cf2c91-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:473936@sip.diamondcard.us>;tag=e0c20796a2acd5434c46ad46445d6de7.636c
Content-Length: 0


2013/10/29 18:18:36.471	  0:04.944	Opal Liste...ff09dcd700	SIP	Queueing PDU "11 PUBLISH <501>", transaction=z9hG4bK24062d91-e43e-e311-9af5-f81a670d972b, token=88cf2c91-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:36.471	  0:04.944	Opal Liste...ff09dcd700	Opal	Transport clean up on termination
2013/10/29 18:18:36.471	  0:04.944	Opal Liste...ff09dcd700	Opal	Transport Close
2013/10/29 18:18:36.472	  0:04.944	    Pool:0x7efecb168700	SIP	Handling PDU "11 PUBLISH <501>" for transaction=z9hG4bK24062d91-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:36.472	  0:04.945	    Pool:0x7efecb168700	SIP	PUBLISH transaction id=z9hG4bK24062d91-e43e-e311-9af5-f81a670d972b completing.
2013/10/29 18:18:36.472	  0:04.944	Opal Liste...ff09dcd700	Opal	Deleted transport udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:36.472	  0:04.945	    Pool:0x7efecb168700	SIP	Not retrying PUBLISH due to error response 501 Not Implemented
2013/10/29 18:18:36.472	  0:04.945	    Pool:0x7efecb168700	SIP	Changing PUBLISH handler from Subscribing to Unsubscribed, target=sip:473936@sip.diamondcard.us, id=88cf2c91-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:36.472	  0:04.945	    Pool:0x7efecb168700	SIP	Changing PUBLISH handler from Unsubscribed to Subscribing, target=sip:473936@sip.diamondcard.us, id=88cf2c91-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:36.472	  0:04.945	    Pool:0x7efecb168700	OpalUDP	Writing to interface 0 - "192.168.178.35%wlan0"
2013/10/29 18:18:36.472	  0:04.945	    Pool:0x7efecb168700	SIP	Transaction created.
2013/10/29 18:18:36.473	  0:04.945	    Pool:0x7efecb168700	SIP	Transaction remembering interface "192.168.178.35%wlan0"
2013/10/29 18:18:36.473	  0:04.945	    Pool:0x7efecb168700	DNS	SRV Lookup "_sip._udp.sip.diamondcard.us"
2013/10/29 18:18:36.473	  0:04.946	    Pool:0x7efecb168700	SIP	Attempting SRV record entry 0: 46.19.58.41:5060
2013/10/29 18:18:36.473	  0:04.946	    Pool:0x7efecb168700	SIP	Transaction remote address is udp$46.19.58.41:5060
2013/10/29 18:18:36.473	  0:04.946	    Pool:0x7efecb168700	SIP	Sending PDU (931 bytes) to: rem=udp$46.19.58.41:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
PUBLISH sip:473936@sip.diamondcard.us SIP/2.0
CSeq: 13 PUBLISH
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bK0ab06f91-e43e-e311-9af5-f81a670d972b;rport
User-Agent: Ekiga/4.0.1
From: <sip:473936@sip.diamondcard.us>
Call-ID: 88cf2c91-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:473936@sip.diamondcard.us>
Expires: 300
Event: presence
Content-Length: 502
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:473936@sip.diamondcard.us">
  <tuple id="TFA9B2B9A">
    <status>
      <basic>open</basic>
    </status>
    <contact priority="1">sip:473936@sip.diamondcard.us</contact>
    <note>I&apos;m available using Ekiga</note>
    <timestamp>2013-10-29T18:18:36+09:30</timestamp>
  </tuple>
</presence>

2013/10/29 18:18:36.473	  0:04.946	    Pool:0x7efecb168700	OpalUDP	Setting interface to 192.168.178.35%wlan0
2013/10/29 18:18:36.473	  0:04.946	    Pool:0x7efecb168700	SIP	Transaction timers set: retry=0.500, completion=6.000
2013/10/29 18:18:36.473	  0:04.946	    Pool:0x7efecb168700	SIP	PUBLISH transaction id=z9hG4bK24062d91-e43e-e311-9af5-f81a670d972b completed.
2013/10/29 18:18:36.473	  0:04.946	    Pool:0x7efecb168700	SIP	Handled PDU "11 PUBLISH <501>"
2013/10/29 18:18:36.478	  0:04.951	Opal Liste...ff09dcd700	OpalUDP	Binding to interface: 192.168.178.35:5060
2013/10/29 18:18:36.478	  0:04.951	Opal Liste...ff09dcd700	SIP	Waiting for PDU on udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:36.478	  0:04.951	Opal Liste...ff09dcd700	SIP	PDU received: rem=udp$46.19.58.41:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
SIP/2.0 501 Method Not Supported Here
CSeq: 1 SUBSCRIBE
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bK980f2d91-e43e-e311-9af5-f81a670d972b;rport=61012;received=118.211.230.192
Server: Generic SIP Proxy
From: <sip:473936@sip.diamondcard.us>;tag=46072d91-e43e-e311-9af5-f81a670d972b
Call-ID: 42ca2c91-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:473936@sip.diamondcard.us>;tag=e0c20796a2acd5434c46ad46445d6de7.3529
Content-Length: 0


2013/10/29 18:18:36.478	  0:04.951	Opal Liste...ff09dcd700	SIP	Queueing PDU "1 SUBSCRIBE <501>", transaction=z9hG4bK980f2d91-e43e-e311-9af5-f81a670d972b, token=42ca2c91-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:36.478	  0:04.951	Opal Liste...ff09dcd700	Opal	Transport clean up on termination
2013/10/29 18:18:36.478	  0:04.951	Opal Liste...ff09dcd700	Opal	Transport Close
2013/10/29 18:18:36.478	  0:04.951	    Pool:0x7efecb168700	SIP	Handling PDU "1 SUBSCRIBE <501>" for transaction=z9hG4bK980f2d91-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:36.479	  0:04.951	    Pool:0x7efecb168700	SIP	SUBSCRIBE transaction id=z9hG4bK980f2d91-e43e-e311-9af5-f81a670d972b completing.
2013/10/29 18:18:36.478	  0:04.951	Opal Liste...ff09dcd700	Opal	Deleted transport udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:36.479	  0:04.952	    Pool:0x7efecb168700	SIP	Not retrying SUBSCRIBE due to error response 501 Not Implemented
2013/10/29 18:18:36.479	  0:04.952	    Pool:0x7efecb168700	SIP	Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:473936@sip.diamondcard.us, id=42ca2c91-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:36.479	  0:04.952	    Pool:0x7efecb168700	SIP	SUBSCRIBE transaction id=z9hG4bK980f2d91-e43e-e311-9af5-f81a670d972b completed.
2013/10/29 18:18:36.479	  0:04.952	    Pool:0x7efecb168700	SIP	Handled PDU "1 SUBSCRIBE <501>"
2013/10/29 18:18:36.479	  0:04.952	Opal Liste...ff09dcd700	OpalUDP	Binding to interface: 192.168.178.35:5060
2013/10/29 18:18:36.479	  0:04.952	Opal Liste...ff09dcd700	SIP	Waiting for PDU on udp$86.64.162.35:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:36.479	  0:04.952	Opal Liste...ff09dcd700	SIP	PDU received: rem=udp$86.64.162.35:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
SIP/2.0 200 OK
CSeq: 12 PUBLISH
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bK8a9f2d91-e43e-e311-9af5-f81a670d972b;rport=61012;received=118.211.230.192
Server: Kamailio (1.5.3-notls (i386/linux))
From: <sip:el_gallo_azul@ekiga.net>
Call-ID: 3c752d91-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:el_gallo_azul@ekiga.net>;tag=f85b0bd16aaafa8479586ac9f88b3198-9101
Expires: 295
Content-Length: 0
SIP-ETag: a.1382863207.7492.785554.0


2013/10/29 18:18:36.480	  0:04.952	Opal Liste...ff09dcd700	SIP	Queueing PDU "12 PUBLISH <200>", transaction=z9hG4bK8a9f2d91-e43e-e311-9af5-f81a670d972b, token=3c752d91-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:36.480	  0:04.952	Opal Liste...ff09dcd700	Opal	Transport clean up on termination
2013/10/29 18:18:36.480	  0:04.952	Opal Liste...ff09dcd700	Opal	Transport Close
2013/10/29 18:18:36.480	  0:04.952	    Pool:0x7efecb168700	SIP	Handling PDU "12 PUBLISH <200>" for transaction=z9hG4bK8a9f2d91-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:36.480	  0:04.953	    Pool:0x7efecb168700	SIP	PUBLISH transaction id=z9hG4bK8a9f2d91-e43e-e311-9af5-f81a670d972b completing.
2013/10/29 18:18:36.480	  0:04.953	Opal Liste...ff09dcd700	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:36.480	  0:04.953	    Pool:0x7efecb168700	SIP	Finalising handlers interface "192.168.178.35%wlan0"
2013/10/29 18:18:36.480	  0:04.953	    Pool:0x7efecb168700	OpalUDP	Setting interface to 192.168.178.35%wlan0
2013/10/29 18:18:36.480	  0:04.953	    Pool:0x7efecb168700	SIP	Expiry time for PUBLISH set to 295 seconds.
2013/10/29 18:18:36.480	  0:04.953	    Pool:0x7efecb168700	SIP	Product Info: name="Kamailio", version="", vendor="", comments="(1.5.3-notls (i386/linux))"
2013/10/29 18:18:36.480	  0:04.953	    Pool:0x7efecb168700	SIP	Changing PUBLISH handler from Subscribing to Subscribed, target=sip:el_gallo_azul@ekiga.net, id=3c752d91-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:36.480	  0:04.953	    Pool:0x7efecb168700	SIP	PUBLISH transaction id=z9hG4bK8a9f2d91-e43e-e311-9af5-f81a670d972b completed.
2013/10/29 18:18:36.480	  0:04.953	    Pool:0x7efecb168700	SIP	Handled PDU "12 PUBLISH <200>"
2013/10/29 18:18:36.561	  0:05.034	Housekeepe...ff368ea700	SIP	Set state Terminated_Success for REGISTER transaction id=z9hG4bK5454a090-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:36.561	  0:05.034	Housekeepe...ff368ea700	SIP	Completed state for transaction id=z9hG4bK5454a090-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:36.587	  0:05.060	Housekeepe...ff368ea700	SIP	Set state Terminated_Success for REGISTER transaction id=z9hG4bK28f3a590-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:36.587	  0:05.060	Housekeepe...ff368ea700	SIP	Completed state for transaction id=z9hG4bK28f3a590-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:36.685	  0:05.157	Opal Garba...ff367e6700	SIP	Transaction id=z9hG4bK9ce75690-e43e-e311-9af5-f81a670d972b destroyed.
2013/10/29 18:18:36.685	  0:05.158	Opal Garba...ff367e6700	SIP	Transaction id=z9hG4bK5454a090-e43e-e311-9af5-f81a670d972b destroyed.
2013/10/29 18:18:36.685	  0:05.158	Opal Garba...ff367e6700	SIP	Transaction id=z9hG4bK28f3a590-e43e-e311-9af5-f81a670d972b destroyed.
2013/10/29 18:18:36.685	  0:05.158	Opal Garba...ff367e6700	SIP	Transaction id=z9hG4bKd2195d90-e43e-e311-9af5-f81a670d972b destroyed.
2013/10/29 18:18:36.685	  0:05.158	Opal Garba...ff367e6700	SIP	Transaction id=z9hG4bKc2261a90-e43e-e311-9af5-f81a670d972b destroyed.
2013/10/29 18:18:36.685	  0:05.158	Opal Garba...ff367e6700	Opal	Transport clean up on termination
2013/10/29 18:18:36.685	  0:05.158	Opal Garba...ff367e6700	Opal	Transport Close
2013/10/29 18:18:36.685	  0:05.158	Opal Garba...ff367e6700	Opal	Transport clean up on termination
2013/10/29 18:18:36.685	  0:05.158	Opal Garba...ff367e6700	Opal	Transport Close
2013/10/29 18:18:36.685	  0:05.158	Opal Garba...ff367e6700	Opal	Deleted transport udp$46.19.58.41:5060<if=udp$*:5060>
2013/10/29 18:18:36.686	  0:05.158	Opal Garba...ff367e6700	SIP	Destroyed SUBSCRIBE handler for sip:473936@sip.diamondcard.us
2013/10/29 18:18:36.907	  0:05.380	Opal Liste...ff09dcd700	OpalUDP	Binding to interface: 192.168.178.35:5060
2013/10/29 18:18:36.907	  0:05.380	Opal Liste...ff09dcd700	SIP	Waiting for PDU on udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:36.907	  0:05.380	Opal Liste...ff09dcd700	SIP	PDU received: rem=udp$46.19.58.41:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
SIP/2.0 501 Method Not Supported Here
CSeq: 13 PUBLISH
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bK0ab06f91-e43e-e311-9af5-f81a670d972b;rport=61012;received=118.211.230.192
Server: Generic SIP Proxy
From: <sip:473936@sip.diamondcard.us>
Call-ID: 88cf2c91-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:473936@sip.diamondcard.us>;tag=e0c20796a2acd5434c46ad46445d6de7.714b
Content-Length: 0


2013/10/29 18:18:36.907	  0:05.380	Opal Liste...ff09dcd700	SIP	Queueing PDU "13 PUBLISH <501>", transaction=z9hG4bK0ab06f91-e43e-e311-9af5-f81a670d972b, token=88cf2c91-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:36.907	  0:05.380	Opal Liste...ff09dcd700	Opal	Transport clean up on termination
2013/10/29 18:18:36.908	  0:05.380	Opal Liste...ff09dcd700	Opal	Transport Close
2013/10/29 18:18:36.908	  0:05.380	    Pool:0x7efecb168700	SIP	Handling PDU "13 PUBLISH <501>" for transaction=z9hG4bK0ab06f91-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:36.908	  0:05.381	    Pool:0x7efecb168700	SIP	PUBLISH transaction id=z9hG4bK0ab06f91-e43e-e311-9af5-f81a670d972b completing.
2013/10/29 18:18:36.908	  0:05.380	Opal Liste...ff09dcd700	Opal	Deleted transport udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:36.908	  0:05.381	    Pool:0x7efecb168700	SIP	Not retrying PUBLISH due to error response 501 Not Implemented
2013/10/29 18:18:36.908	  0:05.381	    Pool:0x7efecb168700	SIP	Changing PUBLISH handler from Subscribing to Unsubscribed, target=sip:473936@sip.diamondcard.us, id=88cf2c91-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:36.908	  0:05.381	    Pool:0x7efecb168700	SIP	PUBLISH transaction id=z9hG4bK0ab06f91-e43e-e311-9af5-f81a670d972b completed.
2013/10/29 18:18:36.908	  0:05.381	    Pool:0x7efecb168700	SIP	Handled PDU "13 PUBLISH <501>"
2013/10/29 18:18:36.998	  0:05.471	Housekeepe...ff368ea700	SIP	Set state Terminated_Success for REGISTER transaction id=z9hG4bK42ece290-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:36.998	  0:05.471	Housekeepe...ff368ea700	SIP	Completed state for transaction id=z9hG4bK42ece290-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:37.473	  0:05.946	Housekeepe...ff368ea700	SIP	Set state Terminated_Success for PUBLISH transaction id=z9hG4bK24062d91-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:37.474	  0:05.946	Housekeepe...ff368ea700	SIP	Completed state for transaction id=z9hG4bK24062d91-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:37.499	  0:05.972	Housekeepe...ff368ea700	SIP	Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bK980f2d91-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:37.499	  0:05.972	Housekeepe...ff368ea700	SIP	Completed state for transaction id=z9hG4bK980f2d91-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:37.499	  0:05.972	Housekeepe...ff368ea700	SIP	Set state Terminated_Success for PUBLISH transaction id=z9hG4bK8a9f2d91-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:37.499	  0:05.972	Housekeepe...ff368ea700	SIP	Completed state for transaction id=z9hG4bK8a9f2d91-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:37.686	  0:06.159	Opal Garba...ff367e6700	SIP	Transaction id=z9hG4bK8a9f2d91-e43e-e311-9af5-f81a670d972b destroyed.
2013/10/29 18:18:37.686	  0:06.159	Opal Garba...ff367e6700	SIP	Transaction id=z9hG4bK980f2d91-e43e-e311-9af5-f81a670d972b destroyed.
2013/10/29 18:18:37.686	  0:06.159	Opal Garba...ff367e6700	SIP	Transaction id=z9hG4bK42ece290-e43e-e311-9af5-f81a670d972b destroyed.
2013/10/29 18:18:37.686	  0:06.159	Opal Garba...ff367e6700	SIP	Transaction id=z9hG4bK24062d91-e43e-e311-9af5-f81a670d972b destroyed.
2013/10/29 18:18:37.687	  0:06.159	Opal Garba...ff367e6700	Opal	Transport clean up on termination
2013/10/29 18:18:37.687	  0:06.160	Opal Garba...ff367e6700	Opal	Transport Close
2013/10/29 18:18:37.687	  0:06.160	Opal Garba...ff367e6700	Opal	Transport clean up on termination
2013/10/29 18:18:37.687	  0:06.160	Opal Garba...ff367e6700	Opal	Transport Close
2013/10/29 18:18:37.687	  0:06.160	Opal Garba...ff367e6700	Opal	Deleted transport udp$46.19.58.41:5060<if=udp$*:5060>
2013/10/29 18:18:37.687	  0:06.160	Opal Garba...ff367e6700	SIP	Destroyed PUBLISH handler for sip:473936@sip.diamondcard.us
2013/10/29 18:18:37.908	  0:06.381	Housekeepe...ff368ea700	SIP	Set state Terminated_Success for PUBLISH transaction id=z9hG4bK0ab06f91-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:37.908	  0:06.381	Housekeepe...ff368ea700	SIP	Completed state for transaction id=z9hG4bK0ab06f91-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:38.687	  0:07.160	Opal Garba...ff367e6700	SIP	Transaction id=z9hG4bK0ab06f91-e43e-e311-9af5-f81a670d972b destroyed.
2013/10/29 18:18:41.892	  0:10.365	Housekeepe...ff368ea700	SIP	NAT Binding refresh for REGISTER to sip:473936@sip.diamondcard.us, id=2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:41.893	  0:10.366	Housekeepe...ff368ea700	SIP	Constructed OPTIONS handler for sip:473936@sip.diamondcard.us
2013/10/29 18:18:41.893	  0:10.366	Housekeepe...ff368ea700	SIP	Changing OPTIONS handler from Unavailable to Subscribed, target=sip:473936@sip.diamondcard.us, id=e6c2aa94-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:41.893	  0:10.366	Housekeepe...ff368ea700	SIP	Executing state change to Unsubscribing for OPTIONS handler, target=sip:473936@sip.diamondcard.us, id=e6c2aa94-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:41.893	  0:10.366	Housekeepe...ff368ea700	SIP	Changing OPTIONS handler from Subscribed to Unsubscribing, target=sip:473936@sip.diamondcard.us, id=e6c2aa94-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:41.893	  0:10.366	Housekeepe...ff368ea700	OpalUDP	Binding to interface: 192.168.178.35:5060
2013/10/29 18:18:41.893	  0:10.366	Housekeepe...ff368ea700	SIP	Created transport udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:41.893	  0:10.366	Housekeepe...ff368ea700	OpalUDP	Started connect to 46.19.58.41:5060
2013/10/29 18:18:41.893	  0:10.366	Housekeepe...ff368ea700	SIP	Transaction created.
2013/10/29 18:18:41.894	  0:10.367	Housekeepe...ff368ea700	SIP	Transaction remembering interface "192.168.178.35%wlan0"
2013/10/29 18:18:41.894	  0:10.367	Housekeepe...ff368ea700	SIP	No SRV lookup as has explicit port number.
2013/10/29 18:18:41.894	  0:10.367	Housekeepe...ff368ea700	SIP	Transaction remote address is udp$46.19.58.41:5060
2013/10/29 18:18:41.894	  0:10.367	Housekeepe...ff368ea700	SIP	Sending PDU (630 bytes) to: rem=udp$46.19.58.41:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
OPTIONS sip:473936@sip.diamondcard.us SIP/2.0
Route: <sip:46.19.58.41:5060;lr>
CSeq: 14 OPTIONS
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bKf8ecaa94-e43e-e311-9af5-f81a670d972b;rport
User-Agent: Ekiga/4.0.1
From: <sip:473936@sip.diamondcard.us>;tag=dee8aa94-e43e-e311-9af5-f81a670d972b
Call-ID: e6c2aa94-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:473936@sip.diamondcard.us>
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


2013/10/29 18:18:41.895	  0:10.367	Housekeepe...ff368ea700	OpalUDP	Setting interface to 192.168.178.35%wlan0
2013/10/29 18:18:41.895	  0:10.368	Housekeepe...ff368ea700	SIP	Transaction timers set: retry=0.500, completion=6.000
2013/10/29 18:18:42.325	  0:10.798	Opal Liste...ff09dcd700	OpalUDP	Binding to interface: 192.168.178.35:5060
2013/10/29 18:18:42.326	  0:10.798	Opal Liste...ff09dcd700	SIP	Waiting for PDU on udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:42.326	  0:10.799	Opal Liste...ff09dcd700	SIP	PDU received: rem=udp$46.19.58.41:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
SIP/2.0 200 Got it
CSeq: 14 OPTIONS
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bKf8ecaa94-e43e-e311-9af5-f81a670d972b;rport=61012;received=118.211.230.192
Server: Generic SIP Proxy
From: <sip:473936@sip.diamondcard.us>;tag=dee8aa94-e43e-e311-9af5-f81a670d972b
Call-ID: e6c2aa94-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:473936@sip.diamondcard.us>;tag=e0c20796a2acd5434c46ad46445d6de7.acec
Content-Length: 0


2013/10/29 18:18:42.326	  0:10.799	Opal Liste...ff09dcd700	SIP	Queueing PDU "14 OPTIONS <200>", transaction=z9hG4bKf8ecaa94-e43e-e311-9af5-f81a670d972b, token=e6c2aa94-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:42.326	  0:10.799	Opal Liste...ff09dcd700	Opal	Transport clean up on termination
2013/10/29 18:18:42.326	  0:10.799	Opal Liste...ff09dcd700	Opal	Transport Close
2013/10/29 18:18:42.326	  0:10.799	    Pool:0x7efecb168700	SIP	Handling PDU "14 OPTIONS <200>" for transaction=z9hG4bKf8ecaa94-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:42.326	  0:10.799	    Pool:0x7efecb168700	SIP	OPTIONS transaction id=z9hG4bKf8ecaa94-e43e-e311-9af5-f81a670d972b completing.
2013/10/29 18:18:42.326	  0:10.799	Opal Liste...ff09dcd700	Opal	Deleted transport udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:42.327	  0:10.799	    Pool:0x7efecb168700	SIP	Finalising handlers interface "192.168.178.35%wlan0"
2013/10/29 18:18:42.327	  0:10.799	    Pool:0x7efecb168700	OpalUDP	Setting interface to 192.168.178.35%wlan0
2013/10/29 18:18:42.327	  0:10.799	    Pool:0x7efecb168700	SIP	Completed OPTIONS command to sip:473936@sip.diamondcard.us;OPAL-proxy=46.19.58.41;OPAL-interface=192.168.178.35%25wlan0, status=200 OK
2013/10/29 18:18:42.327	  0:10.800	    Pool:0x7efecb168700	SIP	Product Info: name="Generic", version="", vendor="", comments="SIP Proxy"
2013/10/29 18:18:42.327	  0:10.800	    Pool:0x7efecb168700	SIP	Changing OPTIONS handler from Unsubscribing to Unsubscribed, target=sip:473936@sip.diamondcard.us, id=e6c2aa94-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:42.327	  0:10.800	    Pool:0x7efecb168700	SIP	OPTIONS transaction id=z9hG4bKf8ecaa94-e43e-e311-9af5-f81a670d972b completed.
2013/10/29 18:18:42.327	  0:10.800	    Pool:0x7efecb168700	SIP	Handled PDU "14 OPTIONS <200>"
2013/10/29 18:18:42.492	  0:10.965	                       	OpalMan	Clearing all calls and waiting, primary thread.
2013/10/29 18:18:42.688	  0:11.161	Opal Garba...ff367e6700	Opal	Transport clean up on termination
2013/10/29 18:18:42.688	  0:11.161	Opal Garba...ff367e6700	Opal	Transport Close
2013/10/29 18:18:42.688	  0:11.161	Opal Garba...ff367e6700	Opal	Transport clean up on termination
2013/10/29 18:18:42.688	  0:11.161	Opal Garba...ff367e6700	Opal	Transport Close
2013/10/29 18:18:42.688	  0:11.161	Opal Garba...ff367e6700	Opal	Deleted transport udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:42.688	  0:11.161	Opal Garba...ff367e6700	SIP	Destroyed OPTIONS handler for sip:473936@sip.diamondcard.us
2013/10/29 18:18:42.689	  0:11.161	                       	OpalMan	All calls cleared.
2013/10/29 18:18:42.689	  0:11.162	                       	OpalMan	Shutting down endpoints.
2013/10/29 18:18:42.689	  0:11.162	                       	OpalMan	Clearing all calls and waiting, primary thread.
2013/10/29 18:18:43.328	  0:11.801	Housekeepe...ff368ea700	SIP	Set state Terminated_Success for OPTIONS transaction id=z9hG4bKf8ecaa94-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:43.328	  0:11.801	Housekeepe...ff368ea700	SIP	Completed state for transaction id=z9hG4bKf8ecaa94-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:43.689	  0:12.162	Opal Garba...ff367e6700	SIP	Transaction id=z9hG4bKf8ecaa94-e43e-e311-9af5-f81a670d972b destroyed.
2013/10/29 18:18:43.689	  0:12.162	                       	OpalMan	All calls cleared.
2013/10/29 18:18:43.689	  0:12.162	                       	OpalPres	'sip:473936@sip.diamondcard.us' closing.
2013/10/29 18:18:43.689	  0:12.162	                       	OpalPres	Stopping command thread PThreadObj:0x7efecb22b700
2013/10/29 18:18:43.689	  0:12.162	PThreadObj...fecb22b700	OpalPres	Command thread ended
2013/10/29 18:18:43.689	  0:12.162	PThreadObj...fecb22b700	PTLib	Thread ended: name="PThreadObj:0x7efecb22b700", real=10.359, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%)
2013/10/29 18:18:43.699	  0:12.172	                       	SIPPres	'sip:473936@sip.diamondcard.us' sending own presence No Presence/
2013/10/29 18:18:43.700	  0:12.172	                       	SIPPres	PIDF entity derived from AOR as pres:473936@sip.diamondcard.us
2013/10/29 18:18:43.700	  0:12.173	                       	SIP	Start PUBLISH
 eventPackage=presence
          aor=sip:473936@sip.diamondcard.us
       remote=sip:473936@sip.diamondcard.us
        local=
      contact=
        proxy=
       authID=
        realm=
       expire=300
      restore=30
     minRetry=default
     maxRetry=default
2013/10/29 18:18:43.701	  0:12.173	                       	SIP	Constructed PUBLISH handler for sip:473936@sip.diamondcard.us
2013/10/29 18:18:43.701	  0:12.174	                       	SIP	Executing state change to Subscribing for PUBLISH handler, target=sip:473936@sip.diamondcard.us, id=f49fbe95-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:43.701	  0:12.174	                       	SIP	Changing PUBLISH handler from Unavailable to Subscribing, target=sip:473936@sip.diamondcard.us, id=f49fbe95-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:43.701	  0:12.174	                       	DNS	SRV Lookup "_sip._udp.sip.diamondcard.us"
2013/10/29 18:18:43.701	  0:12.174	                       	SIP	Attempting SRV record entry 0: 46.19.58.41:5060
2013/10/29 18:18:43.701	  0:12.174	                       	OpalUDP	Binding to interface: 0.0.0.0:5060
2013/10/29 18:18:43.701	  0:12.174	                       	SIP	Created transport udp$46.19.58.41:5060<if=udp$*:5060>
2013/10/29 18:18:43.701	  0:12.174	                       	OpalUDP	Started connect to 46.19.58.41:5060
2013/10/29 18:18:43.701	  0:12.174	                       	OpalUDP	Writing to interface 0 - "192.168.178.35%wlan0"
2013/10/29 18:18:43.701	  0:12.174	                       	SIP	Transaction created.
2013/10/29 18:18:43.702	  0:12.175	                       	SIP	Transaction remembering interface "192.168.178.35%wlan0"
2013/10/29 18:18:43.702	  0:12.175	                       	DNS	SRV Lookup "_sip._udp.sip.diamondcard.us"
2013/10/29 18:18:43.702	  0:12.175	                       	SIP	Attempting SRV record entry 0: 46.19.58.41:5060
2013/10/29 18:18:43.702	  0:12.175	                       	SIP	Transaction remote address is udp$46.19.58.41:5060
2013/10/29 18:18:43.702	  0:12.175	                       	SIP	Sending PDU (884 bytes) to: rem=udp$46.19.58.41:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
PUBLISH sip:473936@sip.diamondcard.us SIP/2.0
CSeq: 15 PUBLISH
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bKaeccbe95-e43e-e311-9af5-f81a670d972b;rport
User-Agent: Ekiga/4.0.1
From: <sip:473936@sip.diamondcard.us>
Call-ID: f49fbe95-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:473936@sip.diamondcard.us>
Expires: 300
Event: presence
Content-Length: 455
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:473936@sip.diamondcard.us">
  <tuple id="TFA9B2B9A">
    <status>
      <basic>closed</basic>
    </status>
    <contact priority="1">sip:473936@sip.diamondcard.us</contact>
    <timestamp>2013-10-29T18:18:43+09:30</timestamp>
  </tuple>
</presence>

2013/10/29 18:18:43.703	  0:12.175	                       	OpalUDP	Setting interface to 192.168.178.35%wlan0
2013/10/29 18:18:43.703	  0:12.175	                       	SIP	Transaction timers set: retry=0.500, completion=6.000
2013/10/29 18:18:43.703	  0:12.176	                       	SIPPres	'sip:473936@sip.diamondcard.us' sending final unsubscribe for own presence watcher
2013/10/29 18:18:43.703	  0:12.176	                       	SIP	Expiry time for SUBSCRIBE set to 300 seconds.
2013/10/29 18:18:43.703	  0:12.176	                       	SIP	Changing SUBSCRIBE handler from Unavailable to Unsubscribed, target=sip:473936@sip.diamondcard.us;OPAL-local-id=sip:473936%40sip.diamondcard.us, id=cc6f908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:43.703	  0:12.176	                       	SIP	Start PUBLISH
 eventPackage=presence
          aor=sip:473936@sip.diamondcard.us
       remote=
        local=
      contact=
        proxy=
       authID=
        realm=
       expire=0
      restore=30
     minRetry=default
     maxRetry=default
2013/10/29 18:18:43.703	  0:12.176	                       	SIP	Queueing state change to Subscribing for PUBLISH handler while in Subscribing state, target=sip:473936@sip.diamondcard.us, id=f49fbe95-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:43.703	  0:12.176	                       	SIPPres	'sip:473936@sip.diamondcard.us' awaiting unsubscriptions to complete.
2013/10/29 18:18:43.703	  0:12.176	                       	SIPPres	'sip:473936@sip.diamondcard.us' closed.
2013/10/29 18:18:43.704	  0:12.176	                       	OpalPres	'sip:el_gallo_azul@ekiga.net' closing.
2013/10/29 18:18:43.704	  0:12.176	                       	OpalPres	Stopping command thread PThreadObj:0x7efecb1a9700
2013/10/29 18:18:43.704	  0:12.176	PThreadObj...fecb1a9700	OpalPres	Command thread ended
2013/10/29 18:18:43.704	  0:12.177	PThreadObj...fecb1a9700	PTLib	Thread ended: name="PThreadObj:0x7efecb1a9700", real=10.373, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%)
2013/10/29 18:18:43.714	  0:12.187	                       	SIPPres	'sip:el_gallo_azul@ekiga.net' sending own presence No Presence/
2013/10/29 18:18:43.714	  0:12.187	                       	SIPPres	PIDF entity derived from AOR as pres:el_gallo_azul@ekiga.net
2013/10/29 18:18:43.714	  0:12.187	                       	SIP	Start PUBLISH
 eventPackage=presence
          aor=sip:el_gallo_azul@ekiga.net
       remote=sip:el_gallo_azul@ekiga.net
        local=
      contact=
        proxy=
       authID=
        realm=
       expire=300
      restore=30
     minRetry=default
     maxRetry=default
2013/10/29 18:18:43.714	  0:12.187	                       	SIP	Executing state change to Subscribing for PUBLISH handler, target=sip:el_gallo_azul@ekiga.net, id=3c752d91-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:43.714	  0:12.187	                       	SIP	Changing PUBLISH handler from Subscribed to Subscribing, target=sip:el_gallo_azul@ekiga.net, id=3c752d91-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:43.714	  0:12.187	                       	SIP	Transaction created.
2013/10/29 18:18:43.715	  0:12.188	                       	SIP	Transaction remembering interface "192.168.178.35%wlan0"
2013/10/29 18:18:43.715	  0:12.188	                       	DNS	SRV Lookup "_sip._udp.ekiga.net"
2013/10/29 18:18:43.715	  0:12.188	                       	SIP	Attempting SRV record entry 0: 86.64.162.35:5060
2013/10/29 18:18:43.715	  0:12.188	                       	SIP	Transaction remote address is udp$86.64.162.35:5060
2013/10/29 18:18:43.715	  0:12.188	                       	SIP	Sending PDU (916 bytes) to: rem=udp$86.64.162.35:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
PUBLISH sip:el_gallo_azul@ekiga.net SIP/2.0
CSeq: 16 PUBLISH
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bK78c4c095-e43e-e311-9af5-f81a670d972b;rport
User-Agent: Ekiga/4.0.1
From: <sip:el_gallo_azul@ekiga.net>
Call-ID: 3c752d91-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:el_gallo_azul@ekiga.net>
SIP-If-Match: a.1382863207.7492.785554.0
Expires: 300
Event: presence
Content-Length: 451
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:el_gallo_azul@ekiga.net">
  <tuple id="TFA9B2B9C">
    <status>
      <basic>closed</basic>
    </status>
    <contact priority="1">sip:el_gallo_azul@ekiga.net</contact>
    <timestamp>2013-10-29T18:18:43+09:30</timestamp>
  </tuple>
</presence>

2013/10/29 18:18:43.715	  0:12.188	                       	OpalUDP	Setting interface to 192.168.178.35%wlan0
2013/10/29 18:18:43.716	  0:12.188	                       	SIP	Transaction timers set: retry=0.500, completion=6.000
2013/10/29 18:18:43.716	  0:12.188	                       	SIPPres	'sip:el_gallo_azul@ekiga.net' sending final unsubscribe for own presence watcher
2013/10/29 18:18:43.716	  0:12.189	                       	SIP	Expiry time for SUBSCRIBE set to 300 seconds.
2013/10/29 18:18:43.716	  0:12.189	                       	SIP	Changing SUBSCRIBE handler from Unavailable to Unsubscribed, target=sip:el_gallo_azul@ekiga.net;OPAL-local-id=sip:el_gallo_azul%40ekiga.net, id=d452918f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:43.716	  0:12.189	                       	SIP	Start PUBLISH
 eventPackage=presence
          aor=sip:el_gallo_azul@ekiga.net
       remote=
        local=
      contact=
        proxy=
       authID=
        realm=
       expire=0
      restore=30
     minRetry=default
     maxRetry=default
2013/10/29 18:18:43.716	  0:12.189	                       	SIP	Queueing state change to Subscribing for PUBLISH handler while in Subscribing state, target=sip:el_gallo_azul@ekiga.net, id=3c752d91-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:43.716	  0:12.189	                       	SIPPres	'sip:el_gallo_azul@ekiga.net' awaiting unsubscriptions to complete.
2013/10/29 18:18:43.716	  0:12.189	                       	SIPPres	'sip:el_gallo_azul@ekiga.net' closed.
2013/10/29 18:18:43.716	  0:12.189	                       	OpalEP	pc endpoint shutting down.
2013/10/29 18:18:43.716	  0:12.189	                       	SIP	Shutting down.
2013/10/29 18:18:43.716	  0:12.189	                       	SIP	Changing REGISTER handler from Subscribed to Unsubscribing, target=sip:473936@sip.diamondcard.us, id=2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:43.717	  0:12.190	                       	SIP	Transaction created.
2013/10/29 18:18:43.717	  0:12.190	                       	HTTP	Adding authentication information
2013/10/29 18:18:43.717	  0:12.190	                       	SIP	Transaction remembering interface "192.168.178.35%wlan0"
2013/10/29 18:18:43.717	  0:12.190	                       	DNS	SRV Lookup "_sip._udp.sip.diamondcard.us"
2013/10/29 18:18:43.718	  0:12.190	                       	SIP	Attempting SRV record entry 0: 46.19.58.41:5060
2013/10/29 18:18:43.718	  0:12.190	                       	SIP	Transaction remote address is udp$46.19.58.41:5060
2013/10/29 18:18:43.718	  0:12.191	                       	SIP	Sending PDU (794 bytes) to: rem=udp$46.19.58.41:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
REGISTER sip:sip.diamondcard.us SIP/2.0
CSeq: 17 REGISTER
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bKba1dc195-e43e-e311-9af5-f81a670d972b;rport
User-Agent: Ekiga/4.0.1
Authorization: Digest username="473936", realm="sip.diamondcard.us", nonce="526f76810000f666e519ae3c9c4497fc6447dc6d3ca40ad7", uri="sip:sip.diamondcard.us", algorithm=MD5, response="247b393c8d8f4e60695293a6415ee107"
From: <sip:473936@sip.diamondcard.us>;tag=286e908f-e43e-e311-9af5-f81a670d972b
Call-ID: 2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:473936@sip.diamondcard.us>
Contact: <sip:473936@118.211.230.192:61012>;q=1;received=sip:118.211.230.192:61012
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK
Expires: 0
Content-Length: 0
Max-Forwards: 70


2013/10/29 18:18:43.718	  0:12.191	                       	OpalUDP	Setting interface to 192.168.178.35%wlan0
2013/10/29 18:18:43.718	  0:12.191	                       	SIP	Transaction timers set: retry=0.500, completion=6.000
2013/10/29 18:18:43.718	  0:12.191	                       	SIP	Attempting to abort PUBLISH transaction id=z9hG4bK78c4c095-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:43.718	  0:12.191	                       	SIP	Set state Terminated_Aborted for PUBLISH transaction id=z9hG4bK78c4c095-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:43.718	  0:12.191	                       	SIP	Not retrying PUBLISH due to error response 487 Request Terminated
2013/10/29 18:18:43.718	  0:12.191	                       	SIP	Changing PUBLISH handler from Subscribing to Unsubscribed, target=sip:el_gallo_azul@ekiga.net, id=3c752d91-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:43.718	  0:12.191	                       	SIP	Completed state for transaction id=z9hG4bK78c4c095-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:43.719	  0:12.191	                       	SIP	Attempting to abort PUBLISH transaction id=z9hG4bKaeccbe95-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:43.719	  0:12.191	                       	SIP	Set state Terminated_Aborted for PUBLISH transaction id=z9hG4bKaeccbe95-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:43.719	  0:12.192	                       	SIP	Not retrying PUBLISH due to error response 487 Request Terminated
2013/10/29 18:18:43.719	  0:12.192	                       	SIP	Changing PUBLISH handler from Subscribing to Unsubscribed, target=sip:473936@sip.diamondcard.us, id=f49fbe95-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:43.719	  0:12.192	                       	SIP	Completed state for transaction id=z9hG4bKaeccbe95-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:44.137	  0:12.610	Opal Liste...ff09dcd700	OpalUDP	Binding to interface: 192.168.178.35:5060
2013/10/29 18:18:44.137	  0:12.610	Opal Liste...ff09dcd700	SIP	Waiting for PDU on udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:44.137	  0:12.610	Opal Liste...ff09dcd700	SIP	PDU received: rem=udp$46.19.58.41:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
SIP/2.0 501 Method Not Supported Here
CSeq: 15 PUBLISH
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bKaeccbe95-e43e-e311-9af5-f81a670d972b;rport=61012;received=118.211.230.192
Server: Generic SIP Proxy
From: <sip:473936@sip.diamondcard.us>
Call-ID: f49fbe95-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:473936@sip.diamondcard.us>;tag=e0c20796a2acd5434c46ad46445d6de7.946c
Content-Length: 0


2013/10/29 18:18:44.137	  0:12.610	Opal Liste...ff09dcd700	SIP	Queueing PDU "15 PUBLISH <501>", transaction=z9hG4bKaeccbe95-e43e-e311-9af5-f81a670d972b, token=z9hG4bKaeccbe95-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:44.138	  0:12.610	Opal Liste...ff09dcd700	Opal	Transport clean up on termination
2013/10/29 18:18:44.138	  0:12.610	Opal Liste...ff09dcd700	Opal	Transport Close
2013/10/29 18:18:44.138	  0:12.611	    Pool:0x7efecb168700	SIP	Handling PDU "15 PUBLISH <501>" for transaction=z9hG4bKaeccbe95-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:44.138	  0:12.611	Opal Liste...ff09dcd700	Opal	Deleted transport udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:44.138	  0:12.611	    Pool:0x7efecb168700	SIP	Ignoring duplicate response to PUBLISH transaction id=z9hG4bKaeccbe95-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:44.138	  0:12.611	    Pool:0x7efecb168700	SIP	Handled PDU "15 PUBLISH <501>"
2013/10/29 18:18:44.141	  0:12.614	Opal Liste...ff09dcd700	OpalUDP	Binding to interface: 192.168.178.35:5060
2013/10/29 18:18:44.141	  0:12.614	Opal Liste...ff09dcd700	SIP	Waiting for PDU on udp$86.64.162.35:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:44.141	  0:12.614	Opal Liste...ff09dcd700	SIP	PDU received: rem=udp$86.64.162.35:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
SIP/2.0 200 OK
CSeq: 16 PUBLISH
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bK78c4c095-e43e-e311-9af5-f81a670d972b;rport=61012;received=118.211.230.192
Server: Kamailio (1.5.3-notls (i386/linux))
From: <sip:el_gallo_azul@ekiga.net>
Call-ID: 3c752d91-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:el_gallo_azul@ekiga.net>;tag=f85b0bd16aaafa8479586ac9f88b3198-ba20
Expires: 295
Content-Length: 0
SIP-ETag: a.1382863207.7492.785580.1


2013/10/29 18:18:44.141	  0:12.614	Opal Liste...ff09dcd700	SIP	Queueing PDU "16 PUBLISH <200>", transaction=z9hG4bK78c4c095-e43e-e311-9af5-f81a670d972b, token=z9hG4bK78c4c095-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:44.141	  0:12.614	Opal Liste...ff09dcd700	Opal	Transport clean up on termination
2013/10/29 18:18:44.141	  0:12.614	Opal Liste...ff09dcd700	Opal	Transport Close
2013/10/29 18:18:44.141	  0:12.614	    Pool:0x7efecb168700	SIP	Handling PDU "16 PUBLISH <200>" for transaction=z9hG4bK78c4c095-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:44.142	  0:12.614	    Pool:0x7efecb168700	SIP	Ignoring duplicate response to PUBLISH transaction id=z9hG4bK78c4c095-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:44.141	  0:12.614	Opal Liste...ff09dcd700	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:44.142	  0:12.614	    Pool:0x7efecb168700	SIP	Handled PDU "16 PUBLISH <200>"
2013/10/29 18:18:44.158	  0:12.631	Opal Liste...ff09dcd700	OpalUDP	Binding to interface: 192.168.178.35:5060
2013/10/29 18:18:44.158	  0:12.631	Opal Liste...ff09dcd700	SIP	Waiting for PDU on udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:44.158	  0:12.631	Opal Liste...ff09dcd700	SIP	PDU received: rem=udp$46.19.58.41:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
SIP/2.0 100 Trying
CSeq: 17 REGISTER
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bKba1dc195-e43e-e311-9af5-f81a670d972b;rport=61012;received=118.211.230.192
Server: Generic SIP Proxy
From: <sip:473936@sip.diamondcard.us>;tag=286e908f-e43e-e311-9af5-f81a670d972b
Call-ID: 2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:473936@sip.diamondcard.us>
Content-Length: 0


2013/10/29 18:18:44.158	  0:12.631	Opal Liste...ff09dcd700	SIP	Queueing PDU "17 REGISTER <100>", transaction=z9hG4bKba1dc195-e43e-e311-9af5-f81a670d972b, token=2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:44.159	  0:12.631	Opal Liste...ff09dcd700	Opal	Transport clean up on termination
2013/10/29 18:18:44.159	  0:12.631	Opal Liste...ff09dcd700	Opal	Transport Close
2013/10/29 18:18:44.159	  0:12.632	    Pool:0x7efecb168700	SIP	Handling PDU "17 REGISTER <100>" for transaction=z9hG4bKba1dc195-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:44.159	  0:12.632	Opal Liste...ff09dcd700	Opal	Deleted transport udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:44.159	  0:12.632	    Pool:0x7efecb168700	SIP	REGISTER transaction id=z9hG4bKba1dc195-e43e-e311-9af5-f81a670d972b proceeding.
2013/10/29 18:18:44.159	  0:12.632	    Pool:0x7efecb168700	SIP	Handled PDU "17 REGISTER <100>"
2013/10/29 18:18:44.160	  0:12.632	Opal Liste...ff09dcd700	OpalUDP	Binding to interface: 192.168.178.35:5060
2013/10/29 18:18:44.160	  0:12.633	Opal Liste...ff09dcd700	SIP	Waiting for PDU on udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:44.160	  0:12.633	Opal Liste...ff09dcd700	SIP	PDU received: rem=udp$46.19.58.41:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
SIP/2.0 401 Unauthorized
CSeq: 17 REGISTER
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bKba1dc195-e43e-e311-9af5-f81a670d972b;rport=61012;received=118.211.230.192
Server: Generic SIP Proxy
From: <sip:473936@sip.diamondcard.us>;tag=286e908f-e43e-e311-9af5-f81a670d972b
Call-ID: 2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:473936@sip.diamondcard.us>;tag=e0c20796a2acd5434c46ad46445d6de7.3410
Content-Length: 0
WWW-Authenticate: Digest realm="sip.diamondcard.us", nonce="526f76890000f6700ebc8c84efeda4a119b2dc6fb76036df"


2013/10/29 18:18:44.160	  0:12.633	Opal Liste...ff09dcd700	SIP	Queueing PDU "17 REGISTER <401>", transaction=z9hG4bKba1dc195-e43e-e311-9af5-f81a670d972b, token=2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:44.160	  0:12.633	Opal Liste...ff09dcd700	Opal	Transport clean up on termination
2013/10/29 18:18:44.160	  0:12.633	Opal Liste...ff09dcd700	Opal	Transport Close
2013/10/29 18:18:44.160	  0:12.633	    Pool:0x7efecb168700	SIP	Handling PDU "17 REGISTER <401>" for transaction=z9hG4bKba1dc195-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:44.160	  0:12.633	    Pool:0x7efecb168700	SIP	REGISTER transaction id=z9hG4bKba1dc195-e43e-e311-9af5-f81a670d972b completing.
2013/10/29 18:18:44.160	  0:12.633	Opal Liste...ff09dcd700	Opal	Deleted transport udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:44.160	  0:12.633	    Pool:0x7efecb168700	SIP	Finalising handlers interface "192.168.178.35%wlan0"
2013/10/29 18:18:44.160	  0:12.633	    Pool:0x7efecb168700	OpalUDP	Setting interface to 192.168.178.35%wlan0
2013/10/29 18:18:44.161	  0:12.633	    Pool:0x7efecb168700	SIP	Received Authentication Required response for z9hG4bKba1dc195-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:44.161	  0:12.633	    Pool:0x7efecb168700	SIP	Updating authentication credentials of user "473936" for realm "sip.diamondcard.us"
2013/10/29 18:18:44.161	  0:12.634	    Pool:0x7efecb168700	SIP	Transaction created.
2013/10/29 18:18:44.161	  0:12.634	    Pool:0x7efecb168700	HTTP	Adding authentication information
2013/10/29 18:18:44.161	  0:12.634	    Pool:0x7efecb168700	SIP	Transaction remembering interface "192.168.178.35%wlan0"
2013/10/29 18:18:44.162	  0:12.634	    Pool:0x7efecb168700	DNS	SRV Lookup "_sip._udp.sip.diamondcard.us"
2013/10/29 18:18:44.162	  0:12.634	    Pool:0x7efecb168700	SIP	Attempting SRV record entry 0: 46.19.58.41:5060
2013/10/29 18:18:44.162	  0:12.635	    Pool:0x7efecb168700	SIP	Transaction remote address is udp$46.19.58.41:5060
2013/10/29 18:18:44.162	  0:12.635	    Pool:0x7efecb168700	SIP	Sending PDU (794 bytes) to: rem=udp$46.19.58.41:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
REGISTER sip:sip.diamondcard.us SIP/2.0
CSeq: 18 REGISTER
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bK30e10496-e43e-e311-9af5-f81a670d972b;rport
User-Agent: Ekiga/4.0.1
Authorization: Digest username="473936", realm="sip.diamondcard.us", nonce="526f76890000f6700ebc8c84efeda4a119b2dc6fb76036df", uri="sip:sip.diamondcard.us", algorithm=MD5, response="423f15dd737d82c06f0b95e11af812df"
From: <sip:473936@sip.diamondcard.us>;tag=286e908f-e43e-e311-9af5-f81a670d972b
Call-ID: 2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:473936@sip.diamondcard.us>
Contact: <sip:473936@118.211.230.192:61012>;q=1;received=sip:118.211.230.192:61012
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING,PRACK
Expires: 0
Content-Length: 0
Max-Forwards: 70


2013/10/29 18:18:44.162	  0:12.635	    Pool:0x7efecb168700	OpalUDP	Setting interface to 192.168.178.35%wlan0
2013/10/29 18:18:44.162	  0:12.635	    Pool:0x7efecb168700	SIP	Transaction timers set: retry=0.500, completion=6.000
2013/10/29 18:18:44.162	  0:12.635	    Pool:0x7efecb168700	SIP	REGISTER transaction id=z9hG4bKba1dc195-e43e-e311-9af5-f81a670d972b completed.
2013/10/29 18:18:44.162	  0:12.635	    Pool:0x7efecb168700	SIP	Handled PDU "17 REGISTER <401>"
2013/10/29 18:18:44.595	  0:13.068	Opal Liste...ff09dcd700	OpalUDP	Binding to interface: 192.168.178.35:5060
2013/10/29 18:18:44.595	  0:13.068	Opal Liste...ff09dcd700	SIP	Waiting for PDU on udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:44.596	  0:13.068	Opal Liste...ff09dcd700	SIP	PDU received: rem=udp$46.19.58.41:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
SIP/2.0 100 Trying
CSeq: 18 REGISTER
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bK30e10496-e43e-e311-9af5-f81a670d972b;rport=61012;received=118.211.230.192
Server: Generic SIP Proxy
From: <sip:473936@sip.diamondcard.us>;tag=286e908f-e43e-e311-9af5-f81a670d972b
Call-ID: 2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:473936@sip.diamondcard.us>
Content-Length: 0


2013/10/29 18:18:44.596	  0:13.069	Opal Liste...ff09dcd700	SIP	Queueing PDU "18 REGISTER <100>", transaction=z9hG4bK30e10496-e43e-e311-9af5-f81a670d972b, token=2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:44.596	  0:13.069	Opal Liste...ff09dcd700	Opal	Transport clean up on termination
2013/10/29 18:18:44.596	  0:13.069	Opal Liste...ff09dcd700	Opal	Transport Close
2013/10/29 18:18:44.596	  0:13.069	    Pool:0x7efecb168700	SIP	Handling PDU "18 REGISTER <100>" for transaction=z9hG4bK30e10496-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:44.596	  0:13.069	Opal Liste...ff09dcd700	Opal	Deleted transport udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:44.596	  0:13.069	    Pool:0x7efecb168700	SIP	REGISTER transaction id=z9hG4bK30e10496-e43e-e311-9af5-f81a670d972b proceeding.
2013/10/29 18:18:44.596	  0:13.069	    Pool:0x7efecb168700	SIP	Handled PDU "18 REGISTER <100>"
2013/10/29 18:18:44.598	  0:13.071	Opal Liste...ff09dcd700	OpalUDP	Binding to interface: 192.168.178.35:5060
2013/10/29 18:18:44.598	  0:13.071	Opal Liste...ff09dcd700	SIP	Waiting for PDU on udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:44.599	  0:13.071	Opal Liste...ff09dcd700	SIP	PDU received: rem=udp$46.19.58.41:5060,local=udp$192.168.178.35:5060,if=192.168.178.35%wlan0
SIP/2.0 200 OK
CSeq: 18 REGISTER
Via: SIP/2.0/UDP 192.168.178.35:5060;branch=z9hG4bK30e10496-e43e-e311-9af5-f81a670d972b;rport=61012;received=118.211.230.192
Server: Generic SIP Proxy
From: <sip:473936@sip.diamondcard.us>;tag=286e908f-e43e-e311-9af5-f81a670d972b
Call-ID: 2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
To: <sip:473936@sip.diamondcard.us>;tag=e0c20796a2acd5434c46ad46445d6de7.1468
Contact: <sip:473936@192.168.178.27:51741;transport=udp>;expires=2419;received="sip:118.211.230.192:51741", <sip:473936@118.211.230.192;uniq=45A9092C6157C5D9ADFCAB362F2C9>;expires=1202
Content-Length: 0


2013/10/29 18:18:44.599	  0:13.072	Opal Liste...ff09dcd700	SIP	Queueing PDU "18 REGISTER <200>", transaction=z9hG4bK30e10496-e43e-e311-9af5-f81a670d972b, token=2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:44.599	  0:13.072	Opal Liste...ff09dcd700	Opal	Transport clean up on termination
2013/10/29 18:18:44.599	  0:13.072	Opal Liste...ff09dcd700	Opal	Transport Close
2013/10/29 18:18:44.599	  0:13.072	    Pool:0x7efecb168700	SIP	Handling PDU "18 REGISTER <200>" for transaction=z9hG4bK30e10496-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:44.599	  0:13.072	    Pool:0x7efecb168700	SIP	REGISTER transaction id=z9hG4bK30e10496-e43e-e311-9af5-f81a670d972b completing.
2013/10/29 18:18:44.599	  0:13.072	Opal Liste...ff09dcd700	Opal	Deleted transport udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:44.599	  0:13.072	    Pool:0x7efecb168700	SIP	Finalising handlers interface "192.168.178.35%wlan0"
2013/10/29 18:18:44.599	  0:13.072	    Pool:0x7efecb168700	OpalUDP	Setting interface to 192.168.178.35%wlan0
2013/10/29 18:18:44.599	  0:13.072	    Pool:0x7efecb168700	SIP	Changing REGISTER handler from Unsubscribing to Unsubscribed, target=sip:473936@sip.diamondcard.us, id=2a5d908f-e43e-e311-9af5-f81a670d972b@greg-W2600CR
2013/10/29 18:18:44.599	  0:13.072	    Pool:0x7efecb168700	SIP	REGISTER transaction id=z9hG4bK30e10496-e43e-e311-9af5-f81a670d972b completed.
2013/10/29 18:18:44.599	  0:13.072	    Pool:0x7efecb168700	SIP	Handled PDU "18 REGISTER <200>"
2013/10/29 18:18:44.689	  0:13.162	Opal Garba...ff367e6700	SIP	Transaction id=z9hG4bK78c4c095-e43e-e311-9af5-f81a670d972b destroyed.
2013/10/29 18:18:44.689	  0:13.162	Opal Garba...ff367e6700	SIP	Transaction id=z9hG4bKaeccbe95-e43e-e311-9af5-f81a670d972b destroyed.
2013/10/29 18:18:44.690	  0:13.162	Opal Garba...ff367e6700	SIP	Destroyed SUBSCRIBE handler for sip:473936@sip.diamondcard.us;OPAL-local-id=sip:473936%40sip.diamondcard.us
2013/10/29 18:18:44.690	  0:13.163	Opal Garba...ff367e6700	SIP	Destroyed SUBSCRIBE handler for sip:el_gallo_azul@ekiga.net;OPAL-local-id=sip:el_gallo_azul%40ekiga.net
2013/10/29 18:18:44.690	  0:13.163	Opal Garba...ff367e6700	Opal	Transport clean up on termination
2013/10/29 18:18:44.690	  0:13.163	Opal Garba...ff367e6700	Opal	Transport Close
2013/10/29 18:18:44.690	  0:13.163	Opal Garba...ff367e6700	Opal	Transport clean up on termination
2013/10/29 18:18:44.690	  0:13.163	Opal Garba...ff367e6700	Opal	Transport Close
2013/10/29 18:18:44.690	  0:13.163	Opal Garba...ff367e6700	Opal	Deleted transport udp$86.64.162.35:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:44.690	  0:13.163	Opal Garba...ff367e6700	SIP	Destroyed PUBLISH handler for sip:el_gallo_azul@ekiga.net
2013/10/29 18:18:44.690	  0:13.163	Opal Garba...ff367e6700	Opal	Transport clean up on termination
2013/10/29 18:18:44.690	  0:13.163	Opal Garba...ff367e6700	Opal	Transport Close
2013/10/29 18:18:44.690	  0:13.163	Opal Garba...ff367e6700	Opal	Transport clean up on termination
2013/10/29 18:18:44.690	  0:13.163	Opal Garba...ff367e6700	Opal	Transport Close
2013/10/29 18:18:44.691	  0:13.163	Opal Garba...ff367e6700	Opal	Deleted transport udp$46.19.58.41:5060<if=udp$*:5060>
2013/10/29 18:18:44.691	  0:13.163	Opal Garba...ff367e6700	SIP	Destroyed PUBLISH handler for sip:473936@sip.diamondcard.us
2013/10/29 18:18:44.691	  0:13.164	Opal Garba...ff367e6700	Opal	Transport clean up on termination
2013/10/29 18:18:44.691	  0:13.164	Opal Garba...ff367e6700	Opal	Transport Close
2013/10/29 18:18:44.691	  0:13.164	Opal Garba...ff367e6700	Opal	Transport clean up on termination
2013/10/29 18:18:44.691	  0:13.164	Opal Garba...ff367e6700	Opal	Transport Close
2013/10/29 18:18:44.691	  0:13.164	Opal Garba...ff367e6700	Opal	Deleted transport udp$46.19.58.41:5060<if=udp$192.168.178.35:5060>
2013/10/29 18:18:44.691	  0:13.164	Opal Garba...ff367e6700	SIP	Destroyed REGISTER handler for sip:473936@sip.diamondcard.us
2013/10/29 18:18:45.163	  0:13.636	Housekeepe...ff368ea700	SIP	Set state Terminated_Success for REGISTER transaction id=z9hG4bKba1dc195-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:45.164	  0:13.636	Housekeepe...ff368ea700	SIP	Completed state for transaction id=z9hG4bKba1dc195-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:45.600	  0:14.073	Housekeepe...ff368ea700	SIP	Set state Terminated_Success for REGISTER transaction id=z9hG4bK30e10496-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:45.600	  0:14.073	Housekeepe...ff368ea700	SIP	Completed state for transaction id=z9hG4bK30e10496-e43e-e311-9af5-f81a670d972b
2013/10/29 18:18:45.622	  0:14.095	                       	OpalEP	sip endpoint shutting down.
2013/10/29 18:18:45.622	  0:14.095	                       	Listen	Stopping listening thread on udp$*:5060
2013/10/29 18:18:45.622	  0:14.095	                       	MonSock	Closed UDP socket 0x1dd9a00
2013/10/29 18:18:45.622	  0:14.095	Opal Liste...ff09dcd700	Listen	UDP read error.
2013/10/29 18:18:45.623	  0:14.095	Opal Liste...ff09dcd700	PWLib	File handle low water mark set: 36 PTextFile
2013/10/29 18:18:45.623	  0:14.095	Opal Liste...ff09dcd700	PTLib	Thread ended: name="Opal Listener:0x7eff09dcd700", real=12.293, kernel=0.000 (0%), user=0.020 (0.1%), both=0.020 (0.1%)
2013/10/29 18:18:45.643	  0:14.115	                       	MonSock	Deleting UDP socket 0x1dd9a00
2013/10/29 18:18:45.643	  0:14.116	                       	OpalEP	h323 endpoint shutting down.
2013/10/29 18:18:45.643	  0:14.116	                       	Listen	Stopping listening thread on tcp$*:1720
2013/10/29 18:18:45.643	  0:14.116	Opal Liste...ff09d8c700	PWLib	File handle low water mark set: 35 PTextFile
2013/10/29 18:18:45.643	  0:14.116	Opal Liste...ff09d8c700	PTLib	Thread ended: name="Opal Listener:0x7eff09d8c700", real=12.313, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%)
2013/10/29 18:18:45.643	  0:14.116	                       	PCSS	Deleted PC sound system endpoint.
2013/10/29 18:18:45.643	  0:14.116	                       	LocalEP	Deleted endpoint.
2013/10/29 18:18:45.643	  0:14.116	                       	OpalEP	pc endpoint destroyed.
2013/10/29 18:18:45.644	  0:14.116	    Pool:0x7efecb168700	PTLib	Thread ended: name="Pool:0x7efecb168700", real=11.854, kernel=0.000 (0%), user=0.020 (0.1%), both=0.020 (0.1%)
2013/10/29 18:18:45.654	  0:14.126	                       	ThreadPool	Destroying pool thread
2013/10/29 18:18:45.654	  0:14.127	                       	SIP	Transaction id=z9hG4bK30e10496-e43e-e311-9af5-f81a670d972b destroyed.
2013/10/29 18:18:45.654	  0:14.127	                       	SIP	Transaction id=z9hG4bKba1dc195-e43e-e311-9af5-f81a670d972b destroyed.
2013/10/29 18:18:45.654	  0:14.127	                       	OpalEP	sip endpoint destroyed.
2013/10/29 18:18:45.654	  0:14.127	                       	OpalEP	h323 endpoint destroyed.
2013/10/29 18:18:45.654	  0:14.127	                       	OpalMan	Shutting down endpoints.
2013/10/29 18:18:45.654	  0:14.127	                       	OpalMan	Clearing all calls and waiting, primary thread.
2013/10/29 18:18:45.691	  0:14.164	                       	OpalMan	All calls cleared.
2013/10/29 18:18:45.692	  0:14.164	Opal Garba...ff367e6700	PTLib	Thread ended: name="Opal Garbage:0x7eff367e6700", real=14.010, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%)
2013/10/29 18:18:45.702	  0:14.174	                       	IfaceMon	Awaiting thread termination
2013/10/29 18:18:45.702	  0:14.175	Network In...ff2007a700	IfaceMon	Finished interface monitor thread.
2013/10/29 18:18:45.702	  0:14.175	Network In...ff2007a700	PWLib	File handle low water mark set: 28 PTextFile
2013/10/29 18:18:45.702	  0:14.175	Network In...ff2007a700	PTLib	Thread ended: name="Network Interface Monitor:0x7eff2007a700", real=13.901, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%)
2013/10/29 18:18:45.702	  0:14.175	                       	OpalMan	Deleted manager.
2013/10/29 18:18:45.702	  0:14.175	AudioEvent...ff36868700	PTLib	Thread ended: name="AudioEventScheduler:0x7eff36868700", real=14.077, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%)
2013/10/29 18:18:45.703	  0:14.175	VideoPrevi...ff368a9700	PTLib	Thread ended: name="VideoPreviewManager:0x7eff368a9700", real=14.079, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%)
2013/10/29 18:18:45.704	  0:14.177	                       	OpalPlugin	Using default handler for plugin codec spandsp_ptplugin
2013/10/29 18:18:45.704	  0:14.177	                       	OpalPlugin	Using default handler for plugin codec lpc10_ptplugin
2013/10/29 18:18:45.705	  0:14.177	                       	OpalPlugin	Using default handler for plugin codec g7222_ptplugin
2013/10/29 18:18:45.705	  0:14.177	                       	OpalPlugin	Using default handler for plugin codec g726_ptplugin
2013/10/29 18:18:45.705	  0:14.178	                       	OpalPlugin	Using default handler for plugin codec iLBC_ptplugin
2013/10/29 18:18:45.705	  0:14.178	                       	OpalPlugin	Using default handler for plugin codec g7221_ptplugin
2013/10/29 18:18:45.705	  0:14.178	                       	OpalPlugin	Using default handler for plugin codec gsmamrcodec_ptplugin
2013/10/29 18:18:45.705	  0:14.178	                       	OpalPlugin	Using default handler for plugin codec g722_ptplugin
2013/10/29 18:18:45.705	  0:14.178	                       	OpalPlugin	Using default handler for plugin codec speex_ptplugin
2013/10/29 18:18:45.705	  0:14.178	                       	OpalPlugin	Using default handler for plugin codec gsm0610_ptplugin
2013/10/29 18:18:45.705	  0:14.178	                       	OpalPlugin	Using default handler for plugin codec ima_adpcm_ptplugin
2013/10/29 18:18:45.705	  0:14.178	                       	OpalPlugin	Using default handler for plugin codec silk_ptplugin
2013/10/29 18:18:45.705	  0:14.178	                       	OpalPlugin	Using default handler for plugin codec h261_vic_ptplugin
2013/10/29 18:18:45.705	  0:14.178	                       	OpalPlugin	Using default handler for plugin codec theora_ptplugin
2013/10/29 18:18:45.706	  0:14.178	                       	OpalPlugin	Using default handler for plugin codec h264_x264_ptplugin
2013/10/29 18:18:45.706	  0:14.178	                       	OpalPlugin	Using default handler for plugin codec mpeg4_ffmpeg_ptplugin
2013/10/29 18:18:45.706	  0:14.178	                       	OpalPlugin	Using default handler for plugin codec h263_ffmpeg_ptplugin
2013/10/29 18:18:45.706	  0:14.179	                       	UDLL	Closing /usr/lib/opal-3.10.10/codecs/video/h263_ffmpeg_ptplugin.so
2013/10/29 18:18:45.706	  0:14.179	                       	UDLL	Closing /usr/lib/opal-3.10.10/codecs/video/mpeg4_ffmpeg_ptplugin.so
2013/10/29 18:18:45.706	  0:14.179	                       	UDLL	Closing /usr/lib/opal-3.10.10/codecs/video/h264_x264_ptplugin.so
2013/10/29 18:18:45.706	  0:14.179	                       	UDLL	Closing /usr/lib/opal-3.10.10/codecs/video/theora_ptplugin.so
2013/10/29 18:18:45.706	  0:14.179	                       	UDLL	Closing /usr/lib/opal-3.10.10/codecs/video/h261_vic_ptplugin.so
2013/10/29 18:18:45.706	  0:14.179	                       	UDLL	Closing /usr/lib/opal-3.10.10/codecs/audio/silk_ptplugin.so
2013/10/29 18:18:45.707	  0:14.179	                       	UDLL	Closing /usr/lib/opal-3.10.10/codecs/audio/ima_adpcm_ptplugin.so
2013/10/29 18:18:45.707	  0:14.179	                       	UDLL	Closing /usr/lib/opal-3.10.10/codecs/audio/gsm0610_ptplugin.so
2013/10/29 18:18:45.707	  0:14.180	                       	UDLL	Closing /usr/lib/opal-3.10.10/codecs/audio/speex_ptplugin.so
2013/10/29 18:18:45.707	  0:14.180	                       	UDLL	Closing /usr/lib/opal-3.10.10/codecs/audio/g722_ptplugin.so
2013/10/29 18:18:45.707	  0:14.180	                       	UDLL	Closing /usr/lib/opal-3.10.10/codecs/audio/gsmamrcodec_ptplugin.so
2013/10/29 18:18:45.707	  0:14.180	                       	UDLL	Closing /usr/lib/opal-3.10.10/codecs/audio/g7221_ptplugin.so
2013/10/29 18:18:45.707	  0:14.180	                       	UDLL	Closing /usr/lib/opal-3.10.10/codecs/audio/iLBC_ptplugin.so
2013/10/29 18:18:45.707	  0:14.180	                       	UDLL	Closing /usr/lib/opal-3.10.10/codecs/audio/g726_ptplugin.so
2013/10/29 18:18:45.707	  0:14.180	                       	UDLL	Closing /usr/lib/opal-3.10.10/codecs/audio/g7222_ptplugin.so
2013/10/29 18:18:45.707	  0:14.180	                       	UDLL	Closing /usr/lib/opal-3.10.10/codecs/audio/lpc10_ptplugin.so
2013/10/29 18:18:45.707	  0:14.180	                       	UDLL	Closing /usr/lib/opal-3.10.10/fax/spandsp_ptplugin.so
2013/10/29 18:18:45.707	  0:14.180	                       	UDLL	Closing /usr/lib/ptlib-2.10.10/devices/sound/oss_pwplugin.so
2013/10/29 18:18:45.707	  0:14.180	                       	UDLL	Closing /usr/lib/ptlib-2.10.10/devices/sound/pulse_pwplugin.so
2013/10/29 18:18:45.707	  0:14.180	                       	UDLL	Closing /usr/lib/ptlib-2.10.10/devices/sound/alsa_pwplugin.so
2013/10/29 18:18:45.707	  0:14.180	                       	UDLL	Closing /usr/lib/ptlib-2.10.10/devices/videoinput/v4l2_pwplugin.so
2013/10/29 18:18:47.895	  0:16.368	GMVideoOut...ff36827700	PWLib	File handle low water mark set: 17 PTextFile
2013/10/29 18:18:47.896	  0:16.368	GMVideoOut...ff36827700	PTLib	Thread ended: name="GMVideoOutputManager:0x7eff36827700", real=16.254, kernel=0.000 (0%), user=0.000 (0%), both=0.000 (0%)
2013/10/29 18:18:47.911	  0:16.384	Housekeepe...ff368ea700	PWLib	File handle low water mark set: 13 PTextFile
2013/10/29 18:18:47.911	  0:16.384	Housekeepe...ff368ea700	PTLib	Thread ended: name="Housekeeper:0x7eff368ea700", real=16.288, kernel=0.000 (0%), user=0.010 (0%), both=0.010 (0%)
