- FreeSWITCH-users - FreeSWITCH Mailing Lists

cepstral problem
by stephen at stephenjc 13 Dec '10

13 Dec '10

I'm working with cepstral support and they seem to have no idea. Does any one know why cepstral might produce no audio on a cent os 5.5 machine. Its works fine on another box of mine. Thanks, Stephen C -All of my email addresses go to the same place -Save Paper, think before you print

4 4

DockStar compile failure, with possible fix
by Tom C 13 Dec '10

13 Dec '10

I was attempting to build FreeSwitch on a Dockstar running Debian Squeeze, and ran into the sofia.c logger() compile problem as described here: http://jira.freeswitch.org/browse/FS-802 The offered solution was to downgrade GCC, but being a Linux newbie, I didn't know how to do that. So I decided to figure out what the problem really is. After learning waaaay too much about va_list, I see that the logger() function in sofia.c assumes that va_list will always be a pointer. But on different platforms, va_list can be an array or a struct. On the Dockstar (with Debian Squeeze and GCC 4.4), va_list is apparently a struct. I made the following modifications to logger() to make it truly portable, and it compiles and runs fine on my DockStar, my Debian Lenny x86. (My Windows build is having bigger problems.) But I'm no C guru, so someone needs to thoroughly examine my code before anyone else takes it as gospel. Am I actually checking what needs to be checked with the va_arg() macro? Changing it to if(1) worked too, after all. ORIGINAL logger() in sofia.c, to make it easy to see what I changed. static void logger(void *logarg, char const *fmt, va_list ap) { /* gcc 4.4 gets mad at us for testing if (ap) so let's try to work around it....*/ void *ap_ptr = (void *) (intptr_t) ap; //Error now occurs here, because ap is a struct. if (!fmt) return; if (ap_ptr) { //Error used to occur here, before attempted fix above. switch_log_vprintf(SWITCH_CHANNEL_LOG_CLEAN, mod_sofia_globals.tracelevel, fmt, ap); } else { switch_log_printf(SWITCH_CHANNEL_LOG_CLEAN, mod_sofia_globals.tracelevel, "%s", fmt); } } My MODIFIED logger() function, additions and changes labelled with DOCKSTAR: static void logger(void *logarg, char const *fmt, va_list ap) { va_list temp_ap; //DOCKSTAR: Added line (replacing previous ap_ptr cast). if (!fmt) return; va_copy(temp_ap, ap); //DOCKSTAR: Added Line. Make copy of "ap" so va_arg() macro doesn't move pointer. if (va_arg(temp_ap, int)) { //DOCKSTAR: Modified, get first argument from ap, check non-null. switch_log_vprintf(SWITCH_CHANNEL_LOG_CLEAN, mod_sofia_globals.tracelevel, fmt, ap); } else { switch_log_printf(SWITCH_CHANNEL_LOG_CLEAN, mod_sofia_globals.tracelevel, "%s", fmt); } va_end(temp_ap); //DOCKSTAR: Added line. Release our copy of "ap". }

3 5

Re: [Freeswitch-users] opal to sip problem
by Gustavo Espeche 13 Dec '10

13 Dec '10

Hi i try to start mod_h323 but i have follow issue. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. On Mon, 2010-12-13 at 17:48 +0300, freeswitch-users-request(a)lists.freeswitch.org wrote: > Re: opal to sip problem

2 2

Re: [Freeswitch-users] Radius AAA
by Abid Saleem 13 Dec '10

13 Dec '10

Hi, Sorry I was away from work. Just recalling the radius AAA and credit-time stuff, I am wondering if there is a way to announce the remaining time of 1 minute or 30 seconds before the call has to disconnect. Please help if you FS supports this feature. Regards-----------Abid Saleem Date: Mon, 15 Nov 2010 08:53:23 +0100 From: tculjaga(a)gmail.com To: freeswitch-users(a)lists.freeswitch.org Subject: Re: [Freeswitch-users] Radius AAA <action application="log" data="INFO credit_time=${credit_time}"/> <action application="sched_hangup" data="+${credit_time:17:-1} ${Core-UUID}"/> In this scenario you don't have to check for return code, because you will get hangup if authentication fails. Nice one Nazim :P T. _______________________________________________ FreeSWITCH-users mailing list FreeSWITCH-users(a)lists.freeswitch.org http://lists.freeswitch.org/mailman/listinfo/freeswitch-users UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users http://www.freeswitch.org

2 1

Re: [Freeswitch-users] FreeSWITCH-users Digest, Vol 54, Issue 87
by Gustavo Espeche 13 Dec '10

13 Dec '10

