Re: [Freeswitch-users] uuid_bridge kills both channels if they are executing java app
I've sent deep-breath message to the dev list. Just-in-case, here is a cross-post: 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 On Wed, Dec 2, 2009 at 10:24 PM, Anthony Minessale anthony.miness...@gmail.com wrote: 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 shiya...@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.31 [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/6786081...@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/6786081...@66.19.38.143) Ended 2009-12-02 13:28:05.894422 [NOTICE] switch_core_session.c:1088 Close Channel sofia/external/6786081...@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
Re: [Freeswitch-users] uuid_bridge kills both channels if they are executing java app
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 shiya...@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.31 [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/6786081...@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/6786081...@66.19.38.143) Ended 2009-12-02 13:28:05.894422 [NOTICE] switch_core_session.c:1088 Close Channel sofia/external/6786081...@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
[Freeswitch-users] uuid_bridge kills both channels if they are executing java app
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/ 1...@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/1...@192.168.147.130 [BREAK] 2009-07-09 05:58:26.641307 [DEBUG] switch_ivr_bridge.c:1167 (sofia/internal/ 1...@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 freeswi...@localhost.localdomain 2009-07-09 05:58:26.674348 [DEBUG] switch_core_session.c:933 Send signal sofia/internal/1...@master.agent.starpoundtec 2009-07-09 05:58:26.714809 [DEBUG] switch_core_session.c:813 Send signal sofia/internal/1...@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/1...@master.agent.starpoundtech.net [BREAK] (FS version is 1.0.4) Any thoughts? Artem ___ 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
Re: [Freeswitch-users] uuid_bridge kills both channels if they are executing java app
On Thu, Nov 19, 2009 at 11:46 AM, Artem Shiyanov shiya...@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/1...@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/1...@192.168.147.130 [BREAK] 2009-07-09 05:58:26.641307 [DEBUG] switch_ivr_bridge.c:1167 (sofia/internal/1...@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 freeswi...@localhost.localdomain 2009-07-09 05:58:26.674348 [DEBUG] switch_core_session.c:933 Send signal sofia/internal/1...@master.agent.starpoundtec 2009-07-09 05:58:26.714809 [DEBUG] switch_core_session.c:813 Send signal sofia/internal/1...@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/1...@master.agent.starpoundtech.net [BREAK] (FS version is 1.0.4) Any thoughts? First, update to latest trunk - there are many behaviors that have been tweaked and repaired since early August when 1.0.4 came out. Try it on latest trunk and see if the behavior persists, is different, or is gone. Please report back and let us know how it all goes. -MC ___ 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
Re: [Freeswitch-users] uuid_bridge kills both channels if they are executing java app
I don't see any hangups here, are you talking about the BREAK signals? Mathieu Rene Avant-Garde Solutions Inc Office: + 1 (514) 664-1044 x100 Cell: +1 (514) 664-1044 x200 mr...@avgs.ca On 19-Nov-09, at 11:46 AM, Artem Shiyanov 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/1...@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/1...@192.168.147.130 [BREAK] 2009-07-09 05:58:26.641307 [DEBUG] switch_ivr_bridge.c:1167 (sofia/internal/1...@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 freeswi...@localhost.localdomain 2009-07-09 05:58:26.674348 [DEBUG] switch_core_session.c:933 Send signal sofia/internal/1...@master.agent.starpoundtec 2009-07-09 05:58:26.714809 [DEBUG] switch_core_session.c:813 Send signal sofia/internal/1...@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/1...@master.agent.starpoundtech.net [BREAK] (FS version is 1.0.4) Any thoughts? Artem ___ 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
Re: [Freeswitch-users] uuid_bridge kills both channels if they are executing java app
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 shiya...@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/1...@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/1...@192.168.147.130 [BREAK] 2009-07-09 05:58:26.641307 [DEBUG] switch_ivr_bridge.c:1167 (sofia/internal/1...@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 freeswi...@localhost.localdomain 2009-07-09 05:58:26.674348 [DEBUG] switch_core_session.c:933 Send signal sofia/internal/1...@master.agent.starpoundtec 2009-07-09 05:58:26.714809 [DEBUG] switch_core_session.c:813 Send signal sofia/internal/1...@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/1...@master.agent.starpoundtech.net [BREAK] (FS version is 1.0.4) Any thoughts? Artem ___ 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_miness...@hotmail.com msn%3aanthony_miness...@hotmail.com GTALK/JABBER/PAYPAL:anthony.miness...@gmail.compaypal%3aanthony.miness...@gmail.com IRC: irc.freenode.net #freeswitch FreeSWITCH Developer Conference sip:8...@conference.freeswitch.org sip%3a...@conference.freeswitch.org iax:gu...@conference.freeswitch.org/888 googletalk:conf+...@conference.freeswitch.orggoogletalk%3aconf%2b...@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