cancel
Showing results for 
Show  only  | Search instead for 
Did you mean: 
triassicsoul1
New Contributor II

Cannot transfer call to external number

We use TA900 series on our own private IP network (no NAT and no outside IP access to management).  The problem is we can't transfer an active call to an external number.  Regardless whether the customer has a PBX or analog phones only, when attempting a hookflash to transfer an active call, both parties of the active call hear a click, but both parties remain connected.  The IAD customer attempting the hookflash does not get dial tone in order to dial digits to transfer the call.  The non-IAD caller is not put on hold.

Architecture is SIP trunk to the IAD, analog to the phone. The TA908 Gen2 is on FW R12.3.1

1111111111 = Calling Party

2222222222 = Called Party (who is trying to transfer the call)

The hookflash originates on the TA908.  The switch "sees" the event and responds back to the TA908 with an accept.

Here is the debug output.  Would someone kindly help me figure out what is happening?  MUCH THANKS!!!

test#debug voice switchboard

test#debug voice summary

test#debug sip stack messages

test#

test#

test#

test#

test#

test#

test#

07:07:02.681 SIP.STACK MSG     Rx: UDP src=192.168.61.19:5060 dst=192.168.61.200:5060

07:07:02.682 SIP.STACK MSG         INVITE sip:2222222222@192.168.61.200:5060;user=phone SIP/2.0

07:07:02.682 SIP.STACK MSG         Via: SIP/2.0/UDP 192.168.61.19:5060;branch=z9hG4bK-00102-koh450dx2egzB94-0

07:07:02.683 SIP.STACK MSG         Max-Forwards: 6

07:07:02.684 SIP.STACK MSG         Record-Route: <sip:1293172737@192.168.61.19:5060;lr>

07:07:02.684 SIP.STACK MSG         From: "City & State" <sip:1111111111@192.168.61.19:5060>;tag=koh450dx2egzB94-IPTrunk-1292-19-17at192.168.61.19

07:07:02.685 SIP.STACK MSG         To: sip:2222222222@192.168.61.200:5060

07:07:02.685 SIP.STACK MSG         Call-ID: koh450dx2egzB94@192.168.61.19

07:07:02.686 SIP.STACK MSG         CSeq: 102 INVITE

07:07:02.687 SIP.STACK MSG         Expires: 180

07:07:02.687 SIP.STACK MSG         Allow: UPDATE,INVITE,CANCEL,BYE,ACK

07:07:02.688 SIP.STACK MSG         Supported: 100rel

07:07:02.688 SIP.STACK MSG         Contact: sip:192.168.61.19:5060

07:07:02.689 SIP.STACK MSG         User-Agent: Taqua-T7000-R6.4.0

07:07:02.690 SIP.STACK MSG         Content-Type: application/sdp

07:07:02.690 SIP.STACK MSG         Content-Length: 218

07:07:02.691 SIP.STACK MSG    

07:07:02.691 SIP.STACK MSG         v=0

07:07:02.692 SIP.STACK MSG         o=- 3044814 3044814 IN IP4 192.168.61.19

07:07:02.693 SIP.STACK MSG         s=SIP Call

07:07:02.693 SIP.STACK MSG         c=IN IP4 192.168.61.119

07:07:02.694 SIP.STACK MSG         t=0 0

07:07:02.694 SIP.STACK MSG         m=audio 56466 RTP/AVP 0 101

07:07:02.695 SIP.STACK MSG         a=rtpmap:0 PCMU/8000

07:07:02.695 SIP.STACK MSG         a=rtpmap:101 telephone-event/8000

07:07:02.696 SIP.STACK MSG         a=fmtp:101 0-15

07:07:02.697 SIP.STACK MSG         a=ptime:20

07:07:02.697 SIP.STACK MSG         a=sendrecv

07:07:02.698 SIP.STACK MSG    

07:07:02.704 TM.T01 01 SipTM_Idle           rcvd SIP call-leg request: INVITE

07:07:02.706 TM.T01 01 SipTM_Idle           call-leg -> Offering

07:07:02.706 TM.T01 01 SipTM_Idle           State change      >> SipTM_Idle->SipTM_Trying

07:07:02.708 TM.T01 01 SipTM_Trying         SDP offer is not loopback request

07:07:02.709 TM.T01 01 SipTM_Trying         Processing From for Caller-ID.

07:07:02.709 TM.T01 01 SipTM_Trying         Caller ID Name   = "City & State"

07:07:02.710 TM.T01 01 SipTM_Trying         Caller ID Number = "1111111111"

07:07:02.711 TM.T01 01 SipTM_Trying         info: unable to set redirect number(s) from INVITE

07:07:02.712 TM.T01 01 SipTM_Trying         sent: TA->InboundCall

07:07:02.712 TM.T01 01 Looking up source address for destination 192.168.61.19

07:07:02.713 TM.T01 01 call-leg (0x31c30c0) -> src: 192.168.61.200 : 5060  dst: 192.168.61.19 : 5060

07:07:02.717 SIP.STACK MSG     Tx: UDP src=192.168.61.200:5060 dst=192.168.61.19:5060

07:07:02.717 SIP.STACK MSG         SIP/2.0 100 Trying

07:07:02.718 SIP.STACK MSG         From: "City & State"<sip:1111111111@192.168.61.19:5060>;tag=koh450dx2egzB94-IPTrunk-1292-19-17at192.168.61.19

07:07:02.718 SIP.STACK MSG         To: <sip:2222222222@192.168.61.200:5060>

07:07:02.719 SIP.STACK MSG         Call-ID: koh450dx2egzB94@192.168.61.19

07:07:02.720 SIP.STACK MSG         CSeq: 102 INVITE

07:07:02.720 SIP.STACK MSG         Via: SIP/2.0/UDP 192.168.61.19:5060;branch=z9hG4bK-00102-koh450dx2egzB94-0

07:07:02.721 SIP.STACK MSG         Contact: <sip:2222222222@192.168.61.200:5060;transport=UDP>

07:07:02.721 SIP.STACK MSG         Supported: 100rel,replaces

07:07:02.722 SIP.STACK MSG         Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, PRACK, REFER, REGISTER

07:07:02.723 SIP.STACK MSG         User-Agent: ADTRAN_Total_Access_908_2nd_Gen/R12.3.1.E

07:07:02.723 SIP.STACK MSG         Content-Length: 0

07:07:02.724 SIP.STACK MSG    

07:07:02.725 TM.T01 01 SipTM_Trying         sent: 100 Trying

07:07:02.726 TA.T01 01 TAIdle               rcvd: inboundCall from TM

07:07:02.726 TA.T01 01 State change      >> TAIdle->TAInboundCall (TAS_Calling)

07:07:02.727 TA.T01 01 Success - DID resolved 2222222222 to 8001

07:07:02.728 TA.T01 01 TAIdle               sent: call to SB

07:07:02.729 TM.T01 01 SipTM_Trying         tachg -> TAInboundCall

07:07:02.730 TM.T01 01 SipTM_Trying         State change      >> SipTM_Trying->SipTM_Pending

07:07:02.731 SB.CALL 1 Idle                 Called the call routine with 8001

07:07:02.731 SB.CCM isMappable:

07:07:02.732 SB.CCM  :  Call Struct 0x31f1810 :   Call-ID = 1

07:07:02.733 SB.CCM  :  Org Acct = T01    Dst Acct = 8001

07:07:02.733 SB.CCM  : -1292-19-17at192.168.61.19

07:07:02.793 SIP.STACK MSG         To: <sip:2222222222@192.168.61.200:5060>;tag=319d0c8-7f000001-13c4-1a6-10188d3d-1a6

07:07:02.794 SIP.STACK MSG         Call-ID: koh450dx2egzB94@192.168.61.19

07:07:02.794 SIP.STACK MSG         CSeq: 102 INVITE

07:07:02.795 SIP.STACK MSG         Via: SIP/2.0/UDP 192.168.61.19:5060;branch=z9hG4bK-00102-koh450dx2egzB94-0

07:07:02.795 SIP.STACK MSG         Contact: <sip:2222222222@192.168.61.200:5060;transport=UDP>

07:07:02.796 SIP.STACK MSG         Record-Route: <sip:1293172737@192.168.61.19:5060;lr>

07:07:02.797 SIP.STACK MSG         Supported: 100rel,replaces

07:07:02.797 SIP.STACK MSG         Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, PRACK, REFER, REGISTER

