Odd "loopback" behavior on Small Community Network calls 2

[SCNIPOffice]

Hi all,

Running five sites with IP Office 500 v2 and R6 software. Just installed last week and have setup small community networking between all sites in a meshed network environment. We use centralized voicemail at one location with queue announcements that play for callers who aren't answered right away by an agent.

When I put users across the SCN into a huntgroup, a strange situation occurs where the call appears on all phones in that huntgroup to see the phone ring on and off rapidly as if the caller were calling and hanging up about once a second. I think this only happens after a queue announcement has been played (hosted through the centralized voicemail at our main location).

I'm at a loss as to what to look at next. Two of the primary requirements for this new phone system were to have a meshed network setup making the overall system not dependent on any one location and to be able to have agents in multiple locations be in a hunt group.

Does anyone have any insight into what might be going on? Thanks!

 

[tlpeter]

Can you post a trace ?


Homo sapiens non urinat in ventum

honey, i fried the IP Office !!!

 

[SCNIPOffice]

Sure, how would I go about getting that?

Are you talking about the output of system monitor?

 

[NYSTECH]

Are the Agents already on a call or idle when this occurs?

ACS- IPOffice Implement

 

[tlpeter]

Yes i mean an output of monitor.
How did you program the announcements ?


Homo sapiens non urinat in ventum

honey, i fried the IP Office !!!

 

[SCNIPOffice]

I haven't been able to pinpoint the exact circumstances when this happens, so I'm not sure about the call state of all of the agents. I know that there are users not on a call in the huntgroup when this happens.

The queue announcements work through the central voicemail pro server. After 16 seconds, 1st announcement plays once, then sent to local MOH. Overflow is also set to 16 seconds to ring the overflow group which contains all of the initial users plus some more back office folk.

After 30 seconds no answer, voicemail pro reroutes to external answering service number.

Is that the detail you were looking for for announcement setup?

I'm attempting to get the situation to replicate. Thusfar I've tried adding agents from two locations to a hunt group and haven't seen it. I'm going to add users at a third location and see if that's the magic trigger.

 

[SCNIPOffice]

This happens instantly once I add logged in users from more than two locations in a hunt group. I'm going through the monitor log now to paste the relevant data from the event.

If there are users from three locations that aren't logged into the hunt group, it doesn't happen. Only when they're logged in (check mark in the manager).

 

[SCNIPOffice]

Here's an example. I've sanitized this slightly and I'm not sure if I'm grabbing everything, but this call appeared hundreds of times as described above.


