[sip-comm-dev] Possible Issue with SDP & RTP


#1

Javier,

Please try trunk in r4592 or a later revision for re-INVITE support
which takes into account changes in the connection address.

Regards,
Lubo

···

On Fri, Oct 3, 2008 at 4:28 PM, Javier Mendiara Cañardo <javier.mendiara@gmail.com> wrote:

Hi all,

I think I've found an issue with RTP retransmission.
If two SDP descriptions are sent in INVITES in the same SIP Dialog, SipComm
sends RTP to the destination specified in the first INVITE, and I think it
should be sent to the specified in the last INVITE

Attached there are 3 files, the sipcomm log (FINE graining), Wireshark Dump
and a JPG showing where SIP server sends the second INVITE with the correct
SDP and where is sending SIPComm the RTP audio.

As you can see, the other softphone is sending RTP directly to SipComm, but
SipComm is sending audio via SIP Server.

The scenario is as follows:
A SipCommunicator (SVN 4548) located at 192.168.1.135, with SIP ID 6003,
running under WinXP Pro SP3, java1.6, from Netbeans ANT run
One SIP Gateway (GrandStream HT503) located at 192.168.1.102, with SIP ID
6000
Asterisk SIP server (v1.4.18.1) located at 192.168.1.101, forcing G711U
(ULAW) codec
WireShark (tcpdump) listening in 192.168.1.135 (where sipcomm is located)

In the wireshark dump there is also debug info from the SIP Gateway
(sip:6000) sent using SysLog events (WireShark filter: syslog)
In "Log Sipcommunicator.log" file, line 914, you can see the second INVITE

This behaviour is a pity, because if the SIPComm and GW are in the same LAN
and the asterisk is outside, the Sipcommunicator voice will travel too long
to reach the destination, while GW to SIP voice will travel using the LAN.

One thing more, I've forced in Asterisk to use G711U, so I think that
Asterisk media server conversion between audio formats is not causing this.
Also, both SDP are identical.

What do you think? Is it possible to solve?

PS: I've tried with other softphones, and it seems that this issue is
correctly managed.

--
Javier Mendiara Cañardo

Skype: jmendiara.tid
MsnIM: javier.mendiara@gmail.com

14:28:58.656 FINA:
util.launchutils.SipCommunicatorLock.interInstanceConnect().538 Failed to
connect to a running sc instance.
14:29:01.890 FINA: impl.configuration.ConfigurationServiceImpl.start().37
Service Impl:
net.java.sip.communicator.impl.configuration.ConfigurationActivator [
STARTED ]
14:29:01.890 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.runtime.name=Java(TM) SE Runtime Environment
14:29:01.890 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
felix.config.properties=file:C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk/lib/felix.client.run.properties
14:29:01.890 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
sun.boot.library.path=C:\Java\jdk1.6.0_03\jre\bin
14:29:01.890 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.vm.version=1.6.0_03-b05
14:29:01.890 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.vm.vendor=Sun Microsystems Inc.
14:29:01.890 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.vendor.url=http://java.sun.com/
14:29:01.890 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
path.separator=;
14:29:01.890 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.util.logging.config.file=C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk/lib/logging.properties
14:29:01.890 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.vm.name=Java HotSpot(TM) Client VM
14:29:01.906 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
file.encoding.pkg=sun.io
14:29:01.906 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
sun.java.launcher=SUN_STANDARD
14:29:01.906 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
user.country=ES
14:29:01.906 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
sun.os.patch.level=Service Pack 3
14:29:01.906 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.vm.specification.name=Java Virtual Machine Specification
14:29:01.906 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
user.dir=C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk
14:29:01.906 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.runtime.version=1.6.0_03-b05
14:29:01.906 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.awt.graphicsenv=sun.awt.Win32GraphicsEnvironment
14:29:01.921 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.endorsed.dirs=C:\Java\jdk1.6.0_03\jre\lib\endorsed
14:29:01.921 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
os.arch=x86
14:29:01.921 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.io.tmpdir=C:\DOCUME~1\ADMINI~1\CONFIG~1\Temp\
14:29:01.921 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
line.separator=

14:29:01.921 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.vm.specification.vendor=Sun Microsystems Inc.
14:29:01.921 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
user.variant=
14:29:01.921 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
os.name=Windows XP
14:29:01.921 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
sun.jnu.encoding=Cp1252
14:29:01.921 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.net.preferIPv6Addresses=false
14:29:01.921 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.library.path=C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\${ld.library.path};C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\native\windows;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\${system.PATH};C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\${dyld.library.path}
14:29:01.921 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.specification.name=Java Platform API Specification
14:29:01.921 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.class.version=50.0
14:29:01.921 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
sun.management.compiler=HotSpot Client Compiler
14:29:01.921 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
org.osgi.framework.bootdelegation=
14:29:01.921 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
os.version=5.1
14:29:01.921 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
user.home=C:\Documents and Settings\Administrador
14:29:01.921 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
user.timezone=Europe/Paris
14:29:01.921 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.awt.printerjob=sun.awt.windows.WPrinterJob
14:29:01.921 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
file.encoding=Cp1252
14:29:01.921 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.specification.version=1.6
14:29:01.937 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
felix.auto.start.68=
14:29:01.937 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.class.path=C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\sc-bundles\sc-launcher.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\sc-bundles\util.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\bundle\junit.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\bundle\log4j.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\bundle\org.apache.felix.bundlerepository-1.0.0.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\bundle\org.apache.felix.servicebinder-0.9.0-SNAPSHOT.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\felix.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\JainSipApi1.2.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\JainSipRi1.2.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\KeybindingUtil.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\Stun4J.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\aclibico-2.1.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\apache-ant-1.7.0.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\bcprov-jdk14-140.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\commons-logging.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\concurrent.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\dnsjava-2.0.3.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\httpcore-4.0-beta2.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\izpack-shortcut-link.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\jcalendar-1.3.2.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\jcommon-1.0.0.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\jdic_misc.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\jdom.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\jfontchooser-1.0.5.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\jfreechart-1.0.1.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\jmf.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\jml-1.0b2.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\jna.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\joscar-client.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\joscar-common.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\joscar-protocol.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\jsch-0.1.36.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\jsocks-klea.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\jspeex.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\junit.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\log4j-1.2.8.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\nist-sdp-1.0.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\pircbot.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\profiler4j-1.0-beta3-SC.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\rome-0.9.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\smack.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\smackx-jingle.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\smackx.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\transparency.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\ymsg_network_v0_63.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\zrtp4j-0.9.0.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\jdic-all.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\os-specific\windows\installer-exclude\jmf.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\os-specific\windows\installer-exclude\sound.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\os-specific\windows\jdic_stub.jar
14:29:01.953 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
user.name=Administrador
14:29:01.953 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.vm.specification.version=1.0
14:29:01.953 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.home=C:\Java\jdk1.6.0_03\jre
14:29:01.953 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
sun.arch.data.model=32
14:29:01.953 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
user.language=es
14:29:01.953 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.specification.vendor=Sun Microsystems Inc.
14:29:01.953 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
awt.toolkit=sun.awt.windows.WToolkit
14:29:01.953 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.vm.info=mixed mode
14:29:01.953 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.version=1.6.0_03
14:29:01.953 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.ext.dirs=C:\Java\jdk1.6.0_03\jre\lib\ext;C:\WINDOWS\Sun\Java\lib\ext
14:29:01.953 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
sun.boot.class.path=C:\Java\jdk1.6.0_03\jre\lib\resources.jar;C:\Java\jdk1.6.0_03\jre\lib\rt.jar;C:\Java\jdk1.6.0_03\jre\lib\sunrsasign.jar;C:\Java\jdk1.6.0_03\jre\lib\jsse.jar;C:\Java\jdk1.6.0_03\jre\lib\jce.jar;C:\Java\jdk1.6.0_03\jre\lib\charsets.jar;C:\Java\jdk1.6.0_03\jre\classes
14:29:01.953 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
net.java.sip.communicator.SC_HOME_DIR_LOCATION=C:\Documents and
Settings\Administrador
14:29:01.953 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.vendor=Sun Microsystems Inc.
14:29:01.953 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
file.separator=\
14:29:01.953 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
net.java.sip.communicator.SC_HOME_DIR_NAME=.sip-communicator
14:29:01.968 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi
14:29:01.968 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
sun.io.unicode.encoding=UnicodeLittle
14:29:01.968 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
sun.cpu.endian=little
14:29:01.968 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
sun.desktop=windows
14:29:01.968 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
sun.cpu.isalist=
14:29:01.968 FINA:
impl.configuration.ConfigurationServiceImpl.createConfigurationFile().912
Using config file in $HOME/.sip-communicator:
C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\sip-communicator.xml
14:29:02.046 FINA: impl.configuration.ConfigurationServiceImpl.start().46
Service Impl:
net.java.sip.communicator.impl.configuration.ConfigurationActivator
[REGISTERED]
14:29:02.078 INFO: impl.resources.ResourceManagementActivator.start().40
Resource manager ... [REGISTERED]
14:29:02.078 INFO:
impl.resources.ResourceManagementServiceImpl.serviceChanged().179 Resource
registered
net.java.sip.communicator.plugin.defaultresourcepack.DefaultColorPackImpl@176cad3
14:29:02.109 INFO:
impl.resources.ResourceManagementServiceImpl.serviceChanged().179 Resource
registered
net.java.sip.communicator.plugin.defaultresourcepack.DefaultImagePackImpl@13ad33d
14:29:02.125 INFO:
impl.resources.ResourceManagementServiceImpl.serviceChanged().179 Resource
registered
net.java.sip.communicator.plugin.defaultresourcepack.DefaultLanguagePackImpl@a613f8
14:29:02.156 INFO:
impl.resources.ResourceManagementServiceImpl.serviceChanged().179 Resource
registered
net.java.sip.communicator.plugin.defaultresourcepack.DefaultSettingsPackImpl@edf3f6
14:29:02.156 INFO:
impl.resources.ResourceManagementServiceImpl.serviceChanged().179 Resource
registered
net.java.sip.communicator.plugin.defaultresourcepack.DefaultSoundPackImpl@19ce060
14:29:02.171 INFO:
plugin.defaultresourcepack.DefaultResourcePackActivator.start().92 Default
resources ... [REGISTERED]
14:29:02.171 FINA: impl.version.VersionActivator.start() Started.
14:29:02.187 FINA: impl.version.VersionActivator.start() SIP Protocol
Provider Factory ... [REGISTERED]
14:29:02.187 FINA: impl.version.VersionActivator.start() SIP Communicator
Version: sip-communicator-1.0-alpha3-0.build.by.SVN
14:29:03.218 INFO: impl.browserlauncher.BrowserLauncherActivator.start().37
UI Service...[ STARTED ]
14:29:03.234 INFO: impl.browserlauncher.BrowserLauncherActivator.start().42
UI Service ...[REGISTERED]
14:29:03.250 FINA: impl.media.MediaActivator.start().53 Started.
14:29:03.265 FINA: impl.media.MediaActivator.start().69 Media Service ...
[REGISTERED]
14:29:03.281 FINA:
impl.protocol.AccountManagerImpl.doLoadStoredAccounts().110 Discovered 0
stored net.java.sip.communicator.impl.protocol.icq accounts
14:29:03.296 FINA: impl.protocol.sip.SipActivator.start().54 Started.
14:29:03.312 FINA:
impl.protocol.AccountManagerImpl.doLoadStoredAccounts().110 Discovered 0
stored net.java.sip.communicator.impl.protocol.icq accounts
14:29:03.312 FINA: impl.protocol.sip.SipActivator.start().75 SIP Protocol
Provider Factory ... [REGISTERED]
14:29:03.328 FINA:
impl.protocol.AccountManagerImpl.doLoadStoredAccounts().110 Discovered 1
stored net.java.sip.communicator.impl.protocol.sip accounts
14:29:03.328 FINA:
impl.protocol.AccountManagerImpl.doLoadStoredAccounts().118 Loading account
net.java.sip.communicator.impl.protocol.sip.acc1222944379687
14:29:03.421 INFO: impl.protocol.gibberish.GibberishActivator.start().74
Gibberish protocol implementation [STARTED].
14:29:03.437 INFO: impl.protocol.ssh.SSHActivator.start().80 SSH protocol
implementation [STARTED].
14:29:03.578 INFO: impl.netaddr.NetworkAddressManagerServiceImpl.start().149
Stun server address(null)/port(null) not set (or invalid). Disabling STUN.
14:29:03.578 INFO: impl.netaddr.NetworkAddressManagerServiceImpl.start().59
Network Address Manager ...[ STARTED ]
14:29:03.578 INFO: impl.netaddr.NetworkAddressManagerServiceImpl.start().64
Network Address Manager Service ...[REGISTERED]
14:29:03.578 INFO: impl.protocol.zeroconf.ZeroconfActivator.start().74
Zeroconf protocol implementation [STARTED].
14:29:03.593 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.initialize().574 Created
stack: gov.nist.javax.sip.SipStackImpl@d2efa1
14:29:03.593 INFO: impl.protocol.irc.IrcActivator.start().75 IRC protocol
implementation [STARTED].
14:29:03.625 INFO: impl.protocol.dict.DictActivator.start().66 DICT protocol
implementation [STARTED].
14:29:03.640 FINA: impl.contactlist.ContactlistActivator.start().44 Service
Impl: net.java.sip.communicator.impl.contactlist.ContactlistActivator [
STARTED ]
14:29:03.687 FINA: impl.contactlist.MetaContactListServiceImpl.start().145
Starting the meta contact list implementation.
14:29:03.734 FINA: impl.contactlist.ContactlistActivator.start().57 Service
Impl: net.java.sip.communicator.impl.contactlist.ContactlistActivator
[REGISTERED]
14:29:03.750 INFO: impl.protocol.rss.RssActivator.start().93 RSS protocol
implementation [STARTED].
14:29:03.750 FINA: impl.protocol.rss.RssActivator.start().100 User-Agent set
to SIP Communicator/1.0-alpha3-0.build.by.SVN
14:29:03.906 FINA:
impl.history.HistoryServiceImpl.setConfigurationService().286 New
configuration service registered.
14:29:03.921 FINA:
impl.history.HistoryServiceImpl.setFileAccessService().325 New file access
service registered.
14:29:03.921 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.createListeningPoint().904
Created LP 0.0.0.0:5060/udp
14:29:03.953 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.createListeningPoint().904
Created LP 0.0.0.0:5060/tcp
14:29:03.953 FINA:
impl.msghistory.MessageHistoryServiceImpl.setHistoryService().865 New
history service registered.
14:29:03.953 FINA: impl.msghistory.MessageHistoryServiceImpl.start().704
Starting the msg history implementation.
14:29:03.968 INFO: impl.msghistory.MessageHistoryActivator.start().60
Message History Service ...[REGISTERED]
14:29:04.046 FINA:
impl.callhistory.CallHistoryServiceImpl.setHistoryService().613 New history
service registered.
14:29:04.046 FINA: impl.callhistory.CallHistoryServiceImpl.start().473
Starting the call history implementation.
14:29:04.250 INFO: impl.callhistory.CallHistoryActivator.start().56 Call
History Service ...[REGISTERED]
14:29:04.265 INFO: impl.audionotifier.AudioNotifierActivator.start().66
Audio Notifier Service...[ STARTED ]
14:29:04.281 INFO:
impl.media.device.JmfDeviceDetector.detectCaptureDevices().125 Looking for
Audio capturer
14:29:04.281 INFO: impl.audionotifier.AudioNotifierActivator.start().71
Audio Notifier Service ...[REGISTERED]
14:29:04.281 FINA: impl.keybindings.KeybindingsActivator.start().34 Service
Impl: net.java.sip.communicator.impl.keybindings.KeybindingsActivator [
STARTED ]
14:29:04.296 INFO: impl.media.device.DirectSoundAuto.<init>().55 DirectSound
Capture Supported = true
14:29:04.375 INFO: impl.notification.NotificationActivator.start().46
Notification Service...[ STARTED ]
14:29:04.375 INFO: impl.notification.NotificationActivator.start().53
Notification Service ...[REGISTERED]
14:29:04.406 FINA:
impl.notification.NotificationServiceImpl.registerDefaultNotificationForEvent().995
Registering default event IncomingMessage/PopupMessageAction/null/null
14:29:04.421 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.createListeningPoint().904
Created LP 0.0.0.0:5061/tls
14:29:04.437 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.initListeningPoints().824
Created listening points and SIP provider for account
SIP:6003@192.168.1.101
14:29:04.484 FINA:
impl.notification.NotificationServiceImpl.registerDefaultNotificationForEvent().995
Registering default event
IncomingMessage/SoundAction/resources/sounds/incomingMessage.wav/null
14:29:04.546 FINA:
impl.protocol.sip.OperationSetPresenceSipImpl.<init>().273 presence
initialized with :true, true, 30, 3600 for null
14:29:04.578 FINA:
impl.notification.NotificationServiceImpl.registerDefaultNotificationForEvent().995
Registering default event IncomingCall/PopupMessageAction/null/null
14:29:05.296 INFO: impl.media.device.DirectSoundAuto.<init>().102
DirectSoundAuto: Committed ok
14:29:05.421 FINA: impl.protocol.AccountManagerImpl.storeAccount().562
Stored account for id SIP:6003@192.168.1.101 for package
net.java.sip.communicator.impl.protocol.sip
14:29:05.421 FINA:
impl.callhistory.CallHistoryServiceImpl.serviceChanged().653 Service is a
protocol provider.
14:29:05.421 FINA:
impl.callhistory.CallHistoryServiceImpl.serviceChanged().656 Handling
registration of a new Protocol Provider.
14:29:05.421 FINA:
impl.notification.NotificationServiceImpl.registerDefaultNotificationForEvent().995
Registering default event ProactiveNotification/PopupMessageAction/null/null
14:29:05.437 FINA:
impl.callhistory.CallHistoryServiceImpl.handleProviderAdded().676 Adding
protocol provider SIP
14:29:05.437 FINA:
impl.msghistory.MessageHistoryServiceImpl.serviceChanged().905 Service is a
protocol provider.
14:29:05.437 FINA:
impl.msghistory.MessageHistoryServiceImpl.serviceChanged().908 Handling
registration of a new Protocol Provider.
14:29:05.437 FINA:
impl.msghistory.MessageHistoryServiceImpl.handleProviderAdded().928 Adding
protocol provider SIP
14:29:05.453 INFO: impl.media.device.JavaSoundAuto.<init>().43 JavaSound
Capture Supported = true
14:29:05.578 FINA:
impl.contactlist.MetaContactListServiceImpl.serviceChanged().1842 Service is
a protocol provider.
14:29:05.593 FINA:
impl.contactlist.MetaContactListServiceImpl.serviceChanged().1868 Handling
registration of a new Protocol Provider.
14:29:05.593 FINA:
impl.contactlist.MetaContactListServiceImpl.handleProviderAdded().1516
Adding protocol provider SIP
14:29:05.609 INFO: impl.gui.GuiActivator.start().88 UI Service...[ STARTED
]
14:29:05.625 INFO: impl.media.device.JavaSoundAuto.<init>().69
JavaSoundAuto: Committed ok
14:29:05.625 INFO: impl.gui.GuiActivator.start().93 UI Service
...[REGISTERED]
14:29:05.625 FINA:
impl.media.device.JmfDeviceDetector.detectCaptureDevices().154 No FMJ
javasound detected: *** Class
'net.sf.fmj.media.cdp.javasound.CaptureDevicePlugger' was not found. Bundle
17 does not import package 'net.sf.fmj.media.cdp.javasound', nor is the
package exported by any other bundle or available from the system class
loader. ***
14:29:05.640 FINA:
impl.protocol.AccountManagerImpl.doLoadStoredAccounts().110 Discovered 0
stored net.java.sip.communicator.impl.protocol.jabber accounts
14:29:05.687 INFO:
impl.media.device.JmfDeviceDetector.detectCaptureDevices().175 Looking for
video capture devices
14:29:05.687 FINA:
impl.protocol.AccountManagerImpl.doLoadStoredAccounts().110 Discovered 0
stored net.java.sip.communicator.impl.protocol.msn accounts
14:29:05.687 FINA:
impl.protocol.AccountManagerImpl.doLoadStoredAccounts().110 Discovered 0
stored net.java.sip.communicator.impl.protocol.yahoo accounts
14:29:05.687 FINA:
impl.protocol.AccountManagerImpl.doLoadStoredAccounts().110 Discovered 0
stored net.java.sip.communicator.impl.protocol.gibberish accounts
14:29:05.703 FINA:
impl.protocol.AccountManagerImpl.doLoadStoredAccounts().110 Discovered 0
stored net.java.sip.communicator.impl.protocol.ssh accounts
14:29:05.703 FINA:
impl.protocol.AccountManagerImpl.doLoadStoredAccounts().110 Discovered 0
stored net.java.sip.communicator.impl.protocol.zeroconf accounts
14:29:05.703 FINA:
impl.protocol.AccountManagerImpl.doLoadStoredAccounts().110 Discovered 0
stored net.java.sip.communicator.impl.protocol.irc accounts
14:29:05.703 FINA: impl.media.device.VFWAuto.autoDetectDevices().61 Found
device Microsoft WDM Image Capture (Win32)
14:29:05.703 FINA:
impl.protocol.AccountManagerImpl.doLoadStoredAccounts().110 Discovered 0
stored net.java.sip.communicator.impl.protocol.dict accounts
14:29:05.703 FINA:
impl.protocol.AccountManagerImpl.doLoadStoredAccounts().110 Discovered 0
stored net.java.sip.communicator.impl.protocol.rss accounts
14:29:05.703 FINA: impl.media.device.VFWAuto.autoDetectDevices().62 Querying
device. Please wait...
14:29:05.750 INFO:
impl.media.device.JmfDeviceDetector.detectCaptureDevices().182 Detected 0
VFW video capture device(s).
14:29:05.781 INFO:
impl.media.device.JmfDeviceDetector.detectCaptureDevices().197 Detected 0
SUN Video capture device(s).
14:29:05.828 INFO:
impl.media.device.JmfDeviceDetector.detectCaptureDevices().212 Detected 0
SUN Video Plus device(s).
14:29:05.828 FINA:
impl.media.device.JmfDeviceDetector.detectCaptureDevices().232 No V4l video
detected: com/sun/media/protocol/v4l/V4LDeviceQuery
14:29:05.828 INFO:
impl.media.device.DeviceConfiguration.extractConfiguredCaptureDevices().81
Scanning for configured Audio Devices.
14:29:05.828 FINA:
impl.media.device.DeviceConfiguration.extractConfiguredCaptureDevices().90
Found 2 capture devices: [DirectSoundCapture : dsound://
LINEAR, 48000.0 Hz, 16-bit, Stereo, LittleEndian, Signed
LINEAR, 48000.0 Hz, 16-bit, Mono, LittleEndian, Signed
LINEAR, 48000.0 Hz, 8-bit, Stereo, Unsigned
LINEAR, 48000.0 Hz, 8-bit, Mono, Unsigned
LINEAR, 44100.0 Hz, 16-bit, Stereo, LittleEndian, Signed
LINEAR, 44100.0 Hz, 16-bit, Mono, LittleEndian, Signed
LINEAR, 44100.0 Hz, 8-bit, Stereo, Unsigned
LINEAR, 44100.0 Hz, 8-bit, Mono, Unsigned
LINEAR, 32000.0 Hz, 16-bit, Stereo, LittleEndian, Signed
LINEAR, 32000.0 Hz, 16-bit, Mono, LittleEndian, Signed
LINEAR, 32000.0 Hz, 8-bit, Stereo, Unsigned
LINEAR, 32000.0 Hz, 8-bit, Mono, Unsigned
LINEAR, 22050.0 Hz, 16-bit, Stereo, LittleEndian, Signed
LINEAR, 22050.0 Hz, 16-bit, Mono, LittleEndian, Signed
LINEAR, 22050.0 Hz, 8-bit, Stereo, Unsigned
LINEAR, 22050.0 Hz, 8-bit, Mono, Unsigned
LINEAR, 16000.0 Hz, 16-bit, Stereo, LittleEndian, Signed
LINEAR, 16000.0 Hz, 16-bit, Mono, LittleEndian, Signed
LINEAR, 16000.0 Hz, 8-bit, Stereo, Unsigned
LINEAR, 16000.0 Hz, 8-bit, Mono, Unsigned
LINEAR, 11025.0 Hz, 16-bit, Stereo, LittleEndian, Signed
LINEAR, 11025.0 Hz, 16-bit, Mono, LittleEndian, Signed
LINEAR, 11025.0 Hz, 8-bit, Stereo, Unsigned
LINEAR, 11025.0 Hz, 8-bit, Mono, Unsigned
LINEAR, 8000.0 Hz, 16-bit, Stereo, LittleEndian, Signed
LINEAR, 8000.0 Hz, 16-bit, Mono, LittleEndian, Signed
LINEAR, 8000.0 Hz, 8-bit, Stereo, Unsigned
LINEAR, 8000.0 Hz, 8-bit, Mono, Unsigned
, JavaSound audio capture : javasound://44100
LINEAR, 44100.0 Hz, 16-bit, Stereo, LittleEndian, Signed
LINEAR, 44100.0 Hz, 16-bit, Mono, LittleEndian, Signed
LINEAR, 22050.0 Hz, 16-bit, Stereo, LittleEndian, Signed
LINEAR, 22050.0 Hz, 16-bit, Mono, LittleEndian, Signed
LINEAR, 11025.0 Hz, 16-bit, Stereo, LittleEndian, Signed
LINEAR, 11025.0 Hz, 16-bit, Mono, LittleEndian, Signed
LINEAR, 8000.0 Hz, 16-bit, Stereo, LittleEndian, Signed
LINEAR, 8000.0 Hz, 16-bit, Mono, LittleEndian, Signed
]
14:29:05.968 INFO:
impl.media.device.DeviceConfiguration.extractConfiguredCaptureDevices().93
Found DirectSoundCapture as an audio capture device.
14:29:05.968 INFO:
impl.media.device.DeviceConfiguration.extractConfiguredCaptureDevices().97
Scanning for configured Video Devices.
14:29:05.968 INFO:
impl.media.device.DeviceConfiguration.extractConfiguredCaptureDevices().117
No Video Device was found.
14:29:05.968 FINA: impl.media.MediaControl.registerCustomPackages().1243
Adding package : net.java.sip.communicator.impl
14:29:06.015 FINA: impl.media.MediaControl.registerCustomPackages().1249
Registering new protocol prefix list : [javax, com.sun, com.ibm,
net.java.sip.communicator.impl]
14:29:06.015 INFO: impl.media.MediaControl.createDataSource().740 Creating
datasource for:dsound://
14:29:06.062 FINA: impl.media.MediaControl.registerCustomCodecs().1191 Codec
: net.java.sip.communicator.impl.media.codec.audio.alaw.JavaEncoder is
succsefully registered : true
14:29:06.062 FINA: impl.media.MediaControl.registerCustomCodecs().1191 Codec
: net.java.sip.communicator.impl.media.codec.audio.alaw.DePacketizer is
succsefully registered : true
14:29:06.062 FINA: impl.media.MediaControl.registerCustomCodecs().1191 Codec
: net.java.sip.communicator.impl.media.codec.audio.alaw.Packetizer is
succsefully registered : true
14:29:06.109 FINA: impl.media.MediaControl.registerCustomCodecs().1191 Codec
: net.java.sip.communicator.impl.media.codec.audio.ulaw.Packetizer is
succsefully registered : true
14:29:06.109 FINA: impl.media.MediaControl.registerCustomCodecs().1191 Codec
: net.java.sip.communicator.impl.media.codec.audio.speex.JavaEncoder is
succsefully registered : true
14:29:06.109 FINA: impl.media.MediaControl.registerCustomCodecs().1191 Codec
: net.java.sip.communicator.impl.media.codec.audio.speex.JavaDecoder is
succsefully registered : true
14:29:06.109 FINA: impl.media.MediaControl.registerCustomCodecs().1191 Codec
: net.java.sip.communicator.impl.media.codec.audio.ilbc.JavaEncoder is
succsefully registered : true
14:29:06.125 FINA: impl.media.MediaControl.registerCustomCodecs().1191 Codec
: net.java.sip.communicator.impl.media.codec.audio.ilbc.JavaDecoder is
succsefully registered : true
14:29:06.390 INFO: plugin.updatechecker.Resources.getConfigString().47 No
config file specified for update checker
14:29:06.390 INFO: plugin.updatechecker.Resources.getConfigString().48
Disabling update checks
14:29:06.390 FINA:
plugin.updatechecker.UpdateCheckActivator.isNewestVersion().214 Updates are
disabled. Faking latest version.
14:29:06.390 INFO: impl.gui.UIServiceImpl.serviceChanged().752 Handling
registration of a new Plugin Component.
14:29:06.390 FINA: impl.gui.UIServiceImpl.firePluginEvent().169 Will
dispatch the following plugin component event:
net.java.sip.communicator.impl.gui.event.PluginComponentEvent[source=net.java.sip.communicator.plugin.branding.AboutWindowPluginComponent@d507d3]
14:29:06.406 INFO: plugin.branding.BrandingActivator.registerMenuEntry().129
ABOUT WINDOW ... [REGISTERED]
14:29:06.406 INFO: impl.gui.UIServiceImpl.serviceChanged().752 Handling
registration of a new Plugin Component.
14:29:06.406 FINA: impl.gui.UIServiceImpl.firePluginEvent().169 Will
dispatch the following plugin component event:
net.java.sip.communicator.impl.gui.event.PluginComponentEvent[source=net.java.sip.communicator.plugin.branding.AboutWindowPluginComponent@307c44]
14:29:06.406 INFO: plugin.branding.BrandingActivator.registerMenuEntry().142
CHAT ABOUT WINDOW ... [REGISTERED]
14:29:06.765 INFO: impl.systray.SystrayActivator.start().51 Systray
Service...[ STARTED ]
14:29:06.765 INFO: impl.systray.SystrayActivator.start().58 Systray Service
...[REGISTERED]
14:29:06.765 INFO:
impl.gui.main.configforms.ConfigurationFrame.serviceChanged().235 Handling
registration of a new Configuration Form.
14:29:06.796 INFO: impl.gui.UIServiceImpl.serviceChanged().752 Handling
registration of a new Plugin Component.
14:29:06.796 FINA: impl.gui.UIServiceImpl.firePluginEvent().169 Will
dispatch the following plugin component event:
net.java.sip.communicator.impl.gui.event.PluginComponentEvent[source=net.java.sip.communicator.plugin.whiteboard.WhiteboardMenuItem@191394e]
14:29:06.796 INFO: plugin.whiteboard.WhiteboardActivator.start().56
WHITEBOARD... [REGISTERED]
14:29:06.812 INFO:
impl.gui.main.account.AccountRegWizardContainerImpl.serviceChanged().328
Handling registration of a new Account Wizard.
14:29:06.828 INFO:
impl.gui.main.account.AccountRegWizardContainerImpl.serviceChanged().328
Handling registration of a new Account Wizard.
14:29:06.843 INFO:
impl.gui.main.account.AccountRegWizardContainerImpl.serviceChanged().328
Handling registration of a new Account Wizard.
14:29:06.859 INFO:
impl.gui.main.account.AccountRegWizardContainerImpl.serviceChanged().328
Handling registration of a new Account Wizard.
14:29:06.859 INFO:
impl.gui.main.account.AccountRegWizardContainerImpl.serviceChanged().328
Handling registration of a new Account Wizard.
14:29:06.875 INFO:
impl.gui.main.account.AccountRegWizardContainerImpl.serviceChanged().328
Handling registration of a new Account Wizard.
14:29:06.890 INFO:
impl.gui.main.account.AccountRegWizardContainerImpl.serviceChanged().328
Handling registration of a new Account Wizard.
14:29:06.890 INFO:
plugin.gibberishaccregwizz.GibberishAccRegWizzActivator.start().51 Loading
gibberish account wizard.
14:29:06.906 INFO:
impl.gui.main.account.AccountRegWizardContainerImpl.serviceChanged().328
Handling registration of a new Account Wizard.
14:29:06.906 INFO:
plugin.gibberishaccregwizz.GibberishAccRegWizzActivator.start().77 Gibberish
account registration wizard [STARTED].
14:29:06.906 INFO: plugin.sshaccregwizz.SSHAccRegWizzActivator.start().58
Loading ssh account wizard.
14:29:06.921 INFO:
impl.gui.main.account.AccountRegWizardContainerImpl.serviceChanged().328
Handling registration of a new Account Wizard.
14:29:06.921 INFO: plugin.sshaccregwizz.SSHAccRegWizzActivator.start().85
SSH account registration wizard [STARTED].
14:29:06.937 INFO: impl.gui.UIServiceImpl.serviceChanged().752 Handling
registration of a new Plugin Component.
14:29:06.937 FINA: impl.gui.UIServiceImpl.firePluginEvent().169 Will
dispatch the following plugin component event:
net.java.sip.communicator.impl.gui.event.PluginComponentEvent[source=net.java.sip.communicator.plugin.extendedcallhistorysearch.ExtendedCallHistorySearchItem@1ebe8ec]
14:29:06.937 INFO:
plugin.extendedcallhistorysearch.ExtendedCallHistorySearchActivator.start().48
EXTENDED CALL HISTORY SEARCH... [REGISTERED]
14:29:06.937 INFO: plugin.rssaccregwizz.RssAccRegWizzActivator.start().46
Loading rss account wizard.
14:29:06.953 INFO:
impl.gui.main.account.AccountRegWizardContainerImpl.serviceChanged().328
Handling registration of a new Account Wizard.
14:29:06.953 INFO: plugin.rssaccregwizz.RssAccRegWizzActivator.start().73
RSS account registration wizard [STARTED].
14:29:06.953 INFO:
plugin.zeroconfaccregwizz.ZeroconfAccRegWizzActivator.start().47 Loading
zeroconf account wizard.
14:29:06.953 INFO:
impl.gui.main.account.AccountRegWizardContainerImpl.serviceChanged().328
Handling registration of a new Account Wizard.
14:29:06.968 INFO:
plugin.zeroconfaccregwizz.ZeroconfAccRegWizzActivator.start().74 Zeroconf
account registration wizard [STARTED].
14:29:06.968 INFO: plugin.ircaccregwizz.IrcAccRegWizzActivator.start().41
Loading irc account wizard.
14:29:06.968 INFO:
impl.gui.main.account.AccountRegWizardContainerImpl.serviceChanged().328
Handling registration of a new Account Wizard.
14:29:06.968 INFO: plugin.ircaccregwizz.IrcAccRegWizzActivator.start().69
IRC account registration wizard [STARTED].
14:29:06.984 INFO:
impl.gui.main.configforms.ConfigurationFrame.serviceChanged().235 Handling
registration of a new Configuration Form.
14:29:07.000 INFO: impl.gui.UIServiceImpl.serviceChanged().752 Handling
registration of a new Plugin Component.
14:29:07.000 FINA: impl.gui.UIServiceImpl.firePluginEvent().169 Will
dispatch the following plugin component event:
net.java.sip.communicator.impl.gui.event.PluginComponentEvent[source=net.java.sip.communicator.plugin.contactinfo.ContactInfoMenuItem@c1dc0b]
14:29:07.000 INFO: plugin.contactinfo.ContactInfoActivator.start().44
CONTACT INFO... [REGISTERED]
14:29:07.140 FINA: plugin.chatalerter.ChatAlerterActivator.start().99 Found
1 already installed providers.
14:29:07.140 FINA:
plugin.chatalerter.ChatAlerterActivator.handleProviderAdded().155 Adding
protocol provider SIP
14:29:07.171 INFO:
impl.gui.main.configforms.ConfigurationFrame.serviceChanged().235 Handling
registration of a new Configuration Form.
14:29:07.171 FINA:
plugin.keybindingchooser.KeybindingChooserActivator.start().39 Service Impl:
net.java.sip.communicator.plugin.keybindingchooser.KeybindingChooserActivator
[ STARTED ]
14:29:07.453 INFO:
impl.gui.main.configforms.ConfigurationFrame.serviceChanged().235 Handling
registration of a new Configuration Form.
14:29:07.859 INFO:
impl.gui.main.configforms.ConfigurationFrame.serviceChanged().235 Handling
registration of a new Configuration Form.
14:29:07.859 INFO:
plugin.generalconfig.GeneralConfigPluginActivator.start().43 PREFERENCES
PLUGIN... [REGISTERED]
14:29:07.875 INFO:
impl.gui.main.account.AccountRegWizardContainerImpl.serviceChanged().328
Handling registration of a new Account Wizard.
14:29:07.875 INFO:
plugin.simpleaccreg.SimpleAccountRegistrationActivator.start().51 SIMPLE
ACCOUNT REGISTRATION ...[STARTED]
14:29:07.890 FINA: impl.shutdown.ShutdownTimeout.start().51 Starting the
ShutdownTimeout service.
14:29:09.890 FINA:
service.protocol.AbstractProtocolProviderService.fireRegistrationStateChanged().74
Dispatching RegistrationStateChangeEvent[ oldState=Unregistered;
newState=RegistrationState=Registering;reasonCode=-1;reason=null] to 7
listeners.
14:29:09.890 FINA:
impl.protocol.sip.OperationSetBasicInstantMessagingSipImpl.registrationStateChanged().545
The provider changed state from: RegistrationState=Unregistered to:
RegistrationState=Registering
14:29:09.890 FINA:
impl.protocol.sip.OperationSetTypingNotificationsSipImpl.registrationStateChanged().130
The provider changed state from: RegistrationState=Unregistered to:
RegistrationState=Registering
14:29:09.937 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.getMaxForwardsHeader().1490
generated max forwards: Max-Forwards: 70

