IP Office 500v2 SIP Trunk Issue

[tmcd123]

IP Office with 8.1.79. VM Pro with 8.1.9402. We just setup a SIP trunk as a Backup to their PRI. SIP trunk works fine. All routing is setup identical to the PRI as far as how it routes day/night, etc. Calls ring through fine. If after hours, the call gets answer by an Auto Attendant, and we try to dial extensions from the transfer action, some extensions will ring through as normal. Some extensions will be auto answered by speakerphone as if the auto answer setting on the phone is on. We verified the setting is off on all phones. We verified the extensions work properly if we dial a number on the PRI and the VM answers. The call will ring at the extension. If we change the DID to route directly to the extension and not the AA, the calls will ring through as normal.

If I change the transfer from the AA, to be an Assisted Transfer that will work correctly. Only problem is I lose the ringing that they are used to.

Anyone have any insight as to what to check?

 

[tmcd123]

Is everyone else just as stumped as I am?

 

[amriddle01]

As usual, we can't help (other than guessing) without a trace of the issue happening

 

[tmcd123]

Cal to 609-000-0000 to x123 Fran. Answerd by VM:Main Night, dialed 123 and extension auto answered.


********** Rolled Over Log - filename = C:\Users\HSPVMPRO\Desktop\IP Office\Logs\Logs 20140117 150341 (2).txt **********
61264074mS PRN: Monitor Status IP 500 V2 8.1(79)
61264074mS PRN: LAW=U PRI=1, BRI=0, ALOG=8, VCOMP=20, MDM=0, WAN=0, MODU=1 LANM=0 CkSRC=1 VMAIL=1(VER=3 TYP=1) 1-X=0 CALLS=2(TOT=384)
61266302mS SIP Rx: UDP 208.x.x.111:5060 -> 192.168.10.10:5060
BYE sip:6090000000@192.168.10.10:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 208.x.x.111:5060;branch=z9hG4bKled1hr2070g1m5hje4a1cdggpjoj2.1
From: <sip:23.x.x.225>;tag=1013161841-1389989008202-
To: "HSPPa" <sip:6090000000@carrrier.net>;tag=d230eca47490013c
Call-ID: BW150328202170114-508408140@10.128.0.51
CSeq: 283543975 BYE
Max-Forwards: 69
Content-Length: 0

61266305mS SIP Tx: UDP 192.168.10.10:5060 -> 208.x.x.111:5060
SIP/2.0 200 OK
Via: SIP/2.0/UDP 208.x.x.111:5060;branch=z9hG4bKled1hr2070g1m5hje4a1cdggpjoj2.1
From: <sip:23.x.x.225>;tag=1013161841-1389989008202-
To: "HSPPa" <sip:6090000000@carrrier.net>;tag=d230eca47490013c
Call-ID: BW150328202170114-508408140@10.128.0.51
CSeq: 283543975 BYE
Supported: timer
Server: IP Office 8.1 (79)
Content-Length: 0

61266306mS CMLineRx: v=0
CMReleaseComp
Line: type=SIPLine 17 Call: lid=17 id=4555 in=1
IE CMIERespondingPartyNumber (230)(P:0 S:100 T:3 N:100 R:4) number=823.x.x.225
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceSIPTrunk type=0 number=17 channel=1 rx_gain=32 tx_gain=32 ep_callid=4555 ipaddr=10.0.0.121 apps=0
Cause=16, Normal call clearing
61266306mS CMCallEvt: 17.4555.1 384 SIPTrunk Endpoint: StateChange: END=A CMCSConnected->CMCSCompleted
61266309mS PRN: CDR - TCPSend maxqueuesize=3000 framecount=3000 operational=0
61266310mS CMLOGGING: CALL:2014/01/1715:03,00:00:13,000,,I,123,6090000000,,,,0,,""n/a,0
61266310mS CD: CALL: 17.4555.1 BState=Connected Cut=2 Music=0.0 Aend="Line 17" (0.0) Bend="Fran(123)" [Fran(123)] (8.16) CalledNum=123 (Fran) CallingNum= () Internal=0 Time=14000 AState=Idle
61266310mS CD: CALL: 17.4555.1 Deleted
61266311mS CMCallEvt: 17.4555.1 -1 SIPTrunk Endpoint: StateChange: END=X CMCSCompleted->CMCSDelete
61266312mS CMExtnEvt: Fran: CALL LOST (CMCauseNormal)
61266312mS CMExtnEvt: Fran: Extn(123) Calling Party Number() Type(CMNTypeNetworkSpecific)
61266312mS CMCallEvt: 0.4559.0 -1 Fran.0: StateChange: END=X CMCSConnected->CMCSCompleted
61266312mS CMExtnEvt: v=14 State, new=PortRecoverDelay old=Connected,0,0,Fran
61266313mS CMExtnTx: v=123, p1=0
CMReleaseComp
Line: type=DigitalExtn 6 Call: lid=0 id=4559 in=0
Called[123] Type=Default (100) Reason=CMDRdirect Calling[] Type=NetworkSpecific Plan=Default Pres=Allowed (0)
Cause=16, Normal call clearing
Timed: 17/01/14 15:03
61266313mS CMExtnEvt: Fran: CMExtnHandler::SetCurrent( id: 4559->0 )
61266314mS CMCallEvt: 0.4559.0 -1 Fran.-1: StateChange: END=X CMCSCompleted->CMCSDelete
61266314mS CMCallEvt: 0.4559.0 -1 BaseEP: DELETE CMEndpoint f507b0d0 TOTAL NOW=3 CALL_LIST=1
61266314mS CMCallEvt: END CALL:384 (f5022c30)
61266316mS CMCallEvt: 17.4555.1 -1 BaseEP: DELETE CMEndpoint f50b0ab0 TOTAL NOW=2 CALL_LIST=1
61266317mS CMMap: PCG::UnmapBChan pcp[127]b1r0 cp_b f57f5058 other_cp_b f58b71e8
61266317mS CMMap: a=4.18 b=8.16 M02
61266318mS CMMap: PlatformConnectionAudioSAP:isconnectVoice rtpRelay ID: 1 Close success
61266318mS H323Evt: SetOperational 11 192.168.10.10:49152 to 0
61266318mS CMMap: PCG::UnmapBChan pcp[157]b0r1 cp_b 0 other_cp_b 0
61266318mS H323Evt: RTP(END): 192.168.10.10/49152 208.x.x.111/31602 CODEC=G729A8K(6) PKTSZ=20 RFC2833=on AGE=13985 SENT=413 RECV=726 RTdelay=0 jitter=0 loss=0 remotejitter=0 remoteloss=0
61266319mS CMMap: a=4.18 b=0.0 Mapper::FreeCodec freed CMRTVocoder resource busy 1, total 20
61266503mS RES: Fri 17/1/2014 15:03:43 FreeMem=58875204 58196076(1) CachedMem=679128 CMMsg=6(8) Buff=5200 1402 999 12196 3 Links=4406 BTree=0 CPU=1/5/3400/10521/13199/2
61266503mS RES2: IP 500 V2 8.1(79) 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=16 TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
61266503mS RES4: XML MemObjs=45 PoolMem=2097152(1) FreeMem=2082064(1)
61268314mS CMExtnEvt: Fran: Recover Timer reason=CMTRWrapUp
61268314mS CMExtnEvt: v=14 State, new=Idle old=PortRecoverDelay,0,0,Fran
61268315mS CMExtnTx: v=123, p1=0
CMVoiceMailStatus
Line: type=DigitalExtn 6 Call: lid=0 id=-1 in=0
Called[Fran Msgs=1 Old=10 Sav=0] Type=Default (100) Reason=CMDRdirect Calling[00000001] Type=Default Plan=Default
Display [Fran Msgs=1]
Timed: 17/01/14 15:03

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

********** Warning: Logging to Screen Started **********
61271504mS RES: Fri 17/1/2014 15:03:47 FreeMem=58884652 58196076(1) CachedMem=688576 CMMsg=6(8) Buff=5200 1401 1000 12196 3 Links=4408 BTree=0 CPU=1/5/3400/10721/13199/1
61271505mS RES2: IP 500 V2 8.1(79) Tasks=49 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=64 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=1 TCP=16 TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
61271505mS RES4: XML MemObjs=45 PoolMem=2097152(1) FreeMem=2082064(1)
61276145mS CMExtnRxP: v=112
CMShortCode
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
ShortCode GetSystemInfo (99) = []
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
61276145mS CMExtnEvt: Tina Giacinto: ProcessShortCode feature=GetSystemInfo id=
61276145mS CMExtnTxP: v=112
CMShortCode
Line: type=DigitalExtn 6 Call: lid=0 id=-1 in=0
ShortCode GetSystemInfo (99) = [8.1 (79)] Calling[HSP ] Type=Default Plan=Default
Display [IP 500 V2]
Tag type=Text flags=0x1 [1,1,3,1,0,1,0.] [0x31 0x2c 0x31 0x2c 0x33 0x2c 0x31 0x2c 0x30 0x2c 0x31 0x2c 0x30 0x00 ]
Timed: 17/01/14 15:03
61283941mS CMCallEvt: 0.4561.0 -1 BaseEP: NEW CMEndpoint f50b0ab0 TOTAL NOW=3 CALL_LIST=1
61283942mS SIP Tx: UDP 192.168.10.10:5060 -> 208.x.x.111:5060
REGISTER sip:carrrier.net SIP/2.0
Via: SIP/2.0/UDP 192.168.10.10:5060;rport;branch=z9hG4bK1ab8b80da6786793958045a34a511886
From: <sip:6090000000@carrrier.net>;tag=0a3025b9e8a3f17f
To: <sip:6090000000@carrrier.net>
Call-ID: 7c35d23402035e13ba19f2e3277f131d
CSeq: 1911730169 REGISTER
Contact: "Unknown" <sip:6090000000@192.168.10.10:5060;transport=udp>
Expires: 3600
Authorization: Digest username="tds_tg-0001009594-1",realm="BroadWorks",nonce="BroadWorksXhqiv53gyT3245xaBW",response="794398e27ef8eb597f7871fbef721556",uri="sip:carrrier.net",algorithm=MD5,qop=auth,nc=000007fe,cnonce="629b3f37
0490c66f4ce
Max-Forwards: 70
User-Agent: IP Office 8.1 (79)
Supported: timer
Content-Length: 0

