Hello
all,
I am running into a
strange problem with message (or transaction) flags. I set a message flag while
processing the INVITE, but this flag does not appear in any messages that belong
to the same transaction. I discovered this while trying to resolved an
issue with Re-INVITEs. Now I am testing it with a simple INVITE-CANCEL senario
and I see that again the flag does not appear when processing the CANCEL. My
script is pretty basic, at least for the CANCEL processing. The debug output is
at the end of the email.
if
(is_method("CANCEL"))
{
if (!t_check_trans())
{
# Must be a CANCEL for a transaction that has not been
established.
# Drop it and the client will resend
it.
exit;
}
else xlog("L_ERR","$rm: tran found flags=0x$mF
bflags=0x$bF\n");
....
t_relay();
}
Searching on the
internet I came across this
So what is the
story? Am I doing something wrong?
thank you for any
help
George
1(18475) SIP Request:
1(18475)
method: <CANCEL>
1(18475)
uri:
<sip:2116872933@altecnet.gr;user=phone>
1(18475) version:
<SIP/2.0>
1(18475) parse_headers: flags=2
1(18475) Found
param type 232, <branch> = <z9hG4bK-mcodgtqb0696>;
state=6
1(18475) Found param type 235, <rport> = <n/a>;
state=17
1(18475) end of header reached, state=5
1(18475)
parse_headers: Via found, flags=2
1(18475) parse_headers: this is the
first via
1(18475) After parse_msg...
1(18475) preparing to
run routing scripts...
1(18475) parse_headers:
flags=100
1(18475) DEBUG:parse_to:end of header reached,
state=10
1(18475) DBUG:parse_to: display={},
ruri={sip:2116872933@altecnet.gr;user=phone}
1(18475) DEBUG:
get_hdr_field: <To> [41]; uri=[sip:2116872933@altecnet.gr;user=phone]
1(18475) DEBUG: to body
[<sip:2116872933@altecnet.gr;user=phone>
]
1(18475)
get_hdr_field: cseq <CSeq>: <2> <CANCEL>
1(18475)
DEBUG:maxfwd:is_maxfwd_present: value = 70
1(18475)
DBG:maxfwd:process_maxfwd_header: value 70 decreased to 10
1(18475)
check_via_address(213.5.17.236, 213.5.17.236, 0)
1(18475)
parse_headers: flags=80
1(18475) DEBUG: add_param:
tag=b59u54s6kf
1(18475) DEBUG:parse_to:end of header reached,
state=29
1(18475) DBUG:parse_to: display={"snom"},
ruri={sip:demo2@altecnet.gr}
1(18475) parse_headers:
flags=200
1(18475) DEBUG: get_hdr_body :
content_length=0
1(18475) found end of header
1(18475)
find_first_route: No Route headers found
1(18475) loose_route: There is
no Route HF
1(18475) grep_sock_info - checking if host==us: 11==10
&& [altecnet.gr] == [213.5.43.4]
1(18475) grep_sock_info
- checking if port 5060 matches port 5060
1(18475) grep_sock_info -
checking if host==us: 11==10 && [altecnet.gr] ==
[213.5.43.7]
1(18475) grep_sock_info - checking if port 5060 matches
port 5060
1(18475) grep_sock_info - checking if host==us: 11==10
&& [altecnet.gr] == [213.5.43.8]
1(18475) grep_sock_info
- checking if port 5060 matches port 5060
1(18475) grep_sock_info -
checking if host==us: 11==10 && [altecnet.gr] ==
[213.5.43.9]
1(18475) grep_sock_info - checking if port 5060 matches
port 5060
1(18475) grep_sock_info - checking if host==us: 11==12
&& [altecnet.gr] == [172.31.100.5]
1(18475)
grep_sock_info - checking if port 5060 matches port 5060
1(18475)
parse_headers: flags=78
1(18475) DEBUG: t_lookupOriginalT: searching on
hash entry 13236
1(18475) DEBUG: RFC3261 transaction matched,
tid=-mcodgtqb0696
1(18475) DEBUG: t_lookupOriginalT: canceled
transaction found (0xb616b3d8)!
1(18475) DEBUG:tm:REF_UNSAFE: after is
1
1(18475) DEBUG: t_lookupOriginalT completed
1(18475) CANCEL:
tran found flags=0x00000000 bflags=0x00000000
1(18475) route9: CANCEL
sip:2116872933@altecnet.gr;user=phone <null> fl=0x00000000 bfl=0x00000000
branch_id=0
1(18475) DEBUG: t_newtran: T on
entrance=0xffffffff
1(18475) parse_headers:
flags=ffffffffffffffff
1(18475) parse_headers:
flags=78
1(18475) t_lookup_request: start searching: hash=13236,
isACK=0
1(18475) DEBUG: RFC3261 transaction matching
failed
1(18475) DEBUG: t_lookup_request: no transaction
found
1(18475) DBG: trans=0xb616fc50, callback type 1, id 0
entered
1(18475) check_via_address(213.5.17.236, 213.5.17.236,
0)
1(18475) DEBUG:tm:set_timer: relative timeout is
1000000
1(18475) DEBUG: add_to_tail_of_timer[4]: 0xb616fd9c
(5200000)
1(18475) DEBUG:tm:set_timer: relative timeout is
3
1(18475) DEBUG: add_to_tail_of_timer[0]: 0xb616fdb8
(7)
1(18475) DEBUG: e2e_cancel: e2e cancel proceeding
1(18475)
parse_headers: flags=ffffffffffffffff
1(18475)
check_via_address(213.5.17.236, 213.5.17.236, 0)
1(18475)
WARNING:vqm_resize: resize(0) called
1(18475) DEBUG:tm:_reply_light:
reply sent out. buf=0x817a358: SIP/2.0 2..., shmem=0xb6171780: SIP/2.0
2
1(18475) DEBUG:tm:_reply_light: finished
1(18475)
DEBUG:tm:t_relay_to: new transaction fwd'ed
1(18475)
DEBUG:tm:UNREF_UNSAFE: after is 0
1(18475) DEBUG:tm:UNREF_UNSAFE: after
is 0
1(18475) DEBUG:destroy_avp_list: destroying list
(nil)
1(18475) receive_msg: cleaning up