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 < [email protected]> 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 <[email protected]>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 <[email protected]> >> 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: [email protected] >> >> >> 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 <[email protected]>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/[email protected] [CS_SOFT_EXECUTE] >>> [DESTINATION_OUT_OF_ORDER] >>> 2009-12-02 13:28:05.894422 [NOTICE] switch_core_session.c:1086 Session 17 >>> (sofia/external/[email protected]) Ended >>> 2009-12-02 13:28:05.894422 [NOTICE] switch_core_session.c:1088 Close >>> Channel >>> sofia/external/[email protected] [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 <[email protected]>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 < >>>> [email protected]> 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 <[email protected]>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/[email protected]) 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/[email protected] [BREAK] >>>>>> 2009-07-09 05:58:26.641307 [DEBUG] switch_ivr_bridge.c:1167 >>>>>> (sofia/internal/[email protected]) 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 >>>>>> >>>>>> [email protected]> 2009-07-09 05:58:26.674348 [DEBUG] >>>>>> switch_core_session.c:933 Send signal >>>>>> sofia/internal/[email protected] >>>>>> 2009-07-09 05:58:26.714809 [DEBUG] switch_core_session.c:813 Send >>>>>> signal sofia/internal/[email protected] [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/[email protected] [BREAK] >>>>>> >>>>>> (FS version is 1.0.4) >>>>>> >>>>>> Any thoughts? >>>>>> >>>>>> >>>>>> Artem >>>>>> >>>>>> >>>>>> >>>>>> _______________________________________________ >>>>>> FreeSWITCH-users mailing list >>>>>> [email protected] >>>>>> 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:[email protected]<msn%[email protected]> >>>>> GTALK/JABBER/PAYPAL:[email protected]<paypal%[email protected]> >>>>> IRC: irc.freenode.net #freeswitch >>>>> >>>>> FreeSWITCH Developer Conference >>>>> sip:[email protected]<sip%[email protected]> >>>>> iax:[email protected]/888 >>>>> googletalk:[email protected]<googletalk%3aconf%[email protected]> >>>>> pstn:213-799-1400 >>>>> >>>>> _______________________________________________ >>>>> FreeSWITCH-users mailing list >>>>> [email protected] >>>>> 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 >>> [email protected] >>> 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:[email protected] <msn%[email protected]> >> GTALK/JABBER/PAYPAL:[email protected]<paypal%[email protected]> >> IRC: irc.freenode.net #freeswitch >> >> FreeSWITCH Developer Conference >> sip:[email protected] <sip%[email protected]> >> iax:[email protected]/888 >> googletalk:[email protected]<googletalk%3aconf%[email protected]> >> pstn:213-799-1400 >> >> _______________________________________________ >> FreeSWITCH-users mailing list >> [email protected] >> 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 >> [email protected] >> http://lists.freeswitch.org/mailman/listinfo/freeswitch-dev >> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-dev >> 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:[email protected] <msn%[email protected]> > GTALK/JABBER/PAYPAL:[email protected]<paypal%[email protected]> > IRC: irc.freenode.net #freeswitch > > FreeSWITCH Developer Conference > sip:[email protected] <sip%[email protected]> > iax:[email protected]/888 > googletalk:[email protected]<googletalk%3aconf%[email protected]> > pstn:213-799-1400 > > _______________________________________________ > FreeSWITCH-dev mailing list > [email protected] > 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 [email protected] http://lists.freeswitch.org/mailman/listinfo/freeswitch-dev UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-dev http://www.freeswitch.org
