IP Office with UCM, external calls won't go to autoattendant 1

[wjtcol]

Hello,

This isn't my first VM Pro installation by a long shot, but it is the first time I've used one of these Unified Communications Modules, so perhaps there is something I've missed.

Internally, voicemail works fine, and a shortcode created to call the auto attendant works as well. However, with an n the system status I see the call being routed to the autoattendant, but on the caller side I either get dead air or perpetual ringing.

The auto attendant is named NightService, and in incoming call routes, I have VM:NightService.

Software version is 9.0.300.941.

Thanks,

Will

 

[wjtcol]

Monitor trace of an attempted call in -

UNICODE-UTF8
eng
09:44:23 1899508mS SIP Rx: UDP 93.95.124.20:5060 -> 192.168.100.220:5060
INVITE sip:01394593107@78.141.43.1:51908 SIP/2.0
Via: SIP/2.0/UDP 93.95.124.20:5060;branch=z9hG4bK6e3eb79c;rport
Max-Forwards: 70
From: "01206790060" <sip:01206790060@93.95.124.20>;tag=as117fe475
To: <sip:01394593107@78.141.43.1:51908>
Contact: <sip:01206790060@93.95.124.20:5060>
Call-ID: 5fa96f661643c77d7c03e9510e885fc4@93.95.124.20:5060
CSeq: 102 INVITE
User-Agent: Voiceflex
Date: Thu, 10 Jul 2014 08:44:24 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Content-Type: application/sdp
Content-Length: 328

v=0
o=Voiceflex 1955997430 1955997430 IN IP4 93.95.124.20
s=Voiceflex
c=IN IP4 93.95.124.20
t=0 0
m=audio 20704 RTP/AVP 0 8 18 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSuppff - - - -
a=ptime:20
a=sendrecv
09:44:23 1899512mS CMCallEvt: 0.1051.0 -1 BaseEP: NEW CMEndpoint f4f3c4b0 TOTAL NOW=1 CALL_LIST=0
09:44:23 1899512mS Stun: Info: media ports will be changed due to NAT traversal.
09:44:23 1899515mS SIP Tx: UDP 192.168.100.220:5060 -> 93.95.124.20:5060
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 93.95.124.20:5060;branch=z9hG4bK6e3eb79c;rport
From: "01206790060" <sip:01206790060@93.95.124.20>;tag=as117fe475
Call-ID: 5fa96f661643c77d7c03e9510e885fc4@93.95.124.20:5060
CSeq: 102 INVITE
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,REFER,NOTIFY,UPDATE
Supported: timer
Server: IP Office 9.0.3.0 build 941
To: <sip:01394593107@78.141.43.1:51908>;tag=7dc0cb54cf3aadad
Content-Length: 0

