[Freeswitch-users] Client gets hang-up after 32 seconds

Mon Apr 27 17:33:49 MSD 2015

We have an extension that we can call and can talk with no problems.
However if the extension calls us, he gets connected and we can only talk
for 32 seconds before the call gets terminated.   We looked at the log and
are unable to identify the reason why he cannot call us but that we can call
him. The error seems to be that when he calls Freeswitch for some reason
sets a timeout of 30 seconds (Setting leg timeout to 30)  See log below.

Please help. How can we fix this? 

Thanks

Charles 

6d0e7ed5-6078-4a31-9d6e-0e7d239c3579 o=FreeSWITCH 1430114648 1430114650 IN
IP4 156.34.240.101

6d0e7ed5-6078-4a31-9d6e-0e7d239c3579 s=FreeSWITCH

6d0e7ed5-6078-4a31-9d6e-0e7d239c3579 c=IN IP4 156.34.240.101

6d0e7ed5-6078-4a31-9d6e-0e7d239c3579 t=0 0

6d0e7ed5-6078-4a31-9d6e-0e7d239c3579 m=audio 25974 RTP/AVP 0 101

6d0e7ed5-6078-4a31-9d6e-0e7d239c3579 a=rtpmap:0 PCMU/8000

6d0e7ed5-6078-4a31-9d6e-0e7d239c3579 a=rtpmap:101 telephone-event/8000

6d0e7ed5-6078-4a31-9d6e-0e7d239c3579 a=fmtp:101 0-16

6d0e7ed5-6078-4a31-9d6e-0e7d239c3579 a=ptime:20

6d0e7ed5-6078-4a31-9d6e-0e7d239c3579 a=sendrecv

6d0e7ed5-6078-4a31-9d6e-0e7d239c3579 

6d0e7ed5-6078-4a31-9d6e-0e7d239c3579 2015-04-27 10:17:04.785852 [DEBUG]
switch_core_session.c:908 Send signal sofia/internal/425 at 192.168.20.153
[BREAK]

6d0e7ed5-6078-4a31-9d6e-0e7d239c3579 2015-04-27 10:17:04.785852 [DEBUG]
switch_core_session.c:1053 Send signal sofia/internal/425 at 192.168.20.153
[BREAK]

6d0e7ed5-6078-4a31-9d6e-0e7d239c3579 2015-04-27 10:17:04.785852 [NOTICE]
switch_ivr_originate.c:3522 Channel [sofia/internal/425 at 192.168.20.153] has
been answered

6d0e7ed5-6078-4a31-9d6e-0e7d239c3579 2015-04-27 10:17:04.785852 [DEBUG]
switch_channel.c:3689 (sofia/internal/425 at 192.168.20.153) Callstate Change
EARLY -> ACTIVE

6d0e7ed5-6078-4a31-9d6e-0e7d239c3579 2015-04-27 10:17:04.785852 [DEBUG]
sofia.c:6614 Channel sofia/internal/425 at 192.168.20.153 entering state
[completed][200]

6d0e7ed5-6078-4a31-9d6e-0e7d239c3579 2015-04-27 10:17:04.785852 [DEBUG]
switch_ivr_originate.c:3580 Originate Resulted in Success:
[sofia/internal/sip:200 at 192.168.20.150:5060]

6d0e7ed5-6078-4a31-9d6e-0e7d239c3579 2015-04-27 10:17:04.785852 [DEBUG]
switch_ivr_originate.c:2847 sofia/internal/sip:200 at 192.168.20.150:5060
Setting leg timeout to 30

6d0e7ed5-6078-4a31-9d6e-0e7d239c3579 2015-04-27 10:17:04.785852 [DEBUG]
switch_ivr_originate.c:3580 Originate Resulted in Success:
[sofia/internal/sip:200 at 192.168.20.150:5060]

