Dear Sirs!
When I used OPAL v.2.2.2, I successfully dialed IP-phone
Cisco 7905G by
H.323
from my application.
Yesterday I have updated my version of OPAL from the CVS.
And now
I can't dial IP-phone Cisco 7905G, but it is ok with other
devices
such as AddPac etc.
I have examined traces - there is some problem with
capabilities
exchange, then Cisco sends us invalid PDU.
Trace of a bad call with the latest version:
1:45.864 ThreadID=0x00000c34 Version 1.0.1 by on Windows
XP
(v5.1.2600-i586) at 2006/10/22 15:51:03.125
2006/10/22 15:51:09.562
1:52.304 ThreadID=0x000004fc ChildOpalManager.cpp(65) Inf Ch
ildOpalManager::CreateCall
2006/10/22 15:51:09.562 1:52.305 ThreadID=0x000004fc
call.cxx(255) Call Created Call[2]
2006/10/22 15:51:09.562 1:52.305 ThreadID=0x000004fc
h323ep.cxx(530) H323 Making call to: h323:123 194.135.22.201
2006/10/22 15:51:09.562 1:52.306 ThreadID=0x000004fc
connection.cxx(461) OpalCon Created connection
Call[2]-EP<h323>[localhost/29130]
2006/10/22 15:51:09.562 1:52.306 ThreadID=0x000004fc
rfc2833.cxx(72) RFC2833 Handler created
2006/10/22 15:51:09.562 1:52.306 ThreadID=0x000004fc
h4601.cxx(1498) H460 Endpoint Attached
2006/10/22 15:51:09.578 1:52.312 ThreadID=0x000004fc
call.cxx(461) Call GetOtherPartyConnection
Call[2]-EP<h323>[localhost/29130]
2006/10/22 15:51:09.578 1:52.313 ThreadID=0x000004fc
connection.cxx(461) OpalCon Created connection
Call[2]-EP<>[Plantronics
Headset]
2006/10/22 15:51:09.578 1:52.313 ThreadID=0x000004fc
rfc2833.cxx(72) RFC2833 Handler created
2006/10/22 15:51:09.578
1:52.313 ThreadID=0x000004fc silencedetect.cxx(94) Silence H
andler created
2006/10/22 15:51:09.578 1:52.314 ThreadID=0x000004fc
echocancel.cxx(136) Echo Canceler Handler created
2006/10/22 15:51:09.578 1:52.314 ThreadID=0x000004fc
pcss.cxx(383) PCSS Created PC sound system connection.
2006/10/22 15:51:09.578 1:52.314 ThreadID=0x000004fc
h323ep.cxx(718) H323 Created new connection: localhost/29130
2006/10/22 15:51:09.578 1:52.314 H225 Calle...er:2c2e668
h323.cxx(1670) H225 Started call thread
2006/10/22 15:51:09.578 1:52.314 H225 Calle...er:2c2e668
connection.cxx(1331) OpalCon SetPhase from
UninitialisedPhase to SetUpPhase
2006/10/22 15:51:09.578 1:52.315 H225 Calle...er:2c2e668
connection.cxx(1331) OpalCon SetPhase from SetUpPhase to
SetUpPhase
2006/10/22 15:51:09.578 1:52.315 H225 Calle...er:2c2e668
transports.cxx(1544) OpalTCP Connecting to
194.135.22.201:1720 (local
port=0)
2006/10/22 15:51:09.578 1:52.328 H225 Calle...er:2c2e668
transports.cxx(1664) OpalTCP Started connection to
194.135.22.201:1720
(if=194.135.22.1:1151)
2006/10/22 15:51:09.593 1:52.328 H225 Calle...er:2c2e668
h323.cxx(1848) H225 Sending Setup PDU
2006/10/22 15:51:09.593 1:52.329 H225 Calle...er:2c2e668
call.cxx(499) Call GetMediaFormats for
Call[2]-EP<h323>[localhost/29130]
G.711-uLaw-64k
G.711-ALaw-64k
PCM-16
YUV420P
RGB32
RGB24
2006/10/22 15:51:09.593 1:52.329 H225 Calle...er:2c2e668
h323caps.cxx(2246) H323 FindCapability:
"G.711-uLaw-64k"
2006/10/22 15:51:09.593 1:52.329 H225 Calle...er:2c2e668
h323caps.cxx(2161) H323 Added capability: G.711-uLaw-64k
<1>
2006/10/22 15:51:09.593 1:52.329 H225 Calle...er:2c2e668
h323caps.cxx(2246) H323 FindCapability:
"G.711-ALaw-64k"
2006/10/22 15:51:09.593 1:52.329 H225 Calle...er:2c2e668
h323caps.cxx(2161) H323 Added capability: G.711-ALaw-64k
<2>
2006/10/22 15:51:09.593 1:52.330 H225 Calle...er:2c2e668
h323caps.cxx(2161) H323 Added capability:
UserInput/hookflash <3>
2006/10/22 15:51:09.593 1:52.330 H225 Calle...er:2c2e668
h323caps.cxx(2161) H323 Added capability:
UserInput/basicString <4>
2006/10/22 15:51:09.593 1:52.330 H225 Calle...er:2c2e668
h323caps.cxx(2161) H323 Added capability: UserInput/dtmf
<5>
2006/10/22 15:51:09.593 1:52.330 H225 Calle...er:2c2e668
h323caps.cxx(2161) H323 Added capability: UserInput/RFC2833
<6>
2006/10/22 15:51:09.593 1:52.330 H225 Calle...er:2c2e668
h323caps.cxx(2246) H323 FindCapability:
"UserInput/RFC2833"
2006/10/22 15:51:09.593 1:52.330 H225 Calle...er:2c2e668
h323caps.cxx(2255) H323 Found capability: UserInput/RFC2833
<6>
2006/10/22 15:51:09.593 1:52.330 H225 Calle...er:2c2e668
call.cxx(461) Call GetOtherPartyConnection
Call[2]-EP<h323>[localhost/29130]
2006/10/22 15:51:09.593 1:52.330 H225 Calle...er:2c2e668
connection.cxx(942) OpalCon GetMediaInformation for session
1 - no channel.
2006/10/22 15:51:09.593 1:52.330 H225 Calle...er:2c2e668
h323caps.cxx(2181) H323 Removing capability:
UserInput/RFC2833 <6>
2006/10/22 15:51:09.593 1:52.330 H225 Calle...er:2c2e668
h323.cxx(3325) H323 SetLocalCapabilities:
Table:
G.711-uLaw-64k <1>
G.711-ALaw-64k <2>
UserInput/hookflash <3>
UserInput/basicString <4>
UserInput/dtmf <5>
Set:
0:
0:
G.711-uLaw-64k <1>
G.711-ALaw-64k <2>
1:
UserInput/hookflash <3>
2:
UserInput/basicString <4>
UserInput/dtmf <5>
2006/10/22 15:51:09.593 1:52.330 H225 Calle...er:2c2e668
h323.cxx(1865) H225 Check for Fast start by local endpoint
2006/10/22 15:51:09.593 1:52.330 H225 Calle...er:2c2e668
h323.cxx(3539) H245 Default OnSelectLogicalChannels,
FastStartDisabled
2006/10/22 15:51:09.593 1:52.330 H225 Calle...er:2c2e668
h323caps.cxx(2282) H323 FindCapability: G.711-uLaw-64k
<1>
2006/10/22 15:51:09.593 1:52.330 H225 Calle...er:2c2e668
h323caps.cxx(2282) H323 FindCapability: G.711-ALaw-64k
<2>
2006/10/22 15:51:09.593 1:52.331 H225 Calle...er:2c2e668
h323pdu.cxx(566) H225 Sending PDU:
{
q931pdu = {
protocolDiscriminator = 8
callReference = 29130
from = originator
messageType = Setup
IE: Bearer-Capability = {
80 90 a5
...
}
IE: Display = {
49 6e 66 69 6e 69 74 79 00
Infinity.
}
IE: Called-Party-Number = {
81 31 32 33
.123
}
IE: User-User = {
20 b8 06 00 08 91 4a 00 06 01 40 0b 00 49 00 6e
.....J.....I.n
00 66 00 69 00 6e 00 69 00 74 00 79 00 20 00 33
.f.i.n.i.t.y. .3
...
}
}
h225pdu = {
h323_uu_pdu = {
h323_message_body = setup {
protocolIdentifier = 0.0.8.2250.0.6
sourceAddress = 1 entries {
[0]=h323_ID 12 characters {
0049 006e 0066 0069 006e 0069 0074 0079
Infinity
0020 0033 002e 0030
3.0
}
}
sourceInfo = {
vendor = {
vendor = {
t35CountryCode = 9
t35Extension = 0
manufacturerCode = 61
}
productId = 3 octets {
00 00 00
...
}
versionId = 21 octets {
31 2e 30 2e 31 20 28 4f 50 41 4c 20 76 32
2e 33 1.0.1
(OPAL v2.3
2e 32 29 00 00
.2)..
}
}
terminal = {
}
mc = FALSE
undefinedNode = FALSE
}
destinationAddress = 1 entries {
[0]=dialedDigits "123"
}
destCallSignalAddress = ipAddress {
ip = 4 octets {
c2 87 16 c9
....
}
port = 1720
}
activeMC = FALSE
conferenceID = 16 octets {
da 45 b0 a2 13 f7 18 10 81 d4 00 13 d4 5c b9
cb
.E.............
}
conferenceGoal = create <<null>>
callType = pointToPoint <<null>>
sourceCallSignalAddress = ipAddress {
ip = 4 octets {
c2 87 16 01
....
}
port = 1151
}
callIdentifier = {
guid = 16 octets {
da 45 b0 a2 13 f7 18 10 81 d3 00 13 d4 5c b9
cb
.E.............
}
}
mediaWaitForConnect = FALSE
canOverlapSend = FALSE
multipleCalls = FALSE
maintainConnection = FALSE
}
h245Tunneling = FALSE
}
}
}
2006/10/22 15:51:09.593 1:52.340 H225 Calle...er:2c2e668
connection.cxx(1331) OpalCon SetPhase from SetUpPhase to
AlertingPhase
2006/10/22 15:51:09.593 1:52.340 H225 Calle...er:2c2e668
h323.cxx(530) H225 Reading PDUs: callRef=29130
2006/10/22 15:51:09.671 1:52.407 H225 Calle...er:2c2e668
h323pdu.cxx(566) H225 Receiving PDU:
{
q931pdu = {
protocolDiscriminator = 8
callReference = 29130
from = destination
messageType = CallProceeding
IE: User-User = {
21 c0 06 00 08 91 4a 00 02 20 c0 b5 00 00 12 12
!.....J.. ......
43 69 73 63 6f 20 49 50 20 50 68 6f 6e 65 20 37
Cisco IP Phone 7
...
}
}
h225pdu = {
h323_uu_pdu = {
h323_message_body = callProceeding {
protocolIdentifier = 0.0.8.2250.0.2
destinationInfo = {
vendor = {
vendor = {
t35CountryCode = 181
t35Extension = 0
manufacturerCode = 18
}
productId = 19 octets {
43 69 73 63 6f 20 49 50 20 50 68 6f 6e 65
20 37 Cisco
IP Phone 7
39 30 35
905
}
versionId = 30 octets {
76 3a 30 78 30 34 30 30 30 31 30 30 20 66
3a 2e
v:0x04000100 f:.
68 33 32 33 20 62 3a 30 34 30 39 32 37 41
h323
b:040927A
}
}
mc = FALSE
undefinedNode = FALSE
}
h245Address = ipAddress {
ip = 4 octets {
c2 87 16 c9
....
}
port = 1740
}
callIdentifier = {
guid = 16 octets {
da 45 b0 a2 13 f7 18 10 81 d3 00 13 d4 5c b9
cb
.E.............
}
}
multipleCalls = FALSE
maintainConnection = FALSE
}
h245Tunneling = FALSE
}
}
}
2006/10/22 15:51:09.671 1:52.407 H225 Calle...er:2c2e668
h323.cxx(583) H225 Handling PDU: CallProceeding
callRef=29130
2006/10/22 15:51:09.671 1:52.407 H225 Calle...er:2c2e668
h323.cxx(859) H225 Set protocol version to 2 and implying
H.245 version 3
2006/10/22 15:51:09.671 1:52.407 H225 Calle...er:2c2e668
h323.cxx(1129) H225 Set remote party name:
"194.135.22.201"
2006/10/22 15:51:09.671 1:52.408 H225 Calle...er:2c2e668
h323.cxx(1137) H225 Set remote application name: "Cisco
IP Phone
7905 v:0x04000100 f:.h323 b:040927A 181/18"
2006/10/22 15:51:09.671 1:52.408 H225 Calle...er:2c2e668
h323.cxx(3364) H323 InternalEstablishedConnectionCheck:
connectionState=AwaitingSignalConnect
fastStartState=FastStartDisabled
2006/10/22 15:51:09.703 1:52.441 H225 Calle...er:2c2e668
h323pdu.cxx(566) H225 Receiving PDU:
{
q931pdu = {
protocolDiscriminator = 8
callReference = 29130
from = destination
messageType = Alerting
IE: User-User = {
23 c0 06 00 08 91 4a 00 02 20 c0 b5 00 00 12 12
#.....J.. ......
43 69 73 63 6f 20 49 50 20 50 68 6f 6e 65 20 37
Cisco IP Phone 7
...
}
}
h225pdu = {
h323_uu_pdu = {
h323_message_body = alerting {
protocolIdentifier = 0.0.8.2250.0.2
destinationInfo = {
vendor = {
vendor = {
t35CountryCode = 181
t35Extension = 0
manufacturerCode = 18
}
productId = 19 octets {
43 69 73 63 6f 20 49 50 20 50 68 6f 6e 65
20 37 Cisco
IP Phone 7
39 30 35
905
}
versionId = 30 octets {
76 3a 30 78 30 34 30 30 30 31 30 30 20 66
3a 2e
v:0x04000100 f:.
68 33 32 33 20 62 3a 30 34 30 39 32 37 41
h323
b:040927A
}
}
mc = FALSE
undefinedNode = FALSE
}
h245Address = ipAddress {
ip = 4 octets {
c2 87 16 c9
....
}
port = 1740
}
callIdentifier = {
guid = 16 octets {
da 45 b0 a2 13 f7 18 10 81 d3 00 13 d4 5c b9
cb
.E.............
}
}
multipleCalls = FALSE
maintainConnection = FALSE
}
h245Tunneling = FALSE
}
}
}
2006/10/22 15:51:09.703 1:52.441 H225 Calle...er:2c2e668
h323.cxx(583) H225 Handling PDU: Alerting callRef=29130
2006/10/22 15:51:09.703 1:52.441 H225 Calle...er:2c2e668
h323.cxx(859) H225 Set protocol version to 2 and implying
H.245 version 3
2006/10/22 15:51:09.703 1:52.442 H225 Calle...er:2c2e668
h323.cxx(1129) H225 Set remote party name:
"194.135.22.201"
2006/10/22 15:51:09.703 1:52.442 H225 Calle...er:2c2e668
h323.cxx(1137) H225 Set remote application name: "Cisco
IP Phone
7905 v:0x04000100 f:.h323 b:040927A 181/18"
2006/10/22 15:51:09.703 1:52.442 H225 Calle...er:2c2e668
h323ep.cxx(1045) H225 Received alerting PDU.
2006/10/22 15:51:09.703 1:52.442 H225 Calle...er:2c2e668
manager.cxx(659) OpalMan OnAlerting
Call[2]-EP<h323>[localhost/29130]
2006/10/22 15:51:09.703 1:52.442 H225 Calle...er:2c2e668
call.cxx(350) Call OnAlerting
Call[2]-EP<h323>[localhost/29130]
2006/10/22 15:51:09.703 1:52.442 H225 Calle...er:2c2e668
pcss.cxx(433) PCSS SetAlerting(194.135.22.201)
2006/10/22 15:51:09.703 1:52.442 H225 Calle...er:2c2e668
h323.cxx(3364) H323 InternalEstablishedConnectionCheck:
connectionState=AwaitingSignalConnect
fastStartState=FastStartDisabled
2006/10/22 15:51:12.062 1:54.800 H225 Calle...er:2c2e668
h323pdu.cxx(566) H225 Receiving PDU:
{
q931pdu = {
protocolDiscriminator = 8
callReference = 29130
from = destination
messageType = Connect
IE: Bearer-Capability = {
80 90 a3
...
}
IE: Display = {
31 32 33
123
}
IE: User-User = {
22 c0 06 00 08 91 4a 00 02 00 c2 87 16 c9 06 cc
".....J.........
20 c0 b5 00 00 12 12 43 69 73 63 6f 20 49 50 20
......Cisco IP
...
}
}
h225pdu = {
h323_uu_pdu = {
h323_message_body = connect {
protocolIdentifier = 0.0.8.2250.0.2
h245Address = ipAddress {
ip = 4 octets {
c2 87 16 c9
....
}
port = 1740
}
destinationInfo = {
vendor = {
vendor = {
t35CountryCode = 181
t35Extension = 0
manufacturerCode = 18
}
productId = 19 octets {
43 69 73 63 6f 20 49 50 20 50 68 6f 6e 65
20 37 Cisco
IP Phone 7
39 30 35
905
}
versionId = 30 octets {
76 3a 30 78 30 34 30 30 30 31 30 30 20 66
3a 2e
v:0x04000100 f:.
68 33 32 33 20 62 3a 30 34 30 39 32 37 41
h323
b:040927A
}
}
mc = FALSE
undefinedNode = FALSE
}
conferenceID = 16 octets {
da 45 b0 a2 13 f7 18 10 81 d4 00 13 d4 5c b9
cb
.E.............
}
callIdentifier = {
guid = 16 octets {
da 45 b0 a2 13 f7 18 10 81 d3 00 13 d4 5c b9
cb
.E.............
}
}
multipleCalls = FALSE
maintainConnection = FALSE
}
h245Tunneling = FALSE
}
}
}
2006/10/22 15:51:12.062 1:54.801 H225 Calle...er:2c2e668
h323.cxx(583) H225 Handling PDU: Connect callRef=29130
2006/10/22 15:51:12.062 1:54.801 H225 Calle...er:2c2e668
connection.cxx(1331) OpalCon SetPhase from AlertingPhase to
ConnectedPhase
2006/10/22 15:51:12.062 1:54.801 H225 Calle...er:2c2e668
h323.cxx(859) H225 Set protocol version to 2 and implying
H.245 version 3
2006/10/22 15:51:12.062 1:54.801 H225 Calle...er:2c2e668
h323.cxx(1129) H225 Set remote party name: "123
[194.135.22.201]"
2006/10/22 15:51:12.062 1:54.801 H225 Calle...er:2c2e668
h323.cxx(1137) H225 Set remote application name: "Cisco
IP Phone
7905 v:0x04000100 f:.h323 b:040927A 181/18"
2006/10/22 15:51:12.062 1:54.801 H225 Calle...er:2c2e668
h323ep.cxx(1108) H225 Received connect PDU.
2006/10/22 15:51:12.062 1:54.801 H225 Calle...er:2c2e668
manager.cxx(673) OpalMan OnConnected
Call[2]-EP<h323>[localhost/29130]
2006/10/22 15:51:12.062 1:54.802 H225 Calle...er:2c2e668
call.cxx(387) Call OnConnected
Call[2]-EP<h323>[localhost/29130]
2006/10/22 15:51:12.062 1:54.802 H225 Calle...er:2c2e668
call.cxx(499) Call GetMediaFormats for
Call[2]-EP<h323>[localhost/29130]
2006/10/22 15:51:12.062 1:54.802 H225 Calle...er:2c2e668
call.cxx(510) Call OpenSourceMediaStreams for session 1 with
media
2006/10/22 15:51:12.062 1:54.802 H225 Calle...er:2c2e668
call.cxx(510) Call OpenSourceMediaStreams for session 2 with
media
2006/10/22 15:51:12.062 1:54.802 H225 Calle...er:2c2e668
call.cxx(510) Call OpenSourceMediaStreams for session 3 with
media
2006/10/22 15:51:12.062 1:54.802 H225 Calle...er:2c2e668
pcss.cxx(442) PCSS SetConnected()
2006/10/22 15:51:12.062 1:54.803 H225 Calle...er:2c2e668
call.cxx(499) Call GetMediaFormats for
Call[2]-EP<>[Plantronics Headset]
2006/10/22 15:51:12.062 1:54.803 H225 Calle...er:2c2e668
call.cxx(510) Call OpenSourceMediaStreams for session 1 with
media
2006/10/22 15:51:12.062 1:54.803 H225 Calle...er:2c2e668
call.cxx(510) Call OpenSourceMediaStreams for session 2 with
media
2006/10/22 15:51:12.062 1:54.803 H225 Calle...er:2c2e668
call.cxx(510) Call OpenSourceMediaStreams for session 3 with
media
2006/10/22 15:51:12.062 1:54.803 H225 Calle...er:2c2e668
h323.cxx(3364) H323 InternalEstablishedConnectionCheck:
connectionState=HasExecutedSignalConnect
fastStartState=FastStartDisabled
2006/10/22 15:51:12.062 1:54.803 H225 Calle...er:2c2e668
h323.cxx(1348) H225 No H245 address provided by remote,
starting control
channel
2006/10/22 15:51:12.062 1:54.805 Opal Liste...er:3a319a8
transports.cxx(961) Listen Started listening thread on
tcp$194.135.22.1:1152
2006/10/22 15:51:12.062 1:54.806 H225 Calle...er:2c2e668
h323pdu.cxx(566) H225 Sending PDU:
{
q931pdu = {
protocolDiscriminator = 8
callReference = 29130
from = originator
messageType = Facility
IE: Facility = {
}
IE: User-User = {
26 80 06 00 08 91 4a 00 02 81 01 00 13 05 80 11
&.....J.........
00 da 45 b0 a2 13 f7 18 10 81 d3 00 13 d4 5c b9
..E............
...
}
}
h225pdu = {
h323_uu_pdu = {
h323_message_body = facility {
protocolIdentifier = 0.0.8.2250.0.2
reason = startH245 <<null>>
callIdentifier = {
guid = 16 octets {
da 45 b0 a2 13 f7 18 10 81 d3 00 13 d4 5c b9
cb
.E.............
}
}
h245Address = ipAddress {
ip = 4 octets {
c2 87 16 01
....
}
port = 1152
}
multipleCalls = FALSE
maintainConnection = FALSE
}
h245Tunneling = FALSE
}
}
}
2006/10/22 15:51:12.062 1:54.806 Opal Liste...er:3a319a8
transports.cxx(1187) Listen Waiting on socket accept on
tcp$194.135.22.1:1152
2006/10/22 15:51:12.062 1:54.806 H225 Calle...er:2c2e668
h323.cxx(3364) H323 InternalEstablishedConnectionCheck:
connectionState=HasExecutedSignalConnect
fastStartState=FastStartDisabled
2006/10/22 15:51:12.125 1:54.866 Opal Liste...er:3a319a8
transports.cxx(1664) OpalTCP Started connection to
194.135.22.201:15064
(if=194.135.22.1:1152)
2006/10/22 15:51:12.125 1:54.867 Opal Liste...er:3a319a8
h323.cxx(2491) H245 Started control channel
2006/10/22 15:51:12.125 1:54.868 Opal Liste...er:3a319a8
call.cxx(499) Call GetMediaFormats for
Call[2]-EP<h323>[localhost/29130]
G.711-uLaw-64k
G.711-ALaw-64k
PCM-16
YUV420P
RGB32
RGB24
2006/10/22 15:51:12.125 1:54.868 Opal Liste...er:3a319a8
h323caps.cxx(2181) H323 Removing capability:
UserInput/hookflash <3>
2006/10/22 15:51:12.125 1:54.868 Opal Liste...er:3a319a8
h323caps.cxx(2181) H323 Removing capability:
UserInput/basicString <4>
2006/10/22 15:51:12.125 1:54.868 Opal Liste...er:3a319a8
h323caps.cxx(2181) H323 Removing capability: UserInput/dtmf
<5>
2006/10/22 15:51:12.125 1:54.868 Opal Liste...er:3a319a8
h323caps.cxx(2246) H323 FindCapability:
"G.711-uLaw-64k"
2006/10/22 15:51:12.125 1:54.868 Opal Liste...er:3a319a8
h323caps.cxx(2255) H323 Found capability: G.711-uLaw-64k
<1>
2006/10/22 15:51:12.125 1:54.868 Opal Liste...er:3a319a8
h323caps.cxx(2246) H323 FindCapability:
"G.711-ALaw-64k"
2006/10/22 15:51:12.125 1:54.868 Opal Liste...er:3a319a8
h323caps.cxx(2255) H323 Found capability: G.711-ALaw-64k
<2>
2006/10/22 15:51:12.125 1:54.869 Opal Liste...er:3a319a8
h323caps.cxx(2161) H323 Added capability:
UserInput/hookflash <3>
2006/10/22 15:51:12.125 1:54.869 Opal Liste...er:3a319a8
h323caps.cxx(2161) H323 Added capability:
UserInput/basicString <4>
2006/10/22 15:51:12.125 1:54.869 Opal Liste...er:3a319a8
h323caps.cxx(2161) H323 Added capability: UserInput/dtmf
<5>
2006/10/22 15:51:12.125 1:54.869 Opal Liste...er:3a319a8
h323caps.cxx(2161) H323 Added capability: UserInput/RFC2833
<6>
2006/10/22 15:51:12.125 1:54.869 Opal Liste...er:3a319a8
h323caps.cxx(2246) H323 FindCapability:
"UserInput/RFC2833"
2006/10/22 15:51:12.125 1:54.869 Opal Liste...er:3a319a8
h323caps.cxx(2255) H323 Found capability: UserInput/RFC2833
<6>
2006/10/22 15:51:12.125 1:54.869 Opal Liste...er:3a319a8
call.cxx(461) Call GetOtherPartyConnection
Call[2]-EP<h323>[localhost/29130]
2006/10/22 15:51:12.125 1:54.869 Opal Liste...er:3a319a8
connection.cxx(942) OpalCon GetMediaInformation for session
1 - no channel.
2006/10/22 15:51:12.125 1:54.869 Opal Liste...er:3a319a8
h323caps.cxx(2181) H323 Removing capability:
UserInput/RFC2833 <6>
2006/10/22 15:51:12.125 1:54.870 Opal Liste...er:3a319a8
h323.cxx(3325) H323 SetLocalCapabilities:
Table:
G.711-uLaw-64k <1>
G.711-ALaw-64k <2>
UserInput/hookflash <3>
UserInput/basicString <4>
UserInput/dtmf <5>
Set:
0:
0:
G.711-uLaw-64k <1>
G.711-ALaw-64k <2>
1:
UserInput/hookflash <3>
2:
UserInput/basicString <4>
UserInput/dtmf <5>
2006/10/22 15:51:12.125 1:54.870 Opal Liste...er:3a319a8
h323neg.cxx(584) H245 Sending TerminalCapabilitySet:
outSeq=1
2006/10/22 15:51:12.125 1:54.870 Opal Liste...er:3a319a8
h323caps.cxx(2246) H323 FindCapability:
"UserInput/RFC2833"
2006/10/22 15:51:12.125 1:54.870 Opal Liste...er:3a319a8
h323pdu.cxx(566) H245 Sending PDU:
request terminalCapabilitySet {
sequenceNumber = 1
protocolIdentifier = 0.0.8.245.0.13
multiplexCapability = h2250Capability {
maximumAudioDelayJitter = 250
receiveMultipointCapability = {
multicastCapability = FALSE
multiUniCastConference = FALSE
mediaDistributionCapability = 1 entries {
[0]={
centralizedControl = FALSE
distributedControl = FALSE
centralizedAudio = FALSE
distributedAudio = FALSE
centralizedVideo = FALSE
distributedVideo = FALSE
}
}
}
transmitMultipointCapability = {
multicastCapability = FALSE
multiUniCastConference = FALSE
mediaDistributionCapability = 1 entries {
[0]={
centralizedControl = FALSE
distributedControl = FALSE
centralizedAudio = FALSE
distributedAudio = 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
mediaPacketizationCapability = {
h261aVideoPacketization = FALSE
}
logicalChannelSwitchingCapability = FALSE
t120DynamicPortCapability = TRUE
}
capabilityTable = 5 entries {
[0]={
capabilityTableEntryNumber = 1
capability = receiveAudioCapability g711Ulaw64k 240
}
[1]={
capabilityTableEntryNumber = 2
capability = receiveAudioCapability g711Alaw64k 240
}
[2]={
capabilityTableEntryNumber = 3
capability = receiveUserInputCapability hookflash
<<null>>
}
[3]={
capabilityTableEntryNumber = 4
capability = receiveUserInputCapability basicString
<<null>>
}
[4]={
capabilityTableEntryNumber = 5
capability = receiveUserInputCapability dtmf
<<null>>
}
}
capabilityDescriptors = 1 entries {
[0]={
capabilityDescriptorNumber = 1
simultaneousCapabilities = 3 entries {
[0]=2 entries {
[0]=1
[1]=2
}
[1]=1 entries {
[0]=3
}
[2]=2 entries {
[0]=4
[1]=5
}
}
}
}
}
2006/10/22 15:51:12.140 1:54.880 Opal Liste...er:3a319a8
h323neg.cxx(352) H245 Sending MasterSlaveDetermination
2006/10/22 15:51:12.140 1:54.880 Opal Liste...er:3a319a8
h323pdu.cxx(566) H245 Sending PDU:
request masterSlaveDetermination {
terminalType = 50
statusDeterminationNumber = 7907564
}
2006/10/22 15:51:12.187 1:54.931 Opal Liste...er:3a319a8
h323.cxx(2549) H245 Received TPKT: size=4 pos=0.0 {
22 00 01 00
"
}
2006/10/22 15:51:12.187 1:54.931 Opal Liste...er:3a319a8
h323pdu.cxx(566) H245 Receiving PDU:
response terminalCapabilitySetReject {
sequenceNumber = 1
cause = unspecified <<null>>
}
2006/10/22 15:51:12.187 1:54.931 Opal Liste...er:3a319a8
h323neg.cxx(677) H245 Received TerminalCapabilitySetReject:
state=InProgress pduSeq=1 outSeq=1
2006/10/22 15:51:12.187 1:54.932 Opal Liste...er:3a319a8
h323.cxx(3364) H323 InternalEstablishedConnectionCheck:
connectionState=HasExecutedSignalConnect
fastStartState=FastStartDisabled
2006/10/22 15:51:12.218 1:54.959 Opal Liste...er:3a319a8
h323.cxx(2549) H245 Received TPKT: size=51 pos=0.0 {
02 70 01 06 00 08 81 75 00 03 80 10 80 00 96 00 p
u
00 00 00 00 00 00 19 80 01 00 01 00 01 80 00 00
85 01 10 80 00 01 85 01 40 00 80 01 01 ff 01 00
00 00 01
}
2006/10/22 15:51:12.218 1:54.960 Opal Liste...er:3a319a8
h323.cxx(2605) H245 Invalid PDU decode!
Raw PDU:
size=33 pos=2e.0 {
000 02 70 01 06 00 08 81 75 00 03 80 10 80 00 96 00 p
u
00 00 00 00 00 00 19 80 01 00 01 00 01 80 00 00
85 01 10 80 00 01 85 01 40 00 80 01 01 ff 01 00
00 00 01
}
Partial PDU:
request terminalCapabilitySet {
sequenceNumber = 1
protocolIdentifier = 0.0.8.245.0.3
multiplexCapability = h2250Capability {
maximumAudioDelayJitter = 150
receiveMultipointCapability = {
multicastCapability = FALSE
multiUniCastConference = FALSE
mediaDistributionCapability = 0 entries {
}
}
transmitMultipointCapability = {
multicastCapability = FALSE
multiUniCastConference = FALSE
mediaDistributionCapability = 0 entries {
}
}
receiveAndTransmitMultipointCapability = {
multicastCapability = FALSE
multiUniCastConference = FALSE
mediaDistributionCapability = 0 entries {
}
}
mcCapability = {
centralizedConferenceMC = FALSE
decentralizedConferenceMC = FALSE
}
rtcpVideoControlCapability = FALSE
mediaPacketizationCapability = {
h261aVideoPacketization = FALSE
}
logicalChannelSwitchingCapability = FALSE
t120DynamicPortCapability = FALSE
}
capabilityTable = 2 entries {
[0]={
capabilityTableEntryNumber = 1
capability = receiveAndTransmitUserInputCapability
basicString
<<null>>
}
[1]={
capabilityTableEntryNumber = 2
capability = receiveAndTransmitUserInputCapability
dtmf <<null>>
}
}
capabilityDescriptors = 1 entries {
[0]={
capabilityDescriptorNumber = 1
simultaneousCapabilities = 2 entries {
[0]=0 entries {
}
[1]=1 entries {
[0]=1
}
}
}
}
}
2006/10/22 15:51:12.234 1:54.971 Opal Liste...er:3a319a8
h323.cxx(2549) H245 Received TPKT: size=2 pos=0.0 {
20 a0
}
2006/10/22 15:51:12.234 1:54.971 Opal Liste...er:3a319a8
h323pdu.cxx(566) H245 Receiving PDU:
response masterSlaveDeterminationAck {
decision = slave <<null>>
}
2006/10/22 15:51:12.234 1:54.971 Opal Liste...er:3a319a8
h323neg.cxx(441) H245 Received MasterSlaveDeterminationAck:
state=Outgoing
2006/10/22 15:51:12.234 1:54.971 Opal Liste...er:3a319a8
h323neg.cxx(458) H245 MasterSlaveDetermination: remote is
master
2006/10/22 15:51:12.234 1:54.971 Opal Liste...er:3a319a8
h323pdu.cxx(566) H245 Sending PDU:
response masterSlaveDeterminationAck {
decision = master <<null>>
}
2006/10/22 15:51:12.234 1:54.972 Opal Liste...er:3a319a8
h323.cxx(3364) H323 InternalEstablishedConnectionCheck:
connectionState=HasExecutedSignalConnect
fastStartState=FastStartDisabled
2006/10/22 15:51:16.250 1:58.985 Opal Liste...er:3a319a8
h323.cxx(2549) H245 Received TPKT: size=2 pos=0.0 {
4a 40
J
}
2006/10/22 15:51:16.250 1:58.985 Opal Liste...er:3a319a8
h323pdu.cxx(566) H245 Receiving PDU:
command endSessionCommand disconnect <<null>>
2006/10/22 15:51:16.250 1:58.985 Opal Liste...er:3a319a8
connection.cxx(1331) OpalCon SetPhase from ConnectedPhase to
ReleasingPhase
2006/10/22 15:51:16.250 1:58.985 Opal Liste...er:3a319a8
connection.cxx(588) OpalCon Releasing
Call[2]-EP<h323>[localhost/29130]
2006/10/22 15:51:16.250 1:58.985 Opal Liste...er:3a319a8
connection.cxx(541) OpalCon Call end reason for
localhost/29130 set to
EndedByRefusal
2006/10/22 15:51:16.250 1:58.985 Opal Liste...er:3a319a8
h323.cxx(2570) H245 Control channel closed.
2006/10/22 15:51:16.250 1:58.989 OnRelease:...se:3a43c28
connection.cxx(617) OpalCon OnReleased
Call[2]-EP<h323>[localhost/29130]
2006/10/22 15:51:16.250 1:58.989 OnRelease:...se:3a43c28
connection.cxx(813) OpalCon Media stream threads closed.
2006/10/22 15:51:16.250 1:58.989 OnRelease:...se:3a43c28
endpoint.cxx(419) OpalEP OnReleased
Call[2]-EP<h323>[localhost/29130]
2006/10/22 15:51:16.250 1:58.989 OnRelease:...se:3a43c28
manager.cxx(689) OpalMan OnReleased
Call[2]-EP<h323>[localhost/29130]
2006/10/22 15:51:16.250 1:58.989 OnRelease:...se:3a43c28
call.cxx(645) Call OnReleased
Call[2]-EP<h323>[localhost/29130]
2006/10/22 15:51:16.250 1:58.989 OnRelease:...se:3a43c28
connection.cxx(1331) OpalCon SetPhase from ConnectedPhase to
ReleasingPhase
2006/10/22 15:51:16.250 1:58.989 OnRelease:...se:3a43c28
connection.cxx(588) OpalCon Releasing
Call[2]-EP<>[Plantronics Headset]
2006/10/22 15:51:16.250 1:58.989 OnRelease:...se:3a43c28
connection.cxx(541) OpalCon Call end reason for Plantronics
Headset set to
EndedByRefusal
2006/10/22 15:51:16.250 1:58.990 OnRelease:...se:3a43c28
h323.cxx(394) H323 Connection localhost/29130 closing:
connectionState=HasExecutedSignalConnect
2006/10/22 15:51:16.250 1:58.990 OnRelease:...se:3a43c28
h323.cxx(401) H225 Sending release complete PDU:
callRef=29130
2006/10/22 15:51:16.250 1:58.990 OnRelease:...se:3a43c28
h323pdu.cxx(566) H245 Sending PDU:
command endSessionCommand disconnect <<null>>
2006/10/22 15:51:16.250 1:58.991 OnRelease:...se:3a43c28
h323pdu.cxx(566) H225 Sending PDU:
{
q931pdu = {
protocolDiscriminator = 8
callReference = 29130
from = originator
messageType = ReleaseComplete
IE: User-User = {
25 c0 06 00 08 91 4a 00 02 18 08 11 00 da 45 b0
%.....J.......E.
a2 13 f7 18 10 81 d3 00 13 d4 5c b9 cb 02 80 01
...............
...
}
}
h225pdu = {
h323_uu_pdu = {
h323_message_body = releaseComplete {
protocolIdentifier = 0.0.8.2250.0.2
reason = destinationRejection
<<null>>
callIdentifier = {
guid = 16 octets {
da 45 b0 a2 13 f7 18 10 81 d3 00 13 d4 5c b9
cb
.E.............
}
}
}
h245Tunneling = FALSE
}
}
}
2006/10/22 15:51:16.250 1:58.991 OnRelease:...se:3a43c28
h323.cxx(456) H323 Awaiting end session from remote for
10.000 seconds
2006/10/22 15:51:16.250 1:58.991 OnRelease:...se:3a43c28
connection.cxx(1331) OpalCon SetPhase from ReleasingPhase to
ReleasedPhase
2006/10/22 15:51:16.250 1:58.991 OnRelease:...se:3a43c28
transports.cxx(1376) Opal Transport clean up on termination
2006/10/22 15:51:16.250 1:58.991 OnRelease:...se:3a43c28
transports.cxx(1361) Opal Transport Close
2006/10/22 15:51:16.250 1:58.992 OnRelease:...se:3a3ac78
connection.cxx(617) OpalCon OnReleased
Call[2]-EP<>[Plantronics Headset]
2006/10/22 15:51:16.250 1:58.992 OnRelease:...se:3a3ac78
connection.cxx(813) OpalCon Media stream threads closed.
2006/10/22 15:51:16.250 1:58.992 OnRelease:...se:3a3ac78
endpoint.cxx(419) OpalEP OnReleased
Call[2]-EP<>[Plantronics Headset]
2006/10/22 15:51:16.250 1:58.992 OnRelease:...se:3a3ac78
manager.cxx(689) OpalMan OnReleased
Call[2]-EP<>[Plantronics Headset]
2006/10/22 15:51:16.250 1:58.992 OnRelease:...se:3a3ac78
call.cxx(645) Call OnReleased Call[2]-EP<>[Plantronics
Headset]
2006/10/22 15:51:16.250 1:58.992 OnRelease:...se:3a3ac78
manager.cxx(558) OpalMan OnClearedCall "" to
"194.135.22.201"
2006/10/22 15:51:16.250 1:58.992 OnRelease:...se:3a3ac78
connection.cxx(608) OpalCon OnRelease thread completed for
Plantronics
Headset
2006/10/22 15:51:16.250 1:58.997 H225 Calle...er:2c2e668
h323pdu.cxx(566) H225 Receiving PDU:
{
q931pdu = {
protocolDiscriminator = 8
callReference = 29130
from = destination
messageType = ReleaseComplete
IE: Cause - Normal call clearing = {
80 90
..
}
IE: User-User = {
25 80 06 00 08 91 4a 00 02 01 11 00 da 45 b0 a2
%.....J......E..
13 f7 18 10 81 d3 00 13 d4 5c b9 cb 02 80 01 00
...............
}
}
h225pdu = {
h323_uu_pdu = {
h323_message_body = releaseComplete {
protocolIdentifier = 0.0.8.2250.0.2
callIdentifier = {
guid = 16 octets {
da 45 b0 a2 13 f7 18 10 81 d3 00 13 d4 5c b9
cb
.E.............
}
}
}
h245Tunneling = FALSE
}
}
}
2006/10/22 15:51:16.250 1:58.997 H225 Calle...er:2c2e668
h323.cxx(583) H225 Handling PDU: ReleaseComplete
callRef=29130
2006/10/22 15:51:16.250 1:58.997 H225 Calle...er:2c2e668
connection.cxx(575) OpalCon Already released
Call[2]-EP<h323>[localhost/29130]
2006/10/22 15:51:16.250 1:58.997 H225 Calle...er:2c2e668
h323.cxx(573) H225 Signal channel closed.
2006/10/22 15:51:16.281 1:59.016 OpalGarbage
pcss.cxx(389) PCSS Deleted PC sound system connection.
2006/10/22 15:51:16.281 1:59.016 OpalGarbage
connection.cxx(505) OpalCon Connection
Call[2]-EP<>[Plantronics Headset]
destroyed.
2006/10/22 15:51:19.312 2:02.047 OnRelease:...se:3a43c28
transports.cxx(1376) Opal Transport clean up on termination
2006/10/22 15:51:19.312 2:02.047 OnRelease:...se:3a43c28
transports.cxx(1361) Opal Transport Close
2006/10/22 15:51:19.312 2:02.047 OnRelease:...se:3a43c28
h323.cxx(472) H323 Connection localhost/29130 terminated.
2006/10/22 15:51:19.312 2:02.049 OnRelease:...se:3a43c28
connection.cxx(608) OpalCon OnRelease thread completed for
localhost/29130
2006/10/22 15:51:20.281 2:03.016 OpalGarbage
transports.cxx(1376) Opal Transport clean up on termination
2006/10/22 15:51:20.281 2:03.016 OpalGarbage
transports.cxx(1361) Opal Transport Close
2006/10/22 15:51:20.281 2:03.016 OpalGarbage
transports.cxx(1509) Opal Deleted transport
tcp$194.135.22.201:1720<if=tcp$194.135.22.1:1151>
2006/10/22 15:51:20.281 2:03.016 OpalGarbage
transports.cxx(1376) Opal Transport clean up on termination
2006/10/22 15:51:20.281 2:03.016 OpalGarbage
transports.cxx(1361) Opal Transport Close
2006/10/22 15:51:20.281 2:03.016 OpalGarbage
transports.cxx(1509) Opal Deleted transport
tcp$194.135.22.201:15064<if=tcp$194.135.22.1:1152>
2006/10/22 15:51:20.281 2:03.017 OpalGarbage
h323.cxx(380) H323 Connection localhost/29130 deleted.
2006/10/22 15:51:20.281 2:03.017 OpalGarbage
connection.cxx(505) OpalCon Connection
Call[2]-EP<h323>[localhost/29130]
destroyed.
2006/10/22 15:51:21.515 2:04.262 OpalGarbage
call.cxx(265) Call Call[2] destroyed.
2006/10/22 15:52:12.515 2:55.255 ThreadID=0x00001410
pcss.cxx(213) PCSS Deleted PC sound system endpoint.
2006/10/22 15:52:12.515 2:55.255 ThreadID=0x00001410
endpoint.cxx(234) OpalEP endpoint destroyed.
2006/10/22 15:52:12.515 2:55.256 ThreadID=0x00001410
transports.cxx(947) Listen Stopping listening thread on
tcp$194.135.22.1:1720
2006/10/22 15:52:12.515 2:55.256 ThreadID=0x00001410
h323ep.cxx(206) H323 Deleted endpoint.
2006/10/22 15:52:12.515 2:55.257 ThreadID=0x00001410
endpoint.cxx(234) OpalEP h323 endpoint destroyed.
2006/10/22 15:52:12.515 2:55.257 ThreadID=0x00001410
manager.cxx(415) OpalMan Deleted manager.
What is the reason of this problem?
Thank you,
Ilya Pleshchinskii,
Inteltelecom ltd,
Moscow, Russia
ilyainteltelecom.ru
------------------------------------------------------------
------------
Check the FAQ before asking! - http://www.
openh323.org/~openh323/fom.cgi
The OpenH323 Project mailing list, using Mailman. To
unsubscribe or
change your subscription options, goto
htt
p://www.openh323.org/mailman/listinfo/openh323
Maintained by Quicknet Technologies, Inc - http://www.quicknet.net
------------------------------------------------------------
------------
|