Hello,
Here are URS logs :
request to 65200(TServer) message RequestRegisterAddress
AttributeReferenceID 4
AttributeExtensions [51] 00 02 00 00..
'SWITCH' 'Alcatel_A4400'
'CUSTOMER_ID' 'Resources'
AttributeAddressType 4 (RouteDN)
AttributeControlMode 0
AttributeRegisterMode 0
AttributeThisDN '361836'
..sent to nestor:7001(fd=572)
received from 65200(TServer)nestor:7001(fd=572) message EventRegistered
AttributeThisDN '361836'
AttributeAddressInfoType 8 (AddressInfoAddressType)
AttributeAddressType 3 (Queue)
AttributeAddressInfoStatus 3
AttributeExtensions [17] 00 01 01 00..
'status' 0
AttributeReferenceID 4
AttributeTimeinSecs 1301901782 (09:23:02)
AttributeTimeinuSecs 140000
AttributeEventSequenceNumber 000000000000002f
09:23:02.140_T_I_ [14:32] EventRegistered is received for tserver TServer[Alcatel_A4400] (this dn=361836)
received from 65200(TServer)nestor:7001(fd=572) message EventQueued
AttributeOtherDN '2016'
AttributeOtherDNRole 1
AttributeOtherTrunk 262648
AttributeThisDN '361836'
AttributeThisDNRole 2
AttributeThisTrunk 228611
AttributeThisQueue '361836'
AttributeCallState 0
AttributeCallType 2
AttributeConnID 008101e931182002
AttributeCallUUID '4UDN9RPJE955T4ECT2DR26LIEC000008'
AttributeDNIS '361836'
AttributeANI '2016'
AttributeCallID 525
AttributeNetworkCallID c974994d0d021700
AttributeExtensions [126] 00 04 02 00..
'GCTI_GLOB_CID' bin: C9 74 99 4D.. (len=8)
'GCTI_NAT_INDICATIONTYPE' 'Public:National'
'GCTI_NAT_INDICATION' '2016'
'GCTI_BUSINESS_CALL' 1
AttributeTimeinSecs 1301901955 (09:25:55)
AttributeTimeinuSecs 203000
AttributeEventSequenceNumber 000000000000005f
09:25:55.203_T_I_0000000000000000 [14:0c] EventQueued is received for tserver TServer[Alcatel_A4400] (this dn=361836)
_T_I_008101e931182002 [01:11] connid 008101e931182002 is bound to the call 1-01b38370
09:25:55.203_I_I_008101e931182002 [01:01] call (1-01b38370) for Resources created
_T_I_008101e931182002 [14:09] add DN TServer 361836 <361836@Alcatel_A4400> (CDN 57 008101e931182002) to the call 1-01b38370 truly:22
09:25:56.015_M_I_ [10:1d] PULSE (calls: 0(1)=0+0-0, targets=0, time=1301901956, mem=0,1399,48,10,107,1)
09:25:58.015_M_I_ [10:1d] PULSE (calls: 0(1)=0+0-0, targets=0, time=1301901958, mem=0,1399,48,10,107,1)
09:26:00.015_M_I_ [10:1d] PULSE (calls: 0(1)=0+0-0, targets=0, time=1301901960, mem=0,1399,48,10,107,1)
09:26:02.015_M_I_ [10:1d] PULSE (calls: 0(1)=0+0-0, targets=0, time=1301901962, mem=0,1399,48,10,107,1)
09:26:02.140_G_I_ [01:0b] look for hanged interactions: 1 at all now
09:26:02.140_G_I_ [01:0b] there are 1 calls in progress now
_G_I_ Version: 7.6.001.06
root 00a73ca0
09:26:04.015_M_I_ [10:1d] PULSE (calls: 0(1)=0+0-0, targets=0, time=1301901964, mem=0,1399,48,10,107,1)
received from 65200(TServer)nestor:7001(fd=572) message EventDiverted
AttributeOtherDN '2016'
AttributeOtherDNRole 1
AttributeOtherTrunk 262648
AttributeThisDN '361836'
AttributeThisDNRole 2
AttributeThisTrunk 228611
AttributeThisQueue '361836'
AttributeCallState 23
AttributeCallType 2
AttributeConnID 008101e931182002
AttributeCallUUID '4UDN9RPJE955T4ECT2DR26LIEC000008'
AttributeDNIS '361836'
AttributeANI '2016'
AttributeCallID 525
AttributeNetworkCallID c974994d0d021700
AttributeExtensions [126] 00 04 01 00..
'GCTI_BUSINESS_CALL' 1
'GCTI_GLOB_CID' bin: C9 74 99 4D.. (len=8)
'GCTI_NAT_INDICATIONTYPE' 'Public:National'
'GCTI_NAT_INDICATION' '2016'
AttributeTimeinSecs 1301901965 (09:26:05)
AttributeTimeinuSecs 156000
AttributeEventSequenceNumber 0000000000000063
09:26:05.171_T_I_008101e931182002 [14:0c] EventDiverted is received for tserver TServer[Alcatel_A4400] (this dn=361836)
_T_I_008101e931182002 [14:0a] del DN (TServer[Alcatel_A4400] 361836) (ref.id=0)
_T_W_008101e931182002 [14:0a] there is no DNs for call, activate delayed selfdestruction
09:26:05.171 Int 20004 interaction 008101e931182002 is taken over by switch
09:26:05.171_I_I_008101e931182002 [01:08] call deleting truly
and TServer logs :
+++ Evt::Delivered +++
*** Transaction Info:
Type/Oper. : 1 - 4 <Event : Delivered>
crossRef : 17378200
ConnId[0] : (20d-7d03)-<(Queue)>
ConnId[1] : (20d-1f8)-<(Trunk)>
SubjDev[1] : 361836 (Private)
LocalDev[0]: 2016 (Public)
LocalDev[1]: 361836 (Private)
Cause : 42 <EnteringDistribution>
LocalInfo : 2 <Alerting>
TimeStamp : <110404093537Z>
*** PrivateList has 2 elements:
PData : Global CallID : c974994d0d021700
PData : National Indication: [12:2016]
refDev <361836> (cref = '17378200')
have calls (-) & (-) by GCid (ref = 0)
no call (20d) found -> create new
@09:25:55.2030 {tscp.call {constructed} {uuid 4UDN9RPJE955T4ECT2DR26LIEC000008} {connection-id 008101e931182002} {call-id 525}}
Call::Call: call (20d:0)@0129B448 created
@09:25:55.2030 Call [008101e931182002]: urid 00010081
an external party <2016> with role '1' is added to the call (2:20d:0@0129B448)
Party: <20d:2016@0129B6B0> created
Party::Activate: for <20d:20d:2016@0129B6B0/created/Ok> with cause (Null[0])
Party::Originate: for <20d:20d:2016@0129B6B0/created/Ok> with cause (NewCall[22])
TmCall::created(0:0): for <2:20d:1@0129B448> with rel (0)
@09:25:55.2030 [ISCC] Call created:
+ c:008101e931182002,0129b448 @ m:0000000000000000,00000000 p:0 i:0000020d nw:c974994d0d021700 t:2
Party::commit::created[0]: for <20d:20d:2016@0129B6B0/originating> with cause/rel (0:0)
@09:25:55.2030 [ISCC] Party added [ssp view]:
@ c:008101e931182002,0129b448 @ m:0000000000000000,00000000,0000000000000000 p:1 i:0000020d nw:00000000:c974994d0d021700 t:2
+ p:0129b6b0 @ c:008101e931182002,0129b448 r:1 t:1 s:1 n:2016
@09:25:55.2030 [ISCC] Party added:
@ c:008101e931182002,0129b448 @ m:0000000000000000,00000000 p:1 i:0000020d nw:c974994d0d021700 t:2
+ p:0000000000000000,0129b6b0 @ c:008101e931182002,0129b448 r:1 ------ n:2016:
an internal party <361836> with role '2' is added to the call (2:20d:1@0129B448)
PartyGroup: <20d:361836@0129B7F8> created
PartyGroup::Queue: for <20d:20d:361836@0129B7F8/created/Ok> with cause (EnteringDistribution[42])
PartyGroup::PushQueue: queue <361836> is pushed for <20d:20d:361836@0129B7F8>
PartyGroup::commit::created[0]: for <20d:20d:361836@0129B7F8/queued> with cause/rel (0:0)
@09:25:55.2030 [ISCC] Party added [ssp view]:
@ c:008101e931182002,0129b448 @ m:0000000000000000,00000000,0000000000000000 p:2 i:0000020d nw:00000000:c974994d0d021700 t:2
p:0129b6b0 @ c:008101e931182002,0129b448 r:1 t:1 s:1 n:2016
+ p:0129b7f8 @ c:008101e931182002,0129b448 r:2 t:0 s:9 n:361836
@09:25:55.2030 [ISCC] Party added:
@ c:008101e931182002,0129b448 @ m:0000000000000000,00000000 p:2 i:0000020d nw:c974994d0d021700 t:2
p:0000000000000000,0129b6b0 @ c:008101e931182002,0129b448 r:1 ------ n:2016:
+ p:0000000000000000,0129b7f8 @ c:008101e931182002,0129b448 r:2 ------ n:361836:
@09:25:55.2030 [0] 7.6.003.08 distribute_event: message EventQueued
AttributeEventSequenceNumber 000000000000005f
AttributeTimeinuSecs 203000
AttributeTimeinSecs 1301901955 (09:25:55)
AttributeExtensions [126] 00 04 02 00..
'GCTI_GLOB_CID' bin: C9 74 99 4D.. (len=8)
'GCTI_NAT_INDICATIONTYPE' 'Public:National'
'GCTI_NAT_INDICATION' '2016'
'GCTI_BUSINESS_CALL' 1
AttributeNetworkCallID c974994d0d021700
AttributeCallID 525
AttributeANI '2016'
AttributeDNIS '361836'
AttributeCallUUID '4UDN9RPJE955T4ECT2DR26LIEC000008'
AttributeConnID 008101e931182002
AttributeCallType 2
AttributeCallState 0
AttributeThisQueue '361836'
AttributeThisTrunk 228611
AttributeThisDNRole 2
AttributeThisDN '361836'
AttributeOtherTrunk 262648
AttributeOtherDNRole 1
AttributeOtherDN '2016'
@09:25:55.2030 [ISCC] Translate: '2016' -> ''; result 1 ()
@09:25:55.2030 [ISCC] Translate: '2016' -> ''; result 1 ()
09:25:55.203 Int 04544 Interaction message "EventQueued" generated
09:25:55.203 Trc 04542 EventQueued sent to [404] (00000004 Universal_Routing_Server 10.3.66.131:3876)
09:25:55.203 Trc 04542 EventQueued sent to [392] (00000003 Stat_Server_720 10.3.66.131:3868)
dest pi is passed -> call (2:20d:2@0129B448), thisDev <361836>, dest = <361836>
--- Evt::Delivered ---
+++ Evt::Delivered +++
*** Transaction Info:
Type/Oper. : 1 - 4 <Event : Delivered>
crossRef : 17378200
ConnId[0] : (20d-7d03)-<(Queue)>
ConnId[1] : (20d-1f8)-<(Trunk)>
SubjDev[1] : 361836 (Private)
LocalDev[0]: 2016 (Public)
LocalDev[1]: 361836 (Private)
Cause : 42 <EnteringDistribution>
LocalInfo : 2 <Alerting>
TimeStamp : <110404093537Z>
*** PrivateList has 2 elements:
PData : Global CallID : c974994d0d021700
PData : National Indication: [12:2016]
refDev <361836> (cref = '17378200')
have calls (-) & (-) by GCid (ref = 0)
no call (20d) found -> create new
@09:25:55.2030 {tscp.call {constructed} {uuid 4UDN9RPJE955T4ECT2DR26LIEC000008} {connection-id 008101e931182002} {call-id 525}}
Call::Call: call (20d:0)@0129B448 created
@09:25:55.2030 Call [008101e931182002]: urid 00010081
an external party <2016> with role '1' is added to the call (2:20d:0@0129B448)
Party: <20d:2016@0129B6B0> created
Party::Activate: for <20d:20d:2016@0129B6B0/created/Ok> with cause (Null[0])
Party::Originate: for <20d:20d:2016@0129B6B0/created/Ok> with cause (NewCall[22])
TmCall::created(0:0): for <2:20d:1@0129B448> with rel (0)
@09:25:55.2030 [ISCC] Call created:
+ c:008101e931182002,0129b448 @ m:0000000000000000,00000000 p:0 i:0000020d nw:c974994d0d021700 t:2
Party::commit::created[0]: for <20d:20d:2016@0129B6B0/originating> with cause/rel (0:0)
@09:25:55.2030 [ISCC] Party added [ssp view]:
@ c:008101e931182002,0129b448 @ m:0000000000000000,00000000,0000000000000000 p:1 i:0000020d nw:00000000:c974994d0d021700 t:2
+ p:0129b6b0 @ c:008101e931182002,0129b448 r:1 t:1 s:1 n:2016
@09:25:55.2030 [ISCC] Party added:
@ c:008101e931182002,0129b448 @ m:0000000000000000,00000000 p:1 i:0000020d nw:c974994d0d021700 t:2
+ p:0000000000000000,0129b6b0 @ c:008101e931182002,0129b448 r:1 ------ n:2016:
an internal party <361836> with role '2' is added to the call (2:20d:1@0129B448)
PartyGroup: <20d:361836@0129B7F8> created
PartyGroup::Queue: for <20d:20d:361836@0129B7F8/created/Ok> with cause (EnteringDistribution[42])
PartyGroup::PushQueue: queue <361836> is pushed for <20d:20d:361836@0129B7F8>
PartyGroup::commit::created[0]: for <20d:20d:361836@0129B7F8/queued> with cause/rel (0:0)
@09:25:55.2030 [ISCC] Party added [ssp view]:
@ c:008101e931182002,0129b448 @ m:0000000000000000,00000000,0000000000000000 p:2 i:0000020d nw:00000000:c974994d0d021700 t:2
p:0129b6b0 @ c:008101e931182002,0129b448 r:1 t:1 s:1 n:2016
+ p:0129b7f8 @ c:008101e931182002,0129b448 r:2 t:0 s:9 n:361836
@09:25:55.2030 [ISCC] Party added:
@ c:008101e931182002,0129b448 @ m:0000000000000000,00000000 p:2 i:0000020d nw:c974994d0d021700 t:2
p:0000000000000000,0129b6b0 @ c:008101e931182002,0129b448 r:1 ------ n:2016:
+ p:0000000000000000,0129b7f8 @ c:008101e931182002,0129b448 r:2 ------ n:361836:
@09:25:55.2030 [0] 7.6.003.08 distribute_event: message EventQueued
AttributeEventSequenceNumber 000000000000005f
AttributeTimeinuSecs 203000
AttributeTimeinSecs 1301901955 (09:25:55)
AttributeExtensions [126] 00 04 02 00..
'GCTI_GLOB_CID' bin: C9 74 99 4D.. (len=8)
'GCTI_NAT_INDICATIONTYPE' 'Public:National'
'GCTI_NAT_INDICATION' '2016'
'GCTI_BUSINESS_CALL' 1
AttributeNetworkCallID c974994d0d021700
AttributeCallID 525
AttributeANI '2016'
AttributeDNIS '361836'
AttributeCallUUID '4UDN9RPJE955T4ECT2DR26LIEC000008'
AttributeConnID 008101e931182002
AttributeCallType 2
AttributeCallState 0
AttributeThisQueue '361836'
AttributeThisTrunk 228611
AttributeThisDNRole 2
AttributeThisDN '361836'
AttributeOtherTrunk 262648
AttributeOtherDNRole 1
AttributeOtherDN '2016'
@09:25:55.2030 [ISCC] Translate: '2016' -> ''; result 1 ()
@09:25:55.2030 [ISCC] Translate: '2016' -> ''; result 1 ()
09:25:55.203 Int 04544 Interaction message "EventQueued" generated
09:25:55.203 Trc 04542 EventQueued sent to [404] (00000004 Universal_Routing_Server 10.3.66.131:3876)
09:25:55.203 Trc 04542 EventQueued sent to [392] (00000003 Stat_Server_720 10.3.66.131:3868)
dest pi is passed -> call (2:20d:2@0129B448), thisDev <361836>, dest = <361836>
--- Evt::Delivered ---
--- Evt::ConnectionCleared ---
link (link-tcp) S->H: (0[0 / 0] requests pending)
04/04/11@09:26:20.421 call to clean: call(2:20d:008101e931182002@0129B448):
09:26:20.421 Trc 20021 Call 008101e931182002 cleared with TReliabilityInPast
@09:26:20.4210 [0] 7.6.003.08 distribute_event: message EventReleased
AttributeEventSequenceNumber 0000000000000067
AttributeTimeinuSecs 421000
AttributeTimeinSecs 1301901980 (09:26:20)
AttributeReliability 1
AttributeANI '2016'
AttributeDNIS '361836'
AttributeCallUUID '4UDN9RPJE955T4ECT2DR26LIEC000008'
AttributeConnID 008101e931182002
AttributeCallID 525
AttributeCallType 2
AttributeThisDN 'Alcatel_A4400::'
@09:26:20.4210 [ISCC] Translate: '2016' -> ''; result 1 ()
@09:26:20.4210 [ISCC] Party object is removed: p:0000000000000000,00000000 n::
@09:26:20.4210 [ISCC] Error: No call is associated with the event, skipped
@09:26:20.4210 [ISCC] Error: No call is associated with the event, skipped
@09:26:20.4210 [ISCC] Error: No call is associated with the event, skipped
@09:26:20.4210 [ISCC] Error: No call is associated with the event, skipped
09:26:20.421 Int 04544 Interaction message "EventReleased" generated
09:26:20.421 Trc 04542 EventReleased sent to [392] (00000003 Stat_Server_720 10.3.66.131:3868)
@09:26:20.4210 [ISCC] Call destroyed:
- c:008101e931182002,0129b448 @ m:0000000000000000,00000000 p:0 i:0000020d nw:c974994d0d021700 t:2
@09:26:20.4210 [ISCC] Call object is removed: c:008101e931182002,00000000
Regards,
Thomas