R6 SCN intermittently disconnecting

[pdob]

We have 5 sites on SCN and one of the site is intermittently disconnecting, usually twice a week. Below is the trace from the site that's disconnecting...traced h323 and line 130. Thanks all.

All 5 sites are IP500 R6
Centralized VM Pro on main site.



BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
Display [eng]
Locale: eng
4132157603mS CMTARGET: Problem with Line Id: 5 - preselected: 00000000, Unlicenced: 0, OperationalTest: 1, NewCallResourceCheck: -1
4132157606mS CMARS: FOUND LINE - Line Id: 1 - using line group id: 0 - Called Number: 165601753 - Calling Number: 6505
4132157606mS CMARS: SEND Setup TO LINE
4132157607mS CMLineTx: v=1
CMSetup
Line: type=Q931Line 1 Call: lid=0 id=792595 in=0
Called[165601753] Type=Unknown (0) Reason=CMDRdirect Calling[116505] Type=Unknown Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
Locale: eng
4132157607mS CD: CALL: 0.792591.0 BState=Idle Cut=2 Music=0.0 Aend="James Collins(6505)" (11.8) Bend="" [Line 1] (0.0) CalledNum=01753 () CallingNum=6505 (James Collins) Internal=1 Time=2580 AState=Dialling
4132157766mS CMLineRx: v=1
CMSetupAck
Line: type=Q931Line 1 Call: lid=0 id=792595 in=0
BChan: slot=0 chan=18
4132157769mS CMARS: LINE ep Received: CMSetupAck - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
4132157769mS CMARS: LINE IS NOW THE B END OF THE CALL - CMARSEndpoint::RequestEnd()
4132157769mS CMARS: CMARSEndpoint::CallLost(cause=124) - Address: 0.792594.0 157655 ARS for Mayflower - Call State: CMCSOverlapRecv
4132157772mS CD: CALL: 0.792591.0 BState=Idle Cut=2 Music=0.0 Aend="James Collins(6505)" (11.8) Bend="Line 1" [Line 1] (0.19) CalledNum=165601753 () CallingNum=6505 (James Collins) Internal=0 Time=2745 AState=Dialling
4132157773mS CMMap: PCG::MapBChan pcp[248]b1r0 cp_b f52fd8ac other_cp_b 0 type CGTypeSimple
4132157773mS CMMap: PCG::MapBChan pcp[137]b1r0 cp_b f5869c70 other_cp_b f52fd8ac type CGTypeSimple
4132157773mS CMMap: a=11.8 b=0.19 M1
4132158549mS PRN: 6505: Digit Pressed 2
4132158549mS CMExtnRx: v=6505, p1=0
CMInformation
Line: type=DigitalExtn 9 Call: lid=0 id=792591 in=0
Called[2] Type=Default (100) Reason=CMDRdirect
4132158550mS CMLineTx: v=1
CMInformation
Line: type=Q931Line 1 Call: lid=0 id=792595 in=0
Called[2] Type=Default (100) Reason=CMDRdirect
BChan: slot=0 chan=18
4132158694mS CMExtnRx: v=6537, p1=0
CMSetup
Line: type=DigitalExtn 6 Call: lid=0 id=792596 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[6537] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=Aylin Pektas
4132158696mS CMTARGET: 0.792596.0 157656 Aylin Pektas.0: LOOKUP CALL ROUTE: type=100 called_party= sub= calling=6537 dir=out complete=0 ses=0
4132158697mS CMTARGET: 0.792596.0 157656 Aylin Pektas.0: ADD TARGET (N): number= type=100 depth=1 nobar=1 setorig=1 ses=0
4132158698mS CMExtnTx: v=6537, p1=0
CMSetupAck
Line: type=DigitalExtn 6 Call: lid=0 id=792596 in=0
BChan: slot=8 chan=32
Timed: 28/07/10 15:22
4132158698mS CD: CALL: 0.792596.0 BState=Idle Cut=1 Music=3.0 Aend="Aylin Pektas(6537)" (8.32) Bend="" [] (0.0) CalledNum= () CallingNum=6537 (Aylin Pektas) Internal=1 Time=9 AState=DialInitiated
4132158699mS CMMap: a=8.32 b=0.0 D1
4132158797mS PRN: 6505: Digit Pressed 1
4132158797mS CMExtnRx: v=6505, p1=0
CMInformation
Line: type=DigitalExtn 9 Call: lid=0 id=792591 in=0
Called[1] Type=Default (100) Reason=CMDRdirect
4132158798mS CMLineTx: v=1
CMInformation
Line: type=Q931Line 1 Call: lid=0 id=792595 in=0
Called[1] Type=Default (100) Reason=CMDRdirect
BChan: slot=0 chan=18
4132159178mS H323Evt: Shared tcp socket for line 130 disconnected
4132159178mS H323Evt: H323Pipe:isconnectIndication cap 1
4132159178mS H323Evt: H323Pipe:isconnectIndication cap 2
4132159178mS H323Evt: H323Pipe:isconnectIndication cap f
4132159179mS H323Evt: H323 stack for line 130 is disconnected
4132159186mS PRN: 6537: Digit Pressed 6
4132159186mS CMExtnRx: v=6537, p1=0
CMInformation
Line: type=DigitalExtn 6 Call: lid=0 id=792596 in=0
Called[6] Type=Default (100) Reason=CMDRdirect
4132159188mS CMMap: a=8.32 b=0.0 D0
4132159209mS CMExtnRx: v=6555, p1=0
CMReleaseComp
Line: type=DigitalExtn 7 Call: lid=0 id=792570 in=0
4132159213mS PRN: CDR - TCPSend maxqueuesize=500 operational=1
4132159216mS CMLOGGING: CALL:2010/07/2815:22,00:00:14,001,6555,O,6538,6538,JohnSawyer,,,1,,""n/a,0
4132159216mS CD: CALL: 0.792570.0 BState=Connected Cut=2 Music=0.0 Aend="John Sawyer(6555)" (9.19) Bend="Neelan Samaratu(6538)" [Neelan Samaratu(6538)] (9.3) CalledNum=6538 (Neelan Samaratu) CallingNum=6555 (John Sawyer) Internal=1 Time=19335 AState=Idle
4132159216mS CD: CALL: 0.792570.0 Deleted
4132159222mS CMExtnTx: v=6538, p1=0
CMReleaseComp
Line: type=DigitalExtn 7 Call: lid=0 id=792573 in=0
Called[6538] Type=Default (100) Reason=CMDRdirect Calling[6555] Type=Internal Plan=Default
Cause=16, Normal call clearing
Timed: 28/07/10 15:22
4132159228mS CMMap: PCG::UnmapBChan pcp[206]b1r0 cp_b f5323194 other_cp_b f53059e4
4132159228mS CMMap: a=9.19 b=9.3 M0
4132159228mS CMMap: PCG::UnmapBChan pcp[238]b1r0 cp_b f53059e4 other_cp_b 0
4132159458mS PRN: 6537: Digit Pressed 5
4132159458mS CMExtnRx: v=6537, p1=0
CMInformation
Line: type=DigitalExtn 6 Call: lid=0 id=792596 in=0
Called[5] Type=Default (100) Reason=CMDRdirect
4132159761mS PRN: 6537: Digit Pressed 9
4132159761mS CMExtnRx: v=6537, p1=0
CMInformation
Line: type=DigitalExtn 6 Call: lid=0 id=792596 in=0
Called[9] Type=Default (100) Reason=CMDRdirect
4132159763mS PRN: 6505: Digit Pressed 6
4132159763mS CMExtnRx: v=6505, p1=0
CMInformation
Line: type=DigitalExtn 9 Call: lid=0 id=792591 in=0
Called[6] Type=Default (100) Reason=CMDRdirect
4132159764mS CMLineTx: v=1
CMInformation
Line: type=Q931Line 1 Call: lid=0 id=792595 in=0
Called[6] Type=Default (100) Reason=CMDRdirect
BChan: slot=0 chan=18
4132160082mS PRN: 6537: Digit Pressed 1
4132160082mS CMExtnRx: v=6537, p1=0
CMInformation
Line: type=DigitalExtn 6 Call: lid=0 id=792596 in=0
Called[1] Type=Default (100) Reason=CMDRdirect
4132160635mS PRN: 6505: Digit Pressed 9
4132160635mS CMExtnRx: v=6505, p1=0
CMInformation
Line: type=DigitalExtn 9 Call: lid=0 id=792591 in=0
Called[9] Type=Default (100) Reason=CMDRdirect
4132160636mS CMLineTx: v=1
CMInformation
Line: type=Q931Line 1 Call: lid=0 id=792595 in=0
Called[9] Type=Default (100) Reason=CMDRdirect
BChan: slot=0 chan=18
4132160876mS PRN: 6505: Digit Pressed 2
4132160876mS CMExtnRx: v=6505, p1=0
CMInformation
Line: type=DigitalExtn 9 Call: lid=0 id=792591 in=0
Called[2] Type=Default (100) Reason=CMDRdirect
4132160877mS CMLineTx: v=1
CMInformation
Line: type=Q931Line 1 Call: lid=0 id=792595 in=0
Called[2] Type=Default (100) Reason=CMDRdirect
BChan: slot=0 chan=18
4132161082mS CMTARGET: 0.792596.0 157656 Aylin Pektas.0: TimerExpired cause=CMTCDelayedProcessing
4132161082mS CMTARGET: 0.792596.0 157656 Aylin Pektas.0: LOOKUP CALL ROUTE: type=100 called_party=6591 sub= calling=6537 dir=out complete=0 ses=0
4132161082mS CMTARGET: 0.792596.0 157656 Aylin Pektas.0: ADD TARGET (N): number=6591 type=100 depth=1 nobar=1 setorig=1 ses=0
4132161083mS CMTARGET: 0.792596.0 157656 Aylin Pektas.0: SET USER: Rachel Ellis orig=1
4132161083mS CMTARGET: 0.792596.0 157656 Aylin Pektas.0: ADD USER: Rachel Ellis depth=2 disallow_cw=0 dnd=0 real_call=1 group_call=0 type(CMNTypeDefault) incl(0x0) excpt(0x0), allow_redir(1) remote=00000000
4132161085mS CMTARGET: 0.792598.0 157656 Rachel Ellis.0: ADD PRIMARY
4132161086mS CMTARGET: 0.792596.0 157656 Aylin Pektas.0: CancelTimer CMTCDialToneTimeout
4132161086mS CMTARGET: 0.792596.0 157656 Aylin Pektas.0: INITIAL TARGETING SUCCEEDED
4132161086mS CMTARGET: 0.792596.0 157656 Aylin Pektas.0: GetNoAnswerTimer:15
4132161088mS CMExtnTx: v=6591, p1=0
CMSetup
Line: type=DigitalExtn 8 Call: lid=0 id=792598 in=0
Called[6591] Type=Default (100) Reason=CMDRdirect Calling[6537] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=Rachel Ellis
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=Aylin Pektas
IE CMIEMohSourceId (247) MOH Source = 255
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Aylin Pektas
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=6537
IE CMIEDeviceDetail (231) LOCALE=eng HW=15 VER=6 class=CMDeviceStdPhone type=27 number=130 channel=0 rx_gain=32 tx_gain=32 ep_callid=792596 ipaddr=192.168.2.5 apps=0
Display [Aylin Pektas>Rachel Ellis]
Timed: 28/07/10 15:22
Locale: eng
4132161088mS CMExtnRx: v=6591, p1=0
CMAlerting
Line: type=DigitalExtn 8 Call: lid=0 id=792598 in=0
4132161092mS CMExtnTx: v=6537, p1=0
CMAlerting
Line: type=DigitalExtn 6 Call: lid=0 id=792596 in=0
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Rachel Ellis
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=6591
IE CMIEDeviceDetail (231) LOCALE=eng HW=15 VER=6 class=CMDeviceStdPhone type=27 number=323 channel=0 rx_gain=32 tx_gain=32 ep_callid=792598 ipaddr=192.168.2.5 apps=0
Timed: 28/07/10 15:22
4132161092mS CD: CALL: 0.792596.0 BState=Ringing Cut=1 Music=2.0 Aend="Aylin Pektas(6537)" (8.32) Bend="Rachel Ellis(6591)" [Rachel Ellis(6591)] (0.0) CalledNum=6591 (Rachel Ellis) CallingNum=6537 (Aylin Pektas) Internal=1 Time=2403 AState=Ringing
4132161093mS CMMap: a=8.32 b=0.0 R1
4132161218mS CMExtnTx: v=6555, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 7 Call: lid=0 id=-1 in=0
Called[John Sawyer Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [John Sawyer Msgs=0]
Timed: 28/07/10 15:22
4132161223mS CMExtnTx: v=6538, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 7 Call: lid=0 id=-1 in=0
Called[Neelan Samaratu Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Neelan Samaratu Msgs=0]
Timed: 28/07/10 15:22
4132161282mS RES: Wed 28/7/2010 15:22:33 FreeMem=63511796(1) CMMsg=9 (9) Buff=200 905 998 7236 2 Links=16549
4132161282mS RES2: IP 500 V2 6.0(8) Tasks=39 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=93 Poll=0 Ready=0 CMReady=1 CMQueue=0 VPNNQueue=0 Monitor=1 ASC=1 SYS=MNTD OPT=UMNT
4132161385mS PRN: 6505: Digit Pressed 0
4132161385mS CMExtnRx: v=6505, p1=0
CMInformation
Line: type=DigitalExtn 9 Call: lid=0 id=792591 in=0
Called[0] Type=Default (100) Reason=CMDRdirect
4132161386mS CMLineTx: v=1
CMInformation
Line: type=Q931Line 1 Call: lid=0 id=792595 in=0
Called[0] Type=Default (100) Reason=CMDRdirect
BChan: slot=0 chan=18
4132163382mS CMLineRx: v=1
CMProceeding
Line: type=Q931Line 1 Call: lid=0 id=792595 in=0
4132163383mS CMExtnTx: v=6505, p1=0
CMProceeding
Line: type=DigitalExtn 9 Call: lid=0 id=792591 in=0
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=01753216920
IE CMIEDeviceDetail (231) LOCALE=eng HW=15 VER=6 class=CMDeviceISDNTrunk type=0 number=1 channel=18 rx_gain=32 tx_gain=32 ep_callid=792595 ipaddr=192.168.2.5 apps=0
Timed: 28/07/10 15:22
4132163384mS CD: CALL: 0.792591.0 BState=Ringing Cut=3 Music=0.0 Aend="James Collins(6505)" (11.8) Bend="Line 1" [Line 1] (0.19) CalledNum=165601753216920 () CallingNum=6505 (James Collins) Internal=0 Time=8357 AState=Dialled
4132163386mS CMLineRx: v=1
CMAlerting
Line: type=Q931Line 1 Call: lid=0 id=792595 in=0
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLPublicNetRemoteUser (4), pd=CMPDInbandPattern (8)
4132163387mS CMExtnTx: v=6505, p1=0
CMAlerting
Line: type=DigitalExtn 9 Call: lid=0 id=792591 in=0
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLPublicNetRemoteUser (4), pd=CMPDInbandPattern (8)
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=01753216920
IE CMIEDeviceDetail (231) LOCALE=eng HW=15 VER=6 class=CMDeviceISDNTrunk type=0 number=1 channel=18 rx_gain=32 tx_gain=32 ep_callid=792595 ipaddr=192.168.2.5 apps=0
Timed: 28/07/10 15:22
4132163388mS CD: CALL: 0.792591.0 BState=Ringing Cut=3 Music=0.0 Aend="James Collins(6505)" (11.8) Bend="Line 1" [Line 1] (0.19) CalledNum=165601753216920 () CallingNum=6505 (James Collins) Internal=0 Time=8361 AState=Ringing
4132163445mS CMExtnRx: v=6591, p1=0
CMConnect
Line: type=DigitalExtn 8 Call: lid=0 id=792598 in=0
4132163445mS CMTARGET: 0.792596.0 157656 Aylin Pektas.0: CancelTimer CMTCCoverageTimeout
4132163447mS CMExtnTx: v=6537, p1=0
CMConnect
Line: type=DigitalExtn 6 Call: lid=0 id=792596 in=0
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Rachel Ellis
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=6591
IE CMIEDeviceDetail (231) LOCALE=eng HW=15 VER=6 class=CMDeviceStdPhone type=27 number=323 channel=0 rx_gain=32 tx_gain=32 ep_callid=792598 ipaddr=192.168.2.5 apps=0
Timed: 28/07/10 15:22
4132163447mS CMExtnRx: v=6537, p1=0
CMConnectAck
Line: type=DigitalExtn 6 Call: lid=0 id=792596 in=0
4132163447mS CMTARGET: 0.792596.0 157656 Aylin Pektas.0: ~CMTargetHandler f505a1b8 ep f5090e14
4132163451mS CMExtnTx: v=6591, p1=0
CMConnectAck
Line: type=DigitalExtn 8 Call: lid=0 id=792598 in=0
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Aylin Pektas
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=6537
IE CMIEDeviceDetail (231) LOCALE=eng HW=15 VER=6 class=CMDeviceStdPhone type=27 number=130 channel=0 rx_gain=32 tx_gain=32 ep_callid=792596 ipaddr=192.168.2.5 apps=0
Timed: 28/07/10 15:22
4132163452mS CD: CALL: 0.792596.0 BState=Connected Cut=3 Music=0.0 Aend="Aylin Pektas(6537)" (8.32) Bend="Rachel Ellis(6591)" [Rachel Ellis(6591)] (10.25) CalledNum=6591 (Rachel Ellis) CallingNum=6537 (Aylin Pektas) Internal=1 Time=4763 AState=Connected
4132163453mS CMMap: a=8.32 b=0.0 R0
4132163453mS CMMap: PCG::MapBChan pcp[196]b1r0 cp_b f532b2cc other_cp_b 0 type CGTypeSimple
4132163453mS CMMap: PCG::MapBChan pcp[200]b1r0 cp_b f5327f1c other_cp_b f532b2cc type CGTypeSimple
4132163453mS CMMap: a=8.32 b=10.25 M1
4132163495mS CMLineRx: v=1
CMAlerting
Line: type=Q931Line 1 Call: lid=0 id=792586 in=0
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLPublicNetRemoteUser (4), pd=CMPDInbandPattern (8)
4132163496mS CMExtnTx: v=6528, p1=0
CMAlerting
Line: type=DigitalExtn 6 Call: lid=0 id=792581 in=0
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLPublicNetRemoteUser (4), pd=CMPDInbandPattern (8)
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=07860633081
IE CMIEDeviceDetail (231) LOCALE=eng HW=15 VER=6 class=CMDeviceISDNTrunk type=0 number=1 channel=5 rx_gain=32 tx_gain=32 ep_callid=792586 ipaddr=192.168.2.5 apps=0
Timed: 28/07/10 15:22
4132163497mS CD: CALL: 0.792581.0 BState=Ringing Cut=3 Music=0.0 Aend="Matt Tibbert(6528)" (8.23) Bend="Line 1" [Line 1] (0.6) CalledNum=165607860633081 () CallingNum=6528 (Matt Tibbert) Internal=0 Time=14934 AState=Ringing
4132163742mS CMLineRx: v=1
CMConnect
Line: type=Q931Line 1 Call: lid=0 id=792586 in=0
BChan: slot=0 chan=5
4132163743mS CMExtnTx: v=6528, p1=0
CMConnect
Line: type=DigitalExtn 6 Call: lid=0 id=792581 in=0
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=07860633081
IE CMIEDeviceDetail (231) LOCALE=eng HW=15 VER=6 class=CMDeviceISDNTrunk type=0 number=1 channel=5 rx_gain=32 tx_gain=32 ep_callid=792586 ipaddr=192.168.2.5 apps=0
Timed: 28/07/10 15:22
4132163743mS CMExtnRx: v=6528, p1=0
CMConnectAck
Line: type=DigitalExtn 6 Call: lid=0 id=792581 in=0
4132163743mS CMTARGET: 0.792581.0 157653 Matt Tibbert.0: ~CMTargetHandler f504e664 ep f4e8e1d0
4132163746mS CMLineTx: v=1
CMConnectAck
Line: type=Q931Line 1 Call: lid=0 id=792586 in=0
BChan: slot=0 chan=5
4132163746mS CD: CALL: 0.792581.0 BState=Connected Cut=3 Music=0.0 Aend="Matt Tibbert(6528)" (8.23) Bend="Line 1" [Line 1] (0.6) CalledNum=165607860633081 () CallingNum=6528 (Matt Tibbert) Internal=0 Time=15183 AState=Connected
4132165894mS PRN: 6535: Digit Pressed 2
4132165894mS CMExtnRx: v=6535, p1=0
CMInformation
Line: type=DigitalExtn 6 Call: lid=0 id=792583 in=0
Called[2] Type=Default (100) Reason=CMDRdirect
4132165895mS CMLineTx: v=1
CMInformation
Line: type=Q931Line 1 Call: lid=0 id=792590 in=0
Called[2] Type=Default (100) Reason=CMDRdirect
BChan: slot=0 chan=15
4132166560mS PRN: 6560: Feature Key Pressed 6
4132166567mS CMLOGGING: CALL:2010/07/2815:22,00:00:15,006,6560,O,165601869336111,01869336111,AshleyHall,,,0,,""n/a,0
4132166567mS CD: CALL: 0.792559.0 BState=Connected Cut=2 Music=0.0 Aend="Ashley Hall(6560)" (9.24) Bend="Line 1" [Line 1] (0.4) CalledNum=165601869336111 () CallingNum=6560 (Ashley Hall) Internal=0 Time=27965 AState=Idle
4132166567mS CD: CALL: 0.792559.0 Deleted
4132166573mS CMLineTx: v=1
CMDisconnect
Line: type=Q931Line 1 Call: lid=0 id=792575 in=0
Cause=16, Normal call clearing
4132166579mS CMMap: PCG::UnmapBChan pcp[123]b1r0 cp_b f586b2f8 other_cp_b f52ee9f4
4132166579mS CMMap: a=9.24 b=0.4 M0
4132166579mS CMMap: PCG::UnmapBChan pcp[266]b1r0 cp_b f52ee9f4 other_cp_b 0
4132166632mS CMLineRx: v=1
CMReleaseComp
Line: type=Q931Line 1 Call: lid=0 id=792575 in=0
BChan: slot=0 chan=3
4132166633mS PRN: CDR - TCPSend maxqueuesize=500 operational=1
4132166781mS RES: Wed 28/7/2010 15:22:38 FreeMem=63546180(1) CMMsg=9 (9) Buff=200 905 998 7236 2 Links=16560
4132166782mS RES2: IP 500 V2 6.0(8) Tasks=39 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=89 Poll=0 Ready=1 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 ASC=1 SYS=MNTD OPT=UMNT
4132167328mS PRN: 6597: Feature Key Pressed 6
4132167334mS CMLOGGING: CALL:2010/07/2815:21,00:00:54,000,6597,O,16560046370333040,0046370333040,EvelinaArvidss,,,0,,""n/a,0
4132167334mS CD: CALL: 0.792519.0 BState=Connected Cut=2 Music=0.0 Aend="Evelina Arvidss(6597)" (10.31) Bend="Line 1" [Line 1] (0.3) CalledNum=16560046370333040 () CallingNum=6597 (Evelina Arvidss) Internal=0 Time=94975 AState=Idle
4132167335mS CD: CALL: 0.792519.0 Deleted
4132167340mS CMLineTx: v=1
CMDisconnect
Line: type=Q931Line 1 Call: lid=0 id=792524 in=0
Cause=16, Normal call clearing
4132167345mS CMMap: PCG::UnmapBChan pcp[122]b1r0 cp_b f586b494 other_cp_b f530cc78
4132167345mS CMMap: a=10.31 b=0.3 M0
4132167345mS CMMap: PCG::UnmapBChan pcp[229]b1r0 cp_b f530cc78 other_cp_b 0
4132167409mS CMLineRx: v=1
CMReleaseComp
Line: type=Q931Line 1 Call: lid=0 id=792524 in=0
BChan: slot=0 chan=2
4132167409mS PRN: CDR - TCPSend maxqueuesize=500 operational=1
4132168569mS CMExtnTx: v=6560, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 7 Call: lid=0 id=-1 in=0
Called[Ashley Hall Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Ashley Hall Msgs=0]
Timed: 28/07/10 15:22
4132168994mS CMLineRx: v=1
CMConnect
Line: type=Q931Line 1 Call: lid=0 id=792588 in=0
BChan: slot=0 chan=13
4132168995mS CMExtnTx: v=6515, p1=0
CMConnect
Line: type=DigitalExtn 6 Call: lid=0 id=792576 in=0
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=01420520066
IE CMIEDeviceDetail (231) LOCALE=eng HW=15 VER=6 class=CMDeviceISDNTrunk type=0 number=1 channel=13 rx_gain=32 tx_gain=32 ep_callid=792588 ipaddr=192.168.2.5 apps=0
Timed: 28/07/10 15:22
4132168995mS CMExtnRx: v=6515, p1=0
CMConnectAck
Line: type=DigitalExtn 6 Call: lid=0 id=792576 in=0
4132168995mS CMTARGET: 0.792576.0 157651 Nick Tubb.0: ~CMTargetHandler f503fe28 ep f4e9d044
4132168998mS CMLineTx: v=1
CMConnectAck
Line: type=Q931Line 1 Call: lid=0 id=792588 in=0
BChan: slot=0 chan=13
4132168999mS CD: CALL: 0.792576.0 BState=Connected Cut=3 Music=0.0 Aend="Nick Tubb(6515)" (8.10) Bend="Line 1" [Line 1] (0.14) CalledNum=165601420520066 () CallingNum=6515 (Nick Tubb) Internal=0 Time=25454 AState=Connected
4132169336mS CMExtnTx: v=6597, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 8 Call: lid=0 id=-1 in=0
Called[Evelina Arvidss Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Evelina Arvidss Msgs=0]
Timed: 28/07/10 15:22
4132170052mS PRN: 6528: Feature Key Pressed 6
4132170058mS CMLOGGING: CALL:2010/07/2815:22,00:00:06,000,6528,O,165607860633081,07860633081,MattTibbert,,,0,,""n/a,0
4132170058mS CD: CALL: 0.792581.0 BState=Connected Cut=2 Music=0.0 Aend="Matt Tibbert(6528)" (8.23) Bend="Line 1" [Line 1] (0.6) CalledNum=165607860633081 () CallingNum=6528 (Matt Tibbert) Internal=0 Time=21495 AState=Idle
4132170059mS CD: CALL: 0.792581.0 Deleted
4132170064mS CMLineTx: v=1
CMDisconnect
Line: type=Q931Line 1 Call: lid=0 id=792586 in=0
Cause=16, Normal call clearing
4132170069mS CMMap: PCG::UnmapBChan pcp[125]b1r0 cp_b f586afc0 other_cp_b f53156d4
4132170069mS CMMap: a=8.23 b=0.6 M0
4132170069mS CMMap: PCG::UnmapBChan pcp[218]b1r0 cp_b f53156d4 other_cp_b 0
4132170124mS CMLineRx: v=1
CMReleaseComp
Line: type=Q931Line 1 Call: lid=0 id=792586 in=0
BChan: slot=0 chan=5
4132170124mS PRN: CDR - TCPSend maxqueuesize=500 operational=1
4132170667mS PRN: 6503: Feature Key Pressed 6
4132170670mS PRN: CDR - TCPSend maxqueuesize=500 operational=1
4132170673mS CMLOGGING: CALL:2010/07/2815:21,00:00:41,002,02077663300,I,6503,116503,,,,0,,""n/a,0
4132170673mS CD: CALL: 1.15.1 BState=Disconnecting Cut=1 Music=0.0 Aend="Line 1" (0.15) Bend="Jennifer Saunde(6503)" [Jennifer Saunde(6503)] (11.6) CalledNum=6503 (Jennifer Saunde) CallingNum=02077663300 () Internal=0 Time=44151 AState=Connected
4132170674mS CD: CALL: 1.15.1 Deleted
4132170677mS CMLineTx: v=1
CMDisconnect
Line: type=Q931Line 1 Call: lid=1 id=15 in=1
Cause=16, Normal call clearing
4132170683mS CMMap: PCG::UnmapBChan pcp[134]b1r0 cp_b f586a144 other_cp_b f52f9964
4132170683mS CMMap: a=11.6 b=0.15 M0
4132170683mS CMMap: PCG::UnmapBChan pcp[252]b1r0 cp_b f52f9964 other_cp_b 0
4132170748mS CMLineRx: v=1
CMReleaseComp
Line: type=Q931Line 1 Call: lid=1 id=15 in=1
BChan: slot=0 chan=14
4132172060mS CMExtnTx: v=6528, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 6 Call: lid=0 id=-1 in=0
Called[Matt Tibbert Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Matt Tibbert Msgs=0]
Timed: 28/07/10 15:22
4132172253mS CMExtnRx: v=6537, p1=0
CMReleaseComp
Line: type=DigitalExtn 6 Call: lid=0 id=792596 in=0
4132172257mS PRN: CDR - TCPSend maxqueuesize=500 operational=1
4132172259mS CMLOGGING: CALL:2010/07/2815:22,00:00:08,002,6537,O,6591,6591,AylinPektas,,,1,,""n/a,0
4132172260mS CD: CALL: 0.792596.0 BState=Connected Cut=2 Music=0.0 Aend="Aylin Pektas(6537)" (8.32) Bend="Rachel Ellis(6591)" [Rachel Ellis(6591)] (10.25) CalledNum=6591 (Rachel Ellis) CallingNum=6537 (Aylin Pektas) Internal=1 Time=13570 AState=Idle
4132172260mS CD: CALL: 0.792596.0 Deleted
4132172264mS CMExtnTx: v=6591, p1=0
CMReleaseComp
Line: type=DigitalExtn 8 Call: lid=0 id=792598 in=0
Called[6591] Type=Default (100) Reason=CMDRdirect Calling[6537] Type=Internal Plan=Default
Cause=16, Normal call clearing
Timed: 28/07/10 15:22
4132172269mS CMMap: PCG::UnmapBChan pcp[200]b1r0 cp_b f5327f1c other_cp_b f532b2cc
4132172269mS CMMap: a=8.32 b=10.25 M0
4132172269mS CMMap: PCG::UnmapBChan pcp[196]b1r0 cp_b f532b2cc other_cp_b 0
4132172282mS RES: Wed 28/7/2010 15:22:44 FreeMem=63628060(1) CMMsg=9 (9) Buff=200 901 996 7234 2 Links=16654
4132172282mS RES2: IP 500 V2 6.0(8) Tasks=39 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=98 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 ASC=1 SYS=MNTD OPT=UMNT
4132172520mS PRN: 6528: Digit Pressed 0
4132172524mS CMExtnRx: v=6528, p1=0
CMSetup
Line: type=DigitalExtn 6 Call: lid=0 id=792599 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[6528] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=Matt Tibbert
4132172526mS CMTARGET: 0.792599.0 157657 Matt Tibbert.0: LOOKUP CALL ROUTE: type=100 called_party= sub= calling=6528 dir=out complete=0 ses=0
4132172526mS CMTARGET: 0.792599.0 157657 Matt Tibbert.0: ADD TARGET (N): number= type=100 depth=1 nobar=1 setorig=1 ses=0
4132172527mS CMExtnTx: v=6528, p1=0
CMSetupAck
Line: type=DigitalExtn 6 Call: lid=0 id=792599 in=0
BChan: slot=8 chan=23
Timed: 28/07/10 15:22
4132172528mS CMExtnRx: v=6528, p1=0
CMInformation
Line: type=DigitalExtn 6 Call: lid=0 id=792599 in=0
Called[0] Type=Default (100) Reason=CMDRdirect
4132172529mS CD: CALL: 0.792599.0 BState=Idle Cut=0 Music=0.0 Aend="Matt Tibbert(6528)" (8.23) Bend="" [] (0.0) CalledNum=0 () CallingNum=6528 (Matt Tibbert) Internal=1 Time=9 AState=Dialling
4132172611mS CMExtnRx: v=6591, p1=0
CMSetup
Line: type=DigitalExtn 8 Call: lid=0 id=792601 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[6591] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=Rachel Ellis
4132172612mS CMTARGET: 0.792601.0 157658 Rachel Ellis.0: LOOKUP CALL ROUTE: type=100 called_party= sub= calling=6591 dir=out complete=0 ses=0
4132172612mS CMTARGET: 0.792601.0 157658 Rachel Ellis.0: ADD TARGET (N): number= type=100 depth=1 nobar=1 setorig=1 ses=0
4132172614mS CMExtnTx: v=6591, p1=0
CMSetupAck
Line: type=DigitalExtn 8 Call: lid=0 id=792601 in=0
BChan: slot=10 chan=25
Timed: 28/07/10 15:22
4132172614mS CD: CALL: 0.792601.0 BState=Idle Cut=1 Music=3.0 Aend="Rachel Ellis(6591)" (10.25) Bend="" [] (0.0) CalledNum= () CallingNum=6591 (Rachel Ellis) Internal=1 Time=8 AState=DialInitiated
4132172615mS CMMap: a=10.25 b=0.0 D1
4132172678mS CMExtnTx: v=6503, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 9 Call: lid=0 id=-1 in=0
Called[Jennifer Saunde Msgs=0 Old=0 Sav=1] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Jennifer Saunde Msgs=0]
Timed: 28/07/10 15:22
4132172802mS PRN: 6528: Digit Pressed 1
4132172802mS CMExtnRx: v=6528, p1=0
CMInformation
Line: type=DigitalExtn 6 Call: lid=0 id=792599 in=0
Called[1] Type=Default (100) Reason=CMDRdirect
4132173012mS PRN: 6528: Digit Pressed 7
4132173012mS CMExtnRx: v=6528, p1=0
CMInformation
Line: type=DigitalExtn 6 Call: lid=0 id=792599 in=0
Called[7] Type=Default (100) Reason=CMDRdirect
4132173229mS PRN: 6528: Digit Pressed 0
4132173229mS CMExtnRx: v=6528, p1=0
CMInformation
Line: type=DigitalExtn 6 Call: lid=0 id=792599 in=0
Called[0] Type=Default (100) Reason=CMDRdirect
4132173454mS PRN: 6528: Digit Pressed 6
4132173454mS CMExtnRx: v=6528, p1=0
CMInformation
Line: type=DigitalExtn 6 Call: lid=0 id=792599 in=0
Called[6] Type=Default (100) Reason=CMDRdirect
4132174185mS H323Evt: V6 on the other side of line 130 tcp f4fd9000 shared_tcp f527ddf8 tcp f4fd9000 shared_msg_sent 0 new_caps f ver_major 6
4132174261mS CMExtnTx: v=6537, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 6 Call: lid=0 id=-1 in=0
Called[Aylin Pektas Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Aylin Pektas Msgs=0]
Timed: 28/07/10 15:22
4132174367mS PRN: 6528: Digit Pressed 8
4132174367mS CMExtnRx: v=6528, p1=0
CMInformation
Line: type=DigitalExtn 6 Call: lid=0 id=792599 in=0
Called[8] Type=Default (100) Reason=CMDRdirect
4132174483mS H323Evt: V6 on the other side of line 130 tcp f527ddf8 shared_tcp f527ddf8 tcp f527ddf8 shared_msg_sent 1 new_caps f ver_major 6
4132174483mS H323Evt: H323 stack for line 130 is connected
4132174484mS H323Evt: H323Pipe::ConnectIndication cap 1
4132174484mS H323Evt: H323Pipe::ConnectIndication cap 2
4132174484mS H323Evt: H323Pipe::ConnectIndication cap f
4132174484mS H323Evt: Shared TCP became operational for line 130
4132174487mS CMLineRx: v=1
CMConnect
Line: type=Q931Line 1 Call: lid=0 id=792595 in=0
BChan: slot=0 chan=18
4132174487mS CMExtnTx: v=6505, p1=0
CMConnect
Line: type=DigitalExtn 9 Call: lid=0 id=792591 in=0
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=01753216920
IE CMIEDeviceDetail (231) LOCALE=eng HW=15 VER=6 class=CMDeviceISDNTrunk type=0 number=1 channel=18 rx_gain=32 tx_gain=32 ep_callid=792595 ipaddr=192.168.2.5 apps=0
Timed: 28/07/10 15:22
4132174488mS CMExtnRx: v=6505, p1=0

 

