[Users] retransmission 404 not found

Cseke Tamas cseke.tamas at eworldcom.hu
Mon Oct 9 14:04:36 CEST 2006


Hi,

I have a retransmission problem,
i recive an ack for an 404 reply, however opener 1.1.0 retransmit it:

U 213.xxx.xxx.92:5060 -> 213.xxx.xxx.32:5060
  SIP/2.0 404 Not Found..Via: SIP/2.0/UDP 
192.168.1.106;rport=5060;received=2
  17.xxx.xxx.22;branch=z9hG4bKf0dec68a3f9e217a..From: 
<sip:241 at callshop.peakte
  lecom.me.uk>;tag=25dde6813cae8747..To: <sip:1 at callshop.>;t
  ag=D3DB214-25D2..Date: Mon, 09 Oct 2006 11:37:18 GMT..Call-ID: 
3c47a6403890
  c2f2 at 192.168.1.106..Server: Cisco-SIPGateway/IOS-12.x..CSeq: 42170 
INVITE..
  Allow-Events: telephone-event..Content-Length: 0....
#
U 213.xxx.xxx.32:5060 -> 213.xxx.xxx.92:5060
  ACK sip:1 at callshop. SIP/2.0..Via: SIP/2.0/UDP 192.168.1.10
  6;branch=z9hG4bKf0dec68a3f9e217a..From: <sip:241 at callshop.
  >;tag=25dde6813cae8747..To: <sip:1 at callshop.>;tag=D3DB214-
  25D2..Contact: <sip:241 at 192.168.1.106>..Authorization: Digest 
username="241
  ", realm="callshop.", algorithm=MD5, uri="sip:1 at callshop
  ", nonce="452a37e35171ad890ccd763950d954c45090f992", respon
  se="f68ecea0d5196e8e88abeac6a68"..Call-ID: 3c47a6403890c2f2 at 192.168.1.
  106..CSeq: 42170 ACK..User-Agent: Grandstream BT110 
1.0.8.23..Max-Forwards:
   70..Allow: 
INVITE,ACK,CANCEL,BYE,NOTIFY,REFER,OPTIONS,INFO,SUBSCRIBE..Cont
  ent-Length: 0....
#
U 213.xxx.xxx.92:5060 -> 213.xxx.xxx.32:5060
  SIP/2.0 404 Not Found..Via: SIP/2.0/UDP 
192.168.1.106;rport=5060;received=2
  17.xxx.xxx.22;branch=z9hG4bKf0dec68a3f9e217a..From: 
<sip:241 at callshop.peakte
  lecom.me.uk>;tag=25dde6813cae8747..To: <sip:1 at callshop.>;t
  ag=D3DB214-25D2..Date: Mon, 09 Oct 2006 11:37:18 GMT..Call-ID: 
3c47a6403890
  c2f2 at 192.168.1.106..Server: Cisco-SIPGateway/IOS-12.x..CSeq: 42170 
INVITE..
  Allow-Events: telephone-event..Content-Length: 0....
#
U 213.xxx.xxx.92:5060 -> 213.xxx.xxx.32:5060
  SIP/2.0 404 Not Found..Via: SIP/2.0/UDP 
192.168.1.106;rport=5060;received=2
  17.xxx.xxx.22;branch=z9hG4bKf0dec68a3f9e217a..From: 
<sip:241 at callshop.peakte
  lecom.me.uk>;tag=25dde6813cae8747..To: <sip:1 at callshop.>;t
  ag=D3DB214-25D2..Date: Mon, 09 Oct 2006 11:37:18 GMT..Call-ID: 
3c47a6403890
  c2f2 at 192.168.1.106..Server: Cisco-SIPGateway/IOS-12.x..CSeq: 42170 
INVITE..
  Allow-Events: telephone-event..Content-Length: 0....



0(15109) DEBUG: to body [<sip:1 at callshop>]
 0(15109) get_hdr_field: cseq <CSeq>: <42170> <INVITE>
 0(15109) parse_headers: flags=8
 0(15109) DEBUG: t_reply_matching: hash 39491 label 455964109 branch 2
 0(15109) DEBUG:tm:REF_UNSAFE: after is 1
 0(15109) DEBUG: t_reply_matching: reply matched (T=0xb3df7618)!
 0(15109) DEBUG: t_check: msg id=35 global id=35 T end=0xb3df7618
 0(15109) DEBUG:tm:reply_received: org. status uas=100, uac[2]=100 
local=0 
is_in                                                                             
vite=1)
 0(15109) parse_headers: flags=8
 0(15109) DEBUG:tm:build_local: using FROM=<From: 
