14 Sep
2017
14 Sep
'17
8:53 p.m.
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