[Serusers] Sems accpets non-existent users calls
Carlos Loarca
songohan15 at yahoo.com
Mon Dec 13 10:08:11 CET 2004
Hello Jiri, Rafael, Ulrich:
I installed ser (sip router) and sems (sip + sems),
after struggling for long time with them and make them
work (conference, authentication, all my needs),
everythind worked fine. Then, I needed to restart the
servers. When I tested my system again with the same
configuration files, it didnt work properly.
Calls between online users still works, but when I
call a non-existent user (fake one), the system still
tries (TRYING state) to establish it even though, sems
display a "no email address" notification. I receive a
Timeout message after a while.
This is the place where I download my servers
ftp://ftp.berlios.de/pub/ser/0.8.14/ and it is
supposed to be the stable version and the sems server
installed is the one on contrib folder.
I attach the log files from sems(notice the "no email
address" message) and ser at the end of this text.
Regarding the configuration files, I'm using the basic
ones from this distribution.
Can you give me a clue of what is going on???
Thanx,
Songo.
---------------SER OUTPUT IN DEBUG MODE--------------
4(5556) parse_headers: flags=-1
4(5556) DEBUG: t_check: msg id=2 global id=2 T
start=0x402f3200
4(5556) DEBUG: t_check: T alredy found!
4(5556) record_route->nameaddr.uri:
sip:juan at 147.214.193.11;ftag=1265157650;lr=on
4(5556) vm: first proxy has loose routing.
4(5556) vm: calculated route:
<sip:juan at 147.214.193.11;ftag=1265157650;lr=on>
4(5556) vm: next r-uri:
sip:ecarloa at 147.214.141.157:5060
4(5556) parse_headers: flags=-1
4(5556) query="select email_address from subscriber
where username='juan'"
4(5556) vm: write_to_vm_fifo: <0.2
sip_request.voicemail
INVITE
juan
.
conf.wider.se
10.1.0.15
5090
sip:juan at conf.wider.se:5090
sip:ecarloa at 147.214.141.157:5060
ecarloa <sip:ecarloa at wider.se>
<sip:juan at wider.se>
226C518E-3FCF-413B-A2D4-EDF5C1F24E26 at 147.214.141.157
1265157650
.
54916
57670:1263549648
<sip:juan at 147.214.193.11;ftag=1265157650;lr=on>
sip:juan at 147.214.193.11;ftag=1265157650;lr=on
P-MsgFlags: 0
.
v=0
o=ecarloa 1903577 1903577 IN IP4 147.214.141.157
s=X-PRO
c=IN IP4 147.214.141.157
t=0 0
m=audio 8000 RTP/AVP 0 101
a=rtpmap:0 pcmu/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
>
5(5557) DEBUG: fifo_t_reply: ############### begin
##############
5(5557) DEBUG: fifo_t_reply:
trans_id=57670:1263549648
5(5557) DEBUG: fifo_t_reply: hash_index=57670
label=1263549648
5(5557) DEBUG: fifo_t_reply: to-tag: 000015BD6507507B
5(5557) DEBUG: fifo_t_reply: new headers: Contact:
<sip:juan at 10.1.0.15:5090>
5(5557) DEBUG: t_lookup_ident: transaction found
5(5557) parse_headers: flags=-1
5(5557) check_via_address(147.214.193.11,
147.214.193.11, 0)
5(5557) t_reply_with_body: buffer computed
5(5557) DEBUG: cleanup_uacs: RETR/FR timers reset
5(5557) DEBUG: add_to_tail_of_timer[4]: 0x402f32b4
5(5557) DEBUG: add_to_tail_of_timer[0]: 0x402f32c8
5(5557) DEBUG: reply sent out. buf=0x80db908: SIP/2.0
4..., shmem=0x402f49a0: SIP/2.0 4
5(5557) DEBUG: t_reply: finished
5(5557) DEBUG: fifo_t_reply: ################ end
##############
4(5556) DEBUG: write_to_vm_fifo: write completed
4(5556) DEBUG: add_to_tail_of_timer[0]: 0x402f333c
4(5556) **************** vm start - end
******************
4(5556) DEBUG:destroy_avp_list: destroing list (nil)
4(5556) receive_msg: cleaning up
6(5558) DEBUG: timer routine:4,tl=0x402f32b4
next=(nil)
6(5558) DEBUG: retransmission_handler : reply
resending (t=0x402f3200, SIP/2.0 4 ... )
6(5558) DEBUG: reply retransmitted. buf=0x421a1de0:
SIP/2.0 4..., shmem=0x402f49a0: SIP/2.0 4
6(5558) DEBUG: add_to_tail_of_timer[5]: 0x402f32b4
6(5558) DEBUG: retransmission_handler : done
2(5554) SIP Request:
2(5554) method: <INVITE>
2(5554) uri: <sip:juan at conf.wider.se:5090>
2(5554) version: <SIP/2.0>
2(5554) parse_headers: flags=1
2(5554) Found param type 232, <branch> =
<z9hG4bK641e.35bfa03.0>; state=16
2(5554) end of header reached, state=5
2(5554) parse_headers: Via found, flags=1
2(5554) parse_headers: this is the first via
2(5554) After parse_msg...
2(5554) preparing to run routing scripts...
2(5554) DEBUG : is_maxfwd_present: searching for
max_forwards header
2(5554) parse_headers: flags=128
2(5554) Found param type 235, <rport> = <5060>;
state=6
2(5554) Found param type 232, <branch> =
<z9hG4bK0CEAD939118543379231D866C75F6C83>; state=16
2(5554) end of header reached, state=5
2(5554) parse_headers: Via found, flags=128
2(5554) parse_headers: this is the second via
2(5554) end of header reached, state=9
2(5554) DEBUG: get_hdr_field: <To> [21];
uri=[sip:juan at wider.se]
2(5554) DEBUG: to body [<sip:juan at wider.se>
]
2(5554) get_hdr_field: cseq <CSeq>: <54916> <INVITE>
2(5554) DEBUG: is_maxfwd_present: value = 69
2(5554) DEBUG: add_param: tag=1265157650
2(5554) end of header reached, state=29
2(5554) parse_headers: flags=256
2(5554) DEBUG: get_hdr_body : content_length=199
2(5554) found end of header
2(5554) find_first_route(): No Route headers found
2(5554) loose_route(): There is no Route HF
2(5554) check_self - checking if host==us: 13==9 &&
[conf.wider.se] == [10.1.0.15]
2(5554) check_self - checking if port 5090 matches
port 5090
2(5554) DEBUG: t_addifnew: msg id=4 , global msg id=3
, T on entrance=0xffffffff
2(5554) parse_headers: flags=-1
2(5554) parse_headers: flags=60
2(5554) t_lookup_request: start searching:
hash=57670, isACK=0
2(5554) DEBUG: RFC3261 transaction matched,
tid=641e.35bfa03.0
2(5554) DEBUG: t_lookup_request: transaction found
(T=0x402f3200)
2(5554) DEBUG: reply retransmitted. buf=0x421a1de0:
SIP/2.0 4..., shmem=0x402f49a0: SIP/2.0 4
2(5554) DEBUG:destroy_avp_list: destroing list (nil)
2(5554) receive_msg: cleaning up
6(5558) DEBUG: timer routine:5,tl=0x402f32b4
next=(nil)
6(5558) DEBUG: retransmission_handler : reply
resending (t=0x402f3200, SIP/2.0 4 ... )
6(5558) DEBUG: reply retransmitted. buf=0x421a1de0:
SIP/2.0 4..., shmem=0x402f49a0: SIP/2.0 4
6(5558) DEBUG: add_to_tail_of_timer[6]: 0x402f32b4
6(5558) DEBUG: retransmission_handler : done
4(5556) SIP Request:
4(5556) method: <INVITE>
4(5556) uri: <sip:juan at conf.wider.se:5090>
4(5556) version: <SIP/2.0>
4(5556) parse_headers: flags=1
4(5556) Found param type 232, <branch> =
<z9hG4bK641e.35bfa03.0>; state=16
4(5556) end of header reached, state=5
--------------SEMS OUTPUT IN DEBUG
MODE----------------
(5565) INFO: run (AmServer.cpp:154): Sems started
(5565) DEBUG: run (AmServer.cpp:167): version= <0.2>
(5565) DEBUG: run (AmServer.cpp:177): fct_name=
<sip_request.voicemail>
(5565) DEBUG: execute (AmServer.cpp:236): cmd.method=
<INVITE>
(5565) DEBUG: execute (AmServer.cpp:237): cmd.user=
<juan>
(5565) DEBUG: execute (AmServer.cpp:238): cmd.email=
<>
(5565) DEBUG: execute (AmServer.cpp:239): cmd.domain=
<conf.wider.se>
(5565) DEBUG: execute (AmServer.cpp:240): cmd.dstip=
<10.1.0.15>
(5565) DEBUG: execute (AmServer.cpp:241): cmd.port=
<5090>
(5565) DEBUG: execute (AmServer.cpp:242): cmd.r_uri=
<sip:juan at conf.wider.se:5090>
(5565) DEBUG: execute (AmServer.cpp:243):
cmd.from_uri= <sip:ecarloa at 147.214.141.157:5060>
(5565) DEBUG: execute (AmServer.cpp:244): cmd.from=
<ecarloa <sip:ecarloa at wider.se>>
(5565) DEBUG: execute (AmServer.cpp:245): cmd.to=
<<sip:juan at wider.se>>
(5565) DEBUG: execute (AmServer.cpp:246): cmd.callid=
<1B0E0906-C5C0-44B1-B29A-CBE64417276B at 147.214.141.157>
(5565) DEBUG: execute (AmServer.cpp:247):
cmd.from_tag= <3474869260>
(5565) DEBUG: execute (AmServer.cpp:248): cmd.to_tag=
<>
(5565) DEBUG: execute (AmServer.cpp:251): cseq_str=
<32452>
(5565) DEBUG: execute (AmServer.cpp:256): cseq=
<32452>(32452)
(5565) DEBUG: execute (AmServer.cpp:258): cmd.key=
<34926:921248335>
(5565) DEBUG: execute (AmServer.cpp:259): cmd.route=
<<sip:juan at 147.214.193.11;ftag=3474869260;lr=on>>
(5565) DEBUG: execute (AmServer.cpp:260):
cmd.next_hop=
<sip:juan at 147.214.193.11;ftag=3474869260;lr=on>
(5565) DEBUG: execute (AmServer.cpp:266): hdrs:
`P-MsgFlags: 0
'
(5565) DEBUG: execute (AmServer.cpp:273): body: `v=0
o=ecarloa 1783814 1783814 IN IP4 147.214.141.157
s=X-PRO
c=IN IP4 147.214.141.157
t=0 0
m=audio 8000 RTP/AVP 0 101
a=rtpmap:0 pcmu/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
'
(5565) DEBUG: execute (AmServer.cpp:284): everything
is OK !
(5565) ERROR: startSession (AmSession.cpp:442): 404
voicemail: no email address for user <juan>
(5565) DEBUG: sendToFIFO (AmRequest.cpp:194):
msg=<:vm_reply:000015BD5025B896
404
voicemail: no email address for user <juan>
34926:921248335
000015BD47C9409A
Contact: <sip:juan at 10.1.0.15:5090>
.
.
>
(5565) DEBUG: openReplyFifo (AmRequest.cpp:362):
flags=800
(5565) DEBUG: openReplyFifo (AmRequest.cpp:364):
flags=0
(5565) DEBUG: write_to_fifo (AmRequest.cpp:708): Write
to fifo: completed
(5565) DEBUG: getReturnCode (AmRequest.cpp:400):
Waiting for Ser to respond to the FIFO request...
(5565) DEBUG: getReturnCode (AmRequest.cpp:421):
response from Ser: 200 fifo_t_reply succeeded
(5565) DEBUG: getReturnCode (AmRequest.cpp:442):
n_cmd.from_uri= <>
(5565) DEBUG: getReturnCode (AmRequest.cpp:445):
n_cmd.next_hop= <>
(5565) DEBUG: getReturnCode (AmRequest.cpp:448):
n_cmd.route= <>
(5565) DEBUG: sendToFIFO (AmRequest.cpp:216):
sendToFifo returns ret=getReturnCode=0
=====
CUANDO UNO SABE A DONDE VA, EL MUNDO SE ABRE PARA DEJARLO PASAR.
__________________________________
Do you Yahoo!?
Yahoo! Mail - Find what you need with new enhanced search.
http://info.mail.yahoo.com/mail_250
More information about the sr-users
mailing list