09:44:23 1899518mS CMCallEvt: CREATE CALL:10 (f5668c08)
09:44:23 1899518mS CMCallEvt: 0.1052.0 -1 BaseEP: NEW CMEndpoint f4f145a8 TOTAL NOW=2 CALL_LIST=0
09:44:23 1899520mS CMLineRx: v=0
CMSetup
Line: type=SIPLine 17 Call: lid=17 id=1051 in=1
Called[201] Type=Default (100) Reason=CMDRdirect SndComp Calling[01206790060@93.95.124.20] Type=Unknown Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
IE CMIEFastStartInfoData (6) 6 item(s)
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=01206790060@93.95.124.20
IE CMIEDeviceDetail (231) LOCALE=eng HW=15 VER=9 class=CMDeviceSIPTrunk type=0 number=17 channel=1 features=0x0 rx_gain=32 tx_gain=32 ep_callid=1051 ipaddr=192.168.100.220 apps=0 loc=999 em_loc=999 features2=0x0
IE CMIEDIDNumber (245)(P:100 S:100 T:100 N:100 R:4) number=01394593107
09:44:23 1899520mS CD: CALL: 17.1051.1 BState=Idle Cut=1 Music=0.0 Aend="Line 17" (0.0) Bend="" [] (0.0) CalledNum=201 () CallingNum=01206790060@93.95.124.20 () Internal=0 Time=3 AState=Idle
09:44:23 1899520mS CMCallEvt: 17.1051.1 10 SIPTrunk Endpoint: StateChange: END=A CMCSIdle->CMCSDialInitiated
09:44:23 1899521mS CMTARGET: 17.1051.1 10 SIPTrunk Endpoint: LOOKUP CALL ROUTE: type=100 called_party=201 sub= calling=01206790060@93.95.124.20 dir=in complete=1 ses=0
09:44:23 1899521mS CMTARGET: 17.1051.1 10 SIPTrunk Endpoint: SET BESTMATCH: length 0 vs -1 match= dest=Main
09:44:23 1899521mS CMCallEvt: Priority hike: call 10 priority 0->1
09:44:23 1899521mS CMTARGET: 17.1051.1 10 SIPTrunk Endpoint: LOOKUP ICR: DDI=01394593107 CGPN=01206790060@93.95.124.20 Matched Time profile: Open (Destination VM:NightService ) => CDPN=VM:NightService
09:44:23 1899521mS CMTARGET: 17.1051.1 10 SIPTrunk Endpoint: ADD TARGET (N): number=VM:NightService type=100 depth=1 nobar=1 setorig=1 ses=0
09:44:23 1899521mS CMTARGET: 17.1051.1 10 SIPTrunk Endpoint: ADD VM TARGET
09:44:23 1899522mS CMTARGET: **** 17.1051.1 10 SIPTrunk Endpoint: MakeVoicemailTarget pbx=<null> local=1 type=1
09:44:23 1899522mS CMMap: a=4.1 b=0.0 Mapper::AllocateCodec allocated CMRTVocoder resource busy 1, total 32
09:44:23 1899522mS CMCallEvt: 0.1053.0 -1 BaseEP: NEW CMEndpoint f4f1df40 TOTAL NOW=3 CALL_LIST=1
09:44:23 1899523mS CMTARGET: 0.1053.0 10 RAS.0: ADD PRIMARY
09:44:23 1899523mS CMTARGET: 17.1051.1 10 SIPTrunk Endpoint: ADD VM TARGET: SUCCEEDED
09:44:23 1899523mS CMTARGET: 17.1051.1 10 SIPTrunk Endpoint: INITIAL TARGETING SUCCEEDED
09:44:23 1899523mS CMTARGET: 17.1051.1 10 SIPTrunk Endpoint: GetNoAnswerTimer:15
09:44:23 1899523mS CMCallEvt: 17.1051.1 10 SIPTrunk Endpoint: StateChange: END=A CMCSDialInitiated->CMCSDialled
09:44:23 1899525mS CMLineTx: v=0
CMProceeding
Line: type=SIPLine 17 Call: lid=17 id=1051 in=1
09:44:23 1899525mS CMCallEvt: 0.1052.0 10 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
09:44:23 1899525mS CMCallEvt: 0.1053.0 10 RAS.0: StateChange: END=T CMCSIdle->CMCSOffering
09:44:23 1899526mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 0->1053 )
09:44:23 1899526mS CMExtnTx: v=RAS, p1=0
CMSetup
Line: type=RAS 1 Call: lid=0 id=1012 in=0
Called[NightService] Type=Voicemail (102) Reason=CMDRX_Attendant SndComp Calling[01206790060@93.95.124.20] Type=Unknown Plan=Default
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ALaw
BChan: slot=21 chan=11
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=01206790060@93.95.124.20
IE CMIEDeviceDetail (231) LOCALE=eng HW=15 VER=9 class=CMDeviceSIPTrunk type=0 number=17 channel=1 features=0x20 rx_gain=32 tx_gain=32 ep_callid=1051 ipaddr=192.168.100.220 apps=0 loc=999 em_loc=999 features2=0x0
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEDIDNumber (245)(P:100 S:100 T:100 N:100 R:4) number=01394593107
IE CMIEMohSourceId (247) MOH Source = 1
Display [01206790060>NightService]
Timed: 10/07/14 09:44
Locale: eng
09:44:23 1899526mS CMMap: a=21.11 b=1.65534 T
09:44:23 1899528mS CMExtnRx: v=RAS, p1=0
CMConnect
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=NightService
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:102 N:100 R:4) number=NightService
IE CMIEDeviceDetail (231) LOCALE=eng HW=15 VER=9 class=CMDeviceVoicemail type=0 number=0 channel=11 features=0x0 rx_gain=32 tx_gain=32 ep_callid=1053 ipaddr=192.168.100.220 apps=0 loc=0 em_loc=0 features2=0x0
09:44:23 1899528mS CMCallEvt: 0.1053.0 10 RAS.0: StateChange: END=T CMCSOffering->CMCSConnReq
09:44:23 1899528mS CMCallEvt: 0.1052.0 10 TargetingEP: RequestEnd 0.1053.0 10 RAS.0
09:44:23 1899528mS CMTARGET: 17.1051.1 10 SIPTrunk Endpoint: CancelTimer CMTCNoAnswerTimeout
09:44:23 1899529mS CMExtnEvt: v=1012 State, new=Connected old=Idle,0,0,RAS
09:44:23 1899529mS CMCallEvt: 17.1051.1 10 SIPTrunk Endpoint: StateChange: END=A CMCSDialled->CMCSOGConnReq
09:44:23 1899533mS CMLineTx: v=0
CMFacility
Line: type=SIPLine 17 Call: lid=17 id=1051 in=1
IE CMIEFastStartInfoData (6) 2 item(s)
09:44:23 1899533mS Stun: EPNatMediaHelper f4f1cc98 for 17.1051.1 10 SIPTrunk Endpoint created
09:44:23 1899533mS Stun: Creating STUNClient to resolve binding for 192.168.100.220:49152, server FQDN [77.72.169.164]:3478
09:44:23 1899533mS Stun: Creating STUNUdpClient (this=0xf4edb550) local port:49152
09:44:23 1899534mS Stun: Creating STUNClient to resolve binding for 192.168.100.220:49153, server FQDN [77.72.169.164]:3478
09:44:23 1899534mS Stun: Creating STUNUdpClient (this=0xf4f0a6f8) local port:49153
09:44:23 1899535mS CMLineTx: v=0
CMConnect
Line: type=SIPLine 17 Call: lid=17 id=1051 in=1
BChan: slot=21 chan=11
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
ConnectedName.Invoke.CodePageISO8859-1
invokeId 14633
user 'NightService' presentation Allowed
IE CMIEFastStartInfoData (6) 2 item(s)
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=NightService
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:102 N:100 R:4) number=NightService
IE CMIEDeviceDetail (231) LOCALE=eng HW=15 VER=9 class=CMDeviceVoicemail type=0 number=0 channel=11 features=0x0 rx_gain=32 tx_gain=32 ep_callid=1053 ipaddr=192.168.100.220 apps=0 loc=0 em_loc=0 features2=0x0
09:44:23 1899535mS Stun: Creating STUNClient to resolve binding for 192.168.100.220:49152, server FQDN [77.72.169.164]:3478
09:44:23 1899535mS Stun: Creating STUNUdpClient (this=0xf4f1b018) local port:49152
09:44:23 1899535mS Stun: Creating STUNClient to resolve binding for 192.168.100.220:49153, server FQDN [77.72.169.164]:3478
09:44:23 1899535mS Stun: Creating STUNUdpClient (this=0xf4f1b640) local port:49153
09:44:23 1899537mS CMCallEvt: 0.1052.0 -1 TargetingEP: StateChange: END=X CMCSOffering->CMCSDelete
09:44:23 1899537mS CMCallEvt: 0.1052.0 -1 BaseEP: DELETE CMEndpoint f4f145a8 TOTAL NOW=2 CALL_LIST=1
09:44:23 1899539mS Stun: StunClient: ResolveIPAndPort 192.168.100.220:49152
09:44:23 1899539mS Stun: STUNUdpClient TX: (this=0xf4edb550) [192.168.100.220:49152]=>[77.72.169.164:3478] ToS:136
09:44:23 1899539mS Stun: StunClient: ResolveIPAndPort 192.168.100.220:49153
09:44:23 1899539mS Stun: STUNUdpClient TX: (this=0xf4f0a6f8) [192.168.100.220:49153]=>[77.72.169.164:3478] ToS:136
09:44:23 1899540mS Stun: StunClient: ResolveIPAndPort 192.168.100.220:49152
09:44:23 1899540mS Stun: STUNUdpClient TX: (this=0xf4f1b018) [192.168.100.220:49152]=>[77.72.169.164:3478] ToS:136
09:44:23 1899540mS Stun: StunClient: ResolveIPAndPort 192.168.100.220:49153
09:44:23 1899540mS Stun: STUNUdpClient TX: (this=0xf4f1b640) [192.168.100.220:49153]=>[77.72.169.164:3478] ToS:136
09:44:23 1899540mS H323Evt: SetOperational 10 192.168.100.220:0 to 0
09:44:23 1899541mS H323Evt: SetRfc2833 10: (1) rx payload 101 tx payload 101
09:44:23 1899541mS CMMap: PCG::MapBChan pcp[101]b0r1 cp_b 0 other_cp_b 0 type CGTypeSimple
09:44:23 1899541mS CMMap: PCGS CPReserveCodec for the other end (pcp[101]b0r1) true
09:44:23 1899541mS CMMap: PCG::MapBChan pcp[45]b1r0 cp_b f568f140 other_cp_b f569cd20 type CGTypeSimple
09:44:23 1899541mS CMMap: a=4.1 b=21.11 M12
09:44:23 1899542mS CMMap: PlatformConnectionAudioSAP::Bind pcp[101]b0r1 to 4.1
09:44:23 1899542mS CMMap: PlatformConnectionAudioSAP::ConnectVoice (connect) pcp[101]b0r1 echo_cancellation set to 0
09:44:23 1899542mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[101]b0r1 Configure 4.1
09:44:23 1899542mS CMMap: PlatformConnectionAudioSAP::ConnectVoice rtpRelay Open got rtpRelay ID: 1
09:44:23 1899542mS CMMap: PlatformConnectionAudioSAP::ConnectVoice rtpRelay Configure success
09:44:23 1899542mS CMMap: PlatformConnectionAudioSAP::ConnectVoice local IP addr 192.168.100.220:49152, Remote IP addr 93.95.124.20:20704
09:44:23 1899542mS CMMap: PlatformConnectionAudioSAP::ConnectVoice DSP IP addr 1.1.1.3:32768, telogyHost IP addr 1.1.1.1:10
09:44:23 1899542mS CMMap: PlatformConnectionAudioSAP::ConnectVoice rtpRelay Start success
09:44:23 1899542mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[101]b0r1 ConnectIndication 4.1
09:44:23 1899543mS H323Evt: SetOperational 10 192.168.100.220:49152 to 1
09:44:23 1899563mS Stun: STUNUdpClient RX: (this=0xf4edb550) [77.72.169.164:3478]=>[192.168.100.220:49152] ToS:184
09:44:23 1899563mS Stun: Response To Enquiry is: 78.141.43.1:63232
09:44:23 1899564mS Stun: STUNUdpClient RX: (this=0xf4edb550) [77.72.169.164:3478]=>[192.168.100.220:49152] ToS:184
09:44:23 1899564mS Stun: STUNUdpClient RX: (this=0xf4f0a6f8) [77.72.169.164:3478]=>[192.168.100.220:49153] ToS:0
09:44:23 1899564mS Stun: Response To Enquiry is: 78.141.43.1:63233
09:44:23 1899564mS Stun: STUNUdpClient RX: (this=0xf4f1b640) [77.72.169.164:3478]=>[192.168.100.220:49153] ToS:0
09:44:23 1899564mS Stun: STUNUdpClient RX: (this=0xf4f0a6f8) [77.72.169.164:3478]=>[192.168.100.220:49153] ToS:0
09:44:23 1899565mS Stun: STUNUdpClient RX: (this=0xf4f1b640) [77.72.169.164:3478]=>[192.168.100.220:49153] ToS:0
09:44:23 1899565mS Stun: Response To Enquiry is: 78.141.43.1:63233
09:44:23 1899569mS Stun: Creating STUNClient to resolve binding for 78.141.43.1:63232, server FQDN [77.72.169.164]:3478
09:44:23 1899569mS Stun: Creating STUNUdpClient (this=0xf4f1b640) local port:63232
09:44:23 1899569mS Stun: Creating STUNClient to resolve binding for 78.141.43.1:63233, server FQDN [77.72.169.164]:3478
09:44:23 1899570mS Stun: Creating STUNUdpClient (this=0xf4f1b018) local port:63233
09:44:23 1899570mS CD: CALL: 17.1051.1 BState=Connected Cut=3 Music=0.0 Aend="Line 17" (0.0) Bend="NightService(NightService)" [VoiceMail] (21.11) CalledNum=NightService () CallingNum=01206790060@93.95.124.20 () Internal=0 Time=53 AState=Connected
09:44:23 1899570mS Stun: StunClient: ResolveIPAndPort 78.141.43.1:63232
09:44:23 1899570mS Stun: STUNUdpClient TX: (this=0xf4f1b640) [192.168.100.220:63232]=>[77.72.169.164:3478] ToS:136
09:44:23 1899571mS Stun: StunClient: ResolveIPAndPort 78.141.43.1:63233
09:44:23 1899571mS Stun: STUNUdpClient TX: (this=0xf4f1b018) [192.168.100.220:63233]=>[77.72.169.164:3478] ToS:136
09:44:23 1899594mS Stun: STUNUdpClient RX: (this=0xf4f1b640) [77.72.169.164:3478]=>[192.168.100.220:63232] ToS:0
09:44:23 1899596mS Stun: STUNUdpClient RX: (this=0xf4f1b018) [77.72.169.164:3478]=>[192.168.100.220:63233] ToS:0
09:44:23 1899899mS RES: Thu 10/7/2014 09:44:23 FreeMem=55061568 54703776(3) CachedMem=357792 CMMsg=5(7) Buff=5200 1362 999 7459 5 Links=6584 BTree=14640 CPU=07.27% CPUStats=30/38/17140/28237/32539/0/0 MCR=0 MCW=0
09:44:23 1899899mS RES2: IP 500 V2 9.0.3.0 build 941 Tasks=49 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=68 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=1 TCP=20(TLS=4) TAPI=1 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
09:44:23 1899899mS RES4: XML MemObjs=61 PoolMem=2097152(1) FreeMem=2081872(1)
09:44:24 1900540mS Stun: StunClient: ResponseTimeout in Resolve RTP, attempt 0
09:44:24 1900570mS Stun: StunClient: ResponseTimeout in Resolve RTP, attempt 0
09:44:24 1900570mS Stun: STUNUdpClient TX: (this=0xf4f1b640) [192.168.100.220:63232]=>[77.72.169.164:3478] ToS:136
09:44:24 1900571mS Stun: StunClient: ResponseTimeout in Resolve RTP, attempt 0
09:44:24 1900571mS Stun: STUNUdpClient TX: (this=0xf4f1b018) [192.168.100.220:63233]=>[77.72.169.164:3478] ToS:136
09:44:24 1900594mS Stun: STUNUdpClient RX: (this=0xf4f1b640) [77.72.169.164:3478]=>[192.168.100.220:63232] ToS:0
09:44:24 1900595mS Stun: STUNUdpClient RX: (this=0xf4f1b018) [77.72.169.164:3478]=>[192.168.100.220:63233] ToS:0
09:44:25 1901540mS Stun: StunClient: ResponseTimeout in Resolve RTP, attempt 1
09:44:25 1901570mS Stun: StunClient: ResponseTimeout in Resolve RTP, attempt 1
09:44:25 1901570mS Stun: STUNUdpClient TX: (this=0xf4f1b640) [192.168.100.220:63232]=>[77.72.169.164:3478] ToS:136
09:44:25 1901571mS Stun: StunClient: ResponseTimeout in Resolve RTP, attempt 1
09:44:25 1901571mS Stun: STUNUdpClient TX: (this=0xf4f1b018) [192.168.100.220:63233]=>[77.72.169.164:3478] ToS:136
09:44:25 1901594mS Stun: STUNUdpClient RX: (this=0xf4f1b640) [77.72.169.164:3478]=>[192.168.100.220:63232] ToS:0
09:44:25 1901595mS Stun: STUNUdpClient RX: (this=0xf4f1b018) [77.72.169.164:3478]=>[192.168.100.220:63233] ToS:0
09:44:27 1903540mS Stun: StunClient: ResponseTimeout in Resolve RTP, attempt 2
09:44:27 1903570mS Stun: StunClient: ResponseTimeout in Resolve RTP, attempt 2
09:44:27 1903570mS Stun: STUNUdpClient TX: (this=0xf4f1b640) [192.168.100.220:63232]=>[77.72.169.164:3478] ToS:136
09:44:27 1903571mS Stun: StunClient: ResponseTimeout in Resolve RTP, attempt 2
09:44:27 1903571mS Stun: STUNUdpClient TX: (this=0xf4f1b018) [192.168.100.220:63233]=>[77.72.169.164:3478] ToS:136
09:44:27 1903594mS Stun: STUNUdpClient RX: (this=0xf4f1b640) [77.72.169.164:3478]=>[192.168.100.220:63232] ToS:0
09:44:27 1903596mS Stun: STUNUdpClient RX: (this=0xf4f1b018) [77.72.169.164:3478]=>[192.168.100.220:63233] ToS:0
09:44:31 1907540mS Stun: StunClient: ResponseTimeout in Resolve RTP, attempt 3
09:44:31 1907570mS Stun: StunClient: ResponseTimeout in Resolve RTP, attempt 3
09:44:31 1907570mS Stun: STUNUdpClient TX: (this=0xf4f1b640) [192.168.100.220:63232]=>[77.72.169.164:3478] ToS:136
09:44:31 1907571mS Stun: StunClient: ResponseTimeout in Resolve RTP, attempt 3
09:44:31 1907571mS Stun: STUNUdpClient TX: (this=0xf4f1b018) [192.168.100.220:63233]=>[77.72.169.164:3478] ToS:136
09:44:31 1907596mS Stun: STUNUdpClient RX: (this=0xf4f1b640) [77.72.169.164:3478]=>[192.168.100.220:63232] ToS:0
09:44:31 1907596mS Stun: STUNUdpClient RX: (this=0xf4f1b018) [77.72.169.164:3478]=>[192.168.100.220:63233] ToS:0
09:44:39 1915540mS Stun: StunClient: ResponseTimeout in Resolve RTP, attempt 4
09:44:39 1915570mS Stun: StunClient: ResponseTimeout in Resolve RTP, attempt 4
09:44:39 1915570mS Stun: STUNUdpClient TX: (this=0xf4f1b640) [192.168.100.220:63232]=>[77.72.169.164:3478] ToS:136
09:44:39 1915571mS Stun: StunClient: ResponseTimeout in Resolve RTP, attempt 4
09:44:39 1915571mS Stun: STUNUdpClient TX: (this=0xf4f1b018) [192.168.100.220:63233]=>[77.72.169.164:3478] ToS:136
09:44:39 1915593mS Stun: STUNUdpClient RX: (this=0xf4f1b640) [77.72.169.164:3478]=>[192.168.100.220:63232] ToS:0
09:44:39 1915594mS Stun: STUNUdpClient RX: (this=0xf4f1b018) [77.72.169.164:3478]=>[192.168.100.220:63233] ToS:0

