[sip-comm] Sip-communicator not worki


#1

Hello everyone in the list.

This is my firts message on this list.

I just downloaded and installed sip-communicator and I can't make It work correctly.

I'm working in a Linux machine running Mdk9.1, I installed both the Java JMF and java-sip (from nist) without problems.
I find different problems installing and trying to use sip-communicator

In the first place the places where the program seems to search for the configuration file has nothing to do with what is said in the Manual:

sip-communicator.properties (2.24 KB)

···

##############
3. Configuration
.....manually modify the SipCommunicator.properties file. The latter could be either in the nist-sip-1.2/src/net/java/sip/communicator(???) directory or in $HOME/.sip-communicator.....
##############
It seems to me that the program only find the configuration file if it's
called sip-communicator.properties and only if it's in "the same" directory from where I'm running the program. Is this an error or the expected behavior? (anyway the classpath in the sip-communicator.sh script only works if I use it from the sip-communicator directory, but It was easy to fix)

Second, I'm trying to make sip-comm register with an asterisk pbx server. It's tested and works perfectly with various other SIP clients (X-lite, SJPhone, KPhone, etc) but doesn't work with sip-communicator. First it complains about not knowing how to handle the "100 Trying" response (It seems rather strange to me). However, after I close
the warning dialogs, sometimes it's able to register sometimes not :frowning:

If it gets registered, I try to make a call and I can ring the other phone but my sip-communicator never realizes when the other side responds
Looking at the trace logs I see the RINGING and OK responses but sip-comm doesn't seems to know about it??

It's not a problem only with asterisk because I tried to call (or receive a call) directly from other sip agent (SJPhone, X-lite) and always the same seems to happen, I see the response messages in the logs but sip-communicator doesn't seems to notice it.
Does anybody have tested sip-communicator using asterisk or another server as proxy or register?

If I remove all references to the asterisk server (I leave empty the
REGISTRAR_ADDRESS and OUTBOUND_PROXY options) and I call a SJPhone sip client directly again, now It seems to connect but gives the following error:
********************
net.java.sip.communicator.media.MediaException: Couldn't set any of the tracks to a valid RTP format

at net.java.sip.communicator.media.AVTransmitter.configureProcessor(AVTransmitter.java:287)
at net.java.sip.communicator.media.AVTransmitter.start(AVTransmitter.java:140)
at net.java.sip.communicator.media.MediaManager.startTransmitter(MediaManager.java:452)
at net.java.sip.communicator.media.MediaManager.openMediaStreams(MediaManager.java:387)
at net.java.sip.communicator.SipCommunicator.callStateChanged(SipCommunicator.java:614)
at net.java.sip.communicator.sip.Call.fireCallStatusChangedEvent(Call.java:247)
at net.java.sip.communicator.sip.Call.setState(Call.java:153)
at net.java.sip.communicator.sip.CallProcessing.processInviteOK(CallProcessing.java:236)
at net.java.sip.communicator.sip.SipManager.processResponse(SipManager.java:1418)
at gov.nist.javax.sip.SipProviderImpl.run(SipProviderImpl.java:195)
at java.lang.Thread.run(Thread.java:536)
**********************

I send attached the .properties file I'm using. I have tried with diferent combinations but nothing seems work.

Thanks for any help

Luis


#2

Hello Luis,

I just downloaded and installed sip-communicator and I can't make It
work correctly.

Now that's a shame :slight_smile:

I'm working in a Linux machine running Mdk9.1, I installed both the Java
JMF and java-sip (from nist) without problems.
I find different problems installing and trying to use sip-communicator

In the first place the places where the program seems to search for the
configuration file has nothing to do with what is said in the Manual:
##############
3. Configuration
.....manually modify the SipCommunicator.properties file. The latter
could be either in the nist-sip-1.2/src/net/java/sip/communicator(???)
directory or in $HOME/.sip-communicator.....
##############
It seems to me that the program only find the configuration file if it's
called sip-communicator.properties and only if it's in "the same"
directory from where I'm running the program. Is this an error or the
expected behavior?

Yup this is the expected behavior. It's the manual that's apparently
quite out of date! Thanks for reporting it!

(anyway the classpath in the sip-communicator.sh
script only works if I use it from the sip-communicator directory, but
It was easy to fix)

Would you want to contribute your fix?

Second, I'm trying to make sip-comm register with an asterisk pbx
server. It's tested and works perfectly with various other SIP clients
(X-lite, SJPhone, KPhone, etc) but doesn't work with sip-communicator.
First it complains about not knowing how to handle the "100 Trying"
response (It seems rather strange to me). However, after I close
the warning dialogs, sometimes it's able to register sometimes not :frowning:

This is likely to be a problem with transactions.
Sip Communicator has been tested against the NIST registrar and
iptel.org's Sip Express Router but they are both rfc3261 compliant.
Are you using a 3261 or an rfc2543 compliant Registrar?

Anyways, let's try and see what's wrong with this asterisk pbx. Could
you please send me the traces from the /log dir?

If it gets registered, I try to make a call and I can ring the other
phone but my sip-communicator never realizes when the other side responds
Looking at the trace logs I see the RINGING and OK responses but
sip-comm doesn't seems to know about it??

Same here can I see the log files?

If I remove all references to the asterisk server (I leave empty the
REGISTRAR_ADDRESS and OUTBOUND_PROXY options) and I call a SJPhone sip
client directly again, now It seems to connect but gives the following
error:
********************
net.java.sip.communicator.media.MediaException: Couldn't set any of the
tracks to a valid RTP format

