Re: [Ekiga-list] Problem in sending Instant Messag (RFC 3428)



Eugen Dedu wrote:
On 10/02/11 09:43, kalpesh wrote:
Hi,

We are implementing Instant Message (by following RFC 3428) in our IpPBX.

Currently we are not supporting PUBLISH method.

We are using Ekiga for testing this feature and both the endpoints
exchanges Message successfully when we are sending the Message first.

The problem is when we send a second message request to another
endpoint(Ekiga) we receive the 200 ok response for the request but the
message doesn't get displayed in the chat window where the first message
is getting displayed fine.

Has anyone come across such problem?

Just want to know if it is a problem with Ekiga and if yes then how to
get around it?

Hi,

I have not seen such a behaviour (the first message is ok, but the following ones are not displayed).

The best is to look at what ekiga sends and receives using the -d 4 output.

Rewrite an e-mail if you still have troubles with ekiga.


hi,

i have checked ekiga with debug on, but there's no difference in the processing of the firs and the second and subsequent MESSAGE request.

ekiga is acknowledging all the requests with 200 OK response but as i told earlier it doesn't display the text in the MESSAGE requests received after the first MESSAGE.

PFA the debug log file.

thanks in advance

kalpesh
Gtk-Message: Failed to load module "gnomebreakpad": libgnomebreakpad.so: cannot open shared object file: No such file or directory
2011/02/21 17:41:17.578	  0:00.106	                       		Version 3.2.7 by  on Unix Linux (2.6.33.3-85.fc13.i686.PAE-i686) with PTLib (v2.6.7) at 2011/2/21 17:41:17.578
2011/02/21 17:41:17.579	  0:00.107	                       	Ekiga git revision: unknown
2011/02/21 17:41:17.580	  0:00.108	                       	Ekiga registered on D-Bus: org.ekiga.Ekiga
2011/02/21 17:41:17.584	  0:00.112	                       	PWLib	File handle high water mark set: 17 Thread unblock pipe
2011/02/21 17:41:17.584	  0:00.112	                       	PTLib	Thread high water mark set: 2
2011/02/21 17:41:17.584	  0:00.112	                       	PWLib	File handle high water mark set: 19 Thread unblock pipe
2011/02/21 17:41:17.585	  0:00.112	                       	PTLib	Thread high water mark set: 3
2011/02/21 17:41:17.588	  0:00.116	                       	PWLib	File handle high water mark set: 21 Thread unblock pipe
2011/02/21 17:41:17.589	  0:00.117	                       	PWLib	File handle high water mark set: 23 Thread unblock pipe
2011/02/21 17:41:17.601	  0:00.129	                       	PTLib	Thread high water mark set: 4
2011/02/21 17:41:17.602	  0:00.130	                       	HalManager_dbus	Initialising HAL Manager
2011/02/21 17:41:17.605	  0:00.132	                       	HalManager_dbus	Populating device list
2011/02/21 17:41:17.731	  0:00.259	                       	HalManager_dbus	Populated device list with 7 devices
2011/02/21 17:41:17.731	  0:00.259	                       	HalManager_dbus	Populating interface list
2011/02/21 17:41:17.733	  0:00.261	                       	HalManager_dbus	Populating full interface list failed - Method "getDevices" with signature "" on interface "org.freedesktop.NetworkManager" doesn't exist

2011/02/21 17:41:17.734	  0:00.261	                       	Detecting V4L2 devices
2011/02/21 17:41:17.734	  0:00.261	                       	Unable to detect v4l2 directory
2011/02/21 17:41:17.754	  0:00.282	                       	PWLib	File handle high water mark set: 28 Thread unblock pipe
2011/02/21 17:41:17.755	  0:00.282	                       	PTLib	Thread high water mark set: 5
2011/02/21 17:41:17.755	  0:00.282	                       	OpalMan	Created manager.
2011/02/21 17:41:17.755	  0:00.283	                       	OpalMan	Attached endpoint with prefix pc
2011/02/21 17:41:17.755	  0:00.283	                       	OpalEP	Created endpoint: pc
2011/02/21 17:41:17.760	  0:00.287	                       	PCSS	Created PC sound system endpoint.
Players:
Default
HDA Intel
HDA Intel (1)
EKIGA
*.wav
Recorders:
Default
HDA Intel
EKIGA
*.wav

2011/02/21 17:41:17.760	  0:00.288	                       	OPAL	SetMediaFormatOrder()
2011/02/21 17:41:17.760	  0:00.288	                       	OPAL	SetMediaFormatMask()
2011/02/21 17:41:17.761	  0:00.289	                       	OpalMan	Attached endpoint with prefix sip
2011/02/21 17:41:17.761	  0:00.289	                       	OpalEP	Created endpoint: sip
2011/02/21 17:41:17.761	  0:00.289	                       	PWLib	File handle high water mark set: 29 PUDPSocket
2011/02/21 17:41:17.762	  0:00.289	                       	IfaceMon	Initial interface list:
127.0.0.1 [00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:01] <00-00-00-00-00-00> (lo)
10.106.1.203 [fe:80:00:00:00:00:00:00:02:07:e9:ff:fe:0a:60:d3] <00-07-E9-0A-60-D3> (eth1)

2011/02/21 17:41:17.762	  0:00.290	                       	PWLib	File handle high water mark set: 30 Thread unblock pipe
2011/02/21 17:41:17.762	  0:00.290	                       	PTLib	Thread high water mark set: 6
2011/02/21 17:41:17.762	  0:00.290	                       	PWLib	File handle high water mark set: 32 Thread unblock pipe
2011/02/21 17:41:17.762	  0:00.290	                       	PTLib	Thread high water mark set: 7
2011/02/21 17:41:17.763	  0:00.290	                       	OpalMan	Attached endpoint with prefix sips
2011/02/21 17:41:17.763	  0:00.290	                       	SIP	Created endpoint.
2011/02/21 17:41:17.763	  0:00.291	                       	PWLib	File handle high water mark set: 33 PUDPSocket
2011/02/21 17:41:17.763	  0:00.291	                       	MonSock	Created socket bundle for all interfaces.
2011/02/21 17:41:17.763	  0:00.291	                       	PWLib	File handle high water mark set: 34 PUDPSocket
2011/02/21 17:41:17.764	  0:00.292	                       	MonSock	Created bundled UDP socket 10.106.1.203:5060
2011/02/21 17:41:17.764	  0:00.292	                       	PWLib	File handle high water mark set: 36 Thread unblock pipe
2011/02/21 17:41:17.764	  0:00.292	                       	PTLib	Thread high water mark set: 8
2011/02/21 17:41:17.765	  0:00.292	Opal Liste...0xb727db70	Listen	Started listening thread on udp$*:5060
2011/02/21 17:41:17.765	  0:00.293	                       	OpalMan	Added route "sip:.*=pc:*"
2011/02/21 17:41:17.765	  0:00.293	                       	OpalMan	Added route "pc:.*=sip:<da>"
2011/02/21 17:41:17.766	  0:00.293	                       	OpalMan	Attached endpoint with prefix h323
2011/02/21 17:41:17.766	  0:00.293	                       	OpalEP	Created endpoint: h323
2011/02/21 17:41:17.766	  0:00.294	                       	OpalMan	Attached endpoint with prefix h323s
2011/02/21 17:41:17.766	  0:00.294	                       	H323	Created endpoint.
2011/02/21 17:41:17.766	  0:00.294	                       	PWLib	File handle high water mark set: 37 PTCPSocket
2011/02/21 17:41:17.766	  0:00.294	                       	PWLib	File handle high water mark set: 39 Thread unblock pipe
2011/02/21 17:41:17.766	  0:00.294	                       	PTLib	Thread high water mark set: 9
2011/02/21 17:41:17.766	  0:00.294	                       	OpalMan	Added route "h323:.*=pc:<db>"
2011/02/21 17:41:17.767	  0:00.294	                       	OpalMan	Added route "pc:.*=h323:<da>"
2011/02/21 17:41:17.770	  0:00.298	Network In...0xb7444b70	IfaceMon	Started interface monitor thread.
2011/02/21 17:41:17.770	  0:00.298	Network In...0xb7444b70	PWLib	File handle high water mark set: 40 PUDPSocket
2011/02/21 17:41:17.771	  0:00.298	Opal Liste...0xb6fffb70	Listen	Started listening thread on tcp$*:1720
2011/02/21 17:41:17.771	  0:00.299	Opal Liste...0xb6fffb70	Listen	Waiting on socket accept on tcp$*:1720
2011/02/21 17:41:17.783	  0:00.310	                       	MediaFormat	Removing codecs SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,theora,H.261,H.261-CIF,H.261-QCIF
2011/02/21 17:41:17.783	  0:00.311	                       	OPAL	SetMediaFormatMask(PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,G.722-64k,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz)
2011/02/21 17:41:17.783	  0:00.311	                       	OPAL	SetMediaFormatOrder(SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,theora,H.261,H.261-CIF,H.261-QCIF)
2011/02/21 17:41:17.797	  0:00.324	                       	MediaFormat	Removing codecs SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,theora,H.261,H.261-CIF,H.261-QCIF
2011/02/21 17:41:17.797	  0:00.325	                       	OPAL	SetMediaFormatMask(PCM-16-48kHz,PCM-16-32kHz,PCM-16-16kHz,G.722-64k,PCM-16,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,MS-IMA-ADPCM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,YUV420P,RFC4175_YCbCr-4:2:0,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz)
2011/02/21 17:41:17.797	  0:00.325	                       	OPAL	SetMediaFormatOrder(SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,G.711-uLaw-64k,G.711-ALaw-64k,theora,H.261,H.261-CIF,H.261-QCIF)
2011/02/21 17:41:17.819	  0:00.347	                       	PWLib	File handle high water mark set: 41 Thread unblock pipe
2011/02/21 17:41:17.820	  0:00.348	                       	PTLib	Thread high water mark set: 10
2011/02/21 17:41:17.838	  0:00.366	StunDetector:0xb6fbeb70	PWLib	File handle high water mark set: 45 PUDPSocket
2011/02/21 17:41:17.852	  0:00.380	StunDetector:0xb6fbeb70	PWLib	File handle low water mark set: 44 PUDPSocket
2011/02/21 17:41:17.887	  0:00.415	                       	SIP	Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:44455503@10.106.1.225, id=4290f75a-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:41:17.888	  0:00.416	                       	OpalUDP	Binding to interface: 0.0.0.0:5060
2011/02/21 17:41:17.888	  0:00.416	                       	SIP	Created transport udp$10.106.1.225:5060<if=udp$*:5060>
2011/02/21 17:41:17.889	  0:00.416	                       	OpalUDP	Started connect to 10.106.1.225:5060
2011/02/21 17:41:17.889	  0:00.417	                       	OpalUDP	Writing to interface 0 - "10.106.1.203%eth1"
2011/02/21 17:41:17.889	  0:00.417	                       	OpalMan	Listener interfaces: associated transport=None
    udp$10.106.1.203:5060
2011/02/21 17:41:17.890	  0:00.418	                       	SIP	Transaction created.
2011/02/21 17:41:17.892	  0:00.420	                       	SIP	Transaction remote address is udp$10.106.1.225:5060
2011/02/21 17:41:17.892	  0:00.420	                       	SIP	Sending PDU (566 bytes) to: rem=udp$10.106.1.225:5060,local=udp$10.106.1.203:5060,if=10.106.1.203%eth1
SUBSCRIBE sip:44455503@10.106.1.225 SIP/2.0
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 10.106.1.203:5060;branch=z9hG4bKac2af85a-213c-e011-958e-0007e90a60d3;rport
User-Agent: Ekiga/3.2.7
From: "shiv" <sip:root@10.106.1.203>;tag=1e04f85a-213c-e011-958e-0007e90a60d3
Call-ID: 4290f75a-213c-e011-958e-0007e90a60d3@onebox_shiv
To: <sip:44455503@10.106.1.225>
Contact: <sip:root@10.106.1.203>
Accept: application/pidf+xml
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Expires: 300
Event: presence
Content-Length: 0
Max-Forwards: 70


2011/02/21 17:41:17.893	  0:00.421	                       	OpalUDP	Setting interface to 10.106.1.203%eth1
2011/02/21 17:41:17.893	  0:00.421	                       	SIP	Transaction timers set: retry=0.500, completion=6.000
2011/02/21 17:41:17.894	  0:00.421	                       	SIP	Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:44455503@10.106.1.225, id=dc8bf85a-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:41:17.894	  0:00.422	                       	OpalUDP	Binding to interface: 0.0.0.0:5060
2011/02/21 17:41:17.894	  0:00.422	                       	SIP	Created transport udp$10.106.1.225:5060<if=udp$*:5060>
2011/02/21 17:41:17.894	  0:00.422	                       	OpalUDP	Started connect to 10.106.1.225:5060
2011/02/21 17:41:17.895	  0:00.422	                       	OpalUDP	Writing to interface 0 - "10.106.1.203%eth1"
2011/02/21 17:41:17.895	  0:00.423	                       	OpalMan	Listener interfaces: associated transport=None
    udp$10.106.1.203:5060
2011/02/21 17:41:17.896	  0:00.424	Opal Liste...0xb727db70	OpalUDP	Binding to interface: 10.106.1.203:5060
2011/02/21 17:41:17.896	  0:00.424	Opal Liste...0xb727db70	SIP	Waiting for PDU on udp$10.106.1.225:5060<if=udp$10.106.1.203:5060>
2011/02/21 17:41:17.897	  0:00.425	Opal Liste...0xb727db70	SIP	PDU received: rem=udp$10.106.1.225:5060,local=udp$10.106.1.203:5060,if=10.106.1.203%eth1
SIP/2.0 501 Not Implement
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 10.106.1.203:5060;branch=z9hG4bKac2af85a-213c-e011-958e-0007e90a60d3;rport
User-Agent: Ekiga/3.2.7
From: "shiv" <sip:root@10.106.1.203>;tag=1e04f85a-213c-e011-958e-0007e90a60d3
Call-ID: 4290f75a-213c-e011-958e-0007e90a60d3@onebox_shiv
To: <sip:44455503@10.106.1.225>
Contact: <sip:root@10.106.1.203>
Accept: application/pidf+xml
Allow: INVITE, ACK, OPTIONS, BYE, CANCEL, SUBSCRIBE, NOTIFY, REFER, MESSAGE, INFO, PING
Expires: 300
Event: presence
Content-Length: 0


