[Freeswitch-users] Issues using loopback

Dip Mehta dipkumar.mehta at gmail.com
Tue Nov 8 16:18:27 MSK 2011 

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
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20111108/952a0481/attachment-0001.html

Join us at ClueCon 2011 Aug 9-11, 2011
More information about the FreeSWITCH-users mailing list