09:27:27.346 TM.T11 01 SipTM_Idle Clearing globalSipIdentity 09:27:27.346 TM.T11 01 SipTM_Idle rcvd SIP call-leg request: INVITE 09:27:27.346 TM.T11 01 SipTM_Idle Looking for Responsible Registered Sip Identity 09:27:27.346 TM.T11 01 SipTM_Idle Checking Diversion header 09:27:27.346 TM.T11 01 SipTM_Idle Checking Calling party 09:27:27.347 TM.T11 01 SipTM_Idle Calling Party Check (sipIdentity=): isFoundIdentityRegistered=0, foundIdentity=0 09:27:27.347 TM.T11 01 SipTM_Idle Checking one & only trunk registration 09:27:27.347 TM.T11 01 SipTM_Idle Found Trunk Range Record 09:27:27.347 TM.T11 01 SipTM_Idle One & Only Check (sipIdentity=NPAnxx2072): isFoundIdentityRegistered=1, foundIdentity=1 09:27:27.348 TM.T11 01 SipTM_Idle Checking Called Party 09:27:27.348 TM.T11 01 SipTM_Idle Found! (globalSipIdentity is now NPAnxx2072): isFoundIdentityRegistered=1, foundIdentity=1 09:27:27.348 TM.T11 01 SipTM_Idle Found registered SIP identity 09:27:27.348 TM.T11 01 SipTM_Idle call-leg -> Offering 09:27:27.348 TM.T11 01 SipTM_Idle State change >> SipTM_Idle->SipTM_Trying 09:27:27.350 TM.T11 01 SipTM_Trying SDP offer is not loopback request 09:27:27.350 TM.T11 01 SipTM_Trying Ignoring P-Asserted-Identity header. 09:27:27.351 TM.T11 01 SipTM_Trying Processing From for Caller-ID. 09:27:27.351 TM.T11 01 SipTM_Trying Caller ID Name = "BATON ROUGE WA" 09:27:27.351 TM.T11 01 SipTM_Trying Caller ID Number = "NPANXX0303" 09:27:27.351 TM.T11 01 SipTM_Trying info: unable to set redirect number(s) from INVITE 09:27:27.352 TM.T11 01 SipTM_Trying sent: TA->InboundCall 09:27:27.352 TM.T11 01 Looking up source address for destination xx.xxx.xxx.130 09:27:27.352 TM.T11 01 call-leg (0x0x4fca59e0) -> src: 10.10.30.27 : 5060 dst: xx.xxx.xxx.130 : 5060 09:27:27.354 TM.T11 01 SipTM_Trying sent: 100 Trying 09:27:27.354 TA.T11 01 TAIdle rcvd: inboundCall from TM 09:27:27.354 TA.T11 01 State change >> TAIdle->TAInboundCall (TAS_Calling) 09:27:27.354 TA.T11 01 Failed - DID translation: no match for NPAnxx2081, using NPAnxx2081 09:27:27.355 TA.T11 Country is : 8 and it's default ring pattern is : 0 09:27:27.355 TA.T11 01 TAIdle sent: call to SB 09:27:27.355 SB.CALL Received association notice for CallID 45 and SIP 0gQAAC8WAAACBAAALxYAALSXPuPHm81KIHxssVUq7w6dqd/XtEl930ob+T1swV/Q@xx.xxx.xxx.130 09:27:27.355 SB.CALL Store cache 1 for CallID 45 and SIP 0gQAAC8WAAACBAAALxYAALSXPuPHm81KIHxssVUq7w6dqd/XtEl930ob+T1swV/Q@xx.xxx.xxx.130 09:27:27.356 SB.CALL Store cache 1 for CallID 45 and SIP 0gQAAC8WAAACBAAALxYAALSXPuPHm81KIHxssVUq7w6dqd/XtEl930ob+T1swV/Q@xx.xxx.xxx.130 09:27:27.356 TM.T11 01 SipTM_Trying tachg -> TAInboundCall 09:27:27.356 TM.T11 01 SipTM_Trying State change >> SipTM_Trying->SipTM_Pending 09:27:27.356 SB.CALL 45 Idle Called the call routine with NPAnxx2081 09:27:27.357 SB.CCM isMappable: 09:27:27.357 SB.CCM : Call Struct 0x0x504ad010 : Call-ID = 45 09:27:27.357 SB.CCM : Org Acct = T11 Dst Acct = NPAnxx2081 09:27:27.357 SB.CCM : Org Port ID = SipTrunk 0/0.100 Dst Port ID = unknown 0/0 09:27:27.357 SB.CCM : SDP Transaction = CallID: 45 09:27:27.358 SB.CCM : SDP Offer = 0x5054a110, (xx.xxx.xxx.130:16418) 09:27:27.358 SB.CCM isMappable: Call Connection Type is RTP_TO_TDM 09:27:27.358 RTP.CHANNEL allocateForInterface 0/1.1, status = 1, allocatedForInterface = 0 09:27:27.358 SB.CCM isMappable: Reserving RTP Channel 0/1.1 09:27:27.359 SB.CCM translateOffer: offer codec list: G729 PCMU 09:27:27.360 SB.CCM translateOffer: revised offer codec list: PCMU G729 09:27:27.360 SB.CCM translateOffer: codec list after answerer: PCMU G729 09:27:27.361 SB.CCM translateOffer: DTMF signaling: answerer has no restrictions configured, passing offer(NTE 101) through 09:27:27.361 SRTP.SDES NEGOTIATION SrtpNegotiation - SDP before offer filter 09:27:27.362 SRTP.SDES NEGOTIATION m=audio 16418 RTP/AVP 0 18 101 09:27:27.362 SRTP.SDES NEGOTIATION invoked pass-through SRTP filter 09:27:27.362 SRTP.SDES NEGOTIATION SrtpNegotiation - SDP after offer filter 09:27:27.362 SRTP.SDES NEGOTIATION m=audio 16418 RTP/AVP 0 18 101 09:27:27.363 SRTP.SDES NEGOTIATION SrtpNegotiation - SDP after offer translator 09:27:27.363 SRTP.SDES NEGOTIATION m=audio 16418 RTP/AVP 0 18 101 09:27:27.363 SB.CCM translateOffer: success 09:27:27.363 MEDIA.MANAGER Allocating media port. 09:27:27.363 MEDIA.MANAGER getSubstitutePort: No matching callIdMap entry found for call 45 09:27:27.364 MEDIA.MANAGER Call ID map : Added new entry : call ID 45 : session -78938595476871INIP4xx.xxx.xxx.130 : version 78938595476871 : index 78 09:27:27.364 MEDIA.MANAGER New media entry : type(0), callID(45), sessionID(-78938595476871INIP4xx.xxx.xxx.130), original IP(xx.xxx.xxx.130) ports(16418-16419), substitute IP(::) ports(10078-10079), RtpChannel(0/1.1), connection(0x0x50549510), sdpOverride(0), me(0x0x50547410). RtpChannel 0/1.1 09:27:27.364 SB.CALL 45 Idle Call sent from T11 to NPAnxx2081 (NPAnxx2081) 09:27:27.364 SB.CALL 45 State change >> Idle->Delivering 09:27:27.365 RTP.MANAGER VirtualPhone 0 - empty - RTP: Request resource 09:27:27.365 RTP.MANAGER VirtualPhone 0 - Dsp 0/1.1 - RTP: DSP channel allocated for the resource 09:27:27.365 RTP.PROVIDER VirtualPhone 0 - Dsp 0/1.1 - RTP: providing already allocated RTP channel 09:27:27.365 TA.T11 01 TAInboundCall CallResp event accepted 09:27:27.365 TA.T11 01 State change >> TAInboundCall->TAConnectWaitIn (TAS_Calling) 09:27:27.366 SA.NPAnxx2081 rcvd: deliver from SB 09:27:27.366 SA.NPAnxx2081 PM not ready to take call: process call coverage 09:27:27.366 SA.NPAnxx2081 sent: ClearCall to SB 09:27:27.366 SB.CALL 45 Delivering Called the clearCall routine 09:27:27.366 SB.CALL 45 Delivering Call Cleared on call from NPAnxx2081 to T11 09:27:27.366 SB.CALL 45 State change >> Delivering->Clearing 09:27:27.367 TA.T11 01 TAConnectWaitIn ClearCall event accepted 09:27:27.367 TA.T11 01 TAConnectWaitIn Processing a forward from call coverage 09:27:27.367 TA.T11 01 TAConnectWaitIn sent: call to SB 09:27:27.368 TA.T11 01 State change >> TAConnectWaitIn->TAInboundCall (TAS_Calling) 09:27:27.368 SB.CALL 45 Clearing Called the clearResponse routine 09:27:27.368 SB.CALL 45 State change >> Clearing->CallIdlePending 09:27:27.368 SB.CCM release: 09:27:27.368 SB.CCM : Call Struct 0x0x504ad010 : Call-ID = 45 09:27:27.368 SB.CCM : Org Acct = T11 Dst Acct = NPAnxx2081 09:27:27.369 SB.CCM : Org Port ID = SipTrunk 0/0.100 Dst Port ID = VirtualPhone 0/0 09:27:27.369 SB.CCM : SDP Transaction = CallID: 46 09:27:27.369 SB.CCM : SDP Offer = 0x5054a110, (xx.xxx.xxx.130:16418) 09:27:27.369 SB.CCM : Offer side SRTP session details 09:27:27.369 SB.CCM : None 09:27:27.370 SB.CCM : Answer side SRTP session details 09:27:27.370 SB.CCM : None 09:27:27.370 SB.CCM : RTP Channel = 0/1.1 09:27:27.370 SB.CCM release: Call Connection Type is RTP_TO_TDM 09:27:27.370 SB.CCM release: Releasing RTP Channel 0/1.1 09:27:27.370 RTP.CHANNEL RtpChannel::deallocate, status = 3, allocatedForInterface = 0 09:27:27.371 RTP.CHANNEL Channel 0/1.1 released successfully. 09:27:27.371 SB.CALL 45 CallIdlePending ClearResponse sent from T11 to NPAnxx2081 09:27:27.371 SB.CALL 46 Idle Called the call routine with npanxx6291 09:27:27 SB.TGMgr For dialed number npanxx6291, against template 337-NXX-XXXX, on TrunkGroup SIP TO GDS, the score is 4000 09:27:27.372 SB.CCM isMappable: 09:27:27.372 SB.CCM : Call Struct 0x0x50452810 : Call-ID = 46 09:27:27.372 SB.CCM : Org Acct = T11 Dst Acct = T11 09:27:27.372 SB.CCM : Org Port ID = SipTrunk 0/0.100 Dst Port ID = unknown 0/0 09:27:27.372 SB.CCM : SDP Transaction = CallID: 46 09:27:27.373 SB.CCM : SDP Offer = 0x5054a110, (xx.xxx.xxx.130:16418) 09:27:27.373 SB.CCM Baan: Rejecting call due to SIP call capacity license limits 09:27:27.373 SB.CALL 46 Idle Call sent from T11 to T11 (npanxx6291) 09:27:27.373 SB.CALL 46 State change >> Idle->Delivering 09:27:27.373 SA.NPAnxx2081 ERROR! clearResponse ignored 09:27:27.373 RTP.CHANNEL VirtualPhone 0 - Dsp 0/1.1 - RTP: releasing RTP resource 09:27:27.374 RTP.CHANNEL VirtualPhone 0 - Dsp 0/1.1 - RTP: releasing 09:27:27.374 TA.T11 01 TAInboundCall CallResp event accepted 09:27:27.374 TA.T11 01 State change >> TAInboundCall->TAConnectWaitIn (TAS_Calling) 09:27:27.374 TA.T11 Deliver event rejected, no RTP resource 09:27:27.374 SB.CALL 46 Delivering Called the clearCall routine 09:27:27.375 SB.CALL 46 Delivering Clearing due to Trunk Clear Reason on call from T11 to T11 09:27:27.375 SB.CALL 46 State change >> Delivering->Clearing 09:27:27.375 TA.T11 01 TAConnectWaitIn ClearCall event accepted 09:27:27.375 TA.T11 01 TAConnectWaitIn Redelivering a call 09:27:27.375 TA.T11 Country is : 8 and it's default ring pattern is : 0 09:27:27.376 TA.T11 01 TAConnectWaitIn sent: call to SB 09:27:27.376 TA.T11 01 State change >> TAConnectWaitIn->TAInboundCall (TAS_Calling) 09:27:27.376 SB.CALL 46 Clearing Called the clearResponse routine 09:27:27.376 SB.CALL 46 State change >> Clearing->CallIdlePending 09:27:27.376 SB.CCM release: 09:27:27.376 SB.CCM : Call Struct 0x0x50452810 : Call-ID = 46 09:27:27.377 SB.CCM : Org Acct = T11 Dst Acct = T11 09:27:27.377 SB.CCM : Org Port ID = SipTrunk 0/0.100 Dst Port ID = unknown 0/0 09:27:27.377 SB.CCM : SDP Transaction = CallID: 47 09:27:27.377 SB.CCM : SDP Offer = 0x5054a110, (xx.xxx.xxx.130:16418) 09:27:27.378 SB.CCM release: Call Connection Type is RTP_TO_RTP 09:27:27.378 SB.CALL 46 CallIdlePending ClearResponse sent from T11 to T11 09:27:27.378 SB.CALL 47 Idle Called the call routine with NPAnxx2081 09:27:27.378 SB.CCM isMappable: 09:27:27.378 SB.CCM : Call Struct 0x0x50433410 : Call-ID = 47 09:27:27.378 SB.CCM : Org Acct = T11 Dst Acct = NPAnxx2081 09:27:27.379 SB.CCM : Org Port ID = SipTrunk 0/0.100 Dst Port ID = unknown 0/0 09:27:27.379 SB.CCM : SDP Transaction = CallID: 47 09:27:27.379 SB.CCM : SDP Offer = 0x5054a110, (xx.xxx.xxx.130:16418) 09:27:27.379 SB.CCM isMappable: Call Connection Type is RTP_TO_TDM 09:27:27.380 RTP.CHANNEL allocateForInterface 0/1.1, status = 1, allocatedForInterface = 0 09:27:27.380 SB.CCM isMappable: Reserving RTP Channel 0/1.1 09:27:27.381 SB.CCM translateOffer: offer codec list: G729 PCMU 09:27:27.381 SB.CCM translateOffer: revised offer codec list: PCMU G729 09:27:27.382 SB.CCM translateOffer: codec list after answerer: PCMU G729 09:27:27.382 SB.CCM translateOffer: DTMF signaling: answerer has no restrictions configured, passing offer(NTE 101) through 09:27:27.383 SRTP.SDES NEGOTIATION SrtpNegotiation - SDP before offer filter 09:27:27.383 SRTP.SDES NEGOTIATION m=audio 16418 RTP/AVP 0 18 101 09:27:27.383 SRTP.SDES NEGOTIATION invoked pass-through SRTP filter 09:27:27.384 SRTP.SDES NEGOTIATION SrtpNegotiation - SDP after offer filter 09:27:27.384 SRTP.SDES NEGOTIATION m=audio 16418 RTP/AVP 0 18 101 09:27:27.384 SRTP.SDES NEGOTIATION SrtpNegotiation - SDP after offer translator 09:27:27.384 SRTP.SDES NEGOTIATION m=audio 16418 RTP/AVP 0 18 101 09:27:27.384 SB.CCM translateOffer: success 09:27:27.385 MEDIA.MANAGER Allocating media port. 09:27:27.385 MEDIA.MANAGER getSubstitutePort: No matching callIdMap entry found for call 47 09:27:27.386 MEDIA.MANAGER Call ID map : Added new entry : call ID 47 : session -78938595476871INIP4xx.xxx.xxx.130 : version 78938595476871 : index 80 09:27:27.386 MEDIA.MANAGER New media entry : type(0), callID(47), sessionID(-78938595476871INIP4xx.xxx.xxx.130), original IP(xx.xxx.xxx.130) ports(16418-16419), substitute IP(::) ports(10080-10081), RtpChannel(0/1.1), connection(0x0x50547e10), sdpOverride(0), me(0x0x50547c10). RtpChannel 0/1.1 09:27:27.386 SB.CALL 47 Idle Call sent from T11 to NPAnxx2081 (NPAnxx2081) 09:27:27.386 SB.CALL 47 State change >> Idle->Delivering 09:27:27.387 TA.T11 clearResponse event rejected, no matching CallID46 09:27:27.387 RTP.MANAGER VirtualPhone 0 - empty - RTP: Request resource 09:27:27.387 RTP.MANAGER VirtualPhone 0 - Dsp 0/1.1 - RTP: DSP channel allocated for the resource 09:27:27.387 RTP.PROVIDER VirtualPhone 0 - Dsp 0/1.1 - RTP: providing already allocated RTP channel 09:27:27.387 TA.T11 01 TAInboundCall CallResp event accepted 09:27:27.388 TA.T11 01 State change >> TAInboundCall->TAConnectWaitIn (TAS_Calling) 09:27:27.388 SA.NPAnxx2081 rcvd: deliver from SB 09:27:27.388 SA.NPAnxx2081 Redelivered call: process call coverage 09:27:27.388 SA.NPAnxx2081 sent: ClearCall to SB 09:27:27.388 SB.CALL 47 Delivering Called the clearCall routine 09:27:27.388 SB.CALL 47 Delivering Call Cleared on call from NPAnxx2081 to T11 09:27:27.389 SB.CALL 47 State change >> Delivering->Clearing 09:27:27.389 TA.T11 01 TAConnectWaitIn ClearCall event accepted 09:27:27.389 TA.T11 01 State change >> TAConnectWaitIn->TAClearingComplete (TAS_Clearing) 09:27:27.389 TM.T11 01 SipTM_Pending tachg -> TAClearingComplete 09:27:27.389 TM.T11 01 SipTM_Pending State change >> SipTM_Pending->SipTM_CallFail 09:27:27.392 TM.T11 01 SipTM_CallFail call-leg -> Disconnected 09:27:27.392 TM.T11 01 SipTM_CallFail CallLegStateChanged to Disconnected - TM change to closing state. 09:27:27.392 TM.T11 01 SipTM_CallFail State change >> SipTM_CallFail->SipTM_Closing 09:27:27.392 TM.T11 01 SipTM_Closing sent: TA->Clear 09:27:27.392 TM.T11 01 SipTM_CallFail sent: 0 09:27:27.393 TM.T11 01 SipTM_Closing State change >> SipTM_Closing->SipTM_Terminated 09:27:27.393 TA.T11 Country is : 8 and it's default ring pattern is : 0 09:27:27.393 TM.T11 01 SipTM_Terminated sent: TA->AppearanceOff 09:27:27.393 TM.T11 01 SipTM_Terminated State change >> SipTM_Terminated->SipTM_Idle 09:27:27.394 TM.T11 01 SipTM_Idle Clearing globalSipIdentity NPAnxx2072 09:27:27.394 SB.CALL 47 Clearing Called the clearResponse routine 09:27:27.394 SB.CALL 47 State change >> Clearing->CallIdlePending 09:27:27.395 SB.CCM release: 09:27:27.395 SB.CCM : Call Struct 0x0x50433410 : Call-ID = 47 09:27:27.395 SB.CCM : Org Acct = T11 Dst Acct = NPAnxx2081 09:27:27.395 SB.CCM : Org Port ID = SipTrunk 0/0.100 Dst Port ID = VirtualPhone 0/0 09:27:27.395 SB.CCM : SDP Transaction = CallID: 47 09:27:27.395 SB.CCM : SDP Offer = 0x5054a110, (xx.xxx.xxx.130:16418) 09:27:27.396 SB.CCM : Offer side SRTP session details 09:27:27.396 SB.CCM : None 09:27:27.396 SB.CCM : Answer side SRTP session details 09:27:27.396 SB.CCM : None 09:27:27.396 SB.CCM : RTP Channel = 0/1.1 09:27:27.396 SB.CCM release: Call Connection Type is RTP_TO_TDM 09:27:27.397 SB.CCM release: Releasing RTP Channel 0/1.1 09:27:27.397 RTP.CHANNEL RtpChannel::deallocate, status = 3, allocatedForInterface = 0 09:27:27.397 RTP.CHANNEL Channel 0/1.1 released successfully. 09:27:27.397 SB.CALL 47 CallIdlePending ClearResponse sent from T11 to NPAnxx2081 09:27:27.397 TA.T11 01 TAClearingComplete rcvd: clear from TM 09:27:27.398 TA.T11 01 TAClearingComplete rcvd: appearance off from TM 09:27:27.398 TA.T11 01 TAClearingComplete Clear Local Variables 09:27:27.398 TA.T11 01 State change >> TAClearingComplete->TAIdle (TAS_Idle) 09:27:27.398 TM.T11 01 SipTM_Idle tachg -> TAIdle 09:27:27.398 SA.NPAnxx2081 ERROR! clearResponse ignored 09:27:27.399 RTP.CHANNEL VirtualPhone 0 - Dsp 0/1.1 - RTP: releasing RTP resource 09:27:27.399 RTP.CHANNEL VirtualPhone 0 - Dsp 0/1.1 - RTP: releasing 09:27:27 SB.CallStructObserver 45 Created 09:27:27 SB.CallStructObserver 45 <-> 0gQAAC8WAAACBAAALxYAALSXPuPHm81KIHxssVUq7w6dqd/XtEl930ob+T1swV/Q@xx.xxx.xxx.130 09:27:27.399 SB.CALL Received association of CallID 45 with SIP 0gQAAC8WAAACBAAALxYAALSXPuPHm81KIHxssVUq7w6dqd/XtEl930ob+T1swV/Q@xx.xxx.xxx.130 09:27:27.400 SB.CALL Received association notice for CallID 45 and SIP 0gQAAC8WAAACBAAALxYAALSXPuPHm81KIHxssVUq7w6dqd/XtEl930ob+T1swV/Q@xx.xxx.xxx.130 09:27:27 SB.CallStructObserver 46 Created 09:27:27 SB.CallStructObserver 46 <-> 0gQAAC8WAAACBAAALxYAALSXPuPHm81KIHxssVUq7w6dqd/XtEl930ob+T1swV/Q@xx.xxx.xxx.130 09:27:27.401 SB.CALL Received association of CallID 46 with SIP 0gQAAC8WAAACBAAALxYAALSXPuPHm81KIHxssVUq7w6dqd/XtEl930ob+T1swV/Q@xx.xxx.xxx.130 09:27:27.401 SB.CALL Received association notice for CallID 46 and SIP 0gQAAC8WAAACBAAALxYAALSXPuPHm81KIHxssVUq7w6dqd/XtEl930ob+T1swV/Q@xx.xxx.xxx.130 09:27:27 SB.CallStructObserver 45 Finalized 09:27:27 SB.CallStructObserver 47 Created 09:27:27 SB.CallStructObserver 47 <-> 0gQAAC8WAAACBAAALxYAALSXPuPHm81KIHxssVUq7w6dqd/XtEl930ob+T1swV/Q@xx.xxx.xxx.130 09:27:27.402 SB.CALL Received association of CallID 47 with SIP 0gQAAC8WAAACBAAALxYAALSXPuPHm81KIHxssVUq7w6dqd/XtEl930ob+T1swV/Q@xx.xxx.xxx.130 09:27:27.402 SB.CALL Received association notice for CallID 47 and SIP 0gQAAC8WAAACBAAALxYAALSXPuPHm81KIHxssVUq7w6dqd/XtEl930ob+T1swV/Q@xx.xxx.xxx.130 09:27:27.403 SB.CALL Store cache 1 for CallID 47 and SIP 0gQAAC8WAAACBAAALxYAALSXPuPHm81KIHxssVUq7w6dqd/XtEl930ob+T1swV/Q@xx.xxx.xxx.130 09:27:27.403 SB.CALL Store cache 1 for CallID 47 and SIP 0gQAAC8WAAACBAAALxYAALSXPuPHm81KIHxssVUq7w6dqd/XtEl930ob+T1swV/Q@xx.xxx.xxx.130 09:27:27 SB.CallStructObserver 46 Finalized 09:27:27 SB.CallStructObserver 47 Finalized 2024.10.31 09:27:27 SB.CALL 46 RTP resource unavailable or SDP negotiation failed. Call from (NPANXX0303) to (npanxx6291). 2024.10.31 09:27:28 SMDR 45 10/31/2024 09:27:27 0.0 0 E 00/00 BATON ROUGE WA NPANXX0303 00/00 NPAnxx2081 0 N 2024.10.31 09:27:28 SMDR 46 10/31/2024 09:27:27 0.0 0 E 00/00 BATON ROUGE WA NPANXX0303 00/00 Unknown npanxx6291 0 F 09:27:29.715 MEDIA.MANAGER Remove Call ID map entry for call 45 09:27:29.716 MEDIA.MANAGER Remove Call ID map entry for call 47u a