[Freeswitch-users] Issue with SDP - Call hangs up right after SIP 200 OK

Thu Jul 23 06:37:41 UTC 2020

Hello,

>> "988 Incomplete offer/answer"

You put the answer :

 <mailto:&iTEq at 8GæÙ¸ª%25ÄÄ]äáSIP/2.0> &iTEq at 8GæÙ¸ª%ÄÄ]äáSIP/2.0 200 OK

Via: SIP/2.0/UDP
1.1.131.37;rport=5060;branch=z9hG4bKttaHKmXNgBtSQ;received=1.1.131.37

Call-ID: cbecb6ef-4300-1239-dfa0-000c29124868

From: "Debug Proxy" <sip:+15145552353 at 1.1.131.37>;tag=rDQF79t14QmKr

To: <sip:9919605 at dev-proxy.xxx.com>;tag=1661639224

CSeq: 22952030 INVITE

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

Supported: 100rel,precondition

Contact: <sip:Account1 at 192.168.1.193:5060>

Require: replaces

Content-Type: application/sdp

Server: Panasonic-KX-TGP600/11.110 (4c364e38b998)

Content-Length: 263

v=0

o=- 1595011648 1595011648 IN IP4 192.168.1.193

s=-

c=IN IP4 192.168.1.193

t=0 0

m=audio 16176 RTP/AVP 0 101

a=rtpmap:0 PCMU/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-15

a=sendrecv

a=ptime:20

m=video 0 RTP/AVP 34 0 16 178 64 0 16 178 0 0

And the offer ???

Thanks

De : FreeSWITCH-users [mailto:freeswitch-users-bounces at lists.freeswitch.org]
De la part de Marc Bernard
Envoyé : mercredi 22 juillet 2020 22:14
À : freeswitch-users at lists.freeswitch.org
Objet : [Freeswitch-users] Issue with SDP - Call hangs up right after SIP
200 OK

Hello People,

Ive been investigating an issue with SDP when sending a call to a Panasonic
KX-TGP600 Phone, the call end as soon as the Panasonic send its SIP OK

I could not see what is wrong with the SDP and why I get "INVITE: error
parsing SDP answer in 200 OK" resulting in "988 Incomplete offer/answer" 

I also find it weird that there is no output for this error when Sofia debug
is not enabled. It took me a while before I thought of trying to enable more
debugging.

There is also the "not found by name */1.1.217.127:1029" Part which I am not
sure is related/relevant in this.

--

Here is the console log portion that I thought was relevant:

2020-07-17 15:44:33.495660 [DEBUG] sofia.c:7325 Channel
sofia/private/+15145557030 at 2.2.127.218
<mailto:sofia/private/+15145557030 at 2.2.127.218>  entering state [early][180]

nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering

2020-07-17 15:44:33.495660 [NOTICE] switch_ivr_originate.c:556 Ring Ready
sofia/private/+15145557030 at 2.2.127.218
<mailto:sofia/private/+15145557030 at 2.2.127.218> !

tport.c:2759 tport_wakeup_pri() tport_wakeup_pri(0x7f0e440044a0): events IN

tport.c:2882 tport_recv_event() tport_recv_event(0x7f0e440044a0)

tport_type_udp.c:324 tport_recv_dgram() tport(0x7f0e440044a0): zero length
packet

tport.c:2759 tport_wakeup_pri() tport_wakeup_pri(0x7f0e440044a0): events IN

tport.c:2882 tport_recv_event() tport_recv_event(0x7f0e440044a0)

tport.c:3223 tport_recv_iovec() tport_recv_iovec(0x7f0e440044a0) msg
0x7f0e44011e30 from (udp/1.1.131.37:5060) has 845 bytes, veclen = 1

tport.c:3041 tport_deliver() tport_deliver(0x7f0e440044a0): msg
0x7f0e44011e30 (845 bytes) from udp/1.1.217.127:5060/sip next=(nil)

nta.c:3304 agent_recv_response() nta: received 200 OK for INVITE (22953743)

nta.c:3371 agent_recv_response() nta: 200 OK is going to a transaction

tport.c:4240 tport_release() tport_release(0x7f0e440044a0): 0x7f0e4400bca0
by 0x7f0e44010c40 with 0x7f0e44011e30

nua_dialog.c:564 nua_dialog_usage_set_refresh_range() nua(): refresh session
after 55 seconds (in [55..65])

soa.c:1171 soa_set_remote_sdp() soa_set_remote_sdp(static::0x7f0e4401a9b0,
(nil), 0x7f0e4401267a, 259) called

nua_session.c:970 nua_session_client_response() nua(0x7f0e48048c30): INVITE:
error parsing SDP answer in 200 OK (200)

nua_stack.c:271 nua_stack_event() nua(0x7f0e48048c30): event r_invite 200 OK

