<p dir="ltr">Could you send an actual trace?<br>
sofia profile [your-profile] siptrace on<br>
</p>
<br><div class="gmail_quote"><div dir="ltr">On Tue, Jul 24, 2018, 10:35 Paul Muaddib <<a href="mailto:paul.muaddib83@gmail.com">paul.muaddib83@gmail.com</a>> wrote:<br></div><blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex"><div dir="ltr"><div>Hi,</div><div><br></div><div>I finally managed to setup Telekom DeutschlandLAN Voip sip trunk. Most of it is working now, but some calls get droped without clear reason. After one ring freeswitch drops the call: Originate Failed.  Cause: NONE. That happens only if the caller uses a mobile phone. Some mobile phones work, others dont. This was not happening before when I used a ISDN / SIP Gateway. <br></div><br><div>Here is the log file:</div><div><br></div><div>2018-07-23 16:32:57.539715 [NOTICE] switch_channel.c:1104 New Channel sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> [1a4d0118-09cc-46e7-906e-c2bfd1cecba1]<br>2018-07-23 16:32:57.539715 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+@<a href="http://sip-trunk.telekom.de" target="_blank">sip-trunk.telekom.de</a>) Running State Change CS_NEW (Cur 1 Tot 229)<br>2018-07-23 16:32:57.539715 [DEBUG] sofia.c:9873 sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> receiving invite from <a href="http://217.0.15.67:5060" target="_blank">217.0.15.67:5060</a> version: 1.6.20 git 43a9feb 2018-05-07 18:56:11Z 32bit<br>2018-07-23 16:32:57.539715 [DEBUG] sofia.c:7084 Channel sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> entering state [received][100]<br>2018-07-23 16:32:57.539715 [DEBUG] sofia.c:7094 Remote SDP:<br>v=0<br>o=- 1532356377 1532356377 IN IP4 217.0.15.67<br>s=on transit<br>c=IN IP4 217.0.132.101<br>t=0 0<br>a=sendrecv<br>m=audio 22482 RTP/AVP 9 8 100<br>a=rtpmap:9 G722/8000<br>a=rtpmap:8 PCMA/8000<br>a=rtpmap:100 telephone-event/8000<br>a=fmtp:100 0-15<br>a=maxptime:40<br>a=ptime:20<br><br>2018-07-23 16:32:57.559717 [DEBUG] sofia.c:7486 (sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a>) State Change CS_NEW -> CS_INIT<br>2018-07-23 16:32:57.559717 [DEBUG] switch_core_state_machine.c:603 (sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a>) State NEW<br>2018-07-23 16:32:57.559717 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a>) Running State Change CS_INIT (Cur 1 Tot 229)<br>2018-07-23 16:32:57.559717 [DEBUG] switch_core_state_machine.c:627 (sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a>) State INIT<br>2018-07-23 16:32:57.559717 [DEBUG] mod_sofia.c:90 sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> SOFIA INIT<br>2018-07-23 16:32:57.559717 [DEBUG] switch_core_state_machine.c:40 sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Standard INIT<br>2018-07-23 16:32:57.559717 [DEBUG] switch_core_state_machine.c:48 (sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a>) State Change CS_INIT -> CS_ROUTING<br>2018-07-23 16:32:57.559717 [DEBUG] switch_core_state_machine.c:627 (sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a>) State INIT going to sleep<br>2018-07-23 16:32:57.559717 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a>) Running State Change CS_ROUTING (Cur 1 Tot 229)<br>2018-07-23 16:32:57.559717 [DEBUG] switch_channel.c:2249 (sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a>) Callstate Change DOWN -> RINGING<br>2018-07-23 16:32:57.559717 [DEBUG] switch_core_state_machine.c:643 (sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a>) State ROUTING<br>2018-07-23 16:32:57.559717 [DEBUG] mod_sofia.c:143 sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> SOFIA ROUTING<br>2018-07-23 16:32:57.559717 [DEBUG] switch_core_state_machine.c:236 sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Standard ROUTING<br>2018-07-23 16:32:57.559717 [INFO] mod_dialplan_xml.c:637 Processing +49171XXXXXXX <+49171XXXXXXX>->551130118089 in context public<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> parsing [public->unloop] continue=false<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> parsing [public->outside_call] continue=true<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Absolute Condition [outside_call]<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Action set(outside_call=true)<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> parsing [public->call_debug] continue=true<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> parsing [public->telekom-voip-inbound-main] continue=false<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Regex (PASS) [telekom-voip-inbound-main] ${sip_h_P-Called-Party-ID}(<<a href="mailto:sip%3A%2B49XXXXXXXXX@sip-trunk.telekom.de" target="_blank">sip:+49XXXXXXXXX@sip-trunk.telekom.de</a>;user=phone>) =~ /(XXXXXXXXX)/ break=on-false<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Action set(domain_name=10.0.200.2)<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Action set(effective_caller_id_number=${caller_id_number})<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Action set(effective_caller_id_name=${caller_id_number})<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Action set(ringback=%(1000,4000,425))<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Action transfer(operator XML default)<br>2018-07-23 16:32:57.559717 [DEBUG] switch_core_state_machine.c:286 (sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a>) State Change CS_ROUTING -> CS_EXECUTE<br>2018-07-23 16:32:57.559717 [DEBUG] switch_core_state_machine.c:643 (sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a>) State ROUTING going to sleep<br>2018-07-23 16:32:57.559717 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a>) Running State Change CS_EXECUTE (Cur 1 Tot 229)<br>2018-07-23 16:32:57.559717 [DEBUG] switch_core_state_machine.c:650 (sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a>) State EXECUTE<br>2018-07-23 16:32:57.559717 [DEBUG] mod_sofia.c:198 sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> SOFIA EXECUTE<br>2018-07-23 16:32:57.559717 [DEBUG] switch_core_state_machine.c:328 sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Standard EXECUTE<br>EXECUTE sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> set(outside_call=true)<br>2018-07-23 16:32:57.559717 [DEBUG] mod_dptools.c:1548 SET sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> [outside_call]=[true]<br>EXECUTE sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> export(RFC2822_DATE=Mon, 23 Jul 2018 16:32:57 +0200)<br>2018-07-23 16:32:57.559717 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [RFC2822_DATE]=[Mon, 23 Jul 2018 16:32:57 +0200]<br>EXECUTE sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> set(domain_name=10.0.200.2)<br>2018-07-23 16:32:57.559717 [DEBUG] mod_dptools.c:1548 SET sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> [domain_name]=[10.0.200.2]<br>EXECUTE sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> set(effective_caller_id_number=+49171XXXXXXX)<br>2018-07-23 16:32:57.559717 [DEBUG] mod_dptools.c:1548 SET sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> [effective_caller_id_number]=[+49171XXXXXXX]<br>EXECUTE sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> set(effective_caller_id_name=+49171XXXXXXX)<br>2018-07-23 16:32:57.559717 [DEBUG] mod_dptools.c:1548 SET sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> [effective_caller_id_name]=[+49171XXXXXXX]<br>EXECUTE sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> set(ringback=%(1000,4000,425))<br>2018-07-23 16:32:57.559717 [DEBUG] mod_dptools.c:1548 SET sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> [ringback]=[%(1000,4000,425)]<br>EXECUTE sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> transfer(operator XML default)<br>2018-07-23 16:32:57.559717 [DEBUG] switch_ivr.c:2165 (sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a>) State Change CS_EXECUTE -> CS_ROUTING<br>2018-07-23 16:32:57.559717 [NOTICE] switch_ivr.c:2172 Transfer sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> to XML[operator@default]<br>2018-07-23 16:32:57.559717 [DEBUG] switch_core_state_machine.c:650 (sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a>) State EXECUTE going to sleep<br>2018-07-23 16:32:57.559717 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a>) Running State Change CS_ROUTING (Cur 1 Tot 229)<br>2018-07-23 16:32:57.559717 [DEBUG] switch_core_state_machine.c:643 (sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a>) State ROUTING<br>2018-07-23 16:32:57.559717 [DEBUG] mod_sofia.c:143 sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> SOFIA ROUTING<br>2018-07-23 16:32:57.559717 [DEBUG] switch_core_state_machine.c:236 sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Standard ROUTING<br>2018-07-23 16:32:57.559717 [INFO] mod_dialplan_xml.c:637 Processing +49171XXXXXXX <+49171XXXXXXX>->operator in context default<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> parsing [default->unloop] continue=false<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> parsing [default->blacklist_check1] continue=false<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Regex (FAIL) [blacklist_check1] ${regex(${caller_id_number}|(XXXXXXXXX))}(false) =~ /^true$/ break=on-false<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> parsing [default->Time of day] continue=true<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Date/Time Match (PASS) [Time of day] break=never<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Action set(office_status=closed) INLINE<br>EXECUTE sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> set(office_status=closed)<br>2018-07-23 16:32:57.559717 [DEBUG] mod_dptools.c:1548 SET sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> [office_status]=[closed]<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Date/TimeMatch (FAIL) [Time of day] break=never<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> parsing [default->global] continue=true<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Absolute Condition [global]<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Action export(nolocal:api_on_answer=uuid_setvar ${uuid} transfer false)<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Action set(ringback=%(1000,4000,425))<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> parsing [default->global-pickup] continue=false<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Regex (FAIL) [global-pickup] destination_number(operator) =~ /^#$/ break=on-false<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> parsing [default->local-pickup] continue=false<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Regex (FAIL) [local-pickup] destination_number(operator) =~ /^\*\*(\d+)$/ break=on-false<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> parsing [default->group_dial_werkstatt] continue=false<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Regex (FAIL) [group_dial_werkstatt] destination_number(operator) =~ /^16$/ break=on-false<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> parsing [default->Operator] continue=false<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Regex (PASS) [Operator] destination_number(operator) =~ /^(operator)$|^(90)$/ break=on-false<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Action export(dialed_extension=operator)<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Action set(dialed_user=operator@${domain_name})<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Action set(call_timeout=90)<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Action set(hangup_after_bridge=true)<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Action set(continue_on_fail=true)<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Action bind_digit_action(get_digits,~^([1-9][0-9])$,exec:lua,get_digits.lua,peer,self)<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Action digit_action_set_realm(get_digits)<br>Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Action ring_ready()<br>|--- Dialplan: Processing recursive conditions level:1 [Operator_recur_1] require-nested=FALSE<br>|--- Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Regex (FAIL) [Operator_recur_1] ${office_status}(closed) =~ /^(open)$/ break=on-true<br>|--- Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Regex (PASS) [Operator_recur_1] ${office_status}(closed) =~ /^(closed)$/ break=on-true<br>|--- Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Action bridge(group/buero :_: group/werkstatt,pickup/global)<br>|--- Dialplan: sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Action hangup(NO_ANSWER)<br>2018-07-23 16:32:57.559717 [DEBUG] switch_core_state_machine.c:286 (sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a>) State Change CS_ROUTING -> CS_EXECUTE<br>2018-07-23 16:32:57.559717 [DEBUG] switch_core_state_machine.c:643 (sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a>) State ROUTING going to sleep<br>2018-07-23 16:32:57.559717 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a>) Running State Change CS_EXECUTE (Cur 1 Tot 229)<br>2018-07-23 16:32:57.559717 [DEBUG] switch_core_state_machine.c:650 (sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a>) State EXECUTE<br>2018-07-23 16:32:57.559717 [DEBUG] mod_sofia.c:198 sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> SOFIA EXECUTE<br>2018-07-23 16:32:57.559717 [DEBUG] switch_core_state_machine.c:328 sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Standard EXECUTE<br>EXECUTE sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> export(nolocal:api_on_answer=uuid_setvar 1a4d0118-09cc-46e7-906e-c2bfd1cecba1 transfer false)<br>2018-07-23 16:32:57.559717 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) (REMOTE ONLY) [api_on_answer]=[uuid_setvar 1a4d0118-09cc-46e7-906e-c2bfd1cecba1 transfer false]<br>EXECUTE sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> set(ringback=%(1000,4000,425))<br>2018-07-23 16:32:57.559717 [DEBUG] mod_dptools.c:1548 SET sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> [ringback]=[%(1000,4000,425)]<br>EXECUTE sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> export(dialed_extension=operator)<br>2018-07-23 16:32:57.559717 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [dialed_extension]=[operator]<br>EXECUTE sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> set(dialed_user=<a href="mailto:operator@10.0.200.2" target="_blank">operator@10.0.200.2</a>)<br>2018-07-23 16:32:57.559717 [DEBUG] mod_dptools.c:1548 SET sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> [dialed_user]=[<a href="mailto:operator@10.0.200.2" target="_blank">operator@10.0.200.2</a>]<br>EXECUTE sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> set(call_timeout=90)<br>2018-07-23 16:32:57.579719 [DEBUG] mod_dptools.c:1548 SET sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> [call_timeout]=[90]<br>EXECUTE sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> set(hangup_after_bridge=true)<br>2018-07-23 16:32:57.579719 [DEBUG] mod_dptools.c:1548 SET sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> [hangup_after_bridge]=[true]<br>EXECUTE sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> set(continue_on_fail=true)<br>2018-07-23 16:32:57.579719 [DEBUG] mod_dptools.c:1548 SET sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> [continue_on_fail]=[true]<br>EXECUTE sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> bind_digit_action(get_digits,~^([1-9][0-9])$,exec:lua,get_digits.lua,peer,self)<br>2018-07-23 16:32:57.579719 [INFO] switch_ivr_async.c:214 Digit parser DPTOOLS: Setting realm to 'get_digits'<br>2018-07-23 16:32:57.579719 [DEBUG] switch_ivr_async.c:320 Digit parser DPTOOLS: binding ^([1-9][0-9])$/get_digits/0 callback: 0xb58163e0 data: 0x8f414d0<br>EXECUTE sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> digit_action_set_realm(get_digits)<br>EXECUTE sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> ring_ready()<br>2018-07-23 16:32:57.579719 [NOTICE] mod_sofia.c:2273 Ring-Ready sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a>!<br>2018-07-23 16:32:57.579719 [DEBUG] sofia.c:7084 Channel sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> entering state [early][180]<br>2018-07-23 16:32:57.579719 [NOTICE] mod_dptools.c:1028 Ring Ready sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a>!<br>EXECUTE sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> bridge(group/buero :_: group/werkstatt,pickup/global)<br>2018-07-23 16:32:57.579719 [DEBUG] switch_channel.c:1250 sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> EXPORTING[export_vars] [RFC2822_DATE]=[Mon, 23 Jul 2018 16:32:57 +0200] to event<br>2018-07-23 16:32:57.579719 [DEBUG] switch_channel.c:1250 sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> EXPORTING[export_vars] [api_on_answer]=[uuid_setvar 1a4d0118-09cc-46e7-906e-c2bfd1cecba1 transfer false] to event<br>2018-07-23 16:32:57.579719 [DEBUG] switch_channel.c:1250 sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> EXPORTING[export_vars] [dialed_extension]=[operator] to event<br>2018-07-23 16:32:57.579719 [DEBUG] switch_ivr_originate.c:1548 Parsing ultra-global variables<br>2018-07-23 16:32:57.579719 [INFO] switch_ivr_originate.c:1614 Sending early media<br>2018-07-23 16:32:57.579719 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables<br>2018-07-23 16:32:57.579719 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]<br>2018-07-23 16:32:57.579719 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]<br>2018-07-23 16:32:57.579719 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]<br>2018-07-23 16:32:57.579719 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match<br>2018-07-23 16:32:57.579719 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]<br>2018-07-23 16:32:57.579719 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match<br>2018-07-23 16:32:57.579719 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]<br>2018-07-23 16:32:57.579719 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]<br>2018-07-23 16:32:57.579719 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 100@8000<br>2018-07-23 16:32:57.579719 [DEBUG] switch_core_media.c:3061 Set Codec sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> G722/8000 20 ms 160 samples 64000 bits 1 channels<br>2018-07-23 16:32:57.579719 [DEBUG] switch_core_codec.c:111 sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Original read codec set to G722:9<br>2018-07-23 16:32:57.579719 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 100@8000<br>2018-07-23 16:32:57.579719 [DEBUG] switch_core_media.c:4767 sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Set 2833 dtmf send payload to 100 recv payload to 100<br>2018-07-23 16:32:57.579719 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a>] 10.0.200.2 port 7082 -> 217.0.132.101 port 22482 codec: 9 ms: 20<br>2018-07-23 16:32:57.579719 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables<br>2018-07-23 16:32:57.579719 [DEBUG] switch_rtp.c:4137 Starting timer [soft] 160 bytes per 20ms<br>2018-07-23 16:32:57.579719 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables<br>2018-07-23 16:32:57.579719 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables<br>2018-07-23 16:32:57.579719 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/<a href="http://10@10.0.200.10:5060" target="_blank">10@10.0.200.10:5060</a> [6306dfab-d366-4060-8b39-8e8349f72417]<br>2018-07-23 16:32:57.579719 [DEBUG] mod_sofia.c:4819 (sofia/internal/<a href="http://10@10.0.200.10:5060" target="_blank">10@10.0.200.10:5060</a>) State Change CS_NEW -> CS_INIT<br>2018-07-23 16:32:57.579719 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables<br>2018-07-23 16:32:57.579719 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables<br>2018-07-23 16:32:57.579719 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/<a href="http://11@10.0.200.11:5060" target="_blank">11@10.0.200.11:5060</a> [ed0352eb-0d61-41f0-87c0-5837c5de4ef8]<br>2018-07-23 16:32:57.579719 [WARNING] switch_ivr_originate.c:2647 Only calling the first element in the list in this mode.<br>2018-07-23 16:32:57.579719 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables<br>2018-07-23 16:32:57.579719 [DEBUG] mod_sofia.c:4819 (sofia/internal/<a href="http://11@10.0.200.11:5060" target="_blank">11@10.0.200.11:5060</a>) State Change CS_NEW -> CS_INIT<br>2018-07-23 16:32:57.579719 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/<a href="http://15@10.0.200.15:5060" target="_blank">15@10.0.200.15:5060</a> [9c8a2e89-cab4-40f6-b760-4a9e3ea6ef05]<br>2018-07-23 16:32:57.579719 [DEBUG] mod_sofia.c:4819 (sofia/internal/<a href="http://15@10.0.200.15:5060" target="_blank">15@10.0.200.15:5060</a>) State Change CS_NEW -> CS_INIT<br>2018-07-23 16:32:57.579719 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/<a href="http://10@10.0.200.10:5060" target="_blank">10@10.0.200.10:5060</a>) Running State Change CS_INIT (Cur 4 Tot 232)<br>2018-07-23 16:32:57.579719 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/<a href="http://10@10.0.200.10:5060" target="_blank">10@10.0.200.10:5060</a>) State INIT<br>2018-07-23 16:32:57.579719 [DEBUG] mod_sofia.c:90 sofia/internal/<a href="http://10@10.0.200.10:5060" target="_blank">10@10.0.200.10:5060</a> SOFIA INIT<br>2018-07-23 16:32:57.579719 [DEBUG] sofia_glue.c:1295 sofia/internal/<a href="http://10@10.0.200.10:5060" target="_blank">10@10.0.200.10:5060</a> sending invite version: 1.6.20 git 43a9feb 2018-05-07 18:56:11Z 32bit<br>Local SDP:<br>v=0<br>o=FreeSWITCH 1532349289 1532349290 IN IP4 10.0.200.2<br>s=FreeSWITCH<br>c=IN IP4 10.0.200.2<br>t=0 0<br>m=audio 7088 RTP/AVP 8 0 9<br>a=rtpmap:8 PCMA/8000<br>a=rtpmap:0 PCMU/8000<br>a=rtpmap:9 G722/8000<br>a=ptime:20<br>a=sendrecv<br><br>2018-07-23 16:32:57.579719 [DEBUG] switch_core_state_machine.c:40 sofia/internal/<a href="http://10@10.0.200.10:5060" target="_blank">10@10.0.200.10:5060</a> Standard INIT<br>2018-07-23 16:32:57.579719 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/<a href="http://10@10.0.200.10:5060" target="_blank">10@10.0.200.10:5060</a>) State Change CS_INIT -> CS_ROUTING<br>2018-07-23 16:32:57.579719 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/<a href="http://10@10.0.200.10:5060" target="_blank">10@10.0.200.10:5060</a>) State INIT going to sleep<br>2018-07-23 16:32:57.579719 [DEBUG] mod_dptools.c:3872 () State Change CS_NEW -> CS_ROUTING<br>2018-07-23 16:32:57.579719 [NOTICE] switch_channel.c:1104 New Channel pickup/global [66ab149c-54a4-4d17-8428-26e1872e6f9d]<br>2018-07-23 16:32:57.599721 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/<a href="http://11@10.0.200.11:5060" target="_blank">11@10.0.200.11:5060</a>) Running State Change CS_INIT (Cur 5 Tot 233)<br>2018-07-23 16:32:57.599721 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/<a href="http://11@10.0.200.11:5060" target="_blank">11@10.0.200.11:5060</a>) State INIT<br>2018-07-23 16:32:57.599721 [DEBUG] mod_sofia.c:90 sofia/internal/<a href="http://11@10.0.200.11:5060" target="_blank">11@10.0.200.11:5060</a> SOFIA INIT<br>2018-07-23 16:32:57.599721 [DEBUG] sofia_glue.c:1295 sofia/internal/<a href="http://11@10.0.200.11:5060" target="_blank">11@10.0.200.11:5060</a> sending invite version: 1.6.20 git 43a9feb 2018-05-07 18:56:11Z 32bit<br>Local SDP:<br>v=0<br>o=FreeSWITCH 1532349305 1532349306 IN IP4 10.0.200.2<br>s=FreeSWITCH<br>c=IN IP4 10.0.200.2<br>t=0 0<br>m=audio 7072 RTP/AVP 8 0 9<br>a=rtpmap:8 PCMA/8000<br>a=rtpmap:0 PCMU/8000<br>a=rtpmap:9 G722/8000<br>a=ptime:20<br>a=sendrecv<br><br>2018-07-23 16:32:57.599721 [DEBUG] switch_core_state_machine.c:40 sofia/internal/<a href="http://11@10.0.200.11:5060" target="_blank">11@10.0.200.11:5060</a> Standard INIT<br>2018-07-23 16:32:57.599721 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/<a href="http://11@10.0.200.11:5060" target="_blank">11@10.0.200.11:5060</a>) State Change CS_INIT -> CS_ROUTING<br>2018-07-23 16:32:57.599721 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/<a href="http://11@10.0.200.11:5060" target="_blank">11@10.0.200.11:5060</a>) State INIT going to sleep<br>2018-07-23 16:32:57.599721 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/<a href="http://11@10.0.200.11:5060" target="_blank">11@10.0.200.11:5060</a>) Running State Change CS_ROUTING (Cur 5 Tot 233)<br>2018-07-23 16:32:57.599721 [DEBUG] sofia.c:7084 Channel sofia/internal/<a href="http://11@10.0.200.11:5060" target="_blank">11@10.0.200.11:5060</a> entering state [calling][0]<br>2018-07-23 16:32:57.599721 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/<a href="http://11@10.0.200.11:5060" target="_blank">11@10.0.200.11:5060</a>) State ROUTING<br>2018-07-23 16:32:57.599721 [DEBUG] mod_sofia.c:143 sofia/internal/<a href="http://11@10.0.200.11:5060" target="_blank">11@10.0.200.11:5060</a> SOFIA ROUTING<br>2018-07-23 16:32:57.599721 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/<a href="http://11@10.0.200.11:5060" target="_blank">11@10.0.200.11:5060</a>) State Change CS_ROUTING -> CS_CONSUME_MEDIA<br>2018-07-23 16:32:57.599721 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/<a href="http://11@10.0.200.11:5060" target="_blank">11@10.0.200.11:5060</a>) State ROUTING going to sleep<br>2018-07-23 16:32:57.599721 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/<a href="http://15@10.0.200.15:5060" target="_blank">15@10.0.200.15:5060</a>) Running State Change CS_INIT (Cur 5 Tot 233)<br>2018-07-23 16:32:57.599721 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/<a href="http://15@10.0.200.15:5060" target="_blank">15@10.0.200.15:5060</a>) State INIT<br>2018-07-23 16:32:57.599721 [DEBUG] mod_sofia.c:90 sofia/internal/<a href="http://15@10.0.200.15:5060" target="_blank">15@10.0.200.15:5060</a> SOFIA INIT<br>2018-07-23 16:32:57.599721 [DEBUG] sofia_glue.c:1295 sofia/internal/<a href="http://15@10.0.200.15:5060" target="_blank">15@10.0.200.15:5060</a> sending invite version: 1.6.20 git 43a9feb 2018-05-07 18:56:11Z 32bit<br>Local SDP:<br>v=0<br>o=FreeSWITCH 1532349297 1532349298 IN IP4 10.0.200.2<br>s=FreeSWITCH<br>c=IN IP4 10.0.200.2<br>t=0 0<br>m=audio 7080 RTP/AVP 8 0 9<br>a=rtpmap:8 PCMA/8000<br>a=rtpmap:0 PCMU/8000<br>a=rtpmap:9 G722/8000<br>a=ptime:20<br>a=sendrecv<br><br>2018-07-23 16:32:57.599721 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/<a href="http://11@10.0.200.11:5060" target="_blank">11@10.0.200.11:5060</a>) Running State Change CS_CONSUME_MEDIA (Cur 5 Tot 233)<br>2018-07-23 16:32:57.599721 [DEBUG] switch_core_state_machine.c:40 sofia/internal/<a href="http://15@10.0.200.15:5060" target="_blank">15@10.0.200.15:5060</a> Standard INIT<br>2018-07-23 16:32:57.599721 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/<a href="http://15@10.0.200.15:5060" target="_blank">15@10.0.200.15:5060</a>) State Change CS_INIT -> CS_ROUTING<br>2018-07-23 16:32:57.599721 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/<a href="http://15@10.0.200.15:5060" target="_blank">15@10.0.200.15:5060</a>) State INIT going to sleep<br>2018-07-23 16:32:57.599721 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/<a href="http://11@10.0.200.11:5060" target="_blank">11@10.0.200.11:5060</a>) State CONSUME_MEDIA<br>2018-07-23 16:32:57.599721 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/<a href="http://11@10.0.200.11:5060" target="_blank">11@10.0.200.11:5060</a>) State CONSUME_MEDIA going to sleep<br>2018-07-23 16:32:57.599721 [DEBUG] switch_core_state_machine.c:584 (pickup/global) Running State Change CS_ROUTING (Cur 5 Tot 233)<br>2018-07-23 16:32:57.599721 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/<a href="http://15@10.0.200.15:5060" target="_blank">15@10.0.200.15:5060</a>) Running State Change CS_ROUTING (Cur 5 Tot 233)<br>2018-07-23 16:32:57.599721 [DEBUG] switch_core_state_machine.c:643 (pickup/global) State ROUTING<br>2018-07-23 16:32:57.599721 [DEBUG] switch_ivr_originate.c:67 (pickup/global) State Change CS_ROUTING -> CS_CONSUME_MEDIA<br>2018-07-23 16:32:57.599721 [DEBUG] switch_core_state_machine.c:643 (pickup/global) State ROUTING going to sleep<br>2018-07-23 16:32:57.599721 [DEBUG] switch_core_state_machine.c:584 (pickup/global) Running State Change CS_CONSUME_MEDIA (Cur 5 Tot 233)<br>2018-07-23 16:32:57.599721 [DEBUG] switch_core_state_machine.c:662 (pickup/global) State CONSUME_MEDIA<br>2018-07-23 16:32:57.599721 [DEBUG] switch_core_state_machine.c:662 (pickup/global) State CONSUME_MEDIA going to sleep<br>2018-07-23 16:32:57.599721 [DEBUG] sofia.c:7084 Channel sofia/internal/<a href="http://15@10.0.200.15:5060" target="_blank">15@10.0.200.15:5060</a> entering state [calling][0]<br>2018-07-23 16:32:57.599721 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/<a href="http://15@10.0.200.15:5060" target="_blank">15@10.0.200.15:5060</a>) State ROUTING<br>2018-07-23 16:32:57.599721 [DEBUG] mod_sofia.c:143 sofia/internal/<a href="http://15@10.0.200.15:5060" target="_blank">15@10.0.200.15:5060</a> SOFIA ROUTING<br>2018-07-23 16:32:57.599721 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/<a href="http://15@10.0.200.15:5060" target="_blank">15@10.0.200.15:5060</a>) State Change CS_ROUTING -> CS_CONSUME_MEDIA<br>2018-07-23 16:32:57.599721 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/<a href="http://15@10.0.200.15:5060" target="_blank">15@10.0.200.15:5060</a>) State ROUTING going to sleep<br>2018-07-23 16:32:57.599721 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/<a href="http://15@10.0.200.15:5060" target="_blank">15@10.0.200.15:5060</a>) Running State Change CS_CONSUME_MEDIA (Cur 5 Tot 233)<br>2018-07-23 16:32:57.599721 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/<a href="http://15@10.0.200.15:5060" target="_blank">15@10.0.200.15:5060</a>) State CONSUME_MEDIA<br>2018-07-23 16:32:57.599721 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/<a href="http://15@10.0.200.15:5060" target="_blank">15@10.0.200.15:5060</a>) State CONSUME_MEDIA going to sleep<br>2018-07-23 16:32:57.599721 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/<a href="http://10@10.0.200.10:5060" target="_blank">10@10.0.200.10:5060</a>) Running State Change CS_ROUTING (Cur 5 Tot 233)<br>2018-07-23 16:32:57.599721 [DEBUG] sofia.c:7084 Channel sofia/internal/<a href="http://10@10.0.200.10:5060" target="_blank">10@10.0.200.10:5060</a> entering state [calling][0]<br>2018-07-23 16:32:57.599721 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/<a href="http://10@10.0.200.10:5060" target="_blank">10@10.0.200.10:5060</a>) State ROUTING<br>2018-07-23 16:32:57.599721 [DEBUG] mod_sofia.c:143 sofia/internal/<a href="http://10@10.0.200.10:5060" target="_blank">10@10.0.200.10:5060</a> SOFIA ROUTING<br>2018-07-23 16:32:57.599721 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/<a href="http://10@10.0.200.10:5060" target="_blank">10@10.0.200.10:5060</a>) State Change CS_ROUTING -> CS_CONSUME_MEDIA<br>2018-07-23 16:32:57.599721 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/<a href="http://10@10.0.200.10:5060" target="_blank">10@10.0.200.10:5060</a>) State ROUTING going to sleep<br>2018-07-23 16:32:57.599721 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/<a href="http://10@10.0.200.10:5060" target="_blank">10@10.0.200.10:5060</a>) Running State Change CS_CONSUME_MEDIA (Cur 5 Tot 233)<br>2018-07-23 16:32:57.599721 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/<a href="http://10@10.0.200.10:5060" target="_blank">10@10.0.200.10:5060</a>) State CONSUME_MEDIA<br>2018-07-23 16:32:57.599721 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/<a href="http://10@10.0.200.10:5060" target="_blank">10@10.0.200.10:5060</a>) State CONSUME_MEDIA going to sleep<br>2018-07-23 16:32:57.599721 [DEBUG] switch_core_media.c:7180 sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Set 2833 dtmf send payload to 100<br>2018-07-23 16:32:57.599721 [DEBUG] switch_core_media.c:7187 sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Set 2833 dtmf receive payload to 100<br>2018-07-23 16:32:57.599721 [DEBUG] switch_core_media.c:7210 sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Set rtp dtmf delay to 40<br>2018-07-23 16:32:57.599721 [NOTICE] sofia_media.c:92 Pre-Answer sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a>!<br>2018-07-23 16:32:57.599721 [DEBUG] switch_channel.c:3474 (sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a>) Callstate Change RINGING -> EARLY<br>2018-07-23 16:32:57.619722 [DEBUG] switch_core_media.c:6861 Audio params are unchanged for sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a>.<br>2018-07-23 16:32:57.619722 [DEBUG] mod_sofia.c:2364 Ring SDP:<br>v=0<br>o=FreeSWITCH 1532349295 1532349296 IN IP4 10.0.200.2<br>s=FreeSWITCH<br>c=IN IP4 10.0.200.2<br>t=0 0<br>m=audio 7082 RTP/AVP 9 100<br>a=rtpmap:9 G722/8000<br>a=rtpmap:100 telephone-event/8000<br>a=fmtp:100 0-16<br>a=ptime:20<br>a=sendrecv<br><br>2018-07-23 16:32:57.619722 [DEBUG] sofia.c:7084 Channel sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> entering state [early][183]<br>2018-07-23 16:32:57.639724 [DEBUG] sofia.c:7084 Channel sofia/internal/<a href="http://15@10.0.200.15:5060" target="_blank">15@10.0.200.15:5060</a> entering state [proceeding][180]<br>2018-07-23 16:32:57.639724 [NOTICE] sofia.c:7192 Ring-Ready sofia/internal/<a href="http://15@10.0.200.15:5060" target="_blank">15@10.0.200.15:5060</a>!<br>2018-07-23 16:32:57.639724 [DEBUG] switch_channel.c:3346 (sofia/internal/<a href="http://15@10.0.200.15:5060" target="_blank">15@10.0.200.15:5060</a>) Callstate Change DOWN -> RINGING<br>2018-07-23 16:32:57.639724 [DEBUG] sofia.c:7084 Channel sofia/internal/<a href="http://10@10.0.200.10:5060" target="_blank">10@10.0.200.10:5060</a> entering state [proceeding][180]<br>2018-07-23 16:32:57.639724 [NOTICE] sofia.c:7192 Ring-Ready sofia/internal/<a href="http://10@10.0.200.10:5060" target="_blank">10@10.0.200.10:5060</a>!<br>2018-07-23 16:32:57.639724 [DEBUG] switch_channel.c:3346 (sofia/internal/<a href="http://10@10.0.200.10:5060" target="_blank">10@10.0.200.10:5060</a>) Callstate Change DOWN -> RINGING<br>2018-07-23 16:32:57.679728 [DEBUG] sofia.c:7084 Channel sofia/internal/<a href="http://11@10.0.200.11:5060" target="_blank">11@10.0.200.11:5060</a> entering state [proceeding][180]<br>2018-07-23 16:32:57.679728 [NOTICE] sofia.c:7192 Ring-Ready sofia/internal/<a href="http://11@10.0.200.11:5060" target="_blank">11@10.0.200.11:5060</a>!<br>2018-07-23 16:32:57.679728 [DEBUG] switch_channel.c:3346 (sofia/internal/<a href="http://11@10.0.200.11:5060" target="_blank">11@10.0.200.11:5060</a>) Callstate Change DOWN -> RINGING<br>2018-07-23 16:32:58.879835 [DEBUG] sofia.c:7084 Channel sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> entering state [terminated][487]<br>2018-07-23 16:32:58.879835 [NOTICE] sofia.c:8273 Hangup sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> [CS_EXECUTE] [ORIGINATOR_CANCEL]<br>2018-07-23 16:32:58.899837 [NOTICE] switch_ivr_originate.c:3612 Hangup sofia/internal/<a href="http://15@10.0.200.15:5060" target="_blank">15@10.0.200.15:5060</a> [CS_CONSUME_MEDIA] [LOSE_RACE]<br>2018-07-23 16:32:58.899837 [NOTICE] switch_ivr_originate.c:3612 Hangup pickup/global [CS_CONSUME_MEDIA] [LOSE_RACE]<br>2018-07-23 16:32:58.899837 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/<a href="http://15@10.0.200.15:5060" target="_blank">15@10.0.200.15:5060</a>) Running State Change CS_HANGUP (Cur 5 Tot 233)<br>2018-07-23 16:32:58.899837 [DEBUG] switch_ivr_originate.c:3837 Originate Cancelled by originator termination Cause: 487 [ORIGINATOR_CANCEL]<br>2018-07-23 16:32:58.899837 [DEBUG] switch_core_state_machine.c:584 (pickup/global) Running State Change CS_HANGUP (Cur 5 Tot 233)<br>2018-07-23 16:32:58.899837 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/<a href="http://15@10.0.200.15:5060" target="_blank">15@10.0.200.15:5060</a>) Callstate Change RINGING -> HANGUP<br>2018-07-23 16:32:58.899837 [DEBUG] switch_core_state_machine.c:850 (pickup/global) Callstate Change DOWN -> HANGUP<br>2018-07-23 16:32:58.899837 [DEBUG] switch_core_state_machine.c:852 (pickup/global) State HANGUP<br>2018-07-23 16:32:58.899837 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/<a href="http://15@10.0.200.15:5060" target="_blank">15@10.0.200.15:5060</a>) State HANGUP<br>2018-07-23 16:32:58.899837 [DEBUG] switch_core_state_machine.c:60 pickup/global Standard HANGUP, cause: LOSE_RACE<br>2018-07-23 16:32:58.899837 [DEBUG] switch_core_state_machine.c:852 (pickup/global) State HANGUP going to sleep<br>2018-07-23 16:32:58.899837 [DEBUG] mod_sofia.c:438 Channel sofia/internal/<a href="http://15@10.0.200.15:5060" target="_blank">15@10.0.200.15:5060</a> hanging up, cause: LOSE_RACE<br>2018-07-23 16:32:58.899837 [DEBUG] switch_core_state_machine.c:619 (pickup/global) State Change CS_HANGUP -> CS_REPORTING<br>2018-07-23 16:32:58.899837 [DEBUG] switch_core_state_machine.c:584 (pickup/global) Running State Change CS_REPORTING (Cur 5 Tot 233)<br>2018-07-23 16:32:58.899837 [DEBUG] switch_core_state_machine.c:938 (pickup/global) State REPORTING<br>2018-07-23 16:32:58.899837 [DEBUG] switch_core_state_machine.c:938 (pickup/global) State REPORTING going to sleep<br>2018-07-23 16:32:58.899837 [DEBUG] switch_core_state_machine.c:610 (pickup/global) State Change CS_REPORTING -> CS_DESTROY<br>2018-07-23 16:32:58.899837 [DEBUG] switch_core_session.c:1665 Session 233 (pickup/global) Locked, Waiting on external entities<br>2018-07-23 16:32:58.899837 [NOTICE] switch_core_session.c:1683 Session 233 (pickup/global) Ended<br>2018-07-23 16:32:58.899837 [NOTICE] switch_core_session.c:1687 Close Channel pickup/global [CS_DESTROY]<br>2018-07-23 16:32:58.899837 [DEBUG] switch_core_state_machine.c:741 (pickup/global) Running State Change CS_DESTROY (Cur 4 Tot 233)<br>2018-07-23 16:32:58.899837 [DEBUG] switch_core_state_machine.c:751 (pickup/global) State DESTROY<br>2018-07-23 16:32:58.899837 [DEBUG] switch_core_state_machine.c:181 pickup/global Standard DESTROY<br>2018-07-23 16:32:58.899837 [DEBUG] switch_core_state_machine.c:751 (pickup/global) State DESTROY going to sleep<br>2018-07-23 16:32:58.899837 [NOTICE] switch_ivr_originate.c:3612 Hangup sofia/internal/<a href="http://10@10.0.200.10:5060" target="_blank">10@10.0.200.10:5060</a> [CS_CONSUME_MEDIA] [LOSE_RACE]<br>2018-07-23 16:32:58.899837 [DEBUG] mod_sofia.c:502 Sending CANCEL to sofia/internal/<a href="http://15@10.0.200.15:5060" target="_blank">15@10.0.200.15:5060</a><br>2018-07-23 16:32:58.899837 [DEBUG] switch_core_state_machine.c:60 sofia/internal/<a href="http://15@10.0.200.15:5060" target="_blank">15@10.0.200.15:5060</a> Standard HANGUP, cause: LOSE_RACE<br>2018-07-23 16:32:58.899837 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/<a href="http://15@10.0.200.15:5060" target="_blank">15@10.0.200.15:5060</a>) State HANGUP going to sleep<br>2018-07-23 16:32:58.899837 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/<a href="http://15@10.0.200.15:5060" target="_blank">15@10.0.200.15:5060</a>) State Change CS_HANGUP -> CS_REPORTING<br>2018-07-23 16:32:58.899837 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/<a href="http://15@10.0.200.15:5060" target="_blank">15@10.0.200.15:5060</a>) Running State Change CS_REPORTING (Cur 4 Tot 233)<br>2018-07-23 16:32:58.899837 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/<a href="http://15@10.0.200.15:5060" target="_blank">15@10.0.200.15:5060</a>) State REPORTING<br>2018-07-23 16:32:58.899837 [DEBUG] switch_core_state_machine.c:174 sofia/internal/<a href="http://15@10.0.200.15:5060" target="_blank">15@10.0.200.15:5060</a> Standard REPORTING, cause: LOSE_RACE<br>2018-07-23 16:32:58.899837 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/<a href="http://15@10.0.200.15:5060" target="_blank">15@10.0.200.15:5060</a>) State REPORTING going to sleep<br>2018-07-23 16:32:58.899837 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/<a href="http://15@10.0.200.15:5060" target="_blank">15@10.0.200.15:5060</a>) State Change CS_REPORTING -> CS_DESTROY<br>2018-07-23 16:32:58.899837 [DEBUG] switch_core_session.c:1665 Session 232 (sofia/internal/<a href="http://15@10.0.200.15:5060" target="_blank">15@10.0.200.15:5060</a>) Locked, Waiting on external entities<br>2018-07-23 16:32:58.899837 [NOTICE] switch_core_session.c:1683 Session 232 (sofia/internal/<a href="http://15@10.0.200.15:5060" target="_blank">15@10.0.200.15:5060</a>) Ended<br>2018-07-23 16:32:58.899837 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/<a href="http://15@10.0.200.15:5060" target="_blank">15@10.0.200.15:5060</a> [CS_DESTROY]<br>2018-07-23 16:32:58.899837 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/<a href="http://15@10.0.200.15:5060" target="_blank">15@10.0.200.15:5060</a>) Running State Change CS_DESTROY (Cur 3 Tot 233)<br>2018-07-23 16:32:58.899837 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/<a href="http://15@10.0.200.15:5060" target="_blank">15@10.0.200.15:5060</a>) State DESTROY<br>2018-07-23 16:32:58.899837 [DEBUG] mod_sofia.c:343 sofia/internal/<a href="http://15@10.0.200.15:5060" target="_blank">15@10.0.200.15:5060</a> SOFIA DESTROY<br>2018-07-23 16:32:58.899837 [DEBUG] switch_core_state_machine.c:181 sofia/internal/<a href="http://15@10.0.200.15:5060" target="_blank">15@10.0.200.15:5060</a> Standard DESTROY<br>2018-07-23 16:32:58.899837 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/<a href="http://15@10.0.200.15:5060" target="_blank">15@10.0.200.15:5060</a>) State DESTROY going to sleep<br>2018-07-23 16:32:58.899837 [NOTICE] switch_ivr_originate.c:3612 Hangup sofia/internal/<a href="http://11@10.0.200.11:5060" target="_blank">11@10.0.200.11:5060</a> [CS_CONSUME_MEDIA] [LOSE_RACE]<br>2018-07-23 16:32:58.899837 [DEBUG] switch_ivr_originate.c:3837 Originate Cancelled by originator termination Cause: 487 [ORIGINATOR_CANCEL]<br>2018-07-23 16:32:58.899837 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [group] cause: [LOSE_RACE]<br>2018-07-23 16:32:58.899837 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 502 [LOSE_RACE]<br>2018-07-23 16:32:58.899837 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/<a href="http://10@10.0.200.10:5060" target="_blank">10@10.0.200.10:5060</a>) Running State Change CS_HANGUP (Cur 3 Tot 233)<br>2018-07-23 16:32:58.899837 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/<a href="http://10@10.0.200.10:5060" target="_blank">10@10.0.200.10:5060</a>) Callstate Change RINGING -> HANGUP<br>2018-07-23 16:32:58.899837 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/<a href="http://10@10.0.200.10:5060" target="_blank">10@10.0.200.10:5060</a>) State HANGUP<br>2018-07-23 16:32:58.899837 [DEBUG] mod_sofia.c:438 Channel sofia/internal/<a href="http://10@10.0.200.10:5060" target="_blank">10@10.0.200.10:5060</a> hanging up, cause: LOSE_RACE<br>2018-07-23 16:32:58.899837 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/<a href="http://11@10.0.200.11:5060" target="_blank">11@10.0.200.11:5060</a>) Running State Change CS_HANGUP (Cur 3 Tot 233)<br>2018-07-23 16:32:58.899837 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/<a href="http://11@10.0.200.11:5060" target="_blank">11@10.0.200.11:5060</a>) Callstate Change RINGING -> HANGUP<br>2018-07-23 16:32:58.899837 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/<a href="http://11@10.0.200.11:5060" target="_blank">11@10.0.200.11:5060</a>) State HANGUP<br>2018-07-23 16:32:58.899837 [DEBUG] mod_sofia.c:438 Channel sofia/internal/<a href="http://11@10.0.200.11:5060" target="_blank">11@10.0.200.11:5060</a> hanging up, cause: LOSE_RACE<br>2018-07-23 16:32:58.899837 [INFO] mod_dptools.c:3436 Originate Failed.  Cause: NONE<br>2018-07-23 16:32:58.899837 [DEBUG] switch_core_session.c:2815 sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)<br>2018-07-23 16:32:58.899837 [DEBUG] switch_core_state_machine.c:650 (sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a>) State EXECUTE going to sleep<br>2018-07-23 16:32:58.899837 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a>) Running State Change CS_HANGUP (Cur 3 Tot 233)<br>2018-07-23 16:32:58.919839 [DEBUG] switch_core_state_machine.c:850 (sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a>) Callstate Change EARLY -> HANGUP<br>2018-07-23 16:32:58.919839 [DEBUG] switch_core_state_machine.c:852 (sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a>) State HANGUP<br>2018-07-23 16:32:58.919839 [DEBUG] mod_sofia.c:438 Channel sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> hanging up, cause: ORIGINATOR_CANCEL<br>2018-07-23 16:32:58.919839 [DEBUG] switch_core_state_machine.c:60 sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Standard HANGUP, cause: ORIGINATOR_CANCEL<br>2018-07-23 16:32:58.919839 [DEBUG] switch_core_state_machine.c:852 (sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a>) State HANGUP going to sleep<br>2018-07-23 16:32:58.919839 [DEBUG] switch_core_state_machine.c:619 (sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a>) State Change CS_HANGUP -> CS_REPORTING<br>2018-07-23 16:32:58.919839 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a>) Running State Change CS_REPORTING (Cur 3 Tot 233)<br>2018-07-23 16:32:58.919839 [DEBUG] mod_sofia.c:502 Sending CANCEL to sofia/internal/<a href="http://10@10.0.200.10:5060" target="_blank">10@10.0.200.10:5060</a><br>2018-07-23 16:32:58.919839 [DEBUG] switch_core_state_machine.c:938 (sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a>) State REPORTING<br>2018-07-23 16:32:58.919839 [DEBUG] switch_core_state_machine.c:60 sofia/internal/<a href="http://10@10.0.200.10:5060" target="_blank">10@10.0.200.10:5060</a> Standard HANGUP, cause: LOSE_RACE<br>2018-07-23 16:32:58.919839 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/<a href="http://10@10.0.200.10:5060" target="_blank">10@10.0.200.10:5060</a>) State HANGUP going to sleep<br>2018-07-23 16:32:58.919839 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/<a href="http://10@10.0.200.10:5060" target="_blank">10@10.0.200.10:5060</a>) State Change CS_HANGUP -> CS_REPORTING<br>2018-07-23 16:32:58.919839 [DEBUG] switch_core_state_machine.c:174 sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Standard REPORTING, cause: ORIGINATOR_CANCEL<br>2018-07-23 16:32:58.919839 [DEBUG] switch_core_state_machine.c:938 (sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a>) State REPORTING going to sleep<br>2018-07-23 16:32:58.919839 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/<a href="http://10@10.0.200.10:5060" target="_blank">10@10.0.200.10:5060</a>) Running State Change CS_REPORTING (Cur 3 Tot 233)<br>2018-07-23 16:32:58.919839 [DEBUG] switch_core_state_machine.c:610 (sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a>) State Change CS_REPORTING -> CS_DESTROY<br>2018-07-23 16:32:58.919839 [DEBUG] switch_core_session.c:1665 Session 229 (sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a>) Locked, Waiting on external entities<br>2018-07-23 16:32:58.919839 [NOTICE] switch_core_session.c:1683 Session 229 (sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a>) Ended<br>2018-07-23 16:32:58.919839 [NOTICE] switch_core_session.c:1687 Close Channel sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> [CS_DESTROY]<br>2018-07-23 16:32:58.919839 [DEBUG] switch_core_state_machine.c:741 (sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a>) Running State Change CS_DESTROY (Cur 2 Tot 233)<br>2018-07-23 16:32:58.919839 [DEBUG] switch_core_state_machine.c:751 (sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a>) State DESTROY<br>2018-07-23 16:32:58.919839 [DEBUG] mod_sofia.c:343 sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> SOFIA DESTROY<br>2018-07-23 16:32:58.919839 [DEBUG] switch_core_state_machine.c:181 sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a> Standard DESTROY<br>2018-07-23 16:32:58.919839 [DEBUG] switch_core_state_machine.c:751 (sofia/external/+<a href="mailto:49171XXXXXXX@sip-trunk.telekom.de" target="_blank">49171XXXXXXX@sip-trunk.telekom.de</a>) State DESTROY going to sleep<br>2018-07-23 16:32:58.919839 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/<a href="http://10@10.0.200.10:5060" target="_blank">10@10.0.200.10:5060</a>) State REPORTING<br>2018-07-23 16:32:58.919839 [DEBUG] switch_core_state_machine.c:174 sofia/internal/<a href="http://10@10.0.200.10:5060" target="_blank">10@10.0.200.10:5060</a> Standard REPORTING, cause: LOSE_RACE<br>2018-07-23 16:32:58.919839 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/<a href="http://10@10.0.200.10:5060" target="_blank">10@10.0.200.10:5060</a>) State REPORTING going to sleep<br>2018-07-23 16:32:58.919839 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/<a href="http://10@10.0.200.10:5060" target="_blank">10@10.0.200.10:5060</a>) State Change CS_REPORTING -> CS_DESTROY<br>2018-07-23 16:32:58.919839 [DEBUG] switch_core_session.c:1665 Session 230 (sofia/internal/<a href="http://10@10.0.200.10:5060" target="_blank">10@10.0.200.10:5060</a>) Locked, Waiting on external entities<br>2018-07-23 16:32:58.919839 [NOTICE] switch_core_session.c:1683 Session 230 (sofia/internal/<a href="http://10@10.0.200.10:5060" target="_blank">10@10.0.200.10:5060</a>) Ended<br>2018-07-23 16:32:58.919839 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/<a href="http://10@10.0.200.10:5060" target="_blank">10@10.0.200.10:5060</a> [CS_DESTROY]<br>2018-07-23 16:32:58.919839 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/<a href="http://10@10.0.200.10:5060" target="_blank">10@10.0.200.10:5060</a>) Running State Change CS_DESTROY (Cur 1 Tot 233)<br>2018-07-23 16:32:58.919839 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/<a href="http://10@10.0.200.10:5060" target="_blank">10@10.0.200.10:5060</a>) State DESTROY<br>2018-07-23 16:32:58.919839 [DEBUG] mod_sofia.c:343 sofia/internal/<a href="http://10@10.0.200.10:5060" target="_blank">10@10.0.200.10:5060</a> SOFIA DESTROY<br>2018-07-23 16:32:58.919839 [DEBUG] switch_core_state_machine.c:181 sofia/internal/<a href="http://10@10.0.200.10:5060" target="_blank">10@10.0.200.10:5060</a> Standard DESTROY<br>2018-07-23 16:32:58.919839 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/<a href="http://10@10.0.200.10:5060" target="_blank">10@10.0.200.10:5060</a>) State DESTROY going to sleep<br>2018-07-23 16:32:58.919839 [DEBUG] mod_sofia.c:502 Sending CANCEL to sofia/internal/<a href="http://11@10.0.200.11:5060" target="_blank">11@10.0.200.11:5060</a><br>2018-07-23 16:32:58.919839 [DEBUG] switch_core_state_machine.c:60 sofia/internal/<a href="http://11@10.0.200.11:5060" target="_blank">11@10.0.200.11:5060</a> Standard HANGUP, cause: LOSE_RACE<br>2018-07-23 16:32:58.919839 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/<a href="http://11@10.0.200.11:5060" target="_blank">11@10.0.200.11:5060</a>) State HANGUP going to sleep<br>2018-07-23 16:32:58.919839 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/<a href="http://11@10.0.200.11:5060" target="_blank">11@10.0.200.11:5060</a>) State Change CS_HANGUP -> CS_REPORTING<br>2018-07-23 16:32:58.919839 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/<a href="http://11@10.0.200.11:5060" target="_blank">11@10.0.200.11:5060</a>) Running State Change CS_REPORTING (Cur 1 Tot 233)<br>2018-07-23 16:32:58.919839 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/<a href="http://11@10.0.200.11:5060" target="_blank">11@10.0.200.11:5060</a>) State REPORTING<br>2018-07-23 16:32:58.919839 [DEBUG] switch_core_state_machine.c:174 sofia/internal/<a href="http://11@10.0.200.11:5060" target="_blank">11@10.0.200.11:5060</a> Standard REPORTING, cause: LOSE_RACE<br>2018-07-23 16:32:58.919839 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/<a href="http://11@10.0.200.11:5060" target="_blank">11@10.0.200.11:5060</a>) State REPORTING going to sleep<br>2018-07-23 16:32:58.919839 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/<a href="http://11@10.0.200.11:5060" target="_blank">11@10.0.200.11:5060</a>) State Change CS_REPORTING -> CS_DESTROY<br>2018-07-23 16:32:58.919839 [DEBUG] switch_core_session.c:1665 Session 231 (sofia/internal/<a href="http://11@10.0.200.11:5060" target="_blank">11@10.0.200.11:5060</a>) Locked, Waiting on external entities<br>2018-07-23 16:32:58.919839 [NOTICE] switch_core_session.c:1683 Session 231 (sofia/internal/<a href="http://11@10.0.200.11:5060" target="_blank">11@10.0.200.11:5060</a>) Ended<br>2018-07-23 16:32:58.919839 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/<a href="http://11@10.0.200.11:5060" target="_blank">11@10.0.200.11:5060</a> [CS_DESTROY]<br>2018-07-23 16:32:58.919839 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/<a href="http://11@10.0.200.11:5060" target="_blank">11@10.0.200.11:5060</a>) Running State Change CS_DESTROY (Cur 0 Tot 233)<br>2018-07-23 16:32:58.919839 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/<a href="http://11@10.0.200.11:5060" target="_blank">11@10.0.200.11:5060</a>) State DESTROY<br>2018-07-23 16:32:58.919839 [DEBUG] mod_sofia.c:343 sofia/internal/<a href="http://11@10.0.200.11:5060" target="_blank">11@10.0.200.11:5060</a> SOFIA DESTROY<br>2018-07-23 16:32:58.919839 [DEBUG] switch_core_state_machine.c:181 sofia/internal/<a href="http://11@10.0.200.11:5060" target="_blank">11@10.0.200.11:5060</a> Standard DESTROY<br>2018-07-23 16:32:58.919839 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/<a href="http://11@10.0.200.11:5060" target="_blank">11@10.0.200.11:5060</a>) State DESTROY going to sleep<br><br></div></div>
_________________________________________________________________________<br>
Professional FreeSWITCH Services<br>
<a href="mailto:sales@freeswitch.com" target="_blank">sales@freeswitch.com</a><br>
<a href="https://freeswitch.com" rel="noreferrer" target="_blank">https://freeswitch.com</a><br>
<br>
Official FreeSWITCH Sites<br>
<a href="https://freeswitch.com/oss" rel="noreferrer" target="_blank">https://freeswitch.com/oss</a><br>
<a href="https://freeswitch.org/confluence" rel="noreferrer" target="_blank">https://freeswitch.org/confluence</a><br>
<a href="https://cluecon.com" rel="noreferrer" target="_blank">https://cluecon.com</a><br>
<br>
FreeSWITCH-users mailing list<br>
<a href="mailto:FreeSWITCH-users@lists.freeswitch.org" target="_blank">FreeSWITCH-users@lists.freeswitch.org</a><br>
<a href="http://lists.freeswitch.org/mailman/listinfo/freeswitch-users" rel="noreferrer" target="_blank">http://lists.freeswitch.org/mailman/listinfo/freeswitch-users</a><br>
UNSUBSCRIBE:<a href="http://lists.freeswitch.org/mailman/options/freeswitch-users" rel="noreferrer" target="_blank">http://lists.freeswitch.org/mailman/options/freeswitch-users</a><br>
<a href="https://freeswitch.com" rel="noreferrer" target="_blank">https://freeswitch.com</a></blockquote></div>