Hi i try to start mod_h323 but i have follow issue. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.430472 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.430472 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. 2010-12-13 17:29:22.432465 [DEBUG] transports.cxx:1475 Waiting on socket accept on ip$10.0.0.1:1720 2010-12-13 17:29:22.432465 [INFO] transports.cxx:1482 Failed to open transport, connection not started. On Mon, 2010-12-13 at 17:48 +0300, freeswitch-users-request(a)lists.freeswitch.org wrote: > Send FreeSWITCH-users mailing list submissions to > freeswitch-users(a)lists.freeswitch.org > > To subscribe or unsubscribe via the World Wide Web, visit > http://lists.freeswitch.org/mailman/listinfo/freeswitch-users > or, via email, send a message with subject or body 'help' to > freeswitch-users-request(a)lists.freeswitch.org > > You can reach the person managing the list at > freeswitch-users-owner(a)lists.freeswitch.org > > When replying, please edit your Subject line so it is more specific > than "Re: Contents of FreeSWITCH-users digest..." > Today's Topics: > > 1. Re: opal to sip problem (Nikolay Kondratyev) > MHTML Document attachment > > -------- Forwarded Message -------- > > From: Nikolay Kondratyev <kond(a)nstel.ru> > > Reply-to: FreeSWITCH Users Help > > <freeswitch-users(a)lists.freeswitch.org> > > To: 'FreeSWITCH Users Help' <freeswitch-users(a)lists.freeswitch.org> > > Subject: Re: [Freeswitch-users] opal to sip problem > > Date: Mon, 13 Dec 2010 17:48:08 +0300 > > > > I can't help you with mod_opal, but I use FS with mod_h323 ad sip<->h.323 > > gateway successfully. > > Rgds, > > Nikolay. > > > > > -----Original Message----- > > > From: freeswitch-users-bounces(a)lists.freeswitch.org > > > [mailto:freeswitch-users-bounces@lists.freeswitch.org] On > > > Behalf Of Gustavo Espeche > > > Sent: Monday, December 13, 2010 5:24 PM > > > To: freeswitch-users(a)lists.freeswitch.org > > > Subject: [Freeswitch-users] opal to sip problem > > > > > > Hello list, we are try to do inter-worker between h323 and sip using > > > opal in freeswitch, but FS don't send the call to our sip gw, > > > follow is > > > the call flow: > > > > > > h323 endpoint -->FS->sip gateway > > > > > > attached a FS debug of call. > > > > > > if something know or work with opal in freeSwitch and have > > > some tips we > > > appreciate a lot if advice about it. > > > Best Regards. > > > -------------------------------------------------------------- > > > --------------------------------------------------------------- > > > 2010-12-13 11:58:21.325242 [DEBUG] transports.cxx:1200 Started > > > connection to 200.117.192.17:19270 (if=10.0.0.1:1720) > > > 2010-12-13 11:58:21.325242 [DEBUG] osutil.cxx:189 File handle > > > high water > > > mark set: 67 Thread unblock pipe > > > 2010-12-13 11:58:21.325242 [DEBUG] tlibthrd.cxx:587 Thread high water > > > mark set: 8 > > > 2010-12-13 11:58:21.325242 [DEBUG] transports.cxx:666 Waiting > > > on socket > > > accept on tcp$10.0.0.1:1720 > > > 2010-12-13 11:58:21.325242 [DEBUG] h323ep.cxx:501 Awaiting first PDU > > > 2010-12-13 11:58:21.805203 [DEBUG] h323pdu.cxx:80 Receiving PDU: > > > { > > > q931pdu = { > > > protocolDiscriminator = 8 > > > callReference = 22716 > > > from = originator > > > messageType = Setup > > > IE: Bearer-Capability = { > > > 88 93 a5 > > > ... > > > } > > > IE: Display = { > > > 67 75 73 74 61 76 6f 00 gustavo. > > > } > > > IE: Called-Party-Number = { > > > 81 32 33 31 35 34 33 35 31 34 32 38 30 36 33 > > > 33 .231543514280633 > > > } > > > > > > IE: User-User = { > > > 20 b8 06 00 08 91 4a 00 06 01 40 06 00 67 00 > > > 75 .....J...@..g.u > > > 00 73 00 74 00 61 00 76 00 6f 22 c0 09 00 00 > > > 3d .s.t.a.v.o"....= > > > ... > > > } > > > } > > > h225pdu = { > > > h323_uu_pdu = { > > > h323_me > > > ssage_body = setup { > > > protocolIdentifier = 0.0.8.2250.0.6 > > > sourceAddress = 1 entries { > > > [0]=h323_ID 7 characters { > > > 0067 0075 0073 0074 0061 0076 006f gustavo > > > } > > > } > > > sour > > > ceInfo = { > > > vendor = { > > > vendor = { > > > t35CountryCode = 9 > > > t35Extension = 0 > > > manufacturerCode = 61 > > > } > > > productId = 3 octets { > > > 00 00 00 > > > (OPAL v3.6 > > > 2e 36 29 00 00 .6 > > > ).. > > > } > > > } > > > terminal = { > > > } > > > mc = false > > > undefinedNode = false > > > } > > > destinationAddress = 1 entries { > > > [0]=dialedDigits "231543514280633" > > > } > > > > > > Log-Func: > > > Log-Line: 0 > > > User-Data: > > > > > > destCallSignalAddress = ipAddress { > > > ip = 4 octets { > > > 48 33 2f 64 H3/d > > > } > > > port = 1720 > > > } > > > activeMC = falets { > > > 4e 76 b2 f2 1d 05 e0 11 9b b7 00 1e 64 1f 33 fe > > > Nv..........d.3. > > > } > > > conferenceGoal = create <<null>> > > > callType = pointToPoint <<null>> > > > sourceCallSignalAddress = ipAddress { > > > ip = > > > Log-Func: > > > Log-Line: 0 > > > User-Data: > > > > > > 4 octets { > > > c8 75 c0 11 .u.. > > > } > > > port = 19270 > > > } > > > callIdentifier = { > > > guid = 16 octets { > > > 3a 76 f 33 fe :v..........d.3. > > > } > > > } > > > fastStart = 8 entries { > > > [0]= 29 octets { > > > 40 00 00 06 04 01 00 4c 20 13 80 11 1c 00 01 00 > > > @......L ....... > > > c8 75 c0 11 13 de 00 c8 75 c0 11 13 > > > df .u......u.... > > > } > > > [1]= 19 octets { > > > 00 00 64 0c 20 13 80 0b 0d 00 01 00 c8 75 c0 > > > 11 ..d. ........u.. > > > 13 df 00 ... > > > } > > > [ > > > 2]= 35 octets { > > > 40 00 00 06 04 01 00 48 78 00 4a ff 00 80 01 00 > > > @......Hx.J..... > > > 80 11 1c 00 02 00 c8 75 c0 11 13 e0 00 c8 75 > > > c0 .......u......u. > > > ... > > > } > > > [3]= 25 octets { > > > > > > 00 00 65 08 78 00 4a ff 00 80 01 00 80 0b 0d > > > 00 ..e.x.J......... > > > 02 00 c8 75 c0 11 13 e1 > > > 00 ...u..... > > > } > > > [4]= 34 octets { > > > 40 00 00 06 04 01 00 48 68 4a ff 00 80 01 > > > 00 80 @......HhJ...... > > > 11 1c 00 02 00 c8 75 c0 11 13 e0 00 c8 75 c0 > > > 11 ......u......u.. > > > ... > > > } > > > [5]= 24 octets { > > > 00 00 66 08 68 4a ff 00 80 01 00 80 0b 0d 00 > > > 02 ..f.hJ.......... > > > > > > 00 c8 75 c0 11 13 e1 > > > 00 ..u..... > > > } > > > [6]= 34 octets { > > > 40 00 00 06 04 01 00 48 70 4a ff 00 80 01 00 80 > > > @......HpJ...... > > > 11 1c 00 02 00 c8 75 c0 11 13 e0 0 > > > 0 c8 75 c0 11 ......u......u.. > > > ... > > > } > > > [7]= 24 octets { > > > 00 00 67 08 70 4a ff 00 80 01 00 80 0b 0d 00 > > > 02 ..g.pJ.......... > > > 00 c8 75 c0 11 13 e1 > > > 00 ..u..... > > > > > > } > > > } > > > mediaWaitForConnect = false > > > canOverlapSend = false > > > multipleCalls = false > > > maintainConnection = false > > > parallelH245Control = 2 entries { > > > [0]= 125 octets { > > > > > > 02 70 01 06 00 08 81 75 00 0d 80 13 80 01 f4 > > > 00 .p.....u........ > > > 01 00 00 01 00 00 01 00 00 0c c0 01 00 01 80 > > > 07 ................ > > > ... > > > } > > > [1]= 7 octets { > > > 01 00 32 80 5d 41 2 > > > b ..2.]A+ > > > } > > > } > > > } > > > h245Tunneling = true > > > } > > > } > > > } > > > 2010-12-13 11:58:21.805203 [DEBUG] h323ep.cxx:510 Incoming call, first > > > PDU: callReference=22716 > > > 2010-12-13 11:58:21.805203 [DEBUG] call.cxx:72 Created > > > Call[Cc70e3cf91] > > > 2010-12-13 11:58:21.805203 [DEBUG] connection.cxx:262 Created > > > connection > > > Call[Cc70e3cf91]-EP<h323>[tcp$200.117.192.17:19270/22716] > > > 2010-12-13 11:58:21.805203 [DEBUG] rfc2833.cxx:140 Handler created > > > 2010-12-13 11:58:21.805203 [DEBUG] rfc2833.cxx:328 Rx > > > capability set to > > > "0-16,32,36" > > > 2010-12-13 11:58:21.805203 [DEBUG] rfc2833.cxx:140 Handler created > > > 2010-12-13 11:58:21.805203 [DEBUG] rfc2833.cxx:328 Rx > > > capability set to > > > "192,193" > > > 2010-12-13 11:58:21.805203 [DEBUG] osutil.cxx:189 File handle > > > high water > > > mark set: 68 PUDPSocket > > > 2010-12-13 11:58:21.808186 [DEBUG] h4601.cxx:1583 Endpoint Attached > > > 2010-12-13 11:58:21.808186 [DEBUG] h323ep.cxx:552 Created new > > > connection: tcp$200.117.192.17:19270/22716 > > > 2010-12-13 11:58:21.808186 [DEBUG] h323.cxx:559 Handling PDU: Setup > > > callRef=22716 > > > 2010-12-13 11:58:21.808186 [DEBUG] connection.cxx:1516 SetPhase from > > > UninitialisedPhase to SetUpPhase for Call[Cc70e3cf91]-EP<h323>[tcp > > > $200.117.192.17:19270/22716] > > > 2010-12-13 11:58:21.808186 [DEBUG] h323.cxx:943 Set protocol > > > version to > > > 6 and implying H.245 version 13 > > > 2010-12-13 11:58:21.808186 [DEBUG] h323.cxx:1240 Set remote > > > application > > > name: " 3.2.6 (OPAL v3.6.6) 9/61 " > > > 2010-12-13 11:58:21.808186 [DEBUG] manager.cxx:1392 Checking incoming > > > call for NAT: local=72.51.47.100, peer=200.117.192.17, > > > sig=200.117.192.17 > > > 2010-12-13 11:58:21.808186 [DEBUG] h323.cxx:1041 Sending call > > > proceeding > > > PDU > > > 2010-12-13 11:58:21.811196 [DEBUG] h323pdu.cxx:80 Sending PDU: > > > { > > > q931pdu = { > > > protocolDiscriminator = 8 > > > callReference = 22716 > > > from = destination > > > messageType = CallProceeding > > > IE: Display = { > > > 72 6f 6f 74 00 > > > root. > > > } > > > IE: User-User = { > > > 21 80 06 00 08 91 4a 00 06 22 c0 09 00 00 3d > > > 1e !.....J.."....=. > > > 56 6f 78 20 4c 75 63 69 64 61 20 50 74 79 2e 20 Vox Lucida > > > Pty. > > > ... > > > } > > > } > > > h225pdu = { > > > > > > h323_uu_pdu = { > > > h323_message_body = callProceeding { > > > protocolIdentifier = 0.0.8.2250.0.6 > > > destinationInfo = { > > > vendor = { > > > vendor = { > > > t35CountryCode = 9 > > > t35Extensio > > > n = 0 > > > manufacturerCode = 61 > > > } > > > productId = 31 octets { > > > 56 6f 78 20 4c 75 63 69 64 61 20 50 74 79 2e 20 Vox > > > Lucida Pty. > > > 4c 74 64 2e 20 6d 6f 64 5f 6f 70 61 6c 00 00 > > > Log-Line: 0 > > > UINCOMING DATA [(null)] > > > > > > RECV EVENT > > > Event-Name: SOCKET_DATA > > > ser-Data: _undef_ > > > > > > > > > } > > > } > > > terminal = { > > > } > > > mc = false > > > undefinedNode = false > > > } > > > callIdentifier = { > > > guid = 16 octets { > > > 3a 76 b2 f2 1d 05 e0 11 9b b7 00 1e 64 1f 33 fe > > > :v..........d.3. > > > } > > > } > > > multipleCalls = false > > > maintainConnection = false > > > } > > > h245Tunneling = true > > > } > > > } > > > } > > > 2010-12-13 11:58:21.811196 [DEBUG] manager.cxx:600 OnIncoming > > > connection > > > Call[Cc70e3cf91]-EP<h323>[tcp$200.117.192.17:19270/22716] > > > 2010-12-13 11:58:21.811196 [DEBUG] call.cxx:288 > > > GetOtherPartyConnection > > > Call[Cc70e3cf91]-EP<h323>[tcp$200.117.192.17:19270/22716] > > > 2010-12-13 11:58:21.811196 [DEBUG] manager.cxx:1200 Searching > > > for route > > > "h323:root 231543514280633" > > > 2010-12-13 11:58:21.811196 [DEBUG] manager.cxx:1228 Matched regex > > > "h323:.*" > > > 2010-12-13 11:58:21.811196 [DEBUG] manager.cxx:573 Set up > > > connection to > > > "local:231543514280633" > > > 2010-12-13 11:58:21.811196 [DEBUG] connection.cxx:262 Created > > > connection > > > Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] > > > 2010-12-13 11:58:21.811196 [DEBUG] localep.cxx:205 Created connection > > > with token "Ldc8d6c462" > > > 2010-12-13 11:58:21.811196 [DEBUG] h323.cxx:1075 Incoming > > > call accepted > > > 2010-12-13 11:58:21.811196 [DEBUG] mod_opal.cpp:770 Matched FS codec > > > PCMA to OPAL media format G.711-ALaw-64k > > > 2010-12-13 11:58:21.811196 [DEBUG] mod_opal.cpp:770 Matched FS codec > > > G729 to OPAL media format G.729 > > > 2010-12-13 11:58:21.811196 [DEBUG] mod_opal.cpp:770 Matched FS codec > > > PCMU to OPAL media format G.711-uLaw-64k > > > 2010-12-13 11:58:21.811196 [DEBUG] mod_opal.cpp:770 Matched FS codec > > > G723 to OPAL media format G.723.1 > > > 2010-12-13 11:58:21.814188 [DEBUG] call.cxx:425 GetMediaFormats for > > > Call[Cc70e3cf91]-EP<h323>[tcp$200.117.192.17:19270/22716] > > > G.723.1 > > > G.729 > > > G.711-uLaw-64k > > > G.711-ALaw-64k > > > > > > 2010-12-13 11:58:21.814188 [DEBUG] h323caps.cxx:2381 Could not find > > > capability: "G.723.1" > > > 2010-12-13 11:58:21.814188 [DEBUG] h323caps.cxx:2284 Added capability: > > > G.723.1 <1> > > > 2010-12-13 11:58:21.814188 [DEBUG] h323caps.cxx:2381 Could not find > > > capability: "G.729" > > > 2010-12-13 11:58:21.814188 [DEBUG] h323caps.cxx:2284 Added capability: > > > G.729 <2> > > > 2010-12-13 11:58:21.814188 [DEBUG] h323caps.cxx:2381 Could not find > > > capability: "G.711-uLaw-64k" > > > 2010-12-13 11:58:21.814188 [DEBUG] h323caps.cxx:2284 Added capability: > > > G.711-uLaw-64k <3> > > > 2010-12-13 11:58:21.814188 [DEBUG] h323caps.cxx:2381 Could not find > > > capability: "G.711-ALaw-64k" > > > 2010-12-13 11:58:21.814188 [DEBUG] h323caps.cxx:2284 Added capability: > > > G.711-ALaw-64k <4> > > > 2010-12-13 11:58:21.814188 [DEBUG] h323caps.cxx:2376 Found capability: > > > G.723.1 <1> > > > 2010-12-13 11:58:21.814188 [DEBUG] h323caps.cxx:2376 Found capability: > > > G.729 <2> > > > 2010-12-13 11:58:21.814188 [DEBUG] h323caps.cxx:2376 Found capability: > > > G.711-uLaw-64k <3> > > > 2010-12-13 11:58:21.814188 [DEBUG] h323caps.cxx:2376 Found capability: > > > G.711-ALaw-64k <4> > > > 2010-12-13 11:58:21.814188 [DEBUG] h323caps.cxx:2284 Added capability: > > > UserInput/hookflash <5> > > > 2010-12-13 11:58:21.814188 [DEBUG] h323caps.cxx:2284 Added capability: > > > UserInput/basicString <6> > > > 2010-12-13 11:58:21.817197 [DEBUG] h323caps.cxx:2284 Added capability: > > > UserInput/dtmf <7> > > > 2010-12-13 11:58:21.817197 [DEBUG] h323caps.cxx:2284 Added capability: > > > UserInput/RFC2833 <8> > > > 2010-12-13 11:58:21.817197 [DEBUG] h323caps.cxx:2376 Found capability: > > > UserInput/RFC2833 <8> > > > 2010-12-13 11:58:21.817197 [DEBUG] call.cxx:288 > > > GetOtherPartyConnection > > > Call[Cc70e3cf91]-EP<h323>[tcp$200.117.192.17:19270/22716] > > > 2010-12-13 11:58:21.817197 [DEBUG] h323.cxx:3704 SetLocalCapabilities: > > > Table: > > > G.723.1 <1> > > > G.729 <2> > > > G.711-uLaw-64k <3> > > > G.711-ALaw-64k <4> > > > UserInput/hookflash <5> > > > UserInput/basicString <6> > > > UserInput/dtmf <7> > > > UserInput/RFC2833 <8> > > > Set: > > > 0: > > > > > > > > > Log-Func: > > > Log-Line: 0 > > > User-Data: > > > > > > 0: > > > G.723.1 <1> > > > G.729 <2> > > > G.711-uLaw-64k <3> > > > G.711-ALaw-64k <4> > > > 1: > > > UserInput/hookflash <5> > > > 2: > > > UserInput/basicString <6> > > > UserInput/dtINCOMING DATA [(null)] > > > > > > RECV EVENT > > > Event-Name: SOCKET_DATA > > > > > > > > > 2010-12-13 11:58:21.817197 [DEBUG] h323.cxx:1121 Fast start detected > > > 2010-12-13 11:58:21.817197 [DEBUG] h323caps.cxx:2594 Could not find > > > capability: audioData, type g711Alaw64k > > > 2010-12-13 11:58:21.817197 [DEBUG] h323caps.cxx:845 > > > Capability tx frames > > > left at 20 as remote allows 20 > > > 2010-12-13 11:58:21.817197 [DEBUG] h323caps.cxx:2294 Added capability: > > > G.711-ALaw-64k <4> > > > 2010-12-13 11:58:21.817197 [DEBUG] h323caps.cxx:845 > > > Capability tx frames > > > left at 20 as remote allows 20 > > > 2010-12-13 11:58:21.817197 [DEBUG] h323caps.cxx:2594 Could not find > > > capability: videoData, type h261VideoCapability > > > 2010-12-13 11:58:21.817197 [DEBUG] h323caps.cxx:2594 Could not find > > > capability: videoData, type h261VideoCapability > > > 2010-12-13 11:58:21.817197 [DEBUG] h323caps.cxx:2594 Could not find > > > capability: videoData, type h261VideoCapability > > > 2010-12-13 11:58:21.817197 [DEBUG] h323caps.cxx:2594 Could not find > > > capability: videoData, type h261VideoCapability > > > 2010-12-13 11:58:21.820187 [DEBUG] h323caps.cxx:2594 Could not find > > > capability: videoData, type h261VideoCapability > > > 2010-12-13 11:58:21.820187 [DEBUG] h323caps.cxx:2594 Could not find > > > capability: videoData, type h261VideoCapability > > > 2010-12-13 11:58:21.820187 [DEBUG] h323caps.cxx:2594 Could not find > > > capability: videoData, type h261VideoCapability > > > 2010-12-13 11:58:21.820187 [DEBUG] h323caps.cxx:2594 Could not find > > > capability: videoData, type h261VideoCapability > > > 2010-12-13 11:58:21.820187 [DEBUG] h323caps.cxx:2594 Could not find > > > capability: videoData, type h261VideoCapability > > > 2010-12-13 11:58:21.820187 [DEBUG] h323caps.cxx:2594 Could not find > > > capability: videoData, type h261VideoCapability > > > 2010-12-13 11:58:21.820187 [DEBUG] h323caps.cxx:2594 Could not find > > > capability: videoData, type h261VideoCapability > > > 2010-12-13 11:58:21.820187 [DEBUG] h323caps.cxx:2594 Could not find > > > capability: videoData, type h261VideoCapability > > > 2010-12-13 11:58:21.820187 [DEBUG] call.cxx:288 > > > GetOtherPartyConnection > > > Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] > > > 2010-12-13 11:58:21.820187 [DEBUG] call.cxx:169 OnSetUp > > > Call[Cc70e3cf91]-EP<h323>[tcp$200.117.192.17:19270/22716] > > > 2010-12-13 11:58:21.820187 [DEBUG] localep.cxx:240 Incoming call from > > > gustavo [200.117.192.17] > > > 2010-12-13 11:58:21.820187 [DEBUG] mod_opal.cpp:641 Created switch > > > caller profile: > > > username = > > > dialplan = XML > > > caller_id_name = gustavo [200.117.192.17] > > > caller_id_number = 0000000000 > > > network_addr = > > > source = opal > > > context > > > Log-Func: > > > Log-Line: 0 > > > User-Data: > > > > > > = default > > > 2010-12-13 11:58:21.823196 [NOTICE] switch_channel.c:784 New Channel > > > opal/in:231543514280633 [7b4a89ac-b5d7-4fb4-bab5-ddaa8e73485c] > > > 2010-12-13 11:58:21.823196 [DEBUG] mod_opal.cpp:647 > > > (opal/in:231543514280633) State Change CS_NEW -> CS_INIT > > > 2010-12-13 11:58:21.823196 [DEBUG] mod_opal.cpp:1092 State changed on > > > connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] > > > 2010-12-13 11:58:21.823196 [DEBUG] switch_core_session.c:1083 Send > > > signal opal/in:231543514280633 [BREAK] > > > 2010-12-13 11:58:21.823196 [DEBUG] mod_opal.cpp:931 Kill 3 on > > > connection > > > Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] > > > 2010-12-13 11:58:21.823196 [DEBUG] connection.cxx:1516 SetPhase from > > > UninitialisedPhase to AlertingPhase for > > > Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] > > > 2010-12-13 11:58:21.823196 [NOTICE] mod_opal.cpp:676 Ring-Ready > > > opal/in:231543514280633! > > > 2010-12-13 11:58:21.823196 [DEBUG] manager.cxx:678 OnAlerting > > > Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] > > > 2010-12-13 11:58:21.823196 [DEBUG] call.cxx:196 OnAlerting > > > Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] > > > 2010-12-13 11:58:21.823196 [DEBUG] h323.cxx:2067 SetAlerting > > > Call[Cc70e3cf91]-EP<h323>[tcp$200.117.192.17:19270/22716] > > > 2010-12-13 11:58:21.823196 [DEBUG] switch_core_state_machine.c:320 > > > (opal/in:231543514280633) Running State Change CS_INIT > > > 2010-12-13 11:58:21.823196 [DEBUG] switch_core_state_machine.c:356 > > > (opal/in:231543514280633) State INIT > > > 2010-12-13 11:58:21.823196 [DEBUG] osutil.cxx:189 File handle > > > high water > > > mark set: 72 Thread unblock pipe > > > 2010-12-13 11:58:21.823196 [DEBUG] tlibthrd.cxx:587 Thread high water > > > mark set: 10 > > > 2010-12-13 11:58:21.823196 [DEBUG] mod_opal.cpp:842 Started > > > routing for > > > connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] > > > 2010-12-13 11:58:21.823196 [DEBUG] mod_opal.cpp:843 > > > (opal/in:231543514280633) State Change CS_INIT -> CS_ROUTING > > > 2010-12-13 11:58:21.823196 [DEBUG] mod_opal.cpp:1092 State changed on > > > connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] > > > 2010-12-13 11:58:21.823196 [DEBUG] switch_core_session.c:1083 Send > > > signal opal/in:231543514280633 [BREAK] > > > 2010-12-13 11:58:21.823196 [DEBUG] mod_opal.cpp:931 Kill 3 on > > > connection > > > Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] > > > 2010-12-13 11:58:21.823196 [DEBUG] switch_core_state_machine.c:356 > > > (opal/in:231543514280633) State INIT going to sleep > > > 2010-12-13 11:58:21.823196 [DEBUG] switch_core_state_machine.c:320 > > > (opal/in:231543514280633) Running State Change CS_ROUTING > > > 2010-12-13 11:58:21.823196 [DEBUG] switch_channel.c:1615 > > > (opal/in:231543514280633) Callstate Change DOWN -> RINGING > > > 2010-12-13 11:58:21.823196 [DEBUG] switch_core_state_machine.c:359 > > > (opal/in:231543514280633) State ROUTING > > > 2010-12-13 11:58:21.823196 [DEBUG] mod_opal.cpp:850 Routing connection > > > Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] > > > 2010-12-13 11:58:21.823196 [DEBUG] switch_core_state_machine.c:77 > > > opal/in:231543514280633 Standard ROUTING > > > 2010-12-13 11:58:21.823196 [INFO] mod_dialplan_xml.c:331 Processing > > > gustavo [200.117.192.17] <0000000000>->231543514280633 in context > > > default > > > 2010-12-13 11:58:21.826206 [DEBUG] h323pdu.cxx:80 Receiving PDU: > > > request terminalCapabilitySet { > > > sequenceNumber = 1 > > > protocolIdentifier = 0.0.8.245.0.13 > > > multiplexCapability = h2250Capability { > > > maximumAudioDelayJitter = 500 > > > receiveMultipointCapabi > > > lity = { > > > multicastCapability = false > > > multiUniCastConference = false > > > mediaDistributionCapability = 1 entries { > > > [0]={ > > > centralizedControl = false > > > distributedControl = false > > > centraliz > > > edAudio = false > > > distributedAudio = false > > > centralizedVideo = false > > > distributedVideo = false > > > } > > > } > > > } > > > transmitMultipointCapability = { > > > multicastCapability = false > > > multiUniC > > > astConference = false > > > mediaDistributionCapability = 1 entries { > > > [0]={ > > > centralizedControl = false > > > distributedControl = false > > > centralizedAudio = false > > > distributedAudio = false > > > > > > centralizedVideo = false > > > distributedVideo = false > > > } > > > } > > > } > > > receiveAndTransmitMultipointCapability = { > > > multicastCapability = false > > > multiUniCastConference = false > > > mediaDistributionCapab > > > ility = 1 entries { > > > [0]={ > > > centralizedControl = false > > > distributedControl = false > > > centralizedAudio = false > > > distributedAudio = false > > > centralizedVideo = false > > > distributedV > > > ideo = false > > > } > > > } > > > } > > > mcCapability = { > > > centralizedConferenceMC = false > > > decentralizedConferenceMC = false > > > } > > > rtcpVideoControlCapability = false > > > mediaPacketizationCapability = { > > > h261a > > > VideoPacketization = false > > > } > > > logicalChannelSwitchingCapability = false > > > t120DynamicPortCapability = true > > > } > > > capabilityTable = 8 entries { > > > [0]={ > > > capabilityTableEntryNumber = 1 > > > capability = receiveAudioCap > > > ability g711Alaw64k 240 > > > } > > > [1]={ > > > capabilityTableEntryNumber = 2 > > > capability = receiveVideoCapability h261VideoCapability { > > > qcifMPI = 1 > > > cifMPI = 1 > > > temporalSpatialTradeOffCapability = false > > > > > > maxBitRate = 19200 > > > stillImageTransmission = false > > > videoBadMBsCap = false > > > } > > > } > > > [2]={ > > > capabilityTableEntryNumber = 3 > > > capability = receiveVideoCapability h261VideoCapability { > > > cifMPI > > > = 1 > > > temporalSpatialTradeOffCapability = false > > > maxBitRate = 19200 > > > stillImageTransmission = false > > > videoBadMBsCap = false > > > } > > > } > > > [3]={ > > > capabilityTableEntryNumber = 4 > > > capability = > > > receiveVideoCapability h261VideoCapability { > > > qcifMPI = 1 > > > temporalSpatialTradeOffCapability = false > > > maxBitRate = 19200 > > > stillImageTransmission = false > > > videoBadMBsCap = false > > > } > > > } > > > [4 > > > ]={ > > > capabilityTableEntryNumber = 5 > > > capability = receiveUserInputCapability hookflash <<null>> > > > } > > > [5]={ > > > capabilityTableEntryNumber = 6 > > > capability = receiveUserInputCapability basicString <<null>> > > > } > > > > > > [6]={ > > > capabilityTableEntryNumber = 7 > > > capability = receiveUserInputCapability dtmf <<null>> > > > } > > > [7]={ > > > capabilityTableEntryNumber = 8 > > > capability = receiveRTPAudioTelephonyEventCapability { > > > dynamic > > > RTPPayloadType = 101 > > > audioTelephoneEvent = "0-16" > > > } > > > } > > > } > > > capabilityDescriptors = 1 entries { > > > [0]={ > > > capabilityDescriptorNumber = 1 > > > simultaneousCapabilities = 4 entries { > > > [0]=1 entries { > > > > > > [0]=1 > > > } > > > [1]=3 entries { > > > [0]=2 > > > [1]=3 > > > [2]=4 > > > } > > > [2]=1 entries { > > > [0]=5 > > > } > > > [3]=3 entries { > > > [0]=6 > > > [1]=7 > > > > > > Log-Func: > > > Log-Line: 0 > > > User-Data: > > > > > > [2]=8 > > > 2010-12-13 11:58:21.826206 [DEBUG] h323.cxx:2792 Set protocol > > > version to 13 > > > 2010-12-13 11:58:21.826206 [DEBUG] h323neg.cxx:378 Received > > > TerminalCapabilitySet: state=Idle pduSeq=1 inSeq=4294967295 > > > 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:1988 > > > H323Capabilities(ctor) > > > 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2294 Added capability: > > > G.723.1 <1> > > > 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2294 Added capability: > > > G.729 <2> > > > 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2294 Added capability: > > > G.711-uLaw-64k <3> > > > 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2294 Added capability: > > > G.711-ALaw-64k <4> > > > 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2294 Added capability: > > > UserInput/hookflash <5> > > > 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2294 Added capability: > > > UserInput/basicString <6> > > > 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2294 Added capability: > > > UserInput/dtmf <7> > > > 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2294 Added capability: > > > UserInput/RFC2833 <8> > > > 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2355 Found capability: > > > G.723.1 <1> > > > 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2355 Found capability: > > > G.729 <2> > > > 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2355 Found capability: > > > G.711-uLaw-64k <3> > > > 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2355 Found capability: > > > G.711-ALaw-64k <4> > > > 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2355 Found capability: > > > UserInput/hookflash <5> > > > 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2355 Found capability: > > > UserInput/basicString <6> > > > 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2355 Found capability: > > > UserInput/dtmf <7> > > > 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2355 Found capability: > > > UserInput/RFC2833 <8> > > > 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2376 Found capability: > > > G.711-ALaw-64k <4> > > > 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2376 Found capability: > > > G.711-uLaw-64k <3> > > > 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2376 Found capability: > > > G.723.1 <1> > > > 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2381 Could not find > > > capability: "G.723.1(5.3k)" > > > 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2284 Added capability: > > > G.723.1 <9> > > > 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2381 Could not find > > > capability: "G.723.1A(5.3k)" > > > 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2284 Added capability: > > > G.723.1 <10> > > > 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2381 Could not find > > > capability: "G.723.1A(6.3k)" > > > 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2284 Added capability: > > > G.723.1 <11> > > > 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2381 Could not find > > > capability: "G.728" > > > 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2284 Added capability: > > > G.728 <12> > > > 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2376 Found capability: > > > G.729 <2> > > > 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2381 Could not find > > > capability: "G.729A" > > > 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2284 Added capability: > > > G.729A <13> > > > 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2381 Could not find > > > capability: "G.729A/B" > > > 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2284 Added capability: > > > G.729A/B <14> > > > 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2381 Could not find > > > capability: "G.729B" > > > 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2284 Added capability: > > > G.729B <15> > > > 2010-12-13 11:58:21.831146 [DEBUG] h323caps.cxx:2381 Could not find > > > capability: "GSM-06.10" > > > 2010-12-13 11:58:21.831146 [DEBUG] h323caps.cxx:2284 Added capability: > > > GSM-06.10 <16> > > > 2010-12-13 11:58:21.831146 [DEBUG] h323caps.cxx:2381 Could not find > > > capability: "GSM-AMR" > > > 2010-12-13 11:58:21.831146 [DEBUG] h323caps.cxx:2284 Added capability: > > > GSM-AMR <17> > > > 2010-12-13 11:58:21.831146 [DEBUG] h323caps.cxx:2381 Could not find > > > capability: "T.38" > > > 2010-12-13 11:58:21.831146 [DEBUG] h323caps.cxx:2284 Added capability: > > > T.38 <18> > > > 2010-12-13 11:58:21.831146 [DEBUG] h323caps.cxx:2376 Found capability: > > > UserInput/RFC2833 <8> > > > 2010-12-13 11:58:21.831146 [DEBUG] h323caps.cxx:2376 Found capability: > > > UserInput/basicString <6> > > > 2010-12-13 11:58:21.834192 [DEBUG] h323caps.cxx:2376 Found capability: > > > UserInput/dtmf <7> > > > 2010-12-13 11:58:21.834192 [DEBUG] h323caps.cxx:2381 Could not find > > > capability: "UserInput/generalString" > > > 2010-12-13 11:58:21.834192 [DEBUG] h323caps.cxx:2284 Added capability: > > > UserInput/generalString <19> > > > 2010-12-13 11:58:21.834192 [DEBUG] h323caps.cxx:2376 Found capability: > > > UserInput/hookflash <5> > > > 2010-12-13 11:58:21.834192 [DEBUG] h323caps.cxx:2381 Could not find > > > capability: "UserInput/iA5String" > > > 2010-12-13 11:58:21.834192 [DEBUG] h323caps.cxx:2284 Added capability: > > > UserInput/iA5String <20> > > > 2010-12-13 11:58:21.834192 [DEBUG] h323caps.cxx:2381 Could not find > > > capability: "iLBC" > > > 2010-12-13 11:58:21.834192 [DEBUG] h323caps.cxx:2284 Added capability: > > > iLBC <21> > > > 2010-12-13 11:58:21.834192 [DEBUG] h323caps.cxx:2284 Added capability: > > > UserInput/hookflash <22> > > > 2010-12-13 11:58:21.834192 [DEBUG] h323caps.cxx:2284 Added capability: > > > UserInput/basicString <23> > > > 2010-12-13 11:58:21.834192 [DEBUG] h323caps.cxx:2284 Added capability: > > > UserInput/dtmf <24> > > > 2010-12-13 11:58:21.834192 [DEBUG] h323caps.cxx:2284 Added capability: > > > UserInput/RFC2833 <25> > > > 2010-12-13 11:58:21.834192 [CONSOLE] mod_xml_curl.c:312 XML > > > response is > > > in /tmp/90f1dd7d-a63c-4e15-981c-6eed9e70313b.tmp.xml > > > Dialplan: opal/in:231543514280633 parsing [default->external] > > > continue=false > > > Dialplan: opal/in:231543514280633 Regex (PASS) [external] > > > destination_number(231543514280633) =~ /^231543514280633/ > > > break=on-false > > > Dialplan: opal/in:231543514280633 Action set(continue_on_fail=true) > > > Dialplan: opal/in:231543514280633 Action > > > set(hangup_after_bridge=true) > > > Dialplan: opal/in:231543514280633 Action set(progress_timeout=15) > > > Dialplan: opal/in:231543514280633 Action set(proxy_media=false) > > > Dialplan: opal/in:231543514280633 Action set(bypass_media=true) > > > Dialplan: opal/in:231543514280633 Action > > > set(absolute_codec_string=PCMA) > > > Dialplan: opal/in:231543514280633 Action > > > bridge(sofia/external/21543514280633(a)200.35.145.149) > > > 2010-12-13 11:58:21.834192 [DEBUG] switch_core_state_machine.c:119 > > > (opal/in:231543514280633) State Change CS_ROUTING -> CS_EXECUTE > > > 2010-12-13 11:58:21.834192 [DEBUG] mod_opal.cpp:1092 State changed on > > > connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] > > > 2010-12-13 11:58:21.834192 [DEBUG] switch_core_session.c:1083 Send > > > signal opal/in:231543514280633 [BREAK] > > > 2010-12-13 11:58:21.834192 [DEBUG] mod_opal.cpp:931 Kill 3 on > > > connection > > > Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] > > > 2010-12-13 11:58:21.834192 [DEBUG] switch_core_state_machine.c:359 > > > (opal/in:231543514280633) State ROUTING going to sleep > > > 2010-12-13 11:58:21.834192 [DEBUG] switch_core_state_machine.c:320 > > > (opal/in:231543514280633) Running State Change CS_EXECUTE > > > 2010-12-13 11:58:21.834192 [DEBUG] h323caps.cxx:2284 Added capability: > > > H.239-Video+H.239-Video <26> > > > 2010-12-13 11:58:21.834192 [DEBUG] switch_core_state_machine.c:366 > > > (opal/in:231543514280633) State EXECUTE > > > 2010-12-13 11:58:21.834192 [DEBUG] mod_opal.cpp:857 Executing > > > connection > > > Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] > > > 2010-12-13 11:58:21.834192 [DEBUG] switch_core_state_machine.c:157 > > > opal/in:231543514280633 Standard EXECUTE > > > EXECUTE opal/in:231543514280633 set(continue_on_fail=true) > > > 2010-12-13 11:58:21.837191 [DEBUG] h323caps.cxx:2284 Added capability: > > > H.239-Control <27> > > > 2010-12-13 11:58:21.837191 [DEBUG] h323caps.cxx:2029 Parsing remote > > > capabilities > > > 2010-12-13 11:58:21.837191 [DEBUG] mod_opal.cpp:1076 Received > > > message 26 > > > on connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] > > > 2010-12-13 11:58:21.837191 [DEBUG] mod_dptools.c:1028 > > > opal/in:231543514280633 SET [continue_on_fail]=[true] > > > 2010-12-13 11:58:21.837191 [DEBUG] h323caps.cxx:819 > > > Capability tx frames > > > left at 20 as remote allows 240 > > > 2010-12-13 11:58:21.837191 [DEBUG] h323caps.cxx:2529 Could not find > > > capability: receiveVideoCapability, type h261VideoCapability > > > 2010-12-13 11:58:21.837191 [DEBUG] h323caps.cxx:2529 Could not find > > > capability: receiveVideoCapability, type h261VideoCapability > > > 2010-12-13 11:58:21.837191 [DEBUG] h323caps.cxx:2529 Could not find > > > capability: receiveVideoCapability, type h261VideoCapability > > > 2010-12-13 11:58:21.837191 [DEBUG] h323caps.cxx:2671 Found capability: > > > UserInput/RFC2833 <8> > > > 2010-12-13 11:58:21.837191 [DEBUG] mod_opal.cpp:1076 Received > > > message 27 > > > on connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] > > > EXECUTE opal/in:231543514280633 set(hangup_after_bridge=true) > > > 2010-12-13 11:58:21.837191 [DEBUG] mod_opal.cpp:1076 Received > > > message 26 > > > on connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] > > > 2010-12-13 11:58:21.837191 [DEBUG] mod_dptools.c:1028 > > > opal/in:231543514280633 SET [hangup_after_bridge]=[true] > > > 2010-12-13 11:58:21.837191 [DEBUG] h323caps.cxx:2411 Could not find > > > capability: G.711-ALaw-64k <1> > > > 2010-12-13 11:58:21.837191 [DEBUG] h323caps.cxx:2294 Added capability: > > > G.711-ALaw-64k <1> > > > 2010-12-13 11:58:21.837191 [DEBUG] mod_opal.cpp:1076 Received > > > message 27 > > > on connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] > > > EXECUTE opal/in:231543514280633 set(progress_timeout=15) > > > 2010-12-13 11:58:21.837191 [DEBUG] h323caps.cxx:2411 Could not find > > > capability: UserInput/hookflash <5> > > > 2010-12-13 11:58:21.837191 [DEBUG] h323caps.cxx:2294 Added capability: > > > UserInput/hookflash <5> > > > 2010-12-13 11:58:21.837191 [DEBUG] h323caps.cxx:2411 Could not find > > > capability: UserInput/basicString <6> > > > 2010-12-13 11:58:21.837191 [DEBUG] mod_opal.cpp:1076 Received > > > message 26 > > > on connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] > > > 2010-12-13 11:58:21.837191 [DEBUG] mod_dptools.c:1028 > > > opal/in:231543514280633 SET [progress_timeout]=[15] > > > 2010-12-13 11:58:21.837191 [DEBUG] h323caps.cxx:2294 Added capability: > > > UserInput/basicString <6> > > > 2010-12-13 11:58:21.837191 [DEBUG] h323caps.cxx:2411 Could not find > > > capability: UserInput/dtmf <7> > > > 2010-12-13 11:58:21.840189 [DEBUG] h323caps.cxx:2294 Added capability: > > > UserInput/dtmf <7> > > > 2010-12-13 11:58:21.840189 [DEBUG] h323caps.cxx:2411 Could not find > > > capability: UserInput/RFC2833 <8> > > > 2010-12-13 11:58:21.840189 [DEBUG] h323caps.cxx:2294 Added capability: > > > UserInput/RFC2833 <8> > > > 2010-12-13 11:58:21.840189 [DEBUG] h323caps.cxx:2355 Found capability: > > > G.711-ALaw-64k <1> > > > 2010-12-13 11:58:21.840189 [DEBUG] h323caps.cxx:2355 Found capability: > > > UserInput/hookflash <5> > > > 2010-12-13 11:58:21.840189 [DEBUG] h323caps.cxx:2355 Found capability: > > > UserInput/basicString <6> > > > 2010-12-13 11:58:21.840189 [DEBUG] h323caps.cxx:2355 Found capability: > > > UserInput/dtmf <7> > > > 2010-12-13 11:58:21.840189 [DEBUG] h323caps.cxx:2355 Found capability: > > > UserInput/RFC2833 <8> > > > 2010-12-13 11:58:21.840189 [DEBUG] mod_opal.cpp:1076 Received > > > message 27 > > > on connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] > > > EXECUTE opal/in:231543514280633 set(proxy_media=false) > > > 2010-12-13 11:58:21.840189 [DEBUG] h323caps.cxx:2777 Capability merge > > > result: > > > Table: > > > G.711-ALaw-64k <1> > > > UserInput/hookflash <5> > > > UserInput/basicString <6> > > > UserInput/dtmf <7> > > > UserInput/RFC2833 <8> > > > Set: > > > 0: > > > 0: > > > > > > > > > Log-Func: > > > Log-Line: 0 > > > User-Data: > > > > > > G.711-ALaw-64k <1> > > > 1: > > > 2: > > > UserInput/hookflash <5> > > > 3: > > > UserInput/basicString <6> > > > UserInput/dtmf <INCOMING DATA [(null)] > > > > > > RECV EVENT > > > Event-Name: SOCKET_DATA > > > > > > > > > 2010-12-13 11:58:21.840189 [DEBUG] h323caps.cxx:2778 Received > > > capability > > > set, is accepted > > > 2010-12-13 11:58:21.840189 [DEBUG] mod_opal.cpp:1076 Received > > > message 26 > > > on connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] > > > 2010-12-13 11:58:21.840189 [DEBUG] mod_dptools.c:1028 > > > opal/in:231543514280633 SET [proxy_media]=[false] > > > 2010-12-13 11:58:21.840189 [DEBUG] h323neg.cxx:341 Sending > > > TerminalCapabilitySet: outSeq=1 > > > 2010-12-13 11:58:21.840189 [DEBUG] mod_opal.cpp:1076 Received > > > message 27 > > > on connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] > > > EXECUTE opal/in:231543514280633 set(bypass_media=true) > > > 2010-12-13 11:58:21.840189 [DEBUG] mod_opal.cpp:1076 Received > > > message 26 > > > on connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] > > > 2010-12-13 11:58:21.840189 [DEBUG] mod_dptools.c:1028 > > > opal/in:231543514280633 SET [bypass_media]=[true] > > > 2010-12-13 11:58:21.840189 [DEBUG] mod_opal.cpp:1076 Received > > > message 27 > > > on connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] > > > EXECUTE opal/in:231543514280633 set(absolute_codec_string=PCMA) > > > 2010-12-13 11:58:21.840189 [DEBUG] mod_opal.cpp:1076 Received > > > message 26 > > > on connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] > > > 2010-12-13 11:58:21.840189 [DEBUG] mod_dptools.c:1028 > > > opal/in:231543514280633 SET [absolute_codec_string]=[PCMA] > > > 2010-12-13 11:58:21.840189 [DEBUG] mod_opal.cpp:1076 Received > > > message 27 > > > on connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] > > > EXECUTE opal/in:231543514280633 > > > bridge(sofia/external/21543514280633(a)200.35.145.149) > > > 2010-12-13 11:58:21.840189 [DEBUG] mod_opal.cpp:1076 Received > > > message 26 > > > on connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] > > > 2010-12-13 11:58:21.840189 [NOTICE] switch_channel.c:784 New Channel > > > sofia/external/21543514280633(a)200.35.145.149 > > > [f9bf5b9e-a273-400c-b18c-e2c98c84750f] > > > 2010-12-13 11:58:21.840189 [DEBUG] mod_sofia.c:3995 > > > (sofia/external/21543514280633(a)200.35.145.149) State Change CS_NEW -> > > > CS_INIT > > > 2010-12-13 11:58:21.840189 [DEBUG] switch_core_session.c:1083 Send > > > signal sofia/external/21543514280633(a)200.35.145.149 [BREAK] > > > 2010-12-13 11:58:21.843189 [DEBUG] h323pdu.cxx:80 Sending PDU: > > > request terminalCapabilitySet { > > > sequenceNumber = 1 > > > protocolIdentifier = 0.0.8.245.0.13 > > > multiplexCapability = h2250Capability { > > > maximumAudioDelayJitter = 250 > > > receiveMultipointCapabili > > > ty = { > > > multicastCapability = false > > > multiUniCastConference = false > > > mediaDistributionCapability = 1 entries { > > > [0]={ > > > centralizedControl = false > > > distributedControl = false > > > centralized > > > Audio = false > > > distributedAudio = false > > > centralizedVideo = false > > > distributedVideo = false > > > } > > > } > > > } > > > transmitMultipointCapability = { > > > multicastCapability = false > > > multiUniCas > > > tConference = false > > > mediaDistributionCapability = 1 entries { > > > [0]={ > > > centralizedControl = false > > > distributedControl = false > > > centralizedAudio = false > > > distributedAudio = false > > > > > > centralizedVideo = false > > > distributedVideo = false > > > } > > > } > > > } > > > receiveAndTransmitMultipointCapability = { > > > multicastCapability = false > > > multiUniCastConference = false > > > mediaDistributionCapabil > > > ity = 1 entries { > > > [0]={ > > > centralizedControl = false > > > distributedControl = false > > > centralizedAudio = false > > > distributedAudio = false > > > centralizedVideo = false > > > distributedVid > > > eo = false > > > } > > > } > > > } > > > mcCapability = { > > > centralizedConferenceMC = false > > > decentralizedConferenceMC = false > > > } > > > rtcpVideoControlCapability = false > > > mediaPacketizationCapability = { > > > h261aVi > > > deoPacketization = false > > > } > > > logicalChannelSwitchingCapability = false > > > t120DynamicPortCapability = true > > > } > > > capabilityTable = 8 entries { > > > [0]={ > > > capabilityTableEntryNumber = 1 > > > capability = receiveAudioCapab > > > ility g7231 { > > > maxAl_sduAudioFrames = 8 > > > silenceSuppression = false > > > } > > > } > > > [1]={ > > > capabilityTableEntryNumber = 2 > > > capability = receiveAudioCapability g729 24 > > > } > > > [2]={ > > > capabilityTabl > > > eEntryNumber = 3 > > > capability = receiveAudioCapability g711Ulaw64k 240 > > > } > > > [3]={ > > > capabilityTableEntryNumber = 4 > > > capability = receiveAudioCapability g711Alaw64k 240 > > > } > > > [4]={ > > > capabilityTableEntryNumbe > > > r = 5 > > > capability = receiveUserInputCapability hookflash <<null>> > > > } > > > [5]={ > > > capabilityTableEntryNumber = 6 > > > capability = receiveUserInputCapability basicString <<null>> > > > } > > > [6]={ > > > capabilityTableEntry > > > Number = 7 > > > capability = receiveUserInputCapability dtmf <<null>> > > > } > > > [7]={ > > > capabilityTableEntryNumber = 8 > > > capability = receiveRTPAudioTelephonyEventCapability { > > > dynamicRTPPayloadType = 101 > > > audioT > > > elephoneEvent = "0-16" > > > } > > > } > > > } > > > capabilityDescriptors = 1 entries { > > > [0]={ > > > capabilityDescriptorNumber = 1 > > > simultaneousCapabilities = 3 entries { > > > [0]=4 entries { > > > [0]=1 > > > [1]=2 > > > > > > [2]=3 > > > [3]=4 > > > } > > > [1]=1 entries { > > > [0]=5 > > > } > > > [2]=3 entries { > > > [0]=6 > > > [1]=7 > > > [2]=8 > > > } > > > } > > > } > > > } > > > } > > > 2010-12-13 11:58:21.843189 [DEBUG] h323caps.cxx:2376 Found capability: > > > UserInput/RFC2833 <8> > > > 2010-12-13 11:58:21.843189 [DEBUG] h323.cxx:3535 User Input RFC2833 > > > payload type set to [pt=101] > > > 2010-12-13 11:58:21.843189 [DEBUG] h323pdu.cxx:80 Sending PDU: > > > response terminalCapabilitySetAck { > > > sequenceNumber = 1 > > > } > > > 2010-12-13 11:58:21.843189 [DEBUG] switch_core_state_machine.c:320 > > > (sofia/external/21543514280633(a)200.35.145.149) Running State Change > > > CS_INIT > > > 2010-12-13 11:58:21.843189 [DEBUG] switch_core_state_machine.c:356 > > > (sofia/external/21543514280633(a)200.35.145.149) State INIT > > > 2010-12-13 11:58:21.843189 [DEBUG] mod_sofia.c:86 > > > sofia/external/21543514280633(a)200.35.145.149 SOFIA INIT > > > 2010-12-13 11:58:21.843189 [DEBUG] h323.cxx:3771 > > > InternalEstablishedConnectionCheck: > > > connectionState=AwaitingLocalAnswer > > > fastStartState=FastStartResponse H.245 is unavailable > > > 2010-12-13 11:58:21.843189 [DEBUG] h323pdu.cxx:80 Receiving PDU: > > > request masterSlaveDetermination { > > > terminalType = 50 > > > statusDeterminationNumber = 6111531 > > > } > > > 2010-12-13 11:58:21.843189 [DEBUG] h323neg.cxx:129 Received > > > MasterSlaveDetermination: state=Idle > > > 2010-12-13 11:58:21.843189 [DEBUG] h323neg.cxx:160 > > > MasterSlaveDetermination: local is master > > > 2010-12-13 11:58:21.843189 [DEBUG] h323pdu.cxx:80 Sending PDU: > > > response masterSlaveDeterminationAck { > > > decision = slave <<null>> > > > } > > > 2010-12-13 11:58:21.843189 [DEBUG] h323.cxx:3771 > > > InternalEstablishedConnectionCheck: > > > connectionState=AwaitingLocalAnswer > > > fastStartState=FastStartResponse H.245 is ready > > > 2010-12-13 11:58:21.843189 [DEBUG] mod_sofia.c:126 > > > (sofia/external/21543514280633(a)200.35.145.149) State Change CS_INIT -> > > > CS_ROUTING > > > 2010-12-13 11:58:21.843189 [DEBUG] switch_core_session.c:1083 Send > > > signal sofia/external/21543514280633(a)200.35.145.149 [BREAK] > > > 2010-12-13 11:58:21.843189 [DEBUG] sofia.c:4604 Channel > > > sofia/external/21543514280633(a)200.35.145.149 entering state > > > [terminated][900] > > > 2010-12-13 11:58:21.843189 [DEBUG] h323ep.cxx:1033 > > > OnSendAlerting conn = > > > Call[Cc70e3cf91]-EP<h323>[tcp$200.117.192.17:19270/22716] > > > 2010-12-13 11:58:21.843189 [DEBUG] switch_channel.c:2455 > > > (sofia/external/21543514280633(a)200.35.145.149) Callstate > > > Change DOWN -> > > > HANGUP > > > 2010-12-13 11:58:21.843189 [DEBUG] h323.cxx:2110 SetAlerting sending > > > Alerting PDU > > > 2010-12-13 11:58:21.843189 [NOTICE] sofia.c:5244 Hangup > > > sofia/external/21543514280633(a)200.35.145.149 [CS_ROUTING] > > > [NORMAL_UNSPECIFIED] > > > 2010-12-13 11:58:21.843189 [DEBUG] switch_channel.c:2471 Send signal > > > sofia/external/21543514280633(a)200.35.145.149 [KILL] > > > 2010-12-13 11:58:21.843189 [DEBUG] switch_core_session.c:1083 Send > > > signal sofia/external/21543514280633(a)200.35.145.149 [BREAK] > > > 2010-12-13 11:58:21.843189 [DEBUG] switch_core_state_machine.c:356 > > > (sofia/external/21543514280633(a)200.35.145.149) State INIT > > > going to sleep > > > 2010-12-13 11:58:21.843189 [DEBUG] switch_core_state_machine.c:320 > > > (sofia/external/21543514280633(a)200.35.145.149) Running State Change > > > CS_HANGUP > > > 2010-12-13 11:58:21.846211 [DEBUG] h323pdu.cxx:80 Sending PDU: > > > { > > > q931pdu = { > > > protocolDiscriminator = 8 > > > callReference = 22716 > > > from = destination > > > messageType = Alerting > > > IE: Display = { > > > 72 6f 6f 74 00 > > > root. > > > } > > > IE: User-User = { > > > 23 80 06 00 08 91 4a 00 06 22 c0 09 00 00 3d 1e > > > #.....J.."....=. > > > 56 6f 78 20 4c 75 63 69 64 61 20 50 74 79 2e 20 Vox Lucida > > > Pty. > > > ... > > > } > > > } > > > h225pdu = { > > > h323 > > > _uu_pdu = { > > > h323_message_body = alerting { > > > protocolIdentifier = 0.0.8.2250.0.6 > > > destinationInfo = { > > > vendor = { > > > vendor = { > > > t35CountryCode = 9 > > > t35Extension = 0 > > > > > > manufacturerCode = 61 > > > } > > > productId = 31 octets { > > > 56 6f 78 20 4c 75 63 69 64 61 20 50 74 79 2e 20 Vox > > > Lucida Pty. > > > 4c 74 64 2e 20 6d 6f 64 5f 6f 70 61 6c 00 00 > > > Ltd. > > > mod_op > > > Log-Line: 0 > > > User-Data: > > > > > > } > > > terminal = { > > > } > > > mc = false > > > undefinedNode = false > > > } > > > callIdentifier = { > > > guid = 16 octets { > > > 3a 76 b2 f2 1d 05 e0 11 9b b7 00 1e 64 1f 33 > > > fe :v........ > > > ..d.3. > > > } > > > } > > > multipleCalls = false > > > maintainConnection = false > > > } > > > h245Tunneling = true > > > h245Control = 3 entries { > > > [0]= 108 octets { > > > 02 70 01 06 00 08 81 75 00 0d 80 > > > 13 80 00 fa 00 .p.....u........ > > > 01 00 00 01 00 00 01 00 00 0c c0 01 00 01 80 > > > 07 ................ > > > ... > > > } > > > [1]= 3 octets { > > > 21 80 01 !.. > > > } > > > > > > [2]= 2 octets { > > > 20 a0 . > > > } > > > } > > > } > > > } > > > } > > > 2010-12-13 11:58:21.846211 [DEBUG] h323ep.cxx:1039 > > > OnSentAlerting conn = > > > Call[Cc70e3cf91]-EP<h323>[tcp$200.117.192.17:19270/22716] > > > 2010-12-13 11:58:21.846211 [DEBUG] h323.cxx:3771 > > > InternalEstablishedConnectionCheck: > > > connectionState=AwaitingLocalAnswer > > > fastStartState=FastStartResponse H.245 is ready > > > 2010-12-13 11:58:21.846211 [DEBUG] connection.cxx:361 > > > OnSetUpConnectionCall[Cc70e3cf91]-EP<local>[Ldc8d6c462] > > > 2010-12-13 11:58:21.846211 [DEBUG] endpoint.cxx:408 OnSetUpConnection > > > Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] > > > 2010-12-13 11:58:21.846211 [DEBUG] h323.cxx:1701 OnAnswerCall > > > Call[Cc70e3cf91]-EP<h323>[tcp$200.117.192.17:19270/22716], caller = > > > gustavo [200.117.192.17] > > > 2010-12-13 11:58:21.846211 [DEBUG] call.cxx:219 OnAnswerCall > > > Call[Cc70e3cf91]-EP<h323>[tcp$200.117.192.17:19270/22716] caller > > > "gustavo [200.117.192.17]" > > > 2010-12-13 11:58:21.846211 [DEBUG] h323.cxx:1713 Answering call: > > > AnswerCallDeferred > > > 2010-12-13 11:58:21.846211 [DEBUG] connection.cxx:541 Answering call: > > > AnswerCallDeferred > > > 2010-12-13 11:58:21.846211 [DEBUG] h323.cxx:3771 > > > InternalEstablishedConnectionCheck: > > > connectionState=AwaitingLocalAnswer > > > fastStartState=FastStartResponse H.245 is ready > > > 2010-12-13 11:58:21.846211 [DEBUG] h323.cxx:506 Reading PDUs: > > > callRef=22716 > > > 2010-12-13 11:58:21.846211 [DEBUG] > > > switch_ivr_originate.c:3448 Originate > > > Resulted in Error Cause: 31 [NORMAL_UNSPECIFIED] > > > 2010-12-13 11:58:21.849192 [INFO] mod_dptools.c:2579 Originate Failed. > > > Cause: NORMAL_UNSPECIFIED > > > 2010-12-13 11:58:21.849192 [DEBUG] mod_opal.cpp:1076 Received > > > message 27 > > > on connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] > > > 2010-12-13 11:58:21.849192 [NOTICE] switch_core_state_machine.c:189 > > > opal/in:231543514280633 has executed the last dialplan instruction, > > > hanging up. > > > 2010-12-13 11:58:21.849192 [DEBUG] switch_channel.c:2455 > > > (opal/in:231543514280633) Callstate Change RINGING -> HANGUP > > > 2010-12-13 11:58:21.849192 [NOTICE] switch_core_state_machine.c:191 > > > Hangup opal/in:231543514280633 [CS_EXECUTE] [NORMAL_CLEARING] > > > 2010-12-13 11:58:21.849192 [DEBUG] switch_channel.c:2471 Send signal > > > opal/in:231543514280633 [KILL] > > > 2010-12-13 11:58:21.849192 [DEBUG] mod_opal.cpp:931 Kill 1 on > > > connection > > > Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] > > > 2010-12-13 11:58:21.849192 [DEBUG] mod_opal.cpp:1092 State changed on > > > connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] > > > 2010-12-13 11:58:21.849192 [DEBUG] switch_core_session.c:1083 Send > > > signal opal/in:231543514280633 [BREAK] > > > 2010-12-13 11:58:21.849192 [DEBUG] mod_opal.cpp:931 Kill 3 on > > > connection > > > Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] > > > 2010-12-13 11:58:21.849192 [DEBUG] switch_core_state_machine.c:366 > > > (opal/in:231543514280633) State EXECUTE going to sleep > > > 2010-12-13 11:58:21.849192 [DEBUG] switch_core_state_machine.c:320 > > > (opal/in:231543514280633) Running State Change CS_HANGUP > > > 2010-12-13 11:58:21.849192 [DEBUG] switch_core_state_machine.c:557 > > > (sofia/external/21543514280633(a)200.35.145.149) State HANGUP > > > 2010-12-13 11:58:21.849192 [DEBUG] mod_sofia.c:453 > > > sofia/external/21543514280633(a)200.35.145.149 Overriding SIP cause 480 > > > with 900 from the other leg > > > 2010-12-13 11:58:21.849192 [DEBUG] mod_sofia.c:459 Channel > > > sofia/external/21543514280633(a)200.35.145.149 hanging up, cause: > > > NORMAL_UNSPECIFIED > > > 2010-12-13 11:58:21.849192 [DEBUG] switch_core_state_machine.c:46 > > > sofia/external/21543514280633(a)200.35.145.149 Standard HANGUP, cause: > > > NORMAL_UNSPECIFIED > > > 2010-12-13 11:58:21.849192 [DEBUG] switch_core_state_machine.c:557 > > > (sofia/external/21543514280633(a)200.35.145.149) State HANGUP going to > > > sleep > > > 2010-12-13 11:58:21.849192 [DEBUG] switch_core_state_machine.c:351 > > > (sofia/external/21543514280633(a)200.35.145.149) State Change > > > CS_HANGUP -> > > > CS_REPORTING > > > 2010-12-13 11:58:21.849192 [DEBUG] switch_core_session.c:1083 Send > > > signal sofia/external/21543514280633(a)200.35.145.149 [BREAK] > > > 2010-12-13 11:58:21.849192 [DEBUG] switch_core_state_machine.c:320 > > > (sofia/external/21543514280633(a)200.35.145.149) Running State Change > > > CS_REPORTING > > > 2010-12-13 11:58:21.849192 [DEBUG] switch_core_state_machine.c:617 > > > (sofia/external/21543514280633(a)200.35.145.149) State REPORTING > > > 2010-12-13 11:58:21.849192 [DEBUG] switch_core_state_machine.c:557 > > > (opal/in:231543514280633) State HANGUP > > > 2010-12-13 11:58:21.849192 [DEBUG] connection.cxx:401 Call end reason > > > for Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] set to EndedByRemoteUser > > > 2010-12-13 11:58:21.849192 [DEBUG] call.cxx:112 Clearing > > > Call[Cc70e3cf91] reason=EndedByRemoteUser > > > 2010-12-13 11:58:21.852189 [DEBUG] connection.cxx:1516 SetPhase from > > > SetUpPhase to ReleasingPhase for Call[Cc70e3cf91]-EP<h323>[tcp > > > $200.117.192.17:19270/22716] > > > 2010-12-13 11:58:21.852189 [DEBUG] connection.cxx:459 Releasing > > > asynchronously > > > Call[Cc70e3cf91]-EP<h323>[tcp$200.117.192.17:19270/22716] > > > 2010-12-13 11:58:21.852189 [DEBUG] osutil.cxx:196 File handle > > > low water > > > mark set: 69 Thread unblock pipe > > > 2010-12-13 11:58:21.852189 [DEBUG] connection.cxx:1516 SetPhase from > > > AlertingPhase to ReleasingPhase for > > > Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] > > > 2010-12-13 11:58:21.852189 [DEBUG] connection.cxx:448 Releasing > > > Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] > > > 2010-12-13 11:58:21.852189 [DEBUG] connection.cxx:489 OnReleased > > > Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] > > > 2010-12-13 11:58:21.852189 [DEBUG] connection.cxx:808 Media streams > > > closed. > > > 2010-12-13 11:58:21.852189 [DEBUG] endpoint.cxx:450 OnReleased > > > Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] > > > 2010-12-13 11:58:21.852189 [DEBUG] manager.cxx:709 OnReleased > > > Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] > > > 2010-12-13 11:58:21.852189 [DEBUG] call.cxx:716 OnReleased > > > Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] > > > 2010-12-13 11:58:21.852189 [DEBUG] connection.cxx:436 Already released > > > Call[Cc70e3cf91]-EP<h323>[tcp$200.117.192.17:19270/22716] > > > 2010-12-13 11:58:21.856029 [DEBUG] connection.cxx:1516 SetPhase from > > > ReleasingPhase to ReleasedPhase for > > > Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] > > > 2010-12-13 11:58:21.856029 [DEBUG] switch_core_state_machine.c:46 > > > opal/in:231543514280633 Standard HANGUP, cause: NORMAL_CLEARING > > > 2010-12-13 11:58:21.856029 [DEBUG] switch_core_state_machine.c:557 > > > (opal/in:231543514280633) State HANGUP going to sleep > > > 2010-12-13 11:58:21.856029 [DEBUG] switch_core_state_machine.c:351 > > > (opal/in:231543514280633) State Change CS_HANGUP -> CS_REPORTING > > > 2010-12-13 11:58:21.856029 [DEBUG] switch_core_session.c:1083 Send > > > signal opal/in:231543514280633 [BREAK] > > > 2010-12-13 11:58:21.856029 [DEBUG] switch_core_state_machine.c:320 > > > (opal/in:231543514280633) Running State Change CS_REPORTING > > > 2010-12-13 11:58:21.856029 [DEBUG] switch_core_state_machine.c:617 > > > (opal/in:231543514280633) State REPORTING > > > 2010-12-13 11:58:21.856029 [DEBUG] connection.cxx:401 Call end reason > > > for Call[Cc70e3cf91]-EP<h323>[tcp$200.117.192.17:19270/22716] set to > > > EndedByRemoteUser > > > 2010-12-13 11:58:21.856029 [DEBUG] h323.cxx:349 OnReleased: tcp > > > $200.117.192.17:19270/22716, connectionState=AwaitingLocalAnswer > > > 2010-12-13 11:58:21.856029 [DEBUG] switch_core_state_machine.c:53 > > > sofia/external/21543514280633(a)200.35.145.149 Standard > > > REPORTING, cause: > > > NORMAL_UNSPECIFIED > > > 2010-12-13 11:58:21.856029 [DEBUG] switch_core_state_machine.c:617 > > > (sofia/external/21543514280633(a)200.35.145.149) State > > > REPORTING going to > > > sleep > > > 2010-12-13 11:58:21.856029 [DEBUG] switch_core_state_machine.c:345 > > > (sofia/external/21543514280633(a)200.35.145.149) State Change > > > CS_REPORTING > > > -> CS_DESTROY > > > 2010-12-13 11:58:21.856029 [DEBUG] switch_core_session.c:1083 Send > > > signal sofia/external/21543514280633(a)200.35.145.149 [BREAK] > > > 2010-12-13 11:58:21.856029 [DEBUG] switch_core_session.c:1250 > > > Session 2 > > > (sofia/external/21543514280633(a)200.35.145.149) Locked, Waiting on > > > external entities > > > 2010-12-13 11:58:21.856029 [NOTICE] > > > switch_core_session.c:1268 Session 2 > > > (sofia/external/21543514280633(a)200.35.145.149) Ended > > > 2010-12-13 11:58:21.856029 [NOTICE] switch_core_session.c:1270 Close > > > Channel sofia/external/21543514280633(a)200.35.145.149 [CS_DESTROY] > > > 2010-12-13 11:58:21.856029 [DEBUG] switch_core_state_machine.c:449 > > > (sofia/external/21543514280633(a)200.35.145.149) Callstate Change HANGUP > > > -> DOWN > > > 2010-12-13 11:58:21.858228 [DEBUG] switch_core_state_machine.c:452 > > > (sofia/external/21543514280633(a)200.35.145.149) Running State Change > > > CS_DESTROY > > > 2010-12-13 11:58:21.858228 [DEBUG] switch_core_state_machine.c:462 > > > (sofia/external/21543514280633(a)200.35.145.149) State DESTROY > > > 2010-12-13 11:58:21.858228 [DEBUG] mod_sofia.c:364 > > > sofia/external/21543514280633(a)200.35.145.149 SOFIA DESTROY > > > 2010-12-13 11:58:21.858228 [DEBUG] switch_core_state_machine.c:60 > > > sofia/external/21543514280633(a)200.35.145.149 Standard DESTROY > > > 2010-12-13 11:58:21.858228 [DEBUG] switch_core_state_machine.c:462 > > > (sofia/external/21543514280633(a)200.35.145.149) State DESTROY going to > > > sleep > > > 2010-12-13 11:58:21.856029 [DEBUG] h323.cxx:356 Sending > > > release complete > > > PDU: callRef=22716 > > > 2010-12-13 11:58:21.858228 [DEBUG] h323pdu.cxx:80 Sending PDU: > > > command endSessionCommand disconnect <<null>> > > > 2010-12-13 11:58:21.858228 [DEBUG] h323pdu.cxx:80 Sending PDU: > > > { > > > q931pdu = { > > > protocolDiscriminator = 8 > > > callReference = 22716 > > > from = destination > > > messageType = ReleaseComplete > > > IE: Cause - Normal call clearing = { > > > 80 90 > > > .. > > > } > > > IE: Display = { > > > 72 6f 6f 74 00 root. > > > } > > > IE: User-User = { > > > 25 80 06 00 08 91 4a 00 06 01 11 00 3a 76 b2 f2 > > > %.....J.....:v.. > > > > > > Log-Func: > > > Log-Line: 0 > > > User-Data: > > > > > > 1d 05 e0 11 9b b7 00 1e 64 1f 33 fe 04 c0 01 80 ........d.3..... > > > ... > > > } > > > } > > > h225pdu = { > > > h323_uu_pdu = { > > > h323_message_body = releaseComplete { > > > protocolIdentifier = 0.0.8= { > > > guid = 16 octets { > > > 3a 76 b2 f2 1d 05 e0 11 9b b7 00 1e 64 1f 33 > > > fe :v..........d.3. > > > } > > > } > > > } > > > h245Tunneling = true > > > h245Control = 1 entries { > > > [0]= 2 octets { > > > > > > 4a 40 J@ > > > } > > > } > > > } > > > } > > > } > > > 2010-12-13 11:58:21.861189 [DEBUG] h323.cxx:412 Awaiting end session > > > from remote for 9.997 seconds > > > 2010-12-13 11:58:21.861189 [DEBUG] switch_core_state_machine.c:53 > > > opal/in:231543514280633 Standard REPORTING, cause: NORMAL_CLEARING > > > 2010-12-13 11:58:21.861189 [DEBUG] switch_core_state_machine.c:617 > > > (opal/in:231543514280633) State REPORTING going to sleep > > > 2010-12-13 11:58:21.861189 [DEBUG] switch_core_state_machine.c:345 > > > (opal/in:231543514280633) State Change CS_REPORTING -> CS_DESTROY > > > 2010-12-13 11:58:21.861189 [DEBUG] switch_core_session.c:1083 Send > > > signal opal/in:231543514280633 [BREAK] > > > 2010-12-13 11:58:21.861189 [DEBUG] switch_core_session.c:1250 > > > Session 1 > > > (opal/in:231543514280633) Locked, Waiting on external entities > > > 2010-12-13 11:58:21.861189 [NOTICE] > > > switch_core_session.c:1268 Session 1 > > > (opal/in:231543514280633) Ended > > > 2010-12-13 11:58:21.861189 [NOTICE] switch_core_session.c:1270 Close > > > Channel opal/in:231543514280633 [CS_DESTROY] > > > 2010-12-13 11:58:21.861189 [DEBUG] switch_core_state_machine.c:449 > > > (opal/in:231543514280633) Callstate Change HANGUP -> DOWN > > > 2010-12-13 11:58:21.861189 [DEBUG] switch_core_state_machine.c:452 > > > (opal/in:231543514280633) Running State Change CS_DESTROY > > > 2010-12-13 11:58:21.861189 [DEBUG] switch_core_state_machine.c:462 > > > (opal/in:231543514280633) State DESTROY > > > 2010-12-13 11:58:21.861189 [DEBUG] switch_core_state_machine.c:60 > > > opal/in:231543514280633 Standard DESTROY > > > 2010-12-13 11:58:21.861189 [DEBUG] switch_core_state_machine.c:462 > > > (opal/in:231543514280633) State DESTROY going to sleep > > > 2010-12-13 11:58:22.087167 [DEBUG] h323pdu.cxx:80 Receiving PDU: > > > { > > > q931pdu = { > > > protocolDiscriminator = 8 > > > callReference = 22716 > > > from = originator > > > messageType = Facility > > > IE: Facility = { > > > > > > } > > > IE: Display = { > > > 67 75 73 74 > > > (..........!... > > > 80 . > > > } > > > } > > > h225pdu = { > > > > > > h323_uu_pdu = { > > > h323_message_body = empty <<null>> > > > h245Tunneling = true > > > h245Control = 2 entries { > > > [0]= 3 octets { > > > 21 80 01 !.. > > > } > > > [1]= 2 > > > octets { > > > 20 80 . > > > } > > > } > > > } > > > } > > > } > > > 2010-12-13 11:58:22.087167 [DEBUG] h323.cxx:559 Handling PDU: Facility > > > callRef=22716 > > > 2010-12-13 11:58:22.087167 [DEBUG] h323.cxx:2709 Checking for end > > > session on PDU: response terminalCapabilitySetAck > > > 2010-12-13 11:58:22.087167 [DEBUG] h323.cxx:2709 Checking for end > > > session on PDU: response masterSlaveDeterminationAck > > > 2010-12-13 11:58:22.087167 [DEBUG] connection.cxx:436 Already released > > > Call[Cc70e3cf91]-EP<h323>[tcp$200.117.192.17:19270/22716] > > > 2010-12-13 11:58:22.087167 [DEBUG] h323.cxx:549 Signal channel closed. > > > 2010-12-13 11:58:22.087167 [DEBUG] transports.cxx:900 > > > Transport clean up > > > on termination > > > 2010-12-13 11:58:22.087167 [DEBUG] transports.cxx:885 Transport Close > > > 2010-12-13 11:58:22.090169 [DEBUG] osutil.cxx:196 File handle > > > low water > > > mark set: 65 PTextFile > > > 2010-12-13 11:58:22.090169 [DEBUG] tlibthrd.cxx:1020 Could not parse > > > thread stat file /proc/21145/task/21190/stat > > > 2010-12-13 11:58:22.306147 [DEBUG] connection.cxx:489 OnReleased > > > Call[Cc70e3cf91]-EP<h323>[tcp$200.117.192.17:19270/22716] > > > 2010-12-13 11:58:22.306147 [DEBUG] connection.cxx:808 Media streams > > > closed. > > > 2010-12-13 11:58:22.306147 [DEBUG] endpoint.cxx:450 OnReleased > > > Call[Cc70e3cf91]-EP<h323>[tcp$200.117.192.17:19270/22716] > > > 2010-12-13 11:58:22.306147 [DEBUG] manager.cxx:709 OnReleased > > > Call[Cc70e3cf91]-EP<h323>[tcp$200.117.192.17:19270/22716] > > > 2010-12-13 11:58:22.306147 [DEBUG] call.cxx:716 OnReleased > > > Call[Cc70e3cf91]-EP<h323>[tcp$200.117.192.17:19270/22716] > > > 2010-12-13 11:58:22.306147 [DEBUG] manager.cxx:535 OnClearedCall > > > Call[Cc70e3cf91] from "h323:200.117.192.17" to "h323:231543514280633" > > > 2010-12-13 11:58:22.306147 [DEBUG] connection.cxx:1516 SetPhase from > > > ReleasingPhase to ReleasedPhase for Call[Cc70e3cf91]-EP<h323>[tcp > > > $200.117.192.17:19270/22716] > > > 2010-12-13 11:58:22.306147 [DEBUG] connection.cxx:480 OnRelease thread > > > completed for > > > Call[Cc70e3cf91]-EP<h323>[tcp$200.117.192.17:19270/22716] > > > 2010-12-13 11:58:22.308152 [DEBUG] tlibthrd.cxx:1020 Could not parse > > > thread stat file /proc/21145/task/21194/stat > > > 2010-12-13 11:58:22.588122 [DEBUG] transports.cxx:900 > > > Transport clean up > > > on termination > > > 2010-12-13 11:58:22.588122 [DEBUG] transports.cxx:885 Transport Close > > > 2010-12-13 11:58:22.588122 [DEBUG] transports.cxx:1045 > > > Deleted transport > > > tcp$200.117.192.17:19270<if=tcp$10.0.0.1:1720> > > > 2010-12-13 11:58:22.590122 [DEBUG] h323.cxx:330 Connection tcp > > > $200.117.192.17:19270/22716 deleted. > > > 2010-12-13 11:58:22.590122 [DEBUG] connection.cxx:343 Connection > > > Call[Cc70e3cf91]-EP<h323>[tcp$200.117.192.17:19270/22716] destroyed. > > > 2010-12-13 11:58:22.590122 [DEBUG] localep.cxx:211 Deleted connection. > > > 2010-12-13 11:58:22.590122 [DEBUG] connection.cxx:343 Connection > > > Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] destroyed. > > > 2010-12-13 11:58:23.592039 [DEBUG] call.cxx:86 Call[Cc70e3cf91] > > > destroyed. > > > > > > -------------------------------------------------------------- > > > -------------------------------------------------------------- > > > ----------------------------------------- > > > Gustavo Espeche > > > www.easyipcall.com > > > > > > > > > > > > > > > > > > > > > > > > _______________________________________________ > > > FreeSWITCH-users mailing list > > > FreeSWITCH-users(a)lists.freeswitch.org > > > http://lists.freeswitch.org/mailman/listinfo/freeswitch-users > > > UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freesw > > > itch-users > > > http://www.freeswitch.org > > > > > > > _______________________________________________ > FreeSWITCH-users mailing list > FreeSWITCH-users(a)lists.freeswitch.org > http://lists.freeswitch.org/mailman/listinfo/freeswitch-users > UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users > http://www.freeswitch.org