2011/02/21 17:41:17.897	  0:00.425	Opal Liste...0xb727db70	SIP	Queueing PDU "2 SUBSCRIBE <501>", transaction=z9hG4bKac2af85a-213c-e011-958e-0007e90a60d3, token=4290f75a-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:41:17.898	  0:00.425	Opal Liste...0xb727db70	PWLib	File handle high water mark set: 46 Thread unblock pipe
2011/02/21 17:41:17.898	  0:00.426	Opal Liste...0xb727db70	PTLib	Thread high water mark set: 11
2011/02/21 17:41:17.898	  0:00.426	Opal Liste...0xb727db70	Opal	Transport clean up on termination
2011/02/21 17:41:17.898	  0:00.426	Opal Liste...0xb727db70	Opal	Transport Close
2011/02/21 17:41:17.898	  0:00.426	        Pool:0xb6ee5b70	SIP	Handling PDU "2 SUBSCRIBE <501>" for transaction=z9hG4bKac2af85a-213c-e011-958e-0007e90a60d3
2011/02/21 17:41:17.898	  0:00.426	        Pool:0xb6ee5b70	SIP	SUBSCRIBE transaction id=z9hG4bKac2af85a-213c-e011-958e-0007e90a60d3 completed.
2011/02/21 17:41:17.898	  0:00.426	Opal Liste...0xb727db70	Opal	Deleted transport udp$10.106.1.225:5060<if=udp$10.106.1.203:5060>
2011/02/21 17:41:17.899	  0:00.426	        Pool:0xb6ee5b70	OpalUDP	Setting interface to 10.106.1.203%eth1
2011/02/21 17:41:17.899	  0:00.427	        Pool:0xb6ee5b70	SIP	Not retrying SUBSCRIBE due to error response 501 Not Implemented
2011/02/21 17:41:17.899	  0:00.427	        Pool:0xb6ee5b70	SIP	Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:44455503@10.106.1.225, id=4290f75a-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:41:17.899	  0:00.427	        Pool:0xb6ee5b70	SIP	Handled PDU "2 SUBSCRIBE <501>"
2011/02/21 17:41:17.899	  0:00.427	                       	SIP	Transaction created.
2011/02/21 17:41:17.901	  0:00.429	                       	SIP	Transaction remote address is udp$10.106.1.225:5060
2011/02/21 17:41:17.901	  0:00.429	                       	SIP	Sending PDU (582 bytes) to: rem=udp$10.106.1.225:5060,local=udp$10.106.1.203:5060,if=10.106.1.203%eth1
SUBSCRIBE sip:44455503@10.106.1.225 SIP/2.0
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 10.106.1.203:5060;branch=z9hG4bK888ef95a-213c-e011-958e-0007e90a60d3;rport
User-Agent: Ekiga/3.2.7
From: "shiv" <sip:root@10.106.1.203>;tag=166af95a-213c-e011-958e-0007e90a60d3
Call-ID: dc8bf85a-213c-e011-958e-0007e90a60d3@onebox_shiv
To: <sip:44455503@10.106.1.225>
Contact: <sip:44455503@10.106.1.203>
Accept: application/dialog-info+xml
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Expires: 300
Event: dialog;sla;ma
Content-Length: 0
Max-Forwards: 70


2011/02/21 17:41:17.902	  0:00.430	                       	OpalUDP	Setting interface to 10.106.1.203%eth1
2011/02/21 17:41:17.902	  0:00.430	                       	SIP	Transaction timers set: retry=0.500, completion=6.000
2011/02/21 17:41:17.903	  0:00.430	                       	SIP	Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:44455501@10.106.1.225, id=eeebf95a-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:41:17.903	  0:00.431	                       	OpalUDP	Binding to interface: 0.0.0.0:5060
2011/02/21 17:41:17.903	  0:00.431	                       	SIP	Created transport udp$10.106.1.225:5060<if=udp$*:5060>
2011/02/21 17:41:17.903	  0:00.431	                       	OpalUDP	Started connect to 10.106.1.225:5060
2011/02/21 17:41:17.904	  0:00.432	Opal Liste...0xb727db70	OpalUDP	Binding to interface: 10.106.1.203:5060
2011/02/21 17:41:17.904	  0:00.432	Opal Liste...0xb727db70	SIP	Waiting for PDU on udp$10.106.1.225:5060<if=udp$10.106.1.203:5060>
2011/02/21 17:41:17.905	  0:00.432	Opal Liste...0xb727db70	SIP	PDU received: rem=udp$10.106.1.225:5060,local=udp$10.106.1.203:5060,if=10.106.1.203%eth1
SIP/2.0 501 Not Implement
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 10.106.1.203:5060;branch=z9hG4bK888ef95a-213c-e011-958e-0007e90a60d3;rport
User-Agent: Ekiga/3.2.7
From: "shiv" <sip:root@10.106.1.203>;tag=166af95a-213c-e011-958e-0007e90a60d3
Call-ID: dc8bf85a-213c-e011-958e-0007e90a60d3@onebox_shiv
To: <sip:44455503@10.106.1.225>
Contact: <sip:44455503@10.106.1.203>
Accept: application/dialog-info+xml
Allow: INVITE, ACK, OPTIONS, BYE, CANCEL, SUBSCRIBE, NOTIFY, REFER, MESSAGE, INFO, PING
Expires: 300
Event: dialog;sla;ma
Content-Length: 0


2011/02/21 17:41:17.905	  0:00.433	Opal Liste...0xb727db70	SIP	Queueing PDU "2 SUBSCRIBE <501>", transaction=z9hG4bK888ef95a-213c-e011-958e-0007e90a60d3, token=dc8bf85a-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:41:17.905	  0:00.433	Opal Liste...0xb727db70	Opal	Transport clean up on termination
2011/02/21 17:41:17.905	  0:00.433	Opal Liste...0xb727db70	Opal	Transport Close
2011/02/21 17:41:17.905	  0:00.433	        Pool:0xb6ee5b70	SIP	Handling PDU "2 SUBSCRIBE <501>" for transaction=z9hG4bK888ef95a-213c-e011-958e-0007e90a60d3
2011/02/21 17:41:17.906	  0:00.433	        Pool:0xb6ee5b70	SIP	SUBSCRIBE transaction id=z9hG4bK888ef95a-213c-e011-958e-0007e90a60d3 completed.
2011/02/21 17:41:17.905	  0:00.433	Opal Liste...0xb727db70	Opal	Deleted transport udp$10.106.1.225:5060<if=udp$10.106.1.203:5060>
2011/02/21 17:41:17.906	  0:00.434	        Pool:0xb6ee5b70	OpalUDP	Setting interface to 10.106.1.203%eth1
2011/02/21 17:41:17.906	  0:00.434	                       	OpalUDP	Writing to interface 0 - "10.106.1.203%eth1"
2011/02/21 17:41:17.906	  0:00.434	        Pool:0xb6ee5b70	SIP	Not retrying SUBSCRIBE due to error response 501 Not Implemented
2011/02/21 17:41:17.906	  0:00.434	        Pool:0xb6ee5b70	SIP	Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:44455503@10.106.1.225, id=dc8bf85a-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:41:17.906	  0:00.434	        Pool:0xb6ee5b70	SIP	Handled PDU "2 SUBSCRIBE <501>"
2011/02/21 17:41:17.906	  0:00.434	                       	PWLib	File handle high water mark set: 47 PUDPSocket
2011/02/21 17:41:17.906	  0:00.434	                       	OpalMan	Listener interfaces: associated transport=None
    udp$10.106.1.203:5060
2011/02/21 17:41:17.907	  0:00.435	                       	SIP	Transaction created.
2011/02/21 17:41:17.909	  0:00.437	                       	SIP	Transaction remote address is udp$10.106.1.225:5060
2011/02/21 17:41:17.910	  0:00.438	                       	SIP	Sending PDU (566 bytes) to: rem=udp$10.106.1.225:5060,local=udp$10.106.1.203:5060,if=10.106.1.203%eth1
SUBSCRIBE sip:44455501@10.106.1.225 SIP/2.0
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 10.106.1.203:5060;branch=z9hG4bK26d1fa5a-213c-e011-958e-0007e90a60d3;rport
User-Agent: Ekiga/3.2.7
From: "shiv" <sip:root@10.106.1.203>;tag=64a2fa5a-213c-e011-958e-0007e90a60d3
Call-ID: eeebf95a-213c-e011-958e-0007e90a60d3@onebox_shiv
To: <sip:44455501@10.106.1.225>
Contact: <sip:root@10.106.1.203>
Accept: application/pidf+xml
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Expires: 300
Event: presence
Content-Length: 0
Max-Forwards: 70


2011/02/21 17:41:17.910	  0:00.438	                       	OpalUDP	Setting interface to 10.106.1.203%eth1
2011/02/21 17:41:17.910	  0:00.438	                       	SIP	Transaction timers set: retry=0.500, completion=6.000
2011/02/21 17:41:17.911	  0:00.439	                       	SIP	Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:44455501@10.106.1.225, id=3832fb5a-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:41:17.911	  0:00.439	                       	OpalUDP	Binding to interface: 0.0.0.0:5060
2011/02/21 17:41:17.911	  0:00.439	                       	SIP	Created transport udp$10.106.1.225:5060<if=udp$*:5060>
2011/02/21 17:41:17.912	  0:00.440	                       	OpalUDP	Started connect to 10.106.1.225:5060
2011/02/21 17:41:17.912	  0:00.440	Opal Liste...0xb727db70	OpalUDP	Binding to interface: 10.106.1.203:5060
2011/02/21 17:41:17.912	  0:00.440	Opal Liste...0xb727db70	SIP	Waiting for PDU on udp$10.106.1.225:5060<if=udp$10.106.1.203:5060>
2011/02/21 17:41:17.913	  0:00.441	Opal Liste...0xb727db70	SIP	PDU received: rem=udp$10.106.1.225:5060,local=udp$10.106.1.203:5060,if=10.106.1.203%eth1
SIP/2.0 501 Not Implement
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 10.106.1.203:5060;branch=z9hG4bK26d1fa5a-213c-e011-958e-0007e90a60d3;rport
User-Agent: Ekiga/3.2.7
From: "shiv" <sip:root@10.106.1.203>;tag=64a2fa5a-213c-e011-958e-0007e90a60d3
Call-ID: eeebf95a-213c-e011-958e-0007e90a60d3@onebox_shiv
To: <sip:44455501@10.106.1.225>
Contact: <sip:root@10.106.1.203>
Accept: application/pidf+xml
Allow: INVITE, ACK, OPTIONS, BYE, CANCEL, SUBSCRIBE, NOTIFY, REFER, MESSAGE, INFO, PING
Expires: 300
Event: presence
Content-Length: 0


2011/02/21 17:41:17.913	  0:00.441	Opal Liste...0xb727db70	SIP	Queueing PDU "2 SUBSCRIBE <501>", transaction=z9hG4bK26d1fa5a-213c-e011-958e-0007e90a60d3, token=eeebf95a-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:41:17.913	  0:00.441	Opal Liste...0xb727db70	Opal	Transport clean up on termination
2011/02/21 17:41:17.914	  0:00.441	Opal Liste...0xb727db70	Opal	Transport Close
2011/02/21 17:41:17.914	  0:00.441	        Pool:0xb6ee5b70	SIP	Handling PDU "2 SUBSCRIBE <501>" for transaction=z9hG4bK26d1fa5a-213c-e011-958e-0007e90a60d3
2011/02/21 17:41:17.914	  0:00.442	        Pool:0xb6ee5b70	SIP	SUBSCRIBE transaction id=z9hG4bK26d1fa5a-213c-e011-958e-0007e90a60d3 completed.
2011/02/21 17:41:17.914	  0:00.441	Opal Liste...0xb727db70	Opal	Deleted transport udp$10.106.1.225:5060<if=udp$10.106.1.203:5060>
2011/02/21 17:41:17.914	  0:00.442	        Pool:0xb6ee5b70	OpalUDP	Setting interface to 10.106.1.203%eth1
2011/02/21 17:41:17.914	  0:00.442	                       	OpalUDP	Writing to interface 0 - "10.106.1.203%eth1"
2011/02/21 17:41:17.914	  0:00.442	        Pool:0xb6ee5b70	SIP	Not retrying SUBSCRIBE due to error response 501 Not Implemented
2011/02/21 17:41:17.914	  0:00.442	        Pool:0xb6ee5b70	SIP	Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:44455501@10.106.1.225, id=eeebf95a-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:41:17.914	  0:00.442	        Pool:0xb6ee5b70	SIP	Handled PDU "2 SUBSCRIBE <501>"
2011/02/21 17:41:17.915	  0:00.442	                       	OpalMan	Listener interfaces: associated transport=None
    udp$10.106.1.203:5060
2011/02/21 17:41:17.915	  0:00.443	                       	SIP	Transaction created.
2011/02/21 17:41:17.917	  0:00.445	                       	SIP	Transaction remote address is udp$10.106.1.225:5060
2011/02/21 17:41:17.918	  0:00.445	                       	SIP	Sending PDU (582 bytes) to: rem=udp$10.106.1.225:5060,local=udp$10.106.1.203:5060,if=10.106.1.203%eth1
SUBSCRIBE sip:44455501@10.106.1.225 SIP/2.0
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 10.106.1.203:5060;branch=z9hG4bK5203fc5a-213c-e011-958e-0007e90a60d3;rport
User-Agent: Ekiga/3.2.7
From: "shiv" <sip:root@10.106.1.203>;tag=f2e0fb5a-213c-e011-958e-0007e90a60d3
Call-ID: 3832fb5a-213c-e011-958e-0007e90a60d3@onebox_shiv
To: <sip:44455501@10.106.1.225>
Contact: <sip:44455501@10.106.1.203>
Accept: application/dialog-info+xml
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Expires: 300
Event: dialog;sla;ma
Content-Length: 0
Max-Forwards: 70


2011/02/21 17:41:17.918	  0:00.446	                       	OpalUDP	Setting interface to 10.106.1.203%eth1
2011/02/21 17:41:17.918	  0:00.446	                       	SIP	Transaction timers set: retry=0.500, completion=6.000
2011/02/21 17:41:17.919	  0:00.447	                       	VideoOutputCoreConfBridge	Updating video view
2011/02/21 17:41:17.920	  0:00.447	Opal Liste...0xb727db70	OpalUDP	Binding to interface: 10.106.1.203:5060
2011/02/21 17:41:17.920	  0:00.448	Opal Liste...0xb727db70	SIP	Waiting for PDU on udp$10.106.1.225:5060<if=udp$10.106.1.203:5060>
2011/02/21 17:41:17.920	  0:00.448	Opal Liste...0xb727db70	SIP	PDU received: rem=udp$10.106.1.225:5060,local=udp$10.106.1.203:5060,if=10.106.1.203%eth1
SIP/2.0 501 Not Implement
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 10.106.1.203:5060;branch=z9hG4bK5203fc5a-213c-e011-958e-0007e90a60d3;rport
User-Agent: Ekiga/3.2.7
From: "shiv" <sip:root@10.106.1.203>;tag=f2e0fb5a-213c-e011-958e-0007e90a60d3
Call-ID: 3832fb5a-213c-e011-958e-0007e90a60d3@onebox_shiv
To: <sip:44455501@10.106.1.225>
Contact: <sip:44455501@10.106.1.203>
Accept: application/dialog-info+xml
Allow: INVITE, ACK, OPTIONS, BYE, CANCEL, SUBSCRIBE, NOTIFY, REFER, MESSAGE, INFO, PING
Expires: 300
Event: dialog;sla;ma
Content-Length: 0


