« Back to Technical Questions

BUG: MakeCall is not handled properly

Combination View Flat View Tree View
Threads [ Previous | Next ]
toggle
Hi,
 
we access the uaCSTA interface of the CME system to do call monitoring and call control. While placing a call every now and then the CME accepts the MakeCall request but does not handle it. In basic words: MakeCallRequest is sent, MakeCallResponse is received containing a valid call ID. Afterwards the CME sends a OriginatedEvent telling the device which should handle the call is in dialtone mode and nothing else happens. I can clear the connection using the CSTA interface.
 
This scenario happens a lot in customer systems, obviously not in the dev system in house.
 
Traces:
 
################################################################################

20.06.2011 11:05:46:718;32;SIP;TRANSACTION COMPLETED: ID=z9hG4bK42D851F53 SEQUENCE=241 METHOD=INFO DIRECTION=CALLEE

20.06.2011 11:06:05:626;32;SIP;TRANSACTION STARTED:
ID=z9hG4bK.A5B4B925D8AA4E9044EEE7802FC785A4 SEQUENCE=280 METHOD=INFO
DIRECTION=CALLER

20.06.2011 11:06:05:626;32;SIP;<--- REQUEST FROM 192.168.224.2:5360 TO 192.168.224.1:5060

################################################################################

INFO sip:291@192.168.224.1:5060;transport=tcp SIP/2.0

Via: SIP/2.0/tcp 192.168.224.2:5360;branch=z9hG4bK.A5B4B925D8AA4E9044EEE7802FC785A4

From: <sip:ecsta@bekodkt-bbt013.beko.local>;tag=03CCACE3AA60452A135F268A5314B7F3

To: <sip:291@192.168.224.1>;tag=F542FA70-E3D

Call-ID: 4E5B1E8ED943431499EAB2930082BFC0

CSeq: 280 INFO

Content-Type: application/csta+xml

Content-Disposition: signal;handling=required

Max-Forwards: 70

User-Agent: ESTOS ECSTA

Content-Length: 291
<?xml version="1.0" encoding="UTF-8"?><MakeCall
xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3"><callingDevice>tel:291;device=0002FDFFD0EE</callingDevice><calledDirectoryNumber>tel:00815136856177</calledDirectoryNumber><autoOriginate>doNotPrompt</autoOriginate></MakeCall>

################################################################################

20.06.2011 11:06:05:641;32;SIP;---> RESPONSE FROM 192.168.224.1:5060 TO 192.168.224.2:5360

################################################################################

SIP/2.0 100 Trying

Via: SIP/2.0/tcp 192.168.224.2:5360;branch=z9hG4bK.A5B4B925D8AA4E9044EEE7802FC785A4

From: <sip:ecsta@bekodkt-bbt013.beko.local>;tag=03CCACE3AA60452A135F268A5314B7F3

To: <sip:291@192.168.224.1>;tag=F542FA70-E3D

Date: Mon, 20 Jun 2011 09:02:48 GMT

Call-ID: 4E5B1E8ED943431499EAB2930082BFC0

Server: Cisco-SIPGateway/IOS-12.x

CSeq: 280 INFO

Content-Length: 0
################################################################################

20.06.2011 11:06:05:641;32;SIP;---> RESPONSE FROM 192.168.224.1:5060 TO 192.168.224.2:5360

################################################################################

SIP/2.0 200 OK

Via: SIP/2.0/tcp 192.168.224.2:5360;branch=z9hG4bK.A5B4B925D8AA4E9044EEE7802FC785A4

From: <sip:ecsta@bekodkt-bbt013.beko.local>;tag=03CCACE3AA60452A135F268A5314B7F3

To: <sip:291@192.168.224.1>;tag=F542FA70-E3D

Date: Mon, 20 Jun 2011 09:02:48 GMT

Call-ID: 4E5B1E8ED943431499EAB2930082BFC0

Server: Cisco-SIPGateway/IOS-12.x

CSeq: 280 INFO

Contact: <sip:291@192.168.224.1:5060;transport=tcp>

Content-Type: application/csta+xml

Content-Disposition: signal;handling=required

Content-Length: 280
<?xml version="1.0" encoding="UTF-8"?>

<MakeCallResponse xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3">

<callingDevice>

<callID>E7713CC7-9A5211E0-803AAA46-70FAE5AE</callID>

<deviceID>tel:291;device=0002FDFFD0EE</deviceID></callingDevice></MakeCallResponse>



################################################################################

20.06.2011 11:06:05:641;32;SIP;TRANSACTION COMPLETED:
ID=z9hG4bK.A5B4B925D8AA4E9044EEE7802FC785A4 SEQUENCE=280 METHOD=INFO
DIRECTION=CALLER

20.06.2011 11:06:05:641;32;SIP;---> REQUEST FROM 192.168.224.1:16778 TO 192.168.224.2:5360

