Sip server and Agent Desktop (ActiveX starter Application) error 51 Unsupport

gitosus · May 16, 2012, 8:18am

Dear genesys guru,

I have the following environments;
-. genesys framework 6.5
-. genesys desktop 6.5
-. desktop starter v 6.5 (visual basic)
-. sip server 7.2
-. genesys urs 6.5
-. Asterisk pbx version 1.8.3
-. X-lite version 3

I have tested using pbx simulator and using agent desktop (develop based on ActiveX starter application) works fine. And now I have sip server version 7.2. I have through and follow the documentation of sip server integrated with Asterisk pbx.

The problem I have is; when the call come in (enter to the system) I only could answer with x-lite to answer the call. But if I answer via desktop application then there is error 51
mentioned Unsupported operation.

What command should be put for “Answer” at desktop application to avoid error 51, I have test hang up works fine, I have test the transfer call also works fine.

Please to assist me on this problem?

Thanks and Regards,

Gito sus

Rene · May 16, 2012, 10:58am

Hi,

Please check that ‘sip-cti-control’ option with value ‘talk’ is configured on Annex tab of agent’s DN. However, I’m not sure whether X-Lite 3 supports BroadSoft SIP Extension Event Package that is mandatory for this feature to work.

R.

gitosus · May 17, 2012, 12:01am

Hi Rene,

Thank you for the response, with you advice on my lab system, I have added DN annex of section Tserver as the following;

-.dual-dialog-enabled value false
-.make-call-rfc3725-flow value 1
-.refer-enabled value false
-.reuse-sdp-on-reinvite value true
-. service-type value softswitch
-.sip-hold-rfc3264 value flase
-.sip-initial-hold-rfc3264 value true
-. subscriber-presence value asterisk
-. contact value sip:asterisk:5060
-. sip-cti-control value talk

Now When the call arrive, I could see the desktop application without error 51 (the error 51 is appear when I press answer buttom). Now I have new problem, the desktop application seem to answer the call but, it lost relation with Asterisk (X-lite). The call keeps ring and ring, and after some time the desktop sending error 237 (an error occurred on DN 2002) Error 237:Call has disconnected. It seem desktop it answer the call but the call not been answerd by sip end point

Is my annex T-server has been configure properly as I have mentoned above, I just follow what ever in documentation, probably there are some parameter not define correctly as I am new on sip server. Would you assist me on what possible wrong inmy setup please?

Thanks and Regards,

Gito sus

cavagnaro · May 17, 2012, 2:36am

Can you post the RINGING snippet? There you should see if it supports TALK, HOLD as 3pcc. If not, you will not be able to control the answer. I also don’t think XLite supports it, Bria does.

gitosus · May 17, 2012, 4:42am

Hi Cavagnaro,

Here is I made test call to even ringing and cancel the call from the caller point of view;
Hope the information enough to trace the call flow.

Rgds, gito sus

