[Freeswitch-dev] uuid_bridge bug?

30 Apr 2009

      I am using FS r13096, sometimes uuid_bridge will hangup both legs with no obvious reason( see the debug log below )

The scenario is
1.       Caller(8002) Connects to Callee(7001)
2.       8002 then is parked by using uuid_park ( hence, 7001 is released )
3.       now using a simple lua script ( as listed below ) to re-connect 7001 back to the parked call
4.       most of the time, the re-connect will success, however, sometimes it will drop both legs.

local api = freeswitch.API();

-- Invite the requestor
local ses = freeswitch.Session("user/" .. requestor);
if ses:ready() then
        ses:answer();

        -- Get the UUID of this leg
        local uuid = ses:getVariable("uuid");

        -- Bridge it to the parked id
        freeswitch.consoleLog("info", "uuid_bridge " .. parkedid .. " " .. uuid .. "\n");
        api:execute("uuid_bridge", parkedid .. " " .. uuid );
end

2009-04-30 15:42:25 [DEBUG] sofia_glue.c:2929 sofia_glue_negotiate_sdp() Audio Codec Compare [PCMA:8:8000:0]/[PCMA:8:8000:20]
2009-04-30 15:42:25 [DEBUG] sofia_glue.c:1912 sofia_glue_tech_set_codec() Set Codec sofia/internal/sip:7001@192.168.0.31:5340 PCMA/8000 20 ms 160 samples
2009-04-30 15:42:25 [DEBUG] sofia_glue.c:2889 sofia_glue_negotiate_sdp() Set 2833 dtmf payload to 101
2009-04-30 15:42:25 [DEBUG] sofia_glue.c:2146 sofia_glue_activate_rtp() AUDIO RTP [sofia/internal/sip:7001@192.168.0.31:5340] 192.168.0.137 port 28890 -> 192.168.0.31 port 7360 codec: 8 ms: 20
2009-04-30 15:42:25 [DEBUG] switch_rtp.c:900 switch_rtp_create() Starting timer [soft] 160 bytes per 20ms
2009-04-30 15:42:25 [NOTICE] sofia.c:3399 sofia_handle_sip_i_state() Channel [sofia/internal/sip:7001@192.168.0.31:5340] has been answered
2009-04-30 15:42:25 [DEBUG] switch_channel.c:182 switch_channel_audio_sync() sofia/internal/sip:7001@192.168.0.31:5340 receive message [AUDIO_SYNC]
2009-04-30 15:42:25 [DEBUG] switch_ivr_originate.c:2016 switch_ivr_originate() Originate Resulted in Success: [sofia/internal/sip:7001@192.168.0.31:5340]
2009-04-30 15:42:25 [DEBUG] switch_channel.c:182 switch_channel_audio_sync() sofia/internal/sip:7001@192.168.0.31:5340 receive message [AUDIO_SYNC]
2009-04-30 15:42:25 [DEBUG] switch_ivr_originate.c:2016 switch_ivr_originate() Originate Resulted in Success: [sofia/internal/sip:7001@192.168.0.31:5340]
2009-04-30 15:42:25 [DEBUG] switch_channel.c:182 switch_channel_audio_sync() sofia/internal/sip:7001@192.168.0.31:5340 receive message [AUDIO_SYNC]
2009-04-30 15:42:25 [DEBUG] switch_cpp.cpp:496 CoreSession() (sofia/internal/sip:7001@192.168.0.31:5340) State Change CS_CONSUME_MEDIA -> CS_SOFT_EXECUTE
2009-04-30 15:42:25 [DEBUG] switch_core_session.c:869 switch_core_session_signal_state_change() Send signal sofia/internal/sip:7001@192.168.0.31:5340 [BREAK]
2009-04-30 15:42:25 [INFO] switch_cpp.cpp:1122 console_log() uuid_bridge 87d70dc0-96aa-4185-9614-f742a9564f30 723ee9d3-6a30-485f-8069-c535f4946e98
2009-04-30 15:42:25 [DEBUG] switch_ivr_bridge.c:1164 switch_ivr_uuid_bridge() (sofia/internal/8002@192.168.0.137) State Change CS_PARK -> CS_HIBERNATE
2009-04-30 15:42:25 [DEBUG] switch_core_state_machine.c:517 switch_core_session_run() (sofia/internal/sip:7001@192.168.0.31:5340) State CONSUME_MEDIA going to sleep
2009-04-30 15:42:25 [DEBUG] switch_core_state_machine.c:397 switch_core_session_run() (sofia/internal/sip:7001@192.168.0.31:5340) Running State Change CS_SOFT_EXECUTE
2009-04-30 15:42:25 [DEBUG] switch_core_state_machine.c:514 switch_core_session_run() (sofia/internal/8002@192.168.0.137) State PARK going to sleep
2009-04-30 15:42:25 [DEBUG] switch_core_state_machine.c:511 switch_core_session_run() (sofia/internal/sip:7001@192.168.0.31:5340) State SOFT_EXECUTE
2009-04-30 15:42:25 [DEBUG] mod_sofia.c:430 sofia_on_soft_execute() SOFIA TRANSMIT
2009-04-30 15:42:25 [DEBUG] switch_ivr_bridge.c:604 uuid_bridge_on_soft_execute() sofia/internal/sip:7001@192.168.0.31:5340 CUSTOM SOFT_EXECUTE
2009-04-30 15:42:25 [DEBUG] switch_core_state_machine.c:192 switch_core_standard_on_soft_execute() sofia/internal/sip:7001@192.168.0.31:5340 Standard SOFT_EXECUTE
2009-04-30 15:42:25 [DEBUG] switch_core_state_machine.c:511 switch_core_session_run() (sofia/internal/sip:7001@192.168.0.31:5340) State SOFT_EXECUTE going to sleep
2009-04-30 15:42:25 [DEBUG] switch_core_session.c:869 switch_core_session_signal_state_change() Send signal sofia/internal/8002@192.168.0.137 [BREAK]
2009-04-30 15:42:25 [DEBUG] switch_core_state_machine.c:397 switch_core_session_run() (sofia/internal/8002@192.168.0.137) Running State Change CS_HIBERNATE
2009-04-30 15:42:25 [DEBUG] switch_core_state_machine.c:520 switch_core_session_run() (sofia/internal/8002@192.168.0.137) State HIBERNATE
2009-04-30 15:42:25 [DEBUG] mod_sofia.c:160 sofia_on_hibernate() sofia/internal/8002@192.168.0.137 SOFIA HIBERNATE
2009-04-30 15:42:25 [DEBUG] switch_ivr_bridge.c:594 uuid_bridge_on_hibernate() (sofia/internal/8002@192.168.0.137) State Change CS_HIBERNATE -> CS_RESET
2009-04-30 15:42:25 [DEBUG] switch_core_session.c:869 switch_core_session_signal_state_change() Send signal sofia/internal/8002@192.168.0.137 [BREAK]
2009-04-30 15:42:25 [DEBUG] switch_core_state_machine.c:520 switch_core_session_run() (sofia/internal/8002@192.168.0.137) State HIBERNATE going to sleep
2009-04-30 15:42:25 [DEBUG] switch_core_state_machine.c:397 switch_core_session_run() (sofia/internal/8002@192.168.0.137) Running State Change CS_RESET
2009-04-30 15:42:25 [DEBUG] switch_core_state_machine.c:501 switch_core_session_run() (sofia/internal/8002@192.168.0.137) State RESET
2009-04-30 15:42:25 [DEBUG] mod_sofia.c:145 sofia_on_reset() sofia/internal/8002@192.168.0.137 SOFIA RESET
2009-04-30 15:42:25 [DEBUG] switch_ivr_bridge.c:581 uuid_bridge_on_reset() sofia/internal/8002@192.168.0.137 CUSTOM RESET
2009-04-30 15:42:25 [DEBUG] switch_ivr_bridge.c:586 uuid_bridge_on_reset() (sofia/internal/8002@192.168.0.137) State Change CS_RESET -> CS_SOFT_EXECUTE
2009-04-30 15:42:25 [DEBUG] switch_core_session.c:869 switch_core_session_signal_state_change() Send signal sofia/internal/8002@192.168.0.137 [BREAK]
2009-04-30 15:42:25 [DEBUG] switch_core_state_machine.c:501 switch_core_session_run() (sofia/internal/8002@192.168.0.137) State RESET going to sleep
2009-04-30 15:42:25 [DEBUG] switch_core_state_machine.c:397 switch_core_session_run() (sofia/internal/8002@192.168.0.137) Running State Change CS_SOFT_EXECUTE
2009-04-30 15:42:25 [DEBUG] switch_core_state_machine.c:511 switch_core_session_run() (sofia/internal/8002@192.168.0.137) State SOFT_EXECUTE
2009-04-30 15:42:25 [DEBUG] mod_sofia.c:430 sofia_on_soft_execute() SOFIA TRANSMIT
2009-04-30 15:42:25 [DEBUG] switch_ivr_bridge.c:604 uuid_bridge_on_soft_execute() sofia/internal/8002@192.168.0.137 CUSTOM SOFT_EXECUTE
2009-04-30 15:42:25 [DEBUG] switch_ivr_bridge.c:1166 switch_ivr_uuid_bridge() (sofia/internal/sip:7001@192.168.0.31:5340) State Change CS_SOFT_EXECUTE -> CS_HIBERNATE
2009-04-30 15:42:25 [DEBUG] switch_core_session.c:869 switch_core_session_signal_state_change() Send signal sofia/internal/sip:7001@192.168.0.31:5340 [BREAK]
2009-04-30 15:42:25 [DEBUG] switch_core_state_machine.c:397 switch_core_session_run() (sofia/internal/sip:7001@192.168.0.31:5340) Running State Change CS_HIBERNATE
2009-04-30 15:42:25 [DEBUG] switch_core_state_machine.c:520 switch_core_session_run() (sofia/internal/sip:7001@192.168.0.31:5340) State HIBERNATE
2009-04-30 15:42:25 [DEBUG] mod_sofia.c:160 sofia_on_hibernate() sofia/internal/sip:7001@192.168.0.31:5340 SOFIA HIBERNATE
2009-04-30 15:42:25 [DEBUG] switch_core_state_machine.c:212 switch_core_standard_on_hibernate() sofia/internal/sip:7001@192.168.0.31:5340 Standard HIBERNATE
2009-04-30 15:42:25 [DEBUG] switch_core_state_machine.c:520 switch_core_session_run() (sofia/internal/sip:7001@192.168.0.31:5340) State HIBERNATE going to sleep
2009-04-30 15:42:25 [DEBUG] switch_cpp.cpp:916 destroy() destroy/unlink session from object
2009-04-30 15:42:25 [NOTICE] switch_cpp.cpp:919 destroy() Hangup sofia/internal/sip:7001@192.168.0.31:5340 [CS_HIBERNATE] [NORMAL_CLEARING]
2009-04-30 15:42:25 [DEBUG] switch_channel.c:1617 switch_channel_perform_hangup() Send signal sofia/internal/sip:7001@192.168.0.31:5340 [KILL]
2009-04-30 15:42:25 [DEBUG] switch_core_session.c:869 switch_core_session_signal_state_change() Send signal sofia/internal/sip:7001@192.168.0.31:5340 [BREAK]
2009-04-30 15:42:25 [DEBUG] switch_core_state_machine.c:397 switch_core_session_run() (sofia/internal/sip:7001@192.168.0.31:5340) Running State Change CS_HANGUP
2009-04-30 15:42:25 [DEBUG] switch_core_state_machine.c:448 switch_core_session_run() (sofia/internal/sip:7001@192.168.0.31:5340) State HANGUP
2009-04-30 15:42:25 [DEBUG] mod_sofia.c:315 sofia_on_hangup() Channel sofia/internal/sip:7001@192.168.0.31:5340 hanging up, cause: NORMAL_CLEARING
2009-04-30 15:42:25 [DEBUG] mod_sofia.c:370 sofia_on_hangup() Sending BYE to sofia/internal/sip:7001@192.168.0.31:5340

[Freeswitch-dev] uuid_bridge bug?

Scott Shen