Re: [Ekiga-list] G729 codec




Jure Petrovic writes:
 > 
 > 
 > Please swap the src/main.c file with the attached one and recompile.
 > This is a small test program for g729 codec. It encodes test.wav file to
 > test.g729 and then decodes it back to test_out.raw.
 > 
 > Let me know if the test.wav and test_out.raw files contain the same
 > audio sample. (Except for lower quality, of course ;)) You just take any
 > wav sample and put it in bin/ directory with the name test.wav.
 > 
 > Oh, the resulting test_out.raw file is in WAV format, but it doesn't
 > contain appropriate headers, so you will have to import it as "raw
 > audio" with audacity or something similar.
 > 
 > 
 > Also, if you start ekiga from the console with -d 4 option, you will see
 > some output from the codec. Can you paste this here, please? 
 > 
 > 
 > 
 > 
 > 

I played back the test_out.raw file. I can hear squeaky noise instead of things from the original file.

Below are contents of `ekiga -d 4` codec related stuff, I believe:

2007/11/03 09:04:37.601	  0:17.475	  Opal Listener:84a2400	SDP	Media session port=18118
2007/11/03 09:04:37.612	  0:17.486	  Opal Listener:84a2400	SDP	Adding media session with 3 formats
2007/11/03 09:04:37.615	  0:17.489	  Opal Listener:84a2400	SIP	Queueing PDU: 2 INVITE <183>
2007/11/03 09:04:37.615	  0:17.489	  Opal Listener:84a2400	Opal	Transport clean up on termination
2007/11/03 09:04:37.615	  0:17.489	  Opal Listener:84a2400	Opal	Transport Close
2007/11/03 09:04:37.615	  0:17.489	  Opal Listener:84a2400	Opal	Deleted transport udp$217.11.128.5:5060<if=udp$89.78.148.75:5060>
2007/11/03 09:04:37.624	  0:17.498	    SIP Handler:8564500	SIP	Handling PDU 2 INVITE <183>
2007/11/03 09:04:37.625	  0:17.499	    SIP Handler:8564500	SIP	Transaction 2 INVITE proceeding.
2007/11/03 09:04:37.626	  0:17.500	    SIP Handler:8564500	SIP	Set targetAddress to sip:0896741121 205 46 34 6:5060
2007/11/03 09:04:37.626	  0:17.500	    SIP Handler:8564500	SIP	Received Session Progress response
2007/11/03 09:04:37.626	  0:17.500	    SIP Handler:8564500	SIP	RTP payload type G729 matched to codec G.729
2007/11/03 09:04:37.631	  0:17.506	    SIP Handler:8564500	SIP	RTP payload type [pt=100] not matched to audio codec
2007/11/03 09:04:37.632	  0:17.506	    SIP Handler:8564500	Call	GetOtherPartyConnection Call[1]-EP<sip>[da498715-5188-dc11-9be6-00300510c2ee chello089078148075 chello pl]
2007/11/03 09:04:37.632	  0:17.506	    SIP Handler:8564500	Call	IsMediaBypassPossible Call[1]-EP<sip>[da498715-5188-dc11-9be6-00300510c2ee chello089078148075 chello pl] session 1
2007/11/03 09:04:37.632	  0:17.506	    SIP Handler:8564500	OpalMan	IsMediaBypassPossible: session 1
2007/11/03 09:04:37.632	  0:17.506	    SIP Handler:8564500	OpalCon	IsMediaBypassPossible: default returns FALSE
2007/11/03 09:04:37.632	  0:17.506	    SIP Handler:8564500	RTP	Found existing session 1
2007/11/03 09:04:37.634	  0:17.508	    SIP Handler:8564500	RTP_UDP	Session 1, SetRemoteSocketInfo: data channel, new=205.46.34.6:18118, local=89.78.148.75:5000-5001, remote=0.0.0.0:0-0
2007/11/03 09:04:37.636	  0:17.510	    SIP Handler:8564500	Call	OpenSourceMediaStreams for session 1 with media G.729
2007/11/03 09:04:37.637	  0:17.511	    SIP Handler:8564500	OpalCon	Selected media stream PCM-16 -> G.729
2007/11/03 09:04:37.638	  0:17.512	    SIP Handler:8564500	Ekiga	Creating Sound Channel
2007/11/03 09:04:37.956	  0:17.830	    SIP Handler:8564500	Alsa	Buffer time is 42500
2007/11/03 09:04:37.956	  0:17.830	    SIP Handler:8564500	Alsa	Period time is 10625
2007/11/03 09:04:37.957	  0:17.831	    SIP Handler:8564500	ALSA	Device default Opened
2007/11/03 09:04:37.960	  0:17.834	    SIP Handler:8564500	Got volume 15
2007/11/03 09:04:37.960	  0:17.834	    SIP Handler:8564500	OpalMan	OnOpenMediaStream Call[1]-EP<pc>[Default],OpalAudioMediaStream-Source-PCM-16
2007/11/03 09:04:37.961	  0:17.835	    SIP Handler:8564500	Call	PatchMediaStreams Call[1]-EP<pc>[Default]
2007/11/03 09:04:37.961	  0:17.835	    SIP Handler:8564500	OpalCon	OpenSinkMediaStream Call[1]-EP<sip>[da498715-5188-dc11-9be6-00300510c2ee chello089078148075 chello pl] session=1
2007/11/03 09:04:37.962	  0:17.836	    SIP Handler:8564500	OpalCon	OpenSinkMediaStream, selected PCM-16 -> G.729
2007/11/03 09:04:37.962	  0:17.836	    SIP Handler:8564500	Call	IsMediaBypassPossible Call[1]-EP<sip>[da498715-5188-dc11-9be6-00300510c2ee chello089078148075 chello pl] session 1
2007/11/03 09:04:37.963	  0:17.837	    SIP Handler:8564500	OpalMan	IsMediaBypassPossible: session 1
2007/11/03 09:04:37.963	  0:17.837	    SIP Handler:8564500	OpalCon	IsMediaBypassPossible: default returns FALSE
2007/11/03 09:04:37.963	  0:17.837	    SIP Handler:8564500	RTP	Found existing session 1
2007/11/03 09:04:37.963	  0:17.837	    SIP Handler:8564500	RTP	Found existing session 1
2007/11/03 09:04:37.963	  0:17.837	    SIP Handler:8564500	OpalMan	OnOpenMediaStream Call[1]-EP<sip>[da498715-5188-dc11-9be6-00300510c2ee chello089078148075 chello pl],OpalRTPMediaStream-Sink-G.729
2007/11/03 09:04:37.964	  0:17.838	    SIP Handler:8564500	OpalCon	Opened sink stream 1_1
2007/11/03 09:04:37.965	  0:17.839	    SIP Handler:8564500	Patch	Created primary codec PCM-16/G.729 with ID 1_1
2007/11/03 09:04:37.965	  0:17.839	    SIP Handler:8564500	Patch	Added media stream sink OpalRTPMediaStream-Sink-G.729 using transcoder PCM-16->G.729
2007/11/03 09:04:37.965	  0:17.839	    SIP Handler:8564500	Media	Audio source data size set to  320 bytes and 20 buffers.
2007/11/03 09:04:37.965	  0:17.839	    SIP Handler:8564500	OpalCon	New patch created
2007/11/03 09:04:37.966	  0:17.840	    SIP Handler:8564500	OpalCon	New patch created
2007/11/03 09:04:37.966	  0:17.840	    SIP Handler:8564500	OpalCon	Opened source stream 1_1
2007/11/03 09:04:37.966	  0:17.840	    SIP Handler:8564500	Call	GetOtherPartyConnection Call[1]-EP<sip>[da498715-5188-dc11-9be6-00300510c2ee chello089078148075 chello pl]
2007/11/03 09:04:37.967	  0:17.841	    SIP Handler:8564500	OpalCon	OpenSourceMediaStream reordering codec for sink stream format G.729
2007/11/03 09:04:37.968	  0:17.842	    SIP Handler:8564500	OpalCon	Selected media stream G.729 -> PCM-16
2007/11/03 09:04:37.968	  0:17.842	    SIP Handler:8564500	Call	IsMediaBypassPossible Call[1]-EP<sip>[da498715-5188-dc11-9be6-00300510c2ee chello089078148075 chello pl] session 1
2007/11/03 09:04:37.968	  0:17.842	    SIP Handler:8564500	OpalMan	IsMediaBypassPossible: session 1
2007/11/03 09:04:37.968	  0:17.842	    SIP Handler:8564500	OpalCon	IsMediaBypassPossible: default returns FALSE
2007/11/03 09:04:37.968	  0:17.843	    SIP Handler:8564500	RTP	Found existing session 1
2007/11/03 09:04:37.969	  0:17.843	    SIP Handler:8564500	RTP	Found existing session 1
2007/11/03 09:04:37.969	  0:17.843	    SIP Handler:8564500	OpalMan	OnOpenMediaStream Call[1]-EP<sip>[da498715-5188-dc11-9be6-00300510c2ee chello089078148075 chello pl],OpalRTPMediaStream-Source-G.729
2007/11/03 09:04:37.969	  0:17.843	    SIP Handler:8564500	Call	PatchMediaStreams Call[1]-EP<sip>[da498715-5188-dc11-9be6-00300510c2ee chello089078148075 chello pl]
2007/11/03 09:04:37.969	  0:17.843	    SIP Handler:8564500	OpalCon	OpenSinkMediaStream Call[1]-EP<pc>[Default] session=1
2007/11/03 09:04:37.971	  0:17.845	    SIP Handler:8564500	OpalCon	OpenSinkMediaStream, selected G.729 -> PCM-16
2007/11/03 09:04:37.971	  0:17.845	    SIP Handler:8564500	Ekiga	Creating Sound Channel
2007/11/03 09:04:38.280	  0:18.154	    SIP Handler:8564500	Alsa	Buffer time is 42500
2007/11/03 09:04:38.281	  0:18.155	    SIP Handler:8564500	Alsa	Period time is 10625
2007/11/03 09:04:38.281	  0:18.155	    SIP Handler:8564500	ALSA	Device default Opened
2007/11/03 09:04:38.283	  0:18.157	    SIP Handler:8564500	Got volume 13
2007/11/03 09:04:38.284	  0:18.158	    SIP Handler:8564500	OpalMan	OnOpenMediaStream Call[1]-EP<pc>[Default],OpalAudioMediaStream-Sink-PCM-16
2007/11/03 09:04:38.284	  0:18.158	    SIP Handler:8564500	OpalCon	Opened sink stream 1_1
2007/11/03 09:04:38.285	  0:18.159	    SIP Handler:8564500	Patch	Created primary codec G.729/PCM-16 with ID 1_1
2007/11/03 09:04:38.285	  0:18.159	    SIP Handler:8564500	Media	Audio sink data size set to  160 bytes and 20 buffers.
2007/11/03 09:04:38.285	  0:18.159	    SIP Handler:8564500	Patch	Added media stream sink OpalAudioMediaStream-Sink-PCM-16 using transcoder G.729->PCM-16
2007/11/03 09:04:38.285	  0:18.159	    SIP Handler:8564500	OpalCon	New patch created
2007/11/03 09:04:38.285	  0:18.159	    SIP Handler:8564500	OpalCon	New patch created
2007/11/03 09:04:38.285	  0:18.159	    SIP Handler:8564500	OpalCon	Adding RFC2833 receive handler
2007/11/03 09:04:38.285	  0:18.159	    SIP Handler:8564500	OpalCon	Adding Cisco NSE receive handler
2007/11/03 09:04:38.286	  0:18.160	    SIP Handler:8564500	OpalCon	Opened source stream 1_1
2007/11/03 09:04:38.286	  0:18.160	    SIP Handler:8564500	OpalCon	OpenSourceMediaStream (already opened) for session 1 on Call[1]-EP<sip>[da498715-5188-dc11-9be6-00300510c2ee chello089078148075 chello pl]
2007/11/03 09:04:38.286	  0:18.160	    SIP Handler:8564500	SIP	Could not find SDP media description for Video
2007/11/03 09:04:38.286	  0:18.160	    SIP Handler:8564500	SIP	Could not find SDP media description for Image
2007/11/03 09:04:38.287	  0:18.161	    SIP Handler:8564500	OpalCon	SetPhase from UninitialisedPhase to AlertingPhase
2007/11/03 09:04:38.287	  0:18.161	    SIP Handler:8564500	OpalMan	OnAlerting Call[1]-EP<sip>[da498715-5188-dc11-9be6-00300510c2ee chello089078148075 chello pl]
2007/11/03 09:04:38.287	  0:18.161	    SIP Handler:8564500	Call	OnAlerting Call[1]-EP<sip>[da498715-5188-dc11-9be6-00300510c2ee chello089078148075 chello pl]
2007/11/03 09:04:38.287	  0:18.161	    SIP Handler:8564500	PCSS	SetAlerting(0896741121 voipprovider pl)
2007/11/03 09:04:38.287	  0:18.161	    SIP Handler:8564500	SIP	Starting receive media to annunciate remote progress tones
2007/11/03 09:04:38.287	  0:18.161	    SIP Handler:8564500	OpalMan	OnConnected Call[1]-EP<sip>[da498715-5188-dc11-9be6-00300510c2ee chello089078148075 chello pl]
2007/11/03 09:04:38.287	  0:18.161	    SIP Handler:8564500	Call	OnConnected Call[1]-EP<sip>[da498715-5188-dc11-9be6-00300510c2ee chello089078148075 chello pl]
2007/11/03 09:04:38.288	  0:18.162	    SIP Handler:8564500	PCSS	SetConnected()
2007/11/03 09:04:38.288	  0:18.162	    SIP Handler:8564500	GMPCSSEndpoint	 PCSS connection established
2007/11/03 09:04:38.288	  0:18.162	    SIP Handler:8564500	GMManager	 Will establish the connection
2007/11/03 09:04:38.288	  0:18.162	    SIP Handler:8564500	OpalMan	OnEstablished Call[1]-EP<pc>[Default]
2007/11/03 09:04:38.288	  0:18.162	    SIP Handler:8564500	Call	OnEstablished Call[1]-EP<pc>[Default]
2007/11/03 09:04:38.311	  0:18.185	    SIP Handler:8564500	Call	GetMediaFormats for Call[1]-EP<pc>[Default]
G.729
PCM-16

