319095267mS CMARS: FORM: Main - Received Number: 4048210
319095267mS CMARS: CMARSTargetingPending
319096366mS H323Evt: Recv: RegistrationRequest 10.44.43.13; Endpoints registered: 10; Endpoints in registration: 0
319096412mS H323Evt: Recv: RegistrationRequest 10.44.43.22; Endpoints registered: 10; Endpoints in registration: 0
319096834mS PRN: Line 1: Inband: Line Loopback Active
319097404mS H323Evt: Recv: RegistrationRequest 10.44.43.32; Endpoints registered: 10; Endpoints in registration: 0
319099507mS CMARS: Case 1 - dial_delay_time: 4000 has expired - Resolve what we might have - Called Number: 4048210 Addr: f4eec224
319099507mS CMARS: FORM: Main - Received Number: 4048210
319099507mS CMARS: FOUND A SHORT CODE - short_code: N; - Tel: Ns - Called_Party: 4048210 - Line Group Id: 0
319099508mS CMARS: FindActiveARSByGroupID GroupID=0 - Not Found
319099508mS CMLRQ: FindActiveLRQByGroupID GroupID=0 - Not Found
319099508mS CMMap: a=0.1 b=0.0 Mapper::AllocateCodec allocated CMRTVocoder resource busy 1, total 42
319099509mS CMARS: LINE with group ID: 0 HAS NO CHANNEL: 34 - ATTEMPT OTHER TARGETS
319099510mS CMARS: No Alternate ARS Or User Priority lower than ARS Priority - Remain on the Current Form: Main
319099510mS CMARS: CMARSTargetingBusy
319099510mS CMCallEvt: 0.2040.0 289 ARS for Main: StateChange: END=B CMCSOverlapRecv->CMCSAccept
319099511mS CMCallEvt: 251.2038.0 289 FrontDesk1.0: StateChange: END=A CMCSDialling->CMCSRingBack
319099511mS CMExtnEvt: v=2 State, new=Alerting old=Dialling,0,0,FrontDesk1
319099512mS CMExtnTx: v=3801, p1=0
CMProgress
Line: type=IPLine 250 Call: lid=251 id=2038 in=0
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLUser (0), pd=CMPDInbandPattern (8)
Display [WAITING_FOR_LINE]
Timed: 11/11/13 08:20
319099512mS CD: CALL: 251.2038.0 BState=Ringing Cut=3 Music=0.0 Aend="FrontDesk1(3801)" (0.0) Bend="" [] (0.0) CalledNum=94048210 () CallingNum=3801 (FrontDesk1) Internal=1 Time=8506 AState=Ringing
319099513mS CMMap: a=21.39 b=0.0 DTMF::AllocateRAS allocated CMRTTonegen resource busy 6, total 8
319099513mS CMMap: a=21.39 b=1.255 T
319099513mS CMMap: PCG::AddToneGenerator g T[889] for cp[741]b0r1 append pcp[743]b0r1 (total 1)
319099514mS CMMap: a=0.17 b=0.0 IP::ReserveCodec pcp[743]b0r1 allocated CMRTVocoder resource busy 2, total 42, codec type 5
319099514mS CMMap: a=21.39 b=0.17 M2
319099514mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[743]b0r1 Configure 0.17
319099514mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[743]b0r1 ConnectIndication 0.17
319099514mS CMMap: PCG::MapBChan pcp[741]b0r1 cp_b 0 other_cp_b 0 type CGTypeDTMF
319099515mS CMMap: DTMF:

layTones g T[889] ,EEEEE
319099515mS CMMap: Started playing tones, cp[741]b0r1 (from g null)
319099515mS CMMap: cp[741]b0r1 SetOperGroup from g null to g null not executed, playing tones in progress ,EEEEE
319099766mS RES: Mon 11/11/2013 08:20:34 FreeMem=58421824 57820992(1) CachedMem=600832 CMMsg=9(10) Buff=5200 959 1000 7423 5 Links=6164
319099767mS RES2: IP 500 V2 8.0(66) Tasks=48 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=72 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 TCP=34 TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
319100507mS CMARS: Retarget existing BUSY targets - Num of Busy Targets: 1 - Current Form: Main
319101336mS CMMap: PCG::UnmapBChan pcp[741]b0r1 cp_b 0 other_cp_b 0
319101336mS CMMap: PCG::UnmapBChan pcp[743]b0r1 cp_b f56bca90 other_cp_b f56b47e8
319101336mS CMMap: a=21.39 b=0.17 M0
319101336mS CMMap: a=21.39 b=0.0 T0
319101337mS CMMap: a=21.39 b=0.0 DTMF::~DTMF freed CMRTTonegen resource busy 5, total 8
319101337mS CMMap: a=21.39 b=0.0 DTMF::AllocateRAS allocated CMRTTonegen resource busy 6, total 8
319101337mS CMMap: a=21.39 b=1.255 T
319101337mS CMMap: PCG::AddToneGenerator g T[890] for cp[741]b0r1 append pcp[744]b0r1 (total 1)
319101338mS CMMap: a=0.18 b=0.0 IP::ReserveCodec pcp[744]b0r1 allocated CMRTVocoder resource busy 3, total 42, codec type 5
319101338mS CMMap: a=21.39 b=0.18 M2
319101338mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[744]b0r1 Configure 0.18
319101338mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[744]b0r1 ConnectIndication 0.18
319101338mS CMMap: PCG::MapBChan pcp[741]b0r1 cp_b 0 other_cp_b 0 type CGTypeDTMF
319101339mS CMMap: DTMF:

layTones g T[890] ,EEEEE
319101339mS CMMap: Started playing tones, cp[741]b0r1 (from g null)
319101339mS CMMap: cp[741]b0r1 SetOperGroup from g null to g null not executed, playing tones in progress ,EEEEE
319101339mS CMMap: a=0.17 b=0.0 IP::FreeCodec pcp[743]b0r1 freed CMRTVocoder resource busy 3, total 42
319101886mS CMExtnTx: v=3801, p1=8000
CMFacility
Line: type=IPLine 250 Call: lid=251 id=2 in=1
IE CMIEFastStartInfoData (6) 2 item(s)
319101886mS CMExtnRx: v=3801, p1=0
CMReleaseComp
Line: type=IPLine 250 Call: lid=251 id=2038 in=0
319101886mS CMCallEvt: 251.2038.0 289 FrontDesk1.0: StateChange: END=A CMCSRingBack->CMCSCompleted
319101887mS CMExtnEvt: v=2 State, new=PortRecoverDelay old=Alerting,0,0,FrontDesk1
319101888mS CMLOGGING: CALL:2013/11/1108:20,00:00:00,000,3801,O,94048210,94048210,FrontDesk1,,,1,,""n/a,0
319101888mS CD: CALL: 251.2038.0 BState=Ringing Cut=2 Music=0.0 Aend="FrontDesk1(3801)" (0.0) Bend="" [] (0.0) CalledNum=94048210 () CallingNum=3801 (FrontDesk1) Internal=1 Time=10882 AState=Idle
319101888mS CD: CALL: 251.2038.0 Deleted
319101888mS CMExtnEvt: FrontDesk1: CALL LOST (CMCauseNormal)
319101888mS CMExtnEvt: FrontDesk1: Extn(3801) Calling Party Number(3801) Type(CMNTypeInternal)
319101889mS CMExtnEvt: FrontDesk1: CMExtnHandler::SetCurrent( id: 2038->0 )
319101890mS CMCallEvt: 251.2038.0 -1 FrontDesk1.-1: StateChange: END=X CMCSCompleted->CMCSDelete
319101890mS CMARS: CMARSEndpoint::CallLost(cause=16) - Address: 0.2040.0 289 ARS for Main - Call State: CMCSAccept
319101891mS CMCallEvt: 0.2040.0 -1 ARS for Main: StateChange: END=X CMCSAccept->CMCSDelete
319101891mS CMCallEvt: 0.2040.0 -1 BaseEP: DELETE CMEndpoint f4eec224 TOTAL NOW=1 CALL_LIST=0