+++ Ringing +++
SetDialling: party 0280701698.01F45430-00830200e7ae5004.01997A48, cause Null
sipcs: HA:PARTY[0280701698@01f45430:1]:SetDialing
– guessed previous party: 7703.01F479B0-00830200e7ae5004.01997A48
– 3rd pi: <d7703/7703-00830200e7ae5004>.01F479B0/l:2/r:0/Released,Destination
SetDelivered: party 2002.01F50730-00830200e7ae5004.01997A48, cause Distributed
– TellDelivered
– party_info 2002.01F50F80 state change: from to
– G7 ringing
@13:27:44.3540 [ISCC] Debug: Party added [ssp view]:
@ c:00830200e7ae5004,01f45a80 @ m:0000000000000000,00000000,0000000000000000 p:3 i:00000004 nw:00000000:0000000000000000 t:2
p:01f455f0 @ c:00830200e7ae5004,01f45a80 r:1 t:1 s:1000c n:0280701698
p:01f45db0 @ c:00830200e7ae5004,01f45a80 r:2 t:0 s:0 n:7703
+ p:01f50f80 @ c:00830200e7ae5004,01f45a80 r:2 t:0 s:a n:2002
@13:27:44.3540 [ISCC] Debug: Party added:
@ c:00830200e7ae5004,01f45a80 @ m:0000000000000000,00000000 p:2 i:00000004 nw:0000000000000000 t:2
p:0000000000000000,01f455f0 @ c:00830200e7ae5004,01f45a80 r:1 ------ n:0280701698:
+ p:0000000000000000,01f50f80 @ c:00830200e7ae5004,01f45a80 r:2 ------ n:2002:
@13:27:44.3540 [0] 7.2.100.41 distribute_event: message EventRinging
AttributeEventSequenceNumber 0000000000000074
AttributeTimeinuSecs 354000
AttributeTimeinSecs 1326767264 (13:27:44)
AttributeANI ‘0280701698’
AttributeDNIS ‘70000’
AttributeCallUUID ‘D7FA597E-96CE-4C97-95CB-F2B13248965A’
AttributeConnID 00830200e7ae5004
AttributeCallID 4
AttributeCallType 2
AttributeCallState 0
AttributeOtherQueue ‘70000’
AttributeThisQueue ‘7703’
AttributeThisDNRole 2
AttributeAgentID ‘1803’
AttributeThisDN ‘2002’
AttributeOtherDNRole 1
AttributeOtherDN ‘0280701698’
@13:27:44.3540 [ISCC] Debug: Translate: ‘0280701698’ → ‘’; result 1 ()
13:27:44.354 Int 04544 Interaction message “EventRinging” generated
13:27:44.354 Trc 04542 EventRinging sent to 544 (000a DesktopToolkitX)
13:27:44.354 Trc 04542 EventRinging sent to 548 (0009 DesktopToolkitX)
13:27:44.354 Trc 04542 EventRinging sent to 504 (0004 Route_StatServer)
13:27:44.354 Trc 04542 EventRinging sent to 516 (0003 Melb_CCA_StatServer)
13:27:44.354 Trc 04542 EventRinging sent to 508 (0002 Sip_StatServer)
SetDead: 7703.01F479B0-00830200e7ae5004.01997A48
sipcs: -party [7703@01f479b0:0]
– deleted aTmParty 01F479B0
– RemoveParty 7703.01F479B0-00830200e7ae5004.01997A48
– deleted party_info_tspp 01F45DB0
— Ringing —
Call kill timer cancelled for 00830200e7ae5004

cavagnaro · May 17, 2012, 9:59pm

We need the SIP logs, the TSERVER ones.

gitosus · May 18, 2012, 2:02am

Hi Cavanaro,

Here is sorry for wrong information; I am new bee so hopefully this the correct one bellow; Other possible that I could look to work arround is to setup the X-lite with Auto Answer, so the call will be answered automatically. But again another problem the configuration of auto in (for the desktop/ActiveX application) does not work, as I have manually to change from ACW to Auto in again. Probably you are correct that X-lite does not support for this Sip/Asterisk configuration. If I have to use Bira what version do you think is compatible with sip server 7.2?

Thanks again for discussion and helping the problem I have.

Rgds, gito sus