2007/11/03 09:04:38.311	  0:18.185	    SIP Handler:8564500	Call	OpenSourceMediaStreams for session 1 with media G.729,PCM-16
2007/11/03 09:04:38.312	  0:18.186	    SIP Handler:8564500	OpalCon	OpenSourceMediaStream (already opened) for session 1 on Call[1]-EP<sip>[da498715-5188-dc11-9be6-00300510c2ee chello089078148075 chello pl]
2007/11/03 09:04:38.312	  0:18.186	    SIP Handler:8564500	Call	OpenSourceMediaStreams for session 2 with media G.729,PCM-16
2007/11/03 09:04:38.312	  0:18.186	    SIP Handler:8564500	Call	OpenSourceMediaStreams for session 3 with media G.729,PCM-16
2007/11/03 09:04:38.335	  0:18.209	    SIP Handler:8564500	Call	GetMediaFormats for Call[1]-EP<sip>[da498715-5188-dc11-9be6-00300510c2ee chello089078148075 chello pl]
G.729
PCM-16

2007/11/03 09:04:38.335	  0:18.209	    SIP Handler:8564500	Call	OpenSourceMediaStreams for session 1 with media G.729,PCM-16
2007/11/03 09:04:38.336	  0:18.210	    SIP Handler:8564500	OpalCon	OpenSourceMediaStream (already opened) for session 1 on Call[1]-EP<pc>[Default]
2007/11/03 09:04:38.336	  0:18.210	    SIP Handler:8564500	Call	OpenSourceMediaStreams for session 2 with media G.729,PCM-16
2007/11/03 09:04:38.336	  0:18.210	    SIP Handler:8564500	Call	OpenSourceMediaStreams for session 3 with media G.729,PCM-16
2007/11/03 09:04:38.336	  0:18.210	    SIP Handler:8564500	PWLib	File handle high water mark set: 52 Thread unblock pipe
2007/11/03 09:04:38.336	  0:18.210	    SIP Handler:8564500	PWLib	Thread high water mark set: 9
2007/11/03 09:04:38.337	  0:18.211	    Media Patch:85d64e0	Patch	Thread started for Patch OpalAudioMediaStream-Source-PCM-16 -> OpalRTPMediaStream-Sink-G.729
2007/11/03 09:04:38.338	  0:18.212	    Media Patch:85d64e0	Alsa	Buffer time is 170000
2007/11/03 09:04:38.338	  0:18.212	    Media Patch:85d64e0	Alsa	Period time is 10625
2007/11/03 09:04:38.339	  0:18.213	    SIP Handler:8564500	Media	Starting thread Media Patch:85d64e0
2007/11/03 09:04:38.339	  0:18.213	    SIP Handler:8564500	PWLib	File handle high water mark set: 54 Thread unblock pipe
2007/11/03 09:04:38.340	  0:18.214	    SIP Handler:8564500	PWLib	Thread high water mark set: 10
2007/11/03 09:04:38.340	  0:18.214	    Media Patch:85c7a80	Patch	Thread started for Patch OpalRTPMediaStream-Source-G.729 -> OpalAudioMediaStream-Sink-PCM-16
2007/11/03 09:04:38.341	  0:18.215	    Media Patch:85c7a80	RTP	Opal jitter buffer created: size=101 delay=20-500 ms  /160 (20ms) obj=0x85d5758
2007/11/03 09:04:38.342	  0:18.216	    Media Patch:85c7a80	PWLib	File handle high water mark set: 56 Thread unblock pipe
2007/11/03 09:04:38.342	  0:18.216	    Media Patch:85c7a80	PWLib	Thread high water mark set: 11
2007/11/03 09:04:38.342	  0:18.216	     RTP Jitter:85cfc38	RTP	Jitter RTP receive thread started: 0x85d5758
2007/11/03 09:04:38.343	  0:18.217	    SIP Handler:8564500	Media	Starting thread Media Patch:85c7a80
2007/11/03 09:04:38.343	  0:18.217	    SIP Handler:8564500	OpalCon	Media stream threads started.
2007/11/03 09:04:38.343	  0:18.217	    SIP Handler:8564500	OpalCon	Media stream threads started.
2007/11/03 09:04:38.343	  0:18.217	    SIP Handler:8564500	SIP	Awaiting next PDU.
2007/11/03 09:04:38.384	  0:18.258	    Media Patch:85d64e0	RTP	Session 1, first sent data: ver=2 pt=G729 psz=20 m=1 x=0 seq=11040 ts=1266346207 src=3839833797 ccnt=0
2007/11/03 09:04:38.390	  0:18.264	     RTP Jitter:85cfc38	RTP	Session 1, first receive data: ver=2 pt=G729 psz=60 m=0 x=0 seq=9997 ts=1184040010 src=66158642 ccnt=0
2007/11/03 09:04:38.402	  0:18.276	    Media Patch:85c7a80	Alsa	Buffer time is 170000
2007/11/03 09:04:38.402	  0:18.276	    Media Patch:85c7a80	Alsa	Period time is 10625
2007/11/03 09:04:38.464	  0:18.338	    Media Patch:85c7a80	ALSA	Could not write 0 960 Success
2007/11/03 09:04:38.525	  0:18.399	    Media Patch:85c7a80	ALSA	Could not write 0 960 Success
2007/11/03 09:04:38.587	  0:18.461	    Media Patch:85c7a80	ALSA	Could not write 0 960 Success
2007/11/03 09:04:38.647	  0:18.522	    Media Patch:85c7a80	ALSA	Could not write 0 960 Success
2007/11/03 09:04:38.705	  0:18.579	    Media Patch:85c7a80	ALSA	Could not write 0 960 Success
2007/11/03 09:04:38.801	  0:18.675	    Media Patch:85c7a80	Opal	Expected payload type G729, but received CiscoCN. Ignoring packet
2007/11/03 09:04:40.431	  0:20.305	    Media Patch:85d64e0	RTP	Session 1, transmit statistics:  packets=101 octets=2020 avgTime=20 maxTime=53 minTime=7
2007/11/03 09:04:41.463	  0:21.337	            Housekeeper	PWLib	File handle high water mark set: 57 PUDPSocket
2007/11/03 09:04:41.531	  0:21.405	     RTP Jitter:85cfc38	RTP	Received start of talk burst: 1184065130
2007/11/03 09:04:41.542	  0:21.417	    Media Patch:85c7a80	ALSA	Could not write 0 960 Success
2007/11/03 09:04:41.737	  0:21.611	    Media Patch:85c7a80	ALSA	Could not write 0 960 Success
2007/11/03 09:04:42.203	  0:22.077	    Media Patch:85c7a80	Opal	Expected payload type G729, but received CiscoCN. Ignoring packet
2007/11/03 09:04:42.436	  0:22.311	    Media Patch:85d64e0	RTP	Session 1, transmit statistics:  packets=201 octets=4020 avgTime=20 maxTime=50 minTime=7
2007/11/03 09:04:42.880	  0:22.755	  Opal Listener:84a2400	OpalUDP	Binding to interface: 89.78.148.75:5060
..... Pawel: I cut trace here
.....
.....
....
....