################################################################################

INFO sip:ecsta@192.168.224.2:5360;maddr=192.168.224.2;transport=tcp SIP/2.0

Via: SIP/2.0/TCP 192.168.224.1:5060;branch=z9hG4bK42D8618A

From: <sip:291@192.168.224.1>;tag=F542FA70-E3D

To: <sip:ecsta@bekodkt-bbt013.beko.local>;tag=03CCACE3AA60452A135F268A5314B7F3

Date: Mon, 20 Jun 2011 09:02:48 GMT

Call-ID: 4E5B1E8ED943431499EAB2930082BFC0

User-Agent: Cisco-SIPGateway/IOS-12.x

Max-Forwards: 70

Timestamp: 1308560568

CSeq: 161 INFO

Contact: <sip:291@192.168.224.1:5060;transport=tcp>

Content-Type: application/csta+xml

Content-Disposition: signal;handling=required

Content-Length: 554
<?xml version="1.0" encoding="UTF-8"?>

<OriginatedEvent xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3">

<monitorCrossRefID>000154AF</monitorCrossRefID>

<originatedConnection>

<callID>E7713CC7-9A5211E0-803AAA46-70FAE5AE</callID>

<deviceID>tel:291;device=0002FDFFD0EE</deviceID></originatedConnection>

<callingDevice>

<deviceIdentifier>tel:291;device=0002FDFFD0EE</deviceIdentifier></callingDevice>

<calledDevice>

<notKnown/></calledDevice>

<localConnectionInfo>connected</localConnectionInfo>

<cause>normal</cause></OriginatedEvent>

 
From the client side everything looks okay....
 


 
 
 
 
 
 
 
 
 
 
 

Hi Stephan,

did you are application received CONNECTION_RINGING event , please send us the logs by enabling below debugs for analysis.

debug cti all
debug ccsip messages

Thanks,
Raghavendra

RE: BUG: MakeCall is not handled properly
Answer
6/24/11 10:00 AM as a reply to Raghavendra Gutty Veeranagappa.
Hi Raghavendra,
 
from the CSTA view i do not receive any more events. The system stays in that status until i hang up the phone manually or drop the connection using
ClearConnection. As i cannot reproduce the scenario in house i cannot attach any more logging apart from the uaCSTA payload i´m logging anyhow. As mentioned we´ve seen this issue in about a dozen customer scenarios and the customers are getting bugged. Every log i´m looking at looks exactly the same. MakeCall MakeCallResult Originated nothing. It does not depend wether i call an internal extension or a external destination.
 
The CME does NOT process the MakeCall request as supposed to.
 
Jan
 
 
 
 
 
 

Hi Stephan,

it is difficult to say what went wrong without logs please try to collect the logs from customer site.
also send us your sample we will try to test in our local setup.

Thanks,
Raghavendra

RE: BUG: MakeCall is not handled properly
Answer
6/24/11 1:37 PM as a reply to Raghavendra Gutty Veeranagappa.
Hi Raghavendra,



i received logs from a customer who is facing the issue described above. MakeCall, MakeCallResponse, Originated, no more events, Phone stays in dialtone mode.


Hope someone can take a deeper look in whats going on as from the uaCSTA part everything looks fine.


Jan

 
 
Attachments:

Hi Stephan,

Please confirm whether you are using UCXSI API to make call, if you are not using ucxsi api then this is not the right forum to discuss the issue.

Thanks,
Raghavendra

RE: BUG: MakeCall is not handled properly
Answer
7/1/11 9:15 PM as a reply to Raghavendra Gutty Veeranagappa.
Hi Raghavendra,
 
i´m using the CSTA TR87 interface which is part of the UC Express Services Interface. I´m not sure what UCXSI API is in your terminology but from my point of view i´m not in the wrong forum...
 
Jan

Hi Stephan,

i mean are you using UCXSI SDK ? if so which version ? i hope you are communicating with CME via CSTA by passing UCXSI API is that correct.

Thanks,
Raghavendra

RE: BUG: MakeCall is not handled properly
Answer
9/30/11 10:34 PM as a reply to Raghavendra Gutty Veeranagappa.
Hi,

I am having a very similar problem here. Except that i receive the following CSTA message if i do not clear the connection after a few seconds.

<?xml version="1.0" encoding="UTF-8"?>
<ConnectionClearedEvent xmlns="http://www.ecma-international.org/standards/ecma-323/csta/ed3">
<monitorCrossRefID>00000A32</monitorCrossRefID>
<droppedConnection>
<callID>2344282E-EAAD11E0-8C37B813-25886C02</callID>
<deviceID>tel:221;device=B8BEBF9D46C7</deviceID></droppedConnection>
<releasingDevice>
<deviceIdentifier>tel:221;device=B8BEBF9D46C7</deviceIdentifier></releasingDevice>
<cause>destNotObtainable</cause></ConnectionClearedEvent>

