Thanks for the response Sean, My gateway was in the configuration but is under sip interface 3. I move it under sip interface 1 and though I no longer get the error in bold below.. I still can not make a phone ring on the call manager side. 2012-03-10 05:01:21.362045 [DEBUG] sofia.c:6288 IP 10.0.1.141 Rejected by acl "net_list_5". Falling back to Digest auth. 2012-03-10 05:01:21.366989 [DEBUG] sofia.c:6288 IP 10.0.1.141 Rejected by acl "net_list_5". Falling back to Digest auth. 2012-03-10 05:01:21.368779 [NOTICE] switch_channel.c:784 New Channel sofia/sipinterface_1/peter_gibbons@bluebox [af0c695f-5d4b-452a-8cc4-5c7ba8841593] 2012-03-10 05:01:21.368779 [DEBUG] switch_core_state_machine.c:318 (sofia/sipinterface_1/peter_gibbons@bluebox) Running State Change CS_NEW 2012-03-10 05:01:21.368779 [DEBUG] switch_core_state_machine.c:324 (sofia/sipinterface_1/peter_gibbons@bluebox) State NEW 2012-03-10 05:01:21.376921 [DEBUG] sofia.c:4559 Channel sofia/sipinterface_1/peter_gibbons@bluebox entering state [received][100] 2012-03-10 05:01:21.376921 [DEBUG] sofia.c:4570 Remote SDP: v=0 o=iSoftPhone 1331384504 1 IN IP4 10.0.1.141 s=conversation c=IN IP4 10.0.1.141 t=0 0 m=audio 10500 RTP/AVP 0 8 3 97 98 9 101 a=rtpmap:0 PCMU/8000 a=rtpmap:8 PCMA/8000 a=rtpmap:3 GSM/8000 a=rtpmap:97 G726-32/8000 a=rtpmap:98 iLBC/8000 a=rtpmap:9 G722/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-11 2012-03-10 05:01:21.376921 [DEBUG] sofia_glue.c:4353 Audio Codec Compare [PCMU:0:8000:0:64000]/[G7221:115:32000:20:48000] 2012-03-10 05:01:21.376921 [DEBUG] sofia_glue.c:4353 Audio Codec Compare [PCMU:0:8000:0:64000]/[G7221:107:16000:20:32000] 2012-03-10 05:01:21.376921 [DEBUG] sofia_glue.c:4353 Audio Codec Compare [PCMU:0:8000:0:64000]/[G722:9:8000:20:64000] 2012-03-10 05:01:21.376921 [DEBUG] sofia_glue.c:4353 Audio Codec Compare [PCMU:0:8000:0:64000]/[PCMU:0:8000:20:64000] 2012-03-10 05:01:21.376921 [DEBUG] sofia_glue.c:2721 Set Codec sofia/sipinterface_1/peter_gibbons@bluebox PCMU/8000 20 ms 160 samples 64000 bits 2012-03-10 05:01:21.376921 [DEBUG] sofia_glue.c:4457 Set 2833 dtmf send/recv payload to 101 2012-03-10 05:01:21.376921 [DEBUG] sofia.c:4732 (sofia/sipinterface_1/peter_gibbons@bluebox) State Change CS_NEW -> CS_INIT 2012-03-10 05:01:21.376921 [DEBUG] switch_core_session.c:1057 Send signal sofia/sipinterface_1/peter_gibbons@bluebox [BREAK] 2012-03-10 05:01:21.378955 [DEBUG] switch_core_state_machine.c:318 (sofia/sipinterface_1/peter_gibbons@bluebox) Running State Change CS_INIT 2012-03-10 05:01:21.378955 [DEBUG] switch_core_state_machine.c:342 (sofia/sipinterface_1/peter_gibbons@bluebox) State INIT 2012-03-10 05:01:21.378955 [DEBUG] mod_sofia.c:83 sofia/sipinterface_1/peter_gibbons@bluebox SOFIA INIT 2012-03-10 05:01:21.378955 [DEBUG] mod_sofia.c:123 (sofia/sipinterface_1/peter_gibbons@bluebox) State Change CS_INIT -> CS_ROUTING 2012-03-10 05:01:21.378955 [DEBUG] switch_core_session.c:1057 Send signal sofia/sipinterface_1/peter_gibbons@bluebox [BREAK] 2012-03-10 05:01:21.378955 [DEBUG] switch_core_state_machine.c:342 (sofia/sipinterface_1/peter_gibbons@bluebox) State INIT going to sleep 2012-03-10 05:01:21.378955 [DEBUG] switch_core_state_machine.c:318 (sofia/sipinterface_1/peter_gibbons@bluebox) Running State Change CS_ROUTING 2012-03-10 05:01:21.380921 [DEBUG] switch_channel.c:1615 (sofia/sipinterface_1/peter_gibbons@bluebox) Callstate Change DOWN -> RINGING 2012-03-10 05:01:21.380921 [DEBUG] switch_core_state_machine.c:345 (sofia/sipinterface_1/peter_gibbons@bluebox) State ROUTING 2012-03-10 05:01:21.380921 [DEBUG] mod_sofia.c:146 sofia/sipinterface_1/peter_gibbons@bluebox SOFIA ROUTING 2012-03-10 05:01:21.380921 [DEBUG] switch_core_state_machine.c:77 sofia/sipinterface_1/peter_gibbons@bluebox Standard ROUTING 2012-03-10 05:01:21.380921 [INFO] mod_dialplan_xml.c:331 Processing peter_gibbons <peter_gibbons>->4000 in context context_1 Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox parsing [context_1->dingaling_1_pattern_1] continue=true Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Regex (FAIL) [dingaling_1_pattern_1] destination_number(4000) =~ /^1{0,1}([2-9][0-8][0-9][2-9][0-9]{6})$/ break=on-false Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox parsing [context_1->conditioning_callerid] continue=true Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Regex (PASS) [conditioning_callerid] ${internal_caller_id_number}(2002) =~ /^.+$/ break=on-false Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Action set(effective_caller_id_name=${internal_caller_id_name}) Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Action set(effective_caller_id_number=${internal_caller_id_number}) Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox parsing [context_1->postroute_global] continue=true Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Absolute Condition [postroute_global] Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid}) Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number}) Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Action hash(insert/${domain_name}-last_dial/global/${uuid}) Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox parsing [context_1->preanswer_gtalk] continue=true Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Regex (FAIL) [preanswer_gtalk] source(mod_sofia) =~ /^mod_dingaling$/ break=on-false Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox parsing [context_1->main_trunk_1_pattern_5] continue=true Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Regex (PASS) [main_trunk_1_pattern_5] destination_number(4000) =~ /^(40[0-9]{2})$/ break=on-false Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Action set(prepend=) Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Regex (PASS) [main_trunk_1_pattern_5] ${outbound_caller_id_number}(5555552002) =~ /^.+$/ break=never Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Action set(effective_caller_id_name=${outbound_caller_id_name}) Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Action set(effective_caller_id_number=${outbound_caller_id_number}) Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Action export(sip_cid_type=rpid) Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Regex (PASS) [main_trunk_1_pattern_5] destination_number(4000) =~ /^(40[0-9]{2})$/ break=on-false Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Action set(failure_causes=NORMAL_CLEARING,ORIGINATOR_CANCEL,CRASH) Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Action bridge(sofia/gateway/trunk_1/${prepend}4000) Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox parsing [context_1->main_number_6] continue=true Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Regex (FAIL) [main_number_6] destination_number(4000) =~ /^2006$/ break=on-false Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox parsing [context_1->main_number_5] continue=true Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Regex (FAIL) [main_number_5] destination_number(4000) =~ /^2005$/ break=on-false Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox parsing [context_1->main_number_4] continue=true Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Regex (FAIL) [main_number_4] destination_number(4000) =~ /^2004$/ break=on-false Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox parsing [context_1->main_number_3] continue=true Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Regex (FAIL) [main_number_3] destination_number(4000) =~ /^2003$/ break=on-false Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox parsing [context_1->main_number_2] continue=true Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Regex (FAIL) [main_number_2] destination_number(4000) =~ /^2002$/ break=on-false Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox parsing [context_1->main_number_1] continue=true Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Regex (FAIL) [main_number_1] destination_number(4000) =~ /^2001$/ break=on-false 2012-03-10 05:01:21.380921 [DEBUG] switch_core_state_machine.c:119 (sofia/sipinterface_1/peter_gibbons@bluebox) State Change CS_ROUTING -> CS_EXECUTE 2012-03-10 05:01:21.380921 [DEBUG] switch_core_session.c:1057 Send signal sofia/sipinterface_1/peter_gibbons@bluebox [BREAK] 2012-03-10 05:01:21.380921 [DEBUG] switch_core_state_machine.c:345 (sofia/sipinterface_1/peter_gibbons@bluebox) State ROUTING going to sleep 2012-03-10 05:01:21.380921 [DEBUG] switch_core_state_machine.c:318 (sofia/sipinterface_1/peter_gibbons@bluebox) Running State Change CS_EXECUTE 2012-03-10 05:01:21.380921 [DEBUG] switch_core_state_machine.c:352 (sofia/sipinterface_1/peter_gibbons@bluebox) State EXECUTE 2012-03-10 05:01:21.380921 [DEBUG] mod_sofia.c:239 sofia/sipinterface_1/peter_gibbons@bluebox SOFIA EXECUTE 2012-03-10 05:01:21.380921 [DEBUG] switch_core_state_machine.c:157 sofia/sipinterface_1/peter_gibbons@bluebox Standard EXECUTE EXECUTE sofia/sipinterface_1/peter_gibbons@bluebox set(effective_caller_id_name=Peter Gibbons) 2012-03-10 05:01:21.380921 [DEBUG] mod_dptools.c:1028 sofia/sipinterface_1/peter_gibbons@bluebox SET [effective_caller_id_name]=[Peter Gibbons] EXECUTE sofia/sipinterface_1/peter_gibbons@bluebox set(effective_caller_id_number=2002) 2012-03-10 05:01:21.382939 [DEBUG] mod_dptools.c:1028 sofia/sipinterface_1/peter_gibbons@bluebox SET [effective_caller_id_number]=[2002] EXECUTE sofia/sipinterface_1/peter_gibbons@bluebox hash(insert/10.0.1.3-spymap/peter_gibbons/af0c695f-5d4b-452a-8cc4-5c7ba8841593) EXECUTE sofia/sipinterface_1/peter_gibbons@bluebox hash(insert/10.0.1.3-last_dial/peter_gibbons/4000) EXECUTE sofia/sipinterface_1/peter_gibbons@bluebox hash(insert/10.0.1.3-last_dial/global/af0c695f-5d4b-452a-8cc4-5c7ba8841593) EXECUTE sofia/sipinterface_1/peter_gibbons@bluebox set(RFC2822_DATE=Sat, 10 Mar 2012 05:01:21 -0800) 2012-03-10 05:01:21.382939 [DEBUG] mod_dptools.c:1028 sofia/sipinterface_1/peter_gibbons@bluebox SET [RFC2822_DATE]=[Sat, 10 Mar 2012 05:01:21 -0800] EXECUTE sofia/sipinterface_1/peter_gibbons@bluebox set(prepend=) 2012-03-10 05:01:21.382939 [DEBUG] mod_dptools.c:1028 sofia/sipinterface_1/peter_gibbons@bluebox SET [prepend]=[UNDEF] EXECUTE sofia/sipinterface_1/peter_gibbons@bluebox set(effective_caller_id_name=Peter Gibbons) 2012-03-10 05:01:21.382939 [DEBUG] mod_dptools.c:1028 sofia/sipinterface_1/peter_gibbons@bluebox SET [effective_caller_id_name]=[Peter Gibbons] EXECUTE sofia/sipinterface_1/peter_gibbons@bluebox set(effective_caller_id_number=5555552002) 2012-03-10 05:01:21.382939 [DEBUG] mod_dptools.c:1028 sofia/sipinterface_1/peter_gibbons@bluebox SET [effective_caller_id_number]=[5555552002] EXECUTE sofia/sipinterface_1/peter_gibbons@bluebox export(sip_cid_type=rpid) 2012-03-10 05:01:21.382939 [DEBUG] switch_channel.c:933 EXPORT (export_vars) [sip_cid_type]=[rpid] EXECUTE sofia/sipinterface_1/peter_gibbons@bluebox set(failure_causes=NORMAL_CLEARING,ORIGINATOR_CANCEL,CRASH) 2012-03-10 05:01:21.382939 [DEBUG] mod_dptools.c:1028 sofia/sipinterface_1/peter_gibbons@bluebox SET [failure_causes]=[NORMAL_CLEARING,ORIGINATOR_CANCEL,CRASH] EXECUTE sofia/sipinterface_1/peter_gibbons@bluebox bridge(sofia/gateway/trunk_1/4000) 2012-03-10 05:01:21.384935 [DEBUG] switch_channel.c:890 sofia/sipinterface_1/peter_gibbons@bluebox EXPORTING[export_vars] [sip_cid_type]=[rpid] to event 2012-03-10 05:01:21.384935 [NOTICE] switch_channel.c:784 New Channel sofia/sipinterface_1/4000 [149cbebe-befd-4a6a-9fe6-3597c88ade68] 2012-03-10 05:01:21.386950 [DEBUG] mod_sofia.c:3968 (sofia/sipinterface_1/4000) State Change CS_NEW -> CS_INIT 2012-03-10 05:01:21.386950 [DEBUG] switch_core_session.c:1057 Send signal sofia/sipinterface_1/4000 [BREAK] 2012-03-10 05:01:21.386950 [DEBUG] switch_core_state_machine.c:318 (sofia/sipinterface_1/4000) Running State Change CS_INIT 2012-03-10 05:01:21.388953 [DEBUG] switch_core_state_machine.c:342 (sofia/sipinterface_1/4000) State INIT 2012-03-10 05:01:21.388953 [DEBUG] mod_sofia.c:83 sofia/sipinterface_1/4000 SOFIA INIT 2012-03-10 05:01:21.388953 [DEBUG] mod_sofia.c:123 (sofia/sipinterface_1/4000) State Change CS_INIT -> CS_ROUTING 2012-03-10 05:01:21.388953 [DEBUG] switch_core_session.c:1057 Send signal sofia/sipinterface_1/4000 [BREAK] 2012-03-10 05:01:21.388953 [DEBUG] sofia.c:4559 Channel sofia/sipinterface_1/4000 entering state [calling][0] 2012-03-10 05:01:21.388953 [DEBUG] switch_core_state_machine.c:342 (sofia/sipinterface_1/4000) State INIT going to sleep 2012-03-10 05:01:21.388953 [DEBUG] switch_core_state_machine.c:318 (sofia/sipinterface_1/4000) Running State Change CS_ROUTING 2012-03-10 05:01:21.388953 [DEBUG] switch_channel.c:1615 (sofia/sipinterface_1/4000) Callstate Change DOWN -> RINGING 2012-03-10 05:01:21.388953 [DEBUG] switch_core_state_machine.c:345 (sofia/sipinterface_1/4000) State ROUTING 2012-03-10 05:01:21.388953 [DEBUG] mod_sofia.c:146 sofia/sipinterface_1/4000 SOFIA ROUTING 2012-03-10 05:01:21.388953 [DEBUG] switch_ivr_originate.c:66 (sofia/sipinterface_1/4000) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2012-03-10 05:01:21.388953 [DEBUG] switch_core_session.c:1057 Send signal sofia/sipinterface_1/4000 [BREAK] 2012-03-10 05:01:21.388953 [DEBUG] switch_core_state_machine.c:345 (sofia/sipinterface_1/4000) State ROUTING going to sleep 2012-03-10 05:01:21.388953 [DEBUG] switch_core_state_machine.c:318 (sofia/sipinterface_1/4000) Running State Change CS_CONSUME_MEDIA 2012-03-10 05:01:21.388953 [DEBUG] switch_core_state_machine.c:364 (sofia/sipinterface_1/4000) State CONSUME_MEDIA 2012-03-10 05:01:21.388953 [DEBUG] switch_core_state_machine.c:364 (sofia/sipinterface_1/4000) State CONSUME_MEDIA going to sleep 2012-03-10 05:01:21.390900 [DEBUG] sofia.c:4559 Channel sofia/sipinterface_1/4000 entering state [terminated][503] 2012-03-10 05:01:21.390900 [DEBUG] switch_channel.c:2457 (sofia/sipinterface_1/4000) Callstate Change RINGING -> HANGUP 2012-03-10 05:01:21.390900 [NOTICE] sofia.c:5190 Hangup sofia/sipinterface_1/4000 [CS_CONSUME_MEDIA] [NORMAL_TEMPORARY_FAILURE] 2012-03-10 05:01:21.390900 [DEBUG] switch_channel.c:2473 Send signal sofia/sipinterface_1/4000 [KILL] 2012-03-10 05:01:21.390900 [DEBUG] switch_core_session.c:1057 Send signal sofia/sipinterface_1/4000 [BREAK] 2012-03-10 05:01:21.390900 [DEBUG] switch_core_state_machine.c:318 (sofia/sipinterface_1/4000) Running State Change CS_HANGUP 2012-03-10 05:01:21.390900 [DEBUG] switch_core_state_machine.c:539 (sofia/sipinterface_1/4000) State HANGUP 2012-03-10 05:01:21.390900 [DEBUG] mod_sofia.c:450 sofia/sipinterface_1/4000 Overriding SIP cause 503 with 503 from the other leg 2012-03-10 05:01:21.390900 [DEBUG] mod_sofia.c:456 Channel sofia/sipinterface_1/4000 hanging up, cause: NORMAL_TEMPORARY_FAILURE 2012-03-10 05:01:21.392800 [DEBUG] switch_ivr_originate.c:3413 Originate Resulted in Error Cause: 41 [NORMAL_TEMPORARY_FAILURE] 2012-03-10 05:01:21.392800 [INFO] mod_dptools.c:2579 Originate Failed. Cause: NORMAL_TEMPORARY_FAILURE 2012-03-10 05:01:21.392800 [DEBUG] mod_dptools.c:2610 Failure causes [NORMAL_CLEARING,ORIGINATOR_CANCEL,CRASH]: Cause: NORMAL_TEMPORARY_FAILURE 2012-03-10 05:01:21.392800 [NOTICE] switch_core_state_machine.c:189 sofia/sipinterface_1/peter_gibbons@bluebox has executed the last dialplan instruction, hanging up. 2012-03-10 05:01:21.392800 [DEBUG] switch_channel.c:2457 (sofia/sipinterface_1/peter_gibbons@bluebox) Callstate Change RINGING -> HANGUP 2012-03-10 05:01:21.392800 [NOTICE] switch_core_state_machine.c:191 Hangup sofia/sipinterface_1/peter_gibbons@bluebox [CS_EXECUTE] [NORMAL_CLEARING] 2012-03-10 05:01:21.392800 [DEBUG] switch_channel.c:2473 Send signal sofia/sipinterface_1/peter_gibbons@bluebox [KILL] 2012-03-10 05:01:21.392800 [DEBUG] switch_core_session.c:1057 Send signal sofia/sipinterface_1/peter_gibbons@bluebox [BREAK] 2012-03-10 05:01:21.392800 [DEBUG] switch_core_state_machine.c:352 (sofia/sipinterface_1/peter_gibbons@bluebox) State EXECUTE going to sleep 2012-03-10 05:01:21.392800 [DEBUG] switch_core_state_machine.c:318 (sofia/sipinterface_1/peter_gibbons@bluebox) Running State Change CS_HANGUP 2012-03-10 05:01:21.392800 [DEBUG] switch_core_state_machine.c:539 (sofia/sipinterface_1/peter_gibbons@bluebox) State HANGUP 2012-03-10 05:01:21.392800 [DEBUG] mod_sofia.c:450 sofia/sipinterface_1/peter_gibbons@bluebox Overriding SIP cause 480 with 503 from the other leg 2012-03-10 05:01:21.392800 [DEBUG] mod_sofia.c:456 Channel sofia/sipinterface_1/peter_gibbons@bluebox hanging up, cause: NORMAL_CLEARING 2012-03-10 05:01:21.394771 [DEBUG] switch_core_state_machine.c:46 sofia/sipinterface_1/4000 Standard HANGUP, cause: NORMAL_TEMPORARY_FAILURE 2012-03-10 05:01:21.394771 [DEBUG] switch_core_state_machine.c:539 (sofia/sipinterface_1/4000) State HANGUP going to sleep 2012-03-10 05:01:21.394771 [DEBUG] switch_core_state_machine.c:337 (sofia/sipinterface_1/4000) State Change CS_HANGUP -> CS_REPORTING 2012-03-10 05:01:21.394771 [DEBUG] switch_core_session.c:1057 Send signal sofia/sipinterface_1/4000 [BREAK] 2012-03-10 05:01:21.394771 [DEBUG] switch_core_state_machine.c:318 (sofia/sipinterface_1/4000) Running State Change CS_REPORTING 2012-03-10 05:01:21.394771 [DEBUG] switch_core_state_machine.c:599 (sofia/sipinterface_1/4000) State REPORTING 2012-03-10 05:01:21.394771 [DEBUG] switch_core_state_machine.c:53 sofia/sipinterface_1/4000 Standard REPORTING, cause: NORMAL_TEMPORARY_FAILURE 2012-03-10 05:01:21.394771 [DEBUG] switch_core_state_machine.c:599 (sofia/sipinterface_1/4000) State REPORTING going to sleep 2012-03-10 05:01:21.394771 [DEBUG] switch_core_state_machine.c:331 (sofia/sipinterface_1/4000) State Change CS_REPORTING -> CS_DESTROY 2012-03-10 05:01:21.394771 [DEBUG] switch_core_session.c:1057 Send signal sofia/sipinterface_1/4000 [BREAK] 2012-03-10 05:01:21.394771 [DEBUG] switch_core_session.c:1224 Session 180 (sofia/sipinterface_1/4000) Locked, Waiting on external entities 2012-03-10 05:01:21.394771 [NOTICE] switch_core_session.c:1242 Session 180 (sofia/sipinterface_1/4000) Ended 2012-03-10 05:01:21.394771 [NOTICE] switch_core_session.c:1244 Close Channel sofia/sipinterface_1/4000 [CS_DESTROY] 2012-03-10 05:01:21.394771 [DEBUG] switch_core_state_machine.c:431 (sofia/sipinterface_1/4000) Callstate Change HANGUP -> DOWN 2012-03-10 05:01:21.394771 [DEBUG] switch_core_state_machine.c:434 (sofia/sipinterface_1/4000) Running State Change CS_DESTROY 2012-03-10 05:01:21.394771 [DEBUG] switch_core_state_machine.c:444 (sofia/sipinterface_1/4000) State DESTROY 2012-03-10 05:01:21.394771 [DEBUG] mod_sofia.c:361 sofia/sipinterface_1/4000 SOFIA DESTROY 2012-03-10 05:01:21.394771 [DEBUG] switch_core_state_machine.c:60 sofia/sipinterface_1/4000 Standard DESTROY 2012-03-10 05:01:21.394771 [DEBUG] switch_core_state_machine.c:444 (sofia/sipinterface_1/4000) State DESTROY going to sleep 2012-03-10 05:01:21.398824 [DEBUG] mod_sofia.c:518 Responding to INVITE with: 503 2012-03-10 05:01:21.398824 [DEBUG] switch_core_state_machine.c:46 sofia/sipinterface_1/peter_gibbons@bluebox Standard HANGUP, cause: NORMAL_CLEARING 2012-03-10 05:01:21.398824 [DEBUG] switch_core_state_machine.c:539 (sofia/sipinterface_1/peter_gibbons@bluebox) State HANGUP going to sleep 2012-03-10 05:01:21.398824 [DEBUG] switch_core_state_machine.c:337 (sofia/sipinterface_1/peter_gibbons@bluebox) State Change CS_HANGUP -> CS_REPORTING 2012-03-10 05:01:21.398824 [DEBUG] switch_core_session.c:1057 Send signal sofia/sipinterface_1/peter_gibbons@bluebox [BREAK] 2012-03-10 05:01:21.398824 [DEBUG] switch_core_state_machine.c:318 (sofia/sipinterface_1/peter_gibbons@bluebox) Running State Change CS_REPORTING 2012-03-10 05:01:21.398824 [DEBUG] switch_core_state_machine.c:599 (sofia/sipinterface_1/peter_gibbons@bluebox) State REPORTING 2012-03-10 05:01:21.555960 [DEBUG] switch_core_state_machine.c:53 sofia/sipinterface_1/peter_gibbons@bluebox Standard REPORTING, cause: NORMAL_CLEARING 2012-03-10 05:01:21.555960 [DEBUG] switch_core_state_machine.c:599 (sofia/sipinterface_1/peter_gibbons@bluebox) State REPORTING going to sleep 2012-03-10 05:01:21.556972 [DEBUG] switch_core_state_machine.c:331 (sofia/sipinterface_1/peter_gibbons@bluebox) State Change CS_REPORTING -> CS_DESTROY 2012-03-10 05:01:21.556972 [DEBUG] switch_core_session.c:1057 Send signal sofia/sipinterface_1/peter_gibbons@bluebox [BREAK] 2012-03-10 05:01:21.556972 [DEBUG] switch_core_session.c:1224 Session 179 (sofia/sipinterface_1/peter_gibbons@bluebox) Locked, Waiting on external entities 2012-03-10 05:01:21.556972 [NOTICE] switch_core_session.c:1242 Session 179 (sofia/sipinterface_1/peter_gibbons@bluebox) Ended 2012-03-10 05:01:21.556972 [NOTICE] switch_core_session.c:1244 Close Channel sofia/sipinterface_1/peter_gibbons@bluebox [CS_DESTROY] 2012-03-10 05:01:21.556972 [DEBUG] switch_core_state_machine.c:431 (sofia/sipinterface_1/peter_gibbons@bluebox) Callstate Change HANGUP -> DOWN 2012-03-10 05:01:21.556972 [DEBUG] switch_core_state_machine.c:434 (sofia/sipinterface_1/peter_gibbons@bluebox) Running State Change CS_DESTROY 2012-03-10 05:01:21.556972 [DEBUG] switch_core_state_machine.c:444 (sofia/sipinterface_1/peter_gibbons@bluebox) State DESTROY 2012-03-10 05:01:21.556972 [DEBUG] mod_sofia.c:361 sofia/sipinterface_1/peter_gibbons@bluebox SOFIA DESTROY 2012-03-10 05:01:21.556972 [DEBUG] switch_core_state_machine.c:60 sofia/sipinterface_1/peter_gibbons@bluebox Standard DESTROY 2012-03-10 05:01:21.556972 [DEBUG] switch_core_state_machine.c:444 (sofia/sipinterface_1/peter_gibbons@bluebox) State DESTROY going to sleep From: Sean Devoy <sdevoy@bizfocused.com<mailto:sdevoy@bizfocused.com>> Reply-To: FreeSWITCH Users Help <freeswitch-users@lists.freeswitch.org<mailto:freeswitch-users@lists.freeswitch.org>> Date: Fri, 9 Mar 2012 16:22:46 -0500 To: 'FreeSWITCH Users Help' <freeswitch-users@lists.freeswitch.org<mailto:freeswitch-users@lists.freeswitch.org>> Subject: Re: [Freeswitch-users] Unable to call cisco from freeswitch/blubox Hi Robbie, I am no expert, but I can help move this along some. The first error is “2012-03-09 08:05:13.421692 [ERR] mod_sofia.c:3738 Invalid Gateway” which is from the dial plan statement “bridge(sofia/gateway/trunk_1/${prepend}4000)” FS doesn’t seem to know what “trunk_1” is. I had this and resolved it on my FS (although it was to a VOIP provider gateway). In your SIP configuration (where you define things like port 5060) you must have a <gateways> </gateways> section that must contain a <gateway name=”trunk_1”></gateway> entity. Working out the params and variables for your gateway I cannot help as I don’t know call manager at all. I had not defined my gateway WITHIN a sip interface, so FS didn’t really know how to get to it. If you have it defined, you should see it in ./fs_cli with a “sofia status” command. Hope that helps some. Sean From: Robbie A. Garrett [mailto:rgarrett@garrettnet.net] Sent: Friday, March 09, 2012 11:09 AM To: freeswitch-users@lists.freeswitch.org<mailto:freeswitch-users@lists.freeswitch.org> Subject: [Freeswitch-users] Unable to call cisco from freeswitch/blubox Hi All, My home use lab uses cisco call manager 8.0. My old setup was cisco call manager to asterisk and then to google voice. Since asterisk and google voice are now broken; I'm looking to move in the direction of free switch. Currently My cisco call manager can call any extension on free switch and leave voicemail's. When I add google talk to frees witch I'm able to call from my cisco VoIP phone, through free switch, and to google. My issue is that free switch is unable to call my cisco call manager. Below is an output of the log of a call from extension 2002 to extension 4000. From what I can tell is that free-switch is not even trying to talk to 10.0.1.99 (my call manger). It's failing before it gets that far. 2012-03-09 08:05:13.418262 [DEBUG] sofia_glue.c:4353 Audio Codec Compare [PCMU:0:8000:0:64000]/[G7221:115:32000:20:48000] 2012-03-09 08:05:13.418262 [DEBUG] sofia_glue.c:4353 Audio Codec Compare [PCMU:0:8000:0:64000]/[G7221:107:16000:20:32000] 2012-03-09 08:05:13.418262 [DEBUG] sofia_glue.c:4353 Audio Codec Compare [PCMU:0:8000:0:64000]/[G722:9:8000:20:64000] 2012-03-09 08:05:13.418262 [DEBUG] sofia_glue.c:4353 Audio Codec Compare [PCMU:0:8000:0:64000]/[PCMU:0:8000:20:64000] 2012-03-09 08:05:13.418262 [DEBUG] sofia_glue.c:2721 Set Codec sofia/sipinterface_1/peter_gibbons@bluebox PCMU/8000 20 ms 160 samples 64000 bits 2012-03-09 08:05:13.418262 [DEBUG] sofia_glue.c:4457 Set 2833 dtmf send/recv payload to 101 2012-03-09 08:05:13.418262 [DEBUG] sofia.c:4732 (sofia/sipinterface_1/peter_gibbons@bluebox) State Change CS_NEW -> CS_INIT 2012-03-09 08:05:13.418262 [DEBUG] switch_core_session.c:1057 Send signal sofia/sipinterface_1/peter_gibbons@bluebox [BREAK] 2012-03-09 08:05:13.419735 [DEBUG] switch_core_state_machine.c:318 (sofia/sipinterface_1/peter_gibbons@bluebox) Running State Change CS_INIT 2012-03-09 08:05:13.419735 [DEBUG] switch_core_state_machine.c:342 (sofia/sipinterface_1/peter_gibbons@bluebox) State INIT 2012-03-09 08:05:13.419735 [DEBUG] mod_sofia.c:83 sofia/sipinterface_1/peter_gibbons@bluebox SOFIA INIT 2012-03-09 08:05:13.419735 [DEBUG] mod_sofia.c:123 (sofia/sipinterface_1/peter_gibbons@bluebox) State Change CS_INIT -> CS_ROUTING 2012-03-09 08:05:13.419735 [DEBUG] switch_core_session.c:1057 Send signal sofia/sipinterface_1/peter_gibbons@bluebox [BREAK] 2012-03-09 08:05:13.419735 [DEBUG] switch_core_state_machine.c:342 (sofia/sipinterface_1/peter_gibbons@bluebox) State INIT going to sleep 2012-03-09 08:05:13.419735 [DEBUG] switch_core_state_machine.c:318 (sofia/sipinterface_1/peter_gibbons@bluebox) Running State Change CS_ROUTING 2012-03-09 08:05:13.419735 [DEBUG] switch_channel.c:1615 (sofia/sipinterface_1/peter_gibbons@bluebox) Callstate Change DOWN -> RINGING 2012-03-09 08:05:13.419735 [DEBUG] switch_core_state_machine.c:345 (sofia/sipinterface_1/peter_gibbons@bluebox) State ROUTING 2012-03-09 08:05:13.419735 [DEBUG] mod_sofia.c:146 sofia/sipinterface_1/peter_gibbons@bluebox SOFIA ROUTING 2012-03-09 08:05:13.419735 [DEBUG] switch_core_state_machine.c:77 sofia/sipinterface_1/peter_gibbons@bluebox Standard ROUTING 2012-03-09 08:05:13.419735 [INFO] mod_dialplan_xml.c:331 Processing peter_gibbons <peter_gibbons>->4000 in context context_1 Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox parsing [context_1->conditioning_callerid] continue=true Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Regex (PASS) [conditioning_callerid] ${internal_caller_id_number}(2002) =~ /^.+$/ break=on-false Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Action set(effective_caller_id_name=${internal_caller_id_name}) Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Action set(effective_caller_id_number=${internal_caller_id_number}) Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox parsing [context_1->postroute_global] continue=true Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Absolute Condition [postroute_global] Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid}) Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number}) Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Action hash(insert/${domain_name}-last_dial/global/${uuid}) Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox parsing [context_1->preanswer_gtalk] continue=true Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Regex (FAIL) [preanswer_gtalk] source(mod_sofia) =~ /^mod_dingaling$/ break=on-false Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox parsing [context_1->main_trunk_1_pattern_5] continue=true Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Regex (PASS) [main_trunk_1_pattern_5] destination_number(4000) =~ /^(40[0-9]{2})$/ break=on-false Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Action set(prepend=) Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Regex (PASS) [main_trunk_1_pattern_5] ${outbound_caller_id_number}(5555552002) =~ /^.+$/ break=never Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Action set(effective_caller_id_name=${outbound_caller_id_name}) Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Action set(effective_caller_id_number=${outbound_caller_id_number}) Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Action export(sip_cid_type=rpid) Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Regex (PASS) [main_trunk_1_pattern_5] destination_number(4000) =~ /^(40[0-9]{2})$/ break=on-false Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Action set(failure_causes=NORMAL_CLEARING,ORIGINATOR_CANCEL,CRASH) Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Action bridge(sofia/gateway/trunk_1/${prepend}4000) Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox parsing [context_1->main_number_6] continue=true Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Regex (FAIL) [main_number_6] destination_number(4000) =~ /^2006$/ break=on-false Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox parsing [context_1->main_number_5] continue=true Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Regex (FAIL) [main_number_5] destination_number(4000) =~ /^2005$/ break=on-false Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox parsing [context_1->main_number_4] continue=true Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Regex (FAIL) [main_number_4] destination_number(4000) =~ /^2004$/ break=on-false Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox parsing [context_1->main_number_3] continue=true Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Regex (FAIL) [main_number_3] destination_number(4000) =~ /^2003$/ break=on-false Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox parsing [context_1->main_number_2] continue=true Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Regex (FAIL) [main_number_2] destination_number(4000) =~ /^2002$/ break=on-false Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox parsing [context_1->main_number_1] continue=true Dialplan: sofia/sipinterface_1/peter_gibbons@bluebox Regex (FAIL) [main_number_1] destination_number(4000) =~ /^2001$/ break=on-false 2012-03-09 08:05:13.419735 [DEBUG] switch_core_state_machine.c:119 (sofia/sipinterface_1/peter_gibbons@bluebox) State Change CS_ROUTING -> CS_EXECUTE 2012-03-09 08:05:13.419735 [DEBUG] switch_core_session.c:1057 Send signal sofia/sipinterface_1/peter_gibbons@bluebox [BREAK] 2012-03-09 08:05:13.419735 [DEBUG] switch_core_state_machine.c:345 (sofia/sipinterface_1/peter_gibbons@bluebox) State ROUTING going to sleep 2012-03-09 08:05:13.419735 [DEBUG] switch_core_state_machine.c:318 (sofia/sipinterface_1/peter_gibbons@bluebox) Running State Change CS_EXECUTE 2012-03-09 08:05:13.419735 [DEBUG] switch_core_state_machine.c:352 (sofia/sipinterface_1/peter_gibbons@bluebox) State EXECUTE 2012-03-09 08:05:13.419735 [DEBUG] mod_sofia.c:239 sofia/sipinterface_1/peter_gibbons@bluebox SOFIA EXECUTE 2012-03-09 08:05:13.419735 [DEBUG] switch_core_state_machine.c:157 sofia/sipinterface_1/peter_gibbons@bluebox Standard EXECUTE EXECUTE sofia/sipinterface_1/peter_gibbons@bluebox set(effective_caller_id_name=Peter Gibbons) 2012-03-09 08:05:13.419735 [DEBUG] mod_dptools.c:1028 sofia/sipinterface_1/peter_gibbons@bluebox SET [effective_caller_id_name]=[Peter Gibbons] EXECUTE sofia/sipinterface_1/peter_gibbons@bluebox set(effective_caller_id_number=2002) 2012-03-09 08:05:13.419735 [DEBUG] mod_dptools.c:1028 sofia/sipinterface_1/peter_gibbons@bluebox SET [effective_caller_id_number]=[2002] EXECUTE sofia/sipinterface_1/peter_gibbons@bluebox hash(insert/10.0.1.3-spymap/peter_gibbons/bc1115e3-b592-454c-8813-a6a7fb2754d4) EXECUTE sofia/sipinterface_1/peter_gibbons@bluebox hash(insert/10.0.1.3-last_dial/peter_gibbons/4000) EXECUTE sofia/sipinterface_1/peter_gibbons@bluebox hash(insert/10.0.1.3-last_dial/global/bc1115e3-b592-454c-8813-a6a7fb2754d4) EXECUTE sofia/sipinterface_1/peter_gibbons@bluebox set(RFC2822_DATE=Fri, 09 Mar 2012 08:05:13 -0800) 2012-03-09 08:05:13.421692 [DEBUG] mod_dptools.c:1028 sofia/sipinterface_1/peter_gibbons@bluebox SET [RFC2822_DATE]=[Fri, 09 Mar 2012 08:05:13 -0800] EXECUTE sofia/sipinterface_1/peter_gibbons@bluebox set(prepend=) 2012-03-09 08:05:13.421692 [DEBUG] mod_dptools.c:1028 sofia/sipinterface_1/peter_gibbons@bluebox SET [prepend]=[UNDEF] EXECUTE sofia/sipinterface_1/peter_gibbons@bluebox set(effective_caller_id_name=Peter Gibbons) 2012-03-09 08:05:13.421692 [DEBUG] mod_dptools.c:1028 sofia/sipinterface_1/peter_gibbons@bluebox SET [effective_caller_id_name]=[Peter Gibbons] EXECUTE sofia/sipinterface_1/peter_gibbons@bluebox set(effective_caller_id_number=5555552002) 2012-03-09 08:05:13.421692 [DEBUG] mod_dptools.c:1028 sofia/sipinterface_1/peter_gibbons@bluebox SET [effective_caller_id_number]=[5555552002] EXECUTE sofia/sipinterface_1/peter_gibbons@bluebox export(sip_cid_type=rpid) 2012-03-09 08:05:13.421692 [DEBUG] switch_channel.c:933 EXPORT (export_vars) [sip_cid_type]=[rpid] EXECUTE sofia/sipinterface_1/peter_gibbons@bluebox set(failure_causes=NORMAL_CLEARING,ORIGINATOR_CANCEL,CRASH) 2012-03-09 08:05:13.421692 [DEBUG] mod_dptools.c:1028 sofia/sipinterface_1/peter_gibbons@bluebox SET [failure_causes]=[NORMAL_CLEARING,ORIGINATOR_CANCEL,CRASH] EXECUTE sofia/sipinterface_1/peter_gibbons@bluebox bridge(sofia/gateway/trunk_1/4000) 2012-03-09 08:05:13.421692 [DEBUG] switch_channel.c:890 sofia/sipinterface_1/peter_gibbons@bluebox EXPORTING[export_vars] [sip_cid_type]=[rpid] to event 2012-03-09 08:05:13.421692 [ERR] mod_sofia.c:3738 Invalid Gateway 2012-03-09 08:05:13.421692 [NOTICE] mod_sofia.c:4060 Close Channel N/A [CS_NEW] 2012-03-09 08:05:13.421692 [DEBUG] switch_core_state_machine.c:434 () Running State Change CS_DESTROY 2012-03-09 08:05:13.421692 [DEBUG] switch_core_state_machine.c:444 (N/A) State DESTROY 2012-03-09 08:05:13.421692 [DEBUG] mod_sofia.c:361 N/A SOFIA DESTROY 2012-03-09 08:05:13.421692 [DEBUG] switch_core_state_machine.c:444 (N/A) State DESTROY going to sleep 2012-03-09 08:05:13.421692 [ERR] switch_ivr_originate.c:2605 Cannot create outgoing channel of type [sofia] cause: [INVALID_NUMBER_FORMAT] 2012-03-09 08:05:13.421692 [DEBUG] switch_ivr_originate.c:3413 Originate Resulted in Error Cause: 28 [INVALID_NUMBER_FORMAT] 2012-03-09 08:05:13.421692 [INFO] mod_dptools.c:2579 Originate Failed. Cause: INVALID_NUMBER_FORMAT 2012-03-09 08:05:13.421692 [DEBUG] mod_dptools.c:2610 Failure causes [NORMAL_CLEARING,ORIGINATOR_CANCEL,CRASH]: Cause: INVALID_NUMBER_FORMAT 2012-03-09 08:05:13.421692 [NOTICE] switch_core_state_machine.c:189 sofia/sipinterface_1/peter_gibbons@bluebox has executed the last dialplan instruction, hanging up. 2012-03-09 08:05:13.421692 [DEBUG] switch_channel.c:2457 (sofia/sipinterface_1/peter_gibbons@bluebox) Callstate Change RINGING -> HANGUP 2012-03-09 08:05:13.421692 [NOTICE] switch_core_state_machine.c:191 Hangup sofia/sipinterface_1/peter_gibbons@bluebox [CS_EXECUTE] [NORMAL_CLEARING] 2012-03-09 08:05:13.421692 [DEBUG] switch_channel.c:2473 Send signal sofia/sipinterface_1/peter_gibbons@bluebox [KILL] 2012-03-09 08:05:13.421692 [DEBUG] switch_core_session.c:1057 Send signal sofia/sipinterface_1/peter_gibbons@bluebox [BREAK] 2012-03-09 08:05:13.421692 [DEBUG] switch_core_state_machine.c:352 (sofia/sipinterface_1/peter_gibbons@bluebox) State EXECUTE going to sleep 2012-03-09 08:05:13.421692 [DEBUG] switch_core_state_machine.c:318 (sofia/sipinterface_1/peter_gibbons@bluebox) Running State Change CS_HANGUP 2012-03-09 08:05:13.423711 [DEBUG] switch_core_state_machine.c:539 (sofia/sipinterface_1/peter_gibbons@bluebox) State HANGUP 2012-03-09 08:05:13.423711 [DEBUG] mod_sofia.c:456 Channel sofia/sipinterface_1/peter_gibbons@bluebox hanging up, cause: NORMAL_CLEARING 2012-03-09 08:05:13.427318 [DEBUG] mod_sofia.c:518 Responding to INVITE with: 480 2012-03-09 08:05:13.427318 [DEBUG] switch_core_state_machine.c:46 sofia/sipinterface_1/peter_gibbons@bluebox Standard HANGUP, cause: NORMAL_CLEARING 2012-03-09 08:05:13.427318 [DEBUG] switch_core_state_machine.c:539 (sofia/sipinterface_1/peter_gibbons@bluebox) State HANGUP going to sleep 2012-03-09 08:05:13.427318 [DEBUG] switch_core_state_machine.c:337 (sofia/sipinterface_1/peter_gibbons@bluebox) State Change CS_HANGUP -> CS_REPORTING 2012-03-09 08:05:13.427318 [DEBUG] switch_core_session.c:1057 Send signal sofia/sipinterface_1/peter_gibbons@bluebox [BREAK] 2012-03-09 08:05:13.427318 [DEBUG] switch_core_state_machine.c:318 (sofia/sipinterface_1/peter_gibbons@bluebox) Running State Change CS_REPORTING 2012-03-09 08:05:13.427318 [DEBUG] switch_core_state_machine.c:599 (sofia/sipinterface_1/peter_gibbons@bluebox) State REPORTING 2012-03-09 08:05:13.586710 [DEBUG] switch_core_state_machine.c:53 sofia/sipinterface_1/peter_gibbons@bluebox Standard REPORTING, cause: NORMAL_CLEARING 2012-03-09 08:05:13.586710 [DEBUG] switch_core_state_machine.c:599 (sofia/sipinterface_1/peter_gibbons@bluebox) State REPORTING going to sleep 2012-03-09 08:05:13.586710 [DEBUG] switch_core_state_machine.c:331 (sofia/sipinterface_1/peter_gibbons@bluebox) State Change CS_REPORTING -> CS_DESTROY 2012-03-09 08:05:13.586710 [DEBUG] switch_core_session.c:1057 Send signal sofia/sipinterface_1/peter_gibbons@bluebox [BREAK] 2012-03-09 08:05:13.586710 [DEBUG] switch_core_session.c:1224 Session 66 (sofia/sipinterface_1/peter_gibbons@bluebox) Locked, Waiting on external entities 2012-03-09 08:05:13.586710 [NOTICE] switch_core_session.c:1242 Session 66 (sofia/sipinterface_1/peter_gibbons@bluebox) Ended 2012-03-09 08:05:13.586710 [NOTICE] switch_core_session.c:1244 Close Channel sofia/sipinterface_1/peter_gibbons@bluebox [CS_DESTROY] 2012-03-09 08:05:13.588724 [DEBUG] switch_core_state_machine.c:431 (sofia/sipinterface_1/peter_gibbons@bluebox) Callstate Change HANGUP -> DOWN 2012-03-09 08:05:13.588724 [DEBUG] switch_core_state_machine.c:434 (sofia/sipinterface_1/peter_gibbons@bluebox) Running State Change CS_DESTROY 2012-03-09 08:05:13.588724 [DEBUG] switch_core_state_machine.c:444 (sofia/sipinterface_1/peter_gibbons@bluebox) State DESTROY 2012-03-09 08:05:13.588724 [DEBUG] mod_sofia.c:361 sofia/sipinterface_1/peter_gibbons@bluebox SOFIA DESTROY 2012-03-09 08:05:13.588724 [DEBUG] switch_core_state_machine.c:60 sofia/sipinterface_1/peter_gibbons@bluebox Standard DESTROY 2012-03-09 08:05:13.588724 [DEBUG] switch_core_state_machine.c:444 (sofia/sipinterface_1/peter_gibbons@bluebox) State DESTROY going to sleep _________________________________________________________________________ Professional FreeSWITCH Consulting Services: consulting@freeswitch.org<mailto:consulting@freeswitch.org> http://www.freeswitchsolutions.com FreeSWITCH-powered IP PBX: The CudaTel Communication Server http://www.cudatel.com Official FreeSWITCH Sites http://www.freeswitch.org http://wiki.freeswitch.org http://www.cluecon.com FreeSWITCH-users mailing list FreeSWITCH-users@lists.freeswitch.org<mailto:FreeSWITCH-users@lists.freeswitch.org> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org