2012-01-18 11:46:26> Action: Extension ‘2002@SIPSERVER’(AfterCallWork):
[2012-01-18 11:44:20]Monitored
[2012-01-18 11:45:26]LoggedIn
[2012-01-18 11:46:26]AfterCallWork (ConnID 36875425189040129)
[2012-01-18 11:46:26]OnHook
2012-01-18 11:46:26> Status: Agent ‘ig’([2012-01-18 11:46:26]AfterCallWork)
2012-01-18 11:46:26> Client: Sending message to CCPulse
2012-01-18 11:46:26> Client: Message ‘Info’
‘LONG_VALUE’ 0
‘TM_LENGTH’ 0
‘TM_SERVER’ 1326847586
‘USER_REQ_ID’ 9
‘REQ_ID’ 12
2012-01-18 11:46:28> Server: Switch ‘SIPSERVER’->‘EventAgentReady’
ThisDN ‘2002’
Agent LoginID ‘1803’
This Queue ‘7703’
2012-01-18 11:46:28> Action: ACDQueue ‘7703@SIPSERVER’ DNs Ready: 1
2012-01-18 11:46:28> Action: ACDQueue ‘7703@SIPSERVER’ Agents Ready: 1
2012-01-18 11:46:28> Action: Extension ‘2002@SIPSERVER’(WaitForNextCall):
[2012-01-18 11:44:20]Monitored
[2012-01-18 11:45:26]LoggedIn
[2012-01-18 11:46:26]OnHook
[2012-01-18 11:46:28]WaitForNextCall
2012-01-18 11:46:30> Status: AgentsGroup ‘Optus_Surepage_Group’([2012-01-18 11:46:28]WaitForNextCall)
2012-01-18 11:46:30> Client: Sending message to CCPulse
2012-01-18 11:46:30> Client: Message ‘Info’
‘LONG_VALUE’ 2
‘TM_LENGTH’ 0
‘TM_SERVER’ 1326847590
‘USER_REQ_ID’ 3
‘REQ_ID’ 6
2012-01-18 11:46:30> Status: Agent ‘ig’([2012-01-18 11:46:28]WaitForNextCall)
2012-01-18 11:46:30> Client: Sending message to CCPulse
2012-01-18 11:46:30> Client: Message ‘Info’
‘LONG_VALUE’ 0
‘TM_LENGTH’ 0
‘TM_SERVER’ 1326847590
‘USER_REQ_ID’ 9
‘REQ_ID’ 12
2012-01-18 11:46:32> Server: Switch ‘SIPSERVER’->‘EventQueued’
ThisDN ‘70000’
ConnID 00830200fb559002 (36875425189040130)
Call Type ‘Inbound’
This Queue ‘70000’
DNIS ‘70000’
ANI ‘0280701698’
2012-01-18 11:46:32> Server: Switch ‘SIPSERVER’->‘EventRouteRequest’
ThisDN ‘70000’
ConnID 00830200fb559002 (36875425189040130)
Call Type ‘Inbound’
This Queue ‘70000’
DNIS ‘70000’
ANI ‘0280701698’
2012-01-18 11:46:32> Action: RoutingPoint ‘70000@SIPSERVER’(Monitored):
[2012-01-18 11:44:20]Monitored
[2012-01-18 11:46:32]CallWait (ConnID 36875425189040130)
2012-01-18 11:46:32> Server: Switch ‘SIPSERVER’->‘EventRouteUsed’
ThisDN ‘70000’
ConnID 00830200fb559002 (36875425189040130)
Call Type ‘Inbound’
This Queue ‘70000’
DNIS ‘70000’
ANI ‘0280701698’
ThirdPartyDN ‘7703’
2012-01-18 11:46:32> Action: RoutingPoint ‘70000@SIPSERVER’(Monitored):
[2012-01-18 11:44:20]Monitored
2012-01-18 11:46:32> Server: Switch ‘SIPSERVER’->‘EventDiverted’
ThisDN ‘70000’
ConnID 00830200fb559002 (36875425189040130)
Call Type ‘Inbound’
This Queue ‘70000’
DNIS ‘70000’
ANI ‘0280701698’
ThirdPartyDN ‘7703’
2012-01-18 11:46:32> Server: Switch ‘SIPSERVER’->‘EventQueued’
ThisDN ‘7703’
ConnID 00830200fb559002 (36875425189040130)
Call Type ‘Inbound’
This Queue ‘7703’
DNIS ‘70000’
ANI ‘0280701698’
2012-01-18 11:46:32> Action: ACDQueue ‘7703@SIPSERVER’(Monitored):
[2012-01-18 11:44:20]Monitored
[2012-01-18 11:46:32]CallWait (ConnID 36875425189040130)
2012-01-18 11:46:32> Server: Switch ‘SIPSERVER’->‘EventDiverted’
ThisDN ‘7703’
ConnID 00830200fb559002 (36875425189040130)
Call Type ‘Inbound’
This Queue ‘7703’
DNIS ‘70000’
ANI ‘0280701698’
ThirdPartyDN ‘2002’
2012-01-18 11:46:32> Action: ACDQueue ‘7703@SIPSERVER’(Monitored):
[2012-01-18 11:44:20]Monitored
2012-01-18 11:46:32> Server: Switch ‘SIPSERVER’->‘EventRinging’
ThisDN ‘2002’
ConnID 00830200fb559002 (36875425189040130)
Call Type ‘Inbound’
Agent LoginID ‘1803’
This Queue ‘7703’
DNIS ‘70000’
ANI ‘0280701698’
2012-01-18 11:46:32> Action: ACDQueue ‘7703@SIPSERVER’ DNs Ready: 0
2012-01-18 11:46:32> Action: ACDQueue ‘7703@SIPSERVER’ Agents Ready: 0
2012-01-18 11:46:32> Action: Extension ‘2002@SIPSERVER’(CallRinging):
[2012-01-18 11:44:20]Monitored
[2012-01-18 11:45:26]LoggedIn
[2012-01-18 11:46:26]OnHook
[2012-01-18 11:46:28]WaitForNextCall
[2012-01-18 11:46:32]CallRinging (ConnID 36875425189040130)
2012-01-18 11:46:32> Status: AgentsGroup ‘Optus_Surepage_Group’([2012-01-18 11:46:32]NotReadyForNextCall)
2012-01-18 11:46:32> Client: Sending message to CCPulse
2012-01-18 11:46:32> Client: Message ‘Info’
‘LONG_VALUE’ 2
‘TM_LENGTH’ 0
‘TM_SERVER’ 1326847592
‘USER_REQ_ID’ 3
‘REQ_ID’ 6
2012-01-18 11:46:32> Status: Agent ‘ig’([2012-01-18 11:46:32]CallRinging)
2012-01-18 11:46:32> Client: Sending message to CCPulse
2012-01-18 11:46:32> Client: Message ‘Info’
‘LONG_VALUE’ 0
‘TM_LENGTH’ 0
‘TM_SERVER’ 1326847592
‘USER_REQ_ID’ 9
‘REQ_ID’ 12
-AP[1]-<-500 @11:46:48.7360
-Ap[1]->-500
2012-01-18 11:46:52> Server: Switch ‘SIPSERVER’->‘EventAbandoned’
ThisDN ‘2002’
ConnID 00830200fb559002 (36875425189040130)
Call Type ‘Inbound’
Agent LoginID ‘1803’
This Queue ‘7703’
DNIS ‘70000’
ANI ‘0280701698’
======================== End ========= Doc

