[Serusers] t_replicate on register, then 404 not found - sr-users

31 Dec 2004


      I have researched this list and all the docs I can find, but I am having a problem I can't solve.
I am using a Sipura registering to my SER server which is replicating register requests to another SIP Gatekeeper.  We are using the other SIP gatekeeper for routing, and it appears that the registration request is being replicated (but then it is removed after a short timeout period).  Anyway, the username on the SIP device and in ser is a 11 digit phone number.
When I dial the phone number from another sip device that is registered, I get 404 Not Found.  I am including my debug for this call as well as parts of my ser.cfg.  Can anyone point me in the right direction? Theoretically, the SER server should send any 11 digit phone number registered to it directly to the registered endpoint, otherwise, relay it to the other SIP router...
Thanks for the help...
198.67.41.194 Is the SIP Router
198.67.41.196 Is my SER server
phone is the user placing the call
12523152553 is the number being called....
-----------------------------------ser.cfg Registration and Routing----------------------------
        if (uri=~"sip:1[0-9]+@.*") {
                rewritehostport("198.67.41.194:5060");
                forward(uri:host, uri:port);
        };
if (method=="REGISTER") {
                        if (!proxy_authorize("sip.simflex.net" /* realm */,
                                "subscriber" /* table name */ )) {
                                proxy_challenge("sip.simflex.net", "0");
                                setflag(1);
                                break;
                        };
                                if (!src_ip==198.67.41.194) {
                                                if(t_newtran()){
                                                t_replicate("198.67.41.194", "5060");
                                                };
                                };
                        save("location");
                        break;
                };
-----------------------------------DEBUG----------------------------
SIP/2.0 183 Session Progress
Call-ID: EBBF58CE-320D-4906-ADD5-34B16E162D8C@10.0.0.2
From: "sip.simflex.net" sip:phone@sip.simflex.net:8286;tag=3089475581
To: sip:12523152553@sip.simflex.net
Content-Length: 0
CSeq: 25148 INVITE
Via: SIP/2.0/UDP 10.0.0.2:8286;received=198.67.41.187;rport=8286;received=198.67.41.187;rport=8286;branch=z9hG4bKABE3A5E79A9544F6A76997C242A1E16C
Record-Route: sip:198.67.41.194:5060;lr
0(27492) DEBUG: reply relayed. buf=0x80c47a8: SIP/2.0 1..., shmem=0xb576a588: SIP/2.0 1
 0(27492) DBG: callback type 7, id 1 entered
 0(27492) DEBUG: add_to_tail_of_timer[1]: 0xb5766de4
 0(27492) DEBUG:destroy_avp_list: destroing list (nil)
 0(27492) receive_msg: cleaning up
 0(27492) SIP Reply  (status):
 0(27492)  version: <SIP/2.0>
 0(27492)  status:  <183>
 0(27492)  reason:  <Session Progress>
 0(27492) parse_headers: flags=1
 0(27492) end of header reached, state=9
 0(27492) DEBUG: get_hdr_field: <To> [35]; uri=[sip:12523152553@sip.simflex.net]
 0(27492) DEBUG: to body [sip:12523152553@sip.simflex.net
]
 0(27492) DEBUG: get_hdr_body : content_length=219
 0(27492) get_hdr_field: cseq <CSeq>: <25148> <INVITE>
 0(27492) Found param type 232, <branch> = <0>; state=16
 0(27492) end of header reached, state=5
 0(27492) parse_headers: Via found, flags=1
 0(27492) parse_headers: this is the first via
 0(27492) After parse_msg...
 0(27492) forward_reply: found module tm, passing reply to it
 0(27492) DEBUG: t_check: msg id=19 global id=18 T start=0xffffffff
 0(27492) parse_headers: flags=17
 0(27492) parse_headers: flags=4
 0(27492) DEBUG: t_reply_matching: failure to match a transaction
 0(27492) DEBUG: t_check: msg id=19 global id=19 T end=(nil)
 0(27492) parse_headers: flags=2
 0(27492) Found param type 232, <branch> = <z9hG4bK174a.53dcb714.0>; state=16
 0(27492) end of header reached, state=5
 0(27492) parse_headers: Via found, flags=2
 0(27492) parse_headers: this is the second via
 0(27492)  old size: 784, new size: 743
 0(27492) build_res_from_sip_res: copied size: orig:311, new: 270, rest: 473 msg=
