2595743396mS CMLineRx: v=10
CMSetup
Line: type=Q931Line 10 Call: lid=10 id=195199 in=1
Called[860] Type=Unknown (0) Reason=CMDRdirect SndComp Calling[xxxxxxxxxx] Type=Unknown Plan=Default Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=4 chan=53
IE CMIERespondingPartyNumber (230)(P:0 S:100 T:0 N:100 R:4) number=xxxxxxxxxx
IE CMIEDeviceDetail (231) LOCALE=enu HW=8 VER=4 class=CMDeviceT1Trunk type=0 number=10 channel=20 rx_gain=32 tx_gain=32
2595743396mS CD: CALL: 10.195199.1 BState=Idle Cut=1 Music=0.0 Aend="Line 10" (4.53) Bend="" [] (0.0) CalledNum=860 () CallingNum=xxxxxxxxxx () Internal=0 Time=2 AState=Idle
2595743397mS CMCallEvt: 10.195199.1 46881 T1 Trunk:10 CHAN=20: StateChange: END=A CMCSIdle->CMCSDialInitiated
2595743397mS CMTARGET: 10.195199.1 46881 T1 Trunk:10 CHAN=20: LOOKUP CALL ROUTE: type=0 called_party=860 sub= calling=xxxxxxxxxx dir=in complete=1 ses=0
2595743397mS CMTARGET: 10.195199.1 46881 T1 Trunk:10 CHAN=20: SET BESTMATCH: length 0 vs -257940880 match= dest=*23
2595743397mS CMTARGET: 10.195199.1 46881 T1 Trunk:10 CHAN=20: SET BESTMATCH: length 3 vs 0 match=860 dest=VM:Test123
2595743398mS CMCallEvt: Priority hike: call 46881 priority 0->1
2595743398mS CMTARGET: 10.195199.1 46881 T1 Trunk:10 CHAN=20: LOOKUP INCOMING CALL ROUTE: calling party is xxxxxxxxxx. Matched profile Destination VM:Test123
2595743398mS CMTARGET: 10.195199.1 46881 T1 Trunk:10 CHAN=20: ADD TARGET (N): number=VM:Test123 type=0 depth=1 nobar=1 setorig=1 ses=0
2595743398mS CMTARGET: 10.195199.1 46881 T1 Trunk:10 CHAN=20: ADD VOICEMAIL TARGET
2595743399mS CMCallEvt: 0.195201.0 -1 BaseEP: NEW CMEndpoint f58013d4 TOTAL NOW=13 CALL_LIST=6
2595743399mS CMTARGET: 0.195201.0 46881 RAS.0: ADD PRIMARY
2595743399mS CMTARGET: 10.195199.1 46881 T1 Trunk:10 CHAN=20: ADD VOICEMAIL TARGET: SUCCEEDED
2595743399mS CMTARGET: 10.195199.1 46881 T1 Trunk:10 CHAN=20: INITIAL TARGETING SUCCEEDED
2595743399mS CMTARGET: 10.195199.1 46881 T1 Trunk:10 CHAN=20: GetNoAnswerTimer:15
2595743400mS CMCallEvt: 10.195199.1 46881 T1 Trunk:10 CHAN=20: StateChange: END=A CMCSDialInitiated->CMCSDialled
2595743400mS CMLineTx: v=10
CMProceeding
Line: type=Q931Line 10 Call: lid=10 id=195199 in=1
2595743400mS CMCallEvt: 0.195200.0 46881 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
2595743401mS CMCallEvt: 0.195201.0 46881 RAS.0: StateChange: END=T CMCSIdle->CMCSOffering
2595743401mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 0->195201 )
2595743401mS CMExtnTx: v=RAS, p1=0
CMSetup
Line: type=RAS 1 Call: lid=0 id=1008 in=0
Called[Test123] Type=Voicemail (102) Reason=CMDRX_Attendant SndComp Calling[xxxxxxxxxx] Type=Unknown Plan=Default Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=21 chan=9
IE CMIERespondingPartyNumber (230)(P:0 S:100 T:0 N:100 R:4) number=xxxxxxxxxx
IE CMIEDeviceDetail (231) LOCALE=enu HW=8 VER=4 class=CMDeviceT1Trunk type=0 number=10 channel=20 rx_gain=32 tx_gain=32
IE CMIEIcrPriorityDetail (239) Priority = 1
Display [xxxxxxxxxx>Test123]
Tag type=Text flags=0x1 [ShopKo.] [0x53 0x68 0x6f 0x70 0x4b 0x6f 0x00 ]
Timed: 05/11/08 12:12
Locale: enu
2595743402mS CMMap: a=21.9 b=1.254 T
2595743403mS CMExtnRx: v=RAS, p1=0
CMConnect
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
IE CMIERespondingPartyName (228) Test123
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:102 N:100 R:4) number=Test123
IE CMIEDeviceDetail (231) LOCALE=enu HW=8 VER=4 class=CMDeviceVoicemail type=0 number=0 channel=9 rx_gain=32 tx_gain=32
Tag type=Text flags=0x1 [ShopKo.] [0x53 0x68 0x6f 0x70 0x4b 0x6f 0x00 ]
2595743403mS CMCallEvt: 0.195201.0 46881 RAS.0: StateChange: END=T CMCSOffering->CMCSConnReq
2595743403mS CMCallEvt: 0.195200.0 46881 TargetingEP: RequestEnd 0.195201.0 46881 RAS.0
2595743403mS CMTARGET: 10.195199.1 46881 T1 Trunk:10 CHAN=20: CancelTimer CMTCNoAnswerTimeout
2595743404mS CMExtnEvt: v=1008 State, new=Connected old=Idle,0,0,RAS
2595743404mS CMCallEvt: 10.195199.1 46881 T1 Trunk:10 CHAN=20: StateChange: END=A CMCSDialled->CMCSOGConnReq
2595743405mS CMLineTx: v=10
CMConnect
Line: type=Q931Line 10 Call: lid=10 id=195199 in=1
BChan: slot=21 chan=9
IE CMIERespondingPartyName (228) Test123
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:102 N:100 R:4) number=Test123
IE CMIEDeviceDetail (231) LOCALE=enu HW=8 VER=4 class=CMDeviceVoicemail type=0 number=0 channel=9 rx_gain=32 tx_gain=32
Tag type=Text flags=0x1 [ShopKo.] [0x53 0x68 0x6f 0x70 0x4b 0x6f 0x00 ]
2595743405mS CMLineRx: v=10
CMConnectAck
Line: type=Q931Line 10 Call: lid=10 id=195199 in=1
BChan: slot=4 chan=53
2595743405mS CMCallEvt: 10.195199.1 46881 T1 Trunk:10 CHAN=20: StateChange: END=A CMCSOGConnReq->CMCSConnected
2595743405mS CMTARGET: 10.195199.1 46881 T1 Trunk:10 CHAN=20: ~CMTargetHandler
2595743406mS CMCallEvt: 0.195201.0 46881 RAS.0: StateChange: END=B CMCSConnReq->CMCSConnected
2595743406mS CMExtnTx: v=RAS, p1=0
CMConnectAck
Line: type=RAS 1 Call: lid=0 id=1008 in=0
BChan: slot=21 chan=9
2595743407mS CMCallEvt: 0.195200.0 -1 TargetingEP: StateChange: END=X CMCSOffering->CMCSDelete
2595743407mS CMCallEvt: 0.195200.0 -1 BaseEP: DELETE CMEndpoint f5806ff4 TOTAL NOW=12 CALL_LIST=6
2595743408mS CD: CALL: 10.195199.1 BState=Connected Cut=3 Music=0.0 Aend="Line 10" (4.53) Bend="Test123(Test123)" [VoiceMail] (21.9) CalledNum=Test123 () CallingNum=xxxxxxxxxx () Internal=0 Time=14 AState=Connected
2595743410mS CMMap: PCG::MapBChan pcp[136]b1r0 cp_b f5a32cb8 other_cp_b 0 type CGTypeSimple
2595743410mS CMMap: PCG::MapBChan pcp[41]b1r0 cp_b f5a8b638 other_cp_b f5a32cb8 type CGTypeSimple
2595743410mS CMMap: a=4.53 b=21.9 M1
2595743412mS CMLineRx: v=10
CMConnectAck
Line: type=Q931Line 10 Call: lid=10 id=195199 in=1
BChan: slot=4 chan=53
IE CMIERespondingPartyNumber (230)(P:0 S:100 T:0 N:100 R:4) number=xxxxxxxxxx
IE CMIEDeviceDetail (231) LOCALE=enu HW=8 VER=4 class=CMDeviceT1Trunk type=0 number=10 channel=20 rx_gain=32 tx_gain=32
2595743643mS RES: Wed 5/11/2008 12:12:17 FreeMem=77680852(21) CMMsg=7 (9) Buff=200 966 998 7135 3 Links=15324