14:29:09.937 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.getIntendedDestination().2762
Returning address 192.168.1.101/192.168.1.101 for destination 192.168.1.101
14:29:09.937 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.getLocalViaHeaders().1445
generated via headers:Via: SIP/2.0/UDP 192.168.1.135:5060

14:29:09.953 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.getIntendedDestination().2762
Returning address 192.168.1.101/192.168.1.101 for destination 192.168.1.101
14:29:09.968 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.getContactHeader().1559
generated contactHeader:Contact: "6003"
<sip:6003@192.168.1.135:5060;transport=udp>

14:29:11.703 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/udp could not be resolved null
14:29:11.703 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/udp
14:29:11.765 FINA: impl.protocol.sip.SipRegistrarConnection.register().414
sent request= REGISTER sip:192.168.1.101 SIP/2.0
Call-ID: fc209035180fb03ddb973abb02ceb2ef@0.0.0.0
CSeq: 1 REGISTER
From: <sip:6003@192.168.1.101>;tag=23d21598
To: <sip:6003@192.168.1.101>
Via: SIP/2.0/UDP
192.168.1.135:5060;branch=z9hG4bKd95fe0d12b4e888c1b7a247f9dee5c6f
Max-Forwards: 70
User-Agent: SIP Communicator 1.0-alpha3-0.build.by.SVN Windows XP
Expires: 3600
Contact: "6003" <sip:6003@192.168.1.135:5060;transport=udp>;expires=3600
Content-Length: 0

14:29:11.859 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1018
received response=
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP
192.168.1.135:5060;branch=z9hG4bKd95fe0d12b4e888c1b7a247f9dee5c6f;received=192.168.1.135
From: <sip:6003@192.168.1.101>;tag=23d21598
To: <sip:6003@192.168.1.101>;tag=as4e5e9d46
Call-ID: fc209035180fb03ddb973abb02ceb2ef@0.0.0.0
CSeq: 1 REGISTER
User-Agent: Asterisk PBX
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,SUBSCRIBE,NOTIFY
Supported: replaces
WWW-Authenticate: Digest nonce="6eec7d69",realm="asterisk",algorithm=MD5
Content-Length: 0

14:29:11.859 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1036
Found 1 processor(s) for method REGISTER
14:29:11.859 FINA:
impl.protocol.sip.SipRegistrarConnection.processAuthenticationChallenge().1001
Authenticating a Register request.
14:29:11.875 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/udp could not be resolved null
14:29:11.875 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/udp
14:29:11.875 FINA:
impl.protocol.sip.security.MessageDigestAlgorithm.calculateResponse().61
trying to authenticate using : MD5, 6003, asterisk, true, 6eec7d69,
00000001, xyz, REGISTER, sip:192.168.1.101, , null
14:29:11.875 FINA:
impl.protocol.sip.security.SipSecurityManager.handleChallenge().274 Created
authorization header: Authorization: Digest
response="bdb0077e0756de72e236b7da89a9574c",username="6003",nonce="6eec7d69",realm="asterisk",uri="sip:192.168.1.101",algorithm=MD5

14:29:11.875 FINA:
impl.protocol.sip.security.SipSecurityManager.handleChallenge().291
Returning authorization transaction.
14:29:11.890 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1018
received response=
SIP/2.0 100 Trying
Via: SIP/2.0/UDP
192.168.1.135:5060;branch=z9hG4bKebb47fbb7f3254c3066cdd958c1865c2;received=192.168.1.135
From: <sip:6003@192.168.1.101>;tag=23d21598
To: <sip:6003@192.168.1.101>
Call-ID: fc209035180fb03ddb973abb02ceb2ef@0.0.0.0
CSeq: 2 REGISTER
User-Agent: Asterisk PBX
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,SUBSCRIBE,NOTIFY
Supported: replaces
Contact: <sip:6003@192.168.1.101>
Content-Length: 0

14:29:11.890 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1036
Found 1 processor(s) for method REGISTER
14:29:11.937 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1018
received response=
SIP/2.0 200 OK
Via: SIP/2.0/UDP
192.168.1.135:5060;branch=z9hG4bKebb47fbb7f3254c3066cdd958c1865c2;received=192.168.1.135
From: <sip:6003@192.168.1.101>;tag=23d21598
To: <sip:6003@192.168.1.101>;tag=as4e5e9d46
Call-ID: fc209035180fb03ddb973abb02ceb2ef@0.0.0.0
CSeq: 2 REGISTER
User-Agent: Asterisk PBX
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,SUBSCRIBE,NOTIFY
Supported: replaces
Expires: 3600
Contact: <sip:6003@192.168.1.135:5060;transport=udp>;expires=3600
Date: Fri, 03 Oct 2008 12:29:10 GMT
Content-Length: 0

14:29:11.937 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1036
Found 1 processor(s) for method REGISTER
14:29:11.937 FINA:
service.protocol.AbstractProtocolProviderService.fireRegistrationStateChanged().74
Dispatching RegistrationStateChangeEvent[ oldState=Registering;
newState=RegistrationState=Registered;reasonCode=-1;reason=null] to 7
listeners.
14:29:11.953 FINA:
impl.protocol.sip.OperationSetPresenceSipImpl.registrationStateChanged().4980
enter registered state
14:29:11.953 FINA:
impl.protocol.sip.OperationSetBasicInstantMessagingSipImpl.registrationStateChanged().545
The provider changed state from: RegistrationState=Registering to:
RegistrationState=Registered
14:29:11.953 FINA:
impl.protocol.sip.OperationSetTypingNotificationsSipImpl.registrationStateChanged().130
The provider changed state from: RegistrationState=Registering to:
RegistrationState=Registered
14:29:11.984 FINA:
impl.protocol.sip.OperationSetPresenceSipImpl.fireProviderStatusChangeEvent().693
Dispatching Provider Status Change. Listeners=2
evt=ProviderPresenceStatusChangeEvent-[OldStatus=PresenceStatus:Offline,
NewStatus=PresenceStatus:Online]
14:29:12.000 FINA:
impl.protocol.sip.OperationSetPresenceSipImpl.fireProviderStatusChangeEvent().711
status dispatching done.
14:29:12.000 FINA:
impl.protocol.sip.OperationSetPresenceSipImpl.fireProviderMsgStatusChangeEvent().727
Dispatching stat. msg change. Listeners=2
evt=java.beans.PropertyChangeEvent[source=net.java.sip.communicator.impl.protocol.sip.ProtocolProviderServiceSipImpl@169c398]
14:29:12.000 FINA:
impl.protocol.sip.OperationSetPresenceSipImpl.fireProviderMsgStatusChangeEvent().745
status dispatching done.
14:29:17.250 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processTransactionTerminated().1129
Transaction terminated for req=REGISTER sip:192.168.1.101 SIP/2.0
Call-ID: fc209035180fb03ddb973abb02ceb2ef@0.0.0.0
CSeq: 1 REGISTER
From: <sip:6003@192.168.1.101>;tag=23d21598
To: <sip:6003@192.168.1.101>
Via: SIP/2.0/UDP
192.168.1.135:5060;branch=z9hG4bKd95fe0d12b4e888c1b7a247f9dee5c6f
Max-Forwards: 70
User-Agent: SIP Communicator 1.0-alpha3-0.build.by.SVN Windows XP
Expires: 3600
Contact: "6003" <sip:6003@192.168.1.135:5060;transport=udp>;expires=3600
Content-Length: 0

14:29:17.250 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processTransactionTerminated().1139
Found 1 processor(s) for method REGISTER
14:29:17.375 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processTransactionTerminated().1129
Transaction terminated for req=REGISTER sip:192.168.1.101 SIP/2.0
Call-ID: fc209035180fb03ddb973abb02ceb2ef@0.0.0.0
CSeq: 2 REGISTER
From: <sip:6003@192.168.1.101>;tag=23d21598
To: <sip:6003@192.168.1.101>
Max-Forwards: 70
User-Agent: SIP Communicator 1.0-alpha3-0.build.by.SVN Windows XP
Expires: 3600
Contact: "6003" <sip:6003@192.168.1.135:5060;transport=udp>;expires=3600
Via: SIP/2.0/UDP
192.168.1.135:5060;branch=z9hG4bKebb47fbb7f3254c3066cdd958c1865c2
Authorization: Digest
response="bdb0077e0756de72e236b7da89a9574c",username="6003",nonce="6eec7d69",realm="asterisk",uri="sip:192.168.1.101",algorithm=MD5
Content-Length: 0

14:29:17.375 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processTransactionTerminated().1139
Found 1 processor(s) for method REGISTER
14:29:17.953 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/UDP could not be resolved null
14:29:17.953 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/UDP
14:29:18.031 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processRequest().1181
received request=
NOTIFY sip:6003@192.168.1.135:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK08d6e902;received=192.168.1.101
From: "asterisk" <sip:asterisk@192.168.1.101>;tag=as5c7c4547
To: <sip:6003@192.168.1.135:5060;transport=udp>
Contact: <sip:asterisk@192.168.1.101>
Call-ID: 5d5a8366429d4eca2ead8b290685b99a@192.168.1.101
CSeq: 102 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: message-summary
Content-Type: application/simple-message-summary
Content-Length: 93

Messages-Waiting: no
Message-Account: sip:asterisk@192.168.1.101
Voice-Message: 0/0 (0/0)

14:29:18.953 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/UDP could not be resolved null
14:29:18.953 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/UDP
14:29:18.968 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processRequest().1181
received request=
NOTIFY sip:6003@192.168.1.135:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK08d6e902;received=192.168.1.101
From: "asterisk" <sip:asterisk@192.168.1.101>;tag=as5c7c4547
To: <sip:6003@192.168.1.135:5060;transport=udp>
Contact: <sip:asterisk@192.168.1.101>
Call-ID: 5d5a8366429d4eca2ead8b290685b99a@192.168.1.101
CSeq: 102 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: message-summary
Content-Type: application/simple-message-summary
Content-Length: 93

Messages-Waiting: no
Message-Account: sip:asterisk@192.168.1.101
Voice-Message: 0/0 (0/0)

14:29:19.937 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/UDP could not be resolved null
14:29:19.953 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/UDP
14:29:19.968 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processRequest().1181
received request=
NOTIFY sip:6003@192.168.1.135:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK08d6e902;received=192.168.1.101
From: "asterisk" <sip:asterisk@192.168.1.101>;tag=as5c7c4547
To: <sip:6003@192.168.1.135:5060;transport=udp>
Contact: <sip:asterisk@192.168.1.101>
Call-ID: 5d5a8366429d4eca2ead8b290685b99a@192.168.1.101
CSeq: 102 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: message-summary
Content-Type: application/simple-message-summary
Content-Length: 93

Messages-Waiting: no
Message-Account: sip:asterisk@192.168.1.101
Voice-Message: 0/0 (0/0)

14:29:21.921 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/UDP could not be resolved null
14:29:21.921 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/UDP
14:29:21.921 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processRequest().1181
received request=
NOTIFY sip:6003@192.168.1.135:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK08d6e902;received=192.168.1.101
From: "asterisk" <sip:asterisk@192.168.1.101>;tag=as5c7c4547
To: <sip:6003@192.168.1.135:5060;transport=udp>
Contact: <sip:asterisk@192.168.1.101>
Call-ID: 5d5a8366429d4eca2ead8b290685b99a@192.168.1.101
CSeq: 102 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: message-summary
Content-Type: application/simple-message-summary
Content-Length: 93

Messages-Waiting: no
Message-Account: sip:asterisk@192.168.1.101
Voice-Message: 0/0 (0/0)

14:29:25.937 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/UDP could not be resolved null
14:29:25.937 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/UDP
14:29:25.953 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processRequest().1181
received request=
NOTIFY sip:6003@192.168.1.135:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK08d6e902;received=192.168.1.101
From: "asterisk" <sip:asterisk@192.168.1.101>;tag=as5c7c4547
To: <sip:6003@192.168.1.135:5060;transport=udp>
Contact: <sip:asterisk@192.168.1.101>
Call-ID: 5d5a8366429d4eca2ead8b290685b99a@192.168.1.101
CSeq: 102 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: message-summary
Content-Type: application/simple-message-summary
Content-Length: 93

Messages-Waiting: no
Message-Account: sip:asterisk@192.168.1.101
Voice-Message: 0/0 (0/0)

14:29:29.937 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/UDP could not be resolved null
14:29:29.937 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/UDP
14:29:29.953 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processRequest().1181
received request=
NOTIFY sip:6003@192.168.1.135:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK08d6e902;received=192.168.1.101
From: "asterisk" <sip:asterisk@192.168.1.101>;tag=as5c7c4547
To: <sip:6003@192.168.1.135:5060;transport=udp>
Contact: <sip:asterisk@192.168.1.101>
Call-ID: 5d5a8366429d4eca2ead8b290685b99a@192.168.1.101
CSeq: 102 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: message-summary
Content-Type: application/simple-message-summary
Content-Length: 93

Messages-Waiting: no
Message-Account: sip:asterisk@192.168.1.101
Voice-Message: 0/0 (0/0)