61283974mS SIP Rx: UDP 208.x.x.111:5060 -> 192.168.10.10:5060
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.10.10:5060;received=23.x.x.225;branch=z9hG4bK1ab8b80da6786793958045a34a511886;rport=5060
From: <sip:6090000000@carrrier.net>;tag=0a3025b9e8a3f17f
To: <sip:6090000000@carrrier.net>;tag=aprqokt3h73-b8bthj7eivbjf
Call-ID: 7c35d23402035e13ba19f2e3277f131d
CSeq: 1911730169 REGISTER
Contact: <sip:6090000000@192.168.10.10:5060;transport=udp>;expires=60

61283976mS CMCallEvt: 0.4561.0 -1 SIPTrunk Endpoint: StateChange: END=X CMCSIdle->CMCSDelete
61283977mS CMCallEvt: 0.4561.0 -1 BaseEP: DELETE CMEndpoint f50b0ab0 TOTAL NOW=2 CALL_LIST=1
61284012mS RES: Fri 17/1/2014 15:03:59 FreeMem=58871628 58196076(1) CachedMem=675552 CMMsg=6(8) Buff=5200 1401 999 12196 3 Links=4406 BTree=0 CPU=3/7/3400/10637/13199/1
61284012mS RES2: IP 500 V2 8.1(79) Tasks=49 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=65 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=1 TCP=16 TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
61284012mS RES4: XML MemObjs=45 PoolMem=2097152(1) FreeMem=2082064(1)
61289014mS RES: Fri 17/1/2014 15:04:05 FreeMem=58884652 58196076(1) CachedMem=688576 CMMsg=6(8) Buff=5200 1401 1000 12196 3 Links=4408 BTree=0 CPU=1/5/3400/10702/13199/1
61289014mS RES2: IP 500 V2 8.1(79) Tasks=49 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=64 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=1 TCP=16 TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
61289014mS RES4: XML MemObjs=45 PoolMem=2097152(1) FreeMem=2082064(1)
61303173mS CMExtnRxP: v=113
CMShortCode
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
ShortCode GetSystemInfo (99) = []
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
61303173mS CMExtnEvt: Kimberly Park: ProcessShortCode feature=GetSystemInfo id=
61303174mS CMExtnTxP: v=113
CMShortCode
Line: type=DigitalExtn 6 Call: lid=0 id=-1 in=0
ShortCode GetSystemInfo (99) = [8.1 (79)] Calling[HSP ] Type=Default Plan=Default
Display [IP 500 V2]
Tag type=Text flags=0x1 [1,1,3,1,0,1,0.] [0x31 0x2c 0x31 0x2c 0x33 0x2c 0x31 0x2c 0x30 0x2c 0x31 0x2c 0x30 0x00 ]
Timed: 17/01/14 15:04
61306845mS CMExtnRxP: v=115
CMShortCode
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
ShortCode GetSystemInfo (99) = []
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
61306845mS CMExtnEvt: Linda Fox: ProcessShortCode feature=GetSystemInfo id=
61306846mS CMExtnTxP: v=115
CMShortCode
Line: type=DigitalExtn 3 Call: lid=0 id=-1 in=0
ShortCode GetSystemInfo (99) = [8.1 (79)] Calling[HSP ] Type=Default Plan=Default
Display [IP 500 V2]
Tag type=Text flags=0x1 [1,1,3,1,0,1,0.] [0x31 0x2c 0x31 0x2c 0x33 0x2c 0x31 0x2c 0x30 0x2c 0x31 0x2c 0x30 0x00 ]
Timed: 17/01/14 15:04
61313977mS CMCallEvt: 0.4562.0 -1 BaseEP: NEW CMEndpoint f50b0ab0 TOTAL NOW=3 CALL_LIST=1
61313978mS SIP Tx: UDP 192.168.10.10:5060 -> 208.x.x.111:5060
REGISTER sip:carrrier.net SIP/2.0
Via: SIP/2.0/UDP 192.168.10.10:5060;rport;branch=z9hG4bKf6c3cd189636269f6047cf6d25dc7370
From: <sip:6090000000@carrrier.net>;tag=8414abb71c53451e
To: <sip:6090000000@carrrier.net>
Call-ID: 7c35d23402035e13ba19f2e3277f131d
CSeq: 1911730170 REGISTER
Contact: "Unknown" <sip:6090000000@192.168.10.10:5060;transport=udp>
Expires: 3600
Authorization: Digest username="tds_tg-0001009594-1",realm="BroadWorks",nonce="BroadWorksXhqiv53gyT3245xaBW",response="2d630b20cbb80b442ed3d8b872997ba1",uri="sip:carrrier.net",algorithm=MD5,qop=auth,nc=000007ff,cnonce="1bf4728c
030d46f47f5
Max-Forwards: 70
User-Agent: IP Office 8.1 (79)
Supported: timer
Content-Length: 0

61313999mS SIP Rx: UDP 208.x.x.111:5060 -> 192.168.10.10:5060
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.10.10:5060;received=23.x.x.225;branch=z9hG4bKf6c3cd189636269f6047cf6d25dc7370;rport=5060
From: <sip:6090000000@carrrier.net>;tag=8414abb71c53451e
To: <sip:6090000000@carrrier.net>;tag=1211861025-1389989069895
Call-ID: 7c35d23402035e13ba19f2e3277f131d
CSeq: 1911730170 REGISTER
Contact: <sip:6090000000@192.168.10.10:5060;transport=udp>;expires=60;q=0.5
Allow-Events: call-info,line-seize,dialog,message-summary,as-feature-event,x-broadworks-hoteling,x-broadworks-call-center-status
Content-Length: 0

61314001mS CMCallEvt: 0.4562.0 -1 SIPTrunk Endpoint: StateChange: END=X CMCSIdle->CMCSDelete
61314002mS CMCallEvt: 0.4562.0 -1 BaseEP: DELETE CMEndpoint f50b0ab0 TOTAL NOW=2 CALL_LIST=1
61314018mS RES: Fri 17/1/2014 15:04:29 FreeMem=58873564 58196076(1) CachedMem=677488 CMMsg=6(8) Buff=5200 1402 1000 12196 3 Links=4415 BTree=0 CPU=4/8/3400/10614/13199/1
61314019mS RES2: IP 500 V2 8.1(79) Tasks=49 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=63 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=1 TCP=15 TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
61314019mS RES4: XML MemObjs=45 PoolMem=2097152(1) FreeMem=2082064(1)
61317121mS CMExtnRxP: v=100
CMShortCode
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
ShortCode GetSystemInfo (99) = []
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
61317121mS CMExtnEvt: Doreen Barnard: ProcessShortCode feature=GetSystemInfo id=
61317122mS CMExtnTxP: v=100
CMShortCode
Line: type=DigitalExtn 3 Call: lid=0 id=-1 in=0
ShortCode GetSystemInfo (99) = [8.1 (79)] Calling[HSP ] Type=Default Plan=Default
Display [IP 500 V2]
Tag type=Text flags=0x1 [1,1,3,1,0,1,0.] [0x31 0x2c 0x31 0x2c 0x33 0x2c 0x31 0x2c 0x30 0x2c 0x31 0x2c 0x30 0x00 ]
Timed: 17/01/14 15:04

