sr-dev November 2024

sr-dev@lists.kamailio.org

20 participants
367 discussions

[kamailio/kamailio] Wrong Route header order in called party re-INVITE when using topos (Issue #3778)

by Javi Montoro

Hi There, We found a situation where topos seems to break, let me explain... Assuming the following scenario: ``` Caller ---- Callee A: ------INVITE-----> Record-Route:A.A.A.A, Record-Route:B.B.B.B B: <-----200 OK------ C: <-----INVITE------ Route: A.A.A.A, B.B.B.B D: ------200 OK-----> Record-Route:B.B.B.B, Record-Route:A.A.A.A (reversed order) E: <======INVITE===== Route: B.B.B.B, A.A.A.A (wrong) ``` A and B establish the connection from caller to callee, and topos works fine. C (re-INVITE from callee) sends the Route header according to the Record-Routes from the original INVITE (A) D is the 200 OK sent from the caller to the first re-INVITE (C) coming from the callee, with the Record-Route headers reversed, because is the order in which the callee received them; and according to the RFC it's working as intended: ``` When a UAS responds to a request with a response that establishes a dialog (such as a 2xx to INVITE), the UAS MUST copy all Record-Route header field values from the request into the response (including the URIs, URI parameters, and any Record-Route header field parameters, whether they are known or unknown to the UAS) and MUST maintain the order of those values. [...] [When a UAC receives a response...] The route set MUST be set to the list of URIs in the Record-Route header field from the response, taken in reverse order and preserving all URI parameters. ``` E takes the Route order from the last 200 OK ignoring they are in reversed order and assuming the top one is the first one, when it should be the other way around, sending to an IP address not reachable from the callee. And I think here is the issue, topos should not update the path on the Record-Routes from a 200 OK but if it does, it should take the reverse order When disabling topos, everything works fine, or with topos enabled, by setting rr_update=0 works for us, but what if there is a real path update, rr_update=0 wouldn't work for us anymore. The Kamailio version is 5.8.0-rc0 Let me know if you need more information. Thanks a lot, Javi -- Reply to this email directly or view it on GitHub: https://github.com/kamailio/kamailio/issues/3778 You are receiving this because you are subscribed to this thread. Message ID: <kamailio/kamailio/issues/3778(a)github.com>

1 month, 1 week

[kamailio/kamailio] The tm.cancel function immediately deletes the transaction. (Issue #4011)

by JuanPascual2

Hello I use the ‘**tm.t_uac_star**t’ function to send an INVITE and then the **‘tm.cancel**’ function to cancel the INVITE. But the ‘**tm.cancel**’ function immediately deletes the transaction. Kamailio can't acknowledge message 487 because the transaction has been deleted. **Callflow description:** ‘tm.t_uac_start’ Kamailio: INVITE ---> Kamailio: <--- 180 Ringing 'tm.cancel' Kamailio: CANCEL ---> Kamailio: <--- 200 OK (CANCEL) Kamailio: <--- 487 Request Terminated (INVITE) Kamailio can't send "ACK" because the transaction has been deleted **Below trace:** 21(45279) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: exec is str: {"params":["INVITE","sip:2345@sip.osp.com","sip:10.192.117.5:5049","udp:10.192.117.6:5084","From: <sip:+336111111111@fqfn-incoming.com>;tag=a5507eec786ec44a0aa7ec48e98248e4\r\nTo: <sip:2345@sip.osp.com>\r\nCSeq: 1 INVITE\r\nCall-ID: 68443192-0f66b617ce5917df\r\nContact: sip:Kasuar@10.192.117.6:5084\r\nContent-Type: application\/sdp\r\nSupported: gruu,replaces,norefersub\r\nAllow: INVITE,ACK,BYE,CANCEL,OPTIONS,NOTIFY,SUBSCRIBE,INFO,MESSAGE,UPDATE,REFER\r\nUser-Agent: tm.cancel\r\n","v=0\r\no=- 3218051986 1337943000 IN IP4 10.192.117.5\r\ns=-\r\nc=IN IP4 10.192.117.5\r\nt=0 0\r\nm=audio 5936 RTP\/AVP 0 8 101\r\na=rtpmap:0 PCMU\/8000\r\na=rtpmap:8 PCMA\/8000\r\na=rtpmap:101 telephone-event\/8000\r\na=fmtp:101 0-15\r\na=sendrecv\r\na=rtcp-rsize\r\na=ssrc:2797071403 cname:sip:1234@sip.osp.com\r\na=mid:0\r\na=minptime:20\r\na=ptime:20\r\na=label:1\r\n"],"jsonrpc":"2.0","method":"**tm.t_uac_start**","id":1} 21(45279) DEBUG: <core> [core/socket_info.c:740]: grep_sock_info(): checking if host==us: 12==12 && [10.192.117.6] == [10.192.117.6] 21(45279) DEBUG: <core> [core/socket_info.c:744]: grep_sock_info(): checking if port 5084 (advertise 0) matches port 5084 21(45279) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [From] type 4 21(45279) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [To] type 3 21(45279) DEBUG: <core> [core/parser/parse_addr_spec.c:904]: parse_addr_spec(): end of header reached, state=10 21(45279) DEBUG: <core> [core/parser/msg_parser.c:172]: get_hdr_field(): <To> [24]; uri=[sip:2345@sip.osp.com] 21(45279) DEBUG: <core> [core/parser/msg_parser.c:174]: get_hdr_field(): to body (24)[<sip:2345@sip.osp.com> ], to tag (0)[] 3(45261) INFO: <script>: 2024-10-31 10-54-06 [debug] /opt/kamailio/callbasic.lua:154: COMMANDE {"jsonrpc":"2.0","method":"**tm.cancel**","id":1,"params":["68443192-0f66b617ce5917df","1"]} 3(45261) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: exec is str: {"jsonrpc":"2.0","method":"tm.cancel","id":1,"params":["68443192-0f66b617ce5917df","1"]} 3(45261) DEBUG: tm [t_lookup.c:2285]: t_lookup_callid(): just locked hash index 43770, looking for transactions there: 3(45261) DEBUG: tm [t_lookup.c:2310]: t_lookup_callid(): we have a match: callid=>>Call-ID: 68443192-0f66b617ce5917df << cseq=>>CSeq: 1<< 3(45261) DEBUG: tm [t_lookup.c:2317]: t_lookup_callid(): t_lookup_callid: transaction found. 3(45261) DEBUG: tm [t_cancel.c:427]: rpc_cancel(): Now calling cancel_uacs 3(45261) DEBUG: tm [t_cancel.c:337]: cancel_branch(): sending cancel... 3(45261) DEBUG: <core> [core/parser/parse_fline.c:247]: parse_first_line(): first line type 1 (request) flags 1 3(45261) DEBUG: <core> [core/parser/msg_parser.c:722]: parse_msg(): SIP Request: 3(45261) DEBUG: <core> [core/parser/msg_parser.c:723]: parse_msg(): method: <CANCEL> 3(45261) DEBUG: <core> [core/parser/msg_parser.c:725]: parse_msg(): uri: <sip:2345@sip.osp.com> 3(45261) DEBUG: <core> [core/parser/msg_parser.c:727]: parse_msg(): version: <SIP/2.0> 3(45261) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Via] type 1 3(45261) DEBUG: <core> [core/parser/parse_via.c:1309]: parse_via_param(): Found param type 232, <branch> = <z9hG4bKafaa.fe4571d3000000000000000000000000.0>; state=16 3(45261) DEBUG: <core> [core/parser/parse_via.c:2663]: parse_via(): end of header reached, state=5 3(45261) DEBUG: <core> [core/parser/msg_parser.c:592]: parse_headers(): Via found, flags=2 3(45261) DEBUG: <core> [core/parser/msg_parser.c:594]: parse_headers(): this is the first via 3(45261) DEBUG: app_lua [app_lua_api.c:511]: sr_lua_reload_script(): No need to reload [/opt/kamailio/kamailio.lua] is version 0 3(45261) DEBUG: app_lua [app_lua_api.c:688]: app_lua_run_ex(): executing Lua function: [[ksr_tm_event]] 3(45261) DEBUG: app_lua [app_lua_api.c:690]: app_lua_run_ex(): lua top index is: 1 3(45261) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: xinfo is str: 2024-10-31 10-54-06 [debug] /opt/kamailio/kamailio.lua:143: --> Sending request: $rm 3(45261) DEBUG: <core> [core/pvapi.c:410]: pv_cache_lookup(): pvar [$rm] found in cache 3(45261) INFO: <script>: 2024-10-31 10-54-06 [debug] /opt/kamailio/kamailio.lua:143: --> Sending request: CANCEL 3(45261) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: get is str: $hdr(Hexarb) 3(45261) DEBUG: <core> [core/kemi.c:2988]: sr_kemi_pv_get_mode(): pv get: $hdr(Hexarb) 3(45261) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Hexarb] type 0 3(45261) DEBUG: <core> [core/pvapi.c:386]: pv_cache_add(): pvar [$hdr(Hexarb)] added in cache 3(45261) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [To] type 3 3(45261) DEBUG: <core> [core/parser/parse_addr_spec.c:904]: parse_addr_spec(): end of header reached, state=10 3(45261) DEBUG: <core> [core/parser/msg_parser.c:172]: get_hdr_field(): <To> [24]; uri=[sip:2345@sip.osp.com] 3(45261) DEBUG: <core> [core/parser/msg_parser.c:174]: get_hdr_field(): to body (24)[<sip:2345@sip.osp.com> ], to tag (0)[] 3(45261) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [From] type 4 3(45261) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [CSeq] type 5 3(45261) DEBUG: <core> [core/parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <1> <CANCEL> 3(45261) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Call-ID] type 6 3(45261) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Max-Forwards] type 8 3(45261) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Content-Length] type 12 3(45261) DEBUG: <core> [core/parser/msg_parser.c:187]: get_hdr_field(): content_length=0 3(45261) DEBUG: <core> [core/parser/msg_parser.c:91]: get_hdr_field(): found end of header 3(45261) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Via] type 1 3(45261) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Hexarb] type 0 3(45261) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [To] type 3 3(45261) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Hexarb] type 0 3(45261) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [From] type 4 3(45261) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Hexarb] type 0 3(45261) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [CSeq] type 5 3(45261) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Hexarb] type 0 3(45261) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Call-ID] type 6 3(45261) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Hexarb] type 0 3(45261) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Max-Forwards] type 8 3(45261) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Hexarb] type 0 3(45261) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Content-Length] type 12 3(45261) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Hexarb] type 0 3(45261) DEBUG: app_lua [app_lua_mod.c:166]: sr_kemi_config_engine_lua(): execution of route type 513 with name [ksr_tm_event] returned 1 3(45261) DEBUG: jsonrpcs [jsonrpcs_mod.c:443]: jsonrpc_send_mode(): sending response with body: (nil) - 200 OK 4(45262) DEBUG: <core> [core/udp_server.c:715]: udp_rcv_loop(): received on udp socket: (106/100/329) [[SIP/2.0 200 OK 0D 0A Via: SIP/2.0/UDP 10.192.117.6:5084;branch=z9hG4bKafaa.fe4571d30000000000000000000000]] 4(45262) DEBUG: <core> [core/parser/parse_fline.c:247]: parse_first_line(): first line type 2 (reply(status)) flags 1 4(45262) DEBUG: <core> [core/parser/msg_parser.c:732]: parse_msg(): SIP Reply (status): 4(45262) DEBUG: <core> [core/parser/msg_parser.c:733]: parse_msg(): version: <SIP/2.0> 4(45262) DEBUG: <core> [core/parser/msg_parser.c:735]: parse_msg(): status: <200> 4(45262) DEBUG: <core> [core/parser/msg_parser.c:737]: parse_msg(): reason: <OK> 4(45262) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Via] type 1 4(45262) DEBUG: <core> [core/parser/parse_via.c:1309]: parse_via_param(): Found param type 232, <branch> = <z9hG4bKafaa.fe4571d3000000000000000000000000.0>; state=16 4(45262) DEBUG: <core> [core/parser/parse_via.c:2663]: parse_via(): end of header reached, state=5 4(45262) DEBUG: <core> [core/parser/msg_parser.c:592]: parse_headers(): Via found, flags=2 4(45262) DEBUG: <core> [core/parser/msg_parser.c:594]: parse_headers(): this is the first via 4(45262) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [To] type 3 4(45262) DEBUG: <core> [core/parser/parse_addr_spec.c:185]: parse_to_param(): add param: tag=501b95048bf50805 4(45262) DEBUG: <core> [core/parser/parse_addr_spec.c:904]: parse_addr_spec(): end of header reached, state=29 4(45262) DEBUG: <core> [core/parser/msg_parser.c:172]: get_hdr_field(): <To> [45]; uri=[sip:2345@sip.osp.com] 4(45262) DEBUG: <core> [core/parser/msg_parser.c:174]: get_hdr_field(): to body (22)[<sip:2345@sip.osp.com>], to tag (16)[501b95048bf50805] 4(45262) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [From] type 4 4(45262) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [CSeq] type 5 4(45262) DEBUG: <core> [core/parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <1> <CANCEL> 4(45262) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Call-ID] type 6 4(45262) DEBUG: <core> [core/receive.c:392]: receive_msg(): --- received sip message - reply - call-id: [68443192-0f66b617ce5917df] - cseq: [1 CANCEL] 4(45262) DEBUG: <core> [core/receive.c:263]: ksr_evrt_pre_routing(): event route core:pre-routing not defined 6(45264) DEBUG: <core> [core/udp_server.c:715]: udp_rcv_loop(): received on udp socket: (106/100/345) [[SIP/2.0 487 Request Terminated 0D 0A Via: SIP/2.0/UDP 10.192.117.6:5084;branch=z9hG4bKafaa.fe4571d3000000]] 6(45264) DEBUG: <core> [core/parser/parse_fline.c:247]: parse_first_line(): first line type 2 (reply(status)) flags 1 6(45264) DEBUG: <core> [core/parser/msg_parser.c:732]: parse_msg(): SIP Reply (status): 6(45264) DEBUG: <core> [core/parser/msg_parser.c:733]: parse_msg(): version: <SIP/2.0> 4(45262) DEBUG: app_lua [app_lua_api.c:511]: sr_lua_reload_script(): No need to reload [/opt/kamailio/kamailio.lua] is version 6(45264) DEBUG: <core> [core/parser/msg_parser.c:735]: parse_msg(): status: <487> 3(45261) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: xinfo is str: 2024-10-31 10-54-06 [debug] /opt/kamailio/kamailio.lua:56: ksr_request_route: 21.28 ms 0 4(45262) DEBUG: app_lua [app_lua_api.c:688]: app_lua_run_ex(): executing Lua function: [[ksr_reply_route]] 6(45264) DEBUG: <core> [core/parser/msg_parser.c:737]: parse_msg(): reason: <Request Terminated> 6(45264) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Via] type 1 3(45261) INFO: <script>: 2024-10-31 10-54-06 [debug] /opt/kamailio/kamailio.lua:56: ksr_request_route: 21.28 ms 6(45264) DEBUG: <core> [core/parser/parse_via.c:1309]: parse_via_param(): Found param type 232, <branch> = <z9hG4bKafaa.fe4571d 4(45262) DEBUG: app_lua [app_lua_api.c:690]: app_lua_run_ex(): lua top index is: 17 3(45261) DEBUG: app_lua [app_lua_mod.c:169]: sr_kemi_config_engine_lua(): execution of route type 1 with no name returned 1 3(45261) DEBUG: <core> [core/receive.c:531]: receive_msg(): request-route executed in: 24264 usec 3000000000000000000000000.0>; state=16 6(45264) DEBUG: <core> [core/parser/parse_via.c:2663]: parse_via(): end of header reached, state=5 6(45264) DEBUG: <core> [core/parser/msg_parser.c:592]: parse_headers(): Via found, flags=2 6(45264) DEBUG: <core> [core/parser/msg_parser.c:594]: parse_headers(): this is the first via 3(45261) DEBUG: tm [h_table.c:133]: free_cell_helper(): freeing transaction 0x735e6229d610 from t_lookup.c:2059 3(45261) WARNING: tm [h_table.c:141]: free_cell_helper(): removed cell 0x735e6229d610 is still linked in hash table (t_lookup.c:2059) 3(45261) DEBUG: <core> [core/usr_avp.c:654]: destroy_avp_list(): destroying list (nil) 6(45264) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [To] type 3 6(45264) DEBUG: <core> [core/parser/parse_addr_spec.c:185]: parse_to_param(): add param: tag=501b95048bf50805 6(45264) DEBUG: <core> [core/parser/parse_addr_spec.c:904]: parse_addr_spec(): end of header reached, state=29 6(45264) DEBUG: <core> [core/parser/msg_parser.c:172]: get_hdr_field(): <To> [45]; uri=[sip:2345@sip.osp.com] 6(45264) DEBUG: <core> [core/parser/msg_parser.c:174]: get_hdr_field(): to body (22)[<sip:2345@sip.osp.com>], to tag (16)[501b95048bf50805] 6(45264) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [From] type 4 6(45264) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [CSeq] type 5 6(45264) DEBUG: <core> [core/parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <1> <INVITE> 6(45264) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Call-ID] type 6 6(45264) DEBUG: <core> [core/receive.c:392]: receive_msg(): --- received sip message - reply - call-id: [68443192-0f66b617ce5917df] - cseq: [1 INVITE] 6(45264) DEBUG: <core> [core/receive.c:263]: ksr_evrt_pre_routing(): event route core:pre-routing not defined 6(45264) DEBUG: app_lua [app_lua_api.c:511]: sr_lua_reload_script(): No need to reload [/opt/kamailio/kamailio.lua] is version 0 6(45264) DEBUG: app_lua [app_lua_api.c:688]: app_lua_run_ex(): executing Lua function: [[ksr_reply_route]] 6(45264) DEBUG: app_lua [app_lua_api.c:690]: app_lua_run_ex(): lua top index is: 17 6(45264) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: xinfo is str: 2024-10-31 10-54-06 [debug] /opt/kamailio/kamailio.lua:67: ++ Reply received $rs $rr 6(45264) DEBUG: <core> [core/pvapi.c:502]: pv_spec_lookup(): PV <$rs> is not in cache 6(45264) DEBUG: <core> [core/pvapi.c:352]: pv_cache_add(): PV cache not initialized, doing it now 6(45264) DEBUG: <core> [core/pvapi.c:386]: pv_cache_add(): pvar [$rs] added in cache 6(45264) DEBUG: <core> [core/pvapi.c:502]: pv_spec_lookup(): PV <$rr> is not in cache 6(45264) DEBUG: <core> [core/pvapi.c:386]: pv_cache_add(): pvar [$rr] added in cache 6(45264) INFO: <script>: 2024-10-31 10-54-06 [debug] /opt/kamailio/kamailio.lua:67: ++ Reply received 487 Request Terminated 6(45264) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: get is str: $ci 4(45262) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: xinfo is str: 2024-10-31 10-54-06 [debug] /opt/kamailio/kamailio.lua:67: ++ Reply received $rs $rr 4(45262) DEBUG: <core> [core/pvapi.c:502]: pv_spec_lookup(): PV <$rs> is not in cache 4(45262) DEBUG: <core> [core/pvapi.c:352]: pv_cache_add(): PV cache not initialized, doing it now 4(45262) DEBUG: <core> [core/pvapi.c:386]: pv_cache_add(): pvar [$rs] added in cache 4(45262) DEBUG: <core> [core/pvapi.c:502]: pv_spec_lookup(): PV <$rr> is not in cache 4(45262) DEBUG: <core> [core/pvapi.c:386]: pv_cache_add(): pvar [$rr] added in cache 4(45262) INFO: <script>: 2024-10-31 10-54-06 [debug] /opt/kamailio/kamailio.lua:67: ++ Reply received 200 OK 4(45262) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: get is str: $ci 4(45262) DEBUG: <core> [core/kemi.c:2988]: sr_kemi_pv_get_mode(): pv get: $ci 3(45261) DEBUG: <core> [core/usr_avp.c:654]: destroy_avp_list(): destroying list (nil) 3(45261) DEBUG: <core> [core/usr_avp.c:654]: destroy_avp_list(): destroying list (nil) 3(45261) DEBUG: <core> [core/usr_avp.c:654]: destroy_avp_list(): destroying list (nil) 3(45261) DEBUG: <core> [core/usr_avp.c:654]: destroy_avp_list(): destroying list (nil) 3(45261) DEBUG: <core> [core/usr_avp.c:654]: destroy_avp_list(): destroying list (nil) 3(45261) DEBUG: <core> [core/xavp.c:630]: xavp_destroy_list(): destroying xavp list (nil) 3(45261) DEBUG: <core> [core/xavp.c:630]: xavp_destroy_list(): destroying xavp list (nil) 3(45261) DEBUG: <core> [core/xavp.c:630]: xavp_destroy_list(): destroying xavp list (nil) 3(45261) DEBUG: <core> [core/receive.c:635]: receive_msg(): cleaning up 4(45262) DEBUG: <core> [core/pvapi.c:386]: pv_cache_add(): pvar [$ci] added in cache 6(45264) DEBUG: <core> [core/kemi.c:2988]: sr_kemi_pv_get_mode(): pv get: $ci 6(45264) DEBUG: <core> [core/pvapi.c:386]: pv_cache_add(): pvar [$ci] added in cache 4(45262) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: get is str: $mb 4(45262) DEBUG: <core> [core/kemi.c:2988]: sr_kemi_pv_get_mode(): pv get: $mb 6(45264) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: get is str: $mb 4(45262) DEBUG: <core> [core/pvapi.c:386]: pv_cache_add(): pvar [$mb] added in cache 4(45262) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: get is str: $mb 4(45262) DEBUG: <core> [core/kemi.c:2988]: sr_kemi_pv_get_mode(): pv get: $mb 4(45262) DEBUG: <core> [core/pvapi.c:410]: pv_cache_lookup(): pvar [$mb] found in cache 4(45262) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: get is str: $ci 4(45262) DEBUG: <core> [core/kemi.c:2988]: sr_kemi_pv_get_mode(): pv get: $ci 4(45262) DEBUG: <core> [core/pvapi.c:410]: pv_cache_lookup(): pvar [$ci] found in cache 6(45264) DEBUG: <core> [core/kemi.c:2988]: sr_kemi_pv_get_mode(): pv get: $mb 6(45264) DEBUG: <core> [core/pvapi.c:386]: pv_cache_add(): pvar [$mb] added in cache 6(45264) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: get is str: $mb 6(45264) DEBUG: <core> [core/kemi.c:2988]: sr_kemi_pv_get_mode(): pv get: $mb 6(45264) DEBUG: <core> [core/pvapi.c:410]: pv_cache_lookup(): pvar [$mb] found in cache 6(45264) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: get is str: $ci 6(45264) DEBUG: <core> [core/kemi.c:2988]: sr_kemi_pv_get_mode(): pv get: $ci 6(45264) DEBUG: <core> [core/pvapi.c:410]: pv_cache_lookup(): pvar [$ci] found in cache 4(45262) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: xinfo is str: 2024-10-31 10-54-06 [debug] /opt/kamailio/routing.lua:181: $rs $rm Type: term State: callout Callid: 68443192-0f66b617ce5917df 4(45262) DEBUG: <core> [core/pvapi.c:410]: pv_cache_lookup(): pvar [$rs] found in cache 4(45262) DEBUG: <core> [core/pvapi.c:502]: pv_spec_lookup(): PV <$rm> is not in cache 4(45262) DEBUG: <core> [core/pvapi.c:386]: pv_cache_add(): pvar [$rm] added in cache 4(45262) INFO: <script>: 2024-10-31 10-54-06 [debug] /opt/kamailio/routing.lua:181: 200 CANCEL Type: term State: callout Callid: 68443192-0f66b617ce5917df 4(45262) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: get is str: $rs 4(45262) DEBUG: <core> [core/kemi.c:2988]: sr_kemi_pv_get_mode(): pv get: $rs 4(45262) DEBUG: <core> [core/pvapi.c:410]: pv_cache_lookup(): pvar [$rs] found in cache 4(45262) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: get is str: $rs 4(45262) DEBUG: <core> [core/kemi.c:2988]: sr_kemi_pv_get_mode(): pv get: $rs 4(45262) DEBUG: <core> [core/pvapi.c:410]: pv_cache_lookup(): pvar [$rs] found in cache 4(45262) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: get is str: $rs 4(45262) DEBUG: <core> [core/kemi.c:2988]: sr_kemi_pv_get_mode(): pv get: $rs 4(45262) DEBUG: <core> [core/pvapi.c:410]: pv_cache_lookup(): pvar [$rs] found in cache 4(45262) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: get is str: $rs 4(45262) DEBUG: <core> [core/kemi.c:2988]: sr_kemi_pv_get_mode(): pv get: $rs 4(45262) DEBUG: <core> [core/pvapi.c:410]: pv_cache_lookup(): pvar [$rs] found in cache 4(45262) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: get is str: $rs 4(45262) DEBUG: <core> [core/kemi.c:2988]: sr_kemi_pv_get_mode(): pv get: $rs 4(45262) DEBUG: <core> [core/pvapi.c:410]: pv_cache_lookup(): pvar [$rs] found in cache 4(45262) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: get is str: $rs 4(45262) DEBUG: <core> [core/kemi.c:2988]: sr_kemi_pv_get_mode(): pv get: $rs 4(45262) DEBUG: <core> [core/pvapi.c:410]: pv_cache_lookup(): pvar [$rs] found in cache 4(45262) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: get is str: $rm 4(45262) DEBUG: <core> [core/kemi.c:2988]: sr_kemi_pv_get_mode(): pv get: $rm 4(45262) DEBUG: <core> [core/pvapi.c:410]: pv_cache_lookup(): pvar [$rm] found in cache 4(45262) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: get is str: $rs 4(45262) DEBUG: <core> [core/kemi.c:2988]: sr_kemi_pv_get_mode(): pv get: $rs 4(45262) DEBUG: <core> [core/pvapi.c:410]: pv_cache_lookup(): pvar [$rs] found in cache 4(45262) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: get is str: $rm 4(45262) DEBUG: <core> [core/kemi.c:2988]: sr_kemi_pv_get_mode(): pv get: $rm 4(45262) DEBUG: <core> [core/pvapi.c:410]: pv_cache_lookup(): pvar [$rm] found in cache 4(45262) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: xinfo is str: 2024-10-31 10-54-06 [debug] /opt/kamailio/kamailio.lua:71: ksr_reply_route: 13.63 ms 4(45262) INFO: <script>: 2024-10-31 10-54-06 [debug] /opt/kamailio/kamailio.lua:71: ksr_reply_route: 13.63 ms EBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: xinfo is str: 2024-10-31 10-54-07 0m 4(45262) DEBUG: app_lua [app_lua_mod.c:169]: sr_kemi_config_engine_lua(): execution of route type 128 with no name returned 1 [debug] /opt/kamailio/routing.lua:181: $rs $rm Type: term State: callout Callid: 68443192-0f66b617ce5917df 6(45264) DEBUG: <core> [core/pvapi.c:410]: pv_cache_lookup(): pvar [$rs] found in cache 6(45264) DEBUG: <core> [core/pvapi.c:502]: pv_spec_lookup(): PV <$rm> is not in cache 6(45264) DEBUG: <core> [core/pvapi.c:386]: pv_cache_add(): pvar [$rm] added in cache 4(45262) DEBUG: tm [t_lookup.c:1565]: t_check_msg(): msg (0x735e6b5b91d0) id=1/45262 global id=0/0 T start=0xffffffffffffffff 4(45262) DEBUG: tm [t_lookup.c:1410]: t_reply_matching(): t_reply_matching: hash 43770 label 0 branch 0 4(45262) DEBUG: tm [t_lookup.c:1539]: t_reply_matching(): no matching transaction exists 4(45262) DEBUG: tm [t_lookup.c:1542]: t_reply_matching(): failure to match a transaction 4(45262) DEBUG: tm [t_lookup.c:1637]: t_check_msg(): msg (0x735e6b5b91d0) id=1/45262 global id=1/45262 T end=(nil) 4(45262) DEBUG: tm [t_reply.c:2376]: reply_received(): **transaction not found** - (branch -1) 4(45262) DEBUG: app_lua [app_lua_api.c:511]: sr_lua_reload_script(): No need to reload [/opt/kamailio/kamailio.lua] is version 0 4(45262) DEBUG: app_lua [app_lua_api.c:688]: app_lua_run_ex(): executing Lua function: [[ksr_slreply]] 4(45262) DEBUG: app_lua [app_lua_api.c:690]: app_lua_run_ex(): lua top index is: 17 4(45262) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: xinfo is str: 2024-10-31 10-54-07 [debug] /opt/kamailio/kamailio.lua:169: Reply received on_sl_reply 4(45262) INFO: <script>: 2024-10-31 10-54-07 [debug] /opt/kamailio/kamailio.lua:169: Reply received on_sl_reply 4(45262) DEBUG: app_lua [app_lua_mod.c:166]: sr_kemi_config_engine_lua(): execution of route type 513 with name [ksr_slreply] returned 1 4(45262) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Server] type 29 4(45262) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Content-Length] type 12 4(45262) DEBUG: <core> [core/parser/msg_parser.c:187]: get_hdr_field(): content_length=0 4(45262) DEBUG: <core> [core/parser/msg_parser.c:91]: get_hdr_field(): found end of header 4(45262) DEBUG: <core> [core/forward.c:901]: do_forward_reply(): reply cannot be forwarded - no 2nd via 4(45262) DEBUG: <core> [core/receive.c:624]: receive_msg(): reply-route executed in: 22900 usec 4(45262) DEBUG: <core> [core/usr_avp.c:654]: destroy_avp_list(): destroying list (nil) 4(45262) DEBUG: <core> [core/usr_avp.c:654]: destroy_avp_list(): destroying list (nil) 4(45262) DEBUG: <core> [core/usr_avp.c:654]: destroy_avp_list(): destroying list (nil) 4(45262) DEBUG: <core> [core/usr_avp.c:654]: destroy_avp_list(): destroying list (nil) 4(45262) DEBUG: <core> [core/usr_avp.c:654]: destroy_avp_list(): destroying list (nil) 4(45262) DEBUG: <core> [core/usr_avp.c:654]: destroy_avp_list(): destroying list (nil) 4(45262) DEBUG: <core> [core/xavp.c:630]: xavp_destroy_list(): destroying xavp list (nil) 4(45262) DEBUG: <core> [core/xavp.c:630]: xavp_destroy_list(): destroying xavp list (nil) 4(45262) DEBUG: <core> [core/xavp.c:630]: xavp_destroy_list(): destroying xavp list (nil) 4(45262) DEBUG: <core> [core/receive.c:635]: receive_msg(): cleaning up 6(45264) INFO: <script>: 2024-10-31 10-54-07 [debug] /opt/kamailio/routing.lua:181: 487 INVITE Type: term State: callout Callid: 68443192-0f66b617ce5917df 6(45264) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: get is str: $rs 6(45264) DEBUG: <core> [core/kemi.c:2988]: sr_kemi_pv_get_mode(): pv get: $rs 6(45264) DEBUG: <core> [core/pvapi.c:410]: pv_cache_lookup(): pvar [$rs] found in cache 6(45264) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: get is str: $hdr(RAck) 6(45264) DEBUG: <core> [core/kemi.c:2988]: sr_kemi_pv_get_mode(): pv get: $hdr(RAck) 6(45264) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [RAck] type 0 6(45264) DEBUG: <core> [core/pvapi.c:386]: pv_cache_add(): pvar [$hdr(RAck)] added in cache 6(45264) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Server] type 29 6(45264) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Content-Length] type 12 6(45264) DEBUG: <core> [core/parser/msg_parser.c:187]: get_hdr_field(): content_length=0 6(45264) DEBUG: <core> [core/parser/msg_parser.c:91]: get_hdr_field(): found end of header 6(45264) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Via] type 1 6(45264) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [RAck] type 0 6(45264) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [To] type 3 6(45264) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [RAck] type 0 6(45264) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [From] type 4 6(45264) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [RAck] type 0 6(45264) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [CSeq] type 5 6(45264) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [RAck] type 0 6(45264) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Call-ID] type 6 6(45264) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [RAck] type 0 6(45264) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Server] type 29 6(45264) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [RAck] type 0 6(45264) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Content-Length] type 12 6(45264) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [RAck] type 0 6(45264) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: get is str: $hdr(Require) 6(45264) DEBUG: <core> [core/kemi.c:2988]: sr_kemi_pv_get_mode(): pv get: $hdr(Require) 6(45264) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Require] type 18 6(45264) DEBUG: pv [pv_core.c:3926]: pv_parse_hdr_name(): using hdr type (18) instead of <Require> 6(45264) DEBUG: <core> [core/pvapi.c:386]: pv_cache_add(): pvar [$hdr(Require)] added in cache 6(45264) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: get is str: $hdr(RSeq) 6(45264) DEBUG: <core> [core/kemi.c:2988]: sr_kemi_pv_get_mode(): pv get: $hdr(RSeq) 6(45264) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [RSeq] type 0 6(45264) DEBUG: <core> [core/pvapi.c:386]: pv_cache_add(): pvar [$hdr(RSeq)] added in cache 6(45264) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Via] type 1 6(45264) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [RSeq] type 0 6(45264) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [To] type 3 6(45264) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [RSeq] type 0 6(45264) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [From] type 4 6(45264) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [RSeq] type 0 6(45264) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [CSeq] type 5 6(45264) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [RSeq] type 0 6(45264) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Call-ID] type 6 6(45264) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [RSeq] type 0 6(45264) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Server] type 29 6(45264) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [RSeq] type 0 6(45264) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Content-Length] type 12 6(45264) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [RSeq] type 0 6(45264) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: get is str: $hdr(User-Agent) 6(45264) DEBUG: <core> [core/kemi.c:2988]: sr_kemi_pv_get_mode(): pv get: $hdr(User-Agent) 6(45264) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [User-Agent] type 28 6(45264) DEBUG: pv [pv_core.c:3926]: pv_parse_hdr_name(): using hdr type (28) instead of <User-Agent> 6(45264) DEBUG: <core> [core/pvapi.c:386]: pv_cache_add(): pvar [$hdr(User-Agent)] added in cache 6(45264) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: get is str: $hdr(Accept) 6(45264) DEBUG: <core> [core/kemi.c:2988]: sr_kemi_pv_get_mode(): pv get: $hdr(Accept) 6(45264) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Accept] type 23 6(45264) DEBUG: pv [pv_core.c:3926]: pv_parse_hdr_name(): using hdr type (23) instead of <Accept> 6(45264) DEBUG: <core> [core/pvapi.c:386]: pv_cache_add(): pvar [$hdr(Accept)] added in cache 6(45264) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: get is str: $hdr(Server) 6(45264) DEBUG: <core> [core/kemi.c:2988]: sr_kemi_pv_get_mode(): pv get: $hdr(Server) 6(45264) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Server] type 29 6(45264) DEBUG: pv [pv_core.c:3926]: pv_parse_hdr_name(): using hdr type (29) instead of <Server> 6(45264) DEBUG: <core> [core/pvapi.c:386]: pv_cache_add(): pvar [$hdr(Server)] added in cache 6(45264) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: get is str: $hdr(Server) 6(45264) DEBUG: <core> [core/kemi.c:2988]: sr_kemi_pv_get_mode(): pv get: $hdr(Server) 6(45264) DEBUG: <core> [core/pvapi.c:410]: pv_cache_lookup(): pvar [$hdr(Server)] found in cache 6(45264) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: get is str: $hdr(Supported) 6(45264) DEBUG: <core> [core/kemi.c:2988]: sr_kemi_pv_get_mode(): pv get: $hdr(Supported) 6(45264) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Supported] type 17 6(45264) DEBUG: pv [pv_core.c:3926]: pv_parse_hdr_name(): using hdr type (17) instead of <Supported> 6(45264) DEBUG: <core> [core/pvapi.c:386]: pv_cache_add(): pvar [$hdr(Supported)] added in cache 6(45264) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: get is str: $hdr(Allow) 6(45264) DEBUG: <core> [core/kemi.c:2988]: sr_kemi_pv_get_mode(): pv get: $hdr(Allow) 6(45264) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Allow] type 21 6(45264) DEBUG: pv [pv_core.c:3926]: pv_parse_hdr_name(): using hdr type (21) instead of <Allow> 6(45264) DEBUG: <core> [core/pvapi.c:386]: pv_cache_add(): pvar [$hdr(Allow)] added in cache 6(45264) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: get is str: $rm 6(45264) DEBUG: <core> [core/kemi.c:2988]: sr_kemi_pv_get_mode(): pv get: $rm 6(45264) DEBUG: <core> [core/pvapi.c:410]: pv_cache_lookup(): pvar [$rm] found in cache 6(45264) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: get is str: $rs 6(45264) DEBUG: <core> [core/kemi.c:2988]: sr_kemi_pv_get_mode(): pv get: $rs 6(45264) DEBUG: <core> [core/pvapi.c:410]: pv_cache_lookup(): pvar [$rs] found in cache 6(45264) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: xinfo is str: 2024-10-31 10-54-07 [debug] /opt/kamailio/kamailio.lua:71: ksr_reply_route: 26.79 ms 6(45264) INFO: <script>: 2024-10-31 10-54-07 [debug] /opt/kamailio/kamailio.lua:71: ksr_reply_route: 26.79 ms 6(45264) DEBUG: app_lua [app_lua_mod.c:169]: sr_kemi_config_engine_lua(): execution of route type 128 with no name returned 1 6(45264) DEBUG: tm [t_lookup.c:1565]: t_check_msg(): msg (0x735e6b5b91d0) id=1/45264 global id=0/0 T start=0xffffffffffffffff 6(45264) DEBUG: tm [t_lookup.c:1410]: t_reply_matching(): t_reply_matching: hash 43770 label 0 branch 0 6(45264) DEBUG: tm [t_lookup.c:1539]: t_reply_matching(): no matching transaction exists 6(45264) DEBUG: tm [t_lookup.c:1542]: t_reply_matching(): failure to match a transaction 6(45264) DEBUG: tm [t_lookup.c:1637]: t_check_msg(): msg (0x735e6b5b91d0) id=1/45264 global id=1/45264 T end=(nil) 6(45264) DEBUG: tm [t_reply.c:2376]: reply_received(): transaction not found - (branch -1) 6(45264) DEBUG: app_lua [app_lua_api.c:511]: sr_lua_reload_script(): No need to reload [/opt/kamailio/kamailio.lua] is version 0 6(45264) DEBUG: app_lua [app_lua_api.c:688]: app_lua_run_ex(): executing Lua function: [[ksr_slreply]] 6(45264) DEBUG: app_lua [app_lua_api.c:690]: app_lua_run_ex(): lua top index is: 17 6(45264) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: xinfo is str: 2024-10-31 10-54-07 [debug] /opt/kamailio/kamailio.lua:169: Reply received on_sl_reply 6(45264) INFO: <script>: 2024-10-31 10-54-07 [debug] /opt/kamailio/kamailio.lua:169: Reply received on_sl_reply 6(45264) DEBUG: app_lua [app_lua_mod.c:166]: sr_kemi_config_engine_lua(): execution of route type 513 with name [ksr_slreply] returned 1 6(45264) DEBUG: <core> [core/forward.c:901]: do_forward_reply(): reply cannot be forwarded - no 2nd via 6(45264) DEBUG: <core> [core/receive.c:624]: receive_msg(): reply-route executed in: 28304 usec 6(45264) DEBUG: <core> [core/usr_avp.c:654]: destroy_avp_list(): destroying list (nil) 6(45264) DEBUG: <core> [core/usr_avp.c:654]: destroy_avp_list(): destroying list (nil) 6(45264) DEBUG: <core> [core/usr_avp.c:654]: destroy_avp_list(): destroying list (nil) 6(45264) DEBUG: <core> [core/usr_avp.c:654]: destroy_avp_list(): destroying list (nil) 6(45264) DEBUG: <core> [core/usr_avp.c:654]: destroy_avp_list(): destroying list (nil) 6(45264) DEBUG: <core> [core/usr_avp.c:654]: destroy_avp_list(): destroying list (nil) 6(45264) DEBUG: <core> [core/xavp.c:630]: xavp_destroy_list(): destroying xavp list (nil) 6(45264) DEBUG: <core> [core/xavp.c:630]: xavp_destroy_list(): destroying xavp list (nil) 6(45264) DEBUG: <core> [core/xavp.c:630]: xavp_destroy_list(): destroying xavp list (nil) 6(45264) DEBUG: <core> [core/receive.c:635]: receive_msg(): cleaning up 5(45263) DEBUG: <core> [core/udp_server.c:715]: udp_rcv_loop(): received on udp socket: (106/100/345) [[SIP/2.0 487 Request Terminated 0D 0A Via: SIP/2.0/UDP 10.192.117.6:5084;branch=z9hG4bKafaa.fe4571d3000000]] 5(45263) DEBUG: <core> [core/parser/parse_fline.c:247]: parse_first_line(): first line type 2 (reply(status)) flags 1 5(45263) DEBUG: <core> [core/parser/msg_parser.c:732]: parse_msg(): SIP Reply (status): 5(45263) DEBUG: <core> [core/parser/msg_parser.c:733]: parse_msg(): version: <SIP/2.0> 5(45263) DEBUG: <core> [core/parser/msg_parser.c:735]: parse_msg(): status: <487> 5(45263) DEBUG: <core> [core/parser/msg_parser.c:737]: parse_msg(): reason: <Request Terminated> 5(45263) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Via] type 1 5(45263) DEBUG: <core> [core/parser/parse_via.c:1309]: parse_via_param(): Found param type 232, <branch> = <z9hG4bKafaa.fe4571d3000000000000000000000000.0>; state=16 5(45263) DEBUG: <core> [core/parser/parse_via.c:2663]: parse_via(): end of header reached, state=5 5(45263) DEBUG: <core> [core/parser/msg_parser.c:592]: parse_headers(): Via found, flags=2 5(45263) DEBUG: <core> [core/parser/msg_parser.c:594]: parse_headers(): this is the first via 5(45263) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [To] type 3 5(45263) DEBUG: <core> [core/parser/parse_addr_spec.c:185]: parse_to_param(): add param: tag=501b95048bf50805 5(45263) DEBUG: <core> [core/parser/parse_addr_spec.c:904]: parse_addr_spec(): end of header reached, state=29 5(45263) DEBUG: <core> [core/parser/msg_parser.c:172]: get_hdr_field(): <To> [45]; uri=[sip:2345@sip.osp.com] 5(45263) DEBUG: <core> [core/parser/msg_parser.c:174]: get_hdr_field(): to body (22)[<sip:2345@sip.osp.com>], to tag (16)[501b95048bf50805] 5(45263) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [From] type 4 5(45263) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [CSeq] type 5 5(45263) DEBUG: <core> [core/parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <1> <INVITE> 5(45263) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Call-ID] type 6 5(45263) DEBUG: <core> [core/receive.c:392]: receive_msg(): --- received sip message - reply - call-id: [68443192-0f66b617ce5917df] - cseq: [1 INVITE] 5(45263) DEBUG: <core> [core/receive.c:263]: ksr_evrt_pre_routing(): event route core:pre-routing not defined 5(45263) DEBUG: app_lua [app_lua_api.c:511]: sr_lua_reload_script(): No need to reload [/opt/kamailio/kamailio.lua] is version 0 5(45263) DEBUG: app_lua [app_lua_api.c:688]: app_lua_run_ex(): executing Lua function: [[ksr_reply_route]] 5(45263) DEBUG: app_lua [app_lua_api.c:690]: app_lua_run_ex(): lua top index is: 17 5(45263) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: xinfo is str: 2024-10-31 10-54-07 [debug] /opt/kamailio/kamailio.lua:67: ++ Reply received $rs $rr 5(45263) DEBUG: <core> [core/pvapi.c:502]: pv_spec_lookup(): PV <$rs> is not in cache 5(45263) DEBUG: <core> [core/pvapi.c:352]: pv_cache_add(): PV cache not initialized, doing it now 5(45263) DEBUG: <core> [core/pvapi.c:386]: pv_cache_add(): pvar [$rs] added in cache 5(45263) DEBUG: <core> [core/pvapi.c:502]: pv_spec_lookup(): PV <$rr> is not in cache 5(45263) DEBUG: <core> [core/pvapi.c:386]: pv_cache_add(): pvar [$rr] added in cache 5(45263) INFO: <script>: 2024-10-31 10-54-07 [debug] /opt/kamailio/kamailio.lua:67: ++ Reply received 487 Request Terminated 5(45263) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: get is str: $ci 5(45263) DEBUG: <core> [core/kemi.c:2988]: sr_kemi_pv_get_mode(): pv get: $ci 5(45263) DEBUG: <core> [core/pvapi.c:386]: pv_cache_add(): pvar [$ci] added in cache 5(45263) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: get is str: $mb 5(45263) DEBUG: <core> [core/kemi.c:2988]: sr_kemi_pv_get_mode(): pv get: $mb 5(45263) DEBUG: <core> [core/pvapi.c:386]: pv_cache_add(): pvar [$mb] added in cache 5(45263) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: get is str: $mb 5(45263) DEBUG: <core> [core/kemi.c:2988]: sr_kemi_pv_get_mode(): pv get: $mb 5(45263) DEBUG: <core> [core/pvapi.c:410]: pv_cache_lookup(): pvar [$mb] found in cache 5(45263) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: xinfo is str: 2024-10-31 10-54-07 [debug] /opt/kamailio/kamailio.lua:71: ksr_reply_route: 6.68 ms 5(45263) INFO: <script>: 2024-10-31 10-54-07 [debug] /opt/kamailio/kamailio.lua:71: ksr_reply_route: 6.68 ms 5(45263) DEBUG: app_lua [app_lua_mod.c:169]: sr_kemi_config_engine_lua(): execution of route type 128 with no name returned 1 5(45263) DEBUG: tm [t_lookup.c:1565]: t_check_msg(): msg (0x735e6b5b91d0) id=1/45263 global id=0/0 T start=0xffffffffffffffff 5(45263) DEBUG: tm [t_lookup.c:1410]: t_reply_matching(): t_reply_matching: hash 43770 label 0 branch 0 5(45263) DEBUG: tm [t_lookup.c:1539]: t_reply_matching(): no matching transaction exists 5(45263) DEBUG: tm [t_lookup.c:1542]: t_reply_matching(): failure to match a transaction 5(45263) DEBUG: tm [t_lookup.c:1637]: t_check_msg(): msg (0x735e6b5b91d0) id=1/45263 global id=1/45263 T end=(nil) 5(45263) DEBUG: tm [t_reply.c:2376]: reply_received(): transaction not found - (branch -1) 5(45263) DEBUG: app_lua [app_lua_api.c:511]: sr_lua_reload_script(): No need to reload [/opt/kamailio/kamailio.lua] is version 0 5(45263) DEBUG: app_lua [app_lua_api.c:688]: app_lua_run_ex(): executing Lua function: [[ksr_slreply]] 5(45263) DEBUG: app_lua [app_lua_api.c:690]: app_lua_run_ex(): lua top index is: 17 5(45263) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: xinfo is str: 2024-10-31 10-54-07 [debug] /opt/kamailio/kamailio.lua:169: Reply received on_sl_reply 5(45263) INFO: <script>: 2024-10-31 10-54-07 [debug] /opt/kamailio/kamailio.lua:169: Reply received on_sl_reply 5(45263) DEBUG: app_lua [app_lua_mod.c:166]: sr_kemi_config_engine_lua(): execution of route type 513 with name [ksr_slreply] returned 1 5(45263) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Server] type 29 5(45263) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Content-Length] type 12 5(45263) DEBUG: <core> [core/parser/msg_parser.c:187]: get_hdr_field(): content_length=0 5(45263) DEBUG: <core> [core/parser/msg_parser.c:91]: get_hdr_field(): found end of header 5(45263) DEBUG: <core> [core/forward.c:901]: do_forward_reply(): reply cannot be forwarded - no 2nd via 5(45263) DEBUG: <core> [core/receive.c:624]: receive_msg(): reply-route executed in: 8967 usec 5(45263) DEBUG: <core> [core/usr_avp.c:654]: destroy_avp_list(): destroying list (nil) 5(45263) DEBUG: <core> [core/usr_avp.c:654]: destroy_avp_list(): destroying list (nil) 5(45263) DEBUG: <core> [core/usr_avp.c:654]: destroy_avp_list(): destroying list (nil) 5(45263) DEBUG: <core> [core/usr_avp.c:654]: destroy_avp_list(): destroying list (nil) 5(45263) DEBUG: <core> [core/usr_avp.c:654]: destroy_avp_list(): destroying list (nil) 5(45263) DEBUG: <core> [core/usr_avp.c:654]: destroy_avp_list(): destroying list (nil) 5(45263) DEBUG: <core> [core/xavp.c:630]: xavp_destroy_list(): destroying xavp list (nil) 5(45263) DEBUG: <core> [core/xavp.c:630]: xavp_destroy_list(): destroying xavp list (nil) 5(45263) DEBUG: <core> [core/xavp.c:630]: xavp_destroy_list(): destroying xavp list (nil) 5(45263) DEBUG: <core> [core/receive.c:635]: receive_msg(): cleaning up 7(45265) DEBUG: <core> [core/udp_server.c:715]: udp_rcv_loop(): received on udp socket: (106/100/345) [[SIP/2.0 487 Request Terminated 0D 0A Via: SIP/2.0/UDP 10.192.117.6:5084;branch=z9hG4bKafaa.fe4571d3000000]] 7(45265) DEBUG: <core> [core/parser/parse_fline.c:247]: parse_first_line(): first line type 2 (reply(status)) flags 1 7(45265) DEBUG: <core> [core/parser/msg_parser.c:732]: parse_msg(): SIP Reply (status): 7(45265) DEBUG: <core> [core/parser/msg_parser.c:733]: parse_msg(): version: <SIP/2.0> 7(45265) DEBUG: <core> [core/parser/msg_parser.c:735]: parse_msg(): status: <487> 7(45265) DEBUG: <core> [core/parser/msg_parser.c:737]: parse_msg(): reason: <Request Terminated> 7(45265) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Via] type 1 7(45265) DEBUG: <core> [core/parser/parse_via.c:1309]: parse_via_param(): Found param type 232, <branch> = <z9hG4bKafaa.fe4571d3000000000000000000000000.0>; state=16 7(45265) DEBUG: <core> [core/parser/parse_via.c:2663]: parse_via(): end of header reached, state=5 7(45265) DEBUG: <core> [core/parser/msg_parser.c:592]: parse_headers(): Via found, flags=2 7(45265) DEBUG: <core> [core/parser/msg_parser.c:594]: parse_headers(): this is the first via 7(45265) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [To] type 3 7(45265) DEBUG: <core> [core/parser/parse_addr_spec.c:185]: parse_to_param(): add param: tag=501b95048bf50805 7(45265) DEBUG: <core> [core/parser/parse_addr_spec.c:904]: parse_addr_spec(): end of header reached, state=29 7(45265) DEBUG: <core> [core/parser/msg_parser.c:172]: get_hdr_field(): <To> [45]; uri=[sip:2345@sip.osp.com] 7(45265) DEBUG: <core> [core/parser/msg_parser.c:174]: get_hdr_field(): to body (22)[<sip:2345@sip.osp.com>], to tag (16)[501b95048bf50805] 7(45265) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [From] type 4 7(45265) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [CSeq] type 5 7(45265) DEBUG: <core> [core/parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <1> <INVITE> 7(45265) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Call-ID] type 6 7(45265) DEBUG: <core> [core/receive.c:392]: receive_msg(): --- received sip message - reply - call-id: [68443192-0f66b617ce5917df] - cseq: [1 INVITE] 7(45265) DEBUG: <core> [core/receive.c:263]: ksr_evrt_pre_routing(): event route core:pre-routing not defined 7(45265) DEBUG: app_lua [app_lua_api.c:511]: sr_lua_reload_script(): No need to reload [/opt/kamailio/kamailio.lua] is version 0 7(45265) DEBUG: app_lua [app_lua_api.c:688]: app_lua_run_ex(): executing Lua function: [[ksr_reply_route]] 7(45265) DEBUG: app_lua [app_lua_api.c:690]: app_lua_run_ex(): lua top index is: 17 7(45265) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: xinfo is str: 2024-10-31 10-54-08 [debug] /opt/kamailio/kamailio.lua:67: ++ Reply received $rs $rr 7(45265) DEBUG: <core> [core/pvapi.c:502]: pv_spec_lookup(): PV <$rs> is not in cache 7(45265) DEBUG: <core> [core/pvapi.c:352]: pv_cache_add(): PV cache not initialized, doing it now 7(45265) DEBUG: <core> [core/pvapi.c:386]: pv_cache_add(): pvar [$rs] added in cache 7(45265) DEBUG: <core> [core/pvapi.c:502]: pv_spec_lookup(): PV <$rr> is not in cache 7(45265) DEBUG: <core> [core/pvapi.c:386]: pv_cache_add(): pvar [$rr] added in cache 7(45265) INFO: <script>: 2024-10-31 10-54-08 [debug] /opt/kamailio/kamailio.lua:67: ++ Reply received 487 Request Terminated 7(45265) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: get is str: $ci 7(45265) DEBUG: <core> [core/kemi.c:2988]: sr_kemi_pv_get_mode(): pv get: $ci 7(45265) DEBUG: <core> [core/pvapi.c:386]: pv_cache_add(): pvar [$ci] added in cache 7(45265) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: get is str: $mb 7(45265) DEBUG: <core> [core/kemi.c:2988]: sr_kemi_pv_get_mode(): pv get: $mb 7(45265) DEBUG: <core> [core/pvapi.c:386]: pv_cache_add(): pvar [$mb] added in cache 7(45265) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: get is str: $mb 7(45265) DEBUG: <core> [core/kemi.c:2988]: sr_kemi_pv_get_mode(): pv get: $mb 7(45265) DEBUG: <core> [core/pvapi.c:410]: pv_cache_lookup(): pvar [$mb] found in cache 7(45265) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: xinfo is str: 2024-10-31 10-54-08 [debug] /opt/kamailio/kamailio.lua:71: ksr_reply_route: 6.48 ms 7(45265) INFO: <script>: 2024-10-31 10-54-08 [debug] /opt/kamailio/kamailio.lua:71: ksr_reply_route: 6.48 ms 7(45265) DEBUG: app_lua [app_lua_mod.c:169]: sr_kemi_config_engine_lua(): execution of route type 128 with no name returned 1 7(45265) DEBUG: tm [t_lookup.c:1565]: t_check_msg(): msg (0x735e6b5b91d0) id=1/45265 global id=0/0 T start=0xffffffffffffffff 7(45265) DEBUG: tm [t_lookup.c:1410]: t_reply_matching(): t_reply_matching: hash 43770 label 0 branch 0 7(45265) DEBUG: tm [t_lookup.c:1539]: t_reply_matching(): no matching transaction exists 7(45265) DEBUG: tm [t_lookup.c:1542]: t_reply_matching(): failure to match a transaction 7(45265) DEBUG: tm [t_lookup.c:1637]: t_check_msg(): msg (0x735e6b5b91d0) id=1/45265 global id=1/45265 T end=(nil) 7(45265) DEBUG: tm [t_reply.c:2376]: reply_received(): transaction not found - (branch -1) 7(45265) DEBUG: app_lua [app_lua_api.c:511]: sr_lua_reload_script(): No need to reload [/opt/kamailio/kamailio.lua] is version 0 7(45265) DEBUG: app_lua [app_lua_api.c:688]: app_lua_run_ex(): executing Lua function: [[ksr_slreply]] 7(45265) DEBUG: app_lua [app_lua_api.c:690]: app_lua_run_ex(): lua top index is: 17 7(45265) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: xinfo is str: 2024-10-31 10-54-08 [debug] /opt/kamailio/kamailio.lua:169: Reply received on_sl_reply 7(45265) INFO: <script>: 2024-10-31 10-54-08 [debug] /opt/kamailio/kamailio.lua:169: Reply received on_sl_reply 7(45265) DEBUG: app_lua [app_lua_mod.c:166]: sr_kemi_config_engine_lua(): execution of route type 513 with name [ksr_slreply] returned 1 7(45265) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Server] type 29 7(45265) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Content-Length] type 12 7(45265) DEBUG: <core> [core/parser/msg_parser.c:187]: get_hdr_field(): content_length=0 7(45265) DEBUG: <core> [core/parser/msg_parser.c:91]: get_hdr_field(): found end of header 7(45265) DEBUG: <core> [core/forward.c:901]: do_forward_reply(): reply cannot be forwarded - no 2nd via 7(45265) DEBUG: <core> [core/receive.c:624]: receive_msg(): reply-route executed in: 8419 usec 7(45265) DEBUG: <core> [core/usr_avp.c:654]: destroy_avp_list(): destroying list (nil) 7(45265) DEBUG: <core> [core/usr_avp.c:654]: destroy_avp_list(): destroying list (nil) 7(45265) DEBUG: <core> [core/usr_avp.c:654]: destroy_avp_list(): destroying list (nil) 7(45265) DEBUG: <core> [core/usr_avp.c:654]: destroy_avp_list(): destroying list (nil) 7(45265) DEBUG: <core> [core/usr_avp.c:654]: destroy_avp_list(): destroying list (nil) 7(45265) DEBUG: <core> [core/usr_avp.c:654]: destroy_avp_list(): destroying list (nil) 7(45265) DEBUG: <core> [core/xavp.c:630]: xavp_destroy_list(): destroying xavp list (nil) 7(45265) DEBUG: <core> [core/xavp.c:630]: xavp_destroy_list(): destroying xavp list (nil) 7(45265) DEBUG: <core> [core/xavp.c:630]: xavp_destroy_list(): destroying xavp list (nil) 7(45265) DEBUG: <core> [core/receive.c:635]: receive_msg(): cleaning up 8(45266) DEBUG: <core> [core/udp_server.c:715]: udp_rcv_loop(): received on udp socket: (106/100/345) [[SIP/2.0 487 Request Terminated 0D 0A Via: SIP/2.0/UDP 10.192.117.6:5084;branch=z9hG4bKafaa.fe4571d3000000]] 8(45266) DEBUG: <core> [core/parser/parse_fline.c:247]: parse_first_line(): first line type 2 (reply(status)) flags 1 8(45266) DEBUG: <core> [core/parser/msg_parser.c:732]: parse_msg(): SIP Reply (status): 8(45266) DEBUG: <core> [core/parser/msg_parser.c:733]: parse_msg(): version: <SIP/2.0> 8(45266) DEBUG: <core> [core/parser/msg_parser.c:735]: parse_msg(): status: <487> 8(45266) DEBUG: <core> [core/parser/msg_parser.c:737]: parse_msg(): reason: <Request Terminated> 8(45266) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Via] type 1 8(45266) DEBUG: <core> [core/parser/parse_via.c:1309]: parse_via_param(): Found param type 232, <branch> = <z9hG4bKafaa.fe4571d3000000000000000000000000.0>; state=16 8(45266) DEBUG: <core> [core/parser/parse_via.c:2663]: parse_via(): end of header reached, state=5 8(45266) DEBUG: <core> [core/parser/msg_parser.c:592]: parse_headers(): Via found, flags=2 8(45266) DEBUG: <core> [core/parser/msg_parser.c:594]: parse_headers(): this is the first via 8(45266) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [To] type 3 8(45266) DEBUG: <core> [core/parser/parse_addr_spec.c:185]: parse_to_param(): add param: tag=501b95048bf50805 8(45266) DEBUG: <core> [core/parser/parse_addr_spec.c:904]: parse_addr_spec(): end of header reached, state=29 8(45266) DEBUG: <core> [core/parser/msg_parser.c:172]: get_hdr_field(): <To> [45]; uri=[sip:2345@sip.osp.com] 8(45266) DEBUG: <core> [core/parser/msg_parser.c:174]: get_hdr_field(): to body (22)[<sip:2345@sip.osp.com>], to tag (16)[501b95048bf50805] 8(45266) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [From] type 4 8(45266) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [CSeq] type 5 8(45266) DEBUG: <core> [core/parser/msg_parser.c:152]: get_hdr_field(): cseq <CSeq>: <1> <INVITE> 8(45266) DEBUG: <core> [core/parser/parse_hname2.c:315]: parse_sip_header_name(): parsed header name [Call-ID] type 6 8(45266) DEBUG: <core> [core/receive.c:392]: receive_msg(): --- received sip message - reply - call-id: [68443192-0f66b617ce5917df] - cseq: [1 INVITE] 8(45266) DEBUG: <core> [core/receive.c:263]: ksr_evrt_pre_routing(): event route core:pre-routing not defined 8(45266) DEBUG: app_lua [app_lua_api.c:511]: sr_lua_reload_script(): No need to reload [/opt/kamailio/kamailio.lua] is version 0 8(45266) DEBUG: app_lua [app_lua_api.c:688]: app_lua_run_ex(): executing Lua function: [[ksr_reply_route]] 8(45266) DEBUG: app_lua [app_lua_api.c:690]: app_lua_run_ex(): lua top index is: 17 8(45266) DEBUG: app_lua [app_lua_api.c:1023]: sr_kemi_lua_exec_func_ex(): param[0] for: xinfo is str: 2024-10-31 10-54-10 [debug] /opt/kamailio/kamailio.lua:67: ++ Reply received $rs $rr -- Reply to this email directly or view it on GitHub: https://github.com/kamailio/kamailio/issues/4011 You are receiving this because you are subscribed to this thread. Message ID: <kamailio/kamailio/issues/4011(a)github.com>

