[Asterisk-Dev] Netmeeting connection not established

by way of asterchris at habmalnefrage.de asterchris at habmalnefrage.de
Thu Sep 18 10:55:25 MST 2003


hello *

since the cvs upgrade version ~ 1 Sep I cannot establish a connection to
netmeeting.

1.) E.g.
exten => 1234,1,Dial,H323/192.168.0.10

If I dial 1234 with capi or another netmeeting or openphone, first the
called netmeeting rings. When I pickup netmeeting I still get a ringtone in my
calling phone respectively see the calling openphone client trying to establish
a connection. The called netmeeting tells me that a connection is established
as well as asterisk.

2.) If I use openphone instead, everything works fine. I pickup openphone
and I can talk.

3.) Ok, what I found is that if I specify
exten => 1234,1,Dial,H323/192.168.0.10|10
and then dial 1234 netmeeting rings and if I pickup I get the same result as
in 1.) but then after the timeout (10 seconds) asterisk displays "Nobody
picked up after 10000ms" and then the connection is established.

4.) If I declare the following extensions:

exten => 1234,1,Answer
exten => 1234,2,Dial,H323/192.168.0.10

It also works. When I pickup the called netmeeting the connection is
established immediately.


So I think asterisk doesn't realize when the called party picks up the
phone. But as I said this problem only occurs with use of netmeeting, not with
openphone! And also I tried different netmeeting clients on different
workstations to be sure that netmeeting isn't broken.

I use the recommended openh323 1.11.7 and pwlib 1.4.11

I also can setup a connection using netmeeting -> gnugk -> netmeeting,
netmeeting <-> openphone.


.... H.323 Trace 4 - H.323 Debug ....
looks very similar to the working openphone Trace.

...
Capability merge result:
  Table:
    G.711-ALaw-64k{sw} <2>
    G.711-uLaw-64k{sw} <3>
    G.723.1{sw} <4>
  Set:
    0:
      0:
        G.711-uLaw-64k{sw} <3>
        G.723.1{sw} <4>
        G.711-ALaw-64k{sw} <2>
      1:
      2:
    1:
      0:
        G.711-uLaw-64k{sw} <3>
        G.723.1{sw} <4>
        G.711-ALaw-64k{sw} <2>
      1:
      2:
    2:
      0:
        G.711-uLaw-64k{sw} <3>
        G.723.1{sw} <4>
        G.711-ALaw-64k{sw} <2>
      1:
      2:
    3:
      0:
        G.711-uLaw-64k{sw} <3>
        G.723.1{sw} <4>
        G.711-ALaw-64k{sw} <2>
      1:
      2:
    4:
      0:
        G.711-uLaw-64k{sw} <3>
        G.723.1{sw} <4>
        G.711-ALaw-64k{sw} <2>
      1:
      2:

  5:26.602                 H245:812e080     h323caps.cxx(1981)  H245   
Received capability set, is accepted
  5:26.602                 H245:812e080      h323neg.cxx(535)   H245   
TerminalCapabilitySet already in progress: outSeq=2
  5:26.604                 H245:812e080      h323pdu.cxx(474)   H245   
Sending PDU:
  response terminalCapabilitySetAck {
    sequenceNumber = 1
  }
  5:26.606                 H245:812e080         h323.cxx(3876)  H323   
InternalEstablishedConnectionCheck: connectionState=HasExecutedSignalConnect
fastStartState=FastStartDisabled
  5:26.611                 H245:812e080         h323.cxx(3163)  H245   
Received TPKT:  size=7 pos=0.0 {
     01 00 32 80 01 c5 a8                                2
   }
  5:26.613                 H245:812e080      h323pdu.cxx(474)   H245   
Receiving PDU:
  request masterSlaveDetermination {
    terminalType = 50
    statusDeterminationNumber = 116136
  }
  5:26.615                 H245:812e080      h323neg.cxx(348)   H245   
Received MasterSlaveDetermination: state=Outgoing
  5:26.616                 H245:812e080      h323neg.cxx(379)   H245   
MasterSlaveDetermination: local is master
  5:26.618                 H245:812e080      h323pdu.cxx(474)   H245   
Sending PDU:
  response masterSlaveDeterminationAck {
    decision = slave <<null>>
  }
  5:26.619                 H245:812e080         h323.cxx(3876)  H323   
InternalEstablishedConnectionCheck: connectionState=HasExecutedSignalConnect
fastStartState=FastStartDisabled
  5:26.621                 H245:812e080         h323.cxx(3163)  H245   