[TheSmash]

Need more trace options selected on the H323 tab of sysmon. Post this trace once you have all the H323 options (less Whole Packet).

Looks like the line disconnects and the reconnects 15 seconds later. I have the exact same issue on a V5 SCN.

Do you use Sonicwalls per chance?

ACSS (SME)

My name is Mike but everyone calls me The Smash...

 

[pdob]

I checked all options in H.323 tab except View Whole Packet. Yes, we do have Sonicwall at all locations but only 1 site having this issue

Calling x6530 on IP Line 130...



********** Warning: Logging to Screen Started **********
4219473380mS PRN: 6538: Feature Key Pressed 6
4219473385mS CMLOGGING: CALL:2010/07/2915:36,00:00:39,016,6538,O,165601622716543,01622716543,NeelanSamaratu,,,0,,""n/a,0
4219473385mS CD: CALL: 0.814279.0 BState=Connected Cut=2 Music=0.0 Aend="Neelan Samaratu(6538)" (9.3) Bend="Line 1" [Line 1] (0.3) CalledNum=165601622716543 () CallingNum=6538 (Neelan Samaratu) Internal=0 Time=62018 AState=Idle
4219473385mS CD: CALL: 0.814279.0 Deleted
4219473389mS CMLineTx: v=1
CMDisconnect
Line: type=Q931Line 1 Call: lid=0 id=814282 in=0
Cause=16, Normal call clearing
4219473392mS CMMap: PCG::UnmapBChan pcp[122]b1r0 cp_b f586b494 other_cp_b f5323194
4219473392mS CMMap: a=9.3 b=0.3 M0
4219473392mS CMMap: PCG::UnmapBChan pcp[206]b1r0 cp_b f5323194 other_cp_b 0
4219473468mS CMLineRx: v=1
CMReleaseComp
Line: type=Q931Line 1 Call: lid=0 id=814282 in=0
BChan: slot=0 chan=2
4219473468mS PRN: CDR - TCPSend maxqueuesize=500 operational=1
4219473556mS PRN: 6538: Feature Key Pressed 6
4219473781mS RES: Thu 29/7/2010 15:37:38 FreeMem=63711808(2) CMMsg=9 (9) Buff=200 901 997 7227 2 Links=16458
4219473782mS RES2: IP 500 V2 6.0(8) Tasks=39 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=83 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 ASC=1 SYS=MNTD OPT=UMNT
4219475007mS H323Rx: 1957 src=192.168.0.9:1720
H323 Pcol=08(Q931) Reflen=2 ref=0004(Remote)
Message Type = Setup
ForwardLCParameters [G711_ULAW64K (frames=20)] H2250 SID=01: RTP=192.168.0.9(49152) , RTCP=192.168.0.9(49153) ,
ReverseLCParameters [G711_ULAW64K (frames=20)] H2250 SID=01: RTP=192.168.0.9(49152) , RTCP=192.168.0.9(49153) ,
ForwardLCParameters [G729_A (frames=2)] H2250 SID=01: RTP=192.168.0.9(49152) , RTCP=192.168.0.9(49153) ,
ReverseLCParameters [G729_A (frames=2)] H2250 SID=01: RTP=192.168.0.9(49152) , RTCP=192.168.0.9(49153) ,
ForwardLCParameters [G729 (frames=2)] H2250 SID=01: RTP=192.168.0.9(49152) , RTCP=192.168.0.9(49153) ,
ReverseLCParameters [G729 (frames=2)] H2250 SID=01: RTP=192.168.0.9(49152) , RTCP=192.168.0.9(49153) ,
ForwardLCParameters [G729_WB (frames=2)] H2250 SID=01: RTP=192.168.0.9(49152) , RTCP=192.168.0.9(49153) ,
ReverseLCParameters [G729_WB (frames=2)] H2250 SID=01: RTP=192.168.0.9(49152) , RTCP=192.168.0.9(49153) ,
ForwardLCParameters [G729_AWB (frames=2)] H2250 SID=01: RTP=192.168.0.9(49152) , RTCP=192.168.0.9(49153) ,
ReverseLCParameters [G729_AWB (frames=2)] H2250 SID=01: RTP=192.168.0.9(49152) , RTCP=192.168.0.9(49153) ,
ForwardLCParameters [G711_ALAW64K (frames=20)] H2250 SID=01: RTP=192.168.0.9(49152) , RTCP=192.168.0.9(49153) ,
ReverseLCParameters [G711_ALAW64K (frames=20)] H2250 SID=01: RTP=192.168.0.9(49152) , RTCP=192.168.0.9(49153) ,
ForwardLCParameters G7231 H2250 SID=01: RTP=192.168.0.9(49152) , RTCP=192.168.0.9(49153) ,
ReverseLCParameters G7231 H2250 SID=01: RTP=192.168.0.9(49152) , RTCP=192.168.0.9(49153) ,
ForwardLCParameters GSM H2250 SID=01: RTP=192.168.0.9(49152) , RTCP=192.168.0.9(49153) ,
ReverseLCParameters GSM H2250 SID=01: RTP=192.168.0.9(49152) , RTCP=192.168.0.9(49153) ,
ForwardLCParameters H2250 SID=01: RTP=192.168.0.9(49152) , RTCP=192.168.0.9(49153) ,
ReverseLCParameters H2250 SID=01: RTP=192.168.0.9(49152) , RTCP=192.168.0.9(49153) ,

