GNOME.org
 

[Ekiga-list] Ekiga jitter buffer thread problem.

Hello,
I am testing ekiga V2.0.3 with OpenMCU 2.1.1. When sending audio or video using H.323 I get the following error from Ekiga.

"Jitter buffer thread did not terminate ".

When I try to close the application, it crashes.

I have configured ekiga with a jitter buffer size of 1024 and testing is done onour local LAN with Ekiga and OpenMCU running on the same machine.

The platform and configuration details are as follows:

O/S: Debian etch Linux debian-1 2.6.18-4-686 #1 SMP

ALSA sound.
Sound input/output device: HDA Intel (set to default in ekiga)
Webcam: Logitech Quickcam EC

OpenMCU: 2.1.1

Any help will be greatly appreciated.

Thanks & regards,
Raj Mohan

The extracted output from ekiga -d 4 is:

--------------------- Debug trace ------------------------

2007/04/26 10:19:38.387   0:18.878          H225 Caller:85e31d0 GMSIPEndpoint H.323 connection established
2007/04/26 10:19:38.391   0:18.882          H225 Caller:85e31d0 RTP     Found existing session 1
2007/04/26 10:19: 38.391   0:18.882          H225 Caller:85e31d0 RTP     Found existing session 2
2007/04/26 10:19:38.391   0:18.882          H225 Caller:85e31d0 GMManager Will establish the connection
2007/04/26 10:19:38.391   0:18.882           H225 Caller:85e31d0 OpalMan OnEstablished Call[1]-EP<h323>[localhost/1580]
2007/04/26 10:19:38.391   0:18.882          H225 Caller:85e31d0 Call    OnEstablished Call[1]-EP<h323>[localhost/1580]
2007/04/26 10:19:38.392   0:18.883          H225 Caller:85e31d0 H323    Started receiving logical channel: GSM-06.10 <2>
2007/04/26 10:19:38.392   0:18.883          Media Patch:85e62a0 Patch   Thread started for Patch OpalRTPMediaStream-Source-GSM-06.10 -> OpalAudioMediaStream-Sink-PCM-16
2007/04/26 10:19:38.392   0:18.883          Media Patch:85e62a0 RTP     Jitter buffer created: size=101 delay=160-4000/160 (20ms) obj=0x8608d48
2007/04/26 10:19:38.393   0:18.884           RTP Jitter:8608d48 RTP     Jitter RTP receive thread started: 0x8608d48
2007/04/26 10:19:38.394   0:18.885           RTP Jitter:8608d48 RTP     First receive data: ver=2 pt=GSM psz=33 m=1 x=0 seq=26669 ts=160 src="" ccnt=0
2007/04/26 10:19:38.394   0:18.885           RTP Jitter:8608d48 RTP     Received start of talk burst: 160
2007/04/26 10:19:38.395   0:18.886          H225 Caller:85e31d0 Media   Starting thread Media Patch:85e62a0
2007/04/26 10:19:38.395   0:18.886          H225 Caller:85e31d0 H225    Fast start open:
  {
    forwardLogicalChannelNumber = 102
    forwardLogicalChannelParameters = {
      dataType = audioData gsmFullRate {
        audioUnitSize = 33
        comfortNoise = FALSE
        scrambled = FALSE
      }
      multiplexParameters = h2250LogicalChannelParameters {
        sessionID = 1
        mediaChannel = unicastAddress iPAddress {
          network =  4 octets {
            c0 a8 01 65                                        ...e
          }
          tsapIdentifier = 5010
        }
        mediaGuaranteedDelivery = FALSE
        mediaControlChannel = unicastAddress iPAddress {
          network =  4 octets {
            c0 a8 01 65                                        ...e
          }
          tsapIdentifier = 5011
        }
      }
    }
  }
