[OpenSIPS-Devel] Reply INVITE

Anderson Catao andersoncatao at hotmail.com
Tue Mar 15 18:45:22 CET 2016 

Hello !

I'm having a problem that this disturbing me a lot and I have no idea how
can I solve this. I'm using my opensips as redirect server and some INVITE
that the server receives some times it doesn't answer and the most time its
works perfect... When Its occur I the server run my redirect script twice
and it must not occur, because every time that I run my script I need to pay
for that....

Opensips server: 192.168.1.100
Request device: 192.168.1.101
Opensips.cfg:

if (is_method("INVITE")) {
		# create dialog with timeout
		if ( !create_dialog("B") ) {
			send_reply("500","Internal Server Error");
			exit;
		}
		

			if ($tU == $rU)#
			{
			 setflag(ACC_DO); # do accounting
			 exec_dset("/usr/local/opensips_proxy/sbin/qryNumber $tU $Au $ci $rU");
			 sl_send_reply("302", "Moved Temporarily"); #
			 exit;
			} else {
			 sl_send_reply("302", "Moved Temporarily"); #
			 exit;
			}

	}



Wireshark:
<http://opensips-open-sip-server.1449251.n2.nabble.com/file/n7602043/wireshark.png> 

Opensips log debug 6:

Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:core:parse_msg: SIP Request:
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:core:parse_msg:  method:  <INVITE>
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:core:parse_msg:  uri:     <sip:1122334455 at 192.168.1.100:5060>
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:core:parse_msg:  version: <SIP/2.0>
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:core:parse_headers: flags=2
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:core:parse_via_param: found param type 232, <branch> =
<z9hG4bKbd0694a8328cef93a>; state=6
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:core:parse_via_param: found param type 235, <rport> = <n/a>; state=17
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:core:parse_via: end of header reached, state=5
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:core:parse_headers: via found, flags=2
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:core:parse_headers: this is the first via
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:core:receive_msg: After parse_msg...
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:core:receive_msg: preparing to run routing scripts...
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:core:parse_headers: flags=100
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:maxfwd:is_maxfwd_present: value = 70
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:core:parse_headers: flags=8
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:core:parse_to: end of header reached, state=10
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:core:parse_to: display={}, ruri={sip:1122334455 at 192.168.1.100}
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:core:get_hdr_field: <To> [31]; uri=[sip:1122334455 at 192.168.1.100]
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:core:get_hdr_field: to body [<sip:1122334455 at 192.168.1.100>#015#012]
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:uri:has_totag: no totag
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:core:parse_headers: flags=78
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:core:get_hdr_field: cseq <CSeq>: <593784255> <INVITE>
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:tm:t_lookup_request: start searching: hash=11896, isACK=0
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:tm:matching_3261: RFC3261 transaction matching failed
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:tm:t_lookup_request: no transaction found
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:core:parse_to_param: tag=f6a7bbd9a3
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:core:parse_to: end of header reached, state=29
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:core:parse_to: display={}, ruri={sip:11668899 at 192.168.1.100}
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:core:grep_sock_info: checking if host==us: 12==12 &&  [192.168.1.100] ==
[192.168.1.100]
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:core:grep_sock_info: checking if port 5060 matches port 5060
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:core:parse_headers: flags=200
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:core:get_hdr_field: content_length=305
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:core:get_hdr_field: found end of header
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:rr:find_first_route: No Route headers found
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:rr:loose_route: There is no Route HF
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:dialog:w_create_dialog2: bye on timeout activated
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:dialog:build_new_dlg: new dialog 0x7f1015b64be8
(c=3bd33335b2d69bbc,f=sip:11668899 at 192.168.1.100,t=sip:1122334455 at 192.168.1.100,ft=f6a7bbd9a3)
on hash 765
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:core:parse_headers: flags=ffffffffffffffff
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:dialog:init_leg_info: route_set , contact sip:11668899 at 192.168.1.101,
cseq 593784255 and bind_addr udp:192.168.1.100:5060
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:dialog:dlg_add_leg_info: set leg 0 for 0x7f1015b64be8: tag=<f6a7bbd9a3>
rcseq=<0>
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:dialog:link_dlg: ref dlg 0x7f1015b64be8 with 4 -> 4 in h_entry
0x7f1015b3a928 - 765
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:rr:add_rr_param: adding (;did=df2.a6344ad5) 0x7f1039926320
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:core:comp_scriptvar: str 20 : 1122334455
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:core:parse_headers: flags=ffffffffffffffff
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:core:parse_headers: flags=78
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:core:MD5StringArray: MD5 calculated: 84666ae5989b8bc1f33a40a39a4f0237
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:exec:print_hf_var: SIP_HF_CONTENT_LENGTH=305
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:exec:print_hf_var: SIP_HF_CONTENT_TYPE=application/sdp
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:exec:print_hf_var: SIP_HF_X_SIEMENS_CALL_TYPE=ST-insecure
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:exec:print_hf_var: SIP_HF_USER_AGENT=OpenScape Business M5T SIP
Stack/4.2.12.14
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:exec:print_hf_var:
SIP_HF_P_ASSERTED_IDENTITY=<sip:11668899 at 192.168.1.100>
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:exec:print_hf_var: SIP_HF_CONTACT=<sip:11668899 at 192.168.1.101>
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:exec:print_hf_var: SIP_HF_ALLOW=INVITE, ACK, OPTIONS, BYE, CANCEL,
REGISTER, INFO, UPDATE
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:exec:print_hf_var: SIP_HF_CSEQ=593784255 INVITE
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:exec:print_hf_var: SIP_HF_CALLID=3bd33335b2d69bbc
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:exec:print_hf_var: SIP_HF_TO=<sip:1122334455 at 192.168.1.100>
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:exec:print_hf_var:
SIP_HF_FROM=<sip:11668899 at 192.168.1.100>;tag=f6a7bbd9a3
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:exec:print_hf_var: SIP_HF_MAX_FORWARDS=69
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:exec:print_hf_var: SIP_HF_VIA=SIP/2.0/UDP
192.168.1.101;branch=z9hG4bKbd0694a8328cef93a;rport
Mar 15 12:30:46 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13658]:
DBG:exec:w_exec_dset: executing [/usr/local/opensips_proxy/sbin/qryNumber
1122334455 11668899 at 192.168.1.100 3bd33335b2d69bbc 1122334455]
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:core:parse_msg: SIP Request:
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:core:parse_msg:  method:  <INVITE>
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:core:parse_msg:  uri:     <sip:1122334455 at 192.168.1.100:5060>
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:core:parse_msg:  version: <SIP/2.0>
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:core:parse_headers: flags=2
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:core:parse_via_param: found param type 232, <branch> =
<z9hG4bKbd0694a8328cef93a>; state=6
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:core:parse_via_param: found param type 235, <rport> = <n/a>; state=17
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:core:parse_via: end of header reached, state=5
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:core:parse_headers: via found, flags=2
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:core:parse_headers: this is the first via
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:core:receive_msg: After parse_msg...
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:core:receive_msg: preparing to run routing scripts...
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:core:parse_headers: flags=100
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:maxfwd:is_maxfwd_present: value = 70
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:core:parse_headers: flags=8
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:core:parse_to: end of header reached, state=10
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:core:parse_to: display={}, ruri={sip:1122334455 at 192.168.1.100}
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:core:get_hdr_field: <To> [31]; uri=[sip:1122334455 at 192.168.1.100]
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:core:get_hdr_field: to body [<sip:1122334455 at 192.168.1.100>#015#012]
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:uri:has_totag: no totag
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:core:parse_headers: flags=78
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:core:get_hdr_field: cseq <CSeq>: <593784255> <INVITE>
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:tm:t_lookup_request: start searching: hash=11896, isACK=0
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:tm:matching_3261: RFC3261 transaction matching failed
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:tm:t_lookup_request: no transaction found
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:core:parse_to_param: tag=f6a7bbd9a3
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:core:parse_to: end of header reached, state=29
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:core:parse_to: display={}, ruri={sip:11668899 at 192.168.1.100}
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:core:grep_sock_info: checking if host==us: 12==12 &&  [192.168.1.100] ==
[192.168.1.100]
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:core:grep_sock_info: checking if port 5060 matches port 5060
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:core:parse_headers: flags=200
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:core:get_hdr_field: content_length=305
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:core:get_hdr_field: found end of header
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:rr:find_first_route: No Route headers found
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:rr:loose_route: There is no Route HF
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:dialog:w_create_dialog2: bye on timeout activated
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:dialog:build_new_dlg: new dialog 0x7f1015b65890
(c=3bd33335b2d69bbc,f=sip:11668899 at 192.168.1.100,t=sip:1122334455 at 192.168.1.100,ft=f6a7bbd9a3)
on hash 765
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:core:parse_headers: flags=ffffffffffffffff
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:dialog:init_leg_info: route_set , contact sip:11668899 at 192.168.1.101,
cseq 593784255 and bind_addr udp:192.168.1.100:5060
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:dialog:dlg_add_leg_info: set leg 0 for 0x7f1015b65890: tag=<f6a7bbd9a3>
rcseq=<0>
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:dialog:link_dlg: ref dlg 0x7f1015b65890 with 4 -> 4 in h_entry
0x7f1015b3a928 - 765
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:rr:add_rr_param: adding (;did=df2.b6344ad5) 0x7f1039925db0
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:core:comp_scriptvar: str 20 : 1122334455
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:core:parse_headers: flags=ffffffffffffffff
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:core:parse_headers: flags=78
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:core:MD5StringArray: MD5 calculated: 84666ae5989b8bc1f33a40a39a4f0237
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:exec:print_hf_var: SIP_HF_CONTENT_LENGTH=305
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:exec:print_hf_var: SIP_HF_CONTENT_TYPE=application/sdp
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:exec:print_hf_var: SIP_HF_X_SIEMENS_CALL_TYPE=ST-insecure
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:exec:print_hf_var: SIP_HF_USER_AGENT=OpenScape Business M5T SIP
Stack/4.2.12.14
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:exec:print_hf_var:
SIP_HF_P_ASSERTED_IDENTITY=<sip:11668899 at 192.168.1.100>
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:exec:print_hf_var: SIP_HF_CONTACT=<sip:11668899 at 192.168.1.101>
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:exec:print_hf_var: SIP_HF_ALLOW=INVITE, ACK, OPTIONS, BYE, CANCEL,
REGISTER, INFO, UPDATE
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:exec:print_hf_var: SIP_HF_CSEQ=593784255 INVITE
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:exec:print_hf_var: SIP_HF_CALLID=3bd33335b2d69bbc
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:exec:print_hf_var: SIP_HF_TO=<sip:1122334455 at 192.168.1.100>
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:exec:print_hf_var:
SIP_HF_FROM=<sip:11668899 at 192.168.1.100>;tag=f6a7bbd9a3
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:exec:print_hf_var: SIP_HF_MAX_FORWARDS=69
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:exec:print_hf_var: SIP_HF_VIA=SIP/2.0/UDP
192.168.1.101;branch=z9hG4bKbd0694a8328cef93a;rport
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:exec:w_exec_dset: executing [/usr/local/opensips_proxy/sbin/qryNumber
1122334455 11668899 at 192.168.1.100 3bd33335b2d69bbc 1122334455]
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:exec:exec_str: rtrim
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:core:sig_usr: SIGCHLD received: we do not worry about grand-children
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:core:parse_headers: flags=ffffffffffffffff
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:dialog:next_state_dlg: unref dlg 0x7f1015b65890 with 1 -> 3 in entry
0x7f1015b3a928
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:dialog:next_state_dlg: dialog 0x7f1015b65890 changed from state 1 to
state 5, due event 1
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:dialog:dlg_onreply: dialog 0x7f1015b65890 failed (negative reply)
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:dialog:unref_dlg: unref dlg 0x7f1015b65890 with 1 -> 2 in entry
0x7f1015b3a928
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:dialog:unref_dlg: unref dlg 0x7f1015b65890 with 1 -> 1 in entry
0x7f1015b3a928
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:core:destroy_avp_list: destroying list (nil)
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13657]:
DBG:core:receive_msg: cleaning up
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13660]:
DBG:core:parse_msg: SIP Request:
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13660]:
DBG:core:parse_msg:  method:  <ACK>
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13660]:
DBG:core:parse_msg:  uri:     <sip:1122334455 at 192.168.1.100:5060>
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13660]:
DBG:core:parse_msg:  version: <SIP/2.0>
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13660]:
DBG:core:parse_headers: flags=2
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13660]:
DBG:core:parse_via_param: found param type 232, <branch> =
<z9hG4bKbd0694a8328cef93a>; state=6
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13660]:
DBG:core:parse_via_param: found param type 235, <rport> = <n/a>; state=17
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13660]:
DBG:core:parse_via: end of header reached, state=5
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13660]:
DBG:core:parse_headers: via found, flags=2
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13660]:
DBG:core:parse_headers: this is the first via
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13660]:
DBG:core:receive_msg: After parse_msg...
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13660]:
DBG:core:receive_msg: preparing to run routing scripts...
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13660]:
DBG:core:parse_headers: flags=8
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13660]:
DBG:core:parse_to_param: tag=4f21ef5ebb6916326d7149cb22285bd2.dfb7
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13660]:
DBG:core:parse_to: end of header reached, state=29
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13660]:
DBG:core:parse_to: display={}, ruri={sip:1122334455 at 192.168.1.100}
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13660]:
DBG:core:get_hdr_field: <To> [73]; uri=[sip:1122334455 at 192.168.1.100]
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13660]:
DBG:core:get_hdr_field: to body [<sip:1122334455 at 192.168.1.100>]
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13660]:
DBG:sl:sl_filter_ACK: local ACK found -> dropping it!
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13660]:
DBG:core:destroy_avp_list: destroying list (nil)
Mar 15 12:30:47 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13660]:
DBG:core:receive_msg: cleaning up
Mar 15 12:30:48 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13662]:
DBG:dialog:dlg_timer_remove_from_db: triggering delete for 1 dialogs
Mar 15 12:30:48 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13662]:
DBG:db_mysql:has_stmt_ctx: ctx found for dialog
Mar 15 12:30:48 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13662]:
DBG:db_mysql:db_mysql_do_prepared_query: conn=0x7f10399240d0
(tail=139707662069304) MC=0x7f1039923758
Mar 15 12:30:48 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13662]:
DBG:db_mysql:db_mysql_do_prepared_query: set values for the statement run
Mar 15 12:30:48 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13662]:
DBG:db_mysql:db_mysql_val2bind: added val (0): len=8; type=8; is_null=0
Mar 15 12:30:48 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13662]:
DBG:db_mysql:db_mysql_do_prepared_query: doing BIND_PARAM in...
Mar 15 12:30:48 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13662]:
DBG:db_mysql:mysql_raise_event: MySQL status has not changed: connected
Mar 15 12:30:48 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13662]:
DBG:dialog:dlg_timer_remove_from_db: unref dlg 0x7f1015b65890 with 1 -> 0 in
entry 0x7f1015b3a928
Mar 15 12:30:48 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13662]:
DBG:dialog:dlg_timer_remove_from_db: ref <=0 for dialog 0x7f1015b65890
Mar 15 12:30:48 SRVOPS /usr/local/opensips_proxy/sbin/opensips[13662]:
DBG:dialog:destroy_dlg: destroing dialog 0x7f1015b65890




--
View this message in context: http://opensips-open-sip-server.1449251.n2.nabble.com/Reply-INVITE-tp7602043.html
Sent from the OpenSIPS - Devel mailing list archive at Nabble.com.

More information about the Devel mailing list