Anthony,

in pastebined log you could search "DESTINATION_OUT_OF_ORDER" and you'll find it.
Anyway, I'll fire a bug in Jira.

Artem



On Thu, Dec 3, 2009 at 9:41 PM, Anthony Minessale <anthony.minessale@gmail.com> wrote:
The case of the channel hangup with DESTINATION_OUT_OF_ORDER is not depicted in your log.
can you capture the log of the entire procedure top to bottom and file a jira ticket on http://jira.freeswitch.org and attach the trace as a .txt file.



On Thu, Dec 3, 2009 at 12:16 PM, Artem Shiyanov <shiyanov@gmail.com> wrote:
Hi there!

This message is a forward from user-mail-list.
I'm trying to fix such a problem:
FreSwithch compiled from SVN-trunk, date = 11/02/2009.

What is need: connect two users, initially one is on the home-grown java-based IVR and other party is off hook.

What is done/got:
User1 is on the java application, it represents simple IVR system, and the most used FS API operation is "streamFile".
User2 is off hook.
next:
(mod_socket) create_uuid
<get uuid_x>
bgapi originate {origination_caller_id_name=User1}[origination_uuid=uuid_x]User1 &park()
<User1 answers incomming call>
<get event channel_User1 answered>
<get event channel_User1 parked>
uuid_bridge uuid_User1 uuid_User2
<get event channel_User1 hangup, cause=NORMAL_CLEARING>
<get event channel_User2 hangup, cause=DESTINATION_OUT_OF_ORDER>
<both channel_User1 and channel_User2 are down>


FS log is here: http://pastebin.freeswitch.org/11380


Thank you much for any help,
Artem





---------- Forwarded message ----------
From: Anthony Minessale <anthony.minessale@gmail.com>
Date: Wed, Dec 2, 2009 at 10:24 PM
Subject: Re: [Freeswitch-users] uuid_bridge kills both channels if they are executing java app
To: freeswitch-users@lists.freeswitch.org


you should be working on SVN trunk if you are doing development, we are so far forward from 1.0.4 we can't do debugging very easily.

I don't know all of the details of what you are trying to do but you are hitting some race conditions because of the async nature of the socket connection and the way you are using it.




On Wed, Dec 2, 2009 at 1:08 PM, Artem Shiyanov <shiyanov@gmail.com> wrote:
I'm back again with the same issue.
Now it is became worse: it reproduces occasionally.
[FS version is 1.04, test_load = 2 active calls]

I've got 2 logs: successful and not.
Here is a bad_case:
 
2009-12-02 13:27:55.159931 [NOTICE] switch_core_session.c:1576 Execute
java(/usr/local/freeswitch/scripts/fs2agi.jar org.starpound.fs2agi.Translator
${agi_url})
Dec 2, 2009 1:27:55 PM org.starpound.fs2agi.Translator run
INFO: ***************************************************
Dec 2, 2009 1:27:55 PM org.starpound.fs2agi.Translator run
INFO: Run AGI application agi://localhost:4573/hello.agi?callId=929 for session
2898ad41-4ec1-4628-89fd-651a93a7221d
2009-12-02 13:27:55.169841 [NOTICE] switch_cpp.cpp:1130 Run AGI application
agi://localhost:4573/hello.agi?callId=929
2009-12-02 13:28:02.888831 [CRIT] mod_local_stream.c:234 Leaking stream handle!

[switch_ivr_play_file() src/switch_ivr_play_say.c:1026]
2009-12-02 13:28:04.799806 [NOTICE] switch_channel.c:602 New Channel
sofia/internal/2001 [76d2c0e9-16a4-4098-92c0-5977cb482e17]
2009-12-02 13:28:05.148834 [NOTICE] sofia.c:3353 Ring-Ready sofia/internal/2001!
2009-12-02 13:28:05.855093 [NOTICE] sofia.c:3794 Channel [sofia/internal/2001] has
been answered
Dec 2, 2009 1:28:05 PM org.starpound.fs2agi.Translator tellAllWeCrashed
INFO: AGI application agi://localhost:4573/hello.agi?callId=929 for session
2898ad41-4ec1-4628-89fd-651a93a7221d crashed. Exception is:
java.lang.Exception: Internal FreeSwitch failure while streamming file, see
FreeSwitch logs for details
        at
org.starpound.fs2agi.agicommands.StreamFileCommand.execute(StreamFileCommand.java:36)
        at org.starpound.fs2agi.AgiConnection.run(AgiConnection.java:48)
        at org.starpound.fs2agi.Translator.run(Translator.java:56)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
        at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
        at java.lang.reflect.Method.invoke(Method.java:597)
        at org.freeswitch.Launcher.launch(Launcher.java:80)
