[Freeswitch-users] Hangup when using ACL and TLS isn't relayed between servers

Thu May 22 16:09:33 MSD 2014

Its not using tls, its using udp, and the connection is refused from the remote side.
On May 22, 2014, at 9:11 AM, Michael Kristensen <michael.kristensen at dencrypt.dk> wrote:

> Anyone that have an idea, what could be the problem?
> 
> Regards,
> Michael
> 
> On 13 May 2014, at 08:14, Michael Kristensen <michael.kristensen at dencrypt.dk> wrote:
> 
>> Hi,
>> 
>> I’m working on bridging a call from one freeswitch server to another freeswitch server using ACL and TLS. The setup work without problems when running without TLS. 
>> 
>> But when I enable TLS, the BYE isn’t relayed from one server to the other - so the client in the other end is left hanging. I can from wireshark see that the BYE isn’t sent. And from the debug code below it seems to be because I get an 503 error (Connection refused), Is this somehow related to the "Overriding SIP cause 480 with 200 from the other leg"?
>> 
>> - Why do I get this error when I use TLS?
>> 
>> Best Regards,
>> Michael
>> 
>> 
>> 2014-05-12 15:56:13.310819 [DEBUG] switch_ivr_bridge.c:659 BRIDGE THREAD DONE [sofia/internal/00michael at xx.xx.xx.216]
>> 2014-05-12 15:56:13.310819 [DEBUG] switch_ivr_bridge.c:689 Send signal sofia/internal/sip:michael at xx.xx.xx.6:38824 [BREAK]
>> 2014-05-12 15:56:13.310819 [DEBUG] switch_core_session.c:907 Send signal sofia/internal/00michael at xx.xx.xx.216 [BREAK]
>> 2014-05-12 15:56:13.310819 [NOTICE] switch_ivr_bridge.c:1603 Hangup sofia/internal/00michael at xx.xx.xx.216 [CS_EXECUTE] [NORMAL_CLEARING]
>> 2014-05-12 15:56:13.310819 [DEBUG] switch_channel.c:3215 Send signal sofia/internal/00michael at xx.xx.xx.216 [KILL]
>> 2014-05-12 15:56:13.310819 [DEBUG] switch_core_session.c:1387 Send signal sofia/internal/00michael at xx.xx.xx.216 [BREAK]
>> 2014-05-12 15:56:13.310819 [DEBUG] switch_core_session.c:2861 sofia/internal/00michael at xx.xx.xx.216 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
>> 2014-05-12 15:56:13.310819 [DEBUG] switch_core_state_machine.c:530 (sofia/internal/00michael at xx.xx.xx.216) State EXECUTE going to sleep
>> 2014-05-12 15:56:13.310819 [DEBUG] switch_core_state_machine.c:467 (sofia/internal/00michael at xx.xx.xx.216) Running State Change CS_HANGUP
>> 2014-05-12 15:56:13.310819 [DEBUG] switch_core_state_machine.c:730 (sofia/internal/00michael at xx.xx.xx.216) Callstate Change ACTIVE -> HANGUP
>> 2014-05-12 15:56:13.310819 [DEBUG] switch_core_state_machine.c:732 (sofia/internal/00michael at xx.xx.xx.216) State HANGUP
>> 2014-05-12 15:56:13.310819 [DEBUG] mod_sofia.c:407 sofia/internal/00michael at xx.xx.xx.216 Overriding SIP cause 480 with 200 from the other leg
>> 2014-05-12 15:56:13.310819 [DEBUG] mod_sofia.c:413 Channel sofia/internal/00michael at xx.xx.xx.216 hanging up, cause: NORMAL_CLEARING
>> 2014-05-12 15:56:13.310819 [DEBUG] mod_sofia.c:465 Sending BYE to sofia/internal/00michael at xx.xx.xx.216
>> nua.c:645 nua_bye() nua: nua_bye: entering
>> nua_stack.c:569 nua_stack_signal() nua(0x7ffa54057fd0): recv signal r_bye
>> nua_params.c:480 nua_stack_set_params() nua: nua_stack_set_params: entering
>> soa.c:405 soa_set_params() soa_set_params(static::0x7ffa54058870, ...) called
>> soa.c:1784 soa_terminate() soa_terminate(static::0x7ffa54058870) called
>> soa.c:1302 soa_init_offer_answer() soa_init_offer_answer(static::0x7ffa54058870) called
>> nta.c:2665 nta_tpn_by_url() nta: selecting scheme sip
>> tport.c:3265 tport_tsend() tport_tsend(0x7ffa540605a0) tpn = */xx.xx.xx.216:5080
>> tport.c:4054 tport_resolve() tport_resolve addrinfo = xx.xx.xx.216:5080
>> tport.c:4688 tport_by_addrinfo() tport_by_addrinfo(0x7ffa540605a0): not found by name */xx.xx.xx.216:5080
>> tport.c:3602 tport_vsend() tport_vsend(0x7ffa540605a0): 676 bytes of 676 to udp/xx.xx.xx.216:5080
>> tport.c:3500 tport_send_msg() tport_vsend returned 676
>> nta.c:8280 outgoing_send() nta: sent BYE (59615422) to */xx.xx.xx.216:5080
>> tport.c:4168 tport_pend() tport_pend(0x7ffa540605a0): pending 0x7ffa540ac300 for udp/xx.xx.xx.232:5060 (already 0)
>> nua_stack.c:529 nua_signal() nua(0x7ffa54057fd0): sent signal r_bye
>> 2014-05-12 15:56:13.310819 [DEBUG] switch_core_state_machine.c:58 sofia/internal/00michael at xx.xx.xx.216 Standard HANGUP, cause: NORMAL_CLEARING
>> 2014-05-12 15:56:13.310819 [DEBUG] switch_core_state_machine.c:732 (sofia/internal/00michael at xx.xx.xx.216) State HANGUP going to sleep
>> 2014-05-12 15:56:13.310819 [DEBUG] switch_core_state_machine.c:499 (sofia/internal/00michael at xx.xx.xx.216) State Change CS_HANGUP -> CS_REPORTING
>> 2014-05-12 15:56:13.310819 [DEBUG] switch_core_session.c:1387 Send signal sofia/internal/00michael at xx.xx.xx.216 [BREAK]
>> tport.c:2757 tport_wakeup_pri() tport_wakeup_pri(0x7ffa540605a0): events ERR
>> tport_type_udp.c:519 tport_udp_error() tport_udp_error: Connection refused (111) [icmp type=3 code=3]
>> tport_type_udp.c:524 tport_udp_error()  reported by [xx.xx.xx.216]:0
>> tport.c:4230 tport_release() tport_release(0x7ffa540605a0): 0x7ffa540ac300 by 0x7ffa5403dc10 with (nil)
>> nta.c:8472 outgoing_print_tport_error() nta: BYE (59615422): Connection refused (111) with udp/[xx.xx.xx.216]:5080
>> nua_stack.c:271 nua_stack_event() nua(0x7ffa54057fd0): event r_bye 503 Service Unavailable
>> nua_session.c:4137 signal_call_state_change() nua(0x7ffa54057fd0): call state changed: terminating -> terminated
>> nua_stack.c:271 nua_stack_event() nua(0x7ffa54057fd0): event i_state 503 to BYE
>> nua_stack.c:271 nua_stack_event() nua(0x7ffa54057fd0): event i_terminated 503 to BYE
>> nua_dialog.c:397 nua_dialog_usage_remove_at() nua(0x7ffa54057fd0): removing session usage
>> soa.c:358 soa_destroy() soa_destroy(static::0x7ffa54058870) called
>> nta.c:4457 nta_leg_destroy() nta_leg_destroy(0x7ffa54058a50)
>> nua_session.c:351 nua_session_usage_destroy() nua: terminated session 0x7ffa54057fd0
>> nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
>> nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
>> nua_stack.c:359 nua_application_event() nua: nua_application_event: entering
>> 2014-05-12 15:56:13.310819 [DEBUG] switch_core_state_machine.c:467 (sofia/internal/00michael at xx.xx.xx.216) Running State Change CS_REPORTING
>> 2014-05-12 15:56:13.310819 [DEBUG] switch_core_state_machine.c:818 (sofia/internal/00michael at xx.xx.xx.216) State REPORTING
>> 2014-05-12 15:56:13.310819 [DEBUG] switch_core_state_machine.c:102 sofia/internal/00michael at xx.xx.xx.216 Standard REPORTING, cause: NORMAL_CLEARING
>> 2014-05-12 15:56:13.310819 [DEBUG] switch_core_state_machine.c:818 (sofia/internal/00michael at xx.xx.xx.216) State REPORTING going to sleep
>> 2014-05-12 15:56:13.310819 [DEBUG] switch_core_state_machine.c:493 (sofia/internal/00michael at xx.xx.xx.216) State Change CS_REPORTING -> CS_DESTROY
>> 2014-05-12 15:56:13.310819 [DEBUG] switch_core_session.c:1387 Send signal sofia/internal/00michael at xx.xx.xx.216 [BREAK]
>> 2014-05-12 15:56:13.310819 [DEBUG] switch_core_session.c:1600 Session 9 (sofia/internal/00michael at xx.xx.xx.216) Locked, Waiting on external entities
>> 
>> _________________________________________________________________________
>> Professional FreeSWITCH Consulting Services:
>> consulting at freeswitch.org
>> http://www.freeswitchsolutions.com
>> 
>> 
>> 
>> 
>> Official FreeSWITCH Sites
>> http://www.freeswitch.org
>> http://wiki.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
> 
> _________________________________________________________________________
> Professional FreeSWITCH Consulting Services:
> consulting at freeswitch.org
> http://www.freeswitchsolutions.com
> 
> 
> 
> 
> Official FreeSWITCH Sites
> http://www.freeswitch.org
> http://wiki.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/20140522/09118cdf/attachment-0001.html 