[SR-Users] Error with ACK from SIPML5

Daniel-Constantin Mierla miconda at gmail.com
Mon Jun 24 17:11:39 CEST 2013


Can you get the ngrep on the server from the initial invite to the ack. 
It seems that record route is not properly mirrored, so one client might 
mess the route path.

Cheers,
Daniel

On 6/23/13 8:58 PM, Jayesh Nambiar wrote:
> I did do debug=3 and saw the logs but couldn't figure out much. Here 
> is the log for the appropriate ACK received on websocket port. I've 
> highlighted a few lines I felt might be problematic:
>
> DEBUG: <core> [tcp_read.c:1146]: ws_process_msg(): WebSocket Message: 
> [[>>>#012<81>þ#003ø´¶#037<8a>õõTªÇßo°<85><84>,¾ô<84>.¼<9a><87>-²<9a><8e>)¤<8c><8c>*º<82><83>$þÆ×qùÄÙmþ<89>Ã{ú<94>åVÚ<9b><84>1º¹¼IãÕ<8c>?Ùýæ0¸<9a><86>0Ýç<96>{ì<83>Ü~æ<86><85>sù<84>Ò1ãÚÀ~æÝÒ$èÆ×qéÜ<8b>e³Üñ+èÿ<8e>pÛÓÔjçñ<86>iÛ<8c>äKûöä+¾ú<8d>múÛÄk<87>¾ðmåÙ<8c>?¨þ×fïÇÞ=¶Çßo°<85><86>/º<84><86>.º<84><87>_íÜ×qþÝ<98>|åÙ<88>$þÕÑ"½ñýVÙÿ÷~áòñ-Ûî<84>UüÄòP<87>¾âp°<94><8a>lãÄ<8c>.¸<87><82>_íÜ×qþÝ<98>|åÙ<88>$þÕÑ"²ö<85>Y²<8c><81>T½ÿÀzç¹¼\åÚÂ~éÀ<8c>?¨þ×fïÇÞ=¶Çßo°<85><86>/º<84><86>.º<84><87>_îÒ<81>uëØ<84>,æÇ<86>{¤ÝØiëØß{±ÆÂ|ýÑÔ2èÆÓ~áÑÄ"äÛ<8d>|æÝÕt¸××sæ<89>Øp±ÀÄ~äÇÆpøÀ<8b>hù<8a><8d>4í<9a>Ùrë<9a>Åvú<99>ßr±<9f>Åvú<9a>ß|ï<8f>Ú~äÓÃ~íÑ<8b>=ïÚ<9a>yø<96>»#025ÉÕÚs§ýò%ª<86><87>/¾ÕÔ'³<99><80>&¿<82><9b>&è×<87>2¿<86><8e>z§Ö<87>'èÐ<8f>{¾Ö<85>.é¹¼\ÙÑÇ%ª<80><8e>'ªõõT<87>¾õpäÀÓqþ<99>úzäÓÂw°<94><86>#022<80>æÙjþÑ<8c>?¶Çßo°<86><87>)¤<85><84>'¤<8c><80>1³<8e><83>/¼<84><8d>sø<8f>ÅvúÙÚ*§ÛÃkèÛÃqî<8f>ÂmëÚÅoåÆÂ"ÿÐÆ!<87>¾û~ò<99>ðpøÃ×mîÇ<8c>?½<84>»#025ÚÆÙgó<99>÷jþÜÙmãÎ×kãÛØ%ªðßxïÇÂ?ÿÇÓmäÕÛz·<96><87>/º<84><86>/»<84><86>.¨<98>ÄzëØÛ"¨ÓÞ~äÀß1éÛÛ=¦ÚÙqéÑ<8b>=ß×Ò[ìØþWÛØúYÿÝþHÇ<84>ØI¡í<86>'þàÐFÆÓÁV¨<98>Ãmã<89><94>lãÄ<8c>.¸<87><82>_¸<85><80>1»<86><8e>1²<82><98>'°<81><86>)¿<8f>ÂmëÚÅoåÆÂ"ÿÐÆ=¦ÆÓlúÛØlï<89><94>{é<82><85>~º<83><81>y¾<82><8e>{¿<81>×&¾<8d>Ð)ëÕ<87>*ì<80>×z¼<82><83>=¦ÕÚxåÆßkâÙ<8b>RÎ<81>»#025ØÛÃkï<8e><96>#ùÝÆ%¸<85><80>1»<86><8e>1²<82><98>&°<8c><86>'º<8f>ÂmëÚÅoåÆÂ"ýÇ<8d>m¸<89>Ùq±ØÄ"åÚ<8d>yþÕÑ"½ñýVÙÿ÷~áòñ-Ûî<84>UüÄòP±Ú×k·ÍÓl´¹¼MåÁÂz°<94><8a>lãÄ<8c>-»<82><98>.¸<8c><98>'¼<9a><8f>$ø<86><8b>pä<8f>Úm·ÛØ$ìÀ×x·<83>óTÃçý^ëßðX¸åì-ÀÂÆ[Å<8f>Ø~þ<89>Ïzù<8a>»#025ßÇÓm§õÑzäÀ<8c>?Ãù<9b>|æÝÓqþ<9b>ùRË<85><98>/ªÇßoÇø<83>2ü<85><98>-º<85><85>1º<87><98>-½¹¼PøÓ×qãÎ×kãÛØ%ªðÙjèÕØxå<94>âzæÑÕpç¹¼#022<80><<<]]
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> websocket [ws_frame.c:346]: decode_and_validate_ws_frame(): decoding 
> WebSocket frame
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> websocket [ws_frame.c:396]: decode_and_validate_ws_frame(): supported 
> non-control frame: 0x1
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> websocket [ws_frame.c:500]: decode_and_validate_ws_frame(): Rx 
> (decoded): ACK sip:1234 at 126.128.68.8:5065;transport=udp 
> SIP/2.0#015#012Via: SIP/2.0/WS 
> df7jal23ls0d.invalid;branch=z9hG4bK8oQgbumE0vQ8RTqBR44N;rport#015#012From: 
> "Jayesh"<sip:1000001001 at ghanti.com 
> <mailto:sip%3A1000001001 at ghanti.com>>;tag=7EKISKAakFG2QZ2JvpDO#015#012To: 
> <sip:1234 at ghanti.com 
> <mailto:sip%3A1234 at ghanti.com>>;tag=8B3F887K7Kvem#015#012Contact: 
> "Jayesh"<sip:1000001001 at df7jal23ls0d.invalid;rtcweb-breaker=no;click2call=no;transport=ws>;+g.oma.sip-im;+sip.ice;language="en,fr"#015#012Call-ID: 
> 2104ab89-6956-9bc1-528e-b18bd9d4b31c#015#012CSeq: 488 
> ACK#015#012Content-Length: 0#015#012Route: 
> <sip:126.128.68.9:5060;lr;sipml5-outbound;transport=udp>#015#012Max-Forwards: 
> 70#015#012Proxy-Authorization: Digest 
> username="1000001001",realm="ghanti.com 
> <http://ghanti.com>",nonce="UcdDflHHQlLFuiHWM0nV+Y08tTfYLgwI",uri="sip:1234 at 126.128.68.8:5065;transport=udp",response="dc63a077f468d55a949f6aa15f4ae665",algorithm=MD5#015#012Route: 
> <sip:126.128.68.9:8080;transport=ws;r2=on;lr=on;ftag=7EKISKAakFG2QZ2JvpDO;nat=yes>#015#012Route: 
> <sip:126.128.68.9;r2=on;lr=on;ftag=7EKISKAakFG2QZ2JvpDO;nat=yes>#015#012User-Agent: 
> IM-client/OMA1.0 sipML5-v1.2013.03.27#015#012Organization: Doubango 
> Telecom#015#012#015#012
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> websocket [ws_frame.c:580]: ws_frame_receive(): Rx SIP message:#012ACK 
> sip:1234 at 126.128.68.8:5065;transport=udp SIP/2.0#015#012Via: 
> SIP/2.0/WS 
> df7jal23ls0d.invalid;branch=z9hG4bK8oQgbumE0vQ8RTqBR44N;rport#015#012From: 
> "Jayesh"<sip:1000001001 at ghanti.com 
> <mailto:sip%3A1000001001 at ghanti.com>>;tag=7EKISKAakFG2QZ2JvpDO#015#012To: 
> <sip:1234 at ghanti.com 
> <mailto:sip%3A1234 at ghanti.com>>;tag=8B3F887K7Kvem#015#012Contact: 
> "Jayesh"<sip:1000001001 at df7jal23ls0d.invalid;rtcweb-breaker=no;click2call=no;transport=ws>;+g.oma.sip-im;+sip.ice;language="en,fr"#015#012Call-ID: 
> 2104ab89-6956-9bc1-528e-b18bd9d4b31c#015#012CSeq: 488 
> ACK#015#012Content-Length: 0#015#012Route: 
> <sip:126.128.68.9≈:5060;lr;sipml5-outbound;transport=udp>#015#012Max-Forwards: 
> 70#015#012Proxy-Authorization: Digest 
> username="1000001001",realm="ghanti.com 
> <http://ghanti.com>",nonce="UcdDflHHQlLFuiHWM0nV+Y08tTfYLgwI",uri="sip:1234 at 126.128.68.8:5065;transport=udp",response="dc63a077f468d55a949f6aa15f4ae665",algorithm=MD5#015#012Route: 
> <sip:126.128.68.9:8080;transport=ws;r2=on;lr=on;ftag=7EKISKAakFG2QZ2JvpDO;nat=yes>#015#012Route: 
> <sip:126.128.68.9;r2=on;lr=on;ftag=7EKISKAakFG2QZ2JvpDO;nat=yes>#015#012User-Agent: 
> IM-client/OMA1.0 sipML5-v1.2013.03.27#015#012Organization: Doubango 
> Telecom#015#012#015#012
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> <core> [parser/msg_parser.c:623]: parse_msg(): SIP Request:
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> <core> [parser/msg_parser.c:625]: parse_msg():  method:  <ACK>
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> <core> [parser/msg_parser.c:627]: parse_msg():  uri:   
> <sip:1234 at 126.128.68.8:5065;transport=udp>
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> <core> [parser/msg_parser.c:629]: parse_msg():  version: <SIP/2.0>
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> <core> [parser/parse_via.c:1284]: parse_via_param(): Found param type 
> 232, <branch> = <z9hG4bK8oQgbumE0vQ8RTqBR44N>; state=6
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> <core> [parser/parse_via.c:1284]: parse_via_param(): Found param type 
> 235, <rport> = <n/a>; state=17
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> <core> [parser/parse_via.c:2672]: parse_via(): end of header reached, 
> state=5
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> <core> [parser/msg_parser.c:513]: parse_headers(): parse_headers: Via 
> found, flags=2
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> <core> [parser/msg_parser.c:515]: parse_headers(): parse_headers: this 
> is the first via
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> <core> [receive.c:149]: receive_msg(): After parse_msg...
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> <core> [receive.c:190]: receive_msg(): preparing to run routing scripts...
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> sl [sl_funcs.c:396]: sl_filter_ACK(): DEBUG : sl_filter_ACK: to late 
> to be a local ACK!
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> <core> [parser/parse_to.c:176]: parse_to_param(): DEBUG: add_param: 
> tag=8B3F887K7Kvem
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> <core> [parser/parse_to.c:799]: parse_to(): end of header reached, 
> state=29
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> <core> [parser/msg_parser.c:190]: get_hdr_field(): DEBUG: 
> get_hdr_field: <To> [41]; uri=[sip:1234 at ghanti.com 
> <mailto:sip%3A1234 at ghanti.com>]
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> <core> [parser/msg_parser.c:192]: get_hdr_field(): DEBUG: to body 
> [<sip:1234 at ghanti.com <mailto:sip%3A1234 at ghanti.com>>]
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> <core> [parser/msg_parser.c:170]: get_hdr_field(): get_hdr_field: cseq 
> <CSeq>: <488> <ACK>
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> <core> [parser/msg_parser.c:204]: get_hdr_field(): DEBUG: get_hdr_body 
> : content_length=0
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> maxfwd [mf_funcs.c:85]: is_maxfwd_present(): value = 70
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> maxfwd [maxfwd.c:161]: process_maxfwd_header(): value 70 decreased to 16
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> <core> [parser/msg_parser.c:106]: get_hdr_field(): found end of header
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> <core> [parser/parse_to.c:176]: parse_to_param(): DEBUG: add_param: 
> tag=7EKISKAakFG2QZ2JvpDO
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> <core> [parser/parse_to.c:799]: parse_to(): end of header reached, 
> state=29
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> sanity [mod_sanity.c:255]: w_sanity_check(): sanity checks result: 1
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: INFO: 
> <script>: Request Method is ACK
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> nathelper [nathelper.c:888]: add_contact_alias_0_f(): contact uri host 
> is not an ip address
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> nathelper [nathelper.c:965]: add_contact_alias_0_f(): adding param 
> <;alias=203.153.53.166~59841~5>
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: INFO: 
> <script>: Inside NATDETECT
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> nathelper [nathelper.c:888]: add_contact_alias_0_f(): contact uri host 
> is not an ip address
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> nathelper [nathelper.c:965]: add_contact_alias_0_f(): adding param 
> <;alias=203.153.53.166~59841~5>
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> siputils [checks.c:106]: has_totag(): totag found
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> rr [loose.c:89]: is_preloaded(): is_preloaded: No
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> <core> [socket_info.c:589]: grep_sock_info(): grep_sock_info - 
> checking if host==us: 12==12 && [126.128.68.8] == [126.128.68.9]
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> <core> [socket_info.c:593]: grep_sock_info(): grep_sock_info - 
> checking if port 5060 (advertise 0) matches port 5065
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> <core> [socket_info.c:589]: grep_sock_info(): grep_sock_info - 
> checking if host==us: 12==12 && [126.128.68.8] == [126.128.68.9]
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> <core> [socket_info.c:593]: grep_sock_info(): grep_sock_info - 
> checking if port 5060 (advertise 0) matches port 5065
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> <core> [socket_info.c:589]: grep_sock_info(): grep_sock_info - 
> checking if host==us: 12==12 && [126.128.68.8] == [126.128.68.9]
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> <core> [socket_info.c:593]: grep_sock_info(): grep_sock_info - 
> checking if port 8080 (advertise 0) matches port 5065
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> <core> [forward.c:462]: check_self(): check_self: host != me
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> <core> [socket_info.c:589]: grep_sock_info(): grep_sock_info - 
> checking if host==us: 12==12 && [126.128.68.9] == [126.128.68.9]
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> <core> [socket_info.c:593]: grep_sock_info(): grep_sock_info - 
> checking if port 5060 (advertise 0) matches port 5060
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> rr [loose.c:778]: after_loose(): Topmost route URI: 
> 'sip:126.128.68.9:5060;lr;sipml5-outbound;transport=udp' is me
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> rr [loose.c:862]: after_loose(): URI to be processed: 
> 'sip:126.128.68.9:8080;transport=ws;r2=on;lr=on;ftag=7EKISKAakFG2QZ2JvpDO;nat=yes'
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> rr [loose.c:871]: after_loose(): Next URI is a loose router
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> siputils [checks.c:106]: has_totag(): totag found
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> rr [loose.c:974]: check_route_param(): params are 
> <;lr;sipml5-outbound;transport=udp>
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: INFO: 
> <script>: Inside Natmanage ACK
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> siputils [checks.c:106]: has_totag(): totag found
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> tm [t_lookup.c:1395]: t_newtran(): DEBUG: t_newtran: msg id=8 , global 
> msg id=7 , T on entrance=(nil)
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> tm [t_lookup.c:534]: t_lookup_request(): t_lookup_request: start 
> searching: hash=60000, isACK=1
> un 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: tm 
> [t_lookup.c:492]: matching_3261(): *DEBUG: RFC3261 transaction 
> matching failed*
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> tm [t_lookup.c:716]: t_lookup_request(): *DEBUG: t_lookup_request: no 
> transaction found*
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> tm [t_funcs.c:311]: t_relay_to(): SER: forwarding ACK  statelessly
> *Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: 
> WARNING: <core> [msg_translator.c:2499]: via_builder(): TCP/TLS 
> connection (id: 0) for WebSocket could not be found*
> *Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: ERROR: 
> <core> [msg_translator.c:1725]: build_req_buf_from_sip_req(): could 
> not create Via header*
> *Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: ERROR: 
> <core> [forward.c:607]: forward_request(): ERROR: forward_request: 
> building failed*
> *Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: ERROR: 
> sl [sl_funcs.c:371]: sl_reply_error(): ERROR: sl_reply_error used: I'm 
> terribly sorry, server error occurred (1/SL)*
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> <core> [usr_avp.c:644]: destroy_avp_list(): DEBUG:destroy_avp_list: 
> destroying list (nil)
> Jun 23 18:45:46  /usr/local/ghanti-ko/sbin/kamailio[22879]: last 
> message repeated 5 times
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> <core> [xavp.c:447]: xavp_destroy_list(): destroying xavp list (nil)
> Jun 23 18:45:46 v9 /usr/local/ghanti-ko/sbin/kamailio[22879]: DEBUG: 
> <core> [receive.c:293]: receive_msg(): receive_msg: cleaning up
>
> Thanks for all the help.
>
> --- Jayesh
>
>
> On Sun, Jun 23, 2013 at 8:53 PM, Daniel-Constantin Mierla 
> <miconda at gmail.com <mailto:miconda at gmail.com>> wrote:
>
>     Are you sure the error messages are printed for this ACK, because
>     it has to be sent via UDP, not via WS, thus the messages seem to
>     be unrelated.
>
>     Get the log messages with debug=3 in order to see what is happening.
>
>     Cheers,
>     Daniel
>
>
>     On 6/22/13 9:10 AM, Jayesh Nambiar wrote:
>>     Hi,
>>     Is it possible that this could be a problem in the script. But
>>     when I call using a normal SIP Client like X-Lite, it works well.
>>     This only happens when the request comes on websocket. Is there
>>     something to be handled specially when handling requests like ACK
>>     over websocket?
>>     The reference is more or less taken from
>>     https://gist.github.com/jesusprubio/4066845
>>
>>     --- Jayesh
>>
>>
>>     On Wed, Jun 19, 2013 at 2:14 PM, Jayesh Nambiar
>>     <jayesh1017 at gmail.com <mailto:jayesh1017 at gmail.com>> wrote:
>>
>>         Hi Daniel,
>>         I checked using the kamcmd command and I see the webrtc
>>         connection active. The ACK comes from the webrtc client but
>>         somehow kamailio is unable to create a via header and forward
>>         the request. The ACK coming from webrtc client is as follows:
>>
>>         SEND: ACK sip:1223 at 126.128.68.8:5065;transport=udp SIP/2.0
>>         Via: SIP/2.0/WS
>>         df7jal23ls0d.invalid;branch=z9hG4bKJ0Qsiuh94B2slPLp19qH;rport
>>         From: "Jayesh"<sip:1000001001 at ghanti.com
>>         <mailto:sip%3A1000001001 at ghanti.com>>;tag=vsTKsfB1ZR8EkHTPqzyc
>>         To: <sip:1223 at ghanti.com
>>         <mailto:sip%3A1223 at ghanti.com>>;tag=UQ9veX0vUg7QS
>>         Contact:
>>         "Jayesh"<sip:1000001001 at df7jal23ls0d.invalid;rtcweb-breaker=no;click2call=no;transport=ws>;+g.oma.sip-im;+sip.ice;language="en,fr"
>>         Call-ID: 2c76d5ee-e9d0-7f22-8be2-76e80b4ab48b
>>         CSeq: 42684 ACK
>>         Content-Length: 0
>>         Route: <sip:126.128.68.9:5060;lr;sipml5-outbound;transport=udp>
>>         Max-Forwards: 70
>>         Proxy-Authorization: Digest
>>         username="1000001001",realm="ghanti.com
>>         <http://ghanti.com>",nonce="UcFsQ1HBaxc84k4N1J00fpVDj9G57x5p",uri="sip:1223 at 126.128.68.8:5065;transport=udp",response="5b18a2182c335315875224f2244fb48b",algorithm=MD5
>>         Route:
>>         <sip:126.128.68.9:8080;transport=ws;r2=on;lr=on;ftag=vsTKsfB1ZR8EkHTPqzyc;nat=yes>
>>         Route:
>>         <sip:126.128.68.9;r2=on;lr=on;ftag=vsTKsfB1ZR8EkHTPqzyc;nat=yes>
>>         User-Agent: IM-client/OMA1.0 sipML5-v1.2013.03.27
>>         Organization: Doubango Telecom
>>
>>         Where 126.128.68.9:5060 <http://126.128.68.9:5060> is the
>>         Kamailio; 126.128.68.8:5065 <http://126.128.68.8:5065> is a
>>         freeswitch playing music and the request is received on
>>         126.128.68.9:8080 <http://126.128.68.9:8080> which is
>>         kamailio acting as a websocket server.
>>         Also the connection between Kamailio and Freeswitch is UDP. I
>>         am not able to use the websocket module just because of this
>>         small problem caused in signaling. Any help is really
>>         appreciated. Could it be related to the nathelper module?
>>
>>         Thanks,
>>
>>         --- Jayesh
>>
>>
>>         On Tue, Jun 18, 2013 at 2:17 PM, Daniel-Constantin Mierla
>>         <miconda at gmail.com <mailto:miconda at gmail.com>> wrote:
>>
>>             Hello,
>>
>>
>>             On 6/14/13 4:52 PM, Jayesh Nambiar wrote:
>>>             Hello All,
>>>             I have a setup which is SIPML5 -> Kamailio(Websocket) ->
>>>             Freeswitch. All I am trying to do is initiate a call
>>>             from SIPML5 and Play a Music file from freeswitch. This
>>>             works, but the call hangs up after 30 seconds due to ACK
>>>             timeout from from the SIPML5.
>>>
>>>             What I can see is the SIPML5 from Chrome does send an
>>>             ACK on websocket, but I see the following error in
>>>             kamailio syslog:
>>>
>>>             *via_builder(): TCP/TLS connection (id: 0) for WebSocket
>>>             could not be found*
>>>             *ERROR: <core> [msg_translator.c:1725]:
>>>             build_req_buf_from_sip_req(): could not create Via header*
>>>             *ERROR: <core> [forward.c:607]: forward_request():
>>>             ERROR: forward_request: building failed*
>>>             *ERROR: sl [sl_funcs.c:371]: sl_reply_error(): ERROR:
>>>             sl_reply_error used: I'm terribly sorry, server error
>>>             occurred (1/SL)*
>>>
>>>             Can someone help me understand what I am missing here
>>>             and why kamailio is not able to create a VIA header to
>>>             forward this request towards freeswitch. I have built a
>>>             simple config file by following
>>>             https://gist.github.com/jesusprubio/4066845 as reference.
>>>
>>>             Any help in understanding this is really appreciated.
>>             looks like the web rtc connection was down (closed by one
>>             side or interrupted by the network). You can check with
>>             kamcmd the list of active connections.
>>
>>             Cheers,
>>             Daniel
>>
>>             -- 
>>             Daniel-Constantin Mierla -http://www.asipto.com
>>             http://twitter.com/#!/miconda  <http://twitter.com/#%21/miconda>  -http://www.linkedin.com/in/miconda
>>             Kamailio Advanced Training, San Francisco, USA - June 24-27, 2013
>>                *http://asipto.com/u/katu  *
>>
>>
>>             _______________________________________________
>>             SIP Express Router (SER) and Kamailio (OpenSER) -
>>             sr-users mailing list
>>             sr-users at lists.sip-router.org
>>             <mailto:sr-users at lists.sip-router.org>
>>             http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users
>>
>>
>>
>
>     -- 
>     Daniel-Constantin Mierla -http://www.asipto.com
>     http://twitter.com/#!/miconda  <http://twitter.com/#%21/miconda>  -http://www.linkedin.com/in/miconda
>
>

-- 
Daniel-Constantin Mierla - http://www.asipto.com
http://twitter.com/#!/miconda - http://www.linkedin.com/in/miconda

-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.sip-router.org/pipermail/sr-users/attachments/20130624/44b13d5b/attachment-0001.html>


More information about the sr-users mailing list