[sip-comm-dev] Re: sip-communicator <-> asterisk communication


#1

Hello Ben,

net.java.sip.communicator.sip.CallProcessing

line # 219

is where we send an ACK to an INVITE triggerred OK. My bet is that the OK response doesn't reach the application and gets thrown away by the stack for some reason ... however, I have no idea why and I am currently out of France (i.e. away from the office and a decent inetnet connection) so I wouldn't be able to get into that before the 1st of may. Let me know if you find anything in particular. I'll look at that as soon as I'm back. You could probably open an asterirsk account for me somewhere so that I could test directly?

Thanks for your ongoing efforts Ben
Cheers
Emil

ben@asselstine.com wrote:

···

Hi all,

After testing the java sip-communicator user-agent [1] with asterisk [2],
I found that it wasn't working. Here's what I saw after registration:

invite ->
<-407
ack ->
invite /w proxy credentials ->
<- 100
<- 200
<- 200 *
<- 200
etc.

* according to the sip-communicator logs, it only gets one 200 message
from asterisk.

conclusions:

sip-communicator doesn't send an ACK to the 2nd invite/100/200 sequence,
and asterisk tries to retransmit until it receives an ACK.

asterisk garbles the SDP in retransmitting the 200 OK message. Content-length stays the same, yet there are some extra bytes on the end
during retransmission.

Am I right? I've included the sip-communicator and asterisk logs inline
so that others can validate my claims. My apologies for the long post.

logs for sip-communicator starting at the 2nd invite,
and continuing to the last 200 message received from asterisk.
--------------------------------------------------------------
<message
from="192.168.3.44:5060"
to="192.168.3.18:5060"
time="1082113903972"
isSender="true"
transactionId="z9hg4bk9ccdda05565c85c31ef1f27980f342f9"
callId="d6e12283021906af516b4d9318a90fc1@192.168.3.44"
firstLine="INVITE sip:600@192.168.3.18 SIP/2.0"
debugLine="0"

<![CDATA[INVITE sip:600@192.168.3.18 SIP/2.0

Call-ID: d6e12283021906af516b4d9318a90fc1@192.168.3.44

CSeq: 2 INVITE

To: <sip:600@192.168.3.18>

Via: SIP/2.0/UDP
192.168.3.44:5060;branch=z9hG4bK9ccdda05565c85c31ef1f27980f342f9

Max-Forwards: 70

Contact: "Ben Asselstine" <sip:192.168.3.44:5060;transport=udp>

Content-Type: application/sdp

Proxy-Authorization: Digest
username="ben",realm="asterisk",nonce="40c531b4",uri="sip:600@192.168.3.18",response="c0bba5ef8fdb04e465b135fba09b072c"

From: "Ben Asselstine"
<sip:ben@itchy.xxxxxx.yyy.zzz:5060;transport=udp>;tag=22162914

Content-Length: 153

v=0

o=ben 0 0 IN IP4 192.168.3.44

s=-

c=IN IP4 192.168.3.44

t=0 0

m=audio 22224 RTP/AVP 0 3 4 5 6 8 15

m=video 22222 RTP/AVP 34 26 31

a=recvonly

]]>
</message>

38141 [Thread-3] TRACE sip.CallDispatcher - [entry] findCall
38141 [Thread-3] TRACE sip.CallDispatcher - [exit] findCall
38141 [Thread-3] TRACE sip.SipManager - [exit] processResponse
<message
from="192.168.3.18:5060"
to="192.168.3.44:5060"
time="1082113903997"
isSender="false"
statusMessage="normal processing"
transactionId="z9hg4bk9ccdda05565c85c31ef1f27980f342f9"
firstLine="SIP/2.0 100 Trying"
callId="d6e12283021906af516b4d9318a90fc1@192.168.3.44"
debugLine="0"

<![CDATA[SIP/2.0 100 Trying

Via: SIP/2.0/UDP
192.168.3.44:5060;branch=z9hG4bK9ccdda05565c85c31ef1f27980f342f9

From: "Ben Asselstine"
<sip:ben@itchy.xxxxxx.yyy.zzz:5060;transport=udp>;tag=22162914

To: <sip:600@192.168.3.18>;tag=as16260d10

Call-ID: d6e12283021906af516b4d9318a90fc1@192.168.3.44

CSeq: 2 INVITE

User-Agent: Asterisk PBX

Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER

Contact: <sip:600@192.168.3.18>

Content-Length: 0

]]>
</message>

<message
from="192.168.3.18:5060"
to="192.168.3.44:5060"
time="1082113904004"
isSender="false"
statusMessage="normal processing"
transactionId="z9hg4bk9ccdda05565c85c31ef1f27980f342f9"
firstLine="SIP/2.0 200 OK"
callId="d6e12283021906af516b4d9318a90fc1@192.168.3.44"
debugLine="0"

