<!DOCTYPE HTML PUBLIC "-//W3C//DTD HTML 4.0 Transitional//EN">
<HTML><HEAD>
<META content="text/html; charset=iso-8859-1" http-equiv=Content-Type>
<META name=GENERATOR content="MSHTML 8.00.6001.19019">
<STYLE></STYLE>
</HEAD>
<BODY bgColor=#ffffff>
<DIV><FONT size=2>I tried the command in a cluster environment</FONT></DIV>
<DIV><FONT size=2>so 9999 can be on node1 and 7777 on node2</FONT></DIV>
<DIV><FONT size=2></FONT> </DIV>
<DIV><FONT size=2>here is the log</FONT></DIV>
<DIV><FONT size=2></FONT> </DIV>
<DIV><FONT size=1>
<P>2011-04-01 19:54:45.192621 [DEBUG] switch_ivr_originate.c:1973 variable
string 0 = [presence_id=9999@boophone.com]<BR>2011-04-01 19:54:45.193638
[NOTICE] switch_channel.c:812 New Channel
sofia/internal/sip:9999@192.168.0.18:58251
[43cf3947-e9b3-4828-befc-e284dc4a9e3a]<BR>2011-04-01 19:54:45.193638 [DEBUG]
mod_sofia.c:4286 (sofia/internal/sip:9999@192.168.0.18:58251) State Change
CS_NEW -> CS_INIT<BR>2011-04-01 19:54:45.193638 [DEBUG]
switch_core_session.c:1116 Send signal
sofia/internal/sip:9999@192.168.0.18:58251 [BREAK]<BR>2011-04-01 19:54:45.193638
[DEBUG] switch_core_state_machine.c:320
(sofia/internal/sip:9999@192.168.0.18:58251) Running State Change
CS_INIT<BR>2011-04-01 19:54:45.193638 [DEBUG] switch_core_state_machine.c:356
(sofia/internal/sip:9999@192.168.0.18:58251) State INIT<BR>2011-04-01
19:54:45.193638 [DEBUG] mod_sofia.c:84
sofia/internal/sip:9999@192.168.0.18:58251 SOFIA INIT<BR>2011-04-01
19:54:45.194651 [DEBUG] mod_sofia.c:124
(sofia/internal/sip:9999@192.168.0.18:58251) State Change CS_INIT ->
CS_ROUTING<BR>2011-04-01 19:54:45.194651 [DEBUG] switch_core_session.c:1116 Send
signal sofia/internal/sip:9999@192.168.0.18:58251 [BREAK]<BR>2011-04-01
19:54:45.194651 [DEBUG] switch_core_state_machine.c:356
(sofia/internal/sip:9999@192.168.0.18:58251) State INIT going to
sleep<BR>2011-04-01 19:54:45.194651 [DEBUG] switch_core_state_machine.c:320
(sofia/internal/sip:9999@192.168.0.18:58251) Running State Change
CS_ROUTING<BR>2011-04-01 19:54:45.194651 [DEBUG] switch_channel.c:1668
(sofia/internal/sip:9999@192.168.0.18:58251) Callstate Change DOWN ->
RINGING<BR>2011-04-01 19:54:45.194651 [DEBUG] switch_core_state_machine.c:359
(sofia/internal/sip:9999@192.168.0.18:58251) State ROUTING<BR>2011-04-01
19:54:45.194651 [DEBUG] mod_sofia.c:147
sofia/internal/sip:9999@192.168.0.18:58251 SOFIA ROUTING<BR>2011-04-01
19:54:45.194651 [DEBUG] switch_ivr_originate.c:66
(sofia/internal/sip:9999@192.168.0.18:58251) State Change CS_ROUTING ->
CS_CONSUME_MEDIA<BR>2011-04-01 19:54:45.194651 [DEBUG]
switch_core_session.c:1116 Send signal
sofia/internal/sip:9999@192.168.0.18:58251 [BREAK]<BR>2011-04-01 19:54:45.194651
[DEBUG] switch_core_state_machine.c:359
(sofia/internal/sip:9999@192.168.0.18:58251) State ROUTING going to
sleep<BR>2011-04-01 19:54:45.194651 [DEBUG] switch_core_state_machine.c:320
(sofia/internal/sip:9999@192.168.0.18:58251) Running State Change
CS_CONSUME_MEDIA<BR>2011-04-01 19:54:45.194651 [DEBUG]
switch_core_state_machine.c:378 (sofia/internal/sip:9999@192.168.0.18:58251)
State CONSUME_MEDIA<BR>2011-04-01 19:54:45.194651 [DEBUG]
switch_core_state_machine.c:378 (sofia/internal/sip:9999@192.168.0.18:58251)
State CONSUME_MEDIA going to sleep<BR>2011-04-01 19:54:45.195670 [DEBUG]
sofia.c:4754 Channel sofia/internal/sip:9999@192.168.0.18:58251 entering state
[calling][0]<BR>2011-04-01 19:54:48.856682 [DEBUG] mod_nibblebill.c:572 Received
request via SESSION_HEARTBEAT!<BR>2011-04-01 19:54:48.856682 [DEBUG]
mod_nibblebill.c:433 Attempting to bill at $0.03 per minute to account
9999<BR>2011-04-01 19:54:48.856682 [DEBUG] mod_nibblebill.c:491 60 seconds
passed since last bill time of 2011-04-01 19:53:48<BR>2011-04-01 19:54:48.856682
[DEBUG] mod_nibblebill.c:498 Billing $0.030010 to 9999 (Call:
704fa1fc-7b97-40b3-ad16-b5fc9da6d667 / 0.134583 so far)<BR>2011-04-01
19:54:48.856682 [DEBUG] mod_nibblebill.c:321 Doing update query<BR>[UPDATE
accounts SET cash=cash-0.030010 WHERE id='9999']<BR>2011-04-01 19:54:48.944981
[DEBUG] mod_nibblebill.c:366 Doing lookup query<BR>[SELECT cash AS
nibble_balance FROM accounts WHERE id='9999']<BR>2011-04-01 19:54:48.951074
[DEBUG] mod_nibblebill.c:376 Retrieved current balance for account 9999 (balance
= 10.105113)<BR>2011-04-01 19:55:01.857456 [DEBUG] mod_nibblebill.c:572 Received
request via SESSION_HEARTBEAT!<BR>2011-04-01 19:55:01.857456 [DEBUG]
mod_nibblebill.c:433 Attempting to bill at $0.03 per minute to account
9999<BR>2011-04-01 19:55:01.857456 [DEBUG] mod_nibblebill.c:491 60 seconds
passed since last bill time of 2011-04-01 19:54:01<BR>2011-04-01 19:55:01.857456
[DEBUG] mod_nibblebill.c:498 Billing $0.030010 to 9999 (Call:
2879366a-0478-4480-9977-8b22847be252 / 0.150150 so far)<BR>2011-04-01
19:55:01.857456 [DEBUG] mod_nibblebill.c:321 Doing update query<BR>[UPDATE
accounts SET cash=cash-0.030010 WHERE id='9999']<BR>2011-04-01 19:55:01.943873
[DEBUG] mod_nibblebill.c:366 Doing lookup query<BR>[SELECT cash AS
nibble_balance FROM accounts WHERE id='9999']<BR>2011-04-01 19:55:01.949965
[DEBUG] mod_nibblebill.c:376 Retrieved current balance for account 9999 (balance
= 10.075103)<BR>2011-04-01 19:55:17.196147 [DEBUG] sofia.c:4754 Channel
sofia/internal/sip:9999@192.168.0.18:58251 entering state
[terminated][408]<BR>2011-04-01 19:55:17.196147 [DEBUG] switch_channel.c:2563
(sofia/internal/sip:9999@192.168.0.18:58251) Callstate Change RINGING ->
HANGUP<BR>2011-04-01 19:55:17.196147 [NOTICE] sofia.c:5394 Hangup
sofia/internal/sip:9999@192.168.0.18:58251 [CS_CONSUME_MEDIA]
[RECOVERY_ON_TIMER_EXPIRE]<BR>2011-04-01 19:55:17.196147 [DEBUG]
switch_channel.c:2579 Send signal sofia/internal/sip:9999@192.168.0.18:58251
[KILL]<BR>2011-04-01 19:55:17.196147 [DEBUG] switch_core_session.c:1116 Send
signal sofia/internal/sip:9999@192.168.0.18:58251 [BREAK]<BR>2011-04-01
19:55:17.196147 [DEBUG] switch_core_state_machine.c:320
(sofia/internal/sip:9999@192.168.0.18:58251) Running State Change
CS_HANGUP<BR>2011-04-01 19:55:17.196147 [DEBUG] switch_core_state_machine.c:560
(sofia/internal/sip:9999@192.168.0.18:58251) State HANGUP<BR>2011-04-01
19:55:17.196147 [DEBUG] mod_sofia.c:451
sofia/internal/sip:9999@192.168.0.18:58251 Overriding SIP cause 504 with 408
from the other leg<BR>2011-04-01 19:55:17.196147 [DEBUG] mod_sofia.c:457 Channel
sofia/internal/sip:9999@192.168.0.18:58251 hanging up, cause:
RECOVERY_ON_TIMER_EXPIRE<BR>2011-04-01 19:55:17.204366 [DEBUG]
switch_core_state_machine.c:46 sofia/internal/sip:9999@192.168.0.18:58251
Standard HANGUP, cause: RECOVERY_ON_TIMER_EXPIRE<BR>2011-04-01 19:55:17.204366
[DEBUG] switch_core_state_machine.c:560
(sofia/internal/sip:9999@192.168.0.18:58251) State HANGUP going to
sleep<BR>2011-04-01 19:55:17.204366 [DEBUG] switch_core_state_machine.c:351
(sofia/internal/sip:9999@192.168.0.18:58251) State Change CS_HANGUP ->
CS_REPORTING<BR>2011-04-01 19:55:17.204366 [DEBUG] switch_core_session.c:1116
Send signal sofia/internal/sip:9999@192.168.0.18:58251 [BREAK]<BR>2011-04-01
19:55:17.204366 [DEBUG] switch_core_state_machine.c:320
(sofia/internal/sip:9999@192.168.0.18:58251) Running State Change
CS_REPORTING<BR>2011-04-01 19:55:17.204366 [DEBUG]
switch_core_state_machine.c:620 (sofia/internal/sip:9999@192.168.0.18:58251)
State REPORTING<BR>2011-04-01 19:55:17.204366 [DEBUG]
switch_core_state_machine.c:53 sofia/internal/sip:9999@192.168.0.18:58251
Standard REPORTING, cause: RECOVERY_ON_TIMER_EXPIRE<BR>2011-04-01
19:55:17.204366 [DEBUG] switch_core_state_machine.c:620
(sofia/internal/sip:9999@192.168.0.18:58251) State REPORTING going to
sleep<BR>2011-04-01 19:55:17.204366 [DEBUG] switch_core_state_machine.c:345
(sofia/internal/sip:9999@192.168.0.18:58251) State Change CS_REPORTING ->
CS_DESTROY<BR>2011-04-01 19:55:17.204366 [DEBUG] switch_core_session.c:1116 Send
signal sofia/internal/sip:9999@192.168.0.18:58251 [BREAK]<BR>2011-04-01
19:55:17.204366 [DEBUG] switch_core_session.c:1288 Session 79
(sofia/internal/sip:9999@192.168.0.18:58251) Locked, Waiting on external
entities<BR>2011-04-01 19:55:17.206396 [DEBUG] switch_ivr_originate.c:3506
Originate Resulted in Error Cause: 102 [RECOVERY_ON_TIMER_EXPIRE]<BR>2011-04-01
19:55:17.206396 [ERR] switch_ivr_originate.c:2640 Cannot create outgoing channel
of type [user] cause: [RECOVERY_ON_TIMER_EXPIRE]<BR>2011-04-01 19:55:17.206396
[NOTICE] switch_core_session.c:1306 Session 79
(sofia/internal/sip:9999@192.168.0.18:58251) Ended<BR>2011-04-01 19:55:17.206396
[DEBUG] switch_ivr_originate.c:3506 Originate Resulted in Error Cause: 102
[RECOVERY_ON_TIMER_EXPIRE]<BR>2011-04-01 19:55:17.206396 [NOTICE]
switch_core_session.c:1308 Close Channel
sofia/internal/sip:9999@192.168.0.18:58251 [CS_DESTROY]<BR>2011-04-01
19:55:17.206396 [DEBUG] switch_core_state_machine.c:449
(sofia/internal/sip:9999@192.168.0.18:58251) Callstate Change HANGUP ->
DOWN<BR>2011-04-01 19:55:17.206396 [DEBUG] switch_core_state_machine.c:452
(sofia/internal/sip:9999@192.168.0.18:58251) Running State Change
CS_DESTROY<BR>2011-04-01 19:55:17.206396 [DEBUG] switch_core_state_machine.c:462
(sofia/internal/sip:9999@192.168.0.18:58251) State DESTROY<BR>2011-04-01
19:55:17.206396 [DEBUG] mod_sofia.c:362
sofia/internal/sip:9999@192.168.0.18:58251 SOFIA DESTROY<BR>2011-04-01
19:55:17.206396 [DEBUG] switch_core_state_machine.c:60
sofia/internal/sip:9999@192.168.0.18:58251 Standard DESTROY<BR>2011-04-01
19:55:17.206396 [DEBUG] switch_core_state_machine.c:462
(sofia/internal/sip:9999@192.168.0.18:58251) State DESTROY going to
sleep<BR><BR><BR><FONT size=2>thanks</FONT></P></FONT></DIV>
<BLOCKQUOTE
style="BORDER-LEFT: #000000 2px solid; PADDING-LEFT: 5px; PADDING-RIGHT: 0px; MARGIN-LEFT: 5px; MARGIN-RIGHT: 0px">
<DIV style="FONT: 10pt arial">----- Original Message ----- </DIV>
<DIV
style="FONT: 10pt arial; BACKGROUND: #e4e4e4; font-color: black"><B>From:</B>
<A title=steveayre@gmail.com href="mailto:steveayre@gmail.com">Steven Ayre</A>
</DIV>
<DIV style="FONT: 10pt arial"><B>To:</B> <A
title=freeswitch-users@lists.freeswitch.org
href="mailto:freeswitch-users@lists.freeswitch.org">FreeSWITCH Users Help</A>
</DIV>
<DIV style="FONT: 10pt arial"><B>Sent:</B> Friday, April 01, 2011 5:30
PM</DIV>
<DIV style="FONT: 10pt arial"><B>Subject:</B> Re: [Freeswitch-users] originate
from cli</DIV>
<DIV><BR></DIV>It can also be that the other side sent FS a reply saying that
*it* had timed out. siptrace will also show if that's the case.<BR><BR><BR>
<DIV class=gmail_quote>On 1 April 2011 22:29, Steven Ayre <SPAN dir=ltr><<A
href="mailto:steveayre@gmail.com">steveayre@gmail.com</A>></SPAN>
wrote:<BR>
<BLOCKQUOTE
style="BORDER-LEFT: rgb(204,204,204) 1px solid; MARGIN: 0pt 0pt 0pt 0.8ex; PADDING-LEFT: 1ex"
class=gmail_quote>It means FS sent a message and didn't get a reply (timed
out).<BR><BR>As anthm says, look at the siptrace - that'll show you what's
being sent / received.<BR><BR>-Steve<BR><BR><BR>
<DIV class=gmail_quote>
<DIV>
<DIV></DIV>
<DIV class=h5>On 1 April 2011 21:17, Madovsky <SPAN dir=ltr><<A
href="mailto:infos@madovsky.org"
target=_blank>infos@madovsky.org</A>></SPAN> wrote:<BR></DIV></DIV>
<BLOCKQUOTE
style="BORDER-LEFT: rgb(204,204,204) 1px solid; MARGIN: 0pt 0pt 0pt 0.8ex; PADDING-LEFT: 1ex"
class=gmail_quote>
<DIV>
<DIV></DIV>
<DIV class=h5>
<DIV bgcolor="#ffffff">
<DIV><FONT size=2>I make some test with originate from cli.</FONT></DIV>
<DIV><FONT size=2></FONT> </DIV>
<DIV><FONT size=2>/usr/local/freeswitch/bin/fs_cli -x "originate user/9999
7777 XML public"</FONT></DIV><FONT size=2>
<DIV>-ERR RECOVERY_ON_TIMER_EXPIRE</DIV>
<DIV> </DIV>
<DIV>9999 and 7777 are ready to receive calls and I don't have any
NAT.</DIV>
<DIV> </DIV>
<DIV>is anyone can explain what it means ?</DIV>
<DIV> </DIV>
<DIV>Thanks</DIV>
<DIV></DIV></FONT> </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
style="TEXT-ALIGN: left; PADDING-BOTTOM: 0px; LINE-HEIGHT: 130%; MARGIN-TOP: 0px; PADDING-LEFT: 0px; PADDING-RIGHT: 0px; WORD-WRAP: break-word; COLOR: black; MARGIN-LEFT: 0px; FONT-SIZE: 10px; OVERFLOW: hidden; PADDING-TOP: 0px"></DIV></BLOCKQUOTE></DIV><BR>
<DIV
style="Z-INDEX: 9999; POSITION: absolute; TEXT-ALIGN: left; PADDING-BOTTOM: 0px; LINE-HEIGHT: 130%; MARGIN-TOP: 0px; PADDING-LEFT: 0px; PADDING-RIGHT: 0px; WORD-WRAP: break-word; VISIBILITY: hidden; COLOR: black; MARGIN-LEFT: 0px; FONT-SIZE: 10px; OVERFLOW: hidden; PADDING-TOP: 0px; LEFT: -5000px"
id=avg_ls_inline_popup></DIV>
<P>
<HR>
<P></P>_______________________________________________<BR>FreeSWITCH-users
mailing
list<BR>FreeSWITCH-users@lists.freeswitch.org<BR>http://lists.freeswitch.org/mailman/listinfo/freeswitch-users<BR>UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users<BR>http://www.freeswitch.org<BR></BLOCKQUOTE></BODY></HTML>