[Ekiga-devel-list] My IMA-ADPCM plugin codec doesn't work, but it establishes the call



Hi all!

Finally I can do calls with IMA-ADPCM, I changed the source code of OPAL-3.6.8. But now the problem is that I don't send any UDP/RTP packet.
I attach the: ekiga -d 4 2>output.txt , just in case anybody can help me. I am doing calls directly between two computers with a wired connection, without SIP server registration.

Thanks!
SIP/2.0 200 OK
CSeq: 1 INVITE
Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK4c3adf9f-9234-e111-9e0c-0012796294a7;rport
User-Agent: Ekiga/3.2.7
From: "usuario2" <sip:2@192.168.1.35>;tag=14bfdd9f-9234-e111-9e0c-0012796294a7
Call-ID: 56d2dd9f-9234-e111-9e0c-0012796294a7@tvsotano
To: <sip:naxo@192.168.1.35>;tag=0e6c6a99-9234-e111-8932-e0cb4e4ad409
Contact: <sip:naxo@192.168.1.35>
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Content-Type: application/sdp
Content-Length: 265

v=0
o=- 1325606804 1 IN IP4 192.168.1.35
s=Opal SIP Session
c=IN IP4 192.168.1.35
t=0 0
m=audio 5062 RTP/AVP 5 101 125
a=sendrecv
a=rtpmap:5 DVI4_8k/8000/1
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16,32,36
a=rtpmap:125 NSE/8000
a=fmtp:125 192-193

2012/01/03 17:07:05.446	  0:40.308	Opal Liste...0xb6c02b70	SIP	Queueing PDU "1 INVITE <200>", transaction=z9hG4bK4c3adf9f-9234-e111-9e0c-0012796294a7, token=14bfdd9f-9234-e111-9e0c-0012796294a7
2012/01/03 17:07:05.446	  0:40.308	Opal Liste...0xb6c02b70	Opal	Transport clean up on termination
2012/01/03 17:07:05.446	  0:40.308	Opal Liste...0xb6c02b70	Opal	Transport Close
2012/01/03 17:07:05.446	  0:40.308	        Pool:0xb6b80b70	SIP	Handling PDU "1 INVITE <200>" for transaction=z9hG4bK4c3adf9f-9234-e111-9e0c-0012796294a7
2012/01/03 17:07:05.446	  0:40.308	Opal Liste...0xb6c02b70	Opal	Deleted transport udp$192.168.1.35:5060<if=udp$192.168.1.12:5060>
2012/01/03 17:07:05.447	  0:40.309	        Pool:0xb6b80b70	SIP	Set Request URI to sip:naxo@192.168.1.35
2012/01/03 17:07:05.447	  0:40.309	        Pool:0xb6b80b70	OpalUDP	Setting interface to 192.168.1.12%eth0
2012/01/03 17:07:05.448	  0:40.310	        Pool:0xb6b80b70	SDP	Parsed connection address udp$192.168.1.35
2012/01/03 17:07:05.448	  0:40.310	        Pool:0xb6b80b70	SDP	Parsed connection address udp$192.168.1.35
2012/01/03 17:07:05.448	  0:40.310	        Pool:0xb6b80b70	SDP	Media session port=5062
2012/01/03 17:07:05.449	  0:40.310	        Pool:0xb6b80b70	SDP	Parsed media session with 3 'audio' formats
2012/01/03 17:07:05.449	  0:40.311	        Pool:0xb6b80b70	SIP	Product Info: name="Ekiga", version="3.2.7", vendor="", comments=""
2012/01/03 17:07:05.449	  0:40.311	        Pool:0xb6b80b70	SIP	Transaction remote address changed to udp$192.168.1.35:5060
2012/01/03 17:07:05.451	  0:40.313	        Pool:0xb6b80b70	SIP	Sending PDU (423 bytes) to: rem=udp$192.168.1.35:5060,local=udp$192.168.1.12:5060,if=192.168.1.12%eth0
ACK sip:naxo@192.168.1.35 SIP/2.0
CSeq: 1 ACK
Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK22811ba6-9234-e111-9e0c-0012796294a7;rport
From: "usuario2" <sip:2@192.168.1.35>;tag=14bfdd9f-9234-e111-9e0c-0012796294a7
Call-ID: 56d2dd9f-9234-e111-9e0c-0012796294a7@tvsotano
To: <sip:naxo@192.168.1.35>;tag=0e6c6a99-9234-e111-8932-e0cb4e4ad409
Contact: <sip:usuario@192.168.1.12>
Content-Length: 0
Max-Forwards: 70


2012/01/03 17:07:05.452	  0:40.314	        Pool:0xb6b80b70	OpalUDP	Setting interface to 192.168.1.12%eth0
2012/01/03 17:07:05.452	  0:40.314	        Pool:0xb6b80b70	SIP	INVITE transaction id=z9hG4bK4c3adf9f-9234-e111-9e0c-0012796294a7 completed.
2012/01/03 17:07:05.452	  0:40.314	        Pool:0xb6b80b70	SIP	Handling 200 OK response for INVITE
2012/01/03 17:07:05.452	  0:40.314	        Pool:0xb6b80b70	SIP	Received INVITE OK response
2012/01/03 17:07:05.453	  0:40.315	        Pool:0xb6b80b70	SIP	Processing received SDP media description for audio
2012/01/03 17:07:05.453	  0:40.315	        Pool:0xb6b80b70	SIP	RTP payload type DVI4_8k matched to codec MS-IMA-ADPCM
2012/01/03 17:07:05.453	  0:40.315	        Pool:0xb6b80b70	MediaFormat	Removing codecs PCM-16-48kHz,CELT-48K,PCM-16-32kHz,CELT-32K,PCM-16-16kHz,G.722-64k,SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,PCM-16,G.711-ALaw-64k,G.711-uLaw-64k,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,iLBC,iLBC-13k3,iLBC-15k2,YUV420P,H.261,H.261-CIF,H.261-QCIF,RFC4175_YCbCr-4:2:0,theora,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz
2012/01/03 17:07:05.454	  0:40.315	        Pool:0xb6b80b70	Call	IsMediaBypassPossible Call[d967136c31]-EP<sip>[14bfdd9f-9234-e111-9e0c-0012796294a7] session 1
2012/01/03 17:07:05.454	  0:40.316	        Pool:0xb6b80b70	OpalMan	IsMediaBypassPossible: session 1
2012/01/03 17:07:05.454	  0:40.316	        Pool:0xb6b80b70	OpalCon	IsMediaBypassPossible: default returns false
2012/01/03 17:07:05.454	  0:40.316	        Pool:0xb6b80b70	RTP	Found existing RTP session 1
2012/01/03 17:07:05.454	  0:40.316	        Pool:0xb6b80b70	RTP_UDP	Session 1, SetRemoteSocketInfo: data channel, new=192.168.1.35:5062, local=192.168.1.12:5062-5063, remote=0.0.0.0:0-0
2012/01/03 17:07:05.454	  0:40.316	        Pool:0xb6b80b70	RTP	Found existing media session 1
2012/01/03 17:07:05.454	  0:40.316	        Pool:0xb6b80b70	Call	OpenSourceMediaStreams opening audio session 1 on Call[d967136c31]-EP<sip>[14bfdd9f-9234-e111-9e0c-0012796294a7]
2012/01/03 17:07:05.464	  0:40.326	        Pool:0xb6b80b70	MediaFormat	Removing codecs PCM-16-48kHz,CELT-48K,PCM-16-32kHz,CELT-32K,PCM-16-16kHz,G.722-64k,SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,PCM-16,G.711-ALaw-64k,G.711-uLaw-64k,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,iLBC,iLBC-13k3,iLBC-15k2,YUV420P,H.261,H.261-CIF,H.261-QCIF,RFC4175_YCbCr-4:2:0,theora,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz
2012/01/03 17:07:05.466	  0:40.328	        Pool:0xb6b80b70	MediaFormat	Removing codecs PCM-16-48kHz,CELT-48K,PCM-16-32kHz,CELT-32K,PCM-16-16kHz,G.722-64k,SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,PCM-16,G.711-ALaw-64k,G.711-uLaw-64k,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,iLBC,iLBC-13k3,iLBC-15k2,YUV420P,H.261,H.261-CIF,H.261-QCIF,RFC4175_YCbCr-4:2:0,theora,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz
2012/01/03 17:07:05.466	  0:40.328	        Pool:0xb6b80b70	Call	GetMediaFormats for Call[d967136c31]-EP<sip>[14bfdd9f-9234-e111-9e0c-0012796294a7]
MS-IMA-ADPCM