********** SysMonitor v9.0.3.0 build 941 [connected to 192.168.100.220 (Bruizer)] **********
09:44:51 1927692mS PRN: Monitor Status IP 500 V2 9.0.3.0 build 941
09:44:51 1927692mS PRN: LAW=A PRI=0, BRI=0, ALOG=0, VCOMP=32, MDM=0, WAN=0, MODU=0 LANM=0 CkSRC=0 VMAIL=1(VER=3 TYP=1) 1-X=1 CALLS=1(TOT=10)
09:44:54 1930045mS H323Evt: Recv: RegistrationRequest 192.168.100.221; Endpoints registered: 1; Endpoints in registration: 0
09:44:55 1931540mS Stun: StunClient: ResponseTimeout in Resolve RTP, attempt 5
09:44:55 1931570mS Stun: StunClient: ResponseTimeout in Resolve RTP, attempt 5
09:44:55 1931570mS Stun: STUNUdpClient TX: (this=0xf4f1b640) [192.168.100.220:63232]=>[77.72.169.164:3478] ToS:136
09:44:55 1931571mS Stun: StunClient: ResponseTimeout in Resolve RTP, attempt 5
09:44:55 1931571mS Stun: STUNUdpClient TX: (this=0xf4f1b018) [192.168.100.220:63233]=>[77.72.169.164:3478] ToS:136
09:44:55 1931593mS Stun: STUNUdpClient RX: (this=0xf4f1b640) [77.72.169.164:3478]=>[192.168.100.220:63232] ToS:0
09:44:55 1931595mS Stun: STUNUdpClient RX: (this=0xf4f1b018) [77.72.169.164:3478]=>[192.168.100.220:63233] ToS:0
09:45:04 1940125mS SIP Rx: UDP 93.95.124.20:5060 -> 192.168.100.220:5060
CANCEL sip:01394593107@78.141.43.1:51908 SIP/2.0
Via: SIP/2.0/UDP 93.95.124.20:5060;branch=z9hG4bK6e3eb79c;rport
Max-Forwards: 70
From: "01206790060" <sip:01206790060@93.95.124.20>;tag=as117fe475
To: <sip:01394593107@78.141.43.1:51908>
Call-ID: 5fa96f661643c77d7c03e9510e885fc4@93.95.124.20:5060
CSeq: 102 CANCEL
User-Agent: Voiceflex
Content-Length: 0