2011/02/21 17:41:17.921	  0:00.449	Opal Liste...0xb727db70	SIP	Queueing PDU "2 SUBSCRIBE <501>", transaction=z9hG4bK5203fc5a-213c-e011-958e-0007e90a60d3, token=3832fb5a-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:41:17.921	  0:00.449	Opal Liste...0xb727db70	Opal	Transport clean up on termination
2011/02/21 17:41:17.921	  0:00.449	Opal Liste...0xb727db70	Opal	Transport Close
2011/02/21 17:41:17.921	  0:00.449	        Pool:0xb6ee5b70	SIP	Handling PDU "2 SUBSCRIBE <501>" for transaction=z9hG4bK5203fc5a-213c-e011-958e-0007e90a60d3
2011/02/21 17:41:17.921	  0:00.449	        Pool:0xb6ee5b70	SIP	SUBSCRIBE transaction id=z9hG4bK5203fc5a-213c-e011-958e-0007e90a60d3 completed.
2011/02/21 17:41:17.921	  0:00.449	Opal Liste...0xb727db70	Opal	Deleted transport udp$10.106.1.225:5060<if=udp$10.106.1.203:5060>
2011/02/21 17:41:17.922	  0:00.449	        Pool:0xb6ee5b70	OpalUDP	Setting interface to 10.106.1.203%eth1
2011/02/21 17:41:17.922	  0:00.450	        Pool:0xb6ee5b70	SIP	Not retrying SUBSCRIBE due to error response 501 Not Implemented
2011/02/21 17:41:17.922	  0:00.450	        Pool:0xb6ee5b70	SIP	Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:44455501@10.106.1.225, id=3832fb5a-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:41:17.922	  0:00.450	                       	VideoOutputCoreConfBridge	Updating zoom
2011/02/21 17:41:17.922	  0:00.450	        Pool:0xb6ee5b70	SIP	Handled PDU "2 SUBSCRIBE <501>"
2011/02/21 17:41:17.922	  0:00.450	                       	VideoOutputCoreConfBridge	Updating Video Settings
2011/02/21 17:41:17.923	  0:00.451	                       	VideoOutputCoreConfBridge	Updating Video Settings
2011/02/21 17:41:17.923	  0:00.451	                       	VideoOutputCoreConfBridge	Updating Video Settings
2011/02/21 17:41:17.923	  0:00.451	                       	VideoOutputCoreConfBridge	Updating Video Settings
2011/02/21 17:41:17.923	  0:00.451	                       	VidInputCoreConfBridge	Updating preview size and fps
2011/02/21 17:41:17.924	  0:00.451	                       	VidInputCore	Setting new preview config: 176x144/30
2011/02/21 17:41:17.927	  0:00.455	                       	VidInputCoreConfBridge	Updating preview size and fps
2011/02/21 17:41:17.927	  0:00.455	                       	VidInputCore	Setting new preview config: 176x144/30
2011/02/21 17:41:17.931	  0:00.459	                       	VidInputCoreConfBridge	Updating device
2011/02/21 17:41:17.932	  0:00.459	                       	VidinputCore	Tried to set malformed device
2011/02/21 17:41:17.932	  0:00.460	                       	VidInputCore	Setting device: Moving Logo (Moving Logo/Moving Logo)
2011/02/21 17:41:17.932	  0:00.460	                       	GMVideoInputManager_mlogo	Setting Device Moving Logo
2011/02/21 17:41:17.932	  0:00.460	                       	VidInputCoreConfBridge	Updating device
2011/02/21 17:41:17.932	  0:00.460	                       	VidinputCore	Tried to set malformed device
2011/02/21 17:41:17.932	  0:00.460	                       	VidInputCore	Setting device: Moving Logo (Moving Logo/Moving Logo)
2011/02/21 17:41:17.932	  0:00.460	                       	GMVideoInputManager_mlogo	Setting Device Moving Logo
2011/02/21 17:41:17.933	  0:00.460	                       	VidInputCoreConfBridge	Updating device
2011/02/21 17:41:17.933	  0:00.460	                       	VidinputCore	Tried to set malformed device
2011/02/21 17:41:17.933	  0:00.460	                       	VidInputCore	Setting device: Moving Logo (Moving Logo/Moving Logo)
2011/02/21 17:41:17.933	  0:00.461	                       	GMVideoInputManager_mlogo	Setting Device Moving Logo
2011/02/21 17:41:17.933	  0:00.461	                       	VidInputCoreConfBridge	Updating image
2011/02/21 17:41:17.933	  0:00.461	                       	VidInputCoreConfBridge	Updating preview
2011/02/21 17:41:17.933	  0:00.461	                       	VidInputCore	Stopping Preview
2011/02/21 17:41:17.934	  0:00.462	                       	AudioOutputCoreConfBridge	Updating device
2011/02/21 17:41:17.934	  0:00.462	                       	AudioOutputCore	Tried to set malformed device
2011/02/21 17:41:17.934	  0:00.462	                       	AudioOutputCore	Setting device[0]: SILENT (Ekiga/Ekiga)
2011/02/21 17:41:17.934	  0:00.462	                       	GMAudioOutputManager_null	Setting Device[0] SILENT (Ekiga/Ekiga)
2011/02/21 17:41:17.934	  0:00.462	                       	AudioOutputCoreConfBridge	Updating device
2011/02/21 17:41:17.934	  0:00.462	                       	AudioOutputCore	Tried to set malformed device
2011/02/21 17:41:17.935	  0:00.462	                       	AudioOutputCore	Setting device[1]: SILENT (Ekiga/Ekiga)
2011/02/21 17:41:17.937	  0:00.465	                       	AudioInputCoreConfBridge	Updating device
2011/02/21 17:41:17.937	  0:00.465	                       	AudioInputCore	Tried to set malformed device
2011/02/21 17:41:17.937	  0:00.465	                       	AudioInputCore	Setting device: SILENT (Ekiga/Ekiga)
2011/02/21 17:41:17.937	  0:00.465	                       	GMAudioInputManager_null	Setting Device SILENT (Ekiga/Ekiga)
2011/02/21 17:41:18.048	  0:00.575	                       	Ekiga version 3.2.7
2011/02/21 17:41:18.048	  0:00.576	                       	OPAL version 3.6.8
2011/02/21 17:41:18.048	  0:00.576	                       	PTLIB version 2.6.7
2011/02/21 17:41:18.048	  0:00.576	                       	GNOME support disabled
2011/02/21 17:41:18.048	  0:00.576	                       	Accelerated rendering support enabled
2011/02/21 17:41:18.048	  0:00.576	                       	DBUS support enabled
2011/02/21 17:41:18.048	  0:00.576	                       	GConf support enabled
2011/02/21 17:41:18.048	  0:00.576	                       	ESound support disabled
2011/02/21 17:41:18.173	  0:00.700	                       	SIP	Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:neighbour@kalpeshsukla.local:5060, id=2a1d235b-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:41:18.173	  0:00.701	                       	SIP	No SRV lookup as has explicit port number.
2011/02/21 17:41:18.175	  0:00.703	                       	Socket	Name lookup of "kalpeshsukla.local" failed: errno=-2
2011/02/21 17:41:18.175	  0:00.703	                       	Opal	Could not find host "kalpeshsukla.local"
2011/02/21 17:41:18.175	  0:00.703	                       	OpalUDP	Binding to interface: 0.0.0.0:5060
2011/02/21 17:41:18.175	  0:00.703	                       	Socket	Name lookup of "kalpeshsukla.local" failed: errno=4
2011/02/21 17:41:18.175	  0:00.703	                       	Opal	Could not find host "kalpeshsukla.local"
2011/02/21 17:41:18.175	  0:00.703	                       	SIP	Could not find udp$kalpeshsukla.local:5060
2011/02/21 17:41:18.175	  0:00.703	                       	Opal	Transport clean up on termination
2011/02/21 17:41:18.175	  0:00.703	                       	Opal	Transport Close
2011/02/21 17:41:18.176	  0:00.703	                       	Opal	Deleted transport udp$*:5060<if=udp$*:5060>
2011/02/21 17:41:18.176	  0:00.703	                       	SIP	Changing SUBSCRIBE handler from Subscribing to Unavailable, target=sip:neighbour@kalpeshsukla.local:5060, id=2a1d235b-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:41:18.176	  0:00.704	                       	SIP	Retrying SUBSCRIBE in 30 seconds.
2011/02/21 17:41:18.176	  0:00.704	                       	SIP	Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:neighbour@kalpeshsukla.local:5060, id=9ead235b-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:41:18.176	  0:00.704	                       	SIP	No SRV lookup as has explicit port number.
2011/02/21 17:41:18.177	  0:00.704	                       	Socket	Name lookup of "kalpeshsukla.local" failed: errno=4
2011/02/21 17:41:18.177	  0:00.704	                       	Opal	Could not find host "kalpeshsukla.local"
2011/02/21 17:41:18.177	  0:00.705	                       	OpalUDP	Binding to interface: 0.0.0.0:5060
2011/02/21 17:41:18.177	  0:00.705	                       	Socket	Name lookup of "kalpeshsukla.local" failed: errno=4
2011/02/21 17:41:18.177	  0:00.705	                       	Opal	Could not find host "kalpeshsukla.local"
2011/02/21 17:41:18.177	  0:00.705	                       	SIP	Could not find udp$kalpeshsukla.local:5060
2011/02/21 17:41:18.177	  0:00.705	                       	Opal	Transport clean up on termination
2011/02/21 17:41:18.177	  0:00.705	                       	Opal	Transport Close
2011/02/21 17:41:18.177	  0:00.705	                       	Opal	Deleted transport udp$*:5060<if=udp$*:5060>
2011/02/21 17:41:18.177	  0:00.705	                       	SIP	Changing SUBSCRIBE handler from Subscribing to Unavailable, target=sip:neighbour@kalpeshsukla.local:5060, id=9ead235b-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:41:18.178	  0:00.705	                       	SIP	Retrying SUBSCRIBE in 30 seconds.
2011/02/21 17:41:18.761	  0:01.288	Opal Garbage:0xb7485b70	SIP	Deleting SIPSubscribeHandler sip:44455503@10.106.1.225
2011/02/21 17:41:18.761	  0:01.289	Opal Garbage:0xb7485b70	Opal	Transport clean up on termination
2011/02/21 17:41:18.761	  0:01.289	Opal Garbage:0xb7485b70	Opal	Transport Close
2011/02/21 17:41:18.761	  0:01.289	Opal Garbage:0xb7485b70	Opal	Transport clean up on termination
2011/02/21 17:41:18.761	  0:01.289	Opal Garbage:0xb7485b70	Opal	Transport Close
2011/02/21 17:41:18.761	  0:01.289	Opal Garbage:0xb7485b70	Opal	Deleted transport udp$10.106.1.225:5060<if=udp$10.106.1.203:5060>
2011/02/21 17:41:18.761	  0:01.289	Opal Garbage:0xb7485b70	SIP	Deleted handler.
2011/02/21 17:41:18.762	  0:01.290	Opal Garbage:0xb7485b70	SIP	Deleting SIPSubscribeHandler sip:44455503@10.106.1.225
2011/02/21 17:41:18.762	  0:01.290	Opal Garbage:0xb7485b70	Opal	Transport clean up on termination
2011/02/21 17:41:18.762	  0:01.290	Opal Garbage:0xb7485b70	Opal	Transport Close
2011/02/21 17:41:18.762	  0:01.290	Opal Garbage:0xb7485b70	Opal	Transport clean up on termination
2011/02/21 17:41:18.762	  0:01.290	Opal Garbage:0xb7485b70	Opal	Transport Close
2011/02/21 17:41:18.762	  0:01.290	Opal Garbage:0xb7485b70	Opal	Deleted transport udp$10.106.1.225:5060<if=udp$10.106.1.203:5060>
2011/02/21 17:41:18.762	  0:01.290	Opal Garbage:0xb7485b70	SIP	Deleted handler.
2011/02/21 17:41:18.763	  0:01.290	Opal Garbage:0xb7485b70	SIP	Deleting SIPSubscribeHandler sip:44455501@10.106.1.225
2011/02/21 17:41:18.763	  0:01.290	Opal Garbage:0xb7485b70	Opal	Transport clean up on termination
2011/02/21 17:41:18.763	  0:01.291	Opal Garbage:0xb7485b70	Opal	Transport Close
2011/02/21 17:41:18.763	  0:01.291	Opal Garbage:0xb7485b70	Opal	Transport clean up on termination
2011/02/21 17:41:18.763	  0:01.291	Opal Garbage:0xb7485b70	Opal	Transport Close
2011/02/21 17:41:18.763	  0:01.291	Opal Garbage:0xb7485b70	Opal	Deleted transport udp$10.106.1.225:5060<if=udp$10.106.1.203:5060>
2011/02/21 17:41:18.763	  0:01.291	Opal Garbage:0xb7485b70	SIP	Deleted handler.
2011/02/21 17:41:18.763	  0:01.291	Opal Garbage:0xb7485b70	SIP	Deleting SIPSubscribeHandler sip:44455501@10.106.1.225
2011/02/21 17:41:18.764	  0:01.291	Opal Garbage:0xb7485b70	Opal	Transport clean up on termination
2011/02/21 17:41:18.764	  0:01.291	Opal Garbage:0xb7485b70	Opal	Transport Close
2011/02/21 17:41:18.764	  0:01.291	Opal Garbage:0xb7485b70	Opal	Transport clean up on termination
2011/02/21 17:41:18.764	  0:01.292	Opal Garbage:0xb7485b70	Opal	Transport Close
2011/02/21 17:41:18.764	  0:01.292	Opal Garbage:0xb7485b70	Opal	Deleted transport udp$10.106.1.225:5060<if=udp$10.106.1.203:5060>
2011/02/21 17:41:18.764	  0:01.292	Opal Garbage:0xb7485b70	SIP	Deleted handler.
2011/02/21 17:41:18.899	  0:01.427	 Housekeeper:0xb72beb70	SIP	Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bKac2af85a-213c-e011-958e-0007e90a60d3
2011/02/21 17:41:18.907	  0:01.434	 Housekeeper:0xb72beb70	SIP	Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bK888ef95a-213c-e011-958e-0007e90a60d3
2011/02/21 17:41:18.915	  0:01.443	 Housekeeper:0xb72beb70	SIP	Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bK26d1fa5a-213c-e011-958e-0007e90a60d3
2011/02/21 17:41:18.922	  0:01.450	 Housekeeper:0xb72beb70	SIP	Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bK5203fc5a-213c-e011-958e-0007e90a60d3
2011/02/21 17:41:19.765	  0:02.292	Opal Garbage:0xb7485b70	SIP	Transaction id=z9hG4bK888ef95a-213c-e011-958e-0007e90a60d3 destroyed.
2011/02/21 17:41:19.765	  0:02.293	Opal Garbage:0xb7485b70	SIP	Transaction id=z9hG4bK5203fc5a-213c-e011-958e-0007e90a60d3 destroyed.
2011/02/21 17:41:19.765	  0:02.293	Opal Garbage:0xb7485b70	SIP	Transaction id=z9hG4bK26d1fa5a-213c-e011-958e-0007e90a60d3 destroyed.
2011/02/21 17:41:19.765	  0:02.293	Opal Garbage:0xb7485b70	SIP	Transaction id=z9hG4bKac2af85a-213c-e011-958e-0007e90a60d3 destroyed.
2011/02/21 17:41:20.254	  0:02.782	StunDetector:0xb6fbeb70	STUN	No response to STUN server 75.101.138.128:3478
2011/02/21 17:41:20.254	  0:02.782	StunDetector:0xb6fbeb70	OPAL	STUN server "stun.ekiga.net" replies Blocked, external IP 127.0.0.1
2011/02/21 17:41:20.927	  0:03.454	                       	PWLib	File handle low water mark set: 41 Thread unblock pipe
2011/02/21 17:41:20.928	  0:03.456	  subscriber:0xb6fbeb70	SIP	Start REGISTER
        aor=44455502@10.106.1.225
  registrar=10.106.1.225
    contact=
     authID=77777
      realm=
     expire=3600
    restore=30
   minRetry=0.000
   maxRetry=0.000