2012/01/03 17:07:05.467	  0:40.329	        Pool:0xb6b80b70	MediaFormat	Merging MS-IMA-ADPCM into MS-IMA-ADPCM
2012/01/03 17:07:05.467	  0:40.329	        Pool:0xb6b80b70	MediaFormat	Merging PCM-16 into MS-IMA-ADPCM
2012/01/03 17:07:05.467	  0:40.329	        Pool:0xb6b80b70	MediaFormat	Merging MS-IMA-ADPCM into PCM-16
2012/01/03 17:07:05.467	  0:40.329	        Pool:0xb6b80b70	MediaFormat	Changed media option "Max Bit Rate" from 128000 to 32443
2012/01/03 17:07:05.467	  0:40.329	        Pool:0xb6b80b70	Call	Selected media formats MS-IMA-ADPCM -> PCM-16
2012/01/03 17:07:05.467	  0:40.329	        Pool:0xb6b80b70	Call	IsMediaBypassPossible Call[d967136c31]-EP<sip>[14bfdd9f-9234-e111-9e0c-0012796294a7] session 1
2012/01/03 17:07:05.468	  0:40.329	        Pool:0xb6b80b70	OpalMan	IsMediaBypassPossible: session 1
2012/01/03 17:07:05.468	  0:40.329	        Pool:0xb6b80b70	OpalCon	IsMediaBypassPossible: default returns false
2012/01/03 17:07:05.468	  0:40.330	        Pool:0xb6b80b70	RTP	Found existing media session 1
2012/01/03 17:07:05.468	  0:40.330	        Pool:0xb6b80b70	OpalMan	OnOpenMediaStream Call[d967136c31]-EP<sip>[14bfdd9f-9234-e111-9e0c-0012796294a7],OpalRTPMediaStream-Source-MS-IMA-ADPCM
2012/01/03 17:07:05.468	  0:40.330	        Pool:0xb6b80b70	OpalCon	Opened source stream d967136c31_1 with format MS-IMA-ADPCM
2012/01/03 17:07:05.468	  0:40.330	        Pool:0xb6b80b70	GMAudioOutputManager_ptlib	Setting Device[0] Default (PTLIB/ALSA)
2012/01/03 17:07:05.468	  0:40.330	        Pool:0xb6b80b70	AudioOutputCore	Opening device[0] with 1-8000/16
2012/01/03 17:07:05.469	  0:40.330	        Pool:0xb6b80b70	GMAudioOutputManager_ptlib	Opening Device Default (PTLIB/ALSA)
2012/01/03 17:07:05.469	  0:40.330	        Pool:0xb6b80b70	GMAudioOutputManager_ptlib	Opening Device with 1-8000/16
2012/01/03 17:07:05.540	  0:40.402	        Pool:0xb6b80b70	ALSA	SetHardwareParams Player channels=1 sample rate=8000
2012/01/03 17:07:05.541	  0:40.403	        Pool:0xb6b80b70	ALSA	Successfully set period size to 160
2012/01/03 17:07:05.541	  0:40.403	        Pool:0xb6b80b70	ALSA	Successfully set periods to 4
2012/01/03 17:07:05.541	  0:40.403	        Pool:0xb6b80b70	ALSA	parameters set ok
2012/01/03 17:07:05.541	  0:40.403	        Pool:0xb6b80b70	ALSA	Device default Opened
2012/01/03 17:07:05.551	  0:40.413	        Pool:0xb6b80b70	ALSA	Got volume 256
2012/01/03 17:07:05.551	  0:40.413	        Pool:0xb6b80b70	PCSS	Opened mono sound channel "EKIGA" for playing at 8.0 kHz.
2012/01/03 17:07:05.552	  0:40.414	        Pool:0xb6b80b70	OpalMan	OnOpenMediaStream Call[d967136c31]-EP<pc>[n747004f22],OpalAudioMediaStream-Sink-PCM-16
2012/01/03 17:07:05.552	  0:40.414	        Pool:0xb6b80b70	OpalCon	Opened sink stream d967136c31_1 with format PCM-16
2012/01/03 17:07:05.552	  0:40.414	        Pool:0xb6b80b70	Patch	Created Sink: format=PCM-16
2012/01/03 17:07:05.552	  0:40.414	        Pool:0xb6b80b70	OpalPlugin	Setting decoder options:
          Format Name       = MS-IMA-ADPCM
           Media Type       = audio
         Payload Type       = DVI4_8k
        Encoding Name       = DVI4_8k
             Channels (R/W) = 1          UnsignedInt
           Clock Rate (R/O) = 8000       UnsignedInt
           Frame Time (R/O) = 504        UnsignedInt
         Max Bit Rate (R/O) = 32443      UnsignedInt
       Max Frame Size (R/O) = 256        UnsignedInt
Max Frames Per Packet (R/O) = 1          UnsignedInt
         Needs Jitter (R/O) = 1          Boolean
             Protocol (R/O) =            String
 Rx Frames Per Packet (R/W) = 1          UnsignedInt
 Tx Frames Per Packet (R/W) = 1          UnsignedInt


2012/01/03 17:07:05.553	  0:40.415	        Pool:0xb6b80b70	Patch	Created primary codec MS-IMA-ADPCM->PCM-16 with ID d967136c31_1
2012/01/03 17:07:05.553	  0:40.415	        Pool:0xb6b80b70	Media	Audio sink data size set to 1008, buffer size set to 160 and 7 buffers.
2012/01/03 17:07:05.553	  0:40.415	        Pool:0xb6b80b70	Media	Set data size from 16 to 1008
2012/01/03 17:07:05.553	  0:40.415	        Pool:0xb6b80b70	GMAudioOutputManager_ptlib	Setting buffer size of device[0] 160/7
2012/01/03 17:07:05.553	  0:40.415	        Pool:0xb6b80b70	ALSA	SetBuffers direction=Player size=160 count=7
2012/01/03 17:07:05.553	  0:40.415	        Pool:0xb6b80b70	ALSA	SetHardwareParams Player channels=1 sample rate=8000
2012/01/03 17:07:05.554	  0:40.416	        Pool:0xb6b80b70	ALSA	Successfully set period size to 80
2012/01/03 17:07:05.554	  0:40.416	        Pool:0xb6b80b70	ALSA	Successfully set periods to 7
2012/01/03 17:07:05.554	  0:40.416	        Pool:0xb6b80b70	ALSA	parameters set ok
2012/01/03 17:07:05.554	  0:40.416	        Pool:0xb6b80b70	Patch	Added media stream sink OpalAudioMediaStream-Sink-PCM-16 using transcoder MS-IMA-ADPCM->PCM-16, data size=1008
2012/01/03 17:07:05.555	  0:40.416	        Pool:0xb6b80b70	Media	RTP data size cannot be changed to 256, fixed at 2048
2012/01/03 17:07:05.555	  0:40.417	        Pool:0xb6b80b70	PCSS	Adding filters to patch
2012/01/03 17:07:05.555	  0:40.417	        Pool:0xb6b80b70	OpalCon	Source stream of connection Call[d967136c31]-EP<sip>[14bfdd9f-9234-e111-9e0c-0012796294a7] uses patch Patch OpalRTPMediaStream-Source-MS-IMA-ADPCM -> OpalAudioMediaStream-Sink-PCM-16
2012/01/03 17:07:05.555	  0:40.417	        Pool:0xb6b80b70	RTPCon	Adding RFC2833 receive handler
2012/01/03 17:07:05.555	  0:40.417	        Pool:0xb6b80b70	RTPCon	Adding Cisco NSE receive handler
2012/01/03 17:07:05.555	  0:40.417	        Pool:0xb6b80b70	Call	GetOtherPartyConnection Call[d967136c31]-EP<sip>[14bfdd9f-9234-e111-9e0c-0012796294a7]
2012/01/03 17:07:05.555	  0:40.417	        Pool:0xb6b80b70	Call	OpenSourceMediaStreams opening audio session 1 on Call[d967136c31]-EP<pc>[n747004f22]
2012/01/03 17:07:05.558	  0:40.420	        Pool:0xb6b80b70	MediaFormat	Removing codecs PCM-16-48kHz,CELT-48K,PCM-16-32kHz,CELT-32K,PCM-16-16kHz,G.722-64k,SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,PCM-16,G.711-ALaw-64k,G.711-uLaw-64k,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,iLBC,iLBC-13k3,iLBC-15k2,YUV420P,H.261,H.261-CIF,H.261-QCIF,RFC4175_YCbCr-4:2:0,theora,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz
2012/01/03 17:07:05.559	  0:40.421	        Pool:0xb6b80b70	MediaFormat	Removing codecs PCM-16-48kHz,CELT-48K,PCM-16-32kHz,CELT-32K,PCM-16-16kHz,G.722-64k,SpeexIETFWide-20.6k,SpeexWB,SpeexWide-20.6k,PCM-16,G.711-ALaw-64k,G.711-uLaw-64k,G.726-16k,G.726-24k,G.726-32k,G.726-40k,GSM-06.10,GSM-AMR,LPC-10,MS-GSM,SpeexIETFNarrow-11k,SpeexIETFNarrow-15k,SpeexIETFNarrow-18.2k,SpeexIETFNarrow-24.6k,SpeexIETFNarrow-5.95k,SpeexIETFNarrow-8k,SpeexNB,SpeexWNarrow-8k,iLBC,iLBC-13k3,iLBC-15k2,YUV420P,H.261,H.261-CIF,H.261-QCIF,RFC4175_YCbCr-4:2:0,theora,RGB32,RGB24,RFC4175_RGB,SIP-IM,T.140,H.224/H323AnnexQ,H.224/HDLCTunneling,Linear-16-Stereo-48kHz
2012/01/03 17:07:05.560	  0:40.421	        Pool:0xb6b80b70	Call	GetMediaFormats for Call[d967136c31]-EP<pc>[n747004f22]
MS-IMA-ADPCM
T.38
UserInput/RFC2833
NamedSignalEvent