14:29:33.937 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/UDP could not be resolved null
14:29:33.937 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/UDP
14:29:33.953 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processRequest().1181
received request=
NOTIFY sip:6003@192.168.1.135:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK08d6e902;received=192.168.1.101
From: "asterisk" <sip:asterisk@192.168.1.101>;tag=as5c7c4547
To: <sip:6003@192.168.1.135:5060;transport=udp>
Contact: <sip:asterisk@192.168.1.101>
Call-ID: 5d5a8366429d4eca2ead8b290685b99a@192.168.1.101
CSeq: 102 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: message-summary
Content-Type: application/simple-message-summary
Content-Length: 93

Messages-Waiting: no
Message-Account: sip:asterisk@192.168.1.101
Voice-Message: 0/0 (0/0)

14:29:36.937 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.getIntendedDestination().2762
Returning address 192.168.1.101/192.168.1.101 for destination 192.168.1.101
14:29:36.937 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.getLocalViaHeaders().1445
generated via headers:Via: SIP/2.0/UDP 192.168.1.135:5060

14:29:36.953 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.getIntendedDestination().2762
Returning address 192.168.1.101/192.168.1.101 for destination 192.168.1.101
14:29:36.953 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.getContactHeader().1559
generated contactHeader:Contact: "6003"
<sip:6003@192.168.1.135:5060;transport=udp>

14:29:36.953 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/udp could not be resolved null
14:29:36.953 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/udp
14:29:36.968 FINA: impl.protocol.sip.SipRegistrarConnection.register().414
sent request= REGISTER sip:192.168.1.101 SIP/2.0
Call-ID: 44ed27a2c8ba16243f414584e55bcb60@0.0.0.0
CSeq: 3 REGISTER
From: <sip:6003@192.168.1.101>;tag=11fb7c94
To: <sip:6003@192.168.1.101>
Via: SIP/2.0/UDP
192.168.1.135:5060;branch=z9hG4bK8163c5ad4ca1a339f3d7869cee32bb23
Max-Forwards: 70
User-Agent: SIP Communicator 1.0-alpha3-0.build.by.SVN Windows XP
Expires: 3600
Contact: "6003" <sip:6003@192.168.1.135:5060;transport=udp>;expires=3600
Content-Length: 0

14:29:36.968 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1018
received response=
SIP/2.0 100 Trying
Via: SIP/2.0/UDP
192.168.1.135:5060;branch=z9hG4bK8163c5ad4ca1a339f3d7869cee32bb23;received=192.168.1.135
From: <sip:6003@192.168.1.101>;tag=11fb7c94
To: <sip:6003@192.168.1.101>
Call-ID: 44ed27a2c8ba16243f414584e55bcb60@0.0.0.0
CSeq: 3 REGISTER
User-Agent: Asterisk PBX
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,SUBSCRIBE,NOTIFY
Supported: replaces
Contact: <sip:6003@192.168.1.101>
Content-Length: 0

14:29:36.968 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1036
Found 1 processor(s) for method REGISTER
14:29:36.968 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1018
received response=
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP
192.168.1.135:5060;branch=z9hG4bK8163c5ad4ca1a339f3d7869cee32bb23;received=192.168.1.135
From: <sip:6003@192.168.1.101>;tag=11fb7c94
To: <sip:6003@192.168.1.101>;tag=as2f8aca43
Call-ID: 44ed27a2c8ba16243f414584e55bcb60@0.0.0.0
CSeq: 3 REGISTER
User-Agent: Asterisk PBX
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,SUBSCRIBE,NOTIFY
Supported: replaces
WWW-Authenticate: Digest nonce="02022b8b",realm="asterisk",algorithm=MD5
Content-Length: 0

14:29:36.968 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1036
Found 1 processor(s) for method REGISTER
14:29:36.984 FINA:
impl.protocol.sip.SipRegistrarConnection.processAuthenticationChallenge().1001
Authenticating a Register request.
14:29:36.984 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/udp could not be resolved null
14:29:36.984 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/udp
14:29:36.984 FINA:
impl.protocol.sip.security.MessageDigestAlgorithm.calculateResponse().61
trying to authenticate using : MD5, 6003, asterisk, true, 02022b8b,
00000001, xyz, REGISTER, sip:192.168.1.101, , null
14:29:36.984 FINA:
impl.protocol.sip.security.SipSecurityManager.handleChallenge().274 Created
authorization header: Authorization: Digest
response="86a87012f3312cc287767d86c43735f3",username="6003",nonce="02022b8b",realm="asterisk",uri="sip:192.168.1.101",algorithm=MD5

14:29:37.000 FINA:
impl.protocol.sip.security.SipSecurityManager.handleChallenge().291
Returning authorization transaction.
14:29:37.000 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1018
received response=
SIP/2.0 100 Trying
Via: SIP/2.0/UDP
192.168.1.135:5060;branch=z9hG4bK06c73ff0c56c450d09996136b0755945;received=192.168.1.135
From: <sip:6003@192.168.1.101>;tag=11fb7c94
To: <sip:6003@192.168.1.101>
Call-ID: 44ed27a2c8ba16243f414584e55bcb60@0.0.0.0
CSeq: 4 REGISTER
User-Agent: Asterisk PBX
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,SUBSCRIBE,NOTIFY
Supported: replaces
Contact: <sip:6003@192.168.1.101>
Content-Length: 0

14:29:37.000 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1036
Found 1 processor(s) for method REGISTER
14:29:37.015 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1018
received response=
SIP/2.0 200 OK
Via: SIP/2.0/UDP
192.168.1.135:5060;branch=z9hG4bK06c73ff0c56c450d09996136b0755945;received=192.168.1.135
From: <sip:6003@192.168.1.101>;tag=11fb7c94
To: <sip:6003@192.168.1.101>;tag=as2f8aca43
Call-ID: 44ed27a2c8ba16243f414584e55bcb60@0.0.0.0
CSeq: 4 REGISTER
User-Agent: Asterisk PBX
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,SUBSCRIBE,NOTIFY
Supported: replaces
Expires: 3600
Contact: <sip:6003@192.168.1.135:5060;transport=udp>;expires=3600
Date: Fri, 03 Oct 2008 12:29:35 GMT
Content-Length: 0

14:29:37.015 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1036
Found 1 processor(s) for method REGISTER
14:29:39.984 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/UDP could not be resolved null
14:29:39.984 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/UDP
14:29:40.000 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processRequest().1181
received request=
NOTIFY sip:6003@192.168.1.135:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK39a432e3;received=192.168.1.101
From: "asterisk" <sip:asterisk@192.168.1.101>;tag=as5c2ca2cb
To: <sip:6003@192.168.1.135:5060;transport=udp>
Contact: <sip:asterisk@192.168.1.101>
Call-ID: 32c1f92f340b1e5c25ff9d61243a2a06@192.168.1.101
CSeq: 102 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: message-summary
Content-Type: application/simple-message-summary
Content-Length: 93

Messages-Waiting: no
Message-Account: sip:asterisk@192.168.1.101
Voice-Message: 0/0 (0/0)

14:29:40.937 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/UDP could not be resolved null
14:29:40.937 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/UDP
14:29:40.937 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processRequest().1181
received request=
NOTIFY sip:6003@192.168.1.135:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK39a432e3;received=192.168.1.101
From: "asterisk" <sip:asterisk@192.168.1.101>;tag=as5c2ca2cb
To: <sip:6003@192.168.1.135:5060;transport=udp>
Contact: <sip:asterisk@192.168.1.101>
Call-ID: 32c1f92f340b1e5c25ff9d61243a2a06@192.168.1.101
CSeq: 102 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: message-summary
Content-Type: application/simple-message-summary
Content-Length: 93

Messages-Waiting: no
Message-Account: sip:asterisk@192.168.1.101
Voice-Message: 0/0 (0/0)

14:29:41.953 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/UDP could not be resolved null
14:29:41.953 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/UDP
14:29:41.968 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processRequest().1181
received request=
NOTIFY sip:6003@192.168.1.135:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK39a432e3;received=192.168.1.101
From: "asterisk" <sip:asterisk@192.168.1.101>;tag=as5c2ca2cb
To: <sip:6003@192.168.1.135:5060;transport=udp>
Contact: <sip:asterisk@192.168.1.101>
Call-ID: 32c1f92f340b1e5c25ff9d61243a2a06@192.168.1.101
CSeq: 102 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: message-summary
Content-Type: application/simple-message-summary
Content-Length: 93

Messages-Waiting: no
Message-Account: sip:asterisk@192.168.1.101
Voice-Message: 0/0 (0/0)

14:29:42.468 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processTransactionTerminated().1129
Transaction terminated for req=REGISTER sip:192.168.1.101 SIP/2.0
Call-ID: 44ed27a2c8ba16243f414584e55bcb60@0.0.0.0
CSeq: 3 REGISTER
From: <sip:6003@192.168.1.101>;tag=11fb7c94
To: <sip:6003@192.168.1.101>
Via: SIP/2.0/UDP
192.168.1.135:5060;branch=z9hG4bK8163c5ad4ca1a339f3d7869cee32bb23
Max-Forwards: 70
User-Agent: SIP Communicator 1.0-alpha3-0.build.by.SVN Windows XP
Expires: 3600
Contact: "6003" <sip:6003@192.168.1.135:5060;transport=udp>;expires=3600
Content-Length: 0

14:29:42.468 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processTransactionTerminated().1139
Found 1 processor(s) for method REGISTER
14:29:42.500 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processTransactionTerminated().1129
Transaction terminated for req=REGISTER sip:192.168.1.101 SIP/2.0
Call-ID: 44ed27a2c8ba16243f414584e55bcb60@0.0.0.0
CSeq: 4 REGISTER
From: <sip:6003@192.168.1.101>;tag=11fb7c94
To: <sip:6003@192.168.1.101>
Max-Forwards: 70
User-Agent: SIP Communicator 1.0-alpha3-0.build.by.SVN Windows XP
Expires: 3600
Contact: "6003" <sip:6003@192.168.1.135:5060;transport=udp>;expires=3600
Via: SIP/2.0/UDP
192.168.1.135:5060;branch=z9hG4bK06c73ff0c56c450d09996136b0755945
Authorization: Digest
response="86a87012f3312cc287767d86c43735f3",username="6003",nonce="02022b8b",realm="asterisk",uri="sip:192.168.1.101",algorithm=MD5
Content-Length: 0

14:29:42.500 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processTransactionTerminated().1139
Found 1 processor(s) for method REGISTER
14:29:43.953 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/UDP could not be resolved null
14:29:43.953 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/UDP
14:29:43.968 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processRequest().1181
received request=
NOTIFY sip:6003@192.168.1.135:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK39a432e3;received=192.168.1.101
From: "asterisk" <sip:asterisk@192.168.1.101>;tag=as5c2ca2cb
To: <sip:6003@192.168.1.135:5060;transport=udp>
Contact: <sip:asterisk@192.168.1.101>
Call-ID: 32c1f92f340b1e5c25ff9d61243a2a06@192.168.1.101
CSeq: 102 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: message-summary
Content-Type: application/simple-message-summary
Content-Length: 93

Messages-Waiting: no
Message-Account: sip:asterisk@192.168.1.101
Voice-Message: 0/0 (0/0)

14:29:46.031 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/UDP could not be resolved null
14:29:46.031 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/UDP
14:29:46.046 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processRequest().1181
received request=
INVITE sip:6003@192.168.1.135:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK6b6a792c;received=192.168.1.101
From: "6000" <sip:6000@192.168.1.101>;tag=as131e994d
To: <sip:6003@192.168.1.135:5060;transport=udp>
Contact: <sip:6000@192.168.1.101>
Call-ID: 5da645183b230eb37be4b3f21234c8b6@192.168.1.101
CSeq: 102 INVITE
User-Agent: Asterisk PBX
Max-Forwards: 70
Date: Fri, 03 Oct 2008 12:29:44 GMT
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,SUBSCRIBE,NOTIFY
Supported: replaces
Content-Type: application/sdp
Content-Length: 184

v=0
o=root 8812 8812 IN IP4 192.168.1.101
s=session
c=IN IP4 192.168.1.101
t=0 0
m=audio 13566 RTP/AVP 0
a=rtpmap:0 PCMU/8000
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv

14:29:46.046 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processRequest().1265 Found
1 processor(s) for method INVITE
14:29:46.093 FINA:
impl.protocol.sip.OperationSetBasicTelephonySipImpl.processRequest().532
received INVITE
14:29:46.093 FINA:
impl.protocol.sip.OperationSetBasicTelephonySipImpl.processRequest().538
request is an INVITE. Dialog state=null
14:29:46.125 FINA: service.protocol.Call.fireCallParticipantEvent().174
Dispatching a CallParticipant event to 0 listeners. event is:
CallParticipantEvent: ID=1 source participant=6000
<sip:6000@192.168.1.101>;status=Unknown source call=Call:
id=122303698609327649252 participants=1
14:29:46.125 FINA:
service.protocol.AbstractCallParticipant.fireCallParticipantChangeEvent().101
Dispatching a CallParticipantChangeEvent event to 1 listeners. event is:
CallParticipantChangeEvent: type=CallParticipantStatusChange
oldV=net.java.sip.communicator.service.protocol.CallParticipantState:Unknown
newV=net.java.sip.communicator.service.protocol.CallParticipantState:Incoming
Call for participant=6000 <sip:6000@192.168.1.101>;status=Incoming Call
14:29:46.125 FINA:
service.protocol.AbstractOperationSetBasicTelephony.fireCallEvent().59
Dispatching a CallEvent to 2 listeners. event is: CallEvent:[ id=2
Call=Call: id=122303698609327649252 participants=1]
14:29:46.281 FINA:
impl.protocol.sip.OperationSetBasicTelephonySipImpl.processInvite().1508
Invite seems ok, we'll say RINGING.
14:29:46.281 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.attachToTag().2443
generated to tag: e32f9b83
14:29:46.281 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.getIntendedDestination().2762
Returning address 192.168.1.101/192.168.1.101 for destination 192.168.1.101
14:29:46.281 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.getContactHeader().1559
generated contactHeader:Contact: "6003"
<sip:6003@192.168.1.135:5060;transport=udp>

14:29:46.281 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/UDP could not be resolved null
14:29:46.296 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/UDP
14:29:46.296 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/UDP could not be resolved null
14:29:46.296 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/UDP
14:29:46.312 FINA:
impl.protocol.sip.OperationSetBasicTelephonySipImpl.processInvite().1554
sent a RINGING response: SIP/2.0 180 Ringing
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK6b6a792c;received=192.168.1.101
From: "6000" <sip:6000@192.168.1.101>;tag=as131e994d
To: "6003" <sip:6003@192.168.1.135:5060;transport=udp>;tag=e32f9b83
Call-ID: 5da645183b230eb37be4b3f21234c8b6@192.168.1.101
CSeq: 102 INVITE
User-Agent: SIP Communicator 1.0-alpha3-0.build.by.SVN Windows XP
Contact: "6003" <sip:6003@192.168.1.135:5060;transport=udp>
Content-Length: 0

14:29:47.953 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/UDP could not be resolved null
14:29:47.953 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/UDP
14:29:47.968 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processRequest().1181
received request=
NOTIFY sip:6003@192.168.1.135:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK39a432e3;received=192.168.1.101
From: "asterisk" <sip:asterisk@192.168.1.101>;tag=as5c2ca2cb
To: <sip:6003@192.168.1.135:5060;transport=udp>
Contact: <sip:asterisk@192.168.1.101>
Call-ID: 32c1f92f340b1e5c25ff9d61243a2a06@192.168.1.101
CSeq: 102 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: message-summary
Content-Type: application/simple-message-summary
Content-Length: 93

Messages-Waiting: no
Message-Account: sip:asterisk@192.168.1.101
Voice-Message: 0/0 (0/0)

14:29:51.078 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.attachToTag().2431 We seem
to already have a tag in this dialog. Returning
14:29:51.250 FINA:
impl.media.CallSessionImpl.registerCustomCodecFormats().2150 registering
format ilbc/rtp, 8000.0 Hz, 16-bit, Mono, LittleEndian, Signed with RTP
manager
14:29:51.265 FINA:
impl.media.CallSessionImpl.registerCustomCodecFormats().2150 registering
format ALAW/rtp, 8000.0 Hz, 8-bit, Mono, Signed with RTP manager
14:29:51.265 FINA:
impl.media.CallSessionImpl.registerCustomCodecFormats().2150 registering
format speex/rtp, 8000.0 Hz, 8-bit, Mono, Signed with RTP manager
14:29:51.343 FINA:
impl.netaddr.NetworkAddressManagerServiceImpl.getPublicAddressFor().375 Stun
is disabled for destination /192.168.1.101, skipping mapped address recovery
(useStun=false, IPv6@=false).
14:29:51.343 FINA: impl.media.CallSessionImpl.allocateMediaPorts().1925
AudioSessionAddress=DataAddress: /0.0.0.0
ControlAddress: /0.0.0.0
DataPort: 5000
ControlPort: 5001
14:29:51.343 FINA: impl.media.CallSessionImpl.allocateMediaPorts().1926
AudioPublicAddress=/192.168.1.135:5000
14:29:51.343 FINA:
impl.netaddr.NetworkAddressManagerServiceImpl.getPublicAddressFor().375 Stun
is disabled for destination /192.168.1.101, skipping mapped address recovery
(useStun=false, IPv6@=false).
14:29:51.343 FINA: com.sun.media.rtp.RTPSessionMgr.initialize() Looking for
local data address: /0.0.0.0 and control address/0.0.0.0
14:29:51.375 FINA: com.sun.media.rtp.RTPSessionMgr.initialize() Looking for
local data address: /0.0.0.0 and control address/0.0.0.0
14:29:51.375 FINA:
impl.media.CallSessionImpl.createSessionDescription().1417 Will create media
descs with: audio public address=/192.168.1.135:5000 and video public
address=/192.168.1.135:5002
14:29:51.375 FINA: impl.media.MediaControl.calculateSupportedEncodings().599
We will be able to transmit in:
14:29:51.937 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/UDP could not be resolved null
14:29:51.953 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/UDP
14:29:51.953 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processRequest().1181
received request=
NOTIFY sip:6003@192.168.1.135:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK39a432e3;received=192.168.1.101
From: "asterisk" <sip:asterisk@192.168.1.101>;tag=as5c2ca2cb
To: <sip:6003@192.168.1.135:5060;transport=udp>
Contact: <sip:asterisk@192.168.1.101>
Call-ID: 32c1f92f340b1e5c25ff9d61243a2a06@192.168.1.101
CSeq: 102 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: message-summary
Content-Type: application/simple-message-summary
Content-Length: 93

Messages-Waiting: no
Message-Account: sip:asterisk@192.168.1.101
Voice-Message: 0/0 (0/0)

14:29:52.093 FINA: impl.media.MediaControl.calculateSupportedEncodings().623
Audio=[1]=dvi/rtp; sdp=5
14:29:52.093 FINA: impl.media.MediaControl.calculateSupportedEncodings().623
Audio=[2]=speex/rtp; sdp=110
14:29:52.093 FINA: impl.media.MediaControl.calculateSupportedEncodings().623
Audio=[3]=ilbc/rtp; sdp=97
14:29:52.093 FINA: impl.media.MediaControl.calculateSupportedEncodings().623
Audio=[6]=g723/rtp; sdp=4
14:29:52.093 FINA: impl.media.MediaControl.calculateSupportedEncodings().623
Audio=[7]=gsm/rtp; sdp=3
14:29:52.093 FINA: impl.media.MediaControl.calculateSupportedEncodings().623
Audio=[8]=ULAW/rtp; sdp=0
14:29:52.093 FINA: impl.media.MediaControl.calculateSupportedEncodings().623
Audio=[9]=ALAW/rtp; sdp=8
14:29:52.093 FINA: impl.media.MediaControl.calculateSupportedEncodings().644
unknown encoding format mpegaudio/rtp
14:29:52.093 FINA: impl.media.MediaControl.calculateSupportedEncodings().644
unknown encoding format mpegaudio/rtp
14:29:52.093 FINA: impl.media.MediaControl.calculateSupportedEncodings().644
unknown encoding format mpegaudio/rtp
14:29:52.093 FINA: impl.media.MediaControl.calculateSupportedEncodings().644
unknown encoding format mpegaudio/rtp
14:29:52.109 FINA: impl.media.MediaControl.calculateSupportedEncodings().644
unknown encoding format mpegaudio/rtp
14:29:52.109 FINA: impl.media.MediaControl.calculateSupportedEncodings().644
unknown encoding format mpegaudio/rtp
14:29:52.109 FINA: impl.media.MediaControl.calculateSupportedEncodings().644
unknown encoding format mpegaudio/rtp
14:29:52.796 FINA:
impl.media.MediaControl.createDataSourceForEncodings().825 Available
encodings are:
14:29:52.796 FINA:
impl.media.MediaControl.createDataSourceForEncodings().828 track[1]
format[1]=dvi/rtp
14:29:52.796 FINA:
impl.media.MediaControl.createDataSourceForEncodings().828 track[1]
format[2]=speex/rtp
14:29:52.796 FINA:
impl.media.MediaControl.createDataSourceForEncodings().828 track[1]
format[3]=ilbc/rtp
14:29:52.796 FINA:
impl.media.MediaControl.createDataSourceForEncodings().828 track[1]
format[4]=dvi/rtp
14:29:52.796 FINA:
impl.media.MediaControl.createDataSourceForEncodings().828 track[1]
format[5]=dvi/rtp
14:29:52.796 FINA:
impl.media.MediaControl.createDataSourceForEncodings().828 track[1]
format[6]=g723/rtp
14:29:52.812 FINA:
impl.media.MediaControl.createDataSourceForEncodings().828 track[1]
format[7]=gsm/rtp
14:29:52.812 FINA:
impl.media.MediaControl.createDataSourceForEncodings().828 track[1]
format[8]=ULAW/rtp
14:29:52.812 FINA:
impl.media.MediaControl.createDataSourceForEncodings().828 track[1]
format[9]=ALAW/rtp
14:29:52.812 FINA:
impl.media.MediaControl.createDataSourceForEncodings().828 track[1]
format[10]=mpegaudio/rtp
14:29:52.812 FINA:
impl.media.MediaControl.createDataSourceForEncodings().828 track[1]
format[11]=mpegaudio/rtp
14:29:52.812 FINA:
impl.media.MediaControl.createDataSourceForEncodings().828 track[1]
format[12]=mpegaudio/rtp
14:29:52.812 FINA:
impl.media.MediaControl.createDataSourceForEncodings().828 track[1]
format[13]=mpegaudio/rtp
14:29:52.812 FINA:
impl.media.MediaControl.createDataSourceForEncodings().828 track[1]
format[14]=mpegaudio/rtp
14:29:52.812 FINA:
impl.media.MediaControl.createDataSourceForEncodings().828 track[1]
format[15]=mpegaudio/rtp
14:29:52.812 FINA:
impl.media.MediaControl.createDataSourceForEncodings().828 track[1]
format[16]=mpegaudio/rtp
14:29:52.812 FINA:
impl.media.MediaControl.createDataSourceForEncodings().877 Track 0 is set to
transmit as: ULAW/rtp, 8000.0 Hz, 8-bit, Mono, FrameSize=8 bits
14:29:54.328 FINA: impl.media.CallSessionImpl.update().2345 received the
following JMF SendStreamEvent -
javax.media.rtp.event.NewSendStreamEvent=javax.media.rtp.event.NewSendStreamEvent[source
= RTPManager
       SSRCCache com.sun.media.rtp.SSRCCache@a88bc2
       Dataport 5000
       Controlport 5001
       Address 0.0.0.0
       RTPForwarder com.sun.media.rtp.util.PacketForwarder@16f8789
       RTPDemux com.sun.media.rtp.RTPDemultiplexer@12b6037]
14:29:54.328 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.getIntendedDestination().2762
Returning address 192.168.1.101/192.168.1.101 for destination 192.168.1.101
14:29:54.328 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.getContactHeader().1559
generated contactHeader:Contact: "6003"
<sip:6003@192.168.1.135:5060;transport=udp>

14:29:54.359 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/UDP could not be resolved null
14:29:54.359 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/UDP
14:29:54.359 FINA:
impl.protocol.sip.OperationSetBasicTelephonySipImpl.answerCallParticipant().2796
sent response
SIP/2.0 200 OK
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK6b6a792c;received=192.168.1.101
From: "6000" <sip:6000@192.168.1.101>;tag=as131e994d
To: <sip:6003@192.168.1.135:5060;transport=udp>;tag=e32f9b83
Call-ID: 5da645183b230eb37be4b3f21234c8b6@192.168.1.101
CSeq: 102 INVITE
User-Agent: SIP Communicator 1.0-alpha3-0.build.by.SVN Windows XP
Content-Type: application/sdp
Contact: "6003" <sip:6003@192.168.1.135:5060;transport=udp>
Content-Length: 98

v=0
o=6003 0 0 IN IP4 192.168.1.135
s=-
c=IN IP4 192.168.1.135
t=0 0
m=audio 5000 RTP/AVP 0

14:29:54.390 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/UDP could not be resolved null
14:29:54.390 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/UDP could not be resolved null
14:29:54.406 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/UDP
14:29:54.406 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/UDP
14:29:54.406 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processRequest().1181
received request=
ACK sip:6003@192.168.1.135:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK1b83304d;received=192.168.1.101
From: "6000" <sip:6000@192.168.1.101>;tag=as131e994d
To: <sip:6003@192.168.1.135:5060;transport=udp>;tag=e32f9b83
Contact: <sip:6000@192.168.1.101>
Call-ID: 5da645183b230eb37be4b3f21234c8b6@192.168.1.101
CSeq: 102 ACK
User-Agent: Asterisk PBX
Max-Forwards: 70
Content-Length: 0