This seams to indicate that the extension number is not valid however, it can be reached by the cisci ip phone with the 221 extension.

Any ideas on what could cause this problem?

P.S. I am not using the UCXSI SDK but i could not find a more appropriate forum. Could you point it to me if there is one?

Hello,

We have the same issue with somme installaltion, please find bellow the trace. the makecall does't work via uaCSTA and work from the device.

See line 028750 to 028756 , it seems that the called number (1981) is not found by the system ?
device 1200 call device 1891

028746: Sep 22 09:11:00.970: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_callID getting dn_tag 293 mac 1CAA0711A1B5
028747: Sep 22 09:11:00.970: //CTI/CM:find_lineinfo_node finding number 1891
028748: Sep 22 09:11:00.970: //CTI/CM: target_node 86094C54
028749: Sep 22 09:11:00.970: //CTI/CM: - dn 1891

Error 1891 not found!
028750: Sep 22 09:11:00.970: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_GCID num 1891 gcid 9705B463-E43111E0-AE5B8166-E33C11DC
028751: Sep 22 09:11:00.970: //CTI/CM:cmm_find_callid_from_gcid dn 1891
028752: Sep 22 09:11:00.970: //CTI/CM:find_gcidinfo_node
028753: Sep 22 09:11:00.970: //CTI/CM: target_node 8AE43920
028754: Sep 22 09:11:00.970: //CTI/CM: - gcid 9705B463-E43111E0-AE5B8166-E33C11DC
028755: Sep 22 09:11:00.970: //CTI/CM: to return callID -1
028756: Sep 22 09:11:00.970: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_GCID fail in getting CallID

// same search with 1200 but no error
028757: Sep 22 09:11:00.970: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_GCID num 1200 gcid 9705B463-E43111E0-AE5B8166-E33C11DC
028758: Sep 22 09:11:00.970: //CTI/CM:cmm_find_callid_from_gcid dn 1200
028759: Sep 22 09:11:00.970: //CTI/CM:find_gcidinfo_node
028760: Sep 22 09:11:00.970: //CTI/CM: target_node 8AE43920
028761: Sep 22 09:11:00.970: //CTI/CM: - gcid 9705B463-E43111E0-AE5B8166-E33C11DC
028762: Sep 22 09:11:00.970: //CTI/CM: to return callID 5511
028763: Sep 22 09:11:00.970: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_callID num 1200 callID 5511
028764: Sep 22 09:11:00.970: //CTI/CM:find_lineinfo_node finding number 1200
028765: Sep 22 09:11:00.974: //CTI/CM: target_node 885AFEC0
028766: Sep 22 09:11:00.974: //CTI/CM: - dn 1200

// l¿UC return clear connection with cause « numberUnallocated »
028767: Sep 22 09:11:00.974: //CTI/CM:cti_cmm_set_dev_id_DN_Phone_by_callID getting dn_tag 293 mac 1CAA0711A1B5
028768: Sep 22 09:11:00.974: //CTI/CM:cti_cmm_notify_ccm_from_lineinfo trigger EV_CONN_CLEARED
028769: Sep 22 09:11:00.974: <== CMM show CTI event ==>
028770: Sep 22 09:11:00.974: - Calling 1200
028771: Sep 22 09:11:00.974: - Calling attr 1
028772: Sep 22 09:11:00.974: - Calling tag 293
028773: Sep 22 09:11:00.974: - Calling MAC 1CAA0711A1B5
028774: Sep 22 09:11:00.974: - Called 1891
028775: Sep 22 09:11:00.974: - Called attr 1
028776: Sep 22 09:11:00.974: - Called tag -1
028777: Sep 22 09:11:00.974: - Called MAC
028778: Sep 22 09:11:00.974: - ConnAddr 1200
028779: Sep 22 09:11:00.974: - ConnAddr attr 1
028780: Sep 22 09:11:00.974: - ConnAddr tag 293
028781: Sep 22 09:11:00.974: - ConnAddr MAC 1CAA0711A1B5
028782: Sep 22 09:11:00.974: - type 69
028783: Sep 22 09:11:00.974: - Cause 9 numberUnallocated
028784: Sep 22 09:11:00.974: - Gcid 9705B463-E43111E0-AE5B8166-E33C11DC
028785: Sep 22 09:11:00.974: - EV_CONN_CLEARED
028786: Sep 22 09:11:00.974: - Cause 9(numberUnallocated)
028787: Sep 22 09:11:00.974: - releasing_device
028788: Sep 22 09:11:00.974: - releasing_device attr0
028789: Sep 22 09:11:00.974: - direction 0
028790: Sep 22 09:11:00.974: - localConnectionState Null