2012/01/03 17:07:05.560	  0:40.422	        Pool:0xb6b80b70	MediaFormat	Merging MS-IMA-ADPCM into MS-IMA-ADPCM
2012/01/03 17:07:05.560	  0:40.422	        Pool:0xb6b80b70	MediaFormat	Merging MS-IMA-ADPCM into PCM-16
2012/01/03 17:07:05.560	  0:40.422	        Pool:0xb6b80b70	MediaFormat	Changed media option "Max Bit Rate" from 128000 to 32443
2012/01/03 17:07:05.560	  0:40.422	        Pool:0xb6b80b70	MediaFormat	Merging PCM-16 into MS-IMA-ADPCM
2012/01/03 17:07:05.561	  0:40.423	        Pool:0xb6b80b70	Call	Selected media formats PCM-16 -> MS-IMA-ADPCM
2012/01/03 17:07:05.561	  0:40.423	        Pool:0xb6b80b70	AudioInputCore	Starting stream 1x8000/16
2012/01/03 17:07:05.561	  0:40.423	        Pool:0xb6b80b70	GMAudioInputManager_ptlib	Setting Device Default (PTLIB/ALSA)
2012/01/03 17:07:05.561	  0:40.423	        Pool:0xb6b80b70	AudioInputCore	Opening device with 1-8000/16
2012/01/03 17:07:05.561	  0:40.423	        Pool:0xb6b80b70	GMAudioInputManager_ptlib	Opening Device Default (PTLIB/ALSA)
2012/01/03 17:07:05.561	  0:40.423	        Pool:0xb6b80b70	GMAudioInputManager_ptlib	Opening Device with 1-8000/16
2012/01/03 17:07:05.562	  0:40.424	        Pool:0xb6b80b70	ALSA	SetHardwareParams Recorder channels=1 sample rate=8000
2012/01/03 17:07:05.562	  0:40.424	        Pool:0xb6b80b70	ALSA	Successfully set period size to 192
2012/01/03 17:07:05.563	  0:40.424	        Pool:0xb6b80b70	ALSA	Successfully set periods to 3
2012/01/03 17:07:05.563	  0:40.425	        Pool:0xb6b80b70	ALSA	parameters set ok
2012/01/03 17:07:05.563	  0:40.425	        Pool:0xb6b80b70	ALSA	Device default Opened
2012/01/03 17:07:05.564	  0:40.426	        Pool:0xb6b80b70	ALSA	Got volume 256
2012/01/03 17:07:05.564	  0:40.426	        Pool:0xb6b80b70	PCSS	Opened mono sound channel "EKIGA" for recording at 8.0 kHz.
2012/01/03 17:07:05.564	  0:40.426	        Pool:0xb6b80b70	OpalMan	OnOpenMediaStream Call[d967136c31]-EP<pc>[n747004f22],OpalAudioMediaStream-Source-PCM-16
2012/01/03 17:07:05.564	  0:40.426	        Pool:0xb6b80b70	OpalCon	Opened source stream d967136c31_1 with format PCM-16
2012/01/03 17:07:05.564	  0:40.426	        Pool:0xb6b80b70	Call	IsMediaBypassPossible Call[d967136c31]-EP<sip>[14bfdd9f-9234-e111-9e0c-0012796294a7] session 1
2012/01/03 17:07:05.565	  0:40.426	        Pool:0xb6b80b70	OpalMan	IsMediaBypassPossible: session 1
2012/01/03 17:07:05.565	  0:40.426	        Pool:0xb6b80b70	OpalCon	IsMediaBypassPossible: default returns false
2012/01/03 17:07:05.565	  0:40.427	        Pool:0xb6b80b70	RTP	Found existing media session 1
2012/01/03 17:07:05.565	  0:40.427	        Pool:0xb6b80b70	OpalMan	OnOpenMediaStream Call[d967136c31]-EP<sip>[14bfdd9f-9234-e111-9e0c-0012796294a7],OpalRTPMediaStream-Sink-MS-IMA-ADPCM
2012/01/03 17:07:05.565	  0:40.427	        Pool:0xb6b80b70	OpalCon	Opened sink stream d967136c31_1 with format MS-IMA-ADPCM
2012/01/03 17:07:05.565	  0:40.427	        Pool:0xb6b80b70	Patch	Created Sink: format=MS-IMA-ADPCM
2012/01/03 17:07:05.565	  0:40.427	        Pool:0xb6b80b70	OpalPlugin	Setting encoder options:
          Format Name       = MS-IMA-ADPCM
           Media Type       = audio
         Payload Type       = DVI4_8k
        Encoding Name       = DVI4_8k
             Channels (R/W) = 1          UnsignedInt
           Clock Rate (R/O) = 8000       UnsignedInt
           Frame Time (R/O) = 504        UnsignedInt
         Max Bit Rate (R/O) = 32443      UnsignedInt
       Max Frame Size (R/O) = 256        UnsignedInt
Max Frames Per Packet (R/O) = 1          UnsignedInt
         Needs Jitter (R/O) = 1          Boolean
             Protocol (R/O) = SIP        String
 Rx Frames Per Packet (R/W) = 1          UnsignedInt
 Tx Frames Per Packet (R/W) = 1          UnsignedInt