********** SysMonitor v10.1 (79) [connected to 10.0.0.121 (HSP )] **********
61317710mS PRN: Monitor Status IP 500 V2 8.1(79)
61317710mS PRN: LAW=U PRI=1, BRI=0, ALOG=8, VCOMP=20, MDM=0, WAN=0, MODU=1 LANM=0 CkSRC=1 VMAIL=1(VER=3 TYP=1) 1-X=0 CALLS=1(TOT=384)
61319020mS RES: Fri 17/1/2014 15:04:35 FreeMem=58886588 58196076(1) CachedMem=690512 CMMsg=6(8) Buff=5200 1402 1000 12196 3 Links=4409 BTree=0 CPU=1/5/3400/10686/13199/1
61319020mS RES2: IP 500 V2 8.1(79) Tasks=49 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=62 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=1 TCP=15 TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
61319020mS RES4: XML MemObjs=45 PoolMem=2097152(1) FreeMem=2082064(1)
61326414mS CMExtnEvt: Fran: No user activity
61338006mS CMExtnRxP: v=112
CMShortCode
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
ShortCode GetSystemInfo (99) = []
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
61338006mS CMExtnEvt: Tina Giacinto: ProcessShortCode feature=GetSystemInfo id=
61338007mS CMExtnTxP: v=112
CMShortCode
Line: type=DigitalExtn 6 Call: lid=0 id=-1 in=0
ShortCode GetSystemInfo (99) = [8.1 (79)] Calling[HSP ] Type=Default Plan=Default
Display [IP 500 V2]
Tag type=Text flags=0x1 [1,1,3,1,0,1,0.] [0x31 0x2c 0x31 0x2c 0x33 0x2c 0x31 0x2c 0x30 0x2c 0x31 0x2c 0x30 0x00 ]
Timed: 17/01/14 15:04
61340681mS SIP Rx: UDP 208.x.x.111:5060 -> 192.168.10.10:5060
INVITE sip:6090000000@192.168.10.10:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 208.x.x.111:5060;branch=z9hG4bK2jiuhd20c84gd2lh81o0.1
From: <sip:23.x.x.225>;tag=1945217187-1389989096572-
To: "HSPPa"<sip:6090000000@carrrier.net>
Call-ID: BW150456572170114-842863131@10.128.0.51
CSeq: 283588159 INVITE
Contact: <sip:208.x.x.111:5060;transport=udp>
Supported: 100rel
Allow: ACK,BYE,CANCEL,INFO,INVITE,OPTIONS,PRACK,REFER,NOTIFY,UPDATE
Accept: application/media_control+xml,application/sdp,multipart/mixed
Max-Forwards: 69
Content-Type: application/sdp
Content-Disposition: session;handling=required
Content-Length: 289

v=0
o=BroadWorks 439754102 1 IN IP4 208.x.x.111
s=-
c=IN IP4 208.x.x.111
t=0 0
m=audio 11882 RTP/AVP 18 0 8 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
a=maxptime:20
61340685mS CMCallEvt: 0.4563.0 -1 BaseEP: NEW CMEndpoint f50b0ab0 TOTAL NOW=3 CALL_LIST=1
61340687mS SIP Tx: UDP 192.168.10.10:5060 -> 208.x.x.111:5060
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 208.x.x.111:5060;branch=z9hG4bK2jiuhd20c84gd2lh81o0.1
From: <sip:23.x.x.225>;tag=1945217187-1389989096572-
To: "HSPPa" <sip:6090000000@carrrier.net>;tag=e9e98121c92a13c7
Call-ID: BW150456572170114-842863131@10.128.0.51
CSeq: 283588159 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Supported: timer
Server: IP Office 8.1 (79)
Content-Length: 0

61340690mS CMCallEvt: CREATE CALL:385 (f5022c30)
61340690mS CMCallEvt: 0.4564.0 -1 BaseEP: NEW CMEndpoint f5050c18 TOTAL NOW=4 CALL_LIST=1
61340693mS CMLineRx: v=0
CMSetup
Line: type=SIPLine 17 Call: lid=17 id=4563 in=1
Called[6090000000] Type=Default (100) Reason=CMDRdirect SndComp Calling[] Type=NetworkSpecific Plan=Default Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIEFastStartInfoData (6) 4 item(s)
IE CMIERespondingPartyNumber (230)(P:0 S:100 T:3 N:100 R:4) number=
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceSIPTrunk type=0 number=17 channel=1 rx_gain=32 tx_gain=32 ep_callid=4563 ipaddr=10.0.0.121 apps=0
61340693mS CD: CALL: 17.4563.1 BState=Idle Cut=1 Music=0.0 Aend="Line 17" (0.0) Bend="" [] (0.0) CalledNum=6090000000 () CallingNum= () Internal=0 Time=3 AState=Idle
61340694mS CMCallEvt: 17.4563.1 385 SIPTrunk Endpoint: StateChange: END=A CMCSIdle->CMCSDialInitiated
61340694mS CMTARGET: 17.4563.1 385 SIPTrunk Endpoint: LOOKUP CALL ROUTE: type=100 called_party=6090000000 sub= calling= dir=in complete=1 ses=0
61340694mS CMTARGET: 17.4563.1 385 SIPTrunk Endpoint: SET BESTMATCH: length 10 vs -1 match=6090000000 dest=VM:Main Night
61340694mS CMCallEvt: Priority hike: call 385 priority 0->1
61340694mS CMTARGET: 17.4563.1 385 SIPTrunk Endpoint: LOOKUP ICR: DDI=6090000000 CGPN= (Destination VM:Main Night ) => CDPN=VM:Main Night
61340694mS CMTARGET: 17.4563.1 385 SIPTrunk Endpoint: ADD TARGET (N): number=VM:Main Night type=100 depth=1 nobar=1 setorig=1 ses=0
61340695mS CMTARGET: 17.4563.1 385 SIPTrunk Endpoint: ADD VM TARGET
61340695mS CMTARGET: **** 17.4563.1 385 SIPTrunk Endpoint: MakeVoicemailTarget pbx=<null> local=1 type=1
61340695mS CMMap: a=4.18 b=0.0 Mapper::AllocateCodec allocated CMRTVocoder resource busy 1, total 20
61340695mS CMCallEvt: 0.4565.0 -1 BaseEP: NEW CMEndpoint f507b0d0 TOTAL NOW=5 CALL_LIST=2
61340696mS CMTARGET: 0.4565.0 385 RAS.0: ADD PRIMARY
61340696mS CMTARGET: 17.4563.1 385 SIPTrunk Endpoint: ADD VM TARGET: SUCCEEDED
61340696mS CMTARGET: 17.4563.1 385 SIPTrunk Endpoint: INITIAL TARGETING SUCCEEDED
61340696mS CMTARGET: 17.4563.1 385 SIPTrunk Endpoint: GetNoAnswerTimer:20
61340696mS CMCallEvt: 17.4563.1 385 SIPTrunk Endpoint: StateChange: END=A CMCSDialInitiated->CMCSDialled
61340698mS CMLineTx: v=0
CMProceeding
Line: type=SIPLine 17 Call: lid=17 id=4563 in=1
61340698mS CMCallEvt: 0.4564.0 385 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
61340698mS CMCallEvt: 0.4565.0 385 RAS.0: StateChange: END=T CMCSIdle->CMCSOffering
61340699mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 0->4565 )
61340699mS CMExtnTx: v=RAS, p1=0
CMSetup
Line: type=RAS 1 Call: lid=0 id=1011 in=0
Called[Main Night] Type=Voicemail (102) Reason=CMDRX_Attendant SndComp Calling[] Type=NetworkSpecific Plan=Default Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=21 chan=42
IE CMIERespondingPartyNumber (230)(P:0 S:100 T:3 N:100 R:4) number=
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceSIPTrunk type=0 number=17 channel=1 rx_gain=32 tx_gain=32 ep_callid=4563 ipaddr=10.0.0.121 apps=0
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEDIDNumber (245)(P:100 S:100 T:100 N:100 R:4) number=6090000000
IE CMIEMohSourceId (247) MOH Source = 1
Display [??>Main Night]
Timed: 17/01/14 15:04
Locale: enu
61340699mS CMMap: a=21.42 b=1.254 T
61340701mS CMExtnRx: v=RAS, p1=0
CMConnect
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Main Night
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:102 N:100 R:4) number=Main Night
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceVoicemail type=0 number=0 channel=42 rx_gain=32 tx_gain=32 ep_callid=4565 ipaddr=10.0.0.121 apps=0
61340701mS CMCallEvt: 0.4565.0 385 RAS.0: StateChange: END=T CMCSOffering->CMCSConnReq
61340701mS CMCallEvt: 0.4564.0 385 TargetingEP: RequestEnd 0.4565.0 385 RAS.0
61340701mS CMTARGET: 17.4563.1 385 SIPTrunk Endpoint: CancelTimer CMTCNoAnswerTimeout
61340702mS CMExtnEvt: v=1011 State, new=Connected old=Idle,0,0,RAS
61340702mS CMCallEvt: 17.4563.1 385 SIPTrunk Endpoint: StateChange: END=A CMCSDialled->CMCSOGConnReq
61340707mS CMLineTx: v=0
CMConnect
Line: type=SIPLine 17 Call: lid=17 id=4563 in=1
BChan: slot=21 chan=42
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
ConnectedName.Invoke.CodePageISO8859-1
invokeId 14817
user 'Main Night' presentation Allowed
IE CMIEFastStartInfoData (6) 2 item(s)
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Main Night
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:102 N:100 R:4) number=Main Night
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceVoicemail type=0 number=0 channel=42 rx_gain=32 tx_gain=32 ep_callid=4565 ipaddr=10.0.0.121 apps=0
61340709mS SIP Tx: UDP 192.168.10.10:5060 -> 208.x.x.111:5060
SIP/2.0 200 OK
Via: SIP/2.0/UDP 208.x.x.111:5060;branch=z9hG4bK2jiuhd20c84gd2lh81o0.1
From: <sip:23.x.x.225>;tag=1945217187-1389989096572-
To: "HSPPa" <sip:6090000000@carrrier.net>;tag=e9e98121c92a13c7
Call-ID: BW150456572170114-842863131@10.128.0.51
CSeq: 283588159 INVITE
Contact: "HSPPa" <sip:6090000000@192.168.10.10:5060;transport=udp>
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Supported: timer
Server: IP Office 8.1 (79)
Content-Type: application/sdp
Content-Length: 241

