IP Office R11.1 Spectrum SIP trunks no audio on forwarder/twinned calls to cell sometimes

[mode1]

I have a customer that has Spectrum SIP trunks coming in on fiber. Spectrum installed a RAD and Adtran 908e that I plugged the IP Office directly into the Adtran port. This has been installed for over a year with no issues on call quality or audio. They recently wanted to forward or twin calls to the sales people on the road. After many adjustments using 11.1 essential. I downgraded to 10 software because I found a configuration guide for Spectrum SIP trunks using IP Office 10.0 software. This did not work, as expected. Spectrum says it's not an issue on their side. I did find a possible issue in the monitor traces showing the RTP ports being in a range lower than what was programmed, so I changed and this worked 4 times in a row and then quite working. Keepalives are on and I am at a lost. I have attached a call with audio and copied the call with no audio. Thanks in advance
19:42:42 1249371mS PRN: CLIENT: IPAddress=10.10.10.60 UDPPort=62794 LastReceived=1245516(3855) LastSent=1249371(0) LastOldUpdate=1245370(4001) SentPackets=22 MissedPackets=0 OldSentPackets=0 OldMissedPackets=0
19:42:42 1249371mS PRN: ++++ END OF UDP MONITOR CLIENT DUMP ++++
19:42:43 1250034mS SIP Rx: UDP 10.0.0.1:5060 -> 10.0.0.2:5060
INVITE sip:9892703830@10.0.0.2:5060 SIP/2.0
From: "MID MICHIGAN CO" <sip:9895297539@10.0.0.2:5060;transport=UDP>;tag=4fbd7c78-7f000001-13c4-b075e-b0d3269d-b075e
To: <sip:9892703830@10.0.0.2:5060>
Call-ID: 4fcba8b0-7f000001-13c4-b075e-e5c01179-b075e@10.0.0.2
CSeq: 1 INVITE
Via: SIP/2.0/UDP 10.0.0.1:5060;branch=z9hG4bK-b075e-2b14c752-54ddd80c
Max-Forwards: 70
Supported: 100rel,replaces
Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, PRACK, REFER, REGISTER
User-Agent: ADTRAN_Total_Access_908e_3rd_Gen/R13.2.2.E
Contact: <sip:9895297539@10.0.0.1:5060;transport=UDP>
Content-Type: application/sdp
Content-Length: 206

v=0
o=BroadWorks 2424012794 1 IN IP4 10.0.0.1
s=-
c=IN IP4 10.0.0.1
t=0 0
m=audio 13280 RTP/AVP 0 97
a=sendrecv
a=maxptime:20
a=rtpmap:0 PCMU/8000
a=rtpmap:97 telephone-event/8000
a=fmtp:97 0-15
19:42:43 1250037mS CMCallEvt: 0000000000000000 0.1026.0 -1 BaseEP: NEW CMEndpoint f17d1d38 TOTAL NOW=1 CALL_LIST=0
19:42:43 1250038mS NAT: Info: Line 17: Not using STUN for media in this case.
19:42:43 1250041mS SIP Tx: UDP 10.0.0.2:5060 -> 10.0.0.1:5060
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 10.0.0.1:5060;branch=z9hG4bK-b075e-2b14c752-54ddd80c
From: "MID MICHIGAN CO" <sip:9895297539@10.0.0.2:5060;transport=UDP>;tag=4fbd7c78-7f000001-13c4-b075e-b0d3269d-b075e
Call-ID: 4fcba8b0-7f000001-13c4-b075e-e5c01179-b075e@10.0.0.2
CSeq: 1 INVITE
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,NOTIFY,UPDATE
Supported: timer,100rel
Server: IP Office 10.1.0.8.0 build 3
To: <sip:9892703830@10.0.0.2:5060>;tag=74e2a0151050427c
Content-Length: 0

