PSIP not following 302 (Port Redirect)

Hello, everyone.

I already found a very similar topic PJSIP isn’t following 302 response but using redirect_method didn’t solve it for me.

I’m trying to make a call from a sip phone registered in asterisk and dial to another sip server. That sip server sends a 302 changing only the port [Contact: “” sip:222@172.31.101.101:25064], but pjsip reinvite on the same port.

Could the problem be due to port redirection only?

pjsip.conf

[receptivo101]
type=endpoint
context=default
disallow=all
allow=alaw,ulaw
transport=transport-udp
aors=receptivo101
direct_media=no
force_rport=yes
rewrite_contact=yes
redirect_method=user

[receptivo101]
type=aor
contact=sip:172.31.101.101:5061

[receptivo101]
type=identify
endpoint=receptivo101
match=172.31.101.0/24

extensions.conf

exten => _222,1,NoOp(TRUNK receptivo101)
same => 2,Progress()
same => 3,Set(__ARCH_RECORD_ID=${UNIQUEID})
same => 4,Dial(PJSIP/${EXTEN:0:255}@receptivo101,90)
same => 5,Hangup()

and log, where 192.168.100.35 is Asterisk and 172.31.101.101 own Sip Server…

[Feb 26 12:44:30] DEBUG[24868]: res_pjsip/pjsip_resolver.c:479 sip_resolve: Performing SIP DNS resolution of target '172.31.101.101'
[Feb 26 12:44:30] DEBUG[24868]: res_pjsip/pjsip_resolver.c:506 sip_resolve: Transport type for target '172.31.101.101' is 'UDP transport'
[Feb 26 12:44:30] DEBUG[24868]: res_pjsip/pjsip_resolver.c:527 sip_resolve: Target '172.31.101.101' is an IP address, skipping resolution
[Feb 26 12:44:30] DEBUG[24868]: res_pjsip/pjsip_message_filter.c:296 filter_on_tx_message: Re-wrote Contact URI host/port to 192.168.100.35:5060 (this may be re-written again later)
<--- Transmitting SIP request (932 bytes) to UDP:172.31.101.101:5061 --->
INVITE sip:222@172.31.101.101:5061 SIP/2.0
Via: SIP/2.0/UDP 192.168.100.35:5060;rport;branch=z9hG4bKPj49a67fe0-504f-4260-9bc1-c95092770928
From: "400" <sip:400@192.168.100.35>;tag=20ca5e97-5902-41da-b2d8-8be9ad30a3b1
To: <sip:222@172.31.101.101>
Contact: <sip:asterisk@192.168.100.35:5060>
Call-ID: ecf84997-579f-442a-9bbc-f6c42501b949
CSeq: 27081 INVITE
Allow: OPTIONS, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, REFER
Supported: 100rel, timer, replaces, norefersub, histinfo
Session-Expires: 1800
Min-SE: 90
Max-Forwards: 70
User-Agent: CommSL-MGCOMM
Content-Type: application/sdp
Content-Length:   265

v=0
o=- 1813102033 1813102033 IN IP4 192.168.100.35
s=Asterisk
c=IN IP4 192.168.100.35
t=0 0
m=audio 14422 RTP/AVP 8 0 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=maxptime:150
a=sendrecv

[Feb 26 12:44:30] DEBUG[24868]: res_pjsip_session.c:4652 session_inv_on_state_changed:  PJSIP/receptivo101-00000005 Event: TSX_STATE  Inv State: CALLING
[Feb 26 12:44:30] DEBUG[24868]: res_pjsip_session.c:4390 __print_debug_details: Function session_inv_on_state_changed called on event TSX_STATE
[Feb 26 12:44:30] DEBUG[24868]: res_pjsip_session.c:4404 __print_debug_details: The state change pertains to the endpoint 'receptivo101(PJSIP/receptivo101-00000005)'
[Feb 26 12:44:30] DEBUG[24868]: res_pjsip_session.c:4409 __print_debug_details: The inv session still has an invite_tsx (0x7fe5bc032218)
[Feb 26 12:44:30] DEBUG[24868]: res_pjsip_session.c:4424 __print_debug_details: There is no transaction involved in this state change
[Feb 26 12:44:30] DEBUG[24868]: res_pjsip_session.c:4426 __print_debug_details: The current inv state is CALLING
[Feb 26 12:44:30] DEBUG[24868]: res_pjsip_session.c:4678 session_inv_on_state_changed: PJSIP/receptivo101-00000005: Source of transaction state change is TX_MSG
[Feb 26 12:44:30] DEBUG[24868]: res_pjsip_session.c:4726 session_inv_on_state_changed:
[Feb 26 12:44:30] DEBUG[24868]: res_pjsip_session.c:4770 session_inv_on_tsx_state_changed:  PJSIP/receptivo101-00000005 TSX State: Calling  Inv State: CALLING
[Feb 26 12:44:30] DEBUG[24868]: res_pjsip_session.c:4390 __print_debug_details: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Feb 26 12:44:30] DEBUG[24868]: res_pjsip_session.c:4404 __print_debug_details: The state change pertains to the endpoint 'receptivo101(PJSIP/receptivo101-00000005)'
[Feb 26 12:44:30] DEBUG[24868]: res_pjsip_session.c:4409 __print_debug_details: The inv session still has an invite_tsx (0x7fe5bc032218)
[Feb 26 12:44:30] DEBUG[24868]: res_pjsip_session.c:4415 __print_debug_details: The UAC INVITE transaction involved in this state change is 0x7fe5bc032218
[Feb 26 12:44:30] DEBUG[24868]: res_pjsip_session.c:4419 __print_debug_details: The current transaction state is Calling
[Feb 26 12:44:30] DEBUG[24868]: res_pjsip_session.c:4421 __print_debug_details: The transaction state change event is TX_MSG
[Feb 26 12:44:30] DEBUG[24868]: res_pjsip_session.c:4426 __print_debug_details: The current inv state is CALLING
[Feb 26 12:44:30] DEBUG[24868]: res_pjsip_session.c:4958 session_inv_on_tsx_state_changed:  Nothing delayed
[Feb 26 12:44:30] DEBUG[24868]: res_pjsip_session.c:4202 session_on_tsx_state:  PJSIP/receptivo101-00000005 TSX State: Calling  Inv State: CALLING
[Feb 26 12:44:30] DEBUG[24868]: res_pjsip_session.c:4206 session_on_tsx_state:  Topology: Pending:  <0:audio-0:audio:sendrecv (alaw|ulaw)>  Active: (null topology)
[Feb 26 12:44:30] DEBUG[24868]: res_pjsip_session.c:4211 session_on_tsx_state:
[Feb 26 12:44:30] DEBUG[24868]: chan_pjsip.c:2382 call:  RC: 0
       > 0x7fe5c4047590 -- Strict RTP switching to RTP target address 192.168.100.54:4004 as source