14:29:54.421 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processRequest().1265 Found
1 processor(s) for method ACK
14:29:54.421 FINA:
service.protocol.AbstractCallParticipant.fireCallParticipantChangeEvent().101
Dispatching a CallParticipantChangeEvent event to 4 listeners. event is:
CallParticipantChangeEvent: type=CallParticipantStatusChange
oldV=net.java.sip.communicator.service.protocol.CallParticipantState:Incoming
Call
newV=net.java.sip.communicator.service.protocol.CallParticipantState:Connected
for participant=6000 <sip:6000@192.168.1.101>;status=Connected
14:29:54.421 FINA: service.protocol.Call.fireCallChangeEvent().226
Dispatching a CallChange event to 4 listeners. event is: CallChangeEvent:
type=CallState
oldV=net.java.sip.communicator.service.protocol.CallState:Initializing
newV=net.java.sip.communicator.service.protocol.CallState:In Progress
14:29:54.421 FINA: impl.media.CallSessionImpl.callStateChanged().2176 call
connected. starting streaming
14:29:54.437 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processRequest().1181
received request=
INVITE sip:6003@192.168.1.135:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK7ce039f7;received=192.168.1.101
From: "6000" <sip:6000@192.168.1.101>;tag=as131e994d
To: <sip:6003@192.168.1.135:5060;transport=udp>;tag=e32f9b83
Contact: <sip:6000@192.168.1.101>
Call-ID: 5da645183b230eb37be4b3f21234c8b6@192.168.1.101
CSeq: 103 INVITE
User-Agent: Asterisk PBX
Max-Forwards: 70
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,SUBSCRIBE,NOTIFY
Supported: replaces
Content-Type: application/sdp
Content-Length: 183

v=0
o=root 8812 8813 IN IP4 192.168.1.102
s=session
c=IN IP4 192.168.1.102
t=0 0
m=audio 5004 RTP/AVP 0
a=rtpmap:0 PCMU/8000
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv

14:29:54.453 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processRequest().1265 Found
1 processor(s) for method INVITE
14:29:54.453 FINA:
impl.protocol.sip.OperationSetBasicTelephonySipImpl.processRequest().532
received INVITE
14:29:54.453 FINA:
impl.protocol.sip.OperationSetBasicTelephonySipImpl.processRequest().538
request is an INVITE. Dialog state=Confirmed Dialog
14:29:54.453 FINA:
impl.protocol.sip.OperationSetBasicTelephonySipImpl.processInvite().1508
Invite seems ok, we'll say OK.
14:29:54.453 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.attachToTag().2431 We seem
to already have a tag in this dialog. Returning
14:29:54.453 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.getIntendedDestination().2762
Returning address 192.168.1.101/192.168.1.101 for destination 192.168.1.101
14:29:54.453 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.getContactHeader().1559
generated contactHeader:Contact: "6003"
<sip:6003@192.168.1.135:5060;transport=udp>

14:29:54.453 FINA:
impl.media.CallSessionImpl.createSessionDescription().1417 Will create media
descs with: audio public address=/192.168.1.135:5000 and video public
address=/192.168.1.135:5002
14:29:54.484 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/UDP could not be resolved null
14:29:54.484 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/UDP
14:29:54.484 FINA:
impl.protocol.sip.OperationSetBasicTelephonySipImpl.processInvite().1554
sent a OK response: SIP/2.0 200 OK
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK7ce039f7;received=192.168.1.101
From: "6000" <sip:6000@192.168.1.101>;tag=as131e994d
To: "6003" <sip:6003@192.168.1.135:5060;transport=udp>;tag=e32f9b83
Call-ID: 5da645183b230eb37be4b3f21234c8b6@192.168.1.101
CSeq: 103 INVITE
User-Agent: SIP Communicator 1.0-alpha3-0.build.by.SVN Windows XP
Contact: "6003" <sip:6003@192.168.1.135:5060;transport=udp>
Content-Type: application/sdp
Content-Length: 110

v=0
o=6003 0 0 IN IP4 192.168.1.135
s=-
c=IN IP4 192.168.1.135
t=0 0
m=audio 5000 RTP/AVP 0
a=sendrecv

14:29:54.500 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/UDP could not be resolved null
14:29:54.500 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/UDP
14:29:54.515 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processRequest().1181
received request=
ACK sip:6003@192.168.1.135:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK3543b597;received=192.168.1.101
From: "6000" <sip:6000@192.168.1.101>;tag=as131e994d
To: <sip:6003@192.168.1.135:5060;transport=udp>;tag=e32f9b83
Contact: <sip:6000@192.168.1.101>
Call-ID: 5da645183b230eb37be4b3f21234c8b6@192.168.1.101
CSeq: 103 ACK
User-Agent: Asterisk PBX
Max-Forwards: 70
Content-Length: 0

14:29:54.515 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processRequest().1265 Found
1 processor(s) for method ACK
14:29:54.578 FINA: impl.media.CallSessionImpl.update().2366 received a new
incoming stream. javax.media.rtp.event.NewReceiveStreamEvent[source =
RTPManager
       SSRCCache com.sun.media.rtp.SSRCCache@a88bc2
       Dataport 5000
       Controlport 5001
       Address 0.0.0.0
       RTPForwarder com.sun.media.rtp.util.PacketForwarder@16f8789
       RTPDemux com.sun.media.rtp.RTPDemultiplexer@12b6037]
14:29:54.578 FINA: impl.media.CallSessionImpl.update().2376 Received new RTP
stream: ULAW/rtp, 8000.0 Hz, 8-bit, Mono
14:29:54.593 FINA: impl.media.CallSessionImpl.controllerUpdate().2450
Received a ControllerEvent:
javax.media.TransitionEvent[source=com.sun.media.content.unknown.Handler@112ee4f,previous=Unrealized,current=Realizing,target=Realized]
14:29:54.625 FINA: impl.media.CallSessionImpl.controllerUpdate().2450
Received a ControllerEvent:
javax.media.RealizeCompleteEvent[source=com.sun.media.content.unknown.Handler@112ee4f,previous=Realizing,current=Realized,target=Realized]
14:29:54.625 FINA: impl.media.CallSessionImpl.controllerUpdate().2492 A
player was realized and will be started.
14:29:54.625 FINA: impl.media.CallSessionImpl.controllerUpdate().2450
Received a ControllerEvent:
javax.media.TransitionEvent[source=com.sun.media.content.unknown.Handler@112ee4f,previous=Realized,current=Prefetching,target=Started]
14:29:54.625 FINA: impl.media.CallSessionImpl.controllerUpdate().2450
Received a ControllerEvent:
javax.media.PrefetchCompleteEvent[source=com.sun.media.content.unknown.Handler@112ee4f,previous=Prefetching,current=Prefetched,target=Started]
14:29:54.640 FINA: impl.media.CallSessionImpl.controllerUpdate().2450
Received a ControllerEvent:
javax.media.StartEvent[source=com.sun.media.content.unknown.Handler@112ee4f,previous=Prefetched,current=Started,target=Started,mediaTime=javax.media.Time@16c1857,timeBaseTime=javax.media.Time@1b1fd9c]
14:29:54.640 FINA: impl.media.CallSessionImpl.controllerUpdate().2551
Received a StartEvent
14:29:54.796 FINA: impl.media.CallSessionImpl.update().2366 received a new
incoming stream. javax.media.rtp.event.NewReceiveStreamEvent[source =
RTPManager
       SSRCCache com.sun.media.rtp.SSRCCache@a88bc2
       Dataport 5000
       Controlport 5001
       Address 0.0.0.0
       RTPForwarder com.sun.media.rtp.util.PacketForwarder@16f8789
       RTPDemux com.sun.media.rtp.RTPDemultiplexer@12b6037]
14:29:54.796 FINA: impl.media.CallSessionImpl.update().2376 Received new RTP
stream: ULAW/rtp, 8000.0 Hz, 8-bit, Mono
14:29:54.812 FINA: impl.media.CallSessionImpl.controllerUpdate().2450
Received a ControllerEvent:
javax.media.TransitionEvent[source=com.sun.media.content.unknown.Handler@163765,previous=Unrealized,current=Realizing,target=Realized]
14:29:54.828 FINA: impl.media.CallSessionImpl.controllerUpdate().2450
Received a ControllerEvent:
javax.media.RealizeCompleteEvent[source=com.sun.media.content.unknown.Handler@163765,previous=Realizing,current=Realized,target=Realized]
14:29:54.828 FINA: impl.media.CallSessionImpl.controllerUpdate().2492 A
player was realized and will be started.
14:29:54.828 FINA: impl.media.CallSessionImpl.controllerUpdate().2450
Received a ControllerEvent:
javax.media.TransitionEvent[source=com.sun.media.content.unknown.Handler@163765,previous=Realized,current=Prefetching,target=Started]
14:29:54.843 FINA: impl.media.CallSessionImpl.controllerUpdate().2450
Received a ControllerEvent:
javax.media.PrefetchCompleteEvent[source=com.sun.media.content.unknown.Handler@163765,previous=Prefetching,current=Prefetched,target=Started]
14:29:54.843 FINA: impl.media.CallSessionImpl.controllerUpdate().2450
Received a ControllerEvent:
javax.media.StartEvent[source=com.sun.media.content.unknown.Handler@163765,previous=Prefetched,current=Started,target=Started,mediaTime=javax.media.Time@7262b6,timeBaseTime=javax.media.Time@1aecf45]
14:29:54.843 FINA: impl.media.CallSessionImpl.controllerUpdate().2551
Received a StartEvent
14:29:55.937 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/UDP could not be resolved null
14:29:55.937 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/UDP
14:29:55.937 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processRequest().1181
received request=
NOTIFY sip:6003@192.168.1.135:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK39a432e3;received=192.168.1.101
From: "asterisk" <sip:asterisk@192.168.1.101>;tag=as5c2ca2cb
To: <sip:6003@192.168.1.135:5060;transport=udp>
Contact: <sip:asterisk@192.168.1.101>
Call-ID: 32c1f92f340b1e5c25ff9d61243a2a06@192.168.1.101
CSeq: 102 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: message-summary
Content-Type: application/simple-message-summary
Content-Length: 93

Messages-Waiting: no
Message-Account: sip:asterisk@192.168.1.101
Voice-Message: 0/0 (0/0)

14:29:59.468 FINA: impl.media.CallSessionImpl.update().2322 A new
participant had just joined:
14:29:59.468 FINA: impl.media.CallSessionImpl.update().2433 The previously
unidentified stream ULAW/rtp, 8000.0 Hz, 8-bit, Mono had now been identified
as sent by:
14:30:02.015 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.getIntendedDestination().2762
Returning address 192.168.1.101/192.168.1.101 for destination 192.168.1.101
14:30:02.015 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.getLocalViaHeaders().1445
generated via headers:Via: SIP/2.0/UDP 192.168.1.135:5060

14:30:02.015 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.getIntendedDestination().2762
Returning address 192.168.1.101/192.168.1.101 for destination 192.168.1.101
14:30:02.031 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.getContactHeader().1559
generated contactHeader:Contact: "6003"
<sip:6003@192.168.1.135:5060;transport=udp>

14:30:02.062 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/udp could not be resolved null
14:30:02.062 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/udp
14:30:02.062 FINA: impl.protocol.sip.SipRegistrarConnection.register().414
sent request= REGISTER sip:192.168.1.101 SIP/2.0
Call-ID: f213606d2b0e107a61a74b09b16045d3@0.0.0.0
CSeq: 5 REGISTER
From: <sip:6003@192.168.1.101>;tag=b19cb5ad
To: <sip:6003@192.168.1.101>
Via: SIP/2.0/UDP
192.168.1.135:5060;branch=z9hG4bK4e0f0a9efc49ea65ec810d87eb08f775
Max-Forwards: 70
User-Agent: SIP Communicator 1.0-alpha3-0.build.by.SVN Windows XP
Expires: 3600
Contact: "6003" <sip:6003@192.168.1.135:5060;transport=udp>;expires=3600
Content-Length: 0

14:30:02.078 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1018
received response=
SIP/2.0 100 Trying
Via: SIP/2.0/UDP
192.168.1.135:5060;branch=z9hG4bK4e0f0a9efc49ea65ec810d87eb08f775;received=192.168.1.135
From: <sip:6003@192.168.1.101>;tag=b19cb5ad
To: <sip:6003@192.168.1.101>
Call-ID: f213606d2b0e107a61a74b09b16045d3@0.0.0.0
CSeq: 5 REGISTER
User-Agent: Asterisk PBX
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,SUBSCRIBE,NOTIFY
Supported: replaces
Contact: <sip:6003@192.168.1.101>
Content-Length: 0

14:30:02.093 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1036
Found 1 processor(s) for method REGISTER
14:30:02.093 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1018
received response=
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP
192.168.1.135:5060;branch=z9hG4bK4e0f0a9efc49ea65ec810d87eb08f775;received=192.168.1.135
From: <sip:6003@192.168.1.101>;tag=b19cb5ad
To: <sip:6003@192.168.1.101>;tag=as4f359166
Call-ID: f213606d2b0e107a61a74b09b16045d3@0.0.0.0
CSeq: 5 REGISTER
User-Agent: Asterisk PBX
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,SUBSCRIBE,NOTIFY
Supported: replaces
WWW-Authenticate: Digest nonce="3f9f7283",realm="asterisk",algorithm=MD5
Content-Length: 0

14:30:02.093 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1036
Found 1 processor(s) for method REGISTER
14:30:02.093 FINA:
impl.protocol.sip.SipRegistrarConnection.processAuthenticationChallenge().1001
Authenticating a Register request.
14:30:02.109 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/udp could not be resolved null
14:30:02.109 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/udp
14:30:02.109 FINA:
impl.protocol.sip.security.MessageDigestAlgorithm.calculateResponse().61
trying to authenticate using : MD5, 6003, asterisk, true, 3f9f7283,
00000001, xyz, REGISTER, sip:192.168.1.101, , null
14:30:02.109 FINA:
impl.protocol.sip.security.SipSecurityManager.handleChallenge().274 Created
authorization header: Authorization: Digest
response="331617856c35b8a67febf5268b5c71d0",username="6003",nonce="3f9f7283",realm="asterisk",uri="sip:192.168.1.101",algorithm=MD5

14:30:02.125 FINA:
impl.protocol.sip.security.SipSecurityManager.handleChallenge().291
Returning authorization transaction.
14:30:02.125 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1018
received response=
SIP/2.0 100 Trying
Via: SIP/2.0/UDP
192.168.1.135:5060;branch=z9hG4bK2375a625cb6ab421d131af2a5aad7d2a;received=192.168.1.135
From: <sip:6003@192.168.1.101>;tag=b19cb5ad
To: <sip:6003@192.168.1.101>
Call-ID: f213606d2b0e107a61a74b09b16045d3@0.0.0.0
CSeq: 6 REGISTER
User-Agent: Asterisk PBX
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,SUBSCRIBE,NOTIFY
Supported: replaces
Contact: <sip:6003@192.168.1.101>
Content-Length: 0

14:30:02.125 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1036
Found 1 processor(s) for method REGISTER
14:30:02.125 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1018
received response=
SIP/2.0 200 OK
Via: SIP/2.0/UDP
192.168.1.135:5060;branch=z9hG4bK2375a625cb6ab421d131af2a5aad7d2a;received=192.168.1.135
From: <sip:6003@192.168.1.101>;tag=b19cb5ad
To: <sip:6003@192.168.1.101>;tag=as4f359166
Call-ID: f213606d2b0e107a61a74b09b16045d3@0.0.0.0
CSeq: 6 REGISTER
User-Agent: Asterisk PBX
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,SUBSCRIBE,NOTIFY
Supported: replaces
Expires: 3600
Contact: <sip:6003@192.168.1.135:5060;transport=udp>;expires=3600
Date: Fri, 03 Oct 2008 12:30:01 GMT
Content-Length: 0

14:30:02.140 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1036
Found 1 processor(s) for method REGISTER
14:30:02.796 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processTransactionTerminated().1129
Transaction terminated for req=INVITE
sip:6003@192.168.1.135:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK6b6a792c;received=192.168.1.101
From: "6000" <sip:6000@192.168.1.101>;tag=as131e994d
To: <sip:6003@192.168.1.135:5060;transport=udp>
Contact: <sip:6000@192.168.1.101>
Call-ID: 5da645183b230eb37be4b3f21234c8b6@192.168.1.101
CSeq: 102 INVITE
User-Agent: Asterisk PBX
Max-Forwards: 70
Date: Fri, 03 Oct 2008 12:29:44 GMT
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,SUBSCRIBE,NOTIFY
Supported: replaces
Content-Type: application/sdp
Content-Length: 184

v=0
o=root 8812 8812 IN IP4 192.168.1.101
s=session
c=IN IP4 192.168.1.101
t=0 0
m=audio 13566 RTP/AVP 0
a=rtpmap:0 PCMU/8000
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv

14:30:02.812 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processTransactionTerminated().1139
Found 1 processor(s) for method INVITE
14:30:03.000 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processTransactionTerminated().1129
Transaction terminated for req=INVITE
sip:6003@192.168.1.135:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK7ce039f7;received=192.168.1.101
From: "6000" <sip:6000@192.168.1.101>;tag=as131e994d
To: <sip:6003@192.168.1.135:5060;transport=udp>;tag=e32f9b83
Contact: <sip:6000@192.168.1.101>
Call-ID: 5da645183b230eb37be4b3f21234c8b6@192.168.1.101
CSeq: 103 INVITE
User-Agent: Asterisk PBX
Max-Forwards: 70
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,SUBSCRIBE,NOTIFY
Supported: replaces
Content-Type: application/sdp
Content-Length: 183

v=0
o=root 8812 8813 IN IP4 192.168.1.102
s=session
c=IN IP4 192.168.1.102
t=0 0
m=audio 5004 RTP/AVP 0
a=rtpmap:0 PCMU/8000
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv

14:30:03.000 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processTransactionTerminated().1139
Found 1 processor(s) for method INVITE
14:30:06.640 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.getIntendedDestination().2762
Returning address 192.168.1.101/192.168.1.101 for destination 192.168.1.101
14:30:06.640 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.getLocalViaHeaders().1445
generated via headers:Via: SIP/2.0/UDP 192.168.1.135:5060

14:30:06.656 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/udp could not be resolved null
14:30:06.671 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/udp
14:30:06.671 FINA:
impl.protocol.sip.OperationSetBasicTelephonySipImpl.sendRequest().457 Sent
request:
BYE sip:6000@192.168.1.101 SIP/2.0
CSeq: 1 BYE
From: <sip:6003@192.168.1.135:5060;transport=udp>;tag=e32f9b83
To: "6000" <sip:6000@192.168.1.101>;tag=as131e994d
Call-ID: 5da645183b230eb37be4b3f21234c8b6@192.168.1.101
User-Agent: SIP Communicator 1.0-alpha3-0.build.by.SVN Windows XP
Max-Forwards: 70
Via: SIP/2.0/UDP
192.168.1.135:5060;branch=z9hG4bK4c26826ce4e59f90822acfff08b8540a
Content-Length: 0

14:30:06.687 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1018
received response=
SIP/2.0 200 OK
Via: SIP/2.0/UDP
192.168.1.135:5060;branch=z9hG4bK4c26826ce4e59f90822acfff08b8540a;received=192.168.1.135
From: <sip:6003@192.168.1.135:5060;transport=udp>;tag=e32f9b83
To: "6000" <sip:6000@192.168.1.101>;tag=as131e994d
Call-ID: 5da645183b230eb37be4b3f21234c8b6@192.168.1.101
CSeq: 1 BYE
User-Agent: Asterisk PBX
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,SUBSCRIBE,NOTIFY
Supported: replaces
Contact: <sip:6000@192.168.1.101>
Content-Length: 0

14:30:06.687 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1036
Found 1 processor(s) for method BYE
14:30:06.703 FINA:
service.protocol.AbstractCallParticipant.fireCallParticipantChangeEvent().101
Dispatching a CallParticipantChangeEvent event to 4 listeners. event is:
CallParticipantChangeEvent: type=CallParticipantStatusChange
oldV=net.java.sip.communicator.service.protocol.CallParticipantState:Connected
newV=net.java.sip.communicator.service.protocol.CallParticipantState:Disconnected
for participant=6000 <sip:6000@192.168.1.101>;status=Disconnected
14:30:06.703 FINA: service.protocol.Call.fireCallParticipantEvent().174
Dispatching a CallParticipant event to 4 listeners. event is:
CallParticipantEvent: ID=2 source participant=6000
<sip:6000@192.168.1.101>;status=Disconnected source call=Call:
id=122303698609327649252 participants=0
14:30:06.703 FINA: service.protocol.Call.fireCallChangeEvent().226
Dispatching a CallChange event to 4 listeners. event is: CallChangeEvent:
type=CallState oldV=net.java.sip.communicator.service.protocol.CallState:In
Progress newV=net.java.sip.communicator.service.protocol.CallState:Ended
14:30:06.703 FINA:
service.protocol.AbstractOperationSetBasicTelephony.fireCallEvent().59
Dispatching a CallEvent to 3 listeners. event is: CallEvent:[ id=3
Call=Call: id=122303698609327649252 participants=0]
14:30:06.859 ADVERTENCIA: impl.media.CallSessionImpl.callStateChanged().2190
Stopping streaming.
14:30:06.859 FINA: impl.media.CallSessionImpl.update().2345 received the
following JMF SendStreamEvent -
javax.media.rtp.event.StreamClosedEvent=javax.media.rtp.event.StreamClosedEvent[source
= RTPManager
       SSRCCache com.sun.media.rtp.SSRCCache@a88bc2
       Dataport 5000
       Controlport 5001
       Address 0.0.0.0
       RTPForwarder com.sun.media.rtp.util.PacketForwarder@16f8789
       RTPDemux com.sun.media.rtp.RTPDemultiplexer@12b6037]
14:30:06.875 FINA: impl.media.CallSessionImpl.printFlowStatistics().524
global transmission stats ((for audio flows)):
bytes sent: 100320
local colls: 0
remote colls: 0
RTCP sent: 3
RTP sent: 418
transmit failed: 0
14:30:06.875 FINA: impl.media.CallSessionImpl.printFlowStatistics().536
global reception stats ((for audio flows)):
bad RTCP packets: 0
bad RTP packets: 0
bytes received: 106060
local collisions: 0
malformed BYEs: 0
malformed RRs: 0
malformed SDESs: 0
malformed SRs: 0
packets looped: 0
packets received: 618
remote collisions: 0
RTCPs received: 2
SRRs received: 1
transmit failed: 0
unknown types: 0
14:30:06.875 FINA: impl.media.CallSessionImpl.printFlowStatistics().524
global transmission stats ((for video flows)):
bytes sent: 0
local colls: 0
remote colls: 0
RTCP sent: 0
RTP sent: 0
transmit failed: 0
14:30:06.875 FINA: impl.media.CallSessionImpl.printFlowStatistics().536
global reception stats ((for video flows)):
bad RTCP packets: 0
bad RTP packets: 0
bytes received: 0
local collisions: 0
malformed BYEs: 0
malformed RRs: 0
malformed SDESs: 0
malformed SRs: 0
packets looped: 0
packets received: 0
remote collisions: 0
RTCPs received: 0
SRRs received: 0
transmit failed: 0
unknown types: 0
14:30:06.937 FINA: impl.media.MediaControl.registerCustomCodecs().1175 Codec
: net.java.sip.communicator.impl.media.codec.audio.alaw.JavaEncoder is
already registered
14:30:06.937 FINA: impl.media.MediaControl.registerCustomCodecs().1175 Codec
: net.java.sip.communicator.impl.media.codec.audio.alaw.DePacketizer is
already registered
14:30:06.937 FINA: impl.media.MediaControl.registerCustomCodecs().1175 Codec
: net.java.sip.communicator.impl.media.codec.audio.alaw.Packetizer is
already registered
14:30:06.937 FINA: impl.media.MediaControl.registerCustomCodecs().1175 Codec
: net.java.sip.communicator.impl.media.codec.audio.ulaw.Packetizer is
already registered
14:30:06.937 FINA: impl.media.MediaControl.registerCustomCodecs().1175 Codec
: net.java.sip.communicator.impl.media.codec.audio.speex.JavaEncoder is
already registered
14:30:06.937 FINA: impl.media.MediaControl.registerCustomCodecs().1175 Codec
: net.java.sip.communicator.impl.media.codec.audio.speex.JavaDecoder is
already registered
14:30:06.937 FINA: impl.media.MediaControl.registerCustomCodecs().1175 Codec
: net.java.sip.communicator.impl.media.codec.audio.ilbc.JavaEncoder is
already registered
14:30:06.937 FINA: impl.media.MediaControl.registerCustomCodecs().1175 Codec
: net.java.sip.communicator.impl.media.codec.audio.ilbc.JavaDecoder is
already registered
14:30:07.062 FINA: impl.media.CallSessionImpl.controllerUpdate().2450
Received a ControllerEvent:
javax.media.StopByRequestEvent[source=com.sun.media.content.unknown.Handler@112ee4f,previous=Started,current=Prefetched,target=Prefetched,mediaTime=javax.media.Time@13e8c1c]
14:30:07.062 FINA: impl.media.CallSessionImpl.controllerUpdate().2450
Received a ControllerEvent:
javax.media.DeallocateEvent[source=com.sun.media.content.unknown.Handler@112ee4f,previous=Prefetched,current=Realized,target=Realized,mediaTime=javax.media.Time@b8705b]
14:30:07.062 FINA: impl.media.CallSessionImpl.controllerUpdate().2450
Received a ControllerEvent: javax.medi
---------------------------------------------------------------------
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


