[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]