07:07:02.798 SIP.STACK MSG         User-Agent: ADTRAN_Total_Access_908_2nd_Gen/R12.3.1.E

07:07:02.798 SIP.STACK MSG         Content-Length: 0

07:07:02.799 SIP.STACK MSG    

07:07:02.800 TM.T01 01 SipTM_Alerting       Sent 180 Ringing

07:07:02 SB.CallStructObserver 1 Created

07:07:02 SB.CallStructObserver 1 <-> koh450dx2egzB94@192.168.61.19

07:07:02 SB.CallStructObserver 2 Created

07:07:05.803 TONESERVICES.EVENTS fxs 0/1 - Dsp 0/1.2 - Caller-ID Generation: starting Caller-ID alert and sending Caller-ID information:

07:07:05.804 TONESERVICES.EVENTS fxs 0/1 - Dsp 0/1.2 - Caller-ID Generation:   chars = ".'..01010707..1111111111..City & State."

07:07:05.804 TONESERVICES.EVENTS fxs 0/1 - Dsp 0/1.2 - Caller-ID Generation:   bytes = "80 27 01 08 30 31 30 31 30 37 30 37 02 0A 32 36 30 38 34 36 30 36 39 39 07 0F 42 6C 75 66 66 74 6F 6E 20 20 20 20 20 49 4E 15"

07:07:05.805 TONESERVICES.EVENTS fxs 0/1 - Dsp 0/1.2 - Caller-ID Generation: TDM map

07:07:06.621 TONESERVICES.EVENTS fxs 0/1 - Dsp 0/1.2 - Caller-ID Generation: received Caller-ID Done event

07:07:06.622 TONESERVICES.EVENTS fxs 0/1 - Dsp 0/1.2 - Caller-ID Generation: stopping

07:07:06.623 TONESERVICES.EVENTS fxs 0/1 - Dsp 0/1.2 - Caller-ID Generation: TDM unmap

07:07:06.624 RTP.CHANNEL fxs 0/1 - Dsp 0/1.2 - Caller-ID Generation: releasing RTP resource

07:07:06.625 TONESERVICES.EVENTS fxs 0/1 - Dsp 0/1.2 - Caller-ID Generation: release

07:07:14.483 PM.0:1 Ringing              Processed OFFHOOK

07:07:14.484 PM.0:1 State change      >> Ringing->Connected

07:07:14.485 SA.2222222222 Ca:0 Ringing              rcvd: AcctPhoneMgr_connect from PM

07:07:14.486 SA.2222222222 Ca:0 Ringing              sent: connect to SB

07:07:14.486 SA.2222222222 Ca:0 Ringing              State change      >> Ringing->Connecting (CAS_Active)

07:07:14.487 SB.CALL 2 Alerting             Called the connect routine

07:07:14.487 SB.CCM isResponseMappable:

07:07:14.488 SB.CCM  :  Call Struct 0x31f1c10 :   Call-ID = 2

07:07:14.488 SB.CCM  :  Org Acct = 8001    Dst Acct = 2222222222

07:07:14.489 SB.CCM  :  Org Port ID = RingGroup 0/0   Dst Port ID = FxsPhone 0/1

07:07:14.490 SB.CCM  :  SDP Transaction = CallID: 2

07:07:14.491 SB.CCM  :  SDP Offer = 0x02cf0d10, ([::1]:60000)

07:07:14.491 SB.CCM  :  RTP Channel = 0/1.1

07:07:14.492 SB.CCM isResponseMappable: reversing call connection type to compensate for event originator direction

07:07:14.492 SB.CCM isResponseMappable: Call Connection Type is TDM_TO_RG

07:07:14.493 SB.CCM isResponseMappable: Creating SDP Answer based on SDP Offer

07:07:14.493 SB.CCM createAnswer: creating SDP answer using RTP channel 0/1.1

07:07:14.495 SB.CCM createAnswer : offer  codec list: PCMU

07:07:14.496 SB.CCM              : answer codec list: PCMU

07:07:14.496 SB.CCM updateAnswerWithEndpointConfig: no endpoint configuration to update with

07:07:14.500 SB.CCM createAnswer : result codec list: PCMU

07:07:14.501 SB.CCM createAnswer : final DTMF signaling(NTE 101)

07:07:14.502 MEDIA.MANAGER getSubstitutePort: No matching callIdMap entry found for call 2

07:07:14.502 SB.CCM updateMediaEntryForReinviteWithSameSdp : no substitute port found for offer SDP callId (2) sessionId (-43622INIP6::1) remote port (60000)

07:07:14.504 SB.CCM translateAnswer: offer  codec list: PCMU

07:07:14.504 SB.CCM                : answer codec list: PCMU

07:07:14.506 SB.CCM translateAnswer: CODEC transcoding is not required

07:07:14.507 SB.CCM translateAnswer: offer / answer DTMF signaling identical: DTMF transcoding not required

07:07:14.508 SB.CCM translateAnswer: success

07:07:14.509 MEDIA.MANAGER Allocating media port.

07:07:14.510 MEDIA.MANAGER getSubstitutePort: No matching callIdMap entry found for call 2

07:07:14.511 MEDIA.MANAGER Call ID map : Added new entry : call ID 2 : session -43634INIP4127.0.0.3 : version 2 : index 2

07:07:14.512 MEDIA.MANAGER New media entry : type(0), callID(2), sessionID(-43634INIP4127.0.0.3), original IP(127.0.0.3) ports(10002-10003), substitute IP(::) ports(10002-10003), RtpChannel(0/1.1), connection(0x2cf0f10), sdpOverride(0), me(0x2cf0e10). RtpChannel 0/1.1

07:07:14.513 SB.CALL 2 Alerting             Connect sent from 2222222222 to 8001

07:07:14.514 SB.CALL 2 State change      >> Alerting->Connecting

07:07:14.518 RG.8001 app. 1 in from 1111111111 State change      >> Ringing->Transfer Pending

07:07:14.518 RG.8001 app. 10000 out to 2222222222 State change      >> Calling->Transfering

07:07:14.519 SB.CALL 2 Connecting           Called the connectResponse routine

07:07:14.520 VOICE.SUMMARY 8001 is connected to 2222222222 (2222222222)

07:07:14.521 SB.CALL 2 Connecting           ConnectResponse sent from 8001 to 2222222222

07:07:14.521 SB.CALL 1 Alerting             Called the connect routine

07:07:14.522 SB.CCM isResponseMappable:

07:07:14.522 SB.CCM  :  Call Struct 0x31f1810 :   Call-ID = 1

07:07:14.523 SB.CCM  :  Org Acct = T01    Dst Acct = 8001

07:07:14.524 SB.CCM  :  Org Port ID = SipTrunk 0/0   Dst Port ID = RingGroup 0/0

07:07:14.524 SB.CCM  :  SDP Transaction = CallID: 1

07:07:14.525 SB.CCM  :  SDP Offer = 0x02cef510, (192.168.61.119:56466)

07:07:14.526 SB.CCM  :  RTP Channel = 0/1.1

07:07:14.526 SB.CCM isResponseMappable: reversing call connection type to compensate for event originator direction

07:07:14.527 SB.CCM isResponseMappable: Call Connection Type is RG_TO_RTP

07:07:14.527 SB.CALL 1 Alerting             Connect sent from 8001 to T01

07:07:14.528 SB.CALL 1 State change      >> Alerting->Connecting

07:07:14.529 SA.2222222222 Ca:0 Connecting           rcvd: connectResponse from SB

07:07:14.529 SA.2222222222 Ca:0 Connecting           State change      >> Connecting->Connected (CAS_Connected)

07:07:14.530 SA.2222222222 Ca:0 Connected            sent: AcctPhoneMgr_cachg(CAS_Connected) to PM

07:07:14.530 PM.0:1 Connected            Processed CACHG:Connected

07:07:14.531 PM.0:1 State change      >> Connected->Connected

07:07:14.531 PM.0:1 Connected            sent: finalizeConnect to SA

07:07:14.532 SA.2222222222 Ca:0 Connected            sent: AcctPhoneMgr_info to PM

07:07:14.533 PM.0:1 ERROR! APM_Info ignored

07:07:14.533 TA.T01 01 TAConnectWaitIn      connect event accepted

07:07:14.534 TA.T01 01 State change      >> TAConnectWaitIn->TAConnectPending (TAS_Connected)

07:07:14.535 TM.T01 01 SipTM_Alerting       tachg -> TAConnectPending

