OXE6.1:DHCP&3d party SIP clients (sorry if posted twice)

cavagnaro · Post by **cavagnaro** » 09 Aug 2006 13:51

Follow the instructions that Frank made:

viewtopic.php?t=900

niko · Post by **niko** » 10 Aug 2006 10:29

cavagnaro wrote:Follow the instructions that Frank made:

viewtopic.php?t=900

Thanks Cavagnaro

Actually I went thru Frank's post and suggestions but still experiencing this issue.

Looks like the error that I get, if you can call it an error, is different; "Service Not Available, 503"

cavagnaro · Post by **cavagnaro** » 10 Aug 2006 10:32

Very strange. Maybe if you reset the sip motor?

dhs3_init -R SIPMOTOR

niko · Post by **niko** » 10 Aug 2006 20:48

cavagnaro wrote: Very strange. Maybe if you reset the sip motor?

dhs3_init -R SIPMOTOR

No luck.

niko · Post by **niko** » 12 Aug 2006 10:50

niko wrote:
cavagnaro wrote: Very strange. Maybe if you reset the sip motor?

dhs3_init -R SIPMOTOR
No luck.

Seem to be related to the fact that contact header is missing from the request packet. Is field mandatory?

Post by **frank** » 12 Aug 2006 21:20

niko wrote:
cavagnaro wrote:Follow the instructions that Frank made:

viewtopic.php?t=900
Thanks Cavagnaro

Actually I went thru Frank's post and suggestions but still experiencing this issue.

Looks like the error that I get, if you can call it an error, is different; "Service Not Available, 503"

Did you do traces ?

niko · Post by **niko** » 13 Aug 2006 12:10

frank wrote:
niko wrote:
cavagnaro wrote:Follow the instructions that Frank made:

viewtopic.php?t=900
Thanks Cavagnaro

Actually I went thru Frank's post and suggestions but still experiencing this issue.

Looks like the error that I get, if you can call it an error, is different; "Service Not Available, 503"
Did you do traces ?

Hey Frank

Here is the trace (log level 3). Thanks for looking at it.Niko.

User-Agent: ABS GW v5.1.0
To: <sip:2002@192.168.1.12>;tag=a2ac44e5bf65728cbc6db161bfafe8f2
From: "Niko - SIP Phone" <sip:3590@192.168.1.12>;tag=61746da6f3ff1b22
Call-ID: 95287e04604ffaab@192.168.1.23
CSeq: 34030 INVITE
Via: SIP/2.0/tcp 192.168.1.12;branch=z9hG4bK51d9552492a592d35559d4cbd8eb860a74ee32c76416d9a89d23f880fe426358
Via: SIP/2.0/UDP 192.168.1.23:6060;branch=z9hG4bKf88c2c0e59f84a60
Content-Length: 0

-------------------------------------------------
1155485943 -> info : CTransport::createConnectionTcp() 192.168.1.12 5060
1155485943 -> 23C [~CResponse] resp(100) 95287e04604ffaab@192.168.1.23 34030 INVITE
1155485943 -> 2196 [CTransProceedingState::createResponse] Final : Transaction changes to Completed state
1155485943 -> 2196 [CTransaction::changeState] STATE CHANGED TO COMPLETED
1155485943 -> 1546 [CDialog::onTransactionState(pTrans = 2196, previousState = Proceeding, currentState = Completed, reason = Final resp creation]
1155485943 -> 2196 [CTransaction::startTimer] Timer H is started (delay = 32000 ms)
1155485943 -> 1546 [CDialog::updateRouteSet]
1155485943 -> info : CTransport::acceptTcpProc() from 192.168.1.12:10073
1155485943 -> info : CTransport::setConnectionEntry(192.168.1.12,10073,1)
1155485943 -> info : CTransport::DoSTest(192.168.1.12,10073,1) NbMsg:1 < seuil
1155485943 -> info : CTransport::receiveTcpProc() from 192.168.1.12:10073 prot:1 rc:845 msg:1
1155485943 -> RECEIVE MESSAGE FROM NETWORK (192.168.1.12:10073 [tcp])
----------------------utf8-----------------------
SIP/2.0 100 Trying
To: <sip:2002@192.168.1.12>
From: "Niko - SIP Phone" <sip:3590@192.168.1.12>;tag=61746da6f3ff1b22
Call-ID: 95287e04604ffaab@192.168.1.23
CSeq: 34030 INVITE
Via: SIP/2.0/tcp 192.168.1.12;branch=z9hG4bK51d9552492a592d35559d4cbd8eb860a74ee32c76416d9a89d23f880fe426358
Via: SIP/2.0/UDP 192.168.1.23:6060;branch=z9hG4bKf88c2c0e59f84a60
Content-Length: 0

SIP/2.0 503 Service Unavailable
User-Agent: ABS GW v5.1.0
To: <sip:2002@192.168.1.12>;tag=a2ac44e5bf65728cbc6db161bfafe8f2
From: "Niko - SIP Phone" <sip:3590@192.168.1.12>;tag=61746da6f3ff1b22
Call-ID: 95287e04604ffaab@192.168.1.23
CSeq: 34030 INVITE
Via: SIP/2.0/tcp 192.168.1.12;branch=z9hG4bK51d9552492a592d35559d4cbd8eb860a74ee32c76416d9a89d23f880fe426358
Via: SIP/2.0/UDP 192.168.1.23:6060;branch=z9hG4bKf88c2c0e59f84a60
Content-Length: 0

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

1155485943 -> [CIOCom::receiveResponse] CResponse 23E ref=1
1155485943 -> [CDispatcher::onIncomingResponse]
1155485943 -> 104 [CCallManager::onIncomingResponse]
1155485943 -> 11da [CCall::receiveResponse] 100
1155485943 -> 11da [CCall::getDialog] Confirmed Dialog is not found (ID = 61746da6f3ff1b22;)
1155485943 -> 1545 [CDialog::receiveResponse]
1155485943 -> 2195 [CTransCallingState::receiveResponse] Provisional : Transaction changes to Proceeding state
1155485943 -> 2195 [CTransaction::changeState] STATE CHANGED TO PROCEEDING
1155485943 -> 1545 [CDialog::onTransactionState(pTrans = 2195, previousState = Calling, currentState = Proceeding, reason = 1xx response reception]
1155485943 -> 2195 [CTransaction::freeTimerToken] Timer B is freed
1155485943 -> 2195 [CTransProceedingState::enterInState] Start Timer C
1155485943 -> 2195 [CTransaction::startTimer] Timer C is started (delay = 180000 ms)
1155485943 -> 23E [CResponse::getProxyContext] Compute proxy context
1155485943 -> 223 [CRequest::getProxyContext] Get proxy context from received context
1155485943 -> Next message in packet (No 1) :
1155485943 -> RECEIVE MESSAGE FROM NETWORK (192.168.1.12:10073 [tcp])
----------------------utf8-----------------------
SIP/2.0 503 Service Unavailable
User-Agent: ABS GW v5.1.0
To: <sip:2002@192.168.1.12>;tag=a2ac44e5bf65728cbc6db161bfafe8f2
From: "Niko - SIP Phone" <sip:3590@192.168.1.12>;tag=61746da6f3ff1b22
Call-ID: 95287e04604ffaab@192.168.1.23
CSeq: 34030 INVITE
Via: SIP/2.0/tcp 192.168.1.12;branch=z9hG4bK51d9552492a592d35559d4cbd8eb860a74ee32c76416d9a89d23f880fe426358
Via: SIP/2.0/UDP 192.168.1.23:6060;branch=z9hG4bKf88c2c0e59f84a60
Content-Length: 0

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

1155485943 -> [CIOCom::receiveResponse] CResponse 23F ref=1
1155485943 -> [CDispatcher::onIncomingResponse]
1155485943 -> 104 [CCallManager::onIncomingResponse]
1155485943 -> 11da [CCall::receiveResponse] 503
1155485943 -> 11da [CCall::getDialog] Confirmed Dialog is not found (ID = 61746da6f3ff1b22;a2ac44e5bf65728cbc6db161bfafe8f2)
1155485943 -> 1545 [CDialog::receiveResponse]
1155485943 -> 23E [~CResponse] resp(100) 95287e04604ffaab@192.168.1.23 34030 INVITE
1155485943 -> 2195 [CTransProceedingState::receiveResponse] Final : Transaction changes to Completed state
1155485943 -> 2195 [CTransaction::changeState] STATE CHANGED TO COMPLETED
1155485943 -> 1545 [CDialog::onTransactionState(pTrans = 2195, previousState = Proceeding, currentState = Completed, reason = Final resp reception]
1155485943 -> 2195 [CTransaction::freeTimerToken] Timer C is freed
1155485943 -> 225 [CRequest::CRequest] Creation of a request
1155485943 -> 225 [CMessage::send] sip_sendMessage (192.168.1.12:6060)
1155485943 -> SEND MESSAGE TO NETWORK (192.168.1.12:6060 [tcp]) (BUFF LEN = 385)
----------------------utf8-----------------------
ACK sip:2002@192.168.1.12:6060;transport=tcp SIP/2.0
Call-ID: 95287e04604ffaab@192.168.1.23
From: "Niko - SIP Phone" <sip:3590@192.168.1.12>;tag=61746da6f3ff1b22
To: <sip:2002@192.168.1.12>;tag=a2ac44e5bf65728cbc6db161bfafe8f2
Via: SIP/2.0/tcp 192.168.1.12;branch=z9hG4bK51d9552492a592d35559d4cbd8eb860a74ee32c76416d9a89d23f880fe426358
CSeq: 34030 ACK
Content-Length: 0

-------------------------------------------------
1155485943 -> info : CTransport::createConnectionTcp() 192.168.1.12 6060
1155485943 -> 2195 [CTransCompletedState::enterInState] Final(!UDP) : INVITE Client Transaction changes to Terminated state
1155485943 -> 2195 [CTransaction::changeState] STATE CHANGED TO TERMINATED
1155485943 -> 1545 [CDialog::updateRouteSet]
1155485943 -> 23F [CResponse::getProxyContext] Compute proxy context
1155485943 -> 223 [CRequest::getProxyContext] Get proxy context from received context
1155485943 -> 11da [CCall::makeGenericResponse] 503
1155485943 -> 11da [CCall::getDialog] Confirmed Dialog is not found (ID = a2ac44e5bf65728cbc6db161bfafe8f2;61746da6f3ff1b22)
1155485943 -> 1544 [CDialog::createResponse] 503
1155485943 -> 2194 [CTransProceedingState::createResponse] CResponse 240 ref=1
1155485943 -> 240 [CMessage::send] sip_sendMessage (192.168.1.23:6060)
1155485943 -> SEND MESSAGE TO NETWORK (192.168.1.23:6060 [udp]) (BUFF LEN = 352)
----------------------utf8-----------------------
SIP/2.0 503 Service Unavailable
User-Agent: ABS GW v5.1.0
To: <sip:2002@192.168.1.12>;tag=a2ac44e5bf65728cbc6db161bfafe8f2
From: "Niko - SIP Phone" <sip:3590@192.168.1.12>;tag=61746da6f3ff1b22
Call-ID: 95287e04604ffaab@192.168.1.23
CSeq: 34030 INVITE
Via: SIP/2.0/UDP 192.168.1.23:6060;branch=z9hG4bKf88c2c0e59f84a60
Content-Length: 0

-------------------------------------------------
1155485943 -> info : CTransport::createConnectionUdp 192.168.1.23 6060
1155485943 -> 23B [~CResponse] resp(100) 95287e04604ffaab@192.168.1.23 34030 INVITE
1155485943 -> 2194 [CTransProceedingState::createResponse] Final : Transaction changes to Completed state
1155485943 -> 2194 [CTransaction::changeState] STATE CHANGED TO COMPLETED
1155485943 -> 1544 [CDialog::onTransactionState(pTrans = 2194, previousState = Proceeding, currentState = Completed, reason = Final resp creation]
1155485943 -> 2194 [CTransaction::startTimer] Timer G is started (delay = 500 ms)
1155485943 -> 2194 [CTransaction::startTimer] Timer H is started (delay = 32000 ms)
1155485943 -> 1544 [CDialog::updateRouteSet]
1155485943 -> info : CTransport::DoSTest(192.168.1.12,10072,1) NbMsg:2 < seuil
1155485943 -> info : CTransport::receiveTcpProc() from 192.168.1.12:10072 prot:1 rc:385 msg:2
1155485943 -> RECEIVE MESSAGE FROM NETWORK (192.168.1.12:10072 [tcp])
----------------------utf8-----------------------
ACK sip:2002@192.168.1.12:6060;transport=tcp SIP/2.0
Call-ID: 95287e04604ffaab@192.168.1.23
From: "Niko - SIP Phone" <sip:3590@192.168.1.12>;tag=61746da6f3ff1b22
To: <sip:2002@192.168.1.12>;tag=a2ac44e5bf65728cbc6db161bfafe8f2
Via: SIP/2.0/tcp 192.168.1.12;branch=z9hG4bK51d9552492a592d35559d4cbd8eb860a74ee32c76416d9a89d23f880fe426358
CSeq: 34030 ACK
Content-Length: 0

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

1155485943 -> 226 [CRequest::CRequest] Creation of a request (RECEIVED FROM NETWORK)
1155485943 -> [CIOCom::receiveRequest] Received CRequest 226 ref=1
1155485943 -> [CDispatcher::onIncomingRequest()]
1155485943 -> 102 [CCallManager::onIncomingRequest()]
1155485943 -> 11db [CCall::receiveRequest] ACK
1155485943 -> 11db [CCall::getDialog] Confirmed Dialog is not found (ID = a2ac44e5bf65728cbc6db161bfafe8f2;61746da6f3ff1b22)
1155485943 -> 1546 [CDialog::receiveRequest]
1155485943 -> 1546 [CDialog::receiveAckRequest]
1155485943 -> 2196 [CTransCompletedState::receiveRequest] ACK(UDP) : Transaction changes to Confirmed state
1155485943 -> 2196 [CTransaction::changeState] STATE CHANGED TO CONFIRMED
1155485943 -> 1546 [CDialog::onTransactionState(pTrans = 2196, previousState = Completed, currentState = Confirmed, reason = Request reception]
1155485943 -> 2196 [CTransaction::freeTimerToken] Timer H is freed
1155485943 -> 2196 [CTransaction::changeState] STATE CHANGED TO TERMINATED
1155485943 -> 1546 [CDialog::receiveAckRequest] receiving an ACK on a previously rejected INVITE
1155485943 -> 11db [CCall::checkAuthentication] ACK
1155485943 -> [CMotorCall] same Dialog
1155485943 -> [onReceiveRequest] Ack => update the inviteContext.
1155485943 -> [receiveAckMessage] Call: 95287e04604ffaab@192.168.1.23 eqt: 1331 TERMINATED_STATE received a message.
1155485943 -> [ipc_thread] IPC Thread : Ipc reception.
1155485943 -> [ipc_thread] IPC Thread : TCL thread signaled.
1155485943 -> [emitEventToMonitel] Event sent on eqt : 1331
1155485943 -> [display_ipc_out] ------------ Begin ---------------
1155485943 -> ACK
1155485943 -> [display_ipc_out] ------------- End ----------------
1155485943 -> [receiveAckMessage] Reset of the session.
1155485943 -> [CMotorCall::unRegister] Remove eqt : 1331 from the map.
1155485943 -> [CMotorCallManager::eraseCallwithEqt] CMotorCall 1331 erased.
1155485943 -> [exec_ipc] in.
1155485943 -> [display_ipc_in] ------------ Begin ---------------
1155485943 -> neqt : 1331
1155485943 -> SIP EQT RELEASED
1155485943 -> [display_ipc_in] ------------- End ----------------
1155485943 -> [CMotorCallManager::onIncomingEvent] an event 10773 arrived on the eqt 1331.
1155485943 -> [CMotorCallManager::onIncomingEvent] NO call with eqt: 1331 to release.
1155485943 -> info : CTransport::receiveUdpProc() from 192.168.1.23:44055 prot:0 rc:485 msg:69
1155485943 -> info : CTransport::DoSTest(192.168.1.23,44055,0) NbMsg:69 < seuil
1155485943 -> RECEIVE MESSAGE FROM NETWORK (192.168.1.23:44055 [udp])
----------------------utf8-----------------------
ACK sip:2002@192.168.1.12 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.23:6060;branch=z9hG4bKf88c2c0e59f84a60
From: "Niko - SIP Phone" <sip:3590@192.168.1.12>;tag=61746da6f3ff1b22
To: <sip:2002@192.168.1.12>;tag=a2ac44e5bf65728cbc6db161bfafe8f2
Contact: <sip:3590@192.168.1.23:6060>
Call-ID: 95287e04604ffaab@192.168.1.23
CSeq: 34030 ACK
User-Agent: Grandstream BT100 1.0.6.7
Max-Forwards: 70
Allow: INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE
Content-Length: 0

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

1155485943 -> 227 [CRequest::CRequest] Creation of a request (RECEIVED FROM NETWORK)
1155485943 -> [CIOCom::receiveRequest] Received CRequest 227 ref=1
1155485943 -> [CDispatcher::onIncomingRequest()]
1155485943 -> 104 [CCallManager::onIncomingRequest()]
1155485943 -> 11da [CCall::receiveRequest] ACK
1155485943 -> 11da [CCall::getDialog] Confirmed Dialog is not found (ID = a2ac44e5bf65728cbc6db161bfafe8f2;61746da6f3ff1b22)
1155485943 -> 1544 [CDialog::receiveRequest]
1155485943 -> 1544 [CDialog::receiveAckRequest]
1155485943 -> 2194 [CTransCompletedState::receiveRequest] ACK(UDP) : Transaction changes to Confirmed state
1155485943 -> 2194 [CTransaction::changeState] STATE CHANGED TO CONFIRMED
1155485943 -> 1544 [CDialog::onTransactionState(pTrans = 2194, previousState = Completed, currentState = Confirmed, reason = Request reception]
1155485943 -> 2194 [CTransaction::freeTimerToken] Timer G is freed
1155485943 -> 2194 [CTransaction::freeTimerToken] Timer H is freed
1155485943 -> 2194 [CTransaction::startTimer] Timer I is started (delay = 5000 ms)
1155485943 -> 1544 [CDialog::receiveAckRequest] receiving an ACK on a previously rejected INVITE
1155485943 -> 1545 [CDialog::onTransactionState(pTrans = 2195, previousState = null, currentState = Terminated, reason = Timer D fires]
1155485943 -> 1545 [CDialog::onTransactionState] dialog is terminated
1155485943 -> 1545 [CDialog::onTransactionState] onDialogClosed
1155485943 -> 11da [CCall::onDialogClosed]
1155485943 -> 11da [CCall::onDialogClosed] only initial *********
1155485943 -> 225 [~CRequest] req 95287e04604ffaab@192.168.1.23 34030 ACK
1155485943 -> 23F [~CResponse] resp(503) 95287e04604ffaab@192.168.1.23 34030 INVITE
1155485943 -> 1546 [CDialog::onTransactionState(pTrans = 2196, previousState = null, currentState = Terminated, reason = Timer I fires]
1155485943 -> 1546 [CDialog::onTransactionState] dialog is terminated
1155485943 -> 1546 [CDialog::onTransactionState] onDialogClosed
1155485943 -> 11db [CCall::onDialogClosed]
1155485943 -> 11db [CCall::onDialogClosed] only initial *********
1155485943 -> 11db [CCall::onDialogClosed] All the initial dialogs in the Call are closed
1155485943 -> 11db [CCall] closed
1155485943 -> 102 [CCallManager::onCallClosed]
1155485943 -> [CMotorCallManager::onNotifyCallClosed] Call : 95287e04604ffaab@192.168.1.23 is closed.
1155485943 -> 226 [~CRequest] req 95287e04604ffaab@192.168.1.23 34030 ACK
1155485945 -> 11db [CCall::removeGarbage]
1155485945 -> 1546 [CDialog::~CDialog]
1155485945 -> [---*--- SESSION-TIMER ---*---] Free CSessionTimerContext

1155485945 -> [CMotorCall::~CMotorCall] Call : 95287e04604ffaab@192.168.1.23 is being destructed.
1155485945 -> 11db [CCall::~CCall]
1155485945 -> 11db [CCall::removeGarbage]
1155485945 -> 23D [~CResponse] resp(503) 95287e04604ffaab@192.168.1.23 34030 INVITE
1155485945 -> 224 [~CRequest] req 95287e04604ffaab@192.168.1.23 34030 INVITE

(1)cpu_oxe>

Post by **frank** » 14 Aug 2006 20:38

Did you follow the procedure on how to create a sip trunk on the SIP forum ?

niko · Post by **niko** » 15 Aug 2006 13:40

frank wrote:Did you follow the procedure on how to create a sip trunk on the SIP forum ?

As a matter of fact that was the first thing to try out...

Post by **frank** » 15 Aug 2006 21:22

Ok, did you configure the SIP settings on the PBX so there is no authentication ?