<![CDATA[SIP/2.0 200 OK

Via: SIP/2.0/UDP
192.168.3.44:5060;branch=z9hG4bK9ccdda05565c85c31ef1f27980f342f9

From: "Ben Asselstine"
<sip:ben@itchy.xxxxxx.yyy.zzz:5060;transport=udp>;tag=22162914

To: <sip:600@192.168.3.18>;tag=as16260d10

Call-ID: d6e12283021906af516b4d9318a90fc1@192.168.3.44

CSeq: 2 INVITE

User-Agent: Asterisk PBX

Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER

Contact: <sip:600@192.168.3.18>

Content-Type: application/sdp

Content-Length: 207

v=0

o=root 10318 10318 IN IP4 192.168.3.18

s=session

c=IN IP4 192.168.3.18

t=0 0

m=audio 11382 RTP/AVP 3 0 8

a=rtpmap:3 GSM/8000

a=rtpmap:0 PCMU/8000

a=rtpmap:8 PCMA/8000

a=silenceSupp:off - - - -

]]>
</message>

38174 [AWT-EventQueue-0] TRACE sip.CallDispatcher - [entry] createCall
38175 [AWT-EventQueue-0] TRACE sip.CallDispatcher - [entry] findCall
38175 [AWT-EventQueue-0] TRACE sip.CallDispatcher - [exit] findCall
38176 [AWT-EventQueue-0] DEBUG sip.CallDispatcher - created call[ Call
12743356
from 600@sip:600@192.168.3.18
SDP:null]
38176 [AWT-EventQueue-0] TRACE sip.CallDispatcher - [exit] createCall
38177 [AWT-EventQueue-0] TRACE sip.Call - [entry] setState
38177 [AWT-EventQueue-0] DEBUG sip.Call - setting call status to Dialing
38179 [AWT-EventQueue-0] TRACE sip.Call - [exit] setState
38179 [AWT-EventQueue-0] TRACE sip.CallProcessing - [exit] invite
38179 [AWT-EventQueue-0] TRACE sip.SipManager - [exit] establishCall
38181 [AWT-EventQueue-0] TRACE communicator.SipCommunicator - [exit]
handleDialRequest

logs for asterisk starting at the 2nd invite,
and continuing to the 1st 200 retransmittal by asterisk.
--------------------------------------------------------------
Sip read:
INVITE sip:600@192.168.3.18 SIP/2.0
Call-ID: d6e12283021906af516b4d9318a90fc1@192.168.3.44
CSeq: 2 INVITE
To: <sip:600@192.168.3.18>
Via: SIP/2.0/UDP
192.168.3.44:5060;branch=z9hG4bK9ccdda05565c85c31ef1f27980f342f9
Max-Forwards: 70
Contact: "Ben Asselstine" <sip:192.168.3.44:5060;transport=udp>
Content-Type: application/sdp
Proxy-Authorization: Digest
username="ben",realm="asterisk",nonce="40c531b4",uri="sip:600@192.168.3.18",response="c0bba5ef8fdb04e465b135fba09b072c"
From: "Ben Asselstine"
<sip:ben@itchy.xxxxxx.yyy.zzz:5060;transport=udp>;tag=22162914
Content-Length: 153

v=0
o=ben 0 0 IN IP4 192.168.3.44
s=-
c=IN IP4 192.168.3.44
t=0 0
m=audio 22224 RTP/AVP 0 3 4 5 6 8 15
m=video 22222 RTP/AVP 34 26 31
a=recvonly

11 headers, 8 lines
Using latest request as basis request
Sending to 192.168.3.44 : 5060 (non-NAT)
Found audio format UNKN
Found audio format ULAW
Found audio format UNKN
Found audio format ALAW
Found audio format UNKN
Capabilities: us - 524302, them - 47/0, combined - 14
Non-codec capabilities: us - 1, them - 0, combined - 0
Looking for 600 in default
list_route: hop: <sip:192.168.3.44:5060;transport=udp>
Transmitting (no NAT):
SIP/2.0 100 Trying
Via: SIP/2.0/UDP
192.168.3.44:5060;branch=z9hG4bK9ccdda05565c85c31ef1f27980f342f9
From: "Ben Asselstine"
<sip:ben@itchy.xxxxxx.yyy.zzz:5060;transport=udp>;tag=22162914
To: <sip:600@192.168.3.18>;tag=as16260d10
Call-ID: d6e12283021906af516b4d9318a90fc1@192.168.3.44
CSeq: 2 INVITE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER
Contact: <sip:600@192.168.3.18>
Content-Length: 0

to 192.168.3.44:5060
    -- Executing Playback("SIP/ben-c20e", "demo-echotest") in new stack