2011/02/21 17:41:20.929	  0:03.457	  subscriber:0xb6fbeb70	SIP	Changing REGISTER handler from Unavailable to Subscribing, target=sip:44455502@10.106.1.225, id=90a7c75c-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:41:20.929	  0:03.457	  subscriber:0xb6fbeb70	OpalUDP	Binding to interface: 0.0.0.0:5060
2011/02/21 17:41:20.930	  0:03.457	  subscriber:0xb6fbeb70	SIP	Created transport udp$10.106.1.225:5060<if=udp$*:5060>
2011/02/21 17:41:20.930	  0:03.458	  subscriber:0xb6fbeb70	OpalUDP	Started connect to 10.106.1.225:5060
2011/02/21 17:41:20.930	  0:03.458	  subscriber:0xb6fbeb70	OpalUDP	Writing to interface 0 - "10.106.1.203%eth1"
2011/02/21 17:41:20.932	  0:03.460	  subscriber:0xb6fbeb70	OpalMan	Listener interfaces: associated transport=udp$10.106.1.203:5060
    udp$10.106.1.203:5060
2011/02/21 17:41:20.933	  0:03.461	  subscriber:0xb6fbeb70	SIP	Transaction created.
2011/02/21 17:41:20.935	  0:03.463	  subscriber:0xb6fbeb70	SIP	Transaction remote address is udp$10.106.1.225:5060
2011/02/21 17:41:20.935	  0:03.463	  subscriber:0xb6fbeb70	SIP	Sending PDU (514 bytes) to: rem=udp$10.106.1.225:5060,local=udp$10.106.1.203:5060,if=10.106.1.203%eth1
REGISTER sip:10.106.1.225 SIP/2.0
CSeq: 1 REGISTER
Via: SIP/2.0/UDP 10.106.1.203:5060;branch=z9hG4bKb49bc85c-213c-e011-958e-0007e90a60d3;rport
User-Agent: Ekiga/3.2.7
From: <sip:44455502@10.106.1.225>;tag=b4b5c75c-213c-e011-958e-0007e90a60d3
Call-ID: 90a7c75c-213c-e011-958e-0007e90a60d3@onebox_shiv
To: <sip:44455502@10.106.1.225>
Contact: <sip:44455502@10.106.1.203>;q=1
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Expires: 3600
Content-Length: 0
Max-Forwards: 70


2011/02/21 17:41:20.936	  0:03.463	  subscriber:0xb6fbeb70	OpalUDP	Setting interface to 10.106.1.203%eth1
2011/02/21 17:41:20.936	  0:03.464	  subscriber:0xb6fbeb70	SIP	Transaction timers set: retry=0.500, completion=6.000
2011/02/21 17:41:20.941	  0:03.469	Opal Liste...0xb727db70	OpalUDP	Binding to interface: 10.106.1.203:5060
2011/02/21 17:41:20.941	  0:03.469	Opal Liste...0xb727db70	SIP	Waiting for PDU on udp$10.106.1.225:5060<if=udp$10.106.1.203:5060>
2011/02/21 17:41:20.942	  0:03.470	Opal Liste...0xb727db70	SIP	PDU received: rem=udp$10.106.1.225:5060,local=udp$10.106.1.203:5060,if=10.106.1.203%eth1
SIP/2.0 200 OK
CSeq: 1 REGISTER
Via: SIP/2.0/UDP 10.106.1.203:5060;branch=z9hG4bKb49bc85c-213c-e011-958e-0007e90a60d3;rport
User-Agent: Ekiga/3.2.7
From: <sip:44455502@10.106.1.225>;tag=b4b5c75c-213c-e011-958e-0007e90a60d3
Call-ID: 90a7c75c-213c-e011-958e-0007e90a60d3@onebox_shiv
To: <sip:44455502@10.106.1.225>
Contact: <sip:44455502@10.106.1.203>;q=1
Allow: MESSAGE, ACK, NOTIFY, OPTIONS, REFER, INFO, BYE, CANCEL, INVITE
Expires: 3600
Content-Length: 0


2011/02/21 17:41:20.942	  0:03.470	Opal Liste...0xb727db70	SIP	Queueing PDU "1 REGISTER <200>", transaction=z9hG4bKb49bc85c-213c-e011-958e-0007e90a60d3, token=90a7c75c-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:41:20.943	  0:03.470	Opal Liste...0xb727db70	Opal	Transport clean up on termination
2011/02/21 17:41:20.943	  0:03.470	Opal Liste...0xb727db70	Opal	Transport Close
2011/02/21 17:41:20.943	  0:03.471	        Pool:0xb6ee5b70	SIP	Handling PDU "1 REGISTER <200>" for transaction=z9hG4bKb49bc85c-213c-e011-958e-0007e90a60d3
2011/02/21 17:41:20.943	  0:03.471	        Pool:0xb6ee5b70	SIP	REGISTER transaction id=z9hG4bKb49bc85c-213c-e011-958e-0007e90a60d3 completed.
2011/02/21 17:41:20.943	  0:03.471	Opal Liste...0xb727db70	Opal	Deleted transport udp$10.106.1.225:5060<if=udp$10.106.1.203:5060>
2011/02/21 17:41:20.943	  0:03.471	        Pool:0xb6ee5b70	OpalUDP	Setting interface to 10.106.1.203%eth1
2011/02/21 17:41:20.943	  0:03.471	        Pool:0xb6ee5b70	SIP	Product Info: name="Ekiga", version="3.2.7", vendor="", comments=""
2011/02/21 17:41:20.944	  0:03.471	        Pool:0xb6ee5b70	SIP	Changing REGISTER handler from Subscribing to Subscribed, target=sip:44455502@10.106.1.225, id=90a7c75c-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:41:20.944	  0:03.472	        Pool:0xb6ee5b70	SIP	Expiry time for REGISTER set to 3600 seconds.
2011/02/21 17:41:20.944	  0:03.472	        Pool:0xb6ee5b70	SIP	Product Info: name="Ekiga", version="3.2.7", vendor="", comments=""
2011/02/21 17:41:20.945	  0:03.473	        Pool:0xb6ee5b70	SIP	Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:44455502@10.106.1.225, id=c821ca5c-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:41:20.945	  0:03.473	Opal Liste...0xb727db70	OpalUDP	Binding to interface: 10.106.1.203:5060
2011/02/21 17:41:20.945	  0:03.473	        Pool:0xb6ee5b70	OpalUDP	Binding to interface: 0.0.0.0:5060
2011/02/21 17:41:20.945	  0:03.473	Opal Liste...0xb727db70	SIP	Waiting for PDU on udp$10.106.1.225:5060<if=udp$10.106.1.203:5060>
2011/02/21 17:41:20.946	  0:03.473	        Pool:0xb6ee5b70	SIP	Created transport udp$10.106.1.225:5060<if=udp$*:5060>
2011/02/21 17:41:20.946	  0:03.474	        Pool:0xb6ee5b70	OpalUDP	Started connect to 10.106.1.225:5060
2011/02/21 17:41:20.946	  0:03.474	        Pool:0xb6ee5b70	OpalUDP	Writing to interface 0 - "10.106.1.203%eth1"
2011/02/21 17:41:20.946	  0:03.474	Opal Liste...0xb727db70	SIP	PDU received: rem=udp$10.106.1.225:5060,local=udp$10.106.1.203:5060,if=10.106.1.203%eth1
NOTIFY sip:44455502@10.106.1.225 SIP/2.0
CSeq: 335672337 NOTIFY
Via: SIP/2.0/UDP 10.106.1.225:5060;rport;branch=z9hG4bK05cbc5ec066eb1f37429c787fb9f36d6
From: "SWITCH" <sip:SWITCH@10.106.1.225>;tag=wDO6LuYm
Call-ID: 2303362011-6A0AE10112102341@10.106.1.225
To: <sip:44455502@10.106.1.225>
Contact: <sip:SWITCH@10.106.1.225:5060>
Event: message-summary
Content-Type: application/simple-message-summary
Content-Length: 38

Messages-Waiting: No
Voicemail: 0/0

2011/02/21 17:41:20.946	  0:03.474	        Pool:0xb6ee5b70	SIP	Transaction created.
2011/02/21 17:41:20.947	  0:03.474	Opal Liste...0xb727db70	SIP	Received NOTIFY message-summary
2011/02/21 17:41:20.947	  0:03.475	Opal Liste...0xb727db70	SIP	Work around Asterisk bug in message-summary event package.
2011/02/21 17:41:20.948	  0:03.476	        Pool:0xb6ee5b70	SIP	Transaction remote address is udp$10.106.1.225:5060
2011/02/21 17:41:20.949	  0:03.476	        Pool:0xb6ee5b70	SIP	Sending PDU (589 bytes) to: rem=udp$10.106.1.225:5060,local=udp$10.106.1.203:5060,if=10.106.1.203%eth1
SUBSCRIBE sip:44455502@10.106.1.225 SIP/2.0
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 10.106.1.203:5060;branch=z9hG4bK6e85ca5c-213c-e011-958e-0007e90a60d3;rport
User-Agent: Ekiga/3.2.7
From: <sip:44455502@10.106.1.225>;tag=1061ca5c-213c-e011-958e-0007e90a60d3
Call-ID: c821ca5c-213c-e011-958e-0007e90a60d3@onebox_shiv
To: <sip:44455502@10.106.1.225>
Contact: <sip:44455502@10.106.1.203>
Accept: application/simple-message-summary
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Expires: 3600
Event: message-summary
Content-Length: 0
Max-Forwards: 70


2011/02/21 17:41:20.949	  0:03.477	        Pool:0xb6ee5b70	OpalUDP	Setting interface to 10.106.1.203%eth1
2011/02/21 17:41:20.949	  0:03.477	        Pool:0xb6ee5b70	SIP	Transaction timers set: retry=0.500, completion=6.000
2011/02/21 17:41:20.949	  0:03.477	Opal Liste...0xb727db70	SIP	Found a SUBSCRIBE corresponding to the NOTIFY message-summary
2011/02/21 17:41:20.949	  0:03.477	        Pool:0xb6ee5b70	SIP	Handled PDU "1 REGISTER <200>"
2011/02/21 17:41:20.950	  0:03.477	Opal Liste...0xb727db70	SIP	Dialog initial sequence number 335672337
2011/02/21 17:41:20.950	  0:03.478	Opal Liste...0xb727db70	SIP	Received unexpected sequence number 335672337, expecting 1
2011/02/21 17:41:20.951	  0:03.479	Opal Liste...0xb727db70	SIP	Sending PDU (327 bytes) to: rem=udp$10.106.1.225:5060,local=udp$10.106.1.203:5060,if=10.106.1.203%eth1
SIP/2.0 200 OK
CSeq: 335672337 NOTIFY
Via: SIP/2.0/UDP 10.106.1.225:5060;rport;branch=z9hG4bK05cbc5ec066eb1f37429c787fb9f36d6
From: "SWITCH" <sip:SWITCH@10.106.1.225>;tag=wDO6LuYm
Call-ID: 2303362011-6A0AE10112102341@10.106.1.225
To: <sip:44455502@10.106.1.225>
Contact: <sip:44455502@10.106.1.203>
Content-Length: 0


2011/02/21 17:41:20.952	  0:03.479	Opal Liste...0xb727db70	OpalUDP	Setting interface to 10.106.1.203%eth1
2011/02/21 17:41:20.952	  0:03.480	Opal Liste...0xb727db70	Opal	Transport clean up on termination
2011/02/21 17:41:20.952	  0:03.480	Opal Liste...0xb727db70	Opal	Transport Close
2011/02/21 17:41:20.952	  0:03.480	Opal Liste...0xb727db70	Opal	Deleted transport udp$10.106.1.225:5060<if=udp$10.106.1.203:5060>
2011/02/21 17:41:20.953	  0:03.480	Opal Liste...0xb727db70	OpalUDP	Binding to interface: 10.106.1.203:5060
2011/02/21 17:41:20.953	  0:03.480	Opal Liste...0xb727db70	SIP	Waiting for PDU on udp$10.106.1.225:5060<if=udp$10.106.1.203:5060>
2011/02/21 17:41:20.953	  0:03.481	Opal Liste...0xb727db70	SIP	PDU received: rem=udp$10.106.1.225:5060,local=udp$10.106.1.203:5060,if=10.106.1.203%eth1
SIP/2.0 501 Not Implement
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 10.106.1.203:5060;branch=z9hG4bK6e85ca5c-213c-e011-958e-0007e90a60d3;rport
User-Agent: Ekiga/3.2.7
From: <sip:44455502@10.106.1.225>;tag=1061ca5c-213c-e011-958e-0007e90a60d3
Call-ID: c821ca5c-213c-e011-958e-0007e90a60d3@onebox_shiv
To: <sip:44455502@10.106.1.225>
Contact: <sip:44455502@10.106.1.203>
Accept: application/simple-message-summary
Allow: INVITE, ACK, OPTIONS, BYE, CANCEL, SUBSCRIBE, NOTIFY, REFER, MESSAGE, INFO, PING
Expires: 3600
Event: message-summary
Content-Length: 0


2011/02/21 17:41:20.954	  0:03.482	Opal Liste...0xb727db70	SIP	Queueing PDU "2 SUBSCRIBE <501>", transaction=z9hG4bK6e85ca5c-213c-e011-958e-0007e90a60d3, token=c821ca5c-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:41:20.954	  0:03.482	Opal Liste...0xb727db70	Opal	Transport clean up on termination
2011/02/21 17:41:20.954	  0:03.482	Opal Liste...0xb727db70	Opal	Transport Close
2011/02/21 17:41:20.954	  0:03.482	        Pool:0xb6ee5b70	SIP	Handling PDU "2 SUBSCRIBE <501>" for transaction=z9hG4bK6e85ca5c-213c-e011-958e-0007e90a60d3
2011/02/21 17:41:20.954	  0:03.482	        Pool:0xb6ee5b70	SIP	SUBSCRIBE transaction id=z9hG4bK6e85ca5c-213c-e011-958e-0007e90a60d3 completed.
2011/02/21 17:41:20.954	  0:03.482	Opal Liste...0xb727db70	Opal	Deleted transport udp$10.106.1.225:5060<if=udp$10.106.1.203:5060>
2011/02/21 17:41:20.954	  0:03.482	        Pool:0xb6ee5b70	OpalUDP	Setting interface to 10.106.1.203%eth1
2011/02/21 17:41:20.955	  0:03.482	        Pool:0xb6ee5b70	SIP	Not retrying SUBSCRIBE due to error response 501 Not Implemented
2011/02/21 17:41:20.955	  0:03.482	        Pool:0xb6ee5b70	SIP	Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:44455502@10.106.1.225, id=c821ca5c-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:41:20.955	  0:03.482	        Pool:0xb6ee5b70	SIP	Handled PDU "2 SUBSCRIBE <501>"
2011/02/21 17:41:20.955	  0:03.483	                       	SIP	Changing PUBLISH handler from Unavailable to Subscribing, target=sip:44455502@10.106.1.225, id=40a3cb5c-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:41:20.955	  0:03.483	                       	OpalUDP	Binding to interface: 0.0.0.0:5060
2011/02/21 17:41:20.955	  0:03.483	                       	SIP	Created transport udp$10.106.1.225:5060<if=udp$*:5060>
2011/02/21 17:41:20.956	  0:03.483	                       	OpalUDP	Started connect to 10.106.1.225:5060
2011/02/21 17:41:20.956	  0:03.484	                       	OpalUDP	Writing to interface 0 - "10.106.1.203%eth1"
2011/02/21 17:41:20.956	  0:03.484	                       	SIP	Transaction created.
2011/02/21 17:41:20.958	  0:03.486	                       	SIP	Transaction remote address is udp$10.106.1.225:5060
2011/02/21 17:41:20.959	  0:03.486	                       	SIP	Sending PDU (837 bytes) to: rem=udp$10.106.1.225:5060,local=udp$10.106.1.203:5060,if=10.106.1.203%eth1
PUBLISH sip:44455502@10.106.1.225 SIP/2.0
CSeq: 2 PUBLISH
Via: SIP/2.0/UDP 10.106.1.203:5060;branch=z9hG4bKc6fecb5c-213c-e011-958e-0007e90a60d3;rport
User-Agent: Ekiga/3.2.7
From: <sip:44455502@10.106.1.225>;tag=ccdfcb5c-213c-e011-958e-0007e90a60d3
Call-ID: 40a3cb5c-213c-e011-958e-0007e90a60d3@onebox_shiv
To: <sip:44455502@10.106.1.225>
Contact: <sip:44455502@10.106.1.203>
Expires: 500
Event: presence
Content-Type: application/pidf+xml
Content-Length: 345
Max-Forwards: 70

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