4219475008mS H323Evt: v=0 stacknum=130 State, new=NullState, old=NullState id=-1
4219475008mS H323Evt: v=0 stacknum=130 State, new=Present, old=NullState id=958
4219475014mS CMLineRx: v=130
CMSetup
Line: type=IPLine 130 Call: lid=130 id=958 in=1
Called[6530] Type=Default (100) Reason=CMDRdirect SndComp Calling[3258] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
IE CMIEFastStartInfoData (6)
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
CallingName.Invoke.CodePageISO8859-1
invokeId 19917
user 'Paul Dobluis' presentation Allowed
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Paul Dobluis
IE CMIERespondingPartyKName (229)(Type=CMNameDefault) name=Paul Dobluis
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=3258
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=6 class=CMDeviceStdPhone type=10 number=128 channel=0 rx_gain=32 tx_gain=32 ep_callid=77700 ipaddr=192.168.0.9 apps=0
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=Sebastian
IE CMIECalledPartyKName (225)(Type=CMNameDefault) name=Sebastian Cole
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=Paul Dobluis
IE CMIECallingPartyKName (226)(Type=CMNameDefault) name=Paul Dobluis
IE CMIEMohSourceId (247) MOH Source = 255
4219475019mS CD: CALL: 130.958.1 BState=Idle Cut=1 Music=0.0 Aend="Line 130" (380.1) Bend="" [] (0.0) CalledNum=6530 (Sebastian) CallingNum=3258 (Paul Dobluis) Internal=0 Time=4 AState=Idle
4219475020mS CMTARGET: 130.958.1 161914 H323TrunkEP: LOOKUP CALL ROUTE: type=100 called_party=6530 sub= calling=3258 dir=out complete=1 ses=0
4219475020mS CMTARGET: 130.958.1 161914 H323TrunkEP: ADD TARGET (N): number=6530 type=100 depth=1 nobar=1 setorig=1 ses=0
4219475020mS CMTARGET: 130.958.1 161914 H323TrunkEP: SET USER: Sebastian orig=1
4219475021mS CMTARGET: 130.958.1 161914 H323TrunkEP: ADD USER: Sebastian depth=2 disallow_cw=0 dnd=0 real_call=1 group_call=0 type(CMNTypeDefault) incl(0x0) excpt(0x0), allow_redir(1) remote=00000000
4219475021mS CMMap: a=4.1 b=0.0 Mapper::AllocateCodec allocated CMRTVocoder resource busy 1, total 64
4219475023mS CMTARGET: 0.814305.0 161914 Sebastian.0: ADD PRIMARY
4219475023mS CMTARGET: 130.958.1 161914 H323TrunkEP: INITIAL TARGETING SUCCEEDED
4219475024mS CMTARGET: 130.958.1 161914 H323TrunkEP: GetNoAnswerTimer:15
4219475025mS CMLineTx: v=130
CMProceeding
Line: type=IPLine 130 Call: lid=130 id=958 in=1
4219475027mS CMExtnCopyProcessCallMsg: v=23
CMSetup
Line: type=NoLine 0 Call: lid=0 id=814305 in=0
Called[6530] Type=Default (100) Reason=CMDRdirect SndComp Calling[3258] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
CallingName.Invoke.CodePageISO8859-1
invokeId 19917
user 'Paul Dobluis' presentation Allowed
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Paul Dobluis
IE CMIERespondingPartyKName (229)(Type=CMNameDefault) name=Paul Dobluis
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=3258
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=6 class=CMDeviceStdPhone type=10 number=128 channel=0 rx_gain=32 tx_gain=32 ep_callid=77700 ipaddr=192.168.0.9 apps=0
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=Sebastian
IE CMIECalledPartyKName (225)(Type=CMNameDefault) name=Sebastian Cole
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=Paul Dobluis
IE CMIECallingPartyKName (226)(Type=CMNameDefault) name=Paul Dobluis
IE CMIEMohSourceId (247) MOH Source = 255
Display [Paul Dobluis>Sebastian]
Timed: 29/07/10 15:37
Locale: enu
4219475028mS CMExtnTxP: v=6530
CMSetup
Line: type=DigitalExtn 6 Call: lid=0 id=814305 in=0
Called[6530] Type=Default (100) Reason=CMDRdirect SndComp Calling[3258] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
CallingName.Invoke.CodePageISO8859-1
invokeId 19917
user 'Paul Dobluis' presentation Allowed
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Paul Dobluis
IE CMIERespondingPartyKName (229)(Type=CMNameDefault) name=Paul Dobluis
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=3258
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=6 class=CMDeviceStdPhone type=10 number=128 channel=0 rx_gain=32 tx_gain=32 ep_callid=77700 ipaddr=192.168.0.9 apps=0
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=Sebastian
IE CMIECalledPartyKName (225)(Type=CMNameDefault) name=Sebastian Cole
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=Paul Dobluis
IE CMIECallingPartyKName (226)(Type=CMNameDefault) name=Paul Dobluis
IE CMIEMohSourceId (247) MOH Source = 255
Display [Paul Dobluis>Sebastian]
Timed: 29/07/10 15:37
Locale: enu
4219475028mS CMExtnTx: v=6530, p1=0
CMSetup
Line: type=DigitalExtn 6 Call: lid=0 id=814305 in=0
Called[6530] Type=Default (100) Reason=CMDRdirect SndComp Calling[3258] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
CallingName.Invoke.CodePageISO8859-1
invokeId 19917
user 'Paul Dobluis' presentation Allowed
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Paul Dobluis
IE CMIERespondingPartyKName (229)(Type=CMNameDefault) name=Paul Dobluis
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=3258
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=6 class=CMDeviceStdPhone type=10 number=128 channel=0 rx_gain=32 tx_gain=32 ep_callid=77700 ipaddr=192.168.0.9 apps=0
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=Sebastian
IE CMIECalledPartyKName (225)(Type=CMNameDefault) name=Sebastian Cole
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=Paul Dobluis
IE CMIECallingPartyKName (226)(Type=CMNameDefault) name=Paul Dobluis
IE CMIEMohSourceId (247) MOH Source = 255
Display [Paul Dobluis>Sebastian]
Timed: 29/07/10 15:37
Locale: enu
4219475028mS CMExtnRx: v=6530, p1=0
CMAlerting
Line: type=DigitalExtn 6 Call: lid=0 id=814305 in=0
4219475029mS CMExtnCopyProcessMsg: v=23
CMAlerting
Line: type=DigitalExtn 6 Call: lid=0 id=814305 in=0
4219475033mS CMLineTx: v=130
CMAlerting
Line: type=IPLine 130 Call: lid=130 id=958 in=1
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Sebastian
IE CMIERespondingPartyKName (229)(Type=CMNameDefault) name=Sebastian Cole
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=6530
IE CMIEDeviceDetail (231) LOCALE=eng HW=15 VER=6 class=CMDeviceStdPhone type=27 number=123 channel=0 rx_gain=32 tx_gain=32 ep_callid=814305 ipaddr=192.168.2.5 apps=0
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
AlertingName.Invoke.CodePageUtf8String
invokeId 45553
user 'Sebastian' presentation Allowed
IE CMIEFastStartInfoData (6)
4219475035mS CD: CALL: 130.958.1 BState=Ringing Cut=1 Music=2.0 Aend="Line 130" (380.1) Bend="Sebastian(6530)" [Sebastian(6530)] (0.0) CalledNum=6530 (Sebastian) CallingNum=3258 (Paul Dobluis) Internal=0 Time=20 AState=Ringing
4219475036mS CMMap: PCG::AddToneGenerator g R1[3] for cp[410]b0r1 append pcp[411]b0r1 (total 1)
4219475036mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[411]b0r1 Configure 0.0
4219475036mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[411]b0r1 ConnectIndication 0.0
4219475037mS CMMap: a=0.0 b=0.0 pcp[410]b0r1 RTPR1
4219475041mS H323Evt: v=0 stacknum=130 State, new=ICProceeding, old=Present id=958
4219475042mS H323Tx: 1957 dst=192.168.0.9:1720
H323 Pcol=08(Q931) Reflen=2 ref=0004(Local)
Message Type = CallProceeding