<--- Received SIP response (364 bytes) from UDP:172.31.101.101:5061 --->
SIP/2.0 302 Redirect URA
Via: SIP/2.0/UDP 192.168.100.35:5060;rport;branch=z9hG4bKPj49a67fe0-504f-4260-9bc1-c95092770928
From: "400" <sip:400@192.168.100.35>;tag=20ca5e97-5902-41da-b2d8-8be9ad30a3b1
To: <sip:222@172.31.101.101>
Call-ID: ecf84997-579f-442a-9bbc-f6c42501b949
CSeq: 27081 INVITE
Contact: "" <sip:222@172.31.101.101:25064>
Content-Length: 0


[Feb 26 12:44:30] DEBUG[24644]: res_pjsip/pjsip_distributor.c:502 distributor: Searching for serializer associated with dialog dlg0x7fe5b00039d8 for Response msg 302/INVITE/cseq=27081 (rdata0x7fe5c40025c8)
[Feb 26 12:44:30] DEBUG[24644]: res_pjsip/pjsip_distributor.c:510 distributor: Found serializer pjsip/outsess/receptivo101-00000065 associated with dialog dlg0x7fe5b00039d8
<--- Transmitting SIP request (378 bytes) to UDP:172.31.101.101:5061 --->
ACK sip:222@172.31.101.101:5061 SIP/2.0
Via: SIP/2.0/UDP 192.168.100.35:5060;rport;branch=z9hG4bKPj49a67fe0-504f-4260-9bc1-c95092770928
From: "400" <sip:400@192.168.100.35>;tag=20ca5e97-5902-41da-b2d8-8be9ad30a3b1
To: <sip:222@172.31.101.101>
Call-ID: ecf84997-579f-442a-9bbc-f6c42501b949
CSeq: 27081 ACK
Max-Forwards: 70
User-Agent: CommSL-MGCOMM
Content-Length:  0


[Feb 26 12:44:30] DEBUG[24868]: res_pjsip_session.c:4225 session_on_rx_response:  PJSIP/receptivo101-00000005 Method: INVITE Status: 302
[Feb 26 12:44:30] DEBUG[24868]: res_pjsip_session.c:4228 session_on_rx_response:
[Feb 26 12:44:30] DEBUG[24868]: res_pjsip/pjsip_resolver.c:479 sip_resolve: Performing SIP DNS resolution of target '172.31.101.101'
[Feb 26 12:44:30] DEBUG[24868]: res_pjsip/pjsip_resolver.c:506 sip_resolve: Transport type for target '172.31.101.101' is 'UDP transport'
[Feb 26 12:44:30] DEBUG[24868]: res_pjsip/pjsip_resolver.c:527 sip_resolve: Target '172.31.101.101' is an IP address, skipping resolution
[Feb 26 12:44:30] DEBUG[24868]: res_pjsip/pjsip_message_filter.c:296 filter_on_tx_message: Re-wrote Contact URI host/port to 192.168.100.35:5060 (this may be re-written again later)
<--- Transmitting SIP request (932 bytes) to UDP:172.31.101.101:5061 --->
INVITE sip:222@172.31.101.101:5061 SIP/2.0
Via: SIP/2.0/UDP 192.168.100.35:5060;rport;branch=z9hG4bKPj69a23a8c-2905-4c07-80d9-51c86065190f
From: "400" <sip:400@192.168.100.35>;tag=20ca5e97-5902-41da-b2d8-8be9ad30a3b1
To: <sip:222@172.31.101.101>
Contact: <sip:asterisk@192.168.100.35:5060>
Call-ID: ecf84997-579f-442a-9bbc-f6c42501b949
CSeq: 27082 INVITE
Allow: OPTIONS, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, REFER
Supported: 100rel, timer, replaces, norefersub, histinfo
Session-Expires: 1800
Min-SE: 90
Max-Forwards: 70
User-Agent: CommSL-MGCOMM
Content-Type: application/sdp
Content-Length:   265

v=0
o=- 1813102033 1813102033 IN IP4 192.168.100.35
s=Asterisk
c=IN IP4 192.168.100.35
t=0 0
m=audio 14422 RTP/AVP 8 0 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=maxptime:150
a=sendrecv

That redirect_method won’t do what you want. You’d want to use either uri_core or uri_pjsip. If that fails you’d need to show it.

1 Like

They also fail, I already tried ‘uri_core’, but it has the similar behavior as ‘user’ to me. I can’t use ‘uri_pjsip’ as the documentation says it doesn’t forward some data, but I tested it anyway.

The main difference I noticed was the number of attempts: ‘uri_core’ tries 30x and ‘uri_pjsip’ 2x

Here partial log with ‘uri_core’