2007/11/03 09:04:42.902	  0:22.776	    SIP Handler:8564500	SIP	Received INVITE OK response
2007/11/03 09:04:42.903	  0:22.777	    SIP Handler:8564500	SIP	RTP payload type G729 matched to codec G.729
2007/11/03 09:04:42.910	  0:22.784	    SIP Handler:8564500	SIP	RTP payload type [pt=100] not matched to audio codec
2007/11/03 09:04:42.910	  0:22.784	    SIP Handler:8564500	Call	GetOtherPartyConnection Call[1]-EP<sip>[da498715-5188-dc11-9be6-00300510c2ee chello089078148075 chello pl]
2007/11/03 09:04:42.910	  0:22.784	    SIP Handler:8564500	Call	IsMediaBypassPossible Call[1]-EP<sip>[da498715-5188-dc11-9be6-00300510c2ee chello089078148075 chello pl] session 1
2007/11/03 09:04:42.911	  0:22.785	    SIP Handler:8564500	OpalMan	IsMediaBypassPossible: session 1
2007/11/03 09:04:42.911	  0:22.785	    SIP Handler:8564500	OpalCon	IsMediaBypassPossible: default returns FALSE
2007/11/03 09:04:42.911	  0:22.785	    SIP Handler:8564500	RTP	Found existing session 1
2007/11/03 09:04:42.912	  0:22.786	    SIP Handler:8564500	RTP_UDP	Session 1, SetRemoteSocketInfo: data channel, new=205.46.34.6:18118, local=89.78.148.75:5000-5001, remote=205.46.34.6:18118-18119
2007/11/03 09:04:42.914	  0:22.788	    SIP Handler:8564500	Call	OpenSourceMediaStreams for session 1 with media G.729,UserInput/RFC2833
2007/11/03 09:04:42.914	  0:22.788	    SIP Handler:8564500	OpalCon	OpenSourceMediaStream (already opened) for session 1 on Call[1]-EP<pc>[Default]
2007/11/03 09:04:42.915	  0:22.789	    SIP Handler:8564500	Call	GetOtherPartyConnection Call[1]-EP<sip>[da498715-5188-dc11-9be6-00300510c2ee chello089078148075 chello pl]
2007/11/03 09:04:42.915	  0:22.789	    SIP Handler:8564500	OpalCon	OpenSourceMediaStream (already opened) for session 1 on Call[1]-EP<sip>[da498715-5188-dc11-9be6-00300510c2ee chello089078148075 chello pl]
2007/11/03 09:04:42.915	  0:22.789	    SIP Handler:8564500	OpalCon	OpenSourceMediaStream (already opened) for session 1 on Call[1]-EP<sip>[da498715-5188-dc11-9be6-00300510c2ee chello089078148075 chello pl]
2007/11/03 09:04:42.915	  0:22.789	    SIP Handler:8564500	SIP	Could not find SDP media description for Video
2007/11/03 09:04:42.915	  0:22.789	    SIP Handler:8564500	SIP	Could not find SDP media description for Image
2007/11/03 09:04:42.915	  0:22.789	    SIP Handler:8564500	OpalMan	OnConnected Call[1]-EP<sip>[da498715-5188-dc11-9be6-00300510c2ee chello089078148075 chello pl]
2007/11/03 09:04:42.916	  0:22.790	    SIP Handler:8564500	Call	OnConnected Call[1]-EP<sip>[da498715-5188-dc11-9be6-00300510c2ee chello089078148075 chello pl]
2007/11/03 09:04:42.916	  0:22.790	    SIP Handler:8564500	PCSS	SetConnected()
2007/11/03 09:04:42.916	  0:22.790	    SIP Handler:8564500	GMPCSSEndpoint	 PCSS connection established
2007/11/03 09:04:42.916	  0:22.790	    SIP Handler:8564500	GMManager	 Will establish the connection
2007/11/03 09:04:42.916	  0:22.790	    SIP Handler:8564500	OpalMan	OnEstablished Call[1]-EP<pc>[Default]
2007/11/03 09:04:42.916	  0:22.790	    SIP Handler:8564500	Call	OnEstablished Call[1]-EP<pc>[Default]
2007/11/03 09:04:42.947	  0:22.821	    SIP Handler:8564500	Call	GetMediaFormats for Call[1]-EP<pc>[Default]
G.729
PCM-16