2011/02/21 17:41:20.959	  0:03.487	                       	OpalUDP	Setting interface to 10.106.1.203%eth1
2011/02/21 17:41:20.959	  0:03.487	                       	SIP	Transaction timers set: retry=0.500, completion=6.000
2011/02/21 17:41:20.962	  0:03.489	Opal Liste...0xb727db70	OpalUDP	Binding to interface: 10.106.1.203:5060
2011/02/21 17:41:20.962	  0:03.490	Opal Liste...0xb727db70	SIP	Waiting for PDU on udp$10.106.1.225:5060<if=udp$10.106.1.203:5060>
2011/02/21 17:41:20.962	  0:03.490	Opal Liste...0xb727db70	SIP	PDU received: rem=udp$10.106.1.225:5060,local=udp$10.106.1.203:5060,if=10.106.1.203%eth1
SIP/2.0 501 Not Implement
CSeq: 2 PUBLISH
Via: SIP/2.0/UDP 10.106.1.203:5060;branch=z9hG4bKc6fecb5c-213c-e011-958e-0007e90a60d3;rport
User-Agent: Ekiga/3.2.7
From: <sip:44455502@10.106.1.225>;tag=ccdfcb5c-213c-e011-958e-0007e90a60d3
Call-ID: 40a3cb5c-213c-e011-958e-0007e90a60d3@onebox_shiv
To: <sip:44455502@10.106.1.225>
Contact: <sip:44455502@10.106.1.203>
Expires: 500
Event: presence
Content-Type: application/pidf+xml
Content-Length: 345

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

2011/02/21 17:41:20.963	  0:03.491	Opal Liste...0xb727db70	SIP	Queueing PDU "2 PUBLISH <501>", transaction=z9hG4bKc6fecb5c-213c-e011-958e-0007e90a60d3, token=40a3cb5c-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:41:20.963	  0:03.491	Opal Liste...0xb727db70	Opal	Transport clean up on termination
2011/02/21 17:41:20.963	  0:03.491	Opal Liste...0xb727db70	Opal	Transport Close
2011/02/21 17:41:20.963	  0:03.491	        Pool:0xb6ee5b70	SIP	Handling PDU "2 PUBLISH <501>" for transaction=z9hG4bKc6fecb5c-213c-e011-958e-0007e90a60d3
2011/02/21 17:41:20.963	  0:03.491	        Pool:0xb6ee5b70	SIP	PUBLISH transaction id=z9hG4bKc6fecb5c-213c-e011-958e-0007e90a60d3 completed.
2011/02/21 17:41:20.963	  0:03.491	Opal Liste...0xb727db70	Opal	Deleted transport udp$10.106.1.225:5060<if=udp$10.106.1.203:5060>
2011/02/21 17:41:20.964	  0:03.491	        Pool:0xb6ee5b70	OpalUDP	Setting interface to 10.106.1.203%eth1
2011/02/21 17:41:20.964	  0:03.491	        Pool:0xb6ee5b70	SIP	Not retrying PUBLISH due to error response 501 Not Implemented
2011/02/21 17:41:20.964	  0:03.492	        Pool:0xb6ee5b70	SIP	Changing PUBLISH handler from Subscribing to Unsubscribed, target=sip:44455502@10.106.1.225, id=40a3cb5c-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:41:20.964	  0:03.492	        Pool:0xb6ee5b70	SIP	Handled PDU "2 PUBLISH <501>"
2011/02/21 17:41:21.766	  0:04.294	Opal Garbage:0xb7485b70	SIP	Deleting SIPSubscribeHandler sip:44455502@10.106.1.225
2011/02/21 17:41:21.766	  0:04.294	Opal Garbage:0xb7485b70	Opal	Transport clean up on termination
2011/02/21 17:41:21.766	  0:04.294	Opal Garbage:0xb7485b70	Opal	Transport Close
2011/02/21 17:41:21.766	  0:04.294	Opal Garbage:0xb7485b70	Opal	Transport clean up on termination
2011/02/21 17:41:21.766	  0:04.294	Opal Garbage:0xb7485b70	Opal	Transport Close
2011/02/21 17:41:21.766	  0:04.294	Opal Garbage:0xb7485b70	Opal	Deleted transport udp$10.106.1.225:5060<if=udp$10.106.1.203:5060>
2011/02/21 17:41:21.767	  0:04.295	Opal Garbage:0xb7485b70	SIP	Deleted handler.
2011/02/21 17:41:21.767	  0:04.295	Opal Garbage:0xb7485b70	SIP	Deleting SIPPublishHandler sip:44455502@10.106.1.225
2011/02/21 17:41:21.767	  0:04.295	Opal Garbage:0xb7485b70	Opal	Transport clean up on termination
2011/02/21 17:41:21.767	  0:04.295	Opal Garbage:0xb7485b70	Opal	Transport Close
2011/02/21 17:41:21.767	  0:04.295	Opal Garbage:0xb7485b70	Opal	Transport clean up on termination
2011/02/21 17:41:21.767	  0:04.295	Opal Garbage:0xb7485b70	Opal	Transport Close
2011/02/21 17:41:21.767	  0:04.295	Opal Garbage:0xb7485b70	Opal	Deleted transport udp$10.106.1.225:5060<if=udp$10.106.1.203:5060>
2011/02/21 17:41:21.768	  0:04.296	Opal Garbage:0xb7485b70	SIP	Deleted handler.
2011/02/21 17:41:21.949	  0:04.477	 Housekeeper:0xb72beb70	SIP	Set state Terminated_Success for REGISTER transaction id=z9hG4bKb49bc85c-213c-e011-958e-0007e90a60d3
2011/02/21 17:41:21.955	  0:04.483	 Housekeeper:0xb72beb70	SIP	Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bK6e85ca5c-213c-e011-958e-0007e90a60d3
2011/02/21 17:41:21.964	  0:04.492	 Housekeeper:0xb72beb70	SIP	Set state Terminated_Success for PUBLISH transaction id=z9hG4bKc6fecb5c-213c-e011-958e-0007e90a60d3
2011/02/21 17:41:22.768	  0:05.296	Opal Garbage:0xb7485b70	SIP	Transaction id=z9hG4bK6e85ca5c-213c-e011-958e-0007e90a60d3 destroyed.
2011/02/21 17:41:22.769	  0:05.296	Opal Garbage:0xb7485b70	SIP	Transaction id=z9hG4bKb49bc85c-213c-e011-958e-0007e90a60d3 destroyed.
2011/02/21 17:41:22.769	  0:05.297	Opal Garbage:0xb7485b70	SIP	Transaction id=z9hG4bKc6fecb5c-213c-e011-958e-0007e90a60d3 destroyed.
2011/02/21 17:41:32.771	  0:15.298	Network In...0xb7444b70	PWLib	File handle low water mark set: 40 PUDPSocket
2011/02/21 17:41:36.247	  0:18.775	Opal Liste...0xb727db70	OpalUDP	Binding to interface: 10.106.1.203:5060
2011/02/21 17:41:36.248	  0:18.775	Opal Liste...0xb727db70	SIP	Waiting for PDU on udp$10.106.1.225:5060<if=udp$10.106.1.203:5060>
2011/02/21 17:41:36.248	  0:18.776	Opal Liste...0xb727db70	SIP	PDU received: rem=udp$10.106.1.225:5060,local=udp$10.106.1.203:5060,if=10.106.1.203%eth1
MESSAGE sip:44455502@10.106.1.225:5060;user=phone SIP/2.0
CSeq: 1 MESSAGE
Via: SIP/2.0/UDP 10.106.1.225:5060;rport;branch=z9hG4bK90a896d513bceb3dfd3637aeccbad8f5
From: "Anonymous" <sip:anonymous@10.106.1.225:5060;user=phone>;tag=GR52RWG346-34
Call-ID: 2303512011-6A0AE10122102640@10.106.1.225
To: "44455502@10.106.1.225" <sip:44455502@10.106.1.225:5060>
Allow: MESSAGE, ACK, NOTIFY, OPTIONS, REFER, INFO, BYE, CANCEL, INVITE
Content-Type: text/plain
Content-Length: 10
max-forwards: 70

hello shiv
2011/02/21 17:41:36.250	  0:18.778	Opal Liste...0xb727db70	SIP	Sending PDU (376 bytes) to: rem=udp$10.106.1.225:5060,local=udp$10.106.1.203:5060,if=10.106.1.203%eth1
SIP/2.0 200 OK
CSeq: 1 MESSAGE
Via: SIP/2.0/UDP 10.106.1.225:5060;rport;branch=z9hG4bK90a896d513bceb3dfd3637aeccbad8f5
From: "Anonymous" <sip:anonymous@10.106.1.225:5060;user=phone>;tag=GR52RWG346-34
Call-ID: 2303512011-6A0AE10122102640@10.106.1.225
To: "44455502@10.106.1.225" <sip:44455502@10.106.1.225:5060>
Contact: <sip:44455502@10.106.1.203>
Content-Length: 0


2011/02/21 17:41:36.251	  0:18.779	Opal Liste...0xb727db70	OpalUDP	Setting interface to 10.106.1.203%eth1
2011/02/21 17:41:36.251	  0:18.779	Opal Liste...0xb727db70	Opal	Transport clean up on termination
2011/02/21 17:41:36.251	  0:18.779	Opal Liste...0xb727db70	Opal	Transport Close
2011/02/21 17:41:36.251	  0:18.779	Opal Liste...0xb727db70	Opal	Deleted transport udp$10.106.1.225:5060<if=udp$10.106.1.203:5060>
2011/02/21 17:41:36.286	  0:18.814	                       	SIP	Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:anonymous@10.106.1.225:5060, id=40feee65-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:41:36.286	  0:18.814	                       	SIP	No SRV lookup as has explicit port number.
2011/02/21 17:41:36.286	  0:18.814	                       	OpalUDP	Binding to interface: 0.0.0.0:5060
2011/02/21 17:41:36.287	  0:18.814	                       	SIP	Created transport udp$10.106.1.225:5060<if=udp$*:5060>
2011/02/21 17:41:36.287	  0:18.814	                       	OpalUDP	Started connect to 10.106.1.225:5060
2011/02/21 17:41:36.287	  0:18.815	                       	OpalUDP	Writing to interface 0 - "10.106.1.203%eth1"
2011/02/21 17:41:36.287	  0:18.815	                       	SIP	Transaction created.
2011/02/21 17:41:36.289	  0:18.817	                       	SIP	No SRV lookup as has explicit port number.
2011/02/21 17:41:36.289	  0:18.817	                       	SIP	Transaction remote address is udp$10.106.1.225:5060
2011/02/21 17:41:36.290	  0:18.817	                       	SIP	Sending PDU (579 bytes) to: rem=udp$10.106.1.225:5060,local=udp$10.106.1.203:5060,if=10.106.1.203%eth1
SUBSCRIBE sip:anonymous@10.106.1.225:5060 SIP/2.0
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 10.106.1.203:5060;branch=z9hG4bK705aef65-213c-e011-958e-0007e90a60d3;rport
User-Agent: Ekiga/3.2.7
From: <sip:44455502@10.106.1.225>;tag=5232ef65-213c-e011-958e-0007e90a60d3
Call-ID: 40feee65-213c-e011-958e-0007e90a60d3@onebox_shiv
To: <sip:anonymous@10.106.1.225:5060>
Contact: <sip:44455502@10.106.1.203>
Accept: application/pidf+xml
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Expires: 300
Event: presence
Content-Length: 0
Max-Forwards: 70


2011/02/21 17:41:36.290	  0:18.818	                       	OpalUDP	Setting interface to 10.106.1.203%eth1
2011/02/21 17:41:36.290	  0:18.818	                       	SIP	Transaction timers set: retry=0.500, completion=6.000
2011/02/21 17:41:36.291	  0:18.818	                       	SIP	Changing SUBSCRIBE handler from Unavailable to Subscribing, target=sip:anonymous@10.106.1.225:5060, id=eeb3ef65-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:41:36.291	  0:18.818	                       	SIP	No SRV lookup as has explicit port number.
2011/02/21 17:41:36.291	  0:18.819	                       	OpalUDP	Binding to interface: 0.0.0.0:5060
2011/02/21 17:41:36.291	  0:18.819	                       	SIP	Created transport udp$10.106.1.225:5060<if=udp$*:5060>
2011/02/21 17:41:36.291	  0:18.819	                       	OpalUDP	Started connect to 10.106.1.225:5060
2011/02/21 17:41:36.291	  0:18.819	                       	OpalUDP	Writing to interface 0 - "10.106.1.203%eth1"
2011/02/21 17:41:36.292	  0:18.820	Opal Liste...0xb727db70	OpalUDP	Binding to interface: 10.106.1.203:5060
2011/02/21 17:41:36.292	  0:18.820	Opal Liste...0xb727db70	SIP	Waiting for PDU on udp$10.106.1.225:5060<if=udp$10.106.1.203:5060>
2011/02/21 17:41:36.293	  0:18.820	Opal Liste...0xb727db70	SIP	PDU received: rem=udp$10.106.1.225:5060,local=udp$10.106.1.203:5060,if=10.106.1.203%eth1
SIP/2.0 501 Not Implement
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 10.106.1.203:5060;branch=z9hG4bK705aef65-213c-e011-958e-0007e90a60d3;rport
User-Agent: Ekiga/3.2.7
From: <sip:44455502@10.106.1.225>;tag=5232ef65-213c-e011-958e-0007e90a60d3
Call-ID: 40feee65-213c-e011-958e-0007e90a60d3@onebox_shiv
To: <sip:anonymous@10.106.1.225:5060>
Contact: <sip:44455502@10.106.1.203>
Accept: application/pidf+xml
Allow: INVITE, ACK, OPTIONS, BYE, CANCEL, SUBSCRIBE, NOTIFY, REFER, MESSAGE, INFO, PING
Expires: 300
Event: presence
Content-Length: 0