cavagnaro · May 18, 2012, 4:11am

Weird, can’t see SIP messages at all.
Please be sure that Log section has verbose=all and if there is a x-log-sip option open that file and post the content of it.

gitosus · May 18, 2012, 4:41am

Ah .. probably this below that with sip information;

sipcs: 14:34:11.657 Received [488,UDP] 563 bytes from 192.168.56.10:5060 <<<<<
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 192.168.56.8:5060;branch=z9hG4bK8ADD9B31-527C-484D-B057-3236F0056368-10;received=192.168.56.8
From: “Gito Sarwono 2971” sip:0280701698@192.168.56.10;tag=as6260cfd8
To: sip:2002@asterisk;tag=as7d793145
Call-ID: 5888763B-7453-4716-B580-643C2EF4FA39-5@192.168.56.8
CSeq: 1 INVITE
Server: Asterisk PBX 1.8.3.2
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Session-Expires: 1800;refresher=uac
Contact: sip:2002@192.168.56.10:5060
Content-Length: 0

sipcs: 14:34:11.657 dispatchDE: dlg[11]
sipcs: dialog [11:02@01a12fd0]: << Event 01 << TRN[19]
sipcs: qdDem: << Event 1 << DLG[11]
sipcs: prp response 0
14:34:11.657 +++ CIFace::Event +++
+++ Pre-event +++
Type EventDiverted
Devices: <7703/7703> <2002/2002> <-/->
Calls: 5/00830200fb559005/5.01993D40/c:2/r:0 0/none
Parties: <d7703/7703-00830200fb559005>.01F40DA0/l:2/r:0/Queued,Destination
none
none
Flags: divert=0 hook=1 postCall=0 active=1 moveAll=1 callType=1
— Pre-event —
+++ Diverted +++
– created party_info_tspp 01F4CF00
– created aTmParty 01F48CF0
sipcs: +party [2002@01f48cf0:0], call [00830200fb559005@01993d40]
SetRole: Destination for 2002.01F48CF0-00830200fb559005.01993D40
– AddParty to 01993D40: 2002.01F48CF0-00830200fb559005.01993D40 after 7703.01F40DA0-00830200fb559005.01993D40
CreateParty new internal: 2002.01F48CF0-00830200fb559005.01993D40
DoDivert: from 7703.01F40DA0-00830200fb559005.01993D40 to 2002.01F48CF0-00830200fb559005.01993D40 cause Distributed
@14:34:11.6570 [0] 7.2.100.41 distribute_event: message EventDiverted
AttributeEventSequenceNumber 0000000000000078
AttributeTimeinuSecs 657000
AttributeTimeinSecs 1326857651 (14:34:11)
AttributeANI ‘0280701698’
AttributeDNIS ‘70000’
AttributeCallUUID ‘6845521E-F4E1-49A3-96DF-09092C464E79’
AttributeConnID 00830200fb559005
AttributeCallID 5
AttributeCallType 2
AttributeCallState 0
AttributeOtherDNRole 1
AttributeOtherDN ‘0280701698’
AttributeThirdPartyDNRole 2
AttributeThirdPartyDN ‘2002’
AttributeOtherQueue ‘70000’
AttributeThisQueue ‘7703’
AttributeThisDNRole 2
AttributeThisDN ‘7703’
@14:34:11.6570 [ISCC] Debug: Translate: ‘0280701698’ → ‘’; result 1 ()
14:34:11.657 Int 04544 Interaction message “EventDiverted” generated
14:34:11.657 Trc 04542 EventDiverted sent to 520 (0004 Melb_CCA_StatServer)
14:34:11.657 Trc 04542 EventDiverted sent to 528 (0005 Sip_StatServer)
14:34:11.657 Trc 04542 EventDiverted sent to 504 (0002 Route_StatServer)
InheritQueues: from 7703 to 2002
– party_info 7703.01F40670 state change: from to
– G7 dequeued
@14:34:11.6570 [ISCC] Debug: Party removed [ssp view]:
@ c:00830200fb559005,01f40340 @ m:0000000000000000,00000000,0000000000000000 p:3 i:00000005 nw:00000000:0000000000000000 t:2
p:01f41680 @ c:00830200fb559005,01f40340 r:1 t:1 s:1000c n:0280701698
- p:01f40670 @ c:00830200fb559005,01f40340 r:2 t:0 s:0 n:7703
p:01f4cf00 @ c:00830200fb559005,01f40340 r:2 t:0 s:0 n:2002
@14:34:11.6570 [ISCC] Debug: Party removed:
@ c:00830200fb559005,01f40340 @ m:0000000000000000,00000000 p:2 i:00000005 nw:0000000000000000 t:2
p:0000000000000000,01f41680 @ c:00830200fb559005,01f40340 r:1 ------ n:0280701698:
- p:0000000000000000,01f40670 @ c:00830200fb559005,01f40340 r:2 ------ n:7703:
@14:34:11.6570 [ISCC] Debug: Party object is removed: p:0000000000000000,00000000 n:7703:
Call 00830200fb559005 check scheduled at 14:34:26.657
— Diverted —
+++ Post-event +++
Type EventDiverted
Devices: <7703/7703> <2002/2002> <-/->
Calls: 5/00830200fb559005/5.01993D40/c:1/r:0 0/none
Parties: <d7703/7703-00830200fb559005>.01F40DA0/l:2/r:0/Released,Destination
<d2002/2002-00830200fb559005>.01F48CF0/l:2/r:0/Null,DNull,Destination
none
Cause: Distributed/38, Info: 0
Flags: divert=0 hook=1 postCall=0 active=1 moveAll=1 callType=1
— Post-event —
14:34:11.657 — CIFace::Event —
14:34:11.657 +++ CIFace::Event +++
+++ Pre-event +++
Type EventRinging
Devices: <2002/2002> <-/0280701698> <-/->
Calls: 5/00830200fb559005/5.01993D40/c:1/r:0 0/none
Parties: <d2002/2002-00830200fb559005>.01F48CF0/l:2/r:0/Null,DNull,Destination
<x0280701698/0280701698-00830200fb559005>.01F414C0/l:1/r:0/Dialing,Origination
none
Flags: divert=0 hook=1 postCall=0 active=1 moveAll=1 callType=1
— Pre-event —
+++ Ringing +++
SetDialling: party 0280701698.01F414C0-00830200fb559005.01993D40, cause Null
sipcs: HA:PARTY[0280701698@01f414c0:1]:SetDialing
– guessed previous party: 7703.01F40DA0-00830200fb559005.01993D40
– 3rd pi: <d7703/7703-00830200fb559005>.01F40DA0/l:2/r:0/Released,Destination
SetDelivered: party 2002.01F48CF0-00830200fb559005.01993D40, cause Distributed
– TellDelivered
– party_info 2002.01F4CF00 state change: from to
– G7 ringing
@14:34:11.6570 [ISCC] Debug: Party added [ssp view]:
@ c:00830200fb559005,01f40340 @ m:0000000000000000,00000000,0000000000000000 p:3 i:00000005 nw:00000000:0000000000000000 t:2
p:01f41680 @ c:00830200fb559005,01f40340 r:1 t:1 s:1000c n:0280701698
p:01f40670 @ c:00830200fb559005,01f40340 r:2 t:0 s:0 n:7703
+ p:01f4cf00 @ c:00830200fb559005,01f40340 r:2 t:0 s:a n:2002
@14:34:11.6570 [ISCC] Debug: Party added:
@ c:00830200fb559005,01f40340 @ m:0000000000000000,00000000 p:2 i:00000005 nw:0000000000000000 t:2
p:0000000000000000,01f41680 @ c:00830200fb559005,01f40340 r:1 ------ n:0280701698:
+ p:0000000000000000,01f4cf00 @ c:00830200fb559005,01f40340 r:2 ------ n:2002:
@14:34:11.6570 [0] 7.2.100.41 distribute_event: message EventRinging
AttributeEventSequenceNumber 0000000000000079
AttributeTimeinuSecs 657000
AttributeTimeinSecs 1326857651 (14:34:11)
AttributeANI ‘0280701698’
AttributeDNIS ‘70000’
AttributeCallUUID ‘6845521E-F4E1-49A3-96DF-09092C464E79’
AttributeConnID 00830200fb559005
AttributeCallID 5
AttributeCallType 2
AttributeCallState 0
AttributeOtherQueue ‘70000’
AttributeThisQueue ‘7703’
AttributeThisDNRole 2
AttributeAgentID ‘1803’
AttributeThisDN ‘2002’
AttributeOtherDNRole 1
AttributeOtherDN ‘0280701698’
@14:34:11.6570 [ISCC] Debug: Translate: ‘0280701698’ → ‘’; result 1 ()
14:34:11.657 Int 04544 Interaction message “EventRinging” generated
14:34:11.657 Trc 04542 EventRinging sent to 544 (0007 DesktopToolkitX)
14:34:11.657 Trc 04542 EventRinging sent to 536 (0006 DesktopToolkitX)
14:34:11.657 Trc 04542 EventRinging sent to 520 (0004 Melb_CCA_StatServer)
14:34:11.657 Trc 04542 EventRinging sent to 528 (0005 Sip_StatServer)
14:34:11.657 Trc 04542 EventRinging sent to 504 (0002 Route_StatServer)
SetDead: 7703.01F40DA0-00830200fb559005.01993D40
sipcs: -party [7703@01f40da0:0]
– deleted aTmParty 01F40DA0
– RemoveParty 7703.01F40DA0-00830200fb559005.01993D40
– deleted party_info_tspp 01F40670
— Ringing —
Call kill timer cancelled for 00830200fb559005
+++ Post-event +++
Type EventRinging
Devices: <2002/2002> <-/0280701698> <7703/7703>
Calls: 5/00830200fb559005/5.01993D40/c:2/r:0 0/none
Parties: <d2002/2002-00830200fb559005>.01F48CF0/l:2/r:0/Alerting,DNull,Destination
<x0280701698/0280701698-00830200fb559005>.01F414C0/l:1/r:0/Dialing,Origination
none
Cause: Distributed/38, Info: 0
Call type: Inbound/2
Flags: divert=0 hook=1 postCall=0 active=1 moveAll=1 callType=1
— Post-event —
14:34:11.657 — CIFace::Event —
sipcs: party [2002@01f48cf0:0] +dlg [11@1a12fd0]
sipcs: dialog [11:02@01a12fd0] : -party [@00000000] +party [2002@01f48cf0]
sipcs: Reg. Info Party: dn[2002]<=cme[2002]