Received TPKT:  size=3 pos=0.0 {
     21 80 02                                          !
   }
  5:26.623                 H245:812e080      h323pdu.cxx(474)   H245   
Receiving PDU:
  response terminalCapabilitySetAck {
    sequenceNumber = 2
  }
  5:26.624                 H245:812e080      h323neg.cxx(620)   H245   
Received TerminalCapabilitySetAck: state=InProgress pduSeq=2 outSeq=2
  5:26.625                 H245:812e080      h323neg.cxx(630)   H245   
TerminalCapabilitySet Sent.
  5:26.625                 H245:812e080         h323.cxx(3876)  H323   
InternalEstablishedConnectionCheck: connectionState=HasExecutedSignalConnect
fastStartState=FastStartDisabled
  5:26.951                 H245:812e080         h323.cxx(3163)  H245   
Received TPKT:  size=2 pos=0.0 {
     20 80
   }
  5:26.953                 H245:812e080      h323pdu.cxx(474)   H245   
Receiving PDU:
  response masterSlaveDeterminationAck {
    decision = master <<null>>
  }
  5:26.954                 H245:812e080      h323neg.cxx(406)   H245   
Received MasterSlaveDeterminationAck: state=Incoming
  5:26.955                 H245:812e080         h323.cxx(3876)  H323   
InternalEstablishedConnectionCheck: connectionState=HasExecutedSignalConnect
fastStartState=FastStartDisabled
  5:26.956                 H245:812e080     h323caps.cxx(1699)  H323   
FindCapability: "T.120"
  5:26.957                 H245:812e080         h323.cxx(3933)  H245   
Default OnSelectLogicalChannels, FastStartDisabled
  5:26.958                 H245:812e080     h323caps.cxx(1735)  H323   
FindCapability: G.711-uLaw-64k{sw} <1>
  5:26.958                 H245:812e080     h323caps.cxx(1739)  H323   
Found capability: G.711-uLaw-64k{sw} <3>
  5:26.959                 H245:812e080         h323.cxx(3977)  H323   
Selecting G.711-uLaw-64k{sw} <3>
  5:26.960                 H245:812e080      h323neg.cxx(743)   H245   
Opening channel: T-103
  us: 0.0.0.0:16848
them: 0.0.0.0:0
info: 0.0.0.0:16848
        =*= In CreateRealTimeLogicalChannel for call 16476
                -- externalIpAddress: 192.168.0.6
                -- externalPort: 16848
                -- SessionID: 1
                -- Direction: IsTransmitter
  5:26.963                 H245:812e080     channels.cxx(893)   H323RTP
OnSendingPDU
         -- Started logical channel: sending G.711-uLaw-64k{sw}
                -- channelsOpen = 1
                -- remoteIpAddress: 0.0.0.0
                -- remotePort: 0
                -- ExternalIpAddress: 192.168.0.6
                -- ExternalPort: 16848
  5:26.965                 H245:812e080       codecs.cxx(1074)  Codec   G711
uLaw encoder created for at 64k, frame of 240 samples
  5:26.966                 H245:812e080     channels.cxx(777)   LogChan
Bandwidth requested/used = 64.0/0.0 kb/s
  5:26.967                 H245:812e080         h323.cxx(4396)  H323   
Bandwidth request: -0.0kb/s, available: 10000.0kb/s
  5:26.968                 H245:812e080         h323.cxx(4396)  H323   
Bandwidth request: +64.0kb/s, available: 10000.0kb/s
  5:26.969                 H245:812e080      h323pdu.cxx(474)   H245   
Sending PDU:
  request openLogicalChannel {
    forwardLogicalChannelNumber = 103
    forwardLogicalChannelParameters = {
      dataType = audioData g711Ulaw64k 30
      multiplexParameters = h2250LogicalChannelParameters {
        sessionID = 1
        mediaGuaranteedDelivery = FALSE
        mediaControlChannel = unicastAddress iPAddress {
          network =  4 octets {
            c0 a8 00 06                                        ....
          }
          tsapIdentifier = 16849
        }
        silenceSuppression = FALSE
      }
    }
  }
        -- Connection Established with "a a [192.168.0.20]"
  5:26.974                 H245:812e080         h323.cxx(3163)  H245   