at
net.java.sip.communicator.media.AVTransmitter.configureProcessor(AVTransmitter.java:287)
at
net.java.sip.communicator.media.AVTransmitter.start(AVTransmitter.java:140)
at
net.java.sip.communicator.media.MediaManager.startTransmitter(MediaManager.java:452)
at
net.java.sip.communicator.media.MediaManager.openMediaStreams(MediaManager.java:387)
at
net.java.sip.communicator.SipCommunicator.callStateChanged(SipCommunicator.java:614)
at
net.java.sip.communicator.sip.Call.fireCallStatusChangedEvent(Call.java:247)
at net.java.sip.communicator.sip.Call.setState(Call.java:153)
at
net.java.sip.communicator.sip.CallProcessing.processInviteOK(CallProcessing.java:236)
at
net.java.sip.communicator.sip.SipManager.processResponse(SipManager.java:1418)
at gov.nist.javax.sip.SipProviderImpl.run(SipProviderImpl.java:195)
at java.lang.Thread.run(Thread.java:536)
**********************

This one seems to be a problem with your JMF installation. If so it
could be seen in the log files

I send attached the .properties file I'm using. I have tried with
diferent combinations but nothing seems work.

Thanks for any help

Thanks for your Patience

Cheers
Emil

http://www.emcho.com

···

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


#3

Emil Ivov wrote:

In the first place the places where the program seems to search for the configuration file has nothing to do with what is said in the Manual:
##############
3. Configuration
.....manually modify the SipCommunicator.properties file. The latter could be either in the nist-sip-1.2/src/net/java/sip/communicator(???) directory or in $HOME/.sip-communicator.....
##############
It seems to me that the program only find the configuration file if it's
called sip-communicator.properties and only if it's in "the same" directory from where I'm running the program. Is this an error or the expected behavior?
   
Yup this is the expected behavior. It's the manual that's apparently
quite out of date! Thanks for reporting it!

