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