sipcs: 14:34:11.657 Sending [488,UDP] 617 bytes to 192.168.56.10:5060 >>>>>
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 192.168.56.10:5060;branch=z9hG4bK63cd3974;received=192.168.56.10
From: “Gito Sarwono 2971” sip:0280701698@192.168.56.10;tag=as6260cfd8
To: sip:70000@192.168.56.8:5060;tag=19A0E3F5-C946-4702-AC76-390303E3EE29-5
Call-ID: 7d1c0524474ceca62bd1f30f2b8411e4@192.168.56.10:5060
CSeq: 102 INVITE
Server: Asterisk PBX 1.8.3.2
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Call-Info: http://genesyslab.com; 5888763B-7453-4716-B580-643C2EF4FA39-5%40192.168.56.8;gen-rt=as7d793145;gen-lt=as6260cfd8
Supported: replaces
Content-Length: 0

sipcs: 14:34:11.657 dispatchDE: dlg[10]
sipcs: UK-PEM:HANDLE(NON-CONNECTED):<<EVENT(13)<<DLG[10]
sipcs: dialog [10:01@01a04fb8]: << Event 13 << TRN[18]
14:34:25.957 Trc 04541 RequestAnswerCall received from 544 (0007 DesktopToolkitX)
message RequestAnswerCall
AttributeThisDN ‘2002’
AttributeConnID 00830200fb559005
AttributeReferenceID 14
14:34:25.957 Int 04543 Interaction message “RequestAnswerCall” received from 544 (“DesktopToolkitX”)
14:34:25.957 – created: CRequest@01F48970 RequestAnswerCall-DesktopToolkitX[544]/14
14:34:25.957 +++ CIFace::Request +++
– new invoke
– thisCall by party
Parsed: RequestAnswerCall
From: DesktopToolkitX[544]/14
Numbers: +<2002> -
Calls: 00830200fb559005.01993D40 none
Parties: 2002.01F48CF0-00830200fb559005.01993D40
none
Status: parsed:1 queued:0 sent:0 acked:0 preevent:0 event:0 context:0 transferred:0