2007/04/26 10:19:38.396    0:18.887          H225 Caller:85e31d0 H323    FindCapability: audioData
2007/04/26 10:19:38.396   0:18.887          H225 Caller:85e31d0 H323    Capability tx frames reduced from 4 to 1
2007/04/26 10:19:38.396   0: 18.887          H225 Caller:85e31d0 H323    Found capability: GSM-06.10 <2>
2007/04/26 10:19:38.396   0:18.887          H225 Caller:85e31d0 H323RTP OnReceivedPDU for channel: T-102
2007/04/26 10:19:38.396   0: 18.887          H225 Caller:85e31d0 H323    Capability tx frames reduced from 4 to 1
2007/04/26 10:19:38.396   0:18.887          H225 Caller:85e31d0 RTP_UDP SetRemoteSocketInfo: session=1 control channel, new= 192.168.1.101:5011, local=192.168.1.101:5000-5001, remote=192.168.1.101:5010-5011
2007/04/26 10:19:38.396   0:18.887          H225 Caller:85e31d0 RTP_UDP SetRemoteSocketInfo: session=1 data channel, new= 192.168.1.101:5010, local=192.168.1.101:5000-5001, remote=192.168.1.101:5010-5011
2007/04/26 10:19:38.396   0:18.887          H225 Caller:85e31d0 H323    FindCapability: GSM-06.10 <2>
2007/04/26 10:19:38.396    0:18.887          H225 Caller:85e31d0 H323    Added capability: GSM-06.10 <2>
2007/04/26 10:19:38.396   0:18.887          H225 Caller:85e31d0 LogChan Bandwidth requested/used = 13.2/0.0 kb/s
2007/04/26 10:19:38.396   0:18.887          H225 Caller:85e31d0 OpalCon Bandwidth request of 13.2kb/s, available: 3986.8kb/s
2007/04/26 10:19:38.396   0:18.887          H225 Caller:85e31d0 H323    Started sending logical channel: GSM-06.10 <2>
2007/04/26 10:19:38.396   0:18.887          H225 Caller:85e31d0 Call    OpenSourceMediaStreams for session 1 with media GSM-06.10
2007/04/26 10:19:38.396   0:18.887          H225 Caller:85e31d0 OpalCon OpenSourceMediaStream for session 1 on Call[1]-EP<pc>[Default]
2007/04/26 10:19:38.397   0:18.888          H225 Caller:85e31d0 OpalCon Selected media stream PCM-16 -> GSM-06.10
2007/04/26 10:19:38.409   0:18.900          Media Patch:85e62a0 RTP     Jitter buffer length exceeded
2007/04/26 10:19:38.409   0:18.900          Media Patch:85e62a0 RTP     Jitter buffer length exceed was prior to first write. Not increasing buffer size
2007/04/26 10:19:38.542   0:19.033           RTP Jitter:8608d48 RTP     Receive statistics:  packets=101 octets=3333 lost=0 tooLate=0 order=0 avgTime=1 maxTime=21 minTime=0 jitter=0 maxJitter=1
2007/04/26 10:19:38.643   0:19.134          Media Patch:85e62a0 RTP     Jitter buffer length exceeded
2007/04/26 10:19:38.643   0:19.134          Media Patch:85e62a0 RTP     Jitter buffer size increased to 320 (40ms)
2007/04/26 10:19:38.709   0:19.200          H225 Caller:85e31d0 OpalMan OnOpenMediaStream Call[1]-EP<pc>[Default],OpalAudioMediaStream-Source-PCM-16
2007/04/26 10:19:38.709   0:19.200          H225 Caller:85e31d0 Call    PatchMediaStreams Call[1]-EP<pc>[Default]
2007/04/26 10:19:38.709    0:19.200          H225 Caller:85e31d0 OpalCon OpenSinkMediaStream Call[1]-EP<h323>[localhost/1580] session=1
2007/04/26 10:19:38.710   0:19.201          H225 Caller:85e31d0 OpalCon OpenSinkMediaStream, selected PCM-16 -> GSM-06.10
2007/04/26 10:19:38.710   0:19.201          H225 Caller:85e31d0 Call    CanDoMediaBypass Call[1]-EP<h323>[localhost/1580] session 1
2007/04/26 10:19:38.710   0:19.201          H225 Caller:85e31d0 OpalMan IsMediaBypassPossible: session 1
2007/04/26 10:19:38.710   0:19.201          H225 Caller:85e31d0 H323    IsMediaBypassPossible: session 1
2007/04/26 10:19:38.710   0:19.201          H225 Caller:85e31d0 OpalCon IsMediaBypassPossible: default returns FALSE
2007/04/26 10:19:38.710   0:19.201          H225 Caller:85e31d0 OpalMan OnOpenMediaStream Call[1]-EP<h323>[localhost/1580],OpalRTPMediaStream-Sink-GSM-06.10
2007/04/26 10:19:38.712   0:19.203          H225 Caller:85e31d0 Codec   GSM0610 encoder created
2007/04/26 10:19:38.712   0:19.203          H225 Caller:85e31d0 Patch   Added media stream sink OpalRTPMediaStream-Sink-GSM-06.10 using transcoder PCM-16->GSM-06.10
2007/04/26 10:19:38.712   0:19.203          H225 Caller:85e31d0 Media   Audio source data size set to  320 bytes and 2 buffers.
2007/04/26 10:19:38.712   0:19.203          H225 Caller:85e31d0 OpalCon New patch created
2007/04/26 10:19:38.712   0:19.203          H225 Caller:85e31d0 OpalCon New patch created
2007/04/26 10:19:38.712   0:19.203           H225 Caller:85e31d0 Call    OpenSourceMediaStreams for session 1 adjusted media to GSM-06.10
2007/04/26 10:19:38.712   0:19.203         Media Patch:b441e540 Patch   Thread started for Patch OpalAudioMediaStream-Source-PCM-16 -> OpalRTPMediaStream-Sink-GSM-06.10
2007/04/26 10:19:38.713   0:19.204          H225 Caller:85e31d0 Media   Starting thread Media Patch:b441e540
2007/04/26 10:19:38.713   0:19.204          H225 Caller:85e31d0 RTP     Releasing session 1
2007/04/26 10:19:38.713   0:19.204          H225 Caller:85e31d0 RTP     Deleting session 1
2007/04/26 10:19:38.713   0:19.204          H225 Caller:85e31d0 RTP     Removing jitter buffer 0x8608d48 RTP Jitter:8608d48
2007/04/26 10:19:38.728   0:19.219          Media Patch:85e62a0 Patch   Thread ended for Patch OpalRTPMediaStream-Source-GSM-06.10 -> OpalAudioMediaStream-Sink-PCM-16
2007/04/26 10:19:48.523   0:29.014           RTP Jitter:8608d48 RTP     OnRxSenderReport: ssrc=4018989693 ntp=2007/4/26-10:19: 48.523034 rtp=95200 psent=595 osent=19635
2007/04/26 10:19:48.523   0:29.014           RTP Jitter:8608d48 RTP     OnSourceDescription: ssrc=4018989693
  item[0]: type=CNAME data="" debian-1"
  item[1]: type=TOOL data=""
