Genesys CTI User Forum

Genesys CTI User Forum => Genesys CTI Technical Discussion => Topic started by: yahoo on May 28, 2009, 12:49:33 PM

Title: Unexpected GctmMsg::destroy called.
Post by: yahoo on May 28, 2009, 12:49:33 PM
Hi all,

Can any one let me know wat does this mean

[b]@20:27:31.7289 [gctm] WARNING: Unexpected GctmMsg::destroy called.[/b]

@20:27:31.7284 [0] 7.2.004.00 send_to_client: message EventError
(Invalid call reference ID)
AttributeEventSequenceNumber 000000000003e6fb
AttributeCustomerID 'Resources'
AttributeTimeinuSecs 728436
AttributeTimeinSecs 1243470451 (20:27:31)
AttributeConnID 00f201b156ec07e4
AttributeThisDN '60670'
AttributeReferenceID 29978
AttributeErrorCode 408
20:27:31.728 Int 04545 Interaction message "EventError" sent to 16 ("crcur")
20:27:31.728 Trc 04542 EventError sent to 16 (0005 crcur)
[b]@20:27:31.7289 [gctm] WARNING: Unexpected GctmMsg::destroy called.[/b]
@20:27:35.1611 [gctm] Call [00f201b156ec07e4/483535e,s6,ta] Removing ref to moved consult call 483090f
20:27:39.547 Trc 04541 RequestQueryServer received from 13 (00fd Monitor App)
message RequestQueryServer
AttributeReferenceID 3110
@20:27:39.5484 [0] 7.2.004.00 send_to_client: message EventServerInfo
AttributeEventSequenceNumber 000000000003e6fc
AttributeTimeinuSecs 548465
AttributeTimeinSecs 1243470459 (20:27:39)
AttributeReferenceID 3110
AttributeExtensions [309] 00 02 00 00..


thanks in advance
Title: Re: Unexpected GctmMsg::destroy called.
Post by: Fra on May 28, 2009, 12:58:07 PM
Hi,

it's hard to say just from the message you posted. Could you please upload the full URS trace of the call? Can you also tell me what switch you are using and the value of the URS option route_consult_call?

Cheers,

Fra
Title: Re: Unexpected GctmMsg::destroy called.
Post by: yahoo on May 28, 2009, 01:55:35 PM
we are using nortel symposium.

route_consult_call is set to true (i used values of 2000 3000, 4000 and 10000 for xfers but that did not work out so went for true) also give_treatment = true.

connid is 00f201b156ec07e4
Title: Re: Unexpected GctmMsg::destroy called.
Post by: René on May 28, 2009, 02:30:04 PM
Yahoo,

I would say something is wrong in the routing strategy as T-Server receives the request "RequestRouteCall" with empty target:

[font=Courier New]20:27:31.714 Trc 04541 RequestRouteCall received from 16 (0005 crcur)
message RequestRouteCall
AttributeThisDN '60670'
AttributeConnID 00f201b156ec07e4
[b]AttributeOtherDN ''                    <--- MISSING TARGET NUMBER[/b]
AttributeExtensions [53] 00 02 00 00..
'CUSTOMER_ID' 'Resources'
'SWITCH' 'gskcrcoption81c'
AttributeRouteType 1 (RouteTypeDefault)
AttributeReferenceID 29978[/font]

Message "[gctm] WARNING: Unexpected GctmMsg::destroy called." is just reaction to unexpected call end caused by routing to empty target.

