SIP Trunk between Cisco 2821 Router

[drlechowicz]

I am having problems calling from the Cisco phone to the Alcatel Phone. Over a SIP trunk using a cisco 2821 Cube Router. If I remove the cisco router from the SIP External Gateways, I can get the SIP call to ring the alcatel phone. But when someone answers it goes to fast busy. If I have it set up as an external gateway in the Alcatel, I get a 404 message. Here's the debugs. Any help would be greatly appreciated. Thanks.

Dirk

traced started ...
1348717308 -> main.trace.timer = 480mn
1348717314 -> RECEIVE MESSAGE FROM NETWORK (192.168.2.1:60120 [UDP])
----------------------utf8-----------------------
INVITE sip:101@192.168.2.12:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.2.1:5060;branch=z9hG4bK18E6E
Remote-Party-ID: "Anonymous" <sip:anonymous@192.168.2.1>;party=calling;screen=no;privacy=full
From: "anonymous" <sip:anonymous@192.168.2.1>;tag=4B42070-20AE
To: <sip:101@192.168.2.12>
Date: Thu, 27 Sep 2012 02:54:51 GMT
Call-ID: 8ABCC208-78511E2-85529353-53103EB5@192.168.2.1
Supported: 100rel,timer,resource-priority,replaces
Min-SE: 1800
Cisco-Guid: 3014546688-65536-2469-4203004170
User-Agent: Cisco-SIPGateway/IOS-12.x
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
CSeq: 101 INVITE
Timestamp: 1348714491
Contact: <sip:anonymous@192.168.2.1:5060>
Expires: 180
Allow-Events: telephone-event
Max-Forwards: 69
Content-Type: application/sdp
Content-Disposition: session;handling=required
Content-Length: 266

v=0
o=CiscoSystemsSIP-GW-UserAgent 5264 188 IN IP4 192.168.2.1
s=SIP Call
c=IN IP4 192.168.2.1
t=0 0
m=audio 16524 RTP/AVP 18 101
c=IN IP4 192.168.2.1
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=ptime:20
-------------------------------------------------