07:07:14.535 TM.T01 01 SipTM_Alerting       State change      >> SipTM_Alerting->SipTM_Accept

07:07:14.536 TM.T01 01 SDP DPI call ID 1 : No media bin.

07:07:14.537 TM.T01 01 Processing new SDP entries.

07:07:14.538 TM.T01 01 Checking for internal Media Gateway IP Address

07:07:14.539 TM.T01 01 Using RTP Channel 0/1.1

07:07:14.539 TM.T01 01 Inserting 192.168.61.200 into SDP for Media Gateway

07:07:14.540 MEDIA.MANAGER getSubstitutePort: Matching callIdMap entry found for call 1 sessionId -43635INIP6::1 remote port 60000

07:07:14.541 MEDIA.MANAGER Call ID map : Added new session ID : call ID 1 : session -43635INIP6::1 : version 1 : index 4

07:07:14.542 MEDIA.MANAGER New media entry : type(0), callID(1), sessionID(-43635INIP6::1), original IP(::1) ports(60000-60001), substitute IP(192.168.61.200) ports(10004-10005), RtpChannel(0/1.1), connection(0x2cf1510), sdpOverride(0), me(0x2cf0d10). RtpChannel 0/1.1

07:07:14.543 TM.T01 01 Adding RTP Media Gateway Entry: [::1]:60000 -> 192.168.61.200:60000

07:07:14.543 TM.T01 01 Allocating anchor ports 10004 and 10005 for interface 192.168.61.200

07:07:14.548 SIP.STACK MSG     Tx: UDP src=192.168.61.200:5060 dst=192.168.61.19:5060

07:07:14.548 SIP.STACK MSG         SIP/2.0 200 OK

07:07:14.549 SIP.STACK MSG         From: "City & State"<sip:1111111111@192.168.61.19:5060>;tag=koh450dx2egzB94-IPTrunk-1292-19-17at192.168.61.19

07:07:14.550 SIP.STACK MSG         To: <sip:2222222222@192.168.61.200:5060>;tag=319d0c8-7f000001-13c4-1a6-10188d3d-1a6

07:07:14.550 SIP.STACK MSG         Call-ID: koh450dx2egzB94@192.168.61.19

07:07:14.551 SIP.STACK MSG         CSeq: 102 INVITE

07:07:14.552 SIP.STACK MSG         Via: SIP/2.0/UDP 192.168.61.19:5060;branch=z9hG4bK-00102-koh450dx2egzB94-0

07:07:14.552 SIP.STACK MSG         Contact: <sip:2222222222@192.168.61.200:5060;transport=UDP>

07:07:14.553 SIP.STACK MSG         Record-Route: <sip:1293172737@192.168.61.19:5060;lr>

07:07:14.553 SIP.STACK MSG         Supported: 100rel,replaces

07:07:14.554 SIP.STACK MSG         Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, PRACK, REFER, REGISTER

07:07:14.555 SIP.STACK MSG         User-Agent: ADTRAN_Total_Access_908_2nd_Gen/R12.3.1.E

07:07:14.555 SIP.STACK MSG         Content-Type: application/sdp

07:07:14.556 SIP.STACK MSG         Content-Length: 219

07:07:14.556 SIP.STACK MSG    

07:07:14.557 SIP.STACK MSG         v=0

07:07:14.557 SIP.STACK MSG         o=- 43635 1 IN IP4 192.168.61.200

07:07:14.558 SIP.STACK MSG         s=-

07:07:14.558 SIP.STACK MSG         c=IN IP4 192.168.61.200

07:07:14.559 SIP.STACK MSG         t=0 0

07:07:14.560 SIP.STACK MSG         m=audio 10004 RTP/AVP 0 101

07:07:14.560 SIP.STACK MSG         a=sendrecv

07:07:14.561 SIP.STACK MSG         a=silenceSupp:off - - - -

07:07:14.562 SIP.STACK MSG         a=rtpmap:0 PCMU/8000

07:07:14.562 SIP.STACK MSG         a=rtpmap:101 telephone-event/8000

07:07:14.563 SIP.STACK MSG         a=fmtp:101 0-15

07:07:14.563 SIP.STACK MSG    

07:07:14.566 TM.T01 01 SipTM_Accept         call-leg -> Accepted

07:07:14.567 TM.T01 01 SipTM_Accept         sent: 200 with SDP

07:07:14.568 SA.2222222222 Ca:0 Connected            rcvd: AcctPhoneMgr_finalizeConnect from PM

07:07:14.569 SA.2222222222 Ca:0 Connected            sent: finalizeConnect to SB

07:07:14.569 SB.CALL 2 Connecting           Called the finalizeConnect routine

07:07:14.570 SB.CALL 2 State change      >> Connecting->Connected

07:07:14.592 SIP.STACK MSG     Rx: UDP src=192.168.61.19:5060 dst=192.168.61.200:5060

07:07:14.593 SIP.STACK MSG         ACK sip:2222222222@192.168.61.200:5060;user=phone SIP/2.0

07:07:14.594 SIP.STACK MSG         Via: SIP/2.0/UDP 192.168.61.19:5060;branch=z9hG4bK-00102-koh450dx2egzB94-102

07:07:14.594 SIP.STACK MSG         Max-Forwards: 6

07:07:14.595 SIP.STACK MSG         From: "City & State" <sip:1111111111@192.168.61.19:5060>;tag=koh450dx2egzB94-IPTrunk-1292-19-17at192.168.61.19

07:07:14.595 SIP.STACK MSG         To: sip:2222222222@192.168.61.200:5060;tag=319d0c8-7f000001-13c4-1a6-10188d3d-1a6

07:07:14.596 SIP.STACK MSG         Call-ID: koh450dx2egzB94@192.168.61.19

07:07:14.597 SIP.STACK MSG         CSeq: 102 ACK

07:07:14.597 SIP.STACK MSG         Content-Length: 0

07:07:14.598 SIP.STACK MSG    

07:07:14.601 SIP.STACK MSG Found existing transaction for the request message.

07:07:14.602 TM.T01 01 SipTM_Accept         rcvd SIP call-leg request: ACK

07:07:14.603 TM.T01 01 SipTM_Accept         call-leg -> Connected

07:07:14.603 TM.T01 01 SipTM_Accept         No body in message when trying to get SDP

07:07:14.604 TM.T01 01 SipTM_Accept         info: unable to save SDP

07:07:14.605 TM.T01 01 SipTM_Accept         sent: TA->Connect

07:07:14.605 TM.T01 01 SipTM_Accept         State change      >> SipTM_Accept->SipTM_Connected

07:07:14.606 TM.T01 01 SipTM_Connected      call-leg-mod -> Modify Idle

07:07:14.607 TA.T01 01 TAConnectPending     rcvd: connect from TM

07:07:14.607 TA.T01 01 State change      >> rt (10006)

07:07:14.675 SB.CCM translateAnswer: offer  codec list: PCMU PCMA G729

07:07:14.676 SB.CCM                : answer codec list: PCMU

07:07:14.677 SB.CCM translateAnswer: CODEC transcoding is not required

07:07:14.678 SB.CCM translateAnswer: offer / answer DTMF signaling identical: DTMF transcoding not required

07:07:14.680 SB.CCM translateAnswer: success

07:07:14.681 MEDIA.MANAGER Allocating media port.

07:07:14.682 MEDIA.MANAGER getSubstitutePort: Matching callIdMap entry found for call 3 sessionId -43636INIP4127.0.0.3 remote port 0

07:07:14.683 MEDIA.MANAGER Call ID map : Added new session ID : call ID 3 : session -43636INIP4127.0.0.3 : version 2 : index 8

07:07:14.684 MEDIA.MANAGER New media entry : type(0), callID(3), sessionID(-43636INIP4127.0.0.3), original IP(127.0.0.3) ports(10008-10009), substitute IP(::) ports(10008-10009), RtpChannel(0/1.1), connection(0x2903d10), sdpOverride(0), me(0x2905810). RtpChannel 0/1.1

07:07:14.685 SB.CALL 3 State change      >> TransferUpdating->ReInvitingTransferConnecting

07:07:14.686 SB.CALL 3 ReInvitingTransferConnecting Sent connect from 2222222222 to T01

07:07:14.686 TA.T01 01 TAConnectWaitIn      connect event accepted

07:07:14.687 TA.T01 01 State change      >> TAConnectWaitIn->TAConnectPending (TAS_Connected)

07:07:14.687 TM.T01 01 SipTM_Connected      tachg -> TAConnectPending

