From 3cxphonesystem.trace.log
From 3cxphonesystem.trace.log:
11:46:21.746|ServerAuthManager.cxx(47)|Trace5|Resip|>>:ServerAuth requested credentials SipReq: INVITE
[email protected] tid=ac1157135403 cseq=INVITE
[email protected] / 2 from(wire)
11:46:21.746|ServerAuthManager.cxx(125)|Trace5|Resip|>>:Checking for auth result in realm=3CXPhoneSystem A1=
11:46:21.746|ServerAuthManager.cxx(210)|Trace5|Resip|>>:Authorized request for 3CXPhoneSystem
11:46:21.746|.\Authorization.cpp(294)|Trace5|Authorization|AuthMgr:

nAuthSuccess:
Caller "S,ARSEMENT & HA"<sip:337
#hidden@192.168.10.250>;tag=1c1156541311 has been authenticated successfully
11:46:21.746|DialogUsageManager.cxx(1190)|Trace5|Resip|>>:Got: SipReq: INVITE
[email protected] tid=ac1157135403 cseq=INVITE
[email protected] / 2 from(wire)
11:46:21.746|.\CallMgr.cpp(349)|Trace5|CallControl|ADSFactory::createAppDialogSet:Creating ADS for INVITE
11:46:21.746|.\CallConf.cpp(506)|Trace5|CallControl|CallConf::CallConf:Call created: 1
11:46:21.746|.\CallConf.cpp(445)|Trace5|CallControl|CallConf::addCallLeg:Added leg# 1 to call 1
11:46:21.746|.\Call.cpp(1372)|Trace5|CallControl|CallLegImpl::CallLegImpl:Leg @L:1@C:1 is created
11:46:21.746|InviteSession.cxx(2046)|Trace5|Resip|>>:Transition UAS_Start -> UAS_Offer
11:46:21.746|.\ISHandler.cpp(21)|Trace5|CallControl|ISHandler:

nNewSession:Incoming: sis=6619;oat=Offer;rl=INVITE sip:
[email protected];user=phone SIP/2.0
11:46:21.776|.\Endpoint.cpp(111)|Trace5|Endpoint|Endpoint::findSource:Lines Is here
11:46:21.776|.\Endpoint.cpp(126)|Trace5|Endpoint|Endpoint::findSource:Only one lines is here
11:46:21.776|.\Endpoint.cpp(132)|Trace5|Endpoint|Endpoint::findSource:Settings for caller is found: CfgExtLine:10800
11:46:21.776|.\Call.cpp(125)|Trace5|CallControl|CallLegImpl:

nIncoming:Endpoint is built: Ln:10800@AH_VOIP_GW
11:46:21.966|.\Call.cpp(693)|Trace5|CallControl|CallLegImpl::initMSEndpoint:Created internal MS endpoint for Ln:10800@AH_VOIP_GW: addr=192.168.10.8:7000
11:46:21.966|ServerInviteSession.cxx(91)|Trace5|Resip|>>:UAS_Offer: provisional(100)
11:46:21.966|InviteSession.cxx(2046)|Trace5|Resip|>>:Transition UAS_Offer -> UAS_EarlyOffer
11:46:21.966|.\ISHandler.cpp(140)|Trace5|CallControl|ISHandler:

nReadyToSend:InviteSession(6619) sends SIP/2.0 100 Trying
11:46:21.966|.\CallConf.cpp(120)|Log2|CallControl|CallConf:

nIncoming:[CM103002] Call(1): Incoming call from 337#hidden (Ln:10800@AH_VOIP_GW) to sip:
[email protected]
11:46:21.996|.\CallStrategies.cpp(439)|Trace5|CallManager|StratInOut::initialize:Initialize InOut call strategy
11:46:22.577|.\ExtLine.cpp(225)|Trace5|Endpoints|ExtLine::getTargetOverride:Look for target override
11:46:22.637|.\Call.cpp(693)|Trace5|CallControl|CallLegImpl::initMSEndpoint:Created internal MS endpoint for Ext.113: addr=192.168.10.8:7002
11:46:22.637|.\CallConf.cpp(445)|Trace5|CallControl|CallConf::addCallLeg:Added leg# 2 to call 1
11:46:22.637|.\Call.cpp(1372)|Trace5|CallControl|CallLegImpl::CallLegImpl:Leg 113@L:2@C:1 is created
11:46:22.637|.\CallConf.cpp(132)|Trace5|CallControl|CallConf:

nIncoming:MediaServer could be used for this call
11:46:22.637|.\ISHandler.cpp(157)|Trace5|CallControl|ISHandler:

nOffer:Offered: is=6619; rl=INVITE sip:
[email protected];user=phone SIP/2.0
11:46:22.637|.\CallStrategies.cpp(643)|Trace5|CallManager|StratInOut:

nOffer:InOut: got offer from 10800@L:1@C:1
11:46:22.637|.\CallEvents.cpp(78)|Trace5||FireStatusEvent:Fire event: Calling; DN 10800
11:46:22.727|DialogUsageManager.cxx(1190)|Trace5|Resip|>>:Got: SipResp: 100 tid=dc696f43aa366b3d cseq=INVITE
[email protected] / 1 from(wire)
11:46:22.877|DialogUsageManager.cxx(1190)|Trace5|Resip|>>:Got: SipResp: 180 tid=dc696f43aa366b3d cseq=INVITE
[email protected] / 1 from(wire)
11:46:22.877|Dialog.cxx(547)|Trace5|Resip|>>