1348717314 -> 9 [CRequest::CRequest] Creation of a request (RECEIVED FROM NETWORK)
1348717314 -> [CIOCom::receiveRequest] Received CRequest 9 ref=1
1348717314 -> [CDispatcher::onIncomingRequest()]
1348717314 -> [CDispatcher::processRequestFromManagerIndex()] start at index 0
1348717314 -> 102 [CCallManager::onIncomingRequest()]
1348717314 -> [CMotorCallManager::callFactory] For call : 8ABCC208-78511E2-85529353-53103EB5@192.168.2.1.
1348717314 -> 1105 [CCall::receiveRequest] INVITE
1348717314 -> 1105 [CCall::getDialog] Confirmed Dialog is not found (ID = ;4B42070-20AE)
1348717314 -> 1105 [CCall::getDialog] Returning Initial Dialog Server
1348717314 -> 1505 [CDialog::receiveRequest]
1348717314 -> 1505 [CDialog::receiveInviteRequest]
1348717314 -> 2105 [CTransaction::changeState] STATE CHANGED TO INITIAL
1348717314 -> 2105 [CTransaction::changeState] notifying the parent dialog
1348717314 -> 1505 [CDialog::onTransactionState(pTrans = 2105, previousState = Terminated, currentState = Initial, reason = None]
1348717314 -> 2105 [CTransInitialState::receiveRequest] Transaction changes to Proceeding state
1348717314 -> 2105 [CTransaction::changeState] STATE CHANGED TO PROCEEDING
1348717314 -> 2105 [CTransaction::changeState] notifying the parent dialog
1348717314 -> 1505 [CDialog::onTransactionState(pTrans = 2105, previousState = Initial, currentState = Proceeding, reason = Request reception]
1348717314 -> 2105 [CTransProceedingState::createResponse] CResponse 9 ref=1
1348717314 -> 9 [CMessage::send] sip_sendMessage (192.168.2.1:5060)
1348717314 -> SEND MESSAGE TO NETWORK (192.168.2.1:5060 [UDP]) (BUFF LEN = 286)
----------------------utf8-----------------------
SIP/2.0 100 Trying
To: <sip:101@192.168.2.12>
From: "anonymous" <sip:anonymous@192.168.2.1>;tag=4B42070-20AE
Call-ID: 8ABCC208-78511E2-85529353-53103EB5@192.168.2.1
CSeq: 101 INVITE
Via: SIP/2.0/UDP 192.168.2.1:5060;branch=z9hG4bK18E6E
Timestamp: 1348714491
Content-Length: 0

-------------------------------------------------
1348717314 -> 1505 [CDialog::receiveInviteRequest] a new Server INVITE transaction is in progress
1348717314 -> [---*--- SESSION-TIMER ---*---] New CSessionTimerContext from request (Server, UA)

1348717314 -> [---*--- SESSION-TIMER ---*---] Update CSessionTimerContext (refresh reception)

1348717314 -> [---*--- SESSION-TIMER ---*---] Min-SE updated : 1800

1348717314 -> 1105 [CCall::checkAuthentication] INVITE
1348717314 -> 1105[CMotorCall::controlAuthentication] ---*--- AUTH ---*--- Control authentication
1348717314 -> [CGwMngData::isAuthenticationRequiredForThisClient] 192.168.2.1 .
1348717314 -> [isDomainFromGwExt] Host from request is : 192.168.2.1.
1348717314 -> [isDomainFromGwExt] User from request is : anonymous
1348717314 -> [isDomainFromGwExt]-->gw_ext_save=0
1348717314 -> domain from External Gateway 0.
1348717314 -> 1105[CMotorCall::toGatewayOrProxy] request for proxydomain=192.168.2.12.
1348717314 -> [isUserRegistred] getRegistration=101.
1348717314 -> [isUserRegistred] result=-1.
1348717314 -> 1105[CMotorCall::onReceiveRequest] request for gateway.
1348717314 -> 1105[CMotorCall::onReceiveRequest] state = INITIAL_STATE.
1348717314 -> 1105[CMotorCall::methodInviteReceived] nb available licenses=12 .
1348717314 -> 1105[CMotorCall::compareDialogFromContext] presumed to be the same Dialog
1348717314 -> 1105[CMotorCall::methodInviteReceived] Invite => update the inviteContext.
1348717314 -> [CForkedBranch::CForkedBranch] context = 0x839e29c.
1348717314 -> 1105[CMotorCall::methodInviteReceived] CAC = 0
1348717314 -> [receiveInviteMessage] Call: 8ABCC208-78511E2-85529353-53103EB5@192.168.2.1 eqt: -1 INITIAL_STATE received a message.
1348717314 -> [decodeInviteCLIR].
1348717314 -> [decodeInvitePAssertedId].
1348717314 -> [isDomainFromGwExt] Host from request is : 192.168.2.1.
1348717314 -> [isDomainFromGwExt] User from request is : anonymous
1348717314 -> [isDomainFromGwExt]-->gw_ext_save=0
1348717314 -> [isValidGwExt] ext gw 0 is valid
1348717314 -> [isSessionTimerTooSmall].
1348717314 -> [decodeInviteSDP].
1348717314 -> [checkSdpValidity] Sdp contains 1 media description(s).
1348717314 -> [checkSdpValidity] Media 0 type 1 contains 2 formats.
1348717314 -> [checkSdpValidity] Format : 18.
1348717314 -> [convertSdpIntoTsdp] Sdp contains 1 media description(s).
1348717314 -> [convertSdpIntoTsdp] The audio media contains 2 format(s).
1348717314 -> [convertSdpIntoTsdp] Format 0 is 18.
1348717314 -> [convertSdpIntoTsdp] Format 1 is 101.
1348717314 -> [convertSdpIntoTsdp] Get the direction.
1348717314 -> [convertSdpIntoTsdp] Direction is sendrecv.
1348717314 -> [convertSdpIntoTsdp] The connection is at media level.
1348717314 -> [convertSdpIntoTsdp] Connection address retrieved in sdp: 192.168.2.1.
1348717314 -> [convertIPStrIntoTuipv] 192.168.2.1 => 192.168.2.1
1348717314 -> [isCACWithInvalidMediaAddr].
1348717314 -> [decodeInviteTo].
1348717314 -> [decodeInviteReferredBy].
1348717314 -> [receiveInviteMessage] ReferBy = Zero indirect=1
1348717314 -> [decodeInviteACSBU].
1348717314 -> 1105[CMotorCall::emitEventToMonitel] content size (lginfo): 652
1348717314 -> [ipc_thread] IPC Thread : Ipc reception.
1348717314 -> [ipc_thread] IPC Thread : TCL thread signaled.
1348717314 -> [CMotorCallManager::insertCallwithEqt] CMotorCall 1017 inserted.
1348717314 -> 1105[CMotorCall::emitEventToMonitel] Event sent on eqt : 1017
1348717314 -> [display_ipc_out] ------------ Begin ---------------
1348717314 -> INVITE
1348717314 -> REQUEST URI : <> 101@192.168.2.12:5060 ; user=name
1348717314 -> FROM : <anonymous> anonymous@192.168.2.1:5060 ; user=name
1348717314 -> TO : <> 101@192.168.2.12:5060 ; user=name
1348717314 -> CAC : 0
1348717314 -> CAC ADDRESS :
1348717314 -> CLIR : 0
1348717314 -> SDP :
1348717314 -> ADDRESS : 192.168.2.1 :16524
1348717314 -> ALGOS :
1348717314 -> G729
1348717314 -> 101
1348717314 -> DIRECTION : SEND & RECEIVE
1348717314 -> N_GW_EXT : 0
1348717314 -> [display_ipc_out] ------------- End ----------------
1348717314 -> [exec_ipc] in.
1348717314 -> [display_ipc_in] ------------ Begin ---------------
1348717314 -> neqt : 1017
1348717314 -> REQUEST FAILURE
1348717314 -> xx : 4
1348717314 -> RELATIVE REQUEST : INVITE
1348717314 -> [display_ipc_in] ------------- End ----------------
1348717314 -> [CMotorCallManager::onIncomingEvent] an event 10767 arrived on the eqt 1017.
1348717314 -> [CMotorCallManager::onIncomingEvent] state = PROCEEDING_STATE.
1348717314 -> [onIncomingEvent] REQUEST FAILURE arrived.
1348717314 -> [receiveRequestFailureEvent] Call: 8ABCC208-78511E2-85529353-53103EB5@192.168.2.1 eqt: 1017 PROCEEDING_STATE received an event.
1348717314 -> [receiveRequestFailureEvent] incoming call.
1348717314 -> 1105 [CCall::makeGenericResponse] 404
1348717314 -> 1105 [CCall::getDialog] Confirmed Dialog is not found (ID = ;4B42070-20AE)
1348717314 -> 1505 [CDialog::createResponse] 404
1348717314 -> 2105 [CTransProceedingState::createResponse] CResponse A ref=1
1348717314 -> A [CMessage::send] sip_sendMessage (192.168.2.1:5060)
1348717314 -> SEND MESSAGE TO NETWORK (192.168.2.1:5060 [UDP]) (BUFF LEN = 398)
----------------------utf8-----------------------
SIP/2.0 404 Not Found
Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, SUBSCRIBE, OPTIONS
User-Agent: ABS GW v5.1.0
To: <sip:101@192.168.2.12>;tag=73fbb74f8370bfcb6ef0bc25b65db3b3
From: "anonymous" <sip:anonymous@192.168.2.1>;tag=4B42070-20AE
Call-ID: 8ABCC208-78511E2-85529353-53103EB5@192.168.2.1
CSeq: 101 INVITE
Via: SIP/2.0/UDP 192.168.2.1:5060;branch=z9hG4bK18E6E
Content-Length: 0

-------------------------------------------------
1348717314 -> 9 [~CResponse] resp(100) 8ABCC208-78511E2-85529353-53103EB5@192.168.2.1 101 INVITE
1348717314 -> 2105 [CTransProceedingState::createResponse] Final : Transaction changes to Completed state
1348717314 -> 2105 [CTransaction::changeState] STATE CHANGED TO COMPLETED
1348717314 -> 2105 [CTransaction::changeState] notifying the parent dialog
1348717314 -> 1505 [CDialog::onTransactionState(pTrans = 2105, previousState = Proceeding, currentState = Completed, reason = Final resp creation]
1348717314 -> 2105 [CTransaction::startTimer] Timer G is started (delay = 500 ms)
1348717314 -> 2105 [CTransaction::startTimer] Timer H is started (delay = 32000 ms)
1348717314 -> 1505 [CDialog::updateRouteSet] there are 0 record-route headers
1348717314 -> RECEIVE MESSAGE FROM NETWORK (192.168.2.1:50195 [UDP])
----------------------utf8-----------------------
ACK sip:101@192.168.2.12:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.2.1:5060;branch=z9hG4bK18E6E
From: "anonymous" <sip:anonymous@192.168.2.1>;tag=4B42070-20AE
To: <sip:101@192.168.2.12>;tag=73fbb74f8370bfcb6ef0bc25b65db3b3
Date: Thu, 27 Sep 2012 02:54:51 GMT
Call-ID: 8ABCC208-78511E2-85529353-53103EB5@192.168.2.1
Max-Forwards: 70
CSeq: 101 ACK
Allow-Events: telephone-event
Content-Length: 0

-------------------------------------------------

1348717314 -> A [CRequest::CRequest] Creation of a request (RECEIVED FROM NETWORK)
1348717314 -> [CIOCom::receiveRequest] Received CRequest A ref=1
1348717314 -> [CDispatcher::onIncomingRequest()]
1348717314 -> [CDispatcher::processRequestFromManagerIndex()] start at index 0
1348717314 -> 102 [CCallManager::onIncomingRequest()]
1348717314 -> 1105 [CCall::receiveRequest] ACK
1348717314 -> 1105 [CCall::getDialog] Confirmed Dialog is not found (ID = 73fbb74f8370bfcb6ef0bc25b65db3b3;4B42070-20AE)
1348717314 -> 1505 [CDialog::receiveRequest]
1348717314 -> 1505 [CDialog::receiveAckRequest]
1348717314 -> 2105 [CTransCompletedState::receiveRequest] ACK(UDP) : Transaction changes to Confirmed state
1348717314 -> 2105 [CTransaction::changeState] STATE CHANGED TO CONFIRMED
1348717314 -> 2105 [CTransaction::changeState] notifying the parent dialog
1348717314 -> 1505 [CDialog::onTransactionState(pTrans = 2105, previousState = Completed, currentState = Confirmed, reason = Request reception]
1348717314 -> 2105 [CTransaction::freeTimerToken] Timer G is freed
1348717314 -> 2105 [CTransaction::freeTimerToken] Timer H is freed
1348717314 -> 2105 [CTransaction::startTimer] Timer I is started (delay = 5000 ms)
1348717314 -> 1505 [CDialog::receiveAckRequest] receiving an ACK on a previously rejected INVITE
1348717314 -> 1105 [CCall::checkAuthentication] ACK
1348717314 -> 1105[CMotorCall::controlAuthentication] ---*--- AUTH ---*--- Control authentication
1348717314 -> 1105[CMotorCall::controlAuthentication] ---*--- END ---*--- Control authentication
1348717314 -> 1105[CMotorCall::toGatewayOrProxy] request for proxydomain=192.168.2.12.
1348717314 -> [isUserRegistred] getRegistration=101.
1348717314 -> [isUserRegistred] result=-1.
1348717314 -> 1105[CMotorCall::onReceiveRequest] request for gateway.
1348717314 -> 1105[CMotorCall::onReceiveRequest] state = TERMINATED_STATE.
1348717314 -> 1105[CMotorCall::compareDialogFromContext] same Dialog
1348717314 -> 1105[CMotorCall::methodAckReceived] Ack => update the inviteContext.
1348717314 -> [receiveAckMessage] Call: 8ABCC208-78511E2-85529353-53103EB5@192.168.2.1 eqt: 1017 TERMINATED_STATE received a message.
1348717314 -> 1105[CMotorCall::emitEventToMonitel] content size (lginfo): 652
1348717314 -> 1105[CMotorCall::emitEventToMonitel] CAC = 0 eqtCAC = -1
1348717314 -> 1105[CMotorCall::emitEventToMonitel] CAC = 0
1348717314 -> [ipc_thread] IPC Thread : Ipc reception.
1348717314 -> [ipc_thread] IPC Thread : TCL thread signaled.
1348717314 -> 1105[CMotorCall::emitEventToMonitel] Event sent on eqt : 1017
1348717314 -> [display_ipc_out] ------------ Begin ---------------
1348717314 -> ACK
1348717314 -> [display_ipc_out] ------------- End ----------------
1348717314 -> [exec_ipc] in.
1348717314 -> [display_ipc_in] ------------ Begin ---------------
1348717314 -> neqt : 1017
1348717314 -> SIP EQT RELEASED
1348717314 -> [display_ipc_in] ------------- End ----------------
1348717314 -> [CMotorCallManager::onIncomingEvent] an event 10773 arrived on the eqt 1017.
1348717314 -> [CMotorCallManager::onIncomingEvent] The call with eqt: 1017 has released its equipment.
1348717314 -> 1105[CMotorCall::unRegister] Remove eqt : 1017 from the map.
1348717314 -> [CMotorCallManager::eraseCallwithEqt] CMotorCall 1017 erased.
1348717314 -> [CMotorCallManager::onIncomingEvent] state = TERMINATED_STATE.
1348717314 -> [CMotorCallManager::onIncomingEvent] killSession.
1348717314 -> 1105 [CCall::killSession]
1348717314 -> 2105 [CTransaction::changeState] STATE CHANGED TO TERMINATED
1348717314 -> 2105 [CTransaction::freeTimerToken] Timer I is freed
1348717314 -> 2105 [CTransaction::changeState] no transaction state
1348717314 -> 1105 [CCall::onKill Session Call manager close is called]
1348717314 -> 1105 [CCall::killSession] notifying the call manager
1348717314 -> 102 [CCallManager::onCallClosed]
1348717314 -> 102 [CCallManager::onCallClosed()] removing from the maps
1348717314 -> [CMotorCallManager::onNotifyCallClosed] Call : 8ABCC208-78511E2-85529353-53103EB5@192.168.2.1 is closed.
1348717314 -> [CMotorCallManager::onNotifyCallClosed] call processResponse.
1348717314 -> 1105[CMotorCall::processResponse] call chooseBestResponse.
1348717314 -> 1105[CMotorCall::chooseBestResponse] pForkedBranch=0x839ed30 iResponseCode=2000.
1348717314 -> [CForkedBranch::getResponseCode] response=2000.
1348717314 -> 1105[CMotorCall::chooseBestResponse] next branch.
1348717314 -> 1105[CMotorCall::processResponse] no response found.
1348717314 -> [CMotorCallManager::onNotifyCallClosed] Remove eqt from the map.
1348717314 -> [CCall::onKill] set callmanager to null
1348717314 -> [CTransaction::notifyDialog]
1348717314 -> [CTransaction::notifyDialog] notify parent dialog
1348717314 -> 1505 [CDialog::onTransactionState(pTrans = 2105, previousState = null, currentState = Terminated, reason = None]
1348717314 -> 1505 [CDialog::onTransactionState] dialog is terminated
1348717314 -> 1505 [CDialog::onTransactionState] onDialogClosed
1348717314 -> 1105 [CCall::onDialogClosed]
1348717314 -> 1105 [CCall::onDialogClosed] only initial *********
1348717314 -> 1105 [CCall::onDialogClosed] All the initial dialogs in the Call are closed
1348717314 -> A [~CRequest] req 8ABCC208-78511E2-85529353-53103EB5@192.168.2.1 101 ACK
1348717314 -> 102 [CCallManager::removeGarbage] at index i = 0
1348717314 -> 1105[CMotorCall::~CMotorCall] Call : 8ABCC208-78511E2-85529353-53103EB5@192.168.2.1 is being destructed.
1348717314 -> 1105 [CCall::~CCall]
1348717314 -> 1105 [CCall::removeGarbage]
1348717314 -> 1505 [CDialog::~CDialog]
1348717314 -> [---*--- SESSION-TIMER ---*---] Free CSessionTimerContext

1348717314 -> A [~CResponse] resp(404) 8ABCC208-78511E2-85529353-53103EB5@192.168.2.1 101 INVITE
1348717314 -> 9 [~CRequest] req 8ABCC208-78511E2-85529353-53103EB5@192.168.2.1 101 INVITE

(1)xa000000> timed out waiting for input: auto-logout

[dryhouse]

Try this:

Configure an external gateway in Alcatel with remote domain "anonymous" and you trunk sip...

Regards.