SIP Device not making outgoing/external PSTN calls but can receive PSTN calls

[imukonyezi]

Dear Community,
I configured an account as SIP device and was able to successfuly register the account. I have tested with linphone app on iOS and Grandstream phone.
I can make internal extension to extension calls, between sip devices and sip extensions and between sip devices and noe devices. However I am not able to make external calls to the PSTN. We usually dial 0 on NOE and Sip extensions to make an external PSTN call. However on the SIP device dialling a 0 immediately throws a call failed error and message 0 is not connected. However I can receive external calls from PSTN to the SIP device via its DID number.

When I look at the traces from OXE it's as if the 0 is being identified as an extension number on the system.

The traces are below

Thank you for all your support.

(1)CS1> traced
** UNIX-trace-daemon started ... (static user group No 1) **

traced started ...
1726547216 -> Tue Sep 17 07:26:56 2024 2103 [CTransCompletedState::timerFires] Timer J fires
Tue Sep 17 07:26:56 2024 2103 [CTransCompletedState::timerFires] Timer J : Transaction changes to Terminated state
Tue Sep 17 07:26:56 2024 2103 [CTransaction::changeState] STATE CHANGED TO TERMINATED
Tue Sep 17 07:26:56 2024 2103 [CTransaction::changeState] no transaction state, terminated reason : Timer J fires
Tue Sep 17 07:26:56 2024 [CTransaction::notifyDialog]
Tue Sep 17 07:26:56 2024 [CTransaction::notifyDialog] notify parent dialog
Tue Sep 17 07:26:56 2024 15f5 [CDialog::onTransactionState(pTrans = 2103, previousState = null, currentState = Terminated, reason = Timer J fires]
Tue Sep 17 07:26:56 2024 15f5 [CDialog::onTransactionState] dialog is terminated
Tue Sep 17 07:26:56 2024 15f5 [CDialog::onTransactionState] onDialogClosed
Tue Sep 17 07:26:56 2024 115f [CCall::onDialogClosed]
Tue Sep 17 07:26:56 2024 115f [CCall::onDialogClosed] only initial *********
Tue Sep 17 07:26:56 2024 115f [CCall::onDialogClosed] All the initial dialogs in the Call are closed
Tue Sep 17 07:26:56 2024 115f [CCall] closed
Tue Sep 17 07:26:56 2024 102 [CCallManager::onCallClosed]
Tue Sep 17 07:26:56 2024 102 [CCallManager::onCallClosed()] Call 115f closed. Removing call 145865008 from the maps
Tue Sep 17 07:26:56 2024 [CMotorCallManager::onNotifyCallClosed] Call : a71d8bdd is closed.
Tue Sep 17 07:26:56 2024 [CMotorCallManager::onNotifyCallClosed] Remove eqt from the map.
Tue Sep 17 07:26:56 2024 102 [CCallManager::onCallClosed] pCall: 115f added in the Garbage List
1726547217 -> Tue Sep 17 07:26:56 2024 2103 [~CTransaction] transaction 1071895603 OPTIONS
Tue Sep 17 07:26:57 2024 RECEIVE MESSAGE FROM NETWORK (172.16.203.40:61459 [UDP])
----------------------utf8-----------------------
INVITE sip:0@172.16.5.6 SIP/2.0
Via: SIP/2.0/UDP 172.16.203.40:61459;branch=z9hG4bK.zTwT0dWyE;rport
From: "ISAAC MUKONYEZI " <sip:2991@172.16.5.6>;tag=jafB4Dli1
To: "0" <sip:0@172.16.5.6>
CSeq: 20 INVITE
Call-ID: -zSGrwovU9
Max-Forwards: 70
Supported: replaces, outbound, gruu, path, record-aware
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, NOTIFY, MESSAGE, SUBSCRIBE, INFO, PRACK, UPDATE
Content-Type: application/sdp
Content-Length: 1697
Contact: "ISAAC MUKONYEZI " <sip:2991@172.16.203.40:61459;transport=udp>;expires=3600;+sip.instance="<urn:uuid:355f279e-57ca-00b0-8716-b254daa2e808>";+org.linphone.specs="li
me"
User-Agent: LinphoneiOS/5.2.2 (iPhone) LinphoneSDK/5.3.20

v=0
o=2991 1494 2861 IN IP4 172.16.203.40
s=Talk
c=IN IP4 172.16.203.40
t=0 0
a=rtcp-xr:rcvr-rtt=all:10000 stat-summary=loss,dup,jitt,TTL voip-metrics
a=record:off
m=audio 7228 RTP/AVP 96 97 98 0 8 3 9 99 18 100 102 103 104 105 106 107 101 108 109 110 111 112
a=rtpmap:96 opus/48000/2
a=fmtp:96 useinbandfec=1
a=rtpmap:97 speex/16000
a=fmtp:97 vbr=on
a=rtpmap:98 speex/8000
a=fmtp:98 vbr=on
a=rtpmap:99 iLBC/8000
a=fmtp:99 mode=30
a=fmtp:18 annexb=yes
a=rtpmap:100 mpeg4-generic/16000
a=fmtp:100 config=F8EE2000; constantDuration=512; indexDeltaLength=3; indexLength=3; mode=AAC-hbr; profile-level-id=76; sizeLength=13; streamType=5
a=rtpmap:102 mpeg4-generic/22050
a=fmtp:102 config=F8EE2000; constantDuration=512; indexDeltaLength=3; indexLength=3; mode=AAC-hbr; profile-level-id=76; sizeLength=13; streamType=5
a=rtpmap:103 mpeg4-generic/32000
a=fmtp:103 config=F8E82000; constantDuration=512; indexDeltaLength=3; indexLength=3; mode=AAC-hbr; profile-level-id=76; sizeLength=13; streamType=5
a=rtpmap:104 mpeg4-generic/44100
a=fmtp:104 config=F8E82000; constantDuration=512; indexDeltaLength=3; indexLength=3; mode=AAC-hbr; profile-level-id=76; sizeLength=13; streamType=5
a=rtpmap:105 mpeg4-generic/48000
a=fmtp:105 config=F8EE2000; constantDuration=512; indexDeltaLength=3; indexLength=3; mode=AAC-hbr; profile-level-id=76; sizeLength=13; streamType=5
a=rtpmap:106 iSAC/16000
a=rtpmap:107 BV16/8000
a=rtpmap:101 telephone-event/48000
a=rtpmap:108 telephone-event/16000
a=rtpmap:109 telephone-event/8000
a=rtpmap:110 telephone-event/22050
a=rtpmap:111 telephone-event/32000
a=rtpmap:112 telephone-event/44100
a=rtcp-fb:* trr-int 1000
a=rtcp-fb:* ccm tmmbr
-------------------------------------------------

1726547217 -> Tue Sep 17 07:26:57 2024 [CIOCom::receiveRequest] optimisation value 1
Tue Sep 17 07:26:57 2024 [CIOCom::IsNoeOptionsMsg] Not in same domain FAILED => 172.16.5.6 main1
Tue Sep 17 07:26:57 2024 [CIOCom::IsNoeOptionsMsg] NoeOpt 0
Tue Sep 17 07:26:57 2024 4F99 [CRequest::CRequest] Creation of a request (RECEIVED FROM NETWORK)
Tue Sep 17 07:26:57 2024 [CIOCom::receiveRequest] Received CRequest 4F99 ref=1
Tue Sep 17 07:26:57 2024 [CIOCom::receiveRequest] rport1 empty
Tue Sep 17 07:26:57 2024 [CIOCom::receiveRequest] Set received param on first Via header : 172.16.203.40
Tue Sep 17 07:26:57 2024 [CDispatcher::onIncomingRequest()]
Tue Sep 17 07:26:57 2024 [CDispatcher::processRequestFromManagerIndex()] start at index 0
Tue Sep 17 07:26:57 2024 [CDispatcher::processRequestFromManagerIndex] Call does not exist or manager changed: Find the new manager to handle
Tue Sep 17 07:26:57 2024 102 [CCallManager::onIncomingRequest()]
Tue Sep 17 07:26:57 2024 [CMotorCallManager::callFactory] For call : -zSGrwovU9.
Tue Sep 17 07:26:57 2024 1163 [CCall::CCall] resources number: 20
Tue Sep 17 07:26:57 2024 1163[CMotorCall::setSessionTimer] main gw sessionTimer=1800
Tue Sep 17 07:26:57 2024 [CMotorCall :: CMotorCall()] Oxe_Version_Name = OmniPCX Enterprise R100.0 n1.291.66.a
Tue Sep 17 07:26:57 2024 [CMotorCallManager::callFactory]=8b23be0.
Tue Sep 17 07:26:57 2024 1163 [CCall::receiveRequest] INVITE
Tue Sep 17 07:26:57 2024 1163 [CCall::getDialog] Confirmed Dialog is not found (ID = ;jafB4Dli1)
1726547217 -> Tue Sep 17 07:26:57 2024 1163 [CCall::getDialog] Initial Dialog Server not found
Tue Sep 17 07:26:57 2024 15f9 [CDialog::CDialog] resources number: 18
Tue Sep 17 07:26:57 2024 15f9 [CDialog::receiveRequest]
Tue Sep 17 07:26:57 2024 15f9 [CDialog::receiveInviteRequest]
Tue Sep 17 07:26:57 2024 2109 [CTransaction::changeState] STATE CHANGED TO INITIAL
Tue Sep 17 07:26:57 2024 2109 [CTransaction::changeState] notifying the parent dialog
Tue Sep 17 07:26:57 2024 15f9 [CDialog::onTransactionState(pTrans = 2109, previousState = Terminated, currentState = Initial, reason = None]
Tue Sep 17 07:26:57 2024 2109 [CTransInitialState::receiveRequest] Transaction changes to Proceeding state
Tue Sep 17 07:26:57 2024 2109 [CTransaction::changeState] STATE CHANGED TO PROCEEDING
Tue Sep 17 07:26:57 2024 2109 [CTransaction::changeState] notifying the parent dialog
Tue Sep 17 07:26:57 2024 15f9 [CDialog::onTransactionState(pTrans = 2109, previousState = Initial, currentState = Proceeding, reason = Request reception]
Tue Sep 17 07:26:57 2024 2109 [CTransProceedingState::createResponse] CResponse 4D04 ref=1
Tue Sep 17 07:26:57 2024 [DBG: CResponse::freeAttributes]
Tue Sep 17 07:26:57 2024 [DBG: CMessage::freeAttributes Start Alert freeAttributes !!!!!!!!]:
Tue Sep 17 07:26:57 2024 [DBG: CResponse::freeAttributes] Ends
Tue Sep 17 07:26:57 2024 4D04 [CMessage::send] sip_sendMessage (172.16.203.40:61459)
Tue Sep 17 07:26:57 2024 SEND MESSAGE TO NETWORK (172.16.203.40:61459 [UDP]) (BUFF LEN = 267)
----------------------utf8-----------------------
SIP/2.0 100 Trying
To: "0" <sip:0@172.16.5.6>
From: "ISAAC MUKONYEZI " <sip:2991@172.16.5.6>;tag=jafB4Dli1
Call-ID: -zSGrwovU9
CSeq: 20 INVITE
Via: SIP/2.0/UDP 172.16.203.40:61459;received=172.16.203.40;branch=z9hG4bK.zTwT0dWyE;rport=61459
Content-Length: 0

-------------------------------------------------
1726547217 -> Tue Sep 17 07:26:57 2024 4D04 [CMessage::onSendSuccess]
Tue Sep 17 07:26:57 2024 2109 [CTransaction::onSendSuccess] CMessage 4D04 send Success
Tue Sep 17 07:26:57 2024 15f9 [CDialog::onSendSuccess]
Tue Sep 17 07:26:57 2024 15f9 [CDialog::receiveInviteRequest] a new Server INVITE transaction is in progress
Tue Sep 17 07:26:57 2024 [CSessionTimerContext::CSessionTimerContext] New CSessionTimerContext from request (Server, UA)
Tue Sep 17 07:26:57 2024 [CSessionTimerContext::updateAfterRefreshReception] Update CSessionTimerContext (refresh reception)
Tue Sep 17 07:26:57 2024 [CSessionTimerContext::updateSessionExpires] Session-Expires updated : 0
Tue Sep 17 07:26:57 2024 [CSessionTimerContext::setRefreshMethod] Allow refreshMethod=UPDATE
Tue Sep 17 07:26:57 2024 15f9 [CDialog::addTransaction] added transaction 2109 with branch z9hG4bK.zTwT0dWyE, with CSeq 20
Tue Sep 17 07:26:57 2024 1163 [CCall::receiveRequest] iErrorCode=488.
Tue Sep 17 07:26:57 2024 1163 [CCall::checkAuthentication] INVITE
Tue Sep 17 07:26:57 2024 1163[CMotorCall::controlAuthentication] ---*--- AUTH ---*--- Control authentication
Tue Sep 17 07:26:57 2024 [getFromHeader] look at the From.
Tue Sep 17 07:26:57 2024 [getFromHeader] displayName="ISAAC MUKONYEZI ".
Tue Sep 17 07:26:57 2024 [convertCSipURLIntoSipuri] uri = sip:2991@172.16.5.6.
Tue Sep 17 07:26:57 2024 [getFromHeader] =2991@172.16.5.6.
Tue Sep 17 07:26:57 2024 [getFromHeader] clirPresent=0.
Tue Sep 17 07:26:57 2024 [getReqUriHeader] look at the requri.
1726547217 -> Tue Sep 17 07:26:57 2024 [convertCSipURLIntoSipuri] uri = sip:0@172.16.5.6.
Tue Sep 17 07:26:57 2024 1163[CMotorCall::getUserType] extGw=-1.
Tue Sep 17 07:26:57 2024 [CMotorCall::analyseRouting] TEL uri=0.
Tue Sep 17 07:26:57 2024 [CMotorCall::analyseRouting] 2991@172.16.5.6 is authenticable.
Tue Sep 17 07:26:57 2024 1163[CMotorCall::setExtGw] -1->-3
Tue Sep 17 07:26:57 2024 1163[CMotorCall::setSessionTimer] main gw sessionTimer=1800
Tue Sep 17 07:26:57 2024 [isUserInDico] looking for 2991@172.16.5.6 ...
Tue Sep 17 07:26:57 2024 [isUserInDico] found in the dictionnary.
Tue Sep 17 07:26:57 2024 [isAddrInDico] sip device station OK
Tue Sep 17 07:26:57 2024 [getExternalStationParameters] stat=13 digit=4 app=0 com=0 data=1 term=142.
Tue Sep 17 07:26:57 2024 [CMotorCallManager::processInDico] device station crypto=145900091, extGateway=-1.
Tue Sep 17 07:26:57 2024 [CMotorCallManager::processInDico] End extGw=-3.
Tue Sep 17 07:26:57 2024 1163[CMotorCall::setExtGw] -3->-3
Tue Sep 17 07:26:57 2024 1163[CMotorCall::getUserType] extGw=-3.
Tue Sep 17 07:26:57 2024 1163[CMotorCall::controlAuthentication] m_other_calling=0 extgw=-3
Tue Sep 17 07:26:57 2024 1163[CMotorCall::controlAuthentication] from:sip:2991@172.16.5.6:5060
Tue Sep 17 07:26:57 2024 [CMngData::getStackPort] >>>>> transportProtocol: 0, isMTLS: 0, m_uStackSecureMtlsPort: 6261, m_uStackSecurePort: 5061, m_uStackUnsecurePort 5060
Tue Sep 17 07:26:57 2024 [CMngData::getStackPort] >>>>> port: 5060
Tue Sep 17 07:26:57 2024 [CGwMngData::isAuthenticationRequiredForThisClient] 2991@172.16.5.6 .
1726547217 -> Tue Sep 17 07:26:57 2024 [CGwMngData::isAuthenticationRequiredForThisClient] -3 need auth=0.
Tue Sep 17 07:26:57 2024 [CGwMngData::isAuthenticationRequiredForThisClient] external gateway -3.
Tue Sep 17 07:26:57 2024 CGwMngData::isAuthenticationRequiredForThisClient: gateway=-3
Tue Sep 17 07:26:57 2024 1163[CMotorCall::controlAuthentication] ---*--- END-05 ---*--- Control authentication
Tue Sep 17 07:26:57 2024 1163[CMotorCall::setExtGw] -3->-3
Tue Sep 17 07:26:57 2024 1163 [CCall::controlAuthentication] need auth =0
Tue Sep 17 07:26:57 2024 1163 [CCall::checkAuthentication] AUTHENT=0
Tue Sep 17 07:26:57 2024 1163 [CCall::checkAuthentication] iRetValue=0
Tue Sep 17 07:26:57 2024 1163 [CCall::receiveRequest] extGw=-1.
Tue Sep 17 07:26:57 2024 1163[CMotorCall::onReceiveRequest] request received.
Tue Sep 17 07:26:57 2024 1163[CMotorCall::setFilterUsedMode] To be traced = 0
Tue Sep 17 07:26:57 2024 1163[CMotorCall::initOfUserType] station role already defined s=0 d=1 o=0.
Tue Sep 17 07:26:57 2024 1163[CMotorCall::initOfUserType] crypted=0 crypting_author=0.
Tue Sep 17 07:26:57 2024 1163[CMotorCall::onReceiveRequest] accepte? m_other_calling=0 gw=-1.
Tue Sep 17 07:26:57 2024 1163[CMotorCall::toGatewayOrProxy] SIP_PRIVATE_TRANSIT_MODE=1.
Tue Sep 17 07:26:57 2024 1163[CMotorCall::toGatewayOrProxy] request for proxydomain=172.16.5.6.
Tue Sep 17 07:26:57 2024 [getReqUriHeader] look at the requri.
Tue Sep 17 07:26:57 2024 [convertCSipURLIntoSipuri] uri = sip:0@172.16.5.6.
Tue Sep 17 07:26:57 2024 1163[CMotorCall::toGatewayOrProxy] requri isSeplosStation?.
1726547217 -> Tue Sep 17 07:26:57 2024 [isUserInDico] looking for 0@172.16.5.6 ...
Tue Sep 17 07:26:57 2024 [isUserInDico] NOT found in the dictionnary.
Tue Sep 17 07:26:57 2024 [isAddrInDico] other sip user
Tue Sep 17 07:26:57 2024 [isSeplosStation] user=0 host=172.16.5.6: not a seplos station
Tue Sep 17 07:26:57 2024 1163[CMotorCall::toGatewayOrProxy] requri isDeviceStation?.
Tue Sep 17 07:26:57 2024 [isUserInDico] looking for 0@172.16.5.6 ...
Tue Sep 17 07:26:57 2024 [isUserInDico] NOT found in the dictionnary.
Tue Sep 17 07:26:57 2024 [isAddrInDico] other sip user
Tue Sep 17 07:26:57 2024 [isExternalStation] user=0 host=172.16.5.6: not device station
Tue Sep 17 07:26:57 2024 [isUserInDico] looking for 0@172.16.5.6 ...
Tue Sep 17 07:26:57 2024 [isUserInDico] NOT found in the dictionnary.
Tue Sep 17 07:26:57 2024 [isAddrInDico] other sip user
Tue Sep 17 07:26:57 2024 [isReqUriDevice] user=0 host=172.16.5.6: not ext voice mail
Tue Sep 17 07:26:57 2024 1163[CMotorCall::toGatewayOrProxy] requri other.
Tue Sep 17 07:26:57 2024 [isUserRegistred] getRegistration=0.
Tue Sep 17 07:26:57 2024 [isUserRegistred] result=-1.
Tue Sep 17 07:26:57 2024 1163[CMotorCall::toGatewayOrProxy] req uri = unregistred.
Tue Sep 17 07:26:57 2024 1163[CMotorCall::toGatewayOrProxy] req uri device = 0 req uri vm = 0.
Tue Sep 17 07:26:57 2024 1163[CMotorCall::toGatewayOrProxy] unregistred, request to gateway.
Tue Sep 17 07:26:57 2024 1163[CMotorCall::storeRequestInCall] inRequest storage=0x8a86870.
Tue Sep 17 07:26:57 2024 1163[CMotorCall::onReceiveRequest] request for gateway.
1726547217 -> Tue Sep 17 07:26:57 2024 1163[CMotorCall::onReceiveRequest] state = INITIAL_STATE.
Tue Sep 17 07:26:57 2024 1163[CMotorCall::methodInviteReceived] nb available licenses=30 .
Tue Sep 17 07:26:57 2024 [isUserInDico] looking for 2991@172.16.5.6 ...
Tue Sep 17 07:26:57 2024 [isUserInDico] found in the dictionnary.
Tue Sep 17 07:26:57 2024 [CGwServAuthenticate::isUserDeclared] found in the dictionnary.
Tue Sep 17 07:26:57 2024 1163[CMotorCall::compareDialogFromContext] presumed to be the same Dialog
Tue Sep 17 07:26:57 2024 1163[CMotorCall::methodInviteReceived] Invite => update the inviteContext.
Tue Sep 17 07:26:57 2024 [CForkedBranch::CForkedBranch] context = 0x8b241a4.
Tue Sep 17 07:26:57 2024 1163[CMotorCall::methodInviteReceived] reqUri stored: sip:2991@172.16.203.40:61459;transport=udp
Tue Sep 17 07:26:57 2024 1163[CMotorCall::methodInviteReceived] cacUsed = 0 before.
Tue Sep 17 07:26:57 2024 1163[CMotorCall::methodInviteReceived] returnStat = 0 before.
Tue Sep 17 07:26:57 2024 1163[CMotorCall::methodInviteReceived] cacUsed = 0 after.
Tue Sep 17 07:26:57 2024 1163[CMotorCall::methodInviteReceived] CAC = 0
Tue Sep 17 07:26:57 2024 [receiveInviteMessage] Call: -zSGrwovU9 eqt: -1 INITIAL_STATE received a message.
Tue Sep 17 07:26:57 2024 [decodeInviteCLIR].
Tue Sep 17 07:26:57 2024 [receiveInviteMessage] INFO present in Allow header
Tue Sep 17 07:26:57 2024 [receiveInviteMessage] UPDATE present in Allow header
Tue Sep 17 07:26:57 2024 am_I_PCS SCSWorking : NO
Tue Sep 17 07:26:57 2024 check_PCSstate
1726547217 -> Tue Sep 17 07:26:57 2024 am_I_PCS SCSWorking : NO
Tue Sep 17 07:26:57 2024 OXE contains PCS configuration
Tue Sep 17 07:26:57 2024 check_PCSstate get the address of the user
Tue Sep 17 07:26:57 2024 1163[CMotorCall::addrToMediaInit] cac case.
Tue Sep 17 07:26:57 2024 [isUserRegistred] getRegistration=2991.
Tue Sep 17 07:26:57 2024 [isUserRegistred] result=0.
Tue Sep 17 07:26:57 2024 1163[CMotorCall::addrToMediaInit] user registred.
Tue Sep 17 07:26:57 2024 1163[CMotorCall::addrToMediaInit] contact list = 6.
Tue Sep 17 07:26:57 2024 1163[CMotorCall::addrToMediaInit] contact = 0
Tue Sep 17 07:26:57 2024 [convertIPStrIntoTuipv] 172.16.203.40 => 172.16.203.40
Tue Sep 17 07:26:57 2024 1163[CMotorCall::addrToMediaInit] S_OK.
Tue Sep 17 07:26:57 2024 check_PCSstate we have found the ip if user
Tue Sep 17 07:26:57 2024 check_PCSstate toAddress [0]= 172
Tue Sep 17 07:26:57 2024 check_PCSstate toAddress [1]= 16
Tue Sep 17 07:26:57 2024 check_PCSstate toAddress [2]= 203
Tue Sep 17 07:26:57 2024 check_PCSstate toAddress [3]= 40
Tue Sep 17 07:26:57 2024 check_PCSstate toAddress [172.16.203.40]
Tue Sep 17 07:26:57 2024 check_PCSstate user IP is Found ? = YES
Tue Sep 17 07:26:57 2024 The recevied host 172.16.203.40
Tue Sep 17 07:26:57 2024 Trying to find the ip address in domain list
Tue Sep 17 07:26:57 2024 iplink_is_good_range_for_reg
Tue Sep 17 07:26:57 2024 iplink_is_good_range_for_reg
Tue Sep 17 07:26:57 2024 iplink_is_good_range_for_reg
Tue Sep 17 07:26:57 2024 iplink_is_good_range_for_reg
1726547217 -> Tue Sep 17 07:26:57 2024 iplink_is_good_range_for_reg
Tue Sep 17 07:26:57 2024 iplink_is_good_range_for_reg
Tue Sep 17 07:26:57 2024 iplink_is_good_range_for_reg
Tue Sep 17 07:26:57 2024 iplink_is_good_range_for_reg
Tue Sep 17 07:26:57 2024 iplink_is_good_range_for_reg
Tue Sep 17 07:26:57 2024 iplink_is_good_range_for_reg
Tue Sep 17 07:26:57 2024 iplink_is_good_range_for_reg
Tue Sep 17 07:26:57 2024 iplink_is_good_range_for_reg
Tue Sep 17 07:26:57 2024 iplink_is_good_range_for_reg
Tue Sep 17 07:26:57 2024 The user is ipadd not in any Domain range return state as -1
Tue Sep 17 07:26:57 2024 check_PCSstate stat of PCS is : -1
Tue Sep 17 07:26:57 2024 1163[CMotorCall::setSessionTimer] main gw sessionTimer=1800
Tue Sep 17 07:26:57 2024 1163[CMotorCall::analyseSessionTimer] Session Timer extGw=-1 sessionDelay=1800 .
Tue Sep 17 07:26:57 2024 1163[CMotorCall::isExtGwTLSwithREINVITE] ExtGw NOT manged with TLS and refresh REINVITE
Tue Sep 17 07:26:57 2024 1163[CMotorCall::analyseSessionTimer] exit with uacGatewayMode=0 uacRefresher=0.
Tue Sep 17 07:26:57 2024 [checkSdpValidity] Sdp contains 1 media description(s).
Tue Sep 17 07:26:57 2024 [checkSdpValidity] without video control.
Tue Sep 17 07:26:57 2024 [checkSdpValidity] Media 0 type 1 contains 22 formats.
Tue Sep 17 07:26:57 2024 [checkSdpValidity] media audio.
Tue Sep 17 07:26:57 2024 [checkSdpValidity] Format : 96.
Tue Sep 17 07:26:57 2024 [checkSdpValidity] Format : 97.
Tue Sep 17 07:26:57 2024 [checkSdpValidity] Format : 98.
1726547217 -> Tue Sep 17 07:26:57 2024 [checkSdpValidity] Format : 0.
Tue Sep 17 07:26:57 2024 [checkSdpValidity] Format : 8.
Tue Sep 17 07:26:57 2024 [checkSdpValidity] return false.
Tue Sep 17 07:26:57 2024 [convertSdpIntoTsdp] Sdp contains 1 media description(s).
Tue Sep 17 07:26:57 2024 [convertSdpIntoTsdp] No Direction in the session part.
Tue Sep 17 07:26:57 2024 [convertSdpIntoTsdp] Check the direction in Session part - result:0.
Tue Sep 17 07:26:57 2024 [convertSdpIntoTsdp] new media analyzed=0 sdp_index=0 count=1.
Tue Sep 17 07:26:57 2024 [audioMediaSentToCH] media AUDIO detected (previous crypto=0), clearMediaLine=0
Tue Sep 17 07:26:57 2024 [convertCryptoInfoInFile] clearMediaLine2: 0.
Tue Sep 17 07:26:57 2024 [convertAudioMedia] The audio media contains 22 format(s).
Tue Sep 17 07:26:57 2024 [convertAudioMedia] Format 0 is 96 opt syst G711=0.
Tue Sep 17 07:26:57 2024 [convertAudioMedia] 96.
Tue Sep 17 07:26:57 2024 [convertAudioMedia] Format 1 is 97 opt syst G711=0.
Tue Sep 17 07:26:57 2024 [convertAudioMedia] 97.
Tue Sep 17 07:26:57 2024 [convertAudioMedia] Format 2 is 98 opt syst G711=0.
Tue Sep 17 07:26:57 2024 [convertAudioMedia] 98.
Tue Sep 17 07:26:57 2024 [convertAudioMedia] Format 3 is 0 opt syst G711=0.
Tue Sep 17 07:26:57 2024 [convertAudioMedia] Format is PCMU.
Tue Sep 17 07:26:57 2024 [convertAudioMedia] Format 4 is 8 opt syst G711=0.
Tue Sep 17 07:26:57 2024 [convertAudioMedia] Format is PCMA.
Tue Sep 17 07:26:57 2024 [convertAudioMedia] Format 5 is 3 opt syst G711=0.
1726547217 -> Tue Sep 17 07:26:57 2024 [convertAudioMedia] Format 6 is 9 opt syst G711=0.
Tue Sep 17 07:26:57 2024 [convertAudioMedia] Format is G722.
Tue Sep 17 07:26:57 2024 [convertAudioMedia] Format 7 is 99 opt syst G711=0.
Tue Sep 17 07:26:57 2024 [convertAudioMedia] 99.
Tue Sep 17 07:26:57 2024 [convertAudioMedia] Format 8 is 18 opt syst G711=0.
Tue Sep 17 07:26:57 2024 [convertAudioMedia] Format is G729.
Tue Sep 17 07:26:57 2024 [convertAudioMedia] Format 9 is 100 opt syst G711=0.
Tue Sep 17 07:26:57 2024 [convertAudioMedia] 100.
Tue Sep 17 07:26:57 2024 [convertAudioMedia] Format is DTMF:100.
Tue Sep 17 07:26:57 2024 [convertAudioMedia] tsdp is full.
Tue Sep 17 07:26:57 2024 [convertAudioMedia] invalid Direction in this media part.
Tue Sep 17 07:26:57 2024 [convertAudioMedia] Connection address retrieved in sdp: 172.16.203.40.
Tue Sep 17 07:26:57 2024 [convertIPStrIntoTuipv] 172.16.203.40 => 172.16.203.40
Tue Sep 17 07:26:57 2024 [audioMediaSentToCH] sdp index=0.
Tue Sep 17 07:26:57 2024 [display_sdp] address =172.16.203.40
Tue Sep 17 07:26:57 2024 [display_sdp] codecs =1.
Tue Sep 17 07:26:57 2024 [display_sdp] codecs =0.
Tue Sep 17 07:26:57 2024 [display_sdp] codecs =27.
Tue Sep 17 07:26:57 2024 [display_sdp] codecs =17.
Tue Sep 17 07:26:57 2024 [display_sdp] codecs =100.
Tue Sep 17 07:26:57 2024 [display_sdp] direction=0.
Tue Sep 17 07:26:57 2024 [convertSdpIntoTsdp] ok.
Tue Sep 17 07:26:57 2024 [convertSdpIntoTsdp] 5-crypto_info=0xbfffec54 crypto_index=0 clear media=1
Tue Sep 17 07:26:57 2024 [convertSdpIntoTsdp] only one media taken into account xxx crypto_index=0 clear media=1
1726547217 -> Tue Sep 17 07:26:57 2024 [convertSdpIntoTsdp] 3-crypto_info=0xbfffec54 crypto_index=0 clear media=1
Tue Sep 17 07:26:57 2024 [receiveInviteMessage] NO 100rel REQUIRED for this call.
Tue Sep 17 07:26:57 2024 [CInitialState::receiveinvitemessage allow_update 0 prack_required 0
Tue Sep 17 07:26:57 2024 [receiveinvitemessage] get requri.
Tue Sep 17 07:26:57 2024 [convertCSipURLIntoSipuri] uri = sip:0@172.16.5.6.
Tue Sep 17 07:26:57 2024 [decodeInviteTo].
Tue Sep 17 07:26:57 2024 [convertCSipURLIntoSipuri] uri = sip:0@172.16.5.6.
Tue Sep 17 07:26:57 2024 [decodeInviteReferredBy].
Tue Sep 17 07:26:57 2024 [decodeInviteReferredBy] ReferBy = Zero indirect=4.
Tue Sep 17 07:26:57 2024 [decodeInviteACSBU].
Tue Sep 17 07:26:57 2024 systemOption[BOOL_SIP_DIVERSION_INFO_TRANSIT]=0
Tue Sep 17 07:26:57 2024 [receiveInviteMessage] concatenating from header
Tue Sep 17 07:26:57 2024 [receiveInviteMessage] temp_fromuserinfo = 2991
Tue Sep 17 07:26:57 2024 [receiveInviteMessage] From_userinfo = 2991
Tue Sep 17 07:26:57 2024 1163[CMotorCall::setFilterUsedMode] To be traced = 0
Tue Sep 17 07:26:57 2024 1163[emitEventToMonitel] content size (lginfo): 656 ccal crypted= 0
Tue Sep 17 07:26:57 2024 ** SIP DEVICE **
Tue Sep 17 07:26:57 2024 1163[sendLgEvtSipCreate] get pilot ok trunk : 15
Tue Sep 17 07:26:57 2024 [ipc_thread] Normal case : evt 10767
Tue Sep 17 07:26:57 2024 [ipc_thread] IPC Thread : Ipc reception 3875.
Tue Sep 17 07:26:57 2024 [ipc_thread] IPC Thread : TCL thread signaled.
Tue Sep 17 07:26:57 2024 [CMotorCallManager::insertCallwithEqt] CMotorCall 6819 inserted.
1726547217 -> Tue Sep 17 07:26:57 2024 1163[sendLgEvtSipCreate] Event sent on eqt : 6819
Tue Sep 17 07:26:57 2024 [display_ipc_out] ------------ Begin ---------------
Tue Sep 17 07:26:57 2024 Id : -1
Tue Sep 17 07:26:57 2024 INVITE
Tue Sep 17 07:26:57 2024 REQUEST URI : <> 0@172.16.5.6:5060 ; user=name
Tue Sep 17 07:26:57 2024 FROM : <ISAAC MUKONYEZI > 2991@172.16.5.6:5060 ; user=name
Tue Sep 17 07:26:57 2024 TO : <0> 0@172.16.5.6:5060 ; user=name
Tue Sep 17 07:26:57 2024 CAC : 0
Tue Sep 17 07:26:57 2024 CAC ADDRESS :
Tue Sep 17 07:26:57 2024 CAC-CSBU info : UNKNOWN
Tue Sep 17 07:26:57 2024 CLIR : 0
Tue Sep 17 07:26:57 2024 Prack Required : 0
Tue Sep 17 07:26:57 2024 Allow Update : 0
Tue Sep 17 07:26:57 2024 SDP :
Tue Sep 17 07:26:57 2024 ADDRESS : 172.16.203.40 :7228
Tue Sep 17 07:26:57 2024 ALGOS :
Tue Sep 17 07:26:57 2024 PCMU
Tue Sep 17 07:26:57 2024 PCMA
Tue Sep 17 07:26:57 2024 G722
Tue Sep 17 07:26:57 2024 G729
Tue Sep 17 07:26:57 2024 100
Tue Sep 17 07:26:57 2024 DIRECTION : SEND & RECEIVE
Tue Sep 17 07:26:57 2024 video index : 0
Tue Sep 17 07:26:57 2024 crypto index : 0
Tue Sep 17 07:26:57 2024 N_GW_EXT : -1
Tue Sep 17 07:26:57 2024 [display_ipc_out] ------------- End ----------------
Tue Sep 17 07:26:57 2024 1163[CMotorCall::setCurrentState] NOE-SIP : sendBackupUpdate Msg not sent to twin CS
Tue Sep 17 07:26:57 2024 1163[CMotorCall::getOutgoingCall] outgoingCall:0
Tue Sep 17 07:26:57 2024 1163 [CCall::getCallDuplicationData]
Tue Sep 17 07:26:57 2024 [CViaSetSerializer::copy] Added via 7c59 at index 0
1726547217 -> Tue Sep 17 07:26:57 2024 1163 [CCall::getCallDuplicationData] : Request URI = sip:0@172.16.5.6, Call ID = -zSGrwovU9, Local URI = sip:0@172.16.5.6, Local Tag =
, Remote URI = sip:2991@172.16.5.6, Remote Tag = jafB4Dli1, Local CSeq = 1847570026, Remote CSeq = 20
Tue Sep 17 07:26:57 2024 [CViaSetSerializer::serialize] Serialized via set : ViaSerialize=Via: SIP/2.0/UDP 172.16.203.40:61459;received=172.16.203.40;branch=z9hG4bK.zTwT0dWy
E;rport=61459
Tue Sep 17 07:26:57 2024 SendToSipgwCpuSec: Message sent to the STAND-BY CPU
Tue Sep 17 07:26:57 2024 [receiveInviteMessage] send RemoteSdp to the StandBy.
Tue Sep 17 07:26:57 2024 crms00401657 SIP MajorAlarm is suppressed!!![CDuplicateCall::sendRemoteSdp] szSdpMessage > 1000 !!!!!!!!!.SDP not copied
Tue Sep 17 07:26:57 2024 [exec_ipc] in 3866.
Tue Sep 17 07:26:57 2024 [display_ipc_in] ------------ Begin ---------------
Tue Sep 17 07:26:57 2024 neqt : 6819 Id : -1
Tue Sep 17 07:26:57 2024 REQUEST FAILURE
Tue Sep 17 07:26:57 2024 xx : 4
Tue Sep 17 07:26:57 2024 RELATIVE REQUEST : INVITE
Tue Sep 17 07:26:57 2024 [display_ipc_in] ------------- End ----------------
Tue Sep 17 07:26:57 2024 [CMotorCallManager::onIncomingEvent] an event 10767 arrived on the eqt 6819 -1.
Tue Sep 17 07:26:57 2024 [CMotorCallManager::onIncomingEvent] state = PROCEEDING_STATE.
Tue Sep 17 07:26:57 2024 [onIncomingEvent] REQUEST FAILURE arrived.
Tue Sep 17 07:26:57 2024 [receiveRequestFailureEvent] Call: -zSGrwovU9 eqt: 6819 PROCEEDING_STATE received an event.
1726547217 -> Tue Sep 17 07:26:57 2024 1163[CMotorCall::getOutgoingCall] outgoingCall:0
Tue Sep 17 07:26:57 2024 [receiveRequestFailureEvent] incoming call.
Tue Sep 17 07:26:57 2024 1163 [CCall::makeGenericResponse] 404
Tue Sep 17 07:26:57 2024 1163 [CCall::getDialog] Confirmed Dialog is not found (ID = ;jafB4Dli1)
Tue Sep 17 07:26:57 2024 1163 [CCall::getDialog] Returning Initial Dialog Server
Tue Sep 17 07:26:57 2024 15f9 [CDialog::createResponse] 404
Tue Sep 17 07:26:57 2024 2109 [CTransProceedingState::createResponse] CResponse 4D05 ref=1
Tue Sep 17 07:26:57 2024 [DBG: CResponse::freeAttributes]
Tue Sep 17 07:26:57 2024 [DBG: CMessage::freeAttributes Start Alert freeAttributes !!!!!!!!]:
Tue Sep 17 07:26:57 2024 [DBG: CResponse::freeAttributes] Ends
Tue Sep 17 07:26:57 2024 4D05 [CMessage::send] sip_sendMessage (172.16.203.40:61459)
Tue Sep 17 07:26:57 2024 SEND MESSAGE TO NETWORK (172.16.203.40:61459 [UDP]) (BUFF LEN = 441)
----------------------utf8-----------------------
SIP/2.0 404 Not Found
Allow: INVITE, ACK, CANCEL, BYE, PRACK, NOTIFY, REFER, SUBSCRIBE, OPTIONS, UPDATE
User-Agent: OmniPCX Enterprise R100.0 n1.291.66.a
To: "0" <sip:0@172.16.5.6>;tag=2a7666c656cf732ee73d0559b6ea8614
From: "ISAAC MUKONYEZI " <sip:2991@172.16.5.6>;tag=jafB4Dli1
Call-ID: -zSGrwovU9
CSeq: 20 INVITE
Via: SIP/2.0/UDP 172.16.203.40:61459;received=172.16.203.40;branch=z9hG4bK.zTwT0dWyE;rport=61459
Content-Length: 0

-------------------------------------------------
Tue Sep 17 07:26:57 2024 4D05 [CMessage::onSendSuccess]
1726547217 -> Tue Sep 17 07:26:57 2024 2109 [CTransaction::onSendSuccess] CMessage 4D05 send Success
Tue Sep 17 07:26:57 2024 15f9 [CDialog::onSendSuccess]
Tue Sep 17 07:26:57 2024 [CResponse::~CResponse]
Tue Sep 17 07:26:57 2024 4D04 [~CResponse] resp(100) -zSGrwovU9 20 INVITE
Tue Sep 17 07:26:57 2024 [CResponse::~CResponse Ends]
Tue Sep 17 07:26:57 2024 [CMessage::~CMessage Ends]
Tue Sep 17 07:26:57 2024 2109 [CTransProceedingState::createResponse] Final : Transaction changes to Completed state
Tue Sep 17 07:26:57 2024 2109 [CTransaction::changeState] STATE CHANGED TO COMPLETED
Tue Sep 17 07:26:57 2024 2109 [CTransaction::changeState] notifying the parent dialog
Tue Sep 17 07:26:57 2024 15f9 [CDialog::onTransactionState(pTrans = 2109, previousState = Proceeding, currentState = Completed, reason = Final resp creation]
Tue Sep 17 07:26:57 2024 2109 [CTransaction::startTimer] Timer H is started (delay = 32000 ms)
Tue Sep 17 07:26:57 2024 15f9 [CDialog::updateRouteSet] there are 0 record-route headers
Tue Sep 17 07:26:57 2024 SendToSipgwCpuSec: Message sent to the STAND-BY CPU
Tue Sep 17 07:26:57 2024 [exec_ipc] in end 3866.
Tue Sep 17 07:26:57 2024 RECEIVE MESSAGE FROM NETWORK (172.16.203.40:61459 [UDP])
----------------------utf8-----------------------
ACK sip:0@172.16.5.6 SIP/2.0
Via: SIP/2.0/UDP 172.16.203.40:61459;branch=z9hG4bK.zTwT0dWyE;rport
Call-ID: -zSGrwovU9
From: "ISAAC MUKONYEZI " <sip:2991@172.16.5.6>;tag=jafB4Dli1
To: "0" <sip:0@172.16.5.6>;tag=2a7666c656cf732ee73d0559b6ea8614
Contact: "ISAAC MUKONYEZI " <sip:2991@172.16.203.40:61459;transport=udp>;expires=3600;+sip.instance="<urn:uuid:355f279e-57ca-00b0-8716-b254daa2e808>";+org.linphone.specs="li
me"
Max-Forwards: 70
CSeq: 20 ACK

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

1726547217 -> Tue Sep 17 07:26:57 2024 [CIOCom::receiveRequest] optimisation value 1
Tue Sep 17 07:26:57 2024 [CIOCom::IsNoeOptionsMsg] Not in same domain FAILED => 172.16.5.6 main1
Tue Sep 17 07:26:57 2024 [CIOCom::IsNoeOptionsMsg] NoeOpt 0
Tue Sep 17 07:26:57 2024 4F9A [CRequest::CRequest] Creation of a request (RECEIVED FROM NETWORK)
Tue Sep 17 07:26:57 2024 [CIOCom::receiveRequest] Received CRequest 4F9A ref=1
Tue Sep 17 07:26:57 2024 [CIOCom::receiveRequest] rport1 empty
Tue Sep 17 07:26:57 2024 [CIOCom::receiveRequest] Set received param on first Via header : 172.16.203.40
Tue Sep 17 07:26:57 2024 [CDispatcher::onIncomingRequest()]
Tue Sep 17 07:26:57 2024 [CDispatcher::processRequestFromManagerIndex()] start at index 0
Tue Sep 17 07:26:57 2024 102 [CCallManager::onIncomingRequest()]
Tue Sep 17 07:26:57 2024 1163 [CCall::receiveRequest] ACK
Tue Sep 17 07:26:57 2024 1163 [CCall::getDialog] Confirmed Dialog is not found (ID = 2a7666c656cf732ee73d0559b6ea8614;jafB4Dli1)
Tue Sep 17 07:26:57 2024 1163 [CCall::getDialog] Returning Initial Dialog Server
Tue Sep 17 07:26:57 2024 15f9 [CDialog::receiveRequest]
Tue Sep 17 07:26:57 2024 15f9 [CDialog::receiveAckRequest]
Tue Sep 17 07:26:57 2024 2109 [CTransCompletedState::receiveRequest] ACK(UDP) : Transaction changes to Confirmed state
Tue Sep 17 07:26:57 2024 2109 [CTransaction::changeState] STATE CHANGED TO CONFIRMED
Tue Sep 17 07:26:57 2024 2109 [CTransaction::changeState] notifying the parent dialog
Tue Sep 17 07:26:57 2024 15f9 [CDialog::onTransactionState(pTrans = 2109, previousState = Completed, currentState = Confirmed, reason = Request reception]
1726547217 -> Tue Sep 17 07:26:57 2024 2109 [CTransaction::freeTimerToken] Timer G is freed
Tue Sep 17 07:26:57 2024 2109 [CTransaction::freeTimerToken] Timer H is freed
Tue Sep 17 07:26:57 2024 2109 [CTransaction::startTimer] Timer I is started (delay = 5000 ms)
Tue Sep 17 07:26:57 2024 15f9 [CDialog::receiveAckRequest] receiving an ACK on a previously rejected INVITE
Tue Sep 17 07:26:57 2024 1163 [CCall::receiveRequest] iErrorCode=488.
Tue Sep 17 07:26:57 2024 1163 [CCall::checkAuthentication] ACK
Tue Sep 17 07:26:57 2024 1163[CMotorCall::controlAuthentication] ---*--- AUTH ---*--- Control authentication
Tue Sep 17 07:26:57 2024 1163[CMotorCall::controlAuthentication] ---*--- END-03 ---*--- Control authentication
Tue Sep 17 07:26:57 2024 1163 [CCall::controlAuthentication] need auth =0
Tue Sep 17 07:26:57 2024 1163 [CCall::checkAuthentication] AUTHENT=0
Tue Sep 17 07:26:57 2024 1163 [CCall::checkAuthentication] iRetValue=0
Tue Sep 17 07:26:57 2024 1163 [CCall::receiveRequest] extGw=-1.
Tue Sep 17 07:26:57 2024 1163[CMotorCall::onReceiveRequest] request received.
Tue Sep 17 07:26:57 2024 1163[CMotorCall::onReceiveRequest] s=0 d=1 v=0 o=0.
Tue Sep 17 07:26:57 2024 1163[CMotorCall::onReceiveRequest] request for gateway.
Tue Sep 17 07:26:57 2024 [DBG: CSipSdpMessage::Destructor]
Tue Sep 17 07:26:57 2024 [DBG: CSipSdpMessage::Destructor] Ends
Tue Sep 17 07:26:57 2024 1163[CMotorCall::onReceiveRequest] state = TERMINATED_STATE.
Tue Sep 17 07:26:57 2024 1163[CMotorCall::compareDialogFromContext] same Dialog
Tue Sep 17 07:26:57 2024 1163[CMotorCall::compareDialogFromContext] same Dialog
1726547217 -> Tue Sep 17 07:26:57 2024 1163[CMotorCall::methodAckReceived] Ack => update the inviteContext.
Tue Sep 17 07:26:57 2024 [receiveAckMessage] Call: -zSGrwovU9 eqt: 6819 TERMINATED_STATE received a message.
Tue Sep 17 07:26:57 2024 [receiveAckMessage] boolean flag check 0
Tue Sep 17 07:26:57 2024 1163[emitEventToMonitel] content size (lginfo): 656 ccal crypted= 0
Tue Sep 17 07:26:57 2024 1163[emitEventToMonitel] CAC = 0 eqtCAC = -1
Tue Sep 17 07:26:57 2024 1163[emitEventToMonitel] cacUsed=0 eqtCac=-1
Tue Sep 17 07:26:57 2024 1163[emitEventToMonitel] displayIPCMessageOut
Tue Sep 17 07:26:57 2024 ** SIP DEVICE **
Tue Sep 17 07:26:57 2024 [sendLgEvtSip] Event sent on eqt : 6819 Id :-1
Tue Sep 17 07:26:57 2024 [ipc_thread] Normal case : evt 10773
Tue Sep 17 07:26:57 2024 [ipc_thread] IPC Thread : Ipc reception 3875.
Tue Sep 17 07:26:57 2024 [ipc_thread] IPC Thread : TCL thread signaled.
Tue Sep 17 07:26:57 2024 [display_ipc_out] ------------ Begin ---------------
Tue Sep 17 07:26:57 2024 Id : -1
Tue Sep 17 07:26:57 2024 ACK
Tue Sep 17 07:26:57 2024 [display_ipc_out] ------------- End ----------------
Tue Sep 17 07:26:57 2024 [exec_ipc] in 3866.
Tue Sep 17 07:26:57 2024 [display_ipc_in] ------------ Begin ---------------
Tue Sep 17 07:26:57 2024 neqt : 6819 Id : -1
Tue Sep 17 07:26:57 2024 SIP EQT RELEASED
Tue Sep 17 07:26:57 2024 [display_ipc_in] ------------- End ----------------
Tue Sep 17 07:26:57 2024 [CMotorCallManager::onIncomingEvent] an event 10773 arrived on the eqt 6819 -1.
Tue Sep 17 07:26:57 2024 1163[CMotorCall::emitSipEqtReleaseAck] SIP_EQT_RELEASE_ACK
Tue Sep 17 07:26:57 2024 1163[emitEventToMonitel] content size (lginfo): 656 ccal crypted= 0
Tue Sep 17 07:26:57 2024 1163[emitEventToMonitel] CAC = 0 eqtCAC = -1
Tue Sep 17 07:26:57 2024 1163[emitEventToMonitel] cacUsed=0 eqtCac=-1
Tue Sep 17 07:26:57 2024 1163[emitEventToMonitel] displayIPCMessageOut
Tue Sep 17 07:26:57 2024 ** SIP DEVICE **
Tue Sep 17 07:26:57 2024 [sendLgEvtSip] Event sent on eqt : 6819 Id :-1
Tue Sep 17 07:26:57 2024 [display_ipc_out] ------------ Begin ---------------
Tue Sep 17 07:26:57 2024 Id : -1
Tue Sep 17 07:26:57 2024 SIP_EQT_RELEASE_ACK
Tue Sep 17 07:26:57 2024 [display_ipc_out] ------------- End ----------------
Tue Sep 17 07:26:57 2024 [CMotorCallManager::onIncomingEvent] state = TERMINATED_STATE.
Tue Sep 17 07:26:57 2024 1163[CMotorCall::unRegister] Remove eqt : 6819 diag : -1 from the map.
Tue Sep 17 07:26:57 2024 [CMotorCallManager::eraseCallwithEqt] erase 6819 -1.
Tue Sep 17 07:26:57 2024 [CMotorCallManager::eraseCallwithEqt] erase 6819 -1.
Tue Sep 17 07:26:57 2024 [CMotorCallManager::onIncomingEvent] killSession.
Tue Sep 17 07:26:57 2024 1163 [CCall::killSession]
Tue Sep 17 07:26:57 2024 2109 [CTransaction::changeState] STATE CHANGED TO TERMINATED
Tue Sep 17 07:26:57 2024 2109 [CTransaction::freeTimerToken] Timer I is freed
Tue Sep 17 07:26:57 2024 2109 [CTransaction::changeState] no transaction state, terminated reason : None
1726547217 -> Tue Sep 17 07:26:57 2024 1163 [CCall::killSession] notifying the call manager
Tue Sep 17 07:26:57 2024 102 [CCallManager::onCallClosed]
Tue Sep 17 07:26:57 2024 102 [CCallManager::onCallClosed()] Call 1163 closed. Removing call 145898464 from the maps
Tue Sep 17 07:26:57 2024 [CMotorCallManager::onNotifyCallClosed] Call : -zSGrwovU9 is closed.
Tue Sep 17 07:26:57 2024 [CMotorCallManager::onNotifyCallClosed] call processResponse.
Tue Sep 17 07:26:57 2024 1163[CMotorCall::chooseBestResponse] pForkedBranch=0x8b48190 iResponseCode=2000.
Tue Sep 17 07:26:57 2024 1163[CMotorCall::chooseBestResponse] next branch.
Tue Sep 17 07:26:57 2024 1163[CMotorCall::processResponse] no response found.
Tue Sep 17 07:26:57 2024 [CMotorCallManager::onNotifyCallClosed] Remove eqt from the map.
Tue Sep 17 07:26:57 2024 102 [CCallManager::onCallClosed] pCall: 1163 added in the Garbage List
Tue Sep 17 07:26:57 2024 [exec_ipc] in end 3866.
Tue Sep 17 07:26:57 2024 [CTransaction::notifyDialog]
Tue Sep 17 07:26:57 2024 [CTransaction::notifyDialog] notify parent dialog
Tue Sep 17 07:26:57 2024 15f9 [CDialog::onTransactionState] dialog is terminated
Tue Sep 17 07:26:57 2024 15f9 [CDialog::onTransactionState] onDialogClosed
Tue Sep 17 07:26:57 2024 1163 [CCall::onDialogClosed]
Tue Sep 17 07:26:57 2024 1163 [CCall::onDialogClosed] only initial *********
Tue Sep 17 07:26:57 2024 1163 [CCall::onDialogClosed] All the initial dialogs in the Call are closed
1726547218 -> Tue Sep 17 07:26:57 2024 2109 [~CTransaction] transaction 20 INVITE
Tue Sep 17 07:26:57 2024 [CRequest::~CRequest]
Tue Sep 17 07:26:57 2024 4F9A [~CRequest] req -zSGrwovU9 20 ACK
Tue Sep 17 07:26:57 2024 [CRequest::~CRequest] Ends
Tue Sep 17 07:26:57 2024 [DBG: CMessage::freeAttributes Start Alert freeAttributes !!!!!!!!]:
Tue Sep 17 07:26:57 2024 [CMessage::~CMessage Ends]
Tue Sep 17 07:26:57 2024 [fnInit] *************************** Init timeout.
Tue Sep 17 07:26:57 2024 [fnInit] treatTimeout.
Tue Sep 17 07:26:57 2024 [CMotorInitTimerContext::treatTimeout] emitRegisterMessage gw=2.
Tue Sep 17 07:26:57 2024 [CMotorInitTimerContext::start] delay =6000 gw 2.
Tue Sep 17 07:26:57 2024 [startRegistration] timer value=6000.
Tue Sep 17 07:26:57 2024 2104 [CTransCompletedState::timerFires] Timer J fires
Tue Sep 17 07:26:57 2024 2104 [CTransCompletedState::timerFires] Timer J : Transaction changes to Terminated state
Tue Sep 17 07:26:57 2024 2104 [CTransaction::changeState] STATE CHANGED TO TERMINATED
Tue Sep 17 07:26:57 2024 2104 [CTransaction::changeState] no transaction state, terminated reason : Timer J fires
Tue Sep 17 07:26:58 2024 [CTransaction::notifyDialog]
Tue Sep 17 07:26:58 2024 15f6 [CDialog::onTransactionState] dialog is terminated
Tue Sep 17 07:26:58 2024 15f6 [CDialog::onTransactionState] onDialogClosed
1726547218 -> Tue Sep 17 07:26:58 2024 1160 [CCall::onDialogClosed]
Tue Sep 17 07:26:58 2024 1160 [CCall::onDialogClosed] only initial *********
Tue Sep 17 07:26:58 2024 1160 [CCall::onDialogClosed] All the initial dialogs in the Call are closed
Tue Sep 17 07:26:58 2024 1160 [CCall] closed
Tue Sep 17 07:26:58 2024 102 [CCallManager::onCallClosed]
Tue Sep 17 07:26:58 2024 102 [CCallManager::onCallClosed()] Call 1160 closed. Removing call 145964872 from the maps
Tue Sep 17 07:26:58 2024 [CMotorCallManager::onNotifyCallClosed] Call : 594d8ff50eab09660a603cf80b448383@172.16.9.125:5060 is closed.
Tue Sep 17 07:26:58 2024 [CMotorCallManager::onNotifyCallClosed] Remove eqt from the map.
Tue Sep 17 07:26:58 2024 102 [CCallManager::onCallClosed] pCall: 1160 added in the Garbage List
Tue Sep 17 07:26:58 2024 2104 [~CTransaction] transaction 102 OPTIONS
Tue Sep 17 07:26:58 2024 102 [CCallManager::removeGarbage] at index i = 2
Tue Sep 17 07:26:58 2024 1160 [CMotorCall::clear_subscription] no subscription managed
Tue Sep 17 07:26:58 2024 1160[CMotorCall::~CMotorCall] Call : 594d8ff50eab09660a603cf80b448383@172.16.9.125:5060 is being destructed.
Tue Sep 17 07:26:58 2024 1160[CMotorCall::~CMotorCall] Call : 594d8ff50eab09660a603cf80b448383@172.16.9.125:5060 is being destructed.
Tue Sep 17 07:26:58 2024 [CMotorCall::getSupervisorKeys] Returning NULL value for supervisor keys
Tue Sep 17 07:26:58 2024 [DBG: CSipSdpMessage::Destructor]
Tue Sep 17 07:26:58 2024 [DBG: CSipSdpMessage::Destructor] Ends
Tue Sep 17 07:26:58 2024 [DBG: CSipSdpMessage::Destructor]
Tue Sep 17 07:26:58 2024 [DBG: CSipSdpMessage::Destructor] Ends
Tue Sep 17 07:26:58 2024 1160 [CCall::~CCall] resources number: 19
1726547218 -> Tue Sep 17 07:26:58 2024 1160 [CCall::TestDegradedModeEnd] resources number: 18
Tue Sep 17 07:26:58 2024 1160 [CCall::removeGarbage]
Tue Sep 17 07:26:58 2024 15f6 [CDialog::~CDialog] resources number: 17
Tue Sep 17 07:26:58 2024 [CResponse::~CResponse]
Tue Sep 17 07:26:58 2024 4CFC [~CResponse] resp(200) 594d8ff50eab09660a603cf80b448383@172.16.9.125:5060 102 OPTIONS
Tue Sep 17 07:26:58 2024 [CRequest::~CRequest]
Tue Sep 17 07:26:58 2024 [CRequest::~CRequest] Ends
Tue Sep 17 07:26:58 2024 [DBG: CMessage::freeAttributes Start Alert freeAttributes !!!!!!!!]:
Tue Sep 17 07:26:58 2024 [CMessage::~CMessage Ends]
Tue Sep 17 07:26:58 2024 [CResponse::~CResponse Ends]
Tue Sep 17 07:26:58 2024 [CMessage::~CMessage Ends]
Tue Sep 17 07:26:58 2024 [CMessage::~CMessage Ends]
Tue Sep 17 07:26:58 2024 [CCall::~CCall] Ends
Tue Sep 17 07:26:58 2024 102 [CCallManager::removeGarbage] at index i = 1
Tue Sep 17 07:26:58 2024 1163 [CMotorCall::clear_subscription] no subscription managed
Tue Sep 17 07:26:58 2024 1163[CMotorCall::~CMotorCall] Call : -zSGrwovU9 is being destructed.
Tue Sep 17 07:26:58 2024 [CMotorCall::getSupervisorKeys] Returning NULL value for supervisor keys
Tue Sep 17 07:26:58 2024 [DBG: CSipSdpMessage::Destructor]
Tue Sep 17 07:26:58 2024 [DBG: CSipSdpMessage::Destructor] Ends
Tue Sep 17 07:26:58 2024 [DBG: CSipSdpMessage::Destructor]
Tue Sep 17 07:26:58 2024 [DBG: CSipSdpMessage::Destructor] Ends
Tue Sep 17 07:26:58 2024 1163 [CCall::~CCall] resources number: 18
1726547218 -> Tue Sep 17 07:26:58 2024 1163 [CCall::TestDegradedModeEnd] resources number: 17
Tue Sep 17 07:26:58 2024 1163 [CCall::removeGarbage]
Tue Sep 17 07:26:58 2024 15f9 [CDialog::~CDialog] resources number: 16
Tue Sep 17 07:26:58 2024 [CSessionTimerContext::~CSessionTimerContext] Free CSessionTimerContext
Tue Sep 17 07:26:58 2024 [CResponse::~CResponse]
Tue Sep 17 07:26:58 2024 4D05 [~CResponse] resp(404) -zSGrwovU9 20 INVITE
Tue Sep 17 07:26:58 2024 [CRequest::~CRequest]
Tue Sep 17 07:26:58 2024 4F99 [~CRequest] req -zSGrwovU9 20 INVITE
Tue Sep 17 07:26:58 2024 [CRequest::~CRequest] Ends
Tue Sep 17 07:26:58 2024 [DBG: CMessage::freeAttributes Start Alert freeAttributes !!!!!!!!]:
Tue Sep 17 07:26:58 2024 [DBG: CSipSdpMessage::Destructor]
Tue Sep 17 07:26:58 2024 [DBG: CSipSdpMessage::Destructor] Ends
Tue Sep 17 07:26:58 2024 [CMessage::~CMessage Ends]
Tue Sep 17 07:26:58 2024 [CResponse::~CResponse Ends]
Tue Sep 17 07:26:58 2024 [CMessage::~CMessage Ends]
Tue Sep 17 07:26:58 2024 [CCall::~CCall] Ends
Tue Sep 17 07:26:58 2024 102 [CCallManager::removeGarbage] at index i = 0
Tue Sep 17 07:26:58 2024 115f [CMotorCall::clear_subscription] no subscription managed
Tue Sep 17 07:26:58 2024 115f[CMotorCall::~CMotorCall] Call : a71d8bdd is being destructed.
Tue Sep 17 07:26:58 2024 [CMotorCall::getSupervisorKeys] Returning NULL value for supervisor keys
Tue Sep 17 07:26:58 2024 [DBG: CSipSdpMessage::Destructor]
Tue Sep 17 07:26:58 2024 [DBG: CSipSdpMessage::Destructor] Ends
Tue Sep 17 07:26:58 2024 [DBG: CSipSdpMessage::Destructor]
1726547219 -> Tue Sep 17 07:26:58 2024 [DBG: CSipSdpMessage::Destructor] Ends
Tue Sep 17 07:26:58 2024 115f [CCall::~CCall] resources number: 17
Tue Sep 17 07:26:58 2024 115f [CCall::TestDegradedModeEnd] resources number: 16
Tue Sep 17 07:26:58 2024 115f [CCall::removeGarbage]
Tue Sep 17 07:26:58 2024 15f5 [CDialog::~CDialog] resources number: 15
Tue Sep 17 07:26:58 2024 [CResponse::~CResponse]
Tue Sep 17 07:26:58 2024 4CFB [~CResponse] resp(200) a71d8bdd 1071895603 OPTIONS
Tue Sep 17 07:26:58 2024 [CRequest::~CRequest]
Tue Sep 17 07:26:58 2024 4F90 [~CRequest] req a71d8bdd 1071895603 OPTIONS
Tue Sep 17 07:26:58 2024 [CRequest::~CRequest] Ends
Tue Sep 17 07:26:58 2024 [DBG: CMessage::freeAttributes Start Alert freeAttributes !!!!!!!!]:
Tue Sep 17 07:26:58 2024 [CMessage::~CMessage Ends]
Tue Sep 17 07:26:58 2024 [CResponse::~CResponse Ends]
Tue Sep 17 07:26:58 2024 [CMessage::~CMessage Ends]
Tue Sep 17 07:26:58 2024 [CCall::~CCall] Ends
Tue Sep 17 07:26:59 2024 [CKeepaliveManager::receiveSignal]

[haroun]

are you sure that th edevice is sending the whole number ? i creat the same issue by sending only the 0 to oxe a i got 404 check numbering plan in the device if
other
sip device check pnc cos (Public Network COS)if user config under user as sip dev or the related trunk group and network pnc if confIG under translatoer

[imukonyezi]

Hello Haroun,
Thank you for the feedback and support.
I have checked all configuration in PNC and all looks fine.
However today I checked some prefixes like 9 which is used for dialling the Operator is okay.
I tried directly seizing one of our PSTN trunks and I was still geting the error mesage. The configuration for the prefix directy accessing one of our PSTN trunks is as shown below


┌─Review/Modify: Prefix Plan──────────────────────────────────────────────┐
│ │
│ Node Number (reserved) : 1 │
│ Instance (reserved) : 1 │
│ Number : 605 │
│ │
│ Prefix Meaning + Professional trunk seize │
│ Prefix Information : 5 │
│ Private Route type + False │
│ │

However when I setup a speed dialling number that directly seizes the PSTN trunk using the prefix 605 as shown below, I am able to make an external call from the SIP device. So I am now left wondering what could be the issue.


┌─Review/Modify: Direct SpdDl No. Pref.─────────────────────────────────────────────┐
│ │
│ Node Number (reserved) : 1 │
│ Instance (reserved) : 1 │
│ Instance (reserved) : 1 │
│ Direct Speed Dial No. Prefix : 3420 │
│ │
│ Call Number : 6050314802650 │
│ Directory name : ISAAC DESK │
│ Directory First Name : ISAAC OFFICE │
│ UTF-8 Directory Name : ------------------------------------------- │
│ UTF-8 Directory First Name : ------------------------------------------- │
│ Call Restrictions - Barring + False │
│ Overfl.Dir.Spd Dl.No. Pref. : -------- │
│ Auto.Attendant Calling Right : 0 │
│ Call Type + Normal │
│ Timed Forward.Dir.Spd.Dl.No. : -------- │
│ Range Number : 255 │
│ Can be Called/Dialed By Name + YES │
│ Displayed Name : ISAAC OFFICE │
│ External DISA Dir.No. : -------- │
│ Phone book Name (Dial by name) : ISAAC │
│ Phone book First Name : DESK │
│ │
└───────────────────────────────────────────────────────────────────────────────────┘

[haroun]

can u past the meaning of the prefix 0
is it Professional trunk seize or Professional TG With Overlapping

[alex]

┌─Review/Modify: Prefix Plan──────────────────────────────────────────────┐
│ │
│ Node Number (reserved) : 1 │
│ Instance (reserved) : 1 │
│ Number : 605 │
│ │
│ Prefix Meaning + Professional trunk seize │
│ Prefix Information : 5 │
│ Private Route type + False │
│ │

Who configured SIP trunking on your PABX? Did he ever open the System documentation?
For SIP trinking it's clearly written:

6.3.3.8.2 Outgoing calls
Two kinds of numbering plan can be used for outgoing calls from a SIP external gateway:
• Routing prefix
• ARS

Don't use Prof.seize prefixes, check the docs

[haroun]

┌─Review/Modify: Prefix Plan──────────────────────────────────────────────┐
│ │
│ Node Number (reserved) : 1 │
│ Instance (reserved) : 1 │
│ Number : 605 │
│ │
│ Prefix Meaning + Professional trunk seize │
│ Prefix Information : 5 │
│ Private Route type + False │
│ │

Who configured SIP trunking on your PABX? Did he ever open the System documentation?
For SIP trinking it's clearly written:

6.3.3.8.2 Outgoing calls
Two kinds of numbering plan can be used for outgoing calls from a SIP external gateway:
• Routing prefix
• ARS

Don't use Prof.seize prefixes, check the docs

hi Alex another question for Seplos , in the old release we must mandatory change the compression type of the system to g729 to make call working with third party sip phones brand (polycom , GS ETC...] is that true for the actual release ?

[alex]

hi Alex another question for Seplos , in the old release we must mandatory change the compression type of the system to g729 to make call working with third party sip phones brand (polycom , GS ETC...] is that true for the actual release ?

Hi haroun
Never have problems with that. On R100.1 SEPLOS (Bittel IP60DT) sets work even in G.722.

[haroun]

hi Alex and thank you for your info
may be in r100 the system default compression is g719 because in th old release that was g723

[imukonyezi]

Dear Alex and Haroun,

Please find attached the parameters of the system configurations for the "0" prefix that we use to initiate external calls across all devices, including SIP extensions.

-Review/Modify: Prefix Plan-
Node Number (reserved) : 1
Instance (reserved) : 1
Number : 0
Prefix Meaning + ARS Personal Trk Grp
Discriminator No. : 0
Private Call Profile : 0

I have also attached the compression parameters showing that we are using G.729, as well as the multi-algorithm setting (though I'm not sure if it’s relevant to the issue of SIP devices not being able to make outgoing calls).

-Review/Modify: Compression Parameters-
Node Number (reserved):1
Instance (reserved): 1
Instance (reserved): 1
System Option + Compression Type
Compression Type + 729

Review/Modify: Compression Parameters
Node Number (reserved) : 1
Instance (reserved) : 1
Instance (reserved) : 1
System Option + Multi. Algorithms for Compression
Multi. Algorithms for Compression + False


Additionally, I’ve included a list of SIP parameters under the system settings. Could you let me know if there’s anything on that list I should pay particular attention to?

-[ 27 ] Instances: SIP parameters-
-> Packetization times per codec
Via Header
_ Inbound Calls Routing
TLS signaling possible
Local resources
Loose Route with RegID
Reject unidentified proxy calls
SRTP offer answer mode
Hotel doorcam application
Transfer : Refer using single step
RE- INVITE delay for hold
SIP Bearer Capability
Number of SIP trunks (UCaas)
Enhanced codec negotiation
G722 for SIP trunking sipmotor restart delay
Private SIP transit mode
SIP registered pseudo reservation
Blind transfer with direct RTP
From Header For Anonymous Calls Maximum Trunk Group Overflow
SIP video transit mode
Raise SIP Motor Incidents
Enhanced Canonical Form
Force NCT on Internal Route
SIP diversion info for incoming Accept keys for unsecured GW
Enforce T2 ISDN behavior

Alex, do SIP devices require a different prefix configuration to access the outgoing trunks for the PSTN? If so, what would be the best configuration for that?

I have also seen from another post where you advise that for sip devices there is need for a sip trunk and gateway. What would be the ideal configuration for the sip trunk and gateway? Also I think on our system we are only limited to one sip gateway.

Best regards,
Isaac

[oldboy]

-Review/Modify: Prefix Plan-
Node Number (reserved) : 1
Instance (reserved) : 1
Number : 0
Prefix Meaning + ARS Personal Trk Grp
Discriminator No. : 0
Private Call Profile : 0

If you change to ARS Professional trk grp with overlap does it change ??