brucealmighty
Technical User
I have a weird one here, we just upgraded customer last night from 4.1 (500v1)Wxp to 9.0v2 server 2008. from vmpro client I can send wav file or call to a user's phone to record no problem, plays the wave file or "recording prompts".
When dialing *17, calling the AA, or trying to record greeting the call drops right away. Seems like when system tries to access vm wave it drops. Pressing vm button will take you to visual voice but pressing listen, greeting ect.. the call drops.
Firewall is off, exceptions have been made just in case. I captured monitor trace below, looks like the matrix to me. Any help would be great, thank you!
********** SysMonitor v9.0.12.0 build 1006 **********
********** contact made with 10.0.0.250 at 07:33:02 12/7/2018 **********
********** System (10.0.0.250) has been up and running for 12hrs, 28mins and 28secs(44908394mS) **********
********** Warning: TEXT File Logging selected **********
07:33:06 44913598mS PRN: +++++++++++++++++ END OF MEMORY CAPTURE ++++++++++++++++
07:33:20 44927008mS CMTARGET: ISDN BChannel 1: in-service check = 1
07:33:20 44927008mS ISDNL3Evt: v=9 stacknum=9 State, new=Present, old=NullState id=21
07:33:20 44927009mS CMLineRx: v=9
CMSetup
Line: type=Q931Line 9 Call: lid=9 id=21 in=1
Called[5531] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[8017724466] Type=Unknown Plan=ISDN Pres=Allowed (0)
BC: CMTC=3K1Audio CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=0 chan=1
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLPublicNetLocalUser (2), pd=CMPDOrigNotISDN (3)
07:33:20 44927009mS PRN: Q931Trunk: Found QBChannel to match 0.1 --> 4.2
07:33:20 44927009mS CMCallEvt: 0.1137.0 -1 BaseEP: NEW CMEndpoint f4dbaa1c TOTAL NOW=1 CALL_LIST=0
07:33:20 44927009mS CMTARGET: ISDN BChannel 1: in-service check = 1
07:33:20 44927010mS CMTARGET: ISDN BChannel 1: in-service check = 1
07:33:20 44927010mS CMCallEvt: CREATE CALL:49 (f4dc68e4)
07:33:20 44927010mS CMCallEvt: 0.1138.0 -1 BaseEP: NEW CMEndpoint f4dc4f94 TOTAL NOW=2 CALL_LIST=0
07:33:20 44927012mS CD: CALL: 9.21.1 BState=Idle Cut=1 Music=0.0 Aend="Line 9" (4.2) Bend="" [] (0.0) CalledNum=5531 () CallingNum=8017724466 () Internal=0 Time=2 AState=Idle
07:33:20 44927012mS CMCallEvt: 9.21.1 49 Q931 Trunk:9 CHAN=1: StateChange: END=A CMCSIdle->CMCSDialInitiated
07:33:20 44927012mS CMTARGET: 9.21.1 49 Q931 Trunk:9 CHAN=1: LOOKUP CALL ROUTE: type=0 called_party=5531 sub= calling=8017724466 dir=in complete=1 ses=0
07:33:20 44927012mS CMTARGET: 9.21.1 49 Q931 Trunk:9 CHAN=1: SET BESTMATCH: length 0 vs -1 match= dest=Main
07:33:20 44927013mS CMTARGET: 9.21.1 49 Q931 Trunk:9 CHAN=1: SET BESTMATCH: length 4 vs 0 match=8018225531 dest=VoiceMail
07:33:20 44927013mS CMCallEvt: Priority hike: call 49 priority 0->1
07:33:20 44927013mS CMTARGET: 9.21.1 49 Q931 Trunk:9 CHAN=1: LOOKUP ICR: DDI= CGPN=8017724466 (Destination VoiceMail ) => CDPN=VoiceMail
07:33:20 44927013mS CMTARGET: 9.21.1 49 Q931 Trunk:9 CHAN=1: ADD TARGET (N): number=VoiceMail type=0 depth=1 nobar=1 setorig=1 ses=0
07:33:20 44927013mS CMTARGET: 9.21.1 49 Q931 Trunk:9 CHAN=1: ADD VM TARGET
07:33:20 44927014mS CMTARGET: **** 9.21.1 49 Q931 Trunk:9 CHAN=1: MakeVoicemailTarget pbx=<null> local=1 type=1
07:33:20 44927014mS CMCallEvt: 0.1139.0 -1 BaseEP: NEW CMEndpoint f4db6204 TOTAL NOW=3 CALL_LIST=1
07:33:20 44927014mS CMTARGET: 0.1139.0 49 RAS.0: ADD PRIMARY
07:33:20 44927014mS CMTARGET: 9.21.1 49 Q931 Trunk:9 CHAN=1: ADD VM TARGET: SUCCEEDED
07:33:20 44927014mS CMTARGET: 9.21.1 49 Q931 Trunk:9 CHAN=1: INITIAL TARGETING SUCCEEDED
07:33:20 44927015mS CMTARGET: 9.21.1 49 Q931 Trunk:9 CHAN=1: GetNoAnswerTimer:15
07:33:20 44927015mS CMCallEvt: 9.21.1 49 Q931 Trunk:9 CHAN=1: StateChange: END=A CMCSDialInitiated->CMCSDialled
07:33:20 44927015mS CMLineTx: v=9
CMProceeding
Line: type=Q931Line 9 Call: lid=9 id=21 in=1
BChan: slot=0 chan=1
07:33:20 44927015mS CMCallEvt: 0.1138.0 49 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
07:33:20 44927016mS CMCallEvt: 0.1139.0 49 RAS.0: StateChange: END=T CMCSIdle->CMCSOffering
07:33:20 44927016mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 0->1139 )
07:33:20 44927016mS CMExtnTx: v=RAS, p1=0
CMSetup
Line: type=RAS 1 Call: lid=0 id=1011 in=0
Called[VoiceMail] Type=Voicemail (102) Reason=CMDRX_Attendant SndComp Calling[8017724466] Type=Unknown Plan=ISDN Pres=Allowed (0)
BC: CMTC=3K1Audio CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=21 chan=42
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLPublicNetLocalUser (2), pd=CMPDOrigNotISDN (3)
IE CMIERespondingPartyNumber (230)(P:0 S:1 T:0 N:1 R:4) number=8017724466
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=9 class=CMDeviceISDNTrunk type=2 number=9 channel=1 features=0x20 rx_gain=32 tx_gain=32 ep_callid=21 ipaddr=10.0.0.250 apps=0 loc=0 em_loc=0 features2=0x0
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEDIDNumber (245)(P:100 S:100 T:0 N:1 R:4) number=5531
IE CMIEMohSourceId (247) MOH Source = 1
Display [8017724466>VoiceMail]
Timed: 12/07/18 07:33
Locale: enu
07:33:20 44927017mS CMMap: a=21.42 b=1.65534 T
07:33:20 44927018mS CMExtnRx: v=RAS, p1=0
CMConnect
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=VoiceMail
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:102 N:100 R:4) number=VoiceMail
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=9 class=CMDeviceVoicemail type=0 number=0 channel=42 features=0x0 rx_gain=32 tx_gain=32 ep_callid=1139 ipaddr=10.0.0.250 apps=0 loc=0 em_loc=0 features2=0x0
07:33:20 44927019mS CMCallEvt: 0.1139.0 49 RAS.0: StateChange: END=T CMCSOffering->CMCSConnReq
07:33:20 44927019mS CMCallEvt: 0.1138.0 49 TargetingEP: RequestEnd 0.1139.0 49 RAS.0
07:33:20 44927019mS CMTARGET: 9.21.1 49 Q931 Trunk:9 CHAN=1: CancelTimer CMTCNoAnswerTimeout
07:33:20 44927020mS CMExtnEvt: v=1011 State, new=Connected old=Idle,0,0,RAS
07:33:20 44927020mS CMCallEvt: 9.21.1 49 Q931 Trunk:9 CHAN=1: StateChange: END=A CMCSDialled->CMCSOGConnReq
07:33:20 44927020mS CMLineTx: v=9
CMConnect
Line: type=Q931Line 9 Call: lid=9 id=21 in=1
BChan: slot=0 chan=1
Display [++fVoiceMail,]
07:33:20 44927020mS CMCallEvt: 0.1138.0 -1 TargetingEP: StateChange: END=X CMCSOffering->CMCSDelete
07:33:20 44927020mS CMCallEvt: 0.1138.0 -1 BaseEP: DELETE CMEndpoint f4dc4f94 TOTAL NOW=2 CALL_LIST=1
07:33:20 44927021mS CD: CALL: 9.21.1 BState=Connected Cut=3 Music=0.0 Aend="Line 9" (4.2) Bend="VoiceMail(VoiceMail)" [VoiceMail] (21.42) CalledNum=VoiceMail () CallingNum=8017724466 () Internal=0 Time=11 AState=Connected
[highlight #EF2929]07:33:20 44927022mS CMMap: PCG::MapBChan pcp[94]b1r0 cp_b f555e1e0 other_cp_b 0 type CGTypeSimple
07:33:20 44927022mS CMMap: PCG::MapBChan pcp[44]b1r0 cp_b f55a76c0 other_cp_b f555e1e0 type CGTypeSimple
07:33:20 44927022mS CMMap: a=4.2 b=21.42 M12[/highlight]
07:33:20 44927023mS ISDNL3Evt: v=9 stacknum=9 State, new=ICProceeding, old=Present id=21
07:33:20 44927023mS ISDNL3Evt: v=9 stacknum=9 State, new=ConnReq, old=ICProceeding id=21
07:33:20 44927063mS ISDNL3Evt: v=9 stacknum=9 State, new=Active, old=ConnReq id=21
07:33:20 44927064mS CMLineRx: v=9
CMConnectAck
Line: type=Q931Line 9 Call: lid=9 id=21 in=1
07:33:20 44927064mS CMCallEvt: 9.21.1 49 Q931 Trunk:9 CHAN=1: StateChange: END=A CMCSOGConnReq->CMCSConnected
07:33:20 44927064mS CMTARGET: 9.21.1 49 Q931 Trunk:9 CHAN=1: ~CMTargetHandler f4dbef04 ep f4dbaa1c
07:33:20 44927065mS CMCallEvt: 0.1139.0 49 RAS.0: StateChange: END=B CMCSConnReq->CMCSConnected
07:33:20 44927065mS CMExtnTx: v=RAS, p1=0
CMConnectAck
Line: type=RAS 1 Call: lid=0 id=1011 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[8017724466] Type=Unknown Plan=ISDN Pres=Allowed (0)
BChan: slot=21 chan=42
IE CMIERespondingPartyNumber (230)(P:0 S:1 T:0 N:1 R:4) number=8017724466
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=9 class=CMDeviceISDNTrunk type=2 number=9 channel=1 features=0x20 rx_gain=32 tx_gain=32 ep_callid=21 ipaddr=10.0.0.250 apps=0 loc=0 em_loc=0 features2=0x0
07:33:20 44927069mS CMExtnRx: v=RAS, p1=0
CMReleaseComp
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
07:33:20 44927069mS CMCallEvt: 0.1139.0 49 RAS.0: StateChange: END=B CMCSConnected->CMCSCompleted
07:33:20 44927070mS CMExtnEvt: v=1011 State, new=PortRecoverDelay old=Connected,0,0,RAS
07:33:20 44927070mS CMLOGGING: CALL:2018/07/1207:33,00:00:01,000,8017724466,I,VoiceMail,5531,,,,0,,""n/a,0
07:33:20 44927071mS CD: CALL: 9.21.1 BState=Disconnecting Cut=1 Music=0.0 Aend="Line 9" (4.2) Bend="VoiceMail(VoiceMail)" [VoiceMail] (21.42) CalledNum=VoiceMail () CallingNum=8017724466 () Internal=0 Time=61 AState=Connected
07:33:20 44927071mS CD: CALL: 9.21.1 Deleted
07:33:20 44927071mS CMLineTx: v=9
CMDisconnect
Line: type=Q931Line 9 Call: lid=9 id=21 in=1
Cause=16, Normal call clearing
07:33:20 44927072mS CMCallEvt: 9.21.1 -1 Q931 Trunk:9 CHAN=1: StateChange: END=X CMCSConnected->CMCSCompleted
07:33:20 44927072mS CMExtnEvt: RAS: CALL LOST (CMCauseNormal)
07:33:20 44927072mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 1139->0 )
07:33:20 44927072mS CMCallEvt: 0.1139.0 -1 RAS.-1: StateChange: END=X CMCSCompleted->CMCSDelete
07:33:20 44927072mS CMExtnEvt: v=1011 State, new=Idle old=PortRecoverDelay,0,0,RAS
07:33:20 44927072mS CMExtnTx: v=RAS, p1=0
CMReleaseComp
Line: type=RAS 1 Call: lid=0 id=1011 in=0
BChan: slot=21 chan=42
Cause=16, Normal call clearing
[highlight #EF2929]07:33:20 44927073mS CMMap: a=21.42 b=0.0 T0[/highlight]
07:33:20 44927074mS CMCallEvt: END CALL:49 (f4dc68e4)
07:33:20 44927074mS CMCallEvt: 0.1139.0 -1 BaseEP: DELETE CMEndpoint f4db6204 TOTAL NOW=1 CALL_LIST=0
07:33:20 44927075mS ISDNL3Evt: v=9 stacknum=9 State, new=DiscReq, old=Active id=21
[highlight #EF2929] 07:33:20 44927077mS CMMap: PCG::UnmapBChan pcp[94]b1r0 cp_b f555e1e0 other_cp_b f55a76c0
07:33:20 44927077mS CMMap: a=21.42 b=4.2 M02
07:33:20 44927077mS CMMap: PCG::UnmapBChan pcp[44]b1r0 cp_b f55a76c0 other_cp_b 0[/highlight]
07:33:20 44927123mS ISDNL3Evt: v=9 stacknum=9 State, new=NullState, old=DiscReq id=21
07:33:20 44927124mS CMLineRx: v=9
CMReleaseComp
Line: type=Q931Line 9 Call: lid=9 id=21 in=1
BChan: slot=0 chan=1
07:33:20 44927124mS CMCallEvt: 9.21.1 -1 Q931 Trunk:9 CHAN=1: StateChange: END=X CMCSCompleted->CMCSDelete
07:33:20 44927124mS CMTARGET: ISDN BChannel 1: in-service check = 1
07:33:20 44927125mS CMTARGET: ISDN BChannel 1: in-service check = 1
07:33:20 44927125mS CMCallEvt: 9.21.1 -1 BaseEP: DELETE CMEndpoint f4dbaa1c TOTAL NOW=0 CALL_LIST=0
When dialing *17, calling the AA, or trying to record greeting the call drops right away. Seems like when system tries to access vm wave it drops. Pressing vm button will take you to visual voice but pressing listen, greeting ect.. the call drops.
Firewall is off, exceptions have been made just in case. I captured monitor trace below, looks like the matrix to me. Any help would be great, thank you!
********** SysMonitor v9.0.12.0 build 1006 **********
********** contact made with 10.0.0.250 at 07:33:02 12/7/2018 **********
********** System (10.0.0.250) has been up and running for 12hrs, 28mins and 28secs(44908394mS) **********
********** Warning: TEXT File Logging selected **********
07:33:06 44913598mS PRN: +++++++++++++++++ END OF MEMORY CAPTURE ++++++++++++++++
07:33:20 44927008mS CMTARGET: ISDN BChannel 1: in-service check = 1
07:33:20 44927008mS ISDNL3Evt: v=9 stacknum=9 State, new=Present, old=NullState id=21
07:33:20 44927009mS CMLineRx: v=9
CMSetup
Line: type=Q931Line 9 Call: lid=9 id=21 in=1
Called[5531] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[8017724466] Type=Unknown Plan=ISDN Pres=Allowed (0)
BC: CMTC=3K1Audio CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=0 chan=1
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLPublicNetLocalUser (2), pd=CMPDOrigNotISDN (3)
07:33:20 44927009mS PRN: Q931Trunk: Found QBChannel to match 0.1 --> 4.2
07:33:20 44927009mS CMCallEvt: 0.1137.0 -1 BaseEP: NEW CMEndpoint f4dbaa1c TOTAL NOW=1 CALL_LIST=0
07:33:20 44927009mS CMTARGET: ISDN BChannel 1: in-service check = 1
07:33:20 44927010mS CMTARGET: ISDN BChannel 1: in-service check = 1
07:33:20 44927010mS CMCallEvt: CREATE CALL:49 (f4dc68e4)
07:33:20 44927010mS CMCallEvt: 0.1138.0 -1 BaseEP: NEW CMEndpoint f4dc4f94 TOTAL NOW=2 CALL_LIST=0
07:33:20 44927012mS CD: CALL: 9.21.1 BState=Idle Cut=1 Music=0.0 Aend="Line 9" (4.2) Bend="" [] (0.0) CalledNum=5531 () CallingNum=8017724466 () Internal=0 Time=2 AState=Idle
07:33:20 44927012mS CMCallEvt: 9.21.1 49 Q931 Trunk:9 CHAN=1: StateChange: END=A CMCSIdle->CMCSDialInitiated
07:33:20 44927012mS CMTARGET: 9.21.1 49 Q931 Trunk:9 CHAN=1: LOOKUP CALL ROUTE: type=0 called_party=5531 sub= calling=8017724466 dir=in complete=1 ses=0
07:33:20 44927012mS CMTARGET: 9.21.1 49 Q931 Trunk:9 CHAN=1: SET BESTMATCH: length 0 vs -1 match= dest=Main
07:33:20 44927013mS CMTARGET: 9.21.1 49 Q931 Trunk:9 CHAN=1: SET BESTMATCH: length 4 vs 0 match=8018225531 dest=VoiceMail
07:33:20 44927013mS CMCallEvt: Priority hike: call 49 priority 0->1
07:33:20 44927013mS CMTARGET: 9.21.1 49 Q931 Trunk:9 CHAN=1: LOOKUP ICR: DDI= CGPN=8017724466 (Destination VoiceMail ) => CDPN=VoiceMail
07:33:20 44927013mS CMTARGET: 9.21.1 49 Q931 Trunk:9 CHAN=1: ADD TARGET (N): number=VoiceMail type=0 depth=1 nobar=1 setorig=1 ses=0
07:33:20 44927013mS CMTARGET: 9.21.1 49 Q931 Trunk:9 CHAN=1: ADD VM TARGET
07:33:20 44927014mS CMTARGET: **** 9.21.1 49 Q931 Trunk:9 CHAN=1: MakeVoicemailTarget pbx=<null> local=1 type=1
07:33:20 44927014mS CMCallEvt: 0.1139.0 -1 BaseEP: NEW CMEndpoint f4db6204 TOTAL NOW=3 CALL_LIST=1
07:33:20 44927014mS CMTARGET: 0.1139.0 49 RAS.0: ADD PRIMARY
07:33:20 44927014mS CMTARGET: 9.21.1 49 Q931 Trunk:9 CHAN=1: ADD VM TARGET: SUCCEEDED
07:33:20 44927014mS CMTARGET: 9.21.1 49 Q931 Trunk:9 CHAN=1: INITIAL TARGETING SUCCEEDED
07:33:20 44927015mS CMTARGET: 9.21.1 49 Q931 Trunk:9 CHAN=1: GetNoAnswerTimer:15
07:33:20 44927015mS CMCallEvt: 9.21.1 49 Q931 Trunk:9 CHAN=1: StateChange: END=A CMCSDialInitiated->CMCSDialled
07:33:20 44927015mS CMLineTx: v=9
CMProceeding
Line: type=Q931Line 9 Call: lid=9 id=21 in=1
BChan: slot=0 chan=1
07:33:20 44927015mS CMCallEvt: 0.1138.0 49 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
07:33:20 44927016mS CMCallEvt: 0.1139.0 49 RAS.0: StateChange: END=T CMCSIdle->CMCSOffering
07:33:20 44927016mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 0->1139 )
07:33:20 44927016mS CMExtnTx: v=RAS, p1=0
CMSetup
Line: type=RAS 1 Call: lid=0 id=1011 in=0
Called[VoiceMail] Type=Voicemail (102) Reason=CMDRX_Attendant SndComp Calling[8017724466] Type=Unknown Plan=ISDN Pres=Allowed (0)
BC: CMTC=3K1Audio CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=21 chan=42
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLPublicNetLocalUser (2), pd=CMPDOrigNotISDN (3)
IE CMIERespondingPartyNumber (230)(P:0 S:1 T:0 N:1 R:4) number=8017724466
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=9 class=CMDeviceISDNTrunk type=2 number=9 channel=1 features=0x20 rx_gain=32 tx_gain=32 ep_callid=21 ipaddr=10.0.0.250 apps=0 loc=0 em_loc=0 features2=0x0
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEDIDNumber (245)(P:100 S:100 T:0 N:1 R:4) number=5531
IE CMIEMohSourceId (247) MOH Source = 1
Display [8017724466>VoiceMail]
Timed: 12/07/18 07:33
Locale: enu
07:33:20 44927017mS CMMap: a=21.42 b=1.65534 T
07:33:20 44927018mS CMExtnRx: v=RAS, p1=0
CMConnect
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=VoiceMail
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:102 N:100 R:4) number=VoiceMail
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=9 class=CMDeviceVoicemail type=0 number=0 channel=42 features=0x0 rx_gain=32 tx_gain=32 ep_callid=1139 ipaddr=10.0.0.250 apps=0 loc=0 em_loc=0 features2=0x0
07:33:20 44927019mS CMCallEvt: 0.1139.0 49 RAS.0: StateChange: END=T CMCSOffering->CMCSConnReq
07:33:20 44927019mS CMCallEvt: 0.1138.0 49 TargetingEP: RequestEnd 0.1139.0 49 RAS.0
07:33:20 44927019mS CMTARGET: 9.21.1 49 Q931 Trunk:9 CHAN=1: CancelTimer CMTCNoAnswerTimeout
07:33:20 44927020mS CMExtnEvt: v=1011 State, new=Connected old=Idle,0,0,RAS
07:33:20 44927020mS CMCallEvt: 9.21.1 49 Q931 Trunk:9 CHAN=1: StateChange: END=A CMCSDialled->CMCSOGConnReq
07:33:20 44927020mS CMLineTx: v=9
CMConnect
Line: type=Q931Line 9 Call: lid=9 id=21 in=1
BChan: slot=0 chan=1
Display [++fVoiceMail,]
07:33:20 44927020mS CMCallEvt: 0.1138.0 -1 TargetingEP: StateChange: END=X CMCSOffering->CMCSDelete
07:33:20 44927020mS CMCallEvt: 0.1138.0 -1 BaseEP: DELETE CMEndpoint f4dc4f94 TOTAL NOW=2 CALL_LIST=1
07:33:20 44927021mS CD: CALL: 9.21.1 BState=Connected Cut=3 Music=0.0 Aend="Line 9" (4.2) Bend="VoiceMail(VoiceMail)" [VoiceMail] (21.42) CalledNum=VoiceMail () CallingNum=8017724466 () Internal=0 Time=11 AState=Connected
[highlight #EF2929]07:33:20 44927022mS CMMap: PCG::MapBChan pcp[94]b1r0 cp_b f555e1e0 other_cp_b 0 type CGTypeSimple
07:33:20 44927022mS CMMap: PCG::MapBChan pcp[44]b1r0 cp_b f55a76c0 other_cp_b f555e1e0 type CGTypeSimple
07:33:20 44927022mS CMMap: a=4.2 b=21.42 M12[/highlight]
07:33:20 44927023mS ISDNL3Evt: v=9 stacknum=9 State, new=ICProceeding, old=Present id=21
07:33:20 44927023mS ISDNL3Evt: v=9 stacknum=9 State, new=ConnReq, old=ICProceeding id=21
07:33:20 44927063mS ISDNL3Evt: v=9 stacknum=9 State, new=Active, old=ConnReq id=21
07:33:20 44927064mS CMLineRx: v=9
CMConnectAck
Line: type=Q931Line 9 Call: lid=9 id=21 in=1
07:33:20 44927064mS CMCallEvt: 9.21.1 49 Q931 Trunk:9 CHAN=1: StateChange: END=A CMCSOGConnReq->CMCSConnected
07:33:20 44927064mS CMTARGET: 9.21.1 49 Q931 Trunk:9 CHAN=1: ~CMTargetHandler f4dbef04 ep f4dbaa1c
07:33:20 44927065mS CMCallEvt: 0.1139.0 49 RAS.0: StateChange: END=B CMCSConnReq->CMCSConnected
07:33:20 44927065mS CMExtnTx: v=RAS, p1=0
CMConnectAck
Line: type=RAS 1 Call: lid=0 id=1011 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[8017724466] Type=Unknown Plan=ISDN Pres=Allowed (0)
BChan: slot=21 chan=42
IE CMIERespondingPartyNumber (230)(P:0 S:1 T:0 N:1 R:4) number=8017724466
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=9 class=CMDeviceISDNTrunk type=2 number=9 channel=1 features=0x20 rx_gain=32 tx_gain=32 ep_callid=21 ipaddr=10.0.0.250 apps=0 loc=0 em_loc=0 features2=0x0
07:33:20 44927069mS CMExtnRx: v=RAS, p1=0
CMReleaseComp
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
07:33:20 44927069mS CMCallEvt: 0.1139.0 49 RAS.0: StateChange: END=B CMCSConnected->CMCSCompleted
07:33:20 44927070mS CMExtnEvt: v=1011 State, new=PortRecoverDelay old=Connected,0,0,RAS
07:33:20 44927070mS CMLOGGING: CALL:2018/07/1207:33,00:00:01,000,8017724466,I,VoiceMail,5531,,,,0,,""n/a,0
07:33:20 44927071mS CD: CALL: 9.21.1 BState=Disconnecting Cut=1 Music=0.0 Aend="Line 9" (4.2) Bend="VoiceMail(VoiceMail)" [VoiceMail] (21.42) CalledNum=VoiceMail () CallingNum=8017724466 () Internal=0 Time=61 AState=Connected
07:33:20 44927071mS CD: CALL: 9.21.1 Deleted
07:33:20 44927071mS CMLineTx: v=9
CMDisconnect
Line: type=Q931Line 9 Call: lid=9 id=21 in=1
Cause=16, Normal call clearing
07:33:20 44927072mS CMCallEvt: 9.21.1 -1 Q931 Trunk:9 CHAN=1: StateChange: END=X CMCSConnected->CMCSCompleted
07:33:20 44927072mS CMExtnEvt: RAS: CALL LOST (CMCauseNormal)
07:33:20 44927072mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 1139->0 )
07:33:20 44927072mS CMCallEvt: 0.1139.0 -1 RAS.-1: StateChange: END=X CMCSCompleted->CMCSDelete
07:33:20 44927072mS CMExtnEvt: v=1011 State, new=Idle old=PortRecoverDelay,0,0,RAS
07:33:20 44927072mS CMExtnTx: v=RAS, p1=0
CMReleaseComp
Line: type=RAS 1 Call: lid=0 id=1011 in=0
BChan: slot=21 chan=42
Cause=16, Normal call clearing
[highlight #EF2929]07:33:20 44927073mS CMMap: a=21.42 b=0.0 T0[/highlight]
07:33:20 44927074mS CMCallEvt: END CALL:49 (f4dc68e4)
07:33:20 44927074mS CMCallEvt: 0.1139.0 -1 BaseEP: DELETE CMEndpoint f4db6204 TOTAL NOW=1 CALL_LIST=0
07:33:20 44927075mS ISDNL3Evt: v=9 stacknum=9 State, new=DiscReq, old=Active id=21
[highlight #EF2929] 07:33:20 44927077mS CMMap: PCG::UnmapBChan pcp[94]b1r0 cp_b f555e1e0 other_cp_b f55a76c0
07:33:20 44927077mS CMMap: a=21.42 b=4.2 M02
07:33:20 44927077mS CMMap: PCG::UnmapBChan pcp[44]b1r0 cp_b f55a76c0 other_cp_b 0[/highlight]
07:33:20 44927123mS ISDNL3Evt: v=9 stacknum=9 State, new=NullState, old=DiscReq id=21
07:33:20 44927124mS CMLineRx: v=9
CMReleaseComp
Line: type=Q931Line 9 Call: lid=9 id=21 in=1
BChan: slot=0 chan=1
07:33:20 44927124mS CMCallEvt: 9.21.1 -1 Q931 Trunk:9 CHAN=1: StateChange: END=X CMCSCompleted->CMCSDelete
07:33:20 44927124mS CMTARGET: ISDN BChannel 1: in-service check = 1
07:33:20 44927125mS CMTARGET: ISDN BChannel 1: in-service check = 1
07:33:20 44927125mS CMCallEvt: 9.21.1 -1 BaseEP: DELETE CMEndpoint f4dbaa1c TOTAL NOW=0 CALL_LIST=0