07:07:14.689 TM.T01 01 SipTM_Connected      sent: TA->Connect

07:07:14.689 TA.T01 01 TAConnectPending     rcvd: connect from TM

07:07:14.690 TA.T01 01 State change      >> TAConnectPending->TAConnected (TAS_Connected)

07:07:14.690 SB.CALL 3 ReInvitingTransferConnecting Called the connectResponse routine

07:07:14.691 SB.CCM connect:

07:07:14.692 SB.CCM  :  Call Struct 0x31ea810 :   Call-ID = 3

07:07:14.692 SB.CCM  :  Org Acct = T01    Dst Acct = 2222222222

07:07:14.693 SB.CCM  :  Org Port ID = SipTrunk 0/0   Dst Port ID = FxsPhone 0/1

07:07:14.693 SB.CCM  :  SDP Transaction = CallID: 3

07:07:14.694 SB.CCM  :  SDP Offer = 0x02cf1610, ([::]:60000)

07:07:14.695 SB.CCM  :  SDP Answer = 0x02905a10, (127.0.0.3:10008)

07:07:14.696 SB.CCM  :  RTP Channel = 0/1.1

07:07:14.696 SB.CCM connect: Call Connection Type is RTP_TO_TDM

07:07:14.697 SB.CCM SDP offer is [::]:60000, SDP answer is 127.0.0.3:10008

07:07:14.698 MEDIA.MANAGER Trying to connect call ID 3 : SDP sessions -43634INIP40.0.0.0 and -43636INIP4127.0.0.3

07:07:14.700 MEDIA.MANAGER Found 1 ports for session -43634INIP40.0.0.0

07:07:14.700 MEDIA.MANAGER Found 1 ports for session -43636INIP4127.0.0.3

07:07:14.701 MEDIA.MANAGER Connecting Disconnected Local [::]:10006 : Remote [::]:60000

07:07:14.702 MEDIA.MANAGER    and     Disconnected Local [::]:10008 : Remote 127.0.0.3:10008

07:07:14.703 MEDIA.MANAGER Setting up DSP Media Connection 3 for entry(type(0), callID(3), sessionID(-43634INIP40.0.0.0), original IP(::) ports(60000-60001), substitute IP(::) ports(10006-10007), RtpChannel(0/1.1), connection(0x2cf1b10), sdpOverride(0), me(0x2cf1a10))

07:07:14.704 MEDIA.MANAGER Setting up DSP Media Connection 3 for entry(type(0), callID(3), sessionID(-43636INIP4127.0.0.3), original IP(127.0.0.3) ports(10008-10009), substitute IP(::) ports(10008-10009), RtpChannel(0/1.1), connection(0x2903d10), sdpOverride(0), me(0x2905810))

07:07:14.705 MEDIA.MANAGER Connection Fixup 1 DSP Port 10006

07:07:14.705 MEDIA.MANAGER   Local [::]:10006 : Remote [::]:60000

07:07:14.706 MEDIA.MANAGER Connection Fixup 2 DSP Port 10008

07:07:14.706 MEDIA.MANAGER   Local [::]:10008 : Remote 127.0.0.3:10008

07:07:14.707 MEDIA.MANAGER connectionFixup : Letting other side fixup connection : entry 10006 sub [::]:10006 remote [::]:60000

07:07:14.708 MEDIA.MANAGER                 : Other side : entry 10008 sub [::]:10008 remote 127.0.0.3:10008

07:07:14.708 MEDIA.MANAGER Connection Fixup 1 DSP Port 10008

07:07:14.709 MEDIA.MANAGER   Local [::]:10008 : Remote 127.0.0.3:10008

07:07:14.710 MEDIA.MANAGER Connection Fixup 2 DSP Port 10006

07:07:14.710 MEDIA.MANAGER   Local [::]:10006 : Remote [::]:60000

07:07:14.711 MEDIA.MANAGER connectionFixup : DSP media : Change entry 10008 remote from 127.0.0.3:10008 to [::]:60000

07:07:14.711 MEDIA.MANAGER Setup RTP Channel false for 0/1.1

07:07:14.712 MEDIA.MANAGER Setup RTP Channel true for 0/1.1

07:07:14.713 MEDIA.MANAGER Connection Result 1 DSP Port 10008

07:07:14.713 MEDIA.MANAGER   Local [::]:10008 : Remote [::]:60000

07:07:14.714 MEDIA.MANAGER Connection Result 2 Entry not activated

07:07:14.714 MEDIA.MANAGER connectionFixup success for port 10008 and 10006

07:07:14.715 MEDIA.MANAGER Marking setup complete for port 10008

07:07:14.715 MEDIA.MANAGER Marking setup complete for port 10006

07:07:14.716 MEDIA.MANAGER Connection Fixup 1 DSP Port 10007

07:07:14.717 MEDIA.MANAGER   Local [::]:10007 : Remote [::]:60001

07:07:14.717 MEDIA.MANAGER Connection Fixup 2 DSP Port 10009

07:07:14.718 MEDIA.MANAGER   Local [::]:10009 : Remote 127.0.0.3:10009

07:07:14.718 MEDIA.MANAGER connectionFixup : Letting other side fixup connection : entry 10007 sub [::]:10007 remote [::]:60001

07:07:14.720 MEDIA.MANAGER                 : Other side : entry 10009 sub [::]:10009 remote 127.0.0.3:10009

07:07:14.721 MEDIA.MANAGER Connection Fixup 1 DSP Port 10009

07:07:14.722 MEDIA.MANAGER   Local [::]:10009 : Remote 127.0.0.3:10009

07:07:14.722 MEDIA.MANAGER Connection Fixup 2 DSP Port 10007

07:07:14.723 MEDIA.MANAGER   Local [::]:10007 : Remote [::]:60001

07:07:14.723 MEDIA.MANAGER connectionFixup : DSP media : Change entry 10009 remote from 127.0.0.3:10009 to [::]:60001

07:07:14.724 MEDIA.MANAGER Connection Result 1 DSP Port 10009

07:07:14.724 MEDIA.MANAGER   Local [::]:10009 : Remote [::]:60001

07:07:14.725 MEDIA.MANAGER Connection Result 2 Entry not activated

07:07:14.725 MEDIA.MANAGER connectionFixup success for port 10009 and 10007

07:07:14.726 MEDIA.MANAGER Marking setup complete for port 10009

07:07:14.727 MEDIA.MANAGER Marking setup complete for port 10007

07:07:14.727 MEDIA.MANAGER Connected DSP Port 10008

07:07:14.728 MEDIA.MANAGER   Local [::]:10008 : Remote [::]:60000

07:07:14.729 MEDIA.MANAGER ConnecteCK MSG         t=0 0

07:07:14.790 SIP.STACK MSG         m=audio 56466 RTP/AVP 0 101

07:07:14.791 SIP.STACK MSG         a=rtpmap:0 PCMU/8000

07:07:14.792 SIP.STACK MSG         a=rtpmap:101 telephone-event/8000

07:07:14.792 SIP.STACK MSG         a=fmtp:101 0-15

07:07:14.793 SIP.STACK MSG         a=ptime:20

07:07:14.793 SIP.STACK MSG         a=sendrecv

07:07:14.794 SIP.STACK MSG    

07:07:14.798 TM.T01 01 SipTM_ReInviting     rcvd SIP call-leg response: 200 OK

07:07:14.799 TM.T01 01 SipTM_ReInviting     call-leg-mod -> Modify Re-Invite Remote Accepted

07:07:14.800 TM.T01 01 SipTM_ReInviting     State change      >> SipTM_ReInviting->SipTM_ReInvitingPassed

07:07:14.801 TM.T01 01 SipTM_ReInvitingPassed sent: TA->ReInvite Response - PASS

07:07:14.802 TM.T01 01 SipTM_ReInvitingPassed sent: TA->ReConnect

07:07:14.803 SB.CCM  :  Call Struct 0x31f1c10 :   Call-ID = 2

07:07:14.804 SB.CCM  :  Org Acct = 8001    Dst Acct = 2222222222

07:07:14.804 SB.CCM  :  Org Port ID = RingGroup 0/0   Dst Port ID = FxsPhone 0/1

07:07:14.805 SB.CCM release: Call Connection Type is RG_TO_TDM

07:07:14.806 SB.CALL 2 CallIdlePending      ClearResponse sent from 8001 to 2222222222

07:07:14.806 TA.T01 01 TAReInvited          rcvd: reInviteResponse from TM