09:45:04 1940128mS SIP Tx: UDP 192.168.100.220:5060 -> 93.95.124.20:5060
SIP/2.0 200 OK
Via: SIP/2.0/UDP 93.95.124.20:5060;branch=z9hG4bK6e3eb79c;rport
From: "01206790060" <sip:01206790060@93.95.124.20>;tag=as117fe475
Call-ID: 5fa96f661643c77d7c03e9510e885fc4@93.95.124.20:5060
CSeq: 102 CANCEL
Supported: timer
Server: IP Office 9.0.3.0 build 941
To: <sip:01394593107@78.141.43.1:51908>;tag=7dc0cb54cf3aadad
Content-Length: 0

09:45:04 1940129mS SIP Tx: UDP 192.168.100.220:5060 -> 93.95.124.20:5060
SIP/2.0 487 Request Terminated
Via: SIP/2.0/UDP 93.95.124.20:5060;branch=z9hG4bK6e3eb79c;rport
From: "01206790060" <sip:01206790060@93.95.124.20>;tag=as117fe475
Call-ID: 5fa96f661643c77d7c03e9510e885fc4@93.95.124.20:5060
CSeq: 102 INVITE
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,REFER,NOTIFY,UPDATE
Supported: timer
Server: IP Office 9.0.3.0 build 941
To: <sip:01394593107@78.141.43.1:51908>;tag=7dc0cb54cf3aadad
Content-Length: 0