<sip:241 at callshop                                                                             
me.uk>;tag=25dde6813cae8747
 >, TO=<To: <sip:1 at callshop>;tag=D3DB214-25D2
 >, CSEQ_N=<CSeq: 42170>
 0(15109) parse_headers: flags=80
 0(15109) DEBUG: get_hdr_body : content_length=0
 0(15109) found end of header
 0(15109) DEBUG:tm:t_should_relay_response: T_code=100, new_code=404
 0(15109) DEBUG:tm:t_pick_branch: picked branch 2, code 404
 0(15109) DEBUG:tm:relay_reply: branch=2, save=0, relay=2
 0(15109)  old size: 482, new size: 427
 0(15109) build_res_from_sip_res: copied size: orig:83, new: 28, rest: 
399 msg=
SIP/2.0 404 Not Found
Via: SIP/2.0/UDP 
192.168.1.106;rport=5060;received=217.xxx.xxx.22;branch=z9hG4bKf                                                                             
0dec68a3f9e217a
From: <sip:241 at callshop>;tag=25dde6813cae8747
To: <sip:1 at callshop>;tag=D3DB214-25D2
Date: Mon, 09 Oct 2006 11:37:18 GMT
Call-ID: 3c47a6403890c2f2 at 192.168.1.106
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 42170 INVITE
Allow-Events: telephone-event
Content-Length: 0



 0(15109) DEBUG: add_to_tail_of_timer[4]: 0xb3df76c8
 0(15109) DEBUG: add_to_tail_of_timer[0]: 0xb3df76d8
 0(15109) DEBUG:tm:relay_reply: sent buf=0x812e928: SIP/2.0 4..., 
shmem=0xb3df8f                                                                             
50: SIP/2.0 4
 0(15109) DEBUG: cleanup_uac_timers: RETR/FR timers reset
 0(15109) DEBUG:tm:UNREF_UNSAFE: after is 0
 0(15109) DEBUG:destroy_avp_list: destroying list (nil)
 0(15109) receive_msg: cleaning up
 0(15109) SIP Request:
 0(15109)  method:  <ACK>
 0(15109)  uri:     <sip:1 at callshop>
 0(15109)  version: <SIP/2.0>
 0(15109) parse_headers: flags=2
 0(15109) Found param type 232, <branch> = <z9hG4bKf0dec68a3f9e217a>; 
state=16
 0(15109) end of header reached, state=5
 0(15109) parse_headers: Via found, flags=2
 0(15109) parse_headers: this is the first via
 0(15109) After parse_msg...
 0(15109) preparing to run routing scripts...
 0(15109) parse_headers: flags=8
 0(15109) DEBUG: add_param: tag=D3DB214-25D2
 0(15109) DEBUG:parse_to:end of header reached, state=29
 0(15109) DBUG:parse_to: display={}, ruri={sip:1 at callshop}
 0(15109) DEBUG: get_hdr_field: <To> [53]; 
uri=[sip:1 at callshop                                                                             
]
 0(15109) DEBUG: to body [<sip:1 at callshop>]
 0(15109) parse_headers: flags=100
 0(15109) get_hdr_field: cseq <CSeq>: <42170> <ACK>
 0(15109) DEBUG:maxfwd:is_maxfwd_present: value = 70
 0(15109) DEBUG: add_param: tag=25dde6813cae8747
 0(15109) DEBUG:parse_to:end of header reached, state=29
 0(15109) DBUG:parse_to: display={}, ruri={sip:241 at callshop}
 0(15109) DEBUG: t_check: msg id=36 global id=35 T start=0xffffffff
 0(15109) parse_headers: flags=ffffffffffffffff
 0(15109) DEBUG: get_hdr_body : content_length=0
 0(15109) found end of header
 0(15109) parse_headers: flags=78
 0(15109) t_lookup_request: start searching: hash=39491, isACK=1
 0(15109) DEBUG: RFC3261 transaction matched, tid=f0dec68a3f9e217a
 0(15109) DEBUG:tm:REF_UNSAFE: after is 1
 0(15109) DEBUG: t_lookup_request: transaction found (T=0xb3df7618)
 0(15109) DEBUG: t_check: msg id=36 global id=36 T end=0xb3df7618
 0(15109) DEBUG:t_check_status: checked status is <404>
 0(15109) parse_headers: flags=58
 0(15109) parse_headers: flags=200
 0(15109) find_first_route: No Route headers found
 0(15109) loose_route: There is no Route HF
 0(15109) grep_sock_info - checking if host==us: 26==12 &&  
[callshop.                                                                             
m.me.uk] == [213.166.8.92]
 0(15109) grep_sock_info - checking if port 5060 matches port 5060
 0(15109) grep_sock_info - checking if host==us: 26==12 &&  
[callshop.                                                                             
m.me.uk] == [213.166.8.92]
 0(15109) grep_sock_info - checking if port 5060 matches port 5060
 0(15109) DEBUG: t_newtran: msg id=36 , global msg id=36 , T on 
entrance=0xb3df7                                                                             
618
* 0(15109) ERROR: t_newtran: transaction already in process 0xb3df7618*
 0(15109) DEBUG:tm:UNREF_UNSAFE: after is 0
 0(15109) DEBUG:destroy_avp_list: destroying list (nil)
 0(15109) receive_msg: cleaning up
 1(15110) DEBUG: timer routine:1,tl=0xb3df78ec next=0xb3df7818
 1(15110) DEBUG: timer routine:1,tl=0xb3df7818 next=0xb3df7744
 1(15110) DEBUG: timer routine:1,tl=0xb3df7744 next=(nil)
 1(15110) DEBUG: timer routine:4,tl=0xb3df76c8 next=0xb3df78dc
 1(15110) DEBUG: retransmission_handler : reply resending (t=0xb3df7618, 
SIP/2.0                                                                              
4 ... )
 1(15110) DEBUG:tm:t_retransmit_reply: buf=0xb7db8c60: SIP/2.0 4..., 
shmem=0xb3d                                                                             
f8f50: SIP/2.0 4
 1(15110) DEBUG: add_to_tail_of_timer[5]: 0xb3df76c8
 1(15110) DEBUG: retransmission_handler : done
 1(15110) DEBUG: timer routine:4,tl=0xb3df78dc next=0xb3df7808
 1(15110) DEBUG: timer routine:4,tl=0xb3df7808 next=0xb3df7734
 1(15110) DEBUG: timer routine:4,tl=0xb3df7734 next=(nil)
 1(15110) DEBUG: timer routine:5,tl=0xb3df76c8 next=(nil)
 1(15110) DEBUG: retransmission_handler : reply resending (t=0xb3df7618, 
SIP/2.0                                                                              
4 ... )
 1(15110) DEBUG:tm:t_retransmit_reply: buf=0xb7db8c60: SIP/2.0 4..., 
shmem=0xb3d                                                                             
f8f50: SIP/2.0 4
 1(15110) DEBUG: add_to_tail_of_timer[6]: 0xb3df76c8
 1(15110) DEBUG: retransmission_handler : done
 1(15110) DEBUG: timer routine:6,tl=0xb3df76c8 next=(nil)
 1(15110) DEBUG: retransmission_handler : reply resending (t=0xb3df7618, 
SIP/2.0                                                                              
4 ... )
 1(15110) DEBUG:tm:t_retransmit_reply: buf=0xb7db8c60: SIP/2.0 4..., 
shmem=0xb3d                                                                             
f8f50: SIP/2.0 4
 1(15110) DEBUG: add_to_tail_of_timer[7]: 0xb3df76c8
 1(15110) DEBUG: retransmission_handler : done
 1(15110) DEBUG: timer routine:7,tl=0xb3df76c8 next=(nil)
 1(15110) DEBUG: retransmission_handler : reply resending (t=0xb3df7618, 
SIP/2.0                                                                              
4 ... )
 1(15110) DEBUG:tm:t_retransmit_reply: buf=0xb7db8c60: SIP/2.0 4..., 
shmem=0xb3d                                                                             
f8f50: SIP/2.0 4
 1(15110) DEBUG: add_to_tail_of_timer[7]: 0xb3df76c8
 1(15110) DEBUG: retransmission_handler : done
 1(15110) INFO: signal 2 received
 1(15110) Memory status (pkg):
 1(15110) fm_status (0x811fd80):
 1(15110)  heap size= 1048576
 1(15110)  used= 34976, used+overhead=55056, free=993520
 1(15110)  max used (+overhead)= 55880
 1(15110) dumping free list:
 1(15110) hash =   7 fragments no.:     1, unused:     0
                 bucket size:        56 -        56 (first        56)
 1(15110) hash =  16 fragments no.:     6, unused:     0
                 bucket size:       128 -       128 (first       128)
 1(15110) hash = 255 fragments no.:     1, unused:     0
                 bucket size:      2040 -      2040 (first      2040)
 1(15110) hash = 2054 fragments no.:     1, unused:     0
                 bucket size:    524288 -   1048576 (first    990656)
 1(15110) TOTAL:      9 free fragments = 993520 free bytes
 1(15110) -----------------------------
 
thanks any help,
Tamas
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.kamailio.org/pipermail/users/attachments/20061009/45fb5250/attachment.htm 


More information about the Users mailing list