07:07:14.807 TA.T01 01 TAReInvited          rcvd: reConnect from TM

07:07:14.808 SB.CALL 3 ReInviteOrg          Called the reInviteResponse routine

07:07:14.808 SB.CALL 3 ReInviteOrg          Called the reconnect routine

07:07:14.809 SB.CCM isMappable:

07:07:14.809 SB.CCM  :  Call Struct 0x31ea810 :   Call-ID = 3

07:07:14.810 SB.CCM  :  Org Acct = T01    Dst Acct = 2222222222

07:07:14.811 SB.CCM  :  Org Port ID = SipTrunk 0/1.1   Dst Port ID = FxsPhone 0/1

07:07:14.811 SB.CCM  :  SDP Transaction = CallID: 3

07:07:14.812 SB.CCM  :  SDP Offer = 0x02905310, (192.168.61.119:56466)

07:07:14.813 SB.CCM  :  RTP Channel = 0/1.1

07:07:14.813 SB.CCM isMappable: Call Connection Type is RTP_TO_TDM

07:07:14.815 SB.CCM translateOffer: offer codec list: PCMU

07:07:14.816 SB.CCM translateOffer: revised offer codec list: PCMU

07:07:14.817 SB.CCM translateOffer: codec list after answerer: PCMU

07:07:14.820 SB.CCM translateOffer: DTMF signaling: answerer has no restrictions configured, passing offer(NTE 101) through

07:07:14.821 SB.CCM translateOffer: success

07:07:14.822 MEDIA.MANAGER Allocating media port.

07:07:14.822 MEDIA.MANAGER getSubstitutePort: Matching callIdMap entry found for call 3 sessionId -3044814INIP4192.168.61.19 remote port 56466

07:07:14.823 MEDIA.MANAGER Call ID map : Added new session ID : call ID 3 : session -3044814INIP4192.168.61.19 : version 3044815 : index 10

07:07:14.824 MEDIA.MANAGER New media entry : type(0), callID(3), sessionID(-3044814INIP4192.168.61.19), original IP(192.168.61.119) ports(56466-56467), substitute IP(::) ports(10010-10011), RtpChannel(0/1.1), connection(0x28f7110), sdpOverride(0), me(0x2900510). RtpChannel 0/1.1

07:07:14.825 SB.CALL 3 ReInviteOrg          reInvite with SDP sent to 2222222222

07:07:14.826 SB.CALL 3 State change      >> ReInviteOrg->ReInviteDst

07:07:14.826 SA.2222222222 Ca:0 Connected            rcvd: reInvite from SB

07:07:14.827 SA.2222222222 Ca:0 Connected            State change      >> Connected->ReinvitePending (CAS_Connected)

07:07:14.828 SA.2222222222 Ca:0 ReinvitePending      sent: AcctPhoneMgr_reinvite to PM

07:07:14.828 PM.0:1 Connected            Processed Reinvite Event pass

07:07:14.829 SA.2222222222 Ca:0 ReinvitePending      rcvd: AcctPhoneMgr_Reinvite from PM

07:07:14.829 SA.2222222222 Ca:0 ReinvitePending      sent: reinviteResponse(pass) to SB

07:07:14.830 SA.2222222222 Ca:0 ReinvitePending      rcvd: AcctPhoneMgr_Reconnect from PM

07:07:14.831 SA.2222222222 Ca:0 ReinvitePending      sent: reconnect to SB

07:07:14.832 SA.2222222222 Ca:0 ReinvitePending      State change      >> ReinvitePending->ReconnectPending (CAS_Connected)

07:07:14.832 SB.CALL 3 ReInviteDst          Called the reInviteResponse routine

07:07:14.833 SB.CALL 3 ReInviteDst          Called the reconnect routine

07:07:14.833 SB.CCM isResponseMappable:

07:07:14.834 SB.CCM  :  Call Struct 0x31ea810 :   Call-ID = 3

07:07:14.834 SB.CCM  :  Org Acct = T01    Dst Acct = 2222222222

07:07:14.835 SB.CCM  :  Org Port ID = SipTrunk 0/1.1   Dst Port ID = FxsPhone 0/1

07:07:14.836 SB.CCM  :  SDP Transaction = CallID: 3

07:07:14.836 SB.CCM  :  SDP Offer = 0x02905310, (192.168.61.119:56466)

07:07:14.837 SB.CCM  :  RTP Channel = 0/1.1

07:07:14.838 SB.CCM isResponseMappable: reversing call connection type to compensate for event originator direction

07:07:14.839 SB.CCM isResponseMappable: Call Connection Type is TDM_TO_RTP

07:07:14.839 SB.CCM isResponseMappable: Creating SDP Answer based on SDP Offer

07:07:14.840 SB.CCM createAnswer: creating SDP answer using RTP channel 0/1.1

07:07:14.841 SB.CCM createAnswer : offer  codec list: PCMU

07:07:14.842 SB.CCM              : answer codec list: PCMU

07:07:14.846 SB.CCM createAnswer : result codec list: PCMU

07:07:14.847 SB.CCM createAnswer : final DTMF signaling(NTE 101)

07:07:14.848 MEDIA.MANAGER getSubstitutePort: Matching callIdMap entry found for call 3 sessionId -3044814INIP4192.168.61.19 remote port 56466

07:07:14.848 MEDIA.MANAGER getSubstitutePort: Matching sessionPortMap entry found for session

07:07:14.849 MEDIA.MANAGER getSubstitutePort: Session port count (1)  Returning port (10010)

07:07:14.849 SB.CCM updateMediaEntryForReinviteWithSameSdp : no associated port found for port (10010)

07:07:14.851 SB.CCM translateAnswer: offer  codec list: PCMU

07:07:14.851 SB.CCM                : answer codec list: PCMU

07:07:14.853 SB.CCM translateAnswer: CODEC transcoding is not required

07:07:14.854 SB.CCM translateAnswer: offer / answer DTMF signaling identical: DTMF transcoding not required

07:07:14.855 SB.CCM translateAnswer: success

07:07:14.856 MEDIA.MANAGER Allocating media port.

07:07:14.857 MEDIA.MANAGER getSubstitutePort: Matching callIdMap entry found for call 3 sessionId -43636INIP4127.0.0.3 remote port 0

07:07:14.857 MEDIA.MANAGER getSubstitutePort: Matching sessi7:07:17.533 MEDIA.MANAGER Remove Call ID map entry for call 1

07:07:17.533 MEDIA.MANAGER Remove Call ID map entry for call 2

test#

test#

test#

test#

test#

test#

test#  Hook Flash event

test#

test#

test#

test#

test#

test#

test#

test#

07:08:15.120 PM.0:1 Connected            Processed flash hook

07:08:15.121 PM.0:1 State change      >> Connected->Hold Pending

07:08:15.121 SA.2222222222 Ca:0 Connected            rcvd: AcctPhoneMgr_hold(ON) from PM

07:08:15.122 SA.2222222222 Ca:0 Connected            sent: hold(ON) to SB

07:08:15.123 SA.2222222222 Ca:0 Connected            State change      >> Connected->HoldPending (CAS_Connected)

07:08:15.123 SB.CCM isMappable:

07:08:15.124 SB.CCM  :  Call Struct 0x31ea810 :   Call-ID = 3

07:08:15.124 SB.CCM  :  Org Acct = T01    Dst Acct = 2222222222

07:08:15.125 SB.CCM  :  Org Port ID = SipTrunk 0/1.1   Dst Port ID = FxsPhone 0/1

07:08:15.126 SB.CCM  :  RTP Channel = 0/1.1

07:08:15.126 SB.CCM isMappable: reversing call connection type to compensate for event originator direction

07:08:15.127 SB.CCM isMappable: Call Connection Type is TDM_TO_RTP

07:08:15.127 SB.CCM isMappable: Creating SDP Offer

07:08:15.131 SB.CCM updateOfferWithEndpointConfig: DTMF(NTE 101), VAD(off), ptime(0)

07:08:15.133 SB.CCM translateOffer: offer codec list: PCMU

07:08:15.133 SB.CCM translateOffer: revised offer codec list: PCMU

07:08:15.135 SB.CCM translateOffer: codec list after answerer: PCMU

07:08:15.137 SB.CCM translateOffer: DTMF signaling: answerer has no restrictions configured, passing offer(NTE 101) through

07:08:15.138 SB.CCM translateOffer: success

07:08:15.139 MEDIA.MANAGER Allocating media port.

