[sr-dev] Testing the Websocket module with sipml5.org

Thu Aug 16 18:43:13 CEST 2012

Progress! Now, I get an "Unsupported media type" message in the sipml5
page when I try to make a call, which is a much more polite behavior.
Maybe I just have to enable some codec in the other softphone? Here's
the log.

Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6719]:
DEBUG: <core> [io_wait.h:617]: DBG: io_watch_del (0x8253ea0, 28, -1,
0x0) fd_no=22 called
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6719]:
DEBUG: <core> [tcp_main.c:4296]: tcp: DBG: sending to child, events 1
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6719]:
DEBUG: <core> [tcp_main.c:3967]: selected tcp worker 3 13(6718) for
activity on [tcp:192.168.0.148:80], 0xb559f770
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [tcp_read.c:1503]: received n=4 con=0xb559f770, fd=9
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [tcp_read.c:1128]: WebSocket Message: (BINARY JUNK)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: websocket [ws_frame.c:343]: decoding WebSocket frame
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: websocket [ws_frame.c:393]: supported non-control frame: 0x1
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: websocket [ws_frame.c:497]: Rx (decoded): INVITE
sip:default_2002 at 192.168.0.148 SIP/2.0#015#012Via: SIP/2.0/WS
df7jal23ls0d.invalid;branch=z9hG4bKwV2n29qE21vqAMQyEWmwlX8smTg1ulcT;rport#015#012From:
<sip:default_2003 at 192.168.0.148>;tag=iWkTlWnsvaUNzW1reSya#015#012To:
<sip:default_2002 at 192.168.0.148>#015#012Contact: "Web Ext
2003"<sip:default_2003 at df7jal23ls0d.invalid;transport=ws>;+sip.ice#015#012Call-ID:
f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56775
INVITE#015#012Content-Type: application/sdp#015#012Content-Length:
2989#015#012Max-Forwards: 70#015#012User-Agent: IM-client/OMA1.0
sipML5/v0.0.0000.0#015#012Organization: Doubango
Telecom#015#012#015#012v=0#015#012o=- 3900702828 1 IN IP4
127.0.0.1#015#012s=webrtc (chrome 22.0.1189.0) - Doubango Telecom
(sipML5 r000)#015#012t=0 0#015#012a=group:BUNDLE audio
video#015#012m=audio 60827 RTP/SAVPF 103 104 0 8 106 105 13
126#015#012c=IN IP4 189.24.147.149#015#012a=rtcp:60827 IN IP4
189.24.147.149#015#012a=candidate:678360110 1 udp 2130714367
169.254.103.29 63966 typ host generation
0#015#012a=candidate:678360110 2 udp 2130714367 169.254.103.29 63966
typ host generation 0#015#012a=candidate:3473159702 1 udp 2130714367
192.168.0.92 60825 typ host generation 0#015#012a=candidate:3473159702
2 udp 2130714367 192.168.0.92 60825 typ host generation
0#015#012a=candidate:957632450 1 udp 1912610559 189.24.147.149 60827
typ srflx generation 0#015#012a=candidate:957632450 2 udp 1912610559
189.24.147.149 60827 typ srflx generation
0#015#012a=candidate:1726810334 1 tcp 1694506751 169.254.103.29 3302
typ host generation 0#015#012a=candidate:1726810334 2 tcp 1694506751
169.254.103.29 3302 typ host generation
0#015#012a=candidate:2173160166 1 tcp 1694506751 192.168.0.92 3303 typ
host generation 0#015#012a=candidate:2173160166 2 tcp 1694506751
192.168.0.92 3303 typ host generation
0#015#012a=ice-ufrag:zUOb+h3ouRkd/QYd#015#012a=ice-pwd:kyZNFOSaqMo0cv4hP6+gsbeO#015#012a=sendrecv#015#012a=mid:audio#015#012a=rtcp-
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: websocket [ws_frame.c:575]: Rx SIP message:#012INVITE
sip:default_2002 at 192.168.0.148 SIP/2.0#015#012Via: SIP/2.0/WS
df7jal23ls0d.invalid;branch=z9hG4bKwV2n29qE21vqAMQyEWmwlX8smTg1ulcT;rport#015#012From:
<sip:default_2003 at 192.168.0.148>;tag=iWkTlWnsvaUNzW1reSya#015#012To:
<sip:default_2002 at 192.168.0.148>#015#012Contact: "Web Ext
2003"<sip:default_2003 at df7jal23ls0d.invalid;transport=ws>;+sip.ice#015#012Call-ID:
f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56775
INVITE#015#012Content-Type: application/sdp#015#012Content-Length:
2989#015#012Max-Forwards: 70#015#012User-Agent: IM-client/OMA1.0
sipML5/v0.0.0000.0#015#012Organization: Doubango
Telecom#015#012#015#012v=0#015#012o=- 3900702828 1 IN IP4
127.0.0.1#015#012s=webrtc (chrome 22.0.1189.0) - Doubango Telecom
(sipML5 r000)#015#012t=0 0#015#012a=group:BUNDLE audio
video#015#012m=audio 60827 RTP/SAVPF 103 104 0 8 106 105 13
126#015#012c=IN IP4 189.24.147.149#015#012a=rtcp:60827 IN IP4
189.24.147.149#015#012a=candidate:678360110 1 udp 2130714367
169.254.103.29 63966 typ host generation
0#015#012a=candidate:678360110 2 udp 2130714367 169.254.103.29 63966
typ host generation 0#015#012a=candidate:3473159702 1 udp 2130714367
192.168.0.92 60825 typ host generation 0#015#012a=candidate:3473159702
2 udp 2130714367 192.168.0.92 60825 typ host generation
0#015#012a=candidate:957632450 1 udp 1912610559 189.24.147.149 60827
typ srflx generation 0#015#012a=candidate:957632450 2 udp 1912610559
189.24.147.149 60827 typ srflx generation
0#015#012a=candidate:1726810334 1 tcp 1694506751 169.254.103.29 3302
typ host generation 0#015#012a=candidate:1726810334 2 tcp 1694506751
169.254.103.29 3302 typ host generation
0#015#012a=candidate:2173160166 1 tcp 1694506751 192.168.0.92 3303 typ
host generation 0#015#012a=candidate:2173160166 2 tcp 1694506751
192.168.0.92 3303 typ host generation
0#015#012a=ice-ufrag:zUOb+h3ouRkd/QYd#015#012a=ice-pwd:kyZNFOSaqMo0cv4hP6+gsbeO#015#012a=sendrecv#015#012a=mid:audio#015#012a=
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/msg_parser.c:623]: SIP Request:
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/msg_parser.c:625]:  method:  <INVITE>
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/msg_parser.c:627]:  uri:
<sip:default_2002 at 192.168.0.148>
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/msg_parser.c:629]:  version: <SIP/2.0>
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/parse_via.c:1284]: Found param type 232,
<branch> = <z9hG4bKwV2n29qE21vqAMQyEWmwlX8smTg1ulcT>; state=6
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/parse_via.c:1284]: Found param type 235, <rport>
= <n/a>; state=17
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/parse_via.c:2672]: end of header reached,
state=5
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: Via found,
flags=2
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/msg_parser.c:515]: parse_headers: this is the
first via
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [receive.c:149]: After parse_msg...
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [receive.c:190]: preparing to run routing scripts...
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/parse_to.c:799]: end of header reached, state=10
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/msg_parser.c:190]: DEBUG: get_hdr_field: <To>
[34]; uri=[sip:default_2002 at 192.168.0.148]
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/msg_parser.c:192]: DEBUG: to body
[<sip:default_2002 at 192.168.0.148>#015#012]
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/msg_parser.c:170]: get_hdr_field: cseq <CSeq>:
<56775> <INVITE>
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/msg_parser.c:204]: DEBUG: get_hdr_body :
content_length=2989
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: maxfwd [mf_funcs.c:85]: value = 70
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/msg_parser.c:106]: found end of header
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/parse_to.c:176]: DEBUG: add_param:
tag=iWkTlWnsvaUNzW1reSya
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/parse_to.c:799]: end of header reached, state=29
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: sanity [mod_sanity.c:255]: sanity checks result: 1
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: nathelper [nathelper.c:831]: contact uri host is not an ip
address
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: nathelper [nathelper.c:904]: adding param
<;alias=192.168.0.92~3290~5>
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: siputils [checks.c:103]: no totag
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: tm [t_lookup.c:1079]: DEBUG: t_check_msg: msg id=1 global id=0
T start=0xffffffff
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: tm [t_lookup.c:527]: t_lookup_request: start searching:
hash=34648, isACK=0
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: tm [t_lookup.c:485]: DEBUG: RFC3261 transaction matching failed
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: tm [t_lookup.c:709]: DEBUG: t_lookup_request: no transaction
found
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: tm [t_lookup.c:1148]: DEBUG: t_check_msg: msg id=1 global id=1
T end=(nil)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [socket_info.c:589]: grep_sock_info - checking if
host==us: 13==13 && [192.168.0.148] == [192.168.0.148]
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [socket_info.c:593]: grep_sock_info - checking if port
5060 (advertise 0) matches port 5060
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: auth_db [authorize.c:363]: realm [192.168.0.148] table
[subscriber] flags [1]
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: auth [api.c:86]: auth:pre_auth: Credentials with realm
'192.168.0.148' not found
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: auth_db [authorize.c:180]: no credentials
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: auth [challenge.c:128]: build_challenge_hf:
realm='192.168.0.148'
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: auth [challenge.c:270]: auth: 'Proxy-Authenticate: Digest
realm="192.168.0.148",
nonce="UC0io1AtIXfj2LIHZ0fGntzp0rLMgji1"#015#012'
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: sl [sl.c:278]: reply in stateless mode (sl)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [msg_translator.c:206]: check_via_address(192.168.0.92,
df7jal23ls0d.invalid, 0)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: websocket [ws_frame.c:612]: Tx SIP message:#012SIP/2.0 407
Proxy Authentication Required#015#012Via: SIP/2.0/WS
df7jal23ls0d.invalid;branch=z9hG4bKwV2n29qE21vqAMQyEWmwlX8smTg1ulcT;rport=3290;received=192.168.0.92#015#012From:
<sip:default_2003 at 192.168.0.148>;tag=iWkTlWnsvaUNzW1reSya#015#012To:
<sip:default_2002 at 192.168.0.148>;tag=e50f8b01bca2eddb1ef234118a00d06d.a5ef#015#012Call-ID:
f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56775
INVITE#015#012Proxy-Authenticate: Digest realm="192.168.0.148",
nonce="UC0io1AtIXfj2LIHZ0fGntzp0rLMgji1"#015#012Server: kamailio
(3.4.0-dev2 (i386/linux))#015#012Content-Length: 0#015#012#015#012
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: websocket [ws_frame.c:127]: encoding WebSocket frame
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: websocket [ws_frame.c:156]: supported non-control frame: 0x1
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [tcp_main.c:2314]: tcp_send: send from reader (6718
(13)), reusing fd
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [tcp_main.c:2550]: tcp_send: sending...
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [tcp_main.c:2584]: tcp_send: after real write: c=
0xb559f770 n=534 fd=9
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [tcp_main.c:2585]: tcp_send: buf=#012�~#002#022SIP/2.0
407 Proxy Authentication Required#015#012Via: SIP/2.0/WS
df7jal23ls0d.invalid;branch=z9hG4bKwV2n29qE21vqAMQyEWmwlX8smTg1ulcT;rport=3290;received=192.168.0.92#015#012From:
<sip:default_2003 at 192.168.0.148>;tag=iWkTlWnsvaUNzW1reSya#015#012To:
<sip:default_2002 at 192.168.0.148>;tag=e50f8b01bca2eddb1ef234118a00d06d.a5ef#015#012Call-ID:
f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56775
INVITE#015#012Proxy-Authenticate: Digest realm="192.168.0.148",
nonce="UC0io1AtIXfj2LIHZ0fGntzp0rLMgji1"#015#012Server: kamailio
(3.4.0-dev2 (i386/linux))#015#012Content-Length: 0#015#012#015#012
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list
(nil)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list
(nil)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list
(nil)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list
(nil)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list
(nil)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list
(nil)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [xavp.c:365]: destroying xavp list (nil)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [receive.c:293]: receive_msg: cleaning up
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x82760e0, 9, 2,
0xb559f770), fd_no=1
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [tcp_read.c:1128]: WebSocket Message: (BINARY JUNK)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: websocket [ws_frame.c:343]: decoding WebSocket frame
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: websocket [ws_frame.c:393]: supported non-control frame: 0x1
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: websocket [ws_frame.c:497]: Rx (decoded): ACK
sip:default_2002 at 192.168.0.148 SIP/2.0#015#012Via: SIP/2.0/WS
df7jal23ls0d.invalid;branch=z9hG4bKwV2n29qE21vqAMQyEWmwlX8smTg1ulcT;rport#015#012From:
<sip:default_2003 at 192.168.0.148>;tag=iWkTlWnsvaUNzW1reSya#015#012To:
<sip:default_2002 at 192.168.0.148>;tag=e50f8b01bca2eddb1ef234118a00d06d.a5ef#015#012Call-ID:
f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56775
ACK#015#012Content-Length: 0#015#012Max-Forwards: 70#015#012#015#012
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: websocket [ws_frame.c:575]: Rx SIP message:#012ACK
sip:default_2002 at 192.168.0.148 SIP/2.0#015#012Via: SIP/2.0/WS
df7jal23ls0d.invalid;branch=z9hG4bKwV2n29qE21vqAMQyEWmwlX8smTg1ulcT;rport#015#012From:
<sip:default_2003 at 192.168.0.148>;tag=iWkTlWnsvaUNzW1reSya#015#012To:
<sip:default_2002 at 192.168.0.148>;tag=e50f8b01bca2eddb1ef234118a00d06d.a5ef#015#012Call-ID:
f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56775
ACK#015#012Content-Length: 0#015#012Max-Forwards: 70#015#012#015#012
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/msg_parser.c:623]: SIP Request:
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/msg_parser.c:625]:  method:  <ACK>
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/msg_parser.c:627]:  uri:
<sip:default_2002 at 192.168.0.148>
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/msg_parser.c:629]:  version: <SIP/2.0>
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/parse_via.c:1284]: Found param type 232,
<branch> = <z9hG4bKwV2n29qE21vqAMQyEWmwlX8smTg1ulcT>; state=6
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/parse_via.c:1284]: Found param type 235, <rport>
= <n/a>; state=17
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/parse_via.c:2672]: end of header reached,
state=5
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: Via found,
flags=2
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/msg_parser.c:515]: parse_headers: this is the
first via
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [receive.c:149]: After parse_msg...
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [receive.c:190]: preparing to run routing scripts...
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/parse_to.c:176]: DEBUG: add_param:
tag=e50f8b01bca2eddb1ef234118a00d06d.a5ef
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/parse_to.c:799]: end of header reached, state=29
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/msg_parser.c:190]: DEBUG: get_hdr_field: <To>
[76]; uri=[sip:default_2002 at 192.168.0.148]
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/msg_parser.c:192]: DEBUG: to body
[<sip:default_2002 at 192.168.0.148>]
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: sl [sl_funcs.c:415]: SL local ACK found -> dropping it!
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list
(nil)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list
(nil)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list
(nil)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list
(nil)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list
(nil)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list
(nil)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [xavp.c:365]: destroying xavp list (nil)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [receive.c:293]: receive_msg: cleaning up
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [tcp_read.c:1128]: WebSocket Message: (BINARY JUNK)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: websocket [ws_frame.c:343]: decoding WebSocket frame
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: websocket [ws_frame.c:393]: supported non-control frame: 0x1
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: websocket [ws_frame.c:497]: Rx (decoded): INVITE
sip:default_2002 at 192.168.0.148 SIP/2.0#015#012Via: SIP/2.0/WS
df7jal23ls0d.invalid;branch=z9hG4bKgtGX51vnUPOCui4M7lGl0LQDTKaLflPh;rport#015#012From:
<sip:default_2003 at 192.168.0.148>;tag=iWkTlWnsvaUNzW1reSya#015#012To:
<sip:default_2002 at 192.168.0.148>#015#012Contact: "Web Ext
2003"<sip:default_2003 at df7jal23ls0d.invalid;transport=ws>;+sip.ice#015#012Call-ID:
f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56776
INVITE#015#012Content-Type: application/sdp#015#012Content-Length:
2989#015#012Max-Forwards: 70#015#012Proxy-Authorization: Digest
username="default_2003",realm="192.168.0.148",nonce="UC0io1AtIXfj2LIHZ0fGntzp0rLMgji1",uri="sip:default_2002 at 192.168.0.148",response="30649c7abfef028651393486fdc21cb3",algorithm=MD5#015#012User-Agent:
IM-client/OMA1.0 sipML5/v0.0.0000.0#015#012Organization: Doubango
Telecom#015#012#015#012v=0#015#012o=- 3900702828 1 IN IP4
127.0.0.1#015#012s=webrtc (chrome 22.0.1189.0) - Doubango Telecom
(sipML5 r000)#015#012t=0 0#015#012a=group:BUNDLE audio
video#015#012m=audio 60827 RTP/SAVPF 103 104 0 8 106 105 13
126#015#012c=IN IP4 189.24.147.149#015#012a=rtcp:60827 IN IP4
189.24.147.149#015#012a=candidate:678360110 1 udp 2130714367
169.254.103.29 63966 typ host generation
0#015#012a=candidate:678360110 2 udp 2130714367 169.254.103.29 63966
typ host generation 0#015#012a=candidate:3473159702 1 udp 2130714367
192.168.0.92 60825 typ host generation 0#015#012a=candidate:3473159702
2 udp 2130714367 192.168.0.92 60825 typ host generation
0#015#012a=candidate:957632450 1 udp 1912610559 189.24.147.149 60827
typ srflx generation 0#015#012a=candidate:957632450 2 udp 1912610559
189.24.147.149 60827 typ srflx generation
0#015#012a=candidate:1726810334 1 tcp 1694506751 169.254.103.29 3302
typ host generation 0#015#012a=candidate:1726810334 2 tcp 1694506751
169.254.103.29 3302 typ host generation
0#015#012a=candidate:2173160166 1 tcp 1694506751 192.168.0.92 3303 typ
host generation 0
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: websocket [ws_frame.c:575]: Rx SIP message:#012INVITE
sip:default_2002 at 192.168.0.148 SIP/2.0#015#012Via: SIP/2.0/WS
df7jal23ls0d.invalid;branch=z9hG4bKgtGX51vnUPOCui4M7lGl0LQDTKaLflPh;rport#015#012From:
<sip:default_2003 at 192.168.0.148>;tag=iWkTlWnsvaUNzW1reSya#015#012To:
<sip:default_2002 at 192.168.0.148>#015#012Contact: "Web Ext
2003"<sip:default_2003 at df7jal23ls0d.invalid;transport=ws>;+sip.ice#015#012Call-ID:
f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56776
INVITE#015#012Content-Type: application/sdp#015#012Content-Length:
2989#015#012Max-Forwards: 70#015#012Proxy-Authorization: Digest
username="default_2003",realm="192.168.0.148",nonce="UC0io1AtIXfj2LIHZ0fGntzp0rLMgji1",uri="sip:default_2002 at 192.168.0.148",response="30649c7abfef028651393486fdc21cb3",algorithm=MD5#015#012User-Agent:
IM-client/OMA1.0 sipML5/v0.0.0000.0#015#012Organization: Doubango
Telecom#015#012#015#012v=0#015#012o=- 3900702828 1 IN IP4
127.0.0.1#015#012s=webrtc (chrome 22.0.1189.0) - Doubango Telecom
(sipML5 r000)#015#012t=0 0#015#012a=group:BUNDLE audio
video#015#012m=audio 60827 RTP/SAVPF 103 104 0 8 106 105 13
126#015#012c=IN IP4 189.24.147.149#015#012a=rtcp:60827 IN IP4
189.24.147.149#015#012a=candidate:678360110 1 udp 2130714367
169.254.103.29 63966 typ host generation
0#015#012a=candidate:678360110 2 udp 2130714367 169.254.103.29 63966
typ host generation 0#015#012a=candidate:3473159702 1 udp 2130714367
192.168.0.92 60825 typ host generation 0#015#012a=candidate:3473159702
2 udp 2130714367 192.168.0.92 60825 typ host generation
0#015#012a=candidate:957632450 1 udp 1912610559 189.24.147.149 60827
typ srflx generation 0#015#012a=candidate:957632450 2 udp 1912610559
189.24.147.149 60827 typ srflx generation
0#015#012a=candidate:1726810334 1 tcp 1694506751 169.254.103.29 3302
typ host generation 0#015#012a=candidate:1726810334 2 tcp 1694506751
169.254.103.29 3302 typ host generation
0#015#012a=candidate:2173160166 1 tcp 1694506751 192.168.0.92 3303 typ
host generat
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/msg_parser.c:623]: SIP Request:
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/msg_parser.c:625]:  method:  <INVITE>
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/msg_parser.c:627]:  uri:
<sip:default_2002 at 192.168.0.148>
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/msg_parser.c:629]:  version: <SIP/2.0>
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/parse_via.c:1284]: Found param type 232,
<branch> = <z9hG4bKgtGX51vnUPOCui4M7lGl0LQDTKaLflPh>; state=6
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/parse_via.c:1284]: Found param type 235, <rport>
= <n/a>; state=17
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/parse_via.c:2672]: end of header reached,
state=5
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: Via found,
flags=2
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/msg_parser.c:515]: parse_headers: this is the
first via
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [receive.c:149]: After parse_msg...
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [receive.c:190]: preparing to run routing scripts...
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/parse_to.c:799]: end of header reached, state=10
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/msg_parser.c:190]: DEBUG: get_hdr_field: <To>
[34]; uri=[sip:default_2002 at 192.168.0.148]
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/msg_parser.c:192]: DEBUG: to body
[<sip:default_2002 at 192.168.0.148>#015#012]
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/msg_parser.c:170]: get_hdr_field: cseq <CSeq>:
<56776> <INVITE>
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/msg_parser.c:204]: DEBUG: get_hdr_body :
content_length=2989
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: maxfwd [mf_funcs.c:85]: value = 70
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/msg_parser.c:106]: found end of header
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/parse_to.c:176]: DEBUG: add_param:
tag=iWkTlWnsvaUNzW1reSya
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/parse_to.c:799]: end of header reached, state=29
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: sanity [mod_sanity.c:255]: sanity checks result: 1
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: nathelper [nathelper.c:831]: contact uri host is not an ip
address
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: nathelper [nathelper.c:904]: adding param
<;alias=192.168.0.92~3290~5>
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: siputils [checks.c:103]: no totag
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: tm [t_lookup.c:1079]: DEBUG: t_check_msg: msg id=3 global id=1
T start=(nil)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: tm [t_lookup.c:527]: t_lookup_request: start searching:
hash=34645, isACK=0
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: tm [t_lookup.c:485]: DEBUG: RFC3261 transaction matching failed
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: tm [t_lookup.c:709]: DEBUG: t_lookup_request: no transaction
found
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: tm [t_lookup.c:1148]: DEBUG: t_check_msg: msg id=3 global id=3
T end=(nil)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [socket_info.c:589]: grep_sock_info - checking if
host==us: 13==13 && [192.168.0.148] == [192.168.0.148]
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [socket_info.c:593]: grep_sock_info - checking if port
5060 (advertise 0) matches port 5060
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: auth_db [authorize.c:363]: realm [192.168.0.148] table
[subscriber] flags [1]
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: auth [api.c:96]: auth: digest-algo: MD5 parsed value: 1
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [db_res.c:118]: allocate 28 bytes for result set at
0xb73cc8c0
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: db_mysql [km_res.c:68]: 1 columns returned from the query
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [db_res.c:155]: allocate 4 bytes for result names at
0xb73cc7b8
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [db_res.c:165]: allocate 4 bytes for result types at
0xb73cc7c8
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: db_mysql [km_res.c:85]: allocate 8 bytes for RES_NAMES[0] at
0xb73cc7d8
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: db_mysql [km_res.c:92]: RES_NAMES(0xb73cc7d8)[0]=[password]
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: db_mysql [km_res.c:136]: use DB1_STRING result type
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [db_res.c:184]: allocate 8 bytes for rows at 0xb73cc7e8
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [db_row.c:119]: allocate 20 bytes for row values at
0xb73cc7f8
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [db_val.c:117]: converting STRING [2003]
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: auth_db [authorize.c:124]: HA1 string calculated:
77b5c108972f5224aa674d91c4ba6fdc
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: auth [api.c:211]: check_response: Our result =
'30649c7abfef028651393486fdc21cb3'
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: auth [api.c:218]: check_response: Authorization is OK
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [db_res.c:81]: freeing 1 columns
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [db_res.c:85]: freeing RES_NAMES[0] at 0xb73cc7d8
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [db_res.c:94]: freeing result names at 0xb73cc7b8
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [db_res.c:99]: freeing result types at 0xb73cc7c8
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [db_res.c:54]: freeing 1 rows
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [db_row.c:97]: freeing row values at 0xb73cc7f8
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [db_res.c:62]: freeing rows at 0xb73cc7e8
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [db_res.c:136]: freeing result set at 0xb73cc8c0
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [socket_info.c:589]: grep_sock_info - checking if
host==us: 13==13 && [192.168.0.148] == [192.168.0.148]
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [socket_info.c:593]: grep_sock_info - checking if port
5060 (advertise 0) matches port 5060
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: registrar [lookup.c:181]: contact for [default_2002] found by
address
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: tm [t_lookup.c:1379]: DEBUG: t_newtran: msg id=3 , global msg
id=3 , T on entrance=(nil)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: tm [t_lookup.c:527]: t_lookup_request: start searching:
hash=34645, isACK=0
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: tm [t_lookup.c:485]: DEBUG: RFC3261 transaction matching failed
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: tm [t_lookup.c:709]: DEBUG: t_lookup_request: no transaction
found
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [md5utils.c:67]: DEBUG: MD5 calculated:
c80d0124b3aeb262f000b4f5d8fe26b5
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: tm [t_funcs.c:351]: SER: new INVITE
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [msg_translator.c:206]: check_via_address(192.168.0.92,
df7jal23ls0d.invalid, 0)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [mem/shm_mem.c:111]: WARNING:vqm_resize: resize(0)
called
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: websocket [ws_frame.c:612]: Tx SIP message:#012SIP/2.0 100
trying -- your call is important to us#015#012Via: SIP/2.0/WS
df7jal23ls0d.invalid;branch=z9hG4bKgtGX51vnUPOCui4M7lGl0LQDTKaLflPh;rport=3290;received=192.168.0.92#015#012From:
<sip:default_2003 at 192.168.0.148>;tag=iWkTlWnsvaUNzW1reSya#015#012To:
<sip:default_2002 at 192.168.0.148>#015#012Call-ID:
f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56776
INVITE#015#012Server: kamailio (3.4.0-dev2
(i386/linux))#015#012Content-Length: 0#015#012#015#012
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: websocket [ws_frame.c:127]: encoding WebSocket frame
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: websocket [ws_frame.c:156]: supported non-control frame: 0x1
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [tcp_main.c:2314]: tcp_send: send from reader (6718
(13)), reusing fd
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [tcp_main.c:2550]: tcp_send: sending...
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [tcp_main.c:2584]: tcp_send: after real write: c=
0xb559f770 n=409 fd=9
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [tcp_main.c:2585]: tcp_send: buf=#012�~#001�SIP/2.0 100
trying -- your call is important to us#015#012Via: SIP/2.0/WS
df7jal23ls0d.invalid;branch=z9hG4bKgtGX51vnUPOCui4M7lGl0LQDTKaLflPh;rport=3290;received=192.168.0.92#015#012From:
<sip:default_2003 at 192.168.0.148>;tag=iWkTlWnsvaUNzW1reSya#015#012To:
<sip:default_2002 at 192.168.0.148>#015#012Call-ID:
f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56776
INVITE#015#012Server: kamailio (3.4.0-dev2
(i386/linux))#015#012Content-Length: 0#015#012#015#012
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: tm [t_reply.c:703]: DEBUG: reply sent out. buf=0xb73ccf80:
SIP/2.0 100 trying -..., shmem=0xb559dce8: SIP/2.0 100 trying -
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: tm [t_reply.c:713]: DEBUG: _reply_light: finished
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [msg_translator.c:206]: check_via_address(192.168.0.92,
df7jal23ls0d.invalid, 0)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: tm [t_funcs.c:388]: SER: new transaction fwd'ed
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list
(nil)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list
(nil)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list
(nil)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list
(nil)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list
(nil)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list
(nil)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [xavp.c:365]: destroying xavp list (nil)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [receive.c:293]: receive_msg: cleaning up
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]:
DEBUG: <core> [parser/msg_parser.c:633]: SIP Reply  (status):
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]:
DEBUG: <core> [parser/msg_parser.c:635]:  version: <SIP/2.0>
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]:
DEBUG: <core> [parser/msg_parser.c:637]:  status:  <100>
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]:
DEBUG: <core> [parser/msg_parser.c:639]:  reason:  <Trying>
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]:
DEBUG: <core> [parser/parse_via.c:1284]: Found param type 232,
<branch> = <z9hG4bK5578.c80d0124b3aeb262f000b4f5d8fe26b5.0>; state=16
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]:
DEBUG: <core> [parser/parse_via.c:2672]: end of header reached,
state=5
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]:
DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: Via found,
flags=2
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]:
DEBUG: <core> [parser/msg_parser.c:515]: parse_headers: this is the
first via
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]:
DEBUG: <core> [receive.c:149]: After parse_msg...
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]:
DEBUG: tm [t_lookup.c:1079]: DEBUG: t_check_msg: msg id=2 global id=1
T start=(nil)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]:
DEBUG: <core> [parser/parse_via.c:1284]: Found param type 234,
<received> = <192.168.0.92>; state=6
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]:
DEBUG: <core> [parser/parse_via.c:1284]: Found param type 232,
<branch> = <z9hG4bKgtGX51vnUPOCui4M7lGl0LQDTKaLflPh>; state=6
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]:
DEBUG: <core> [parser/parse_via.c:1284]: Found param type 235, <rport>
= <3290>; state=16
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]:
DEBUG: <core> [parser/parse_via.c:2672]: end of header reached,
state=5
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]:
DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: Via found,
flags=22
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]:
DEBUG: <core> [parser/msg_parser.c:526]: parse_headers: this is the
second via
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]:
DEBUG: <core> [parser/parse_to.c:799]: end of header reached, state=10
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]:
DEBUG: <core> [parser/msg_parser.c:190]: DEBUG: get_hdr_field: <To>
[34]; uri=[sip:default_2002 at 192.168.0.148]
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]:
DEBUG: <core> [parser/msg_parser.c:192]: DEBUG: to body
[<sip:default_2002 at 192.168.0.148>#015#012]
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]:
DEBUG: <core> [parser/msg_parser.c:170]: get_hdr_field: cseq <CSeq>:
<56776> <INVITE>
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]:
DEBUG: tm [t_lookup.c:965]: DEBUG: t_reply_matching: hash 34645 label
0 branch 0
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]:
DEBUG: tm [t_lookup.c:1016]: DEBUG: t_reply_matching: reply matched
(T=0xb55a3948)!
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]:
DEBUG: tm [t_lookup.c:1148]: DEBUG: t_check_msg: msg id=2 global id=2
T end=0xb55a3948
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]:
DEBUG: tm [t_reply.c:2081]: DEBUG: reply_received: org. status
uas=100, uac[0]=0 local=0 is_invite=1)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]:
DEBUG: tm [t_reply.c:1204]: ->>>>>>>>> T_code=100, new_code=100
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]:
DEBUG: tm [t_reply.c:1702]: DEBUG: relay_reply: branch=0, save=0,
relay=-1
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]:
DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list
(nil)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]:
DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list
(nil)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]:
DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list
(nil)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]:
DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list
(nil)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]:
DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list
(nil)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]:
DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list
(nil)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]:
DEBUG: <core> [xavp.c:365]: destroying xavp list (nil)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6706]:
DEBUG: <core> [receive.c:293]: receive_msg: cleaning up
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: <core> [parser/msg_parser.c:633]: SIP Reply  (status):
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: <core> [parser/msg_parser.c:635]:  version: <SIP/2.0>
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: <core> [parser/msg_parser.c:637]:  status:  <415>
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: <core> [parser/msg_parser.c:639]:  reason:  <Unsupported Media
Type>
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: <core> [parser/parse_via.c:1284]: Found param type 232,
<branch> = <z9hG4bK5578.c80d0124b3aeb262f000b4f5d8fe26b5.0>; state=16
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: <core> [parser/parse_via.c:2672]: end of header reached,
state=5
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: Via found,
flags=2
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: <core> [parser/msg_parser.c:515]: parse_headers: this is the
first via
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: <core> [receive.c:149]: After parse_msg...
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: tm [t_lookup.c:1079]: DEBUG: t_check_msg: msg id=2 global id=1
T start=(nil)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: <core> [parser/parse_via.c:1284]: Found param type 234,
<received> = <192.168.0.92>; state=6
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: <core> [parser/parse_via.c:1284]: Found param type 232,
<branch> = <z9hG4bKgtGX51vnUPOCui4M7lGl0LQDTKaLflPh>; state=6
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: <core> [parser/parse_via.c:1284]: Found param type 235, <rport>
= <3290>; state=16
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: <core> [parser/parse_via.c:2672]: end of header reached,
state=5
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: Via found,
flags=22
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: <core> [parser/msg_parser.c:526]: parse_headers: this is the
second via
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: <core> [parser/parse_to.c:176]: DEBUG: add_param: tag=7145a639
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: <core> [parser/parse_to.c:799]: end of header reached, state=29
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: <core> [parser/msg_parser.c:190]: DEBUG: get_hdr_field: <To>
[47]; uri=[sip:default_2002 at 192.168.0.148]
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: <core> [parser/msg_parser.c:192]: DEBUG: to body
[<sip:default_2002 at 192.168.0.148>]
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: <core> [parser/msg_parser.c:170]: get_hdr_field: cseq <CSeq>:
<56776> <INVITE>
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: tm [t_lookup.c:965]: DEBUG: t_reply_matching: hash 34645 label
0 branch 0
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: tm [t_lookup.c:1016]: DEBUG: t_reply_matching: reply matched
(T=0xb55a3948)!
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: tm [t_lookup.c:1148]: DEBUG: t_check_msg: msg id=2 global id=2
T end=0xb55a3948
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: tm [t_reply.c:2081]: DEBUG: reply_received: org. status
uas=100, uac[0]=100 local=0 is_invite=1)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: tm [t_reply.c:1204]: ->>>>>>>>> T_code=100, new_code=415
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: tm [t_reply.c:1702]: DEBUG: relay_reply: branch=0, save=0,
relay=0
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: <core> [msg_translator.c:2009]:  old size: 490, new size: 404
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: <core> [msg_translator.c:2027]: build_res_from_sip_res: copied
size: orig:122, new: 36, rest: 368 msg=#012SIP/2.0 415 Unsupported
Media Type#015#012Via: SIP/2.0/WS
df7jal23ls0d.invalid;received=192.168.0.92;branch=z9hG4bKgtGX51vnUPOCui4M7lGl0LQDTKaLflPh;rport=3290#015#012To:
<sip:default_2002 at 192.168.0.148>;tag=7145a639#015#012From:
<sip:default_2003 at 192.168.0.148>;tag=iWkTlWnsvaUNzW1reSya#015#012Call-ID:
f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56776
INVITE#015#012User-Agent: X-Lite release 5.0.0 stamp
67284#015#012Content-Length: 0#015#012#015#012
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: websocket [ws_frame.c:612]: Tx SIP message:#012SIP/2.0 415
Unsupported Media Type#015#012Via: SIP/2.0/WS
df7jal23ls0d.invalid;received=192.168.0.92;branch=z9hG4bKgtGX51vnUPOCui4M7lGl0LQDTKaLflPh;rport=3290#015#012To:
<sip:default_2002 at 192.168.0.148>;tag=7145a639#015#012From:
<sip:default_2003 at 192.168.0.148>;tag=iWkTlWnsvaUNzW1reSya#015#012Call-ID:
f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56776
INVITE#015#012User-Agent: X-Lite release 5.0.0 stamp
67284#015#012Content-Length: 0#015#012#015#012
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: websocket [ws_frame.c:127]: encoding WebSocket frame
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: websocket [ws_frame.c:156]: supported non-control frame: 0x1
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: <core> [tcp_main.c:2330]: tcp_send: tcp connection found
(0xb559f770), acquiring fd
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6719]:
DEBUG: <core> [tcp_main.c:3618]: handle_ser_child: read response=
b559f770, 2, fd -1 from 3 (6708)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: <core> [tcp_main.c:2341]: tcp_send, c= 0xb559f770, n=8
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: <core> [tcp_main.c:2371]: tcp_send: after receive_fd: c=
0xb559f770 n=4 fd=6
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: <core> [tcp_main.c:2550]: tcp_send: sending...
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: <core> [tcp_main.c:2584]: tcp_send: after real write: c=
0xb559f770 n=408 fd=6
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: <core> [tcp_main.c:2585]: tcp_send: buf=#012�~#001�SIP/2.0 415
Unsupported Media Type#015#012Via: SIP/2.0/WS
df7jal23ls0d.invalid;received=192.168.0.92;branch=z9hG4bKgtGX51vnUPOCui4M7lGl0LQDTKaLflPh;rport=3290#015#012To:
<sip:default_2002 at 192.168.0.148>;tag=7145a639#015#012From:
<sip:default_2003 at 192.168.0.148>;tag=iWkTlWnsvaUNzW1reSya#015#012Call-ID:
f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56776
INVITE#015#012User-Agent: X-Lite release 5.0.0 stamp
67284#015#012Content-Length: 0#015#012#015#012
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: tm [t_reply.c:1543]: DEBUG: cleanup_uac_timers: RETR/FR timers
reset
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list
(nil)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list
(nil)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list
(nil)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list
(nil)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list
(nil)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: <core> [usr_avp.c:644]: DEBUG:destroy_avp_list: destroying list
(nil)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: <core> [xavp.c:365]: destroying xavp list (nil)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6708]:
DEBUG: <core> [receive.c:293]: receive_msg: cleaning up
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [tcp_read.c:1128]: WebSocket Message: (BINARY JUNK)
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: websocket [ws_frame.c:343]: decoding WebSocket frame
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: websocket [ws_frame.c:393]: supported non-control frame: 0x1
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: websocket [ws_frame.c:497]: Rx (decoded): ACK
sip:default_2002 at 192.168.0.148 SIP/2.0#015#012Via: SIP/2.0/WS
df7jal23ls0d.invalid;branch=z9hG4bKgtGX51vnUPOCui4M7lGl0LQDTKaLflPh;rport#015#012From:
<sip:default_2003 at 192.168.0.148>;tag=iWkTlWnsvaUNzW1reSya#015#012To:
<sip:default_2002 at 192.168.0.148>;tag=7145a639#015#012Call-ID:
f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56776
ACK#015#012Content-Length: 0#015#012Max-Forwards: 70#015#012#015#012
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: websocket [ws_frame.c:575]: Rx SIP message:#012ACK
sip:default_2002 at 192.168.0.148 SIP/2.0#015#012Via: SIP/2.0/WS
df7jal23ls0d.invalid;branch=z9hG4bKgtGX51vnUPOCui4M7lGl0LQDTKaLflPh;rport#015#012From:
<sip:default_2003 at 192.168.0.148>;tag=iWkTlWnsvaUNzW1reSya#015#012To:
<sip:default_2002 at 192.168.0.148>;tag=7145a639#015#012Call-ID:
f22e18c6-9d3f-82cf-5e26-7e786f35213f#015#012CSeq: 56776
ACK#015#012Content-Length: 0#015#012Max-Forwards: 70#015#012#015#012
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/msg_parser.c:623]: SIP Request:
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/msg_parser.c:625]:  method:  <ACK>
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/msg_parser.c:627]:  uri:
<sip:default_2002 at 192.168.0.148>
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/msg_parser.c:629]:  version: <SIP/2.0>
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/parse_via.c:1284]: Found param type 232,
<branch> = <z9hG4bKgtGX51vnUPOCui4M7lGl0LQDTKaLflPh>; state=6
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/parse_via.c:1284]: Found param type 235, <rport>
= <n/a>; state=17
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/parse_via.c:2672]: end of header reached,
state=5
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/msg_parser.c:513]: parse_headers: Via found,
flags=2
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/msg_parser.c:515]: parse_headers: this is the
first via
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [receive.c:149]: After parse_msg...
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [receive.c:190]: preparing to run routing scripts...
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/parse_to.c:176]: DEBUG: add_param: tag=7145a639
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/parse_to.c:799]: end of header reached, state=29
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/msg_parser.c:190]: DEBUG: get_hdr_field: <To>
[47]; uri=[sip:default_2002 at 192.168.0.148]
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/msg_parser.c:192]: DEBUG: to body
[<sip:default_2002 at 192.168.0.148>]
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/msg_parser.c:170]: get_hdr_field: cseq <CSeq>:
<56776> <ACK>
Aug 16 13:36:08 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [parser/msg_parser.c:204]: DEBUG: get_hdr_body :
content_length=0
Aug 16 13:36:14 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [io_wait.h:617]: DBG: io_watch_del (0x82760e0, 9, -1,
0x10) fd_no=2 called
Aug 16 13:36:14 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [tcp_read.c:1430]: releasing con 0xb559f770, state 1,
fd=9, id=1
Aug 16 13:36:14 localhost /usr/local/kamailio/sbin/kamailio[6718]:
DEBUG: <core> [tcp_read.c:1431]:  extra_data (nil)
Aug 16 13:36:14 localhost /usr/local/kamailio/sbin/kamailio[6719]:
DEBUG: <core> [tcp_main.c:3379]: handle_tcp_child: reader response=
b559f770, 1 from 3
Aug 16 13:36:14 localhost /usr/local/kamailio/sbin/kamailio[6719]:
DEBUG: <core> [io_wait.h:390]: DBG: io_watch_add(0x8253ea0, 28, 2,
0xb559f770), fd_no=21
Aug 16 13:36:14 localhost /usr/local/kamailio/sbin/kamailio[6719]:
DEBUG: <core> [tcp_main.c:3509]: handle_tcp_child: CONN_RELEASE
0xb559f770 refcnt= 16
Aug 16 13:36:17 localhost /usr/local/kamailio/sbin/kamailio[6709]:
DEBUG: <core> [udp_server.c:498]: udp_rcv_loop: probing packet
received from 192.168.0.101 50195

On Thu, Aug 16, 2012 at 1:32 PM, Juan Castro <jcastro at instant.com.br> wrote:
> Hoo-hah. Now I get a truckload of messages. Thanks!
>
> On Thu, Aug 16, 2012 at 1:07 PM, Muhammad Shahzad
> <shaheryarkh at googlemail.com> wrote:
>> humm, interesting.
>>
>> Can you try to put following on top of your config?
>>
>> ---
>> debug=4
>> log_stderror=no
>>
>> memdbg=5
>> memlog=5
>>
>> log_facility=LOG_LOCAL0
>>
>> fork=yes
>> children=4
>> ---
>>
>> Make sure you don't override these variable in later config. This should
>> work, if not something is really wrong with your installation.
>>
>> Thank you.

-- 
Juan Carlos Castro y Castro
Instant Solutions - Telefonia Gerando Resultado
http://www.instant.com.br
Principais capitais: 4063-6100
Demais regiões: (11)4063-6100