[Ekiga-list] Ekiga jitter buffer thread problem.
Raj Mohan Kovilath
rmohan43 at gmail.com
Thu Apr 26 05:16:06 UTC 2007
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=4018989693 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="root at debian-1"
item[1]: type=TOOL data="OpenMCU"
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 at 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
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://mail.gnome.org/archives/ekiga-list/attachments/20070426/a6531480/attachment.html>
More information about the ekiga-list
mailing list