- FreeSWITCH-users - FreeSWITCH Mailing Lists

Re: [Freeswitch-users] tts_commandline question
by Mathieu Parent 14 Dec '10

14 Dec '10

2010/12/14 Phone <phone.bytes(a)gmail.com>: > Hi Mathieu, > > I have been trying to use mod_cepstral. I had trouble with it hanging and > needing to restart freeswitch after funning only a few hours. > > MC suggested that I try tts_commandline to see if that worked better. I did > and found I did not have any issues doing cepstral this way. > > However, my need is to be able to feed a text file to Swift instead of > sending the text on the command line. I have tried using a -f switch as > Swift allows, but it seems that tts_commandline does not support this > option. mod_tts_commandline only work with text as a ARG. ${text} is replaced by the actual string, quoted (for security reasons). > > Am I missing something here or is there another way of doing this? You probably want to use cepstral as a system command (http://wiki.freeswitch.org/wiki/Mod_commands#system) and the playback (http://wiki.freeswitch.org/wiki/Misc._Dialplan_Tools_playback) > Following is a piece of a trace from the log. > > It seems to almost build the swift command correctly. However, the -f > option is inside the single quotes, instead of outside of them like the -o > (output file) is. > > I don't think it is finding the .txt file, as the log later shows that it is > trying to speak the filename including the -f option, instead of the > contents of the file. > > Thanks > > EXECUTE FreeTDM/1:1/7878030 speak(tts_commandline|Callie|-f/tmp/test.txt) > 2010-12-11 00:26:58.683522 [DEBUG] switch_ivr_play_say.c:2237 OPEN TTS > tts_commandline > 2010-12-11 00:26:58.683522 [DEBUG] switch_ivr_play_say.c:2246 Raw Codec > Activated > 2010-12-11 00:26:58.683522 [DEBUG] mod_tts_commandline.c:147 Executing: > swift -p audio/sampling-rate=8000 -n 'Callie' '-f/tmp/test.txt' -o > '/tmp/bd5bb8db-ffc2-4830-a971-24a1454c725c.tmp.wav' > 2010-12-11 00:26:58.696525 [ERR] mod_sndfile.c:194 Error Opening File > [/tmp/bd5bb8db-ffc2-4830-a971-24a1454c725c.tmp.wav] [System error : No such > file or directory.] > 2010-12-11 00:26:58.696525 [ERR] mod_tts_commandline.c:157 Failed to open > file: /tmp/bd5bb8db-ffc2-4830-a971-24a1454c725c.tmp.wav > 2010-12-11 00:26:58.696525 [DEBUG] switch_ivr_play_say.c:1935 Speaking text: > -f/tmp/test.txt > 2010-12-11 00:26:59.097509 [DEBUG] switch_ivr_play_say.c:2127 done speaking > text > > > > -- Mathieu

1 0

mod_h323 issue
by Gustavo Espeche 14 Dec '10

14 Dec '10

Hello, i'll try to interworker h323 to sip with fs but fs don't send the call to sip gw, i test with some h323 endpoint with the same result, follow is the debug log of FS, i'm apreciate a lot if someone can give me some tips for that it work. best Regards Gustavo Espeche www.easyipcall.com 2010-12-14 12:38:57.395263 [DEBUG] transports.cxx:1756 Started connection: host=200.117.192.17:20570, if=72.51.47.100:1720, handle=5 2010-12-14 12:38:57.395263 [DEBUG] transports.cxx:1550 Waiting on socket accept on ip$72.51.47.100:1720 2010-12-14 12:38:57.395263 [DEBUG] transports.cxx:681 Started incoming call thread 2010-12-14 12:38:57.395263 [DEBUG] transports.cxx:1334 Awaiting first PDU 2010-12-14 12:38:57.635261 [DEBUG] h323pdu.cxx:618 Receiving PDU [ip $72.51.47.100:1720/ip$200.117.192.17:20570] : { q931pdu = { protocolDiscriminator = 8 callReference = 4293 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"....= ... } } h 225pdu = { h323_uu_pdu = { h323_message_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 } } sourceInfo = { vendor = { vendor = { t35CountryCode = 9 t35Extension = 0 manufacturerCode = 61 } produc (OPAL v3.6 2e 36 29 00 00 .6).. } } terminal = { } mc = false undefinedNode = false } destinationAddress = 1 entries { [0]= dialedDigits "231543514280633" } destCallSignalAddress = ipAddress { ip = 4 octets { 48 33 2f 64 H3/d } port = 1720 } a ctiveMC = false conferenceID = 16 octets { b6 4e 58 c9 ec 05 e0 11 9e 19 00 1e 64 1f 33 fe .NX.........d.3. } conferenceGoal = create <<null>> callType = pointToPoint <<null>> sourceC allSignalAddress = ipAddress { ip = 4 octets { c8 75 c0 11 .u.. } port = 20570 } callIdentifier = { guid = 16 octets { a2 4e 58 c9 ec 05 e0 11 9e 19 00 1e 64 1f 33 fe .NX.........d.3. } } fastStart = 12 entries { [0]= 29 octets { 40 00 00 06 04 01 00 4c 20 13 80 11 1c 00 01 00 @......L ....... Log-Func: Log-Line: 0 User-Data: c8 75 c0 11 13 c6 00 c8 75 c0 11 13 c7 .u......u.... } [1]= 19 octets { 00 00 64 0c 20 13 80 0b 0d 00 01 00 c8 75 c0 11 ..d. ........u.. ... } [2]= 32 octets { 40 00 00 06 04 01 00 4e 0c 03 00 20 00 80 11 1c @......N... .... 00 01 00 c8 75 c0 11 13 c6 00 c8 75 c0 11 13 c7 ....u......u.... } [3]= 22 octets { 00 00 65 0e 0c 03 00 20 00 80 0b 0d 00 01 00 c8 ..e.... ........ 75 c0 11 13 c7 00 u..... } [4]= 43 octets { 40 00 00 06 04 01 00 4c 10 09 00 00 3d 09 47 2e @......L....=.G. 37 32 36 2d 31 36 6b 80 12 1c 40 01 00 c8 75 c0 726-16k...@...u. ... } [5]= 32 octets { 00 00 66 0c 10 09 00 00 3d 09 47 2e 37 32 36 2d ..f.....=.G.726- 31 36 6b 80 0b 0d 40 01 00 c8 75 c0 11 13 c7 54 16k...@...u....T } [6]= 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 c8 00 c8 75 c0 .......u......u. ... } [7]= 25 octets { 00 00 67 08 78 00 4a ff 00 80 01 00 80 0b 0d 00 ..g.x.J......... 02 00 c8 75 c0 11 13 c9 00 ...u..... } [8]= 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 c8 00 c8 75 c0 11 ......u......u.. ... } [9]= 24 octets { 00 00 68 08 68 4a ff 00 80 01 00 80 0b 0d 00 02 ..h.hJ.......... 00 c8 75 c0 11 13 c9 00 ..u..... } [10]= 34 o ctets { 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 c8 00 c8 75 c0 11 ......u......u.. ... } [11]= 24 octets { 0 0 00 69 08 70 4a ff 00 80 01 00 80 0b 0d 00 02 ..i.pJ.......... 00 c8 75 c0 11 13 c9 00 ..u..... } } mediaWaitForConnect = false canOverlapSend = false m ultipleCalls = false maintainConnection = false parallelH245Control = 2 entries { [0]= 157 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 09 ................ ... } [1]= 7 octets { 01 00 32 80 1e 40 29 ..2..@) } } } h245Tunneling = true Log-Func: LogINCOMING DATA [(null)] RECV EVENT Event-Name: SOCKET_DATA -Line: 0 User-Data: _undef_ 2010-12-14 12:38:57.635261 [DEBUG] transports.cxx:1344 Incoming call, first PDU: callReference=4293 2010-12-14 12:38:57.635261 [DEBUG] mod_h323.cpp:608 ======>FSH323EndPoint::CreateConnection callReference = 4293 userDate = (nil) [0x9a76490] 2010-12-14 12:38:57.635261 [DEBUG] mod_h323.cpp:613 ------> SWITCH_CALL_DIRECTION_INBOUND 2010-12-14 12:38:57.638241 [DEBUG] mod_h323.cpp:621 ------> fsSession = 0x9bfe898 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3598 Added capability: G.711-ALaw-64k <1> 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3598 Added capability: G.711-uLaw-64k <2> 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3598 Added capability: GSM-06.10{sw} <3> 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3598 Added capability: G.729A/B{sw} <4> 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3598 Added capability: G.729A{sw} <5> 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3598 Added capability: G.729B{sw} <6> 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3598 Added capability: G.729{sw} <7> 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3598 Added capability: T.38-IFP-PRE <8> 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3598 Added capability: UserInput/hookflash <9> 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3598 Added capability: UserInput/basicString <10> 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3598 Added capability: UserInput/dtmf <11> 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3598 Added capability: UserInput/RFC2833 <12> 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3598 Added capability: UserInput/Navigation <13> 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3598 Added capability: UserInput/Softkey <14> 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3598 Added capability: UserInput/PointDevice <15> 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3598 Added capability: UserInput/Modal <16> 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3660 FindCapability: 1 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3664 Found capability: G.711-ALaw-64k <1> 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3660 FindCapability: 2 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3664 Found capability: G.711-uLaw-64k <2> 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3660 FindCapability: 3 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3664 Found capability: GSM-06.10{sw} <3> 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3660 FindCapability: 4 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3664 Found capability: G.729A/B{sw} <4> 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3660 FindCapability: 5 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3664 Found capability: G.729A{sw} <5> 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3660 FindCapability: 6 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3664 Found capability: G.729B{sw} <6> 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3660 FindCapability: 7 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3664 Found capability: G.729{sw} <7> 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3660 FindCapability: 8 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3664 Found capability: T.38-IFP-PRE <8> 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3660 FindCapability: 9 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3664 Found capability: UserInput/hookflash <9> 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3660 FindCapability: 10 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3664 Found capability: UserInput/basicString <10> 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3660 FindCapability: 11 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3664 Found capability: UserInput/dtmf <11> 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3660 FindCapability: 12 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3664 Found capability: UserInput/RFC2833 <12> 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3660 FindCapability: 13 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3664 Found capability: UserInput/Navigation <13> 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3660 FindCapability: 14 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3664 Found capability: UserInput/Softkey <14> 2010-12-14 12:38:57.638241 [DEBUG] h323caps.cxx:3660 FindCapability: 15 2010-12-14 12:38:57.641242 [DEBUG] h323caps.cxx:3664 Found capability: UserInput/PointDevice <15> 2010-12-14 12:38:57.641244 [DEBUG] h323caps.cxx:3660 FindCapability: 16 2010-12-14 12:38:57.641244 [DEBUG] h323caps.cxx:3664 Found capability: UserInput/Modal <16> 2010-12-14 12:38:57.641244 [DEBUG] h4601.cxx:1792 Endpoint Attached 2010-12-14 12:38:57.641244 [DEBUG] rfc2833.cxx:87 Handler created 2010-12-14 12:38:57.641244 [DEBUG] h4601.cxx:1287 Loaded Std 24 2010-12-14 12:38:57.641244 [DEBUG] h4601.cxx:1260 Loaded Feature Std24 2010-12-14 12:38:57.641244 [DEBUG] mod_h323.cpp:707 ======>FSH323Connection::FSH323Connection [0x9a7d140] 2010-12-14 12:38:57.641244 [DEBUG] h323ep.cxx:3160 Created new connection: ip$200.117.192.17:20570/4293 2010-12-14 12:38:57.641244 [DEBUG] mod_h323.cpp:764 ---------->token = ip$200.117.192.17:20570/4293 [0x9a7d140] 2010-12-14 12:38:57.641244 [DEBUG] h323.cxx:1426 Handling PDU: Setup callRef=4293 2010-12-14 12:38:57.641244 [DEBUG] mod_h323.cpp:1013 ======>FSH323Connection::OnReceivedSignalSetup [0x9a7d140] 2010-12-14 12:38:57.641244 [DEBUG] h323.cxx:1707 Set protocol version to 6 and implying H.245 version 13 2010-12-14 12:38:57.641244 [DEBUG] h323.cxx:1993 Set remote application name: " 3.2.6 (OPAL v3.6.6) 9/61" 2010-12-14 12:38:57.641244 [DEBUG] h4601.cxx:1775 Removing all Features are remote/Gk does not appear to support H.460 2010-12-14 12:38:57.641244 [DEBUG] mod_h323.cpp:771 ======>FSH323Connection::OnSetLocalCapabilities() [0x9a7d140] 2010-12-14 12:38:57.641244 [DEBUG] mod_h323.cpp:778 ======>FSH323Connection::SetLocalCapabilities() Size local capability = 16 [0x9a7d140] 2010-12-14 12:38:57.641244 [DEBUG] mod_h323.cpp:819 ======>FSH323Connection::decodeCapability [0x9a7d140] 2010-12-14 12:38:57.641244 [NOTICE] mod_h323.cpp:841 capability 'G.711-ALaw-64k' format 'PCMA' 8 2010-12-14 12:38:57.641244 [DEBUG] mod_h323.cpp:819 ======>FSH323Connection::decodeCapability [0x9a7d140] 2010-12-14 12:38:57.641244 [NOTICE] mod_h323.cpp:841 capability 'G.711-uLaw-64k' format 'PCMU' 0 2010-12-14 12:38:57.641244 [DEBUG] mod_h323.cpp:819 ======>FSH323Connection::decodeCapability [0x9a7d140] 2010-12-14 12:38:57.641244 [NOTICE] mod_h323.cpp:841 capability 'GSM-06.10' format 'GSM' 3 2010-12-14 12:38:57.641244 [DEBUG] mod_h323.cpp:819 ======>FSH323Connection::decodeCapability [0x9a7d140] 2010-12-14 12:38:57.641244 [NOTICE] mod_h323.cpp:841 capability 'G.729A/B' format 'G729' 18 2010-12-14 12:38:57.641244 [DEBUG] mod_h323.cpp:819 ======>FSH323Connection::decodeCapability [0x9a7d140] 2010-12-14 12:38:57.641244 [NOTICE] mod_h323.cpp:841 capability 'G.729A' format 'G729' 18 2010-12-14 12:38:57.641244 [DEBUG] mod_h323.cpp:819 ======>FSH323Connection::decodeCapability [0x9a7d140] 2010-12-14 12:38:57.641244 [NOTICE] mod_h323.cpp:841 capability 'G.729B' format 'G729b' 18 2010-12-14 12:38:57.641244 [DEBUG] mod_h323.cpp:819 ======>FSH323Connection::decodeCapability [0x9a7d140] 2010-12-14 12:38:57.641244 [NOTICE] mod_h323.cpp:841 capability 'G.729' format 'G729' 18 2010-12-14 12:38:57.641244 [DEBUG] mod_h323.cpp:819 ======>FSH323Connection::decodeCapability [0x9a7d140] 2010-12-14 12:38:57.641244 [DEBUG] mod_h323.cpp:819 ======>FSH323Connection::decodeCapability [0x9a7d140] 2010-12-14 12:38:57.641244 [DEBUG] mod_h323.cpp:819 ======>FSH323Connection::decodeCapability [0x9a7d140] 2010-12-14 12:38:57.641244 [DEBUG] mod_h323.cpp:819 ======>FSH323Connection::decodeCapability [0x9a7d140] 2010-12-14 12:38:57.641244 [DEBUG] mod_h323.cpp:819 ======>FSH323Connection::decodeCapability [0x9a7d140] 2010-12-14 12:38:57.641244 [DEBUG] mod_h323.cpp:819 ======>FSH323Connection::decodeCapability [0x9a7d140] 2010-12-14 12:38:57.641244 [DEBUG] mod_h323.cpp:819 ======>FSH323Connection::decodeCapability [0x9a7d140] 2010-12-14 12:38:57.641244 [DEBUG] mod_h323.cpp:819 ======>FSH323Connection::decodeCapability [0x9a7d140] 2010-12-14 12:38:57.641244 [DEBUG] mod_h323.cpp:819 ======>FSH323Connection::decodeCapability [0x9a7d140] 2010-12-14 12:38:57.644242 [DEBUG] h323.cxx:1830 Sending call proceeding PDU 2010-12-14 12:38:57.644242 [DEBUG] mod_h323.cpp:634 ======>FSH323EndPoint::OnSetGatewayPrefixes [0x9a76490] 2010-12-14 12:38:57.644242 [DEBUG] mod_h323.cpp:1077 ======>FSH323Connection::OnSendCallProceeding fastStartState = FastStartInitiate [0x9a7d140] 2010-12-14 12:38:57.644242 [DEBUG] mod_h323.cpp:634 ======>FSH323EndPoint::OnSetGatewayPrefixes [0x9a76490] 2010-12-14 12:38:57.644242 [DEBUG] h323.cxx:1873 Incoming call accepted 2010-12-14 12:38:57.644242 [DEBUG] h323.cxx:1715 Fast start detected 2010-12-14 12:38:57.644242 [DEBUG] h323.cxx:1722 Fast start open: { forwardLogicalChannelNumber = 1 forwardLogicalChannelParameters = { dataType = nullData <<null>> multiplexParameters = none <<null>> } reverseLogicalChannelParameters = { dataType = audioData g711Alaw64k 20 multiplexParameters = h2250LogicalChannelParameters { sessionID = 1 mediaChannel = unicastAddress iPAddress { network = 4 octets { c8 75 c0 11 .u.. } tsapIdentifier = 5062 } mediaGuaranteedDelivery = false mediaControlChannel = unicastAddress iPAddress { network = 4 octets { c8 75 c0 11 Log-Func: Log-Line: 0 User-Data: .u.. } tsapIdentifier2010-12-14 12:38:57.644242 [DEBUG] h323.cxx:4747 CreateLogicalChannel - reverse channel 2010-12-14 12:38:57.644242 [DEBUG] h323caps.cxx:3792 FindCapability: audioData 2010-12-14 12:38:57.644242 [DEBUG] h323caps.cxx:3833 Found capability: G.711-ALaw-64k <1> 2010-12-14 12:38:57.644242 [DEBUG] h323caps.cxx:3712 FindCapability: G.711-ALaw-64k <1> 2010-12-14 12:38:57.644242 [DEBUG] h323caps.cxx:3598 Added capability: G.711-ALaw-64k <1> 2010-12-14 12:38:57.644242 [DEBUG] mod_h323.cpp:978 ======>FSH323Connection::OnCreateLogicalChannel ('G.711-ALaw-64k',IsTransmitter) [0x9a7d140] 2010-12-14 12:38:57.644242 [DEBUG] mod_h323.cpp:961 ======>FSH323Connection::CreateRealTimeLogicalChannel [0x9a7d140] 2010-12-14 12:38:57.644242 [DEBUG] mod_h323.cpp:1880 ======>FSH323_ExternalRTPChannel::FSH323_ExternalRTPChannel sessionID = 1 :IsTransmitter addr = 72.51.47.100:56790 [0xb773dc88] 2010-12-14 12:38:57.644242 [DEBUG] mod_h323.cpp:1884 ------->capability.GetPayloadType() return = [pt=128] 2010-12-14 12:38:57.644242 [DEBUG] mod_h323.cpp:1885 ------->capability.GetFormatName() return = G.711-ALaw-64k 2010-12-14 12:38:57.644242 [DEBUG] mod_h323.cpp:1897 ------->payloadCode = 8 2010-12-14 12:38:57.644242 [DEBUG] codecs.cxx:1534 G711 ALaw encoder created for at 64k, 160 samples 2010-12-14 12:38:57.644242 [DEBUG] channels.cxx:866 Bandwidth requested/used = 64.0/0.0 kb/s 2010-12-14 12:38:57.644242 [DEBUG] h323.cxx:5105 Bandwidth request: -0.0kb/s, available: 10000.0kb/s 2010-12-14 12:38:57.644242 [DEBUG] h323.cxx:5105 Bandwidth request: +64.0kb/s, available: 10000.0kb/s 2010-12-14 12:38:57.644242 [DEBUG] channels.cxx:1056 OnReceivedPDU for channel: T-0 2010-12-14 12:38:57.644242 [DEBUG] mod_h323.cpp:2223 ======>FSH323_ExternalRTPChannel::OnReceivedPDU [0xb773dc88] 2010-12-14 12:38:57.644242 [DEBUG] mod_h323.cpp:2230 Remote RTP address 200.117.192.17:5062 2010-12-14 12:38:57.644242 [DEBUG] mod_h323.cpp:1357 ======>PFSH323Connection::setRemoteAddress remoteIP = 200.117.192.17 , remotePort = 5062 [0x9a7d140] 2010-12-14 12:38:57.644242 [DEBUG] mod_h323.cpp:1360 Got remote RTP address 200.117.192.17:5062 [0x9a7d140] 2010-12-14 12:38:57.647243 [DEBUG] h323.cxx:1722 Fast start open: { forwardLogicalChannelNumber = 101 forwardLogicalChannelParameters = { dataType = audioData g711Alaw64k 20 multiplexParameters = h2250LogicalChannelParameters { sessionID = 1 mediaGuaranteedDelivery = false mediaControlChannel = unicastAddress iPAddress { network = 4 octets { c8 75 c0 11 .u.. } tsapIdentifier = 5063 Log-Func: Log-Line: 0 User-Data: } silenceSu2010-12-14 12:38:57.647243 [DEBUG] h323.cxx:4762 CreateLogicalChannel - forward channel 2010-12-14 12:38:57.647243 [DEBUG] h323caps.cxx:3792 FindCapability: audioData 2010-12-14 12:38:57.647243 [DEBUG] h323caps.cxx:3833 Found capability: G.711-ALaw-64k <1> 2010-12-14 12:38:57.647243 [DEBUG] mod_h323.cpp:978 ======>FSH323Connection::OnCreateLogicalChannel ('G.711-ALaw-64k',IsReceiver) [0x9a7d140] 2010-12-14 12:38:57.647243 [DEBUG] mod_h323.cpp:961 ======>FSH323Connection::CreateRealTimeLogicalChannel [0x9a7d140] 2010-12-14 12:38:57.647243 [DEBUG] mod_h323.cpp:1880 ======>FSH323_ExternalRTPChannel::FSH323_ExternalRTPChannel sessionID = 1 :IsReceiver addr = 72.51.47.100:56790 [0xb773b9a0] 2010-12-14 12:38:57.647243 [DEBUG] mod_h323.cpp:1884 ------->capability.GetPayloadType() return = [pt=128] 2010-12-14 12:38:57.647243 [DEBUG] mod_h323.cpp:1885 ------->capability.GetFormatName() return = G.711-ALaw-64k 2010-12-14 12:38:57.647243 [DEBUG] mod_h323.cpp:1897 ------->payloadCode = 8 2010-12-14 12:38:57.647243 [DEBUG] codecs.cxx:1534 G711 ALaw decoder created for at 64k, 160 samples 2010-12-14 12:38:57.647243 [DEBUG] channels.cxx:866 Bandwidth requested/used = 64.0/0.0 kb/s 2010-12-14 12:38:57.647243 [DEBUG] h323.cxx:5105 Bandwidth request: -0.0kb/s, available: 9936.0kb/s 2010-12-14 12:38:57.647243 [DEBUG] h323.cxx:5105 Bandwidth request: +64.0kb/s, available: 9936.0kb/s 2010-12-14 12:38:57.647243 [DEBUG] channels.cxx:1056 OnReceivedPDU for channel: R-101 2010-12-14 12:38:57.647243 [DEBUG] mod_h323.cpp:2223 ======>FSH323_ExternalRTPChannel::OnReceivedPDU [0xb773b9a0] 2010-12-14 12:38:57.647243 [DEBUG] mod_h323.cpp:2230 Remote RTP address 200.117.192.17:5062 2010-12-14 12:38:57.647243 [DEBUG] mod_h323.cpp:1357 ======>PFSH323Connection::setRemoteAddress remoteIP = 200.117.192.17 , remotePort = 5062 [0x9a7d140] 2010-12-14 12:38:57.647243 [DEBUG] h323.cxx:1722 Fast start open: { forwardLogicalChannelNumber = 1 forwardLogicalChannelParameters = { dataType = nullData <<null>> multiplexParameters = none <<null>> } reverseLogicalChannelParameters = { dataType = audioData gsmFullRate { audioUnitSize = 33 comfortNoise = false scrambled = false } multiplexParameters = h2250LogicalChannelParameters { sessionID = 1 mediaChannel = unicastAddre ss iPAddress { network = 4 octets { c8 75 c0 11 .u.. } tsapIdentifier = 5062 } mediaGuaranteedDelivery = false mediaControlChannel = unicastAdd ress iPAddress { network = 4 octets { c8 75 c0 11 .u.. } tsapIdentifier = 5063 } } } } 2010-12-14 12:38:57.647243 [DEBUG] h323.cxx:4747 CreateLogicalChannel - reverse channel 2010-12-14 12:38:57.647243 [DEBUG] h323caps.cxx:3792 FindCapability: audioData 2010-12-14 12:38:57.647243 [DEBUG] mod_h323.h:529 ==============>BaseGSM0610Cap::OnReceivedPDU [0xb790e360] 2010-12-14 12:38:57.647243 [DEBUG] h323caps.cxx:1543 Capability tx frames reduced from 2 to 1 2010-12-14 12:38:57.647243 [DEBUG] h323caps.cxx:3833 Found capability: GSM-06.10{sw} <3> 2010-12-14 12:38:57.647243 [DEBUG] mod_h323.h:529 ==============>BaseGSM0610Cap::OnReceivedPDU [0x9b1c240] 2010-12-14 12:38:57.647243 [DEBUG] h323caps.cxx:1543 Capability tx frames reduced from 2 to 1 2010-12-14 12:38:57.647243 [DEBUG] h323caps.cxx:3712 FindCapability: GSM-06.10{sw} <3> 2010-12-14 12:38:57.647243 [DEBUG] h323caps.cxx:3598 Added capability: GSM-06.10{sw} <3> 2010-12-14 12:38:57.647243 [DEBUG] mod_h323.cpp:978 ======>FSH323Connection::OnCreateLogicalChannel ('GSM-06.10{sw}',IsTransmitter) [0x9a7d140] 2010-12-14 12:38:57.647243 [DEBUG] mod_h323.cpp:961 ======>FSH323Connection::CreateRealTimeLogicalChannel [0x9a7d140] 2010-12-14 12:38:57.647243 [DEBUG] mod_h323.cpp:1880 ======>FSH323_ExternalRTPChannel::FSH323_ExternalRTPChannel sessionID = 1 :IsTransmitter addr = 72.51.47.100:56790 [0xb7922880] 2010-12-14 12:38:57.647243 [DEBUG] mod_h323.cpp:1884 ------->capability.GetPayloadType() return = [pt=128] 2010-12-14 12:38:57.647243 [DEBUG] mod_h323.cpp:1885 ------->capability.GetFormatName() return = GSM-06.10{sw} 2010-12-14 12:38:57.647243 [DEBUG] mod_h323.cpp:1897 ------->payloadCode = 3 2010-12-14 12:38:57.647243 [DEBUG] channels.cxx:1056 OnReceivedPDU for channel: T-0 2010-12-14 12:38:57.647243 [DEBUG] mod_h323.h:529 ==============>BaseGSM0610Cap::OnReceivedPDU [0xb79211f0] 2010-12-14 12:38:57.647243 [DEBUG] mod_h323.cpp:2223 ======>FSH323_ExternalRTPChannel::OnReceivedPDU [0xb7922880] 2010-12-14 12:38:57.647243 [DEBUG] mod_h323.cpp:2230 Remote RTP address 200.117.192.17:5062 2010-12-14 12:38:57.647243 [DEBUG] mod_h323.cpp:1357 ======>PFSH323Connection::setRemoteAddress remoteIP = 200.117.192.17 , remotePort = 5062 [0x9a7d140] 2010-12-14 12:38:57.650240 [DEBUG] h323.cxx:1722 Fast start open: { forwardLogicalChannelNumber = 102 forwardLogicalChannelParameters = { dataType = audioData gsmFullRate { audioUnitSize = 33 comfortNoise = false scrambled = fal se } multiplexParameters = h2250LogicalChannelParameters { sessionID = 1 mediaGuaranteedDelivery = false mediaControlChannel = unicastAddress iPAddress { network = 4 octets { c8 75 c0 11 .u.. } tsapIdentifier = 5063 } silenceSuppression = false } } } 2010-12-14 12:38:57.650240 [DEBUG] h323.cxx:4762 CreateLogicalChannel - forward channel 2010-12-14 12:38:57.650240 [DEBUG] h323caps.cxx:3792 FindCapability: audioData 2010-12-14 12:38:57.650240 [DEBUG] mod_h323.h:529 ==============>BaseGSM0610Cap::OnReceivedPDU [0xb79769f8] 2010-12-14 12:38:57.650240 [DEBUG] h323caps.cxx:3833 Found capability: GSM-06.10{sw} <3> 2010-12-14 12:38:57.650240 [DEBUG] mod_h323.h:529 ==============>BaseGSM0610Cap::OnReceivedPDU [0x9b1c240] 2010-12-14 12:38:57.650240 [DEBUG] h323caps.cxx:1543 Capability rx frames reduced from 24 to 1 2010-12-14 12:38:57.650240 [DEBUG] mod_h323.cpp:978 ======>FSH323Connection::OnCreateLogicalChannel ('GSM-06.10{sw}',IsReceiver) [0x9a7d140] 2010-12-14 12:38:57.650240 [DEBUG] mod_h323.cpp:961 ======>FSH323Connection::CreateRealTimeLogicalChannel [0x9a7d140] 2010-12-14 12:38:57.650240 [DEBUG] mod_h323.cpp:1880 ======>FSH323_ExternalRTPChannel::FSH323_ExternalRTPChannel sessionID = 1 :IsReceiver addr = 72.51.47.100:56790 [0xb791e1d8] 2010-12-14 12:38:57.650240 [DEBUG] mod_h323.cpp:1884 ------->capability.GetPayloadType() return = [pt=128] 2010-12-14 12:38:57.650240 [DEBUG] mod_h323.cpp:1885 ------->capability.GetFormatName() return = GSM-06.10{sw} 2010-12-14 12:38:57.650240 [DEBUG] mod_h323.cpp:1897 ------->payloadCode = 3 2010-12-14 12:38:57.650240 [DEBUG] channels.cxx:1056 OnReceivedPDU for channel: R-102 2010-12-14 12:38:57.650240 [DEBUG] mod_h323.h:529 ==============>BaseGSM0610Cap::OnReceivedPDU [0xb79769f8] 2010-12-14 12:38:57.650240 [DEBUG] mod_h323.cpp:2223 ======>FSH323_ExternalRTPChannel::OnReceivedPDU [0xb791e1d8] 2010-12-14 12:38:57.650240 [DEBUG] mod_h323.cpp:2230 Remote RTP address 200.117.192.17:5062 2010-12-14 12:38:57.650240 [DEBUG] mod_h323.cpp:1357 ======>PFSH323Connection::setRemoteAddress remoteIP = 200.117.192.17 , remotePort = 5062 [0x9a7d140] 2010-12-14 12:38:57.650240 [DEBUG] h323.cxx:1722 Fast start open: { forwardLogicalChannelNumber = 1 forwardLogicalChannelParameters = { dataType = nullData <<null>> multiplexParameters = none <<null>> } reverseLogicalChannelParameters = { dataType = audioData nonStandard [Equivalence G.726-16k] { nonStandardIdentifier = h221NonStandard { t35CountryCode = 9 t35Extension = 0 manufacturerCode = 61 } data = 9 octets { 47 2e 37 32 36 2d 31 36 6b G.726-16k } } multiplexParameters = h2250LogicalChannelParameters { sessionID = 1 mediaChannel = unicastAddress iPAddress { network = 4 octets { c8 75 c0 11 .u.. } tsapIdentifier = 5062 } mediaGuaranteedDelivery = false mediaControlChannel = unicastAddress iPAddress { network = 4 octets { c8 75 c0 11 .u.. } tsapIdentifier = 5063 } dynamicRTPPayloadType = 117 } } } 2010-12-14 12:38:57.650240 [DEBUG] h323.cxx:4747 CreateLogicalChannel - reverse channel 2010-12-14 12:38:57.650240 [DEBUG] h323caps.cxx:3792 FindCapability: audioData 2010-12-14 12:38:57.650240 [DEBUG] h323.cxx:4806 CreateLogicalChannel - unknown data type 2010-12-14 12:38:57.650240 [DEBUG] h323.cxx:1722 Fast start open: { forwardLogicalChannelNumber = 103 forwardLogicalChannelParameters = { dataType = audioData nonStandard [Equivalence G.726-16k] { nonStandardIdentifier = h221NonStandard { t35CountryCode = 9 t35Extension = 0 manufacturerCode = 61 } data = 9 octets { 47 2e 37 32 36 2d 31 36 6b G.726-16k } } multiplexParameters = h2250Logical ChannelParameters { sessionID = 1 mediaGuaranteedDelivery = false mediaControlChannel = unicastAddress iPAddress { network = 4 octets { c8 75 c0 11 .u.. Log-Func: Log-Line: 0 User-Data: } tsapIdentifier = 5063 } silenceSuppression = false dynamicRTP2010-12-14 12:38:57.650240 [DEBUG] h323.cxx:4762 CreateLogicalChannel - forward channel 2010-12-14 12:38:57.650240 [DEBUG] h323caps.cxx:3792 FindCapability: audioData 2010-12-14 12:38:57.650240 [DEBUG] h323.cxx:4806 CreateLogicalChannel - unknown data type 2010-12-14 12:38:57.653258 [DEBUG] h323.cxx:1722 Fast start open: { forwardLogicalChannelNumber = 1 forwardLogicalChannelParameters = { dataType = nullData <<null>> multiplexParameters = none <<null>> } reverseLogicalChannelParameters = { dataType = videoData h261VideoCapability { qcifMPI = 1 cifMPI = 1 temporalSpatialTradeOffCapability = false maxBitRate = 19200 stillImageTransmission = false videoBadMBsCap = false } multiplexParameters = h2250LogicalChannelParameters { sessionID = 2 mediaChannel = unicastAddress iPAddress { network = 4 octets { c8 75 c0 11 .u.. } tsapIdentifier = 5064 } mediaGuaranteedDelivery = false mediaControlChannel = unicastAddress iPAddress { network = 4 octets { c8 75 c0 11 .u.. } Log-Func: Log-Line: 0 User-Data: tsapIdentifier2010-12-14 12:38:57.653258 [DEBUG] h323.cxx:4747 CreateLogicalChannel - reverse channel 2010-12-14 12:38:57.653258 [DEBUG] h323caps.cxx:3792 FindCapability: videoData 2010-12-14 12:38:57.653258 [DEBUG] h323.cxx:4806 CreateLogicalChannel - unknown data type 2010-12-14 12:38:57.653258 [DEBUG] h323.cxx:1722 Fast start open: { forwardLogicalChannelNumber = 104 forwardLogicalChannelParameters = { dataType = videoData h261VideoCapability { qcifMPI = 1 cifMPI = 1 temporalSpatialTradeOffC apability = false maxBitRate = 19200 stillImageTransmission = false videoBadMBsCap = false } multiplexParameters = h2250LogicalChannelParameters { sessionID = 2 mediaGuaranteedDelivery = false mediaControlChannel = unicastAddress iPAddress { network = 4 octets { c8 75 c0 11 .u.. } tsapIdentifier = 5065 } silenceSuppression = false } Log-Func: INCOMING DATA [(null)] RECV EVENT Event-Name: SOCKET_DATA Log-Line: 0 User-Data: _undef_ 2010-12-14 12:38:57.653258 [DEBUG] h323.cxx:4762 CreateLogicalChannel - forward channel 2010-12-14 12:38:57.653258 [DEBUG] h323caps.cxx:3792 FindCapability: videoData 2010-12-14 12:38:57.653258 [DEBUG] h323.cxx:4806 CreateLogicalChannel - unknown data type 2010-12-14 12:38:57.653258 [DEBUG] h323.cxx:1722 Fast start open: { forwardLogicalChannelNumber = 1 forwardLogicalChannelParameters = { dataType = nullData <<null>> multiplexParameters = none <<null>> } reverseLogicalChannelParameters = { dataType = videoData h261VideoCapability { cifMPI = 1 temporalSpatialTradeOffCapability = false maxBitRate = 19200 stillImageTransmission = false videoBadMBsCap = false } multiplexParameters = h2250LogicalChannelParameters { sessionID = 2 mediaChannel = unicastAddress iPAddress { network = 4 octets { c8 75 c0 11 .u.. } tsapIdentifier = 506 4 } mediaGuaranteedDelivery = false mediaControlChannel = unicastAddress iPAddress { network = 4 octets { c8 75 c0 11 .u.. } tsapIdentifier = 5 Log-Func: Log-Line: 0 User-DataINCOMING DATA [(null)] RECV EVENT Event-Name: SOCKET_DATA : _undef_ 2010-12-14 12:38:57.653258 [DEBUG] h323.cxx:4747 CreateLogicalChannel - reverse channel 2010-12-14 12:38:57.653258 [DEBUG] h323caps.cxx:3792 FindCapability: videoData 2010-12-14 12:38:57.653258 [DEBUG] h323.cxx:4806 CreateLogicalChannel - unknown data type 2010-12-14 12:38:57.653258 [DEBUG] h323.cxx:1722 Fast start open: { forwardLogicalChannelNumber = 105 forwardLogicalChannelParameters = { dataType = videoData h261VideoCapability { cifMPI = 1 temporalSpatialTradeOffCapability = false maxBitRate = 19200 stillImageTransmission = false videoBadMBsCap = false } multiplexParameters = h2250LogicalChannelParameters { sessionID = 2 mediaGuaranteedDelivery = false mediaControlChan nel = unicastAddress iPAddress { network = 4 octets { c8 75 c0 11 .u.. } tsapIdentifier = 5065 } silenceSuppression = false } } } 2010-12-14 12:38:57.653258 [DEBUG] h323.cxx:4762 CreateLogicalChannel - forward channel 2010-12-14 12:38:57.653258 [DEBUG] h323caps.cxx:3792 FindCapability: videoData 2010-12-14 12:38:57.653258 [DEBUG] h323.cxx:4806 CreateLogicalChannel - unknown data type 2010-12-14 12:38:57.653258 [DEBUG] h323.cxx:1722 Fast start open: { forwardLogicalChannelNumber = 1 forwardLogicalChannelParameters = { dataType = nullData <<null>> multiplexParameters = none <<null>> } reverseLogicalChannelParameters = { dataType = videoData h261VideoCapability { qcifMPI = 1 temporalSpatialTradeOffCapability = false maxBitRate = 19200 stillImageTransmission = false videoBadMBsCap = false } multiplexParameter s = h2250LogicalChannelParameters { sessionID = 2 mediaChannel = unicastAddress iPAddress { network = 4 octets { c8 75 c0 11 .u.. } tsapIdentifier = 50 64 } mediaGuaranteedDelivery = false mediaControlChannel = unicastAddress iPAddress { network = 4 octets { c8 75 c0 11 .u.. } tsapIdentifier = Log-Func: Log-Line: 0 User-Data:INCOMING DATA [(null)] RECV EVENT Event-Name: SOCKET_DATA 2010-12-14 12:38:57.653258 [DEBUG] h323.cxx:4747 CreateLogicalChannel - reverse channel 2010-12-14 12:38:57.653258 [DEBUG] h323caps.cxx:3792 FindCapability: videoData 2010-12-14 12:38:57.653258 [DEBUG] h323.cxx:4806 CreateLogicalChannel - unknown data type 2010-12-14 12:38:57.656259 [DEBUG] h323.cxx:1722 Fast start open: { forwardLogicalChannelNumber = 106 forwardLogicalChannelParameters = { dataType = videoData h261VideoCapability { qcifMPI = 1 temporalSpatialTradeOffCapability = false maxBitRate = 19200 stillImageTransmission = false videoBadMBsCap = false } multiplexParameters = h2250LogicalChannelParameters { sessionID = 2 mediaGuaranteedDelivery = false mediaControlCha nnel = unicastAddress iPAddress { network = 4 octets { c8 75 c0 11 .u.. } tsapIdentifier = 5065 } silenceSuppression = false } } } 2010-12-14 12:38:57.656259 [DEBUG] h323.cxx:4762 CreateLogicalChannel - forward channel 2010-12-14 12:38:57.656259 [DEBUG] h323caps.cxx:3792 FindCapability: videoData 2010-12-14 12:38:57.656259 [DEBUG] h323.cxx:4806 CreateLogicalChannel - unknown data type 2010-12-14 12:38:57.656259 [DEBUG] h323.cxx:1736 Opened 4 fast start channels 2010-12-14 12:38:57.656259 [DEBUG] mod_h323.cpp:634 ======>FSH323EndPoint::OnSetGatewayPrefixes [0x9a76490] 2010-12-14 12:38:57.656259 [DEBUG] mod_h323.cpp:857 ======>FSH323Connection::OnAnswerCall caller = gustavo [200.117.192.17] [0x9a7d140] 2010-12-14 12:38:57.656259 [DEBUG] mod_h323.cpp:877 address index = 0 value = 2315435142806332010-12-14 12:38:57.656259 [NOTICE] mod_h323.cpp:884 Called number or alias = 231543514280633 2010-12-14 12:38:57.656259 [DEBUG] mod_h323.cpp:925 Created switch caller profile: username = dialplan = XML caller_id_name = gustavo [200.117.192.17] caller_id_number = 0000000000 network_addr = 200.117.192.17 source = h323 context = default destination_number = 231543514280633 2010-12-14 12:38:57.656259 [NOTICE] switch_channel.c:784 New Channel h323/231543514280633 [c79e219b-ecd5-4481-b08d-7de04a99be6c] 2010-12-14 12:38:57.656259 [DEBUG] mod_h323.cpp:949 (h323/231543514280633) State Change CS_NEW -> CS_INIT 2010-12-14 12:38:57.656259 [DEBUG] mod_h323.cpp:1584 ======>FSH323Connection::state_change [0x9a7d140] 2010-12-14 12:38:57.656259 [DEBUG] mod_h323.cpp:1585 State changed on connection [0x9a7d140] 2010-12-14 12:38:57.656259 [DEBUG] switch_core_session.c:1083 Send signal h323/231543514280633 [BREAK] 2010-12-14 12:38:57.656259 [DEBUG] mod_h323.cpp:1382 ======>FSH323Connection::kill_channel sig = 3 [0x9a7d140] 2010-12-14 12:38:57.656259 [DEBUG] h323.cxx:2505 Answering call: AnswerCallDeferred 2010-12-14 12:38:57.656259 [DEBUG] h323.cxx:4369 InternalEstablishedConnectionCheck: connectionState=AwaitingLocalAnswer fastStartState=FastStartResponse 2010-12-14 12:38:57.656259 [DEBUG] mod_h323.cpp:1018 ---------> after FSH323Connection::OnReceivedSignalSetup connectionState = AwaitingLocalAnswer [0x9a7d140] 2010-12-14 12:38:57.656259 [DEBUG] mod_h323.cpp:634 ======>FSH323EndPoint::OnSetGatewayPrefixes [0x9a76490] 2010-12-14 12:38:57.656259 [DEBUG] mod_h323.cpp:1022 ---------> after callProceedingPDU.BuildCallProceeding connectionState = AwaitingLocalAnswer [0x9a7d140] 2010-12-14 12:38:57.656259 [DEBUG] h323.cxx:4451 Default OnSelectLogicalChannels, FastStartResponse 2010-12-14 12:38:57.656259 [DEBUG] mod_h323.cpp:1915 ------------->h323_mutex_lock 2010-12-14 12:38:57.656259 [DEBUG] mod_h323.cpp:1917 ======>FSH323_ExternalRTPChannel::Start() [0xb773dc88] 2010-12-14 12:38:57.656259 [DEBUG] mod_h323.cpp:1924 ------------->m_sessionID = 1 m_active_sessionID = 0 2010-12-14 12:38:57.656259 [DEBUG] mod_h323.cpp:971 ======>FSH323Connection::OnStartLogicalChannel chennel = 0xb773dc88 [0x9a7d140] 2010-12-14 12:38:57.656259 [DEBUG] mod_h323.cpp:972 ======>FSH323Connection::OnStartLogicalChannel connectionState = AwaitingLocalAnswer [0x9a7d140] 2010-12-14 12:38:57.656259 [DEBUG] mod_h323.cpp:1973 ------------------------->H323Capability::e_Audio 2010-12-14 12:38:57.656259 [DEBUG] mod_h323.cpp:1992 ------------------->GetFrameSize() return = 634 2010-12-14 12:38:57.656259 [DEBUG] mod_h323.cpp:1993 ------------------->GetFrameTime() return = 3077839696 2010-12-14 12:38:57.656259 [DEBUG] mod_h323.cpp:1994 ------------------->payloadCode = 8 2010-12-14 12:38:57.656259 [DEBUG] mod_h323.cpp:1995 ------------------->m_codec_ms return = 20 2010-12-14 12:38:57.656259 [DEBUG] mod_h323.cpp:1996 ------------------->m_capability->GetFormatName() return = G.711-ALaw-64k 2010-12-14 12:38:57.656259 [DEBUG] mod_h323.cpp:1997 ------------------->GetH245CodecName() return = PCMA 2010-12-14 12:38:57.656259 [DEBUG] mod_h323.cpp:2036 h323/231543514280633 initialise write codec Audio for connection [0x9a9d4a0] 2010-12-14 12:38:57.659246 [DEBUG] mod_h323.cpp:2091 Set write Audio codec to G.711-ALaw-64k for connection [0xb773dc88] 2010-12-14 12:38:57.659246 [DEBUG] mod_h323.cpp:2097 ------------------->tech_pvt->rtp_session = [(nil)] 2010-12-14 12:38:57.659246 [DEBUG] mod_h323.cpp:2098 ------------------->samples_per_packet = 160 2010-12-14 12:38:57.659246 [DEBUG] mod_h323.cpp:2099 ------------------->actual_samples_per_second = 8000 2010-12-14 12:38:57.659246 [DEBUG] mod_h323.cpp:2134 ------------------->timer_name = soft 2010-12-14 12:38:57.659246 [DEBUG] switch_rtp.c:1423 Starting timer [soft] 160 bytes per 20ms 2010-12-14 12:38:57.659246 [DEBUG] switch_core_state_machine.c:320 (h323/231543514280633) Running State Change CS_INIT 2010-12-14 12:38:57.659246 [DEBUG] switch_core_state_machine.c:356 (h323/231543514280633) State INIT 2010-12-14 12:38:57.659246 [DEBUG] mod_h323.cpp:1592 ======>FSH323Connection::on_init [0x9a7d140] 2010-12-14 12:38:57.659246 [DEBUG] mod_h323.cpp:1599 Started routing for connection [0x9a7d140] 2010-12-14 12:38:57.659246 [DEBUG] mod_h323.cpp:1600 (h323/231543514280633) State Change CS_INIT -> CS_ROUTING 2010-12-14 12:38:57.659246 [DEBUG] mod_h323.cpp:1584 ======>FSH323Connection::state_change [0x9a7d140] 2010-12-14 12:38:57.659246 [DEBUG] mod_h323.cpp:1585 State changed on connection [0x9a7d140] 2010-12-14 12:38:57.659246 [DEBUG] switch_core_session.c:1083 Send signal h323/231543514280633 [BREAK] 2010-12-14 12:38:57.659246 [DEBUG] mod_h323.cpp:1382 ======>FSH323Connection::kill_channel sig = 3 [0x9a7d140] 2010-12-14 12:38:57.659246 [DEBUG] switch_core_state_machine.c:356 (h323/231543514280633) State INIT going to sleep 2010-12-14 12:38:57.659246 [DEBUG] switch_core_state_machine.c:320 (h323/231543514280633) Running State Change CS_ROUTING 2010-12-14 12:38:57.659246 [DEBUG] switch_channel.c:1615 (h323/231543514280633) Callstate Change DOWN -> RINGING 2010-12-14 12:38:57.659246 [DEBUG] switch_core_state_machine.c:359 (h323/231543514280633) State ROUTING 2010-12-14 12:38:57.659246 [DEBUG] mod_h323.cpp:1375 ======>FSH323Connection::on_routing [0x9a7d140] 2010-12-14 12:38:57.659246 [DEBUG] switch_core_state_machine.c:77 h323/231543514280633 Standard ROUTING 2010-12-14 12:38:57.659246 [INFO] mod_dialplan_xml.c:331 Processing gustavo [200.117.192.17] <0000000000>->231543514280633 in context default 2010-12-14 12:38:57.662268 [DEBUG] mod_h323.cpp:2146 ------------------------->tech_pvt->rtp_session = 0xb794bf18 2010-12-14 12:38:57.662268 [DEBUG] mod_h323.cpp:2169 ------------->External RTP address 200.117.192.17:5062 2010-12-14 12:38:57.662268 [DEBUG] mod_h323.cpp:2215 ------------->h323_mutex_unlock 2010-12-14 12:38:57.662268 [DEBUG] mod_h323.cpp:1915 ------------->h323_mutex_lock 2010-12-14 12:38:57.662268 [DEBUG] mod_h323.cpp:1917 ======>FSH323_ExternalRTPChannel::Start() [0xb773b9a0] 2010-12-14 12:38:57.662268 [DEBUG] mod_h323.cpp:1924 ------------->m_sessionID = 1 m_active_sessionID = 1 2010-12-14 12:38:57.662268 [DEBUG] mod_h323.cpp:971 ======>FSH323Connection::OnStartLogicalChannel chennel = 0xb773b9a0 [0x9a7d140] 2010-12-14 12:38:57.662268 [DEBUG] mod_h323.cpp:972 ======>FSH323Connection::OnStartLogicalChannel connectionState = AwaitingLocalAnswer [0x9a7d140] 2010-12-14 12:38:57.662268 [DEBUG] mod_h323.cpp:1973 ------------------------->H323Capability::e_Audio 2010-12-14 12:38:57.662268 [DEBUG] mod_h323.cpp:1992 ------------------->GetFrameSize() return = 634 2010-12-14 12:38:57.662268 [DEBUG] mod_h323.cpp:1993 ------------------->GetFrameTime() return = 3077839696 2010-12-14 12:38:57.662268 [DEBUG] mod_h323.cpp:1994 ------------------->payloadCode = 8 2010-12-14 12:38:57.662268 [DEBUG] mod_h323.cpp:1995 ------------------->m_codec_ms return = 20 2010-12-14 12:38:57.662268 [DEBUG] mod_h323.cpp:1996 ------------------->m_capability->GetFormatName() return = G.711-ALaw-64k 2010-12-14 12:38:57.662268 [DEBUG] mod_h323.cpp:1997 ------------------->GetH245CodecName() return = PCMA 2010-12-14 12:38:57.662268 [DEBUG] mod_h323.cpp:2036 h323/231543514280633 initialise read codec Audio for connection [0x9a9d488] 2010-12-14 12:38:57.662268 [DEBUG] mod_h323.cpp:2091 Set read Audio codec to G.711-ALaw-64k for connection [0xb773b9a0] 2010-12-14 12:38:57.662268 [DEBUG] mod_h323.cpp:2097 ------------------->tech_pvt->rtp_session = [0xb794bf18] 2010-12-14 12:38:57.662268 [DEBUG] mod_h323.cpp:2098 ------------------->samples_per_packet = 160 2010-12-14 12:38:57.662268 [DEBUG] mod_h323.cpp:2099 ------------------->actual_samples_per_second = 8000 2010-12-14 12:38:57.662268 [DEBUG] mod_h323.cpp:2103 ------------------->old remot port = 5062 new remote port = 5062 2010-12-14 12:38:57.662268 [DEBUG] mod_h323.cpp:2169 ------------->External RTP address 200.117.192.17:5062 2010-12-14 12:38:57.662268 [DEBUG] mod_h323.cpp:2172 ------------->h323_io_mutex_lock 2010-12-14 12:38:57.662268 [DEBUG] mod_h323.cpp:2176 ------------->h323_io_mutex_unlock 2010-12-14 12:38:57.662268 [DEBUG] mod_h323.cpp:2215 ------------->h323_mutex_unlock 2010-12-14 12:38:57.662268 [DEBUG] mod_h323.cpp:1903 ======>FSH323_ExternalRTPChannel::~FSH323_ExternalRTPChannel IsTransmitter [0xb7922880] 2010-12-14 12:38:57.662268 [DEBUG] mod_h323.cpp:1906 ------------->switch_core_session_unlock_codec_read [0x9bfe898] 2010-12-14 12:38:57.662268 [DEBUG] mod_h323.cpp:1908 ------------->switch_core_session_unlock_codec_write [0x9bfe898] 2010-12-14 12:38:57.662268 [DEBUG] h323.cxx:5105 Bandwidth request: -0.0kb/s, available: 9872.0kb/s 2010-12-14 12:38:57.662268 [DEBUG] mod_h323.cpp:1903 ======>FSH323_ExternalRTPChannel::~FSH323_ExternalRTPChannel IsReceiver [0xb791e1d8] 2010-12-14 12:38:57.662268 [DEBUG] mod_h323.cpp:1906 ------------->switch_core_session_unlock_codec_read [0x9bfe898] 2010-12-14 12:38:57.662268 [DEBUG] mod_h323.cpp:1908 ------------->switch_core_session_unlock_codec_write [0x9bfe898] 2010-12-14 12:38:57.662268 [DEBUG] h323.cxx:5105 Bandwidth request: -0.0kb/s, available: 9872.0kb/s 2010-12-14 12:38:57.662268 [DEBUG] h323.cxx:3094 Accepting fastStart for 2 channels 2010-12-14 12:38:57.665259 [DEBUG] channels.cxx:989 OnSendingPDU 2010-12-14 12:38:57.665259 [DEBUG] h323.cxx:1652 Build fastStart: { forwardLogicalChannelNumber = 101 forwardLogicalChannelParameters = { dataType = nullData <<null>> multiplexParameters = none <<null>> } reverseLogicalChannelParameters = { dataType = audioData g711Alaw64k 20 multiplexParameters = h2250LogicalChannelParameters { sessionID = 1 mediaGuaranteedDelivery = false mediaControlChannel = unicastAddress iPAddress { network = 4 oc tets { 48 33 2f 64 H3/d } tsapIdentifier = 56791 } silenceSuppression = false } } } 2010-12-14 12:38:57.665259 [DEBUG] h323.cxx:1657 Built fastStart for G.711-ALaw-64k <1> 2010-12-14 12:38:57.665259 [DEBUG] channels.cxx:989 OnSendingPDU 2010-12-14 12:38:57.665259 [DEBUG] h323.cxx:1652 Build fastStart: { forwardLogicalChannelNumber = 101 forwardLogicalChannelParameters = { dataType = audioData g711Alaw64k 20 multiplexParameters = h2250LogicalChannelParameters { sessionID = 1 mediaChannel = unicastAddress iPAddress { network = 4 octets { 48 33 2f 64 H3/d } tsapIdentifier = 56790 } mediaGuaranteedDelivery = false mediaControlChannel = unicastAddress iPAddress { network = 4 octets { 48 33 2f 64 H3/d } tsapIdentifier = 56791 } silenceSuppression = false Log-Func: Log-LINCOMING DATA [(null)] RECV EVENT Event-Name: SOCKET_DATA ine: 0 User-Data: _undef_ 2010-12-14 12:38:57.665259 [DEBUG] h323.cxx:1657 Built fastStart for G.711-ALaw-64k <1> 2010-12-14 12:38:57.668246 [DEBUG] h323pdu.cxx:618 Sending PDU [ip $72.51.47.100:1720/ip$200.117.192.17:20570] : { q931pdu = { protocolDiscriminator = 8 callReference = 4293 from = destination messageType = CallProceeding IE: Display = (....=. 46 72 65 65 53 57 49 54 43 48 20 6d 6f 64 5f 68 FreeSWITCH m od_h ... } } h225pdu = { h323_uu_pdu = { h323_message_body = callProceeding { protocolIdentifier = 0.0.8.2250.0.6 destinationInfo = { vendor = { vendor = { t35CountryCode = 9 t35Extension = 0 manufacturerCode = 61 } productId = 21 octets { 46 72 65 65 53 57 49 54 43 48 20 6d 6f 64 5f 68 FreeSWITCH mod_h (H323p 6c 75 73 20 76 31 2e 32 32 2e 30 29 00 00 lus v1.22.0).. } } gateway = { } mc = false undefinedNode = false } callIdentifier = { guid = 16 octets { a2 4e 5 8 c9 ec 05 e0 11 9e 19 00 1e 64 1f 33 fe .NX.........d.3. } } fastStart = 2 entries { [0]= 23 octets { 40 00 64 06 04 01 00 4c 20 13 80 0b 0d 00 01 00 @.d....L ....... 48 33 2f 64 dd d7 00 H3/d... } [1]= 26 octets { 00 00 64 0c 20 13 80 12 1d 00 01 00 48 33 2f 64 ..d. .......H3/d dd d6 00 48 33 2f 64 dd d7 00 ... H3/d... } } multipleCalls = false maintainConnection = false } h245Tunneling = true } } } 2010-12-14 12:38:57.668246 [DEBUG] h323pdu.cxx:618 Receiving PDU [(noaddr)/(noaddr)] : request terminalCapabilitySet { sequenceNumber = 1 protocolIdentifier = 0.0.8.245.0.13 multiplexCapability = h2250Capability { maximumAudioDelayJitter = 500 r eceiveMultipointCapability = { multicastCapability = false multiUniCastConference = false mediaDistributionCapability = 1 entries { [0]={ centralizedControl = false distributedControl = false centralizedAudio = false distributedAudio = false centralizedVideo = false distributedVideo = false } } } transmitMultipointCapability = { multicastCapability = f alse multiUniCastConference = false mediaDistributionCapability = 1 entries { [0]={ centralizedControl = false distributedControl = false centralizedAudio = false distributedA udio = false centralizedVideo = false distributedVideo = false } } } receiveAndTransmitMultipointCapability = { multicastCapability = false multiUniCastConference = false mediaDistributionCapability = 1 entries { [0]={ centralizedControl = false distributedControl = false centralizedAudio = false distributedAudio = false centralizedVideo = false distributedVideo = false } } } mcCapability = { centralizedConferenceMC = false decentralizedConferenceMC = false } rtcpVideoControlCapability = false mediaPacketizationCapabi lity = { h261aVideoPacketization = false } logicalChannelSwitchingCapability = false t120DynamicPortCapability = true } capabilityTable = 10 entries { [0]={ capabilityTableEntryNumber = 1 capab ility = receiveAudioCapability g711Alaw64k 240 } [1]={ capabilityTableEntryNumber = 2 capability = receiveAudioCapability gsmFullRate { audioUnitSize = 231 comfortNoise = false scrambled = fa lse } } [2]={ capabilityTableEntryNumber = 3 capability = receiveAudioCapability nonStandard [Equivalence G.726-16k] { nonStandardIdentifier = h221NonStandard { t35CountryCode = 9 t35Extension = 0 manufacturerCode = 61 } data = 9 octets { 47 2e 37 32 36 2d 31 36 6b G.726-16k } } } [3]={ capabilityTableEntryNumber = 4 capability = receiveVideoCapability h261VideoCapability { qcifMPI = 1 cifMPI = 1 temporalSpatialTradeOffCapability = false maxBitRate = 19200 stillImageTransmission = false videoBadMB sCap = false } } [4]={ capabilityTableEntryNumber = 5 capability = receiveVideoCapability h261VideoCapability { cifMPI = 1 temporalSpatialTradeOffCapability = false maxBitRate = 19200 stillImageTransmission = false videoBadMBsCap = false } } [5]={ capabilityTableEntryNumber = 6 capability = receiveVideoCapability h261VideoCapability { qcifMPI = 1 tempora lSpatialTradeOffCapability = false maxBitRate = 19200 stillImageTransmission = false videoBadMBsCap = false } } [6]={ capabilityTableEntryNumber = 7 capability = receiveUserInputCapab ility hookflash <<null>> } [7]={ capabilityTableEntryNumber = 8 capability = receiveUserInputCapability basicString <<null>> } [8]={ capabilityTableEntryNumber = 9 capability = receiveUserInput Capability dtmf <<null>> } [9]={ capabilityTableEntryNumber = 10 capability = receiveRTPAudioTelephonyEventCapability { dynamicRTPPayloadType = 101 audioTelephoneEvent = "0-16" } } } capabilityDescriptors = 1 entries { [0]={ capabilityDescriptorNumber = 1 simultaneousCapabilities = 4 entries { [0]=3 entries { [0]=1 [1]=2 [2]=3 } [1]=3 ent ries { [0]=4 [1]=5 [2]=6 } [2]=1 entries { [0]=7 } [3]=3 entries { [0]=8 [1]=9 [2]=10 } } } } } 2010-12-14 12:38:57.668246 [DEBUG] h323.cxx:3611 Set protocol version to 13 2010-12-14 12:38:57.668246 [DEBUG] h323neg.cxx:631 Received TerminalCapabilitySet: state=Idle pduSeq=1 inSeq=4294967295 Dialplan: h323/231543514280633 parsing [default->external] continue=false Dialplan: h323/231543514280633 Regex (PASS) [external] destination_number(231543514280633) =~ /^231543514280633/ break=on-false Dialplan: h323/231543514280633 Action set(continue_on_fail=true) Dialplan: h323/231543514280633 Action set(hangup_after_bridge=true) Dialplan: h323/231543514280633 Action set(progress_timeout=15) Dialplan: h323/231543514280633 Action set(proxy_media=true) Dialplan: h323/231543514280633 Action set(bypass_media=false) Dialplan: h323/231543514280633 Action set(absolute_codec_string=GSM@40i,G726-16,PCMA) Dialplan: h323/231543514280633 Action bridge(sofia/external/21543514280633(a)200.35.145.149) 2010-12-14 12:38:57.671243 [DEBUG] switch_core_state_machine.c:119 (h323/231543514280633) State Change CS_ROUTING -> CS_EXECUTE 2010-12-14 12:38:57.671243 [DEBUG] mod_h323.cpp:1584 ======>FSH323Connection::state_change [0x9a7d140] 2010-12-14 12:38:57.671243 [DEBUG] h323caps.cxx:3598 Added capability: G.711-ALaw-64k <1> 2010-12-14 12:38:57.671243 [DEBUG] mod_h323.cpp:1585 State changed on connection [0x9a7d140] 2010-12-14 12:38:57.671243 [DEBUG] h323caps.cxx:3598 Added capability: G.711-uLaw-64k <2> 2010-12-14 12:38:57.671243 [DEBUG] switch_core_session.c:1083 Send signal h323/231543514280633 [BREAK] 2010-12-14 12:38:57.671243 [DEBUG] mod_h323.cpp:1382 ======>FSH323Connection::kill_channel sig = 3 [0x9a7d140] 2010-12-14 12:38:57.671243 [DEBUG] h323caps.cxx:3598 Added capability: GSM-06.10{sw} <3> 2010-12-14 12:38:57.671243 [DEBUG] switch_core_state_machine.c:359 (h323/231543514280633) State ROUTING going to sleep 2010-12-14 12:38:57.671243 [DEBUG] h323caps.cxx:3598 Added capability: G.729A/B{sw} <4> 2010-12-14 12:38:57.671243 [DEBUG] switch_core_state_machine.c:320 (h323/231543514280633) Running State Change CS_EXECUTE 2010-12-14 12:38:57.671243 [DEBUG] h323caps.cxx:3598 Added capability: G.729A{sw} <5> 2010-12-14 12:38:57.671243 [DEBUG] h323caps.cxx:3598 Added capability: G.729B{sw} <6> 2010-12-14 12:38:57.671243 [DEBUG] h323caps.cxx:3598 Added capability: G.729{sw} <7> 2010-12-14 12:38:57.671243 [DEBUG] switch_core_state_machine.c:366 (h323/231543514280633) State EXECUTE 2010-12-14 12:38:57.671243 [DEBUG] mod_h323.cpp:1368 ======>FSH323Connection::on_execute [0x9a7d140] 2010-12-14 12:38:57.671243 [DEBUG] h323caps.cxx:3598 Added capability: T.38-IFP-PRE <8> 2010-12-14 12:38:57.671243 [DEBUG] switch_core_state_machine.c:157 h323/231543514280633 Standard EXECUTE 2010-12-14 12:38:57.671243 [DEBUG] h323caps.cxx:3598 Added capability: UserInput/hookflash <9> EXECUTE h323/231543514280633 set(continue_on_fail=true) 2010-12-14 12:38:57.671243 [DEBUG] mod_h323.cpp:1460 ======>FSH323Connection::receive_message MSG = 26 2010-12-14 12:38:57.671243 [DEBUG] mod_h323.cpp:1567 Received message id = 26 [0x9a7d140] 2010-12-14 12:38:57.671243 [DEBUG] mod_dptools.c:1028 h323/231543514280633 SET [continue_on_fail]=[true] 2010-12-14 12:38:57.671243 [DEBUG] mod_h323.cpp:1460 ======>FSH323Connection::receive_message MSG = 27 2010-12-14 12:38:57.671243 [DEBUG] mod_h323.cpp:1567 Received message id = 27 [0x9a7d140] EXECUTE h323/231543514280633 set(hangup_after_bridge=true) 2010-12-14 12:38:57.671243 [DEBUG] mod_h323.cpp:1460 ======>FSH323Connection::receive_message MSG = 26 2010-12-14 12:38:57.671243 [DEBUG] mod_h323.cpp:1567 Received message id = 26 [0x9a7d140] 2010-12-14 12:38:57.671243 [DEBUG] mod_dptools.c:1028 h323/231543514280633 SET [hangup_after_bridge]=[true] 2010-12-14 12:38:57.671243 [DEBUG] mod_h323.cpp:1460 ======>FSH323Connection::receive_message MSG = 27 2010-12-14 12:38:57.671243 [DEBUG] mod_h323.cpp:1567 Received message id = 27 [0x9a7d140] EXECUTE h323/231543514280633 set(progress_timeout=15) 2010-12-14 12:38:57.671243 [DEBUG] h323caps.cxx:3598 Added capability: UserInput/basicString <10> 2010-12-14 12:38:57.671243 [DEBUG] mod_h323.cpp:1460 ======>FSH323Connection::receive_message MSG = 26 2010-12-14 12:38:57.671243 [DEBUG] mod_h323.cpp:1567 Received message id = 26 [0x9a7d140] 2010-12-14 12:38:57.671243 [DEBUG] mod_dptools.c:1028 h323/231543514280633 SET [progress_timeout]=[15] 2010-12-14 12:38:57.671243 [DEBUG] h323caps.cxx:3598 Added capability: UserInput/dtmf <11> 2010-12-14 12:38:57.671243 [DEBUG] h323caps.cxx:3598 Added capability: UserInput/RFC2833 <12> 2010-12-14 12:38:57.671243 [DEBUG] h323caps.cxx:3598 Added capability: UserInput/Navigation <13> 2010-12-14 12:38:57.671243 [DEBUG] h323caps.cxx:3598 Added capability: UserInput/Softkey <14> 2010-12-14 12:38:57.671243 [DEBUG] mod_h323.cpp:1460 ======>FSH323Connection::receive_message MSG = 27 2010-12-14 12:38:57.671243 [DEBUG] mod_h323.cpp:1567 Received message id = 27 [0x9a7d140] EXECUTE h323/231543514280633 set(proxy_media=true) 2010-12-14 12:38:57.671243 [DEBUG] h323caps.cxx:3598 Added capability: UserInput/PointDevice <15> 2010-12-14 12:38:57.671243 [DEBUG] h323caps.cxx:3598 Added capability: UserInput/Modal <16> 2010-12-14 12:38:57.671243 [DEBUG] h323caps.cxx:3727 FindCapability: receiveAudioCapability 2010-12-14 12:38:57.671243 [DEBUG] h323caps.cxx:4002 FindCapability: Audio subtype=1 2010-12-14 12:38:57.671243 [DEBUG] mod_h323.cpp:1460 ======>FSH323Connection::receive_message MSG = 26 2010-12-14 12:38:57.671243 [DEBUG] mod_h323.cpp:1567 Received message id = 26 [0x9a7d140] 2010-12-14 12:38:57.671243 [DEBUG] mod_dptools.c:1028 h323/231543514280633 SET [proxy_media]=[true] 2010-12-14 12:38:57.671243 [DEBUG] h323caps.cxx:4009 Found capability: G.711-ALaw-64k <1> 2010-12-14 12:38:57.671243 [DEBUG] h323caps.cxx:1523 Capability tx frames left at 20 as remote allows 240 2010-12-14 12:38:57.671243 [DEBUG] h323caps.cxx:3727 FindCapability: receiveAudioCapability 2010-12-14 12:38:57.674242 [DEBUG] mod_h323.cpp:1460 ======>FSH323Connection::receive_message MSG = 27 2010-12-14 12:38:57.674242 [DEBUG] mod_h323.cpp:1567 Received message id = 27 [0x9a7d140] EXECUTE h323/231543514280633 set(bypass_media=false) 2010-12-14 12:38:57.674242 [DEBUG] mod_h323.cpp:1460 ======>FSH323Connection::receive_message MSG = 26 2010-12-14 12:38:57.674242 [DEBUG] mod_h323.cpp:1567 Received message id = 26 [0x9a7d140] 2010-12-14 12:38:57.674242 [DEBUG] mod_dptools.c:1028 h323/231543514280633 SET [bypass_media]=[false] 2010-12-14 12:38:57.674242 [DEBUG] mod_h323.cpp:1460 ======>FSH323Connection::receive_message MSG = 27 2010-12-14 12:38:57.674242 [DEBUG] mod_h323.cpp:1567 Received message id = 27 [0x9a7d140] EXECUTE h323/231543514280633 set(absolute_codec_string=GSM@40i,G726-16,PCMA) 2010-12-14 12:38:57.674242 [DEBUG] h323caps.cxx:4002 FindCapability: Audio subtype=17 2010-12-14 12:38:57.674242 [DEBUG] h323caps.cxx:4009 Found capability: GSM-06.10{sw} <3> 2010-12-14 12:38:57.674242 [DEBUG] mod_h323.h:529 ==============>BaseGSM0610Cap::OnReceivedPDU [0xb7948a88] 2010-12-14 12:38:57.674242 [DEBUG] h323caps.cxx:1523 Capability tx frames left at 1 as remote allows 7 2010-12-14 12:38:57.674242 [DEBUG] mod_h323.cpp:1460 ======>FSH323Connection::receive_message MSG = 26 2010-12-14 12:38:57.674242 [DEBUG] mod_h323.cpp:1567 Received message id = 26 [0x9a7d140] 2010-12-14 12:38:57.674242 [DEBUG] mod_dptools.c:1028 h323/231543514280633 SET [absolute_codec_string]=[GSM@40i,G726-16,PCMA] 2010-12-14 12:38:57.674242 [DEBUG] mod_h323.cpp:1460 ======>FSH323Connection::receive_message MSG = 27 2010-12-14 12:38:57.674242 [DEBUG] mod_h323.cpp:1567 Received message id = 27 [0x9a7d140] EXECUTE h323/231543514280633 bridge(sofia/external/21543514280633(a)200.35.145.149) 2010-12-14 12:38:57.674242 [DEBUG] mod_h323.cpp:1460 ======>FSH323Connection::receive_message MSG = 26 2010-12-14 12:38:57.674242 [DEBUG] mod_h323.cpp:1567 Received message id = 26 [0x9a7d140] 2010-12-14 12:38:57.674242 [DEBUG] h323caps.cxx:3727 FindCapability: receiveAudioCapability 2010-12-14 12:38:57.674242 [DEBUG] h323caps.cxx:3984 FindCapability: Audio nonStandard 2010-12-14 12:38:57.674242 [DEBUG] h323caps.cxx:3727 FindCapability: receiveVideoCapability 2010-12-14 12:38:57.674242 [DEBUG] h323caps.cxx:4002 FindCapability: Video subtype=1 2010-12-14 12:38:57.674242 [DEBUG] h323caps.cxx:3727 FindCapability: receiveVideoCapability 2010-12-14 12:38:57.674242 [DEBUG] h323caps.cxx:4002 FindCapability: Video subtype=1 2010-12-14 12:38:57.674242 [DEBUG] h323caps.cxx:3727 FindCapability: receiveVideoCapability 2010-12-14 12:38:57.674242 [DEBUG] h323caps.cxx:4002 FindCapability: Video subtype=1 2010-12-14 12:38:57.674242 [DEBUG] h323caps.cxx:3727 FindCapability: receiveUserInputCapability 2010-12-14 12:38:57.674242 [DEBUG] h323caps.cxx:4002 FindCapability: UserInput subtype=5 2010-12-14 12:38:57.674242 [NOTICE] switch_channel.c:784 New Channel sofia/external/21543514280633(a)200.35.145.149 [de3adda4-e34b-4ec0-99ca-b3ec50a2a267] 2010-12-14 12:38:57.674242 [DEBUG] h323caps.cxx:4009 Found capability: UserInput/hookflash <9> 2010-12-14 12:38:57.674242 [DEBUG] h323caps.cxx:3727 FindCapability: receiveUserInputCapability 2010-12-14 12:38:57.674242 [DEBUG] h323caps.cxx:4002 FindCapability: UserInput subtype=1 2010-12-14 12:38:57.674242 [DEBUG] mod_sofia.c:3995 (sofia/external/21543514280633(a)200.35.145.149) State Change CS_NEW -> CS_INIT 2010-12-14 12:38:57.674242 [DEBUG] switch_core_session.c:1083 Send signal sofia/external/21543514280633(a)200.35.145.149 [BREAK] 2010-12-14 12:38:57.674242 [DEBUG] h323caps.cxx:4009 Found capability: UserInput/basicString <10> 2010-12-14 12:38:57.674242 [DEBUG] h323caps.cxx:3727 FindCapability: receiveUserInputCapability 2010-12-14 12:38:57.674242 [DEBUG] h323caps.cxx:4002 FindCapability: UserInput subtype=4 2010-12-14 12:38:57.674242 [DEBUG] h323caps.cxx:4009 Found capability: UserInput/dtmf <11> 2010-12-14 12:38:57.674242 [DEBUG] h323caps.cxx:3727 FindCapability: receiveRTPAudioTelephonyEventCapability 2010-12-14 12:38:57.674242 [DEBUG] h323caps.cxx:4002 FindCapability: UserInput subtype=10000 2010-12-14 12:38:57.674242 [DEBUG] h323caps.cxx:4009 Found capability: UserInput/RFC2833 <12> 2010-12-14 12:38:57.677302 [DEBUG] switch_core_state_machine.c:320 (sofia/external/21543514280633(a)200.35.145.149) Running State Change CS_INIT 2010-12-14 12:38:57.677302 [DEBUG] switch_core_state_machine.c:356 (sofia/external/21543514280633(a)200.35.145.149) State INIT 2010-12-14 12:38:57.677302 [DEBUG] mod_sofia.c:86 sofia/external/21543514280633(a)200.35.145.149 SOFIA INIT 2010-12-14 12:38:57.677302 [DEBUG] mod_h323.cpp:1103 ======>FSH323Connection::OnReceivedCapabilitySet [0x9a7d140] 2010-12-14 12:38:57.677302 [DEBUG] h323caps.cxx:3712 FindCapability: G.711-ALaw-64k <1> 2010-12-14 12:38:57.677302 [DEBUG] h323caps.cxx:3598 Added capability: G.711-ALaw-64k <1> 2010-12-14 12:38:57.677302 [DEBUG] h323caps.cxx:3712 FindCapability: GSM-06.10{sw} <2> 2010-12-14 12:38:57.677302 [DEBUG] h323caps.cxx:3598 Added capability: GSM-06.10{sw} <2> 2010-12-14 12:38:57.677302 [DEBUG] h323caps.cxx:3712 FindCapability: UserInput/hookflash <7> 2010-12-14 12:38:57.677302 [DEBUG] h323caps.cxx:3598 Added capability: UserInput/hookflash <7> 2010-12-14 12:38:57.677302 [DEBUG] h323caps.cxx:3712 FindCapability: UserInput/basicString <8> 2010-12-14 12:38:57.677302 [DEBUG] h323caps.cxx:3598 Added capability: UserInput/basicString <8> 2010-12-14 12:38:57.677302 [DEBUG] mod_sofia.c:126 (sofia/external/21543514280633(a)200.35.145.149) State Change CS_INIT -> CS_ROUTING 2010-12-14 12:38:57.677302 [DEBUG] switch_core_session.c:1083 Send signal sofia/external/21543514280633(a)200.35.145.149 [BREAK] 2010-12-14 12:38:57.677302 [DEBUG] h323caps.cxx:3712 FindCapability: UserInput/dtmf <9> 2010-12-14 12:38:57.677302 [DEBUG] switch_core_state_machine.c:356 (sofia/external/21543514280633(a)200.35.145.149) State INIT going to sleep 2010-12-14 12:38:57.677302 [DEBUG] switch_core_state_machine.c:320 (sofia/external/21543514280633(a)200.35.145.149) Running State Change CS_ROUTING 2010-12-14 12:38:57.677302 [DEBUG] sofia.c:4604 Channel sofia/external/21543514280633(a)200.35.145.149 entering state [terminated][900] 2010-12-14 12:38:57.677302 [DEBUG] switch_channel.c:1615 (sofia/external/21543514280633(a)200.35.145.149) Callstate Change DOWN -> RINGING 2010-12-14 12:38:57.677302 [DEBUG] h323caps.cxx:3598 Added capability: UserInput/dtmf <9> 2010-12-14 12:38:57.677302 [DEBUG] h323caps.cxx:3712 FindCapability: UserInput/RFC2833 <10> 2010-12-14 12:38:57.677302 [DEBUG] h323caps.cxx:3598 Added capability: UserInput/RFC2833 <10> 2010-12-14 12:38:57.677302 [DEBUG] h323caps.cxx:3660 FindCapability: 1 2010-12-14 12:38:57.677302 [DEBUG] h323caps.cxx:3664 Found capability: G.711-ALaw-64k <1> 2010-12-14 12:38:57.677302 [DEBUG] switch_core_state_machine.c:359 (sofia/external/21543514280633(a)200.35.145.149) State ROUTING 2010-12-14 12:38:57.677302 [DEBUG] mod_sofia.c:149 sofia/external/21543514280633(a)200.35.145.149 SOFIA ROUTING 2010-12-14 12:38:57.677302 [DEBUG] switch_ivr_originate.c:66 (sofia/external/21543514280633(a)200.35.145.149) State Change CS_ROUTING -> CS_CONSUME_MEDIA 2010-12-14 12:38:57.677302 [DEBUG] switch_core_session.c:1083 Send signal sofia/external/21543514280633(a)200.35.145.149 [BREAK] 2010-12-14 12:38:57.677302 [DEBUG] switch_core_state_machine.c:359 (sofia/external/21543514280633(a)200.35.145.149) State ROUTING going to sleep 2010-12-14 12:38:57.677302 [DEBUG] switch_channel.c:2455 (sofia/external/21543514280633(a)200.35.145.149) Callstate Change RINGING -> HANGUP 2010-12-14 12:38:57.677302 [DEBUG] switch_core_state_machine.c:320 (sofia/external/21543514280633(a)200.35.145.149) Running State Change CS_HANGUP 2010-12-14 12:38:57.677302 [NOTICE] sofia.c:5244 Hangup sofia/external/21543514280633(a)200.35.145.149 [CS_CONSUME_MEDIA] [NORMAL_UNSPECIFIED] 2010-12-14 12:38:57.677302 [DEBUG] h323caps.cxx:3660 FindCapability: 2 2010-12-14 12:38:57.677302 [DEBUG] h323caps.cxx:3664 Found capability: GSM-06.10{sw} <2> 2010-12-14 12:38:57.677302 [DEBUG] h323caps.cxx:3660 FindCapability: 7 2010-12-14 12:38:57.677302 [DEBUG] h323caps.cxx:3664 Found capability: UserInput/hookflash <7> 2010-12-14 12:38:57.677302 [DEBUG] h323caps.cxx:3660 FindCapability: 8 2010-12-14 12:38:57.677302 [DEBUG] h323caps.cxx:3664 Found capability: UserInput/basicString <8> 2010-12-14 12:38:57.677302 [DEBUG] h323caps.cxx:3660 FindCapability: 9 2010-12-14 12:38:57.677302 [DEBUG] h323caps.cxx:3664 Found capability: UserInput/dtmf <9> 2010-12-14 12:38:57.680240 [DEBUG] switch_ivr_originate.c:3448 Originate Resulted in Error Cause: 31 [NORMAL_UNSPECIFIED] 2010-12-14 12:38:57.680240 [INFO] mod_dptools.c:2579 Originate Failed. Cause: NORMAL_UNSPECIFIED 2010-12-14 12:38:57.680240 [DEBUG] mod_h323.cpp:1460 ======>FSH323Connection::receive_message MSG = 27 2010-12-14 12:38:57.680240 [DEBUG] mod_h323.cpp:1567 Received message id = 27 [0x9a7d140] 2010-12-14 12:38:57.680240 [NOTICE] switch_core_state_machine.c:189 h323/231543514280633 has executed the last dialplan instruction, hanging up. 2010-12-14 12:38:57.680240 [DEBUG] switch_channel.c:2455 (h323/231543514280633) Callstate Change RINGING -> HANGUP 2010-12-14 12:38:57.680240 [DEBUG] h323caps.cxx:3660 FindCapability: 10 2010-12-14 12:38:57.680240 [DEBUG] h323caps.cxx:3664 Found capability: UserInput/RFC2833 <10> 2010-12-14 12:38:57.680240 [NOTICE] switch_core_state_machine.c:191 Hangup h323/231543514280633 [CS_EXECUTE] [NORMAL_CLEARING] 2010-12-14 12:38:57.680240 [DEBUG] h323caps.cxx:4197 Capability merge result: Table: G.711-ALaw-64k <1> GSM-06.10{sw} <2> UserInput/hookflash <7> UserInput/basicString <8> UserInput/dtmf <9> UserInput/RFC2833 <10> Set: 0: 0: G.711-ALaw-64k <1> GSM-06.10{sw} <2> 1: 2: UserInput/hookflash <7> 3: UserInput/basicString <8> UserInput/dtmf <9> UserInput/RFC2833 <1 LogINCOMING DATA [(null)] RECV EVENT Event-Name: SOCKET_DATA -Func: _undef_ Log-Line: 0 User-Data: _undef_ INCOMING DATA [(null)] RECV EVENT Event-Name: SOCKET_DATA 2010-12-14 12:38:57.680240 [DEBUG] h323caps.cxx:4199 Received capability set, is accepted 2010-12-14 12:38:57.680240 [DEBUG] switch_channel.c:2471 Send signal h323/231543514280633 [KILL] 2010-12-14 12:38:57.680240 [DEBUG] mod_h323.cpp:1382 ======>FSH323Connection::kill_channel sig = 1 [0x9a7d140] 2010-12-14 12:38:57.680240 [DEBUG] mod_h323.cpp:1407 --->Kill soket [0x9a7d140] 2010-12-14 12:38:57.680240 [DEBUG] mod_h323.cpp:1584 ======>FSH323Connection::state_change [0x9a7d140] 2010-12-14 12:38:57.680240 [DEBUG] mod_h323.cpp:1585 State changed on connection [0x9a7d140] 2010-12-14 12:38:57.680240 [DEBUG] switch_core_session.c:1083 Send signal h323/231543514280633 [BREAK] 2010-12-14 12:38:57.680240 [DEBUG] mod_h323.cpp:1382 ======>FSH323Connection::kill_channel sig = 3 [0x9a7d140] 2010-12-14 12:38:57.680240 [DEBUG] switch_core_state_machine.c:366 (h323/231543514280633) State EXECUTE going to sleep 2010-12-14 12:38:57.680240 [DEBUG] switch_core_state_machine.c:320 (h323/231543514280633) Running State Change CS_HANGUP 2010-12-14 12:38:57.680240 [DEBUG] h323neg.cxx:600 Sending TerminalCapabilitySet: outSeq=1 2010-12-14 12:38:57.680240 [DEBUG] switch_core_state_machine.c:557 (h323/231543514280633) State HANGUP 2010-12-14 12:38:57.680240 [DEBUG] mod_h323.cpp:2338 ======>switch_status_t on_hangup [0x9bfe898] 2010-12-14 12:38:57.680240 [DEBUG] mod_h323.cpp:2353 ------------->h323_mutex_lock 2010-12-14 12:38:57.680240 [DEBUG] mod_h323.cpp:2356 ------------->h323_mutex_unlock 2010-12-14 12:38:57.680240 [DEBUG] mod_h323.cpp:2363 ----->ip $200.117.192.17:20570/4293 2010-12-14 12:38:57.680240 [DEBUG] mod_h323.cpp:2366 -----> () = -1 2010-12-14 12:38:57.680240 [DEBUG] h323pdu.cxx:1281 Call End Reason Normal call clearing 2010-12-14 12:38:57.680240 [DEBUG] h323ep.cxx:2802 Clearing connection ip$200.117.192.17:20570/4293 reason=EndedByRemoteUser 2010-12-14 12:38:57.680240 [DEBUG] h323.cxx:1139 Call end reason for ip $200.117.192.17:20570/4293 set to EndedByRemoteUser 2010-12-14 12:38:57.680240 [DEBUG] h323.cxx:1157 Sending release complete PDU: callRef=4293 2010-12-14 12:38:57.680240 [DEBUG] h323t38.cxx:216 OnSendingPDU for capability 2010-12-14 12:38:57.683262 [DEBUG] mod_h323.cpp:1085 ======>FSH323Connection::OnSendReleaseComplete cause = 16 2010-12-14 12:38:57.683262 [DEBUG] h323pdu.cxx:618 Sending PDU [ip $72.51.47.100:1720/ip$200.117.192.17:20570] : { q931pdu = { protocolDiscriminator = 8 callReference = 4293 from = destination messageType = ReleaseComplete IE: Cause - N ormal call clearing = { 80 90 .. } IE: User-User = { 25 80 06 00 08 91 4a 00 06 01 11 00 a2 4e 58 c9 %.....J......NX. ec 05 e0 11 9e 19 00 1e 64 1f 33 fe 02 80 01 8 0 ........d.3..... } } h225pdu = { h323_uu_pdu = { h323_message_body = releaseComplete { protocolIdentifier = 0.0.8.2250.0.6 callIdentifier = { guid = 16 octets { a2 4e 58 c9 ec 05 e0 11 9e 19 00 1e 64 1f 33 fe .NX.........d.3. } } } h245Tunneling = true } } } 2010-12-14 12:38:57.683262 [DEBUG] h323ep.cxx:2861 Cleaning up connections 2010-12-14 12:38:57.683262 [DEBUG] h323.cxx:1199 Connection ip $200.117.192.17:20570/4293 closing: connectionState=AwaitingLocalAnswer 2010-12-14 12:38:57.683262 [DEBUG] mod_h323.cpp:2387 ------------->h323_mutex_lock 2010-12-14 12:38:57.683262 [DEBUG] mod_h323.cpp:2389 ------------->h323_mutex_unlock 2010-12-14 12:38:57.683262 [DEBUG] switch_core_state_machine.c:46 h323/231543514280633 Standard HANGUP, cause: NORMAL_CLEARING 2010-12-14 12:38:57.683262 [DEBUG] switch_core_state_machine.c:557 (h323/231543514280633) State HANGUP going to sleep 2010-12-14 12:38:57.683262 [DEBUG] switch_core_state_machine.c:351 (h323/231543514280633) State Change CS_HANGUP -> CS_REPORTING 2010-12-14 12:38:57.683262 [DEBUG] switch_core_session.c:1083 Send signal h323/231543514280633 [BREAK] 2010-12-14 12:38:57.683262 [DEBUG] switch_core_state_machine.c:320 (h323/231543514280633) Running State Change CS_REPORTING 2010-12-14 12:38:57.683262 [DEBUG] switch_core_state_machine.c:617 (h323/231543514280633) State REPORTING 2010-12-14 12:38:57.683262 [DEBUG] h323pdu.cxx:618 Sending PDU [(noaddr)/(noaddr)] : request terminalCapabilitySet { sequenceNumber = 1 protocolIdentifier = 0.0.8.245.0.13 multiplexCapability = h2250Capability { maximumAudioDelayJitter = 60 rece iveMultipointCapability = { multicastCapability = false multiUniCastConference = false mediaDistributionCapability = 1 entries { [0]={ centralizedControl = false distributedControl = false 2010-12-14 12:38:57.683262 [DEBUG] switch_core_state_machine.c:557 (sofia/external/21543514280633(a)200.35.145.149) State HANGUP 2010-12-14 12:38:57.686742 [DEBUG] switch_channel.c:2471 Send signal sofia/external/21543514280633(a)200.35.145.149 [KILL] 2010-12-14 12:38:57.686742 [DEBUG] switch_core_session.c:1083 Send signal sofia/external/21543514280633(a)200.35.145.149 [BREAK] 2010-12-14 12:38:57.686742 [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-14 12:38:57.686742 [DEBUG] mod_sofia.c:459 Channel sofia/external/21543514280633(a)200.35.145.149 hanging up, cause: NORMAL_UNSPECIFIED 2010-12-14 12:38:57.686742 [DEBUG] switch_core_state_machine.c:46 sofia/external/21543514280633(a)200.35.145.149 Standard HANGUP, cause: NORMAL_UNSPECIFIED 2010-12-14 12:38:57.686742 [DEBUG] switch_core_state_machine.c:557 (sofia/external/21543514280633(a)200.35.145.149) State HANGUP going to sleep 2010-12-14 12:38:57.686742 [DEBUG] switch_core_state_machine.c:351 (sofia/external/21543514280633(a)200.35.145.149) State Change CS_HANGUP -> CS_REPORTING 2010-12-14 12:38:57.686742 [DEBUG] switch_core_session.c:1083 Send signal sofia/external/21543514280633(a)200.35.145.149 [BREAK] 2010-12-14 12:38:57.686742 [DEBUG] switch_core_state_machine.c:320 (sofia/external/21543514280633(a)200.35.145.149) Running State Change CS_REPORTING centralizedAudio = false distributedAudio = false centralizedVideo = false distributedVideo = false } } } transmitMultipointCapability = { multicastCapability = fals e multiUniCastConference = false mediaDistributionCapability = 1 entries { [0]={ centralizedControl = false distributedControl = false centralizedAudio = false distributedAudi o = false centralizedVideo = false distributedVideo = false } } } receiveAndTransmitMultipointCapability = { multicastCapability = false multiUniCastConference = false med iaDistributionCapability = 1 entries { [0]={ centralizedControl = false distributedControl = false centralizedAudio = false distributedAudio = false centralizedVideo = false distributedVideo = false } } } mcCapability = { centralizedConferenceMC = false decentralizedConferenceMC = false } rtcpVideoControlCapability = false mediaPacketizationCapabilit y = { h261aVideoPacketization = false } logicalChannelSwitchingCapability = false t120DynamicPortCapability = true } capabilityTable = 16 entries { [0]={ capabilityTableEntryNumber = 1 capabili ty = receiveAudioCapability g711Alaw64k 20 } [1]={ capabilityTableEntryNumber = 2 capability = receiveAudioCapability g711Ulaw64k 20 } [2]={ capabilityTableEntryNumber = 3 capability = receiveA udioCapability gsmFullRate { audioUnitSize = 33 comfortNoise = false scrambled = false } } [3]={ capabilityTableEntryNumber = 4 capability = receiveAudioCapability g729AnnexAwAnnexB 2 4 } [4]={ capabilityTableEntryNumber = 5 capability = receiveAudioCapability g729AnnexA 24 } [5]={ capabilityTableEntryNumber = 6 capability = receiveAudioCapability g729wAnnexB 24 } [6]={ capabilityTableEntryNumber = 7 capability = receiveAudioCapability g729 24 } [7]={ capabilityTableEntryNumber = 8 capability = receiveAndTransmitDataApplicationCapability { application = t38fax { t38FaxProtocol = udp <<null>> t38FaxProfile = { fillBitRemoval = false transcodingJBIG = false transcodingMMR = false version = 0 t38FaxRateManage ment = transferredTCF <<null>> t38FaxUdpOptions = { t38FaxMaxBuffer = 200 t38FaxMaxDatagram = 72 t38FaxUdpEC = t38UDPRedundancy <<null>> } } } maxBitRate = 144 } } [8]={ capabilityTableEntryNumber = 9 capability = receiveUserInputCapability hookflash <<null>> } [9]={ capabilityTableEntryNumber = 10 capability = receiveUserIn putCapability basicString <<null>> } [10]={ capabilityTableEntryNumber = 11 capability = receiveUserInputCapability dtmf <<null>> } [11]={ capabilityTableEntryNumber = 12 capability = receiveRT PAudioTelephonyEventCapability { dynamicRTPPayloadType = 101 audioTelephoneEvent = "0-16" } } [12]={ capabilityTableEntryNumber = 13 capability = receiveUserInputCapability genericUserInputCapa bility { capabilityIdentifier = standard 0.0.8.249.1 } } [13]={ capabilityTableEntryNumber = 14 capability = receiveUserInputCapability genericUserInputCapability { capabilityIdentifier = stand ard 0.0.8.249.2 } } [14]={ capabilityTableEntryNumber = 15 capability = receiveUserInputCapability genericUserInputCapability { capabilityIdentifier = standard 0.0.8.249.3 } } [15]={ capabilityTableEntryNumber = 16 capability = receiveUserInputCapability genericUserInputCapability { capabilityIdentifier = standard 0.0.8.249.4 } } } capabilityDescriptors = 1 entries { [0]={ capabilityDescriptorNumber = 1 simultaneousCapabilities = 2 entries { [0]=8 entries { [0]=1 [1]=2 [2]=3 [3]=4 [4]=5 [5]=6 [6]=7 [ Log-Func: Log-Line: 0 User-Data: 7]=8 } [1]=8 entries { [0]=9 [1]=10 [2]=11 [3]=12 [4]=13 [5]=14 [6]=15 [7]=16 2010-12-14 12:38:57.689292 [DEBUG] h323pdu.cxx:618 Sending PDU [ip $72.51.47.100:1720/ip$200.117.192.17:20570] : { q931pdu = { protocolDiscriminator = 8 callReference = 4293 from = destination messageType = Facility IE: Facility = { (.............p. 06 00 08 81 75 00 0d 80 13 80 00 3c 00 01 00 00 ....u......<.... ... } } h225pdu = { h323_uu_pdu = { h323_message_body = empty <<null>> h245Tunneling = true h245Control = 1 entries { [0]= 231 octets { 02 70 01 06 00 08 81 75 00 0d 80 13 80 00 3c 00 .p.....u......<. 01 00 00 01 00 00 01 00 0 0 0c c0 01 00 01 80 0f ................ ... } } } } } 2010-12-14 12:38:57.689292 [DEBUG] switch_core_state_machine.c:53 h323/231543514280633 Standard REPORTING, cause: NORMAL_CLEARING 2010-12-14 12:38:57.689292 [DEBUG] switch_core_state_machine.c:617 (h323/231543514280633) State REPORTING going to sleep 2010-12-14 12:38:57.689292 [DEBUG] switch_core_state_machine.c:345 (h323/231543514280633) State Change CS_REPORTING -> CS_DESTROY 2010-12-14 12:38:57.689292 [DEBUG] switch_core_session.c:1083 Send signal h323/231543514280633 [BREAK] 2010-12-14 12:38:57.689292 [DEBUG] switch_core_session.c:1250 Session 9 (h323/231543514280633) Locked, Waiting on external entities 2010-12-14 12:38:57.689292 [NOTICE] switch_core_session.c:1268 Session 9 (h323/231543514280633) Ended 2010-12-14 12:38:57.689292 [NOTICE] switch_core_session.c:1270 Close Channel h323/231543514280633 [CS_DESTROY] 2010-12-14 12:38:57.689292 [DEBUG] switch_core_state_machine.c:449 (h323/231543514280633) Callstate Change HANGUP -> DOWN 2010-12-14 12:38:57.689292 [DEBUG] switch_core_state_machine.c:452 (h323/231543514280633) Running State Change CS_DESTROY 2010-12-14 12:38:57.689292 [DEBUG] switch_core_state_machine.c:462 (h323/231543514280633) State DESTROY 2010-12-14 12:38:57.689292 [DEBUG] mod_h323.cpp:2307 ======>on_destroy 2010-12-14 12:38:57.689292 [DEBUG] switch_core_state_machine.c:60 h323/231543514280633 Standard DESTROY 2010-12-14 12:38:57.689292 [DEBUG] switch_core_state_machine.c:462 (h323/231543514280633) State DESTROY going to sleep 2010-12-14 12:38:57.686742 [DEBUG] switch_core_state_machine.c:617 (sofia/external/21543514280633(a)200.35.145.149) State REPORTING 2010-12-14 12:38:57.689292 [DEBUG] h323caps.cxx:3676 FindCapability: "UserInput/RFC2833" 2010-12-14 12:38:57.689292 [DEBUG] h323caps.cxx:3685 Found capability: UserInput/RFC2833 <10> 2010-12-14 12:38:57.689292 [DEBUG] h323.cxx:4228 User Input RFC2833 payload type set to [pt=101] 2010-12-14 12:38:57.689292 [DEBUG] mod_h323.cpp:1109 ======>END H323Connection::OnReceivedCapabilitySet [0x9a7d140] 2010-12-14 12:38:57.689292 [DEBUG] mod_h323.cpp:1112 ----> Capabilities = G.711-ALaw-64k 2010-12-14 12:38:57.689292 [DEBUG] mod_h323.cpp:1112 ----> Capabilities = GSM-06.10{sw} 2010-12-14 12:38:57.689292 [DEBUG] mod_h323.cpp:1112 ----> Capabilities = UserInput/hookflash 2010-12-14 12:38:57.689292 [DEBUG] mod_h323.cpp:1112 ----> Capabilities = UserInput/basicString 2010-12-14 12:38:57.689292 [DEBUG] mod_h323.cpp:1112 ----> Capabilities = UserInput/dtmf 2010-12-14 12:38:57.689292 [DEBUG] mod_h323.cpp:1112 ----> Capabilities = UserInput/RFC2833 2010-12-14 12:38:57.689292 [DEBUG] h323caps.cxx:4009 Found capability: G.711-ALaw-64k <1> 2010-12-14 12:38:57.689292 [DEBUG] mod_h323.cpp:1120 ----> Capabilities not NULL 2010-12-14 12:38:57.689292 [DEBUG] h323pdu.cxx:618 Sending PDU [(noaddr)/(noaddr)] : response terminalCapabilitySetAck { sequenceNumber = 1 } 2010-12-14 12:38:57.692254 [DEBUG] h323pdu.cxx:618 Sending PDU [ip $72.51.47.100:1720/ip$200.117.192.17:20570] : { q931pdu = { protocolDiscriminator = 8 callReference = 4293 from = destination messageType = Facility IE: Facility = { (..........!.. } } h225pdu = { h323_uu_pdu = { h323_message_body = empty <<null>> h245Tunneling = true h245Control = 1 entries { [0]= 3 octets { 21 80 01 !.. } } } } } 2010-12-14 12:38:57.692254 [DEBUG] h323.cxx:4369 InternalEstablishedConnectionCheck: connectionState=AwaitingLocalAnswer fastStartState=FastStartAcknowledged 2010-12-14 12:38:57.692254 [DEBUG] h323pdu.cxx:618 Receiving PDU [(noaddr)/(noaddr)] : request masterSlaveDetermination { terminalType = 50 statusDeterminationNumber = 1982505 } 2010-12-14 12:38:57.692254 [DEBUG] h323neg.cxx:395 Received MasterSlaveDetermination: state=Idle 2010-12-14 12:38:57.692254 [DEBUG] h323neg.cxx:426 MasterSlaveDetermination: local is master 2010-12-14 12:38:57.692254 [DEBUG] h323pdu.cxx:618 Sending PDU [(noaddr)/(noaddr)] : response masterSlaveDeterminationAck { decision = slave <<null>> } 2010-12-14 12:38:57.695276 [DEBUG] switch_core_state_machine.c:53 sofia/external/21543514280633(a)200.35.145.149 Standard REPORTING, cause: NORMAL_UNSPECIFIED 2010-12-14 12:38:57.695276 [DEBUG] switch_core_state_machine.c:617 (sofia/external/21543514280633(a)200.35.145.149) State REPORTING going to sleep 2010-12-14 12:38:57.695276 [DEBUG] h323pdu.cxx:618 Sending PDU [ip $72.51.47.100:1720/ip$200.117.192.17:20570] : { q931pdu = { protocolDiscriminator = 8 callReference = 4293 from = destination messageType = Facility IE: Facility = { (.......... . } } h225pdu = { h323_uu_pdu = { h323_message_body = empty <<null>> h245Tunneling = true h245Control = 1 entries { [0]= 2 octets { 20 a0 . } } } } } 2010-12-14 12:38:57.695276 [DEBUG] switch_core_state_machine.c:345 (sofia/external/21543514280633(a)200.35.145.149) State Change CS_REPORTING -> CS_DESTROY 2010-12-14 12:38:57.695276 [DEBUG] switch_core_session.c:1083 Send signal sofia/external/21543514280633(a)200.35.145.149 [BREAK] 2010-12-14 12:38:57.695276 [DEBUG] switch_core_session.c:1250 Session 10 (sofia/external/21543514280633(a)200.35.145.149) Locked, Waiting on external entities 2010-12-14 12:38:57.695276 [NOTICE] switch_core_session.c:1268 Session 10 (sofia/external/21543514280633(a)200.35.145.149) Ended 2010-12-14 12:38:57.695276 [NOTICE] switch_core_session.c:1270 Close Channel sofia/external/21543514280633(a)200.35.145.149 [CS_DESTROY] 2010-12-14 12:38:57.695276 [DEBUG] switch_core_state_machine.c:449 (sofia/external/21543514280633(a)200.35.145.149) Callstate Change HANGUP -> DOWN 2010-12-14 12:38:57.695276 [DEBUG] switch_core_state_machine.c:452 (sofia/external/21543514280633(a)200.35.145.149) Running State Change CS_DESTROY 2010-12-14 12:38:57.695276 [DEBUG] switch_core_state_machine.c:462 (sofia/external/21543514280633(a)200.35.145.149) State DESTROY 2010-12-14 12:38:57.695276 [DEBUG] mod_sofia.c:364 sofia/external/21543514280633(a)200.35.145.149 SOFIA DESTROY 2010-12-14 12:38:57.695276 [DEBUG] switch_core_state_machine.c:60 sofia/external/21543514280633(a)200.35.145.149 Standard DESTROY 2010-12-14 12:38:57.695276 [DEBUG] switch_core_state_machine.c:462 (sofia/external/21543514280633(a)200.35.145.149) State DESTROY going to sleep 2010-12-14 12:38:57.695276 [DEBUG] h323.cxx:4369 InternalEstablishedConnectionCheck: connectionState=AwaitingLocalAnswer fastStartState=FastStartAcknowledged 2010-12-14 12:38:57.695276 [DEBUG] h323.cxx:4369 InternalEstablishedConnectionCheck: connectionState=AwaitingLocalAnswer fastStartState=FastStartAcknowledged 2010-12-14 12:38:57.695276 [DEBUG] h323.cxx:1353 Reading PDUs: callRef=4293 2010-12-14 12:38:57.695276 [DEBUG] h323neg.cxx:380 Stopping MasterSlaveDetermination: state=Incoming 2010-12-14 12:38:57.695276 [DEBUG] h323neg.cxx:612 Stopping TerminalCapabilitySet: state=InProgress 2010-12-14 12:38:57.695276 [DEBUG] channels.cxx:743 Cleaning up T-101 2010-12-14 12:38:57.695276 [DEBUG] h323ep.cxx:3213 Stopped sending logical channel: G.711-ALaw-64k <1> 2010-12-14 12:38:57.695276 [DEBUG] channels.cxx:771 Cleaned up T-101 2010-12-14 12:38:57.695276 [DEBUG] channels.cxx:743 Cleaning up R-101 2010-12-14 12:38:57.695276 [DEBUG] h323ep.cxx:3213 Stopped receiving logical channel: G.711-ALaw-64k <1> 2010-12-14 12:38:57.695276 [DEBUG] channels.cxx:771 Cleaned up R-101 2010-12-14 12:38:57.695276 [DEBUG] mod_h323.cpp:1903 ======>FSH323_ExternalRTPChannel::~FSH323_ExternalRTPChannel IsTransmitter [0xb773dc88] 2010-12-14 12:38:57.695276 [DEBUG] mod_h323.cpp:1906 ------------->switch_core_session_unlock_codec_read [0x9bfe898] 2010-12-14 12:38:57.695276 [DEBUG] mod_h323.cpp:1908 ------------->switch_core_session_unlock_codec_write [0x9bfe898] 2010-12-14 12:38:57.695276 [DEBUG] h323.cxx:5105 Bandwidth request: -64.0kb/s, available: 9872.0kb/s 2010-12-14 12:38:57.695276 [DEBUG] mod_h323.cpp:1903 ======>FSH323_ExternalRTPChannel::~FSH323_ExternalRTPChannel IsReceiver [0xb773b9a0] 2010-12-14 12:38:57.695276 [DEBUG] mod_h323.cpp:1906 ------------->switch_core_session_unlock_codec_read [0x9bfe898] 2010-12-14 12:38:57.695276 [DEBUG] mod_h323.cpp:1908 ------------->switch_core_session_unlock_codec_write [0x9bfe898] 2010-12-14 12:38:57.695276 [DEBUG] h323.cxx:5105 Bandwidth request: -64.0kb/s, available: 9936.0kb/s 2010-12-14 12:38:57.698292 [DEBUG] transports.cxx:1295 H323Transport::Close 2010-12-14 12:38:57.698292 [INFO] h323pdu.cxx:1576 Read error (4): Interrupted system call 2010-12-14 12:38:57.698292 [DEBUG] h323ep.cxx:2802 Clearing connection ip$200.117.192.17:20570/4293 reason=EndedByTransportFail 2010-12-14 12:38:57.698292 [DEBUG] transports.cxx:1295 H323Transport::Close 2010-12-14 12:38:57.698292 [DEBUG] h323.cxx:1367 Signal channel closed. 2010-12-14 12:38:57.701253 [DEBUG] tlibthrd.cxx:1020 Could not parse thread stat file /proc/23814/task/23999/stat 2010-12-14 12:38:57.966214 [DEBUG] transports.cxx:1432 H323Transport::CleanUpOnTermination for H225 Answer:b73c5b90 2010-12-14 12:38:57.966214 [INFO] h323.cxx:1273 Connection ip $200.117.192.17:20570/4293 terminated. 2010-12-14 12:38:57.966214 [DEBUG] mod_h323.cpp:737 ======>FSH323Connection::~FSH323Connection [0x9a7d140] 2010-12-14 12:38:57.966214 [DEBUG] h323.cxx:1089 Connection ip $200.117.192.17:20570/4293 deleted. 2010-12-14 12:38:57.966214 [DEBUG] h323ep.cxx:2861 Cleaning up connections

3 2

Timer for mod_spidermonkey impossible ?
by Shamun toha md 14 Dec '10

14 Dec '10

Hello, I have a test script like this. But is it possible to make a setTimeout(function(){ console_log("Hello world") }, 1000); ?? Like a timer which keep running with interval of 1 second ? Where i can do some Ajax ??? helloworld.js ========= var argv; function on_hangup(hup_session, how) { console_log( "err", how + " HOOK" + "\n" + "hello world" + session.getVariable("test") + "\n" ); exit(); ); session.setHangupHook(on_hangup); Thanks & Regards

2 2

priority call queue using mod_fifo
by Rajkumar K 14 Dec '10

14 Dec '10

Hi, I would like to implement the priority queue using mod_fifo. Please provide the following informations. 1. How to set the priority for the caller or consumer (who should be served first)? 2. Is it possible to choose the agent with different methods such as round robin (before bridging the call)? 3. How can we control the call flow after bridging the call with agent (for call recording and other options)? 4. Is there any detailed documentation available for mod_fifo module. regards rajkumar k

1 0

originate call generates weird noise and freezes phone
by Sébastien Frippiat 14 Dec '10

14 Dec '10

Hello. I was using FreeSwitch from 2010/09/09 (don't have the exact git version sorry) but I recently updated it with version from 2010/12/09 (git-e680c82 2010-12-09 08-59-06 -0600). I run an application that handles web requests and convert them to originate calls. Everything was working fine but now, anytime I try an originate call, everything goes wrong. Here is what I do (to originate a call from 26 to 27): - bgapi originate {{parameters }}sofia/internal/26% 27 with the following parameters: origination_caller_id_name='{0}', origination_caller_id_number='{1}', originate_timeout=20, effective_caller_id_name='{2}', effective_caller_id_number='{3}', allow_outside_calls={0}, outside_calls_gateway={1} (the last two parameters are user parameters I use in my dialplan) - phone 26 starts ringing and I pick it up (silence on the line) - phone 27 starts ringing and I pick it up (lots of deafening noise + phone freezes after a few seconds) As I said, it was working fine with version from early september. I did not find anything useful in the logs or in the config. Has anything changed ? What can I do to solve this problem ? I tested with a Grandstream GXP2000 and a SNOM M3 and only the Grandstream has the problem. I suspect a codec issue but what puzzles me is that it was previously working and that it completely freezes the phone. Thanks, Sebastien

2 2

Cepstral + FS question
by Malay Thakershi 14 Dec '10

14 Dec '10

Hello, it would be great help if someone who has used Cepstral from FS can share their views. 1. I just have one Cepstral Allison voice license (1 port) on my FS server. I use swift command to convert text files to WAV which are then played by mod_managed in FS call process. First question is regarding limitations on simultaneous conversions (TXT to WAV) using swift command. If I have multiple threads doing this, will there be any degradation because of Cepstral? I tried running two BAT files with 3 commands each. But running them simultaneously or separate produced same outcome. Does anyone know when Cepstral licensing kicks in and starts degrading quality ( or worse inserting "not licensed") prompt? 2. When I call session speak from mod_managed (or stream file) after selecting Allison / Cepstral as my voice, does Cepstral engine interfere with quality of the playback? If yes, when will I see it and how can I produce their effects? 3. What is the sensible number of ports (from Cepstral) I should be prepared to buy if findings in the previous points imposes significant limitations? Thank you for any help or guidance. Malay

3 4

a2billing and freeswitch
by Rafqat . 14 Dec '10

14 Dec '10

Hi I was wondering if a2billing can be setup to work with freeswitch? If so, can anyone please point me to some documentation on how this can be done? Cheers Raf

4 3

send dtmf to IVR from CLI
by Madovsky 14 Dec '10

14 Dec '10

is it possible to send dtmf to an IVR from CLI if I know the uuid of leg A only ? Thanks F

3 2

Re: [Freeswitch-users] Incomplete offer/answer debugging
by Jerre Cope 13 Dec '10

13 Dec '10

Sorry. The wifi phone is a Loctec WP04. Not Cisco quality, but I needed a phone the technician could drop and kick across the parking lot. It's been working fine, so I bought 6 more of them. Picture and manual here if you're curious. I doubt it will be very helpful: http://www.yippz.com/wp04-wifi-phone.html

1 0

Incomplete offer/answer debugging
by Jerre Cope 13 Dec '10

13 Dec '10

Hello! Newbie question. My wifi SIP phone has stopped answering lately and I don't know if I've induced a configuration problem by making freeswitch current, or whether I've staticed the phone firmware. I have the wifi handset set up as a typical extension 1000. I've got a Twinkle softphone on ext 1001 and I can call that extension from 1000, but when I call from 1001 to ext 1000, the handset returns the nua_media_error. Both extensions are on the same network. I've posted a pastebin of log level 9 sip trace on pastebin 14768 I could use some help understanding the log so I can correct my nua_media_error negotiation problem. I started the log early in the conversation, where it negotiates past the IP to the user registration, but I don't see a clear reason for the "BYE" on line 526. Thanks for FreeSWITCH!

2 1