[Freeswitch-users] Originate_timeout longer than 5 minutes?

Thu Apr 2 23:46:56 MSD 2015

Hi Fredick,

Your PBX is giving 504 Gateway timeout and not sure how it will solve the
issue by sending the keep-alive.  Once the call answered if you want to
send any KEEP alive for the particular call you can use SIP session timer.

enable-timer

This enables or disables support for RFC 4028
<http://tools.ietf.org/html/rfc4028> SIP Session Timers.

<param name="enable-timer" value="false"/>

session-timeout

session timers for all call to expire after the specified seconds Then it
will send another invite (re-invite). If not specified defaults to 30
minutes. Some gateways may reject values less than 30 minutes. This values
refers to Session-Expires in RFC 4028 <http://tools.ietf.org/html/rfc4028>(The
time at which an element will consider the session timed out, if no
successful session refresh transaction occurs beforehand)

<param name="session-timeout" value="1800"/>

But for your problem I strongly recommend you to check the issue in your
PBX side where we are getting 504 error.

Regards

Paul

On Thu, Apr 2, 2015 at 12:21 PM, Fredrik Larsson <fl at workmansoft.com> wrote:

> This is the log as well:
>
> 2015-04-02 21:16:28.729469 [DEBUG] mod_httapi.c:1218 Process Tag:
> [playback]
> 66eb8d8c-51f1-4a96-af26-a76fd65f86c9 2015-04-02 21:16:28.729469 [DEBUG]
> switch_ivr_play_say.c:1314 Codec Activated L16 at 8000hz 1 channels 20ms
> ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2749 tport_wakeup_pri()
> tport_wakeup_pri(02632630): events IN
> ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:2864 tport_recv_event()
> tport_recv_event(02632630)
> ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3205 tport_recv_iovec()
> tport(02632630) msg 03CE6898 from (udp/10.98.7.37:5070) has 472 bytes,
> veclen = 1
> recv 472 bytes from udp/[10.44.33.16]:5060 at 19:16:28.991477:
>    ------------------------------------------------------------------------
>    SIP/2.0 504 Gateway Timeout
>    Via: SIP/2.0/UDP 10.98.7.37:5070;rport;branch=z9hG4bK3KvmXjNUaD61e
>    From: "wm98991155" <sip:7411041111 at 10.98.7.37>;tag=3BU8reyaX4m6g
>    Call-ID: e82eb3a4-540e-1233-2385-33f7f366dd04
>    CSeq: 73664880 INVITE
>    Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,INFO,NOTIFY,UPDATE
>    Supported: timer
>    Server: IP Office 9.0.5.0 build 972
>    Reason: Q.850;cause=102;text="Recovery on timer expiry"
>    To: <sip:9999 at 10.44.33.16>;tag=5c965d7da3a12d47
>    Content-Length: 0
>
>    ------------------------------------------------------------------------
> ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3023 tport_deliver()
> tport(02632630): msg 03CE6898 (472 bytes) from udp/10.44.33.16:5070/sip
> next=00000000
> ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3299 agent_recv_response() nta:
> received 504 Gateway Timeout for INVITE (73664880)
> ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:3366 agent_recv_response() nta:
> 504 Gateway Timeout is going to a transaction
> ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4222 tport_release()
> tport(02632630): 03CE6078 by 027C4748 with 03CE6898
> ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:3257 tport_tsend()
> tport_tsend(02632630) tpn = UDP/10.44.33.16:5060
> ..\..\sofia-sip\libsofia-sip-ua\tport\tport.c:4680 tport_by_addrinfo()
> tport(02632630): not found by name UDP/10.44.33.16:5060
> send 323 bytes to udp/[10.44.33.16]:5060 at 19:16:28.991477:
>    ------------------------------------------------------------------------
>    ACK sip:9999 at 10.44.33.16 SIP/2.0
>    Via: SIP/2.0/UDP 10.98.7.37:5070;rport;branch=z9hG4bK3KvmXjNUaD61e
>    Max-Forwards: 70
>    From: "wm98991155" <sip:7411041111 at 10.98.7.37>;tag=3BU8reyaX4m6g
>    To: <sip:9999 at 10.44.33.16>;tag=5c965d7da3a12d47
>    Call-ID: e82eb3a4-540e-1233-2385-33f7f366dd04
>    CSeq: 73664880 ACK
>    Content-Length: 0
>
>    ------------------------------------------------------------------------
> ..\..\sofia-sip\libsofia-sip-ua\nta\nta.c:8304 outgoing_send() nta: sent
> ACK (73664880) to UDP/10.44.33.16:5060
> 285c3556-b081-4ac7-bd62-b82129f4f362 2015-04-02 21:16:28.989475 [DEBUG]
> switch_core_session.c:1053 Send signal sofia/internal/9999 at 10.44.33.16
> [BREAK]
> ..\..\sofia-sip\libsofia-sip-ua\nua\nua_session.c:4139
> signal_call_state_change() nua(01D997F0): call state changed: proceeding ->
> init
> 285c3556-b081-4ac7-bd62-b82129f4f362 2015-04-02 21:16:28.989475 [DEBUG]
> switch_core_session.c:1053 Send signal sofia/internal/9999 at 10.44.33.16
> [BREAK]
> 285c3556-b081-4ac7-bd62-b82129f4f362 2015-04-02 21:16:28.989475 [DEBUG]
> switch_core_session.c:1053 Send signal sofia/internal/9999 at 10.44.33.16
> [BREAK]
> ..\..\sofia-sip\libsofia-sip-ua\nua\nua_dialog.c:397
> nua_dialog_usage_remove_at() nua(01D997F0): removing session usage
> 285c3556-b081-4ac7-bd62-b82129f4f362 2015-04-02 21:16:28.989475 [DEBUG]
> sofia.c:5840 Remote Reason: 102
> 285c3556-b081-4ac7-bd62-b82129f4f362 2015-04-02 21:16:28.989475 [DEBUG]
> sofia.c:6614 Channel sofia/internal/9999 at 10.44.33.16 entering state
> [terminated][504]
> 285c3556-b081-4ac7-bd62-b82129f4f362 2015-04-02 21:16:28.989475 [NOTICE]
> sofia.c:7529 Hangup sofia/internal/9999 at 10.44.33.16 [CS_CONSUME_MEDIA]
> [RECOVERY_ON_TIMER_EXPIRE]
> 285c3556-b081-4ac7-bd62-b82129f4f362 2015-04-02 21:16:28.989475 [DEBUG]
> switch_channel.c:3222 Send signal sofia/internal/9999 at 10.44.33.16 [KILL]
> 285c3556-b081-4ac7-bd62-b82129f4f362 2015-04-02 21:16:28.989475 [DEBUG]
> switch_core_session.c:1388 Send signal sofia/internal/9999 at 10.44.33.16
> [BREAK]
> ..\..\sofia-sip\libsofia-sip-ua\nua\nua_stack.c:529 nua_signal()
> nua(01D997F0): sent signal r_destroy
> 285c3556-b081-4ac7-bd62-b82129f4f362 2015-04-02 21:16:28.989475 [DEBUG]
> switch_core_state_machine.c:472 (sofia/internal/9999 at 10.44.33.16) Running
> State Change CS_HANGUP
> 285c3556-b081-4ac7-bd62-b82129f4f362 2015-04-02 21:16:28.989475 [DEBUG]
> switch_core_state_machine.c:735 (sofia/internal/9999 at 10.44.33.16)
> Callstate Change RINGING -> HANGUP
> 285c3556-b081-4ac7-bd62-b82129f4f362 2015-04-02 21:16:28.989475 [DEBUG]
> switch_core_state_machine.c:737 (sofia/internal/9999 at 10.44.33.16) State
> HANGUP
> 285c3556-b081-4ac7-bd62-b82129f4f362 2015-04-02 21:16:28.989475 [DEBUG]
> mod_sofia.c:413 Channel sofia/internal/9999 at 10.44.33.16 hanging up,
> cause: RECOVERY_ON_TIMER_EXPIRE
> 285c3556-b081-4ac7-bd62-b82129f4f362 2015-04-02 21:16:28.989475 [DEBUG]
> switch_core_state_machine.c:60 sofia/internal/9999 at 10.44.33.16 Standard
> HANGUP, cause: RECOVERY_ON_TIMER_EXPIRE
> 285c3556-b081-4ac7-bd62-b82129f4f362 2015-04-02 21:16:28.989475 [DEBUG]
> switch_core_state_machine.c:737 (sofia/internal/9999 at 10.44.33.16) State
> HANGUP going to sleep
> 285c3556-b081-4ac7-bd62-b82129f4f362 2015-04-02 21:16:28.989475 [DEBUG]
> switch_core_state_machine.c:504 (sofia/internal/9999 at 10.44.33.16) State
> Change CS_HANGUP -> CS_REPORTING
> 285c3556-b081-4ac7-bd62-b82129f4f362 2015-04-02 21:16:28.989475 [DEBUG]
> switch_core_session.c:1388 Send signal sofia/internal/9999 at 10.44.33.16
> [BREAK]
> 285c3556-b081-4ac7-bd62-b82129f4f362 2015-04-02 21:16:28.989475 [DEBUG]
> switch_core_state_machine.c:472 (sofia/internal/9999 at 10.44.33.16) Running
> State Change CS_REPORTING
> 285c3556-b081-4ac7-bd62-b82129f4f362 2015-04-02 21:16:28.989475 [DEBUG]
> switch_core_state_machine.c:823 (sofia/internal/9999 at 10.44.33.16) State
> REPORTING
> 285c3556-b081-4ac7-bd62-b82129f4f362 2015-04-02 21:16:28.989475 [DEBUG]
> switch_core_state_machine.c:104 sofia/internal/9999 at 10.44.33.16 Standard
> REPORTING, cause: RECOVERY_ON_TIMER_EXPIRE
> 285c3556-b081-4ac7-bd62-b82129f4f362 2015-04-02 21:16:28.989475 [DEBUG]
> switch_core_state_machine.c:823 (sofia/internal/9999 at 10.44.33.16) State
> REPORTING going to sleep
> 285c3556-b081-4ac7-bd62-b82129f4f362 2015-04-02 21:16:28.989475 [DEBUG]
> switch_core_state_machine.c:498 (sofia/internal/9999 at 10.44.33.16) State
> Change CS_REPORTING -> CS_DESTROY
> 285c3556-b081-4ac7-bd62-b82129f4f362 2015-04-02 21:16:28.989475 [DEBUG]
> switch_core_session.c:1388 Send signal sofia/internal/9999 at 10.44.33.16
> [BREAK]
> 285c3556-b081-4ac7-bd62-b82129f4f362 2015-04-02 21:16:28.989475 [DEBUG]
> switch_core_session.c:1615 Session 3 (sofia/internal/9999 at 10.44.33.16)
> Locked, Waiting on external entities
> 2015-04-02 21:16:28.989475 [DEBUG] switch_ivr_originate.c:3695 Originate
> Resulted in Error Cause: 102 [RECOVERY_ON_TIMER_EXPIRE]
> 285c3556-b081-4ac7-bd62-b82129f4f362 2015-04-02 21:16:28.989475 [NOTICE]
> switch_core_session.c:1633 Session 3 (sofia/internal/9999 at 10.44.33.16)
> Ended
> 285c3556-b081-4ac7-bd62-b82129f4f362 2015-04-02 21:16:28.989475 [NOTICE]
> switch_core_session.c:1637 Close Channel sofia/internal/9999 at 10.44.33.16
> [CS_DESTROY]
> 285c3556-b081-4ac7-bd62-b82129f4f362 2015-04-02 21:16:28.989475 [DEBUG]
> switch_core_state_machine.c:626 (sofia/internal/9999 at 10.44.33.16) Running
> State Change CS_DESTROY
> 285c3556-b081-4ac7-bd62-b82129f4f362 2015-04-02 21:16:28.989475 [DEBUG]
> switch_core_state_machine.c:636 (sofia/internal/9999 at 10.44.33.16) State
> DESTROY
> 285c3556-b081-4ac7-bd62-b82129f4f362 2015-04-02 21:16:28.989475 [DEBUG]
> mod_sofia.c:323 sofia/internal/9999 at 10.44.33.16 SOFIA DESTROY
> 285c3556-b081-4ac7-bd62-b82129f4f362 2015-04-02 21:16:28.989475 [DEBUG]
> switch_core_state_machine.c:111 sofia/internal/9999 at 10.44.33.16 Standard
> DESTROY
> 285c3556-b081-4ac7-bd62-b82129f4f362 2015-04-02 21:16:28.989475 [DEBUG]
> switch_core_state_machine.c:636 (sofia/internal/9999 at 10.44.33.16) State
> DESTROY going to sleep
>
>
>
>
> _________________________________________________________________________
> Professional FreeSWITCH Consulting Services:
> consulting at freeswitch.org
> http://www.freeswitchsolutions.com
>
> Official FreeSWITCH Sites
> http://www.freeswitch.org
> http://confluence.freeswitch.org
> http://www.cluecon.com
>
> FreeSWITCH-users mailing list
> FreeSWITCH-users at lists.freeswitch.org
> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
> http://www.freeswitch.org
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20150402/7f717cd4/attachment-0001.html 