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

Michael Kristensen michael.kristensen at dencrypt.dk
Thu May 22 13:11:38 MSD 2014


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

-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20140522/93a1a264/attachment-0001.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/20140522/93a1a264/attachment-0001.bin 


Join us at ClueCon 2013 Aug 6-8, 2013
More information about the FreeSWITCH-users mailing list