2012/01/03 17:07:05.566	  0:40.428	        Pool:0xb6b80b70	Patch	Created primary codec PCM-16->MS-IMA-ADPCM with ID d967136c31_1
2012/01/03 17:07:05.566	  0:40.428	        Pool:0xb6b80b70	Media	RTP data size cannot be changed to 256, fixed at 528
2012/01/03 17:07:05.566	  0:40.428	        Pool:0xb6b80b70	Patch	Added media stream sink OpalRTPMediaStream-Sink-MS-IMA-ADPCM using transcoder PCM-16->MS-IMA-ADPCM, data size=528
2012/01/03 17:07:05.566	  0:40.428	        Pool:0xb6b80b70	Media	Audio source data size set to 1008, buffer size set to 160 and 7 buffers.
2012/01/03 17:07:05.566	  0:40.428	        Pool:0xb6b80b70	Media	Set data size from 16 to 1008
2012/01/03 17:07:05.566	  0:40.428	        Pool:0xb6b80b70	AudioInputCore	Setting stream buffer size 7/160
2012/01/03 17:07:05.566	  0:40.428	        Pool:0xb6b80b70	GMAudioInputManager_ptlib	Setting buffer size to 160/7
2012/01/03 17:07:05.566	  0:40.428	        Pool:0xb6b80b70	ALSA	SetBuffers direction=Recorder size=160 count=7
2012/01/03 17:07:05.566	  0:40.428	        Pool:0xb6b80b70	ALSA	SetHardwareParams Recorder channels=1 sample rate=8000
2012/01/03 17:07:05.567	  0:40.429	        Pool:0xb6b80b70	ALSA	Successfully set period size to 192
2012/01/03 17:07:05.567	  0:40.429	        Pool:0xb6b80b70	ALSA	Successfully set periods to 3
2012/01/03 17:07:05.568	  0:40.430	        Pool:0xb6b80b70	ALSA	parameters set ok
2012/01/03 17:07:05.568	  0:40.430	        Pool:0xb6b80b70	PCSS	Adding filters to patch
2012/01/03 17:07:05.568	  0:40.430	        Pool:0xb6b80b70	OpalCon	Sink stream of connection Call[d967136c31]-EP<sip>[14bfdd9f-9234-e111-9e0c-0012796294a7] uses patch Patch OpalAudioMediaStream-Source-PCM-16 -> OpalRTPMediaStream-Sink-MS-IMA-ADPCM
2012/01/03 17:07:05.568	  0:40.430	        Pool:0xb6b80b70	OpalCon	SetPhase from AlertingPhase to ConnectedPhase for Call[d967136c31]-EP<sip>[14bfdd9f-9234-e111-9e0c-0012796294a7]
2012/01/03 17:07:05.568	  0:40.430	        Pool:0xb6b80b70	OpalCon	OnConnected for Call[d967136c31]-EP<sip>[14bfdd9f-9234-e111-9e0c-0012796294a7]
2012/01/03 17:07:05.568	  0:40.430	        Pool:0xb6b80b70	OpalMan	OnConnected Call[d967136c31]-EP<sip>[14bfdd9f-9234-e111-9e0c-0012796294a7]
2012/01/03 17:07:05.568	  0:40.430	        Pool:0xb6b80b70	Call	OnConnected Call[d967136c31]-EP<sip>[14bfdd9f-9234-e111-9e0c-0012796294a7]
2012/01/03 17:07:05.568	  0:40.430	        Pool:0xb6b80b70	OpalCon	SetConnected for Call[d967136c31]-EP<pc>[n747004f22]
2012/01/03 17:07:05.568	  0:40.430	        Pool:0xb6b80b70	OpalCon	SetPhase from AlertingPhase to ConnectedPhase for Call[d967136c31]-EP<pc>[n747004f22]
2012/01/03 17:07:05.568	  0:40.430	        Pool:0xb6b80b70	OpalCon	SetPhase from ConnectedPhase to EstablishedPhase for Call[d967136c31]-EP<pc>[n747004f22]
2012/01/03 17:07:05.568	  0:40.430	        Pool:0xb6b80b70	OpalCon	OnEstablished Call[d967136c31]-EP<pc>[n747004f22]
2012/01/03 17:07:05.569	  0:40.430	        Pool:0xb6b80b70	PWLib	File handle high water mark set: 54 Thread unblock pipe
2012/01/03 17:07:05.569	  0:40.431	        Pool:0xb6b80b70	PTLib	No permission to set priority level 3
2012/01/03 17:07:05.569	  0:40.431	        Pool:0xb6b80b70	PTLib	Thread high water mark set: 11
2012/01/03 17:07:05.569	  0:40.431	        Pool:0xb6b80b70	Media	Starting thread Media Patch:0xb222fb70
2012/01/03 17:07:05.569	  0:40.431	 Media Patch:0xb222fb70	Patch	Thread started for Patch OpalRTPMediaStream-Source-MS-IMA-ADPCM -> OpalAudioMediaStream-Sink-PCM-16
2012/01/03 17:07:05.569	  0:40.431	        Pool:0xb6b80b70	PWLib	File handle high water mark set: 56 Thread unblock pipe
2012/01/03 17:07:05.569	  0:40.431	        Pool:0xb6b80b70	PTLib	No permission to set priority level 3
2012/01/03 17:07:05.569	  0:40.431	        Pool:0xb6b80b70	PTLib	Thread high water mark set: 12
2012/01/03 17:07:05.569	  0:40.431	 Media Patch:0xb222fb70	InfLID	Setting jitter buffer time from 160 to 4000
2012/01/03 17:07:05.570	  0:40.431	        Pool:0xb6b80b70	Media	Starting thread Media Patch
2012/01/03 17:07:05.570	  0:40.432	        Pool:0xb6b80b70	OpalCon	Media stream threads started.
2012/01/03 17:07:05.570	  0:40.432	        Pool:0xb6b80b70	OpalMan	OnEstablished Call[d967136c31]-EP<pc>[n747004f22]
2012/01/03 17:07:05.570	  0:40.432	 Media Patch:0xb222fb70	RTP	Opal jitter buffer created: size=101 delay=20-500 ms  /160 (20ms) obj=0x8e95d70
2012/01/03 17:07:05.570	  0:40.432	 Media Patch:0xb21eeb70	Patch	Thread started for Patch OpalAudioMediaStream-Source-PCM-16 -> OpalRTPMediaStream-Sink-MS-IMA-ADPCM
2012/01/03 17:07:05.570	  0:40.432	 Media Patch:0xb222fb70	PWLib	File handle high water mark set: 58 Thread unblock pipe
2012/01/03 17:07:05.570	  0:40.432	        Pool:0xb6b80b70	Call	OnEstablished Call[d967136c31]-EP<pc>[n747004f22]
2012/01/03 17:07:05.570	  0:40.432	 Media Patch:0xb222fb70	PTLib	Thread high water mark set: 13
2012/01/03 17:07:05.570	  0:40.432	        Pool:0xb6b80b70	OpalCon	Media stream threads started.
2012/01/03 17:07:05.571	  0:40.433	  RTP Jitter:0xb21adb70	RTP	Jitter RTP receive thread started: 0x8e95d70
2012/01/03 17:07:05.571	  0:40.433	        Pool:0xb6b80b70	OpalCon	SetPhase from ConnectedPhase to EstablishedPhase for Call[d967136c31]-EP<sip>[14bfdd9f-9234-e111-9e0c-0012796294a7]
2012/01/03 17:07:05.571	  0:40.433	        Pool:0xb6b80b70	OpalCon	OnEstablished Call[d967136c31]-EP<sip>[14bfdd9f-9234-e111-9e0c-0012796294a7]
2012/01/03 17:07:05.571	  0:40.433	        Pool:0xb6b80b70	OpalCon	Media stream threads started.
2012/01/03 17:07:05.571	  0:40.433	        Pool:0xb6b80b70	OpalMan	OnEstablished Call[d967136c31]-EP<sip>[14bfdd9f-9234-e111-9e0c-0012796294a7]
2012/01/03 17:07:05.571	  0:40.433	        Pool:0xb6b80b70	RTP	Found existing RTP session 1
2012/01/03 17:07:05.572	  0:40.433	        Pool:0xb6b80b70	Call	OnEstablished Call[d967136c31]-EP<sip>[14bfdd9f-9234-e111-9e0c-0012796294a7]
2012/01/03 17:07:05.572	  0:40.434	        Pool:0xb6b80b70	OpalCon	Media stream threads started.
2012/01/03 17:07:05.572	  0:40.434	        Pool:0xb6b80b70	SIP	Handled PDU "1 INVITE <200>"
2012/01/03 17:07:05.599	  0:40.461	                       	AEScheduler	Removing Event incoming_call_sound from queue
2012/01/03 17:07:05.599	  0:40.461	                       	AEScheduler	Removing Event ring_tone_sound from queue
2012/01/03 17:07:05.648	  0:40.510	 Media Patch:0xb21eeb70	Patch	Media conversion (primary) failed
2012/01/03 17:07:05.648	  0:40.510	 Media Patch:0xb21eeb70	Patch	WriteFrame failed
2012/01/03 17:07:05.648	  0:40.510	 Media Patch:0xb21eeb70	Patch	Thread ended because all sink writes failed failed
2012/01/03 17:07:05.648	  0:40.510	 Media Patch:0xb21eeb70	Patch	Thread ended for Patch OpalAudioMediaStream-Source-PCM-16 -> OpalRTPMediaStream-Sink-MS-IMA-ADPCM
2012/01/03 17:07:06.572	  0:41.434	 Housekeeper:0xb6c43b70	SIP	Set state Terminated_Success for INVITE transaction id=z9hG4bK4c3adf9f-9234-e111-9e0c-0012796294a7
2012/01/03 17:07:07.066	  0:41.928	AudioEvent...0xb6d47b70	AEScheduler	Checking pending list with 0 elements
2012/01/03 17:07:11.486	  0:46.348	Network In...0xb6c84b70	PWLib	File handle high water mark set: 59 PUDPSocket
2012/01/03 17:07:25.026	  0:59.888	                       	Call	Clearing Call[d967136c31] reason=EndedByLocalUser
2012/01/03 17:07:25.026	  0:59.888	                       	OpalCon	SetPhase from EstablishedPhase to ReleasingPhase for Call[d967136c31]-EP<pc>[n747004f22]
2012/01/03 17:07:25.026	  0:59.888	                       	OpalCon	Releasing Call[d967136c31]-EP<pc>[n747004f22]
2012/01/03 17:07:25.026	  0:59.888	                       	OpalCon	Call end reason for Call[d967136c31]-EP<pc>[n747004f22] set to EndedByLocalUser
2012/01/03 17:07:25.026	  0:59.888	                       	OpalCon	OnReleased Call[d967136c31]-EP<pc>[n747004f22]
2012/01/03 17:07:25.026	  0:59.888	                       	OpalEP	OnReleased Call[d967136c31]-EP<pc>[n747004f22]
2012/01/03 17:07:25.027	  0:59.888	                       	OpalMan	OnReleased Call[d967136c31]-EP<pc>[n747004f22]
2012/01/03 17:07:25.027	  0:59.889	                       	Call	OnReleased Call[d967136c31]-EP<pc>[n747004f22]
2012/01/03 17:07:25.027	  0:59.889	                       	OpalCon	SetPhase from EstablishedPhase to ReleasingPhase for Call[d967136c31]-EP<sip>[14bfdd9f-9234-e111-9e0c-0012796294a7]
2012/01/03 17:07:25.027	  0:59.889	                       	OpalCon	Releasing Call[d967136c31]-EP<sip>[14bfdd9f-9234-e111-9e0c-0012796294a7]
2012/01/03 17:07:25.027	  0:59.889	                       	OpalCon	Call end reason for Call[d967136c31]-EP<sip>[14bfdd9f-9234-e111-9e0c-0012796294a7] set to EndedByLocalUser
2012/01/03 17:07:25.027	  0:59.889	                       	PWLib	File handle high water mark set: 60 Thread unblock pipe
2012/01/03 17:07:25.027	  0:59.889	                       	PTLib	Thread high water mark set: 14
2012/01/03 17:07:25.027	  0:59.889	   OnRelease:0xb216cb70	SIP	OnReleased: Call[d967136c31]-EP<sip>[14bfdd9f-9234-e111-9e0c-0012796294a7], phase = ReleasingPhase
2012/01/03 17:07:25.027	  0:59.889	   OnRelease:0xb216cb70	OpalCon	SetPhase from ReleasingPhase to ReleasingPhase for Call[d967136c31]-EP<sip>[14bfdd9f-9234-e111-9e0c-0012796294a7]
2012/01/03 17:07:25.030	  0:59.892	   OnRelease:0xb216cb70	SIP	BYE transaction id=z9hG4bKe6f1c6b1-9234-e111-9e0c-0012796294a7 created.
2012/01/03 17:07:25.030	  0:59.892	   OnRelease:0xb216cb70	Media	Closing RTP for OpalRTPMediaStream-Source-MS-IMA-ADPCM
2012/01/03 17:07:25.030	  0:59.892	   OnRelease:0xb216cb70	RTP_UDP	Session 1, Shutting down read.
2012/01/03 17:07:25.030	  0:59.892	  RTP Jitter:0xb21adb70	RTP_UDP	Session 1, Read shutdown.
2012/01/03 17:07:25.030	  0:59.892	   OnRelease:0xb216cb70	Media	Closing stream OpalRTPMediaStream-Source-MS-IMA-ADPCM
2012/01/03 17:07:25.030	  0:59.892	  RTP Jitter:0xb21adb70	RTP	Jitter RTP receive thread ended
2012/01/03 17:07:25.031	  0:59.892	   OnRelease:0xb216cb70	Media	Disconnecting OpalRTPMediaStream-Source-MS-IMA-ADPCM from patch thread Patch OpalRTPMediaStream-Source-MS-IMA-ADPCM -> OpalAudioMediaStream-Sink-PCM-16
2012/01/03 17:07:25.031	  0:59.893	  RTP Jitter:0xb21adb70	RTP	Jitter RTP receive thread finished: 0x8e95d70
2012/01/03 17:07:25.031	  0:59.893	   OnRelease:0xb216cb70	Patch	Closing media patch Patch OpalRTPMediaStream-Source-MS-IMA-ADPCM -> OpalAudioMediaStream-Sink-PCM-16
2012/01/03 17:07:25.037	  0:59.899	                       	AudioOutputCore	Closing current device
2012/01/03 17:07:25.037	  0:59.899	 Media Patch:0xb222fb70	Patch	Thread ended for Patch OpalRTPMediaStream-Source-MS-IMA-ADPCM -> OpalAudioMediaStream-Sink-PCM-16
2012/01/03 17:07:25.037	  0:59.899	                       	GMAudioOutputManager_ptlib	Closing device[0] Default (PTLIB/ALSA)
2012/01/03 17:07:25.037	  0:59.899	                       	ALSA	Closing default
2012/01/03 17:07:25.037	  0:59.899	                       	GMAudioOutputManager_ptlib	Setting Device[0] Default (PTLIB/ALSA)
2012/01/03 17:07:25.037	  0:59.899	   OnRelease:0xb216cb70	AudioOutputCore	Closing current device
2012/01/03 17:07:25.037	  0:59.899	                       	Media	Closing stream OpalAudioMediaStream-Sink-PCM-16
2012/01/03 17:07:25.037	  0:59.899	   OnRelease:0xb216cb70	GMAudioOutputManager_ptlib	Closing device[0] Default (PTLIB/ALSA)
2012/01/03 17:07:25.038	  0:59.899	                       	Media	Disconnecting OpalAudioMediaStream-Sink-PCM-16 from patch thread Patch OpalRTPMediaStream-Source-MS-IMA-ADPCM -> OpalAudioMediaStream-Sink-PCM-16
2012/01/03 17:07:25.038	  0:59.900	   OnRelease:0xb216cb70	GMAudioOutputManager_ptlib	Setting Device[0] Default (PTLIB/ALSA)
2012/01/03 17:07:25.038	  0:59.900	                       	Patch	Removing media stream sink OpalAudioMediaStream-Sink-PCM-16
2012/01/03 17:07:25.038	  0:59.900	                       	OpalCon	Removed media stream OpalAudioMediaStream-Sink-PCM-16
2012/01/03 17:07:25.038	  0:59.900	                       	Call	GetOtherPartyConnection Call[d967136c31]-EP<pc>[n747004f22]
2012/01/03 17:07:25.038	  0:59.900	                       	AudioInputCore	Stopping Stream
2012/01/03 17:07:25.038	  0:59.900	                       	AudioInputCore	Closing current device
2012/01/03 17:07:25.038	  0:59.900	                       	GMAudioInputManager_ptlib	Closing device Default (PTLIB/ALSA)
2012/01/03 17:07:25.038	  0:59.900	                       	ALSA	Closing default
2012/01/03 17:07:25.039	  0:59.901	                       	GMAudioInputManager_ptlib	Setting Device Default (PTLIB/ALSA)
2012/01/03 17:07:25.039	  0:59.901	                       	Media	Closing stream OpalAudioMediaStream-Source-PCM-16
2012/01/03 17:07:25.039	  0:59.901	                       	Media	Disconnecting OpalAudioMediaStream-Source-PCM-16 from patch thread Patch OpalAudioMediaStream-Source-PCM-16 -> OpalRTPMediaStream-Sink-MS-IMA-ADPCM
2012/01/03 17:07:25.039	  0:59.901	                       	Patch	Closing media patch Patch OpalAudioMediaStream-Source-PCM-16 -> OpalRTPMediaStream-Sink-MS-IMA-ADPCM
2012/01/03 17:07:25.039	  0:59.901	                       	Media	Closing RTP for OpalRTPMediaStream-Sink-MS-IMA-ADPCM
2012/01/03 17:07:25.039	  0:59.901	                       	RTP_UDP	Session 1, Shutting down write.
2012/01/03 17:07:25.039	  0:59.901	                       	Media	Closing stream OpalRTPMediaStream-Sink-MS-IMA-ADPCM
2012/01/03 17:07:25.039	  0:59.901	                       	Media	Disconnecting OpalRTPMediaStream-Sink-MS-IMA-ADPCM from patch thread Patch OpalAudioMediaStream-Source-PCM-16 -> OpalRTPMediaStream-Sink-MS-IMA-ADPCM
2012/01/03 17:07:25.039	  0:59.901	                       	Patch	Removing media stream sink OpalRTPMediaStream-Sink-MS-IMA-ADPCM
2012/01/03 17:07:25.040	  0:59.901	                       	OpalCon	Removed media stream OpalRTPMediaStream-Sink-MS-IMA-ADPCM
2012/01/03 17:07:25.040	  0:59.902	                       	Patch	Waiting for media patch thread to stop Patch OpalAudioMediaStream-Source-PCM-16
2012/01/03 17:07:25.040	  0:59.902	                       	OpalCon	Removed media stream OpalAudioMediaStream-Source-PCM-16
2012/01/03 17:07:25.040	  0:59.902	                       	OpalCon	Media streams closed.
2012/01/03 17:07:25.048	  0:59.910	   OnRelease:0xb216cb70	Patch	Waiting for media patch thread to stop Patch OpalRTPMediaStream-Source-MS-IMA-ADPCM
2012/01/03 17:07:25.049	  0:59.911	   OnRelease:0xb216cb70	OpalCon	Removed media stream OpalRTPMediaStream-Source-MS-IMA-ADPCM
2012/01/03 17:07:25.049	  0:59.911	   OnRelease:0xb216cb70	OpalCon	Media streams closed.
2012/01/03 17:07:25.049	  0:59.911	   OnRelease:0xb216cb70	SIP	Awaiting BYE transaction completion, id=z9hG4bKe6f1c6b1-9234-e111-9e0c-0012796294a7
2012/01/03 17:07:25.049	  0:59.911	   OnRelease:0xb216cb70	SIP	Transaction remote address is udp$192.168.1.35:5060
2012/01/03 17:07:25.049	  0:59.911	   OnRelease:0xb216cb70	SIP	Sending PDU (423 bytes) to: rem=udp$192.168.1.35:5060,local=udp$192.168.1.12:5060,if=192.168.1.12%eth0
BYE sip:naxo@192.168.1.35 SIP/2.0
CSeq: 2 BYE
Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bKe6f1c6b1-9234-e111-9e0c-0012796294a7;rport
From: "usuario2" <sip:2@192.168.1.35>;tag=14bfdd9f-9234-e111-9e0c-0012796294a7
Call-ID: 56d2dd9f-9234-e111-9e0c-0012796294a7@tvsotano
To: <sip:naxo@192.168.1.35>;tag=0e6c6a99-9234-e111-8932-e0cb4e4ad409
Contact: <sip:usuario@192.168.1.12>
Content-Length: 0
Max-Forwards: 70