SIP/2.0 183 Session Progress
Call-ID: EBBF58CE-320D-4906-ADD5-34B16E162D8C@10.0.0.2
From: "sip.simflex.net" sip:phone@sip.simflex.net:8286;tag=3089475581
To: sip:12523152553@sip.simflex.net
Content-Length: 219
Content-Type: application/sdp
CSeq: 25148 INVITE
Via: SIP/2.0/UDP 198.67.41.196;branch=z9hG4bK174a.53dcb714.0
Via: SIP/2.0/UDP 10.0.0.2:8286;received=198.67.41.187;rport=8286;received=198.67.41.187;rport=8286;branch=z9hG4bKABE3A5E79A9544F6A76997C242A1E16C
Record-Route: sip:198.67.41.194:5060;lr
v=0
o=MG4000|1.0 111 12345 IN IP4 198.67.41.194
s=-
c=IN IP4 198.67.41.194
t=0 0
m=audio 1695 RTP/AVP 18 101
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=X-sqn: 0
a=X-cap: 1 image udptl t38
a=ptime:10
0(27492) update_sock_struct_from_via: using via host
 0(27492) update_sock_struct_from_via: trying SRV lookup
 0(27492)  reply forwarded to 198.67.41.196:0
 0(27492) DEBUG:destroy_avp_list: destroing list (nil)
 0(27492) receive_msg: cleaning up
 0(27492) SIP Reply  (status):
 0(27492)  version: <SIP/2.0>
 0(27492)  status:  <183>
 0(27492)  reason:  <Session Progress>
 0(27492) parse_headers: flags=1
 0(27492) end of header reached, state=9
 0(27492) DEBUG: get_hdr_field: <To> [35]; uri=[sip:12523152553@sip.simflex.net]
 0(27492) DEBUG: to body [sip:12523152553@sip.simflex.net
]
 0(27492) DEBUG: get_hdr_body : content_length=219
 0(27492) get_hdr_field: cseq <CSeq>: <25148> <INVITE>
 0(27492) Found param type 232, <branch> = <z9hG4bK174a.53dcb714.0>; state=16
 0(27492) end of header reached, state=5
 0(27492) parse_headers: Via found, flags=1
 0(27492) parse_headers: this is the first via
 0(27492) After parse_msg...
 0(27492) forward_reply: found module tm, passing reply to it
 0(27492) DEBUG: t_check: msg id=20 global id=19 T start=(nil)
 0(27492) parse_headers: flags=17
 0(27492) parse_headers: flags=4
 0(27492) DEBUG: t_reply_matching: hash 42097 label 1098632501 branch 0
 0(27492) DEBUG: t_reply_matching: reply matched (T=0xb5766ca8)!
 0(27492) DBG: callback type 1, id 5 entered
 0(27492) DEBUG: t_check: msg id=20 global id=20 T end=0xb5766ca8
 0(27492) DEBUG: t_on_reply: org. status uas=183, uac[0]=183 local=0 is_invite=1)
 0(27492) ->>>>>>>>> T_code=183, new_code=183
 0(27492) DEBUG: relay_reply: branch=0, save=0, relay=0
 0(27492)  old size: 743, new size: 681
 0(27492) build_res_from_sip_res: copied size: orig:332, new: 270, rest: 411 msg=