#2

Lubo,

I've updated to r4607, and seems that RTP audio in SIP calls from
sip-comm to others is not working.

Scenario (same hardware):
SIP-communicator-> sip:6003 , in 10.95.74.122
callee/caller phone -> sip:6000, in 10.95.74.222
Asterisk -> 10.95.74.124
Wireshark listenting in sip-comm PC, (10.95.74.122)

Attached are logs for both incoming and outgoing calls from the
sip-comm point of view.
As you will see there is not outgoing RTP Audio streams long enought
(more than 10 seconds, that is, the real conversation) sended by
sip-comm.

What are we missing?
Best regards

PS: I've downgraded to r4590 and I'm able to create incoming/outgoing
calls, using Asterisk for sending sip-comm RTP audio, having normal
calls.

incoming call.pcap (650 KB)

sc-jainsipdebug.incoming.log (504 Bytes)

sc-jainsipserver.incoming.log (33.4 KB)

sip-communicator0.incoming.log (114 KB)

outgoing call.pcap (274 KB)

sc-jainsipdebug.incoming.log (504 Bytes)

sc-jainsipserver.outgoing.log (32.3 KB)

sip-communicator0.outgoing.log (114 KB)

···

2008/10/20 Lubomir Marinov <lubomir.marinov@gmail.com>:

Javier,

Please try trunk in r4592 or a later revision for re-INVITE support
which takes into account changes in the connection address.

Regards,
Lubo

On Fri, Oct 3, 2008 at 4:28 PM, Javier Mendiara Cañardo > <javier.mendiara@gmail.com> wrote:

Hi all,

I think I've found an issue with RTP retransmission.
If two SDP descriptions are sent in INVITES in the same SIP Dialog, SipComm
sends RTP to the destination specified in the first INVITE, and I think it
should be sent to the specified in the last INVITE

Attached there are 3 files, the sipcomm log (FINE graining), Wireshark Dump
and a JPG showing where SIP server sends the second INVITE with the correct
SDP and where is sending SIPComm the RTP audio.

As you can see, the other softphone is sending RTP directly to SipComm, but
SipComm is sending audio via SIP Server.

The scenario is as follows:
A SipCommunicator (SVN 4548) located at 192.168.1.135, with SIP ID 6003,
running under WinXP Pro SP3, java1.6, from Netbeans ANT run
One SIP Gateway (GrandStream HT503) located at 192.168.1.102, with SIP ID
6000
Asterisk SIP server (v1.4.18.1) located at 192.168.1.101, forcing G711U
(ULAW) codec
WireShark (tcpdump) listening in 192.168.1.135 (where sipcomm is located)

In the wireshark dump there is also debug info from the SIP Gateway
(sip:6000) sent using SysLog events (WireShark filter: syslog)
In "Log Sipcommunicator.log" file, line 914, you can see the second INVITE

This behaviour is a pity, because if the SIPComm and GW are in the same LAN
and the asterisk is outside, the Sipcommunicator voice will travel too long
to reach the destination, while GW to SIP voice will travel using the LAN.

One thing more, I've forced in Asterisk to use G711U, so I think that
Asterisk media server conversion between audio formats is not causing this.
Also, both SDP are identical.

What do you think? Is it possible to solve?

PS: I've tried with other softphones, and it seems that this issue is
correctly managed.

--
Javier Mendiara Cañardo

Skype: jmendiara.tid
MsnIM: javier.mendiara@gmail.com

14:28:58.656 FINA:
util.launchutils.SipCommunicatorLock.interInstanceConnect().538 Failed to
connect to a running sc instance.
14:29:01.890 FINA: impl.configuration.ConfigurationServiceImpl.start().37
Service Impl:
net.java.sip.communicator.impl.configuration.ConfigurationActivator [
STARTED ]
14:29:01.890 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.runtime.name=Java(TM) SE Runtime Environment
14:29:01.890 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
felix.config.properties=file:C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk/lib/felix.client.run.properties
14:29:01.890 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
sun.boot.library.path=C:\Java\jdk1.6.0_03\jre\bin
14:29:01.890 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.vm.version=1.6.0_03-b05
14:29:01.890 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.vm.vendor=Sun Microsystems Inc.
14:29:01.890 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.vendor.url=http://java.sun.com/
14:29:01.890 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
path.separator=;
14:29:01.890 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.util.logging.config.file=C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk/lib/logging.properties
14:29:01.890 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.vm.name=Java HotSpot(TM) Client VM
14:29:01.906 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
file.encoding.pkg=sun.io
14:29:01.906 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
sun.java.launcher=SUN_STANDARD
14:29:01.906 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
user.country=ES
14:29:01.906 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
sun.os.patch.level=Service Pack 3
14:29:01.906 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.vm.specification.name=Java Virtual Machine Specification
14:29:01.906 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
user.dir=C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk
14:29:01.906 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.runtime.version=1.6.0_03-b05
14:29:01.906 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.awt.graphicsenv=sun.awt.Win32GraphicsEnvironment
14:29:01.921 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.endorsed.dirs=C:\Java\jdk1.6.0_03\jre\lib\endorsed
14:29:01.921 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
os.arch=x86
14:29:01.921 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.io.tmpdir=C:\DOCUME~1\ADMINI~1\CONFIG~1\Temp\
14:29:01.921 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
line.separator=

14:29:01.921 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.vm.specification.vendor=Sun Microsystems Inc.
14:29:01.921 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
user.variant=
14:29:01.921 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
os.name=Windows XP
14:29:01.921 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
sun.jnu.encoding=Cp1252
14:29:01.921 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.net.preferIPv6Addresses=false
14:29:01.921 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.library.path=C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\${ld.library.path};C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\native\windows;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\${system.PATH};C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\${dyld.library.path}
14:29:01.921 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.specification.name=Java Platform API Specification
14:29:01.921 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.class.version=50.0
14:29:01.921 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
sun.management.compiler=HotSpot Client Compiler
14:29:01.921 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
org.osgi.framework.bootdelegation=
14:29:01.921 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
os.version=5.1
14:29:01.921 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
user.home=C:\Documents and Settings\Administrador
14:29:01.921 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
user.timezone=Europe/Paris
14:29:01.921 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.awt.printerjob=sun.awt.windows.WPrinterJob
14:29:01.921 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
file.encoding=Cp1252
14:29:01.921 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.specification.version=1.6
14:29:01.937 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
felix.auto.start.68=
14:29:01.937 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.class.path=C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\sc-bundles\sc-launcher.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\sc-bundles\util.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\bundle\junit.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\bundle\log4j.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\bundle\org.apache.felix.bundlerepository-1.0.0.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\bundle\org.apache.felix.servicebinder-0.9.0-SNAPSHOT.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\felix.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\JainSipApi1.2.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\JainSipRi1.2.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\KeybindingUtil.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\Stun4J.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\aclibico-2.1.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\apache-ant-1.7.0.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\bcprov-jdk14-140.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\commons-logging.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\concurrent.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\dnsjava-2.0.3.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\httpcore-4.0-beta2.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\izpack-shortcut-link.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\jcalendar-1.3.2.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\jcommon-1.0.0.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\jdic_misc.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\jdom.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\jfontchooser-1.0.5.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\jfreechart-1.0.1.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\jmf.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\jml-1.0b2.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\jna.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\joscar-client.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\joscar-common.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\joscar-protocol.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\jsch-0.1.36.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\jsocks-klea.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\jspeex.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\junit.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\log4j-1.2.8.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\nist-sdp-1.0.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\pircbot.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\profiler4j-1.0-beta3-SC.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\rome-0.9.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\smack.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\smackx-jingle.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\smackx.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\transparency.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\ymsg_network_v0_63.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\installer-exclude\zrtp4j-0.9.0.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\jdic-all.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\os-specific\windows\installer-exclude\jmf.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\os-specific\windows\installer-exclude\sound.jar;C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\lib\os-specific\windows\jdic_stub.jar
14:29:01.953 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
user.name=Administrador
14:29:01.953 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.vm.specification.version=1.0
14:29:01.953 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.home=C:\Java\jdk1.6.0_03\jre
14:29:01.953 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
sun.arch.data.model=32
14:29:01.953 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
user.language=es
14:29:01.953 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.specification.vendor=Sun Microsystems Inc.
14:29:01.953 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
awt.toolkit=sun.awt.windows.WToolkit
14:29:01.953 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.vm.info=mixed mode
14:29:01.953 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.version=1.6.0_03
14:29:01.953 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.ext.dirs=C:\Java\jdk1.6.0_03\jre\lib\ext;C:\WINDOWS\Sun\Java\lib\ext
14:29:01.953 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
sun.boot.class.path=C:\Java\jdk1.6.0_03\jre\lib\resources.jar;C:\Java\jdk1.6.0_03\jre\lib\rt.jar;C:\Java\jdk1.6.0_03\jre\lib\sunrsasign.jar;C:\Java\jdk1.6.0_03\jre\lib\jsse.jar;C:\Java\jdk1.6.0_03\jre\lib\jce.jar;C:\Java\jdk1.6.0_03\jre\lib\charsets.jar;C:\Java\jdk1.6.0_03\jre\classes
14:29:01.953 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
net.java.sip.communicator.SC_HOME_DIR_LOCATION=C:\Documents and
Settings\Administrador
14:29:01.953 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.vendor=Sun Microsystems Inc.
14:29:01.953 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
file.separator=\
14:29:01.953 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
net.java.sip.communicator.SC_HOME_DIR_NAME=.sip-communicator
14:29:01.968 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
java.vendor.url.bug=http://java.sun.com/cgi-bin/bugreport.cgi
14:29:01.968 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
sun.io.unicode.encoding=UnicodeLittle
14:29:01.968 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
sun.cpu.endian=little
14:29:01.968 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
sun.desktop=windows
14:29:01.968 FINA:
impl.configuration.ConfigurationServiceImpl.debugPrintSystemProperties().1168
sun.cpu.isalist=
14:29:01.968 FINA:
impl.configuration.ConfigurationServiceImpl.createConfigurationFile().912
Using config file in $HOME/.sip-communicator:
C:\Proyectos\COGKNOW\netbeans\sip-communicator\trunk\sip-communicator.xml
14:29:02.046 FINA: impl.configuration.ConfigurationServiceImpl.start().46
Service Impl:
net.java.sip.communicator.impl.configuration.ConfigurationActivator
[REGISTERED]
14:29:02.078 INFO: impl.resources.ResourceManagementActivator.start().40
Resource manager ... [REGISTERED]
14:29:02.078 INFO:
impl.resources.ResourceManagementServiceImpl.serviceChanged().179 Resource
registered
net.java.sip.communicator.plugin.defaultresourcepack.DefaultColorPackImpl@176cad3
14:29:02.109 INFO:
impl.resources.ResourceManagementServiceImpl.serviceChanged().179 Resource
registered
net.java.sip.communicator.plugin.defaultresourcepack.DefaultImagePackImpl@13ad33d
14:29:02.125 INFO:
impl.resources.ResourceManagementServiceImpl.serviceChanged().179 Resource
registered
net.java.sip.communicator.plugin.defaultresourcepack.DefaultLanguagePackImpl@a613f8
14:29:02.156 INFO:
impl.resources.ResourceManagementServiceImpl.serviceChanged().179 Resource
registered
net.java.sip.communicator.plugin.defaultresourcepack.DefaultSettingsPackImpl@edf3f6
14:29:02.156 INFO:
impl.resources.ResourceManagementServiceImpl.serviceChanged().179 Resource
registered
net.java.sip.communicator.plugin.defaultresourcepack.DefaultSoundPackImpl@19ce060
14:29:02.171 INFO:
plugin.defaultresourcepack.DefaultResourcePackActivator.start().92 Default
resources ... [REGISTERED]
14:29:02.171 FINA: impl.version.VersionActivator.start() Started.
14:29:02.187 FINA: impl.version.VersionActivator.start() SIP Protocol
Provider Factory ... [REGISTERED]
14:29:02.187 FINA: impl.version.VersionActivator.start() SIP Communicator
Version: sip-communicator-1.0-alpha3-0.build.by.SVN
14:29:03.218 INFO: impl.browserlauncher.BrowserLauncherActivator.start().37
UI Service...[ STARTED ]
14:29:03.234 INFO: impl.browserlauncher.BrowserLauncherActivator.start().42
UI Service ...[REGISTERED]
14:29:03.250 FINA: impl.media.MediaActivator.start().53 Started.
14:29:03.265 FINA: impl.media.MediaActivator.start().69 Media Service ...
[REGISTERED]
14:29:03.281 FINA:
impl.protocol.AccountManagerImpl.doLoadStoredAccounts().110 Discovered 0
stored net.java.sip.communicator.impl.protocol.icq accounts
14:29:03.296 FINA: impl.protocol.sip.SipActivator.start().54 Started.
14:29:03.312 FINA:
impl.protocol.AccountManagerImpl.doLoadStoredAccounts().110 Discovered 0
stored net.java.sip.communicator.impl.protocol.icq accounts
14:29:03.312 FINA: impl.protocol.sip.SipActivator.start().75 SIP Protocol
Provider Factory ... [REGISTERED]
14:29:03.328 FINA:
impl.protocol.AccountManagerImpl.doLoadStoredAccounts().110 Discovered 1
stored net.java.sip.communicator.impl.protocol.sip accounts
14:29:03.328 FINA:
impl.protocol.AccountManagerImpl.doLoadStoredAccounts().118 Loading account
net.java.sip.communicator.impl.protocol.sip.acc1222944379687
14:29:03.421 INFO: impl.protocol.gibberish.GibberishActivator.start().74
Gibberish protocol implementation [STARTED].
14:29:03.437 INFO: impl.protocol.ssh.SSHActivator.start().80 SSH protocol
implementation [STARTED].
14:29:03.578 INFO: impl.netaddr.NetworkAddressManagerServiceImpl.start().149
Stun server address(null)/port(null) not set (or invalid). Disabling STUN.
14:29:03.578 INFO: impl.netaddr.NetworkAddressManagerServiceImpl.start().59
Network Address Manager ...[ STARTED ]
14:29:03.578 INFO: impl.netaddr.NetworkAddressManagerServiceImpl.start().64
Network Address Manager Service ...[REGISTERED]
14:29:03.578 INFO: impl.protocol.zeroconf.ZeroconfActivator.start().74
Zeroconf protocol implementation [STARTED].
14:29:03.593 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.initialize().574 Created
stack: gov.nist.javax.sip.SipStackImpl@d2efa1
14:29:03.593 INFO: impl.protocol.irc.IrcActivator.start().75 IRC protocol
implementation [STARTED].
14:29:03.625 INFO: impl.protocol.dict.DictActivator.start().66 DICT protocol
implementation [STARTED].
14:29:03.640 FINA: impl.contactlist.ContactlistActivator.start().44 Service
Impl: net.java.sip.communicator.impl.contactlist.ContactlistActivator [
STARTED ]
14:29:03.687 FINA: impl.contactlist.MetaContactListServiceImpl.start().145
Starting the meta contact list implementation.
14:29:03.734 FINA: impl.contactlist.ContactlistActivator.start().57 Service
Impl: net.java.sip.communicator.impl.contactlist.ContactlistActivator
[REGISTERED]
14:29:03.750 INFO: impl.protocol.rss.RssActivator.start().93 RSS protocol
implementation [STARTED].
14:29:03.750 FINA: impl.protocol.rss.RssActivator.start().100 User-Agent set
to SIP Communicator/1.0-alpha3-0.build.by.SVN
14:29:03.906 FINA:
impl.history.HistoryServiceImpl.setConfigurationService().286 New
configuration service registered.
14:29:03.921 FINA:
impl.history.HistoryServiceImpl.setFileAccessService().325 New file access
service registered.
14:29:03.921 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.createListeningPoint().904
Created LP 0.0.0.0:5060/udp
14:29:03.953 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.createListeningPoint().904
Created LP 0.0.0.0:5060/tcp
14:29:03.953 FINA:
impl.msghistory.MessageHistoryServiceImpl.setHistoryService().865 New
history service registered.
14:29:03.953 FINA: impl.msghistory.MessageHistoryServiceImpl.start().704
Starting the msg history implementation.
14:29:03.968 INFO: impl.msghistory.MessageHistoryActivator.start().60
Message History Service ...[REGISTERED]
14:29:04.046 FINA:
impl.callhistory.CallHistoryServiceImpl.setHistoryService().613 New history
service registered.
14:29:04.046 FINA: impl.callhistory.CallHistoryServiceImpl.start().473
Starting the call history implementation.
14:29:04.250 INFO: impl.callhistory.CallHistoryActivator.start().56 Call
History Service ...[REGISTERED]
14:29:04.265 INFO: impl.audionotifier.AudioNotifierActivator.start().66
Audio Notifier Service...[ STARTED ]
14:29:04.281 INFO:
impl.media.device.JmfDeviceDetector.detectCaptureDevices().125 Looking for
Audio capturer
14:29:04.281 INFO: impl.audionotifier.AudioNotifierActivator.start().71
Audio Notifier Service ...[REGISTERED]
14:29:04.281 FINA: impl.keybindings.KeybindingsActivator.start().34 Service
Impl: net.java.sip.communicator.impl.keybindings.KeybindingsActivator [
STARTED ]
14:29:04.296 INFO: impl.media.device.DirectSoundAuto.<init>().55 DirectSound
Capture Supported = true
14:29:04.375 INFO: impl.notification.NotificationActivator.start().46
Notification Service...[ STARTED ]
14:29:04.375 INFO: impl.notification.NotificationActivator.start().53
Notification Service ...[REGISTERED]
14:29:04.406 FINA:
impl.notification.NotificationServiceImpl.registerDefaultNotificationForEvent().995
Registering default event IncomingMessage/PopupMessageAction/null/null
14:29:04.421 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.createListeningPoint().904
Created LP 0.0.0.0:5061/tls
14:29:04.437 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.initListeningPoints().824
Created listening points and SIP provider for account
SIP:6003@192.168.1.101
14:29:04.484 FINA:
impl.notification.NotificationServiceImpl.registerDefaultNotificationForEvent().995
Registering default event
IncomingMessage/SoundAction/resources/sounds/incomingMessage.wav/null
14:29:04.546 FINA:
impl.protocol.sip.OperationSetPresenceSipImpl.<init>().273 presence
initialized with :true, true, 30, 3600 for null
14:29:04.578 FINA:
impl.notification.NotificationServiceImpl.registerDefaultNotificationForEvent().995
Registering default event IncomingCall/PopupMessageAction/null/null
14:29:05.296 INFO: impl.media.device.DirectSoundAuto.<init>().102
DirectSoundAuto: Committed ok
14:29:05.421 FINA: impl.protocol.AccountManagerImpl.storeAccount().562
Stored account for id SIP:6003@192.168.1.101 for package
net.java.sip.communicator.impl.protocol.sip
14:29:05.421 FINA:
impl.callhistory.CallHistoryServiceImpl.serviceChanged().653 Service is a
protocol provider.
14:29:05.421 FINA:
impl.callhistory.CallHistoryServiceImpl.serviceChanged().656 Handling
registration of a new Protocol Provider.
14:29:05.421 FINA:
impl.notification.NotificationServiceImpl.registerDefaultNotificationForEvent().995
Registering default event ProactiveNotification/PopupMessageAction/null/null
14:29:05.437 FINA:
impl.callhistory.CallHistoryServiceImpl.handleProviderAdded().676 Adding
protocol provider SIP
14:29:05.437 FINA:
impl.msghistory.MessageHistoryServiceImpl.serviceChanged().905 Service is a
protocol provider.
14:29:05.437 FINA:
impl.msghistory.MessageHistoryServiceImpl.serviceChanged().908 Handling
registration of a new Protocol Provider.
14:29:05.437 FINA:
impl.msghistory.MessageHistoryServiceImpl.handleProviderAdded().928 Adding
protocol provider SIP
14:29:05.453 INFO: impl.media.device.JavaSoundAuto.<init>().43 JavaSound
Capture Supported = true
14:29:05.578 FINA:
impl.contactlist.MetaContactListServiceImpl.serviceChanged().1842 Service is
a protocol provider.
14:29:05.593 FINA:
impl.contactlist.MetaContactListServiceImpl.serviceChanged().1868 Handling
registration of a new Protocol Provider.
14:29:05.593 FINA:
impl.contactlist.MetaContactListServiceImpl.handleProviderAdded().1516
Adding protocol provider SIP
14:29:05.609 INFO: impl.gui.GuiActivator.start().88 UI Service...[ STARTED
]
14:29:05.625 INFO: impl.media.device.JavaSoundAuto.<init>().69
JavaSoundAuto: Committed ok
14:29:05.625 INFO: impl.gui.GuiActivator.start().93 UI Service
...[REGISTERED]
14:29:05.625 FINA:
impl.media.device.JmfDeviceDetector.detectCaptureDevices().154 No FMJ
javasound detected: *** Class
'net.sf.fmj.media.cdp.javasound.CaptureDevicePlugger' was not found. Bundle
17 does not import package 'net.sf.fmj.media.cdp.javasound', nor is the
package exported by any other bundle or available from the system class
loader. ***
14:29:05.640 FINA:
impl.protocol.AccountManagerImpl.doLoadStoredAccounts().110 Discovered 0
stored net.java.sip.communicator.impl.protocol.jabber accounts
14:29:05.687 INFO:
impl.media.device.JmfDeviceDetector.detectCaptureDevices().175 Looking for
video capture devices
14:29:05.687 FINA:
impl.protocol.AccountManagerImpl.doLoadStoredAccounts().110 Discovered 0
stored net.java.sip.communicator.impl.protocol.msn accounts
14:29:05.687 FINA:
impl.protocol.AccountManagerImpl.doLoadStoredAccounts().110 Discovered 0
stored net.java.sip.communicator.impl.protocol.yahoo accounts
14:29:05.687 FINA:
impl.protocol.AccountManagerImpl.doLoadStoredAccounts().110 Discovered 0
stored net.java.sip.communicator.impl.protocol.gibberish accounts
14:29:05.703 FINA:
impl.protocol.AccountManagerImpl.doLoadStoredAccounts().110 Discovered 0
stored net.java.sip.communicator.impl.protocol.ssh accounts
14:29:05.703 FINA:
impl.protocol.AccountManagerImpl.doLoadStoredAccounts().110 Discovered 0
stored net.java.sip.communicator.impl.protocol.zeroconf accounts
14:29:05.703 FINA:
impl.protocol.AccountManagerImpl.doLoadStoredAccounts().110 Discovered 0
stored net.java.sip.communicator.impl.protocol.irc accounts
14:29:05.703 FINA: impl.media.device.VFWAuto.autoDetectDevices().61 Found
device Microsoft WDM Image Capture (Win32)
14:29:05.703 FINA:
impl.protocol.AccountManagerImpl.doLoadStoredAccounts().110 Discovered 0
stored net.java.sip.communicator.impl.protocol.dict accounts
14:29:05.703 FINA:
impl.protocol.AccountManagerImpl.doLoadStoredAccounts().110 Discovered 0
stored net.java.sip.communicator.impl.protocol.rss accounts
14:29:05.703 FINA: impl.media.device.VFWAuto.autoDetectDevices().62 Querying
device. Please wait...
14:29:05.750 INFO:
impl.media.device.JmfDeviceDetector.detectCaptureDevices().182 Detected 0
VFW video capture device(s).
14:29:05.781 INFO:
impl.media.device.JmfDeviceDetector.detectCaptureDevices().197 Detected 0
SUN Video capture device(s).
14:29:05.828 INFO:
impl.media.device.JmfDeviceDetector.detectCaptureDevices().212 Detected 0
SUN Video Plus device(s).
14:29:05.828 FINA:
impl.media.device.JmfDeviceDetector.detectCaptureDevices().232 No V4l video
detected: com/sun/media/protocol/v4l/V4LDeviceQuery
14:29:05.828 INFO:
impl.media.device.DeviceConfiguration.extractConfiguredCaptureDevices().81
Scanning for configured Audio Devices.
14:29:05.828 FINA:
impl.media.device.DeviceConfiguration.extractConfiguredCaptureDevices().90
Found 2 capture devices: [DirectSoundCapture : dsound://
LINEAR, 48000.0 Hz, 16-bit, Stereo, LittleEndian, Signed
LINEAR, 48000.0 Hz, 16-bit, Mono, LittleEndian, Signed
LINEAR, 48000.0 Hz, 8-bit, Stereo, Unsigned
LINEAR, 48000.0 Hz, 8-bit, Mono, Unsigned
LINEAR, 44100.0 Hz, 16-bit, Stereo, LittleEndian, Signed
LINEAR, 44100.0 Hz, 16-bit, Mono, LittleEndian, Signed
LINEAR, 44100.0 Hz, 8-bit, Stereo, Unsigned
LINEAR, 44100.0 Hz, 8-bit, Mono, Unsigned
LINEAR, 32000.0 Hz, 16-bit, Stereo, LittleEndian, Signed
LINEAR, 32000.0 Hz, 16-bit, Mono, LittleEndian, Signed
LINEAR, 32000.0 Hz, 8-bit, Stereo, Unsigned
LINEAR, 32000.0 Hz, 8-bit, Mono, Unsigned
LINEAR, 22050.0 Hz, 16-bit, Stereo, LittleEndian, Signed
LINEAR, 22050.0 Hz, 16-bit, Mono, LittleEndian, Signed
LINEAR, 22050.0 Hz, 8-bit, Stereo, Unsigned
LINEAR, 22050.0 Hz, 8-bit, Mono, Unsigned
LINEAR, 16000.0 Hz, 16-bit, Stereo, LittleEndian, Signed
LINEAR, 16000.0 Hz, 16-bit, Mono, LittleEndian, Signed
LINEAR, 16000.0 Hz, 8-bit, Stereo, Unsigned
LINEAR, 16000.0 Hz, 8-bit, Mono, Unsigned
LINEAR, 11025.0 Hz, 16-bit, Stereo, LittleEndian, Signed
LINEAR, 11025.0 Hz, 16-bit, Mono, LittleEndian, Signed
LINEAR, 11025.0 Hz, 8-bit, Stereo, Unsigned
LINEAR, 11025.0 Hz, 8-bit, Mono, Unsigned
LINEAR, 8000.0 Hz, 16-bit, Stereo, LittleEndian, Signed
LINEAR, 8000.0 Hz, 16-bit, Mono, LittleEndian, Signed
LINEAR, 8000.0 Hz, 8-bit, Stereo, Unsigned
LINEAR, 8000.0 Hz, 8-bit, Mono, Unsigned
, JavaSound audio capture : javasound://44100
LINEAR, 44100.0 Hz, 16-bit, Stereo, LittleEndian, Signed
LINEAR, 44100.0 Hz, 16-bit, Mono, LittleEndian, Signed
LINEAR, 22050.0 Hz, 16-bit, Stereo, LittleEndian, Signed
LINEAR, 22050.0 Hz, 16-bit, Mono, LittleEndian, Signed
LINEAR, 11025.0 Hz, 16-bit, Stereo, LittleEndian, Signed
LINEAR, 11025.0 Hz, 16-bit, Mono, LittleEndian, Signed
LINEAR, 8000.0 Hz, 16-bit, Stereo, LittleEndian, Signed
LINEAR, 8000.0 Hz, 16-bit, Mono, LittleEndian, Signed
]
14:29:05.968 INFO:
impl.media.device.DeviceConfiguration.extractConfiguredCaptureDevices().93
Found DirectSoundCapture as an audio capture device.
14:29:05.968 INFO:
impl.media.device.DeviceConfiguration.extractConfiguredCaptureDevices().97
Scanning for configured Video Devices.
14:29:05.968 INFO:
impl.media.device.DeviceConfiguration.extractConfiguredCaptureDevices().117
No Video Device was found.
14:29:05.968 FINA: impl.media.MediaControl.registerCustomPackages().1243
Adding package : net.java.sip.communicator.impl
14:29:06.015 FINA: impl.media.MediaControl.registerCustomPackages().1249
Registering new protocol prefix list : [javax, com.sun, com.ibm,
net.java.sip.communicator.impl]
14:29:06.015 INFO: impl.media.MediaControl.createDataSource().740 Creating
datasource for:dsound://
14:29:06.062 FINA: impl.media.MediaControl.registerCustomCodecs().1191 Codec
: net.java.sip.communicator.impl.media.codec.audio.alaw.JavaEncoder is
succsefully registered : true
14:29:06.062 FINA: impl.media.MediaControl.registerCustomCodecs().1191 Codec
: net.java.sip.communicator.impl.media.codec.audio.alaw.DePacketizer is
succsefully registered : true
14:29:06.062 FINA: impl.media.MediaControl.registerCustomCodecs().1191 Codec
: net.java.sip.communicator.impl.media.codec.audio.alaw.Packetizer is
succsefully registered : true
14:29:06.109 FINA: impl.media.MediaControl.registerCustomCodecs().1191 Codec
: net.java.sip.communicator.impl.media.codec.audio.ulaw.Packetizer is
succsefully registered : true
14:29:06.109 FINA: impl.media.MediaControl.registerCustomCodecs().1191 Codec
: net.java.sip.communicator.impl.media.codec.audio.speex.JavaEncoder is
succsefully registered : true
14:29:06.109 FINA: impl.media.MediaControl.registerCustomCodecs().1191 Codec
: net.java.sip.communicator.impl.media.codec.audio.speex.JavaDecoder is
succsefully registered : true
14:29:06.109 FINA: impl.media.MediaControl.registerCustomCodecs().1191 Codec
: net.java.sip.communicator.impl.media.codec.audio.ilbc.JavaEncoder is
succsefully registered : true
14:29:06.125 FINA: impl.media.MediaControl.registerCustomCodecs().1191 Codec
: net.java.sip.communicator.impl.media.codec.audio.ilbc.JavaDecoder is
succsefully registered : true
14:29:06.390 INFO: plugin.updatechecker.Resources.getConfigString().47 No
config file specified for update checker
14:29:06.390 INFO: plugin.updatechecker.Resources.getConfigString().48
Disabling update checks
14:29:06.390 FINA:
plugin.updatechecker.UpdateCheckActivator.isNewestVersion().214 Updates are
disabled. Faking latest version.
14:29:06.390 INFO: impl.gui.UIServiceImpl.serviceChanged().752 Handling
registration of a new Plugin Component.
14:29:06.390 FINA: impl.gui.UIServiceImpl.firePluginEvent().169 Will
dispatch the following plugin component event:
net.java.sip.communicator.impl.gui.event.PluginComponentEvent[source=net.java.sip.communicator.plugin.branding.AboutWindowPluginComponent@d507d3]
14:29:06.406 INFO: plugin.branding.BrandingActivator.registerMenuEntry().129
ABOUT WINDOW ... [REGISTERED]
14:29:06.406 INFO: impl.gui.UIServiceImpl.serviceChanged().752 Handling
registration of a new Plugin Component.
14:29:06.406 FINA: impl.gui.UIServiceImpl.firePluginEvent().169 Will
dispatch the following plugin component event:
net.java.sip.communicator.impl.gui.event.PluginComponentEvent[source=net.java.sip.communicator.plugin.branding.AboutWindowPluginComponent@307c44]
14:29:06.406 INFO: plugin.branding.BrandingActivator.registerMenuEntry().142
CHAT ABOUT WINDOW ... [REGISTERED]
14:29:06.765 INFO: impl.systray.SystrayActivator.start().51 Systray
Service...[ STARTED ]
14:29:06.765 INFO: impl.systray.SystrayActivator.start().58 Systray Service
...[REGISTERED]
14:29:06.765 INFO:
impl.gui.main.configforms.ConfigurationFrame.serviceChanged().235 Handling
registration of a new Configuration Form.
14:29:06.796 INFO: impl.gui.UIServiceImpl.serviceChanged().752 Handling
registration of a new Plugin Component.
14:29:06.796 FINA: impl.gui.UIServiceImpl.firePluginEvent().169 Will
dispatch the following plugin component event:
net.java.sip.communicator.impl.gui.event.PluginComponentEvent[source=net.java.sip.communicator.plugin.whiteboard.WhiteboardMenuItem@191394e]
14:29:06.796 INFO: plugin.whiteboard.WhiteboardActivator.start().56
WHITEBOARD... [REGISTERED]
14:29:06.812 INFO:
impl.gui.main.account.AccountRegWizardContainerImpl.serviceChanged().328
Handling registration of a new Account Wizard.
14:29:06.828 INFO:
impl.gui.main.account.AccountRegWizardContainerImpl.serviceChanged().328
Handling registration of a new Account Wizard.
14:29:06.843 INFO:
impl.gui.main.account.AccountRegWizardContainerImpl.serviceChanged().328
Handling registration of a new Account Wizard.
14:29:06.859 INFO:
impl.gui.main.account.AccountRegWizardContainerImpl.serviceChanged().328
Handling registration of a new Account Wizard.
14:29:06.859 INFO:
impl.gui.main.account.AccountRegWizardContainerImpl.serviceChanged().328
Handling registration of a new Account Wizard.
14:29:06.875 INFO:
impl.gui.main.account.AccountRegWizardContainerImpl.serviceChanged().328
Handling registration of a new Account Wizard.
14:29:06.890 INFO:
impl.gui.main.account.AccountRegWizardContainerImpl.serviceChanged().328
Handling registration of a new Account Wizard.
14:29:06.890 INFO:
plugin.gibberishaccregwizz.GibberishAccRegWizzActivator.start().51 Loading
gibberish account wizard.
14:29:06.906 INFO:
impl.gui.main.account.AccountRegWizardContainerImpl.serviceChanged().328
Handling registration of a new Account Wizard.
14:29:06.906 INFO:
plugin.gibberishaccregwizz.GibberishAccRegWizzActivator.start().77 Gibberish
account registration wizard [STARTED].
14:29:06.906 INFO: plugin.sshaccregwizz.SSHAccRegWizzActivator.start().58
Loading ssh account wizard.
14:29:06.921 INFO:
impl.gui.main.account.AccountRegWizardContainerImpl.serviceChanged().328
Handling registration of a new Account Wizard.
14:29:06.921 INFO: plugin.sshaccregwizz.SSHAccRegWizzActivator.start().85
SSH account registration wizard [STARTED].
14:29:06.937 INFO: impl.gui.UIServiceImpl.serviceChanged().752 Handling
registration of a new Plugin Component.
14:29:06.937 FINA: impl.gui.UIServiceImpl.firePluginEvent().169 Will
dispatch the following plugin component event:
net.java.sip.communicator.impl.gui.event.PluginComponentEvent[source=net.java.sip.communicator.plugin.extendedcallhistorysearch.ExtendedCallHistorySearchItem@1ebe8ec]
14:29:06.937 INFO:
plugin.extendedcallhistorysearch.ExtendedCallHistorySearchActivator.start().48
EXTENDED CALL HISTORY SEARCH... [REGISTERED]
14:29:06.937 INFO: plugin.rssaccregwizz.RssAccRegWizzActivator.start().46
Loading rss account wizard.
14:29:06.953 INFO:
impl.gui.main.account.AccountRegWizardContainerImpl.serviceChanged().328
Handling registration of a new Account Wizard.
14:29:06.953 INFO: plugin.rssaccregwizz.RssAccRegWizzActivator.start().73
RSS account registration wizard [STARTED].
14:29:06.953 INFO:
plugin.zeroconfaccregwizz.ZeroconfAccRegWizzActivator.start().47 Loading
zeroconf account wizard.
14:29:06.953 INFO:
impl.gui.main.account.AccountRegWizardContainerImpl.serviceChanged().328
Handling registration of a new Account Wizard.
14:29:06.968 INFO:
plugin.zeroconfaccregwizz.ZeroconfAccRegWizzActivator.start().74 Zeroconf
account registration wizard [STARTED].
14:29:06.968 INFO: plugin.ircaccregwizz.IrcAccRegWizzActivator.start().41
Loading irc account wizard.
14:29:06.968 INFO:
impl.gui.main.account.AccountRegWizardContainerImpl.serviceChanged().328
Handling registration of a new Account Wizard.
14:29:06.968 INFO: plugin.ircaccregwizz.IrcAccRegWizzActivator.start().69
IRC account registration wizard [STARTED].
14:29:06.984 INFO:
impl.gui.main.configforms.ConfigurationFrame.serviceChanged().235 Handling
registration of a new Configuration Form.
14:29:07.000 INFO: impl.gui.UIServiceImpl.serviceChanged().752 Handling
registration of a new Plugin Component.
14:29:07.000 FINA: impl.gui.UIServiceImpl.firePluginEvent().169 Will
dispatch the following plugin component event:
net.java.sip.communicator.impl.gui.event.PluginComponentEvent[source=net.java.sip.communicator.plugin.contactinfo.ContactInfoMenuItem@c1dc0b]
14:29:07.000 INFO: plugin.contactinfo.ContactInfoActivator.start().44
CONTACT INFO... [REGISTERED]
14:29:07.140 FINA: plugin.chatalerter.ChatAlerterActivator.start().99 Found
1 already installed providers.
14:29:07.140 FINA:
plugin.chatalerter.ChatAlerterActivator.handleProviderAdded().155 Adding
protocol provider SIP
14:29:07.171 INFO:
impl.gui.main.configforms.ConfigurationFrame.serviceChanged().235 Handling
registration of a new Configuration Form.
14:29:07.171 FINA:
plugin.keybindingchooser.KeybindingChooserActivator.start().39 Service Impl:
net.java.sip.communicator.plugin.keybindingchooser.KeybindingChooserActivator
[ STARTED ]
14:29:07.453 INFO:
impl.gui.main.configforms.ConfigurationFrame.serviceChanged().235 Handling
registration of a new Configuration Form.
14:29:07.859 INFO:
impl.gui.main.configforms.ConfigurationFrame.serviceChanged().235 Handling
registration of a new Configuration Form.
14:29:07.859 INFO:
plugin.generalconfig.GeneralConfigPluginActivator.start().43 PREFERENCES
PLUGIN... [REGISTERED]
14:29:07.875 INFO:
impl.gui.main.account.AccountRegWizardContainerImpl.serviceChanged().328
Handling registration of a new Account Wizard.
14:29:07.875 INFO:
plugin.simpleaccreg.SimpleAccountRegistrationActivator.start().51 SIMPLE
ACCOUNT REGISTRATION ...[STARTED]
14:29:07.890 FINA: impl.shutdown.ShutdownTimeout.start().51 Starting the
ShutdownTimeout service.
14:29:09.890 FINA:
service.protocol.AbstractProtocolProviderService.fireRegistrationStateChanged().74
Dispatching RegistrationStateChangeEvent[ oldState=Unregistered;
newState=RegistrationState=Registering;reasonCode=-1;reason=null] to 7
listeners.
14:29:09.890 FINA:
impl.protocol.sip.OperationSetBasicInstantMessagingSipImpl.registrationStateChanged().545
The provider changed state from: RegistrationState=Unregistered to:
RegistrationState=Registering
14:29:09.890 FINA:
impl.protocol.sip.OperationSetTypingNotificationsSipImpl.registrationStateChanged().130
The provider changed state from: RegistrationState=Unregistered to:
RegistrationState=Registering
14:29:09.937 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.getMaxForwardsHeader().1490
generated max forwards: Max-Forwards: 70