– validate
– state check: ok
SIPTS_IFace::QServe()01F48970
CIFace: Sent CRequest@01F48970 RequestAnswerCall-DesktopToolkitX[544]/14
sipcs: Reg. Info: dn[2002]<=cme[2002]
sipcs: Reg. Info: dn[2002]<=cme[2002]
sipcs: Reg. Info: dn[2002]<=cme[2002]
sipcs: Reg. Info: dn[2002]<=cme[2002]
sipcs: Reg. Info: dn[2002]<=cme[2002]
sipcs: Reg. Info: dn[2002]<=cme[2002]

sipcs: 14:34:25.957 Sending [488,UDP] 388 bytes to 192.168.56.10:5060 >>>>>
NOTIFY sip:2002@asterisk:5060 SIP/2.0
From: “Gito Sarwono 2971” sip:0280701698@192.168.56.10;tag=as6260cfd8
To: sip:2002@asterisk;tag=as7d793145
Call-ID: 5888763B-7453-4716-B580-643C2EF4FA39-5@192.168.56.8
CSeq: 2 NOTIFY
Content-Length: 0
Via: SIP/2.0/UDP 192.168.56.8:5060;branch=z9hG4bK8ADD9B31-527C-484D-B057-3236F0056368-11
Contact: sip:192.168.56.8:5060
Event: talk