19:42:43 1250043mS CMCallEvt: CREATE CALL:7 (f17c2648)
19:42:43 1250043mS CMCallEvt: 0000000000000000 0.1027.0 -1 BaseEP: NEW CMEndpoint f17c0af8 TOTAL NOW=2 CALL_LIST=0
19:42:43 1250045mS CMLineRx: v=0
CMSetup
Line: type=SIPLine 17 Call: lid=17 id=1026 in=1
Called[211] Type=Default (100) Reason=CMDRdirect SndComp Calling[9895297539@10.0.0.1] Type=Unknown Plan=Default
BC: CMTC=Speech CMTM=CallIndSigCon CMTR=64 CMST=Default CMU1=ULaw
IE CMIEFastStartInfoData (6) 2 item(s)
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=9895297539@10.0.0.1
IE CMIEDeviceDetail (231) 0a00000200000402 LOCALE=enu HW=15 VER=10 class=CMDeviceSIPTrunk type=0 number=17 channel=1 features=0x0 rx_gain=32 tx_gain=32
ep_callid=1026 ipaddr=10.0.0.2 apps=0 loc=999 em_a_loc=999 em_d_loc=0 features2=0x0 is_spcall=1 ignores_dtmf=0 avgsid=
IE CMIEDIDNumber (245)(P:100 S:100 T:100 N:100 R:4) number=9892703830
19:42:43 1250045mS CMCallEvt: 0a00000200000402 17.1026.1 7 SIPTrunk Endpoint: StateChange: END=A CMCSIdle->CMCSDialInitiated
19:42:43 1250046mS CMTARGET: 0a00000200000402 17.1026.1 7 SIPTrunk Endpoint: LOOKUP CALL ROUTE: GID=101 type=100 called_party=211 sub= calling=9895297539@10.0.0.1 calling_sub= dir=in complete=1 ses=0
19:42:43 1250046mS CMTARGET: 0a00000200000402 17.1026.1 7 SIPTrunk Endpoint: SET BESTMATCH: length 0 vs -1 match= dest=Main
19:42:43 1250046mS CMTARGET: 0a00000200000402 17.1026.1 7 SIPTrunk Endpoint: SET BESTMATCH: length 10 vs 0 match=9892703830 dest=211
19:42:43 1250046mS CMCallEvt: Priority hike: call 7 priority 0->1
19:42:43 1250046mS CMTARGET: 0a00000200000402 17.1026.1 7 SIPTrunk Endpoint: LOOKUP ICR: DDI=9892703830 CGPN=9895297539@10.0.0.1 (Destination 211 ) => CDPN=211
19:42:43 1250046mS CMTARGET: 0a00000200000402 17.1026.1 7 SIPTrunk Endpoint: ADD TARGET (N): number=211 type=100 depth=1 nobar=1 setorig=1 ses=0
19:42:43 1250046mS CMTARGET: 0a00000200000402 17.1026.1 7 SIPTrunk Endpoint: SET USER: Test Phone orig=1
19:42:43 1250047mS CMTARGET: 0a00000200000402 17.1026.1 7 SIPTrunk Endpoint: ADD USER: Test Phone depth=1 disallow_cw=0 dnd=0 real_call=1 group_call=0 type(CMNTypeDefault) incl(0x0) excpt(0x0), allow_redir(1) remote=00000000 simult 0 (0) r_lic=T
19:42:43 1250047mS CMCallEvt: 0000000000000000 0.1028.0 -1 BaseEP: NEW CMEndpoint f17ba004 TOTAL NOW=3 CALL_LIST=1
19:42:43 1250047mS CMCallEvt: 0000000000000000 0.1028.0 -1 Test Phone.-1: NEW CMExtnEndpoint f17ba004, Name=Test Phone, Extn=211, Phys Extn=211
19:42:43 1250047mS ERR: CMLCMediaHandler created without ep
19:42:43 1250047mS PRN: Begin Stack Trace, Task=CMRtEngine taskaddr=f5850c48 stkbot=f5817a20 stackptr=f581ebb0(12928) stacksize=42000
19:42:43 1250047mS PRN: findfunc f024a7bc f10a6878 f03e1d90 f0a919bc f03b3094 f03e6f64 f03e724c f03fa34c
19:42:43 1250048mS PRN: findfunc f03fa4b0 f044a088 f0451298 f0458348 f045fe74 f0456e1c f045cc18 f0443b00
19:42:43 1250048mS PRN: findfunc f0384500 f038bccc f038ba44 f0b7b080 f0b7bd30 f0acd1a4 f0b7b43c f0b7b76c
19:42:43 1250048mS PRN: findfunc f0b7b980 f0b7ba9c f0acfa80 f10f5154 f07522a8 f075327c f071c1c8 f071d064
19:42:43 1250048mS PRN: findfunc f071d190 f024bff4 f024bfa8
19:42:43 1250048mS PRN: offsets f581ebb0(12928) f581ebe0 f581ebe0(12880) f581ec00 f581ec00(12848) f581ec20 f581ec20(12816) f581ec30 f581ec30(12800) f581ec60 f581ec60(12752) f581ec90 f581ec90(12704) f58204c0 f58204c0(6512) f5820500
19:42:43 1250048mS PRN: offsets f5820500(6448) f5820530 f5820530(6400) f5820550 f5820550(6368) f58207f0 f58207f0(5696) f58208f0 f58208f0(5440) f5820bc0 f5820bc0(4720) f5820e60 f5820e60(4048) f58210b0 f58210b0(3456) f5821260
19:42:43 1250048mS PRN: offsets f5821260(3024) f5821280 f5821280(2992) f58212a0 f58212a0(2960) f58212d0 f58212d0(2912) f5821360 f5821360(2768) f5821370 f5821370(2752) f58219a0 f58219a0(1168) f58219f0 f58219f0(1088) f5821a50
19:42:43 1250048mS PRN: offsets f5821a50(992) f5821b30 f5821b30(768) f5821b80 f5821b80(688) f5821be0 f5821be0(592) f5821d10 f5821d10(288) f5821d20 f5821d20(272) f5821d60 f5821d60(208) f5821d80 f5821d80(176) f5821db0
19:42:43 1250048mS PRN: offsets f5821db0(128) f5821dd0 f5821dd0(96) f5821de0 f5821de0(80) aaaaaaaa
19:42:43 1250048mS PRN: End Stack Trace
19:42:43 1250050mS CMTARGET: 0a00000200000404 0.1028.0 7 Test Phone.0: ADD TWINNED PRIMARY twinbusy=0
19:42:43 1250050mS CMCallEvt: 0000000000000000 0.1029.0 -1 BaseEP: NEW CMEndpoint f17c53c4 TOTAL NOW=4 CALL_LIST=1
19:42:43 1250050mS CMCallEvt: CREATE CALL:8 (f17c4e90)
19:42:43 1250050mS CMCallEvt: 0000000000000000 0.1030.0 -1 BaseEP: NEW CMEndpoint f17a20e0 TOTAL NOW=5 CALL_LIST=1
19:42:43 1250052mS CMCallEvt: 0a00000200000405 0.1029.0 8 GHOST:: StateChange: END=A CMCSIdle->CMCSDialInitiated
19:42:43 1250052mS CMTARGET: 0a00000200000405 0.1029.0 8 GHOST:: LOOKUP CALL ROUTE: GID=0 type=100 called_party=918108204050 sub= calling=211 calling_sub= dir=out complete=1 ses=0
19:42:43 1250052mS CMTARGET: 0a00000200000405 0.1029.0 8 GHOST:: ADD TARGET (N): number=918108204050 type=100 depth=1 nobar=1 setorig=1 ses=0
19:42:43 1250053mS CMTARGET: 0a00000200000405 0.1029.0 8 GHOST:: USER SC: 918108204050 2 18108204050 sc=type=Dial code=9N, num=Nsi9892703830
19:42:43 1250053mS CMARS: FindActiveARSByGroupID GroupID=50 - Found
19:42:43 1250053mS CMARS: MakeCallTarget - Called Number: 18108204050
19:42:43 1250053mS CMCallEvt: 0000000000000000 0.1031.0 -1 BaseEP: NEW CMEndpoint f179d174 TOTAL NOW=6 CALL_LIST=2
19:42:43 1250054mS CMTARGET: 0a00000200000405 0.1029.0 8 GHOST:: INITIAL TARGETING SUCCEEDED
19:42:43 1250054mS CMTARGET: 0a00000200000405 0.1029.0 8 GHOST:: GetNoAnswerTimer:15
19:42:43 1250054mS CMCallEvt: 0a00000200000405 0.1029.0 8 GHOST:: StateChange: END=A CMCSDialInitiated->CMCSDialled
19:42:43 1250055mS CMCallEvt: 0000000000000000 0.1030.0 8 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
19:42:43 1250055mS CMCallEvt: 0000000000000000 0.1031.0 8 ARS for Main: StateChange: END=T CMCSIdle->CMCSOffering
19:42:43 1250056mS CMCallEvt: 0000000000000000 0.1030.0 8 TargetingEP: RequestEnd 0000000000000000 0.1031.0 8 ARS for Main
19:42:43 1250056mS CMTARGET: 0a00000200000405 0.1029.0 8 GHOST:: CancelTimer CMTCNoAnswerTimeout
19:42:43 1250057mS CMCallEvt: 0000000000000000 0.1031.0 8 ARS for Main: StateChange: END=B CMCSOffering->CMCSOverlapRecv
19:42:43 1250057mS CMARS: FORM: Main - Received Number: 18108204050
19:42:43 1250057mS CMARS: FOUND A SHORT CODE - short_code: 1N; - Tel: 1N - Called_Party: 18108204050 - Line Group Id: 101
19:42:43 1250057mS CMARS: SetCurrentTarget: Short_Code: 1N; - Line_Group_ID: 101
19:42:43 1250057mS CMARS: FindActiveARSByGroupID GroupID=101 - Not Found
19:42:43 1250057mS CMLRQ: FindActiveLRQByGroupID GroupID=101 - Not Found
19:42:43 1250058mS CMMap: a=0.18 b=0.0 Mapper::AllocateCodec allocated CMRTVocoder(f560ad80) resource busy 1, total 10
19:42:43 1250058mS CMCallEvt: 0000000000000000 0.1032.0 -1 BaseEP: NEW CMEndpoint f179b1ac TOTAL NOW=7 CALL_LIST=2
19:42:43 1250059mS NAT: Info: Line 17: Not using STUN for media in this case.
19:42:43 1250060mS CMARS: ModifyCMARSTarget: Short_Code: 1N; - Line_Group_ID: 101 set line status to CMARS_TRYING
19:42:43 1250060mS CMARS: FOUND LINE - Line Id: 17 - using line group id: 101 (code 1N;, line 101) - Called Number: 18108204050 - Calling Number: 9892703830
19:42:43 1250060mS CMARS: SEND Setup TO LINE
19:42:43 1250060mS CMCallEvt: 0a00000200000408 17.1032.0 8 SIPTrunk Endpoint: StateChange: END=child CMCSIdle->CMCSOffering
19:42:43 1250061mS CMCallEvt: 0000000000000000 0.1030.0 -1 TargetingEP: StateChange: END=X CMCSOffering->CMCSDelete
19:42:43 1250061mS CMCallEvt: 0000000000000000 0.1030.0 -1 BaseEP: DELETE CMEndpoint f17a20e0 TOTAL NOW=6 CALL_LIST=2
19:42:43 1250061mS CMExtnEvt: Test Phone:211 ExtnFault now 0
19:42:43 1250063mS CMMap: a=0.19 b=0.0 Mapper::AllocateCodec allocated CMRTVocoder(f560ac80) resource busy 2, total 10
19:42:43 1250063mS CMCallEvt: 0000000000000000 0.1033.0 -1 BaseEP: NEW CMEndpoint f17967a4 TOTAL NOW=7 CALL_LIST=2
19:42:43 1250063mS CMCallEvt: 0000000000000000 0.1033.0 -1 Test Phone.-1: NEW CMExtnEndpoint f17967a4, Name=Test Phone, Extn=211, Phys Extn=
19:42:43 1250064mS CMTARGET: 0a00000200000409 0.1033.0 7 Test Phone.0: ADD FNE TWINNED MOBILE: Test Phone
19:42:43 1250064mS CMTARGET: TryLoggedOutTargeting for user Test Phone Succeeded
19:42:43 1250064mS CMTARGET: FoundKnownSystemTargets ICR cache hit
19:42:43 1250065mS CMTARGET: 0a00000200000402 17.1026.1 7 SIPTrunk Endpoint: INITIAL TARGETING SUCCEEDED
19:42:43 1250065mS CMTARGET: 0a00000200000402 17.1026.1 7 SIPTrunk Endpoint: GetNoAnswerTimer:15
19:42:43 1250065mS CMCallEvt: 0a00000200000402 17.1026.1 7 SIPTrunk Endpoint: StateChange: END=A CMCSDialInitiated->CMCSDialled
19:42:43 1250066mS CMLineTx: v=0
CMProceeding
Line: type=SIPLine 17 Call: lid=17 id=1026 in=1
19:42:43 1250066mS CMCallEvt: 0000000000000000 0.1027.0 7 TargetingEP: StateChange: END=B CMCSIdle->CMCSOffering
19:42:43 1250067mS CMCallEvt: 0a00000200000404 0.1028.0 7 Test Phone.0: StateChange: END=T CMCSIdle->CMCSOffering
19:42:43 1250068mS CMCallEvt: 0a00000200000404 0.1028.0 7 Test Phone.0: StateChange: END=T CMCSOffering->CMCSCompleted
19:42:43 1250068mS CMExtnEvt: Test Phone: CALL LOST (CMCauseNormal)
19:42:43 1250068mS CMExtnEvt: Test Phone: Extn(211) Calling Party Number(9895297539@10.0.0.1) Type(CMNTypeUnknown)
19:42:43 1250069mS CMCallEvt: 0a00000200000404 0.1028.0 -1 Test Phone.-1: StateChange: END=X CMCSCompleted->CMCSDelete
19:42:43 1250069mS CMCallEvt: 0a00000200000404 0.1028.0 -1 BaseEP: DELETE CMEndpoint f17ba004 TOTAL NOW=6 CALL_LIST=2
19:42:43 1250069mS CMCallEvt: 0a00000200000409 0.1033.0 7 Test Phone.0: StateChange: END=T CMCSIdle->CMCSOffering
19:42:43 1250070mS CMExtnEvt: Test Phone: CMExtnHandler::SetCurrent( id: 0->1033 )
19:42:43 1250070mS CMExtnTx: v=211, p1=0
CMSetup
Line: type=NoLine 0 Call: lid=0 id=1033 in=0
Called[211] Type=Default (100) Reason=CMDRdirect SndComp Calling[9895297539@10.0.0.1] Type=Unknown Plan=Default
BC: CMTC=Speech CMTM=CallIndSigCon CMTR=64 CMST=Default CMU1=ULaw
IE CMIECalledPartyName (224)(Type=CMNameInternalUser) name=Test Phone
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=9895297539@10.0.0.1
IE CMIEDeviceDetail (231) 0a00000200000402 LOCALE=enu HW=15 VER=10 class=CMDeviceSIPTrunk type=0 number=17 channel=1 features=0x20 rx_gain=32 tx_gain=32
ep_callid=1026 ipaddr=10.0.0.2 apps=0 loc=999 em_a_loc=999 em_d_loc=0 features2=0x0 is_spcall=1 ignores_dtmf=0 avgsid=
IE CMIEIcrPriorityDetail (239) Priority = 1
IE CMIEDIDNumber (245)(P:100 S:100 T:100 N:100 R:4) number=9892703830
IE CMIEMohSourceId (247) MOH Source = 1
Display [9895297539>Test Phone]
Timed: 09/02/23 19:42
Locale: enu
19:42:43 1250070mS CMExtnRx: v=211, p1=0
CMAlerting
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
19:42:43 1250070mS CMCallEvt: 0a00000200000409 0.1033.0 7 Test Phone.0: StateChange: END=T CMCSOffering->CMCSRinging
19:42:43 1250070mS CMExtnEvt: v=-1 State, new=Ringing old=Idle,0,0,Test Phone
19:42:43 1250071mS CMCallEvt: 0000000000000000 0.1027.0 7 TargetingEP: StateChange: END=B CMCSOffering->CMCSRinging
19:42:43 1250071mS CMCallEvt: 0a00000200000402 17.1026.1 7 SIPTrunk Endpoint: StateChange: END=A CMCSDialled->CMCSRingBack
19:42:43 1250072mS CMLineTx: v=0
CMAlerting
Line: type=SIPLine 17 Call: lid=17 id=1026 in=1
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
AlertingName.Invoke.CodePageISO8859-1
invokeId 14630
user 'Test Phone' presentation Allowed
IE CMIERespondingPartyName (228)(Type=CMNameInternalUser) name=Test Phone
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=211
IE CMIEDeviceDetail (231) 0a00000200000409 LOCALE=enu HW=15 VER=10 class=CMDeviceGhostPhone type=56 number=1 channel=0 features=0x10000000 rx_gain=32 tx_gain=32
ep_callid=1033 ipaddr=10.0.0.2 apps=0 loc=0 em_a_loc=0 em_d_loc=0 features2=0x0 is_spcall=0 ignores_dtmf=0 avgsid=
19:42:43 1250073mS SIP Tx: UDP 10.0.0.2:5060 -> 10.0.0.1:5060
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 10.0.0.1:5060;branch=z9hG4bK-b075e-2b14c752-54ddd80c
From: "MID MICHIGAN CO" <sip:9895297539@10.0.0.2:5060;transport=UDP>;tag=4fbd7c78-7f000001-13c4-b075e-b0d3269d-b075e
Call-ID: 4fcba8b0-7f000001-13c4-b075e-e5c01179-b075e@10.0.0.2
CSeq: 1 INVITE
Contact: "Test Phone" <sip:9892703830@10.0.0.2:5060;transport=udp>
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,NOTIFY,UPDATE
P-Preferred-Identity: "Test Phone" <sip:211@10.0.0.2:5060>
Supported: timer,100rel
Server: IP Office 10.1.0.8.0 build 3
To: <sip:9892703830@10.0.0.2:5060>;tag=74e2a0151050427c
Content-Length: 0