07:08:15.139 MEDIA.MANAGER getSubstitutePort: Matching callIdMap entry found for call 3 sessionId -43636INIP4127.0.0.3 remote port 0

07:08:15.140 MEDIA.MANAGER getSubstitutePort: Matching sessionPortMap entry found for session

07:08:15.141 MEDIA.MANAGER getSubstitutePort: Session port count (1)  Returning port (10008)

07:08:15.142 MEDIA.MANAGER Existing entry found for port reuse of SDP port 0 and sub port 10008.

07:08:15.142 MEDIA.MANAGER Reuse media entry with updated SDP : call 3 : session -43636INIP4127.0.0.3 : remote IP 127.0.0.3 ports 10008 - 10009 : new :: ports 0 - 1.

07:08:15.143 MEDIA.MANAGER Call ID map : Replacing with newer version : call ID 3 : session -43636INIP4127.0.0.3 : version 4 : index 8

07:08:15.144 SB.CALL 3 Connected            Hold sent from 2222222222 to T01

07:08:15.145 TA.T01 01 TAConnected          hold event accepted

07:08:15.145 TM.T01 01 SipTM_Connected      State change      >> SipTM_Connected->SipTM_HoldFar

07:08:15.146 TM.T01 01 SDP DPI call ID 3 : No media bin.

07:08:15.147 TM.T01 01 Processing new SDP entries.

07:08:15.148 TM.T01 01 Checking for internal Media Gateway IP Address

07:08:15.148 TM.T01 01 RTP Channel is NULL, Media Gateway must not be involved in call

07:08:15.149 TM.T01 01 Undo of previous operation not required (RTP NAT Entry for [::]:10008 not found)

07:08:15.149 TM.T01 01 Checking for internal Media Gateway IP Address

07:08:15.150 TM.T01 01 SDP has hold address

07:08:15.155 SIP.STACK MSG     Tx: UDP src=192.168.61.200:5060 dst=192.168.61.19:5060

07:08:15.156 SIP.STACK MSG         INVITE sip:192.168.61.19:5060 SIP/2.0

07:08:15.156 SIP.STACK MSG         From: <sip:2222222222@192.168.61.200:5060>;tag=319d0c8-7f000001-13c4-1a6-10188d3d-1a6

07:08:15.157 SIP.STACK MSG         To: "City & State"<sip:1111111111@192.168.61.19:5060>;tag=koh450dx2egzB94-IPTrunk-1292-19-17at192.168.61.19

07:08:15.158 SIP.STACK MSG         Call-ID: koh450dx2egzB94@192.168.61.19

07:08:15.158 SIP.STACK MSG         CSeq: 2 INVITE

07:08:15.159 SIP.STACK MSG         Via: SIP/2.0/UDP 192.168.61.200:5060;branch=z9hG4bK-1ee-78cf2-1d43f3fc

07:08:15.159 SIP.STACK MSG         Max-Forwards: 70

07:08:15.160 SIP.STACK MSG         Supported: 100rel,replaces

07:08:15.161 SIP.STACK MSG         Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, PRACK, REFER, REGISTER

07:08:15.161 SIP.STACK MSG         User-Agent: ADTRAN_Total_Access_908_2nd_Gen/R12.3.1.E

07:08:15.163 SIP.STACK MSG         Contact: <sip:2222222222@192.168.61.200:5060;transport=UDP>

07:08:15.163 SIP.STACK MSG         Route: <sip:1293172737@192.168.61.19:5060;lr>

07:08:15.164 SIP.STACK MSG         Content-Type: application/sdp

07:08:15.164 SIP.STACK MSG         Content-Length: 193

07:08:15.165 SIP.STACK MSG    

07:08:15.165 SIP.STACK MSG         v=0

07:08:15.166 SIP.STACK MSG         o=- 43636 3 IN IP4 0.0.0.0

07:08:15.166 SIP.STACK MSG         s=-

07:08:15.167 SIP.STACK MSG         c=IN IP4 0.0.0.0

07:08:15.168 SIP.STACK MSG         t=0 0

07:08:15.168 SIP.STACK MSG         m=audio 10008 RTP/AVP 0 101

07:08:15.169 SIP.STACK MSG         a=silenceSupp:off - - - -

07:08:15.169 SIP.STACK MSG         a=rtpmap:0 PCMU/8000

07:08:15.170 SIP.STACK MSG         a=rtpmap:101 telephone-event/8000

07:08:15.170 SIP.STACK MSG         a=fmtp:101 0-15

07:08:15.171 SIP.STACK MSG    

07:08:15.173 TM.T01 01 SipTM_HoldFar        call-leg-mod -> Modify Re-Invite Sent

07:08:15.174 TM.T01 01 SipTM_HoldFar        sent: re-INVITE

07:08:15.179 SIP.STACK MSG     Rx: UDP src=192.168.61.19:5060 dst=192.168.61.200:5060

07:08:15.179 SIP.STACK MSG         SIP/2.0 100 Trying

07:08:15.180 SIP.STACK MSG         Via: SIP/2.0/UDP 192.168.61.200:5060;branch=z9hG4bK-1ee-78cf2-1d43f3fc

07:08:15.181 SIP.STACK MSG         From: <sip:2222222222@192.168.61.200:5060>;tag=319d0c8-7f000001-13c4-1a6-10188d3d-1a6

07:08:15.182 SIP.STACK MSG         To: "City & State" <sip:1111111111@192.168.61.19:5060>;tag=koh450dx2egzB94-IPTrunk-1292-19-17at192.168.61.19

07:08:15.182 SIP.STACK MSG         Call-ID: koh450dx2egzB94@192.168.61.19

07:08:15.183 SIP.STACK MSG         CSeq: 2 INVITE

07:08:15.184 SIP.STACK MSG         Content-Length: 0

07:08:15.184 SIP.STACK MSG    

07:08:15.187 TM.T01 01 SipTM_HoldFar        rcvd SIP call-leg response: 100 Trying

07:08:15.188 TM.T01 01 SipTM_HoldFar        call-leg-mod -> Modify Re-Invite Proceeding

07:08:19.183 SIP.STACK MSG     Rx: UDP src=192.168.61.19:5060 dst=192.168.61.200:5060

07:08:19.184 SIP.STACK MSG         SIP/2.0 488 Not Acceptable Here

07:08:19.184 SIP.STACK MSG         Via: SIP/2.0/UDP 192.168.61.200:5060;branch=z9hG4bK-1ee-78cf2-1d43f3fc

07:08:19.185 SIP.STACK MSG         From: <sip:2222222222@192.168.61.200:5060>;tag=319d0c8-7f000001-13c4-1a6-10188d3d-1a6

07:08:19.186 SIP.STACK MSG         To: "City & State" <sip:1111111111@192.168.61.19:5060>;tag=koh450dx2egzB94-IPTrunk-1292-19-17at192.168.61.19

07:08:19.186 SIP.STACK MSG         Call-ID: koh450dx2egzB94@192.168.61.19

07:08:19.187 SIP.STACK MSG         CSeq: 2 INVITE

07:08:19.187 SIP.STACK MSG         Content-Length: 0

07:08:19.188 SIP.STACK MSG    

07:08:19.191 TM.T01 01 SipTM_HoldFar        rcvd SIP call-leg response: 488 Not Acceptable Here

07:08:19.192 TM.T01 01 SipTM_HoldFar        call-leg-mod -> Modify Re-Invite Response Rcvd

07:08:19.192 TM.T01 01 SipTM_HoldFar        State change      >> SipTM_HoldFar->SipTM_HoldFarFailed

07:08:19.193 TM.T01 01 SipTM_HoldFarFailed  sent: TA->Hold Response

07:08:19.194 TM.T01 01 SipTM_HoldFarFailed  State change      >> SipTM_HoldFarFailed->SipTM_Connected

07:08:19.197 SIP.STACK MSG     Tx: UDP src=192.168.61.200:5060 dst=192.168.61.19:5060

07:08:19.198 SIP.STACK MSG         ACK sip:192.168.61.19:5060;transport=UDP SIP/2.0

07:08:19.199 SIP.STACK MSG         From: <sip:2222222222@192.168.61.200:5060>;tag=319d0c8-7f000001-13c4-1a6-10188d3d-1a6

07:08:19.199 SIP.STACK MSG         To: "City & State"<sip:1111111111@192.168.61.19:5060>;tag=koh450dx2egzB94-IPTrunk-1292-19-17at192.168.61.19

