clicktocall issue:NO_ROUTE_DESTINATION
180 views
Skip to first unread message
Mountain King
Jun 30, 2016, 11:32:52 AM6/30/16
to 2600hz-dev
when using clicktocall function, will bring about NO_ROUTE_DESTINATION error, the one is calling to callee for example 12345678901, and the other is callee was called,but when transfer to caller can't find dialplan;
=================================
callee can't called
2016-06-29 18:31:36.818449 [INFO] kazoo_node.c:625 exec: originate({origination_callee_id_number='+12345678901',origination_callee_id_name='12345678901',origination_caller_id_number='11223344',origination_caller_id_name='Device QuickCall',ignore_early_media='true',continue_on_fail='true',call_timeout=30,originate_timeout=30,ecallmgr_Fetch-ID='72b1d2999b5901b6f67779b755e82d16',ecallmgr_Ecallmgr-Node='ecal...@mcc-kazoo02.sjz.sankuai.info',ecallmgr_Auto-Call-Rule-ID='26f66860-cc02-47da-8a63-b9a0d5fde347',ecallmgr_Authorizing-Type='device',inherit_codec='false',ecallmgr_Authorizing-ID='31e479fb292c414fe62e928edbabb619',ecallmgr_Retain-CID='true',sip_auto_answer='true',ecallmgr_Account-ID='07a049bfcfd1685c42a8762a4897626f',local_var_clobber='true'}[ecallmgr_Auto-Call-Rule-ID='26f66860-cc02-47da-8a63-b9a0d5fde347',ecallmgr_Authorizing-Type='device',inherit_codec='false',ecallmgr_Authorizing-ID='31e479fb292c414fe62e928edbabb619',ecallmgr_Retain-CID='true',sip_auto_answer='true',ecallmgr_Account-ID='07a049bfcfd1685c42a8762a4897626f',ecallmgr_Realm='sip.meituan.com',origination_uuid=4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall]loopback/+12345678901/context_2 'm:^:unset:origination_callee_id_number^unset:origination_callee_id_name^unset:origination_caller_id_number^unset:origination_caller_id_name^unset:ignore_early_media^unset:continue_on_fail^unset:call_timeout^unset:originate_timeout^unset:ecallmgr_Auto-Call-Rule-ID^unset:inherit_codec^unset:sip_auto_answer^unset:ignore_early_media^unset:group_confirm_key^unset:group_confirm_cancel_timeout^unset:group_confirm_file^unset:sip_h_Call-Info^unset:sip_h_Alert-Info^unset:alert_info^unset:sip_invite_params^set:sip_auto_answer=false^transfer:+11223344 XML context_2' inline)
2016-06-29 18:31:36.818449 [DEBUG] switch_event.c:1688 Parsing variable [origination_uuid]=[4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall]
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:36.818449 [DEBUG] switch_core_session.c:612 loopback/+12345678901-a set UUID=4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:36.818449 [DEBUG] switch_core_state_machine.c:472 (loopback/+12345678901-a) Running State Change CS_INIT
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:36.818449 [DEBUG] switch_core_state_machine.c:512 (loopback/+12345678901-a) State INIT
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:36.818449 [DEBUG] mod_loopback.c:359 (loopback/+12345678901-a) State Change CS_INIT -> CS_ROUTING
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:36.818449 [DEBUG] switch_core_session.c:1388 Send signal loopback/+12345678901-a [BREAK]
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:36.818449 [DEBUG] mod_loopback.c:598 loopback/+12345678901-a CHANNEL KILL
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:36.818449 [DEBUG] switch_core_state_machine.c:512 (loopback/+12345678901-a) State INIT going to sleep
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:36.818449 [DEBUG] switch_core_state_machine.c:472 (loopback/+12345678901-a) Running State Change CS_ROUTING
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:36.818449 [DEBUG] switch_core_state_machine.c:528 (loopback/+12345678901-a) State ROUTING
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:36.818449 [DEBUG] mod_loopback.c:391 loopback/+12345678901-a CHANNEL ROUTING
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:36.818449 [DEBUG] switch_ivr_originate.c:67 (loopback/+12345678901-a) State Change CS_ROUTING -> CS_CONSUME_MEDIA
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:36.818449 [DEBUG] switch_core_session.c:1388 Send signal loopback/+12345678901-a [BREAK]
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:36.818449 [DEBUG] mod_loopback.c:598 loopback/+12345678901-a CHANNEL KILL
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:36.818449 [DEBUG] switch_core_state_machine.c:528 (loopback/+12345678901-a) State ROUTING going to sleep
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:36.818449 [DEBUG] switch_core_state_machine.c:472 (loopback/+12345678901-a) Running State Change CS_CONSUME_MEDIA
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:36.818449 [DEBUG] switch_core_state_machine.c:547 (loopback/+12345678901-a) State CONSUME_MEDIA
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:36.818449 [DEBUG] mod_loopback.c:658 CHANNEL CONSUME_MEDIA
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:36.818449 [DEBUG] switch_core_state_machine.c:547 (loopback/+12345678901-a) State CONSUME_MEDIA going to sleep
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:39.918412 [NOTICE] mod_loopback.c:561 Hangup loopback/+12345678901-a [CS_CONSUME_MEDIA] [NO_ROUTE_DESTINATION]
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:39.918412 [DEBUG] switch_channel.c:3222 Send signal loopback/+12345678901-a [KILL]
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:39.918412 [DEBUG] mod_loopback.c:598 loopback/+12345678901-a CHANNEL KILL
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:39.918412 [DEBUG] switch_core_session.c:1388 Send signal loopback/+12345678901-a [BREAK]
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:39.918412 [DEBUG] mod_loopback.c:598 loopback/+12345678901-a CHANNEL KILL
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:39.918412 [DEBUG] switch_core_state_machine.c:472 (loopback/+12345678901-a) Running State Change CS_HANGUP
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:39.918412 [DEBUG] switch_core_state_machine.c:735 (loopback/+12345678901-a) Callstate Change DOWN -> HANGUP
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:39.918412 [DEBUG] switch_core_state_machine.c:737 (loopback/+12345678901-a) State HANGUP
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:39.918412 [DEBUG] mod_loopback.c:545 loopback/+12345678901-a CHANNEL HANGUP
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:39.918412 [DEBUG] switch_core_state_machine.c:60 loopback/+12345678901-a Standard HANGUP, cause: NO_ROUTE_DESTINATION
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:39.918412 [DEBUG] switch_core_state_machine.c:737 (loopback/+12345678901-a) State HANGUP going to sleep
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:39.918412 [DEBUG] switch_core_state_machine.c:504 (loopback/+12345678901-a) State Change CS_HANGUP -> CS_REPORTING
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:39.918412 [DEBUG] switch_core_session.c:1388 Send signal loopback/+12345678901-a [BREAK]
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:39.918412 [DEBUG] mod_loopback.c:598 loopback/+12345678901-a CHANNEL KILL
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:39.918412 [DEBUG] switch_core_state_machine.c:472 (loopback/+12345678901-a) Running State Change CS_REPORTING
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:39.918412 [DEBUG] switch_core_state_machine.c:823 (loopback/+12345678901-a) State REPORTING
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:39.918412 [DEBUG] switch_core_state_machine.c:104 loopback/+12345678901-a Standard REPORTING, cause: NO_ROUTE_DESTINATION
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:39.918412 [DEBUG] switch_core_state_machine.c:823 (loopback/+12345678901-a) State REPORTING going to sleep
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:39.918412 [DEBUG] switch_core_state_machine.c:498 (loopback/+12345678901-a) State Change CS_REPORTING -> CS_DESTROY
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:39.918412 [DEBUG] switch_core_session.c:1388 Send signal loopback/+12345678901-a [BREAK]
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:39.918412 [DEBUG] mod_loopback.c:598 loopback/+12345678901-a CHANNEL KILL
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:39.918412 [DEBUG] switch_core_session.c:1615 Session 7076 (loopback/+12345678901-a) Locked, Waiting on external entities
2016-06-29 18:31:39.938401 [INFO] kazoo_node.c:625 exec: uuid_setvar_multi(4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall ecallmgr_Channel-Authorized=true;ecallmgr_Account-ID=07a049bfcfd1685c42a8762a4897626f)
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:39.938401 [NOTICE] switch_core_session.c:1633 Session 7076 (loopback/+12345678901-a) Ended
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:39.938401 [NOTICE] switch_core_session.c:1637 Close Channel loopback/+12345678901-a [CS_DESTROY]
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:39.938401 [DEBUG] switch_core_state_machine.c:626 (loopback/+12345678901-a) Running State Change CS_DESTROY
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:39.938401 [DEBUG] switch_core_state_machine.c:636 (loopback/+12345678901-a) State DESTROY
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:39.938401 [DEBUG] switch_core_state_machine.c:111 loopback/+12345678901-a Standard DESTROY
4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall 2016-06-29 18:31:39.938401 [DEBUG] switch_core_state_machine.c:636 (loopback/+12345678901-a) State DESTROY going to sleep
========================================
callee was called but caller can't transfer to
2016-06-29 18:34:50.158155 [INFO] kazoo_node.c:625 exec: originate({origination_callee_id_number='+xxxxxxxxxxx',origination_callee_id_name='xxxxxxxxxxx',origination_caller_id_number='11223344',origination_caller_id_name='Device QuickCall',ignore_early_media='true',continue_on_fail='true',call_timeout=30,originate_timeout=30,ecallmgr_Fetch-ID='50959064daeb4618e061642323748a91',ecallmgr_Ecallmgr-Node='ecal...@mcc-kazoo02.sjz.sankuai.info',ecallmgr_Auto-Call-Rule-ID='8e8fd6de-8726-4881-b92f-bede1f712596',ecallmgr_Authorizing-Type='device',inherit_codec='false',ecallmgr_Authorizing-ID='8fb9289d0313d57d04ad4112564b9312',ecallmgr_Retain-CID='true',sip_auto_answer='true',ecallmgr_Account-ID='07a049bfcfd1685c42a8762a4897626f',local_var_clobber='true'}[ecallmgr_Auto-Call-Rule-ID='8e8fd6de-8726-4881-b92f-bede1f712596',ecallmgr_Authorizing-Type='device',inherit_codec='false',ecallmgr_Authorizing-ID='8fb9289d0313d57d04ad4112564b9312',ecallmgr_Retain-CID='true',sip_auto_answer='true',ecallmgr_Account-ID='07a049bfcfd1685c42a8762a4897626f',ecallmgr_Realm='sip.meituan.com',origination_uuid=f62bb835a781fa4ec508c620a1beee37e535-autocall]loopback/+xxxxxxxxxxx/context_2 'm:^:unset:origination_callee_id_number^unset:origination_callee_id_name^unset:origination_caller_id_number^unset:origination_caller_id_name^unset:ignore_early_media^unset:continue_on_fail^unset:call_timeout^unset:originate_timeout^unset:ecallmgr_Auto-Call-Rule-ID^unset:inherit_codec^unset:sip_auto_answer^unset:ignore_early_media^unset:group_confirm_key^unset:group_confirm_cancel_timeout^unset:group_confirm_file^unset:sip_h_Call-Info^unset:sip_h_Alert-Info^unset:alert_info^unset:sip_invite_params^set:sip_auto_answer=false^transfer:+11223344 XML context_2' inline)
2016-06-29 18:34:50.158155 [DEBUG] switch_event.c:1688 Parsing variable [origination_callee_id_number]=[+xxxxxxxxxxx]
2016-06-29 18:34:50.158155 [DEBUG] switch_event.c:1688 Parsing variable [origination_callee_id_name]=[xxxxxxxxxxx]
2016-06-29 18:34:50.158155 [DEBUG] switch_event.c:1688 Parsing variable [origination_uuid]=[f62bb835a781fa4ec508c620a1beee37e535-autocall]
1ba01ea2-3de5-11e6-87d6-fbf5318198dc 2016-06-29 18:34:50.158155 [NOTICE] switch_channel.c:1055 New Channel loopback/+xxxxxxxxxxx/context_2-a [1ba01ea2-3de5-11e6-87d6-fbf5318198dc]
1ba01ea2-3de5-11e6-87d6-fbf5318198dc 2016-06-29 18:34:50.158155 [DEBUG] mod_loopback.c:157 loopback/+xxxxxxxxxxx/context_2-a setup codec L16/8000/20
1ba01ea2-3de5-11e6-87d6-fbf5318198dc 2016-06-29 18:34:50.158155 [NOTICE] switch_channel.c:1053 Rename Channel loopback/+xxxxxxxxxxx/context_2-a->loopback/+xxxxxxxxxxx-a [1ba01ea2-3de5-11e6-87d6-fbf5318198dc]
1ba01ea2-3de5-11e6-87d6-fbf5318198dc 2016-06-29 18:34:50.158155 [DEBUG] mod_loopback.c:1168 (loopback/+xxxxxxxxxxx-a) State Change CS_NEW -> CS_INIT
1ba01ea2-3de5-11e6-87d6-fbf5318198dc 2016-06-29 18:34:50.158155 [DEBUG] switch_core_session.c:1388 Send signal loopback/+xxxxxxxxxxx-a [BREAK]
1ba01ea2-3de5-11e6-87d6-fbf5318198dc 2016-06-29 18:34:50.158155 [DEBUG] mod_loopback.c:598 loopback/+xxxxxxxxxxx-a CHANNEL KILL
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:34:50.158155 [DEBUG] switch_core_session.c:612 loopback/+xxxxxxxxxxx-a set UUID=f62bb835a781fa4ec508c620a1beee37e535-autocall
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:34:50.158155 [DEBUG] switch_core_state_machine.c:472 (loopback/+xxxxxxxxxxx-a) Running State Change CS_INIT
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:34:50.158155 [DEBUG] switch_core_state_machine.c:512 (loopback/+xxxxxxxxxxx-a) State INIT
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.158155 [NOTICE] switch_channel.c:1055 New Channel loopback/+xxxxxxxxxxx-b [1ba06aa6-3de5-11e6-87d8-fbf5318198dc]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.158155 [DEBUG] mod_loopback.c:157 loopback/+xxxxxxxxxxx-b setup codec L16/8000/20
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.158155 [DEBUG] mod_loopback.c:273 (loopback/+xxxxxxxxxxx-b) State Change CS_NEW -> CS_INIT
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.158155 [DEBUG] switch_core_session.c:1388 Send signal loopback/+xxxxxxxxxxx-b [BREAK]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.158155 [DEBUG] mod_loopback.c:598 loopback/+xxxxxxxxxxx-b CHANNEL KILL
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:34:50.158155 [DEBUG] mod_loopback.c:359 (loopback/+xxxxxxxxxxx-a) State Change CS_INIT -> CS_ROUTING
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:34:50.158155 [DEBUG] switch_core_session.c:1388 Send signal loopback/+xxxxxxxxxxx-a [BREAK]
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:34:50.158155 [DEBUG] mod_loopback.c:598 loopback/+xxxxxxxxxxx-a CHANNEL KILL
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:34:50.158155 [DEBUG] switch_core_state_machine.c:512 (loopback/+xxxxxxxxxxx-a) State INIT going to sleep
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.158155 [DEBUG] switch_core_state_machine.c:472 (loopback/+xxxxxxxxxxx-b) Running State Change CS_INIT
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.158155 [DEBUG] switch_core_state_machine.c:512 (loopback/+xxxxxxxxxxx-b) State INIT
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.158155 [DEBUG] mod_loopback.c:359 (loopback/+xxxxxxxxxxx-b) State Change CS_INIT -> CS_ROUTING
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.158155 [DEBUG] switch_core_session.c:1388 Send signal loopback/+xxxxxxxxxxx-b [BREAK]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.158155 [DEBUG] mod_loopback.c:598 loopback/+xxxxxxxxxxx-b CHANNEL KILL
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.158155 [DEBUG] switch_core_state_machine.c:512 (loopback/+xxxxxxxxxxx-b) State INIT going to sleep
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:34:50.158155 [DEBUG] switch_core_state_machine.c:472 (loopback/+xxxxxxxxxxx-a) Running State Change CS_ROUTING
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.158155 [DEBUG] switch_core_state_machine.c:472 (loopback/+xxxxxxxxxxx-b) Running State Change CS_ROUTING
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:34:50.158155 [DEBUG] switch_core_state_machine.c:528 (loopback/+xxxxxxxxxxx-a) State ROUTING
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:34:50.158155 [DEBUG] mod_loopback.c:391 loopback/+xxxxxxxxxxx-a CHANNEL ROUTING
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:34:50.158155 [DEBUG] switch_ivr_originate.c:67 (loopback/+xxxxxxxxxxx-a) State Change CS_ROUTING -> CS_CONSUME_MEDIA
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.158155 [DEBUG] switch_channel.c:2184 (loopback/+xxxxxxxxxxx-b) Callstate Change DOWN -> RINGING
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:34:50.158155 [DEBUG] switch_core_session.c:1388 Send signal loopback/+xxxxxxxxxxx-a [BREAK]
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:34:50.158155 [DEBUG] mod_loopback.c:598 loopback/+xxxxxxxxxxx-a CHANNEL KILL
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:34:50.158155 [DEBUG] switch_core_state_machine.c:528 (loopback/+xxxxxxxxxxx-a) State ROUTING going to sleep
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:34:50.158155 [DEBUG] switch_core_state_machine.c:472 (loopback/+xxxxxxxxxxx-a) Running State Change CS_CONSUME_MEDIA
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:34:50.158155 [DEBUG] switch_core_state_machine.c:547 (loopback/+xxxxxxxxxxx-a) State CONSUME_MEDIA
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:34:50.158155 [DEBUG] mod_loopback.c:658 CHANNEL CONSUME_MEDIA
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:34:50.158155 [DEBUG] switch_core_state_machine.c:547 (loopback/+xxxxxxxxxxx-a) State CONSUME_MEDIA going to sleep
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.158155 [DEBUG] switch_core_state_machine.c:528 (loopback/+xxxxxxxxxxx-b) State ROUTING
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.158155 [DEBUG] mod_loopback.c:391 loopback/+xxxxxxxxxxx-b CHANNEL ROUTING
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.158155 [DEBUG] switch_core_state_machine.c:166 loopback/+xxxxxxxxxxx-b Standard ROUTING
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.158155 [INFO] mod_dialplan_xml.c:635 Processing Device QuickCall <11223344>->+xxxxxxxxxxx in context context_2
2016-06-29 18:34:50.378176 [INFO] kazoo_node.c:625 exec: uuid_setvar_multi(f62bb835a781fa4ec508c620a1beee37e535-autocall ecallmgr_Channel-Authorized=true;ecallmgr_Account-ID=07a049bfcfd1685c42a8762a4897626f)
2016-06-29 18:34:50.378176 [INFO] kazoo_node.c:625 exec: uuid_setvar_multi(1ba06aa6-3de5-11e6-87d8-fbf5318198dc ecallmgr_Channel-Authorized=true;ecallmgr_Account-ID=07a049bfcfd1685c42a8762a4897626f)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b parsing [context_2->park] continue=false
1ba06aa6-3de5-11e6-87d8-fbf5318198dc |--- Dialplan: Processing recursive conditions level:1 [park_recur_1] require-nested=TRUE
1ba06aa6-3de5-11e6-87d8-fbf5318198dc |--- Dialplan: loopback/+xxxxxxxxxxx-b Regex (PASS) [park_recur_1] variable_ecallmgr_Bridge-ID() =~ /^$/ break=on-false
1ba06aa6-3de5-11e6-87d8-fbf5318198dc |--- Dialplan: loopback/+xxxxxxxxxxx-b Action export(ecallmgr_Bridge-ID=${UUID})
1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Absolute Condition [park]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action log(NOTICE log|${uuid}|ecal...@mcc-kazoo02.sjz.sankuai.info won call control)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action export(ecallmgr_Ecallmgr-Node=ecal...@mcc-kazoo02.sjz.sankuai.info)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ringback=%(2000,4000,440,480))
1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(transfer_ringback=%(2000,4000,440,480))
1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Ecallmgr-Node=ecal...@mcc-kazoo02.sjz.sankuai.info)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Auto-Call-Rule-ID=8e8fd6de-8726-4881-b92f-bede1f712596)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Authorizing-Type=device)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Authorizing-ID=8fb9289d0313d57d04ad4112564b9312)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Retain-CID=true)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Account-ID=07a049bfcfd1685c42a8762a4897626f)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Realm=sip.meituan.com)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Fetch-ID=50959064daeb4618e061642323748a91)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action park()
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_core_state_machine.c:216 (loopback/+xxxxxxxxxxx-b) State Change CS_ROUTING -> CS_EXECUTE
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_core_session.c:1388 Send signal loopback/+xxxxxxxxxxx-b [BREAK]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_loopback.c:598 loopback/+xxxxxxxxxxx-b CHANNEL KILL
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_core_state_machine.c:528 (loopback/+xxxxxxxxxxx-b) State ROUTING going to sleep
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_core_state_machine.c:472 (loopback/+xxxxxxxxxxx-b) Running State Change CS_EXECUTE
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_core_state_machine.c:535 (loopback/+xxxxxxxxxxx-b) State EXECUTE
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_loopback.c:433 loopback/+xxxxxxxxxxx-b CHANNEL EXECUTE
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_core_state_machine.c:258 loopback/+xxxxxxxxxxx-b Standard EXECUTE
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b export(ecallmgr_Bridge-ID=1ba06aa6-3de5-11e6-87d8-fbf5318198dc)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_channel.c:1247 EXPORT (export_vars) [ecallmgr_Bridge-ID]=[1ba06aa6-3de5-11e6-87d8-fbf5318198dc]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b log(NOTICE log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|ecal...@mcc-kazoo02.sjz.sankuai.info won call control)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [NOTICE] mod_dptools.c:1650 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|ecal...@mcc-kazoo02.sjz.sankuai.info won call control
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b export(ecallmgr_Ecallmgr-Node=ecal...@mcc-kazoo02.sjz.sankuai.info)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_channel.c:1247 EXPORT (export_vars) [ecallmgr_Ecallmgr-Node]=[ecal...@mcc-kazoo02.sjz.sankuai.info]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ringback=%(2000,4000,440,480))
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ringback]=[%(2000,4000,440,480)]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(transfer_ringback=%(2000,4000,440,480))
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [transfer_ringback]=[%(2000,4000,440,480)]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Ecallmgr-Node=ecal...@mcc-kazoo02.sjz.sankuai.info)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Ecallmgr-Node]=[ecal...@mcc-kazoo02.sjz.sankuai.info]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Auto-Call-Rule-ID=8e8fd6de-8726-4881-b92f-bede1f712596)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Auto-Call-Rule-ID]=[8e8fd6de-8726-4881-b92f-bede1f712596]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Authorizing-Type=device)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Authorizing-Type]=[device]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Authorizing-ID=8fb9289d0313d57d04ad4112564b9312)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Authorizing-ID]=[8fb9289d0313d57d04ad4112564b9312]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Retain-CID=true)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Retain-CID]=[true]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Account-ID=07a049bfcfd1685c42a8762a4897626f)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Account-ID]=[07a049bfcfd1685c42a8762a4897626f]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Realm=sip.meituan.com)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Realm]=[sip.meituan.com]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Fetch-ID=50959064daeb4618e061642323748a91)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Fetch-ID]=[50959064daeb4618e061642323748a91]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b park()
2016-06-29 18:34:50.438152 [INFO] kazoo_node.c:625 exec: uuid_setvar_multi(1ba06aa6-3de5-11e6-87d8-fbf5318198dc ecallmgr_Application-Name=callflow;ecallmgr_Application-Node=whistl...@mcc-kazoo01.sjz.sankuai.info;ecallmgr_Ecallmgr-Node=ecal...@mcc-kazoo02.sjz.sankuai.info;ecallmgr_Auto-Call-Rule-ID=8e8fd6de-8726-4881-b92f-bede1f712596;ecallmgr_Authorizing-Type=device;ecallmgr_Authorizing-ID=8fb9289d0313d57d04ad4112564b9312;ecallmgr_Retain-CID=true;ecallmgr_Account-ID=07a049bfcfd1685c42a8762a4897626f;ecallmgr_Realm=sip.meituan.com;ecallmgr_Fetch-ID=50959064daeb4618e061642323748a91)
2016-06-29 18:34:50.478183 [INFO] kazoo_node.c:625 exec: uuid_setvar_multi(1ba06aa6-3de5-11e6-87d8-fbf5318198dc effective_caller_id_name=Device QuickCall;effective_caller_id_number=11223344;ecallmgr_Application-Name=callflow;ecallmgr_Application-Node=whistl...@mcc-kazoo01.sjz.sankuai.info;ecallmgr_Ecallmgr-Node=ecal...@mcc-kazoo02.sjz.sankuai.info;ecallmgr_Auto-Call-Rule-ID=8e8fd6de-8726-4881-b92f-bede1f712596;ecallmgr_Authorizing-Type=device;ecallmgr_Authorizing-ID=8fb9289d0313d57d04ad4112564b9312;ecallmgr_Retain-CID=true;ecallmgr_Account-ID=07a049bfcfd1685c42a8762a4897626f;ecallmgr_Realm=sip.meituan.com;ecallmgr_Fetch-ID=50959064daeb4618e061642323748a91)
2016-06-29 18:34:51.078137 [INFO] kazoo_node.c:625 exec: uuid_setvar(1ba06aa6-3de5-11e6-87d8-fbf5318198dc ecallmgr_Recording-Url http://mcc-test/recording/call_recording_1ba06aa6-3de5-11e6-87d8-fbf5318198dc.mp3)
2016-06-29 18:34:51.158188 [INFO] kazoo_node.c:625 exec: uuid_setvar_multi(1ba06aa6-3de5-11e6-87d8-fbf5318198dc effective_caller_id_name=Device QuickCall;effective_caller_id_number=11223344;ecallmgr_Application-Name=callflow;ecallmgr_Application-Node=whistl...@mcc-kazoo01.sjz.sankuai.info;ecallmgr_Ecallmgr-Node=ecal...@mcc-kazoo02.sjz.sankuai.info;ecallmgr_Auto-Call-Rule-ID=8e8fd6de-8726-4881-b92f-bede1f712596;ecallmgr_Authorizing-Type=device;ecallmgr_Authorizing-ID=8fb9289d0313d57d04ad4112564b9312;ecallmgr_Retain-CID=true;ecallmgr_Account-ID=07a049bfcfd1685c42a8762a4897626f;ecallmgr_Realm=sip.meituan.com;ecallmgr_Fetch-ID=50959064daeb4618e061642323748a91)
2016-06-29 18:34:51.158188 [NOTICE] kazoo_node.c:302 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|building xferext extension: set ignore_display_updates=true
2016-06-29 18:34:51.158188 [NOTICE] kazoo_node.c:302 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|building xferext extension: set continue_on_fail=true
2016-06-29 18:34:51.158188 [NOTICE] kazoo_node.c:302 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|building xferext extension: export sip_redirect_context=context_2
2016-06-29 18:34:51.158188 [NOTICE] kazoo_node.c:302 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|building xferext extension: set hangup_after_bridge=true
2016-06-29 18:34:51.158188 [NOTICE] kazoo_node.c:302 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|building xferext extension: export ecallmgr_Inception=${ecallmgr_Inception}
2016-06-29 18:34:51.158188 [NOTICE] kazoo_node.c:302 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|building xferext extension: bridge {ignore_display_updates='true',ecallmgr_Account-ID='07a049bfcfd1685c42a8762a4897626f',ecallmgr_Reseller-ID='07a049bfcfd1685c42a8762a4897626f',effective_caller_id_number='11223344',effective_caller_id_name='Device QuickCall',origination_caller_id_number='11223344',origination_caller_id_name='Device QuickCall',ignore_early_media='false',local_var_clobber='true'}[sip_auth_password='mt@009009',sip_auth_username='admin',sip_from_uri='sip:1122...@x.x.6.205',ecallmgr_Global-Resource='false',ecallmgr_Resource-ID='9417ff08859d9c4d65fcc02ab86b2fd0',ecallmgr_E164-Destination='+xxxxxxxxxxx',ecallmgr_Original-Number='0xxxxxxxxxxx',absolute_codec_string='^^:PCMA',leg_progress_timeout='30',effective_callee_id_number='0xxxxxxxxxxx',effective_callee_id_name='0xxxxxxxxxxx']sofia/sipinterface_1/0xxxxx...@x.x.6.205|[sip_auth_password='mt@009009',sip_auth_username='admin',sip_from_uri='sip:1122...@x.x.0.75',ecallmgr_Global-Resource='false',ecallmgr_Resource-ID='dc57fba99f6d7d73ebdba7978aceb58f',ecallmgr_E164-Destination='+xxxxxxxxxxx',ecallmgr_Original-Number='0xxxxxxxxxxx',absolute_codec_string='^^:PCMA',leg_progress_timeout='30',effective_callee_id_number='0xxxxxxxxxxx',effective_callee_id_name='0xxxxxxxxxxx']sofia/sipinterface_1/0xxxxx...@x.x.0.75
2016-06-29 18:34:51.158188 [NOTICE] kazoo_node.c:302 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|building xferext extension: event Event-Name=CUSTOM,Event-Subclass=whistle::masquerade,whistle_event_name=CHANNEL_EXECUTE_COMPLETE,whistle_application_name=bridge
2016-06-29 18:34:51.158188 [NOTICE] kazoo_node.c:302 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|building xferext extension: park
2016-06-29 18:34:51.158188 [NOTICE] kazoo_node.c:305 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|transfered call to xferext extension
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:51.158188 [DEBUG] switch_core_session.c:1188 Send signal loopback/+xxxxxxxxxxx-b [BREAK]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:51.158188 [DEBUG] mod_loopback.c:598 loopback/+xxxxxxxxxxx-b CHANNEL KILL
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_channel.c:3103 (loopback/+xxxxxxxxxxx-b) State Change CS_EXECUTE -> CS_ROUTING
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_core_session.c:1388 Send signal loopback/+xxxxxxxxxxx-b [BREAK]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] mod_loopback.c:598 loopback/+xxxxxxxxxxx-b CHANNEL KILL
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_core_state_machine.c:535 (loopback/+xxxxxxxxxxx-b) State EXECUTE going to sleep
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_core_state_machine.c:472 (loopback/+xxxxxxxxxxx-b) Running State Change CS_ROUTING
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_core_state_machine.c:528 (loopback/+xxxxxxxxxxx-b) State ROUTING
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] mod_loopback.c:391 loopback/+xxxxxxxxxxx-b CHANNEL ROUTING
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_core_state_machine.c:166 loopback/+xxxxxxxxxxx-b Standard ROUTING
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_core_state_machine.c:186 (loopback/+xxxxxxxxxxx-b) State Change CS_ROUTING -> CS_EXECUTE
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_core_session.c:1388 Send signal loopback/+xxxxxxxxxxx-b [BREAK]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] mod_loopback.c:598 loopback/+xxxxxxxxxxx-b CHANNEL KILL
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_core_state_machine.c:528 (loopback/+xxxxxxxxxxx-b) State ROUTING going to sleep
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_core_state_machine.c:472 (loopback/+xxxxxxxxxxx-b) Running State Change CS_EXECUTE
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_core_state_machine.c:535 (loopback/+xxxxxxxxxxx-b) State EXECUTE
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] mod_loopback.c:433 loopback/+xxxxxxxxxxx-b CHANNEL EXECUTE
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_core_state_machine.c:258 loopback/+xxxxxxxxxxx-b Standard EXECUTE
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ignore_display_updates=true)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ignore_display_updates]=[true]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(continue_on_fail=true)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [continue_on_fail]=[true]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b export(sip_redirect_context=context_2)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_channel.c:1247 EXPORT (export_vars) [sip_redirect_context]=[context_2]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(hangup_after_bridge=true)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [hangup_after_bridge]=[true]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b export(ecallmgr_Inception=)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_channel.c:1247 EXPORT (export_vars) [ecallmgr_Inception]=[UNDEF]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b bridge({ignore_display_updates=true,ecallmgr_Account-ID=07a049bfcfd1685c42a8762a4897626f,ecallmgr_Reseller-ID=07a049bfcfd1685c42a8762a4897626f,effective_caller_id_number=11223344,effective_caller_id_name=Device QuickCall,origination_caller_id_number=11223344,origination_caller_id_name=Device QuickCall,ignore_early_media=false,local_var_clobber=true}[sip_auth_password=mt@009009,sip_auth_username=admin,sip_from_uri=sip:1122...@x.x.6.205,ecallmgr_Global-Resource=false,ecallmgr_Resource-ID=9417ff08859d9c4d65fcc02ab86b2fd0,ecallmgr_E164-Destination=+xxxxxxxxxxx,ecallmgr_Original-Number=0xxxxxxxxxxx,absolute_codec_string=^^:PCMA,leg_progress_timeout=30,effective_callee_id_number=0xxxxxxxxxxx,effective_callee_id_name=0xxxxxxxxxxx]sofia/sipinterface_1/0xxxxx...@x.x.6.205|[sip_auth_password=mt@009009,sip_auth_username=admin,sip_from_uri=sip:1122...@x.x.0.75,ecallmgr_Global-Resource=false,ecallmgr_Resource-ID=dc57fba99f6d7d73ebdba7978aceb58f,ecallmgr_E164-Destination=+xxxxxxxxxxx,ecallmgr_Original-Number=0xxxxxxxxxxx,absolute_codec_string=^^:PCMA,leg_progress_timeout=30,effective_callee_id_number=0xxxxxxxxxxx,effective_callee_id_name=0xxxxxxxxxxx]sofia/sipinterface_1/0xxxxx...@x.x.0.75)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_channel.c:1201 loopback/+xxxxxxxxxxx-b EXPORTING[export_vars] [ecallmgr_Bridge-ID]=[1ba06aa6-3de5-11e6-87d8-fbf5318198dc] to event
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_channel.c:1201 loopback/+xxxxxxxxxxx-b EXPORTING[export_vars] [ecallmgr_Ecallmgr-Node]=[ecal...@mcc-kazoo02.sjz.sankuai.info] to event
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_channel.c:1201 loopback/+xxxxxxxxxxx-b EXPORTING[export_vars] [sip_redirect_context]=[context_2] to event
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_ivr_originate.c:2103 Parsing global variables
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_ivr_originate.c:2553 Parsing session specific variables
2016-06-29 18:34:51.178158 [DEBUG] switch_event.c:1688 Parsing variable [ecallmgr_E164-Destination]=[+xxxxxxxxxxx]
2016-06-29 18:34:51.178158 [DEBUG] switch_event.c:1688 Parsing variable [ecallmgr_Original-Number]=[0xxxxxxxxxxx]
2016-06-29 18:34:51.178158 [DEBUG] switch_event.c:1688 Parsing variable [effective_callee_id_number]=[0xxxxxxxxxxx]
2016-06-29 18:34:51.178158 [DEBUG] switch_event.c:1688 Parsing variable [effective_callee_id_name]=[0xxxxxxxxxxx]
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:51.178158 [NOTICE] switch_channel.c:1055 New Channel sofia/sipinterface_1/0xxxxx...@x.x.6.205 [1c3babba-3de5-11e6-8844-fbf5318198dc]
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] mod_sofia.c:4649 (sofia/sipinterface_1/0xxxxx...@x.x.6.205) State Change CS_NEW -> CS_INIT
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_core_session.c:1388 Send signal sofia/sipinterface_1/0xxxxx...@x.x.6.205 [BREAK]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_ivr_originate.c:2856 sofia/sipinterface_1/0xxxxx...@x.x.6.205 Setting leg progress timeout to 30
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_core_state_machine.c:472 (sofia/sipinterface_1/0xxxxx...@x.x.6.205) Running State Change CS_INIT
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_core_state_machine.c:512 (sofia/sipinterface_1/0xxxxx...@x.x.6.205) State INIT
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] mod_sofia.c:87 sofia/sipinterface_1/0xxxxx...@x.x.6.205 SOFIA INIT
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] sofia_glue.c:1232 sofia/sipinterface_1/0xxxxx...@x.x.6.205 sending invite version: 1.4.15 64bit
1c3babba-3de5-11e6-8844-fbf5318198dc Local SDP:
1c3babba-3de5-11e6-8844-fbf5318198dc v=0
1c3babba-3de5-11e6-8844-fbf5318198dc o=FreeSWITCH 1467177103 1467177104 IN IP4 172.31.10.248
1c3babba-3de5-11e6-8844-fbf5318198dc s=FreeSWITCH
1c3babba-3de5-11e6-8844-fbf5318198dc c=IN IP4 172.31.10.248
1c3babba-3de5-11e6-8844-fbf5318198dc t=0 0
1c3babba-3de5-11e6-8844-fbf5318198dc m=audio 19388 RTP/AVP 8 101 13
1c3babba-3de5-11e6-8844-fbf5318198dc a=rtpmap:8 PCMA/8000
1c3babba-3de5-11e6-8844-fbf5318198dc a=rtpmap:101 telephone-event/8000
1c3babba-3de5-11e6-8844-fbf5318198dc a=fmtp:101 0-16
1c3babba-3de5-11e6-8844-fbf5318198dc a=ptime:20
1c3babba-3de5-11e6-8844-fbf5318198dc a=sendrecv
1c3babba-3de5-11e6-8844-fbf5318198dc
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_core_state_machine.c:40 sofia/sipinterface_1/0xxxxx...@x.x.6.205 Standard INIT
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_core_state_machine.c:48 (sofia/sipinterface_1/0xxxxx...@x.x.6.205) State Change CS_INIT -> CS_ROUTING
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_core_session.c:1388 Send signal sofia/sipinterface_1/0xxxxx...@x.x.6.205 [BREAK]
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_core_state_machine.c:512 (sofia/sipinterface_1/0xxxxx...@x.x.6.205) State INIT going to sleep
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_core_state_machine.c:472 (sofia/sipinterface_1/0xxxxx...@x.x.6.205) Running State Change CS_ROUTING
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_core_state_machine.c:528 (sofia/sipinterface_1/0xxxxx...@x.x.6.205) State ROUTING
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] mod_sofia.c:123 sofia/sipinterface_1/0xxxxx...@x.x.6.205 SOFIA ROUTING
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_ivr_originate.c:67 (sofia/sipinterface_1/0xxxxx...@x.x.6.205) State Change CS_ROUTING -> CS_CONSUME_MEDIA
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_core_session.c:1388 Send signal sofia/sipinterface_1/0xxxxx...@x.x.6.205 [BREAK]
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_core_state_machine.c:528 (sofia/sipinterface_1/0xxxxx...@x.x.6.205) State ROUTING going to sleep
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_core_state_machine.c:472 (sofia/sipinterface_1/0xxxxx...@x.x.6.205) Running State Change CS_CONSUME_MEDIA
INVITE sip:0xxxxx...@x.x.6.205 SIP/2.0
To: <sip:0xxxxx...@x.x.6.205>1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_core_state_machine.c:547 (sofia/sipinterface_1/0xxxxx...@x.x.6.205) State CONSUME_MEDIA
Call-ID: 1c3babba-3de5-11e6-8844-fbf5318198dc
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_core_state_machine.c:547 (sofia/sipinterface_1/0xxxxx...@x.x.6.205) State CONSUME_MEDIA going to sleep
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_core_session.c:1053 Send signal sofia/sipinterface_1/0xxxxx...@x.x.6.205 [BREAK]
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] sofia.c:6614 Channel sofia/sipinterface_1/0xxxxx...@x.x.6.205 entering state [calling][0]
2016-06-29 18:34:51.198126 [INFO] kazoo_node.c:625 exec: uuid_setvar_multi(1c3babba-3de5-11e6-8844-fbf5318198dc ecallmgr_Channel-Authorized=true;ecallmgr_Reseller-ID=07a049bfcfd1685c42a8762a4897626f;ecallmgr_Account-ID=07a049bfcfd1685c42a8762a4897626f)
To: <sip:0xxxxx...@x.x.6.205>
Call-ID: 1c3babba-3de5-11e6-8844-fbf5318198dc
Contact: <sip:0xxxxx...@x.x.6.205:5060>
To: <sip:0xxxxx...@x.x.6.205>
Call-ID: 1c3babba-3de5-11e6-8844-fbf5318198dc
Contact: <sip:0xxxxx...@x.x.6.205:5060>
To: <sip:0xxxxx...@x.x.6.205>;tag=as62f43ad5
Call-ID: 1c3babba-3de5-11e6-8844-fbf5318198dc
Contact: <sip:0xxxxx...@x.x.6.205:5060>
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:59.098582 [DEBUG] switch_core_session.c:1053 Send signal sofia/sipinterface_1/0xxxxx...@x.x.6.205 [BREAK]
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:59.098582 [DEBUG] switch_core_session.c:1053 Send signal sofia/sipinterface_1/0xxxxx...@x.x.6.205 [BREAK]
To: <sip:0xxxxx...@x.x.6.205>;tag=as62f43ad5
Call-ID: 1c3babba-3de5-11e6-8844-fbf5318198dc
Contact: <sip:0xxxxx...@x.x.6.205:5060>
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:59.098582 [DEBUG] switch_core_session.c:1053 Send signal sofia/sipinterface_1/0xxxxx...@x.x.6.205 [BREAK]
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:59.098582 [DEBUG] switch_core_session.c:1053 Send signal sofia/sipinterface_1/0xxxxx...@x.x.6.205 [BREAK]
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:59.098582 [DEBUG] sofia.c:6614 Channel sofia/sipinterface_1/0xxxxx...@x.x.6.205 entering state [proceeding][180]
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:59.098582 [NOTICE] sofia.c:6717 Ring-Ready sofia/sipinterface_1/0xxxxx...@x.x.6.205!
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:59.098582 [DEBUG] switch_channel.c:3277 (sofia/sipinterface_1/0xxxxx...@x.x.6.205) Callstate Change DOWN -> RINGING
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:59.098582 [DEBUG] sofia.c:6614 Channel sofia/sipinterface_1/0xxxxx...@x.x.6.205 entering state [proceeding][183]
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:59.098582 [DEBUG] sofia.c:6624 Remote SDP:
1c3babba-3de5-11e6-8844-fbf5318198dc v=0
1c3babba-3de5-11e6-8844-fbf5318198dc o=root 2094684600 2094684600 IN IP4 x.x.6.205
1c3babba-3de5-11e6-8844-fbf5318198dc s=Digium Gateway
1c3babba-3de5-11e6-8844-fbf5318198dc c=IN IP4 x.x.6.205
1c3babba-3de5-11e6-8844-fbf5318198dc t=0 0
1c3babba-3de5-11e6-8844-fbf5318198dc m=audio 10832 RTP/AVP 8 101
1c3babba-3de5-11e6-8844-fbf5318198dc a=rtpmap:8 PCMA/8000
1c3babba-3de5-11e6-8844-fbf5318198dc a=rtpmap:101 telephone-event/8000
1c3babba-3de5-11e6-8844-fbf5318198dc a=fmtp:101 0-16
1c3babba-3de5-11e6-8844-fbf5318198dc a=silenceSupp:off - - - -
1c3babba-3de5-11e6-8844-fbf5318198dc a=ptime:20
1c3babba-3de5-11e6-8844-fbf5318198dc
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:59.098582 [DEBUG] switch_core_media.c:3627 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:59.098582 [DEBUG] switch_core_media.c:3682 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:59.098582 [DEBUG] switch_core_media.c:3543 Set telephone-event payload to 101
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:59.098582 [DEBUG] switch_core_media.c:2473 Set Codec sofia/sipinterface_1/0xxxxx...@x.x.6.205 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:59.098582 [DEBUG] switch_core_codec.c:111 sofia/sipinterface_1/0xxxxx...@x.x.6.205 Original read codec set to PCMA:8
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:59.098582 [DEBUG] switch_core_media.c:3881 Set 2833 dtmf send payload to 101
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:59.098582 [DEBUG] switch_core_media.c:5141 AUDIO RTP [sofia/sipinterface_1/0xxxxx...@x.x.6.205] 172.31.10.248 port 19388 -> x.x.6.205 port 10832 codec: 8 ms: 20
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:34:59.098582 [NOTICE] mod_loopback.c:955 Pre-Answer loopback/+xxxxxxxxxxx-a!
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:59.098582 [DEBUG] switch_rtp.c:3548 Starting timer [soft] 160 bytes per 20ms
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:34:59.098582 [DEBUG] switch_channel.c:3399 (loopback/+xxxxxxxxxxx-a) Callstate Change DOWN -> EARLY
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:59.098582 [DEBUG] switch_core_session.c:908 Send signal loopback/+xxxxxxxxxxx-b [BREAK]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:59.098582 [DEBUG] mod_loopback.c:598 loopback/+xxxxxxxxxxx-b CHANNEL KILL
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:59.098582 [NOTICE] switch_ivr_originate.c:1192 Pre-Answer loopback/+xxxxxxxxxxx-b!
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:59.098582 [DEBUG] switch_channel.c:3399 (loopback/+xxxxxxxxxxx-b) Callstate Change RINGING -> EARLY
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:59.098582 [DEBUG] switch_ivr_originate.c:1249 Raw Codec Activation Success L16@8000hz 1 channel 20ms
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:59.098582 [DEBUG] switch_core_codec.c:221 loopback/+xxxxxxxxxxx-b Push codec L16:70
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:59.098582 [DEBUG] switch_ivr_originate.c:1317 Play Ringback Tone [%(2000,4000,440,480)]
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:59.098582 [DEBUG] switch_core_media.c:5439 Set 2833 dtmf send payload to 101
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:59.098582 [DEBUG] switch_core_media.c:5445 Set 2833 dtmf receive payload to 101
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:59.098582 [NOTICE] sofia_media.c:92 Pre-Answer sofia/sipinterface_1/0xxxxx...@x.x.6.205!
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:59.098582 [DEBUG] switch_channel.c:3395 Send signal loopback/+xxxxxxxxxxx-b [BREAK]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:59.098582 [DEBUG] mod_loopback.c:598 loopback/+xxxxxxxxxxx-b CHANNEL KILL
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:59.098582 [DEBUG] switch_channel.c:3399 (sofia/sipinterface_1/0xxxxx...@x.x.6.205) Callstate Change RINGING -> EARLY
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:59.118148 [DEBUG] switch_core_codec.c:246 loopback/+xxxxxxxxxxx-b Restore previous codec L16:70.
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:59.118148 [DEBUG] switch_ivr_originate.c:3580 Originate Resulted in Success: [sofia/sipinterface_1/0xxxxx...@x.x.6.205]
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:59.118148 [DEBUG] switch_core_session.c:908 Send signal sofia/sipinterface_1/0xxxxx...@x.x.6.205 [BREAK]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:59.118148 [DEBUG] switch_core_session.c:908 Send signal loopback/+xxxxxxxxxxx-b [BREAK]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:59.118148 [DEBUG] mod_loopback.c:598 loopback/+xxxxxxxxxxx-b CHANNEL KILL
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:59.118148 [DEBUG] switch_ivr_bridge.c:1465 (sofia/sipinterface_1/0xxxxx...@x.x.6.205) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:59.118148 [DEBUG] switch_core_session.c:1388 Send signal sofia/sipinterface_1/0xxxxx...@x.x.6.205 [BREAK]
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:59.118148 [DEBUG] switch_core_state_machine.c:472 (sofia/sipinterface_1/0xxxxx...@x.x.6.205) Running State Change CS_EXCHANGE_MEDIA
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:59.118148 [DEBUG] switch_core_state_machine.c:538 (sofia/sipinterface_1/0xxxxx...@x.x.6.205) State EXCHANGE_MEDIA
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:59.118148 [DEBUG] mod_sofia.c:596 SOFIA EXCHANGE_MEDIA
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:34:59.178135 [DEBUG] switch_rtp.c:5853 Correct ip/port confirmed.
2016-06-29 18:35:00.478159 [INFO] kazoo_node.c:625 exec: uuid_setvar_multi(1ba06aa6-3de5-11e6-87d8-fbf5318198dc enable_file_write_buffering=false;RECORD_APPEND=true)
2016-06-29 18:35:00.538179 [INFO] kazoo_node.c:625 exec: uuid_setvar_multi(1ba06aa6-3de5-11e6-87d8-fbf5318198dc record_sample_rate=8000;record_min_sec=0;recording_follow_attxfer=true;recording_follow_transfer=true)
2016-06-29 18:35:00.578311 [NOTICE] kazoo_node.c:285 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|executing export ecallmgr_Insert-At=now
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:00.578311 [DEBUG] switch_core_session.c:1188 Send signal loopback/+xxxxxxxxxxx-b [BREAK]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:00.578311 [DEBUG] mod_loopback.c:598 loopback/+xxxxxxxxxxx-b CHANNEL KILL
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:00.598185 [DEBUG] switch_core_session.c:908 Send signal loopback/+xxxxxxxxxxx-b [BREAK]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:00.598185 [DEBUG] mod_loopback.c:598 loopback/+xxxxxxxxxxx-b CHANNEL KILL
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:00.598185 [DEBUG] switch_ivr.c:614 loopback/+xxxxxxxxxxx-b Command Execute export(ecallmgr_Insert-At=now)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b export(ecallmgr_Insert-At=now)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:00.598185 [DEBUG] switch_channel.c:1247 EXPORT (export_vars) [ecallmgr_Insert-At]=[now]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:00.598185 [DEBUG] switch_core_session.c:908 Send signal loopback/+xxxxxxxxxxx-b [BREAK]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:00.598185 [DEBUG] mod_loopback.c:598 loopback/+xxxxxxxxxxx-b CHANNEL KILL
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:35:00.598185 [DEBUG] switch_ivr_bridge.c:395 Send signal sofia/sipinterface_1/0xxxxx...@x.x.6.205 [BREAK]
2016-06-29 18:35:01.018370 [NOTICE] kazoo_node.c:285 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|executing export ecallmgr_Time-Limit=3600
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:01.018370 [DEBUG] switch_core_session.c:1188 Send signal loopback/+xxxxxxxxxxx-b [BREAK]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:01.018370 [DEBUG] mod_loopback.c:598 loopback/+xxxxxxxxxxx-b CHANNEL KILL
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:01.038342 [DEBUG] switch_core_session.c:908 Send signal loopback/+xxxxxxxxxxx-b [BREAK]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:01.038342 [DEBUG] mod_loopback.c:598 loopback/+xxxxxxxxxxx-b CHANNEL KILL
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:01.038342 [DEBUG] switch_ivr.c:614 loopback/+xxxxxxxxxxx-b Command Execute export(ecallmgr_Time-Limit=3600)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b export(ecallmgr_Time-Limit=3600)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:01.038342 [DEBUG] switch_channel.c:1247 EXPORT (export_vars) [ecallmgr_Time-Limit]=[3600]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:01.038342 [DEBUG] switch_core_session.c:908 Send signal loopback/+xxxxxxxxxxx-b [BREAK]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:01.038342 [DEBUG] mod_loopback.c:598 loopback/+xxxxxxxxxxx-b CHANNEL KILL
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:35:01.038342 [DEBUG] switch_ivr_bridge.c:395 Send signal sofia/sipinterface_1/0xxxxx...@x.x.6.205 [BREAK]
2016-06-29 18:35:01.178132 [NOTICE] kazoo_node.c:285 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|executing export ecallmgr_Media-Name=call_recording_1ba06aa6-3de5-11e6-87d8-fbf5318198dc.mp3
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:01.178132 [DEBUG] switch_core_session.c:1188 Send signal loopback/+xxxxxxxxxxx-b [BREAK]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:01.178132 [DEBUG] mod_loopback.c:598 loopback/+xxxxxxxxxxx-b CHANNEL KILL
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:01.178132 [DEBUG] switch_core_session.c:908 Send signal loopback/+xxxxxxxxxxx-b [BREAK]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:01.178132 [DEBUG] mod_loopback.c:598 loopback/+xxxxxxxxxxx-b CHANNEL KILL
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:01.178132 [DEBUG] switch_ivr.c:614 loopback/+xxxxxxxxxxx-b Command Execute export(ecallmgr_Media-Name=call_recording_1ba06aa6-3de5-11e6-87d8-fbf5318198dc.mp3)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b export(ecallmgr_Media-Name=call_recording_1ba06aa6-3de5-11e6-87d8-fbf5318198dc.mp3)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:01.198436 [DEBUG] switch_channel.c:1247 EXPORT (export_vars) [ecallmgr_Media-Name]=[call_recording_1ba06aa6-3de5-11e6-87d8-fbf5318198dc.mp3]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:01.198436 [DEBUG] switch_core_session.c:908 Send signal loopback/+xxxxxxxxxxx-b [BREAK]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:01.198436 [DEBUG] mod_loopback.c:598 loopback/+xxxxxxxxxxx-b CHANNEL KILL
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:35:01.198436 [DEBUG] switch_ivr_bridge.c:395 Send signal sofia/sipinterface_1/0xxxxx...@x.x.6.205 [BREAK]
2016-06-29 18:35:01.198436 [INFO] kazoo_node.c:625 exec: uuid_record(1ba06aa6-3de5-11e6-87d8-fbf5318198dc start /data/recording/call_recording_1ba06aa6-3de5-11e6-87d8-fbf5318198dc.mp3 3600)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:01.218129 [DEBUG] switch_core_media_bug.c:534 Attaching BUG to loopback/+xxxxxxxxxxx-b
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:01.238129 [DEBUG] switch_ivr_async.c:1469 No silence detection configured; assuming start of speech
To: <sip:0xxxxx...@x.x.6.205>;tag=as62f43ad5
Call-ID: 1c3babba-3de5-11e6-8844-fbf5318198dc
Contact: <sip:0xxxxx...@x.x.6.205:5060>
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:35:03.998150 [DEBUG] switch_core_session.c:1053 Send signal sofia/sipinterface_1/0xxxxx...@x.x.6.205 [BREAK]
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:35:03.998150 [DEBUG] switch_core_session.c:1053 Send signal sofia/sipinterface_1/0xxxxx...@x.x.6.205 [BREAK]
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:35:04.018164 [DEBUG] sofia.c:6614 Channel sofia/sipinterface_1/0xxxxx...@x.x.6.205 entering state [completing][200]
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:35:04.018164 [DEBUG] sofia.c:6624 Remote SDP:
1c3babba-3de5-11e6-8844-fbf5318198dc v=0
1c3babba-3de5-11e6-8844-fbf5318198dc o=root 2094684600 2094684601 IN IP4 x.x.6.205
1c3babba-3de5-11e6-8844-fbf5318198dc s=Digium Gateway
1c3babba-3de5-11e6-8844-fbf5318198dc c=IN IP4 x.x.6.205
1c3babba-3de5-11e6-8844-fbf5318198dc t=0 0
1c3babba-3de5-11e6-8844-fbf5318198dc m=audio 10832 RTP/AVP 8 101
1c3babba-3de5-11e6-8844-fbf5318198dc a=rtpmap:8 PCMA/8000
1c3babba-3de5-11e6-8844-fbf5318198dc a=rtpmap:101 telephone-event/8000
1c3babba-3de5-11e6-8844-fbf5318198dc a=fmtp:101 0-16
1c3babba-3de5-11e6-8844-fbf5318198dc a=silenceSupp:off - - - -
1c3babba-3de5-11e6-8844-fbf5318198dc a=ptime:20
1c3babba-3de5-11e6-8844-fbf5318198dc
ACK sip:0xxxxx...@x.x.6.205:5060 SIP/2.0
To: <sip:0xxxxx...@x.x.6.205>;tag=as62f43ad5
Call-ID: 1c3babba-3de5-11e6-8844-fbf5318198dc
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:35:04.018164 [DEBUG] switch_core_session.c:1053 Send signal sofia/sipinterface_1/0xxxxx...@x.x.6.205 [BREAK]
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:35:04.018164 [DEBUG] switch_core_session.c:1053 Send signal sofia/sipinterface_1/0xxxxx...@x.x.6.205 [BREAK]
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:35:04.038136 [DEBUG] sofia.c:6614 Channel sofia/sipinterface_1/0xxxxx...@x.x.6.205 entering state [ready][200]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:04.038136 [DEBUG] switch_channel.c:3635 Send signal loopback/+xxxxxxxxxxx-b [BREAK]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:04.038136 [DEBUG] mod_loopback.c:598 loopback/+xxxxxxxxxxx-b CHANNEL KILL
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:35:04.038136 [NOTICE] sofia.c:7417 Channel [sofia/sipinterface_1/0xxxxx...@x.x.6.205] has been answered
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:35:04.038136 [DEBUG] switch_channel.c:3689 (sofia/sipinterface_1/0xxxxx...@x.x.6.205) Callstate Change EARLY -> ACTIVE
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.058137 [NOTICE] mod_loopback.c:950 Channel [loopback/+xxxxxxxxxxx-a] has been answered
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.058137 [DEBUG] switch_channel.c:3689 (loopback/+xxxxxxxxxxx-a) Callstate Change EARLY -> ACTIVE
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:04.058137 [DEBUG] switch_core_session.c:908 Send signal loopback/+xxxxxxxxxxx-b [BREAK]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:04.058137 [DEBUG] mod_loopback.c:598 loopback/+xxxxxxxxxxx-b CHANNEL KILL
2016-06-29 18:35:04.058137 [DEBUG] switch_ivr_originate.c:3580 Originate Resulted in Success: [loopback/+xxxxxxxxxxx-a]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:04.058137 [NOTICE] switch_ivr_bridge.c:496 Channel [loopback/+xxxxxxxxxxx-b] has been answered
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.058137 [DEBUG] switch_ivr.c:1847 (loopback/+xxxxxxxxxxx-a) State Change CS_CONSUME_MEDIA -> CS_ROUTING
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.058137 [DEBUG] switch_core_session.c:1388 Send signal loopback/+xxxxxxxxxxx-a [BREAK]
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.058137 [DEBUG] mod_loopback.c:598 loopback/+xxxxxxxxxxx-a CHANNEL KILL
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.058137 [DEBUG] switch_core_state_machine.c:472 (loopback/+xxxxxxxxxxx-a) Running State Change CS_ROUTING
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.058137 [DEBUG] switch_core_state_machine.c:528 (loopback/+xxxxxxxxxxx-a) State ROUTING
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.058137 [DEBUG] mod_loopback.c:391 loopback/+xxxxxxxxxxx-a CHANNEL ROUTING
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.058137 [DEBUG] switch_core_state_machine.c:166 loopback/+xxxxxxxxxxx-a Standard ROUTING
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.058137 [INFO] switch_channel.c:3062 loopback/+xxxxxxxxxxx-a Flipping CID from "Device QuickCall" <11223344> to "xxxxxxxxxxx" <+xxxxxxxxxxx>
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.058137 [DEBUG] switch_core_state_machine.c:216 (loopback/+xxxxxxxxxxx-a) State Change CS_ROUTING -> CS_EXECUTE
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.058137 [DEBUG] switch_core_session.c:1388 Send signal loopback/+xxxxxxxxxxx-a [BREAK]
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.058137 [DEBUG] mod_loopback.c:598 loopback/+xxxxxxxxxxx-a CHANNEL KILL
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.058137 [DEBUG] switch_core_state_machine.c:528 (loopback/+xxxxxxxxxxx-a) State ROUTING going to sleep
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.058137 [DEBUG] switch_core_state_machine.c:472 (loopback/+xxxxxxxxxxx-a) Running State Change CS_EXECUTE
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.058137 [DEBUG] switch_core_state_machine.c:535 (loopback/+xxxxxxxxxxx-a) State EXECUTE
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.058137 [DEBUG] mod_loopback.c:433 loopback/+xxxxxxxxxxx-a CHANNEL EXECUTE
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.058137 [DEBUG] switch_core_state_machine.c:258 loopback/+xxxxxxxxxxx-a Standard EXECUTE
f62bb835a781fa4ec508c620a1beee37e535-autocall EXECUTE loopback/+xxxxxxxxxxx-a unset(origination_callee_id_number)
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.058137 [DEBUG] mod_dptools.c:1596 UNSET [origination_callee_id_number]
f62bb835a781fa4ec508c620a1beee37e535-autocall EXECUTE loopback/+xxxxxxxxxxx-a unset(origination_callee_id_name)
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.058137 [DEBUG] switch_core_session.c:908 Send signal loopback/+xxxxxxxxxxx-a [BREAK]
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.058137 [DEBUG] mod_dptools.c:1596 UNSET [origination_callee_id_name]
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.058137 [DEBUG] mod_loopback.c:598 loopback/+xxxxxxxxxxx-a CHANNEL KILL
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.058137 [NOTICE] switch_ivr.c:1854 Transfer loopback/+xxxxxxxxxxx-a to inline[m:^:unset:origination_callee_id_number^unset:origination_callee_id_name^unset:origination_caller_id_number^unset:origination_caller_id_name^unset:ignore_early_media^unset:continue_on_fail^unset:call_timeout^unset:originate_timeout^unset:ecallmgr_Auto-Call-Rule-ID^unset:inherit_codec^unset:sip_auto_answer^unset:ignore_early_media^unset:group_confirm_key^unset:group_confirm_cancel_timeout^unset:group_confirm_file^unset:sip_h_Call-Info^unset:sip_h_Alert-Info^unset:alert_info^unset:sip_invite_params^set:sip_auto_answer=false^transfer:+11223344 XML context_2@default]
f62bb835a781fa4ec508c620a1beee37e535-autocall EXECUTE loopback/+xxxxxxxxxxx-a unset(origination_caller_id_number)
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.058137 [DEBUG] mod_dptools.c:1596 UNSET [origination_caller_id_number]
f62bb835a781fa4ec508c620a1beee37e535-autocall EXECUTE loopback/+xxxxxxxxxxx-a unset(origination_caller_id_name)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:04.058137 [DEBUG] switch_channel.c:3689 (loopback/+xxxxxxxxxxx-b) Callstate Change EARLY -> ACTIVE
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.058137 [DEBUG] mod_dptools.c:1596 UNSET [origination_caller_id_name]
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:35:04.058137 [DEBUG] switch_core_session.c:970 Send signal sofia/sipinterface_1/0xxxxx...@x.x.6.205 [BREAK]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:04.058137 [DEBUG] switch_core_session.c:970 Send signal loopback/+xxxxxxxxxxx-b [BREAK]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:04.058137 [DEBUG] mod_loopback.c:598 loopback/+xxxxxxxxxxx-b CHANNEL KILL
f62bb835a781fa4ec508c620a1beee37e535-autocall EXECUTE loopback/+xxxxxxxxxxx-a unset(ignore_early_media)
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.058137 [DEBUG] mod_dptools.c:1596 UNSET [ignore_early_media]
f62bb835a781fa4ec508c620a1beee37e535-autocall EXECUTE loopback/+xxxxxxxxxxx-a unset(continue_on_fail)
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.058137 [DEBUG] mod_dptools.c:1596 UNSET [continue_on_fail]
f62bb835a781fa4ec508c620a1beee37e535-autocall EXECUTE loopback/+xxxxxxxxxxx-a unset(call_timeout)
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.058137 [DEBUG] mod_dptools.c:1596 UNSET [call_timeout]
f62bb835a781fa4ec508c620a1beee37e535-autocall EXECUTE loopback/+xxxxxxxxxxx-a unset(originate_timeout)
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.058137 [DEBUG] mod_dptools.c:1596 UNSET [originate_timeout]
f62bb835a781fa4ec508c620a1beee37e535-autocall EXECUTE loopback/+xxxxxxxxxxx-a unset(ecallmgr_Auto-Call-Rule-ID)
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.058137 [DEBUG] mod_dptools.c:1596 UNSET [ecallmgr_Auto-Call-Rule-ID]
f62bb835a781fa4ec508c620a1beee37e535-autocall EXECUTE loopback/+xxxxxxxxxxx-a unset(inherit_codec)
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.058137 [DEBUG] mod_dptools.c:1596 UNSET [inherit_codec]
f62bb835a781fa4ec508c620a1beee37e535-autocall EXECUTE loopback/+xxxxxxxxxxx-a unset(sip_auto_answer)
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.058137 [DEBUG] mod_dptools.c:1596 UNSET [sip_auto_answer]
f62bb835a781fa4ec508c620a1beee37e535-autocall EXECUTE loopback/+xxxxxxxxxxx-a unset(ignore_early_media)
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.058137 [DEBUG] mod_dptools.c:1596 UNSET [ignore_early_media]
f62bb835a781fa4ec508c620a1beee37e535-autocall EXECUTE loopback/+xxxxxxxxxxx-a unset(group_confirm_key)
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.078133 [DEBUG] mod_dptools.c:1596 UNSET [group_confirm_key]
f62bb835a781fa4ec508c620a1beee37e535-autocall EXECUTE loopback/+xxxxxxxxxxx-a unset(group_confirm_cancel_timeout)
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.078133 [DEBUG] mod_dptools.c:1596 UNSET [group_confirm_cancel_timeout]
f62bb835a781fa4ec508c620a1beee37e535-autocall EXECUTE loopback/+xxxxxxxxxxx-a unset(group_confirm_file)
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.078133 [DEBUG] mod_dptools.c:1596 UNSET [group_confirm_file]
f62bb835a781fa4ec508c620a1beee37e535-autocall EXECUTE loopback/+xxxxxxxxxxx-a unset(sip_h_Call-Info)
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.078133 [DEBUG] mod_dptools.c:1596 UNSET [sip_h_Call-Info]
f62bb835a781fa4ec508c620a1beee37e535-autocall EXECUTE loopback/+xxxxxxxxxxx-a unset(sip_h_Alert-Info)
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.078133 [DEBUG] mod_dptools.c:1596 UNSET [sip_h_Alert-Info]
f62bb835a781fa4ec508c620a1beee37e535-autocall EXECUTE loopback/+xxxxxxxxxxx-a unset(alert_info)
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.078133 [DEBUG] mod_dptools.c:1596 UNSET [alert_info]
f62bb835a781fa4ec508c620a1beee37e535-autocall EXECUTE loopback/+xxxxxxxxxxx-a unset(sip_invite_params)
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.078133 [DEBUG] mod_dptools.c:1596 UNSET [sip_invite_params]
f62bb835a781fa4ec508c620a1beee37e535-autocall EXECUTE loopback/+xxxxxxxxxxx-a set(sip_auto_answer=false)
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.078133 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-a SET [sip_auto_answer]=[false]
f62bb835a781fa4ec508c620a1beee37e535-autocall EXECUTE loopback/+xxxxxxxxxxx-a transfer(+11223344 XML context_2)
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.078133 [DEBUG] switch_ivr.c:1847 (loopback/+xxxxxxxxxxx-a) State Change CS_EXECUTE -> CS_ROUTING
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.078133 [DEBUG] switch_core_session.c:1388 Send signal loopback/+xxxxxxxxxxx-a [BREAK]
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.078133 [DEBUG] mod_loopback.c:598 loopback/+xxxxxxxxxxx-a CHANNEL KILL
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.078133 [DEBUG] switch_core_session.c:908 Send signal loopback/+xxxxxxxxxxx-a [BREAK]
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.078133 [DEBUG] mod_loopback.c:598 loopback/+xxxxxxxxxxx-a CHANNEL KILL
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.078133 [NOTICE] switch_ivr.c:1854 Transfer loopback/+xxxxxxxxxxx-a to XML[+11223344@context_2]
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.078133 [DEBUG] switch_core_state_machine.c:535 (loopback/+xxxxxxxxxxx-a) State EXECUTE going to sleep
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.078133 [DEBUG] switch_core_state_machine.c:472 (loopback/+xxxxxxxxxxx-a) Running State Change CS_ROUTING
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.078133 [DEBUG] switch_core_state_machine.c:528 (loopback/+xxxxxxxxxxx-a) State ROUTING
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.078133 [DEBUG] mod_loopback.c:391 loopback/+xxxxxxxxxxx-a CHANNEL ROUTING
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.078133 [DEBUG] switch_core_state_machine.c:166 loopback/+xxxxxxxxxxx-a Standard ROUTING
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:04.078133 [INFO] mod_dialplan_xml.c:635 Processing xxxxxxxxxxx <+xxxxxxxxxxx>->+11223344 in context context_2
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:07.178136 [WARNING] mod_dialplan_xml.c:665 Context context_2 not found
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:07.178136 [INFO] switch_core_state_machine.c:241 No Route, Aborting
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:07.178136 [NOTICE] switch_core_state_machine.c:242 Hangup loopback/+xxxxxxxxxxx-a [CS_ROUTING] [NO_ROUTE_DESTINATION]
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:07.178136 [DEBUG] switch_channel.c:3222 Send signal loopback/+xxxxxxxxxxx-a [KILL]
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:07.178136 [DEBUG] mod_loopback.c:598 loopback/+xxxxxxxxxxx-a CHANNEL KILL
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:07.178136 [DEBUG] switch_core_session.c:1388 Send signal loopback/+xxxxxxxxxxx-a [BREAK]
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:07.178136 [DEBUG] mod_loopback.c:598 loopback/+xxxxxxxxxxx-a CHANNEL KILL
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:07.178136 [DEBUG] switch_core_state_machine.c:528 (loopback/+xxxxxxxxxxx-a) State ROUTING going to sleep
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:07.178136 [DEBUG] switch_core_state_machine.c:472 (loopback/+xxxxxxxxxxx-a) Running State Change CS_HANGUP
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:07.178136 [DEBUG] switch_core_state_machine.c:735 (loopback/+xxxxxxxxxxx-a) Callstate Change ACTIVE -> HANGUP
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:07.178136 [DEBUG] switch_core_state_machine.c:737 (loopback/+xxxxxxxxxxx-a) State HANGUP
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:07.178136 [DEBUG] mod_loopback.c:545 loopback/+xxxxxxxxxxx-a CHANNEL HANGUP
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:07.178136 [NOTICE] mod_loopback.c:561 Hangup loopback/+xxxxxxxxxxx-b [CS_EXECUTE] [NO_ROUTE_DESTINATION]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:07.178136 [DEBUG] switch_channel.c:3222 Send signal loopback/+xxxxxxxxxxx-b [KILL]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:07.178136 [DEBUG] mod_loopback.c:598 loopback/+xxxxxxxxxxx-b CHANNEL KILL
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:07.178136 [DEBUG] switch_core_session.c:1388 Send signal loopback/+xxxxxxxxxxx-b [BREAK]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:07.178136 [DEBUG] mod_loopback.c:598 loopback/+xxxxxxxxxxx-b CHANNEL KILL
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:07.178136 [DEBUG] switch_core_state_machine.c:60 loopback/+xxxxxxxxxxx-a Standard HANGUP, cause: NO_ROUTE_DESTINATION
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:07.178136 [DEBUG] switch_core_state_machine.c:737 (loopback/+xxxxxxxxxxx-a) State HANGUP going to sleep
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:07.178136 [DEBUG] switch_core_state_machine.c:504 (loopback/+xxxxxxxxxxx-a) State Change CS_HANGUP -> CS_REPORTING
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:07.178136 [DEBUG] switch_core_session.c:1388 Send signal loopback/+xxxxxxxxxxx-a [BREAK]
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:07.178136 [DEBUG] mod_loopback.c:598 loopback/+xxxxxxxxxxx-a CHANNEL KILL
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:07.178136 [DEBUG] switch_core_state_machine.c:472 (loopback/+xxxxxxxxxxx-a) Running State Change CS_REPORTING
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:07.178136 [DEBUG] switch_core_state_machine.c:823 (loopback/+xxxxxxxxxxx-a) State REPORTING
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:07.178136 [DEBUG] switch_core_state_machine.c:104 loopback/+xxxxxxxxxxx-a Standard REPORTING, cause: NO_ROUTE_DESTINATION
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:07.178136 [DEBUG] switch_core_state_machine.c:823 (loopback/+xxxxxxxxxxx-a) State REPORTING going to sleep
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:07.178136 [DEBUG] switch_core_state_machine.c:498 (loopback/+xxxxxxxxxxx-a) State Change CS_REPORTING -> CS_DESTROY
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:07.178136 [DEBUG] switch_core_session.c:1388 Send signal loopback/+xxxxxxxxxxx-a [BREAK]
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:07.178136 [DEBUG] mod_loopback.c:598 loopback/+xxxxxxxxxxx-a CHANNEL KILL
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:07.178136 [DEBUG] switch_core_session.c:1615 Session 7325 (loopback/+xxxxxxxxxxx-a) Locked, Waiting on external entities
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:35:07.198160 [DEBUG] switch_ivr_bridge.c:579 loopback/+xxxxxxxxxxx-b ending bridge by request from write function
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:07.198160 [DEBUG] switch_ivr_bridge.c:660 BRIDGE THREAD DONE [loopback/+xxxxxxxxxxx-b]
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:35:07.198160 [DEBUG] switch_ivr_bridge.c:690 Send signal sofia/sipinterface_1/0xxxxx...@x.x.6.205 [BREAK]
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:35:07.198160 [DEBUG] switch_ivr_bridge.c:660 BRIDGE THREAD DONE [sofia/sipinterface_1/0xxxxx...@x.x.6.205]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:07.198160 [DEBUG] switch_ivr_bridge.c:690 Send signal loopback/+xxxxxxxxxxx-b [BREAK]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:07.198160 [DEBUG] mod_loopback.c:598 loopback/+xxxxxxxxxxx-b CHANNEL KILL
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:35:07.198160 [NOTICE] switch_ivr_bridge.c:754 Hangup sofia/sipinterface_1/0xxxxx...@x.x.6.205 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:35:07.198160 [DEBUG] switch_channel.c:3222 Send signal sofia/sipinterface_1/0xxxxx...@x.x.6.205 [KILL]
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:35:07.198160 [DEBUG] switch_core_session.c:1388 Send signal sofia/sipinterface_1/0xxxxx...@x.x.6.205 [BREAK]
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:35:07.198160 [DEBUG] switch_core_state_machine.c:538 (sofia/sipinterface_1/0xxxxx...@x.x.6.205) State EXCHANGE_MEDIA going to sleep
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:35:07.198160 [DEBUG] switch_core_state_machine.c:472 (sofia/sipinterface_1/0xxxxx...@x.x.6.205) Running State Change CS_HANGUP
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:35:07.198160 [DEBUG] switch_core_state_machine.c:735 (sofia/sipinterface_1/0xxxxx...@x.x.6.205) Callstate Change ACTIVE -> HANGUP
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:35:07.198160 [DEBUG] switch_core_state_machine.c:737 (sofia/sipinterface_1/0xxxxx...@x.x.6.205) State HANGUP
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:35:07.198160 [DEBUG] mod_sofia.c:413 Channel sofia/sipinterface_1/0xxxxx...@x.x.6.205 hanging up, cause: NORMAL_CLEARING
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:35:07.198160 [DEBUG] switch_ivr_bridge.c:1563 sofia/sipinterface_1/0xxxxx...@x.x.6.205 skip receive message [UNBRIDGE] (channel is hungup already)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:07.198160 [DEBUG] switch_ivr_bridge.c:1566 loopback/+xxxxxxxxxxx-b skip receive message [UNBRIDGE] (channel is hungup already)
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:35:07.198160 [DEBUG] mod_sofia.c:467 Sending BYE to sofia/sipinterface_1/0xxxxx...@x.x.6.205
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:35:07.198160 [DEBUG] switch_core_state_machine.c:60 sofia/sipinterface_1/0xxxxx...@x.x.6.205 Standard HANGUP, cause: NORMAL_CLEARING
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:35:07.198160 [DEBUG] switch_core_state_machine.c:737 (sofia/sipinterface_1/0xxxxx...@x.x.6.205) State HANGUP going to sleep
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:07.198160 [DEBUG] switch_core_session.c:2893 loopback/+xxxxxxxxxxx-b skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:35:07.198160 [DEBUG] switch_core_state_machine.c:504 (sofia/sipinterface_1/0xxxxx...@x.x.6.205) State Change CS_HANGUP -> CS_REPORTING
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:35:07.198160 [DEBUG] switch_core_session.c:1388 Send signal sofia/sipinterface_1/0xxxxx...@x.x.6.205 [BREAK]
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:35:07.198160 [DEBUG] switch_core_state_machine.c:472 (sofia/sipinterface_1/0xxxxx...@x.x.6.205) Running State Change CS_REPORTING
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:35:07.198160 [DEBUG] switch_core_state_machine.c:823 (sofia/sipinterface_1/0xxxxx...@x.x.6.205) State REPORTING
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:35:07.198160 [DEBUG] switch_core_state_machine.c:104 sofia/sipinterface_1/0xxxxx...@x.x.6.205 Standard REPORTING, cause: NORMAL_CLEARING
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:35:07.198160 [DEBUG] switch_core_state_machine.c:823 (sofia/sipinterface_1/0xxxxx...@x.x.6.205) State REPORTING going to sleep
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:35:07.198160 [DEBUG] switch_core_state_machine.c:498 (sofia/sipinterface_1/0xxxxx...@x.x.6.205) State Change CS_REPORTING -> CS_DESTROY
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:35:07.198160 [DEBUG] switch_core_session.c:1388 Send signal sofia/sipinterface_1/0xxxxx...@x.x.6.205 [BREAK]
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:35:07.198160 [DEBUG] switch_core_session.c:1615 Session 7329 (sofia/sipinterface_1/0xxxxx...@x.x.6.205) Locked, Waiting on external entities
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:35:07.198160 [NOTICE] switch_core_session.c:1633 Session 7329 (sofia/sipinterface_1/0xxxxx...@x.x.6.205) Ended
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:35:07.198160 [NOTICE] switch_core_session.c:1637 Close Channel sofia/sipinterface_1/0xxxxx...@x.x.6.205 [CS_DESTROY]
BYE sip:0xxxxx...@x.x.6.205:5060 SIP/2.0
Via: SIP/2.0/UDP 172.31.10.248:11000;rport;branch=z9hG4bK5159eZr413K1Q1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:07.198160 [DEBUG] switch_core_state_machine.c:535 (loopback/+xxxxxxxxxxx-b) State EXECUTE going to sleep
To: <sip:0xxxxx...@x.x.6.205>;tag=as62f43ad5
Call-ID: 1c3babba-3de5-11e6-8844-fbf5318198dc
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:07.198160 [DEBUG] switch_core_state_machine.c:472 (loopback/+xxxxxxxxxxx-b) Running State Change CS_HANGUP
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:07.198160 [DEBUG] switch_ivr_async.c:1299 Stop recording file /data/recording/call_recording_1ba06aa6-3de5-11e6-87d8-fbf5318198dc.mp3
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:35:07.198160 [DEBUG] switch_core_state_machine.c:626 (sofia/sipinterface_1/0xxxxx...@x.x.6.205) Running State Change CS_DESTROY
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:35:07.198160 [DEBUG] switch_core_state_machine.c:636 (sofia/sipinterface_1/0xxxxx...@x.x.6.205) State DESTROY
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:35:07.198160 [DEBUG] mod_sofia.c:323 sofia/sipinterface_1/0xxxxx...@x.x.6.205 SOFIA DESTROY
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:35:07.198160 [DEBUG] switch_core_state_machine.c:111 sofia/sipinterface_1/0xxxxx...@x.x.6.205 Standard DESTROY
1c3babba-3de5-11e6-8844-fbf5318198dc 2016-06-29 18:35:07.198160 [DEBUG] switch_core_state_machine.c:636 (sofia/sipinterface_1/0xxxxx...@x.x.6.205) State DESTROY going to sleep
To: <sip:0xxxxx...@x.x.6.205>;tag=as62f43ad5
Call-ID: 1c3babba-3de5-11e6-8844-fbf5318198dc
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:07.198160 [DEBUG] switch_ivr_async.c:1352 Channel is hung up
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:07.218158 [DEBUG] switch_core_media_bug.c:782 Removing BUG from loopback/+xxxxxxxxxxx-b
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:07.218158 [DEBUG] switch_core_state_machine.c:735 (loopback/+xxxxxxxxxxx-b) Callstate Change ACTIVE -> HANGUP
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:07.218158 [DEBUG] switch_core_state_machine.c:737 (loopback/+xxxxxxxxxxx-b) State HANGUP
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:07.218158 [DEBUG] mod_loopback.c:545 loopback/+xxxxxxxxxxx-b CHANNEL HANGUP
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:07.218158 [DEBUG] switch_core_state_machine.c:60 loopback/+xxxxxxxxxxx-b Standard HANGUP, cause: NO_ROUTE_DESTINATION
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:07.218158 [DEBUG] switch_core_state_machine.c:737 (loopback/+xxxxxxxxxxx-b) State HANGUP going to sleep
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:07.218158 [DEBUG] switch_core_state_machine.c:504 (loopback/+xxxxxxxxxxx-b) State Change CS_HANGUP -> CS_REPORTING
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:07.218158 [DEBUG] switch_core_session.c:1388 Send signal loopback/+xxxxxxxxxxx-b [BREAK]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:07.218158 [DEBUG] mod_loopback.c:598 loopback/+xxxxxxxxxxx-b CHANNEL KILL
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:07.218158 [DEBUG] switch_core_state_machine.c:472 (loopback/+xxxxxxxxxxx-b) Running State Change CS_REPORTING
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:07.218158 [NOTICE] switch_core_session.c:1633 Session 7325 (loopback/+xxxxxxxxxxx-a) Ended
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:07.218158 [NOTICE] switch_core_session.c:1637 Close Channel loopback/+xxxxxxxxxxx-a [CS_DESTROY]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:07.218158 [DEBUG] switch_core_state_machine.c:823 (loopback/+xxxxxxxxxxx-b) State REPORTING
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:07.218158 [DEBUG] switch_core_state_machine.c:104 loopback/+xxxxxxxxxxx-b Standard REPORTING, cause: NO_ROUTE_DESTINATION
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:07.218158 [DEBUG] switch_core_state_machine.c:823 (loopback/+xxxxxxxxxxx-b) State REPORTING going to sleep
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:07.218158 [DEBUG] switch_core_state_machine.c:626 (loopback/+xxxxxxxxxxx-a) Running State Change CS_DESTROY
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:07.218158 [DEBUG] switch_core_state_machine.c:498 (loopback/+xxxxxxxxxxx-b) State Change CS_REPORTING -> CS_DESTROY
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:07.218158 [DEBUG] switch_core_session.c:1388 Send signal loopback/+xxxxxxxxxxx-b [BREAK]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:07.218158 [DEBUG] mod_loopback.c:598 loopback/+xxxxxxxxxxx-b CHANNEL KILL
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:07.218158 [DEBUG] switch_core_session.c:1615 Session 7326 (loopback/+xxxxxxxxxxx-b) Locked, Waiting on external entities
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:07.218158 [NOTICE] switch_core_session.c:1633 Session 7326 (loopback/+xxxxxxxxxxx-b) Ended
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:07.218158 [NOTICE] switch_core_session.c:1637 Close Channel loopback/+xxxxxxxxxxx-b [CS_DESTROY]
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:07.218158 [DEBUG] switch_core_state_machine.c:636 (loopback/+xxxxxxxxxxx-a) State DESTROY
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:07.218158 [DEBUG] switch_core_state_machine.c:111 loopback/+xxxxxxxxxxx-a Standard DESTROY
f62bb835a781fa4ec508c620a1beee37e535-autocall 2016-06-29 18:35:07.218158 [DEBUG] switch_core_state_machine.c:636 (loopback/+xxxxxxxxxxx-a) State DESTROY going to sleep
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:07.218158 [DEBUG] switch_core_state_machine.c:626 (loopback/+xxxxxxxxxxx-b) Running State Change CS_DESTROY
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:07.218158 [DEBUG] switch_core_state_machine.c:636 (loopback/+xxxxxxxxxxx-b) State DESTROY
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:07.218158 [DEBUG] switch_core_state_machine.c:111 loopback/+xxxxxxxxxxx-b Standard DESTROY
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:35:07.218158 [DEBUG] switch_core_state_machine.c:636 (loopback/+xxxxxxxxxxx-b) State DESTROY going to sleep
Luis Azedo
Jun 30, 2016, 1:05:37 PM6/30/16
to 2600h...@googlegroups.com
what about kazoo logs in debug mode ?
--
You received this message because you are subscribed to the Google Groups "2600hz-dev" group.
To unsubscribe from this group and stop receiving emails from it, send an email to 2600hz-dev+...@googlegroups.com.
For more options, visit https://groups.google.com/d/optout.
Mountain King
Jul 1, 2016, 11:03:12 AM7/1/16
to 2600hz-dev
I fetch new logs of each node,there are two kazoo and four freeswitch nodes in my test. originate one clicktocall test will not bring about issue,but it will bring about issue when originate currency clicktocalls, my test parmeters, currency num every time is 4, and currency interval is 1s, total clicktocall numbers is 500, no-route-destination fail number is 141,it seem as kazoo not fetch callflow, so fs can't get dialplan maybe. thanks.
在 2016年6月30日星期四 UTC+8下午11:32:52，Mountain King写道：
在 2016年6月30日星期四 UTC+8下午11:32:52，Mountain King写道：
when using clicktocall function, will bring about NO_ROUTE_DESTINATION error, the one is calling to callee for example 12345678901, and the other is callee was called,but when transfer to caller can't find dialplan;
=================================callee can't called
2016-06-29 18:31:36.818449 [INFO] kazoo_node.c:625 exec: originate({origination_callee_id_number='+12345678901',origination_callee_id_name='12345678901',origination_caller_id_number='11223344',origination_caller_id_name='Device QuickCall',ignore_early_media='true',continue_on_fail='true',call_timeout=30,originate_timeout=30,ecallmgr_Fetch-ID='72b1d2999b5901b6f67779b755e82d16',ecallmgr_Ecallmgr-Node='ecall...@mcc-kazoo02.sjz.info',ecallmgr_Auto-Call-Rule-ID='26f66860-cc02-47da-8a63-b9a0d5fde347',ecallmgr_Authorizing-Type='device',inherit_codec='false',ecallmgr_Authorizing-ID='31e479fb292c414fe62e928edbabb619',ecallmgr_Retain-CID='true',sip_auto_answer='true',ecallmgr_Account-ID='07a049bfcfd1685c42a8762a4897626f',local_var_clobber='true'}[ecallmgr_Auto-Call-Rule-ID='26f66860-cc02-47da-8a63-b9a0d5fde347',ecallmgr_Authorizing-Type='device',inherit_codec='false',ecallmgr_Authorizing-ID='31e479fb292c414fe62e928edbabb619',ecallmgr_Retain-CID='true',sip_auto_answer='true',ecallmgr_Account-ID='07a049bfcfd1685c42a8762a4897626f',ecallmgr_Realm='sip.meituan.com',origination_uuid=4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall]loopback/+12345678901/context_2 'm:^:unset:origination_callee_id_number^unset:origination_callee_id_name^unset:origination_caller_id_number^unset:origination_caller_id_name^unset:ignore_early_media^unset:continue_on_fail^unset:call_timeout^unset:originate_timeout^unset:ecallmgr_Auto-Call-Rule-ID^unset:inherit_codec^unset:sip_auto_answer^unset:ignore_early_media^unset:group_confirm_key^unset:group_confirm_cancel_timeout^unset:group_confirm_file^unset:sip_h_Call-Info^unset:sip_h_Alert-Info^unset:alert_info^unset:sip_invite_params^set:sip_auto_answer=false^transfer:+11223344 XML context_2' inline)
2016-06-29 18:34:50.158155 [INFO] kazoo_node.c:625 exec: originate({origination_callee_id_number='+xxxxxxxxxxx',origination_callee_id_name='xxxxxxxxxxx',origination_caller_id_number='11223344',origination_caller_id_name='Device QuickCall',ignore_early_media='true',continue_on_fail='true',call_timeout=30,originate_timeout=30,ecallmgr_Fetch-ID='50959064daeb4618e061642323748a91',ecallmgr_Ecallmgr-Node='ecall...@mcc-kazoo02.sjz.info',ecallmgr_Auto-Call-Rule-ID='8e8fd6de-8726-4881-b92f-bede1f712596',ecallmgr_Authorizing-Type='device',inherit_codec='false',ecallmgr_Authorizing-ID='8fb9289d0313d57d04ad4112564b9312',ecallmgr_Retain-CID='true',sip_auto_answer='true',ecallmgr_Account-ID='07a049bfcfd1685c42a8762a4897626f',local_var_clobber='true'}[ecallmgr_Auto-Call-Rule-ID='8e8fd6de-8726-4881-b92f-bede1f712596',ecallmgr_Authorizing-Type='device',inherit_codec='false',ecallmgr_Authorizing-ID='8fb9289d0313d57d04ad4112564b9312',ecallmgr_Retain-CID='true',sip_auto_answer='true',ecallmgr_Account-ID='07a049bfcfd1685c42a8762a4897626f',ecallmgr_Realm='sip.meituan.com',origination_uuid=f62bb835a781fa4ec508c620a1beee37e535-autocall]loopback/+xxxxxxxxxxx/context_2 'm:^:unset:origination_callee_id_number^unset:origination_callee_id_name^unset:origination_caller_id_number^unset:origination_caller_id_name^unset:ignore_early_media^unset:continue_on_fail^unset:call_timeout^unset:originate_timeout^unset:ecallmgr_Auto-Call-Rule-ID^unset:inherit_codec^unset:sip_auto_answer^unset:ignore_early_media^unset:group_confirm_key^unset:group_confirm_cancel_timeout^unset:group_confirm_file^unset:sip_h_Call-Info^unset:sip_h_Alert-Info^unset:alert_info^unset:sip_invite_params^set:sip_auto_answer=false^transfer:+11223344 XML context_2' inline)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action log(NOTICE log|${uuid}|ecallmgr@mcc-kazoo02.sjz.sankuai.info won call control)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action export(ecallmgr_Ecallmgr-Node=ecal...@mcc-kazoo02.sjz.sankuai.info)1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ringback=%(2000,4000,440,480))1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(transfer_ringback=%(2000,4000,440,480))
1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Ecallmgr-Node=ecall...@mcc-kazoo02.sjz.sankuai.info)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Auto-Call-Rule-ID=8e8fd6de-8726-4881-b92f-bede1f712596)1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Authorizing-Type=device)1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Authorizing-ID=8fb9289d0313d57d04ad4112564b9312)1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Retain-CID=true)1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Account-ID=07a049bfcfd1685c42a8762a4897626f)1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Realm=sip.meituan.com)1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Fetch-ID=50959064daeb4618e061642323748a91)1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action park()1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_core_state_machine.c:216 (loopback/+xxxxxxxxxxx-b) State Change CS_ROUTING -> CS_EXECUTE1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_core_session.c:1388 Send signal loopback/+xxxxxxxxxxx-b [BREAK]1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_loopback.c:598 loopback/+xxxxxxxxxxx-b CHANNEL KILL1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_core_state_machine.c:528 (loopback/+xxxxxxxxxxx-b) State ROUTING going to sleep1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_core_state_machine.c:472 (loopback/+xxxxxxxxxxx-b) Running State Change CS_EXECUTE1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_core_state_machine.c:535 (loopback/+xxxxxxxxxxx-b) State EXECUTE1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_loopback.c:433 loopback/+xxxxxxxxxxx-b CHANNEL EXECUTE1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_core_state_machine.c:258 loopback/+xxxxxxxxxxx-b Standard EXECUTE1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b export(ecallmgr_Bridge-ID=1ba06aa6-3de5-11e6-87d8-fbf5318198dc)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_channel.c:1247 EXPORT (export_vars) [ecallmgr_Bridge-ID]=[1ba06aa6-3de5-11e6-87d8-fbf5318198dc]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b log(NOTICE log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|ecallmgr@mcc-kazoo02.sjz.sankuai.info won call control)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [NOTICE] mod_dptools.c:1650 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|ecallmgr@mcc-kazoo02.sjz.sankuai.info won call control1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b export(ecallmgr_Ecallmgr-Node=ecal...@mcc-kazoo02.sjz.sankuai.info)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_channel.c:1247 EXPORT (export_vars) [ecallmgr_Ecallmgr-Node]=[ecallm...@mcc-kazoo02.sjz.sankuai.info]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ringback=%(2000,4000,440,480))1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ringback]=[%(2000,4000,440,480)]1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(transfer_ringback=%(2000,4000,440,480))1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [transfer_ringback]=[%(2000,4000,440,480)]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Ecallmgr-Node=ecall...@mcc-kazoo02.sjz.sankuai.info)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Ecallmgr-Node]=[ecallm...@mcc-kazoo02.sjz.sankuai.info]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Auto-Call-Rule-ID=8e8fd6de-8726-4881-b92f-bede1f712596)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Auto-Call-Rule-ID]=[8e8fd6de-8726-4881-b92f-bede1f712596]1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Authorizing-Type=device)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Authorizing-Type]=[device]1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Authorizing-ID=8fb9289d0313d57d04ad4112564b9312)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Authorizing-ID]=[8fb9289d0313d57d04ad4112564b9312]1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Retain-CID=true)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Retain-CID]=[true]1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Account-ID=07a049bfcfd1685c42a8762a4897626f)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Account-ID]=[07a049bfcfd1685c42a8762a4897626f]1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Realm=sip.meituan.com)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Realm]=[sip.meituan.com]1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Fetch-ID=50959064daeb4618e061642323748a91)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Fetch-ID]=[50959064daeb4618e061642323748a91]1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b park()
2016-06-29 18:34:50.438152 [INFO] kazoo_node.c:625 exec: uuid_setvar_multi(1ba06aa6-3de5-11e6-87d8-fbf5318198dc ecallmgr_Application-Name=callflow;ecallmgr_Application-Node=whistle_apps@mcc-kazoo01.sjz.sankuai.info;ecallmgr_Ecallmgr-Node=ecallmgr@mcc-kazoo02.sjz.sankuai.info;ecallmgr_Auto-Call-Rule-ID=8e8fd6de-8726-4881-b92f-bede1f712596;ecallmgr_Authorizing-Type=device;ecallmgr_Authorizing-ID=8fb9289d0313d57d04ad4112564b9312;ecallmgr_Retain-CID=true;ecallmgr_Account-ID=07a049bfcfd1685c42a8762a4897626f;ecallmgr_Realm=sip.meituan.com;ecallmgr_Fetch-ID=50959064daeb4618e061642323748a91)2016-06-29 18:34:50.478183 [INFO] kazoo_node.c:625 exec: uuid_setvar_multi(1ba06aa6-3de5-11e6-87d8-fbf5318198dc effective_caller_id_name=Device QuickCall;effective_caller_id_number=11223344;ecallmgr_Application-Name=callflow;ecallmgr_Application-Node=whistle_...@mcc-kazoo01.sjz.sankuai.info;ecallmgr_Ecallmgr-Node=ecallmgr@mcc-kazoo02.sjz.sankuai.info;ecallmgr_Auto-Call-Rule-ID=8e8fd6de-8726-4881-b92f-bede1f712596;ecallmgr_Authorizing-Type=device;ecallmgr_Authorizing-ID=8fb9289d0313d57d04ad4112564b9312;ecallmgr_Retain-CID=true;ecallmgr_Account-ID=07a049bfcfd1685c42a8762a4897626f;ecallmgr_Realm=sip.meituan.com;ecallmgr_Fetch-ID=50959064daeb4618e061642323748a91)
2016-06-29 18:34:51.078137 [INFO] kazoo_node.c:625 exec: uuid_setvar(1ba06aa6-3de5-11e6-87d8-fbf5318198dc ecallmgr_Recording-Url http://mcc-test/recording/call_recording_1ba06aa6-3de5-11e6-87d8-fbf5318198dc.mp3)
2016-06-29 18:34:51.158188 [INFO] kazoo_node.c:625 exec: uuid_setvar_multi(1ba06aa6-3de5-11e6-87d8-fbf5318198dc effective_caller_id_name=Device QuickCall;effective_caller_id_number=11223344;ecallmgr_Application-Name=callflow;ecallmgr_Application-Node=whistle_...@mcc-kazoo01.sjz.sankuai.info;ecallmgr_Ecallmgr-Node=ecallmgr@mcc-kazoo02.sjz.sankuai.info;ecallmgr_Auto-Call-Rule-ID=8e8fd6de-8726-4881-b92f-bede1f712596;ecallmgr_Authorizing-Type=device;ecallmgr_Authorizing-ID=8fb9289d0313d57d04ad4112564b9312;ecallmgr_Retain-CID=true;ecallmgr_Account-ID=07a049bfcfd1685c42a8762a4897626f;ecallmgr_Realm=sip.meituan.com;ecallmgr_Fetch-ID=50959064daeb4618e061642323748a91)
2016-06-29 18:34:51.158188 [NOTICE] kazoo_node.c:302 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|building xferext extension: set ignore_display_updates=true2016-06-29 18:34:51.158188 [NOTICE] kazoo_node.c:302 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|building xferext extension: set continue_on_fail=true2016-06-29 18:34:51.158188 [NOTICE] kazoo_node.c:302 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|building xferext extension: export sip_redirect_context=context_22016-06-29 18:34:51.158188 [NOTICE] kazoo_node.c:302 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|building xferext extension: set hangup_after_bridge=true2016-06-29 18:34:51.158188 [NOTICE] kazoo_node.c:302 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|building xferext extension: export ecallmgr_Inception=${ecallmgr_Inception}
2016-06-29 18:34:51.158188 [NOTICE] kazoo_node.c:302 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|building xferext extension: bridge {ignore_display_updates='true',ecallmgr_Account-ID='07a049bfcfd1685c42a8762a4897626f',ecallmgr_Reseller-ID='07a049bfcfd1685c42a8762a4897626f',effective_caller_id_number='11223344',effective_caller_id_name='Device QuickCall',origination_caller_id_number='11223344',origination_caller_id_name='Device QuickCall',ignore_early_media='false',local_var_clobber='true'}[sip_auth_password='mt@009009',sip_auth_username='admin',sip_from_uri='sip:1122...@x.x.6.205',ecallmgr_Global-Resource='false',ecallmgr_Resource-ID='9417ff08859d9c4d65fcc02ab86b2fd0',ecallmgr_E164-Destination='+xxxxxxxxxxx',ecallmgr_Original-Number='0xxxxxxxxxxx',absolute_codec_string='^^:PCMA',leg_progress_timeout='30',effective_callee_id_number='0xxxxxxxxxxx',effective_callee_id_name='0xxxxxxxxxxx']sofia/sipinterface_1/0xxxxxxxxxxx@x.x.6.205|[sip_auth_password='mt@009009',sip_auth_username='admin',sip_from_uri='sip:1122...@x.x.0.75',ecallmgr_Global-Resource='false',ecallmgr_Resource-ID='dc57fba99f6d7d73ebdba7978aceb58f',ecallmgr_E164-Destination='+xxxxxxxxxxx',ecallmgr_Original-Number='0xxxxxxxxxxx',absolute_codec_string='^^:PCMA',leg_progress_timeout='30',effective_callee_id_number='0xxxxxxxxxxx',effective_callee_id_name='0xxxxxxxxxxx']sofia/sipinterface_1/0xxxxxxxxxxx@x.x.0.75
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b bridge({ignore_display_updates=true,ecallmgr_Account-ID=07a049bfcfd1685c42a8762a4897626f,ecallmgr_Reseller-ID=07a049bfcfd1685c42a8762a4897626f,effective_caller_id_number=11223344,effective_caller_id_name=Device QuickCall,origination_caller_id_number=11223344,origination_caller_id_name=Device QuickCall,ignore_early_media=false,local_var_clobber=true}[sip_auth_password=mt@009009,sip_auth_username=admin,sip_from_uri=sip:1122...@x.x.6.205,ecallmgr_Global-Resource=false,ecallmgr_Resource-ID=9417ff08859d9c4d65fcc02ab86b2fd0,ecallmgr_E164-Destination=+xxxxxxxxxxx,ecallmgr_Original-Number=0xxxxxxxxxxx,absolute_codec_string=^^:PCMA,leg_progress_timeout=30,effective_callee_id_number=0xxxxxxxxxxx,effective_callee_id_name=0xxxxxxxxxxx]sofia/sipinterface_1/0xxxxxxxxxxx@x.x.6.205|[sip_auth_password=mt@009009,sip_auth_username=admin,sip_from_uri=sip:1122...@x.x.0.75,ecallmgr_Global-Resource=false,ecallmgr_Resource-ID=dc57fba99f6d7d73ebdba7978aceb58f,ecallmgr_E164-Destination=+xxxxxxxxxxx,ecallmgr_Original-Number=0xxxxxxxxxxx,absolute_codec_string=^^:PCMA,leg_progress_timeout=30,effective_callee_id_number=0xxxxxxxxxxx,effective_callee_id_name=0xxxxxxxxxxx]sofia/sipinterface_1/0xxxxxxxxxxx@x.x.0.75)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_channel.c:1201 loopback/+xxxxxxxxxxx-b EXPORTING[export_vars] [ecallmgr_Bridge-ID]=[1ba06aa6-3de5-11e6-87d8-fbf5318198dc] to event
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_channel.c:1201 loopback/+xxxxxxxxxxx-b EXPORTING[export_vars] [ecallmgr_Ecallmgr-Node]=[ecallm...@mcc-kazoo02.sjz.sankuai.info] to event
...
Mountain King
Jul 6, 2016, 7:41:35 AM7/6/16
to 2600hz-dev
I drawn a conclusion that freeswitch will reproduct 'no route destinaton' bug when clicktocall request bigger than 4 per second by integration testing, and when clicktocall about 1000 requests at 4cps，freeswitch will product about 60 'no route destination' bug.
在 2016年6月30日星期四 UTC+8下午11:32:52，Mountain King写道：
when using clicktocall function, will bring about NO_ROUTE_DESTINATION error, the one is calling to callee for example 12345678901, and the other is callee was called,but when transfer to caller can't find dialplan;
=================================callee can't called
2016-06-29 18:31:36.818449 [INFO] kazoo_node.c:625 exec: originate({origination_callee_id_number='+12345678901',origination_callee_id_name='12345678901',origination_caller_id_number='11223344',origination_caller_id_name='Device QuickCall',ignore_early_media='true',continue_on_fail='true',call_timeout=30,originate_timeout=30,ecallmgr_Fetch-ID='72b1d2999b5901b6f67779b755e82d16',ecallmgr_Ecallmgr-Node='ecall...@mcc-kazoo02.sjz.sankuai.info',ecallmgr_Auto-Call-Rule-ID='26f66860-cc02-47da-8a63-b9a0d5fde347',ecallmgr_Authorizing-Type='device',inherit_codec='false',ecallmgr_Authorizing-ID='31e479fb292c414fe62e928edbabb619',ecallmgr_Retain-CID='true',sip_auto_answer='true',ecallmgr_Account-ID='07a049bfcfd1685c42a8762a4897626f',local_var_clobber='true'}[ecallmgr_Auto-Call-Rule-ID='26f66860-cc02-47da-8a63-b9a0d5fde347',ecallmgr_Authorizing-Type='device',inherit_codec='false',ecallmgr_Authorizing-ID='31e479fb292c414fe62e928edbabb619',ecallmgr_Retain-CID='true',sip_auto_answer='true',ecallmgr_Account-ID='07a049bfcfd1685c42a8762a4897626f',ecallmgr_Realm='sip.meituan.com',origination_uuid=4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall]loopback/+12345678901/context_2 'm:^:unset:origination_callee_id_number^unset:origination_callee_id_name^unset:origination_caller_id_number^unset:origination_caller_id_name^unset:ignore_early_media^unset:continue_on_fail^unset:call_timeout^unset:originate_timeout^unset:ecallmgr_Auto-Call-Rule-ID^unset:inherit_codec^unset:sip_auto_answer^unset:ignore_early_media^unset:group_confirm_key^unset:group_confirm_cancel_timeout^unset:group_confirm_file^unset:sip_h_Call-Info^unset:sip_h_Alert-Info^unset:alert_info^unset:sip_invite_params^set:sip_auto_answer=false^transfer:+11223344 XML context_2' inline)
2016-06-29 18:34:50.158155 [INFO] kazoo_node.c:625 exec: originate({origination_callee_id_number='+xxxxxxxxxxx',origination_callee_id_name='xxxxxxxxxxx',origination_caller_id_number='11223344',origination_caller_id_name='Device QuickCall',ignore_early_media='true',continue_on_fail='true',call_timeout=30,originate_timeout=30,ecallmgr_Fetch-ID='50959064daeb4618e061642323748a91',ecallmgr_Ecallmgr-Node='ecall...@mcc-kazoo02.sjz.sankuai.info',ecallmgr_Auto-Call-Rule-ID='8e8fd6de-8726-4881-b92f-bede1f712596',ecallmgr_Authorizing-Type='device',inherit_codec='false',ecallmgr_Authorizing-ID='8fb9289d0313d57d04ad4112564b9312',ecallmgr_Retain-CID='true',sip_auto_answer='true',ecallmgr_Account-ID='07a049bfcfd1685c42a8762a4897626f',local_var_clobber='true'}[ecallmgr_Auto-Call-Rule-ID='8e8fd6de-8726-4881-b92f-bede1f712596',ecallmgr_Authorizing-Type='device',inherit_codec='false',ecallmgr_Authorizing-ID='8fb9289d0313d57d04ad4112564b9312',ecallmgr_Retain-CID='true',sip_auto_answer='true',ecallmgr_Account-ID='07a049bfcfd1685c42a8762a4897626f',ecallmgr_Realm='sip.meituan.com',origination_uuid=f62bb835a781fa4ec508c620a1beee37e535-autocall]loopback/+xxxxxxxxxxx/context_2 'm:^:unset:origination_callee_id_number^unset:origination_callee_id_name^unset:origination_caller_id_number^unset:origination_caller_id_name^unset:ignore_early_media^unset:continue_on_fail^unset:call_timeout^unset:originate_timeout^unset:ecallmgr_Auto-Call-Rule-ID^unset:inherit_codec^unset:sip_auto_answer^unset:ignore_early_media^unset:group_confirm_key^unset:group_confirm_cancel_timeout^unset:group_confirm_file^unset:sip_h_Call-Info^unset:sip_h_Alert-Info^unset:alert_info^unset:sip_invite_params^set:sip_auto_answer=false^transfer:+11223344 XML context_2' inline)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action log(NOTICE log|${uuid}|ecallmgr@mcc-kazoo02.sjz.sankuai.info won call control)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action export(ecallmgr_Ecallmgr-Node=ecal...@mcc-kazoo02.sjz.sankuai.info)1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ringback=%(2000,4000,440,480))1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(transfer_ringback=%(2000,4000,440,480))
1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Ecallmgr-Node=ecall...@mcc-kazoo02.sjz.sankuai.info)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Auto-Call-Rule-ID=8e8fd6de-8726-4881-b92f-bede1f712596)1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Authorizing-Type=device)1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Authorizing-ID=8fb9289d0313d57d04ad4112564b9312)1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Retain-CID=true)1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Account-ID=07a049bfcfd1685c42a8762a4897626f)1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Realm=sip.meituan.com)1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Fetch-ID=50959064daeb4618e061642323748a91)1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action park()1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_core_state_machine.c:216 (loopback/+xxxxxxxxxxx-b) State Change CS_ROUTING -> CS_EXECUTE1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_core_session.c:1388 Send signal loopback/+xxxxxxxxxxx-b [BREAK]1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_loopback.c:598 loopback/+xxxxxxxxxxx-b CHANNEL KILL1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_core_state_machine.c:528 (loopback/+xxxxxxxxxxx-b) State ROUTING going to sleep1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_core_state_machine.c:472 (loopback/+xxxxxxxxxxx-b) Running State Change CS_EXECUTE1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_core_state_machine.c:535 (loopback/+xxxxxxxxxxx-b) State EXECUTE1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_loopback.c:433 loopback/+xxxxxxxxxxx-b CHANNEL EXECUTE1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_core_state_machine.c:258 loopback/+xxxxxxxxxxx-b Standard EXECUTE1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b export(ecallmgr_Bridge-ID=1ba06aa6-3de5-11e6-87d8-fbf5318198dc)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_channel.c:1247 EXPORT (export_vars) [ecallmgr_Bridge-ID]=[1ba06aa6-3de5-11e6-87d8-fbf5318198dc]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b log(NOTICE log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|ecallmgr@mcc-kazoo02.sjz.sankuai.info won call control)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [NOTICE] mod_dptools.c:1650 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|ecallmgr@mcc-kazoo02.sjz.sankuai.info won call control1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b export(ecallmgr_Ecallmgr-Node=ecal...@mcc-kazoo02.sjz.sankuai.info)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_channel.c:1247 EXPORT (export_vars) [ecallmgr_Ecallmgr-Node]=[ecallm...@mcc-kazoo02.sjz.sankuai.info]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ringback=%(2000,4000,440,480))1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ringback]=[%(2000,4000,440,480)]1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(transfer_ringback=%(2000,4000,440,480))1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [transfer_ringback]=[%(2000,4000,440,480)]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Ecallmgr-Node=ecall...@mcc-kazoo02.sjz.sankuai.info)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Ecallmgr-Node]=[ecallm...@mcc-kazoo02.sjz.sankuai.info]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Auto-Call-Rule-ID=8e8fd6de-8726-4881-b92f-bede1f712596)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Auto-Call-Rule-ID]=[8e8fd6de-8726-4881-b92f-bede1f712596]1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Authorizing-Type=device)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Authorizing-Type]=[device]1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Authorizing-ID=8fb9289d0313d57d04ad4112564b9312)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Authorizing-ID]=[8fb9289d0313d57d04ad4112564b9312]1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Retain-CID=true)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Retain-CID]=[true]1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Account-ID=07a049bfcfd1685c42a8762a4897626f)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Account-ID]=[07a049bfcfd1685c42a8762a4897626f]1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Realm=sip.meituan.com)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Realm]=[sip.meituan.com]1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Fetch-ID=50959064daeb4618e061642323748a91)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Fetch-ID]=[50959064daeb4618e061642323748a91]1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b park()
2016-06-29 18:34:50.438152 [INFO] kazoo_node.c:625 exec: uuid_setvar_multi(1ba06aa6-3de5-11e6-87d8-fbf5318198dc ecallmgr_Application-Name=callflow;ecallmgr_Application-Node=whistle_apps@mcc-kazoo01.sjz.sankuai.info;ecallmgr_Ecallmgr-Node=ecallmgr@mcc-kazoo02.sjz.sankuai.info;ecallmgr_Auto-Call-Rule-ID=8e8fd6de-8726-4881-b92f-bede1f712596;ecallmgr_Authorizing-Type=device;ecallmgr_Authorizing-ID=8fb9289d0313d57d04ad4112564b9312;ecallmgr_Retain-CID=true;ecallmgr_Account-ID=07a049bfcfd1685c42a8762a4897626f;ecallmgr_Realm=sip.meituan.com;ecallmgr_Fetch-ID=50959064daeb4618e061642323748a91)2016-06-29 18:34:50.478183 [INFO] kazoo_node.c:625 exec: uuid_setvar_multi(1ba06aa6-3de5-11e6-87d8-fbf5318198dc effective_caller_id_name=Device QuickCall;effective_caller_id_number=11223344;ecallmgr_Application-Name=callflow;ecallmgr_Application-Node=whistle_...@mcc-kazoo01.sjz.sankuai.info;ecallmgr_Ecallmgr-Node=ecallmgr@mcc-kazoo02.sjz.sankuai.info;ecallmgr_Auto-Call-Rule-ID=8e8fd6de-8726-4881-b92f-bede1f712596;ecallmgr_Authorizing-Type=device;ecallmgr_Authorizing-ID=8fb9289d0313d57d04ad4112564b9312;ecallmgr_Retain-CID=true;ecallmgr_Account-ID=07a049bfcfd1685c42a8762a4897626f;ecallmgr_Realm=sip.meituan.com;ecallmgr_Fetch-ID=50959064daeb4618e061642323748a91)
2016-06-29 18:34:51.078137 [INFO] kazoo_node.c:625 exec: uuid_setvar(1ba06aa6-3de5-11e6-87d8-fbf5318198dc ecallmgr_Recording-Url http://mcc-test/recording/call_recording_1ba06aa6-3de5-11e6-87d8-fbf5318198dc.mp3)
2016-06-29 18:34:51.158188 [INFO] kazoo_node.c:625 exec: uuid_setvar_multi(1ba06aa6-3de5-11e6-87d8-fbf5318198dc effective_caller_id_name=Device QuickCall;effective_caller_id_number=11223344;ecallmgr_Application-Name=callflow;ecallmgr_Application-Node=whistle_...@mcc-kazoo01.sjz.sankuai.info;ecallmgr_Ecallmgr-Node=ecallmgr@mcc-kazoo02.sjz.sankuai.info;ecallmgr_Auto-Call-Rule-ID=8e8fd6de-8726-4881-b92f-bede1f712596;ecallmgr_Authorizing-Type=device;ecallmgr_Authorizing-ID=8fb9289d0313d57d04ad4112564b9312;ecallmgr_Retain-CID=true;ecallmgr_Account-ID=07a049bfcfd1685c42a8762a4897626f;ecallmgr_Realm=sip.meituan.com;ecallmgr_Fetch-ID=50959064daeb4618e061642323748a91)
2016-06-29 18:34:51.158188 [NOTICE] kazoo_node.c:302 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|building xferext extension: set ignore_display_updates=true2016-06-29 18:34:51.158188 [NOTICE] kazoo_node.c:302 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|building xferext extension: set continue_on_fail=true2016-06-29 18:34:51.158188 [NOTICE] kazoo_node.c:302 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|building xferext extension: export sip_redirect_context=context_22016-06-29 18:34:51.158188 [NOTICE] kazoo_node.c:302 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|building xferext extension: set hangup_after_bridge=true2016-06-29 18:34:51.158188 [NOTICE] kazoo_node.c:302 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|building xferext extension: export ecallmgr_Inception=${ecallmgr_Inception}
2016-06-29 18:34:51.158188 [NOTICE] kazoo_node.c:302 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|building xferext extension: bridge {ignore_display_updates='true',ecallmgr_Account-ID='07a049bfcfd1685c42a8762a4897626f',ecallmgr_Reseller-ID='07a049bfcfd1685c42a8762a4897626f',effective_caller_id_number='11223344',effective_caller_id_name='Device QuickCall',origination_caller_id_number='11223344',origination_caller_id_name='Device QuickCall',ignore_early_media='false',local_var_clobber='true'}[sip_auth_password='mt@009009',sip_auth_username='admin',sip_from_uri='sip:1122...@x.x.6.205',ecallmgr_Global-Resource='false',ecallmgr_Resource-ID='9417ff08859d9c4d65fcc02ab86b2fd0',ecallmgr_E164-Destination='+xxxxxxxxxxx',ecallmgr_Original-Number='0xxxxxxxxxxx',absolute_codec_string='^^:PCMA',leg_progress_timeout='30',effective_callee_id_number='0xxxxxxxxxxx',effective_callee_id_name='0xxxxxxxxxxx']sofia/sipinterface_1/0xxxxxxxxxxx@x.x.6.205|[sip_auth_password='mt@009009',sip_auth_username='admin',sip_from_uri='sip:1122...@x.x.0.75',ecallmgr_Global-Resource='false',ecallmgr_Resource-ID='dc57fba99f6d7d73ebdba7978aceb58f',ecallmgr_E164-Destination='+xxxxxxxxxxx',ecallmgr_Original-Number='0xxxxxxxxxxx',absolute_codec_string='^^:PCMA',leg_progress_timeout='30',effective_callee_id_number='0xxxxxxxxxxx',effective_callee_id_name='0xxxxxxxxxxx']sofia/sipinterface_1/0xxxxxxxxxxx@x.x.0.75
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b bridge({ignore_display_updates=true,ecallmgr_Account-ID=07a049bfcfd1685c42a8762a4897626f,ecallmgr_Reseller-ID=07a049bfcfd1685c42a8762a4897626f,effective_caller_id_number=11223344,effective_caller_id_name=Device QuickCall,origination_caller_id_number=11223344,origination_caller_id_name=Device QuickCall,ignore_early_media=false,local_var_clobber=true}[sip_auth_password=mt@009009,sip_auth_username=admin,sip_from_uri=sip:1122...@x.x.6.205,ecallmgr_Global-Resource=false,ecallmgr_Resource-ID=9417ff08859d9c4d65fcc02ab86b2fd0,ecallmgr_E164-Destination=+xxxxxxxxxxx,ecallmgr_Original-Number=0xxxxxxxxxxx,absolute_codec_string=^^:PCMA,leg_progress_timeout=30,effective_callee_id_number=0xxxxxxxxxxx,effective_callee_id_name=0xxxxxxxxxxx]sofia/sipinterface_1/0xxxxxxxxxxx@x.x.6.205|[sip_auth_password=mt@009009,sip_auth_username=admin,sip_from_uri=sip:1122...@x.x.0.75,ecallmgr_Global-Resource=false,ecallmgr_Resource-ID=dc57fba99f6d7d73ebdba7978aceb58f,ecallmgr_E164-Destination=+xxxxxxxxxxx,ecallmgr_Original-Number=0xxxxxxxxxxx,absolute_codec_string=^^:PCMA,leg_progress_timeout=30,effective_callee_id_number=0xxxxxxxxxxx,effective_callee_id_name=0xxxxxxxxxxx]sofia/sipinterface_1/0xxxxxxxxxxx@x.x.0.75)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_channel.c:1201 loopback/+xxxxxxxxxxx-b EXPORTING[export_vars] [ecallmgr_Bridge-ID]=[1ba06aa6-3de5-11e6-87d8-fbf5318198dc] to event
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_channel.c:1201 loopback/+xxxxxxxxxxx-b EXPORTING[export_vars] [ecallmgr_Ecallmgr-Node]=[ecallm...@mcc-kazoo02.sjz.sankuai.info] to event
...
emnvn
Feb 15, 2017, 10:30:58 AM2/15/17
to 2600hz-dev
I meet the same problem. When I make about 20 request I will see about 5 calls with error of "no_route_response". I tried to trace the problem, the result that I saw the error when Ecallmgr send REPLY to kazoo (freeswitch:fetch_reply(Node, FetchId, Section, iolist_to_binary(XML), 3 * ?MILLISECONDS_IN_SECOND))
Error In ecallmgr: {error,baduuid}
Error in FreeSWITCH: 2017-02-15 01:29:54.352776 [ERR] kazoo_node.c:760 Recieved fetch reply for an unknown/expired UUID: 2782c512-f348-11e6-9ca5-39395c182d1f
I only use one Kazoo Node. I don't know how to improve Kazoo/FreeSWITCH performance.
Hope any help!
Thanks.
Error In ecallmgr: {error,baduuid}
Error in FreeSWITCH: 2017-02-15 01:29:54.352776 [ERR] kazoo_node.c:760 Recieved fetch reply for an unknown/expired UUID: 2782c512-f348-11e6-9ca5-39395c182d1f
I only use one Kazoo Node. I don't know how to improve Kazoo/FreeSWITCH performance.
Hope any help!
Thanks.
On Thursday, June 30, 2016 at 10:32:52 PM UTC+7, Mountain King wrote:
when using clicktocall function, will bring about NO_ROUTE_DESTINATION error, the one is calling to callee for example 12345678901, and the other is callee was called,but when transfer to caller can't find dialplan;
=================================callee can't called
2016-06-29 18:31:36.818449 [INFO] kazoo_node.c:625 exec: originate({origination_callee_id_number='+12345678901',origination_callee_id_name='12345678901',origination_caller_id_number='11223344',origination_caller_id_name='Device QuickCall',ignore_early_media='true',continue_on_fail='true',call_timeout=30,originate_timeout=30,ecallmgr_Fetch-ID='72b1d2999b5901b6f67779b755e82d16',ecallmgr_Ecallmgr-Node='ecall...@mcc-kazoo02.sjz.sankuai.info',ecallmgr_Auto-Call-Rule-ID='26f66860-cc02-47da-8a63-b9a0d5fde347',ecallmgr_Authorizing-Type='device',inherit_codec='false',ecallmgr_Authorizing-ID='31e479fb292c414fe62e928edbabb619',ecallmgr_Retain-CID='true',sip_auto_answer='true',ecallmgr_Account-ID='07a049bfcfd1685c42a8762a4897626f',local_var_clobber='true'}[ecallmgr_Auto-Call-Rule-ID='26f66860-cc02-47da-8a63-b9a0d5fde347',ecallmgr_Authorizing-Type='device',inherit_codec='false',ecallmgr_Authorizing-ID='31e479fb292c414fe62e928edbabb619',ecallmgr_Retain-CID='true',sip_auto_answer='true',ecallmgr_Account-ID='07a049bfcfd1685c42a8762a4897626f',ecallmgr_Realm='sip.meituan.com',origination_uuid=4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall]loopback/+12345678901/context_2 'm:^:unset:origination_callee_id_number^unset:origination_callee_id_name^unset:origination_caller_id_number^unset:origination_caller_id_name^unset:ignore_early_media^unset:continue_on_fail^unset:call_timeout^unset:originate_timeout^unset:ecallmgr_Auto-Call-Rule-ID^unset:inherit_codec^unset:sip_auto_answer^unset:ignore_early_media^unset:group_confirm_key^unset:group_confirm_cancel_timeout^unset:group_confirm_file^unset:sip_h_Call-Info^unset:sip_h_Alert-Info^unset:alert_info^unset:sip_invite_params^set:sip_auto_answer=false^transfer:+11223344 XML context_2' inline)
2016-06-29 18:34:50.158155 [INFO] kazoo_node.c:625 exec: originate({origination_callee_id_number='+xxxxxxxxxxx',origination_callee_id_name='xxxxxxxxxxx',origination_caller_id_number='11223344',origination_caller_id_name='Device QuickCall',ignore_early_media='true',continue_on_fail='true',call_timeout=30,originate_timeout=30,ecallmgr_Fetch-ID='50959064daeb4618e061642323748a91',ecallmgr_Ecallmgr-Node='ecall...@mcc-kazoo02.sjz.sankuai.info',ecallmgr_Auto-Call-Rule-ID='8e8fd6de-8726-4881-b92f-bede1f712596',ecallmgr_Authorizing-Type='device',inherit_codec='false',ecallmgr_Authorizing-ID='8fb9289d0313d57d04ad4112564b9312',ecallmgr_Retain-CID='true',sip_auto_answer='true',ecallmgr_Account-ID='07a049bfcfd1685c42a8762a4897626f',local_var_clobber='true'}[ecallmgr_Auto-Call-Rule-ID='8e8fd6de-8726-4881-b92f-bede1f712596',ecallmgr_Authorizing-Type='device',inherit_codec='false',ecallmgr_Authorizing-ID='8fb9289d0313d57d04ad4112564b9312',ecallmgr_Retain-CID='true',sip_auto_answer='true',ecallmgr_Account-ID='07a049bfcfd1685c42a8762a4897626f',ecallmgr_Realm='sip.meituan.com',origination_uuid=f62bb835a781fa4ec508c620a1beee37e535-autocall]loopback/+xxxxxxxxxxx/context_2 'm:^:unset:origination_callee_id_number^unset:origination_callee_id_name^unset:origination_caller_id_number^unset:origination_caller_id_name^unset:ignore_early_media^unset:continue_on_fail^unset:call_timeout^unset:originate_timeout^unset:ecallmgr_Auto-Call-Rule-ID^unset:inherit_codec^unset:sip_auto_answer^unset:ignore_early_media^unset:group_confirm_key^unset:group_confirm_cancel_timeout^unset:group_confirm_file^unset:sip_h_Call-Info^unset:sip_h_Alert-Info^unset:alert_info^unset:sip_invite_params^set:sip_auto_answer=false^transfer:+11223344 XML context_2' inline)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Ecallmgr-Node=ecall...@mcc-kazoo02.sjz.sankuai.info)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Auto-Call-Rule-ID=8e8fd6de-8726-4881-b92f-bede1f712596)1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Authorizing-Type=device)1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Authorizing-ID=8fb9289d0313d57d04ad4112564b9312)1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Retain-CID=true)1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Account-ID=07a049bfcfd1685c42a8762a4897626f)1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Realm=sip.meituan.com)1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Fetch-ID=50959064daeb4618e061642323748a91)1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action park()1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_core_state_machine.c:216 (loopback/+xxxxxxxxxxx-b) State Change CS_ROUTING -> CS_EXECUTE1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_core_session.c:1388 Send signal loopback/+xxxxxxxxxxx-b [BREAK]1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_loopback.c:598 loopback/+xxxxxxxxxxx-b CHANNEL KILL1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_core_state_machine.c:528 (loopback/+xxxxxxxxxxx-b) State ROUTING going to sleep1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_core_state_machine.c:472 (loopback/+xxxxxxxxxxx-b) Running State Change CS_EXECUTE1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_core_state_machine.c:535 (loopback/+xxxxxxxxxxx-b) State EXECUTE1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_loopback.c:433 loopback/+xxxxxxxxxxx-b CHANNEL EXECUTE1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_core_state_machine.c:258 loopback/+xxxxxxxxxxx-b Standard EXECUTE1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b export(ecallmgr_Bridge-ID=1ba06aa6-3de5-11e6-87d8-fbf5318198dc)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_channel.c:1247 EXPORT (export_vars) [ecallmgr_Bridge-ID]=[1ba06aa6-3de5-11e6-87d8-fbf5318198dc]1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b log(NOTICE log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|ecal...@mcc-kazoo02.sjz.sankuai.info won call control)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [NOTICE] mod_dptools.c:1650 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|ecal...@mcc-kazoo02.sjz.sankuai.info won call control1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b export(ecallmgr_Ecallmgr-Node=ecal...@mcc-kazoo02.sjz.sankuai.info)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_channel.c:1247 EXPORT (export_vars) [ecallmgr_Ecallmgr-Node]=[ecallm...@mcc-kazoo02.sjz.sankuai.info]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ringback=%(2000,4000,440,480))1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ringback]=[%(2000,4000,440,480)]1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(transfer_ringback=%(2000,4000,440,480))1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [transfer_ringback]=[%(2000,4000,440,480)]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Ecallmgr-Node=ecall...@mcc-kazoo02.sjz.sankuai.info)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Ecallmgr-Node]=[ecallm...@mcc-kazoo02.sjz.sankuai.info]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Auto-Call-Rule-ID=8e8fd6de-8726-4881-b92f-bede1f712596)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Auto-Call-Rule-ID]=[8e8fd6de-8726-4881-b92f-bede1f712596]1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Authorizing-Type=device)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Authorizing-Type]=[device]1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Authorizing-ID=8fb9289d0313d57d04ad4112564b9312)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Authorizing-ID]=[8fb9289d0313d57d04ad4112564b9312]1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Retain-CID=true)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Retain-CID]=[true]1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Account-ID=07a049bfcfd1685c42a8762a4897626f)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Account-ID]=[07a049bfcfd1685c42a8762a4897626f]1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Realm=sip.meituan.com)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Realm]=[sip.meituan.com]1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Fetch-ID=50959064daeb4618e061642323748a91)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Fetch-ID]=[50959064daeb4618e061642323748a91]1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b park()2016-06-29 18:34:50.438152 [INFO] kazoo_node.c:625 exec: uuid_setvar_multi(1ba06aa6-3de5-11e6-87d8-fbf5318198dc ecallmgr_Application-Name=callflow;ecallmgr_Application-Node=whistl...@mcc-kazoo01.sjz.sankuai.info;ecallmgr_Ecallmgr-Node=ecal...@mcc-kazoo02.sjz.sankuai.info;ecallmgr_Auto-Call-Rule-ID=8e8fd6de-8726-4881-b92f-bede1f712596;ecallmgr_Authorizing-Type=device;ecallmgr_Authorizing-ID=8fb9289d0313d57d04ad4112564b9312;ecallmgr_Retain-CID=true;ecallmgr_Account-ID=07a049bfcfd1685c42a8762a4897626f;ecallmgr_Realm=sip.meituan.com;ecallmgr_Fetch-ID=50959064daeb4618e061642323748a91)
2016-06-29 18:34:50.478183 [INFO] kazoo_node.c:625 exec: uuid_setvar_multi(1ba06aa6-3de5-11e6-87d8-fbf5318198dc effective_caller_id_name=Device QuickCall;effective_caller_id_number=11223344;ecallmgr_Application-Name=callflow;ecallmgr_Application-Node=whistle_...@mcc-kazoo01.sjz.sankuai.info;ecallmgr_Ecallmgr-Node=ecal...@mcc-kazoo02.sjz.sankuai.info;ecallmgr_Auto-Call-Rule-ID=8e8fd6de-8726-4881-b92f-bede1f712596;ecallmgr_Authorizing-Type=device;ecallmgr_Authorizing-ID=8fb9289d0313d57d04ad4112564b9312;ecallmgr_Retain-CID=true;ecallmgr_Account-ID=07a049bfcfd1685c42a8762a4897626f;ecallmgr_Realm=sip.meituan.com;ecallmgr_Fetch-ID=50959064daeb4618e061642323748a91)
2016-06-29 18:34:51.078137 [INFO] kazoo_node.c:625 exec: uuid_setvar(1ba06aa6-3de5-11e6-87d8-fbf5318198dc ecallmgr_Recording-Url http://mcc-test/recording/call_recording_1ba06aa6-3de5-11e6-87d8-fbf5318198dc.mp3)
2016-06-29 18:34:51.158188 [INFO] kazoo_node.c:625 exec: uuid_setvar_multi(1ba06aa6-3de5-11e6-87d8-fbf5318198dc effective_caller_id_name=Device QuickCall;effective_caller_id_number=11223344;ecallmgr_Application-Name=callflow;ecallmgr_Application-Node=whistle_...@mcc-kazoo01.sjz.sankuai.info;ecallmgr_Ecallmgr-Node=ecal...@mcc-kazoo02.sjz.sankuai.info;ecallmgr_Auto-Call-Rule-ID=8e8fd6de-8726-4881-b92f-bede1f712596;ecallmgr_Authorizing-Type=device;ecallmgr_Authorizing-ID=8fb9289d0313d57d04ad4112564b9312;ecallmgr_Retain-CID=true;ecallmgr_Account-ID=07a049bfcfd1685c42a8762a4897626f;ecallmgr_Realm=sip.meituan.com;ecallmgr_Fetch-ID=50959064daeb4618e061642323748a91)
2016-06-29 18:34:51.158188 [NOTICE] kazoo_node.c:302 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|building xferext extension: set ignore_display_updates=true2016-06-29 18:34:51.158188 [NOTICE] kazoo_node.c:302 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|building xferext extension: set continue_on_fail=true2016-06-29 18:34:51.158188 [NOTICE] kazoo_node.c:302 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|building xferext extension: export sip_redirect_context=context_22016-06-29 18:34:51.158188 [NOTICE] kazoo_node.c:302 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|building xferext extension: set hangup_after_bridge=true2016-06-29 18:34:51.158188 [NOTICE] kazoo_node.c:302 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|building xferext extension: export ecallmgr_Inception=${ecallmgr_Inception}
2016-06-29 18:34:51.158188 [NOTICE] kazoo_node.c:302 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|building xferext extension: bridge {ignore_display_updates='true',ecallmgr_Account-ID='07a049bfcfd1685c42a8762a4897626f',ecallmgr_Reseller-ID='07a049bfcfd1685c42a8762a4897626f',effective_caller_id_number='11223344',effective_caller_id_name='Device QuickCall',origination_caller_id_number='11223344',origination_caller_id_name='Device QuickCall',ignore_early_media='false',local_var_clobber='true'}[sip_auth_password='mt@009009',sip_auth_username='admin',sip_from_uri='sip:1122...@x.x.6.205',ecallmgr_Global-Resource='false',ecallmgr_Resource-ID='9417ff08859d9c4d65fcc02ab86b2fd0',ecallmgr_E164-Destination='+xxxxxxxxxxx',ecallmgr_Original-Number='0xxxxxxxxxxx',absolute_codec_string='^^:PCMA',leg_progress_timeout='30',effective_callee_id_number='0xxxxxxxxxxx',effective_callee_id_name='0xxxxxxxxxxx']sofia/sipinterface_1/0xxxxxxxxxxx@x.x.6.205|[sip_auth_password='mt@009009',sip_auth_username='admin',sip_from_uri='sip:1122...@x.x.0.75',ecallmgr_Global-Resource='false',ecallmgr_Resource-ID='dc57fba99f6d7d73ebdba7978aceb58f',ecallmgr_E164-Destination='+xxxxxxxxxxx',ecallmgr_Original-Number='0xxxxxxxxxxx',absolute_codec_string='^^:PCMA',leg_progress_timeout='30',effective_callee_id_number='0xxxxxxxxxxx',effective_callee_id_name='0xxxxxxxxxxx']sofia/sipinterface_1/0xxxxxxxxxxx@x.x.0.75
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b bridge({ignore_display_updates=true,ecallmgr_Account-ID=07a049bfcfd1685c42a8762a4897626f,ecallmgr_Reseller-ID=07a049bfcfd1685c42a8762a4897626f,effective_caller_id_number=11223344,effective_caller_id_name=Device QuickCall,origination_caller_id_number=11223344,origination_caller_id_name=Device QuickCall,ignore_early_media=false,local_var_clobber=true}[sip_auth_password=mt@009009,sip_auth_username=admin,sip_from_uri=sip:1122...@x.x.6.205,ecallmgr_Global-Resource=false,ecallmgr_Resource-ID=9417ff08859d9c4d65fcc02ab86b2fd0,ecallmgr_E164-Destination=+xxxxxxxxxxx,ecallmgr_Original-Number=0xxxxxxxxxxx,absolute_codec_string=^^:PCMA,leg_progress_timeout=30,effective_callee_id_number=0xxxxxxxxxxx,effective_callee_id_name=0xxxxxxxxxxx]sofia/sipinterface_1/0xxxxxxxxxxx@x.x.6.205|[sip_auth_password=mt@009009,sip_auth_username=admin,sip_from_uri=sip:1122...@x.x.0.75,ecallmgr_Global-Resource=false,ecallmgr_Resource-ID=dc57fba99f6d7d73ebdba7978aceb58f,ecallmgr_E164-Destination=+xxxxxxxxxxx,ecallmgr_Original-Number=0xxxxxxxxxxx,absolute_codec_string=^^:PCMA,leg_progress_timeout=30,effective_callee_id_number=0xxxxxxxxxxx,effective_callee_id_name=0xxxxxxxxxxx]sofia/sipinterface_1/0xxxxxxxxxxx@x.x.0.75)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_channel.c:1201 loopback/+xxxxxxxxxxx-b EXPORTING[export_vars] [ecallmgr_Bridge-ID]=[1ba06aa6-3de5-11e6-87d8-fbf5318198dc] to event
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_channel.c:1201 loopback/+xxxxxxxxxxx-b EXPORTING[export_vars] [ecallmgr_Ecallmgr-Node]=[ecallm...@mcc-kazoo02.sjz.sankuai.info] to event
...
Sergey Safarov
Feb 15, 2017, 11:17:36 PM2/15/17
to 2600hz-dev
Think some think wrong in DNS names. Check forward and backward server name resolving.
среда, 15 февраля 2017 г., 18:30:58 UTC+3 пользователь emnvn написал:
Also look output of command "tcpdump -ni any port 53" when you make call.
среда, 15 февраля 2017 г., 18:30:58 UTC+3 пользователь emnvn написал:
emnvn
Feb 19, 2017, 11:07:10 AM2/19/17
to 2600hz-dev
I think no problem with DNS because Ecallmgr and FreeSWITCH communicate by Local IP (127.0.0.1). I have log time process for each step since Ecallmgr receive dialplan request from FreeSWITCH until Ecallmgr send dialplan to FreeSWITCH.
When I make only one Calls I saw that total time is about 100-200 milisecond but when I make 20 calls in one second, I saw about 7-8 calls have total processing time > 3 seconds. I have some stastics:
(1) wh_amqp_worker use gen_listener send message and handle_call, before amqp_util send message to rabbitmq (ecallmgr_fs_routesearch_for_route -> amqp_util:basic_publish): 2324.169 milisecond
(2) amqp_util send message to rabbitmq, gen_listener receive message (amqp_util:basic_public-> gen_listener:handle_info) : 1408.14 milisecond
(3) cf_route_req found a diaplan, it send response to rabbitmq, Ecallmgr receive this message from RabbitMQ (cf_route_req: send_route_response -> reply_affirmative): total time: 2549.679 milisecond
(4) Ecallmgr send dialplan to FreeSWICH and receive response: total time 2015. milisecond
In statistic (1), I only saw logic code (create message, valid, send to gen_server...) I don't see that it query db or do heavy task but processing time may be greate than 2 seconds)
In statistic (2), (3) It may the bottleneck in rabbitmq but I can't now sure that. In normal case It only take 8 milisecond to receive message.
In statistic (4) It very strange because Ecallmgr and FreeSWITch on the same server
I use Kazoo version 3.22-59. In testing time CPU is only use about 20%, RAM is free more than 5 GB.
When I make only one Calls I saw that total time is about 100-200 milisecond but when I make 20 calls in one second, I saw about 7-8 calls have total processing time > 3 seconds. I have some stastics:
(1) wh_amqp_worker use gen_listener send message and handle_call, before amqp_util send message to rabbitmq (ecallmgr_fs_routesearch_for_route -> amqp_util:basic_publish): 2324.169 milisecond
(2) amqp_util send message to rabbitmq, gen_listener receive message (amqp_util:basic_public-> gen_listener:handle_info) : 1408.14 milisecond
(3) cf_route_req found a diaplan, it send response to rabbitmq, Ecallmgr receive this message from RabbitMQ (cf_route_req: send_route_response -> reply_affirmative): total time: 2549.679 milisecond
(4) Ecallmgr send dialplan to FreeSWICH and receive response: total time 2015. milisecond
In statistic (1), I only saw logic code (create message, valid, send to gen_server...) I don't see that it query db or do heavy task but processing time may be greate than 2 seconds)
In statistic (2), (3) It may the bottleneck in rabbitmq but I can't now sure that. In normal case It only take 8 milisecond to receive message.
In statistic (4) It very strange because Ecallmgr and FreeSWITch on the same server
I use Kazoo version 3.22-59. In testing time CPU is only use about 20%, RAM is free more than 5 GB.
Matt - nurango (VCCS)
Feb 27, 2017, 5:07:33 PM2/27/17
to 2600hz-dev
Hey I am wondering what version of kazoo you guys are using? I just upgraded to 4.0-32 and broke Quickcall;
kz_amqp_worker:716 (<0.339.0>) return: {[{<<"code">>,312},{<<"message">>,<<"NO_ROUTE">>},{<<"exchange">>,<<"callmgr">>},{<<"routing_key">>,<<"originate.resource.req">>}]}
Wondering if we're all on the same version. Thanks.
On Thursday, June 30, 2016 at 11:32:52 AM UTC-4, Mountain King wrote:
when using clicktocall function, will bring about NO_ROUTE_DESTINATION error, the one is calling to callee for example 12345678901, and the other is callee was called,but when transfer to caller can't find dialplan;
=================================callee can't called
2016-06-29 18:31:36.818449 [INFO] kazoo_node.c:625 exec: originate({origination_callee_id_number='+12345678901',origination_callee_id_name='12345678901',origination_caller_id_number='11223344',origination_caller_id_name='Device QuickCall',ignore_early_media='true',continue_on_fail='true',call_timeout=30,originate_timeout=30,ecallmgr_Fetch-ID='72b1d2999b5901b6f67779b755e82d16',ecallmgr_Ecallmgr-Node='ecall...@mcc-kazoo02.sjz.sankuai.info',ecallmgr_Auto-Call-Rule-ID='26f66860-cc02-47da-8a63-b9a0d5fde347',ecallmgr_Authorizing-Type='device',inherit_codec='false',ecallmgr_Authorizing-ID='31e479fb292c414fe62e928edbabb619',ecallmgr_Retain-CID='true',sip_auto_answer='true',ecallmgr_Account-ID='07a049bfcfd1685c42a8762a4897626f',local_var_clobber='true'}[ecallmgr_Auto-Call-Rule-ID='26f66860-cc02-47da-8a63-b9a0d5fde347',ecallmgr_Authorizing-Type='device',inherit_codec='false',ecallmgr_Authorizing-ID='31e479fb292c414fe62e928edbabb619',ecallmgr_Retain-CID='true',sip_auto_answer='true',ecallmgr_Account-ID='07a049bfcfd1685c42a8762a4897626f',ecallmgr_Realm='sip.meituan.com',origination_uuid=4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall]loopback/+12345678901/context_2 'm:^:unset:origination_callee_id_number^unset:origination_callee_id_name^unset:origination_caller_id_number^unset:origination_caller_id_name^unset:ignore_early_media^unset:continue_on_fail^unset:call_timeout^unset:originate_timeout^unset:ecallmgr_Auto-Call-Rule-ID^unset:inherit_codec^unset:sip_auto_answer^unset:ignore_early_media^unset:group_confirm_key^unset:group_confirm_cancel_timeout^unset:group_confirm_file^unset:sip_h_Call-Info^unset:sip_h_Alert-Info^unset:alert_info^unset:sip_invite_params^set:sip_auto_answer=false^transfer:+11223344 XML context_2' inline)
2016-06-29 18:34:50.158155 [INFO] kazoo_node.c:625 exec: originate({origination_callee_id_number='+xxxxxxxxxxx',origination_callee_id_name='xxxxxxxxxxx',origination_caller_id_number='11223344',origination_caller_id_name='Device QuickCall',ignore_early_media='true',continue_on_fail='true',call_timeout=30,originate_timeout=30,ecallmgr_Fetch-ID='50959064daeb4618e061642323748a91',ecallmgr_Ecallmgr-Node='ecall...@mcc-kazoo02.sjz.sankuai.info',ecallmgr_Auto-Call-Rule-ID='8e8fd6de-8726-4881-b92f-bede1f712596',ecallmgr_Authorizing-Type='device',inherit_codec='false',ecallmgr_Authorizing-ID='8fb9289d0313d57d04ad4112564b9312',ecallmgr_Retain-CID='true',sip_auto_answer='true',ecallmgr_Account-ID='07a049bfcfd1685c42a8762a4897626f',local_var_clobber='true'}[ecallmgr_Auto-Call-Rule-ID='8e8fd6de-8726-4881-b92f-bede1f712596',ecallmgr_Authorizing-Type='device',inherit_codec='false',ecallmgr_Authorizing-ID='8fb9289d0313d57d04ad4112564b9312',ecallmgr_Retain-CID='true',sip_auto_answer='true',ecallmgr_Account-ID='07a049bfcfd1685c42a8762a4897626f',ecallmgr_Realm='sip.meituan.com',origination_uuid=f62bb835a781fa4ec508c620a1beee37e535-autocall]loopback/+xxxxxxxxxxx/context_2 'm:^:unset:origination_callee_id_number^unset:origination_callee_id_name^unset:origination_caller_id_number^unset:origination_caller_id_name^unset:ignore_early_media^unset:continue_on_fail^unset:call_timeout^unset:originate_timeout^unset:ecallmgr_Auto-Call-Rule-ID^unset:inherit_codec^unset:sip_auto_answer^unset:ignore_early_media^unset:group_confirm_key^unset:group_confirm_cancel_timeout^unset:group_confirm_file^unset:sip_h_Call-Info^unset:sip_h_Alert-Info^unset:alert_info^unset:sip_invite_params^set:sip_auto_answer=false^transfer:+11223344 XML context_2' inline)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Ecallmgr-Node=ecall...@mcc-kazoo02.sjz.sankuai.info)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Auto-Call-Rule-ID=8e8fd6de-8726-4881-b92f-bede1f712596)1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Authorizing-Type=device)1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Authorizing-ID=8fb9289d0313d57d04ad4112564b9312)1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Retain-CID=true)1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Account-ID=07a049bfcfd1685c42a8762a4897626f)1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Realm=sip.meituan.com)1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Fetch-ID=50959064daeb4618e061642323748a91)1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action park()1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_core_state_machine.c:216 (loopback/+xxxxxxxxxxx-b) State Change CS_ROUTING -> CS_EXECUTE1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_core_session.c:1388 Send signal loopback/+xxxxxxxxxxx-b [BREAK]1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_loopback.c:598 loopback/+xxxxxxxxxxx-b CHANNEL KILL1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_core_state_machine.c:528 (loopback/+xxxxxxxxxxx-b) State ROUTING going to sleep1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_core_state_machine.c:472 (loopback/+xxxxxxxxxxx-b) Running State Change CS_EXECUTE1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_core_state_machine.c:535 (loopback/+xxxxxxxxxxx-b) State EXECUTE1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_loopback.c:433 loopback/+xxxxxxxxxxx-b CHANNEL EXECUTE1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_core_state_machine.c:258 loopback/+xxxxxxxxxxx-b Standard EXECUTE1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b export(ecallmgr_Bridge-ID=1ba06aa6-3de5-11e6-87d8-fbf5318198dc)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_channel.c:1247 EXPORT (export_vars) [ecallmgr_Bridge-ID]=[1ba06aa6-3de5-11e6-87d8-fbf5318198dc]1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b log(NOTICE log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|ecal...@mcc-kazoo02.sjz.sankuai.info won call control)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [NOTICE] mod_dptools.c:1650 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|ecal...@mcc-kazoo02.sjz.sankuai.info won call control1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b export(ecallmgr_Ecallmgr-Node=ecal...@mcc-kazoo02.sjz.sankuai.info)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_channel.c:1247 EXPORT (export_vars) [ecallmgr_Ecallmgr-Node]=[ecallm...@mcc-kazoo02.sjz.sankuai.info]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ringback=%(2000,4000,440,480))1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ringback]=[%(2000,4000,440,480)]1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(transfer_ringback=%(2000,4000,440,480))1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [transfer_ringback]=[%(2000,4000,440,480)]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Ecallmgr-Node=ecall...@mcc-kazoo02.sjz.sankuai.info)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Ecallmgr-Node]=[ecallm...@mcc-kazoo02.sjz.sankuai.info]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Auto-Call-Rule-ID=8e8fd6de-8726-4881-b92f-bede1f712596)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Auto-Call-Rule-ID]=[8e8fd6de-8726-4881-b92f-bede1f712596]1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Authorizing-Type=device)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Authorizing-Type]=[device]1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Authorizing-ID=8fb9289d0313d57d04ad4112564b9312)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Authorizing-ID]=[8fb9289d0313d57d04ad4112564b9312]1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Retain-CID=true)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Retain-CID]=[true]1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Account-ID=07a049bfcfd1685c42a8762a4897626f)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Account-ID]=[07a049bfcfd1685c42a8762a4897626f]1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Realm=sip.meituan.com)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Realm]=[sip.meituan.com]1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Fetch-ID=50959064daeb4618e061642323748a91)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Fetch-ID]=[50959064daeb4618e061642323748a91]1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b park()2016-06-29 18:34:50.438152 [INFO] kazoo_node.c:625 exec: uuid_setvar_multi(1ba06aa6-3de5-11e6-87d8-fbf5318198dc ecallmgr_Application-Name=callflow;ecallmgr_Application-Node=whistl...@mcc-kazoo01.sjz.sankuai.info;ecallmgr_Ecallmgr-Node=ecal...@mcc-kazoo02.sjz.sankuai.info;ecallmgr_Auto-Call-Rule-ID=8e8fd6de-8726-4881-b92f-bede1f712596;ecallmgr_Authorizing-Type=device;ecallmgr_Authorizing-ID=8fb9289d0313d57d04ad4112564b9312;ecallmgr_Retain-CID=true;ecallmgr_Account-ID=07a049bfcfd1685c42a8762a4897626f;ecallmgr_Realm=sip.meituan.com;ecallmgr_Fetch-ID=50959064daeb4618e061642323748a91)
2016-06-29 18:34:50.478183 [INFO] kazoo_node.c:625 exec: uuid_setvar_multi(1ba06aa6-3de5-11e6-87d8-fbf5318198dc effective_caller_id_name=Device QuickCall;effective_caller_id_number=11223344;ecallmgr_Application-Name=callflow;ecallmgr_Application-Node=whistle_...@mcc-kazoo01.sjz.sankuai.info;ecallmgr_Ecallmgr-Node=ecal...@mcc-kazoo02.sjz.sankuai.info;ecallmgr_Auto-Call-Rule-ID=8e8fd6de-8726-4881-b92f-bede1f712596;ecallmgr_Authorizing-Type=device;ecallmgr_Authorizing-ID=8fb9289d0313d57d04ad4112564b9312;ecallmgr_Retain-CID=true;ecallmgr_Account-ID=07a049bfcfd1685c42a8762a4897626f;ecallmgr_Realm=sip.meituan.com;ecallmgr_Fetch-ID=50959064daeb4618e061642323748a91)
2016-06-29 18:34:51.078137 [INFO] kazoo_node.c:625 exec: uuid_setvar(1ba06aa6-3de5-11e6-87d8-fbf5318198dc ecallmgr_Recording-Url http://mcc-test/recording/call_recording_1ba06aa6-3de5-11e6-87d8-fbf5318198dc.mp3)
2016-06-29 18:34:51.158188 [INFO] kazoo_node.c:625 exec: uuid_setvar_multi(1ba06aa6-3de5-11e6-87d8-fbf5318198dc effective_caller_id_name=Device QuickCall;effective_caller_id_number=11223344;ecallmgr_Application-Name=callflow;ecallmgr_Application-Node=whistle_...@mcc-kazoo01.sjz.sankuai.info;ecallmgr_Ecallmgr-Node=ecal...@mcc-kazoo02.sjz.sankuai.info;ecallmgr_Auto-Call-Rule-ID=8e8fd6de-8726-4881-b92f-bede1f712596;ecallmgr_Authorizing-Type=device;ecallmgr_Authorizing-ID=8fb9289d0313d57d04ad4112564b9312;ecallmgr_Retain-CID=true;ecallmgr_Account-ID=07a049bfcfd1685c42a8762a4897626f;ecallmgr_Realm=sip.meituan.com;ecallmgr_Fetch-ID=50959064daeb4618e061642323748a91)
2016-06-29 18:34:51.158188 [NOTICE] kazoo_node.c:302 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|building xferext extension: set ignore_display_updates=true2016-06-29 18:34:51.158188 [NOTICE] kazoo_node.c:302 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|building xferext extension: set continue_on_fail=true2016-06-29 18:34:51.158188 [NOTICE] kazoo_node.c:302 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|building xferext extension: export sip_redirect_context=context_22016-06-29 18:34:51.158188 [NOTICE] kazoo_node.c:302 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|building xferext extension: set hangup_after_bridge=true2016-06-29 18:34:51.158188 [NOTICE] kazoo_node.c:302 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|building xferext extension: export ecallmgr_Inception=${ecallmgr_Inception}
2016-06-29 18:34:51.158188 [NOTICE] kazoo_node.c:302 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|building xferext extension: bridge {ignore_display_updates='true',ecallmgr_Account-ID='07a049bfcfd1685c42a8762a4897626f',ecallmgr_Reseller-ID='07a049bfcfd1685c42a8762a4897626f',effective_caller_id_number='11223344',effective_caller_id_name='Device QuickCall',origination_caller_id_number='11223344',origination_caller_id_name='Device QuickCall',ignore_early_media='false',local_var_clobber='true'}[sip_auth_password='mt@009009',sip_auth_username='admin',sip_from_uri='sip:1122...@x.x.6.205',ecallmgr_Global-Resource='false',ecallmgr_Resource-ID='9417ff08859d9c4d65fcc02ab86b2fd0',ecallmgr_E164-Destination='+xxxxxxxxxxx',ecallmgr_Original-Number='0xxxxxxxxxxx',absolute_codec_string='^^:PCMA',leg_progress_timeout='30',effective_callee_id_number='0xxxxxxxxxxx',effective_callee_id_name='0xxxxxxxxxxx']sofia/sipinterface_1/0xxxxxxxxxxx@x.x.6.205|[sip_auth_password='mt@009009',sip_auth_username='admin',sip_from_uri='sip:1122...@x.x.0.75',ecallmgr_Global-Resource='false',ecallmgr_Resource-ID='dc57fba99f6d7d73ebdba7978aceb58f',ecallmgr_E164-Destination='+xxxxxxxxxxx',ecallmgr_Original-Number='0xxxxxxxxxxx',absolute_codec_string='^^:PCMA',leg_progress_timeout='30',effective_callee_id_number='0xxxxxxxxxxx',effective_callee_id_name='0xxxxxxxxxxx']sofia/sipinterface_1/0xxxxxxxxxxx@x.x.0.75
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b bridge({ignore_display_updates=true,ecallmgr_Account-ID=07a049bfcfd1685c42a8762a4897626f,ecallmgr_Reseller-ID=07a049bfcfd1685c42a8762a4897626f,effective_caller_id_number=11223344,effective_caller_id_name=Device QuickCall,origination_caller_id_number=11223344,origination_caller_id_name=Device QuickCall,ignore_early_media=false,local_var_clobber=true}[sip_auth_password=mt@009009,sip_auth_username=admin,sip_from_uri=sip:1122...@x.x.6.205,ecallmgr_Global-Resource=false,ecallmgr_Resource-ID=9417ff08859d9c4d65fcc02ab86b2fd0,ecallmgr_E164-Destination=+xxxxxxxxxxx,ecallmgr_Original-Number=0xxxxxxxxxxx,absolute_codec_string=^^:PCMA,leg_progress_timeout=30,effective_callee_id_number=0xxxxxxxxxxx,effective_callee_id_name=0xxxxxxxxxxx]sofia/sipinterface_1/0xxxxxxxxxxx@x.x.6.205|[sip_auth_password=mt@009009,sip_auth_username=admin,sip_from_uri=sip:1122...@x.x.0.75,ecallmgr_Global-Resource=false,ecallmgr_Resource-ID=dc57fba99f6d7d73ebdba7978aceb58f,ecallmgr_E164-Destination=+xxxxxxxxxxx,ecallmgr_Original-Number=0xxxxxxxxxxx,absolute_codec_string=^^:PCMA,leg_progress_timeout=30,effective_callee_id_number=0xxxxxxxxxxx,effective_callee_id_name=0xxxxxxxxxxx]sofia/sipinterface_1/0xxxxxxxxxxx@x.x.0.75)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_channel.c:1201 loopback/+xxxxxxxxxxx-b EXPORTING[export_vars] [ecallmgr_Bridge-ID]=[1ba06aa6-3de5-11e6-87d8-fbf5318198dc] to event
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_channel.c:1201 loopback/+xxxxxxxxxxx-b EXPORTING[export_vars] [ecallmgr_Ecallmgr-Node]=[ecallm...@mcc-kazoo02.sjz.sankuai.info] to event
...
Matt - nurango (VCCS)
Feb 27, 2017, 11:41:19 PM2/27/17
to 2600hz-dev
For me it was a reboot of ecallmgr oddly enough. I noticed that on node C2C was going through App= Crossbar and it should show App=ecallmgr.
thx
On Thursday, June 30, 2016 at 11:32:52 AM UTC-4, Mountain King wrote:
when using clicktocall function, will bring about NO_ROUTE_DESTINATION error, the one is calling to callee for example 12345678901, and the other is callee was called,but when transfer to caller can't find dialplan;
=================================callee can't called
2016-06-29 18:31:36.818449 [INFO] kazoo_node.c:625 exec: originate({origination_callee_id_number='+12345678901',origination_callee_id_name='12345678901',origination_caller_id_number='11223344',origination_caller_id_name='Device QuickCall',ignore_early_media='true',continue_on_fail='true',call_timeout=30,originate_timeout=30,ecallmgr_Fetch-ID='72b1d2999b5901b6f67779b755e82d16',ecallmgr_Ecallmgr-Node='ecall...@mcc-kazoo02.sjz.sankuai.info',ecallmgr_Auto-Call-Rule-ID='26f66860-cc02-47da-8a63-b9a0d5fde347',ecallmgr_Authorizing-Type='device',inherit_codec='false',ecallmgr_Authorizing-ID='31e479fb292c414fe62e928edbabb619',ecallmgr_Retain-CID='true',sip_auto_answer='true',ecallmgr_Account-ID='07a049bfcfd1685c42a8762a4897626f',local_var_clobber='true'}[ecallmgr_Auto-Call-Rule-ID='26f66860-cc02-47da-8a63-b9a0d5fde347',ecallmgr_Authorizing-Type='device',inherit_codec='false',ecallmgr_Authorizing-ID='31e479fb292c414fe62e928edbabb619',ecallmgr_Retain-CID='true',sip_auto_answer='true',ecallmgr_Account-ID='07a049bfcfd1685c42a8762a4897626f',ecallmgr_Realm='sip.meituan.com',origination_uuid=4a9531c47b8455d2b50cd23fd8ef5dd3836c-autocall]loopback/+12345678901/context_2 'm:^:unset:origination_callee_id_number^unset:origination_callee_id_name^unset:origination_caller_id_number^unset:origination_caller_id_name^unset:ignore_early_media^unset:continue_on_fail^unset:call_timeout^unset:originate_timeout^unset:ecallmgr_Auto-Call-Rule-ID^unset:inherit_codec^unset:sip_auto_answer^unset:ignore_early_media^unset:group_confirm_key^unset:group_confirm_cancel_timeout^unset:group_confirm_file^unset:sip_h_Call-Info^unset:sip_h_Alert-Info^unset:alert_info^unset:sip_invite_params^set:sip_auto_answer=false^transfer:+11223344 XML context_2' inline)
2016-06-29 18:34:50.158155 [INFO] kazoo_node.c:625 exec: originate({origination_callee_id_number='+xxxxxxxxxxx',origination_callee_id_name='xxxxxxxxxxx',origination_caller_id_number='11223344',origination_caller_id_name='Device QuickCall',ignore_early_media='true',continue_on_fail='true',call_timeout=30,originate_timeout=30,ecallmgr_Fetch-ID='50959064daeb4618e061642323748a91',ecallmgr_Ecallmgr-Node='ecall...@mcc-kazoo02.sjz.sankuai.info',ecallmgr_Auto-Call-Rule-ID='8e8fd6de-8726-4881-b92f-bede1f712596',ecallmgr_Authorizing-Type='device',inherit_codec='false',ecallmgr_Authorizing-ID='8fb9289d0313d57d04ad4112564b9312',ecallmgr_Retain-CID='true',sip_auto_answer='true',ecallmgr_Account-ID='07a049bfcfd1685c42a8762a4897626f',local_var_clobber='true'}[ecallmgr_Auto-Call-Rule-ID='8e8fd6de-8726-4881-b92f-bede1f712596',ecallmgr_Authorizing-Type='device',inherit_codec='false',ecallmgr_Authorizing-ID='8fb9289d0313d57d04ad4112564b9312',ecallmgr_Retain-CID='true',sip_auto_answer='true',ecallmgr_Account-ID='07a049bfcfd1685c42a8762a4897626f',ecallmgr_Realm='sip.meituan.com',origination_uuid=f62bb835a781fa4ec508c620a1beee37e535-autocall]loopback/+xxxxxxxxxxx/context_2 'm:^:unset:origination_callee_id_number^unset:origination_callee_id_name^unset:origination_caller_id_number^unset:origination_caller_id_name^unset:ignore_early_media^unset:continue_on_fail^unset:call_timeout^unset:originate_timeout^unset:ecallmgr_Auto-Call-Rule-ID^unset:inherit_codec^unset:sip_auto_answer^unset:ignore_early_media^unset:group_confirm_key^unset:group_confirm_cancel_timeout^unset:group_confirm_file^unset:sip_h_Call-Info^unset:sip_h_Alert-Info^unset:alert_info^unset:sip_invite_params^set:sip_auto_answer=false^transfer:+11223344 XML context_2' inline)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Ecallmgr-Node=ecall...@mcc-kazoo02.sjz.sankuai.info)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Auto-Call-Rule-ID=8e8fd6de-8726-4881-b92f-bede1f712596)1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Authorizing-Type=device)1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Authorizing-ID=8fb9289d0313d57d04ad4112564b9312)1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Retain-CID=true)1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Account-ID=07a049bfcfd1685c42a8762a4897626f)1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Realm=sip.meituan.com)1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action set(ecallmgr_Fetch-ID=50959064daeb4618e061642323748a91)1ba06aa6-3de5-11e6-87d8-fbf5318198dc Dialplan: loopback/+xxxxxxxxxxx-b Action park()1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_core_state_machine.c:216 (loopback/+xxxxxxxxxxx-b) State Change CS_ROUTING -> CS_EXECUTE1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_core_session.c:1388 Send signal loopback/+xxxxxxxxxxx-b [BREAK]1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_loopback.c:598 loopback/+xxxxxxxxxxx-b CHANNEL KILL1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_core_state_machine.c:528 (loopback/+xxxxxxxxxxx-b) State ROUTING going to sleep1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_core_state_machine.c:472 (loopback/+xxxxxxxxxxx-b) Running State Change CS_EXECUTE1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_core_state_machine.c:535 (loopback/+xxxxxxxxxxx-b) State EXECUTE1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_loopback.c:433 loopback/+xxxxxxxxxxx-b CHANNEL EXECUTE1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_core_state_machine.c:258 loopback/+xxxxxxxxxxx-b Standard EXECUTE1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b export(ecallmgr_Bridge-ID=1ba06aa6-3de5-11e6-87d8-fbf5318198dc)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_channel.c:1247 EXPORT (export_vars) [ecallmgr_Bridge-ID]=[1ba06aa6-3de5-11e6-87d8-fbf5318198dc]1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b log(NOTICE log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|ecal...@mcc-kazoo02.sjz.sankuai.info won call control)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [NOTICE] mod_dptools.c:1650 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|ecal...@mcc-kazoo02.sjz.sankuai.info won call control1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b export(ecallmgr_Ecallmgr-Node=ecal...@mcc-kazoo02.sjz.sankuai.info)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] switch_channel.c:1247 EXPORT (export_vars) [ecallmgr_Ecallmgr-Node]=[ecallm...@mcc-kazoo02.sjz.sankuai.info]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ringback=%(2000,4000,440,480))1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ringback]=[%(2000,4000,440,480)]1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(transfer_ringback=%(2000,4000,440,480))1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [transfer_ringback]=[%(2000,4000,440,480)]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Ecallmgr-Node=ecall...@mcc-kazoo02.sjz.sankuai.info)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Ecallmgr-Node]=[ecallm...@mcc-kazoo02.sjz.sankuai.info]
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Auto-Call-Rule-ID=8e8fd6de-8726-4881-b92f-bede1f712596)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Auto-Call-Rule-ID]=[8e8fd6de-8726-4881-b92f-bede1f712596]1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Authorizing-Type=device)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Authorizing-Type]=[device]1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Authorizing-ID=8fb9289d0313d57d04ad4112564b9312)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Authorizing-ID]=[8fb9289d0313d57d04ad4112564b9312]1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Retain-CID=true)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Retain-CID]=[true]1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Account-ID=07a049bfcfd1685c42a8762a4897626f)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Account-ID]=[07a049bfcfd1685c42a8762a4897626f]1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Realm=sip.meituan.com)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Realm]=[sip.meituan.com]1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b set(ecallmgr_Fetch-ID=50959064daeb4618e061642323748a91)1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:50.398201 [DEBUG] mod_dptools.c:1435 loopback/+xxxxxxxxxxx-b SET [ecallmgr_Fetch-ID]=[50959064daeb4618e061642323748a91]1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b park()2016-06-29 18:34:50.438152 [INFO] kazoo_node.c:625 exec: uuid_setvar_multi(1ba06aa6-3de5-11e6-87d8-fbf5318198dc ecallmgr_Application-Name=callflow;ecallmgr_Application-Node=whistl...@mcc-kazoo01.sjz.sankuai.info;ecallmgr_Ecallmgr-Node=ecal...@mcc-kazoo02.sjz.sankuai.info;ecallmgr_Auto-Call-Rule-ID=8e8fd6de-8726-4881-b92f-bede1f712596;ecallmgr_Authorizing-Type=device;ecallmgr_Authorizing-ID=8fb9289d0313d57d04ad4112564b9312;ecallmgr_Retain-CID=true;ecallmgr_Account-ID=07a049bfcfd1685c42a8762a4897626f;ecallmgr_Realm=sip.meituan.com;ecallmgr_Fetch-ID=50959064daeb4618e061642323748a91)
2016-06-29 18:34:50.478183 [INFO] kazoo_node.c:625 exec: uuid_setvar_multi(1ba06aa6-3de5-11e6-87d8-fbf5318198dc effective_caller_id_name=Device QuickCall;effective_caller_id_number=11223344;ecallmgr_Application-Name=callflow;ecallmgr_Application-Node=whistle_...@mcc-kazoo01.sjz.sankuai.info;ecallmgr_Ecallmgr-Node=ecal...@mcc-kazoo02.sjz.sankuai.info;ecallmgr_Auto-Call-Rule-ID=8e8fd6de-8726-4881-b92f-bede1f712596;ecallmgr_Authorizing-Type=device;ecallmgr_Authorizing-ID=8fb9289d0313d57d04ad4112564b9312;ecallmgr_Retain-CID=true;ecallmgr_Account-ID=07a049bfcfd1685c42a8762a4897626f;ecallmgr_Realm=sip.meituan.com;ecallmgr_Fetch-ID=50959064daeb4618e061642323748a91)
2016-06-29 18:34:51.078137 [INFO] kazoo_node.c:625 exec: uuid_setvar(1ba06aa6-3de5-11e6-87d8-fbf5318198dc ecallmgr_Recording-Url http://mcc-test/recording/call_recording_1ba06aa6-3de5-11e6-87d8-fbf5318198dc.mp3)
2016-06-29 18:34:51.158188 [INFO] kazoo_node.c:625 exec: uuid_setvar_multi(1ba06aa6-3de5-11e6-87d8-fbf5318198dc effective_caller_id_name=Device QuickCall;effective_caller_id_number=11223344;ecallmgr_Application-Name=callflow;ecallmgr_Application-Node=whistle_...@mcc-kazoo01.sjz.sankuai.info;ecallmgr_Ecallmgr-Node=ecal...@mcc-kazoo02.sjz.sankuai.info;ecallmgr_Auto-Call-Rule-ID=8e8fd6de-8726-4881-b92f-bede1f712596;ecallmgr_Authorizing-Type=device;ecallmgr_Authorizing-ID=8fb9289d0313d57d04ad4112564b9312;ecallmgr_Retain-CID=true;ecallmgr_Account-ID=07a049bfcfd1685c42a8762a4897626f;ecallmgr_Realm=sip.meituan.com;ecallmgr_Fetch-ID=50959064daeb4618e061642323748a91)
2016-06-29 18:34:51.158188 [NOTICE] kazoo_node.c:302 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|building xferext extension: set ignore_display_updates=true2016-06-29 18:34:51.158188 [NOTICE] kazoo_node.c:302 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|building xferext extension: set continue_on_fail=true2016-06-29 18:34:51.158188 [NOTICE] kazoo_node.c:302 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|building xferext extension: export sip_redirect_context=context_22016-06-29 18:34:51.158188 [NOTICE] kazoo_node.c:302 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|building xferext extension: set hangup_after_bridge=true2016-06-29 18:34:51.158188 [NOTICE] kazoo_node.c:302 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|building xferext extension: export ecallmgr_Inception=${ecallmgr_Inception}
2016-06-29 18:34:51.158188 [NOTICE] kazoo_node.c:302 log|1ba06aa6-3de5-11e6-87d8-fbf5318198dc|building xferext extension: bridge {ignore_display_updates='true',ecallmgr_Account-ID='07a049bfcfd1685c42a8762a4897626f',ecallmgr_Reseller-ID='07a049bfcfd1685c42a8762a4897626f',effective_caller_id_number='11223344',effective_caller_id_name='Device QuickCall',origination_caller_id_number='11223344',origination_caller_id_name='Device QuickCall',ignore_early_media='false',local_var_clobber='true'}[sip_auth_password='mt@009009',sip_auth_username='admin',sip_from_uri='sip:1122...@x.x.6.205',ecallmgr_Global-Resource='false',ecallmgr_Resource-ID='9417ff08859d9c4d65fcc02ab86b2fd0',ecallmgr_E164-Destination='+xxxxxxxxxxx',ecallmgr_Original-Number='0xxxxxxxxxxx',absolute_codec_string='^^:PCMA',leg_progress_timeout='30',effective_callee_id_number='0xxxxxxxxxxx',effective_callee_id_name='0xxxxxxxxxxx']sofia/sipinterface_1/0xxxxxxxxxxx@x.x.6.205|[sip_auth_password='mt@009009',sip_auth_username='admin',sip_from_uri='sip:1122...@x.x.0.75',ecallmgr_Global-Resource='false',ecallmgr_Resource-ID='dc57fba99f6d7d73ebdba7978aceb58f',ecallmgr_E164-Destination='+xxxxxxxxxxx',ecallmgr_Original-Number='0xxxxxxxxxxx',absolute_codec_string='^^:PCMA',leg_progress_timeout='30',effective_callee_id_number='0xxxxxxxxxxx',effective_callee_id_name='0xxxxxxxxxxx']sofia/sipinterface_1/0xxxxxxxxxxx@x.x.0.75
1ba06aa6-3de5-11e6-87d8-fbf5318198dc EXECUTE loopback/+xxxxxxxxxxx-b bridge({ignore_display_updates=true,ecallmgr_Account-ID=07a049bfcfd1685c42a8762a4897626f,ecallmgr_Reseller-ID=07a049bfcfd1685c42a8762a4897626f,effective_caller_id_number=11223344,effective_caller_id_name=Device QuickCall,origination_caller_id_number=11223344,origination_caller_id_name=Device QuickCall,ignore_early_media=false,local_var_clobber=true}[sip_auth_password=mt@009009,sip_auth_username=admin,sip_from_uri=sip:1122...@x.x.6.205,ecallmgr_Global-Resource=false,ecallmgr_Resource-ID=9417ff08859d9c4d65fcc02ab86b2fd0,ecallmgr_E164-Destination=+xxxxxxxxxxx,ecallmgr_Original-Number=0xxxxxxxxxxx,absolute_codec_string=^^:PCMA,leg_progress_timeout=30,effective_callee_id_number=0xxxxxxxxxxx,effective_callee_id_name=0xxxxxxxxxxx]sofia/sipinterface_1/0xxxxxxxxxxx@x.x.6.205|[sip_auth_password=mt@009009,sip_auth_username=admin,sip_from_uri=sip:1122...@x.x.0.75,ecallmgr_Global-Resource=false,ecallmgr_Resource-ID=dc57fba99f6d7d73ebdba7978aceb58f,ecallmgr_E164-Destination=+xxxxxxxxxxx,ecallmgr_Original-Number=0xxxxxxxxxxx,absolute_codec_string=^^:PCMA,leg_progress_timeout=30,effective_callee_id_number=0xxxxxxxxxxx,effective_callee_id_name=0xxxxxxxxxxx]sofia/sipinterface_1/0xxxxxxxxxxx@x.x.0.75)
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_channel.c:1201 loopback/+xxxxxxxxxxx-b EXPORTING[export_vars] [ecallmgr_Bridge-ID]=[1ba06aa6-3de5-11e6-87d8-fbf5318198dc] to event
1ba06aa6-3de5-11e6-87d8-fbf5318198dc 2016-06-29 18:34:51.178158 [DEBUG] switch_channel.c:1201 loopback/+xxxxxxxxxxx-b EXPORTING[export_vars] [ecallmgr_Ecallmgr-Node]=[ecallm...@mcc-kazoo02.sjz.sankuai.info] to event
...
Mountain King
Jun 22, 2017, 1:00:58 AM6/22/17
to 2600hz-dev
Hi, had your Kazoo/FreeSWITCH performance improved? and how to improve?
Thanks
在 2017年2月20日星期一 UTC+8上午12:07:10，emnvn写道：
在 2017年2月20日星期一 UTC+8上午12:07:10，emnvn写道：
Reply all
Reply to author
Forward
0 new messages