1 0

Scale UP Freeswitch
by Stephen Wilde 13 Dec '10

13 Dec '10

Hi, I have one server running Freeswitch with some ISDN connections (via FreeTDM+Sangoma boards) and some SIP connections with service providers and customer. The usage of Freeswitch is as switching so it "bridge" each incoming call to a new outgoing call. SIP calls use G.729 and ISDN calls use ALaw for voice encoding. Now the number of call is grow up and also the CPU load is a little high so I have the necessity to scale UP my Freeswitch to handle more calls: what is the best way to do that? My first idea is to use a Sangoma D500 board to reduce the CPU load. Can be this a solution? There are different way to scale UP? Thanks in advance, Stephen

7 14

NOTICE about posting.
by Brian West 13 Dec '10

13 Dec '10

If you've never posted to our mailing list you are first post moderated. If you send the same message to the list multiple times while its in the moderator queue I'll reject them all and you'll have to resend it. I know this sounds mean but when you send the same message 10 times within 3 minutes of each wondering why it doesn't make it to the list it just creates more work for our team so please be patient on weekends if your first posts do not make it to the list. Thanks, Brian

3 2

opal to sip problem
by Gustavo Espeche 13 Dec '10

13 Dec '10

Hello list, we are try to do inter-worker between h323 and sip using opal in freeswitch, but FS don't send the call to our sip gw, follow is the call flow: h323 endpoint -->FS->sip gateway attached a FS debug of call. if something know or work with opal in freeSwitch and have some tips we appreciate a lot if advice about it. Best Regards. ----------------------------------------------------------------------------------------------------------------------------- 2010-12-13 11:58:21.325242 [DEBUG] transports.cxx:1200 Started connection to 200.117.192.17:19270 (if=72.51.47.100:1720) 2010-12-13 11:58:21.325242 [DEBUG] osutil.cxx:189 File handle high water mark set: 67 Thread unblock pipe 2010-12-13 11:58:21.325242 [DEBUG] tlibthrd.cxx:587 Thread high water mark set: 8 2010-12-13 11:58:21.325242 [DEBUG] transports.cxx:666 Waiting on socket accept on tcp$72.51.47.100:1720 2010-12-13 11:58:21.325242 [DEBUG] h323ep.cxx:501 Awaiting first PDU 2010-12-13 11:58:21.805203 [DEBUG] h323pdu.cxx:80 Receiving PDU: { q931pdu = { protocolDiscriminator = 8 callReference = 22716 from = originator messageType = Setup IE: Bearer-Capability = { 88 93 a5 ... } IE: Display = { 67 75 73 74 61 76 6f 00 gustavo. } IE: Called-Party-Number = { 81 32 33 31 35 34 33 35 31 34 32 38 30 36 33 33 .231543514280633 } IE: User-User = { 20 b8 06 00 08 91 4a 00 06 01 40 06 00 67 00 75 .....J...@..g.u 00 73 00 74 00 61 00 76 00 6f 22 c0 09 00 00 3d .s.t.a.v.o"....= ... } } h225pdu = { h323_uu_pdu = { h323_me ssage_body = setup { protocolIdentifier = 0.0.8.2250.0.6 sourceAddress = 1 entries { [0]=h323_ID 7 characters { 0067 0075 0073 0074 0061 0076 006f gustavo } } sour ceInfo = { vendor = { vendor = { t35CountryCode = 9 t35Extension = 0 manufacturerCode = 61 } productId = 3 octets { 00 00 00 (OPAL v3.6 2e 36 29 00 00 .6 ).. } } terminal = { } mc = false undefinedNode = false } destinationAddress = 1 entries { [0]=dialedDigits "231543514280633" } Log-Func: Log-Line: 0 User-Data: destCallSignalAddress = ipAddress { ip = 4 octets { 48 33 2f 64 H3/d } port = 1720 } activeMC = falets { 4e 76 b2 f2 1d 05 e0 11 9b b7 00 1e 64 1f 33 fe Nv..........d.3. } conferenceGoal = create <<null>> callType = pointToPoint <<null>> sourceCallSignalAddress = ipAddress { ip = Log-Func: Log-Line: 0 User-Data: 4 octets { c8 75 c0 11 .u.. } port = 19270 } callIdentifier = { guid = 16 octets { 3a 76 f 33 fe :v..........d.3. } } fastStart = 8 entries { [0]= 29 octets { 40 00 00 06 04 01 00 4c 20 13 80 11 1c 00 01 00 @......L ....... c8 75 c0 11 13 de 00 c8 75 c0 11 13 df .u......u.... } [1]= 19 octets { 00 00 64 0c 20 13 80 0b 0d 00 01 00 c8 75 c0 11 ..d. ........u.. 13 df 00 ... } [ 2]= 35 octets { 40 00 00 06 04 01 00 48 78 00 4a ff 00 80 01 00 @......Hx.J..... 80 11 1c 00 02 00 c8 75 c0 11 13 e0 00 c8 75 c0 .......u......u. ... } [3]= 25 octets { 00 00 65 08 78 00 4a ff 00 80 01 00 80 0b 0d 00 ..e.x.J......... 02 00 c8 75 c0 11 13 e1 00 ...u..... } [4]= 34 octets { 40 00 00 06 04 01 00 48 68 4a ff 00 80 01 00 80 @......HhJ...... 11 1c 00 02 00 c8 75 c0 11 13 e0 00 c8 75 c0 11 ......u......u.. ... } [5]= 24 octets { 00 00 66 08 68 4a ff 00 80 01 00 80 0b 0d 00 02 ..f.hJ.......... 00 c8 75 c0 11 13 e1 00 ..u..... } [6]= 34 octets { 40 00 00 06 04 01 00 48 70 4a ff 00 80 01 00 80 @......HpJ...... 11 1c 00 02 00 c8 75 c0 11 13 e0 0 0 c8 75 c0 11 ......u......u.. ... } [7]= 24 octets { 00 00 67 08 70 4a ff 00 80 01 00 80 0b 0d 00 02 ..g.pJ.......... 00 c8 75 c0 11 13 e1 00 ..u..... } } mediaWaitForConnect = false canOverlapSend = false multipleCalls = false maintainConnection = false parallelH245Control = 2 entries { [0]= 125 octets { 02 70 01 06 00 08 81 75 00 0d 80 13 80 01 f4 00 .p.....u........ 01 00 00 01 00 00 01 00 00 0c c0 01 00 01 80 07 ................ ... } [1]= 7 octets { 01 00 32 80 5d 41 2 b ..2.]A+ } } } h245Tunneling = true } } } 2010-12-13 11:58:21.805203 [DEBUG] h323ep.cxx:510 Incoming call, first PDU: callReference=22716 2010-12-13 11:58:21.805203 [DEBUG] call.cxx:72 Created Call[Cc70e3cf91] 2010-12-13 11:58:21.805203 [DEBUG] connection.cxx:262 Created connection Call[Cc70e3cf91]-EP<h323>[tcp$200.117.192.17:19270/22716] 2010-12-13 11:58:21.805203 [DEBUG] rfc2833.cxx:140 Handler created 2010-12-13 11:58:21.805203 [DEBUG] rfc2833.cxx:328 Rx capability set to "0-16,32,36" 2010-12-13 11:58:21.805203 [DEBUG] rfc2833.cxx:140 Handler created 2010-12-13 11:58:21.805203 [DEBUG] rfc2833.cxx:328 Rx capability set to "192,193" 2010-12-13 11:58:21.805203 [DEBUG] osutil.cxx:189 File handle high water mark set: 68 PUDPSocket 2010-12-13 11:58:21.808186 [DEBUG] h4601.cxx:1583 Endpoint Attached 2010-12-13 11:58:21.808186 [DEBUG] h323ep.cxx:552 Created new connection: tcp$200.117.192.17:19270/22716 2010-12-13 11:58:21.808186 [DEBUG] h323.cxx:559 Handling PDU: Setup callRef=22716 2010-12-13 11:58:21.808186 [DEBUG] connection.cxx:1516 SetPhase from UninitialisedPhase to SetUpPhase for Call[Cc70e3cf91]-EP<h323>[tcp $200.117.192.17:19270/22716] 2010-12-13 11:58:21.808186 [DEBUG] h323.cxx:943 Set protocol version to 6 and implying H.245 version 13 2010-12-13 11:58:21.808186 [DEBUG] h323.cxx:1240 Set remote application name: " 3.2.6 (OPAL v3.6.6) 9/61 " 2010-12-13 11:58:21.808186 [DEBUG] manager.cxx:1392 Checking incoming call for NAT: local=72.51.47.100, peer=200.117.192.17, sig=200.117.192.17 2010-12-13 11:58:21.808186 [DEBUG] h323.cxx:1041 Sending call proceeding PDU 2010-12-13 11:58:21.811196 [DEBUG] h323pdu.cxx:80 Sending PDU: { q931pdu = { protocolDiscriminator = 8 callReference = 22716 from = destination messageType = CallProceeding IE: Display = { 72 6f 6f 74 00 root. } IE: User-User = { 21 80 06 00 08 91 4a 00 06 22 c0 09 00 00 3d 1e !.....J.."....=. 56 6f 78 20 4c 75 63 69 64 61 20 50 74 79 2e 20 Vox Lucida Pty. ... } } h225pdu = { h323_uu_pdu = { h323_message_body = callProceeding { protocolIdentifier = 0.0.8.2250.0.6 destinationInfo = { vendor = { vendor = { t35CountryCode = 9 t35Extensio n = 0 manufacturerCode = 61 } productId = 31 octets { 56 6f 78 20 4c 75 63 69 64 61 20 50 74 79 2e 20 Vox Lucida Pty. 4c 74 64 2e 20 6d 6f 64 5f 6f 70 61 6c 00 00 Log-Line: 0 UINCOMING DATA [(null)] RECV EVENT Event-Name: SOCKET_DATA ser-Data: _undef_ } } terminal = { } mc = false undefinedNode = false } callIdentifier = { guid = 16 octets { 3a 76 b2 f2 1d 05 e0 11 9b b7 00 1e 64 1f 33 fe :v..........d.3. } } multipleCalls = false maintainConnection = false } h245Tunneling = true } } } 2010-12-13 11:58:21.811196 [DEBUG] manager.cxx:600 OnIncoming connection Call[Cc70e3cf91]-EP<h323>[tcp$200.117.192.17:19270/22716] 2010-12-13 11:58:21.811196 [DEBUG] call.cxx:288 GetOtherPartyConnection Call[Cc70e3cf91]-EP<h323>[tcp$200.117.192.17:19270/22716] 2010-12-13 11:58:21.811196 [DEBUG] manager.cxx:1200 Searching for route "h323:root 231543514280633" 2010-12-13 11:58:21.811196 [DEBUG] manager.cxx:1228 Matched regex "h323:.*" 2010-12-13 11:58:21.811196 [DEBUG] manager.cxx:573 Set up connection to "local:231543514280633" 2010-12-13 11:58:21.811196 [DEBUG] connection.cxx:262 Created connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] 2010-12-13 11:58:21.811196 [DEBUG] localep.cxx:205 Created connection with token "Ldc8d6c462" 2010-12-13 11:58:21.811196 [DEBUG] h323.cxx:1075 Incoming call accepted 2010-12-13 11:58:21.811196 [DEBUG] mod_opal.cpp:770 Matched FS codec PCMA to OPAL media format G.711-ALaw-64k 2010-12-13 11:58:21.811196 [DEBUG] mod_opal.cpp:770 Matched FS codec G729 to OPAL media format G.729 2010-12-13 11:58:21.811196 [DEBUG] mod_opal.cpp:770 Matched FS codec PCMU to OPAL media format G.711-uLaw-64k 2010-12-13 11:58:21.811196 [DEBUG] mod_opal.cpp:770 Matched FS codec G723 to OPAL media format G.723.1 2010-12-13 11:58:21.814188 [DEBUG] call.cxx:425 GetMediaFormats for Call[Cc70e3cf91]-EP<h323>[tcp$200.117.192.17:19270/22716] G.723.1 G.729 G.711-uLaw-64k G.711-ALaw-64k 2010-12-13 11:58:21.814188 [DEBUG] h323caps.cxx:2381 Could not find capability: "G.723.1" 2010-12-13 11:58:21.814188 [DEBUG] h323caps.cxx:2284 Added capability: G.723.1 <1> 2010-12-13 11:58:21.814188 [DEBUG] h323caps.cxx:2381 Could not find capability: "G.729" 2010-12-13 11:58:21.814188 [DEBUG] h323caps.cxx:2284 Added capability: G.729 <2> 2010-12-13 11:58:21.814188 [DEBUG] h323caps.cxx:2381 Could not find capability: "G.711-uLaw-64k" 2010-12-13 11:58:21.814188 [DEBUG] h323caps.cxx:2284 Added capability: G.711-uLaw-64k <3> 2010-12-13 11:58:21.814188 [DEBUG] h323caps.cxx:2381 Could not find capability: "G.711-ALaw-64k" 2010-12-13 11:58:21.814188 [DEBUG] h323caps.cxx:2284 Added capability: G.711-ALaw-64k <4> 2010-12-13 11:58:21.814188 [DEBUG] h323caps.cxx:2376 Found capability: G.723.1 <1> 2010-12-13 11:58:21.814188 [DEBUG] h323caps.cxx:2376 Found capability: G.729 <2> 2010-12-13 11:58:21.814188 [DEBUG] h323caps.cxx:2376 Found capability: G.711-uLaw-64k <3> 2010-12-13 11:58:21.814188 [DEBUG] h323caps.cxx:2376 Found capability: G.711-ALaw-64k <4> 2010-12-13 11:58:21.814188 [DEBUG] h323caps.cxx:2284 Added capability: UserInput/hookflash <5> 2010-12-13 11:58:21.814188 [DEBUG] h323caps.cxx:2284 Added capability: UserInput/basicString <6> 2010-12-13 11:58:21.817197 [DEBUG] h323caps.cxx:2284 Added capability: UserInput/dtmf <7> 2010-12-13 11:58:21.817197 [DEBUG] h323caps.cxx:2284 Added capability: UserInput/RFC2833 <8> 2010-12-13 11:58:21.817197 [DEBUG] h323caps.cxx:2376 Found capability: UserInput/RFC2833 <8> 2010-12-13 11:58:21.817197 [DEBUG] call.cxx:288 GetOtherPartyConnection Call[Cc70e3cf91]-EP<h323>[tcp$200.117.192.17:19270/22716] 2010-12-13 11:58:21.817197 [DEBUG] h323.cxx:3704 SetLocalCapabilities: Table: G.723.1 <1> G.729 <2> G.711-uLaw-64k <3> G.711-ALaw-64k <4> UserInput/hookflash <5> UserInput/basicString <6> UserInput/dtmf <7> UserInput/RFC2833 <8> Set: 0: Log-Func: Log-Line: 0 User-Data: 0: G.723.1 <1> G.729 <2> G.711-uLaw-64k <3> G.711-ALaw-64k <4> 1: UserInput/hookflash <5> 2: UserInput/basicString <6> UserInput/dtINCOMING DATA [(null)] RECV EVENT Event-Name: SOCKET_DATA 2010-12-13 11:58:21.817197 [DEBUG] h323.cxx:1121 Fast start detected 2010-12-13 11:58:21.817197 [DEBUG] h323caps.cxx:2594 Could not find capability: audioData, type g711Alaw64k 2010-12-13 11:58:21.817197 [DEBUG] h323caps.cxx:845 Capability tx frames left at 20 as remote allows 20 2010-12-13 11:58:21.817197 [DEBUG] h323caps.cxx:2294 Added capability: G.711-ALaw-64k <4> 2010-12-13 11:58:21.817197 [DEBUG] h323caps.cxx:845 Capability tx frames left at 20 as remote allows 20 2010-12-13 11:58:21.817197 [DEBUG] h323caps.cxx:2594 Could not find capability: videoData, type h261VideoCapability 2010-12-13 11:58:21.817197 [DEBUG] h323caps.cxx:2594 Could not find capability: videoData, type h261VideoCapability 2010-12-13 11:58:21.817197 [DEBUG] h323caps.cxx:2594 Could not find capability: videoData, type h261VideoCapability 2010-12-13 11:58:21.817197 [DEBUG] h323caps.cxx:2594 Could not find capability: videoData, type h261VideoCapability 2010-12-13 11:58:21.820187 [DEBUG] h323caps.cxx:2594 Could not find capability: videoData, type h261VideoCapability 2010-12-13 11:58:21.820187 [DEBUG] h323caps.cxx:2594 Could not find capability: videoData, type h261VideoCapability 2010-12-13 11:58:21.820187 [DEBUG] h323caps.cxx:2594 Could not find capability: videoData, type h261VideoCapability 2010-12-13 11:58:21.820187 [DEBUG] h323caps.cxx:2594 Could not find capability: videoData, type h261VideoCapability 2010-12-13 11:58:21.820187 [DEBUG] h323caps.cxx:2594 Could not find capability: videoData, type h261VideoCapability 2010-12-13 11:58:21.820187 [DEBUG] h323caps.cxx:2594 Could not find capability: videoData, type h261VideoCapability 2010-12-13 11:58:21.820187 [DEBUG] h323caps.cxx:2594 Could not find capability: videoData, type h261VideoCapability 2010-12-13 11:58:21.820187 [DEBUG] h323caps.cxx:2594 Could not find capability: videoData, type h261VideoCapability 2010-12-13 11:58:21.820187 [DEBUG] call.cxx:288 GetOtherPartyConnection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] 2010-12-13 11:58:21.820187 [DEBUG] call.cxx:169 OnSetUp Call[Cc70e3cf91]-EP<h323>[tcp$200.117.192.17:19270/22716] 2010-12-13 11:58:21.820187 [DEBUG] localep.cxx:240 Incoming call from gustavo [200.117.192.17] 2010-12-13 11:58:21.820187 [DEBUG] mod_opal.cpp:641 Created switch caller profile: username = dialplan = XML caller_id_name = gustavo [200.117.192.17] caller_id_number = 0000000000 network_addr = source = opal context Log-Func: Log-Line: 0 User-Data: = default 2010-12-13 11:58:21.823196 [NOTICE] switch_channel.c:784 New Channel opal/in:231543514280633 [7b4a89ac-b5d7-4fb4-bab5-ddaa8e73485c] 2010-12-13 11:58:21.823196 [DEBUG] mod_opal.cpp:647 (opal/in:231543514280633) State Change CS_NEW -> CS_INIT 2010-12-13 11:58:21.823196 [DEBUG] mod_opal.cpp:1092 State changed on connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] 2010-12-13 11:58:21.823196 [DEBUG] switch_core_session.c:1083 Send signal opal/in:231543514280633 [BREAK] 2010-12-13 11:58:21.823196 [DEBUG] mod_opal.cpp:931 Kill 3 on connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] 2010-12-13 11:58:21.823196 [DEBUG] connection.cxx:1516 SetPhase from UninitialisedPhase to AlertingPhase for Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] 2010-12-13 11:58:21.823196 [NOTICE] mod_opal.cpp:676 Ring-Ready opal/in:231543514280633! 2010-12-13 11:58:21.823196 [DEBUG] manager.cxx:678 OnAlerting Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] 2010-12-13 11:58:21.823196 [DEBUG] call.cxx:196 OnAlerting Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] 2010-12-13 11:58:21.823196 [DEBUG] h323.cxx:2067 SetAlerting Call[Cc70e3cf91]-EP<h323>[tcp$200.117.192.17:19270/22716] 2010-12-13 11:58:21.823196 [DEBUG] switch_core_state_machine.c:320 (opal/in:231543514280633) Running State Change CS_INIT 2010-12-13 11:58:21.823196 [DEBUG] switch_core_state_machine.c:356 (opal/in:231543514280633) State INIT 2010-12-13 11:58:21.823196 [DEBUG] osutil.cxx:189 File handle high water mark set: 72 Thread unblock pipe 2010-12-13 11:58:21.823196 [DEBUG] tlibthrd.cxx:587 Thread high water mark set: 10 2010-12-13 11:58:21.823196 [DEBUG] mod_opal.cpp:842 Started routing for connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] 2010-12-13 11:58:21.823196 [DEBUG] mod_opal.cpp:843 (opal/in:231543514280633) State Change CS_INIT -> CS_ROUTING 2010-12-13 11:58:21.823196 [DEBUG] mod_opal.cpp:1092 State changed on connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] 2010-12-13 11:58:21.823196 [DEBUG] switch_core_session.c:1083 Send signal opal/in:231543514280633 [BREAK] 2010-12-13 11:58:21.823196 [DEBUG] mod_opal.cpp:931 Kill 3 on connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] 2010-12-13 11:58:21.823196 [DEBUG] switch_core_state_machine.c:356 (opal/in:231543514280633) State INIT going to sleep 2010-12-13 11:58:21.823196 [DEBUG] switch_core_state_machine.c:320 (opal/in:231543514280633) Running State Change CS_ROUTING 2010-12-13 11:58:21.823196 [DEBUG] switch_channel.c:1615 (opal/in:231543514280633) Callstate Change DOWN -> RINGING 2010-12-13 11:58:21.823196 [DEBUG] switch_core_state_machine.c:359 (opal/in:231543514280633) State ROUTING 2010-12-13 11:58:21.823196 [DEBUG] mod_opal.cpp:850 Routing connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] 2010-12-13 11:58:21.823196 [DEBUG] switch_core_state_machine.c:77 opal/in:231543514280633 Standard ROUTING 2010-12-13 11:58:21.823196 [INFO] mod_dialplan_xml.c:331 Processing gustavo [200.117.192.17] <0000000000>->231543514280633 in context default 2010-12-13 11:58:21.826206 [DEBUG] h323pdu.cxx:80 Receiving PDU: request terminalCapabilitySet { sequenceNumber = 1 protocolIdentifier = 0.0.8.245.0.13 multiplexCapability = h2250Capability { maximumAudioDelayJitter = 500 receiveMultipointCapabi lity = { multicastCapability = false multiUniCastConference = false mediaDistributionCapability = 1 entries { [0]={ centralizedControl = false distributedControl = false centraliz edAudio = false distributedAudio = false centralizedVideo = false distributedVideo = false } } } transmitMultipointCapability = { multicastCapability = false multiUniC astConference = false mediaDistributionCapability = 1 entries { [0]={ centralizedControl = false distributedControl = false centralizedAudio = false distributedAudio = false centralizedVideo = false distributedVideo = false } } } receiveAndTransmitMultipointCapability = { multicastCapability = false multiUniCastConference = false mediaDistributionCapab ility = 1 entries { [0]={ centralizedControl = false distributedControl = false centralizedAudio = false distributedAudio = false centralizedVideo = false distributedV ideo = false } } } mcCapability = { centralizedConferenceMC = false decentralizedConferenceMC = false } rtcpVideoControlCapability = false mediaPacketizationCapability = { h261a VideoPacketization = false } logicalChannelSwitchingCapability = false t120DynamicPortCapability = true } capabilityTable = 8 entries { [0]={ capabilityTableEntryNumber = 1 capability = receiveAudioCap ability g711Alaw64k 240 } [1]={ capabilityTableEntryNumber = 2 capability = receiveVideoCapability h261VideoCapability { qcifMPI = 1 cifMPI = 1 temporalSpatialTradeOffCapability = false maxBitRate = 19200 stillImageTransmission = false videoBadMBsCap = false } } [2]={ capabilityTableEntryNumber = 3 capability = receiveVideoCapability h261VideoCapability { cifMPI = 1 temporalSpatialTradeOffCapability = false maxBitRate = 19200 stillImageTransmission = false videoBadMBsCap = false } } [3]={ capabilityTableEntryNumber = 4 capability = receiveVideoCapability h261VideoCapability { qcifMPI = 1 temporalSpatialTradeOffCapability = false maxBitRate = 19200 stillImageTransmission = false videoBadMBsCap = false } } [4 ]={ capabilityTableEntryNumber = 5 capability = receiveUserInputCapability hookflash <<null>> } [5]={ capabilityTableEntryNumber = 6 capability = receiveUserInputCapability basicString <<null>> } [6]={ capabilityTableEntryNumber = 7 capability = receiveUserInputCapability dtmf <<null>> } [7]={ capabilityTableEntryNumber = 8 capability = receiveRTPAudioTelephonyEventCapability { dynamic RTPPayloadType = 101 audioTelephoneEvent = "0-16" } } } capabilityDescriptors = 1 entries { [0]={ capabilityDescriptorNumber = 1 simultaneousCapabilities = 4 entries { [0]=1 entries { [0]=1 } [1]=3 entries { [0]=2 [1]=3 [2]=4 } [2]=1 entries { [0]=5 } [3]=3 entries { [0]=6 [1]=7 Log-Func: Log-Line: 0 User-Data: [2]=8 2010-12-13 11:58:21.826206 [DEBUG] h323.cxx:2792 Set protocol version to 13 2010-12-13 11:58:21.826206 [DEBUG] h323neg.cxx:378 Received TerminalCapabilitySet: state=Idle pduSeq=1 inSeq=4294967295 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:1988 H323Capabilities(ctor) 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2294 Added capability: G.723.1 <1> 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2294 Added capability: G.729 <2> 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2294 Added capability: G.711-uLaw-64k <3> 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2294 Added capability: G.711-ALaw-64k <4> 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2294 Added capability: UserInput/hookflash <5> 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2294 Added capability: UserInput/basicString <6> 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2294 Added capability: UserInput/dtmf <7> 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2294 Added capability: UserInput/RFC2833 <8> 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2355 Found capability: G.723.1 <1> 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2355 Found capability: G.729 <2> 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2355 Found capability: G.711-uLaw-64k <3> 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2355 Found capability: G.711-ALaw-64k <4> 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2355 Found capability: UserInput/hookflash <5> 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2355 Found capability: UserInput/basicString <6> 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2355 Found capability: UserInput/dtmf <7> 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2355 Found capability: UserInput/RFC2833 <8> 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2376 Found capability: G.711-ALaw-64k <4> 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2376 Found capability: G.711-uLaw-64k <3> 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2376 Found capability: G.723.1 <1> 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2381 Could not find capability: "G.723.1(5.3k)" 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2284 Added capability: G.723.1 <9> 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2381 Could not find capability: "G.723.1A(5.3k)" 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2284 Added capability: G.723.1 <10> 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2381 Could not find capability: "G.723.1A(6.3k)" 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2284 Added capability: G.723.1 <11> 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2381 Could not find capability: "G.728" 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2284 Added capability: G.728 <12> 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2376 Found capability: G.729 <2> 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2381 Could not find capability: "G.729A" 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2284 Added capability: G.729A <13> 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2381 Could not find capability: "G.729A/B" 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2284 Added capability: G.729A/B <14> 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2381 Could not find capability: "G.729B" 2010-12-13 11:58:21.826206 [DEBUG] h323caps.cxx:2284 Added capability: G.729B <15> 2010-12-13 11:58:21.831146 [DEBUG] h323caps.cxx:2381 Could not find capability: "GSM-06.10" 2010-12-13 11:58:21.831146 [DEBUG] h323caps.cxx:2284 Added capability: GSM-06.10 <16> 2010-12-13 11:58:21.831146 [DEBUG] h323caps.cxx:2381 Could not find capability: "GSM-AMR" 2010-12-13 11:58:21.831146 [DEBUG] h323caps.cxx:2284 Added capability: GSM-AMR <17> 2010-12-13 11:58:21.831146 [DEBUG] h323caps.cxx:2381 Could not find capability: "T.38" 2010-12-13 11:58:21.831146 [DEBUG] h323caps.cxx:2284 Added capability: T.38 <18> 2010-12-13 11:58:21.831146 [DEBUG] h323caps.cxx:2376 Found capability: UserInput/RFC2833 <8> 2010-12-13 11:58:21.831146 [DEBUG] h323caps.cxx:2376 Found capability: UserInput/basicString <6> 2010-12-13 11:58:21.834192 [DEBUG] h323caps.cxx:2376 Found capability: UserInput/dtmf <7> 2010-12-13 11:58:21.834192 [DEBUG] h323caps.cxx:2381 Could not find capability: "UserInput/generalString" 2010-12-13 11:58:21.834192 [DEBUG] h323caps.cxx:2284 Added capability: UserInput/generalString <19> 2010-12-13 11:58:21.834192 [DEBUG] h323caps.cxx:2376 Found capability: UserInput/hookflash <5> 2010-12-13 11:58:21.834192 [DEBUG] h323caps.cxx:2381 Could not find capability: "UserInput/iA5String" 2010-12-13 11:58:21.834192 [DEBUG] h323caps.cxx:2284 Added capability: UserInput/iA5String <20> 2010-12-13 11:58:21.834192 [DEBUG] h323caps.cxx:2381 Could not find capability: "iLBC" 2010-12-13 11:58:21.834192 [DEBUG] h323caps.cxx:2284 Added capability: iLBC <21> 2010-12-13 11:58:21.834192 [DEBUG] h323caps.cxx:2284 Added capability: UserInput/hookflash <22> 2010-12-13 11:58:21.834192 [DEBUG] h323caps.cxx:2284 Added capability: UserInput/basicString <23> 2010-12-13 11:58:21.834192 [DEBUG] h323caps.cxx:2284 Added capability: UserInput/dtmf <24> 2010-12-13 11:58:21.834192 [DEBUG] h323caps.cxx:2284 Added capability: UserInput/RFC2833 <25> 2010-12-13 11:58:21.834192 [CONSOLE] mod_xml_curl.c:312 XML response is in /tmp/90f1dd7d-a63c-4e15-981c-6eed9e70313b.tmp.xml Dialplan: opal/in:231543514280633 parsing [default->external] continue=false Dialplan: opal/in:231543514280633 Regex (PASS) [external] destination_number(231543514280633) =~ /^231543514280633/ break=on-false Dialplan: opal/in:231543514280633 Action set(continue_on_fail=true) Dialplan: opal/in:231543514280633 Action set(hangup_after_bridge=true) Dialplan: opal/in:231543514280633 Action set(progress_timeout=15) Dialplan: opal/in:231543514280633 Action set(proxy_media=false) Dialplan: opal/in:231543514280633 Action set(bypass_media=true) Dialplan: opal/in:231543514280633 Action set(absolute_codec_string=PCMA) Dialplan: opal/in:231543514280633 Action bridge(sofia/external/21543514280633(a)200.35.145.149) 2010-12-13 11:58:21.834192 [DEBUG] switch_core_state_machine.c:119 (opal/in:231543514280633) State Change CS_ROUTING -> CS_EXECUTE 2010-12-13 11:58:21.834192 [DEBUG] mod_opal.cpp:1092 State changed on connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] 2010-12-13 11:58:21.834192 [DEBUG] switch_core_session.c:1083 Send signal opal/in:231543514280633 [BREAK] 2010-12-13 11:58:21.834192 [DEBUG] mod_opal.cpp:931 Kill 3 on connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] 2010-12-13 11:58:21.834192 [DEBUG] switch_core_state_machine.c:359 (opal/in:231543514280633) State ROUTING going to sleep 2010-12-13 11:58:21.834192 [DEBUG] switch_core_state_machine.c:320 (opal/in:231543514280633) Running State Change CS_EXECUTE 2010-12-13 11:58:21.834192 [DEBUG] h323caps.cxx:2284 Added capability: H.239-Video+H.239-Video <26> 2010-12-13 11:58:21.834192 [DEBUG] switch_core_state_machine.c:366 (opal/in:231543514280633) State EXECUTE 2010-12-13 11:58:21.834192 [DEBUG] mod_opal.cpp:857 Executing connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] 2010-12-13 11:58:21.834192 [DEBUG] switch_core_state_machine.c:157 opal/in:231543514280633 Standard EXECUTE EXECUTE opal/in:231543514280633 set(continue_on_fail=true) 2010-12-13 11:58:21.837191 [DEBUG] h323caps.cxx:2284 Added capability: H.239-Control <27> 2010-12-13 11:58:21.837191 [DEBUG] h323caps.cxx:2029 Parsing remote capabilities 2010-12-13 11:58:21.837191 [DEBUG] mod_opal.cpp:1076 Received message 26 on connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] 2010-12-13 11:58:21.837191 [DEBUG] mod_dptools.c:1028 opal/in:231543514280633 SET [continue_on_fail]=[true] 2010-12-13 11:58:21.837191 [DEBUG] h323caps.cxx:819 Capability tx frames left at 20 as remote allows 240 2010-12-13 11:58:21.837191 [DEBUG] h323caps.cxx:2529 Could not find capability: receiveVideoCapability, type h261VideoCapability 2010-12-13 11:58:21.837191 [DEBUG] h323caps.cxx:2529 Could not find capability: receiveVideoCapability, type h261VideoCapability 2010-12-13 11:58:21.837191 [DEBUG] h323caps.cxx:2529 Could not find capability: receiveVideoCapability, type h261VideoCapability 2010-12-13 11:58:21.837191 [DEBUG] h323caps.cxx:2671 Found capability: UserInput/RFC2833 <8> 2010-12-13 11:58:21.837191 [DEBUG] mod_opal.cpp:1076 Received message 27 on connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] EXECUTE opal/in:231543514280633 set(hangup_after_bridge=true) 2010-12-13 11:58:21.837191 [DEBUG] mod_opal.cpp:1076 Received message 26 on connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] 2010-12-13 11:58:21.837191 [DEBUG] mod_dptools.c:1028 opal/in:231543514280633 SET [hangup_after_bridge]=[true] 2010-12-13 11:58:21.837191 [DEBUG] h323caps.cxx:2411 Could not find capability: G.711-ALaw-64k <1> 2010-12-13 11:58:21.837191 [DEBUG] h323caps.cxx:2294 Added capability: G.711-ALaw-64k <1> 2010-12-13 11:58:21.837191 [DEBUG] mod_opal.cpp:1076 Received message 27 on connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] EXECUTE opal/in:231543514280633 set(progress_timeout=15) 2010-12-13 11:58:21.837191 [DEBUG] h323caps.cxx:2411 Could not find capability: UserInput/hookflash <5> 2010-12-13 11:58:21.837191 [DEBUG] h323caps.cxx:2294 Added capability: UserInput/hookflash <5> 2010-12-13 11:58:21.837191 [DEBUG] h323caps.cxx:2411 Could not find capability: UserInput/basicString <6> 2010-12-13 11:58:21.837191 [DEBUG] mod_opal.cpp:1076 Received message 26 on connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] 2010-12-13 11:58:21.837191 [DEBUG] mod_dptools.c:1028 opal/in:231543514280633 SET [progress_timeout]=[15] 2010-12-13 11:58:21.837191 [DEBUG] h323caps.cxx:2294 Added capability: UserInput/basicString <6> 2010-12-13 11:58:21.837191 [DEBUG] h323caps.cxx:2411 Could not find capability: UserInput/dtmf <7> 2010-12-13 11:58:21.840189 [DEBUG] h323caps.cxx:2294 Added capability: UserInput/dtmf <7> 2010-12-13 11:58:21.840189 [DEBUG] h323caps.cxx:2411 Could not find capability: UserInput/RFC2833 <8> 2010-12-13 11:58:21.840189 [DEBUG] h323caps.cxx:2294 Added capability: UserInput/RFC2833 <8> 2010-12-13 11:58:21.840189 [DEBUG] h323caps.cxx:2355 Found capability: G.711-ALaw-64k <1> 2010-12-13 11:58:21.840189 [DEBUG] h323caps.cxx:2355 Found capability: UserInput/hookflash <5> 2010-12-13 11:58:21.840189 [DEBUG] h323caps.cxx:2355 Found capability: UserInput/basicString <6> 2010-12-13 11:58:21.840189 [DEBUG] h323caps.cxx:2355 Found capability: UserInput/dtmf <7> 2010-12-13 11:58:21.840189 [DEBUG] h323caps.cxx:2355 Found capability: UserInput/RFC2833 <8> 2010-12-13 11:58:21.840189 [DEBUG] mod_opal.cpp:1076 Received message 27 on connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] EXECUTE opal/in:231543514280633 set(proxy_media=false) 2010-12-13 11:58:21.840189 [DEBUG] h323caps.cxx:2777 Capability merge result: Table: G.711-ALaw-64k <1> UserInput/hookflash <5> UserInput/basicString <6> UserInput/dtmf <7> UserInput/RFC2833 <8> Set: 0: 0: Log-Func: Log-Line: 0 User-Data: G.711-ALaw-64k <1> 1: 2: UserInput/hookflash <5> 3: UserInput/basicString <6> UserInput/dtmf <INCOMING DATA [(null)] RECV EVENT Event-Name: SOCKET_DATA 2010-12-13 11:58:21.840189 [DEBUG] h323caps.cxx:2778 Received capability set, is accepted 2010-12-13 11:58:21.840189 [DEBUG] mod_opal.cpp:1076 Received message 26 on connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] 2010-12-13 11:58:21.840189 [DEBUG] mod_dptools.c:1028 opal/in:231543514280633 SET [proxy_media]=[false] 2010-12-13 11:58:21.840189 [DEBUG] h323neg.cxx:341 Sending TerminalCapabilitySet: outSeq=1 2010-12-13 11:58:21.840189 [DEBUG] mod_opal.cpp:1076 Received message 27 on connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] EXECUTE opal/in:231543514280633 set(bypass_media=true) 2010-12-13 11:58:21.840189 [DEBUG] mod_opal.cpp:1076 Received message 26 on connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] 2010-12-13 11:58:21.840189 [DEBUG] mod_dptools.c:1028 opal/in:231543514280633 SET [bypass_media]=[true] 2010-12-13 11:58:21.840189 [DEBUG] mod_opal.cpp:1076 Received message 27 on connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] EXECUTE opal/in:231543514280633 set(absolute_codec_string=PCMA) 2010-12-13 11:58:21.840189 [DEBUG] mod_opal.cpp:1076 Received message 26 on connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] 2010-12-13 11:58:21.840189 [DEBUG] mod_dptools.c:1028 opal/in:231543514280633 SET [absolute_codec_string]=[PCMA] 2010-12-13 11:58:21.840189 [DEBUG] mod_opal.cpp:1076 Received message 27 on connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] EXECUTE opal/in:231543514280633 bridge(sofia/external/21543514280633(a)200.35.145.149) 2010-12-13 11:58:21.840189 [DEBUG] mod_opal.cpp:1076 Received message 26 on connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] 2010-12-13 11:58:21.840189 [NOTICE] switch_channel.c:784 New Channel sofia/external/21543514280633(a)200.35.145.149 [f9bf5b9e-a273-400c-b18c-e2c98c84750f] 2010-12-13 11:58:21.840189 [DEBUG] mod_sofia.c:3995 (sofia/external/21543514280633(a)200.35.145.149) State Change CS_NEW -> CS_INIT 2010-12-13 11:58:21.840189 [DEBUG] switch_core_session.c:1083 Send signal sofia/external/21543514280633(a)200.35.145.149 [BREAK] 2010-12-13 11:58:21.843189 [DEBUG] h323pdu.cxx:80 Sending PDU: request terminalCapabilitySet { sequenceNumber = 1 protocolIdentifier = 0.0.8.245.0.13 multiplexCapability = h2250Capability { maximumAudioDelayJitter = 250 receiveMultipointCapabili ty = { multicastCapability = false multiUniCastConference = false mediaDistributionCapability = 1 entries { [0]={ centralizedControl = false distributedControl = false centralized Audio = false distributedAudio = false centralizedVideo = false distributedVideo = false } } } transmitMultipointCapability = { multicastCapability = false multiUniCas tConference = false mediaDistributionCapability = 1 entries { [0]={ centralizedControl = false distributedControl = false centralizedAudio = false distributedAudio = false centralizedVideo = false distributedVideo = false } } } receiveAndTransmitMultipointCapability = { multicastCapability = false multiUniCastConference = false mediaDistributionCapabil ity = 1 entries { [0]={ centralizedControl = false distributedControl = false centralizedAudio = false distributedAudio = false centralizedVideo = false distributedVid eo = false } } } mcCapability = { centralizedConferenceMC = false decentralizedConferenceMC = false } rtcpVideoControlCapability = false mediaPacketizationCapability = { h261aVi deoPacketization = false } logicalChannelSwitchingCapability = false t120DynamicPortCapability = true } capabilityTable = 8 entries { [0]={ capabilityTableEntryNumber = 1 capability = receiveAudioCapab ility g7231 { maxAl_sduAudioFrames = 8 silenceSuppression = false } } [1]={ capabilityTableEntryNumber = 2 capability = receiveAudioCapability g729 24 } [2]={ capabilityTabl eEntryNumber = 3 capability = receiveAudioCapability g711Ulaw64k 240 } [3]={ capabilityTableEntryNumber = 4 capability = receiveAudioCapability g711Alaw64k 240 } [4]={ capabilityTableEntryNumbe r = 5 capability = receiveUserInputCapability hookflash <<null>> } [5]={ capabilityTableEntryNumber = 6 capability = receiveUserInputCapability basicString <<null>> } [6]={ capabilityTableEntry Number = 7 capability = receiveUserInputCapability dtmf <<null>> } [7]={ capabilityTableEntryNumber = 8 capability = receiveRTPAudioTelephonyEventCapability { dynamicRTPPayloadType = 101 audioT elephoneEvent = "0-16" } } } capabilityDescriptors = 1 entries { [0]={ capabilityDescriptorNumber = 1 simultaneousCapabilities = 3 entries { [0]=4 entries { [0]=1 [1]=2 [2]=3 [3]=4 } [1]=1 entries { [0]=5 } [2]=3 entries { [0]=6 [1]=7 [2]=8 } } } } } 2010-12-13 11:58:21.843189 [DEBUG] h323caps.cxx:2376 Found capability: UserInput/RFC2833 <8> 2010-12-13 11:58:21.843189 [DEBUG] h323.cxx:3535 User Input RFC2833 payload type set to [pt=101] 2010-12-13 11:58:21.843189 [DEBUG] h323pdu.cxx:80 Sending PDU: response terminalCapabilitySetAck { sequenceNumber = 1 } 2010-12-13 11:58:21.843189 [DEBUG] switch_core_state_machine.c:320 (sofia/external/21543514280633(a)200.35.145.149) Running State Change CS_INIT 2010-12-13 11:58:21.843189 [DEBUG] switch_core_state_machine.c:356 (sofia/external/21543514280633(a)200.35.145.149) State INIT 2010-12-13 11:58:21.843189 [DEBUG] mod_sofia.c:86 sofia/external/21543514280633(a)200.35.145.149 SOFIA INIT 2010-12-13 11:58:21.843189 [DEBUG] h323.cxx:3771 InternalEstablishedConnectionCheck: connectionState=AwaitingLocalAnswer fastStartState=FastStartResponse H.245 is unavailable 2010-12-13 11:58:21.843189 [DEBUG] h323pdu.cxx:80 Receiving PDU: request masterSlaveDetermination { terminalType = 50 statusDeterminationNumber = 6111531 } 2010-12-13 11:58:21.843189 [DEBUG] h323neg.cxx:129 Received MasterSlaveDetermination: state=Idle 2010-12-13 11:58:21.843189 [DEBUG] h323neg.cxx:160 MasterSlaveDetermination: local is master 2010-12-13 11:58:21.843189 [DEBUG] h323pdu.cxx:80 Sending PDU: response masterSlaveDeterminationAck { decision = slave <<null>> } 2010-12-13 11:58:21.843189 [DEBUG] h323.cxx:3771 InternalEstablishedConnectionCheck: connectionState=AwaitingLocalAnswer fastStartState=FastStartResponse H.245 is ready 2010-12-13 11:58:21.843189 [DEBUG] mod_sofia.c:126 (sofia/external/21543514280633(a)200.35.145.149) State Change CS_INIT -> CS_ROUTING 2010-12-13 11:58:21.843189 [DEBUG] switch_core_session.c:1083 Send signal sofia/external/21543514280633(a)200.35.145.149 [BREAK] 2010-12-13 11:58:21.843189 [DEBUG] sofia.c:4604 Channel sofia/external/21543514280633(a)200.35.145.149 entering state [terminated][900] 2010-12-13 11:58:21.843189 [DEBUG] h323ep.cxx:1033 OnSendAlerting conn = Call[Cc70e3cf91]-EP<h323>[tcp$200.117.192.17:19270/22716] 2010-12-13 11:58:21.843189 [DEBUG] switch_channel.c:2455 (sofia/external/21543514280633(a)200.35.145.149) Callstate Change DOWN -> HANGUP 2010-12-13 11:58:21.843189 [DEBUG] h323.cxx:2110 SetAlerting sending Alerting PDU 2010-12-13 11:58:21.843189 [NOTICE] sofia.c:5244 Hangup sofia/external/21543514280633(a)200.35.145.149 [CS_ROUTING] [NORMAL_UNSPECIFIED] 2010-12-13 11:58:21.843189 [DEBUG] switch_channel.c:2471 Send signal sofia/external/21543514280633(a)200.35.145.149 [KILL] 2010-12-13 11:58:21.843189 [DEBUG] switch_core_session.c:1083 Send signal sofia/external/21543514280633(a)200.35.145.149 [BREAK] 2010-12-13 11:58:21.843189 [DEBUG] switch_core_state_machine.c:356 (sofia/external/21543514280633(a)200.35.145.149) State INIT going to sleep 2010-12-13 11:58:21.843189 [DEBUG] switch_core_state_machine.c:320 (sofia/external/21543514280633(a)200.35.145.149) Running State Change CS_HANGUP 2010-12-13 11:58:21.846211 [DEBUG] h323pdu.cxx:80 Sending PDU: { q931pdu = { protocolDiscriminator = 8 callReference = 22716 from = destination messageType = Alerting IE: Display = { 72 6f 6f 74 00 root. } IE: User-User = { 23 80 06 00 08 91 4a 00 06 22 c0 09 00 00 3d 1e #.....J.."....=. 56 6f 78 20 4c 75 63 69 64 61 20 50 74 79 2e 20 Vox Lucida Pty. ... } } h225pdu = { h323 _uu_pdu = { h323_message_body = alerting { protocolIdentifier = 0.0.8.2250.0.6 destinationInfo = { vendor = { vendor = { t35CountryCode = 9 t35Extension = 0 manufacturerCode = 61 } productId = 31 octets { 56 6f 78 20 4c 75 63 69 64 61 20 50 74 79 2e 20 Vox Lucida Pty. 4c 74 64 2e 20 6d 6f 64 5f 6f 70 61 6c 00 00 Ltd. mod_op Log-Line: 0 User-Data: } terminal = { } mc = false undefinedNode = false } callIdentifier = { guid = 16 octets { 3a 76 b2 f2 1d 05 e0 11 9b b7 00 1e 64 1f 33 fe :v........ ..d.3. } } multipleCalls = false maintainConnection = false } h245Tunneling = true h245Control = 3 entries { [0]= 108 octets { 02 70 01 06 00 08 81 75 00 0d 80 13 80 00 fa 00 .p.....u........ 01 00 00 01 00 00 01 00 00 0c c0 01 00 01 80 07 ................ ... } [1]= 3 octets { 21 80 01 !.. } [2]= 2 octets { 20 a0 . } } } } } 2010-12-13 11:58:21.846211 [DEBUG] h323ep.cxx:1039 OnSentAlerting conn = Call[Cc70e3cf91]-EP<h323>[tcp$200.117.192.17:19270/22716] 2010-12-13 11:58:21.846211 [DEBUG] h323.cxx:3771 InternalEstablishedConnectionCheck: connectionState=AwaitingLocalAnswer fastStartState=FastStartResponse H.245 is ready 2010-12-13 11:58:21.846211 [DEBUG] connection.cxx:361 OnSetUpConnectionCall[Cc70e3cf91]-EP<local>[Ldc8d6c462] 2010-12-13 11:58:21.846211 [DEBUG] endpoint.cxx:408 OnSetUpConnection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] 2010-12-13 11:58:21.846211 [DEBUG] h323.cxx:1701 OnAnswerCall Call[Cc70e3cf91]-EP<h323>[tcp$200.117.192.17:19270/22716], caller = gustavo [200.117.192.17] 2010-12-13 11:58:21.846211 [DEBUG] call.cxx:219 OnAnswerCall Call[Cc70e3cf91]-EP<h323>[tcp$200.117.192.17:19270/22716] caller "gustavo [200.117.192.17]" 2010-12-13 11:58:21.846211 [DEBUG] h323.cxx:1713 Answering call: AnswerCallDeferred 2010-12-13 11:58:21.846211 [DEBUG] connection.cxx:541 Answering call: AnswerCallDeferred 2010-12-13 11:58:21.846211 [DEBUG] h323.cxx:3771 InternalEstablishedConnectionCheck: connectionState=AwaitingLocalAnswer fastStartState=FastStartResponse H.245 is ready 2010-12-13 11:58:21.846211 [DEBUG] h323.cxx:506 Reading PDUs: callRef=22716 2010-12-13 11:58:21.846211 [DEBUG] switch_ivr_originate.c:3448 Originate Resulted in Error Cause: 31 [NORMAL_UNSPECIFIED] 2010-12-13 11:58:21.849192 [INFO] mod_dptools.c:2579 Originate Failed. Cause: NORMAL_UNSPECIFIED 2010-12-13 11:58:21.849192 [DEBUG] mod_opal.cpp:1076 Received message 27 on connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] 2010-12-13 11:58:21.849192 [NOTICE] switch_core_state_machine.c:189 opal/in:231543514280633 has executed the last dialplan instruction, hanging up. 2010-12-13 11:58:21.849192 [DEBUG] switch_channel.c:2455 (opal/in:231543514280633) Callstate Change RINGING -> HANGUP 2010-12-13 11:58:21.849192 [NOTICE] switch_core_state_machine.c:191 Hangup opal/in:231543514280633 [CS_EXECUTE] [NORMAL_CLEARING] 2010-12-13 11:58:21.849192 [DEBUG] switch_channel.c:2471 Send signal opal/in:231543514280633 [KILL] 2010-12-13 11:58:21.849192 [DEBUG] mod_opal.cpp:931 Kill 1 on connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] 2010-12-13 11:58:21.849192 [DEBUG] mod_opal.cpp:1092 State changed on connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] 2010-12-13 11:58:21.849192 [DEBUG] switch_core_session.c:1083 Send signal opal/in:231543514280633 [BREAK] 2010-12-13 11:58:21.849192 [DEBUG] mod_opal.cpp:931 Kill 3 on connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] 2010-12-13 11:58:21.849192 [DEBUG] switch_core_state_machine.c:366 (opal/in:231543514280633) State EXECUTE going to sleep 2010-12-13 11:58:21.849192 [DEBUG] switch_core_state_machine.c:320 (opal/in:231543514280633) Running State Change CS_HANGUP 2010-12-13 11:58:21.849192 [DEBUG] switch_core_state_machine.c:557 (sofia/external/21543514280633(a)200.35.145.149) State HANGUP 2010-12-13 11:58:21.849192 [DEBUG] mod_sofia.c:453 sofia/external/21543514280633(a)200.35.145.149 Overriding SIP cause 480 with 900 from the other leg 2010-12-13 11:58:21.849192 [DEBUG] mod_sofia.c:459 Channel sofia/external/21543514280633(a)200.35.145.149 hanging up, cause: NORMAL_UNSPECIFIED 2010-12-13 11:58:21.849192 [DEBUG] switch_core_state_machine.c:46 sofia/external/21543514280633(a)200.35.145.149 Standard HANGUP, cause: NORMAL_UNSPECIFIED 2010-12-13 11:58:21.849192 [DEBUG] switch_core_state_machine.c:557 (sofia/external/21543514280633(a)200.35.145.149) State HANGUP going to sleep 2010-12-13 11:58:21.849192 [DEBUG] switch_core_state_machine.c:351 (sofia/external/21543514280633(a)200.35.145.149) State Change CS_HANGUP -> CS_REPORTING 2010-12-13 11:58:21.849192 [DEBUG] switch_core_session.c:1083 Send signal sofia/external/21543514280633(a)200.35.145.149 [BREAK] 2010-12-13 11:58:21.849192 [DEBUG] switch_core_state_machine.c:320 (sofia/external/21543514280633(a)200.35.145.149) Running State Change CS_REPORTING 2010-12-13 11:58:21.849192 [DEBUG] switch_core_state_machine.c:617 (sofia/external/21543514280633(a)200.35.145.149) State REPORTING 2010-12-13 11:58:21.849192 [DEBUG] switch_core_state_machine.c:557 (opal/in:231543514280633) State HANGUP 2010-12-13 11:58:21.849192 [DEBUG] connection.cxx:401 Call end reason for Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] set to EndedByRemoteUser 2010-12-13 11:58:21.849192 [DEBUG] call.cxx:112 Clearing Call[Cc70e3cf91] reason=EndedByRemoteUser 2010-12-13 11:58:21.852189 [DEBUG] connection.cxx:1516 SetPhase from SetUpPhase to ReleasingPhase for Call[Cc70e3cf91]-EP<h323>[tcp $200.117.192.17:19270/22716] 2010-12-13 11:58:21.852189 [DEBUG] connection.cxx:459 Releasing asynchronously Call[Cc70e3cf91]-EP<h323>[tcp$200.117.192.17:19270/22716] 2010-12-13 11:58:21.852189 [DEBUG] osutil.cxx:196 File handle low water mark set: 69 Thread unblock pipe 2010-12-13 11:58:21.852189 [DEBUG] connection.cxx:1516 SetPhase from AlertingPhase to ReleasingPhase for Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] 2010-12-13 11:58:21.852189 [DEBUG] connection.cxx:448 Releasing Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] 2010-12-13 11:58:21.852189 [DEBUG] connection.cxx:489 OnReleased Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] 2010-12-13 11:58:21.852189 [DEBUG] connection.cxx:808 Media streams closed. 2010-12-13 11:58:21.852189 [DEBUG] endpoint.cxx:450 OnReleased Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] 2010-12-13 11:58:21.852189 [DEBUG] manager.cxx:709 OnReleased Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] 2010-12-13 11:58:21.852189 [DEBUG] call.cxx:716 OnReleased Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] 2010-12-13 11:58:21.852189 [DEBUG] connection.cxx:436 Already released Call[Cc70e3cf91]-EP<h323>[tcp$200.117.192.17:19270/22716] 2010-12-13 11:58:21.856029 [DEBUG] connection.cxx:1516 SetPhase from ReleasingPhase to ReleasedPhase for Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] 2010-12-13 11:58:21.856029 [DEBUG] switch_core_state_machine.c:46 opal/in:231543514280633 Standard HANGUP, cause: NORMAL_CLEARING 2010-12-13 11:58:21.856029 [DEBUG] switch_core_state_machine.c:557 (opal/in:231543514280633) State HANGUP going to sleep 2010-12-13 11:58:21.856029 [DEBUG] switch_core_state_machine.c:351 (opal/in:231543514280633) State Change CS_HANGUP -> CS_REPORTING 2010-12-13 11:58:21.856029 [DEBUG] switch_core_session.c:1083 Send signal opal/in:231543514280633 [BREAK] 2010-12-13 11:58:21.856029 [DEBUG] switch_core_state_machine.c:320 (opal/in:231543514280633) Running State Change CS_REPORTING 2010-12-13 11:58:21.856029 [DEBUG] switch_core_state_machine.c:617 (opal/in:231543514280633) State REPORTING 2010-12-13 11:58:21.856029 [DEBUG] connection.cxx:401 Call end reason for Call[Cc70e3cf91]-EP<h323>[tcp$200.117.192.17:19270/22716] set to EndedByRemoteUser 2010-12-13 11:58:21.856029 [DEBUG] h323.cxx:349 OnReleased: tcp $200.117.192.17:19270/22716, connectionState=AwaitingLocalAnswer 2010-12-13 11:58:21.856029 [DEBUG] switch_core_state_machine.c:53 sofia/external/21543514280633(a)200.35.145.149 Standard REPORTING, cause: NORMAL_UNSPECIFIED 2010-12-13 11:58:21.856029 [DEBUG] switch_core_state_machine.c:617 (sofia/external/21543514280633(a)200.35.145.149) State REPORTING going to sleep 2010-12-13 11:58:21.856029 [DEBUG] switch_core_state_machine.c:345 (sofia/external/21543514280633(a)200.35.145.149) State Change CS_REPORTING -> CS_DESTROY 2010-12-13 11:58:21.856029 [DEBUG] switch_core_session.c:1083 Send signal sofia/external/21543514280633(a)200.35.145.149 [BREAK] 2010-12-13 11:58:21.856029 [DEBUG] switch_core_session.c:1250 Session 2 (sofia/external/21543514280633(a)200.35.145.149) Locked, Waiting on external entities 2010-12-13 11:58:21.856029 [NOTICE] switch_core_session.c:1268 Session 2 (sofia/external/21543514280633(a)200.35.145.149) Ended 2010-12-13 11:58:21.856029 [NOTICE] switch_core_session.c:1270 Close Channel sofia/external/21543514280633(a)200.35.145.149 [CS_DESTROY] 2010-12-13 11:58:21.856029 [DEBUG] switch_core_state_machine.c:449 (sofia/external/21543514280633(a)200.35.145.149) Callstate Change HANGUP -> DOWN 2010-12-13 11:58:21.858228 [DEBUG] switch_core_state_machine.c:452 (sofia/external/21543514280633(a)200.35.145.149) Running State Change CS_DESTROY 2010-12-13 11:58:21.858228 [DEBUG] switch_core_state_machine.c:462 (sofia/external/21543514280633(a)200.35.145.149) State DESTROY 2010-12-13 11:58:21.858228 [DEBUG] mod_sofia.c:364 sofia/external/21543514280633(a)200.35.145.149 SOFIA DESTROY 2010-12-13 11:58:21.858228 [DEBUG] switch_core_state_machine.c:60 sofia/external/21543514280633(a)200.35.145.149 Standard DESTROY 2010-12-13 11:58:21.858228 [DEBUG] switch_core_state_machine.c:462 (sofia/external/21543514280633(a)200.35.145.149) State DESTROY going to sleep 2010-12-13 11:58:21.856029 [DEBUG] h323.cxx:356 Sending release complete PDU: callRef=22716 2010-12-13 11:58:21.858228 [DEBUG] h323pdu.cxx:80 Sending PDU: command endSessionCommand disconnect <<null>> 2010-12-13 11:58:21.858228 [DEBUG] h323pdu.cxx:80 Sending PDU: { q931pdu = { protocolDiscriminator = 8 callReference = 22716 from = destination messageType = ReleaseComplete IE: Cause - Normal call clearing = { 80 90 .. } IE: Display = { 72 6f 6f 74 00 root. } IE: User-User = { 25 80 06 00 08 91 4a 00 06 01 11 00 3a 76 b2 f2 %.....J.....:v.. Log-Func: Log-Line: 0 User-Data: 1d 05 e0 11 9b b7 00 1e 64 1f 33 fe 04 c0 01 80 ........d.3..... ... } } h225pdu = { h323_uu_pdu = { h323_message_body = releaseComplete { protocolIdentifier = 0.0.8= { guid = 16 octets { 3a 76 b2 f2 1d 05 e0 11 9b b7 00 1e 64 1f 33 fe :v..........d.3. } } } h245Tunneling = true h245Control = 1 entries { [0]= 2 octets { 4a 40 J@ } } } } } 2010-12-13 11:58:21.861189 [DEBUG] h323.cxx:412 Awaiting end session from remote for 9.997 seconds 2010-12-13 11:58:21.861189 [DEBUG] switch_core_state_machine.c:53 opal/in:231543514280633 Standard REPORTING, cause: NORMAL_CLEARING 2010-12-13 11:58:21.861189 [DEBUG] switch_core_state_machine.c:617 (opal/in:231543514280633) State REPORTING going to sleep 2010-12-13 11:58:21.861189 [DEBUG] switch_core_state_machine.c:345 (opal/in:231543514280633) State Change CS_REPORTING -> CS_DESTROY 2010-12-13 11:58:21.861189 [DEBUG] switch_core_session.c:1083 Send signal opal/in:231543514280633 [BREAK] 2010-12-13 11:58:21.861189 [DEBUG] switch_core_session.c:1250 Session 1 (opal/in:231543514280633) Locked, Waiting on external entities 2010-12-13 11:58:21.861189 [NOTICE] switch_core_session.c:1268 Session 1 (opal/in:231543514280633) Ended 2010-12-13 11:58:21.861189 [NOTICE] switch_core_session.c:1270 Close Channel opal/in:231543514280633 [CS_DESTROY] 2010-12-13 11:58:21.861189 [DEBUG] switch_core_state_machine.c:449 (opal/in:231543514280633) Callstate Change HANGUP -> DOWN 2010-12-13 11:58:21.861189 [DEBUG] switch_core_state_machine.c:452 (opal/in:231543514280633) Running State Change CS_DESTROY 2010-12-13 11:58:21.861189 [DEBUG] switch_core_state_machine.c:462 (opal/in:231543514280633) State DESTROY 2010-12-13 11:58:21.861189 [DEBUG] switch_core_state_machine.c:60 opal/in:231543514280633 Standard DESTROY 2010-12-13 11:58:21.861189 [DEBUG] switch_core_state_machine.c:462 (opal/in:231543514280633) State DESTROY going to sleep 2010-12-13 11:58:22.087167 [DEBUG] h323pdu.cxx:80 Receiving PDU: { q931pdu = { protocolDiscriminator = 8 callReference = 22716 from = originator messageType = Facility IE: Facility = { } IE: Display = { 67 75 73 74 (..........!... 80 . } } h225pdu = { h323_uu_pdu = { h323_message_body = empty <<null>> h245Tunneling = true h245Control = 2 entries { [0]= 3 octets { 21 80 01 !.. } [1]= 2 octets { 20 80 . } } } } } 2010-12-13 11:58:22.087167 [DEBUG] h323.cxx:559 Handling PDU: Facility callRef=22716 2010-12-13 11:58:22.087167 [DEBUG] h323.cxx:2709 Checking for end session on PDU: response terminalCapabilitySetAck 2010-12-13 11:58:22.087167 [DEBUG] h323.cxx:2709 Checking for end session on PDU: response masterSlaveDeterminationAck 2010-12-13 11:58:22.087167 [DEBUG] connection.cxx:436 Already released Call[Cc70e3cf91]-EP<h323>[tcp$200.117.192.17:19270/22716] 2010-12-13 11:58:22.087167 [DEBUG] h323.cxx:549 Signal channel closed. 2010-12-13 11:58:22.087167 [DEBUG] transports.cxx:900 Transport clean up on termination 2010-12-13 11:58:22.087167 [DEBUG] transports.cxx:885 Transport Close 2010-12-13 11:58:22.090169 [DEBUG] osutil.cxx:196 File handle low water mark set: 65 PTextFile 2010-12-13 11:58:22.090169 [DEBUG] tlibthrd.cxx:1020 Could not parse thread stat file /proc/21145/task/21190/stat 2010-12-13 11:58:22.306147 [DEBUG] connection.cxx:489 OnReleased Call[Cc70e3cf91]-EP<h323>[tcp$200.117.192.17:19270/22716] 2010-12-13 11:58:22.306147 [DEBUG] connection.cxx:808 Media streams closed. 2010-12-13 11:58:22.306147 [DEBUG] endpoint.cxx:450 OnReleased Call[Cc70e3cf91]-EP<h323>[tcp$200.117.192.17:19270/22716] 2010-12-13 11:58:22.306147 [DEBUG] manager.cxx:709 OnReleased Call[Cc70e3cf91]-EP<h323>[tcp$200.117.192.17:19270/22716] 2010-12-13 11:58:22.306147 [DEBUG] call.cxx:716 OnReleased Call[Cc70e3cf91]-EP<h323>[tcp$200.117.192.17:19270/22716] 2010-12-13 11:58:22.306147 [DEBUG] manager.cxx:535 OnClearedCall Call[Cc70e3cf91] from "h323:200.117.192.17" to "h323:231543514280633" 2010-12-13 11:58:22.306147 [DEBUG] connection.cxx:1516 SetPhase from ReleasingPhase to ReleasedPhase for Call[Cc70e3cf91]-EP<h323>[tcp $200.117.192.17:19270/22716] 2010-12-13 11:58:22.306147 [DEBUG] connection.cxx:480 OnRelease thread completed for Call[Cc70e3cf91]-EP<h323>[tcp$200.117.192.17:19270/22716] 2010-12-13 11:58:22.308152 [DEBUG] tlibthrd.cxx:1020 Could not parse thread stat file /proc/21145/task/21194/stat 2010-12-13 11:58:22.588122 [DEBUG] transports.cxx:900 Transport clean up on termination 2010-12-13 11:58:22.588122 [DEBUG] transports.cxx:885 Transport Close 2010-12-13 11:58:22.588122 [DEBUG] transports.cxx:1045 Deleted transport tcp$200.117.192.17:19270<if=tcp$72.51.47.100:1720> 2010-12-13 11:58:22.590122 [DEBUG] h323.cxx:330 Connection tcp $200.117.192.17:19270/22716 deleted. 2010-12-13 11:58:22.590122 [DEBUG] connection.cxx:343 Connection Call[Cc70e3cf91]-EP<h323>[tcp$200.117.192.17:19270/22716] destroyed. 2010-12-13 11:58:22.590122 [DEBUG] localep.cxx:211 Deleted connection. 2010-12-13 11:58:22.590122 [DEBUG] connection.cxx:343 Connection Call[Cc70e3cf91]-EP<local>[Ldc8d6c462] destroyed. 2010-12-13 11:58:23.592039 [DEBUG] call.cxx:86 Call[Cc70e3cf91] destroyed. --------------------------------------------------------------------------------------------------------------------------------------------------------------------- Gustavo Espeche www.easyipcall.com

