Hi here are the full logs:
TServer logs:
message RequestRouteCall
AttributeThisDN '3257'
AttributeConnID 006e026dff8f6ae0
AttributeOtherDN '3800003'
AttributeLocation 'SIPSwitchMVD1'
AttributeExtensions [266] 00 0a 00 00..
'agent-greeting' 'greeting/W_Collections_Nordics'
'customer-greeting' 'greeting/silence'
'CUSTOMER_ID' 'Resources'
'AGENT' 'sipagent1'
'PLACE' 'SIP3800003'
'DN' '3800003'
'ACCESS' '3800003'
'SWITCH' 'SIPSwitchMVD1'
'NVQ' 1
'TARGET' 'Testing@statserver_routing_mvd1.GA'
AttributeRouteType 0 (RouteTypeUnknown)
AttributeReason [14] 00 01 01 00..
'RTR' 111
AttributeReferenceID 2722149
11:12:51.286 Int 04543 Interaction message "RequestRouteCall" received from 732 ("urs")
@11:12:51.2860 [ISCC] Request with xaction type 'route' will be processed by strategy 'ROUTE_ORIGINATION_STRATEGY'
@11:12:51.2860 [ISCC] +? tep [006e026dff8f6ae0:sipserver_voice_mvd2@SIPSwitchMVD1:006e026dff8f6ae0]
@11:12:51.2860 [ISCC] Created transaction [route O7213605 o::006e026dff8f6ae0:3257 d:sipserver_voice_mvd2@SIPSwitchMVD1:006e026dff8f6ae0:3800003 c:urs:00000027@]
@11:12:51.2860 [ISCC] Send to location SIPSwitchMVD1
@11:12:51.2860 [ISCC] Send to server sipserver_voice_mvd2@SIPSwitchMVD1/0: message ISCCRequestGetCallDataXferService
ISCCAttributeTrackingID 7218573 [006e258d]
ISCCAttributeToken ''
ISCCAttributeGlobalTransactionID '9S59AVML6T6E33947DNHVB677C06DLFU'
ISCCAttributeISLinkUUID '9S59AVML6T6E33947DNHVB677C06DLG0'
ISCCAttributeNetworkDestDN 'SIPSwitchMVD1::3800003'
ISCCAttributeFirstTransferOriginationDN '3257'
ISCCAttributeFirstTransferConnID 006e026dff8f6ae0
ISCCAttributeFirstTransferHomeLocation 'tserver@Switch'
ISCCAttributeLastTransferOriginationDN '3257'
ISCCAttributeLastTransferConnID 006e026dff8f6ae0
ISCCAttributeLastTransferHomeLocation 'tserver@Switch'
ISCCAttributeCallType Inbound
ISCCAttributeCallDataXferType RouteNoToken
ISCCAttributeDestinationDN '3800003'
ISCCAttributeReferenceID 7213605 [006e1225]
ISCCAttributeOriginationDN '3257'
ISCCAttributeConnID 006e026dff8f6ae0
ISCCAttributeExtensions
'agent-greeting' 'greeting/W_Collections_Nordics'
'customer-greeting' 'greeting/silence'
'CUSTOMER_ID' 'Resources'
'AGENT' 'sipagent1'
'PLACE' 'SIP3800003'
'DN' '3800003'
'ACCESS' '3800003'
'SWITCH' 'SIPSwitchMVD1'
'NVQ' 1
'TARGET' 'Testing@statserver_routing_mvd1.GA'
ISCCAttributeUpdateRevision 458862 [0007006e]
ISCCAttributeUserData
'RRequestedSkills'(list)
'CustomerSegment' 'default'
'ServiceType' 'default'
'ServiceObjective' ''
'key2' 'value2'
'key1' 'value1'
'PegDEF' 1
'RTargetAgentGroup' 'Testing'
'RVQID' ''
'RVQDBID' ''
'RTargetTypeSelected' '2'
'RTargetRuleSelected' ''
'RTargetObjectSelected' 'Testing'
'RTargetObjSelDBID' '124'
'RTargetAgentSelected' 'sipagent1'
'RTargetPlaceSelected' 'SIP3800003'
'RTenant' 'Resources'
'RStrategyName' 'migration-tserver-sipserver-test'
'RStrategyDBID' '3279'
'CBR-actual_volume' ''
'CBR-Interaction_cost' ''
'CBR-contract_DBIDs' ''
'CBR-IT-path_DBIDs' ''
'RRequestedSkillCombination' ''
'RTargetRequested' 'Testing'
'PegAGTesting' 1
@11:12:51.2860 [ISCC] [connection 04679228] Sent to connection [2208]2
@11:12:51.2900 [ISCC] Party is removed [expected]:
@ c:006e026dff8f6ae0,05069eb0 @ m:0000000000000000,00000000 p:1 i:000031ff nw:000131ff5654624e t:2
- p:0000000000000000,032b9940 @ c:006e026dff8f6ae0,05069eb0 r:2 ----- n:3257:
@11:12:51.2900 [ISCC] Party is added [expected]:
@ c:006e026dff8f6ae0,05069eb0 @ m:0000000000000000,00000000 p:1 i:000031ff nw:000131ff5654624e t:2
+ p:006e026dff8f6ae0,00000000 @ c:006e026dff8f6ae0,05069eb0 r:2 -a--- n:3800003:sipserver_voice_mvd2@SIPSwitchMVD1
@11:12:51.2900 [ISCC] Message EventTransactionStatus [Event (To Client):0017ee8d] is enqueued
@11:12:51.3060 [ISCC] Message EventTransactionStatus [Event (To Client):0017ee8d] is dequeued
@11:12:51.3060 [0] 8.1.001.06 send_to_client: message EventTransactionStatus
AttributeEventSequenceNumber 0000000001c84cd9
AttributeTimeinuSecs 306000
AttributeTimeinSecs 1448370771 (11:12:51)
AttributeExtensions [276] 00 06 00 00..
'class.feature-set' 'iscc.transaction iscc.direction iscc.call-operation iscc.is-link-creation'
'class.name' 'iscc.transaction-route'
'iscc.direction.role' 'source'
'iscc.transaction' 'started'
'iscc.transaction.state' 'in-progress'
'object.id' '9S59AVML6T6E33947DNHVB677C06DLFU'
AttributeSubscriptionID '9S59AVML6T6E33947DNHVB677C000001'
11:12:51.306 Trc 04542 EventTransactionStatus sent to [600] (00000006 icon_voice 10.135.94.111:58416)
11:12:51.306 Trc 04542 EventTransactionStatus sent to [960] (0000340f 5000 10.135.94.70:1995)
@11:12:51.3060 [ISCC] Message [Event (To Client):0017ee8d] is deleted
@11:12:51.3060 [ISCC] [connection 04679228] Received from connection [2208]2
@11:12:51.3060 [ISCC] Received from server sipserver_voice_mvd2@SIPSwitchMVD1/0: message ISCCEventCallDataXferService
ISCCAttributeCallDataXferType RouteNoToken
ISCCAttributeCallDataXferResource '83800003'
ISCCAttributeReferenceID 7213605 [006e1225]
ISCCAttributeTrackingID 14811692 [00e2022c]
@11:12:51.3060 [ISCC] Call is committed:
@ c:006e026dff8f6ae0,05069eb0 @ m:0000000000000000,00000000 p:1 i:000031ff nw:000131ff5654624e t:2
p:006e026dff8f6ae0,00000000 @ c:006e026dff8f6ae0,05069eb0 r:2 ca--- n:3800003:sipserver_voice_mvd2@SIPSwitchMVD1
@11:12:51.3060 [ISCC] Message RequestRouteCall [Request:0017ee8e] is enqueued
@11:12:51.3060 [ISCC] Message EventTransactionStatus [Event (To Client):0017ee8f] is enqueued
@11:12:51.3250 [ISCC] Message RequestRouteCall [Request:0017ee8e] is dequeued
@11:12:51.3250 [ISCC] Sent to tserver [from ISCC:00000001@]: message RequestRouteCall
AttributeReferenceID 7213605
AttributeConnID 006e026dff8f6ae0
AttributeOtherDN '83800003'
AttributeThisDN '3257'
AttributeLocation 'SIPSwitchMVD1'
AttributeExtensions [266] 00 0a 00 00..
'agent-greeting' 'greeting/W_Collections_Nordics'
'customer-greeting' 'greeting/silence'
'CUSTOMER_ID' 'Resources'
'AGENT' 'sipagent1'
'PLACE' 'SIP3800003'
'DN' '3800003'
'ACCESS' '3800003'
'SWITCH' 'SIPSwitchMVD1'
'NVQ' 1
'TARGET' 'Testing@statserver_routing_mvd1.GA'
AttributeRouteType 0 (RouteTypeUnknown)
AttributeReason [14] 00 01 01 00..
'RTR' 111
@11:12:51.3250 [ISCC] Message [Request:0017ee8e] is deleted
@11:12:51.3250 [ISCC] Message EventTransactionStatus [Event (To Client):0017ee8f] is dequeued
@11:12:51.3250 [0] 8.1.001.06 send_to_client: message EventTransactionStatus
AttributeEventSequenceNumber 0000000001c84cda
AttributeTimeinuSecs 325000
AttributeTimeinSecs 1448370771 (11:12:51)
AttributeExtensions [326] 00 07 00 00..
'iscc.call-operation' 'started'
'iscc.call-operation.call-id' '9S59AVML6T6E33947DNHVB677C06DLFM'
'iscc.is-link-creation' 'started'
'iscc.is-link-creation.is-link-id' '9S59AVML6T6E33947DNHVB677C06DLG0'
'iscc.is-link-creation.state' 'in-progress'
'iscc.transaction.state' 'in-progress'
'object.id' '9S59AVML6T6E33947DNHVB677C06DLFU'
AttributeSubscriptionID '9S59AVML6T6E33947DNHVB677C000001'
11:12:51.325 Trc 04542 EventTransactionStatus sent to [600] (00000006 icon_voice 10.135.94.111:58416)
11:12:51.325 Trc 04542 EventTransactionStatus sent to [960] (0000340f 5000 10.135.94.70:1995)
@11:12:51.3250 [ISCC] Message [Event (To Client):0017ee8f] is deleted
@11:12:51.3290 [gctmi] Distributing request RequestRouteCall
@11:12:51.3290 [csta] Party [006e026dff8f6ae0:3257,s809,tRP,rDST,lINT] (reqRouteCall)
@11:12:51.3290 [>>] CSTA_REQUEST invokeID 5490717
attRouteSelect
{
callingDevice NULL
directAgentCallSplit NULL
priorityCalling 0
destRoute NULL
collectCode NULL
userProvidedCode NULL
redirectType vdn
}
cstaRouteSelectInv
{
acsHandle 15025456
routeRegisterReqID 333
routingCrossRefID 52241075
routeSelected '83800003'
remainRetry 0
setupInformation NULL
routeUsedReq 1
}
@11:12:51.4580 [tsapi] TsapiLink [s4] [15025456] >> 6:131
@11:12:51.4580 [<<] CSTAEVENTREPORT
CSTARouteUsedExtEvent
{
routeRegisterReqID 333
routingCrossRefID 52241075
routeUsed
{
deviceID '83800003'
deviceIDType implicitPublic
deviceIDStatus idProvided
}
callingDevice
{
deviceID '6530'
deviceIDType explicitPrivateLocalNumber
deviceIDStatus idProvided
}
domain 0
}
@11:12:51.4580 [csta] (processRouteUsed)
@11:12:51.4580 [gctmi] TMsg [EventRouteUsed(3257)] distributing to model
@11:12:51.4580 [gctmi] Call [006e026dff8f6ae0/31ff,sOG,tOG,l1] distributing EventRouteUsed
@11:12:51.4580 [gctmi] Call [006e026dff8f6ae0/31ff,sOG,tOG,l1] processRouteUsed
@11:12:51.4580 [gctm] Call [006e026dff8f6ae0/31ff,sOG,tOG,l1] RouteQueue waits for matching divert/r-used.
@11:12:51.4580 [gctmi] TMsg [EventRouteUsed(3257)] (re)distributing to model in 250mS
@11:12:51.4580 [tsapi] TsapiLink [s4] [15025456] >> 6:87
@11:12:51.4580 [<<] CSTAEVENTREPORT
CSTARouteEndEvent
{
routeRegisterReqID 333
routingCrossRefID 52241075
errorValue genericUnspecified
}
@11:12:51.4580 [gctmi] TMsg [EventDiverted(3257)] distributing to model
@11:12:51.4580 [gctmi] Call [006e026dff8f6ae0/31ff,sOG,tOG,l1] distributing EventDiverted
@11:12:51.4580 [gctmi] Call [006e026dff8f6ae0/31ff,sOG,tOG,l1] processDiverted
@11:12:51.4580 [gctmi] TMsg [EventDiverted(3257)] (re)distributing to model in 250mS
@11:12:51.4580 [csta] (processRouteEnd) - ignored if already routed.
@11:12:51.4580 [gctmi] TMsg [EventRouteUsed(3257)] distributing to model
@11:12:51.4580 [gctmi] Call [006e026dff8f6ae0/31ff,sOG,tOG,l1] distributing EventRouteUsed
@11:12:51.4580 [gctmi] Call [006e026dff8f6ae0/31ff,sOG,tOG,l1] processRouteUsed
@11:12:51.4580 [gctmi] TMsg [EventRouteUsed(3257)] (re)distributing to model in 250mS
@11:12:51.6610 [tsapi] TsapiLink [s4] [15025456] >> 4:59
@11:12:51.6610 [<<] CSTAUNSOLICITED monitorCrossRefId 189516
CSTAEstablishedEvent
{
establishedConnection
{
callID 12790
deviceID '4901054'
devIDType 0
}
answeringDevice
{
deviceID '4901054'
deviceIDType explicitPrivateLocalNumber
deviceIDStatus idProvided
}
callingDevice
{
deviceID '6314832959'
deviceIDType explicitPrivateUnknown
deviceIDStatus idProvided
}
calledDevice
{
deviceID '000008030008'
deviceIDType explicitPrivateUnknown
deviceIDStatus idProvided
}
localConnectionInfo csConnect
cause ecNewCall
}
ATTEstablishedEvent
{
trunkGroup '738'
trunkMember '187'
split '7019066'
reason arNone
originalCallInfo
{
reason orNone
trunkGroup ''
trunkMember ''
ucid '00000000000000000000'
flexibleBilling 0
}
ucid '00001127901448370743'
flexibleBilling 0
}
@11:12:51.6610 [tsapi] (processEstablishedEvent)
@11:12:51.6610 [csta] (processEstablishedEvent)
@11:12:51.6610 [tsapi] filtering lastRedirectDevice for EventEstablished
@11:12:51.6610 [gctmi] TMsg [EventEstablished(4901054)] distributing to model
@11:12:51.6610 [gctmi] Call [006e026dff8f6adf/31f6,sOG,tOG,l1] distributing EventEstablished
@11:12:51.6610 [gctmi] Call [006e026dff8f6adf/31f6,sOG,tOG,l1] processEstablished
@11:12:51.6610 [gctm] Call [006e026dff8f6adf/31f6,sOG,tOG,l1] Transition to ESTABLISHED state.
@11:12:51.6610 [tsapi] Call [006e026dff8f6adf/12790,sOG,tOG,l1] (createParty)
@11:12:51.6610 [gctm] Call [006e026dff8f6adf/31f6,sOG,tOG,l1] Releasing party status cleared.
@11:12:51.6610 [gctm] Party [006e026dff8f6adf:6314832959,s0,tUNK,rORG,lUNK] created.
@11:12:51.6650 [gctmi] Party [006e026dff8f6adf:6314832959,s0,tUNK,rORG,lUNK] processDialing
@11:12:51.6650 [gctm] Party [006e026dff8f6adf:6314832959,s0,tUNK,rORG,lUNK] Changing state to 1000c
@11:12:51.6650 [gctm] Call [006e026dff8f6adf/31f6,sOG,tOG,l2] setNbrCallLegs for PartyParty [006e026dff8f6adf:6314832959,s1000c,tUNK,rORG,lUNK]
@11:12:51.6650 [ISCC] Party added [ssp view]:
@ c:006e026dff8f6adf,0506abd0 @ m:0000000000000000,00000000,0000000000000000 p:2 i:000031f6 nw:00000000:000131f656546237 t:2
p:03d5d3b8 @ c:006e026dff8f6adf,0506abd0 r:2 t:0 s:a n:4901054
+ p:03148d90 @ c:006e026dff8f6adf,0506abd0 r:1 t:1 s:1000c n:6314832959
@11:12:51.6650 [ISCC] Party added:
@ c:006e026dff8f6adf,0506abd0 @ m:0000000000000000,00000000 p:2 i:000031f6 nw:000131f656546237 t:2
p:0000000000000000,03d5d3b8 @ c:006e026dff8f6adf,0506abd0 r:2 ----- n:4901054:
+ p:0000000000000000,03148d90 @ c:006e026dff8f6adf,0506abd0 r:1 ----- n:6314832959:
@11:12:51.6650 [0] 8.1.001.06 distribute call/party event: message EventCallPartyAdded
AttributeEventSequenceNumber 0000000001c84cdb
AttributeTimeStamp 56546253000a25a8
AttributeDN '6314832959'
AttributeMediaType 0
AttributePartyState 0x1000c
AttributePartyType 1
AttributeConnID 006e026dff8f6adf
AttributeCallUUID '9S59AVML6T6E33947DNHVB677C06DLF5'
AttributePartyUUID '9S59AVML6T6E33947DNHVB677C06DLG1'
-------------------------------
SIPServer logs:
@10:12:51.8370 [ISCC] Received from server tserver@Switch/0: message ISCCRequestGetCallDataXferService
ISCCAttributeUserData
'RRequestedSkills'(list)
'CustomerSegment' 'default'
'ServiceType' 'default'
'ServiceObjective' ''
'key2' 'value2'
'key1' 'value1'
'PegDEF' 1
'RTargetAgentGroup' 'Testing'
'RVQID' ''
'RVQDBID' ''
'RTargetTypeSelected' '2'
'RTargetRuleSelected' ''
'RTargetObjectSelected' 'Testing'
'RTargetObjSelDBID' '124'
'RTargetAgentSelected' 'sipagent1'
'RTargetPlaceSelected' 'SIP3800003'
'RTenant' 'Resources'
'RStrategyName' 'migration-tserver-sipserver-test'
'RStrategyDBID' '3279'
'CBR-actual_volume' ''
'CBR-Interaction_cost' ''
'CBR-contract_DBIDs' ''
'CBR-IT-path_DBIDs' ''
'RRequestedSkillCombination' ''
'RTargetRequested' 'Testing'
'PegAGTesting' 1
ISCCAttributeUpdateRevision 458862 [0007006e]
ISCCAttributeExtensions
'agent-greeting' 'greeting/W_Collections_Nordics'
'customer-greeting' 'greeting/silence'
'CUSTOMER_ID' 'Resources'
'AGENT' 'sipagent1'
'PLACE' 'SIP3800003'
'DN' '3800003'
'ACCESS' '3800003'
'SWITCH' 'SIPSwitchMVD1'
'NVQ' 1
'TARGET' 'Testing@statserver_routing_mvd1.GA'
ISCCAttributeConnID 006e026dff8f6ae0
ISCCAttributeOriginationDN '3257'
ISCCAttributeReferenceID 7213605 [006e1225]
ISCCAttributeDestinationDN '3800003'
ISCCAttributeCallDataXferType RouteNoToken
ISCCAttributeCallType Inbound
ISCCAttributeLastTransferHomeLocation 'tserver@Switch'
ISCCAttributeLastTransferConnID 006e026dff8f6ae0
ISCCAttributeLastTransferOriginationDN '3257'
ISCCAttributeFirstTransferHomeLocation 'tserver@Switch'
ISCCAttributeFirstTransferConnID 006e026dff8f6ae0
ISCCAttributeFirstTransferOriginationDN '3257'
ISCCAttributeNetworkDestDN 'SIPSwitchMVD1::3800003'
ISCCAttributeISLinkUUID '9S59AVML6T6E33947DNHVB677C06DLG0'
ISCCAttributeGlobalTransactionID '9S59AVML6T6E33947DNHVB677C06DLFU'
ISCCAttributeToken ''
ISCCAttributeTrackingID 7218573 [006e258d]
@10:12:51.8370 [ISCC] DESTINATION_TRANSACTION: origination location 'tserver@Switch' controller client:'none'
@10:12:51.8370 [ISCC] Transaction [D7213605]: created [origin:tserver@Switch controller:tserver@Switch device:3800003 resource:]
@10:12:51.8370 [ISCC] Party object is created: p:0000000000000000,0000000000000000 n::
@10:12:51.8370 [ISCC] Message SDRequestGetCallDataXferService [SD Request:00000325] is enqueued
@10:12:51.8370 [ISCC] Message EventTransactionStatus [Event (To Client):00000326] is enqueued
@10:12:51.8530 [ISCC] Message SDRequestGetCallDataXferService [SD Request:00000325] is dequeued
@10:12:51.8530 [ISCC] Send to tserver: message SDRequestGetCallDataXferService
SDAttributePackedISCCMessage {bstring}
SDAttributeISLinkUUID '9S59AVML6T6E33947DNHVB677C06DLG0'
SDAttributeLastTransferConnID 006e026dff8f6ae0
SDAttributeLastTransferOriginationDN '3257'
SDAttributeLastTransferHomeLocation 'tserver@Switch'
SDAttributeFirstTransferConnID 006e026dff8f6ae0
SDAttributeFirstTransferOriginationDN '3257'
SDAttributeFirstTransferHomeLocation 'tserver@Switch'
SDAttributeUpdateRevision 458862 [0007006e]
SDAttributeUserData
'RRequestedSkills'(list)
'CustomerSegment' 'default'
'ServiceType' 'default'
'ServiceObjective' ''
'key2' 'value2'
'key1' 'value1'
'PegDEF' 1
'RTargetAgentGroup' 'Testing'
'RVQID' ''
'RVQDBID' ''
'RTargetTypeSelected' '2'
'RTargetRuleSelected' ''
'RTargetObjectSelected' 'Testing'
'RTargetObjSelDBID' '124'
'RTargetAgentSelected' 'sipagent1'
'RTargetPlaceSelected' 'SIP3800003'
'RTenant' 'Resources'
'RStrategyName' 'migration-tserver-sipserver-test'
'RStrategyDBID' '3279'
'CBR-actual_volume' ''
'CBR-Interaction_cost' ''
'CBR-contract_DBIDs' ''
'CBR-IT-path_DBIDs' ''
'RRequestedSkillCombination' ''
'RTargetRequested' 'Testing'
'PegAGTesting' 1
SDAttributeDestinationDN '3800003'
SDAttributeOriginationDN '3257'
SDAttributeConnID 006e026dff8f6ae0
SDAttributeReferenceID 7213605 [006e1225]
SDAttributeCallDataXferType RouteNoToken
@10:12:51.8530 [ISCC] tserver: message SDEventCallDataXferService
SDAttributeCallDataXferType RouteNoToken
SDAttributeReferenceID 7213605 [006e1225]
@10:12:51.8530 [ISCC] Resource erp3800003: available for location Switch (access number 83800003)
@10:12:51.8530 [ISCC] Resource erp3800003: acquired by Transaction [D7213605]
@10:12:51.8530 [ISCC] Message EventTransactionStatus [Event (To Client):00000328] is enqueued
@10:12:51.8530 [ISCC] Send to location Switch
@10:12:51.8530 [ISCC] Send to server tserver@Switch/0: message ISCCEventCallDataXferService
ISCCAttributeTrackingID 14811692 [00e2022c]
ISCCAttributeReferenceID 7213605 [006e1225]
ISCCAttributeCallDataXferResource '83800003'
ISCCAttributeCallDataXferType RouteNoToken
@10:12:51.8530 [ISCC] [connection 0000000001e85820] Sent to connection [708]2
@10:12:51.8530 [ISCC] Message [SD Request:00000325] is deleted
@10:12:51.8530 [ISCC] Message EventTransactionStatus [Event (To Client):00000326] is dequeued
@10:12:51.8530 [0] 8.1.101.83 send_to_client: message EventTransactionStatus
AttributeEventSequenceNumber 000000000000229a
AttributeTimeinuSecs 853000
AttributeTimeinSecs 1448370771 (10:12:51)
AttributeExtensions [379] 00 08 00 00..
'class.feature-set' 'iscc.transaction iscc.direction iscc.is-link-creation iscc.resource iscc.call-operation'
'class.name' 'iscc.transaction-route'
'iscc.direction.role' 'target'
'iscc.transaction' 'started'
'iscc.transaction.source-switch' 'Switch'
'iscc.transaction.state' 'in-progress'
'iscc.transaction.target-switch' 'SIPSwitchMVD1'
'object.id' '9S59AVML6T6E33947DNHVB677C06DLFU'
AttributeSubscriptionID 'FIGHL8LCD94CP3FMEVIL7G4EGK00002S'
10:12:51.853 Trc 04542 EventTransactionStatus sent to [748] (00000005 icon_voice 10.135.94.111:64245)
@10:12:51.8530 [ISCC] Message [Event (To Client):00000326] is deleted
@10:12:51.8530 [ISCC] Message EventTransactionStatus [Event (To Client):00000328] is dequeued
@10:12:51.8530 [0] 8.1.101.83 send_to_client: message EventTransactionStatus
AttributeEventSequenceNumber 000000000000229b
AttributeTimeinuSecs 853000
AttributeTimeinSecs 1448370771 (10:12:51)
AttributeExtensions [290] 00 07 00 00..
'iscc.is-link-creation' 'started'
'iscc.is-link-creation.is-link-id' '9S59AVML6T6E33947DNHVB677C06DLG0'
'iscc.is-link-creation.state' 'in-progress'
'iscc.resource' 'acquired'
'iscc.resource.name' 'erp3800003'
'iscc.transaction.state' 'in-progress'
'object.id' '9S59AVML6T6E33947DNHVB677C06DLFU'
AttributeSubscriptionID 'FIGHL8LCD94CP3FMEVIL7G4EGK00002S'
10:12:51.853 Trc 04542 EventTransactionStatus sent to [748] (00000005 icon_voice 10.135.94.111:64245)
@10:12:51.8530 [ISCC] Message [Event (To Client):00000328] is deleted
CGCL2Connector[924]: Connection with 10.135.94.12(10.135.94.12) established
10:12:52.110: CGSIPListener[0]: Accepted new connection on socket 924
10:12:52.146: Unable to resolve number for DN:59825186530
10:12:52.146: Unable to resolve number for DN:59825186530
10:12:52.146: trunk ip addr 10.135.94.12
10:12:52.146: Selected for Dn (geo-loc[]:partitionId[SipServerDefaultPartition]:cpdCapability[]): Service MSML (geo-loc[], priority[0], capacity 0 (0% of 0))
10:12:52.146: gateway 'trunk_to_avaya' associated with address '10.135.94.12'
10:12:52.146: $+NET:SIP::0:0
10:12:52.146: SIPS:LOGBLOCK:BEGIN:SIPMSG:[
10:12:52.146: SIPTR: Received [924,TCP] 1412 bytes from 10.135.94.12:25001 <<<<<
INVITE sip:3800003@10.135.92.29 SIP/2.0
From: "Vigliarolo, Juan Carlos" <sip:59825186530@sabre.com>;tag=80d8d96d9f95e51305455e435b600
To: sip:3800003@10.135.92.29
Call-ID: 80d8d96d9f95e51315455e435b600
CSeq: 1 INVITE
Max-Forwards: 71
Route: <sip:10.135.92.29;lr;phase=terminating;transport=tcp>
Record-Route: <sip:10.135.94.12;lr;transport=tcp>
Via: SIP/2.0/TCP 10.135.94.12;branch=z9hG4bK80d8d96d9f95e51325455e435b600
User-Agent: Avaya CM/R015x.02.1.016.4
Supported: timer, replaces, join, histinfo, 100rel
Allow: INVITE, CANCEL, BYE, ACK, PRACK, SUBSCRIBE, NOTIFY, REFER, OPTIONS, INFO, PUBLISH
Contact: "Vigliarolo, Juan Carlos" <sip:59825186530@10.135.94.12;transport=tcp>
Session-Expires: 1200;refresher=uac
Min-SE: 1200
P-Asserted-Identity: "Vigliarolo, Juan Carlos" <sip:59825186530@sabre.com>
Accept-Language: en
Content-Type: application/sdp
History-Info: <sip:3800003@10.135.92.29>;index=1
History-Info: "3800003" <sip:3800003@10.135.92.29>;index=1.1
Alert-Info: <cid:internal@invalid.unknown.domain>;avaya-cm-alert-type=internal
User-to-User: 00F7020002F509546573745061626C6FF40482878CB1FA08000131FF5654624E;encoding=hex
Content-Length: 235
v=0
o=- 1 1 IN IP4 10.135.94.12
s=-
c=IN IP4 10.137.39.20
b=AS:64
t=0 0
m=audio 18488 RTP/AVP 0 18 8 127
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:8 PCMA/8000
a=rtpmap:127 telephone-event/8000
10:12:52.150: Unable to resolve number for DN:59825186530
10:12:52.150: Unable to resolve number for DN:59825186530
10:12:52.150: trunk ip addr 10.135.94.12
10:12:52.150: Selected for Dn (geo-loc[]:partitionId[SipServerDefaultPartition]:cpdCapability[]): Service MSML (geo-loc[], priority[0], capacity 0 (0% of 0))
10:12:52.150: gateway 'trunk_to_avaya' associated with address '10.135.94.12'
10:12:52.150: CallMatcher: no call match attributes found
10:12:52.150: Unable to resolve number for DN:59825186530
10:12:52.150: Unable to resolve number for DN:59825186530
10:12:52.150: trunk ip addr 10.135.94.12
10:12:52.150: Selected for Dn (geo-loc[]:partitionId[SipServerDefaultPartition]:cpdCapability[]): Service MSML (geo-loc[], priority[0], capacity 0 (0% of 0))
10:12:52.150: gateway 'trunk_to_avaya' associated with address '10.135.94.12'
10:12:52.150: MarkAsLastUsed: Node not found for serviceDN 'dummy'
10:12:52.150: User-to-User=00F7020002F509546573745061626C6FF40482878CB1FA08000131FF5654624E;encoding=hex
10:12:52.150: ResolveCallInfo: set flag DIAL_PLAN_PROCESSING
10:12:52.150: SIPTR(5900): Begin step 0 - SipTransactionCreateCall(5901)
10:12:52.150 SIPCONN(59825186530): Create dialog
10:12:52.150: SipDialog: set monitor 0000000002a914e8
10:12:52.150 SIPCONN(59825186530): refer dialog 00000000028d7030 initiated
10:12:52.150 SIPCONN(59825186530): change transaction 0 -> 0
10:12:52.150: SipDialog: set monitor 0000000002a914e8
10:12:52.150 SIPCONN(59825186530): ClrMediaPeer
@10:12:52.1500 [BSYNC] Trace: Send to backup (sipserver_voice_mvd1) [712]:
message EventUserEvent
attr_#1005 0
attr_#1004 150
attr_#1003 1448370772
attr_#1002 8839
attr_#1001 1
attr_#1000 131072
attr_#15999 1
attr_#16704 1
attr_#16000 1
attr_#16500 [53] 31 3d 31 0d..
AttributeUserEvent [16099]
@10:12:52.1500 [BSYNC] Trace: Sent
10:12:52.150: HA:MESSAGE:TYPE[sipStackSync]: SYNCED
10:12:52.150: SIPDLG[12571]: register TRN[723492]
10:12:52.150: SIPDLG[12571]: TRN[723492] flags set to 0x1
10:12:52.150: SipDialog: event INVITE, t=723492, s=1, r=5, m=0000000002a914e8
10:12:52.150 SIPCONN(59825186530): HandleSipDialogEvent(INVITE)
10:12:52.150 SIPCONN(59825186530): Capabilities 2301ed
10:12:52.150 SIPCONN(59825186530): new transaction
10:12:52.150 SIPCONN(59825186530): store remote content
10:12:52.150 SIPCONN(59825186530): verify sdp
10:12:52.150 SIPCONN(59825186530): sdp state SDP_STATE_NULL, event SDP_EVENT_SDP
10:12:52.150 SIPCONN(59825186530): new sdp state SDP_STATE_OFFER_RECEIVED, event SDP_EVENT_SDP
10:12:52.150 SIPCONN(59825186530): state e:10,p:0,s:1,c:9,rc:0,m:0
10:12:52.150: SIPTR(5901): complete
10:12:52.150: SIPTR(5900): Step 0 - SipTransactionCreateCall(5901) complete
10:12:52.150: SIPTR(5900): Begin step 1 - SipTransactionResolveCallInfoByDialPlan(5902)
10:12:52.150: DialPlan:Not Found for dest 3800003
10:12:52.150: DialPlan: DialPlan found, but no rule defined for destination 3800003.
10:12:52.150: DialPlan: No rule applied, using original destination '3800003'.
10:12:52.150: DialPlan: clear flag DIAL_PLAN_PROCESSING
10:12:52.150: ERROR: 10000002, SipGetGeoLocationData( callId, result, overflow, oldPrefered), SipCall.cpp,1745
10:12:52.150: ProcessDialPlanResult: Connecting to DEVICE(81889,3800003).
10:12:52.150: Create regular call
@10:12:52.1500 {tscp.call {constructed} {uuid FIGHL8LCD94CP3FMEVIL7G4EGK0002IP} {connection-id 00e202732bfdd17a} {call-id 16785987}}
10:12:52.154 -- created call_info_tspp 00e202732bfdd17a@2ad1700
@10:12:52.1540 Call [00e202732bfdd17a]: urid 000100e2
@10:12:52.1540 [BSYNC] Trace: Send to backup (sipserver_voice_mvd1) [712]:
message RequestSetCallInfo
AttributeUpdateRevision 65762
AttributeConnID 00e202732bfdd17a
AttributeCallUUID 'D6C0ESQ1B978L6M3J9GSBB8QVS0000C7'
AttributeCallID 16785987
@10:12:52.1540 [BSYNC] Trace: Sent
10:12:52.154 -- created aTmCall 288afc0/2ad1700
10:12:52.154 -- TmCallThread created: 25ee790
10:12:52.154 SetANI for 288afc0:1: 59825186530
10:12:52.154 SetDNIS for 288afc0:1: 3800003
10:12:52.154: SIPCALL(16785987): add party '59825186530'
10:12:52.154 -- created party_info_tspp 2bbc120
10:12:52.154 -- created aTmParty 1aabeb0
10:12:52.154 SetRole: Origination for 59825186530.1aabeb0-288afc0:1
10:12:52.154 -- AddParty to 288afc0: 59825186530.1aabeb0-288afc0:1
10:12:52.154 -- new TSCP call leg 1
10:12:52.154 -- call leg created leg_id=1
10:12:52.154 CreateParty new external: 59825186530.1aabeb0-288afc0:1
10:12:52.154: Call 16785987 dn 59825186530 SetPartyId 16786786
10:12:52.154 SIPCONN(59825186530): set monitor 0000000002a91430, 0000000002911868
10:12:52.154 SIPCONN(3800003): set monitor 0000000002a90590, 0000000002910c68
10:12:52.154: SIPCALL(16785987): add party '3800003'
10:12:52.154 -- created party_info_tspp 2bbdb20
10:12:52.154 -- created aTmParty 1aaaa70
10:12:52.154 SetRole: Destination for 3800003.1aaaa70-288afc0:1
10:12:52.154 -- AddParty to 288afc0: 3800003.1aaaa70-288afc0:1
10:12:52.154 -- new TSCP call leg 2
10:12:52.154 -- call leg created leg_id=2
10:12:52.154 -- created NAData 2acbaa0
10:12:52.154 CreateParty new internal: 3800003.1aaaa70-288afc0:1
10:12:52.154: Call 16785987 dn 3800003 SetPartyId 16786787
10:12:52.154: Selected for Dn (geo-loc[]:partitionId[SipServerDefaultPartition]:cpdCapability[]): Service MSML (geo-loc[], priority[0], capacity 0 (0% of 0))
10:12:52.154: SIPTR(5902): complete
10:12:52.154: SIPTR(5900): Step 1 - SipTransactionResolveCallInfoByDialPlan(5902) complete
10:12:52.154: SIPTR(5900): Begin step 2 - SipTransactionChangePartyState(5903)
10:12:52.154: SIPTR(5903): complete
10:12:52.154: SIPTR(5900): Step 2 - SipTransactionChangePartyState(5903) complete
10:12:52.154: SIPTR(5900): Begin step 3 - SipTransactionGetOffer(5904)
10:12:52.154: SIPTR(5904): complete
10:12:52.154: SIPTR(5900): Step 3 - SipTransactionGetOffer(5904) complete
10:12:52.154: SIPTR(5900): Begin step 4 - SipTransactionConnectNewParty(5905)
Session value of the SDP is [1]
Version values of the SDP is [1]
10:12:52.154 SIPCONN(3800003): re-invite-null
10:12:52.154: SipDialog: set monitor 0000000002a90648
10:12:52.154 SIPCONN(3800003): main dialog 0 created, flags 0x21f
10:12:52.154 SIPCONN(3800003): Local contact: '<sip:59825186530@10.135.92.29:5060>'
10:12:52.154: add party info '3800003' state 0.
@10:12:52.1540 [BSYNC] Trace: Send to backup (sipserver_voice_mvd1) [712]:
message EventUserEvent
attr_#1005 0
attr_#1004 154
attr_#1003 1448370772
attr_#1002 8840
attr_#1001 1
attr_#1000 131072
attr_#15999 1
attr_#16704 1
attr_#16000 1
attr_#16500 [80] 31 3d 31 0d..
AttributeUserEvent [16099]
@10:12:52.1540 [BSYNC] Trace: Sent
10:12:52.154: HA:MESSAGE:TYPE[sipStackSync]: SYNCED
10:12:52.154: SIPDLG[12572]: register TRN[723493]
10:12:52.154: SIPDLG[12572]: TRN[723493] flags set to 0x6
10:12:52.154: Sending [0,UDP] 1264 bytes to 10.135.91.182:5060 >>>>>
INVITE sip:3800003@10.135.91.182:5060 SIP/2.0
From: "Anonymous" <sip:anonymous@anonymous.invalid>;tag=76FF9BE5-C8E9-4569-88E9-4FBC59508A09-660294
To: sip:3800003@10.135.92.29:5060
Call-ID: E59CC5B6-AEB0-494E-A6C1-53BAA0009636-656863@10.135.92.29
CSeq: 1 INVITE
Content-Length: 134
Content-Type: application/sdp
Via: SIP/2.0/UDP 10.135.92.29:5060;branch=z9hG4bKE6D30701-3DF7-4FF3-B296-DD5C0F19ED7F-10811
Contact: <sip:59825186530@10.135.92.29:5060>
Allow: ACK, BYE, CANCEL, INFO, INVITE, MESSAGE, NOTIFY, OPTIONS, PRACK, REFER, UPDATE
User-to-User: 00F7020002F509546573745061626C6FF40482878CB1FA08000131FF5654624E;encoding=hex
User-Agent: Avaya CM/R015x.02.1.016.4
Accept-Language: en
History-Info: <sip:3800003@10.135.92.29>;index=1
History-Info: "3800003" <sip:3800003@10.135.92.29>;index=1.1
Alert-Info: <cid:internal@invalid.unknown.domain>;avaya-cm-alert-type=internal
Max-Forwards: 70
X-Genesys-CallUUID: D6C0ESQ1B978L6M3J9GSBB8QVS0000C7
Privacy: id
P-Asserted-Identity: "Vigliarolo, Juan Carlos" <sip:59825186530@sabre.com>
Session-Expires: 1800;refresher=uac
Min-SE: 90
Supported: uui,100rel,timer
v=0
o=- 1446597765 1 IN IP4 10.137.39.20
s=-
c=IN IP4 10.137.39.20
b=AS:64
t=0 0
m=audio 18488 RTP/AVP 8
a=rtpmap:8 PCMA/8000
10:12:52.158: SipDialog: event SEND_INVITE, t=723493, s=2, r=7, m=0000000002a90648
10:12:52.158 SIPCONN(3800003): HandleSipDialogEvent(SEND_INVITE) - filtered
10:12:52.158 SIPCONN(3800003): sdp state SDP_STATE_NULL, event SDP_EVENT_OFFER_SENT
10:12:52.158 SIPCONN(3800003): new sdp state SDP_STATE_OFFER_SENT, event SDP_EVENT_OFFER_SENT
10:12:52.158: SIPS:LOGBLOCK:END:SIPMSG:]
10:12:52.158: $-NET:SIP::0:10401
10:12:52.214: $+NET:SIP::0:0
10:12:52.214: SIPTR: Received [0,UDP] 396 bytes from 10.135.91.182:5060 <<<<<
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 10.135.92.29:5060;branch=z9hG4bKE6D30701-3DF7-4FF3-B296-DD5C0F19ED7F-10811
From: "Anonymous" <sip:anonymous@anonymous.invalid>;tag=76FF9BE5-C8E9-4569-88E9-4FBC59508A09-660294
To: <sip:3800003@10.135.92.29:5060>
Call-ID: E59CC5B6-AEB0-494E-A6C1-53BAA0009636-656863@10.135.92.29
CSeq: 1 INVITE
User-Agent: Yealink SIP-T23P 44.80.0.88
Content-Length: 0
10:12:52.214: SipDialog: event CALLING_RESPROV, t=723493, s=2, r=5, m=0000000002a90648
10:12:52.214 SIPCONN(3800003): HandleSipDialogEvent(CALLING_RESPROV)
10:12:52.214 SIPCONN(3800003): Capabilities 61013f
10:12:52.214 SIPCONN(3800003): reliable=0
10:12:52.214 SIPCONN(3800003): store remote content
10:12:52.214 SIPCONN(3800003): store remote content - trying ignored
10:12:52.214 SIPCONN(3800003): Trying Timer for 32000 mlsec started...
10:12:52.214: $-NET:SIP::0:131
@10:12:52.2740 [ISCC] [connection 0000000001e85820] Received from connection [708]2
@10:12:52.2740 [ISCC] Received from server tserver@Switch/0: message ISCCEventAttachedDataChanged
ISCCAttributeOperationUUID '9S59AVML6T6E33947DNHVB677C06DLG2'
ISCCAttributeUpdateRevision 524398 [0008006e]
ISCCAttributeUserData
'RRequestedSkills'(list)
'CustomerSegment' 'default'
'ServiceType' 'default'
'ServiceObjective' ''
'key2' 'value2'
'key1' 'value1'
'PegDEF' 1
'RVQID' ''
'RVQDBID' ''
'RTargetTypeSelected' '2'
'RTargetRuleSelected' ''
'RTargetObjectSelected' 'Testing'
'RTargetObjSelDBID' '124'
'RTargetAgentSelected' 'sipagent1'
'RTargetPlaceSelected' 'SIP3800003'
'RTenant' 'Resources'
'RStrategyName' 'migration-tserver-sipserver-test'
'RStrategyDBID' '3279'
'CBR-actual_volume' ''
'CBR-Interaction_cost' ''
'CBR-contract_DBIDs' ''
'CBR-IT-path_DBIDs' ''
'RRequestedSkillCombination' ''
'RTargetRequested' 'Testing'
'PegAGTesting' 1
ISCCAttributeConnID 006e026dff8f6ae0
ISCCAttributeTrackingID 7218574 [006e258e]
@10:12:52.2780 [ISCC] tep disconnected: inbound event postponed ISCCEventAttachedDataChanged
10:12:52.350: Sending [924,TCP] 319 bytes to 10.135.94.12:25001 >>>>>
SIP/2.0 100 Trying
From: "Vigliarolo, Juan Carlos" <sip:59825186530@sabre.com>;tag=80d8d96d9f95e51305455e435b600
To: sip:3800003@10.135.92.29
Call-ID: 80d8d96d9f95e51315455e435b600
CSeq: 1 INVITE
Via: SIP/2.0/TCP 10.135.94.12;branch=z9hG4bK80d8d96d9f95e51325455e435b600;received=10.135.94.12
Content-Length: 0
10:12:52.358: $+NET:SIP::0:0
10:12:52.358: SIPTR: Received [0,UDP] 625 bytes from 10.135.91.182:5060 <<<<<
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 10.135.92.29:5060;branch=z9hG4bKE6D30701-3DF7-4FF3-B296-DD5C0F19ED7F-10811
From: "Anonymous" <sip:anonymous@anonymous.invalid>;tag=76FF9BE5-C8E9-4569-88E9-4FBC59508A09-660294
To: <sip:3800003@10.135.92.29:5060>;tag=2361909949
Call-ID: E59CC5B6-AEB0-494E-A6C1-53BAA0009636-656863@10.135.92.29
CSeq: 1 INVITE
Contact: <sip:3800003@10.135.91.182:5060>
Allow: INVITE, INFO, PRACK, ACK, BYE, CANCEL, OPTIONS, NOTIFY, REGISTER, SUBSCRIBE, REFER, PUBLISH, UPDATE, MESSAGE
User-Agent: Yealink SIP-T23P 44.80.0.88
Allow-Events: talk,hold,conference,refer,check-sync
Content-Length: 0
10:12:52.362: SipDialog: event CALLING_RESPROV, t=723493, s=2, r=5, m=0000000002a90648
10:12:52.362 SIPCONN(3800003): HandleSipDialogEvent(CALLING_RESPROV)
10:12:52.362 SIPCONN(3800003): Trying Timer deleted...
10:12:52.362 SIPCONN(3800003): Capabilities 6103ff
10:12:52.362 SIPCONN(3800003): reliable=0
10:12:52.362 SIPCONN(3800003): store remote content
10:12:52.362 SIPCONN(3800003): skip SDP pocessing [0/SDP_STATE_OFFER_SENT]
10:12:52.362 SIPCONN(59825186530): 18x response, state 1,1,0,0,1
10:12:52.362 SIPCONN(59825186530): SendResponse(180,723492)
10:12:52.362: add party info '59825186530' state 1.
10:12:52.362: Sending [924,TCP] 631 bytes to 10.135.94.12:25001 >>>>>
SIP/2.0 180 Ringing
From: "Vigliarolo, Juan Carlos" <sip:59825186530@sabre.com>;tag=80d8d96d9f95e51305455e435b600
To: sip:3800003@10.135.92.29;tag=76FF9BE5-C8E9-4569-88E9-4FBC59508A09-660293
Call-ID: 80d8d96d9f95e51315455e435b600
CSeq: 1 INVITE
Record-Route: <sip:10.135.94.12;lr;transport=tcp>
Via: SIP/2.0/TCP 10.135.94.12;branch=z9hG4bK80d8d96d9f95e51325455e435b600;received=10.135.94.12
X-Genesys-CallUUID: D6C0ESQ1B978L6M3J9GSBB8QVS0000C7
Allow: INVITE, ACK, PRACK, CANCEL, BYE, UPDATE
User-Agent: Yealink SIP-T23P 44.80.0.88
Allow-Events: talk,hold,conference,refer,check-sync
Supported: uui
Content-Length: 0
10:12:52.362: SipDialog: event CALLED_RESPROV, t=723492, s=1, r=6, m=0000000002a914e8
10:12:52.362 SIPCONN(59825186530): HandleSipDialogEvent(CALLED_RESPROV) - filtered
10:12:52.362 SIPCONN(3800003): state e:2,p:0,s:2,c:9,rc:0,m:1
10:12:52.362 SIPPARTY(3800003): 16786787 verify update of party-connection state N-A
10:12:52.362 SIPPARTY(3800003): 16786787 update party-connection state N-A
10:12:52.362: Adding OtherTrunkName(trunk_to_avaya): done
10:12:52.362 ClearContext: party 3800003.1aaaa70-288afc0:1
10:12:52.362 +++ CIFace::Event +++
+++ Pre-event +++
Type EventRinging
Devices: <3800003/3800003> <-/59825186530> <-/->
Calls: 16785987/00e202732bfdd17a/16785987.288afc0/c:0/r:0 0/none
Parties: D3800003/3800003.1aaaa70-288afc0:1/l:2/r:0/Null,DNull,Destination
X59825186530/59825186530.1aabeb0-288afc0:1/l:1/r:0/Null,Origination
none
Flags: divert=0 hook=0 postCall=0 active=1 moveAll=1 callType=1 hideOtherPi=0 InternalOther=0
--- Pre-event ---
+++ Ringing +++
SetDialling: party 59825186530.1aabeb0-288afc0:1, cause Null
-- party_info 59825186530.2bbc120 state change: from <Null> to <Connected,Dialing>
-- AgnEmu: business type defaults to private for agent
-- AgnEmu: private call BusinessType[1/1:0]
-- aTmCall::SetBusinessType():00e202732bfdd17a set to BusinessType[1/1:0]
-- aTmParty::SetBusinessType():3800003 set to BusinessType[1/1:0]
-- G7 dialing
-- call_info::committed created
@10:12:52.3620 [ISCC] Call object is created: c:00e202732bfdd17a,0000000002ad1700
@10:12:52.3620 [ISCC] Call created:
+ c:00e202732bfdd17a,0000000002ad1700 @ m:0000000000000000,0000000000000000 p:0 i:01002243 nw:0000000000000000 t:2
@10:12:52.3620 [BSYNC] Trace: Send to backup (sipserver_voice_mvd1) [712]:
message RequestSetCallInfo
AttributeUpdateRevision 65762
AttributePropagatedCallType 2
AttributeConnID 00e202732bfdd17a
@10:12:52.3620 [BSYNC] Trace: Sent
@10:12:52.3620 [0] 8.1.101.83 distribute call/party event: message EventCallCreated
AttributeEventSequenceNumber 000000000000229c
AttributeTimeStamp 5654625400058610
AttributeANI '59825186530'
AttributeMediaType 0
AttributeCallType 2
AttributeCallID 16785987
AttributeConnID 00e202732bfdd17a
AttributeCallUUID 'D6C0ESQ1B978L6M3J9GSBB8QVS0000C7'
AttributeDNIS '3800003'
10:12:52.362 Int 04544 Interaction message "EventCallCreated" generated
10:12:52.362 Trc 04542 EventCallCreated sent to [748] (00000005 icon_voice 10.135.94.111:64245)
@10:12:52.3620 [ISCC] Party added [ddp view]:
@ c:00e202732bfdd17a,0000000002ad1700 @ m:0000000000000000,0000000000000000,0000000000000000 p:2 i:01002243 nw:00000000:0000000000000000 t:2
+ p:0000000002bbc120 @ c:00e202732bfdd17a,0000000002ad1700 r:1 t:1 s:1000c n:59825186530
p:0000000002bbdb20 @ c:00e202732bfdd17a,0000000002ad1700 r:2 t:0 s:0 n:3800003
@10:12:52.3660 [ISCC] Party object is created: p:0000000000000000,0000000002bbc120 n:59825186530:
@10:12:52.3660 [ISCC] Party added:
@ c:00e202732bfdd17a,0000000002ad1700 @ m:0000000000000000,0000000000000000 p:1 i:01002243 nw:0000000000000000 t:2
+ p:0000000000000000,0000000002bbc120 @ c:00e202732bfdd17a,0000000002ad1700 r:1 ----- n:59825186530:
@10:12:52.3660 [BSYNC] Trace: Send to backup (sipserver_voice_mvd1) [712]:
message EventCallPartyState
AttributeDN '59825186530'
AttributePartyUUID 'FIGHL8LCD94CP3FMEVIL7G4EGK0002IQ'
AttributePartyID 40e202732bfdd2a9
AttributeConnID 00e202732bfdd17a
@10:12:52.3660 [BSYNC] Trace: Sent
@10:12:52.3660 [0] 8.1.101.83 distribute call/party event: message EventCallPartyAdded
AttributeEventSequenceNumber 000000000000229d
AttributeTimeStamp 56546254000595b0
AttributeDN '59825186530'
AttributeMediaType 0
AttributePartyState 0x1000c
AttributePartyType 1
AttributeConnID 00e202732bfdd17a
AttributeCallUUID 'D6C0ESQ1B978L6M3J9GSBB8QVS0000C7'
AttributePartyUUID 'FIGHL8LCD94CP3FMEVIL7G4EGK0002IQ'
10:12:52.366 Int 04544 Interaction message "EventCallPartyAdded" generated
10:12:52.366 Trc 04542 EventCallPartyAdded sent to [748] (00000005 icon_voice 10.135.94.111:64245)
SetDelivered: party 3800003.1aaaa70-288afc0:1, cause Null
-- TellDelivered
-- party_info 3800003.2bbdb20 state change: from <Null> to <Alerting>
-- aTmParty::SetBusinessType():3800003 set to BusinessType[1/1:0]
-- G7 ringing
-- call delivered
@10:12:52.3660 [ISCC] Party added [ddp view]:
@ c:00e202732bfdd17a,0000000002ad1700 @ m:0000000000000000,0000000000000000,0000000000000000 p:2 i:01002243 nw:00000000:0000000000000000 t:2
p:0000000002bbc120 @ c:00e202732bfdd17a,0000000002ad1700 r:1 t:1 s:1000c n:59825186530
+ p:0000000002bbdb20 @ c:00e202732bfdd17a,0000000002ad1700 r:2 t:0 s:a n:3800003
@10:12:52.3660 [ISCC] Party object is created: p:0000000000000000,0000000002bbdb20 n:3800003:
@10:12:52.3660 [ISCC] Party added:
@ c:00e202732bfdd17a,0000000002ad1700 @ m:0000000000000000,0000000000000000 p:2 i:01002243 nw:0000000000000000 t:2
p:0000000000000000,0000000002bbc120 @ c:00e202732bfdd17a,0000000002ad1700 r:1 ----- n:59825186530:
+ p:0000000000000000,0000000002bbdb20 @ c:00e202732bfdd17a,0000000002ad1700 r:2 ----- n:3800003:
@10:12:52.3660 [BSYNC] Trace: Send to backup (sipserver_voice_mvd1) [712]:
message EventCallPartyState
AttributeDN '3800003'
AttributePartyUUID 'FIGHL8LCD94CP3FMEVIL7G4EGK0002IR'
AttributePartyID 40e202732bfdd2aa
AttributeConnID 00e202732bfdd17a
@10:12:52.3660 [BSYNC] Trace: Sent
@10:12:52.3660 [0] 8.1.101.83 distribute call/party event: message EventCallPartyAdded
AttributeEventSequenceNumber 000000000000229e
AttributeTimeStamp 56546254000595b0
AttributeDBID 3566
AttributeDN '3800003'
AttributeMediaType 0
AttributePartyState 0xa
AttributePartyType 0
AttributeConnID 00e202732bfdd17a
AttributeCallUUID 'D6C0ESQ1B978L6M3J9GSBB8QVS0000C7'
AttributePartyUUID 'FIGHL8LCD94CP3FMEVIL7G4EGK0002IR'
10:12:52.366 Int 04544 Interaction message "EventCallPartyAdded" generated
10:12:52.366 Trc 04542 EventCallPartyAdded sent to [748] (00000005 icon_voice 10.135.94.111:64245)
@10:12:52.3660 [0] 8.1.101.83 distribute_event: message EventRinging
AttributeEventSequenceNumber 000000000000229f
AttributeTimeinuSecs 366000
AttributeTimeinSecs 1448370772 (10:12:52)
AttributeExtensions [70] 00 03 00 00..
'OtherTrunkName' 'trunk_to_avaya'
'PRIVACY' 'id'
'BusinessCall' 0
AttributeThisDNRole 2
AttributeAgentID 'sipagent1'
AttributeThisDN '3800003'
AttributeANI '59825186530'
AttributeDNIS '3800003'
AttributeCallUUID 'D6C0ESQ1B978L6M3J9GSBB8QVS0000C7'
AttributeConnID 00e202732bfdd17a
AttributeCallID 16785987
AttributeLocalCallType 2
AttributeCallType 2
AttributeCallState 0
AttributeOtherDNRole 1
AttributeOtherDN '59825186530'
10:12:52.366 Int 04544 Interaction message "EventRinging" generated
10:12:52.366 Trc 04542 EventRinging sent to [904] (00000021 wde_sipagent1 10.135.91.230:60565)
10:12:52.366 Trc 04542 EventRinging sent to [756] (00000007 statserver_routing_mvd1 10.135.94.113:49238)
10:12:52.366 Trc 04542 EventRinging sent to [768] (0000000a reporting_statserver 10.135.94.212:54979)
10:12:52.366 Trc 04542 EventRinging sent to [736] (00000004 mosaic_statserver 10.135.94.212:54978)
10:12:52.366 Trc 04542 EventRinging sent to [760] (00000008 urs_statserver 10.135.94.211:55495)
10:12:52.366 Trc 04542 EventRinging sent to [748] (00000005 icon_voice 10.135.94.111:64245)
-- Cleanup call leg
--- Ringing ---
+++ Post-event +++
Type EventRinging
Devices: <3800003/3800003> <-/59825186530> <-/->
Calls: 16785987/00e202732bfdd17a/16785987.288afc0/c:2/r:0 0/none
Parties: D3800003/3800003.1aaaa70-288afc0:1/l:2/r:0/Alerting,DNull,Destination
X59825186530/59825186530.1aabeb0-288afc0:1/l:1/r:0/Dialing,Origination
none
Call type: Inbound/2
Flags: divert=0 hook=0 postCall=0 active=1 moveAll=1 callType=1 hideOtherPi=0 InternalOther=0
--- Post-event ---
10:12:52.366 --- CIFace::Event ---
10:12:52.366: SIPTR(5905): complete
10:12:52.366: SIPTR(5900): Step 4 - SipTransactionConnectNewParty(5905) complete
10:12:52.366: SIPTR(5900): complete
10:12:52.366: SIPCM: transaction SipScenario(5900) complete
10:12:52.366: PI: 00 S[IN]D[59825186530]C[*D[59825186530]]P[3800003]
10:12:52.366: PI: 00 S[AN]D[3800003]C[*D[3800003]]P[59825186530]
10:12:52.366: CALLSTATE(a:2,d:0,i:0,e:1,r:0,o:0)
10:12:52.366: -----------------------------------------------------------
10:12:52.366: C[16785987]:CF[D[[0]]:SC[5900]
10:12:52.366: P[16786786]

[59825186530[81888]]:LID[0]:D2[3800003[81889]]:
10:12:52.366 SIPCONN(59825186530): endPoint :CON[16787857]STATE[1]:PEER[16787858]

[59825186530[81888]]:DLG[12571 STATE[1]]:D2[[0]:TD[723492[IM[yes]]]:RC
10:12:52.366: P[16786787]

[3800003[81889]]:LID[0]:D2[59825186530[81888]]:
10:12:52.366 SIPCONN(3800003): endPoint :CON[16787858]STATE[2]:PEER[16787857]

[3800003[81889]]:DLG[12572 STATE[2]]:D2[59825186530[81888]:TD[723493[IM[yes]]]:LC
10:12:52.366: HA: BEGIN SYNC: CALL[16785987] SYNCTYPE[1]: SECOND CALL[0] SYNCTYPE[-1]
@10:12:52.3660 [BSYNC] Trace: Send to backup (sipserver_voice_mvd1) [712]:
message EventUserEvent
attr_#1005 0
attr_#1004 366
attr_#1003 1448370772
attr_#1002 8841
attr_#1001 1
attr_#1000 131072
attr_#15999 1
attr_#16704 1
attr_#16000 1
attr_#16500 [2630] 31 3d 33 0d..
AttributeUserEvent [16099]
@10:12:52.3660 [BSYNC] Trace: Sent
10:12:52.366: HA:MESSAGE:TYPE[sipStackSync]: SYNCED
@10:12:52.3660 [BSYNC] Trace: Send to backup (sipserver_voice_mvd1) [712]:
message EventUserEvent
attr_#1005 0
attr_#1004 366
attr_#1003 1448370772
attr_#1002 8842
attr_#1001 1
attr_#1000 131072
attr_#15999 1
attr_#16704 1
attr_#16000 1
attr_#16500 [3002] 31 3d 33 0d..
AttributeUserEvent [16099]
@10:12:52.3660 [BSYNC] Trace: Sent
10:12:52.366: HA:MESSAGE:TYPE[sipStackSync]: SYNCED
10:12:52.366: HA: END SYNC: CALL[16785987] SYNCTYPE[1]: SECOND CALL[0] SYNCTYPE[-1]
10:12:52.366 -- created NAData 2acbb90
@10:12:52.3700 [BSYNC] Trace: Send to backup (sipserver_voice_mvd1) [712]:
message EventUserEvent
attr_#1005 0
attr_#1004 370
attr_#1003 1448370772
attr_#1002 8843
attr_#1001 1
attr_#1000 131072
attr_#15999 1
attr_#16704 1
attr_#16000 1
attr_#16102 [1104] 32 3a 31 7c..
attr_#16101 [1340] 32 3a 31 7c..
attr_#16100 [246] 31 3a 31 7c..
AttributeUserEvent [16001]
@10:12:52.3700 [BSYNC] Trace: Sent
10:12:52.370: HA:MESSAGE:TYPE[callSync:]: SYNCED
10:12:52.370: call1 16785987 idle
10:12:52.370: $-NET:SIP::0:9562
@10:12:52.3740 [BSYNC] Trace: Received [712]:
message EventUserEvent
AttributeUserEvent [16002]
attr_#16100 [22] 31 3a 31 7c..
attr_#16000 1
attr_#16704 0
attr_#15999 1
attr_#1000 131072
attr_#1001 1
attr_#1002 3812
attr_#1003 1448370772
attr_#1004 340
attr_#1005 0
10:12:52.374: SIPTS:<< SYNC(16002) <<
10:12:52.374: $+NET:CTI:callSyncAck:2236
10:12:52.378: HA:MESSAGE:TYPE[callSyncAck]:RECEIVED
10:12:52.378: HA:MESSAGE:TYPE[callSyncAck]:RECEIVED
10:12:52.378: HA:MESSAGE:TYPE[callSyncAck]:PROCESSED
10:12:52.378: $-NET:CTI:callSyncAck:2237
10:12:55.024 Trc 04541 RequestAnswerCall received from [904] (00000021 wde_sipagent1 10.135.91.230:60565)
message RequestAnswerCall
AttributeReferenceID 109
AttributeThisDN '3800003'
AttributeConnID 00e202732bfdd17a
10:12:55.024 Int 04543 Interaction message "RequestAnswerCall" received from 904 ("wde_sipagent1")
10:12:55.024 -- created: CRequest@2a2a8d0 RequestAnswerCall-wde_sipagent1[904]/109
10:12:55.024: $+TLIB:CTI:Unknown:0:45940639690
10:12:55.024 +++ CIFace::Request +++
-- new invoke
-- thisCall by party
Parsed: RequestAnswerCall
From: wde_sipagent1[904]/109
Numbers: +<3800003> -<none>
Calls: 288afc0:1 none
Parties: 3800003.1aaaa70-288afc0:1
none
Status: parsed:1 queued:0 sent:0 acked:0 preevent:0 event:0 context:0 transferred:0
-----
-- validate
-- state check: ok
CIFace: Sent CRequest@2a2a8d0 RequestAnswerCall-wde_sipagent1[904]/109
TNAEmulator::NotifyBackup()
@10:12:55.0240 [BSYNC] Trace: Send to backup (sipserver_voice_mvd1) [712]:
message EventUserEvent
attr_#1005 0
attr_#1004 24
attr_#1003 1448370775
attr_#1002 8844
attr_#1001 5
attr_#1000 131072
AttributeAgentWorkMode 0 (Unknown)
AttributeAgentStateReasonUnused 0
AttributeThisDN '3800003'
@10:12:55.0240 [BSYNC] Trace: Sent
-- NAData ClRq added
FinishRequest CRequest@2a2a8d0 RequestAnswerCall-wde_sipagent1[904]/109
IFace stats: q=0 s=0
-- complete
-- NAData ClRq removed
TNAEmulator::NotifyBackup()
@10:12:55.0240 [BSYNC] Trace: Send to backup (sipserver_voice_mvd1) [712]:
message EventUserEvent
attr_#1005 0
attr_#1004 24
attr_#1003 1448370775
attr_#1002 8845
attr_#1001 5
attr_#1000 131072
AttributeAgentWorkMode 0 (Unknown)
AttributeAgentStateReasonUnused 1
AttributeThisDN '3800003'
@10:12:55.0240 [BSYNC] Trace: Sent
10:12:55.024: SIPDLG[12572]: register TRN[723494]
10:12:55.024: Sending [0,UDP] 502 bytes to 10.135.91.182:5060 >>>>>
NOTIFY sip:3800003@10.135.91.182:5060 SIP/2.0
From: "Anonymous" <sip:anonymous@anonymous.invalid>;tag=76FF9BE5-C8E9-4569-88E9-4FBC59508A09-660294
To: <sip:3800003@10.135.92.29:5060>;tag=2361909949
Call-ID: E59CC5B6-AEB0-494E-A6C1-53BAA0009636-656863@10.135.92.29
CSeq: 2 NOTIFY
Content-Length: 0
Via: SIP/2.0/UDP 10.135.92.29:5060;branch=z9hG4bKE6D30701-3DF7-4FF3-B296-DD5C0F19ED7F-10812
Contact: <sip:59825186530@10.135.92.29:5060>
Event: talk
Subscription-State: active
Max-Forwards: 70
10:12:55.024: SipDialog: event SEND_EARLY_REQ, t=723494, s=2, r=5, m=0000000002a90648
10:12:55.024 SIPCONN(3800003): HandleSipDialogEvent(SEND_EARLY_REQ) - filtered
10:12:55.024 --- CIFace::Request ---
10:12:55.024: $-TLIB:CTI:Unknown:0:1001
10:12:55.084: $+NET:SIP::0:0
10:12:55.084: SIPTR: Received [0,UDP] 450 bytes from 10.135.91.182:5060 <<<<<
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.135.92.29:5060;branch=z9hG4bKE6D30701-3DF7-4FF3-B296-DD5C0F19ED7F-10812
From: "Anonymous" <sip:anonymous@anonymous.invalid>;tag=76FF9BE5-C8E9-4569-88E9-4FBC59508A09-660294
To: <sip:3800003@10.135.92.29:5060>;tag=2361909949
Call-ID: E59CC5B6-AEB0-494E-A6C1-53BAA0009636-656863@10.135.92.29
CSeq: 2 NOTIFY
Contact: <sip:3800003@10.135.91.182:5060>
User-Agent: Yealink SIP-T23P 44.80.0.88
Content-Length: 0
10:12:55.084: SipDialog: event EARLY_RES, t=723494, s=2, r=5, m=0000000002a90648
10:12:55.084 SIPCONN(3800003): HandleSipDialogEvent(EARLY_RES)
10:12:55.084 SIPCONN(3800003): concurrent transaction: c:723493, n:723494
10:12:55.084 SIPCONN(3800003): event ignored
10:12:55.084: $-NET:SIP::0:143
10:12:55.116: $+NET:SIP::0:0
10:12:55.116: SIPTR: Received [0,UDP] 812 bytes from 10.135.91.182:5060 <<<<<
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.135.92.29:5060;branch=z9hG4bKE6D30701-3DF7-4FF3-B296-DD5C0F19ED7F-10811
From: "Anonymous" <sip:anonymous@anonymous.invalid>;tag=76FF9BE5-C8E9-4569-88E9-4FBC59508A09-660294
To: <sip:3800003@10.135.92.29:5060>;tag=2361909949
Call-ID: E59CC5B6-AEB0-494E-A6C1-53BAA0009636-656863@10.135.92.29
CSeq: 1 INVITE
Contact: <sip:3800003@10.135.91.182:5060>
Content-Type: application/sdp
Allow: INVITE, INFO, PRACK, ACK, BYE, CANCEL, OPTIONS, NOTIFY, REGISTER, SUBSCRIBE, REFER, PUBLISH, UPDATE, MESSAGE
User-Agent: Yealink SIP-T23P 44.80.0.88
Allow-Events: talk,hold,conference,refer,check-sync
Content-Length: 157
v=0
o=- 20112 20112 IN IP4 10.135.91.182
s=SDP data
c=IN IP4 10.135.91.182
t=0 0
m=audio 12118 RTP/AVP 8
a=rtpmap:8 PCMA/8000
a=ptime:20
a=sendrecv
10:12:55.120: SipDialog: event CALLING_RESOK, t=723493, s=7, r=5, m=0000000002a90648
10:12:55.120 SIPCONN(3800003): HandleSipDialogEvent(CALLING_RESOK)
10:12:55.120 SIPCONN(3800003): store remote content
10:12:55.120 SIPCONN(3800003): sdp state SDP_STATE_OFFER_SENT, event SDP_EVENT_SDP
10:12:55.120 SIPCONN(3800003): new sdp state SDP_STATE_NULL, event SDP_EVENT_SDP
10:12:55.120 SIPCONN(3800003): 1pcc event CALLING_RESOK
10:12:55.120 SIPCONN(3800003): SetChargingState: true
10:12:55.120: SIPTR(5906): Begin step 0 - SipTransactionConnectCompleteAnswer(5907)
10:12:55.120 SIPCONN(3800003): re-invite-connected-accepted
10:12:55.120 SIPCONN(59825186530): re-invite-called-initiated
10:12:55.120 SIPCONN(3800003): GetAnswer
10:12:55.120 SIPCONN(3800003): NotifyResponseOnAnswer
10:12:55.120 SIPCONN(59825186530): SendAnswer
Session value of the SDP is [20112]
Version values of the SDP is [20112]
10:12:55.120 SIPCONN(59825186530): SendAnswer::Response
10:12:55.120 SIPCONN(59825186530): SendResponse(200,723492)
10:12:55.120: add party info '59825186530' state 1.
10:12:55.120: Sending [924,TCP] 958 bytes to 10.135.94.12:25001 >>>>>
SIP/2.0 200 OK
From: "Vigliarolo, Juan Carlos" <sip:59825186530@sabre.com>;tag=80d8d96d9f95e51305455e435b600
To: sip:3800003@10.135.92.29;tag=76FF9BE5-C8E9-4569-88E9-4FBC59508A09-660293
Call-ID: 80d8d96d9f95e51315455e435b600
CSeq: 1 INVITE
Record-Route: <sip:10.135.94.12;lr;transport=tcp>
Via: SIP/2.0/TCP 10.135.94.12;branch=z9hG4bK80d8d96d9f95e51325455e435b600;received=10.135.94.12
Contact: <sip:10.135.92.29:5060;transport=tcp>
X-Genesys-CallUUID: D6C0ESQ1B978L6M3J9GSBB8QVS0000C7
Allow: INVITE, ACK, PRACK, CANCEL, BYE, REFER, INFO, UPDATE, MESSAGE, NOTIFY, OPTIONS
User-Agent: Yealink SIP-T23P 44.80.0.88
Allow-Events: talk,hold,conference,refer,check-sync
Session-Expires: 1200;refresher=uac
Require: timer
Supported: uui,timer
Content-Type: application/sdp
Content-Length: 153
v=0
o=- 1446597757 1 IN IP4 10.135.91.182
s=SDP
c=IN IP4 10.135.91.182
t=0 0
m=audio 12118 RTP/AVP 8
a=ptime:20
a=sendrecv
a=rtpmap:8 PCMA/8000
10:12:55.120: SipDialog: event CALLED_RESOK, t=723492, s=7, r=5, m=0000000002a914e8
10:12:55.120 SIPCONN(59825186530): HandleSipDialogEvent(CALLED_RESOK) - filtered
10:12:55.120 SIPCONN(59825186530): TRCLR(723492)
10:12:55.120 SIPCONN(59825186530): sdp state SDP_STATE_OFFER_RECEIVED, event SDP_EVENT_ANSWER_SENT
10:12:55.120 SIPCONN(59825186530): new sdp state SDP_STATE_NULL, event SDP_EVENT_ANSWER_SENT
10:12:55.120 SIPCONN(3800003): state e:3,p:2,s:3,c:0,rc:0,m:1
10:12:55.120 SIPCONN(3800003): SetChargingState: true
10:12:55.120 SIPCONN(59825186530): CheckUpdateTransferStatus: no original dialog
10:12:55.120 SIPPARTY(3800003): 16786787 verify update of party-connection state A-C
10:12:55.120: Adding OtherTrunkName(trunk_to_avaya): done
10:12:55.120 -- thisCall by party
10:12:55.120 SetContext: for party 3800003.1aaaa70-288afc0:1
10:12:55.120 +++ CIFace::Event +++
+++ Pre-event +++
Type EventEstablished
Devices: <3800003/3800003> <-/59825186530> <-/->
Calls: 16785987/00e202732bfdd17a/16785987.288afc0/c:2/r:1 0/none
Parties: D3800003/3800003.1aaaa70-288afc0:1/l:2/r:1/Alerting,DNull,Destination