Hi! I have just experienced some call drops and each time the sequence is the same in the freeswitch.log file. Both parties are sure that they did not accidentally hit the 3 button to send the DTMF tone (and the same thing has happened four times already after ~5 minutes). 2009-09-13 19:28:23.835216 [DEBUG] sofia.c:4329 INFO DTMF(3) 2009-09-13 19:28:23.835216 [DEBUG] sofia.c:4450 dispatched freeswitch event for INFO 2009-09-13 19:28:23.859408 [DEBUG] switch_rtp.c:1624 Send start packet for [3] ts=2591120 dur=160/160/13120 seq=64923 2009-09-13 19:28:23.879439 [DEBUG] switch_rtp.c:1560 Send middle packet for [3] ts=2591120 dur=320/320/13120 seq=64924 2009-09-13 19:28:23.899455 [DEBUG] switch_rtp.c:1560 Send middle packet for [3] ts=2591120 dur=480/480/13120 seq=64925 : : 2009-09-13 19:28:25.439404 [DEBUG] switch_rtp.c:1560 Send middle packet for [3] ts=2591120 dur=12800/12800/13120 seq=65002 2009-09-13 19:28:25.459312 [DEBUG] switch_rtp.c:1560 Send middle packet for [3] ts=2591120 dur=12960/12960/13120 seq=65003 2009-09-13 19:28:25.479183 [DEBUG] switch_rtp.c:1560 Send end packet for [3] ts=2591120 dur=13120/13120/13120 seq=65004 2009-09-13 19:28:25.479183 [DEBUG] switch_rtp.c:1560 Send end packet for [3] ts=2591120 dur=13120/13120/13120 seq=65005 2009-09-13 19:28:25.479183 [DEBUG] switch_rtp.c:1560 Send end packet for [3] ts=2591120 dur=13120/13120/13120 seq=65006 2009-09-13 19:28:33.879341 [NOTICE] sofia.c:322 Hangup sofia/external/<number> [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING] 2009-09-13 19:28:33.879341 [DEBUG] switch_channel.c:1683 Send signal sofia/external/<number> [KILL] 2009-09-13 19:28:33.879341 [DEBUG] switch_core_session.c:932 Send signal sofia/external/<number> [BREAK] 2009-09-13 19:28:33.900940 [DEBUG] switch_ivr_bridge.c:371 sofia/external/<number> ending bridge by request from write function 2009-09-13 19:28:33.900940 [DEBUG] switch_ivr_bridge.c:426 sofia/internal_nat/1000@192.168.1.120 receive message [UNBRIDGE] 2009-09-13 19:28:33.900940 [DEBUG] switch_core_session.c:630 Send signal sofia/internal_nat/1000@192.168.1.120 [BREAK] 2009-09-13 19:28:33.900940 [DEBUG] switch_ivr_bridge.c:452 BRIDGE THREAD DONE [sofia/internal_nat/1000@192.168.1.120] 2009-09-13 19:28:33.900940 [DEBUG] switch_ivr_bridge.c:454 Send signal sofia/external/<number> [BREAK] 2009-09-13 19:28:33.912049 [NOTICE] switch_core_state_machine.c:179 Hangup sofia/internal_nat/1000@192.168.1.120 [CS_EXECUTE] [NORMAL_CLEARING] Anyone have any idea what this sequence means and why I am getting this? Is it my sip provider or something in FreeSwitch? What does the 'Send end packet for [3] ts=2591120 dur=13120/13120/13120 seq=65006' mean? Notice that dur (duration?) is increasing a lot until the call drops. Thanks!
Is this still happening? If so please make sure that you are on latest trunk and re-test. Get a pcap of the traffic (SIP and RTP) for review and then report back. Thanks, MC On Sun, Sep 13, 2009 at 2:34 AM, Mark Campbell-Smith < mcampbellsmith@gmail.com> wrote:
Hi!
I have just experienced some call drops and each time the sequence is the same in the freeswitch.log file. Both parties are sure that they did not accidentally hit the 3 button to send the DTMF tone (and the same thing has happened four times already after ~5 minutes).
2009-09-13 19:28:23.835216 [DEBUG] sofia.c:4329 INFO DTMF(3) 2009-09-13 19:28:23.835216 [DEBUG] sofia.c:4450 dispatched freeswitch event for INFO 2009-09-13 19:28:23.859408 [DEBUG] switch_rtp.c:1624 Send start packet for [3] ts=2591120 dur=160/160/13120 seq=64923 2009-09-13 19:28:23.879439 [DEBUG] switch_rtp.c:1560 Send middle packet for [3] ts=2591120 dur=320/320/13120 seq=64924 2009-09-13 19:28:23.899455 [DEBUG] switch_rtp.c:1560 Send middle packet for [3] ts=2591120 dur=480/480/13120 seq=64925 : : 2009-09-13 19:28:25.439404 [DEBUG] switch_rtp.c:1560 Send middle packet for [3] ts=2591120 dur=12800/12800/13120 seq=65002 2009-09-13 19:28:25.459312 [DEBUG] switch_rtp.c:1560 Send middle packet for [3] ts=2591120 dur=12960/12960/13120 seq=65003 2009-09-13 19:28:25.479183 [DEBUG] switch_rtp.c:1560 Send end packet for [3] ts=2591120 dur=13120/13120/13120 seq=65004 2009-09-13 19:28:25.479183 [DEBUG] switch_rtp.c:1560 Send end packet for [3] ts=2591120 dur=13120/13120/13120 seq=65005 2009-09-13 19:28:25.479183 [DEBUG] switch_rtp.c:1560 Send end packet for [3] ts=2591120 dur=13120/13120/13120 seq=65006 2009-09-13 19:28:33.879341 [NOTICE] sofia.c:322 Hangup sofia/external/<number> [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING] 2009-09-13 19:28:33.879341 [DEBUG] switch_channel.c:1683 Send signal sofia/external/<number> [KILL] 2009-09-13 19:28:33.879341 [DEBUG] switch_core_session.c:932 Send signal sofia/external/<number> [BREAK] 2009-09-13 19:28:33.900940 [DEBUG] switch_ivr_bridge.c:371 sofia/external/<number> ending bridge by request from write function 2009-09-13 19:28:33.900940 [DEBUG] switch_ivr_bridge.c:426 sofia/internal_nat/1000@192.168.1.120 receive message [UNBRIDGE] 2009-09-13 19:28:33.900940 [DEBUG] switch_core_session.c:630 Send signal sofia/internal_nat/1000@192.168.1.120 [BREAK] 2009-09-13 19:28:33.900940 [DEBUG] switch_ivr_bridge.c:452 BRIDGE THREAD DONE [sofia/internal_nat/1000@192.168.1.120] 2009-09-13 19:28:33.900940 [DEBUG] switch_ivr_bridge.c:454 Send signal sofia/external/<number> [BREAK] 2009-09-13 19:28:33.912049 [NOTICE] switch_core_state_machine.c:179 Hangup sofia/internal_nat/1000@192.168.1.120 [CS_EXECUTE] [NORMAL_CLEARING]
Anyone have any idea what this sequence means and why I am getting this? Is it my sip provider or something in FreeSwitch? What does the 'Send end packet for [3] ts=2591120 dur=13120/13120/13120 seq=65006' mean? Notice that dur (duration?) is increasing a lot until the call drops.
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
The problem hasn't been seen again, but the exact call case has not been performed again. Will update freeswitch and monitor next time. Cheers MCS On Sat, Sep 19, 2009 at 6:27 AM, Michael Collins <msc@freeswitch.org> wrote:
Is this still happening? If so please make sure that you are on latest trunk and re-test. Get a pcap of the traffic (SIP and RTP) for review and then report back.
Thanks, MC
On Sun, Sep 13, 2009 at 2:34 AM, Mark Campbell-Smith <mcampbellsmith@gmail.com> wrote:
Hi!
I have just experienced some call drops and each time the sequence is the same in the freeswitch.log file. Both parties are sure that they did not accidentally hit the 3 button to send the DTMF tone (and the same thing has happened four times already after ~5 minutes).
2009-09-13 19:28:23.835216 [DEBUG] sofia.c:4329 INFO DTMF(3) 2009-09-13 19:28:23.835216 [DEBUG] sofia.c:4450 dispatched freeswitch event for INFO 2009-09-13 19:28:23.859408 [DEBUG] switch_rtp.c:1624 Send start packet for [3] ts=2591120 dur=160/160/13120 seq=64923 2009-09-13 19:28:23.879439 [DEBUG] switch_rtp.c:1560 Send middle packet for [3] ts=2591120 dur=320/320/13120 seq=64924 2009-09-13 19:28:23.899455 [DEBUG] switch_rtp.c:1560 Send middle packet for [3] ts=2591120 dur=480/480/13120 seq=64925 : : 2009-09-13 19:28:25.439404 [DEBUG] switch_rtp.c:1560 Send middle packet for [3] ts=2591120 dur=12800/12800/13120 seq=65002 2009-09-13 19:28:25.459312 [DEBUG] switch_rtp.c:1560 Send middle packet for [3] ts=2591120 dur=12960/12960/13120 seq=65003 2009-09-13 19:28:25.479183 [DEBUG] switch_rtp.c:1560 Send end packet for [3] ts=2591120 dur=13120/13120/13120 seq=65004 2009-09-13 19:28:25.479183 [DEBUG] switch_rtp.c:1560 Send end packet for [3] ts=2591120 dur=13120/13120/13120 seq=65005 2009-09-13 19:28:25.479183 [DEBUG] switch_rtp.c:1560 Send end packet for [3] ts=2591120 dur=13120/13120/13120 seq=65006 2009-09-13 19:28:33.879341 [NOTICE] sofia.c:322 Hangup sofia/external/<number> [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING] 2009-09-13 19:28:33.879341 [DEBUG] switch_channel.c:1683 Send signal sofia/external/<number> [KILL] 2009-09-13 19:28:33.879341 [DEBUG] switch_core_session.c:932 Send signal sofia/external/<number> [BREAK] 2009-09-13 19:28:33.900940 [DEBUG] switch_ivr_bridge.c:371 sofia/external/<number> ending bridge by request from write function 2009-09-13 19:28:33.900940 [DEBUG] switch_ivr_bridge.c:426 sofia/internal_nat/1000@192.168.1.120 receive message [UNBRIDGE] 2009-09-13 19:28:33.900940 [DEBUG] switch_core_session.c:630 Send signal sofia/internal_nat/1000@192.168.1.120 [BREAK] 2009-09-13 19:28:33.900940 [DEBUG] switch_ivr_bridge.c:452 BRIDGE THREAD DONE [sofia/internal_nat/1000@192.168.1.120] 2009-09-13 19:28:33.900940 [DEBUG] switch_ivr_bridge.c:454 Send signal sofia/external/<number> [BREAK] 2009-09-13 19:28:33.912049 [NOTICE] switch_core_state_machine.c:179 Hangup sofia/internal_nat/1000@192.168.1.120 [CS_EXECUTE] [NORMAL_CLEARING]
Anyone have any idea what this sequence means and why I am getting this? Is it my sip provider or something in FreeSwitch? What does the 'Send end packet for [3] ts=2591120 dur=13120/13120/13120 seq=65006' mean? Notice that dur (duration?) is increasing a lot until the call drops.
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
participants (2)
-
Mark Campbell-Smith
-
Michael Collins