We're at 192.168.3.18 port 11382
Answering/Requesting with preferred capability 2
Answering with capability 4
Answering with capability 8
Reliably Transmitting (no NAT):
SIP/2.0 200 OK
Via: SIP/2.0/UDP
192.168.3.44:5060;branch=z9hG4bK9ccdda05565c85c31ef1f27980f342f9
From: "Ben Asselstine"
<sip:ben@itchy.xxxxxx.yyy.zzz:5060;transport=udp>;tag=22162914
To: <sip:600@192.168.3.18>;tag=as16260d10
Call-ID: d6e12283021906af516b4d9318a90fc1@192.168.3.44
CSeq: 2 INVITE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER
Contact: <sip:600@192.168.3.18>
Content-Type: application/sdp
Content-Length: 207

v=0
o=root 10318 10318 IN IP4 192.168.3.18
s=session
c=IN IP4 192.168.3.18
t=0 0
m=audio 11382 RTP/AVP 3 0 8
a=rtpmap:3 GSM/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=silenceSupp:off - - - -

to 192.168.3.44:5060
    -- Playing 'demo-echotest' (language 'en')
Retransmitting #1 (no NAT):
SIP/2.0 200 OK
Via: SIP/2.0/UDP
192.168.3.44:5060;branch=z9hG4bK9ccdda05565c85c31ef1f27980f342f9
From: "Ben Asselstine"
<sip:ben@itchy.xxxxxx.yyy.zzz:5060;transport=udp>;tag=22162914
To: <sip:600@192.168.3.18>;tag=as16260d10
Call-ID: d6e12283021906af516b4d9318a90fc1@192.168.3.44
CSeq: 2 INVITE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER
Contact: <sip:600@192.168.3.18>
Content-Type: application/sdp
Content-Length: 207

v=0
o=root 10318 10318 IN IP4 192.168.3.18
s=session
c=IN IP4 192.168.3.18
t=0 0
m=audio 11382 RTP/AVP 3 0 8
a=rtpmap:3 GSM/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=silenceSupp:off - - - -
" <
to 192.168.3.44:5060
Retransmitting #2 (no NAT):
SIP/2.0 200 OK
Via: SIP/2.0/UDP
192.168.3.44:5060;branch=z9hG4bK9ccdda05565c85c31ef1f27980f342f9
From: "Ben Asselstine"
<sip:ben@itchy.xxxxxx.yyy.zzz:5060;transport=udp>;tag=22162914
To: <sip:600@192.168.3.18>;tag=as16260d10
Call-ID: d6e12283021906af516b4d9318a90fc1@192.168.3.44
CSeq: 2 INVITE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER
Contact: <sip:600@192.168.3.18>
Content-Type: application/sdp
Content-Length: 207

v=0
o=root 10318 10318 IN IP4 192.168.3.18
s=session
c=IN IP4 192.168.3.18
t=0 0
m=audio 11382 RTP/AVP 3 0 8
a=rtpmap:3 GSM/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=silenceSupp:off - - - -
" <
to 192.168.3.44:5060

thanks,

Ben

[1] http://sip-communicator.dev.java.net/
[2] http://www.asterisk.org

---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscribe@sip-communicator.dev.java.net
For additional commands, e-mail: dev-help@sip-communicator.dev.java.net


#2

Yes, 1 more thing. The NumberFormatExceptions are normal and harmless.

Emil

ben@asselstine.com wrote:

···

Hello,

I've learned that sometimes sip-communicator sends the elusive 2nd ACK.
Does this look normal? There are a couple of
java.lang.NumberFormatException: null errors. And at the bottom I don't
like the look of com.sun.media.processor.unknown.Handler.
Unfortunately I don't have a proper log file to compare this one to, so
I'm quite unsure about what's right and what's wrong. If I could see a
log file from a successful connection of sip-communicator I could do more.

The log shows the transmitter and receiver both starting, but I'm not
hearing any audio yet, and I know there's audio being sent to
sip-communicator. asterisk doesn't complain about not being able to write
on a socket. The strange thing is that I tried sniffing port 22222 for
packets, and I saw none.

ttyl,

Ben

Here's the log:

<message
from="192.168.3.44:5060"
to="192.168.3.18:5060"
time="1082988370639"
isSender="true"
transactionId="39322e3136382e332e34343a3530363"
callId="6cfd22e6e814cd5c5d174a43ecf99b10@192.168.3.44"
firstLine="ACK sip:600@192.168.3.18;transport=udp SIP/2.0"
debugLine="0"

<![CDATA[ACK sip:600@192.168.3.18;transport=udp SIP/2.0

Call-ID: 6cfd22e6e814cd5c5d174a43ecf99b10@192.168.3.44

CSeq: 2 ACK

To: <sip:600@itchy.dlcspm.mil.ca>;tag=as7743cef6

Via: SIP/2.0/UDP 192.168.3.44:5060;branch=39322e3136382e332e34343a3530363

Max-Forwards: 70

From: "Ben Asselstine"
<sip:ben@itchy.dlcspm.mil.ca:5060;transport=udp>;tag=14113863

Content-Length: 0

]]>
</message>

65294 [Thread-3] DEBUG sip.Call - setting remote description to [v=0

o=root 9333 9333 IN IP4 192.168.3.18

s=session

c=IN IP4 192.168.3.18

t=0 0

m=audio 13692 RTP/AVP 3 0 8

a=rtpmap:3 GSM/8000

a=rtpmap:0 PCMU/8000

a=rtpmap:8 PCMA/8000

a=silenceSupp:off - - - -

]
65294 [Thread-3] TRACE sip.Call - [entry] setState
65294 [Thread-3] DEBUG sip.Call - setting call status to Connected
65294 [Thread-3] TRACE communicator.Interlocutor - [entry] callStateChanged
65294 [Thread-3] TRACE communicator.Interlocutor - [exit] callStateChanged
65294 [Thread-3] TRACE communicator.SipCommunicator - [entry]
callStateChanged
65295 [Thread-3] TRACE media.MediaManager - [entry] openMediaStreams
65295 [Thread-3] DEBUG media.MediaManager - sdpData arg - v=0

o=root 9333 9333 IN IP4 192.168.3.18

s=session

c=IN IP4 192.168.3.18

t=0 0

m=audio 13692 RTP/AVP 3 0 8

a=rtpmap:3 GSM/8000

a=rtpmap:0 PCMU/8000

a=rtpmap:8 PCMA/8000

a=silenceSupp:off - - - -