14:29:09.937 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.getIntendedDestination().2762
Returning address 192.168.1.101/192.168.1.101 for destination 192.168.1.101
14:29:09.937 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.getLocalViaHeaders().1445
generated via headers:Via: SIP/2.0/UDP 192.168.1.135:5060

14:29:09.953 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.getIntendedDestination().2762
Returning address 192.168.1.101/192.168.1.101 for destination 192.168.1.101
14:29:09.968 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.getContactHeader().1559
generated contactHeader:Contact: "6003"
<sip:6003@192.168.1.135:5060;transport=udp>

14:29:11.703 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/udp could not be resolved null
14:29:11.703 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/udp
14:29:11.765 FINA: impl.protocol.sip.SipRegistrarConnection.register().414
sent request= REGISTER sip:192.168.1.101 SIP/2.0
Call-ID: fc209035180fb03ddb973abb02ceb2ef@0.0.0.0
CSeq: 1 REGISTER
From: <sip:6003@192.168.1.101>;tag=23d21598
To: <sip:6003@192.168.1.101>
Via: SIP/2.0/UDP
192.168.1.135:5060;branch=z9hG4bKd95fe0d12b4e888c1b7a247f9dee5c6f
Max-Forwards: 70
User-Agent: SIP Communicator 1.0-alpha3-0.build.by.SVN Windows XP
Expires: 3600
Contact: "6003" <sip:6003@192.168.1.135:5060;transport=udp>;expires=3600
Content-Length: 0

14:29:11.859 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1018
received response=
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP
192.168.1.135:5060;branch=z9hG4bKd95fe0d12b4e888c1b7a247f9dee5c6f;received=192.168.1.135
From: <sip:6003@192.168.1.101>;tag=23d21598
To: <sip:6003@192.168.1.101>;tag=as4e5e9d46
Call-ID: fc209035180fb03ddb973abb02ceb2ef@0.0.0.0
CSeq: 1 REGISTER
User-Agent: Asterisk PBX
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,SUBSCRIBE,NOTIFY
Supported: replaces
WWW-Authenticate: Digest nonce="6eec7d69",realm="asterisk",algorithm=MD5
Content-Length: 0

14:29:11.859 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1036
Found 1 processor(s) for method REGISTER
14:29:11.859 FINA:
impl.protocol.sip.SipRegistrarConnection.processAuthenticationChallenge().1001
Authenticating a Register request.
14:29:11.875 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/udp could not be resolved null
14:29:11.875 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/udp
14:29:11.875 FINA:
impl.protocol.sip.security.MessageDigestAlgorithm.calculateResponse().61
trying to authenticate using : MD5, 6003, asterisk, true, 6eec7d69,
00000001, xyz, REGISTER, sip:192.168.1.101, , null
14:29:11.875 FINA:
impl.protocol.sip.security.SipSecurityManager.handleChallenge().274 Created
authorization header: Authorization: Digest
response="bdb0077e0756de72e236b7da89a9574c",username="6003",nonce="6eec7d69",realm="asterisk",uri="sip:192.168.1.101",algorithm=MD5

14:29:11.875 FINA:
impl.protocol.sip.security.SipSecurityManager.handleChallenge().291
Returning authorization transaction.
14:29:11.890 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1018
received response=
SIP/2.0 100 Trying
Via: SIP/2.0/UDP
192.168.1.135:5060;branch=z9hG4bKebb47fbb7f3254c3066cdd958c1865c2;received=192.168.1.135
From: <sip:6003@192.168.1.101>;tag=23d21598
To: <sip:6003@192.168.1.101>
Call-ID: fc209035180fb03ddb973abb02ceb2ef@0.0.0.0
CSeq: 2 REGISTER
User-Agent: Asterisk PBX
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,SUBSCRIBE,NOTIFY
Supported: replaces
Contact: <sip:6003@192.168.1.101>
Content-Length: 0

14:29:11.890 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1036
Found 1 processor(s) for method REGISTER
14:29:11.937 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1018
received response=
SIP/2.0 200 OK
Via: SIP/2.0/UDP
192.168.1.135:5060;branch=z9hG4bKebb47fbb7f3254c3066cdd958c1865c2;received=192.168.1.135
From: <sip:6003@192.168.1.101>;tag=23d21598
To: <sip:6003@192.168.1.101>;tag=as4e5e9d46
Call-ID: fc209035180fb03ddb973abb02ceb2ef@0.0.0.0
CSeq: 2 REGISTER
User-Agent: Asterisk PBX
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,SUBSCRIBE,NOTIFY
Supported: replaces
Expires: 3600
Contact: <sip:6003@192.168.1.135:5060;transport=udp>;expires=3600
Date: Fri, 03 Oct 2008 12:29:10 GMT
Content-Length: 0

14:29:11.937 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1036
Found 1 processor(s) for method REGISTER
14:29:11.937 FINA:
service.protocol.AbstractProtocolProviderService.fireRegistrationStateChanged().74
Dispatching RegistrationStateChangeEvent[ oldState=Registering;
newState=RegistrationState=Registered;reasonCode=-1;reason=null] to 7
listeners.
14:29:11.953 FINA:
impl.protocol.sip.OperationSetPresenceSipImpl.registrationStateChanged().4980
enter registered state
14:29:11.953 FINA:
impl.protocol.sip.OperationSetBasicInstantMessagingSipImpl.registrationStateChanged().545
The provider changed state from: RegistrationState=Registering to:
RegistrationState=Registered
14:29:11.953 FINA:
impl.protocol.sip.OperationSetTypingNotificationsSipImpl.registrationStateChanged().130
The provider changed state from: RegistrationState=Registering to:
RegistrationState=Registered
14:29:11.984 FINA:
impl.protocol.sip.OperationSetPresenceSipImpl.fireProviderStatusChangeEvent().693
Dispatching Provider Status Change. Listeners=2
evt=ProviderPresenceStatusChangeEvent-[OldStatus=PresenceStatus:Offline,
NewStatus=PresenceStatus:Online]
14:29:12.000 FINA:
impl.protocol.sip.OperationSetPresenceSipImpl.fireProviderStatusChangeEvent().711
status dispatching done.
14:29:12.000 FINA:
impl.protocol.sip.OperationSetPresenceSipImpl.fireProviderMsgStatusChangeEvent().727
Dispatching stat. msg change. Listeners=2
evt=java.beans.PropertyChangeEvent[source=net.java.sip.communicator.impl.protocol.sip.ProtocolProviderServiceSipImpl@169c398]
14:29:12.000 FINA:
impl.protocol.sip.OperationSetPresenceSipImpl.fireProviderMsgStatusChangeEvent().745
status dispatching done.
14:29:17.250 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processTransactionTerminated().1129
Transaction terminated for req=REGISTER sip:192.168.1.101 SIP/2.0
Call-ID: fc209035180fb03ddb973abb02ceb2ef@0.0.0.0
CSeq: 1 REGISTER
From: <sip:6003@192.168.1.101>;tag=23d21598
To: <sip:6003@192.168.1.101>
Via: SIP/2.0/UDP
192.168.1.135:5060;branch=z9hG4bKd95fe0d12b4e888c1b7a247f9dee5c6f
Max-Forwards: 70
User-Agent: SIP Communicator 1.0-alpha3-0.build.by.SVN Windows XP
Expires: 3600
Contact: "6003" <sip:6003@192.168.1.135:5060;transport=udp>;expires=3600
Content-Length: 0

14:29:17.250 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processTransactionTerminated().1139
Found 1 processor(s) for method REGISTER
14:29:17.375 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processTransactionTerminated().1129
Transaction terminated for req=REGISTER sip:192.168.1.101 SIP/2.0
Call-ID: fc209035180fb03ddb973abb02ceb2ef@0.0.0.0
CSeq: 2 REGISTER
From: <sip:6003@192.168.1.101>;tag=23d21598
To: <sip:6003@192.168.1.101>
Max-Forwards: 70
User-Agent: SIP Communicator 1.0-alpha3-0.build.by.SVN Windows XP
Expires: 3600
Contact: "6003" <sip:6003@192.168.1.135:5060;transport=udp>;expires=3600
Via: SIP/2.0/UDP
192.168.1.135:5060;branch=z9hG4bKebb47fbb7f3254c3066cdd958c1865c2
Authorization: Digest
response="bdb0077e0756de72e236b7da89a9574c",username="6003",nonce="6eec7d69",realm="asterisk",uri="sip:192.168.1.101",algorithm=MD5
Content-Length: 0

14:29:17.375 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processTransactionTerminated().1139
Found 1 processor(s) for method REGISTER
14:29:17.953 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/UDP could not be resolved null
14:29:17.953 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/UDP
14:29:18.031 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processRequest().1181
received request=
NOTIFY sip:6003@192.168.1.135:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK08d6e902;received=192.168.1.101
From: "asterisk" <sip:asterisk@192.168.1.101>;tag=as5c7c4547
To: <sip:6003@192.168.1.135:5060;transport=udp>
Contact: <sip:asterisk@192.168.1.101>
Call-ID: 5d5a8366429d4eca2ead8b290685b99a@192.168.1.101
CSeq: 102 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: message-summary
Content-Type: application/simple-message-summary
Content-Length: 93

Messages-Waiting: no
Message-Account: sip:asterisk@192.168.1.101
Voice-Message: 0/0 (0/0)

14:29:18.953 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/UDP could not be resolved null
14:29:18.953 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/UDP
14:29:18.968 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processRequest().1181
received request=
NOTIFY sip:6003@192.168.1.135:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK08d6e902;received=192.168.1.101
From: "asterisk" <sip:asterisk@192.168.1.101>;tag=as5c7c4547
To: <sip:6003@192.168.1.135:5060;transport=udp>
Contact: <sip:asterisk@192.168.1.101>
Call-ID: 5d5a8366429d4eca2ead8b290685b99a@192.168.1.101
CSeq: 102 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: message-summary
Content-Type: application/simple-message-summary
Content-Length: 93

Messages-Waiting: no
Message-Account: sip:asterisk@192.168.1.101
Voice-Message: 0/0 (0/0)

14:29:19.937 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/UDP could not be resolved null
14:29:19.953 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/UDP
14:29:19.968 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processRequest().1181
received request=
NOTIFY sip:6003@192.168.1.135:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK08d6e902;received=192.168.1.101
From: "asterisk" <sip:asterisk@192.168.1.101>;tag=as5c7c4547
To: <sip:6003@192.168.1.135:5060;transport=udp>
Contact: <sip:asterisk@192.168.1.101>
Call-ID: 5d5a8366429d4eca2ead8b290685b99a@192.168.1.101
CSeq: 102 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: message-summary
Content-Type: application/simple-message-summary
Content-Length: 93

Messages-Waiting: no
Message-Account: sip:asterisk@192.168.1.101
Voice-Message: 0/0 (0/0)

14:29:21.921 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/UDP could not be resolved null
14:29:21.921 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/UDP
14:29:21.921 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processRequest().1181
received request=
NOTIFY sip:6003@192.168.1.135:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK08d6e902;received=192.168.1.101
From: "asterisk" <sip:asterisk@192.168.1.101>;tag=as5c7c4547
To: <sip:6003@192.168.1.135:5060;transport=udp>
Contact: <sip:asterisk@192.168.1.101>
Call-ID: 5d5a8366429d4eca2ead8b290685b99a@192.168.1.101
CSeq: 102 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: message-summary
Content-Type: application/simple-message-summary
Content-Length: 93

