I make some test with originate from cli. /usr/local/freeswitch/bin/fs_cli -x "originate user/9999 7777 XML public" -ERR RECOVERY_ON_TIMER_EXPIRE 9999 and 7777 are ready to receive calls and I don't have any NAT. is anyone can explain what it means ? Thanks
turn on the sip trace and look at the logs On Fri, Apr 1, 2011 at 3:17 PM, Madovsky <infos@madovsky.org> wrote:
I make some test with originate from cli.
/usr/local/freeswitch/bin/fs_cli -x "originate user/9999 7777 XML public" -ERR RECOVERY_ON_TIMER_EXPIRE
9999 and 7777 are ready to receive calls and I don't have any NAT.
is anyone can explain what it means ?
Thanks
_______________________________________________ FreeSWITCH-users mailing list 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
-- Anthony Minessale II FreeSWITCH http://www.freeswitch.org/ ClueCon http://www.cluecon.com/ Twitter: http://twitter.com/FreeSWITCH_wire AIM: anthm MSN:anthony_minessale@hotmail.com GTALK/JABBER/PAYPAL:anthony.minessale@gmail.com IRC: irc.freenode.net #freeswitch FreeSWITCH Developer Conference sip:888@conference.freeswitch.org googletalk:conf+888@conference.freeswitch.org pstn:+19193869900
It means FS sent a message and didn't get a reply (timed out). As anthm says, look at the siptrace - that'll show you what's being sent / received. -Steve On 1 April 2011 21:17, Madovsky <infos@madovsky.org> wrote:
I make some test with originate from cli.
/usr/local/freeswitch/bin/fs_cli -x "originate user/9999 7777 XML public" -ERR RECOVERY_ON_TIMER_EXPIRE
9999 and 7777 are ready to receive calls and I don't have any NAT.
is anyone can explain what it means ?
Thanks
_______________________________________________ FreeSWITCH-users mailing list 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
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. On 1 April 2011 22:29, Steven Ayre <steveayre@gmail.com> wrote:
It means FS sent a message and didn't get a reply (timed out).
As anthm says, look at the siptrace - that'll show you what's being sent / received.
-Steve
On 1 April 2011 21:17, Madovsky <infos@madovsky.org> wrote:
I make some test with originate from cli.
/usr/local/freeswitch/bin/fs_cli -x "originate user/9999 7777 XML public" -ERR RECOVERY_ON_TIMER_EXPIRE
9999 and 7777 are ready to receive calls and I don't have any NAT.
is anyone can explain what it means ?
Thanks
_______________________________________________ FreeSWITCH-users mailing list 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
I tried the command in a cluster environment so 9999 can be on node1 and 7777 on node2 here is the log 2011-04-01 19:54:45.192621 [DEBUG] switch_ivr_originate.c:1973 variable string 0 = [presence_id=9999@boophone.com] 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] 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 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] 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 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 2011-04-01 19:54:45.193638 [DEBUG] mod_sofia.c:84 sofia/internal/sip:9999@192.168.0.18:58251 SOFIA INIT 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 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] 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 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 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 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 2011-04-01 19:54:45.194651 [DEBUG] mod_sofia.c:147 sofia/internal/sip:9999@192.168.0.18:58251 SOFIA ROUTING 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 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] 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 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 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 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 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] 2011-04-01 19:54:48.856682 [DEBUG] mod_nibblebill.c:572 Received request via SESSION_HEARTBEAT! 2011-04-01 19:54:48.856682 [DEBUG] mod_nibblebill.c:433 Attempting to bill at $0.03 per minute to account 9999 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 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) 2011-04-01 19:54:48.856682 [DEBUG] mod_nibblebill.c:321 Doing update query [UPDATE accounts SET cash=cash-0.030010 WHERE id='9999'] 2011-04-01 19:54:48.944981 [DEBUG] mod_nibblebill.c:366 Doing lookup query [SELECT cash AS nibble_balance FROM accounts WHERE id='9999'] 2011-04-01 19:54:48.951074 [DEBUG] mod_nibblebill.c:376 Retrieved current balance for account 9999 (balance = 10.105113) 2011-04-01 19:55:01.857456 [DEBUG] mod_nibblebill.c:572 Received request via SESSION_HEARTBEAT! 2011-04-01 19:55:01.857456 [DEBUG] mod_nibblebill.c:433 Attempting to bill at $0.03 per minute to account 9999 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 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) 2011-04-01 19:55:01.857456 [DEBUG] mod_nibblebill.c:321 Doing update query [UPDATE accounts SET cash=cash-0.030010 WHERE id='9999'] 2011-04-01 19:55:01.943873 [DEBUG] mod_nibblebill.c:366 Doing lookup query [SELECT cash AS nibble_balance FROM accounts WHERE id='9999'] 2011-04-01 19:55:01.949965 [DEBUG] mod_nibblebill.c:376 Retrieved current balance for account 9999 (balance = 10.075103) 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] 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 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] 2011-04-01 19:55:17.196147 [DEBUG] switch_channel.c:2579 Send signal sofia/internal/sip:9999@192.168.0.18:58251 [KILL] 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] 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 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 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 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 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 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 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 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] 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 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 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 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 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 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] 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 2011-04-01 19:55:17.206396 [DEBUG] switch_ivr_originate.c:3506 Originate Resulted in Error Cause: 102 [RECOVERY_ON_TIMER_EXPIRE] 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] 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 2011-04-01 19:55:17.206396 [DEBUG] switch_ivr_originate.c:3506 Originate Resulted in Error Cause: 102 [RECOVERY_ON_TIMER_EXPIRE] 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] 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 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 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 2011-04-01 19:55:17.206396 [DEBUG] mod_sofia.c:362 sofia/internal/sip:9999@192.168.0.18:58251 SOFIA DESTROY 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 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 thanks ----- Original Message ----- From: Steven Ayre To: FreeSWITCH Users Help Sent: Friday, April 01, 2011 5:30 PM Subject: Re: [Freeswitch-users] originate from cli 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. On 1 April 2011 22:29, Steven Ayre <steveayre@gmail.com> wrote: It means FS sent a message and didn't get a reply (timed out). As anthm says, look at the siptrace - that'll show you what's being sent / received. -Steve On 1 April 2011 21:17, Madovsky <infos@madovsky.org> wrote: I make some test with originate from cli. /usr/local/freeswitch/bin/fs_cli -x "originate user/9999 7777 XML public" -ERR RECOVERY_ON_TIMER_EXPIRE 9999 and 7777 are ready to receive calls and I don't have any NAT. is anyone can explain what it means ? Thanks _______________________________________________ FreeSWITCH-users mailing list 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 ------------------------------------------------------------------------------ _______________________________________________ FreeSWITCH-users mailing list 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
sorry the sip trace : after this command: /usr/local/freeswitch/bin/fs_cli -x "originate user/9999999999 1111111111" ------------------------------------------------------------------------ 2011-04-01 23:33:39.673581 [DEBUG] sofia.c:4754 Channel sofia/internal/sip:99999999999@11.22.33.44:52767 entering state [calling][0] send 1385 bytes to udp/[11.22.33.44]:52767 at 03:33:40.673952: ------------------------------------------------------------------------ INVITE sip:99999999999@11.22.33.44:52767 SIP/2.0 Via: SIP/2.0/UDP 11.22.33.44:5080;rport;branch=z9hG4bK415Sta2FX5NrD Max-Forwards: 70 From: "" <sip:0000000000@11.22.33.44>;tag=S16mjZ06UgQSe To: <sip:99999999999@11.22.33.44:52767> Call-ID: 02ac42f6-92d1-4f3b-bc98-aa00d5f01af5 CSeq: 10521545 INVITE Contact: <sip:mod_sofia@11.22.33.44:5080> User-Agent: CiscoSystems-SIP-GW-UA Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, precondition, path, replaces Allow-Events: talk, hold, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Content-Type: application/sdp Content-Disposition: session Content-Length: 492 X-FS-Support: update_display Remote-Party-ID: <sip:0000000000@11.22.33.44>;party=calling;screen=yes;privacy=off v=0 o=FreeSWITCH 1301682069 1301682070 IN IP4 11.22.33.44 s=FreeSWITCH c=IN IP4 11.22.33.44 t=0 0 m=audio 33150 RTP/AVP 98 0 8 3 99 100 102 103 104 9 105 5 106 101 13 a=rtpmap:98 SPEEX/16000 a=rtpmap:99 G726-16/8000 a=rtpmap:100 G726-24/8000 a=rtpmap:102 G726-32/8000 a=rtpmap:103 G726-40/8000 a=rtpmap:104 G7221/16000 a=fmtp:104 bitrate=32000 a=rtpmap:105 iLBC/8000 a=fmtp:105 mode=20 a=rtpmap:106 L16/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 ------------------------------------------------------------------------ send 1385 bytes to udp/[11.22.33.44]:52767 at 03:33:42.673971: ------------------------------------------------------------------------ INVITE sip:99999999999@11.22.33.44:52767 SIP/2.0 Via: SIP/2.0/UDP 11.22.33.44:5080;rport;branch=z9hG4bK415Sta2FX5NrD Max-Forwards: 70 From: "" <sip:0000000000@11.22.33.44>;tag=S16mjZ06UgQSe To: <sip:99999999999@11.22.33.44:52767> Call-ID: 02ac42f6-92d1-4f3b-bc98-aa00d5f01af5 CSeq: 10521545 INVITE Contact: <sip:mod_sofia@11.22.33.44:5080> User-Agent: CiscoSystems-SIP-GW-UA Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, precondition, path, replaces Allow-Events: talk, hold, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Content-Type: application/sdp Content-Disposition: session Content-Length: 492 X-FS-Support: update_display Remote-Party-ID: <sip:0000000000@11.22.33.44>;party=calling;screen=yes;privacy=off v=0 o=FreeSWITCH 1301682069 1301682070 IN IP4 11.22.33.44 s=FreeSWITCH c=IN IP4 11.22.33.44 t=0 0 m=audio 33150 RTP/AVP 98 0 8 3 99 100 102 103 104 9 105 5 106 101 13 a=rtpmap:98 SPEEX/16000 a=rtpmap:99 G726-16/8000 a=rtpmap:100 G726-24/8000 a=rtpmap:102 G726-32/8000 a=rtpmap:103 G726-40/8000 a=rtpmap:104 G7221/16000 a=fmtp:104 bitrate=32000 a=rtpmap:105 iLBC/8000 a=fmtp:105 mode=20 a=rtpmap:106 L16/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 ------------------------------------------------------------------------ send 1385 bytes to udp/[11.22.33.44]:52767 at 03:33:46.673944: ------------------------------------------------------------------------ INVITE sip:99999999999@11.22.33.44:52767 SIP/2.0 Via: SIP/2.0/UDP 11.22.33.44:5080;rport;branch=z9hG4bK415Sta2FX5NrD Max-Forwards: 70 From: "" <sip:0000000000@11.22.33.44>;tag=S16mjZ06UgQSe To: <sip:99999999999@11.22.33.44:52767> Call-ID: 02ac42f6-92d1-4f3b-bc98-aa00d5f01af5 CSeq: 10521545 INVITE Contact: <sip:mod_sofia@11.22.33.44:5080> User-Agent: CiscoSystems-SIP-GW-UA Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, precondition, path, replaces Allow-Events: talk, hold, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Content-Type: application/sdp Content-Disposition: session Content-Length: 492 X-FS-Support: update_display Remote-Party-ID: <sip:0000000000@11.22.33.44>;party=calling;screen=yes;privacy=off v=0 o=FreeSWITCH 1301682069 1301682070 IN IP4 11.22.33.44 s=FreeSWITCH c=IN IP4 11.22.33.44 t=0 0 m=audio 33150 RTP/AVP 98 0 8 3 99 100 102 103 104 9 105 5 106 101 13 a=rtpmap:98 SPEEX/16000 a=rtpmap:99 G726-16/8000 a=rtpmap:100 G726-24/8000 a=rtpmap:102 G726-32/8000 a=rtpmap:103 G726-40/8000 a=rtpmap:104 G7221/16000 a=fmtp:104 bitrate=32000 a=rtpmap:105 iLBC/8000 a=fmtp:105 mode=20 a=rtpmap:106 L16/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 ------------------------------------------------------------------------ send 1385 bytes to udp/[11.22.33.44]:52767 at 03:33:54.673950: ------------------------------------------------------------------------ INVITE sip:99999999999@11.22.33.44:52767 SIP/2.0 Via: SIP/2.0/UDP 11.22.33.44:5080;rport;branch=z9hG4bK415Sta2FX5NrD Max-Forwards: 70 From: "" <sip:0000000000@11.22.33.44>;tag=S16mjZ06UgQSe To: <sip:99999999999@11.22.33.44:52767> Call-ID: 02ac42f6-92d1-4f3b-bc98-aa00d5f01af5 CSeq: 10521545 INVITE Contact: <sip:mod_sofia@11.22.33.44:5080> User-Agent: CiscoSystems-SIP-GW-UA Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, precondition, path, replaces Allow-Events: talk, hold, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Content-Type: application/sdp Content-Disposition: session Content-Length: 492 X-FS-Support: update_display Remote-Party-ID: <sip:0000000000@11.22.33.44>;party=calling;screen=yes;privacy=off v=0 o=FreeSWITCH 1301682069 1301682070 IN IP4 11.22.33.44 s=FreeSWITCH c=IN IP4 11.22.33.44 t=0 0 m=audio 33150 RTP/AVP 98 0 8 3 99 100 102 103 104 9 105 5 106 101 13 a=rtpmap:98 SPEEX/16000 a=rtpmap:99 G726-16/8000 a=rtpmap:100 G726-24/8000 a=rtpmap:102 G726-32/8000 a=rtpmap:103 G726-40/8000 a=rtpmap:104 G7221/16000 a=fmtp:104 bitrate=32000 a=rtpmap:105 iLBC/8000 a=fmtp:105 mode=20 a=rtpmap:106 L16/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 ------------------------------------------------------------------------ send 1385 bytes to udp/[11.22.33.44]:52767 at 03:34:10.674077: ------------------------------------------------------------------------ INVITE sip:99999999999@11.22.33.44:52767 SIP/2.0 Via: SIP/2.0/UDP 11.22.33.44:5080;rport;branch=z9hG4bK415Sta2FX5NrD Max-Forwards: 70 From: "" <sip:0000000000@11.22.33.44>;tag=S16mjZ06UgQSe To: <sip:99999999999@11.22.33.44:52767> Call-ID: 02ac42f6-92d1-4f3b-bc98-aa00d5f01af5 CSeq: 10521545 INVITE Contact: <sip:mod_sofia@11.22.33.44:5080> User-Agent: CiscoSystems-SIP-GW-UA Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, precondition, path, replaces Allow-Events: talk, hold, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Content-Type: application/sdp Content-Disposition: session Content-Length: 492 X-FS-Support: update_display Remote-Party-ID: <sip:0000000000@11.22.33.44>;party=calling;screen=yes;privacy=off v=0 o=FreeSWITCH 1301682069 1301682070 IN IP4 11.22.33.44 s=FreeSWITCH c=IN IP4 11.22.33.44 t=0 0 m=audio 33150 RTP/AVP 98 0 8 3 99 100 102 103 104 9 105 5 106 101 13 a=rtpmap:98 SPEEX/16000 a=rtpmap:99 G726-16/8000 a=rtpmap:100 G726-24/8000 a=rtpmap:102 G726-32/8000 a=rtpmap:103 G726-40/8000 a=rtpmap:104 G7221/16000 a=fmtp:104 bitrate=32000 a=rtpmap:105 iLBC/8000 a=fmtp:105 mode=20 a=rtpmap:106 L16/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 ------------------------------------------------------------------------ 2011-04-01 23:34:11.674022 [DEBUG] sofia.c:4754 Channel sofia/internal/sip:99999999999@11.22.33.44:52767 entering state [terminated][408] 2011-04-01 23:34:11.674022 [DEBUG] switch_channel.c:2563 (sofia/internal/sip:99999999999@11.22.33.44:52767) Callstate Change RINGING -> HANGUP 2011-04-01 23:34:11.674022 [NOTICE] sofia.c:5394 Hangup sofia/internal/sip:99999999999@11.22.33.44:52767 [CS_CONSUME_MEDIA] [RECOVERY_ON_TIMER_EXPIRE] 2011-04-01 23:34:11.674022 [DEBUG] switch_channel.c:2579 Send signal sofia/internal/sip:99999999999@11.22.33.44:52767 [KILL] 2011-04-01 23:34:11.674022 [DEBUG] switch_core_session.c:1116 Send signal sofia/internal/sip:99999999999@11.22.33.44:52767 [BREAK] 2011-04-01 23:34:11.674022 [DEBUG] switch_core_state_machine.c:320 (sofia/internal/sip:99999999999@11.22.33.44:52767) Running State Change CS_HANGUP 2011-04-01 23:34:11.674022 [DEBUG] switch_ivr_originate.c:3506 Originate Resulted in Error Cause: 102 [RECOVERY_ON_TIMER_EXPIRE] 2011-04-01 23:34:11.674022 [ERR] switch_ivr_originate.c:2640 Cannot create outgoing channel of type [user] cause: [RECOVERY_ON_TIMER_EXPIRE] 2011-04-01 23:34:11.674022 [DEBUG] switch_ivr_originate.c:3506 Originate Resulted in Error Cause: 102 [RECOVERY_ON_TIMER_EXPIRE] 2011-04-01 23:34:11.675057 [DEBUG] switch_core_state_machine.c:560 (sofia/internal/sip:99999999999@11.22.33.44:52767) State HANGUP 2011-04-01 23:34:11.675057 [DEBUG] mod_sofia.c:451 sofia/internal/sip:99999999999@11.22.33.44:52767 Overriding SIP cause 504 with 408 from the other leg 2011-04-01 23:34:11.675057 [DEBUG] mod_sofia.c:457 Channel sofia/internal/sip:99999999999@11.22.33.44:52767 hanging up, cause: RECOVERY_ON_TIMER_EXPIRE 2011-04-01 23:34:11.695371 [DEBUG] switch_core_state_machine.c:46 sofia/internal/sip:99999999999@11.22.33.44:52767 Standard HANGUP, cause: RECOVERY_ON_TIMER_EXPIRE 2011-04-01 23:34:11.695371 [DEBUG] switch_core_state_machine.c:560 (sofia/internal/sip:99999999999@11.22.33.44:52767) State HANGUP going to sleep 2011-04-01 23:34:11.695371 [DEBUG] switch_core_state_machine.c:351 (sofia/internal/sip:99999999999@11.22.33.44:52767) State Change CS_HANGUP -> CS_REPORTING 2011-04-01 23:34:11.695371 [DEBUG] switch_core_session.c:1116 Send signal sofia/internal/sip:99999999999@11.22.33.44:52767 [BREAK] 2011-04-01 23:34:11.695371 [DEBUG] switch_core_state_machine.c:320 (sofia/internal/sip:99999999999@11.22.33.44:52767) Running State Change CS_REPORTING 2011-04-01 23:34:11.695371 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/sip:99999999999@11.22.33.44:52767) State REPORTING 2011-04-01 23:34:11.695371 [DEBUG] switch_core_state_machine.c:53 sofia/internal/sip:99999999999@11.22.33.44:52767 Standard REPORTING, cause: RECOVERY_ON_TIMER_EXPIRE 2011-04-01 23:34:11.695371 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/sip:99999999999@11.22.33.44:52767) State REPORTING going to sleep 2011-04-01 23:34:11.695371 [DEBUG] switch_core_state_machine.c:345 (sofia/internal/sip:99999999999@11.22.33.44:52767) State Change CS_REPORTING -> CS_DESTROY 2011-04-01 23:34:11.695371 [DEBUG] switch_core_session.c:1116 Send signal sofia/internal/sip:99999999999@11.22.33.44:52767 [BREAK] 2011-04-01 23:34:11.695371 [DEBUG] switch_core_session.c:1288 Session 5 (sofia/internal/sip:99999999999@11.22.33.44:52767) Locked, Waiting on external entities 2011-04-01 23:34:11.695371 [NOTICE] switch_core_session.c:1306 Session 5 (sofia/internal/sip:99999999999@11.22.33.44:52767) Ended 2011-04-01 23:34:11.695371 [NOTICE] switch_core_session.c:1308 Close Channel sofia/internal/sip:99999999999@11.22.33.44:52767 [CS_DESTROY] 2011-04-01 23:34:11.696436 [DEBUG] switch_core_state_machine.c:449 (sofia/internal/sip:99999999999@11.22.33.44:52767) Callstate Change HANGUP -> DOWN 2011-04-01 23:34:11.696436 [DEBUG] switch_core_state_machine.c:452 (sofia/internal/sip:99999999999@11.22.33.44:52767) Running State Change CS_DESTROY 2011-04-01 23:34:11.696436 [DEBUG] switch_core_state_machine.c:462 (sofia/internal/sip:99999999999@11.22.33.44:52767) State DESTROY 2011-04-01 23:34:11.696436 [DEBUG] mod_sofia.c:362 sofia/internal/sip:99999999999@11.22.33.44:52767 SOFIA DESTROY 2011-04-01 23:34:11.696436 [DEBUG] switch_core_state_machine.c:60 sofia/internal/sip:99999999999@11.22.33.44:52767 Standard DESTROY 2011-04-01 23:34:11.696436 [DEBUG] switch_core_state_machine.c:462 (sofia/internal/sip:99999999999@11.22.33.44:52767) State DESTROY going to sleep a ----- Original Message ----- From: Steven Ayre To: FreeSWITCH Users Help Sent: Friday, April 01, 2011 5:30 PM Subject: Re: [Freeswitch-users] originate from cli 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. On 1 April 2011 22:29, Steven Ayre <steveayre@gmail.com> wrote: It means FS sent a message and didn't get a reply (timed out). As anthm says, look at the siptrace - that'll show you what's being sent / received. -Steve On 1 April 2011 21:17, Madovsky <infos@madovsky.org> wrote: I make some test with originate from cli. /usr/local/freeswitch/bin/fs_cli -x "originate user/9999 7777 XML public" -ERR RECOVERY_ON_TIMER_EXPIRE 9999 and 7777 are ready to receive calls and I don't have any NAT. is anyone can explain what it means ? Thanks _______________________________________________ FreeSWITCH-users mailing list 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 ------------------------------------------------------------------------------ _______________________________________________ FreeSWITCH-users mailing list 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
You never get the reply to the invite trace from the thing you are calling and see if they are getting any packets. Maybe you have iptables or selinux on on one host or the other. On Apr 1, 2011 10:37 PM, "Madovsky" <infos@madovsky.org> wrote:
sorry the sip trace : after this command:
/usr/local/freeswitch/bin/fs_cli -x "originate user/9999999999 1111111111"
------------------------------------------------------------------------ 2011-04-01 23:33:39.673581 [DEBUG] sofia.c:4754 Channel sofia/internal/ sip:99999999999@11.22.33.44:52767 entering state [calling][0] send 1385 bytes to udp/[11.22.33.44]:52767 at 03:33:40.673952: ------------------------------------------------------------------------ INVITE sip:99999999999@11.22.33.44:52767 SIP/2.0 Via: SIP/2.0/UDP 11.22.33.44:5080;rport;branch=z9hG4bK415Sta2FX5NrD Max-Forwards: 70 From: "" <sip:0000000000@11.22.33.44>;tag=S16mjZ06UgQSe To: <sip:99999999999@11.22.33.44:52767> Call-ID: 02ac42f6-92d1-4f3b-bc98-aa00d5f01af5 CSeq: 10521545 INVITE Contact: <sip:mod_sofia@11.22.33.44:5080> User-Agent: CiscoSystems-SIP-GW-UA Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, precondition, path, replaces Allow-Events: talk, hold, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Content-Type: application/sdp Content-Disposition: session Content-Length: 492 X-FS-Support: update_display Remote-Party-ID: <sip:0000000000@11.22.33.44 ;party=calling;screen=yes;privacy=off
v=0 o=FreeSWITCH 1301682069 1301682070 IN IP4 11.22.33.44 s=FreeSWITCH c=IN IP4 11.22.33.44 t=0 0 m=audio 33150 RTP/AVP 98 0 8 3 99 100 102 103 104 9 105 5 106 101 13 a=rtpmap:98 SPEEX/16000 a=rtpmap:99 G726-16/8000 a=rtpmap:100 G726-24/8000 a=rtpmap:102 G726-32/8000 a=rtpmap:103 G726-40/8000 a=rtpmap:104 G7221/16000 a=fmtp:104 bitrate=32000 a=rtpmap:105 iLBC/8000 a=fmtp:105 mode=20 a=rtpmap:106 L16/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 ------------------------------------------------------------------------ send 1385 bytes to udp/[11.22.33.44]:52767 at 03:33:42.673971: ------------------------------------------------------------------------ INVITE sip:99999999999@11.22.33.44:52767 SIP/2.0 Via: SIP/2.0/UDP 11.22.33.44:5080;rport;branch=z9hG4bK415Sta2FX5NrD Max-Forwards: 70 From: "" <sip:0000000000@11.22.33.44>;tag=S16mjZ06UgQSe To: <sip:99999999999@11.22.33.44:52767> Call-ID: 02ac42f6-92d1-4f3b-bc98-aa00d5f01af5 CSeq: 10521545 INVITE Contact: <sip:mod_sofia@11.22.33.44:5080> User-Agent: CiscoSystems-SIP-GW-UA Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, precondition, path, replaces Allow-Events: talk, hold, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Content-Type: application/sdp Content-Disposition: session Content-Length: 492 X-FS-Support: update_display Remote-Party-ID: <sip:0000000000@11.22.33.44 ;party=calling;screen=yes;privacy=off
v=0 o=FreeSWITCH 1301682069 1301682070 IN IP4 11.22.33.44 s=FreeSWITCH c=IN IP4 11.22.33.44 t=0 0 m=audio 33150 RTP/AVP 98 0 8 3 99 100 102 103 104 9 105 5 106 101 13 a=rtpmap:98 SPEEX/16000 a=rtpmap:99 G726-16/8000 a=rtpmap:100 G726-24/8000 a=rtpmap:102 G726-32/8000 a=rtpmap:103 G726-40/8000 a=rtpmap:104 G7221/16000 a=fmtp:104 bitrate=32000 a=rtpmap:105 iLBC/8000 a=fmtp:105 mode=20 a=rtpmap:106 L16/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 ------------------------------------------------------------------------ send 1385 bytes to udp/[11.22.33.44]:52767 at 03:33:46.673944: ------------------------------------------------------------------------ INVITE sip:99999999999@11.22.33.44:52767 SIP/2.0 Via: SIP/2.0/UDP 11.22.33.44:5080;rport;branch=z9hG4bK415Sta2FX5NrD Max-Forwards: 70 From: "" <sip:0000000000@11.22.33.44>;tag=S16mjZ06UgQSe To: <sip:99999999999@11.22.33.44:52767> Call-ID: 02ac42f6-92d1-4f3b-bc98-aa00d5f01af5 CSeq: 10521545 INVITE Contact: <sip:mod_sofia@11.22.33.44:5080> User-Agent: CiscoSystems-SIP-GW-UA Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, precondition, path, replaces Allow-Events: talk, hold, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Content-Type: application/sdp Content-Disposition: session Content-Length: 492 X-FS-Support: update_display Remote-Party-ID: <sip:0000000000@11.22.33.44 ;party=calling;screen=yes;privacy=off
v=0 o=FreeSWITCH 1301682069 1301682070 IN IP4 11.22.33.44 s=FreeSWITCH c=IN IP4 11.22.33.44 t=0 0 m=audio 33150 RTP/AVP 98 0 8 3 99 100 102 103 104 9 105 5 106 101 13 a=rtpmap:98 SPEEX/16000 a=rtpmap:99 G726-16/8000 a=rtpmap:100 G726-24/8000 a=rtpmap:102 G726-32/8000 a=rtpmap:103 G726-40/8000 a=rtpmap:104 G7221/16000 a=fmtp:104 bitrate=32000 a=rtpmap:105 iLBC/8000 a=fmtp:105 mode=20 a=rtpmap:106 L16/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 ------------------------------------------------------------------------ send 1385 bytes to udp/[11.22.33.44]:52767 at 03:33:54.673950: ------------------------------------------------------------------------ INVITE sip:99999999999@11.22.33.44:52767 SIP/2.0 Via: SIP/2.0/UDP 11.22.33.44:5080;rport;branch=z9hG4bK415Sta2FX5NrD Max-Forwards: 70 From: "" <sip:0000000000@11.22.33.44>;tag=S16mjZ06UgQSe To: <sip:99999999999@11.22.33.44:52767> Call-ID: 02ac42f6-92d1-4f3b-bc98-aa00d5f01af5 CSeq: 10521545 INVITE Contact: <sip:mod_sofia@11.22.33.44:5080> User-Agent: CiscoSystems-SIP-GW-UA Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, precondition, path, replaces Allow-Events: talk, hold, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Content-Type: application/sdp Content-Disposition: session Content-Length: 492 X-FS-Support: update_display Remote-Party-ID: <sip:0000000000@11.22.33.44 ;party=calling;screen=yes;privacy=off
v=0 o=FreeSWITCH 1301682069 1301682070 IN IP4 11.22.33.44 s=FreeSWITCH c=IN IP4 11.22.33.44 t=0 0 m=audio 33150 RTP/AVP 98 0 8 3 99 100 102 103 104 9 105 5 106 101 13 a=rtpmap:98 SPEEX/16000 a=rtpmap:99 G726-16/8000 a=rtpmap:100 G726-24/8000 a=rtpmap:102 G726-32/8000 a=rtpmap:103 G726-40/8000 a=rtpmap:104 G7221/16000 a=fmtp:104 bitrate=32000 a=rtpmap:105 iLBC/8000 a=fmtp:105 mode=20 a=rtpmap:106 L16/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 ------------------------------------------------------------------------ send 1385 bytes to udp/[11.22.33.44]:52767 at 03:34:10.674077: ------------------------------------------------------------------------ INVITE sip:99999999999@11.22.33.44:52767 SIP/2.0 Via: SIP/2.0/UDP 11.22.33.44:5080;rport;branch=z9hG4bK415Sta2FX5NrD Max-Forwards: 70 From: "" <sip:0000000000@11.22.33.44>;tag=S16mjZ06UgQSe To: <sip:99999999999@11.22.33.44:52767> Call-ID: 02ac42f6-92d1-4f3b-bc98-aa00d5f01af5 CSeq: 10521545 INVITE Contact: <sip:mod_sofia@11.22.33.44:5080> User-Agent: CiscoSystems-SIP-GW-UA Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, precondition, path, replaces Allow-Events: talk, hold, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Content-Type: application/sdp Content-Disposition: session Content-Length: 492 X-FS-Support: update_display Remote-Party-ID: <sip:0000000000@11.22.33.44 ;party=calling;screen=yes;privacy=off
v=0 o=FreeSWITCH 1301682069 1301682070 IN IP4 11.22.33.44 s=FreeSWITCH c=IN IP4 11.22.33.44 t=0 0 m=audio 33150 RTP/AVP 98 0 8 3 99 100 102 103 104 9 105 5 106 101 13 a=rtpmap:98 SPEEX/16000 a=rtpmap:99 G726-16/8000 a=rtpmap:100 G726-24/8000 a=rtpmap:102 G726-32/8000 a=rtpmap:103 G726-40/8000 a=rtpmap:104 G7221/16000 a=fmtp:104 bitrate=32000 a=rtpmap:105 iLBC/8000 a=fmtp:105 mode=20 a=rtpmap:106 L16/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 ------------------------------------------------------------------------ 2011-04-01 23:34:11.674022 [DEBUG] sofia.c:4754 Channel sofia/internal/ sip:99999999999@11.22.33.44:52767 entering state [terminated][408] 2011-04-01 23:34:11.674022 [DEBUG] switch_channel.c:2563 (sofia/internal/ sip:99999999999@11.22.33.44:52767) Callstate Change RINGING -> HANGUP 2011-04-01 23:34:11.674022 [NOTICE] sofia.c:5394 Hangup sofia/internal/ sip:99999999999@11.22.33.44:52767 [CS_CONSUME_MEDIA] [RECOVERY_ON_TIMER_EXPIRE] 2011-04-01 23:34:11.674022 [DEBUG] switch_channel.c:2579 Send signal sofia/internal/sip:99999999999@11.22.33.44:52767 [KILL] 2011-04-01 23:34:11.674022 [DEBUG] switch_core_session.c:1116 Send signal sofia/internal/sip:99999999999@11.22.33.44:52767 [BREAK] 2011-04-01 23:34:11.674022 [DEBUG] switch_core_state_machine.c:320 (sofia/internal/sip:99999999999@11.22.33.44:52767) Running State Change CS_HANGUP 2011-04-01 23:34:11.674022 [DEBUG] switch_ivr_originate.c:3506 Originate Resulted in Error Cause: 102 [RECOVERY_ON_TIMER_EXPIRE] 2011-04-01 23:34:11.674022 [ERR] switch_ivr_originate.c:2640 Cannot create outgoing channel of type [user] cause: [RECOVERY_ON_TIMER_EXPIRE] 2011-04-01 23:34:11.674022 [DEBUG] switch_ivr_originate.c:3506 Originate Resulted in Error Cause: 102 [RECOVERY_ON_TIMER_EXPIRE] 2011-04-01 23:34:11.675057 [DEBUG] switch_core_state_machine.c:560 (sofia/internal/sip:99999999999@11.22.33.44:52767) State HANGUP 2011-04-01 23:34:11.675057 [DEBUG] mod_sofia.c:451 sofia/internal/ sip:99999999999@11.22.33.44:52767 Overriding SIP cause 504 with 408 from the other leg 2011-04-01 23:34:11.675057 [DEBUG] mod_sofia.c:457 Channel sofia/internal/ sip:99999999999@11.22.33.44:52767 hanging up, cause: RECOVERY_ON_TIMER_EXPIRE 2011-04-01 23:34:11.695371 [DEBUG] switch_core_state_machine.c:46 sofia/internal/sip:99999999999@11.22.33.44:52767 Standard HANGUP, cause: RECOVERY_ON_TIMER_EXPIRE 2011-04-01 23:34:11.695371 [DEBUG] switch_core_state_machine.c:560 (sofia/internal/sip:99999999999@11.22.33.44:52767) State HANGUP going to sleep 2011-04-01 23:34:11.695371 [DEBUG] switch_core_state_machine.c:351 (sofia/internal/sip:99999999999@11.22.33.44:52767) State Change CS_HANGUP -> CS_REPORTING 2011-04-01 23:34:11.695371 [DEBUG] switch_core_session.c:1116 Send signal sofia/internal/sip:99999999999@11.22.33.44:52767 [BREAK] 2011-04-01 23:34:11.695371 [DEBUG] switch_core_state_machine.c:320 (sofia/internal/sip:99999999999@11.22.33.44:52767) Running State Change CS_REPORTING 2011-04-01 23:34:11.695371 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/sip:99999999999@11.22.33.44:52767) State REPORTING 2011-04-01 23:34:11.695371 [DEBUG] switch_core_state_machine.c:53 sofia/internal/sip:99999999999@11.22.33.44:52767 Standard REPORTING, cause: RECOVERY_ON_TIMER_EXPIRE 2011-04-01 23:34:11.695371 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/sip:99999999999@11.22.33.44:52767) State REPORTING going to sleep 2011-04-01 23:34:11.695371 [DEBUG] switch_core_state_machine.c:345 (sofia/internal/sip:99999999999@11.22.33.44:52767) State Change CS_REPORTING -> CS_DESTROY 2011-04-01 23:34:11.695371 [DEBUG] switch_core_session.c:1116 Send signal sofia/internal/sip:99999999999@11.22.33.44:52767 [BREAK] 2011-04-01 23:34:11.695371 [DEBUG] switch_core_session.c:1288 Session 5 (sofia/internal/sip:99999999999@11.22.33.44:52767) Locked, Waiting on external entities 2011-04-01 23:34:11.695371 [NOTICE] switch_core_session.c:1306 Session 5 (sofia/internal/sip:99999999999@11.22.33.44:52767) Ended 2011-04-01 23:34:11.695371 [NOTICE] switch_core_session.c:1308 Close Channel sofia/internal/sip:99999999999@11.22.33.44:52767 [CS_DESTROY] 2011-04-01 23:34:11.696436 [DEBUG] switch_core_state_machine.c:449 (sofia/internal/sip:99999999999@11.22.33.44:52767) Callstate Change HANGUP -> DOWN 2011-04-01 23:34:11.696436 [DEBUG] switch_core_state_machine.c:452 (sofia/internal/sip:99999999999@11.22.33.44:52767) Running State Change CS_DESTROY 2011-04-01 23:34:11.696436 [DEBUG] switch_core_state_machine.c:462 (sofia/internal/sip:99999999999@11.22.33.44:52767) State DESTROY 2011-04-01 23:34:11.696436 [DEBUG] mod_sofia.c:362 sofia/internal/ sip:99999999999@11.22.33.44:52767 SOFIA DESTROY 2011-04-01 23:34:11.696436 [DEBUG] switch_core_state_machine.c:60 sofia/internal/sip:99999999999@11.22.33.44:52767 Standard DESTROY 2011-04-01 23:34:11.696436 [DEBUG] switch_core_state_machine.c:462 (sofia/internal/sip:99999999999@11.22.33.44:52767) State DESTROY going to sleep
a
----- Original Message ----- From: Steven Ayre To: FreeSWITCH Users Help Sent: Friday, April 01, 2011 5:30 PM Subject: Re: [Freeswitch-users] originate from cli
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.
On 1 April 2011 22:29, Steven Ayre <steveayre@gmail.com> wrote:
It means FS sent a message and didn't get a reply (timed out).
As anthm says, look at the siptrace - that'll show you what's being sent / received.
-Steve
On 1 April 2011 21:17, Madovsky <infos@madovsky.org> wrote:
I make some test with originate from cli.
/usr/local/freeswitch/bin/fs_cli -x "originate user/9999 7777 XML public" -ERR RECOVERY_ON_TIMER_EXPIRE
9999 and 7777 are ready to receive calls and I don't have any NAT.
is anyone can explain what it means ?
Thanks
_______________________________________________ FreeSWITCH-users mailing list 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
------------------------------------------------------------------------------
_______________________________________________ FreeSWITCH-users mailing list 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
Ok I'm trying to figure it out thanks ----- Original Message ----- From: Anthony Minessale To: FreeSWITCH Users Help Sent: Saturday, April 02, 2011 3:34 PM Subject: Re: [Freeswitch-users] originate from cli You never get the reply to the invite trace from the thing you are calling and see if they are getting any packets. Maybe you have iptables or selinux on on one host or the other. On Apr 1, 2011 10:37 PM, "Madovsky" <infos@madovsky.org> wrote:
sorry the sip trace : after this command:
/usr/local/freeswitch/bin/fs_cli -x "originate user/9999999999 1111111111"
------------------------------------------------------------------------ 2011-04-01 23:33:39.673581 [DEBUG] sofia.c:4754 Channel sofia/internal/sip:99999999999@11.22.33.44:52767 entering state [calling][0] send 1385 bytes to udp/[11.22.33.44]:52767 at 03:33:40.673952: ------------------------------------------------------------------------ INVITE sip:99999999999@11.22.33.44:52767 SIP/2.0 Via: SIP/2.0/UDP 11.22.33.44:5080;rport;branch=z9hG4bK415Sta2FX5NrD Max-Forwards: 70 From: "" <sip:0000000000@11.22.33.44>;tag=S16mjZ06UgQSe To: <sip:99999999999@11.22.33.44:52767> Call-ID: 02ac42f6-92d1-4f3b-bc98-aa00d5f01af5 CSeq: 10521545 INVITE Contact: <sip:mod_sofia@11.22.33.44:5080> User-Agent: CiscoSystems-SIP-GW-UA Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, precondition, path, replaces Allow-Events: talk, hold, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Content-Type: application/sdp Content-Disposition: session Content-Length: 492 X-FS-Support: update_display Remote-Party-ID: <sip:0000000000@11.22.33.44>;party=calling;screen=yes;privacy=off
v=0 o=FreeSWITCH 1301682069 1301682070 IN IP4 11.22.33.44 s=FreeSWITCH c=IN IP4 11.22.33.44 t=0 0 m=audio 33150 RTP/AVP 98 0 8 3 99 100 102 103 104 9 105 5 106 101 13 a=rtpmap:98 SPEEX/16000 a=rtpmap:99 G726-16/8000 a=rtpmap:100 G726-24/8000 a=rtpmap:102 G726-32/8000 a=rtpmap:103 G726-40/8000 a=rtpmap:104 G7221/16000 a=fmtp:104 bitrate=32000 a=rtpmap:105 iLBC/8000 a=fmtp:105 mode=20 a=rtpmap:106 L16/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 ------------------------------------------------------------------------ send 1385 bytes to udp/[11.22.33.44]:52767 at 03:33:42.673971: ------------------------------------------------------------------------ INVITE sip:99999999999@11.22.33.44:52767 SIP/2.0 Via: SIP/2.0/UDP 11.22.33.44:5080;rport;branch=z9hG4bK415Sta2FX5NrD Max-Forwards: 70 From: "" <sip:0000000000@11.22.33.44>;tag=S16mjZ06UgQSe To: <sip:99999999999@11.22.33.44:52767> Call-ID: 02ac42f6-92d1-4f3b-bc98-aa00d5f01af5 CSeq: 10521545 INVITE Contact: <sip:mod_sofia@11.22.33.44:5080> User-Agent: CiscoSystems-SIP-GW-UA Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, precondition, path, replaces Allow-Events: talk, hold, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Content-Type: application/sdp Content-Disposition: session Content-Length: 492 X-FS-Support: update_display Remote-Party-ID: <sip:0000000000@11.22.33.44>;party=calling;screen=yes;privacy=off
v=0 o=FreeSWITCH 1301682069 1301682070 IN IP4 11.22.33.44 s=FreeSWITCH c=IN IP4 11.22.33.44 t=0 0 m=audio 33150 RTP/AVP 98 0 8 3 99 100 102 103 104 9 105 5 106 101 13 a=rtpmap:98 SPEEX/16000 a=rtpmap:99 G726-16/8000 a=rtpmap:100 G726-24/8000 a=rtpmap:102 G726-32/8000 a=rtpmap:103 G726-40/8000 a=rtpmap:104 G7221/16000 a=fmtp:104 bitrate=32000 a=rtpmap:105 iLBC/8000 a=fmtp:105 mode=20 a=rtpmap:106 L16/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 ------------------------------------------------------------------------ send 1385 bytes to udp/[11.22.33.44]:52767 at 03:33:46.673944: ------------------------------------------------------------------------ INVITE sip:99999999999@11.22.33.44:52767 SIP/2.0 Via: SIP/2.0/UDP 11.22.33.44:5080;rport;branch=z9hG4bK415Sta2FX5NrD Max-Forwards: 70 From: "" <sip:0000000000@11.22.33.44>;tag=S16mjZ06UgQSe To: <sip:99999999999@11.22.33.44:52767> Call-ID: 02ac42f6-92d1-4f3b-bc98-aa00d5f01af5 CSeq: 10521545 INVITE Contact: <sip:mod_sofia@11.22.33.44:5080> User-Agent: CiscoSystems-SIP-GW-UA Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, precondition, path, replaces Allow-Events: talk, hold, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Content-Type: application/sdp Content-Disposition: session Content-Length: 492 X-FS-Support: update_display Remote-Party-ID: <sip:0000000000@11.22.33.44>;party=calling;screen=yes;privacy=off
v=0 o=FreeSWITCH 1301682069 1301682070 IN IP4 11.22.33.44 s=FreeSWITCH c=IN IP4 11.22.33.44 t=0 0 m=audio 33150 RTP/AVP 98 0 8 3 99 100 102 103 104 9 105 5 106 101 13 a=rtpmap:98 SPEEX/16000 a=rtpmap:99 G726-16/8000 a=rtpmap:100 G726-24/8000 a=rtpmap:102 G726-32/8000 a=rtpmap:103 G726-40/8000 a=rtpmap:104 G7221/16000 a=fmtp:104 bitrate=32000 a=rtpmap:105 iLBC/8000 a=fmtp:105 mode=20 a=rtpmap:106 L16/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 ------------------------------------------------------------------------ send 1385 bytes to udp/[11.22.33.44]:52767 at 03:33:54.673950: ------------------------------------------------------------------------ INVITE sip:99999999999@11.22.33.44:52767 SIP/2.0 Via: SIP/2.0/UDP 11.22.33.44:5080;rport;branch=z9hG4bK415Sta2FX5NrD Max-Forwards: 70 From: "" <sip:0000000000@11.22.33.44>;tag=S16mjZ06UgQSe To: <sip:99999999999@11.22.33.44:52767> Call-ID: 02ac42f6-92d1-4f3b-bc98-aa00d5f01af5 CSeq: 10521545 INVITE Contact: <sip:mod_sofia@11.22.33.44:5080> User-Agent: CiscoSystems-SIP-GW-UA Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, precondition, path, replaces Allow-Events: talk, hold, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Content-Type: application/sdp Content-Disposition: session Content-Length: 492 X-FS-Support: update_display Remote-Party-ID: <sip:0000000000@11.22.33.44>;party=calling;screen=yes;privacy=off
v=0 o=FreeSWITCH 1301682069 1301682070 IN IP4 11.22.33.44 s=FreeSWITCH c=IN IP4 11.22.33.44 t=0 0 m=audio 33150 RTP/AVP 98 0 8 3 99 100 102 103 104 9 105 5 106 101 13 a=rtpmap:98 SPEEX/16000 a=rtpmap:99 G726-16/8000 a=rtpmap:100 G726-24/8000 a=rtpmap:102 G726-32/8000 a=rtpmap:103 G726-40/8000 a=rtpmap:104 G7221/16000 a=fmtp:104 bitrate=32000 a=rtpmap:105 iLBC/8000 a=fmtp:105 mode=20 a=rtpmap:106 L16/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 ------------------------------------------------------------------------ send 1385 bytes to udp/[11.22.33.44]:52767 at 03:34:10.674077: ------------------------------------------------------------------------ INVITE sip:99999999999@11.22.33.44:52767 SIP/2.0 Via: SIP/2.0/UDP 11.22.33.44:5080;rport;branch=z9hG4bK415Sta2FX5NrD Max-Forwards: 70 From: "" <sip:0000000000@11.22.33.44>;tag=S16mjZ06UgQSe To: <sip:99999999999@11.22.33.44:52767> Call-ID: 02ac42f6-92d1-4f3b-bc98-aa00d5f01af5 CSeq: 10521545 INVITE Contact: <sip:mod_sofia@11.22.33.44:5080> User-Agent: CiscoSystems-SIP-GW-UA Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, UPDATE, INFO, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE Supported: timer, precondition, path, replaces Allow-Events: talk, hold, presence, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer Content-Type: application/sdp Content-Disposition: session Content-Length: 492 X-FS-Support: update_display Remote-Party-ID: <sip:0000000000@11.22.33.44>;party=calling;screen=yes;privacy=off
v=0 o=FreeSWITCH 1301682069 1301682070 IN IP4 11.22.33.44 s=FreeSWITCH c=IN IP4 11.22.33.44 t=0 0 m=audio 33150 RTP/AVP 98 0 8 3 99 100 102 103 104 9 105 5 106 101 13 a=rtpmap:98 SPEEX/16000 a=rtpmap:99 G726-16/8000 a=rtpmap:100 G726-24/8000 a=rtpmap:102 G726-32/8000 a=rtpmap:103 G726-40/8000 a=rtpmap:104 G7221/16000 a=fmtp:104 bitrate=32000 a=rtpmap:105 iLBC/8000 a=fmtp:105 mode=20 a=rtpmap:106 L16/8000 a=rtpmap:101 telephone-event/8000 a=fmtp:101 0-16 a=ptime:20 ------------------------------------------------------------------------ 2011-04-01 23:34:11.674022 [DEBUG] sofia.c:4754 Channel sofia/internal/sip:99999999999@11.22.33.44:52767 entering state [terminated][408] 2011-04-01 23:34:11.674022 [DEBUG] switch_channel.c:2563 (sofia/internal/sip:99999999999@11.22.33.44:52767) Callstate Change RINGING -> HANGUP 2011-04-01 23:34:11.674022 [NOTICE] sofia.c:5394 Hangup sofia/internal/sip:99999999999@11.22.33.44:52767 [CS_CONSUME_MEDIA] [RECOVERY_ON_TIMER_EXPIRE] 2011-04-01 23:34:11.674022 [DEBUG] switch_channel.c:2579 Send signal sofia/internal/sip:99999999999@11.22.33.44:52767 [KILL] 2011-04-01 23:34:11.674022 [DEBUG] switch_core_session.c:1116 Send signal sofia/internal/sip:99999999999@11.22.33.44:52767 [BREAK] 2011-04-01 23:34:11.674022 [DEBUG] switch_core_state_machine.c:320 (sofia/internal/sip:99999999999@11.22.33.44:52767) Running State Change CS_HANGUP 2011-04-01 23:34:11.674022 [DEBUG] switch_ivr_originate.c:3506 Originate Resulted in Error Cause: 102 [RECOVERY_ON_TIMER_EXPIRE] 2011-04-01 23:34:11.674022 [ERR] switch_ivr_originate.c:2640 Cannot create outgoing channel of type [user] cause: [RECOVERY_ON_TIMER_EXPIRE] 2011-04-01 23:34:11.674022 [DEBUG] switch_ivr_originate.c:3506 Originate Resulted in Error Cause: 102 [RECOVERY_ON_TIMER_EXPIRE] 2011-04-01 23:34:11.675057 [DEBUG] switch_core_state_machine.c:560 (sofia/internal/sip:99999999999@11.22.33.44:52767) State HANGUP 2011-04-01 23:34:11.675057 [DEBUG] mod_sofia.c:451 sofia/internal/sip:99999999999@11.22.33.44:52767 Overriding SIP cause 504 with 408 from the other leg 2011-04-01 23:34:11.675057 [DEBUG] mod_sofia.c:457 Channel sofia/internal/sip:99999999999@11.22.33.44:52767 hanging up, cause: RECOVERY_ON_TIMER_EXPIRE 2011-04-01 23:34:11.695371 [DEBUG] switch_core_state_machine.c:46 sofia/internal/sip:99999999999@11.22.33.44:52767 Standard HANGUP, cause: RECOVERY_ON_TIMER_EXPIRE 2011-04-01 23:34:11.695371 [DEBUG] switch_core_state_machine.c:560 (sofia/internal/sip:99999999999@11.22.33.44:52767) State HANGUP going to sleep 2011-04-01 23:34:11.695371 [DEBUG] switch_core_state_machine.c:351 (sofia/internal/sip:99999999999@11.22.33.44:52767) State Change CS_HANGUP -> CS_REPORTING 2011-04-01 23:34:11.695371 [DEBUG] switch_core_session.c:1116 Send signal sofia/internal/sip:99999999999@11.22.33.44:52767 [BREAK] 2011-04-01 23:34:11.695371 [DEBUG] switch_core_state_machine.c:320 (sofia/internal/sip:99999999999@11.22.33.44:52767) Running State Change CS_REPORTING 2011-04-01 23:34:11.695371 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/sip:99999999999@11.22.33.44:52767) State REPORTING 2011-04-01 23:34:11.695371 [DEBUG] switch_core_state_machine.c:53 sofia/internal/sip:99999999999@11.22.33.44:52767 Standard REPORTING, cause: RECOVERY_ON_TIMER_EXPIRE 2011-04-01 23:34:11.695371 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/sip:99999999999@11.22.33.44:52767) State REPORTING going to sleep 2011-04-01 23:34:11.695371 [DEBUG] switch_core_state_machine.c:345 (sofia/internal/sip:99999999999@11.22.33.44:52767) State Change CS_REPORTING -> CS_DESTROY 2011-04-01 23:34:11.695371 [DEBUG] switch_core_session.c:1116 Send signal sofia/internal/sip:99999999999@11.22.33.44:52767 [BREAK] 2011-04-01 23:34:11.695371 [DEBUG] switch_core_session.c:1288 Session 5 (sofia/internal/sip:99999999999@11.22.33.44:52767) Locked, Waiting on external entities 2011-04-01 23:34:11.695371 [NOTICE] switch_core_session.c:1306 Session 5 (sofia/internal/sip:99999999999@11.22.33.44:52767) Ended 2011-04-01 23:34:11.695371 [NOTICE] switch_core_session.c:1308 Close Channel sofia/internal/sip:99999999999@11.22.33.44:52767 [CS_DESTROY] 2011-04-01 23:34:11.696436 [DEBUG] switch_core_state_machine.c:449 (sofia/internal/sip:99999999999@11.22.33.44:52767) Callstate Change HANGUP -> DOWN 2011-04-01 23:34:11.696436 [DEBUG] switch_core_state_machine.c:452 (sofia/internal/sip:99999999999@11.22.33.44:52767) Running State Change CS_DESTROY 2011-04-01 23:34:11.696436 [DEBUG] switch_core_state_machine.c:462 (sofia/internal/sip:99999999999@11.22.33.44:52767) State DESTROY 2011-04-01 23:34:11.696436 [DEBUG] mod_sofia.c:362 sofia/internal/sip:99999999999@11.22.33.44:52767 SOFIA DESTROY 2011-04-01 23:34:11.696436 [DEBUG] switch_core_state_machine.c:60 sofia/internal/sip:99999999999@11.22.33.44:52767 Standard DESTROY 2011-04-01 23:34:11.696436 [DEBUG] switch_core_state_machine.c:462 (sofia/internal/sip:99999999999@11.22.33.44:52767) State DESTROY going to sleep
a
----- Original Message ----- From: Steven Ayre To: FreeSWITCH Users Help Sent: Friday, April 01, 2011 5:30 PM Subject: Re: [Freeswitch-users] originate from cli
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.
On 1 April 2011 22:29, Steven Ayre <steveayre@gmail.com> wrote:
It means FS sent a message and didn't get a reply (timed out).
As anthm says, look at the siptrace - that'll show you what's being sent / received.
-Steve
On 1 April 2011 21:17, Madovsky <infos@madovsky.org> wrote:
I make some test with originate from cli.
/usr/local/freeswitch/bin/fs_cli -x "originate user/9999 7777 XML public" -ERR RECOVERY_ON_TIMER_EXPIRE
9999 and 7777 are ready to receive calls and I don't have any NAT.
is anyone can explain what it means ?
Thanks
_______________________________________________ FreeSWITCH-users mailing list 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
------------------------------------------------------------------------------
_______________________________________________ FreeSWITCH-users mailing list 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
------------------------------------------------------------------------------ _______________________________________________ FreeSWITCH-users mailing list 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
participants (3)
-
Anthony Minessale
-
Madovsky
-
Steven Ayre