19 Aug
2004
19 Aug
'04
5:33 p.m.
Hi , I am trying to add Voicemail services... the problem is that when the call is missed
(busy, not answered or not connected) I can see some debug in ser´s
second instance for users in "voicemail group" but the call is closed and a busy
tone return to the caller...
you can see my 3 config files in the following url and details from both ser debugs,
please someone send me some advice.
Config files in:
http://rrisco.pub.millicom.com.pe/ser_config_files.txt
- ser.cfg for Main SER running on 5060
- voicemail.cfg for second instance on port 5090
- sems.conf for Media Server (sems)
DEBUG from 1st instamce (port 5060)
---------------------------------------
4(31737) DEBUG: relay_reply: branch=1, save=0, relay=1
4(31737) old size: 669, new size: 607
4(31737) build_res_from_sip_res: copied size: orig:108, new: 46, rest: 561 msg=
SIP/2.0 404 not reponsible for host in r-uri
Via: SIP/2.0/UDP 10.0.0.236:5060;branch=z9hG4bKa200bcf5a4114
From: <sip:6603000@call.millicom.com.pe>;tag=a200bcf5a4
To: <sip:6604000@call.millicom.com.pe>;tag=3749ec7003921b5c92fe06c5dc660395.5093
Call-ID: a2609600-48ac-bcf1-81f5-0002a40055b2(a)10.0.0.236
CSeq: 114 INVITE
Server: Sip EXpress router (0.8.14 (i386/linux))
Content-Length: 0
Warning: 392 200.110.2.131:5090 "Noisy feedback tells: pid=31707
req_src_ip=200.110.2.131 req_src_port=5060 in_uri=sip:6604000@call.millicom.com.pe:5090
out_uri=sip:6604000@call.millicom.com.pe:5090 via_cnt==2"
4(31737) DEBUG: reply relayed. buf=0x80dec48: SIP/2.0 4..., shmem=0x422be8a8: SIP/2.0 4
4(31737) DBG: callback type 7, id 1 entered
4(31737) DEBUG: cleanup_uacs: RETR/FR timers reset
4(31737) DEBUG: add_to_tail_of_timer[4]: 0x422c287c
4(31737) DEBUG: add_to_tail_of_timer[0]: 0x422c2890
4(31737) DEBUG:destroy_avp_list: destroing list (nil)
4(31737) receive_msg: cleaning up
[root@gkproxy01 admin]#
[root@gkproxy01 admin]# 2(31735) SIP Request:
2(31735) method: <ACK>
2(31735) uri: <sip:6604000@call.millicom.com.pe>
2(31735) version: <SIP/2.0>
2(31735) parse_headers: flags=1
2(31735) Found param type 232, <branch> = <z9hG4bKa200bcf5a4114>; state=16
2(31735) end of header reached, state=5
2(31735) parse_headers: Via found, flags=1
2(31735) parse_headers: this is the first via
2(31735) After parse_msg...
2(31735) preparing to run routing scripts...
2(31735) DEBUG : sl_filter_ACK: to late to be a local ACK!
2(31735) DEBUG : is_maxfwd_present: searching for max_forwards header
2(31735) parse_headers: flags=128
2(31735) DEBUG: add_param: tag=3749ec7003921b5c92fe06c5dc660395.5093
2(31735) end of header reached, state=29
2(31735) DEBUG: get_hdr_field: <To> [78]; uri=[sip:6604000@call.millicom.com.pe]
2(31735) DEBUG: to body [<sip:6604000@call.millicom.com.pe>]
2(31735) get_hdr_field: cseq <CSeq>: <114> <ACK>
2(31735) DEBUG: get_hdr_body : content_length=0
2(31735) DEBUG: is_maxfwd_present: value = 70
2(31735) DEBUG: add_param: tag=a200bcf5a4
2(31735) end of header reached, state=29
2(31735) parse_headers: flags=256
2(31735) found end of header
2(31735) find_first_route(): No Route headers found
2(31735) loose_route(): There is no Route HF
2(31735) check_self - checking if host==us: 20==13 && [call.millicom.com.pe] ==
[200.110.2.131]
2(31735) check_self - checking if port 5060 matches port 5060
2(31735) check_self - checking if host==us: 20==9 && [call.millicom.com.pe] ==
[127.0.0.1]
2(31735) check_self - checking if port 5060 matches port 5060
2(31735) query="select grp from grp where username='6604000' AND
grp='voicemail'"
2(31735) is_user_in(): User is in group 'voicemail'
2(31735) rwrite(): Rewriting Request-URI with 'sip:6604000@200.110.6.58'
2(31735) DEBUG: t_addifnew: msg id=11467 , global msg id=11434 , T on
entrance=0xffffffff
2(31735) parse_headers: flags=-1
2(31735) parse_headers: flags=60
2(31735) t_lookup_request: start searching: hash=31284, isACK=1
2(31735) DEBUG: RFC3261 transaction matched, tid=a200bcf5a4114
2(31735) DEBUG: t_lookup_request: transaction found (T=0x422c27c8)
2(31735) DEBUG: cleanup_uacs: RETR/FR timers reset
2(31735) DEBUG: add_to_tail_of_timer[2]: 0x422c2810
2(31735) DEBUG:destroy_avp_list: destroing list (nil)
2(31735) receive_msg: cleaning up
DEBUG FROM 2nd INSTANCE (PORT 5090)
--------------------------------------------------
]
7(31709) get_hdr_field: cseq <CSeq>: <113> <INVITE>
7(31709) DEBUG: get_hdr_body : content_length=180
7(31709) DEBUG: is_maxfwd_present: value = 69
7(31709) check_self - checking if host==us: 20==9 && [call.millicom.com.pe] ==
[127.0.0.1]
7(31709) check_self - checking if port 5090 matches port 5090
7(31709) check_self - checking if host==us: 20==13 && [call.millicom.com.pe] ==
[200.110.2.131]
7(31709) check_self - checking if port 5090 matches port 5090
7(31709) check_self: host != me
7(31709) parse_headers: flags=-1
7(31709) found end of header
7(31709) check_via_address(200.110.2.131, 200.110.2.131, 0)
7(31709) DEBUG:destroy_avp_list: destroing list (nil)
7(31709) receive_msg: cleaning up
5(31705) SIP Request:
5(31705) method: <ACK>
5(31705) uri: <sip:6605454@call.millicom.com.pe:5090>
5(31705) version: <SIP/2.0>
5(31705) parse_headers: flags=1
5(31705) Found param type 232, <branch> = <z9hG4bKd517.2ea1e793.0>; state=16
5(31705) end of header reached, state=5
5(31705) parse_headers: Via found, flags=1
5(31705) parse_headers: this is the first via
5(31705) After parse_msg...
5(31705) preparing to run routing scripts...
5(31705) parse_headers: flags=4
5(31705) DEBUG: add_param: tag=3749ec7003921b5c92fe06c5dc660395.01b8
5(31705) end of header reached, state=29
5(31705) DEBUG: get_hdr_field: <To> [78]; uri=[sip:6605454@call.millicom.com.pe]
5(31705) DEBUG: to body [<sip:6605454@call.millicom.com.pe>]
5(31705) DEBUG: sl_filter_ACK : local ACK found -> dropping it!
5(31705) DEBUG:destroy_avp_list: destroing list (nil)
5(31705) receive_msg: cleaning up
6(31707) SIP Request:
6(31707) method: <INVITE>
6(31707) uri: <sip:6604000@call.millicom.com.pe:5090>
6(31707) version: <SIP/2.0>
6(31707) parse_headers: flags=1
6(31707) Found param type 232, <branch> = <z9hG4bK43a7.d99b0dc7.1>; state=16
6(31707) end of header reached, state=5
6(31707) parse_headers: Via found, flags=1
6(31707) parse_headers: this is the first via
6(31707) After parse_msg...
6(31707) preparing to run routing scripts...
6(31707) DEBUG : is_maxfwd_present: searching for max_forwards header
6(31707) parse_headers: flags=128
6(31707) Found param type 232, <branch> = <z9hG4bKa200bcf5a4114>; state=16
6(31707) end of header reached, state=5
6(31707) parse_headers: Via found, flags=128
6(31707) parse_headers: this is the second via
6(31707) end of header reached, state=9
6(31707) DEBUG: get_hdr_field: <To> [36]; uri=[sip:6604000@call.millicom.com.pe]
6(31707) DEBUG: to body [<sip:6604000@call.millicom.com.pe>
]
6(31707) get_hdr_field: cseq <CSeq>: <114> <INVITE>
6(31707) DEBUG: get_hdr_body : content_length=180
6(31707) DEBUG: is_maxfwd_present: value = 69
6(31707) check_self - checking if host==us: 20==9 && [call.millicom.com.pe] ==
[127.0.0.1]
6(31707) check_self - checking if port 5090 matches port 5090
6(31707) check_self - checking if host==us: 20==13 && [call.millicom.com.pe] ==
[200.110.2.131]
6(31707) check_self - checking if port 5090 matches port 5090
6(31707) check_self: host != me
6(31707) parse_headers: flags=-1
6(31707) found end of header
6(31707) check_via_address(200.110.2.131, 200.110.2.131, 0)
6(31707) DEBUG:destroy_avp_list: destroing list (nil)
6(31707) receive_msg: cleaning up
8(31711) SIP Request:
8(31711) method: <ACK>
8(31711) uri: <sip:6604000@call.millicom.com.pe:5090>
8(31711) version: <SIP/2.0>
8(31711) parse_headers: flags=1
8(31711) Found param type 232, <branch> = <z9hG4bK43a7.d99b0dc7.1>; state=16
8(31711) end of header reached, state=5
8(31711) parse_headers: Via found, flags=1
8(31711) parse_headers: this is the first via
8(31711) After parse_msg...
8(31711) preparing to run routing scripts...
8(31711) parse_headers: flags=4
8(31711) DEBUG: add_param: tag=3749ec7003921b5c92fe06c5dc660395.5093
8(31711) end of header reached, state=29
8(31711) DEBUG: get_hdr_field: <To> [78]; uri=[sip:6604000@call.millicom.com.pe]
8(31711) DEBUG: to body [<sip:6604000@call.millicom.com.pe>]
8(31711) DEBUG: sl_filter_ACK : local ACK found -> dropping it!
8(31711) DEBUG:destroy_avp_list: destroing list (nil)
8(31711) receive_msg: cleaning up
Thank You
Rafael
---------------------------------
Do You Yahoo!?
Todo lo que quieres saber de Estados Unidos, América Latina y el resto del Mundo.
Visíta Yahoo! Noticias.
19 Aug
19 Aug
6:05 p.m.
second ser doesn't recognize the domain from RURI.
added "alias=call.millicom.com.pe"
bogdan
Rafael J. Risco G.V wrote:
Hi , I am trying to add Voicemail services... the problem is that when
the call is missed (busy, not answered or not connected) I can see
some debug in ser´s
second instance for users in "voicemail group" but the call is closed
and a busy tone return to the caller...
you can see my 3 config files in the following url and details from
both ser debugs, please someone send me some advice.
Config files in:
http://rrisco.pub.millicom.com.pe/ser_config_files.txt
- ser.cfg for Main SER running on 5060
- voicemail.cfg for second instance on port 5090
- sems.conf for Media Server (sems)
DEBUG from 1st instamce (port 5060)
---------------------------------------
4(31737) DEBUG: relay_reply: branch=1, save=0, relay=1
4(31737) old size: 669, new size: 607
4(31737) build_res_from_sip_res: copied size: orig:108, new: 46,
rest: 561 msg=
SIP/2.0 404 not reponsible for host in r-uri
Via: SIP/2.0/UDP 10.0.0.236:5060;branch=z9hG4bKa200bcf5a4114
From: <sip:6603000@call.millicom.com.pe>;tag=a200bcf5a4
To:
<sip:6604000@call.millicom.com.pe>;tag=3749ec7003921b5c92fe06c5dc660395.5093
Call-ID: a2609600-48ac-bcf1-81f5-0002a40055b2(a)10.0.0.236
<mailto:a2609600-48ac-bcf1-81f5-0002a40055b2@10.0.0.236>
CSeq: 114 INVITE
Server: Sip EXpress router (0.8.14 (i386/linux))
Content-Length: 0
Warning: 392 200.110.2.131:5090 "Noisy feedback tells: pid=31707
req_src_ip=200.110.2.131 req_src_port=5060
in_uri=sip:6604000@call.millicom.com.pe:5090
out_uri=sip:6604000@call.millicom.com.pe:5090 via_cnt==2"
4(31737) DEBUG: reply relayed. buf=0x80dec48: SIP/2.0 4...,
shmem=0x422be8a8: SIP/2.0 4
4(31737) DBG: callback type 7, id 1 entered
4(31737) DEBUG: cleanup_uacs: RETR/FR timers reset
4(31737) DEBUG: add_to_tail_of_timer[4]: 0x422c287c
4(31737) DEBUG: add_to_tail_of_timer[0]: 0x422c2890
4(31737) DEBUG:destroy_avp_list: destroing list (nil)
4(31737) receive_msg: cleaning up
[root@gkproxy01 admin]#
[root@gkproxy01 admin]# 2(31735) SIP Request:
2(31735) method: <ACK>
2(31735) uri: <sip:6604000@call.millicom.com.pe>
2(31735) version: <SIP/2.0>
2(31735) parse_headers: flags=1
2(31735) Found param type 232, <branch> = <z9hG4bKa200bcf5a4114>;
state=16
2(31735) end of header reached, state=5
2(31735) parse_headers: Via found, flags=1
2(31735) parse_headers: thi s is the first via
2(31735) After parse_msg...
2(31735) preparing to run routing scripts...
2(31735) DEBUG : sl_filter_ACK: to late to be a local ACK!
2(31735) DEBUG : is_maxfwd_present: searching for max_forwards header
2(31735) parse_headers: flags=128
2(31735) DEBUG: add_param: tag=3749ec7003921b5c92fe06c5dc660395.5093
2(31735) end of header reached, state=29
2(31735) DEBUG: get_hdr_field: <To> [78];
uri=[sip:6604000@call.millicom.com.pe]
2(31735) DEBUG: to body [<sip:6604000@call.millicom.com.pe>]
2(31735) get_hdr_field: cseq <CSeq>: <114> <ACK>
2(31735) DEBUG: get_hdr_body : content_length=0
2(31735) DEBUG: is_maxfwd_present: value = 70
2(31735) DEBUG: add_param: tag=a200bcf5a4
2(31735) end of header reached, state=29
2(31735) parse_headers: flags=256
2(31735) found end of header
2(31735) find_first_route(): No Route headers found
2(31735) loose_route(): There is no Route HF
2(31735) check_self - checking if host==us: 20==13 &&
[call.millicom.com.pe] == [200.110.2.131]
2(31735) check_self - checking if port 5060 matches port 5060
2(31735) check_self - checking if host==us: 20==9 &&
[call.millicom.com.pe] == [127.0.0.1]
2(31735) check_self - checking if port 5060 matches port 5060
2(31735) query="select grp from grp where username='6604000' AND
grp='voicemail'"
2(31735) is_user_in(): User is in group 'voicemail'
2(31735) rwrite(): Rewriting Request-URI with 'sip:6604000@200.110.6.58'
2(31735) DEBUG: t_addifnew: msg id=11467 , global msg id=11434 , T on
entrance=0xffffffff
2(31735) parse_headers: flags=-1
2(31735) parse_headers: flags=60
2(31735) t_lookup_request: start searching: hash=31284, isACK=1
2(31735) DEBUG: RF C3261 transaction matched, tid=a200bcf5a4114
2(31735) DEBUG: t_lookup_request: transaction found (T=0x422c27c8)
2(31735) DEBUG: cleanup_uacs: RETR/FR timers reset
2(31735) DEBUG: add_to_tail_of_timer[2]: 0x422c2810
2(31735) DEBUG:destroy_avp_list: destroing list (nil)
2(31735) receive_msg: cleaning up
DEBUG FROM 2nd INSTANCE (PORT 5090)
--------------------------------------------------
]
7(31709) get_hdr_field: cseq <CSeq>: <113> <INVITE>
7(31709) DEBUG: get_hdr_body : content_length=180
7(31709) DEBUG: is_maxfwd_present: value = 69
7(31709) check_self - checking if host==us: 20==9 &&
[call.millicom.com.pe] == [127.0.0.1]
7(31709) check_self - checking if port 5090 matches port 5090
7(31709) check_self - checking if host==us: 20==13 &&
[call.millicom.com.pe] == [200.110.2.131]
7(31709) check_self - checking if port 5090 matches port 5090
7(31709) check_self: host != me
7(31709) parse_headers: flags=-1
7(31709) found end of header
7(31709) check_via_address(200.110.2.131, 200.110.2.131, 0)
7(31709) DEBUG:destroy_avp_list: destroing list (nil)
7(31709) receive_msg: cleaning up
5(31705) SIP Request:
5(31705) method: <ACK>
5(31705) uri: <sip:6605454@call.millicom.com.pe:5090>
5(31705) version: <SIP/2.0>
5(31705) parse_headers: flags=1
5(31705) Found param type 232, <branch> = <z9hG4bKd517.2ea1e793.0>;
state=16
5(31705) end of header reached, state=5
5(31705) parse_headers: Via found, flags=1
5(31705) parse_headers: this is the first via
5(31705) After parse_msg...
5(31705) preparing to run routing scripts...
5(31705) parse_headers: flags=4
5(31705) DEBUG: add_param: tag=3749ec7003921b5c92fe06c5dc660395.01b8
5(31705) end of header reached, state=29
5(31705) DEBUG: get_hdr_field: <To> [78];
uri=[sip:6605454@call.millicom.com.pe]
5(31705) DEBUG: to body [<sip:6605454@call.millicom.com.pe>]
5(31705) DEBUG: sl_filter_ACK : local ACK found -> dropping it!
5(31705) DEBUG:destroy_avp_list: destroing list (nil)
5(31705) receive_msg: cleaning up
6(31707) SIP Request:
6(31707) method: <INVITE>
6(31707) uri: <sip:6604000@call.millicom.com.pe:5090>
6(31707) version: <SIP/2.0>
6(31707) parse_headers: flags=1
6(31707) Found param type 232, <branch> = <z9hG4bK43a7.d99b0dc7.1>;
state=16
6(31707) end of header reached, state=5
6(31707) parse_headers: Via found, flags=1
6(31707) parse_h eaders: this is the first via
6(31707) After parse_msg...
6(31707) preparing to run routing scripts...
6(31707) DEBUG : is_maxfwd_present: searching for max_forwards header
6(31707) parse_headers: flags=128
6(31707) Found param type 232, <branch> = <z9hG4bKa200bcf5a4114>;
state=16
6(31707) end of header reached, state=5
6(31707) parse_headers: Via found, flags=128
6(31707) parse_headers: this is the second via
6(31707) end of header reached, state=9
6(31707) DEBUG: get_hdr_field: <To> [36];
uri=[sip:6604000@call.millicom.com.pe]
6(31707) DEBUG: to body [<sip:6604000@call.millicom.com.pe>
]
6(31707) get_hdr_field: cseq <CSeq>: <114> <INVITE>
6(31707) DEBUG: get_hdr_body : content_length=180
6(31707) DEBUG: is_maxfwd_present: value = 69
6(31707) check_self - checking if host==us: 20==9 &&
[call.millicom.com.pe] == [127.0.0.1]
6(31707) check_self - checking if port 5090 matches port 5090
6(31707) check_self - checking if host==us: 20==13 &&
[call.millicom.com.pe] == [200.110.2.131]
6(31707) check_self - checking if port 5090 matches port 5090
6(31707) check_self: host != me
6(31707) parse_headers: flags=-1
6(31707) found end of header
6(31707) check_via_address(200.110.2.131, 200.110.2.131, 0)
6(31707) DEBUG:destroy_avp_list: destroing list (nil)
6(31707) receive_msg: cleaning up
8(31711) SIP Request:
8(31711) method: <ACK>
8(31711) uri: <sip:6604000@call.millicom.com.pe:5090>
8(31711) version: <SIP/2.0>
8(31711) parse_headers: flags=1
8(31711) Found param type 232, <branch> = <z9hG4bK43a7.d99b0dc7.1>;
state=16
8(31711) end of h eader reached, state=5
8(31711) parse_headers: Via found, flags=1
8(31711) parse_headers: this is the first via
8(31711) After parse_msg...
8(31711) preparing to run routing scripts...
8(31711) parse_headers: flags=4
8(31711) DEBUG: add_param: tag=3749ec7003921b5c92fe06c5dc660395.5093
8(31711) end of header reached, state=29
8(31711) DEBUG: get_hdr_field: <To> [78];
uri=[sip:6604000@call.millicom.com.pe]
8(31711) DEBUG: to body [<sip:6604000@call.millicom.com.pe>]
8(31711) DEBUG: sl_filter_ACK : local ACK found -> dropping it!
8(31711) DEBUG:destroy_avp_list: destroing list (nil)
8(31711) receive_msg: cleaning up
Thank You
Rafael
------------------------------------------------------------------------
*Do You Yahoo!?*
<http://espanol.yahoo.com/mail_tagline/*http://espanol.news.yahoo.com>
Todo lo que quieres saber de Estados Unidos, América Latina y el resto
del Mundo.
Visíta Yahoo! Noticias
<http://espanol.yahoo.com/mail_tagline/*http://espanol.news.yahoo.com>.
------------------------------------------------------------------------
_______________________________________________
Serusers mailing list
serusers(a)lists.iptel.org
http://lists.iptel.org/mailman/listinfo/serusers
10:24 p.m.
hi
thanks a lot Bodgan for your help... I have added my alias in voicemail.cfg and now I have
errors about "fifo_server" in the first instance as you can see in the debug
below..., I will look for some reference in google but if somebody has had the same
problem send me some advice.
Rafael Risco
SER (5060) DEBUG
---------------------
9(6673) ERROR: fifo_server: command vm_reply is not available
9(6673) ERROR: fifo_server: command must have at least 3 chars
9(6673) ERROR: fifo_server: command must begin with :: 00001A6105B7AC33
9(6673) ERROR: fifo_server: command must begin with :: Content-Type: application/sdp
9(6673) ERROR: fifo_server: command must begin with :: v=0
9(6673) ERROR: fifo_server: command must begin with :: s=session
9(6673) ERROR: fifo_server: command must begin with :: t=0 0
9(6673) ERROR: fifo_server: command must begin with :: a=rtpmap:0 /
9(6673) INFO: fifo_server: command empty
10(6678) DEBUG: timer routine:4,tl=0x422ba480 next=(nil)
9(6673) ERROR: fifo_server: command vm_reply is not available
9(6673) ERROR: fifo_server: command must begin with :: could not send response.
9(6673) ERROR: fifo_server: command must begin with :: 00001A6105B7AC33
9(6673) ERROR: fifo_server: command must have at least 3 chars
9(6673) INFO: fifo_server: command empty
4(6642) SIP Request:
4(6642) method: <REGISTER 4(6642) uri:
<sip:200.110.2.131 4(6642) version:
<SIP/2.0
VOICEMAIL (5090) DEBUG
------------------------------------
0(6717) DEBUG: init_mod_child (0): tm
0(6717) DEBUG: callid: '293ffabd-6717(a)200.110.2.131'
0(6717) DEBUG: init_mod_child (0): voicemail
0(6717) voicemail - initializing child 0
2(6719) SIP Request:
2(6719) method: <INVITE 2(6719) uri:
<sip:6605454@call.millicom.com.pe:5090
2(6719) version: <SIP/2.0
2(6719) parse_headers: flags=1
2(6719) Found param type 232, <branch> = <z9hG4bKa827.0de3cd13.0>; state=16
2(6719) end of header reached, state=5
2(6719) parse_headers: Via found, flags=1
2(6719) parse_headers: this is the first via
2(6719) After parse_msg...
2(6719) preparing to run routing scripts...
2(6719) DEBUG : is_maxfwd_present: searching for max_forwards header
2(6719) parse_headers: flags=128
2(6719) Found param type 232, <branch> = <z9hG4bK3500dd15a4123>; state=16
2(6719) end of header reached, state=5
2(6719) parse_headers: Via found, flags=128
2(6719) parse_headers: this is the second via
2(6719) end of header reached, state=9
2(6719) DEBUG: get_hdr_field: <To> [36]; uri=[sip:6605454@call.millicom.com.pe]
2(6719) DEBUG: to body [<sip:6605454@call.millicom.com.pe
]
2(6719) get_hdr_field: cseq <CSeq>: <123> <INVITE 2(6719) DEBUG: get_hdr_body :
content_length=180
2(6719) DEBUG: is_maxfwd_present: value = 69
2(6719) check_self - checking if host==us: 20==13 && [call.millicom.com.pe] ==
[200.110.2.131]
2(6719) check_self - checking if port 5090 matches port 5090
2(6719) check_self - checking if host==us: 20==9 && [call.millicom.com.pe] ==
[127.0.0.1]
2(6719) check_self - checking if port 5090 matches port 5090
2(6719) DEBUG: t_addifnew: msg id=1 , global msg id=0 , T on entrance=0xffffffff
2(6719) parse_headers: flags=-1
2(6719) found end of header
2(6719) parse_headers: flags=60
2(6719) t_lookup_request: start searching: hash=29322, isACK=0
2(6719) DEBUG: RFC3261 transaction matching failed
2(6719) DEBUG: t_lookup_request: no transaction found
2(6719) DEBUG: add_param: tag=3500dd15a4
2(6719) end of header reached, state=29
2(6719) DEBUG: t_check: msg id=1 global id=1 T start=0x422b65a0
2(6719) DEBUG: t_check: T alredy found!
2(6719) parse_headers: flags=-1
2(6719) check_via_address(200.110.2.131, 200.110.2.131, 0)
2(6719) WARNING:vqm_resize: resize(0) called
2(6719) DEBUG: reply sent out. buf=0x80dbd30: SIP/2.0 1..., shmem=0x422b7ae8: SIP/2.0 1
2(6719) DEBUG: t_reply: finished
2(6719) **************** vm start - begin ******************
2(6719) parse_headers: flags=-1
2(6719) DEBUG: t_check: msg id=1 global id=1 T start=0x422b65a0
2(6719) DEBUG: t_check: T alredy found!
2(6719) record_route->nameaddr.uri: sip:6605454@200.110.2.131;ftag=3500dd15a4;lr=on
2(6719) vm: first proxy has loose routing.
2(6719) vm: calculated route: <sip:6605454@200.110.2.131;ftag=3500dd15a4;lr=on 2(6719) vm: next r-uri: sip:6603000@10.0.0.236
2(6719) parse_headers: flags=-1
2(6719) query="select email_address from subscriber where
username='6605454'"
2(6719) vm: write_to_vm_fifo: <0.2
sip_request.voicemail
INVITE
6605454
rrisco(a)millicom.com.pe
call.millicom.com.pe
200.110.2.131
5090
sip:6605454@call.millicom.com.pe:5090
sip:6603000@10.0.0.236
<sip:6603000@call.millicom.com.pe
<sip:6605454@call.millicom.com.pe
35669700-9433-dd5b-8215-0002a40055b2(a)10.0.0.236
3500dd15a4
.
123
29322:943117687
<sip:6605454@200.110.2.131;ftag=3500dd15a4;lr=on
sip:6605454@200.110.2.131;ftag=3500dd15a4;lr=on
P-MsgFlags: 0
Min-SE: 1800
Date: Sat, 25 Apr 1970 20:08:21 GMT
.
v=0
o=- 9922101 9922101 IN IP4 10.0.0.236
s=AddPac Gateway SDP
c=IN IP4 10.0.0.236
t=0 0
m=audio 23390 RTP/AVP 4 18 0 101
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
2(6719) DEBUG: write_to_vm_fifo: write
completed
2(6719) DEBUG: add_to_tail_of_timer[0]: 0x422b66dc
2(6719) **************** vm start - end ******************
2(6719) DEBUG:destroy_avp_list: destroing list (nil)
2(6719) receive_msg: cleaning up
10(6736) DEBUG: timer routine:0,tl=0x422b66dc next=(nil)
10(6736) DEBUG: FR_handler:stop retr. and send CANCEL (0x422b65a0)
10(6736) ->>>>>>>>> T_code=100, new_code=408
10(6736) DEBUG: relay_reply: branch=0, save=0, relay=0
10(6736) parse_headers: flags=-1
10(6736) check_via_address(200.110.2.131, 200.110.2.131, 0)
10(6736) DEBUG: reply relayed. buf=0x80db730: SIP/2.0 4..., shmem=0x422b7ae8: SIP/2.0 4
10(6736) DEBUG: add_to_tail_of_timer[4]: 0x422b6654
10(6736) DEBUG: add_to_tail_of_timer[0]: 0x422b6668
10(6736) DEBUG: final_response_handler : done
4(6721) SIP Request:
4(6721) method: <ACK 4(6721) uri:
<sip:6605454@call.millicom.com.pe:5090
4(6721) version: <SIP/2.0
4(6721) parse_headers: flags=1
4(6721) Found param type 232, <branch> = <z9hG4bKa827.0de3cd13.0>; state=16
4(6721) end of header reached, state=5
4(6721) parse_headers: Via found, flags=1
4(6721) parse_headers: this is the first via
4(6721) After parse_msg...
4(6721) preparing to run routing scripts...
4(6721) DEBUG : sl_filter_ACK: to late to be a local ACK!
4(6721) DEBUG : is_maxfwd_present: searching for max_forwards header
4(6721) parse_headers: flags=128
4(6721) DEBUG: add_param: tag=39652ef7bd23dd6cc4b64cc488bcb5a3-e162
4(6721) end of header reached, state=29
4(6721) DEBUG: get_hdr_field: <To> [78]; uri=[sip:6605454@call.millicom.com.pe]
4(6721) DEBUG: to body [<sip:6605454@call.millicom.com.pe>]
4(6721) get_hdr_field: cseq <CSeq>: <123> <ACK 4(6721) DEBUG: get_hdr_body : content_length=0
4(6721) found end of header
4(6721) DEBUG: is_maxfwd_present: max_forwards header not found!
4(6721) check_self - checking if host==us: 20==13 && [call.millicom.com.pe] ==
[200.110.2.131]
4(6721) check_self - checking if port 5090 matches port 5090
4(6721) check_self - checking if host==us: 20==9 && [call.millicom.com.pe] ==
[127.0.0.1]
4(6721) check_self - checking if port 5090 matches port 5090
4(6721) DEBUG: t_addifnew: msg id=1 , global msg id=0 , T on entrance=0xffffffff
4(6721) parse_headers: flags=-1
4(6721) parse_headers: flags=60
4(6721) t_lookup_request: start searching: hash=29322, isACK=1
4(6721) DEBUG: RFC3261 transaction matched, tid=a827.0de3cd13.0
4(6721) DEBUG: t_lookup_request: transaction found (T=0x422b65a0)
4(6721) DEBUG: cleanup_uacs: RETR/FR timers reset
4(6721) DEBUG: add_to_tail_of_timer[2]: 0x422b65e8
4(6721) DEBUG:destroy_avp_list: destroing list (nil)
4(6721) receive_msg: cleaning up
10(6736) DEBUG: timer routine:0,tl=0x422b6668 next=(nil)
10(6736) DEBUG: timer routine:4,tl=0x422b6654 next=(nil)
10(6736) DEBUG: timer routine:2,tl=0x422b65e8 next=(nil)
10(6736) DEBUG: wait_handler : removing 0x422b65a0 from table
10(6736) DEBUG: delete transaction 0x422b65a0
10(6736) DEBUG: wait_handler : done
Bogdan-Andrei IANCU <iancu(a)fokus.fraunhofer.de> wrote:
second ser doesn't recognize the domain from RURI.
added "alias=call.millicom.com.pe"
bogdan
Rafael J. Risco G.V wrote:
> Hi , I am trying to add Voicemail
services... the problem is that when
> the call is missed (busy, not answered or not connected) I can see
> some debug in ser´s
> second instance for users in "voicemail group" but the call is closed
> and a busy tone return to the caller...
> you can see my 3 config files in the following url and details from
> both ser debugs, please someone send me some advice.
>
> Config files in:
> http://rrisco.pub.millicom.com.pe/ser_config_files.txt
> - ser.cfg for Main SER running on 5060
> - voicemail.cfg for second instance on port 5090
> - sems.conf for Media Server (sems)
---------------------------------
Do You Yahoo!?
Todo lo que quieres saber de Estados Unidos, América Latina y el resto del Mundo.
Visíta Yahoo! Noticias.
11:55 p.m.
hi
I am answering myself... :
1. it is necessary to load VM module in the first ser instance too.
2.- In voicemail.cfg the fifo value is fifo="/tmp/vm_ser_fifo"
3.- In Sems.cfg: the value of 'ser_fifo_name' is "/tmp/vm_ser_fifo"
instead of "/tmp/ser_fifo" ( ser_fifo_name=/tmp/vm_ser_fifo )
now it works perfectly.
Rafael
"Rafael J. Risco G.V" <rafael_rgv(a)yahoo.com> wrote:
hi
thanks a lot Bodgan for your help... I have added my alias in voicemail.cfg and now I have
errors about "fifo_server" in the first instance as you can see in the debug
below..., I will look for some reference in google but if somebody has had the same
problem send me some advice.
Rafael Risco
SER (5060) DEBUG
---------------------
9(6673) ERROR: fifo_server: command vm_reply is not available
9(6673) ERROR: fifo_server: command must have at least 3 chars
9(6673) ERROR: fifo_server: command must begin with :: 00001A6105B7AC33
9(6673) ERROR: fifo_server: command must begin with :: Content-Type: application/sdp
9(6673) ERROR: fifo_server: command must begin with :: v=0
9(6673) ERROR: fifo_server: command must begin with :: s=session
9(6673) ERROR: fifo_server: command must begin with :: t=0 0
9(6673) ERROR: fifo_server: command must begin with :: a=rtpmap:0 /
9(6673) INFO: fifo_server: command empty
10(6678) DEBUG: timer routine:4,tl=0x422ba480 next=(nil)
9(6673) ERROR: fifo_server: command vm_reply is not available
9(6673) ERROR: fifo_server: command must begin with :: could not send response.
9(6673) ERROR: fifo_server: command must begin with :: 00001A6105B7AC33
9(6673) ERROR: fifo_server: command must have at least 3 chars
9(6673) INFO: fifo_server: command empty
4(6642) SIP Request:
4(6642) method: <REGISTER 4(6642) uri:
<sip:200.110.2.131 4(6642) version:
<SIP/2.0
VOICEMAIL (5090) DEBUG
------------------------------------
0(6717) DEBUG: init_mod_child (0): tm
0(6717) DEBUG: callid: '293ffabd-6717(a)200.110.2.131'
0(6717) DEBUG: init_mod_child (0): voicemail
0(6717) voicemail - initializing child 0
2(6719) SIP Request:
2(6719) method: <INVITE 2(6719) uri:
<sip:6605454@call.millicom.com.pe:5090
2(6719) version: <SIP/2.0
2(6719) parse_headers: flags=1
2(6719) Found param type 232, <branch> = <z9hG4bKa827.0de3cd13.0>; state=16
2(6719) end of header reached, state=5
2(6719) parse_headers: Via found, flags=1
2(6719) parse_headers: this is the first via
2(6719) After parse_msg...
2(6719) preparing to run routing scripts...
2(6719) DEBUG : is_maxfwd_present: searching for max_forwards header
2(6719) parse_headers: flags=128
2(6719) Found param type 232, <branch> = <z9hG4bK3500dd15a4123>; state=16
2(6719) end of header reached, state=5
2(6719) parse_headers: Via found, flags=128
2(6719) parse_headers: this is the second via
2(6719) end of header reached, state=9
2(6719) DEBUG: get_hdr_field: <To> [36]; uri=[sip:6605454@call.millicom.com.pe]
2(6719) DEBUG: to body [<sip:6605454@call.millicom.com.pe
]
2(6719) get_hdr_field: cseq <CSeq>: <123> <INVITE 2(6719) DEBUG: get_hdr_body :
content_length=180
2(6719) DEBUG: is_maxfwd_present: value = 69
2(6719) check_self - checking if host==us: 20==13 && [call.millicom.com.pe] ==
[200.110.2.131]
2(6719) check_self - checking if port 5090 matches port 5090
2(6719) check_self - checking if host==us: 20==9 && [call.millicom.com.pe] ==
[127.0.0.1]
2(6719) check_self - checking if port 5090 matches port 5090
2(6719) DEBUG: t_addifnew: msg id=1 , global msg id=0 , T on entrance=0xffffffff
2(6719) parse_headers: flags=-1
2(6719) found end of header
2(6719) parse_headers: flags=60
2(6719) t_lookup_request: start searching: hash=29322, isACK=0
2(6719) DEBUG: RFC3261 transaction matching failed
2(6719) DEBUG: t_lookup_request: no transaction found
2(6719) DEBUG: add_param: tag=3500dd15a4
2(6719) end of header reached, state=29
2(6719) DEBUG: t_check: msg id=1 global id=1 T start=0x422b65a0
2(6719) DEBUG: t_check: T alredy found!
2(6719) parse_headers: flags=-1
2(6719) check_via_address(200.110.2.131, 200.110.2.131, 0)
2(6719) WARNING:vqm_resize: resize(0) called
2(6719) DEBUG: reply sent out. buf=0x80dbd30: SIP/2.0 1..., shmem=0x422b7ae8: SIP/2.0 1
2(6719) DEBUG: t_reply: finished
2(6719) **************** vm start - begin ******************
2(6719) parse_headers: flags=-1
2(6719) DEBUG: t_check: msg id=1 global id=1 T start=0x422b65a0
2(6719) DEBUG: t_check: T alredy found!
2(6719) record_route->nameaddr.uri: sip:6605454@200.110.2.131;ftag=3500dd15a4;lr=on
2(6719) vm: first proxy has loose routing.
2(6719) vm: calculated route: <sip:6605454@200.110.2.131;ftag=3500dd15a4;lr=on 2(6719) vm: next r-uri: sip:6603000@10.0.0.236
2(6719) parse_headers: flags=-1
2(6719) query="select email_address from subscriber where
username='6605454'"
2(6719) vm: write_to_vm_fifo: <0.2
sip_request.voicemail
INVITE
6605454
rrisco(a)millicom.com.pe
call.millicom.com.pe
200.110.2.131
5090
sip:6605454@call.millicom.com.pe:5090
sip:6603000@10.0.0.236
<sip:6603000@call.millicom.com.pe
<sip:6605454@call.millicom.com.pe
35669700-9433-dd5b-8215-0002a40055b2(a)10.0.0.236
3500dd15a4
.
123
29322:943117687
<sip:6605454@200.110.2.131;ftag=3500dd15a4;lr=on
sip:6605454@200.110.2.131;ftag=3500dd15a4;lr=on
P-MsgFlags: 0
Min-SE: 1800
Date: Sat, 25 Apr 1970 20:08:21 GMT
.
v=0
o=- 9922101 9922101 IN IP4 10.0.0.236
s=AddPac Gateway SDP
c=IN IP4 10.0.0.236
t=0 0
m=audio 23390 RTP/AVP 4 18 0 101
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
2(6719) DEBUG: write_to_vm_fifo: write
completed
2(6719) DEBUG: add_to_tail_of_timer[0]: 0x422b66dc
2(6719) **************** vm start - end ******************
2(6719) DEBUG:destroy_avp_list: destroing list (nil)
2(6719) receive_msg: cleaning up
10(6736) DEBUG: timer routine:0,tl=0x422b66dc next=(nil)
10(6736) DEBUG: FR_handler:stop retr. and send CANCEL (0x422b65a0)
10(6736) ->>>>>>>>> T_code=100, new_code=408
10(6736) DEBUG: relay_reply: branch=0, save=0, relay=0
10(6736) parse_headers: flags=-1
10(6736) check_via_address(200.110.2.131, 200.110.2.131, 0)
10(6736) DEBUG: reply relayed. buf=0x80db730: SIP/2.0 4..., shmem=0x422b7ae8: SIP/2.0 4
10(6736) DEBUG: add_to_tail_of_timer[4]: 0x422b6654
10(6736) DEBUG: add_to_tail_of_timer[0]: 0x422b6668
10(6736) DEBUG: final_response_handler : done
4(6721) SIP Request:
4(6721) method: <ACK 4(6721) uri:
<sip:6605454@call.millicom.com.pe:5090
4(6721) version: <SIP/2.0
4(6721) parse_headers: flags=1
4(6721) Found param type 232, <branch> = <z9hG4bKa827.0de3cd13.0>; state=16
4(6721) end of header reached, state=5
4(6721) parse_headers: Via found, flags=1
4(6721) parse_headers: this is the first via
4(6721) After parse_msg...
4(6721) preparing to run routing scripts...
4(6721) DEBUG : sl_filter_ACK: to late to be a local ACK!
4(6721) DEBUG : is_maxfwd_present: searching for max_forwards header
4(6721) parse_headers: flags=128
4(6721) DEBUG: add_param: tag=39652ef7bd23dd6cc4b64cc488bcb5a3-e162
4(6721) end of header reached, state=29
4(6721) DEBUG: get_hdr_field: <To> [78]; uri=[sip:6605454@call.millicom.com.pe]
4(6721) DEBUG: to body [<sip:6605454@call.millicom.com.pe>]
4(6721) get_hdr_field: cseq <CSeq>: <123> <ACK 4(6721) DEBUG: get_hdr_body : content_length=0
4(6721) found end of header
4(6721) DEBUG: is_maxfwd_present: max_forwards header not found!
4(6721) check_self - checking if host==us: 20==13 && [call.millicom.com.pe] ==
[200.110.2.131]
4(6721) check_self - checking if port 5090 matches port 5090
4(6721) check_self - checking if host==us: 20==9 && [call.millicom.com.pe] ==
[127.0.0.1]
4(6721) check_self - checking if port 5090 matches port 5090
4(6721) DEBUG: t_addifnew: msg id=1 , global msg id=0 , T on entrance=0xffffffff
4(6721) parse_headers: flags=-1
4(6721) parse_headers: flags=60
4(6721) t_lookup_request: start searching: hash=29322, isACK=1
4(6721) DEBUG: RFC3261 transaction matched, tid=a827.0de3cd13.0
4(6721) DEBUG: t_lookup_request: transaction found (T=0x422b65a0)
4(6721) DEBUG: cleanup_uacs: RETR/FR timers reset
4(6721) DEBUG: add_to_tail_of_timer[2]: 0x422b65e8
4(6721) DEBUG:destroy_avp_list: destroing list (nil)
4(6721) receive_msg: cleaning up
10(6736) DEBUG: timer routine:0,tl=0x422b6668 next=(nil)
10(6736) DEBUG: timer routine:4,tl=0x422b6654 next=(nil)
10(6736) DEBUG: timer routine:2,tl=0x422b65e8 next=(nil)
10(6736) DEBUG: wait_handler : removing 0x422b65a0 from table
10(6736) DEBUG: delete transaction 0x422b65a0
10(6736) DEBUG: wait_handler : done
Bogdan-Andrei IANCU <iancu(a)fokus.fraunhofer.de> wrote:
second ser doesn't recognize the domain from RURI.
added "alias=call.millicom.com.pe"
bogdan
Rafael J. Risco G.V wrote:
> Hi , I am trying to add Voicemail
services... the problem is that when
> the call is missed (busy, not answered or not connected) I can see
> some debug in ser´s
> second instance for users in "voicemail group" but the call is closed
> and a busy tone return to the caller...
> you can see my 3 config files in the following url and details from
> both ser debugs, please someone send me some advice.
>
> Config files in:
> http://rrisco.pub.millicom.com.pe/ser_config_files.txt
> - ser.cfg for Main SER running on 5060
> - voicemail.cfg for second instance on port 5090
> - sems.conf for Media Server (sems)
---------------------------------
Do You Yahoo!?
Todo lo que quieres saber de Estados Unidos, América Latina y el resto del Mundo.
Visíta Yahoo! Noticias.
---------------------------------
Do You Yahoo!?
Todo lo que quieres saber de Estados Unidos, América Latina y el resto del Mundo.
Visíta Yahoo! Noticias.
20 Aug
20 Aug
8:32 a.m.
Rafael J. Risco G.V wrote:
hi
I am answering myself... :
1. it is necessary to load VM module in the first ser instance too.
actually you don't need VM in first instance. My guess is you mixed up
the fifo files used by both server (maybe they have the same name) and
SEMS fifo commands reach the first instance, instead the second.
bogdan
2.- In voicemail.cfg the fifo value is
fifo="/tmp/vm_ser_fifo"
3.- In Sems.cfg: the value of 'ser_fifo_name' is "/tmp/vm_ser_fifo"
instead of "/tmp/ser_fifo" ( ser_fifo_name=/tmp/vm_ser_fifo )
now it works perfectly.
Rafael
21 Aug
21 Aug
2:20 a.m.
Hi
You are right, it is not necessary vm in first instance..., now I have a new problem,
recently I am receiving this error in sems debug:
[root@gkproxy01 answer_machine]# (1932) ERROR: bye (AmRequest.cpp:238): AmRequest::bye:
481 Call Leg/Transaction Does Not Exist
(1932) ERROR: bye (AmRequest.cpp:238): AmRequest::bye: 481 Call Leg/Transaction Does Not
Exist
I can listen the announcement but do not receive any mail...
this is the Voicemail debug:
[root@gkproxy01 sems_2004-07-27]# 3(2028) SIP Request:
3(2028) method: <INVITE 3(2028) uri:
<sip:6605454@call.millicom.com.pe:5090
3(2028) version: <SIP/2.0
3(2028) parse_headers: flags=1
3(2028) Found param type 232, <branch> = <z9hG4bK1ee5.e67a562.0>; state=16
3(2028) end of header reached, state=5
3(2028) parse_headers: Via found, flags=1
3(2028) parse_headers: this is the first via
3(2028) After parse_msg...
3(2028) preparing to run routing scripts...
3(2028) DEBUG : is_maxfwd_present: searching for max_forwards header
3(2028) parse_headers: flags=128
3(2028) Found param type 232, <branch> = <z9hG4bK3b00d2eea4212>; state=16
3(2028) end of header reached, state=5
3(2028) parse_headers: Via found, flags=128
3(2028) parse_headers: this is the second via
3(2028) end of header reached, state=9
3(2028) DEBUG: get_hdr_field: <To> [36]; uri=[sip:6605454@call.millicom.com.pe]
3(2028) DEBUG: to body [<sip:6605454@call.millicom.com.pe
]
3(2028) get_hdr_field: cseq <CSeq>: <212> <INVITE 3(2028) DEBUG: get_hdr_body :
content_length=182
3(2028) DEBUG: is_maxfwd_present: value = 69
3(2028) check_self - checking if host==us: 20==13 && [call.millicom.com.pe] ==
[200.110.2.131]
3(2028) check_self - checking if port 5090 matches port 5090
3(2028) check_self - checking if host==us: 20==9 && [call.millicom.com.pe] ==
[127.0.0.1]
3(2028) check_self - checking if port 5090 matches port 5090
3(2028) DEBUG: t_addifnew: msg id=2 , global msg id=1 , T on entrance=0xffffffff
3(2028) parse_headers: flags=-1
3(2028) found end of header
3(2028) parse_headers: flags=60
3(2028) t_lookup_request: start searching: hash=24289, isACK=0
3(2028) DEBUG: RFC3261 transaction matching failed
3(2028) DEBUG: t_lookup_request: no transaction found
3(2028) DEBUG: add_param: tag=3b00d2eea4
3(2028) end of header reached, state=29
3(2028) DEBUG: t_check: msg id=2 global id=2 T start=0x422b8d30
3(2028) DEBUG: t_check: T alredy found!
3(2028) parse_headers: flags=-1
3(2028) check_via_address(200.110.2.131, 200.110.2.131, 0)
3(2028) WARNING:vqm_resize: resize(0) called
3(2028) DEBUG: reply sent out. buf=0x80dbd58: SIP/2.0 1..., shmem=0x422b68b0: SIP/2.0 1
3(2028) DEBUG: t_reply: finished
3(2028) **************** vm start - begin ******************
3(2028) parse_headers: flags=-1
3(2028) DEBUG: t_check: msg id=2 global id=2 T start=0x422b8d30
3(2028) DEBUG: t_check: T alredy found!
3(2028) record_route->nameaddr.uri: sip:6605454@200.110.2.131;ftag=3b00d2eea4;lr=on
3(2028) vm: first proxy has loose routing.
3(2028) vm: calculated route: <sip:6605454@200.110.2.131;ftag=3b00d2eea4;lr=on 3(2028) vm: next r-uri: sip:6603000@10.0.0.236
3(2028) parse_headers: flags=-1
3(2028) query="select email_address from subscriber where
username='6605454'"
3(2028) vm: write_to_vm_fifo: <0.2
sip_request.voicemail
INVITE
6605454
rrisco(a)millicom.com.pe
call.millicom.com.pe
200.110.2.131
5090
sip:6605454@call.millicom.com.pe:5090
sip:6603000@10.0.0.236
<sip:6603000@call.millicom.com.pe
<sip:6605454@call.millicom.com.pe
3bdd9800-8a85-d2ac-82ee-0002a40055b2(a)10.0.0.236
3b00d2eea4
.
212
24289:1288922012
<sip:6605454@200.110.2.131;ftag=3b00d2eea4;lr=on
sip:6605454@200.110.2.131;ftag=3b00d2eea4;lr=on
P-MsgFlags: 0
Min-SE: 1800
Date: Sun, 26 Apr 1970 22:48:27 GMT
.
v=0
o=- 10018107 10018107 IN IP4 10.0.0.236
s=AddPac Gateway SDP
c=IN IP4 10.0.0.236
t=0 0
m=audio 23562 RTP/AVP 4 18 0 101
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
3(2028) DEBUG: write_to_vm_fifo: write
completed
3(2028) DEBUG: add_to_tail_of_timer[0]: 0x422b8e6c
3(2028) **************** vm start - end ******************
3(2028) DEBUG:destroy_avp_list: destroing list (nil)
3(2028) receive_msg: cleaning up
9(2038) DEBUG: fifo_t_reply: ############### begin ##############
9(2038) DEBUG: fifo_t_reply: trans_id=24289:1288922012
9(2038) DEBUG: fifo_t_reply: hash_index=24289 label=1288922012
9(2038) DEBUG: fifo_t_reply: to-tag: 0000078C0EA8250E
9(2038) DEBUG: fifo_t_reply: new headers: Contact: <sip:6605454@200.110.2.131:5090 Content-Type: application/sdp
9(2038) DEBUG: fifo_t_reply: body: v=0
o=username 0 0 IN IP4 200.110.2.131
s=session
c=IN IP4 200.110.2.131
t=0 0
m=audio 1296 RTP/AVP 0
a=rtpmap:0 /
9(2038) DEBUG: t_lookup_ident: transaction found
9(2038) parse_headers: flags=-1
9(2038) check_via_address(200.110.2.131, 200.110.2.131, 0)
9(2038) t_reply_with_body: buffer computed
9(2038) DEBUG: cleanup_uacs: RETR/FR timers reset
9(2038) DEBUG: add_to_tail_of_timer[4]: 0x422b8de4
9(2038) DEBUG: add_to_tail_of_timer[0]: 0x422b8df8
9(2038) DEBUG: reply sent out. buf=0x80dbe20: SIP/2.0 2..., shmem=0x422b6c20: SIP/2.0 2
9(2038) DEBUG: t_reply: finished
9(2038) DEBUG: fifo_t_reply: ################ end ##############
10(2040) DEBUG: timer routine:0,tl=0x422b8e6c next=(nil)
10(2040) DEBUG: timer routine:4,tl=0x422b8de4 next=(nil)
10(2040) DEBUG: retransmission_handler : reply resending (t=0x422b8d30, SIP/2.0 2 ... )
10(2040) DEBUG: reply retransmitted. buf=0x4416b400: SIP/2.0 2..., shmem=0x422b6c20:
SIP/2.0 2
10(2040) DEBUG: add_to_tail_of_timer[5]: 0x422b8de4
10(2040) DEBUG: retransmission_handler : done
4(2029) SIP Request:
4(2029) method: <ACK 4(2029) uri:
<sip:6605454@call.millicom.com.pe:5090
4(2029) version: <SIP/2.0
4(2029) parse_headers: flags=1
4(2029) Found param type 232, <branch> = <0>; state=16
4(2029) end of header reached, state=5
4(2029) parse_headers: Via found, flags=1
4(2029) parse_headers: this is the first via
4(2029) After parse_msg...
4(2029) preparing to run routing scripts...
4(2029) DEBUG : sl_filter_ACK: to late to be a local ACK!
4(2029) DEBUG : is_maxfwd_present: searching for max_forwards header
4(2029) parse_headers: flags=128
4(2029) Found param type 232, <branch> = <z9hG4bK3b00d2eea4212>; state=16
4(2029) end of header reached, state=5
4(2029) parse_headers: Via found, flags=128
4(2029) parse_headers: this is the second via
4(2029) DEBUG: add_param: tag=0000078C0EA8250E
4(2029) end of header reached, state=29
4(2029) DEBUG: get_hdr_field: <To> [57]; uri=[sip:6605454@call.millicom.com.pe]
4(2029) DEBUG: to body [<sip:6605454@call.millicom.com.pe>]
4(2029) get_hdr_field: cseq <CSeq>: <212> <ACK 4(2029) DEBUG: get_hdr_body : content_length=0
4(2029) DEBUG: is_maxfwd_present: value = 69
4(2029) check_self - checking if host==us: 20==13 && [call.millicom.com.pe] ==
[200.110.2.131]
4(2029) check_self - checking if port 5090 matches port 5090
4(2029) check_self - checking if host==us: 20==9 && [call.millicom.com.pe] ==
[127.0.0.1]
4(2029) check_self - checking if port 5090 matches port 5090
4(2029) DEBUG: t_addifnew: msg id=4 , global msg id=3 , T on entrance=0xffffffff
4(2029) parse_headers: flags=-1
4(2029) found end of header
4(2029) parse_headers: flags=60
4(2029) t_lookup_request: start searching: hash=24289, isACK=1
4(2029) DEBUG: proceeding to pre-RFC3261 transaction matching
4(2029) DEBUG: t_lookup_request: transaction found (T=0x422b8d30)
4(2029) DEBUG: cleanup_uacs: RETR/FR timers reset
4(2029) DEBUG: add_to_tail_of_timer[2]: 0x422b8d78
4(2029) DEBUG:destroy_avp_list: destroing list (nil)
4(2029) receive_msg: cleaning up
10(2040) DEBUG: timer routine:0,tl=0x422b8df8 next=(nil)
10(2040) DEBUG: timer routine:5,tl=0x422b8de4 next=(nil)
10(2040) DEBUG: timer routine:2,tl=0x422b8d78 next=(nil)
10(2040) DEBUG: wait_handler : removing 0x422b8d30 from table
10(2040) DEBUG: delete transaction 0x422b8d30
10(2040) DEBUG: wait_handler : done
[root@gkproxy01 sems_2004-07-27]#
[root@gkproxy01 sems_2004-07-27]#
[root@gkproxy01 sems_2004-07-27]#
[root@gkproxy01 sems_2004-07-27]#
[root@gkproxy01 sems_2004-07-27]#
[root@gkproxy01 sems_2004-07-27]#
[root@gkproxy01 sems_2004-07-27]#
[root@gkproxy01 sems_2004-07-27]# 9(2038) fifo_get_method: method: 'BYE'
9(2038) DEBUG: fifo_get_ruri: 'sip:6603000@10.0.0.236'
9(2038) DEBUG: fifo_get_nexthop: hop:
'sip:6605454@200.110.2.131;ftag=3b00d2eea4;lr=on'
9(2038) fifo_get_headers: headers: From:
<sip:6605454@call.millicom.com.pe>;tag=0000078C0EA8250E
To: <sip:6603000@call.millicom.com.pe>;tag=3b00d2eea4
CSeq: 213 BYE
Call-ID: 3bdd9800-8a85-d2ac-82ee-0002a40055b2(a)10.0.0.236
Contact: <sip:6605454@200.110.2.131:5090
Route: <sip:6605454@200.110.2.131;ftag=3b00d2eea4;lr=on
9(2038) parse_headers: flags=-1
9(2038) DEBUG: add_param: tag=3b00d2eea4
9(2038) end of header reached, state=29
9(2038) DEBUG: get_hdr_field: <To> [51]; uri=[sip:6603000@call.millicom.com.pe]
9(2038) DEBUG: to body [<sip:6603000@call.millicom.com.pe>]
9(2038) get_hdr_field: cseq <CSeq>: <213> <BYE 9(2038) DEBUG: fifo_uac: parse_headers
succeeded
9(2038) fifo_get_body: body:
9(2038) DEBUG: add_param: tag=0000078C0EA8250E
9(2038) end of header reached, state=29
9(2038) DEBUG: get_hf_block: one more hf processed
9(2038) DEBUG: get_hf_block: one more hf processed
9(2038) DEBUG: fifo_uac: EoL -- proceeding to transaction creation
9(2038) DEBUG: mk_proxy: doing DNS lookup...
9(2038) DEBUG: dlg2hash: 24290
9(2038) DEBUG: add_to_tail_of_timer[4]: 0x422b8e58
9(2038) DEBUG: add_to_tail_of_timer[0]: 0x422b8e6c
1(2024) SIP Reply (status):
1(2024) version: <SIP/2.0 1(2024) status:
<481 1(2024) reason: <Call Leg/Transaction Does
Not Exist 1(2024)
parse_headers: flags=1
1(2024) Found param type 232, <branch> = <z9hG4bK2ee5.0b7fc205.0>; state=16
1(2024) end of header reached, state=5
1(2024) parse_headers: Via found, flags=1
1(2024) parse_headers: this is the first via
1(2024) After parse_msg...
1(2024) forward_reply: found module tm, passing reply to it
1(2024) DEBUG: t_check: msg id=3 global id=2 T start=0xffffffff
1(2024) parse_headers: flags=17
1(2024) DEBUG: add_param: tag=3b00d2eea4
1(2024) end of header reached, state=29
1(2024) DEBUG: get_hdr_field: <To> [51]; uri=[sip:6603000@call.millicom.com.pe]
1(2024) DEBUG: to body [<sip:6603000@call.millicom.com.pe>]
1(2024) get_hdr_field: cseq <CSeq>: <213> <BYE 1(2024) DEBUG: t_reply_matching: hash 24290
label 1345124272 branch 0
1(2024) DEBUG: t_reply_matching: reply matched (T=0x422b8d30)!
1(2024) DEBUG: t_check: msg id=3 global id=3 T end=0x422b8d30
1(2024) DEBUG: t_on_reply: org. status uas=0, uac[0]=0 local=1 is_invite=0)
1(2024) ->>>>>>>>> T_code=0, new_code=481
1(2024) DEBUG: local_reply: branch=0, save=0, winner=0
1(2024) DEBUG: local transaction completed
1(2024) !!!!! ref_counter: 1
1(2024) DEBUG: fifo UAC completed with status 481
1(2024) DEBUG: fifo_callback sucesssfuly completed
1(2024) DEBUG: cleanup_uacs: RETR/FR timers reset
1(2024) DEBUG: add_to_tail_of_timer[2]: 0x422b8d78
1(2024) DEBUG:destroy_avp_list: destroing list (nil)
1(2024) receive_msg: cleaning up
10(2040) DEBUG: timer routine:0,tl=0x422b8e6c next=(nil)
10(2040) DEBUG: timer routine:4,tl=0x422b8e58 next=(nil)
9(2038) fifo_get_method: method: 'BYE'
9(2038) DEBUG: fifo_get_ruri: 'sip:6603000@10.0.0.236'
9(2038) DEBUG: fifo_get_nexthop: hop:
'sip:6605454@200.110.2.131;ftag=3b00d2eea4;lr=on'
9(2038) fifo_get_headers: headers: From:
<sip:6605454@call.millicom.com.pe>;tag=0000078C0EA8250E
To: <sip:6603000@call.millicom.com.pe>;tag=3b00d2eea4
CSeq: 214 BYE
Call-ID: 3bdd9800-8a85-d2ac-82ee-0002a40055b2(a)10.0.0.236
Contact: <sip:6605454@200.110.2.131:5090
Route: <sip:6605454@200.110.2.131;ftag=3b00d2eea4;lr=on
9(2038) parse_headers: flags=-1
9(2038) DEBUG: add_param: tag=3b00d2eea4
9(2038) end of header reached, state=29
9(2038) DEBUG: get_hdr_field: <To> [51]; uri=[sip:6603000@call.millicom.com.pe]
9(2038) DEBUG: to body [<sip:6603000@call.millicom.com.pe>]
9(2038) get_hdr_field: cseq <CSeq>: <214> <BYE 9(2038) DEBUG: fifo_uac: parse_headers
succeeded
9(2038) fifo_get_body: body:
9(2038) DEBUG: add_param: tag=0000078C0EA8250E
9(2038) end of header reached, state=29
9(2038) DEBUG: get_hf_block: one more hf processed
9(2038) DEBUG: get_hf_block: one more hf processed
9(2038) DEBUG: fifo_uac: EoL -- proceeding to transaction creation
9(2038) DEBUG: mk_proxy: doing DNS lookup...
9(2038) DEBUG: dlg2hash: 24287
9(2038) DEBUG: add_to_tail_of_timer[4]: 0x422b82a0
9(2038) DEBUG: add_to_tail_of_timer[0]: 0x422b82b4
4(2029) SIP Reply (status):
4(2029) version: <SIP/2.0 4(2029) status:
<481 4(2029) reason: <Call Leg/Transaction Does
Not Exist 4(2029)
parse_headers: flags=1
4(2029) Found param type 232, <branch> = <z9hG4bKfde5.d3b83bb7.0>; state=16
4(2029) end of header reached, state=5
4(2029) parse_headers: Via found, flags=1
4(2029) parse_headers: this is the first via
4(2029) After parse_msg...
4(2029) forward_reply: found module tm, passing reply to it
4(2029) DEBUG: t_check: msg id=5 global id=4 T start=0xffffffff
4(2029) parse_headers: flags=17
4(2029) DEBUG: add_param: tag=3b00d2eea4
4(2029) end of header reached, state=29
4(2029) DEBUG: get_hdr_field: <To> [51]; uri=[sip:6603000@call.millicom.com.pe]
4(2029) DEBUG: to body [<sip:6603000@call.millicom.com.pe>]
4(2029) get_hdr_field: cseq <CSeq>: <214> <BYE 4(2029) DEBUG: t_reply_matching: hash 24287
label 2075364157 branch 0
4(2029) DEBUG: t_reply_matching: reply matched (T=0x422b8178)!
4(2029) DEBUG: t_check: msg id=5 global id=5 T end=0x422b8178
4(2029) DEBUG: t_on_reply: org. status uas=0, uac[0]=0 local=1 is_invite=0)
4(2029) ->>>>>>>>> T_code=0, new_code=481
4(2029) DEBUG: local_reply: branch=0, save=0, winner=0
4(2029) DEBUG: local transaction completed
4(2029) !!!!! ref_counter: 1
4(2029) DEBUG: fifo UAC completed with status 481
4(2029) DEBUG: fifo_callback sucesssfuly completed
4(2029) DEBUG: cleanup_uacs: RETR/FR timers reset
4(2029) DEBUG: add_to_tail_of_timer[2]: 0x422b81c0
4(2029) DEBUG:destroy_avp_list: destroing list (nil)
4(2029) receive_msg: cleaning up
10(2040) DEBUG: timer routine:0,tl=0x422b82b4 next=(nil)
10(2040) DEBUG: timer routine:4,tl=0x422b82a0 next=(nil)
10(2040) DEBUG: timer routine:2,tl=0x422b8d78 next=(nil)
10(2040) DEBUG: wait_handler : removing 0x422b8d30 from table
10(2040) DEBUG: delete transaction 0x422b8d30
10(2040) DEBUG: wait_handler : done
10(2040) DEBUG: timer routine:2,tl=0x422b81c0 next=(nil)
10(2040) DEBUG: wait_handler : removing 0x422b8178 from table
10(2040) DEBUG: delete transaction 0x422b8178
10(2040) DEBUG: wait_handler : done
Bogdan-Andrei IANCU <iancu(a)fokus.fraunhofer.de> wrote:
Rafael J. Risco G.V wrote:
> now it works perfectly.
>
> Rafael
---------------------------------
Do You Yahoo!?
Todo lo que quieres saber de Estados Unidos, América Latina y el resto del Mundo.
Visíta Yahoo! Noticias.
hi
I am answering myself... :
1. it is necessary to load VM module in the first ser instance too.
actually you don't need VM in first instance. My guess is you mixed up
the fifo files used by both server (maybe they have the same name) and
SEMS fifo commands reach the first instance, instead the second.
bogdan
> 2.- In voicemail.cfg the fifo value is fifo="/tmp/vm_ser_fifo"
> 3.- In Sems.cfg: the value of 'ser_fifo_name' is "/tmp/vm_ser_fifo"
> instead of "/tmp/ser_fifo" ( ser_fifo_name=/tmp/vm_ser_fifo )
7269
days inactive
7271
days old
5 comments
2 participants
participants (2)
-
Bogdan-Andrei IANCU -
Rafael J. Risco G.V