nua_stack.c:359 nua_application_event() nua: nua_application_event: entering

nta.c:2670 nta_tpn_by_url() nta: selecting scheme sip

tport.c:3275 tport_tsend() tport_tsend(0x7f0e440044a0) tpn =
*/1.1.217.127:1029

tport.c:4064 tport_resolve() tport_resolve addrinfo = 1.1.217.127:1029

tport.c:4698 tport_by_addrinfo() tport_by_addrinfo(0x7f0e440044a0): not
found by name */1.1.217.127:1029

nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering

tport.c:3612 tport_vsend() tport_vsend(0x7f0e440044a0): 391 bytes of 391 to
udp/1.1.217.127:1029

tport.c:3510 tport_send_msg() tport_vsend returned 391

nta.c:8315 outgoing_send() nta: sent ACK (22953743) to */1.1.217.127:1029

nua_stack.c:271 nua_stack_event() nua(0x7f0e48048c30): event i_media_error
988 Incomplete offer/answer

nua_params.c:482 nua_stack_set_params() nua: nua_stack_set_params: entering

soa.c:1784 soa_terminate() soa_terminate(static::0x7f0e4401a9b0) called

nua_stack.c:359 nua_application_event() nua: nua_application_event: entering

soa.c:1302 soa_init_offer_answer()
soa_init_offer_answer(static::0x7f0e4401a9b0) called

nta.c:2670 nta_tpn_by_url() nta: selecting scheme sip

tport.c:3275 tport_tsend() tport_tsend(0x7f0e440044a0) tpn =
*/1.1.217.127:1029

tport.c:4064 tport_resolve() tport_resolve addrinfo = 1.1.217.127:1029

tport.c:4698 tport_by_addrinfo() tport_by_addrinfo(0x7f0e440044a0): not
found by name */1.1.217.127:1029

tport.c:3612 tport_vsend() tport_vsend(0x7f0e440044a0): 616 bytes of 616 to
udp/1.1.217.127:1029

tport.c:3510 tport_send_msg() tport_vsend returned 616

nta.c:8315 outgoing_send() nta: sent BYE (22953744) to */1.1.217.127:1029

tport.c:4178 tport_pend() tport_pend(0x7f0e440044a0): pending 0x7f0e4400d880
for udp/1.1.131.37:5060 (already 0)

nta.c:1348 set_timeout() nta: timer shortened to 1000 ms

nua_session.c:4140 signal_call_state_change() nua(0x7f0e48048c30): call
state changed: proceeding -> terminating, received answer

soa.c:1098 soa_get_remote_sdp() soa_get_remote_sdp(static::0x7f0e4401a9b0,
[0x7f0e705a0778], [0x7f0e705a0780], [(nil)]) called

soa.c:616 soa_get_params() soa_get_params(static::0x7f0e4401a9b0, ...)
called

nua_stack.c:271 nua_stack_event() nua(0x7f0e48048c30): event i_state 200 OK

nua_stack.c:359 nua_application_event() nua: nua_application_event: entering

nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering

nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering

nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering

nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering

2020-07-17 15:44:35.795689 [DEBUG] sofia.c:7325 Channel
sofia/public/golfvalneigette3 entering state [terminating][200]

--

And here is the SIP OK from the Panasonic:

 <mailto:&iTEq at 8GæÙ¸ª%25ÄÄ]äáSIP/2.0> &iTEq at 8GæÙ¸ª%ÄÄ]äáSIP/2.0 200 OK

Via: SIP/2.0/UDP
1.1.131.37;rport=5060;branch=z9hG4bKttaHKmXNgBtSQ;received=1.1.131.37

Call-ID: cbecb6ef-4300-1239-dfa0-000c29124868

From: "Debug Proxy" <sip:+15145552353 at 1.1.131.37>;tag=rDQF79t14QmKr

To: <sip:9919605 at dev-proxy.xxx.com>;tag=1661639224

CSeq: 22952030 INVITE

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

Supported: 100rel,precondition

Contact: <sip:Account1 at 192.168.1.193:5060>

Require: replaces

Content-Type: application/sdp

Server: Panasonic-KX-TGP600/11.110 (4c364e38b998)

Content-Length: 263

v=0

o=- 1595011648 1595011648 IN IP4 192.168.1.193

s=-

c=IN IP4 192.168.1.193

t=0 0

m=audio 16176 RTP/AVP 0 101

a=rtpmap:0 PCMU/8000

a=rtpmap:101 telephone-event/8000

a=fmtp:101 0-15

a=sendrecv

a=ptime:20

m=video 0 RTP/AVP 34 0 16 178 64 0 16 178 0 0

--

I have a PCAP file for the whole call should it be needed.

Any help resolving this issue would be greatly appreciated.

Kindly,

-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20200723/a89b47e6/attachment-0001.html>