2007/11/03 09:04:42.947	  0:22.821	    SIP Handler:8564500	Call	OpenSourceMediaStreams for session 1 with media G.729,PCM-16
2007/11/03 09:04:42.948	  0:22.822	    SIP Handler:8564500	OpalCon	OpenSourceMediaStream (already opened) for session 1 on Call[1]-EP<sip>[da498715-5188-dc11-9be6-00300510c2ee chello089078148075 chello pl]
2007/11/03 09:04:42.948	  0:22.822	    SIP Handler:8564500	Call	OpenSourceMediaStreams for session 2 with media G.729,PCM-16
2007/11/03 09:04:42.948	  0:22.822	    SIP Handler:8564500	Call	OpenSourceMediaStreams for session 3 with media G.729,PCM-16
2007/11/03 09:04:42.977	  0:22.851	    SIP Handler:8564500	Call	GetMediaFormats for Call[1]-EP<sip>[da498715-5188-dc11-9be6-00300510c2ee chello089078148075 chello pl]
G.729
PCM-16

2007/11/03 09:04:42.977	  0:22.851	    SIP Handler:8564500	Call	OpenSourceMediaStreams for session 1 with media G.729,PCM-16
2007/11/03 09:04:42.978	  0:22.852	    SIP Handler:8564500	OpalCon	OpenSourceMediaStream (already opened) for session 1 on Call[1]-EP<pc>[Default]
2007/11/03 09:04:42.978	  0:22.852	    SIP Handler:8564500	Call	OpenSourceMediaStreams for session 2 with media G.729,PCM-16
2007/11/03 09:04:42.978	  0:22.852	    SIP Handler:8564500	Call	OpenSourceMediaStreams for session 3 with media G.729,PCM-16
2007/11/03 09:04:42.978	  0:22.852	    SIP Handler:8564500	OpalCon	Media stream threads started.
2007/11/03 09:04:42.978	  0:22.852	    SIP Handler:8564500	OpalCon	Media stream threads started.
2007/11/03 09:04:42.978	  0:22.852	    SIP Handler:8564500	OpalCon	SetPhase from AlertingPhase to EstablishedPhase
2007/11/03 09:04:42.978	  0:22.852	    SIP Handler:8564500	GMSIPEndpoint	 SIP connection established
2007/11/03 09:04:42.978	  0:22.853	    SIP Handler:8564500	RTP	Found existing session 1
2007/11/03 09:04:42.979	  0:22.853	    SIP Handler:8564500	GMManager	 Will establish the connection
2007/11/03 09:04:42.979	  0:22.853	    SIP Handler:8564500	OpalMan	OnEstablished Call[1]-EP<sip>[da498715-5188-dc11-9be6-00300510c2ee chello089078148075 chello pl]
2007/11/03 09:04:42.979	  0:22.853	    SIP Handler:8564500	Call	OnEstablished Call[1]-EP<sip>[da498715-5188-dc11-9be6-00300510c2ee chello089078148075 chello pl]
2007/11/03 09:04:42.979	  0:22.853	    SIP Handler:8564500	SIP	Awaiting next PDU.
2007/11/03 09:04:43.402	  0:23.276	     RTP Jitter:85cfc38	RTP	Received start of talk burst: 1184080090
2007/11/03 09:04:43.415	  0:23.289	    Media Patch:85c7a80	ALSA	Could not write 0 960 Success
2007/11/03 09:04:43.473	  0:23.347	    Media Patch:85c7a80	ALSA	Could not write 0 960 Success
2007/11/03 09:04:43.535	  0:23.409	    Media Patch:85c7a80	ALSA	Could not write 0 960 Success
2007/11/03 09:04:43.596	  0:23.470	    Media Patch:85c7a80	ALSA	Could not write 0 960 Success
2007/11/03 09:04:43.930	  0:23.804	            Housekeeper	SIP	Set state Terminated_Success for transaction 2 INVITE
2007/11/03 09:04:43.958	  0:23.832	    Media Patch:85c7a80	ALSA	Could not write 0 960 Success
2007/11/03 09:04:43.983	  0:23.857	            Housekeeper	RTP	Found existing session 1
2007/11/03 09:04:44.017	  0:23.891	    Media Patch:85c7a80	ALSA	Could not write 0 960 Success
2007/11/03 09:04:44.081	  0:23.955	    Media Patch:85c7a80	ALSA	Could not write 0 960 Success
2007/11/03 09:04:44.193	  0:24.067	    Media Patch:85c7a80	Opal	Expected payload type G729, but received CiscoCN. Ignoring packet
2007/11/03 09:04:44.442	  0:24.316	    Media Patch:85d64e0	RTP	Session 1, transmit statistics:  packets=301 octets=6020 avgTime=20 maxTime=44 minTime=7
2007/11/03 09:04:44.983	  0:24.858	            Housekeeper	RTP	Found existing session 1
2007/11/03 09:04:45.070	  0:24.944	     RTP Jitter:85cfc38	RTP	Received start of talk burst: 1184093450
2007/11/03 09:04:45.080	  0:24.954	    Media Patch:85c7a80	ALSA	Could not write 0 960 Success
2007/11/03 09:04:45.147	  0:25.021	    Media Patch:85c7a80	ALSA	Could not write 0 960 Success
2007/11/03 09:04:45.721	  0:25.595	    Media Patch:85c7a80	Opal	Expected payload type G729, but received CiscoCN. Ignoring packet
2007/11/03 09:04:46.001	  0:25.875	            Housekeeper	RTP	Found existing session 1
2007/11/03 09:04:46.448	  0:26.322	    Media Patch:85d64e0	RTP	Session 1, transmit statistics:  packets=401 octets=8020 avgTime=20 maxTime=42 minTime=7
2007/11/03 09:04:46.989	  0:26.863	     RTP Jitter:85cfc38	RTP	Session 1, SentSenderReport: ssrc=3839833797 ntp=3403065886.4249423986 rtp=135936208 psent=428 osent=8560
2007/11/03 09:04:46.989	  0:26.863	     RTP Jitter:85cfc38	RTP	Session 1, SentReceiverReport: ssrc=66158642 fraction=0 lost=0 last_seq=0 jitter=51 lsr=0 dlsr=0
2007/11/03 09:04:46.990	  0:26.864	     RTP Jitter:85cfc38	RTP	Session 1, sending SDES: user1 chello089078148075 chello pl
2007/11/03 09:04:47.003	  0:26.877	            Housekeeper	RTP	Found existing session 1
2007/11/03 09:04:47.783	  0:27.657	     RTP Jitter:85cfc38	RTP	Session 1, OnRxSenderReport: ssrc=66158642 ntp=2007/11/3-8:57:49.070872 rtp=0 psent=56 osent=3124
  RR: ssrc=3124 fraction=228 lost=14628549 last_seq=1 jitter=11503 lsr=0.640 dlsr=51958:21:15.160

