jsonrpcc problem - sr-users

14 Sep 2017

Hello,

I have a problem with jsonrpcc module.

OS is Debian Jessie. I tried with packeged kamailio 4.4 and compiled
kamailio 5.0.

Kamailio starts properly. Routing block is very simple:

route {
    jsonrpc_request("get_source", "{'ipv4': '$si',
'port': $sp}",
"RESPONSE", "ERROR", "$var(result)");
    exit;
}

route[RESPONSE] {
   xlog("Result received: $var(result)");
}

route[ERROR] {
   xlog("Error received: $var(result)");
}

If any message arrives, kamailio sends it to my rpc_server (netstring
encoded):

106:{'id': 2, 'jsonrpc': '2.0', 'method':
'get_source', 'params': {'ipv4':
'10.20.121.231', 'port': 5060}},

My server decodes the message and immediately answers with netstring
encoded response:

47:{"jsonrpc": "2.0", "result": "200 OK",
"id": 2},

At this moment kamailio logs errors and disconnects from rpc server:

root@kamailio-test:/etc/kamailio#  4(925) DEBUG: <core>
[parser/msg_parser.c:595]: parse_msg(): SIP Request:
 4(925) DEBUG: <core> [parser/msg_parser.c:597]: parse_msg():  method:
 <REGISTER>
 4(925) DEBUG: <core> [parser/msg_parser.c:599]: parse_msg():  uri:
<sip:kamailio.conpeek.com>
 4(925) DEBUG: <core> [parser/msg_parser.c:601]: parse_msg():  version:
<SIP/2.0>
 4(925) DEBUG: <core> [parser/parse_via.c:1303]: parse_via_param(): Found
param type 232, <branch> = <z9hG4bK1033201528>; state=16
 4(925) DEBUG: <core> [parser/parse_via.c:2639]: parse_via(): end of header
reached, state=5
 4(925) DEBUG: <core> [parser/msg_parser.c:486]: parse_headers(): Via
found, flags=2
 4(925) DEBUG: <core> [parser/msg_parser.c:488]: parse_headers(): this is
the first via
 4(925) DEBUG: <core> [receive.c:178]: receive_msg(): After parse_msg...
 4(925) DEBUG: <core> [receive.c:221]: receive_msg(): preparing to run
routing scripts...
 4(925) DEBUG: tm [t_lookup.c:1312]: t_newtran(): DEBUG: t_newtran: msg
id=1 , global msg id=0 , T on entrance=0xffffffffffffffff
 4(925) DEBUG: <core> [parser/parse_addr_spec.c:864]: parse_addr_spec():
end of header reached, state=10
 4(925) DEBUG: <core> [parser/msg_parser.c:171]: get_hdr_field(): <To>
[40]; uri=[sip:1000@kamailio.conpeek.com]
 4(925) DEBUG: <core> [parser/msg_parser.c:172]: get_hdr_field(): to body
["1000" <sip:1000@kamailio.conpeek.com>
]
 4(925) DEBUG: <core> [parser/msg_parser.c:152]: get_hdr_field(): cseq
<CSeq>: <1> <REGISTER>
 4(925) DEBUG: <core> [parser/msg_parser.c:183]: get_hdr_field():
content_length=0
 4(925) DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end
of header
 4(925) DEBUG: tm [t_lookup.c:466]: t_lookup_request(): t_lookup_request:
start searching: hash=43020, isACK=0
 4(925) DEBUG: tm [t_lookup.c:424]: matching_3261(): DEBUG: RFC3261
transaction matching failed
 4(925) DEBUG: tm [t_lookup.c:648]: t_lookup_request(): DEBUG:
t_lookup_request: no transaction found
 4(925) DEBUG: <core> [md5utils.c:67]: MD5StringArray(): MD5 calculated:
920bfe28c581e1bf18554e3a9b234ceb
 4(925) DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list
(nil)
 4(925) DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list
(nil)
 4(925) DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list
(nil)
 4(925) DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list
(nil)
 4(925) DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list
(nil)
 4(925) DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list
(nil)
 4(925) DEBUG: <core> [xavp.c:446]: xavp_destroy_list(): destroying xavp
list (nil)
 4(925) DEBUG: <core> [receive.c:318]: receive_msg(): cleaning up
 *0(921) ALERT: <core> [main.c:740]: handle_sigs(): child process 929
exited by a signal 11*
* 0(921) ALERT: <core> [main.c:743]: handle_sigs(): core was generated*
 0(921) INFO: <core> [main.c:755]: handle_sigs(): terminating due to SIGCHLD
 0(921) DEBUG: <core> [main.c:757]: handle_sigs(): terminating due to
SIGCHLD
 7(928) INFO: <core> [main.c:810]: sig_usr(): signal 15 received
 6(927) INFO: <core> [main.c:810]: sig_usr(): signal 15 received
 5(926) INFO: <core> [main.c:810]: sig_usr(): signal 15 received
 4(925) INFO: <core> [main.c:810]: sig_usr(): signal 15 received
 3(924) INFO: <core> [main.c:810]: sig_usr(): signal 15 received
 2(923) INFO: <core> [main.c:810]: sig_usr(): signal 15 received
 1(922) INFO: <core> [main.c:810]: sig_usr(): signal 15 received
 0(921) DEBUG: tm [t_funcs.c:86]: tm_shutdown(): DEBUG: tm_shutdown : start
 0(921) DEBUG: tm [t_funcs.c:89]: tm_shutdown(): DEBUG: tm_shutdown :
emptying hash table
 0(921) DEBUG: tm [h_table.c:127]: free_cell_helper(): freeing transaction
0x7fb99a822350 from h_table.c:449
 0(921) DEBUG: tm [t_funcs.c:91]: tm_shutdown(): DEBUG: tm_shutdown :
removing semaphores
 0(921) DEBUG: tm [t_funcs.c:93]: tm_shutdown(): DEBUG: tm_shutdown :
destroying tmcb lists
 0(921) DEBUG: tm [t_funcs.c:96]: tm_shutdown(): DEBUG: tm_shutdown : done
 0(921) INFO: <core> [sctp_core.c:53]: sctp_core_destroy(): SCTP API not
initialized
 0(921) DEBUG: <core> [mem/shm.c:174]: shm_core_lock_destroy(): destroying
the shared memory lock
 0(921) DEBUG: <core> [mem/shm.c:266]: shm_destroy_manager(): destroying
memory manager: f_malloc
 0(921) DEBUG: <core> [mem/pkg.c:89]: pkg_destroy_manager(): destroying
memory manager: f_malloc

The problem occures also if rpc server does not reply, i think when trying
to start ERROR route.

If I try with jsonrpc_notification, everything is OK.

Any help ?

Regards Adam