2 1

Test - Please ignore
by Marc de Corny 13 Dec '10

13 Dec '10

Test - Please ignore thanks Marc

1 0

Re: [Freeswitch-users] bind_meta_app what call leg?-decision
by Михаил Салтанов 13 Dec '10

13 Dec '10

Hello The decision of this problem in default.xml <------><action application="set" data="bridge_pre_execute_aleg_app=bind_meta_app"/> <------><action application="set" data="bridge_pre_execute_aleg_data=1 ab s execute_extension::dxa XML features"/> <------><action application="set" data="bridge_pre_execute_bleg_app=bind_meta_app"/> <------><action application="set" data="bridge_pre_execute_bleg_data=1 ab s execute_extension::dxb XML features"/> in features.xml  <extension name="dxa"> <condition field="destination_number" expression="^dxa$"> <------><action application="answer"/> <------><action application="set" data="who_make_transfer=${caller_id_number}"/> <------><action application="read" data="11 11 'tone_stream://%(10000,0,350,440)' digits 5000 #"/> <------><action application="execute_extension" data="is_transfer XML features"/> </condition> </extension> .... <extension name="dxb"> <condition field="destination_number" expression="^dxb$"> <action application="set" data="who_make_transfer=${destination_number}"/> <><action application="answer"/> <------><action application="read" data="11 11 'tone_stream://%(10000,0,350,440)' digits 5000 #"/> <------><action application="set" data="accountcode=${who_make_transfer}"/> <------><action application="execute_extension" data="is_transfer XML features"/> </condition> </extension> С уважением Михаил Салтанов

1 0