4219475043mS H323Evt: v=0 stacknum=130 State, new=Received, old=ICProceeding id=958
4219475044mS H323Tx: 1957 dst=192.168.0.9:1720
H323 Pcol=08(Q931) Reflen=2 ref=0004(Local)
Message Type = Alerting
ForwardLCParameters [G711_ULAW64K (frames=20)] H2250 SID=01: RTP=192.168.2.5(49152) , RTCP=192.168.2.5(49153) ,
ReverseLCParameters [G711_ULAW64K (frames=20)] H2250 SID=01: RTP=192.168.2.5(49152) , RTCP=192.168.2.5(49153) ,

4219475386mS CMExtnTx: v=6538, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 7 Call: lid=0 id=-1 in=0
Called[Neelan Samaratu Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Neelan Samaratu Msgs=0]
Timed: 29/07/10 15:37
4219476035mS H323Evt: RTP(50t): 192.168.2.5/49152 192.168.0.9/49152 CODEC=Ulaw64K(5) PKTSZ=160 RFC2833=off AGE=999 SENT=50 (avg size=160) RECV=0 (avg size=0)
4219476654mS CMExtnRx: v=6536, p1=0
CMSetup
Line: type=DigitalExtn 6 Call: lid=0 id=814306 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[6536] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=Nahal Safarzade
4219476655mS CMTARGET: 0.814306.0 161915 Nahal Safarzade.0: LOOKUP CALL ROUTE: type=100 called_party= sub= calling=6536 dir=out complete=0 ses=0
4219476656mS CMTARGET: 0.814306.0 161915 Nahal Safarzade.0: ADD TARGET (N): number= type=100 depth=1 nobar=1 setorig=1 ses=0
4219476657mS CMExtnTx: v=6536, p1=0
CMSetupAck
Line: type=DigitalExtn 6 Call: lid=0 id=814306 in=0
BChan: slot=8 chan=31
Timed: 29/07/10 15:37
4219476657mS CD: CALL: 0.814306.0 BState=Idle Cut=1 Music=3.0 Aend="Nahal Safarzade(6536)" (8.31) Bend="" [] (0.0) CalledNum= () CallingNum=6536 (Nahal Safarzade) Internal=1 Time=7 AState=DialInitiated
4219476658mS CMMap: a=8.31 b=0.0 D1
4219476673mS CMExtnRx: v=6509, p1=0
CMReleaseComp
Line: type=DigitalExtn 6 Call: lid=0 id=814296 in=0
4219476678mS CMLOGGING: CALL:2010/07/2915:37,00:00:11,004,6509,O,165601274394679,01274394679,AnthonyGunning,,,0,,""n/a,0
4219476678mS CD: CALL: 0.814296.0 BState=Connected Cut=2 Music=0.0 Aend="Anthony Gunning(6509)" (8.4) Bend="Line 1" [Line 1] (0.11) CalledNum=165601274394679 () CallingNum=6509 (Anthony Gunning) Internal=0 Time=24518 AState=Idle
4219476678mS CD: CALL: 0.814296.0 Deleted
4219476682mS CMLineTx: v=1
CMDisconnect
Line: type=Q931Line 1 Call: lid=0 id=814302 in=0
Cause=16, Normal call clearing
4219476685mS CMMap: PCG::UnmapBChan pcp[130]b1r0 cp_b f586a7b4 other_cp_b f52edd60
4219476685mS CMMap: a=8.4 b=0.11 M0
4219476685mS CMMap: PCG::UnmapBChan pcp[267]b1r0 cp_b f52edd60 other_cp_b 0
4219476765mS CMLineRx: v=1
CMReleaseComp
Line: type=Q931Line 1 Call: lid=0 id=814302 in=0
BChan: slot=0 chan=10
4219476765mS PRN: CDR - TCPSend maxqueuesize=500 operational=1
4219476902mS PRN: 6536: Digit Pressed 0
4219476902mS CMExtnRx: v=6536, p1=0
CMInformation
Line: type=DigitalExtn 6 Call: lid=0 id=814306 in=0
Called[0] Type=Default (100) Reason=CMDRdirect
4219476904mS CMMap: a=8.31 b=0.0 D0
4219477046mS PRN: 6536: Digit Pressed 0
4219477046mS CMExtnRx: v=6536, p1=0
CMInformation
Line: type=DigitalExtn 6 Call: lid=0 id=814306 in=0
Called[0] Type=Default (100) Reason=CMDRdirect
4219477261mS PRN: 6536: Digit Pressed 4
4219477261mS CMExtnRx: v=6536, p1=0
CMInformation
Line: type=DigitalExtn 6 Call: lid=0 id=814306 in=0
Called[4] Type=Default (100) Reason=CMDRdirect
4219477524mS PRN: 6536: Digit Pressed 6
4219477524mS CMExtnRx: v=6536, p1=0
CMInformation
Line: type=DigitalExtn 6 Call: lid=0 id=814306 in=0
Called[6] Type=Default (100) Reason=CMDRdirect
4219477824mS PRN: 6536: Digit Pressed 3
4219477824mS CMExtnRx: v=6536, p1=0
CMInformation
Line: type=DigitalExtn 6 Call: lid=0 id=814306 in=0
Called[3] Type=Default (100) Reason=CMDRdirect
4219477984mS PRN: 6536: Digit Pressed 3
4219477984mS CMExtnRx: v=6536, p1=0
CMInformation
Line: type=DigitalExtn 6 Call: lid=0 id=814306 in=0
Called[3] Type=Default (100) Reason=CMDRdirect
4219478486mS PRN: 6536: Digit Pressed 2
4219478486mS CMExtnRx: v=6536, p1=0
CMInformation
Line: type=DigitalExtn 6 Call: lid=0 id=814306 in=0
Called[2] Type=Default (100) Reason=CMDRdirect
4219478638mS PRN: 6536: Digit Pressed 2
4219478638mS CMExtnRx: v=6536, p1=0
CMInformation
Line: type=DigitalExtn 6 Call: lid=0 id=814306 in=0
Called[2] Type=Default (100) Reason=CMDRdirect
4219478679mS CMExtnTx: v=6509, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 6 Call: lid=0 id=-1 in=0
Called[Anthony Gunning Msgs=0 Old=0 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000000] Type=Default Plan=Default
Display [Anthony Gunning Msgs=0]
Timed: 29/07/10 15:37
4219478831mS PRN: 6536: Digit Pressed 2
4219478831mS CMExtnRx: v=6536, p1=0
CMInformation
Line: type=DigitalExtn 6 Call: lid=0 id=814306 in=0
Called[2] Type=Default (100) Reason=CMDRdirect
4219479173mS H323Rx: 1957 src=192.168.0.9:1720
H323 Pcol=08(Q931) Reflen=2 ref=0004(Remote)
Message Type = ReleaseComplete

