[Freeswitch-users] Hanged up callers doubt
Leonardo P. Bidinoto
leonardo.bidinoto at voicetechnology.com.br
Mon Jul 18 17:21:08 MSD 2011
Hello FS Community,
i found a solution for this issue and i'm giving some feedback about how I
avoided this ghost callers issue.
I discovered the scenario when ghost start to happen. When two different ESL
connections try to execute commands with the same channel with small
interval with each other, like one command is processing and the other
connection send another command to the same channel.
FS channel keeps stuck and the symptom is the appearance of ghost calls in
the system. Even when the user hangs up, the channel keeps in the memory. As
I described in my posts, after while this issue crashes FS. I discovered it
after a stress test with tons of calls running and my software management
trying to command those lines simultaneity.
There is no way to fix this after ghost appearance, only restarting FS.
The issue is generated by one command executed by ESL connection thru
mod_socket waiting for the response from the channel(the channel is inside a
conference) and another ESL connection try to execute another one like
conference_kick or conference_relate.
To avoid this situation I created a semaphore to block simultaneous command
execution over the same channel and stacking the other commands to wait for
the response of the previous command. That was tricky because you need to
set the semaphore right after you get the command, otherwise you will get
ghost callers on extreme conditions (I.E: High traffic)
I hope this hint could be useful to the other users.
PS:. Before sending this email, i checked with the lasted FS
version(git-db5f504 2011-07-17 17-00-38 -0400), and verify that
'conference_kick' command was no longer causing the channel get stuck, but
'conference_relate' command still showing the same problem.
2011/6/3 David Ponzone <david.ponzone at ipeva.fr>
> I guess there is no way to drop stuck calls. That's why they are stuck.
> I think you should find a way to solve the root cause.
>
> David Ponzone Direction Technique
> email: david.ponzone at ipeva.fr
> tel: 01 74 03 18 97
> gsm: 06 66 98 76 34
>
> Service Client IPeva
> tel: 0811 46 26 26
> www.ipeva.fr - www.ipeva-studio.com
>
> *Ce message et toutes les pièces jointes sont confidentiels et établis à
> l'intention exclusive de ses destinataires. Toute utilisation ou diffusion
> non autorisée est interdite. Tout message électronique est susceptible
> d'altération. **IPeva** décline toute responsabilité au titre de ce
> message s'il a été altéré, déformé ou falsifié. Si vous n'êtes pas
> destinataire de ce message, merci de le détruire immédiatement et d'avertir
> l'expéditeur.*
> *
> *
>
>
>
> Le 03/06/2011 à 14:31, Leonardo P. Bidinoto a écrit :
>
> Hi David,
>
> Yes, indeed that should work, BUT it doesn't if an user inside is stucked(i
> get a "false" from "uuid_exists").
> With "conference kick", all users inside will be dropped, but the stucked
> ones will remain, and with that the conference isnt destroyed.
>
>
>
> 2011/6/2 David Ponzone <david.ponzone at ipeva.fr>
>
>> Sure, that one is easy:
>>
>> from fs_cli:
>>
>> conference <confname> kick all
>>
>> David Ponzone Direction Technique
>> email: david.ponzone at ipeva.fr
>> tel: 01 74 03 18 97
>> gsm: 06 66 98 76 34
>>
>> Service Client IPeva
>> tel: 0811 46 26 26
>> www.ipeva.fr - www.ipeva-studio.com
>>
>> *Ce message et toutes les pièces jointes sont confidentiels et établis à
>> l'intention exclusive de ses destinataires. Toute utilisation ou diffusion
>> non autorisée est interdite. Tout message électronique est susceptible
>> d'altération. **IPeva** décline toute responsabilité au titre de ce
>> message s'il a été altéré, déformé ou falsifié. Si vous n'êtes pas
>> destinataire de ce message, merci de le détruire immédiatement et d'avertir
>> l'expéditeur.*
>> *
>> *
>>
>>
>>
>> Le 02/06/2011 à 21:33, Leonardo P. Bidinoto a écrit :
>>
>> Hi,
>>
>> About this issue, could be a way to destroy the conference from FS, in a
>> attempt to remove the stucked channels from the "show channels " and
>> "conference list"?
>>
>> 2011/5/17 Leonardo P. Bidinoto <leonardo.bidinoto at voicetechnology.com.br>
>>
>>> Sure. Im sending a pcap file made by tcpdump and one that i made by
>>> ngrep. In both files, it was registering whats happening when i stuck the
>>> channel by hanging up while using a ESL connection inside a conference(app
>>> socket 8085 sync full). I did a "conference kick" command in this channel
>>> while its was waiting to close the ESL connection.
>>>
>>>
>>>
>>> 2011/5/16 Michael Collins <msc at freeswitch.org>
>>>
>>>> Can you tcpdump or otherwise capture the traffic on port 8085? I am
>>>> curious what is happening with that.
>>>> -MC
>>>>
>>>>
>>>> On Mon, May 16, 2011 at 12:12 PM, Leonardo P. Bidinoto <
>>>> leonardo.bidinoto at voicetechnology.com.br> wrote:
>>>>
>>>>> hehe, ok michael.
>>>>>
>>>>> here is the pastebin link:
>>>>> http://pastebin.freeswitch.org/16303
>>>>>
>>>>>
>>>>> 2011/5/13 Michael Collins <msc at freeswitch.org>
>>>>>
>>>>>> Pastebin this info and select "FreeSWITCH Log" as the syntax
>>>>>> highlighting. I need the colorized output to read logs. (I'm getting older
>>>>>> and it's hard for me to ready black and white in an email.)
>>>>>>
>>>>>> -MC
>>>>>>
>>>>>>
>>>>>> On Fri, May 13, 2011 at 7:32 AM, Leonardo P. Bidinoto <
>>>>>> leonardo.bidinoto at voicetechnology.com.br> wrote:
>>>>>>
>>>>>>> Hi Michael,
>>>>>>>
>>>>>>> Just succeeded to reproduce the problem.
>>>>>>>
>>>>>>> The condition is: when a channel inside a conference is using a ESL
>>>>>>> connection(lets call it "A") through socket application and another ESL
>>>>>>> connection(lets call it "B") executes a command with this channel, the "B"
>>>>>>> ESL connection will wait the "A" ESL connection close to execute its
>>>>>>> command.
>>>>>>> If the channel hangs up before the "A" ESL connection is closed, then
>>>>>>> "B" ESL command will never be executed and the stucked channel will still be
>>>>>>> there, into sofia and the conference too.
>>>>>>> To verify that, just do "show channels" and "conference list". with
>>>>>>> "uuid_exists" command, return "false".
>>>>>>>
>>>>>>> Here are the actions done by the channel before get stucked:
>>>>>>>
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.187321
>>>>>>> [NOTICE] switch_channel.c:816 New Channel sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 [16e09413-9cb0-4011-a635-f91933a35c0f]
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.188323
>>>>>>> [DEBUG] sofia.c:4761 Channel sofia/external/1000123402 at 192.168.0.154entering state [received][100]
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.188323
>>>>>>> [DEBUG] sofia.c:4772 Remote SDP:
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.188323
>>>>>>> [DEBUG] sofia_glue.c:4656 Audio Codec Compare
>>>>>>> [BV32:107:16000:20:0]/[PCMU:0:8000:20:64000]
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.188323
>>>>>>> [DEBUG] sofia_glue.c:4656 Audio Codec Compare
>>>>>>> [BV32:107:16000:20:0]/[PCMA:8:8000:20:64000]
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.188323
>>>>>>> [DEBUG] sofia_glue.c:4656 Audio Codec Compare
>>>>>>> [PCMU:0:8000:20:64000]/[PCMU:0:8000:20:64000]
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.188323
>>>>>>> [DEBUG] sofia_glue.c:2788 Set Codec sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 PCMU/8000 20 ms 160 samples 64000 bits
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.188323
>>>>>>> [DEBUG] sofia_glue.c:4770 Set 2833 dtmf send/recv payload to 101
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.188323
>>>>>>> [DEBUG] sofia.c:4943 (sofia/external/1000123402 at 192.168.0.154) State
>>>>>>> Change CS_NEW -> CS_INIT
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.188323
>>>>>>> [DEBUG] switch_core_session.c:1114 Send signal sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 [BREAK]
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.188323
>>>>>>> [DEBUG] switch_core_state_machine.c:325 (sofia/external/
>>>>>>> 1000123402 at 192.168.0.154) Running State Change CS_INIT
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.188323
>>>>>>> [DEBUG] switch_core_state_machine.c:361 (sofia/external/
>>>>>>> 1000123402 at 192.168.0.154) State INIT
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.188323
>>>>>>> [DEBUG] mod_sofia.c:84 sofia/external/1000123402 at 192.168.0.154 SOFIA
>>>>>>> INIT
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.188323
>>>>>>> [DEBUG] mod_sofia.c:124 (sofia/external/1000123402 at 192.168.0.154)
>>>>>>> State Change CS_INIT -> CS_ROUTING
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.188323
>>>>>>> [DEBUG] switch_core_session.c:1114 Send signal sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 [BREAK]
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.188323
>>>>>>> [DEBUG] switch_core_state_machine.c:361 (sofia/external/
>>>>>>> 1000123402 at 192.168.0.154) State INIT going to sleep
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.188323
>>>>>>> [DEBUG] switch_core_state_machine.c:325 (sofia/external/
>>>>>>> 1000123402 at 192.168.0.154) Running State Change CS_ROUTING
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.188323
>>>>>>> [DEBUG] switch_channel.c:1665 (sofia/external/
>>>>>>> 1000123402 at 192.168.0.154) Callstate Change DOWN -> RINGING
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.188323
>>>>>>> [DEBUG] switch_core_state_machine.c:364 (sofia/external/
>>>>>>> 1000123402 at 192.168.0.154) State ROUTING
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.188323
>>>>>>> [DEBUG] mod_sofia.c:147 sofia/external/1000123402 at 192.168.0.154SOFIA ROUTING
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.188323
>>>>>>> [DEBUG] switch_core_state_machine.c:77 sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 Standard ROUTING
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.188323
>>>>>>> [INFO] mod_dialplan_xml.c:331 Processing Phone1 <1000123402>->1234567890 in
>>>>>>> context public
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f Dialplan: sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 parsing [public->unloop] continue=false
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f Dialplan: sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 Regex (PASS) [unloop] ${unroll_loops}(true)
>>>>>>> =~ /^true$/ break=on-false
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f Dialplan: sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 Regex (FAIL) [unloop] ${sip_looped_call}()
>>>>>>> =~ /^true$/ break=on-false
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f Dialplan: sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 parsing [public->public_extensions]
>>>>>>> continue=false
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f Dialplan: sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 Regex (PASS) [public_extensions]
>>>>>>> destination_number(1234567890) =~ /^(\d*)$/ break=on-false
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f Dialplan: sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 Action transfer(1234567890 XML default)
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.190328
>>>>>>> [DEBUG] switch_core_state_machine.c:119 (sofia/external/
>>>>>>> 1000123402 at 192.168.0.154) State Change CS_ROUTING -> CS_EXECUTE
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.190328
>>>>>>> [DEBUG] switch_core_session.c:1114 Send signal sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 [BREAK]
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.190328
>>>>>>> [DEBUG] switch_core_state_machine.c:364 (sofia/external/
>>>>>>> 1000123402 at 192.168.0.154) State ROUTING going to sleep
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.190328
>>>>>>> [DEBUG] switch_core_state_machine.c:325 (sofia/external/
>>>>>>> 1000123402 at 192.168.0.154) Running State Change CS_EXECUTE
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.190328
>>>>>>> [DEBUG] switch_core_state_machine.c:371 (sofia/external/
>>>>>>> 1000123402 at 192.168.0.154) State EXECUTE
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.190328
>>>>>>> [DEBUG] mod_sofia.c:240 sofia/external/1000123402 at 192.168.0.154SOFIA EXECUTE
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.191327
>>>>>>> [DEBUG] switch_core_state_machine.c:157 sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 Standard EXECUTE
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f EXECUTE sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 transfer(1234567890 XML default)
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.191327
>>>>>>> [DEBUG] switch_ivr.c:1597 (sofia/external/1000123402 at 192.168.0.154)
>>>>>>> State Change CS_EXECUTE -> CS_ROUTING
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.191327
>>>>>>> [DEBUG] switch_core_session.c:1114 Send signal sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 [BREAK]
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.191327
>>>>>>> [DEBUG] switch_core_session.c:707 Send signal sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 [BREAK]
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.191327
>>>>>>> [NOTICE] switch_ivr.c:1603 Transfer sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 to XML[1234567890 at default]
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.191327
>>>>>>> [DEBUG] switch_core_state_machine.c:371 (sofia/external/
>>>>>>> 1000123402 at 192.168.0.154) State EXECUTE going to sleep
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.191327
>>>>>>> [DEBUG] switch_core_state_machine.c:325 (sofia/external/
>>>>>>> 1000123402 at 192.168.0.154) Running State Change CS_ROUTING
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.191327
>>>>>>> [DEBUG] switch_core_state_machine.c:364 (sofia/external/
>>>>>>> 1000123402 at 192.168.0.154) State ROUTING
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.191327
>>>>>>> [DEBUG] mod_sofia.c:147 sofia/external/1000123402 at 192.168.0.154SOFIA ROUTING
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.191327
>>>>>>> [DEBUG] switch_core_state_machine.c:77 sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 Standard ROUTING
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.191327
>>>>>>> [INFO] mod_dialplan_xml.c:331 Processing Phone1 <1000123402>->1234567890 in
>>>>>>> context default
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f Dialplan: sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 parsing [default->flex] continue=false
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f Dialplan: sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 Regex (PASS) [flex]
>>>>>>> destination_number(1234567890) =~ /^(\d+)$/ break=on-false
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f Dialplan: sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 Action log(INFO VOICE received
>>>>>>> dest=1234567890)
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f Dialplan: sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 Action set(playback_terminators=#)
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f Dialplan: sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 Action log(INFO Let's do some ivrd, shall
>>>>>>> we?)
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f Dialplan: sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 Action
>>>>>>> set(ivr_path=/usr/local/ipconf/fs/daemon/ivr.rb)
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f Dialplan: sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 Action socket(localhost:8084 full)
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.193325
>>>>>>> [DEBUG] switch_core_state_machine.c:119 (sofia/external/
>>>>>>> 1000123402 at 192.168.0.154) State Change CS_ROUTING -> CS_EXECUTE
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.193325
>>>>>>> [DEBUG] switch_core_session.c:1114 Send signal sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 [BREAK]
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.193325
>>>>>>> [DEBUG] switch_core_state_machine.c:364 (sofia/external/
>>>>>>> 1000123402 at 192.168.0.154) State ROUTING going to sleep
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.193325
>>>>>>> [DEBUG] switch_core_state_machine.c:325 (sofia/external/
>>>>>>> 1000123402 at 192.168.0.154) Running State Change CS_EXECUTE
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.193325
>>>>>>> [DEBUG] switch_core_state_machine.c:371 (sofia/external/
>>>>>>> 1000123402 at 192.168.0.154) State EXECUTE
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.193325
>>>>>>> [DEBUG] mod_sofia.c:240 sofia/external/1000123402 at 192.168.0.154SOFIA EXECUTE
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.193325
>>>>>>> [DEBUG] switch_core_state_machine.c:157 sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 Standard EXECUTE
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f EXECUTE sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 log(INFO VOICE received dest=1234567890)
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.193325
>>>>>>> [INFO] mod_dptools.c:1184 VOICE received dest=1234567890
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f EXECUTE sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 set(playback_terminators=#)
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.194331
>>>>>>> [DEBUG] mod_dptools.c:1060 sofia/external/1000123402 at 192.168.0.154SET [playback_terminators]=[#]
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f EXECUTE sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 log(INFO Let's do some ivrd, shall we?)
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.194331
>>>>>>> [INFO] mod_dptools.c:1184 Let's do some ivrd, shall we?
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f EXECUTE sofia/external/
>>>>>>> 1000123402 at 192.168.0.154set(ivr_path=/usr/local/ipconf/fs/daemon/ivr.rb)
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.194331
>>>>>>> [DEBUG] mod_dptools.c:1060 sofia/external/1000123402 at 192.168.0.154SET [ivr_path]=[/usr/local/ipconf/fs/daemon/ivr.rb]
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f EXECUTE sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 socket(localhost:8084 full)
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.930309
>>>>>>> [DEBUG] switch_ivr.c:561 sofia/external/1000123402 at 192.168.0.154Command Execute answer()
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f EXECUTE sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 answer()
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.930309
>>>>>>> [DEBUG] sofia_glue.c:3022 AUDIO RTP [sofia/external/
>>>>>>> 1000123402 at 192.168.0.154] 192.168.0.154 port 24232 -> 192.168.0.111
>>>>>>> port 4046 codec: 0 ms: 20
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.930309
>>>>>>> [DEBUG] switch_rtp.c:1623 Starting timer [soft] 160 bytes per 20ms
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.932313
>>>>>>> [DEBUG] sofia_glue.c:3284 Set 2833 dtmf send payload to 101
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.932313
>>>>>>> [DEBUG] sofia_glue.c:3289 Set 2833 dtmf receive payload to 101
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.932313
>>>>>>> [DEBUG] mod_sofia.c:681 Local SDP sofia/external/
>>>>>>> 1000123402 at 192.168.0.154:
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.932313
>>>>>>> [DEBUG] switch_core_session.c:707 Send signal sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 [BREAK]
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.932313
>>>>>>> [DEBUG] switch_channel.c:2827 (sofia/external/
>>>>>>> 1000123402 at 192.168.0.154) Callstate Change RINGING -> ACTIVE
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.932313
>>>>>>> [NOTICE] mod_dptools.c:930 Channel [sofia/external/
>>>>>>> 1000123402 at 192.168.0.154] has been answered
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:47.933312
>>>>>>> [DEBUG] sofia.c:4761 Channel sofia/external/1000123402 at 192.168.0.154entering state [completed][200]
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:48.040285
>>>>>>> [DEBUG] sofia.c:4761 Channel sofia/external/1000123402 at 192.168.0.154entering state [ready][200]
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:48.082294
>>>>>>> [DEBUG] switch_ivr.c:561 sofia/external/1000123402 at 192.168.0.154Command Execute
>>>>>>> playback(/usr/local/freeswitch/sounds/flex/app32/teste/WelcomeToTheConferenceSystem.wav)
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f EXECUTE sofia/external/
>>>>>>> 1000123402 at 192.168.0.154playback(/usr/local/freeswitch/sounds/flex/app32/teste/WelcomeToTheConferenceSystem.wav)
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:48.082294
>>>>>>> [DEBUG] switch_ivr_play_say.c:1279 Codec Activated L16 at 8000hz 1
>>>>>>> channels 20ms
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:48.114277
>>>>>>> [DEBUG] switch_ivr_play_say.c:1649 done playing file
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:48.155281
>>>>>>> [DEBUG] switch_ivr.c:561 sofia/external/1000123402 at 192.168.0.154Command Execute read(1 1
>>>>>>> /usr/local/freeswitch/sounds/flex/app32/teste/Mainmenu.wav flex_digits 5000
>>>>>>> )
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f EXECUTE sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 read(1 1
>>>>>>> /usr/local/freeswitch/sounds/flex/app32/teste/Mainmenu.wav flex_digits 5000
>>>>>>> )
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:51:48.155281
>>>>>>> [DEBUG] switch_ivr_play_say.c:1279 Codec Activated L16 at 8000hz 1
>>>>>>> channels 20ms
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:00.893670
>>>>>>> [DEBUG] switch_rtp.c:3280 RTP RECV DTMF 1:1120
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:00.893670
>>>>>>> [DEBUG] switch_ivr_play_say.c:1649 done playing file
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:00.897674
>>>>>>> [DEBUG] switch_ivr.c:561 sofia/external/1000123402 at 192.168.0.154Command Execute set(flex_digits)
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f EXECUTE sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 set(flex_digits)
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:00.897674
>>>>>>> [DEBUG] mod_dptools.c:1060 sofia/external/1000123402 at 192.168.0.154SET [flex_digits]=[UNDEF]
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:00.940673
>>>>>>> [DEBUG] switch_ivr.c:561 sofia/external/1000123402 at 192.168.0.154Command Execute read(1 1
>>>>>>> /usr/local/freeswitch/sounds/flex/app32/teste/PleaseEnterYourPassCodeNow.wav
>>>>>>> flex_digits 5000 )
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f EXECUTE sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 read(1 1
>>>>>>> /usr/local/freeswitch/sounds/flex/app32/teste/PleaseEnterYourPassCodeNow.wav
>>>>>>> flex_digits 5000 )
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:00.940673
>>>>>>> [DEBUG] switch_ivr_play_say.c:1279 Codec Activated L16 at 8000hz 1
>>>>>>> channels 20ms
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:01.113662
>>>>>>> [DEBUG] switch_rtp.c:3280 RTP RECV DTMF 8:640
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:01.113662
>>>>>>> [DEBUG] switch_ivr_play_say.c:1649 done playing file
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:01.116663
>>>>>>> [DEBUG] switch_ivr.c:561 sofia/external/1000123402 at 192.168.0.154Command Execute set(flex_digits)
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f EXECUTE sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 set(flex_digits)
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:01.116663
>>>>>>> [DEBUG] mod_dptools.c:1060 sofia/external/1000123402 at 192.168.0.154SET [flex_digits]=[UNDEF]
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:01.117663
>>>>>>> [DEBUG] switch_ivr.c:561 sofia/external/1000123402 at 192.168.0.154Command Execute read(1 11
>>>>>>> /usr/local/freeswitch/sounds/flex/app32/teste/Silence.wav flex_digits 5000
>>>>>>> #,*)
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f EXECUTE sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 read(1 11
>>>>>>> /usr/local/freeswitch/sounds/flex/app32/teste/Silence.wav flex_digits 5000
>>>>>>> #,*)
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:01.117663
>>>>>>> [DEBUG] switch_ivr_play_say.c:1279 Codec Activated L16 at 8000hz 1
>>>>>>> channels 20ms
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:01.153658
>>>>>>> [DEBUG] switch_ivr_play_say.c:1649 done playing file
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:02.053615
>>>>>>> [DEBUG] switch_rtp.c:3280 RTP RECV DTMF #:960
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:02.055618
>>>>>>> [DEBUG] switch_ivr.c:561 sofia/external/1000123402 at 192.168.0.154Command Execute set(flex_digits)
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f EXECUTE sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 set(flex_digits)
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:02.055618
>>>>>>> [DEBUG] mod_dptools.c:1060 sofia/external/1000123402 at 192.168.0.154SET [flex_digits]=[UNDEF]
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:02.146613
>>>>>>> [DEBUG] switch_ivr.c:561 sofia/external/1000123402 at 192.168.0.154Command Execute
>>>>>>> playback(/usr/local/freeswitch/sounds/flex/app32/teste/InternalHelp.wav)
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f EXECUTE sofia/external/
>>>>>>> 1000123402 at 192.168.0.154playback(/usr/local/freeswitch/sounds/flex/app32/teste/InternalHelp.wav)
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:02.146613
>>>>>>> [DEBUG] switch_ivr_play_say.c:1279 Codec Activated L16 at 8000hz 1
>>>>>>> channels 20ms
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:02.333603
>>>>>>> [DEBUG] switch_rtp.c:3280 RTP RECV DTMF #:800
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:02.333603
>>>>>>> [DEBUG] mod_dptools.c:1664 Digit #
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:02.333603
>>>>>>> [DEBUG] switch_ivr_play_say.c:1649 done playing file
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:02.350617
>>>>>>> [DEBUG] switch_ivr.c:561 sofia/external/1000123402 at 192.168.0.154Command Execute
>>>>>>> playback(/usr/local/freeswitch/sounds/flex/app32/teste/YouAreTheOnlyPersonOnThisConference.wav)
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f EXECUTE sofia/external/
>>>>>>> 1000123402 at 192.168.0.154playback(/usr/local/freeswitch/sounds/flex/app32/teste/YouAreTheOnlyPersonOnThisConference.wav)
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:02.350617
>>>>>>> [DEBUG] switch_ivr_play_say.c:1279 Codec Activated L16 at 8000hz 1
>>>>>>> channels 20ms
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:04.253520
>>>>>>> [DEBUG] switch_ivr_play_say.c:1649 done playing file
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:04.322510
>>>>>>> [DEBUG] switch_ivr.c:561 sofia/external/1000123402 at 192.168.0.154Command Execute conference(15646 at teste
>>>>>>> +flags{waste})
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f EXECUTE sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 conference(15646 at teste+flags{waste})
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:04.813492
>>>>>>> [DEBUG] mod_conference.c:5582 Raw Codec Activation Success L16 at 8000hz1 channel 20ms
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:04.813492
>>>>>>> [DEBUG] mod_conference.c:5627 Raw Codec Activation Success L16 at 8000hz1 channel 20ms
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:04.813492
>>>>>>> [DEBUG] switch_core_codec.c:116 sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 Push codec L16:70
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:05.280469
>>>>>>> [DEBUG] switch_core_session.c:707 Send signal sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 [BREAK]
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:05.280469
>>>>>>> [DEBUG] mod_conference.c:2557 Setup timer soft success interval: 20
>>>>>>> samples: 160
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:16.573928
>>>>>>> [DEBUG] switch_rtp.c:3280 RTP RECV DTMF *:960
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:16.593929
>>>>>>> [DEBUG] mod_conference.c:2021 Execute app: socket, localhost:8085 sync full
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:16.593929
>>>>>>> [DEBUG] switch_core_codec.c:141 sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 Restore previous codec PCMU:0.
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f EXECUTE sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 socket(localhost:8085 sync full)
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:17.097927
>>>>>>> [DEBUG] switch_ivr.c:561 sofia/external/1000123402 at 192.168.0.154Command Execute read(1 1
>>>>>>> /usr/local/freeswitch/sounds/flex/app32/teste/Silence.wav flex_digits 2000 )
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f EXECUTE sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 read(1 1
>>>>>>> /usr/local/freeswitch/sounds/flex/app32/teste/Silence.wav flex_digits 2000 )
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:17.097927
>>>>>>> [DEBUG] switch_ivr_play_say.c:1279 Codec Activated L16 at 8000hz 1
>>>>>>> channels 20ms
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:17.133923
>>>>>>> [DEBUG] switch_ivr_play_say.c:1649 done playing file
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:17.373913
>>>>>>> [DEBUG] switch_rtp.c:3280 RTP RECV DTMF 1:1120
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:17.375912
>>>>>>> [DEBUG] switch_ivr.c:561 sofia/external/1000123402 at 192.168.0.154Command Execute set(flex_digits)
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f EXECUTE sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 set(flex_digits)
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:17.375912
>>>>>>> [DEBUG] mod_dptools.c:1060 sofia/external/1000123402 at 192.168.0.154SET [flex_digits]=[UNDEF]
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:17.490911
>>>>>>> [DEBUG] switch_ivr.c:561 sofia/external/1000123402 at 192.168.0.154Command Execute
>>>>>>> playback(/usr/local/freeswitch/sounds/flex/app32/teste/SelfMuteOn.wav)
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f EXECUTE sofia/external/
>>>>>>> 1000123402 at 192.168.0.154playback(/usr/local/freeswitch/sounds/flex/app32/teste/SelfMuteOn.wav)
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:17.490911
>>>>>>> [DEBUG] switch_ivr_play_say.c:1279 Codec Activated L16 at 8000hz 1
>>>>>>> channels 20ms
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:19.233824
>>>>>>> [DEBUG] switch_ivr_play_say.c:1649 done playing file
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:19.235828
>>>>>>> [DEBUG] switch_cpp.cpp:988 sofia/external/1000123402 at 192.168.0.154destroy/unlink session from object
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:19.235828
>>>>>>> [DEBUG] switch_core_codec.c:116 sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 Push codec L16:70
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:43.433668
>>>>>>> [DEBUG] switch_rtp.c:3280 RTP RECV DTMF *:800
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:43.453667
>>>>>>> [DEBUG] mod_conference.c:2021 Execute app: socket, localhost:8085 sync full
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:43.453667
>>>>>>> [DEBUG] switch_core_codec.c:141 sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 Restore previous codec PCMU:0.
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f EXECUTE sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 socket(localhost:8085 sync full)
>>>>>>>
>>>>>>> ====================================================================================================================================================
>>>>>>> While Inside this connection, a "conference 15646 kick [member_id of
>>>>>>> this channels]" command is executed by a fs_cli console and get stuck while
>>>>>>> waiting response.
>>>>>>>
>>>>>>> ====================================================================================================================================================
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:44.000850
>>>>>>> [DEBUG] switch_ivr.c:561 sofia/external/1000123402 at 192.168.0.154Command Execute read(1 1
>>>>>>> /usr/local/freeswitch/sounds/flex/app32/teste/Silence.wav flex_digits 2000 )
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f EXECUTE sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 read(1 1
>>>>>>> /usr/local/freeswitch/sounds/flex/app32/teste/Silence.wav flex_digits 2000 )
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:44.000850
>>>>>>> [DEBUG] switch_ivr_play_say.c:1279 Codec Activated L16 at 8000hz 1
>>>>>>> channels 20ms
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:44.033846
>>>>>>> [DEBUG] switch_ivr_play_say.c:1649 done playing file
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:44.553821
>>>>>>> [DEBUG] switch_rtp.c:3280 RTP RECV DTMF 1:960
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:44.555825
>>>>>>> [DEBUG] switch_ivr.c:561 sofia/external/1000123402 at 192.168.0.154Command Execute set(flex_digits)
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f EXECUTE sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 set(flex_digits)
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:44.555825
>>>>>>> [DEBUG] mod_dptools.c:1060 sofia/external/1000123402 at 192.168.0.154SET [flex_digits]=[UNDEF]
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:56.674244
>>>>>>> [DEBUG] switch_channel.c:2560 (sofia/external/
>>>>>>> 1000123402 at 192.168.0.154) Callstate Change ACTIVE -> HANGUP
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:56.674244
>>>>>>> [NOTICE] sofia.c:538 Hangup sofia/external/1000123402 at 192.168.0.154[CS_EXECUTE] [NORMAL_CLEARING]
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:56.674244
>>>>>>> [DEBUG] switch_channel.c:2576 Send signal sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 [KILL]
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:56.674244
>>>>>>> [DEBUG] switch_core_session.c:1114 Send signal sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 [BREAK]
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:56.675246
>>>>>>> [DEBUG] switch_core_session.c:2057 sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 skip receive message
>>>>>>> [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:56.675246
>>>>>>> [DEBUG] switch_cpp.cpp:988 sofia/external/1000123402 at 192.168.0.154destroy/unlink session from object
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:56.675246
>>>>>>> [DEBUG] switch_core_session.c:2057 sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 skip receive message
>>>>>>> [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:56.675246
>>>>>>> [DEBUG] switch_core_codec.c:116 sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 Push codec L16:70
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:56.675246
>>>>>>> [DEBUG] mod_conference.c:2815 Channel leaving conference, cause:
>>>>>>> NORMAL_CLEARING
>>>>>>> 16e09413-9cb0-4011-a635-f91933a35c0f 2011-05-13 10:52:56.757239
>>>>>>> [DEBUG] mod_conference.c:6104 sofia/external/
>>>>>>> 1000123402 at 192.168.0.154 skip receive message [UNBRIDGE] (channel is
>>>>>>> hungup already)
>>>>>>>
>>>>>>> I hope this info helps.
>>>>>>>
>>>>>>> 2011/5/12 Michael Collins <msc at freeswitch.org>
>>>>>>>
>>>>>>>>
>>>>>>>>
>>>>>>>> On Thu, May 12, 2011 at 12:27 PM, Leonardo P. Bidinoto <
>>>>>>>> leonardo.bidinoto at voicetechnology.com.br> wrote:
>>>>>>>>
>>>>>>>>> Hi Michael,
>>>>>>>>>
>>>>>>>>> Im not using to any cdr module.
>>>>>>>>
>>>>>>>>
>>>>>>>> I would recommend that you do several things:
>>>>>>>>
>>>>>>>> #1 - update to latest git
>>>>>>>> #2 - rotate logs
>>>>>>>> #3 - enable uuid logging (see logfile.conf.xml - param name "uuid")
>>>>>>>> #4 - reproduce the symptom with a single call (if possible)
>>>>>>>> #5 - pastebin the log for the uuid in question and link to it in
>>>>>>>> this thread
>>>>>>>>
>>>>>>>> From there hopefully we'll get a clue as to what is happening.
>>>>>>>> -MC
>>>>>>>>
>>>>>>>> _______________________________________________
>>>>>>>> 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
>>>>>>>>
>>>>>>>>
>>>>>>>
>>>>>>>
>>>>>>> --
>>>>>>> Leonardo Pires Bidinoto
>>>>>>> Voice Technology
>>>>>>> www.voicetechnology.com.br
>>>>>>>
>>>>>>> _______________________________________________
>>>>>>> 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
>>>>>>>
>>>>>>>
>>>>>>
>>>>>> _______________________________________________
>>>>>> 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
>>>>>>
>>>>>>
>>>>>
>>>>>
>>>>> --
>>>>> Leonardo Pires Bidinoto
>>>>> Voice Technology
>>>>> www.voicetechnology.com.br
>>>>>
>>>>> _______________________________________________
>>>>> 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
>>>>>
>>>>>
>>>>
>>>> _______________________________________________
>>>> 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
>>>>
>>>>
>>>
>>>
>>> --
>>> Leonardo Pires Bidinoto
>>> Voice Technology
>>> www.voicetechnology.com.br
>>>
>>
>>
>>
>> --
>> Leonardo Pires Bidinoto
>> Voice Technology
>> www.voicetechnology.com.br
>> _______________________________________________
>> 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
>>
>>
>>
>> _______________________________________________
>> 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
>>
>>
>
>
> --
> Leonardo Pires Bidinoto
> Voice Technology
> www.voicetechnology.com.br
> _______________________________________________
> 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
>
>
>
> _______________________________________________
> 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
>
>
--
Leonardo Pires Bidinoto
Voice Technology
www.voicetechnology.com.br
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20110718/94d5650d/attachment-0001.html
More information about the FreeSWITCH-users
mailing list