[Feb 26 13:53:52] DEBUG[25637]: res_pjsip/pjsip_resolver.c:479 sip_resolve: Performing SIP DNS resolution of target '172.31.101.101'
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip/pjsip_resolver.c:506 sip_resolve: Transport type for target '172.31.101.101' is 'UDP transport'
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip/pjsip_resolver.c:527 sip_resolve: Target '172.31.101.101' is an IP address, skipping resolution
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip/pjsip_message_filter.c:296 filter_on_tx_message: Re-wrote Contact URI host/port to 192.168.100.35:5060 (this may be re-written again later)
<--- Transmitting SIP request (984 bytes) to UDP:172.31.101.101:5061 --->
INVITE sip:222@172.31.101.101:5061 SIP/2.0
Via: SIP/2.0/UDP 192.168.100.35:5060;rport;branch=z9hG4bKPjdb4ac206-192b-4287-822b-883ba654e0ff
From: "400" <sip:400@192.168.100.35>;tag=72dc81aa-8c32-4e69-9cce-7365a848a35b
To: <sip:222@172.31.101.101>
Contact: <sip:asterisk@192.168.100.35:5060>
Call-ID: 301cf274-d8c8-4c04-97f5-3206b866d790
CSeq: 17908 INVITE
Allow: OPTIONS, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, REFER
Supported: 100rel, timer, replaces, norefersub, histinfo
Session-Expires: 1800
Min-SE: 90
Diversion: <sip:222@192.168.100.35>;reason=unknown
Max-Forwards: 70
User-Agent: CommSL-MGCOMM
Content-Type: application/sdp
Content-Length:   265

[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4652 session_inv_on_state_changed:  PJSIP/receptivo101-00000062 Event: TSX_STATE  Inv State: CALLING
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4390 __print_debug_details: Function session_inv_on_state_changed called on event TSX_STATE
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4404 __print_debug_details: The state change pertains to the endpoint 'receptivo101(PJSIP/receptivo101-00000062)'
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4409 __print_debug_details: The inv session still has an invite_tsx (0x7fb6a825df98)
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4424 __print_debug_details: There is no transaction involved in this state change
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4426 __print_debug_details: The current inv state is CALLING
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4678 session_inv_on_state_changed: PJSIP/receptivo101-00000062: Source of transaction state change is TX_MSG
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4726 session_inv_on_state_changed:
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4770 session_inv_on_tsx_state_changed:  PJSIP/receptivo101-00000062 TSX State: Calling  Inv State: CALLING
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4390 __print_debug_details: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4404 __print_debug_details: The state change pertains to the endpoint 'receptivo101(PJSIP/receptivo101-00000062)'
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4409 __print_debug_details: The inv session still has an invite_tsx (0x7fb6a825df98)
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4415 __print_debug_details: The UAC INVITE transaction involved in this state change is 0x7fb6a825df98
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4419 __print_debug_details: The current transaction state is Calling
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4421 __print_debug_details: The transaction state change event is TX_MSG
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4426 __print_debug_details: The current inv state is CALLING
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4958 session_inv_on_tsx_state_changed:  Nothing delayed
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4202 session_on_tsx_state:  PJSIP/receptivo101-00000062 TSX State: Calling  Inv State: CALLING
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4206 session_on_tsx_state:  Topology: Pending:  <0:audio-0:audio:sendrecv (alaw|ulaw)>  Active: (null topology)
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4211 session_on_tsx_state:
[Feb 26 13:53:52] DEBUG[25637]: chan_pjsip.c:2382 call:  RC: 0
<--- Received SIP response (364 bytes) from UDP:172.31.101.101:5061 --->
SIP/2.0 302 Redirect URA
Via: SIP/2.0/UDP 192.168.100.35:5060;rport;branch=z9hG4bKPjdb4ac206-192b-4287-822b-883ba654e0ff
From: "400" <sip:400@192.168.100.35>;tag=72dc81aa-8c32-4e69-9cce-7365a848a35b
To: <sip:222@172.31.101.101>
Call-ID: 301cf274-d8c8-4c04-97f5-3206b866d790
CSeq: 17908 INVITE
Contact: "" <sip:222@172.31.101.101:25061>
Content-Length: 0


[Feb 26 13:53:52] DEBUG[25579]: res_pjsip/pjsip_distributor.c:502 distributor: Searching for serializer associated with dialog dlg0x7fb6a8272c68 for Response msg 302/INVITE/cseq=17908 (rdata0x7fb6a4003418)
[Feb 26 13:53:52] DEBUG[25579]: res_pjsip/pjsip_distributor.c:510 distributor: Found serializer pjsip/outsess/receptivo101-000000be associated with dialog dlg0x7fb6a8272c68
<--- Transmitting SIP request (378 bytes) to UDP:172.31.101.101:5061 --->
ACK sip:222@172.31.101.101:5061 SIP/2.0
Via: SIP/2.0/UDP 192.168.100.35:5060;rport;branch=z9hG4bKPjdb4ac206-192b-4287-822b-883ba654e0ff
From: "400" <sip:400@192.168.100.35>;tag=72dc81aa-8c32-4e69-9cce-7365a848a35b
To: <sip:222@172.31.101.101>
Call-ID: 301cf274-d8c8-4c04-97f5-3206b866d790
CSeq: 17908 ACK
Max-Forwards: 70
Content-Length:  0


[Feb 26 13:53:52] DEBUG[25636]: res_pjsip_session.c:4225 session_on_rx_response:  PJSIP/receptivo101-00000062 Method: INVITE Status: 302
[Feb 26 13:53:52] DEBUG[25636]: res_pjsip_session.c:4228 session_on_rx_response:
[Feb 26 13:53:52] DEBUG[25636]: res_pjsip_session.c:4487 handle_incoming_response:  PJSIP/receptivo101-00000062: Response is 302 Redirect URA
[Feb 26 13:53:52] DEBUG[25636]: res_pjsip_session.c:4499 handle_incoming_response:  PJSIP/receptivo101-00000062
[Feb 26 13:53:52] DEBUG[25636]: res_pjsip_session.c:4652 session_inv_on_state_changed:  PJSIP/receptivo101-00000062 Event: TSX_STATE  Inv State: DISCONNCTD
[Feb 26 13:53:52] DEBUG[25636]: res_pjsip_session.c:4390 __print_debug_details: Function session_inv_on_state_changed called on event TSX_STATE
[Feb 26 13:53:52] DEBUG[25636]: res_pjsip_session.c:4404 __print_debug_details: The state change pertains to the endpoint 'receptivo101(PJSIP/receptivo101-00000062)'
[Feb 26 13:53:52] DEBUG[25636]: res_pjsip_session.c:4409 __print_debug_details: The inv session still has an invite_tsx (0x7fb6a825df98)
[Feb 26 13:53:52] DEBUG[25636]: res_pjsip_session.c:4424 __print_debug_details: There is no transaction involved in this state change
[Feb 26 13:53:52] DEBUG[25636]: res_pjsip_session.c:4426 __print_debug_details: The current inv state is DISCONNCTD
[Feb 26 13:53:52] DEBUG[25636]: res_pjsip_session.c:4678 session_inv_on_state_changed: PJSIP/receptivo101-00000062: Source of transaction state change is RX_MSG
[Feb 26 13:53:52] DEBUG[25636]: res_pjsip_session.c:4620 handle_incoming_before_media: PJSIP/receptivo101-00000062: Received response
[Feb 26 13:53:52] DEBUG[25636]: res_pjsip_session.c:4487 handle_incoming_response:  PJSIP/receptivo101-00000062: Response is 302 Redirect URA
[Feb 26 13:53:52] DEBUG[25636]: chan_pjsip.c:3162 chan_pjsip_incoming_response_update_cause:  PJSIP/receptivo101-00000062: Status: 302
[Feb 26 13:53:52] DEBUG[25636]: chan_pjsip.c:3183 chan_pjsip_incoming_response_update_cause:  PJSIP/receptivo101-00000062
[Feb 26 13:53:52] DEBUG[25636]: res_pjsip_session.c:4499 handle_incoming_response:  PJSIP/receptivo101-00000062
[Feb 26 13:53:52] DEBUG[25636]: res_pjsip_session.c:4726 session_inv_on_state_changed:
[Feb 26 13:53:52] DEBUG[25636]: res_pjsip_session.c:4770 session_inv_on_tsx_state_changed:  PJSIP/receptivo101-00000062 TSX State: Completed  Inv State: DISCONNCTD
[Feb 26 13:53:52] DEBUG[25636]: res_pjsip_session.c:4390 __print_debug_details: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Feb 26 13:53:52] DEBUG[25636]: res_pjsip_session.c:4404 __print_debug_details: The state change pertains to the endpoint 'receptivo101(PJSIP/receptivo101-00000062)'
[Feb 26 13:53:52] DEBUG[25636]: res_pjsip_session.c:4409 __print_debug_details: The inv session still has an invite_tsx (0x7fb6a825df98)
[Feb 26 13:53:52] DEBUG[25636]: res_pjsip_session.c:4415 __print_debug_details: The UAC INVITE transaction involved in this state change is 0x7fb6a825df98
[Feb 26 13:53:52] DEBUG[25636]: res_pjsip_session.c:4419 __print_debug_details: The current transaction state is Completed
[Feb 26 13:53:52] DEBUG[25636]: res_pjsip_session.c:4421 __print_debug_details: The transaction state change event is RX_MSG
[Feb 26 13:53:52] DEBUG[25636]: res_pjsip_session.c:4426 __print_debug_details: The current inv state is DISCONNCTD
[Feb 26 13:53:52] DEBUG[25636]: res_pjsip_session.c:4792 session_inv_on_tsx_state_changed:  Disconnected
[Feb 26 13:53:52] DEBUG[25636]: res_pjsip_session.c:4202 session_on_tsx_state:  (null session) TSX State: Completed  Inv State: DISCONNCTD
[Feb 26 13:53:52] DEBUG[25636]: res_pjsip_session.c:4211 session_on_tsx_state:
[Feb 26 13:53:52] DEBUG[25636]: chan_pjsip.c:2975 chan_pjsip_session_end:  PJSIP/receptivo101-00000062
[Feb 26 13:53:52] DEBUG[25636]: chan_pjsip.c:2992 chan_pjsip_session_end:
    -- Now forwarding PJSIP/400-00000048 to 'PJSIP/receptivo101/sip:222@172.31.101.101:5061' (thanks to PJSIP/receptivo101-00000062)