1 month, 1 week

git:5.8:d33e4bc5: pkg/kamailio: packaged jwt module [skip ci]

by Sergey Safarov

Module: kamailio Branch: 5.8 Commit: d33e4bc5fc8b8f482b740bb4fc63ff4c3d7e29ef URL: https://github.com/kamailio/kamailio/commit/d33e4bc5fc8b8f482b740bb4fc63ff4… Author: Sergey Safarov <s.safarov(a)anycast-lb.net> Committer: Sergey Safarov <s.safarov(a)anycast-lb.net> Date: 2024-11-15T14:30:56+02:00 pkg/kamailio: packaged jwt module [skip ci] (cherry picked from commit 2d745d21764485c725e2beea91b67cbda83c96f3) --- Modified: pkg/kamailio/obs/kamailio.spec --- Diff: https://github.com/kamailio/kamailio/commit/d33e4bc5fc8b8f482b740bb4fc63ff4… Patch: https://github.com/kamailio/kamailio/commit/d33e4bc5fc8b8f482b740bb4fc63ff4… --- diff --git a/pkg/kamailio/obs/kamailio.spec b/pkg/kamailio/obs/kamailio.spec index 8f1863fc822..423f7f9f3de 100644 --- a/pkg/kamailio/obs/kamailio.spec +++ b/pkg/kamailio/obs/kamailio.spec @@ -237,6 +237,7 @@ Conflicts: kamailio-dialplan < %ver, kamailio-dnssec < %ver Conflicts: kamailio-geoip < %ver, kamailio-gzcompress < %ver Conflicts: kamailio-http_client < %ver Conflicts: kamailio-ims < %ver, kamailio-java < %ver, kamailio-json < %ver +Conflicts: kamailio-jwt < %ver Conflicts: kamailio-kazoo < %ver Conflicts: kamailio-lcr < %ver, kamailio-ldap < %ver, kamailio-lost < %ver, kamailio-lua < %ver Conflicts: kamailio-nats < %ver @@ -590,6 +591,17 @@ JSON string handling and RPC modules for Kamailio. %endif +%package jwt +Summary: JWT (JSON Web Token) functions module for Kamailio +Group: %{PKGGROUP} +Requires: libjwt, kamailio = %ver +BuildRequires: libjwt-devel + +%description jwt +This module provides JWT (JSON Web Token) functions to be used in Kamailio configuration file. +It relies on libjwt (at least v1.12.0) library (https://github.com/benmcollins/libjwt). + + %if %{with kazoo} %package kazoo Summary: Kazoo middle layer connector support for Kamailio @@ -1245,6 +1257,7 @@ make every-module skip_modules="app_mono db_cassandra db_oracle iptrtpproxy \ kjson \ %endif kjsonrpcs \ + kjwt \ %if %{with kazoo} kkazoo \ %endif @@ -1358,6 +1371,7 @@ make install-modules-all skip_modules="app_mono db_cassandra db_oracle \ kjson \ %endif kjsonrpcs \ + kjwt \ %if %{with kazoo} kkazoo \ %endif @@ -2020,6 +2034,12 @@ fi %endif +%files jwt +%defattr(-,root,root) +%doc %{_docdir}/kamailio/modules/README.jwt +%{_libdir}/kamailio/modules/jwt.so + + %if %{with kazoo} %files kazoo %defattr(-,root,root)

