[Freeswitch-users] Originate_timeout longer than 5 minutes?

Fredrik Larsson fl at workmansoft.com
Thu Apr 2 23:21:42 MSD 2015 

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
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20150402/bbc731a3/attachment.html

Join us at ClueCon 2016 Aug 8-12, 2016
More information about the FreeSWITCH-users mailing list