1) please do not flood emails with traces reference pastebins <br>2) the error is a routing loop being prevented so you are looping the call into your same box too many times.<br><br>You could have figured this out your self by looking at one of the lines of code where it reported the hangup.<br>
<br> if (!switch_strlen_zero(forwardvar)) {<br> forwardval = atoi(forwardvar) - 1;<br> }<br> if (forwardval <= 0) {<br> switch_channel_hangup(channel, SWITCH_CAUSE_EXCHANGE_ROUTING_ERROR);<br> return SWITCH_STATUS_FALSE;<br>
}<br><br><br><div class="gmail_quote">On Thu, Oct 22, 2009 at 12:42 PM, Matthew Fong <span dir="ltr"><<a href="mailto:mattdfong@gmail.com">mattdfong@gmail.com</a>></span> wrote:<br><blockquote class="gmail_quote" style="border-left: 1px solid rgb(204, 204, 204); margin: 0pt 0pt 0pt 0.8ex; padding-left: 1ex;">
<div>I figured out how to turn debug mode on. For some reason F8 didn't work, so I just set the default debug mode to DEBUG in switch.conf.xml</div><div><br></div><a href="http://pastebin.freeswitch.org/10805" target="_blank">http://pastebin.freeswitch.org/10805</a><div>
<br></div><div><a href="http://pastebin.freeswitch.org/10805" target="_blank"></a>So my program does more or less the same sequence for an Agent every time when connected to another party. They uuid_kill the bridged parties uuid, and then are transfered to an extension that park's the agent (using the transfer_after_bridge variable -- in my dialplan extn 1998). In some instances the agent is then transfered to another extension, played a wav file, and then park'ed again</div>
<div><br></div><div>Sometimes, FreeSWITCH unexpectidly hangsup the Agent channel citing an EXCHANGE_ROUTING_ERROR. It seems (to me to happen at random times)...and it's more prevelent with some did providers than others. With <a href="http://didforsale.org" target="_blank">didforsale.org</a> it happens about one ever 5 bridges. With <a href="http://icall.com" target="_blank">icall.com</a> it only happens about once every 30 bridges. With IPKall it happens rarely. I can't figure it out, and it's driving me crazy :)</div>
<div><br></div><div><br></div><div>A GOOD UUID_KILL SEQUENCE:</div><div><div>2009-10-22 17:22:31.741845 [DEBUG] switch_ivr_play_say.c:1432 done playing file</div><div>2009-10-22 17:22:31.741845 [DEBUG] switch_core_state_machine.c:340 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) State EXECUTE going to sleep</div>
<div>2009-10-22 17:22:31.741845 [DEBUG] switch_core_state_machine.c:306 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) Running State Change CS_HIBERNATE</div>
<div>2009-10-22 17:22:31.741845 [DEBUG] switch_core_state_machine.c:355 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) State HIBERNATE</div>
<div>2009-10-22 17:22:31.741845 [DEBUG] mod_sofia.c:160 sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> SOFIA HIBERNATE</div><div>2009-10-22 17:22:31.741845 [DEBUG] switch_ivr_bridge.c:650 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) State Change CS_HIBERNATE -> CS_RESET</div>
<div>2009-10-22 17:22:31.741845 [DEBUG] switch_core_session.c:932 Send signal sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> [BREAK]</div><div>2009-10-22 17:22:31.741845 [DEBUG] switch_core_state_machine.c:355 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) State HIBERNATE going to sleep</div>
<div>2009-10-22 17:22:31.741845 [DEBUG] switch_core_state_machine.c:306 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) Running State Change CS_RESET</div>
<div>2009-10-22 17:22:31.741845 [DEBUG] switch_core_state_machine.c:336 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) State RESET</div>
<div>2009-10-22 17:22:31.741845 [DEBUG] mod_sofia.c:145 sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> SOFIA RESET</div><div>2009-10-22 17:22:31.741845 [DEBUG] switch_ivr_bridge.c:637 sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> CUSTOM RESET</div>
<div>2009-10-22 17:22:31.741845 [DEBUG] switch_core_state_machine.c:67 sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> Standard RESET</div><div>2009-10-22 17:22:31.741845 [DEBUG] switch_core_state_machine.c:336 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) State RESET going to sleep</div>
<div>2009-10-22 17:22:31.748086 [DEBUG] switch_core_state_machine.c:340 (sofia/external/14159927717) State EXECUTE going to sleep</div><div>2009-10-22 17:22:31.748086 [DEBUG] switch_core_state_machine.c:306 (sofia/external/14159927717) Running State Change CS_HIBERNATE</div>
<div>2009-10-22 17:22:31.748086 [DEBUG] switch_core_state_machine.c:355 (sofia/external/14159927717) State HIBERNATE</div><div>2009-10-22 17:22:31.748086 [DEBUG] mod_sofia.c:160 sofia/external/14159927717 SOFIA HIBERNATE</div>
<div>2009-10-22 17:22:31.748086 [DEBUG] switch_ivr_bridge.c:650 (sofia/external/14159927717) State Change CS_HIBERNATE -> CS_RESET</div><div>2009-10-22 17:22:31.748086 [DEBUG] switch_core_session.c:932 Send signal sofia/external/14159927717 [BREAK]</div>
<div>2009-10-22 17:22:31.748086 [DEBUG] switch_core_state_machine.c:355 (sofia/external/14159927717) State HIBERNATE going to sleep</div><div>2009-10-22 17:22:31.748086 [DEBUG] switch_core_state_machine.c:306 (sofia/external/14159927717) Running State Change CS_RESET</div>
<div>2009-10-22 17:22:31.748086 [DEBUG] switch_core_state_machine.c:336 (sofia/external/14159927717) State RESET</div><div>2009-10-22 17:22:31.748086 [DEBUG] mod_sofia.c:145 sofia/external/14159927717 SOFIA RESET</div><div>
2009-10-22 17:22:31.748086 [DEBUG] switch_ivr_bridge.c:637 sofia/external/14159927717 CUSTOM RESET</div><div>2009-10-22 17:22:31.748086 [DEBUG] switch_ivr_bridge.c:642 (sofia/external/14159927717) State Change CS_RESET -> CS_SOFT_EXECUTE</div>
<div>2009-10-22 17:22:31.748086 [DEBUG] switch_core_session.c:932 Send signal sofia/external/14159927717 [BREAK]</div><div>2009-10-22 17:22:31.748086 [DEBUG] switch_core_state_machine.c:336 (sofia/external/14159927717) State RESET going to sleep</div>
<div>2009-10-22 17:22:31.748086 [DEBUG] switch_core_state_machine.c:306 (sofia/external/14159927717) Running State Change CS_SOFT_EXECUTE</div><div>2009-10-22 17:22:31.748086 [DEBUG] switch_core_state_machine.c:346 (sofia/external/14159927717) State SOFT_EXECUTE</div>
<div>2009-10-22 17:22:31.748086 [DEBUG] mod_sofia.c:442 SOFIA TRANSMIT</div><div>2009-10-22 17:22:31.748086 [DEBUG] switch_ivr_bridge.c:660 sofia/external/14159927717 CUSTOM SOFT_EXECUTE</div><div>2009-10-22 17:22:31.748086 [DEBUG] switch_ivr_originate.c:674 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) State Change CS_RESET -> CS_SOFT_EXECUTE</div>
<div>2009-10-22 17:22:31.748086 [DEBUG] switch_core_session.c:932 Send signal sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> [BREAK]</div><div>2009-10-22 17:22:31.748086 [DEBUG] switch_ivr_bridge.c:120 sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> receive message [DISPLAY]</div>
<div>2009-10-22 17:22:31.748086 [DEBUG] switch_ivr_bridge.c:120 sofia/external/14159927717 receive message [DISPLAY]</div><div>2009-10-22 17:22:31.748086 [DEBUG] switch_ivr_bridge.c:924 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) State Change CS_SOFT_EXECUTE -> CS_CONSUME_MEDIA</div>
<div>2009-10-22 17:22:31.748086 [DEBUG] switch_core_session.c:932 Send signal sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> [BREAK]</div><div>2009-10-22 17:22:31.748086 [DEBUG] switch_ivr_bridge.c:968 sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> receive message [BRIDGE]</div>
<div>2009-10-22 17:22:31.748086 [DEBUG] switch_core_session.c:630 Send signal sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> [BREAK]</div><div>2009-10-22 17:22:31.748086 [DEBUG] switch_ivr_bridge.c:975 sofia/external/14159927717 receive message [BRIDGE]</div>
<div>2009-10-22 17:22:31.748086 [DEBUG] switch_core_session.c:630 Send signal sofia/external/14159927717 [BREAK]</div><div>2009-10-22 17:22:31.748086 [DEBUG] switch_ivr_bridge.c:1019 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA</div>
<div>2009-10-22 17:22:31.748086 [DEBUG] switch_core_session.c:932 Send signal sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> [BREAK]</div><div>2009-10-22 17:22:31.749553 [DEBUG] switch_core_state_machine.c:306 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) Running State Change CS_EXCHANGE_MEDIA</div>
<div>2009-10-22 17:22:31.749553 [DEBUG] switch_core_state_machine.c:343 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) State EXCHANGE_MEDIA</div><div>
2009-10-22 17:22:31.749553 [DEBUG] mod_sofia.c:436 SOFIA LOOPBACK</div>
<div>multipler is being overrided to1</div><div>2009-10-22 17:22:34.300662 [INFO] switch_cpp.cpp:1116 BG_AG_GEN_CALLS, Agents total:1 avail:0 OutChan:0 AR:0 +-Calls:0:Q Call NYNoc</div><div>multipler is being overrided to1</div>
<div>2009-10-22 17:22:39.303041 [INFO] switch_cpp.cpp:1116 BG_AG_GEN_CALLS, Agents total:1 avail:0 OutChan:0 AR:0 +-Calls:0:Q Call NYNoc</div><div>2009-10-22 17:22:40.626379 [NOTICE] sofia.c:328 Hangup sofia/external/14159927717 [CS_SOFT_EXECUTE] [NORMAL_CLEARING]</div>
<div>2009-10-22 17:22:40.626379 [DEBUG] switch_channel.c:1810 Send signal sofia/external/14159927717 [KILL]</div><div>2009-10-22 17:22:40.626379 [DEBUG] switch_core_session.c:932 Send signal sofia/external/14159927717 [BREAK]</div>
<div>active_true=1</div><div>--contact NOT in playmsg or conference, update AGENT</div><div>2009-10-22 17:22:40.626379 [DEBUG] switch_core_state_machine.c:437 thread mismatch skipping state handler.</div><div>2009-10-22 17:22:40.626379 [INFO] switch_cpp.cpp:1116 EVENTC_HANGUP_CONTACT, Q_CONTACT fired for agent:1/1 for qcall:1 contact:4801442/4</div>
<div>2009-10-22 17:22:40.639760 [DEBUG] switch_ivr_bridge.c:440 sofia/external/14159927717 ending bridge by request from write function</div><div>2009-10-22 17:22:40.639760 [DEBUG] switch_ivr_bridge.c:495 sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> receive message [UNBRIDGE]</div>
<div>2009-10-22 17:22:40.639760 [DEBUG] switch_core_session.c:630 Send signal sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> [BREAK]</div><div>2009-10-22 17:22:40.639760 [DEBUG] switch_ivr_bridge.c:521 BRIDGE THREAD DONE [sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>]</div>
<div>2009-10-22 17:22:40.639760 [DEBUG] switch_ivr_bridge.c:523 Send signal sofia/external/14159927717 [BREAK]</div><div>2009-10-22 17:22:40.639760 [DEBUG] switch_ivr.c:1361 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) State Change CS_EXCHANGE_MEDIA -> CS_ROUTING</div>
<div>2009-10-22 17:22:40.639760 [DEBUG] switch_core_session.c:932 Send signal sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> [BREAK]</div><div>2009-10-22 17:22:40.639760 [DEBUG] switch_ivr.c:1365 sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> receive message [TRANSFER]</div>
<div>2009-10-22 17:22:40.639760 [DEBUG] switch_core_session.c:630 Send signal sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> [BREAK]</div><div>2009-10-22 17:22:40.639760 [NOTICE] switch_ivr.c:1367 Transfer sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> to xml[1998@default]</div>
<div>2009-10-22 17:22:40.639760 [DEBUG] switch_core_state_machine.c:343 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) State EXCHANGE_MEDIA going to sleep</div>
<div>
2009-10-22 17:22:40.639760 [DEBUG] switch_core_state_machine.c:306 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) Running State Change CS_ROUTING</div>
<div>2009-10-22 17:22:40.639760 [DEBUG] switch_core_state_machine.c:333 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) State ROUTING</div>
<div>2009-10-22 17:22:40.639760 [DEBUG] mod_sofia.c:130 sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> SOFIA ROUTING</div><div>2009-10-22 17:22:40.639760 [DEBUG] switch_core_state_machine.c:78 sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> Standard ROUTING</div>
<div>2009-10-22 17:22:40.639760 [INFO] mod_dialplan_xml.c:391 Processing +14158867717->1998 in context default</div><div>Dialplan: sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> parsing [default->tod_example] continue=true</div>
<div>Dialplan: sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> Regex (PASS) [tod_example] ${strftime(%w)}(4) =~ /^([1-5])$/ break=on-false</div><div>Dialplan: sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> Regex (PASS) [tod_example] ${strftime(%H%M)}(1722) =~ /^((09|1[0-7])[0-5][0-9]|1800)$/ break=on-false</div>
<div>Dialplan: sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> Action set(open=true)</div><div>Dialplan: sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> parsing [default->ivr_demo] continue=false</div>
<div>Dialplan: sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> Regex (FAIL) [ivr_demo] destination_number(1998) =~ /^5000$/ break=on-false</div><div>Dialplan: sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> parsing [default->1975] continue=false</div>
<div>Dialplan: sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> Regex (FAIL) [1975] destination_number(1998) =~ /^1975$/ break=on-false</div><div>Dialplan: sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> parsing [default->1971] continue=false</div>
<div>Dialplan: sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> Regex (FAIL) [1971] destination_number(1998) =~ /^1971$/ break=on-false</div><div>Dialplan: sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> parsing [default->1972] continue=false</div>
<div>Dialplan: sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> Regex (FAIL) [1972] destination_number(1998) =~ /^1972$/ break=on-false</div><div>Dialplan: sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> parsing [default->1920] continue=false</div>
<div>Dialplan: sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> Regex (FAIL) [1920] destination_number(1998) =~ /^1920$/ break=on-false</div><div>Dialplan: sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> parsing [default->1981] continue=false</div>
<div>Dialplan: sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> Regex (FAIL) [1981] destination_number(1998) =~ /^1981$/ break=on-false</div><div>Dialplan: sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> parsing [default->1982] continue=false</div>
<div>Dialplan: sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> Regex (FAIL) [1982] destination_number(1998) =~ /^1982$/ break=on-false</div><div>Dialplan: sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> parsing [default->1481] continue=false</div>
<div>Dialplan: sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> Regex (FAIL) [1481] destination_number(1998) =~ /^1481$/ break=on-false</div><div>Dialplan: sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> parsing [default->1989] continue=false</div>
<div>Dialplan: sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> Regex (FAIL) [1989] destination_number(1998) =~ /^1989$/ break=on-false</div><div>Dialplan: sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> parsing [default->1990] continue=false</div>
<div>Dialplan: sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> Regex (FAIL) [1990] destination_number(1998) =~ /^1990$/ break=on-false</div><div>Dialplan: sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> parsing [default->1991] continue=false</div>
<div>Dialplan: sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> Regex (FAIL) [1991] destination_number(1998) =~ /^1991$/ break=on-false</div><div>Dialplan: sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> parsing [default->1992] continue=false</div>
<div>Dialplan: sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> Regex (FAIL) [1992] destination_number(1998) =~ /^1992$/ break=on-false</div><div>Dialplan: sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> parsing [default->1993] continue=false</div>
<div>Dialplan: sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> Regex (FAIL) [1993] destination_number(1998) =~ /^1993$/ break=on-false</div><div>Dialplan: sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> parsing [default->1994] continue=false</div>
<div>Dialplan: sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> Regex (FAIL) [1994] destination_number(1998) =~ /^1994$/ break=on-false</div><div>Dialplan: sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> parsing [default->1995] continue=false</div>
<div>Dialplan: sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> Regex (FAIL) [1995] destination_number(1998) =~ /^1995$/ break=on-false</div><div>Dialplan: sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> parsing [default->1996] continue=false</div>
<div>Dialplan: sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> Regex (FAIL) [1996] destination_number(1998) =~ /^1996$/ break=on-false</div><div>Dialplan: sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> parsing [default->1997] continue=false</div>
<div>Dialplan: sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> Regex (FAIL) [1997] destination_number(1998) =~ /^1997$/ break=on-false</div><div>Dialplan: sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> parsing [default->1998] continue=false</div>
<div>Dialplan: sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> Regex (PASS) [1998] destination_number(1998) =~ /^1998$/ break=on-false</div><div>Dialplan: sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> Action set(transfer_after_bridge=1998)</div>
<div>Dialplan: sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> Action park()</div><div>2009-10-22 17:22:40.639760 [DEBUG] switch_core_state_machine.c:114 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) State Change CS_ROUTING -> CS_EXECUTE</div>
<div>2009-10-22 17:22:40.639760 [DEBUG] switch_core_session.c:932 Send signal sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> [BREAK]</div><div>2009-10-22 17:22:40.639760 [DEBUG] switch_core_state_machine.c:333 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) State ROUTING going to sleep</div>
<div>2009-10-22 17:22:40.639760 [DEBUG] switch_core_state_machine.c:306 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) Running State Change CS_EXECUTE</div>
<div>2009-10-22 17:22:40.639760 [DEBUG] switch_core_state_machine.c:340 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) State EXECUTE</div>
<div>2009-10-22 17:22:40.639760 [DEBUG] mod_sofia.c:173 sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> SOFIA EXECUTE</div><div>2009-10-22 17:22:40.639760 [DEBUG] switch_core_state_machine.c:151 sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> Standard EXECUTE</div>
<div>EXECUTE sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> set(open=true)</div><div>2009-10-22 17:22:40.639760 [DEBUG] mod_dptools.c:752 sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> SET [open]=[true]</div>
<div>EXECUTE sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> set(transfer_after_bridge=1998)</div><div>2009-10-22 17:22:40.639760 [DEBUG] mod_dptools.c:752 sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> SET [transfer_after_bridge]=[1998]</div>
<div>EXECUTE sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> park()</div><div>2009-10-22 17:22:40.639760 [DEBUG] switch_ivr_bridge.c:446 sofia/external/14159927717 ending bridge by request from read function</div>
<div>2009-10-22 17:22:40.639760 [DEBUG] switch_ivr_bridge.c:521 BRIDGE THREAD DONE [sofia/external/14159927717]</div><div>2009-10-22 17:22:40.639760 [DEBUG] switch_ivr_bridge.c:523 Send signal sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> [BREAK]</div>
<div>subtracting 9 seconds for hh_call: 1095422</div><div>2009-10-22 17:22:40.639760 [DEBUG] switch_core_state_machine.c:346 (sofia/external/14159927717) State SOFT_EXECUTE going to sleep</div><div>2009-10-22 17:22:40.639760 [DEBUG] switch_core_state_machine.c:306 (sofia/external/14159927717) Running State Change CS_HANGUP</div>
<div>2009-10-22 17:22:40.639760 [DEBUG] switch_core_state_machine.c:464 (sofia/external/14159927717) State HANGUP</div><div>2009-10-22 17:22:40.639760 [DEBUG] mod_sofia.c:344 Channel sofia/external/14159927717 hanging up, cause: NORMAL_CLEARING</div>
<div>2009-10-22 17:22:40.639760 [DEBUG] switch_core_state_machine.c:46 sofia/external/14159927717 Standard HANGUP, cause: NORMAL_CLEARING</div><div>2009-10-22 17:22:40.639760 [DEBUG] switch_core_state_machine.c:464 (sofia/external/14159927717) State HANGUP going to sleep</div>
<div>2009-10-22 17:22:40.639760 [DEBUG] switch_core_state_machine.c:325 (sofia/external/14159927717) State Change CS_HANGUP -> CS_REPORTING</div><div>2009-10-22 17:22:40.639760 [DEBUG] switch_core_session.c:932 Send signal sofia/external/14159927717 [BREAK]</div>
<div>2009-10-22 17:22:40.639760 [DEBUG] switch_core_state_machine.c:306 (sofia/external/14159927717) Running State Change CS_REPORTING</div><div>2009-10-22 17:22:40.639760 [DEBUG] switch_core_state_machine.c:555 (sofia/external/14159927717) State REPORTING</div>
<div>2009-10-22 17:22:40.639760 [DEBUG] switch_core_state_machine.c:53 sofia/external/14159927717 Standard REPORTING, cause: NORMAL_CLEARING</div><div>2009-10-22 17:22:40.639760 [DEBUG] switch_core_state_machine.c:555 (sofia/external/14159927717) State REPORTING going to sleep</div>
<div>2009-10-22 17:22:40.639760 [DEBUG] switch_core_state_machine.c:319 (sofia/external/14159927717) State Change CS_REPORTING -> CS_DESTROY</div><div>2009-10-22 17:22:40.639760 [DEBUG] switch_core_session.c:932 Send signal sofia/external/14159927717 [BREAK]</div>
<div>2009-10-22 17:22:40.639760 [DEBUG] switch_core_session.c:1069 Session 6 (sofia/external/14159927717) Locked, Waiting on external entities</div><div>2009-10-22 17:22:40.639760 [NOTICE] switch_core_session.c:1087 Session 6 (sofia/external/14159927717) Ended</div>
<div>2009-10-22 17:22:40.639760 [NOTICE] switch_core_session.c:1089 Close Channel sofia/external/14159927717 [CS_DESTROY]</div><div>2009-10-22 17:22:40.639760 [DEBUG] switch_core_state_machine.c:401 (sofia/external/14159927717) Running State Change CS_DESTROY</div>
<div>2009-10-22 17:22:40.639760 [DEBUG] switch_core_state_machine.c:412 (sofia/external/14159927717) State DESTROY</div><div>2009-10-22 17:22:40.639760 [DEBUG] mod_sofia.c:261 sofia/external/14159927717 SOFIA DESTROY</div>
<div>2009-10-22 17:22:40.639760 [DEBUG] switch_core_state_machine.c:60 sofia/external/14159927717 Standard DESTROY</div><div>2009-10-22 17:22:40.639760 [DEBUG] switch_core_state_machine.c:412 (sofia/external/14159927717) State DESTROY going to sleep</div>
<div>2009-10-22 17:22:40.639760 [INFO] switch_cpp.cpp:1116 PCHangup gw:<a href="http://teliax.com" target="_blank">teliax.com</a> hc:NORMAL_CLEARING du:9 cn:sofia/external/14159927717</div><div><br></div></div><div><br></div>
<div><br></div>
<div>A BAD UUID_KILL SEQUENCE: (where the agent is hungup, unexpectedly)</div><div><br></div><div><div>--bridged in: 0.005821 seconds</div><div>2009-10-22 17:23:26.718930 [INFO] switch_cpp.cpp:1116 AG_CBRIDGE, connected agent:1/1 contact:4801439/22/1/1</div>
<div>2009-10-22 17:23:26.721553 [DEBUG] switch_ivr_play_say.c:1432 done playing file</div><div>2009-10-22 17:23:26.721553 [DEBUG] switch_core_state_machine.c:340 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) State EXECUTE going to sleep</div>
<div>2009-10-22 17:23:26.721553 [DEBUG] switch_core_state_machine.c:306 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) Running State Change CS_HIBERNATE</div>
<div>2009-10-22 17:23:26.721553 [DEBUG] switch_core_state_machine.c:355 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) State HIBERNATE</div>
<div>2009-10-22 17:23:26.721553 [DEBUG] mod_sofia.c:160 sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> SOFIA HIBERNATE</div><div>2009-10-22 17:23:26.721553 [DEBUG] switch_ivr_bridge.c:650 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) State Change CS_HIBERNATE -> CS_RESET</div>
<div>2009-10-22 17:23:26.721553 [DEBUG] switch_core_session.c:932 Send signal sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> [BREAK]</div><div>2009-10-22 17:23:26.721553 [DEBUG] switch_core_state_machine.c:355 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) State HIBERNATE going to sleep</div>
<div>2009-10-22 17:23:26.721553 [DEBUG] switch_core_state_machine.c:306 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) Running State Change CS_RESET</div>
<div>2009-10-22 17:23:26.721553 [DEBUG] switch_core_state_machine.c:336 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) State RESET</div>
<div>2009-10-22 17:23:26.721553 [DEBUG] mod_sofia.c:145 sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> SOFIA RESET</div><div>2009-10-22 17:23:26.721553 [DEBUG] switch_ivr_bridge.c:637 sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> CUSTOM RESET</div>
<div>2009-10-22 17:23:26.721553 [DEBUG] switch_core_state_machine.c:67 sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> Standard RESET</div><div>2009-10-22 17:23:26.721553 [DEBUG] switch_core_state_machine.c:336 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) State RESET going to sleep</div>
<div>2009-10-22 17:23:26.721553 [DEBUG] switch_core_state_machine.c:340 (sofia/external/14159927717) State EXECUTE going to sleep</div><div>2009-10-22 17:23:26.721553 [DEBUG] switch_core_state_machine.c:306 (sofia/external/14159927717) Running State Change CS_HIBERNATE</div>
<div>2009-10-22 17:23:26.721553 [DEBUG] switch_core_state_machine.c:355 (sofia/external/14159927717) State HIBERNATE</div><div>2009-10-22 17:23:26.721553 [DEBUG] mod_sofia.c:160 sofia/external/14159927717 SOFIA HIBERNATE</div>
<div>2009-10-22 17:23:26.721553 [DEBUG] switch_ivr_bridge.c:650 (sofia/external/14159927717) State Change CS_HIBERNATE -> CS_RESET</div><div>2009-10-22 17:23:26.721553 [DEBUG] switch_core_session.c:932 Send signal sofia/external/14159927717 [BREAK]</div>
<div>2009-10-22 17:23:26.721553 [DEBUG] switch_core_state_machine.c:355 (sofia/external/14159927717) State HIBERNATE going to sleep</div><div>2009-10-22 17:23:26.721553 [DEBUG] switch_core_state_machine.c:306 (sofia/external/14159927717) Running State Change CS_RESET</div>
<div>2009-10-22 17:23:26.721553 [DEBUG] switch_core_state_machine.c:336 (sofia/external/14159927717) State RESET</div><div>2009-10-22 17:23:26.721553 [DEBUG] mod_sofia.c:145 sofia/external/14159927717 SOFIA RESET</div><div>
2009-10-22 17:23:26.721553 [DEBUG] switch_ivr_bridge.c:637 sofia/external/14159927717 CUSTOM RESET</div><div>2009-10-22 17:23:26.721553 [DEBUG] switch_ivr_bridge.c:642 (sofia/external/14159927717) State Change CS_RESET -> CS_SOFT_EXECUTE</div>
<div>2009-10-22 17:23:26.721553 [DEBUG] switch_core_session.c:932 Send signal sofia/external/14159927717 [BREAK]</div><div>2009-10-22 17:23:26.721553 [DEBUG] switch_core_state_machine.c:336 (sofia/external/14159927717) State RESET going to sleep</div>
<div>2009-10-22 17:23:26.721553 [DEBUG] switch_core_state_machine.c:306 (sofia/external/14159927717) Running State Change CS_SOFT_EXECUTE</div><div>2009-10-22 17:23:26.721553 [DEBUG] switch_core_state_machine.c:346 (sofia/external/14159927717) State SOFT_EXECUTE</div>
<div>2009-10-22 17:23:26.721553 [DEBUG] mod_sofia.c:442 SOFIA TRANSMIT</div><div>2009-10-22 17:23:26.721553 [DEBUG] switch_ivr_bridge.c:660 sofia/external/14159927717 CUSTOM SOFT_EXECUTE</div><div>2009-10-22 17:23:26.721553 [DEBUG] switch_ivr_originate.c:674 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) State Change CS_RESET -> CS_SOFT_EXECUTE</div>
<div>2009-10-22 17:23:26.721553 [DEBUG] switch_core_session.c:932 Send signal sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> [BREAK]</div><div>2009-10-22 17:23:26.721553 [DEBUG] switch_ivr_bridge.c:120 sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> receive message [DISPLAY]</div>
<div>2009-10-22 17:23:26.721553 [DEBUG] switch_ivr_bridge.c:120 sofia/external/14159927717 receive message [DISPLAY]</div><div>2009-10-22 17:23:26.721553 [DEBUG] switch_ivr_bridge.c:924 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) State Change CS_SOFT_EXECUTE -> CS_CONSUME_MEDIA</div>
<div>2009-10-22 17:23:26.721553 [DEBUG] switch_core_session.c:932 Send signal sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> [BREAK]</div><div>2009-10-22 17:23:26.721553 [DEBUG] switch_ivr_bridge.c:968 sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> receive message [BRIDGE]</div>
<div>2009-10-22 17:23:26.721553 [DEBUG] switch_core_session.c:630 Send signal sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> [BREAK]</div><div>2009-10-22 17:23:26.721553 [DEBUG] switch_ivr_bridge.c:975 sofia/external/14159927717 receive message [BRIDGE]</div>
<div>2009-10-22 17:23:26.721553 [DEBUG] switch_core_session.c:630 Send signal sofia/external/14159927717 [BREAK]</div><div>2009-10-22 17:23:26.721553 [DEBUG] switch_ivr_bridge.c:1019 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA</div>
<div>2009-10-22 17:23:26.721553 [DEBUG] switch_core_session.c:932 Send signal sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> [BREAK]</div><div>2009-10-22 17:23:26.721553 [DEBUG] switch_core_state_machine.c:306 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) Running State Change CS_EXCHANGE_MEDIA</div>
<div>2009-10-22 17:23:26.721553 [DEBUG] switch_core_state_machine.c:343 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) State EXCHANGE_MEDIA</div><div>
2009-10-22 17:23:26.721553 [DEBUG] mod_sofia.c:436 SOFIA LOOPBACK</div>
<div>2009-10-22 17:23:26.740065 [DEBUG] switch_core_io.c:234 sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> receive message [TRANSCODING_NECESSARY]</div>
<div>2009-10-22 17:23:27.319618 [DEBUG] switch_core_media_bug.c:393 Removing BUG from sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a></div>
<div>multipler is being overrided to1</div><div>2009-10-22 17:23:29.406414 [INFO] switch_cpp.cpp:1116 BG_AG_GEN_CALLS, Agents total:1 avail:0 OutChan:0 AR:0 +-Calls:0:Q Call NYNoc</div><div>2009-10-22 17:23:31.873352 [NOTICE] mod_commands.c:1523 Hangup sofia/external/14159927717 [CS_SOFT_EXECUTE] [NORMAL_CLEARING]</div>
<div>2009-10-22 17:23:31.874411 [INFO] switch_cpp.cpp:1116 EVENTC_HANGUP_CONTACT, Q_CONTACT fired for agent:1/1 for qcall:1 contact:4801439/1</div><div>active_true=1</div><div>--contact NOT in playmsg or conference, update AGENT</div>
<div>2009-10-22 17:23:31.875475 [DEBUG] switch_channel.c:1810 Send signal sofia/external/14159927717 [KILL]</div><div>2009-10-22 17:23:31.875475 [DEBUG] switch_core_session.c:932 Send signal sofia/external/14159927717 [BREAK]</div>
<div>2009-10-22 17:23:31.875475 [DEBUG] switch_core_state_machine.c:437 thread mismatch skipping state handler.</div><div>2009-10-22 17:23:31.882153 [DEBUG] switch_ivr_bridge.c:446 sofia/external/14159927717 ending bridge by request from read function</div>
<div>2009-10-22 17:23:31.882153 [DEBUG] switch_ivr_bridge.c:521 BRIDGE THREAD DONE [sofia/external/14159927717]</div><div>2009-10-22 17:23:31.882153 [DEBUG] switch_ivr_bridge.c:523 Send signal sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> [BREAK]</div>
<div>2009-10-22 17:23:31.882153 [DEBUG] switch_ivr_bridge.c:495 sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> receive message [UNBRIDGE]</div><div>2009-10-22 17:23:31.882153 [DEBUG] switch_core_session.c:630 Send signal sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> [BREAK]</div>
<div>2009-10-22 17:23:31.882153 [DEBUG] switch_ivr_bridge.c:521 BRIDGE THREAD DONE [sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>]</div><div>2009-10-22 17:23:31.882153 [DEBUG] switch_ivr_bridge.c:523 Send signal sofia/external/14159927717 [BREAK]</div>
<div>2009-10-22 17:23:31.882153 [NOTICE] switch_ivr.c:1268 Hangup sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> [CS_EXCHANGE_MEDIA] [EXCHANGE_ROUTING_ERROR]</div>
<div>
2009-10-22 17:23:31.882153 [DEBUG] switch_channel.c:1810 Send signal sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> [KILL]</div><div>2009-10-22 17:23:31.882153 [DEBUG] switch_core_session.c:932 Send signal sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> [BREAK]</div>
<div>2009-10-22 17:23:31.882153 [DEBUG] switch_core_state_machine.c:464 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) State HANGUP</div><div>2009-10-22 17:23:31.882153 [DEBUG] mod_sofia.c:344 Channel sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> hanging up, cause: EXCHANGE_ROUTING_ERROR</div>
<div>2009-10-22 17:23:31.882153 [DEBUG] mod_sofia.c:382 Sending BYE to sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a></div><div>2009-10-22 17:23:31.882153 [DEBUG] switch_core_state_machine.c:46 sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> Standard HANGUP, cause: EXCHANGE_ROUTING_ERROR</div>
<div>2009-10-22 17:23:31.882153 [DEBUG] switch_core_state_machine.c:464 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) State HANGUP going to sleep</div>
<div>2009-10-22 17:23:31.884357 [INFO] switch_cpp.cpp:1116 HH_HANGUP, agent:1/1 uuid: f8604694-4e44-4c4c-9805-722b3e9d6b68</div>
<div>2009-10-22 17:23:31.885412 [DEBUG] switch_core_state_machine.c:346 (sofia/external/14159927717) State SOFT_EXECUTE going to sleep</div><div>2009-10-22 17:23:31.885412 [DEBUG] switch_core_state_machine.c:306 (sofia/external/14159927717) Running State Change CS_HANGUP</div>
<div>2009-10-22 17:23:31.885412 [DEBUG] switch_core_state_machine.c:464 (sofia/external/14159927717) State HANGUP</div><div>2009-10-22 17:23:31.885412 [DEBUG] mod_sofia.c:344 Channel sofia/external/14159927717 hanging up, cause: NORMAL_CLEARING</div>
<div>2009-10-22 17:23:31.885412 [DEBUG] mod_sofia.c:382 Sending BYE to sofia/external/14159927717</div><div>2009-10-22 17:23:31.885412 [DEBUG] switch_core_state_machine.c:46 sofia/external/14159927717 Standard HANGUP, cause: NORMAL_CLEARING</div>
<div>2009-10-22 17:23:31.885412 [DEBUG] switch_core_state_machine.c:464 (sofia/external/14159927717) State HANGUP going to sleep</div><div>2009-10-22 17:23:31.885412 [DEBUG] switch_core_state_machine.c:325 (sofia/external/14159927717) State Change CS_HANGUP -> CS_REPORTING</div>
<div>2009-10-22 17:23:31.885412 [DEBUG] switch_core_session.c:932 Send signal sofia/external/14159927717 [BREAK]</div><div>subtracting 5 seconds for hh_call: 1095423</div><div>2009-10-22 17:23:31.885412 [DEBUG] switch_core_state_machine.c:306 (sofia/external/14159927717) Running State Change CS_REPORTING</div>
<div>2009-10-22 17:23:31.885412 [DEBUG] switch_core_state_machine.c:555 (sofia/external/14159927717) State REPORTING</div><div>2009-10-22 17:23:31.885412 [DEBUG] switch_core_state_machine.c:53 sofia/external/14159927717 Standard REPORTING, cause: NORMAL_CLEARING</div>
<div>2009-10-22 17:23:31.885412 [DEBUG] switch_core_state_machine.c:555 (sofia/external/14159927717) State REPORTING going to sleep</div><div>2009-10-22 17:23:31.885412 [DEBUG] switch_core_state_machine.c:319 (sofia/external/14159927717) State Change CS_REPORTING -> CS_DESTROY</div>
<div>2009-10-22 17:23:31.885412 [DEBUG] switch_core_session.c:932 Send signal sofia/external/14159927717 [BREAK]</div><div>2009-10-22 17:23:31.885412 [DEBUG] switch_core_session.c:1069 Session 7 (sofia/external/14159927717) Locked, Waiting on external entities</div>
<div>2009-10-22 17:23:31.885412 [NOTICE] switch_core_session.c:1087 Session 7 (sofia/external/14159927717) Ended</div><div>2009-10-22 17:23:31.885412 [NOTICE] switch_core_session.c:1089 Close Channel sofia/external/14159927717 [CS_DESTROY]</div>
<div>2009-10-22 17:23:31.885412 [DEBUG] switch_core_state_machine.c:401 (sofia/external/14159927717) Running State Change CS_DESTROY</div><div>2009-10-22 17:23:31.885412 [DEBUG] switch_core_state_machine.c:412 (sofia/external/14159927717) State DESTROY</div>
<div>2009-10-22 17:23:31.885412 [DEBUG] mod_sofia.c:261 sofia/external/14159927717 SOFIA DESTROY</div><div>2009-10-22 17:23:31.885412 [DEBUG] switch_core_state_machine.c:60 sofia/external/14159927717 Standard DESTROY</div>
<div>2009-10-22 17:23:31.885412 [DEBUG] switch_core_state_machine.c:412 (sofia/external/14159927717) State DESTROY going to sleep</div><div>2009-10-22 17:23:31.885412 [INFO] switch_cpp.cpp:1116 PCHangup gw:<a href="http://teliax.com" target="_blank">teliax.com</a> hc:NORMAL_CLEARING du:5 cn:sofia/external/14159927717</div>
<div>2009-10-22 17:23:31.885412 [INFO] switch_cpp.cpp:1116 EVENTC_BILLING_CEIL, subtracting 5 for hh_call:1095423</div><div>2009-10-22 17:23:31.901620 [DEBUG] switch_core_state_machine.c:494 Hangup Command luarun(hh_hangup.lua f8604694-4e44-4c4c-9805-722b3e9d6b68 1 1):</div>
<div>+OK</div><div><br></div><div>2009-10-22 17:23:31.902676 [DEBUG] switch_core_state_machine.c:343 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) State EXCHANGE_MEDIA going to sleep</div>
<div>2009-10-22 17:23:31.902676 [DEBUG] switch_core_state_machine.c:306 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) Running State Change CS_HANGUP</div>
<div>2009-10-22 17:23:31.902676 [DEBUG] switch_core_state_machine.c:442 handler already called, skipping state handler.</div>
<div>2009-10-22 17:23:31.902676 [DEBUG] switch_core_state_machine.c:325 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) State Change CS_HANGUP -> CS_REPORTING</div>
<div>2009-10-22 17:23:31.902676 [DEBUG] switch_core_session.c:932 Send signal sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> [BREAK]</div><div>2009-10-22 17:23:31.902676 [DEBUG] switch_core_state_machine.c:306 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) Running State Change CS_REPORTING</div>
<div>2009-10-22 17:23:31.903742 [DEBUG] switch_core_state_machine.c:555 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) State REPORTING</div><div>2009-10-22 17:23:31.903742 [DEBUG] switch_core_state_machine.c:53 sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> Standard REPORTING, cause: EXCHANGE_ROUTING_ERROR</div>
<div>2009-10-22 17:23:31.903742 [DEBUG] switch_core_state_machine.c:555 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) State REPORTING going to sleep</div>
<div>2009-10-22 17:23:31.903742 [DEBUG] switch_core_state_machine.c:319 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) State Change CS_REPORTING -> CS_DESTROY</div>
<div>2009-10-22 17:23:31.903742 [DEBUG] switch_core_session.c:932 Send signal sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> [BREAK]</div><div>2009-10-22 17:23:31.903742 [DEBUG] switch_core_session.c:1069 Session 4 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) Locked, Waiting on external entities</div>
<div>2009-10-22 17:23:31.903742 [NOTICE] switch_core_session.c:1087 Session 4 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) Ended</div><div>2009-10-22 17:23:31.903742 [NOTICE] switch_core_session.c:1089 Close Channel sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> [CS_DESTROY]</div>
<div>2009-10-22 17:23:31.903742 [DEBUG] switch_core_state_machine.c:401 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) Running State Change CS_DESTROY</div>
<div>2009-10-22 17:23:31.904799 [DEBUG] switch_core_state_machine.c:412 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) State DESTROY</div>
<div>2009-10-22 17:23:31.904799 [DEBUG] mod_sofia.c:261 sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> SOFIA DESTROY</div><div>2009-10-22 17:23:31.904799 [DEBUG] switch_core_state_machine.c:60 sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a> Standard DESTROY</div>
<div>2009-10-22 17:23:31.904799 [DEBUG] switch_core_state_machine.c:412 (sofia/external/+<a href="http://14158867717@199.173.100.16:5060" target="_blank">14158867717@199.173.100.16:5060</a>) State DESTROY going to sleep</div>
<div><br></div>
</div><div><br><br><div class="gmail_quote"><div><div></div><div class="h5">On Tue, Oct 20, 2009 at 1:44 AM, Michael Collins <span dir="ltr"><<a href="mailto:msc@freeswitch.org" target="_blank">msc@freeswitch.org</a>></span> wrote:<br>
</div></div><blockquote class="gmail_quote" style="border-left: 1px solid rgb(204, 204, 204); margin: 0pt 0pt 0pt 0.8ex; padding-left: 1ex;"><div><div></div><div class="h5">
<br><br><div class="gmail_quote"><div>On Sun, Oct 18, 2009 at 9:48 PM, Matthew Fong <span dir="ltr"><<a href="mailto:mattdfong@gmail.com" target="_blank">mattdfong@gmail.com</a>></span> wrote:<br><blockquote class="gmail_quote" style="border-left: 1px solid rgb(204, 204, 204); margin: 0pt 0pt 0pt 0.8ex; padding-left: 1ex;">
<div><div>The debug level logs to the console, right? The pastebin, had log level debug, sofia trace on for external and default, and sofia loglevel all 9. Is there another log enable command I'm missing? It seems loglevel all 9 outputs enter and exit functions, but at least to my novice eye, it's not too obvious why freeswitch is sending a BYE to my DID provider.</div>
<div><br></div></div></blockquote></div><div>Evidently the console loglevel debug did not take because there isn't a single DEBUG level line of output. Please try again. Just remember that debug level log messages will have A LOT of information so be patient while sifting through it. Of course, feel free to pastebin the output and add a link to it in this email thread.<br>
<br>-MC<br><br></div></div>
<br></div></div><div class="im">_______________________________________________<br>
FreeSWITCH-users mailing list<br>
<a href="mailto:FreeSWITCH-users@lists.freeswitch.org" target="_blank">FreeSWITCH-users@lists.freeswitch.org</a><br>
<a href="http://lists.freeswitch.org/mailman/listinfo/freeswitch-users" target="_blank">http://lists.freeswitch.org/mailman/listinfo/freeswitch-users</a><br>
UNSUBSCRIBE:<a href="http://lists.freeswitch.org/mailman/options/freeswitch-users" target="_blank">http://lists.freeswitch.org/mailman/options/freeswitch-users</a><br>
<a href="http://www.freeswitch.org" target="_blank">http://www.freeswitch.org</a><br>
<br></div></blockquote></div><br></div>
<br>_______________________________________________<br>
FreeSWITCH-users mailing list<br>
<a href="mailto:FreeSWITCH-users@lists.freeswitch.org">FreeSWITCH-users@lists.freeswitch.org</a><br>
<a href="http://lists.freeswitch.org/mailman/listinfo/freeswitch-users" target="_blank">http://lists.freeswitch.org/mailman/listinfo/freeswitch-users</a><br>
UNSUBSCRIBE:<a href="http://lists.freeswitch.org/mailman/options/freeswitch-users" target="_blank">http://lists.freeswitch.org/mailman/options/freeswitch-users</a><br>
<a href="http://www.freeswitch.org" target="_blank">http://www.freeswitch.org</a><br>
<br></blockquote></div><br><br clear="all"><br>-- <br>Anthony Minessale II<br><br>FreeSWITCH <a href="http://www.freeswitch.org/">http://www.freeswitch.org/</a><br>ClueCon <a href="http://www.cluecon.com/">http://www.cluecon.com/</a><br>
Twitter: <a href="http://twitter.com/FreeSWITCH_wire">http://twitter.com/FreeSWITCH_wire</a><br><br>AIM: anthm<br><a href="mailto:MSN%3Aanthony_minessale@hotmail.com">MSN:anthony_minessale@hotmail.com</a><br>GTALK/JABBER/<a href="mailto:PAYPAL%3Aanthony.minessale@gmail.com">PAYPAL:anthony.minessale@gmail.com</a><br>
IRC: <a href="http://irc.freenode.net">irc.freenode.net</a> #freeswitch<br><br>FreeSWITCH Developer Conference<br><a href="mailto:sip%3A888@conference.freeswitch.org">sip:888@conference.freeswitch.org</a><br><a href="http://iax:guest@conference.freeswitch.org/888">iax:guest@conference.freeswitch.org/888</a><br>
<a href="mailto:googletalk%3Aconf%2B888@conference.freeswitch.org">googletalk:conf+888@conference.freeswitch.org</a><br>pstn:213-799-1400<br>