2012/01/03 17:07:25.050	  0:59.912	   OnRelease:0xb216cb70	OpalUDP	Setting interface to 192.168.1.12%eth0
2012/01/03 17:07:25.050	  0:59.912	   OnRelease:0xb216cb70	SIP	Transaction timers set: retry=0.500, completion=6.000
2012/01/03 17:07:25.052	  0:59.914	Opal Liste...0xb6c02b70	OpalUDP	Binding to interface: 192.168.1.12:5060
2012/01/03 17:07:25.052	  0:59.914	Opal Liste...0xb6c02b70	SIP	Waiting for PDU on udp$192.168.1.35:5060<if=udp$192.168.1.12:5060>
2012/01/03 17:07:25.053	  0:59.915	Opal Liste...0xb6c02b70	SIP	PDU received: rem=udp$192.168.1.35:5060,local=udp$192.168.1.12:5060,if=192.168.1.12%eth0
SIP/2.0 200 OK
CSeq: 2 BYE
Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bKe6f1c6b1-9234-e111-9e0c-0012796294a7;rport
From: "usuario2" <sip:2@192.168.1.35>;tag=14bfdd9f-9234-e111-9e0c-0012796294a7
Call-ID: 56d2dd9f-9234-e111-9e0c-0012796294a7@tvsotano
To: <sip:naxo@192.168.1.35>;tag=0e6c6a99-9234-e111-8932-e0cb4e4ad409
Content-Length: 0


