New subject: import vars from other context

14 Dec 2010


      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@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@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@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@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@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@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@200.35.145.149) State INIT
2010-12-14 12:38:57.677302 [DEBUG] mod_sofia.c:86
sofia/external/21543514280633@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@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@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@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@200.35.145.149) Running State Change
CS_ROUTING
2010-12-14 12:38:57.677302 [DEBUG] sofia.c:4604 Channel
sofia/external/21543514280633@200.35.145.149 entering state
[terminated][900]
2010-12-14 12:38:57.677302 [DEBUG] switch_channel.c:1615
(sofia/external/21543514280633@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@200.35.145.149) State ROUTING
2010-12-14 12:38:57.677302 [DEBUG] mod_sofia.c:149
sofia/external/21543514280633@200.35.145.149 SOFIA ROUTING
2010-12-14 12:38:57.677302 [DEBUG] switch_ivr_originate.c:66
(sofia/external/21543514280633@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@200.35.145.149 [BREAK]
2010-12-14 12:38:57.677302 [DEBUG] switch_core_state_machine.c:359
(sofia/external/21543514280633@200.35.145.149) State ROUTING going to
sleep
2010-12-14 12:38:57.677302 [DEBUG] switch_channel.c:2455
(sofia/external/21543514280633@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@200.35.145.149) Running State Change
CS_HANGUP
2010-12-14 12:38:57.677302 [NOTICE] sofia.c:5244 Hangup
sofia/external/21543514280633@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@200.35.145.149) State HANGUP
2010-12-14 12:38:57.686742 [DEBUG] switch_channel.c:2471 Send signal
sofia/external/21543514280633@200.35.145.149 [KILL]
2010-12-14 12:38:57.686742 [DEBUG] switch_core_session.c:1083 Send
signal sofia/external/21543514280633@200.35.145.149 [BREAK]
2010-12-14 12:38:57.686742 [DEBUG] mod_sofia.c:453
sofia/external/21543514280633@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@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@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@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@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@200.35.145.149 [BREAK]
2010-12-14 12:38:57.686742 [DEBUG] switch_core_state_machine.c:320
(sofia/external/21543514280633@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@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@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@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@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@200.35.145.149 [BREAK]
2010-12-14 12:38:57.695276 [DEBUG] switch_core_session.c:1250 Session 10
(sofia/external/21543514280633@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@200.35.145.149) Ended
2010-12-14 12:38:57.695276 [NOTICE] switch_core_session.c:1270 Close
Channel sofia/external/21543514280633@200.35.145.149 [CS_DESTROY]
2010-12-14 12:38:57.695276 [DEBUG] switch_core_state_machine.c:449
(sofia/external/21543514280633@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@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@200.35.145.149) State DESTROY
2010-12-14 12:38:57.695276 [DEBUG] mod_sofia.c:364
sofia/external/21543514280633@200.35.145.149 SOFIA DESTROY
2010-12-14 12:38:57.695276 [DEBUG] switch_core_state_machine.c:60
sofia/external/21543514280633@200.35.145.149 Standard DESTROY
2010-12-14 12:38:57.695276 [DEBUG] switch_core_state_machine.c:462
(sofia/external/21543514280633@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

mod_h323 issue

Gustavo Espeche

Михаил Салтанов

Steven Ayre

tags

participants (3)