[Freeswitch-users] Issues using loopback
Michael Collins
msc at freeswitch.org
Tue Nov 8 19:12:26 MSK 2011
You should ask on the Newfies mailing list about this one. I don't know if
loopback is supported or not, but Areski can probably answer that question
for you.
-MC
On Tue, Nov 8, 2011 at 5:18 AM, Dip Mehta <dipkumar.mehta at gmail.com> wrote:
> Hi,
>
> I am trying to integrate Newfies with Freeswitch.
>
> I tried using loopback to route it to 9171 to default context. However, it
> doesnot works. When the customer picks up the call, there is a dead air.
>
> Pleae advice.
>
> Logs for your refernce.
>
> 13526785574 = external number
>
> 2011-11-08 08:11:21.192674 [DEBUG] switch_core_session.c:1009 Send signal
> sofia/external/13526785574 [BREAK]
> 2011-11-08 08:11:21.212673 [DEBUG] switch_core_session.c:1009 Send signal
> sofia/external/13526785574 [BREAK]
> 2011-11-08 08:11:21.212673 [DEBUG] switch_ivr.c:577 sofia/external/
> 13526785574 Command Execute set(hangup_after_bridge=false)
> EXECUTE sofia/external/13526785574 set(hangup_after_bridge=false)
> 2011-11-08 08:11:21.212673 [DEBUG] mod_dptools.c:1177 sofia/external/
> 13526785574 SET [hangup_after_bridge]=[false]
> 2011-11-08 08:11:21.212673 [DEBUG] switch_ivr.c:577 sofia/external/
> 13526785574 Command Execute unset(call_timeout)
> EXECUTE sofia/external/13526785574 unset(call_timeout)
> 2011-11-08 08:11:21.212673 [DEBUG] mod_dptools.c:1294 UNSET [call_timeout]
> 2011-11-08 08:11:21.212673 [DEBUG] switch_ivr.c:577 sofia/external/
> 13526785574 Command Execute unset(effective_caller_id_number)
> EXECUTE sofia/external/13526785574 unset(effective_caller_id_number)
> 2011-11-08 08:11:21.212673 [DEBUG] mod_dptools.c:1294 UNSET
> [effective_caller_id_number]
> 2011-11-08 08:11:21.212673 [DEBUG] switch_ivr.c:577 sofia/external/
> 13526785574 Command Execute unset(effective_caller_id_name)
> EXECUTE sofia/external/13526785574 unset(effective_caller_id_name)
> 2011-11-08 08:11:21.212673 [DEBUG] mod_dptools.c:1294 UNSET
> [effective_caller_id_name]
> 2011-11-08 08:11:21.212673 [DEBUG] switch_ivr.c:577 sofia/external/
> 13526785574 Command Execute set(continue_on_fail=true)
> EXECUTE sofia/external/13526785574 set(continue_on_fail=true)
> 2011-11-08 08:11:21.212673 [DEBUG] mod_dptools.c:1177 sofia/external/
> 13526785574 SET [continue_on_fail]=[true]
> 2011-11-08 08:11:21.212673 [DEBUG] switch_core_session.c:1009 Send signal
> sofia/external/13526785574 [BREAK]
> 2011-11-08 08:11:21.212673 [DEBUG] switch_ivr.c:577 sofia/external/
> 13526785574 Command Execute set(hangup_after_bridge=false)
> EXECUTE sofia/external/13526785574 set(hangup_after_bridge=false)
> 2011-11-08 08:11:21.212673 [DEBUG] mod_dptools.c:1177 sofia/external/
> 13526785574 SET [hangup_after_bridge]=[false]
> 2011-11-08 08:11:21.212673 [DEBUG] switch_core_session.c:1009 Send signal
> sofia/external/13526785574 [BREAK]
> 2011-11-08 08:11:21.212673 [DEBUG] switch_core_session.c:1009 Send signal
> sofia/external/13526785574 [BREAK]
> 2011-11-08 08:11:21.212673 [DEBUG] switch_core_session.c:1009 Send signal
> sofia/external/13526785574 [BREAK]
> 2011-11-08 08:11:21.212673 [DEBUG] switch_core_session.c:1009 Send signal
> sofia/external/13526785574 [BREAK]
> 2011-11-08 08:11:21.212673 [DEBUG] switch_core_session.c:1009 Send signal
> sofia/external/13526785574 [BREAK]
> 2011-11-08 08:11:21.212673 [DEBUG] switch_core_session.c:1009 Send signal
> sofia/external/13526785574 [BREAK]
> 2011-11-08 08:11:21.212673 [DEBUG] switch_core_session.c:1009 Send signal
> sofia/external/13526785574 [BREAK]
> 2011-11-08 08:11:21.232676 [DEBUG] switch_ivr.c:577 sofia/external/
> 13526785574 Command Execute set(plivo_dial_rang=false)
> EXECUTE sofia/external/13526785574 set(plivo_dial_rang=false)
> 2011-11-08 08:11:21.232676 [DEBUG] mod_dptools.c:1177 sofia/external/
> 13526785574 SET [plivo_dial_rang]=[false]
> 2011-11-08 08:11:21.232676 [DEBUG] switch_ivr.c:577 sofia/external/
> 13526785574 Command Execute unset(bridge_terminate_key)
> EXECUTE sofia/external/13526785574 unset(bridge_terminate_key)
> 2011-11-08 08:11:21.232676 [DEBUG] mod_dptools.c:1294 UNSET
> [bridge_terminate_key]
> 2011-11-08 08:11:21.232676 [DEBUG] switch_ivr.c:577 sofia/external/
> 13526785574 Command Execute set(bridge_early_media=false)
> EXECUTE sofia/external/13526785574 set(bridge_early_media=false)
> 2011-11-08 08:11:21.232676 [DEBUG] mod_dptools.c:1177 sofia/external/
> 13526785574 SET [bridge_early_media]=[false]
> 2011-11-08 08:11:21.232676 [DEBUG] switch_ivr.c:577 sofia/external/
> 13526785574 Command Execute set(instant_ringback=true)
> EXECUTE sofia/external/13526785574 set(instant_ringback=true)
> 2011-11-08 08:11:21.232676 [DEBUG] mod_dptools.c:1177 sofia/external/
> 13526785574 SET [instant_ringback]=[true]
> 2011-11-08 08:11:21.232676 [DEBUG] switch_ivr.c:577 sofia/external/
> 13526785574 Command Execute set(ringback=$${us-ring}})
> EXECUTE sofia/external/13526785574 set(ringback=$%(2000,4000,440,480)})
> 2011-11-08 08:11:21.232676 [DEBUG] mod_dptools.c:1177 sofia/external/
> 13526785574 SET [ringback]=[$%(2000,4000,440,480)}]
> 2011-11-08 08:11:21.232676 [DEBUG] switch_ivr.c:577 sofia/external/
> 13526785574 Command Execute ring_ready()
> EXECUTE sofia/external/13526785574 ring_ready()
> 2011-11-08 08:11:21.232676 [DEBUG] switch_ivr.c:577 sofia/external/
> 13526785574 Command Execute bridge(<api_on_ring='uuid_setvar
> 225b5e18-0a0b-11e1-ba82-590058a85379 plivo_dial_rang
> true',api_on_pre_answer='uuid_setvar 225b5e18-0a0b-11e1-ba82-590058a85379
> plivo_dial_rang true',api_on_answer_1='sched_api +300
> 26f2e3f6-0a0b-11e1-8b92-bc305bec2901 uuid_transfer
> 225b5e18-0a0b-11e1-ba82-590058a85379 -bleg hangup:ALLOTTED_TIMEOUT
> inline'>[leg_timeout=10]loopback/9171/default/xml:_:)
> EXECUTE sofia/external/13526785574 bridge(<api_on_ring='uuid_setvar
> 225b5e18-0a0b-11e1-ba82-590058a85379 plivo_dial_rang
> true',api_on_pre_answer='uuid_setvar 225b5e18-0a0b-11e1-ba82-590058a85379
> plivo_dial_rang true',api_on_answer_1='sched_api +300
> 26f2e3f6-0a0b-11e1-8b92-bc305bec2901 uuid_transfer
> 225b5e18-0a0b-11e1-ba82-590058a85379 -bleg hangup:ALLOTTED_TIMEOUT
> inline'>[leg_timeout=10]loopback/9171/default/xml:_:)
> 2011-11-08 08:11:21.232676 [DEBUG] switch_ivr_originate.c:1413 Parsing
> ultra-global variables
> 2011-11-08 08:11:21.232676 [DEBUG] switch_event.c:1521 Parsing variable
> [api_on_ring]=[uuid_setvar 225b5e18-0a0b-11e1-ba82-590058a85379
> plivo_dial_rang true]
> 2011-11-08 08:11:21.232676 [DEBUG] switch_event.c:1521 Parsing variable
> [api_on_pre_answer]=[uuid_setvar 225b5e18-0a0b-11e1-ba82-590058a85379
> plivo_dial_rang true]
> 2011-11-08 08:11:21.232676 [DEBUG] switch_event.c:1521 Parsing variable
> [api_on_answer_1]=[sched_api +300 26f2e3f6-0a0b-11e1-8b92-bc305bec2901
> uuid_transfer 225b5e18-0a0b-11e1-ba82-590058a85379 -bleg
> hangup:ALLOTTED_TIMEOUT inline]
> 2011-11-08 08:11:21.232676 [DEBUG] switch_ivr_originate.c:1884 Parsing
> global variables
> 2011-11-08 08:11:21.232676 [DEBUG] switch_ivr_originate.c:2299 Parsing
> session specific variables
> 2011-11-08 08:11:21.232676 [DEBUG] switch_ivr_originate.c:1884 Parsing
> global variables
> 2011-11-08 08:11:21.232676 [DEBUG] switch_event.c:1521 Parsing variable
> [leg_timeout]=[10]
> 2011-11-08 08:11:21.232676 [WARNING] switch_ivr_originate.c:1903 No
> origination URL specified!
> 2011-11-08 08:11:21.232676 [DEBUG] switch_ivr_originate.c:3348 Originate
> Resulted in Error Cause: 27 [DESTINATION_OUT_OF_ORDER]
> 2011-11-08 08:11:21.232676 [NOTICE] switch_channel.c:915 New Channel
> loopback/9171/default/xml-a [26f5283c-0a0b-11e1-ba88-590058a85379]
> 2011-11-08 08:11:21.232676 [DEBUG] mod_loopback.c:143
> loopback/9171/default/xml-a setup codec L16/8000/20
> 2011-11-08 08:11:21.232676 [NOTICE] switch_channel.c:913 Rename Channel
> loopback/9171/default/xml-a->loopback/9171-a
> [26f5283c-0a0b-11e1-ba88-590058a85379]
> 2011-11-08 08:11:21.232676 [DEBUG] mod_loopback.c:943 (loopback/9171-a)
> State Change CS_NEW -> CS_INIT
> 2011-11-08 08:11:21.232676 [DEBUG] switch_core_session.c:1177 Send signal
> loopback/9171-a [BREAK]
> 2011-11-08 08:11:21.232676 [DEBUG] mod_loopback.c:473 loopback/9171-a
> CHANNEL KILL
> 2011-11-08 08:11:21.232676 [DEBUG] switch_ivr_originate.c:2554
> loopback/9171-a Setting leg timeout to 10
> 2011-11-08 08:11:21.232676 [DEBUG] switch_core_state_machine.c:362
> (loopback/9171-a) Running State Change CS_INIT
> 2011-11-08 08:11:21.232676 [DEBUG] switch_core_state_machine.c:401
> (loopback/9171-a) State INIT
> 2011-11-08 08:11:21.232676 [NOTICE] switch_channel.c:915 New Channel
> loopback/9171-b [26f53af2-0a0b-11e1-ba8a-590058a85379]
> 2011-11-08 08:11:21.232676 [DEBUG] mod_loopback.c:143 loopback/9171-b
> setup codec L16/8000/20
> 2011-11-08 08:11:21.232676 [DEBUG] mod_loopback.c:256 (loopback/9171-b)
> State Change CS_NEW -> CS_INIT
> 2011-11-08 08:11:21.232676 [DEBUG] switch_core_session.c:1177 Send signal
> loopback/9171-b [BREAK]
> 2011-11-08 08:11:21.232676 [DEBUG] mod_loopback.c:473 loopback/9171-b
> CHANNEL KILL
> 2011-11-08 08:11:21.232676 [DEBUG] mod_loopback.c:302 (loopback/9171-a)
> State Change CS_INIT -> CS_ROUTING
> 2011-11-08 08:11:21.232676 [DEBUG] switch_core_session.c:1177 Send signal
> loopback/9171-a [BREAK]
> 2011-11-08 08:11:21.232676 [DEBUG] mod_loopback.c:473 loopback/9171-a
> CHANNEL KILL
> 2011-11-08 08:11:21.232676 [DEBUG] switch_core_state_machine.c:401
> (loopback/9171-a) State INIT going to sleep
> 2011-11-08 08:11:21.232676 [DEBUG] switch_core_state_machine.c:362
> (loopback/9171-b) Running State Change CS_INIT
> 2011-11-08 08:11:21.232676 [DEBUG] switch_core_state_machine.c:401
> (loopback/9171-b) State INIT
> 2011-11-08 08:11:21.232676 [DEBUG] mod_loopback.c:302 (loopback/9171-b)
> State Change CS_INIT -> CS_ROUTING
> 2011-11-08 08:11:21.232676 [DEBUG] switch_core_session.c:1177 Send signal
> loopback/9171-b [BREAK]
> 2011-11-08 08:11:21.232676 [DEBUG] mod_loopback.c:473 loopback/9171-b
> CHANNEL KILL
> 2011-11-08 08:11:21.232676 [DEBUG] switch_core_state_machine.c:401
> (loopback/9171-b) State INIT going to sleep
> 2011-11-08 08:11:21.232676 [DEBUG] switch_core_state_machine.c:362
> (loopback/9171-a) Running State Change CS_ROUTING
> 2011-11-08 08:11:21.232676 [DEBUG] switch_channel.c:1844 (loopback/9171-a)
> Callstate Change DOWN -> RINGING
> 2011-11-08 08:11:21.232676 [DEBUG] switch_core_state_machine.c:362
> (loopback/9171-b) Running State Change CS_ROUTING
> 2011-11-08 08:11:21.232676 [DEBUG] switch_channel.c:1844 (loopback/9171-b)
> Callstate Change DOWN -> RINGING
> 2011-11-08 08:11:21.232676 [DEBUG] switch_core_state_machine.c:410
> (loopback/9171-a) State ROUTING
> 2011-11-08 08:11:21.232676 [DEBUG] mod_loopback.c:334 loopback/9171-a
> CHANNEL ROUTING
> 2011-11-08 08:11:21.232676 [DEBUG] switch_ivr_originate.c:66
> (loopback/9171-a) State Change CS_ROUTING -> CS_CONSUME_MEDIA
> 2011-11-08 08:11:21.232676 [DEBUG] switch_core_session.c:1177 Send signal
> loopback/9171-a [BREAK]
> 2011-11-08 08:11:21.232676 [DEBUG] mod_loopback.c:473 loopback/9171-a
> CHANNEL KILL
> 2011-11-08 08:11:21.232676 [DEBUG] switch_core_state_machine.c:410
> (loopback/9171-a) State ROUTING going to sleep
> 2011-11-08 08:11:21.232676 [DEBUG] switch_core_state_machine.c:362
> (loopback/9171-a) Running State Change CS_CONSUME_MEDIA
> 2011-11-08 08:11:21.232676 [DEBUG] switch_core_state_machine.c:410
> (loopback/9171-b) State ROUTING
> 2011-11-08 08:11:21.232676 [DEBUG] switch_core_state_machine.c:429
> (loopback/9171-a) State CONSUME_MEDIA
> 2011-11-08 08:11:21.232676 [DEBUG] mod_loopback.c:334 loopback/9171-b
> CHANNEL ROUTING
> 2011-11-08 08:11:21.232676 [DEBUG] mod_loopback.c:533 CHANNEL CONSUME_MEDIA
> 2011-11-08 08:11:21.232676 [DEBUG] switch_core_state_machine.c:104
> loopback/9171-b Standard ROUTING
> 2011-11-08 08:11:21.232676 [DEBUG] switch_core_state_machine.c:429
> (loopback/9171-a) State CONSUME_MEDIA going to sleep
> 2011-11-08 08:11:21.232676 [INFO] mod_dialplan_xml.c:336 Processing
> Outbound Call <13526785574>->9171 in context default
> Dialplan: loopback/9171-b parsing [default->plivo] continue=false
> Dialplan: loopback/9171-b Regex (PASS) [plivo] destination_number(9171) =~
> /^(\d+)$/ break=on-false
> Dialplan: loopback/9171-b Action socket(127.0.0.1:8084 async full)
> 2011-11-08 08:11:21.232676 [DEBUG] switch_core_state_machine.c:154
> (loopback/9171-b) State Change CS_ROUTING -> CS_EXECUTE
> 2011-11-08 08:11:21.232676 [DEBUG] switch_core_session.c:1177 Send signal
> loopback/9171-b [BREAK]
> 2011-11-08 08:11:21.232676 [DEBUG] mod_loopback.c:473 loopback/9171-b
> CHANNEL KILL
> 2011-11-08 08:11:21.232676 [DEBUG] switch_core_state_machine.c:410
> (loopback/9171-b) State ROUTING going to sleep
> 2011-11-08 08:11:21.232676 [DEBUG] switch_core_state_machine.c:362
> (loopback/9171-b) Running State Change CS_EXECUTE
> 2011-11-08 08:11:21.232676 [DEBUG] switch_core_state_machine.c:417
> (loopback/9171-b) State EXECUTE
> 2011-11-08 08:11:21.232676 [DEBUG] mod_loopback.c:373 loopback/9171-b
> CHANNEL EXECUTE
> 2011-11-08 08:11:21.232676 [DEBUG] switch_core_state_machine.c:192
> loopback/9171-b Standard EXECUTE
> EXECUTE loopback/9171-b socket(127.0.0.1:8084 async full)
> 2011-11-08 08:11:21.232676 [DEBUG] switch_core_session.c:1009 Send signal
> loopback/9171-b [BREAK]
> 2011-11-08 08:11:21.232676 [DEBUG] mod_loopback.c:473 loopback/9171-b
> CHANNEL KILL
> 2011-11-08 08:11:21.232676 [DEBUG] switch_core_session.c:1009 Send signal
> loopback/9171-b [BREAK]
> 2011-11-08 08:11:21.232676 [DEBUG] mod_loopback.c:473 loopback/9171-b
> CHANNEL KILL
> 2011-11-08 08:11:21.252674 [DEBUG] switch_core_session.c:1009 Send signal
> loopback/9171-b [BREAK]
> 2011-11-08 08:11:21.252674 [DEBUG] mod_loopback.c:473 loopback/9171-b
> CHANNEL KILL
> 2011-11-08 08:11:21.252674 [DEBUG] mod_event_socket.c:2617
> (loopback/9171-b) State Change CS_EXECUTE -> CS_RESET
> 2011-11-08 08:11:21.252674 [DEBUG] switch_core_session.c:1177 Send signal
> loopback/9171-b [BREAK]
> 2011-11-08 08:11:21.252674 [DEBUG] mod_loopback.c:473 loopback/9171-b
> CHANNEL KILL
> 2011-11-08 08:11:21.252674 [DEBUG] switch_ivr.c:577 loopback/9171-b
> Command Execute set(plivo_app=true)
> EXECUTE loopback/9171-b set(plivo_app=true)
> 2011-11-08 08:11:21.252674 [DEBUG] mod_dptools.c:1177 loopback/9171-b SET
> [plivo_app]=[true]
> 2011-11-08 08:11:21.252674 [DEBUG] switch_ivr.c:577 loopback/9171-b
> Command Execute set(hangup_after_bridge=false)
> EXECUTE loopback/9171-b set(hangup_after_bridge=false)
> 2011-11-08 08:11:21.252674 [DEBUG] mod_dptools.c:1177 loopback/9171-b SET
> [hangup_after_bridge]=[false]
> 2011-11-08 08:11:21.252674 [DEBUG] switch_ivr.c:577 loopback/9171-b
> Command Execute hangup()
> EXECUTE loopback/9171-b hangup()
> 2011-11-08 08:11:21.252674 [DEBUG] switch_channel.c:2804 (loopback/9171-b)
> Callstate Change RINGING -> HANGUP
> 2011-11-08 08:11:21.252674 [NOTICE] mod_dptools.c:1030 Hangup
> loopback/9171-b [CS_RESET] [NORMAL_CLEARING]
> 2011-11-08 08:11:21.252674 [DEBUG] switch_channel.c:2820 Send signal
> loopback/9171-b [KILL]
> 2011-11-08 08:11:21.252674 [DEBUG] mod_loopback.c:473 loopback/9171-b
> CHANNEL KILL
> 2011-11-08 08:11:21.252674 [DEBUG] switch_core_session.c:1177 Send signal
> loopback/9171-b [BREAK]
> 2011-11-08 08:11:21.252674 [DEBUG] mod_loopback.c:473 loopback/9171-b
> CHANNEL KILL
> 2011-11-08 08:11:21.252674 [DEBUG] switch_core_session.c:2262
> loopback/9171-b skip receive message [APPLICATION_EXEC_COMPLETE] (channel
> is hungup already)
> 2011-11-08 08:11:21.252674 [DEBUG] switch_core_session.c:2262
> loopback/9171-b skip receive message [APPLICATION_EXEC_COMPLETE] (channel
> is hungup already)
> 2011-11-08 08:11:21.252674 [DEBUG] switch_core_state_machine.c:417
> (loopback/9171-b) State EXECUTE going to sleep
> 2011-11-08 08:11:21.252674 [DEBUG] switch_core_state_machine.c:362
> (loopback/9171-b) Running State Change CS_HANGUP
> 2011-11-08 08:11:21.252674 [DEBUG] switch_core_state_machine.c:602
> (loopback/9171-b) State HANGUP
> 2011-11-08 08:11:21.252674 [DEBUG] mod_loopback.c:425 loopback/9171-b
> CHANNEL HANGUP
> 2011-11-08 08:11:21.252674 [DEBUG] switch_channel.c:2804 (loopback/9171-a)
> Callstate Change RINGING -> HANGUP
> 2011-11-08 08:11:21.252674 [NOTICE] mod_loopback.c:436 Hangup
> loopback/9171-a [CS_CONSUME_MEDIA] [NORMAL_CLEARING]
> 2011-11-08 08:11:21.252674 [DEBUG] switch_channel.c:2820 Send signal
> loopback/9171-a [KILL]
> 2011-11-08 08:11:21.252674 [DEBUG] mod_loopback.c:473 loopback/9171-a
> CHANNEL KILL
> 2011-11-08 08:11:21.252674 [DEBUG] switch_core_session.c:1177 Send signal
> loopback/9171-a [BREAK]
> 2011-11-08 08:11:21.252674 [DEBUG] mod_loopback.c:473 loopback/9171-a
> CHANNEL KILL
> 2011-11-08 08:11:21.252674 [DEBUG] switch_core_state_machine.c:47
> loopback/9171-b Standard HANGUP, cause: NORMAL_CLEARING
> 2011-11-08 08:11:21.252674 [DEBUG] switch_core_state_machine.c:602
> (loopback/9171-b) State HANGUP going to sleep
> 2011-11-08 08:11:21.252674 [DEBUG] switch_core_state_machine.c:362
> (loopback/9171-a) Running State Change CS_HANGUP
> 2011-11-08 08:11:21.252674 [DEBUG] switch_core_state_machine.c:393
> (loopback/9171-b) State Change CS_HANGUP -> CS_REPORTING
> 2011-11-08 08:11:21.252674 [DEBUG] switch_core_session.c:1177 Send signal
> loopback/9171-b [BREAK]
> 2011-11-08 08:11:21.252674 [DEBUG] mod_loopback.c:473 loopback/9171-b
> CHANNEL KILL
> 2011-11-08 08:11:21.252674 [DEBUG] switch_core_state_machine.c:362
> (loopback/9171-b) Running State Change CS_REPORTING
> 2011-11-08 08:11:21.252674 [DEBUG] switch_core_state_machine.c:662
> (loopback/9171-b) State REPORTING
> 2011-11-08 08:11:21.252674 [DEBUG] switch_core_state_machine.c:602
> (loopback/9171-a) State HANGUP
> 2011-11-08 08:11:21.252674 [DEBUG] mod_loopback.c:425 loopback/9171-a
> CHANNEL HANGUP
> 2011-11-08 08:11:21.252674 [DEBUG] switch_core_state_machine.c:47
> loopback/9171-a Standard HANGUP, cause: NORMAL_CLEARING
> 2011-11-08 08:11:21.252674 [DEBUG] switch_core_state_machine.c:602
> (loopback/9171-a) State HANGUP going to sleep
> 2011-11-08 08:11:21.252674 [DEBUG] switch_core_state_machine.c:393
> (loopback/9171-a) State Change CS_HANGUP -> CS_REPORTING
> 2011-11-08 08:11:21.252674 [DEBUG] switch_core_session.c:1177 Send signal
> loopback/9171-a [BREAK]
> 2011-11-08 08:11:21.252674 [DEBUG] mod_loopback.c:473 loopback/9171-a
> CHANNEL KILL
> 2011-11-08 08:11:21.252674 [DEBUG] switch_core_state_machine.c:362
> (loopback/9171-a) Running State Change CS_REPORTING
> 2011-11-08 08:11:21.252674 [DEBUG] switch_core_state_machine.c:662
> (loopback/9171-a) State REPORTING
> 2011-11-08 08:11:21.252674 [ERR] mod_xml_cdr.c:366 Got error [0] posting
> to web server [NEWFIES_API_STORE_CDR]
> 2011-11-08 08:11:21.252674 [ERR] mod_xml_cdr.c:373 Retry will be with url
> [NEWFIES_API_STORE_CDR]
> 2011-11-08 08:11:21.252674 [ERR] mod_xml_cdr.c:384 Unable to post to web
> server, writing to file
> 2011-11-08 08:11:21.252674 [DEBUG] switch_core_state_machine.c:79
> loopback/9171-b Standard REPORTING, cause: NORMAL_CLEARING
> 2011-11-08 08:11:21.252674 [DEBUG] switch_core_state_machine.c:662
> (loopback/9171-b) State REPORTING going to sleep
> 2011-11-08 08:11:21.252674 [DEBUG] switch_core_state_machine.c:387
> (loopback/9171-b) State Change CS_REPORTING -> CS_DESTROY
> 2011-11-08 08:11:21.252674 [DEBUG] switch_core_session.c:1177 Send signal
> loopback/9171-b [BREAK]
> 2011-11-08 08:11:21.252674 [DEBUG] mod_loopback.c:473 loopback/9171-b
> CHANNEL KILL
> 2011-11-08 08:11:21.252674 [DEBUG] switch_core_session.c:1377 Session 148
> (loopback/9171-b) Locked, Waiting on external entities
> 2011-11-08 08:11:21.252674 [NOTICE] switch_core_session.c:1395 Session 148
> (loopback/9171-b) Ended
> 2011-11-08 08:11:21.252674 [NOTICE] switch_core_session.c:1397 Close
> Channel loopback/9171-b [CS_DESTROY]
> 2011-11-08 08:11:21.252674 [DEBUG] switch_core_state_machine.c:491
> (loopback/9171-b) Callstate Change HANGUP -> DOWN
> 2011-11-08 08:11:21.252674 [DEBUG] switch_core_state_machine.c:494
> (loopback/9171-b) Running State Change CS_DESTROY
> 2011-11-08 08:11:21.252674 [DEBUG] switch_core_state_machine.c:504
> (loopback/9171-b) State DESTROY
> 2011-11-08 08:11:21.252674 [DEBUG] switch_core_state_machine.c:86
> loopback/9171-b Standard DESTROY
> 2011-11-08 08:11:21.252674 [DEBUG] switch_core_state_machine.c:504
> (loopback/9171-b) State DESTROY going to sleep
> 2011-11-08 08:11:21.252674 [ERR] mod_xml_cdr.c:366 Got error [0] posting
> to web server [NEWFIES_API_STORE_CDR]
> 2011-11-08 08:11:21.252674 [ERR] mod_xml_cdr.c:373 Retry will be with url
> [NEWFIES_API_STORE_CDR]
> 2011-11-08 08:11:21.252674 [ERR] mod_xml_cdr.c:384 Unable to post to web
> server, writing to file
> 2011-11-08 08:11:21.252674 [DEBUG] switch_core_state_machine.c:79
> loopback/9171-a Standard REPORTING, cause: NORMAL_CLEARING
> 2011-11-08 08:11:21.252674 [DEBUG] switch_core_state_machine.c:662
> (loopback/9171-a) State REPORTING going to sleep
> 2011-11-08 08:11:21.252674 [DEBUG] switch_core_state_machine.c:387
> (loopback/9171-a) State Change CS_REPORTING -> CS_DESTROY
> 2011-11-08 08:11:21.252674 [DEBUG] switch_core_session.c:1177 Send signal
> loopback/9171-a [BREAK]
> 2011-11-08 08:11:21.252674 [DEBUG] mod_loopback.c:473 loopback/9171-a
> CHANNEL KILL
> 2011-11-08 08:11:21.252674 [DEBUG] switch_core_session.c:1377 Session 147
> (loopback/9171-a) Locked, Waiting on external entities
> 2011-11-08 08:11:21.252674 [DEBUG] switch_ivr_originate.c:3348 Originate
> Resulted in Error Cause: 16 [NORMAL_CLEARING]
> 2011-11-08 08:11:21.252674 [NOTICE] switch_core_session.c:1395 Session 147
> (loopback/9171-a) Ended
> 2011-11-08 08:11:21.252674 [NOTICE] switch_core_session.c:1397 Close
> Channel loopback/9171-a [CS_DESTROY]
> 2011-11-08 08:11:21.252674 [DEBUG] switch_core_state_machine.c:491
> (loopback/9171-a) Callstate Change HANGUP -> DOWN
> 2011-11-08 08:11:21.252674 [DEBUG] switch_core_state_machine.c:494
> (loopback/9171-a) Running State Change CS_DESTROY
> 2011-11-08 08:11:21.252674 [DEBUG] switch_core_state_machine.c:504
> (loopback/9171-a) State DESTROY
> 2011-11-08 08:11:21.252674 [DEBUG] switch_core_state_machine.c:86
> loopback/9171-a Standard DESTROY
> 2011-11-08 08:11:21.252674 [DEBUG] switch_core_state_machine.c:504
> (loopback/9171-a) State DESTROY going to sleep
> 2011-11-08 08:11:21.272673 [DEBUG] switch_rtp.c:3181 Correct ip/port
> confirmed.
> 2011-11-08 08:11:21.312678 [INFO] mod_dptools.c:2811 Originate Failed.
> Cause: NORMAL_CLEARING
> 2011-11-08 08:11:21.312678 [DEBUG] switch_core_session.c:1009 Send signal
> sofia/external/13526785574 [BREAK]
> 2011-11-08 08:11:21.312678 [DEBUG] mod_event_socket.c:2617 (sofia/external/
> 13526785574) State Change CS_EXECUTE -> CS_RESET
> 2011-11-08 08:11:21.312678 [DEBUG] switch_core_session.c:1177 Send signal
> sofia/external/13526785574 [BREAK]
> 2011-11-08 08:11:21.332673 [DEBUG] switch_ivr.c:577 sofia/external/
> 13526785574 Command Execute hangup()
> EXECUTE sofia/external/13526785574 hangup()
> 2011-11-08 08:11:21.332673 [DEBUG] switch_channel.c:2804 (sofia/external/
> 13526785574) Callstate Change ACTIVE -> HANGUP
> 2011-11-08 08:11:21.332673 [NOTICE] mod_dptools.c:1030 Hangup
> sofia/external/13526785574 [CS_RESET] [NORMAL_CLEARING]
> 2011-11-08 08:11:21.332673 [DEBUG] switch_channel.c:2820 Send signal
> sofia/external/13526785574 [KILL]
> 2011-11-08 08:11:21.332673 [DEBUG] switch_core_session.c:1177 Send signal
> sofia/external/13526785574 [BREAK]
> 2011-11-08 08:11:21.332673 [DEBUG] switch_core_session.c:2262
> sofia/external/13526785574 skip receive message
> [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
> 2011-11-08 08:11:21.332673 [DEBUG] switch_core_session.c:2262
> sofia/external/13526785574 skip receive message
> [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
> 2011-11-08 08:11:21.332673 [DEBUG] switch_core_state_machine.c:417
> (sofia/external/13526785574) State EXECUTE going to sleep
> 2011-11-08 08:11:21.332673 [DEBUG] switch_core_state_machine.c:362
> (sofia/external/13526785574) Running State Change CS_HANGUP
> 2011-11-08 08:11:21.332673 [DEBUG] switch_core_state_machine.c:602
> (sofia/external/13526785574) State HANGUP
> 2011-11-08 08:11:21.332673 [DEBUG] mod_sofia.c:465 Channel sofia/external/
> 13526785574 hanging up, cause: NORMAL_CLEARING
> 2011-11-08 08:11:21.332673 [DEBUG] mod_sofia.c:509 Sending BYE to
> sofia/external/13526785574
> 2011-11-08 08:11:21.332673 [DEBUG] switch_core_state_machine.c:47
> sofia/external/13526785574 Standard HANGUP, cause: NORMAL_CLEARING
> 2011-11-08 08:11:21.332673 [DEBUG] switch_core_state_machine.c:602
> (sofia/external/13526785574) State HANGUP going to sleep
> 2011-11-08 08:11:21.332673 [DEBUG] switch_core_state_machine.c:393
> (sofia/external/13526785574) State Change CS_HANGUP -> CS_REPORTING
> 2011-11-08 08:11:21.332673 [DEBUG] switch_core_session.c:1177 Send signal
> sofia/external/13526785574 [BREAK]
> 2011-11-08 08:11:21.332673 [DEBUG] switch_core_state_machine.c:362
> (sofia/external/13526785574) Running State Change CS_REPORTING
> 2011-11-08 08:11:21.332673 [DEBUG] switch_core_state_machine.c:662
> (sofia/external/13526785574) State REPORTING
> 2011-11-08 08:11:21.332673 [ERR] mod_xml_cdr.c:366 Got error [0] posting
> to web server [NEWFIES_API_STORE_CDR]
> 2011-11-08 08:11:21.332673 [ERR] mod_xml_cdr.c:373 Retry will be with url
> [NEWFIES_API_STORE_CDR]
> 2011-11-08 08:11:21.332673 [ERR] mod_xml_cdr.c:384 Unable to post to web
> server, writing to file
> 2011-11-08 08:11:21.332673 [DEBUG] switch_core_state_machine.c:79
> sofia/external/13526785574 Standard REPORTING, cause: NORMAL_CLEARING
> 2011-11-08 08:11:21.332673 [DEBUG] switch_core_state_machine.c:662
> (sofia/external/13526785574) State REPORTING going to sleep
> 2011-11-08 08:11:21.332673 [DEBUG] switch_core_state_machine.c:387
> (sofia/external/13526785574) State Change CS_REPORTING -> CS_DESTROY
> 2011-11-08 08:11:21.332673 [DEBUG] switch_core_session.c:1177 Send signal
> sofia/external/13526785574 [BREAK]
> 2011-11-08 08:11:21.332673 [DEBUG] switch_core_session.c:1377 Session 146
> (sofia/external/13526785574) Locked, Waiting on external entities
> 2011-11-08 08:11:21.332673 [NOTICE] switch_core_session.c:1395 Session 146
> (sofia/external/13526785574) Ended
> 2011-11-08 08:11:21.332673 [NOTICE] switch_core_session.c:1397 Close
> Channel sofia/external/13526785574 [CS_DESTROY]
> 2011-11-08 08:11:21.332673 [DEBUG] switch_core_state_machine.c:491
> (sofia/external/13526785574) Callstate Change HANGUP -> DOWN
> 2011-11-08 08:11:21.332673 [DEBUG] switch_core_state_machine.c:494
> (sofia/external/13526785574) Running State Change CS_DESTROY
> 2011-11-08 08:11:21.332673 [DEBUG] switch_core_state_machine.c:504
> (sofia/external/13526785574) State DESTROY
> 2011-11-08 08:11:21.332673 [DEBUG] mod_sofia.c:370 sofia/external/
> 13526785574 SOFIA DESTROY
> 2011-11-08 08:11:21.332673 [DEBUG] switch_core_state_machine.c:86
> sofia/external/13526785574 Standard DESTROY
> 2011-11-08 08:11:21.332673 [DEBUG] switch_core_state_machine.c:504
> (sofia/external/13526785574) State DESTROY going to sleep
>
>
>
> FreeSWITCH-users mailing list
> FreeSWITCH-users at lists.freeswitch.org
> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
> http://www.freeswitch.org
>
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20111108/e59056b2/attachment-0001.html
Join us at ClueCon 2011 Aug 9-11, 2011
More information about the FreeSWITCH-users
mailing list