2009-12-02 13:28:05.870185 [NOTICE] switch_core_state_machine.c:179 Hangup
sofia/internal/2001 [CS_EXECUTE] [NORMAL_CLEARING]
2009-12-02 13:28:05.878807 [INFO] switch_cpp.cpp:1130 AGI application
agi://localhost:4573/hello.agi?callId=929 crashed. See FS2AGI log for details.
2009-12-02 13:28:05.894422 [NOTICE] switch_ivr_bridge.c:667 Hangup
sofia/external/6786081291@66.19.38.143 [CS_SOFT_EXECUTE] [DESTINATION_OUT_OF_ORDER]
2009-12-02 13:28:05.894422 [NOTICE] switch_core_session.c:1086 Session 17
(sofia/external/6786081291@66.19.38.143) Ended
2009-12-02 13:28:05.894422 [NOTICE] switch_core_session.c:1088 Close Channel
sofia/external/6786081291@66.19.38.143 [CS_DESTROY]
2009-12-02 13:28:05.894422 [NOTICE] switch_core_session.c:1086 Session 18
(sofia/internal/2001) Ended
2009-12-02 13:28:05.894422 [NOTICE] switch_core_session.c:1088 Close Channel
sofia/internal/2001 [CS_DESTROY]



Message
"Dec 2, 2009 1:28:05 PM org.starpound.fs2agi.Translator tellAllWeCrashed
INFO: AGI application agi://localhost:4573/hello.agi?callId=929 for session
2898ad41-4ec1-4628-89fd-651a93a7221d crashed. Exception is:
..."
is sent from my app upon the onHangup().`

And here is a good_case:

2009-12-02 13:31:45.959813 [NOTICE] switch_core_session.c:1576 Execute
java(/usr/local/freeswitch/scripts/fs2agi.jar org.starpound.fs2agi.Translator
${agi_url})
Dec 2, 2009 1:31:45 PM org.starpound.fs2agi.Translator run
INFO: ***************************************************
Dec 2, 2009 1:31:45 PM org.starpound.fs2agi.Translator run
INFO: Run AGI application agi://localhost:4573/hello.agi?callId=932 for session
7c37369b-ffb2-4436-9288-a640047d0e5e
2009-12-02 13:31:45.965814 [NOTICE] switch_cpp.cpp:1130 Run AGI application
agi://localhost:4573/hello.agi?callId=932
2009-12-02 13:31:53.648915 [CRIT] mod_local_stream.c:234 Leaking stream handle!

[switch_ivr_play_file() src/switch_ivr_play_say.c:1026]
2009-12-02 13:31:59.260797 [NOTICE] switch_channel.c:602 New Channel
sofia/internal/2001 [7db554a6-861e-4492-a87b-78b6dfec6488]
2009-12-02 13:31:59.624818 [NOTICE] sofia.c:3353 Ring-Ready sofia/internal/2001!
2009-12-02 13:32:00.130814 [NOTICE] sofia.c:3794 Channel [sofia/internal/2001] has
been answered
Dec 2, 2009 1:32:00 PM org.starpound.fs2agi.Translator tellAllWeCrashed
INFO: AGI application agi://localhost:4573/hello.agi?callId=932 for session
7c37369b-ffb2-4436-9288-a640047d0e5e crashed. Exception is:
java.lang.Exception: Internal FreeSwitch failure while streamming file, see
FreeSwitch logs for details
        at
org.starpound.fs2agi.agicommands.StreamFileCommand.execute(StreamFileCommand.java:36)
        at org.starpound.fs2agi.AgiConnection.run(AgiConnection.java:48)
        at org.starpound.fs2agi.Translator.run(Translator.java:56)
        at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
        at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
        at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
        at java.lang.reflect.Method.invoke(Method.java:597)
        at org.freeswitch.Launcher.launch(Launcher.java:80)
2009-12-02 13:32:00.149080 [INFO] switch_cpp.cpp:1130 AGI application
agi://localhost:4573/hello.agi?callId=932 crashed. See FS2AGI log for details.
2009-12-02 13:32:00.388838 [INFO] switch_rtp.c:1869 Auto Changing port from
172.26.10.39:26402 to 91.190.120.190:26402



Suggestions?











On Sat, Nov 21, 2009 at 12:58 PM, Artem Shiyanov <shiyanov@gmail.com> wrote:
Anthony,

>>As soon as you call uuid_bridge you are transferring both legs of the call to bridge to each other.
>>This means your java app must exit so the channels can connect to each other.

I didn't know that. Now my java app is exiting upon the onHangup() call so everything has become "ok". Thank you much.
I'll add note to the wiki about this issue.

Artem




On Fri, Nov 20, 2009 at 5:49 AM, Anthony Minessale <anthony.minessale@gmail.com> wrote:
Your "annoying behaviour" is the exact behavior you should be getting considering what you told FS to do.

As soon as you call uuid_bridge you are transferring both legs of the call to bridge to each other.
This means your java app must exit so the channels can connect to each other.

remember that you hangup hook can be called when the channel is transferred not only when it hangs up.
you have to test which is happening based on the input to your callback.


On Thu, Nov 19, 2009 at 1:46 PM, Artem Shiyanov <shiyanov@gmail.com> wrote:
Hi there!

I've got annoying FS behavior:
There are 2 channels executing the same Java application (application itself is an IVR). If I try to bridge them with uuid_bridged then both channels are killed. Here is a log from FS console:
uuid_bridge 68587a9d-1d20-48f1-bdfc-72a2c027e1d2 7d6c08fc-62bf-4a6c-a9ae-763d607e43de
2009-07-09 05:58:26.562783 [DEBUG] switch_ivr_bridge.c:1165 (sofia/internal/1005@192.168.147.130) State Change CS_EXECUTE -> CS_HIBERNATE
2009-07-09 05:58:26.562783 [DEBUG] switch_cpp.cpp:1185 hangup_hook called
2009-07-09 05:58:26.562783 [DEBUG] switch_ivr_play_say.c:1391 done playing file
2009-07-09 05:58:26.576844 [DEBUG] switch_ivr_play_say.c:1391 done playing file
2009-07-09 05:58:26.641307 [DEBUG] switch_core_session.c:933 Send signal sofia/internal/1005@192.168.147.130 [BREAK]
2009-07-09 05:58:26.641307 [DEBUG] switch_ivr_bridge.c:1167 (sofia/internal/1001@master.agent.starpoundtech.net) State Change CS_EXECUTE -> CS_HIBERNATE
2009-07-09 05:58:26.641307 [DEBUG] switch_cpp.cpp:1185 hangup_hook called
API CALL [uuid_bridge(68587a9d-1d20-48f1-bdfc-72a2c027e1d2 7d6c08fc-62bf-4a6c-a9ae-763d607e43de)] output:
+OK 7d6c08fc-62bf-4a6c-a9ae-763d607e43de

freeswitch@localhost.localdomain> 2009-07-09 05:58:26.674348 [DEBUG] switch_core_session.c:933 Send signal sofia/internal/1001@master.agent.starpoundtec
2009-07-09 05:58:26.714809 [DEBUG] switch_core_session.c:813 Send signal sofia/internal/1005@192.168.147.130 [BREAK]

2009-07-09 05:58:26.742764 [CRIT] mod_local_stream.c:234 Leaking stream handle! [switch_ivr_play_file() src/switch_ivr_play_say.c:1026]
2009-07-09 05:58:26.754791 [DEBUG] switch_core_session.c:813 Send signal sofia/internal/1001@master.agent.starpoundtech.net [BREAK]

(FS version is 1.0.4)

Any thoughts?


Artem






--
Anthony Minessale II

FreeSWITCH http://www.freeswitch.org/
ClueCon http://www.cluecon.com/
Twitter: http://twitter.com/FreeSWITCH_wire

AIM: anthm
MSN:anthony_minessale@hotmail.com
GTALK/JABBER/PAYPAL:anthony.minessale@gmail.com
IRC: irc.freenode.net #freeswitch

FreeSWITCH Developer Conference
sip:888@conference.freeswitch.org
iax:guest@conference.freeswitch.org/888
googletalk:conf+888@conference.freeswitch.org
pstn:213-799-1400

_______________________________________________
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




_______________________________________________
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




--
Anthony Minessale II

FreeSWITCH http://www.freeswitch.org/
ClueCon http://www.cluecon.com/
Twitter: http://twitter.com/FreeSWITCH_wire

AIM: anthm
MSN:anthony_minessale@hotmail.com
GTALK/JABBER/PAYPAL:anthony.minessale@gmail.com
IRC: irc.freenode.net #freeswitch

FreeSWITCH Developer Conference
sip:888@conference.freeswitch.org
iax:guest@conference.freeswitch.org/888
googletalk:conf+888@conference.freeswitch.org
pstn:213-799-1400

_______________________________________________
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



_______________________________________________
FreeSWITCH-dev mailing list
FreeSWITCH-dev@lists.freeswitch.org
http://lists.freeswitch.org/mailman/listinfo/freeswitch-dev
UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-dev
http://www.freeswitch.org


_______________________________________________
FreeSWITCH-dev mailing list
FreeSWITCH-dev@lists.freeswitch.org
http://lists.freeswitch.org/mailman/listinfo/freeswitch-dev
UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-dev
http://www.freeswitch.org