Received TPKT:  size=20 pos=0.0 {
     03 00 01 00 0c 60 1f 80 0b 0f 00 01 00 c0 a8 00        `
     14 c1 c9 40                                          @
   }
  5:26.979                 H245:812e080      h323pdu.cxx(474)   H245   
Receiving PDU:
  request openLogicalChannel {
    forwardLogicalChannelNumber = 257
    forwardLogicalChannelParameters = {
      dataType = audioData g711Ulaw64k 32
      multiplexParameters = h2250LogicalChannelParameters {
        sessionID = 1
        mediaGuaranteedDelivery = FALSE
        mediaControlChannel = unicastAddress iPAddress {
          network =  4 octets {
            c0 a8 00 14                                        ....
          }
          tsapIdentifier = 49609
        }
        mediaControlGuaranteedDelivery = FALSE
        silenceSuppression = TRUE
      }
    }
  }
  5:26.983                 H245:812e080      h323neg.cxx(835)   H245   
Received open channel: R-257, state=Released
  5:26.985                 H245:812e080         h323.cxx(4156)  H323   
CreateLogicalChannel - forward channel
  5:26.986                 H245:812e080     h323caps.cxx(1798)  H323   
FindCapability: audioData
  5:26.986                 H245:812e080     h323caps.cxx(1884)  H323   
FindCapability: Audio subtype=3
  5:26.988                 H245:812e080     h323caps.cxx(1890)  H323   
Found capability: G.711-uLaw-64k{sw} <1>
  5:26.989                 H245:812e080     h323caps.cxx(755)   H323   
Capability rx frames reduced from 240 to 32
  us: 0.0.0.0:16848
them: 0.0.0.0:0
info: 0.0.0.0:16848
        =*= In CreateRealTimeLogicalChannel for call 16476
                -- externalIpAddress: 192.168.0.6
                -- externalPort: 16848
                -- SessionID: 1
                -- Direction: IsReceiver
  5:26.990                 H245:812e080       codecs.cxx(1074)  Codec   G711
uLaw decoder created for at 64k, frame of 256 samples
  5:26.991                 H245:812e080     channels.cxx(777)   LogChan
Bandwidth requested/used = 64.0/0.0 kb/s
  5:26.992                 H245:812e080         h323.cxx(4396)  H323   
Bandwidth request: -0.0kb/s, available: 9936.0kb/s
  5:26.992                 H245:812e080         h323.cxx(4396)  H323   
Bandwidth request: +64.0kb/s, available: 9936.0kb/s
  5:26.993                 H245:812e080     channels.cxx(952)   H323RTP
OnReceivedPDU for channel: R-257
  5:26.994                 H245:812e080     h323caps.cxx(760)   H323   
Capability rx frames left at 32 as remote allows 32
  5:26.995                 H245:812e080     channels.cxx(921)   H323RTP
OnSendOpenAck
  5:26.996                 H245:812e080     channels.cxx(942)   H323RTP
Sending open logical channel ACK: sessionID=1
         -- Started logical channel: receiving G.711-uLaw-64k{sw}
                -- channelsOpen = 2
                -- remoteIpAddress: 192.168.0.20
                -- remotePort: 49608
                -- ExternalIpAddress: 192.168.0.6
                -- ExternalPort: 16848
  5:26.997                 H245:812e080      h323pdu.cxx(474)   H245   
Sending PDU:
  response openLogicalChannelAck {
    forwardLogicalChannelNumber = 257
    forwardMultiplexAckParameters = h2250LogicalChannelAckParameters {
      sessionID = 1
      mediaChannel = unicastAddress iPAddress {
        network =  4 octets {
          c0 a8 00 06                                        ....
        }
        tsapIdentifier = 16848
      }
      mediaControlChannel = unicastAddress iPAddress {
        network =  4 octets {
          c0 a8 00 06                                        ....
        }
        tsapIdentifier = 16849
      }
      flowControlToZero = FALSE
    }
  }
  5:27.002                 H245:812e080         h323.cxx(3876)  H323   
InternalEstablishedConnectionCheck: connectionState=EstablishedConnection
fastStartState=FastStartDisabled
  5:27.004                 H245:812e080         h323.cxx(3163)  H245   
Received TPKT:  size=23 pos=0.0 {
     22 c0 00 66 04 80 10 1c 00 00 c0 a8 00 14 c1 c8   "  f
     00 c0 a8 00 14 c1 c9
   }
  5:27.008                 H245:812e080      h323pdu.cxx(474)   H245   
Receiving PDU:
  response openLogicalChannelAck {
    forwardLogicalChannelNumber = 103
    forwardMultiplexAckParameters = h2250LogicalChannelAckParameters {
      sessionID = 1
      mediaChannel = unicastAddress iPAddress {
        network =  4 octets {
          c0 a8 00 14                                        ....
        }
        tsapIdentifier = 49608
      }
      mediaControlChannel = unicastAddress iPAddress {
        network =  4 octets {
          c0 a8 00 14                                        ....
        }
        tsapIdentifier = 49609
      }
      flowControlToZero = FALSE
    }
  }
  5:27.012                 H245:812e080      h323neg.cxx(897)   H245   
Received open channel ack: T-103, state=AwaitingEstablishment
  5:27.013                 H245:812e080     channels.cxx(998)   H323RTP
OnReceiveOpenAck
  5:27.014                 H245:812e080         h323.cxx(3876)  H323   
InternalEstablishedConnectionCheck: connectionState=EstablishedConnection
fastStartState=FastStartDisabled
    -- H323/test answered CAPI[contr1/13]/1
  5:31.692      ThreadID=0x0002c012           osutil.cxx(316)   PWLib   File
handle high water mark set: 49 Thread unblock pipe
        -- ClearCall: Request to clear call with token ip$localhost/16476
  5:31.696      ClearCallT...d:080ecb10       h323ep.cxx(1537)  H323   
Clearing connection ip$localhost/16476 reason=EndedByLocalUser
  5:31.757      ClearCallT...d:080ecb10         h323.cxx(1403)  H323    Call
end reason for ip$localhost/16476 set to EndedByLocalUser
  5:31.758      ClearCallT...d:080ecb10         h323.cxx(1421)  H225   
Sending release complete PDU: callRef=16476
        -- Sending RELEASE COMPLETE
  5:31.761      ClearCallT...d:080ecb10      h323pdu.cxx(474)   H245   
Sending PDU:
  command endSessionCommand disconnect <<null>>
  5:31.763      ClearCallT...d:080ecb10      h323pdu.cxx(474)   H225   
Sending PDU:
  {
    q931pdu = {
      protocolDiscriminator = 8
      callReference = 16476
      from = originator
      messageType = ReleaseComplete
      IE: Cause - Normal call clearing = {
        80 90                                              ..
      }
      IE: User-User = {
        25 80 06 00 08 91 4a 00  02 01 11 00 a8 25 10 67   %.....J......%.g
        a4 e7 d7 11 81 c4 00 10  5a 67 76 40 02 80 01 00   ........Zgv at ....
      }
    }
    h225pdu = {
      h323_uu_pdu = {
        h323_message_body = releaseComplete {
          protocolIdentifier = 0.0.8.2250.0.2
          callIdentifier = {
            guid =  16 octets {
              a8 25 10 67 a4 e7 d7 11  81 c4 00 10 5a 67 76 40  
.%.g........Zgv@
            }
          }
        }
        h245Tunneling = FALSE
      }
    }
  }
  5:31.776          H225 Caller:80ebb88      h323pdu.cxx(474)   H225   
Receiving PDU:
  {
    q931pdu = {
      protocolDiscriminator = 8
      callReference = 16476
      from = destination
      messageType = ReleaseComplete
      IE: Cause - 0x0 (0) = {
        00 00 90                                           ...
      }
      IE: User-User = {
        05 c0 06 00 08 91 4a 00  02 58 08 11 00 a8 25 10   ......J..X....%.
        67 a4 e7 d7 11 81 c4 00  10 5a 67 76 40            g........Zgv@
      }
    }
    h225pdu = {
      h323_uu_pdu = {
        h323_message_body = releaseComplete {
          protocolIdentifier = 0.0.8.2250.0.2
          reason = undefinedReason <<null>>
          callIdentifier = {
            guid =  16 octets {
              a8 25 10 67 a4 e7 d7 11  81 c4 00 10 5a 67 76 40  
.%.g........Zgv@
            }
          }
        }
        h245Tunneling = FALSE
      }
    }
  }
  5:31.783          H225 Caller:80ebb88         h323.cxx(1620)  H225   
Handling PDU: ReleaseComplete callRef=16476
        -- Received RELEASE COMPLETE message...
  5:31.785                 H323 Cleaner       h323ep.cxx(1594)  H323   
Cleaning up connections
  == Spawn extension (pstn, 13, 1) exited non-zero on 'CAPI[contr1/13]/1'
    -- CAPI Hangingup
...

-- 
+++ GMX - die erste Adresse für Mail, Message, More! +++

Getestet von Stiftung Warentest: GMX FreeMail (GUT), GMX ProMail (GUT)
(Heft 9/03 - 23 e-mail-Tarife: 6 gut, 12 befriedigend, 5 ausreichend)

Jetzt selbst kostenlos testen: http://www.gmx.net





More information about the asterisk-dev mailing list