2011/02/21 17:41:36.293	  0:18.821	Opal Liste...0xb727db70	SIP	Queueing PDU "2 SUBSCRIBE <501>", transaction=z9hG4bK705aef65-213c-e011-958e-0007e90a60d3, token=40feee65-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:41:36.293	  0:18.821	Opal Liste...0xb727db70	Opal	Transport clean up on termination
2011/02/21 17:41:36.293	  0:18.821	Opal Liste...0xb727db70	Opal	Transport Close
2011/02/21 17:41:36.293	  0:18.821	        Pool:0xb6ee5b70	SIP	Handling PDU "2 SUBSCRIBE <501>" for transaction=z9hG4bK705aef65-213c-e011-958e-0007e90a60d3
2011/02/21 17:41:36.294	  0:18.821	        Pool:0xb6ee5b70	SIP	SUBSCRIBE transaction id=z9hG4bK705aef65-213c-e011-958e-0007e90a60d3 completed.
2011/02/21 17:41:36.294	  0:18.821	Opal Liste...0xb727db70	Opal	Deleted transport udp$10.106.1.225:5060<if=udp$10.106.1.203:5060>
2011/02/21 17:41:36.294	  0:18.822	        Pool:0xb6ee5b70	OpalUDP	Setting interface to 10.106.1.203%eth1
2011/02/21 17:41:36.294	  0:18.822	                       	SIP	Transaction created.
2011/02/21 17:41:36.294	  0:18.822	        Pool:0xb6ee5b70	SIP	Not retrying SUBSCRIBE due to error response 501 Not Implemented
2011/02/21 17:41:36.294	  0:18.822	        Pool:0xb6ee5b70	SIP	Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:anonymous@10.106.1.225:5060, id=40feee65-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:41:36.294	  0:18.822	        Pool:0xb6ee5b70	SIP	Handled PDU "2 SUBSCRIBE <501>"
2011/02/21 17:41:36.296	  0:18.823	                       	SIP	No SRV lookup as has explicit port number.
2011/02/21 17:41:36.296	  0:18.824	                       	SIP	Transaction remote address is udp$10.106.1.225:5060
2011/02/21 17:41:36.296	  0:18.824	                       	SIP	Sending PDU (592 bytes) to: rem=udp$10.106.1.225:5060,local=udp$10.106.1.203:5060,if=10.106.1.203%eth1
SUBSCRIBE sip:anonymous@10.106.1.225:5060 SIP/2.0
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 10.106.1.203:5060;branch=z9hG4bK8e5ef065-213c-e011-958e-0007e90a60d3;rport
User-Agent: Ekiga/3.2.7
From: <sip:44455502@10.106.1.225>;tag=ae39f065-213c-e011-958e-0007e90a60d3
Call-ID: eeb3ef65-213c-e011-958e-0007e90a60d3@onebox_shiv
To: <sip:anonymous@10.106.1.225:5060>
Contact: <sip:anonymous@10.106.1.203>
Accept: application/dialog-info+xml
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Expires: 300
Event: dialog;sla;ma
Content-Length: 0
Max-Forwards: 70


2011/02/21 17:41:36.296	  0:18.824	                       	OpalUDP	Setting interface to 10.106.1.203%eth1
2011/02/21 17:41:36.297	  0:18.824	                       	SIP	Transaction timers set: retry=0.500, completion=6.000
2011/02/21 17:41:36.299	  0:18.827	Opal Liste...0xb727db70	OpalUDP	Binding to interface: 10.106.1.203:5060
2011/02/21 17:41:36.299	  0:18.827	Opal Liste...0xb727db70	SIP	Waiting for PDU on udp$10.106.1.225:5060<if=udp$10.106.1.203:5060>
2011/02/21 17:41:36.299	  0:18.827	Opal Liste...0xb727db70	SIP	PDU received: rem=udp$10.106.1.225:5060,local=udp$10.106.1.203:5060,if=10.106.1.203%eth1
SIP/2.0 501 Not Implement
CSeq: 2 SUBSCRIBE
Via: SIP/2.0/UDP 10.106.1.203:5060;branch=z9hG4bK8e5ef065-213c-e011-958e-0007e90a60d3;rport
User-Agent: Ekiga/3.2.7
From: <sip:44455502@10.106.1.225>;tag=ae39f065-213c-e011-958e-0007e90a60d3
Call-ID: eeb3ef65-213c-e011-958e-0007e90a60d3@onebox_shiv
To: <sip:anonymous@10.106.1.225:5060>
Contact: <sip:anonymous@10.106.1.203>
Accept: application/dialog-info+xml
Allow: INVITE, ACK, OPTIONS, BYE, CANCEL, SUBSCRIBE, NOTIFY, REFER, MESSAGE, INFO, PING
Expires: 300
Event: dialog;sla;ma
Content-Length: 0


2011/02/21 17:41:36.300	  0:18.828	Opal Liste...0xb727db70	SIP	Queueing PDU "2 SUBSCRIBE <501>", transaction=z9hG4bK8e5ef065-213c-e011-958e-0007e90a60d3, token=eeb3ef65-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:41:36.300	  0:18.828	Opal Liste...0xb727db70	Opal	Transport clean up on termination
2011/02/21 17:41:36.300	  0:18.828	Opal Liste...0xb727db70	Opal	Transport Close
2011/02/21 17:41:36.300	  0:18.828	        Pool:0xb6ee5b70	SIP	Handling PDU "2 SUBSCRIBE <501>" for transaction=z9hG4bK8e5ef065-213c-e011-958e-0007e90a60d3
2011/02/21 17:41:36.301	  0:18.828	        Pool:0xb6ee5b70	SIP	SUBSCRIBE transaction id=z9hG4bK8e5ef065-213c-e011-958e-0007e90a60d3 completed.
2011/02/21 17:41:36.300	  0:18.828	Opal Liste...0xb727db70	Opal	Deleted transport udp$10.106.1.225:5060<if=udp$10.106.1.203:5060>
2011/02/21 17:41:36.301	  0:18.828	        Pool:0xb6ee5b70	OpalUDP	Setting interface to 10.106.1.203%eth1
2011/02/21 17:41:36.301	  0:18.829	        Pool:0xb6ee5b70	SIP	Not retrying SUBSCRIBE due to error response 501 Not Implemented
2011/02/21 17:41:36.301	  0:18.829	        Pool:0xb6ee5b70	SIP	Changing SUBSCRIBE handler from Subscribing to Unsubscribed, target=sip:anonymous@10.106.1.225:5060, id=eeb3ef65-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:41:36.301	  0:18.829	        Pool:0xb6ee5b70	SIP	Handled PDU "2 SUBSCRIBE <501>"
2011/02/21 17:41:36.310	  0:18.838	                       	AEScheduler	Adding Event newmessage.wav 0/0 to queue
2011/02/21 17:41:36.311	  0:18.839	AudioEvent...0xb74c6b70	AEScheduler	Checking pending list with 1 elements
2011/02/21 17:41:36.311	  0:18.839	AudioEvent...0xb74c6b70	AEScheduler	Trying to load newmessage.wav for event newmessage.wav
2011/02/21 17:41:36.311	  0:18.839	AudioEvent...0xb74c6b70	AEScheduler	Trying to load /usr/share/sounds/ekiga/newmessage.wav for event newmessage.wav
2011/02/21 17:41:36.311	  0:18.839	AudioEvent...0xb74c6b70	AudioOutputCore	Opening device[0] with 2-44100/16
2011/02/21 17:41:36.311	  0:18.839	AudioEvent...0xb74c6b70	GMAudioOutputManager_null	Opening Device[0] SILENT (Ekiga/Ekiga)
2011/02/21 17:41:36.312	  0:18.839	AudioEvent...0xb74c6b70	GMAudioOutputManager_null	Opening Device with 2-44100/16
2011/02/21 17:41:36.561	  0:19.089	AudioEvent...0xb74c6b70	AudioOutputCore	Closing current device
2011/02/21 17:41:36.770	  0:19.298	Opal Garbage:0xb7485b70	SIP	Deleting SIPSubscribeHandler sip:anonymous@10.106.1.225:5060
2011/02/21 17:41:36.771	  0:19.298	Opal Garbage:0xb7485b70	Opal	Transport clean up on termination
2011/02/21 17:41:36.771	  0:19.298	Opal Garbage:0xb7485b70	Opal	Transport Close
2011/02/21 17:41:36.771	  0:19.299	Opal Garbage:0xb7485b70	Opal	Transport clean up on termination
2011/02/21 17:41:36.771	  0:19.299	Opal Garbage:0xb7485b70	Opal	Transport Close
2011/02/21 17:41:36.771	  0:19.299	Opal Garbage:0xb7485b70	Opal	Deleted transport udp$10.106.1.225:5060<if=udp$10.106.1.203:5060>
2011/02/21 17:41:36.771	  0:19.299	Opal Garbage:0xb7485b70	SIP	Deleted handler.
2011/02/21 17:41:36.772	  0:19.299	Opal Garbage:0xb7485b70	SIP	Deleting SIPSubscribeHandler sip:anonymous@10.106.1.225:5060
2011/02/21 17:41:36.772	  0:19.299	Opal Garbage:0xb7485b70	Opal	Transport clean up on termination
2011/02/21 17:41:36.772	  0:19.299	Opal Garbage:0xb7485b70	Opal	Transport Close
2011/02/21 17:41:36.772	  0:19.300	Opal Garbage:0xb7485b70	Opal	Transport clean up on termination
2011/02/21 17:41:36.772	  0:19.300	Opal Garbage:0xb7485b70	Opal	Transport Close
2011/02/21 17:41:36.772	  0:19.300	Opal Garbage:0xb7485b70	Opal	Deleted transport udp$10.106.1.225:5060<if=udp$10.106.1.203:5060>
2011/02/21 17:41:36.772	  0:19.300	Opal Garbage:0xb7485b70	SIP	Deleted handler.
2011/02/21 17:41:37.295	  0:19.822	 Housekeeper:0xb72beb70	SIP	Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bK705aef65-213c-e011-958e-0007e90a60d3
2011/02/21 17:41:37.302	  0:19.829	 Housekeeper:0xb72beb70	SIP	Set state Terminated_Success for SUBSCRIBE transaction id=z9hG4bK8e5ef065-213c-e011-958e-0007e90a60d3
2011/02/21 17:41:37.773	  0:20.300	Opal Garbage:0xb7485b70	SIP	Transaction id=z9hG4bK8e5ef065-213c-e011-958e-0007e90a60d3 destroyed.
2011/02/21 17:41:37.773	  0:20.301	Opal Garbage:0xb7485b70	SIP	Transaction id=z9hG4bK705aef65-213c-e011-958e-0007e90a60d3 destroyed.
2011/02/21 17:41:48.177	  0:30.705	 Housekeeper:0xb72beb70	SIP	Starting SUBSCRIBE for offline retry
2011/02/21 17:41:48.177	  0:30.705	 Housekeeper:0xb72beb70	SIP	Changing SUBSCRIBE handler from Unavailable to Restoring, target=sip:neighbour@kalpeshsukla.local:5060, id=2a1d235b-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:41:48.177	  0:30.705	 Housekeeper:0xb72beb70	SIP	No SRV lookup as has explicit port number.
2011/02/21 17:41:48.178	  0:30.705	 Housekeeper:0xb72beb70	Socket	Name lookup of "kalpeshsukla.local" failed: errno=4
2011/02/21 17:41:48.178	  0:30.705	 Housekeeper:0xb72beb70	Opal	Could not find host "kalpeshsukla.local"
2011/02/21 17:41:48.178	  0:30.705	 Housekeeper:0xb72beb70	OpalUDP	Binding to interface: 0.0.0.0:5060
2011/02/21 17:41:48.178	  0:30.706	 Housekeeper:0xb72beb70	Socket	Name lookup of "kalpeshsukla.local" failed: errno=4
2011/02/21 17:41:48.178	  0:30.706	 Housekeeper:0xb72beb70	Opal	Could not find host "kalpeshsukla.local"
2011/02/21 17:41:48.178	  0:30.706	 Housekeeper:0xb72beb70	SIP	Could not find udp$kalpeshsukla.local:5060
2011/02/21 17:41:48.178	  0:30.706	 Housekeeper:0xb72beb70	Opal	Transport clean up on termination
2011/02/21 17:41:48.178	  0:30.706	 Housekeeper:0xb72beb70	Opal	Transport Close
2011/02/21 17:41:48.178	  0:30.706	 Housekeeper:0xb72beb70	Opal	Deleted transport udp$*:5060<if=udp$*:5060>
2011/02/21 17:41:48.178	  0:30.706	 Housekeeper:0xb72beb70	SIP	Changing SUBSCRIBE handler from Restoring to Unavailable, target=sip:neighbour@kalpeshsukla.local:5060, id=2a1d235b-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:41:48.178	  0:30.706	 Housekeeper:0xb72beb70	SIP	Retrying SUBSCRIBE in 30 seconds.
2011/02/21 17:41:48.179	  0:30.706	 Housekeeper:0xb72beb70	SIP	Starting SUBSCRIBE for offline retry
2011/02/21 17:41:48.179	  0:30.706	 Housekeeper:0xb72beb70	SIP	Changing SUBSCRIBE handler from Unavailable to Restoring, target=sip:neighbour@kalpeshsukla.local:5060, id=9ead235b-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:41:48.179	  0:30.706	 Housekeeper:0xb72beb70	SIP	No SRV lookup as has explicit port number.
2011/02/21 17:41:48.179	  0:30.707	 Housekeeper:0xb72beb70	Socket	Name lookup of "kalpeshsukla.local" failed: errno=4
2011/02/21 17:41:48.179	  0:30.707	 Housekeeper:0xb72beb70	Opal	Could not find host "kalpeshsukla.local"
2011/02/21 17:41:48.179	  0:30.707	 Housekeeper:0xb72beb70	OpalUDP	Binding to interface: 0.0.0.0:5060
2011/02/21 17:41:48.179	  0:30.707	 Housekeeper:0xb72beb70	Socket	Name lookup of "kalpeshsukla.local" failed: errno=4
2011/02/21 17:41:48.179	  0:30.707	 Housekeeper:0xb72beb70	Opal	Could not find host "kalpeshsukla.local"
2011/02/21 17:41:48.179	  0:30.707	 Housekeeper:0xb72beb70	SIP	Could not find udp$kalpeshsukla.local:5060
2011/02/21 17:41:48.179	  0:30.707	 Housekeeper:0xb72beb70	Opal	Transport clean up on termination
2011/02/21 17:41:48.180	  0:30.707	 Housekeeper:0xb72beb70	Opal	Transport Close
2011/02/21 17:41:48.180	  0:30.707	 Housekeeper:0xb72beb70	Opal	Deleted transport udp$*:5060<if=udp$*:5060>
2011/02/21 17:41:48.180	  0:30.708	 Housekeeper:0xb72beb70	SIP	Changing SUBSCRIBE handler from Restoring to Unavailable, target=sip:neighbour@kalpeshsukla.local:5060, id=9ead235b-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:41:48.180	  0:30.708	 Housekeeper:0xb72beb70	SIP	Retrying SUBSCRIBE in 30 seconds.
2011/02/21 17:41:51.040	  0:33.567	                       	SIP	Changing MESSAGE handler from Unavailable to Subscribed, target=sip:44455501@10.106.1.225, id=c432ba6e-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:41:51.040	  0:33.568	                       	SIP	Changing MESSAGE handler from Subscribed to Subscribing, target=sip:44455501@10.106.1.225, id=c432ba6e-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:41:51.040	  0:33.568	                       	OpalUDP	Binding to interface: 0.0.0.0:5060
2011/02/21 17:41:51.041	  0:33.568	                       	SIP	Created transport udp$10.106.1.225:5060<if=udp$*:5060>
2011/02/21 17:41:51.041	  0:33.569	                       	OpalUDP	Started connect to 10.106.1.225:5060
2011/02/21 17:41:51.041	  0:33.569	                       	OpalUDP	Writing to interface 0 - "10.106.1.203%eth1"
2011/02/21 17:41:51.041	  0:33.569	                       	SIP	Expiry time for MESSAGE set to 3600 seconds.
2011/02/21 17:41:51.041	  0:33.569	                       	SIP	Transaction created.
2011/02/21 17:41:51.043	  0:33.571	                       	SIP	Transaction remote address is udp$10.106.1.225:5060
2011/02/21 17:41:51.043	  0:33.571	                       	SIP	Sending PDU (414 bytes) to: rem=udp$10.106.1.225:5060,local=udp$10.106.1.203:5060,if=10.106.1.203%eth1
MESSAGE sip:44455501@10.106.1.225 SIP/2.0
CSeq: 3 MESSAGE
Via: SIP/2.0/UDP 10.106.1.203:5060;branch=z9hG4bK96b5ba6e-213c-e011-958e-0007e90a60d3;rport
From: <sip:44455502@10.106.1.225>;tag=2e91ba6e-213c-e011-958e-0007e90a60d3
Call-ID: c432ba6e-213c-e011-958e-0007e90a60d3@onebox_shiv
To: <sip:44455501@10.106.1.225>
Content-Type: text/plain;charset=UTF-8
Content-Length: 13
Max-Forwards: 70