Messages-Waiting: no
Message-Account: sip:asterisk@192.168.1.101
Voice-Message: 0/0 (0/0)

14:29:25.937 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/UDP could not be resolved null
14:29:25.937 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/UDP
14:29:25.953 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processRequest().1181
received request=
NOTIFY sip:6003@192.168.1.135:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK08d6e902;received=192.168.1.101
From: "asterisk" <sip:asterisk@192.168.1.101>;tag=as5c7c4547
To: <sip:6003@192.168.1.135:5060;transport=udp>
Contact: <sip:asterisk@192.168.1.101>
Call-ID: 5d5a8366429d4eca2ead8b290685b99a@192.168.1.101
CSeq: 102 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: message-summary
Content-Type: application/simple-message-summary
Content-Length: 93

Messages-Waiting: no
Message-Account: sip:asterisk@192.168.1.101
Voice-Message: 0/0 (0/0)

14:29:29.937 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/UDP could not be resolved null
14:29:29.937 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/UDP
14:29:29.953 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processRequest().1181
received request=
NOTIFY sip:6003@192.168.1.135:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK08d6e902;received=192.168.1.101
From: "asterisk" <sip:asterisk@192.168.1.101>;tag=as5c7c4547
To: <sip:6003@192.168.1.135:5060;transport=udp>
Contact: <sip:asterisk@192.168.1.101>
Call-ID: 5d5a8366429d4eca2ead8b290685b99a@192.168.1.101
CSeq: 102 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: message-summary
Content-Type: application/simple-message-summary
Content-Length: 93

Messages-Waiting: no
Message-Account: sip:asterisk@192.168.1.101
Voice-Message: 0/0 (0/0)

14:29:33.937 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/UDP could not be resolved null
14:29:33.937 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/UDP
14:29:33.953 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processRequest().1181
received request=
NOTIFY sip:6003@192.168.1.135:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK08d6e902;received=192.168.1.101
From: "asterisk" <sip:asterisk@192.168.1.101>;tag=as5c7c4547
To: <sip:6003@192.168.1.135:5060;transport=udp>
Contact: <sip:asterisk@192.168.1.101>
Call-ID: 5d5a8366429d4eca2ead8b290685b99a@192.168.1.101
CSeq: 102 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: message-summary
Content-Type: application/simple-message-summary
Content-Length: 93

Messages-Waiting: no
Message-Account: sip:asterisk@192.168.1.101
Voice-Message: 0/0 (0/0)

14:29:36.937 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.getIntendedDestination().2762
Returning address 192.168.1.101/192.168.1.101 for destination 192.168.1.101
14:29:36.937 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.getLocalViaHeaders().1445
generated via headers:Via: SIP/2.0/UDP 192.168.1.135:5060

14:29:36.953 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.getIntendedDestination().2762
Returning address 192.168.1.101/192.168.1.101 for destination 192.168.1.101
14:29:36.953 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.getContactHeader().1559
generated contactHeader:Contact: "6003"
<sip:6003@192.168.1.135:5060;transport=udp>

14:29:36.953 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/udp could not be resolved null
14:29:36.953 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/udp
14:29:36.968 FINA: impl.protocol.sip.SipRegistrarConnection.register().414
sent request= REGISTER sip:192.168.1.101 SIP/2.0
Call-ID: 44ed27a2c8ba16243f414584e55bcb60@0.0.0.0
CSeq: 3 REGISTER
From: <sip:6003@192.168.1.101>;tag=11fb7c94
To: <sip:6003@192.168.1.101>
Via: SIP/2.0/UDP
192.168.1.135:5060;branch=z9hG4bK8163c5ad4ca1a339f3d7869cee32bb23
Max-Forwards: 70
User-Agent: SIP Communicator 1.0-alpha3-0.build.by.SVN Windows XP
Expires: 3600
Contact: "6003" <sip:6003@192.168.1.135:5060;transport=udp>;expires=3600
Content-Length: 0

14:29:36.968 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1018
received response=
SIP/2.0 100 Trying
Via: SIP/2.0/UDP
192.168.1.135:5060;branch=z9hG4bK8163c5ad4ca1a339f3d7869cee32bb23;received=192.168.1.135
From: <sip:6003@192.168.1.101>;tag=11fb7c94
To: <sip:6003@192.168.1.101>
Call-ID: 44ed27a2c8ba16243f414584e55bcb60@0.0.0.0
CSeq: 3 REGISTER
User-Agent: Asterisk PBX
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,SUBSCRIBE,NOTIFY
Supported: replaces
Contact: <sip:6003@192.168.1.101>
Content-Length: 0

14:29:36.968 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1036
Found 1 processor(s) for method REGISTER
14:29:36.968 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1018
received response=
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP
192.168.1.135:5060;branch=z9hG4bK8163c5ad4ca1a339f3d7869cee32bb23;received=192.168.1.135
From: <sip:6003@192.168.1.101>;tag=11fb7c94
To: <sip:6003@192.168.1.101>;tag=as2f8aca43
Call-ID: 44ed27a2c8ba16243f414584e55bcb60@0.0.0.0
CSeq: 3 REGISTER
User-Agent: Asterisk PBX
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,SUBSCRIBE,NOTIFY
Supported: replaces
WWW-Authenticate: Digest nonce="02022b8b",realm="asterisk",algorithm=MD5
Content-Length: 0

14:29:36.968 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1036
Found 1 processor(s) for method REGISTER
14:29:36.984 FINA:
impl.protocol.sip.SipRegistrarConnection.processAuthenticationChallenge().1001
Authenticating a Register request.
14:29:36.984 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/udp could not be resolved null
14:29:36.984 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/udp
14:29:36.984 FINA:
impl.protocol.sip.security.MessageDigestAlgorithm.calculateResponse().61
trying to authenticate using : MD5, 6003, asterisk, true, 02022b8b,
00000001, xyz, REGISTER, sip:192.168.1.101, , null
14:29:36.984 FINA:
impl.protocol.sip.security.SipSecurityManager.handleChallenge().274 Created
authorization header: Authorization: Digest
response="86a87012f3312cc287767d86c43735f3",username="6003",nonce="02022b8b",realm="asterisk",uri="sip:192.168.1.101",algorithm=MD5

14:29:37.000 FINA:
impl.protocol.sip.security.SipSecurityManager.handleChallenge().291
Returning authorization transaction.
14:29:37.000 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1018
received response=
SIP/2.0 100 Trying
Via: SIP/2.0/UDP
192.168.1.135:5060;branch=z9hG4bK06c73ff0c56c450d09996136b0755945;received=192.168.1.135
From: <sip:6003@192.168.1.101>;tag=11fb7c94
To: <sip:6003@192.168.1.101>
Call-ID: 44ed27a2c8ba16243f414584e55bcb60@0.0.0.0
CSeq: 4 REGISTER
User-Agent: Asterisk PBX
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,SUBSCRIBE,NOTIFY
Supported: replaces
Contact: <sip:6003@192.168.1.101>
Content-Length: 0

14:29:37.000 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1036
Found 1 processor(s) for method REGISTER
14:29:37.015 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1018
received response=
SIP/2.0 200 OK
Via: SIP/2.0/UDP
192.168.1.135:5060;branch=z9hG4bK06c73ff0c56c450d09996136b0755945;received=192.168.1.135
From: <sip:6003@192.168.1.101>;tag=11fb7c94
To: <sip:6003@192.168.1.101>;tag=as2f8aca43
Call-ID: 44ed27a2c8ba16243f414584e55bcb60@0.0.0.0
CSeq: 4 REGISTER
User-Agent: Asterisk PBX
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,SUBSCRIBE,NOTIFY
Supported: replaces
Expires: 3600
Contact: <sip:6003@192.168.1.135:5060;transport=udp>;expires=3600
Date: Fri, 03 Oct 2008 12:29:35 GMT
Content-Length: 0

14:29:37.015 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1036
Found 1 processor(s) for method REGISTER
14:29:39.984 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/UDP could not be resolved null
14:29:39.984 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/UDP
14:29:40.000 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processRequest().1181
received request=
NOTIFY sip:6003@192.168.1.135:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK39a432e3;received=192.168.1.101
From: "asterisk" <sip:asterisk@192.168.1.101>;tag=as5c2ca2cb
To: <sip:6003@192.168.1.135:5060;transport=udp>
Contact: <sip:asterisk@192.168.1.101>
Call-ID: 32c1f92f340b1e5c25ff9d61243a2a06@192.168.1.101
CSeq: 102 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: message-summary
Content-Type: application/simple-message-summary
Content-Length: 93

Messages-Waiting: no
Message-Account: sip:asterisk@192.168.1.101
Voice-Message: 0/0 (0/0)

14:29:40.937 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/UDP could not be resolved null
14:29:40.937 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/UDP
14:29:40.937 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processRequest().1181
received request=
NOTIFY sip:6003@192.168.1.135:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK39a432e3;received=192.168.1.101
From: "asterisk" <sip:asterisk@192.168.1.101>;tag=as5c2ca2cb
To: <sip:6003@192.168.1.135:5060;transport=udp>
Contact: <sip:asterisk@192.168.1.101>
Call-ID: 32c1f92f340b1e5c25ff9d61243a2a06@192.168.1.101
CSeq: 102 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: message-summary
Content-Type: application/simple-message-summary
Content-Length: 93

Messages-Waiting: no
Message-Account: sip:asterisk@192.168.1.101
Voice-Message: 0/0 (0/0)

14:29:41.953 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/UDP could not be resolved null
14:29:41.953 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/UDP
14:29:41.968 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processRequest().1181
received request=
NOTIFY sip:6003@192.168.1.135:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK39a432e3;received=192.168.1.101
From: "asterisk" <sip:asterisk@192.168.1.101>;tag=as5c2ca2cb
To: <sip:6003@192.168.1.135:5060;transport=udp>
Contact: <sip:asterisk@192.168.1.101>
Call-ID: 32c1f92f340b1e5c25ff9d61243a2a06@192.168.1.101
CSeq: 102 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: message-summary
Content-Type: application/simple-message-summary
Content-Length: 93

Messages-Waiting: no
Message-Account: sip:asterisk@192.168.1.101
Voice-Message: 0/0 (0/0)

14:29:42.468 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processTransactionTerminated().1129
Transaction terminated for req=REGISTER sip:192.168.1.101 SIP/2.0
Call-ID: 44ed27a2c8ba16243f414584e55bcb60@0.0.0.0
CSeq: 3 REGISTER
From: <sip:6003@192.168.1.101>;tag=11fb7c94
To: <sip:6003@192.168.1.101>
Via: SIP/2.0/UDP
192.168.1.135:5060;branch=z9hG4bK8163c5ad4ca1a339f3d7869cee32bb23
Max-Forwards: 70
User-Agent: SIP Communicator 1.0-alpha3-0.build.by.SVN Windows XP
Expires: 3600
Contact: "6003" <sip:6003@192.168.1.135:5060;transport=udp>;expires=3600
Content-Length: 0

14:29:42.468 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processTransactionTerminated().1139
Found 1 processor(s) for method REGISTER
14:29:42.500 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processTransactionTerminated().1129
Transaction terminated for req=REGISTER sip:192.168.1.101 SIP/2.0
Call-ID: 44ed27a2c8ba16243f414584e55bcb60@0.0.0.0
CSeq: 4 REGISTER
From: <sip:6003@192.168.1.101>;tag=11fb7c94
To: <sip:6003@192.168.1.101>
Max-Forwards: 70
User-Agent: SIP Communicator 1.0-alpha3-0.build.by.SVN Windows XP
Expires: 3600
Contact: "6003" <sip:6003@192.168.1.135:5060;transport=udp>;expires=3600
Via: SIP/2.0/UDP
192.168.1.135:5060;branch=z9hG4bK06c73ff0c56c450d09996136b0755945
Authorization: Digest
response="86a87012f3312cc287767d86c43735f3",username="6003",nonce="02022b8b",realm="asterisk",uri="sip:192.168.1.101",algorithm=MD5
Content-Length: 0

14:29:42.500 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processTransactionTerminated().1139
Found 1 processor(s) for method REGISTER
14:29:43.953 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/UDP could not be resolved null
14:29:43.953 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/UDP
14:29:43.968 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processRequest().1181
received request=
NOTIFY sip:6003@192.168.1.135:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK39a432e3;received=192.168.1.101
From: "asterisk" <sip:asterisk@192.168.1.101>;tag=as5c2ca2cb
To: <sip:6003@192.168.1.135:5060;transport=udp>
Contact: <sip:asterisk@192.168.1.101>
Call-ID: 32c1f92f340b1e5c25ff9d61243a2a06@192.168.1.101
CSeq: 102 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: message-summary
Content-Type: application/simple-message-summary
Content-Length: 93

Messages-Waiting: no
Message-Account: sip:asterisk@192.168.1.101
Voice-Message: 0/0 (0/0)

14:29:46.031 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/UDP could not be resolved null
14:29:46.031 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/UDP
14:29:46.046 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processRequest().1181
received request=
INVITE sip:6003@192.168.1.135:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK6b6a792c;received=192.168.1.101
From: "6000" <sip:6000@192.168.1.101>;tag=as131e994d
To: <sip:6003@192.168.1.135:5060;transport=udp>
Contact: <sip:6000@192.168.1.101>
Call-ID: 5da645183b230eb37be4b3f21234c8b6@192.168.1.101
CSeq: 102 INVITE
User-Agent: Asterisk PBX
Max-Forwards: 70
Date: Fri, 03 Oct 2008 12:29:44 GMT
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,SUBSCRIBE,NOTIFY
Supported: replaces
Content-Type: application/sdp
Content-Length: 184

v=0
o=root 8812 8812 IN IP4 192.168.1.101
s=session
c=IN IP4 192.168.1.101
t=0 0
m=audio 13566 RTP/AVP 0
a=rtpmap:0 PCMU/8000
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv

14:29:46.046 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processRequest().1265 Found
1 processor(s) for method INVITE
14:29:46.093 FINA:
impl.protocol.sip.OperationSetBasicTelephonySipImpl.processRequest().532
received INVITE
14:29:46.093 FINA:
impl.protocol.sip.OperationSetBasicTelephonySipImpl.processRequest().538
request is an INVITE. Dialog state=null
14:29:46.125 FINA: service.protocol.Call.fireCallParticipantEvent().174
Dispatching a CallParticipant event to 0 listeners. event is:
CallParticipantEvent: ID=1 source participant=6000
<sip:6000@192.168.1.101>;status=Unknown source call=Call:
id=122303698609327649252 participants=1
14:29:46.125 FINA:
service.protocol.AbstractCallParticipant.fireCallParticipantChangeEvent().101
Dispatching a CallParticipantChangeEvent event to 1 listeners. event is:
CallParticipantChangeEvent: type=CallParticipantStatusChange
oldV=net.java.sip.communicator.service.protocol.CallParticipantState:Unknown
newV=net.java.sip.communicator.service.protocol.CallParticipantState:Incoming
Call for participant=6000 <sip:6000@192.168.1.101>;status=Incoming Call
14:29:46.125 FINA:
service.protocol.AbstractOperationSetBasicTelephony.fireCallEvent().59
Dispatching a CallEvent to 2 listeners. event is: CallEvent:[ id=2
Call=Call: id=122303698609327649252 participants=1]
14:29:46.281 FINA:
impl.protocol.sip.OperationSetBasicTelephonySipImpl.processInvite().1508
Invite seems ok, we'll say RINGING.
14:29:46.281 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.attachToTag().2443
generated to tag: e32f9b83
14:29:46.281 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.getIntendedDestination().2762
Returning address 192.168.1.101/192.168.1.101 for destination 192.168.1.101
14:29:46.281 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.getContactHeader().1559
generated contactHeader:Contact: "6003"
<sip:6003@192.168.1.135:5060;transport=udp>

14:29:46.281 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/UDP could not be resolved null
14:29:46.296 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/UDP
14:29:46.296 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/UDP could not be resolved null
14:29:46.296 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/UDP
14:29:46.312 FINA:
impl.protocol.sip.OperationSetBasicTelephonySipImpl.processInvite().1554
sent a RINGING response: SIP/2.0 180 Ringing
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK6b6a792c;received=192.168.1.101
From: "6000" <sip:6000@192.168.1.101>;tag=as131e994d
To: "6003" <sip:6003@192.168.1.135:5060;transport=udp>;tag=e32f9b83
Call-ID: 5da645183b230eb37be4b3f21234c8b6@192.168.1.101
CSeq: 102 INVITE
User-Agent: SIP Communicator 1.0-alpha3-0.build.by.SVN Windows XP
Contact: "6003" <sip:6003@192.168.1.135:5060;transport=udp>
Content-Length: 0

14:29:47.953 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/UDP could not be resolved null
14:29:47.953 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/UDP
14:29:47.968 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processRequest().1181
received request=
NOTIFY sip:6003@192.168.1.135:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK39a432e3;received=192.168.1.101
From: "asterisk" <sip:asterisk@192.168.1.101>;tag=as5c2ca2cb
To: <sip:6003@192.168.1.135:5060;transport=udp>
Contact: <sip:asterisk@192.168.1.101>
Call-ID: 32c1f92f340b1e5c25ff9d61243a2a06@192.168.1.101
CSeq: 102 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: message-summary
Content-Type: application/simple-message-summary
Content-Length: 93

Messages-Waiting: no
Message-Account: sip:asterisk@192.168.1.101
Voice-Message: 0/0 (0/0)

14:29:51.078 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.attachToTag().2431 We seem
to already have a tag in this dialog. Returning
14:29:51.250 FINA:
impl.media.CallSessionImpl.registerCustomCodecFormats().2150 registering
format ilbc/rtp, 8000.0 Hz, 16-bit, Mono, LittleEndian, Signed with RTP
manager
14:29:51.265 FINA:
impl.media.CallSessionImpl.registerCustomCodecFormats().2150 registering
format ALAW/rtp, 8000.0 Hz, 8-bit, Mono, Signed with RTP manager
14:29:51.265 FINA:
impl.media.CallSessionImpl.registerCustomCodecFormats().2150 registering
format speex/rtp, 8000.0 Hz, 8-bit, Mono, Signed with RTP manager
14:29:51.343 FINA:
impl.netaddr.NetworkAddressManagerServiceImpl.getPublicAddressFor().375 Stun
is disabled for destination /192.168.1.101, skipping mapped address recovery
(useStun=false, IPv6@=false).
14:29:51.343 FINA: impl.media.CallSessionImpl.allocateMediaPorts().1925
AudioSessionAddress=DataAddress: /0.0.0.0
ControlAddress: /0.0.0.0
DataPort: 5000
ControlPort: 5001
14:29:51.343 FINA: impl.media.CallSessionImpl.allocateMediaPorts().1926
AudioPublicAddress=/192.168.1.135:5000
14:29:51.343 FINA:
impl.netaddr.NetworkAddressManagerServiceImpl.getPublicAddressFor().375 Stun
is disabled for destination /192.168.1.101, skipping mapped address recovery
(useStun=false, IPv6@=false).
14:29:51.343 FINA: com.sun.media.rtp.RTPSessionMgr.initialize() Looking for
local data address: /0.0.0.0 and control address/0.0.0.0
14:29:51.375 FINA: com.sun.media.rtp.RTPSessionMgr.initialize() Looking for
local data address: /0.0.0.0 and control address/0.0.0.0
14:29:51.375 FINA:
impl.media.CallSessionImpl.createSessionDescription().1417 Will create media
descs with: audio public address=/192.168.1.135:5000 and video public
address=/192.168.1.135:5002
14:29:51.375 FINA: impl.media.MediaControl.calculateSupportedEncodings().599
We will be able to transmit in:
14:29:51.937 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/UDP could not be resolved null
14:29:51.953 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/UDP
14:29:51.953 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processRequest().1181
received request=
NOTIFY sip:6003@192.168.1.135:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK39a432e3;received=192.168.1.101
From: "asterisk" <sip:asterisk@192.168.1.101>;tag=as5c2ca2cb
To: <sip:6003@192.168.1.135:5060;transport=udp>
Contact: <sip:asterisk@192.168.1.101>
Call-ID: 32c1f92f340b1e5c25ff9d61243a2a06@192.168.1.101
CSeq: 102 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: message-summary
Content-Type: application/simple-message-summary
Content-Length: 93

Messages-Waiting: no
Message-Account: sip:asterisk@192.168.1.101
Voice-Message: 0/0 (0/0)

14:29:52.093 FINA: impl.media.MediaControl.calculateSupportedEncodings().623
Audio=[1]=dvi/rtp; sdp=5
14:29:52.093 FINA: impl.media.MediaControl.calculateSupportedEncodings().623
Audio=[2]=speex/rtp; sdp=110
14:29:52.093 FINA: impl.media.MediaControl.calculateSupportedEncodings().623
Audio=[3]=ilbc/rtp; sdp=97
14:29:52.093 FINA: impl.media.MediaControl.calculateSupportedEncodings().623
Audio=[6]=g723/rtp; sdp=4
14:29:52.093 FINA: impl.media.MediaControl.calculateSupportedEncodings().623
Audio=[7]=gsm/rtp; sdp=3
14:29:52.093 FINA: impl.media.MediaControl.calculateSupportedEncodings().623
Audio=[8]=ULAW/rtp; sdp=0
14:29:52.093 FINA: impl.media.MediaControl.calculateSupportedEncodings().623
Audio=[9]=ALAW/rtp; sdp=8
14:29:52.093 FINA: impl.media.MediaControl.calculateSupportedEncodings().644
unknown encoding format mpegaudio/rtp
14:29:52.093 FINA: impl.media.MediaControl.calculateSupportedEncodings().644
unknown encoding format mpegaudio/rtp
14:29:52.093 FINA: impl.media.MediaControl.calculateSupportedEncodings().644
unknown encoding format mpegaudio/rtp
14:29:52.093 FINA: impl.media.MediaControl.calculateSupportedEncodings().644
unknown encoding format mpegaudio/rtp
14:29:52.109 FINA: impl.media.MediaControl.calculateSupportedEncodings().644
unknown encoding format mpegaudio/rtp
14:29:52.109 FINA: impl.media.MediaControl.calculateSupportedEncodings().644
unknown encoding format mpegaudio/rtp
14:29:52.109 FINA: impl.media.MediaControl.calculateSupportedEncodings().644
unknown encoding format mpegaudio/rtp
14:29:52.796 FINA:
impl.media.MediaControl.createDataSourceForEncodings().825 Available
encodings are:
14:29:52.796 FINA:
impl.media.MediaControl.createDataSourceForEncodings().828 track[1]
format[1]=dvi/rtp
14:29:52.796 FINA:
impl.media.MediaControl.createDataSourceForEncodings().828 track[1]
format[2]=speex/rtp
14:29:52.796 FINA:
impl.media.MediaControl.createDataSourceForEncodings().828 track[1]
format[3]=ilbc/rtp
14:29:52.796 FINA:
impl.media.MediaControl.createDataSourceForEncodings().828 track[1]
format[4]=dvi/rtp
14:29:52.796 FINA:
impl.media.MediaControl.createDataSourceForEncodings().828 track[1]
format[5]=dvi/rtp
14:29:52.796 FINA:
impl.media.MediaControl.createDataSourceForEncodings().828 track[1]
format[6]=g723/rtp
14:29:52.812 FINA:
impl.media.MediaControl.createDataSourceForEncodings().828 track[1]
format[7]=gsm/rtp
14:29:52.812 FINA:
impl.media.MediaControl.createDataSourceForEncodings().828 track[1]
format[8]=ULAW/rtp
14:29:52.812 FINA:
impl.media.MediaControl.createDataSourceForEncodings().828 track[1]
format[9]=ALAW/rtp
14:29:52.812 FINA:
impl.media.MediaControl.createDataSourceForEncodings().828 track[1]
format[10]=mpegaudio/rtp
14:29:52.812 FINA:
impl.media.MediaControl.createDataSourceForEncodings().828 track[1]
format[11]=mpegaudio/rtp
14:29:52.812 FINA:
impl.media.MediaControl.createDataSourceForEncodings().828 track[1]
format[12]=mpegaudio/rtp
14:29:52.812 FINA:
impl.media.MediaControl.createDataSourceForEncodings().828 track[1]
format[13]=mpegaudio/rtp
14:29:52.812 FINA:
impl.media.MediaControl.createDataSourceForEncodings().828 track[1]
format[14]=mpegaudio/rtp
14:29:52.812 FINA:
impl.media.MediaControl.createDataSourceForEncodings().828 track[1]
format[15]=mpegaudio/rtp
14:29:52.812 FINA:
impl.media.MediaControl.createDataSourceForEncodings().828 track[1]
format[16]=mpegaudio/rtp
14:29:52.812 FINA:
impl.media.MediaControl.createDataSourceForEncodings().877 Track 0 is set to
transmit as: ULAW/rtp, 8000.0 Hz, 8-bit, Mono, FrameSize=8 bits
14:29:54.328 FINA: impl.media.CallSessionImpl.update().2345 received the
following JMF SendStreamEvent -
javax.media.rtp.event.NewSendStreamEvent=javax.media.rtp.event.NewSendStreamEvent[source
= RTPManager
       SSRCCache com.sun.media.rtp.SSRCCache@a88bc2
       Dataport 5000
       Controlport 5001
       Address 0.0.0.0
       RTPForwarder com.sun.media.rtp.util.PacketForwarder@16f8789
       RTPDemux com.sun.media.rtp.RTPDemultiplexer@12b6037]