2007/04/26 10:19:48.523   0:29.014           RTP Jitter:8608d48 RTP     SentReceiverReport: ssrc=4018989693 fraction=0 lost=0 last_seq=0 jitter=4909 lsr=0 dlsr=0
2007/04/26 10:19:48.523   0:29.014           RTP Jitter:8608d48 RTP     Sending SDES: space debian-1
2007/04/26 10:19:48.524   0:29.015           RTP Jitter:8608d48 RTP     Receive statistics:  packets=201 octets=6633 lost=0 tooLate=0 order=0 avgTime=99 maxTime=9823 minTime=0 jitter=3 maxJitter=1189
2007/04/26 10:19:48.524   0:29.015           RTP Jitter:8608d48 RTP     Jitter buffer full, throwing away oldest frame (17280)
2007/04/26 10:19:48.525   0:29.016           RTP Jitter:8608d48 RTP     Jitter buffer continuously full, throwing away entire buffer.
2007/04/26 10:19: 48.525   0:29.016           RTP Jitter:8608d48 RTP     Jitter buffer full, threw away 21 oldest frames
2007/04/26 10:19:48.526   0:29.017           RTP Jitter:8608d48 RTP     Receive statistics:  packets=301 octets=9933 lost=0 tooLate=0 order=0 avgTime=0 maxTime=1 minTime=0 jitter=0 maxJitter=1189
2007/04/26 10:19:48.526   0:29.017           RTP Jitter:8608d48 RTP     Jitter buffer full, throwing away oldest frame (36640)
2007/04/26 10:19:48.526   0:29.017           RTP Jitter:8608d48 RTP     Jitter buffer continuously full, throwing away entire buffer.
2007/04/26 10:19:48.526   0:29.017           RTP Jitter:8608d48 RTP     Jitter buffer full, threw away 21 oldest frames
2007/04/26 10:19:48.527   0:29.018            RTP Jitter:8608d48 RTP     Receive statistics:  packets=401 octets=13233 lost=0 tooLate=0 order=0 avgTime=0 maxTime=1 minTime=0 jitter=0 maxJitter=1189
2007/04/26 10:19:48.528   0:29.019           RTP Jitter:8608d48 RTP     Jitter buffer full, throwing away oldest frame (56000)
2007/04/26 10:19:48.528   0:29.019           RTP Jitter:8608d48 RTP     Jitter buffer continuously full, throwing away entire buffer.
2007/04/26 10:19:48.528   0:29.019           RTP Jitter:8608d48 RTP     Jitter buffer full, threw away 21 oldest frames
2007/04/26 10:19:48.725   0:29.216           RTP Jitter:8608d48 RTP_UDP Control read error (4): Interrupted system call
2007/04/26 10:19:48.725   0:29.216           RTP Jitter:8608d48 RTP     Jitter RTP receive thread ended
2007/04/26 10:19:48.725   0:29.216          H225 Caller:85e31d0 RTP_UDP Session 1, Shutting down read.
2007/04/26 10:19: 48.725   0:29.216          H225 Caller:85e31d0 RTP_UDP Session 1, Shutting down write.
2007/04/26 10:19:48.725   0:29.216          H225 Caller:85e31d0 RTP     Final statistics:
    packetsSent       = 0
    octetsSent        = 0
    averageSendTime   = 0
    maximumSendTime   = 0
    minimumSendTime   = 0
    packetsReceived   = 483
    octetsReceived    = 15939
    packetsLost       = 0
    packetsTooLate    = 0
    packetsOutOfOrder = 0
    averageReceiveTime= 0
    maximumReceiveTime= 1
    minimumReceiveTime= 0
    averageJitter     = 1
    maximumJitter     = 1189
2007/04/26 10:19:48.725   0:29.216          H225 Caller:85e31d0 RTP     Releasing session 140840952

[Date Prev][Date Next]   [Thread Prev][Thread Next]   [Thread Index] [Date Index] [Author Index]