4219479174mS H323Evt: v=0 stacknum=130 State, new=NullState, old=Received id=958
4219479174mS CMLineRx: v=130
CMReleaseComp
Line: type=IPLine 130 Call: lid=130 id=958 in=1
4219479175mS CMTARGET: 130.958.1 161914 H323TrunkEP: CancelTimer CMTCCoverageTimeout
4219479176mS PRN: CDR - TCPSend maxqueuesize=500 operational=1
4219479179mS CMLOGGING: CALL:2010/07/2915:37,00:00:00,004,3258,I,6530,6530,PaulDobluis,,,0,,""n/a,0
4219479180mS CD: CALL: 130.958.1 BState=Ringing Cut=0 Music=0.0 Aend="Line 130" (380.1) Bend="Sebastian(6530)" [Sebastian(6530)] (0.0) CalledNum=6530 (Sebastian) CallingNum=3258 (Paul Dobluis) Internal=0 Time=4164 AState=Idle
4219479180mS CD: CALL: 130.958.1 Deleted
4219479180mS CMTARGET: 130.958.1 -1 H323TrunkEP: ~CMTargetHandler f50617d0 ep f51e6470
4219479182mS CMExtnCopyCallLostMsg: v=23
CMReleaseComp
Line: type=NoLine 0 Call: lid=0 id=814305 in=0
Cause=16, Normal call clearing
4219479183mS CMExtnTxP: v=6530
CMReleaseComp
Line: type=DigitalExtn 6 Call: lid=0 id=814305 in=0
Called[6530] Type=Default (100) Reason=CMDRdirect Calling[3258] Type=Internal Plan=Default
Cause=16, Normal call clearing
Timed: 29/07/10 15:37
4219479183mS CMExtnTx: v=6530, p1=0
CMReleaseComp
Line: type=DigitalExtn 6 Call: lid=0 id=814305 in=0
Called[6530] Type=Default (100) Reason=CMDRdirect Calling[3258] Type=Internal Plan=Default
Cause=16, Normal call clearing
Timed: 29/07/10 15:37
4219479187mS CMMap: a=0.0 b=0.0 pcp[410]b0r1 RTPR0
4219479188mS H323Evt: RTP(END): 192.168.2.5/49152 192.168.0.9/49152 CODEC=Ulaw64K(5) PKTSZ=160 RFC2833=off AGE=4151 SENT=207 RECV=0 RTdelay=0 jitter=0 loss=0 remotejitter=0 remoteloss=0
4219479188mS CMMap: a=4.1 b=0.0 Mapper::FreeCodec freed CMRTVocoder resource busy 0, total 64
4219479192mS PRN: Config Write Wake Up
4219479282mS RES: Thu 29/7/2010 15:37:44 FreeMem=63708880(2) CMMsg=9 (9) Buff=200 901 996 7224 2 Links=16463
4219479282mS RES2: IP 500 V2 6.0(8) Tasks=39 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=91 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 ASC=1 SYS=MNTD OPT=UMNT
4219479399mS PRN: 6536: Digit Pressed 1
4219479399mS CMExtnRx: v=6536, p1=0
CMInformation
Line: type=DigitalExtn 6 Call: lid=0 id=814306 in=0
Called[1] Type=Default (100) Reason=CMDRdirect
4219479692mS PRN: Updates IO list size 0 updated list size 1
4219479693mS PRN: Config Write Completed
4219479735mS CMExtnTxP: v=6530
CMShortCode
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
ShortCode RefreshConfig (72) = []
4219479736mS CMExtnRxP: v=6530
CMReadShortCode
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
ShortCode DoNotDisturbExceptionAdd (9) = []
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
4219479736mS CMExtnTxP: v=6530
CMShortCode
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
ShortCode DoNotDisturbExceptionDel (10) = []
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
4219479747mS PRN: 6536: Digit Pressed 8
4219479747mS CMExtnRx: v=6536, p1=0
CMInformation
Line: type=DigitalExtn 6 Call: lid=0 id=814306 in=0
Called[8] Type=Default (100) Reason=CMDRdirect
4219480021mS PRN: 6536: Digit Pressed 1
4219480021mS CMExtnRx: v=6536, p1=0
CMInformation
Line: type=DigitalExtn 6 Call: lid=0 id=814306 in=0
Called[1] Type=Default (100) Reason=CMDRdirect
4219480843mS PRN: 6568: Digit Pressed 0
4219480847mS CMExtnRx: v=6568, p1=0
CMSetup
Line: type=DigitalExtn 7 Call: lid=0 id=814308 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[6568] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=Alex Bayle
4219480848mS CMTARGET: 0.814308.0 161916 Alex Bayle.0: LOOKUP CALL ROUTE: type=100 called_party= sub= calling=6568 dir=out complete=0 ses=0
4219480848mS CMTARGET: 0.814308.0 161916 Alex Bayle.0: ADD TARGET (N): number= type=100 depth=1 nobar=1 setorig=1 ses=0
4219480849mS CMExtnTx: v=6568, p1=0
CMSetupAck
Line: type=DigitalExtn 7 Call: lid=0 id=814308 in=0
BChan: slot=9 chan=31
Timed: 29/07/10 15:37
4219480850mS CMExtnRx: v=6568, p1=0
CMInformation
Line: type=DigitalExtn 7 Call: lid=0 id=814308 in=0
Called[0] Type=Default (100) Reason=CMDRdirect
4219480851mS CD: CALL: 0.814308.0 BState=Idle Cut=0 Music=0.0 Aend="Alex Bayle(6568)" (9.31) Bend="" [] (0.0) CalledNum=0 () CallingNum=6568 (Alex Bayle) Internal=1 Time=8 AState=Dialling
4219481021mS CMTARGET: 0.814306.0 161915 Nahal Safarzade.0: TimerExpired cause=CMTCDelayedProcessing
4219481021mS CMTARGET: 0.814306.0 161915 Nahal Safarzade.0: LOOKUP CALL ROUTE: type=100 called_party=004633222181 sub= calling=6536 dir=out complete=0 ses=0
4219481021mS CMTARGET: 0.814306.0 161915 Nahal Safarzade.0: ADD TARGET (N): number=004633222181 type=100 depth=1 nobar=1 setorig=1 ses=0
4219481022mS CMTARGET: 0.814306.0 161915 Nahal Safarzade.0: DEF SC: 004633222181 0 sc=type=Dial code=?, num=.
4219481022mS CMARS: FindActiveARSByGroupID GroupID=51 - Found
4219481023mS CMARS: MakeCallTarget - Called Number: 004633222181
4219481023mS CMTARGET: 0.814306.0 161915 Nahal Safarzade.0: CancelTimer CMTCDialToneTimeout
4219481023mS CMTARGET: 0.814306.0 161915 Nahal Safarzade.0: INITIAL TARGETING SUCCEEDED
4219481023mS CMTARGET: 0.814306.0 161915 Nahal Safarzade.0: GetNoAnswerTimer:15
4219481024mS CMTARGET: 0.814306.0 161915 Nahal Safarzade.0: CancelTimer CMTCNoAnswerTimeout
4219481025mS CMARS: FORM: Mayflower - Received Number: 004633222181
4219481025mS CMARS: FOUND A SHORT CODE - short_code: 0N - Tel: 1656. - Called_Party: 1656004633222181 - Line Group Id: 0
4219481025mS CMARS: FindActiveARSByGroupID GroupID=0 - Not Found
4219481025mS CMLRQ: FindActiveLRQByGroupID GroupID=0 - Not Found
4219481026mS CMLineTx: v=5
CMRestart
Line: type=Q931Line 5 Call: lid=0 id=-1 in=0
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
Display [eng]
Locale: eng
4219481026mS CMTARGET: Problem with Line Id: 5 - preselected: 00000000, Unlicenced: 0, OperationalTest: 1, NewCallResourceCheck: -1
4219481029mS CMARS: FOUND LINE - Line Id: 1 - using line group id: 0 - Called Number: 1656004633222181 - Calling Number: 6536
4219481029mS CMARS: SEND Setup TO LINE
4219481029mS CMLineTx: v=1
CMSetup
Line: type=Q931Line 1 Call: lid=0 id=814311 in=0
Called[1656004633222181] Type=Unknown (0) Reason=CMDRdirect Calling[116536] Type=Unknown Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
Locale: eng
4219481030mS CD: CALL: 0.814306.0 BState=Idle Cut=2 Music=0.0 Aend="Nahal Safarzade(6536)" (8.31) Bend="" [Line 1] (0.0) CalledNum=004633222181 () CallingNum=6536 (Nahal Safarzade) Internal=1 Time=4380 AState=Dialling
4219481073mS PRN: 6568: Digit Pressed 1
4219481073mS CMExtnRx: v=6568, p1=0
CMInformation
Line: type=DigitalExtn 7 Call: lid=0 id=814308 in=0
Called[1] Type=Default (100) Reason=CMDRdirect
4219481153mS CMLineRx: v=1
CMSetupAck
Line: type=Q931Line 1 Call: lid=0 id=814311 in=0
BChan: slot=0 chan=2
4219481155mS CMARS: LINE ep Received: CMSetupAck - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
4219481155mS CMARS: LINE IS NOW THE B END OF THE CALL - CMARSEndpoint::RequestEnd()
4219481155mS CMARS: CMARSEndpoint::CallLost(cause=124) - Address: 0.814310.0 161915 ARS for Mayflower - Call State: CMCSOverlapRecv
4219481157mS CD: CALL: 0.814306.0 BState=Idle Cut=2 Music=0.0 Aend="Nahal Safarzade(6536)" (8.31) Bend="Line 1" [Line 1] (0.3) CalledNum=1656004633222181 () CallingNum=6536 (Nahal Safarzade) Internal=0 Time=4507 AState=Dialling
4219481158mS CMMap: PCG::MapBChan pcp[195]b1r0 cp_b f532bfb8 other_cp_b 0 type CGTypeSimple
4219481158mS CMMap: PCG::MapBChan pcp[122]b1r0 cp_b f586b494 other_cp_b f532bfb8 type CGTypeSimple
4219481159mS CMMap: a=8.31 b=0.3 M1
4219481265mS PRN: 6568: Digit Pressed 7
4219481265mS CMExtnRx: v=6568, p1=0
CMInformation
Line: type=DigitalExtn 7 Call: lid=0 id=814308 in=0
Called[7] Type=Default (100) Reason=CMDRdirect
4219481456mS PRN: 6568: Digit Pressed 8
4219481456mS CMExtnRx: v=6568, p1=0
CMInformation
Line: type=DigitalExtn 7 Call: lid=0 id=814308 in=0
Called[8] Type=Default (100) Reason=CMDRdirect
4219481643mS PRN: 6568: Digit Pressed 9
4219481643mS CMExtnRx: v=6568, p1=0
CMInformation
Line: type=DigitalExtn 7 Call: lid=0 id=814308 in=0
Called[9] Type=Default (100) Reason=CMDRdirect