[Feb 26 13:53:52] DEBUG[25663][C-00000007]: chan_pjsip.c:2672 chan_pjsip_request_with_stream_topology:  receptivo101/sip:222@172.31.101.101:5061 Topology:  <0:audio-0:audio:sendrecv (alaw)>
[Feb 26 13:53:52] DEBUG[25636]: chan_pjsip.c:2571 request:  receptivo101/sip:222@172.31.101.101:5061
[Feb 26 13:53:52] DEBUG[25636]: res_pjsip_session.c:3330 ast_sip_session_create_outgoing:  receptivo101 (null) Topology:  <0:audio-0:audio:sendrecv (alaw)>
[Feb 26 13:53:52] DEBUG[25636]: chan_pjsip.c:2954 chan_pjsip_session_begin:  receptivo101
[Feb 26 13:53:52] DEBUG[25636]: chan_pjsip.c:2958 chan_pjsip_session_begin:  Direct media no glare mitigation
[Feb 26 13:53:52] DEBUG[25636]: res_pjsip_session/pjsip_session_caps.c:146 ast_sip_session_create_joint_call_stream: 'receptivo101' Caps for outgoing audio call with pref 'remote_merge' - remote: (alaw) local: (alaw|ulaw) joint: (alaw|ulaw)
[Feb 26 13:53:52] DEBUG[25636]: res_pjsip_session.c:3446 ast_sip_session_create_outgoing:
[Feb 26 13:53:52] DEBUG[25636]: chan_pjsip.c:2664 request:
[Feb 26 13:53:52] DEBUG[25663][C-00000007]: chan_pjsip.c:555 chan_pjsip_new:  receptivo101
[Feb 26 13:53:52] DEBUG[25663][C-00000007]: channel_internal_api.c:680 ast_channel_nativeformats_set:  <initializing>: Formats: (none)
[Feb 26 13:53:52] DEBUG[25663][C-00000007]: channel_internal_api.c:692 ast_channel_nativeformats_set:  Channel is being initialized or destroyed
[Feb 26 13:53:52] DEBUG[25663][C-00000007]: stasis.c:579 stasis_topic_create_with_detail: Creating topic. name: channel:1708955632.165, detail:
[Feb 26 13:53:52] DEBUG[25663][C-00000007]: stasis.c:613 stasis_topic_create_with_detail: Topic 'channel:1708955632.165': 0x55fcb5c8c940 created
[Feb 26 13:53:52] DEBUG[25663][C-00000007]: channel.c:945 __ast_channel_alloc_ap: Channel 0x55fcb5c99030 'PJSIP/receptivo101-00000063' allocated
[Feb 26 13:53:52] DEBUG[25663][C-00000007]: chan_pjsip.c:530 compatible_formats_exist:  Topology:  <0:audio-0:audio:sendrecv (alaw|ulaw)> Formats: (alaw|ulaw)
[Feb 26 13:53:52] DEBUG[25663][C-00000007]: chan_pjsip.c:543 compatible_formats_exist:  Compatible? yes
[Feb 26 13:53:52] DEBUG[25663][C-00000007]: channel_internal_api.c:680 ast_channel_nativeformats_set:  PJSIP/receptivo101-00000063: MultistreamFormats: (alaw|ulaw)
[Feb 26 13:53:52] DEBUG[25663][C-00000007]: channel_internal_api.c:702 ast_channel_nativeformats_set:  Set native formats but not topology
[Feb 26 13:53:52] DEBUG[25663][C-00000007]: channel_internal_api.c:1595 ast_channel_set_stream_topology:  PJSIP/receptivo101-00000063:  <0:audio-0:audio:sendrecv (alaw|ulaw)>
[Feb 26 13:53:52] DEBUG[25663][C-00000007]: channel_internal_api.c:1616 ast_channel_set_stream_topology:  Used provided topology
[Feb 26 13:53:52] DEBUG[25663][C-00000007]: chan_pjsip.c:675 chan_pjsip_new:
[Feb 26 13:53:52] DEBUG[25663][C-00000007]: chan_pjsip.c:2692 chan_pjsip_request_with_stream_topology:  Channel: PJSIP/receptivo101-00000063
[Feb 26 13:53:52] DEBUG[25663][C-00000007]: channel.c:5740 set_format: Channel PJSIP/receptivo101-00000063 setting read format path: alaw -> alaw
[Feb 26 13:53:52] DEBUG[25663][C-00000007]: channel.c:5740 set_format: Channel PJSIP/400-00000048 setting write format path: alaw -> alaw
[Feb 26 13:53:52] DEBUG[25663][C-00000007]: channel.c:5740 set_format: Channel PJSIP/400-00000048 setting read format path: alaw -> alaw
[Feb 26 13:53:52] DEBUG[25663][C-00000007]: channel.c:5740 set_format: Channel PJSIP/receptivo101-00000063 setting write format path: alaw -> alaw
[Feb 26 13:53:52] DEBUG[25663][C-00000007]: channel.c:6798 ast_channel_inherit_variables: Inheriting variable __ARCH_RECORD_ID from PJSIP/400-00000048 to PJSIP/receptivo101-00000063.
    -- Not accepting call completion offers from call-forward recipient PJSIP/receptivo101-00000063
