You have to know that I'm
recreating the reality on once
physical machine with different
virtual machine.
My network is like that:
machine3UDP 172.16.0.12 NAT
--------------- machine 1,
172.16.0.10 and 192.168.1.9
NAT(kamailio realtime asterisk)
----------- machine 2 and 3
(private network)192.168.1.15
and.16 TLS
af8448de310d568e037
13(19429) DEBUG: auth
[api.c:210]: check_response: Our
result =
'1fa92b49d32418c756adbcef119c0521'
13(19429) DEBUG: auth
[api.c:217]: check_response:
Authorization is OK
13(19429) DEBUG: <core>
[db_res.c:81]: freeing 1 columns
13(19429) DEBUG: <core>
[db_res.c:85]: freeing
RES_NAMES[0] at 0xb736e5ec
13(19429) DEBUG: <core>
[db_res.c:94]: freeing result
names at 0xb736e584
13(19429) DEBUG: <core>
[db_res.c:99]: freeing result
types at 0xb736e5b8
13(19429) DEBUG: <core>
[db_res.c:54]: freeing 1 rows
13(19429) DEBUG: <core>
[db_row.c:97]: freeing row values
at 0xb736e65c
13(19429) DEBUG: <core>
[db_res.c:62]: freeing rows at
0xb736e624
13(19429) DEBUG: <core>
[db_res.c:136]: freeing result set
at 0xb7371bf0
13(19429) DEBUG: <core>
[socket_info.c:502]:
grep_sock_info - checking if
host==us: 11==11 &&
[192.168.1.9] == [192.168.1.9]
13(19429) DEBUG: <core>
[socket_info.c:505]:
grep_sock_info - checking if port
5060 matches port 5060
13(19429) DEBUG: usrloc
[ucontact.c:805]: exists callback
for type= UL_CONTACT_UPDATE
13(19429) DEBUG: sl [sl.c:278]:
reply in stateless mode (sl)
13(19429) DEBUG: <core>
[msg_translator.c:204]:
check_via_address(192.168.1.15,
192.168.1.15, 0)
13(19429) DEBUG: <core>
[tcp_main.c:2284]: tcp_send: send
from reader (19429 (13)), reusing
fd
13(19429) DEBUG: <core>
[tcp_main.c:2520]: tcp_send:
sending...
13(19429) DEBUG: <core>
[tcp_main.c:2554]: tcp_send: after
real write: c= 0xb35bc370 n=517
fd=11
13(19429) DEBUG: <core>
[tcp_main.c:2555]: tcp_send: buf=
13(19429) DEBUG: <core>
[select.c:424]: Calling SELECT
0xb737c788
13(19429) DEBUG: <core>
[select.c:424]: Calling SELECT
0xb737ebf4
13(19429) DEBUG: <core>
[select.c:424]: Calling SELECT
0xb7384bd0
13(19429) DEBUG: <core>
[select.c:424]: Calling SELECT
0xb7385ef0
13(19429) DEBUG: <core>
[select.c:424]: Calling SELECT
0xb73872b4
13(19429) DEBUG: tm
[uac.c:243]: DEBUG:tm:t_uac:
next_hop=<sip:
192.168.1.9:5080>
13(19429) DEBUG: tm
[uac.c:182]: DEBUG: dlg2hash:
41917
13(19429) DEBUG: <core>
[usr_avp.c:644]:
DEBUG:destroy_avp_list: destroying
list 0xb35d2388
13(19429) DEBUG: <core>
[usr_avp.c:644]:
DEBUG:destroy_avp_list: destroying
list (nil)
13(19429) DEBUG: <core>
[usr_avp.c:644]:
DEBUG:destroy_avp_list: destroying
list (nil)
13(19429) DEBUG: <core>
[usr_avp.c:644]:
DEBUG:destroy_avp_list: destroying
list (nil)
13(19429) DEBUG: <core>
[usr_avp.c:644]:
DEBUG:destroy_avp_list: destroying
list (nil)
13(19429) DEBUG: <core>
[usr_avp.c:644]:
DEBUG:destroy_avp_list: destroying
list (nil)
13(19429) DEBUG: <core>
[xavp.c:365]: destroying xavp list
(nil)
13(19429) DEBUG: <core>
[receive.c:291]: receive_msg:
cleaning up
4(19415) DEBUG: <core>
[parser/msg_parser.c:628]: SIP
Request:
4(19415) DEBUG: <core>
[parser/msg_parser.c:630]:
method: <PUBLISH>
4(19415) DEBUG: <core>
[parser/msg_parser.c:634]:
version: <SIP/2.0>
4(19415) DEBUG: <core>
[parser/parse_via.c:1288]: Found
param type 235, <rport> =
<n/a>; state=6
4(19415) DEBUG: <core>
[parser/parse_via.c:1288]: Found
param type 232, <branch> =
<z9hG4bKPjaaa2cc6202fd4abda385cac59b336e54>;
state=16
4(19415) DEBUG: <core>
[parser/parse_via.c:2563]: end of
header reached, state=5
4(19415) DEBUG: <core>
[parser/msg_parser.c:515]:
parse_headers: Via found, flags=2
4(19415) DEBUG: <core>
[parser/msg_parser.c:517]:
parse_headers: this is the first
via
4(19415) DEBUG: <core>
[receive.c:147]: After
parse_msg...
4(19415) DEBUG: <core>
[receive.c:188]: preparing to run
routing scripts...
4(19415) DEBUG: <core>
[parser/parse_to.c:177]: DEBUG:
add_param:
tag=78a5897e698d4401a128e4918e9edc0b
4(19415) DEBUG: <core>
[parser/parse_to.c:801]: end of
header reached, state=29
4(19415) DEBUG: maxfwd
[mf_funcs.c:85]: value = 70
4(19415) DEBUG: <core>
[parser/parse_to.c:801]: end of
header reached, state=10
4(19415) DEBUG: <core>
[parser/msg_parser.c:187]: DEBUG:
get_hdr_field: <To> [25];
uri=[
sip:102@sip.wtz.local]
]
4(19415) DEBUG: <core>
[parser/msg_parser.c:167]:
get_hdr_field: cseq <CSeq>:
<18509> <PUBLISH>
4(19415) DEBUG: <core>
[parser/msg_parser.c:201]: DEBUG:
get_hdr_body : content_length=284
4(19415) DEBUG: <core>
[parser/msg_parser.c:103]: found
end of header
4(19415) DEBUG: sanity
[mod_sanity.c:251]: sanity checks
result: 1
4(19415) DEBUG: <core>
[msg_translator.c:204]:
check_via_address(192.168.1.15,
192.168.1.15, 0)
4(19415) DEBUG: siputils
[checks.c:104]: no totag
4(19415) DEBUG: tm
[t_lookup.c:1079]: DEBUG:
t_check_msg: msg id=2 global id=1
T start=(nil)
4(19415) DEBUG: tm
[t_lookup.c:527]:
t_lookup_request: start searching:
hash=21215, isACK=0
4(19415) DEBUG: tm
[t_lookup.c:485]: DEBUG: RFC3261
transaction matching failed
4(19415) DEBUG: tm
[t_lookup.c:709]: DEBUG:
t_lookup_request: no transaction
found
4(19415) DEBUG: tm
[t_lookup.c:1148]: DEBUG:
t_check_msg: msg id=2 global id=2
T end=(nil)
4(19415) DEBUG: <core>
[select.c:424]: Calling SELECT
0xb7376c38
4(19415) ERROR: permissions
[trusted.c:415]: no connection to
database
4(19415) DEBUG: <core>
[socket_info.c:502]:
grep_sock_info - checking if
host==us: 13==11 &&
[sip.wtz.local] == [192.168.1.9]
4(19415) DEBUG: <core>
[socket_info.c:505]:
grep_sock_info - checking if port
5060 matches port 5060
4(19415) DEBUG: <core>
[socket_info.c:502]:
grep_sock_info - checking if
host==us: 13==11 &&
[sip.wtz.local] == [192.168.1.9]
4(19415) DEBUG: <core>
[socket_info.c:505]:
grep_sock_info - checking if port
5061 matches port 5060
4(19415) DEBUG: <core>
[msg_translator.c:204]:
check_via_address(192.168.1.15,
192.168.1.15, 0)
4(19415) DEBUG: <core>
[usr_avp.c:644]:
DEBUG:destroy_avp_list: destroying
list (nil)
4(19415) DEBUG: <core>
[usr_avp.c:644]:
DEBUG:destroy_avp_list: destroying
list (nil)
4(19415) DEBUG: <core>
[usr_avp.c:644]:
DEBUG:destroy_avp_list: destroying
list (nil)
4(19415) DEBUG: <core>
[usr_avp.c:644]:
DEBUG:destroy_avp_list: destroying
list (nil)
4(19415) DEBUG: <core>
[usr_avp.c:644]:
DEBUG:destroy_avp_list: destroying
list (nil)
4(19415) DEBUG: <core>
[usr_avp.c:644]:
DEBUG:destroy_avp_list: destroying
list (nil)
4(19415) DEBUG: <core>
[xavp.c:365]: destroying xavp list
(nil)
4(19415) DEBUG: <core>
[receive.c:291]: receive_msg:
cleaning up
1(19412) DEBUG: <core>
[parser/msg_parser.c:638]: SIP
Reply (status):
1(19412) DEBUG: <core>
[parser/msg_parser.c:640]:
version: <SIP/2.0>
1(19412) DEBUG: <core>
[parser/msg_parser.c:642]:
status: <200>
1(19412) DEBUG: <core>
[parser/msg_parser.c:644]:
reason: <OK>
1(19412) DEBUG: <core>
[parser/parse_via.c:1288]: Found
param type 232, <branch> =
<z9hG4bKdb3a.0be1df11.0>;
state=6
1(19412) DEBUG: <core>
[parser/parse_via.c:1288]: Found
param type 234, <received> =
<192.168.1.9>; state=16
1(19412) DEBUG: <core>
[parser/parse_via.c:2563]: end of
header reached, state=5
1(19412) DEBUG: <core>
[parser/msg_parser.c:515]:
parse_headers: Via found, flags=2
1(19412) DEBUG: <core>
[parser/msg_parser.c:517]:
parse_headers: this is the first
via
1(19412) DEBUG: <core>
[receive.c:147]: After
parse_msg...
1(19412) DEBUG: tm
[t_lookup.c:1079]: DEBUG:
t_check_msg: msg id=3 global id=2
T start=0xffffffff
1(19412) DEBUG: <core>
[parser/parse_to.c:177]: DEBUG:
add_param: tag=as699907a5
1(19412) DEBUG: <core>
[parser/parse_to.c:801]: end of
header reached, state=29
1(19412) DEBUG: <core>
[parser/msg_parser.c:187]: DEBUG:
get_hdr_field: <To> [36];
uri=[
sip:102@192.168.1.9]
1(19412) DEBUG: <core>
[parser/msg_parser.c:167]:
get_hdr_field: cseq <CSeq>:
<10> <REGISTER>
1(19412) DEBUG: tm
[t_lookup.c:965]: DEBUG:
t_reply_matching: hash 41917 label
301801136 branch 0
1(19412) DEBUG: tm
[t_lookup.c:1016]: DEBUG:
t_reply_matching: reply matched
(T=0xb35cfc68)!
1(19412) DEBUG: tm
[t_lookup.c:1148]: DEBUG:
t_check_msg: msg id=3 global id=3
T end=0xb35cfc68
1(19412) DEBUG: tm
[t_reply.c:2053]: DEBUG:
reply_received: org. status uas=0,
uac[0]=0 local=2 is_invite=0)
1(19412) DEBUG: tm
[t_reply.c:1168]:
->>>>>>>>>
T_code=0, new_code=200
1(19412) DEBUG: tm
[t_reply.c:1931]: DEBUG:
local_reply: branch=0, save=0,
winner=0
1(19412) DEBUG: tm
[t_reply.c:1968]: DEBUG: local
transaction completed
1(19412) DEBUG: tm
[t_reply.c:1509]: DEBUG:
cleanup_uac_timers: RETR/FR timers
reset
1(19412) DEBUG: <core>
[usr_avp.c:644]:
DEBUG:destroy_avp_list: destroying
list (nil)
1(19412) DEBUG: <core>
[usr_avp.c:644]:
DEBUG:destroy_avp_list: destroying
list (nil)
1(19412) DEBUG: <core>
[usr_avp.c:644]:
DEBUG:destroy_avp_list: destroying
list (nil)
1(19412) DEBUG: <core>
[usr_avp.c:644]:
DEBUG:destroy_avp_list: destroying
list (nil)
1(19412) DEBUG: <core>
[usr_avp.c:644]:
DEBUG:destroy_avp_list: destroying
list (nil)
1(19412) DEBUG: <core>
[usr_avp.c:644]:
DEBUG:destroy_avp_list: destroying
list (nil)
1(19412) DEBUG: <core>
[xavp.c:365]: destroying xavp list
(nil)
1(19412) DEBUG: <core>
[receive.c:291]: receive_msg:
cleaning up
2(19413) DEBUG: <core>
[parser/msg_parser.c:628]: SIP
Request:
2(19413) DEBUG: <core>
[parser/msg_parser.c:630]:
method: <REGISTER>
2(19413) DEBUG: <core>
[parser/msg_parser.c:634]:
version: <SIP/2.0>
2(19413) DEBUG: <core>
[parser/parse_via.c:1288]: Found
param type 235, <rport> =
<n/a>; state=6
2(19413) DEBUG: <core>
[parser/parse_via.c:1288]: Found
param type 232, <branch> =
<z9hG4bKPjb88ef0f513f643c9b9b08ad7dba384aa>;
state=16
2(19413) DEBUG: <core>
[parser/parse_via.c:2563]: end of
header reached, state=5
2(19413) DEBUG: <core>
[parser/msg_parser.c:515]:
parse_headers: Via found, flags=2
2(19413) DEBUG: <core>
[parser/msg_parser.c:517]:
parse_headers: this is the first
via
2(19413) DEBUG: <core>
[receive.c:147]: After
parse_msg...
2(19413) DEBUG: <core>
[receive.c:188]: preparing to run
routing scripts...
2(19413) DEBUG: <core>
[parser/parse_to.c:177]: DEBUG:
add_param:
tag=27a6e4f8cff94d9ab8a68d58011e5508
2(19413) DEBUG: <core>
[parser/parse_to.c:801]: end of
header reached, state=29
2(19413) DEBUG: <core>
[parser/parse_to.c:801]: end of
header reached, state=10
2(19413) DEBUG: <core>
[parser/msg_parser.c:187]: DEBUG:
get_hdr_field: <To> [23];
uri=[
sip:103@sip.wtz.nat]
]
2(19413) DEBUG: <core>
[parser/msg_parser.c:167]:
get_hdr_field: cseq <CSeq>:
<45402> <REGISTER>
2(19413) DEBUG: <core>
[parser/msg_parser.c:201]: DEBUG:
get_hdr_body : content_length=0
2(19413) DEBUG: <core>
[parser/msg_parser.c:103]: found
end of header
2(19413) DEBUG: <core>
[parser/sdp/sdp.c:696]: message
body has length zero
2(19413) DEBUG: sdpops
[sdpops_mod.c:657]: attempting to
search for media type: [audio]
2(19413) DEBUG: sdpops
[sdpops_mod.c:662]: No sdp body
2(19413) DEBUG: maxfwd
[mf_funcs.c:85]: value = 70
2(19413) DEBUG: sanity
[mod_sanity.c:251]: sanity checks
result: 1
2(19413) DEBUG: <core>
[msg_translator.c:204]:
check_via_address(172.16.0.12,
172.16.0.12, 0)
2(19413) DEBUG: siputils
[checks.c:104]: no totag
2(19413) DEBUG: tm
[t_lookup.c:1079]: DEBUG:
t_check_msg: msg id=3 global id=2
T start=0xffffffff
2(19413) DEBUG: tm
[t_lookup.c:527]:
t_lookup_request: start searching:
hash=36220, isACK=0
2(19413) DEBUG: tm
[t_lookup.c:485]: DEBUG: RFC3261
transaction matching failed
2(19413) DEBUG: tm
[t_lookup.c:709]: DEBUG:
t_lookup_request: no transaction
found
2(19413) DEBUG: tm
[t_lookup.c:1148]: DEBUG:
t_check_msg: msg id=3 global id=3
T end=(nil)
2(19413) DEBUG: <core>
[select.c:424]: Calling SELECT
0xb7376c38
2(19413) DEBUG: auth_db
[authorize.c:303]: realm value
[sip.wtz.nat]
2(19413) DEBUG: auth
[api.c:85]: auth:pre_auth:
Credentials with realm
'sip.wtz.nat' not found
2(19413) DEBUG: auth_db
[authorize.c:177]: no credentials
2(19413) DEBUG: auth
[challenge.c:102]:
build_challenge_hf:
realm='sip.wtz.nat'
2(19413) DEBUG: auth
[challenge.c:244]: auth:
'WWW-Authenticate: Digest
realm="sip.wtz.nat",
nonce="T9X59E/V+Mg6xGlySWH+rfOEihqYhssq"
'
2(19413) DEBUG: sl [sl.c:278]:
reply in stateless mode (sl)
2(19413) DEBUG: <core>
[msg_translator.c:204]:
check_via_address(172.16.0.12,
172.16.0.12, 0)
2(19413) DEBUG: <core>
[usr_avp.c:644]:
DEBUG:destroy_avp_list: destroying
list 0xb35d2388
2(19413) DEBUG: <core>
[usr_avp.c:644]:
DEBUG:destroy_avp_list: destroying
list (nil)
2(19413) DEBUG: <core>
[usr_avp.c:644]:
DEBUG:destroy_avp_list: destroying
list (nil)
2(19413) DEBUG: <core>
[usr_avp.c:644]:
DEBUG:destroy_avp_list: destroying
list (nil)
2(19413) DEBUG: <core>
[usr_avp.c:644]:
DEBUG:destroy_avp_list: destroying
list (nil)
2(19413) DEBUG: <core>
[usr_avp.c:644]:
DEBUG:destroy_avp_list: destroying
list (nil)
2(19413) DEBUG: <core>
[xavp.c:365]: destroying xavp list
(nil)
2(19413) DEBUG: <core>
[receive.c:291]: receive_msg:
cleaning up
3(19414) DEBUG: <core>
[parser/msg_parser.c:628]: SIP
Request:
3(19414) DEBUG: <core>
[parser/msg_parser.c:630]:
method: <REGISTER>
3(19414) DEBUG: <core>
[parser/msg_parser.c:634]:
version: <SIP/2.0>
3(19414) DEBUG: <core>
[parser/parse_via.c:1288]: Found
param type 235, <rport> =
<n/a>; state=6
3(19414) DEBUG: <core>
[parser/parse_via.c:1288]: Found
param type 232, <branch> =
<z9hG4bKPjec9008971eff4b1fbea5ec76965dfe42>;
state=16
3(19414) DEBUG: <core>
[parser/parse_via.c:2563]: end of
header reached, state=5
3(19414) DEBUG: <core>
[parser/msg_parser.c:515]:
parse_headers: Via found, flags=2
3(19414) DEBUG: <core>
[parser/msg_parser.c:517]:
parse_headers: this is the first
via
3(19414) DEBUG: <core>
[receive.c:147]: After
parse_msg...
3(19414) DEBUG: <core>
[receive.c:188]: preparing to run
routing scripts...
3(19414) DEBUG: <core>
[parser/parse_to.c:177]: DEBUG:
add_param:
tag=27a6e4f8cff94d9ab8a68d58011e5508
3(19414) DEBUG: <core>
[parser/parse_to.c:801]: end of
header reached, state=29
3(19414) DEBUG: <core>
[parser/parse_to.c:801]: end of
header reached, state=10
3(19414) DEBUG: <core>
[parser/msg_parser.c:187]: DEBUG:
get_hdr_field: <To> [23];
uri=[
sip:103@sip.wtz.nat]
]
3(19414) DEBUG: <core>
[parser/msg_parser.c:167]:
get_hdr_field: cseq <CSeq>:
<45403> <REGISTER>
3(19414) DEBUG: <core>
[parser/msg_parser.c:201]: DEBUG:
get_hdr_body : content_length=0
3(19414) DEBUG: <core>
[parser/msg_parser.c:103]: found
end of header
3(19414) DEBUG: <core>
[parser/sdp/sdp.c:696]: message
body has length zero
3(19414) DEBUG: sdpops
[sdpops_mod.c:657]: attempting to
search for media type: [audio]
3(19414) DEBUG: sdpops
[sdpops_mod.c:662]: No sdp body
3(19414) DEBUG: maxfwd
[mf_funcs.c:85]: value = 70
3(19414) DEBUG: sanity
[mod_sanity.c:251]: sanity checks
result: 1
3(19414) DEBUG: <core>
[msg_translator.c:204]:
check_via_address(172.16.0.12,
172.16.0.12, 0)
3(19414) DEBUG: siputils
[checks.c:104]: no totag
3(19414) DEBUG: tm
[t_lookup.c:1079]: DEBUG:
t_check_msg: msg id=2 global id=1
T start=0xffffffff
3(19414) DEBUG: tm
[t_lookup.c:527]:
t_lookup_request: start searching:
hash=36221, isACK=0
3(19414) DEBUG: tm
[t_lookup.c:485]: DEBUG: RFC3261
transaction matching failed
3(19414) DEBUG: tm
[t_lookup.c:709]: DEBUG:
t_lookup_request: no transaction
found
3(19414) DEBUG: tm
[t_lookup.c:1148]: DEBUG:
t_check_msg: msg id=2 global id=2
T end=(nil)
3(19414) DEBUG: <core>
[select.c:424]: Calling SELECT
0xb7376c38
3(19414) DEBUG: auth_db
[authorize.c:303]: realm value
[sip.wtz.nat]
3(19414) DEBUG: auth
[api.c:95]: auth: digest-algo:
parsed value: 0
3(19414) DEBUG: <core>
[db_res.c:118]: allocate 28 bytes
for result set at 0xb7371ad4
3(19414) DEBUG: db_mysql
[km_res.c:68]: 1 columns returned
from the query
3(19414) DEBUG: <core>
[db_res.c:155]: allocate 4 bytes
for result names at 0xb7386114
3(19414) DEBUG: <core>
[db_res.c:165]: allocate 4 bytes
for result types at 0xb732be3c
3(19414) DEBUG: db_mysql
[km_res.c:85]: allocate 8 bytes
for RES_NAMES[0] at 0xb736e3a0
3(19414) DEBUG: db_mysql
[km_res.c:92]:
RES_NAMES(0xb736e3a0)[0]=[sippasswd]
3(19414) DEBUG: db_mysql
[km_res.c:136]: use DB1_STRING
result type
3(19414) DEBUG: <core>
[db_res.c:184]: allocate 8 bytes
for rows at 0xb736e3d8
3(19414) DEBUG: <core>
[db_row.c:119]: allocate 20 bytes
for row values at 0xb736e410
3(19414) DEBUG: <core>
[db_val.c:117]: converting STRING
[103]
3(19414) DEBUG: auth_db
[authorize.c:121]: HA1 string
calculated:
7c958fe006d9d282b8b1791acd644a9b
3(19414) DEBUG: auth
[api.c:210]: check_response: Our
result =
'e76ca7c04e2060cfda3422bd689a3ce6'
3(19414) DEBUG: auth
[api.c:217]: check_response:
Authorization is OK
3(19414) DEBUG: <core>
[db_res.c:81]: freeing 1 columns
3(19414) DEBUG: <core>
[db_res.c:85]: freeing
RES_NAMES[0] at 0xb736e3a0
3(19414) DEBUG: <core>
[db_res.c:94]: freeing result
names at 0xb7386114
3(19414) DEBUG: <core>
[db_res.c:99]: freeing result
types at 0xb732be3c
3(19414) DEBUG: <core>
[db_res.c:54]: freeing 1 rows
3(19414) DEBUG: <core>
[db_row.c:97]: freeing row values
at 0xb736e410
3(19414) DEBUG: <core>
[db_res.c:62]: freeing rows at
0xb736e3d8
3(19414) DEBUG: <core>
[db_res.c:136]: freeing result set
at 0xb7371ad4
3(19414) DEBUG: <core>
[socket_info.c:502]:
grep_sock_info - checking if
host==us: 13==11 &&
[sip.wtz.local] == [192.168.1.9]
3(19414) DEBUG: <core>
[socket_info.c:505]:
grep_sock_info - checking if port
5060 matches port 5060
3(19414) DEBUG: <core>
[socket_info.c:502]:
grep_sock_info - checking if
host==us: 13==11 &&
[sip.wtz.local] == [192.168.1.9]
3(19414) DEBUG: <core>
[socket_info.c:505]:
grep_sock_info - checking if port
5061 matches port 5060
3(19414) DEBUG: usrloc
[ucontact.c:805]: exists callback
for type= UL_CONTACT_UPDATE
3(19414) DEBUG: sl [sl.c:278]:
reply in stateless mode (sl)
3(19414) DEBUG: <core>
[msg_translator.c:204]:
check_via_address(172.16.0.12,
172.16.0.12, 0)
3(19414) DEBUG: <core>
[select.c:424]: Calling SELECT
0xb737c788
3(19414) DEBUG: <core>
[select.c:424]: Calling SELECT
0xb737ebf4
3(19414) DEBUG: <core>
[select.c:424]: Calling SELECT
0xb7384bd0
3(19414) DEBUG: <core>
[select.c:424]: Calling SELECT
0xb7385ef0
3(19414) DEBUG: <core>
[select.c:424]: Calling SELECT
0xb73872b4
3(19414) DEBUG: tm
[uac.c:182]: DEBUG: dlg2hash:
27378
3(19414) DEBUG: <core>
[usr_avp.c:644]:
DEBUG:destroy_avp_list: destroying
list 0xb35d2388
3(19414) DEBUG: <core>
[usr_avp.c:644]:
DEBUG:destroy_avp_list: destroying
list (nil)
3(19414) DEBUG: <core>
[usr_avp.c:644]:
DEBUG:destroy_avp_list: destroying
list (nil)
3(19414) DEBUG: <core>
[usr_avp.c:644]:
DEBUG:destroy_avp_list: destroying
list (nil)
3(19414) DEBUG: <core>
[usr_avp.c:644]:
DEBUG:destroy_avp_list: destroying
list (nil)
3(19414) DEBUG: <core>
[usr_avp.c:644]:
DEBUG:destroy_avp_list: destroying
list (nil)
3(19414) DEBUG: <core>
[xavp.c:365]: destroying xavp list
(nil)
3(19414) DEBUG: <core>
[receive.c:291]: receive_msg:
cleaning up
4(19415) DEBUG: <core>
[parser/msg_parser.c:638]: SIP
Reply (status):
4(19415) DEBUG: <core>
[parser/msg_parser.c:640]:
version: <SIP/2.0>
4(19415) DEBUG: <core>
[parser/msg_parser.c:642]:
status: <200>
4(19415) DEBUG: <core>
[parser/msg_parser.c:644]:
reason: <OK>
4(19415) DEBUG: <core>
[parser/parse_via.c:1288]: Found
param type 232, <branch> =
<z9hG4bK2fa6.37c16006.0>;
state=6
4(19415) DEBUG: <core>
[parser/parse_via.c:1288]: Found
param type 234, <received> =
<192.168.1.9>; state=16
4(19415) DEBUG: <core>
[parser/parse_via.c:2563]: end of
header reached, state=5
4(19415) DEBUG: <core>
[parser/msg_parser.c:515]:
parse_headers: Via found, flags=2
4(19415) DEBUG: <core>
[parser/msg_parser.c:517]:
parse_headers: this is the first
via
4(19415) DEBUG: <core>
[receive.c:147]: After
parse_msg...
4(19415) DEBUG: tm
[t_lookup.c:1079]: DEBUG:
t_check_msg: msg id=3 global id=2
T start=(nil)
4(19415) DEBUG: <core>
[parser/parse_to.c:177]: DEBUG:
add_param: tag=as50edab1b
4(19415) DEBUG: <core>
[parser/parse_to.c:801]: end of
header reached, state=29
4(19415) DEBUG: <core>
[parser/msg_parser.c:187]: DEBUG:
get_hdr_field: <To> [36];
uri=[
sip:103@192.168.1.9]
4(19415) DEBUG: <core>
[parser/msg_parser.c:167]:
get_hdr_field: cseq <CSeq>:
<10> <REGISTER>
4(19415) DEBUG: tm
[t_lookup.c:965]: DEBUG:
t_reply_matching: hash 27378 label
1611013235 branch 0
4(19415) DEBUG: tm
[t_lookup.c:1016]: DEBUG:
t_reply_matching: reply matched
(T=0xb35cfc68)!
4(19415) DEBUG: tm
[t_lookup.c:1148]: DEBUG:
t_check_msg: msg id=3 global id=3
T end=0xb35cfc68
4(19415) DEBUG: tm
[t_reply.c:2053]: DEBUG:
reply_received: org. status uas=0,
uac[0]=0 local=2 is_invite=0)
4(19415) DEBUG: tm
[t_reply.c:1168]:
->>>>>>>>>
T_code=0, new_code=200
4(19415) DEBUG: tm
[t_reply.c:1931]: DEBUG:
local_reply: branch=0, save=0,
winner=0
4(19415) DEBUG: tm
[t_reply.c:1968]: DEBUG: local
transaction completed
4(19415) DEBUG: tm
[t_reply.c:1509]: DEBUG:
cleanup_uac_timers: RETR/FR timers
reset
4(19415) DEBUG: <core>
[usr_avp.c:644]:
DEBUG:destroy_avp_list: destroying
list (nil)
4(19415) DEBUG: <core>
[usr_avp.c:644]:
DEBUG:destroy_avp_list: destroying
list (nil)
4(19415) DEBUG: <core>
[usr_avp.c:644]:
DEBUG:destroy_avp_list: destroying
list (nil)
4(19415) DEBUG: <core>
[usr_avp.c:644]:
DEBUG:destroy_avp_list: destroying
list (nil)
4(19415) DEBUG: <core>
[usr_avp.c:644]:
DEBUG:destroy_avp_list: destroying
list (nil)
4(19415) DEBUG: <core>
[usr_avp.c:644]:
DEBUG:destroy_avp_list: destroying
list (nil)
4(19415) DEBUG: <core>
[xavp.c:365]: destroying xavp list
(nil)
4(19415) DEBUG: <core>
[receive.c:291]: receive_msg:
cleaning up
13(19429) DEBUG: <core>
[io_wait.h:617]: DBG: io_watch_del
(0x82831e0, 11, -1, 0x10) fd_no=2
called
13(19429) DEBUG: <core>
[tcp_read.c:1089]: releasing con
0xb35bc370, state 1, fd=11, id=2
13(19429) DEBUG: <core>
[tcp_read.c:1090]: extra_data
0xb35b9b50
16(19433) DEBUG: <core>
[tcp_main.c:3345]:
handle_tcp_child: reader response=
b35bc370, 1 from 1
16(19433) DEBUG: <core>
[io_wait.h:390]: DBG:
io_watch_add(0x8260fa0, 31, 2,
0xb35bc370), fd_no=20
16(19433) DEBUG: <core>
[tcp_main.c:3475]:
handle_tcp_child: CONN_RELEASE
0xb35bc370 refcnt= 1
1(19412) DEBUG: <core>
[udp_server.c:498]: udp_rcv_loop:
probing packet received from
192.168.1.16 50195
2(19413) DEBUG: <core>
[parser/msg_parser.c:628]: SIP
Request:
2(19413) DEBUG: <core>
[parser/msg_parser.c:630]:
method: <INVITE>
2(19413) DEBUG: <core>
[parser/msg_parser.c:634]:
version: <SIP/2.0>
2(19413) DEBUG: <core>
[parser/parse_via.c:1288]: Found
param type 235, <rport> =
<n/a>; state=6
2(19413) DEBUG: <core>
[parser/parse_via.c:1288]: Found
param type 232, <branch> =
<z9hG4bKPj4ce735389e3f4d8aaa1a4891c1ec15a5>;
state=16
2(19413) DEBUG: <core>
[parser/parse_via.c:2563]: end of
header reached, state=5
2(19413) DEBUG: <core>
[parser/msg_parser.c:515]:
parse_headers: Via found, flags=2
2(19413) DEBUG: <core>
[parser/msg_parser.c:517]:
parse_headers: this is the first
via
2(19413) DEBUG: <core>
[receive.c:147]: After
parse_msg...
2(19413) DEBUG: <core>
[receive.c:188]: preparing to run
routing scripts...
2(19413) DEBUG: <core>
[parser/parse_to.c:177]: DEBUG:
add_param:
tag=b84abd4ec1314a7aae8acc436dc7e4a3
2(19413) DEBUG: <core>
[parser/parse_to.c:801]: end of
header reached, state=29
2(19413) DEBUG: <core>
[parser/parse_to.c:801]: end of
header reached, state=10
2(19413) DEBUG: <core>
[parser/msg_parser.c:187]: DEBUG:
get_hdr_field: <To> [25];
uri=[
sip:102@sip.wtz.local]
]
2(19413) DEBUG: <core>
[parser/msg_parser.c:167]:
get_hdr_field: cseq <CSeq>:
<11479> <INVITE>
2(19413) DEBUG: <core>
[parser/msg_parser.c:201]: DEBUG:
get_hdr_body : content_length=656
2(19413) DEBUG: <core>
[parser/msg_parser.c:103]: found
end of header
2(19413) DEBUG: <core>
[parser/sdp/sdp_helpr_funcs.c:493]:
located IP address [172.16.0.12]
in `o=' field
2(19413) DEBUG: <core>
[parser/sdp/sdp_helpr_funcs.c:493]:
located IP address [172.16.0.12]
in `c=' field
2(19413) DEBUG: <core>
[parser/sdp/sdp_helpr_funcs.c:493]:
located IP address [172.16.0.12]
in `c=' field
2(19413) DEBUG: sdpops
[sdpops_mod.c:657]: attempting to
search for media type: [audio]
2(19413) DEBUG: sdpops
[sdpops_mod.c:679]: stream 0 of 0
- media [audio]
2(19413) DEBUG: sdpops
[sdpops_mod.c:685]: removing media
stream: audio16(19433) :
<core> [pass_fd.c:293]:
ERROR: receive_fd: EOF on 11
16(19433) DEBUG: <core>
[tcp_main.c:3555]: DBG:
handle_ser_child: dead child 2,
pid 19413 (shutting down?)
16(19433) DEBUG: <core>
[io_wait.h:617]: DBG: io_watch_del
(0x8260fa0, 11, -1, 0x0) fd_no=21
called
0(19411) ALERT: <core>
[main.c:751]: child process 19413
exited by a signal 11
0(19411) ALERT: <core>
[main.c:754]: core was not
generated
0(19411) INFO: <core>
[main.c:766]: INFO: terminating
due to SIGCHLD
9(19424) INFO: <core>
[main.c:817]: INFO: signal 15
received
10(19425) INFO: <core>
[main.c:817]: INFO: signal 15
received
11(19426) INFO: <core>
[main.c:817]: INFO: signal 15
received
13(19429) INFO: <core>
[main.c:817]: INFO: signal 15
received
5(19416) INFO: <core>
[main.c:817]: INFO: signal 15
received
15(19432) INFO: <core>
[main.c:817]: INFO: signal 15
received
12(19428) INFO: <core>
[main.c:817]: INFO: signal 15
received
7(19418) INFO: <core>
[main.c:817]: INFO: signal 15
received
3(19414) INFO: <core>
[main.c:817]: INFO: signal 15
received
16(19433) INFO: <core>
[main.c:817]: INFO: signal 15
received
1(19412) INFO: <core>
[main.c:817]: INFO: signal 15
received
4(19415) INFO: <core>
[main.c:817]: INFO: signal 15
received
14(19430) INFO: <core>
[main.c:817]: INFO: signal 15
received
8(19423) INFO: <core>
[main.c:817]: INFO: signal 15
received
6(19417) INFO: <core>
[main.c:817]: INFO: signal 15
received
0(19411) ERROR: ctl
[ctl.c:379]: ERROR: ctl: could not
delete unix socket
/tmp/kamailio_ctl: Operation not
permitted (1)
0(19411) DEBUG: <core>
[db_pool.c:102]: removing
connection from the pool
0(19411) DEBUG: tm
[t_funcs.c:122]: DEBUG:
tm_shutdown : start
0(19411) DEBUG: tm
[t_funcs.c:125]: DEBUG:
tm_shutdown : emptying hash table
0(19411) DEBUG: tm
[t_funcs.c:127]: DEBUG:
tm_shutdown : removing semaphores
0(19411) DEBUG: tm
[t_funcs.c:129]: DEBUG:
tm_shutdown : destroying tmcb
lists
0(19411) DEBUG: tm
[t_funcs.c:132]: DEBUG:
tm_shutdown : done
0(19411) DEBUG: tls
[tls_init.c:674]: tls module final
tls destroy
0(19411) DEBUG: <core>
[mem/shm_mem.c:242]:
shm_mem_destroy
0(19411) DEBUG: <core>
[mem/shm_mem.c:245]: destroying
the shared memory lock
0(19411) DEBUG: <core>
[main.c:770]: terminating due to
SIGCHLD
2012/6/11
Daniel-Constantin Mierla
<miconda@gmail.com>
Hello,
On 6/11/12 3:19 PM,
Grégoire Vandendeurpel
wrote:
Hello,
I'm using Kamailio 3.2.3
and SDPOPS module.
I just have a question
about the configuration:
What do you mean by
"ANY_ROUTE" in
configuration ??
Cause I don't know where
in the configuration , I
have to use the
differents functions of
SDPOPS.
If I do something like
this:
request_route {
if ($fU == "103") {
sdp_remove_codecs_by_id("0,3,8");
#sdp_remove_media("audio");
}
It does not work .
So please help me about
where I have to put this
in??
it should work in that route
if it's a request with SDP.
Can you send the ngrep of
such SIP message along with
log messages when debug=3 in
your config?
Cheers,
Daniel
--
Daniel-Constantin Mierla
- http://www.asipto.com
http://twitter.com/#!/miconda
- http://www.linkedin.com/in/miconda
Kamailio Advanced
Training, Seattle, USA,
Sep 23-26, 2012 - http://asipto.com/u/katu
Kamailio Practical
Workshop, Netherlands,
Sep 10-12, 2012 - http://asipto.com/u/kpw
--
Grégoire
Vandendeurpel,
IT
Sector