ialog::dispatch, ignoring stray response: SipResp: 180 tid=dc696f43aa366b3d cseq=INVITE
[email protected] / 1 from(wire)
11:46:22.877|InviteSession.cxx(2046)|Trace5|Resip|>>:Transition UAC_Start -> UAC_Early
11:46:22.877|.\ISHandler.cpp(31)|Trace5|CallControl|ISHandler:

nNewSession:Answered: sis=6622;oat=None;rl=SIP/2.0 180 Ringing
11:46:22.887|.\CallStrategies.cpp(598)|Trace5|CallManager|StratInOut:

nAnswered:Call from Ln:10800@AH_VOIP_GW is answered on Ext.113
11:46:22.887|.\CallTarget.cpp(79)|Trace5|CallManager|CT_Simple::answered:Call is accepted on: Contact=[<sip:
[email protected]>]; From="337
#hidden"<sip:337
#hidden@192.168.10.250>;tag=2e783d54
11:46:22.887|.\CallEvents.cpp(78)|Trace5||FireStatusEvent:Fire event: Ringing; DN 113
11:46:22.887|ClientInviteSession.cxx(315)|Trace5|Resip|>>:UAC_Early: startStaleCallTimer
11:46:22.887|.\ISHandler.cpp(57)|Trace5|CallControl|ISHandler:

nProvisional

rovisional: cis=6622;rl=SIP/2.0 180 Ringing
11:46:22.887|.\CallConf.cpp(178)|Log2|CallControl|CallConf:

nProvisional:[CM103003] Call(1): Ext.113 is ringing
11:46:22.887|.\CallStrategies.cpp(658)|Trace5|CallManager|StratInOut:

nProvisional:InOut: got provisional SIP/2.0 180 Ringing from 113@L:2@C:1
11:46:22.887|.\CallEvents.cpp(78)|Trace5||FireStatusEvent:Fire event: Calling; DN 10800
11:46:22.887|.\CallEvents.cpp(78)|Trace5||FireStatusEvent:Fire event: Ringing; DN 113
11:46:22.887|ServerInviteSession.cxx(91)|Trace5|Resip|>>:UAS_EarlyOffer: provisional(180)
11:46:22.887|InviteSession.cxx(2046)|Trace5|Resip|>>:Transition UAS_EarlyOffer -> UAS_EarlyOffer
11:46:22.887|.\ISHandler.cpp(140)|Trace5|CallControl|ISHandler:

nReadyToSend:InviteSession(6619) sends SIP/2.0 180 Ringing
11:46:37.949|.\CallStrategies.cpp(781)|Trace5|CallManager|StratInOut:

nCancel:InOut: got Cancel from 113@L:2@C:1
11:46:37.949|.\CallTarget.cpp(90)|Trace5|CallManager|CT_Simple::failed:Call to target 113@L:2@C:1 is failed, resp=SIP/2.0 408
11:46:37.979|.\CallTarget.cpp(107)|Trace5|CallManager|CT_Simple::failed:Applying target for N/A call: CfgSpecMenu
11:46:37.979|.\CallEvents.cpp(78)|Trace5||FireStatusEvent:Fire event: OnHook; DN 113
11:46:37.979|.\CallTarget.cpp(1387)|Trace5|CallManager|CallTarget::create:Create SpecMenu Target
11:46:37.999|.\Call.cpp(1200)|Trace5|CallControl|CallLegImpl:

rovideAnswer

rovide answer (MS) to 10800@L:1@C:1
11:46:37.999|ServerInviteSession.cxx(251)|Trace5|Resip|>>:UAS_EarlyOffer: provideAnswer
11:46:37.999|InviteSession.cxx(2046)|Trace5|Resip|>>:Transition UAS_EarlyOffer -> UAS_EarlyProvidedAnswer
11:46:37.999|ServerInviteSession.cxx(443)|Trace5|Resip|>>:UAS_EarlyProvidedAnswer: accept(200)
11:46:37.999|InviteSession.cxx(2046)|Trace5|Resip|>>:Transition UAS_EarlyProvidedAnswer -> UAS_Accepted
11:46:37.999|.\ISHandler.cpp(140)|Trace5|CallControl|ISHandler:

nReadyToSend:InviteSession(6619) sends SIP/2.0 200 OK
11:46:37.999|.\ISHandler.cpp(74)|Trace5|CallControl|ISHandler:

nConnected:ConnectedUAS: is=6619;rl=SIP/2.0 200 OK
11:46:38.009|.\Call.cpp(253)|Trace5|CallControl|CallLegImpl:

nConnected:Connected: 10800@L:1@C:1
11:46:38.009|.\CallStrategies.cpp(684)|Trace5|CallManager|StratInOut:

nConnected:InOut: got Connected from 10800@L:1@C:1
11:46:38.129|.\CallEvents.cpp(78)|Trace5||FireStatusEvent:Fire event: Connected; DN 10800
11:46:38.179|.\Call.cpp(266)|Log2|CallControl|CallLegImpl:

nConnected:[CM103001] Call(1): Created audio channel for Ln:10800@AH_VOIP_GW (192.168.10.250:6000) with Media Server (192.168.10.8:7000)
11:46:38.179|ClientInviteSession.cxx(174)|Trace5|Resip|>>:UAC_Early: end