65348 [Thread-3] TRACE media.MediaManager - [entry]
extractTransmittableJmfFormats
65348 [Thread-3] TRACE media.MediaManager - [exit]
extractTransmittableJmfFormats
65349 [Thread-3] TRACE media.MediaManager - [entry] startReceiver
65353 [Thread-3] TRACE media.MediaManager - [entry] getAudioPort
65353 [Thread-3] TRACE common.PropertiesDepot - [entry] getProperty
65353 [Thread-3] TRACE common.Utils - [entry] getSystemProperty
65353 [Thread-3] TRACE common.Utils - [exit] getSystemProperty
65353 [Thread-3] TRACE common.PropertiesDepot - [exit] getProperty
65354 [Thread-3] TRACE media.MediaManager - [exit] getAudioPort
65354 [Thread-3] TRACE media.MediaManager - [entry] getVideoPort
65354 [Thread-3] TRACE common.PropertiesDepot - [entry] getProperty
65355 [Thread-3] TRACE common.Utils - [entry] getSystemProperty
65355 [Thread-3] TRACE common.Utils - [exit] getSystemProperty
65355 [Thread-3] TRACE common.PropertiesDepot - [exit] getProperty
65356 [Thread-3] TRACE media.MediaManager - [exit] getVideoPort
65356 [Thread-3] TRACE common.PropertiesDepot - [entry] getProperty
65356 [Thread-3] TRACE common.Utils - [entry] getSystemProperty
65356 [Thread-3] TRACE common.Utils - [exit] getSystemProperty
65356 [Thread-3] TRACE common.PropertiesDepot - [exit] getProperty
65356 [Thread-3] TRACE media.AVReceiver - [entry] initialize
65360 [Thread-3] TRACE media.AVReceiver$SessionLabel - [entry] <init>
65360 [Thread-3] TRACE media.AVReceiver$SessionLabel - [exit] <init>
65360 [Thread-3] DEBUG media.AVReceiver - Start listening for RTP @
addr: 192.168.3.18 port: 22224 ttl: 1
65360 [Thread-3] TRACE media.MediaManager - [entry] getLocalHost
65360 [Thread-3] TRACE common.PropertiesDepot - [entry] getProperty
65360 [Thread-3] TRACE common.Utils - [entry] getSystemProperty
65361 [Thread-3] TRACE common.Utils - [exit] getSystemProperty
65361 [Thread-3] TRACE common.PropertiesDepot - [exit] getProperty
65361 [Thread-3] TRACE common.NetworkAddressManager - [entry] getLocalHost
65361 [Thread-3] TRACE common.PropertiesDepot - [entry] getProperty
65361 [Thread-3] TRACE common.Utils - [entry] getSystemProperty
65361 [Thread-3] TRACE common.Utils - [exit] getSystemProperty
65361 [Thread-3] TRACE common.PropertiesDepot - [exit] getProperty
65362 [Thread-3] TRACE common.PropertiesDepot - [entry] getProperty
65362 [Thread-3] TRACE common.Utils - [entry] getSystemProperty
65362 [Thread-3] TRACE common.Utils - [exit] getSystemProperty
65362 [Thread-3] TRACE common.PropertiesDepot - [exit] getProperty
65362 [Thread-3] DEBUG common.NetworkAddressManager - Found Linklocal
ipv4 address /192.168.3.44
65362 [Thread-3] DEBUG common.NetworkAddressManager - Returning link
local address
65362 [Thread-3] TRACE common.NetworkAddressManager - [exit] getLocalHost
65362 [Thread-3] DEBUG media.MediaManager - returning - 192.168.3.44
65362 [Thread-3] TRACE media.MediaManager - [exit] getLocalHost
65400 [Thread-3] TRACE media.MediaManager - [entry] getLocalHost
65400 [Thread-3] TRACE common.PropertiesDepot - [entry] getProperty
65400 [Thread-3] TRACE common.Utils - [entry] getSystemProperty
65400 [Thread-3] TRACE common.Utils - [exit] getSystemProperty
65400 [Thread-3] TRACE common.PropertiesDepot - [exit] getProperty
65400 [Thread-3] TRACE common.NetworkAddressManager - [entry] getLocalHost
65401 [Thread-3] TRACE common.PropertiesDepot - [entry] getProperty
65401 [Thread-3] TRACE common.Utils - [entry] getSystemProperty
65401 [Thread-3] TRACE common.Utils - [exit] getSystemProperty
65401 [Thread-3] TRACE common.PropertiesDepot - [exit] getProperty
65401 [Thread-3] TRACE common.PropertiesDepot - [entry] getProperty
65401 [Thread-3] TRACE common.Utils - [entry] getSystemProperty
65401 [Thread-3] TRACE common.Utils - [exit] getSystemProperty
65402 [Thread-3] TRACE common.PropertiesDepot - [exit] getProperty
65402 [Thread-3] DEBUG common.NetworkAddressManager - Found Linklocal
ipv4 address /192.168.3.44
65402 [Thread-3] DEBUG common.NetworkAddressManager - Returning link
local address
65402 [Thread-3] TRACE common.NetworkAddressManager - [exit] getLocalHost
65402 [Thread-3] DEBUG media.MediaManager - returning - 192.168.3.44
65402 [Thread-3] TRACE media.MediaManager - [exit] getLocalHost
65402 [Thread-3] TRACE media.MediaManager - [entry] getLocalHost
65402 [Thread-3] TRACE common.PropertiesDepot - [entry] getProperty
65402 [Thread-3] TRACE common.Utils - [entry] getSystemProperty
65403 [Thread-3] TRACE common.Utils - [exit] getSystemProperty
65403 [Thread-3] TRACE common.PropertiesDepot - [exit] getProperty
65403 [Thread-3] TRACE common.NetworkAddressManager - [entry] getLocalHost
65403 [Thread-3] TRACE common.PropertiesDepot - [entry] getProperty
65403 [Thread-3] TRACE common.Utils - [entry] getSystemProperty
65403 [Thread-3] TRACE common.Utils - [exit] getSystemProperty
65403 [Thread-3] TRACE common.PropertiesDepot - [exit] getProperty
65404 [Thread-3] TRACE common.PropertiesDepot - [entry] getProperty
65404 [Thread-3] TRACE common.Utils - [entry] getSystemProperty
65410 [Thread-3] TRACE common.Utils - [exit] getSystemProperty
65411 [Thread-3] TRACE common.PropertiesDepot - [exit] getProperty
65411 [Thread-3] DEBUG common.NetworkAddressManager - Found Linklocal
ipv4 address /192.168.3.44
65411 [Thread-3] DEBUG common.NetworkAddressManager - Returning link
local address
65411 [Thread-3] TRACE common.NetworkAddressManager - [exit] getLocalHost
65411 [Thread-3] DEBUG media.MediaManager - returning - 192.168.3.44
65411 [Thread-3] TRACE media.MediaManager - [exit] getLocalHost
65431 [Thread-3] TRACE common.PropertiesDepot - [entry] getProperty
65431 [Thread-3] TRACE common.Utils - [entry] getSystemProperty
65432 [Thread-3] TRACE common.Utils - [exit] getSystemProperty
65432 [Thread-3] TRACE common.PropertiesDepot - [exit] getProperty
65432 [Thread-3] DEBUG media.AVReceiver - MEDIA_BUFFER_LENGTH length not
specified using default 350
java.lang.NumberFormatException: null
  at java.lang.Integer.parseInt(Integer.java:436)
  at java.lang.Integer.parseInt(Integer.java:518)
  at
net.java.sip.communicator.media.AVReceiver.initialize(AVReceiver.java:193)
  at
net.java.sip.communicator.media.MediaManager.startReceiver(MediaManager.java:543)
  at
net.java.sip.communicator.media.MediaManager.openMediaStreams(MediaManager.java:424)
  at
net.java.sip.communicator.SipCommunicator.callStateChanged(SipCommunicator.java:722)
  at
net.java.sip.communicator.sip.Call.fireCallStatusChangedEvent(Call.java:250)
  at net.java.sip.communicator.sip.Call.setState(Call.java:156)
  at
net.java.sip.communicator.sip.CallProcessing.processInviteOK(CallProcessing.java:239)
  at