SIP/2.0 183 Session Progress
Call-ID: EBBF58CE-320D-4906-ADD5-34B16E162D8C@10.0.0.2
From: "sip.simflex.net" sip:phone@sip.simflex.net:8286;tag=3089475581
To: sip:12523152553@sip.simflex.net
Content-Length: 219
Content-Type: application/sdp
CSeq: 25148 INVITE
Via: SIP/2.0/UDP 10.0.0.2:8286;received=198.67.41.187;rport=8286;received=198.67.41.187;rport=8286;branch=z9hG4bKABE3A5E79A9544F6A76997C242A1E16C
Record-Route: sip:198.67.41.194:5060;lr
v=0
o=MG4000|1.0 111 12345 IN IP4 198.67.41.194
s=-
c=IN IP4 198.67.41.194
t=0 0
m=audio 1695 RTP/AVP 18 101
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=X-sqn: 0
a=X-cap: 1 image udptl t38
a=ptime:10
0(27492) DEBUG: reply relayed. buf=0x80c5a68: SIP/2.0 1..., shmem=0xb576a7e0: SIP/2.0 1
 0(27492) DBG: callback type 7, id 1 entered
 0(27492) DEBUG: add_to_tail_of_timer[1]: 0xb5766de4
 0(27492) DEBUG:destroy_avp_list: destroing list (nil)
 0(27492) receive_msg: cleaning up
 0(27492) SIP Reply  (status):
 0(27492)  version: <SIP/2.0>
 0(27492)  status:  <404>
 0(27492)  reason:  <Not Found>
 0(27492) parse_headers: flags=1
 0(27492) end of header reached, state=9
 0(27492) DEBUG: get_hdr_field: <To> [35]; uri=[sip:12523152553@sip.simflex.net]
 0(27492) DEBUG: to body [sip:12523152553@sip.simflex.net
]
 0(27492) DEBUG: get_hdr_body : content_length=0
 0(27492) get_hdr_field: cseq <CSeq>: <25148> <INVITE>
 0(27492) Found param type 232, <branch> = <0>; state=16
 0(27492) end of header reached, state=5
 0(27492) parse_headers: Via found, flags=1
 0(27492) parse_headers: this is the first via
 0(27492) After parse_msg...
 0(27492) forward_reply: found module tm, passing reply to it
 0(27492) DEBUG: t_check: msg id=21 global id=20 T start=0xffffffff
 0(27492) parse_headers: flags=17
 0(27492) parse_headers: flags=4
 0(27492) DEBUG: t_reply_matching: failure to match a transaction
 0(27492) DEBUG: t_check: msg id=21 global id=21 T end=(nil)
 0(27492) parse_headers: flags=2
 0(27492) Found param type 232, <branch> = <z9hG4bK174a.53dcb714.0>; state=16
 0(27492) end of header reached, state=5
 0(27492) parse_headers: Via found, flags=2
 0(27492) parse_headers: this is the second via
 0(27492)  old size: 482, new size: 441
 0(27492) build_res_from_sip_res: copied size: orig:271, new: 230, rest: 211 msg=
SIP/2.0 404 Not Found
Call-ID: EBBF58CE-320D-4906-ADD5-34B16E162D8C@10.0.0.2
From: "sip.simflex.net" sip:phone@sip.simflex.net:8286;tag=3089475581
To: sip:12523152553@sip.simflex.net
Content-Length: 0
CSeq: 25148 INVITE
Via: SIP/2.0/UDP 198.67.41.196;branch=z9hG4bK174a.53dcb714.0
Via: SIP/2.0/UDP 10.0.0.2:8286;received=198.67.41.187;rport=8286;received=198.67.41.187;rport=8286;branch=z9hG4bKABE3A5E79A9544F6A76997C242A1E16C
0(27492) update_sock_struct_from_via: using via host
 0(27492) update_sock_struct_from_via: trying SRV lookup
 0(27492)  reply forwarded to 198.67.41.196:0
 0(27492) DEBUG:destroy_avp_list: destroing list (nil)
 0(27492) receive_msg: cleaning up
 0(27492) SIP Reply  (status):
 0(27492)  version: <SIP/2.0>
 0(27492)  status:  <404>
 0(27492)  reason:  <Not Found>
 0(27492) parse_headers: flags=1
 0(27492) end of header reached, state=9
 0(27492) DEBUG: get_hdr_field: <To> [35]; uri=[sip:12523152553@sip.simflex.net]
 0(27492) DEBUG: to body [sip:12523152553@sip.simflex.net
]
 0(27492) DEBUG: get_hdr_body : content_length=0
 0(27492) get_hdr_field: cseq <CSeq>: <25148> <INVITE>
 0(27492) Found param type 232, <branch> = <z9hG4bK174a.53dcb714.0>; state=16
 0(27492) end of header reached, state=5
 0(27492) parse_headers: Via found, flags=1
 0(27492) parse_headers: this is the first via
 0(27492) After parse_msg...
 0(27492) forward_reply: found module tm, passing reply to it
 0(27492) DEBUG: t_check: msg id=22 global id=21 T start=(nil)
 0(27492) parse_headers: flags=17
 0(27492) parse_headers: flags=4
 0(27492) DEBUG: t_reply_matching: hash 42097 label 1098632501 branch 0
 0(27492) DEBUG: t_reply_matching: reply matched (T=0xb5766ca8)!
 0(27492) DBG: callback type 1, id 5 entered
 0(27492) DEBUG: t_check: msg id=22 global id=22 T end=0xb5766ca8
 0(27492) DEBUG: t_on_reply: org. status uas=183, uac[0]=183 local=0 is_invite=1)
 0(27492) parse_headers: flags=4
 0(27492) ->>>>>>>>> T_code=183, new_code=404
 0(27492) DBG: callback type 6, id 4 entered
 0(27492) DEBUG: relay_reply: branch=0, save=0, relay=0
 0(27492)  old size: 441, new size: 379
 0(27492) build_res_from_sip_res: copied size: orig:292, new: 230, rest: 149 msg=