********** Warning: Logging to Screen Stopped **********

 

[TheSmash]

Interesting about the Sonicwalls, thanks.

Can't see the issue initially on the last trace. Check your SSA's and see if you get any alarms on your SCN trunks. They should look something like this; No response from IP trunk call request. Check all your systems.

ACSS (SME)

I never touched anything...

 

[TheSmash]

Did you get anywhere with this?

ACSS (SME)

I never touched anything...

 

[TheSmash]

Were you at any point on V4 and then upgraded to V6?

ACSS (SME)

I never touched anything...

 

[pdob]

TheSmash, sorry for the late replies. Been too busy checking this forum.

It does show "No response to IP trunk call request" but we're still able to dial. It's not disconnecting as it used to.

Yes, the problem site at one point was a IP406v2 on 4.2. We upgraded it to IP500 R6 but kept the original config. So could this be causing the intermittent disconnect?

 

[TheSmash]

So you did not have this issue at 4.2? Only when you upgraded to V6 did you have this problem? Can you confirm.

Thanks

ACSS (SME)

I never touched anything...

 

[pdob]

No, we did not have the issue then because the sites were not on the SCN yet. We only enabled SCN after we upgraded all the sites to R6 and added new locations. Site 3 is the issue.

Before
Site 1 - IP500 (5.0.15)
Site 2 - IP500 (5.0.8)
Site 3 - IP406v2 (4.2.20)

After (SCN enabled)
Site 1 - IP500 R6
Site 2 - IP500 R6
Site 3 - IP500v2 R6 (upgraded the control unit only)
Site 4 - IP500v2 R6 (this is a new location)
Site 5 - IP500v2 R6 (another new location)

 

[TheSmash]

Hi, thanks for the info. Can you let me know the model numbers of your Sonicwalls and the firmware versions please? Will be really useful in proving a point which should help us both!

ACSS (SME)

I never touched anything...