4263855mS PRN: 241: Digit Pressed 0
4263855mS CMCallEvt: 0.1041.0 -1 BaseEP: NEW CMEndpoint f5648dd8 TOTAL NOW=1 CALL_LIST=0
4263856mS CMCallEvt: 0.1041.0 -1 Andrew Office.-1: NEW CMExtnEndpoint f5648dd8, Name=Andrew Office, Extn=241, Phys Extn=241
4263856mS CMCallEvt: CREATE CALL:12 (f56483b4)
4263856mS CMCallEvt: 0.1042.0 -1 BaseEP: NEW CMEndpoint f5647170 TOTAL NOW=2 CALL_LIST=0
4263858mS CMExtnEvt: Andrew Office: CMExtnHandler::SetCurrent( id: 0->1041 )
4263859mS CMExtnRx: v=241, p1=0
CMSetup
Line: type=IPLine 250 Call: lid=252 id=1041 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[241] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=Andrew Office
IE CMIEFastStartInfoData (6)
4263859mS CMCallEvt: 252.1041.0 12 Andrew Office.0: StateChange: END=A CMCSIdle->CMCSDialInitiated
4263860mS CMExtnEvt: v=1 State, new=Connected old=Idle,0,0,Andrew Office
4263860mS CMTARGET: 252.1041.0 12 Andrew Office.0: LOOKUP CALL ROUTE: type=100 called_party= sub= calling=241 dir=out complete=0 ses=0
4263860mS CMTARGET: 252.1041.0 12 Andrew Office.0: ADD TARGET (N): number= type=100 depth=1 nobar=1 setorig=1 ses=0
4263861mS CMCallEvt: 252.1041.0 12 Andrew Office.0: StateChange: END=A CMCSDialInitiated->CMCSDialling
4263861mS CMExtnEvt: v=1 State, new=Dialling old=Connected,0,0,Andrew Office
4263863mS CMExtnTx: v=241, p1=0
CMFacility
Line: type=IPLine 250 Call: lid=252 id=1041 in=0
IE CMIEFastStartInfoData (6)
Timed: 04/08/09 14:40
4263863mS CMExtnTx: v=241, p1=8001
CMFacility
Line: type=IPLine 250 Call: lid=252 id=1 in=1
IE CMIEFastStartInfoData (6)
4263864mS CMExtnTx: v=241, p1=0
CMSetupAck
Line: type=IPLine 250 Call: lid=252 id=1041 in=0
Timed: 04/08/09 14:40
4263865mS CMExtnRx: v=241, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=252 id=1041 in=0
Called[0] Type=Default (100) Reason=CMDRdirect Keypad[0]
4263867mS CD: CALL: 252.1041.0 BState=Idle Cut=0 Music=0.0 Aend="Andrew Office(241)" (0.0) Bend="" [] (0.0) CalledNum=0 () CallingNum=241 (Andrew Office) Internal=1 Time=11 AState=Dialling
4264200mS RES: Tue 4/8/2009 14:40:40 FreeMem=73437612(1) CMMsg=6 (6) Buff=200 957 1000 7470 4 Links=2889
4264200mS RES2: IP 500 5.0(8) Tasks=31 RTEngine=0 CMRTEngine=0 Timer=42 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1
4264865mS CMTARGET: 252.1041.0 12 Andrew Office.0: TimerExpired cause=CMTCDelayedProcessing
4264865mS CMTARGET: 252.1041.0 12 Andrew Office.0: LOOKUP CALL ROUTE: type=100 called_party=0 sub= calling=241 dir=out complete=0 ses=0
4264865mS CMTARGET: 252.1041.0 12 Andrew Office.0: ADD TARGET (N): number=0 type=100 depth=1 nobar=1 setorig=1 ses=0
4265140mS PRN: 241: Digit Pressed 4
4265141mS CMExtnRx: v=241, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=252 id=1041 in=0
Called[4] Type=Default (100) Reason=CMDRdirect Keypad[4]
4265141mS CMTARGET: 252.1041.0 12 Andrew Office.0: LOOKUP CALL ROUTE: type=100 called_party=04 sub= calling=241 dir=out complete=0 ses=0
4265141mS CMTARGET: 252.1041.0 12 Andrew Office.0: ADD TARGET (N): number=04 type=100 depth=1 nobar=1 setorig=1 ses=0
4265404mS PRN: 241: Digit Pressed 1
4265404mS CMExtnRx: v=241, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=252 id=1041 in=0
Called[1] Type=Default (100) Reason=CMDRdirect Keypad[1]
4265405mS CMTARGET: 252.1041.0 12 Andrew Office.0: LOOKUP CALL ROUTE: type=100 called_party=041 sub= calling=241 dir=out complete=0 ses=0
4265405mS CMTARGET: 252.1041.0 12 Andrew Office.0: ADD TARGET (N): number=041 type=100 depth=1 nobar=1 setorig=1 ses=0
4265821mS PRN: 241: Digit Pressed 8
4265822mS CMExtnRx: v=241, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=252 id=1041 in=0
Called[8] Type=Default (100) Reason=CMDRdirect Keypad[8]
4265822mS CMTARGET: 252.1041.0 12 Andrew Office.0: LOOKUP CALL ROUTE: type=100 called_party=0418 sub= calling=241 dir=out complete=0 ses=0
4265822mS CMTARGET: 252.1041.0 12 Andrew Office.0: ADD TARGET (N): number=0418 type=100 depth=1 nobar=1 setorig=1 ses=0
4268109mS PRN: 241: Digit Pressed 7
4268110mS CMExtnRx: v=241, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=252 id=1041 in=0
Called[7] Type=Default (100) Reason=CMDRdirect Keypad[7]
4268110mS CMTARGET: 252.1041.0 12 Andrew Office.0: LOOKUP CALL ROUTE: type=100 called_party=04187 sub= calling=241 dir=out complete=0 ses=0
4268110mS CMTARGET: 252.1041.0 12 Andrew Office.0: ADD TARGET (N): number=04187 type=100 depth=1 nobar=1 setorig=1 ses=0
4268461mS PRN: 241: Digit Pressed 8
4268462mS CMExtnRx: v=241, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=252 id=1041 in=0
Called[8] Type=Default (100) Reason=CMDRdirect Keypad[8]
4268462mS CMTARGET: 252.1041.0 12 Andrew Office.0: LOOKUP CALL ROUTE: type=100 called_party=041878 sub= calling=241 dir=out complete=0 ses=0
4268462mS CMTARGET: 252.1041.0 12 Andrew Office.0: ADD TARGET (N): number=041878 type=100 depth=1 nobar=1 setorig=1 ses=0
4268539mS H323Evt: Recv: RegistrationRequest 192.168.0.141; Endpoints registered: 1; Endpoints in registration: 0
4268774mS PRN: 241: Digit Pressed 2
4268774mS CMExtnRx: v=241, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=252 id=1041 in=0
Called[2] Type=Default (100) Reason=CMDRdirect Keypad[2]
4268775mS CMTARGET: 252.1041.0 12 Andrew Office.0: LOOKUP CALL ROUTE: type=100 called_party=0418782 sub= calling=241 dir=out complete=0 ses=0
4268775mS CMTARGET: 252.1041.0 12 Andrew Office.0: ADD TARGET (N): number=0418782 type=100 depth=1 nobar=1 setorig=1 ses=0
4269221mS PRN: 241: Digit Pressed 0
4269222mS CMExtnRx: v=241, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=252 id=1041 in=0
Called[0] Type=Default (100) Reason=CMDRdirect Keypad[0]
4269222mS CMTARGET: 252.1041.0 12 Andrew Office.0: LOOKUP CALL ROUTE: type=100 called_party=04187820 sub= calling=241 dir=out complete=0 ses=0
4269222mS CMTARGET: 252.1041.0 12 Andrew Office.0: ADD TARGET (N): number=04187820 type=100 depth=1 nobar=1 setorig=1 ses=0
4269439mS PRN: 241: Digit Pressed 0
4269439mS CMExtnRx: v=241, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=252 id=1041 in=0
Called[0] Type=Default (100) Reason=CMDRdirect Keypad[0]
4269440mS CMTARGET: 252.1041.0 12 Andrew Office.0: LOOKUP CALL ROUTE: type=100 called_party=041878200 sub= calling=241 dir=out complete=0 ses=0
4269440mS CMTARGET: 252.1041.0 12 Andrew Office.0: ADD TARGET (N): number=041878200 type=100 depth=1 nobar=1 setorig=1 ses=0
4269792mS PRN: 241: Digit Pressed 1
4269793mS CMExtnRx: v=241, p1=0
CMInformation
Line: type=IPLine 250 Call: lid=252 id=1041 in=0
Called[1] Type=Default (100) Reason=CMDRdirect Keypad[1]
4269793mS CMTARGET: 252.1041.0 12 Andrew Office.0: LOOKUP CALL ROUTE: type=100 called_party=0418782001 sub= calling=241 dir=out complete=0 ses=0
4269793mS CMTARGET: 252.1041.0 12 Andrew Office.0: ADD TARGET (N): number=0418782001 type=100 depth=1 nobar=1 setorig=1 ses=0
4269794mS CMTARGET: 252.1041.0 12 Andrew Office.0: USER SC: 0418782001 10 418782001 sc=type=Dial code=0xxxxxxxxx, num=0N"@proxy.freecall.net.au"
4269794mS CMARS: FindActiveARSByGroupID GroupID=50 - Found
4269794mS CMARS: MakeCallTarget - Called Number: 0418782001@proxy.freecall.net.au
4269795mS CMCallEvt: 0.1043.0 -1 BaseEP: NEW CMEndpoint f563e3ac TOTAL NOW=3 CALL_LIST=1
4269795mS CMTARGET: 252.1041.0 12 Andrew Office.0: CancelTimer CMTCDialToneTimeout
4269795mS CMTARGET: 252.1041.0 12 Andrew Office.0: INITIAL TARGETING SUCCEEDED
4269795mS CMTARGET: 252.1041.0 12 Andrew Office.0: GetNoAnswerTimer:15
4269796mS CMCallEvt: 0.1042.0 12 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
4269796mS CMCallEvt: 0.1043.0 12 ARS for Main: StateChange: END=T CMCSIdle->CMCSOffering
4269797mS CMCallEvt: 0.1042.0 12 TargetingEP: RequestEnd 0.1043.0 12 ARS for Main
4269797mS CMTARGET: 252.1041.0 12 Andrew Office.0: CancelTimer CMTCNoAnswerTimeout
4269798mS CMCallEvt: 0.1043.0 12 ARS for Main: StateChange: END=B CMCSOffering->CMCSOverlapRecv
4269798mS CMARS: FORM: Main - Received Number: 0418782001@proxy.freecall.net.au
4269798mS CMARS: FOUND A SHORT CODE - short_code: ? - Tel: . - Called_Party: 0418782001@proxy.freecall.net.au - Line Group Id: 0
4269798mS CMARS: FindActiveARSByGroupID GroupID=0 - Not Found
4269798mS CMLRQ: FindActiveLRQByGroupID GroupID=0 - Not Found
4269799mS CMCallEvt: 0.1044.0 -1 BaseEP: NEW CMEndpoint f563cf10 TOTAL NOW=4 CALL_LIST=1
4269801mS CMARS: FOUND LINE - Line Id: 18 - using line group id: 0 - Called Number: 0418782001@proxy.freecall.net.au - Calling Number: 241
4269801mS CMARS: SEND Setup TO LINE
4269801mS CMCallEvt: 18.1044.0 12 SIPTrunk Endpoint: StateChange: END=child CMCSIdle->CMCSOffering
4269802mS CMLineTx: v=0
CMSetup
Line: type=IPLine 18 Call: lid=18 id=1044 in=0
Called[0418782001@proxy.freecall.net.au] Type=Default (100) Reason=CMDRdirect Calling[241] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=Andrew Office
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Andrew Office
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=241
IE CMIEDeviceDetail (231) LOCALE=ena HW=8 VER=5 class=CMDeviceH323Phone type=33 number=8001 channel=0 rx_gain=32 tx_gain=32 ep_callid=1041 ipaddr=192.168.0.105 apps=0
IE CMIEMohSourceId (247) MOH Source = 255
IE CMIEFastStartInfoData (6)
Locale: ena
4269804mS SIP Tx: UDP 192.168.0.105:5060 -> 203.134.77.5:5060
INVITE sip:0418782001@proxy.freecall.net.au SIP/2.0
Via: SIP/2.0/UDP 203.45.64.141:5060;rport;branch=z9hG4bKa1826a6232e3c6571678e7f9aef2f479
From: "782000" <sip:782000@proxy.freecall.net.au>;tag=8f3a1ab80c9ba76a
To: <sip:0418782001@proxy.freecall.net.au>
Call-ID: 6e63f8ef49748e0b591f367a4ef9e90a@203.45.64.141
CSeq: 2142930857 INVITE
Contact: "782000" <sip:782000@203.45.64.141:5060;transport=udp>
Max-Forwards: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO
Content-Type: application/sdp
Content-Length: 256
v=0
o=UserA 4169244783 3268168780 IN IP4 203.45.64.141
s=Session SDP
c=IN IP4 203.45.64.141
t=0 0
m=audio 49154 RTP/AVP 18 0 8 101
a=rtpmap:18 G729/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
4269805mS CMCallEvt: 0.1042.0 -1 TargetingEP: StateChange: END=X CMCSOffering->CMCSDelete
4269806mS CMCallEvt: 0.1042.0 -1 BaseEP: DELETE CMEndpoint f5647170 TOTAL NOW=3 CALL_LIST=1
4269806mS CD: CALL: 252.1041.0 BState=Idle Cut=2 Music=0.0 Aend="Andrew Office(241)" (0.0) Bend="" [Line 18] (0.0) CalledNum=0418782001 () CallingNum=241 (Andrew Office) Internal=1 Time=5950 AState=Dialling
4270200mS RES: Tue 4/8/2009 14:40:46 FreeMem=73398580(1) CMMsg=6 (6) Buff=200 956 1000 7470 4 Links=2835
4270200mS RES2: IP 500 5.0(8) Tasks=31 RTEngine=0 CMRTEngine=0 Timer=43 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1
4271805mS SIP Tx: UDP 192.168.0.105:5060 -> 203.134.77.5:5060
INVITE sip:0418782001@proxy.freecall.net.au SIP/2.0
Via: SIP/2.0/UDP 203.45.64.141:5060;rport;branch=z9hG4bKa1826a6232e3c6571678e7f9aef2f479
From: "782000" <sip:782000@proxy.freecall.net.au>;tag=8f3a1ab80c9ba76a
To: <sip:0418782001@proxy.freecall.net.au>
Call-ID: 6e63f8ef49748e0b591f367a4ef9e90a@203.45.64.141
CSeq: 2142930857 INVITE
Contact: "782000" <sip:782000@203.45.64.141:5060;transport=udp>
Max-Forwards: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO
Content-Type: application/sdp
Content-Length: 256
v=0
o=UserA 4169244783 3268168780 IN IP4 203.45.64.141
s=Session SDP
c=IN IP4 203.45.64.141
t=0 0
m=audio 49154 RTP/AVP 18 0 8 101
a=rtpmap:18 G729/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
4275805mS SIP Tx: UDP 192.168.0.105:5060 -> 203.134.77.5:5060
INVITE sip:0418782001@proxy.freecall.net.au SIP/2.0
Via: SIP/2.0/UDP 203.45.64.141:5060;rport;branch=z9hG4bKa1826a6232e3c6571678e7f9aef2f479
From: "782000" <sip:782000@proxy.freecall.net.au>;tag=8f3a1ab80c9ba76a
To: <sip:0418782001@proxy.freecall.net.au>
Call-ID: 6e63f8ef49748e0b591f367a4ef9e90a@203.45.64.141
CSeq: 2142930857 INVITE
Contact: "782000" <sip:782000@203.45.64.141:5060;transport=udp>
Max-Forwards: 70
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO
Content-Type: application/sdp
Content-Length: 256
v=0
o=UserA 4169244783 3268168780 IN IP4 203.45.64.141
s=Session SDP
c=IN IP4 203.45.64.141
t=0 0
m=audio 49154 RTP/AVP 18 0 8 101
a=rtpmap:18 G729/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
4280805mS CMARS: LINE ep Received: CMReleaseComp - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
4280806mS CMCallEvt: 18.1044.0 -1 SIPTrunk Endpoint: StateChange: END=X CMCSOffering->CMCSCompleted
4280806mS CMCallEvt: 18.1044.0 -1 SIPTrunk Endpoint: StateChange: END=X CMCSCompleted->CMCSDelete
4280806mS CMARS: Target: Short_Code: ? - Line_Group_ID: 0 has been set to: CMARS_OUTOFSERVICE
4280807mS CMCallEvt: 0.1043.0 12 ARS for Main: StateChange: END=B CMCSOverlapRecv->CMCSAccept
4280807mS CMCallEvt: 252.1041.0 12 Andrew Office.0: StateChange: END=A CMCSDialling->CMCSRingBack
4280807mS CMExtnEvt: v=1 State, new=Alerting old=Dialling,0,0,Andrew Office
4280808mS CMExtnTx: v=241, p1=0
CMProgress
Line: type=IPLine 250 Call: lid=252 id=1041 in=0
IE CMIEProgressIndicator (30) cs=CMCSITUT (0), loc=CMLUser (0), pd=CMPDInbandPattern (8)
Display [WAITING_FOR_LINE]
Timed: 04/08/09 14:40
4280809mS CMCallEvt: 18.1044.0 -1 BaseEP: DELETE CMEndpoint f563cf10 TOTAL NOW=2 CALL_LIST=1
4280809mS CD: CALL: 252.1041.0 BState=Ringing Cut=3 Music=0.0 Aend="Andrew Office(241)" (0.0) Bend="" [] (0.0) CalledNum=0418782001 () CallingNum=241 (Andrew Office) Internal=1 Time=16953 AState=Ringing
4280810mS CMMap: a=21.39 b=0.0 DTMF::AllocateRAS allocated CMRTTonegen resource busy 6, total 8
4280810mS CMMap: a=21.39 b=1.255 T
4280810mS CMMap: PCG::AddToneGenerator g T[30] for cp[130]b0r1 append pcp[132]b0r1 (total 1)
4280811mS CMMap: a=4.1 b=0.0 IP::ReserveCodec pcp[132]b0r1 allocated CMRTVocoder resource busy 1, total 4, codec type 6
4280811mS CMMap: a=21.39 b=4.1 M2
4280811mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[132]b0r1 Configure 4.1
4280811mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[132]b0r1 ConnectIndication 4.1
4280811mS CMMap: PCG::MapBChan pcp[130]b0r1 cp_b 0 other_cp_b 0 type CGTypeDTMF
4280812mS CMMap: DTMF:

