Yes its logged in
I can create modules and test with short codes without a problem.
I do see a VM not visable in the trace ?
Line: type=DigitalExtn 2 Call: lid=0 id=1055 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[88] Type=Internal Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=Test 1
IE CMIECallingPartyKName (226)(Type=CMNameDefault) name=Test 1
16:15:29 1689187mS CMCallEvt: 0.1055.0 17 Test 1.0: StateChange: END=A CMCSIdle->CMCSDialInitiated
16:15:29 1689187mS CMExtnEvt: v=1 State, new=Dialling old=Idle,0,0,Test 1
16:15:29 1689188mS CMTARGET: 0.1055.0 17 Test 1.0: LOOKUP CALL ROUTE: type=100 called_party= sub= calling=88 dir=out complete=0 ses=0
16:15:29 1689188mS CMTARGET: 0.1055.0 17 Test 1.0: ADD TARGET (N): number= type=100 depth=1 nobar=1 setorig=1 ses=0
16:15:29 1689188mS CMCallEvt: 0.1055.0 17 Test 1.0: StateChange: END=A CMCSDialInitiated->CMCSDialling
16:15:29 1689189mS CMExtnTx: v=88, p1=0
CMSetupAck
Line: type=DigitalExtn 2 Call: lid=0 id=1055 in=0
BChan: slot=0 chan=1
Timed: 07/08/14 16:15
16:15:29 1689189mS CMExtnRx: v=88, p1=0
CMInformation
Line: type=DigitalExtn 2 Call: lid=0 id=1055 in=0
Called[5] Type=Default (100) Reason=CMDRdirect
16:15:29 1689190mS CMTARGET: 0.1055.0 17 Test 1.0: Setting Hard Timer 4000
16:15:29 1689190mS CMTARGET: 0.1055.0 17 Test 1.0: LOOKUP CALL ROUTE: type=100 called_party=5 sub= calling=88 dir=out complete=0 ses=0
16:15:29 1689190mS CMTARGET: 0.1055.0 17 Test 1.0: ADD TARGET (N): number=5 type=100 depth=1 nobar=1 setorig=1 ses=0
16:15:29 1689191mS CD: CALL: 0.1055.0 BState=Idle Cut=0 Music=0.0 Aend="Test 1(88)" (0.1) Bend="" [] (0.0) CalledNum=5 () CallingNum=88 (Test 1) Internal=1 Time=6 AState=Dialling
16:15:29 1689721mS PRN: 88: Digit Pressed 0
16:15:29 1689722mS CMExtnRx: v=88, p1=0
CMInformation
Line: type=DigitalExtn 2 Call: lid=0 id=1055 in=0
Called[0] Type=Default (100) Reason=CMDRdirect
16:15:29 1689722mS CMTARGET: 0.1055.0 17 Test 1.0: Setting Hard Timer 4000
16:15:29 1689723mS CMTARGET: 0.1055.0 17 Test 1.0: LOOKUP CALL ROUTE: type=100 called_party=50 sub= calling=88 dir=out complete=0 ses=0
16:15:29 1689723mS CMTARGET: 0.1055.0 17 Test 1.0: ADD TARGET (N): number=50 type=100 depth=1 nobar=1 setorig=1 ses=0
16:15:29 1689910mS PRN: 88: Digit Pressed 0
16:15:29 1689910mS CMExtnRx: v=88, p1=0
CMInformation
Line: type=DigitalExtn 2 Call: lid=0 id=1055 in=0
Called[0] Type=Default (100) Reason=CMDRdirect
16:15:29 1689910mS CMTARGET: 0.1055.0 17 Test 1.0: Setting Hard Timer 4000
16:15:29 1689911mS CMTARGET: 0.1055.0 17 Test 1.0: LOOKUP CALL ROUTE: type=100 called_party=500 sub= calling=88 dir=out complete=0 ses=0
16:15:29 1689911mS CMTARGET: 0.1055.0 17 Test 1.0: ADD TARGET (N): number=500 type=100 depth=1 nobar=1 setorig=1 ses=0
16:15:30 1690634mS PRN: 88: Digit Pressed 1
16:15:30 1690635mS CMExtnRx: v=88, p1=0
CMInformation
Line: type=DigitalExtn 2 Call: lid=0 id=1055 in=0
Called[1] Type=Default (100) Reason=CMDRdirect
16:15:30 1690635mS CMTARGET: 0.1055.0 17 Test 1.0: Setting Hard Timer 4000
16:15:30 1690635mS CMTARGET: 0.1055.0 17 Test 1.0: LOOKUP CALL ROUTE: type=100 called_party=5001 sub= calling=88 dir=out complete=0 ses=0
16:15:30 1690635mS CMTARGET: 0.1055.0 17 Test 1.0: ADD TARGET (N): number=5001 type=100 depth=1 nobar=1 setorig=1 ses=0
16:15:30 1690636mS CMTARGET: 0.1055.0 17 Test 1.0: HG(HunterEd Group,5001,192.168.42.1) Requires Routing To Master(1). IsLocalExecutive(1)
16:15:30 1690636mS CMTARGET: 0.1055.0 17 Test 1.0: HG call targeting occuring here
16:15:30 1690636mS CMTARGET: 0.1055.0 17 Test 1.0: PrimeForHGTarget: HunterEd Group setorig=1 recall=0 resetExtnVars 1
16:15:30 1690636mS CMTARGET: 0.1055.0 17 Test 1.0: AddHGTarget HunterEd Group (depth=1) allowq=1 type=CMNTypeDefault
16:15:30 1690636mS CMCallEvt: 0.1057.0 -1 BaseEP: NEW CMEndpoint f4989194 TOTAL NOW=5 CALL_LIST=2
16:15:30 1690637mS CMTARGET: 0.1055.0 17 Test 1.0: CancelTimer CMTCDelayedProcessing
16:15:30 1690637mS CMTARGET: 0.1055.0 17 Test 1.0: INITIAL TARGETING SUCCEEDED
16:15:30 1690637mS CMTARGET: 0.1055.0 17 Test 1.0: GetNoAnswerTimer:15
16:15:30 1690637mS CMCallEvt: 0.1056.0 17 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
16:15:30 1690638mS CMCallEvt: 0.1057.0 17 ACDTep(HunterEd Group): StateChange: END=T CMCSIdle->CMCSOffering
16:15:30 1690638mS CMTARGET: 0.1055.0 17 Test 1.0: CancelTimer CMTCNoAnswerTimeout
16:15:30 1690639mS CMCallEvt: 0.1057.0 17 ACDTep(HunterEd Group): StateChange: END=T CMCSOffering->CMCSRinging
16:15:30 1690639mS CMCallEvt: 0.1056.0 17 TargetingEP: StateChange: END=B CMCSOffering->CMCSRinging
16:15:30 1690639mS CMCallEvt: 0.1055.0 17 Test 1.0: StateChange: END=A CMCSDialling->CMCSRingBack
16:15:30 1690639mS CMExtnEvt: v=1 State, new=Alerting old=Dialling,0,0,Test 1
16:15:30 1690640mS CMExtnTx: v=88, p1=0
CMAlerting
Line: type=DigitalExtn 2 Call: lid=0 id=1055 in=0
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=HunterEd Group
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:100 N:100 R:4) number=5001
Timed: 07/08/14 16:15
16:15:30 1690641mS CD: CALL: 0.1055.0 BState=Ringing Cut=1 Music=2.0 Aend="THunterEd Group(5001)" (0.1) Bend="HunterEd Group(5001)" [] (0.0) CalledNum=5001 (HunterEd Group) CallingNum=88 (Test 1) Internal=1 Time=1456 AState=Ringing
16:15:30 1690643mS CMMap: a=0.1 b=0.0 R1
16:15:30 1690643mS CD: CALL: 0.1055.0 BState=Ringing Cut=1 Music=2.0 Aend="THunterEd Group(5001)" (0.1) Bend="HunterEd Group(5001)" [] (0.0) CalledNum=5001 (HunterEd Group) CallingNum=88 (Test 1) Internal=1 Time=1458 AState=Ringing
16:15:30 1690643mS CMTARGET: 0.1055.0 17 Test 1.0: PrimeForHGTarget: HunterEd Group setorig=0 recall=0 resetExtnVars 0
16:15:30 1690643mS CMTARGET: 0.1055.0 17 Test 1.0: AddHGTarget HunterEd Group (depth=0) allowq=0 type=CMNTypeDefault
16:15:30 1690644mS CMTARGET: 0.1055.0 17 Test 1.0: AddHGTargetRingIdle(LongestWaiting) HunterEd Group ring_attempt_count 0
16:15:30 1690644mS CMTARGET: 0.1055.0 17 Test 1.0: OV visable. VM NOT visable:Timer running
16:15:30 1690644mS CMTARGET: 0.1055.0 17 Test 1.0: FindNextInServiceHgInHgIncludedList: No Alternative!
16:15:30 1690644mS CMTARGET: 0.1055.0 17 Test 1.0: ADD HG HunterEd Group Complete search - no targets found
16:15:31 1691643mS CD: CALL: 0.1055.0 BState=Ringing Cut=1 Music=2.0 Aend="THunterEd Group(5001)" (0.1) Bend="HunterEd Group(5001)" [] (0.0) CalledNum=5001 (HunterEd Group) CallingNum=88 (Test 1) Internal=1 Time=2458 AState=Ringing
16:15:31 1691643mS CMTARGET: 0.1055.0 17 Test 1.0: PrimeForHGTarget: HunterEd Group setorig=0 recall=0 resetExtnVars 0
16:15:31 1691643mS CMTARGET: 0.1055.0 17 Test 1.0: AddHGTarget HunterEd Group (depth=0) allowq=0 type=CMNTypeDefault
16:15:31 1691643mS CMTARGET: 0.1055.0 17 Test 1.0: OV visable. VM NOT visable:Timer running
16:15:31 1691643mS CMTARGET: 0.1055.0 17 Test 1.0: FindNextInServiceHgInHgIncludedList: No Alternative!
16:15:31 1691643mS CMTARGET: 0.1055.0 17 Test 1.0: PrimeForHGTarget: HunterEd Group setorig=0 recall=0 resetExtnVars 1
16:15:31 1691644mS CMTARGET: 0.1055.0 17 Test 1.0: AddHGTarget HunterEd Group (depth=1) allowq=0 type=CMNTypeDefault
16:15:31 1691644mS CMTARGET: 0.1055.0 17 Test 1.0: AddHGTargetRingIdle(LongestWaiting) HunterEd Group ring_attempt_count 0
16:15:31 1691644mS CMTARGET: 0.1055.0 17 Test 1.0: OV visable. VM NOT visable:Timer running
16:15:31 1691644mS CMTARGET: 0.1055.0 17 Test 1.0: FindNextInServiceHgInHgIncludedList: No Alternative!
16:15:31 1691644mS CMTARGET: 0.1055.0 17 Test 1.0: ADD HG HunterEd Group Complete search - no targets found
16:15:32 1692643mS CD: CALL: 0.1055.0 BState=Ringing Cut=1 Music=2.0 Aend="THunterEd Group(5001)" (0.1) Bend="HunterEd Group(5001)" [] (0.0) CalledNum=5001 (HunterEd Group) CallingNum=88 (Test 1) Internal=1 Time=3458 AState=Ringing
16:15:32 1692643mS CMTARGET: 0.1055.0 17 Test 1.0: PrimeForHGTarget: HunterEd Group setorig=0 recall=0 resetExtnVars 0
16:15:32 1692643mS CMTARGET: 0.1055.0 17 Test 1.0: AddHGTarget HunterEd Group (depth=0) allowq=0 type=CMNTypeDefault
16:15:32 1692643mS CMTARGET: 0.1055.0 17 Test 1.0: OV visable. VM NOT visable:Timer running
16:15:32 1692643mS CMTARGET: 0.1055.0 17 Test 1.0: FindNextInServiceHgInHgIncludedList: No Alternative!
16:15:32 1692643mS CMTARGET: 0.1055.0 17 Test 1.0: PrimeForHGTarget: HunterEd Group setorig=0 recall=0 resetExtnVars 1
16:15:32 1692644mS CMTARGET: 0.1055.0 17 Test 1.0: AddHGTarget HunterEd Group (depth=1) allowq=0 type=CMNTypeDefault
16:15:32 1692644mS CMTARGET: 0.1055.0 17 Test 1.0: AddHGTargetRingIdle(LongestWaiting) HunterEd Group ring_attempt_count 0
16:15:32 1692644mS CMTARGET: 0.1055.0 17 Test 1.0: OV visable. VM NOT visable:Timer running
16:15:32 1692644mS CMTARGET: 0.1055.0 17 Test 1.0: FindNextInServiceHgInHgIncludedList: No Alternative!
16:15:32 1692644mS CMTARGET: 0.1055.0 17 Test 1.0: ADD HG HunterEd Group Complete search - no targets found
16:15:34 1693643mS CD: CALL: 0.1055.0 BState=Ringing Cut=1 Music=2.0 Aend="THunterEd Group(5001)" (0.1) Bend="HunterEd Group(5001)" [] (0.0) CalledNum=5001 (HunterEd Group) CallingNum=88 (Test 1) Internal=1 Time=4458 AState=Ringing
16:15:34 1693643mS CMTARGET: 0.1055.0 17 Test 1.0: PrimeForHGTarget: HunterEd Group setorig=0 recall=0 resetExtnVars 0
16:15:34 1693643mS CMTARGET: 0.1055.0 17 Test 1.0: AddHGTarget HunterEd Group (depth=0) allowq=0 type=CMNTypeDefault
16:15:34 1693643mS CMTARGET: 0.1055.0 17 Test 1.0: OV visable. VM NOT visable:Timer running
16:15:34 1693643mS CMTARGET: 0.1055.0 17 Test 1.0: FindNextInServiceHgInHgIncludedList: No Alternative!
16:15:34 1693643mS CMTARGET: 0.1055.0 17 Test 1.0: PrimeForHGTarget: HunterEd Group setorig=0 recall=0 resetExtnVars 1
16:15:34 1693643mS CMTARGET: 0.1055.0 17 Test 1.0: AddHGTarget HunterEd Group (depth=1) allowq=0 type=CMNTypeDefault
16:15:34 1693644mS CMTARGET: 0.1055.0 17 Test 1.0: AddHGTargetRingIdle(LongestWaiting) HunterEd Group ring_attempt_count 0
16:15:34 1693644mS CMTARGET: 0.1055.0 17 Test 1.0: OV visable. VM NOT visable:Timer running
16:15:34 1693644mS CMTARGET: 0.1055.0 17 Test 1.0: FindNextInServiceHgInHgIncludedList: No Alternative!
16:15:34 1693644mS CMTARGET: 0.1055.0 17 Test 1.0: ADD HG HunterEd Group Complete search - no targets found
16:15:35 1694643mS CD: CALL: 0.1055.0 BState=Ringing Cut=1 Music=2.0 Aend="THunterEd Group(5001)" (0.1) Bend="HunterEd Group(5001)" [] (0.0) CalledNum=5001 (HunterEd Group) CallingNum=88 (Test 1) Internal=1 Time=5458 AState=Ringing
16:15:35 1694643mS CMTARGET: 0.1055.0 17 Test 1.0: PrimeForHGTarget: HunterEd Group setorig=0 recall=0 resetExtnVars 0
16:15:35 1694643mS CMTARGET: 0.1055.0 17 Test 1.0: AddHGTarget HunterEd Group (depth=0) allowq=0 type=CMNTypeDefault
16:15:35 1694643mS CMTARGET: 0.1055.0 17 Test 1.0: OV visable. VM NOT visable:Timer running
16:15:35 1694643mS CMTARGET: 0.1055.0 17 Test 1.0: FindNextInServiceHgInHgIncludedList: No Alternative!
16:15:35 1694643mS CMTARGET: 0.1055.0 17 Test 1.0: PrimeForHGTarget: HunterEd Group setorig=0 recall=0 resetExtnVars 1
16:15:35 1694643mS CMTARGET: 0.1055.0 17 Test 1.0: AddHGTarget HunterEd Group (depth=1) allowq=0 type=CMNTypeDefault
16:15:35 1694644mS CMTARGET: 0.1055.0 17 Test 1.0: AddHGTargetRingIdle(LongestWaiting) HunterEd Group ring_attempt_count 0
16:15:35 1694644mS CMTARGET: 0.1055.0 17 Test 1.0: OV visable. VM NOT visable:Timer running
16:15:35 1694644mS CMTARGET: 0.1055.0 17 Test 1.0: FindNextInServiceHgInHgIncludedList: No Alternative!
16:15:35 1694644mS CMTARGET: 0.1055.0 17 Test 1.0: ADD HG HunterEd Group Complete search - no targets found
16:15:36 1695641mS CMTARGET: **** 0.1055.0 17 Test 1.0: MakeVoicemailTarget pbx=<null> local=1 type=1
16:15:36 1695641mS CMCallEvt: 0.1058.0 -1 BaseEP: NEW CMEndpoint f499440c TOTAL NOW=6 CALL_LIST=2
16:15:36 1695641mS CMTARGET: 0.1058.0 17 RAS.0: ADD PRIMARY
16:15:36 1695642mS CMTARGET: 0.1055.0 17 Test 1.0: SelectTargetAvailable 1 targets
16:15:36 1695642mS CMCallEvt: 0.1058.0 17 RAS.0: StateChange: END=T CMCSIdle->CMCSOffering
16:15:36 1695642mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 0->1058 )
16:15:36 1695643mS CMExtnTx: v=RAS, p1=0
CMSetup
Line: type=RAS 1 Call: lid=0 id=1012 in=0
Called[!$Queued;HunterEd Group,1,0] Type=ACD (103) Reason=CMDRdirect Calling[88] Type=Default Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=21 chan=11
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=Test 1
IE CMIECalledPartyName (224)(Type=CMNameDefault) name=HunterEd Group
IE CMIECallingPartyKName (226)(Type=CMNameDefault) name=Test 1
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Test 1
IE CMIERespondingPartyKName (229)(Type=CMNameDefault) name=Test 1
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=88
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=9 class=CMDeviceStdPhone type=27 number=1 channel=0 features=0x10000420 rx_gain=32 tx_gain=32 ep_callid=1055 ipaddr=192.168.42.1 apps=0 loc=0 em_loc=1 features2=0x0
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=HunterEd Group
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:4) number=5001
IE CMIEOriginalCalledPartyName (236)(Type=CMNameDefault) name=HunterEd Group
IE CMIEOriginalCalledPartyNumber (238)(P:100 S:100 T:101 N:100 R:4) number=5001
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEQueueDetail (246) TIME IN SYSTEM=6, TIME IN QUEUE=5, POS IN QUEUE=1, ETA=0, ANNOUNCEMENT SYNC=0
IE CMIEMohSourceId (247) MOH Source = 1
Timed: 07/08/14 16:15
Locale: enu
16:15:36 1695643mS CMMap: a=21.11 b=1.65534 T
16:15:36 1695646mS CMExtnRx: v=RAS, p1=0
CMConnect
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=!$Queued;HunterEd Group,1,0
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:102 N:100 R:4) number=!$Queued;HunterEd Group,1,0
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=9 class=CMDeviceVoicemail type=0 number=0 channel=11 features=0x0 rx_gain=32 tx_gain=32 ep_callid=1058 ipaddr=192.168.42.1 apps=0 loc=0 em_loc=0 features2=0x0
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=HunterEd Group
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:4) number=5001
16:15:36 1695646mS CMCallEvt: 0.1058.0 17 RAS.0: StateChange: END=T CMCSOffering->CMCSConnReq
16:15:36 1695646mS CMCallEvt: 0.1056.0 17 TargetingEP: RequestEnd 0.1058.0 17 RAS.0
16:15:36 1695646mS CMExtnEvt: v=1012 State, new=Connected old=Idle,0,0,RAS
16:15:36 1695647mS CMCallEvt: 0.1055.0 17 Test 1.0: StateChange: END=A CMCSRingBack->CMCSOGConnReq
16:15:36 1695647mS CMExtnEvt: v=1 State, new=Connected old=Alerting,0,0,Test 1
16:15:36 1695647mS CMExtnTx: v=88, p1=0
CMConnect
Line: type=DigitalExtn 2 Call: lid=0 id=1055 in=0
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=HunterEd Group
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=5001
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=9 class=CMDeviceVoicemail type=0 number=0 channel=11 features=0x0 rx_gain=32 tx_gain=32 ep_callid=1058 ipaddr=192.168.42.1 apps=0 loc=0 em_loc=0 features2=0x0
IE CMIERedirectingPartyName (232)(Type=CMNameDefault) name=HunterEd Group
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:4) number=5001
Timed: 07/08/14 16:15
16:15:36 1695648mS CMExtnRx: v=88, p1=0
CMConnectAck
Line: type=DigitalExtn 2 Call: lid=0 id=1055 in=0
16:15:36 1695648mS CMCallEvt: 0.1055.0 17 Test 1.0: StateChange: END=A CMCSOGConnReq->CMCSConnected
16:15:36 1695649mS CMCallEvt: 0.1058.0 17 RAS.0: StateChange: END=B CMCSConnReq->CMCSConnected
16:15:36 1695649mS CMExtnTx: v=RAS, p1=0
CMConnectAck
Line: type=RAS 1 Call: lid=0 id=1012 in=0
BChan: slot=21 chan=11
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Test 1
IE CMIERespondingPartyKName (229)(Type=CMNameDefault) name=Test 1
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=88
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=9 class=CMDeviceStdPhone type=27 number=1 channel=0 features=0x10000420 rx_gain=32 tx_gain=32 ep_callid=1055 ipaddr=192.168.42.1 apps=0 loc=0 em_loc=1 features2=0x0
16:15:36 1695649mS VMAIL: SESS 6c: CMD=1 Mailbox=$Queued;HunterEd Group,1,0 Access=8 !Internal!=Y
16:15:36 1695649mS VMAIL: VMAIL Open [VMMESSAGE_OPEN] info :-
16:15:36 1695649mS VMAIL: Flags :- 65 6e 75 00 00 00 00 11
16:15:36 1695649mS VMAIL: 00 00 00 66 01 00 00 00
16:15:36 1695649mS VMAIL: Mailbox: $Queued;HunterEd Group,1,0 Access: 8 !Internal!=Y Locale: enu
16:15:36 1695649mS VMAIL: Calling Party number: 88 Display String:
16:15:36 1695650mS VMAIL: Greeting Modifier:
16:15:36 1695650mS VMAIL: Targeted Party number: 5001 Called Party number:
16:15:36 1695650mS VMAIL: Calling Party name: Test 1 Called Party name: HunterEd Group
16:15:36 1695650mS VMAIL: DID number QUEUE: CallAge=6 QueueAge=5 QPos=1 QEta=0 AnnouncmentSync=0
16:15:36 1695650mS VMAIL: Recording Originator: IP addr. of Recording Originator: 0.0.0.0
16:15:36 1695650mS VMAIL: Targeted Party Name: HunterEd Group
16:15:36 1695650mS VMAIL: Recording Type: Auto Recording Incoming HuntGroup
16:15:36 1695650mS VMAIL: Answering Party Number: Answering Party Name:
16:15:36 1695650mS VMAIL: Targeted Party Offset: 00
16:15:36 1695650mS VMAIL: Compression Format: input[00] output[00]
16:15:36 1695650mS VMAIL: Validated Recording: No
16:15:36 1695650mS VMAIL: UTC Date:
16:15:36 1695650mS VMAIL: UTC Time:
16:15:36 1695651mS VMAIL: SESS 6c: CMD=9 Mailbox=$Queued;HunterEd Group,1,0 Access=8 !Internal!=Y
16:15:36 1695651mS VMAIL: VMAIL Open [VMMESSAGE_ACTIVE] info :-
16:15:36 1695651mS VMAIL: Flags :- 65 6e 75 00 00 00 00 11
16:15:36 1695651mS VMAIL: 00 00 00 66 01 80 00 00
16:15:36 1695651mS VMAIL: Mailbox: $Queued;HunterEd Group,1,0 Access: 8 !Internal!=Y Locale: enu
16:15:36 1695651mS VMAIL: Calling Party number: 88 Display String:
16:15:36 1695651mS VMAIL: Greeting Modifier:
16:15:36 1695651mS VMAIL: Targeted Party number: 5001 Called Party number:
16:15:36 1695651mS VMAIL: Calling Party name: Test 1 Called Party name: HunterEd Group
16:15:36 1695651mS VMAIL: DID number QUEUE: CallAge=6 QueueAge=5 QPos=1 QEta=0 AnnouncmentSync=0
16:15:36 1695652mS VMAIL: Recording Originator: IP addr. of Recording Originator: 0.0.0.0
16:15:36 1695652mS VMAIL: Targeted Party Name: HunterEd Group
16:15:36 1695652mS VMAIL: Recording Type: Auto Recording Incoming HuntGroup
16:15:36 1695652mS VMAIL: Answering Party Number: Answering Party Name:
16:15:36 1695652mS VMAIL: Targeted Party Offset: 00
16:15:36 1695652mS VMAIL: Compression Format: input[00] output[00]
16:15:36 1695652mS VMAIL: Validated Recording: No
16:15:36 1695652mS VMAIL: UTC Date:
16:15:36 1695652mS VMAIL: UTC Time:
16:15:36 1695652mS CMCallEvt: 0.1056.0 -1 BaseEP: DELETE CMEndpoint f4966834 TOTAL NOW=5 CALL_LIST=2
16:15:36 1695653mS CD: CALL: 0.1055.0 BState=Ringing Cut=1 Music=2.0 Aend="T" (0.1) Bend="" [] (0.0) CalledNum=5001 (HunterEd Group) CallingNum=88 (Test 1) Internal=1 Time=6467 AState=Ringing
16:15:36 1695655mS CMMap: a=0.1 b=0.0 R0
16:15:36 1695655mS CMMap: PCG::MapBChan pcp[142]b1r0 cp_b f54c03f4 other_cp_b 0 type CGTypeSimple
16:15:36 1695655mS CMMap: PCG::MapBChan pcp[87]b1r0 cp_b f52698c4 other_cp_b f54c03f4 type CGTypeSimple
16:15:36 1695655mS CMMap: a=0.1 b=21.11 M12
16:15:36 1695656mS CD: CALL: 0.1055.0 BState=Ringing Cut=1 Music=2.0 Aend="T" (0.1) Bend="" [] (0.0) CalledNum=5001 (HunterEd Group) CallingNum=88 (Test 1) Internal=1 Time=6471 AState=Ringing
16:15:36 1695656mS CMTARGET: 0.1055.0 17 Test 1.0: PrimeForHGTarget: HunterEd Group setorig=0 recall=0 resetExtnVars 0
16:15:36 1695656mS CMTARGET: 0.1055.0 17 Test 1.0: AddHGTarget HunterEd Group (depth=0) allowq=0 type=CMNTypeDefault
16:15:36 1695656mS CMTARGET: 0.1055.0 17 Test 1.0: OV visable. VM NOT visable:Timer running
16:15:36 1695656mS CMTARGET: 0.1055.0 17 Test 1.0: FindNextInServiceHgInHgIncludedList: No Alternative!
16:15:36 1695656mS CMTARGET: 0.1055.0 17 Test 1.0: PrimeForHGTarget: HunterEd Group setorig=0 recall=0 resetExtnVars 1
16:15:36 1695656mS CMTARGET: 0.1055.0 17 Test 1.0: AddHGTarget HunterEd Group (depth=1) allowq=0 type=CMNTypeDefault
16:15:36 1695656mS CMTARGET: 0.1055.0 17 Test 1.0: AddHGTargetRingIdle(LongestWaiting) HunterEd Group ring_attempt_count 0
16:15:36 1695657mS CMTARGET: 0.1055.0 17 Test 1.0: OV visable. VM NOT visable:Timer running
16:15:36 1695657mS CMTARGET: 0.1055.0 17 Test 1.0: FindNextInServiceHgInHgIncludedList: No Alternative!
16:15:36 1695657mS CMTARGET: 0.1055.0 17 Test 1.0: ADD HG HunterEd Group Complete search - no targets found
16:15:37 1696116mS RES: Thu 7/8/2014 16:15:35 FreeMem=49367868 49149588(2) CachedMem=218280 CMMsg=6(8) Buff=5200 1371 999 7438 5 Links=1604 BTree=13583 CPU=25.38% CPUStats=1/4/15222/18517/35955/0/1 MCR=0 MCW=0
16:15:37 1696116mS RES2: IP 500 V2 9.0.3.0 build 941 Tasks=55 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=61 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=1 TCP=19(TLS=4) TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
16:15:37 1696116mS RES4: XML MemObjs=61 PoolMem=2097152(1) FreeMem=2081872(1)
16:15:37 1696398mS RasRx: v=IFace=LAN2, Src=192.168.50.10:49308, Dst=192.168.50.5:1719 peb=0
RasMessage = registrationRequest = {
requestSeqNum = 1569
protocolIdentifier = 0.0.8.2250.0.5
discoveryComplete = true
callSignalAddress = { 1 item(s)
[0] = ipAddress = {
ip =
c0 a8 32 0a ..2.
port = 5988
}
}
rasAddress = { 1 item(s)
[0] = ipAddress = {
ip =
c0 a8 32 0a ..2.
port = 49308
}
}
terminalType = {
terminal = {
}
mc = false
undefinedNode = false
}
endpointVendor = {
vendor = {
t35CountryCode = 181
t35Extension = 0
manufacturerCode = 16726
}
productId =
49 50 5f 50 68 6f 6e 65 IP_Phone
versionId =
33 2e 31 38 36 61 3.186a
}
timeToLive = 60
keepAlive = true
endpointIdentifier =
0046 0069 0073 0068 002d 0057 0069 006c Fish-Wil
0064 006c 0069 0066 0065 005f 0035 0033 dlife_53
0065 0033 0039 0066 0063 0037 0033 0030 e39fc730
0063 0064 0032 0035 0038 0039 cd2589
}
16:15:37 1696398mS H323Evt: Recv: RegistrationRequest 192.168.50.10; Endpoints registered: 1; Endpoints in registration: 0
16:15:37 1696399mS RasTx: v=Src=192.168.50.5:1719, Dst=192.168.50.10:49308 peb=0
RasMessage = registrationConfirm = {
requestSeqNum = 1569
protocolIdentifier = 0.0.8.2250.0.5
callSignalAddress = { 1 item(s)
[0] = ipAddress = {
ip =
c0 a8 32 05 ..2.
port = 1720
}
}
gatekeeperIdentifier =
0053 004d 0042 0053 SMBS
endpointIdentifier =
0046 0069 0073 0068 002d 0057 0069 006c Fish-Wil
0064 006c 0069 0066 0065 005f 0035 0033 dlife_53
0065 0033 0039 0066 0063 0037 0033 0030 e39fc730
0063 0064 0032 0035 0038 0039 cd2589
timeToLive = 60
willRespondToIRR = false
preGrantedARQ = {
makeCall = true
useGKCallSignalAddressToMakeCall = true
answerCall = true
useGKCallSignalAddressToAnswer = true
}
}
16:15:37 1696656mS CD: CALL: 0.1055.0 BState=Ringing Cut=1 Music=2.0 Aend="T" (0.1) Bend="" [] (0.0) CalledNum=5001 (HunterEd Group) CallingNum=88 (Test 1) Internal=1 Time=7471 AState=Ringing
16:15:37 1696656mS CMTARGET: 0.1055.0 17 Test 1.0: PrimeForHGTarget: HunterEd Group setorig=0 recall=0 resetExtnVars 0
16:15:37 1696656mS CMTARGET: 0.1055.0 17 Test 1.0: AddHGTarget HunterEd Group (depth=0) allowq=0 type=CMNTypeDefault
16:15:37 1696656mS CMTARGET: 0.1055.0 17 Test 1.0: OV visable. VM NOT visable:Timer running
16:15:37 1696656mS CMTARGET: 0.1055.0 17 Test 1.0: FindNextInServiceHgInHgIncludedList: No Alternative!
16:15:37 1696656mS CMTARGET: 0.1055.0 17 Test 1.0: PrimeForHGTarget: HunterEd Group setorig=0 recall=0 resetExtnVars 1
16:15:37 1696656mS CMTARGET: 0.1055.0 17 Test 1.0: AddHGTarget HunterEd Group (depth=1) allowq=0 type=CMNTypeDefault
16:15:37 1696657mS CMTARGET: 0.1055.0 17 Test 1.0: AddHGTargetRingIdle(LongestWaiting) HunterEd Group ring_attempt_count 0
16:15:37 1696657mS CMTARGET: 0.1055.0 17 Test 1.0: OV visable. VM NOT visable:Timer running
16:15:37 1696657mS CMTARGET: 0.1055.0 17 Test 1.0: FindNextInServiceHgInHgIncludedList: No Alternative!
16:15:37 1696657mS CMTARGET: 0.1055.0 17 Test 1.0: ADD HG HunterEd Group Complete search - no targets found
16:15:38 1697656mS CD: CALL: 0.1055.0 BState=Ringing Cut=1 Music=2.0 Aend="T" (0.1) Bend="" [] (0.0) CalledNum=5001 (HunterEd Group) CallingNum=88 (Test 1) Internal=1 Time=8471 AState=Ringing
16:15:38 1697656mS CMTARGET: 0.1055.0 17 Test 1.0: PrimeForHGTarget: HunterEd Group setorig=0 recall=0 resetExtnVars 0
16:15:38 1697656mS CMTARGET: 0.1055.0 17 Test 1.0: AddHGTarget HunterEd Group (depth=0) allowq=0 type=CMNTypeDefault
16:15:38 1697656mS CMTARGET: 0.1055.0 17 Test 1.0: OV visable. VM NOT visable:Timer running
16:15:38 1697656mS CMTARGET: 0.1055.0 17 Test 1.0: FindNextInServiceHgInHgIncludedList: No Alternative!
16:15:38 1697656mS CMTARGET: 0.1055.0 17 Test 1.0: PrimeForHGTarget: HunterEd Group setorig=0 recall=0 resetExtnVars 1
16:15:38 1697656mS CMTARGET: 0.1055.0 17 Test 1.0: AddHGTarget HunterEd Group (depth=1) allowq=0 type=CMNTypeDefault
16:15:38 1697657mS CMTARGET: 0.1055.0 17 Test 1.0: AddHGTargetRingIdle(LongestWaiting) HunterEd Group ring_attempt_count 0
16:15:38 1697657mS CMTARGET: 0.1055.0 17 Test 1.0: OV visable. VM NOT visable:Timer running
16:15:38 1697657mS CMTARGET: 0.1055.0 17 Test 1.0: FindNextInServiceHgInHgIncludedList: No Alternative!
16:15:38 1697657mS CMTARGET: 0.1055.0 17 Test 1.0: ADD HG HunterEd Group Complete search - no targets found
16:15:39 1698656mS CD: CALL: 0.1055.0 BState=Ringing Cut=1 Music=2.0 Aend="T" (0.1) Bend="" [] (0.0) CalledNum=5001 (HunterEd Group) CallingNum=88 (Test 1) Internal=1 Time=9471 AState=Ringing
16:15:39 1698656mS CMTARGET: 0.1055.0 17 Test 1.0: PrimeForHGTarget: HunterEd Group setorig=0 recall=0 resetExtnVars 0
16:15:39 1698656mS CMTARGET: 0.1055.0 17 Test 1.0: AddHGTarget HunterEd Group (depth=0) allowq=0 type=CMNTypeDefault
16:15:39 1698656mS CMTARGET: 0.1055.0 17 Test 1.0: OV visable. VM NOT visable:Timer running
16:15:39 1698656mS CMTARGET: 0.1055.0 17 Test 1.0: FindNextInServiceHgInHgIncludedList: No Alternative!
16:15:39 1698656mS CMTARGET: 0.1055.0 17 Test 1.0: PrimeForHGTarget: HunterEd Group setorig=0 recall=0 resetExtnVars 1
16:15:39 1698657mS CMTARGET: 0.1055.0 17 Test 1.0: AddHGTarget HunterEd Group (depth=1) allowq=0 type=CMNTypeDefault
16:15:39 1698657mS CMTARGET: 0.1055.0 17 Test 1.0: AddHGTargetRingIdle(LongestWaiting) HunterEd Group ring_attempt_count 0
16:15:39 1698657mS CMTARGET: 0.1055.0 17 Test 1.0: OV visable. VM NOT visable:Timer running
16:15:39 1698657mS CMTARGET: 0.1055.0 17 Test 1.0: FindNextInServiceHgInHgIncludedList: No Alternative!
16:15:39 1698657mS CMTARGET: 0.1055.0 17 Test 1.0: ADD HG HunterEd Group Complete search - no targets found
16:15:40 1699656mS CD: CALL: 0.1055.0 BState=Ringing Cut=1 Music=2.0 Aend="T" (0.1) Bend="" [] (0.0) CalledNum=5001 (HunterEd Group) CallingNum=88 (Test 1) Internal=1 Time=10471 AState=Ringing
16:15:40 1699656mS CMTARGET: 0.1055.0 17 Test 1.0: PrimeForHGTarget: HunterEd Group setorig=0 recall=0 resetExtnVars 0
16:15:40 1699656mS CMTARGET: 0.1055.0 17 Test 1.0: AddHGTarget HunterEd Group (depth=0) allowq=0 type=CMNTypeDefault
16:15:40 1699656mS CMTARGET: 0.1055.0 17 Test 1.0: OV visable. VM NOT visable:Timer running
16:15:40 1699656mS CMTARGET: 0.1055.0 17 Test 1.0: FindNextInServiceHgInHgIncludedList: No Alternative!
16:15:40 1699656mS CMTARGET: 0.1055.0 17 Test 1.0: PrimeForHGTarget: HunterEd Group setorig=0 recall=0 resetExtnVars 1
16:15:40 1699657mS CMTARGET: 0.1055.0 17 Test 1.0: AddHGTarget HunterEd Group (depth=1) allowq=0 type=CMNTypeDefault
16:15:40 1699657mS CMTARGET: 0.1055.0 17 Test 1.0: AddHGTargetRingIdle(LongestWaiting) HunterEd Group ring_attempt_count 0
16:15:40 1699657mS CMTARGET: 0.1055.0 17 Test 1.0: OV visable. VM NOT visable:Timer running
16:15:40 1699657mS CMTARGET: 0.1055.0 17 Test 1.0: FindNextInServiceHgInHgIncludedList: No Alternative!
16:15:40 1699657mS CMTARGET: 0.1055.0 17 Test 1.0: ADD HG HunterEd Group Complete search - no targets found
16:15:41 1700656mS CD: CALL: 0.1055.0 BState=Ringing Cut=1 Music=2.0 Aend="T" (0.1) Bend="" [] (0.0) CalledNum=5001 (HunterEd Group) CallingNum=88 (Test 1) Internal=1 Time=11471 AState=Ringing
16:15:41 1700656mS CMTARGET: 0.1055.0 17 Test 1.0: PrimeForHGTarget: HunterEd Group setorig=0 recall=0 resetExtnVars 0
16:15:41 1700656mS CMTARGET: 0.1055.0 17 Test 1.0: AddHGTarget HunterEd Group (depth=0) allowq=0 type=CMNTypeDefault
16:15:41 1700656mS CMTARGET: 0.1055.0 17 Test 1.0: OV visable. VM NOT visable:Timer running
16:15:41 1700657mS CMTARGET: 0.1055.0 17 Test 1.0: FindNextInServiceHgInHgIncludedList: No Alternative!
16:15:41 1700657mS CMTARGET: 0.1055.0 17 Test 1.0: PrimeForHGTarget: HunterEd Group setorig=0 recall=0 resetExtnVars 1
16:15:41 1700657mS CMTARGET: 0.1055.0 17 Test 1.0: AddHGTarget HunterEd Group (depth=1) allowq=0 type=CMNTypeDefault
16:15:41 1700657mS CMTARGET: 0.1055.0 17 Test 1.0: AddHGTargetRingIdle(LongestWaiting) HunterEd Group ring_attempt_count 0
16:15:41 1700657mS CMTARGET: 0.1055.0 17 Test 1.0: OV visable. VM NOT visable:Timer running
16:15:41 1700657mS CMTARGET: 0.1055.0 17 Test 1.0: FindNextInServiceHgInHgIncludedList: No Alternative!
16:15:41 1700657mS CMTARGET: 0.1055.0 17 Test 1.0: ADD HG HunterEd Group Complete search - no targets found
16:15:42 1701656mS CD: CALL: 0.1055.0 BState=Ringing Cut=1 Music=2.0 Aend="T" (0.1) Bend="" [] (0.0) CalledNum=5001 (HunterEd Group) CallingNum=88 (Test 1) Internal=1 Time=12471 AState=Ringing
16:15:42 1701656mS CMTARGET: 0.1055.0 17 Test 1.0: PrimeForHGTarget: HunterEd Group setorig=0 recall=0 resetExtnVars 0
16:15:42 1701656mS CMTARGET: 0.1055.0 17 Test 1.0: AddHGTarget HunterEd Group (depth=0) allowq=0 type=CMNTypeDefault
16:15:42 1701656mS CMTARGET: 0.1055.0 17 Test 1.0: OV visable. VM NOT visable:Timer running
16:15:42 1701656mS CMTARGET: 0.1055.0 17 Test 1.0: FindNextInServiceHgInHgIncludedList: No Alternative!
16:15:42 1701656mS CMTARGET: 0.1055.0 17 Test 1.0: PrimeForHGTarget: HunterEd Group setorig=0 recall=0 resetExtnVars 1
16:15:42 1701656mS CMTARGET: 0.1055.0 17 Test 1.0: AddHGTarget HunterEd Group (depth=1) allowq=0 type=CMNTypeDefault
16:15:42 1701657mS CMTARGET: 0.1055.0 17 Test 1.0: AddHGTargetRingIdle(LongestWaiting) HunterEd Group ring_attempt_count 0
16:15:42 1701657mS CMTARGET: 0.1055.0 17 Test 1.0: OV visable. VM NOT visable:Timer running
16:15:42 1701657mS CMTARGET: 0.1055.0 17 Test 1.0: FindNextInServiceHgInHgIncludedList: No Alternative!
16:15:42 1701657mS CMTARGET: 0.1055.0 17 Test 1.0: ADD HG HunterEd Group Complete search - no targets found
16:15:43 1702656mS CD: CALL: 0.1055.0 BState=Ringing Cut=1 Music=2.0 Aend="T" (0.1) Bend="" [] (0.0) CalledNum=5001 (HunterEd Group) CallingNum=88 (Test 1) Internal=1 Time=13471 AState=Ringing
16:15:43 1702656mS CMTARGET: 0.1055.0 17 Test 1.0: PrimeForHGTarget: HunterEd Group setorig=0 recall=0 resetExtnVars 0
16:15:43 1702656mS CMTARGET: 0.1055.0 17 Test 1.0: AddHGTarget HunterEd Group (depth=0) allowq=0 type=CMNTypeDefault
16:15:43 1702656mS CMTARGET: 0.1055.0 17 Test 1.0: OV visable. VM NOT visable:Timer running
16:15:43 1702656mS CMTARGET: 0.1055.0 17 Test 1.0: FindNextInServiceHgInHgIncludedList: No Alternative!
16:15:43 1702656mS CMTARGET: 0.1055.0 17 Test 1.0: PrimeForHGTarget: HunterEd Group setorig=0 recall=0 resetExtnVars 1
16:15:43 1702656mS CMTARGET: 0.1055.0 17 Test 1.0: AddHGTarget HunterEd Group (depth=1) allowq=0 type=CMNTypeDefault
16:15:43 1702657mS CMTARGET: 0.1055.0 17 Test 1.0: AddHGTargetRingIdle(LongestWaiting) HunterEd Group ring_attempt_count 0
16:15:43 1702657mS CMTARGET: 0.1055.0 17 Test 1.0: OV visable. VM NOT visable:Timer running
16:15:43 1702657mS CMTARGET: 0.1055.0 17 Test 1.0: FindNextInServiceHgInHgIncludedList: No Alternative!
16:15:43 1702657mS CMTARGET: 0.1055.0 17 Test 1.0: ADD HG HunterEd Group Complete search - no targets found
16:15:44 1703656mS CD: CALL: 0.1055.0 BState=Ringing Cut=1 Music=2.0 Aend="T" (0.1) Bend="" [] (0.0) CalledNum=5001 (HunterEd Group) CallingNum=88 (Test 1) Internal=1 Time=14471 AState=Ringing
16:15:44 1703656mS CMTARGET: 0.1055.0 17 Test 1.0: PrimeForHGTarget: HunterEd Group setorig=0 recall=0 resetExtnVars 0
16:15:44 1703656mS CMTARGET: 0.1055.0 17 Test 1.0: AddHGTarget HunterEd Group (depth=0) allowq=0 type=CMNTypeDefault
16:15:44 1703656mS CMTARGET: 0.1055.0 17 Test 1.0: OV visable. VM NOT visable:Timer running
16:15:44 1703656mS CMTARGET: 0.1055.0 17 Test 1.0: FindNextInServiceHgInHgIncludedList: No Alternative!
16:15:44 1703656mS CMTARGET: 0.1055.0 17 Test 1.0: PrimeForHGTarget: HunterEd Group setorig=0 recall=0 resetExtnVars 1
16:15:44 1703657mS CMTARGET: 0.1055.0 17 Test 1.0: AddHGTarget HunterEd Group (depth=1) allowq=0 type=CMNTypeDefault
16:15:44 1703657mS CMTARGET: 0.1055.0 17 Test 1.0: AddHGTargetRingIdle(LongestWaiting) HunterEd Group ring_attempt_count 0
16:15:44 1703657mS CMTARGET: 0.1055.0 17 Test 1.0: OV visable. VM NOT visable:Timer running
16:15:44 1703657mS CMTARGET: 0.1055.0 17 Test 1.0: FindNextInServiceHgInHgIncludedList: No Alternative!
16:15:44 1703657mS CMTARGET: 0.1055.0 17 Test 1.0: ADD HG HunterEd Group Complete search - no targets found
16:15:44 1703660mS CMExtnRx: v=RAS, p1=0
CMReleaseComp
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
Cause=16, Normal call clearing
16:15:44 1703660mS CMCallEvt: 0.1058.0 17 RAS.0: StateChange: END=B CMCSConnected->CMCSCompleted
16:15:44 1703660mS CMExtnEvt: v=1012 State, new=PortRecoverDelay old=Connected,0,0,RAS
16:15:44 1703660mS CMExtnEvt: RAS: CALL LOST (CMCauseNormal)
16:15:44 1703660mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 1058->0 )
16:15:44 1703660mS CMCallEvt: 0.1058.0 -1 RAS.-1: StateChange: END=X CMCSCompleted->CMCSDelete
16:15:44 1703660mS CMExtnEvt: v=1012 State, new=Idle old=PortRecoverDelay,0,0,RAS
16:15:44 1703661mS CMExtnTx: v=RAS, p1=0
CMReleaseComp
Line: type=RAS 1 Call: lid=0 id=1012 in=0
BChan: slot=21 chan=11
Cause=16, Normal call clearing
16:15:44 1703661mS VMAIL: SESS 6c: CMD=2 Mailbox=$Queued;HunterEd Group,1,0 Access=8 !Internal!=Y
16:15:44 1703661mS VMAIL: VMAIL Open [VMMESSAGE_CLOSE] info :-
16:15:44 1703661mS VMAIL: Flags :- 65 6e 75 00 00 00 00 11
16:15:44 1703661mS VMAIL: 00 00 10 66 01 80 00 00
16:15:44 1703661mS VMAIL: Mailbox: $Queued;HunterEd Group,1,0 Access: 8 !Internal!=Y Locale: enu
16:15:44 1703661mS VMAIL: Calling Party number: 88 Display String:
16:15:44 1703661mS VMAIL: Greeting Modifier:
16:15:44 1703661mS VMAIL: Targeted Party number: 5001 Called Party number:
16:15:44 1703661mS VMAIL: Calling Party name: Test 1 Called Party name: HunterEd Group
16:15:44 1703661mS VMAIL: DID number QUEUE: CallAge=6 QueueAge=5 QPos=1 QEta=0 AnnouncmentSync=0
16:15:44 1703661mS VMAIL: Recording Originator: IP addr. of Recording Originator: 0.0.0.0
16:15:44 1703662mS VMAIL: Targeted Party Name: HunterEd Group
16:15:44 1703662mS VMAIL: Recording Type: Auto Recording Incoming HuntGroup
16:15:44 1703662mS VMAIL: Answering Party Number: Answering Party Name:
16:15:44 1703662mS VMAIL: Targeted Party Offset: 00
16:15:44 1703662mS VMAIL: Compression Format: input[00] output[00]
16:15:44 1703662mS VMAIL: Validated Recording: No
16:15:44 1703662mS VMAIL: UTC Date:
16:15:44 1703662mS VMAIL: UTC Time:
16:15:44 1703662mS VMAIL: SESS 6c: END Transmit=64 Discards=0 Recv=70 SeqErrs=0 Empty=0 Purges=0
16:15:44 1703663mS CMMap: a=21.11 b=0.0 T0
16:15:44 1703664mS CMCallEvt: 0.1059.0 -1 BaseEP: NEW CMEndpoint f4966834 TOTAL NOW=6 CALL_LIST=2
16:15:44 1703664mS CMCallEvt: 0.1059.0 17 TargetingEP: StateChange: END=??? CMCSIdle->CMCSConnected
16:15:44 1703665mS CMExtnEvt: RAS: CALL LOST (CMCauseNormal)
16:15:44 1703665mS CMExtnTx: v=RAS, p1=0
CMReleaseComp
Line: type=RAS 1 Call: lid=0 id=1012 in=0
Called[!$Queued;HunterEd Group,1,0] Type=ACD (103) Reason=CMDRdirect Calling[88] Type=Default Plan=Default
BChan: slot=21 chan=11
Cause=16, Normal call clearing
16:15:44 1703665mS CMExtnTx: v=RAS, p1=0
CMReleaseComp
Line: type=RAS 1 Call: lid=0 id=1012 in=0
BChan: slot=21 chan=11
Cause=16, Normal call clearing
16:15:44 1703666mS CMCallEvt: 0.1058.0 -1 BaseEP: DELETE CMEndpoint f499440c TOTAL NOW=5 CALL_LIST=2
16:15:44 1703667mS CMMap: PCG::UnmapBChan pcp[142]b1r0 cp_b f54c03f4 other_cp_b f52698c4
16:15:44 1703667mS CMMap: a=21.11 b=0.1 M02
16:15:44 1703667mS CMMap: PCG::UnmapBChan pcp[87]b1r0 cp_b f52698c4 other_cp_b 0
16:15:44 1703667mS CMMap: a=0.1 b=0.0 H1
********** Warning: Logging to Screen Stopped **********
It's only dialtone!!!