[Freeswitch-users] mod_h323 issue
Gustavo Espeche
gustavo.espeche at upper-soft.com
Tue Dec 14 16:13:55 MSK 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... at ..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... at ...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... at ...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 at 40i,G726-16,PCMA)
Dialplan: h323/231543514280633 Action
bridge(sofia/external/21543514280633 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 200.35.145.149) State INIT
2010-12-14 12:38:57.677302 [DEBUG] mod_sofia.c:86
sofia/external/21543514280633 at 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 at 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 at 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 at 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 at 200.35.145.149) Running State Change
CS_ROUTING
2010-12-14 12:38:57.677302 [DEBUG] sofia.c:4604 Channel
sofia/external/21543514280633 at 200.35.145.149 entering state
[terminated][900]
2010-12-14 12:38:57.677302 [DEBUG] switch_channel.c:1615
(sofia/external/21543514280633 at 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 at 200.35.145.149) State ROUTING
2010-12-14 12:38:57.677302 [DEBUG] mod_sofia.c:149
sofia/external/21543514280633 at 200.35.145.149 SOFIA ROUTING
2010-12-14 12:38:57.677302 [DEBUG] switch_ivr_originate.c:66
(sofia/external/21543514280633 at 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 at 200.35.145.149 [BREAK]
2010-12-14 12:38:57.677302 [DEBUG] switch_core_state_machine.c:359
(sofia/external/21543514280633 at 200.35.145.149) State ROUTING going to
sleep
2010-12-14 12:38:57.677302 [DEBUG] switch_channel.c:2455
(sofia/external/21543514280633 at 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 at 200.35.145.149) Running State Change
CS_HANGUP
2010-12-14 12:38:57.677302 [NOTICE] sofia.c:5244 Hangup
sofia/external/21543514280633 at 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 at 200.35.145.149) State HANGUP
2010-12-14 12:38:57.686742 [DEBUG] switch_channel.c:2471 Send signal
sofia/external/21543514280633 at 200.35.145.149 [KILL]
2010-12-14 12:38:57.686742 [DEBUG] switch_core_session.c:1083 Send
signal sofia/external/21543514280633 at 200.35.145.149 [BREAK]
2010-12-14 12:38:57.686742 [DEBUG] mod_sofia.c:453
sofia/external/21543514280633 at 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 at 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 at 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 at 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 at 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 at 200.35.145.149 [BREAK]
2010-12-14 12:38:57.686742 [DEBUG] switch_core_state_machine.c:320
(sofia/external/21543514280633 at 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 at 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 at 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 at 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 at 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 at 200.35.145.149 [BREAK]
2010-12-14 12:38:57.695276 [DEBUG] switch_core_session.c:1250 Session 10
(sofia/external/21543514280633 at 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 at 200.35.145.149) Ended
2010-12-14 12:38:57.695276 [NOTICE] switch_core_session.c:1270 Close
Channel sofia/external/21543514280633 at 200.35.145.149 [CS_DESTROY]
2010-12-14 12:38:57.695276 [DEBUG] switch_core_state_machine.c:449
(sofia/external/21543514280633 at 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 at 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 at 200.35.145.149) State DESTROY
2010-12-14 12:38:57.695276 [DEBUG] mod_sofia.c:364
sofia/external/21543514280633 at 200.35.145.149 SOFIA DESTROY
2010-12-14 12:38:57.695276 [DEBUG] switch_core_state_machine.c:60
sofia/external/21543514280633 at 200.35.145.149 Standard DESTROY
2010-12-14 12:38:57.695276 [DEBUG] switch_core_state_machine.c:462
(sofia/external/21543514280633 at 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
More information about the FreeSWITCH-users
mailing list