2012/01/03 17:07:25.053	  0:59.915	Opal Liste...0xb6c02b70	SIP	Queueing PDU "2 BYE <200>", transaction=z9hG4bKe6f1c6b1-9234-e111-9e0c-0012796294a7, token=14bfdd9f-9234-e111-9e0c-0012796294a7
2012/01/03 17:07:25.053	  0:59.915	Opal Liste...0xb6c02b70	Opal	Transport clean up on termination
2012/01/03 17:07:25.053	  0:59.915	Opal Liste...0xb6c02b70	Opal	Transport Close
2012/01/03 17:07:25.053	  0:59.915	        Pool:0xb6b80b70	SIP	Handling PDU "2 BYE <200>" for transaction=z9hG4bKe6f1c6b1-9234-e111-9e0c-0012796294a7
2012/01/03 17:07:25.053	  0:59.915	        Pool:0xb6b80b70	SIP	BYE transaction id=z9hG4bKe6f1c6b1-9234-e111-9e0c-0012796294a7 completed.
2012/01/03 17:07:25.053	  0:59.915	Opal Liste...0xb6c02b70	Opal	Deleted transport udp$192.168.1.35:5060<if=udp$192.168.1.12:5060>
2012/01/03 17:07:25.054	  0:59.916	        Pool:0xb6b80b70	SIP	Handled PDU "2 BYE <200>"
2012/01/03 17:07:25.054	  0:59.916	   OnRelease:0xb216cb70	OpalCon	SetPhase from ReleasingPhase to ReleasedPhase for Call[d967136c31]-EP<sip>[14bfdd9f-9234-e111-9e0c-0012796294a7]
2012/01/03 17:07:25.054	  0:59.916	   OnRelease:0xb216cb70	OpalCon	OnReleased Call[d967136c31]-EP<sip>[14bfdd9f-9234-e111-9e0c-0012796294a7]
2012/01/03 17:07:25.054	  0:59.916	   OnRelease:0xb216cb70	OpalEP	OnReleased Call[d967136c31]-EP<sip>[14bfdd9f-9234-e111-9e0c-0012796294a7]
2012/01/03 17:07:25.054	  0:59.916	   OnRelease:0xb216cb70	OpalMan	OnReleased Call[d967136c31]-EP<sip>[14bfdd9f-9234-e111-9e0c-0012796294a7]
2012/01/03 17:07:25.054	  0:59.916	   OnRelease:0xb216cb70	Call	OnReleased Call[d967136c31]-EP<sip>[14bfdd9f-9234-e111-9e0c-0012796294a7]
2012/01/03 17:07:25.055	  0:59.916	   OnRelease:0xb216cb70	OpalCon	Media streams closed.
2012/01/03 17:07:25.055	  0:59.917	   OnRelease:0xb216cb70	OpalCon	OnRelease thread completed for Call[d967136c31]-EP<sip>[14bfdd9f-9234-e111-9e0c-0012796294a7]
2012/01/03 17:07:25.207	  1:00.069	                       	AEScheduler	Removing Event incoming_call_sound from queue
2012/01/03 17:07:25.207	  1:00.069	                       	AEScheduler	Removing Event ring_tone_sound from queue
2012/01/03 17:07:25.207	  1:00.069	                       	OpalMan	OnClearedCall Call[d967136c31] from "pc:usuario" to "sip:naxo@192.168.1.35"
2012/01/03 17:07:25.207	  1:00.069	                       	OPAL	Attempting to close recording on call d967136c31 when not recording.
2012/01/03 17:07:25.313	  1:00.175	Opal Garbage:0xb6cc5b70	PCSS	Deleted PC sound system connection.
2012/01/03 17:07:25.313	  1:00.175	Opal Garbage:0xb6cc5b70	OpalCon	Connection Call[d967136c31]-EP<pc>[n747004f22] destroyed.
2012/01/03 17:07:25.314	  1:00.176	Opal Garbage:0xb6cc5b70	SIP	Transaction id=z9hG4bK4c3adf9f-9234-e111-9e0c-0012796294a7 destroyed.
2012/01/03 17:07:26.054	  1:00.916	 Housekeeper:0xb6c43b70	SIP	Set state Terminated_Success for BYE transaction id=z9hG4bKe6f1c6b1-9234-e111-9e0c-0012796294a7
2012/01/03 17:07:26.314	  1:01.176	Opal Garbage:0xb6cc5b70	SIP	Transaction id=z9hG4bKe6f1c6b1-9234-e111-9e0c-0012796294a7 destroyed.
2012/01/03 17:07:26.315	  1:01.177	Opal Garbage:0xb6cc5b70	Opal	Transport clean up on termination
2012/01/03 17:07:26.315	  1:01.177	Opal Garbage:0xb6cc5b70	Opal	Transport Close
2012/01/03 17:07:26.315	  1:01.177	Opal Garbage:0xb6cc5b70	Opal	Transport clean up on termination
2012/01/03 17:07:26.315	  1:01.177	Opal Garbage:0xb6cc5b70	Opal	Transport Close
2012/01/03 17:07:26.315	  1:01.177	Opal Garbage:0xb6cc5b70	Opal	Deleted transport udp$192.168.1.35:5060<if=udp$192.168.1.12:5060>
2012/01/03 17:07:26.315	  1:01.177	Opal Garbage:0xb6cc5b70	SIP	Deleted connection.
2012/01/03 17:07:26.317	  1:01.178	Opal Garbage:0xb6cc5b70	RTP	Closing session 1
2012/01/03 17:07:26.317	  1:01.179	Opal Garbage:0xb6cc5b70	RTP	Deleting session 1
2012/01/03 17:07:26.317	  1:01.179	Opal Garbage:0xb6cc5b70	InfLID	Switching off jitter buffer.
2012/01/03 17:07:26.317	  1:01.179	Opal Garbage:0xb6cc5b70	RTP	Removing jitter buffer 0x8e95d70 RTP Jitter:0xb21adb70
2012/01/03 17:07:26.317	  1:01.179	Opal Garbage:0xb6cc5b70	RTP_UDP	Session 1, Shutting down write.
2012/01/03 17:07:26.317	  1:01.179	Opal Garbage:0xb6cc5b70	OpalCon	Connection Call[d967136c31]-EP<sip>[14bfdd9f-9234-e111-9e0c-0012796294a7] destroyed.
2012/01/03 17:07:26.486	  1:01.348	Network In...0xb6c84b70	PWLib	File handle low water mark set: 49 PUDPSocket
2012/01/03 17:07:26.658	  1:01.520	 Housekeeper:0xb6c43b70	SIP	Starting REGISTER for offline retry
2012/01/03 17:07:26.658	  1:01.520	 Housekeeper:0xb6c43b70	SIP	Changing REGISTER handler from Unavailable to Restoring, target=sip:56@193.147.53.4, id=6823fa8e-9234-e111-9e0c-0012796294a7@tvsotano
2012/01/03 17:07:26.658	  1:01.520	 Housekeeper:0xb6c43b70	OpalUDP	Writing to interface 0 - "192.168.1.12%eth0"
2012/01/03 17:07:26.661	  1:01.523	 Housekeeper:0xb6c43b70	OpalMan	Listener interfaces: associated transport=udp$192.168.1.12:5060
    udp$192.168.1.12:5060
2012/01/03 17:07:26.661	  1:01.523	 Housekeeper:0xb6c43b70	SIP	Transaction created.
2012/01/03 17:07:26.664	  1:01.526	 Housekeeper:0xb6c43b70	SIP	Transaction remote address is udp$193.147.53.4:5060
2012/01/03 17:07:26.664	  1:01.526	 Housekeeper:0xb6c43b70	SIP	Sending PDU (493 bytes) to: rem=udp$193.147.53.4:5060,local=udp$192.168.1.12:5060,if=192.168.1.12%eth0
REGISTER sip:193.147.53.4 SIP/2.0
CSeq: 3 REGISTER
Via: SIP/2.0/UDP 192.168.1.12:5060;branch=z9hG4bK2644c0b2-9234-e111-9e0c-0012796294a7;rport
User-Agent: Ekiga/3.2.7
From: <sip:56@193.147.53.4>;tag=0e5afa8e-9234-e111-9e0c-0012796294a7
Call-ID: 6823fa8e-9234-e111-9e0c-0012796294a7@tvsotano
To: <sip:56@193.147.53.4>
Contact: <sip:56@192.168.1.12>;q=1
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,SUBSCRIBE,NOTIFY,REFER,MESSAGE,INFO,PING
Expires: 3600
Content-Length: 0
Max-Forwards: 70