2007/11/03 09:04:47.783	  0:27.657	     RTP Jitter:85cfc38	RTP	Session 1, SourceDescription item 0x85db38c, current size = 21
2007/11/03 09:04:47.783	  0:27.658	     RTP Jitter:85cfc38	RTP	Session 1, SourceDescription item 0x85db3a1, current size = 46
2007/11/03 09:04:47.784	  0:27.658	     RTP Jitter:85cfc38	RTP	Session 1, SourceDescription item 0x85db3ba, current size = 71
2007/11/03 09:04:47.784	  0:27.658	     RTP Jitter:85cfc38	RTP	Session 1, OnSourceDescription: 1 entries
  ssrc=66158642
  item[0]: type=CNAME data="0 0 0 205 46 34 6"
  item[1]: type=NAME data="Cisco IOS, VoIP Gateway"
  item[2]: type=TOOL data="Cisco IOS, VoIP Gateway"
2007/11/03 09:04:48.003	  0:27.877	            Housekeeper	RTP	Found existing session 1
2007/11/03 09:04:48.439	  0:28.313	    Media Patch:85d64e0	RTP	Session 1, transmit statistics:  packets=501 octets=10020 avgTime=19 maxTime=55 minTime=7
2007/11/03 09:04:49.003	  0:28.877	            Housekeeper	RTP	Found existing session 1
2007/11/03 09:04:49.746	  0:29.620	     RTP Jitter:85cfc38	RTP	Received start of talk burst: 1184130810
2007/11/03 09:04:49.757	  0:29.631	    Media Patch:85c7a80	ALSA	Could not write 0 960 Success
2007/11/03 09:04:49.832	  0:29.706	    Media Patch:85c7a80	ALSA	Could not write 0 960 Success
2007/11/03 09:04:50.032	  0:29.906	            Housekeeper	RTP	Found existing session 1
2007/11/03 09:04:50.445	  0:30.319	    Media Patch:85d64e0	RTP	Session 1, transmit statistics:  packets=601 octets=12020 avgTime=20 maxTime=43 minTime=7
2007/11/03 09:04:50.801	  0:30.675	    Media Patch:85c7a80	Opal	Expected payload type G729, but received CiscoCN. Ignoring packet
2007/11/03 09:04:51.031	  0:30.905	            Housekeeper	RTP	Found existing session 1
2007/11/03 09:04:52.069	  0:31.943	            Housekeeper	RTP	Found existing session 1
2007/11/03 09:04:52.464	  0:32.339	    Media Patch:85d64e0	RTP	Session 1, transmit statistics:  packets=701 octets=14020 avgTime=20 maxTime=62 minTime=7
2007/11/03 09:04:53.064	  0:32.938	            Housekeeper	RTP	Found existing session 1
2007/11/03 09:04:53.243	  0:33.117	     RTP Jitter:85cfc38	RTP	Session 1, OnRxSenderReport: ssrc=66158642 ntp=2007/11/3-8:57:54.539206 rtp=0 psent=75 osent=4205
  RR: ssrc=4205 fraction=228 lost=14628549 last_seq=1 jitter=11777 lsr=0.800 dlsr=51958:21:15.160

