[kamailio/kamailio] pv_evalx result in destination pv seems wrong (#1551)

List overview All Threads
Download

newer

older

git:master:7d376661: kazoo:...

git:master:c087b1d1: core: pv -...

Victor Seva

31 May 2018 31 May '18

3:22 p.m.

### Description

``` xinfo("Send the call to CGRates ci='$ci'"); $var(tmp) = '"event":"CGR_AUTH_REQUEST", "cgr_category":"$dlg_var(cgrCategory)", "cgr_reqtype":"$dlg_var(cgrReqType)", "cgr_tenant":"$dlg_var(tenant)", "cgr_account":"$dlg_var(cgrAccount)", "cgr_destination":"$dlg_var(cgrDestination)", "direction":"$dlg_var(profile)", "source_user":"$dlg_var(source_user)", "recording_url":"", "account_code":"$dlg_var(account_code)", "billing_cli":"$dlg_var(billing_cli)", "cgr_setuptime":"$dlg_var(start_ts)", "reseller_id":"$dlg_var(reseller_id)"'; pv_evalx("$var(msg)", "$var(tmp)"); xinfo("--- $$var(tmp):'$var(tmp)'"); xinfo("--- $$var(msg):'$var(msg)'"); xinfo("++++"); ``` ### Troubleshooting

#### Log Messages

``` May 31 15:07:27 PBX-2117 kamailio[30682]: INFO: <script>: Send the call to CGRates ci='ynfvvklkkcajrfi@slim.torreviejawireless.org' May 31 15:07:27 PBX-2117 kamailio[30682]: INFO: <script>: --- $var(tmp):'"event":"CGR_AUTH_REQUEST",#012 "cgr_category":"$dlg_var(cgrCategory)",#012 "cgr_reqtype":"$dlg_var(cgrReqType)",#012 "cgr_tenant":"$dlg_var(tenant)",#012 "cgr_account":"$dlg_var(cgrAccount)",#012 "cgr_destination":"$dlg_var(cgrDestination)",#012 "direction":"$dlg_var(profile)",#012 "source_user":"$dlg_var(source_user)",#012 "recording_url":"",#012 "account_code":"$dlg_var(account_code)",#012 "billing_cli":"$dlg_var(billing_cli)",#012 "cgr_setuptime":"$dlg_var(start_ts)",#012 "reseller_id":"$dlg_var(reseller_id)"' May 31 15:07:27 PBX-2117 kamailio[30682]: INFO: <script>: --- $var(msg):'5 May 31 15:07:27 PBX-2117 kamailio[30682]: INFO: <script>: ++++ ```

### Possible Solutions

### Additional Information

* **Kamailio Version** - output of `kamailio -v`

``` version: kamailio 5.0.6 (x86_64/linux) flags: STATS: Off, USE_TCP, USE_TLS, USE_SCTP, TLS_HOOKS, DISABLE_NAGLE, USE_MCAST, DNS_IP_HACK, SHM_MEM, SHM_MMAP, PKG_MALLOC, Q_MALLOC, F_MALLOC, TLSF_MALLOC, DBG_SR_MEMORY, USE_FUTEX, FAST_LOCK-ADAPTIVE_WAIT, USE_DNS_CACHE, USE_DNS_FAILOVER, USE_NAPTR, USE_DST_BLACKLIST, HAVE_RESOLV_RES ADAPTIVE_WAIT_LOOPS=1024, MAX_RECV_BUFFER_SIZE 262144, MAX_LISTEN 16, MAX_URI_SIZE 1024, BUF_SIZE 65535, DEFAULT PKG_SIZE 8MB poll method support: poll, epoll_lt, epoll_et, sigio_rt, select. id: unknown compiled with gcc 4.8.2 ```

-- You are receiving this because you are subscribed to this thread. Reply to this email directly or view it on GitHub: https://github.com/kamailio/kamailio/issues/1551

Attachments:

attachment.html (text/html — 6.0 KB)

Show replies by date

Victor Seva

31 May 31 May

3:34 p.m.

log with debug enabled ``` May 31 15:31:48 PBX-2117 kamailio[43398]: INFO: <script>: Send the call to CGRates ci='wtcxddzfpwypvpi@slim.torreviejawireless.org' May 31 15:31:48 PBX-2117 kamailio[43398]: DEBUG: <core> [core/pvapi.c:321]: pv_cache_lookup(): pvar [$dlg_var(cgrCategory)] found in cache May 31 15:31:48 PBX-2117 kamailio[43398]: DEBUG: <core> [core/pvapi.c:321]: pv_cache_lookup(): pvar [$dlg_var(cgrReqType)] found in cache May 31 15:31:48 PBX-2117 kamailio[43398]: DEBUG: <core> [core/pvapi.c:321]: pv_cache_lookup(): pvar [$dlg_var(tenant)] found in cache May 31 15:31:48 PBX-2117 kamailio[43398]: DEBUG: <core> [core/pvapi.c:321]: pv_cache_lookup(): pvar [$dlg_var(cgrAccount)] found in cache May 31 15:31:48 PBX-2117 kamailio[43398]: DEBUG: <core> [core/pvapi.c:321]: pv_cache_lookup(): pvar [$dlg_var(cgrDestination)] found in cache May 31 15:31:48 PBX-2117 kamailio[43398]: DEBUG: <core> [core/pvapi.c:321]: pv_cache_lookup(): pvar [$dlg_var(profile)] found in cache May 31 15:31:48 PBX-2117 kamailio[43398]: DEBUG: <core> [core/pvapi.c:321]: pv_cache_lookup(): pvar [$dlg_var(source_user)] found in cache May 31 15:31:48 PBX-2117 kamailio[43398]: DEBUG: <core> [core/pvapi.c:321]: pv_cache_lookup(): pvar [$dlg_var(account_code)] found in cache May 31 15:31:48 PBX-2117 kamailio[43398]: DEBUG: <core> [core/pvapi.c:321]: pv_cache_lookup(): pvar [$dlg_var(billing_cli)] found in cache May 31 15:31:48 PBX-2117 kamailio[43398]: DEBUG: <core> [core/pvapi.c:321]: pv_cache_lookup(): pvar [$dlg_var(start_ts)] found in cache May 31 15:31:48 PBX-2117 kamailio[43398]: DEBUG: <core> [core/pvapi.c:321]: pv_cache_lookup(): pvar [$dlg_var(reseller_id)] found in cache May 31 15:31:48 PBX-2117 kamailio[43398]: INFO: <script>: --- $var(tmp):'"event":"CGR_AUTH_REQUEST",#012 "cgr_category":"$dlg_var(cgrCategory)",#012 "cgr_reqtype":"$dlg_var(cgrReqType)",#012 "cgr_tenant":"$dlg_var(tenant)",#012 "cgr_account":"$dlg_var(cgrAccount)",#012 "cgr_destination":"$dlg_var(cgrDestination)",#012 "direction":"$dlg_var(profile)",#012 "source_user":"$dlg_var(source_user)",#012 "recording_url":"",#012 "account_code":"$dlg_var(account_code)",#012 "billing_cli":"$dlg_var(billing_cli)",#012 "cgr_setuptime":"$dlg_var(start_ts)",#012 "reseller_id":"$dlg_var(reseller_id)"' May 31 15:31:48 PBX-2117 kamailio[43398]: INFO: <script>: --- $var(msg):'5 May 31 15:31:48 PBX-2117 kamailio[43398]: INFO: <script>: ++++ ```

-- You are receiving this because you are subscribed to this thread. Reply to this email directly or view it on GitHub: https://github.com/kamailio/kamailio/issues/1551#issuecomment-393571627

Daniel-Constantin Mierla

1 Jun 1 Jun

8:35 a.m.

This seems to be something specific for your environment. I tested with:

``` $var(fmt) = '{"srcip": "$si", "rcvip": "$Ri"}'; pv_evalx("$var(msg)", "$var(fmt)"); xlog("==== fmt: $var(fmt)\n"); xlog("==== msg: $var(msg)\n"); ```

And I got:

``` 2(4083) ERROR: <script>: ==== fmt: {"srcip": "$si", "rcvip": "$Ri"} 2(4083) ERROR: <script>: ==== msg: {"srcip": "127.0.0.1", "rcvip": "127.0.0.1"} ```

Can you test if pv_evalx() is successful? Did you set any pv-related global parameters?

-- You are receiving this because you are subscribed to this thread. Reply to this email directly or view it on GitHub: https://github.com/kamailio/kamailio/issues/1551#issuecomment-393810270

Victor Seva

8:40 a.m.

Try this: ``` #!KAMAILIO # ####### Global Parameters #########

### LOG Levels: 3=DBG, 2=INFO, 1=NOTICE, 0=WARN, -1=ERR log_stderror=yes

memdbg=5 memlog=5

log_facility=LOG_LOCAL0 log_stderror=yes fork=no

/* uncomment the next line to disable TCP (default on) */ disable_tcp=yes

/* uncomment the next line to disable the auto discovery of local aliases * based on reverse DNS on IPs (default on) */ auto_aliases=no

/* add local domain aliases */ #alias="sip.mydomain.com"

/* uncomment and configure the following line if you want Kamailio to * bind on a specific interface/port/proto (default bind on all available) */ #listen=udp:10.0.0.10:5060

/* port to listen to * - can be specified more than once if needed to listen on many ports */ port=5060

# life time of TCP connection when there is no traffic # - a bit higher than registration expires to cope with UA behind NAT tcp_connection_lifetime=3605

####### Modules Section ########

# set paths to location of modules (to sources or installation folders) mpath="modules"

loadmodule "tm.so" loadmodule "rr.so" loadmodule "pv.so" loadmodule "dialog.so" loadmodule "xlog.so" loadmodule "debugger.so"

# ----------------- setting module-specific parameters ---------------

modparam("dialog", "dlg_flag", 9)

# ----- debugger params ----- modparam("debugger", "mod_level_mode", 1) modparam("debugger", "mod_hash_size", 4) modparam("debugger", "mod_level", "core=3") modparam("debugger", "mod_level", "xlog=3")

####### Routing Logic ########

# Main SIP request routing logic # - processing of any incoming SIP request starts with this route # - note: this is the same as route { ... } request_route { $dlg_var(cgrCategory) = "internal"; $dlg_var(cgrReqType) = "cgrReqType"; $dlg_var(tenant) = "tenant"; $dlg_var(cgrAccount) = "cgrAccount"; $dlg_var(cgrDestination) = "cgrDestination"; $dlg_var(profile) = "profile"; $dlg_var(source_user) = "source_user"; $dlg_var(account_code) = "account_code"; $dlg_var(billing_cli) = "billing_cli"; $dlg_var(start_ts) = "start_ts"; $dlg_var(reseller_id) = "reseller_id";

xinfo("Send the call to CGRates ci='$ci'"); $var(tmp) = '"event":"CGR_AUTH_REQUEST", "cgr_category":"$dlg_var(cgrCategory)", "cgr_reqtype":"$dlg_var(cgrReqType)", "cgr_tenant":"$dlg_var(tenant)", "cgr_account":"$dlg_var(cgrAccount)", "cgr_destination":"$dlg_var(cgrDestination)", "direction":"$dlg_var(profile)", "source_user":"$dlg_var(source_user)", "recording_url":"", "account_code":"$dlg_var(account_code)", "billing_cli":"$dlg_var(billing_cli)", "cgr_setuptime":"$dlg_var(start_ts)", "reseller_id":"$dlg_var(reseller_id)"'; pv_evalx("$var(msg)", "$var(tmp)"); xinfo("$$var(tmp):'$var(tmp)'\n"); xinfo("$$var(msg):'$var(msg)'\n"); xinfo("++++\n"); } ```

``` |9(15) INFO: <script>: Send the call to CGRates ci='2019802638@172.19.0.3' 9(15) DEBUG: <core> [core/pvapi.c:1056]: pv_parse_format(): parsing ["event":"CGR_AUTH_REQUEST", | "cgr_category":"$dlg_var(cgrCategory)", | "cgr_reqtype":"$dlg_var(cgrReqType)", | "cgr_tenant":"$dlg_var(tenant)", | "cgr_account":"$dlg_var(cgrAccount)", | "cgr_destination":"$dlg_var(cgrDestination)", | "direction":"$dlg_var(profile)", | "source_user":"$dlg_var(source_user)", | "recording_url":"", | "account_code":"$dlg_var(account_code)", | "billing_cli":"$dlg_var(billing_cli)", | "cgr_setuptime":"$dlg_var(start_ts)", | "reseller_id":"$dlg_var(reseller_id)"] |9(15) DEBUG: <core> [core/pvapi.c:320]: pv_cache_lookup(): pvar [$dlg_var(cgrCategory)] found in cache |9(15) DEBUG: <core> [core/pvapi.c:320]: pv_cache_lookup(): pvar [$dlg_var(cgrReqType)] found in cache |9(15) DEBUG: <core> [core/pvapi.c:320]: pv_cache_lookup(): pvar [$dlg_var(tenant)] found in cache |9(15) DEBUG: <core> [core/pvapi.c:320]: pv_cache_lookup(): pvar [$dlg_var(cgrAccount)] found in cache |9(15) DEBUG: <core> [core/pvapi.c:320]: pv_cache_lookup(): pvar [$dlg_var(cgrDestination)] found in cache |9(15) DEBUG: <core> [core/pvapi.c:320]: pv_cache_lookup(): pvar [$dlg_var(profile)] found in cache |9(15) DEBUG: <core> [core/pvapi.c:320]: pv_cache_lookup(): pvar [$dlg_var(source_user)] found in cache |9(15) DEBUG: <core> [core/pvapi.c:320]: pv_cache_lookup(): pvar [$dlg_var(account_code)] found in cache |9(15) DEBUG: <core> [core/pvapi.c:320]: pv_cache_lookup(): pvar [$dlg_var(billing_cli)] found in cache |9(15) DEBUG: <core> [core/pvapi.c:320]: pv_cache_lookup(): pvar [$dlg_var(start_ts)] found in cache |9(15) DEBUG: <core> [core/pvapi.c:320]: pv_cache_lookup(): pvar [$dlg_var(reseller_id)] found in cache |9(15) DEBUG: <core> [core/pvapi.c:1103]: pv_parse_format(): format parsed OK: [12] items |9(15) INFO: <script>: $var(tmp):'"event":"CGR_AUTH_REQUEST", | "cgr_category":"$dlg_var(cgrCategory)", | "cgr_reqtype":"$dlg_var(cgrReqType)", | "cgr_tenant":"$dlg_var(tenant)", | "cgr_account":"$dlg_var(cgrAccount)", | "cgr_destination":"$dlg_var(cgrDestination)", | "direction":"$dlg_var(profile)", | "source_user":"$dlg_var(source_user)", | "recording_url":"", | "account_code":"$dlg_var(account_code)", | "billing_cli":"$dlg_var(billing_cli)", | "cgr_setuptime":"$dlg_var(start_ts)", | "reseller_id":"$dlg_var(reseller_id)"' |9(15) INFO: <script>: $var(msg):'start_ts 9(15) INFO: <script>: ++++ |9(15) DEBUG: <core> [core/receive.c:275]: receive_msg(): request-route executed in: 13678 usec

```

-- You are receiving this because you are subscribed to this thread. Reply to this email directly or view it on GitHub: https://github.com/kamailio/kamailio/issues/1551#issuecomment-393811584

Victor Seva

8:54 a.m.

has something to do with the size of the string ``` #!KAMAILIO # ####### Global Parameters #########

### LOG Levels: 3=DBG, 2=INFO, 1=NOTICE, 0=WARN, -1=ERR log_stderror=yes

memdbg=5 memlog=5

log_facility=LOG_LOCAL0 log_stderror=yes fork=no

/* uncomment the next line to disable TCP (default on) */ disable_tcp=yes

/* uncomment the next line to disable the auto discovery of local aliases * based on reverse DNS on IPs (default on) */ auto_aliases=no

/* add local domain aliases */ #alias="sip.mydomain.com"

/* uncomment and configure the following line if you want Kamailio to * bind on a specific interface/port/proto (default bind on all available) */ #listen=udp:10.0.0.10:5060

/* port to listen to * - can be specified more than once if needed to listen on many ports */ port=5060

# life time of TCP connection when there is no traffic # - a bit higher than registration expires to cope with UA behind NAT tcp_connection_lifetime=3605

####### Modules Section ########

# set paths to location of modules (to sources or installation folders) mpath="modules"

loadmodule "tm.so" loadmodule "rr.so" loadmodule "pv.so" loadmodule "dialog.so" loadmodule "xlog.so" loadmodule "debugger.so"

# ----------------- setting module-specific parameters ---------------

modparam("dialog", "dlg_flag", 9)

####### Routing Logic ########

xinfo("Send the call to CGRates ci='$ci'"); $var(tmp) = '"event":"CGR_AUTH_REQUEST", "cgr_tenant":"$dlg_var(tenant)", "cgr_account":"$dlg_var(cgrAccount)", "cgr_destination":"$dlg_var(cgrDestination)", "direction":"$dlg_var(profile)", "source_user":"$dlg_var(source_user)", "recording_url":"", "account_code":"$dlg_var(account_code)", "billing_cli":"$dlg_var(billing_cli)", "cgr_setuptime":"$dlg_var(start_ts)", "reseller_id":"$dlg_var(reseller_id)"'; pv_evalx("$var(msg)", "$var(tmp)"); xinfo("$$var(tmp):'$var(tmp)'\n"); xinfo("$$var(msg):'$var(msg)'\n"); xinfo("++++2222+++++\n"); } ```

``` | 10(17) DEBUG: <core> [core/pvapi.c:1103]: pv_parse_format(): format parsed OK: [12] items | 10(17) INFO: <script>: $var(tmp):'"event":"CGR_AUTH_REQUEST", | "cgr_category":"$dlg_var(cgrCategory)", | "cgr_reqtype":"$dlg_var(cgrReqType)", | "cgr_tenant":"$dlg_var(tenant)", | "cgr_account":"$dlg_var(cgrAccount)", | "cgr_destination":"$dlg_var(cgrDestination)", | "direction":"$dlg_var(profile)", | "source_user":"$dlg_var(source_user)", | "recording_url":"", | "account_code":"$dlg_var(account_code)", | "billing_cli":"$dlg_var(billing_cli)", | "cgr_setuptime":"$dlg_var(start_ts)", | "reseller_id":"$dlg_var(reseller_id)"' | 10(17) INFO: <script>: $var(msg):'start_ts10(17) INFO: <script>: ++++ | 10(17) INFO: <script>: Send the call to CGRates ci='210991253@172.19.0.3'10(17) DEBUG: <core> [core/pvapi.c:1056]: pv_parse_format(): parsing ["event":"CGR_AUTH_REQUEST", | "cgr_tenant":"$dlg_var(tenant)", | "cgr_account":"$dlg_var(cgrAccount)", | "cgr_destination":"$dlg_var(cgrDestination)", | "direction":"$dlg_var(profile)", | "source_user":"$dlg_var(source_user)", | "recording_url":"", | "account_code":"$dlg_var(account_code)", | "billing_cli":"$dlg_var(billing_cli)", | "cgr_setuptime":"$dlg_var(start_ts)", | "reseller_id":"$dlg_var(reseller_id)"] | 10(17) DEBUG: <core> [core/pvapi.c:320]: pv_cache_lookup(): pvar [$dlg_var(tenant)] found in cache | 10(17) DEBUG: <core> [core/pvapi.c:320]: pv_cache_lookup(): pvar [$dlg_var(cgrAccount)] found in cache | 10(17) DEBUG: <core> [core/pvapi.c:320]: pv_cache_lookup(): pvar [$dlg_var(cgrDestination)] found in cache | 10(17) DEBUG: <core> [core/pvapi.c:320]: pv_cache_lookup(): pvar [$dlg_var(profile)] found in cache | 10(17) DEBUG: <core> [core/pvapi.c:320]: pv_cache_lookup(): pvar [$dlg_var(source_user)] found in cache | 10(17) DEBUG: <core> [core/pvapi.c:320]: pv_cache_lookup(): pvar [$dlg_var(account_code)] found in cache | 10(17) DEBUG: <core> [core/pvapi.c:320]: pv_cache_lookup(): pvar [$dlg_var(billing_cli)] found in cache | 10(17) DEBUG: <core> [core/pvapi.c:320]: pv_cache_lookup(): pvar [$dlg_var(start_ts)] found in cache | 10(17) DEBUG: <core> [core/pvapi.c:320]: pv_cache_lookup(): pvar [$dlg_var(reseller_id)] found in cache | 10(17) DEBUG: <core> [core/pvapi.c:1103]: pv_parse_format(): format parsed OK: [10] items | 10(17) INFO: <script>: $var(tmp):'"event":"CGR_AUTH_REQUEST", | "cgr_tenant":"$dlg_var(tenant)", | "cgr_account":"$dlg_var(cgrAccount)", | "cgr_destination":"$dlg_var(cgrDestination)", | "direction":"$dlg_var(profile)", | "source_user":"$dlg_var(source_user)", | "recording_url":"", | "account_code":"$dlg_var(account_code)", | "billing_cli":"$dlg_var(billing_cli)", | "cgr_setuptime":"$dlg_var(start_ts)", | "reseller_id":"$dlg_var(reseller_id)"' | 10(17) INFO: <script>: $var(msg):'"event":"CGR_AUTH_REQUEST", | "cgr_tenant":"tenant", | "cgr_account":"cgrAccount", | "cgr_destination":"cgrDestination", | "direction":"profile", | "source_user":"source_user", | "recording_url":"", | "account_code":"account_code", | "billing_cli":"billing_cli", | "cgr_setuptime":"start_ts", | "reseller_id":"reseller_id"' | 10(17) INFO: <script>: ++++2222+++++ | 10(17) DEBUG: <core> [core/receive.c:275]: receive_msg(): request-route executed in: 13013 usec ```

-- You are receiving this because you are subscribed to this thread. Reply to this email directly or view it on GitHub: https://github.com/kamailio/kamailio/issues/1551#issuecomment-393816300

Daniel-Constantin Mierla

9:07 a.m.

The issue is that the dlg vars are using core pv buffers to copy their value before returning it. I assume they do the copy to avoid future race with their shared memory location, by cloning in pv buffers.

A quick solution is to increase pv_buffer_slots value:

* https://www.kamailio.org/wiki/cookbooks/5.1.x/core#pv_buffer_slots.

Must be more than the number of dlg vars you use in the string.

I will think to see if this can be avoided somehow in the dialog module.

-- You are receiving this because you are subscribed to this thread. Reply to this email directly or view it on GitHub: https://github.com/kamailio/kamailio/issues/1551#issuecomment-393820278

Daniel-Constantin Mierla

7 Jun 7 Jun

8:13 a.m.

To allow more flexibility, I increased the default number of slots from 10 to 40. Dialog specific code analysis will be considered in the future.

-- You are receiving this because you are subscribed to this thread. Reply to this email directly or view it on GitHub: https://github.com/kamailio/kamailio/issues/1551#issuecomment-395333021

Daniel-Constantin Mierla

8:13 a.m.

Closed #1551.

-- You are receiving this because you are subscribed to this thread. Reply to this email directly or view it on GitHub: https://github.com/kamailio/kamailio/issues/1551#event-1667912253

2570

Age (days ago)

2577

Last active (days ago)

sr-dev@lists.kamailio.org

7 comments

2 participants

tags (0)

participants (2)

Daniel-Constantin Mierla
Victor Seva