13:35:10 8750106mS PRN: Q931Trunk: Found QBChannel to match 0.2 --> 7.3
13:35:10 8750106mS CMCallEvt: 0000000000000000 0.536872313.0 -1 BaseEP: NEW CMEndpoint f17cd5f4 TOTAL NOW=5 CALL_LIST=2
13:35:10 8750106mS CMTARGET: ISDN BChannel 2: in-service check = 1
13:35:10 8750107mS CMCallEvt: CREATE CALL:103 (f17f42cc)
13:35:10 8750107mS CMCallEvt: 0000000000000000 0.536872314.0 -1 BaseEP: NEW CMEndpoint f17f2428 TOTAL NOW=6 CALL_LIST=2
13:35:10 8750110mS CMCallEvt: 0a0000f920000579 13.6.1 103 Q931 Trunk:13 CHAN=2: StateChange: END=A CMCSIdle->CMCSDialInitiated
13:35:10 8750110mS CMTARGET: 0a0000f920000579 13.6.1 103 Q931 Trunk:13 CHAN=2: LOOKUP CALL ROUTE: GID=0 type=0 called_party=5592562383 sub= calling=5592245922 calling_sub= dir=in complete=1 ses=0
13:35:10 8750111mS CMTARGET: 0a0000f920000579 13.6.1 103 Q931 Trunk:13 CHAN=2: SET BESTMATCH: length 0 vs -1 match= dest=Operator climatch -1 vs -1
13:35:10 8750111mS CMTARGET: 0a0000f920000579 13.6.1 103 Q931 Trunk:13 CHAN=2: SET BESTMATCH: length 4 vs 0 match=2383 dest=2900 climatch -1 vs -1
13:35:10 8750111mS CMCallEvt: Priority hike: call 103 priority 0->1
13:35:10 8750111mS CMTARGET: 0a0000f920000579 13.6.1 103 Q931 Trunk:13 CHAN=2: LOOKUP ICR: DDI= CGPN=5592245922 (Destination 2900 CLI ) => CDPN=2900
13:35:10 8750112mS CMTARGET: 0a0000f920000579 13.6.1 103 Q931 Trunk:13 CHAN=2: ADD TARGET (N): number=2900 type=0 depth=1 nobar=1 setorig=1 ses=0
13:35:10 8750112mS CMTARGET: 0a0000f920000579 13.6.1 103 Q931 Trunk:13 CHAN=2: SET USER: ForwardTest orig=1
13:35:10 8750112mS CMTARGET: 0a0000f920000579 13.6.1 103 Q931 Trunk:13 CHAN=2: ADD USER: ForwardTest depth=1 disallow_cw=0 dnd=0 real_call=1 group_call=0 type(CMNTypeUnknown) incl(0x0) excpt(0x0), allow_redir(1) remote=00000000 simult 0 (0) r_lic=
T
13:35:10 8750113mS CMTARGET: TryLoggedOutTargeting for user ForwardTest Failed Normal
13:35:10 8750113mS CMTARGET: FoundKnownSystemTargets ICR cache hit
13:35:10 8750113mS CMTARGET: 0a0000f920000579 13.6.1 103 Q931 Trunk:13 CHAN=2: NO INITIAL TARGETS: ForwardTest
13:35:10 8750113mS CMTARGET: 0a0000f920000579 13.6.1 103 Q931 Trunk:13 CHAN=2: ADD USER: ForwardTest depth=1 disallow_cw=0 dnd=0 real_call=1 group_call=0 type(CMNTypeUnknown) incl(0x40) excpt(0x40), allow_redir(1) remote=00000000 simult 0 (0) r_li
c=T
13:35:10 8750114mS CMTARGET: TryLoggedOutTargeting for user ForwardTest Failed Normal
13:35:10 8750114mS CMTARGET: 0a0000f920000579 13.6.1 103 Q931 Trunk:13 CHAN=2: SELECT: TRY VOICEMAIL orig_hg() orig_user(2900)
13:35:10 8750114mS CMTARGET: 0a0000f920000579 13.6.1 103 Q931 Trunk:13 CHAN=2: ADD VM TARGET
13:35:10 8750114mS CMTARGET: 0a0000f920000579 13.6.1 103 Q931 Trunk:13 CHAN=2: MakeVoicemailTarget pbx=<null> local=1 type=1
13:35:10 8750115mS CMCallEvt: 0000000000000000 0.536872315.0 -1 BaseEP: NEW CMEndpoint f17c0af0 TOTAL NOW=7 CALL_LIST=3
13:35:10 8750115mS CMTARGET: 0a0000f92000057b 0.536872315.0 103 RAS.0: ADD PRIMARY
13:35:10 8750115mS CMTARGET: 0a0000f920000579 13.6.1 103 Q931 Trunk:13 CHAN=2: ADD VM TARGET: SUCCEEDED
13:35:10 8750115mS CMTARGET: 0a0000f920000579 13.6.1 103 Q931 Trunk:13 CHAN=2: GetNoAnswerTimer:20
13:35:10 8750116mS CMCallEvt: 0a0000f920000579 13.6.1 103 Q931 Trunk:13 CHAN=2: StateChange: END=A CMCSDialInitiated->CMCSDialled
13:35:10 8750116mS CMLineTx: v=13
CMProceeding
Line: type=Q931Line 13 Call: lid=13 id=6 in=1
BChan: slot=0 chan=2
13:35:10 8750116mS CMCallEvt: 0000000000000000 0.536872314.0 103 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
13:35:10 8750117mS CMCallEvt: 0a0000f92000057b 0.536872315.0 103 RAS.0: StateChange: END=T CMCSIdle->CMCSOffering
13:35:10 8750117mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 0->536872315 )
13:35:10 8750118mS CMExtnTx: v=RAS, p1=0
CMSetup
Line: type=RAS 1 Call: lid=0 id=1011 in=0
Called[#ForwardTest] Type=Voicemail (102) Reason=107 SndComp Calling[5592245922] Type=Unknown Plan=ISDN Pres=Allowed (0)
BC: CMTC=Speech CMTM=CallIndSigCon CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=21 chan=42
IE CMIESupplementaryService (3)
Interpretation APDU
rejectAnyUnrecognisedInvokePdu
CallingName.Invoke.CodePageISO8859-1
invokeId 1
user 'Jim' presentation Allowed
IE CMIECallingPartyName (110)(Type=CMNameDefault) name=Jim
IE CMIECalledPartyName (224)(Type=CMNameInternalUser) name=ForwardTest
IE CMIECalledPartyKName (225)(Type=CMNameDefault) name=ForwardTest
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Jim
IE CMIERespondingPartyNumber (230)(P:0 S:0 T:0 N:1 R:4) number=5592245922
IE CMIEDeviceDetail (231) 0a0000f920000579 LOCALE=enu HW=15 VER=11 class=CMDeviceISDNTrunk type=2 number=13 channel=2 features=0x20 rx_gain=32 tx_gain=32
ep_callid=536872313 ipaddr=10.0.0.249 apps=0 loc=0 em_a_loc=0 em_d_loc=0 features2=0x0 is_spcall=1 ignores_dtmf=0
avgsid=<>
IE CMIERedirectingPartyName (232)(Type=CMNameInternalUser) name=ForwardTest
IE CMIERedirectingPartyKName (233)(Type=CMNameDefault) name=ForwardTest
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:2) number=2900
IE CMIEOriginalCalledPartyName (236)(Type=CMNameInternalUser) name=ForwardTest
IE CMIEOriginalCalledPartyKName (237)(Type=CMNameDefault) name=ForwardTest
IE CMIEOriginalCalledPartyNumber (238)(P:100 S:100 T:101 N:100 R:2) number=2900
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEDIDNumber (245)(P:100 S:100 T:0 N:1 R:4) number=5592562383
IE CMIEMohSourceId (247) MOH Source = 1
Display [Jim>ForwardTest]
Timed: 14/07/22 13:35
Locale: enu
13:35:10 8750118mS CMMap: a=21.42 b=1.65534 T
13:35:10 8750120mS CMExtnRx: v=RAS, p1=0
CMAlerting
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
IE CMIERespondingPartyName (228)(Type=CMName_VMPro) name=#ForwardTest
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:102 N:100 R:4) number=#ForwardTest
IE CMIEDeviceDetail (231) 0a0000f92000057b LOCALE=enu HW=15 VER=11 class=CMDeviceVoicemail type=0 number=0 channel=42 features=0x0 rx_gain=32 tx_gain=32
ep_callid=536872315 ipaddr=10.0.0.249 apps=0 loc=0 em_a_loc=0 em_d_loc=0 features2=0x0 is_spcall=0 ignores_dtmf=0
avgsid=<>
IE CMIERedirectingPartyName (232)(Type=CMNameInternalUser) name=ForwardTest
IE CMIERedirectingPartyKName (233)(Type=CMNameDefault) name=ForwardTest
IE CMIERedirectingPartyNumber (234)(P:100 S:100 T:101 N:100 R:2) number=2900
13:35:10 8750121mS CMCallEvt: 0a0000f92000057b 0.536872315.0 103 RAS.0: StateChange: END=T CMCSOffering->CMCSRinging
13:35:10 8750121mS CMExtnEvt: v=1011 State, new=Ringing old=Idle,0,0,RAS
13:35:10 8750121mS CMTARGET: 0a0000f920000579 13.6.1 103 Q931 Trunk:13 CHAN=2: CancelTimer CMTCNoAnswerTimeout
13:35:10 8750121mS CMCallEvt: 0000000000000000 0.536872314.0 103 TargetingEP: StateChange: END=B CMCSOffering->CMCSRinging
13:35:10 8750121mS CMCallEvt: 0a0000f920000579 13.6.1 103 Q931 Trunk:13 CHAN=2: StateChange: END=A CMCSDialled->CMCSRingBack
13:35:10 8750122mS CMLineTx: v=13
CMAlerting
Line: type=Q931Line 13 Call: lid=13 id=6 in=1
BChan: slot=0 chan=2
13:35:10 8750125mS ISDNL3Evt: v=13 stacknum=13 State, new=ICProceeding, old=Present id=6
13:35:10 8750126mS ISDNL3Evt: v=13 stacknum=13 State, new=Received, old=ICProceeding id=6
13:35:10 8750126mS CMMap: a=7.3 b=0.0 R1
13:35:10 8750131mS RES: Thu 14/7/2022 13:35:09 FreeMem=51951004 Heap=51330388(2) Cache=620616 MemObjs=21006(Max 22562) CMMsg=8(9) ASN=0 Buff=5200 1371 1000 7356 5 Links=61802(62007) BTree=1803(4091) CB=6061 MCT=0 CPU=07.50% CPUStats=13.25%/18/5/821/12
620/14507/00.82%/0/03.66% MCR=0 MCW=0 DEV=0
13:35:10 8750131mS RES2: IP 500 V2 11.1.2.0.0 build 239 Tasks=61 RTEngine=2 CMRTEngine=0 ExRTEngine=0 Timer=12+133 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=1 TCP=58(TLS=7 OFF=0) TAPI=1 Partner=123 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
13:35:10 8750131mS RES4: XML MemObjs=8 PoolMem=4748404(2) FreeMem=4736284(0) HeapUsed=0
13:35:10 8750132mS RES5: CLog MemObjs=1606 FreePoolMem(Objs)=5264(94) TotalMem=95200 StringsTotalMem=136500
13:35:10 8750233mS CMExtnRx: v=RAS, p1=0
CMVoiceMailMsg
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
Called[LeaveMessage] Type=Default (100) Reason=CMDRdirect Calling SubAddress[0]
Tag type=Text flags=0x1 [1.] [0x31 0x00 ]
13:35:10 8750233mS CMCallEvt: 0000000000000000 0.1001.0 -1 BaseEP CMEndpoint does nothing with msg - 38 CMVoiceMailMsg