[SR-Users] segfault when using sipt modifications

Sergey Basov sergey.v.basov at gmail.com
Fri Aug 5 15:49:31 CEST 2016


And I think you are wrong about last \x00

Look at attached screenshot, I think you does not have field that is
highlighted "End of optional parameter" this must be last \x00 in
string to get correct part with ISUP.
--
Best regards,
Sergey Basov                     e-mail: sergey.v.basov at gmail.com

tel: (+38067) 403-62-54


2016-08-05 16:44 GMT+03:00 Sergey Basov <sergey.v.basov at gmail.com>:
> And I think you are wrong about last \x00
>
> Look at attached screenshot, I think you does not have field that is
> highlighted "End of optional parameter" this must be last \x00 in
> string to get correct part with ISUP.
> --
> Best regards,
> Sergey Basov                     e-mail: sergey.v.basov at gmail.com
>
> tel: (+38067) 403-62-54
>
>
> 2016-08-05 16:32 GMT+03:00 Sergey Basov <sergey.v.basov at gmail.com>:
>> sorry
>>
>> correct function is isup_update_destination
>> --
>> Best regards,
>> Sergey Basov                     e-mail: sergey.v.basov at gmail.com
>>
>> tel: (+38067) 403-62-54
>>
>>
>> 2016-08-05 16:29 GMT+03:00 Sergey Basov <sergey.v.basov at gmail.com>:
>>> You are welcome.
>>>
>>>
>>> If you shure about last \x00 then you can try to edit modules/sipt/ss7_parser.c
>>> in function isup_update_bci_1
>>> after strings
>>> // pointer to fixed part (2)
>>> offset++;
>>>
>>> add next
>>> // fixing Media requirements to speech 0x00 or 0x01 to 64k
>>> tmp_buf[0] = 0x00;
>>> tmp_buf[1] = 0x0A;
>>> tmp_buf[2] = 0x00;
>>> replace_body_segment(mangle, offset-4, 3, tmp_buf, 3);
>>>
>>> and recompile sipt module. This will change your "Forward Call
>>> Indicators: 0x4800" (last 00) and "Transmission medium requirement: 0
>>> (speech)"
>>>
>>> --
>>> Best regards,
>>> Sergey Basov                     e-mail: sergey.v.basov at gmail.com
>>>
>>> tel: (+38067) 403-62-54
>>>
>>>
>>> 2016-08-05 15:59 GMT+03:00 david <descartin at bts.io>:
>>>> yes, that's x00 it's actually the only one well interpreted by kamailio :)
>>>>
>>>> thanks for the help in this issue Sergey
>>>>
>>>>
>>>>
>>>> El vie, 05-08-2016 a las 15:01 +0300, Sergey Basov escribió:
>>>>
>>>> Hello.
>>>>
>>>> Most important, i think, is the las \x00 which is the flag end of ISUP
>>>> message...
>>>> Without it you cant generate correct ISUP part..
>>>>
>>>> Look in wireshark. It will inform you about any error in ISUP.
>>>>
>>>> --
>>>> Best regards,
>>>> Sergey Basov                     e-mail: sergey.v.basov at gmail.com
>>>>
>>>> tel: (+38067) 403-62-54
>>>>
>>>>
>>>> 2016-08-05 11:07 GMT+03:00 david <descartin at bts.io>:
>>>>> Hello
>>>>>
>>>>> that would be great, despite not needing it now, it would be great, there
>>>>> are 3 x00 in the isup i wanted to set, but 2 are from FCI and optional
>>>>> FCI,
>>>>> and the third is for the called party, which can be changed with sipt
>>>>> functions.
>>>>> anycase, the FCI could be an issue in some scenarios, so an option to let
>>>>> the kamailio get the x00 would be great
>>>>>
>>>>> besides, i have another issue with textops, setting body multipart
>>>>>
>>>>> Aug  3 11:11:03  /usr/local/kamailio/sbin/kamailio[4190]: INFO: <core>
>>>>> [msg_translator.c:1692]: get_boundary(): Content-Type hdr has no params
>>>>> <application/sdp>
>>>>> Aug  3 11:11:03 /usr/local/kamailio/sbin/kamailio[4190]: WARNING: <core>
>>>>> [msg_translator.c:1958]: build_req_buf_from_sip_req(): check_boundaries
>>>>> error
>>>>>
>>>>> but i think there is already another query with this so i will check there
>>>>>
>>>>> best regards
>>>>> david
>>>>>
>>>>>
>>>>>
>>>>>
>>>>> El mar, 02-08-2016 a las 09:22 +0200, Daniel-Constantin Mierla escribió:
>>>>>
>>>>> Hello,
>>>>>
>>>>> maybe the function append_body_part("...") can be extended with an extra
>>>>> parameter where to specify the character to be replaced with 0x00 in the
>>>>> first parameter.
>>>>>
>>>>> Cheers,
>>>>> Daniel
>>>>>
>>>>>
>>>>>
>>>>> On 01/08/16 16:34, Sergey Basov wrote:
>>>>>
>>>>> Hello.
>>>>>
>>>>> Look more carefuly, its only first \x00 changes forward call indicator.
>>>>> You
>>>>> have a few more \x00...
>>>>>
>>>>> You does not get full ISUP into INVITE...
>>>>>
>>>>> As I have told earlier you can replace \x00 by \x01into kamailio script,
>>>>> but
>>>>> you must rewrite it with \x00 later into sip-t module, but this requires
>>>>> some changes into source code of sip-t module...
>>>>>
>>>>> sip-t module itself designed for checking/modifying of the ISUP content
>>>>> into
>>>>> sip-t/sip-i, but not for generating it...
>>>>>
>>>>>
>>>>> 1 авг. 2016 г. 5:08 PM пользователь "david" <descartin at bts.io> написал:
>>>>>
>>>>> Hello Sergey, Daniel
>>>>>
>>>>> thanks a lot for the guidance.
>>>>> i changed the x00 by x01 and it works now, i was seeing the ISUP truncated
>>>>> but i thought it was related to the tshark XD
>>>>>
>>>>> i will find out about the changes needed at sipt configuration, but just
>>>>> in
>>>>> case, the things chaged by this only are the forward call indicators, so i
>>>>> hope that does give us many problems....
>>>>>
>>>>> i will let you know anything
>>>>>
>>>>> thanks and best regards
>>>>> david
>>>>>
>>>>>
>>>>> El lun, 01-08-2016 a las 12:09 +0200, Daniel-Constantin Mierla escribió:
>>>>>
>>>>> Hello,
>>>>>
>>>>> I am not familiar with isup and no testbed around at this moment. That's
>>>>> why I asked for backtrace from the coredump file, based on the
>>>>> guidelines I did in my previous email.
>>>>>
>>>>> Cheers,
>>>>> Daniel
>>>>>
>>>>>
>>>>> On 01/08/16 09:07, Sergey Basov wrote:
>>>>>> Hello,
>>>>>>
>>>>>> try make dump packet after
>>>>>>
>>>>>>
>>>>>> append_body_part("\x01\x12\x49\x00\x0a\x03\x02\x0a\x08\x84\x90\x33\x41\x72\x17\x00\x06\x0a\x08\x04\x13\x93\x70\x21\x73\x23\x10\x08\x01\x00\x00","application/isup;version=itu-t92+","signal;handling=optional");
>>>>>> before sipt_destination($rU, 31, 4);
>>>>>>
>>>>>> when i have done such conversion i found that kamailio from script
>>>>>> ignores all string content after symbol \x00 as this is end of line.
>>>>>>
>>>>>> To avoid this i have replaces \x00 with \x01 in my initial ISUP string
>>>>>> and make changes into SIP-T module to replace it with \x00 while
>>>>>> processing message. before number/presentation/screening
>>>>>> modifications.
>>>>>>
>>>>>> you have first \x00 as forth symbol in your ISUP string and after it
>>>>>> all have to be ignored. Look at dump using wireshark. I think you will
>>>>>> see mailformed ISUP incapsulated message.
>>>>>>
>>>>>> --
>>>>>> Best regards,
>>>>>> Sergey Basov                     e-mail: sergey.v.basov at gmail.com
>>>>>>
>>>>>> tel: (+38067) 403-62-54
>>>>>>
>>>>>>
>>>>>> 2016-08-01 8:21 GMT+03:00 Daniel-Constantin Mierla <miconda at gmail.com>:
>>>>>>> Hello,
>>>>>>>
>>>>>>> would be good to get a core dump file and the backtrace from it in order
>>>>>>> to
>>>>>>> see where it crashes.
>>>>>>>
>>>>>>> For getting the coredump, try:
>>>>>>>
>>>>>>>  - stop kamailio
>>>>>>>
>>>>>>>  - do: ulimit -c unlimited
>>>>>>>
>>>>>>>  - start kamailio as root
>>>>>>>
>>>>>>>  - reproduce the issue
>>>>>>>
>>>>>>> A corefile should be generated in / or working directory. Use gdb to
>>>>>>> grab
>>>>>>> the output of 'bt full' from the corefile and send it here to the
>>>>>>> mailing
>>>>>>> list.
>>>>>>>
>>>>>>> Cheers,
>>>>>>> Daniel
>>>>>>>
>>>>>>>
>>>>>>> On 29/07/16 18:21, david wrote:
>>>>>>>
>>>>>>> hello all
>>>>>>>
>>>>>>>
>>>>>>>
>>>>>>> i'm trying to add a ISUP multipart in a regular call using the textops
>>>>>>> module, and despite seeing one previous issue int he mail list, i'm
>>>>>>> finding
>>>>>>> some issue here
>>>>>>>
>>>>>>> i'm using this commands
>>>>>>>
>>>>>>>     msg_apply_changes();
>>>>>>>     set_body_multipart(,"2123894789_1257887457");
>>>>>>>     msg_apply_changes();
>>>>>>>
>>>>>>>
>>>>>>>
>>>>>>> append_body_part("\x01\x12\x49\x00\x0a\x03\x02\x0a\x08\x84\x90\x33\x41\x72\x17\x00\x06\x0a\x08\x04\x13\x93\x70\x21\x73\x23\x10\x08\x01\x00\x00","application/isup;version=itu-t92+","signal;handling=optional");
>>>>>>>     msg_apply_changes();
>>>>>>>     sipt_destination($rU, 31, 4);
>>>>>>>     sipt_set_calling($fU, 4, 0, 3);
>>>>>>>     msg_apply_changes();
>>>>>>>
>>>>>>> before the record_route command, and when using sipt_destination($rU,
>>>>>>> 31,
>>>>>>> 4); i'm getting asegfault
>>>>>>>
>>>>>>> here you have the logs
>>>>>>> could you please give me any clue about something i'm missing or doing
>>>>>>> wrong?
>>>>>>>
>>>>>>> thanks  alot and regards
>>>>>>> david
>>>>>>>
>>>>>>> 5(24552) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): SIP
>>>>>>> Request:
>>>>>>> 5(24552) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg():  method:
>>>>>>> <INVITE>
>>>>>>> 5(24552) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg():  uri:
>>>>>>> <sip:34666988297 at 79.170.71.132:5060>
>>>>>>> 5(24552) DEBUG: <core> [parser/msg_parser.c:614]: parse_msg():  version:
>>>>>>> <SIP/2.0>
>>>>>>> 5(24552) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param():
>>>>>>> Found
>>>>>>> param type 235, <rport> = <n/a>; state=6
>>>>>>> 5(24552) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param():
>>>>>>> Found
>>>>>>> param type 232, <branch> = <z9hG4bK7ma5t4FKS0SKa>; state=16
>>>>>>> 5(24552) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
>>>>>>> header
>>>>>>> reached, state=5
>>>>>>> 5(24552) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
>>>>>>> parse_headers: Via found, flags=2
>>>>>>> 5(24552) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
>>>>>>> parse_headers: this is the first via
>>>>>>> 5(24552) DEBUG: <core> [receive.c:178]: receive_msg(): After
>>>>>>> parse_msg...
>>>>>>> 5(24552) DEBUG: <core> [receive.c:221]: receive_msg(): preparing to run
>>>>>>> routing scripts...
>>>>>>> 5(24552) DEBUG: maxfwd [mf_funcs.c:85]: is_maxfwd_present(): value = 65
>>>>>>> 5(24552) DEBUG: <core> [parser/parse_addr_spec.c:894]:
>>>>>>> parse_addr_spec():
>>>>>>> end of header reached, state=10
>>>>>>> 5(24552) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field():
>>>>>>> DEBUG:
>>>>>>> get_hdr_field: <To> [49];
>>>>>>> uri=[sip:34666988297 at 79.170.71.132:5060;user=phone]
>>>>>>> 5(24552) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field():
>>>>>>> DEBUG:
>>>>>>> to
>>>>>>> body [<sip:34666988297 at 79.170.71.132:5060;user=phone>
>>>>>>> ]
>>>>>>> 5(24552) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
>>>>>>> get_hdr_field: cseq <CSeq>: <94568362> <INVITE>
>>>>>>> 5(24552) DEBUG: <core> [parser/msg_parser.c:187]: get_hdr_field():
>>>>>>> DEBUG:
>>>>>>> get_hdr_body : content_length=104
>>>>>>> 5(24552) DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found
>>>>>>> end
>>>>>>> of header
>>>>>>> 5(24552) DEBUG: <core> [parser/parse_addr_spec.c:172]: parse_to_param():
>>>>>>> DEBUG: add_param: tag=5jBBFae4D90rg
>>>>>>> 5(24552) DEBUG: <core> [parser/parse_addr_spec.c:894]:
>>>>>>> parse_addr_spec():
>>>>>>> end of header reached, state=29
>>>>>>> 5(24552) DEBUG: sanity [mod_sanity.c:248]: w_sanity_check(): sanity
>>>>>>> checks
>>>>>>> result: 1
>>>>>>> 5(24552) DEBUG: siputils [checks.c:97]: has_totag(): no totag
>>>>>>> 5(24552) DEBUG: <core> [socket_info.c:564]: grep_sock_info(): checking
>>>>>>> if
>>>>>>> host==us: 13==13 && [79.170.71.132] == [79.170.71.132]
>>>>>>> 5(24552) DEBUG: <core> [socket_info.c:567]: grep_sock_info(): checking
>>>>>>> if
>>>>>>> port 5060 (advertise 0) matches port 5060
>>>>>>> 5(24552) DEBUG: tm [t_lookup.c:1011]: t_check_msg(): DEBUG: t_check_msg:
>>>>>>> msg
>>>>>>> id=1 global id=0 T start=0xffffffffffffffff
>>>>>>> 5(24552) DEBUG: tm [t_lookup.c:466]: t_lookup_request():
>>>>>>> t_lookup_request:
>>>>>>> start searching: hash=46859, isACK=0
>>>>>>> 5(24552) DEBUG: tm [t_lookup.c:424]: matching_3261(): DEBUG: RFC3261
>>>>>>> transaction matching failed
>>>>>>> 5(24552) DEBUG: tm [t_lookup.c:648]: t_lookup_request(): DEBUG:
>>>>>>> t_lookup_request: no transaction found
>>>>>>> 5(24552) DEBUG: tm [t_lookup.c:1080]: t_check_msg(): DEBUG: t_check_msg:
>>>>>>> msg
>>>>>>> id=1 global id=1 T end=(nil)
>>>>>>> 5(24552) DEBUG: tm [t_lookup.c:1312]: t_newtran(): DEBUG: t_newtran: msg
>>>>>>> id=1 , global msg id=1 , T on entrance=(nil)
>>>>>>> 5(24552) DEBUG: tm [t_lookup.c:466]: t_lookup_request():
>>>>>>> t_lookup_request:
>>>>>>> start searching: hash=46859, isACK=0
>>>>>>> 5(24552) DEBUG: tm [t_lookup.c:424]: matching_3261(): DEBUG: RFC3261
>>>>>>> transaction matching failed
>>>>>>> 5(24552) DEBUG: tm [t_lookup.c:648]: t_lookup_request(): DEBUG:
>>>>>>> t_lookup_request: no transaction found
>>>>>>> 5(24552) DEBUG: tm [t_hooks.c:358]: run_reqin_callbacks_internal(): DBG:
>>>>>>> trans=0x7fb3fb0c64e0, callback type 1, id 0 entered
>>>>>>> 5(24552) DEBUG: <core> [md5utils.c:67]: MD5StringArray(): MD5
>>>>>>> calculated:
>>>>>>> 5ea9736b3bdcf90a4c3043a5a492b31f
>>>>>>> 5(24552) DEBUG: <core> [select.c:412]: run_select(): Calling SELECT
>>>>>>> 0x7fb404252ef8
>>>>>>> 5(24552) INFO: Theseus-Test Call 69672589-67f5-4edd-8844-f6ad2ee5b208 /
>>>>>>> Call-ID 69672589-67f5-4edd-8844-f6ad2ee5b208: Call-ID =
>>>>>>> 69672589-67f5-4edd-8844-f6ad2ee5b208
>>>>>>> 5(24552) DEBUG: app_lua [app_lua_api.c:643]: app_lua_run(): reload
>>>>>>> deactivated
>>>>>>> 5(24552) DEBUG: app_lua [app_lua_api.c:644]: app_lua_run(): executing
>>>>>>> Lua
>>>>>>> function: [[allow_src_uri]]
>>>>>>> 5(24552) DEBUG: app_lua [app_lua_api.c:645]: app_lua_run(): lua top
>>>>>>> index
>>>>>>> is: 4
>>>>>>> 5(24552) DEBUG: app_lua [app_lua_sr.c:979]: lua_sr_pv_get(): pv get:
>>>>>>> $avp(s:call_obj)
>>>>>>> 5(24552) DEBUG: <core> [pvapi.c:321]: pv_cache_lookup(): pvar
>>>>>>> [$avp(s:call_obj)] found in cache
>>>>>>> 5(24552) DEBUG: app_lua [app_lua_sr.c:979]: lua_sr_pv_get(): pv get: $ci
>>>>>>> 5(24552) DEBUG: <core> [pvapi.c:321]: pv_cache_lookup(): pvar [$ci]
>>>>>>> found
>>>>>>> in
>>>>>>> cache
>>>>>>> 5(24552) DEBUG: app_lua [app_lua_sr.c:1101]: lua_sr_pv_sets(): pv set:
>>>>>>> $var(authorized)
>>>>>>> 5(24552) DEBUG: <core> [pvapi.c:321]: pv_cache_lookup(): pvar
>>>>>>> [$var(authorized)] found in cache
>>>>>>> 5(24552) INFO: Theseus-Test Call 69672589-67f5-4edd-8844-f6ad2ee5b208 /
>>>>>>> Call-ID 69672589-67f5-4edd-8844-f6ad2ee5b208: Method INVITE from
>>>>>>> 79.170.64.130:5080 authorized
>>>>>>> 5(24552) INFO: Theseus-Test Call 58825 / Call-ID
>>>>>>> 69672589-67f5-4edd-8844-f6ad2ee5b208: Object 58825 assigned to Call-ID
>>>>>>> 69672589-67f5-4edd-8844-f6ad2ee5b208
>>>>>>> 5(24552) INFO: Theseus-Test Call 58825 / Call-ID
>>>>>>> 69672589-67f5-4edd-8844-f6ad2ee5b208: The call has been sent to RG
>>>>>>> 106406
>>>>>>> 5(24552) DEBUG: app_lua [app_lua_api.c:643]: app_lua_run(): reload
>>>>>>> deactivated
>>>>>>> 5(24552) DEBUG: app_lua [app_lua_api.c:644]: app_lua_run(): executing
>>>>>>> Lua
>>>>>>> function: [[get_rg_info]]
>>>>>>> 5(24552) DEBUG: app_lua [app_lua_api.c:645]: app_lua_run(): lua top
>>>>>>> index
>>>>>>> is: 4
>>>>>>> 5(24552) DEBUG: app_lua [app_lua_sr.c:979]: lua_sr_pv_get(): pv get: $ci
>>>>>>> 5(24552) DEBUG: <core> [pvapi.c:321]: pv_cache_lookup(): pvar [$ci]
>>>>>>> found
>>>>>>> in
>>>>>>> cache
>>>>>>> 5(24552) DEBUG: app_lua [app_lua_sr.c:979]: lua_sr_pv_get(): pv get:
>>>>>>> $avp(s:call_obj)
>>>>>>> 5(24552) DEBUG: <core> [pvapi.c:321]: pv_cache_lookup(): pvar
>>>>>>> [$avp(s:call_obj)] found in cache
>>>>>>> 5(24552) DEBUG: app_lua [app_lua_sr.c:979]: lua_sr_pv_get(): pv get:
>>>>>>> $shv(flag_list)
>>>>>>> 5(24552) DEBUG: <core> [pvapi.c:293]: pv_cache_add(): pvar
>>>>>>> [$shv(flag_list)]
>>>>>>> added in cache
>>>>>>> 5(24552) INFO: app_lua [app_lua_sr.c:96]: lua_sr_log(): Call 58825 /
>>>>>>> Call-ID
>>>>>>> 69672589-67f5-4edd-8844-f6ad2ee5b208: RG 106406 attributes:
>>>>>>> 5(24552) INFO: app_lua [app_lua_sr.c:96]: lua_sr_log(): Call 58825 /
>>>>>>> Call-ID
>>>>>>> 69672589-67f5-4edd-8844-f6ad2ee5b208: RG OUT 106406 description =
>>>>>>> PBX--NOC-TEST
>>>>>>> 5(24552) INFO: app_lua [app_lua_sr.c:96]: lua_sr_log(): Call 58825 /
>>>>>>> Call-ID
>>>>>>> 69672589-67f5-4edd-8844-f6ad2ee5b208: RG OUT 106406 admin_status = 1
>>>>>>> 5(24552) INFO: app_lua [app_lua_sr.c:96]: lua_sr_log(): Call 58825 /
>>>>>>> Call-ID
>>>>>>> 69672589-67f5-4edd-8844-f6ad2ee5b208: RG OUT 106406 cir_direction = 2
>>>>>>> 5(24552) INFO: app_lua [app_lua_sr.c:96]: lua_sr_log(): Call 58825 /
>>>>>>> Call-ID
>>>>>>> 69672589-67f5-4edd-8844-f6ad2ee5b208: RG OUT 106406 ani_posttranslation
>>>>>>> =
>>>>>>> 0
>>>>>>> 5(24552) INFO: app_lua [app_lua_sr.c:96]: lua_sr_log(): Call 58825 /
>>>>>>> Call-ID
>>>>>>> 69672589-67f5-4edd-8844-f6ad2ee5b208: RG OUT 106406
>>>>>>> ani_posttranslation_table = 0
>>>>>>> 5(24552) INFO: app_lua [app_lua_sr.c:96]: lua_sr_log(): Call 58825 /
>>>>>>> Call-ID
>>>>>>> 69672589-67f5-4edd-8844-f6ad2ee5b208: RG OUT 106406 posttranslation = 1
>>>>>>> 5(24552) INFO: app_lua [app_lua_sr.c:96]: lua_sr_log(): Call 58825 /
>>>>>>> Call-ID
>>>>>>> 69672589-67f5-4edd-8844-f6ad2ee5b208: RG OUT 106406
>>>>>>> posttranslation_table
>>>>>>> =
>>>>>>> 106384
>>>>>>> 5(24552) INFO: app_lua [app_lua_sr.c:96]: lua_sr_log(): Call 58825 /
>>>>>>> Call-ID
>>>>>>> 69672589-67f5-4edd-8844-f6ad2ee5b208: RG OUT 106406 pound = 2
>>>>>>> 5(24552) INFO: app_lua [app_lua_sr.c:96]: lua_sr_log(): Call 58825 /
>>>>>>> Call-ID
>>>>>>> 69672589-67f5-4edd-8844-f6ad2ee5b208: RG OUT 106406 gw_port = 5060
>>>>>>> 5(24552) INFO: app_lua [app_lua_sr.c:96]: lua_sr_log(): Call 58825 /
>>>>>>> Call-ID
>>>>>>> 69672589-67f5-4edd-8844-f6ad2ee5b208: RG OUT 106406 custom_flags = None
>>>>>>> 5(24552) DEBUG: app_lua [app_lua_sr.c:1101]: lua_sr_pv_sets(): pv set:
>>>>>>> $avp(s:description)
>>>>>>> 5(24552) DEBUG: <core> [usr_avp.c:882]: parse_avp_ident(): Parsing
>>>>>>> 's:description'
>>>>>>> 5(24552) DEBUG: <core> [pvapi.c:293]: pv_cache_add(): pvar
>>>>>>> [$avp(s:description)] added in cache
>>>>>>> 5(24552) DEBUG: app_lua [app_lua_sr.c:1101]: lua_sr_pv_sets(): pv set:
>>>>>>> $avp(s:admin_status)
>>>>>>> 5(24552) DEBUG: <core> [pvapi.c:321]: pv_cache_lookup(): pvar
>>>>>>> [$avp(s:admin_status)] found in cache
>>>>>>> 5(24552) DEBUG: app_lua [app_lua_sr.c:1101]: lua_sr_pv_sets(): pv set:
>>>>>>> $avp(s:cir_direction)
>>>>>>> 5(24552) DEBUG: <core> [usr_avp.c:882]: parse_avp_ident(): Parsing
>>>>>>> 's:cir_direction'
>>>>>>> 5(24552) DEBUG: <core> [pvapi.c:293]: pv_cache_add(): pvar
>>>>>>> [$avp(s:cir_direction)] added in cache
>>>>>>> 5(24552) DEBUG: app_lua [app_lua_sr.c:1101]: lua_sr_pv_sets(): pv set:
>>>>>>> $avp(s:ani_posttranslation_table)
>>>>>>> 5(24552) DEBUG: <core> [pvapi.c:321]: pv_cache_lookup(): pvar
>>>>>>> [$avp(s:ani_posttranslation_table)] found in cache
>>>>>>> 5(24552) DEBUG: app_lua [app_lua_sr.c:1101]: lua_sr_pv_sets(): pv set:
>>>>>>> $avp(s:posttranslation_table)
>>>>>>> 5(24552) DEBUG: <core> [pvapi.c:321]: pv_cache_lookup(): pvar
>>>>>>> [$avp(s:posttranslation_table)] found in cache
>>>>>>> 5(24552) DEBUG: app_lua [app_lua_sr.c:1101]: lua_sr_pv_sets(): pv set:
>>>>>>> $avp(s:pound)
>>>>>>> 5(24552) DEBUG: <core> [pvapi.c:321]: pv_cache_lookup(): pvar
>>>>>>> [$avp(s:pound)] found in cache
>>>>>>> 5(24552) DEBUG: app_lua [app_lua_sr.c:1101]: lua_sr_pv_sets(): pv set:
>>>>>>> $avp(s:gw_ip)
>>>>>>> 5(24552) DEBUG: <core> [pvapi.c:321]: pv_cache_lookup(): pvar
>>>>>>> [$avp(s:gw_ip)] found in cache
>>>>>>> 5(24552) DEBUG: app_lua [app_lua_sr.c:1101]: lua_sr_pv_sets(): pv set:
>>>>>>> $avp(s:gw_port)
>>>>>>> 5(24552) DEBUG: <core> [pvapi.c:321]: pv_cache_lookup(): pvar
>>>>>>> [$avp(s:gw_port)] found in cache
>>>>>>> 5(24552) INFO: app_lua [app_lua_sr.c:96]: lua_sr_log(): Call 58825 /
>>>>>>> Call-ID
>>>>>>> 69672589-67f5-4edd-8844-f6ad2ee5b208: Custom flags = None
>>>>>>> 5(24552) DEBUG: app_lua [app_lua_sr.c:979]: lua_sr_pv_get(): pv get:
>>>>>>> $shv(custom_flag_list)
>>>>>>> 5(24552) DEBUG: <core> [pvapi.c:293]: pv_cache_add(): pvar
>>>>>>> [$shv(custom_flag_list)] added in cache
>>>>>>> 5(24552) DEBUG: app_lua [app_lua_sr.c:979]: lua_sr_pv_get(): pv get:
>>>>>>> $shv(custom_flag_list_branch)
>>>>>>> 5(24552) DEBUG: <core> [pvapi.c:293]: pv_cache_add(): pvar
>>>>>>> [$shv(custom_flag_list_branch)] added in cache
>>>>>>> 5(24552) DEBUG: textopsx [textopsx.c:231]: msg_apply_changes_f(): SIP
>>>>>>> message content updated - reparsing
>>>>>>> 5(24552) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): SIP
>>>>>>> Request:
>>>>>>> 5(24552) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg():  method:
>>>>>>> <INVITE>
>>>>>>> 5(24552) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg():  uri:
>>>>>>> <sip:34666988297 at 79.170.71.132:5060>
>>>>>>> 5(24552) DEBUG: <core> [parser/msg_parser.c:614]: parse_msg():  version:
>>>>>>> <SIP/2.0>
>>>>>>> 5(24552) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param():
>>>>>>> Found
>>>>>>> param type 235, <rport> = <n/a>; state=6
>>>>>>> 5(24552) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param():
>>>>>>> Found
>>>>>>> param type 232, <branch> = <z9hG4bK7ma5t4FKS0SKa>; state=16
>>>>>>> 5(24552) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
>>>>>>> header
>>>>>>> reached, state=5
>>>>>>> 5(24552) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
>>>>>>> parse_headers: Via found, flags=2
>>>>>>> 5(24552) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
>>>>>>> parse_headers: this is the first via
>>>>>>> 5(24552) DEBUG: textops [textops.c:1577]: set_multibody_helper():
>>>>>>> delimiter<21>:[2123894789_1257887457]
>>>>>>> 5(24552) DEBUG: <core> [parser/parse_addr_spec.c:894]:
>>>>>>> parse_addr_spec():
>>>>>>> end of header reached, state=10
>>>>>>> 5(24552) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field():
>>>>>>> DEBUG:
>>>>>>> get_hdr_field: <To> [49];
>>>>>>> uri=[sip:34666988297 at 79.170.71.132:5060;user=phone]
>>>>>>> 5(24552) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field():
>>>>>>> DEBUG:
>>>>>>> to
>>>>>>> body [<sip:34666988297 at 79.170.71.132:5060;user=phone>
>>>>>>> ]
>>>>>>> 5(24552) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
>>>>>>> get_hdr_field: cseq <CSeq>: <94568362> <INVITE>
>>>>>>> 5(24552) DEBUG: <core> [parser/msg_parser.c:187]: get_hdr_field():
>>>>>>> DEBUG:
>>>>>>> get_hdr_body : content_length=104
>>>>>>> 5(24552) DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found
>>>>>>> end
>>>>>>> of header
>>>>>>> 5(24552) DEBUG: textops [textops.c:1486]: generate_boundary(): adding
>>>>>>> final
>>>>>>> CRLF+CRLF
>>>>>>> 5(24552) DEBUG: textops [textops.c:1714]: set_multibody_helper():
>>>>>>> content-type<48>:[multipart/mixed;boundary="2123894789_1257887457"]
>>>>>>> 5(24552) DEBUG: textops [textops.c:1768]: set_multibody_helper(): set
>>>>>>> flag
>>>>>>> FL_BODY_MULTIPART
>>>>>>> 5(24552) INFO: <core> [msg_translator.c:1692]: get_boundary():
>>>>>>> Content-Type
>>>>>>> hdr has no params <application/sdp>
>>>>>>> 5(24552) WARNING: <core> [msg_translator.c:1958]:
>>>>>>> build_req_buf_from_sip_req(): check_boundaries error
>>>>>>> 5(24552) DEBUG: <core> [msg_translator.c:422]: clen_builder():
>>>>>>> content-length: 189 (189)
>>>>>>> 5(24552) DEBUG: textopsx [textopsx.c:231]: msg_apply_changes_f(): SIP
>>>>>>> message content updated - reparsing
>>>>>>> 5(24552) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): SIP
>>>>>>> Request:
>>>>>>> 5(24552) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg():  method:
>>>>>>> <INVITE>
>>>>>>> 5(24552) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg():  uri:
>>>>>>> <sip:34666988297 at 79.170.71.132:5060>
>>>>>>> 5(24552) DEBUG: <core> [parser/msg_parser.c:614]: parse_msg():  version:
>>>>>>> <SIP/2.0>
>>>>>>> 5(24552) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param():
>>>>>>> Found
>>>>>>> param type 235, <rport> = <n/a>; state=6
>>>>>>> 5(24552) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param():
>>>>>>> Found
>>>>>>> param type 232, <branch> = <z9hG4bK7ma5t4FKS0SKa>; state=16
>>>>>>> 5(24552) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
>>>>>>> header
>>>>>>> reached, state=5
>>>>>>> 5(24552) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
>>>>>>> parse_headers: Via found, flags=2
>>>>>>> 5(24552) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
>>>>>>> parse_headers: this is the first via
>>>>>>> 5(24552) ERROR: <core> [lvalue.c:345]: lval_pvar_assign(): non existing
>>>>>>> right pvar
>>>>>>> 5(24552) ERROR: <core> [lvalue.c:405]: lval_assign(): assignment failed
>>>>>>> at
>>>>>>> pos: (390,18-390,44)
>>>>>>> 5(24552) DEBUG: <core> [parser/parse_addr_spec.c:894]:
>>>>>>> parse_addr_spec():
>>>>>>> end of header reached, state=10
>>>>>>> 5(24552) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field():
>>>>>>> DEBUG:
>>>>>>> get_hdr_field: <To> [49];
>>>>>>> uri=[sip:34666988297 at 79.170.71.132:5060;user=phone]
>>>>>>> 5(24552) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field():
>>>>>>> DEBUG:
>>>>>>> to
>>>>>>> body [<sip:34666988297 at 79.170.71.132:5060;user=phone>
>>>>>>> ]
>>>>>>> 5(24552) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
>>>>>>> get_hdr_field: cseq <CSeq>: <94568362> <INVITE>
>>>>>>> 5(24552) DEBUG: <core> [parser/msg_parser.c:187]: get_hdr_field():
>>>>>>> DEBUG:
>>>>>>> get_hdr_body : content_length=189
>>>>>>> 5(24552) DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found
>>>>>>> end
>>>>>>> of header
>>>>>>> 5(24552) DEBUG: textops [textops.c:1486]: generate_boundary(): adding
>>>>>>> final
>>>>>>> CRLF+CRLF
>>>>>>> 5(24552) DEBUG: <core> [msg_translator.c:1719]: get_boundary(): boundary
>>>>>>> is
>>>>>>> <--2123894789_1257887457>
>>>>>>> 5(24552) DEBUG: <core> [msg_translator.c:1839]: check_boundaries(): last
>>>>>>> bondary without -- at the end
>>>>>>> 5(24552) DEBUG: <core> [msg_translator.c:1639]: replace_body(): old size
>>>>>>> body[189] actual[319]
>>>>>>> 5(24552) DEBUG: <core> [msg_translator.c:422]: clen_builder():
>>>>>>> content-length: 319 (319)
>>>>>>> 5(24552) DEBUG: textopsx [textopsx.c:231]: msg_apply_changes_f(): SIP
>>>>>>> message content updated - reparsing
>>>>>>> 5(24552) DEBUG: <core> [parser/msg_parser.c:608]: parse_msg(): SIP
>>>>>>> Request:
>>>>>>> 5(24552) DEBUG: <core> [parser/msg_parser.c:610]: parse_msg():  method:
>>>>>>> <INVITE>
>>>>>>> 5(24552) DEBUG: <core> [parser/msg_parser.c:612]: parse_msg():  uri:
>>>>>>> <sip:34666988297 at 79.170.71.132:5060>
>>>>>>> 5(24552) DEBUG: <core> [parser/msg_parser.c:614]: parse_msg():  version:
>>>>>>> <SIP/2.0>
>>>>>>> 5(24552) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param():
>>>>>>> Found
>>>>>>> param type 235, <rport> = <n/a>; state=6
>>>>>>> 5(24552) DEBUG: <core> [parser/parse_via.c:1254]: parse_via_param():
>>>>>>> Found
>>>>>>> param type 232, <branch> = <z9hG4bK7ma5t4FKS0SKa>; state=16
>>>>>>> 5(24552) DEBUG: <core> [parser/parse_via.c:2642]: parse_via(): end of
>>>>>>> header
>>>>>>> reached, state=5
>>>>>>> 5(24552) DEBUG: <core> [parser/msg_parser.c:496]: parse_headers():
>>>>>>> parse_headers: Via found, flags=2
>>>>>>> 5(24552) DEBUG: <core> [parser/msg_parser.c:498]: parse_headers():
>>>>>>> parse_headers: this is the first via
>>>>>>> 5(24552) DEBUG: <core> [parser/parse_addr_spec.c:894]:
>>>>>>> parse_addr_spec():
>>>>>>> end of header reached, state=10
>>>>>>> 5(24552) DEBUG: <core> [parser/msg_parser.c:173]: get_hdr_field():
>>>>>>> DEBUG:
>>>>>>> get_hdr_field: <To> [49];
>>>>>>> uri=[sip:34666988297 at 79.170.71.132:5060;user=phone]
>>>>>>> 5(24552) DEBUG: <core> [parser/msg_parser.c:175]: get_hdr_field():
>>>>>>> DEBUG:
>>>>>>> to
>>>>>>> body [<sip:34666988297 at 79.170.71.132:5060;user=phone>
>>>>>>> ]
>>>>>>> 5(24552) DEBUG: <core> [parser/msg_parser.c:153]: get_hdr_field():
>>>>>>> get_hdr_field: cseq <CSeq>: <94568362> <INVITE>
>>>>>>> 5(24552) DEBUG: <core> [parser/msg_parser.c:187]: get_hdr_field():
>>>>>>> DEBUG:
>>>>>>> get_hdr_body : content_length=319
>>>>>>> 5(24552) DEBUG: <core> [parser/parse_body.c:87]: get_boundary_param():
>>>>>>> boundary is "2123894789_1257887457"
>>>>>>> 5(24552) DEBUG: <core> [parser/msg_parser.c:89]: get_hdr_field(): found
>>>>>>> end
>>>>>>> of header
>>>>>>> 5(24552) DEBUG: <core> [parser/parse_body.c:87]: get_boundary_param():
>>>>>>> boundary is "2123894789_1257887457"
>>>>>>> 5(24552) CRITICAL: <core> [data_lump.c:297]: del_lump(): offset + len
>>>>>>> exceeds message size (1176 + 51 > 1201)
>>>>>>> 35(24582) CRITICAL: <core> [pass_fd.c:275]: receive_fd(): EOF on 17
>>>>>>> 35(24582) DEBUG: <core> [tcp_main.c:3448]: handle_ser_child(): dead
>>>>>>> child
>>>>>>> 5,
>>>>>>> pid 24552 (shutting down?)
>>>>>>> 35(24582) DEBUG: <core> [io_wait.h:598]: io_watch_del(): DBG:
>>>>>>> io_watch_del
>>>>>>> (0xa2ae40, 17, -1, 0x0) fd_no=39 called
>>>>>>> 0(24547) ALERT: <core> [main.c:739]: handle_sigs(): child process 24552
>>>>>>> exited by a signal 6
>>>>>>> 0(24547) ALERT: <core> [main.c:742]: handle_sigs(): core was not
>>>>>>> generated
>>>>>>> 0(24547) INFO: <core> [main.c:754]: handle_sigs(): terminating due to
>>>>>>> SIGCHLD
>>>>>>> 0(24547) DEBUG: <core> [main.c:756]: handle_sigs(): terminating due to
>>>>>>> SIGCHLD
>>>>>>> 34(24581) INFO: <core> [main.c:809]: sig_usr(): signal 15 received
>>>>>>> 33(24580) INFO: <core> [main.c:809]: sig_usr(): signal 15 received
>>>>>>> 32(24579) INFO: <core> [main.c:809]: sig_usr(): signal 15 received
>>>>>>> 31(24578) INFO: <core> [main.c:809]: sig_usr(): signal 15 received
>>>>>>> 30(24577) INFO: <core> [main.c:809]: sig_usr(): signal 15 received
>>>>>>> 29(24576) INFO: <core> [main.c:809]: sig_usr(): signal 15 received
>>>>>>> 28(24575) INFO: <core> [main.c:809]: sig_usr(): signal 15 received
>>>>>>> 27(24574) INFO: <core> [main.c:809]: sig_usr(): signal 15 received
>>>>>>> 26(24573) INFO: <core> [main.c:809]: sig_usr(): signal 15 received
>>>>>>> 25(24572) INFO: <core> [main.c:809]: sig_usr(): signal 15 received
>>>>>>> 24(24571) INFO: <core> [main.c:809]: sig_usr(): signal 15 received
>>>>>>> 23(24570) INFO: <core> [main.c:809]: sig_usr(): signal 15 received
>>>>>>> 22(24569) INFO: <core> [main.c:809]: sig_usr(): signal 15 received
>>>>>>> 21(24568) INFO: <core> [main.c:809]: sig_usr(): signal 15 received
>>>>>>> 20(24567) INFO: <core> [main.c:809]: sig_usr(): signal 15 received
>>>>>>> 19(24566) INFO: <core> [main.c:809]: sig_usr(): signal 15 received
>>>>>>> 18(24565) INFO: <core> [main.c:809]: sig_usr(): signal 15 received
>>>>>>> 17(24564) INFO: <core> [main.c:809]: sig_usr(): signal 15 received
>>>>>>> 16(24563) INFO: <core> [main.c:809]: sig_usr(): signal 15 received
>>>>>>> 15(24562) INFO: <core> [main.c:809]: sig_usr(): signal 15 received
>>>>>>> 14(24561) INFO: <core> [main.c:809]: sig_usr(): signal 15 received
>>>>>>> 13(24560) INFO: <core> [main.c:809]: sig_usr(): signal 15 received
>>>>>>> 12(24559) INFO: <core> [main.c:809]: sig_usr(): signal 15 received
>>>>>>> 11(24558) INFO: <core> [main.c:809]: sig_usr(): signal 15 received
>>>>>>> 10(24557) INFO: <core> [main.c:809]: sig_usr(): signal 15 received
>>>>>>> 9(24556) INFO: <core> [main.c:809]: sig_usr(): signal 15 received
>>>>>>> 8(24555) INFO: <core> [main.c:809]: sig_usr(): signal 15 received
>>>>>>> 7(24554) INFO: <core> [main.c:809]: sig_usr(): signal 15 received
>>>>>>> 6(24553) INFO: <core> [main.c:809]: sig_usr(): signal 15 received
>>>>>>> 4(24551) INFO: <core> [main.c:809]: sig_usr(): signal 15 received
>>>>>>> 3(24550) INFO: <core> [main.c:809]: sig_usr(): signal 15 received
>>>>>>> 2(24549) INFO: <core> [main.c:809]: sig_usr(): signal 15 received
>>>>>>> 1(24548) INFO: <core> [main.c:809]: sig_usr(): signal 15 received
>>>>>>> 35(24582) INFO: <core> [main.c:809]: sig_usr(): signal 15 received
>>>>>>> 0(24547) DEBUG: rtpengine [rtpengine.c:2014]: mod_destroy():
>>>>>>> rtpengine_hash_table_destroy() success!
>>>>>>> 0(24547) DEBUG: db_text [dbtext.c:110]: destroy(): destroy ...
>>>>>>> 0(24547) DEBUG: tm [t_funcs.c:86]: tm_shutdown(): DEBUG: tm_shutdown :
>>>>>>> start
>>>>>>> 0(24547) DEBUG: tm [t_funcs.c:89]: tm_shutdown(): DEBUG: tm_shutdown :
>>>>>>> emptying hash table
>>>>>>> 0(24547) DEBUG: tm [h_table.c:127]: free_cell_helper(): freeing
>>>>>>> transaction
>>>>>>> 0x7fb3fb0c64e0 from h_table.c:449
>>>>>>> 0(24547) DEBUG: tm [t_funcs.c:91]: tm_shutdown(): DEBUG: tm_shutdown :
>>>>>>> removing semaphores
>>>>>>> 0(24547) DEBUG: tm [t_funcs.c:93]: tm_shutdown(): DEBUG: tm_shutdown :
>>>>>>> destroying tmcb lists
>>>>>>> 0(24547) DEBUG: tm [t_funcs.c:96]: tm_shutdown(): DEBUG: tm_shutdown :
>>>>>>> done
>>>>>>> 0(24547) INFO: <core> [sctp_core.c:53]: sctp_core_destroy(): SCTP API
>>>>>>> not
>>>>>>> initialized
>>>>>>> 0(24547) DEBUG: <core> [mem/shm.c:174]: shm_core_lock_destroy():
>>>>>>> destroying
>>>>>>> the shared memory lock
>>>>>>> 0(24547) DEBUG: <core> [mem/shm.c:266]: shm_destroy_manager():
>>>>>>> destroying
>>>>>>> memory manager: f_malloc
>>>>>>> 0(24547) DEBUG: <core> [mem/pkg.c:89]: pkg_destroy_manager(): destroying
>>>>>>> memory manager: f_malloc
>>>>>>>
>>>>>>>
>>>>>>>
>>>>>>> _______________________________________________
>>>>>>> SIP Express Router (SER) and Kamailio (OpenSER) - sr-users mailing list
>>>>>>> sr-users at lists.sip-router.org
>>>>>>> http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users
>>>>>>>
>>>>>>>
>>>>>>> --
>>>>>>> Daniel-Constantin Mierla
>>>>>>> http://www.asipto.com - http://www.kamailio.org
>>>>>>> http://twitter.com/#!/miconda - http://www.linkedin.com/in/miconda
>>>>>>>
>>>>>>>
>>>>>>> _______________________________________________
>>>>>>> SIP Express Router (SER) and Kamailio (OpenSER) - sr-users mailing list
>>>>>>> sr-users at lists.sip-router.org
>>>>>>> http://lists.sip-router.org/cgi-bin/mailman/listinfo/sr-users
>>>>>>>
>>>>>
>>>>>
>>>>>
>>>>>
>>>>> --
>>>>> Daniel-Constantin Mierla
>>>>> http://www.asipto.com - http://www.kamailio.org
>>>>> http://twitter.com/#!/miconda - http://www.linkedin.com/in/miconda
>>>>>
>>>>>
>>>>
>>>>
-------------- next part --------------
A non-text attachment was scrubbed...
Name: isup-in-sip.png
Type: image/png
Size: 26758 bytes
Desc: not available
URL: <http://lists.sip-router.org/pipermail/sr-users/attachments/20160805/21a0fee2/attachment.png>


More information about the sr-users mailing list