b8e098cc-82a9-4128-992e-a21690707fb3 2015-04-27 10:17:04.785852 [DEBUG]
switch_core_session.c:908 Send signal
sofia/internal/sip:200 at 192.168.20.150:5060 [BREAK]

6d0e7ed5-6078-4a31-9d6e-0e7d239c3579 2015-04-27 10:17:04.785852 [DEBUG]
switch_core_session.c:908 Send signal sofia/internal/425 at 192.168.20.153
[BREAK]

b8e098cc-82a9-4128-992e-a21690707fb3 2015-04-27 10:17:04.785852 [DEBUG]
switch_ivr_bridge.c:1465 (sofia/internal/sip:200 at 192.168.20.150:5060) State
Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA

b8e098cc-82a9-4128-992e-a21690707fb3 2015-04-27 10:17:04.785852 [DEBUG]
switch_core_session.c:1388 Send signal
sofia/internal/sip:200 at 192.168.20.150:5060 [BREAK]

b8e098cc-82a9-4128-992e-a21690707fb3 2015-04-27 10:17:04.785852 [DEBUG]
switch_core_state_machine.c:472 (sofia/internal/sip:200 at 192.168.20.150:5060)
Running State Change CS_EXCHANGE_MEDIA

b8e098cc-82a9-4128-992e-a21690707fb3 2015-04-27 10:17:04.785852 [DEBUG]
switch_core_state_machine.c:538 (sofia/internal/sip:200 at 192.168.20.150:5060)
State EXCHANGE_MEDIA

b8e098cc-82a9-4128-992e-a21690707fb3 2015-04-27 10:17:04.785852 [DEBUG]
mod_sofia.c:594 SOFIA EXCHANGE_MEDIA

b8e098cc-82a9-4128-992e-a21690707fb3 2015-04-27 10:17:04.845827 [DEBUG]
switch_rtp.c:5853 Correct ip/port confirmed.

6d0e7ed5-6078-4a31-9d6e-0e7d239c3579 2015-04-27 10:17:36.785826 [DEBUG]
switch_core_session.c:1053 Send signal sofia/internal/425 at 192.168.20.153
[BREAK]

6d0e7ed5-6078-4a31-9d6e-0e7d239c3579 2015-04-27 10:17:36.785826 [DEBUG]
switch_core_session.c:1053 Send signal sofia/internal/425 at 192.168.20.153
[BREAK]

6d0e7ed5-6078-4a31-9d6e-0e7d239c3579 2015-04-27 10:17:36.805822 [DEBUG]
sofia.c:6614 Channel sofia/internal/425 at 192.168.20.153 entering state
[terminating][0]

6d0e7ed5-6078-4a31-9d6e-0e7d239c3579 2015-04-27 10:17:36.805822 [NOTICE]
sofia.c:7530 Hangup sofia/internal/425 at 192.168.20.153 [CS_EXECUTE]
[NORMAL_UNSPECIFIED]

6d0e7ed5-6078-4a31-9d6e-0e7d239c3579 2015-04-27 10:17:36.805822 [DEBUG]
switch_channel.c:3222 Send signal sofia/internal/425 at 192.168.20.153 [KILL]

6d0e7ed5-6078-4a31-9d6e-0e7d239c3579 2015-04-27 10:17:36.805822 [DEBUG]
mod_hash.c:298 Usage for 192.168.20.153_200 is now 0

6d0e7ed5-6078-4a31-9d6e-0e7d239c3579 2015-04-27 10:17:36.805822 [DEBUG]
switch_core_session.c:1388 Send signal sofia/internal/425 at 192.168.20.153
[BREAK]

6d0e7ed5-6078-4a31-9d6e-0e7d239c3579 2015-04-27 10:17:36.805822 [DEBUG]
switch_ivr_bridge.c:660 BRIDGE THREAD DONE
[sofia/internal/425 at 192.168.20.153]