sipcs: 14:34:25.957 dispatchDE: dlg[11]
sipcs: dialog [11:02@01a12fd0]: << Event 73 << TRN[20]
sipcs: qdDem: << Event 73 << DLG[11]
sipcs: dialog [11:02@01a12fd0] : no handler for [73]
sipcs: party [2002@01f48cf0:1]:ANSWER(BSOFT):FORCED
Response (0): for CRequest@01F48970 RequestAnswerCall-DesktopToolkitX[544]/14
– RegisterQueue: CRequest@01F48970 RequestAnswerCall-DesktopToolkitX[544]/14 01F42BD0 21073[0,1]
14:34:25.957 — CIFace::Request —

sipcs: 14:34:25.957 Received [488,UDP] 488 bytes from 192.168.56.10:5060 <<<<<
SIP/2.0 489 Bad event
Via: SIP/2.0/UDP 192.168.56.8:5060;branch=z9hG4bK8ADD9B31-527C-484D-B057-3236F0056368-11;received=192.168.56.8
From: “Gito Sarwono 2971” sip:0280701698@192.168.56.10;tag=as6260cfd8
To: sip:2002@asterisk;tag=as7d793145
Call-ID: 5888763B-7453-4716-B580-643C2EF4FA39-5@192.168.56.8
CSeq: 2 NOTIFY
Server: Asterisk PBX 1.8.3.2
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Content-Length: 0