19:42:43 1250077mS CMCallEvt: 0a00000200000408 17.1032.0 8 SIPTrunk Endpoint: Twin Mobile Delay Timer Expired - Initiate call to Mobile
19:42:43 1250078mS CMLineTx: v=0
CMSetup
Line: type=SIPLine 17 Call: lid=17 id=1032 in=0
Called[18108204050] Type=Default (100) Reason=CMDRdirect SndComp Calling[9892703830] Type=National Plan=ISDN
BC: CMTC=Speech CMTM=CallIndSigCon CMTR=64 CMST=Default CMU1=ULaw
IE CMIEFastStartInfoData (6) 2 item(s)
IE CMIEMediaWaitForConnect (16) CMIEMediaWaitForConnect
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Test Phone
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=211
IE CMIEDeviceDetail (231) 0a00000200000405 LOCALE=enu HW=15 VER=10 class=CMDeviceGhostHandler type=2 number=0 channel=0 features=0x20 rx_gain=32 tx_gain=32
ep_callid=1029 ipaddr=10.0.0.2 apps=0 loc=0 em_a_loc=0 em_d_loc=0 features2=0x0 is_spcall=0 ignores_dtmf=0 avgsid=
IE CMIEMohSourceId (247) MOH Source = 255
IE CMIESecurityData (253) 2 item(s)
CMSecurityElement RX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 1)
}
CMSecurityElement TX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 1)
}
19:42:43 1250081mS SIP Tx: UDP 10.0.0.2:5060 -> 10.0.0.1:5060
INVITE sip:18108204050@10.0.0.1 SIP/2.0
Via: SIP/2.0/UDP 10.0.0.2:5060;rport;branch=z9hG4bKe788fc54d4e62b85ce94ffa2e96e0595
From: "9895297539" <sip:9895297539@10.0.0.1>;tag=d08681363068dfa9
To: <sip:18108204050@10.0.0.1>
Call-ID: 88974b6605e6eea04f61264de7ec627e
CSeq: 1908615602 INVITE
Contact: "9892703830" <sip:9892703830@10.0.0.2:5060;transport=udp>
Max-Forwards: 70
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,NOTIFY,UPDATE
Supported: timer,100rel
User-Agent: IP Office 10.1.0.8.0 build 3
P-Preferred-Identity: "9895297539@10.0.0.1" <sip:9895297539@10.0.0.2:5060>
Diversion: "9892703830" <sip:9892703830@10.0.0.1:5060>;reason=direct;screen=no;privacy=off;counter=1
Content-Type: application/sdp
Content-Length: 193

v=0
o=UserA 3186524405 1151524083 IN IP4 10.0.0.2
s=Session SDP
c=IN IP4 10.0.0.2
t=0 0
m=audio 10000 RTP/AVP 0 97
a=rtpmap:0 PCMU/8000
a=rtpmap:97 telephone-event/8000
a=fmtp:97 0-15
19:42:43 1250090mS SIP Rx: UDP 10.0.0.1:5060 -> 10.0.0.2:5060
SIP/2.0 100 Trying
From: "9895297539"<sip:9895297539@10.0.0.1>;tag=d08681363068dfa9
To: <sip:18108204050@10.0.0.1>
Call-ID: 88974b6605e6eea04f61264de7ec627e
CSeq: 1908615602 INVITE
Via: SIP/2.0/UDP 10.0.0.2:5060;rport=5060;branch=z9hG4bKe788fc54d4e62b85ce94ffa2e96e0595
Contact: <sip:18108204050@10.0.0.1:5060;transport=UDP>
Supported: 100rel,replaces
Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, PRACK, REFER, REGISTER
User-Agent: ADTRAN_Total_Access_908e_3rd_Gen/R13.2.2.E
Content-Length: 0

19:42:43 1250093mS CMLineRx: v=0
CMProceeding
Line: type=SIPLine 17 Call: lid=17 id=1032 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[9892703830] Type=National Plan=ISDN
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=918108204050
IE CMIEDeviceDetail (231) 0a00000200000408 LOCALE=enu HW=15 VER=10 class=CMDeviceSIPTrunk type=0 number=17 channel=2 features=0x1 rx_gain=32 tx_gain=32
ep_callid=1032 ipaddr=10.0.0.2 apps=0 loc=999 em_a_loc=999 em_d_loc=0 features2=0x0 is_spcall=1 ignores_dtmf=0 avgsid=
19:42:43 1250093mS CMARS: LINE ep Received: CMProceeding - child->state = CMCSOffering - ARS Call State = CMCSOverlapRecv
19:42:43 1250093mS CMCallEvt: 0a00000200000408 17.1032.0 8 SIPTrunk Endpoint: StateChange: END=child CMCSOffering->CMCSAccept
19:42:43 1250571mS PRN: +++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:42:43 1250571mS PRN: + loader: 1.45
19:42:43 1250571mS PRN: + cpu: id 12 board a pld 27 type c10 options ac02
19:42:43 1250571mS PRN: + cpu: sn 20TL3050005P
19:42:43 1250571mS PRN: + fpga: id 1 issue 0 build 827
19:42:43 1250571mS PRN: +++++++++++++++++++++++++++++++++++++++++++++++++++++++++++++
19:42:43 1250571mS PRN: ++++++++++++++++++++ LIST OF MODULES ++++++++++++++++++++
19:42:43 1250571mS PRN: +------------------------------------------------------------
19:42:43 1250571mS PRN: + Slot 1: Base COMBO6210 Board=0x04 PLD=0x09
19:42:43 1250571mS PRN: + Mezzanine ATM4 V2 Board=0x01 PLD=0x04
19:42:43 1250571mS PRN: +------------------------------------------------------------
19:42:43 1250571mS PRN: + Slot 2: Base DIGSTA8 Board=0x13 PLD=0x06
19:42:43 1250571mS PRN: + Mezzanine NONE
19:42:43 1250571mS PRN: +------------------------------------------------------------
19:42:43 1250571mS PRN: + Slot 3: Base NONE
19:42:43 1250571mS PRN: + Mezzanine NONE
19:42:43 1250571mS PRN: +------------------------------------------------------------
19:42:43 1250572mS PRN: + Slot 4: Base NONE
19:42:43 1250572mS PRN: + Mezzanine NONE
19:42:43 1250572mS PRN: +------------------------------------------------------------
19:42:43 1250572mS PRN: +++++++++++++++++ END OF LIST OF MODULES +++++++++++++++
19:42:43 1250572mS PRN: +++++++++++++++++ LIST OF MEMORY CARDS ++++++++++++++++++
19:42:43 1250572mS PRN: +------------------------------------------------------------
19:42:43 1250572mS PRN: + System SD Card: MNTD
19:42:43 1250572mS PRN: + CID Register: 0x0353445341303847803e4f5aa2013799
19:42:43 1250572mS PRN: + CSD Register: 0x400e00325b5900003b377f800a4040af
19:42:43 1250572mS PRN: + SCR Register: 0x0235844300000000
19:42:43 1250572mS PRN: + OCR Register: 0xc0ff8000
19:42:43 1250574mS PRN: + Status Register: 0x0000000003000000040090001405
19:42:43 1250574mS PRN: +------------------------------------------------------------
19:42:43 1250574mS PRN: + Optional SD Card: UMNT
19:42:43 1250574mS PRN: +------------------------------------------------------------
19:42:43 1250574mS PRN: ++++++++++++++ END OF LIST OF MEMORY CARDS +++++++++++++
19:42:43 1250574mS PRN: ++++++++++++++++++++ MEMORY CAPTURE +++++++++++++++++++++
19:42:43 1250574mS PRN: +------------------------------------------------------------
19:42:43 1250574mS PRN: + 0x00000005, 0x00000000, 0x00000000, 0x00000053, 0x00000000
19:42:43 1250574mS PRN: + 0x00000000, 0x00000000, 0x00000053, 0x00000001, 0x00000000
19:42:43 1250574mS PRN: + 0x00000001, 0x00000000, 0x00000000, 0x00000053, 0x00000000
19:42:43 1250574mS PRN: + 0x00000002, 0x00000000, 0x00000000, 0x00000000, 0x00000000
19:42:43 1250574mS PRN: + 0x00000000, 0x00000000, 0x00000000, 0x00000000, 0x00000000
19:42:43 1250574mS PRN: + 0x00000000, 0x00000000, 0x00000000, 0x00000000, 0x00000000
19:42:43 1250574mS PRN: + 0x00000000, 0x00000000, 0x00000000, 0x00000000, 0x00000000
19:42:43 1250574mS PRN: + 0x00000000, 0x00000000, 0x00000000, 0x00000000, 0x00000000
19:42:43 1250575mS PRN: + 0x00000000, 0x5f8b14e4, 0xc105a0f3, 0xdb9118a0, 0x064937b6
19:42:43 1250575mS PRN: + 0x00000000, 0x00000000, 0x00000000, 0x00000000, 0x00000000
19:42:43 1250575mS PRN: +------------------------------------------------------------
19:42:43 1250575mS PRN: +++++++++++++++++ END OF MEMORY CAPTURE ++++++++++++++++
19:42:44 1251402mS SIP Rx: UDP 10.0.0.1:5060 -> 10.0.0.2:5060
SIP/2.0 180 Ringing
From: "9895297539"<sip:9895297539@10.0.0.1>;tag=d08681363068dfa9
To: <sip:18108204050@10.0.0.1>;tag=4fbd5f78-7f000001-13c4-b075f-bd5b414a-b075f
Call-ID: 88974b6605e6eea04f61264de7ec627e
CSeq: 1908615602 INVITE
Via: SIP/2.0/UDP 10.0.0.2:5060;rport=5060;branch=z9hG4bKe788fc54d4e62b85ce94ffa2e96e0595
Contact: <sip:18108204050@10.0.0.1:5060;transport=UDP>
Supported: 100rel,replaces
Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, PRACK, REFER, REGISTER
User-Agent: ADTRAN_Total_Access_908e_3rd_Gen/R13.2.2.E
Content-Length: 0

19:42:44 1251405mS CMLineRx: v=0
CMAlerting
Line: type=SIPLine 17 Call: lid=17 id=1032 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[9892703830] Type=National Plan=ISDN
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=918108204050
IE CMIEDeviceDetail (231) 0a00000200000408 LOCALE=enu HW=15 VER=10 class=CMDeviceSIPTrunk type=0 number=17 channel=2 features=0x1 rx_gain=32 tx_gain=32
ep_callid=1032 ipaddr=10.0.0.2 apps=0 loc=999 em_a_loc=999 em_d_loc=0 features2=0x0 is_spcall=1 ignores_dtmf=0 avgsid=
19:42:44 1251405mS CMARS: LINE ep Received: CMAlerting - child->state = CMCSAccept - ARS Call State = CMCSOverlapRecv
19:42:44 1251405mS CMARS: LINE IS NOW THE B END OF THE CALL - CMARSEndpoint::RequestEnd()
19:42:44 1251405mS CMARS: CMARSEndpoint::CallLost(cause=124) - Address: 0a00000200000405 0.1031.0 8 ARS for Main - Call State: CMCSOverlapRecv
19:42:44 1251405mS CMCallEvt: 0a00000200000405 0.1031.0 -1 ARS for Main: StateChange: END=X CMCSOverlapRecv->CMCSDelete
19:42:44 1251406mS CMCallEvt: 0a00000200000408 17.1032.0 8 SIPTrunk Endpoint: StateChange: END=B CMCSAccept->CMCSRinging
19:42:44 1251406mS CMCallEvt: 0a00000200000405 0.1029.0 8 GHOST:: StateChange: END=A CMCSDialled->CMCSRingBack
19:42:44 1251407mS CMCallEvt: 0a00000200000405 0.1031.0 -1 BaseEP: DELETE CMEndpoint f179d174 TOTAL NOW=5 CALL_LIST=2
19:42:44 1251609mS RES: Thu 9/2/2023 19:42:45 FreeMem=56967952 Heap=56814044(3) Cache=153908 MemObjs=9714(Max 10097) CMMsg=5(6) ASN=0 Buff=5200 1363 1000 7443 5 Links=51992(52106) BTree=580(916) CB=6066 CPU=06.31% CPUStats=06.40%/6/9/5861/16266/16988/
00.00%/0/02.49% MCR=0 MCW=0 DEV=0
19:42:44 1251609mS RES2: IP 500 V2 10.1.0.8.0 build 3 Tasks=51 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=11+47 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 TCP=19(TLS=5 OFF=0) TAPI=0 Partner=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
19:42:44 1251609mS RES4: XML MemObjs=8 PoolMem=4748404(2) FreeMem=4736284(0) HeapUsed=0
19:42:44 1251609mS RES5: CLog MemObjs=138 FreePoolMem(Objs)=3224(62) TotalMem=10400 StringsTotalMem=80150
19:42:47 1253554mS SIP Rx: UDP 10.0.0.1:5060 -> 10.0.0.2:5060
SIP/2.0 200 OK
From: "9895297539"<sip:9895297539@10.0.0.1>;tag=d08681363068dfa9
To: <sip:18108204050@10.0.0.1>;tag=4fbd5f78-7f000001-13c4-b075f-bd5b414a-b075f
Call-ID: 88974b6605e6eea04f61264de7ec627e
CSeq: 1908615602 INVITE
Via: SIP/2.0/UDP 10.0.0.2:5060;rport=5060;branch=z9hG4bKe788fc54d4e62b85ce94ffa2e96e0595
Contact: <sip:18108204050@10.0.0.1:5060;transport=UDP>
Supported: 100rel,replaces
Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, PRACK, REFER, REGISTER
User-Agent: ADTRAN_Total_Access_908e_3rd_Gen/R13.2.2.E
Content-Type: application/sdp
Content-Length: 206

v=0
o=BroadWorks 2424012841 1 IN IP4 10.0.0.1
s=-
c=IN IP4 10.0.0.1
t=0 0
m=audio 13284 RTP/AVP 0 97
a=sendrecv
a=maxptime:20
a=rtpmap:0 PCMU/8000
a=rtpmap:97 telephone-event/8000
a=fmtp:97 0-15
19:42:47 1253558mS SIP Tx: UDP 10.0.0.2:5060 -> 10.0.0.1:5060
ACK sip:18108204050@10.0.0.1:5060;transport=UDP SIP/2.0
Via: SIP/2.0/UDP 10.0.0.2:5060;rport;branch=z9hG4bKbc3812750ad8b0720880711b9acff2b0
From: "9895297539" <sip:9895297539@10.0.0.1>;tag=d08681363068dfa9
To: <sip:18108204050@10.0.0.1>;tag=4fbd5f78-7f000001-13c4-b075f-bd5b414a-b075f
Call-ID: 88974b6605e6eea04f61264de7ec627e
CSeq: 1908615602 ACK
Max-Forwards: 70
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,NOTIFY,UPDATE
User-Agent: IP Office 10.1.0.8.0 build 3
Content-Length: 0

19:42:47 1253559mS CMLineRx: v=0
CMConnect
Line: type=SIPLine 17 Call: lid=17 id=1032 in=0
Called[9895297539] Type=Default (100) Reason=CMDRdirect Calling[9892703830] Type=National Plan=ISDN
IE CMIEFastStartInfoData (6) 2 item(s)
IE CMIEFastStartInfoData (6) 2 item(s)
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=918108204050
IE CMIEDeviceDetail (231) 0a00000200000408 LOCALE=enu HW=15 VER=10 class=CMDeviceSIPTrunk type=0 number=17 channel=2 features=0x0 rx_gain=32 tx_gain=32
ep_callid=1032 ipaddr=10.0.0.2 apps=0 loc=999 em_a_loc=999 em_d_loc=0 features2=0x0 is_spcall=1 ignores_dtmf=0 avgsid=
19:42:47 1253560mS CMCallEvt: 0a00000200000408 17.1032.0 8 SIPTrunk Endpoint: StateChange: END=B CMCSRinging->CMCSConnReq
19:42:47 1253560mS CMCallEvt: 0a00000200000405 0.1029.0 8 GHOST:: StateChange: END=A CMCSRingBack->CMCSOGConnReq
19:42:47 1253562mS CMExtnRx: v=211, p1=0
CMConnect
Line: type=NoLine 0 Call: lid=0 id=-1 in=0
IE CMIEFastStartInfoData (6) 2 item(s)
IE CMIEFastStartInfoData (6) 2 item(s)
19:42:47 1253563mS CMCallEvt: 0a00000200000409 0.1033.0 7 Test Phone.0: StateChange: END=T CMCSRinging->CMCSConnReq
19:42:47 1253563mS CMCallEvt: 0000000000000000 0.1027.0 7 TargetingEP: RequestEnd 0a00000200000409 0.1033.0 7 Test Phone.0
19:42:47 1253563mS CMTARGET: 0a00000200000402 17.1026.1 7 SIPTrunk Endpoint: CancelTimer CMTCCoverageTimeout
19:42:47 1253563mS CMCallEvt: 0000000000000000 0.1027.0 -1 BaseEP: DELETE CMEndpoint f17c0af8 TOTAL NOW=4 CALL_LIST=2
19:42:47 1253564mS CMExtnEvt: v=-1 State, new=Connected old=Ringing,0,0,Test Phone
19:42:47 1253564mS CMCallEvt: 0a00000200000402 17.1026.1 7 SIPTrunk Endpoint: StateChange: END=A CMCSRingBack->CMCSOGConnReq
19:42:47 1253569mS CMLineTx: v=0
CMConnect
Line: type=SIPLine 17 Call: lid=17 id=1026 in=1
IE CMIESupplementaryService (3)
Network Facility Extension
sourceEntity: 0
destinationEntity: 0
Interpretation APDU
discardAnyUnrecognisedInvokePdu
ConnectedName.Invoke.CodePageISO8859-1
invokeId 14631
user 'Test Phone' presentation Allowed
IE CMIEFastStartInfoData (6) 2 item(s)
IE CMIERespondingPartyName (228)(Type=CMNameInternalUser) name=Test Phone
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=211
IE CMIEDeviceDetail (231) 0a00000200000409 LOCALE=enu HW=15 VER=10 class=CMDeviceGhostPhone type=56 number=1 channel=0 features=0x10000000 rx_gain=32 tx_gain=32
ep_callid=1033 ipaddr=10.0.0.2 apps=0 loc=0 em_a_loc=0 em_d_loc=0 features2=0x0 is_spcall=0 ignores_dtmf=0 avgsid=
IE CMIESecurityData (253) 2 item(s)
CMSecurityElement RX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 1)
}
CMSecurityElement TX_ONLY {
CMSRTPCryptoCapability none
Codecs list (size 1)
}
19:42:47 1253571mS SIP Tx: UDP 10.0.0.2:5060 -> 10.0.0.1:5060
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.0.0.1:5060;branch=z9hG4bK-b075e-2b14c752-54ddd80c
From: "MID MICHIGAN CO" <sip:9895297539@10.0.0.2:5060;transport=UDP>;tag=4fbd7c78-7f000001-13c4-b075e-b0d3269d-b075e
Call-ID: 4fcba8b0-7f000001-13c4-b075e-e5c01179-b075e@10.0.0.2
CSeq: 1 INVITE
Contact: "Test Phone" <sip:9892703830@10.0.0.2:5060;transport=udp>
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,NOTIFY,UPDATE
P-Preferred-Identity: "Test Phone" <sip:211@10.0.0.2:5060>
Supported: timer,100rel
Server: IP Office 10.1.0.8.0 build 3
To: <sip:9892703830@10.0.0.2:5060>;tag=74e2a0151050427c
Content-Type: application/sdp
Content-Length: 192

v=0
o=UserA 397236039 1826516150 IN IP4 10.0.0.2
s=Session SDP
c=IN IP4 10.0.0.2
t=0 0
m=audio 10002 RTP/AVP 0 97
a=rtpmap:0 PCMU/8000
a=rtpmap:97 telephone-event/8000
a=fmtp:97 0-15
19:42:47 1253573mS CMLineTx: v=0
CMFacility
Line: type=SIPLine 17 Call: lid=17 id=1032 in=0
19:42:47 1253573mS CMExtnTx: v=211, p1=0
CMFacility
Line: type=SIPLine 17 Call: lid=0 id=1033 in=0
19:42:47 1253575mS CMCallEvt: 0a00000200000405 0.1029.0 8 GHOST:: StateChange: END=A CMCSOGConnReq->CMCSConnected
19:42:47 1253575mS CMTARGET: 0a00000200000405 0.1029.0 8 GHOST:: ~CMTargetHandler f17a04b0 ep f17c53c4
19:42:47 1253575mS CMCallEvt: 0a00000200000408 17.1032.0 8 SIPTrunk Endpoint: StateChange: END=B CMCSConnReq->CMCSConnected
19:42:47 1253577mS CMLineTx: v=0
CMConnectAck
Line: type=SIPLine 17 Call: lid=17 id=1032 in=0
IE CMIERespondingPartyName (228)(Type=CMNameDefault) name=Test Phone
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:101 N:100 R:4) number=211
IE CMIEDeviceDetail (231) 0a00000200000405 LOCALE=enu HW=15 VER=10 class=CMDeviceGhostHandler type=2 number=0 channel=0 features=0x20 rx_gain=32 tx_gain=32
ep_callid=1029 ipaddr=10.0.0.2 apps=0 loc=0 em_a_loc=0 em_d_loc=0 features2=0x0 is_spcall=0 ignores_dtmf=0 avgsid=
19:42:47 1253579mS H323Evt: SESS 5 SetOperational local 10.0.0.2:0 remote 0.0.0.0:0 to 0
19:42:47 1253579mS H323Evt: SESS 5 Configure: Ulaw64K packet size 160
19:42:47 1253579mS H323Evt: SESS 5 SetRemUDP 0 -> 13284, remote IP 0.0.0.0 -> 10.0.0.1
19:42:47 1253579mS H323Evt: SESS 5 SetRfc2833: (1) rx payload 97 tx payload 97
19:42:47 1253579mS H323Evt: SESS 5 SetHnt (rtp keepalives): enabled 3, send_initial_packet 1, send_periodic_packets 30
19:42:47 1253580mS CMMap: PCG::MapBChan pcp[94]b0r1 cp_b 0 other_cp_b 0 type CGTypeSimple
19:42:47 1253580mS H323Evt: SESS 6 SetOperational local 10.0.0.2:0 remote 0.0.0.0:0 to 0
19:42:47 1253580mS H323Evt: SESS 6 Configure: Ulaw64K packet size 160
19:42:47 1253580mS H323Evt: SESS 6 SetRemUDP 0 -> 13280, remote IP 0.0.0.0 -> 10.0.0.1
19:42:47 1253580mS H323Evt: SESS 6 SetRfc2833: (1) rx payload 97 tx payload 97
19:42:47 1253580mS H323Evt: SESS 6 SetHnt (rtp keepalives): enabled 3, send_initial_packet 1, send_periodic_packets 30
19:42:47 1253581mS CMMap: PCG::MapBChan pcp[92]b0r1 cp_b 0 other_cp_b 0 type CGTypeSimple
19:42:47 1253581mS CMMap: PCG::MapBChan cp RTP local 10.0.0.2:10002 remote 10.0.0.1:13280 , cp_other RTP local: 10.0.0.2:10000 remote 10.0.0.1:13284
19:42:47 1253581mS CMMap: PCG::MapBChan cp oob 0 rfc_2833 1 , cp_other oob 0, rfc_2833 1, cp behind_nat 0 cp_other behind_nat 0
19:42:47 1253581mS PRN: rtpRelayFilter ioctl(open) rc: 0 rtpRelayId: 1
19:42:47 1253581mS CMMap: PCG::MapBChan rtpRelay Open got rtpRelayId: 1
19:42:47 1253581mS PRN: rtpRelayFilter configure rc: 0 rtpRelayId: 1
19:42:47 1253581mS CMMap: PCG::MapBChan rtpRelay Configure success rtpRelayId: 1
19:42:47 1253581mS CMMap: PCG::MapBChan rtpRelay Start success rtpRelayId: 1
19:42:47 1253581mS CMMap: pcp[92]b0r1 rtpRelayId: 1 SetRTPFilterState RTPFilterPaused(2) -> RTPFilterStarted(1)
19:42:47 1253581mS CMMap: pcp[94]b0r1 rtpRelayId: 1 SetRTPFilterState RTPFilterPaused(2) -> RTPFilterStarted(1)
19:42:47 1253582mS H323Evt: SESS 6 SetOperational local 10.0.0.2:10002 remote 10.0.0.1:13280 to 1
19:42:47 1253582mS H323Evt: SESS 5 SetOperational local 10.0.0.2:10000 remote 10.0.0.1:13284 to 1
19:42:47 1253582mS CMMap: a=0.19 b=0.0 Mapper::FreeCodec freed CMRTVocoder(f560ac80) resource busy 1, total 10
19:42:47 1253582mS CMMap: a=0.18 b=0.0 Mapper::FreeCodec freed CMRTVocoder(f560ad80) resource busy 0, total 10
19:42:47 1253742mS SIP Rx: UDP 10.0.0.1:5060 -> 10.0.0.2:5060
ACK sip:9892703830@10.0.0.2:5060;transport=udp SIP/2.0
From: "MID MICHIGAN CO" <sip:9895297539@10.0.0.2:5060;transport=UDP>;tag=4fbd7c78-7f000001-13c4-b075e-b0d3269d-b075e
To: <sip:9892703830@10.0.0.2:5060>;tag=74e2a0151050427c
Call-ID: 4fcba8b0-7f000001-13c4-b075e-e5c01179-b075e@10.0.0.2
CSeq: 1 ACK
Via: SIP/2.0/UDP 10.0.0.1:5060;branch=z9hG4bK-b0761-2b14d5ce-1f7829e4
Max-Forwards: 70
Supported: 100rel,replaces
Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, PRACK, REFER, REGISTER
User-Agent: ADTRAN_Total_Access_908e_3rd_Gen/R13.2.2.E
Contact: <sip:9895297539@10.0.0.1:5060;transport=UDP>
Content-Length: 0

19:42:47 1253745mS CMLineRx: v=0
CMConnectAck
Line: type=SIPLine 17 Call: lid=17 id=1026 in=1
Called[] Type=Default (100) Reason=CMDRdirect Calling[9895297539@10.0.0.1] Type=Unknown Plan=Default
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=9895297539@10.0.0.1
IE CMIEDeviceDetail (231) 0a00000200000402 LOCALE=enu HW=15 VER=10 class=CMDeviceSIPTrunk type=0 number=17 channel=1 features=0x20 rx_gain=32 tx_gain=32
ep_callid=1026 ipaddr=10.0.0.2 apps=0 loc=999 em_a_loc=999 em_d_loc=0 features2=0x0 is_spcall=1 ignores_dtmf=0 avgsid=
19:42:47 1253745mS CMCallEvt: 0a00000200000402 17.1026.1 7 SIPTrunk Endpoint: StateChange: END=A CMCSOGConnReq->CMCSConnected
19:42:47 1253745mS CMTARGET: 0a00000200000402 17.1026.1 7 SIPTrunk Endpoint: ~CMTargetHandler f17cd028 ep f17d1d38
19:42:47 1253746mS CMCallEvt: 0a00000200000409 0.1033.0 7 Test Phone.0: StateChange: END=B CMCSConnReq->CMCSConnected
19:42:47 1253748mS CMExtnTx: v=211, p1=0
CMConnectAck
Line: type=NoLine 0 Call: lid=0 id=1033 in=0
Called[] Type=Default (100) Reason=CMDRdirect Calling[9895297539@10.0.0.1] Type=Unknown Plan=Default
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=9895297539@10.0.0.1
IE CMIEDeviceDetail (231) 0a00000200000402 LOCALE=enu HW=15 VER=10 class=CMDeviceSIPTrunk type=0 number=17 channel=1 features=0x20 rx_gain=32 tx_gain=32
ep_callid=1026 ipaddr=10.0.0.2 apps=0 loc=999 em_a_loc=999 em_d_loc=0 features2=0x0 is_spcall=1 ignores_dtmf=0 avgsid=
19:42:51 1258611mS RES: Thu 9/2/2023 19:42:52 FreeMem=57017936 Heap=56858664(6) Cache=159272 MemObjs=9710(Max 10097) CMMsg=5(6) ASN=0 Buff=5200 1363 1000 7443 5 Links=52023(52106) BTree=580(916) CB=6064 CPU=06.80% CPUStats=07.39%/1/4/5861/16048/16988/
00.35%/0/02.96% MCR=0 MCW=0 DEV=0
19:42:51 1258611mS RES2: IP 500 V2 10.1.0.8.0 build 3 Tasks=51 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=11+52 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 TCP=19(TLS=5 OFF=0) TAPI=0 Partner=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
19:42:51 1258611mS RES4: XML MemObjs=8 PoolMem=4748404(2) FreeMem=4736284(0) HeapUsed=0
19:42:51 1258611mS RES5: CLog MemObjs=138 FreePoolMem(Objs)=3224(62) TotalMem=10400 StringsTotalMem=80150
19:42:58 1264799mS PRN: Optimizing BTree Lists Completed...Started:1264799 Duration:0 Size:81 Attempted:81

********** SysMonitor v11.1.0.1.0 build 95 [connected to 10.10.10.253 (Nagel Shippers)] **********
19:43:01 1267453mS PRN: Monitor Status IP 500 V2 10.1.0.8.0 build 3
19:43:01 1267454mS PRN: LAW=U PRI=0, BRI=0, ALOG=4, VCOMP=10, MDM=0, WAN=0, MODU=0 LANM=0 CkSRC=0 VMAIL=1(VER=2 TYP=3) 1-X=0 CALLS=2(TOT=8)

********** SysMonitor v11.1.0.1.0 build 95 [connected to 10.10.10.253 (Nagel Shippers)] **********
19:43:12 1279453mS PRN: Monitor Status IP 500 V2 10.1.0.8.0 build 3
19:43:12 1279453mS PRN: LAW=U PRI=0, BRI=0, ALOG=4, VCOMP=10, MDM=0, WAN=0, MODU=0 LANM=0 CkSRC=0 VMAIL=1(VER=2 TYP=3) 1-X=0 CALLS=2(TOT=8)
19:43:18 1284440mS SIP Rx: UDP 10.0.0.1:5060 -> 10.0.0.2:5060
BYE sip:9892703830@10.0.0.2:5060;transport=udp SIP/2.0
From: "MID MICHIGAN CO" <sip:9895297539@10.0.0.2:5060;transport=UDP>;tag=4fbd7c78-7f000001-13c4-b075e-b0d3269d-b075e
To: <sip:9892703830@10.0.0.2:5060>;tag=74e2a0151050427c
Call-ID: 4fcba8b0-7f000001-13c4-b075e-e5c01179-b075e@10.0.0.2
CSeq: 2 BYE
Via: SIP/2.0/UDP 10.0.0.1:5060;branch=z9hG4bK-b0780-2b154db5-6244af0b
Max-Forwards: 70
Supported: 100rel,replaces
Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, PRACK, REFER, REGISTER
User-Agent: ADTRAN_Total_Access_908e_3rd_Gen/R13.2.2.E
Content-Length: 0

19:43:18 1284444mS SIP Tx: UDP 10.0.0.2:5060 -> 10.0.0.1:5060
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.0.0.1:5060;branch=z9hG4bK-b0780-2b154db5-6244af0b
From: "MID MICHIGAN CO" <sip:9895297539@10.0.0.2:5060;transport=UDP>;tag=4fbd7c78-7f000001-13c4-b075e-b0d3269d-b075e
Call-ID: 4fcba8b0-7f000001-13c4-b075e-e5c01179-b075e@10.0.0.2
CSeq: 2 BYE
Supported: timer,100rel
Server: IP Office 10.1.0.8.0 build 3
To: <sip:9892703830@10.0.0.2:5060>;tag=74e2a0151050427c
Content-Length: 0

19:43:18 1284444mS CMLineRx: v=0
CMReleaseComp
Line: type=SIPLine 17 Call: lid=17 id=1026 in=1
Called[] Type=Default (100) Reason=CMDRdirect Calling[9895297539@10.0.0.1] Type=Unknown Plan=Default
IE CMIERespondingPartyNumber (230)(P:100 S:100 T:0 N:100 R:4) number=9895297539@10.0.0.1
IE CMIEDeviceDetail (231) 0a00000200000402 LOCALE=enu HW=15 VER=10 class=CMDeviceSIPTrunk type=0 number=17 channel=1 features=0x20 rx_gain=32 tx_gain=32
ep_callid=1026 ipaddr=10.0.0.2 apps=0 loc=999 em_a_loc=999 em_d_loc=0 features2=0x0 is_spcall=1 ignores_dtmf=0 avgsid=
Cause=16, Normal call clearing
19:43:18 1284445mS CMCallEvt: 0a00000200000402 17.1026.1 7 SIPTrunk Endpoint: StateChange: END=A CMCSConnected->CMCSCompleted
19:43:18 1284446mS CMLineTx: v=0
CMFacility
Line: type=SIPLine 17 Call: lid=17 id=1026 in=1
IE CMIEFastStartInfoData (6) 2 item(s)
19:43:18 1284447mS CMLOGGING: CALL:2023/02/0919:42,00:00:30,003,9895297539@10.0.0.1,I,211,211,,,,0,,"",0,n/a
19:43:18 1284447mS CMCallEvt: 0a00000200000402 17.1026.1 -1 SIPTrunk Endpoint: StateChange: END=X CMCSCompleted->CMCSDelete
19:43:18 1284448mS CMExtnEvt: Test Phone: CALL LOST (CMCauseNormal)
19:43:18 1284448mS CMExtnEvt: Test Phone: Extn(211) Calling Party Number(9895297539@10.0.0.1) Type(CMNTypeUnknown)
19:43:18 1284448mS CMCallEvt: 0a00000200000409 0.1033.0 -1 Test Phone.0: StateChange: END=X CMCSConnected->CMCSCompleted
19:43:18 1284449mS CMExtnEvt: v=-1 State, new=PortRecoverDelay old=Connected,0,0,Test Phone
19:43:18 1284449mS CMExtnTx: v=211, p1=0
CMReleaseComp
Line: type=NoLine 0 Call: lid=0 id=1033 in=0
Called[211] Type=Default (100) Reason=CMDRdirect Calling[9895297539@10.0.0.1] Type=Unknown Plan=Default
Cause=16, Normal call clearing
19:43:18 1284450mS CMExtnEvt: Test Phone: CMExtnHandler::SetCurrent( id: 1033->0 )
19:43:18 1284450mS CMCallEvt: 0a00000200000409 0.1033.0 -1 Test Phone.-1: StateChange: END=X CMCSCompleted->CMCSDelete
19:43:18 1284453mS CMCallEvt: 0a00000200000405 0.1029.0 8 GHOST:: StateChange: END=A CMCSConnected->CMCSCompleted
19:43:18 1284454mS CMLOGGING: CALL:2023/02/0919:42,00:00:30,002,9892703830,O,18108204050,918108204050,TestPhone,,,0,,"",0,n/a
19:43:18 1284454mS CMCallEvt: 0a00000200000405 0.1029.0 -1 GHOST:: CallLost (Ghost @) cause=CMCauseNormal
19:43:18 1284454mS CMCallEvt: 0a00000200000405 0.1029.0 -1 GHOST:: StateChange: END=X CMCSCompleted->CMCSDelete
19:43:18 1284455mS CMLineTx: v=0
CMReleaseComp
Line: type=SIPLine 17 Call: lid=17 id=1032 in=0
Cause=16, Normal call clearing
19:43:18 1284456mS SIP Tx: UDP 10.0.0.2:5060 -> 10.0.0.1:5060
BYE sip:18108204050@10.0.0.1:5060;transport=UDP SIP/2.0
Via: SIP/2.0/UDP 10.0.0.2:5060;rport;branch=z9hG4bK3ecfa6456c5312c766bfaffa06b1742d
From: "9895297539" <sip:9895297539@10.0.0.1>;tag=d08681363068dfa9
To: <sip:18108204050@10.0.0.1>;tag=4fbd5f78-7f000001-13c4-b075f-bd5b414a-b075f
Call-ID: 88974b6605e6eea04f61264de7ec627e
CSeq: 1908615603 BYE
Contact: "9892703830" <sip:9892703830@10.0.0.2:5060;transport=udp>
Max-Forwards: 70
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,NOTIFY,UPDATE
Supported: timer,100rel
Reason: Q.850;cause=16;text="Normal call clearing"
User-Agent: IP Office 10.1.0.8.0 build 3
Content-Length: 0

19:43:18 1284457mS CMCallEvt: 0a00000200000408 17.1032.0 -1 SIPTrunk Endpoint: StateChange: END=X CMCSConnected->CMCSDelete
19:43:18 1284458mS CMCallEvt: 0a00000200000408 17.1032.0 -1 BaseEP: DELETE CMEndpoint f179b1ac TOTAL NOW=3 CALL_LIST=0
19:43:18 1284458mS CMCallEvt: END CALL:8 (f17c4e90)
19:43:18 1284459mS CMCallEvt: 0a00000200000405 0.1029.0 -1 BaseEP: DELETE CMEndpoint f17c53c4 TOTAL NOW=2 CALL_LIST=0
19:43:18 1284459mS CMCallEvt: 0a00000200000409 0.1033.0 -1 BaseEP: DELETE CMEndpoint f17967a4 TOTAL NOW=1 CALL_LIST=0
19:43:18 1284459mS CMCallEvt: END CALL:7 (f17c2648)
19:43:18 1284461mS CMCallEvt: 0a00000200000402 17.1026.1 -1 BaseEP: DELETE CMEndpoint f17d1d38 TOTAL NOW=0 CALL_LIST=0
19:43:18 1284462mS SIP Rx: UDP 10.0.0.1:5060 -> 10.0.0.2:5060
SIP/2.0 200 OK
From: "9895297539"<sip:9895297539@10.0.0.1>;tag=d08681363068dfa9
To: <sip:18108204050@10.0.0.1>;tag=4fbd5f78-7f000001-13c4-b075f-bd5b414a-b075f
Call-ID: 88974b6605e6eea04f61264de7ec627e
CSeq: 1908615603 BYE
Via: SIP/2.0/UDP 10.0.0.2:5060;rport=5060;branch=z9hG4bK3ecfa6456c5312c766bfaffa06b1742d
Supported: 100rel,replaces
Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, PRACK, REFER, REGISTER
User-Agent: ADTRAN_Total_Access_908e_3rd_Gen/R13.2.2.E
Content-Length: 0

19:43:18 1284465mS CMMap: PCG::UnmapBChan pcp[94]b0r1 cp_b 0 other_cp_b 0
19:43:18 1284465mS H323Evt: SESS 6 SetOperational local 10.0.0.2:10002 remote 10.0.0.1:13280 to 0
19:43:18 1284465mS PRN: rtpRelayFilter close rc: 0 rtpRelayId: 1
19:43:18 1284465mS CMMap: PCG::UMMapBChan rtpRelayId: 1 Close success
19:43:18 1284465mS CMMap: PCG::UnmapBChan pcp[92]b0r1 cp_b 0 other_cp_b 0
19:43:18 1284466mS H323Evt: SESS 5: RTP(END): 10.0.0.2/10000 10.0.0.1/13284 CODEC=Ulaw64K(5) PKTSZ=160 RFC2833=on AGE=30886 SENT=1501 RECV=1545 RTdelay=0 jitter=0 loss=256 remotejitter=0 remoteloss=0
19:43:18 1284466mS H323Evt: SESS 6: RTP(END): 10.0.0.2/10002 10.0.0.1/13280 CODEC=Ulaw64K(5) PKTSZ=160 RFC2833=on AGE=30886 SENT=1542 RECV=1504 RTdelay=0 jitter=0 loss=0 remotejitter=0 remoteloss=0
19:43:18 1284516mS RES: Thu 9/2/2023 19:43:18 FreeMem=57071384 Heap=56826588(4) Cache=244796 MemObjs=9233(Max 10097) CMMsg=5(6) ASN=0 Buff=5200 1363 1000 7443 5 Links=51913(52106) BTree=574(916) CB=6072 CPU=06.35% CPUStats=07.58%/21/25/5861/15998/1698
8/00.33%/0/02.94% MCR=0 MCW=0 DEV=0
19:43:18 1284516mS RES2: IP 500 V2 10.1.0.8.0 build 3 Tasks=51 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=11+48 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 TCP=19(TLS=5 OFF=0) TAPI=0 Partner=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
19:43:18 1284517mS RES4: XML MemObjs=8 PoolMem=4748404(2) FreeMem=4736284(0) HeapUsed=0
19:43:18 1284517mS RES5: CLog MemObjs=138 FreePoolMem(Objs)=3224(62) TotalMem=10400 StringsTotalMem=80150
19:43:18 1284559mS CMExtnEvt: Test Phone:211 ExtnFault now 1
19:43:20 1286869mS SIP Tx: UDP 10.0.0.2:5060 -> 10.0.0.1:5060
OPTIONS sip:10.0.0.1 SIP/2.0
Via: SIP/2.0/UDP 10.0.0.2:5060;rport;branch=z9hG4bK1744180d59d48ba62b8127bcc8a5d828
From: <sip:10.0.0.1>;tag=7cd826f8f1ba967a
To: <sip:10.0.0.1>
Call-ID: 5bc69ffe12bf3620298d1d917d88621a
CSeq: 1544552681 OPTIONS
Contact: <sip:10.0.0.2:5060;transport=udp>
Max-Forwards: 70
Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,NOTIFY,UPDATE
Supported: timer
User-Agent: IP Office 10.1.0.8.0 build 3
Content-Length: 0

19:43:20 1286874mS SIP Rx: UDP 10.0.0.1:5060 -> 10.0.0.2:5060
SIP/2.0 200 OK
From: <sip:10.0.0.1>;tag=7cd826f8f1ba967a
To: <sip:10.0.0.1>;tag=4fbd6318-7f000001-13c4-b0782-a9f82578-b0782
Call-ID: 5bc69ffe12bf3620298d1d917d88621a
CSeq: 1544552681 OPTIONS
Via: SIP/2.0/UDP 10.0.0.2:5060;rport=5060;branch=z9hG4bK1744180d59d48ba62b8127bcc8a5d828
Supported: 100rel,replaces
Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, PRACK, REFER, REGISTER
User-Agent: ADTRAN_Total_Access_908e_3rd_Gen/R13.2.2.E
Content-Length: 0

19:43:22 1289513mS PRN: BackupTask SMDR_BACKUP
19:43:22 1289517mS RES: Thu 9/2/2023 19:43:23 FreeMem=57108456 Heap=56880608(3) Cache=227848 MemObjs=9108(Max 10097) CMMsg=5(6) ASN=0 Buff=5200 1363 1000 7443 5 Links=51906(52106) BTree=572(916) CB=6072 CPU=06.41% CPUStats=06.54%/1/4/5861/16287/16988/
00.00%/0/02.52% MCR=0 MCW=0 DEV=0
19:43:22 1289518mS RES2: IP 500 V2 10.1.0.8.0 build 3 Tasks=51 RTEngine=0 CMRTEngine=0 ExRTEngine=0 Timer=11+45 Poll=0 Ready=0 CMReady=0 CMQueue=0 VPNNQueue=0 Monitor=1 SSA=0 TCP=19(TLS=5 OFF=0) TAPI=0 Partner=0 ASC=1 SYS=MNTD OPT=UMNT SDSPD=2034
19:43:22 1289518mS RES4: XML MemObjs=8 PoolMem=4748404(2) FreeMem=4736284(0) HeapUsed=0
19:43:22 1289518mS RES5: CLog MemObjs=138 FreePoolMem(Objs)=3224(62) TotalMem=10400 StringsTotalMem=80150

********** SysMonitor v11.1.0.1.0 build 95 [connected to 10.10.10.253 (Nagel Shippers)] **********
19:43:24 1291455mS PRN: Monitor Status IP 500 V2 10.1.0.8.0 build 3
19:43:24 1291455mS PRN: LAW=U PRI=0, BRI=0, ALOG=4, VCOMP=10, MDM=0, WAN=0, MODU=0 LANM=0 CkSRC=0 VMAIL=1(VER=2 TYP=3) 1-X=0 CALLS=0(TOT=8)