hello kalpesh
2011/02/21 17:41:51.043	  0:33.571	                       	OpalUDP	Setting interface to 10.106.1.203%eth1
2011/02/21 17:41:51.044	  0:33.572	                       	SIP	Transaction timers set: retry=0.499, completion=6.000
2011/02/21 17:41:51.072	  0:33.599	Opal Liste...0xb727db70	OpalUDP	Binding to interface: 10.106.1.203:5060
2011/02/21 17:41:51.072	  0:33.600	Opal Liste...0xb727db70	SIP	Waiting for PDU on udp$10.106.1.225:5060<if=udp$10.106.1.203:5060>
2011/02/21 17:41:51.072	  0:33.600	Opal Liste...0xb727db70	SIP	PDU received: rem=udp$10.106.1.225:5060,local=udp$10.106.1.203:5060,if=10.106.1.203%eth1
SIP/2.0 200 OK
CSeq: 3 MESSAGE
Via: SIP/2.0/UDP 10.106.1.203:5060;branch=z9hG4bK96b5ba6e-213c-e011-958e-0007e90a60d3;rport
From: <sip:44455502@10.106.1.225>;tag=2e91ba6e-213c-e011-958e-0007e90a60d3
Call-ID: c432ba6e-213c-e011-958e-0007e90a60d3@onebox_shiv
To: <sip:44455501@10.106.1.225>;tag=GR52RWG346-34
Content-Length: 0


2011/02/21 17:41:51.073	  0:33.601	Opal Liste...0xb727db70	SIP	Queueing PDU "3 MESSAGE <200>", transaction=z9hG4bK96b5ba6e-213c-e011-958e-0007e90a60d3, token=c432ba6e-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:41:51.073	  0:33.601	Opal Liste...0xb727db70	Opal	Transport clean up on termination
2011/02/21 17:41:51.073	  0:33.601	Opal Liste...0xb727db70	Opal	Transport Close
2011/02/21 17:41:51.073	  0:33.601	        Pool:0xb6ee5b70	SIP	Handling PDU "3 MESSAGE <200>" for transaction=z9hG4bK96b5ba6e-213c-e011-958e-0007e90a60d3
2011/02/21 17:41:51.073	  0:33.601	        Pool:0xb6ee5b70	SIP	MESSAGE transaction id=z9hG4bK96b5ba6e-213c-e011-958e-0007e90a60d3 completed.
2011/02/21 17:41:51.073	  0:33.601	Opal Liste...0xb727db70	Opal	Deleted transport udp$10.106.1.225:5060<if=udp$10.106.1.203:5060>
2011/02/21 17:41:51.074	  0:33.601	        Pool:0xb6ee5b70	OpalUDP	Setting interface to 10.106.1.203%eth1
2011/02/21 17:41:51.074	  0:33.601	        Pool:0xb6ee5b70	SIP	No User-Agent or Server fields, Product Info unknown.
2011/02/21 17:41:51.074	  0:33.602	        Pool:0xb6ee5b70	SIP	Changing MESSAGE handler from Subscribing to Subscribed, target=sip:44455501@10.106.1.225, id=c432ba6e-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:41:51.074	  0:33.602	        Pool:0xb6ee5b70	SIP	Handled PDU "3 MESSAGE <200>"
2011/02/21 17:41:52.074	  0:34.602	 Housekeeper:0xb72beb70	SIP	Set state Terminated_Success for MESSAGE transaction id=z9hG4bK96b5ba6e-213c-e011-958e-0007e90a60d3
2011/02/21 17:41:52.775	  0:35.303	Opal Garbage:0xb7485b70	SIP	Transaction id=z9hG4bK96b5ba6e-213c-e011-958e-0007e90a60d3 destroyed.
2011/02/21 17:41:58.428	  0:40.956	Opal Liste...0xb727db70	OpalUDP	Binding to interface: 10.106.1.203:5060
2011/02/21 17:41:58.428	  0:40.956	Opal Liste...0xb727db70	SIP	Waiting for PDU on udp$10.106.1.225:5060<if=udp$10.106.1.203:5060>
2011/02/21 17:41:58.429	  0:40.956	Opal Liste...0xb727db70	SIP	PDU received: rem=udp$10.106.1.225:5060,local=udp$10.106.1.203:5060,if=10.106.1.203%eth1
MESSAGE sip:44455502@10.106.1.225:5060;user=phone SIP/2.0
CSeq: 1 MESSAGE
Via: SIP/2.0/UDP 10.106.1.225:5060;rport;branch=z9hG4bK299d07733b85128cb69129e35de3ca9c
From: "Anonymous" <sip:anonymous@10.106.1.225:5060;user=phone>;tag=GR52RWG346-34
Call-ID: 2304132011-6A0AE10142102816@10.106.1.225
To: "44455502@10.106.1.225" <sip:44455502@10.106.1.225:5060>
Allow: MESSAGE, ACK, NOTIFY, OPTIONS, REFER, INFO, BYE, CANCEL, INVITE
Content-Type: text/plain
Content-Length: 13
max-forwards: 70

how r u shiv?
2011/02/21 17:41:58.431	  0:40.958	Opal Liste...0xb727db70	SIP	Sending PDU (376 bytes) to: rem=udp$10.106.1.225:5060,local=udp$10.106.1.203:5060,if=10.106.1.203%eth1
SIP/2.0 200 OK
CSeq: 1 MESSAGE
Via: SIP/2.0/UDP 10.106.1.225:5060;rport;branch=z9hG4bK299d07733b85128cb69129e35de3ca9c
From: "Anonymous" <sip:anonymous@10.106.1.225:5060;user=phone>;tag=GR52RWG346-34
Call-ID: 2304132011-6A0AE10142102816@10.106.1.225
To: "44455502@10.106.1.225" <sip:44455502@10.106.1.225:5060>
Contact: <sip:44455502@10.106.1.203>
Content-Length: 0


2011/02/21 17:41:58.431	  0:40.959	Opal Liste...0xb727db70	OpalUDP	Setting interface to 10.106.1.203%eth1
2011/02/21 17:41:58.431	  0:40.959	Opal Liste...0xb727db70	Opal	Transport clean up on termination
2011/02/21 17:41:58.431	  0:40.959	Opal Liste...0xb727db70	Opal	Transport Close
2011/02/21 17:41:58.431	  0:40.959	Opal Liste...0xb727db70	Opal	Deleted transport udp$10.106.1.225:5060<if=udp$10.106.1.203:5060>
2011/02/21 17:42:09.713	  0:52.241	                       	SIP	Changing MESSAGE handler from Subscribed to Subscribing, target=sip:44455501@10.106.1.225, id=c432ba6e-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:42:09.714	  0:52.242	                       	SIP	Expiry time for MESSAGE set to 3600 seconds.
2011/02/21 17:42:09.714	  0:52.242	                       	SIP	Transaction created.
2011/02/21 17:42:09.715	  0:52.243	                       	SIP	Transaction remote address is udp$10.106.1.225:5060
2011/02/21 17:42:09.716	  0:52.243	                       	SIP	Sending PDU (417 bytes) to: rem=udp$10.106.1.225:5060,local=udp$10.106.1.203:5060,if=10.106.1.203%eth1
MESSAGE sip:44455501@10.106.1.225 SIP/2.0
CSeq: 4 MESSAGE
Via: SIP/2.0/UDP 10.106.1.203:5060;branch=z9hG4bK6ae7db79-213c-e011-958e-0007e90a60d3;rport
From: <sip:44455502@10.106.1.225>;tag=b4c0db79-213c-e011-958e-0007e90a60d3
Call-ID: c432ba6e-213c-e011-958e-0007e90a60d3@onebox_shiv
To: <sip:44455501@10.106.1.225>
Content-Type: text/plain;charset=UTF-8
Content-Length: 16
Max-Forwards: 70

how r u kalpesh?
2011/02/21 17:42:09.716	  0:52.244	                       	OpalUDP	Setting interface to 10.106.1.203%eth1
2011/02/21 17:42:09.716	  0:52.244	                       	SIP	Transaction timers set: retry=0.500, completion=6.000
2011/02/21 17:42:09.745	  0:52.272	Opal Liste...0xb727db70	OpalUDP	Binding to interface: 10.106.1.203:5060
2011/02/21 17:42:09.745	  0:52.273	Opal Liste...0xb727db70	SIP	Waiting for PDU on udp$10.106.1.225:5060<if=udp$10.106.1.203:5060>
2011/02/21 17:42:09.745	  0:52.273	Opal Liste...0xb727db70	SIP	PDU received: rem=udp$10.106.1.225:5060,local=udp$10.106.1.203:5060,if=10.106.1.203%eth1
SIP/2.0 200 OK
CSeq: 4 MESSAGE
Via: SIP/2.0/UDP 10.106.1.203:5060;branch=z9hG4bK6ae7db79-213c-e011-958e-0007e90a60d3;rport
From: <sip:44455502@10.106.1.225>;tag=b4c0db79-213c-e011-958e-0007e90a60d3
Call-ID: c432ba6e-213c-e011-958e-0007e90a60d3@onebox_shiv
To: <sip:44455501@10.106.1.225>;tag=GR52RWG346-34
Content-Length: 0