2012/01/03 17:07:26.665	  1:01.527	 Housekeeper:0xb6c43b70	SIP	PDU Write failed: Input/output error
2012/01/03 17:07:26.665	  1:01.527	 Housekeeper:0xb6c43b70	OpalUDP	Setting interface to 192.168.1.12%eth0
2012/01/03 17:07:26.665	  1:01.527	 Housekeeper:0xb6c43b70	SIP	Set state Terminated_TransportError for REGISTER transaction id=z9hG4bK2644c0b2-9234-e111-9e0c-0012796294a7
2012/01/03 17:07:26.665	  1:01.527	 Housekeeper:0xb6c43b70	SIP	Did not start transaction on udp$193.147.53.4:5060<if=udp$192.168.1.12:5060>
2012/01/03 17:07:26.666	  1:01.527	 Housekeeper:0xb6c43b70	SIP	Changing REGISTER handler from Restoring to Unavailable, target=sip:56@193.147.53.4, id=6823fa8e-9234-e111-9e0c-0012796294a7@tvsotano
2012/01/03 17:07:26.666	  1:01.528	 Housekeeper:0xb6c43b70	SIP	Changing REGISTER handler from Unavailable to Unavailable, target=sip:56@193.147.53.4, id=6823fa8e-9234-e111-9e0c-0012796294a7@tvsotano
2012/01/03 17:07:26.666	  1:01.528	 Housekeeper:0xb6c43b70	SIP	Retrying REGISTER in 30 seconds.
2012/01/03 17:07:27.318	  1:02.180	Opal Garbage:0xb6cc5b70	SIP	Transaction id=z9hG4bK2644c0b2-9234-e111-9e0c-0012796294a7 destroyed.
2012/01/03 17:07:27.360	  1:02.222	 Housekeeper:0xb6c43b70	SIP	Starting SUBSCRIBE for offline retry
2012/01/03 17:07:27.360	  1:02.222	 Housekeeper:0xb6c43b70	SIP	Changing SUBSCRIBE handler from Unavailable to Restoring, target=sip:500 ekiga net, id=0a545e8f-9234-e111-9e0c-0012796294a7@tvsotano
2012/01/03 17:07:27.361	  1:02.222	 Housekeeper:0xb6c43b70	DNS	SRV Lookup ekiga.net service _sip._udp
2012/01/03 17:07:27.361	  1:02.223	 Housekeeper:0xb6c43b70	SIP	No SRV record found.
2012/01/03 17:07:27.361	  1:02.223	 Housekeeper:0xb6c43b70	Socket	Name lookup of "ekiga.net" failed: errno=4
2012/01/03 17:07:27.361	  1:02.223	 Housekeeper:0xb6c43b70	Opal	Could not find host "ekiga.net"
2012/01/03 17:07:27.361	  1:02.223	 Housekeeper:0xb6c43b70	OpalUDP	Binding to interface: 0.0.0.0:5060
2012/01/03 17:07:27.362	  1:02.223	 Housekeeper:0xb6c43b70	Socket	Name lookup of "ekiga.net" failed: errno=4
2012/01/03 17:07:27.362	  1:02.224	 Housekeeper:0xb6c43b70	Opal	Could not find host "ekiga.net"
2012/01/03 17:07:27.362	  1:02.224	 Housekeeper:0xb6c43b70	SIP	Could not find udp$ekiga.net:5060
2012/01/03 17:07:27.362	  1:02.224	 Housekeeper:0xb6c43b70	Opal	Transport clean up on termination
2012/01/03 17:07:27.362	  1:02.224	 Housekeeper:0xb6c43b70	Opal	Transport Close
2012/01/03 17:07:27.362	  1:02.224	 Housekeeper:0xb6c43b70	Opal	Deleted transport udp$*:5060<if=udp$*:5060>
2012/01/03 17:07:27.362	  1:02.224	 Housekeeper:0xb6c43b70	SIP	Changing SUBSCRIBE handler from Restoring to Unavailable, target=sip:500 ekiga net, id=0a545e8f-9234-e111-9e0c-0012796294a7@tvsotano
2012/01/03 17:07:27.362	  1:02.224	 Housekeeper:0xb6c43b70	SIP	Retrying SUBSCRIBE in 30 seconds.
2012/01/03 17:07:27.367	  1:02.229	 Housekeeper:0xb6c43b70	SIP	Starting SUBSCRIBE for offline retry
2012/01/03 17:07:27.367	  1:02.229	 Housekeeper:0xb6c43b70	SIP	Changing SUBSCRIBE handler from Unavailable to Restoring, target=sip:500 ekiga net, id=fa64678f-9234-e111-9e0c-0012796294a7@tvsotano
2012/01/03 17:07:27.368	  1:02.229	 Housekeeper:0xb6c43b70	DNS	SRV Lookup ekiga.net service _sip._udp
2012/01/03 17:07:27.368	  1:02.230	 Housekeeper:0xb6c43b70	SIP	No SRV record found.
2012/01/03 17:07:27.368	  1:02.230	 Housekeeper:0xb6c43b70	Socket	Name lookup of "ekiga.net" failed: errno=4
2012/01/03 17:07:27.368	  1:02.230	 Housekeeper:0xb6c43b70	Opal	Could not find host "ekiga.net"
2012/01/03 17:07:27.368	  1:02.230	 Housekeeper:0xb6c43b70	OpalUDP	Binding to interface: 0.0.0.0:5060
2012/01/03 17:07:27.368	  1:02.230	 Housekeeper:0xb6c43b70	Socket	Name lookup of "ekiga.net" failed: errno=4
2012/01/03 17:07:27.368	  1:02.230	 Housekeeper:0xb6c43b70	Opal	Could not find host "ekiga.net"
2012/01/03 17:07:27.369	  1:02.230	 Housekeeper:0xb6c43b70	SIP	Could not find udp$ekiga.net:5060
2012/01/03 17:07:27.369	  1:02.231	 Housekeeper:0xb6c43b70	Opal	Transport clean up on termination
2012/01/03 17:07:27.369	  1:02.231	 Housekeeper:0xb6c43b70	Opal	Transport Close
2012/01/03 17:07:27.369	  1:02.231	 Housekeeper:0xb6c43b70	Opal	Deleted transport udp$*:5060<if=udp$*:5060>
2012/01/03 17:07:27.369	  1:02.231	 Housekeeper:0xb6c43b70	SIP	Changing SUBSCRIBE handler from Restoring to Unavailable, target=sip:500 ekiga net, id=fa64678f-9234-e111-9e0c-0012796294a7@tvsotano
2012/01/03 17:07:27.369	  1:02.231	 Housekeeper:0xb6c43b70	SIP	Retrying SUBSCRIBE in 30 seconds.
2012/01/03 17:07:27.369	  1:02.231	 Housekeeper:0xb6c43b70	SIP	Starting SUBSCRIBE for offline retry
2012/01/03 17:07:27.369	  1:02.231	 Housekeeper:0xb6c43b70	SIP	Changing SUBSCRIBE handler from Unavailable to Restoring, target=sip:501 ekiga net, id=aabe678f-9234-e111-9e0c-0012796294a7@tvsotano
2012/01/03 17:07:27.369	  1:02.231	 Housekeeper:0xb6c43b70	DNS	SRV Lookup ekiga.net service _sip._udp
2012/01/03 17:07:27.370	  1:02.231	 Housekeeper:0xb6c43b70	SIP	No SRV record found.
2012/01/03 17:07:27.370	  1:02.232	 Housekeeper:0xb6c43b70	Socket	Name lookup of "ekiga.net" failed: errno=4
2012/01/03 17:07:27.370	  1:02.232	 Housekeeper:0xb6c43b70	Opal	Could not find host "ekiga.net"
2012/01/03 17:07:27.370	  1:02.232	 Housekeeper:0xb6c43b70	OpalUDP	Binding to interface: 0.0.0.0:5060
2012/01/03 17:07:27.370	  1:02.232	 Housekeeper:0xb6c43b70	Socket	Name lookup of "ekiga.net" failed: errno=4
2012/01/03 17:07:27.370	  1:02.232	 Housekeeper:0xb6c43b70	Opal	Could not find host "ekiga.net"
2012/01/03 17:07:27.370	  1:02.232	 Housekeeper:0xb6c43b70	SIP	Could not find udp$ekiga.net:5060
2012/01/03 17:07:27.370	  1:02.232	 Housekeeper:0xb6c43b70	Opal	Transport clean up on termination
2012/01/03 17:07:27.370	  1:02.232	 Housekeeper:0xb6c43b70	Opal	Transport Close
2012/01/03 17:07:27.370	  1:02.232	 Housekeeper:0xb6c43b70	Opal	Deleted transport udp$*:5060<if=udp$*:5060>
2012/01/03 17:07:27.371	  1:02.233	 Housekeeper:0xb6c43b70	SIP	Changing SUBSCRIBE handler from Restoring to Unavailable, target=sip:501 ekiga net, id=aabe678f-9234-e111-9e0c-0012796294a7@tvsotano
2012/01/03 17:07:27.371	  1:02.233	 Housekeeper:0xb6c43b70	SIP	Retrying SUBSCRIBE in 30 seconds.
2012/01/03 17:07:27.375	  1:02.237	 Housekeeper:0xb6c43b70	SIP	Starting SUBSCRIBE for offline retry
2012/01/03 17:07:27.375	  1:02.237	 Housekeeper:0xb6c43b70	SIP	Changing SUBSCRIBE handler from Unavailable to Restoring, target=sip:501 ekiga net, id=f013688f-9234-e111-9e0c-0012796294a7@tvsotano
2012/01/03 17:07:27.375	  1:02.237	 Housekeeper:0xb6c43b70	DNS	SRV Lookup ekiga.net service _sip._udp
2012/01/03 17:07:27.376	  1:02.237	 Housekeeper:0xb6c43b70	SIP	No SRV record found.
2012/01/03 17:07:27.376	  1:02.238	 Housekeeper:0xb6c43b70	Socket	Name lookup of "ekiga.net" failed: errno=4
2012/01/03 17:07:27.376	  1:02.238	 Housekeeper:0xb6c43b70	Opal	Could not find host "ekiga.net"
2012/01/03 17:07:27.376	  1:02.238	 Housekeeper:0xb6c43b70	OpalUDP	Binding to interface: 0.0.0.0:5060
2012/01/03 17:07:27.376	  1:02.238	 Housekeeper:0xb6c43b70	Socket	Name lookup of "ekiga.net" failed: errno=4
2012/01/03 17:07:27.376	  1:02.238	 Housekeeper:0xb6c43b70	Opal	Could not find host "ekiga.net"
2012/01/03 17:07:27.376	  1:02.238	 Housekeeper:0xb6c43b70	SIP	Could not find udp$ekiga.net:5060
2012/01/03 17:07:27.376	  1:02.238	 Housekeeper:0xb6c43b70	Opal	Transport clean up on termination
2012/01/03 17:07:27.376	  1:02.238	 Housekeeper:0xb6c43b70	Opal	Transport Close
2012/01/03 17:07:27.376	  1:02.238	 Housekeeper:0xb6c43b70	Opal	Deleted transport udp$*:5060<if=udp$*:5060>
2012/01/03 17:07:27.377	  1:02.238	 Housekeeper:0xb6c43b70	SIP	Changing SUBSCRIBE handler from Restoring to Unavailable, target=sip:501 ekiga net, id=f013688f-9234-e111-9e0c-0012796294a7@tvsotano
2012/01/03 17:07:27.377	  1:02.239	 Housekeeper:0xb6c43b70	SIP	Retrying SUBSCRIBE in 30 seconds.
2012/01/03 17:07:27.377	  1:02.239	 Housekeeper:0xb6c43b70	SIP	Starting SUBSCRIBE for offline retry
2012/01/03 17:07:27.377	  1:02.239	 Housekeeper:0xb6c43b70	SIP	Changing SUBSCRIBE handler from Unavailable to Restoring, target=sip:usuario@sip:192, id=ae69688f-9234-e111-9e0c-0012796294a7@tvsotano
2012/01/03 17:07:27.377	  1:02.239	 Housekeeper:0xb6c43b70	SIP	No SRV lookup as has explicit port number.
2012/01/03 17:07:27.377	  1:02.239	 Housekeeper:0xb6c43b70	Socket	Name lookup of "sip" failed: errno=4
2012/01/03 17:07:27.377	  1:02.239	 Housekeeper:0xb6c43b70	Opal	Could not find host "sip"
2012/01/03 17:07:27.377	  1:02.239	 Housekeeper:0xb6c43b70	OpalUDP	Binding to interface: 0.0.0.0:5060
2012/01/03 17:07:27.377	  1:02.239	 Housekeeper:0xb6c43b70	Socket	Name lookup of "sip" failed: errno=4
2012/01/03 17:07:27.377	  1:02.239	 Housekeeper:0xb6c43b70	Opal	Could not find host "sip"
2012/01/03 17:07:27.378	  1:02.239	 Housekeeper:0xb6c43b70	SIP	Could not find udp$sip:192
2012/01/03 17:07:27.378	  1:02.239	 Housekeeper:0xb6c43b70	Opal	Transport clean up on termination
2012/01/03 17:07:27.378	  1:02.240	 Housekeeper:0xb6c43b70	Opal	Transport Close
2012/01/03 17:07:27.378	  1:02.240	 Housekeeper:0xb6c43b70	Opal	Deleted transport udp$*:192<if=udp$*:5060>
2012/01/03 17:07:27.378	  1:02.240	 Housekeeper:0xb6c43b70	SIP	Changing SUBSCRIBE handler from Restoring to Unavailable, target=sip:usuario@sip:192, id=ae69688f-9234-e111-9e0c-0012796294a7@tvsotano
2012/01/03 17:07:27.378	  1:02.240	 Housekeeper:0xb6c43b70	SIP	Retrying SUBSCRIBE in 30 seconds.
2012/01/03 17:07:27.378	  1:02.240	 Housekeeper:0xb6c43b70	SIP	Starting SUBSCRIBE for offline retry
2012/01/03 17:07:27.378	  1:02.240	 Housekeeper:0xb6c43b70	SIP	Changing SUBSCRIBE handler from Unavailable to Restoring, target=sip:usuario@sip:192, id=2ebc688f-9234-e111-9e0c-0012796294a7@tvsotano
2012/01/03 17:07:27.378	  1:02.240	 Housekeeper:0xb6c43b70	SIP	No SRV lookup as has explicit port number.
2012/01/03 17:07:27.378	  1:02.240	 Housekeeper:0xb6c43b70	Socket	Name lookup of "sip" failed: errno=4
2012/01/03 17:07:27.378	  1:02.240	 Housekeeper:0xb6c43b70	Opal	Could not find host "sip"
2012/01/03 17:07:27.378	  1:02.240	 Housekeeper:0xb6c43b70	OpalUDP	Binding to interface: 0.0.0.0:5060
2012/01/03 17:07:27.379	  1:02.241	 Housekeeper:0xb6c43b70	Socket	Name lookup of "sip" failed: errno=4
2012/01/03 17:07:27.379	  1:02.241	 Housekeeper:0xb6c43b70	Opal	Could not find host "sip"
2012/01/03 17:07:27.379	  1:02.241	 Housekeeper:0xb6c43b70	SIP	Could not find udp$sip:192
2012/01/03 17:07:27.379	  1:02.241	 Housekeeper:0xb6c43b70	Opal	Transport clean up on termination
2012/01/03 17:07:27.379	  1:02.241	 Housekeeper:0xb6c43b70	Opal	Transport Close
2012/01/03 17:07:27.379	  1:02.241	 Housekeeper:0xb6c43b70	Opal	Deleted transport udp$*:192<if=udp$*:5060>
2012/01/03 17:07:27.379	  1:02.241	 Housekeeper:0xb6c43b70	SIP	Changing SUBSCRIBE handler from Restoring to Unavailable, target=sip:usuario@sip:192, id=2ebc688f-9234-e111-9e0c-0012796294a7@tvsotano
2012/01/03 17:07:27.379	  1:02.241	 Housekeeper:0xb6c43b70	SIP	Retrying SUBSCRIBE in 30 seconds.
2012/01/03 17:07:27.409	  1:02.271	                       	Call	Call[d967136c31] destroyed.
2012/01/03 17:07:33.267	  1:08.129	 Housekeeper:0xb6c43b70	SIP	Starting SUBSCRIBE for offline retry
2012/01/03 17:07:33.268	  1:08.130	 Housekeeper:0xb6c43b70	SIP	Changing SUBSCRIBE handler from Unavailable to Restoring, target=sip:neighbour@ubuntu.local:5060, id=5ce2f08f-9234-e111-9e0c-0012796294a7@tvsotano
2012/01/03 17:07:33.268	  1:08.130	 Housekeeper:0xb6c43b70	SIP	No SRV lookup as has explicit port number.
2012/01/03 17:07:33.268	  1:08.130	 Housekeeper:0xb6c43b70	Socket	Name lookup of "ubuntu.local" failed: errno=4
2012/01/03 17:07:33.268	  1:08.130	 Housekeeper:0xb6c43b70	Opal	Could not find host "ubuntu.local"
2012/01/03 17:07:33.268	  1:08.130	 Housekeeper:0xb6c43b70	OpalUDP	Binding to interface: 0.0.0.0:5060
2012/01/03 17:07:33.268	  1:08.130	 Housekeeper:0xb6c43b70	Socket	Name lookup of "ubuntu.local" failed: errno=4
2012/01/03 17:07:33.268	  1:08.130	 Housekeeper:0xb6c43b70	Opal	Could not find host "ubuntu.local"
2012/01/03 17:07:33.269	  1:08.130	 Housekeeper:0xb6c43b70	SIP	Could not find udp$ubuntu.local:5060
2012/01/03 17:07:33.269	  1:08.131	 Housekeeper:0xb6c43b70	Opal	Transport clean up on termination
2012/01/03 17:07:33.269	  1:08.131	 Housekeeper:0xb6c43b70	Opal	Transport Close
2012/01/03 17:07:33.269	  1:08.131	 Housekeeper:0xb6c43b70	Opal	Deleted transport udp$*:5060<if=udp$*:5060>
2012/01/03 17:07:33.269	  1:08.131	 Housekeeper:0xb6c43b70	SIP	Changing SUBSCRIBE handler from Restoring to Unavailable, target=sip:neighbour@ubuntu.local:5060, id=5ce2f08f-9234-e111-9e0c-0012796294a7@tvsotano
2012/01/03 17:07:33.269	  1:08.131	 Housekeeper:0xb6c43b70	SIP	Retrying SUBSCRIBE in 30 seconds.
2012/01/03 17:07:33.273	  1:08.135	 Housekeeper:0xb6c43b70	SIP	Starting SUBSCRIBE for offline retry
2012/01/03 17:07:33.273	  1:08.135	 Housekeeper:0xb6c43b70	SIP	Changing SUBSCRIBE handler from Unavailable to Restoring, target=sip:neighbour@ubuntu.local:5060, id=2aa7ec92-9234-e111-9e0c-0012796294a7@tvsotano
2012/01/03 17:07:33.273	  1:08.135	 Housekeeper:0xb6c43b70	SIP	No SRV lookup as has explicit port number.
2012/01/03 17:07:33.274	  1:08.135	 Housekeeper:0xb6c43b70	Socket	Name lookup of "ubuntu.local" failed: errno=4
2012/01/03 17:07:33.274	  1:08.136	 Housekeeper:0xb6c43b70	Opal	Could not find host "ubuntu.local"
2012/01/03 17:07:33.274	  1:08.136	 Housekeeper:0xb6c43b70	OpalUDP	Binding to interface: 0.0.0.0:5060
2012/01/03 17:07:33.274	  1:08.136	 Housekeeper:0xb6c43b70	Socket	Name lookup of "ubuntu.local" failed: errno=4
2012/01/03 17:07:33.274	  1:08.136	 Housekeeper:0xb6c43b70	Opal	Could not find host "ubuntu.local"
2012/01/03 17:07:33.274	  1:08.136	 Housekeeper:0xb6c43b70	SIP	Could not find udp$ubuntu.local:5060
2012/01/03 17:07:33.274	  1:08.136	 Housekeeper:0xb6c43b70	Opal	Transport clean up on termination
2012/01/03 17:07:33.274	  1:08.136	 Housekeeper:0xb6c43b70	Opal	Transport Close
2012/01/03 17:07:33.274	  1:08.136	 Housekeeper:0xb6c43b70	Opal	Deleted transport udp$*:5060<if=udp$*:5060>
2012/01/03 17:07:33.274	  1:08.136	 Housekeeper:0xb6c43b70	SIP	Changing SUBSCRIBE handler from Restoring to Unavailable, target=sip:neighbour@ubuntu.local:5060, id=2aa7ec92-9234-e111-9e0c-0012796294a7@tvsotano
2012/01/03 17:07:33.274	  1:08.136	 Housekeeper:0xb6c43b70	SIP	Retrying SUBSCRIBE in 30 seconds.


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