2007/11/03 09:04:53.243	  0:33.117	     RTP Jitter:85cfc38	RTP	Session 1, SourceDescription item 0x85dbc94, current size = 21
2007/11/03 09:04:53.243	  0:33.118	     RTP Jitter:85cfc38	RTP	Session 1, SourceDescription item 0x85dbca9, current size = 46
2007/11/03 09:04:53.244	  0:33.118	     RTP Jitter:85cfc38	RTP	Session 1, SourceDescription item 0x85dbcc2, current size = 71
2007/11/03 09:04:53.244	  0:33.118	     RTP Jitter:85cfc38	RTP	Session 1, OnSourceDescription: 1 entries
  ssrc=66158642
  item[0]: type=CNAME data="0 0 0 205 46 34 6"
  item[1]: type=NAME data="Cisco IOS, VoIP Gateway"
  item[2]: type=TOOL data="Cisco IOS, VoIP Gateway"
2007/11/03 09:04:54.083	  0:33.957	            Housekeeper	RTP	Found existing session 1
2007/11/03 09:04:54.451	  0:34.325	    Media Patch:85d64e0	RTP	Session 1, transmit statistics:  packets=801 octets=16020 avgTime=19 maxTime=42 minTime=7
2007/11/03 09:04:55.089	  0:34.963	            Housekeeper	RTP	Found existing session 1
2007/11/03 09:04:55.776	  0:35.650	     RTP Jitter:85cfc38	RTP	Session 1, OnReceiverReport: ssrc=66158642
  RR: ssrc=3839833797 fraction=0 lost=1 last_seq=11901 jitter=480 lsr=792:49:17.641 dlsr=10:25:37.972