sipcs: 14:34:25.957 dispatchDE: dlg[11]
sipcs: dialog [11:02@01a12fd0]: << Event 74 << TRN[20]
sipcs: qdDem: << Event 74 << DLG[11]
sipcs: dialog [11:02@01a12fd0] : no handler for [74]
sipcs: [+#]unregister_trx_reactor [20]

sipcs: 14:34:31.295 Received [488,UDP] 365 bytes from 192.168.56.10:5060 <<<<<
CANCEL sip:70000@192.168.56.8:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.56.10:5060;branch=z9hG4bK63cd3974
Max-Forwards: 70
From: “Gito Sarwono 2971” sip:0280701698@192.168.56.10;tag=as6260cfd8
To: sip:70000@192.168.56.8:5060
Call-ID: 7d1c0524474ceca62bd1f30f2b8411e4@192.168.56.10:5060
CSeq: 102 CANCEL
User-Agent: Asterisk PBX 1.8.3.2
Content-Length: 0

sipcs: 14:34:31.295 dispatchDE: dlg[10]
sipcs: UK-PEM:HANDLE(NON-CONNECTED):<<EVENT(16)<<DLG[10]
sipcs: dialog [10:03@01a04fb8]: << Event 16 << TRN[21]
sipcs: 14:34:31.295 ‘qoDem’ Unknown transaction ID for dlg[10]

sipcs: 14:34:31.295 Sending [488,UDP] 351 bytes to 192.168.56.10:5060 >>>>>
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.56.10:5060;branch=z9hG4bK63cd3974;received=192.168.56.10
From: “Gito Sarwono 2971” sip:0280701698@192.168.56.10;tag=as6260cfd8
To: sip:70000@192.168.56.8:5060;tag=19A0E3F5-C946-4702-AC76-390303E3EE29-5
Call-ID: 7d1c0524474ceca62bd1f30f2b8411e4@192.168.56.10:5060
CSeq: 102 CANCEL
Content-Length: 0

sipcs: 14:34:31.295 dispatchDE: dlg[10]
sipcs: UK-PEM:HANDLE(NON-CONNECTED):<<EVENT(19)<<DLG[10]
sipcs: dialog [10:04@01a04fb8]: << Event 19 << TRN[21]
sipcs: 14:34:31.295 ‘qoDem’ Unknown transaction ID for dlg[10]

sipcs: 14:34:31.295 Sending [488,UDP] 367 bytes to 192.168.56.10:5060 >>>>>
SIP/2.0 487 Request Terminated
Via: SIP/2.0/UDP 192.168.56.10:5060;branch=z9hG4bK63cd3974;received=192.168.56.10
From: “Gito Sarwono 2971” sip:0280701698@192.168.56.10;tag=as6260cfd8
To: sip:70000@192.168.56.8:5060;tag=19A0E3F5-C946-4702-AC76-390303E3EE29-5
Call-ID: 7d1c0524474ceca62bd1f30f2b8411e4@192.168.56.10:5060
CSeq: 102 INVITE
Content-Length: 0

Rene · May 18, 2012, 4:15pm

Hi,

It seems that either Asterisk or X-Lite doesn’t support BroadSoft extension as ‘Bad event’ error is returned to SIP Server. I would say that it’s X-Lite that doesn’t support it but I’m not 100 % sure if Asterisk could have any influence on this.

Please test Bria 3.x from Counterpath if you have a possibility as it supports Broadsoft extension.

sipcs: 14:34:25.957 Sending [488,UDP] 388 bytes to 192.168.56.10:5060 >>>>> NOTIFY sip:2002@asterisk:5060 SIP/2.0 From: "Gito Sarwono 2971" ;tag=as6260cfd8 To: ;tag=as7d793145 Call-ID: 5888763B-7453-4716-B580-643C2EF4FA39-5@192.168.56.8 CSeq: 2 NOTIFY Content-Length: 0 Via: SIP/2.0/UDP 192.168.56.8:5060;branch=z9hG4bK8ADD9B31-527C-484D-B057-3236F0056368-11 Contact: Event: talk
sipcs: 14:34:25.957 dispatchDE: dlg[11]
sipcs: dialog [11:02@01a12fd0]: << Event 73 << TRN[20]
sipcs: qdDem: << Event 73 << DLG[11]
sipcs: dialog [11:02@01a12fd0] : no handler for [73]
sipcs: party [2002@01f48cf0:1]:ANSWER(BSOFT):FORCED
Response (0): for CRequest@01F48970 RequestAnswerCall-DesktopToolkitX[544]/14
– RegisterQueue: CRequest@01F48970 RequestAnswerCall-DesktopToolkitX[544]/14 01F42BD0 21073[0,1]
14:34:25.957 — CIFace::Request —

sipcs: 14:34:25.957 Received [488,UDP] 488 bytes from 192.168.56.10:5060 <<<<<
SIP/2.0 489 Bad event
Via: SIP/2.0/UDP 192.168.56.8:5060;branch=z9hG4bK8ADD9B31-527C-484D-B057-3236F0056368-11;received=192.168.56.8
From: “Gito Sarwono 2971” sip:0280701698@192.168.56.10;tag=as6260cfd8
To: sip:2002@asterisk;tag=as7d793145
Call-ID: 5888763B-7453-4716-B580-643C2EF4FA39-5@192.168.56.8
CSeq: 2 NOTIFY
Server: Asterisk PBX 1.8.3.2
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Content-Length: 0

R.

cavagnaro · May 18, 2012, 5:44pm

No XLite doesn’t support it:

compare to a Bria snippet:

SIP/2.0 180 Ringing Via: SIP/2.0/UDP 192.168.90.101:5060;branch=z9hG4bK278EDB5C-BEE3-47A9-B547-A7776F2B2F42-1 Contact: To: ;tag=853b9535 From: ;tag=3D745481-8278-44C7-9CB4-7D1BDEF31B5E-4 Call-ID: AD72F9D6-7F72-4028-A426-9C93668CB7D2-1@192.168.90.101 CSeq: 1 INVITE User-Agent: Bria 3 release 3.3.2 stamp 65843 [b]Allow-Events: hold, talk[/b]
Content-Length: 0

So your softphone can’t control XLite, but you can use XLite as 1pcc so you can override this.

Regards

gitosus · May 21, 2012, 11:43pm

Thank you Cavanaro and Rene,

For your review and suggestion, I will test and will check either asterisk or x-lite problem. I will post when I have the result.

Rgds, gito sus