[Feb 26 13:53:52] DEBUG[25663][C-00000007]: chan_pjsip.c:1632 chan_pjsip_indicate:  PJSIP/400-00000048: Indicated Redirect
[Feb 26 13:53:52] DEBUG[25663][C-00000007]: chan_pjsip.c:1843 chan_pjsip_indicate:  PJSIP/400-00000048
[Feb 26 13:53:52] DEBUG[25663][C-00000007]: chan_pjsip.c:2389 chan_pjsip_call:  PJSIP/receptivo101-00000063 Topology:  <0:audio-0:audio:sendrecv (alaw|ulaw)>
[Feb 26 13:53:52] DEBUG[25663][C-00000007]: chan_pjsip.c:2399 chan_pjsip_call:  'call' task pushed
[Feb 26 13:53:52] DEBUG[25663][C-00000007]: channel.c:2545 ast_hangup: Channel 0x55fcb5abb3c0 'PJSIP/receptivo101-00000062' hanging up.  Refs: 2
[Feb 26 13:53:52] DEBUG[25663][C-00000007]: chan_pjsip.c:2522 chan_pjsip_hangup:  PJSIP/receptivo101-00000062
[Feb 26 13:53:52] DEBUG[25663][C-00000007]: chan_pjsip.c:2442 hangup_cause2sip: AST hangup cause 0 (no match found in PJSIP)
[Feb 26 13:53:52] DEBUG[25663][C-00000007]: chan_pjsip.c:2540 chan_pjsip_hangup:  Cause: 0
[Feb 26 13:53:52] DEBUG[25663][C-00000007]: chan_pjsip.c:1632 chan_pjsip_indicate:  PJSIP/400-00000048: Indicated Stop generators
[Feb 26 13:53:52] DEBUG[25663][C-00000007]: chan_pjsip.c:1843 chan_pjsip_indicate:  PJSIP/400-00000048
[Feb 26 13:53:52] DEBUG[25576]: cdr.c:1460 cdr_object_finalize: Finalized CDR for PJSIP/400-00000048 - start 1708955632.962378 answer 0.000000 end 1708955632.981253 dur 0.018 bill 1708955632.981 dispo NO ANSWER
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4536 handle_outgoing_response:  PJSIP/400-00000048: Method is INVITE, Response is 181 Call Is Being Forwarded
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4555 handle_outgoing_response:  PJSIP/400-00000048
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip/pjsip_message_filter.c:296 filter_on_tx_message: Re-wrote Contact URI host/port to 192.168.100.35:5060 (this may be re-written again later)
<--- Transmitting SIP response (901 bytes) to UDP:192.168.100.54:49229 --->
SIP/2.0 181 Call Is Being Forwarded
Via: SIP/2.0/UDP 192.168.100.54:49229;rport=49229;received=192.168.100.54;branch=z9hG4bKPj475560e42f444f0bb53d02fcf498a333
Call-ID: 866bc2fd64f34df8976af3e0b5595a31
From: "400" <sip:400@400>;tag=af84fb82d5bf40228037f9132e29dffd
To: <sip:222@400>;tag=493454b1-0bbd-4815-9079-a8047e36a2f8
CSeq: 14125 INVITE
Server: CommSL-MGCOMM
Allow: OPTIONS, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, REFER
Supported: histinfo
Contact: <sip:192.168.100.35:5060>
Diversion: <sip:222@400>;reason=unknown
Content-Type: application/sdp
Content-Length:   265

