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@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
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"
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:5060send 323 bytes to udp/[10.44.33.16]:5060 at 19:16:28.991477:
------------------------------------------------------------------------
Via: SIP/2.0/UDP 10.98.7.37:5070;rport;branch=z9hG4bK3KvmXjNUaD61e
Max-Forwards: 70
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:5060285c3556-b081-4ac7-bd62-b82129f4f362 2015-04-02 21:16:28.989475 [DEBUG] switch_core_session.c:1053 Send signal sofia/internal/
9999@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@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@10.44.33.16) State DESTROY going to sleep