net.java.sip.communicator.sip.SipManager.processResponse(SipManager.java:1628)
  at gov.nist.javax.sip.EventScanner.run(EventScanner.java:218)
  at java.lang.Thread.run(Thread.java:534)
65561 [RTPEventHandler] TRACE media.AVReceiver - [entry] update
65561 [RTPEventHandler] DEBUG media.AVReceiver - Recevied new RTP stream:
gsm/rtp, 8000.0 Hz, Mono
65578 [Thread-3] TRACE media.AVReceiver$SessionLabel - [entry] <init>
65578 [Thread-3] TRACE media.AVReceiver$SessionLabel - [exit] <init>
65578 [Thread-3] DEBUG media.AVReceiver - Start listening for RTP @
addr: 192.168.3.18 port: 22222 ttl: 1
65578 [Thread-3] TRACE media.MediaManager - [entry] getLocalHost
65579 [Thread-3] TRACE common.PropertiesDepot - [entry] getProperty
65579 [Thread-3] TRACE common.Utils - [entry] getSystemProperty
65579 [Thread-3] TRACE common.Utils - [exit] getSystemProperty
65579 [Thread-3] TRACE common.PropertiesDepot - [exit] getProperty
65579 [Thread-3] TRACE common.NetworkAddressManager - [entry] getLocalHost
65579 [Thread-3] TRACE common.PropertiesDepot - [entry] getProperty
65579 [Thread-3] TRACE common.Utils - [entry] getSystemProperty
65580 [Thread-3] TRACE common.Utils - [exit] getSystemProperty
65580 [Thread-3] TRACE common.PropertiesDepot - [exit] getProperty
65580 [Thread-3] TRACE common.PropertiesDepot - [entry] getProperty
65580 [Thread-3] TRACE common.Utils - [entry] getSystemProperty
65580 [Thread-3] TRACE common.Utils - [exit] getSystemProperty
65580 [Thread-3] TRACE common.PropertiesDepot - [exit] getProperty
65580 [Thread-3] DEBUG common.NetworkAddressManager - Found Linklocal
ipv4 address /192.168.3.44
65580 [Thread-3] DEBUG common.NetworkAddressManager - Returning link
local address
65581 [Thread-3] TRACE common.NetworkAddressManager - [exit] getLocalHost
65581 [Thread-3] DEBUG media.MediaManager - returning - 192.168.3.44
65581 [Thread-3] TRACE media.MediaManager - [exit] getLocalHost
65587 [Thread-3] TRACE media.MediaManager - [entry] getLocalHost
65587 [Thread-3] TRACE common.PropertiesDepot - [entry] getProperty
65587 [Thread-3] TRACE common.Utils - [entry] getSystemProperty
65587 [Thread-3] TRACE common.Utils - [exit] getSystemProperty
65587 [Thread-3] TRACE common.PropertiesDepot - [exit] getProperty
65588 [Thread-3] TRACE common.NetworkAddressManager - [entry] getLocalHost
65588 [Thread-3] TRACE common.PropertiesDepot - [entry] getProperty
65588 [Thread-3] TRACE common.Utils - [entry] getSystemProperty
65588 [Thread-3] TRACE common.Utils - [exit] getSystemProperty
65588 [Thread-3] TRACE common.PropertiesDepot - [exit] getProperty
65588 [Thread-3] TRACE common.PropertiesDepot - [entry] getProperty
65588 [Thread-3] TRACE common.Utils - [entry] getSystemProperty
65589 [Thread-3] TRACE common.Utils - [exit] getSystemProperty
65589 [Thread-3] TRACE common.PropertiesDepot - [exit] getProperty
65589 [Thread-3] DEBUG common.NetworkAddressManager - Found Linklocal
ipv4 address /192.168.3.44
65589 [Thread-3] DEBUG common.NetworkAddressManager - Returning link
local address
65589 [Thread-3] TRACE common.NetworkAddressManager - [exit] getLocalHost
65589 [Thread-3] DEBUG media.MediaManager - returning - 192.168.3.44
65589 [Thread-3] TRACE media.MediaManager - [exit] getLocalHost
65589 [Thread-3] TRACE media.MediaManager - [entry] getLocalHost
65589 [Thread-3] TRACE common.PropertiesDepot - [entry] getProperty
65590 [Thread-3] TRACE common.Utils - [entry] getSystemProperty
65590 [Thread-3] TRACE common.Utils - [exit] getSystemProperty
65590 [Thread-3] TRACE common.PropertiesDepot - [exit] getProperty
65590 [Thread-3] TRACE common.NetworkAddressManager - [entry] getLocalHost
65590 [Thread-3] TRACE common.PropertiesDepot - [entry] getProperty
65590 [Thread-3] TRACE common.Utils - [entry] getSystemProperty
65590 [Thread-3] TRACE common.Utils - [exit] getSystemProperty
65591 [Thread-3] TRACE common.PropertiesDepot - [exit] getProperty
65591 [Thread-3] TRACE common.PropertiesDepot - [entry] getProperty
65591 [Thread-3] TRACE common.Utils - [entry] getSystemProperty
65591 [Thread-3] TRACE common.Utils - [exit] getSystemProperty
65591 [Thread-3] TRACE common.PropertiesDepot - [exit] getProperty
65591 [Thread-3] DEBUG common.NetworkAddressManager - Found Linklocal
ipv4 address /192.168.3.44
65591 [Thread-3] DEBUG common.NetworkAddressManager - Returning link
local address
65591 [Thread-3] TRACE common.NetworkAddressManager - [exit] getLocalHost
65591 [Thread-3] DEBUG media.MediaManager - returning - 192.168.3.44
65592 [Thread-3] TRACE media.MediaManager - [exit] getLocalHost
65603 [RTPEventHandler] TRACE media.AVReceiver - [exit] update
65603 [JMF thread: SendEventQueue: com.sun.media.content.unknown.Handler]
TRACE media.AVReceiver - [entry] controllerUpdate
65603 [JMF thread: SendEventQueue: com.sun.media.content.unknown.Handler]
TRACE media.AVReceiver - [exit] controllerUpdate
65604 [Thread-3] TRACE common.PropertiesDepot - [entry] getProperty
65604 [Thread-3] TRACE common.Utils - [entry] getSystemProperty
65605 [Thread-3] TRACE common.Utils - [exit] getSystemProperty
65605 [Thread-3] TRACE common.PropertiesDepot - [exit] getProperty
65605 [Thread-3] DEBUG media.AVReceiver - MEDIA_BUFFER_LENGTH length not
specified using default 350
java.lang.NumberFormatException: null
  at java.lang.Integer.parseInt(Integer.java:436)
  at java.lang.Integer.parseInt(Integer.java:518)
  at