315631978mS CMLineTx: v=35
CMProceeding
Line: type=IPLine 35 Call: lid=35 id=121 in=1
315631978mS CMCallEvt: 0.29252.0 3688 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
315631980mS CMCallEvt: 0.29254.0 3688 H323TrunkEP: StateChange: END=T CMCSIdle->CMCSOffering
315631981mS CMLineTx: v=32
CMSetup
Line: type=IPLine 32 Call: lid=0 id=29254 in=0
Called[5100] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[91234567890] Type=Unknown Plan=ISDN Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIEMediaWaitForConnect (16) CMIEMediaWaitForConnect
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
CallingName.Invoke.CodePageUtf8String
invokeId 20209
user 'SMITH,KATHLEEN' presentation Allowed
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=Company
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=SMITH,KATHLEEN
IE CMIEOriginalCalledPartyName (236)(Type=CMNameDefault) name=Company
IE CMIEOriginalCalledPartyNumber (238)(P:100 S:100 T:100 N:100 R:4) number=5100
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=Company
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:100 N:100 R:4) number=5100
IE CMIEDIDNumber (245)(P:100 S:100 T:0 N:0 R:4) number=1660
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=SMITH,KATHLEEN
IE CMIERespondingPartyNumber (230)(P:0 S:101 T:0 N:1 R:4) number=91234567890
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=6 class=CMDeviceISDNTrunk type=2 number=1 channel=2 rx_gain=32 tx_gain=32 ep_callid=14 ipaddr=192.168.122.40 apps=0
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEMohSourceId (247) MOH Source = 1
IE CMIEFastStartInfoData (6)
IE CMIECallSignalChannelData (8) unknown
UUI type=User2User [5100..] [0x35 0x31 0x30 0x30 0xff 0xff ]
Timed: 01/06/10 17:17
Locale: enu
315631983mS CD: CALL: 35.121.1 BState=Ringing Cut=1 Music=2.0 Aend="Line 35" (285.4) Bend="Line 32" [Line 32] (0.0) CalledNum=5100 (Company) CallingNum=91234567890 (SMITH,KATHLEEN) Internal=0 Time=21 AState=Ringing
315631984mS CD2: CALL:S 35.121.1,0.29252.0,1,1,1,2,0,0,Line 35,,Line 32,285.4,0.0,100.0,5100,0.0,91234567890,,,0,5100,100,,0,16,0,1,0.0,,,,Company,,,0,21,0,0,0,0,enu,,0,,0,0,0,0,,3688,,,,,,,,,,,,,,,,,,,,,,,,,,,,,,
315631986mS H323Evt: v=0 stacknum=32 State, new=NullState, old=NullState id=-1
315631990mS H323Evt: v=0 stacknum=32 State, new=Initiated, old=NullState id=29254
315631991mS H323Evt: v=0 stacknum=35 State, new=ICProceeding, old=Present id=121
315631996mS H323Evt: v=0 stacknum=35 State, new=Proceeding, old=Initiated id=29250
315631997mS CMLineRx: v=35
CMProceeding
Line: type=IPLine 35 Call: lid=0 id=29250 in=0
315631997mS CMCallEvt: 0.29250.0 3687 H323TrunkEP: StateChange: END=T CMCSOffering->CMCSAccept
315631998mS CMCallEvt: 0.29248.0 3687 TargetingEP: StateChange: END=B CMCSOffering->CMCSAccept
315631999mS CD: CALL: 32.295.1 BState=Ringing Cut=1 Music=2.0 Aend="Line 32" (282.3) Bend="Line 35" [Line 35] (0.0) CalledNum=5100 (Company) CallingNum=91234567890 (SMITH,KATHLEEN) Internal=0 Time=134 AState=Ringing
315631999mS CD2: CALL:S 32.295.1,0.29248.0,1,1,1,2,0,0,Line 32,,Line 35,282.3,0.0,100.0,5100,0.0,91234567890,,,0,5100,100,,0,16,0,1,0.0,,,,Company,,,0,135,0,0,0,0,enu,,0,,0,0,0,0,,3687,,,,,,,,,,,,,,,,,,,,,,,,,,,,,,
315632025mS H323Evt: v=0 stacknum=32 State, new=NullState, old=NullState id=-1
315632025mS H323Evt: v=0 stacknum=32 State, new=Present, old=NullState id=297
315632031mS CMLineRx: v=32
CMSetup
Line: type=IPLine 32 Call: lid=32 id=297 in=1
Called[5100] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[91234567890] Type=Unknown Plan=ISDN Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIEMediaWaitForConnect (16) CMIEMediaWaitForConnect
IE CMIEFastStartInfoData (6)
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
CallingName.Invoke.CodePageISO8859-1
invokeId 17448
user 'SMITH,KATHLEEN' presentation Allowed
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=Company
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=SMITH,KATHLEEN
IE CMIEOriginalCalledPartyName (236)(Type=CMNameDefault) name=Company
IE CMIEOriginalCalledPartyNumber (238)(P:100 S:100 T:100 N:100 R:4) number=5100
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=Company
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:100 N:100 R:4) number=5100
IE CMIEDIDNumber (245)(P:100 S:100 T:0 N:0 R:4) number=1660
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=SMITH,KATHLEEN
IE CMIERespondingPartyNumber (230)(P:0 S:101 T:0 N:1 R:4) number=91234567890
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=6 class=CMDeviceISDNTrunk type=2 number=1 channel=2 rx_gain=32 tx_gain=32 ep_callid=14 ipaddr=192.168.122.40 apps=0
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEMohSourceId (247) MOH Source = 1
UUI type=User2User [5100..] [0x35 0x31 0x30 0x30 0xff 0xff ]
315632031mS CMCallEvt: 0.29255.0 -1 BaseEP: NEW CMEndpoint f5382c24 TOTAL NOW=29 CALL_LIST=5
315632032mS CMCallEvt: CREATE CALL:3689 (f53386f8)
315632032mS CMCallEvt: 0.29256.0 -1 BaseEP: NEW CMEndpoint f533597c TOTAL NOW=30 CALL_LIST=5
315632035mS CD: CALL: 32.297.1 BState=Idle Cut=1 Music=0.0 Aend="Line 32" (282.5) Bend="" [] (0.0) CalledNum=5100 (Company) CallingNum=91234567890 (SMITH,KATHLEEN) Internal=0 Time=3 AState=Idle
315632036mS CD2: CALL:S 32.297.1,0.29256.0,0,0,1,0,0,0,Line 32,,,282.5,0.0,100.0,5100,0.0,91234567890,,,100,,100,,0,16,0,1,0.0,,,,,,,0,3,0,0,0,0,enu,,0,,0,0,0,0,,3689,,,,,,,,,,,,,,,,,,,,,,,,,,,,,,
315632036mS CMCallEvt: 32.297.1 3689 H323TrunkEP: StateChange: END=A CMCSIdle->CMCSDialInitiated
315632036mS CMTARGET: 32.297.1 3689 H323TrunkEP: LOOKUP CALL ROUTE: type=0 called_party=5100 sub= calling=91234567890 dir=out complete=1 ses=0
315632036mS CMTARGET: 32.297.1 3689 H323TrunkEP: ADD TARGET (N): number=5100 type=0 depth=1 nobar=1 setorig=1 ses=0
315632037mS CMTARGET: 32.297.1 3689 H323TrunkEP: HG(Company,5100,192.168.122.40) Requires Routing To Master(1). IsLocalExecutive(1)
315632037mS CMTARGET: 32.297.1 3689 H323TrunkEP: PrimeForHGTarget: Company (after a Remote Redirect)
315632037mS CMCallEvt: Priority hike: call 3689 priority 0->5
315632037mS CMTARGET: 32.297.1 3689 H323TrunkEP: AddHGTarget Company (depth=2) allowq=0 type=CMNTypeUnknown
315632037mS CMCallEvt: 0.29257.0 -1 BaseEP: NEW CMEndpoint f535e050 TOTAL NOW=31 CALL_LIST=6
315632038mS CMTARGET: 32.297.1 3689 H323TrunkEP: AddHGTargetRingGroup Company starting at 12
315632038mS CMTARGET: 32.297.1 3689 H323TrunkEP: Call 192.168.126.40 for User ClayM
315632038mS CMCallEvt: 0.29258.0 -1 BaseEP: NEW CMEndpoint f53a5e3c TOTAL NOW=32 CALL_LIST=6
315632041mS CMTARGET: MakeCallToNetworkedPBX using line group id: 35 ipaddr=192.168.126.40
315632041mS CMTARGET: 32.297.1 3689 H323TrunkEP: Call 192.168.126.40 for User DonnaS
315632041mS CMTARGET: MakeCallToNetworkedPBX using line group id: 35 ipaddr=192.168.126.40
315632042mS CMTARGET: 32.297.1 3689 H323TrunkEP: Call 192.168.126.40 for User JamesW
315632042mS CMTARGET: MakeCallToNetworkedPBX using line group id: 35 ipaddr=192.168.126.40
315632042mS CMTARGET: 32.297.1 3689 H323TrunkEP: ADD USER: NaseemA depth=3 disallow_cw=0 dnd=0 real_call=1 group_call=1 type(CMNTypeUnknown) incl(0x0) excpt(0x3c), allow_redir(0) remote=00000000
315632042mS CMTARGET: 32.297.1 3689 H323TrunkEP: ADD USER: BrandiO depth=3 disallow_cw=1 dnd=0 real_call=1 group_call=1 type(CMNTypeUnknown) incl(0x0) excpt(0x3c), allow_redir(0) remote=00000000
315632043mS CMTARGET: 32.297.1 3689 H323TrunkEP: ADD USER: KristinaC depth=3 disallow_cw=1 dnd=0 real_call=1 group_call=1 type(CMNTypeUnknown) incl(0x0) excpt(0x3c), allow_redir(0) remote=00000000
315632043mS CMTARGET: 32.297.1 3689 H323TrunkEP: ADD USER: BeccaK depth=3 disallow_cw=1 dnd=0 real_call=1 group_call=1 type(CMNTypeUnknown) incl(0x0) excpt(0x3c), allow_redir(0) remote=00000000
315632044mS CMTARGET: 32.297.1 3689 H323TrunkEP: ADD USER: CassandraF depth=3 disallow_cw=1 dnd=0 real_call=1 group_call=1 type(CMNTypeUnknown) incl(0x0) excpt(0x3c), allow_redir(0) remote=00000000
315632044mS CMTARGET: 32.297.1 3689 H323TrunkEP: ADD USER: CallyH depth=3 disallow_cw=1 dnd=0 real_call=1 group_call=1 type(CMNTypeUnknown) incl(0x0) excpt(0x3c), allow_redir(0) remote=00000000
315632044mS CMTARGET: 32.297.1 3689 H323TrunkEP: ADD USER: LavendaM depth=3 disallow_cw=1 dnd=0 real_call=1 group_call=1 type(CMNTypeUnknown) incl(0x0) excpt(0x3c), allow_redir(0) remote=00000000
315632045mS CMTARGET: 32.297.1 3689 H323TrunkEP: ADD USER: LindsayS depth=3 disallow_cw=1 dnd=0 real_call=1 group_call=1 type(CMNTypeUnknown) incl(0x0) excpt(0x3c), allow_redir(0) remote=00000000
315632045mS CMTARGET: 32.297.1 3689 H323TrunkEP: ADD USER: PaulE depth=3 disallow_cw=1 dnd=0 real_call=1 group_call=1 type(CMNTypeUnknown) incl(0x0) excpt(0x3c), allow_redir(0) remote=00000000
315632045mS CMTARGET: 32.297.1 3689 H323TrunkEP: User PattyK in direction of incoming call (192.168.123.40)
315632046mS CMTARGET: 32.297.1 3689 H323TrunkEP: User CarolineK in direction of incoming call (192.168.123.40)
315632046mS CMTARGET: 32.297.1 3689 H323TrunkEP: User TaraE in direction of incoming call (192.168.123.40)
315632046mS CMTARGET: 32.297.1 3689 H323TrunkEP: INITIAL TARGETING SUCCEEDED
315632046mS CMTARGET: 32.297.1 3689 H323TrunkEP: SetNoAnswerTimer Not Starting: 5100
315632047mS CMCallEvt: 32.297.1 3689 H323TrunkEP: StateChange: END=A CMCSDialInitiated->CMCSDialled
315632048mS CMLineTx: v=32
CMProceeding
Line: type=IPLine 32 Call: lid=32 id=297 in=1
315632048mS CMCallEvt: 0.29256.0 3689 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
315632050mS CMCallEvt: 0.29258.0 3689 H323TrunkEP: StateChange: END=T CMCSIdle->CMCSOffering
315632051mS CMLineTx: v=35
CMSetup
Line: type=IPLine 35 Call: lid=0 id=29258 in=0
Called[5100] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[91234567890] Type=Unknown Plan=ISDN Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIEMediaWaitForConnect (16) CMIEMediaWaitForConnect
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
CallingName.Invoke.CodePageUtf8String
invokeId 20210
user 'SMITH,KATHLEEN' presentation Allowed
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=Company
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=SMITH,KATHLEEN
IE CMIEOriginalCalledPartyName (236)(Type=CMNameDefault) name=Company
IE CMIEOriginalCalledPartyNumber (238)(P:100 S:100 T:100 N:100 R:4) number=5100
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=Company
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:100 N:100 R:4) number=5100
IE CMIEDIDNumber (245)(P:100 S:100 T:0 N:0 R:4) number=1660
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=SMITH,KATHLEEN
IE CMIERespondingPartyNumber (230)(P:0 S:101 T:0 N:1 R:4) number=91234567890
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=6 class=CMDeviceISDNTrunk type=2 number=1 channel=2 rx_gain=32 tx_gain=32 ep_callid=14 ipaddr=192.168.122.40 apps=0
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEMohSourceId (247) MOH Source = 1
IE CMIEFastStartInfoData (6)
IE CMIECallSignalChannelData (8) unknown
UUI type=User2User [5100..] [0x35 0x31 0x30 0x30 0xff 0xff ]
Timed: 01/06/10 17:17
Locale: enu
315632053mS CD: CALL: 32.297.1 BState=Ringing Cut=1 Music=2.0 Aend="Line 32" (282.5) Bend="Line 35" [Line 35] (0.0) CalledNum=5100 (Company) CallingNum=91234567890 (SMITH,KATHLEEN) Internal=0 Time=21 AState=Ringing
315632054mS CD2: CALL:S 32.297.1,0.29256.0,1,1,1,2,0,0,Line 32,,Line 35,282.5,0.0,100.0,5100,0.0,91234567890,,,0,5100,100,,0,16,0,1,0.0,,,,Company,,,0,22,0,0,0,0,enu,,0,,0,0,0,0,,3689,,,,,,,,,,,,,,,,,,,,,,,,,,,,,,
315632058mS H323Evt: v=0 stacknum=35 State, new=NullState, old=NullState id=-1
315632061mS H323Evt: v=0 stacknum=35 State, new=Initiated, old=NullState id=29258
315632063mS H323Evt: v=0 stacknum=32 State, new=ICProceeding, old=Present id=297
315632067mS H323Evt: v=0 stacknum=32 State, new=NullState, old=Initiated id=29254
315632068mS CMLineRx: v=32
CMReleaseComp
Line: type=IPLine 32 Call: lid=0 id=29254 in=0
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
CallingName.Invoke.CodePageISO8859-1
invokeId 20209
user 'SMITH,KATHLEEN' presentation Allowed
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=Company
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=SMITH,KATHLEEN
IE CMIEOriginalCalledPartyName (236)(Type=CMNameDefault) name=Company
IE CMIEOriginalCalledPartyNumber (238)(P:100 S:100 T:100 N:100 R:4) number=5100
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=Company
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:100 N:100 R:4) number=5100
IE CMIEDIDNumber (245)(P:100 S:100 T:0 N:0 R:4) number=1660
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=SMITH,KATHLEEN
IE CMIERespondingPartyNumber (230)(P:0 S:101 T:0 N:1 R:4) number=91234567890
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=6 class=CMDeviceISDNTrunk type=2 number=1 channel=2 rx_gain=32 tx_gain=32 ep_callid=14 ipaddr=192.168.122.40 apps=0
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEMohSourceId (247) MOH Source = 1
Cause=17, User busy
315632069mS CMCallEvt: 0.29254.0 3688 H323TrunkEP: StateChange: END=T CMCSOffering->CMCSCompleted
315632070mS CMCallEvt: 0.29254.0 -1 H323TrunkEP: StateChange: END=X CMCSCompleted->CMCSDelete
315632071mS CMCallEvt: 0.29254.0 -1 BaseEP: DELETE CMEndpoint f5386f34 TOTAL NOW=31 CALL_LIST=6
315632133mS H323Evt: v=0 stacknum=35 State, new=NullState, old=Initiated id=29258
315632134mS CMLineRx: v=35
CMReleaseComp
Line: type=IPLine 35 Call: lid=0 id=29258 in=0
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
CallingName.Invoke.CodePageISO8859-1
invokeId 20210
user 'SMITH,KATHLEEN' presentation Allowed
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=Company
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=SMITH,KATHLEEN
IE CMIEOriginalCalledPartyName (236)(Type=CMNameDefault) name=Company
IE CMIEOriginalCalledPartyNumber (238)(P:100 S:100 T:100 N:100 R:4) number=5100
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=Company
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:100 N:100 R:4) number=5100
IE CMIEDIDNumber (245)(P:100 S:100 T:0 N:0 R:4) number=1660
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=SMITH,KATHLEEN
IE CMIERespondingPartyNumber (230)(P:0 S:101 T:0 N:1 R:4) number=91234567890
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=6 class=CMDeviceISDNTrunk type=2 number=1 channel=2 rx_gain=32 tx_gain=32 ep_callid=14 ipaddr=192.168.122.40 apps=0
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEMohSourceId (247) MOH Source = 1
Cause=17, User busy
315632134mS CMCallEvt: 0.29258.0 3689 H323TrunkEP: StateChange: END=T CMCSOffering->CMCSCompleted
315632135mS CMCallEvt: 0.29258.0 -1 H323TrunkEP: StateChange: END=X CMCSCompleted->CMCSDelete
315632136mS CMCallEvt: 0.29258.0 -1 BaseEP: DELETE CMEndpoint f53a5e3c TOTAL NOW=30 CALL_LIST=6
315632177mS SCN Ctrl Tx: PropagateBLF to 192.168.126.40 length=28
315632178mS SCN Ctrl Tx: PropagateBLF to 192.168.124.40 length=28
315632178mS SCN Ctrl Tx: PropagateBLF to 192.168.125.40 length=28
315632178mS SCN Ctrl Tx: PropagateBLF to 192.168.123.40 length=28
315632257mS SCN Ctrl Rx: ParseRemoteBLF from 192.168.126.40 Array Length(5)
315632282mS SCN Ctrl Rx: VPNNetworkedPBX(192.168.126.40)::ReadLII len(49) Unicode(2)
315632282mS SCN Ctrl Rx: extn(1501) state(1) idle_time(315547127) mass_update(0)
315632282mS SCN Ctrl Rx: extn(1503) state(1) idle_time(315547125) mass_update(0)
315632703mS SCN Ctrl Rx: ParseRemoteBLF from 192.168.123.40 Array Length(5)
315632846mS H323Evt: Recv: RegistrationRequest 10.10.52.42; Endpoints registered: 2; Endpoints in registration: 0
315635196mS H323Evt: Recv: RegistrationRequest 10.10.52.43; Endpoints registered: 2; Endpoints in registration: 0
315636322mS CMExtnRx: v=1101, p1=0
CMConnect
Line: type=DigitalExtn 2 Call: lid=0 id=29235 in=0
315636322mS CMCallEvt: 0.29235.0 3685 NaseemA.0: StateChange: END=T CMCSRinging->CMCSConnReq
315636323mS CMCallEvt: 0.29230.0 3685 TargetingEP: RequestEnd 0.29235.0 3685 NaseemA.0
315636325mS SCN DHG ServiceChg: TriggerUpdateHGAPP 5100 GCs(0) QCs(0) LOCAL
315636325mS SCN DHG ServiceChg: VPNNetworkedPBX(192.168.126.40)::UpdateHGAPP
315636325mS SCN DHG ServiceChg: VPNNetworkedPBX(192.168.123.40)::UpdateHGAPP
315636326mS CMTARGET: 1.14.1 3685 Q931 Trunk:1 CHAN=2: Removed HG from Callinfo
315636326mS CMCallEvt: 0.29231.0 3685 ACDTep(Company): StateChange: END=??? CMCSRinging->CMCSDelete
315636326mS CMCallEvt: 0.29231.0 -1 BaseEP: DELETE CMEndpoint f5330fc0 TOTAL NOW=29 CALL_LIST=6
315636327mS CMTARGET: 1.14.1 3685 Q931 Trunk:1 CHAN=2: CancelTimer CMTCNoAnswerTimeout
315636327mS CMCallEvt: 0.29230.0 -1 BaseEP: DELETE CMEndpoint f5362be0 TOTAL NOW=28 CALL_LIST=6
315636328mS CMExtnEvt: PaulE: CALL LOST (CMCauseAnsweredByOther)
315636328mS CMExtnEvt: PaulE: Extn(1135) Calling Party Number(91234567890) Type(CMNTypeUnknown)
315636329mS CMCallEvt: 0.29232.0 -1 PaulE.0: StateChange: END=X CMCSRinging->CMCSCompleted
315636329mS CMExtnEvt: v=6 State, new=PortRecoverDelay old=Ringing,0,0,PaulE
315636330mS CMExtnTxP: v=1135
CMReleaseComp
Line: type=DigitalExtn 4 Call: lid=0 id=29232 in=0
Called[1135] Type=Unknown (0) Reason=CMDRdirect Calling[91234567890] Type=Unknown Plan=ISDN Pres=Allowed (0)
Cause=124, Answered By Other(IPO)
Timed: 01/06/10 17:17
315636330mS CMExtnTx: v=1135, p1=0
CMReleaseComp
Line: type=DigitalExtn 4 Call: lid=0 id=29232 in=0
Called[1135] Type=Unknown (0) Reason=CMDRdirect Calling[91234567890] Type=Unknown Plan=ISDN Pres=Allowed (0)
Cause=124, Answered By Other(IPO)
Timed: 01/06/10 17:17
315636330mS CMExtnEvt: PaulE: CMExtnHandler::SetCurrent( id: 29232->0 )
315636331mS CMCallEvt: 0.29232.0 -1 PaulE.-1: StateChange: END=X CMCSCompleted->CMCSDelete
315636331mS CMCallEvt: 0.29232.0 -1 BaseEP: DELETE CMEndpoint f532152c TOTAL NOW=27 CALL_LIST=6
315636332mS CMLineTx: v=32
CMReleaseComp
Line: type=IPLine 32 Call: lid=0 id=29233 in=0

 

