Here is the offending line 2011/02/23 15:23:27.018 H225 Answer:be982700 Info H323 Available bandwidth exceeded
My Guess is that either ekiga is not setting correctly the bandwidth value in the OLC or the bandwidth setting of the MCU is too low.
Try increasing the bandwidth to 1000Mbps and try again. H323Endpoint::SetInitialBandwidth()
Simon
-----Original Message----- From: h323plus-bounces@lists.packetizer.com [mailto:h323plus-bounces@lists.packetizer.com] On Behalf Of Jérémy Subtil Sent: 24 February 2011 01:32 To: h323plus@lists.packetizer.com Subject: [h323plus] Unable to connect more than one client
Hi there,
I compiled openmcu 2.2.5 on Computer A, along with ptlib 2.8.3 and h323plus 1.22. The server starts fine with all the expected codecs, and the output is the following:
#openmcu -cd 2011/02/23 15:19:54.998 OpenMCU Message Starting service process "OpenMCU" v2.2.5 - error = 20 Daemon started with pid 2026 2011/02/23 15:19:55.006 H323 Listener:bebd6700 Info H323 Awaiting TCP connections on port 1720 2011/02/23 15:19:55.013 OpenMCU Info MCU Codecs (in preference order): Table: G.711-ALaw-64k <1> G.711-uLaw-64k <2> G.722.1-24k{sw} <3> G.722.1-32k{sw} <4> G.726-16k{sw} <5> G.726-24k{sw} <6> G.726-32k{sw} <7> G.726-40k{sw} <8> GSM-06.10{sw} <9> GSM-AMR{sw} <10> LPC-10{sw} <11> MS-GSM{sw} <12> MS-IMA-ADPCM{sw} <13> SpeexIETFNarrow-11k{sw} <14> SpeexIETFNarrow-15k{sw} <15> SpeexIETFNarrow-18.2k{sw} <16> SpeexIETFNarrow-24.6k{sw} <17> SpeexIETFNarrow-5.95k{sw} <18> SpeexIETFNarrow-8k{sw} <19> SpeexIETFWide-20.6k{sw} <20> SpeexWNarrow-8k{sw} <21> SpeexWide-20.6k{sw} <22> iLBC-13k3{sw} <23> iLBC-15k2{sw} <24> iLBC{sw} <25> H.261-CIF{sw} <26> H.263-CIF{sw} <30> H.239 Control <33> UserInput/hookflash <27> UserInput/basicString <28> UserInput/dtmf <29> UserInput/RFC2833 <31> UserInput/Navigation <32> UserInput/Softkey <34> UserInput/PointDevice <35> UserInput/Modal <36> Set: 0: 0: G.711-ALaw-64k <1> G.711-uLaw-64k <2> G.722.1-24k{sw} <3> G.722.1-32k{sw} <4> G.726-16k{sw} <5> G.726-24k{sw} <6> G.726-32k{sw} <7> G.726-40k{sw} <8> GSM-06.10{sw} <9> GSM-AMR{sw} <10> LPC-10{sw} <11> MS-GSM{sw} <12> MS-IMA-ADPCM{sw} <13> SpeexIETFNarrow-11k{sw} <14> SpeexIETFNarrow-15k{sw} <15> SpeexIETFNarrow-18.2k{sw} <16> SpeexIETFNarrow-24.6k{sw} <17> SpeexIETFNarrow-5.95k{sw} <18> SpeexIETFNarrow-8k{sw} <19> SpeexIETFWide-20.6k{sw} <20> SpeexWNarrow-8k{sw} <21> SpeexWide-20.6k{sw} <22> iLBC-13k3{sw} <23> iLBC-15k2{sw} <24> iLBC{sw} <25> 1: H.261-CIF{sw} <26> H.263-CIF{sw} <30> 2: H.239 Control <33> UserInput/hookflash <27> UserInput/basicString <28> UserInput/dtmf <29> UserInput/RFC2833 <31> UserInput/Navigation <32> UserInput/Softkey <34> UserInput/PointDevice <35> UserInput/Modal <36> 2011/02/23 15:19:55.015 OpenMCU Info Opened master socket for HTTP: 1420 2011/02/23 15:19:55.015 OpenMCU Info Service OpenMCU Started
Computer B can connect successfully with Ekiga 3 as client:
2011/02/23 15:22:34.241 H323 Listener:bebd6700 Info H323TCP Started connection: host=10.1.9.153:30000, if=10.1.9.152:1720, handle=18 2011/02/23 15:22:34.274 H225 Answer:beb54700 Info H225 Set remote application name: " 3.2.7 (OPAL v3.6.8) 9/61" 2011/02/23 15:22:34.305 H225 Answer:beb54700 Info H323 CreateLogicalChannel - unknown data type 2011/02/23 15:22:34.305 H225 Answer:beb54700 Info H323 CreateLogicalChannel - unknown data type 2011/02/23 15:22:34.306 H225 Answer:beb54700 Info RTP_UDP Session 1 created: 10.1.9.152:5000-5001 ssrc=3028627343 2011/02/23 15:22:34.306 H225 Answer:beb54700 Info RTP Adding session RTP_UDP 2011/02/23 15:22:34.306 H225 Answer:beb54700 Warning H323RTP Setting dynamic RTP payload type: 108 - error = 107 2011/02/23 15:22:34.307 H225 Answer:beb54700 Warning H323RTP Setting dynamic RTP payload type: 108 - error = 107 2011/02/23 15:22:34.308 H225 Answer:beb54700 Warning H323RTP Setting dynamic RTP payload type: 110 - error = 107 2011/02/23 15:22:34.308 H225 Answer:beb54700 Warning H323RTP Setting dynamic RTP payload type: 110 - error = 107 2011/02/23 15:22:34.309 H225 Answer:beb54700 Warning H323RTP Setting dynamic RTP payload type: 119 - error = 107 2011/02/23 15:22:34.310 H225 Answer:beb54700 Warning H323RTP Setting dynamic RTP payload type: 119 - error = 107 2011/02/23 15:22:34.310 H225 Answer:beb54700 Warning H323RTP Setting dynamic RTP payload type: 118 - error = 107 2011/02/23 15:22:34.310 H225 Answer:beb54700 Warning H323RTP Setting dynamic RTP payload type: 118 - error = 107 2011/02/23 15:22:34.311 H225 Answer:beb54700 Warning H323RTP Setting dynamic RTP payload type: 117 - error = 107 2011/02/23 15:22:34.311 H225 Answer:beb54700 Warning H323RTP Setting dynamic RTP payload type: 117 - error = 107 2011/02/23 15:22:34.311 H225 Answer:beb54700 Warning H323RTP Setting dynamic RTP payload type: 116 - error = 107 2011/02/23 15:22:34.312 H225 Answer:beb54700 Warning H323RTP Setting dynamic RTP payload type: 116 - error = 107 2011/02/23 15:22:34.313 H225 Answer:beb54700 Warning H323RTP Setting dynamic RTP payload type: 115 - error = 107 2011/02/23 15:22:34.313 H225 Answer:beb54700 Warning H323RTP Setting dynamic RTP payload type: 115 - error = 107 2011/02/23 15:22:34.314 H225 Answer:beb54700 Warning H323RTP Setting dynamic RTP payload type: 103 - error = 107 2011/02/23 15:22:34.314 H225 Answer:beb54700 Warning H323RTP Setting dynamic RTP payload type: 103 - error = 107 2011/02/23 15:22:34.314 H225 Answer:beb54700 Warning H323RTP Setting dynamic RTP payload type: 109 - error = 107 2011/02/23 15:22:34.315 H225 Answer:beb54700 Warning H323RTP Setting dynamic RTP payload type: 109 - error = 107 2011/02/23 15:22:34.316 H225 Answer:beb54700 Info RTP_UDP Session 2 created: 10.1.9.152:5002-5003 ssrc=2730609199 2011/02/23 15:22:34.316 H225 Answer:beb54700 Info RTP Adding session RTP_UDP 2011/02/23 15:22:34.326 H225 Answer:beb54700 Info H323 Available bandwidth exceeded 2011/02/23 15:22:34.326 H225 Answer:beb54700 Info H323 OnReceivedPDU gave error 7 2011/02/23 15:22:34.326 H225 Answer:beb54700 Info RTP Releasing session 2 2011/02/23 15:22:34.328 H225 Answer:beb54700 Info H323 Available bandwidth exceeded 2011/02/23 15:22:34.328 H225 Answer:beb54700 Info H323 OnReceivedPDU gave error 7 2011/02/23 15:22:34.328 H225 Answer:beb54700 Info RTP Releasing session 2 2011/02/23 15:22:34.328 H225 Answer:beb54700 Info H323 Answering call: AnswerCallNow 2011/02/23 15:22:34.328 H225 Answer:beb54700 Info H245 Default OnSelectLogicalChannels, FastStartResponse 2011/02/23 15:22:34.328 H225 Answer:beb54700 Info H323 Started sending logical channel: SpeexIETFWide-20.6k{sw} <20> 2011/02/23 15:22:34.329 H225 Answer:beb54700 Info H323 Started receiving logical channel: SpeexIETFWide-20.6k{sw} <20> 2011/02/23 15:22:34.329 LogChanTx:beac7700 Info H323RTP Transmit SpeexIETFWide-20.6k thread started: rate=320 time=20ms size=1*52=52 2011/02/23 15:22:34.329 H225 Answer:beb54700 Info H323 Started sending logical channel: H.261-CIF{sw} <26> 2011/02/23 15:22:34.329 LogChanRx:bea86700 Info H323RTP Receive SpeexIETFWide-20.6k thread started. 2011/02/23 15:22:34.329 LogChanRx:bea86700 Info RTP Jitter buffer created: size=101 delay=800-4000/800 (100ms) obj=0xc34160 2011/02/23 15:22:34.330 LogChanTx:bea45700 Info H323RTP Transmit H.261-CIF thread started: rate=29 time=0ms size=1*2000=2000 2011/02/23 15:22:34.331 LogChanTx:beac7700 Warning Cannot open WAV file /root/entering.wav - error = 2 2011/02/23 15:22:34.331 H225 Answer:beb54700 Info H323 Started receiving logical channel: H.261-CIF{sw} <26> 2011/02/23 15:22:34.331 H225 Answer:beb54700 Info RTP Releasing session 1 2011/02/23 15:22:34.331 H225 Answer:beb54700 Info RTP Releasing session 1 2011/02/23 15:22:34.331 H225 Answer:beb54700 Info RTP Releasing session 1 2011/02/23 15:22:34.331 H225 Answer:beb54700 Info RTP Releasing session 1 2011/02/23 15:22:34.331 H225 Answer:beb54700 Info RTP Releasing session 1 2011/02/23 15:22:34.331 H225 Answer:beb54700 Info RTP Releasing session 1 2011/02/23 15:22:34.332 H225 Answer:beb54700 Info RTP Releasing session 1 2011/02/23 15:22:34.332 H225 Answer:beb54700 Info RTP Releasing session 1 2011/02/23 15:22:34.332 H225 Answer:beb54700 Info RTP Releasing session 1 2011/02/23 15:22:34.332 H225 Answer:beb54700 Info RTP Releasing session 1 2011/02/23 15:22:34.332 H225 Answer:beb54700 Info RTP Releasing session 1 2011/02/23 15:22:34.332 H225 Answer:beb54700 Info RTP Releasing session 1 2011/02/23 15:22:34.332 H225 Answer:beb54700 Info RTP Releasing session 1 2011/02/23 15:22:34.332 H225 Answer:beb54700 Info RTP Releasing session 1 2011/02/23 15:22:34.332 H225 Answer:beb54700 Info RTP Releasing session 1 2011/02/23 15:22:34.332 H225 Answer:beb54700 Info RTP Releasing session 1 2011/02/23 15:22:34.332 H225 Answer:beb54700 Info RTP Releasing session 1 2011/02/23 15:22:34.332 H225 Answer:beb54700 Info RTP Releasing session 1 2011/02/23 15:22:34.332 H225 Answer:beb54700 Info RTP Releasing session 1 2011/02/23 15:22:34.332 H225 Answer:beb54700 Info RTP Releasing session 1 2011/02/23 15:22:34.333 H225 Answer:beb54700 Info RTP Releasing session 1 2011/02/23 15:22:34.333 H225 Answer:beb54700 Info RTP Releasing session 1 2011/02/23 15:22:34.333 H225 Answer:beb54700 Info RTP Releasing session 2 2011/02/23 15:22:34.333 H225 Answer:beb54700 Info RTP Releasing session 2 2011/02/23 15:22:34.333 LogChanRx:be9c3700 Info H323RTP Receive H.261-CIF thread started. 2011/02/23 15:22:34.341 H225 Answer:beb54700 Info H323 User Input RFC2833 payload type set to [pt=101] 2011/02/23 15:22:34.341 H225 Answer:beb54700 Info H245 MasterSlaveDetermination: local is master 2011/02/23 15:22:34.342 H225 Answer:beb54700 Info H225 Reading PDUs: callRef=3310 2011/02/23 15:22:34.358 RTP Jitter:bea04700 Info RTP First data: ver=2 pt=[pt=108] psz=52 m=1 x=0 seq=1749 ts=0 src=531892144 ccnt=0 2011/02/23 15:22:34.363 LogChanRx:be9c3700 Info RTP First data: ver=2 pt=H261 psz=969 m=1 x=0 seq=54377 ts=0 src=1679710377 ccnt=0 2011/02/23 15:22:34.364 LogChanRx:be9c3700 Info PVidDev SetFrameSize to 176x144 2011/02/23 15:22:34.365 H225 Answer:beb54700 Info H245 TerminalCapabilitySet Sent. 2011/02/23 15:22:36.356 RTP Jitter:bea04700 Info RTP Receive statistics: packets=101 octets=5252 lost=0 tooLate=0 order=0 avgTime=19 maxTime=21 minTime=19 jitter=7129523 maxJitter=33554431 2011/02/23 15:22:38.332 LogChanTx:beac7700 Info RTP Transmit statistics: packets=101 octets=5252 avgTime=40 maxTime=42 minTime=38 2011/02/23 15:22:38.356 RTP Jitter:bea04700 Info RTP Receive statistics: packets=201 octets=10452 lost=0 tooLate=0 order=0 avgTime=20 maxTime=21 minTime=19 jitter=11225 maxJitter=33554431 2011/02/23 15:22:40.354 RTP Jitter:bea04700 Info RTP Receive statistics: packets=301 octets=15652 lost=0 tooLate=0 order=0 avgTime=19 maxTime=21 minTime=19 jitter=17 maxJitter=33554431 2011/02/23 15:22:42.332 LogChanTx:beac7700 Info RTP Transmit statistics: packets=201 octets=10452 avgTime=40 maxTime=42 minTime=38 2011/02/23 15:22:42.354 RTP Jitter:bea04700 Info RTP Receive statistics: packets=401 octets=20852 lost=0 tooLate=0 order=0 avgTime=19 maxTime=21 minTime=19 jitter=0 maxJitter=33554431 2011/02/23 15:22:44.353 RTP Jitter:bea04700 Info RTP Receive statistics: packets=501 octets=26052 lost=0 tooLate=0 order=0 avgTime=20 maxTime=22 minTime=17 jitter=265213 maxJitter=33554431
However, when I try to connect Computer C with Ekiga 3 as well, it fails and openmcu gets a strange beahior: endless log messages even when all clients are disconnected, unable to connect a single client again, unable to access the web interface anymore. The only thing I can do is to kill the daemon. Here is the log I get:
2011/02/23 15:23:26.932 H323 Listener:bebd6700 Info H323TCP Started connection: host=10.1.8.76:30000, if=10.1.9.152:1720, handle=35 2011/02/23 15:23:26.996 H225 Answer:be982700 Info H225 Set remote application name: " 3.2.7 (OPAL v3.6.8) 9/61" 2011/02/23 15:23:26.997 H225 Answer:be982700 Info H323 CreateLogicalChannel - unknown data type 2011/02/23 15:23:26.997 H225 Answer:be982700 Info H323 CreateLogicalChannel - unknown data type 2011/02/23 15:23:26.997 H225 Answer:be982700 Info RTP_UDP Session 1 created: 10.1.9.152:5004-5005 ssrc=529535172 2011/02/23 15:23:26.997 H225 Answer:be982700 Info RTP Adding session RTP_UDP 2011/02/23 15:23:26.998 H225 Answer:be982700 Warning H323RTP Setting dynamic RTP payload type: 111 - error = 22 2011/02/23 15:23:26.998 H225 Answer:be982700 Warning H323RTP Setting dynamic RTP payload type: 111 - error = 22 2011/02/23 15:23:26.999 H225 Answer:be982700 Warning H323RTP Setting dynamic RTP payload type: 113 - error = 22 2011/02/23 15:23:26.999 H225 Answer:be982700 Warning H323RTP Setting dynamic RTP payload type: 113 - error = 22 2011/02/23 15:23:27.001 H225 Answer:be982700 Warning H323RTP Setting dynamic RTP payload type: 119 - error = 22 2011/02/23 15:23:27.001 H225 Answer:be982700 Warning H323RTP Setting dynamic RTP payload type: 119 - error = 22 2011/02/23 15:23:27.001 H225 Answer:be982700 Warning H323RTP Setting dynamic RTP payload type: 118 - error = 22 2011/02/23 15:23:27.002 H225 Answer:be982700 Warning H323RTP Setting dynamic RTP payload type: 118 - error = 22 2011/02/23 15:23:27.003 H225 Answer:be982700 Warning H323RTP Setting dynamic RTP payload type: 117 - error = 22 2011/02/23 15:23:27.004 H225 Answer:be982700 Warning H323RTP Setting dynamic RTP payload type: 117 - error = 22 2011/02/23 15:23:27.005 H225 Answer:be982700 Warning H323RTP Setting dynamic RTP payload type: 116 - error = 22 2011/02/23 15:23:27.005 H225 Answer:be982700 Warning H323RTP Setting dynamic RTP payload type: 116 - error = 22 2011/02/23 15:23:27.006 H225 Answer:be982700 Warning H323RTP Setting dynamic RTP payload type: 99 - error = 22 2011/02/23 15:23:27.007 H225 Answer:be982700 Warning H323RTP Setting dynamic RTP payload type: 99 - error = 22 2011/02/23 15:23:27.007 H225 Answer:be982700 Warning H323RTP Setting dynamic RTP payload type: 106 - error = 22 2011/02/23 15:23:27.007 H225 Answer:be982700 Warning H323RTP Setting dynamic RTP payload type: 106 - error = 22 2011/02/23 15:23:27.008 H225 Answer:be982700 Warning H323RTP Setting dynamic RTP payload type: 112 - error = 22 2011/02/23 15:23:27.008 H225 Answer:be982700 Warning H323RTP Setting dynamic RTP payload type: 112 - error = 22 2011/02/23 15:23:27.009 H225 Answer:be982700 Info RTP_UDP Session 2 created: 10.1.9.152:5006-5007 ssrc=2416178601 2011/02/23 15:23:27.009 H225 Answer:be982700 Info RTP Adding session RTP_UDP 2011/02/23 15:23:27.018 H225 Answer:be982700 Info H323 Available bandwidth exceeded 2011/02/23 15:23:27.019 H225 Answer:be982700 Info H323 OnReceivedPDU gave error 7 2011/02/23 15:23:27.019 H225 Answer:be982700 Info RTP Releasing session 2 2011/02/23 15:23:27.020 H225 Answer:be982700 Info H323 Available bandwidth exceeded 2011/02/23 15:23:27.020 H225 Answer:be982700 Info H323 OnReceivedPDU gave error 7 2011/02/23 15:23:27.020 H225 Answer:be982700 Info RTP Releasing session 2 2011/02/23 15:23:27.021 H225 Answer:be982700 Info H323 Answering call: AnswerCallNow 2011/02/23 15:23:27.021 H225 Answer:be982700 Info H245 Default OnSelectLogicalChannels, FastStartResponse 2011/02/23 15:23:27.021 H225 Answer:be982700 Info H323 Started sending logical channel: SpeexIETFWide-20.6k{sw} <20> 2011/02/23 15:23:27.021 H225 Answer:be982700 Info H323 Started receiving logical channel: SpeexIETFWide-20.6k{sw} <20> 2011/02/23 15:23:27.021 LogChanTx:be941700 Info H323RTP Transmit SpeexIETFWide-20.6k thread started: rate=320 time=20ms size=1*52=52 2011/02/23 15:23:27.021 H225 Answer:be982700 Info H323 Started sending logical channel: H.261-CIF{sw} <26> 2011/02/23 15:23:27.022 LogChanRx:be900700 Info H323RTP Receive SpeexIETFWide-20.6k thread started. 2011/02/23 15:23:27.022 LogChanRx:be900700 Info RTP Jitter buffer created: size=101 delay=800-4000/800 (100ms) obj=0xc34310 2011/02/23 15:23:27.023 LogChanTx:be8bf700 Info H323RTP Transmit H.261-CIF thread started: rate=29 time=0ms size=1*2000=2000 2011/02/23 15:23:28.332 RTP Jitter:bea04700 Info RTP Receive statistics: packets=2701 octets=140452 lost=0 tooLate=0 order=0 avgTime=20 maxTime=22 minTime=17 jitter=3 maxJitter=33554431 2011/02/23 15:23:28.812 RTP Jitter:bea04700 Info RTP Jitter buffer full, throwing away oldest frame (839680) 2011/02/23 15:23:29.212 RTP Jitter:bea04700 Info RTP Jitter buffer continuously full, throwing away entire buffer. 2011/02/23 15:23:29.232 RTP Jitter:bea04700 Info RTP Jitter buffer full, threw away 21 oldest frames 2011/02/23 15:23:30.331 RTP Jitter:bea04700 Info RTP Receive statistics: packets=2801 octets=145652 lost=0 tooLate=0 order=0 avgTime=19 maxTime=21 minTime=19 jitter=0 maxJitter=33554431 2011/02/23 15:23:31.231 RTP Jitter:bea04700 Info RTP Jitter buffer full, throwing away oldest frame (878400) 2011/02/23 15:23:31.630 RTP Jitter:bea04700 Info RTP Jitter buffer continuously full, throwing away entire buffer.
Note that I get the same behavior using other clients, like VidPhone for Windows or a Sony conferencing hardware. In addition, Computers A, B and C are on the same LAN.
Do you have any ideas how I can get openmcu working?
Cheers,
Jeremy Subtil Systems Engineer at Smile http://www.smile.fr/en/