2011/02/21 17:42:09.746	  0:52.273	Opal Liste...0xb727db70	SIP	Queueing PDU "4 MESSAGE <200>", transaction=z9hG4bK6ae7db79-213c-e011-958e-0007e90a60d3, token=c432ba6e-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:42:09.746	  0:52.274	Opal Liste...0xb727db70	Opal	Transport clean up on termination
2011/02/21 17:42:09.746	  0:52.274	Opal Liste...0xb727db70	Opal	Transport Close
2011/02/21 17:42:09.746	  0:52.274	        Pool:0xb6ee5b70	SIP	Handling PDU "4 MESSAGE <200>" for transaction=z9hG4bK6ae7db79-213c-e011-958e-0007e90a60d3
2011/02/21 17:42:09.746	  0:52.274	        Pool:0xb6ee5b70	SIP	MESSAGE transaction id=z9hG4bK6ae7db79-213c-e011-958e-0007e90a60d3 completed.
2011/02/21 17:42:09.746	  0:52.274	Opal Liste...0xb727db70	Opal	Deleted transport udp$10.106.1.225:5060<if=udp$10.106.1.203:5060>
2011/02/21 17:42:09.746	  0:52.274	        Pool:0xb6ee5b70	OpalUDP	Setting interface to 10.106.1.203%eth1
2011/02/21 17:42:09.747	  0:52.274	        Pool:0xb6ee5b70	SIP	No User-Agent or Server fields, Product Info unknown.
2011/02/21 17:42:09.747	  0:52.274	        Pool:0xb6ee5b70	SIP	Changing MESSAGE handler from Subscribing to Subscribed, target=sip:44455501@10.106.1.225, id=c432ba6e-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:42:09.747	  0:52.275	        Pool:0xb6ee5b70	SIP	Handled PDU "4 MESSAGE <200>"
2011/02/21 17:42:10.747	  0:53.275	 Housekeeper:0xb72beb70	SIP	Set state Terminated_Success for MESSAGE transaction id=z9hG4bK6ae7db79-213c-e011-958e-0007e90a60d3
2011/02/21 17:42:10.777	  0:53.305	Opal Garbage:0xb7485b70	SIP	Transaction id=z9hG4bK6ae7db79-213c-e011-958e-0007e90a60d3 destroyed.
2011/02/21 17:42:18.178	  1:00.706	 Housekeeper:0xb72beb70	SIP	Starting SUBSCRIBE for offline retry
2011/02/21 17:42:18.178	  1:00.706	 Housekeeper:0xb72beb70	SIP	Changing SUBSCRIBE handler from Unavailable to Restoring, target=sip:neighbour@kalpeshsukla.local:5060, id=2a1d235b-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:42:18.178	  1:00.706	 Housekeeper:0xb72beb70	SIP	No SRV lookup as has explicit port number.
2011/02/21 17:42:18.178	  1:00.706	 Housekeeper:0xb72beb70	Socket	Name lookup of "kalpeshsukla.local" failed: errno=4
2011/02/21 17:42:18.178	  1:00.706	 Housekeeper:0xb72beb70	Opal	Could not find host "kalpeshsukla.local"
2011/02/21 17:42:18.179	  1:00.706	 Housekeeper:0xb72beb70	OpalUDP	Binding to interface: 0.0.0.0:5060
2011/02/21 17:42:18.179	  1:00.706	 Housekeeper:0xb72beb70	Socket	Name lookup of "kalpeshsukla.local" failed: errno=4
2011/02/21 17:42:18.179	  1:00.707	 Housekeeper:0xb72beb70	Opal	Could not find host "kalpeshsukla.local"
2011/02/21 17:42:18.179	  1:00.707	 Housekeeper:0xb72beb70	SIP	Could not find udp$kalpeshsukla.local:5060
2011/02/21 17:42:18.179	  1:00.707	 Housekeeper:0xb72beb70	Opal	Transport clean up on termination
2011/02/21 17:42:18.179	  1:00.707	 Housekeeper:0xb72beb70	Opal	Transport Close
2011/02/21 17:42:18.179	  1:00.707	 Housekeeper:0xb72beb70	Opal	Deleted transport udp$*:5060<if=udp$*:5060>
2011/02/21 17:42:18.179	  1:00.707	 Housekeeper:0xb72beb70	SIP	Changing SUBSCRIBE handler from Restoring to Unavailable, target=sip:neighbour@kalpeshsukla.local:5060, id=2a1d235b-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:42:18.179	  1:00.707	 Housekeeper:0xb72beb70	SIP	Retrying SUBSCRIBE in 30 seconds.
2011/02/21 17:42:18.179	  1:00.707	 Housekeeper:0xb72beb70	SIP	Starting SUBSCRIBE for offline retry
2011/02/21 17:42:18.179	  1:00.707	 Housekeeper:0xb72beb70	SIP	Changing SUBSCRIBE handler from Unavailable to Restoring, target=sip:neighbour@kalpeshsukla.local:5060, id=9ead235b-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:42:18.180	  1:00.707	 Housekeeper:0xb72beb70	SIP	No SRV lookup as has explicit port number.
2011/02/21 17:42:18.180	  1:00.707	 Housekeeper:0xb72beb70	Socket	Name lookup of "kalpeshsukla.local" failed: errno=4
2011/02/21 17:42:18.180	  1:00.708	 Housekeeper:0xb72beb70	Opal	Could not find host "kalpeshsukla.local"
2011/02/21 17:42:18.180	  1:00.708	 Housekeeper:0xb72beb70	OpalUDP	Binding to interface: 0.0.0.0:5060
2011/02/21 17:42:18.180	  1:00.708	 Housekeeper:0xb72beb70	Socket	Name lookup of "kalpeshsukla.local" failed: errno=4
2011/02/21 17:42:18.180	  1:00.708	 Housekeeper:0xb72beb70	Opal	Could not find host "kalpeshsukla.local"
2011/02/21 17:42:18.180	  1:00.708	 Housekeeper:0xb72beb70	SIP	Could not find udp$kalpeshsukla.local:5060
2011/02/21 17:42:18.180	  1:00.708	 Housekeeper:0xb72beb70	Opal	Transport clean up on termination
2011/02/21 17:42:18.180	  1:00.708	 Housekeeper:0xb72beb70	Opal	Transport Close
2011/02/21 17:42:18.180	  1:00.708	 Housekeeper:0xb72beb70	Opal	Deleted transport udp$*:5060<if=udp$*:5060>
2011/02/21 17:42:18.181	  1:00.708	 Housekeeper:0xb72beb70	SIP	Changing SUBSCRIBE handler from Restoring to Unavailable, target=sip:neighbour@kalpeshsukla.local:5060, id=9ead235b-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:42:18.181	  1:00.708	 Housekeeper:0xb72beb70	SIP	Retrying SUBSCRIBE in 30 seconds.
2011/02/21 17:42:31.050	  1:13.578	Opal Liste...0xb727db70	OpalUDP	Binding to interface: 10.106.1.203:5060
2011/02/21 17:42:31.051	  1:13.578	Opal Liste...0xb727db70	SIP	Waiting for PDU on udp$10.106.1.225:5060<if=udp$10.106.1.203:5060>
2011/02/21 17:42:31.051	  1:13.579	Opal Liste...0xb727db70	SIP	PDU received: rem=udp$10.106.1.225:5060,local=udp$10.106.1.203:5060,if=10.106.1.203%eth1
MESSAGE sip:44455502@10.106.1.225:5060;user=phone SIP/2.0
CSeq: 1 MESSAGE
Via: SIP/2.0/UDP 10.106.1.225:5060;rport;branch=z9hG4bK42b5548e58fa197298ebf48b60f3fc51
From: "Anonymous" <sip:anonymous@10.106.1.225:5060;user=phone>;tag=GR52RWG346-34
Call-ID: 2304462011-6A0AE10162102432@10.106.1.225
To: "44455502@10.106.1.225" <sip:44455502@10.106.1.225:5060>
Allow: MESSAGE, ACK, NOTIFY, OPTIONS, REFER, INFO, BYE, CANCEL, INVITE
Content-Type: text/plain
Content-Length: 8
max-forwards: 70

hey shiv
2011/02/21 17:42:31.053	  1:13.581	Opal Liste...0xb727db70	SIP	Sending PDU (376 bytes) to: rem=udp$10.106.1.225:5060,local=udp$10.106.1.203:5060,if=10.106.1.203%eth1
SIP/2.0 200 OK
CSeq: 1 MESSAGE
Via: SIP/2.0/UDP 10.106.1.225:5060;rport;branch=z9hG4bK42b5548e58fa197298ebf48b60f3fc51
From: "Anonymous" <sip:anonymous@10.106.1.225:5060;user=phone>;tag=GR52RWG346-34
Call-ID: 2304462011-6A0AE10162102432@10.106.1.225
To: "44455502@10.106.1.225" <sip:44455502@10.106.1.225:5060>
Contact: <sip:44455502@10.106.1.203>
Content-Length: 0


2011/02/21 17:42:31.053	  1:13.581	Opal Liste...0xb727db70	OpalUDP	Setting interface to 10.106.1.203%eth1
2011/02/21 17:42:31.054	  1:13.582	Opal Liste...0xb727db70	Opal	Transport clean up on termination
2011/02/21 17:42:31.054	  1:13.582	Opal Liste...0xb727db70	Opal	Transport Close
2011/02/21 17:42:31.054	  1:13.582	Opal Liste...0xb727db70	Opal	Deleted transport udp$10.106.1.225:5060<if=udp$10.106.1.203:5060>
2011/02/21 17:42:43.048	  1:25.576	                       	SIP	Changing MESSAGE handler from Subscribed to Subscribing, target=sip:44455501@10.106.1.225, id=c432ba6e-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:42:43.048	  1:25.576	                       	SIP	Expiry time for MESSAGE set to 3600 seconds.
2011/02/21 17:42:43.048	  1:25.576	                       	SIP	Transaction created.
2011/02/21 17:42:43.050	  1:25.578	                       	SIP	Transaction remote address is udp$10.106.1.225:5060
2011/02/21 17:42:43.050	  1:25.578	                       	SIP	Sending PDU (412 bytes) to: rem=udp$10.106.1.225:5060,local=udp$10.106.1.203:5060,if=10.106.1.203%eth1
MESSAGE sip:44455501@10.106.1.225 SIP/2.0
CSeq: 5 MESSAGE
Via: SIP/2.0/UDP 10.106.1.203:5060;branch=z9hG4bK1c5cba8d-213c-e011-958e-0007e90a60d3;rport
From: <sip:44455502@10.106.1.225>;tag=a235ba8d-213c-e011-958e-0007e90a60d3
Call-ID: c432ba6e-213c-e011-958e-0007e90a60d3@onebox_shiv
To: <sip:44455501@10.106.1.225>
Content-Type: text/plain;charset=UTF-8
Content-Length: 11
Max-Forwards: 70

hey kalpesh
2011/02/21 17:42:43.051	  1:25.578	                       	OpalUDP	Setting interface to 10.106.1.203%eth1
2011/02/21 17:42:43.051	  1:25.579	                       	SIP	Transaction timers set: retry=0.500, completion=6.000
2011/02/21 17:42:43.075	  1:25.603	Opal Liste...0xb727db70	OpalUDP	Binding to interface: 10.106.1.203:5060
2011/02/21 17:42:43.076	  1:25.603	Opal Liste...0xb727db70	SIP	Waiting for PDU on udp$10.106.1.225:5060<if=udp$10.106.1.203:5060>
2011/02/21 17:42:43.076	  1:25.604	Opal Liste...0xb727db70	SIP	PDU received: rem=udp$10.106.1.225:5060,local=udp$10.106.1.203:5060,if=10.106.1.203%eth1
SIP/2.0 200 OK
CSeq: 5 MESSAGE
Via: SIP/2.0/UDP 10.106.1.203:5060;branch=z9hG4bK1c5cba8d-213c-e011-958e-0007e90a60d3;rport
From: <sip:44455502@10.106.1.225>;tag=a235ba8d-213c-e011-958e-0007e90a60d3
Call-ID: c432ba6e-213c-e011-958e-0007e90a60d3@onebox_shiv
To: <sip:44455501@10.106.1.225>;tag=GR52RWG346-34
Content-Length: 0


2011/02/21 17:42:43.076	  1:25.604	Opal Liste...0xb727db70	SIP	Queueing PDU "5 MESSAGE <200>", transaction=z9hG4bK1c5cba8d-213c-e011-958e-0007e90a60d3, token=c432ba6e-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:42:43.077	  1:25.604	Opal Liste...0xb727db70	Opal	Transport clean up on termination
2011/02/21 17:42:43.077	  1:25.604	Opal Liste...0xb727db70	Opal	Transport Close
2011/02/21 17:42:43.077	  1:25.605	        Pool:0xb6ee5b70	SIP	Handling PDU "5 MESSAGE <200>" for transaction=z9hG4bK1c5cba8d-213c-e011-958e-0007e90a60d3
2011/02/21 17:42:43.077	  1:25.605	        Pool:0xb6ee5b70	SIP	MESSAGE transaction id=z9hG4bK1c5cba8d-213c-e011-958e-0007e90a60d3 completed.
2011/02/21 17:42:43.077	  1:25.605	Opal Liste...0xb727db70	Opal	Deleted transport udp$10.106.1.225:5060<if=udp$10.106.1.203:5060>
2011/02/21 17:42:43.077	  1:25.605	        Pool:0xb6ee5b70	OpalUDP	Setting interface to 10.106.1.203%eth1
2011/02/21 17:42:43.077	  1:25.605	        Pool:0xb6ee5b70	SIP	No User-Agent or Server fields, Product Info unknown.
2011/02/21 17:42:43.078	  1:25.605	        Pool:0xb6ee5b70	SIP	Changing MESSAGE handler from Subscribing to Subscribed, target=sip:44455501@10.106.1.225, id=c432ba6e-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:42:43.078	  1:25.605	        Pool:0xb6ee5b70	SIP	Handled PDU "5 MESSAGE <200>"
2011/02/21 17:42:44.077	  1:26.605	 Housekeeper:0xb72beb70	SIP	Set state Terminated_Success for MESSAGE transaction id=z9hG4bK1c5cba8d-213c-e011-958e-0007e90a60d3
2011/02/21 17:42:44.781	  1:27.308	Opal Garbage:0xb7485b70	SIP	Transaction id=z9hG4bK1c5cba8d-213c-e011-958e-0007e90a60d3 destroyed.
2011/02/21 17:42:48.179	  1:30.706	 Housekeeper:0xb72beb70	SIP	Starting SUBSCRIBE for offline retry
2011/02/21 17:42:48.179	  1:30.707	 Housekeeper:0xb72beb70	SIP	Changing SUBSCRIBE handler from Unavailable to Restoring, target=sip:neighbour@kalpeshsukla.local:5060, id=2a1d235b-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:42:48.179	  1:30.707	 Housekeeper:0xb72beb70	SIP	No SRV lookup as has explicit port number.
2011/02/21 17:42:48.179	  1:30.707	 Housekeeper:0xb72beb70	Socket	Name lookup of "kalpeshsukla.local" failed: errno=4
2011/02/21 17:42:48.179	  1:30.707	 Housekeeper:0xb72beb70	Opal	Could not find host "kalpeshsukla.local"
2011/02/21 17:42:48.179	  1:30.707	 Housekeeper:0xb72beb70	OpalUDP	Binding to interface: 0.0.0.0:5060
2011/02/21 17:42:48.179	  1:30.707	 Housekeeper:0xb72beb70	Socket	Name lookup of "kalpeshsukla.local" failed: errno=4
2011/02/21 17:42:48.180	  1:30.707	 Housekeeper:0xb72beb70	Opal	Could not find host "kalpeshsukla.local"
2011/02/21 17:42:48.180	  1:30.707	 Housekeeper:0xb72beb70	SIP	Could not find udp$kalpeshsukla.local:5060
2011/02/21 17:42:48.180	  1:30.707	 Housekeeper:0xb72beb70	Opal	Transport clean up on termination
2011/02/21 17:42:48.180	  1:30.708	 Housekeeper:0xb72beb70	Opal	Transport Close
2011/02/21 17:42:48.180	  1:30.708	 Housekeeper:0xb72beb70	Opal	Deleted transport udp$*:5060<if=udp$*:5060>
2011/02/21 17:42:48.180	  1:30.708	 Housekeeper:0xb72beb70	SIP	Changing SUBSCRIBE handler from Restoring to Unavailable, target=sip:neighbour@kalpeshsukla.local:5060, id=2a1d235b-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:42:48.180	  1:30.708	 Housekeeper:0xb72beb70	SIP	Retrying SUBSCRIBE in 30 seconds.
2011/02/21 17:42:48.180	  1:30.708	 Housekeeper:0xb72beb70	SIP	Starting SUBSCRIBE for offline retry
2011/02/21 17:42:48.180	  1:30.708	 Housekeeper:0xb72beb70	SIP	Changing SUBSCRIBE handler from Unavailable to Restoring, target=sip:neighbour@kalpeshsukla.local:5060, id=9ead235b-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:42:48.180	  1:30.708	 Housekeeper:0xb72beb70	SIP	No SRV lookup as has explicit port number.
2011/02/21 17:42:48.181	  1:30.708	 Housekeeper:0xb72beb70	Socket	Name lookup of "kalpeshsukla.local" failed: errno=4
2011/02/21 17:42:48.181	  1:30.708	 Housekeeper:0xb72beb70	Opal	Could not find host "kalpeshsukla.local"
2011/02/21 17:42:48.181	  1:30.708	 Housekeeper:0xb72beb70	OpalUDP	Binding to interface: 0.0.0.0:5060
2011/02/21 17:42:48.181	  1:30.709	 Housekeeper:0xb72beb70	Socket	Name lookup of "kalpeshsukla.local" failed: errno=4
2011/02/21 17:42:48.181	  1:30.709	 Housekeeper:0xb72beb70	Opal	Could not find host "kalpeshsukla.local"
2011/02/21 17:42:48.181	  1:30.709	 Housekeeper:0xb72beb70	SIP	Could not find udp$kalpeshsukla.local:5060
2011/02/21 17:42:48.181	  1:30.709	 Housekeeper:0xb72beb70	Opal	Transport clean up on termination
2011/02/21 17:42:48.181	  1:30.709	 Housekeeper:0xb72beb70	Opal	Transport Close
2011/02/21 17:42:48.181	  1:30.709	 Housekeeper:0xb72beb70	Opal	Deleted transport udp$*:5060<if=udp$*:5060>
2011/02/21 17:42:48.182	  1:30.709	 Housekeeper:0xb72beb70	SIP	Changing SUBSCRIBE handler from Restoring to Unavailable, target=sip:neighbour@kalpeshsukla.local:5060, id=9ead235b-213c-e011-958e-0007e90a60d3@onebox_shiv
2011/02/21 17:42:48.182	  1:30.709	 Housekeeper:0xb72beb70	SIP	Retrying SUBSCRIBE in 30 seconds.


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