event delivered to entity after detach

[Grzegorz Jamka]

Hi,

I found one issue which looks similarly to one I found some time ago.
This time this is differenet RA (SIP RA) and service.

Service scenario (Hunting Group):
1) Receive INVITE
2) Send INVITE to two parties: alice and bob, attaching to both dialog
activities
3) Call is answered by alice
4) Release and detach from other called parties: here with bob, detach
from dialog activity, send CANCEL
5) In this scenario despite of detach from dialog activity (in which
INVITE has been send) SBB entity still receive responses from that
dialog: here 487 response

Issue:
SBB Entity after detach from Acitivity Context still received
subsequent events from this activity.

Belowe I paste logs pointing on detach operation and delivery 487
resposne to same SBB entity.

Standard ouput with logs on TRACE level (org.mobicents) and snoop from
this scenario I can send on email (I see there is no more posibility
to attache files on this group).

Regards
Grzegorz Jamka


14:44:34,074 TRACE [ActivityContextInterfaceImpl] detach( ac =
ActivityContext{ handle =
RA:SipRA:e716a5b128d9d850...@192.168.1.102:8d966032:null } ,
sbbLocalObject = SbbLocalObjectImpl[-3398161751081120432_____null] )
14:44:34,074 TRACE [ActivityContext] Detached sbb entity
-3398161751081120432_____null from AC with handle
RA:SipRA:e716a5b128d9d850...@192.168.1.102:8d966032:null
14:44:34,074 DEBUG [SbbEntityImpl] Sbb entity
-3398161751081120432_____null detached from AC with handle
RA:SipRA:e716a5b128d9d850...@192.168.1.102:8d966032:null
...
14:44:34,087 DEBUG [EventRoutingTaskImpl] Highest priority SBB entity,
which is attached to the ac
RA:SipRA:e716a5b128d9d850...@192.168.1.102:8d966032:null ,
to deliver the event: -3398161751081120432_____null
14:44:34,087 DEBUG [EventRoutingTaskImpl] ---> Invoking event handler:
ac=RA:SipRA:e716a5b128d9d850...@192.168.1.102:8d966032:null ,
sbbEntity=-3398161751081120432_____null ,
sbbObject=org.mobicents.slee.runtime.sbb.SbbObjectImpl@fb5989
...
14:44:34,087 DEBUG [EventRoutingTaskImpl] ---> Invoking event handler:
ac=RA:SipRA:e716a5b128d9d850...@192.168.1.102:8d966032:null ,
sbbEntity=-3398161751081120432_____null ,
sbbObject=org.mobicents.slee.runtime.sbb.SbbObjectImpl@fb5989
14:44:34,087 DEBUG [HuntingGroupSbbImpl] ClientError event received:
SIP/2.0 487 Request Terminated^M
Via: SIP/2.0/UDP 192.168.1.102:5060;branch=z9hG4bK-363337-
b963f57912344f5c952beb733d865684;received=192.168.1.102^M
From: "Grzegorz" <sip:gja...@localhost.pl>;tag=8d966032^M
To: "bob" <sip:b...@localhost.com>;tag=1865238378^M
Call-ID: e716a5b128d9d850...@192.168.1.102^M
CSeq: 137 INVITE^M
Server: SJphone/1.65.377a (SJ Labs)^M
Content-Length: 0^M
^M

14:44:34,087 DEBUG [HuntingGroupSbbImpl] +++++++ PRINT ACI +++++++
14:44:34,087 TRACE [SbbContextImpl] getActivities() READY
14:44:34,088 DEBUG [ActivityContext] Skipping update of access time
for AC with handle
RA:SipRA:c1dbc27fec12f5d9...@192.168.1.102:21282157:null
14:44:34,088 DEBUG [ActivityContext] Skipping update of access time
for AC with handle
RA:SipRA:uhtodbf...@gjamka.infovide:afe65bd4:khlyo
14:44:34,088 DEBUG [ActivityContext] Skipping update of access time
for AC with handle RA:SipRA:z9hG4bKefycpslo:INVITE
14:44:34,088 DEBUG [HuntingGroupSbbImpl] Dialog:
ClientDialogWrapper[ handle =
c1dbc27fec12f5d9...@192.168.1.102:21282157:null, state =
Confirmed Dialog, clientTXs = [z9hG4bK-363337-
f9a5d7f772f3efab9012022e3cd79dde:INVITE], serverTXs = [] ]
14:44:34,088 DEBUG [HuntingGroupSbbImpl] Dialog: DialogWrapper[ handle
= uhtodbf...@gjamka.infovide:afe65bd4:khlyo, state = Confirmed
Dialog, clientTXs = [], serverTXs = [z9hG4bKefycpslo:INVITE] ]
14:44:34,088 DEBUG [HuntingGroupSbbImpl] ServerTransaction:
ServerTransaction[z9hG4bKefycpslo]
14:44:34,088 DEBUG [HuntingGroupSbbImpl] +++++++ END PRINT ACI +++++++

[Grzegorz Jamka]

I forgot to write about software versions:
- mobicents - 2.2.1.FINAL
- sip11ra - 2.2.0.FINAL

Regards
Grzegorz Jamka

[Eduardo Martins]

That's weird, I think 2.2.1 was patched for such issue. Anyway 2.3 is
on final tests, should be out tomorrow, and is very different in that
"logic", much simplified (and faster), my recommendation is to wait
for it and then see if the problem persists...

-- Eduardo
..............................................
http://emmartins.blogspot.com
http://redhat.com/solutions/telco

[Grzegorz Jamka]

Hi,

The problem still appears.
Scenario is the same as I described in first post: event is delivred
from detached Client Transaction to existing SBB Entity (this is 200
OK Response wich is confugred not as initial-event)
I can send logs (org.mobicents with TRACE level) as well as snoop,
service (binary and source) and descirbe how to reproduce the scenario
if it will be necessary.

Mobicents and SIP11RA versions: 2.3.0-FINAL from last release, but as
I understand it is not RA problem.

Regards
Grzegorz Jamka

[Eduardo Martins]

Pls send it me.

-- Eduardo
..............................................
http://emmartins.blogspot.com
http://redhat.com/solutions/telco

[Eduardo Martins]

Issue confirmed: http://code.google.com/p/mobicents/issues/detail?id=2313

Will let you know once tests for the solution are completed and a
binary snapshot is built, so you can try it out.

-- Eduardo
..............................................
http://emmartins.blogspot.com
http://redhat.com/solutions/telco

[Eduardo Martins]

Here is a snapshot including the fix:

http://hudson.jboss.org/hudson/view/Mobicents/job/Mobicents-Slee-2.x-Release/747/artifact/mobicents-jainslee-2.4.0-SNAPSHOT-jboss-5.1.0.GA.zip

Grzegorz, can you please check if it fixes your issues?

-- Eduardo
..............................................
http://emmartins.blogspot.com
http://redhat.com/solutions/telco

[Grzegorz Jamka]

It's seems to be OK.
I repeated test scenario about 20 times and I don't see this issue any
more (previously the issue coulde be notice after few tests).
I think it is fixed.

Grzegorz Jamka

2011/1/28 Eduardo Martins <emma...@gmail.com>: