[OpenSIPS-Users] not trigger on_failure_route[0] when use mi interface

chow zhouxiaoqiang.mstech at gmail.com
Tue Apr 14 09:53:50 CEST 2015


Hi:
     I use  t_uac_dlg send message.
     the destination  user in my location table.  actually,this user is
offline and not send  unregister。
     then, I sent  lots of message to this user,  only previous message  can 
trigger on_failure_route[].
     
     some  log  like this:
     Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_server: received
:t_uac_dlg:#012MESSAGE#012sip:rh000000000002063 at ivoip.ronghe.tv#012sip:172.17.0.18:8060#012.#012"From:
sip:iCast_weixin at ivoip.ronghe.tv#015#012To:
sip:rh000000000002063 at ivoip.ronghe.tv#015#012Content-Type:
Multipart/Related#015#012"#012"--55ca910c3bd0e785f97e77b8fd9e4ec8#015#012Content-Type:
text/plain#015#012#015#012P-ICast-Message-Expire:
1429164748#015#012P-ICast-WeiXin-NickName:
孙悟空#015#012#015#012--55ca910c3bd0e785f97e77b8fd9e4ec8#015#012Content-Type:
application/xml#015#012#015#012<xml><ToUserName></ToUserName>#012<FromUserName></FromUserName>#012<CreateTime>1428991948</CreateTime>#012<MsgType></MsgType>#012<Content></Content>#012<MsgId>6137473683113663203</MsgId>#012</xml>#015#012--55ca910c3bd0e785f97e77b8fd9e4ec8--#015#012"#012#012
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_server: mi_buf is
:t_uac_dlg:#012MESSAGE#012sip:rh000000000002063 at ivoip.ronghe.tv#012sip:172.17.0.18:8060#012.#012"From:
sip:iCast_weixin at ivoip.ronghe.tv#015#012To:
sip:rh000000000002063 at ivoip.ronghe.tv#015#012Content-Type:
Multipart/Related#015#012"#012"--55ca910c3bd0e785f97e77b8fd9e4ec8#015#012Content-Type:
text/plain#015#012#015#012P-ICast-Message-Expire:
1429164748#015#012P-ICast-WeiXin-NickName:
孙悟空#015#012#015#012--55ca910c3bd0e785f97e77b8fd9e4ec8#015#012Content-Type:
application/xml#015#012#015#012<xml><ToUserName></ToUserName>#012<FromUserName></FromUserName>#012<CreateTime>1428991948</CreateTime>#012<MsgType></MsgType>#012<Content></Content>#012<MsgId>6137473683113663203</MsgId>#012</xml>#015#012--55ca910c3bd0e785f97e77b8fd9e4ec8--#015#012"#012#012
and we have received 726 bytes
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:identify_command: the command starts here:
t_uac_dlg:#012MESSAGE#012sip:rh000000000002063 at ivoip.ronghe.tv#012sip:172.17.0.18:8060#012.#012"From:
sip:iCast_weixin at ivoip.ronghe.tv#015#012To:
sip:rh000000000002063 at ivoip.ronghe.tv#015#012Content-Type:
Multipart/Related#015#012"#012"--55ca910c3bd0e785f97e77b8fd9e4ec8#015#012Content-Type:
text/plain#015#012#015#012P-ICast-Message-Expire:
1429164748#015#012P-ICast-WeiXin-NickName:
孙悟空#015#012#015#012--55ca910c3bd0e785f97e77b8fd9e4ec8#015#012Content-Type:
application/xml#015#012#015#012<xml><ToUserName></ToUserName>#012<FromUserName></FromUserName>#012<CreateTime>1428991948</CreateTime>#012<MsgType></MsgType>#012<Content></Content>#012<MsgId>6137473683113663203</MsgId>#012</xml>#015#012--55ca910c3bd0e785f97e77b8fd9e4ec8--#015#012"#012#012
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:identify_command: the command is t_uac_dlg
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:identify_command: dtgram->len is 726
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:identify_command: dtgram->len is 714
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_server: we have a valid command 
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_server: after identifing the command, the
received datagram is 
MESSAGE#012sip:rh000000000002063 at ivoip.ronghe.tv#012sip:172.17.0.18:8060#012.#012"From:
sip:iCast_weixin at ivoip.ronghe.tv#015#012To:
sip:rh000000000002063 at ivoip.ronghe.tv#015#012Content-Type:
Multipart/Related#015#012"#012"--55ca910c3bd0e785f97e77b8fd9e4ec8#015#012Content-Type:
text/plain#015#012#015#012P-ICast-Message-Expire:
1429164748#015#012P-ICast-WeiXin-NickName:
孙悟空#015#012#015#012--55ca910c3bd0e785f97e77b8fd9e4ec8#015#012Content-Type:
application/xml#015#012#015#012<xml><ToUserName></ToUserName>#012<FromUserName></FromUserName>#012<CreateTime>1428991948</CreateTime>#012<MsgType></MsgType>#012<Content></Content>#012<MsgId>6137473683113663203</MsgId>#012</xml>#015#012--55ca910c3bd0e785f97e77b8fd9e4ec8--#015#012"#012#012
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_server: parsing the command's params
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_node: the remaining datagram to be parsed
is
MESSAGE#012sip:rh000000000002063 at ivoip.ronghe.tv#012sip:172.17.0.18:8060#012.#012"From:
sip:iCast_weixin at ivoip.ronghe.tv#015#012To:
sip:rh000000000002063 at ivoip.ronghe.tv#015#012Content-Type:
Multipart/Related#015#012"#012"--55ca910c3bd0e785f97e77b8fd9e4ec8#015#012Content-Type:
text/plain#015#012#015#012P-ICast-Message-Expire:
1429164748#015#012P-ICast-WeiXin-NickName:
孙悟空#015#012#015#012--55ca910c3bd0e785f97e77b8fd9e4ec8#015#012Content-Type:
application/xml#015#012#015#012<xml><ToUserName></ToUserName>#012<FromUserName></FromUserName>#012<CreateTime>1428991948</CreateTime>#012<MsgType></MsgType>#012<Content></Content>#012<MsgId>6137473683113663203</MsgId>#012</xml>#015#012--55ca910c3bd0e785f97e77b8fd9e4ec8--#015#012"#012#012
and 714 in length 
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_node: the string is not just a quoted
string
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_node: found newline before
attr_separator--we have just the attribute's value
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_node: empty name, attr not quoted value
<MESSAGE> found
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_node: 1 data->len is 714
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_node: 2 data->len is 706
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_tree: adding node <> ; val <MESSAGE>
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_tree: the remaining datagram has 706 bytes
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_node: the remaining datagram to be parsed
is
sip:rh000000000002063 at ivoip.ronghe.tv#012sip:172.17.0.18:8060#012.#012"From:
sip:iCast_weixin at ivoip.ronghe.tv#015#012To:
sip:rh000000000002063 at ivoip.ronghe.tv#015#012Content-Type:
Multipart/Related#015#012"#012"--55ca910c3bd0e785f97e77b8fd9e4ec8#015#012Content-Type:
text/plain#015#012#015#012P-ICast-Message-Expire:
1429164748#015#012P-ICast-WeiXin-NickName:
孙悟空#015#012#015#012--55ca910c3bd0e785f97e77b8fd9e4ec8#015#012Content-Type:
application/xml#015#012#015#012<xml><ToUserName></ToUserName>#012<FromUserName></FromUserName>#012<CreateTime>1428991948</CreateTime>#012<MsgType></MsgType>#012<Content></Content>#012<MsgId>6137473683113663203</MsgId>#012</xml>#015#012--55ca910c3bd0e785f97e77b8fd9e4ec8--#015#012"#012#012
and 706 in length 
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_node: the string is not just a quoted
string
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_node: found newline before
attr_separator--we have just the attribute's value
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_node: empty name, attr not quoted value
<sip:rh000000000002063 at ivoip.ronghe.tv> found
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_node: 1 data->len is 706
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_node: 2 data->len is 668
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_tree: adding node <> ; val
<sip:rh000000000002063 at ivoip.ronghe.tv>
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_tree: the remaining datagram has 668 bytes
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_node: the remaining datagram to be parsed
is sip:172.17.0.18:8060#012.#012"From:
sip:iCast_weixin at ivoip.ronghe.tv#015#012To:
sip:rh000000000002063 at ivoip.ronghe.tv#015#012Content-Type:
Multipart/Related#015#012"#012"--55ca910c3bd0e785f97e77b8fd9e4ec8#015#012Content-Type:
text/plain#015#012#015#012P-ICast-Message-Expire:
1429164748#015#012P-ICast-WeiXin-NickName:
孙悟空#015#012#015#012--55ca910c3bd0e785f97e77b8fd9e4ec8#015#012Content-Type:
application/xml#015#012#015#012<xml><ToUserName></ToUserName>#012<FromUserName></FromUserName>#012<CreateTime>1428991948</CreateTime>#012<MsgType></MsgType>#012<Content></Content>#012<MsgId>6137473683113663203</MsgId>#012</xml>#015#012--55ca910c3bd0e785f97e77b8fd9e4ec8--#015#012"#012#012
and 668 in length 
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_node: the string is not just a quoted
string
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_node: found newline before
attr_separator--we have just the attribute's value
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_node: empty name, attr not quoted value
<sip:172.17.0.18:8060> found
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_node: 1 data->len is 668
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_node: 2 data->len is 647
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_tree: adding node <> ; val
<sip:172.17.0.18:8060>
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_tree: the remaining datagram has 647 bytes
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_node: the remaining datagram to be parsed
is .#012"From: sip:iCast_weixin at ivoip.ronghe.tv#015#012To:
sip:rh000000000002063 at ivoip.ronghe.tv#015#012Content-Type:
Multipart/Related#015#012"#012"--55ca910c3bd0e785f97e77b8fd9e4ec8#015#012Content-Type:
text/plain#015#012#015#012P-ICast-Message-Expire:
1429164748#015#012P-ICast-WeiXin-NickName:
孙悟空#015#012#015#012--55ca910c3bd0e785f97e77b8fd9e4ec8#015#012Content-Type:
application/xml#015#012#015#012<xml><ToUserName></ToUserName>#012<FromUserName></FromUserName>#012<CreateTime>1428991948</CreateTime>#012<MsgType></MsgType>#012<Content></Content>#012<MsgId>6137473683113663203</MsgId>#012</xml>#015#012--55ca910c3bd0e785f97e77b8fd9e4ec8--#015#012"#012#012
and 647 in length 
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_node: the string is not just a quoted
string
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_node: found newline before
attr_separator--we have just the attribute's value
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_node: empty name, attr not quoted value
<.> found
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_node: 1 data->len is 647
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_node: 2 data->len is 645
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_tree: adding node <> ; val <.>
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_tree: the remaining datagram has 645 bytes
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_node: the remaining datagram to be parsed
is "From: sip:iCast_weixin at ivoip.ronghe.tv#015#012To:
sip:rh000000000002063 at ivoip.ronghe.tv#015#012Content-Type:
Multipart/Related#015#012"#012"--55ca910c3bd0e785f97e77b8fd9e4ec8#015#012Content-Type:
text/plain#015#012#015#012P-ICast-Message-Expire:
1429164748#015#012P-ICast-WeiXin-NickName:
孙悟空#015#012#015#012--55ca910c3bd0e785f97e77b8fd9e4ec8#015#012Content-Type:
application/xml#015#012#015#012<xml><ToUserName></ToUserName>#012<FromUserName></FromUserName>#012<CreateTime>1428991948</CreateTime>#012<MsgType></MsgType>#012<Content></Content>#012<MsgId>6137473683113663203</MsgId>#012</xml>#015#012--55ca910c3bd0e785f97e77b8fd9e4ec8--#015#012"#012#012
and 645 in length 
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_node: we have a  quoted value, "From:
sip:iCast_weixin at ivoip.ronghe.tv#015#012To:
sip:rh000000000002063 at ivoip.ronghe.tv#015#012Content-Type:
Multipart/Related#015#012"#012"--55ca910c3bd0e785f97e77b8fd9e4ec8#015#012Content-Type:
text/plain#015#012#015#012P-ICast-Message-Expire:
1429164748#015#012P-ICast-WeiXin-NickName:
孙悟空#015#012#015#012--55ca910c3bd0e785f97e77b8fd9e4ec8#015#012Content-Type:
application/xml#015#012#015#012<xml><ToUserName></ToUserName>#012<FromUserName></FromUserName>#012<CreateTime>1428991948</CreateTime>#012<MsgType></MsgType>#012<Content></Content>#012<MsgId>6137473683113663203</MsgId>#012</xml>#015#012--55ca910c3bd0e785f97e77b8fd9e4ec8--#015#012"#012#012
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_node: " found p is
"#012"--55ca910c3bd0e785f97e77b8fd9e4ec8#015#012Content-Type:
text/plain#015#012#015#012P-ICast-Message-Expire:
1429164748#015#012P-ICast-WeiXin-NickName:
孙悟空#015#012#015#012--55ca910c3bd0e785f97e77b8fd9e4ec8#015#012Content-Type:
application/xml#015#012#015#012<xml><ToUserName></ToUserName>#012<FromUserName></FromUserName>#012<CreateTime>1428991948</CreateTime>#012<MsgType></MsgType>#012<Content></Content>#012<MsgId>6137473683113663203</MsgId>#012</xml>#015#012--55ca910c3bd0e785f97e77b8fd9e4ec8--#015#012"#012#012
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_node: we have reached the end of attr
value, p is "#012"--55ca910c3bd0e785f97e77b8fd9e4ec8#015#012Content-Type:
text/plain#015#012#015#012P-ICast-Message-Expire:
1429164748#015#012P-ICast-WeiXin-NickName:
孙悟空#015#012#015#012--55ca910c3bd0e785f97e77b8fd9e4ec8#015#012Content-Type:
application/xml#015#012#015#012<xml><ToUserName></ToUserName>#012<FromUserName></FromUserName>#012<CreateTime>1428991948</CreateTime>#012<MsgType></MsgType>#012<Content></Content>#012<MsgId>6137473683113663203</MsgId>#012</xml>#015#012--55ca910c3bd0e785f97e77b8fd9e4ec8--#015#012"#012#012
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_node: attr value <From:
sip:iCast_weixin at ivoip.ronghe.tv#015#012To:
sip:rh000000000002063 at ivoip.ronghe.tv#015#012Content-Type:
Multipart/Related#015#012> found
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_node: line ended properly case1
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_node: 1 data->len is 645
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_node: 2 data->len is 527
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_tree: adding node <> ; val <From:
sip:iCast_weixin at ivoip.ronghe.tv#015#012To:
sip:rh000000000002063 at ivoip.ronghe.tv#015#012Content-Type:
Multipart/Related#015#012>
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_tree: the remaining datagram has 527 bytes
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_node: the remaining datagram to be parsed
is #012"--55ca910c3bd0e785f97e77b8fd9e4ec8#015#012Content-Type:
text/plain#015#012#015#012P-ICast-Message-Expire:
1429164748#015#012P-ICast-WeiXin-NickName:
孙悟空#015#012#015#012--55ca910c3bd0e785f97e77b8fd9e4ec8#015#012Content-Type:
application/xml#015#012#015#012<xml><ToUserName></ToUserName>#012<FromUserName></FromUserName>#012<CreateTime>1428991948</CreateTime>#012<MsgType></MsgType>#012<Content></Content>#012<MsgId>6137473683113663203</MsgId>#012</xml>#015#012--55ca910c3bd0e785f97e77b8fd9e4ec8--#015#012"#012#012
and 527 in length 
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_node: we have a  quoted value,
"--55ca910c3bd0e785f97e77b8fd9e4ec8#015#012Content-Type:
text/plain#015#012#015#012P-ICast-Message-Expire:
1429164748#015#012P-ICast-WeiXin-NickName:
孙悟空#015#012#015#012--55ca910c3bd0e785f97e77b8fd9e4ec8#015#012Content-Type:
application/xml#015#012#015#012<xml><ToUserName></ToUserName>#012<FromUserName></FromUserName>#012<CreateTime>1428991948</CreateTime>#012<MsgType></MsgType>#012<Content></Content>#012<MsgId>6137473683113663203</MsgId>#012</xml>#015#012--55ca910c3bd0e785f97e77b8fd9e4ec8--#015#012"#012#012
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_node: " found p is "#012#012
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_node: we have reached the end of attr
value, p is "#012#012
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_node: attr value
<--55ca910c3bd0e785f97e77b8fd9e4ec8#015#012Content-Type:
text/plain#015#012#015#012P-ICast-Message-Expire:
1429164748#015#012P-ICast-WeiXin-NickName:
孙悟空#015#012#015#012--55ca910c3bd0e785f97e77b8fd9e4ec8#015#012Content-Type:
application/xml#015#012#015#012<xml><ToUserName></ToUserName>#012<FromUserName></FromUserName>#012<CreateTime>1428991948</CreateTime>#012<MsgType></MsgType>#012<Content></Content>#012<MsgId>6137473683113663203</MsgId>#012</xml>#015#012--55ca910c3bd0e785f97e77b8fd9e4ec8--#015#012>
found
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_node: line ended properly case1
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_node: 1 data->len is 527
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_node: 2 data->len is 2
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_tree: adding node <> ; val
<--55ca910c3bd0e785f97e77b8fd9e4ec8#015#012Content-Type:
text/plain#015#012#015#012P-ICast-Message-Expire:
1429164748#015#012P-ICast-WeiXin-NickName:
孙悟空#015#012#015#012--55ca910c3bd0e785f97e77b8fd9e4ec8#015#012Content-Type:
application/xml#015#012#015#012<xml><ToUserName></ToUserName>#012<FromUserName></FromUserName>#012<CreateTime>1428991948</CreateTime>#012<MsgType></MsgType>#012<Content></Content>#012<MsgId>6137473683113663203</MsgId>#012</xml>#015#012--55ca910c3bd0e785f97e77b8fd9e4ec8--#015#012>
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_tree: the remaining datagram has 2 bytes
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_parse_node: the remaining datagram to be parsed
is #012#012 and 2 in length 
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_server: done parsing the mi tree
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: DBG:core:parse_headers:
flags=ffffffffffffffff
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: DBG:core:parse_to: end of
header reached, state=9
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: DBG:core:parse_to:
display={}, ruri={sip:rh000000000002063 at ivoip.ronghe.tv}
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: DBG:core:get_hdr_field:
<To> [39]; uri=[sip:rh000000000002063 at ivoip.ronghe.tv] 
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: DBG:core:get_hdr_field: to
body [sip:rh000000000002063 at ivoip.ronghe.tv#015#012]
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: DBG:core:parse_to: end of
header reached, state=9
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: DBG:core:parse_to:
display={}, ruri={sip:iCast_weixin at ivoip.ronghe.tv}
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: DBG:tm:get_hfblock: one
more hf processed
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: DBG:tm:t_uac:
next_hop=<sip:172.17.0.18:8060>
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: DBG:core:mk_proxy: doing
DNS lookup...
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: DBG:core:get_out_socket:
socket determined: 0x7f1796d7f058
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: DBG:tm:t_uac: sending
socket is 172.17.0.18 
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: DBG:tm:dlg2hash: 30120
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: DBG:tm:print_request_uri:
sip:rh000000000002063 at ivoip.ronghe.tv
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: DBG:tm:t_uac: building
sip_msg from buffer
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: DBG:core:parse_msg: SIP
Request:
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: DBG:core:parse_msg: 
method:  <MESSAGE>
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: DBG:core:parse_msg:  uri:    
<sip:rh000000000002063 at ivoip.ronghe.tv>
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: DBG:core:parse_msg: 
version: <SIP/2.0>
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: DBG:core:parse_headers:
flags=2
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: DBG:core:parse_via_param:
found param type 232, <branch> = <z9hG4bK8a57.55d0b014.0>; state=16
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: DBG:core:parse_via: end of
header reached, state=5
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: DBG:core:parse_headers: via
found, flags=2
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: DBG:core:parse_headers:
this is the first via
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: DBG:core:parse_headers:
flags=10
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: DBG:core:parse_to: end of
header reached, state=9
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: DBG:core:parse_to:
display={}, ruri={sip:rh000000000002063 at ivoip.ronghe.tv}
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: DBG:core:get_hdr_field:
<To> [39]; uri=[sip:rh000000000002063 at ivoip.ronghe.tv] 
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: DBG:core:get_hdr_field: to
body [sip:rh000000000002063 at ivoip.ronghe.tv#015#012]
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: DBG:core:parse_to_param:
tag=533cb9e91f4b999cf76861cbb9ed54ed-ddb0
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: DBG:core:parse_to: end of
header reached, state=29
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: DBG:core:parse_to:
display={}, ruri={sip:iCast_weixin at ivoip.ronghe.tv}
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: on local
route:[MESSAGE,from:iCast_weixin,to:rh000000000002063]
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: DBG:core:parse_headers:
flags=ffffffffffffffff
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: DBG:core:get_hdr_field:
cseq <CSeq>: <10> <MESSAGE>
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: DBG:core:get_hdr_field:
content_length=522
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: DBG:core:get_hdr_field:
found end of header
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: DBG:core:mk_proxy: doing
DNS lookup...
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: DBG:core:extract_ftc_hdrs:
flags = 15
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: DBG:core:extract_ftc_hdrs:
hdr 2 extracted as <To: sip:rh000000000002063 at ivoip.ronghe.tv#015#012>
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: DBG:core:extract_ftc_hdrs:
hdr 1 extracted as <From:
<sip:iCast_weixin at ivoip.ronghe.tv>;tag=533cb9e91f4b999cf76861cbb9ed54ed-ddb0#015#012>
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: DBG:core:extract_ftc_hdrs:
hdr 8 extracted as <Call-ID: 68871dad3fa4440a-36928 at 127.0.0.1#015#012>
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: DBG:tm:set_timer: relative
timeout is 500000
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: DBG:tm:insert_timer_unsafe:
[4]: 0x7f17883a8240 (235300000)
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]: DBG:tm:insert_timer_unsafe:
[0]: 0x7f17883a8270 (239)
Apr 14 14:12:28 ivoip /usr/sbin/opensips[36928]:
DBG:mi_datagram:mi_datagram_server: command process (t_uac_dlg)succeded
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]: DBG:tm:utimer_routine:
timer routine:6,tl=0x7f17883aa0b8 next=(nil), timeout=235100000
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]:
DBG:tm:retransmission_handler: retransmission_handler : request resending
(t=0x7f17883a9e98, MESSAGE s ... )
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]: DBG:tm:set_timer: relative
timeout is 4000000
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]: DBG:tm:insert_timer_unsafe:
[7]: 0x7f17883aa0b8 (239100000)
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]:
DBG:tm:retransmission_handler: retransmission_handler : done
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]: DBG:tm:utimer_routine:
timer routine:4,tl=0x7f17883a8240 next=(nil), timeout=235300000
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]:
DBG:tm:retransmission_handler: retransmission_handler : request resending
(t=0x7f17883a8020, MESSAGE s ... )
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]: DBG:tm:set_timer: relative
timeout is 1000000
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]: DBG:tm:insert_timer_unsafe:
[5]: 0x7f17883a8240 (236300000)
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]:
DBG:tm:retransmission_handler: retransmission_handler : done
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]: DBG:tm:timer_routine: timer
routine:0,tl=0x7f1788392bb0 next=0x7f178839d710, timeout=235
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]:
DBG:tm:final_response_handler: Cancel sent out, sending 408 (0x7f1788392960)
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]:
DBG:tm:t_should_relay_response: T_code=0, new_code=408
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]: DBG:tm:t_pick_branch:
picked branch 0, code 408 (prio=800)
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]: DBG:tm:local_reply:
branch=0, save=0, winner=0
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]: DBG:tm:local_reply: local
transaction completed
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]: DBG:tm:run_trans_callbacks:
trans=0x7f1788392960, callback type 256, id 0 entered
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]: DBG:tm:mi_uac_dlg_hdl: MI
UAC generated status 408
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]: DBG:tm:mi_uac_dlg_hdl:
mi_callback successfully completed
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]:
DBG:mi_datagram:mi_datagram_write_node: writing the name <> and value <408
Request Timeout> 
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]:
DBG:mi_datagram:mi_datagram_write_node: writing the name <> and value <.> 
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]:
DBG:mi_datagram:mi_datagram_write_node: writing the name <> and value <.> 
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]:
DBG:mi_datagram:mi_datagram_write_node: writing the name <> and value <.> 
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]:
DBG:mi_datagram:mi_datagram_write_node: writing the name <> and value <.> 
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]:
DBG:mi_datagram:datagram_close_async: the response is 200 OK#012408 Request
Timeout#012.#012.#012.#012.
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]:
DBG:mi_datagram:datagram_close_async: the response: 200 OK#012408 Request
Timeout#012.#012.#012.#012.#012 has been sent in 35 octets
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]: DBG:tm:insert_timer_unsafe:
[2]: 0x7f17883929e0 (240)
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]:
DBG:tm:final_response_handler: done
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]: DBG:tm:timer_routine: timer
routine:0,tl=0x7f178839d710 next=(nil), timeout=235
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]:
DBG:tm:final_response_handler: Cancel sent out, sending 408 (0x7f178839d4c0)
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]:
DBG:tm:t_should_relay_response: T_code=0, new_code=408
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]: DBG:tm:t_pick_branch:
picked branch 0, code 408 (prio=800)
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]: DBG:tm:local_reply:
branch=0, save=0, winner=0
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]: DBG:tm:local_reply: local
transaction completed
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]: DBG:tm:run_trans_callbacks:
trans=0x7f178839d4c0, callback type 256, id 0 entered
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]: DBG:tm:mi_uac_dlg_hdl: MI
UAC generated status 408
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]: DBG:tm:mi_uac_dlg_hdl:
mi_callback successfully completed
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]:
DBG:mi_datagram:mi_datagram_write_node: writing the name <> and value <408
Request Timeout> 
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]:
DBG:mi_datagram:mi_datagram_write_node: writing the name <> and value <.> 
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]:
DBG:mi_datagram:mi_datagram_write_node: writing the name <> and value <.> 
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]:
DBG:mi_datagram:mi_datagram_write_node: writing the name <> and value <.> 
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]:
DBG:mi_datagram:mi_datagram_write_node: writing the name <> and value <.> 
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]:
DBG:mi_datagram:datagram_close_async: the response is 200 OK#012408 Request
Timeout#012.#012.#012.#012.
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]:
DBG:mi_datagram:datagram_close_async: the response: 200 OK#012408 Request
Timeout#012.#012.#012.#012.#012 has been sent in 35 octets
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]: DBG:tm:insert_timer_unsafe:
[2]: 0x7f178839d540 (240)
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]:
DBG:tm:final_response_handler: done
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]: DBG:tm:timer_routine: timer
routine:2,tl=0x7f17883af6c8 next=0x7f17883b2c00, timeout=235
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]: DBG:tm:wait_handler:
removing 0x7f17883af648 from table 
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]: DBG:tm:delete_cell: delete
transaction 0x7f17883af648
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]: DBG:tm:wait_handler: done
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]: DBG:tm:timer_routine: timer
routine:2,tl=0x7f17883b2c00 next=(nil), timeout=235
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]: DBG:tm:wait_handler:
removing 0x7f17883b2b80 from table 
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]: DBG:tm:delete_cell: delete
transaction 0x7f17883b2b80
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]: DBG:tm:wait_handler: done
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]: DBG:tm:utimer_routine:
timer routine:5,tl=0x7f178838a298 next=(nil), timeout=235500000
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]:
DBG:tm:retransmission_handler: retransmission_handler : request resending
(t=0x7f178838a078, MESSAGE s ... )
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]: DBG:tm:set_timer: relative
timeout is 2000000
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]: DBG:tm:insert_timer_unsafe:
[6]: 0x7f178838a298 (237500000)
Apr 14 14:12:29 ivoip /usr/sbin/opensips[36950]:
DBG:tm:retransmission_handler: retransmission_handler : done
A



--
View this message in context: http://opensips-open-sip-server.1449251.n2.nabble.com/not-trigger-on-failure-route-0-when-use-mi-interface-tp7596480.html
Sent from the OpenSIPS - Users mailing list archive at Nabble.com.



More information about the Users mailing list