[asterisk-users] asterisk+ooh323.. one way audio issue

Fri Jul 28 01:55:58 MST 2006

Hi guys,

I tried to make call from SIP channel to H323 using asterisk+ooh323. The SIP client is x-lite.
The problem is that there is one way audio. I hear everything from h323 endpoint, and I see the messages also:

Got RTP packet from 66.135.35.xx:5002 (type 3, seq 36250, ts 74400, len 33)
Sent RTP packet to 212.183.41.xx:45956 (type 18, seq 22288, ts 70880, len 20)

But the problem, when I talk via X-lite, or send dtmf tones, no audio is transfered, no RTP packets on asterisk console.

My ooh323.conf:

[general]
port=1720
bindaddr=0.0.0.0
gateway=no
h323id=ObjSysAsterisk
e164=100
callerid=asterisk
gatekeeper = DISABLE
disallow=all
allow=g729
allow=gsm
allow=ulaw

Note that I tried all combinations of faststart and h245tunneling, but no luck.
Also tried with gsm and g729 codecs (that time X-pro was used) but same oneway audio.
Asterisk version is 1.2.7.1

With full debug this is what I see in asterisk console:

Jul 28 10:40:22 DEBUG[15775]: pbx.c:1674 pbx_extension_helper: Launching 'Dial'
Jul 28 10:40:22 DEBUG[15775]: channel.c:2813 ast_channel_inherit_variables: Not copying variable STACK-test-381637790067-2.
Jul 28 10:40:22 DEBUG[15775]: channel.c:2813 ast_channel_inherit_variables: Not copying variable STACK-test-381637790067-1.
Jul 28 10:40:22 DEBUG[15775]: channel.c:2813 ast_channel_inherit_variables: Not copying variable SIPCALLID.
Jul 28 10:40:22 DEBUG[15775]: channel.c:2813 ast_channel_inherit_variables: Not copying variable SIPUSERAGENT.
Jul 28 10:40:22 DEBUG[15775]: channel.c:2813 ast_channel_inherit_variables: Not copying variable SIPDOMAIN.
Jul 28 10:40:22 DEBUG[15775]: channel.c:2813 ast_channel_inherit_variables: Not copying variable SIPURI.
Jul 28 10:40:22 DEBUG[15775]: channel.c:2348 set_format: Set channel OOH323/66.135.33.xx-14b0 to read format gsm
Jul 28 10:40:22 DEBUG[15775]: channel.c:2348 set_format: Set channel SIP/666666-9cbb to write format gsm
Jul 28 10:40:22 DEBUG[15775]: channel.c:2348 set_format: Set channel SIP/666666-9cbb to read format gsm
Jul 28 10:40:22 DEBUG[15775]: channel.c:2348 set_format: Set channel OOH323/66.135.33.xx-14b0 to write format gsm
Jul 28 10:40:22 DEBUG[16193]: res_config_mysql.c:636 mysql_reconnect: MySQL RealTime: Everything is fine.
Jul 28 10:40:23 DEBUG[15775]: channel.c:2348 set_format: Set channel SIP/666666-9cbb to read format gsm
Jul 28 10:40:23 DEBUG[15775]: channel.c:2348 set_format: Set channel OOH323/66.135.33.xx-14b0 to write format gsm
Jul 28 10:40:23 DEBUG[15775]: channel.c:2348 set_format: Set channel OOH323/66.135.33.xx-14b0 to read format gsm
Jul 28 10:40:23 DEBUG[15775]: channel.c:2348 set_format: Set channel SIP/666666-9cbb to write format gsm
Jul 28 10:40:23 DEBUG[15775]: chan_sip.c:2527 sip_answer: sip_answer(SIP/666666-9cbb)
Jul 28 10:40:23 DEBUG[15775]: channel.c:1956 ast_read: Dropping duplicate answer!
Jul 28 10:40:23 DEBUG[16193]: res_config_mysql.c:125 realtime_mysql: MySQL RealTime: Retrieve SQL: SELECT * FROM sip_buddies WHERE name = '666666'
Jul 28 10:40:23 DEBUG[16193]: res_config_mysql.c:636 mysql_reconnect: MySQL RealTime: Everything is fine.
Jul 28 10:40:23 DEBUG[16207]: chan_sip.c:1394 __sip_ack: Stopping retransmission on '4D979F84-6EF9-4A17-9007-4082B33E6835 at 87.116.143.xx' of Response 6305: Match Found
Jul 28 10:40:23 DEBUG[16207]: chan_sip.c:9442 check_pendings: Sending pending reinvite on '4D979F84-6EF9-4A17-9007-4082B33E6835 at 87.116.143.xx'
Jul 28 10:40:23 DEBUG[15775]: rtp.c:410 ast_rtcp_read: Got RTCP report of 84 bytes
Got RTP packet from 87.116.143.xx:8000 (type 3, seq 1, ts 5920, len 33)
Jul 28 10:40:23 DEBUG[15775]: rtp.c:1341 ast_rtp_write: Ooh, format changed from unknown to gsm
Sent RTP packet to 66.135.33.xx:5004 (type 3, seq 54271, ts 0, len 33)
Got RTP packet from 87.116.143.xx:8000 (type 3, seq 2, ts 6080, len 33)
Sent RTP packet to 66.135.33.xx:5004 (type 3, seq 54272, ts 160, len 33)
Got RTP packet from 87.116.143.xx:8000 (type 3, seq 3, ts 6240, len 33)
Sent RTP packet to 66.135.33.xx:5004 (type 3, seq 54273, ts 320, len 33)
Jul 28 10:40:23 DEBUG[16207]: chan_sip.c:1447 __sip_semi_ack: (Provisional) Stopping retransmission (but retaining packet) on '4D979F84-6EF9-4A17-9007-4082B33E6835 at 87.116.143.xx' Request 102: Found
Jul 28 10:40:23 DEBUG[16207]: chan_sip.c:1372 __sip_ack: Acked pending invite 102
Jul 28 10:40:23 DEBUG[16207]: chan_sip.c:1394 __sip_ack: Stopping retransmission on '4D979F84-6EF9-4A17-9007-4082B33E6835 at 87.116.143.xx' of Request 102: Match Found
Jul 28 10:40:23 DEBUG[16207]: chan_sip.c:6047 build_route: build_route: Contact hop: <sip:666666 at 87.116.143.xx:5060>
Got RTP packet from 66.135.33.xx:5004 (type 3, seq 55961, ts 3520, len 33)
Jul 28 10:40:24 DEBUG[15775]: src/chan_h323.c:3045 ooh323_rtp_read: Oooh, format changed to 2
Jul 28 10:40:24 DEBUG[15775]: channel.c:2348 set_format: Set channel OOH323/66.135.33.xx-14b0 to read format gsm
Jul 28 10:40:24 DEBUG[15775]: channel.c:2348 set_format: Set channel OOH323/66.135.33.xx-14b0 to write format gsm
Jul 28 10:40:24 DEBUG[15775]: rtp.c:1341 ast_rtp_write: Ooh, format changed from unknown to gsm
Sent RTP packet to 87.116.143.xx:8000 (type 3, seq 50183, ts 0, len 33)
Got RTP packet from 66.135.33.xx:5004 (type 3, seq 55962, ts 3680, len 33)
Sent RTP packet to 87.116.143.xx:8000 (type 3, seq 50184, ts 160, len 33)
Got RTP packet from 66.135.33.xx:5004 (type 3, seq 55963, ts 3840, len 33)
Sent RTP packet to 87.116.143.xx:8000 (type 3, seq 50185, ts 320, len 33)
Got RTP packet from 66.135.33.xx:5004 (type 3, seq 55964, ts 4000, len
Got RTP packet from 66.135.33.xx:5004 (type 3, seq 56070, ts 20960, len 33)
Sent RTP packet to 87.116.143.xx:8000 (type 3, seq 50292, ts 17440, len 33)
Jul 28 10:40:26 DEBUG[15775]: rtp.c:1700 ast_rtp_bridge: Oooh, got a hangup
Jul 28 10:40:26 DEBUG[15775]: channel.c:3478 ast_channel_bridge: Returning from native bridge, channels: SIP/666666-9cbb, OOH323/66.135.33.xx-14b0
Jul 28 10:40:26 DEBUG[15775]: channel.c:1323 ast_hangup: Hanging up channel 'OOH323/66.135.33.xx-14b0'
Jul 28 10:40:26 DEBUG[15775]: app_dial.c:1605 dial_exec_full: Exiting with DIALSTATUS=ANSWER.
Jul 28 10:40:26 DEBUG[15775]: pbx.c:2313 __ast_pbx_run: Spawn extension (test,381637790067,2) exited non-zero on 'SIP/666666-9cbb'
Jul 28 10:40:26 DEBUG[15775]: cdr.c:992 ast_cdr_detach: Dropping CDR !
Jul 28 10:40:26 DEBUG[15775]: channel.c:1323 ast_hangup: Hanging up channel 'SIP/666666-9cbb'
Jul 28 10:40:26 DEBUG[15775]: chan_sip.c:2411 sip_hangup: Hangup call SIP/666666-9cbb, SIP callid 4D979F84-6EF9-4A17-9007-4082B33E6835 at 87.116.143.xx)
Jul 28 10:40:26 DEBUG[15775]: chan_sip.c:2419 sip_hangup: update_call_counter(666666) - decrement call limit counter