07:08:19.200 SIP.STACK MSG         Call-ID: koh450dx2egzB94@192.168.61.19

07:08:19.201 SIP.STACK MSG         CSeq: 2 ACK

07:08:19.201 SIP.STACK MSG         Via: SIP/2.0/UDP 192.168.61.200:5060;branch=z9hG4bK-1ee-78cf2-1d43f3fc

07:08:19.202 SIP.STACK MSG         Max-Forwards: 70

07:08:19.202 SIP.STACK MSG         Supported: 100rel,replaces

07:08:19.203 SIP.STACK MSG         Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, PRACK, REFER, REGISTER

07:08:19.204 SIP.STACK MSG         User-Agent: ADTRAN_Total_Access_908_2nd_Gen/R12.3.1.E

07:08:19.204 SIP.STACK MSG         Contact: <sip:2222222222@192.168.61.200:5060;transport=UDP>

07:08:19.205 SIP.STACK MSG         Route: <sip:1293172737@192.168.61.19:5060;lr>

07:08:19.206 SIP.STACK MSG         Content-Length: 0

07:08:19.206 SIP.STACK MSG    

07:08:19.207 TM.T01 01 SipTM_Connected      call-leg-mod -> Modify Idle

07:08:19.208 TA.T01 01 TAConnected          rcvd: holdResponse from TM

07:08:19.209 SB.CALL 3 Connected            Called the holdResponse routine

07:08:19.209 SB.CALL 3 Connected            ERROR! hold request failed

07:08:19.210 SB.CALL 3 Connected            holdResponse sent from T01 to 2222222222

07:08:19.211 SA.2222222222 Ca:0 HoldPending          rcvd: holdResponse from SB

07:08:19.211 SA.2222222222 Ca:0 HoldPending          State change      >> HoldPending->Connected (CAS_Connected)

07:08:19.212 SA.2222222222 Ca:0 Connected            sent: AcctPhoneMgr_holdResponse(fail) to PM

07:08:19.213 PM.0:1 State change      >> Hold Pending->Connected

test#

test#

test#

test#

test#

test#

test#

test#

test#

test#

test#

test#

test#  Disconnect

test#

test#

test#

test#

test#

test#

test#

test#

test#

test#

test#

07:08:57.646 PM.0:1 Connected            Processed ONHOOK

07:08:57.646 PM.0:1 State change      >> Connected->Idle

07:08:57.647 SA.2222222222 Ca:0 Connected            rcvd: AcctPhoneMgr_appearance(OFF) from PM

07:08:57.647 SA.2222222222 Ca:0 Connected            sent: clearCall to SB

07:08:57.648 SA.2222222222 Ca:0 Connected            State change      >> Connected->Clearing (CAS_Active)

07:08:57.649 SA.2222222222 rcvd: AcctPhoneMgr_COSOverride from PM

07:08:57.650 VOICE.SUMMARY Call from T01 to 2222222222 (2222222222) ended by 2222222222: normal clearing

07:08:57.650 SB.CALL 3 Connected            Called the clearCall routine

07:08:57.651 SB.CALL 3 Connected            ClearCall sent from 2222222222 to T01

07:08:57.651 SB.CALL 3 State change      >> Connected->Clearing

07:08:57.652 TA.T01 01 TAConnected          ClearCall event accepted

07:08:57.653 TA.T01 01 State change      >> TAConnected->TAClearingComplete (TAS_Clearing)

07:08:57.653 TM.T01 01 SipTM_Connected      tachg -> TAClearingComplete

07:08:57.654 TM.T01 01 SipTM_Connected      tachgClearing caused transition to byeing state.

07:08:57.655 TM.T01 01 SipTM_Connected      State change      >> SipTM_Connected->SipTM_Byeing

07:08:57.658 SIP.STACK MSG     Tx: UDP src=192.168.61.200:5060 dst=192.168.61.19:5060

07:08:57.659 SIP.STACK MSG         BYE sip:192.168.61.19:5060;transport=UDP SIP/2.0

07:08:57.659 SIP.STACK MSG         From: <sip:2222222222@192.168.61.200:5060>;tag=319d0c8-7f000001-13c4-1a6-10188d3d-1a6

07:08:57.661 SIP.STACK MSG         To: "City & State"<sip:1111111111@192.168.61.19:5060>;tag=koh450dx2egzB94-IPTrunk-1292-19-17at192.168.61.19

07:08:57.661 SIP.STACK MSG         Call-ID: koh450dx2egzB94@192.168.61.19

07:08:57.662 SIP.STACK MSG         CSeq: 3 BYE

07:08:57.662 SIP.STACK MSG         Via: SIP/2.0/UDP 192.168.61.200:5060;branch=z9hG4bK-219-832cb-e4461d0

07:08:57.663 SIP.STACK MSG         Max-Forwards: 70

07:08:57.664 SIP.STACK MSG         Supported: 100rel,replaces

07:08:57.664 SIP.STACK MSG         Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, PRACK, REFER, REGISTER

07:08:57.665 SIP.STACK MSG         User-Agent: ADTRAN_Total_Access_908_2nd_Gen/R12.3.1.E

07:08:57.666 SIP.STACK MSG         Route: <sip:1293172737@192.168.61.19:5060;lr>

07:08:57.666 SIP.STACK MSG         Content-Length: 0

07:08:57.667 SIP.STACK MSG    

07:08:57.670 TM.T01 01 SipTM_Byeing         call-leg -> Disconnecting

07:08:57.670 TM.T01 01 SipTM_Byeing         sent: BYE

07:08:57.672 SB.CALL 3 Clearing             Called the clearResponse routine

07:08:57.672 SB.CALL 3 State change      >> Clearing->CallIdlePending

07:08:57.673 SB.CCM disconnect:

07:08:57.673 SB.CCM  :  Call Struct 0x31ea810 :   Call-ID = 3

07:08:57.674 SB.CCM  :  Org Acct = T01    Dst Acct = 2222222222

07:08:57.675 SB.CCM  :  Org Port ID = SipTrunk 0/1.1   Dst Port ID = FxsPhone 0/1

07:08:57.676 SB.CCM  :  RTP Channel = 0/1.1

07:08:57.678 SIP.STACK MSG     Rx: UDP src=192.168.61.19:5060 dst=192.168.61.200:5060

07:08:57.678 SIP.STACK MSG         SIP/2.0 200 OK

07:08:57.679 SIP.STACK MSG         Via: SIP/2.0/UDP 192.168.61.200:5060;branch=z9hG4bK-219-832cb-e4461d0

07:08:57.679 SIP.STACK MSG         From: <sip:2222222222@192.168.61.200:5060>;tag=319d0c8-7f000001-13c4-1a6-10188d3d-1a6

07:08:57.680 SIP.STACK MSG         To: "City & State" <sip:1111111111@192.168.61.19:5060>;tag=koh450dx2egzB94-IPTrunk-1292-19-17at192.168.61.19

07:08:57.681 SIP.STACK MSG         Call-ID: koh450dx2egzB94@192.168.61.19

07:08:57.682 SIP.STACK MSG         CSeq: 3 BYE

07:08:57.682 SIP.STACK MSG         Content-Length: 0

07:08:57.683 SIP.STACK MSG    

07:08:57.686 TM.T01 01 SipTM_Byeing         rcvd SIP call-leg response: 200 OK

07:08:57.687 TM.T01 01 SipTM_Byeing         call-leg -> Disconnected

07:08:57.687 TM.T01 01 SipTM_Byeing         State change      >> SipTM_Byeing->SipTM_Terminated

07:08:57.688 TM.T01 01 SipTM_Terminated     sent: TA->AppearanceOff

07:08:57.689 TM.T01 01 SipTM_Terminated     State change      >> SipTM_Terminated->SipTM_Idle

07:08:57.690 SB.CCM disconnect: Call Connection Type is RTP_TO_TDM

07:08:57.691 SB.CCM disconnect: Stopping RTP Channel 0/1.1

07:08:57.692 RTP.CHANNEL Channel 0/1.1 stopped successfully.

07:08:57.692 SB.CCM disconnect: Disconnecting TDM streams

07:08:57.693 SB.CCM release:

07:08:57.694 SB.CCM  :  Call Struct 0x31ea810 :   Call-ID = 3

07:08:57.694 SB.CCM  :  Org Acct = T01    Dst Acct = 2222222222

07:08:57.695 SB.CCM  :  Org Port ID = SipTrunk 0/1.1   Dst Port ID = FxsPhone 0/1