09:45:04 1940130mS CMLineRx: v=0
CMReleaseComp
Line: type=SIPLine 17 Call: lid=17 id=1051 in=1
Called[] Type=Default (100) Reason=CMDRdirect Calling[01206790060@93.95.124.20] Type=Unknown Plan=Default
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=01206790060@93.95.124.20
IE CMIEDeviceDetail (231) LOCALE=eng HW=15 VER=9 class=CMDeviceSIPTrunk type=0 number=17 channel=1 features=0x20 rx_gain=32 tx_gain=32 ep_callid=1051 ipaddr=192.168.100.220 apps=0 loc=999 em_loc=999 features2=0x0
Cause=16, Normal call clearing
09:45:04 1940130mS CMCallEvt: 17.1051.1 10 SIPTrunk Endpoint: StateChange: END=A CMCSOGConnReq->CMCSCompleted
09:45:04 1940133mS CMLOGGING: CALL:2014/07/1009:44,00:00:00,000,01206790060@93.95.124.20,I,NightService,201,,,,0,,""n/a,0
09:45:04 1940133mS CD: CALL: 17.1051.1 BState=Connected Cut=2 Music=0.0 Aend="LNightService(NightService)" (0.0) Bend="NightService(NightService)" [VoiceMail] (21.11) CalledNum=NightService () CallingNum=01206790060@93.95.124.20 () Internal=0 Time=40616 AState=Idle
09:45:04 1940133mS CD: CALL: 17.1051.1 Deleted
09:45:04 1940134mS CMCallEvt: 17.1051.1 -1 SIPTrunk Endpoint: StateChange: END=X CMCSCompleted->CMCSDelete
09:45:04 1940134mS CMExtnEvt: RAS: CALL LOST (CMCauseNormal)
09:45:04 1940134mS CMCallEvt: 0.1053.0 -1 RAS.0: StateChange: END=X CMCSConnReq->CMCSCompleted
09:45:04 1940134mS CMExtnEvt: v=1012 State, new=PortRecoverDelay old=Connected,0,0,RAS
09:45:04 1940135mS CMExtnTx: v=RAS, p1=0
CMReleaseComp
Line: type=RAS 1 Call: lid=0 id=1012 in=0
Called[NightService] Type=Voicemail (102) Reason=CMDRX_Attendant Calling[01206790060@93.95.124.20] Type=Unknown Plan=Default
BChan: slot=21 chan=11
Cause=16, Normal call clearing
09:45:04 1940135mS CMMap: a=21.11 b=0.0 T0
09:45:04 1940137mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 1053->0 )
09:45:04 1940137mS CMCallEvt: 0.1053.0 -1 RAS.-1: StateChange: END=X CMCSCompleted->CMCSDelete
09:45:04 1940137mS CMExtnEvt: v=1012 State, new=Idle old=PortRecoverDelay,0,0,RAS
09:45:04 1940137mS 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
09:45:04 1940137mS CMCallEvt: 0.1053.0 -1 BaseEP: DELETE CMEndpoint f4f1df40 TOTAL NOW=1 CALL_LIST=0
09:45:04 1940137mS CMCallEvt: END CALL:10 (f5668c08)
09:45:04 1940138mS Stun: EPNatMediaHelper f4f1cc98 destroyed
09:45:04 1940138mS Stun: ~STUNClient 192.168.100.220:49152
09:45:04 1940138mS Stun: ~STUNClient 192.168.100.220:49153
09:45:04 1940139mS Stun: ~STUNClient 192.168.100.220:49152
09:45:04 1940139mS Stun: ~STUNClient 192.168.100.220:49153
09:45:04 1940139mS Stun: ~STUNClient 78.141.43.1:63232
09:45:04 1940139mS Stun: ~STUNClient 78.141.43.1:63233
09:45:04 1940141mS CMTARGET: 17.1051.1 -1 BaseEP: ~CMTargetHandler f4f226d0 ep f4f3c4b0
09:45:04 1940141mS CMCallEvt: 17.1051.1 -1 BaseEP: DELETE CMEndpoint f4f3c4b0 TOTAL NOW=0 CALL_LIST=0
09:45:04 1940142mS SIP Rx: UDP 93.95.124.20:5060 -> 192.168.100.220:5060
ACK sip:01394593107@78.141.43.1:51908 SIP/2.0
Via: SIP/2.0/UDP 93.95.124.20:5060;branch=z9hG4bK6e3eb79c;rport
Max-Forwards: 70
From: "01206790060" <sip:01206790060@93.95.124.20>;tag=as117fe475
To: <sip:01394593107@78.141.43.1:51908>;tag=7dc0cb54cf3aadad
Contact: <sip:01206790060@93.95.124.20:5060>
Call-ID: 5fa96f661643c77d7c03e9510e885fc4@93.95.124.20:5060
CSeq: 102 ACK
User-Agent: Voiceflex
Content-Length: 0