[tlpeter]

Is there anybody with a call forward on no answer to the same group ?
What is the time set on the no answer timer ?
Is there any overflow group configured and what timer ?
Try building this with only one user.
Then if that is running fine then add another user until you have them all or stop when the problems occur.

Homo sapiens non urinat in ventum

honey, i fried the IP Office !!!

 

[SCNIPOffice]

This is actually what I was thinking... most of the users in this group have a forward no answer entry that rings back to the main hunt group.

No answer time is set to 20 seconds, it's a collective call waiting group, and overflow is set to 16 seconds.

I removed all of the forward no answer entries to see what that does as it's the only thing that references the group the calls get forwarded to (same group where they originated) and explains the large number of entries.

Shouldn't forward no answer not apply here though? From the docs:

For collective hunt groups, the idea of moving to the next member when the No Answer Time expires does not apply, instead calls will continue ringing unless overflow or voicemail is applied.

 

[tlpeter]

You have set no answer on 20 seconds and overflow 16 seconds.
Where does it overflow too ?

Can you use System status to see what happens ?


Homo sapiens non urinat in ventum

honey, i fried the IP Office !!!

 

[SCNIPOffice]

Overflows to an overflow group which contains members of the same original hunt group as well as additional staff.

System status just shows me the rapidly incrementing calls, however I haven't tested since removing the forward no answer from the hunt group members.

 

