^[OMApr 30 16:59:21 deepen ./kamailio[12378]: DEBUG: <core> [ip_addr.c:229]: print_ip(): tcpconn_new: new tcp connection: 127.0.0.1
Apr 30 16:59:21 deepen ./kamailio[12378]: DEBUG: <core> [tcp_main.c:985]: tcpconn_new(): on port 57362, type 2
Apr 30 16:59:21 deepen ./kamailio[12378]: DEBUG: <core> [tcp_main.c:1291]: tcpconn_add(): hashes: 300:466:561, 1
Apr 30 16:59:21 deepen ./kamailio[12378]: DEBUG: <core> [io_wait.h:380]: io_watch_add(): DBG: io_watch_add(0xa28740, 66, 2, 0x7fcf9b7e4de8), fd_no=57
Apr 30 16:59:21 deepen ./kamailio[12378]: DEBUG: <core> [io_wait.h:602]: io_watch_del(): DBG: io_watch_del (0xa28740, 66, -1, 0x0) fd_no=58 called
Apr 30 16:59:21 deepen ./kamailio[12378]: DEBUG: <core> [tcp_main.c:4139]: handle_tcpconn_ev(): sending to child, events 1
Apr 30 16:59:21 deepen ./kamailio[12378]: DEBUG: <core> [tcp_main.c:3818]: send2child(): selected tcp worker 0 38(12370) for activity on [tcp:127.0.0.1:5060], 0x7fcf9b7e4de8
Apr 30 16:59:21 deepen ./kamailio[12370]: DEBUG: <core> [tcp_read.c:1603]: handle_io(): received n=8 con=0x7fcf9b7e4de8, fd=6
Apr 30 16:59:21 deepen ./kamailio[12370]: DEBUG: <core> [tcp_read.c:1403]: tcp_read_req(): content-length=52
Apr 30 16:59:21 deepen ./kamailio[12370]: DEBUG: <core> [parser/msg_parser.c:595]: parse_msg(): SIP Request:
Apr 30 16:59:21 deepen ./kamailio[12370]: DEBUG: <core> [parser/msg_parser.c:597]: parse_msg():  method:  <POST>
Apr 30 16:59:21 deepen ./kamailio[12370]: DEBUG: <core> [parser/msg_parser.c:599]: parse_msg():  uri:     </rpc>
Apr 30 16:59:21 deepen ./kamailio[12370]: DEBUG: <core> [parser/msg_parser.c:601]: parse_msg():  version: <HTTP/1.1>
Apr 30 16:59:21 deepen ./kamailio[12370]: DEBUG: <core> [parser/msg_parser.c:183]: get_hdr_field(): content_length=52
Apr 30 16:59:21 deepen ./kamailio[12370]: DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header
Apr 30 16:59:21 deepen ./kamailio[12370]: DEBUG: <core> [receive.c:178]: receive_msg(): After parse_msg...
Apr 30 16:59:21 deepen ./kamailio[12370]: DEBUG: xhttp [xhttp_mod.c:356]: xhttp_handler(): new fake msg created (237 bytes):#012<POST /rpc HTTP/1.1#015#012Via: SIP/2.0/TCP 127.0.0.1:57362#015#012Host: 127.0.0.1:5060#015#012User-Agent: curl/7.47.0#015#012Accept: */*#015#012Content-Length: 52#015#012Content-Type: application/x-www-form-urlencoded#015#012#015#012{"jsonrpc":"2.0","method":"dispatcher.list", "id":1}>
Apr 30 16:59:21 deepen ./kamailio[12370]: DEBUG: <core> [parser/msg_parser.c:595]: parse_msg(): SIP Request:
Apr 30 16:59:21 deepen ./kamailio[12370]: DEBUG: <core> [parser/msg_parser.c:597]: parse_msg():  method:  <POST>
Apr 30 16:59:21 deepen ./kamailio[12370]: DEBUG: <core> [parser/msg_parser.c:599]: parse_msg():  uri:     </rpc>
Apr 30 16:59:21 deepen ./kamailio[12370]: DEBUG: <core> [parser/msg_parser.c:601]: parse_msg():  version: <HTTP/1.1>
Apr 30 16:59:21 deepen ./kamailio[12370]: DEBUG: <core> [parser/parse_via.c:2639]: parse_via(): end of header reached, state=5
Apr 30 16:59:21 deepen ./kamailio[12370]: DEBUG: <core> [parser/msg_parser.c:486]: parse_headers(): Via found, flags=2
Apr 30 16:59:21 deepen ./kamailio[12370]: DEBUG: <core> [parser/msg_parser.c:488]: parse_headers(): this is the first via
Apr 30 16:59:21 deepen ./kamailio[12370]: ERROR: <script>: json rpc dispatch
Apr 30 16:59:21 deepen ./kamailio[12370]: ERROR: jsonrpc-s [jsonrpc-s_mod.c:1188]: jsonrpc_exec_ex(): method callback not found [dispatcher.reload]
Apr 30 16:59:21 deepen ./kamailio[12370]: DEBUG: <core> [parser/msg_parser.c:183]: get_hdr_field(): content_length=52
Apr 30 16:59:21 deepen ./kamailio[12370]: DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found end of header
Apr 30 16:59:21 deepen ./kamailio[12370]: ERROR: jsonrpc-s [jsonrpc-s_mod.c:1087]: jsonrpc_dispatch(): method callback not found [dispatcher.list]
Apr 30 16:59:21 deepen ./kamailio[12370]: DEBUG: sl [sl.c:280]: send_reply(): reply in stateless mode (sl)
Apr 30 16:59:21 deepen ./kamailio[12370]: DEBUG: <core> [msg_translator.c:158]: check_via_address(): (127.0.0.1, 127.0.0.1, 0)
Apr 30 16:59:21 deepen ./kamailio[12370]: DEBUG: <core> [tcp_main.c:2195]: tcpconn_send_put(): send from reader (12370 (38)), reusing fd
Apr 30 16:59:21 deepen ./kamailio[12370]: DEBUG: <core> [tcp_main.c:2430]: tcpconn_do_send(): sending...
Apr 30 16:59:21 deepen ./kamailio[12370]: DEBUG: <core> [tcp_main.c:2464]: tcpconn_do_send(): after real write: c= 0x7fcf9b7e4de8 n=256 fd=6
Apr 30 16:59:21 deepen ./kamailio[12370]: DEBUG: <core> [tcp_main.c:2465]: tcpconn_do_send(): buf=#012HTTP/1.1 500 Method Not Found#015#012Sia: SIP/2.0/TCP 127.0.0.1:57362#015#012Content-Type: application/json#015#012Server: kamailio (4.4.7 (x86_64/linux))#015#012Content-Length: 96#015#012#015#012{#012#011"jsonrpc":#011"2.0",#012#011"error":#011{#012#011#011"code":#011-32000,#012#011#011"message":#011"Execution Error"#012#011},#012#011"id":#0111#012}
Apr 30 16:59:21 deepen ./kamailio[12370]: ERROR: <core> [data_lump_rpl.c:83]: add_lump_rpl2(): LUMP_RPL_BODY already added!
Apr 30 16:59:21 deepen ./kamailio[12370]: ERROR: xhttp [xhttp_mod.c:409]: xhttp_send_reply(): Error while adding reply lump
Apr 30 16:59:21 deepen ./kamailio[12370]: DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil)
Apr 30 16:59:21 deepen ./kamailio[12370]: message repeated 5 times: [ DEBUG: <core> [usr_avp.c:631]: destroy_avp_list(): destroying list (nil)]
Apr 30 16:59:21 deepen ./kamailio[12370]: DEBUG: <core> [xavp.c:446]: xavp_destroy_list(): destroying xavp list (nil)
Apr 30 16:59:21 deepen ./kamailio[12370]: DEBUG: <core> [receive.c:318]: receive_msg(): cleaning up
Apr 30 16:59:21 deepen ./kamailio[12370]: DEBUG: <core> [io_wait.h:380]: io_watch_add(): DBG: io_watch_add(0xa71d00, 6, 2, 0x7fcf9b7e4de8), fd_no=1
Apr 30 16:59:21 deepen ./kamailio[12370]: DEBUG: <core> [tcp_read.c:195]: tcp_make_closed_event(): TCP closed event creation triggered
Apr 30 16:59:21 deepen ./kamailio[12370]: DEBUG: <core> [tcp_read.c:203]: tcp_make_closed_event(): no callback registering for handling TCP closed event - dropping!
Apr 30 16:59:21 deepen ./kamailio[12370]: DEBUG: <core> [tcp_read.c:307]: tcp_read_data(): EOF on 0x7fcf9b7e4de8, FD 6 ([127.0.0.1]:57362 ->
Apr 30 16:59:21 deepen ./kamailio[12370]: DEBUG: <core> [tcp_read.c:308]: tcp_read_data(): -> [127.0.0.1]:5060)
Apr 30 16:59:21 deepen ./kamailio[12370]: DEBUG: <core> [tcp_read.c:1370]: tcp_read_req(): EOF
Apr 30 16:59:21 deepen ./kamailio[12370]: DEBUG: <core> [io_wait.h:602]: io_watch_del(): DBG: io_watch_del (0xa71d00, 6, -1, 0x10) fd_no=2 called
Apr 30 16:59:21 deepen ./kamailio[12370]: DEBUG: <core> [tcp_read.c:1524]: release_tcpconn(): releasing con 0x7fcf9b7e4de8, state -1, fd=6, id=1 ([127.0.0.1]:57362 -> [127.0.0.1]:5060)
Apr 30 16:59:21 deepen ./kamailio[12370]: DEBUG: <core> [tcp_read.c:1528]: release_tcpconn(): extra_data (nil)
Apr 30 16:59:21 deepen ./kamailio[12378]: DEBUG: <core> [tcp_main.c:3250]: handle_tcp_child(): reader response= 7fcf9b7e4de8, -1 from 0