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@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.
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@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@lists.iptel.org http://lists.iptel.org/mailman/listinfo/serusers
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@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@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@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@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.
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@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@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@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@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@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.
Rafael J. Risco G.V wrote:
hi I am answering myself... :
- 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
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@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@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@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@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@fokus.fraunhofer.de wrote: Rafael J. Risco G.V wrote:
hi I am answering myself... :
- 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
--------------------------------- Do You Yahoo!? Todo lo que quieres saber de Estados Unidos, América Latina y el resto del Mundo. Visíta Yahoo! Noticias.
-
Bogdan-Andrei IANCU -
Rafael J. Risco G.V