[tlpeter]

That is what i thought!

Change the no answer timer to 20 instead of 16.
Is it a collective group ? i think it is otherwise the no aswer timer and overflow timer are not set this way.


Homo sapiens non urinat in ventum

honey, i fried the IP Office !!!

 

[SCNIPOffice]

Yes, it's a collective group.

no answer time is set to 20 and overflow is set to 16, however the same users are members of the overflow group, so maybe the no answer time needs to be higher?

 

[amriddle01]

The No answer time isn't used for collective groups, only overflow and VM answer timers are used

ACSS (SME)
APSS (SME)

http://www.ipoffice.tel

 

[amriddle01]

Oh , I see you have noticed that yourself, what ring type is the overflow group? Is that also collective?

ACSS (SME)
APSS (SME)

http://www.ipoffice.tel

 

[SCNIPOffice]

Yes, both primary hunt group and overflow are collective call waiting. Final destination is VM pro server where calls are routed to an external number.

I've upped the ring no answer time to 60 seconds (more than the voicemail time of 40) and it hasn't happened so far.

I'm continuing to monitor.

Is this a bug?

 

[SCNIPOffice]

False alarm. Turns out I removed the remote users from the primary hunt group. As soon as I added them back in, it happened again.

I notice in monitor that the time is off by a few seconds on each system. Could it be a timing issue? Maybe they're not getting accurate NTP results?

 

[SCNIPOffice]

My next course of action on this is to isolate to see if this is an issue with a meshed network configuration. I've taken the three affected sites and moved them to a star configuration to see if this continues to replicate tomorrow.

My gut tells me it is involving the centralized voicemail server, but I don't have much to base that on.

 

[IPOfficeIreland]

Remove PaulE from the ring groups and test again.