b8e098cc-82a9-4128-992e-a21690707fb3 2015-04-27 10:17:36.805822 [DEBUG]
switch_ivr_bridge.c:690 Send signal
sofia/internal/sip:200 at 192.168.20.150:5060 [BREAK]

b8e098cc-82a9-4128-992e-a21690707fb3 2015-04-27 10:17:36.825829 [DEBUG]
switch_ivr_bridge.c:579 sofia/internal/425 at 192.168.20.153 ending bridge by
request from write function

b8e098cc-82a9-4128-992e-a21690707fb3 2015-04-27 10:17:36.825829 [DEBUG]
switch_ivr_bridge.c:660 BRIDGE THREAD DONE
[sofia/internal/sip:200 at 192.168.20.150:5060]

6d0e7ed5-6078-4a31-9d6e-0e7d239c3579 2015-04-27 10:17:36.825829 [DEBUG]
switch_ivr_bridge.c:690 Send signal sofia/internal/425 at 192.168.20.153
[BREAK]

b8e098cc-82a9-4128-992e-a21690707fb3 2015-04-27 10:17:36.825829 [NOTICE]
switch_ivr_bridge.c:754 Hangup sofia/internal/sip:200 at 192.168.20.150:5060
[CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]

b8e098cc-82a9-4128-992e-a21690707fb3 2015-04-27 10:17:36.825829 [DEBUG]
switch_channel.c:3222 Send signal sofia/internal/sip:200 at 192.168.20.150:5060
[KILL]

b8e098cc-82a9-4128-992e-a21690707fb3 2015-04-27 10:17:36.825829 [DEBUG]
switch_core_session.c:1388 Send signal
sofia/internal/sip:200 at 192.168.20.150:5060 [BREAK]

b8e098cc-82a9-4128-992e-a21690707fb3 2015-04-27 10:17:36.825829 [DEBUG]
switch_core_state_machine.c:538 (sofia/internal/sip:200 at 192.168.20.150:5060)
State EXCHANGE_MEDIA going to sleep

b8e098cc-82a9-4128-992e-a21690707fb3 2015-04-27 10:17:36.825829 [DEBUG]
switch_core_state_machine.c:472 (sofia/internal/sip:200 at 192.168.20.150:5060)
Running State Change CS_HANGUP

b8e098cc-82a9-4128-992e-a21690707fb3 2015-04-27 10:17:36.825829 [DEBUG]
switch_core_state_machine.c:735 (sofia/internal/sip:200 at 192.168.20.150:5060)
Callstate Change ACTIVE -> HANGUP

b8e098cc-82a9-4128-992e-a21690707fb3 2015-04-27 10:17:36.825829 [DEBUG]
switch_ivr_bridge.c:1563 sofia/internal/sip:200 at 192.168.20.150:5060 skip
receive message [UNBRIDGE] (channel is hungup already)

6d0e7ed5-6078-4a31-9d6e-0e7d239c3579 2015-04-27 10:17:36.825829 [DEBUG]
switch_ivr_bridge.c:1566 sofia/internal/425 at 192.168.20.153 skip receive
message [UNBRIDGE] (channel is hungup already)

b8e098cc-82a9-4128-992e-a21690707fb3 2015-04-27 10:17:36.825829 [DEBUG]
switch_core_state_machine.c:737 (sofia/internal/sip:200 at 192.168.20.150:5060)
State HANGUP

b8e098cc-82a9-4128-992e-a21690707fb3 2015-04-27 10:17:36.825829 [DEBUG]
mod_sofia.c:413 Channel sofia/internal/sip:200 at 192.168.20.150:5060 hanging
up, cause: NORMAL_CLEARING

6d0e7ed5-6078-4a31-9d6e-0e7d239c3579 2015-04-27 10:17:36.825829 [DEBUG]
switch_core_session.c:2893 sofia/internal/425 at 192.168.20.153 skip receive
message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)

-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20150427/a0d9f79d/attachment-0001.html 