layTones g T[30] ,EEEEE
4280812mS CMMap: Started playing tones, cp[130]b0r1 (from g null)
4280812mS CMMap: cp[130]b0r1 SetOperGroup from g null to g null not executed, playing tones in progress ,EEEEE
4281200mS RES: Tue 4/8/2009 14:40:57 FreeMem=73419276(1) CMMsg=6 (6) Buff=200 955 1000 7470 4 Links=2866
4281200mS RES2: IP 500 5.0(8) Tasks=31 RTEngine=0 CMRTEngine=0 Timer=46 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1
4281806mS CMARS: Retarget existing BUSY targets - Num of Busy Targets: 1 - Current Form: Main
4281932mS H323Evt: RTP(50t): 192.168.0.105/49152 192.168.0.141/50728 CODEC=G729A8K(6) PKTSZ=20 RFC2833=off AGE=18064 SENT=50 (avg size=20) RECV=21 (avg size=20)
4282633mS CMMap: PCG::UnmapBChan pcp[130]b0r1 cp_b 0 other_cp_b 0
4282633mS CMMap: PCG::UnmapBChan pcp[132]b0r1 cp_b f5a3e754 other_cp_b f5a38790
4282633mS CMMap: a=21.39 b=4.1 M0
4282633mS CMMap: a=21.39 b=0.0 T0
4282634mS CMMap: a=21.39 b=0.0 DTMF::~DTMF freed CMRTTonegen resource busy 5, total 8
4282634mS CMMap: a=21.39 b=0.0 DTMF::AllocateRAS allocated CMRTTonegen resource busy 6, total 8
4282634mS CMMap: a=21.39 b=1.255 T
4282634mS CMMap: PCG::AddToneGenerator g T[31] for cp[130]b0r1 append pcp[133]b0r1 (total 1)
4282635mS CMMap: IP::ReserveCodec pcp[133]b0r1 unable to allocate resource
4282635mS CMMap: IP::ReserveCodec pcp[130]b0r1 unable to allocate resource
4282635mS CMMap: PCG::MapBChan pcp[130]b0r1 cp_b 0 other_cp_b 0 type CGTypeDTMF
4282635mS CMMap: DTMF:

layTones g T[31] ,EEEEE
4282636mS CMMap: Started playing tones, cp[130]b0r1 (from g null)
4282636mS CMMap: cp[130]b0r1 SetOperGroup from g null to g null not executed, playing tones in progress ,EEEEE
4282636mS CMMap: a=4.1 b=0.0 IP::FreeCodec pcp[132]b0r1 freed CMRTVocoder resource busy 1, total 4
4282636mS CMMap: PCG::UnmapBChan pcp[133]b0r1 cp_b 0 other_cp_b 0