14:29:54.328 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.getIntendedDestination().2762
Returning address 192.168.1.101/192.168.1.101 for destination 192.168.1.101
14:29:54.328 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.getContactHeader().1559
generated contactHeader:Contact: "6003"
<sip:6003@192.168.1.135:5060;transport=udp>

14:29:54.359 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/UDP could not be resolved null
14:29:54.359 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/UDP
14:29:54.359 FINA:
impl.protocol.sip.OperationSetBasicTelephonySipImpl.answerCallParticipant().2796
sent response
SIP/2.0 200 OK
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK6b6a792c;received=192.168.1.101
From: "6000" <sip:6000@192.168.1.101>;tag=as131e994d
To: <sip:6003@192.168.1.135:5060;transport=udp>;tag=e32f9b83
Call-ID: 5da645183b230eb37be4b3f21234c8b6@192.168.1.101
CSeq: 102 INVITE
User-Agent: SIP Communicator 1.0-alpha3-0.build.by.SVN Windows XP
Content-Type: application/sdp
Contact: "6003" <sip:6003@192.168.1.135:5060;transport=udp>
Content-Length: 98

v=0
o=6003 0 0 IN IP4 192.168.1.135
s=-
c=IN IP4 192.168.1.135
t=0 0
m=audio 5000 RTP/AVP 0

14:29:54.390 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/UDP could not be resolved null
14:29:54.390 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/UDP could not be resolved null
14:29:54.406 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/UDP
14:29:54.406 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/UDP
14:29:54.406 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processRequest().1181
received request=
ACK sip:6003@192.168.1.135:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK1b83304d;received=192.168.1.101
From: "6000" <sip:6000@192.168.1.101>;tag=as131e994d
To: <sip:6003@192.168.1.135:5060;transport=udp>;tag=e32f9b83
Contact: <sip:6000@192.168.1.101>
Call-ID: 5da645183b230eb37be4b3f21234c8b6@192.168.1.101
CSeq: 102 ACK
User-Agent: Asterisk PBX
Max-Forwards: 70
Content-Length: 0

14:29:54.421 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processRequest().1265 Found
1 processor(s) for method ACK
14:29:54.421 FINA:
service.protocol.AbstractCallParticipant.fireCallParticipantChangeEvent().101
Dispatching a CallParticipantChangeEvent event to 4 listeners. event is:
CallParticipantChangeEvent: type=CallParticipantStatusChange
oldV=net.java.sip.communicator.service.protocol.CallParticipantState:Incoming
Call
newV=net.java.sip.communicator.service.protocol.CallParticipantState:Connected
for participant=6000 <sip:6000@192.168.1.101>;status=Connected
14:29:54.421 FINA: service.protocol.Call.fireCallChangeEvent().226
Dispatching a CallChange event to 4 listeners. event is: CallChangeEvent:
type=CallState
oldV=net.java.sip.communicator.service.protocol.CallState:Initializing
newV=net.java.sip.communicator.service.protocol.CallState:In Progress
14:29:54.421 FINA: impl.media.CallSessionImpl.callStateChanged().2176 call
connected. starting streaming
14:29:54.437 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processRequest().1181
received request=
INVITE sip:6003@192.168.1.135:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK7ce039f7;received=192.168.1.101
From: "6000" <sip:6000@192.168.1.101>;tag=as131e994d
To: <sip:6003@192.168.1.135:5060;transport=udp>;tag=e32f9b83
Contact: <sip:6000@192.168.1.101>
Call-ID: 5da645183b230eb37be4b3f21234c8b6@192.168.1.101
CSeq: 103 INVITE
User-Agent: Asterisk PBX
Max-Forwards: 70
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,SUBSCRIBE,NOTIFY
Supported: replaces
Content-Type: application/sdp
Content-Length: 183

v=0
o=root 8812 8813 IN IP4 192.168.1.102
s=session
c=IN IP4 192.168.1.102
t=0 0
m=audio 5004 RTP/AVP 0
a=rtpmap:0 PCMU/8000
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv

14:29:54.453 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processRequest().1265 Found
1 processor(s) for method INVITE
14:29:54.453 FINA:
impl.protocol.sip.OperationSetBasicTelephonySipImpl.processRequest().532
received INVITE
14:29:54.453 FINA:
impl.protocol.sip.OperationSetBasicTelephonySipImpl.processRequest().538
request is an INVITE. Dialog state=Confirmed Dialog
14:29:54.453 FINA:
impl.protocol.sip.OperationSetBasicTelephonySipImpl.processInvite().1508
Invite seems ok, we'll say OK.
14:29:54.453 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.attachToTag().2431 We seem
to already have a tag in this dialog. Returning
14:29:54.453 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.getIntendedDestination().2762
Returning address 192.168.1.101/192.168.1.101 for destination 192.168.1.101
14:29:54.453 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.getContactHeader().1559
generated contactHeader:Contact: "6003"
<sip:6003@192.168.1.135:5060;transport=udp>

14:29:54.453 FINA:
impl.media.CallSessionImpl.createSessionDescription().1417 Will create media
descs with: audio public address=/192.168.1.135:5000 and video public
address=/192.168.1.135:5002
14:29:54.484 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/UDP could not be resolved null
14:29:54.484 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/UDP
14:29:54.484 FINA:
impl.protocol.sip.OperationSetBasicTelephonySipImpl.processInvite().1554
sent a OK response: SIP/2.0 200 OK
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK7ce039f7;received=192.168.1.101
From: "6000" <sip:6000@192.168.1.101>;tag=as131e994d
To: "6003" <sip:6003@192.168.1.135:5060;transport=udp>;tag=e32f9b83
Call-ID: 5da645183b230eb37be4b3f21234c8b6@192.168.1.101
CSeq: 103 INVITE
User-Agent: SIP Communicator 1.0-alpha3-0.build.by.SVN Windows XP
Contact: "6003" <sip:6003@192.168.1.135:5060;transport=udp>
Content-Type: application/sdp
Content-Length: 110

v=0
o=6003 0 0 IN IP4 192.168.1.135
s=-
c=IN IP4 192.168.1.135
t=0 0
m=audio 5000 RTP/AVP 0
a=sendrecv

14:29:54.500 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/UDP could not be resolved null
14:29:54.500 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/UDP
14:29:54.515 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processRequest().1181
received request=
ACK sip:6003@192.168.1.135:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK3543b597;received=192.168.1.101
From: "6000" <sip:6000@192.168.1.101>;tag=as131e994d
To: <sip:6003@192.168.1.135:5060;transport=udp>;tag=e32f9b83
Contact: <sip:6000@192.168.1.101>
Call-ID: 5da645183b230eb37be4b3f21234c8b6@192.168.1.101
CSeq: 103 ACK
User-Agent: Asterisk PBX
Max-Forwards: 70
Content-Length: 0

14:29:54.515 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processRequest().1265 Found
1 processor(s) for method ACK
14:29:54.578 FINA: impl.media.CallSessionImpl.update().2366 received a new
incoming stream. javax.media.rtp.event.NewReceiveStreamEvent[source =
RTPManager
       SSRCCache com.sun.media.rtp.SSRCCache@a88bc2
       Dataport 5000
       Controlport 5001
       Address 0.0.0.0
       RTPForwarder com.sun.media.rtp.util.PacketForwarder@16f8789
       RTPDemux com.sun.media.rtp.RTPDemultiplexer@12b6037]
14:29:54.578 FINA: impl.media.CallSessionImpl.update().2376 Received new RTP
stream: ULAW/rtp, 8000.0 Hz, 8-bit, Mono
14:29:54.593 FINA: impl.media.CallSessionImpl.controllerUpdate().2450
Received a ControllerEvent:
javax.media.TransitionEvent[source=com.sun.media.content.unknown.Handler@112ee4f,previous=Unrealized,current=Realizing,target=Realized]
14:29:54.625 FINA: impl.media.CallSessionImpl.controllerUpdate().2450
Received a ControllerEvent:
javax.media.RealizeCompleteEvent[source=com.sun.media.content.unknown.Handler@112ee4f,previous=Realizing,current=Realized,target=Realized]
14:29:54.625 FINA: impl.media.CallSessionImpl.controllerUpdate().2492 A
player was realized and will be started.
14:29:54.625 FINA: impl.media.CallSessionImpl.controllerUpdate().2450
Received a ControllerEvent:
javax.media.TransitionEvent[source=com.sun.media.content.unknown.Handler@112ee4f,previous=Realized,current=Prefetching,target=Started]
14:29:54.625 FINA: impl.media.CallSessionImpl.controllerUpdate().2450
Received a ControllerEvent:
javax.media.PrefetchCompleteEvent[source=com.sun.media.content.unknown.Handler@112ee4f,previous=Prefetching,current=Prefetched,target=Started]
14:29:54.640 FINA: impl.media.CallSessionImpl.controllerUpdate().2450
Received a ControllerEvent:
javax.media.StartEvent[source=com.sun.media.content.unknown.Handler@112ee4f,previous=Prefetched,current=Started,target=Started,mediaTime=javax.media.Time@16c1857,timeBaseTime=javax.media.Time@1b1fd9c]
14:29:54.640 FINA: impl.media.CallSessionImpl.controllerUpdate().2551
Received a StartEvent
14:29:54.796 FINA: impl.media.CallSessionImpl.update().2366 received a new
incoming stream. javax.media.rtp.event.NewReceiveStreamEvent[source =
RTPManager
       SSRCCache com.sun.media.rtp.SSRCCache@a88bc2
       Dataport 5000
       Controlport 5001
       Address 0.0.0.0
       RTPForwarder com.sun.media.rtp.util.PacketForwarder@16f8789
       RTPDemux com.sun.media.rtp.RTPDemultiplexer@12b6037]
14:29:54.796 FINA: impl.media.CallSessionImpl.update().2376 Received new RTP
stream: ULAW/rtp, 8000.0 Hz, 8-bit, Mono
14:29:54.812 FINA: impl.media.CallSessionImpl.controllerUpdate().2450
Received a ControllerEvent:
javax.media.TransitionEvent[source=com.sun.media.content.unknown.Handler@163765,previous=Unrealized,current=Realizing,target=Realized]
14:29:54.828 FINA: impl.media.CallSessionImpl.controllerUpdate().2450
Received a ControllerEvent:
javax.media.RealizeCompleteEvent[source=com.sun.media.content.unknown.Handler@163765,previous=Realizing,current=Realized,target=Realized]
14:29:54.828 FINA: impl.media.CallSessionImpl.controllerUpdate().2492 A
player was realized and will be started.
14:29:54.828 FINA: impl.media.CallSessionImpl.controllerUpdate().2450
Received a ControllerEvent:
javax.media.TransitionEvent[source=com.sun.media.content.unknown.Handler@163765,previous=Realized,current=Prefetching,target=Started]
14:29:54.843 FINA: impl.media.CallSessionImpl.controllerUpdate().2450
Received a ControllerEvent:
javax.media.PrefetchCompleteEvent[source=com.sun.media.content.unknown.Handler@163765,previous=Prefetching,current=Prefetched,target=Started]
14:29:54.843 FINA: impl.media.CallSessionImpl.controllerUpdate().2450
Received a ControllerEvent:
javax.media.StartEvent[source=com.sun.media.content.unknown.Handler@163765,previous=Prefetched,current=Started,target=Started,mediaTime=javax.media.Time@7262b6,timeBaseTime=javax.media.Time@1aecf45]
14:29:54.843 FINA: impl.media.CallSessionImpl.controllerUpdate().2551
Received a StartEvent
14:29:55.937 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/UDP could not be resolved null
14:29:55.937 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/UDP
14:29:55.937 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processRequest().1181
received request=
NOTIFY sip:6003@192.168.1.135:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK39a432e3;received=192.168.1.101
From: "asterisk" <sip:asterisk@192.168.1.101>;tag=as5c2ca2cb
To: <sip:6003@192.168.1.135:5060;transport=udp>
Contact: <sip:asterisk@192.168.1.101>
Call-ID: 32c1f92f340b1e5c25ff9d61243a2a06@192.168.1.101
CSeq: 102 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: message-summary
Content-Type: application/simple-message-summary
Content-Length: 93

Messages-Waiting: no
Message-Account: sip:asterisk@192.168.1.101
Voice-Message: 0/0 (0/0)

14:29:59.468 FINA: impl.media.CallSessionImpl.update().2322 A new
participant had just joined:
14:29:59.468 FINA: impl.media.CallSessionImpl.update().2433 The previously
unidentified stream ULAW/rtp, 8000.0 Hz, 8-bit, Mono had now been identified
as sent by:
14:30:02.015 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.getIntendedDestination().2762
Returning address 192.168.1.101/192.168.1.101 for destination 192.168.1.101
14:30:02.015 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.getLocalViaHeaders().1445
generated via headers:Via: SIP/2.0/UDP 192.168.1.135:5060

14:30:02.015 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.getIntendedDestination().2762
Returning address 192.168.1.101/192.168.1.101 for destination 192.168.1.101
14:30:02.031 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.getContactHeader().1559
generated contactHeader:Contact: "6003"
<sip:6003@192.168.1.135:5060;transport=udp>

14:30:02.062 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/udp could not be resolved null
14:30:02.062 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/udp
14:30:02.062 FINA: impl.protocol.sip.SipRegistrarConnection.register().414
sent request= REGISTER sip:192.168.1.101 SIP/2.0
Call-ID: f213606d2b0e107a61a74b09b16045d3@0.0.0.0
CSeq: 5 REGISTER
From: <sip:6003@192.168.1.101>;tag=b19cb5ad
To: <sip:6003@192.168.1.101>
Via: SIP/2.0/UDP
192.168.1.135:5060;branch=z9hG4bK4e0f0a9efc49ea65ec810d87eb08f775
Max-Forwards: 70
User-Agent: SIP Communicator 1.0-alpha3-0.build.by.SVN Windows XP
Expires: 3600
Contact: "6003" <sip:6003@192.168.1.135:5060;transport=udp>;expires=3600
Content-Length: 0

14:30:02.078 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1018
received response=
SIP/2.0 100 Trying
Via: SIP/2.0/UDP
192.168.1.135:5060;branch=z9hG4bK4e0f0a9efc49ea65ec810d87eb08f775;received=192.168.1.135
From: <sip:6003@192.168.1.101>;tag=b19cb5ad
To: <sip:6003@192.168.1.101>
Call-ID: f213606d2b0e107a61a74b09b16045d3@0.0.0.0
CSeq: 5 REGISTER
User-Agent: Asterisk PBX
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,SUBSCRIBE,NOTIFY
Supported: replaces
Contact: <sip:6003@192.168.1.101>
Content-Length: 0

14:30:02.093 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1036
Found 1 processor(s) for method REGISTER
14:30:02.093 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1018
received response=
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP
192.168.1.135:5060;branch=z9hG4bK4e0f0a9efc49ea65ec810d87eb08f775;received=192.168.1.135
From: <sip:6003@192.168.1.101>;tag=b19cb5ad
To: <sip:6003@192.168.1.101>;tag=as4f359166
Call-ID: f213606d2b0e107a61a74b09b16045d3@0.0.0.0
CSeq: 5 REGISTER
User-Agent: Asterisk PBX
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,SUBSCRIBE,NOTIFY
Supported: replaces
WWW-Authenticate: Digest nonce="3f9f7283",realm="asterisk",algorithm=MD5
Content-Length: 0

14:30:02.093 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1036
Found 1 processor(s) for method REGISTER
14:30:02.093 FINA:
impl.protocol.sip.SipRegistrarConnection.processAuthenticationChallenge().1001
Authenticating a Register request.
14:30:02.109 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/udp could not be resolved null
14:30:02.109 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/udp
14:30:02.109 FINA:
impl.protocol.sip.security.MessageDigestAlgorithm.calculateResponse().61
trying to authenticate using : MD5, 6003, asterisk, true, 3f9f7283,
00000001, xyz, REGISTER, sip:192.168.1.101, , null
14:30:02.109 FINA:
impl.protocol.sip.security.SipSecurityManager.handleChallenge().274 Created
authorization header: Authorization: Digest
response="331617856c35b8a67febf5268b5c71d0",username="6003",nonce="3f9f7283",realm="asterisk",uri="sip:192.168.1.101",algorithm=MD5

14:30:02.125 FINA:
impl.protocol.sip.security.SipSecurityManager.handleChallenge().291
Returning authorization transaction.
14:30:02.125 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1018
received response=
SIP/2.0 100 Trying
Via: SIP/2.0/UDP
192.168.1.135:5060;branch=z9hG4bK2375a625cb6ab421d131af2a5aad7d2a;received=192.168.1.135
From: <sip:6003@192.168.1.101>;tag=b19cb5ad
To: <sip:6003@192.168.1.101>
Call-ID: f213606d2b0e107a61a74b09b16045d3@0.0.0.0
CSeq: 6 REGISTER
User-Agent: Asterisk PBX
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,SUBSCRIBE,NOTIFY
Supported: replaces
Contact: <sip:6003@192.168.1.101>
Content-Length: 0

14:30:02.125 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1036
Found 1 processor(s) for method REGISTER
14:30:02.125 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1018
received response=
SIP/2.0 200 OK
Via: SIP/2.0/UDP
192.168.1.135:5060;branch=z9hG4bK2375a625cb6ab421d131af2a5aad7d2a;received=192.168.1.135
From: <sip:6003@192.168.1.101>;tag=b19cb5ad
To: <sip:6003@192.168.1.101>;tag=as4f359166
Call-ID: f213606d2b0e107a61a74b09b16045d3@0.0.0.0
CSeq: 6 REGISTER
User-Agent: Asterisk PBX
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,SUBSCRIBE,NOTIFY
Supported: replaces
Expires: 3600
Contact: <sip:6003@192.168.1.135:5060;transport=udp>;expires=3600
Date: Fri, 03 Oct 2008 12:30:01 GMT
Content-Length: 0

14:30:02.140 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1036
Found 1 processor(s) for method REGISTER
14:30:02.796 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processTransactionTerminated().1129
Transaction terminated for req=INVITE
sip:6003@192.168.1.135:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK6b6a792c;received=192.168.1.101
From: "6000" <sip:6000@192.168.1.101>;tag=as131e994d
To: <sip:6003@192.168.1.135:5060;transport=udp>
Contact: <sip:6000@192.168.1.101>
Call-ID: 5da645183b230eb37be4b3f21234c8b6@192.168.1.101
CSeq: 102 INVITE
User-Agent: Asterisk PBX
Max-Forwards: 70
Date: Fri, 03 Oct 2008 12:29:44 GMT
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,SUBSCRIBE,NOTIFY
Supported: replaces
Content-Type: application/sdp
Content-Length: 184

v=0
o=root 8812 8812 IN IP4 192.168.1.101
s=session
c=IN IP4 192.168.1.101
t=0 0
m=audio 13566 RTP/AVP 0
a=rtpmap:0 PCMU/8000
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv

14:30:02.812 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processTransactionTerminated().1139
Found 1 processor(s) for method INVITE
14:30:03.000 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processTransactionTerminated().1129
Transaction terminated for req=INVITE
sip:6003@192.168.1.135:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP
192.168.1.101:5060;rport=5060;branch=z9hG4bK7ce039f7;received=192.168.1.101
From: "6000" <sip:6000@192.168.1.101>;tag=as131e994d
To: <sip:6003@192.168.1.135:5060;transport=udp>;tag=e32f9b83
Contact: <sip:6000@192.168.1.101>
Call-ID: 5da645183b230eb37be4b3f21234c8b6@192.168.1.101
CSeq: 103 INVITE
User-Agent: Asterisk PBX
Max-Forwards: 70
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,SUBSCRIBE,NOTIFY
Supported: replaces
Content-Type: application/sdp
Content-Length: 183

v=0
o=root 8812 8813 IN IP4 192.168.1.102
s=session
c=IN IP4 192.168.1.102
t=0 0
m=audio 5004 RTP/AVP 0
a=rtpmap:0 PCMU/8000
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv

14:30:03.000 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processTransactionTerminated().1139
Found 1 processor(s) for method INVITE
14:30:06.640 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.getIntendedDestination().2762
Returning address 192.168.1.101/192.168.1.101 for destination 192.168.1.101
14:30:06.640 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.getLocalViaHeaders().1445
generated via headers:Via: SIP/2.0/UDP 192.168.1.135:5060

14:30:06.656 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().69
Domain 192.168.1.101:5060/udp could not be resolved null
14:30:06.671 FINA: impl.protocol.sip.AddressResolverImpl.resolveAddress().89
Returning hop: 192.168.1.101:5060/udp
14:30:06.671 FINA:
impl.protocol.sip.OperationSetBasicTelephonySipImpl.sendRequest().457 Sent
request:
BYE sip:6000@192.168.1.101 SIP/2.0
CSeq: 1 BYE
From: <sip:6003@192.168.1.135:5060;transport=udp>;tag=e32f9b83
To: "6000" <sip:6000@192.168.1.101>;tag=as131e994d
Call-ID: 5da645183b230eb37be4b3f21234c8b6@192.168.1.101
User-Agent: SIP Communicator 1.0-alpha3-0.build.by.SVN Windows XP
Max-Forwards: 70
Via: SIP/2.0/UDP
192.168.1.135:5060;branch=z9hG4bK4c26826ce4e59f90822acfff08b8540a
Content-Length: 0

14:30:06.687 FINA:
impl.protocol.sip.ProtocolProviderServiceSipImpl.processResponse().1018
received response=
SIP/2.0 200 OK
Via: SIP/2.0/UDP
192.168.1.135:5060;branch=z9hG4bK4c26826ce4e59f90822acfff08b8540a;received=192.168.1.135
From: <sip:6003@192.168.1.135:5060;transport=udp>;tag=e32f9b83
To: "6000" <sip:6000@192.168.1.101>;tag=as131e994d
Call-ID: 5da645183b230eb37be4b3f21234c8b6@192.168.1.101
CSeq: 1 BYE
User-Agent: Asterisk PBX
Allow: INVITE,ACK,CANCEL,OPTION...

[Mensaje recortado]


#3

Javier,

Thank you for the feedback! I'm sorry for the delay of my response,
I've been busy myself. I don't expect to be able to look into it in
the next couple of days but I'll try to take on it asap afterwards.

Best regards,
Lubomir

···

On Tue, Oct 28, 2008 at 2:55 PM, Javier Mendiara Cañardo <javier.mendiara@gmail.com> wrote:

Hi Lubo!

Have you found how to solve this issue? Currently I've not too much
time, and I'll be glad to help you ASAP. Meanwhile, have you stimated
when it will be available? I think this point is a great candidate to
be solved before RC1 launch.

Best regards,
Javier

2008/10/21 Javier Mendiara Cañardo <javier.mendiara@gmail.com>:

Lubo,

I've updated to r4607, and seems that RTP audio in SIP calls from
sip-comm to others is not working.

Scenario (same hardware):
SIP-communicator-> sip:6003 , in 10.95.74.122
callee/caller phone -> sip:6000, in 10.95.74.222
Asterisk -> 10.95.74.124
Wireshark listenting in sip-comm PC, (10.95.74.122)

Attached are logs for both incoming and outgoing calls from the
sip-comm point of view.
As you will see there is not outgoing RTP Audio streams long enought
(more than 10 seconds, that is, the real conversation) sended by
sip-comm.

What are we missing?
Best regards

PS: I've downgraded to r4590 and I'm able to create incoming/outgoing
calls, using Asterisk for sending sip-comm RTP audio, having normal
calls.

2008/10/20 Lubomir Marinov <lubomir.marinov@gmail.com>:

Javier,

Please try trunk in r4592 or a later revision for re-INVITE support
which takes into account changes in the connection address.

Regards,
Lubo

On Fri, Oct 3, 2008 at 4:28 PM, Javier Mendiara Cañardo >>> <javier.mendiara@gmail.com> wrote:

Hi all,

I think I've found an issue with RTP retransmission.
If two SDP descriptions are sent in INVITES in the same SIP Dialog, SipComm
sends RTP to the destination specified in the first INVITE, and I think it
should be sent to the specified in the last INVITE

Attached there are 3 files, the sipcomm log (FINE graining), Wireshark Dump
and a JPG showing where SIP server sends the second INVITE with the correct
SDP and where is sending SIPComm the RTP audio.

As you can see, the other softphone is sending RTP directly to SipComm, but
SipComm is sending audio via SIP Server.

The scenario is as follows:
A SipCommunicator (SVN 4548) located at 192.168.1.135, with SIP ID 6003,
running under WinXP Pro SP3, java1.6, from Netbeans ANT run
One SIP Gateway (GrandStream HT503) located at 192.168.1.102, with SIP ID
6000
Asterisk SIP server (v1.4.18.1) located at 192.168.1.101, forcing G711U
(ULAW) codec
WireShark (tcpdump) listening in 192.168.1.135 (where sipcomm is located)

In the wireshark dump there is also debug info from the SIP Gateway
(sip:6000) sent using SysLog events (WireShark filter: syslog)
In "Log Sipcommunicator.log" file, line 914, you can see the second INVITE

This behaviour is a pity, because if the SIPComm and GW are in the same LAN
and the asterisk is outside, the Sipcommunicator voice will travel too long
to reach the destination, while GW to SIP voice will travel using the LAN.

One thing more, I've forced in Asterisk to use G711U, so I think that
Asterisk media server conversion between audio formats is not causing this.
Also, both SDP are identical.

What do you think? Is it possible to solve?

PS: I've tried with other softphones, and it seems that this issue is
correctly managed.

--
Javier Mendiara Cañardo

Skype: jmendiara.tid
MsnIM: javier.mendiara@gmail.com

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