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

[Michael Kristensen]

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

-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20140513/203b968d/attachment.html 
-------------- next part --------------
A non-text attachment was scrubbed...
Name: signature.asc
Type: application/pgp-signature
Size: 496 bytes
Desc: Message signed with OpenPGP using GPGMail
Url : http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20140513/203b968d/attachment.bin