[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4652 session_inv_on_state_changed:  PJSIP/400-00000048 Event: TSX_STATE  Inv State: EARLY
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4390 __print_debug_details: Function session_inv_on_state_changed called on event TSX_STATE
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4404 __print_debug_details: The state change pertains to the endpoint '400(PJSIP/400-00000048)'
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4409 __print_debug_details: The inv session still has an invite_tsx (0x7fb6a8013468)
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4424 __print_debug_details: There is no transaction involved in this state change
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4426 __print_debug_details: The current inv state is EARLY
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4678 session_inv_on_state_changed: PJSIP/400-00000048: Source of transaction state change is TX_MSG
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4726 session_inv_on_state_changed:
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4770 session_inv_on_tsx_state_changed:  PJSIP/400-00000048 TSX State: Proceeding  Inv State: EARLY
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4390 __print_debug_details: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4404 __print_debug_details: The state change pertains to the endpoint '400(PJSIP/400-00000048)'
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4409 __print_debug_details: The inv session still has an invite_tsx (0x7fb6a8013468)
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4415 __print_debug_details: The UAS INVITE transaction involved in this state change is 0x7fb6a8013468
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4419 __print_debug_details: The current transaction state is Proceeding
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4421 __print_debug_details: The transaction state change event is TX_MSG
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4426 __print_debug_details: The current inv state is EARLY
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4958 session_inv_on_tsx_state_changed:  Nothing delayed
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4202 session_on_tsx_state:  PJSIP/400-00000048 TSX State: Proceeding  Inv State: EARLY
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4206 session_on_tsx_state:  Topology: Pending: (null topology)  Active:  <0:audio-0:audio:sendrecv (alaw)>
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4211 session_on_tsx_state:
[Feb 26 13:53:52] DEBUG[25637]: chan_pjsip.c:2365 call:  PJSIP/receptivo101-00000063 Topology:  <0:audio-0:audio:sendrecv (alaw|ulaw)>
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:2851 ast_sip_session_create_invite:  PJSIP/receptivo101-00000063
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:5122 create_local_sdp:  PJSIP/receptivo101-00000063
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:5159 create_local_sdp:  PJSIP/receptivo101-00000063: Processing streams
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:5165 create_local_sdp:  PJSIP/receptivo101-00000063: Processing stream 0:audio-0:audio:sendrecv (alaw|ulaw)
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:495 ast_sip_session_media_state_add:  PJSIP/receptivo101-00000063 Adding position 0
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:541 ast_sip_session_media_state_add:  Creating new media session
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:584 ast_sip_session_media_state_add:  Setting media session as default for audio
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:589 ast_sip_session_media_state_add:  Done
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4998 add_sdp_streams:  PJSIP/receptivo101-00000063 Stream: 0:audio-0:audio:sendrecv (alaw|ulaw)
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_sdp_rtp.c:1742 create_outgoing_sdp_stream:  PJSIP/receptivo101-00000063 Type: audio 0:audio-0:audio:sendrecv (alaw|ulaw)
[Feb 26 13:53:52] DEBUG[25637]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '0.0.0.0' into...
[Feb 26 13:53:52] DEBUG[25637]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '0.0.0.0' and port ''.
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_sdp_rtp.c:260 create_rtp: Transport transport-udp bound to 0.0.0.0: Using it for RTP media.
[Feb 26 13:53:52] DEBUG[25637]: rtp_engine.c:526 ast_rtp_instance_new: Using engine 'asterisk' for RTP instance '0x7fb6ac029f90'
[Feb 26 13:53:52] DEBUG[25637]: res_rtp_asterisk.c:3872 rtp_allocate_transport: (0x7fb6ac029f90) RTP allocated port 16098
[Feb 26 13:53:52] DEBUG[25637]: res_rtp_asterisk.c:3902 rtp_allocate_transport: (0x7fb6ac029f90) ICE creating session 0.0.0.0:16098 (16098)
[Feb 26 13:53:52] DEBUG[25637]: res_rtp_asterisk.c:3784 ice_create: (0x7fb6ac029f90) ICE create
[Feb 26 13:53:52] DEBUG[25637]: res_rtp_asterisk.c:3561 rtp_add_candidates_to_ice: (0x7fb6ac029f90) ICE add system candidates
[Feb 26 13:53:52] DEBUG[25637]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting '192.168.100.35' into...
[Feb 26 13:53:52] DEBUG[25637]: netsock2.c:224 ast_sockaddr_split_hostport: ...host '192.168.100.35' and port ''.
[Feb 26 13:53:52] DEBUG[25637]: res_rtp_asterisk.c:1329 ast_rtp_ice_add_cand: (0x7fb6ac029f90) ICE add candidate: 192.168.100.35:16098, 2130706431
[Feb 26 13:53:52] DEBUG[25637]: rtp_engine.c:543 ast_rtp_instance_new: RTP instance '0x7fb6ac029f90' is setup and ready to go
[Feb 26 13:53:52] DEBUG[25637]: res_rtp_asterisk.c:918 ast_rtp_ice_stop: (0x7fb6ac029f90) ICE stopped
[Feb 26 13:53:52] DEBUG[25637]: netsock2.c:170 ast_sockaddr_split_hostport: Splitting 'mgcomm90' into...
[Feb 26 13:53:52] DEBUG[25637]: netsock2.c:224 ast_sockaddr_split_hostport: ...host 'mgcomm90' and port ''.
[Feb 26 13:53:52] DEBUG[25637]: res_rtp_asterisk.c:8438 ast_rtp_prop_set: (0x7fb6ac029f90) RTCP setup on RTP instance
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_sdp_rtp.c:2027 create_outgoing_sdp_stream:  RC: 1
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:5028 add_sdp_streams:  Handled
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:5197 create_local_sdp:  PJSIP/receptivo101-00000063: Stream 0:audio-0:audio:sendrecv (alaw|ulaw) added
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:5210 create_local_sdp:  PJSIP/receptivo101-00000063: Done with 0:audio-0:audio:sendrecv (alaw|ulaw)
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:5216 create_local_sdp:  PJSIP/receptivo101-00000063: Adding bundle groups (if available)
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:5222 create_local_sdp:  PJSIP/receptivo101-00000063: Copying connection details
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:5225 create_local_sdp:  PJSIP/receptivo101-00000063: Processing media 0
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:5243 create_local_sdp:  PJSIP/receptivo101-00000063: Media 0 reset
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:5266 create_local_sdp:  PJSIP/receptivo101-00000063
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:2876 ast_sip_session_create_invite:
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4518 handle_outgoing_request:  PJSIP/receptivo101-00000063: Method is INVITE
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_geolocation.c:484 handle_outgoing_request:  PJSIP/receptivo101-00000063
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_geolocation.c:497 handle_outgoing_request:  PJSIP/receptivo101-00000063: Endpoint has no geoloc_outgoing_call_profile. Skipping.
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip_session.c:4528 handle_outgoing_request:  PJSIP/receptivo101-00000063
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip/pjsip_resolver.c:479 sip_resolve: Performing SIP DNS resolution of target '172.31.101.101'
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip/pjsip_resolver.c:506 sip_resolve: Transport type for target '172.31.101.101' is 'UDP transport'
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip/pjsip_resolver.c:527 sip_resolve: Target '172.31.101.101' is an IP address, skipping resolution
[Feb 26 13:53:52] DEBUG[25637]: res_pjsip/pjsip_message_filter.c:296 filter_on_tx_message: Re-wrote Contact URI host/port to 192.168.100.35:5060 (this may be re-written again later)
<--- Transmitting SIP request (983 bytes) to UDP:172.31.101.101:5061 --->
INVITE sip:222@172.31.101.101:5061 SIP/2.0
Via: SIP/2.0/UDP 192.168.100.35:5060;rport;branch=z9hG4bKPjdfbc6b65-c2a4-4e48-a38e-a052de0afeed
From: "400" <sip:400@192.168.100.35>;tag=66ff8f5a-8bd1-473a-9500-5ec7cc61a737
To: <sip:222@172.31.101.101>
Contact: <sip:asterisk@192.168.100.35:5060>
Call-ID: fd47ebde-2c46-4481-8d26-73cec76d1f58
CSeq: 8588 INVITE
Allow: OPTIONS, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, REFER
Supported: 100rel, timer, replaces, norefersub, histinfo
Session-Expires: 1800
Min-SE: 90
Diversion: <sip:222@192.168.100.35>;reason=unknown
Max-Forwards: 70
Content-Type: application/sdp
Content-Length:   265

Here partial log with ‘uri_pjsip’

[Feb 26 14:11:59] DEBUG[25826]: res_pjsip/pjsip_resolver.c:479 sip_resolve: Performing SIP DNS resolution of target '172.31.101.101'
[Feb 26 14:11:59] DEBUG[25826]: res_pjsip/pjsip_resolver.c:506 sip_resolve: Transport type for target '172.31.101.101' is 'UDP transport'
[Feb 26 14:11:59] DEBUG[25826]: res_pjsip/pjsip_resolver.c:527 sip_resolve: Target '172.31.101.101' is an IP address, skipping resolution
[Feb 26 14:11:59] DEBUG[25826]: res_pjsip/pjsip_message_filter.c:296 filter_on_tx_message: Re-wrote Contact URI host/port to 192.168.100.35:5060 (this may be re-written again later)
<--- Transmitting SIP request (930 bytes) to UDP:172.31.101.101:5061 --->
INVITE sip:222@172.31.101.101:5061 SIP/2.0
Via: SIP/2.0/UDP 192.168.100.35:5060;rport;branch=z9hG4bKPj11382093-2d6f-4996-b576-07e6d6ecfc5d
From: "400" <sip:400@192.168.100.35>;tag=e7b3dc8b-ade8-4ac6-8119-85cb585005fa
To: <sip:222@172.31.101.101>
Contact: <sip:asterisk@192.168.100.35:5060>
Call-ID: 9faca7f4-1ab9-48c6-809e-133d9c1c5b79
CSeq: 20219 INVITE
Allow: OPTIONS, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, REFER
Supported: 100rel, timer, replaces, norefersub, histinfo
Session-Expires: 1800
Min-SE: 90
Max-Forwards: 70
Content-Type: application/sdp
Content-Length:   263


[Feb 26 14:11:59] DEBUG[25826]: res_pjsip_session.c:4652 session_inv_on_state_changed:  PJSIP/receptivo101-00000001 Event: TSX_STATE  Inv State: CALLING
[Feb 26 14:11:59] DEBUG[25826]: res_pjsip_session.c:4390 __print_debug_details: Function session_inv_on_state_changed called on event TSX_STATE
[Feb 26 14:11:59] DEBUG[25826]: res_pjsip_session.c:4404 __print_debug_details: The state change pertains to the endpoint 'receptivo101(PJSIP/receptivo101-00000001)'
[Feb 26 14:11:59] DEBUG[25826]: res_pjsip_session.c:4409 __print_debug_details: The inv session still has an invite_tsx (0x563cb4eda368)
[Feb 26 14:11:59] DEBUG[25826]: res_pjsip_session.c:4424 __print_debug_details: There is no transaction involved in this state change
[Feb 26 14:11:59] DEBUG[25826]: res_pjsip_session.c:4426 __print_debug_details: The current inv state is CALLING
[Feb 26 14:11:59] DEBUG[25826]: res_pjsip_session.c:4678 session_inv_on_state_changed: PJSIP/receptivo101-00000001: Source of transaction state change is TX_MSG
[Feb 26 14:11:59] DEBUG[25826]: res_pjsip_session.c:4726 session_inv_on_state_changed:
[Feb 26 14:11:59] DEBUG[25826]: res_pjsip_session.c:4770 session_inv_on_tsx_state_changed:  PJSIP/receptivo101-00000001 TSX State: Calling  Inv State: CALLING
[Feb 26 14:11:59] DEBUG[25826]: res_pjsip_session.c:4390 __print_debug_details: Function session_inv_on_tsx_state_changed called on event TSX_STATE
[Feb 26 14:11:59] DEBUG[25826]: res_pjsip_session.c:4404 __print_debug_details: The state change pertains to the endpoint 'receptivo101(PJSIP/receptivo101-00000001)'
[Feb 26 14:11:59] DEBUG[25826]: res_pjsip_session.c:4409 __print_debug_details: The inv session still has an invite_tsx (0x563cb4eda368)
[Feb 26 14:11:59] DEBUG[25826]: res_pjsip_session.c:4415 __print_debug_details: The UAC INVITE transaction involved in this state change is 0x563cb4eda368
[Feb 26 14:11:59] DEBUG[25826]: res_pjsip_session.c:4419 __print_debug_details: The current transaction state is Calling
[Feb 26 14:11:59] DEBUG[25826]: res_pjsip_session.c:4421 __print_debug_details: The transaction state change event is TX_MSG
[Feb 26 14:11:59] DEBUG[25826]: res_pjsip_session.c:4426 __print_debug_details: The current inv state is CALLING
[Feb 26 14:11:59] DEBUG[25826]: res_pjsip_session.c:4958 session_inv_on_tsx_state_changed:  Nothing delayed
[Feb 26 14:11:59] DEBUG[25826]: res_pjsip_session.c:4202 session_on_tsx_state:  PJSIP/receptivo101-00000001 TSX State: Calling  Inv State: CALLING
[Feb 26 14:11:59] DEBUG[25826]: res_pjsip_session.c:4206 session_on_tsx_state:  Topology: Pending:  <0:audio-0:audio:sendrecv (alaw|ulaw)>  Active: (null topology)
[Feb 26 14:11:59] DEBUG[25826]: res_pjsip_session.c:4211 session_on_tsx_state:
[Feb 26 14:11:59] DEBUG[25826]: chan_pjsip.c:2382 call:  RC: 0
       > 0x563cb5487820 -- Strict RTP switching to RTP target address 192.168.100.54:4026 as source
<--- Received SIP response (364 bytes) from UDP:172.31.101.101:5061 --->
SIP/2.0 302 Redirect URA
Via: SIP/2.0/UDP 192.168.100.35:5060;rport;branch=z9hG4bKPj11382093-2d6f-4996-b576-07e6d6ecfc5d
From: "400" <sip:400@192.168.100.35>;tag=e7b3dc8b-ade8-4ac6-8119-85cb585005fa
To: <sip:222@172.31.101.101>
Call-ID: 9faca7f4-1ab9-48c6-809e-133d9c1c5b79
CSeq: 20219 INVITE
Contact: "" <sip:222@172.31.101.101:25066>
Content-Length: 0


[Feb 26 14:11:59] DEBUG[25731]: res_pjsip/pjsip_distributor.c:502 distributor: Searching for serializer associated with dialog dlg0x563cb5486088 for Response msg 302/INVITE/cseq=20219 (rdata0x7f357c003288)
[Feb 26 14:11:59] DEBUG[25731]: res_pjsip/pjsip_distributor.c:510 distributor: Found serializer pjsip/outsess/receptivo101-00000063 associated with dialog dlg0x563cb5486088
<--- Transmitting SIP request (378 bytes) to UDP:172.31.101.101:5061 --->
ACK sip:222@172.31.101.101:5061 SIP/2.0
Via: SIP/2.0/UDP 192.168.100.35:5060;rport;branch=z9hG4bKPj11382093-2d6f-4996-b576-07e6d6ecfc5d
From: "400" <sip:400@192.168.100.35>;tag=e7b3dc8b-ade8-4ac6-8119-85cb585005fa
To: <sip:222@172.31.101.101>
Call-ID: 9faca7f4-1ab9-48c6-809e-133d9c1c5b79
CSeq: 20219 ACK
Max-Forwards: 70
Content-Length:  0


[Feb 26 14:11:59] DEBUG[25826]: res_pjsip_session.c:4225 session_on_rx_response:  PJSIP/receptivo101-00000001 Method: INVITE Status: 302
[Feb 26 14:11:59] DEBUG[25826]: res_pjsip_session.c:4228 session_on_rx_response:
[Feb 26 14:11:59] DEBUG[25826]: res_pjsip/pjsip_resolver.c:479 sip_resolve: Performing SIP DNS resolution of target '172.31.101.101'
[Feb 26 14:11:59] DEBUG[25826]: res_pjsip/pjsip_resolver.c:506 sip_resolve: Transport type for target '172.31.101.101' is 'UDP transport'
[Feb 26 14:11:59] DEBUG[25826]: res_pjsip/pjsip_resolver.c:527 sip_resolve: Target '172.31.101.101' is an IP address, skipping resolution
[Feb 26 14:11:59] DEBUG[25826]: res_pjsip/pjsip_message_filter.c:296 filter_on_tx_message: Re-wrote Contact URI host/port to 192.168.100.35:5060 (this may be re-written again later)
<--- Transmitting SIP request (930 bytes) to UDP:172.31.101.101:5061 --->
INVITE sip:222@172.31.101.101:5061 SIP/2.0
Via: SIP/2.0/UDP 192.168.100.35:5060;rport;branch=z9hG4bKPjc9bfc4b6-8c1e-4178-8191-f7c614679234
From: "400" <sip:400@192.168.100.35>;tag=e7b3dc8b-ade8-4ac6-8119-85cb585005fa
To: <sip:222@172.31.101.101>
Contact: <sip:asterisk@192.168.100.35:5060>
Call-ID: 9faca7f4-1ab9-48c6-809e-133d9c1c5b79
CSeq: 20220 INVITE
Allow: OPTIONS, REGISTER, SUBSCRIBE, NOTIFY, PUBLISH, INVITE, ACK, BYE, CANCEL, UPDATE, PRACK, MESSAGE, REFER
Supported: 100rel, timer, replaces, norefersub, histinfo
Session-Expires: 1800
Min-SE: 90
Max-Forwards: 70
Content-Type: application/sdp
Content-Length:   263

Disable rewrite_contact next.

It worked!

This topic was automatically closed 30 days after the last reply. New replies are no longer allowed.