<DIV><FONT size=2>
<P>Hi</P>
<P>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:</P>
<P>[root@gkproxy01 answer_machine]# (1932) ERROR: bye (AmRequest.cpp:238): AmRequest::bye: 481 Call Leg/Transaction Does Not Exist<BR>(1932) ERROR: bye (AmRequest.cpp:238): AmRequest::bye: 481 Call Leg/Transaction Does Not Exist</P>
<P>I can listen the announcement but do not receive any mail... </P>
<P>this is the Voicemail debug:</P>
<P>[root@gkproxy01 sems_2004-07-27]# 3(2028) SIP Request:<BR> 3(2028) method: <INVITE><BR> 3(2028) uri: <sip:6605454@call.millicom.com.pe:5090><BR> 3(2028) version: <SIP/2.0><BR> 3(2028) parse_headers: flags=1<BR> 3(2028) Found param type 232, <branch> = <z9hG4bK1ee5.e67a562.0>; state=16<BR> 3(2028) end of header reached, state=5<BR> 3(2028) parse_headers: Via found, flags=1<BR> 3(2028) parse_headers: this is the first via<BR> 3(2028) After parse_msg...<BR> 3(2028) preparing to run routing scripts...<BR> 3(2028) DEBUG : is_maxfwd_present: searching for max_forwards header<BR> 3(2028) parse_headers: flags=128<BR> 3(2028) Found param type 232, <branch> = <z9hG4bK3b00d2eea4212>; state=16<BR> 3(2028) end of header reached, state=5<BR> 3(2028) parse_headers: Via found, flags=128<BR> 3(2028) parse_headers: this is
the second via<BR> 3(2028) end of header reached, state=9<BR> 3(2028) DEBUG: get_hdr_field: <To> [36]; uri=[sip:6605454@call.millicom.com.pe] <BR> 3(2028) DEBUG: to body [<sip:6605454@call.millicom.com.pe><BR>]<BR> 3(2028) get_hdr_field: cseq <CSeq>: <212> <INVITE><BR> 3(2028) DEBUG: get_hdr_body : content_length=182<BR> 3(2028) DEBUG: is_maxfwd_present: value = 69 <BR> 3(2028) check_self - checking if host==us: 20==13 && [call.millicom.com.pe] == [200.110.2.131]<BR> 3(2028) check_self - checking if port 5090 matches port 5090<BR> 3(2028) check_self - checking if host==us: 20==9 && [call.millicom.com.pe] == [127.0.0.1]<BR> 3(2028) check_self - checking if port 5090 matches port 5090<BR> 3(2028) DEBUG: t_addifnew: msg id=2 , global msg id=1 , T on entrance=0xffffffff<BR> 3(2028) parse_headers: flags=-1<BR> 3(2028) found end of header<BR> 3(2028)
parse_headers: flags=60<BR> 3(2028) t_lookup_request: start searching: hash=24289, isACK=0<BR> 3(2028) DEBUG: RFC3261 transaction matching failed<BR> 3(2028) DEBUG: t_lookup_request: no transaction found<BR> 3(2028) DEBUG: add_param: tag=3b00d2eea4<BR> 3(2028) end of header reached, state=29<BR> 3(2028) DEBUG: t_check: msg id=2 global id=2 T start=0x422b8d30<BR> 3(2028) DEBUG: t_check: T alredy found!<BR> 3(2028) parse_headers: flags=-1<BR> 3(2028) check_via_address(200.110.2.131, 200.110.2.131, 0)<BR> 3(2028) WARNING:vqm_resize: resize(0) called<BR> 3(2028) DEBUG: reply sent out. buf=0x80dbd58: SIP/2.0 1..., shmem=0x422b68b0: SIP/2.0 1<BR> 3(2028) DEBUG: t_reply: finished<BR> 3(2028) **************** vm start - begin ******************<BR> 3(2028) parse_headers: flags=-1<BR> 3(2028) DEBUG: t_check: msg id=2 global id=2 T start=0x422b8d30<BR> 3(2028) DEBUG: t_check: T alredy found!<BR> 3(2028)
record_route->nameaddr.uri: sip:6605454@200.110.2.131;ftag=3b00d2eea4;lr=on<BR> 3(2028) vm: first proxy has loose routing.<BR> 3(2028) vm: calculated route: <sip:6605454@200.110.2.131;ftag=3b00d2eea4;lr=on><BR> 3(2028) vm: next r-uri: sip:6603000@10.0.0.236<BR> 3(2028) parse_headers: flags=-1<BR> 3(2028) query="select email_address from subscriber where username='6605454'"<BR> 3(2028) vm: write_to_vm_fifo: <0.2<BR>sip_request.voicemail<BR>INVITE<BR>6605454<BR><A href="mailto:rrisco@millicom.com.pe">rrisco@millicom.com.pe</A><BR>call.millicom.com.pe<BR>200.110.2.131<BR>5090<BR>sip:6605454@call.millicom.com.pe:5090<BR>sip:6603000@10.0.0.236<BR><sip:6603000@call.millicom.com.pe><BR><sip:6605454@call.millicom.com.pe><BR><A
href="mailto:3bdd9800-8a85-d2ac-82ee-0002a40055b2@10.0.0.236">3bdd9800-8a85-d2ac-82ee-0002a40055b2@10.0.0.236</A><BR>3b00d2eea4<BR>.<BR>212<BR>24289:1288922012<BR><sip:6605454@200.110.2.131;ftag=3b00d2eea4;lr=on><BR>sip:6605454@200.110.2.131;ftag=3b00d2eea4;lr=on<BR>P-MsgFlags: 0<BR>Min-SE: 1800<BR>Date: Sun, 26 Apr 1970 22:48:27 GMT<BR>.<BR>v=0<BR>o=- 10018107 10018107 IN IP4 10.0.0.236<BR>s=AddPac Gateway SDP<BR>c=IN IP4 10.0.0.236<BR>t=0 0<BR>m=audio 23562 RTP/AVP 4 18 0 101<BR>a=rtpmap:101 telephone-event/8000<BR>a=fmtp:101 0-15</P>
<P>><BR> 3(2028) DEBUG: write_to_vm_fifo: write completed<BR> 3(2028) DEBUG: add_to_tail_of_timer[0]: 0x422b8e6c<BR> 3(2028) **************** vm start - end ******************<BR> 3(2028) DEBUG:destroy_avp_list: destroing list (nil)<BR> 3(2028) receive_msg: cleaning up<BR> 9(2038) DEBUG: fifo_t_reply: ############### begin ##############<BR> 9(2038) DEBUG: fifo_t_reply: trans_id=24289:1288922012<BR> 9(2038) DEBUG: fifo_t_reply: hash_index=24289 label=1288922012<BR> 9(2038) DEBUG: fifo_t_reply: to-tag: 0000078C0EA8250E<BR> 9(2038) DEBUG: fifo_t_reply: new headers: Contact: <sip:6605454@200.110.2.131:5090><BR>Content-Type: application/sdp</P>
<P> 9(2038) DEBUG: fifo_t_reply: body: v=0<BR>o=username 0 0 IN IP4 200.110.2.131<BR>s=session<BR>c=IN IP4 200.110.2.131<BR>t=0 0<BR>m=audio 1296 RTP/AVP 0<BR>a=rtpmap:0 /</P>
<P> 9(2038) DEBUG: t_lookup_ident: transaction found<BR> 9(2038) parse_headers: flags=-1<BR> 9(2038) check_via_address(200.110.2.131, 200.110.2.131, 0)<BR> 9(2038) t_reply_with_body: buffer computed<BR> 9(2038) DEBUG: cleanup_uacs: RETR/FR timers reset<BR> 9(2038) DEBUG: add_to_tail_of_timer[4]: 0x422b8de4<BR> 9(2038) DEBUG: add_to_tail_of_timer[0]: 0x422b8df8<BR> 9(2038) DEBUG: reply sent out. buf=0x80dbe20: SIP/2.0 2..., shmem=0x422b6c20: SIP/2.0 2<BR> 9(2038) DEBUG: t_reply: finished<BR> 9(2038) DEBUG: fifo_t_reply: ################ end ##############<BR>10(2040) DEBUG: timer routine:0,tl=0x422b8e6c next=(nil)<BR>10(2040) DEBUG: timer routine:4,tl=0x422b8de4 next=(nil)<BR>10(2040) DEBUG: retransmission_handler : reply resending (t=0x422b8d30, SIP/2.0 2 ... )<BR>10(2040) DEBUG: reply retransmitted. buf=0x4416b400: SIP/2.0 2..., shmem=0x422b6c20: SIP/2.0 2<BR>10(2040) DEBUG: add_to_tail_of_timer[5]: 0x422b8de4<BR>10(2040) DEBUG:
retransmission_handler : done<BR> 4(2029) SIP Request:<BR> 4(2029) method: <ACK><BR> 4(2029) uri: <sip:6605454@call.millicom.com.pe:5090><BR> 4(2029) version: <SIP/2.0><BR> 4(2029) parse_headers: flags=1<BR> 4(2029) Found param type 232, <branch> = <0>; state=16<BR> 4(2029) end of header reached, state=5<BR> 4(2029) parse_headers: Via found, flags=1<BR> 4(2029) parse_headers: this is the first via<BR> 4(2029) After parse_msg...<BR> 4(2029) preparing to run routing scripts...<BR> 4(2029) DEBUG : sl_filter_ACK: to late to be a local ACK!<BR> 4(2029) DEBUG : is_maxfwd_present: searching for max_forwards header<BR> 4(2029) parse_headers: flags=128<BR> 4(2029) Found param type 232, <branch> = <z9hG4bK3b00d2eea4212>; state=16<BR> 4(2029) end of header reached, state=5<BR> 4(2029) parse_headers: Via found,
flags=128<BR> 4(2029) parse_headers: this is the second via<BR> 4(2029) DEBUG: add_param: tag=0000078C0EA8250E<BR> 4(2029) end of header reached, state=29<BR> 4(2029) DEBUG: get_hdr_field: <To> [57]; uri=[sip:6605454@call.millicom.com.pe] <BR> 4(2029) DEBUG: to body [<sip:6605454@call.millicom.com.pe>]<BR> 4(2029) get_hdr_field: cseq <CSeq>: <212> <ACK><BR> 4(2029) DEBUG: get_hdr_body : content_length=0<BR> 4(2029) DEBUG: is_maxfwd_present: value = 69 <BR> 4(2029) check_self - checking if host==us: 20==13 && [call.millicom.com.pe] == [200.110.2.131]<BR> 4(2029) check_self - checking if port 5090 matches port 5090<BR> 4(2029) check_self - checking if host==us: 20==9 && [call.millicom.com.pe] == [127.0.0.1]<BR> 4(2029) check_self - checking if port 5090 matches port 5090<BR> 4(2029) DEBUG: t_addifnew: msg id=4 , global msg id=3 , T on
entrance=0xffffffff<BR> 4(2029) parse_headers: flags=-1<BR> 4(2029) found end of header<BR> 4(2029) parse_headers: flags=60<BR> 4(2029) t_lookup_request: start searching: hash=24289, isACK=1<BR> 4(2029) DEBUG: proceeding to pre-RFC3261 transaction matching<BR> 4(2029) DEBUG: t_lookup_request: transaction found (T=0x422b8d30)<BR> 4(2029) DEBUG: cleanup_uacs: RETR/FR timers reset<BR> 4(2029) DEBUG: add_to_tail_of_timer[2]: 0x422b8d78<BR> 4(2029) DEBUG:destroy_avp_list: destroing list (nil)<BR> 4(2029) receive_msg: cleaning up<BR>10(2040) DEBUG: timer routine:0,tl=0x422b8df8 next=(nil)<BR>10(2040) DEBUG: timer routine:5,tl=0x422b8de4 next=(nil)<BR>10(2040) DEBUG: timer routine:2,tl=0x422b8d78 next=(nil)<BR>10(2040) DEBUG: wait_handler : removing 0x422b8d30 from table <BR>10(2040) DEBUG: delete transaction 0x422b8d30<BR>10(2040) DEBUG: wait_handler : done</P>
<P>[root@gkproxy01 sems_2004-07-27]# <BR>[root@gkproxy01 sems_2004-07-27]# <BR>[root@gkproxy01 sems_2004-07-27]# <BR>[root@gkproxy01 sems_2004-07-27]# <BR>[root@gkproxy01 sems_2004-07-27]# <BR>[root@gkproxy01 sems_2004-07-27]# <BR>[root@gkproxy01 sems_2004-07-27]# <BR>[root@gkproxy01 sems_2004-07-27]# 9(2038) fifo_get_method: method: 'BYE'<BR> 9(2038) DEBUG: fifo_get_ruri: 'sip:6603000@10.0.0.236'<BR> 9(2038) DEBUG: fifo_get_nexthop: hop: 'sip:6605454@200.110.2.131;ftag=3b00d2eea4;lr=on'<BR> 9(2038) fifo_get_headers: headers: From: <sip:6605454@call.millicom.com.pe>;tag=0000078C0EA8250E<BR>To: <sip:6603000@call.millicom.com.pe>;tag=3b00d2eea4<BR>CSeq: 213 BYE<BR>Call-ID: <A href="mailto:3bdd9800-8a85-d2ac-82ee-0002a40055b2@10.0.0.236">3bdd9800-8a85-d2ac-82ee-0002a40055b2@10.0.0.236</A><BR>Contact: <sip:6605454@200.110.2.131:5090><BR>Route: <sip:6605454@200.110.2.131;ftag=3b00d2eea4;lr=on></P>
<P> 9(2038) parse_headers: flags=-1<BR> 9(2038) DEBUG: add_param: tag=3b00d2eea4<BR> 9(2038) end of header reached, state=29<BR> 9(2038) DEBUG: get_hdr_field: <To> [51]; uri=[sip:6603000@call.millicom.com.pe] <BR> 9(2038) DEBUG: to body [<sip:6603000@call.millicom.com.pe>]<BR> 9(2038) get_hdr_field: cseq <CSeq>: <213> <BYE><BR> 9(2038) DEBUG: fifo_uac: parse_headers succeeded<BR> 9(2038) fifo_get_body: body: <BR> 9(2038) DEBUG: add_param: tag=0000078C0EA8250E<BR> 9(2038) end of header reached, state=29<BR> 9(2038) DEBUG: get_hf_block: one more hf processed<BR> 9(2038) DEBUG: get_hf_block: one more hf processed<BR> 9(2038) DEBUG: fifo_uac: EoL -- proceeding to transaction creation<BR> 9(2038) DEBUG: mk_proxy: doing DNS lookup...<BR> 9(2038) DEBUG: dlg2hash: 24290<BR> 9(2038) DEBUG: add_to_tail_of_timer[4]: 0x422b8e58<BR> 9(2038) DEBUG: add_to_tail_of_timer[0]:
0x422b8e6c<BR> 1(2024) SIP Reply (status):<BR> 1(2024) version: <SIP/2.0><BR> 1(2024) status: <481><BR> 1(2024) reason: <Call Leg/Transaction Does Not Exist><BR> 1(2024) parse_headers: flags=1<BR> 1(2024) Found param type 232, <branch> = <z9hG4bK2ee5.0b7fc205.0>; state=16<BR> 1(2024) end of header reached, state=5<BR> 1(2024) parse_headers: Via found, flags=1<BR> 1(2024) parse_headers: this is the first via<BR> 1(2024) After parse_msg...<BR> 1(2024) forward_reply: found module tm, passing reply to it<BR> 1(2024) DEBUG: t_check: msg id=3 global id=2 T start=0xffffffff<BR> 1(2024) parse_headers: flags=17<BR> 1(2024) DEBUG: add_param: tag=3b00d2eea4<BR> 1(2024) end of header reached, state=29<BR> 1(2024) DEBUG: get_hdr_field: <To> [51]; uri=[sip:6603000@call.millicom.com.pe] <BR> 1(2024) DEBUG: to body
[<sip:6603000@call.millicom.com.pe>]<BR> 1(2024) get_hdr_field: cseq <CSeq>: <213> <BYE><BR> 1(2024) DEBUG: t_reply_matching: hash 24290 label 1345124272 branch 0<BR> 1(2024) DEBUG: t_reply_matching: reply matched (T=0x422b8d30)!<BR> 1(2024) DEBUG: t_check: msg id=3 global id=3 T end=0x422b8d30<BR> 1(2024) DEBUG: t_on_reply: org. status uas=0, uac[0]=0 local=1 is_invite=0)<BR> 1(2024) ->>>>>>>>> T_code=0, new_code=481<BR> 1(2024) DEBUG: local_reply: branch=0, save=0, winner=0<BR> 1(2024) DEBUG: local transaction completed<BR> 1(2024) !!!!! ref_counter: 1<BR> 1(2024) DEBUG: fifo UAC completed with status 481<BR> 1(2024) DEBUG: fifo_callback sucesssfuly completed<BR> 1(2024) DEBUG: cleanup_uacs: RETR/FR timers reset<BR> 1(2024) DEBUG: add_to_tail_of_timer[2]: 0x422b8d78<BR> 1(2024) DEBUG:destroy_avp_list: destroing list (nil)<BR> 1(2024) receive_msg: cleaning
up<BR>10(2040) DEBUG: timer routine:0,tl=0x422b8e6c next=(nil)<BR>10(2040) DEBUG: timer routine:4,tl=0x422b8e58 next=(nil)<BR> 9(2038) fifo_get_method: method: 'BYE'<BR> 9(2038) DEBUG: fifo_get_ruri: 'sip:6603000@10.0.0.236'<BR> 9(2038) DEBUG: fifo_get_nexthop: hop: 'sip:6605454@200.110.2.131;ftag=3b00d2eea4;lr=on'<BR> 9(2038) fifo_get_headers: headers: From: <sip:6605454@call.millicom.com.pe>;tag=0000078C0EA8250E<BR>To: <sip:6603000@call.millicom.com.pe>;tag=3b00d2eea4<BR>CSeq: 214 BYE<BR>Call-ID: <A href="mailto:3bdd9800-8a85-d2ac-82ee-0002a40055b2@10.0.0.236">3bdd9800-8a85-d2ac-82ee-0002a40055b2@10.0.0.236</A><BR>Contact: <sip:6605454@200.110.2.131:5090><BR>Route: <sip:6605454@200.110.2.131;ftag=3b00d2eea4;lr=on></P>
<P> 9(2038) parse_headers: flags=-1<BR> 9(2038) DEBUG: add_param: tag=3b00d2eea4<BR> 9(2038) end of header reached, state=29<BR> 9(2038) DEBUG: get_hdr_field: <To> [51]; uri=[sip:6603000@call.millicom.com.pe] <BR> 9(2038) DEBUG: to body [<sip:6603000@call.millicom.com.pe>]<BR> 9(2038) get_hdr_field: cseq <CSeq>: <214> <BYE><BR> 9(2038) DEBUG: fifo_uac: parse_headers succeeded<BR> 9(2038) fifo_get_body: body: <BR> 9(2038) DEBUG: add_param: tag=0000078C0EA8250E<BR> 9(2038) end of header reached, state=29<BR> 9(2038) DEBUG: get_hf_block: one more hf processed<BR> 9(2038) DEBUG: get_hf_block: one more hf processed<BR> 9(2038) DEBUG: fifo_uac: EoL -- proceeding to transaction creation<BR> 9(2038) DEBUG: mk_proxy: doing DNS lookup...<BR> 9(2038) DEBUG: dlg2hash: 24287<BR> 9(2038) DEBUG: add_to_tail_of_timer[4]: 0x422b82a0<BR> 9(2038) DEBUG: add_to_tail_of_timer[0]:
0x422b82b4<BR> 4(2029) SIP Reply (status):<BR> 4(2029) version: <SIP/2.0><BR> 4(2029) status: <481><BR> 4(2029) reason: <Call Leg/Transaction Does Not Exist><BR> 4(2029) parse_headers: flags=1<BR> 4(2029) Found param type 232, <branch> = <z9hG4bKfde5.d3b83bb7.0>; state=16<BR> 4(2029) end of header reached, state=5<BR> 4(2029) parse_headers: Via found, flags=1<BR> 4(2029) parse_headers: this is the first via<BR> 4(2029) After parse_msg...<BR> 4(2029) forward_reply: found module tm, passing reply to it<BR> 4(2029) DEBUG: t_check: msg id=5 global id=4 T start=0xffffffff<BR> 4(2029) parse_headers: flags=17<BR> 4(2029) DEBUG: add_param: tag=3b00d2eea4<BR> 4(2029) end of header reached, state=29<BR> 4(2029) DEBUG: get_hdr_field: <To> [51]; uri=[sip:6603000@call.millicom.com.pe] <BR> 4(2029) DEBUG: to body
[<sip:6603000@call.millicom.com.pe>]<BR> 4(2029) get_hdr_field: cseq <CSeq>: <214> <BYE><BR> 4(2029) DEBUG: t_reply_matching: hash 24287 label 2075364157 branch 0<BR> 4(2029) DEBUG: t_reply_matching: reply matched (T=0x422b8178)!<BR> 4(2029) DEBUG: t_check: msg id=5 global id=5 T end=0x422b8178<BR> 4(2029) DEBUG: t_on_reply: org. status uas=0, uac[0]=0 local=1 is_invite=0)<BR> 4(2029) ->>>>>>>>> T_code=0, new_code=481<BR> 4(2029) DEBUG: local_reply: branch=0, save=0, winner=0<BR> 4(2029) DEBUG: local transaction completed<BR> 4(2029) !!!!! ref_counter: 1<BR> 4(2029) DEBUG: fifo UAC completed with status 481<BR> 4(2029) DEBUG: fifo_callback sucesssfuly completed<BR> 4(2029) DEBUG: cleanup_uacs: RETR/FR timers reset<BR> 4(2029) DEBUG: add_to_tail_of_timer[2]: 0x422b81c0<BR> 4(2029) DEBUG:destroy_avp_list: destroing list (nil)<BR> 4(2029) receive_msg: cleaning
up<BR>10(2040) DEBUG: timer routine:0,tl=0x422b82b4 next=(nil)<BR>10(2040) DEBUG: timer routine:4,tl=0x422b82a0 next=(nil)<BR>10(2040) DEBUG: timer routine:2,tl=0x422b8d78 next=(nil)<BR>10(2040) DEBUG: wait_handler : removing 0x422b8d30 from table <BR>10(2040) DEBUG: delete transaction 0x422b8d30<BR>10(2040) DEBUG: wait_handler : done<BR>10(2040) DEBUG: timer routine:2,tl=0x422b81c0 next=(nil)<BR>10(2040) DEBUG: wait_handler : removing 0x422b8178 from table <BR>10(2040) DEBUG: delete transaction 0x422b8178<BR>10(2040) DEBUG: wait_handler : done</P>
<P> </P>
<P> </P>
<P> </P>
<P> </P>
<P> </P>
<P> </P>
<P></FONT><BR><BR><B><I>Bogdan-Andrei IANCU <iancu@fokus.fraunhofer.de></I></B> wrote:</P>
<BLOCKQUOTE class=replbq style="PADDING-LEFT: 5px; MARGIN-LEFT: 5px; BORDER-LEFT: #1010ff 2px solid">Rafael J. Risco G.V wrote:<BR><BR>> hi<BR>> I am answering myself... :<BR>> <BR>> 1. it is necessary to load VM module in the first ser instance too.<BR><BR>actually you don't need VM in first instance. My guess is you mixed up <BR>the fifo files used by both server (maybe they have the same name) and <BR>SEMS fifo commands reach the first instance, instead the second.<BR><BR>bogdan<BR><BR>> 2.- In voicemail.cfg the fifo value is fifo="/tmp/vm_ser_fifo"<BR>> 3.- In Sems.cfg: the value of 'ser_fifo_name' is "/tmp/vm_ser_fifo" <BR>> instead of "/tmp/ser_fifo" ( ser_fifo_name=/tmp/vm_ser_fifo )<BR>><BR>> now it works perfectly.<BR>> <BR>> Rafael<BR><BR><BR></BLOCKQUOTE></DIV><p><br><hr size=1 noshade><b>Do You Yahoo!?</b><br>
<a href=http://espanol.yahoo.com/mail_tagline/*http://espanol.news.yahoo.com><img src="http://us.i1.yimg.com/us.yimg.com/i/not/dailynews.gif" width=30 height=26 border=0 align=left></a>
Todo lo que quieres saber de Estados Unidos, América Latina y el resto del Mundo.<br>
Visíta <a href=http://espanol.yahoo.com/mail_tagline/*http://espanol.news.yahoo.com>Yahoo! Noticias</a>.<br>