Ok,... anyway I think it would be much better (at least in the unix-like world) if the
configuration file would be taken by default from $HOME/.sip-communicator... (whatever).
This way is much simpler to have a per-user configuration, and being able to run
sip-communicator form anywhere (just making a simbolic link from /usr/local/bin to
sip-comunicator.sh 's location)

(anyway the classpath in the sip-communicator.sh script only works if I use it from the sip-communicator directory, but It was easy to fix)
   
Would you want to contribute your fix?

Now I'm using the buildclass tool from the jpackage.org project's jpackage-utils like this:

java -classpath `build-classpath jain-sip sip-communicator`:$JMFHOME/lib/jmf.jar -Dlog4j.configuration=sip-communicator.properties net.java.sip.communicator.SipCommunicator

and only need to have the jain-sip and sip-communicator directories under /usr/share/java with
the relevant .jar files there.

One more question: What is the -Dlog4j.configuration option? I suppose it's related with the
log4j.* entries in sip-communicator.properties and this with the logging functions... but these
parameters are not taken from sip-communicator.properties by default anyway??
Does exist any reference to the command options supported by SipCommunicator?
Where can I get a list of properties's description more complete that the one in the Manual?
Or the only way is "going to the sources" :wink: ?

For example: Can I ask from the command for a diferent properties file to use?

Tanks very much for your attention (and for your nice work :slight_smile:
Best regards
Luis


#4

Emil Ivov wrote:
As you asked me, I send the trace logs.
This time I have set the asterisk's IP addres in the *REGISTRAR_ADDRESS*

This is likely to be a problem with transactions.
Sip Communicator has been tested against the NIST registrar and
iptel.org's Sip Express Router but they are both rfc3261 compliant.
Are you using a 3261 or an rfc2543 compliant Registrar?

Anyways, let's try and see what's wrong with this asterisk pbx. Could
you please send me the traces from the /log dir

I'm able to register (well at least sometimes :slight_smile: but with complains about not
knowing how to handle TRYING responses:
1- I run sip-comunicator:

···

*************************************************************************************************
0 [main] TRACE communicator.SipCommunicator - [entry] main
2579 [main] TRACE communicator.SipCommunicator - [entry] <init>
2583 [main] TRACE communicator.SipCommunicator - [entry] getPropertiesFile
2590 [main] TRACE common.Utils - [entry] getProperty
2594 [main] TRACE common.Utils - [exit] getProperty
2599 [main] TRACE communicator.SipCommunicator - [exit] getPropertiesFile
2605 [main] TRACE communicator.SipCommunicator - [exit] <init>
2609 [main] TRACE communicator.SipCommunicator - [entry] launch
2613 [main] TRACE media.MediaManager - [entry] start
2684 [main] TRACE common.Utils - [entry] getProperty
2688 [main] TRACE common.Utils - [exit] getProperty
2692 [main] DEBUG media.MediaManager - No media source property was set. Going for capture devices
3225 [main] TRACE media.MediaManager - [entry] createDataSource
3690 [main] TRACE media.MediaManager - [exit] createDataSource
3697 [main] TRACE media.MediaManager - [entry] initProcessor
4678 [main] DEBUG media.MediaManager - We will be able to transmit in:
5015 [main] DEBUG media.MediaManager - Audio=[1]=dvi/rtp; sdp=5
5018 [main] DEBUG media.MediaManager - Audio=[4]=g723/rtp; sdp=4
5022 [main] DEBUG media.MediaManager - Audio=[5]=gsm/rtp; sdp=3
5026 [main] DEBUG media.MediaManager - Audio=[6]=ULAW/rtp; sdp=0
5030 [main] TRACE media.MediaManager - [exit] initProcessor
5035 [main] TRACE media.MediaManager - [exit] start
5041 [main] TRACE media.MediaManager - [entry] addMediaListener
5045 [main] TRACE media.MediaManager - [exit] addMediaListener
5051 [main] TRACE communicator.SipCommunicator - [entry] initDebugTool
5055 [main] TRACE common.Utils - [entry] getProperty
5061 [main] TRACE common.Utils - [exit] getProperty
5065 [main] TRACE common.Utils - [entry] getProperty
5070 [main] TRACE common.Utils - [exit] getProperty
5076 [main] TRACE common.Utils - [entry] getProperty
5081 [main] TRACE common.Utils - [exit] getProperty
5087 [main] TRACE communicator.SipCommunicator - [exit] initDebugTool
5092 [main] TRACE sip.SipManager - [entry] addCommunicationsListener
5096 [main] TRACE sip.SipManager - [exit] addCommunicationsListener
5399 [main] TRACE sip.SipManager - [entry] start
5403 [main] TRACE sip.SipManager - [entry] initProperties
5404 [main] TRACE common.Utils - [entry] getProperty
5404 [main] TRACE common.Utils - [exit] getProperty
5405 [main] DEBUG sip.SipManager - stack address=192.168.2.176
5406 [main] TRACE common.Utils - [entry] getProperty
5406 [main] TRACE common.Utils - [exit] getProperty
5407 [main] DEBUG sip.SipManager - stack name is:sip-communicator
5407 [main] TRACE common.Utils - [entry] getProperty
5408 [main] TRACE common.Utils - [exit] getProperty
5408 [main] DEBUG sip.SipManager - retransmission filter is:sip-communicator
5408 [main] TRACE common.Utils - [entry] getProperty
5409 [main] TRACE common.Utils - [exit] getProperty
5409 [main] DEBUG sip.SipManager - public address=sip:101@192.168.2.176
5415 [main] TRACE common.Utils - [entry] getProperty
5419 [main] TRACE common.Utils - [exit] getProperty
5422 [main] DEBUG sip.SipManager - registrar address=192.168.2.175
5422 [main] TRACE common.Utils - [entry] getProperty
5423 [main] TRACE common.Utils - [exit] getProperty
5424 [main] DEBUG sip.SipManager - registrar port=5060
5424 [main] TRACE common.Utils - [entry] getProperty
5425 [main] TRACE common.Utils - [exit] getProperty
5425 [main] TRACE common.Utils - [entry] getProperty
5426 [main] TRACE common.Utils - [exit] getProperty
5426 [main] DEBUG sip.SipManager - registrar transport=udp
5426 [main] TRACE common.Utils - [entry] getProperty
5427 [main] TRACE common.Utils - [exit] getProperty
5427 [main] DEBUG sip.SipManager - server log=/tmp/log/sip-communicator.stack.log
5428 [main] TRACE common.Utils - [entry] getProperty
5429 [main] TRACE common.Utils - [exit] getProperty
5429 [main] DEBUG sip.SipManager - stack path=gov.nist
5429 [main] TRACE common.Utils - [entry] getProperty
5435 [main] TRACE common.Utils - [exit] getProperty
5439 [main] DEBUG sip.SipManager - router path=net.java.sip.communicator.sip.SipCommRouter
5443 [main] TRACE common.Utils - [entry] getProperty
5444 [main] TRACE common.Utils - [exit] getProperty
5444 [main] DEBUG sip.SipManager - transport=udp
5445 [main] TRACE common.Utils - [entry] getProperty
5445 [main] TRACE common.Utils - [exit] getProperty
5446 [main] DEBUG sip.SipManager - preferred local port=5060
5446 [main] TRACE common.Utils - [entry] getProperty
5447 [main] TRACE common.Utils - [exit] getProperty
5447 [main] DEBUG sip.SipManager - display name=Luis Vazquez
5447 [main] TRACE sip.SipManager - [exit] initProperties
6360 [main] TRACE security.SipSecurityManager - [entry] setHeaderFactory
6365 [main] TRACE security.SipSecurityManager - [exit] setHeaderFactory
6369 [main] TRACE sip.SipManager - [exit] start
6377 [main] TRACE communicator.SipCommunicator - sipManager appears to be successfully started
6417 [main] TRACE sip.SipManager - [entry] register
6425 [main] TRACE sip.RegisterProcessing - [entry] register
6430 [main] TRACE sip.SipManager - [entry] getFromHeader
6613 [main] DEBUG sip.SipManager - Generated from header: From: "Luis Vazquez" <sip:101@192.168.2.176:5060;transport=udp>;tag=12366398

6618 [main] TRACE sip.SipManager - [exit] getFromHeader
6623 [main] TRACE sip.SipManager - [entry] fireRegistering
6627 [main] DEBUG sip.SipManager - registering with address="Luis Vazquez" <sip:101@192.168.2.176:5060;transport=udp>
6842 [main] TRACE communicator.SipCommunicator - [entry] registering
6853 [main] TRACE communicator.SipCommunicator - [exit] registering
6857 [main] TRACE sip.SipManager - [exit] fireRegistering
7462 [main] TRACE sip.SipManager - [entry] getLocalViaHeaders
7476 [main] DEBUG sip.SipManager - generated via headers:Via: SIP/2.0/UDP 192.168.2.176:5060

7482 [main] TRACE sip.SipManager - [exit] getLocalViaHeaders
7486 [main] TRACE sip.SipManager - [entry] getMaxForwardsHeader
7499 [main] DEBUG sip.SipManager - generate max forwards: Max-Forwards: 70

7504 [main] TRACE sip.SipManager - [exit] getMaxForwardsHeader
7748 [main] TRACE sip.SipManager - [entry] getContactHeader
7754 [main] TRACE sip.SipManager - [entry] getLocalHostAddress
7758 [main] TRACE common.Utils - [entry] getProperty
7764 [main] TRACE common.Utils - [exit] getProperty
7770 [main] DEBUG sip.SipManager - returning addres=192.168.2.176
7775 [main] TRACE sip.SipManager - [exit] getLocalHostAddress
7780 [main] DEBUG sip.SipManager - generated contactHeader:Contact: "Luis Vazquez" <sip:192.168.2.176:5060;transport=udp>

7785 [main] TRACE sip.SipManager - [exit] getContactHeader
hop = 192.168.2.175:5060/udp
7921 [main] DEBUG sip.RegisterProcessing - sent request= REGISTER sip:192.168.2.175:5060;transport=udp SIP/2.0
Call-ID: 4c6cee1dd4139963f0137d79099d35ad@192.168.2.176
CSeq: 1 REGISTER
From: "Luis Vazquez" <sip:101@192.168.2.176:5060;transport=udp>;tag=12366398
To: "Luis Vazquez" <sip:101@192.168.2.176:5060;transport=udp>
Via: SIP/2.0/UDP 192.168.2.176:5060;branch=z9hG4bKbf2848e0f55b84b4358f907430b8a915
Max-Forwards: 70
Expires: 600
Contact: "Luis Vazquez" <sip:192.168.2.176:5060;transport=udp>
Content-Length: 0

7931 [main] TRACE sip.RegisterProcessing - [entry] scheduleReRegistration
7938 [main] TRACE sip.RegisterProcessing - [exit] scheduleReRegistration
7942 [main] TRACE sip.RegisterProcessing - [exit] register
7948 [main] TRACE sip.SipManager - [exit] register
7954 [main] TRACE communicator.SipCommunicator - [exit] launch
7958 [main] TRACE communicator.SipCommunicator - [exit] main
8345 [Thread-5] TRACE sip.SipManager - [entry] processResponse
8346 [Thread-5] DEBUG sip.SipManager - received response=javax.sip.ResponseEvent[source=gov.nist.javax.sip.SipProviderImpl@1db9852]
8445 [Thread-5] TRACE sip.RegisterProcessing - [entry] processAuthenticationChallenge
8450 [Thread-5] TRACE security.SipSecurityManager - [entry] handleChallenge
8518 [Thread-5] TRACE communicator.SipCommunicator - [entry] obtainCredentials

*************************************************************************************************
2- At this point I get a dialog asking for the password in asterisk, then i select authenticate button
and the first

*************************************************************************************************
"SipCommunicator's SipMannager didn't know how to handle ,message 100 Trying in this context!"
*************************************************************************************************
appears. The log messages are:

*************************************************************************************************
24218 [Thread-5] TRACE communicator.SipCommunicator - [exit] obtainCredentials
24223 [Thread-5] TRACE security.MessageDigestAlgorithm - [entry] H
24225 [Thread-5] TRACE security.MessageDigestAlgorithm - [exit] H
24226 [Thread-5] TRACE security.MessageDigestAlgorithm - [entry] H
24227 [Thread-5] TRACE security.MessageDigestAlgorithm - [exit] H
24227 [Thread-5] TRACE security.MessageDigestAlgorithm - [entry] KD
24228 [Thread-5] TRACE security.MessageDigestAlgorithm - [entry] H
24230 [Thread-5] TRACE security.MessageDigestAlgorithm - [exit] H
24231 [Thread-5] TRACE security.MessageDigestAlgorithm - [exit] KD
24233 [Thread-5] TRACE security.SipSecurityManager - [exit] handleChallenge
hop = 192.168.2.175:5060/udp
24239 [Thread-5] TRACE sip.SipManager - [entry] fireUnregistered
24240 [Thread-5] DEBUG sip.SipManager - unregistered, address is "Luis Vazquez" <sip:101@192.168.2.176:5060;transport=udp>
24241 [Thread-5] TRACE communicator.SipCommunicator - [entry] unregistered
24242 [Thread-5] TRACE communicator.SipCommunicator - [exit] unregistered
24243 [Thread-5] TRACE sip.SipManager - [exit] fireUnregistered
24243 [Thread-5] TRACE sip.RegisterProcessing - [exit] processAuthenticationChallenge
24243 [Thread-5] TRACE sip.SipManager - [exit] processResponse
24315 [Thread-5] TRACE sip.SipManager - [entry] processResponse
24319 [Thread-5] DEBUG sip.SipManager - received response=javax.sip.ResponseEvent[source=gov.nist.javax.sip.SipProviderImpl@3cb075]
24349 [Thread-5] TRACE sip.SipManager - [entry] fireUnknownMessageReceived
24353 [Thread-5] DEBUG sip.SipManager - unknown message=SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.2.176:5060;branch=z9hG4bK374c7886bbf320d75051f049e9798ad1
From: "Luis Vazquez" <sip:101@192.168.2.176:5060;transport=udp>;tag=22897006
To: "Luis Vazquez" <sip:101@192.168.2.176:5060;transport=udp>;tag=as573f1074
Call-ID: 2b1672d7eea26bb9036eca057043ebf7@192.168.2.176
CSeq: 2 REGISTER
User-Agent: null
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER
Contact: <sip:101@192.168.2.175>
Content-Length: 0

24360 [Thread-5] TRACE communicator.SipCommunicator - [entry] receivedUnknownMessage
*************************************************************************************************

After I close the dialog and retry a couple of times I get register, with logs

*************************************************************************************************
375643 [Thread-5] TRACE communicator.SipCommunicator - [exit] receivedUnknownMessage
375644 [Thread-5] TRACE sip.SipManager - [exit] fireUnknownMessageReceived
375644 [Thread-5] TRACE sip.SipManager - [exit] processResponse
375650 [Thread-5] TRACE sip.SipManager - [entry] processResponse
375650 [Thread-5] DEBUG sip.SipManager - received response=javax.sip.ResponseEvent[source=gov.nist.javax.sip.SipProviderImpl@3cb075]
375682 [Thread-5] TRACE sip.RegisterProcessing - [entry] processOK
375689 [Thread-5] TRACE sip.SipManager - [entry] fireRegistered
375696 [Thread-5] DEBUG sip.SipManager - registered with address = "Luis Vazquez" <sip:101@192.168.2.176:5060;transport=udp>
375703 [Thread-5] TRACE communicator.SipCommunicator - [entry] registered
375723 [Thread-5] TRACE communicator.SipCommunicator - [exit] registered
375729 [Thread-5] TRACE sip.SipManager - [exit] fireRegistered
375734 [Thread-5] TRACE sip.RegisterProcessing - [exit] processOK
375740 [Thread-5] TRACE sip.SipManager - [exit] processResponse
378747 [Thread-5] TRACE sip.SipManager - [entry] processRequest
378747 [Thread-5] DEBUG sip.SipManager - received request=javax.sip.RequestEvent[source=gov.nist.javax.sip.SipProviderImpl@3cb075]
378789 [Thread-5] TRACE sip.SipManager - [entry] sendNotImplemented
378791 [Thread-5] TRACE sip.SipManager - [entry] attachToTag
378792 [Thread-5] TRACE sip.SipManager - [exit] attachToTag
378793 [Thread-5] TRACE sip.SipManager - [exit] sendNotImplemented
378793 [Thread-5] TRACE sip.SipManager - [exit] processRequest
java.lang.NullPointerException
    at net.java.sip.communicator.sip.SipManager.attachToTag(SipManager.java:844)
    at net.java.sip.communicator.sip.SipManager.sendNotImplemented(SipManager.java:565)
    at net.java.sip.communicator.sip.SipManager.processRequest(SipManager.java:1316)
    at gov.nist.javax.sip.SipProviderImpl.run(SipProviderImpl.java:193)
    at java.lang.Thread.run(Thread.java:536)
****************************************************************

This point I'm registered with asterisk but after a timeout I get unregistered an no way to register
again!! The logs when this happens are:

****************************************************************
606735 [Thread-1] TRACE sip.RegisterProcessing - [entry] run
606736 [Thread-1] TRACE sip.RegisterProcessing - [entry] register
606736 [Thread-1] TRACE sip.SipManager - [entry] getFromHeader
606737 [Thread-1] TRACE sip.SipManager - [exit] getFromHeader
606737 [Thread-1] TRACE sip.SipManager - [entry] fireRegistering
606737 [Thread-1] DEBUG sip.SipManager - registering with address="Luis Vazquez" <sip:101@192.168.2.176:5060;transport=udp>
606738 [Thread-1] TRACE communicator.SipCommunicator - [entry] registering
606739 [Thread-1] TRACE communicator.SipCommunicator - [exit] registering
606739 [Thread-1] TRACE sip.SipManager - [exit] fireRegistering
606742 [Thread-1] TRACE sip.SipManager - [entry] getLocalViaHeaders
606746 [Thread-1] TRACE sip.SipManager - [exit] getLocalViaHeaders
606746 [Thread-1] TRACE sip.SipManager - [entry] getMaxForwardsHeader
606747 [Thread-1] TRACE sip.SipManager - [exit] getMaxForwardsHeader
606747 [Thread-1] TRACE sip.SipManager - [entry] getContactHeader
606748 [Thread-1] TRACE sip.SipManager - [exit] getContactHeader
hop = 192.168.2.175:5060/udp
606753 [Thread-1] DEBUG sip.RegisterProcessing - sent request= REGISTER sip:192.168.2.175:5060;transport=udp SIP/2.0
Call-ID: af4c0b1f4127078c99a0b1a732bc7a25@192.168.2.176
CSeq: 1 REGISTER
From: "Luis Vazquez" <sip:101@192.168.2.176:5060;transport=udp>;tag=22897006
To: "Luis Vazquez" <sip:101@192.168.2.176:5060;transport=udp>
Via: SIP/2.0/UDP 192.168.2.176:5060;branch=z9hG4bK54bd1077fa9e7399ddfa33f3e3b58105
Max-Forwards: 70
Expires: 600
Contact: "Luis Vazquez" <sip:192.168.2.176:5060;transport=udp>
Content-Length: 0

606764 [Thread-1] TRACE sip.RegisterProcessing - [entry] scheduleReRegistration
606765 [Thread-1] TRACE sip.RegisterProcessing - [exit] scheduleReRegistration
606766 [Thread-1] TRACE sip.RegisterProcessing - [exit] register
606766 [Thread-1] TRACE sip.RegisterProcessing - [exit] run
****************************************************************

Next time I'll send the trace when I try to make a call through asterisk.

Bye
Luis


#5

Hello Luis,

Could you please send me the sip-communicator.stack.log
file as well? (again in the log directory)

Cheers
Emil

Emil Ivov wrote:
As you asked me, I send the trace logs.
This time I have set the asterisk's IP addres in the *REGISTRAR_ADDRESS*

This is likely to be a problem with transactions.
Sip Communicator has been tested against the NIST registrar and
iptel.org's Sip Express Router but they are both rfc3261 compliant.
Are you using a 3261 or an rfc2543 compliant Registrar?

Anyways, let's try and see what's wrong with this asterisk pbx. Could
you please send me the traces from the /log dir

I'm able to register (well at least sometimes :slight_smile: but with complains
about not
knowing how to handle TRYING responses:
1- I run sip-comunicator:

*************************************************************************************************
0 [main] TRACE communicator.SipCommunicator - [entry] main
2579 [main] TRACE communicator.SipCommunicator - [entry] <init>
2583 [main] TRACE communicator.SipCommunicator - [entry] getPropertiesFile
2590 [main] TRACE common.Utils - [entry] getProperty
2594 [main] TRACE common.Utils - [exit] getProperty
2599 [main] TRACE communicator.SipCommunicator - [exit] getPropertiesFile
2605 [main] TRACE communicator.SipCommunicator - [exit] <init>
2609 [main] TRACE communicator.SipCommunicator - [entry] launch
2613 [main] TRACE media.MediaManager - [entry] start
2684 [main] TRACE common.Utils - [entry] getProperty
2688 [main] TRACE common.Utils - [exit] getProperty
2692 [main] DEBUG media.MediaManager - No media source property was
set. Going for capture devices
3225 [main] TRACE media.MediaManager - [entry] createDataSource
3690 [main] TRACE media.MediaManager - [exit] createDataSource
3697 [main] TRACE media.MediaManager - [entry] initProcessor
4678 [main] DEBUG media.MediaManager - We will be able to transmit in:
5015 [main] DEBUG media.MediaManager - Audio=[1]=dvi/rtp; sdp=5
5018 [main] DEBUG media.MediaManager - Audio=[4]=g723/rtp; sdp=4
5022 [main] DEBUG media.MediaManager - Audio=[5]=gsm/rtp; sdp=3
5026 [main] DEBUG media.MediaManager - Audio=[6]=ULAW/rtp; sdp=0
5030 [main] TRACE media.MediaManager - [exit] initProcessor
5035 [main] TRACE media.MediaManager - [exit] start
5041 [main] TRACE media.MediaManager - [entry] addMediaListener
5045 [main] TRACE media.MediaManager - [exit] addMediaListener
5051 [main] TRACE communicator.SipCommunicator - [entry] initDebugTool
5055 [main] TRACE common.Utils - [entry] getProperty
5061 [main] TRACE common.Utils - [exit] getProperty
5065 [main] TRACE common.Utils - [entry] getProperty
5070 [main] TRACE common.Utils - [exit] getProperty
5076 [main] TRACE common.Utils - [entry] getProperty
5081 [main] TRACE common.Utils - [exit] getProperty
5087 [main] TRACE communicator.SipCommunicator - [exit] initDebugTool
5092 [main] TRACE sip.SipManager - [entry] addCommunicationsListener
5096 [main] TRACE sip.SipManager - [exit] addCommunicationsListener
5399 [main] TRACE sip.SipManager - [entry] start
5403 [main] TRACE sip.SipManager - [entry] initProperties
5404 [main] TRACE common.Utils - [entry] getProperty
5404 [main] TRACE common.Utils - [exit] getProperty
5405 [main] DEBUG sip.SipManager - stack address=192.168.2.176
5406 [main] TRACE common.Utils - [entry] getProperty
5406 [main] TRACE common.Utils - [exit] getProperty
5407 [main] DEBUG sip.SipManager - stack name is:sip-communicator
5407 [main] TRACE common.Utils - [entry] getProperty
5408 [main] TRACE common.Utils - [exit] getProperty
5408 [main] DEBUG sip.SipManager - retransmission filter
is:sip-communicator
5408 [main] TRACE common.Utils - [entry] getProperty
5409 [main] TRACE common.Utils - [exit] getProperty
5409 [main] DEBUG sip.SipManager - public address=sip:101@192.168.2.176
5415 [main] TRACE common.Utils - [entry] getProperty
5419 [main] TRACE common.Utils - [exit] getProperty
5422 [main] DEBUG sip.SipManager - registrar address=192.168.2.175
5422 [main] TRACE common.Utils - [entry] getProperty
5423 [main] TRACE common.Utils - [exit] getProperty
5424 [main] DEBUG sip.SipManager - registrar port=5060
5424 [main] TRACE common.Utils - [entry] getProperty
5425 [main] TRACE common.Utils - [exit] getProperty
5425 [main] TRACE common.Utils - [entry] getProperty
5426 [main] TRACE common.Utils - [exit] getProperty
5426 [main] DEBUG sip.SipManager - registrar transport=udp
5426 [main] TRACE common.Utils - [entry] getProperty
5427 [main] TRACE common.Utils - [exit] getProperty
5427 [main] DEBUG sip.SipManager - server
log=/tmp/log/sip-communicator.stack.log
5428 [main] TRACE common.Utils - [entry] getProperty
5429 [main] TRACE common.Utils - [exit] getProperty
5429 [main] DEBUG sip.SipManager - stack path=gov.nist
5429 [main] TRACE common.Utils - [entry] getProperty
5435 [main] TRACE common.Utils - [exit] getProperty
5439 [main] DEBUG sip.SipManager - router
path=net.java.sip.communicator.sip.SipCommRouter
5443 [main] TRACE common.Utils - [entry] getProperty
5444 [main] TRACE common.Utils - [exit] getProperty
5444 [main] DEBUG sip.SipManager - transport=udp
5445 [main] TRACE common.Utils - [entry] getProperty
5445 [main] TRACE common.Utils - [exit] getProperty
5446 [main] DEBUG sip.SipManager - preferred local port=5060
5446 [main] TRACE common.Utils - [entry] getProperty
5447 [main] TRACE common.Utils - [exit] getProperty
5447 [main] DEBUG sip.SipManager - display name=Luis Vazquez
5447 [main] TRACE sip.SipManager - [exit] initProperties
6360 [main] TRACE security.SipSecurityManager - [entry] setHeaderFactory
6365 [main] TRACE security.SipSecurityManager - [exit] setHeaderFactory
6369 [main] TRACE sip.SipManager - [exit] start
6377 [main] TRACE communicator.SipCommunicator - sipManager appears to
be successfully started
6417 [main] TRACE sip.SipManager - [entry] register
6425 [main] TRACE sip.RegisterProcessing - [entry] register
6430 [main] TRACE sip.SipManager - [entry] getFromHeader
6613 [main] DEBUG sip.SipManager - Generated from header: From: "Luis
Vazquez" <sip:101@192.168.2.176:5060;transport=udp>;tag=12366398

6618 [main] TRACE sip.SipManager - [exit] getFromHeader
6623 [main] TRACE sip.SipManager - [entry] fireRegistering
6627 [main] DEBUG sip.SipManager - registering with address="Luis
Vazquez" <sip:101@192.168.2.176:5060;transport=udp>
6842 [main] TRACE communicator.SipCommunicator - [entry] registering
6853 [main] TRACE communicator.SipCommunicator - [exit] registering
6857 [main] TRACE sip.SipManager - [exit] fireRegistering
7462 [main] TRACE sip.SipManager - [entry] getLocalViaHeaders
7476 [main] DEBUG sip.SipManager - generated via headers:Via:
SIP/2.0/UDP 192.168.2.176:5060

7482 [main] TRACE sip.SipManager - [exit] getLocalViaHeaders
7486 [main] TRACE sip.SipManager - [entry] getMaxForwardsHeader
7499 [main] DEBUG sip.SipManager - generate max forwards: Max-Forwards: 70

7504 [main] TRACE sip.SipManager - [exit] getMaxForwardsHeader
7748 [main] TRACE sip.SipManager - [entry] getContactHeader
7754 [main] TRACE sip.SipManager - [entry] getLocalHostAddress
7758 [main] TRACE common.Utils - [entry] getProperty
7764 [main] TRACE common.Utils - [exit] getProperty
7770 [main] DEBUG sip.SipManager - returning addres=192.168.2.176
7775 [main] TRACE sip.SipManager - [exit] getLocalHostAddress
7780 [main] DEBUG sip.SipManager - generated contactHeader:Contact:
"Luis Vazquez" <sip:192.168.2.176:5060;transport=udp>

7785 [main] TRACE sip.SipManager - [exit] getContactHeader
hop = 192.168.2.175:5060/udp
7921 [main] DEBUG sip.RegisterProcessing - sent request= REGISTER
sip:192.168.2.175:5060;transport=udp SIP/2.0
Call-ID: 4c6cee1dd4139963f0137d79099d35ad@192.168.2.176
CSeq: 1 REGISTER
From: "Luis Vazquez" <sip:101@192.168.2.176:5060;transport=udp>;tag=12366398
To: "Luis Vazquez" <sip:101@192.168.2.176:5060;transport=udp>
Via: SIP/2.0/UDP
192.168.2.176:5060;branch=z9hG4bKbf2848e0f55b84b4358f907430b8a915
Max-Forwards: 70
Expires: 600
Contact: "Luis Vazquez" <sip:192.168.2.176:5060;transport=udp>
Content-Length: 0

7931 [main] TRACE sip.RegisterProcessing - [entry] scheduleReRegistration
7938 [main] TRACE sip.RegisterProcessing - [exit] scheduleReRegistration
7942 [main] TRACE sip.RegisterProcessing - [exit] register
7948 [main] TRACE sip.SipManager - [exit] register
7954 [main] TRACE communicator.SipCommunicator - [exit] launch
7958 [main] TRACE communicator.SipCommunicator - [exit] main
8345 [Thread-5] TRACE sip.SipManager - [entry] processResponse
8346 [Thread-5] DEBUG sip.SipManager - received
response=javax.sip.ResponseEvent[source=gov.nist.javax.sip.SipProviderImpl@1db9852]
8445 [Thread-5] TRACE sip.RegisterProcessing - [entry]
processAuthenticationChallenge
8450 [Thread-5] TRACE security.SipSecurityManager - [entry] handleChallenge
8518 [Thread-5] TRACE communicator.SipCommunicator - [entry]
obtainCredentials

*************************************************************************************************
2- At this point I get a dialog asking for the password in asterisk,
then i select authenticate button
and the first

*************************************************************************************************
"SipCommunicator's SipMannager didn't know how to handle ,message 100
Trying in this context!"
*************************************************************************************************
appears. The log messages are:

*************************************************************************************************
24218 [Thread-5] TRACE communicator.SipCommunicator - [exit]
obtainCredentials
24223 [Thread-5] TRACE security.MessageDigestAlgorithm - [entry] H
24225 [Thread-5] TRACE security.MessageDigestAlgorithm - [exit] H
24226 [Thread-5] TRACE security.MessageDigestAlgorithm - [entry] H
24227 [Thread-5] TRACE security.MessageDigestAlgorithm - [exit] H
24227 [Thread-5] TRACE security.MessageDigestAlgorithm - [entry] KD
24228 [Thread-5] TRACE security.MessageDigestAlgorithm - [entry] H
24230 [Thread-5] TRACE security.MessageDigestAlgorithm - [exit] H
24231 [Thread-5] TRACE security.MessageDigestAlgorithm - [exit] KD
24233 [Thread-5] TRACE security.SipSecurityManager - [exit] handleChallenge
hop = 192.168.2.175:5060/udp
24239 [Thread-5] TRACE sip.SipManager - [entry] fireUnregistered
24240 [Thread-5] DEBUG sip.SipManager - unregistered, address is "Luis
Vazquez" <sip:101@192.168.2.176:5060;transport=udp>
24241 [Thread-5] TRACE communicator.SipCommunicator - [entry] unregistered
24242 [Thread-5] TRACE communicator.SipCommunicator - [exit] unregistered
24243 [Thread-5] TRACE sip.SipManager - [exit] fireUnregistered
24243 [Thread-5] TRACE sip.RegisterProcessing - [exit]
processAuthenticationChallenge
24243 [Thread-5] TRACE sip.SipManager - [exit] processResponse
24315 [Thread-5] TRACE sip.SipManager - [entry] processResponse
24319 [Thread-5] DEBUG sip.SipManager - received
response=javax.sip.ResponseEvent[source=gov.nist.javax.sip.SipProviderImpl@3cb075]
24349 [Thread-5] TRACE sip.SipManager - [entry] fireUnknownMessageReceived
24353 [Thread-5] DEBUG sip.SipManager - unknown message=SIP/2.0 100 Trying
Via: SIP/2.0/UDP
192.168.2.176:5060;branch=z9hG4bK374c7886bbf320d75051f049e9798ad1
From: "Luis Vazquez" <sip:101@192.168.2.176:5060;transport=udp>;tag=22897006
To: "Luis Vazquez" <sip:101@192.168.2.176:5060;transport=udp>;tag=as573f1074
Call-ID: 2b1672d7eea26bb9036eca057043ebf7@192.168.2.176
CSeq: 2 REGISTER
User-Agent: null
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER
Contact: <sip:101@192.168.2.175>
Content-Length: 0

24360 [Thread-5] TRACE communicator.SipCommunicator - [entry]
receivedUnknownMessage
*************************************************************************************************

After I close the dialog and retry a couple of times I get register,
with logs

*************************************************************************************************
375643 [Thread-5] TRACE communicator.SipCommunicator - [exit]
receivedUnknownMessage
375644 [Thread-5] TRACE sip.SipManager - [exit] fireUnknownMessageReceived
375644 [Thread-5] TRACE sip.SipManager - [exit] processResponse
375650 [Thread-5] TRACE sip.SipManager - [entry] processResponse
375650 [Thread-5] DEBUG sip.SipManager - received
response=javax.sip.ResponseEvent[source=gov.nist.javax.sip.SipProviderImpl@3cb075]
375682 [Thread-5] TRACE sip.RegisterProcessing - [entry] processOK
375689 [Thread-5] TRACE sip.SipManager - [entry] fireRegistered
375696 [Thread-5] DEBUG sip.SipManager - registered with address =
"Luis Vazquez" <sip:101@192.168.2.176:5060;transport=udp>
375703 [Thread-5] TRACE communicator.SipCommunicator - [entry] registered
375723 [Thread-5] TRACE communicator.SipCommunicator - [exit] registered
375729 [Thread-5] TRACE sip.SipManager - [exit] fireRegistered
375734 [Thread-5] TRACE sip.RegisterProcessing - [exit] processOK
375740 [Thread-5] TRACE sip.SipManager - [exit] processResponse
378747 [Thread-5] TRACE sip.SipManager - [entry] processRequest
378747 [Thread-5] DEBUG sip.SipManager - received
request=javax.sip.RequestEvent[source=gov.nist.javax.sip.SipProviderImpl@3cb075]
378789 [Thread-5] TRACE sip.SipManager - [entry] sendNotImplemented
378791 [Thread-5] TRACE sip.SipManager - [entry] attachToTag
378792 [Thread-5] TRACE sip.SipManager - [exit] attachToTag
378793 [Thread-5] TRACE sip.SipManager - [exit] sendNotImplemented
378793 [Thread-5] TRACE sip.SipManager - [exit] processRequest
java.lang.NullPointerException
    at
net.java.sip.communicator.sip.SipManager.attachToTag(SipManager.java:844)
    at
net.java.sip.communicator.sip.SipManager.sendNotImplemented(SipManager.java:565)
    at
net.java.sip.communicator.sip.SipManager.processRequest(SipManager.java:1316)
    at gov.nist.javax.sip.SipProviderImpl.run(SipProviderImpl.java:193)
    at java.lang.Thread.run(Thread.java:536)
****************************************************************

This point I'm registered with asterisk but after a timeout I get
unregistered an no way to register
again!! The logs when this happens are:

****************************************************************
606735 [Thread-1] TRACE sip.RegisterProcessing - [entry] run
606736 [Thread-1] TRACE sip.RegisterProcessing - [entry] register
606736 [Thread-1] TRACE sip.SipManager - [entry] getFromHeader
606737 [Thread-1] TRACE sip.SipManager - [exit] getFromHeader
606737 [Thread-1] TRACE sip.SipManager - [entry] fireRegistering
606737 [Thread-1] DEBUG sip.SipManager - registering with address="Luis
Vazquez" <sip:101@192.168.2.176:5060;transport=udp>
606738 [Thread-1] TRACE communicator.SipCommunicator - [entry] registering
606739 [Thread-1] TRACE communicator.SipCommunicator - [exit] registering
606739 [Thread-1] TRACE sip.SipManager - [exit] fireRegistering
606742 [Thread-1] TRACE sip.SipManager - [entry] getLocalViaHeaders
606746 [Thread-1] TRACE sip.SipManager - [exit] getLocalViaHeaders
606746 [Thread-1] TRACE sip.SipManager - [entry] getMaxForwardsHeader
606747 [Thread-1] TRACE sip.SipManager - [exit] getMaxForwardsHeader
606747 [Thread-1] TRACE sip.SipManager - [entry] getContactHeader
606748 [Thread-1] TRACE sip.SipManager - [exit] getContactHeader
hop = 192.168.2.175:5060/udp
606753 [Thread-1] DEBUG sip.RegisterProcessing - sent request= REGISTER
sip:192.168.2.175:5060;transport=udp SIP/2.0
Call-ID: af4c0b1f4127078c99a0b1a732bc7a25@192.168.2.176
CSeq: 1 REGISTER
From: "Luis Vazquez" <sip:101@192.168.2.176:5060;transport=udp>;tag=22897006
To: "Luis Vazquez" <sip:101@192.168.2.176:5060;transport=udp>
Via: SIP/2.0/UDP
192.168.2.176:5060;branch=z9hG4bK54bd1077fa9e7399ddfa33f3e3b58105
Max-Forwards: 70
Expires: 600
Contact: "Luis Vazquez" <sip:192.168.2.176:5060;transport=udp>
Content-Length: 0

606764 [Thread-1] TRACE sip.RegisterProcessing - [entry]
scheduleReRegistration
606765 [Thread-1] TRACE sip.RegisterProcessing - [exit]
scheduleReRegistration
606766 [Thread-1] TRACE sip.RegisterProcessing - [exit] register
606766 [Thread-1] TRACE sip.RegisterProcessing - [exit] run
****************************************************************

Next time I'll send the trace when I try to make a call through asterisk.

Bye
Luis

Cheers
Emil

http://www.emcho.com

···

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


#6

Emil Ivov wrote:

Hello Luis,

Could you please send me the sip-communicator.stack.log
file as well? (again in the log directory)

Cheers
Emil

Ok, here it is!
As you will see there are a mix of different tries and odds :slight_smile:
keep in contact
Luis

sip-communicator.stack.log (113 KB)


#7

Hello Luis,

I've just committed a fix (reported by Steve) that should solve your
problem. (Sorry for the delay)

Cheers
Emil

···

Emil Ivov wrote:

Hello Luis,

Could you please send me the sip-communicator.stack.log
file as well? (again in the log directory)

Cheers
Emil

Ok, here it is!
As you will see there are a mix of different tries and odds :slight_smile:
keep in contact
Luis

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