SIP/2.0 404 Not Found
Call-ID: EBBF58CE-320D-4906-ADD5-34B16E162D8C@10.0.0.2
From: "sip.simflex.net" sip:phone@sip.simflex.net:8286;tag=3089475581
To: sip:12523152553@sip.simflex.net
Content-Length: 0
CSeq: 25148 INVITE
Via: SIP/2.0/UDP 10.0.0.2:8286;received=198.67.41.187;rport=8286;received=198.67.41.187;rport=8286;branch=z9hG4bKABE3A5E79A9544F6A76997C242A1E16C
0(27492) DEBUG: reply relayed. buf=0x80c47a8: SIP/2.0 4..., shmem=0xb57681b8: SIP/2.0 4
 0(27492) DBG: callback type 7, id 1 entered
 0(27492) DEBUG: cleanup_uacs: RETR/FR timers reset
 0(27492) DEBUG: add_to_tail_of_timer[4]: 0xb5766d5c
 0(27492) DEBUG: add_to_tail_of_timer[0]: 0xb5766d70
 0(27492) DEBUG:destroy_avp_list: destroing list (nil)
 0(27492) receive_msg: cleaning up
 0(27492) SIP Request:
 0(27492)  method:  <ACK>
 0(27492)  uri:     sip:12523152553@sip.simflex.net
 0(27492)  version: <SIP/2.0>
 0(27492) parse_headers: flags=1
 0(27492) Found param type 235, <rport> = <n/a>; state=6
 0(27492) Found param type 232, <branch> = <z9hG4bKABE3A5E79A9544F6A76997C242A1E16C>; state=16
 0(27492) end of header reached, state=5
 0(27492) parse_headers: Via found, flags=1
 0(27492) parse_headers: this is the first via
 0(27492) After parse_msg...
 0(27492) preparing to run routing scripts...
 0(27492) DEBUG : sl_filter_ACK: to late to be a local ACK!
 0(27492) lookup(): '12523152553' Not found in usrloc
 0(27492) DEBUG : is_maxfwd_present: searching for max_forwards header
 0(27492) parse_headers: flags=128
 0(27492) end of header reached, state=9
 0(27492) DEBUG: get_hdr_field: <To> [35]; uri=[sip:12523152553@sip.simflex.net]
 0(27492) DEBUG: to body [sip:12523152553@sip.simflex.net
]
 0(27492) get_hdr_field: cseq <CSeq>: <25148> <ACK>
 0(27492) DEBUG: is_maxfwd_present: value = 70
 0(27492) DEBUG: add_param: tag=3089475581
 0(27492) end of header reached, state=29
 0(27492) parse_headers: flags=256
 0(27492) DEBUG: get_hdr_body : content_length=0
 0(27492) found end of header
 0(27492) find_first_route(): No Route headers found
 0(27492) loose_route(): There is no Route HF
 0(27492) DEBUG: mk_proxy: doing DNS lookup...
 0(27492) check_via_address(198.67.41.187, 10.0.0.2, 0)
 0(27492) Sending: