- FreeSWITCH-users - FreeSWITCH Mailing Lists

OpenSIPS Summit 2018 - Call For Papers
by Bogdan-Andrei Iancu 13 Dec '17

13 Dec '17

<http://www.opensips.org/events/Summit-2018Amsterdam> Call For Papers OpenSIPS Summit 2018 May 1-4, 2018 Amsterdam, The Netherlands *The Call for Papers is now open!* Submit your paper and share with us your experience, achievements or projects you had in VoIP & RTC area (with or without OpenSIPS) - be a speaker for our experts, developers and users from all over the world. The OpenSIPS Summit <http://www.opensips.org/events/Summit-2018Amsterdam/> attracts a large spectrum of participants from areas as VoIP providers/carriers or telcos due to its broad format that covers talks, inspiring presentations, workshops and trainings. So we are looking for papers to cover a broad and various area of VoIP & RTC. And our speaker will enjoy free admission to the event, covering lunches and evening events ;) . *How to Submit* For the first time we are using the papercall.io portal to manage the collecting the papers. This will transform the submission process in something simple and friendly for our potential speakers. Find out the submission form here: *https://www.papercall.io/opensips-summit-2018* *Event Tickets* Choose your preferred bundle as * pass type - conference, training or both * group - discounts do apply *Register now <http://www.opensips.org/events/Summit-2018Amsterdam/#mu-register>* and secure your seat for the OpenSIPS Summit ! <https://www.google.com/maps/place/Radisson+Blu+Hotel,+Amsterdam/@52.3702502…> * * *Radisson Blu** **Rusland 17, 1012CK Amsterdam, The Netherlands* Meet us again at our familiar Venue, with even more space and comfort than ever! This year the OpenSIPS Summit expands in size and will accommodate more participants and speakers. ** Interested in becoming a sponsor too? Please contact <http://www.opensips.org/events/Summit-2018Amsterdam/#mu-contact> our team or email <mailto:summit@opensips.org?subject=Sponsoring%20OpenSIPS%20Summit> us! -- Bogdan-Andrei Iancu OpenSIPS Founder and Developer http://www.opensips-solutions.com

1 0

DTMF error
by Enrico Vergata 13 Dec '17

13 Dec '17

Hello FS-users, I'am trying to execute a python script, launched from the dialplan. Everything works fine but at a given time I need to execute a TTS request to the gTTS module within python ( google translator API) to gather a .wav file to be played. That's right after a DTMF is needed to select the good "if" condition. FS performs good the task when it interact with Jisti but FS stop detect RTP DTMF Event when does not matter which other telephone/softphone is used. Disabling the TTS request, FS detects well the RTP DTMF Event for both extension: Jitsi and the others. I don't really know what might be the cause? Anyway the script keeps carrying on and the media files are all well played. I'm using FreeSWITCH Version 1.9.0 in Centos 7, I've replicated the script in Lua that has shown the same behavior with the exception that even with Jisti FS stop detecting DTMF events. Thanks you in advance for any help and idea. Best regards, Enrico

1 0

Jittering only on internal Lines
by Philipp Zeitschel 13 Dec '17

13 Dec '17

Hi, Hope somebody can help I'm running 1.6.19~36~7a77e0b-1~jessie+1 The problem is that I got jittering on the internal line. If I get a call from outside (leg a) I can hear leg a totally clear only on leg b (my internal telephone) I get sound cuts. If I make a total internal call both side have jitters, internal is no nat, the phones are in the same /24 Network with freeswitch. I have no glue how to debug this, can you guide me in the right direction? Here is ja call log: nta.c:6791 incoming_reply() nta: sent 100 Trying for INVITE (1) nua_stack.c:271 nua_stack_event() nua(0x7fe6d8059c20): event i_invite 100 Trying nua_session.c:4139 signal_call_state_change() nua(0x7fe6d8059c20): call state changed: init -> received, received offer soa.c:1098 soa_get_remote_sdp() soa_get_remote_sdp(static::0x7fe6d805e1a0, [0x7fe7040b88c8], [0x7fe7040b88d0], [(nil)]) called nua_stack.c:271 nua_stack_event() nua(0x7fe6d8059c20): event i_state 100 Trying nua_stack.c:359 nua_application_event() nua: nua_application_event: entering nua.c:342 nua_handle_bind() nua: nua_handle_bind: entering 2017-12-13 10:05:04.668947 [NOTICE] switch_channel.c:1104 New Channel sofia/external/+xxx(a)tmobile.de [d220a9b4-8c44-49ce-a226-18625641c2ed] nua_stack.c:359 nua_application_event() nua: nua_application_event: entering 2017-12-13 10:05:04.668947 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+xxx(a)tmobile.de) Running State Change CS_NEW (Cur 1 Tot 205) nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering 2017-12-13 10:05:04.668947 [DEBUG] sofia.c:9873 sofia/external/+xxx(a)tmobile.de receiving invite from 217.0.23.100:5060 version: 1.6.19 -36-7a77e0b 64bit nua.c:610 nua_set_hparams() nua: nua_set_hparams: entering nua.c:610 nua_set_hparams() nua: nua_r_set_params with invalid handle (nil) nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering 2017-12-13 10:05:04.668947 [DEBUG] sofia.c:7084 Channel sofia/external/+xxx(a)tmobile.de entering state [received][100] 2017-12-13 10:05:04.668947 [DEBUG] sofia.c:7094 Remote SDP: v=0 o=- 629563164 271544510 IN IP4 217.0.23.100 s=- c=IN IP4 217.0.4.164 t=0 0 a=sendrecv m=audio 25732 RTP/AVP 9 8 100 a=rtpmap:9 G722/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:100 telephone-event/8000 a=fmtp:100 0-15 a=maxptime:40 a=ptime:20 2017-12-13 10:05:04.668947 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1] 2017-12-13 10:05:04.668947 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match 2017-12-13 10:05:04.668947 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2017-12-13 10:05:04.668947 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2017-12-13 10:05:04.668947 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1] 2017-12-13 10:05:04.668947 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2017-12-13 10:05:04.668947 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 2017-12-13 10:05:04.668947 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2017-12-13 10:05:04.668947 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 100@8000 2017-12-13 10:05:04.668947 [DEBUG] switch_core_media.c:3061 Set Codec sofia/external/+xxx(a)tmobile.de G722/8000 20 ms 160 samples 64000 bits 1 channels 2017-12-13 10:05:04.668947 [DEBUG] switch_core_codec.c:111 sofia/external/+xxx(a)tmobile.de Original read codec set to G722:9 2017-12-13 10:05:04.668947 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 100@8000 2017-12-13 10:05:04.668947 [DEBUG] switch_core_media.c:4767 sofia/external/+xxx(a)tmobile.de Set 2833 dtmf send payload to 100 recv payload to 100 2017-12-13 10:05:04.668947 [DEBUG] sofia.c:7507 (sofia/external/+xxx(a)tmobile.de) State Change CS_NEW -> CS_INIT nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering 2017-12-13 10:05:04.668947 [DEBUG] switch_core_state_machine.c:603 (sofia/external/+xxx(a)tmobile.de) State NEW 2017-12-13 10:05:04.668947 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+xxx(a)tmobile.de) Running State Change CS_INIT (Cur 1 Tot 205) 2017-12-13 10:05:04.668947 [DEBUG] switch_core_state_machine.c:627 (sofia/external/+xxx(a)tmobile.de) State INIT 2017-12-13 10:05:04.668947 [DEBUG] mod_sofia.c:90 sofia/external/+xxx(a)tmobile.de SOFIA INIT 2017-12-13 10:05:04.668947 [DEBUG] switch_core_state_machine.c:40 sofia/external/+xxx(a)tmobile.de Standard INIT 2017-12-13 10:05:04.668947 [DEBUG] switch_core_state_machine.c:48 (sofia/external/+xxx(a)tmobile.de) State Change CS_INIT -> CS_ROUTING 2017-12-13 10:05:04.668947 [DEBUG] switch_core_state_machine.c:627 (sofia/external/+xxx(a)tmobile.de) State INIT going to sleep 2017-12-13 10:05:04.668947 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+xxx(a)tmobile.de) Running State Change CS_ROUTING (Cur 1 Tot 205) 2017-12-13 10:05:04.668947 [DEBUG] switch_channel.c:2249 (sofia/external/+xxx(a)tmobile.de) Callstate Change DOWN -> RINGING 2017-12-13 10:05:04.668947 [DEBUG] switch_core_state_machine.c:643 (sofia/external/+xxx(a)tmobile.de) State ROUTING 2017-12-13 10:05:04.668947 [DEBUG] mod_sofia.c:143 sofia/external/+xxx(a)tmobile.de SOFIA ROUTING 2017-12-13 10:05:04.668947 [DEBUG] switch_core_state_machine.c:236 sofia/external/+xxx(a)tmobile.de Standard ROUTING 2017-12-13 10:05:04.668947 [INFO] mod_dialplan_xml.c:637 Processing +xxx <+xxx>->xxx in context public Dialplan: sofia/external/+xxx(a)tmobile.de parsing [public->xxx] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (PASS) [xxx] destination_number(xxx) =~ /^(xxx)$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de Action set(call_direction=inbound) INLINE EXECUTE sofia/external/+xxx(a)tmobile.de set(call_direction=inbound) 2017-12-13 10:05:04.668947 [DEBUG] mod_dptools.c:1548 SET sofia/external/+xxx(a)tmobile.de [call_direction]=[inbound] Dialplan: sofia/external/+xxx(a)tmobile.de Action set(domain_uuid=404d503a-ef07-4c72-9b52-1ac9e32a3a71) INLINE EXECUTE sofia/external/+xxx(a)tmobile.de set(domain_uuid=404d503a-ef07-4c72-9b52-1ac9e32a3a71) 2017-12-13 10:05:04.668947 [DEBUG] mod_dptools.c:1548 SET sofia/external/+xxx(a)tmobile.de [domain_uuid]=[404d503a-ef07-4c72-9b52-1ac9e32a3a71] Dialplan: sofia/external/+xxx(a)tmobile.de Action set(domain_name=sip.xxx) INLINE EXECUTE sofia/external/+xxx(a)tmobile.de set(domain_name=sip.xxx) 2017-12-13 10:05:04.668947 [DEBUG] mod_dptools.c:1548 SET sofia/external/+xxx(a)tmobile.de [domain_name]=[sip.xxx] Dialplan: sofia/external/+xxx(a)tmobile.de Action transfer(50 XML sip.xxx) 2017-12-13 10:05:04.668947 [DEBUG] switch_core_state_machine.c:286 (sofia/external/+xxx(a)tmobile.de) State Change CS_ROUTING -> CS_EXECUTE 2017-12-13 10:05:04.668947 [DEBUG] switch_core_state_machine.c:643 (sofia/external/+xxx(a)tmobile.de) State ROUTING going to sleep 2017-12-13 10:05:04.668947 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+xxx(a)tmobile.de) Running State Change CS_EXECUTE (Cur 1 Tot 205) 2017-12-13 10:05:04.668947 [DEBUG] switch_core_state_machine.c:650 (sofia/external/+xxx(a)tmobile.de) State EXECUTE 2017-12-13 10:05:04.668947 [DEBUG] mod_sofia.c:198 sofia/external/+xxx(a)tmobile.de SOFIA EXECUTE 2017-12-13 10:05:04.668947 [DEBUG] switch_core_state_machine.c:328 sofia/external/+xxx(a)tmobile.de Standard EXECUTE EXECUTE sofia/external/+xxx(a)tmobile.de transfer(50 XML sip.xxx) 2017-12-13 10:05:04.668947 [DEBUG] switch_ivr.c:2165 (sofia/external/+xxx(a)tmobile.de) State Change CS_EXECUTE -> CS_ROUTING 2017-12-13 10:05:04.668947 [NOTICE] switch_ivr.c:2172 Transfer sofia/external/+xxx(a)tmobile.de to XML[50(a)sip.xxx] 2017-12-13 10:05:04.668947 [DEBUG] switch_core_state_machine.c:650 (sofia/external/+xxx(a)tmobile.de) State EXECUTE going to sleep 2017-12-13 10:05:04.668947 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+xxx(a)tmobile.de) Running State Change CS_ROUTING (Cur 1 Tot 205) 2017-12-13 10:05:04.668947 [DEBUG] switch_core_state_machine.c:643 (sofia/external/+xxx(a)tmobile.de) State ROUTING 2017-12-13 10:05:04.668947 [DEBUG] mod_sofia.c:143 sofia/external/+xxx(a)tmobile.de SOFIA ROUTING 2017-12-13 10:05:04.668947 [DEBUG] switch_core_state_machine.c:236 sofia/external/+xxx(a)tmobile.de Standard ROUTING 2017-12-13 10:05:04.668947 [INFO] mod_dialplan_xml.c:637 Processing +xxx <+xxx>->50 in context sip.xxx Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->user_exists] continue=true Dialplan: sofia/external/+xxx(a)tmobile.de Regex (PASS) [user_exists] () =~ // break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE EXECUTE sofia/external/+xxx(a)tmobile.de set(user_exists=true) 2017-12-13 10:05:04.668947 [DEBUG] mod_dptools.c:1548 SET sofia/external/+xxx(a)tmobile.de [user_exists]=[true] Dialplan: sofia/external/+xxx(a)tmobile.de Regex (PASS) [user_exists] ${user_exists}(true) =~ /^true$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de Action set(extension_uuid=${user_data ${destination_number}@${domain_name} var extension_uuid}) INLINE EXECUTE sofia/external/+xxx(a)tmobile.de set(extension_uuid=55f069a7-483d-4793-ba37-bb75b3e368ea) 2017-12-13 10:05:04.708841 [DEBUG] mod_dptools.c:1548 SET sofia/external/+xxx(a)tmobile.de [extension_uuid]=[55f069a7-483d-4793-ba37-bb75b3e368ea] Dialplan: sofia/external/+xxx(a)tmobile.de Action set(hold_music=${user_data ${destination_number}@${domain_name} var hold_music}) INLINE EXECUTE sofia/external/+xxx(a)tmobile.de set(hold_music=local_stream://default) 2017-12-13 10:05:04.708841 [DEBUG] mod_dptools.c:1548 SET sofia/external/+xxx(a)tmobile.de [hold_music]=[local_stream://default] Dialplan: sofia/external/+xxx(a)tmobile.de Action set(forward_all_enabled=${user_data ${destination_number}@${domain_name} var forward_all_enabled}) INLINE EXECUTE sofia/external/+xxx(a)tmobile.de set(forward_all_enabled=) 2017-12-13 10:05:04.708841 [DEBUG] mod_dptools.c:1548 SET sofia/external/+xxx(a)tmobile.de [forward_all_enabled]=[UNDEF] Dialplan: sofia/external/+xxx(a)tmobile.de Action set(forward_all_destination=${user_data ${destination_number}@${domain_name} var forward_all_destination}) INLINE EXECUTE sofia/external/+xxx(a)tmobile.de set(forward_all_destination=) 2017-12-13 10:05:04.728848 [DEBUG] mod_dptools.c:1548 SET sofia/external/+xxx(a)tmobile.de [forward_all_destination]=[UNDEF] Dialplan: sofia/external/+xxx(a)tmobile.de Action set(forward_busy_enabled=${user_data ${destination_number}@${domain_name} var forward_busy_enabled}) INLINE EXECUTE sofia/external/+xxx(a)tmobile.de set(forward_busy_enabled=) 2017-12-13 10:05:04.728848 [DEBUG] mod_dptools.c:1548 SET sofia/external/+xxx(a)tmobile.de [forward_busy_enabled]=[UNDEF] Dialplan: sofia/external/+xxx(a)tmobile.de Action set(forward_busy_destination=${user_data ${destination_number}@${domain_name} var forward_busy_destination}) INLINE EXECUTE sofia/external/+xxx(a)tmobile.de set(forward_busy_destination=) 2017-12-13 10:05:04.748842 [DEBUG] mod_dptools.c:1548 SET sofia/external/+xxx(a)tmobile.de [forward_busy_destination]=[UNDEF] Dialplan: sofia/external/+xxx(a)tmobile.de Action set(forward_no_answer_enabled=${user_data ${destination_number}@${domain_name} var forward_no_answer_enabled}) INLINE EXECUTE sofia/external/+xxx(a)tmobile.de set(forward_no_answer_enabled=) 2017-12-13 10:05:04.748842 [DEBUG] mod_dptools.c:1548 SET sofia/external/+xxx(a)tmobile.de [forward_no_answer_enabled]=[UNDEF] Dialplan: sofia/external/+xxx(a)tmobile.de Action set(forward_no_answer_destination=${user_data ${destination_number}@${domain_name} var forward_no_answer_destination}) INLINE EXECUTE sofia/external/+xxx(a)tmobile.de set(forward_no_answer_destination=) 2017-12-13 10:05:04.748842 [DEBUG] mod_dptools.c:1548 SET sofia/external/+xxx(a)tmobile.de [forward_no_answer_destination]=[UNDEF] Dialplan: sofia/external/+xxx(a)tmobile.de Action set(forward_user_not_registered_enabled=${user_data ${destination_number}@${domain_name} var forward_user_not_registered_enabled}) INLINE EXECUTE sofia/external/+xxx(a)tmobile.de set(forward_user_not_registered_enabled=) 2017-12-13 10:05:04.768845 [DEBUG] mod_dptools.c:1548 SET sofia/external/+xxx(a)tmobile.de [forward_user_not_registered_enabled]=[UNDEF] Dialplan: sofia/external/+xxx(a)tmobile.de Action set(forward_user_not_registered_destination=${user_data ${destination_number}@${domain_name} var forward_user_not_registered_destination}) INLINE EXECUTE sofia/external/+xxx(a)tmobile.de set(forward_user_not_registered_destination=) 2017-12-13 10:05:04.768845 [DEBUG] mod_dptools.c:1548 SET sofia/external/+xxx(a)tmobile.de [forward_user_not_registered_destination]=[UNDEF] Dialplan: sofia/external/+xxx(a)tmobile.de Action set(do_not_disturb=${user_data ${destination_number}@${domain_name} var do_not_disturb}) INLINE EXECUTE sofia/external/+xxx(a)tmobile.de set(do_not_disturb=) 2017-12-13 10:05:04.768845 [DEBUG] mod_dptools.c:1548 SET sofia/external/+xxx(a)tmobile.de [do_not_disturb]=[UNDEF] Dialplan: sofia/external/+xxx(a)tmobile.de Action set(call_timeout=${user_data ${destination_number}@${domain_name} var call_timeout}) INLINE EXECUTE sofia/external/+xxx(a)tmobile.de set(call_timeout=30) 2017-12-13 10:05:04.788848 [DEBUG] mod_dptools.c:1548 SET sofia/external/+xxx(a)tmobile.de [call_timeout]=[30] Dialplan: sofia/external/+xxx(a)tmobile.de Action set(missed_call_app=${user_data ${destination_number}@${domain_name} var missed_call_app}) INLINE EXECUTE sofia/external/+xxx(a)tmobile.de set(missed_call_app=) 2017-12-13 10:05:04.788848 [DEBUG] mod_dptools.c:1548 SET sofia/external/+xxx(a)tmobile.de [missed_call_app]=[UNDEF] Dialplan: sofia/external/+xxx(a)tmobile.de Action set(missed_call_data=${user_data ${destination_number}@${domain_name} var missed_call_data}) INLINE EXECUTE sofia/external/+xxx(a)tmobile.de set(missed_call_data=) 2017-12-13 10:05:04.788848 [DEBUG] mod_dptools.c:1548 SET sofia/external/+xxx(a)tmobile.de [missed_call_data]=[UNDEF] Dialplan: sofia/external/+xxx(a)tmobile.de Action set(toll_allow=${user_data ${destination_number}@${domain_name} var toll_allow}) INLINE EXECUTE sofia/external/+xxx(a)tmobile.de set(toll_allow=domestic,international,local) 2017-12-13 10:05:04.808842 [DEBUG] mod_dptools.c:1548 SET sofia/external/+xxx(a)tmobile.de [toll_allow]=[domestic,international,local] Dialplan: sofia/external/+xxx(a)tmobile.de Action set(call_screen_enabled=${user_data ${destination_number}@${domain_name} var call_screen_enabled}) INLINE EXECUTE sofia/external/+xxx(a)tmobile.de set(call_screen_enabled=false) 2017-12-13 10:05:04.808842 [DEBUG] mod_dptools.c:1548 SET sofia/external/+xxx(a)tmobile.de [call_screen_enabled]=[false] Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->call-direction] continue=true Dialplan: sofia/external/+xxx(a)tmobile.de Regex (PASS) [call-direction] ${call_direction}(inbound) =~ /^(inbound|outbound|local)$/ break=never Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->variables] continue=true Dialplan: sofia/external/+xxx(a)tmobile.de Regex (PASS) [variables] () =~ // break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de Action export(origination_callee_id_name=${destination_number}) Dialplan: sofia/external/+xxx(a)tmobile.de Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->user_record] continue=true Dialplan: sofia/external/+xxx(a)tmobile.de Regex (PASS) [user_record] () =~ // break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de Action set(user_record=${user_data ${destination_number}@${domain_name} var user_record}) INLINE EXECUTE sofia/external/+xxx(a)tmobile.de set(user_record=all) 2017-12-13 10:05:04.828844 [DEBUG] mod_dptools.c:1548 SET sofia/external/+xxx(a)tmobile.de [user_record]=[all] Dialplan: sofia/external/+xxx(a)tmobile.de Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE 2017-12-13 10:05:04.848838 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7fe6f032c110 Connected. 2017-12-13 10:05:04.848838 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7fe6f032c110 released. EXECUTE sofia/external/+xxx(a)tmobile.de set(from_user_exists=false) 2017-12-13 10:05:04.848838 [DEBUG] mod_dptools.c:1548 SET sofia/external/+xxx(a)tmobile.de [from_user_exists]=[false] Dialplan: sofia/external/+xxx(a)tmobile.de Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never Dialplan: sofia/external/+xxx(a)tmobile.de Regex (PASS) [user_record] ${user_record}(all) =~ /^all$/ break=never Dialplan: sofia/external/+xxx(a)tmobile.de Action set(record_session=true) INLINE EXECUTE sofia/external/+xxx(a)tmobile.de set(record_session=true) 2017-12-13 10:05:04.848838 [DEBUG] mod_dptools.c:1548 SET sofia/external/+xxx(a)tmobile.de [record_session]=[true] Dialplan: sofia/external/+xxx(a)tmobile.de Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never Dialplan: sofia/external/+xxx(a)tmobile.de Regex (PASS) [user_record] ${call_direction}(inbound) =~ /^inbound$/ break=never Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [user_record] ${user_record}(all) =~ /^inbound$/ break=never Dialplan: sofia/external/+xxx(a)tmobile.de Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^outbound$/ break=never Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [user_record] ${user_record}(all) =~ /^outbound$/ break=never Dialplan: sofia/external/+xxx(a)tmobile.de Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^local$/ break=never Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [user_record] ${user_record}(all) =~ /^local$/ break=never Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [user_record] ${from_user_record}() =~ /^all$/ break=never Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never Dialplan: sofia/external/+xxx(a)tmobile.de Regex (PASS) [user_record] ${call_direction}(inbound) =~ /^inbound$/ break=never Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [user_record] ${from_user_record}() =~ /^inbound$/ break=never Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^outbound$/ break=never Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [user_record] ${from_user_record}() =~ /^outbound$/ break=never Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^local$/ break=never Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [user_record] ${from_user_record}() =~ /^local$/ break=never Dialplan: sofia/external/+xxx(a)tmobile.de Regex (PASS) [user_record] ${record_session}(true) =~ /^true$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de Action export(nolocal:api_on_answer=uuid_record ${uuid} start ${recordings_dir}/${domain_name}/archive/${strftime(%Y)}/${strftime(%b)}/${strftime(%d)}/${uuid}.${record_ext}) Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->redial] continue=true Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [redial] destination_number(50) =~ /^(redial|\*870)$/ break=on-true Dialplan: sofia/external/+xxx(a)tmobile.de Regex (PASS) [redial] () =~ // break=never Dialplan: sofia/external/+xxx(a)tmobile.de Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number}) Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->speed_dial] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [speed_dial] destination_number(50) =~ /^\*0(.*)$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->Telekom_4000733.0d] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [Telekom_4000733.0d] username(+xxx) =~ /50/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->Telekom_9236276.0d] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [Telekom_9236276.0d] username(+xxx) =~ /100/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->Telekom_9236276.0d-fritz] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [Telekom_9236276.0d-fritz] username(+xxx) =~ /1001/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->Telekom_9236277.0d] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [Telekom_9236277.0d] username(+xxx) =~ /10/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->Telekom_9236277.110] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [Telekom_9236277.110] destination_number(50) =~ /^(110)$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->Telekom_9236277.112] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [Telekom_9236277.112] destination_number(50) =~ /^(112)$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->Telekom_9236277.19222] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [Telekom_9236277.19222] destination_number(50) =~ /^(19222)$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->Telekom_9727575.0d] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (PASS) [Telekom_9727575.0d] username(+xxx) =~ /30/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [Telekom_9727575.0d] destination_number(50) =~ /^0(\d*)$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->Telekom_9727577.0d] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [Telekom_9727577.0d] username(+xxx) =~ /20/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->agent_status] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [agent_status] destination_number(50) =~ /^\*22$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->agent_status_id] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [agent_status_id] destination_number(50) =~ /^\*23$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->group-intercept] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [group-intercept] destination_number(50) =~ /^\*8$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->page-extension] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [page-extension] destination_number(50) =~ /^\*8(\d{2,7})$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->eavesdrop] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [eavesdrop] destination_number(50) =~ /^\*33(\d{2,7})$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->call_privacy] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [call_privacy] destination_number(50) =~ /^\*67(\d+)$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->call_return] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [call_return] destination_number(50) =~ /^\*69$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->extension_queue] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [extension_queue] destination_number(50) =~ /^\*800(.*)$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->intercept-ext] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [intercept-ext] destination_number(50) =~ /^\*\*(\d+)$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->intercept-ext-polycom] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [intercept-ext-polycom] destination_number(50) =~ /^\*97(\d+)$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->dx] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [dx] destination_number(50) =~ /^dx$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->att_xfer] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [att_xfer] destination_number(50) =~ /^att_xfer$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->extension-to-voicemail] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (PASS) [extension-to-voicemail] ${user_exists}(true) =~ /^true$/ break=on-false 2017-12-13 10:05:04.848838 [ERR] switch_regex.c:104 COMPILE ERROR: 1 [nothing to repeat][^+xxx$] Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [extension-to-voicemail] username(+xxx) =~ /^+xxx$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->fax] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [fax] destination_number(50) =~ /^100$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->send_to_voicemail] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [send_to_voicemail] destination_number(50) =~ /^\*99(\d{2,10})$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->vmain] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [vmain] destination_number(50) =~ /^vmain$|^\*4000$|^\*98$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->xfer_vm] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [xfer_vm] destination_number(50) =~ /^xfer_vm$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->is_transfer] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [is_transfer] destination_number(50) =~ /^is_transfer$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->vmain_user] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [vmain_user] destination_number(50) =~ /^\*97$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->cf] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [cf] destination_number(50) =~ /^cf$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->delay_echo] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [delay_echo] destination_number(50) =~ /^\*9195$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->echo] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [echo] destination_number(50) =~ /^\*9196$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->is_zrtp_secure] continue=true Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [is_zrtp_secure] ${zrtp_secure_media_confirmed}() =~ /^true$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de ANTI-Action eval(not_secure) Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->milliwatt] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [milliwatt] destination_number(50) =~ /^\*9197$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->is_secure] continue=true Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [is_secure] ${sip_via_protocol}(udp) =~ /tls/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->tone_stream] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [tone_stream] destination_number(50) =~ /^\*9198$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->hold_music] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [hold_music] destination_number(50) =~ /^\*9664$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->recordings] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [recordings] destination_number(50) =~ /^\*(732)$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->directory] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [directory] destination_number(50) =~ /^\*411$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->wake-up] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [wake-up] destination_number(50) =~ /^\*(925)$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->valet_park] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [valet_park] destination_number(50) =~ /^(park\+)?(\*59[0-9][0-9])$/ break=never Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [valet_park] ${sip_h_Referred-By}() =~ /sip:(.*)@.*/ break=never Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [valet_park] destination_number(50) =~ /^(park\+)?(\*59[0-9][0-9])$/ break=never Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [valet_park] destination_number(50) =~ /^(park\+)?(\*59[0-9][0-9])$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->operator] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [operator] destination_number(50) =~ /^0$|^operator$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->operator-forward] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [operator-forward] destination_number(50) =~ /^\*000$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->do-not-disturb] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [do-not-disturb] destination_number(50) =~ /^\*77$/ break=on-true Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [do-not-disturb] destination_number(50) =~ /^\*78$|\*363$/ break=on-true Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [do-not-disturb] destination_number(50) =~ /^\*79$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->call-forward] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [call-forward] destination_number(50) =~ /^\*72$/ break=on-true Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [call-forward] destination_number(50) =~ /^\*73$/ break=on-true Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [call-forward] destination_number(50) =~ /^\*74$/ break=on-true Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->call forward all] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (PASS) [call forward all] ${user_exists}(true) =~ /^true/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [call forward all] ${forward_all_enabled}() =~ /^true/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->follow-me] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [follow-me] destination_number(50) =~ /^\*21$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->clear_sip_auto_answer] continue=true Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [clear_sip_auto_answer] ${click_to_call}() =~ /true/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->talking clock date and time] continue=true Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [talking clock date and time] destination_number(50) =~ /^\*9172$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->talking clock time] continue=true Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [talking clock time] destination_number(50) =~ /^\*9170$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->talking clock date] continue=true Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [talking clock date] destination_number(50) =~ /^\*9171$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->call_screen] continue=true Dialplan: sofia/external/+xxx(a)tmobile.de Regex (FAIL) [call_screen] ${call_screen_enabled}(false) =~ /^true$/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->local_extension] continue=true Dialplan: sofia/external/+xxx(a)tmobile.de Regex (PASS) [local_extension] ${user_exists}(true) =~ /true/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de Action export(dialed_extension=${destination_number}) INLINE EXECUTE sofia/external/+xxx(a)tmobile.de export(dialed_extension=50) 2017-12-13 10:05:04.848838 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [dialed_extension]=[50] Dialplan: sofia/external/+xxx(a)tmobile.de Action limit(hash ${domain_name} ${destination_number} ${limit_max} ${limit_destination}) Dialplan: sofia/external/+xxx(a)tmobile.de Regex (PASS) [local_extension] () =~ // break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de Action set(hangup_after_bridge=true) Dialplan: sofia/external/+xxx(a)tmobile.de Action set(continue_on_fail=true) Dialplan: sofia/external/+xxx(a)tmobile.de Action hash(insert/${domain_name}-call_return/${dialed_extension}/${caller_id_number}) Dialplan: sofia/external/+xxx(a)tmobile.de Action hash(insert/${domain_name}-last_dial_ext/${dialed_extension}/${uuid}) Dialplan: sofia/external/+xxx(a)tmobile.de Action set(called_party_call_group=${user_data(${dialed_extension}@${domain_name} var call_group)}) Dialplan: sofia/external/+xxx(a)tmobile.de Action hash(insert/${domain_name}-last_dial/${called_party_call_group}/${uuid}) Dialplan: sofia/external/+xxx(a)tmobile.de Action set(api_hangup_hook=lua app.lua hangup) Dialplan: sofia/external/+xxx(a)tmobile.de Action export(domain_name=${domain_name}) Dialplan: sofia/external/+xxx(a)tmobile.de Action bridge(user/${destination_number}@${domain_name}) Dialplan: sofia/external/+xxx(a)tmobile.de Action lua(app.lua failure_handler) Dialplan: sofia/external/+xxx(a)tmobile.de parsing [sip.xxx->voicemail] continue=false Dialplan: sofia/external/+xxx(a)tmobile.de Regex (PASS) [voicemail] ${user_exists}(true) =~ /true/ break=on-false Dialplan: sofia/external/+xxx(a)tmobile.de Action answer() Dialplan: sofia/external/+xxx(a)tmobile.de Action sleep(1000) Dialplan: sofia/external/+xxx(a)tmobile.de Action set(voicemail_action=save) Dialplan: sofia/external/+xxx(a)tmobile.de Action set(voicemail_id=${destination_number}) Dialplan: sofia/external/+xxx(a)tmobile.de Action set(voicemail_profile=default) Dialplan: sofia/external/+xxx(a)tmobile.de Action lua(app.lua voicemail) 2017-12-13 10:05:04.848838 [DEBUG] switch_core_state_machine.c:286 (sofia/external/+xxx(a)tmobile.de) State Change CS_ROUTING -> CS_EXECUTE 2017-12-13 10:05:04.848838 [DEBUG] switch_core_state_machine.c:643 (sofia/external/+xxx(a)tmobile.de) State ROUTING going to sleep 2017-12-13 10:05:04.848838 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+xxx(a)tmobile.de) Running State Change CS_EXECUTE (Cur 1 Tot 205) 2017-12-13 10:05:04.848838 [DEBUG] switch_core_state_machine.c:650 (sofia/external/+xxx(a)tmobile.de) State EXECUTE 2017-12-13 10:05:04.848838 [DEBUG] mod_sofia.c:198 sofia/external/+xxx(a)tmobile.de SOFIA EXECUTE 2017-12-13 10:05:04.848838 [DEBUG] switch_core_state_machine.c:328 sofia/external/+xxx(a)tmobile.de Standard EXECUTE EXECUTE sofia/external/+xxx(a)tmobile.de export(origination_callee_id_name=50) 2017-12-13 10:05:04.848838 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [origination_callee_id_name]=[50] EXECUTE sofia/external/+xxx(a)tmobile.de set(RFC2822_DATE=Wed, 13 Dec 2017 10:05:04 +0100) 2017-12-13 10:05:04.848838 [DEBUG] mod_dptools.c:1548 SET sofia/external/+xxx(a)tmobile.de [RFC2822_DATE]=[Wed, 13 Dec 2017 10:05:04 +0100] EXECUTE sofia/external/+xxx(a)tmobile.de export(nolocal:api_on_answer=uuid_record d220a9b4-8c44-49ce-a226-18625641c2ed start /var/lib/freeswitch/recordings/sip.xxx/archive/2017/Dec/13/d220a9b4-8c44-49ce-a226-18625641c2ed.mp3) 2017-12-13 10:05:04.848838 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) (REMOTE ONLY) [api_on_answer]=[uuid_record d220a9b4-8c44-49ce-a226-18625641c2ed start /var/lib/freeswitch/recordings/sip.xxx/archive/2017/Dec/13/d220a9b4-8c44-49ce-a226-18625641c2ed.mp3] EXECUTE sofia/external/+xxx(a)tmobile.de hash(insert/sip.xxx-last_dial/+xxx/50) EXECUTE sofia/external/+xxx(a)tmobile.de eval(not_secure) EXECUTE sofia/external/+xxx(a)tmobile.de limit(hash sip.xxx 50 ) 2017-12-13 10:05:04.848838 [DEBUG] switch_limit.c:126 incr called: sip.xxx_50 max:-1, interval:0 2017-12-13 10:05:04.848838 [DEBUG] mod_hash.c:194 Usage for sip.xxx_50 is now 1 EXECUTE sofia/external/+xxx(a)tmobile.de set(hangup_after_bridge=true) 2017-12-13 10:05:04.848838 [DEBUG] mod_dptools.c:1548 SET sofia/external/+xxx(a)tmobile.de [hangup_after_bridge]=[true] EXECUTE sofia/external/+xxx(a)tmobile.de set(continue_on_fail=true) 2017-12-13 10:05:04.848838 [DEBUG] mod_dptools.c:1548 SET sofia/external/+xxx(a)tmobile.de [continue_on_fail]=[true] EXECUTE sofia/external/+xxx(a)tmobile.de hash(insert/sip.xxx-call_return/50/+xxx) EXECUTE sofia/external/+xxx(a)tmobile.de hash(insert/sip.xxx-last_dial_ext/50/d220a9b4-8c44-49ce-a226-18625641c2ed) EXECUTE sofia/external/+xxx(a)tmobile.de set(called_party_call_group=) 2017-12-13 10:05:04.848838 [DEBUG] mod_dptools.c:1548 SET sofia/external/+xxx(a)tmobile.de [called_party_call_group]=[UNDEF] EXECUTE sofia/external/+xxx(a)tmobile.de hash(insert/sip.xxx-last_dial//d220a9b4-8c44-49ce-a226-18625641c2ed) EXECUTE sofia/external/+xxx(a)tmobile.de set(api_hangup_hook=lua app.lua hangup) 2017-12-13 10:05:04.848838 [DEBUG] mod_dptools.c:1548 SET sofia/external/+xxx(a)tmobile.de [api_hangup_hook]=[lua app.lua hangup] EXECUTE sofia/external/+xxx(a)tmobile.de export(domain_name=sip.xxx) 2017-12-13 10:05:04.848838 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [domain_name]=[sip.xxx] EXECUTE sofia/external/+xxx(a)tmobile.de bridge(user/50(a)sip.xxx) 2017-12-13 10:05:04.848838 [DEBUG] switch_channel.c:1250 sofia/external/+xxx(a)tmobile.de EXPORTING[export_vars] [dialed_extension]=[50] to event 2017-12-13 10:05:04.848838 [DEBUG] switch_channel.c:1250 sofia/external/+xxx(a)tmobile.de EXPORTING[export_vars] [origination_callee_id_name]=[50] to event 2017-12-13 10:05:04.848838 [DEBUG] switch_channel.c:1250 sofia/external/+xxx(a)tmobile.de EXPORTING[export_vars] [api_on_answer]=[uuid_record d220a9b4-8c44-49ce-a226-18625641c2ed start /var/lib/freeswitch/recordings/sip.xxx/archive/2017/Dec/13/d220a9b4-8c44-49ce-a226-18625641c2ed.mp3] to event 2017-12-13 10:05:04.848838 [DEBUG] switch_channel.c:1250 sofia/external/+xxx(a)tmobile.de EXPORTING[export_vars] [domain_name]=[sip.xxx] to event 2017-12-13 10:05:04.848838 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2017-12-13 10:05:04.848838 [DEBUG] switch_channel.c:1250 sofia/external/+xxx(a)tmobile.de EXPORTING[export_vars] [dialed_extension]=[50] to event 2017-12-13 10:05:04.848838 [DEBUG] switch_channel.c:1250 sofia/external/+xxx(a)tmobile.de EXPORTING[export_vars] [origination_callee_id_name]=[50] to event 2017-12-13 10:05:04.848838 [DEBUG] switch_channel.c:1250 sofia/external/+xxx(a)tmobile.de EXPORTING[export_vars] [api_on_answer]=[uuid_record d220a9b4-8c44-49ce-a226-18625641c2ed start /var/lib/freeswitch/recordings/sip.xxx/archive/2017/Dec/13/d220a9b4-8c44-49ce-a226-18625641c2ed.mp3] to event 2017-12-13 10:05:04.848838 [DEBUG] switch_channel.c:1250 sofia/external/+xxx(a)tmobile.de EXPORTING[export_vars] [domain_name]=[sip.xxx] to event 2017-12-13 10:05:04.848838 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables 2017-12-13 10:05:04.848838 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/50@10.1.0.5:5060 [b30aff2d-f7a1-492f-af42-d10814d2a663] 2017-12-13 10:05:04.848838 [DEBUG] mod_sofia.c:4819 (sofia/internal/50@10.1.0.5:5060) State Change CS_NEW -> CS_INIT 2017-12-13 10:05:04.848838 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/50@10.1.0.5:5060) Running State Change CS_INIT (Cur 2 Tot 206) 2017-12-13 10:05:04.848838 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/50@10.1.0.5:5060) State INIT 2017-12-13 10:05:04.848838 [DEBUG] mod_sofia.c:90 sofia/internal/50@10.1.0.5:5060 SOFIA INIT nua_common.c:108 nh_create_handle() nua: nh_create_handle: entering nua.c:342 nua_handle_bind() nua: nua_handle_bind: entering 2017-12-13 10:05:04.848838 [DEBUG] sofia_glue.c:1295 sofia/internal/50@10.1.0.5:5060 sending invite version: 1.6.19 -36-7a77e0b 64bit Local SDP: v=0 o=FreeSWITCH 1513134686 1513134687 IN IP4 10.1.0.20 s=FreeSWITCH c=IN IP4 10.1.0.20 t=0 0 m=audio 21218 RTP/AVP 9 8 0 3 101 13 a=rtpmap:9 G722/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:0 PCMU/8000 a=rtpmap:3 GSM/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=rtpmap:13 CN/8000 a=ptime:20 a=sendrecv nua.c:633 nua_invite() nua: nua_invite: entering nua_stack.c:529 nua_signal() nua(0x7fe6f0083660): sent signal r_invite 2017-12-13 10:05:04.848838 [DEBUG] switch_core_state_machine.c:40 sofia/internal/50@10.1.0.5:5060 Standard INIT 2017-12-13 10:05:04.848838 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/50@10.1.0.5:5060) State Change CS_INIT -> CS_ROUTING 2017-12-13 10:05:04.848838 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/50@10.1.0.5:5060) State INIT going to sleep nua_stack.c:569 nua_stack_signal() nua(0x7fe6f0083660): recv signal r_invite nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering soa.c:280 soa_clone() soa_clone(static::0x7fe6c8001930, 0x7fe6c8001130, 0x7fe6f0083660) called soa.c:403 soa_set_params() soa_set_params(static::0x7fe6c800dcc0, ...) called soa.c:403 soa_set_params() soa_set_params(static::0x7fe6c800dcc0, ...) called soa.c:1052 soa_set_user_sdp() soa_set_user_sdp(static::0x7fe6c800dcc0, (nil), 0x7fe6f0082fbf, -1) called soa.c:890 soa_set_capability_sdp() soa_set_capability_sdp(static::0x7fe6c800dcc0, (nil), 0x7fe6f0082fbf, -1) called nua_dialog.c:338 nua_dialog_usage_add() nua(0x7fe6f0083660): adding session usage nta.c:4417 nta_leg_tcreate() nta_leg_tcreate(0x7fe6c8012930) soa.c:1302 soa_init_offer_answer() soa_init_offer_answer(static::0x7fe6c800dcc0) called soa.c:1426 soa_generate_offer() soa_generate_offer(static::0x7fe6c800dcc0, 0) called soa_static.c:1148 offer_answer_step() soa_static_offer_answer_action(0x7fe6c800dcc0, soa_generate_offer): called soa_static.c:1189 offer_answer_step() soa_static(0x7fe6c800dcc0, soa_generate_offer): generating local description soa_static.c:1217 offer_answer_step() soa_static(0x7fe6c800dcc0, soa_generate_offer): upgrade with local description soa_static.c:1029 soa_sdp_mode_set() soa_sdp_mode_set(0x7fe70403aa70, (nil), ""): called soa_static.c:1446 offer_answer_step() soa_static(0x7fe6c800dcc0, soa_generate_offer): storing local description soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::0x7fe6c800dcc0, [(nil)], [0x7fe70403cbf8], [0x7fe70403cbf4]) called nta.c:2665 nta_tpn_by_url() nta: selecting scheme sip tport.c:3257 tport_tsend() tport_tsend(0x7fe6c8004420) tpn = */10.1.0.5:5060 tport.c:4046 tport_resolve() tport_resolve addrinfo = 10.1.0.5:5060 tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7fe6c8004420): not found by name */10.1.0.5:5060 2017-12-13 10:05:04.848838 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/50@10.1.0.5:5060) Running State Change CS_ROUTING (Cur 2 Tot 206) tport.c:3594 tport_vsend() tport_vsend(0x7fe6c8004420): 1235 bytes of 1235 to udp/10.1.0.5:5060 tport.c:3492 tport_send_msg() tport_vsend returned 1235 nta.c:8304 outgoing_send() nta: sent INVITE (116241888) to */10.1.0.5:5060 tport.c:4160 tport_pend() tport_pend(0x7fe6c8004420): pending 0x7fe6c800f190 for udp/10.1.0.20:5060 (already 0) nta.c:1348 set_timeout() nta: timer shortened to 1000 ms nua_session.c:4139 signal_call_state_change() nua(0x7fe6f0083660): call state changed: init -> calling, sent offer soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::0x7fe6c800dcc0, [0x7fe70403cbd8], [0x7fe70403cbe0], [(nil)]) called nua_stack.c:269 nua_stack_event() nua(0x7fe6f0083660): event i_state INVITE sent nua_stack.c:359 nua_application_event() nua: nua_application_event: entering 2017-12-13 10:05:04.848838 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/50@10.1.0.5:5060) State ROUTING 2017-12-13 10:05:04.848838 [DEBUG] mod_sofia.c:143 sofia/internal/50@10.1.0.5:5060 SOFIA ROUTING 2017-12-13 10:05:04.848838 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/50@10.1.0.5:5060) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2017-12-13 10:05:04.848838 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/50@10.1.0.5:5060) State ROUTING going to sleep 2017-12-13 10:05:04.848838 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/50@10.1.0.5:5060) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 206) nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering 2017-12-13 10:05:04.848838 [DEBUG] sofia.c:7084 Channel sofia/internal/50@10.1.0.5:5060 entering state [calling][0] nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering 2017-12-13 10:05:04.848838 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/50@10.1.0.5:5060) State CONSUME_MEDIA 2017-12-13 10:05:04.848838 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/50@10.1.0.5:5060) State CONSUME_MEDIA going to sleep nta.c:1296 agent_timer() nta: timer set next to 4458 ms tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(0x7fe6c8004420): events IN tport.c:2864 tport_recv_event() tport_recv_event(0x7fe6c8004420) tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x7fe6c8004420) msg 0x7fe6c8013890 from (udp/10.1.0.20:5060) has 377 bytes, veclen = 1 tport.c:3023 tport_deliver() tport_deliver(0x7fe6c8004420): msg 0x7fe6c8013890 (377 bytes) from udp/10.1.0.5:5060/sip next=(nil) nta.c:3299 agent_recv_response() nta: received 100 Trying for INVITE (116241888) nta.c:3366 agent_recv_response() nta: 100 Trying is going to a transaction nta.c:9564 outgoing_estimate_delay() nta_outgoing: RTT is 35.308 ms tport.c:4222 tport_release() tport_release(0x7fe6c8004420): 0x7fe6c800f190 by 0x7fe6c8015010 with 0x7fe6c8013890 (preliminary) tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(0x7fe6c8004420): events IN tport.c:2864 tport_recv_event() tport_recv_event(0x7fe6c8004420) tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x7fe6c8004420) msg 0x7fe6c8013890 from (udp/10.1.0.20:5060) has 416 bytes, veclen = 1 tport.c:3023 tport_deliver() tport_deliver(0x7fe6c8004420): msg 0x7fe6c8013890 (416 bytes) from udp/10.1.0.5:5060/sip next=(nil) nta.c:3299 agent_recv_response() nta: received 180 Ringing for INVITE (116241888) nta.c:3366 agent_recv_response() nta: 180 Ringing is going to a transaction tport.c:4222 tport_release() tport_release(0x7fe6c8004420): 0x7fe6c800f190 by 0x7fe6c8015010 with 0x7fe6c8013890 (preliminary) nua_stack.c:271 nua_stack_event() nua(0x7fe6f0083660): event r_invite 180 Ringing nua_session.c:4139 signal_call_state_change() nua(0x7fe6f0083660): call state changed: calling -> proceeding nua_stack.c:271 nua_stack_event() nua(0x7fe6f0083660): event i_state 180 Ringing nua_stack.c:359 nua_application_event() nua: nua_application_event: entering nua_stack.c:359 nua_application_event() nua: nua_application_event: entering nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering 2017-12-13 10:05:04.988827 [DEBUG] sofia.c:7084 Channel sofia/internal/50@10.1.0.5:5060 entering state [proceeding][180] 2017-12-13 10:05:04.988827 [NOTICE] sofia.c:7192 Ring-Ready sofia/internal/50@10.1.0.5:5060! 2017-12-13 10:05:04.988827 [DEBUG] switch_channel.c:3346 (sofia/internal/50@10.1.0.5:5060) Callstate Change DOWN -> RINGING nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering 2017-12-13 10:05:05.008827 [INFO] switch_ivr_originate.c:1215 Sending early media 2017-12-13 10:05:05.008827 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/external/+xxx(a)tmobile.de] 10.1.0.20 port 29612 -> 217.0.4.164 port 25732 codec: 9 ms: 20 2017-12-13 10:05:05.008827 [DEBUG] switch_rtp.c:4111 Starting timer [soft] 160 bytes per 20ms 2017-12-13 10:05:05.028830 [DEBUG] switch_core_media.c:7179 sofia/external/+xxx(a)tmobile.de Set 2833 dtmf send payload to 100 2017-12-13 10:05:05.028830 [DEBUG] switch_core_media.c:7186 sofia/external/+xxx(a)tmobile.de Set 2833 dtmf receive payload to 100 2017-12-13 10:05:05.028830 [DEBUG] switch_core_media.c:7209 sofia/external/+xxx(a)tmobile.de Set rtp dtmf delay to 40 2017-12-13 10:05:05.028830 [DEBUG] mod_sofia.c:2364 Ring SDP: v=0 o=FreeSWITCH 1513126293 1513126294 IN IP4 87.138.220.32 s=FreeSWITCH c=IN IP4 87.138.220.32 t=0 0 m=audio 29612 RTP/AVP 9 100 a=rtpmap:9 G722/8000 a=rtpmap:100 telephone-event/8000 a=fmtp:100 0-16 a=ptime:20 a=sendrecv 2017-12-13 10:05:05.028830 [NOTICE] mod_sofia.c:2367 Pre-Answer sofia/external/+xxx(a)tmobile.de! 2017-12-13 10:05:05.028830 [DEBUG] switch_channel.c:3474 (sofia/external/+xxx(a)tmobile.de) Callstate Change RINGING -> EARLY nua.c:879 nua_respond() nua: nua_respond: entering nua_stack.c:529 nua_signal() nua(0x7fe6d8059c20): sent signal r_respond 2017-12-13 10:05:05.028830 [DEBUG] switch_ivr_originate.c:1273 Raw Codec Activation Success L16@16000hz 1 channel 20ms nua_stack.c:573 nua_stack_signal() nua(0x7fe6d8059c20): recv signal r_respond 183 Session Progress nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering 2017-12-13 10:05:05.028830 [DEBUG] switch_core_codec.c:223 sofia/external/+xxx(a)tmobile.de Push codec L16:100 soa.c:403 soa_set_params() soa_set_params(static::0x7fe6d805e1a0, ...) called soa.c:1052 soa_set_user_sdp() soa_set_user_sdp(static::0x7fe6d805e1a0, (nil), 0x7fe6f010e30c, -1) called soa.c:890 soa_set_capability_sdp() soa_set_capability_sdp(static::0x7fe6d805e1a0, (nil), 0x7fe6f010e30c, -1) called nua_session.c:2320 nua_invite_server_respond() nua: nua_invite_server_respond: entering soa.c:1515 soa_generate_answer() soa_generate_answer(static::0x7fe6d805e1a0) called 2017-12-13 10:05:05.028830 [DEBUG] switch_ivr_originate.c:1342 Play Ringback Tone [%(1000,4000,425)] soa_static.c:1148 offer_answer_step() soa_static_offer_answer_action(0x7fe6d805e1a0, soa_generate_answer): called soa_static.c:1189 offer_answer_step() soa_static(0x7fe6d805e1a0, soa_generate_answer): generating local description soa_static.c:1230 offer_answer_step() soa_static(0x7fe6d805e1a0, soa_generate_answer): upgrade with remote description soa_static.c:1029 soa_sdp_mode_set() soa_sdp_mode_set(0x7fe7040b6ab0, 0x7fe6d8059f10, ""): called soa_static.c:1446 offer_answer_step() soa_static(0x7fe6d805e1a0, soa_generate_answer): storing local description soa.c:1730 soa_activate() soa_activate(static::0x7fe6d805e1a0, (nil)) called soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::0x7fe6d805e1a0, [(nil)], [0x7fe7040b8c38], [0x7fe7040b8c34]) called tport.c:3257 tport_tsend() tport_tsend(0x7fe6d8004660) tpn = UDP/217.0.23.100:5060 tport.c:4046 tport_resolve() tport_resolve addrinfo = 217.0.23.100:5060 tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7fe6d8004660): not found by name UDP/217.0.23.100:5060 tport.c:3594 tport_vsend() tport_vsend(0x7fe6d8004660): 1080 bytes of 1080 to udp/217.0.23.100:5060 tport.c:3492 tport_send_msg() tport_vsend returned 1080 nta.c:6791 incoming_reply() nta: sent 183 Session Progress for INVITE (1) nua_session.c:4139 signal_call_state_change() nua(0x7fe6d8059c20): call state changed: received -> early, sent answer soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::0x7fe6d805e1a0, [0x7fe7040b8ce8], [0x7fe7040b8cf0], [(nil)]) called soa.c:616 soa_get_params() soa_get_params(static::0x7fe6d805e1a0, ...) called nua_stack.c:271 nua_stack_event() nua(0x7fe6d8059c20): event i_state 183 Session Progress nua_stack.c:359 nua_application_event() nua: nua_application_event: entering nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering 2017-12-13 10:05:05.048828 [DEBUG] sofia.c:7084 Channel sofia/external/+xxx(a)tmobile.de entering state [early][183] nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering 2017-12-13 10:05:05.448891 [DEBUG] switch_rtp.c:7271 Correct audio ip/port confirmed. nta.c:1296 agent_timer() nta: timer set next to 14237 ms nta.c:9101 outgoing_timer_dk() nta: timer K fired, terminate BYE (116241861) nta.c:8799 outgoing_reclaim_queued() outgoing_reclaim_all((nil), (nil), 0x7fe7040b8d40) nta.c:8929 _nta_outgoing_timer() nta_outgoing_timer: 0/0 resent, 0/0 tout, 1/1 term, 1/1 free nta.c:1296 agent_timer() nta: timer set next to 55697 ms tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(0x7fe6c8004420): events IN tport.c:2864 tport_recv_event() tport_recv_event(0x7fe6c8004420) tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x7fe6c8004420) msg 0x7fe6c80178a0 from (udp/10.1.0.20:5060) has 687 bytes, veclen = 1 tport.c:3023 tport_deliver() tport_deliver(0x7fe6c8004420): msg 0x7fe6c80178a0 (687 bytes) from udp/10.1.0.5:5060/sip next=(nil) nta.c:3299 agent_recv_response() nta: received 200 OK for INVITE (116241888) nta.c:3366 agent_recv_response() nta: 200 OK is going to a transaction tport.c:4222 tport_release() tport_release(0x7fe6c8004420): 0x7fe6c800f190 by 0x7fe6c8015010 with 0x7fe6c80178a0 soa.c:1171 soa_set_remote_sdp() soa_set_remote_sdp(static::0x7fe6c800dcc0, (nil), 0x7fe6c8018085, 186) called soa.c:1595 soa_process_answer() soa_process_answer(static::0x7fe6c800dcc0) called soa_static.c:1148 offer_answer_step() soa_static_offer_answer_action(0x7fe6c800dcc0, soa_process_answer): called soa_static.c:1029 soa_sdp_mode_set() soa_sdp_mode_set(0x7fe6c80144e0, 0x7fe6c8013890, ""): called soa_static.c:1304 offer_answer_step() soa_static(0x7fe6c800dcc0, soa_process_answer): upgrade codecs with remote description soa_static.c:1446 offer_answer_step() soa_static(0x7fe6c800dcc0, soa_process_answer): storing local description soa.c:1730 soa_activate() soa_activate(static::0x7fe6c800dcc0, (nil)) called nua_session.c:988 nua_session_client_response() nua(0x7fe6f0083660): INVITE: processed SDP answer in 200 OK nua_stack.c:271 nua_stack_event() nua(0x7fe6f0083660): event r_invite 200 OK nua_session.c:4139 signal_call_state_change() nua(0x7fe6f0083660): call state changed: proceeding -> completing, received answer soa.c:1098 soa_get_remote_sdp() soa_get_remote_sdp(static::0x7fe6c800dcc0, [0x7fe70403c5e8], [0x7fe70403c5f0], [(nil)]) called soa.c:616 soa_get_params() soa_get_params(static::0x7fe6c800dcc0, ...) called nua_stack.c:271 nua_stack_event() nua(0x7fe6f0083660): event i_state 200 OK nua_stack.c:359 nua_application_event() nua: nua_application_event: entering nua_stack.c:359 nua_application_event() nua: nua_application_event: entering nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering 2017-12-13 10:05:15.288844 [DEBUG] sofia.c:7084 Channel sofia/internal/50@10.1.0.5:5060 entering state [completing][200] 2017-12-13 10:05:15.288844 [DEBUG] sofia.c:7094 Remote SDP: v=0 o=50 5016 49 IN IP4 10.1.0.5 s=Mapping c=IN IP4 10.1.0.5 t=0 0 m=audio 5016 RTP/AVP 9 101 a=rtpmap:9 G722/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 nua.c:639 nua_ack() nua: nua_ack: entering nua_stack.c:529 nua_signal() nua(0x7fe6f0083660): sent signal r_ack nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering nua_stack.c:569 nua_stack_signal() nua(0x7fe6f0083660): recv signal r_ack nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering soa.c:403 soa_set_params() soa_set_params(static::0x7fe6c800dcc0, ...) called soa.c:1730 soa_activate() soa_activate(static::0x7fe6c800dcc0, (nil)) called nta.c:2665 nta_tpn_by_url() nta: selecting scheme sip tport.c:3257 tport_tsend() tport_tsend(0x7fe6c8004420) tpn = */10.1.0.5:5060 tport.c:4046 tport_resolve() tport_resolve addrinfo = 10.1.0.5:5060 tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7fe6c8004420): not found by name */10.1.0.5:5060 tport.c:3594 tport_vsend() tport_vsend(0x7fe6c8004420): 371 bytes of 371 to udp/10.1.0.5:5060 tport.c:3492 tport_send_msg() tport_vsend returned 371 nta.c:8304 outgoing_send() nta: sent ACK (116241888) to */10.1.0.5:5060 nua_session.c:4139 signal_call_state_change() nua(0x7fe6f0083660): call state changed: completing -> ready nua_stack.c:271 nua_stack_event() nua(0x7fe6f0083660): event i_state 200 ACK sent nua_stack.c:271 nua_stack_event() nua(0x7fe6f0083660): event i_active 200 Call active nua_stack.c:359 nua_application_event() nua: nua_application_event: entering nua_stack.c:359 nua_application_event() nua: nua_application_event: entering nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering 2017-12-13 10:05:15.288844 [DEBUG] sofia.c:7084 Channel sofia/internal/50@10.1.0.5:5060 entering state [ready][200] 2017-12-13 10:05:15.288844 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1] 2017-12-13 10:05:15.288844 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match 2017-12-13 10:05:15.288844 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 2017-12-13 10:05:15.288844 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 2017-12-13 10:05:15.288844 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[GSM:3:8000:20:13200:1] 2017-12-13 10:05:15.288844 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000 2017-12-13 10:05:15.288844 [DEBUG] switch_core_media.c:3061 Set Codec sofia/internal/50@10.1.0.5:5060 G722/8000 20 ms 160 samples 64000 bits 1 channels 2017-12-13 10:05:15.288844 [DEBUG] switch_core_codec.c:111 sofia/internal/50@10.1.0.5:5060 Original read codec set to G722:9 2017-12-13 10:05:15.288844 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000 2017-12-13 10:05:15.288844 [DEBUG] switch_core_media.c:4767 sofia/internal/50@10.1.0.5:5060 Set 2833 dtmf send payload to 101 recv payload to 101 2017-12-13 10:05:15.288844 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/internal/50@10.1.0.5:5060] 10.1.0.20 port 21218 -> 10.1.0.5 port 5016 codec: 9 ms: 20 2017-12-13 10:05:15.288844 [DEBUG] switch_rtp.c:4111 Starting timer [soft] 160 bytes per 20ms 2017-12-13 10:05:15.288844 [DEBUG] switch_core_media.c:7179 sofia/internal/50@10.1.0.5:5060 Set 2833 dtmf send payload to 101 2017-12-13 10:05:15.288844 [DEBUG] switch_core_media.c:7186 sofia/internal/50@10.1.0.5:5060 Set 2833 dtmf receive payload to 101 2017-12-13 10:05:15.288844 [DEBUG] switch_core_media.c:7209 sofia/internal/50@10.1.0.5:5060 Set rtp dtmf delay to 40 2017-12-13 10:05:15.288844 [NOTICE] sofia.c:8218 Channel [sofia/internal/50@10.1.0.5:5060] has been answered 2017-12-13 10:05:15.288844 [DEBUG] switch_channel.c:3571 sofia/internal/50@10.1.0.5:5060 process uuid_record d220a9b4-8c44-49ce-a226-18625641c2ed start /var/lib/freeswitch/recordings/sip.xxx/archive/2017/Dec/13/d220a9b4-8c44-49ce-a226-18625641c2ed.mp3: uuid_record(d220a9b4-8c44-49ce-a226-18625641c2ed start /var/lib/freeswitch/recordings/sip.xxx/archive/2017/Dec/13/d220a9b4-8c44-49ce-a226-18625641c2ed.mp3) 2017-12-13 10:05:15.308840 [DEBUG] switch_core_codec.c:248 sofia/external/+xxx(a)tmobile.de Restore previous codec G722:9. 2017-12-13 10:05:15.308840 [DEBUG] switch_core_media.c:6861 Audio params are unchanged for sofia/external/+xxx(a)tmobile.de. 2017-12-13 10:05:15.308840 [DEBUG] mod_sofia.c:850 Local SDP sofia/external/+xxx(a)tmobile.de: v=0 o=FreeSWITCH 1513126293 1513126295 IN IP4 87.138.220.32 s=FreeSWITCH c=IN IP4 87.138.220.32 t=0 0 m=audio 29612 RTP/AVP 9 100 a=rtpmap:9 G722/8000 a=rtpmap:100 telephone-event/8000 a=fmtp:100 0-16 a=ptime:20 a=sendrecv nua.c:879 nua_respond() nua: nua_respond: entering nua_stack.c:573 nua_stack_signal() nua(0x7fe6d8059c20): recv signal r_respond 200 OK nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering nua_stack.c:529 nua_signal() nua(0x7fe6d8059c20): sent signal r_respond soa.c:403 soa_set_params() soa_set_params(static::0x7fe6d805e1a0, ...) called soa.c:1052 soa_set_user_sdp() soa_set_user_sdp(static::0x7fe6d805e1a0, (nil), 0x7fe6f00854ea, -1) called nua_session.c:2320 nua_invite_server_respond() nua: nua_invite_server_respond: entering soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::0x7fe6d805e1a0, [(nil)], [0x7fe7040b8c38], [0x7fe7040b8c34]) called tport.c:3257 tport_tsend() tport_tsend(0x7fe6d8004660) tpn = UDP/217.0.23.100:5060 tport.c:4046 tport_resolve() tport_resolve addrinfo = 217.0.23.100:5060 tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7fe6d8004660): not found by name UDP/217.0.23.100:5060 tport.c:3594 tport_vsend() tport_vsend(0x7fe6d8004660): 1074 bytes of 1074 to udp/217.0.23.100:5060 tport.c:3492 tport_send_msg() tport_vsend returned 1074 nta.c:6791 incoming_reply() nta: sent 200 OK for INVITE (1) nta.c:1348 set_timeout() nta: timer shortened to 500 ms nua_session.c:4139 signal_call_state_change() nua(0x7fe6d8059c20): call state changed: early -> completed, sent answer soa.c:1270 soa_get_local_sdp() soa_get_local_sdp(static::0x7fe6d805e1a0, [0x7fe7040b8ce8], [0x7fe7040b8cf0], [(nil)]) called soa.c:616 soa_get_params() soa_get_params(static::0x7fe6d805e1a0, ...) called nua_stack.c:271 nua_stack_event() nua(0x7fe6d8059c20): event i_state 200 OK nua_stack.c:359 nua_application_event() nua: nua_application_event: entering 2017-12-13 10:05:15.308840 [NOTICE] switch_ivr_originate.c:3647 Channel [sofia/external/+xxx(a)tmobile.de] has been answered 2017-12-13 10:05:15.308840 [DEBUG] switch_core_media_bug.c:945 Attaching BUG to sofia/external/+xxx(a)tmobile.de 2017-12-13 10:05:15.308840 [DEBUG] switch_channel.c:3773 (sofia/internal/50@10.1.0.5:5060) Callstate Change RINGING -> ACTIVE 2017-12-13 10:05:15.308840 [DEBUG] switch_channel.c:3773 (sofia/external/+xxx(a)tmobile.de) Callstate Change EARLY -> ACTIVE nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering 2017-12-13 10:05:15.308840 [DEBUG] sofia.c:7084 Channel sofia/external/+xxx(a)tmobile.de entering state [completed][200] nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering 2017-12-13 10:05:15.308840 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/internal/50@10.1.0.5:5060] nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering 2017-12-13 10:05:15.308840 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/internal/50@10.1.0.5:5060] 2017-12-13 10:05:15.308840 [DEBUG] switch_ivr_bridge.c:1614 (sofia/internal/50@10.1.0.5:5060) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA 2017-12-13 10:05:15.308840 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/50@10.1.0.5:5060) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 206) 2017-12-13 10:05:15.308840 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/50@10.1.0.5:5060) State EXCHANGE_MEDIA 2017-12-13 10:05:15.308840 [DEBUG] mod_sofia.c:631 SOFIA EXCHANGE_MEDIA 2017-12-13 10:05:15.328842 [DEBUG] switch_ivr_async.c:1500 No silence detection configured; assuming start of speech 2017-12-13 10:05:15.348847 [DEBUG] switch_rtp.c:7271 Correct audio ip/port confirmed. 2017-12-13 10:05:15.348847 [DEBUG] switch_core_io.c:448 Setting BUG Codec G722:9 2017-12-13 10:05:15.408848 [DEBUG] switch_rtp.c:7271 Correct audio ip/port confirmed. nta.c:6996 _nta_incoming_timer() nta: timer G fired, retransmitting 200 reply tport.c:3257 tport_tsend() tport_tsend(0x7fe6d8004660) tpn = UDP/217.0.23.100:5060 tport.c:4046 tport_resolve() tport_resolve addrinfo = 217.0.23.100:5060 tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7fe6d8004660): not found by name UDP/217.0.23.100:5060 tport.c:3594 tport_vsend() tport_vsend(0x7fe6d8004660): 1074 bytes of 1074 to udp/217.0.23.100:5060 tport.c:3492 tport_send_msg() tport_vsend returned 1074 nta.c:7188 _nta_incoming_timer() nta_incoming_timer: 1/1 resent, 0/1 tout, 0/0 term, 0/1 free nta.c:1296 agent_timer() nta: timer set next to 1000 ms tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(0x7fe6d8004660): events IN tport.c:2864 tport_recv_event() tport_recv_event(0x7fe6d8004660) tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x7fe6d8004660) msg 0x7fe6d8061980 from (udp/10.1.0.20:5080) has 550 bytes, veclen = 1 tport.c:3023 tport_deliver() tport_deliver(0x7fe6d8004660): msg 0x7fe6d8061980 (550 bytes) from udp/217.0.23.100:5080/sip next=(nil) nta.c:2880 agent_recv_request() nta: received ACK sip:+4991314000733@87.138.220.32:5080;transport=udp SIP/2.0 (CSeq 1) nta.c:3019 agent_recv_request() nta: ACK (1) is going to INVITE (1) nua_session.c:2569 process_ack_or_cancel() nua: process_ack_or_cancel: entering soa.c:1214 soa_clear_remote_sdp() soa_clear_remote_sdp(static::0x7fe6d805e1a0) called nua_stack.c:271 nua_stack_event() nua(0x7fe6d8059c20): event i_ack 200 OK nua_session.c:4139 signal_call_state_change() nua(0x7fe6d8059c20): call state changed: completed -> ready nua_stack.c:271 nua_stack_event() nua(0x7fe6d8059c20): event i_state 200 OK nua_stack.c:271 nua_stack_event() nua(0x7fe6d8059c20): event i_active 200 Call active nua_stack.c:359 nua_application_event() nua: nua_application_event: entering nua_dialog.c:564 nua_dialog_usage_set_refresh_range() nua(): refresh session after 1768 seconds (in [1768..1768]) nua_stack.c:359 nua_application_event() nua: nua_application_event: entering nua_stack.c:359 nua_application_event() nua: nua_application_event: entering nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering 2017-12-13 10:05:15.908861 [DEBUG] sofia.c:7084 Channel sofia/external/+xxx(a)tmobile.de entering state [ready][200] nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering nta.c:1296 agent_timer() nta: timer set next to 4099 ms 2017-12-13 10:05:17.368873 [INFO] mod_shout.c:332 LAME 3.99.5 64bits (http://lame.sf.net) 2017-12-13 10:05:17.368873 [INFO] mod_shout.c:332 polyphase lowpass filter disabled nta.c:7159 _nta_incoming_timer() nta: timer J fired, terminate 401 response nta.c:5825 incoming_reclaim_queued() incoming_reclaim_all((nil), (nil), 0x7fe70403cc60) nta.c:7188 _nta_incoming_timer() nta_incoming_timer: 0/0 resent, 0/0 tout, 1/3 term, 1/3 free nta.c:1296 agent_timer() nta: timer set next to 37 ms nta.c:7159 _nta_incoming_timer() nta: timer J fired, terminate 200 response nta.c:5825 incoming_reclaim_queued() incoming_reclaim_all((nil), (nil), 0x7fe70403cc60) nta.c:7188 _nta_incoming_timer() nta_incoming_timer: 0/0 resent, 0/0 tout, 1/2 term, 1/2 free nta.c:1296 agent_timer() nta: timer set next to 15997 ms nta.c:7134 _nta_incoming_timer() nta: timer I fired, terminate 200 response nta.c:5825 incoming_reclaim_queued() incoming_reclaim_all((nil), (nil), 0x7fe7040b8c60) nta.c:7188 _nta_incoming_timer() nta_incoming_timer: 0/0 resent, 0/0 tout, 1/1 term, 1/1 free nta.c:1289 agent_timer() nta: timer not set tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(0x7fe6c8004420): events IN tport.c:2864 tport_recv_event() tport_recv_event(0x7fe6c8004420) tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x7fe6c8004420) msg 0x7fe6c800d4f0 from (udp/10.1.0.20:5060) has 463 bytes, veclen = 1 tport.c:3023 tport_deliver() tport_deliver(0x7fe6c8004420): msg 0x7fe6c800d4f0 (463 bytes) from udp/10.1.0.4:5060/sip next=(nil) nta.c:2880 agent_recv_request() nta: received REGISTER sip:sip.xxx SIP/2.0 (CSeq 10060) nta.c:3085 agent_recv_request() nta: REGISTER (10060) going to a default leg nua_server.c:102 nua_stack_process_request() nua: nua_stack_process_request: entering nua_stack.c:899 nh_create() nua: nh_create: entering nua_common.c:108 nh_create_handle() nua: nh_create_handle: entering nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering soa.c:280 soa_clone() soa_clone(static::0x7fe6c8001930, 0x7fe6c8001130, 0x7fe6c800ff60) called soa.c:403 soa_set_params() soa_set_params(static::0x7fe6c800ae10, ...) called nua_stack.c:271 nua_stack_event() nua(0x7fe6c800ff60): event i_register 100 Trying nua_stack.c:359 nua_application_event() nua: nua_application_event: entering nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering nua.c:879 nua_respond() nua: nua_respond: entering nua_stack.c:529 nua_signal() nua(0x7fe6c800ff60): sent signal r_respond 2017-12-13 10:05:22.088943 [WARNING] sofia_reg.c:1792 SIP auth challenge (REGISTER) on sofia profile 'internal' for [20(a)sip.xxx] from ip 10.1.0.4 nua.c:921 nua_handle_destroy() nua: nua_handle_destroy: entering nua_stack.c:573 nua_stack_signal() nua(0x7fe6c800ff60): recv signal r_respond 401 Unauthorized nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering nua_stack.c:529 nua_signal() nua(0x7fe6c800ff60): sent signal r_destroy nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering soa.c:403 soa_set_params() soa_set_params(static::0x7fe6c800ae10, ...) called nua.c:921 nua_handle_destroy() nua: nua_handle_destroy: entering tport.c:3257 tport_tsend() tport_tsend(0x7fe6c8004420) tpn = UDP/10.1.0.4:5060 tport.c:4046 tport_resolve() tport_resolve addrinfo = 10.1.0.4:5060 tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7fe6c8004420): not found by name UDP/10.1.0.4:5060 tport.c:3594 tport_vsend() tport_vsend(0x7fe6c8004420): 586 bytes of 586 to udp/10.1.0.4:5060 tport.c:3492 tport_send_msg() tport_vsend returned 586 nta.c:6791 incoming_reply() nta: sent 401 Unauthorized for REGISTER (10060) nua_stack.c:569 nua_stack_signal() nua(0x7fe6c800ff60): recv signal r_destroy nta.c:4470 nta_leg_destroy() nta_leg_destroy((nil)) soa.c:356 soa_destroy() soa_destroy(static::0x7fe6c800ae10) called tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(0x7fe6c8004420): events IN tport.c:2864 tport_recv_event() tport_recv_event(0x7fe6c8004420) tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x7fe6c8004420) msg 0x7fe6c8006140 from (udp/10.1.0.20:5060) has 736 bytes, veclen = 1 tport.c:3023 tport_deliver() tport_deliver(0x7fe6c8004420): msg 0x7fe6c8006140 (736 bytes) from udp/10.1.0.4:5060/sip next=(nil) nta.c:2880 agent_recv_request() nta: received REGISTER sip:sip.xxx SIP/2.0 (CSeq 10061) nta.c:3085 agent_recv_request() nta: REGISTER (10061) going to a default leg nua_server.c:102 nua_stack_process_request() nua: nua_stack_process_request: entering nua_stack.c:899 nh_create() nua: nh_create: entering nua_common.c:108 nh_create_handle() nua: nh_create_handle: entering nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering soa.c:280 soa_clone() soa_clone(static::0x7fe6c8001930, 0x7fe6c8001130, 0x7fe6c8010200) called soa.c:403 soa_set_params() soa_set_params(static::0x7fe6c8012550, ...) called nua_stack.c:271 nua_stack_event() nua(0x7fe6c8010200): event i_register 100 Trying nua_stack.c:359 nua_application_event() nua: nua_application_event: entering nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering nua.c:879 nua_respond() nua: nua_respond: entering nua_stack.c:529 nua_signal() nua(0x7fe6c8010200): sent signal r_respond nua_stack.c:573 nua_stack_signal() nua(0x7fe6c8010200): recv signal r_respond 200 OK nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering soa.c:403 soa_set_params() soa_set_params(static::0x7fe6c8012550, ...) called nua.c:921 nua_handle_destroy() nua: nua_handle_destroy: entering nua_stack.c:529 nua_signal() nua(0x7fe6c8010200): sent signal r_destroy nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering nua.c:921 nua_handle_destroy() nua: nua_handle_destroy: entering tport.c:3257 tport_tsend() tport_tsend(0x7fe6c8004420) tpn = UDP/10.1.0.4:5060 tport.c:4046 tport_resolve() tport_resolve addrinfo = 10.1.0.4:5060 tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7fe6c8004420): not found by name UDP/10.1.0.4:5060 tport.c:3594 tport_vsend() tport_vsend(0x7fe6c8004420): 532 bytes of 532 to udp/10.1.0.4:5060 tport.c:3492 tport_send_msg() tport_vsend returned 532 nta.c:6791 incoming_reply() nta: sent 200 OK for REGISTER (10061) nua_stack.c:569 nua_stack_signal() nua(0x7fe6c8010200): recv signal r_destroy nta.c:4470 nta_leg_destroy() nta_leg_destroy((nil)) soa.c:356 soa_destroy() soa_destroy(static::0x7fe6c8012550) called tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(0x7fe6c8004420): events IN tport.c:2864 tport_recv_event() tport_recv_event(0x7fe6c8004420) tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x7fe6c8004420) msg 0x7fe6c80182f0 from (udp/10.1.0.20:5060) has 466 bytes, veclen = 1 tport.c:3023 tport_deliver() tport_deliver(0x7fe6c8004420): msg 0x7fe6c80182f0 (466 bytes) from udp/10.1.0.4:5060/sip next=(nil) nta.c:2880 agent_recv_request() nta: received REGISTER sip:sip.xxx SIP/2.0 (CSeq 10060) nta.c:3085 agent_recv_request() nta: REGISTER (10060) going to a default leg nua_server.c:102 nua_stack_process_request() nua: nua_stack_process_request: entering nua_stack.c:899 nh_create() nua: nh_create: entering nua_common.c:108 nh_create_handle() nua: nh_create_handle: entering nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering soa.c:280 soa_clone() soa_clone(static::0x7fe6c8001930, 0x7fe6c8001130, 0x7fe6c801a190) called soa.c:403 soa_set_params() soa_set_params(static::0x7fe6c801a770, ...) called nua_stack.c:271 nua_stack_event() nua(0x7fe6c801a190): event i_register 100 Trying nua_stack.c:359 nua_application_event() nua: nua_application_event: entering nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering nua.c:879 nua_respond() nua: nua_respond: entering nua_stack.c:529 nua_signal() nua(0x7fe6c801a190): sent signal r_respond nua_stack.c:573 nua_stack_signal() nua(0x7fe6c801a190): recv signal r_respond 401 Unauthorized nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering soa.c:403 soa_set_params() soa_set_params(static::0x7fe6c801a770, ...) called 2017-12-13 10:05:22.168877 [WARNING] sofia_reg.c:1792 SIP auth challenge (REGISTER) on sofia profile 'internal' for [30(a)sip.xxx] from ip 10.1.0.4 nua.c:921 nua_handle_destroy() nua: nua_handle_destroy: entering nua_stack.c:529 nua_signal() nua(0x7fe6c801a190): sent signal r_destroy nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering nua.c:921 nua_handle_destroy() nua: nua_handle_destroy: entering tport.c:3257 tport_tsend() tport_tsend(0x7fe6c8004420) tpn = UDP/10.1.0.4:5060 tport.c:4046 tport_resolve() tport_resolve addrinfo = 10.1.0.4:5060 tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7fe6c8004420): not found by name UDP/10.1.0.4:5060 tport.c:3594 tport_vsend() tport_vsend(0x7fe6c8004420): 589 bytes of 589 to udp/10.1.0.4:5060 tport.c:3492 tport_send_msg() tport_vsend returned 589 nta.c:6791 incoming_reply() nta: sent 401 Unauthorized for REGISTER (10060) nua_stack.c:569 nua_stack_signal() nua(0x7fe6c801a190): recv signal r_destroy nta.c:4470 nta_leg_destroy() nta_leg_destroy((nil)) soa.c:356 soa_destroy() soa_destroy(static::0x7fe6c801a770) called tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(0x7fe6c8004420): events IN tport.c:2864 tport_recv_event() tport_recv_event(0x7fe6c8004420) tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x7fe6c8004420) msg 0x7fe6c8019f60 from (udp/10.1.0.20:5060) has 737 bytes, veclen = 1 tport.c:3023 tport_deliver() tport_deliver(0x7fe6c8004420): msg 0x7fe6c8019f60 (737 bytes) from udp/10.1.0.4:5060/sip next=(nil) nta.c:2880 agent_recv_request() nta: received REGISTER sip:sip.xxx SIP/2.0 (CSeq 10061) nta.c:3085 agent_recv_request() nta: REGISTER (10061) going to a default leg nua_server.c:102 nua_stack_process_request() nua: nua_stack_process_request: entering nua_stack.c:899 nh_create() nua: nh_create: entering nua_common.c:108 nh_create_handle() nua: nh_create_handle: entering nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering soa.c:280 soa_clone() soa_clone(static::0x7fe6c8001930, 0x7fe6c8001130, 0x7fe6c801cdf0) called soa.c:403 soa_set_params() soa_set_params(static::0x7fe6c801d520, ...) called nua_stack.c:271 nua_stack_event() nua(0x7fe6c801cdf0): event i_register 100 Trying nua_stack.c:359 nua_application_event() nua: nua_application_event: entering nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering nua.c:879 nua_respond() nua: nua_respond: entering nua_stack.c:573 nua_stack_signal() nua(0x7fe6c801cdf0): recv signal r_respond 200 OK nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering soa.c:403 soa_set_params() soa_set_params(static::0x7fe6c801d520, ...) called nua_stack.c:529 nua_signal() nua(0x7fe6c801cdf0): sent signal r_respond tport.c:3257 tport_tsend() tport_tsend(0x7fe6c8004420) tpn = UDP/10.1.0.4:5060 tport.c:4046 tport_resolve() tport_resolve addrinfo = 10.1.0.4:5060 nua.c:921 nua_handle_destroy() nua: nua_handle_destroy: entering tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7fe6c8004420): not found by name UDP/10.1.0.4:5060 nua_stack.c:529 nua_signal() nua(0x7fe6c801cdf0): sent signal r_destroy nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering nua.c:921 nua_handle_destroy() nua: nua_handle_destroy: entering tport.c:3594 tport_vsend() tport_vsend(0x7fe6c8004420): 533 bytes of 533 to udp/10.1.0.4:5060 tport.c:3492 tport_send_msg() tport_vsend returned 533 nta.c:6791 incoming_reply() nta: sent 200 OK for REGISTER (10061) nua_stack.c:569 nua_stack_signal() nua(0x7fe6c801cdf0): recv signal r_destroy nta.c:4470 nta_leg_destroy() nta_leg_destroy((nil)) soa.c:356 soa_destroy() soa_destroy(static::0x7fe6c801d520) called nta.c:7159 _nta_incoming_timer() nta: timer J fired, terminate 200 response nta.c:5825 incoming_reclaim_queued() incoming_reclaim_all((nil), (nil), 0x7fe70403cc60) nta.c:7188 _nta_incoming_timer() nta_incoming_timer: 0/0 resent, 0/0 tout, 1/5 term, 1/5 free nta.c:1296 agent_timer() nta: timer set next to 11157 ms tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(0x7fe6c8004420): events IN tport.c:2864 tport_recv_event() tport_recv_event(0x7fe6c8004420) tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x7fe6c8004420) msg 0x7fe6c8007f80 from (udp/10.1.0.20:5060) has 467 bytes, veclen = 1 tport.c:3023 tport_deliver() tport_deliver(0x7fe6c8004420): msg 0x7fe6c8007f80 (467 bytes) from udp/10.1.0.5:5060/sip next=(nil) nta.c:2880 agent_recv_request() nta: received REGISTER sip:sip.xxx SIP/2.0 (CSeq 11232) nta.c:3085 agent_recv_request() nta: REGISTER (11232) going to a default leg nua_server.c:102 nua_stack_process_request() nua: nua_stack_process_request: entering nua_stack.c:899 nh_create() nua: nh_create: entering nua_common.c:108 nh_create_handle() nua: nh_create_handle: entering nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering soa.c:280 soa_clone() soa_clone(static::0x7fe6c8001930, 0x7fe6c8001130, 0x7fe6c801d010) called soa.c:403 soa_set_params() soa_set_params(static::0x7fe6c8008410, ...) called nua_stack.c:271 nua_stack_event() nua(0x7fe6c801d010): event i_register 100 Trying nua_stack.c:359 nua_application_event() nua: nua_application_event: entering nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering nua.c:879 nua_respond() nua: nua_respond: entering nua_stack.c:529 nua_signal() nua(0x7fe6c801d010): sent signal r_respond 2017-12-13 10:05:46.788911 [WARNING] sofia_reg.c:1792 SIP auth challenge (REGISTER) on sofia profile 'internal' for [50(a)sip.xxx] from ip 10.1.0.5 nua.c:921 nua_handle_destroy() nua: nua_handle_destroy: entering nua_stack.c:573 nua_stack_signal() nua(0x7fe6c801d010): recv signal r_respond 401 Unauthorized nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering soa.c:403 soa_set_params() soa_set_params(static::0x7fe6c8008410, ...) called nua_stack.c:529 nua_signal() nua(0x7fe6c801d010): sent signal r_destroy nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering nua.c:921 nua_handle_destroy() nua: nua_handle_destroy: entering tport.c:3257 tport_tsend() tport_tsend(0x7fe6c8004420) tpn = UDP/10.1.0.5:5060 tport.c:4046 tport_resolve() tport_resolve addrinfo = 10.1.0.5:5060 tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7fe6c8004420): not found by name UDP/10.1.0.5:5060 tport.c:3594 tport_vsend() tport_vsend(0x7fe6c8004420): 590 bytes of 590 to udp/10.1.0.5:5060 tport.c:3492 tport_send_msg() tport_vsend returned 590 nta.c:6791 incoming_reply() nta: sent 401 Unauthorized for REGISTER (11232) nua_stack.c:569 nua_stack_signal() nua(0x7fe6c801d010): recv signal r_destroy nta.c:4470 nta_leg_destroy() nta_leg_destroy((nil)) soa.c:356 soa_destroy() soa_destroy(static::0x7fe6c8008410) called tport.c:2749 tport_wakeup_pri() tport_wakeup_pri(0x7fe6c8004420): events IN tport.c:2864 tport_recv_event() tport_recv_event(0x7fe6c8004420) tport.c:3205 tport_recv_iovec() tport_recv_iovec(0x7fe6c8004420) msg 0x7fe6c8008410 from (udp/10.1.0.20:5060) has 737 bytes, veclen = 1 tport.c:3023 tport_deliver() tport_deliver(0x7fe6c8004420): msg 0x7fe6c8008410 (737 bytes) from udp/10.1.0.5:5060/sip next=(nil) nta.c:2880 agent_recv_request() nta: received REGISTER sip:sip.xxx SIP/2.0 (CSeq 11233) nta.c:3085 agent_recv_request() nta: REGISTER (11233) going to a default leg nua_server.c:102 nua_stack_process_request() nua: nua_stack_process_request: entering nua_stack.c:899 nh_create() nua: nh_create: entering nua_common.c:108 nh_create_handle() nua: nh_create_handle: entering nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering soa.c:280 soa_clone() soa_clone(static::0x7fe6c8001930, 0x7fe6c8001130, 0x7fe6c80201c0) called soa.c:403 soa_set_params() soa_set_params(static::0x7fe6c80209f0, ...) called nua_stack.c:271 nua_stack_event() nua(0x7fe6c80201c0): event i_register 100 Trying nua_stack.c:359 nua_application_event() nua: nua_application_event: entering nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering nua.c:879 nua_respond() nua: nua_respond: entering nua_stack.c:573 nua_stack_signal() nua(0x7fe6c80201c0): recv signal r_respond 200 OK nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering soa.c:403 soa_set_params() soa_set_params(static::0x7fe6c80209f0, ...) called nua_stack.c:529 nua_signal() nua(0x7fe6c80201c0): sent signal r_respond tport.c:3257 tport_tsend() tport_tsend(0x7fe6c8004420) tpn = UDP/10.1.0.5:5060 tport.c:4046 tport_resolve() tport_resolve addrinfo = 10.1.0.5:5060 tport.c:4680 tport_by_addrinfo() tport_by_addrinfo(0x7fe6c8004420): not found by name UDP/10.1.0.5:5060 nua.c:921 nua_handle_destroy() nua: nua_handle_destroy: entering nua_stack.c:529 nua_signal() nua(0x7fe6c80201c0): sent signal r_destroy nua.c:366 nua_handle_magic() nua: nua_handle_magic: entering nua.c:921 nua_handle_destroy() nua: nua_handle_destroy: entering tport.c:3594 tport_vsend() tport_vsend(0x7fe6c8004420): 534 bytes of 534 to udp/10.1.0.5:5060 tport.c:3492 tport_send_msg() tport_vsend returned 534 nta.c:6791 incoming_reply() nta: sent 200 OK for REGISTER (11233) nua_stack.c:569 nua_stack_signal() nua(0x7fe6c80201c0): recv signal r_destroy nta.c:4470 nta_leg_destroy() nta_leg_destroy((nil)) soa.c:356 soa_destroy() soa_destroy(static::0x7fe6c80209f0) called nta.c:9101 outgoing_timer_dk() nta: timer D fired, terminate INVITE (116241888) nta.c:8799 outgoing_reclaim_queued() outgoing_reclaim_all((nil), (nil), 0x7fe70403cd40) nta.c:8929 _nta_outgoing_timer() nta_outgoing_timer: 0/0 resent, 0/1 tout, 1/1 term, 1/2 free nta.c:1296 agent_timer() nta: timer set next to 3 ms nta.c:8982 outgoing_timer_bf() nta: timer F fired, terminating ACK (116241888) nta.c:8799 outgoing_reclaim_queued() outgoing_reclaim_all((nil), (nil), 0x7fe70403cd40) nta.c:8929 _nta_outgoing_timer() nta_outgoing_timer: 0/0 resent, 1/1 tout, 0/0 term, 1/1 free nta.c:1296 agent_timer() nta: timer set next to 6827 ms nta.c:7159 _nta_incoming_timer() nta: timer J fired, terminate 401 response nta.c:5825 incoming_reclaim_queued() incoming_reclaim_all((nil), (nil), 0x7fe70403cc60) nta.c:7188 _nta_incoming_timer() nta_incoming_timer: 0/0 resent, 0/0 tout, 1/6 term, 1/6 free nta.c:1296 agent_timer() nta: timer set next to 35 ms nta.c:7159 _nta_incoming_timer() nta: timer J fired, terminate 200 response nta.c:5825 incoming_reclaim_queued() incoming_reclaim_all((nil), (nil), 0x7fe70403cc60) nta.c:7188 _nta_incoming_timer() nta_incoming_timer: 0/0 resent, 0/0 tout, 1/5 term, 1/5 free nta.c:1296 agent_timer() nta: timer set next to 28 ms nta.c:7159 _nta_incoming_timer() nta: timer J fired, terminate 401 response nta.c:5825 incoming_reclaim_queued() incoming_reclaim_all((nil), (nil), 0x7fe70403cc60) nta.c:7188 _nta_incoming_timer() nta_incoming_timer: 0/0 resent, 0/0 tout, 1/4 term, 1/4 free nta.c:1296 agent_timer() nta: timer set next to 37 ms nta.c:7159 _nta_incoming_timer() nta: timer J fired, terminate 200 response nta.c:5825 incoming_reclaim_queued() incoming_reclaim_all((nil), (nil), 0x7fe70403cc60) nta.c:7188 _nta_incoming_timer() nta_incoming_timer: 0/0 resent, 0/0 tout, 1/3 term, 1/3 free nta.c:1296 agent_timer() nta: timer set next to 24575 ms nta.c:7159 _nta_incoming_timer() nta: timer J fired, terminate 401 response nta.c:5825 incoming_reclaim_queued() incoming_reclaim_all((nil), (nil), 0x7fe70403cc60) nta.c:7188 _nta_incoming_timer() nta_incoming_timer: 0/0 resent, 0/0 tout, 1/2 term, 1/2 free nta.c:1296 agent_timer() nta: timer set next to 73 ms nta.c:7159 _nta_incoming_timer() nta: timer J fired, terminate 200 response nta.c:5825 incoming_reclaim_queued() incoming_reclaim_all((nil), (nil), 0x7fe70403cc60) nta.c:7188 _nta_incoming_timer() nta_incoming_timer: 0/0 resent, 0/0 tout, 1/1 term, 1/1 free nta.c:1289 agent_timer() nta: timer not set Regards Philipp

1 0

Can't find modules to install
by Euan Millar 12 Dec '17

12 Dec '17

Hi, I have an IVR app that I am translating into French but I get an error ... Invalid SAY interface[fr] I think I need to install the module "mod_say_fr" into /usr/lib/freeswitch/mod but I cant find where online I would source this module. Can anyone help me with the URL for this module? This is the first time for me to add a module and somebody else performed the Freeswitch installation. Is it as simple as downloading the mod_say_fr.so file into the directory and loading it in the config XML? Many thanks in advance if you are able to help me. Kind regards, Euan

2 2

esl originate + sessions-per-second
by René Weiss 12 Dec '17

12 Dec '17

Hi, Is there a way do detect that an "originate" call over ESL has failed because of the "sessions-per-second" setting? I see "Throttle Error!" in the Freeswitch logfile and "503 Maximum Calls In Progress" on the SIP level, but the originate call itself just returns "-ERR DESTINATION_OUT_OF_ORDER" with no indication that the "sessions-per-second" limit was the reason for it. Thanks, René

1 0

Subscribing to event channels with Verto
by Richard A. Branham, Jr. 12 Dec '17

12 Dec '17

I have a web application in which phone calling has been implemented using Verto. Calls have been working for quite a while now and we are happy with Verto and FS. I am now attempting to create other event channels using Verto. Two clients subscribe to an event channel with the following: myVertoHandle.sendMethod('verto.subscribe', { "eventChannel": "my-channel", "subParams": { "callID": null, "someparam":"someparam value" } }); The subscription requests are received by the server and subscribedChannels includes "my-channel" in both cases. However, when I use the following to broadcast a message to the channel, it does not seem to be sent to the clients: myVertoHandle.broadcast("my-channel", {"someval":"val1", "anotherval":"val2"}); The server receives the event and sends a reply to the sender, but other clients do not receive the event. (The reply from the server includes message = "MCAST Data Sent".) We are using FreeSWITCH Version 1.6.19~64bit ( 64bit). Some questions: --Is it possible to implement non-call communication using this approach, or am I way off track? --If this is the correct approach, what am I missing in the implementation? Thanks!

1 0

Cancel reason on loose_race
by Jon Schøpzinsky 11 Dec '17

11 Dec '17

Hi list, We have the following setup, with a queue server, running our own queue software, a main switch and a SBC. Queue FreeSWITCH -> Master FreeSWITCH -> OpenSIPS -> UA When a queue dials multiple UA’s, an one of them answers, the rest is correctly sent a CANCEL with the LOOSE_RACE as X-Reason header. This is sent from the Queue FreeSWITCH to the Master FreeSWITCH, but when the Master FreeSWITCH sends this on to the OpenSIPS, the X-Reason header gets changed to NORMAL_CLEARING. Is there any way we can get this header “proxied” through? If not readily available, where should we look to implement this functionality ourselves? We thought of using our own X- headers, to indicate the reason to the OpenSIPS, but there doesn’t seem to be a way to affect headers on CANCEL messages, only on BYE messages. Thank you, Jon Schøpzinsky ThisIsUniverse

1 0

VOIP on Google Cloud
by Michael Avers 10 Dec '17

10 Dec '17

Hey, anybody running Freeswitch on Google Compute Engine? How is it working out? Mike

3 2

SOFIA_PRESENCE check
by Thomas Chen 10 Dec '17

10 Dec '17

i am trying to send CHAT/SMS to external user (outside of the FS box) i was hoping to handle that in chatplan similar to handling external phone # in dialplan with ESL however, seems like the SOFIA_PRESENCE.c is checking whether the ADDR@IP is present first before even activating chatplan (so i keep on getting error message "nobody to send to / internal profile) is there anyway to bypass this check (and accept all destination "to:" to the chatplan ??? thanks

1 0

Directory Gateway Start On Demand
by Martin Gordian 08 Dec '17

08 Dec '17

Hello List, according to https://freeswitch.org/confluence/display/FREESWITCH/Gateways+Configuration example 3, I have configured a gateway for a user to come up and down only when the user is registered, like this <user id="12345"> <params> <param name="password" value="12345678" /> </params> <variables> <variable name="default_gateway" value="GW_12345"/> </variables> <gateways> <gateway name="GW_12345"> <param name="username" value="54321"/> <param name="password" value="87654321"/> <param name="proxy" value="111.111.111.111:5060"/> <param name="register" value="false"/> </gateway> </gateways> </user> Now, according to the clarification on the mentioned page, I expect to see a SIP REGISTER request in Wireshark, from FreeSWITCH to 111.111.111.111 (changed the IP for the question), when the user 12345 registers. But there is none. Checking the gateway with sofia command reveals that there is no SIP registration done, the gatesway is down. What am I missing here, so that the gateway will come up when the user 12345 registers? Thank you.

1 0