R.
Title: Re: Unexpected GctmMsg::destroy called.
Post by: Fra on May 30, 2009, 01:08:49 AM
Renč is right, but the situation is actually more complicated:
[list]
[li]callflow starts on RP 60624, you have two (why?) RequestGiveRingBackTreatment, then your strategy routes the call to GVP[/li]
[li]the DN which picks up the call is 65331, after 12 secs the call is put on hold for an alledged transfer: the strange thing is that Tserver receives a Route Request from the switch after 2 secs (!) on RP 60670 and generates only at this point an EventDialing for the second leg (consult call) that needs to be created for the transfer (I have removed some attributes just to keep it short):
[font=Times New Roman][size=8pt]
@20:27:26.8834  7.2.004.00 distribute_event: message EventHeld
AttributeCallState 0
AttributeOtherDN '9195979634'
AttributeOtherDNRole 1
AttributeThisDN '65331'
AttributeConnID 00f201b156ec07e4
AttributeCallID 75715422
AttributeCallType 2
@20:27:28.6828 [<<] FF 0A 00 3F 04 00 00 0B 05 00 96 06 04 83 09 0F 30 09 00 08 36 35 33 33 31 4B 07 36 30 36 37 30 4D 04 A3 18 4C 0E 00 02 39 31 39 35 39 37 39 36 33 34 4E 07 35 39 31 31 30 53 06 04 83 53 5E
Route Request
Call ID:483090f
Origination Address:Internal, 65331
Controlled DN:60670
Transfer Party TN:a318
Transfer Party DN:National, 9195979634
Transfer Party DNIS:59110
Transfer Call ID:483535e
@20:27:28.6833 [mlink] (processRouteRequest)
@20:27:28.6834 [gctm] distributing EventRouteRequest to model
@20:27:28.6836 Call [00f201b156ec07e6]: urid 000300f2
@20:27:28.6836 Call [00f201b156ec07e6]: urid 00000000
@20:27:28.6838 [gctm] Call [00f201b156ec07e6/483090f,s0,t1] created.
@20:27:28.6838 [gctm] Call [00f201b156ec07e6/483090f,s0,t1] distributing EventRouteRequest
@20:27:28.6839 [mlink] Call [00f201b156ec07e6/75696399,s0,t1] (processRouteRequest)
@20:27:28.6840 [mlink] Call [00f201b156ec07e6/75696399,s0,t1] (sendDialForAnlgCnslt). Generating EvDialing.
@20:27:28.6841 [gctm] Call [00f201b156ec07e6/483090f,s0,t1] distributing EventDialing
@20:27:28.6841 [gctm] Call [00f201b156ec07e6/483090f,s0,t1] processDialing
@20:27:28.6842 [gctm] Call [00f201b156ec07e6/483090f,s0,t1] processDialInitiated
@20:27:28.6842 [gctm] Call [00f201b156ec07e6/483090f,s0,t1] Changing state to 1
@20:27:28.6844 [gctm] Party [00f201b156ec07e6:65331,s0,at1,r1,l1] created.
@20:27:28.6845 [gctm] Party [00f201b156ec07e6:65331,s0,at1,r1,l1] processDialing
@20:27:28.6846 [gctm] Address [65331,t1,s1] processOffHook
@20:27:28.6847 [gctm] Party [00f201b156ec07e6:65331,s0,at1,r1,l1] Changing state to 1000c
@20:27:28.6849 7.2.004.00 distribute_event: message EventDialing
AttributeCallState 0
AttributeOtherDNRole 2
AttributeOtherDN '60670'
AttributeThisTrunk 60499
AttributeThisDNRole 1
AttributeThisDN '65331'
AttributeTransferConnID 00f201b156ec07e4
AttributeCallUUID '00684E9E-DA70-1A1D-990C-8FC146C4AA77'
AttributeConnID 00f201b156ec07e6
AttributeCallID 75696399
AttributeCallType 4
@20:27:28.6859 [gctm] Call [00f201b156ec07e6/483090f,s1,t2] processRouteRequest
@20:27:28.6861 [gctm] Party [00f201b156ec07e6:60670,s0,at4,r2,l1] created.
@20:27:28.6862 [gctm] Party [00f201b156ec07e6:65331,s1000c,at1,r1,l1] processDialing
@20:27:28.6862 [gctm] Address [65331,t1,s1] processOffHook
@20:27:28.6863 [gctm] Party [00f201b156ec07e6:65331,s1000c,at1,r1,l1] EvDialing ignored, in Dialing state
@20:27:28.6864 [gctm] Party [00f201b156ec07e6:60670,s0,at4,r2,l1] processRouteRequest
@20:27:28.6864 [gctm] Party [00f201b156ec07e6:60670,s0,at4,r2,l1] Changing state to 800
@20:27:28.6866 7.2.004.00 distribute_event: message EventRouteRequest
AttributeThisQueue '60670'
AttributeCallState 0
AttributeOtherDNRole 1
AttributeOtherDN '65331'
AttributeOtherTrunk 60499
AttributeThisDNRole 2
AttributeThisDN '60670'
AttributeTransferConnID 00f201b156ec07e4
AttributeCallUUID '00684E9E-DA70-1A1D-990C-8FC146C4AA77'
AttributeConnID 00f201b156ec07e6
AttributeCallID 75696399
AttributeCallType 4 [/size][/font] [/li]

[li] as the route_consult_call is set to true, URS is instructed to kick off the strategy loaded against the RP 60670[/li]
[li] after the second RequestGiveRingBackTreatment, TServer merges the calls and the transfer is completed [/li]
[li] URS sends a RequestRouteCall with destination 60595 which the switch deems as invalid
[font=Times New Roman][size=8pt]
20:27:31.694 Trc 04541 RequestRouteCall received from 16 (0005 crcur)
message RequestRouteCall
AttributeThisDN '60670'
AttributeConnID 00f201b156ec07e4
AttributeOtherDN '60595'
AttributeExtensions [53] 00 02 00 00..
'CUSTOMER_ID' 'Resources'
'SWITCH' 'gskcrcoption81c'
AttributeRouteType 6 (RouteTypeDirect)
AttributeReferenceID 29976
20:27:31.694 Int 04543 Interaction message "RequestRouteCall" received from 16 ("crcur")
@20:27:31.6944 [tsgctm] Distributing request RequestRouteCall
@20:27:31.6945 [mlink] Party [00f201b156ec07e4:60670,s2560,at4,r2,l1] (reqRouteCall)
Route Call
Reference ID:2c9
Call ID:483535e
Controlled DN:60670
Destination Address:Unknown, 60595
@20:27:31.6947 [>>] FF 0A 00 20 04 02 C9 0D 09 00 96 06 04 83 53 5E 4B 07 36 30 36 37 30 31 09 00 00 36 30 35 39 35
@20:27:31.7104 [<<] FF 0A 00 23 04 82 C9 0D 0A 00 96 06 04 83 53 5E 31 09 00 00 36 30 35 39 35 4B 07 36 30 36 37 30 AA 03 21
Route Call Response
Reference ID:2c9
Call ID:483535e
Destination Address:Unknown, 60595
Controlled DN:60670
Call Status:Invalid music route
@20:27:31.7106 [mlink] (processRouteCallResponse)
@20:27:31.7109 [gctm] distributing EventError to model
@20:27:31.7110 [gctm] request RequestRouteCall deactivated in reqMgr
@20:27:31.7110 [gctm] Switch [s1] distributing EventError
@20:27:31.7111 [gctm] Switch [s1] processError
@20:27:31.7112 7.2.004.00 send_to_client: message EventError
(Invalid music route or destination)
AttributeEventSequenceNumber 000000000003e6f8
AttributeCustomerID 'Resources'
AttributeTimeinuSecs 711224
AttributeTimeinSecs 1243470451 (20:27:31)
AttributeConnID 00f201b156ec07e4
AttributeThisDN '60670'
AttributeReferenceID 29976
AttributeErrorCode 413

[/size][/font][/li]
[li] call defaults (that's what Renč was indicating, but that's the very last issue you have here  ;D ) [/li][/list]

Fra

p.s. : why is the route_consult_call set to true?