Anyone that have an idea, what could be the problem?

Regards,

Michael

On 13 May 2014, at 08:14, Michael Kristensen <michael.kristensen@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@xx.xx.xx.216]

2014-05-12 15:56:13.310819 [DEBUG] switch_ivr_bridge.c:689 Send signal sofia/internal/sip:michael@xx.xx.xx.6:38824 [BREAK]

2014-05-12 15:56:13.310819 [DEBUG] switch_core_session.c:907 Send signal sofia/internal/00michael@xx.xx.xx.216 [BREAK]

2014-05-12 15:56:13.310819 [NOTICE] switch_ivr_bridge.c:1603 Hangup sofia/internal/00michael@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@xx.xx.xx.216 [KILL]

2014-05-12 15:56:13.310819 [DEBUG] switch_core_session.c:1387 Send signal sofia/internal/00michael@xx.xx.xx.216 [BREAK]

2014-05-12 15:56:13.310819 [DEBUG] switch_core_session.c:2861 sofia/internal/00michael@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@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@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@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@xx.xx.xx.216) State HANGUP

2014-05-12 15:56:13.310819 [DEBUG] mod_sofia.c:407 sofia/internal/00michael@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@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@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@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@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@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@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@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@xx.xx.xx.216) State REPORTING

2014-05-12 15:56:13.310819 [DEBUG] switch_core_state_machine.c:102 sofia/internal/00michael@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@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@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@xx.xx.xx.216 [BREAK]

2014-05-12 15:56:13.310819 [DEBUG] switch_core_session.c:1600 Session 9 (sofia/internal/00michael@xx.xx.xx.216) Locked, Waiting on external entities

_________________________________________________________________________
Professional FreeSWITCH Consulting Services:
consulting@freeswitch.org
http://www.freeswitchsolutions.com

FreeSWITCH-powered IP PBX: The CudaTel Communication Server
http://www.cudatel.com

Official FreeSWITCH Sites
http://www.freeswitch.org
http://wiki.freeswitch.org
http://www.cluecon.com

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

_________________________________________________________________________
Professional FreeSWITCH Consulting Services:
consulting@freeswitch.org
http://www.freeswitchsolutions.com

FreeSWITCH-powered IP PBX: The CudaTel Communication Server
http://www.cudatel.com

Official FreeSWITCH Sites
http://www.freeswitch.org
http://wiki.freeswitch.org
http://www.cluecon.com

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