2007/11/03 09:04:55.776	  0:35.650	     RTP Jitter:85cfc38	RTP	Session 1, SourceDescription item 0x85dbc80, current size = 21
2007/11/03 09:04:55.776	  0:35.650	     RTP Jitter:85cfc38	RTP	Session 1, SourceDescription item 0x85dbc95, current size = 46
2007/11/03 09:04:55.776	  0:35.650	     RTP Jitter:85cfc38	RTP	Session 1, SourceDescription item 0x85dbcae, current size = 71
2007/11/03 09:04:55.776	  0:35.650	     RTP Jitter:85cfc38	RTP	Session 1, OnSourceDescription: 1 entries
  ssrc=66158642
  item[0]: type=CNAME data="0 0 0 205 46 34 6"
  item[1]: type=NAME data="Cisco IOS, VoIP Gateway"
  item[2]: type=TOOL data="Cisco IOS, VoIP Gateway"
2007/11/03 09:04:56.112	  0:35.986	            Housekeeper	RTP	Found existing session 1
2007/11/03 09:04:56.484	  0:36.358	    Media Patch:85d64e0	RTP	Session 1, transmit statistics:  packets=901 octets=18020 avgTime=20 maxTime=52 minTime=7
2007/11/03 09:04:57.016	  0:36.890	                       	Call	Clearing Call[1] reason=EndedByLocalUser
2007/11/03 09:04:57.016	  0:36.890	                       	OpalCon	SetPhase from EstablishedPhase to ReleasingPhase
2007/11/03 09:04:57.016	  0:36.890	                       	OpalCon	Releasing Call[1]-EP<pc>[Default]
2007/11/03 09:04:57.016	  0:36.890	                       	OpalCon	Call end reason for Default set to EndedByLocalUser
2007/11/03 09:04:57.017	  0:36.891	                       	PWLib	File handle high water mark set: 58 Thread unblock pipe
2007/11/03 09:04:57.017	  0:36.891	                       	PWLib	Thread high water mark set: 12
2007/11/03 09:04:57.017	  0:36.891	                       	OpalCon	SetPhase from EstablishedPhase to ReleasingPhase
2007/11/03 09:04:57.017	  0:36.891	                       	OpalCon	Releasing Call[1]-EP<sip>[da498715-5188-dc11-9be6-00300510c2ee chello089078148075 chello pl]
2007/11/03 09:04:57.017	  0:36.891	                       	OpalCon	Call end reason for da498715-5188-dc11-9be6-00300510c2ee chello089078148075 chello pl set to EndedByLocalUser
2007/11/03 09:04:57.017	  0:36.891	                       	PWLib	File handle high water mark set: 60 Thread unblock pipe
2007/11/03 09:04:57.018	  0:36.892	                       	PWLib	Thread high water mark set: 13
2007/11/03 09:04:57.023	  0:36.897	      OnRelease:85b8dc8	OpalCon	OnReleased Call[1]-EP<pc>[Default]
2007/11/03 09:04:57.024	  0:36.898	      OnRelease:85b8dc8	Media	Closing raw media stream OpalAudioMediaStream-Source-PCM-16
2007/11/03 09:04:57.024	  0:36.898	      OnRelease:85b8dc8	Media	Closing stream OpalAudioMediaStream-Source-PCM-16
2007/11/03 09:04:57.024	  0:36.898	      OnRelease:85b8dc8	Media	Disconnecting OpalAudioMediaStream-Source-PCM-16 from patch thread Patch OpalAudioMediaStream-Source-PCM-16 -> OpalRTPMediaStream-Sink-G.729
2007/11/03 09:04:57.024	  0:36.898	      OnRelease:85b8dc8	Patch	Closing media patch Patch OpalAudioMediaStream-Source-PCM-16 -> OpalRTPMediaStream-Sink-G.729
2007/11/03 09:04:57.024	  0:36.898	      OnRelease:85b8dc8	Media	Closing raw media stream OpalAudioMediaStream-Source-PCM-16
2007/11/03 09:04:57.025	  0:36.899	      OnRelease:85b8dc8	Patch	Removing media stream sink OpalRTPMediaStream-Sink-G.729
2007/11/03 09:04:57.025	  0:36.899	      OnRelease:85b8dc8	Patch	Waiting for media patch thread to stop Patch OpalAudioMediaStream-Source-PCM-16
2007/11/03 09:04:57.025	  0:36.899	      OnRelease:8634b00	SIP	OnReleased: Call[1]-EP<sip>[da498715-5188-dc11-9be6-00300510c2ee chello089078148075 chello pl], phase = ReleasingPhase
2007/11/03 09:04:57.026	  0:36.900	      OnRelease:8634b00	OpalCon	SetPhase from ReleasingPhase to ReleasingPhase
2007/11/03 09:04:57.027	  0:36.901	      OnRelease:8634b00	PWLib	File handle high water mark set: 61 PUDPSocket
2007/11/03 09:04:57.029	  0:36.903	    Media Patch:85d64e0	Patch	Thread ended for Patch OpalAudioMediaStream-Source-PCM-16
2007/11/03 09:04:57.034	  0:36.908	      OnRelease:8634b00	SIP	Transaction 4 BYE created.
2007/11/03 09:04:57.035	  0:36.909	      OnRelease:85b8dc8	PWLib	Destroyed thread 0x85d64e0 Media Patch:85d64e0(id = 0)
2007/11/03 09:04:57.036	  0:36.910	      OnRelease:85b8dc8	Patch	Media patch thread Patch OpalAudioMediaStream-Source-PCM-16 destroyed.
2007/11/03 09:04:57.036	  0:36.910	      OnRelease:85b8dc8	Media	Closing raw media stream OpalAudioMediaStream-Sink-PCM-16
2007/11/03 09:04:57.037	  0:36.911	      OnRelease:85b8dc8	Media	Closing stream OpalAudioMediaStream-Sink-PCM-16
2007/11/03 09:04:57.037	  0:36.911	      OnRelease:85b8dc8	Media	Disconnecting OpalAudioMediaStream-Sink-PCM-16 from patch thread Patch OpalRTPMediaStream-Source-G.729 -> OpalAudioMediaStream-Sink-PCM-16
2007/11/03 09:04:57.037	  0:36.911	      OnRelease:85b8dc8	Patch	Removing media stream sink OpalAudioMediaStream-Sink-PCM-16
2007/11/03 09:04:57.038	  0:36.912	      OnRelease:85b8dc8	OpalCon	Media stream threads closed.
2007/11/03 09:04:57.039	  0:36.913	      OnRelease:85b8dc8	GMPCSSEndpoint	 PCSS connection released
2007/11/03 09:04:57.039	  0:36.913	      OnRelease:85b8dc8	OpalEP	OnReleased Call[1]-EP<pc>[Default]
2007/11/03 09:04:57.039	  0:36.913	      OnRelease:85b8dc8	GMManager	 Will release the connection
2007/11/03 09:04:57.039	  0:36.913	      OnRelease:85b8dc8	OpalMan	OnReleased Call[1]-EP<pc>[Default]
2007/11/03 09:04:57.039	  0:36.913	      OnRelease:85b8dc8	Call	OnReleased Call[1]-EP<pc>[Default]
2007/11/03 09:04:57.039	  0:36.914	      OnRelease:85b8dc8	OpalCon	Already released Call[1]-EP<sip>[da498715-5188-dc11-9be6-00300510c2ee chello089078148075 chello pl]
2007/11/03 09:04:57.040	  0:36.914	      OnRelease:85b8dc8	OpalCon	OnRelease thread completed for Default
2007/11/03 09:04:57.040	  0:36.914	ThreadID=0xb45bcb90	PWLib	Destroyed thread 0x85b8dc8 OnRelease:85b8dc8(id = 0)
2007/11/03 09:04:57.041	  0:36.915	      OnRelease:8634b00	Media	Closing RTP for OpalRTPMediaStream-Sink-G.729
2007/11/03 09:04:57.041	  0:36.915	      OnRelease:8634b00	RTP	Session 1, SentSenderReport: ssrc=3839833797 ntp=3403065897.179085564 rtp=135936208 psent=927 osent=18540
2007/11/03 09:04:57.041	  0:36.915	      OnRelease:8634b00	RTP	Session 1, SentReceiverReport: ssrc=66158642 fraction=0 lost=0 last_seq=10043 jitter=63 lsr=0 dlsr=0
2007/11/03 09:04:57.143	  0:37.017	      OnRelease:8634b00	RTP_UDP	Session 1, Shutting down write.
2007/11/03 09:04:57.143	  0:37.017	      OnRelease:8634b00	Media	Closing stream OpalRTPMediaStream-Sink-G.729
2007/11/03 09:04:57.144	  0:37.018	      OnRelease:8634b00	Media	Closing RTP for OpalRTPMediaStream-Source-G.729
2007/11/03 09:04:57.144	  0:37.018	      OnRelease:8634b00	RTP_UDP	Session 1, Shutting down read.
2007/11/03 09:04:57.144	  0:37.018	     RTP Jitter:85cfc38	RTP_UDP	Session 1, Read shutdown.
2007/11/03 09:04:57.144	  0:37.018	     RTP Jitter:85cfc38	RTP	Jitter RTP receive thread ended
2007/11/03 09:04:57.145	  0:37.019	     RTP Jitter:85cfc38	RTP	Jitter RTP receive thread finished: 0x85d5758
2007/11/03 09:04:57.145	  0:37.019	      OnRelease:8634b00	Media	Closing stream OpalRTPMediaStream-Source-G.729
2007/11/03 09:04:57.145	  0:37.019	      OnRelease:8634b00	Media	Disconnecting OpalRTPMediaStream-Source-G.729 from patch thread Patch OpalRTPMediaStream-Source-G.729
2007/11/03 09:04:57.145	  0:37.019	      OnRelease:8634b00	Patch	Closing media patch Patch OpalRTPMediaStream-Source-G.729
2007/11/03 09:04:57.145	  0:37.019	      OnRelease:8634b00	Media	Closing RTP for OpalRTPMediaStream-Source-G.729
2007/11/03 09:04:57.145	  0:37.019	      OnRelease:8634b00	RTP_UDP	Session 1, Shutting down read.
2007/11/03 09:04:57.145	  0:37.020	      OnRelease:8634b00	Patch	Waiting for media patch thread to stop Patch OpalRTPMediaStream-Source-G.729
2007/11/03 09:04:57.146	  0:37.020	    Media Patch:85c7a80	Patch	Thread ended for Patch OpalRTPMediaStream-Source-G.729
2007/11/03 09:04:57.156	  0:37.030	      OnRelease:8634b00	PWLib	Destroyed thread 0x85c7a80 Media Patch:85c7a80(id = 0)
2007/11/03 09:04:57.156	  0:37.030	      OnRelease:8634b00	Patch	Media patch thread Patch OpalRTPMediaStream-Source-G.729 destroyed.
2007/11/03 09:04:57.157	  0:37.031	            Housekeeper	RTP	Found existing session 1
2007/11/03 09:04:57.157	  0:37.031	      OnRelease:8634b00	OpalCon	Media stream threads closed.
2007/11/03 09:04:57.158	  0:37.032	      OnRelease:8634b00	SIP	Adding authentication information
2007/11/03 09:04:57.159	  0:37.033	      OnRelease:8634b00	PWLib	File handle low water mark set: 47 PUDPSocket


Regards,
Pawel


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