09:45:04 1940144mS CMMap: PCG::UnmapBChan pcp[45]b1r0 cp_b f568f140 other_cp_b f569cd20
09:45:04 1940144mS CMMap: a=4.1 b=21.11 M02
09:45:04 1940145mS CMMap: PlatformConnectionAudioSAP:isconnectVoice rtpRelay ID: 1 Close success
09:45:04 1940145mS H323Evt: SetOperational 10 192.168.100.220:49152 to 0
09:45:04 1940145mS CMMap: PCG::UnmapBChan pcp[101]b0r1 cp_b 0 other_cp_b 0
09:45:04 1940146mS H323Evt: RTP(END): 192.168.100.220/49152 93.95.124.20/20704 CODEC=Alaw64K(4) PKTSZ=160 RFC2833=on AGE=40604 SENT=2022 RECV=8 RTdelay=0 jitter=0 loss=0 remotejitter=0 remoteloss=0
09:45:04 1940147mS CMMap: a=4.1 b=0.0 Mapper::FreeCodec freed CMRTVocoder resource busy 1, total 32
09:45:04 1940400mS RES: Thu 10/7/2014 09:45:04 FreeMem=55097776 54670968(4) CachedMem=426808 CMMsg=7(7) Buff=5200 1362 999 7460 5 Links=6694 BTree=14641 CPU=01.83% CPUStats=1/2/17140/29847/32539/0/0 MCR=0 MCW=0
09:45:04 1940400mS RES2: IP 500 V2 9.0.3.0 build 941 Tasks=49 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=67 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=1 TCP=20(TLS=4) TAPI=1 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
09:45:04 1940400mS RES4: XML MemObjs=61 PoolMem=2097152(1) FreeMem=2081872(1)

********** Warning: Logging to Screen Stopped **********

 

[tlpeter]

Try changing the name of the module.
Somehow these default names cannot be used (anymore)

BAZINGA!

I'm not insane, my mother had me tested!

 

[wjtcol]

Nailed it! Thanks Peter.

 

[tlpeter]

Thanks


BAZINGA!

I'm not insane, my mother had me tested!

 

[rocky0217]

I believe I read somewhere to avoid capital letters when creating your AA. VM Pro doesn't like them anymore.

 

[tlpeter]

That is not true as i do that all the time but names like autoattendant or nightservice should be avoided.


BAZINGA!

I'm not insane, my mother had me tested!