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#
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.
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 ---|
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
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.