New subject: in doubt about in-dialog INVITE Route params processing

4 Apr 2012

I've been pondering an issue with Route header parameters being not
mirrored by kamailio proxy into Record-Route field on in-dialog requests
over the last few hours so I thought I'd just whether I'm missing
something obvious.

The call scenario in UA -> lb -> proxy -> sems. Everything is fine with
initial INVITE transaction where we add rr param ;rtpprx=yes. (We also
use dialog module.) The UA does re-INVITE where it mirrors RR headers to
Route set just fine. However, when forwarding re-INVITE kamailio proxy
removes own Route header and inserts RR header without rtpproxy, did etc
params.

The kamailio default config from branch 3.1 routes in-dialog requests in
the same fashion and I can't tell really if this is how it is supposed
to work? I can workaround this by add_rr_param() but shouldn't missing
did param cause a problem with dialog matching? Here is the debug with
log level 3:

...
  Apr  4 13:23:21 sp1 /usr/sbin/kamailio[7520]: DEBUG:
rr [loose.c:792]: Topmost route URI:
'sip:127.0.0.1:5062;lr;ftag=8pTM6oKWVtC4H03478xjIR34SSsYuTU5;did=8a3.aab32831;mpd=ii;rtpprx=yes;vsf=aDNlNDEjPSkBJB9/YykkNnYYdTZ0KGUsfGZxVndVe1M-'
is me
 Apr  4 13:23:21 sp1 /usr/sbin/kamailio[7520]: DEBUG: <core>
[parser/msg_parser.c:103]: found end of header
 Apr  4 13:23:21 sp1 /usr/sbin/kamailio[7520]: DEBUG: rr [loose.c:257]: No next Route HF
found
 Apr  4 13:23:21 sp1 /usr/sbin/kamailio[7520]: DEBUG: rr [loose.c:811]: No next URI found
 Apr  4 13:23:21 sp1 /usr/sbin/kamailio[7520]: DEBUG: rr [rr_cb.c:97]: callback id 1
entered with
<lr;ftag=8pTM6oKWVtC4H03478xjIR34SSsYuTU5;did=8a3.aab32831;mpd=ii;rtpprx=yes;vsf=aDNlNDEjPSkBJB9/YykkNnYYdTZ0KGUsfGZxVndVe1M->
 Apr  4 13:23:21 sp1 /usr/sbin/kamailio[7520]: DEBUG: dialog [dlg_handlers.c:910]: route
param is '8a3.aab32831' (len=12)
 Apr  4 13:23:21 sp1 /usr/sbin/kamailio[7520]: DEBUG: dialog [dlg_hash.c:442]: ref dlg
0x7f0a0be918b0 with 1 -> 3
 Apr  4 13:23:21 sp1 /usr/sbin/kamailio[7520]: DEBUG: dialog [dlg_hash.c:444]: dialog
id=327302058 found on entry 936
 Apr  4 13:23:21 sp1 /usr/sbin/kamailio[7520]: DEBUG: dialog [dlg_hash.c:757]: dialog
0x7f0a0be918b0 changed from state 4 to state 4, due event 8
 Apr  4 13:23:21 sp1 /usr/sbin/kamailio[7520]: DEBUG: dialog [dlg_handlers.c:1042]:
sequential request successfully processed
 Apr  4 13:23:21 sp1 /usr/sbin/kamailio[7520]: DEBUG: dialog [dlg_timer.c:117]: inserting
0x7f0a0be91900 for 127601858
 Apr  4 13:23:21 sp1 /usr/sbin/kamailio[7520]: DEBUG: dialog [dlg_hash.c:411]: cseq is
10760
 Apr  4 13:23:21 sp1 /usr/sbin/kamailio[7520]: DEBUG: rr [rr_cb.c:97]: callback id 0
entered with
<lr;ftag=8pTM6oKWVtC4H03478xjIR34SSsYuTU5;did=8a3.aab32831;mpd=ii;rtpprx=yes;vsf=aDNlNDEjPSkBJB9/YykkNnYYdTZ0KGUsfGZxVndVe1M->
 Apr  4 13:23:21 sp1 /usr/sbin/kamailio[7520]: DEBUG: uac [from.c:421]: getting
'vsf' Route param
 Apr  4 13:23:21 sp1 /usr/sbin/kamailio[7520]: DEBUG: uac [from.c:429]: route param is
'aDNlNDEjPSkBJB9/YykkNnYYdTZ0KGUsfGZxVndVe1M-' (len=44)
 Apr  4 13:23:21 sp1 /usr/sbin/kamailio[7520]: DEBUG: uac [from.c:479]: decoded uris are:
new=[sip:43991001@192.168.51.210] old=[sip:sipwise-user1@192.168.51.210]
 Apr  4 13:23:21 sp1 /usr/sbin/kamailio[7520]: DEBUG: rr [loose.c:983]: params are
<;lr;ftag=8pTM6oKWVtC4H03478xjIR34SSsYuTU5;did=8a3.aab32831;mpd=ii;rtpprx=yes;vsf=aDNlNDEjPSkBJB9/YykkNnYYdTZ0KGUsfGZxVndVe1M->
 Apr  4 13:23:21 sp1 /usr/sbin/kamailio[7520]: DEBUG: siputils [checks.c:76]: totag found
 Apr  4 13:23:21 sp1 /usr/sbin/kamailio[7520]: DEBUG: textops [textops.c:1789]: content
type is 196611
 Apr  4 13:23:21 sp1 /usr/sbin/kamailio[7520]: DEBUG: rr [loose.c:983]: params are
<;lr;ftag=8pTM6oKWVtC4H03478xjIR34SSsYuTU5;did=8a3.aab32831;mpd=ii;rtpprx=yes;vsf=aDNlNDEjPSkBJB9/YykkNnYYdTZ0KGUsfGZxVndVe1M->
 Apr  4 13:23:21 sp1 /usr/sbin/kamailio[7520]: DEBUG: rr [loose.c:983]: params are
<;lr;ftag=8pTM6oKWVtC4H03478xjIR34SSsYuTU5;did=8a3.aab32831;mpd=ii;rtpprx=yes;vsf=aDNlNDEjPSkBJB9/YykkNnYYdTZ0KGUsfGZxVndVe1M->
 Apr  4 13:23:21 sp1 /usr/sbin/kamailio[7520]: INFO: <script>: Use mediaproxy for
forward direction for IPv4/IPv4 - M=INVITE R=sip:127.0.0.1:5080
F=sip:sipwise-user1@192.168.51.210 T=sip:43991002@192.168.51.210 IP=192.168.51.1:35189
(127.0.0.1:5060) ID=M6obxlxpUOPPR3CQYE-d2t9-fgybDo6y
 Apr  4 13:23:21 sp1 /usr/sbin/kamailio[7520]: DEBUG: rtpproxy [rtpproxy_funcs.c:148]:
type <application/sdp> found valid
 Apr  4 13:23:21 sp1 /usr/sbin/kamailio[7520]: DEBUG: rtpproxy [rtpproxy.c:2260]: proxy
reply: 30080 192.168.51.210 4#012
 Apr  4 13:23:21 sp1 /usr/sbin/kamailio[7520]: INFO: <script>: Relaying request -
M=INVITE R=sip:127.0.0.1:5080 F=sip:sipwise-user1@192.168.51.210
T=sip:43991002@192.168.51.210 IP=192.168.51.1:35189 (127.0.0.1:5060)
ID=M6obxlxpUOPPR3CQYE-d2t9-fgybDo6y 