07:08:57.695 SB.CCM  :  RTP Channel = 0/1.1

07:08:57.696 SB.CCM release: Call Connection Type is RTP_TO_TDM

07:08:57.697 SB.CCM release: Releasing RTP Channel 0/1.1

07:08:57.697 RTP.CHANNEL RtpChannel::deallocate, status = 3, allocatedForInterface = 0

07:08:57.698 RTP.CHANNEL Channel 0/1.1 released successfully.

07:08:57.699 SB.CALL 3 CallIdlePending      ClearResponse sent from T01 to 2222222222

07:08:57.699 SA.2222222222 Ca:0 Clearing             rcvd: clearResponse from SB

07:08:57.700 SA.2222222222 Ca:0 Clearing             State change      >> Clearing->Idle (CAS_Idle)

07:08:57.701 SA.2222222222 Ca:0 Idle                 sent: AcctPhoneMgr_cachg(CAS_Idle) to PM

07:08:57.701 PM.0:1 Idle                 Dropped CACHG w/Call State not RINGING

07:08:57.702 TA.T01 01 TAClearingComplete   rcvd: appearance off from TM

07:08:57.703 TA.T01 01 TAClearingComplete   Clear Local Variables

07:08:57.703 TA.T01 01 State change      >> TAClearingComplete->TAIdle (TAS_Idle)

07:08:57.704 TM.T01 01 SipTM_Idle           tachg -> TAIdle

07:08:57.705 RTP.CHANNEL fxs 0/1 - Dsp 0/1.1 - RTP: stopping

07:08:57.706 RTP.CHANNEL fxs 0/1 - Dsp 0/1.1 - RTP: releasing RTP resource

07:08:57.707 RTP.CHANNEL fxs 0/1 - Dsp 0/1.1 - RTP: releasing

07:08:57 SB.CallStructObserver 3 Finalized

1970.01.01 07:08:58 SMDR 3          01/01/1970 07:07:14      1.7 0    I  00/01 City & State 1111111111      00/01 Justin Tyme     2222222222      0 T 

07:09:45.127 MEDIA.MANAGER Remove Call ID map entry for call 3

test#

Labels (2)
0 Kudos
4 Replies
jayh
Honored Contributor
Honored Contributor

Re: Cannot transfer call to external number

Your SIP server is rejecting the re-invite associated with the hookflash.

See the following received message:

07:08:19.183 SIP.STACK MSG     Rx: UDP src=192.168.61.19:5060 dst=192.168.61.200:5060

07:08:19.184 SIP.STACK MSG         SIP/2.0 488 Not Acceptable Here

Then the switchboard detects the failed re-invite and re-establishes the call:

07:08:19.191 TM.T01 01 SipTM_HoldFar        rcvd SIP call-leg response: 488 Not Acceptable Here

07:08:19.192 TM.T01 01 SipTM_HoldFar        call-leg-mod -> Modify Re-Invite Response Rcvd

07:08:19.192 TM.T01 01 SipTM_HoldFar        State change      >> SipTM_HoldFar->SipTM_HoldFarFailed

The next step would be to find out why the SIP server is rejecting the re-invite. It could be a SIP grammar issue, a feature that needs to be turned on, or something else. Logs from the SIP server will be useful.

Re: Cannot transfer call to external number

Thanks for helping me with this jayh

The SIP trace from my Taqua switch is lacking SDP info from the IAD during the REinvite.  Specifically, there is no p=  telling the switch to place the call on hold or make inactive until bridged with a 3rd party.  And the SDP body is missing a codec.

Is there a way to get the IAD to send this SDP info?

Here is my SIP Trace output from my switch.  I copied just the part around the hookflash event...

897.9183> |--- Start RX 901 bytes on FE-1-19-17 at [05/26/2017-12:14:17.880] from 192.168.61.200:5060 ---|

INVITE sip:192.168.61.19:5060 SIP/2.0

From: <sip:2222222222@192.168.61.200:5060>;tag=319d8e8-7f000001-13c4-2da-4fe84954-2da

To: "City & State"<sip:1111111111@192.168.61.19:5060>;tag=RQh450cx2hgds0n-IPTrunk-867-19-17at192.168.61.19

Call-ID: RQh450cx2hgds0n@192.168.61.19

CSeq: 2 INVITE

Via: SIP/2.0/UDP 192.168.61.200:5060;branch=z9hG4bK-30c-be739-22fe0032

Max-Forwards: 70

Supported: 100rel,replaces

Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, PRACK, REFER, REGISTER

User-Agent: ADTRAN_Total_Access_908_2nd_Gen/R12.3.1.E

Contact: <sip:2222222222@192.168.61.200:5060;transport=UDP>

Route: <sip:1293172737@192.168.61.19:5060;lr>

Content-Type: application/sdp

Content-Length: 193

v=0

o=- 43946 3 IN IP4 0.0.0.0

s=-

c=IN IP4 0.0.0.0

t=0 0

m=audio 10020 RTP/AVP 0 101

a=silenceSupp:off - - - -

a=rtpmap:0 PCMU/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-15

|--- End of message received from the network ---|

|--- Start TX 368 bytes on FE-1-19-17 at [05/26/2017-12:14:17.892] to 192.168.61.200:5060 ---|

SIP/2.0 100 Trying

Via: SIP/2.0/UDP 192.168.61.200:5060;branch=z9hG4bK-30c-be739-22fe0032

TRACE[05/26/2017-12:14:17.894] (tSignaling - 867) : ReINVITEprocedure processing in stable call state

TRACE[05/26/2017-12:14:17.894] SRC:0x3f-> (tSignaling - 867) -> (transferIndicate) [connected :: connected]

TRACE[05/26/2017-12:14:17.895] SRC:0x3f-> (tSignaling - 867) -> (recvREINVITE) [connected :: midCallReqRcvd]

From: <sip:2222222222@192.168.61.200:5060>;tag=319d8e8-7f000001-13c4-2da-4fe84954-2da

To: "City & State" <sip:1111111111@192.168.61.19:5060>;tag=RQh450cx2hgds0n-IPTrunk-867-19-17at192.168.61.19

Call-ID: RQh450cx2hgds0n@192.168.61.19

CSeq: 2 INVITE

Content-Length: 0

|--- End of message sent to the network ---|

TRACE[05/26/2017-12:14:17.900] SRC:0x3f-> (tSignaling - 867) -> (protocolServiceRsp) [midCallReqRcvd :: midCallReqRcvd]

TRACE[05/26/2017-12:14:21.900] (tSignaling - 867) : process ReINVITE protocolServiceRsp reasonCode = 3

TRACE[05/26/2017-12:14:21.900] SRC:0x3f-> (tSignaling - 867) -> (protocolServiceRsp) [midCallReqRcvd :: midCallReqRcvd]

|--- Start TX 381 bytes on FE-1-19-17 at [05/26/2017-12:14:21.901] to 192.168.61.200:5060 ---|

SIP/2.0 488 Not Acceptable Here

Via: SIP/2.0/UDP 192.168.61.200:5060;branch=z9hG4bK-30c-be739-22fe0032

From: <sip:2222222222@192.168.61.200:5060>;tag=319d8e8-7f000001-13c4-2da-4fe84954-2da

To: "City & State" <sip:1111111111@192.168.61.19:5060>;tag=RQh450cx2hgds0n-IPTrunk-867-19-17at192.168.61.19

Call-ID: RQh450cx2hgds0n@192.168.61.19

CSeq: 2 INVITE

Content-Length: 0

|--- End of message sent to the network ---|

jayh
Honored Contributor
Honored Contributor

Re: Cannot transfer call to external number

Have you asked Taqua if they have an interop guide for Adtran?

There are a couple of things you can try. First, try disabling the double-renivite and tweaking the SDP.

voice trunk T01

  no prefer double-reinvite

Also this may help:

voice trunk T01

  no prefer reinvite-without-sdp

Another option is to handle the transfer function locally rather than on the softswitch.

voice transfer-mode local

is the command. Default is

voice transfer-mode network

Re: Cannot transfer call to external number

I have asked Taqua for an Interop Guide (if one exists).

Meanwhile, I have tried many different scenarios.  I have tried "transfer-mode = {local and network}".  I have tried disabling the double-reinvite and reinvite-without-sdp as you suggested above (separately and together).  A friend at another telco shared his TA900 config and that config is producing the same results on my switch (and he has the same switch I have).  I'll update again after I try a few more things.