1 month, 1 week

[kamailio/kamailio] xhttp_prom module with custom tags (Issue #4021)

by Christian Berger

### Description In Prometheus there is the possibility to tag values with tags. Those tags can then be used provide context to those values, for example to filter them. ### Expected behavior It would be great if there was a way to provide custom tags to the metrics. These would essentially just be a static string between the name of the metric and its value. Example: `system_load15{host="wurst23.dev.sipgate.net",hostname_short="wurst32"} 0.4` This could probably be achieved by having an `xhttp_prom_ending` parameter similar to the `xhttp_prom_beginning` as we essentially just want to append a static string right after the metric name. #### Actual observed behavior `kamailio_sip_lb_de_core_rcv_replies_1xx_invite 1773 1731419851663` -- Reply to this email directly or view it on GitHub: https://github.com/kamailio/kamailio/issues/4021 You are receiving this because you are subscribed to this thread. Message ID: <kamailio/kamailio/issues/4021(a)github.com>

1 month, 1 week

git:5.7:4ddd8de2: doc: update manpage with missing -B option [skip ci]

by Victor Seva

Module: kamailio Branch: 5.7 Commit: 4ddd8de2d6c6a02a5c156d0505feb9164a7d5a3e URL: https://github.com/kamailio/kamailio/commit/4ddd8de2d6c6a02a5c156d0505feb91… Author: Victor Seva <linuxmaniac(a)torreviejawireless.org> Committer: Victor Seva <linuxmaniac(a)torreviejawireless.org> Date: 2024-11-15T11:19:59+01:00 doc: update manpage with missing -B option [skip ci] (cherry picked from commit 77f03aabd871dbf86c5106a93eb32ce67d7762bf) (cherry picked from commit 6816f992696fe894b3a47cd511a59e1b5a0ed89b) --- Modified: doc/man/kamailio.8 --- Diff: https://github.com/kamailio/kamailio/commit/4ddd8de2d6c6a02a5c156d0505feb91… Patch: https://github.com/kamailio/kamailio/commit/4ddd8de2d6c6a02a5c156d0505feb91… --- diff --git a/doc/man/kamailio.8 b/doc/man/kamailio.8 index f4fc69ade7e..05460afbddc 100644 --- a/doc/man/kamailio.8 +++ b/doc/man/kamailio.8 @@ -8,7 +8,7 @@ kamailio \- a very fast and configurable SIP server .SH SYNOPSIS .B kamailio [ -.B \-hfcmMdVIhEeblLnvKrRDTNWwtugPGSQOaAxXY +.B \-hfcmMdVIhEebBlLnvKrRDTNWwtugPGSQOaAxXY ] [ .BI \-a " auto\-aliases\-mode" ] [ @@ -16,6 +16,8 @@ kamailio \- a very fast and configurable SIP server ] [ .BI \-b " max_rcv_buf_size" ] [ +.BI \-B " max_snd_buf_size" +] [ .BI \-f " config\-file" ] [ .BI \-g " gid" @@ -68,7 +70,10 @@ default is yes. Add config pre-processor define (e.g., -A WITH_AUTH, -A N=1, -A X='"Y"') .TP .BI \-b " max_rcv_buf_size" -Maximum receive buffer size which will not be exceeded by the auto-probing procedure even if the OS allows. +Maximum OS UDP receive buffer size which will not be exceeded by auto-probing-and-increase procedure even if OS allows. +.TP +.BI \-B " max_snd_buf_size" +Maximum OS UDP send buffer size which will not be exceeded by auto-probing-and-increase procedure even if OS allows. .TP .BI \-c Checks the config file and displays the aliases and listen interface list.

1 month, 1 week

git:5.8:6816f992: doc: update manpage with missing -B option [skip ci]

by Victor Seva

Module: kamailio Branch: 5.8 Commit: 6816f992696fe894b3a47cd511a59e1b5a0ed89b URL: https://github.com/kamailio/kamailio/commit/6816f992696fe894b3a47cd511a59e1… Author: Victor Seva <linuxmaniac(a)torreviejawireless.org> Committer: Victor Seva <linuxmaniac(a)torreviejawireless.org> Date: 2024-11-15T11:18:46+01:00 doc: update manpage with missing -B option [skip ci] (cherry picked from commit 77f03aabd871dbf86c5106a93eb32ce67d7762bf) --- Modified: doc/man/kamailio.8 --- Diff: https://github.com/kamailio/kamailio/commit/6816f992696fe894b3a47cd511a59e1… Patch: https://github.com/kamailio/kamailio/commit/6816f992696fe894b3a47cd511a59e1… --- diff --git a/doc/man/kamailio.8 b/doc/man/kamailio.8 index f4fc69ade7e..05460afbddc 100644 --- a/doc/man/kamailio.8 +++ b/doc/man/kamailio.8 @@ -8,7 +8,7 @@ kamailio \- a very fast and configurable SIP server .SH SYNOPSIS .B kamailio [ -.B \-hfcmMdVIhEeblLnvKrRDTNWwtugPGSQOaAxXY +.B \-hfcmMdVIhEebBlLnvKrRDTNWwtugPGSQOaAxXY ] [ .BI \-a " auto\-aliases\-mode" ] [ @@ -16,6 +16,8 @@ kamailio \- a very fast and configurable SIP server ] [ .BI \-b " max_rcv_buf_size" ] [ +.BI \-B " max_snd_buf_size" +] [ .BI \-f " config\-file" ] [ .BI \-g " gid" @@ -68,7 +70,10 @@ default is yes. Add config pre-processor define (e.g., -A WITH_AUTH, -A N=1, -A X='"Y"') .TP .BI \-b " max_rcv_buf_size" -Maximum receive buffer size which will not be exceeded by the auto-probing procedure even if the OS allows. +Maximum OS UDP receive buffer size which will not be exceeded by auto-probing-and-increase procedure even if OS allows. +.TP +.BI \-B " max_snd_buf_size" +Maximum OS UDP send buffer size which will not be exceeded by auto-probing-and-increase procedure even if OS allows. .TP .BI \-c Checks the config file and displays the aliases and listen interface list.

1 month, 1 week

git:master:77f03aab: doc: update manpage with missing -B option [skip ci]

by Victor Seva

Module: kamailio Branch: master Commit: 77f03aabd871dbf86c5106a93eb32ce67d7762bf URL: https://github.com/kamailio/kamailio/commit/77f03aabd871dbf86c5106a93eb32ce… Author: Victor Seva <linuxmaniac(a)torreviejawireless.org> Committer: Victor Seva <linuxmaniac(a)torreviejawireless.org> Date: 2024-11-15T11:10:59+01:00 doc: update manpage with missing -B option [skip ci] * update -l description --- Modified: doc/man/kamailio.8 --- Diff: https://github.com/kamailio/kamailio/commit/77f03aabd871dbf86c5106a93eb32ce… Patch: https://github.com/kamailio/kamailio/commit/77f03aabd871dbf86c5106a93eb32ce… --- diff --git a/doc/man/kamailio.8 b/doc/man/kamailio.8 index f4fc69ade7e..9579c806020 100644 --- a/doc/man/kamailio.8 +++ b/doc/man/kamailio.8 @@ -8,7 +8,7 @@ kamailio \- a very fast and configurable SIP server .SH SYNOPSIS .B kamailio [ -.B \-hfcmMdVIhEeblLnvKrRDTNWwtugPGSQOaAxXY +.B \-hfcmMdVIhEebBlLnvKrRDTNWwtugPGSQOaAxXY ] [ .BI \-a " auto\-aliases\-mode" ] [ @@ -16,6 +16,8 @@ kamailio \- a very fast and configurable SIP server ] [ .BI \-b " max_rcv_buf_size" ] [ +.BI \-B " max_snd_buf_size" +] [ .BI \-f " config\-file" ] [ .BI \-g " gid" @@ -68,7 +70,10 @@ default is yes. Add config pre-processor define (e.g., -A WITH_AUTH, -A N=1, -A X='"Y"') .TP .BI \-b " max_rcv_buf_size" -Maximum receive buffer size which will not be exceeded by the auto-probing procedure even if the OS allows. +Maximum OS UDP receive buffer size which will not be exceeded by auto-probing-and-increase procedure even if OS allows. +.TP +.BI \-B " max_snd_buf_size" +Maximum OS UDP send buffer size which will not be exceeded by auto-probing-and-increase procedure even if OS allows. .TP .BI \-c Checks the config file and displays the aliases and listen interface list. @@ -132,10 +137,18 @@ Turns on via host checking when forwarding replies. Listens on the specified address/interface. Multiple .B \-l mean listening on multiple addresses. The address format is -[proto:]address[:port], where proto = udp|tcp and -address = host|ip_address|interface_name. Example: -l localhost, --l udp:127.0.0.1:5080, -l eth0:5062. -The default behaviour is to listen on all the ipv4 interfaces. +[proto:]addr_lst[:port][/advaddr][/socket_name], +where proto=udp|tcp|tls|sctp, +addr_list = addr|(addr, addrress), +addr=host|ip_address|interface_name, +advaddr=addr[:port] (advertised address) and +socket_name=identifying name. +E.g: -l localhost, -l udp:127.0.0.1:5080, -l eth0:5062, +-l udp:127.0.0.1:5080/1.2.3.4:5060, +-l udp:127.0.0.1:5080//local, +-l udp:127.0.0.1:5080/1.2.3.4:5060/local, +-l \"sctp:(eth0)\", -l \"(eth0, eth1, 127.0.0.1):5065\". +The default behaviour is to listen on all the interfaces. .TP .BI \-\-loadmodule\fR=\fIname load the module specified by name

1 month, 1 week

git:master:db267263: tm: backup/restor current T/branch for rpc reply commands

by Daniel-Constantin Mierla

Module: kamailio Branch: master Commit: db2672636a9f029f624059eb6ef69db86a559ff1 URL: https://github.com/kamailio/kamailio/commit/db2672636a9f029f624059eb6ef69db… Author: Daniel-Constantin Mierla <miconda(a)gmail.com> Committer: Daniel-Constantin Mierla <miconda(a)gmail.com> Date: 2024-11-15T09:44:54+01:00 tm: backup/restor current T/branch for rpc reply commands --- Modified: src/modules/tm/t_reply.c --- Diff: https://github.com/kamailio/kamailio/commit/db2672636a9f029f624059eb6ef69db… Patch: https://github.com/kamailio/kamailio/commit/db2672636a9f029f624059eb6ef69db… --- diff --git a/src/modules/tm/t_reply.c b/src/modules/tm/t_reply.c index a87a9281bf7..a7221235844 100644 --- a/src/modules/tm/t_reply.c +++ b/src/modules/tm/t_reply.c @@ -2990,6 +2990,8 @@ void rpc_reply(rpc_t *rpc, void *c) { int ret; struct cell *trans; + tm_cell_t *orig_t = NULL; + int orig_branch; unsigned int hash_index, label, code; str ti, body, headers, tag, reason; @@ -3025,6 +3027,7 @@ void rpc_reply(rpc_t *rpc, void *c) } LM_DBG("hash_index=%u label=%u\n", hash_index, label); + tm_get_tb(&orig_t, &orig_branch); if(t_lookup_ident(&trans, hash_index, label) < 0) { ERR("Lookup failed\n"); rpc->fault(c, 481, "No such transaction"); @@ -3034,6 +3037,7 @@ void rpc_reply(rpc_t *rpc, void *c) /* it's refcounted now, t_reply_with body unrefs for me -- I can * continue but may not use T anymore */ ret = t_reply_with_body(trans, code, &reason, &body, &headers, &tag); + tm_set_tb(orig_t, orig_branch); if(ret < 0) { LM_ERR("Reply failed\n"); @@ -3056,6 +3060,8 @@ void rpc_reply_callid(rpc_t *rpc, void *c) { int code; tm_cell_t *trans; + tm_cell_t *orig_t = NULL; + int orig_branch; str reason = {0, 0}; str totag = {0, 0}; str hdrs = {0, 0}; @@ -3098,6 +3104,7 @@ void rpc_reply_callid(rpc_t *rpc, void *c) return; } + tm_get_tb(&orig_t, &orig_branch); if(t_lookup_callid(&trans, callid, cseq) < 0) { rpc->fault(c, 404, "Transaction not found"); return; @@ -3106,6 +3113,7 @@ void rpc_reply_callid(rpc_t *rpc, void *c) /* it's refcounted now, t_reply_with body unrefs for me -- I can * continue but may not use T anymore */ n = t_reply_with_body(trans, code, &reason, &body, &hdrs, &totag); + tm_set_tb(orig_t, orig_branch); if(n < 0) { rpc->fault(c, 500, "Reply failed");

1 month, 1 week

git:master:ec30f583: tm: rpc_cancel() switch to get/set T/branch helpers

by Daniel-Constantin Mierla

Module: kamailio Branch: master Commit: ec30f5831ed3b6684715103b0242ed220e0159be URL: https://github.com/kamailio/kamailio/commit/ec30f5831ed3b6684715103b0242ed2… Author: Daniel-Constantin Mierla <miconda(a)gmail.com> Committer: Daniel-Constantin Mierla <miconda(a)gmail.com> Date: 2024-11-15T09:36:32+01:00 tm: rpc_cancel() switch to get/set T/branch helpers --- Modified: src/modules/tm/t_cancel.c --- Diff: https://github.com/kamailio/kamailio/commit/ec30f5831ed3b6684715103b0242ed2… Patch: https://github.com/kamailio/kamailio/commit/ec30f5831ed3b6684715103b0242ed2… --- diff --git a/src/modules/tm/t_cancel.c b/src/modules/tm/t_cancel.c index 832fdf89dc1..0ec5aab200a 100644 --- a/src/modules/tm/t_cancel.c +++ b/src/modules/tm/t_cancel.c @@ -417,8 +417,7 @@ void rpc_cancel(rpc_t *rpc, void *c) return; } - orig_t = get_t(); - orig_branch = get_t_branch(); + tm_get_tb(&orig_t, &orig_branch); if(t_lookup_callid(&trans, callid_s, cseq_s) < 0) { LM_DBG("Lookup failed\n"); rpc->fault(c, 400, "Transaction not found"); @@ -433,7 +432,7 @@ void rpc_cancel(rpc_t *rpc, void *c) /* t_lookup_callid REF`d the transaction for us, we must UNREF here! */ UNREF(trans); - set_t(orig_t, orig_branch); + tm_set_tb(orig_t, orig_branch); j = 0; while(i) { j++;

1 month, 1 week

git:master:027ca1b2: tm: small helper functions to get/set current T and branch

by Daniel-Constantin Mierla

Module: kamailio Branch: master Commit: 027ca1b2e1d0857ac039ff8fee9fecadd88bbda0 URL: https://github.com/kamailio/kamailio/commit/027ca1b2e1d0857ac039ff8fee9feca… Author: Daniel-Constantin Mierla <miconda(a)gmail.com> Committer: Daniel-Constantin Mierla <miconda(a)gmail.com> Date: 2024-11-15T09:33:52+01:00 tm: small helper functions to get/set current T and branch - pair for coherent naming --- Modified: src/modules/tm/t_lookup.c Modified: src/modules/tm/t_lookup.h --- Diff: https://github.com/kamailio/kamailio/commit/027ca1b2e1d0857ac039ff8fee9feca… Patch: https://github.com/kamailio/kamailio/commit/027ca1b2e1d0857ac039ff8fee9feca… --- diff --git a/src/modules/tm/t_lookup.c b/src/modules/tm/t_lookup.c index 48e1634cce8..f0cebc014fe 100644 --- a/src/modules/tm/t_lookup.c +++ b/src/modules/tm/t_lookup.c @@ -150,6 +150,18 @@ int get_t_branch() return T_branch; } +void tm_get_tb(struct cell **t, int *branch) +{ + *t = T; + *branch = T_branch; +} + +void tm_set_tb(struct cell *t, int branch) +{ + T = t; + T_branch = branch; +} + /** * return the transaction by combining get() and t_check_msg() * - if T is not set, checks the transactions table for msg, and if found, diff --git a/src/modules/tm/t_lookup.h b/src/modules/tm/t_lookup.h index f3f69af4ac5..e9c019c7b46 100644 --- a/src/modules/tm/t_lookup.h +++ b/src/modules/tm/t_lookup.h @@ -79,6 +79,8 @@ int get_t_branch(void); typedef void (*tsett_f)(struct cell *t, int branch); void set_t(struct cell *t, int branch); +void tm_get_tb(struct cell **t, int *branch); +void tm_set_tb(struct cell *t, int branch); #define T_GET_TI "t_get_trans_ident" #define T_LOOKUP_IDENT "t_lookup_ident"

1 month, 1 week

← Newer
1
...
27
28
29
30
31
32
33
...
37
Older →

Jump to page:

2024

2023

2022

2021

2020

2019

2018

2017

2016

2015

2014

2013

2012

2011

2010

2009

2008

sr-dev November 2024