v=0
o=UserA 3986148406 1350027880 IN IP4 192.168.10.10
s=Session SDP
c=IN IP4 192.168.10.10
t=0 0
m=audio 49152 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
61340711mS CMCallEvt: 0.4564.0 -1 TargetingEP: StateChange: END=X CMCSOffering->CMCSDelete
61340711mS CMCallEvt: 0.4564.0 -1 BaseEP: DELETE CMEndpoint f5050c18 TOTAL NOW=4 CALL_LIST=2
61340713mS H323Evt: SetOperational 12 192.168.10.10:0 to 0
61340713mS H323Evt: SetRfc2833 (1): rx payload 101 tx payload 101
61340713mS CMMap: PCG::MapBChan pcp[159]b0r1 cp_b 0 other_cp_b 0 type CGTypeSimple
61340713mS CMMap: PCGS CPReserveCodec for the other end (pcp[159]b0r1) true
61340714mS CMMap: PCG::MapBChan pcp[32]b1r0 cp_b f58acc48 other_cp_b f58b71e8 type CGTypeSimple
61340714mS CMMap: a=4.18 b=21.42 M12
61340714mS CMMap: PlatformConnectionAudioSAP::ConnectVoice (connect) pcp[159]b0r1 echo_cancellation set to 0
61340714mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[159]b0r1 Configure 4.18
61340714mS CMMap: PlatformConnectionAudioSAP::ConnectVoice group 2 rtp_enc_codec 0 rtp_fwd_action 0X00000001 src_fwd_action 0X00000004
61340714mS CMMap: PlatformConnectionAudioSAP::ConnectVoice rtpRelay Open got rtpRelay ID: 1
61340714mS CMMap: PlatformConnectionAudioSAP::ConnectVoice rtpRelay Configure success
61340714mS CMMap: PlatformConnectionAudioSAP::ConnectVoice local IP addr 192.168.10.10:49152, Remote IP addr 208.x.x.111:11882
61340714mS CMMap: PlatformConnectionAudioSAP::ConnectVoice DSP IP addr 1.1.1.3:32768, telogyHost IP addr 1.1.1.1:10
61340715mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[159]b0r1 ConnectIndication 4.18
61340715mS H323Evt: SetOperational 12 192.168.10.10:49152 to 1
61340768mS SIP Rx: UDP 208.x.x.111:5060 -> 192.168.10.10:5060
ACK sip:6090000000@192.168.10.10:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 208.x.x.111:5060;branch=z9hG4bKpragg310d8r08u4sp1q1.1
From: <sip:23.x.x.225>;tag=1945217187-1389989096572-
To: "HSPPa" <sip:6090000000@carrrier.net>;tag=e9e98121c92a13c7
Call-ID: BW150456572170114-842863131@10.128.0.51
CSeq: 283588159 ACK
Contact: <sip:208.x.x.111:5060;transport=udp>
Max-Forwards: 69
Content-Length: 0

61340771mS CMLineRx: v=0
CMConnectAck
Line: type=SIPLine 17 Call: lid=17 id=4563 in=1
IE CMIERespondingPartyNumber (230)(P:0 S:100 T:3 N:100 R:4) number=823.x.x.225
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceSIPTrunk type=0 number=17 channel=1 rx_gain=32 tx_gain=32 ep_callid=4563 ipaddr=10.0.0.121 apps=0
61340771mS CMCallEvt: 17.4563.1 385 SIPTrunk Endpoint: StateChange: END=A CMCSOGConnReq->CMCSConnected
61340771mS CMTARGET: 17.4563.1 385 SIPTrunk Endpoint: ~CMTargetHandler f504e8d8 ep f50b0ab0
61340772mS CMCallEvt: 0.4565.0 385 RAS.0: StateChange: END=B CMCSConnReq->CMCSConnected
61340775mS CMExtnTx: v=RAS, p1=0
CMConnectAck
Line: type=RAS 1 Call: lid=0 id=1011 in=0
BChan: slot=21 chan=42
IE CMIERespondingPartyNumber (230)(P:0 S:100 T:3 N:100 R:4) number=823.x.x.225
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceSIPTrunk type=0 number=17 channel=1 rx_gain=32 tx_gain=32 ep_callid=4563 ipaddr=10.0.0.121 apps=0
61340777mS CMMap: a=21.42 b=0.0 PCGS CPReserveCodec (pcp[159]b0r1) true
61340777mS CMMap: a=21.42 b=0.0 PCGS CPReserveCodec (pcp[159]b0r1) true
61340777mS CMMap: PCG::MapBChan pcp[159]b0r1 cp_b f58b71e8 other_cp_b f58acc48 type CGTypeSimple
61340777mS CMMap: a=21.42 b=4.18 M12
61341025mS RES: Fri 17/1/2014 15:04:56 FreeMem=58812460 58196076(1) CachedMem=616384 CMMsg=6(8) Buff=5200 1402 999 12196 3 Links=4332 BTree=0 CPU=32/36/3400/9925/13199/1
61341025mS RES2: IP 500 V2 8.1(79) Tasks=50 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=66 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=1 TCP=15 TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
61341025mS RES4: XML MemObjs=45 PoolMem=2097152(1) FreeMem=2082064(1)
61341752mS H323Evt: RTP(50r): 192.168.10.10/49152 208.x.x.111/11882 CODEC=G729A8K(6) PKTSZ=20 RFC2833=on AGE=1039 SENT=1 (avg size=2) RECV=50 (avg size=20)
61342376mS SIP Rx: UDP 208.x.x.111:5060 -> 192.168.10.10:5060
BYE sip:6090000000@192.168.10.10:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 208.x.x.111:5060;branch=z9hG4bKpragg310d8r08u4sp1q1cdgopd301.1
From: <sip:23.x.x.225>;tag=1945217187-1389989096572-
To: "HSPPa" <sip:6090000000@carrrier.net>;tag=e9e98121c92a13c7
Call-ID: BW150456572170114-842863131@10.128.0.51
CSeq: 283588160 BYE
Max-Forwards: 69
Content-Length: 0

61342379mS SIP Tx: UDP 192.168.10.10:5060 -> 208.x.x.111:5060
SIP/2.0 200 OK
Via: SIP/2.0/UDP 208.x.x.111:5060;branch=z9hG4bKpragg310d8r08u4sp1q1cdgopd301.1
From: <sip:23.x.x.225>;tag=1945217187-1389989096572-
To: "HSPPa" <sip:6090000000@carrrier.net>;tag=e9e98121c92a13c7
Call-ID: BW150456572170114-842863131@10.128.0.51
CSeq: 283588160 BYE
Supported: timer
Server: IP Office 8.1 (79)
Content-Length: 0

61342380mS CMLineRx: v=0
CMReleaseComp
Line: type=SIPLine 17 Call: lid=17 id=4563 in=1
IE CMIERespondingPartyNumber (230)(P:0 S:100 T:3 N:100 R:4) number=823.x.x.225
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceSIPTrunk type=0 number=17 channel=1 rx_gain=32 tx_gain=32 ep_callid=4563 ipaddr=10.0.0.121 apps=0
Cause=16, Normal call clearing
61342380mS CMCallEvt: 17.4563.1 385 SIPTrunk Endpoint: StateChange: END=A CMCSConnected->CMCSCompleted
61342382mS PRN: CDR - ResetQueueSize=3000
61342383mS PRN: CDR - TCPSend maxqueuesize=3000 framecount=3000 operational=0
61342383mS ERR: CDRServer discarding - framecount=3000
61342385mS CMLOGGING: CALL:2014/01/1715:04,00:00:01,000,,I,MainNight,6090000000,,,,0,,""n/a,0
61342385mS CD: CALL: 17.4563.1 BState=Connected Cut=2 Music=0.0 Aend="Line 17" (0.0) Bend="Main Night(Main Night)" [VoiceMail] (21.42) CalledNum=Main Night () CallingNum= () Internal=0 Time=1695 AState=Idle
61342385mS CD: CALL: 17.4563.1 Deleted
61342385mS CMCallEvt: 17.4563.1 -1 SIPTrunk Endpoint: StateChange: END=X CMCSCompleted->CMCSDelete
61342386mS CMExtnEvt: RAS: CALL LOST (CMCauseNormal)
61342386mS CMCallEvt: 0.4565.0 -1 RAS.0: StateChange: END=X CMCSConnected->CMCSCompleted
61342386mS CMExtnEvt: v=1011 State, new=PortRecoverDelay old=Connected,0,0,RAS
61342387mS CMExtnTx: v=RAS, p1=0
CMReleaseComp
Line: type=RAS 1 Call: lid=0 id=1011 in=0
Called[Main Night] Type=Voicemail (102) Reason=CMDRX_Attendant Calling[] Type=NetworkSpecific Plan=Default Pres=Allowed (0)
BChan: slot=21 chan=42
Cause=16, Normal call clearing
61342387mS CMMap: a=21.42 b=0.0 T0
61342388mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 4565->0 )
61342388mS CMCallEvt: 0.4565.0 -1 RAS.-1: StateChange: END=X CMCSCompleted->CMCSDelete
61342389mS CMExtnEvt: v=1011 State, new=Idle old=PortRecoverDelay,0,0,RAS
61342389mS CMExtnTx: v=RAS, p1=0
CMReleaseComp
Line: type=RAS 1 Call: lid=0 id=1011 in=0
BChan: slot=21 chan=42
Cause=16, Normal call clearing
61342389mS CMCallEvt: 0.4565.0 -1 BaseEP: DELETE CMEndpoint f507b0d0 TOTAL NOW=3 CALL_LIST=1
61342389mS CMCallEvt: END CALL:385 (f5022c30)
61342391mS CMCallEvt: 17.4563.1 -1 BaseEP: DELETE CMEndpoint f50b0ab0 TOTAL NOW=2 CALL_LIST=1
61342392mS CMMap: PCG::UnmapBChan pcp[32]b1r0 cp_b f58acc48 other_cp_b f58b71e8
61342392mS CMMap: a=4.18 b=21.42 M02
61342393mS CMMap: PlatformConnectionAudioSAP:isconnectVoice rtpRelay ID: 1 Close success
61342393mS H323Evt: SetOperational 12 192.168.10.10:49152 to 0
61342393mS CMMap: PCG::UnmapBChan pcp[159]b0r1 cp_b 0 other_cp_b 0
61342393mS H323Evt: RTP(END): 192.168.10.10/49152 208.x.x.111/11882 CODEC=G729A8K(6) PKTSZ=20 RFC2833=on AGE=1680 SENT=28 RECV=80 RTdelay=0 jitter=0 loss=0 remotejitter=0 remoteloss=0
61342395mS CMMap: a=4.18 b=0.0 Mapper::FreeCodec freed CMRTVocoder resource busy 1, total 20
61344002mS CMCallEvt: 0.4566.0 -1 BaseEP: NEW CMEndpoint f50b0ab0 TOTAL NOW=3 CALL_LIST=1
61344003mS SIP Tx: UDP 192.168.10.10:5060 -> 208.x.x.111:5060
REGISTER sip:carrrier.net SIP/2.0
Via: SIP/2.0/UDP 192.168.10.10:5060;rport;branch=z9hG4bK6dc4857d16995ce1fee51e8f87d76c61
From: <sip:6090000000@carrrier.net>;tag=0f7b254e651f300a
To: <sip:6090000000@carrrier.net>
Call-ID: 7c35d23402035e13ba19f2e3277f131d
CSeq: 1911730171 REGISTER
Contact: "Unknown" <sip:6090000000@192.168.10.10:5060;transport=udp>
Expires: 3600
Authorization: Digest username="tds_tg-0001009594-1",realm="BroadWorks",nonce="BroadWorksXhqiv53gyT3245xaBW",response="c6b57c996356b9b522678a9c659b5de2",uri="sip:carrrier.net",algorithm=MD5,qop=auth,nc=00000800,cnonce="3720b441
f570d52ad69
Max-Forwards: 70
User-Agent: IP Office 8.1 (79)
Supported: timer
Content-Length: 0

61344021mS SIP Rx: UDP 208.x.x.111:5060 -> 192.168.10.10:5060
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.10.10:5060;received=23.x.x.225;branch=z9hG4bK6dc4857d16995ce1fee51e8f87d76c61;rport=5060
From: <sip:6090000000@carrrier.net>;tag=0f7b254e651f300a
To: <sip:6090000000@carrrier.net>;tag=aprqokt3h73-adbpbp5eivbnf
Call-ID: 7c35d23402035e13ba19f2e3277f131d
CSeq: 1911730171 REGISTER
Contact: <sip:6090000000@192.168.10.10:5060;transport=udp>;expires=60

61344023mS CMCallEvt: 0.4566.0 -1 SIPTrunk Endpoint: StateChange: END=X CMCSIdle->CMCSDelete
61344024mS CMCallEvt: 0.4566.0 -1 BaseEP: DELETE CMEndpoint f50b0ab0 TOTAL NOW=2 CALL_LIST=1
61346026mS RES: Fri 17/1/2014 15:05:01 FreeMem=58862180 58196076(1) CachedMem=666104 CMMsg=6(8) Buff=5200 1402 1000 12196 3 Links=4404 BTree=0 CPU=1/5/3400/10606/13199/1
61346026mS RES2: IP 500 V2 8.1(79) Tasks=49 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=66 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=1 TCP=16 TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
61346027mS RES4: XML MemObjs=45 PoolMem=2097152(1) FreeMem=2082064(1)

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

********** Warning: Logging to Screen Started **********
61356202mS SIP Rx: UDP 208.x.x.111:5060 -> 192.168.10.10:5060
INVITE sip:6090000000@192.168.10.10:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 208.x.x.111:5060;branch=z9hG4bKbtttvn304ghha41vb1a1.1
From: <sip:23.x.x.225>;tag=158348979-1389989112094-
To: "HSPPa"<sip:6090000000@carrrier.net>
Call-ID: BW150512094170114165996972@10.128.0.51
CSeq: 283595920 INVITE
Contact: <sip:208.x.x.111:5060;transport=udp>
Supported: 100rel
Allow: ACK,BYE,CANCEL,INFO,INVITE,OPTIONS,PRACK,REFER,NOTIFY,UPDATE
Accept: application/media_control+xml,application/sdp,multipart/mixed
Max-Forwards: 69
Content-Type: application/sdp
Content-Disposition: session;handling=required
Content-Length: 289

v=0
o=BroadWorks 439756811 1 IN IP4 208.x.x.111
s=-
c=IN IP4 208.x.x.111
t=0 0
m=audio 33802 RTP/AVP 18 0 8 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
a=maxptime:20
61356205mS CMCallEvt: 0.4567.0 -1 BaseEP: NEW CMEndpoint f50b0ab0 TOTAL NOW=3 CALL_LIST=1
61356208mS SIP Tx: UDP 192.168.10.10:5060 -> 208.x.x.111:5060
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 208.x.x.111:5060;branch=z9hG4bKbtttvn304ghha41vb1a1.1
From: <sip:23.x.x.225>;tag=158348979-1389989112094-
To: "HSPPa" <sip:6090000000@carrrier.net>;tag=28e8c86d31165264
Call-ID: BW150512094170114165996972@10.128.0.51
CSeq: 283595920 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Supported: timer
Server: IP Office 8.1 (79)
Content-Length: 0

61356211mS CMCallEvt: CREATE CALL:386 (f5022c30)
61356211mS CMCallEvt: 0.4568.0 -1 BaseEP: NEW CMEndpoint f5050c18 TOTAL NOW=4 CALL_LIST=1
61356214mS CMLineRx: v=0
CMSetup
Line: type=SIPLine 17 Call: lid=17 id=4567 in=1
Called[6090000000] Type=Default (100) Reason=CMDRdirect SndComp Calling[] Type=NetworkSpecific Plan=Default Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIEFastStartInfoData (6) 4 item(s)
IE CMIERespondingPartyNumber (230)(P:0 S:100 T:3 N:100 R:4) number=
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceSIPTrunk type=0 number=17 channel=1 rx_gain=32 tx_gain=32 ep_callid=4567 ipaddr=10.0.0.121 apps=0
61356214mS CD: CALL: 17.4567.1 BState=Idle Cut=1 Music=0.0 Aend="Line 17" (0.0) Bend="" [] (0.0) CalledNum=6090000000 () CallingNum= () Internal=0 Time=3 AState=Idle
61356214mS CMCallEvt: 17.4567.1 386 SIPTrunk Endpoint: StateChange: END=A CMCSIdle->CMCSDialInitiated
61356215mS CMTARGET: 17.4567.1 386 SIPTrunk Endpoint: LOOKUP CALL ROUTE: type=100 called_party=6090000000 sub= calling= dir=in complete=1 ses=0
61356215mS CMTARGET: 17.4567.1 386 SIPTrunk Endpoint: SET BESTMATCH: length 10 vs -1 match=6090000000 dest=VM:Main Night
61356215mS CMCallEvt: Priority hike: call 386 priority 0->1
61356215mS CMTARGET: 17.4567.1 386 SIPTrunk Endpoint: LOOKUP ICR: DDI=6090000000 CGPN= (Destination VM:Main Night ) => CDPN=VM:Main Night
61356215mS CMTARGET: 17.4567.1 386 SIPTrunk Endpoint: ADD TARGET (N): number=VM:Main Night type=100 depth=1 nobar=1 setorig=1 ses=0
61356215mS CMTARGET: 17.4567.1 386 SIPTrunk Endpoint: ADD VM TARGET
61356216mS CMTARGET: **** 17.4567.1 386 SIPTrunk Endpoint: MakeVoicemailTarget pbx=<null> local=1 type=1
61356216mS CMMap: a=4.18 b=0.0 Mapper::AllocateCodec allocated CMRTVocoder resource busy 1, total 20
61356216mS CMCallEvt: 0.4569.0 -1 BaseEP: NEW CMEndpoint f507b0d0 TOTAL NOW=5 CALL_LIST=2
61356216mS CMTARGET: 0.4569.0 386 RAS.0: ADD PRIMARY
61356217mS CMTARGET: 17.4567.1 386 SIPTrunk Endpoint: ADD VM TARGET: SUCCEEDED
61356217mS CMTARGET: 17.4567.1 386 SIPTrunk Endpoint: INITIAL TARGETING SUCCEEDED
61356217mS CMTARGET: 17.4567.1 386 SIPTrunk Endpoint: GetNoAnswerTimer:20
61356217mS CMCallEvt: 17.4567.1 386 SIPTrunk Endpoint: StateChange: END=A CMCSDialInitiated->CMCSDialled
61356218mS CMLineTx: v=0
CMProceeding
Line: type=SIPLine 17 Call: lid=17 id=4567 in=1
61356219mS CMCallEvt: 0.4568.0 386 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
61356219mS CMCallEvt: 0.4569.0 386 RAS.0: StateChange: END=T CMCSIdle->CMCSOffering
61356219mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 0->4569 )
61356220mS CMExtnTx: v=RAS, p1=0
CMSetup
Line: type=RAS 1 Call: lid=0 id=1011 in=0
Called[Main Night] Type=Voicemail (102) Reason=CMDRX_Attendant SndComp Calling[] Type=NetworkSpecific Plan=Default Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
BChan: slot=21 chan=42
IE CMIERespondingPartyNumber (230)(P:0 S:100 T:3 N:100 R:4) number=
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceSIPTrunk type=0 number=17 channel=1 rx_gain=32 tx_gain=32 ep_callid=4567 ipaddr=10.0.0.121 apps=0
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEDIDNumber (245)(P:100 S:100 T:100 N:100 R:4) number=6090000000
IE CMIEMohSourceId (247) MOH Source = 1
Display [??>Main Night]
Timed: 17/01/14 15:05
Locale: enu
61356220mS CMMap: a=21.42 b=1.254 T
61356221mS CMExtnRx: v=RAS, p1=0
CMConnect
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Main Night
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:102 N:100 R:4) number=Main Night
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceVoicemail type=0 number=0 channel=42 rx_gain=32 tx_gain=32 ep_callid=4569 ipaddr=10.0.0.121 apps=0
61356222mS CMCallEvt: 0.4569.0 386 RAS.0: StateChange: END=T CMCSOffering->CMCSConnReq
61356222mS CMCallEvt: 0.4568.0 386 TargetingEP: RequestEnd 0.4569.0 386 RAS.0
61356222mS CMTARGET: 17.4567.1 386 SIPTrunk Endpoint: CancelTimer CMTCNoAnswerTimeout
61356223mS CMExtnEvt: v=1011 State, new=Connected old=Idle,0,0,RAS
61356223mS CMCallEvt: 17.4567.1 386 SIPTrunk Endpoint: StateChange: END=A CMCSDialled->CMCSOGConnReq
61356228mS CMLineTx: v=0
CMConnect
Line: type=SIPLine 17 Call: lid=17 id=4567 in=1
BChan: slot=21 chan=42
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
ConnectedName.Invoke.CodePageISO8859-1
invokeId 14818
user 'Main Night' presentation Allowed
IE CMIEFastStartInfoData (6) 2 item(s)
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Main Night
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:102 N:100 R:4) number=Main Night
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceVoicemail type=0 number=0 channel=42 rx_gain=32 tx_gain=32 ep_callid=4569 ipaddr=10.0.0.121 apps=0
61356230mS SIP Tx: UDP 192.168.10.10:5060 -> 208.x.x.111:5060
SIP/2.0 200 OK
Via: SIP/2.0/UDP 208.x.x.111:5060;branch=z9hG4bKbtttvn304ghha41vb1a1.1
From: <sip:23.x.x.225>;tag=158348979-1389989112094-
To: "HSPPa" <sip:6090000000@carrrier.net>;tag=28e8c86d31165264
Call-ID: BW150512094170114165996972@10.128.0.51
CSeq: 283595920 INVITE
Contact: "HSPPa" <sip:6090000000@192.168.10.10:5060;transport=udp>
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Supported: timer
Server: IP Office 8.1 (79)
Content-Type: application/sdp
Content-Length: 240

v=0
o=UserA 537680120 1837884188 IN IP4 192.168.10.10
s=Session SDP
c=IN IP4 192.168.10.10
t=0 0
m=audio 49152 RTP/AVP 18 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
61356231mS CMCallEvt: 0.4568.0 -1 TargetingEP: StateChange: END=X CMCSOffering->CMCSDelete
61356232mS CMCallEvt: 0.4568.0 -1 BaseEP: DELETE CMEndpoint f5050c18 TOTAL NOW=4 CALL_LIST=2
61356234mS H323Evt: SetOperational 13 192.168.10.10:0 to 0
61356234mS H323Evt: SetRfc2833 (1): rx payload 101 tx payload 101
61356234mS CMMap: PCG::MapBChan pcp[160]b0r1 cp_b 0 other_cp_b 0 type CGTypeSimple
61356234mS CMMap: PCGS CPReserveCodec for the other end (pcp[160]b0r1) true
61356234mS CMMap: PCG::MapBChan pcp[32]b1r0 cp_b f58acc48 other_cp_b f58b71e8 type CGTypeSimple
61356234mS CMMap: a=4.18 b=21.42 M12
61356235mS CMMap: PlatformConnectionAudioSAP::ConnectVoice (connect) pcp[160]b0r1 echo_cancellation set to 0
61356235mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[160]b0r1 Configure 4.18
61356235mS CMMap: PlatformConnectionAudioSAP::ConnectVoice group 2 rtp_enc_codec 0 rtp_fwd_action 0X00000001 src_fwd_action 0X00000004
61356235mS CMMap: PlatformConnectionAudioSAP::ConnectVoice rtpRelay Open got rtpRelay ID: 1
61356235mS CMMap: PlatformConnectionAudioSAP::ConnectVoice rtpRelay Configure success
61356235mS CMMap: PlatformConnectionAudioSAP::ConnectVoice local IP addr 192.168.10.10:49152, Remote IP addr 208.x.x.111:33802
61356235mS CMMap: PlatformConnectionAudioSAP::ConnectVoice DSP IP addr 1.1.1.3:32768, telogyHost IP addr 1.1.1.1:10
61356235mS CMMap: PlatformConnectionAudioSAP::ConnectVoice pcp[160]b0r1 ConnectIndication 4.18
61356235mS H323Evt: SetOperational 13 192.168.10.10:49152 to 1
61356322mS SIP Rx: UDP 208.x.x.111:5060 -> 192.168.10.10:5060
ACK sip:6090000000@192.168.10.10:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 208.x.x.111:5060;branch=z9hG4bKa6bbp2206o71lsg9d0j1.1
From: <sip:23.x.x.225>;tag=158348979-1389989112094-
To: "HSPPa" <sip:6090000000@carrrier.net>;tag=28e8c86d31165264
Call-ID: BW150512094170114165996972@10.128.0.51
CSeq: 283595920 ACK
Contact: <sip:208.x.x.111:5060;transport=udp>
Max-Forwards: 69
Content-Length: 0

61356324mS CMLineRx: v=0
CMConnectAck
Line: type=SIPLine 17 Call: lid=17 id=4567 in=1
IE CMIERespondingPartyNumber (230)(P:0 S:100 T:3 N:100 R:4) number=823.x.x.225
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceSIPTrunk type=0 number=17 channel=1 rx_gain=32 tx_gain=32 ep_callid=4567 ipaddr=10.0.0.121 apps=0
61356324mS CMCallEvt: 17.4567.1 386 SIPTrunk Endpoint: StateChange: END=A CMCSOGConnReq->CMCSConnected
61356324mS CMTARGET: 17.4567.1 386 SIPTrunk Endpoint: ~CMTargetHandler f504e8d8 ep f50b0ab0
61356326mS CMCallEvt: 0.4569.0 386 RAS.0: StateChange: END=B CMCSConnReq->CMCSConnected
61356328mS CMExtnTx: v=RAS, p1=0
CMConnectAck
Line: type=RAS 1 Call: lid=0 id=1011 in=0
BChan: slot=21 chan=42
IE CMIERespondingPartyNumber (230)(P:0 S:100 T:3 N:100 R:4) number=823.x.x.225
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceSIPTrunk type=0 number=17 channel=1 rx_gain=32 tx_gain=32 ep_callid=4567 ipaddr=10.0.0.121 apps=0
61356330mS CMMap: a=21.42 b=0.0 PCGS CPReserveCodec (pcp[160]b0r1) true
61356330mS CMMap: a=21.42 b=0.0 PCGS CPReserveCodec (pcp[160]b0r1) true
61356330mS CMMap: PCG::MapBChan pcp[160]b0r1 cp_b f58b71e8 other_cp_b f58acc48 type CGTypeSimple
61356330mS CMMap: a=21.42 b=4.18 M12
61356530mS RES: Fri 17/1/2014 15:05:12 FreeMem=58810524 58196076(1) CachedMem=614448 CMMsg=6(8) Buff=5200 1402 999 12196 3 Links=4331 BTree=0 CPU=1/5/3400/10601/13199/1
61356530mS RES2: IP 500 V2 8.1(79) Tasks=50 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=68 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=1 TCP=16 TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
61356530mS RES4: XML MemObjs=45 PoolMem=2097152(1) FreeMem=2082064(1)
61357305mS H323Evt: RTP(50r): 192.168.10.10/49152 208.x.x.111/33802 CODEC=G729A8K(6) PKTSZ=20 RFC2833=on AGE=1070 SENT=1 (avg size=2) RECV=50 (avg size=20)
61357885mS CD: CALL: 17.4567.1 BState=Connected Cut=3 Music=0.0 Aend="Line 17" (0.0) Bend="Main Night(Main Night)" [VoiceMail] (21.42) CalledNum=Main Night () CallingNum= () Internal=0 Time=1674 AState=Connected
61361531mS RES: Fri 17/1/2014 15:05:17 FreeMem=58829700 58196076(1) CachedMem=633624 CMMsg=6(8) Buff=5200 1402 1000 12196 3 Links=4334 BTree=0 CPU=3/8/3400/10304/13199/1
61361531mS RES2: IP 500 V2 8.1(79) Tasks=49 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=65 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=1 TCP=16 TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
61361531mS RES4: XML MemObjs=45 PoolMem=2097152(1) FreeMem=2082064(1)
61362494mS CMExtnRx: v=RAS, p1=0
CMFacility
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
Called[CLAIM] Type=ACD (103) Reason=CMDRdirect
61362496mS CMLineTx: v=0
CMFacility
Line: type=SIPLine 17 Call: lid=17 id=4567 in=1
Called[CLAIM] Type=ACD (103) Reason=CMDRdirect
BChan: slot=21 chan=42
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Main Night
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:102 N:100 R:4) number=Main Night
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceVoicemail type=0 number=0 channel=42 rx_gain=32 tx_gain=32 ep_callid=4569 ipaddr=10.0.0.121 apps=0
61362499mS CMMap: a=21.42 b=0.0 PCGS CPReserveCodec (pcp[160]b0r1) true
61362499mS CMMap: a=21.42 b=0.0 PCGS CPReserveCodec (pcp[160]b0r1) true
61362499mS CMMap: PCG::MapBChan pcp[160]b0r1 cp_b f58b71e8 other_cp_b f58acc48 type CGTypeSimple
61362499mS CMMap: a=21.42 b=4.18 M12
61364393mS CD: CALL: 17.4567.1 BState=Connected Cut=3 Music=0.0 Aend="Line 17" (0.0) Bend="Main Night(Main Night)" [VoiceMail] (21.42) CalledNum=Main Night () CallingNum= () Internal=0 Time=8182 AState=Connected
61365033mS CMExtnRxP: v=113
CMShortCode
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
ShortCode GetSystemInfo (99) = []
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
61365034mS CMExtnEvt: Kimberly Park: ProcessShortCode feature=GetSystemInfo id=
61365034mS CMExtnTxP: v=113
CMShortCode
Line: type=DigitalExtn 6 Call: lid=0 id=-1 in=0
ShortCode GetSystemInfo (99) = [8.1 (79)] Calling[HSP ] Type=Default Plan=Default
Display [IP 500 V2]
Tag type=Text flags=0x1 [1,1,3,1,0,1,0.] [0x31 0x2c 0x31 0x2c 0x33 0x2c 0x31 0x2c 0x30 0x2c 0x31 0x2c 0x30 0x00 ]
Timed: 17/01/14 15:05
61365671mS SIP Rx: UDP 208.x.x.111:5060 -> 192.168.10.10:5060
BYE sip:6090000000@192.168.10.10:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 208.x.x.111:5060;branch=z9hG4bKa6bbp2206o71lsg9d0j1cdgop9l82.1
From: <sip:23.x.x.225>;tag=158348979-1389989112094-
To: "HSPPa" <sip:6090000000@carrrier.net>;tag=28e8c86d31165264
Call-ID: BW150512094170114165996972@10.128.0.51
CSeq: 283595921 BYE
Max-Forwards: 69
Content-Length: 0

61365674mS SIP Tx: UDP 192.168.10.10:5060 -> 208.x.x.111:5060
SIP/2.0 200 OK
Via: SIP/2.0/UDP 208.x.x.111:5060;branch=z9hG4bKa6bbp2206o71lsg9d0j1cdgop9l82.1
From: <sip:23.x.x.225>;tag=158348979-1389989112094-
To: "HSPPa" <sip:6090000000@carrrier.net>;tag=28e8c86d31165264
Call-ID: BW150512094170114165996972@10.128.0.51
CSeq: 283595921 BYE
Supported: timer
Server: IP Office 8.1 (79)
Content-Length: 0

61365675mS CMLineRx: v=0
CMReleaseComp
Line: type=SIPLine 17 Call: lid=17 id=4567 in=1
IE CMIERespondingPartyNumber (230)(P:0 S:100 T:3 N:100 R:4) number=823.x.x.225
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceSIPTrunk type=0 number=17 channel=1 rx_gain=32 tx_gain=32 ep_callid=4567 ipaddr=10.0.0.121 apps=0
Cause=16, Normal call clearing
61365675mS CMCallEvt: 17.4567.1 386 SIPTrunk Endpoint: StateChange: END=A CMCSConnected->CMCSCompleted
61365677mS PRN: CDR - TCPSend maxqueuesize=3000 framecount=3000 operational=0
61365678mS CMLOGGING: CALL:2014/01/1715:05,00:00:09,000,,I,MainNight,6090000000,,,,0,,""n/a,0
61365679mS CD: CALL: 17.4567.1 BState=Connected Cut=2 Music=0.0 Aend="Line 17" (0.0) Bend="Main Night(Main Night)" [VoiceMail] (21.42) CalledNum=Main Night () CallingNum= () Internal=0 Time=9467 AState=Idle
61365679mS CD: CALL: 17.4567.1 Deleted
61365679mS CMCallEvt: 17.4567.1 -1 SIPTrunk Endpoint: StateChange: END=X CMCSCompleted->CMCSDelete
61365680mS CMExtnEvt: RAS: CALL LOST (CMCauseNormal)
61365680mS CMCallEvt: 0.4569.0 -1 RAS.0: StateChange: END=X CMCSConnected->CMCSCompleted
61365680mS CMExtnEvt: v=1011 State, new=PortRecoverDelay old=Connected,0,0,RAS
61365680mS CMExtnTx: v=RAS, p1=0
CMReleaseComp
Line: type=RAS 1 Call: lid=0 id=1011 in=0
Called[Main Night] Type=Voicemail (102) Reason=CMDRX_Attendant Calling[] Type=NetworkSpecific Plan=Default Pres=Allowed (0)
BChan: slot=21 chan=42
Cause=16, Normal call clearing
61365681mS CMMap: a=21.42 b=0.0 T0
61365682mS CMExtnEvt: RAS: CMExtnHandler::SetCurrent( id: 4569->0 )
61365682mS CMCallEvt: 0.4569.0 -1 RAS.-1: StateChange: END=X CMCSCompleted->CMCSDelete
61365682mS CMExtnEvt: v=1011 State, new=Idle old=PortRecoverDelay,0,0,RAS
61365682mS CMExtnTx: v=RAS, p1=0
CMReleaseComp
Line: type=RAS 1 Call: lid=0 id=1011 in=0
BChan: slot=21 chan=42
Cause=16, Normal call clearing
61365683mS CMCallEvt: 0.4569.0 -1 BaseEP: DELETE CMEndpoint f507b0d0 TOTAL NOW=3 CALL_LIST=1
61365683mS CMCallEvt: END CALL:386 (f5022c30)
61365684mS CMCallEvt: 17.4567.1 -1 BaseEP: DELETE CMEndpoint f50b0ab0 TOTAL NOW=2 CALL_LIST=1
61365686mS CMMap: PCG::UnmapBChan pcp[32]b1r0 cp_b f58acc48 other_cp_b f58b71e8
61365686mS CMMap: a=4.18 b=21.42 M02
61365686mS CMMap: PlatformConnectionAudioSAP:isconnectVoice rtpRelay ID: 1 Close success
61365687mS H323Evt: SetOperational 13 192.168.10.10:49152 to 0
61365687mS CMMap: PCG::UnmapBChan pcp[160]b0r1 cp_b 0 other_cp_b 0
61365687mS H323Evt: RTP(END): 192.168.10.10/49152 208.x.x.111/33802 CODEC=G729A8K(6) PKTSZ=20 RFC2833=on AGE=9453 SENT=329 RECV=497 RTdelay=0 jitter=0 loss=0 remotejitter=0 remoteloss=0
61365688mS CMMap: a=4.18 b=0.0 Mapper::FreeCodec freed CMRTVocoder resource busy 1, total 20
61368712mS CMExtnRxP: v=115
CMShortCode
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
ShortCode GetSystemInfo (99) = []
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
61368713mS CMExtnEvt: Linda Fox: ProcessShortCode feature=GetSystemInfo id=
61368713mS CMExtnTxP: v=115
CMShortCode
Line: type=DigitalExtn 3 Call: lid=0 id=-1 in=0
ShortCode GetSystemInfo (99) = [8.1 (79)] Calling[HSP ] Type=Default Plan=Default
Display [IP 500 V2]
Tag type=Text flags=0x1 [1,1,3,1,0,1,0.] [0x31 0x2c 0x31 0x2c 0x33 0x2c 0x31 0x2c 0x30 0x2c 0x31 0x2c 0x30 0x00 ]
Timed: 17/01/14 15:05
61371034mS RES: Fri 17/1/2014 15:05:26 FreeMem=58884652 58196076(1) CachedMem=688576 CMMsg=6(8) Buff=5200 1402 1000 12196 3 Links=4408 BTree=0 CPU=1/5/3400/10675/13199/1
61371035mS RES2: IP 500 V2 8.1(79) Tasks=49 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=64 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=1 TCP=16 TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
61371035mS RES4: XML MemObjs=45 PoolMem=2097152(1) FreeMem=2082064(1)
61374023mS CMCallEvt: 0.4570.0 -1 BaseEP: NEW CMEndpoint f50b0ab0 TOTAL NOW=3 CALL_LIST=1
61374025mS SIP Tx: UDP 192.168.10.10:5060 -> 208.x.x.111:5060
REGISTER sip:carrrier.net SIP/2.0
Via: SIP/2.0/UDP 192.168.10.10:5060;rport;branch=z9hG4bK143c000d3ad163b3f003b0f61d8f08b2
From: <sip:6090000000@carrrier.net>;tag=0677e011d6650c93
To: <sip:6090000000@carrrier.net>
Call-ID: 7c35d23402035e13ba19f2e3277f131d
CSeq: 1911730172 REGISTER
Contact: "Unknown" <sip:6090000000@192.168.10.10:5060;transport=udp>
Expires: 3600
Authorization: Digest username="tds_tg-0001009594-1",realm="BroadWorks",nonce="BroadWorksXhqiv53gyT3245xaBW",response="253fd44acdee96c8d0a68c440c5a5b1d",uri="sip:carrrier.net",algorithm=MD5,qop=auth,nc=00000801,cnonce="9f691438
51f7825fc57
Max-Forwards: 70
User-Agent: IP Office 8.1 (79)
Supported: timer
Content-Length: 0

61374042mS SIP Rx: UDP 208.x.x.111:5060 -> 192.168.10.10:5060
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.10.10:5060;received=23.x.x.225;branch=z9hG4bK143c000d3ad163b3f003b0f61d8f08b2;rport=5060
From: <sip:6090000000@carrrier.net>;tag=0677e011d6650c93
To: <sip:6090000000@carrrier.net>;tag=aprqokt3h73-l5blhq6eivbpf
Call-ID: 7c35d23402035e13ba19f2e3277f131d
CSeq: 1911730172 REGISTER
Contact: <sip:6090000000@192.168.10.10:5060;transport=udp>;expires=60

61374044mS CMCallEvt: 0.4570.0 -1 SIPTrunk Endpoint: StateChange: END=X CMCSIdle->CMCSDelete
61374045mS CMCallEvt: 0.4570.0 -1 BaseEP: DELETE CMEndpoint f50b0ab0 TOTAL NOW=2 CALL_LIST=1

********** Warning: Logging to Screen Stopped **********
61377144mS CMExtnRxP: v=100
CMShortCode
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
ShortCode GetSystemInfo (99) = []
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
61377144mS CMExtnEvt: Doreen Barnard: ProcessShortCode feature=GetSystemInfo id=
61377145mS CMExtnTxP: v=100
CMShortCode
Line: type=DigitalExtn 3 Call: lid=0 id=-1 in=0
ShortCode GetSystemInfo (99) = [8.1 (79)] Calling[HSP ] Type=Default Plan=Default
Display [IP 500 V2]
Tag type=Text flags=0x1 [1,1,3,1,0,1,0.] [0x31 0x2c 0x31 0x2c 0x33 0x2c 0x31 0x2c 0x30 0x2c 0x31 0x2c 0x30 0x00 ]
Timed: 17/01/14 15:05

********** SysMonitor v10.1 (79) [connected to 10.0.0.121 (HSP )] **********
61377722mS PRN: Monitor Status IP 500 V2 8.1(79)
61377722mS PRN: LAW=U PRI=1, BRI=0, ALOG=8, VCOMP=20, MDM=0, WAN=0, MODU=1 LANM=0 CkSRC=1 VMAIL=1(VER=3 TYP=1) 1-X=0 CALLS=1(TOT=386)
61379539mS RES: Fri 17/1/2014 15:05:35 FreeMem=58886588 58196076(1) CachedMem=690512 CMMsg=6(8) Buff=5200 1402 1000 12196 3 Links=4409 BTree=0 CPU=1/5/3400/10711/13199/1
61379539mS RES2: IP 500 V2 8.1(79) Tasks=49 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=62 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=2 SSA=1 TCP=15 TAPI=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
61379539mS RES4: XML MemObjs=45 PoolMem=2097152(1) FreeMem=2082064(1)

********** Warning: Logging to Screen Started **********
61399865mS CMExtnRxP: v=112
CMShortCode
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
ShortCode GetSystemInfo (99) = []
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
61399865mS CMExtnEvt: Tina Giacinto: ProcessShortCode feature=GetSystemInfo id=
61399866mS CMExtnTxP: v=112
CMShortCode
Line: type=DigitalExtn 6 Call: lid=0 id=-1 in=0
ShortCode GetSystemInfo (99) = [8.1 (79)] Calling[HSP ] Type=Default Plan=Default
Display [IP 500 V2]
Tag type=Text flags=0x1 [1,1,3,1,0,1,0.] [0x31 0x2c 0x31 0x2c 0x33 0x2c 0x31 0x2c 0x30 0x2c 0x31 0x2c 0x30 0x00 ]
Timed: 17/01/14 15:05
61400321mS SIP Rx: UDP 208.x.x.111:5060 -> 192.168.10.10:5060
INVITE sip:6090000000@192.168.10.10:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 208.x.x.111:5060;branch=z9hG4bKecb3r6306o6h65p223e0.1
From: <sip:23.x.x.225>;tag=628566849-1389989156207-
To: "HSPPa"<sip:6090000000@carrrier.net>
Call-ID: BW150556207170114284474333@10.128.0.51
CSeq: 283617976 INVITE
Contact: <sip:208.x.x.111:5060;transport=udp>
Supported: 100rel
Allow: ACK,BYE,CANCEL,INFO,INVITE,OPTIONS,PRACK,REFER,NOTIFY,UPDATE
Accept: application/media_control+xml,application/sdp,multipart/mixed
Max-Forwards: 69
Content-Type: application/sdp
Content-Disposition: session;handling=required
Content-Length: 289

v=0
o=BroadWorks 439762755 1 IN IP4 208.x.x.111
s=-
c=IN IP4 208.x.x.111
t=0 0
m=audio 42166 RTP/AVP 18 0 8 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
a=maxptime:20
61400324mS CMCallEvt: 0.4571.0 -1 BaseEP: NEW CMEndpoint f50b0ab0 TOTAL NOW=3 CALL_LIST=1
61400327mS SIP Tx: UDP 192.168.10.10:5060 -> 208.x.x.111:5060
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 208.x.x.111:5060;branch=z9hG4bKecb3r6306o6h65p223e0.1
From: <sip:23.x.x.225>;tag=628566849-1389989156207-
To: "HSPPa" <sip:6090000000@carrrier.net>;tag=b565fb04c3323c2a
Call-ID: BW150556207170114284474333@10.128.0.51
CSeq: 283617976 INVITE
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, INFO, UPDATE
Supported: timer
Server: IP Office 8.1 (79)
Content-Length: 0

61400330mS CMCallEvt: CREATE CALL:387 (f5022c30)
61400330mS CMCallEvt: 0.4572.0 -1 BaseEP: NEW CMEndpoint f5050c18 TOTAL NOW=4 CALL_LIST=1
61400333mS CMLineRx: v=0
CMSetup
Line: type=SIPLine 17 Call: lid=17 id=4571 in=1
Called[6090000000] Type=Default (100) Reason=CMDRdirect SndComp Calling[] Type=NetworkSpecific Plan=Default Pres=Allowed (0)
BC: CMTC=Speech CMTM=Circuit CMTR=64 CMST=Default CMU1=ULaw
IE CMIEFastStartInfoData (6) 4 item(s)
IE CMIERespondingPartyNumber (230)(P:0 S:100 T:3 N:100 R:4) number=
IE CMIEDeviceDetail (231) LOCALE=enu HW=15 VER=8 class=CMDeviceSIPTrunk type=0 number=17 channel=1 rx_gain=32 tx_gain=32 ep_callid=4571 ipaddr=10.0.0.121 apps=0
61400333mS CD: CALL: 17.4571.1 BState=Idle Cut=1 Music=0.0 Aend="Line 17" (0.0) Bend="" [] (0.0) CalledNum=6090000000 () Callin

 

[Billx1]

Did you create any "Dial Direct" Short Code(s)???

 

[tmcd123]

No direct dial Short codes.

 

[tmcd123]

Just an update....Turned out to be a SIP trunk carrier issue. We weren't getting any caller ID info on incoming calls so we opened a ticket with them. They found out they didn't have the SIP trunk setup right. They made the necessary changes and caller ID started working as well as the regular transfer to an extension off the Main AA.