And this is from /var/log/asterisk/h323_log:

07:20:01:413  Processing MakeCall command ooh323c_o_3
07:20:01:413  Created a new call (outgoing, ooh323c_o_3)
07:20:01:413  Enabled RFC2833 DTMF capability for (outgoing, ooh323c_o_3)
07:20:01:413  Parsing destination 216.12.173.48
07:20:01:413  Trying to connect to remote endpoint(216.12.173.48:1720) to setup H2250 channel (outgoing, ooh323c_o_3)
07:20:01:501  H2250 transmiter channel creation - succesful (outgoing, ooh323c_o_3)
07:20:01:502  Sent Message - Setup (outgoing, ooh323c_o_3)
07:20:01:671  H.225 Call Proceeding message received (outgoing, ooh323c_o_3)
07:20:01:671  Tunneling is disabled for call as H245 address is provided in callProceeding message (outgoing, ooh323c_o_3)
07:20:01:759  H.225 Progress message received (outgoing, ooh323c_o_3)
07:20:13:412  H.225 Alerting message received (outgoing, ooh323c_o_3)
07:20:19:307  H.225 Connect message received (outgoing, ooh323c_o_3)
07:20:19:307  Remote endpoint has rejected fastStart. (outgoing, ooh323c_o_3)
07:20:19:307  Clearing all logical channels (outgoing, ooh323c_o_3)
07:20:19:307  Creating H245 Connection
07:20:19:307  Trying to connect to remote endpoint to setup H245 connection 216.12.173.49:54659(outgoing, ooh323c_o_3)
07:20:19:396  H245 connection creation succesful (outgoing, ooh323c_o_3)
07:20:19:397  H.225 Notify message Received (outgoing, ooh323c_o_3)
07:20:19:397  Sent Message - TerminalCapabilitySet (outgoing, ooh323c_o_3)
07:20:19:397  Sent Message - MasterSlaveDetermination (outgoing, ooh323c_o_3)
07:20:19:486  Reducing framesPerPkt for transmission of GSM capability from 4 to 1 to match receive capability of remote endpoint.(outgoing, ooh323c_o_3)
07:20:19:486  Reducing framesPerPkt for transmission of Simple capability from 6 to 2 to match receive capability of remote endpoint.(outgoing, ooh323c_o_3)
07:20:19:486  Reducing framesPerPkt for transmission of Simple capability from 6 to 2 to match receive capability of remote endpoint.(outgoing, ooh323c_o_3)
07:20:19:486  Master Slave Determination received (outgoing, ooh323c_o_3)
07:20:19:486  MasterSlaveDetermination done - Slave(outgoing, ooh323c_o_3)
07:20:19:486  Sent Message - TerminalCapabilitySetAck (outgoing, ooh323c_o_3)
07:20:19:486  Opening logical channels (outgoing, ooh323c_o_3)
07:20:19:486  Looking for matching capabilities. (outgoing, ooh323c_o_3)
07:20:19:486  Created new logical channel entry (outgoing, ooh323c_o_3)
07:20:19:486  Sent Message - MasterSlaveDeterminationAck (outgoing, ooh323c_o_3)
07:20:19:486  Sent Message - OpenLogicalChannel(1001). (outgoing, ooh323c_o_3)
07:20:19:664  Created new logical channel entry (outgoing, ooh323c_o_3)
07:20:19:664  Receive channel of type OO_G729 started at 213.203.222.xxx:12222(outgoing, ooh323c_o_3)
07:20:19:664  TransmitLogical Channel of type OO_GSMFULLRATE started (outgoing, ooh323c_o_3)
07:20:19:664  Sent Message - OpenLogicalChannelAck(1) (outgoing, ooh323c_o_3)
07:20:19:664  Received close logical Channel - 1 (outgoing, ooh323c_o_3)
07:20:19:664  Closing logical channel number 1 (outgoing, ooh323c_o_3)
07:20:19:664  Stopped Receive channel 1 (outgoing, ooh323c_o_3)
07:20:19:664  Created new logical channel entry (outgoing, ooh323c_o_3)
07:20:19:664  Receive channel of type OO_GSMFULLRATE started at 213.203.222.xxx:12222(outgoing, ooh323c_o_3)
07:20:19:664  Sent Message - CloseLogicalChannelAck (outgoing, ooh323c_o_3)
07:20:19:664  Sent Message - OpenLogicalChannelAck(2) (outgoing, ooh323c_o_3)
07:20:34:690  H.225 Release Complete message received (outgoing, ooh323c_o_3)
07:20:34:690  Closing H.245 connection (outgoing, ooh323c_o_3)
07:20:34:690  Cleaning Call (outgoing, ooh323c_o_3)- reason:OO_REASON_REMOTE_CLEARED
07:20:34:690  Clearing all logical channels (outgoing, ooh323c_o_3)
07:20:34:690  Stopped Transmit channel 1001 (outgoing, ooh323c_o_3)
07:20:34:690  Stopped Receive channel 2 (outgoing, ooh323c_o_3)
07:20:34:690  Removed call (outgoing, ooh323c_o_3) from list

Any idea?:)
 Thanks, 
 Joseph
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.digium.com/pipermail/asterisk-users/attachments/20060728/a72a6d2f/attachment.htm