net.java.sip.communicator.media.AVReceiver.initialize(AVReceiver.java:193)
  at
net.java.sip.communicator.media.MediaManager.startReceiver(MediaManager.java:543)
  at
net.java.sip.communicator.media.MediaManager.openMediaStreams(MediaManager.java:424)
  at
net.java.sip.communicator.SipCommunicator.callStateChanged(SipCommunicator.java:722)
  at
net.java.sip.communicator.sip.Call.fireCallStatusChangedEvent(Call.java:250)
  at net.java.sip.communicator.sip.Call.setState(Call.java:156)
  at
net.java.sip.communicator.sip.CallProcessing.processInviteOK(CallProcessing.java:239)
  at
net.java.sip.communicator.sip.SipManager.processResponse(SipManager.java:1628)
  at gov.nist.javax.sip.EventScanner.run(EventScanner.java:218)
  at java.lang.Thread.run(Thread.java:534)
65607 [Thread-3] TRACE media.AVReceiver - [exit] initialize
65607 [Thread-3] TRACE media.MediaManager - [exit] startReceiver
65608 [Thread-3] TRACE media.MediaManager - [entry] startTransmitter
65616 [Thread-3] TRACE media.AVTransmitter - [entry] <init>
65616 [Thread-3] DEBUG media.AVTransmitter - Created transmitter for
[192.168.3.18] at ports: [13692] encoded as: [[gsm/rtp, ULAW/rtp, alaw]]
65616 [Thread-3] TRACE media.AVTransmitter - [exit] <init>
65616 [Thread-3] DEBUG media.MediaManager - Starting transmission.
65616 [Thread-3] TRACE media.AVTransmitter - [entry] start
65616 [Thread-3] TRACE media.AVTransmitter - [entry] configureProcessor
65618 [Thread-3] DEBUG media.AVTransmitter - Available encodings are:
65619 [Thread-3] DEBUG media.AVTransmitter - track[1] format[1]=dvi/rtp
65619 [Thread-3] DEBUG media.AVTransmitter - track[1] format[2]=dvi/rtp
65619 [Thread-3] DEBUG media.AVTransmitter - track[1] format[3]=dvi/rtp
65619 [Thread-3] DEBUG media.AVTransmitter - track[1] format[4]=ULAW/rtp
65619 [Thread-3] DEBUG media.AVTransmitter - track[1] format[5]=gsm/rtp
65619 [Thread-3] TRACE media.AVTransmitter - [entry] findFirstMatchingFormat
65619 [Thread-3] TRACE media.AVTransmitter - [exit] findFirstMatchingFormat
65620 [Thread-3] DEBUG media.AVTransmitter - Track 0 is set to transmit
as: gsm/rtp, 8000.0 Hz, Mono, FrameSize=264 bits
65621 [JMF thread: SendEventQueue:
com.sun.media.processor.unknown.Handler] TRACE media.AVTransmitter -
[entry] controllerUpdate
65621 [JMF thread: SendEventQueue:
com.sun.media.processor.unknown.Handler] TRACE media.AVTransmitter -
[exit] controllerUpdate
65952 [JMF thread: SendEventQueue:
com.sun.media.processor.unknown.Handler] TRACE media.AVTransmitter -
[entry] controllerUpdate
65952 [JMF thread: SendEventQueue:
com.sun.media.processor.unknown.Handler] TRACE media.AVTransmitter -
[exit] controllerUpdate
65952 [Thread-3] TRACE media.AVTransmitter - [entry] setJPEGQuality
65955 [Thread-3] TRACE media.AVTransmitter - [exit] setJPEGQuality
65955 [Thread-3] TRACE media.AVTransmitter - [exit] configureProcessor
65956 [Thread-3] TRACE media.AVTransmitter - [entry] createTransmitter
65956 [Thread-3] DEBUG media.AVTransmitter - data sources - 1
65956 [Thread-3] TRACE media.AVTransmitter - [entry] findPortForFormat
65956 [Thread-3] TRACE media.AVTransmitter - [exit] findPortForFormat
65956 [Thread-3] TRACE media.MediaManager - [entry] getLocalHost
65956 [Thread-3] TRACE common.PropertiesDepot - [entry] getProperty
65956 [Thread-3] TRACE common.Utils - [entry] getSystemProperty
65957 [Thread-3] TRACE common.Utils - [exit] getSystemProperty
65957 [Thread-3] TRACE common.PropertiesDepot - [exit] getProperty
65957 [Thread-3] TRACE common.NetworkAddressManager - [entry] getLocalHost
65957 [Thread-3] TRACE common.PropertiesDepot - [entry] getProperty
65957 [Thread-3] TRACE common.Utils - [entry] getSystemProperty
65957 [Thread-3] TRACE common.Utils - [exit] getSystemProperty
65957 [Thread-3] TRACE common.PropertiesDepot - [exit] getProperty
65958 [Thread-3] TRACE common.PropertiesDepot - [entry] getProperty
65958 [Thread-3] TRACE common.Utils - [entry] getSystemProperty
65958 [Thread-3] TRACE common.Utils - [exit] getSystemProperty
65958 [Thread-3] TRACE common.PropertiesDepot - [exit] getProperty
65958 [Thread-3] DEBUG common.NetworkAddressManager - Found Linklocal
ipv4 address /192.168.3.44
65958 [Thread-3] DEBUG common.NetworkAddressManager - Returning link
local address
65958 [Thread-3] TRACE common.NetworkAddressManager - [exit] getLocalHost
65958 [Thread-3] DEBUG media.MediaManager - returning - 192.168.3.44
65959 [Thread-3] TRACE media.MediaManager - [exit] getLocalHost
65962 [Thread-3] DEBUG media.AVTransmitter - Just bond to port13692
65971 [Thread-3] DEBUG media.AVTransmitter - Started transmitting track 0
encoded as gsm/rtp @ [192.168.3.18]:13692!
65971 [Thread-3] TRACE media.AVTransmitter - [exit] createTransmitter
65972 [Thread-3] TRACE media.AVTransmitter - [exit] start
65972 [Thread-3] TRACE media.MediaManager - [exit] startTransmitter
65973 [Thread-3] TRACE media.MediaManager - [exit] openMediaStreams
65973 [Thread-3] TRACE communicator.SipCommunicator - [exit]
callStateChanged
65973 [Thread-3] TRACE sip.Call - [exit] setState
65973 [Thread-3] TRACE sip.CallProcessing - [exit] processInviteOK
65973 [Thread-3] TRACE sip.SipManager - [exit] processResponse
65973 [JMF thread: SendEventQueue:
com.sun.media.processor.unknown.Handler] TRACE media.AVTransmitter -
[entry] controllerUpdate
65973 [JMF thread: SendEventQueue:
com.sun.media.processor.unknown.Handler] TRACE media.AVTransmitter -
[exit] controllerUpdate
65974 [JMF thread: SendEventQueue:
com.sun.media.processor.unknown.Handler] TRACE media.AVTransmitter -
[entry] controllerUpdate
65974 [JMF thread: SendEventQueue:
com.sun.media.processor.unknown.Handler] TRACE media.AVTransmitter -
[exit] controllerUpdate
65976 [JMF thread: SendEventQueue:
com.sun.media.processor.unknown.Handler] TRACE media.AVTransmitter -
[entry] controllerUpdate
65976 [JMF thread: SendEventQueue:
com.sun.media.processor.unknown.Handler] TRACE media.AVTransmitter -
[exit]
---
at this point I clicked hangup. those logs aren't shown.

---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscribe@sip-communicator.dev.java.net
For additional commands, e-mail: dev-help@sip-communicator.dev.java.net

---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscribe@sip-communicator.dev.java.net
For additional commands, e-mail: dev-help@sip-communicator.dev.java.net